0 INFO NodeMain [main]: NodeMain.main(): portalHost = null 1356 INFO NodeManager [main]: Starting NodeManager at 10/02/2015 23:12:03 GMT (1443827523643), version: $Name: $ 1358 INFO NodeManager [main]: Node IP: node2/134.89.36.2 2026 INFO NodeManager [main]: Node ID has been set to 1598 3254 INFO PortManager [main]: Found 14 configured ports in properties file 3318 INFO MOOSPortManager [main]: Found DPA at slot: 0 5198 INFO MOOSPortManager [main]: Found DPA at slot: 1 6808 INFO MOOSPortManager [main]: Found DPA at slot: 2 8398 INFO MOOSPortManager [main]: Found DPA at slot: 3 9986 INFO MOOSPortManager [main]: No DPA found at slot: 4 9990 INFO MOOSPortManager [main]: No DPA found at slot: 5 10025 INFO WDTManager [main]: WDTManager constructor 10027 INFO MOOSWDTManager [main]: MOOSWDTManager constructor 10090 INFO SleepManager [main]: SleepManager constructor 10094 INFO MOOSSleepManager [main]: MOOSSleepManager constructor 11774 INFO CommsLeaseListener [main]: CommsLeaseListener() complete 11840 INFO CpuLeaseSleepRollcallListener [main]: CpuLeaseSleepRollcallListener() constructor 11855 INFO CpuLease [main]: CpuLease() constructor 11858 INFO CpuLease [Thread-5]: CpuLease started 13805 ERROR NodeService [main]: Turbinator constructor failed: com.rbnb.sapi.SAPIException: Nesting java.net.ConnectException 13904 INFO MOOSNodeService [main]: Last MMC reset code: 2 14194 INFO NodeManager [main]: Starting registry... 14232 INFO NodeManager [main]: registry started. 14233 INFO NodeManager [main]: binding NodeService to rmi://localhost/node 14794 INFO NodeManager [main]: NodeService is bound to rmi://localhost/node 14924 INFO MOOSWDTManager [Thread-2]: WDTManager initializing watchdog timer 15152 INFO PortManager [main]: Service jar not specified for port /dev/ttySX0 16040 INFO PortManager [main]: Starting service... 16264 INFO MOOSWDTManager [Thread-2]: WDTManager watchdog initialization successful 31794 INFO BaseInstrumentService [main]: initializeInstrument() for 33368 WARN StreamingSBE19 [main]: Did not receive expected confirmation prompt from command: MP 38393 INFO StreamingSBE19 [main]: Event detection disabled by attribute 38466 INFO BaseInstrumentService [main]: done with initializeInstrument() for 46701 INFO PortManager [main]: service started 47214 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 48011 INFO PortManager [main]: Starting service... 68309 INFO BaseInstrumentService [main]: initializeInstrument() for 68310 INFO WetLabsECO [main]: Initializing... 83729 INFO WetLabsECO [main]: Initializing completed 83730 INFO BaseInstrumentService [main]: done with initializeInstrument() for 92821 INFO PolledInstrumentService [main]: prepareToRun(): sampleMasterId = 1827 92823 INFO PolledInstrumentService [main]: id 1535 slaved to 1827 92842 INFO PortManager [main]: service started 92844 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 92859 INFO WetLabsECO [Thread-24]: TEST: ID=1535, trigger sample at 1443827615154 94886 INFO PortManager [main]: Starting service... 95249 INFO WetLabsECO [Thread-24]: TEST: ID=1535, got complete sample at + 1443827617544 155221 INFO BaseInstrumentService [main]: initializeInstrument() for 157197 INFO Aquadopp [main]: sendSoftBreak(): instrument in mode 5 163437 INFO BaseInstrumentService [main]: done with initializeInstrument() for 163541 INFO Aquadopp [main]: Retrieving Aquadopp state Metadata... 165516 INFO Aquadopp [main]: sendSoftBreak(): instrument in mode 2 173213 INFO PortManager [main]: service started 173222 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 173808 INFO PortManager [main]: Starting service... 175406 INFO Aquadopp [Thread-33]: sendSoftBreak(): instrument in mode 2 184724 INFO BaseInstrumentService [main]: initializeInstrument() for 189616 INFO BaseInstrumentService [main]: done with initializeInstrument() for 191522 INFO PolledInstrumentService [main]: prepareToRun(): sampleMasterId = 1535 191525 INFO PolledInstrumentService [main]: id 1524 slaved to 1535 191529 INFO PortManager [main]: service started 191534 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 191548 INFO AanderaaO2 [Thread-37]: TT: ID=1524, trigger sample at 1443827713842 192617 INFO PortManager [main]: Starting service... 227950 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 233574 INFO BaseInstrumentService [main]: initializeInstrument() for 233576 INFO StreamingWorkhorseADCP-1825 [main]: Initializing Workhorse 1825 233586 INFO StreamingWorkhorseADCP-1825 [main]: Instrument has been pre-configured; ignore configuration attributes 236994 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TE? 237033 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 237120 INFO StreamingWorkhorseADCP-1825 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 237121 INFO StreamingWorkhorseADCP-1825 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 237364 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CF? 237403 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 237447 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 237448 INFO StreamingWorkhorseADCP-1825 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 237450 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 237452 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): property char 3=1 237454 INFO StreamingWorkhorseADCP-1825 [main]: Start pinging 237641 INFO BaseInstrumentService [main]: done with initializeInstrument() for 241144 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WD? 241183 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 241724 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WF? 241769 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 242354 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WN? 242393 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 242924 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WP? 242962 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 243494 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WS? 243532 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 244063 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WV? 244150 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 244684 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TE? 244722 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 245284 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TP? 245322 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 245855 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TS? 245894 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 246454 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EA? 246492 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 247066 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EB? 247108 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 247634 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request ED? 247673 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 248204 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request ES? 248242 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 248823 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EX? 248862 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 249424 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EZ? 249460 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 250004 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CF? 250042 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 250594 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RA? 250618 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 251134 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RR? 251173 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 252522 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RF? 252570 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 253104 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CB? 253142 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 253684 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request PS0 253707 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 255584 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request PS3 255607 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 256914 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RF 256949 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 257434 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request AC 257472 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 259640 INFO PortManager [main]: service started 259644 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 260710 INFO PortManager [main]: Starting service... 297655 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 308314 INFO BaseInstrumentService [main]: initializeInstrument() for 308316 INFO StreamingWorkhorseADCP-1828 [main]: Initializing Workhorse 1828 308327 INFO StreamingWorkhorseADCP-1828 [main]: Instrument has been pre-configured; ignore configuration attributes 311767 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TE? 311824 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 311857 INFO StreamingWorkhorseADCP-1828 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 311859 INFO StreamingWorkhorseADCP-1828 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 312094 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CF? 312133 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 312178 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 312179 INFO StreamingWorkhorseADCP-1828 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 312181 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 312183 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): property char 3=1 312184 INFO StreamingWorkhorseADCP-1828 [main]: Start pinging 312371 INFO BaseInstrumentService [main]: done with initializeInstrument() for 315874 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WD? 315913 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 316544 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WF? 316584 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 317144 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WN? 317184 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 317714 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WP? 317752 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 318324 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WS? 318364 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 318886 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WV? 318939 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 319486 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TE? 319533 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 320086 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TP? 320135 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 320734 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TS? 320773 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 321324 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EA? 321360 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 321904 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EB? 321942 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 322474 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request ED? 322513 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 323054 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request ES? 323093 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 323624 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EX? 323663 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 324244 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EZ? 324283 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 324814 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CF? 324853 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 325394 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RA? 325418 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 325934 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RR? 325973 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 327254 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RF? 327294 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 327874 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CB? 327913 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 328454 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request PS0 328478 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 330436 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request PS3 330464 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 331786 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RF 331827 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 332314 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request AC 332352 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 334493 INFO PortManager [main]: service started 334494 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 335562 INFO PortManager [main]: Starting service... 373461 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 379106 INFO BaseInstrumentService [main]: initializeInstrument() for 379108 INFO StreamingWorkhorseADCP-1827 [main]: Initializing Workhorse 1827 379120 INFO StreamingWorkhorseADCP-1827 [main]: Instrument has been pre-configured; ignore configuration attributes 382554 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request TE? 382644 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 382692 INFO StreamingWorkhorseADCP-1827 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 382694 INFO StreamingWorkhorseADCP-1827 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 382934 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request CF? 382973 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 383018 INFO StreamingWorkhorseADCP-1827 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 383019 INFO StreamingWorkhorseADCP-1827 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 383021 INFO StreamingWorkhorseADCP-1827 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 383023 INFO StreamingWorkhorseADCP-1827 [main]: initializeInstrument(): property char 3=1 383024 INFO StreamingWorkhorseADCP-1827 [main]: Start pinging 383211 INFO BaseInstrumentService [main]: done with initializeInstrument() for 386734 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request WD? 386774 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 387404 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request WF? 387443 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 387994 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request WN? 388030 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 388564 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request WP? 388604 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 389205 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request WS? 389253 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 389790 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request WV? 390009 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 390614 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request TE? 390653 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 391204 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request TP? 391240 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 391815 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request TS? 391857 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 392444 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request EA? 392488 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 393024 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request EB? 393063 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 393606 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request ED? 393656 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 394206 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request ES? 394317 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 394856 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request EX? 394905 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 395484 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request EZ? 395524 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 396054 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request CF? 396094 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 396664 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request RA? 396688 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 397204 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request RR? 397244 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 398424 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request RF? 398461 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 399056 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request CB? 399115 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 399696 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request PS0 399728 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 401594 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request PS3 401618 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 402984 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request RF 403019 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 403506 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request AC 403553 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 405789 INFO PortManager [main]: service started 405792 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 405885 INFO PortManager [main]: Service jar not specified for port /dev/ttySX8 405886 INFO PortManager [main]: Service jar not specified for port /dev/ttySX9 405887 INFO PortManager [main]: Service jar not specified for port /dev/ttySX10 405888 INFO PortManager [main]: Service jar not specified for port /dev/ttySX11 406346 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443827928641 406447 INFO PortManager [main]: Starting service... 408767 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443827931062 409102 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443827931397 418432 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443827940727 419606 INFO BaseInstrumentService [main]: initializeInstrument() for 420831 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443827943126 420900 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443827943194 421006 INFO BaseInstrumentService [main]: done with initializeInstrument() for 424392 INFO PolledInstrumentService [main]: prepareToRun(): sampleMasterId = -1 424399 INFO PolledInstrumentService [main]: id 1599 not slaved 424400 INFO PortManager [main]: service started 424404 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 424707 INFO PortManager [main]: Service jar not specified for port /dev/ttySA2 425807 INFO CommsSchedulerTask [Thread-69]: CommsSchedulerTask.run(): Oct 2, 2015 11:19:07 PM 425922 INFO ThreadUtility [Thread-71]: ThreadGroup "main" includes about 37 threads 426595 INFO ThreadUtility [Thread-71]: thread: "Thread-72" alive daemon thread: "Thread-71" alive thread: "Thread-70" alive thread: "Thread-69" alive thread: "Thread-68" alive thread: "Thread-65" alive daemon thread: "Thread-64" alive thread: "Thread-56" alive daemon thread: "Thread-55" alive thread: "Thread-46" alive daemon thread: "Thread-45" alive thread: "Thread-39" alive daemon thread: "Thread-38" alive thread: "Thread-37" alive thread: "Thread-34" alive daemon thread: "Thread-33" alive thread: "Thread-26" alive daemon thread: "Thread-25" alive thread: "Thread-24" alive thread: "Thread-21" alive thread: "Thread-18" alive daemon thread: "Thread-17" 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 426828 INFO CommsLeaseListener [Thread-69]: Calling SyncProcessRunner("echo ethOn"); attempt #0 427229 INFO NodeManager [main]: NodeManager "node" started. 427527 INFO CommsLeaseListener [Thread-69]: ethOn 428765 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443827951058 431169 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443827953464 431290 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443827953584 438674 INFO CommsLeaseListener [Thread-75]: SyncProcessRunner.exec("echo ethOff"), connect time = 11 439236 INFO CommsLeaseListener [Thread-75]: ethOff 439501 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443827961796 441895 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443827964190 441965 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443827964259 450292 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443827972587 452689 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443827974983 452760 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443827975055 461076 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443827983371 463541 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443827985836 463708 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443827986003 471878 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443827994173 474292 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443827996587 474373 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443827996668 482664 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828004958 485159 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828007454 485236 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828007531 493570 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828015865 495960 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828018254 496031 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828018326 504350 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828026645 506739 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828029034 506808 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828029103 514298 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828036593 516689 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828038984 516758 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828039052 525115 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828047410 527579 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828049874 527657 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828049952 535884 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828058179 538280 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828060574 538434 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828060729 546677 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828068972 549116 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828071411 549200 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828071495 557481 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828079776 559916 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828082211 559998 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828082293 568368 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828090663 570760 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828093054 570840 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828093135 579210 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828101505 581600 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828103894 581687 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828103982 590217 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828112512 592679 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828114974 592837 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828115132 599918 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828122213 602309 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828124604 602381 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828124676 610728 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828133023 613119 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828135414 613199 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828135493 621499 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828143794 623928 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828146222 624011 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828146306 632296 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828154591 634712 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828157007 634796 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828157091 643174 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828165468 645626 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828167921 645699 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828167993 654008 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828176303 656399 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828178694 656486 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828178781 664716 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828187011 667109 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828189404 667197 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828189492 675485 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828197780 677893 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828200188 677987 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828200282 685492 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828207786 687903 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828210198 687972 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828210266 696330 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828218625 698736 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828221031 698820 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828221115 707170 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828229465 709580 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828231875 709662 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828231957 717909 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828240203 720329 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828242624 720411 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828242706 728727 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828251022 731119 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828253414 731280 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828253574 739517 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828261812 741919 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828264213 741990 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828264285 750313 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828272607 752709 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828275004 752796 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828275091 761139 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828283434 763633 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828285928 763722 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828286017 771140 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828293435 773580 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828295875 773667 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828295962 781908 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828304203 784321 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828306616 784403 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828306697 792707 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828315002 795154 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828317449 795227 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828317522 803561 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828325856 805955 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828328250 806047 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828328341 814328 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828336623 816719 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828339013 816840 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828339135 825247 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828347541 827639 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828349934 827709 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828350004 835995 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828358290 838390 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828360684 838470 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828360765 846702 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828368997 849121 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828371416 849250 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828371544 856718 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828379013 859132 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828381426 859279 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828381574 867512 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828389807 870071 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828392366 870151 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828392446 878312 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828400607 880716 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828403010 880797 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828403091 889138 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828411433 891529 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828413824 891601 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828413896 900028 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828422323 902419 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828424714 902501 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828424796 910715 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828433009 913109 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828435404 913176 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828435471 921522 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828443817 923961 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828446256 924113 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828446407 932336 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828454631 934761 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828457056 934857 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828457151 942323 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828464617 944742 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828467037 944833 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828467127 953158 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828475453 955549 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828477844 955696 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828477991 963967 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828486262 966360 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828488654 966431 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828488725 974822 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828497117 977220 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828499514 977316 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828499611 985521 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828507816 987924 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828510218 988127 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828510422 996351 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828518646 998801 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828521096 998968 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828521263 1007130 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828529424 1009542 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828531836 1009622 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828531917 1017940 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828540235 1020357 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828542651 1020447 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828542741 1025575 INFO CommsSchedulerTask [Thread-69]: CommsSchedulerTask.run(): Oct 2, 2015 11:29:07 PM 1028022 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828550317 1030441 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828552736 1030527 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828552821 1037174 INFO CommsLeaseListener [Thread-125]: SyncProcessRunner.exec("echo ethOff"), connect time = 609 1037216 INFO CommsLeaseListener [Thread-125]: 1038738 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828561033 1041129 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828563424 1041218 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828563513 1049547 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828571841 1051951 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828574246 1052021 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828574316 1060356 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828582651 1062749 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828585044 1062918 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828585213 1071121 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828593416 1073590 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828595885 1073819 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828596114 1081939 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828604234 1084416 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828606708 1084541 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828606836 1092738 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828615033 1095184 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828617478 1095255 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828617550 1103607 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828625901 1106001 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828628296 1106082 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828628377 1113593 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828635887 1115995 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828638290 1116068 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828638363 1124517 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828646812 1126910 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828649205 1127041 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828649336 1135172 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828657467 1137569 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828659863 1137702 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828659997 1145946 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828668241 1148339 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828670634 1148411 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828670706 1156731 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828679026 1159146 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828681441 1159228 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828681523 1167627 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828689921 1170090 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828692385 1170172 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828692466 1178352 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828700647 1180774 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828703068 1180883 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828703177 1189171 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443828711466 1191560 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443828713854 1191728 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443828714023