1 INFO NodeMain [main]: NodeMain.main(): portalHost = null 1355 INFO NodeManager [main]: Starting NodeManager at 02/18/2016 23:09:27 GMT (1455836967661), version: $Name: $ 1357 INFO NodeManager [main]: Node IP: node2/134.89.36.2 2024 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 5179 INFO MOOSPortManager [main]: Found DPA at slot: 1 6789 INFO MOOSPortManager [main]: Found DPA at slot: 2 8379 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 11745 INFO CommsLeaseListener [main]: CommsLeaseListener() complete 11811 INFO CpuLeaseSleepRollcallListener [main]: CpuLeaseSleepRollcallListener() constructor 11826 INFO CpuLease [main]: CpuLease() constructor 11828 INFO CpuLease [Thread-5]: CpuLease started 13776 ERROR NodeService [main]: Turbinator constructor failed: com.rbnb.sapi.SAPIException: Nesting java.net.ConnectException 13875 INFO MOOSNodeService [main]: Last MMC reset code: 6 14075 INFO NodeManager [main]: Starting registry... 14115 INFO NodeManager [main]: registry started. 14116 INFO NodeManager [main]: binding NodeService to rmi://localhost/node 14665 INFO NodeManager [main]: NodeService is bound to rmi://localhost/node 14793 INFO MOOSWDTManager [Thread-2]: WDTManager initializing watchdog timer 14848 ERROR MOOSWDTManager [Thread-2]: !!! Reboot due to watchdog reset !!! 15072 INFO PortManager [main]: Service jar not specified for port /dev/ttySX0 15983 INFO PortManager [main]: Starting service... 16245 INFO MOOSWDTManager [Thread-2]: WDTManager watchdog initialization successful 31795 INFO BaseInstrumentService [main]: initializeInstrument() for 33369 WARN StreamingSBE19 [main]: Did not receive expected confirmation prompt from command: MP 38404 INFO StreamingSBE19 [main]: Event detection disabled by attribute 38468 INFO BaseInstrumentService [main]: done with initializeInstrument() for 46643 INFO PortManager [main]: service started 47205 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 47835 INFO PortManager [main]: Starting service... 68352 INFO BaseInstrumentService [main]: initializeInstrument() for 68353 INFO WetLabsECO [main]: Initializing... 83730 INFO WetLabsECO [main]: Initializing completed 83731 INFO BaseInstrumentService [main]: done with initializeInstrument() for 92919 INFO PolledInstrumentService [main]: prepareToRun(): sampleMasterId = 1827 92921 INFO PolledInstrumentService [main]: id 1535 slaved to 1827 92941 INFO PortManager [main]: service started 92944 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 92967 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1455837059280 94997 INFO PortManager [main]: Starting service... 95343 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1455837061656 156892 INFO BaseInstrumentService [main]: initializeInstrument() for 158908 INFO Aquadopp [main]: sendSoftBreak(): instrument in mode 5 165158 INFO BaseInstrumentService [main]: done with initializeInstrument() for 165262 INFO Aquadopp [main]: Retrieving Aquadopp state Metadata... 167257 INFO Aquadopp [main]: sendSoftBreak(): instrument in mode 2 174963 INFO PortManager [main]: service started 174965 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 175430 INFO PortManager [main]: Starting service... 177097 INFO Aquadopp [Thread-34]: sendSoftBreak(): instrument in mode 2 186744 INFO BaseInstrumentService [main]: initializeInstrument() for 191607 INFO BaseInstrumentService [main]: done with initializeInstrument() for 193521 INFO PolledInstrumentService [main]: prepareToRun(): sampleMasterId = 1535 193522 INFO PolledInstrumentService [main]: id 1524 slaved to 1535 193528 INFO PortManager [main]: service started 193535 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 193547 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1455837159861 194615 INFO PortManager [main]: Starting service... 229419 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 235044 INFO BaseInstrumentService [main]: initializeInstrument() for 235046 INFO StreamingWorkhorseADCP-1825 [main]: Initializing Workhorse 1825 235056 INFO StreamingWorkhorseADCP-1825 [main]: Instrument has been pre-configured; ignore configuration attributes 237902 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TE? 237948 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 237985 INFO StreamingWorkhorseADCP-1825 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 237986 INFO StreamingWorkhorseADCP-1825 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 238225 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CF? 238291 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 238337 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 238338 INFO StreamingWorkhorseADCP-1825 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 238340 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 238342 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): property char 3=1 238343 INFO StreamingWorkhorseADCP-1825 [main]: Start pinging 238532 INFO BaseInstrumentService [main]: done with initializeInstrument() for 241405 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WD? 241443 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 241985 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WF? 242023 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 242604 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WN? 242681 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 243214 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WP? 243253 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 243787 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WS? 243828 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 244384 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WV? 244423 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 244954 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TE? 245031 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 245574 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TP? 245613 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 246144 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TS? 246183 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 246715 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EA? 246753 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 247284 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EB? 247371 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 247945 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request ED? 247983 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 248524 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request ES? 248564 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 249094 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EX? 249133 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 249705 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EZ? 249748 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 250294 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CF? 250336 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 250875 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RA? 250899 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 251414 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RR? 251453 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 255945 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RF? 255978 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 256524 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CB? 256563 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 257105 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request PS0 257129 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 259035 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request PS3 259058 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 260345 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RF 260376 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 260865 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request AC 260902 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 263225 INFO PortManager [main]: service started 263235 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 264458 INFO PortManager [main]: Starting service... 301470 ERROR DeviceLog [main]: readLogData: data size mismatch 301471 ERROR DeviceLog [main]: bytes read from file: 1173 301473 ERROR DeviceLog [main]: dataSize in index entry: 1927 301558 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 312224 INFO BaseInstrumentService [main]: initializeInstrument() for 312226 INFO StreamingWorkhorseADCP-1828 [main]: Initializing Workhorse 1828 312239 INFO StreamingWorkhorseADCP-1828 [main]: Instrument has been pre-configured; ignore configuration attributes 314875 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TE? 314915 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 315002 INFO StreamingWorkhorseADCP-1828 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 315003 INFO StreamingWorkhorseADCP-1828 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 315255 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CF? 315306 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 315391 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 315392 INFO StreamingWorkhorseADCP-1828 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 315395 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 315396 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): property char 3=1 315397 INFO StreamingWorkhorseADCP-1828 [main]: Start pinging 315582 INFO BaseInstrumentService [main]: done with initializeInstrument() for 318365 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WD? 318403 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 318944 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WF? 318983 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 319535 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WN? 319612 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 320144 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WP? 320183 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 320715 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WS? 320753 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 321275 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WV? 321314 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 321897 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TE? 321946 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 322526 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TP? 322582 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 323116 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TS? 323163 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 323767 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EA? 323814 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 324344 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EB? 324383 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 324939 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request ED? 325023 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 325555 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request ES? 325593 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 326125 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EX? 326164 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 326704 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EZ? 326747 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 327334 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CF? 327373 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 327925 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RA? 327949 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 328464 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RR? 328504 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 331494 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RF? 331529 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 332076 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CB? 332124 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 332686 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request PS0 332717 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 334595 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request PS3 334622 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 335935 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RF 335970 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 336454 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request AC 336493 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: