0 INFO NodeMain [main]: NodeMain.main(): portalHost = null 1356 INFO NodeManager [main]: Starting NodeManager at 10/02/2015 23:34:45 GMT (1443828885403), version: $Name: $ 1358 INFO NodeManager [main]: Node IP: node2/134.89.36.2 2062 INFO NodeManager [main]: Node ID has been set to 1598 3252 INFO PortManager [main]: Found 14 configured ports in properties file 3315 INFO MOOSPortManager [main]: Found DPA at slot: 0 5209 INFO MOOSPortManager [main]: Found DPA at slot: 1 6828 INFO MOOSPortManager [main]: Found DPA at slot: 2 8418 INFO MOOSPortManager [main]: Found DPA at slot: 3 10006 INFO MOOSPortManager [main]: No DPA found at slot: 4 10010 INFO MOOSPortManager [main]: No DPA found at slot: 5 10045 INFO WDTManager [main]: WDTManager constructor 10047 INFO MOOSWDTManager [main]: MOOSWDTManager constructor 10110 INFO SleepManager [main]: SleepManager constructor 10114 INFO MOOSSleepManager [main]: MOOSSleepManager constructor 11784 INFO CommsLeaseListener [main]: CommsLeaseListener() complete 11850 INFO CpuLeaseSleepRollcallListener [main]: CpuLeaseSleepRollcallListener() constructor 11865 INFO CpuLease [main]: CpuLease() constructor 11868 INFO CpuLease [Thread-5]: CpuLease started 13785 ERROR NodeService [main]: Turbinator constructor failed: com.rbnb.sapi.SAPIException: Nesting java.net.ConnectException 13884 INFO MOOSNodeService [main]: Last MMC reset code: 1 14094 INFO NodeManager [main]: Starting registry... 14135 INFO NodeManager [main]: registry started. 14136 INFO NodeManager [main]: binding NodeService to rmi://localhost/node 14714 INFO NodeManager [main]: NodeService is bound to rmi://localhost/node 14739 INFO MOOSWDTManager [Thread-2]: WDTManager initializing watchdog timer 14885 INFO MOOSWDTManager [Thread-2]: Reboot due to hardware reset 15070 INFO PortManager [main]: Service jar not specified for port /dev/ttySX0 15960 INFO PortManager [main]: Starting service... 16771 INFO MOOSWDTManager [Thread-2]: WDTManager watchdog initialization successful 32164 INFO BaseInstrumentService [main]: initializeInstrument() for 33738 WARN StreamingSBE19 [main]: Did not receive expected confirmation prompt from command: MP 38774 INFO StreamingSBE19 [main]: Event detection disabled by attribute 38918 INFO BaseInstrumentService [main]: done with initializeInstrument() for 47133 INFO PortManager [main]: service started 47654 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 48350 INFO PortManager [main]: Starting service... 69080 INFO BaseInstrumentService [main]: initializeInstrument() for 69081 INFO WetLabsECO [main]: Initializing... 84548 INFO WetLabsECO [main]: Initializing completed 84549 INFO BaseInstrumentService [main]: done with initializeInstrument() for 93747 INFO PolledInstrumentService [main]: prepareToRun(): sampleMasterId = 1827 93749 INFO PolledInstrumentService [main]: id 1535 slaved to 1827 93767 INFO PortManager [main]: service started 93773 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 93811 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828977866 95816 INFO PortManager [main]: Starting service... 96206 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828980261 156371 INFO BaseInstrumentService [main]: initializeInstrument() for 158357 INFO Aquadopp [main]: sendSoftBreak(): instrument in mode 5 164557 INFO BaseInstrumentService [main]: done with initializeInstrument() for 164673 INFO Aquadopp [main]: Retrieving Aquadopp state Metadata... 166656 INFO Aquadopp [main]: sendSoftBreak(): instrument in mode 2 174332 INFO PortManager [main]: service started 174334 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 174844 INFO PortManager [main]: Starting service... 176446 INFO Aquadopp [Thread-34]: sendSoftBreak(): instrument in mode 2 186059 INFO BaseInstrumentService [main]: initializeInstrument() for 190866 INFO BaseInstrumentService [main]: done with initializeInstrument() for 192837 INFO PolledInstrumentService [main]: prepareToRun(): sampleMasterId = 1535 192839 INFO PolledInstrumentService [main]: id 1524 slaved to 1535 192844 INFO PortManager [main]: service started 192853 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 192870 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443829076924 193923 INFO PortManager [main]: Starting service... 229247 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 234874 INFO BaseInstrumentService [main]: initializeInstrument() for 234876 INFO StreamingWorkhorseADCP-1825 [main]: Initializing Workhorse 1825 234887 INFO StreamingWorkhorseADCP-1825 [main]: Instrument has been pre-configured; ignore configuration attributes 238356 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TE? 238405 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 238439 INFO StreamingWorkhorseADCP-1825 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 238440 INFO StreamingWorkhorseADCP-1825 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 238684 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CF? 238722 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 238767 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 238769 INFO StreamingWorkhorseADCP-1825 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 238771 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 238772 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): property char 3=1 238774 INFO StreamingWorkhorseADCP-1825 [main]: Start pinging 238961 INFO BaseInstrumentService [main]: done with initializeInstrument() for 242513 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WD? 242552 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 243094 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WF? 243172 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 243704 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WN? 243742 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 244274 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WP? 244418 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 244954 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WS? 244993 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 245524 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WV? 245563 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 246104 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TE? 246142 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 246684 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TP? 246722 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 247254 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TS? 247293 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 247824 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EA? 247862 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 248434 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EB? 248472 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 249014 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request ED? 249052 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 249584 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request ES? 249671 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 250206 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EX? 250252 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 250824 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EZ? 250863 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 251394 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CF? 251432 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 252004 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RA? 252028 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 252544 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RR? 252583 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 254034 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RF? 254070 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 254614 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CB? 254653 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 255194 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request PS0 255218 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 257124 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request PS3 257148 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 258504 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RF 258539 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 259044 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request AC 259131 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 261324 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 261324 INFO PortManager [main]: service started 262427 INFO PortManager [main]: Starting service... 299177 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 309834 INFO BaseInstrumentService [main]: initializeInstrument() for 309836 INFO StreamingWorkhorseADCP-1828 [main]: Initializing Workhorse 1828 309892 INFO StreamingWorkhorseADCP-1828 [main]: Instrument has been pre-configured; ignore configuration attributes 313374 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TE? 313411 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 313456 INFO StreamingWorkhorseADCP-1828 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 313457 INFO StreamingWorkhorseADCP-1828 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 313694 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CF? 313732 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 313778 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 313779 INFO StreamingWorkhorseADCP-1828 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 313781 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 313782 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): property char 3=1 313784 INFO StreamingWorkhorseADCP-1828 [main]: Start pinging 313971 INFO BaseInstrumentService [main]: done with initializeInstrument() for 317474 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WD? 317590 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 318134 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WF? 318172 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 318724 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WN? 318763 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 319294 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WP? 319332 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 319903 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WS? 319942 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 320486 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WV? 320536 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 321086 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TE? 321136 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 321686 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TP? 321733 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 322354 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TS? 322393 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 322964 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EA? 323003 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 323534 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EB? 323572 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 324154 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request ED? 324193 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 324734 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request ES? 324772 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 325304 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EX? 325343 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 325904 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EZ? 325943 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 326524 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CF? 326562 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 327114 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RA? 327138 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 327654 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RR? 327694 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 329094 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RF? 329179 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 329724 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CB? 329763 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 330403 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request PS0 330442 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 332315 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request PS3 332339 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 333644 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RF 333679 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 334164 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request AC 334202 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 336387 INFO PortManager [main]: service started 336394 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 337423 INFO PortManager [main]: Starting service... 374995 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 380636 INFO BaseInstrumentService [main]: initializeInstrument() for 380638 INFO StreamingWorkhorseADCP-1827 [main]: Initializing Workhorse 1827 380650 INFO StreamingWorkhorseADCP-1827 [main]: Instrument has been pre-configured; ignore configuration attributes 384084 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request TE? 384124 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 384168 INFO StreamingWorkhorseADCP-1827 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 384169 INFO StreamingWorkhorseADCP-1827 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 384484 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request CF? 384524 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 384569 INFO StreamingWorkhorseADCP-1827 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 384571 INFO StreamingWorkhorseADCP-1827 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 384573 INFO StreamingWorkhorseADCP-1827 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 384574 INFO StreamingWorkhorseADCP-1827 [main]: initializeInstrument(): property char 3=1 384576 INFO StreamingWorkhorseADCP-1827 [main]: Start pinging 384761 INFO BaseInstrumentService [main]: done with initializeInstrument() for 388324 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request WD? 388364 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 388945 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request WF? 389030 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 389564 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request WN? 389603 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 390134 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request WP? 390173 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 390706 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request WS? 390816 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 391356 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request WV? 391403 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 392026 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request TE? 392076 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 392624 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request TP? 392664 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 393194 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request TS? 393234 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 393774 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request EA? 393813 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 394357 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request EB? 394500 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 395054 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request ED? 395090 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 395626 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request ES? 395675 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 396248 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request EX? 396306 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 396934 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request EZ? 396974 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 397504 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request CF? 397544 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 398084 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request RA? 398108 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 398674 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request RR? 398714 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 400094 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request RF? 400131 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 400676 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request CB? 400726 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 401286 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request PS0 401366 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 403204 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request PS3 403229 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 404534 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request RF 404572 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 405054 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request AC 405093 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 407274 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 407273 INFO PortManager [main]: service started 407584 INFO PortManager [main]: Service jar not specified for port /dev/ttySX8 407585 INFO PortManager [main]: Service jar not specified for port /dev/ttySX9 407586 INFO PortManager [main]: Service jar not specified for port /dev/ttySX10 407587 INFO PortManager [main]: Service jar not specified for port /dev/ttySX11 407824 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829291879 408022 INFO PortManager [main]: Starting service... 410223 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829294278 410438 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829294493 419762 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829303817 420396 INFO BaseInstrumentService [main]: initializeInstrument() for 421847 INFO BaseInstrumentService [main]: done with initializeInstrument() for 422312 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829306366 422385 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829306438 425344 INFO PolledInstrumentService [main]: prepareToRun(): sampleMasterId = -1 425485 INFO PolledInstrumentService [main]: id 1599 not slaved 425505 INFO PortManager [main]: service started 425517 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 425662 INFO PortManager [main]: Service jar not specified for port /dev/ttySA2 425701 INFO CommsSchedulerTask [Thread-70]: CommsSchedulerTask.run(): Oct 2, 2015 11:41:49 PM 426117 INFO ThreadUtility [Thread-73]: ThreadGroup "main" includes about 38 threads 426596 INFO ThreadUtility [Thread-73]: thread: "RMI-ServerConnection:127.0.0.1" alive daemon thread: "Thread-73" alive thread: "Thread-72" alive daemon thread: "Thread-71" alive thread: "Thread-70" alive thread: "Thread-69" alive thread: "Thread-66" alive daemon thread: "Thread-65" alive thread: "Thread-57" alive daemon thread: "Thread-56" alive thread: "Thread-48" alive daemon thread: "Thread-47" alive thread: "Thread-40" alive daemon thread: "Thread-39" alive thread: "Thread-38" alive thread: "Thread-35" alive daemon thread: "Thread-34" alive thread: "Thread-27" alive daemon thread: "Thread-26" alive thread: "Thread-25" alive thread: "Thread-22" alive thread: "Thread-19" alive daemon thread: "Thread-18" alive thread: "Thread-10" alive daemon thread: "RMI-Leaser" alive daemon thread: "RMI-ConnectionGC" alive daemon thread: "RMI-Accept:1099" alive daemon thread: "Thread-8" alive thread: "Thread-6" alive thread: "Thread-5" alive thread: "Thread-4" alive thread: "RMI-Accept:1025" alive daemon thread: "RMI-DGC" alive thread: "RMI-Ref GC" alive daemon thread: "Thread-3" alive thread: "Thread-2" alive thread: "Thread-1" alive thread: "main" alive 426896 INFO CommsLeaseListener [Thread-70]: Calling SyncProcessRunner("echo ethOn"); attempt #0 427558 INFO NodeManager [main]: NodeManager "node" started. 427717 INFO CommsLeaseListener [Thread-70]: ethOn 430322 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829314377 432729 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829316784 432844 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829316898 438754 INFO CommsLeaseListener [Thread-76]: SyncProcessRunner.exec("echo ethOff"), connect time = 11 438796 INFO CommsLeaseListener [Thread-76]: 441042 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829325097 443440 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829327494 443599 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829327654 451839 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829335894 454236 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829338291 454317 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829338372 462616 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829346671 465010 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829349065 465172 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829349227 473422 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829357477 475978 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829360033 476063 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829360118 484277 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829368332 486692 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829370747 486777 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829370832 495017 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829379072 497414 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829381469 497484 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829381539 505846 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829389901 508246 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829392301 508372 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829392427 515839 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829399894 518231 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829402286 518311 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829402366 526726 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829410781 529169 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829413224 529247 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829413301 537429 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829421484 539870 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829423924 539940 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829423995 548251 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829432306 550665 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829434718 550748 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829434802 559092 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829443146 561512 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829445566 561597 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829445652 569838 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829453893 572322 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829456377 572392 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829456447 580650 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829464705 583039 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829467094 583111 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829467166 591459 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829475513 593864 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829477918 593944 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829477999 601546 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829485601 603939 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829487994 604008 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829488063 612382 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829496437 614800 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829498855 614878 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829498933 623026 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829507081 625442 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829509496 625527 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829509582 633825 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829517879 636242 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829520296 636510 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829520565 644630 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829528685 647033 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829531087 647105 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829531160 655457 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829539512 657900 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829541955 657971 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829542025 666289 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829550344 668790 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829552845 668900 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829552955 677065 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829561119 679460 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829563514 679665 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829563719 687102 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829571157 689499 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829573554 689635 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829573690 697835 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829581890 700231 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829584286 700312 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829584366 708643 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829592698 711061 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829595116 711147 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829595202 719436 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829603490 721853 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829605908 721938 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829605992 730239 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829614294 732630 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829616685 732698 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829616753 741089 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829625143 743479 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829627534 743660 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829627715 751912 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829635967 754332 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829638387 754438 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829638493 762643 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829646697 765040 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829649095 765116 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829649171 772779 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829656834 775169 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829659224 775260 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829659315 783486 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829667541 785903 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829669957 786070 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829670125 794300 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829678355 796712 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829680766 796853 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829680908 805055 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829689109 807502 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829691556 807614 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829691668 815867 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829699922 818259 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829702314 818390 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829702445 826688 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829710743 829080 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829713134 829151 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829713205 837460 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829721515 839849 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829723904 839921 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829723976 848353 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829732407 850776 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829734831 850857 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829734912 858257 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829742312 860672 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829744727 860752 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829744807 869066 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829753121 871546 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829755601 871640 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829755695 879866 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829763921 882341 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829766395 882416 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829766471 890792 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829774847 893190 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829777245 893261 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829777316 901527 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829785582 903919 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829787974 903993 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829788047 912320 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829796375 914710 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829798765 914830 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829798884 923060 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829807114 925476 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829809531 925558 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829809613 933861 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829817915 936272 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829820327 936371 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829820426 943872 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829827927 946302 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829830357 946399 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829830454 954681 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829838736 957091 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829841145 957168 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829841223 965586 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829849641 967980 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829852034 968049 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829852104 976518 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829860572 978910 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829862964 978978 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829863033 987120 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829871175 989510 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829873565 989587 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829873642 997872 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829881927 1000270 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829884324 1000368 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829884422 1008672 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829892726 1011091 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829895146 1011177 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829895232 1019473 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829903528 1021892 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829905946 1022059 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829906113 1025715 INFO CommsSchedulerTask [Thread-70]: CommsSchedulerTask.run(): Oct 2, 2015 11:51:49 PM 1026275 INFO CommsLeaseListener [Thread-70]: Calling SyncProcessRunner("echo ethOn"); attempt #0 1026407 INFO CommsLeaseListener [Thread-70]: 1029471 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829913526 1031882 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829915937 1031962 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829916016 1037424 INFO CommsLeaseListener [Thread-127]: SyncProcessRunner.exec("echo ethOff"), connect time = 11 1037555 INFO CommsLeaseListener [Thread-127]: 1040287 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829924341 1042681 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829926735 1042832 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829926887 1051100 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829935154 1053490 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829937545 1053573 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829937627 1061899 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829945953 1064290 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829948344 1064370 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829948424 1072684 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829956739 1075081 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829959135 1075156 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829959211 1083485 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829967540 1085907 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829969962 1085992 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829970047 1094380 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829978435 1096791 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829980846 1096877 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829980932 1105081 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829989136 1107472 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443829991527 1107541 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443829991596 1115089 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443829999144 1117480 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443830001535 1117551 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443830001606 1125909 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443830009964 1128300 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443830012354 1128380 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443830012435 1136708 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443830020763 1139100 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443830023154 1139172 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443830023227 1147600 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443830031655 1150000 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443830034055 1150086 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443830034140 1158281 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443830042336 1160757 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443830044812 1160923 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443830044978 1169091 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443830053146 1171507 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443830055561 1171590 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443830055644 1179900 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443830063955 1182372 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443830066426 1182460 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443830066515 1190712 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443830074767 1193110 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443830077165 1193181 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443830077236 1200711 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443830084765 1203100 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443830087155 1203171 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443830087226 1211521 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443830095576 1213998 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443830098053 1214071 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443830098126 1222356 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443830106408 1224749 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443830108804 1224904 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443830108958 1233109 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443830117164 1235522 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443830119577 1235616 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443830119671 1243900 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443830127955 1246318 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443830130373 1246417 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443830130471 1254745 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443830138800 1257218 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443830141272 1257409 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443830141464 1265979 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443830150034 1268424 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443830152479 1268499 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443830152554 1276507 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443830160562 1278959 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443830163014 1279038 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443830163093 1286317 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443830170372 1288769 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443830172824 1288931 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443830172986 1297122 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443830181177 1299519 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443830183574 1299632 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443830183687 1307930 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443830191985 1310320 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443830194374 1310422 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443830194477 1318703 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443830202758 1321129 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443830205184 1321267 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443830205321 1329580 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443830213634 1331992 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443830216047 1332083 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443830216138 1340312 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443830224367