2017-12-09T02:14:02.109Z,1512785642.109 [CommandLine](IMPORTANT): got command restart logs 2017-12-09T02:14:28.939Z,1512785668.939 [Startup:StartupSatComms:A](INFO): Timed out from 2017-12-09T02:13:28.8Z 2017-12-09T02:14:28.939Z,1512785668.939 [Startup:StartupSatComms:A] Stopped 2017-12-09T02:14:28.939Z,1512785668.939 [Startup:StartupSatComms:B] Running Loop=1 2017-12-09T02:14:29.338Z,1512785669.338 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2017-12-09T02:14:30.887Z,1512785670.887 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-12-09T02:14:30.887Z,1512785670.887 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2017-12-09T02:14:30.887Z,1512785670.887 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2017-12-09T02:14:30.901Z,1512785670.901 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-12-09T02:14:30.901Z,1512785670.901 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1 2017-12-09T02:14:30.901Z,1512785670.901 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2017-12-09T02:14:30.931Z,1512785670.931 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2017-12-09T02:14:30.931Z,1512785670.931 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2017-12-09T02:14:31.102Z,1512785671.102 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2017-12-09T02:14:31.102Z,1512785671.102 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2017-12-09T02:14:31.103Z,1512785671.103 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2017-12-09T02:14:31.103Z,1512785671.103 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1 2017-12-09T02:14:36.095Z,1512785676.095 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2017-12-09T02:14:36.096Z,1512785676.096 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2017-12-09T02:14:36.096Z,1512785676.096 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2017-12-09T02:14:36.096Z,1512785676.096 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2017-12-09T02:14:36.133Z,1512785676.133 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2017-12-09T02:14:36.133Z,1512785676.133 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2017-12-09T02:14:36.207Z,1512785676.207 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20171208T173741/Courier0200.lzma 2017-12-09T02:14:36.987Z,1512785676.987 [DataOverHttps](INFO): Moved sent file to Logs/20171208T173741/Courier0200.lzma.bak 2017-12-09T02:14:36.987Z,1512785676.987 [DataOverHttps](INFO): SBD MOMSN=5415061 2017-12-09T02:14:47.578Z,1512785687.578 [DataOverHttps](INFO): Sending 184 bytes from file Logs/20171209T021119/Courier0000.lzma 2017-12-09T02:14:48.378Z,1512785688.378 [DataOverHttps](INFO): Moved sent file to Logs/20171209T021119/Courier0000.lzma.bak 2017-12-09T02:14:48.378Z,1512785688.378 [DataOverHttps](INFO): SBD MOMSN=5415063 2017-12-09T02:14:57.600Z,1512785697.600 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20171209T021402/Courier0000.lzma 2017-12-09T02:14:58.366Z,1512785698.366 [DataOverHttps](INFO): Moved sent file to Logs/20171209T021402/Courier0000.lzma.bak 2017-12-09T02:14:58.366Z,1512785698.366 [DataOverHttps](INFO): SBD MOMSN=5415068 2017-12-09T02:15:07.575Z,1512785707.575 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20171209T021402/Courier0004.lzma 2017-12-09T02:15:08.374Z,1512785708.374 [DataOverHttps](INFO): Moved sent file to Logs/20171209T021402/Courier0004.lzma.bak 2017-12-09T02:15:08.374Z,1512785708.374 [DataOverHttps](INFO): SBD MOMSN=5415071 2017-12-09T02:15:17.934Z,1512785717.934 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20171208T173741/Express0201.lzma 2017-12-09T02:15:18.735Z,1512785718.735 [DataOverHttps](INFO): Moved sent file to Logs/20171208T173741/Express0201.lzma.bak 2017-12-09T02:15:18.735Z,1512785718.735 [DataOverHttps](INFO): SBD MOMSN=5415073 2017-12-09T02:15:23.228Z,1512785723.228 [PNI_TCM](ERROR): CRC does not match. Expected:0x64639 got:0x41 2017-12-09T02:15:23.229Z,1512785723.229 [PNI_TCM](ERROR): CRC does not match. Expected:0x64639 got:0x41 2017-12-09T02:15:23.229Z,1512785723.229 [PNI_TCM](ERROR): CRC does not match. Expected:0x64639 got:0x41 2017-12-09T02:15:23.229Z,1512785723.229 [PNI_TCM](ERROR): CRC does not match. Expected:0x64639 got:0x41 2017-12-09T02:15:23.230Z,1512785723.230 [PNI_TCM](ERROR): CRC does not match. Expected:0x64639 got:0x41 2017-12-09T02:15:23.230Z,1512785723.230 [PNI_TCM](ERROR): CRC does not match. Expected:0x64639 got:0x41 2017-12-09T02:15:23.230Z,1512785723.230 [PNI_TCM](ERROR): CRC does not match. Expected:0x64639 got:0x41 2017-12-09T02:15:23.231Z,1512785723.231 [PNI_TCM](ERROR): CRC does not match. Expected:0x64639 got:0x41 2017-12-09T02:15:28.411Z,1512785728.411 [DataOverHttps](INFO): Sending 815 bytes from file Logs/20171209T021119/Express0001.lzma 2017-12-09T02:15:29.210Z,1512785729.210 [DataOverHttps](INFO): Moved sent file to Logs/20171209T021119/Express0001.lzma.bak 2017-12-09T02:15:29.210Z,1512785729.210 [DataOverHttps](INFO): SBD MOMSN=5415075 2017-12-09T02:15:29.281Z,1512785729.281 [Startup:StartupSatComms:B](INFO): Timed out from 2017-12-09T02:14:28.9Z 2017-12-09T02:15:29.281Z,1512785729.281 [Startup:StartupSatComms:B] Stopped 2017-12-09T02:15:29.281Z,1512785729.281 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2017-12-09T02:15:29.281Z,1512785729.281 [Startup:StartupSatComms] Stopped 2017-12-09T02:15:29.282Z,1512785729.282 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2017-12-09T02:15:29.282Z,1512785729.282 [Startup](INFO): Completed Startup 2017-12-09T02:15:29.283Z,1512785729.283 [MissionManager](INFO): Startup is completed. 2017-12-09T02:15:29.283Z,1512785729.283 [MissionManager](INFO): Uninitializing Mission Startup 2017-12-09T02:15:29.283Z,1512785729.283 [Startup] Stopped 2017-12-09T02:15:29.283Z,1512785729.283 [Startup](DEBUG): Aggregate::uninitialize Startup 2017-12-09T02:15:29.283Z,1512785729.283 [Startup:A.GoToSurface] Stopped 2017-12-09T02:15:29.283Z,1512785729.283 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2017-12-09T02:15:29.663Z,1512785729.663 [MissionManager](IMPORTANT): Started mission Default 2017-12-09T02:15:29.663Z,1512785729.663 [Default] Running Loop=1 2017-12-09T02:15:29.663Z,1512785729.663 [Default](DEBUG): Aggregate::initialize Default 2017-12-09T02:15:29.664Z,1512785729.664 [Default:B.GoToSurface] Running Loop=1 2017-12-09T02:15:29.664Z,1512785729.664 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2017-12-09T02:15:29.664Z,1512785729.664 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2017-12-09T02:15:29.664Z,1512785729.664 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2017-12-09T02:15:29.665Z,1512785729.665 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2017-12-09T02:15:29.665Z,1512785729.665 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds. 2017-12-09T02:15:29.665Z,1512785729.665 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2017-12-09T02:15:29.666Z,1512785729.666 [Default:A.Wait] Running Loop=1 2017-12-09T02:15:29.666Z,1512785729.666 [Default:A.Wait](DEBUG): Initialize Wait Component. 2017-12-09T02:15:33.996Z,1512785733.996 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2017-12-09T02:15:33.997Z,1512785733.997 [Rowe_600LCM] Communications Fault, FailCount= 2 2017-12-09T02:15:33.997Z,1512785733.997 [Rowe_600LCM](ERROR): Communications Fault 2017-12-09T02:15:34.171Z,1512785734.171 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s. 2017-12-09T02:15:34.184Z,1512785734.184 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2017-12-09T02:15:34.401Z,1512785734.401 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2017-12-09T02:15:34.494Z,1512785734.494 [Rowe_600LCM](INFO): Powering down 2017-12-09T02:15:34.584Z,1512785734.584 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s. 2017-12-09T02:15:34.970Z,1512785734.970 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s. 2017-12-09T02:15:35.370Z,1512785735.370 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s. 2017-12-09T02:15:35.779Z,1512785735.779 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s. 2017-12-09T02:15:35.809Z,1512785735.809 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2017-12-09T02:15:35.809Z,1512785735.809 [Rowe_600LCM] No Fault, FailCount= 2 2017-12-09T02:15:36.109Z,1512785736.109 [Rowe_600LCM](INFO): Initializing 2017-12-09T02:15:36.109Z,1512785736.109 [Rowe_600LCM](INFO): Checking LCM 2017-12-09T02:15:36.109Z,1512785736.109 [Rowe_600LCM](INFO): LCM OK 2017-12-09T02:15:36.109Z,1512785736.109 [Rowe_600LCM](INFO): Powering up 2017-12-09T02:15:36.185Z,1512785736.185 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s. 2017-12-09T02:15:36.574Z,1512785736.574 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s. 2017-12-09T02:15:36.978Z,1512785736.978 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s. 2017-12-09T02:15:37.372Z,1512785737.372 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s. 2017-12-09T02:15:37.785Z,1512785737.785 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s. 2017-12-09T02:15:38.183Z,1512785738.183 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s. 2017-12-09T02:15:38.578Z,1512785738.578 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s. 2017-12-09T02:15:38.977Z,1512785738.977 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s. 2017-12-09T02:15:39.372Z,1512785739.372 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s. 2017-12-09T02:15:39.773Z,1512785739.773 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s. 2017-12-09T02:15:40.223Z,1512785740.223 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s. 2017-12-09T02:15:40.322Z,1512785740.322 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2017-12-09T02:15:40.391Z,1512785740.391 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null & 2017-12-09T02:15:40.393Z,1512785740.393 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2017-12-09T02:15:40.394Z,1512785740.394 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2017-12-09T02:15:40.395Z,1512785740.395 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2017-12-09T02:15:40.576Z,1512785740.576 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s. 2017-12-09T02:15:42.973Z,1512785742.973 [Default:A.Wait](INFO): Done Waiting. 2017-12-09T02:15:42.973Z,1512785742.973 [Default:A.Wait] Stopped 2017-12-09T02:15:42.973Z,1512785742.973 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2017-12-09T02:15:43.377Z,1512785743.377 [Default:CheckIn] Running Loop=1 2017-12-09T02:15:43.377Z,1512785743.377 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2017-12-09T02:15:43.377Z,1512785743.377 [Default:CheckIn:Read_GPS] Running Loop=1 2017-12-09T02:15:43.826Z,1512785743.826 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2017-12-09T02:15:45.355Z,1512785745.355 [BPC1](ERROR): Failed to receive battery data 2017-12-09T02:15:45.355Z,1512785745.355 [BPC1] Communications Fault, FailCount= 1 2017-12-09T02:15:45.355Z,1512785745.355 [BPC1](ERROR): Communications Fault 2017-12-09T02:15:45.413Z,1512785745.413 [CBIT](ERROR): Communications Fault in component: BPC1 2017-12-09T02:15:46.600Z,1512785746.600 [CBIT](INFO): Clearing failed state for component BPC1 2017-12-09T02:15:46.604Z,1512785746.604 [BPC1] No Fault, FailCount= 1 2017-12-09T02:16:07.358Z,1512785767.358 [BPC1](ERROR): Failed to receive battery data 2017-12-09T02:16:07.358Z,1512785767.358 [BPC1] Communications Fault, FailCount= 2 2017-12-09T02:16:07.358Z,1512785767.358 [BPC1](ERROR): Communications Fault 2017-12-09T02:16:07.435Z,1512785767.435 [CBIT](ERROR): Communications Fault in component: BPC1 2017-12-09T02:16:08.630Z,1512785768.630 [CBIT](INFO): Clearing failed state for component BPC1 2017-12-09T02:16:08.630Z,1512785768.630 [BPC1] No Fault, FailCount= 2 2017-12-09T02:16:13.234Z,1512785773.234 [CommandLine](IMPORTANT): got command maintain sensor depth 2017-12-09T02:16:13.235Z,1512785773.235 [CommandLine](FAULT): Incomplete syntax. Try: help maintain 2017-12-09T02:16:24.418Z,1512785784.418 [CommandLine](IMPORTANT): got command maintain sensor depth 0.000000 meter 2017-12-09T02:16:24.419Z,1512785784.419 [ComponentRegistry](DEBUG): SyncComponent "Maintain_depth" handled in the control thread. 2017-12-09T02:16:24.650Z,1512785784.650 [controlThread](DEBUG): Component order: CycleStarter,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,Maintain_depth,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2017-12-09T02:16:26.943Z,1512785786.943 [CommandLine](IMPORTANT): got command get depth 2017-12-09T02:16:26.944Z,1512785786.944 [CommandLine](IMPORTANT): depth 0.042698 m 2017-12-09T02:16:30.078Z,1512785790.078 [CommandLine](IMPORTANT): got command maintain 2017-12-09T02:16:30.079Z,1512785790.079 [Maintain_depth](INFO): depth maintaining 0.000000 m 2017-12-09T02:16:32.917Z,1512785792.917 [CommandLine](IMPORTANT): got command get depth 2017-12-09T02:16:32.918Z,1512785792.918 [CommandLine](IMPORTANT): depth 0.000000 m 2017-12-09T02:16:34.434Z,1512785794.434 [DataOverHttps](IMPORTANT): SBD MTMSN=20171209T021550 2017-12-09T02:16:39.439Z,1512785799.439 [DataOverHttps](INFO): Received command:load Maintenance/sample.xml;set sample.Depth 0 meter;set sample.WaitBeforeSample 10 second;set sample:SampleAtDepth.TargetDepth 0 meter;run 2017-12-09T02:16:39.496Z,1512785799.496 [CommandLine](IMPORTANT): got command load ./Missions/Maintenance/sample.xml 2017-12-09T02:16:39.496Z,1512785799.496 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/sample.xml 2017-12-09T02:16:39.533Z,1512785799.533 [MissionManager](INFO): DefineArg sample.MissionTimeout = 90.000000 min 2017-12-09T02:16:39.536Z,1512785799.536 [MissionManager](INFO): DefineArg sample.Depth = 7.000000 m 2017-12-09T02:16:39.541Z,1512785799.541 [MissionManager](INFO): DefineArg sample.NumberOfSamples = 1.000000 count 2017-12-09T02:16:39.570Z,1512785799.570 [MissionManager](INFO): DefineArg sample.WaitBeforeSample = 3.000000 min 2017-12-09T02:16:39.571Z,1512785799.571 [sample:A.Pitch](DEBUG): Construct. 2017-12-09T02:16:39.585Z,1512785799.585 [sample:B.SetSpeed](DEBUG): Construct. 2017-12-09T02:16:39.587Z,1512785799.587 [MissionManager](INFO): Inserting Stack: Missions/Insert/SampleAtDepth.xml 2017-12-09T02:16:39.693Z,1512785799.693 [MissionManager](INFO): DefineArg sample:SampleAtDepth.TargetDepth = 5.000000 m 2017-12-09T02:16:39.696Z,1512785799.696 [MissionManager](INFO): DefineArg sample:SampleAtDepth.SettleTime = 30.000000 s 2017-12-09T02:16:39.708Z,1512785799.708 [MissionManager](INFO): DefineArg sample:SampleAtDepth.RotateOnly = 0.000000 bool 2017-12-09T02:16:39.711Z,1512785799.711 [MissionManager](INFO): DefineArg sample:SampleAtDepth.UseCANONSampler = 0.000000 bool 2017-12-09T02:16:39.714Z,1512785799.714 [MissionManager](INFO): DefineArg sample:SampleAtDepth.UseESP = 1.000000 bool 2017-12-09T02:16:39.730Z,1512785799.730 [MissionManager](INFO): DefineArg sample:SampleAtDepth.CANONSamplerTriggerTimeout = 1.000000 min 2017-12-09T02:16:39.733Z,1512785799.733 [MissionManager](INFO): DefineArg sample:SampleAtDepth.CANONSamplerTimeout = 6.000000 min 2017-12-09T02:16:39.736Z,1512785799.736 [sample:SampleAtDepth:B.Pitch](DEBUG): Construct. 2017-12-09T02:16:39.768Z,1512785799.768 [sample:SampleAtDepth:D.Wait](DEBUG): Construct Wait. 2017-12-09T02:16:39.786Z,1512785799.786 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:TriggerCANONSampler:A_Timeout:B.Execute](DEBUG): Construct Execute. 2017-12-09T02:16:39.807Z,1512785799.807 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:E:A_Timeout:B.Execute](DEBUG): Construct Execute. 2017-12-09T02:16:39.854Z,1512785799.854 [MissionManager](DEBUG): This mission is designed to be run in the test tank only and samples whichever sampler is installed once the vehicle reaches the target depth. How long to let the mission run. 90 Depth to sample at. 7 Number of samples to take. 1 How long to wait between samples. This starts *before* the first sample to exercise the SettleTime setting in the insert aggregate. 3 0 2017-12-09T02:16:39.855Z,1512785799.855 [CommandLine](IMPORTANT): Loaded ./Missions/Maintenance/sample.xml 2017-12-09T02:16:43.755Z,1512785803.755 [CommandLine](IMPORTANT): got command set sample.Depth 0.000000 meter 2017-12-09T02:16:43.755Z,1512785803.755 [CommandLine](IMPORTANT): got command set sample.WaitBeforeSample 10.000000 second 2017-12-09T02:16:43.756Z,1512785803.756 [CommandLine](IMPORTANT): got command set sample:SampleAtDepth.TargetDepth 0.000000 meter 2017-12-09T02:16:43.757Z,1512785803.757 [CommandLine](IMPORTANT): got command run 2017-12-09T02:16:43.759Z,1512785803.759 [CommandLine](IMPORTANT): Running 2017-12-09T02:16:43.985Z,1512785803.985 [Default] Stopped 2017-12-09T02:16:43.985Z,1512785803.985 [Default](DEBUG): Aggregate::uninitialize Default 2017-12-09T02:16:43.985Z,1512785803.985 [Default:B.GoToSurface] Stopped 2017-12-09T02:16:43.986Z,1512785803.986 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2017-12-09T02:16:43.986Z,1512785803.986 [Default:CheckIn] Stopped 2017-12-09T02:16:43.986Z,1512785803.986 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2017-12-09T02:16:43.986Z,1512785803.986 [Default:CheckIn:Read_GPS] Stopped 2017-12-09T02:16:43.986Z,1512785803.986 [MissionManager](IMPORTANT): Started mission sample 2017-12-09T02:16:43.986Z,1512785803.986 [sample] Running Loop=1 2017-12-09T02:16:43.986Z,1512785803.986 [sample](DEBUG): Aggregate::initialize sample 2017-12-09T02:16:43.986Z,1512785803.986 [sample:A.Pitch] Running Loop=1 2017-12-09T02:16:43.986Z,1512785803.986 [sample:A.Pitch](DEBUG): Initialize. 2017-12-09T02:16:43.987Z,1512785803.987 [sample:B.SetSpeed] Running Loop=1 2017-12-09T02:16:43.987Z,1512785803.987 [sample:B.SetSpeed](DEBUG): Initialize. 2017-12-09T02:16:43.987Z,1512785803.987 [sample:D] Running Loop=1 2017-12-09T02:16:43.989Z,1512785803.989 [sample:D] Stopped 2017-12-09T02:16:43.989Z,1512785803.989 [sample:E] Running Loop=1 2017-12-09T02:16:43.989Z,1512785803.989 [sample:B.SetSpeed] Running Loop=1 2017-12-09T02:16:44.002Z,1512785804.002 [sample:A.Pitch] Running Loop=1 2017-12-09T02:16:44.106Z,1512785804.106 [sample:E] Stopped 2017-12-09T02:16:44.106Z,1512785804.106 [sample:SampleRepeater] Running Loop=1 2017-12-09T02:16:44.107Z,1512785804.107 [sample:SampleRepeater](DEBUG): Aggregate::initialize sample:SampleRepeater 2017-12-09T02:16:44.107Z,1512785804.107 [sample:SampleRepeater:Sample] Running Loop=1 2017-12-09T02:16:44.107Z,1512785804.107 [sample:SampleRepeater:Sample](DEBUG): Aggregate::initialize sample:SampleRepeater:Sample 2017-12-09T02:16:44.505Z,1512785804.505 [sample:SampleAtDepth] Running Loop=1 2017-12-09T02:16:44.505Z,1512785804.505 [sample:SampleAtDepth](DEBUG): Aggregate::initialize sample:SampleAtDepth 2017-12-09T02:16:44.506Z,1512785804.506 [sample:SampleAtDepth:B.Pitch] Running Loop=1 2017-12-09T02:16:44.506Z,1512785804.506 [sample:SampleAtDepth:B.Pitch](DEBUG): Initialize. 2017-12-09T02:16:44.506Z,1512785804.506 [sample:SampleAtDepth:A] Running Loop=1 2017-12-09T02:16:44.511Z,1512785804.511 [sample:SampleAtDepth:A](INFO): Moving to 0.000000 m 2017-12-09T02:16:44.512Z,1512785804.512 [sample:SampleAtDepth:A] Stopped 2017-12-09T02:16:44.882Z,1512785804.882 [sample:SampleAtDepth:C] Running Loop=1 2017-12-09T02:16:45.283Z,1512785805.283 [sample:SampleAtDepth:C](INFO): At 0.000000 m , settling for 0.166667 min 2017-12-09T02:16:45.284Z,1512785805.284 [sample:SampleAtDepth:C] Stopped 2017-12-09T02:16:45.284Z,1512785805.284 [sample:SampleAtDepth:D.Wait] Running Loop=1 2017-12-09T02:16:45.284Z,1512785805.284 [sample:SampleAtDepth:D.Wait](DEBUG): Initialize Wait Component. 2017-12-09T02:16:56.119Z,1512785816.119 [sample:SampleAtDepth:D.Wait](INFO): Done Waiting. 2017-12-09T02:16:56.120Z,1512785816.120 [sample:SampleAtDepth:D.Wait] Stopped 2017-12-09T02:16:56.120Z,1512785816.120 [sample:SampleAtDepth:D.Wait](DEBUG): Uninitialize Wait Component. 2017-12-09T02:16:56.120Z,1512785816.120 [sample:SampleAtDepth:SampleWrapper] Running Loop=1 2017-12-09T02:16:56.120Z,1512785816.120 [sample:SampleAtDepth:SampleWrapper](DEBUG): Aggregate::initialize sample:SampleAtDepth:SampleWrapper 2017-12-09T02:16:56.120Z,1512785816.120 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler] Running Loop=1 2017-12-09T02:16:56.120Z,1512785816.120 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler](DEBUG): Aggregate::initialize sample:SampleAtDepth:SampleWrapper:SampleCANONSampler 2017-12-09T02:16:56.120Z,1512785816.120 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:A] Running Loop=1 2017-12-09T02:16:56.121Z,1512785816.121 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:B] Running Loop=1 2017-12-09T02:16:56.121Z,1512785816.121 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:TriggerCANONSampler] Running Loop=1 2017-12-09T02:16:56.504Z,1512785816.504 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler] Stopped 2017-12-09T02:16:56.508Z,1512785816.508 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler](DEBUG): Aggregate::uninitialize sample:SampleAtDepth:SampleWrapper:SampleCANONSampler 2017-12-09T02:16:56.509Z,1512785816.509 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:A] Stopped 2017-12-09T02:16:56.509Z,1512785816.509 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:B] Stopped 2017-12-09T02:16:56.509Z,1512785816.509 [sample:SampleAtDepth:SampleWrapper:SampleCANONSampler:TriggerCANONSampler] Stopped 2017-12-09T02:16:56.509Z,1512785816.509 [sample:SampleAtDepth:SampleWrapper:SampleESP] Running Loop=1 2017-12-09T02:16:56.509Z,1512785816.509 [sample:SampleAtDepth:SampleWrapper:SampleESP](DEBUG): Aggregate::initialize sample:SampleAtDepth:SampleWrapper:SampleESP 2017-12-09T02:16:56.509Z,1512785816.509 [sample:SampleAtDepth:SampleWrapper:SampleESP:TriggerESP] Running Loop=1 2017-12-09T02:16:56.905Z,1512785816.905 [sample:SampleAtDepth:SampleWrapper:SampleESP:TriggerESP](DEBUG): Initialize ReadDataComponent to sense ESPComponent.sampling 2017-12-09T02:16:58.093Z,1512785818.093 [ESPComponent](INFO): powering up ESP 2017-12-09T02:16:58.093Z,1512785818.093 [ESPComponent](INFO): manually powering up ESP 2017-12-09T02:16:58.095Z,1512785818.095 [ESPComponent](IMPORTANT): Powered ESP with command: ! echo 1 > /dev/loadA6 2017-12-09T02:16:58.096Z,1512785818.096 [ESPComponent](IMPORTANT): Starting PPPD with command:/sbin/pppd xonxoff asyncmap A0000 /dev/ttyTX1 linkname esp noauth local lock 115200 134.89.95.2:134.89.95.12 persist maxfail 0 holdoff 10 lcp-echo-interval 60 lcp-echo-failure 3 proxyarp ktune deflate 12 ms-dns 134.89.10.32 ms-dns 134.89.10.10 2017-12-09T02:16:58.185Z,1512785818.185 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadA6 2017-12-09T02:16:58.486Z,1512785818.486 [ESPComponent](IMPORTANT): Waiting for ESP to connect (timeout=150.0000) 2017-12-09T02:17:11.414Z,1512785831.414 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2017-12-09T02:17:36.407Z,1512785856.407 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2017-12-09T02:17:36.407Z,1512785856.407 [Rowe_600LCM] Communications Fault, FailCount= 3 2017-12-09T02:17:36.407Z,1512785856.407 [Rowe_600LCM](ERROR): Communications Fault 2017-12-09T02:17:36.809Z,1512785856.809 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2017-12-09T02:17:36.968Z,1512785856.968 [Rowe_600LCM](INFO): Powering down 2017-12-09T02:17:38.890Z,1512785858.890 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.4 s old, using for 20.0 s. 2017-12-09T02:17:38.891Z,1512785858.891 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-12-09T02:17:38.891Z,1512785858.891 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2017-12-09T02:17:38.892Z,1512785858.892 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2017-12-09T02:17:38.894Z,1512785858.894 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-12-09T02:17:38.894Z,1512785858.894 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2 2017-12-09T02:17:38.894Z,1512785858.894 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2017-12-09T02:17:38.954Z,1512785858.954 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2017-12-09T02:17:38.955Z,1512785858.955 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2017-12-09T02:17:38.955Z,1512785858.955 [Rowe_600LCM] No Fault, FailCount= 3 2017-12-09T02:17:38.955Z,1512785858.955 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2017-12-09T02:17:38.955Z,1512785858.955 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2017-12-09T02:17:38.989Z,1512785858.989 [Rowe_600LCM](INFO): Initializing 2017-12-09T02:17:38.989Z,1512785858.989 [Rowe_600LCM](INFO): Checking LCM 2017-12-09T02:17:38.990Z,1512785858.990 [Rowe_600LCM](INFO): LCM OK 2017-12-09T02:17:38.990Z,1512785858.990 [Rowe_600LCM](INFO): Powering up 2017-12-09T02:17:39.102Z,1512785859.102 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2017-12-09T02:17:39.102Z,1512785859.102 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2017-12-09T02:17:39.103Z,1512785859.103 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2017-12-09T02:17:39.103Z,1512785859.103 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2 2017-12-09T02:17:39.424Z,1512785859.424 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2017-12-09T02:17:39.425Z,1512785859.425 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2017-12-09T02:17:39.425Z,1512785859.425 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2017-12-09T02:17:39.425Z,1512785859.425 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2017-12-09T02:17:39.426Z,1512785859.426 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2017-12-09T02:17:39.426Z,1512785859.426 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2017-12-09T02:17:39.828Z,1512785859.828 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s. 2017-12-09T02:17:40.230Z,1512785860.230 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s. 2017-12-09T02:17:40.609Z,1512785860.609 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.1 s old, using for 20.0 s. 2017-12-09T02:17:41.010Z,1512785861.010 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.5 s old, using for 20.0 s. 2017-12-09T02:17:41.428Z,1512785861.428 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s. 2017-12-09T02:17:41.831Z,1512785861.831 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s. 2017-12-09T02:17:42.239Z,1512785862.239 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s. 2017-12-09T02:17:42.631Z,1512785862.631 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s. 2017-12-09T02:17:43.063Z,1512785863.063 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s. 2017-12-09T02:17:43.196Z,1512785863.196 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2017-12-09T02:17:43.268Z,1512785863.268 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null & 2017-12-09T02:17:43.269Z,1512785863.269 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2017-12-09T02:17:43.270Z,1512785863.270 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2017-12-09T02:17:43.271Z,1512785863.271 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2017-12-09T02:17:43.465Z,1512785863.465 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s. 2017-12-09T02:17:57.053Z,1512785877.053 [ESPComponent](IMPORTANT): ESP has connected as client 2017-12-09T02:17:57.811Z,1512785877.811 [ESPComponent](IMPORTANT): ESP sampling sequence starting (sample #1) sampling state: S_WAITING_INITIAL_PROMPT 2017-12-09T02:17:57.811Z,1512785877.811 [ESPComponent](DEBUG): Writing samplingActive=1, sampleNumber=1 2017-12-09T02:17:57.822Z,1512785877.822 [sample:SampleAtDepth:SampleWrapper:SampleESP:TriggerESP] Stopped 2017-12-09T02:17:57.822Z,1512785877.822 [sample:SampleAtDepth:SampleWrapper:SampleESP:B] Running Loop=1 2017-12-09T02:17:58.231Z,1512785878.231 [sample:SampleAtDepth:SampleWrapper:SampleESP:B](IMPORTANT): ESP sampling at 0.000000 m in nan degC water with nan ug/l chlorophyll fluorescence. 2017-12-09T02:17:58.241Z,1512785878.241 [sample:SampleAtDepth:SampleWrapper:SampleESP:B] Stopped 2017-12-09T02:17:58.241Z,1512785878.241 [sample:SampleAtDepth:SampleWrapper:SampleESP:WaitForESP] Running Loop=1 2017-12-09T02:17:58.618Z,1512785878.618 [sample:SampleAtDepth:SampleWrapper:SampleESP:WaitForESP](DEBUG): Initialize ReadDataComponent to sense ESPComponent.sample_number 2017-12-09T02:17:59.010Z,1512785879.010 [ESPComponent](IMPORTANT): ESP sampling state: S_PREPARING_SHOW_LOG 2017-12-09T02:17:59.424Z,1512785879.424 [ESPComponent](IMPORTANT): ESP sampling state: S_PREPARING_SHOW_STATUS 2017-12-09T02:18:01.012Z,1512785881.012 [ESPComponent](IMPORTANT): ESP sampling state: S_LOADING_CARTRIDGE 2017-12-09T02:18:22.205Z,1512785902.205 [ESPComponent](IMPORTANT): ESP sampling state: S_FILTERING 2017-12-09T02:18:24.610Z,1512785904.610 [ESPComponent](IMPORTANT): ESP sampling state: S_WAITING_FOR_PAUSED 2017-12-09T02:18:45.297Z,1512785925.297 [BPC1](ERROR): Failed to receive battery data 2017-12-09T02:18:45.298Z,1512785925.298 [BPC1] Communications Fault, FailCount= 1 2017-12-09T02:18:45.298Z,1512785925.298 [BPC1](ERROR): Communications Fault 2017-12-09T02:18:45.336Z,1512785925.336 [CBIT](ERROR): Communications Fault in component: BPC1 2017-12-09T02:18:46.519Z,1512785926.519 [CBIT](INFO): Clearing failed state for component BPC1 2017-12-09T02:18:46.519Z,1512785926.519 [BPC1] No Fault, FailCount= 1 2017-12-09T02:19:38.997Z,1512785978.997 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2017-12-09T02:19:38.997Z,1512785978.997 [Rowe_600LCM] Communications Fault, FailCount= 4 2017-12-09T02:19:38.997Z,1512785978.997 [Rowe_600LCM](ERROR): Communications Fault 2017-12-09T02:19:39.401Z,1512785979.401 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2017-12-09T02:19:39.588Z,1512785979.588 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.5 s old, using for 20.0 s. 2017-12-09T02:19:39.629Z,1512785979.629 [Rowe_600LCM](INFO): Powering down 2017-12-09T02:19:39.652Z,1512785979.652 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2017-12-09T02:19:39.748Z,1512785979.748 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s. 2017-12-09T02:19:40.128Z,1512785980.128 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s. 2017-12-09T02:19:40.533Z,1512785980.533 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s. 2017-12-09T02:19:40.932Z,1512785980.932 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s. 2017-12-09T02:19:40.947Z,1512785980.947 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2017-12-09T02:19:40.947Z,1512785980.947 [Rowe_600LCM] No Fault, FailCount= 4 2017-12-09T02:19:41.245Z,1512785981.245 [Rowe_600LCM](INFO): Initializing 2017-12-09T02:19:41.245Z,1512785981.245 [Rowe_600LCM](INFO): Checking LCM 2017-12-09T02:19:41.245Z,1512785981.245 [Rowe_600LCM](INFO): LCM OK 2017-12-09T02:19:41.245Z,1512785981.245 [Rowe_600LCM](INFO): Powering up 2017-12-09T02:19:41.340Z,1512785981.340 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s. 2017-12-09T02:19:41.717Z,1512785981.717 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s. 2017-12-09T02:19:42.117Z,1512785982.117 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s. 2017-12-09T02:19:42.522Z,1512785982.522 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s. 2017-12-09T02:19:42.987Z,1512785982.987 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.9 s old, using for 20.0 s. 2017-12-09T02:19:43.331Z,1512785983.331 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s. 2017-12-09T02:19:43.717Z,1512785983.717 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s. 2017-12-09T02:19:44.159Z,1512785984.159 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s. 2017-12-09T02:19:44.528Z,1512785984.528 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s. 2017-12-09T02:19:44.958Z,1512785984.958 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s. 2017-12-09T02:19:45.379Z,1512785985.379 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.3 s old, using for 20.0 s. 2017-12-09T02:19:45.482Z,1512785985.482 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2017-12-09T02:19:45.558Z,1512785985.558 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null & 2017-12-09T02:19:45.559Z,1512785985.559 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2017-12-09T02:19:45.560Z,1512785985.560 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2017-12-09T02:19:45.561Z,1512785985.561 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2017-12-09T02:19:45.735Z,1512785985.735 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s. 2017-12-09T02:20:05.309Z,1512786005.309 [BPC1](ERROR): Failed to receive battery data 2017-12-09T02:20:05.309Z,1512786005.309 [BPC1] Communications Fault, FailCount= 1 2017-12-09T02:20:05.309Z,1512786005.309 [BPC1](ERROR): Communications Fault 2017-12-09T02:20:05.366Z,1512786005.366 [CBIT](ERROR): Communications Fault in component: BPC1 2017-12-09T02:20:06.542Z,1512786006.542 [CBIT](INFO): Clearing failed state for component BPC1 2017-12-09T02:20:06.542Z,1512786006.542 [BPC1] No Fault, FailCount= 1 2017-12-09T02:20:39.564Z,1512786039.564 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-12-09T02:20:39.564Z,1512786039.564 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2017-12-09T02:20:39.564Z,1512786039.564 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2017-12-09T02:20:39.566Z,1512786039.566 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-12-09T02:20:39.566Z,1512786039.566 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3 2017-12-09T02:20:39.566Z,1512786039.566 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2017-12-09T02:20:39.579Z,1512786039.579 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2017-12-09T02:20:39.579Z,1512786039.579 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2017-12-09T02:20:39.981Z,1512786039.981 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2017-12-09T02:20:39.981Z,1512786039.981 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2017-12-09T02:20:39.981Z,1512786039.981 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2017-12-09T02:20:39.982Z,1512786039.982 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3 2017-12-09T02:20:40.285Z,1512786040.285 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2017-12-09T02:20:40.286Z,1512786040.286 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2017-12-09T02:20:40.286Z,1512786040.286 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2017-12-09T02:20:40.287Z,1512786040.287 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2017-12-09T02:20:40.287Z,1512786040.287 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2017-12-09T02:20:40.287Z,1512786040.287 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2017-12-09T02:21:21.468Z,1512786081.468 [BPC1](ERROR): Failed to parse bank A battery data 2017-12-09T02:21:21.468Z,1512786081.468 [BPC1] Data Fault, FailCount= 1 2017-12-09T02:21:21.468Z,1512786081.468 [BPC1](ERROR): Data Fault 2017-12-09T02:21:21.525Z,1512786081.525 [CBIT](ERROR): Data Fault in component: BPC1 2017-12-09T02:21:22.711Z,1512786082.711 [CBIT](INFO): Clearing failed state for component BPC1 2017-12-09T02:21:22.711Z,1512786082.711 [BPC1] No Fault, FailCount= 1 2017-12-09T02:21:30.730Z,1512786090.730 [CBIT](DEBUG): EFC running - data check-sum false 2017-12-09T02:21:42.000Z,1512786102.000 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2017-12-09T02:21:42.001Z,1512786102.001 [Rowe_600LCM] Communications Fault, FailCount= 5 2017-12-09T02:21:42.001Z,1512786102.001 [Rowe_600LCM](ERROR): Communications Fault 2017-12-09T02:21:42.246Z,1512786102.246 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s. 2017-12-09T02:21:42.260Z,1512786102.260 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2017-12-09T02:21:42.261Z,1512786102.261 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM 2017-12-09T02:21:42.405Z,1512786102.405 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2017-12-09T02:21:42.510Z,1512786102.510 [Rowe_600LCM](INFO): Powering down 2017-12-09T02:21:42.685Z,1512786102.685 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s. 2017-12-09T02:21:43.041Z,1512786103.041 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s. 2017-12-09T02:21:43.454Z,1512786103.454 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s. 2017-12-09T02:21:43.858Z,1512786103.858 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s. 2017-12-09T02:21:44.255Z,1512786104.255 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s. 2017-12-09T02:21:44.644Z,1512786104.644 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s. 2017-12-09T02:21:45.042Z,1512786105.042 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s. 2017-12-09T02:21:45.448Z,1512786105.448 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s. 2017-12-09T02:21:45.849Z,1512786105.849 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s. 2017-12-09T02:21:46.253Z,1512786106.253 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s. 2017-12-09T02:21:46.658Z,1512786106.658 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s. 2017-12-09T02:21:47.052Z,1512786107.052 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s. 2017-12-09T02:21:47.442Z,1512786107.442 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s. 2017-12-09T02:21:47.894Z,1512786107.894 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s. 2017-12-09T02:21:48.245Z,1512786108.245 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s. 2017-12-09T02:21:48.648Z,1512786108.648 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s. 2017-12-09T02:21:49.045Z,1512786109.045 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s. 2017-12-09T02:21:49.452Z,1512786109.452 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s. 2017-12-09T02:21:49.856Z,1512786109.856 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s. 2017-12-09T02:21:50.256Z,1512786110.256 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s. 2017-12-09T02:21:50.653Z,1512786110.653 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s. 2017-12-09T02:21:51.048Z,1512786111.048 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s. 2017-12-09T02:21:51.453Z,1512786111.453 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s. 2017-12-09T02:21:51.832Z,1512786111.832 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.9 s old, using for 20.0 s. 2017-12-09T02:21:52.254Z,1512786112.254 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s. 2017-12-09T02:21:52.661Z,1512786112.661 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s. 2017-12-09T02:21:53.059Z,1512786113.059 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s. 2017-12-09T02:21:53.459Z,1512786113.459 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s. 2017-12-09T02:21:53.837Z,1512786113.837 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s. 2017-12-09T02:21:54.239Z,1512786114.239 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s. 2017-12-09T02:21:54.639Z,1512786114.639 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s. 2017-12-09T02:21:55.037Z,1512786115.037 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s. 2017-12-09T02:21:55.461Z,1512786115.461 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s. 2017-12-09T02:21:55.863Z,1512786115.863 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s. 2017-12-09T02:21:56.265Z,1512786116.265 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s. 2017-12-09T02:21:56.648Z,1512786116.648 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s. 2017-12-09T02:21:57.042Z,1512786117.042 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s. 2017-12-09T02:21:57.443Z,1512786117.443 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s. 2017-12-09T02:21:57.851Z,1512786117.851 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s. 2017-12-09T02:21:58.259Z,1512786118.259 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s. 2017-12-09T02:21:58.653Z,1512786118.653 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s. 2017-12-09T02:21:59.054Z,1512786119.054 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s. 2017-12-09T02:21:59.439Z,1512786119.439 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s. 2017-12-09T02:21:59.896Z,1512786119.896 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s. 2017-12-09T02:22:00.241Z,1512786120.241 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s. 2017-12-09T02:22:00.648Z,1512786120.648 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s. 2017-12-09T02:22:01.085Z,1512786121.085 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s. 2017-12-09T02:22:01.450Z,1512786121.450 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s. 2017-12-09T02:22:10.199Z,1512786130.199 [NAL9602](FAULT): GPS failed to acquire within timeout. 2017-12-09T02:22:10.199Z,1512786130.199 [NAL9602] Data Fault, FailCount= 1 2017-12-09T02:22:10.199Z,1512786130.199 [NAL9602](ERROR): Data Fault 2017-12-09T02:22:10.283Z,1512786130.283 [CBIT](ERROR): Data Fault in component: NAL9602 2017-12-09T02:22:10.602Z,1512786130.602 [NAL9602](INFO): Powering down 2017-12-09T02:22:11.455Z,1512786131.455 [CBIT](INFO): Clearing failed state for component NAL9602 2017-12-09T02:22:11.455Z,1512786131.455 [NAL9602] No Fault, FailCount= 1 2017-12-09T02:22:22.231Z,1512786142.231 [BPC1](ERROR): Failed to receive battery data 2017-12-09T02:22:22.231Z,1512786142.231 [BPC1] Communications Fault, FailCount= 1 2017-12-09T02:22:22.231Z,1512786142.231 [BPC1](ERROR): Communications Fault 2017-12-09T02:22:22.254Z,1512786142.254 [CBIT](ERROR): Communications Fault in component: BPC1 2017-12-09T02:22:23.457Z,1512786143.457 [CBIT](INFO): Clearing failed state for component BPC1 2017-12-09T02:22:23.457Z,1512786143.457 [BPC1] No Fault, FailCount= 1 2017-12-09T02:22:40.723Z,1512786160.723 [NAL9602](INFO): Powering up NAL9602 2017-12-09T02:22:51.922Z,1512786171.922 [NAL9602](INFO): NAL9602 initialized 2017-12-09T02:23:22.759Z,1512786202.759 [BPC1](ERROR): Failed to receive battery data 2017-12-09T02:23:22.759Z,1512786202.759 [BPC1] Communications Fault, FailCount= 1 2017-12-09T02:23:22.759Z,1512786202.759 [BPC1](ERROR): Communications Fault 2017-12-09T02:23:22.819Z,1512786202.819 [CBIT](ERROR): Communications Fault in component: BPC1 2017-12-09T02:23:23.985Z,1512786203.985 [CBIT](INFO): Clearing failed state for component BPC1 2017-12-09T02:23:23.985Z,1512786203.985 [BPC1] No Fault, FailCount= 1 2017-12-09T02:23:40.490Z,1512786220.490 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-12-09T02:23:40.490Z,1512786220.490 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2017-12-09T02:23:40.490Z,1512786220.490 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2017-12-09T02:23:40.492Z,1512786220.492 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-12-09T02:23:40.492Z,1512786220.492 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4 2017-12-09T02:23:40.492Z,1512786220.492 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2017-12-09T02:23:40.544Z,1512786220.544 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2017-12-09T02:23:40.544Z,1512786220.544 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2017-12-09T02:23:40.850Z,1512786220.850 [BPC1](ERROR): Failed to parse bank B battery data 2017-12-09T02:23:40.850Z,1512786220.850 [BPC1] Data Fault, FailCount= 2 2017-12-09T02:23:40.850Z,1512786220.850 [BPC1](ERROR): Data Fault 2017-12-09T02:23:40.890Z,1512786220.890 [CBIT](ERROR): Data Fault in component: BPC1 2017-12-09T02:23:40.890Z,1512786220.890 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2017-12-09T02:23:40.890Z,1512786220.890 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2017-12-09T02:23:40.890Z,1512786220.890 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2017-12-09T02:23:40.891Z,1512786220.891 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4 2017-12-09T02:23:41.266Z,1512786221.266 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2017-12-09T02:23:41.266Z,1512786221.266 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2017-12-09T02:23:41.266Z,1512786221.266 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2017-12-09T02:23:41.267Z,1512786221.267 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2017-12-09T02:23:41.267Z,1512786221.267 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2017-12-09T02:23:41.267Z,1512786221.267 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2017-12-09T02:23:41.652Z,1512786221.652 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s. 2017-12-09T02:23:42.064Z,1512786222.064 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s. 2017-12-09T02:23:42.099Z,1512786222.099 [CBIT](INFO): Clearing failed state for component BPC1 2017-12-09T02:23:42.099Z,1512786222.099 [BPC1] No Fault, FailCount= 2 2017-12-09T02:23:42.502Z,1512786222.502 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s. 2017-12-09T02:23:42.861Z,1512786222.861 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s. 2017-12-09T02:23:43.265Z,1512786223.265 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s. 2017-12-09T02:23:43.662Z,1512786223.662 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s. 2017-12-09T02:23:44.051Z,1512786224.051 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s. 2017-12-09T02:23:44.454Z,1512786224.454 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s. 2017-12-09T02:23:44.860Z,1512786224.860 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s. 2017-12-09T02:23:45.250Z,1512786225.250 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s. 2017-12-09T02:23:45.664Z,1512786225.664 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s. 2017-12-09T02:23:46.073Z,1512786226.073 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s. 2017-12-09T02:23:46.461Z,1512786226.461 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s. 2017-12-09T02:23:46.858Z,1512786226.858 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s. 2017-12-09T02:23:47.250Z,1512786227.250 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s. 2017-12-09T02:23:47.698Z,1512786227.698 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s. 2017-12-09T02:23:48.058Z,1512786228.058 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s. 2017-12-09T02:23:48.456Z,1512786228.456 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s. 2017-12-09T02:23:48.861Z,1512786228.861 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s. 2017-12-09T02:23:49.270Z,1512786229.270 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s. 2017-12-09T02:23:49.666Z,1512786229.666 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s. 2017-12-09T02:23:50.055Z,1512786230.055 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s. 2017-12-09T02:23:50.458Z,1512786230.458 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s. 2017-12-09T02:23:50.862Z,1512786230.862 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s. 2017-12-09T02:23:51.260Z,1512786231.260 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s. 2017-12-09T02:23:51.670Z,1512786231.670 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s. 2017-12-09T02:23:52.071Z,1512786232.071 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s. 2017-12-09T02:23:52.466Z,1512786232.466 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s. 2017-12-09T02:23:52.864Z,1512786232.864 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s. 2017-12-09T02:23:53.263Z,1512786233.263 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s. 2017-12-09T02:23:53.658Z,1512786233.658 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s. 2017-12-09T02:23:54.057Z,1512786234.057 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s. 2017-12-09T02:23:54.457Z,1512786234.457 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s. 2017-12-09T02:23:54.865Z,1512786234.865 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s. 2017-12-09T02:23:55.271Z,1512786235.271 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s. 2017-12-09T02:23:55.668Z,1512786235.668 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s. 2017-12-09T02:23:56.055Z,1512786236.055 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s. 2017-12-09T02:23:56.456Z,1512786236.456 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s. 2017-12-09T02:23:56.856Z,1512786236.856 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s. 2017-12-09T02:23:57.255Z,1512786237.255 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s. 2017-12-09T02:23:57.670Z,1512786237.670 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s. 2017-12-09T02:23:58.070Z,1512786238.070 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s. 2017-12-09T02:23:58.487Z,1512786238.487 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s. 2017-12-09T02:23:58.852Z,1512786238.852 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s. 2017-12-09T02:23:59.263Z,1512786239.263 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s. 2017-12-09T02:23:59.647Z,1512786239.647 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s. 2017-12-09T02:24:00.094Z,1512786240.094 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s. 2017-12-09T02:24:00.455Z,1512786240.455 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 20.0 s old, using for 20.0 s. 2017-12-09T02:24:18.861Z,1512786258.861 [BPC1](ERROR): Failed to parse bank A battery data 2017-12-09T02:24:18.862Z,1512786258.862 [BPC1] Data Fault, FailCount= 1 2017-12-09T02:24:18.862Z,1512786258.862 [BPC1](ERROR): Data Fault 2017-12-09T02:24:18.919Z,1512786258.919 [CBIT](ERROR): Data Fault in component: BPC1 2017-12-09T02:24:20.066Z,1512786260.066 [CBIT](INFO): Clearing failed state for component BPC1 2017-12-09T02:24:20.066Z,1512786260.066 [BPC1] No Fault, FailCount= 1 2017-12-09T02:26:41.580Z,1512786401.580 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-12-09T02:26:41.580Z,1512786401.580 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2017-12-09T02:26:41.580Z,1512786401.580 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2017-12-09T02:26:41.582Z,1512786401.582 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-12-09T02:26:41.582Z,1512786401.582 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5 2017-12-09T02:26:41.582Z,1512786401.582 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2017-12-09T02:26:41.608Z,1512786401.608 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2017-12-09T02:26:41.608Z,1512786401.608 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2017-12-09T02:26:42.026Z,1512786402.026 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2017-12-09T02:26:42.026Z,1512786402.026 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2017-12-09T02:26:42.027Z,1512786402.027 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2017-12-09T02:26:42.027Z,1512786402.027 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5 2017-12-09T02:26:42.390Z,1512786402.390 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2017-12-09T02:26:42.390Z,1512786402.390 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2017-12-09T02:26:42.391Z,1512786402.391 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2017-12-09T02:26:42.391Z,1512786402.391 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2017-12-09T02:26:42.391Z,1512786402.391 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2017-12-09T02:26:42.392Z,1512786402.392 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2017-12-09T02:26:42.804Z,1512786402.804 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s. 2017-12-09T02:26:43.196Z,1512786403.196 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s. 2017-12-09T02:26:43.590Z,1512786403.590 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s. 2017-12-09T02:26:43.976Z,1512786403.976 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s. 2017-12-09T02:26:44.381Z,1512786404.381 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s. 2017-12-09T02:26:44.779Z,1512786404.779 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s. 2017-12-09T02:26:45.174Z,1512786405.174 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s. 2017-12-09T02:26:45.587Z,1512786405.587 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s. 2017-12-09T02:26:45.990Z,1512786405.990 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s. 2017-12-09T02:26:46.389Z,1512786406.389 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s. 2017-12-09T02:26:46.781Z,1512786406.781 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s. 2017-12-09T02:26:47.219Z,1512786407.219 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s. 2017-12-09T02:26:47.590Z,1512786407.590 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s. 2017-12-09T02:26:47.979Z,1512786407.979 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s. 2017-12-09T02:26:48.392Z,1512786408.392 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s. 2017-12-09T02:26:48.792Z,1512786408.792 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s. 2017-12-09T02:26:49.190Z,1512786409.190 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s. 2017-12-09T02:26:49.578Z,1512786409.578 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s. 2017-12-09T02:26:49.975Z,1512786409.975 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s. 2017-12-09T02:26:50.382Z,1512786410.382 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s. 2017-12-09T02:26:50.782Z,1512786410.782 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s. 2017-12-09T02:26:51.197Z,1512786411.197 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s. 2017-12-09T02:26:51.589Z,1512786411.589 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s. 2017-12-09T02:26:51.999Z,1512786411.999 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s. 2017-12-09T02:26:52.380Z,1512786412.380 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s. 2017-12-09T02:26:52.774Z,1512786412.774 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s. 2017-12-09T02:26:53.182Z,1512786413.182 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s. 2017-12-09T02:26:53.579Z,1512786413.579 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s. 2017-12-09T02:26:53.984Z,1512786413.984 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s. 2017-12-09T02:26:54.388Z,1512786414.388 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s. 2017-12-09T02:26:54.789Z,1512786414.789 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s. 2017-12-09T02:26:55.188Z,1512786415.188 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s. 2017-12-09T02:26:55.587Z,1512786415.587 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s. 2017-12-09T02:26:55.983Z,1512786415.983 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s. 2017-12-09T02:26:56.384Z,1512786416.384 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s. 2017-12-09T02:26:56.776Z,1512786416.776 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s. 2017-12-09T02:26:57.189Z,1512786417.189 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s. 2017-12-09T02:26:57.595Z,1512786417.595 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s. 2017-12-09T02:26:57.989Z,1512786417.989 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s. 2017-12-09T02:26:58.404Z,1512786418.404 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s. 2017-12-09T02:26:58.782Z,1512786418.782 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s. 2017-12-09T02:26:59.190Z,1512786419.190 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s. 2017-12-09T02:26:59.583Z,1512786419.583 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s. 2017-12-09T02:26:59.977Z,1512786419.977 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s. 2017-12-09T02:27:00.390Z,1512786420.390 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s. 2017-12-09T02:27:00.791Z,1512786420.791 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s. 2017-12-09T02:27:01.188Z,1512786421.188 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s. 2017-12-09T02:27:01.576Z,1512786421.576 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 20.0 s old, using for 20.0 s. 2017-12-09T02:27:02.372Z,1512786422.372 [BPC1](ERROR): Failed to receive battery data 2017-12-09T02:27:02.372Z,1512786422.372 [BPC1] Communications Fault, FailCount= 1 2017-12-09T02:27:02.372Z,1512786422.372 [BPC1](ERROR): Communications Fault 2017-12-09T02:27:02.397Z,1512786422.397 [CBIT](ERROR): Communications Fault in component: BPC1 2017-12-09T02:27:03.607Z,1512786423.607 [CBIT](INFO): Clearing failed state for component BPC1 2017-12-09T02:27:03.607Z,1512786423.607 [BPC1] No Fault, FailCount= 1 2017-12-09T02:27:08.339Z,1512786428.339 [CommandLine](IMPORTANT): got command show stack 2017-12-09T02:27:08.339Z,1512786428.339 [CommandLine](IMPORTANT): Behavior Stack: 2017-12-09T02:27:08.340Z,1512786428.340 [sample](IMPORTANT): Priority 0: sample:A.Pitch 2017-12-09T02:27:08.340Z,1512786428.340 [sample](IMPORTANT): Priority 1: sample:B.SetSpeed 2017-12-09T02:27:08.341Z,1512786428.341 [sample:SampleAtDepth](IMPORTANT): Priority 2: sample:SampleAtDepth:B.Pitch 2017-12-09T02:27:08.341Z,1512786428.341 [sample:SampleAtDepth:SampleWrapper:SampleESP](IMPORTANT): Priority 3: sample:SampleAtDepth:SampleWrapper:SampleESP:WaitForESP 2017-12-09T02:28:21.700Z,1512786501.700 [BPC1](ERROR): Failed to receive battery data 2017-12-09T02:28:21.700Z,1512786501.700 [BPC1] Communications Fault, FailCount= 1 2017-12-09T02:28:21.700Z,1512786501.700 [BPC1](ERROR): Communications Fault 2017-12-09T02:28:21.724Z,1512786501.724 [CBIT](ERROR): Communications Fault in component: BPC1 2017-12-09T02:28:22.930Z,1512786502.930 [CBIT](INFO): Clearing failed state for component BPC1 2017-12-09T02:28:22.930Z,1512786502.930 [BPC1] No Fault, FailCount= 1 2017-12-09T02:29:21.810Z,1512786561.810 [BPC1](ERROR): Failed to receive battery data 2017-12-09T02:29:21.810Z,1512786561.810 [BPC1] Communications Fault, FailCount= 1 2017-12-09T02:29:21.810Z,1512786561.810 [BPC1](ERROR): Communications Fault 2017-12-09T02:29:21.851Z,1512786561.851 [CBIT](ERROR): Communications Fault in component: BPC1 2017-12-09T02:29:23.045Z,1512786563.045 [CBIT](INFO): Clearing failed state for component BPC1 2017-12-09T02:29:23.045Z,1512786563.045 [BPC1] No Fault, FailCount= 1 2017-12-09T02:29:42.469Z,1512786582.469 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-12-09T02:29:42.469Z,1512786582.469 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2017-12-09T02:29:42.469Z,1512786582.469 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2017-12-09T02:29:42.471Z,1512786582.471 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-12-09T02:29:42.471Z,1512786582.471 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6 2017-12-09T02:29:42.471Z,1512786582.471 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2017-12-09T02:29:42.484Z,1512786582.484 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2017-12-09T02:29:42.485Z,1512786582.485 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2017-12-09T02:29:42.845Z,1512786582.845 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2017-12-09T02:29:42.845Z,1512786582.845 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2017-12-09T02:29:42.845Z,1512786582.845 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2017-12-09T02:29:42.846Z,1512786582.846 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6 2017-12-09T02:29:43.234Z,1512786583.234 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2017-12-09T02:29:43.234Z,1512786583.234 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2017-12-09T02:29:43.235Z,1512786583.235 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2017-12-09T02:29:43.235Z,1512786583.235 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2017-12-09T02:29:43.235Z,1512786583.235 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2017-12-09T02:29:43.236Z,1512786583.236 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2017-12-09T02:29:43.633Z,1512786583.633 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s. 2017-12-09T02:29:44.033Z,1512786584.033 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s. 2017-12-09T02:29:44.439Z,1512786584.439 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s. 2017-12-09T02:29:44.841Z,1512786584.841 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s. 2017-12-09T02:29:45.237Z,1512786585.237 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s. 2017-12-09T02:29:45.629Z,1512786585.629 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s. 2017-12-09T02:29:46.027Z,1512786586.027 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s. 2017-12-09T02:29:46.435Z,1512786586.435 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s. 2017-12-09T02:29:46.832Z,1512786586.832 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s. 2017-12-09T02:29:47.242Z,1512786587.242 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s. 2017-12-09T02:29:47.681Z,1512786587.681 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s. 2017-12-09T02:29:48.036Z,1512786588.036 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s. 2017-12-09T02:29:48.436Z,1512786588.436 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s. 2017-12-09T02:29:48.837Z,1512786588.837 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s. 2017-12-09T02:29:49.235Z,1512786589.235 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s. 2017-12-09T02:29:49.636Z,1512786589.636 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s. 2017-12-09T02:29:50.038Z,1512786590.038 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s. 2017-12-09T02:29:50.436Z,1512786590.436 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s. 2017-12-09T02:29:50.845Z,1512786590.845 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s. 2017-12-09T02:29:51.243Z,1512786591.243 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s. 2017-12-09T02:29:51.636Z,1512786591.636 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s. 2017-12-09T02:29:52.041Z,1512786592.041 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s. 2017-12-09T02:29:52.442Z,1512786592.442 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s. 2017-12-09T02:29:52.837Z,1512786592.837 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s. 2017-12-09T02:29:53.250Z,1512786593.250 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s. 2017-12-09T02:29:53.648Z,1512786593.648 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s. 2017-12-09T02:29:54.034Z,1512786594.034 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s. 2017-12-09T02:29:54.427Z,1512786594.427 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s. 2017-12-09T02:29:54.821Z,1512786594.821 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s. 2017-12-09T02:29:55.231Z,1512786595.231 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s. 2017-12-09T02:29:55.622Z,1512786595.622 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s. 2017-12-09T02:29:56.027Z,1512786596.027 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s. 2017-12-09T02:29:56.450Z,1512786596.450 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s. 2017-12-09T02:29:56.830Z,1512786596.830 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s. 2017-12-09T02:29:57.235Z,1512786597.235 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s. 2017-12-09T02:29:57.624Z,1512786597.624 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s. 2017-12-09T02:29:58.026Z,1512786598.026 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s. 2017-12-09T02:29:58.427Z,1512786598.427 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s. 2017-12-09T02:29:58.831Z,1512786598.831 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s. 2017-12-09T02:29:59.239Z,1512786599.239 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s. 2017-12-09T02:29:59.643Z,1512786599.643 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s. 2017-12-09T02:30:00.082Z,1512786600.082 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s. 2017-12-09T02:30:00.447Z,1512786600.447 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s. 2017-12-09T02:30:00.831Z,1512786600.831 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s. 2017-12-09T02:30:01.233Z,1512786601.233 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s. 2017-12-09T02:30:01.633Z,1512786601.633 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s. 2017-12-09T02:30:02.063Z,1512786602.063 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s. 2017-12-09T02:30:02.438Z,1512786602.438 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 20.0 s old, using for 20.0 s. 2017-12-09T02:30:23.218Z,1512786623.218 [BPC1](ERROR): Failed to receive battery data 2017-12-09T02:30:23.218Z,1512786623.218 [BPC1] Communications Fault, FailCount= 1 2017-12-09T02:30:23.218Z,1512786623.218 [BPC1](ERROR): Communications Fault 2017-12-09T02:30:23.274Z,1512786623.274 [CBIT](ERROR): Communications Fault in component: BPC1 2017-12-09T02:30:24.458Z,1512786624.458 [CBIT](INFO): Clearing failed state for component BPC1 2017-12-09T02:30:24.458Z,1512786624.458 [BPC1] No Fault, FailCount= 1 2017-12-09T02:31:30.921Z,1512786690.921 [CBIT](DEBUG): EFC running - data check-sum false 2017-12-09T02:31:42.059Z,1512786702.059 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2017-12-09T02:31:42.059Z,1512786702.059 [Rowe_600LCM] No Fault, FailCount= 5 2017-12-09T02:31:42.345Z,1512786702.345 [Rowe_600LCM](INFO): Initializing 2017-12-09T02:31:42.345Z,1512786702.345 [Rowe_600LCM](INFO): Checking LCM 2017-12-09T02:31:42.345Z,1512786702.345 [Rowe_600LCM](INFO): LCM OK 2017-12-09T02:31:42.345Z,1512786702.345 [Rowe_600LCM](INFO): Powering up 2017-12-09T02:31:46.576Z,1512786706.576 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2017-12-09T02:31:46.644Z,1512786706.644 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null & 2017-12-09T02:31:46.645Z,1512786706.645 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2017-12-09T02:31:46.646Z,1512786706.646 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2017-12-09T02:31:46.647Z,1512786706.647 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2017-12-09T02:32:02.794Z,1512786722.794 [BPC1](ERROR): Failed to receive battery data 2017-12-09T02:32:02.795Z,1512786722.795 [BPC1] Communications Fault, FailCount= 1 2017-12-09T02:32:02.795Z,1512786722.795 [BPC1](ERROR): Communications Fault 2017-12-09T02:32:02.847Z,1512786722.847 [CBIT](ERROR): Communications Fault in component: BPC1 2017-12-09T02:32:04.037Z,1512786724.037 [CBIT](INFO): Clearing failed state for component BPC1 2017-12-09T02:32:04.037Z,1512786724.037 [BPC1] No Fault, FailCount= 1 2017-12-09T02:32:43.531Z,1512786763.531 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-12-09T02:32:43.532Z,1512786763.532 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2017-12-09T02:32:43.532Z,1512786763.532 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2017-12-09T02:32:43.533Z,1512786763.533 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-12-09T02:32:43.534Z,1512786763.534 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7 2017-12-09T02:32:43.534Z,1512786763.534 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2017-12-09T02:32:43.547Z,1512786763.547 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2017-12-09T02:32:43.547Z,1512786763.547 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2017-12-09T02:32:43.937Z,1512786763.937 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2017-12-09T02:32:43.937Z,1512786763.937 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2017-12-09T02:32:43.937Z,1512786763.937 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2017-12-09T02:32:43.938Z,1512786763.938 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7 2017-12-09T02:32:44.368Z,1512786764.368 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2017-12-09T02:32:44.369Z,1512786764.369 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2017-12-09T02:32:44.369Z,1512786764.369 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2017-12-09T02:32:44.369Z,1512786764.369 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2017-12-09T02:32:44.370Z,1512786764.370 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2017-12-09T02:32:44.370Z,1512786764.370 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2017-12-09T02:32:53.478Z,1512786773.478 [NAL9602](FAULT): GPS failed to acquire within timeout. 2017-12-09T02:32:53.478Z,1512786773.478 [NAL9602] Data Fault, FailCount= 2 2017-12-09T02:32:53.478Z,1512786773.478 [NAL9602](ERROR): Data Fault 2017-12-09T02:32:53.569Z,1512786773.569 [CBIT](ERROR): Data Fault in component: NAL9602 2017-12-09T02:32:53.878Z,1512786773.878 [NAL9602](INFO): Powering down 2017-12-09T02:32:54.755Z,1512786774.755 [CBIT](INFO): Clearing failed state for component NAL9602 2017-12-09T02:32:54.755Z,1512786774.755 [NAL9602] No Fault, FailCount= 2 2017-12-09T02:33:22.313Z,1512786802.313 [BPC1](ERROR): Failed to receive battery data 2017-12-09T02:33:22.313Z,1512786802.313 [BPC1] Communications Fault, FailCount= 1 2017-12-09T02:33:22.313Z,1512786802.313 [BPC1](ERROR): Communications Fault 2017-12-09T02:33:22.349Z,1512786802.349 [CBIT](ERROR): Communications Fault in component: BPC1 2017-12-09T02:33:23.553Z,1512786803.553 [CBIT](INFO): Clearing failed state for component BPC1 2017-12-09T02:33:23.553Z,1512786803.553 [BPC1] No Fault, FailCount= 1 2017-12-09T02:33:24.278Z,1512786804.278 [NAL9602](INFO): Powering up NAL9602 2017-12-09T02:33:35.478Z,1512786815.478 [NAL9602](INFO): NAL9602 initialized 2017-12-09T02:33:58.342Z,1512786838.342 [PNI_TCM](ERROR): CRC does not match. Expected:0x28658 got:0x31654 2017-12-09T02:34:15.652Z,1512786855.652 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2017-12-09T02:34:15.653Z,1512786855.653 [Rowe_600LCM] Communications Fault, FailCount= 1 2017-12-09T02:34:15.653Z,1512786855.653 [Rowe_600LCM](ERROR): Communications Fault 2017-12-09T02:34:15.955Z,1512786855.955 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s. 2017-12-09T02:34:15.986Z,1512786855.986 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2017-12-09T02:34:16.057Z,1512786856.057 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2017-12-09T02:34:16.159Z,1512786856.159 [Rowe_600LCM](INFO): Powering down 2017-12-09T02:34:16.367Z,1512786856.367 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s. 2017-12-09T02:34:16.744Z,1512786856.744 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s. 2017-12-09T02:34:17.174Z,1512786857.174 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s. 2017-12-09T02:34:17.570Z,1512786857.570 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s. 2017-12-09T02:34:17.585Z,1512786857.585 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2017-12-09T02:34:17.585Z,1512786857.585 [Rowe_600LCM] No Fault, FailCount= 1 2017-12-09T02:34:17.773Z,1512786857.773 [Rowe_600LCM](INFO): Initializing 2017-12-09T02:34:17.773Z,1512786857.773 [Rowe_600LCM](INFO): Checking LCM 2017-12-09T02:34:17.773Z,1512786857.773 [Rowe_600LCM](INFO): LCM OK 2017-12-09T02:34:17.774Z,1512786857.774 [Rowe_600LCM](INFO): Powering up 2017-12-09T02:34:17.971Z,1512786857.971 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s. 2017-12-09T02:34:18.349Z,1512786858.349 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s. 2017-12-09T02:34:18.747Z,1512786858.747 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s. 2017-12-09T02:34:19.146Z,1512786859.146 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s. 2017-12-09T02:34:19.553Z,1512786859.553 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s. 2017-12-09T02:34:19.944Z,1512786859.944 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s. 2017-12-09T02:34:20.422Z,1512786860.422 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.9 s old, using for 20.0 s. 2017-12-09T02:34:20.773Z,1512786860.773 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s. 2017-12-09T02:34:21.169Z,1512786861.169 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s. 2017-12-09T02:34:21.553Z,1512786861.553 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s. 2017-12-09T02:34:21.966Z,1512786861.966 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2017-12-09T02:34:21.998Z,1512786861.998 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s. 2017-12-09T02:34:22.097Z,1512786862.097 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null & 2017-12-09T02:34:22.098Z,1512786862.098 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2017-12-09T02:34:22.099Z,1512786862.099 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2017-12-09T02:34:22.100Z,1512786862.100 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2017-12-09T02:34:22.398Z,1512786862.398 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s. 2017-12-09T02:34:22.769Z,1512786862.769 [BPC1](ERROR): Failed to parse bank B battery data 2017-12-09T02:34:22.769Z,1512786862.769 [BPC1] Data Fault, FailCount= 1 2017-12-09T02:34:22.769Z,1512786862.769 [BPC1](ERROR): Data Fault 2017-12-09T02:34:22.855Z,1512786862.855 [CBIT](ERROR): Data Fault in component: BPC1 2017-12-09T02:34:23.987Z,1512786863.987 [CBIT](INFO): Clearing failed state for component BPC1 2017-12-09T02:34:23.987Z,1512786863.987 [BPC1] No Fault, FailCount= 1 2017-12-09T02:35:04.227Z,1512786904.227 [BPC1](ERROR): Failed to receive battery data 2017-12-09T02:35:04.227Z,1512786904.227 [BPC1] Communications Fault, FailCount= 1 2017-12-09T02:35:04.227Z,1512786904.227 [BPC1](ERROR): Communications Fault 2017-12-09T02:35:04.276Z,1512786904.276 [CBIT](ERROR): Communications Fault in component: BPC1 2017-12-09T02:35:05.481Z,1512786905.481 [CBIT](INFO): Clearing failed state for component BPC1 2017-12-09T02:35:05.481Z,1512786905.481 [BPC1] No Fault, FailCount= 1 2017-12-09T02:35:26.224Z,1512786926.224 [BPC1](ERROR): Failed to receive battery data 2017-12-09T02:35:26.224Z,1512786926.224 [BPC1] Communications Fault, FailCount= 2 2017-12-09T02:35:26.224Z,1512786926.224 [BPC1](ERROR): Communications Fault 2017-12-09T02:35:26.263Z,1512786926.263 [CBIT](ERROR): Communications Fault in component: BPC1 2017-12-09T02:35:27.466Z,1512786927.466 [CBIT](INFO): Clearing failed state for component BPC1 2017-12-09T02:35:27.466Z,1512786927.466 [BPC1] No Fault, FailCount= 2 2017-12-09T02:35:44.525Z,1512786944.525 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-12-09T02:35:44.525Z,1512786944.525 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2017-12-09T02:35:44.525Z,1512786944.525 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2017-12-09T02:35:44.527Z,1512786944.527 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-12-09T02:35:44.527Z,1512786944.527 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8 2017-12-09T02:35:44.527Z,1512786944.527 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2017-12-09T02:35:44.540Z,1512786944.540 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2017-12-09T02:35:44.557Z,1512786944.557 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2017-12-09T02:35:44.941Z,1512786944.941 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2017-12-09T02:35:44.941Z,1512786944.941 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2017-12-09T02:35:44.942Z,1512786944.942 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2017-12-09T02:35:44.942Z,1512786944.942 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8 2017-12-09T02:35:45.326Z,1512786945.326 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2017-12-09T02:35:45.327Z,1512786945.327 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2017-12-09T02:35:45.327Z,1512786945.327 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2017-12-09T02:35:45.327Z,1512786945.327 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2017-12-09T02:35:45.328Z,1512786945.328 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2017-12-09T02:35:45.328Z,1512786945.328 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2017-12-09T02:35:48.126Z,1512786948.126 [BPC1](ERROR): Failed to receive battery data 2017-12-09T02:35:48.126Z,1512786948.126 [BPC1] Communications Fault, FailCount= 3 2017-12-09T02:35:48.126Z,1512786948.126 [BPC1](ERROR): Communications Fault 2017-12-09T02:35:48.169Z,1512786948.169 [CBIT](ERROR): Communications Fault in component: BPC1 2017-12-09T02:35:49.334Z,1512786949.334 [CBIT](INFO): Clearing failed state for component BPC1 2017-12-09T02:35:49.334Z,1512786949.334 [BPC1] No Fault, FailCount= 3 2017-12-09T02:36:18.385Z,1512786978.385 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2017-12-09T02:36:18.385Z,1512786978.385 [Rowe_600LCM] Communications Fault, FailCount= 2 2017-12-09T02:36:18.385Z,1512786978.385 [Rowe_600LCM](ERROR): Communications Fault 2017-12-09T02:36:18.513Z,1512786978.513 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s. 2017-12-09T02:36:18.547Z,1512786978.547 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2017-12-09T02:36:18.789Z,1512786978.789 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2017-12-09T02:36:18.943Z,1512786978.943 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s. 2017-12-09T02:36:18.967Z,1512786978.967 [Rowe_600LCM](INFO): Powering down 2017-12-09T02:36:19.315Z,1512786979.315 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s. 2017-12-09T02:36:19.713Z,1512786979.713 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s. 2017-12-09T02:36:20.120Z,1512786980.120 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s. 2017-12-09T02:36:20.516Z,1512786980.516 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s. 2017-12-09T02:36:20.538Z,1512786980.538 [CBIT](INFO): Clearing failed state for component Rowe_600LCM 2017-12-09T02:36:20.538Z,1512786980.538 [Rowe_600LCM] No Fault, FailCount= 2 2017-12-09T02:36:20.581Z,1512786980.581 [Rowe_600LCM](INFO): Initializing 2017-12-09T02:36:20.581Z,1512786980.581 [Rowe_600LCM](INFO): Checking LCM 2017-12-09T02:36:20.581Z,1512786980.581 [Rowe_600LCM](INFO): LCM OK 2017-12-09T02:36:20.582Z,1512786980.582 [Rowe_600LCM](INFO): Powering up 2017-12-09T02:36:20.927Z,1512786980.927 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s. 2017-12-09T02:36:21.319Z,1512786981.319 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s. 2017-12-09T02:36:21.712Z,1512786981.712 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s. 2017-12-09T02:36:22.113Z,1512786982.113 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s. 2017-12-09T02:36:22.515Z,1512786982.515 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s. 2017-12-09T02:36:22.919Z,1512786982.919 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s. 2017-12-09T02:36:23.321Z,1512786983.321 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s. 2017-12-09T02:36:23.725Z,1512786983.725 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s. 2017-12-09T02:36:24.118Z,1512786984.118 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s. 2017-12-09T02:36:24.516Z,1512786984.516 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s. 2017-12-09T02:36:24.718Z,1512786984.718 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface 2017-12-09T02:36:24.787Z,1512786984.787 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null & 2017-12-09T02:36:24.788Z,1512786984.788 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track 2017-12-09T02:36:24.789Z,1512786984.789 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity 2017-12-09T02:36:24.790Z,1512786984.790 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe 2017-12-09T02:36:24.935Z,1512786984.935 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s. 2017-12-09T02:38:07.236Z,1512787087.236 [BPC1](ERROR): Failed to receive battery data 2017-12-09T02:38:07.236Z,1512787087.236 [BPC1] Communications Fault, FailCount= 1 2017-12-09T02:38:07.236Z,1512787087.236 [BPC1](ERROR): Communications Fault 2017-12-09T02:38:07.287Z,1512787087.287 [CBIT](ERROR): Communications Fault in component: BPC1 2017-12-09T02:38:08.453Z,1512787088.453 [CBIT](INFO): Clearing failed state for component BPC1 2017-12-09T02:38:08.453Z,1512787088.453 [BPC1] No Fault, FailCount= 1 2017-12-09T02:38:20.976Z,1512787100.976 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time. 2017-12-09T02:38:20.977Z,1512787100.977 [Rowe_600LCM] Communications Fault, FailCount= 3 2017-12-09T02:38:20.977Z,1512787100.977 [Rowe_600LCM](ERROR): Communications Fault 2017-12-09T02:38:21.251Z,1512787101.251 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s. 2017-12-09T02:38:21.297Z,1512787101.297 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM 2017-12-09T02:38:21.381Z,1512787101.381 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface 2017-12-09T02:38:21.606Z,1512787101.606 [Rowe_600LCM](INFO): Powering down 2017-12-09T02:38:21.692Z,1512787101.692 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.9 s old, using for 20.0 s. 2017-12-09T02:38:22.056Z,1512787102.056 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s. 2017-12-09T02:38:22.485Z,1512787102.485 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s. 2017-12-09T02:38:22.882Z,1512787102.882 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s. 2017-12-09T02:38:23.254Z,1512787103.254 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s. 2017-12-09T02:38:23.667Z,1512787103.667 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s. 2017-12-09T02:38:24.052Z,1512787104.052 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s. 2017-12-09T02:38:24.458Z,1512787104.458 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s. 2017-12-09T02:38:24.853Z,1512787104.853 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s. 2017-12-09T02:38:25.291Z,1512787105.291 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.5 s old, using for 20.0 s. 2017-12-09T02:38:25.676Z,1512787105.676 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s. 2017-12-09T02:38:26.053Z,1512787106.053 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s. 2017-12-09T02:38:26.497Z,1512787106.497 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s. 2017-12-09T02:38:26.856Z,1512787106.856 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s. 2017-12-09T02:38:27.259Z,1512787107.259 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s. 2017-12-09T02:38:27.699Z,1512787107.699 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.9 s old, using for 20.0 s. 2017-12-09T02:38:28.091Z,1512787108.091 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.3 s old, using for 20.0 s. 2017-12-09T02:38:28.455Z,1512787108.455 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s. 2017-12-09T02:38:28.859Z,1512787108.859 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s. 2017-12-09T02:38:29.231Z,1512787109.231 [BPC1](ERROR): Failed to receive battery data 2017-12-09T02:38:29.231Z,1512787109.231 [BPC1] Communications Fault, FailCount= 2 2017-12-09T02:38:29.232Z,1512787109.232 [BPC1](ERROR): Communications Fault 2017-12-09T02:38:29.305Z,1512787109.305 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.5 s old, using for 20.0 s. 2017-12-09T02:38:29.332Z,1512787109.332 [CBIT](ERROR): Communications Fault in component: BPC1 2017-12-09T02:38:29.659Z,1512787109.659 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s. 2017-12-09T02:38:30.086Z,1512787110.086 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s. 2017-12-09T02:38:30.456Z,1512787110.456 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s. 2017-12-09T02:38:30.505Z,1512787110.505 [CBIT](INFO): Clearing failed state for component BPC1 2017-12-09T02:38:30.506Z,1512787110.506 [BPC1] No Fault, FailCount= 2 2017-12-09T02:38:30.960Z,1512787110.960 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.1 s old, using for 20.0 s. 2017-12-09T02:38:31.287Z,1512787111.287 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s. 2017-12-09T02:38:31.649Z,1512787111.649 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s. 2017-12-09T02:38:32.053Z,1512787112.053 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s. 2017-12-09T02:38:32.458Z,1512787112.458 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s. 2017-12-09T02:38:32.855Z,1512787112.855 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s. 2017-12-09T02:38:33.256Z,1512787113.256 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s. 2017-12-09T02:38:33.691Z,1512787113.691 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.9 s old, using for 20.0 s. 2017-12-09T02:38:34.071Z,1512787114.071 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s. 2017-12-09T02:38:34.456Z,1512787114.456 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s. 2017-12-09T02:38:34.902Z,1512787114.902 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.1 s old, using for 20.0 s. 2017-12-09T02:38:35.259Z,1512787115.259 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s. 2017-12-09T02:38:35.661Z,1512787115.661 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s. 2017-12-09T02:38:36.078Z,1512787116.078 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s. 2017-12-09T02:38:36.456Z,1512787116.456 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s. 2017-12-09T02:38:36.860Z,1512787116.860 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s. 2017-12-09T02:38:37.291Z,1512787117.291 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s. 2017-12-09T02:38:37.641Z,1512787117.641 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s. 2017-12-09T02:38:38.087Z,1512787118.087 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s. 2017-12-09T02:38:38.458Z,1512787118.458 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s. 2017-12-09T02:38:38.862Z,1512787118.862 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s. 2017-12-09T02:38:39.266Z,1512787119.266 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s. 2017-12-09T02:38:47.026Z,1512787127.026 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-12-09T02:38:47.026Z,1512787127.026 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2017-12-09T02:38:47.026Z,1512787127.026 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2017-12-09T02:38:47.028Z,1512787127.028 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan 2017-12-09T02:38:47.028Z,1512787127.028 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9 2017-12-09T02:38:47.028Z,1512787127.028 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault 2017-12-09T02:38:47.111Z,1512787127.111 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2017-12-09T02:38:47.111Z,1512787127.111 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator 2017-12-09T02:38:47.293Z,1512787127.293 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2017-12-09T02:38:47.293Z,1512787127.293 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2017-12-09T02:38:47.293Z,1512787127.293 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator 2017-12-09T02:38:47.293Z,1512787127.293 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9 2017-12-09T02:38:47.598Z,1512787127.598 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component. 2017-12-09T02:38:47.599Z,1512787127.599 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s. 2017-12-09T02:38:47.599Z,1512787127.599 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s. 2017-12-09T02:38:47.599Z,1512787127.599 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component. 2017-12-09T02:38:47.600Z,1512787127.600 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s. 2017-12-09T02:38:47.600Z,1512787127.600 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s. 2017-12-09T02:38:47.995Z,1512787127.995 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.0 s old, using for 20.0 s. 2017-12-09T02:38:48.467Z,1512787128.467 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.4 s old, using for 20.0 s. 2017-12-09T02:38:48.773Z,1512787128.773 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.7 s old, using for 20.0 s. 2017-12-09T02:38:49.232Z,1512787129.232 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.2 s old, using for 20.0 s. 2017-12-09T02:38:49.599Z,1512787129.599 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.6 s old, using for 20.0 s. 2017-12-09T02:38:50.029Z,1512787130.029 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.0 s old, using for 20.0 s. 2017-12-09T02:38:50.402Z,1512787130.402 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.4 s old, using for 20.0 s. 2017-12-09T02:38:50.802Z,1512787130.802 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.8 s old, using for 20.0 s. 2017-12-09T02:38:51.202Z,1512787131.202 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.2 s old, using for 20.0 s. 2017-12-09T02:38:51.627Z,1512787131.627 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.6 s old, using for 20.0 s. 2017-12-09T02:38:51.980Z,1512787131.980 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.0 s old, using for 20.0 s. 2017-12-09T02:38:52.414Z,1512787132.414 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.4 s old, using for 20.0 s. 2017-12-09T02:38:52.816Z,1512787132.816 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.8 s old, using for 20.0 s. 2017-12-09T02:38:53.195Z,1512787133.195 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.2 s old, using for 20.0 s. 2017-12-09T02:38:53.595Z,1512787133.595 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.6 s old, using for 20.0 s. 2017-12-09T02:38:53.983Z,1512787133.983 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.0 s old, using for 20.0 s. 2017-12-09T02:38:54.391Z,1512787134.391 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.4 s old, using for 20.0 s. 2017-12-09T02:38:54.805Z,1512787134.805 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.8 s old, using for 20.0 s. 2017-12-09T02:38:55.212Z,1512787135.212 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.2 s old, using for 20.0 s. 2017-12-09T02:38:55.609Z,1512787135.609 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.6 s old, using for 20.0 s. 2017-12-09T02:38:55.000Z,1512787136.000 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.0 s old, using for 20.0 s. 2017-12-09T02:38:56.425Z,1512787136.425 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.4 s old, using for 20.0 s. 2017-12-09T02:38:56.804Z,1512787136.804 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.8 s old, using for 20.0 s. 2017-12-09T02:38:57.191Z,1512787137.191 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.2 s old, using for 20.0 s. 2017-12-09T02:38:57.587Z,1512787137.587 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.6 s old, using for 20.0 s. 2017-12-09T02:38:57.984Z,1512787137.984 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.0 s