0 INFO NodeMain [main]: NodeMain.main(): portalHost = null 1356 INFO NodeManager [main]: Starting NodeManager at 02/18/2016 23:25:48 GMT (1455837948777), version: $Name: $ 1358 INFO NodeManager [main]: Node IP: node2/134.89.36.2 2028 INFO NodeManager [main]: Node ID has been set to 1598 3234 INFO PortManager [main]: Found 14 configured ports in properties file 3298 INFO MOOSPortManager [main]: Found DPA at slot: 0 5178 INFO MOOSPortManager [main]: Found DPA at slot: 1 6788 INFO MOOSPortManager [main]: Found DPA at slot: 2 8378 INFO MOOSPortManager [main]: Found DPA at slot: 3 9966 INFO MOOSPortManager [main]: No DPA found at slot: 4 9970 INFO MOOSPortManager [main]: No DPA found at slot: 5 10005 INFO WDTManager [main]: WDTManager constructor 10007 INFO MOOSWDTManager [main]: MOOSWDTManager constructor 10070 INFO SleepManager [main]: SleepManager constructor 10074 INFO MOOSSleepManager [main]: MOOSSleepManager constructor 11754 INFO CommsLeaseListener [main]: CommsLeaseListener() complete 11820 INFO CpuLeaseSleepRollcallListener [main]: CpuLeaseSleepRollcallListener() constructor 11836 INFO CpuLease [main]: CpuLease() constructor 11838 INFO CpuLease [Thread-5]: CpuLease started 13865 ERROR NodeService [main]: Turbinator constructor failed: com.rbnb.sapi.SAPIException: Nesting java.net.ConnectException 13963 INFO MOOSNodeService [main]: Last MMC reset code: 6 14184 INFO NodeManager [main]: Starting registry... 14222 INFO NodeManager [main]: registry started. 14223 INFO NodeManager [main]: binding NodeService to rmi://localhost/node 14784 INFO NodeManager [main]: NodeService is bound to rmi://localhost/node 14924 INFO MOOSWDTManager [Thread-2]: WDTManager initializing watchdog timer 14978 ERROR MOOSWDTManager [Thread-2]: !!! Reboot due to watchdog reset !!! 15154 INFO PortManager [main]: Service jar not specified for port /dev/ttySX0 16049 INFO PortManager [main]: Starting service... 17368 INFO MOOSWDTManager [Thread-2]: WDTManager watchdog initialization successful 31984 INFO BaseInstrumentService [main]: initializeInstrument() for 33558 WARN StreamingSBE19 [main]: Did not receive expected confirmation prompt from command: MP 38594 INFO StreamingSBE19 [main]: Event detection disabled by attribute 38669 INFO BaseInstrumentService [main]: done with initializeInstrument() for 46751 INFO PortManager [main]: service started 47189 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 47849 INFO PortManager [main]: Starting service... 67953 INFO BaseInstrumentService [main]: initializeInstrument() for 67955 INFO WetLabsECO [main]: Initializing... 83407 INFO WetLabsECO [main]: Initializing completed 83408 INFO BaseInstrumentService [main]: done with initializeInstrument() for 92423 INFO PolledInstrumentService [main]: prepareToRun(): sampleMasterId = 1827 92425 INFO PolledInstrumentService [main]: id 1535 slaved to 1827 92443 INFO PortManager [main]: service started 92450 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 92540 INFO WetLabsECO [Thread-24]: TEST: ID=1535, trigger sample at 1455838039969 94684 INFO PortManager [main]: Starting service... 94914 INFO WetLabsECO [Thread-24]: TEST: ID=1535, got complete sample at + 1455838042342 155021 INFO BaseInstrumentService [main]: initializeInstrument() for 156997 INFO Aquadopp [main]: sendSoftBreak(): instrument in mode 5 163197 INFO BaseInstrumentService [main]: done with initializeInstrument() for 163309 INFO Aquadopp [main]: Retrieving Aquadopp state Metadata... 165286 INFO Aquadopp [main]: sendSoftBreak(): instrument in mode 2 173023 INFO PortManager [main]: service started 173024 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 173487 INFO PortManager [main]: Starting service... 175126 INFO Aquadopp [Thread-34]: sendSoftBreak(): instrument in mode 2 184903 INFO BaseInstrumentService [main]: initializeInstrument() for 189760 INFO BaseInstrumentService [main]: done with initializeInstrument() for 191710 INFO PolledInstrumentService [main]: prepareToRun(): sampleMasterId = 1535 191711 INFO PolledInstrumentService [main]: id 1524 slaved to 1535 191718 INFO PortManager [main]: service started 191725 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 191748 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1455838139176 192817 INFO PortManager [main]: Starting service... 227643 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 233273 INFO BaseInstrumentService [main]: initializeInstrument() for 233276 INFO StreamingWorkhorseADCP-1825 [main]: Initializing Workhorse 1825 233286 INFO StreamingWorkhorseADCP-1825 [main]: Instrument has been pre-configured; ignore configuration attributes 236094 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TE? 236133 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 236220 INFO StreamingWorkhorseADCP-1825 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 236221 INFO StreamingWorkhorseADCP-1825 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 236464 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CF? 236502 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 236547 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 236549 INFO StreamingWorkhorseADCP-1825 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 236551 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 236552 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): property char 3=1 236553 INFO StreamingWorkhorseADCP-1825 [main]: Start pinging 236741 INFO BaseInstrumentService [main]: done with initializeInstrument() for 239624 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WD? 239663 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 240204 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WF? 240242 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 240774 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WN? 240850 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 241384 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WP? 241422 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 241954 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WS? 242040 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 242564 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WV? 242602 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 243213 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TE? 243253 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 243794 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TP? 243832 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 244394 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TS? 244432 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 244963 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EA? 245002 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 245534 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EB? 245572 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 246104 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request ED? 246146 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 246684 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request ES? 246723 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 247253 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EX? 247340 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 247883 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EZ? 247922 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 248474 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CF? 248512 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 249074 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RA? 249098 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 249644 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RR? 249683 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 254424 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RF? 254457 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 255004 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CB? 255043 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 255584 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request PS0 255608 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 257483 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request PS3 257508 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 258824 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RF 258905 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 259394 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request AC 259432 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 261687 INFO PortManager [main]: service started 261694 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 262939 INFO PortManager [main]: Starting service... 299921 ERROR DeviceLog [main]: readLogData: data size mismatch 299922 ERROR DeviceLog [main]: bytes read from file: 1173 299924 ERROR DeviceLog [main]: dataSize in index entry: 1927 300018 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 310707 INFO BaseInstrumentService [main]: initializeInstrument() for 310710 INFO StreamingWorkhorseADCP-1828 [main]: Initializing Workhorse 1828 310722 INFO StreamingWorkhorseADCP-1828 [main]: Instrument has been pre-configured; ignore configuration attributes 313364 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TE? 313404 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 313447 INFO StreamingWorkhorseADCP-1828 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 313448 INFO StreamingWorkhorseADCP-1828 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 313684 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CF? 313723 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 313825 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 313826 INFO StreamingWorkhorseADCP-1828 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 313828 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 313829 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): property char 3=1 313831 INFO StreamingWorkhorseADCP-1828 [main]: Start pinging 314011 INFO BaseInstrumentService [main]: done with initializeInstrument() for 316784 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WD? 316823 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 317364 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WF? 317402 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 317934 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WN? 318012 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 318544 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WP? 318582 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 319114 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WS? 319153 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 319724 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WV? 319771 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 320336 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TE? 320383 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 320946 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TP? 320992 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 321585 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TS? 321632 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 322174 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EA? 322212 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 322744 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EB? 322782 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 323314 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request ED? 323353 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 323894 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request ES? 323933 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 324502 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EX? 324552 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 325084 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EZ? 325162 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 325724 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CF? 325771 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 326314 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RA? 326338 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 326854 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RR? 326893 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 330014 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RF? 330048 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 330605 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CB? 330653 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 331226 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request PS0 331256 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 333124 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request PS3 333148 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 334424 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RF 334456 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 334944 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request AC 334982 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: