1 INFO NodeMain [main]: NodeMain.main(): portalHost = null 1419 INFO NodeManager [main]: Starting NodeManager at 02/18/2016 22:37:12 GMT (1455835032745), version: $Name: $ 1421 INFO NodeManager [main]: Node IP: node2/134.89.36.2 2085 INFO NodeManager [main]: Node ID has been set to 1598 3295 INFO PortManager [main]: Found 14 configured ports in properties file 3358 INFO MOOSPortManager [main]: Found DPA at slot: 0 5239 INFO MOOSPortManager [main]: Found DPA at slot: 1 6849 INFO MOOSPortManager [main]: Found DPA at slot: 2 8439 INFO MOOSPortManager [main]: Found DPA at slot: 3 10027 INFO MOOSPortManager [main]: No DPA found at slot: 4 10031 INFO MOOSPortManager [main]: No DPA found at slot: 5 10066 INFO WDTManager [main]: WDTManager constructor 10068 INFO MOOSWDTManager [main]: MOOSWDTManager constructor 10131 INFO SleepManager [main]: SleepManager constructor 10135 INFO MOOSSleepManager [main]: MOOSSleepManager constructor 11815 INFO CommsLeaseListener [main]: CommsLeaseListener() complete 11881 INFO CpuLeaseSleepRollcallListener [main]: CpuLeaseSleepRollcallListener() constructor 11896 INFO CpuLease [main]: CpuLease() constructor 11904 INFO CpuLease [Thread-5]: CpuLease started 13846 ERROR NodeService [main]: Turbinator constructor failed: com.rbnb.sapi.SAPIException: Nesting java.net.ConnectException 13984 INFO MOOSNodeService [main]: Last MMC reset code: 6 14195 INFO NodeManager [main]: Starting registry... 14236 INFO NodeManager [main]: registry started. 14237 INFO NodeManager [main]: binding NodeService to rmi://localhost/node 14825 INFO NodeManager [main]: NodeService is bound to rmi://localhost/node 14953 INFO MOOSWDTManager [Thread-2]: WDTManager initializing watchdog timer 15008 ERROR MOOSWDTManager [Thread-2]: !!! Reboot due to watchdog reset !!! 15182 INFO PortManager [main]: Service jar not specified for port /dev/ttySX0 16103 INFO PortManager [main]: Starting service... 16873 INFO MOOSWDTManager [Thread-2]: WDTManager watchdog initialization successful 31995 INFO BaseInstrumentService [main]: initializeInstrument() for 33569 WARN StreamingSBE19 [main]: Did not receive expected confirmation prompt from command: MP 38594 INFO StreamingSBE19 [main]: Event detection disabled by attribute 38665 INFO BaseInstrumentService [main]: done with initializeInstrument() for 46752 INFO PortManager [main]: service started 47055 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 47696 INFO PortManager [main]: Starting service... 68265 INFO BaseInstrumentService [main]: initializeInstrument() for 68267 INFO WetLabsECO [main]: Initializing... 83630 INFO WetLabsECO [main]: Initializing completed 83631 INFO BaseInstrumentService [main]: done with initializeInstrument() for 92636 INFO PolledInstrumentService [main]: prepareToRun(): sampleMasterId = 1827 92637 INFO PolledInstrumentService [main]: id 1535 slaved to 1827 92696 INFO PortManager [main]: service started 92703 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 92759 INFO WetLabsECO [Thread-24]: TEST: ID=1535, trigger sample at 1455835124093 94906 INFO PortManager [main]: Starting service... 95134 INFO WetLabsECO [Thread-24]: TEST: ID=1535, got complete sample at + 1455835126468 155462 INFO BaseInstrumentService [main]: initializeInstrument() for 157438 INFO Aquadopp [main]: sendSoftBreak(): instrument in mode 5 163646 INFO BaseInstrumentService [main]: done with initializeInstrument() for 163732 INFO Aquadopp [main]: Retrieving Aquadopp state Metadata... 165707 INFO Aquadopp [main]: sendSoftBreak(): instrument in mode 2 173449 INFO PortManager [main]: service started 173455 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 173980 INFO PortManager [main]: Starting service... 175567 INFO Aquadopp [Thread-34]: sendSoftBreak(): instrument in mode 2 184884 INFO BaseInstrumentService [main]: initializeInstrument() for 189678 INFO BaseInstrumentService [main]: done with initializeInstrument() for 191621 INFO PolledInstrumentService [main]: prepareToRun(): sampleMasterId = 1535 191623 INFO PolledInstrumentService [main]: id 1524 slaved to 1535 191632 INFO PortManager [main]: service started 191635 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 191652 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1455835222986 192711 INFO PortManager [main]: Starting service... 227642 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 233274 INFO BaseInstrumentService [main]: initializeInstrument() for 233277 INFO StreamingWorkhorseADCP-1825 [main]: Initializing Workhorse 1825 233286 INFO StreamingWorkhorseADCP-1825 [main]: Instrument has been pre-configured; ignore configuration attributes 236084 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TE? 236123 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 236212 INFO StreamingWorkhorseADCP-1825 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 236213 INFO StreamingWorkhorseADCP-1825 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 236455 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CF? 236493 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 236538 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 236539 INFO StreamingWorkhorseADCP-1825 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 236541 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 236542 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): property char 3=1 236543 INFO StreamingWorkhorseADCP-1825 [main]: Start pinging 236732 INFO BaseInstrumentService [main]: done with initializeInstrument() for 239667 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WD? 239706 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 240244 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WF? 240283 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 240855 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WN? 240893 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 241424 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WP? 241463 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 242032 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WS? 242071 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 242595 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WV? 242633 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 243245 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TE? 243283 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 243825 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TP? 243863 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 244414 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TS? 244453 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 244984 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EA? 245023 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 245554 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EB? 245593 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 246134 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request ED? 246173 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 246704 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request ES? 246743 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 247275 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EX? 247361 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 247904 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EZ? 247943 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 248554 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CF? 248593 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 249134 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RA? 249159 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 249683 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RR? 249728 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 253641 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RF? 253686 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 254234 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CB? 254273 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 254814 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request PS0 254838 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 256705 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request PS3 256732 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 258025 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RF 258056 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 258545 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request AC 258582 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 260787 INFO PortManager [main]: service started 260794 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 262116 INFO PortManager [main]: Starting service... 299067 ERROR DeviceLog [main]: readLogData: data size mismatch 299069 ERROR DeviceLog [main]: bytes read from file: 1173 299070 ERROR DeviceLog [main]: dataSize in index entry: 1927 299140 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 309794 INFO BaseInstrumentService [main]: initializeInstrument() for 309797 INFO StreamingWorkhorseADCP-1828 [main]: Initializing Workhorse 1828 309807 INFO StreamingWorkhorseADCP-1828 [main]: Instrument has been pre-configured; ignore configuration attributes 312445 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TE? 312511 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 312556 INFO StreamingWorkhorseADCP-1828 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 312558 INFO StreamingWorkhorseADCP-1828 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 312794 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CF? 312833 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 312878 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 312880 INFO StreamingWorkhorseADCP-1828 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 312882 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 312883 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): property char 3=1 312884 INFO StreamingWorkhorseADCP-1828 [main]: Start pinging 313152 INFO BaseInstrumentService [main]: done with initializeInstrument() for 315914 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WD? 315954 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 316524 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WF? 316601 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 317134 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WN? 317174 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 317704 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WP? 317743 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 318274 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WS? 318313 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 318885 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WV? 318923 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 319516 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TE? 319564 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 320116 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TP? 320163 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 320766 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TS? 320818 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 321364 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EA? 321403 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 321934 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EB? 321974 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 322504 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request ED? 322543 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 323081 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request ES? 323136 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 323694 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EX? 323745 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 324275 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EZ? 324351 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 324915 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CF? 324954 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 325494 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RA? 325519 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 326034 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RR? 326074 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 328794 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RF? 328831 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 329376 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CB? 329423 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 330076 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request PS0 330180 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 331884 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request PS3 331912 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 333194 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RF 333230 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 333714 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request AC 333753 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: