2017-06-28T01:03:43.092Z,1498611823.092 [Supervisor](DEBUG): Initializing supervisor.
2017-06-28T01:03:43.095Z,1498611823.095 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0
2017-06-28T01:03:43.095Z,1498611823.095 [SyncHandler](INFO): Protected caller Thread ID is 769
2017-06-28T01:03:43.096Z,1498611823.096 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2017-06-28T01:03:43.097Z,1498611823.097 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0
2017-06-28T01:03:43.097Z,1498611823.097 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 770
2017-06-28T01:03:43.100Z,1498611823.100 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2017-06-28T01:03:43.113Z,1498611823.113 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2017-06-28T01:03:43.114Z,1498611823.114 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0
2017-06-28T01:03:43.114Z,1498611823.114 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 771
2017-06-28T01:03:43.115Z,1498611823.115 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2017-06-28T01:03:43.116Z,1498611823.116 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0
2017-06-28T01:03:43.116Z,1498611823.116 [logger ThreadHandler](INFO): Protected caller Thread ID is 772
2017-06-28T01:03:43.118Z,1498611823.118 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2017-06-28T01:03:43.119Z,1498611823.119 [Supervisor](INFO): Looking for Config files in directory: Config/
2017-06-28T01:03:43.122Z,1498611823.122 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2017-06-28T01:03:43.309Z,1498611823.309 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2017-06-28T01:03:43.310Z,1498611823.310 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2017-06-28T01:03:43.445Z,1498611823.445 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2017-06-28T01:03:43.446Z,1498611823.446 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2017-06-28T01:03:43.779Z,1498611823.779 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2017-06-28T01:03:43.780Z,1498611823.780 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2017-06-28T01:03:43.920Z,1498611823.920 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2017-06-28T01:03:43.922Z,1498611823.922 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2017-06-28T01:03:44.008Z,1498611824.008 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2017-06-28T01:03:44.207Z,1498611824.207 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2017-06-28T01:03:44.208Z,1498611824.208 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2017-06-28T01:03:44.295Z,1498611824.295 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2017-06-28T01:03:44.295Z,1498611824.295 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2017-06-28T01:03:44.589Z,1498611824.589 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2017-06-28T01:03:44.589Z,1498611824.589 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2017-06-28T01:03:44.949Z,1498611824.949 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2017-06-28T01:03:44.950Z,1498611824.950 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2017-06-28T01:03:45.250Z,1498611825.250 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2017-06-28T01:03:45.252Z,1498611825.252 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2017-06-28T01:03:45.767Z,1498611825.767 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2017-06-28T01:03:45.768Z,1498611825.768 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2017-06-28T01:03:45.977Z,1498611825.977 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2017-06-28T01:03:45.978Z,1498611825.978 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2017-06-28T01:03:52.573Z,1498611832.573 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2017-06-28T01:03:52.573Z,1498611832.573 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2017-06-28T01:03:54.554Z,1498611834.554 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2017-06-28T01:03:54.555Z,1498611834.555 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2017-06-28T01:03:54.668Z,1498611834.668 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2017-06-28T01:03:54.670Z,1498611834.670 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2017-06-28T01:03:54.673Z,1498611834.673 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2017-06-28T01:03:54.913Z,1498611834.913 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2017-06-28T01:03:54.914Z,1498611834.914 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2017-06-28T01:03:55.019Z,1498611835.019 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2017-06-28T01:03:55.113Z,1498611835.113 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2017-06-28T01:03:55.220Z,1498611835.220 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2017-06-28T01:03:55.310Z,1498611835.310 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2017-06-28T01:03:55.440Z,1498611835.440 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2017-06-28T01:03:55.598Z,1498611835.598 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2017-06-28T01:03:55.697Z,1498611835.697 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2017-06-28T01:03:55.794Z,1498611835.794 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2017-06-28T01:03:55.883Z,1498611835.883 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2017-06-28T01:03:56.066Z,1498611836.066 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2017-06-28T01:03:56.147Z,1498611836.147 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2017-06-28T01:03:56.152Z,1498611836.152 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2017-06-28T01:03:56.319Z,1498611836.319 [SBIT](DEBUG): Construct Startup Built In Test.
2017-06-28T01:03:56.351Z,1498611836.351 [SBIT] Loaded
2017-06-28T01:03:56.351Z,1498611836.351 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2017-06-28T01:03:56.352Z,1498611836.352 [IBIT](DEBUG): Construct Initiated Built In Test.
2017-06-28T01:03:56.380Z,1498611836.380 [IBIT] Loaded
2017-06-28T01:03:56.381Z,1498611836.381 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2017-06-28T01:03:56.384Z,1498611836.384 [CBIT](DEBUG): Construct Continuous Built In Test.
2017-06-28T01:03:56.537Z,1498611836.537 [CBIT] Loaded
2017-06-28T01:03:56.538Z,1498611836.538 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2017-06-28T01:03:56.538Z,1498611836.538 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2017-06-28T01:03:56.539Z,1498611836.539 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2017-06-28T01:03:56.611Z,1498611836.611 [VerticalControl](DEBUG): Construct VerticalControl.
2017-06-28T01:03:56.713Z,1498611836.713 [VerticalControl] Loaded
2017-06-28T01:03:56.714Z,1498611836.714 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2017-06-28T01:03:56.715Z,1498611836.715 [HorizontalControl](DEBUG): Construct HorizontalControl.
2017-06-28T01:03:56.778Z,1498611836.778 [HorizontalControl] Loaded
2017-06-28T01:03:56.779Z,1498611836.779 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2017-06-28T01:03:56.779Z,1498611836.779 [SpeedControl](DEBUG): Construct SpeedControl.
2017-06-28T01:03:56.785Z,1498611836.785 [SpeedControl] Loaded
2017-06-28T01:03:56.785Z,1498611836.785 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2017-06-28T01:03:56.786Z,1498611836.786 [LoopControl](DEBUG): Construct LoopControl.
2017-06-28T01:03:56.787Z,1498611836.787 [LoopControl] Loaded
2017-06-28T01:03:56.787Z,1498611836.787 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2017-06-28T01:03:56.787Z,1498611836.787 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2017-06-28T01:03:56.788Z,1498611836.788 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2017-06-28T01:03:56.830Z,1498611836.830 [DepthRateCalculator] Loaded
2017-06-28T01:03:56.830Z,1498611836.830 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2017-06-28T01:03:56.836Z,1498611836.836 [PitchRateCalculator] Loaded
2017-06-28T01:03:56.836Z,1498611836.836 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2017-06-28T01:03:56.847Z,1498611836.847 [SpeedCalculator] Loaded
2017-06-28T01:03:56.848Z,1498611836.848 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2017-06-28T01:03:56.870Z,1498611836.870 [TempGradientCalculator] Loaded
2017-06-28T01:03:56.870Z,1498611836.870 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2017-06-28T01:03:56.886Z,1498611836.886 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2017-06-28T01:03:56.887Z,1498611836.887 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2017-06-28T01:03:56.892Z,1498611836.892 [YawRateCalculator] Loaded
2017-06-28T01:03:56.892Z,1498611836.892 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2017-06-28T01:03:56.893Z,1498611836.893 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2017-06-28T01:03:56.893Z,1498611836.893 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2017-06-28T01:03:57.017Z,1498611837.017 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2017-06-28T01:03:57.017Z,1498611837.017 [StratificationFrontDetector](DEBUG): (re)initializing
2017-06-28T01:03:57.017Z,1498611837.017 [StratificationFrontDetector] Loaded
2017-06-28T01:03:57.018Z,1498611837.018 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2017-06-28T01:03:57.042Z,1498611837.042 [DepAvgTempFrontDetector] Loaded
2017-06-28T01:03:57.042Z,1498611837.042 [ComponentRegistry](DEBUG): SyncComponent "DepAvgTempFrontDetector" handled in the control thread.
2017-06-28T01:03:57.043Z,1498611837.043 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2017-06-28T01:03:57.043Z,1498611837.043 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2017-06-28T01:03:57.335Z,1498611837.335 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2017-06-28T01:03:57.336Z,1498611837.336 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2017-06-28T01:03:57.459Z,1498611837.459 [DeadReckonUsingMultipleVelocitySources] Loaded
2017-06-28T01:03:57.459Z,1498611837.459 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2017-06-28T01:03:57.515Z,1498611837.515 [DeadReckonUsingSpeedCalculator] Loaded
2017-06-28T01:03:57.515Z,1498611837.515 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2017-06-28T01:03:57.532Z,1498611837.532 [NavChart] Loaded
2017-06-28T01:03:57.533Z,1498611837.533 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2017-06-28T01:03:57.537Z,1498611837.537 [UniversalFixResidualReporter] Loaded
2017-06-28T01:03:57.537Z,1498611837.537 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2017-06-28T01:03:57.538Z,1498611837.538 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2017-06-28T01:03:57.539Z,1498611837.539 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2017-06-28T01:03:57.548Z,1498611837.548 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2017-06-28T01:03:57.549Z,1498611837.549 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2017-06-28T01:03:57.743Z,1498611837.743 [Aanderaa_O2] Loaded
2017-06-28T01:03:57.744Z,1498611837.744 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2017-06-28T01:03:57.931Z,1498611837.931 [CTD_NeilBrown] Loaded
2017-06-28T01:03:57.931Z,1498611837.931 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2017-06-28T01:03:57.933Z,1498611837.933 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 407734E0
2017-06-28T01:03:57.933Z,1498611837.933 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 855
2017-06-28T01:03:58.293Z,1498611838.293 [CTD_Seabird] Loaded
2017-06-28T01:03:58.293Z,1498611838.293 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2017-06-28T01:03:58.294Z,1498611838.294 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 407A34E0
2017-06-28T01:03:58.295Z,1498611838.295 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 856
2017-06-28T01:03:58.310Z,1498611838.310 [PAR_Licor] Loaded
2017-06-28T01:03:58.311Z,1498611838.311 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2017-06-28T01:03:58.361Z,1498611838.361 [WetLabsBB2FL] Loaded
2017-06-28T01:03:58.362Z,1498611838.362 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2017-06-28T01:03:58.363Z,1498611838.363 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407D34E0
2017-06-28T01:03:58.363Z,1498611838.363 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 857
2017-06-28T01:03:58.364Z,1498611838.364 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2017-06-28T01:03:58.364Z,1498611838.364 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2017-06-28T01:04:02.488Z,1498611842.488 [DataOverHttps] Loaded
2017-06-28T01:04:02.489Z,1498611842.489 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2017-06-28T01:04:02.503Z,1498611842.503 [Depth_Keller] Loaded
2017-06-28T01:04:02.503Z,1498611842.503 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2017-06-28T01:04:02.508Z,1498611842.508 [DropWeight] Loaded
2017-06-28T01:04:02.508Z,1498611842.508 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2017-06-28T01:04:02.603Z,1498611842.603 [NAL9602] Loaded
2017-06-28T01:04:02.603Z,1498611842.603 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2017-06-28T01:04:02.613Z,1498611842.613 [Onboard] Loaded
2017-06-28T01:04:02.613Z,1498611842.613 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2017-06-28T01:04:02.620Z,1498611842.620 [Radio_Surface] Loaded
2017-06-28T01:04:02.620Z,1498611842.620 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2017-06-28T01:04:02.622Z,1498611842.622 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A1E4E0
2017-06-28T01:04:02.622Z,1498611842.622 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 858
2017-06-28T01:04:02.816Z,1498611842.816 [PNI_TCM] Loaded
2017-06-28T01:04:02.817Z,1498611842.817 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2017-06-28T01:04:02.881Z,1498611842.881 [Rowe_600LCM] Loaded
2017-06-28T01:04:02.881Z,1498611842.881 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2017-06-28T01:04:02.882Z,1498611842.882 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 40A4E4E0
2017-06-28T01:04:02.883Z,1498611842.883 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 859
2017-06-28T01:04:04.750Z,1498611844.750 [BPC1] Loaded
2017-06-28T01:04:04.750Z,1498611844.750 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2017-06-28T01:04:04.751Z,1498611844.751 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2017-06-28T01:04:04.751Z,1498611844.751 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2017-06-28T01:04:04.877Z,1498611844.877 [BuoyancyServo] Loaded
2017-06-28T01:04:04.877Z,1498611844.877 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2017-06-28T01:04:04.890Z,1498611844.890 [ElevatorServo] Loaded
2017-06-28T01:04:04.890Z,1498611844.890 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2017-06-28T01:04:04.902Z,1498611844.902 [MassServo] Loaded
2017-06-28T01:04:04.902Z,1498611844.902 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2017-06-28T01:04:04.914Z,1498611844.914 [RudderServo] Loaded
2017-06-28T01:04:04.914Z,1498611844.914 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2017-06-28T01:04:04.926Z,1498611844.926 [ThrusterServo] Loaded
2017-06-28T01:04:04.926Z,1498611844.926 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2017-06-28T01:04:04.927Z,1498611844.927 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2017-06-28T01:04:04.927Z,1498611844.927 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2017-06-28T01:04:05.064Z,1498611845.064 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2017-06-28T01:04:05.065Z,1498611845.065 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2017-06-28T01:04:05.106Z,1498611845.106 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2017-06-28T01:04:05.110Z,1498611845.110 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2017-06-28T01:04:05.110Z,1498611845.110 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2017-06-28T01:04:05.117Z,1498611845.117 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2017-06-28T01:04:05.119Z,1498611845.119 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AFC4E0
2017-06-28T01:04:05.119Z,1498611845.119 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 860
2017-06-28T01:04:05.124Z,1498611845.124 [Supervisor](INFO): Main Thread ID is 765
2017-06-28T01:04:05.124Z,1498611845.124 [Supervisor](DEBUG): Running supervisor.
2017-06-28T01:04:05.125Z,1498611845.125 [CommandLine ThreadHandler](INFO): Handler Thread ID is 861
2017-06-28T01:04:05.127Z,1498611845.127 [controlThread ThreadHandler](INFO): Handler Thread ID is 862
2017-06-28T01:04:05.128Z,1498611845.128 [controlThread](DEBUG): Initializing ControlThread
2017-06-28T01:04:05.129Z,1498611845.129 [SBIT](INFO): Initialize SBIT Component.
2017-06-28T01:04:05.129Z,1498611845.129 [SBIT](IMPORTANT): git: 2017-04-25-1-g5be4203
2017-06-28T01:04:05.129Z,1498611845.129 [SBIT](INFO): git hash: 5be4203e99563ce232d7cf111035ff7c729bf400
2017-06-28T01:04:05.131Z,1498611845.131 [SBIT](INFO): Kernel Reporting Different Release From Configuration.
Kernel Expected: 2.6.27.8
Kernel Reported: 2.6.27.8-00011-g2bc81df-dirty
2017-06-28T01:04:05.132Z,1498611845.132 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #646 PREEMPT Thu Feb 11 17:01:12 PST 2016
2017-06-28T01:04:05.133Z,1498611845.133 [SBIT](INFO): Beginning SBIT in 32.000000 seconds.
2017-06-28T01:04:05.133Z,1498611845.133 [IBIT](INFO): Initialize IBIT Component.
2017-06-28T01:04:05.134Z,1498611845.134 [CBIT](DEBUG): Initialize CBIT Component.
2017-06-28T01:04:05.135Z,1498611845.135 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2017-06-28T01:04:05.135Z,1498611845.135 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2017-06-28T01:04:05.136Z,1498611845.136 [logger ThreadHandler](INFO): Handler Thread ID is 863
2017-06-28T01:04:05.164Z,1498611845.164 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 864
2017-06-28T01:04:05.165Z,1498611845.165 [CTD_NeilBrown](INFO): Powering down
2017-06-28T01:04:05.200Z,1498611845.200 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 865
2017-06-28T01:04:05.200Z,1498611845.200 [CTD_Seabird](INFO): Initializing
2017-06-28T01:04:05.232Z,1498611845.232 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2017-06-28T01:04:05.234Z,1498611845.234 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2017-06-28T01:04:05.235Z,1498611845.235 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2017-06-28T01:04:05.236Z,1498611845.236 [LoopControl](DEBUG): Initialize LoopControlComponent.
2017-06-28T01:04:05.237Z,1498611845.237 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-06-28T01:04:05.237Z,1498611845.237 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2017-06-28T01:04:05.237Z,1498611845.237 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2017-06-28T01:04:05.238Z,1498611845.238 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2017-06-28T01:04:05.239Z,1498611845.239 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2017-06-28T01:04:05.240Z,1498611845.240 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2017-06-28T01:04:05.241Z,1498611845.241 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-06-28T01:04:05.242Z,1498611845.242 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-06-28T01:04:05.242Z,1498611845.242 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-06-28T01:04:05.242Z,1498611845.242 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-06-28T01:04:05.243Z,1498611845.243 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-06-28T01:04:05.244Z,1498611845.244 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-06-28T01:04:05.244Z,1498611845.244 [NavChart](DEBUG): Initialize NavChart Navigation.
2017-06-28T01:04:05.244Z,1498611845.244 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2017-06-28T01:04:05.256Z,1498611845.256 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2017-06-28T01:04:05.273Z,1498611845.273 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 867
2017-06-28T01:04:05.281Z,1498611845.281 [WetLabsBB2FL](INFO): Powering down
2017-06-28T01:04:05.317Z,1498611845.317 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 868
2017-06-28T01:04:05.323Z,1498611845.323 [Radio_Surface](INFO): Powering up
2017-06-28T01:04:05.324Z,1498611845.324 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2017-06-28T01:04:05.344Z,1498611845.344 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 869
2017-06-28T01:04:05.345Z,1498611845.345 [Rowe_600LCM](INFO): Initializing
2017-06-28T01:04:05.346Z,1498611845.346 [Rowe_600LCM](INFO): Checking LCM
2017-06-28T01:04:05.346Z,1498611845.346 [Rowe_600LCM](INFO): LCM OK
2017-06-28T01:04:05.346Z,1498611845.346 [Rowe_600LCM](INFO): Powering up
2017-06-28T01:04:05.355Z,1498611845.355 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2017-06-28T01:04:05.360Z,1498611845.360 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 870
2017-06-28T01:04:05.368Z,1498611845.368 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2017-06-28T01:04:05.368Z,1498611845.368 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2017-06-28T01:04:05.369Z,1498611845.369 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2017-06-28T01:04:05.369Z,1498611845.369 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2017-06-28T01:04:05.369Z,1498611845.369 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2017-06-28T01:04:05.369Z,1498611845.369 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2017-06-28T01:04:05.369Z,1498611845.369 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2017-06-28T01:04:05.370Z,1498611845.370 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2017-06-28T01:04:05.370Z,1498611845.370 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2017-06-28T01:04:05.370Z,1498611845.370 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2017-06-28T01:04:05.370Z,1498611845.370 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2017-06-28T01:04:05.370Z,1498611845.370 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2017-06-28T01:04:05.371Z,1498611845.371 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2017-06-28T01:04:05.371Z,1498611845.371 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2017-06-28T01:04:05.371Z,1498611845.371 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2017-06-28T01:04:05.371Z,1498611845.371 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2017-06-28T01:04:05.365Z,1498611845.365 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2017-06-28T01:04:05.459Z,1498611845.459 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2017-06-28T01:04:05.460Z,1498611845.460 [Default:A.Wait](DEBUG): Construct Wait.
2017-06-28T01:04:05.462Z,1498611845.462 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2017-06-28T01:04:05.518Z,1498611845.518 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2017-06-28T01:04:05.518Z,1498611845.518 [CTD_Seabird](INFO): Powering down
2017-06-28T01:04:05.548Z,1498611845.548 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2017-06-28T01:04:05.551Z,1498611845.551 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2017-06-28T01:04:05.573Z,1498611845.573 [Default:E.Execute](DEBUG): Construct Execute.
2017-06-28T01:04:05.576Z,1498611845.576 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
xmlns:Guidance="Tethys/Guidance"
xmlns:Units="Tethys/Units"
xmlns:Universal="Tethys/Universal"
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Sequence/>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Sequence/>
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Sequence/>
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn 300</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2017-06-28T01:04:05.589Z,1498611845.589 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,PAR_Licor,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DepAvgTempFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2017-06-28T01:04:05.674Z,1498611845.674 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2017-06-28T01:04:06.174Z,1498611846.174 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-06-28T01:04:06.335Z,1498611846.335 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-06-28T01:04:06.446Z,1498611846.446 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-06-28T01:04:06.454Z,1498611846.454 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-06-28T01:04:06.468Z,1498611846.468 [ElevatorServo](DEBUG): Initializing EZServoServo.
2017-06-28T01:04:06.474Z,1498611846.474 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2017-06-28T01:04:06.480Z,1498611846.480 [MassServo](DEBUG): Initializing EZServoServo.
2017-06-28T01:04:06.486Z,1498611846.486 [MassServo](DEBUG): Initializing MassServo.
2017-06-28T01:04:06.496Z,1498611846.496 [RudderServo](DEBUG): Initializing EZServoServo.
2017-06-28T01:04:06.502Z,1498611846.502 [RudderServo](DEBUG): Initializing RudderServo.
2017-06-28T01:04:06.508Z,1498611846.508 [ThrusterServo](DEBUG): Initializing EZServoServo.
2017-06-28T01:04:06.514Z,1498611846.514 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2017-06-28T01:04:06.955Z,1498611846.955 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-06-28T01:04:06.956Z,1498611846.956 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-06-28T01:04:07.303Z,1498611847.303 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2017-06-28T01:04:07.303Z,1498611847.303 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2017-06-28T01:04:07.303Z,1498611847.303 [BuoyancyServo] Communications Fault, FailCount= 1
2017-06-28T01:04:07.303Z,1498611847.303 [BuoyancyServo](ERROR): Communications Fault
2017-06-28T01:04:07.535Z,1498611847.535 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2017-06-28T01:04:07.604Z,1498611847.604 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-06-28T01:04:07.605Z,1498611847.605 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-06-28T01:04:07.627Z,1498611847.627 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2017-06-28T01:04:07.629Z,1498611847.629 [BuoyancyServo](INFO): Powering down
2017-06-28T01:04:07.954Z,1498611847.954 [NAL9602](INFO): Powering up NAL9602
2017-06-28T01:04:08.000Z,1498611848.000 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-06-28T01:04:08.001Z,1498611848.001 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-06-28T01:04:08.413Z,1498611848.413 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-06-28T01:04:08.414Z,1498611848.414 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-06-28T01:04:08.861Z,1498611848.861 [Aanderaa_O2](INFO): Powering down
2017-06-28T01:04:08.991Z,1498611848.991 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-06-28T01:04:08.992Z,1498611848.992 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-06-28T01:04:09.235Z,1498611849.235 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-06-28T01:04:09.236Z,1498611849.236 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-06-28T01:04:09.625Z,1498611849.625 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-06-28T01:04:09.810Z,1498611849.810 [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-06-28T01:04:09.836Z,1498611849.836 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2017-06-28T01:04:09.836Z,1498611849.836 [BuoyancyServo] No Fault, FailCount= 1
2017-06-28T01:04:10.047Z,1498611850.047 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-06-28T01:04:10.061Z,1498611850.061 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-06-28T01:04:10.595Z,1498611850.595 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-06-28T01:04:10.596Z,1498611850.596 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-06-28T01:04:10.597Z,1498611850.597 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-06-28T01:04:18.755Z,1498611858.755 [NAL9602](INFO): NAL9602 initialized
2017-06-28T01:04:37.640Z,1498611877.640 [SBIT](IMPORTANT): Beginning Startup BIT
2017-06-28T01:04:37.652Z,1498611877.652 [CBIT](IMPORTANT): Beginning ground fault scan
2017-06-28T01:04:48.297Z,1498611888.297 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 4.657929
CHAN A1 (24V): 0.027450
CHAN A2 (12V): 4.697929
CHAN A3 (5V): -0.011630
CHAN B0 (3.3V): 0.000666
CHAN B1 (3.15aV): -0.000935
CHAN B2 (3.15bV): -0.001038
CHAN B3 (GND): -0.000015
OPEN: -0.000342
Full Scale Calc: 4.765 mA, -1.589 mA
2017-06-28T01:05:29.813Z,1498611929.813 [BPC1](ERROR): Failed to parse bank A battery data
2017-06-28T01:05:29.813Z,1498611929.813 [BPC1] Data Fault, FailCount= 1
2017-06-28T01:05:29.813Z,1498611929.813 [BPC1](ERROR): Data Fault
2017-06-28T01:05:29.850Z,1498611929.850 [CBIT](ERROR): Data Fault in component: BPC1
2017-06-28T01:05:31.044Z,1498611931.044 [CBIT](INFO): Clearing failed state for component BPC1
2017-06-28T01:05:31.045Z,1498611931.045 [BPC1] No Fault, FailCount= 1
2017-06-28T01:05:31.852Z,1498611931.852 [SBIT](IMPORTANT): SBIT PASSED
2017-06-28T01:05:32.243Z,1498611932.243 [MissionManager](IMPORTANT): Started mission Startup
2017-06-28T01:05:32.243Z,1498611932.243 [Startup] Running Loop=1
2017-06-28T01:05:32.243Z,1498611932.243 [Startup](DEBUG): Aggregate::initialize Startup
2017-06-28T01:05:32.243Z,1498611932.243 [Startup:A.GoToSurface] Running Loop=1
2017-06-28T01:05:32.243Z,1498611932.243 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2017-06-28T01:05:32.244Z,1498611932.244 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2017-06-28T01:05:32.245Z,1498611932.245 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2017-06-28T01:05:32.245Z,1498611932.245 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2017-06-28T01:05:32.245Z,1498611932.245 [Startup:A.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2017-06-28T01:05:32.246Z,1498611932.246 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2017-06-28T01:05:32.262Z,1498611932.262 [Startup:StartupSatComms] Running Loop=1
2017-06-28T01:05:32.262Z,1498611932.262 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2017-06-28T01:05:32.262Z,1498611932.262 [Startup:StartupSatComms:A] Running Loop=1
2017-06-28T01:05:32.629Z,1498611932.629 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2017-06-28T01:05:39.338Z,1498611939.338 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003806
2017-06-28T01:06:06.078Z,1498611966.078 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2017-06-28T01:06:06.079Z,1498611966.079 [Rowe_600LCM] Communications Fault, FailCount= 1
2017-06-28T01:06:06.079Z,1498611966.079 [Rowe_600LCM](ERROR): Communications Fault
2017-06-28T01:06:06.176Z,1498611966.176 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2017-06-28T01:06:06.190Z,1498611966.190 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2017-06-28T01:06:06.483Z,1498611966.483 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-06-28T01:06:06.617Z,1498611966.617 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2017-06-28T01:06:06.711Z,1498611966.711 [Rowe_600LCM](INFO): Powering down
2017-06-28T01:06:06.990Z,1498611966.990 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-06-28T01:06:07.387Z,1498611967.387 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-06-28T01:06:07.787Z,1498611967.787 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-06-28T01:06:08.194Z,1498611968.194 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-06-28T01:06:08.208Z,1498611968.208 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2017-06-28T01:06:08.208Z,1498611968.208 [Rowe_600LCM] No Fault, FailCount= 1
2017-06-28T01:06:08.314Z,1498611968.314 [Rowe_600LCM](INFO): Initializing
2017-06-28T01:06:08.315Z,1498611968.315 [Rowe_600LCM](INFO): Checking LCM
2017-06-28T01:06:08.315Z,1498611968.315 [Rowe_600LCM](INFO): LCM OK
2017-06-28T01:06:08.315Z,1498611968.315 [Rowe_600LCM](INFO): Powering up
2017-06-28T01:06:08.590Z,1498611968.590 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-06-28T01:06:08.969Z,1498611968.969 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-06-28T01:06:09.398Z,1498611969.398 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-06-28T01:06:09.807Z,1498611969.807 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-06-28T01:06:10.196Z,1498611970.196 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-06-28T01:06:10.604Z,1498611970.604 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-06-28T01:06:10.979Z,1498611970.979 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-06-28T01:06:11.378Z,1498611971.378 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-06-28T01:06:11.784Z,1498611971.784 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-06-28T01:06:12.239Z,1498611972.239 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-06-28T01:06:12.432Z,1498611972.432 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-06-28T01:06:12.502Z,1498611972.502 [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-06-28T01:06:12.504Z,1498611972.504 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-06-28T01:06:12.505Z,1498611972.505 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-06-28T01:06:12.506Z,1498611972.506 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-06-28T01:06:12.626Z,1498611972.626 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2017-06-28T01:06:25.772Z,1498611985.772 [BPC1](ERROR): Failed to parse bank B battery data
2017-06-28T01:06:25.772Z,1498611985.772 [BPC1] Data Fault, FailCount= 1
2017-06-28T01:06:25.772Z,1498611985.772 [BPC1](ERROR): Data Fault
2017-06-28T01:06:25.819Z,1498611985.819 [CBIT](ERROR): Data Fault in component: BPC1
2017-06-28T01:06:27.042Z,1498611987.042 [CBIT](INFO): Clearing failed state for component BPC1
2017-06-28T01:06:27.042Z,1498611987.042 [BPC1] No Fault, FailCount= 1
2017-06-28T01:06:32.580Z,1498611992.580 [Startup:StartupSatComms:A](INFO): Timed out from 2017-06-28T01:05:32.3Z
2017-06-28T01:06:32.580Z,1498611992.580 [Startup:StartupSatComms:A] Stopped
2017-06-28T01:06:32.580Z,1498611992.580 [Startup:StartupSatComms:B] Running Loop=1
2017-06-28T01:06:32.998Z,1498611992.998 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2017-06-28T01:06:37.533Z,1498611997.533 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20170627T195854/Courier0108.lzma
2017-06-28T01:06:38.316Z,1498611998.316 [DataOverHttps](INFO): Moved sent file to Logs/20170627T195854/Courier0108.lzma.bak
2017-06-28T01:06:38.316Z,1498611998.316 [DataOverHttps](INFO): SBD MOMSN=5047106
2017-06-28T01:06:47.965Z,1498612007.965 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20170627T195854/Express0109.lzma
2017-06-28T01:06:48.737Z,1498612008.737 [DataOverHttps](INFO): Moved sent file to Logs/20170627T195854/Express0109.lzma.bak
2017-06-28T01:06:48.737Z,1498612008.737 [DataOverHttps](INFO): SBD MOMSN=5047108
2017-06-28T01:06:58.433Z,1498612018.433 [DataOverHttps](INFO): Sending 672 bytes from file Logs/20170628T010343/Express0001.lzma
2017-06-28T01:06:59.081Z,1498612019.081 [DataOverHttps](INFO): Moved sent file to Logs/20170628T010343/Express0001.lzma.bak
2017-06-28T01:06:59.081Z,1498612019.081 [DataOverHttps](INFO): SBD MOMSN=5047110
2017-06-28T01:06:59.548Z,1498612019.548 [Startup:StartupSatComms:B] Stopped
2017-06-28T01:06:59.548Z,1498612019.548 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2017-06-28T01:06:59.548Z,1498612019.548 [Startup:StartupSatComms] Stopped
2017-06-28T01:06:59.548Z,1498612019.548 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2017-06-28T01:06:59.549Z,1498612019.549 [Startup](INFO): Completed Startup
2017-06-28T01:06:59.549Z,1498612019.549 [MissionManager](INFO): Startup is completed.
2017-06-28T01:06:59.549Z,1498612019.549 [MissionManager](INFO): Uninitializing Mission Startup
2017-06-28T01:06:59.549Z,1498612019.549 [Startup] Stopped
2017-06-28T01:06:59.550Z,1498612019.550 [Startup](DEBUG): Aggregate::uninitialize Startup
2017-06-28T01:06:59.550Z,1498612019.550 [Startup:A.GoToSurface] Stopped
2017-06-28T01:06:59.550Z,1498612019.550 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2017-06-28T01:07:00.017Z,1498612020.017 [MissionManager](IMPORTANT): Started mission Default
2017-06-28T01:07:00.017Z,1498612020.017 [Default] Running Loop=1
2017-06-28T01:07:00.017Z,1498612020.017 [Default](DEBUG): Aggregate::initialize Default
2017-06-28T01:07:00.017Z,1498612020.017 [Default:B.GoToSurface] Running Loop=1
2017-06-28T01:07:00.017Z,1498612020.017 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2017-06-28T01:07:00.017Z,1498612020.017 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2017-06-28T01:07:00.018Z,1498612020.018 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2017-06-28T01:07:00.018Z,1498612020.018 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2017-06-28T01:07:00.018Z,1498612020.018 [Default:B.GoToSurface](DEBUG): No pitch timeout specified. Using default value of 20.000000 seconds.
2017-06-28T01:07:00.019Z,1498612020.019 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2017-06-28T01:07:00.019Z,1498612020.019 [Default:A.Wait] Running Loop=1
2017-06-28T01:07:00.019Z,1498612020.019 [Default:A.Wait](DEBUG): Initialize Wait Component.
2017-06-28T01:07:05.573Z,1498612025.573 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-06-28T01:07:05.573Z,1498612025.573 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2017-06-28T01:07:05.573Z,1498612025.573 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-06-28T01:07:05.575Z,1498612025.575 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-06-28T01:07:05.575Z,1498612025.575 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2017-06-28T01:07:05.575Z,1498612025.575 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-06-28T01:07:05.587Z,1498612025.587 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-06-28T01:07:05.587Z,1498612025.587 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-06-28T01:07:05.976Z,1498612025.976 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-06-28T01:07:05.976Z,1498612025.976 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2017-06-28T01:07:05.976Z,1498612025.976 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-06-28T01:07:05.976Z,1498612025.976 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2017-06-28T01:07:06.347Z,1498612026.347 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-06-28T01:07:06.347Z,1498612026.347 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-06-28T01:07:06.348Z,1498612026.348 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-06-28T01:07:06.348Z,1498612026.348 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-06-28T01:07:06.349Z,1498612026.349 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-06-28T01:07:06.349Z,1498612026.349 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-06-28T01:07:13.152Z,1498612033.152 [Default:A.Wait](INFO): Done Waiting.
2017-06-28T01:07:13.169Z,1498612033.169 [Default:A.Wait] Stopped
2017-06-28T01:07:13.169Z,1498612033.169 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2017-06-28T01:07:13.558Z,1498612033.558 [Default:CheckIn] Running Loop=1
2017-06-28T01:07:13.558Z,1498612033.558 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-06-28T01:07:13.558Z,1498612033.558 [Default:CheckIn:Read_GPS] Running Loop=1
2017-06-28T01:07:13.964Z,1498612033.964 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2017-06-28T01:08:08.877Z,1498612088.877 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2017-06-28T01:08:08.877Z,1498612088.877 [Rowe_600LCM] Communications Fault, FailCount= 2
2017-06-28T01:08:08.877Z,1498612088.877 [Rowe_600LCM](ERROR): Communications Fault
2017-06-28T01:08:09.184Z,1498612089.184 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2017-06-28T01:08:09.282Z,1498612089.282 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-06-28T01:08:09.323Z,1498612089.323 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2017-06-28T01:08:09.410Z,1498612089.410 [Rowe_600LCM](INFO): Powering down
2017-06-28T01:08:09.593Z,1498612089.593 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2017-06-28T01:08:09.961Z,1498612089.961 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-06-28T01:08:10.371Z,1498612090.371 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-06-28T01:08:10.769Z,1498612090.769 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-06-28T01:08:10.800Z,1498612090.800 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2017-06-28T01:08:10.800Z,1498612090.800 [Rowe_600LCM] No Fault, FailCount= 2
2017-06-28T01:08:11.016Z,1498612091.016 [Rowe_600LCM](INFO): Initializing
2017-06-28T01:08:11.017Z,1498612091.017 [Rowe_600LCM](INFO): Checking LCM
2017-06-28T01:08:11.017Z,1498612091.017 [Rowe_600LCM](INFO): LCM OK
2017-06-28T01:08:11.017Z,1498612091.017 [Rowe_600LCM](INFO): Powering up
2017-06-28T01:08:11.183Z,1498612091.183 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-06-28T01:08:11.577Z,1498612091.577 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-06-28T01:08:11.971Z,1498612091.971 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-06-28T01:08:12.369Z,1498612092.369 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-06-28T01:08:12.773Z,1498612092.773 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-06-28T01:08:13.186Z,1498612093.186 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-06-28T01:08:13.580Z,1498612093.580 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-06-28T01:08:13.991Z,1498612093.991 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-06-28T01:08:14.397Z,1498612094.397 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-06-28T01:08:14.778Z,1498612094.778 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-06-28T01:08:15.114Z,1498612095.114 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-06-28T01:08:15.239Z,1498612095.239 [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-06-28T01:08:15.241Z,1498612095.241 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-06-28T01:08:15.242Z,1498612095.242 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-06-28T01:08:15.243Z,1498612095.243 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-06-28T01:08:15.284Z,1498612095.284 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.5 s old, using for 20.0 s.
2017-06-28T01:08:15.605Z,1498612095.605 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2017-06-28T01:09:21.934Z,1498612161.934 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2017-06-28T01:10:06.775Z,1498612206.775 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-06-28T01:10:06.776Z,1498612206.776 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2017-06-28T01:10:06.776Z,1498612206.776 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-06-28T01:10:06.777Z,1498612206.777 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-06-28T01:10:06.778Z,1498612206.778 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2017-06-28T01:10:06.778Z,1498612206.778 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-06-28T01:10:06.791Z,1498612206.791 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-06-28T01:10:06.791Z,1498612206.791 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-06-28T01:10:07.177Z,1498612207.177 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-06-28T01:10:07.178Z,1498612207.178 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2017-06-28T01:10:07.178Z,1498612207.178 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-06-28T01:10:07.178Z,1498612207.178 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2017-06-28T01:10:07.570Z,1498612207.570 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-06-28T01:10:07.571Z,1498612207.571 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-06-28T01:10:07.571Z,1498612207.571 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-06-28T01:10:07.572Z,1498612207.572 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-06-28T01:10:07.577Z,1498612207.577 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-06-28T01:10:07.578Z,1498612207.578 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-06-28T01:10:20.738Z,1498612220.738 [BPC1](ERROR): Failed to receive battery data
2017-06-28T01:10:20.738Z,1498612220.738 [BPC1] Communications Fault, FailCount= 1
2017-06-28T01:10:20.738Z,1498612220.738 [BPC1](ERROR): Communications Fault
2017-06-28T01:10:20.760Z,1498612220.760 [CBIT](ERROR): Communications Fault in component: BPC1
2017-06-28T01:10:21.969Z,1498612221.969 [CBIT](INFO): Clearing failed state for component BPC1
2017-06-28T01:10:21.969Z,1498612221.969 [BPC1] No Fault, FailCount= 1
2017-06-28T01:11:09.506Z,1498612269.506 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2017-06-28T01:11:09.506Z,1498612269.506 [Rowe_600LCM] Communications Fault, FailCount= 3
2017-06-28T01:11:09.506Z,1498612269.506 [Rowe_600LCM](ERROR): Communications Fault
2017-06-28T01:11:09.529Z,1498612269.529 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2017-06-28T01:11:09.543Z,1498612269.543 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2017-06-28T01:11:09.888Z,1498612269.888 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.7 s old, using for 20.0 s.
2017-06-28T01:11:09.912Z,1498612269.912 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-06-28T01:11:10.052Z,1498612270.052 [Rowe_600LCM](INFO): Powering down
2017-06-28T01:11:10.299Z,1498612270.299 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.1 s old, using for 20.0 s.
2017-06-28T01:11:10.702Z,1498612270.702 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.5 s old, using for 20.0 s.
2017-06-28T01:11:11.100Z,1498612271.100 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.9 s old, using for 20.0 s.
2017-06-28T01:11:11.508Z,1498612271.508 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.3 s old, using for 20.0 s.
2017-06-28T01:11:11.552Z,1498612271.552 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2017-06-28T01:11:11.552Z,1498612271.552 [Rowe_600LCM] No Fault, FailCount= 3
2017-06-28T01:11:11.658Z,1498612271.658 [Rowe_600LCM](INFO): Initializing
2017-06-28T01:11:11.659Z,1498612271.658 [Rowe_600LCM](INFO): Checking LCM
2017-06-28T01:11:11.659Z,1498612271.658 [Rowe_600LCM](INFO): LCM OK
2017-06-28T01:11:11.659Z,1498612271.659 [Rowe_600LCM](INFO): Powering up
2017-06-28T01:11:11.911Z,1498612271.911 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-06-28T01:11:12.306Z,1498612272.306 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.1 s old, using for 20.0 s.
2017-06-28T01:11:12.713Z,1498612272.713 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-06-28T01:11:13.105Z,1498612273.105 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.9 s old, using for 20.0 s.
2017-06-28T01:11:13.495Z,1498612273.495 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.3 s old, using for 20.0 s.
2017-06-28T01:11:13.909Z,1498612273.909 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.7 s old, using for 20.0 s.
2017-06-28T01:11:14.285Z,1498612274.285 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.1 s old, using for 20.0 s.
2017-06-28T01:11:14.711Z,1498612274.711 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.5 s old, using for 20.0 s.
2017-06-28T01:11:15.112Z,1498612275.112 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-06-28T01:11:15.521Z,1498612275.521 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-06-28T01:11:15.751Z,1498612275.751 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-06-28T01:11:15.815Z,1498612275.815 [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-06-28T01:11:15.817Z,1498612275.817 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-06-28T01:11:15.818Z,1498612275.818 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-06-28T01:11:15.819Z,1498612275.819 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-06-28T01:11:15.919Z,1498612275.919 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2017-06-28T01:12:13.835Z,1498612333.835 [Default:CheckIn:Read_GPS](INFO): Timed out from 2017-06-28T01:07:13.6Z
2017-06-28T01:12:13.835Z,1498612333.835 [Default:CheckIn:Read_GPS] Stopped
2017-06-28T01:12:13.835Z,1498612333.835 [Default:CheckIn:Read_Iridium] Running Loop=1
2017-06-28T01:12:14.292Z,1498612334.292 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2017-06-28T01:12:18.616Z,1498612338.616 [DataOverHttps](INFO): Sending 195 bytes from file Logs/20170628T010343/Courier0004.lzma
2017-06-28T01:12:19.354Z,1498612339.354 [DataOverHttps](INFO): Moved sent file to Logs/20170628T010343/Courier0004.lzma.bak
2017-06-28T01:12:19.354Z,1498612339.354 [DataOverHttps](INFO): SBD MOMSN=5047123
2017-06-28T01:12:28.825Z,1498612348.825 [DataOverHttps](INFO): Sending 246 bytes from file Logs/20170628T010343/Express0005.lzma
2017-06-28T01:12:29.563Z,1498612349.563 [DataOverHttps](INFO): Moved sent file to Logs/20170628T010343/Express0005.lzma.bak
2017-06-28T01:12:29.563Z,1498612349.563 [DataOverHttps](INFO): SBD MOMSN=5047125
2017-06-28T01:12:30.041Z,1498612350.041 [Default:CheckIn:Read_Iridium] Stopped
2017-06-28T01:12:30.041Z,1498612350.041 [Default:CheckIn:C.Wait] Running Loop=1
2017-06-28T01:12:30.041Z,1498612350.041 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2017-06-28T01:13:04.352Z,1498612384.352 [BPC1](ERROR): Failed to receive battery data
2017-06-28T01:13:04.352Z,1498612384.352 [BPC1] Communications Fault, FailCount= 1
2017-06-28T01:13:04.352Z,1498612384.352 [BPC1](ERROR): Communications Fault
2017-06-28T01:13:04.390Z,1498612384.390 [CBIT](ERROR): Communications Fault in component: BPC1
2017-06-28T01:13:05.601Z,1498612385.601 [CBIT](INFO): Clearing failed state for component BPC1
2017-06-28T01:13:05.601Z,1498612385.601 [BPC1] No Fault, FailCount= 1
2017-06-28T01:13:08.022Z,1498612388.022 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-06-28T01:13:08.022Z,1498612388.022 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2017-06-28T01:13:08.022Z,1498612388.022 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-06-28T01:13:08.024Z,1498612388.024 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-06-28T01:13:08.025Z,1498612388.025 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2017-06-28T01:13:08.025Z,1498612388.025 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-06-28T01:13:08.065Z,1498612388.065 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-06-28T01:13:08.065Z,1498612388.065 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-06-28T01:13:08.396Z,1498612388.396 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-06-28T01:13:08.396Z,1498612388.396 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2017-06-28T01:13:08.397Z,1498612388.397 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-06-28T01:13:08.397Z,1498612388.397 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2017-06-28T01:13:08.782Z,1498612388.782 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-06-28T01:13:08.783Z,1498612388.783 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-06-28T01:13:08.783Z,1498612388.783 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-06-28T01:13:08.784Z,1498612388.784 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-06-28T01:13:08.784Z,1498612388.784 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-06-28T01:13:08.785Z,1498612388.785 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-06-28T01:13:11.872Z,1498612391.872 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2017-06-28T01:13:11.872Z,1498612391.872 [Rowe_600LCM] Communications Fault, FailCount= 4
2017-06-28T01:13:11.872Z,1498612391.872 [Rowe_600LCM](ERROR): Communications Fault
2017-06-28T01:13:11.988Z,1498612391.988 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2017-06-28T01:13:12.010Z,1498612392.010 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2017-06-28T01:13:12.277Z,1498612392.277 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-06-28T01:13:12.410Z,1498612392.410 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2017-06-28T01:13:12.512Z,1498612392.512 [Rowe_600LCM](INFO): Powering down
2017-06-28T01:13:12.776Z,1498612392.776 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-06-28T01:13:13.173Z,1498612393.173 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-06-28T01:13:13.569Z,1498612393.569 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-06-28T01:13:13.967Z,1498612393.967 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-06-28T01:13:13.980Z,1498612393.980 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2017-06-28T01:13:13.980Z,1498612393.980 [Rowe_600LCM] No Fault, FailCount= 4
2017-06-28T01:13:14.115Z,1498612394.115 [Rowe_600LCM](INFO): Initializing
2017-06-28T01:13:14.116Z,1498612394.116 [Rowe_600LCM](INFO): Checking LCM
2017-06-28T01:13:14.116Z,1498612394.116 [Rowe_600LCM](INFO): LCM OK
2017-06-28T01:13:14.116Z,1498612394.116 [Rowe_600LCM](INFO): Powering up
2017-06-28T01:13:14.367Z,1498612394.367 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-06-28T01:13:14.779Z,1498612394.779 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-06-28T01:13:15.186Z,1498612395.186 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-06-28T01:13:15.580Z,1498612395.580 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-06-28T01:13:15.968Z,1498612395.968 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-06-28T01:13:16.348Z,1498612396.348 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-06-28T01:13:16.754Z,1498612396.754 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-06-28T01:13:17.155Z,1498612397.155 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-06-28T01:13:17.586Z,1498612397.586 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-06-28T01:13:18.012Z,1498612398.012 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-06-28T01:13:18.206Z,1498612398.206 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-06-28T01:13:18.274Z,1498612398.274 [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-06-28T01:13:18.275Z,1498612398.275 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-06-28T01:13:18.276Z,1498612398.276 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-06-28T01:13:18.277Z,1498612398.277 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-06-28T01:13:18.468Z,1498612398.468 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.9 s old, using for 20.0 s.
2017-06-28T01:14:20.655Z,1498612460.655 [NAL9602](FAULT): GPS failed to acquire within timeout.
2017-06-28T01:14:20.655Z,1498612460.655 [NAL9602] Data Fault, FailCount= 1
2017-06-28T01:14:20.655Z,1498612460.655 [NAL9602](ERROR): Data Fault
2017-06-28T01:14:20.804Z,1498612460.804 [CBIT](ERROR): Data Fault in component: NAL9602
2017-06-28T01:14:21.061Z,1498612461.061 [NAL9602](INFO): Powering down
2017-06-28T01:14:21.931Z,1498612461.931 [CBIT](INFO): Clearing failed state for component NAL9602
2017-06-28T01:14:21.931Z,1498612461.931 [NAL9602] No Fault, FailCount= 1
2017-06-28T01:14:26.259Z,1498612466.259 [NAL9602](INFO): Powering up NAL9602
2017-06-28T01:14:37.389Z,1498612477.389 [NAL9602](INFO): NAL9602 initialized
2017-06-28T01:15:14.753Z,1498612514.753 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2017-06-28T01:15:14.753Z,1498612514.753 [Rowe_600LCM] Communications Fault, FailCount= 5
2017-06-28T01:15:14.753Z,1498612514.753 [Rowe_600LCM](ERROR): Communications Fault
2017-06-28T01:15:15.047Z,1498612515.047 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2017-06-28T01:15:15.060Z,1498612515.060 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2017-06-28T01:15:15.061Z,1498612515.061 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2017-06-28T01:15:15.155Z,1498612515.155 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-06-28T01:15:15.250Z,1498612515.250 [Rowe_600LCM](INFO): Powering down
2017-06-28T01:15:15.442Z,1498612515.442 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2017-06-28T01:15:15.843Z,1498612515.843 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-06-28T01:15:16.232Z,1498612516.232 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-06-28T01:15:16.668Z,1498612516.668 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-06-28T01:15:17.092Z,1498612517.092 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-06-28T01:15:17.450Z,1498612517.450 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-06-28T01:15:17.886Z,1498612517.886 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-06-28T01:15:18.239Z,1498612518.239 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-06-28T01:15:18.655Z,1498612518.655 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-06-28T01:15:19.052Z,1498612519.052 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-06-28T01:15:19.440Z,1498612519.440 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-06-28T01:15:19.820Z,1498612519.820 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-06-28T01:15:20.246Z,1498612520.246 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-06-28T01:15:20.649Z,1498612520.649 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-06-28T01:15:21.047Z,1498612521.047 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-06-28T01:15:21.416Z,1498612521.416 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2017-06-28T01:15:21.824Z,1498612521.824 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2017-06-28T01:15:22.222Z,1498612522.222 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2017-06-28T01:15:22.624Z,1498612522.624 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2017-06-28T01:15:23.028Z,1498612523.028 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2017-06-28T01:15:23.434Z,1498612523.434 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2017-06-28T01:15:23.843Z,1498612523.843 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2017-06-28T01:15:24.233Z,1498612524.233 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2017-06-28T01:15:24.630Z,1498612524.630 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2017-06-28T01:15:25.030Z,1498612525.030 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2017-06-28T01:15:25.438Z,1498612525.438 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2017-06-28T01:15:25.835Z,1498612525.835 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2017-06-28T01:15:26.243Z,1498612526.243 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2017-06-28T01:15:26.640Z,1498612526.640 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2017-06-28T01:15:27.041Z,1498612527.041 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2017-06-28T01:15:27.428Z,1498612527.428 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2017-06-28T01:15:27.833Z,1498612527.833 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2017-06-28T01:15:28.241Z,1498612528.241 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2017-06-28T01:15:28.619Z,1498612528.619 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2017-06-28T01:15:29.049Z,1498612529.049 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2017-06-28T01:15:29.449Z,1498612529.449 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2017-06-28T01:15:29.832Z,1498612529.832 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2017-06-28T01:15:30.232Z,1498612530.232 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2017-06-28T01:15:30.631Z,1498612530.631 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2017-06-28T01:15:31.029Z,1498612531.029 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2017-06-28T01:15:31.479Z,1498612531.479 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2017-06-28T01:16:09.167Z,1498612569.167 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-06-28T01:16:09.167Z,1498612569.167 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2017-06-28T01:16:09.167Z,1498612569.167 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-06-28T01:16:09.169Z,1498612569.169 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-06-28T01:16:09.169Z,1498612569.169 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2017-06-28T01:16:09.169Z,1498612569.169 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-06-28T01:16:09.217Z,1498612569.217 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-06-28T01:16:09.218Z,1498612569.218 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-06-28T01:16:09.575Z,1498612569.575 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-06-28T01:16:09.575Z,1498612569.575 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2017-06-28T01:16:09.575Z,1498612569.575 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-06-28T01:16:09.575Z,1498612569.575 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2017-06-28T01:16:09.964Z,1498612569.964 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-06-28T01:16:09.965Z,1498612569.965 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-06-28T01:16:09.965Z,1498612569.965 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-06-28T01:16:09.965Z,1498612569.965 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-06-28T01:16:09.966Z,1498612569.966 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-06-28T01:16:09.966Z,1498612569.966 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-06-28T01:16:10.363Z,1498612570.363 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-06-28T01:16:10.753Z,1498612570.753 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-06-28T01:16:11.185Z,1498612571.185 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-06-28T01:16:11.575Z,1498612571.575 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-06-28T01:16:11.971Z,1498612571.971 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-06-28T01:16:12.338Z,1498612572.338 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-06-28T01:16:12.768Z,1498612572.768 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-06-28T01:16:13.154Z,1498612573.154 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-06-28T01:16:13.557Z,1498612573.557 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-06-28T01:16:13.981Z,1498612573.981 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-06-28T01:16:14.353Z,1498612574.353 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-06-28T01:16:14.753Z,1498612574.753 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-06-28T01:16:15.152Z,1498612575.152 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-06-28T01:16:15.552Z,1498612575.552 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-06-28T01:16:15.950Z,1498612575.950 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2017-06-28T01:16:16.390Z,1498612576.390 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2017-06-28T01:16:16.795Z,1498612576.795 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2017-06-28T01:16:17.158Z,1498612577.158 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2017-06-28T01:16:17.576Z,1498612577.576 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2017-06-28T01:16:17.000Z,1498612578.000 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2017-06-28T01:16:18.358Z,1498612578.358 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2017-06-28T01:16:18.805Z,1498612578.805 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2017-06-28T01:16:19.197Z,1498612579.197 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2017-06-28T01:16:19.560Z,1498612579.560 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2017-06-28T01:16:19.972Z,1498612579.972 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2017-06-28T01:16:20.375Z,1498612580.375 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2017-06-28T01:16:20.767Z,1498612580.767 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2017-06-28T01:16:21.158Z,1498612581.158 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2017-06-28T01:16:21.558Z,1498612581.558 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2017-06-28T01:16:21.937Z,1498612581.937 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2017-06-28T01:16:22.368Z,1498612582.368 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2017-06-28T01:16:22.772Z,1498612582.772 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2017-06-28T01:16:23.174Z,1498612583.174 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2017-06-28T01:16:23.558Z,1498612583.558 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2017-06-28T01:16:23.960Z,1498612583.960 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2017-06-28T01:16:24.361Z,1498612584.361 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2017-06-28T01:16:24.758Z,1498612584.758 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2017-06-28T01:16:25.160Z,1498612585.160 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2017-06-28T01:16:25.556Z,1498612585.556 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2017-06-28T01:16:25.967Z,1498612585.967 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2017-06-28T01:16:26.370Z,1498612586.370 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2017-06-28T01:16:26.770Z,1498612586.770 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2017-06-28T01:16:27.161Z,1498612587.161 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2017-06-28T01:16:27.556Z,1498612587.556 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2017-06-28T01:16:27.962Z,1498612587.962 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2017-06-28T01:16:28.361Z,1498612588.361 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2017-06-28T01:16:28.741Z,1498612588.741 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2017-06-28T01:16:30.343Z,1498612590.343 [BPC1](ERROR): Failed to parse bank B battery data
2017-06-28T01:16:30.343Z,1498612590.343 [BPC1] Data Fault, FailCount= 1
2017-06-28T01:16:30.343Z,1498612590.343 [BPC1](ERROR): Data Fault
2017-06-28T01:16:30.394Z,1498612590.394 [CBIT](ERROR): Data Fault in component: BPC1
2017-06-28T01:16:31.568Z,1498612591.568 [CBIT](INFO): Clearing failed state for component BPC1
2017-06-28T01:16:31.569Z,1498612591.569 [BPC1] No Fault, FailCount= 1
2017-06-28T01:17:01.364Z,1498612621.364 [BPC1](ERROR): Failed to parse bank B battery data
2017-06-28T01:17:01.364Z,1498612621.364 [BPC1] Data Fault, FailCount= 1
2017-06-28T01:17:01.364Z,1498612621.364 [BPC1](ERROR): Data Fault
2017-06-28T01:17:01.411Z,1498612621.411 [CBIT](ERROR): Data Fault in component: BPC1
2017-06-28T01:17:02.578Z,1498612622.578 [CBIT](INFO): Clearing failed state for component BPC1
2017-06-28T01:17:02.578Z,1498612622.578 [BPC1] No Fault, FailCount= 1
2017-06-28T01:17:30.575Z,1498612650.575 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2017-06-28T01:17:30.575Z,1498612650.575 [Default:CheckIn:C.Wait] Stopped
2017-06-28T01:17:30.575Z,1498612650.575 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2017-06-28T01:17:30.576Z,1498612650.576 [Default:CheckIn:D] Running Loop=1
2017-06-28T01:17:30.978Z,1498612650.978 [Default:CheckIn:D] Stopped
2017-06-28T01:17:30.979Z,1498612650.979 [Default:CheckIn:E] Running Loop=1
2017-06-28T01:17:31.415Z,1498612651.415 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.515992 min 
2017-06-28T01:17:31.417Z,1498612651.417 [Default:CheckIn:E] Stopped
2017-06-28T01:17:31.418Z,1498612651.418 [Default:CheckIn](INFO): Completed Default:CheckIn
2017-06-28T01:17:31.418Z,1498612651.418 [Default:CheckIn] Stopped
2017-06-28T01:17:31.418Z,1498612651.418 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-06-28T01:17:31.418Z,1498612651.418 [Default:CheckIn](INFO): Running loop #2
2017-06-28T01:17:31.418Z,1498612651.418 [Default:CheckIn] Running Loop=2
2017-06-28T01:17:31.418Z,1498612651.418 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-06-28T01:17:31.419Z,1498612651.419 [Default:CheckIn:Read_GPS] Running Loop=1
2017-06-28T01:19:10.149Z,1498612750.149 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-06-28T01:19:10.149Z,1498612750.149 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2017-06-28T01:19:10.149Z,1498612750.149 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-06-28T01:19:10.151Z,1498612750.151 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-06-28T01:19:10.151Z,1498612750.151 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2017-06-28T01:19:10.151Z,1498612750.151 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-06-28T01:19:10.164Z,1498612750.164 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-06-28T01:19:10.164Z,1498612750.164 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-06-28T01:19:10.608Z,1498612750.608 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-06-28T01:19:10.608Z,1498612750.608 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2017-06-28T01:19:10.608Z,1498612750.608 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-06-28T01:19:10.608Z,1498612750.608 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2017-06-28T01:19:10.953Z,1498612750.953 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-06-28T01:19:10.954Z,1498612750.954 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-06-28T01:19:10.954Z,1498612750.954 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-06-28T01:19:10.955Z,1498612750.955 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-06-28T01:19:10.955Z,1498612750.955 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-06-28T01:19:10.955Z,1498612750.955 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-06-28T01:19:11.339Z,1498612751.339 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-06-28T01:19:11.729Z,1498612751.729 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-06-28T01:19:12.143Z,1498612752.143 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-06-28T01:19:12.532Z,1498612752.532 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-06-28T01:19:12.949Z,1498612752.949 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-06-28T01:19:13.350Z,1498612753.350 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-06-28T01:19:13.750Z,1498612753.750 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-06-28T01:19:14.136Z,1498612754.136 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-06-28T01:19:14.543Z,1498612754.543 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-06-28T01:19:14.929Z,1498612754.929 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-06-28T01:19:15.334Z,1498612755.334 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-06-28T01:19:15.742Z,1498612755.742 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-06-28T01:19:16.158Z,1498612756.158 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-06-28T01:19:16.559Z,1498612756.559 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-06-28T01:19:16.956Z,1498612756.956 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2017-06-28T01:19:17.335Z,1498612757.335 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2017-06-28T01:19:17.785Z,1498612757.785 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2017-06-28T01:19:18.136Z,1498612758.136 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2017-06-28T01:19:18.533Z,1498612758.533 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2017-06-28T01:19:18.966Z,1498612758.966 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2017-06-28T01:19:19.340Z,1498612759.340 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2017-06-28T01:19:19.775Z,1498612759.775 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2017-06-28T01:19:20.169Z,1498612760.169 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2017-06-28T01:19:20.538Z,1498612760.538 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2017-06-28T01:19:20.930Z,1498612760.930 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2017-06-28T01:19:21.332Z,1498612761.332 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2017-06-28T01:19:21.747Z,1498612761.747 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2017-06-28T01:19:22.142Z,1498612762.142 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2017-06-28T01:19:22.541Z,1498612762.541 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2017-06-28T01:19:22.944Z,1498612762.944 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2017-06-28T01:19:23.346Z,1498612763.346 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2017-06-28T01:19:23.736Z,1498612763.736 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2017-06-28T01:19:24.138Z,1498612764.138 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2017-06-28T01:19:24.537Z,1498612764.537 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2017-06-28T01:19:24.951Z,1498612764.951 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2017-06-28T01:19:25.344Z,1498612765.344 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2017-06-28T01:19:25.746Z,1498612765.746 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2017-06-28T01:19:26.142Z,1498612766.142 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2017-06-28T01:19:26.545Z,1498612766.545 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2017-06-28T01:19:26.941Z,1498612766.941 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2017-06-28T01:19:27.347Z,1498612767.347 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2017-06-28T01:19:27.739Z,1498612767.739 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2017-06-28T01:19:28.146Z,1498612768.146 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2017-06-28T01:19:28.550Z,1498612768.550 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2017-06-28T01:19:28.954Z,1498612768.954 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2017-06-28T01:19:29.339Z,1498612769.339 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2017-06-28T01:19:29.740Z,1498612769.740 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2017-06-28T01:19:30.148Z,1498612770.148 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 20.0 s old, using for 20.0 s.
2017-06-28T01:20:40.924Z,1498612840.924 [BPC1](ERROR): Failed to receive battery data
2017-06-28T01:20:40.924Z,1498612840.924 [BPC1] Communications Fault, FailCount= 1
2017-06-28T01:20:40.924Z,1498612840.924 [BPC1](ERROR): Communications Fault
2017-06-28T01:20:40.971Z,1498612840.971 [CBIT](ERROR): Communications Fault in component: BPC1
2017-06-28T01:20:42.138Z,1498612842.138 [CBIT](INFO): Clearing failed state for component BPC1
2017-06-28T01:20:42.138Z,1498612842.138 [BPC1] No Fault, FailCount= 1
2017-06-28T01:22:11.161Z,1498612931.161 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-06-28T01:22:11.161Z,1498612931.161 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2017-06-28T01:22:11.161Z,1498612931.161 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-06-28T01:22:11.163Z,1498612931.163 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-06-28T01:22:11.163Z,1498612931.163 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2017-06-28T01:22:11.163Z,1498612931.163 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-06-28T01:22:11.176Z,1498612931.176 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-06-28T01:22:11.176Z,1498612931.176 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-06-28T01:22:11.575Z,1498612931.575 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-06-28T01:22:11.575Z,1498612931.575 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2017-06-28T01:22:11.576Z,1498612931.576 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-06-28T01:22:11.576Z,1498612931.576 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2017-06-28T01:22:11.963Z,1498612931.963 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-06-28T01:22:11.964Z,1498612931.964 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-06-28T01:22:11.964Z,1498612931.964 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-06-28T01:22:11.965Z,1498612931.965 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-06-28T01:22:11.965Z,1498612931.965 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-06-28T01:22:11.966Z,1498612931.966 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-06-28T01:22:12.365Z,1498612932.365 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-06-28T01:22:12.740Z,1498612932.740 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-06-28T01:22:13.166Z,1498612933.166 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-06-28T01:22:13.546Z,1498612933.546 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-06-28T01:22:13.991Z,1498612933.991 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-06-28T01:22:14.360Z,1498612934.360 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-06-28T01:22:14.808Z,1498612934.808 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-06-28T01:22:15.155Z,1498612935.155 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-06-28T01:22:15.582Z,1498612935.582 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-06-28T01:22:15.944Z,1498612935.944 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-06-28T01:22:16.383Z,1498612936.383 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-06-28T01:22:16.768Z,1498612936.768 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-06-28T01:22:17.160Z,1498612937.160 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-06-28T01:22:17.553Z,1498612937.553 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-06-28T01:22:17.957Z,1498612937.957 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2017-06-28T01:22:18.347Z,1498612938.347 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2017-06-28T01:22:18.750Z,1498612938.750 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2017-06-28T01:22:19.148Z,1498612939.148 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2017-06-28T01:22:19.553Z,1498612939.553 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2017-06-28T01:22:19.957Z,1498612939.957 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2017-06-28T01:22:20.358Z,1498612940.358 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2017-06-28T01:22:20.762Z,1498612940.762 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2017-06-28T01:22:21.153Z,1498612941.153 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2017-06-28T01:22:21.550Z,1498612941.550 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2017-06-28T01:22:21.953Z,1498612941.953 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2017-06-28T01:22:22.353Z,1498612942.353 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2017-06-28T01:22:22.748Z,1498612942.748 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2017-06-28T01:22:23.157Z,1498612943.157 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2017-06-28T01:22:23.562Z,1498612943.562 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2017-06-28T01:22:23.958Z,1498612943.958 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2017-06-28T01:22:24.327Z,1498612944.327 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2017-06-28T01:22:24.728Z,1498612944.728 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2017-06-28T01:22:25.137Z,1498612945.137 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2017-06-28T01:22:25.538Z,1498612945.538 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2017-06-28T01:22:25.957Z,1498612945.957 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2017-06-28T01:22:26.345Z,1498612946.345 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2017-06-28T01:22:26.751Z,1498612946.751 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2017-06-28T01:22:27.148Z,1498612947.148 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2017-06-28T01:22:27.581Z,1498612947.581 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2017-06-28T01:22:27.942Z,1498612947.942 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2017-06-28T01:22:28.341Z,1498612948.341 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2017-06-28T01:22:28.741Z,1498612948.741 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2017-06-28T01:22:29.150Z,1498612949.150 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2017-06-28T01:22:29.550Z,1498612949.550 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2017-06-28T01:22:29.949Z,1498612949.949 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2017-06-28T01:22:30.339Z,1498612950.339 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2017-06-28T01:22:30.750Z,1498612950.750 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2017-06-28T01:22:31.142Z,1498612951.142 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 20.0 s old, using for 20.0 s.
2017-06-28T01:22:31.550Z,1498612951.550 [Default:CheckIn:Read_GPS](INFO): Timed out from 2017-06-28T01:17:31.4Z
2017-06-28T01:22:31.550Z,1498612951.550 [Default:CheckIn:Read_GPS] Stopped
2017-06-28T01:22:31.550Z,1498612951.550 [Default:CheckIn:Read_Iridium] Running Loop=1
2017-06-28T01:22:31.940Z,1498612951.940 [BPC1](ERROR): Failed to receive battery data
2017-06-28T01:22:31.940Z,1498612951.940 [BPC1] Communications Fault, FailCount= 1
2017-06-28T01:22:31.940Z,1498612951.940 [BPC1](ERROR): Communications Fault
2017-06-28T01:22:31.996Z,1498612951.996 [CBIT](ERROR): Communications Fault in component: BPC1
2017-06-28T01:22:36.018Z,1498612956.018 [CBIT](INFO): Clearing failed state for component BPC1
2017-06-28T01:22:36.018Z,1498612956.018 [BPC1] No Fault, FailCount= 1
2017-06-28T01:22:36.096Z,1498612956.096 [DataOverHttps](INFO): Sending 45 bytes from file Logs/20170628T010343/Courier0008.lzma
2017-06-28T01:22:36.827Z,1498612956.827 [DataOverHttps](INFO): Moved sent file to Logs/20170628T010343/Courier0008.lzma.bak
2017-06-28T01:22:36.828Z,1498612956.828 [DataOverHttps](INFO): SBD MOMSN=5047131
2017-06-28T01:22:45.988Z,1498612965.988 [DataOverHttps](INFO): Sending 287 bytes from file Logs/20170628T010343/Express0009.lzma
2017-06-28T01:22:46.759Z,1498612966.759 [DataOverHttps](INFO): Moved sent file to Logs/20170628T010343/Express0009.lzma.bak
2017-06-28T01:22:46.760Z,1498612966.760 [DataOverHttps](INFO): SBD MOMSN=5047133
2017-06-28T01:22:47.236Z,1498612967.236 [Default:CheckIn:Read_Iridium] Stopped
2017-06-28T01:22:47.236Z,1498612967.236 [Default:CheckIn:C.Wait] Running Loop=1
2017-06-28T01:22:47.236Z,1498612967.236 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2017-06-28T01:24:18.032Z,1498613058.032 [BPC1](ERROR): Failed to parse bank B battery data
2017-06-28T01:24:18.032Z,1498613058.032 [BPC1] Data Fault, FailCount= 1
2017-06-28T01:24:18.032Z,1498613058.032 [BPC1](ERROR): Data Fault
2017-06-28T01:24:18.084Z,1498613058.084 [CBIT](ERROR): Data Fault in component: BPC1
2017-06-28T01:24:19.243Z,1498613059.243 [CBIT](INFO): Clearing failed state for component BPC1
2017-06-28T01:24:19.244Z,1498613059.244 [BPC1] No Fault, FailCount= 1
2017-06-28T01:24:39.181Z,1498613079.181 [NAL9602](FAULT): GPS failed to acquire within timeout.
2017-06-28T01:24:39.182Z,1498613079.182 [NAL9602] Data Fault, FailCount= 2
2017-06-28T01:24:39.182Z,1498613079.182 [NAL9602](ERROR): Data Fault
2017-06-28T01:24:39.252Z,1498613079.252 [CBIT](ERROR): Data Fault in component: NAL9602
2017-06-28T01:24:39.573Z,1498613079.573 [NAL9602](INFO): Powering down
2017-06-28T01:24:40.429Z,1498613080.429 [CBIT](INFO): Clearing failed state for component NAL9602
2017-06-28T01:24:40.429Z,1498613080.429 [NAL9602] No Fault, FailCount= 2
2017-06-28T01:24:44.773Z,1498613084.773 [NAL9602](INFO): Powering up NAL9602
2017-06-28T01:24:55.749Z,1498613095.749 [NAL9602](INFO): NAL9602 initialized
2017-06-28T01:25:12.185Z,1498613112.185 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-06-28T01:25:12.185Z,1498613112.185 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2017-06-28T01:25:12.185Z,1498613112.185 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-06-28T01:25:12.196Z,1498613112.196 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-06-28T01:25:12.196Z,1498613112.196 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2017-06-28T01:25:12.196Z,1498613112.196 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-06-28T01:25:12.221Z,1498613112.221 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-06-28T01:25:12.221Z,1498613112.221 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-06-28T01:25:12.634Z,1498613112.634 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-06-28T01:25:12.634Z,1498613112.634 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2017-06-28T01:25:12.634Z,1498613112.634 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-06-28T01:25:12.634Z,1498613112.634 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2017-06-28T01:25:12.975Z,1498613112.975 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-06-28T01:25:12.976Z,1498613112.976 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-06-28T01:25:12.976Z,1498613112.976 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-06-28T01:25:12.977Z,1498613112.977 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-06-28T01:25:12.977Z,1498613112.977 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-06-28T01:25:12.977Z,1498613112.977 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-06-28T01:25:13.387Z,1498613113.387 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-06-28T01:25:13.792Z,1498613113.792 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-06-28T01:25:14.187Z,1498613114.187 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-06-28T01:25:14.598Z,1498613114.598 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-06-28T01:25:14.996Z,1498613114.996 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-06-28T01:25:15.027Z,1498613115.027 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2017-06-28T01:25:15.027Z,1498613115.027 [Rowe_600LCM] No Fault, FailCount= 5
2017-06-28T01:25:15.155Z,1498613115.155 [Rowe_600LCM](INFO): Initializing
2017-06-28T01:25:15.156Z,1498613115.156 [Rowe_600LCM](INFO): Checking LCM
2017-06-28T01:25:15.156Z,1498613115.156 [Rowe_600LCM](INFO): LCM OK
2017-06-28T01:25:15.156Z,1498613115.156 [Rowe_600LCM](INFO): Powering up
2017-06-28T01:25:15.402Z,1498613115.402 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-06-28T01:25:15.788Z,1498613115.788 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-06-28T01:25:16.184Z,1498613116.184 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-06-28T01:25:16.568Z,1498613116.568 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-06-28T01:25:16.993Z,1498613116.993 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-06-28T01:25:17.426Z,1498613117.426 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-06-28T01:25:17.808Z,1498613117.808 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-06-28T01:25:18.181Z,1498613118.181 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-06-28T01:25:18.623Z,1498613118.623 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-06-28T01:25:18.988Z,1498613118.988 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2017-06-28T01:25:19.251Z,1498613119.251 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-06-28T01:25:19.317Z,1498613119.317 [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-06-28T01:25:19.318Z,1498613119.318 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-06-28T01:25:19.319Z,1498613119.319 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-06-28T01:25:19.320Z,1498613119.320 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-06-28T01:25:19.467Z,1498613119.467 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.3 s old, using for 20.0 s.
2017-06-28T01:26:31.374Z,1498613191.374 [BPC1](ERROR): Failed to parse bank A battery data
2017-06-28T01:26:31.374Z,1498613191.374 [BPC1] Data Fault, FailCount= 1
2017-06-28T01:26:31.375Z,1498613191.375 [BPC1](ERROR): Data Fault
2017-06-28T01:26:31.420Z,1498613191.420 [CBIT](ERROR): Data Fault in component: BPC1
2017-06-28T01:26:32.592Z,1498613192.592 [CBIT](INFO): Clearing failed state for component BPC1
2017-06-28T01:26:32.592Z,1498613192.592 [BPC1] No Fault, FailCount= 1
2017-06-28T01:27:28.919Z,1498613248.919 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2017-06-28T01:27:28.919Z,1498613248.919 [Rowe_600LCM] Communications Fault, FailCount= 1
2017-06-28T01:27:28.919Z,1498613248.919 [Rowe_600LCM](ERROR): Communications Fault
2017-06-28T01:27:28.922Z,1498613248.922 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.5 s old, using for 20.0 s.
2017-06-28T01:27:28.935Z,1498613248.935 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2017-06-28T01:27:29.233Z,1498613249.233 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2017-06-28T01:27:29.324Z,1498613249.324 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-06-28T01:27:29.460Z,1498613249.460 [Rowe_600LCM](INFO): Powering down
2017-06-28T01:27:29.620Z,1498613249.620 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-06-28T01:27:30.059Z,1498613250.059 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-06-28T01:27:30.448Z,1498613250.448 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-06-28T01:27:30.859Z,1498613250.859 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-06-28T01:27:30.874Z,1498613250.874 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2017-06-28T01:27:30.874Z,1498613250.874 [Rowe_600LCM] No Fault, FailCount= 1
2017-06-28T01:27:31.066Z,1498613251.066 [Rowe_600LCM](INFO): Initializing
2017-06-28T01:27:31.067Z,1498613251.067 [Rowe_600LCM](INFO): Checking LCM
2017-06-28T01:27:31.067Z,1498613251.067 [Rowe_600LCM](INFO): LCM OK
2017-06-28T01:27:31.067Z,1498613251.067 [Rowe_600LCM](INFO): Powering up
2017-06-28T01:27:31.255Z,1498613251.255 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-06-28T01:27:31.620Z,1498613251.620 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-06-28T01:27:32.021Z,1498613252.021 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-06-28T01:27:32.425Z,1498613252.425 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-06-28T01:27:32.839Z,1498613252.839 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-06-28T01:27:33.233Z,1498613253.233 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-06-28T01:27:33.621Z,1498613253.621 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-06-28T01:27:34.014Z,1498613254.014 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-06-28T01:27:34.428Z,1498613254.428 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-06-28T01:27:34.834Z,1498613254.834 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-06-28T01:27:35.179Z,1498613255.179 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-06-28T01:27:35.275Z,1498613255.275 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2017-06-28T01:27:35.352Z,1498613255.352 [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-06-28T01:27:35.354Z,1498613255.354 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-06-28T01:27:35.355Z,1498613255.355 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-06-28T01:27:35.356Z,1498613255.356 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-06-28T01:27:35.651Z,1498613255.651 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2017-06-28T01:27:47.637Z,1498613267.637 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2017-06-28T01:27:47.637Z,1498613267.637 [Default:CheckIn:C.Wait] Stopped
2017-06-28T01:27:47.638Z,1498613267.638 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2017-06-28T01:27:47.638Z,1498613267.638 [Default:CheckIn:D] Running Loop=1
2017-06-28T01:27:48.036Z,1498613268.036 [Default:CheckIn:D] Stopped
2017-06-28T01:27:48.036Z,1498613268.036 [Default:CheckIn:E] Running Loop=1
2017-06-28T01:27:48.442Z,1498613268.442 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.800321 min 
2017-06-28T01:27:48.442Z,1498613268.442 [Default:CheckIn:E] Stopped
2017-06-28T01:27:48.443Z,1498613268.443 [Default:CheckIn](INFO): Completed Default:CheckIn
2017-06-28T01:27:48.443Z,1498613268.443 [Default:CheckIn] Stopped
2017-06-28T01:27:48.443Z,1498613268.443 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-06-28T01:27:48.443Z,1498613268.443 [Default:CheckIn](INFO): Running loop #3
2017-06-28T01:27:48.443Z,1498613268.443 [Default:CheckIn] Running Loop=3
2017-06-28T01:27:48.443Z,1498613268.443 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-06-28T01:27:48.443Z,1498613268.443 [Default:CheckIn:Read_GPS] Running Loop=1
2017-06-28T01:28:13.131Z,1498613293.131 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-06-28T01:28:13.131Z,1498613293.131 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2017-06-28T01:28:13.131Z,1498613293.131 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-06-28T01:28:13.138Z,1498613293.138 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-06-28T01:28:13.138Z,1498613293.138 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2017-06-28T01:28:13.138Z,1498613293.138 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-06-28T01:28:13.162Z,1498613293.162 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-06-28T01:28:13.162Z,1498613293.162 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-06-28T01:28:13.498Z,1498613293.498 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-06-28T01:28:13.498Z,1498613293.498 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2017-06-28T01:28:13.499Z,1498613293.499 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-06-28T01:28:13.499Z,1498613293.499 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2017-06-28T01:28:13.896Z,1498613293.896 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-06-28T01:28:13.896Z,1498613293.896 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-06-28T01:28:13.897Z,1498613293.897 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-06-28T01:28:13.897Z,1498613293.897 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-06-28T01:28:13.898Z,1498613293.898 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-06-28T01:28:13.898Z,1498613293.898 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-06-28T01:29:54.082Z,1498613394.082 [PNI_TCM](ERROR): CRC does not match. Expected:0x51424 got:0x19766
2017-06-28T01:29:54.488Z,1498613394.488 [PNI_TCM](ERROR): CRC does not match. Expected:0x48314 got:0x25231
2017-06-28T01:29:55.697Z,1498613395.697 [PNI_TCM](ERROR): CRC does not match. Expected:0x13868 got:0x49728
2017-06-28T01:29:57.707Z,1498613397.707 [BPC1](ERROR): Failed to receive battery data
2017-06-28T01:29:57.707Z,1498613397.707 [BPC1] Communications Fault, FailCount= 1
2017-06-28T01:29:57.707Z,1498613397.707 [BPC1](ERROR): Communications Fault
2017-06-28T01:29:57.741Z,1498613397.741 [CBIT](ERROR): Communications Fault in component: BPC1
2017-06-28T01:29:58.964Z,1498613398.964 [CBIT](INFO): Clearing failed state for component BPC1
2017-06-28T01:29:58.964Z,1498613398.964 [BPC1] No Fault, FailCount= 1
2017-06-28T01:30:00.512Z,1498613400.512 [PNI_TCM](ERROR): CRC does not match. Expected:0x59835 got:0x51483
2017-06-28T01:30:01.560Z,1498613401.560 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2017-06-28T01:30:01.560Z,1498613401.560 [Rowe_600LCM] Communications Fault, FailCount= 2
2017-06-28T01:30:01.560Z,1498613401.560 [Rowe_600LCM](ERROR): Communications Fault
2017-06-28T01:30:01.732Z,1498613401.732 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2017-06-28T01:30:01.745Z,1498613401.745 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2017-06-28T01:30:01.965Z,1498613401.965 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-06-28T01:30:02.099Z,1498613402.099 [Rowe_600LCM](INFO): Powering down
2017-06-28T01:30:02.146Z,1498613402.146 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2017-06-28T01:30:02.521Z,1498613402.521 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-06-28T01:30:02.942Z,1498613402.942 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-06-28T01:30:03.329Z,1498613403.329 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-06-28T01:30:03.344Z,1498613403.344 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2017-06-28T01:30:03.344Z,1498613403.344 [Rowe_600LCM] No Fault, FailCount= 2
2017-06-28T01:30:03.696Z,1498613403.696 [PNI_TCM](ERROR): CRC does not match. Expected:0x12951 got:0x21788
2017-06-28T01:30:03.704Z,1498613403.704 [Rowe_600LCM](INFO): Initializing
2017-06-28T01:30:03.705Z,1498613403.705 [Rowe_600LCM](INFO): Checking LCM
2017-06-28T01:30:03.705Z,1498613403.705 [Rowe_600LCM](INFO): LCM OK
2017-06-28T01:30:03.705Z,1498613403.705 [Rowe_600LCM](INFO): Powering up
2017-06-28T01:30:03.754Z,1498613403.754 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.5 s old, using for 20.0 s.
2017-06-28T01:30:04.107Z,1498613404.107 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-06-28T01:30:04.511Z,1498613404.511 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-06-28T01:30:04.913Z,1498613404.913 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-06-28T01:30:05.338Z,1498613405.338 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-06-28T01:30:05.712Z,1498613405.712 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-06-28T01:30:06.111Z,1498613406.111 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-06-28T01:30:06.510Z,1498613406.510 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-06-28T01:30:06.919Z,1498613406.919 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-06-28T01:30:07.300Z,1498613407.300 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-06-28T01:30:07.799Z,1498613407.799 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.5 s old, using for 20.0 s.
2017-06-28T01:30:07.969Z,1498613407.969 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-06-28T01:30:08.214Z,1498613408.214 [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-06-28T01:30:08.215Z,1498613408.215 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-06-28T01:30:08.216Z,1498613408.216 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-06-28T01:30:08.217Z,1498613408.217 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-06-28T01:30:08.266Z,1498613408.266 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.0 s old, using for 20.0 s.
2017-06-28T01:30:08.542Z,1498613408.542 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2017-06-28T01:30:13.721Z,1498613413.721 [PNI_TCM](ERROR): CRC does not match. Expected:0x37956 got:0x16834
2017-06-28T01:30:16.519Z,1498613416.519 [PNI_TCM](ERROR): CRC does not match. Expected:0x63668 got:0x48747
2017-06-28T01:30:18.101Z,1498613418.101 [BPC1](ERROR): Failed to receive battery data
2017-06-28T01:30:18.101Z,1498613418.101 [BPC1] Communications Fault, FailCount= 2
2017-06-28T01:30:18.101Z,1498613418.101 [BPC1](ERROR): Communications Fault
2017-06-28T01:30:18.167Z,1498613418.167 [CBIT](ERROR): Communications Fault in component: BPC1
2017-06-28T01:30:19.335Z,1498613419.335 [CBIT](INFO): Clearing failed state for component BPC1
2017-06-28T01:30:19.335Z,1498613419.335 [BPC1] No Fault, FailCount= 2
2017-06-28T01:30:24.496Z,1498613424.496 [PNI_TCM](ERROR): CRC does not match. Expected:0x4607 got:0x7105
2017-06-28T01:30:30.899Z,1498613430.899 [PNI_TCM](ERROR): CRC does not match. Expected:0x31693 got:0x32657
2017-06-28T01:30:34.102Z,1498613434.102 [PNI_TCM](ERROR): CRC does not match. Expected:0x39695 got:0x8987
2017-06-28T01:30:37.289Z,1498613437.289 [PNI_TCM](ERROR): CRC does not match. Expected:0x59705 got:0x13596
2017-06-28T01:30:40.523Z,1498613440.523 [PNI_TCM](ERROR): CRC does not match. Expected:0x48771 got:0x1287
2017-06-28T01:30:42.105Z,1498613442.105 [PNI_TCM](ERROR): CRC does not match. Expected:0x28187 got:0x67
2017-06-28T01:30:48.497Z,1498613448.497 [PNI_TCM](ERROR): CRC does not match. Expected:0x53039 got:0x13932
2017-06-28T01:31:13.964Z,1498613473.964 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-06-28T01:31:13.965Z,1498613473.965 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2017-06-28T01:31:13.965Z,1498613473.965 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-06-28T01:31:13.966Z,1498613473.966 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-06-28T01:31:13.967Z,1498613473.967 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9
2017-06-28T01:31:13.967Z,1498613473.967 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-06-28T01:31:13.980Z,1498613473.980 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-06-28T01:31:13.980Z,1498613473.980 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-06-28T01:31:14.390Z,1498613474.390 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-06-28T01:31:14.391Z,1498613474.391 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2017-06-28T01:31:14.391Z,1498613474.391 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-06-28T01:31:14.391Z,1498613474.391 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9
2017-06-28T01:31:14.819Z,1498613474.819 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-06-28T01:31:14.824Z,1498613474.824 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-06-28T01:31:14.825Z,1498613474.825 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-06-28T01:31:14.825Z,1498613474.825 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-06-28T01:31:14.826Z,1498613474.826 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-06-28T01:31:14.826Z,1498613474.826 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-06-28T01:32:22.322Z,1498613542.322 [BPC1](ERROR): Failed to receive battery data
2017-06-28T01:32:22.322Z,1498613542.322 [BPC1] Communications Fault, FailCount= 1
2017-06-28T01:32:22.322Z,1498613542.322 [BPC1](ERROR): Communications Fault
2017-06-28T01:32:22.387Z,1498613542.387 [CBIT](ERROR): Communications Fault in component: BPC1
2017-06-28T01:32:23.564Z,1498613543.564 [CBIT](INFO): Clearing failed state for component BPC1
2017-06-28T01:32:23.564Z,1498613543.564 [BPC1] No Fault, FailCount= 1
2017-06-28T01:32:48.813Z,1498613568.813 [Default:CheckIn:Read_GPS](INFO): Timed out from 2017-06-28T01:27:48.4Z
2017-06-28T01:32:48.814Z,1498613568.814 [Default:CheckIn:Read_GPS] Stopped
2017-06-28T01:32:48.814Z,1498613568.814 [Default:CheckIn:Read_Iridium] Running Loop=1
2017-06-28T01:32:53.540Z,1498613573.540 [DataOverHttps](INFO): Sending 45 bytes from file Logs/20170628T010343/Courier0012.lzma
2017-06-28T01:32:54.300Z,1498613574.300 [DataOverHttps](INFO): Moved sent file to Logs/20170628T010343/Courier0012.lzma.bak
2017-06-28T01:32:54.300Z,1498613574.300 [DataOverHttps](INFO): SBD MOMSN=5047140
2017-06-28T01:33:03.717Z,1498613583.717 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20170628T010343/Express0013.lzma
2017-06-28T01:33:04.490Z,1498613584.490 [DataOverHttps](INFO): Moved sent file to Logs/20170628T010343/Express0013.lzma.bak
2017-06-28T01:33:04.491Z,1498613584.491 [DataOverHttps](INFO): SBD MOMSN=5047142
2017-06-28T01:33:04.979Z,1498613584.979 [Default:CheckIn:Read_Iridium] Stopped
2017-06-28T01:33:04.979Z,1498613584.979 [Default:CheckIn:C.Wait] Running Loop=1
2017-06-28T01:33:04.979Z,1498613584.979 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2017-06-28T01:33:45.606Z,1498613625.606 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2017-06-28T01:33:45.607Z,1498613625.607 [Rowe_600LCM] Communications Fault, FailCount= 3
2017-06-28T01:33:45.607Z,1498613625.607 [Rowe_600LCM](ERROR): Communications Fault
2017-06-28T01:33:45.621Z,1498613625.621 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2017-06-28T01:33:45.661Z,1498613625.661 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2017-06-28T01:33:45.989Z,1498613625.989 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2017-06-28T01:33:46.011Z,1498613626.011 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-06-28T01:33:46.166Z,1498613626.166 [Rowe_600LCM](INFO): Powering down
2017-06-28T01:33:46.430Z,1498613626.430 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-06-28T01:33:46.828Z,1498613626.828 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-06-28T01:33:47.202Z,1498613627.202 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-06-28T01:33:47.600Z,1498613627.600 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-06-28T01:33:47.614Z,1498613627.614 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2017-06-28T01:33:47.614Z,1498613627.614 [Rowe_600LCM] No Fault, FailCount= 3
2017-06-28T01:33:47.770Z,1498613627.770 [Rowe_600LCM](INFO): Initializing
2017-06-28T01:33:47.771Z,1498613627.771 [Rowe_600LCM](INFO): Checking LCM
2017-06-28T01:33:47.771Z,1498613627.771 [Rowe_600LCM](INFO): LCM OK
2017-06-28T01:33:47.771Z,1498613627.771 [Rowe_600LCM](INFO): Powering up
2017-06-28T01:33:48.014Z,1498613628.014 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-06-28T01:33:48.418Z,1498613628.418 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-06-28T01:33:48.816Z,1498613628.816 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-06-28T01:33:49.219Z,1498613629.219 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-06-28T01:33:49.613Z,1498613629.613 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-06-28T01:33:50.003Z,1498613630.003 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-06-28T01:33:50.421Z,1498613630.421 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-06-28T01:33:50.817Z,1498613630.817 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-06-28T01:33:51.222Z,1498613631.222 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-06-28T01:33:51.613Z,1498613631.613 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-06-28T01:33:51.866Z,1498613631.866 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-06-28T01:33:51.933Z,1498613631.933 [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-06-28T01:33:51.934Z,1498613631.934 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-06-28T01:33:51.935Z,1498613631.935 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-06-28T01:33:51.936Z,1498613631.936 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-06-28T01:33:52.036Z,1498613632.036 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2017-06-28T01:34:14.828Z,1498613654.828 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-06-28T01:34:14.828Z,1498613654.828 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2017-06-28T01:34:14.828Z,1498613654.828 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-06-28T01:34:14.830Z,1498613654.830 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-06-28T01:34:14.830Z,1498613654.830 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10
2017-06-28T01:34:14.830Z,1498613654.830 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-06-28T01:34:14.864Z,1498613654.864 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-06-28T01:34:14.864Z,1498613654.864 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-06-28T01:34:15.256Z,1498613655.256 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-06-28T01:34:15.256Z,1498613655.256 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2017-06-28T01:34:15.256Z,1498613655.256 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-06-28T01:34:15.256Z,1498613655.256 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10
2017-06-28T01:34:15.629Z,1498613655.629 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-06-28T01:34:15.630Z,1498613655.630 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-06-28T01:34:15.630Z,1498613655.630 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-06-28T01:34:15.631Z,1498613655.631 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-06-28T01:34:15.631Z,1498613655.631 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-06-28T01:34:15.632Z,1498613655.632 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-06-28T01:34:56.773Z,1498613696.773 [NAL9602](FAULT): GPS failed to acquire within timeout.
2017-06-28T01:34:56.773Z,1498613696.773 [NAL9602] Data Fault, FailCount= 3
2017-06-28T01:34:56.773Z,1498613696.773 [NAL9602](ERROR): Data Fault
2017-06-28T01:34:56.864Z,1498613696.864 [CBIT](ERROR): Data Fault in component: NAL9602
2017-06-28T01:34:57.181Z,1498613697.181 [NAL9602](INFO): Powering down
2017-06-28T01:34:58.032Z,1498613698.032 [CBIT](INFO): Clearing failed state for component NAL9602
2017-06-28T01:34:58.033Z,1498613698.033 [NAL9602] No Fault, FailCount= 3
2017-06-28T01:35:02.386Z,1498613702.386 [NAL9602](INFO): Powering up NAL9602
2017-06-28T01:35:13.280Z,1498613713.280 [NAL9602](INFO): NAL9602 initialized
2017-06-28T01:35:44.886Z,1498613744.886 [PNI_TCM](ERROR): CRC does not match. Expected:0x13764 got:0x10501
2017-06-28T01:35:45.286Z,1498613745.286 [PNI_TCM](ERROR): CRC does not match. Expected:0x59095 got:0x7232
2017-06-28T01:35:47.924Z,1498613747.924 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2017-06-28T01:35:47.924Z,1498613747.924 [Rowe_600LCM] Communications Fault, FailCount= 4
2017-06-28T01:35:47.924Z,1498613747.924 [Rowe_600LCM](ERROR): Communications Fault
2017-06-28T01:35:48.092Z,1498613748.092 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.4 s old, using for 20.0 s.
2017-06-28T01:35:48.106Z,1498613748.106 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2017-06-28T01:35:48.330Z,1498613748.330 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-06-28T01:35:48.427Z,1498613748.427 [Rowe_600LCM](INFO): Powering down
2017-06-28T01:35:48.508Z,1498613748.508 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2017-06-28T01:35:48.921Z,1498613748.921 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-06-28T01:35:49.303Z,1498613749.303 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-06-28T01:35:49.707Z,1498613749.707 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-06-28T01:35:49.736Z,1498613749.736 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2017-06-28T01:35:49.737Z,1498613749.737 [Rowe_600LCM] No Fault, FailCount= 4
2017-06-28T01:35:50.031Z,1498613750.031 [Rowe_600LCM](INFO): Initializing
2017-06-28T01:35:50.032Z,1498613750.032 [Rowe_600LCM](INFO): Checking LCM
2017-06-28T01:35:50.032Z,1498613750.032 [Rowe_600LCM](INFO): LCM OK
2017-06-28T01:35:50.032Z,1498613750.032 [Rowe_600LCM](INFO): Powering up
2017-06-28T01:35:50.116Z,1498613750.116 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-06-28T01:35:50.509Z,1498613750.509 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-06-28T01:35:50.909Z,1498613750.909 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-06-28T01:35:51.309Z,1498613751.309 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-06-28T01:35:51.700Z,1498613751.700 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-06-28T01:35:52.126Z,1498613752.126 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-06-28T01:35:52.503Z,1498613752.503 [PNI_TCM](ERROR): CRC does not match. Expected:0x48389 got:0x52765
2017-06-28T01:35:52.530Z,1498613752.530 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-06-28T01:35:52.909Z,1498613752.909 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-06-28T01:35:53.301Z,1498613753.301 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-06-28T01:35:53.713Z,1498613753.713 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-06-28T01:35:54.147Z,1498613754.147 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.5 s old, using for 20.0 s.
2017-06-28T01:35:54.248Z,1498613754.248 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-06-28T01:35:54.321Z,1498613754.321 [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-06-28T01:35:54.322Z,1498613754.322 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-06-28T01:35:54.323Z,1498613754.323 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-06-28T01:35:54.324Z,1498613754.324 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-06-28T01:35:54.571Z,1498613754.571 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.9 s old, using for 20.0 s.
2017-06-28T01:36:02.892Z,1498613762.892 [PNI_TCM](ERROR): CRC does not match. Expected:0x63291 got:0x5403
2017-06-28T01:37:15.926Z,1498613835.926 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-06-28T01:37:15.926Z,1498613835.926 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2017-06-28T01:37:15.926Z,1498613835.926 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-06-28T01:37:15.932Z,1498613835.932 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-06-28T01:37:15.932Z,1498613835.932 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11
2017-06-28T01:37:15.932Z,1498613835.932 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-06-28T01:37:15.957Z,1498613835.957 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-06-28T01:37:15.958Z,1498613835.958 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-06-28T01:37:16.346Z,1498613836.346 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-06-28T01:37:16.346Z,1498613836.346 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2017-06-28T01:37:16.347Z,1498613836.347 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-06-28T01:37:16.347Z,1498613836.347 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11
2017-06-28T01:37:16.711Z,1498613836.711 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-06-28T01:37:16.712Z,1498613836.712 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-06-28T01:37:16.712Z,1498613836.712 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-06-28T01:37:16.729Z,1498613836.729 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-06-28T01:37:16.729Z,1498613836.729 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-06-28T01:37:16.729Z,1498613836.729 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-06-28T01:37:50.372Z,1498613870.372 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2017-06-28T01:37:50.372Z,1498613870.372 [Rowe_600LCM] Communications Fault, FailCount= 5
2017-06-28T01:37:50.372Z,1498613870.372 [Rowe_600LCM](ERROR): Communications Fault
2017-06-28T01:37:50.379Z,1498613870.379 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.5 s old, using for 20.0 s.
2017-06-28T01:37:50.412Z,1498613870.412 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2017-06-28T01:37:50.412Z,1498613870.412 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2017-06-28T01:37:50.745Z,1498613870.745 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.8 s old, using for 20.0 s.
2017-06-28T01:37:50.774Z,1498613870.774 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-06-28T01:37:50.891Z,1498613870.891 [Rowe_600LCM](INFO): Powering down
2017-06-28T01:37:51.141Z,1498613871.141 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-06-28T01:37:51.566Z,1498613871.566 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-06-28T01:37:51.979Z,1498613871.979 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.1 s old, using for 20.0 s.
2017-06-28T01:37:52.325Z,1498613872.325 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-06-28T01:37:52.755Z,1498613872.755 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-06-28T01:37:53.115Z,1498613873.115 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-06-28T01:37:53.552Z,1498613873.552 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-06-28T01:37:53.968Z,1498613873.968 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.1 s old, using for 20.0 s.
2017-06-28T01:37:54.317Z,1498613874.317 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-06-28T01:37:54.720Z,1498613874.720 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-06-28T01:37:55.130Z,1498613875.130 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-06-28T01:37:55.524Z,1498613875.524 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-06-28T01:37:55.914Z,1498613875.914 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-06-28T01:37:56.332Z,1498613876.332 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-06-28T01:37:56.739Z,1498613876.739 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2017-06-28T01:37:57.135Z,1498613877.135 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2017-06-28T01:37:57.532Z,1498613877.532 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2017-06-28T01:37:57.933Z,1498613877.933 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2017-06-28T01:37:58.329Z,1498613878.329 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2017-06-28T01:37:58.713Z,1498613878.713 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2017-06-28T01:37:59.146Z,1498613879.146 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2017-06-28T01:37:59.540Z,1498613879.540 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2017-06-28T01:37:59.940Z,1498613879.940 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2017-06-28T01:38:00.311Z,1498613880.311 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2017-06-28T01:38:00.709Z,1498613880.709 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2017-06-28T01:38:01.117Z,1498613881.117 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2017-06-28T01:38:01.512Z,1498613881.512 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2017-06-28T01:38:01.913Z,1498613881.913 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2017-06-28T01:38:02.321Z,1498613882.321 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2017-06-28T01:38:02.727Z,1498613882.727 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2017-06-28T01:38:03.144Z,1498613883.144 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2017-06-28T01:38:03.518Z,1498613883.518 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2017-06-28T01:38:03.913Z,1498613883.913 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2017-06-28T01:38:04.326Z,1498613884.326 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2017-06-28T01:38:04.721Z,1498613884.721 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2017-06-28T01:38:05.131Z,1498613885.131 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2017-06-28T01:38:05.526Z,1498613885.526 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2017-06-28T01:38:05.528Z,1498613885.528 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2017-06-28T01:38:05.529Z,1498613885.529 [Default:CheckIn:C.Wait] Stopped
2017-06-28T01:38:05.529Z,1498613885.529 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2017-06-28T01:38:05.529Z,1498613885.529 [Default:CheckIn:D] Running Loop=1
2017-06-28T01:38:05.965Z,1498613885.965 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2017-06-28T01:38:05.972Z,1498613885.972 [Default:CheckIn:D] Stopped
2017-06-28T01:38:05.972Z,1498613885.972 [Default:CheckIn:E] Running Loop=1
2017-06-28T01:38:06.342Z,1498613886.342 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2017-06-28T01:38:06.345Z,1498613886.345 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.099249 min 
2017-06-28T01:38:06.345Z,1498613886.345 [Default:CheckIn:E] Stopped
2017-06-28T01:38:06.346Z,1498613886.346 [Default:CheckIn](INFO): Completed Default:CheckIn
2017-06-28T01:38:06.346Z,1498613886.346 [Default:CheckIn] Stopped
2017-06-28T01:38:06.346Z,1498613886.346 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2017-06-28T01:38:06.346Z,1498613886.346 [Default:CheckIn](INFO): Running loop #4
2017-06-28T01:38:06.346Z,1498613886.346 [Default:CheckIn] Running Loop=4
2017-06-28T01:38:06.346Z,1498613886.346 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2017-06-28T01:38:06.346Z,1498613886.346 [Default:CheckIn:Read_GPS] Running Loop=1
2017-06-28T01:38:09.578Z,1498613889.578 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.7 s old, using for 20.0 s.
2017-06-28T01:38:09.730Z,1498613889.730 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.8 s old, using for 20.0 s.
2017-06-28T01:40:16.965Z,1498614016.965 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-06-28T01:40:16.965Z,1498614016.965 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2017-06-28T01:40:16.965Z,1498614016.965 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-06-28T01:40:16.967Z,1498614016.967 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-06-28T01:40:16.967Z,1498614016.967 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 12
2017-06-28T01:40:16.967Z,1498614016.967 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-06-28T01:40:16.000Z,1498614017.000 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-06-28T01:40:17.000Z,1498614017.000 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-06-28T01:40:17.372Z,1498614017.372 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-06-28T01:40:17.372Z,1498614017.372 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2017-06-28T01:40:17.373Z,1498614017.373 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-06-28T01:40:17.373Z,1498614017.373 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 12
2017-06-28T01:40:17.746Z,1498614017.746 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-06-28T01:40:17.746Z,1498614017.746 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-06-28T01:40:17.751Z,1498614017.751 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-06-28T01:40:17.751Z,1498614017.751 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-06-28T01:40:17.752Z,1498614017.752 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-06-28T01:40:17.752Z,1498614017.752 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-06-28T01:40:18.162Z,1498614018.162 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-06-28T01:40:18.571Z,1498614018.571 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-06-28T01:40:18.954Z,1498614018.954 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.0 s old, using for 20.0 s.
2017-06-28T01:40:19.366Z,1498614019.366 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.4 s old, using for 20.0 s.
2017-06-28T01:40:19.768Z,1498614019.768 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 2.8 s old, using for 20.0 s.
2017-06-28T01:40:20.162Z,1498614020.162 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.2 s old, using for 20.0 s.
2017-06-28T01:40:20.537Z,1498614020.537 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 3.6 s old, using for 20.0 s.
2017-06-28T01:40:20.939Z,1498614020.939 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.0 s old, using for 20.0 s.
2017-06-28T01:40:21.343Z,1498614021.343 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.4 s old, using for 20.0 s.
2017-06-28T01:40:21.751Z,1498614021.751 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.8 s old, using for 20.0 s.
2017-06-28T01:40:22.157Z,1498614022.157 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.2 s old, using for 20.0 s.
2017-06-28T01:40:22.549Z,1498614022.549 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.6 s old, using for 20.0 s.
2017-06-28T01:40:22.953Z,1498614022.953 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.0 s old, using for 20.0 s.
2017-06-28T01:40:23.348Z,1498614023.348 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.4 s old, using for 20.0 s.
2017-06-28T01:40:23.745Z,1498614023.745 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.8 s old, using for 20.0 s.
2017-06-28T01:40:24.149Z,1498614024.149 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.2 s old, using for 20.0 s.
2017-06-28T01:40:24.544Z,1498614024.544 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.6 s old, using for 20.0 s.
2017-06-28T01:40:24.951Z,1498614024.951 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.0 s old, using for 20.0 s.
2017-06-28T01:40:25.360Z,1498614025.360 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.4 s old, using for 20.0 s.
2017-06-28T01:40:25.781Z,1498614025.781 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.8 s old, using for 20.0 s.
2017-06-28T01:40:26.159Z,1498614026.159 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.2 s old, using for 20.0 s.
2017-06-28T01:40:26.563Z,1498614026.563 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.6 s old, using for 20.0 s.
2017-06-28T01:40:26.949Z,1498614026.949 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.0 s old, using for 20.0 s.
2017-06-28T01:40:27.347Z,1498614027.347 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.4 s old, using for 20.0 s.
2017-06-28T01:40:27.755Z,1498614027.755 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.8 s old, using for 20.0 s.
2017-06-28T01:40:28.162Z,1498614028.162 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.2 s old, using for 20.0 s.
2017-06-28T01:40:28.558Z,1498614028.558 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.6 s old, using for 20.0 s.
2017-06-28T01:40:29.002Z,1498614029.002 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.0 s old, using for 20.0 s.
2017-06-28T01:40:29.391Z,1498614029.391 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.4 s old, using for 20.0 s.
2017-06-28T01:40:29.743Z,1498614029.743 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.8 s old, using for 20.0 s.
2017-06-28T01:40:30.181Z,1498614030.181 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.2 s old, using for 20.0 s.
2017-06-28T01:40:30.559Z,1498614030.559 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.6 s old, using for 20.0 s.
2017-06-28T01:40:30.963Z,1498614030.963 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.0 s old, using for 20.0 s.
2017-06-28T01:40:31.411Z,1498614031.411 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.4 s old, using for 20.0 s.
2017-06-28T01:40:31.774Z,1498614031.774 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.8 s old, using for 20.0 s.
2017-06-28T01:40:32.158Z,1498614032.158 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.2 s old, using for 20.0 s.
2017-06-28T01:40:32.538Z,1498614032.538 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.6 s old, using for 20.0 s.
2017-06-28T01:40:32.936Z,1498614032.936 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.0 s old, using for 20.0 s.
2017-06-28T01:40:33.346Z,1498614033.346 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.4 s old, using for 20.0 s.
2017-06-28T01:40:33.751Z,1498614033.751 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.8 s old, using for 20.0 s.
2017-06-28T01:40:34.179Z,1498614034.179 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.2 s old, using for 20.0 s.
2017-06-28T01:40:34.578Z,1498614034.578 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.6 s old, using for 20.0 s.
2017-06-28T01:40:34.974Z,1498614034.974 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.0 s old, using for 20.0 s.
2017-06-28T01:40:35.353Z,1498614035.353 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.4 s old, using for 20.0 s.
2017-06-28T01:40:35.750Z,1498614035.750 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.8 s old, using for 20.0 s.
2017-06-28T01:40:36.156Z,1498614036.156 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.2 s old, using for 20.0 s.
2017-06-28T01:40:36.550Z,1498614036.550 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.6 s old, using for 20.0 s.
2017-06-28T01:40:36.943Z,1498614036.943 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 20.0 s old, using for 20.0 s.
2017-06-28T01:40:41.751Z,1498614041.751 [BPC1](ERROR): Failed to parse bank A battery data
2017-06-28T01:40:41.751Z,1498614041.751 [BPC1] Data Fault, FailCount= 1
2017-06-28T01:40:41.751Z,1498614041.751 [BPC1](ERROR): Data Fault
2017-06-28T01:40:41.798Z,1498614041.798 [CBIT](ERROR): Data Fault in component: BPC1
2017-06-28T01:40:42.979Z,1498614042.979 [CBIT](INFO): Clearing failed state for component BPC1
2017-06-28T01:40:42.979Z,1498614042.979 [BPC1] No Fault, FailCount= 1
2017-06-28T01:42:20.507Z,1498614140.507 [BPC1](ERROR): Failed to receive battery data
2017-06-28T01:42:20.507Z,1498614140.507 [BPC1] Communications Fault, FailCount= 1
2017-06-28T01:42:20.507Z,1498614140.507 [BPC1](ERROR): Communications Fault
2017-06-28T01:42:20.529Z,1498614140.529 [CBIT](ERROR): Communications Fault in component: BPC1
2017-06-28T01:42:21.743Z,1498614141.743 [CBIT](INFO): Clearing failed state for component BPC1
2017-06-28T01:42:21.743Z,1498614141.743 [BPC1] No Fault, FailCount= 1
2017-06-28T01:43:06.514Z,1498614186.514 [Default:CheckIn:Read_GPS](INFO): Timed out from 2017-06-28T01:38:06.3Z
2017-06-28T01:43:06.514Z,1498614186.514 [Default:CheckIn:Read_GPS] Stopped
2017-06-28T01:43:06.515Z,1498614186.515 [Default:CheckIn:Read_Iridium] Running Loop=1
2017-06-28T01:43:10.649Z,1498614190.649 [DataOverHttps](INFO): Sending 45 bytes from file Logs/20170628T010343/Courier0016.lzma
2017-06-28T01:43:11.387Z,1498614191.387 [DataOverHttps](INFO): Moved sent file to Logs/20170628T010343/Courier0016.lzma.bak
2017-06-28T01:43:11.388Z,1498614191.388 [DataOverHttps](INFO): SBD MOMSN=5047148
2017-06-28T01:43:20.541Z,1498614200.541 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-06-28T01:43:20.541Z,1498614200.541 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2017-06-28T01:43:20.541Z,1498614200.541 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-06-28T01:43:20.543Z,1498614200.543 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-06-28T01:43:20.543Z,1498614200.543 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 13
2017-06-28T01:43:20.543Z,1498614200.543 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-06-28T01:43:20.567Z,1498614200.567 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-06-28T01:43:20.568Z,1498614200.568 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-06-28T01:43:20.628Z,1498614200.628 [DataOverHttps](INFO): Sending 260 bytes from file Logs/20170628T010343/Express0017.lzma
2017-06-28T01:43:20.718Z,1498614200.718 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-06-28T01:43:20.718Z,1498614200.718 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2017-06-28T01:43:20.719Z,1498614200.719 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-06-28T01:43:20.719Z,1498614200.719 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 13
2017-06-28T01:43:21.061Z,1498614201.061 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-06-28T01:43:21.062Z,1498614201.062 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-06-28T01:43:21.062Z,1498614201.062 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-06-28T01:43:21.063Z,1498614201.063 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-06-28T01:43:21.063Z,1498614201.063 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-06-28T01:43:21.063Z,1498614201.063 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-06-28T01:43:21.379Z,1498614201.379 [DataOverHttps](INFO): Moved sent file to Logs/20170628T010343/Express0017.lzma.bak
2017-06-28T01:43:21.379Z,1498614201.379 [DataOverHttps](INFO): SBD MOMSN=5047150
2017-06-28T01:43:21.462Z,1498614201.462 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 0.9 s old, using for 20.0 s.
2017-06-28T01:43:21.896Z,1498614201.896 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.4 s old, using for 20.0 s.
2017-06-28T01:43:21.899Z,1498614201.899 [Default:CheckIn:Read_Iridium] Stopped
2017-06-28T01:43:21.899Z,1498614201.899 [Default:CheckIn:C.Wait] Running Loop=1
2017-06-28T01:43:21.899Z,1498614201.899 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2017-06-28T01:43:22.276Z,1498614202.276 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.7 s old, using for 20.0 s.
2017-06-28T01:43:25.489Z,1498614205.489 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.9 s old, using for 20.0 s.
2017-06-28T01:43:25.627Z,1498614205.627 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.1 s old, using for 20.0 s.
2017-06-28T01:43:26.006Z,1498614206.006 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.5 s old, using for 20.0 s.
2017-06-28T01:43:26.401Z,1498614206.401 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.9 s old, using for 20.0 s.
2017-06-28T01:43:26.802Z,1498614206.802 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.3 s old, using for 20.0 s.
2017-06-28T01:43:27.200Z,1498614207.200 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.7 s old, using for 20.0 s.
2017-06-28T01:43:27.604Z,1498614207.604 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.1 s old, using for 20.0 s.
2017-06-28T01:43:28.005Z,1498614208.005 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.5 s old, using for 20.0 s.
2017-06-28T01:43:28.397Z,1498614208.397 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.9 s old, using for 20.0 s.
2017-06-28T01:43:28.805Z,1498614208.805 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.3 s old, using for 20.0 s.
2017-06-28T01:43:29.197Z,1498614209.197 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.7 s old, using for 20.0 s.
2017-06-28T01:43:29.596Z,1498614209.596 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.1 s old, using for 20.0 s.
2017-06-28T01:43:30.004Z,1498614210.004 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.5 s old, using for 20.0 s.
2017-06-28T01:43:30.406Z,1498614210.406 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.9 s old, using for 20.0 s.
2017-06-28T01:43:30.810Z,1498614210.810 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.3 s old, using for 20.0 s.
2017-06-28T01:43:31.203Z,1498614211.203 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.7 s old, using for 20.0 s.
2017-06-28T01:43:31.595Z,1498614211.595 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.1 s old, using for 20.0 s.
2017-06-28T01:43:31.999Z,1498614211.999 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.5 s old, using for 20.0 s.
2017-06-28T01:43:32.393Z,1498614212.393 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.9 s old, using for 20.0 s.
2017-06-28T01:43:32.781Z,1498614212.781 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.2 s old, using for 20.0 s.
2017-06-28T01:43:33.218Z,1498614213.218 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.7 s old, using for 20.0 s.
2017-06-28T01:43:33.621Z,1498614213.621 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.1 s old, using for 20.0 s.
2017-06-28T01:43:34.012Z,1498614214.012 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.5 s old, using for 20.0 s.
2017-06-28T01:43:34.447Z,1498614214.447 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.9 s old, using for 20.0 s.
2017-06-28T01:43:34.792Z,1498614214.792 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.3 s old, using for 20.0 s.
2017-06-28T01:43:35.221Z,1498614215.221 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 14.7 s old, using for 20.0 s.
2017-06-28T01:43:35.598Z,1498614215.598 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.1 s old, using for 20.0 s.
2017-06-28T01:43:36.025Z,1498614216.025 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.5 s old, using for 20.0 s.
2017-06-28T01:43:36.435Z,1498614216.435 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 15.9 s old, using for 20.0 s.
2017-06-28T01:43:36.798Z,1498614216.798 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.3 s old, using for 20.0 s.
2017-06-28T01:43:37.200Z,1498614217.200 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 16.7 s old, using for 20.0 s.
2017-06-28T01:43:37.593Z,1498614217.593 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.1 s old, using for 20.0 s.
2017-06-28T01:43:37.996Z,1498614217.996 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.5 s old, using for 20.0 s.
2017-06-28T01:43:38.398Z,1498614218.398 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 17.9 s old, using for 20.0 s.
2017-06-28T01:43:38.799Z,1498614218.799 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.3 s old, using for 20.0 s.
2017-06-28T01:43:39.200Z,1498614219.200 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 18.7 s old, using for 20.0 s.
2017-06-28T01:43:39.604Z,1498614219.604 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.1 s old, using for 20.0 s.
2017-06-28T01:43:40.007Z,1498614220.007 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.5 s old, using for 20.0 s.
2017-06-28T01:43:40.393Z,1498614220.393 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 19.9 s old, using for 20.0 s.
2017-06-28T01:44:27.723Z,1498614267.723 [PNI_TCM](ERROR): CRC does not match. Expected:0x18130 got:0x32561
2017-06-28T01:44:37.710Z,1498614277.710 [PNI_TCM](ERROR): CRC does not match. Expected:0x21008 got:0x10267
2017-06-28T01:44:40.935Z,1498614280.935 [PNI_TCM](ERROR): CRC does not match. Expected:0x749 got:0x7105
2017-06-28T01:44:45.722Z,1498614285.722 [PNI_TCM](ERROR): CRC does not match. Expected:0x47380 got:0x16836
2017-06-28T01:44:54.934Z,1498614294.934 [PNI_TCM](ERROR): CRC does not match. Expected:0x14753 got:0x1857
2017-06-28T01:45:14.093Z,1498614314.093 [NAL9602](FAULT): GPS failed to acquire within timeout.
2017-06-28T01:45:14.093Z,1498614314.093 [NAL9602] Data Fault, FailCount= 4
2017-06-28T01:45:14.093Z,1498614314.093 [NAL9602](ERROR): Data Fault
2017-06-28T01:45:14.174Z,1498614314.174 [CBIT](ERROR): Data Fault in component: NAL9602
2017-06-28T01:45:14.489Z,1498614314.489 [NAL9602](INFO): Powering down
2017-06-28T01:45:15.387Z,1498614315.387 [CBIT](INFO): Clearing failed state for component NAL9602
2017-06-28T01:45:15.387Z,1498614315.387 [NAL9602] No Fault, FailCount= 4
2017-06-28T01:45:18.925Z,1498614318.925 [PNI_TCM](ERROR): CRC does not match. Expected:0x21867 got:0x2011
2017-06-28T01:45:19.701Z,1498614319.701 [NAL9602](INFO): Powering up NAL9602
2017-06-28T01:45:22.122Z,1498614322.122 [PNI_TCM](ERROR): CRC does not match. Expected:0x37391 got:0x8171
2017-06-28T01:45:30.655Z,1498614330.655 [NAL9602](INFO): NAL9602 initialized
2017-06-28T01:45:45.486Z,1498614345.486 [BPC1](ERROR): Failed to parse bank A battery data
2017-06-28T01:45:45.486Z,1498614345.486 [BPC1] Data Fault, FailCount= 1
2017-06-28T01:45:45.487Z,1498614345.487 [BPC1](ERROR): Data Fault
2017-06-28T01:45:45.529Z,1498614345.529 [CBIT](ERROR): Data Fault in component: BPC1
2017-06-28T01:45:46.763Z,1498614346.763 [CBIT](INFO): Clearing failed state for component BPC1
2017-06-28T01:45:46.763Z,1498614346.763 [BPC1] No Fault, FailCount= 1
2017-06-28T01:46:21.105Z,1498614381.105 [DeadReckonUsingMultipleVelocitySources](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-06-28T01:46:21.105Z,1498614381.105 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2017-06-28T01:46:21.106Z,1498614381.106 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2017-06-28T01:46:21.107Z,1498614381.107 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-06-28T01:46:21.108Z,1498614381.108 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 14
2017-06-28T01:46:21.108Z,1498614381.108 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2017-06-28T01:46:21.137Z,1498614381.137 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2017-06-28T01:46:21.138Z,1498614381.138 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2017-06-28T01:46:21.522Z,1498614381.522 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2017-06-28T01:46:21.522Z,1498614381.522 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2017-06-28T01:46:21.523Z,1498614381.523 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2017-06-28T01:46:21.523Z,1498614381.523 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 14
2017-06-28T01:46:21.890Z,1498614381.890 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-06-28T01:46:21.890Z,1498614381.890 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-06-28T01:46:21.891Z,1498614381.891 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-06-28T01:46:21.891Z,1498614381.891 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-06-28T01:46:21.892Z,1498614381.892 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-06-28T01:46:21.892Z,1498614381.892 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-06-28T01:46:22.295Z,1498614382.295 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.2 s old, using for 20.0 s.
2017-06-28T01:46:22.697Z,1498614382.697 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 1.6 s old, using for 20.0 s.
2017-06-28T01:46:25.979Z,1498614385.979 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 4.9 s old, using for 20.0 s.
2017-06-28T01:46:26.140Z,1498614386.140 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.1 s old, using for 20.0 s.
2017-06-28T01:46:26.479Z,1498614386.479 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.4 s old, using for 20.0 s.
2017-06-28T01:46:26.857Z,1498614386.857 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 5.8 s old, using for 20.0 s.
2017-06-28T01:46:27.253Z,1498614387.253 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.2 s old, using for 20.0 s.
2017-06-28T01:46:27.661Z,1498614387.661 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 6.6 s old, using for 20.0 s.
2017-06-28T01:46:28.110Z,1498614388.110 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.0 s old, using for 20.0 s.
2017-06-28T01:46:28.511Z,1498614388.511 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.4 s old, using for 20.0 s.
2017-06-28T01:46:28.860Z,1498614388.860 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 7.8 s old, using for 20.0 s.
2017-06-28T01:46:29.296Z,1498614389.296 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.2 s old, using for 20.0 s.
2017-06-28T01:46:29.669Z,1498614389.669 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 8.6 s old, using for 20.0 s.
2017-06-28T01:46:30.101Z,1498614390.101 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.0 s old, using for 20.0 s.
2017-06-28T01:46:30.500Z,1498614390.500 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.4 s old, using for 20.0 s.
2017-06-28T01:46:30.872Z,1498614390.872 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 9.8 s old, using for 20.0 s.
2017-06-28T01:46:31.261Z,1498614391.261 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.2 s old, using for 20.0 s.
2017-06-28T01:46:31.664Z,1498614391.664 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 10.6 s old, using for 20.0 s.
2017-06-28T01:46:32.057Z,1498614392.057 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.0 s old, using for 20.0 s.
2017-06-28T01:46:32.458Z,1498614392.458 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.4 s old, using for 20.0 s.
2017-06-28T01:46:32.842Z,1498614392.842 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 11.8 s old, using for 20.0 s.
2017-06-28T01:46:33.241Z,1498614393.241 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.2 s old, using for 20.0 s.
2017-06-28T01:46:33.674Z,1498614393.674 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 12.6 s old, using for 20.0 s.
2017-06-28T01:46:34.080Z,1498614394.080 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.0 s old, using for 20.0 s.
2017-06-28T01:46:34.475Z,1498614394.475 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.4 s old, using for 20.0 s.
2017-06-28T01:46:34.867Z,1498614394.867 [DeadReckonUsingMultipleVelocitySources](DEBUG): Bottom track data is 13.8 s old, using for 20.0 s.
2017-06-28T01:46:35.250Z,1498614395