1 INFO NodeMain [main]: NodeMain.main(): portalHost = null 1355 INFO NodeManager [main]: Starting NodeManager at 02/18/2016 23:33:52 GMT (1455838432951), version: $Name: $ 1357 INFO NodeManager [main]: Node IP: node2/134.89.36.2 2025 INFO NodeManager [main]: Node ID has been set to 1598 3275 INFO PortManager [main]: Found 14 configured ports in properties file 3338 INFO MOOSPortManager [main]: Found DPA at slot: 0 5219 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 10010 INFO MOOSPortManager [main]: No DPA found at slot: 5 10059 INFO WDTManager [main]: WDTManager constructor 10060 INFO MOOSWDTManager [main]: MOOSWDTManager constructor 10124 INFO SleepManager [main]: SleepManager constructor 10127 INFO MOOSSleepManager [main]: MOOSSleepManager constructor 12195 INFO CommsLeaseListener [main]: CommsLeaseListener() complete 12261 INFO CpuLeaseSleepRollcallListener [main]: CpuLeaseSleepRollcallListener() constructor 12276 INFO CpuLease [main]: CpuLease() constructor 12285 INFO CpuLease [Thread-5]: CpuLease started 14196 ERROR NodeService [main]: Turbinator constructor failed: com.rbnb.sapi.SAPIException: Nesting java.net.ConnectException 14295 INFO MOOSNodeService [main]: Last MMC reset code: 6 14598 INFO NodeManager [main]: Starting registry... 14636 INFO NodeManager [main]: registry started. 14637 INFO NodeManager [main]: binding NodeService to rmi://localhost/node 15135 INFO NodeManager [main]: NodeService is bound to rmi://localhost/node 15272 INFO MOOSWDTManager [Thread-2]: WDTManager initializing watchdog timer 15306 ERROR MOOSWDTManager [Thread-2]: !!! Reboot due to watchdog reset !!! 15458 INFO PortManager [main]: Service jar not specified for port /dev/ttySX0 16407 INFO PortManager [main]: Starting service... 16665 INFO MOOSWDTManager [Thread-2]: WDTManager watchdog initialization successful 32254 INFO BaseInstrumentService [main]: initializeInstrument() for 33829 WARN StreamingSBE19 [main]: Did not receive expected confirmation prompt from command: MP 38854 INFO StreamingSBE19 [main]: Event detection disabled by attribute 38925 INFO BaseInstrumentService [main]: done with initializeInstrument() for 47123 INFO PortManager [main]: service started 47524 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 48897 INFO PortManager [main]: Starting service... 68654 INFO BaseInstrumentService [main]: initializeInstrument() for 68656 INFO WetLabsECO [main]: Initializing... 84110 INFO WetLabsECO [main]: Initializing completed 84111 INFO BaseInstrumentService [main]: done with initializeInstrument() for 93166 INFO PolledInstrumentService [main]: prepareToRun(): sampleMasterId = 1827 93168 INFO PolledInstrumentService [main]: id 1535 slaved to 1827 93188 INFO PortManager [main]: service started 93194 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 93209 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1455838524813 95267 INFO PortManager [main]: Starting service... 95582 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1455838527186 155811 INFO BaseInstrumentService [main]: initializeInstrument() for 157778 INFO Aquadopp [main]: sendSoftBreak(): instrument in mode 5 164028 INFO BaseInstrumentService [main]: done with initializeInstrument() for 164143 INFO Aquadopp [main]: Retrieving Aquadopp state Metadata... 166129 INFO Aquadopp [main]: sendSoftBreak(): instrument in mode 2 173929 INFO PortManager [main]: service started 173984 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 174438 INFO PortManager [main]: Starting service... 176107 INFO Aquadopp [Thread-34]: sendSoftBreak(): instrument in mode 2 185525 INFO BaseInstrumentService [main]: initializeInstrument() for 190397 INFO BaseInstrumentService [main]: done with initializeInstrument() for 192378 INFO PolledInstrumentService [main]: prepareToRun(): sampleMasterId = 1535 192380 INFO PolledInstrumentService [main]: id 1524 slaved to 1535 192386 INFO PortManager [main]: service started 192395 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 192438 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1455838624041 193520 INFO PortManager [main]: Starting service... 228410 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 234077 INFO BaseInstrumentService [main]: initializeInstrument() for 234079 INFO StreamingWorkhorseADCP-1825 [main]: Initializing Workhorse 1825 234090 INFO StreamingWorkhorseADCP-1825 [main]: Instrument has been pre-configured; ignore configuration attributes 236885 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TE? 236924 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 236967 INFO StreamingWorkhorseADCP-1825 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 236969 INFO StreamingWorkhorseADCP-1825 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 237205 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CF? 237243 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 237288 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 237289 INFO StreamingWorkhorseADCP-1825 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 237291 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 237293 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): property char 3=1 237294 INFO StreamingWorkhorseADCP-1825 [main]: Start pinging 237482 INFO BaseInstrumentService [main]: done with initializeInstrument() for 240525 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WD? 240563 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 241104 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WF? 241143 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 241674 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WN? 241713 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 242244 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WP? 242283 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 242892 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WS? 242931 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 243454 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WV? 243493 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 244057 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TE? 244127 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 244674 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TP? 244713 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 245254 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TS? 245294 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 245834 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EA? 245873 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 246414 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EB? 246453 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 246984 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request ED? 247111 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 247644 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request ES? 247683 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 248274 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EX? 248313 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 248854 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EZ? 248893 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: 250034 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RA? 250072 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 250584 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RR? 250623 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 255564 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RF? 255595 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 256146 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CB? 256187 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 256734 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request PS0 256758 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 258662 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request PS3 258693 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 259934 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RF 259966 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 260454 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request AC 260492 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 262884 INFO PortManager [main]: service started 262893 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 264193 INFO PortManager [main]: Starting service... 301325 ERROR DeviceLog [main]: readLogData: data size mismatch 301326 ERROR DeviceLog [main]: bytes read from file: 1173 301327 ERROR DeviceLog [main]: dataSize in index entry: 1927 301399 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 312057 INFO BaseInstrumentService [main]: initializeInstrument() for 312059 INFO StreamingWorkhorseADCP-1828 [main]: Initializing Workhorse 1828 312071 INFO StreamingWorkhorseADCP-1828 [main]: Instrument has been pre-configured; ignore configuration attributes 314705 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TE? 314761 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 314806 INFO StreamingWorkhorseADCP-1828 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 314807 INFO StreamingWorkhorseADCP-1828 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 315064 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CF? 315103 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 315149 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 315150 INFO StreamingWorkhorseADCP-1828 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 315152 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 315153 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): property char 3=1 315155 INFO StreamingWorkhorseADCP-1828 [main]: Start pinging 315342 INFO BaseInstrumentService [main]: done with initializeInstrument() for 318146 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WD? 318190 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 318735 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WF? 318810 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 319344 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WN? 319384 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 319914 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WP? 319953 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 320484 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WS? 320523 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 321055 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WV? 321093 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 321636 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TE? 321686 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 322257 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TP? 322311 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 322846 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TS? 322894 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 323455 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EA? 323530 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 324072 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EB? 324149 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 324684 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request ED? 324723 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 325255 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request ES? 325293 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 325824 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EX? 325864 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 326415 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EZ? 326502 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 327034 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CF? 327073 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 327634 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RA? 327659 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 328174 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RR? 328213 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 331426 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RF? 331468 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 332026 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CB? 332076 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 332646 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request PS0 332677 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 334595 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request PS3 334619 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 335924 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RF 335960 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 336444 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request AC 336483 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: