1 INFO NodeMain [main]: NodeMain.main(): portalHost = null 1357 INFO NodeManager [main]: Starting NodeManager at 10/02/2015 22:48:55 GMT (1443826135713), version: $Name: $ 1359 INFO NodeManager [main]: Node IP: node2/134.89.36.2 2022 INFO NodeManager [main]: Node ID has been set to 1598 3193 INFO PortManager [main]: Found 14 configured ports in properties file 3256 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 11775 INFO CommsLeaseListener [main]: CommsLeaseListener() complete 11841 INFO CpuLeaseSleepRollcallListener [main]: CpuLeaseSleepRollcallListener() constructor 11856 INFO CpuLease [main]: CpuLease() constructor 11858 INFO CpuLease [Thread-5]: CpuLease started 13795 ERROR NodeService [main]: Turbinator constructor failed: com.rbnb.sapi.SAPIException: Nesting java.net.ConnectException 13894 INFO MOOSNodeService [main]: Last MMC reset code: 1 14095 INFO NodeManager [main]: Starting registry... 14133 INFO NodeManager [main]: registry started. 14134 INFO NodeManager [main]: binding NodeService to rmi://localhost/node 14715 INFO NodeManager [main]: NodeService is bound to rmi://localhost/node 14877 INFO MOOSWDTManager [Thread-2]: WDTManager initializing watchdog timer 14884 INFO MOOSWDTManager [Thread-2]: Reboot due to hardware reset 15036 INFO PortManager [main]: Service jar not specified for port /dev/ttySX0 15920 INFO PortManager [main]: Starting service... 16731 INFO MOOSWDTManager [Thread-2]: WDTManager watchdog initialization successful 32185 INFO BaseInstrumentService [main]: initializeInstrument() for 33759 WARN StreamingSBE19 [main]: Did not receive expected confirmation prompt from command: MP 38794 INFO StreamingSBE19 [main]: Event detection disabled by attribute 38857 INFO BaseInstrumentService [main]: done with initializeInstrument() for 47081 INFO PortManager [main]: service started 47470 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 48062 INFO PortManager [main]: Starting service... 68985 INFO BaseInstrumentService [main]: initializeInstrument() for 68987 INFO WetLabsECO [main]: Initializing... 84348 INFO WetLabsECO [main]: Initializing completed 84349 INFO BaseInstrumentService [main]: done with initializeInstrument() for 93389 INFO PolledInstrumentService [main]: prepareToRun(): sampleMasterId = 1827 93392 INFO PolledInstrumentService [main]: id 1535 slaved to 1827 93460 INFO PortManager [main]: service started 93465 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 93480 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1443826227844 95837 INFO PortManager [main]: Starting service... 96102 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1443826230466 157352 INFO BaseInstrumentService [main]: initializeInstrument() for 159318 INFO Aquadopp [main]: sendSoftBreak(): instrument in mode 5 165558 INFO BaseInstrumentService [main]: done with initializeInstrument() for 165678 INFO Aquadopp [main]: Retrieving Aquadopp state Metadata... 167658 INFO Aquadopp [main]: sendSoftBreak(): instrument in mode 2 175364 INFO PortManager [main]: service started 175365 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 175883 INFO PortManager [main]: Starting service... 177507 INFO Aquadopp [Thread-34]: sendSoftBreak(): instrument in mode 2 187004 INFO BaseInstrumentService [main]: initializeInstrument() for 191817 INFO BaseInstrumentService [main]: done with initializeInstrument() for 193902 INFO PolledInstrumentService [main]: prepareToRun(): sampleMasterId = 1535 193903 INFO PolledInstrumentService [main]: id 1524 slaved to 1535 193909 INFO PortManager [main]: service started 193915 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 193934 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1443826328297 195101 INFO PortManager [main]: Starting service... 231778 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 237404 INFO BaseInstrumentService [main]: initializeInstrument() for 237406 INFO StreamingWorkhorseADCP-1825 [main]: Initializing Workhorse 1825 237416 INFO StreamingWorkhorseADCP-1825 [main]: Instrument has been pre-configured; ignore configuration attributes 240825 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TE? 240864 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 240907 INFO StreamingWorkhorseADCP-1825 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 240908 INFO StreamingWorkhorseADCP-1825 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 241155 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CF? 241194 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 241238 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 241239 INFO StreamingWorkhorseADCP-1825 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 241241 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 241243 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): property char 3=1 241244 INFO StreamingWorkhorseADCP-1825 [main]: Start pinging 241432 INFO BaseInstrumentService [main]: done with initializeInstrument() for 244905 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WD? 244943 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 245485 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WF? 245523 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 246105 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WN? 246143 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 246675 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WP? 246713 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 247244 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WS? 247283 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 247864 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WV? 247903 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 248504 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TE? 248543 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 249085 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TP? 249123 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 249680 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TS? 249741 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 250275 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EA? 250313 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 250844 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EB? 250883 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 251415 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request ED? 251453 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 251985 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request ES? 252024 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 252593 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EX? 252643 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 253175 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EZ? 253213 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 253774 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CF? 253813 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 254355 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RA? 254378 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 254895 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RR? 254934 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 255935 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RF? 255973 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 256515 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CB? 256553 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 257095 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request PS0 257118 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 259024 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request PS3 259048 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 260415 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RF 260449 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 260934 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request AC 261021 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 263195 INFO PortManager [main]: service started 263205 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 264270 INFO PortManager [main]: Starting service... 301712 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 312434 INFO BaseInstrumentService [main]: initializeInstrument() for 312437 INFO StreamingWorkhorseADCP-1828 [main]: Initializing Workhorse 1828 312449 INFO StreamingWorkhorseADCP-1828 [main]: Instrument has been pre-configured; ignore configuration attributes 315875 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TE? 315916 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 315961 INFO StreamingWorkhorseADCP-1828 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 315962 INFO StreamingWorkhorseADCP-1828 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 316214 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CF? 316259 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 316299 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 316300 INFO StreamingWorkhorseADCP-1828 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 316302 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 316303 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): property char 3=1 316305 INFO StreamingWorkhorseADCP-1828 [main]: Start pinging 316492 INFO BaseInstrumentService [main]: done with initializeInstrument() for 320074 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WD? 320113 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 320655 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WF? 320694 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 321224 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WN? 321265 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 321795 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WP? 321833 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 322407 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WS? 322458 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 323057 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WV? 323106 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 323726 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TE? 323770 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 324335 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TP? 324420 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 324945 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TS? 324984 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 325524 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EA? 325564 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 326095 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EB? 326133 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 326665 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request ED? 326703 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 327295 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request ES? 327333 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 327865 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EX? 327904 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 328444 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EZ? 328483 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 329034 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CF? 329073 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 329618 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RA? 329651 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 330215 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RR? 330255 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 331295 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RF? 331331 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 331875 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CB? 331914 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 332457 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request PS0 332490 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 334375 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request PS3 334399 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 335704 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RF 335740 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 336224 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request AC 336263 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 338480 INFO PortManager [main]: service started 338525 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 339602 INFO PortManager [main]: Starting service... 379109 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 384734 INFO BaseInstrumentService [main]: initializeInstrument() for 384736 INFO StreamingWorkhorseADCP-1827 [main]: Initializing Workhorse 1827 384746 INFO StreamingWorkhorseADCP-1827 [main]: Instrument has been pre-configured; ignore configuration attributes 388187 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request TE? 388279 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 388337 INFO StreamingWorkhorseADCP-1827 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 388339 INFO StreamingWorkhorseADCP-1827 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 388577 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request CF? 388626 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 388682 INFO StreamingWorkhorseADCP-1827 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 388684 INFO StreamingWorkhorseADCP-1827 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 388688 INFO StreamingWorkhorseADCP-1827 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 388689 INFO StreamingWorkhorseADCP-1827 [main]: initializeInstrument(): property char 3=1 388690 INFO StreamingWorkhorseADCP-1827 [main]: Start pinging 388936 INFO BaseInstrumentService [main]: done with initializeInstrument() for 392536 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request WD? 392586 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 393186 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request WF? 393234 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 393777 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request WN? 393826 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 394365 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request WP? 394454 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 394985 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request WS? 395024 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 395595 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request WV? 395640 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 396185 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request TE? 396223 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 396775 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request TP? 396814 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 397347 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request TS? 397396 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 397947 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request EA? 397996 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 398536 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request EB? 398586 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 399185 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request ED? 399224 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 399785 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request ES? 399824 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 400365 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request EX? 400404 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 400965 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request EZ? 401005 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 401534 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request CF? 401574 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 402115 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request RA? 402139 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 402836 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request RR? 402948 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 403978 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request RF? 404072 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 404625 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request CB? 404663 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 405205 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request PS0 405229 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 407143 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request PS3 407176 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 408416 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request RF 408460 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 408989 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: wrote request AC 409049 INFO StreamingWorkhorseADCP-1827 [main]: sendRequest: got echo response: 411447 INFO PortManager [main]: service started 411456 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 411629 INFO PortManager [main]: Service jar not specified for port /dev/ttySX8 411630 INFO PortManager [main]: Service jar not specified for port /dev/ttySX9 411631 INFO PortManager [main]: Service jar not specified for port /dev/ttySX10 411632 INFO PortManager [main]: Service jar not specified for port /dev/ttySX11 412110 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443826546474 412145 INFO PortManager [main]: Starting service... 414589 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443826548953 414922 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443826549286 423833 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443826558197 425014 INFO BaseInstrumentService [main]: initializeInstrument() for 426430 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443826560794 426495 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443826560858 426498 INFO BaseInstrumentService [main]: done with initializeInstrument() for 430076 INFO PolledInstrumentService [main]: prepareToRun(): sampleMasterId = -1 430195 INFO PolledInstrumentService [main]: id 1599 not slaved 430216 INFO PortManager [main]: service started 430228 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 430283 INFO PortManager [main]: Service jar not specified for port /dev/ttySA2 430316 INFO CommsSchedulerTask [Thread-70]: CommsSchedulerTask.run(): Oct 2, 2015 10:56:04 PM 431060 INFO ThreadUtility [Thread-73]: ThreadGroup "main" includes about 36 threads 431256 INFO CommsLeaseListener [Thread-70]: Calling SyncProcessRunner("echo ethOn"); attempt #0 431293 INFO ThreadUtility [Thread-73]: thread: "RMI-ServerConnection:127.0.0.1" alive daemon thread: "Thread-73" alive 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 431859 INFO NodeManager [main]: NodeManager "node" started. 431927 INFO CommsLeaseListener [Thread-70]: ethOn 434377 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443826568741 436770 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443826571134 436840 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443826571203 442965 INFO CommsLeaseListener [Thread-77]: SyncProcessRunner.exec("echo ethOff"), connect time = 11 443028 INFO CommsLeaseListener [Thread-77]: 445195 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443826579559 447612 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443826581976 447713 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443826582077 455982 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443826590346 458458 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443826592822 458541 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443826592904 466815 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443826601178 469251 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443826603615 469325 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443826603689 477622 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443826611986 480019 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443826614383 480131 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443826614495 488419 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443826622783 490812 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443826625176 490881 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443826625245 499263 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443826633627 501670 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443826636034 501746 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443826636110 509988 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443826644351 512403 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443826646767 512570 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443826646934 519997 INFO WetLabsECO [Thread-26]: TEST: ID=1535, trigger sample at 1443826654360 522412 INFO WetLabsECO [Thread-26]: TEST: ID=1535, got complete sample at + 1443826656776 522601 INFO AanderaaO2 [Thread-39]: TT: ID=1524, trigger sample at 1443826656965