1 INFO NodeMain [main]: NodeMain.main(): portalHost = null 1379 INFO NodeManager [main]: Starting NodeManager at 02/18/2016 21:40:48 GMT (1455831648753), version: $Name: $ 1381 INFO NodeManager [main]: Node IP: node2/134.89.36.2 2047 INFO NodeManager [main]: Node ID has been set to 1598 3255 INFO PortManager [main]: Found 14 configured ports in properties file 3318 INFO MOOSPortManager [main]: Found DPA at slot: 0 5199 INFO MOOSPortManager [main]: Found DPA at slot: 1 6809 INFO MOOSPortManager [main]: Found DPA at slot: 2 8399 INFO MOOSPortManager [main]: Found DPA at slot: 3 9987 INFO MOOSPortManager [main]: No DPA found at slot: 4 9991 INFO MOOSPortManager [main]: No DPA found at slot: 5 10026 INFO WDTManager [main]: WDTManager constructor 10027 INFO MOOSWDTManager [main]: MOOSWDTManager constructor 10091 INFO SleepManager [main]: SleepManager constructor 10095 INFO MOOSSleepManager [main]: MOOSSleepManager constructor 11785 INFO CommsLeaseListener [main]: CommsLeaseListener() complete 11851 INFO CpuLeaseSleepRollcallListener [main]: CpuLeaseSleepRollcallListener() constructor 11867 INFO CpuLease [main]: CpuLease() constructor 11875 INFO CpuLease [Thread-5]: CpuLease started 13786 ERROR NodeService [main]: Turbinator constructor failed: com.rbnb.sapi.SAPIException: Nesting java.net.ConnectException 13885 INFO MOOSNodeService [main]: Last MMC reset code: 6 14175 INFO NodeManager [main]: Starting registry... 14213 INFO NodeManager [main]: registry started. 14214 INFO NodeManager [main]: binding NodeService to rmi://localhost/node 14755 INFO NodeManager [main]: NodeService is bound to rmi://localhost/node 14769 INFO MOOSWDTManager [Thread-2]: WDTManager initializing watchdog timer 14967 ERROR MOOSWDTManager [Thread-2]: !!! Reboot due to watchdog reset !!! 15179 INFO PortManager [main]: Service jar not specified for port /dev/ttySX0 16126 INFO PortManager [main]: Starting service... 16356 INFO MOOSWDTManager [Thread-2]: WDTManager watchdog initialization successful 31815 INFO BaseInstrumentService [main]: initializeInstrument() for 33389 WARN StreamingSBE19 [main]: Did not receive expected confirmation prompt from command: MP 38424 INFO StreamingSBE19 [main]: Event detection disabled by attribute 38506 INFO BaseInstrumentService [main]: done with initializeInstrument() for 46753 INFO PortManager [main]: service started 46781 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 47346 INFO PortManager [main]: Starting service... 67745 INFO BaseInstrumentService [main]: initializeInstrument() for 67747 INFO WetLabsECO [main]: Initializing... 83178 INFO WetLabsECO [main]: Initializing completed 83179 INFO BaseInstrumentService [main]: done with initializeInstrument() for 92306 INFO PolledInstrumentService [main]: prepareToRun(): sampleMasterId = 1827 92307 INFO PolledInstrumentService [main]: id 1535 slaved to 1827 92326 INFO PortManager [main]: service started 92333 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 92374 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1455831739755 94409 INFO PortManager [main]: Starting service... 94752 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1455831742134 155752 INFO BaseInstrumentService [main]: initializeInstrument() for 157768 INFO Aquadopp [main]: sendSoftBreak(): instrument in mode 5 164018 INFO BaseInstrumentService [main]: done with initializeInstrument() for 164122 INFO Aquadopp [main]: Retrieving Aquadopp state Metadata... 166117 INFO Aquadopp [main]: sendSoftBreak(): instrument in mode 2 173903 INFO PortManager [main]: service started 173905 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 174402 INFO PortManager [main]: Starting service... 176058 INFO Aquadopp [Thread-34]: sendSoftBreak(): instrument in mode 2 185366 INFO BaseInstrumentService [main]: initializeInstrument() for 190247 INFO BaseInstrumentService [main]: done with initializeInstrument() for 192153 INFO PolledInstrumentService [main]: prepareToRun(): sampleMasterId = 1535 192156 INFO PolledInstrumentService [main]: id 1524 slaved to 1535 192160 INFO PortManager [main]: service started 192165 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 192178 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1455831839559 193314 INFO PortManager [main]: Starting service... 229016 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 234644 INFO BaseInstrumentService [main]: initializeInstrument() for 234647 INFO StreamingWorkhorseADCP-1825 [main]: Initializing Workhorse 1825 234657 INFO StreamingWorkhorseADCP-1825 [main]: Instrument has been pre-configured; ignore configuration attributes 237485 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TE? 237524 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 237567 INFO StreamingWorkhorseADCP-1825 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 237569 INFO StreamingWorkhorseADCP-1825 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 237884 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CF? 237921 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 237968 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 237969 INFO StreamingWorkhorseADCP-1825 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 237972 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 237973 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): property char 3=1 237974 INFO StreamingWorkhorseADCP-1825 [main]: Start pinging 238162 INFO BaseInstrumentService [main]: done with initializeInstrument() for 241095 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WD? 241133 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 241675 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WF? 241713 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 242315 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WN? 242353 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 242884 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WP? 242923 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 243455 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WS? 243493 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 244025 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WV? 244063 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 244655 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TE? 244693 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 245245 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TP? 245280 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 245815 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TS? 245855 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 246415 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EA? 246453 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 246985 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EB? 247023 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 247594 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request ED? 247633 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 248164 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request ES? 248204 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 248735 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EX? 248773 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 249315 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EZ? 249427 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 250004 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CF? 250044 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 250625 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RA? 250649 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 251164 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RR? 251203 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 254154 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RF? 254191 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 254764 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CB? 254803 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 255345 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request PS0 255372 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 257274 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request PS3 257298 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 258615 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RF 258653 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 259143 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request AC 259190 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 261436 INFO PortManager [main]: service started 261446 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 262711 INFO PortManager [main]: Starting service... 299933 ERROR DeviceLog [main]: readLogData: data size mismatch 299937 ERROR DeviceLog [main]: bytes read from file: 1173 299938 ERROR DeviceLog [main]: dataSize in index entry: 1927 300047 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 310706 INFO BaseInstrumentService [main]: initializeInstrument() for 310709 INFO StreamingWorkhorseADCP-1828 [main]: Initializing Workhorse 1828 310721 INFO StreamingWorkhorseADCP-1828 [main]: Instrument has been pre-configured; ignore configuration attributes 313355 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TE? 313402 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 313455 INFO StreamingWorkhorseADCP-1828 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 313457 INFO StreamingWorkhorseADCP-1828 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 313695 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CF? 313734 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 313822 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 313823 INFO StreamingWorkhorseADCP-1828 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 313827 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 313828 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): property char 3=1 313829 INFO StreamingWorkhorseADCP-1828 [main]: Start pinging 314102 INFO BaseInstrumentService [main]: done with initializeInstrument() for 316875 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WD? 316913 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 317455 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WF? 317493 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 318025 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WN? 318111 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 318645 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WP? 318683 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 319215 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WS? 319302 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 319824 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WV? 319866 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 320417 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TE? 320466 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 321038 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TP? 321090 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 321677 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TS? 321726 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 322275 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EA? 322313 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 322845 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EB? 322883 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 323416 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request ED? 323458 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 324001 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request ES? 324056 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 324603 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EX? 324652 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 325184 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EZ? 325263 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 325824 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CF? 325863 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 326404 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RA? 326428 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 326945 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RR? 326984 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 329235 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RF? 329276 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 329905 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CB? 330101 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 330726 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request PS0 330757 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 332655 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request PS3 332682 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 333974 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RF 334059 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 334535 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request AC 334573 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: