1 INFO NodeMain [main]: NodeMain.main(): portalHost = null 1376 INFO NodeManager [main]: Starting NodeManager at 02/18/2016 21:00:32 GMT (1455829232692), version: $Name: $ 1378 INFO NodeManager [main]: Node IP: node2/134.89.36.2 2037 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 5199 INFO MOOSPortManager [main]: Found DPA at slot: 1 6819 INFO MOOSPortManager [main]: Found DPA at slot: 2 8409 INFO MOOSPortManager [main]: Found DPA at slot: 3 9997 INFO MOOSPortManager [main]: No DPA found at slot: 4 10001 INFO MOOSPortManager [main]: No DPA found at slot: 5 10036 INFO WDTManager [main]: WDTManager constructor 10037 INFO MOOSWDTManager [main]: MOOSWDTManager constructor 10101 INFO SleepManager [main]: SleepManager constructor 10105 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 13796 ERROR NodeService [main]: Turbinator constructor failed: com.rbnb.sapi.SAPIException: Nesting java.net.ConnectException 13895 INFO MOOSNodeService [main]: Last MMC reset code: 6 14105 INFO NodeManager [main]: Starting registry... 14145 INFO NodeManager [main]: registry started. 14146 INFO NodeManager [main]: binding NodeService to rmi://localhost/node 14694 INFO NodeManager [main]: NodeService is bound to rmi://localhost/node 14823 INFO MOOSWDTManager [Thread-2]: WDTManager initializing watchdog timer 14878 ERROR MOOSWDTManager [Thread-2]: !!! Reboot due to watchdog reset !!! 15054 INFO PortManager [main]: Service jar not specified for port /dev/ttySX0 15985 INFO PortManager [main]: Starting service... 16723 INFO MOOSWDTManager [Thread-2]: WDTManager watchdog initialization successful 31944 INFO BaseInstrumentService [main]: initializeInstrument() for 33519 WARN StreamingSBE19 [main]: Did not receive expected confirmation prompt from command: MP 38554 INFO StreamingSBE19 [main]: Event detection disabled by attribute 38617 INFO BaseInstrumentService [main]: done with initializeInstrument() for 46653 INFO PortManager [main]: service started 46845 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 47503 INFO PortManager [main]: Starting service... 68213 INFO BaseInstrumentService [main]: initializeInstrument() for 68215 INFO WetLabsECO [main]: Initializing... 83588 INFO WetLabsECO [main]: Initializing completed 83589 INFO BaseInstrumentService [main]: done with initializeInstrument() for 92563 INFO PolledInstrumentService [main]: prepareToRun(): sampleMasterId = 1827 92566 INFO PolledInstrumentService [main]: id 1535 slaved to 1827 92583 INFO PortManager [main]: service started 92590 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 92670 INFO WetLabsECO [Thread-24]: TEST: ID=1535, trigger sample at 1455829323993 94732 INFO PortManager [main]: Starting service... 95043 INFO WetLabsECO [Thread-24]: TEST: ID=1535, got complete sample at + 1455829326367 154813 INFO BaseInstrumentService [main]: initializeInstrument() for 156828 INFO Aquadopp [main]: sendSoftBreak(): instrument in mode 5 163028 INFO BaseInstrumentService [main]: done with initializeInstrument() for 163219 INFO Aquadopp [main]: Retrieving Aquadopp state Metadata... 165239 INFO Aquadopp [main]: sendSoftBreak(): instrument in mode 2 172928 INFO PortManager [main]: service started 172935 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 173344 INFO PortManager [main]: Starting service... 175047 INFO Aquadopp [Thread-34]: sendSoftBreak(): instrument in mode 2 184424 INFO BaseInstrumentService [main]: initializeInstrument() for 189267 INFO BaseInstrumentService [main]: done with initializeInstrument() for 191181 INFO PolledInstrumentService [main]: prepareToRun(): sampleMasterId = 1535 191182 INFO PolledInstrumentService [main]: id 1524 slaved to 1535 191292 INFO PortManager [main]: service started 191295 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 191314 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1455829422637 192379 INFO PortManager [main]: Starting service... 227290 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 232934 INFO BaseInstrumentService [main]: initializeInstrument() for 232937 INFO StreamingWorkhorseADCP-1825 [main]: Initializing Workhorse 1825 232947 INFO StreamingWorkhorseADCP-1825 [main]: Instrument has been pre-configured; ignore configuration attributes 235825 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TE? 235863 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 235907 INFO StreamingWorkhorseADCP-1825 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 235908 INFO StreamingWorkhorseADCP-1825 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 236194 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CF? 236233 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 236277 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 236278 INFO StreamingWorkhorseADCP-1825 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 236280 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 236282 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): property char 3=1 236283 INFO StreamingWorkhorseADCP-1825 [main]: Start pinging 236472 INFO BaseInstrumentService [main]: done with initializeInstrument() for 239385 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WD? 239423 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 239964 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WF? 240004 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 240535 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WN? 240612 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 241145 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WP? 241183 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 241714 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WS? 241753 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 242274 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WV? 242313 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 242844 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TE? 242883 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 243484 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TP? 243523 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 244055 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TS? 244093 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 244624 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EA? 244663 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 245217 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EB? 245263 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 245795 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request ED? 245837 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 246375 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request ES? 246414 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 246944 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EX? 246983 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 247524 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EZ? 247563 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 248095 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CF? 248133 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 248764 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RA? 248789 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 249309 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RR? 249381 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 251615 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RF? 251651 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 252195 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CB? 252233 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 252784 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request PS0 252809 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 254744 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request PS3 254768 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 256074 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RF 256110 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 256603 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request AC 256644 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 258952 INFO PortManager [main]: service started 258975 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 260161 INFO PortManager [main]: Starting service... 296499 ERROR DeviceLog [main]: readLogData: data size mismatch 296500 ERROR DeviceLog [main]: bytes read from file: 1173 296501 ERROR DeviceLog [main]: dataSize in index entry: 1927 296609 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 307274 INFO BaseInstrumentService [main]: initializeInstrument() for 307277 INFO StreamingWorkhorseADCP-1828 [main]: Initializing Workhorse 1828 307289 INFO StreamingWorkhorseADCP-1828 [main]: Instrument has been pre-configured; ignore configuration attributes 310005 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TE? 310044 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 310132 INFO StreamingWorkhorseADCP-1828 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 310133 INFO StreamingWorkhorseADCP-1828 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 310374 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CF? 310413 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 310459 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 310460 INFO StreamingWorkhorseADCP-1828 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 310462 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 310463 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): property char 3=1 310465 INFO StreamingWorkhorseADCP-1828 [main]: Start pinging 310652 INFO BaseInstrumentService [main]: done with initializeInstrument() for 313424 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WD? 313463 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 314004 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WF? 314043 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 314615 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WN? 314653 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 315184 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WP? 315226 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 315765 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WS? 315803 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 316375 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WV? 316413 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 316965 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TE? 317009 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 317546 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TP? 317593 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 318156 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TS? 318204 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 318756 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EA? 318807 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 319354 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EB? 319398 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 319935 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request ED? 319973 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 320505 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request ES? 320544 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 321074 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EX? 321162 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 321704 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EZ? 321744 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 322315 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CF? 322353 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 322894 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RA? 322919 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 323434 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RR? 323474 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 325414 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RF? 325492 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 326045 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CB? 326083 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 326654 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request PS0 326678 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 328607 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request PS3 328637 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 329954 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RF 329990 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 330474 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request AC 330513 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: