1 INFO NodeMain [main]: NodeMain.main(): portalHost = null 1352 INFO NodeManager [main]: Starting NodeManager at 02/19/2016 00:14:51 GMT (1455840891723), version: $Name: $ 1354 INFO NodeManager [main]: Node IP: node2/134.89.36.2 2010 INFO NodeManager [main]: Node ID has been set to 1598 3220 INFO PortManager [main]: Found 14 configured ports in properties file 3283 INFO MOOSPortManager [main]: Found DPA at slot: 0 5164 INFO MOOSPortManager [main]: Found DPA at slot: 1 6774 INFO MOOSPortManager [main]: Found DPA at slot: 2 8364 INFO MOOSPortManager [main]: Found DPA at slot: 3 9952 INFO MOOSPortManager [main]: No DPA found at slot: 4 9956 INFO MOOSPortManager [main]: No DPA found at slot: 5 9991 INFO WDTManager [main]: WDTManager constructor 9992 INFO MOOSWDTManager [main]: MOOSWDTManager constructor 10087 INFO SleepManager [main]: SleepManager constructor 10090 INFO MOOSSleepManager [main]: MOOSSleepManager constructor 12180 INFO CommsLeaseListener [main]: CommsLeaseListener() complete 12246 INFO CpuLeaseSleepRollcallListener [main]: CpuLeaseSleepRollcallListener() constructor 12261 INFO CpuLease [main]: CpuLease() constructor 12263 INFO CpuLease [Thread-5]: CpuLease started 14211 ERROR NodeService [main]: Turbinator constructor failed: com.rbnb.sapi.SAPIException: Nesting java.net.ConnectException 14310 INFO MOOSNodeService [main]: Last MMC reset code: 6 14530 INFO NodeManager [main]: Starting registry... 14568 INFO NodeManager [main]: registry started. 14569 INFO NodeManager [main]: binding NodeService to rmi://localhost/node 15162 INFO NodeManager [main]: NodeService is bound to rmi://localhost/node 15288 INFO MOOSWDTManager [Thread-2]: WDTManager initializing watchdog timer 15362 ERROR MOOSWDTManager [Thread-2]: !!! Reboot due to watchdog reset !!! 15625 INFO PortManager [main]: Service jar not specified for port /dev/ttySX0 15690 INFO MOOSWDTManager [Thread-2]: WDTManager watchdog initialization successful 16515 INFO PortManager [main]: Starting service... 32499 INFO BaseInstrumentService [main]: initializeInstrument() for 34104 WARN StreamingSBE19 [main]: Did not receive expected confirmation prompt from command: MP 39139 INFO StreamingSBE19 [main]: Event detection disabled by attribute 39250 INFO BaseInstrumentService [main]: done with initializeInstrument() for 47337 INFO PortManager [main]: service started 47824 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 49246 INFO PortManager [main]: Starting service... 68869 INFO BaseInstrumentService [main]: initializeInstrument() for 68871 INFO WetLabsECO [main]: Initializing... 84313 INFO WetLabsECO [main]: Initializing completed 84314 INFO BaseInstrumentService [main]: done with initializeInstrument() for 93372 INFO PolledInstrumentService [main]: prepareToRun(): sampleMasterId = 1827 93375 INFO PolledInstrumentService [main]: id 1535 slaved to 1827 93395 INFO PortManager [main]: service started 93400 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 93416 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1455840983794 95425 INFO PortManager [main]: Starting service... 95787 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1455840986166 157357 INFO BaseInstrumentService [main]: initializeInstrument() for 159333 INFO Aquadopp [main]: sendSoftBreak(): instrument in mode 5 165543 INFO BaseInstrumentService [main]: done with initializeInstrument() for 165657 INFO Aquadopp [main]: Retrieving Aquadopp state Metadata... 167652 INFO Aquadopp [main]: sendSoftBreak(): instrument in mode 2 175494 INFO PortManager [main]: service started 175500 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 175986 INFO PortManager [main]: Starting service... 177612 INFO Aquadopp [Thread-34]: sendSoftBreak(): instrument in mode 2 187089 INFO BaseInstrumentService [main]: initializeInstrument() for 188913 ERROR StreamingInstrumentService [Thread-34]: acquireFromStream(), tried startStreaming(): Instrument didn't acknowledge SR command 192022 INFO BaseInstrumentService [main]: done with initializeInstrument() for 193952 INFO PolledInstrumentService [main]: prepareToRun(): sampleMasterId = 1535 193954 INFO PolledInstrumentService [main]: id 1524 slaved to 1535 193960 INFO PortManager [main]: service started 193968 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 193988 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1455841084367 195050 INFO PortManager [main]: Starting service... 230693 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 236320 INFO BaseInstrumentService [main]: initializeInstrument() for 236322 INFO StreamingWorkhorseADCP-1825 [main]: Initializing Workhorse 1825 236332 INFO StreamingWorkhorseADCP-1825 [main]: Instrument has been pre-configured; ignore configuration attributes 239449 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TE? 239489 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 239532 INFO StreamingWorkhorseADCP-1825 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 239534 INFO StreamingWorkhorseADCP-1825 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 239769 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CF? 239808 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 239853 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 239854 INFO StreamingWorkhorseADCP-1825 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 239856 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 239858 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): property char 3=1 239859 INFO StreamingWorkhorseADCP-1825 [main]: Start pinging 240087 INFO BaseInstrumentService [main]: done with initializeInstrument() for 242990 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WD? 243028 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 243640 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WF? 243678 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 244219 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WN? 244255 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 244789 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WP? 244828 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 245400 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WS? 245438 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 245969 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WV? 246008 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 246549 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TE? 246588 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 247129 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TP? 247168 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 247699 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TS? 247738 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 248269 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EA? 248426 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 248960 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EB? 248998 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 249539 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request ED? 249578 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 250109 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request ES? 250148 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 250739 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EX? 250778 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 251320 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EZ? 251358 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 251889 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CF? 251928 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 252469 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RA? 252494 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 253009 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RR? 253048 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 258610 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RF? 258644 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 259189 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CB? 259228 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 259769 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request PS0 259793 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 261739 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request PS3 261763 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 263117 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RF 263165 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 263630 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request AC 263668 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 265870 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 265873 INFO PortManager [main]: service started 267113 INFO PortManager [main]: Starting service... 304167 ERROR DeviceLog [main]: readLogData: data size mismatch 304168 ERROR DeviceLog [main]: bytes read from file: 1173 304170 ERROR DeviceLog [main]: dataSize in index entry: 1927 304239 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 314969 INFO BaseInstrumentService [main]: initializeInstrument() for 314972 INFO StreamingWorkhorseADCP-1828 [main]: Initializing Workhorse 1828 314984 INFO StreamingWorkhorseADCP-1828 [main]: Instrument has been pre-configured; ignore configuration attributes 317640 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TE? 317679 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 317723 INFO StreamingWorkhorseADCP-1828 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 317724 INFO StreamingWorkhorseADCP-1828 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 317960 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CF? 317998 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 318044 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 318045 INFO StreamingWorkhorseADCP-1828 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 318047 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 318049 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): property char 3=1 318050 INFO StreamingWorkhorseADCP-1828 [main]: Start pinging 318301 INFO BaseInstrumentService [main]: done with initializeInstrument() for 321160 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WD? 321198 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 321789 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WF? 321828 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 322391 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WN? 322435 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 323009 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WP? 323048 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 323620 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WS? 323658 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 324189 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WV? 324229 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 324771 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TE? 324818 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 325371 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TP? 325418 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 326082 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TS? 326129 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 326699 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EA? 326738 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 327269 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EB? 327308 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 327869 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request ED? 327908 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 328442 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request ES? 328483 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 329020 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EX? 329058 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 329600 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EZ? 329639 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 330170 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CF? 330209 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 330749 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RA? 330774 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 331289 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RR? 331328 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 334831 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RF? 334873 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 335431 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CB? 335484 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 336042 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request PS0 336072 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 337933 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request PS3 337958 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 339229 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RF 339262 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 339789 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request AC 339828 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: