1 INFO NodeMain [main]: NodeMain.main(): portalHost = null 1377 INFO NodeManager [main]: Starting NodeManager at 02/18/2016 21:16:39 GMT (1455830199643), version: $Name: $ 1379 INFO NodeManager [main]: Node IP: node2/134.89.36.2 2029 INFO NodeManager [main]: Node ID has been set to 1598 3235 INFO PortManager [main]: Found 14 configured ports in properties file 3298 INFO MOOSPortManager [main]: Found DPA at slot: 0 5199 INFO MOOSPortManager [main]: Found DPA at slot: 1 6809 INFO MOOSPortManager [main]: Found DPA at slot: 2 8399 INFO MOOSPortManager [main]: Found DPA at slot: 3 9987 INFO MOOSPortManager [main]: No DPA found at slot: 4 9991 INFO MOOSPortManager [main]: No DPA found at slot: 5 10026 INFO WDTManager [main]: WDTManager constructor 10027 INFO MOOSWDTManager [main]: MOOSWDTManager constructor 10091 INFO SleepManager [main]: SleepManager constructor 10095 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 13806 ERROR NodeService [main]: Turbinator constructor failed: com.rbnb.sapi.SAPIException: Nesting java.net.ConnectException 13905 INFO MOOSNodeService [main]: Last MMC reset code: 6 14115 INFO NodeManager [main]: Starting registry... 14155 INFO NodeManager [main]: registry started. 14157 INFO NodeManager [main]: binding NodeService to rmi://localhost/node 14705 INFO NodeManager [main]: NodeService is bound to rmi://localhost/node 14730 INFO MOOSWDTManager [Thread-2]: WDTManager initializing watchdog timer 14799 ERROR MOOSWDTManager [Thread-2]: !!! Reboot due to watchdog reset !!! 15080 INFO PortManager [main]: Service jar not specified for port /dev/ttySX0 15933 INFO PortManager [main]: Starting service... 16255 INFO MOOSWDTManager [Thread-2]: WDTManager watchdog initialization successful 32135 INFO BaseInstrumentService [main]: initializeInstrument() for 33699 WARN StreamingSBE19 [main]: Did not receive expected confirmation prompt from command: MP 38724 INFO StreamingSBE19 [main]: Event detection disabled by attribute 38796 INFO BaseInstrumentService [main]: done with initializeInstrument() for 46884 INFO PortManager [main]: service started 47399 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 48229 INFO PortManager [main]: Starting service... 68745 INFO BaseInstrumentService [main]: initializeInstrument() for 68747 INFO WetLabsECO [main]: Initializing... 84108 INFO WetLabsECO [main]: Initializing completed 84109 INFO BaseInstrumentService [main]: done with initializeInstrument() for 93171 INFO PolledInstrumentService [main]: prepareToRun(): sampleMasterId = 1827 93174 INFO PolledInstrumentService [main]: id 1535 slaved to 1827 93193 INFO PortManager [main]: service started 93195 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 93217 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1455830291491 95229 INFO PortManager [main]: Starting service... 95592 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1455830293866 155482 INFO BaseInstrumentService [main]: initializeInstrument() for 157458 INFO Aquadopp [main]: sendSoftBreak(): instrument in mode 5 163695 INFO BaseInstrumentService [main]: done with initializeInstrument() for 163830 INFO Aquadopp [main]: Retrieving Aquadopp state Metadata... 165807 INFO Aquadopp [main]: sendSoftBreak(): instrument in mode 2 173512 INFO PortManager [main]: service started 173515 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 174016 INFO PortManager [main]: Starting service... 175647 INFO Aquadopp [Thread-34]: sendSoftBreak(): instrument in mode 2 184984 INFO BaseInstrumentService [main]: initializeInstrument() for 189807 INFO BaseInstrumentService [main]: done with initializeInstrument() for 191820 INFO PolledInstrumentService [main]: prepareToRun(): sampleMasterId = 1535 191821 INFO PolledInstrumentService [main]: id 1524 slaved to 1535 191828 INFO PortManager [main]: service started 191834 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 191847 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1455830390120 192896 INFO PortManager [main]: Starting service... 228433 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 234064 INFO BaseInstrumentService [main]: initializeInstrument() for 234067 INFO StreamingWorkhorseADCP-1825 [main]: Initializing Workhorse 1825 234077 INFO StreamingWorkhorseADCP-1825 [main]: Instrument has been pre-configured; ignore configuration attributes 236915 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TE? 237002 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 237092 INFO StreamingWorkhorseADCP-1825 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 237094 INFO StreamingWorkhorseADCP-1825 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 237334 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CF? 237373 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 237418 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 237419 INFO StreamingWorkhorseADCP-1825 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 237421 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 237422 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): property char 3=1 237423 INFO StreamingWorkhorseADCP-1825 [main]: Start pinging 237612 INFO BaseInstrumentService [main]: done with initializeInstrument() for 240504 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WD? 240543 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 241084 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WF? 241123 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 241745 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WN? 241783 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 242354 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WP? 242393 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 242924 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WS? 242963 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 243524 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WV? 243563 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 244104 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TE? 244144 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 244715 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TP? 244753 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 245284 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TS? 245323 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 245926 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EA? 245976 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 246554 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EB? 246593 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 247124 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request ED? 247163 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 247724 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request ES? 247764 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 248294 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EX? 248333 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 248875 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EZ? 248913 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 249454 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CF? 249493 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 250037 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RA? 250063 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 250575 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RR? 250662 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 253104 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RF? 253141 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 253684 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CB? 253723 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 254324 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request PS0 254349 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 256224 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request PS3 256249 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 257524 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RF 257560 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 258065 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request AC 258103 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 260321 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 260321 INFO PortManager [main]: service started 261800 INFO PortManager [main]: Starting service... 298927 ERROR DeviceLog [main]: readLogData: data size mismatch 298928 ERROR DeviceLog [main]: bytes read from file: 1173 298929 ERROR DeviceLog [main]: dataSize in index entry: 1927 299049 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 309707 INFO BaseInstrumentService [main]: initializeInstrument() for 309709 INFO StreamingWorkhorseADCP-1828 [main]: Initializing Workhorse 1828 309721 INFO StreamingWorkhorseADCP-1828 [main]: Instrument has been pre-configured; ignore configuration attributes 312354 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TE? 312394 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 312437 INFO StreamingWorkhorseADCP-1828 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 312439 INFO StreamingWorkhorseADCP-1828 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 312674 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CF? 312763 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 312809 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 312810 INFO StreamingWorkhorseADCP-1828 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 312812 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 312814 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): property char 3=1 312815 INFO StreamingWorkhorseADCP-1828 [main]: Start pinging 313002 INFO BaseInstrumentService [main]: done with initializeInstrument() for 315754 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WD? 315793 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 316334 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WF? 316422 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 316995 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WN? 317034 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 317564 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WP? 317604 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 318135 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WS? 318174 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 318724 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WV? 318763 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 319306 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TE? 319356 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 319906 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TP? 319962 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 320517 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TS? 320563 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 321225 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EA? 321270 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 321947 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EB? 321993 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 322525 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request ED? 322563 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 323115 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request ES? 323153 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 323684 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EX? 323724 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 324324 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EZ? 324363 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 324904 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CF? 324940 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 325484 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RA? 325509 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 326024 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RR? 326063 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 328124 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RF? 328165 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 328705 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CB? 328744 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 329306 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request PS0 329337 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 331214 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request PS3 331238 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 332525 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RF 332560 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 333045 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request AC 333083 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: