1 INFO NodeMain [main]: NodeMain.main(): portalHost = null 1354 INFO NodeManager [main]: Starting NodeManager at 02/18/2016 21:08:35 GMT (1455829715680), version: $Name: $ 1356 INFO NodeManager [main]: Node IP: node2/134.89.36.2 2021 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 5219 INFO MOOSPortManager [main]: Found DPA at slot: 1 6839 INFO MOOSPortManager [main]: Found DPA at slot: 2 8429 INFO MOOSPortManager [main]: Found DPA at slot: 3 10017 INFO MOOSPortManager [main]: No DPA found at slot: 4 10021 INFO MOOSPortManager [main]: No DPA found at slot: 5 10056 INFO WDTManager [main]: WDTManager constructor 10057 INFO MOOSWDTManager [main]: MOOSWDTManager constructor 10121 INFO SleepManager [main]: SleepManager constructor 10125 INFO MOOSSleepManager [main]: MOOSSleepManager constructor 11805 INFO CommsLeaseListener [main]: CommsLeaseListener() complete 11871 INFO CpuLeaseSleepRollcallListener [main]: CpuLeaseSleepRollcallListener() constructor 11886 INFO CpuLease [main]: CpuLease() constructor 11888 INFO CpuLease [Thread-5]: CpuLease started 13896 ERROR NodeService [main]: Turbinator constructor failed: com.rbnb.sapi.SAPIException: Nesting java.net.ConnectException 13993 INFO MOOSNodeService [main]: Last MMC reset code: 6 14205 INFO NodeManager [main]: Starting registry... 14243 INFO NodeManager [main]: registry started. 14244 INFO NodeManager [main]: binding NodeService to rmi://localhost/node 14825 INFO NodeManager [main]: NodeService is bound to rmi://localhost/node 14963 INFO MOOSWDTManager [Thread-2]: WDTManager initializing watchdog timer 14997 ERROR MOOSWDTManager [Thread-2]: !!! Reboot due to watchdog reset !!! 15148 INFO PortManager [main]: Service jar not specified for port /dev/ttySX0 15751 INFO MOOSWDTManager [Thread-2]: WDTManager watchdog initialization successful 16110 INFO PortManager [main]: Starting service... 32015 INFO BaseInstrumentService [main]: initializeInstrument() for 33579 WARN StreamingSBE19 [main]: Did not receive expected confirmation prompt from command: MP 38604 INFO StreamingSBE19 [main]: Event detection disabled by attribute 38723 INFO BaseInstrumentService [main]: done with initializeInstrument() for 46813 INFO PortManager [main]: service started 47375 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 48091 INFO PortManager [main]: Starting service... 68754 INFO BaseInstrumentService [main]: initializeInstrument() for 68756 INFO WetLabsECO [main]: Initializing... 84138 INFO WetLabsECO [main]: Initializing completed 84139 INFO BaseInstrumentService [main]: done with initializeInstrument() for 93281 INFO PolledInstrumentService [main]: prepareToRun(): sampleMasterId = 1827 93284 INFO PolledInstrumentService [main]: id 1535 slaved to 1827 93302 INFO PortManager [main]: service started 93304 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 93327 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1455829807660 95295 INFO PortManager [main]: Starting service... 95722 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1455829810056 155872 INFO BaseInstrumentService [main]: initializeInstrument() for 157848 INFO Aquadopp [main]: sendSoftBreak(): instrument in mode 5 164048 INFO BaseInstrumentService [main]: done with initializeInstrument() for 164208 INFO Aquadopp [main]: Retrieving Aquadopp state Metadata... 166187 INFO Aquadopp [main]: sendSoftBreak(): instrument in mode 2 173859 INFO PortManager [main]: service started 173865 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 174261 INFO PortManager [main]: Starting service... 175997 INFO Aquadopp [Thread-34]: sendSoftBreak(): instrument in mode 2 185104 INFO BaseInstrumentService [main]: initializeInstrument() for 189939 INFO BaseInstrumentService [main]: done with initializeInstrument() for 191882 INFO PolledInstrumentService [main]: prepareToRun(): sampleMasterId = 1535 191883 INFO PolledInstrumentService [main]: id 1524 slaved to 1535 191888 INFO PortManager [main]: service started 191954 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 192035 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1455829906365 193069 INFO PortManager [main]: Starting service... 227753 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 233384 INFO BaseInstrumentService [main]: initializeInstrument() for 233387 INFO StreamingWorkhorseADCP-1825 [main]: Initializing Workhorse 1825 233397 INFO StreamingWorkhorseADCP-1825 [main]: Instrument has been pre-configured; ignore configuration attributes 236245 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TE? 236284 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 236407 INFO StreamingWorkhorseADCP-1825 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 236409 INFO StreamingWorkhorseADCP-1825 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 236644 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CF? 236683 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 236728 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 236729 INFO StreamingWorkhorseADCP-1825 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 236731 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 236733 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): property char 3=1 236734 INFO StreamingWorkhorseADCP-1825 [main]: Start pinging 236922 INFO BaseInstrumentService [main]: done with initializeInstrument() for 239814 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WD? 239853 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 240394 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WF? 240433 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 241004 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WN? 241043 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 241575 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WP? 241613 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 242145 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WS? 242184 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 242705 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WV? 242743 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 243275 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TE? 243318 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 243854 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TP? 243893 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 244482 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TS? 244527 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 245055 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EA? 245093 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 245714 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EB? 245753 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 246284 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request ED? 246323 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 246854 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request ES? 246894 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 247425 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EX? 247463 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 248004 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EZ? 248043 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 248585 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CF? 248623 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 249214 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RA? 249239 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 249785 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RR? 249824 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 252185 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RF? 252225 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 252764 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CB? 252804 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 253395 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request PS0 253418 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 255294 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request PS3 255319 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 256595 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RF 256630 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 257155 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request AC 257190 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 259420 INFO PortManager [main]: service started 259425 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 260629 INFO PortManager [main]: Starting service... 296296 ERROR DeviceLog [main]: readLogData: data size mismatch 296297 ERROR DeviceLog [main]: bytes read from file: 1173 296298 ERROR DeviceLog [main]: dataSize in index entry: 1927 296418 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 307074 INFO BaseInstrumentService [main]: initializeInstrument() for 307076 INFO StreamingWorkhorseADCP-1828 [main]: Initializing Workhorse 1828 307087 INFO StreamingWorkhorseADCP-1828 [main]: Instrument has been pre-configured; ignore configuration attributes 309785 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TE? 309825 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 309868 INFO StreamingWorkhorseADCP-1828 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 309870 INFO StreamingWorkhorseADCP-1828 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 310104 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CF? 310144 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 310189 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 310190 INFO StreamingWorkhorseADCP-1828 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 310192 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 310194 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): property char 3=1 310243 INFO StreamingWorkhorseADCP-1828 [main]: Start pinging 310432 INFO BaseInstrumentService [main]: done with initializeInstrument() for 313315 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WD? 313402 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 313946 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WF? 314009 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 314554 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WN? 314593 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 315125 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WP? 315202 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 315734 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WS? 315773 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 316304 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WV? 316343 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 316875 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TE? 316914 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 317454 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TP? 317494 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 318036 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TS? 318083 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 318658 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EA? 318711 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 319247 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EB? 319298 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 319947 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request ED? 319992 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 320535 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request ES? 320573 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 321105 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EX? 321143 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 321685 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EZ? 321724 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 322255 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CF? 322293 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 322834 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RA? 322922 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 323465 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RR? 323504 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 325474 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RF? 325511 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 326083 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CB? 326171 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 326704 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request PS0 326729 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 328696 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request PS3 328727 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 330064 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RF 330101 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 330614 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request AC 330653 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: