1 INFO NodeMain [main]: NodeMain.main(): portalHost = null 1379 INFO NodeManager [main]: Starting NodeManager at 02/18/2016 20:28:15 GMT (1455827295705), version: $Name: $ 1381 INFO NodeManager [main]: Node IP: node2/134.89.36.2 2039 INFO NodeManager [main]: Node ID has been set to 1598 3245 INFO PortManager [main]: Found 14 configured ports in properties file 3308 INFO MOOSPortManager [main]: Found DPA at slot: 0 5189 INFO MOOSPortManager [main]: Found DPA at slot: 1 6799 INFO MOOSPortManager [main]: Found DPA at slot: 2 8389 INFO MOOSPortManager [main]: Found DPA at slot: 3 9977 INFO MOOSPortManager [main]: No DPA found at slot: 4 9981 INFO MOOSPortManager [main]: No DPA found at slot: 5 10016 INFO WDTManager [main]: WDTManager constructor 10017 INFO MOOSWDTManager [main]: MOOSWDTManager constructor 10081 INFO SleepManager [main]: SleepManager constructor 10085 INFO MOOSSleepManager [main]: MOOSSleepManager constructor 11765 INFO CommsLeaseListener [main]: CommsLeaseListener() complete 11831 INFO CpuLeaseSleepRollcallListener [main]: CpuLeaseSleepRollcallListener() constructor 11846 INFO CpuLease [main]: CpuLease() constructor 11849 INFO CpuLease [Thread-5]: CpuLease started 13776 ERROR NodeService [main]: Turbinator constructor failed: com.rbnb.sapi.SAPIException: Nesting java.net.ConnectException 13915 INFO MOOSNodeService [main]: Last MMC reset code: 6 14125 INFO NodeManager [main]: Starting registry... 14165 INFO NodeManager [main]: registry started. 14167 INFO NodeManager [main]: binding NodeService to rmi://localhost/node 14715 INFO NodeManager [main]: NodeService is bound to rmi://localhost/node 14843 INFO MOOSWDTManager [Thread-2]: WDTManager initializing watchdog timer 14898 ERROR MOOSWDTManager [Thread-2]: !!! Reboot due to watchdog reset !!! 15074 INFO PortManager [main]: Service jar not specified for port /dev/ttySX0 15950 INFO PortManager [main]: Starting service... 16709 INFO MOOSWDTManager [Thread-2]: WDTManager watchdog initialization successful 32155 INFO BaseInstrumentService [main]: initializeInstrument() for 33729 WARN StreamingSBE19 [main]: Did not receive expected confirmation prompt from command: MP 38764 INFO StreamingSBE19 [main]: Event detection disabled by attribute 38833 INFO BaseInstrumentService [main]: done with initializeInstrument() for 47073 INFO PortManager [main]: service started 47559 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 48268 INFO PortManager [main]: Starting service... 68634 INFO BaseInstrumentService [main]: initializeInstrument() for 68635 INFO WetLabsECO [main]: Initializing... 84058 INFO WetLabsECO [main]: Initializing completed 84059 INFO BaseInstrumentService [main]: done with initializeInstrument() for 93125 INFO PolledInstrumentService [main]: prepareToRun(): sampleMasterId = 1827 93127 INFO PolledInstrumentService [main]: id 1535 slaved to 1827 93145 INFO PortManager [main]: service started 93152 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 93174 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1455827387507 95209 INFO PortManager [main]: Starting service... 95630 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1455827389964 155789 INFO BaseInstrumentService [main]: initializeInstrument() for 157758 INFO Aquadopp [main]: sendSoftBreak(): instrument in mode 5 163958 INFO BaseInstrumentService [main]: done with initializeInstrument() for 164069 INFO Aquadopp [main]: Retrieving Aquadopp state Metadata... 166047 INFO Aquadopp [main]: sendSoftBreak(): instrument in mode 2 173857 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 173858 INFO PortManager [main]: service started 174285 INFO PortManager [main]: Starting service... 175957 INFO Aquadopp [Thread-34]: sendSoftBreak(): instrument in mode 2 185324 INFO BaseInstrumentService [main]: initializeInstrument() for 190137 INFO BaseInstrumentService [main]: done with initializeInstrument() for 192087 INFO PolledInstrumentService [main]: prepareToRun(): sampleMasterId = 1535 192088 INFO PolledInstrumentService [main]: id 1524 slaved to 1535 192095 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 192118 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1455827486452 192095 INFO PortManager [main]: service started 193249 INFO PortManager [main]: Starting service... 228843 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 234475 INFO BaseInstrumentService [main]: initializeInstrument() for 234477 INFO StreamingWorkhorseADCP-1825 [main]: Initializing Workhorse 1825 234487 INFO StreamingWorkhorseADCP-1825 [main]: Instrument has been pre-configured; ignore configuration attributes 237285 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TE? 237324 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 237367 INFO StreamingWorkhorseADCP-1825 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 237368 INFO StreamingWorkhorseADCP-1825 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 237604 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CF? 237643 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 237688 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 237689 INFO StreamingWorkhorseADCP-1825 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 237691 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 237692 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): property char 3=1 237693 INFO StreamingWorkhorseADCP-1825 [main]: Start pinging 237882 INFO BaseInstrumentService [main]: done with initializeInstrument() for 240915 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WD? 240953 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 241495 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WF? 241533 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 242064 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WN? 242109 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 242654 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WP? 242693 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 243224 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WS? 243318 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 243844 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WV? 243883 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 244415 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TE? 244453 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 244995 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TP? 245033 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 245595 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TS? 245639 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 246174 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EA? 246251 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 246784 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EB? 246823 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 247355 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request ED? 247393 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 247964 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request ES? 248003 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 248572 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EX? 248622 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 249155 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EZ? 249193 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 249734 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CF? 249773 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 250315 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RA? 250344 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 250854 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RR? 250893 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 252704 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RF? 252789 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 253334 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CB? 253373 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 253934 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request PS0 253958 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 255835 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request PS3 255858 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 257164 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RF 257200 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 257684 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request AC 257723 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 259926 INFO PortManager [main]: service started 259935 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 261000 INFO PortManager [main]: Starting service... 297648 ERROR DeviceLog [main]: readLogData: data size mismatch 297649 ERROR DeviceLog [main]: bytes read from file: 1173 297650 ERROR DeviceLog [main]: dataSize in index entry: 1927 297719 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 308376 INFO BaseInstrumentService [main]: initializeInstrument() for 308379 INFO StreamingWorkhorseADCP-1828 [main]: Initializing Workhorse 1828 308391 INFO StreamingWorkhorseADCP-1828 [main]: Instrument has been pre-configured; ignore configuration attributes 311035 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TE? 311075 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 311118 INFO StreamingWorkhorseADCP-1828 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 311119 INFO StreamingWorkhorseADCP-1828 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 311365 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CF? 311404 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 311449 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 311450 INFO StreamingWorkhorseADCP-1828 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 311452 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 311453 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): property char 3=1 311455 INFO StreamingWorkhorseADCP-1828 [main]: Start pinging 311642 INFO BaseInstrumentService [main]: done with initializeInstrument() for 314585 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WD? 314624 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 315165 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WF? 315204 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 315735 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WN? 315773 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 316304 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WP? 316381 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 316965 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WS? 317005 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 317544 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WV? 317580 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 318127 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TE? 318174 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 318726 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TP? 318773 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 319326 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TS? 319376 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 319926 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EA? 319973 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 320515 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EB? 320554 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 321085 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request ED? 321124 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 321664 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request ES? 321752 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 322285 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EX? 322324 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 322894 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EZ? 322933 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 323465 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CF? 323504 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 324075 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RA? 324120 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 324645 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RR? 324683 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 326294 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RF? 326332 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 326875 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CB? 326914 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 327545 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request PS0 327569 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 329446 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request PS3 329477 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 331124 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RF 331160 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 331644 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request AC 331683 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: