1 INFO NodeMain [main]: NodeMain.main(): portalHost = null 1376 INFO NodeManager [main]: Starting NodeManager at 02/18/2016 23:01:23 GMT (1455836483762), version: $Name: $ 1378 INFO NodeManager [main]: Node IP: node2/134.89.36.2 2036 INFO NodeManager [main]: Node ID has been set to 1598 3285 INFO PortManager [main]: Found 14 configured ports in properties file 3348 INFO MOOSPortManager [main]: Found DPA at slot: 0 5229 INFO MOOSPortManager [main]: Found DPA at slot: 1 6839 INFO MOOSPortManager [main]: Found DPA at slot: 2 8429 INFO MOOSPortManager [main]: Found DPA at slot: 3 10017 INFO MOOSPortManager [main]: No DPA found at slot: 4 10021 INFO MOOSPortManager [main]: No DPA found at slot: 5 10056 INFO WDTManager [main]: WDTManager constructor 10057 INFO MOOSWDTManager [main]: MOOSWDTManager constructor 10121 INFO SleepManager [main]: SleepManager constructor 10125 INFO MOOSSleepManager [main]: MOOSSleepManager constructor 11855 INFO CommsLeaseListener [main]: CommsLeaseListener() complete 11921 INFO CpuLeaseSleepRollcallListener [main]: CpuLeaseSleepRollcallListener() constructor 11936 INFO CpuLease [main]: CpuLease() constructor 11938 INFO CpuLease [Thread-5]: CpuLease started 13916 ERROR NodeService [main]: Turbinator constructor failed: com.rbnb.sapi.SAPIException: Nesting java.net.ConnectException 14056 INFO MOOSNodeService [main]: Last MMC reset code: 6 14275 INFO NodeManager [main]: Starting registry... 14313 INFO NodeManager [main]: registry started. 14314 INFO NodeManager [main]: binding NodeService to rmi://localhost/node 14915 INFO NodeManager [main]: NodeService is bound to rmi://localhost/node 14928 INFO MOOSWDTManager [Thread-2]: WDTManager initializing watchdog timer 15009 ERROR MOOSWDTManager [Thread-2]: !!! Reboot due to watchdog reset !!! 15305 INFO PortManager [main]: Service jar not specified for port /dev/ttySX0 16266 INFO PortManager [main]: Starting service... 16415 INFO MOOSWDTManager [Thread-2]: WDTManager watchdog initialization successful 32385 INFO BaseInstrumentService [main]: initializeInstrument() for 33959 WARN StreamingSBE19 [main]: Did not receive expected confirmation prompt from command: MP 38994 INFO StreamingSBE19 [main]: Event detection disabled by attribute 39056 INFO BaseInstrumentService [main]: done with initializeInstrument() for 47202 INFO PortManager [main]: service started 47585 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 48350 INFO PortManager [main]: Starting service... 68910 INFO BaseInstrumentService [main]: initializeInstrument() for 68912 INFO WetLabsECO [main]: Initializing... 84278 INFO WetLabsECO [main]: Initializing completed 84279 INFO BaseInstrumentService [main]: done with initializeInstrument() for 93438 INFO PolledInstrumentService [main]: prepareToRun(): sampleMasterId = 1827 93439 INFO PolledInstrumentService [main]: id 1535 slaved to 1827 93457 INFO PortManager [main]: service started 93464 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 93497 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1455836575891 95526 INFO PortManager [main]: Starting service... 95873 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1455836578267 156302 INFO BaseInstrumentService [main]: initializeInstrument() for 158278 INFO Aquadopp [main]: sendSoftBreak(): instrument in mode 5 164528 INFO BaseInstrumentService [main]: done with initializeInstrument() for 164631 INFO Aquadopp [main]: Retrieving Aquadopp state Metadata... 166607 INFO Aquadopp [main]: sendSoftBreak(): instrument in mode 2 174361 INFO PortManager [main]: service started 174365 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 174810 INFO PortManager [main]: Starting service... 176527 INFO Aquadopp [Thread-34]: sendSoftBreak(): instrument in mode 2 185664 INFO BaseInstrumentService [main]: initializeInstrument() for 190467 INFO BaseInstrumentService [main]: done with initializeInstrument() for 192438 INFO PolledInstrumentService [main]: prepareToRun(): sampleMasterId = 1535 192439 INFO PolledInstrumentService [main]: id 1524 slaved to 1535 192446 INFO PortManager [main]: service started 192454 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 192467 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1455836674860 193527 INFO PortManager [main]: Starting service... 228358 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 233984 INFO BaseInstrumentService [main]: initializeInstrument() for 233987 INFO StreamingWorkhorseADCP-1825 [main]: Initializing Workhorse 1825 233997 INFO StreamingWorkhorseADCP-1825 [main]: Instrument has been pre-configured; ignore configuration attributes 236865 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TE? 236904 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 236989 INFO StreamingWorkhorseADCP-1825 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 236990 INFO StreamingWorkhorseADCP-1825 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 237234 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CF? 237273 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 237318 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 237319 INFO StreamingWorkhorseADCP-1825 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 237321 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 237323 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): property char 3=1 237324 INFO StreamingWorkhorseADCP-1825 [main]: Start pinging 237512 INFO BaseInstrumentService [main]: done with initializeInstrument() for 240424 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WD? 240463 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 241004 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WF? 241043 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 241615 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WN? 241653 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 242186 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WP? 242227 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 242765 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WS? 242803 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 243325 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WV? 243363 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 243895 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TE? 243985 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 244525 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TP? 244563 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 245134 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TS? 245173 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 245704 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EA? 245743 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 246355 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EB? 246393 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 246925 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request ED? 246963 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 247494 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request ES? 247533 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 248064 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EX? 248103 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 248714 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EZ? 248753 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 249305 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CF? 249340 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 249885 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RA? 249909 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 250474 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RR? 250513 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 254845 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RF? 254879 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 255424 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CB? 255463 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 256064 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request PS0 256088 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 257965 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request PS3 257988 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 259274 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RF 259307 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 259814 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request AC 259849 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 262103 INFO PortManager [main]: service started 262105 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 263357 INFO PortManager [main]: Starting service... 300143 ERROR DeviceLog [main]: readLogData: data size mismatch 300147 ERROR DeviceLog [main]: bytes read from file: 1173 300148 ERROR DeviceLog [main]: dataSize in index entry: 1927 300222 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 310916 INFO BaseInstrumentService [main]: initializeInstrument() for 310919 INFO StreamingWorkhorseADCP-1828 [main]: Initializing Workhorse 1828 310931 INFO StreamingWorkhorseADCP-1828 [main]: Instrument has been pre-configured; ignore configuration attributes 313585 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TE? 313624 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 313668 INFO StreamingWorkhorseADCP-1828 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 313669 INFO StreamingWorkhorseADCP-1828 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 313915 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CF? 313953 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 313999 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 314001 INFO StreamingWorkhorseADCP-1828 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 314003 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 314005 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): property char 3=1 314006 INFO StreamingWorkhorseADCP-1828 [main]: Start pinging 314220 INFO BaseInstrumentService [main]: done with initializeInstrument() for 317135 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WD? 317173 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 317714 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WF? 317753 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 318285 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WN? 318323 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 318854 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WP? 318893 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 319515 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WS? 319554 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 320105 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WV? 320141 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 320696 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TE? 320746 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 321356 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TP? 321403 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 321946 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TS? 321993 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 322544 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EA? 322583 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 323115 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EB? 323153 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 323684 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request ED? 323723 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 324303 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request ES? 324346 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 324874 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EX? 324914 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 325464 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EZ? 325503 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 326034 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CF? 326073 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 326644 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RA? 326669 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 327184 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RR? 327224 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 330135 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RF? 330179 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 330827 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CB? 330874 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 331516 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request PS0 331547 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 333424 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request PS3 333448 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 334754 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RF 334790 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 335274 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request AC 335313 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: