0 INFO NodeMain [main]: NodeMain.main(): portalHost = null 1394 INFO NodeManager [main]: Starting NodeManager at 02/18/2016 23:17:44 GMT (1455837464774), version: $Name: $ 1396 INFO NodeManager [main]: Node IP: node2/134.89.36.2 2062 INFO NodeManager [main]: Node ID has been set to 1598 3274 INFO PortManager [main]: Found 14 configured ports in properties file 3338 INFO MOOSPortManager [main]: Found DPA at slot: 0 5218 INFO MOOSPortManager [main]: Found DPA at slot: 1 6848 INFO MOOSPortManager [main]: Found DPA at slot: 2 8438 INFO MOOSPortManager [main]: Found DPA at slot: 3 10026 INFO MOOSPortManager [main]: No DPA found at slot: 4 10030 INFO MOOSPortManager [main]: No DPA found at slot: 5 10065 INFO WDTManager [main]: WDTManager constructor 10067 INFO MOOSWDTManager [main]: MOOSWDTManager constructor 10130 INFO SleepManager [main]: SleepManager constructor 10134 INFO MOOSSleepManager [main]: MOOSSleepManager constructor 11834 INFO CommsLeaseListener [main]: CommsLeaseListener() complete 11900 INFO CpuLeaseSleepRollcallListener [main]: CpuLeaseSleepRollcallListener() constructor 11915 INFO CpuLease [main]: CpuLease() constructor 11918 INFO CpuLease [Thread-5]: CpuLease started 13785 ERROR NodeService [main]: Turbinator constructor failed: com.rbnb.sapi.SAPIException: Nesting java.net.ConnectException 13884 INFO MOOSNodeService [main]: Last MMC reset code: 6 14114 INFO NodeManager [main]: Starting registry... 14155 INFO NodeManager [main]: registry started. 14156 INFO NodeManager [main]: binding NodeService to rmi://localhost/node 14734 INFO NodeManager [main]: NodeService is bound to rmi://localhost/node 14874 INFO MOOSWDTManager [Thread-2]: WDTManager initializing watchdog timer 14882 ERROR MOOSWDTManager [Thread-2]: !!! Reboot due to watchdog reset !!! 15057 INFO PortManager [main]: Service jar not specified for port /dev/ttySX0 15961 INFO PortManager [main]: Starting service... 16761 INFO MOOSWDTManager [Thread-2]: WDTManager watchdog initialization successful 32024 INFO BaseInstrumentService [main]: initializeInstrument() for 33598 WARN StreamingSBE19 [main]: Did not receive expected confirmation prompt from command: MP 38633 INFO StreamingSBE19 [main]: Event detection disabled by attribute 38699 INFO BaseInstrumentService [main]: done with initializeInstrument() for 46907 INFO PortManager [main]: service started 47184 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 47772 INFO PortManager [main]: Starting service... 68449 INFO BaseInstrumentService [main]: initializeInstrument() for 68451 INFO WetLabsECO [main]: Initializing... 83817 INFO WetLabsECO [main]: Initializing completed 83818 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 92966 INFO WetLabsECO [Thread-25]: TEST: ID=1535, trigger sample at 1455837556354 95007 INFO PortManager [main]: Starting service... 95342 INFO WetLabsECO [Thread-25]: TEST: ID=1535, got complete sample at + 1455837558730 155851 INFO BaseInstrumentService [main]: initializeInstrument() for 157827 INFO Aquadopp [main]: sendSoftBreak(): instrument in mode 5 164027 INFO BaseInstrumentService [main]: done with initializeInstrument() for 164179 INFO Aquadopp [main]: Retrieving Aquadopp state Metadata... 166166 INFO Aquadopp [main]: sendSoftBreak(): instrument in mode 2 173909 INFO PortManager [main]: service started 173915 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 174507 INFO PortManager [main]: Starting service... 176016 INFO Aquadopp [Thread-34]: sendSoftBreak(): instrument in mode 2 185903 INFO BaseInstrumentService [main]: initializeInstrument() for 190707 INFO BaseInstrumentService [main]: done with initializeInstrument() for 192603 INFO PolledInstrumentService [main]: prepareToRun(): sampleMasterId = 1535 192605 INFO PolledInstrumentService [main]: id 1524 slaved to 1535 192609 INFO PortManager [main]: service started 192615 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 192628 INFO AanderaaO2 [Thread-38]: TT: ID=1524, trigger sample at 1455837656016 193693 INFO PortManager [main]: Starting service... 228402 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 234034 INFO BaseInstrumentService [main]: initializeInstrument() for 234036 INFO StreamingWorkhorseADCP-1825 [main]: Initializing Workhorse 1825 234046 INFO StreamingWorkhorseADCP-1825 [main]: Instrument has been pre-configured; ignore configuration attributes 236954 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TE? 236993 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 237036 INFO StreamingWorkhorseADCP-1825 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 237038 INFO StreamingWorkhorseADCP-1825 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 237274 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CF? 237312 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 237358 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 237359 INFO StreamingWorkhorseADCP-1825 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 237361 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 237362 INFO StreamingWorkhorseADCP-1825 [main]: initializeInstrument(): property char 3=1 237363 INFO StreamingWorkhorseADCP-1825 [main]: Start pinging 237551 INFO BaseInstrumentService [main]: done with initializeInstrument() for 240474 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WD? 240513 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 241053 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WF? 241130 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 241664 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WN? 241702 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 242234 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WP? 242272 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 242804 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WS? 242843 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 243363 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request WV? 243402 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 243954 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TE? 244040 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 244583 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TP? 244622 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 245194 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request TS? 245232 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 245767 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EA? 245850 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 246384 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EB? 246422 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 246954 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request ED? 246992 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 247524 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request ES? 247563 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 248094 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EX? 248132 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 248674 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request EZ? 248712 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 249282 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CF? 249329 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 249864 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RA? 249888 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 250444 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RR? 250483 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 255114 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RF? 255148 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 255703 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request CB? 255742 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 256284 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request PS0 256307 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 258213 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request PS3 258238 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 259534 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request RF 259566 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 260062 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: wrote request AC 260110 INFO StreamingWorkhorseADCP-1825 [main]: sendRequest: got echo response: 262314 INFO NodeManager [Thread-1]: serviceInstalled(): invoke remote callbacks 262317 INFO PortManager [main]: service started 263581 INFO PortManager [main]: Starting service... 300264 ERROR DeviceLog [main]: readLogData: data size mismatch 300266 ERROR DeviceLog [main]: bytes read from file: 1173 300267 ERROR DeviceLog [main]: dataSize in index entry: 1927 300335 INFO StreamingWorkhorseADCP-0 [main]: getSerialParameters(): baud=9600 310996 INFO BaseInstrumentService [main]: initializeInstrument() for 310998 INFO StreamingWorkhorseADCP-1828 [main]: Initializing Workhorse 1828 311009 INFO StreamingWorkhorseADCP-1828 [main]: Instrument has been pre-configured; ignore configuration attributes 313644 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TE? 313684 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 313727 INFO StreamingWorkhorseADCP-1828 [main]: TE response: TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 313728 INFO StreamingWorkhorseADCP-1828 [main]: getProperty(): response=TE 00:00:10.00 --------- Time per Ensemble (hrs:min:sec.sec/100) > 313974 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CF? 314014 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 314058 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): response = CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 314059 INFO StreamingWorkhorseADCP-1828 [main]: getProperty(): response=CF = 11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) > 314061 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): property=11111 --------------- Flow Ctrl (EnsCyc;PngCyc;Binry;Ser;Rec) >, char 3=1 314062 INFO StreamingWorkhorseADCP-1828 [main]: initializeInstrument(): property char 3=1 314064 INFO StreamingWorkhorseADCP-1828 [main]: Start pinging 314299 INFO BaseInstrumentService [main]: done with initializeInstrument() for 317203 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WD? 317242 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 317784 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WF? 317823 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 318354 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WN? 318392 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 318963 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WP? 319003 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 319534 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WS? 319572 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 320104 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request WV? 320191 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 320724 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TE? 320763 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 321365 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TP? 321412 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 321976 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request TS? 322049 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 322596 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EA? 322746 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 323284 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EB? 323323 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 323953 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request ED? 323992 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 324534 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request ES? 324573 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 325104 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EX? 325143 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 325724 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request EZ? 325763 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 326294 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CF? 326333 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 326874 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RA? 326898 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 327414 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RR? 327461 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 330694 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RF? 330730 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 331275 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request CB? 331322 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 331885 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request PS0 331916 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 333784 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request PS3 333808 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 335124 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request RF 335159 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: 335644 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: wrote request AC 335683 INFO StreamingWorkhorseADCP-1828 [main]: sendRequest: got echo response: