1 INFO NodeMain [main]: NodeMain.main(): portalHost = null 1394 INFO NodeManager [main]: Starting NodeManager at 02/18/2016 22:21:05 GMT (1455834065730), version: $Name: $ 1396 INFO NodeManager [main]: Node IP: node2/134.89.36.2 2047 INFO NodeManager [main]: Node ID has been set to 1598 3255 INFO PortManager [main]: Found 14 configured ports in properties file 3318 INFO MOOSPortManager [main]: Found DPA at slot: 0 5199 INFO MOOSPortManager [main]: Found DPA at slot: 1 6829 INFO MOOSPortManager [main]: Found DPA at slot: 2 8419 INFO MOOSPortManager [main]: Found DPA at slot: 3 10007 INFO MOOSPortManager [main]: No DPA found at slot: 4 10011 INFO MOOSPortManager [main]: No DPA found at slot: 5 10046 INFO WDTManager [main]: WDTManager constructor 10047 INFO MOOSWDTManager [main]: MOOSWDTManager constructor 10111 INFO SleepManager [main]: SleepManager constructor 10114 INFO MOOSSleepManager [main]: MOOSSleepManager constructor 11805 INFO CommsLeaseListener [main]: CommsLeaseListener() complete 11871 INFO CpuLeaseSleepRollcallListener [main]: CpuLeaseSleepRollcallListener() constructor 11886 INFO CpuLease [main]: CpuLease() constructor 11888 INFO CpuLease [Thread-5]: CpuLease started 13836 ERROR NodeService [main]: Turbinator constructor failed: com.rbnb.sapi.SAPIException: Nesting java.net.ConnectException 13935 INFO MOOSNodeService [main]: Last MMC reset code: 6 14155 INFO NodeManager [main]: Starting registry... 14196 INFO NodeManager [main]: registry started. 14197 INFO NodeManager [main]: binding NodeService to rmi://localhost/node 14745 INFO NodeManager [main]: NodeService is bound to rmi://localhost/node 14770 INFO MOOSWDTManager [Thread-2]: WDTManager initializing watchdog timer 14839 ERROR MOOSWDTManager [Thread-2]: !!! Reboot due to watchdog reset !!! 15070 INFO PortManager [main]: Service jar not specified for port /dev/ttySX0 15952 INFO PortManager [main]: Starting service... 16771 INFO MOOSWDTManager [Thread-2]: WDTManager watchdog initialization successful 32175 INFO BaseInstrumentService [main]: initializeInstrument() for 33749 WARN StreamingSBE19 [main]: Did not receive expected confirmation prompt from command: MP 38784 INFO StreamingSBE19 [main]: Event detection disabled by attribute 38854 INFO BaseInstrumentService [main]: done with initializeInstrument() for 47104 INFO PortManager [main]: service started 47565 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 48309 INFO PortManager [main]: Starting service... 68987 INFO BaseInstrumentService [main]: initializeInstrument() for 68989 INFO WetLabsECO [main]: Initializing... 84358 INFO WetLabsECO [main]: Initializing completed 84359 INFO BaseInstrumentService [main]: done with initializeInstrument() for 93475 INFO PolledInstrumentService [main]: prepareToRun(): sampleMasterId = 1827 93478 INFO PolledInstrumentService [main]: id 1535 slaved to 1827 93497 INFO PortManager [main]: service started 93515 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 93551 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1455834157894 95577 INFO PortManager [main]: Starting service... 95923 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1455834160267 156640 INFO BaseInstrumentService [main]: initializeInstrument() for 158618 INFO Aquadopp [main]: sendSoftBreak(): instrument in mode 5 164858 INFO BaseInstrumentService [main]: done with initializeInstrument() for 164962 INFO Aquadopp [main]: Retrieving Aquadopp state Metadata... 166937 INFO Aquadopp [main]: sendSoftBreak(): instrument in mode 2 174768 INFO PortManager [main]: service started 174775 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 175216 INFO PortManager [main]: Starting service... 176947 INFO Aquadopp [Thread-34]: sendSoftBreak(): instrument in mode 2 186385 INFO BaseInstrumentService [main]: initializeInstrument() for 191307 INFO BaseInstrumentService [main]: done with initializeInstrument() for 193246 INFO PolledInstrumentService [main]: prepareToRun(): sampleMasterId = 1535 193247 INFO PolledInstrumentService [main]: id 1524 slaved to 1535 193253 INFO PortManager [main]: service started 193254 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 193275 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1455834257617 194390 INFO PortManager [main]: Starting service... 229105 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 234734 INFO BaseInstrumentService [main]: initializeInstrument() for 234737 INFO StreamingWorkhorseADCP-1825 [main]: Initializing Workhorse 1825 234747 INFO StreamingWorkhorseADCP-1825 [main]: Instrument has been pre-configured; ignore configuration attributes 237585 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TE? 237624 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 237668 INFO StreamingWorkhorseADCP-1825 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 237669 INFO StreamingWorkhorseADCP-1825 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 237904 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CF? 237943 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 237988 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 237989 INFO StreamingWorkhorseADCP-1825 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 237991 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 237993 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): property char 3=1 238043 INFO StreamingWorkhorseADCP-1825 [main]: Start pinging 238232 INFO BaseInstrumentService [main]: done with initializeInstrument() for 241254 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WD? 241290 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 241835 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WF? 241873 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 242425 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WN? 242463 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 242995 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WP? 243034 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 243564 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WS? 243609 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 244136 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WV? 244183 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 244725 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TE? 244763 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 245305 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TP? 245343 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 245925 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TS? 245968 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 246505 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EA? 246541 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 247074 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EB? 247113 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 247674 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request ED? 247713 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 248284 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request ES? 248323 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 248854 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EX? 248893 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 249435 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EZ? 249473 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 250004 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CF? 250043 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 250585 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RA? 250661 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 251174 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RR? 251213 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 254954 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RF? 254988 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 255534 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CB? 255574 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 256114 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request PS0 256142 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 258042 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request PS3 258075 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 259324 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RF 259360 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 259844 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request AC 259883 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 262111 INFO PortManager [main]: service started 262115 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 263171 INFO PortManager [main]: Starting service... 300170 ERROR DeviceLog [main]: readLogData: data size mismatch 300172 ERROR DeviceLog [main]: bytes read from file: 1173 300173 ERROR DeviceLog [main]: dataSize in index entry: 1927 300288 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 310946 INFO BaseInstrumentService [main]: initializeInstrument() for 310949 INFO StreamingWorkhorseADCP-1828 [main]: Initializing Workhorse 1828 310961 INFO StreamingWorkhorseADCP-1828 [main]: Instrument has been pre-configured; ignore configuration attributes 313605 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TE? 313645 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 313733 INFO StreamingWorkhorseADCP-1828 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 313735 INFO StreamingWorkhorseADCP-1828 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 313975 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CF? 314013 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 314059 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 314060 INFO StreamingWorkhorseADCP-1828 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 314062 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 314063 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): property char 3=1 314065 INFO StreamingWorkhorseADCP-1828 [main]: Start pinging 314253 INFO BaseInstrumentService [main]: done with initializeInstrument() for 317015 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WD? 317053 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 317594 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WF? 317634 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 318204 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WN? 318244 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 318774 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WP? 318813 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 319344 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WS? 319385 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 319905 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WV? 319943 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 320485 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TE? 320578 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 321127 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TP? 321178 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 321746 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TS? 321793 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 322346 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EA? 322393 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 322984 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EB? 323028 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 323555 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request ED? 323593 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 324124 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request ES? 324172 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 324715 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EX? 324802 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 325405 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EZ? 325444 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 326005 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CF? 326043 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 326584 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RA? 326609 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 327124 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RR? 327163 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 329734 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RF? 329771 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 330329 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CB? 330368 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 330946 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request PS0 330977 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 332895 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request PS3 332919 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 334197 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RF 334232 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 334715 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request AC 334753 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: