1 INFO NodeMain [main]: NodeMain.main(): portalHost = null 1356 INFO NodeManager [main]: Starting NodeManager at 10/02/2015 23:58:51 GMT (1443830331612), version: $Name: $ 1358 INFO NodeManager [main]: Node IP: node2/134.89.36.2 2038 INFO NodeManager [main]: Node ID has been set to 1598 3223 INFO PortManager [main]: Found 14 configured ports in properties file 3286 INFO MOOSPortManager [main]: Found DPA at slot: 0 5179 INFO MOOSPortManager [main]: Found DPA at slot: 1 6799 INFO MOOSPortManager [main]: Found DPA at slot: 2 8389 INFO MOOSPortManager [main]: Found DPA at slot: 3 9977 INFO MOOSPortManager [main]: No DPA found at slot: 4 9981 INFO MOOSPortManager [main]: No DPA found at slot: 5 10016 INFO WDTManager [main]: WDTManager constructor 10017 INFO MOOSWDTManager [main]: MOOSWDTManager constructor 10081 INFO SleepManager [main]: SleepManager constructor 10085 INFO MOOSSleepManager [main]: MOOSSleepManager constructor 11755 INFO CommsLeaseListener [main]: CommsLeaseListener() complete 11821 INFO CpuLeaseSleepRollcallListener [main]: CpuLeaseSleepRollcallListener() constructor 11836 INFO CpuLease [main]: CpuLease() constructor 11838 INFO CpuLease [Thread-5]: CpuLease started 13806 ERROR NodeService [main]: Turbinator constructor failed: com.rbnb.sapi.SAPIException: Nesting java.net.ConnectException 13905 INFO MOOSNodeService [main]: Last MMC reset code: 2 14115 INFO NodeManager [main]: Starting registry... 14153 INFO NodeManager [main]: registry started. 14154 INFO NodeManager [main]: binding NodeService to rmi://localhost/node 14705 INFO NodeManager [main]: NodeService is bound to rmi://localhost/node 14729 INFO MOOSWDTManager [Thread-2]: WDTManager initializing watchdog timer 15035 INFO PortManager [main]: Service jar not specified for port /dev/ttySX0 15960 INFO PortManager [main]: Starting service... 16093 INFO MOOSWDTManager [Thread-2]: WDTManager watchdog initialization successful 32025 INFO BaseInstrumentService [main]: initializeInstrument() for 33599 WARN StreamingSBE19 [main]: Did not receive expected confirmation prompt from command: MP 38634 INFO StreamingSBE19 [main]: Event detection disabled by attribute 38790 INFO BaseInstrumentService [main]: done with initializeInstrument() for 46893 INFO PortManager [main]: service started 47415 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 48055 INFO PortManager [main]: Starting service... 68689 INFO BaseInstrumentService [main]: initializeInstrument() for 68691 INFO WetLabsECO [main]: Initializing... 84068 INFO WetLabsECO [main]: Initializing completed 84069 INFO BaseInstrumentService [main]: done with initializeInstrument() for 93066 INFO PolledInstrumentService [main]: prepareToRun(): sampleMasterId = 1827 93068 INFO PolledInstrumentService [main]: id 1535 slaved to 1827 93086 INFO PortManager [main]: service started 93093 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 93116 INFO WetLabsECO [Thread-24]: TEST: ID=1535, trigger sample at 1443830423379 95099 INFO PortManager [main]: Starting service... 95586 INFO WetLabsECO [Thread-24]: TEST: ID=1535, got complete sample at + 1443830425849 155955 INFO BaseInstrumentService [main]: initializeInstrument() for 157950 INFO Aquadopp [main]: sendSoftBreak(): instrument in mode 5 164188 INFO BaseInstrumentService [main]: done with initializeInstrument() for 164294 INFO Aquadopp [main]: Retrieving Aquadopp state Metadata... 166307 INFO Aquadopp [main]: sendSoftBreak(): instrument in mode 2 173991 INFO PortManager [main]: service started 173996 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 174451 INFO PortManager [main]: Starting service... 176117 INFO Aquadopp [Thread-33]: sendSoftBreak(): instrument in mode 2 185424 INFO BaseInstrumentService [main]: initializeInstrument() for 190187 INFO BaseInstrumentService [main]: done with initializeInstrument() for 192120 INFO PolledInstrumentService [main]: prepareToRun(): sampleMasterId = 1535 192121 INFO PolledInstrumentService [main]: id 1524 slaved to 1535 192128 INFO PortManager [main]: service started 192134 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 192172 INFO AanderaaO2 [Thread-37]: TT: ID=1524, trigger sample at 1443830522435 193195 INFO PortManager [main]: Starting service... 228848 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 234474 INFO BaseInstrumentService [main]: initializeInstrument() for 234477 INFO StreamingWorkhorseADCP-1825 [main]: Initializing Workhorse 1825 234487 INFO StreamingWorkhorseADCP-1825 [main]: Instrument has been pre-configured; ignore configuration attributes 237905 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TE? 237946 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 238047 INFO StreamingWorkhorseADCP-1825 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 238049 INFO StreamingWorkhorseADCP-1825 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 238294 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CF? 238330 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 238378 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 238379 INFO StreamingWorkhorseADCP-1825 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 238381 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 238383 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): property char 3=1 238384 INFO StreamingWorkhorseADCP-1825 [main]: Start pinging 238572 INFO BaseInstrumentService [main]: done with initializeInstrument() for 242045 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WD? 242083 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 242625 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WF? 242663 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 243235 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WN? 243273 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 243805 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WP? 243862 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 244425 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WS? 244464 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 245044 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WV? 245122 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 245654 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TE? 245693 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 246235 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TP? 246273 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 246814 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TS? 246853 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 247385 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EA? 247423 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 247966 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EB? 248015 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 248545 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request ED? 248583 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 249115 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request ES? 249154 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 249684 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EX? 249723 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 250315 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EZ? 250353 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 250894 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CF? 250930 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 251475 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RA? 251498 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 252014 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RR? 252053 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 253635 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RF? 253671 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 254244 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CB? 254283 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 254825 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request PS0 254849 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 256705 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request PS3 256729 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 258035 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RF 258070 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 258555 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request AC 258592 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 260795 INFO PortManager [main]: service started 260804 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 261872 INFO PortManager [main]: Starting service... 299342 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 310014 INFO BaseInstrumentService [main]: initializeInstrument() for 310017 INFO StreamingWorkhorseADCP-1828 [main]: Initializing Workhorse 1828 310027 INFO StreamingWorkhorseADCP-1828 [main]: Instrument has been pre-configured; ignore configuration attributes 313483 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TE? 313531 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 313565 INFO StreamingWorkhorseADCP-1828 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 313566 INFO StreamingWorkhorseADCP-1828 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 313805 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CF? 313862 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 313939 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 313940 INFO StreamingWorkhorseADCP-1828 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 313942 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 313946 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): property char 3=1 313948 INFO StreamingWorkhorseADCP-1828 [main]: Start pinging 314132 INFO BaseInstrumentService [main]: done with initializeInstrument() for 317745 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WD? 317780 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 318325 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WF? 318364 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 318915 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WN? 318954 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 319484 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WP? 319523 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 320056 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WS? 320148 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 320686 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WV? 320734 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 321286 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TE? 321334 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 321922 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TP? 321971 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 322554 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TS? 322593 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 323145 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EA? 323183 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 323714 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EB? 323754 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 324285 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request ED? 324323 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 324855 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request ES? 324893 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 325425 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EX? 325464 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 326007 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EZ? 326048 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 326635 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CF? 326673 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 327235 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RA? 327259 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 327775 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RR? 327813 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 329345 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RF? 329381 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 329926 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CB? 329983 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 330605 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request PS0 330631 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 332525 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request PS3 332549 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 333861 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RF 333933 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 334425 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request AC 334463 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 336637 INFO PortManager [main]: service started 336684 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 337711 INFO PortManager [main]: Starting service... 375448 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 381086 INFO BaseInstrumentService [main]: initializeInstrument() for 381088 INFO StreamingWorkhorseADCP-1827 [main]: Initializing Workhorse 1827 381101 INFO StreamingWorkhorseADCP-1827 [main]: Instrument has been pre-configured; ignore configuration attributes 384495 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request TE? 384535 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 384579 INFO StreamingWorkhorseADCP-1827 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 384580 INFO StreamingWorkhorseADCP-1827 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 384825 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request CF? 384864 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 384909 INFO StreamingWorkhorseADCP-1827 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 384911 INFO StreamingWorkhorseADCP-1827 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 384913 INFO StreamingWorkhorseADCP-1827 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 384915 INFO StreamingWorkhorseADCP-1827 [main]: initializeInstrument(): property char 3=1 384916 INFO StreamingWorkhorseADCP-1827 [main]: Start pinging 385102 INFO BaseInstrumentService [main]: done with initializeInstrument() for 388685 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request WD? 388721 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 389305 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request WF? 389344 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 389927 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request WN? 389982 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 390586 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request WP? 390636 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 391177 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request WS? 391234 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 391829 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request WV? 391873 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 392405 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request TE? 392444 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 392994 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request TP? 393034 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 393565 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request TS? 393608 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 394184 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request EA? 394224 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 394755 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request EB? 394793 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 395325 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request ED? 395364 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 395897 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request ES? 395947 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 396577 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request EX? 396627 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 397196 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request EZ? 397268 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 397795 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request CF? 397835 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 398375 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request RA? 398398 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 398955 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request RR? 398994 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 400426 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request RF? 400472 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 401026 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request CB? 401076 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 401656 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request PS0 401720 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 403545 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request PS3 403569 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 404845 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request RF 404880 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 405364 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request AC 405403 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 407635 INFO PortManager [main]: service started 407723 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 407916 INFO PortManager [main]: Service jar not specified for port /dev/ttySX8 407917 INFO PortManager [main]: Service jar not specified for port /dev/ttySX9 407918 INFO PortManager [main]: Service jar not specified for port /dev/ttySX10 407919 INFO PortManager [main]: Service jar not specified for port /dev/ttySX11 408361 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443830738625 408386 INFO PortManager [main]: Starting service... 410792 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443830741056 411168 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443830741431 421482 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443830751745 422234 INFO BaseInstrumentService [main]: initializeInstrument() for 423626 INFO BaseInstrumentService [main]: done with initializeInstrument() for 424370 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443830754633 424665 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443830754929 427602 INFO PolledInstrumentService [main]: prepareToRun(): sampleMasterId = -1 427604 INFO PolledInstrumentService [main]: id 1599 not slaved 427607 INFO PortManager [main]: service started 427655 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 427715 INFO PortManager [main]: Service jar not specified for port /dev/ttySA2 427753 INFO CommsSchedulerTask [Thread-69]: CommsSchedulerTask.run(): Oct 3, 2015 12:05:58 AM 428213 INFO ThreadUtility [Thread-72]: ThreadGroup "main" includes about 37 threads 428356 INFO CommsLeaseListener [Thread-69]: Calling SyncProcessRunner("echo ethOn"); attempt #0 428820 INFO ThreadUtility [Thread-72]: thread: "RMI-ServerConnection:127.0.0.1" alive daemon thread: "Thread-72" alive thread: "Thread-70" alive thread: "Thread-69" alive thread: "Thread-68" alive thread: "Thread-65" alive daemon thread: "Thread-64" alive thread: "Thread-57" alive daemon thread: "Thread-56" alive thread: "Thread-47" alive daemon thread: "Thread-46" 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 428830 INFO CommsLeaseListener [Thread-69]: 429458 INFO NodeManager [main]: NodeManager "node" started. 430943 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443830761207 433350 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443830763614 433447 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443830763711 439985 INFO CommsLeaseListener [Thread-76]: SyncProcessRunner.exec("echo ethOff"), connect time = 11 440548 INFO CommsLeaseListener [Thread-76]: ethOff 441550 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443830771813 444001 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443830774264 444075 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443830774339 452314 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443830782577 454710 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443830784974 454783 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443830785047 463111 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443830793375 465510 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443830795774 465593 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443830795857 473831 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443830804095 476247 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443830806511 476338 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443830806602