0 INFO NodeMain [main]: NodeMain.main(): portalHost = null 1379 INFO NodeManager [main]: Starting NodeManager at 02/19/2016 00:39:44 GMT (1455842384676), version: $Name: $ 1381 INFO NodeManager [main]: Node IP: node2/134.89.36.2 2046 INFO NodeManager [main]: Node ID has been set to 1598 3254 INFO PortManager [main]: Found 14 configured ports in properties file 3318 INFO MOOSPortManager [main]: Found DPA at slot: 0 5198 INFO MOOSPortManager [main]: Found DPA at slot: 1 6808 INFO MOOSPortManager [main]: Found DPA at slot: 2 8398 INFO MOOSPortManager [main]: Found DPA at slot: 3 9986 INFO MOOSPortManager [main]: No DPA found at slot: 4 9990 INFO MOOSPortManager [main]: No DPA found at slot: 5 10025 INFO WDTManager [main]: WDTManager constructor 10027 INFO MOOSWDTManager [main]: MOOSWDTManager constructor 10090 INFO SleepManager [main]: SleepManager constructor 10094 INFO MOOSSleepManager [main]: MOOSSleepManager constructor 11804 INFO CommsLeaseListener [main]: CommsLeaseListener() complete 11870 INFO CpuLeaseSleepRollcallListener [main]: CpuLeaseSleepRollcallListener() constructor 11885 INFO CpuLease [main]: CpuLease() constructor 11888 INFO CpuLease [Thread-5]: CpuLease started 13895 ERROR NodeService [main]: Turbinator constructor failed: com.rbnb.sapi.SAPIException: Nesting java.net.ConnectException 13992 INFO MOOSNodeService [main]: Last MMC reset code: 6 14284 INFO NodeManager [main]: Starting registry... 14325 INFO NodeManager [main]: registry started. 14326 INFO NodeManager [main]: binding NodeService to rmi://localhost/node 14824 INFO NodeManager [main]: NodeService is bound to rmi://localhost/node 14849 INFO MOOSWDTManager [Thread-2]: WDTManager initializing watchdog timer 15001 ERROR MOOSWDTManager [Thread-2]: !!! Reboot due to watchdog reset !!! 15155 INFO PortManager [main]: Service jar not specified for port /dev/ttySX0 16106 INFO PortManager [main]: Starting service... 16388 INFO MOOSWDTManager [Thread-2]: WDTManager watchdog initialization successful 32094 INFO BaseInstrumentService [main]: initializeInstrument() for 33668 WARN StreamingSBE19 [main]: Did not receive expected confirmation prompt from command: MP 38704 INFO StreamingSBE19 [main]: Event detection disabled by attribute 38775 INFO BaseInstrumentService [main]: done with initializeInstrument() for 46990 INFO PortManager [main]: service started 47439 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 48168 INFO PortManager [main]: Starting service... 68783 INFO BaseInstrumentService [main]: initializeInstrument() for 68785 INFO WetLabsECO [main]: Initializing... 84217 INFO WetLabsECO [main]: Initializing completed 84218 INFO BaseInstrumentService [main]: done with initializeInstrument() for 93235 INFO PolledInstrumentService [main]: prepareToRun(): sampleMasterId = 1827 93238 INFO PolledInstrumentService [main]: id 1535 slaved to 1827 93257 INFO PortManager [main]: service started 93264 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 93301 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1455842476606 95358 INFO PortManager [main]: Starting service... 95672 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1455842478977 156091 INFO BaseInstrumentService [main]: initializeInstrument() for 158137 INFO Aquadopp [main]: sendSoftBreak(): instrument in mode 2 164325 INFO BaseInstrumentService [main]: done with initializeInstrument() for 164433 INFO Aquadopp [main]: Retrieving Aquadopp state Metadata... 166416 INFO Aquadopp [main]: sendSoftBreak(): instrument in mode 2 174156 INFO PortManager [main]: service started 174164 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 174607 INFO PortManager [main]: Starting service... 176296 INFO Aquadopp [Thread-34]: sendSoftBreak(): instrument in mode 2 185344 INFO BaseInstrumentService [main]: initializeInstrument() for 187638 ERROR StreamingInstrumentService [Thread-34]: acquireFromStream(), tried startStreaming(): Instrument didn't acknowledge SR command 190316 INFO BaseInstrumentService [main]: done with initializeInstrument() for 192225 INFO PolledInstrumentService [main]: prepareToRun(): sampleMasterId = 1535 192226 INFO PolledInstrumentService [main]: id 1524 slaved to 1535 192232 INFO PortManager [main]: service started 192234 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 192254 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1455842575558 193307 INFO PortManager [main]: Starting service... 228338 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 233964 INFO BaseInstrumentService [main]: initializeInstrument() for 233966 INFO StreamingWorkhorseADCP-1825 [main]: Initializing Workhorse 1825 233978 INFO StreamingWorkhorseADCP-1825 [main]: Instrument has been pre-configured; ignore configuration attributes 236864 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TE? 236900 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 236947 INFO StreamingWorkhorseADCP-1825 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 236948 INFO StreamingWorkhorseADCP-1825 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 237184 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CF? 237223 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 237267 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 237269 INFO StreamingWorkhorseADCP-1825 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 237271 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 237272 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): property char 3=1 237273 INFO StreamingWorkhorseADCP-1825 [main]: Start pinging 237461 INFO BaseInstrumentService [main]: done with initializeInstrument() for 240475 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WD? 240523 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 241064 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WF? 241099 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 241634 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WN? 241672 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 242244 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WP? 242282 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 242814 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WS? 242852 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 243384 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WV? 243422 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 243954 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TE? 244000 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 244544 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TP? 244582 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 245114 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TS? 245153 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 245734 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EA? 245772 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 246324 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EB? 246359 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 246934 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request ED? 246973 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 247504 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request ES? 247542 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 248114 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EX? 248152 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 248694 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EZ? 248732 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 249264 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CF? 249303 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 249844 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RA? 249868 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 250384 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RR? 250475 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 256414 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RF? 256444 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 257014 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CB? 257053 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 257594 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request PS0 257618 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 259474 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request PS3 259498 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 260814 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RF 260847 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 261334 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request AC 261372 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 263654 INFO PortManager [main]: service started 263663 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 264844 INFO PortManager [main]: Starting service... 302238 ERROR DeviceLog [main]: readLogData: data size mismatch 302239 ERROR DeviceLog [main]: bytes read from file: 1173 302240 ERROR DeviceLog [main]: dataSize in index entry: 1927 302382 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 313046 INFO BaseInstrumentService [main]: initializeInstrument() for 313048 INFO StreamingWorkhorseADCP-1828 [main]: Initializing Workhorse 1828 313060 INFO StreamingWorkhorseADCP-1828 [main]: Instrument has been pre-configured; ignore configuration attributes 315694 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TE? 315733 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 315777 INFO StreamingWorkhorseADCP-1828 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 315778 INFO StreamingWorkhorseADCP-1828 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 316045 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CF? 316093 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 316128 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 316129 INFO StreamingWorkhorseADCP-1828 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 316131 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 316176 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): property char 3=1 316177 INFO StreamingWorkhorseADCP-1828 [main]: Start pinging 316361 INFO BaseInstrumentService [main]: done with initializeInstrument() for 319134 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WD? 319222 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 319764 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WF? 319803 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 320364 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WN? 320403 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 320974 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WP? 321013 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 321544 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WS? 321582 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 322114 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WV? 322159 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 322705 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TE? 322751 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 323305 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TP? 323359 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 324001 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TS? 324108 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 324664 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EA? 324703 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 325234 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EB? 325272 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 325844 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request ED? 325883 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 326414 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request ES? 326453 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 326984 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EX? 327023 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 327564 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EZ? 327651 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 328184 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CF? 328226 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 328804 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RA? 328828 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 329344 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RR? 329384 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 333116 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RF? 333160 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 333756 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CB? 333805 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 334374 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request PS0 334399 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 336274 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request PS3 336298 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 337604 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RF 337636 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 338124 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request AC 338162 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: