2018-05-31T21:50:06.333Z,1527803406.333 [Supervisor](DEBUG): Initializing supervisor.
2018-05-31T21:50:06.336Z,1527803406.336 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2018-05-31T21:50:06.337Z,1527803406.337 [SyncHandler](INFO): Protected caller Thread ID is 5048
2018-05-31T21:50:06.337Z,1527803406.337 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2018-05-31T21:50:06.338Z,1527803406.338 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2018-05-31T21:50:06.338Z,1527803406.338 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5049
2018-05-31T21:50:06.341Z,1527803406.341 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2018-05-31T21:50:06.353Z,1527803406.353 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2018-05-31T21:50:06.354Z,1527803406.354 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2018-05-31T21:50:06.354Z,1527803406.354 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5050
2018-05-31T21:50:06.355Z,1527803406.355 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2018-05-31T21:50:06.356Z,1527803406.356 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2018-05-31T21:50:06.356Z,1527803406.356 [logger ThreadHandler](INFO): Protected caller Thread ID is 5051
2018-05-31T21:50:06.358Z,1527803406.358 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2018-05-31T21:50:06.359Z,1527803406.359 [Supervisor](INFO): Looking for Config files in directory: Config/
2018-05-31T21:50:06.360Z,1527803406.360 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2018-05-31T21:50:06.460Z,1527803406.460 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2018-05-31T21:50:06.461Z,1527803406.461 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2018-05-31T21:50:06.562Z,1527803406.562 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2018-05-31T21:50:06.563Z,1527803406.563 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2018-05-31T21:50:06.769Z,1527803406.769 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2018-05-31T21:50:06.770Z,1527803406.770 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2018-05-31T21:50:07.099Z,1527803407.099 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2018-05-31T21:50:07.099Z,1527803407.099 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2018-05-31T21:50:07.450Z,1527803407.450 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2018-05-31T21:50:07.450Z,1527803407.450 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2018-05-31T21:50:07.926Z,1527803407.926 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2018-05-31T21:50:07.926Z,1527803407.926 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2018-05-31T21:50:08.328Z,1527803408.328 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2018-05-31T21:50:08.328Z,1527803408.328 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2018-05-31T21:50:08.805Z,1527803408.805 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2018-05-31T21:50:08.805Z,1527803408.805 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2018-05-31T21:50:08.886Z,1527803408.886 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2018-05-31T21:50:09.196Z,1527803409.196 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2018-05-31T21:50:09.197Z,1527803409.197 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2018-05-31T21:50:09.343Z,1527803409.343 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2018-05-31T21:50:09.344Z,1527803409.344 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2018-05-31T21:50:09.557Z,1527803409.557 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2018-05-31T21:50:09.557Z,1527803409.557 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2018-05-31T21:50:09.778Z,1527803409.778 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2018-05-31T21:50:09.779Z,1527803409.779 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2018-05-31T21:50:09.876Z,1527803409.876 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2018-05-31T21:50:09.877Z,1527803409.877 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2018-05-31T21:50:10.020Z,1527803410.020 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2018-05-31T21:50:10.022Z,1527803410.022 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2018-05-31T21:50:10.022Z,1527803410.022 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2018-05-31T21:50:10.256Z,1527803410.256 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2018-05-31T21:50:10.256Z,1527803410.256 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2018-05-31T21:50:10.362Z,1527803410.362 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2018-05-31T21:50:10.509Z,1527803410.509 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2018-05-31T21:50:10.648Z,1527803410.648 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2018-05-31T21:50:10.865Z,1527803410.865 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2018-05-31T21:50:11.034Z,1527803411.034 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2018-05-31T21:50:11.396Z,1527803411.396 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2018-05-31T21:50:11.594Z,1527803411.594 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2018-05-31T21:50:11.734Z,1527803411.734 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2018-05-31T21:50:11.867Z,1527803411.867 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2018-05-31T21:50:11.951Z,1527803411.951 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2018-05-31T21:50:11.951Z,1527803411.951 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2018-05-31T21:50:11.954Z,1527803411.954 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2018-05-31T21:50:12.055Z,1527803412.055 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2018-05-31T21:50:12.056Z,1527803412.056 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2018-05-31T21:50:12.069Z,1527803412.069 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2018-05-31T21:50:12.069Z,1527803412.069 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2018-05-31T21:50:12.111Z,1527803412.111 [DepthRateCalculator] Loaded
2018-05-31T21:50:12.111Z,1527803412.111 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2018-05-31T21:50:12.117Z,1527803412.117 [PitchRateCalculator] Loaded
2018-05-31T21:50:12.118Z,1527803412.118 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2018-05-31T21:50:12.134Z,1527803412.134 [SpeedCalculator] Loaded
2018-05-31T21:50:12.134Z,1527803412.134 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2018-05-31T21:50:12.156Z,1527803412.156 [TempGradientCalculator] Loaded
2018-05-31T21:50:12.156Z,1527803412.156 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2018-05-31T21:50:12.172Z,1527803412.172 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2018-05-31T21:50:12.172Z,1527803412.172 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2018-05-31T21:50:12.178Z,1527803412.178 [YawRateCalculator] Loaded
2018-05-31T21:50:12.178Z,1527803412.178 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2018-05-31T21:50:12.219Z,1527803412.219 [ElevatorOffsetCalculator] Loaded
2018-05-31T21:50:12.219Z,1527803412.219 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2018-05-31T21:50:12.219Z,1527803412.219 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2018-05-31T21:50:12.220Z,1527803412.220 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2018-05-31T21:50:12.244Z,1527803412.244 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2018-05-31T21:50:12.245Z,1527803412.245 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2018-05-31T21:50:12.516Z,1527803412.516 [AcousticModem_Benthos_ATM900] Loaded
2018-05-31T21:50:12.516Z,1527803412.516 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2018-05-31T21:50:12.591Z,1527803412.591 [DataOverHttps] Loaded
2018-05-31T21:50:12.591Z,1527803412.591 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2018-05-31T21:50:12.605Z,1527803412.605 [Depth_Keller] Loaded
2018-05-31T21:50:12.605Z,1527803412.605 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2018-05-31T21:50:12.706Z,1527803412.706 [NAL9602] Loaded
2018-05-31T21:50:12.706Z,1527803412.706 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2018-05-31T21:50:12.722Z,1527803412.722 [Onboard] Loaded
2018-05-31T21:50:12.722Z,1527803412.722 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2018-05-31T21:50:12.733Z,1527803412.733 [Radio_Surface] Loaded
2018-05-31T21:50:12.733Z,1527803412.733 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2018-05-31T21:50:12.734Z,1527803412.734 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407B74E0
2018-05-31T21:50:12.735Z,1527803412.735 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5130
2018-05-31T21:50:12.783Z,1527803412.783 [PNI_TCM] Loaded
2018-05-31T21:50:12.783Z,1527803412.783 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2018-05-31T21:50:12.846Z,1527803412.846 [Rowe_600LCM] Loaded
2018-05-31T21:50:12.846Z,1527803412.846 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2018-05-31T21:50:12.847Z,1527803412.847 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 407E74E0
2018-05-31T21:50:12.847Z,1527803412.847 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 5131
2018-05-31T21:50:14.639Z,1527803414.639 [BPC1] Loaded
2018-05-31T21:50:14.639Z,1527803414.639 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2018-05-31T21:50:14.639Z,1527803414.639 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2018-05-31T21:50:14.640Z,1527803414.640 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2018-05-31T21:50:14.770Z,1527803414.770 [SBIT](DEBUG): Construct Startup Built In Test.
2018-05-31T21:50:14.794Z,1527803414.794 [SBIT] Loaded
2018-05-31T21:50:14.794Z,1527803414.794 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2018-05-31T21:50:14.795Z,1527803414.795 [IBIT](DEBUG): Construct Initiated Built In Test.
2018-05-31T21:50:14.807Z,1527803414.807 [IBIT] Loaded
2018-05-31T21:50:14.807Z,1527803414.807 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2018-05-31T21:50:14.810Z,1527803414.810 [CBIT](DEBUG): Construct Continuous Built In Test.
2018-05-31T21:50:14.964Z,1527803414.964 [CBIT] Loaded
2018-05-31T21:50:14.964Z,1527803414.964 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2018-05-31T21:50:14.964Z,1527803414.964 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2018-05-31T21:50:14.965Z,1527803414.965 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2018-05-31T21:50:15.106Z,1527803415.106 [Aanderaa_O2] Loaded
2018-05-31T21:50:15.106Z,1527803415.106 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2018-05-31T21:50:15.155Z,1527803415.155 [CTD_Seabird] Loaded
2018-05-31T21:50:15.156Z,1527803415.156 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2018-05-31T21:50:15.157Z,1527803415.157 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 408A74E0
2018-05-31T21:50:15.157Z,1527803415.157 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 5132
2018-05-31T21:50:15.174Z,1527803415.174 [ESPComponent] Loaded
2018-05-31T21:50:15.174Z,1527803415.174 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2018-05-31T21:50:15.188Z,1527803415.188 [PAR_Licor] Loaded
2018-05-31T21:50:15.189Z,1527803415.189 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2018-05-31T21:50:15.239Z,1527803415.239 [WetLabsBB2FL] Loaded
2018-05-31T21:50:15.240Z,1527803415.240 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2018-05-31T21:50:15.241Z,1527803415.241 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 408D74E0
2018-05-31T21:50:15.241Z,1527803415.241 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5133
2018-05-31T21:50:15.242Z,1527803415.242 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2018-05-31T21:50:15.242Z,1527803415.242 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2018-05-31T21:50:15.482Z,1527803415.482 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2018-05-31T21:50:15.483Z,1527803415.483 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2018-05-31T21:50:15.550Z,1527803415.550 [VerticalControl](DEBUG): Construct VerticalControl.
2018-05-31T21:50:15.647Z,1527803415.647 [VerticalControl] Loaded
2018-05-31T21:50:15.647Z,1527803415.647 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2018-05-31T21:50:15.648Z,1527803415.648 [HorizontalControl](DEBUG): Construct HorizontalControl.
2018-05-31T21:50:15.711Z,1527803415.711 [HorizontalControl] Loaded
2018-05-31T21:50:15.711Z,1527803415.711 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2018-05-31T21:50:15.712Z,1527803415.712 [SpeedControl](DEBUG): Construct SpeedControl.
2018-05-31T21:50:15.717Z,1527803415.717 [SpeedControl] Loaded
2018-05-31T21:50:15.717Z,1527803415.717 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2018-05-31T21:50:15.718Z,1527803415.718 [LoopControl](DEBUG): Construct LoopControl.
2018-05-31T21:50:15.719Z,1527803415.719 [LoopControl] Loaded
2018-05-31T21:50:15.719Z,1527803415.719 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2018-05-31T21:50:15.719Z,1527803415.719 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2018-05-31T21:50:15.720Z,1527803415.720 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2018-05-31T21:50:15.886Z,1527803415.886 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2018-05-31T21:50:15.886Z,1527803415.886 [StratificationFrontDetector](DEBUG): (re)initializing
2018-05-31T21:50:15.886Z,1527803415.886 [StratificationFrontDetector] Loaded
2018-05-31T21:50:15.887Z,1527803415.887 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2018-05-31T21:50:15.887Z,1527803415.887 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2018-05-31T21:50:15.888Z,1527803415.888 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2018-05-31T21:50:15.986Z,1527803415.986 [BuoyancyServo] Loaded
2018-05-31T21:50:15.986Z,1527803415.986 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2018-05-31T21:50:15.998Z,1527803415.998 [ElevatorServo] Loaded
2018-05-31T21:50:15.998Z,1527803415.998 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2018-05-31T21:50:16.009Z,1527803416.009 [MassServo] Loaded
2018-05-31T21:50:16.009Z,1527803416.009 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2018-05-31T21:50:16.021Z,1527803416.021 [RudderServo] Loaded
2018-05-31T21:50:16.021Z,1527803416.021 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2018-05-31T21:50:16.032Z,1527803416.032 [ThrusterServo] Loaded
2018-05-31T21:50:16.033Z,1527803416.033 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2018-05-31T21:50:16.033Z,1527803416.033 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2018-05-31T21:50:16.034Z,1527803416.034 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2018-05-31T21:50:16.126Z,1527803416.126 [DeadReckonUsingSpeedCalculator] Loaded
2018-05-31T21:50:16.126Z,1527803416.126 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2018-05-31T21:50:16.142Z,1527803416.142 [NavChart] Loaded
2018-05-31T21:50:16.143Z,1527803416.143 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2018-05-31T21:50:16.147Z,1527803416.147 [UniversalFixResidualReporter] Loaded
2018-05-31T21:50:16.147Z,1527803416.147 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2018-05-31T21:50:16.147Z,1527803416.147 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2018-05-31T21:50:16.151Z,1527803416.151 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2018-05-31T21:50:16.152Z,1527803416.152 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2018-05-31T21:50:16.159Z,1527803416.159 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2018-05-31T21:50:16.160Z,1527803416.160 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A304E0
2018-05-31T21:50:16.160Z,1527803416.160 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5134
2018-05-31T21:50:16.165Z,1527803416.165 [Supervisor](INFO): Main Thread ID is 4448
2018-05-31T21:50:16.165Z,1527803416.165 [Supervisor](DEBUG): Running supervisor.
2018-05-31T21:50:16.166Z,1527803416.166 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5135
2018-05-31T21:50:16.168Z,1527803416.168 [controlThread ThreadHandler](INFO): Handler Thread ID is 5136
2018-05-31T21:50:16.169Z,1527803416.169 [controlThread](DEBUG): Initializing ControlThread
2018-05-31T21:50:16.170Z,1527803416.170 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-05-31T21:50:16.170Z,1527803416.170 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2018-05-31T21:50:16.170Z,1527803416.170 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2018-05-31T21:50:16.171Z,1527803416.171 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2018-05-31T21:50:16.171Z,1527803416.171 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2018-05-31T21:50:16.172Z,1527803416.172 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2018-05-31T21:50:16.172Z,1527803416.172 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2018-05-31T21:50:16.178Z,1527803416.178 [SBIT](INFO): Initialize SBIT Component.
2018-05-31T21:50:16.178Z,1527803416.178 [SBIT](IMPORTANT): git: 2018-05-22-8-g8382578
2018-05-31T21:50:16.179Z,1527803416.179 [SBIT](INFO): git hash: 83825789aaa96a7be17ace6d33dcc0adbd55c958
2018-05-31T21:50:16.179Z,1527803416.179 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2018-05-31T21:50:16.180Z,1527803416.180 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
2018-05-31T21:50:16.182Z,1527803416.182 [SBIT](INFO): Beginning SBIT in 63.000000 seconds.
2018-05-31T21:50:16.182Z,1527803416.182 [IBIT](INFO): Initialize IBIT Component.
2018-05-31T21:50:16.183Z,1527803416.183 [CBIT](DEBUG): Initialize CBIT Component.
2018-05-31T21:50:16.183Z,1527803416.183 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2018-05-31T21:50:16.184Z,1527803416.184 [logger ThreadHandler](INFO): Handler Thread ID is 5137
2018-05-31T21:50:16.201Z,1527803416.201 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5138
2018-05-31T21:50:16.207Z,1527803416.207 [Radio_Surface](INFO): Powering up
2018-05-31T21:50:16.213Z,1527803416.213 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 5139
2018-05-31T21:50:16.214Z,1527803416.214 [Rowe_600LCM](INFO): Initializing
2018-05-31T21:50:16.214Z,1527803416.214 [Rowe_600LCM](INFO): Checking LCM
2018-05-31T21:50:16.224Z,1527803416.224 [Rowe_600LCM](INFO): LCM OK
2018-05-31T21:50:16.224Z,1527803416.224 [Rowe_600LCM](INFO): Powering up
2018-05-31T21:50:16.229Z,1527803416.229 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 5140
2018-05-31T21:50:16.230Z,1527803416.230 [CTD_Seabird](INFO): Initializing
2018-05-31T21:50:16.231Z,1527803416.231 [CTD_Seabird](INFO): Checking LCM
2018-05-31T21:50:16.231Z,1527803416.231 [CTD_Seabird](INFO): LCM OK
2018-05-31T21:50:16.231Z,1527803416.231 [CTD_Seabird](INFO): Powering up
2018-05-31T21:50:16.237Z,1527803416.237 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5141
2018-05-31T21:50:16.238Z,1527803416.238 [WetLabsBB2FL](INFO): Powering down
2018-05-31T21:50:16.261Z,1527803416.261 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5142
2018-05-31T21:50:16.264Z,1527803416.264 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2018-05-31T21:50:16.265Z,1527803416.265 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2018-05-31T21:50:16.265Z,1527803416.265 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2018-05-31T21:50:16.266Z,1527803416.266 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2018-05-31T21:50:16.266Z,1527803416.266 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2018-05-31T21:50:16.266Z,1527803416.266 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2018-05-31T21:50:16.266Z,1527803416.266 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2018-05-31T21:50:16.266Z,1527803416.266 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2018-05-31T21:50:16.267Z,1527803416.267 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2018-05-31T21:50:16.267Z,1527803416.267 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2018-05-31T21:50:16.267Z,1527803416.267 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2018-05-31T21:50:16.267Z,1527803416.267 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2018-05-31T21:50:16.267Z,1527803416.267 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2018-05-31T21:50:16.268Z,1527803416.268 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2018-05-31T21:50:16.268Z,1527803416.268 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2018-05-31T21:50:16.268Z,1527803416.268 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2018-05-31T21:50:16.293Z,1527803416.293 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2018-05-31T21:50:16.295Z,1527803416.295 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2018-05-31T21:50:16.296Z,1527803416.296 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2018-05-31T21:50:16.296Z,1527803416.296 [LoopControl](DEBUG): Initialize LoopControlComponent.
2018-05-31T21:50:16.298Z,1527803416.298 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T21:50:16.299Z,1527803416.299 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T21:50:16.299Z,1527803416.299 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T21:50:16.300Z,1527803416.300 [NavChart](DEBUG): Initialize NavChart Navigation.
2018-05-31T21:50:16.300Z,1527803416.300 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2018-05-31T21:50:16.302Z,1527803416.302 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2018-05-31T21:50:16.317Z,1527803416.317 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2018-05-31T21:50:16.348Z,1527803416.348 [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>


2018-05-31T21:50:16.349Z,1527803416.349 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2018-05-31T21:50:16.442Z,1527803416.442 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2018-05-31T21:50:16.443Z,1527803416.443 [Default:A.Wait](DEBUG): Construct Wait.
2018-05-31T21:50:16.445Z,1527803416.445 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2018-05-31T21:50:16.464Z,1527803416.464 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2018-05-31T21:50:16.491Z,1527803416.491 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2018-05-31T21:50:16.513Z,1527803416.513 [Default:E.Execute](DEBUG): Construct Execute.
2018-05-31T21:50:16.516Z,1527803416.516 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <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">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <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>


2018-05-31T21:50:16.530Z,1527803416.530 [controlThread](DEBUG): Component order: CycleStarter,AcousticModem_Benthos_ATM900,DataOverHttps,Depth_Keller,NAL9602,Onboard,PNI_TCM,BPC1,Aanderaa_O2,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,ElevatorOffsetCalculator,StratificationFrontDetector,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2018-05-31T21:50:16.554Z,1527803416.554 [AcousticModem_Benthos_ATM900](INFO): Powering up
2018-05-31T21:50:16.555Z,1527803416.555 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2018-05-31T21:50:16.586Z,1527803416.586 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2018-05-31T21:50:16.915Z,1527803416.915 [ESPComponent](INFO): powering down ESP
2018-05-31T21:50:17.446Z,1527803417.446 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-31T21:50:17.570Z,1527803417.570 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-05-31T21:50:17.577Z,1527803417.577 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-05-31T21:50:17.601Z,1527803417.601 [ElevatorServo](DEBUG): Initializing EZServoServo.
2018-05-31T21:50:17.609Z,1527803417.609 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2018-05-31T21:50:17.623Z,1527803417.623 [MassServo](DEBUG): Initializing EZServoServo.
2018-05-31T21:50:17.629Z,1527803417.629 [MassServo](DEBUG): Initializing MassServo.
2018-05-31T21:50:17.651Z,1527803417.651 [RudderServo](DEBUG): Initializing EZServoServo.
2018-05-31T21:50:17.657Z,1527803417.657 [RudderServo](DEBUG): Initializing RudderServo.
2018-05-31T21:50:17.671Z,1527803417.671 [ThrusterServo](DEBUG): Initializing EZServoServo.
2018-05-31T21:50:17.677Z,1527803417.677 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2018-05-31T21:50:17.954Z,1527803417.954 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-31T21:50:18.212Z,1527803418.212 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-31T21:50:18.557Z,1527803418.557 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2018-05-31T21:50:18.557Z,1527803418.557 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2018-05-31T21:50:18.557Z,1527803418.557 [BuoyancyServo] Communications Fault, FailCount= 1
2018-05-31T21:50:18.557Z,1527803418.557 [BuoyancyServo](ERROR): Communications Fault
2018-05-31T21:50:18.769Z,1527803418.769 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2018-05-31T21:50:18.824Z,1527803418.824 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-31T21:50:18.847Z,1527803418.847 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-05-31T21:50:18.848Z,1527803418.848 [BuoyancyServo](INFO): Powering down
2018-05-31T21:50:19.253Z,1527803419.253 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-31T21:50:19.653Z,1527803419.653 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-31T21:50:20.121Z,1527803420.121 [Aanderaa_O2](INFO): Powering down
2018-05-31T21:50:20.259Z,1527803420.259 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-31T21:50:20.506Z,1527803420.506 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2018-05-31T21:50:20.612Z,1527803420.612 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-05-31T21:50:20.692Z,1527803420.692 [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 9600 >& /dev/null &
2018-05-31T21:50:20.734Z,1527803420.734 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-05-31T21:50:20.735Z,1527803420.735 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-05-31T21:50:20.735Z,1527803420.735 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-05-31T21:50:21.046Z,1527803421.046 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2018-05-31T21:50:21.046Z,1527803421.046 [BuoyancyServo] No Fault, FailCount= 1
2018-05-31T21:50:21.387Z,1527803421.387 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2018-05-31T21:50:21.388Z,1527803421.388 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2018-05-31T21:50:21.913Z,1527803421.913 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-05-31T21:50:22.154Z,1527803422.154 [CTD_Seabird](INFO): Started Seabird LCM interface with command:nohup ./lrauv-framework/onboard/bin/gpctd -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyC6 -b 9600 >& /dev/null &
2018-05-31T21:50:22.156Z,1527803422.156 [CTD_Seabird](INFO): LCM subscribed to channel:ctd_t.seabird-gpctd
2018-05-31T21:50:23.078Z,1527803423.078 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-05-31T21:50:23.079Z,1527803423.079 [CTD_Seabird](INFO): Powering down
2018-05-31T21:50:24.144Z,1527803424.144 [AcousticModem_Benthos_ATM900](DEBUG): 
2018-05-31T21:50:25.345Z,1527803425.345 [AcousticModem_Benthos_ATM900](DEBUG): Teledyne Benthos ATM-900 Series OEM w/burn wire
2018-05-31T21:50:25.345Z,1527803425.345 [AcousticModem_Benthos_ATM900](DEBUG): MF Frequency Band
2018-05-31T21:50:25.346Z,1527803425.346 [AcousticModem_Benthos_ATM900](DEBUG): Standard version 8.6.3
2018-05-31T21:50:25.347Z,1527803425.347 [AcousticModem_Benthos_ATM900](DEBUG): Apr  7 2000 21:45:54 
2018-05-31T21:50:25.743Z,1527803425.743 [AcousticModem_Benthos_ATM900](DEBUG): WARNING: battery low
2018-05-31T21:50:26.543Z,1527803426.543 [AcousticModem_Benthos_ATM900](INFO): entering command mode
2018-05-31T21:50:26.942Z,1527803426.942 [AcousticModem_Benthos_ATM900](DEBUG): checking for command mode acknowledgment
2018-05-31T21:50:26.943Z,1527803426.943 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 1: user:1>
2018-05-31T21:50:26.943Z,1527803426.943 [AcousticModem_Benthos_ATM900](INFO): command mode acknowledged
2018-05-31T21:50:27.343Z,1527803427.343 [AcousticModem_Benthos_ATM900](INFO): setting local address to 3
2018-05-31T21:50:27.743Z,1527803427.743 [AcousticModem_Benthos_ATM900](DEBUG): checking for local address setting acknowledgment
2018-05-31T21:50:27.743Z,1527803427.743 [AcousticModem_Benthos_ATM900](INFO): set local address to 3
2018-05-31T21:50:28.146Z,1527803428.146 [AcousticModem_Benthos_ATM900](DEBUG): read user prompt 2: user:2>
2018-05-31T21:50:36.890Z,1527803436.890 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T21:50:37.686Z,1527803437.686 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T21:50:42.950Z,1527803442.950 [NAL9602](INFO): Powering up NAL9602
2018-05-31T21:50:54.147Z,1527803454.147 [NAL9602](INFO): NAL9602 initialized
2018-05-31T21:51:19.823Z,1527803479.823 [SBIT](IMPORTANT): Beginning Startup BIT
2018-05-31T21:51:19.835Z,1527803479.835 [CBIT](IMPORTANT): Beginning ground fault scan
2018-05-31T21:51:31.218Z,1527803491.218 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.009915
CHAN A1 (24V): -0.013311
CHAN A2 (12V): -0.005603
CHAN A3 (5V): -0.002596
CHAN B0 (3.3V): -0.001410
CHAN B1 (3.15aV): -0.001965
CHAN B2 (3.15bV): -0.001116
CHAN B3 (GND): -0.000062
OPEN: 0.005032
Full Scale Calc: 4.765 mA, -1.589 mA
2018-05-31T21:52:13.963Z,1527803533.963 [SBIT](IMPORTANT): SBIT PASSED
2018-05-31T21:52:14.053Z,1527803534.053 [CommandLine](IMPORTANT): got command configSet list
2018-05-31T21:52:14.053Z,1527803534.053 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2018-05-31T21:52:14.057Z,1527803534.057 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2018-05-31T21:52:14.355Z,1527803534.355 [MissionManager](IMPORTANT): Started mission Startup
2018-05-31T21:52:14.355Z,1527803534.355 [Startup] Running Loop=1
2018-05-31T21:52:14.355Z,1527803534.355 [Startup](DEBUG): Aggregate::initialize Startup
2018-05-31T21:52:14.355Z,1527803534.355 [Startup:A.GoToSurface] Running Loop=1
2018-05-31T21:52:14.356Z,1527803534.356 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-05-31T21:52:14.356Z,1527803534.356 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-05-31T21:52:14.357Z,1527803534.357 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-05-31T21:52:14.357Z,1527803534.357 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-05-31T21:52:14.358Z,1527803534.358 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-05-31T21:52:14.358Z,1527803534.358 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-05-31T21:52:14.364Z,1527803534.364 [Startup:StartupSatComms] Running Loop=1
2018-05-31T21:52:14.364Z,1527803534.364 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2018-05-31T21:52:14.365Z,1527803534.365 [Startup:StartupSatComms:A] Running Loop=1
2018-05-31T21:52:14.751Z,1527803534.751 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2018-05-31T21:52:16.237Z,1527803536.237 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-05-31T21:52:16.237Z,1527803536.237 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-05-31T21:52:16.237Z,1527803536.237 [Rowe_600LCM](ERROR): Communications Fault
2018-05-31T21:52:16.368Z,1527803536.368 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-05-31T21:52:16.642Z,1527803536.642 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-05-31T21:52:17.023Z,1527803537.023 [Rowe_600LCM](INFO): Powering down
2018-05-31T21:52:18.369Z,1527803538.369 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-05-31T21:52:18.370Z,1527803538.370 [Rowe_600LCM] No Fault, FailCount= 1
2018-05-31T21:52:18.637Z,1527803538.637 [Rowe_600LCM](INFO): Initializing
2018-05-31T21:52:18.637Z,1527803538.637 [Rowe_600LCM](INFO): Checking LCM
2018-05-31T21:52:18.638Z,1527803538.638 [Rowe_600LCM](INFO): LCM OK
2018-05-31T21:52:18.638Z,1527803538.638 [Rowe_600LCM](INFO): Powering up
2018-05-31T21:52:22.883Z,1527803542.883 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-05-31T21:52:22.948Z,1527803542.948 [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 9600 >& /dev/null &
2018-05-31T21:52:22.950Z,1527803542.950 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-05-31T21:52:22.951Z,1527803542.951 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-05-31T21:52:22.951Z,1527803542.951 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-05-31T21:53:06.118Z,1527803586.118 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T21:53:06.913Z,1527803586.913 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T21:53:14.751Z,1527803594.751 [Startup:StartupSatComms:A](INFO): Timed out from 2018-05-31T21:52:14.4Z
2018-05-31T21:53:14.751Z,1527803594.751 [Startup:StartupSatComms:A] Stopped
2018-05-31T21:53:14.751Z,1527803594.751 [Startup:StartupSatComms:B] Running Loop=1
2018-05-31T21:53:15.163Z,1527803595.163 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-05-31T21:53:16.344Z,1527803596.344 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T21:53:16.344Z,1527803596.344 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2018-05-31T21:53:16.344Z,1527803596.344 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T21:53:16.374Z,1527803596.374 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T21:53:16.781Z,1527803596.781 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T21:53:16.781Z,1527803596.781 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2018-05-31T21:53:17.151Z,1527803597.151 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T21:53:17.152Z,1527803597.152 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T21:53:17.152Z,1527803597.152 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T21:53:22.081Z,1527803602.081 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.014144
2018-05-31T21:53:26.536Z,1527803606.536 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180531T213658/Courier0009.lzma
2018-05-31T21:53:27.334Z,1527803607.334 [DataOverHttps](INFO): Moved sent file to Logs/20180531T213658/Courier0009.lzma.bak
2018-05-31T21:53:27.335Z,1527803607.335 [DataOverHttps](INFO): SBD MOMSN=8300795
2018-05-31T21:53:37.134Z,1527803617.134 [DataOverHttps](INFO): Sending 200 bytes from file Logs/20180531T213658/Express0010.lzma
2018-05-31T21:53:37.926Z,1527803617.926 [DataOverHttps](INFO): Moved sent file to Logs/20180531T213658/Express0010.lzma.bak
2018-05-31T21:53:37.926Z,1527803617.926 [DataOverHttps](INFO): SBD MOMSN=8300797
2018-05-31T21:53:47.543Z,1527803627.543 [DataOverHttps](INFO): Sending 733 bytes from file Logs/20180531T215006/Express0001.lzma
2018-05-31T21:53:48.336Z,1527803628.336 [DataOverHttps](INFO): Moved sent file to Logs/20180531T215006/Express0001.lzma.bak
2018-05-31T21:53:48.336Z,1527803628.336 [DataOverHttps](INFO): SBD MOMSN=8300808
2018-05-31T21:53:49.530Z,1527803629.530 [Startup:StartupSatComms:B] Stopped
2018-05-31T21:53:49.530Z,1527803629.530 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2018-05-31T21:53:49.530Z,1527803629.530 [Startup:StartupSatComms] Stopped
2018-05-31T21:53:49.530Z,1527803629.530 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2018-05-31T21:53:49.531Z,1527803629.531 [Startup](INFO): Completed Startup
2018-05-31T21:53:49.531Z,1527803629.531 [MissionManager](INFO): Startup is completed.
2018-05-31T21:53:49.531Z,1527803629.531 [MissionManager](INFO): Uninitializing Mission Startup
2018-05-31T21:53:49.532Z,1527803629.532 [Startup] Stopped
2018-05-31T21:53:49.532Z,1527803629.532 [Startup](DEBUG): Aggregate::uninitialize Startup
2018-05-31T21:53:49.532Z,1527803629.532 [Startup:A.GoToSurface] Stopped
2018-05-31T21:53:49.532Z,1527803629.532 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-05-31T21:53:49.674Z,1527803629.674 [MissionManager](IMPORTANT): Started mission Default
2018-05-31T21:53:49.675Z,1527803629.675 [Default] Running Loop=1
2018-05-31T21:53:49.675Z,1527803629.675 [Default](DEBUG): Aggregate::initialize Default
2018-05-31T21:53:49.675Z,1527803629.675 [Default:B.GoToSurface] Running Loop=1
2018-05-31T21:53:49.675Z,1527803629.675 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2018-05-31T21:53:49.675Z,1527803629.675 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2018-05-31T21:53:49.676Z,1527803629.676 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2018-05-31T21:53:49.676Z,1527803629.676 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2018-05-31T21:53:49.676Z,1527803629.676 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2018-05-31T21:53:49.681Z,1527803629.681 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2018-05-31T21:53:49.681Z,1527803629.681 [Default:A.Wait] Running Loop=1
2018-05-31T21:53:49.681Z,1527803629.681 [Default:A.Wait](DEBUG): Initialize Wait Component.
2018-05-31T21:54:02.703Z,1527803642.703 [Default:A.Wait](INFO): Done Waiting.
2018-05-31T21:54:02.703Z,1527803642.703 [Default:A.Wait] Stopped
2018-05-31T21:54:02.703Z,1527803642.703 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2018-05-31T21:54:03.111Z,1527803643.111 [Default:CheckIn] Running Loop=1
2018-05-31T21:54:03.111Z,1527803643.111 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-31T21:54:03.111Z,1527803643.111 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-31T21:54:03.524Z,1527803643.524 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2018-05-31T21:54:18.917Z,1527803658.917 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-05-31T21:54:18.917Z,1527803658.917 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-05-31T21:54:18.917Z,1527803658.917 [Rowe_600LCM](ERROR): Communications Fault
2018-05-31T21:54:19.299Z,1527803659.299 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-05-31T21:54:19.321Z,1527803659.321 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-05-31T21:54:19.724Z,1527803659.724 [Rowe_600LCM](INFO): Powering down
2018-05-31T21:54:21.106Z,1527803661.106 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-05-31T21:54:21.106Z,1527803661.106 [Rowe_600LCM] No Fault, FailCount= 2
2018-05-31T21:54:21.341Z,1527803661.341 [Rowe_600LCM](INFO): Initializing
2018-05-31T21:54:21.342Z,1527803661.342 [Rowe_600LCM](INFO): Checking LCM
2018-05-31T21:54:21.342Z,1527803661.342 [Rowe_600LCM](INFO): LCM OK
2018-05-31T21:54:21.342Z,1527803661.342 [Rowe_600LCM](INFO): Powering up
2018-05-31T21:54:25.543Z,1527803665.543 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-05-31T21:54:25.644Z,1527803665.644 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 9600 >& /dev/null &
2018-05-31T21:54:25.646Z,1527803665.646 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-05-31T21:54:25.647Z,1527803665.647 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-05-31T21:54:25.648Z,1527803665.648 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-05-31T21:54:43.558Z,1527803683.558 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T21:54:44.353Z,1527803684.353 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T21:55:37.363Z,1527803737.363 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T21:55:38.157Z,1527803738.157 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T21:55:57.024Z,1527803757.024 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2018-05-31T21:56:17.460Z,1527803777.460 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T21:56:17.460Z,1527803777.460 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2018-05-31T21:56:17.460Z,1527803777.460 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T21:56:17.496Z,1527803777.496 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T21:56:17.897Z,1527803777.897 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T21:56:17.897Z,1527803777.897 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2018-05-31T21:56:18.268Z,1527803778.268 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T21:56:18.268Z,1527803778.268 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T21:56:18.269Z,1527803778.269 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T21:56:22.097Z,1527803782.097 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-05-31T21:56:22.097Z,1527803782.097 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-05-31T21:56:22.097Z,1527803782.097 [Rowe_600LCM](ERROR): Communications Fault
2018-05-31T21:56:22.307Z,1527803782.307 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-05-31T21:56:22.501Z,1527803782.501 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-05-31T21:56:22.593Z,1527803782.593 [Rowe_600LCM](INFO): Powering down
2018-05-31T21:56:23.910Z,1527803783.910 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-05-31T21:56:23.911Z,1527803783.911 [Rowe_600LCM] No Fault, FailCount= 3
2018-05-31T21:56:24.217Z,1527803784.217 [Rowe_600LCM](INFO): Initializing
2018-05-31T21:56:24.218Z,1527803784.218 [Rowe_600LCM](INFO): Checking LCM
2018-05-31T21:56:24.218Z,1527803784.218 [Rowe_600LCM](INFO): LCM OK
2018-05-31T21:56:24.218Z,1527803784.218 [Rowe_600LCM](INFO): Powering up
2018-05-31T21:56:28.407Z,1527803788.407 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-05-31T21:56:28.480Z,1527803788.480 [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 9600 >& /dev/null &
2018-05-31T21:56:28.482Z,1527803788.482 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-05-31T21:56:28.483Z,1527803788.483 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-05-31T21:56:28.483Z,1527803788.483 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-05-31T21:56:31.427Z,1527803791.427 [NAL9602](ERROR): parseGSV uart error: serial timeout
2018-05-31T21:57:02.913Z,1527803822.913 [NAL9602](ERROR): getFix uart error: serial timeout
2018-05-31T21:57:25.514Z,1527803845.514 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T21:57:26.309Z,1527803846.309 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T21:57:33.414Z,1527803853.414 [NAL9602](ERROR): parseGSV uart error: serial timeout
2018-05-31T21:58:04.925Z,1527803884.925 [NAL9602](ERROR): getFix uart error: serial timeout
2018-05-31T21:58:24.309Z,1527803904.309 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-05-31T21:58:24.309Z,1527803904.309 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-05-31T21:58:24.309Z,1527803904.309 [Rowe_600LCM](ERROR): Communications Fault
2018-05-31T21:58:24.354Z,1527803904.354 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-05-31T21:58:24.713Z,1527803904.713 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-05-31T21:58:24.870Z,1527803904.870 [Rowe_600LCM](INFO): Powering down
2018-05-31T21:58:26.330Z,1527803906.330 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-05-31T21:58:26.330Z,1527803906.330 [Rowe_600LCM] No Fault, FailCount= 4
2018-05-31T21:58:26.485Z,1527803906.485 [Rowe_600LCM](INFO): Initializing
2018-05-31T21:58:26.486Z,1527803906.486 [Rowe_600LCM](INFO): Checking LCM
2018-05-31T21:58:26.486Z,1527803906.486 [Rowe_600LCM](INFO): LCM OK
2018-05-31T21:58:26.486Z,1527803906.486 [Rowe_600LCM](INFO): Powering up
2018-05-31T21:58:26.704Z,1527803906.704 [BPC1](ERROR): Failed to receive data from both battery packs
2018-05-31T21:58:26.704Z,1527803906.704 [BPC1] Communications Fault, FailCount= 1
2018-05-31T21:58:26.704Z,1527803906.704 [BPC1](ERROR): Communications Fault
2018-05-31T21:58:26.732Z,1527803906.732 [CBIT](ERROR): Communications Fault in component: BPC1
2018-05-31T21:58:27.927Z,1527803907.927 [CBIT](INFO): Clearing failed state for component BPC1
2018-05-31T21:58:27.927Z,1527803907.927 [BPC1] No Fault, FailCount= 1
2018-05-31T21:58:30.611Z,1527803910.611 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-05-31T21:58:30.725Z,1527803910.725 [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 9600 >& /dev/null &
2018-05-31T21:58:30.726Z,1527803910.726 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-05-31T21:58:30.727Z,1527803910.727 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-05-31T21:58:30.728Z,1527803910.728 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-05-31T21:58:35.471Z,1527803915.471 [NAL9602](ERROR): parseGSV uart error: serial timeout
2018-05-31T21:59:03.300Z,1527803943.300 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-05-31T21:54:03.1Z
2018-05-31T21:59:03.300Z,1527803943.300 [Default:CheckIn:Read_GPS] Stopped
2018-05-31T21:59:03.300Z,1527803943.300 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-31T21:59:03.696Z,1527803943.696 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2018-05-31T21:59:08.737Z,1527803948.737 [NAL9602](ERROR): getFix uart error: serial timeout
2018-05-31T21:59:09.503Z,1527803949.503 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180531T215006/Courier0004.lzma
2018-05-31T21:59:10.081Z,1527803950.081 [DataOverHttps](INFO): Moved sent file to Logs/20180531T215006/Courier0004.lzma.bak
2018-05-31T21:59:10.081Z,1527803950.081 [DataOverHttps](INFO): SBD MOMSN=8300835
2018-05-31T21:59:20.911Z,1527803960.911 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T21:59:20.912Z,1527803960.912 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2018-05-31T21:59:20.912Z,1527803960.912 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T21:59:20.967Z,1527803960.967 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T21:59:20.992Z,1527803960.992 [DataOverHttps](INFO): Sending 251 bytes from file Logs/20180531T215006/Express0005.lzma
2018-05-31T21:59:21.089Z,1527803961.089 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T21:59:21.089Z,1527803961.089 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2018-05-31T21:59:21.440Z,1527803961.440 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T21:59:21.461Z,1527803961.461 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T21:59:21.461Z,1527803961.461 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T21:59:21.792Z,1527803961.792 [DataOverHttps](INFO): Moved sent file to Logs/20180531T215006/Express0005.lzma.bak
2018-05-31T21:59:21.792Z,1527803961.792 [DataOverHttps](INFO): SBD MOMSN=8300837
2018-05-31T21:59:22.970Z,1527803962.970 [Default:CheckIn:Read_Iridium] Stopped
2018-05-31T21:59:22.970Z,1527803962.970 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-31T21:59:22.970Z,1527803962.970 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-31T21:59:39.779Z,1527803979.779 [NAL9602](ERROR): parseGSV uart error: serial timeout
2018-05-31T21:59:56.746Z,1527803996.746 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T21:59:57.541Z,1527803997.541 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T22:00:11.485Z,1527804011.485 [NAL9602](ERROR): getFix uart error: serial timeout
2018-05-31T22:00:26.691Z,1527804026.691 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-05-31T22:00:26.691Z,1527804026.691 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-05-31T22:00:26.691Z,1527804026.691 [Rowe_600LCM](ERROR): Communications Fault
2018-05-31T22:00:27.093Z,1527804027.093 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-05-31T22:00:27.267Z,1527804027.267 [Rowe_600LCM](INFO): Powering down
2018-05-31T22:00:27.905Z,1527804027.905 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-05-31T22:00:27.906Z,1527804027.906 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-05-31T22:00:41.923Z,1527804041.923 [NAL9602](ERROR): parseGSV uart error: serial timeout
2018-05-31T22:00:55.119Z,1527804055.119 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-05-31T22:00:55.119Z,1527804055.119 [NAL9602] Data Fault, FailCount= 1
2018-05-31T22:00:55.119Z,1527804055.119 [NAL9602](ERROR): Data Fault
2018-05-31T22:00:55.206Z,1527804055.206 [CBIT](ERROR): Data Fault in component: NAL9602
2018-05-31T22:00:55.518Z,1527804055.518 [NAL9602](INFO): Powering down
2018-05-31T22:00:56.381Z,1527804056.381 [CBIT](INFO): Clearing failed state for component NAL9602
2018-05-31T22:00:56.381Z,1527804056.381 [NAL9602] No Fault, FailCount= 1
2018-05-31T22:01:27.839Z,1527804087.839 [NAL9602](INFO): Powering up NAL9602
2018-05-31T22:01:38.750Z,1527804098.750 [NAL9602](INFO): NAL9602 initialized
2018-05-31T22:02:21.586Z,1527804141.586 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T22:02:21.586Z,1527804141.586 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2018-05-31T22:02:21.586Z,1527804141.586 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T22:02:21.600Z,1527804141.600 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T22:02:22.020Z,1527804142.020 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T22:02:22.020Z,1527804142.020 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2018-05-31T22:02:22.404Z,1527804142.404 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T22:02:22.405Z,1527804142.405 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T22:02:22.405Z,1527804142.405 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T22:04:23.234Z,1527804263.234 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-31T22:04:23.234Z,1527804263.234 [Default:CheckIn:C.Wait] Stopped
2018-05-31T22:04:23.234Z,1527804263.234 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-31T22:04:23.234Z,1527804263.234 [Default:CheckIn:D] Running Loop=1
2018-05-31T22:04:23.626Z,1527804263.626 [Default:CheckIn:D] Stopped
2018-05-31T22:04:23.626Z,1527804263.626 [Default:CheckIn:E] Running Loop=1
2018-05-31T22:04:24.024Z,1527804264.024 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.565821 min 
2018-05-31T22:04:24.026Z,1527804264.026 [Default:CheckIn:E] Stopped
2018-05-31T22:04:24.026Z,1527804264.026 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-31T22:04:24.026Z,1527804264.026 [Default:CheckIn] Stopped
2018-05-31T22:04:24.026Z,1527804264.026 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-31T22:04:24.026Z,1527804264.026 [Default:CheckIn](INFO): Running loop #2
2018-05-31T22:04:24.026Z,1527804264.026 [Default:CheckIn] Running Loop=2
2018-05-31T22:04:24.027Z,1527804264.027 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-31T22:04:24.027Z,1527804264.027 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-31T22:05:22.813Z,1527804322.813 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T22:05:22.813Z,1527804322.813 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 5
2018-05-31T22:05:22.813Z,1527804322.813 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T22:05:22.847Z,1527804322.847 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T22:05:23.221Z,1527804323.221 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T22:05:23.221Z,1527804323.221 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 5
2018-05-31T22:05:23.608Z,1527804323.608 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T22:05:23.608Z,1527804323.608 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T22:05:23.609Z,1527804323.609 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T22:05:32.410Z,1527804332.410 [BPC1](ERROR): Failed to receive data from both battery packs
2018-05-31T22:05:32.410Z,1527804332.410 [BPC1] Communications Fault, FailCount= 2
2018-05-31T22:05:32.410Z,1527804332.410 [BPC1](ERROR): Communications Fault
2018-05-31T22:05:32.433Z,1527804332.433 [CBIT](ERROR): Communications Fault in component: BPC1
2018-05-31T22:05:33.634Z,1527804333.634 [CBIT](INFO): Clearing failed state for component BPC1
2018-05-31T22:05:33.634Z,1527804333.634 [BPC1] No Fault, FailCount= 2
2018-05-31T22:08:23.698Z,1527804503.698 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T22:08:23.698Z,1527804503.698 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 6
2018-05-31T22:08:23.698Z,1527804503.698 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T22:08:23.711Z,1527804503.711 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T22:08:24.108Z,1527804504.108 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T22:08:24.109Z,1527804504.109 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 6
2018-05-31T22:08:24.491Z,1527804504.491 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T22:08:24.491Z,1527804504.491 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T22:08:24.492Z,1527804504.492 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T22:09:24.098Z,1527804564.098 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-05-31T22:04:24.0Z
2018-05-31T22:09:24.098Z,1527804564.098 [Default:CheckIn:Read_GPS] Stopped
2018-05-31T22:09:24.098Z,1527804564.098 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-31T22:09:28.550Z,1527804568.550 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180531T215006/Courier0007.lzma
2018-05-31T22:09:29.316Z,1527804569.316 [DataOverHttps](INFO): Moved sent file to Logs/20180531T215006/Courier0007.lzma.bak
2018-05-31T22:09:29.316Z,1527804569.316 [DataOverHttps](INFO): SBD MOMSN=8300852
2018-05-31T22:09:39.375Z,1527804579.375 [DataOverHttps](INFO): Sending 280 bytes from file Logs/20180531T215006/Express0008.lzma
2018-05-31T22:09:40.172Z,1527804580.172 [DataOverHttps](INFO): Moved sent file to Logs/20180531T215006/Express0008.lzma.bak
2018-05-31T22:09:40.172Z,1527804580.172 [DataOverHttps](INFO): SBD MOMSN=8300854
2018-05-31T22:09:41.365Z,1527804581.365 [Default:CheckIn:Read_Iridium] Stopped
2018-05-31T22:09:41.366Z,1527804581.366 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-31T22:09:41.366Z,1527804581.366 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-31T22:10:26.702Z,1527804626.702 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-05-31T22:10:26.702Z,1527804626.702 [Rowe_600LCM] No Fault, FailCount= 5
2018-05-31T22:10:26.705Z,1527804626.705 [Rowe_600LCM](INFO): Initializing
2018-05-31T22:10:26.705Z,1527804626.705 [Rowe_600LCM](INFO): Checking LCM
2018-05-31T22:10:26.706Z,1527804626.706 [Rowe_600LCM](INFO): LCM OK
2018-05-31T22:10:26.706Z,1527804626.706 [Rowe_600LCM](INFO): Powering up
2018-05-31T22:10:30.887Z,1527804630.887 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-05-31T22:10:30.996Z,1527804630.996 [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 9600 >& /dev/null &
2018-05-31T22:10:30.998Z,1527804630.998 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-05-31T22:10:30.998Z,1527804630.998 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-05-31T22:10:30.999Z,1527804630.999 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-05-31T22:11:24.748Z,1527804684.748 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T22:11:24.748Z,1527804684.748 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 7
2018-05-31T22:11:24.749Z,1527804684.749 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T22:11:24.762Z,1527804684.762 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T22:11:25.223Z,1527804685.223 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T22:11:25.223Z,1527804685.223 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 7
2018-05-31T22:11:25.552Z,1527804685.552 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T22:11:25.552Z,1527804685.552 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T22:11:25.557Z,1527804685.557 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T22:11:27.810Z,1527804687.810 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T22:11:28.605Z,1527804688.605 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T22:11:40.283Z,1527804700.283 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-05-31T22:11:40.283Z,1527804700.283 [NAL9602] Data Fault, FailCount= 2
2018-05-31T22:11:40.283Z,1527804700.283 [NAL9602](ERROR): Data Fault
2018-05-31T22:11:40.377Z,1527804700.377 [CBIT](ERROR): Data Fault in component: NAL9602
2018-05-31T22:11:40.678Z,1527804700.678 [NAL9602](INFO): Powering down
2018-05-31T22:11:41.563Z,1527804701.563 [CBIT](INFO): Clearing failed state for component NAL9602
2018-05-31T22:11:41.563Z,1527804701.563 [NAL9602] No Fault, FailCount= 2
2018-05-31T22:12:10.826Z,1527804730.826 [NAL9602](INFO): Powering up NAL9602
2018-05-31T22:12:22.022Z,1527804742.022 [NAL9602](INFO): NAL9602 initialized
2018-05-31T22:12:27.381Z,1527804747.381 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-05-31T22:12:27.381Z,1527804747.381 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-05-31T22:12:27.381Z,1527804747.381 [Rowe_600LCM](ERROR): Communications Fault
2018-05-31T22:12:27.786Z,1527804747.786 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-05-31T22:12:27.842Z,1527804747.842 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-05-31T22:12:27.943Z,1527804747.943 [Rowe_600LCM](INFO): Powering down
2018-05-31T22:12:29.309Z,1527804749.309 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-05-31T22:12:29.309Z,1527804749.309 [Rowe_600LCM] No Fault, FailCount= 1
2018-05-31T22:12:29.557Z,1527804749.557 [Rowe_600LCM](INFO): Initializing
2018-05-31T22:12:29.558Z,1527804749.558 [Rowe_600LCM](INFO): Checking LCM
2018-05-31T22:12:29.558Z,1527804749.558 [Rowe_600LCM](INFO): LCM OK
2018-05-31T22:12:29.558Z,1527804749.558 [Rowe_600LCM](INFO): Powering up
2018-05-31T22:12:33.809Z,1527804753.809 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-05-31T22:12:33.873Z,1527804753.873 [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 9600 >& /dev/null &
2018-05-31T22:12:33.874Z,1527804753.874 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-05-31T22:12:33.875Z,1527804753.875 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-05-31T22:12:33.876Z,1527804753.876 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-05-31T22:13:59.046Z,1527804839.046 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T22:13:59.842Z,1527804839.842 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T22:14:25.681Z,1527804865.681 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T22:14:25.681Z,1527804865.681 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 8
2018-05-31T22:14:25.682Z,1527804865.682 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T22:14:25.695Z,1527804865.695 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T22:14:26.105Z,1527804866.105 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T22:14:26.105Z,1527804866.105 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 8
2018-05-31T22:14:26.527Z,1527804866.527 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T22:14:26.528Z,1527804866.528 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T22:14:26.538Z,1527804866.538 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T22:14:29.781Z,1527804869.781 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-05-31T22:14:29.781Z,1527804869.781 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-05-31T22:14:29.781Z,1527804869.781 [Rowe_600LCM](ERROR): Communications Fault
2018-05-31T22:14:30.089Z,1527804870.089 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-05-31T22:14:30.185Z,1527804870.185 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-05-31T22:14:30.287Z,1527804870.287 [Rowe_600LCM](INFO): Powering down
2018-05-31T22:14:31.693Z,1527804871.693 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-05-31T22:14:31.693Z,1527804871.693 [Rowe_600LCM] No Fault, FailCount= 2
2018-05-31T22:14:31.901Z,1527804871.901 [Rowe_600LCM](INFO): Initializing
2018-05-31T22:14:31.902Z,1527804871.902 [Rowe_600LCM](INFO): Checking LCM
2018-05-31T22:14:31.902Z,1527804871.902 [Rowe_600LCM](INFO): LCM OK
2018-05-31T22:14:31.902Z,1527804871.902 [Rowe_600LCM](INFO): Powering up
2018-05-31T22:14:36.083Z,1527804876.083 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-05-31T22:14:36.212Z,1527804876.212 [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 9600 >& /dev/null &
2018-05-31T22:14:36.213Z,1527804876.213 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-05-31T22:14:36.214Z,1527804876.214 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-05-31T22:14:36.215Z,1527804876.215 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-05-31T22:14:41.689Z,1527804881.689 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-31T22:14:41.689Z,1527804881.689 [Default:CheckIn:C.Wait] Stopped
2018-05-31T22:14:41.689Z,1527804881.689 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-31T22:14:41.689Z,1527804881.689 [Default:CheckIn:D] Running Loop=1
2018-05-31T22:14:42.084Z,1527804882.084 [Default:CheckIn:D] Stopped
2018-05-31T22:14:42.084Z,1527804882.084 [Default:CheckIn:E] Running Loop=1
2018-05-31T22:14:42.496Z,1527804882.496 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.873492 min 
2018-05-31T22:14:42.496Z,1527804882.496 [Default:CheckIn:E] Stopped
2018-05-31T22:14:42.496Z,1527804882.496 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-31T22:14:42.496Z,1527804882.496 [Default:CheckIn] Stopped
2018-05-31T22:14:42.496Z,1527804882.496 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-31T22:14:42.497Z,1527804882.497 [Default:CheckIn](INFO): Running loop #3
2018-05-31T22:14:42.497Z,1527804882.497 [Default:CheckIn] Running Loop=3
2018-05-31T22:14:42.497Z,1527804882.497 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-31T22:14:42.497Z,1527804882.497 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-31T22:15:37.475Z,1527804937.475 [BPC1](ERROR): Failed to receive data from both battery packs
2018-05-31T22:15:37.476Z,1527804937.476 [BPC1] Communications Fault, FailCount= 3
2018-05-31T22:15:37.476Z,1527804937.476 [BPC1](ERROR): Communications Fault
2018-05-31T22:15:37.500Z,1527804937.500 [CBIT](ERROR): Communications Fault in component: BPC1
2018-05-31T22:15:38.733Z,1527804938.733 [CBIT](INFO): Clearing failed state for component BPC1
2018-05-31T22:15:38.733Z,1527804938.733 [BPC1] No Fault, FailCount= 3
2018-05-31T22:15:48.214Z,1527804948.214 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T22:15:49.010Z,1527804949.010 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T22:16:32.181Z,1527804992.181 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-05-31T22:16:32.182Z,1527804992.182 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-05-31T22:16:32.182Z,1527804992.182 [Rowe_600LCM](ERROR): Communications Fault
2018-05-31T22:16:32.260Z,1527804992.260 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-05-31T22:16:32.585Z,1527804992.585 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-05-31T22:16:32.728Z,1527804992.728 [Rowe_600LCM](INFO): Powering down
2018-05-31T22:16:34.195Z,1527804994.195 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-05-31T22:16:34.196Z,1527804994.196 [Rowe_600LCM] No Fault, FailCount= 3
2018-05-31T22:16:34.345Z,1527804994.345 [Rowe_600LCM](INFO): Initializing
2018-05-31T22:16:34.345Z,1527804994.345 [Rowe_600LCM](INFO): Checking LCM
2018-05-31T22:16:34.345Z,1527804994.345 [Rowe_600LCM](INFO): LCM OK
2018-05-31T22:16:34.346Z,1527804994.346 [Rowe_600LCM](INFO): Powering up
2018-05-31T22:16:38.477Z,1527804998.477 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-05-31T22:16:38.636Z,1527804998.636 [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 9600 >& /dev/null &
2018-05-31T22:16:38.638Z,1527804998.638 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-05-31T22:16:38.639Z,1527804998.639 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-05-31T22:16:38.640Z,1527804998.640 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-05-31T22:17:26.691Z,1527805046.691 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T22:17:26.691Z,1527805046.691 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 9
2018-05-31T22:17:26.691Z,1527805046.691 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T22:17:26.725Z,1527805046.725 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T22:17:27.118Z,1527805047.118 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T22:17:27.118Z,1527805047.118 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 9
2018-05-31T22:17:27.546Z,1527805047.546 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T22:17:27.547Z,1527805047.547 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T22:17:27.548Z,1527805047.548 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T22:18:18.442Z,1527805098.442 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T22:18:19.237Z,1527805099.237 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T22:18:34.359Z,1527805114.359 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-05-31T22:18:34.359Z,1527805114.359 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-05-31T22:18:34.359Z,1527805114.359 [Rowe_600LCM](ERROR): Communications Fault
2018-05-31T22:18:34.407Z,1527805114.407 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-05-31T22:18:34.761Z,1527805114.761 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-05-31T22:18:34.861Z,1527805114.861 [Rowe_600LCM](INFO): Powering down
2018-05-31T22:18:36.338Z,1527805116.338 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-05-31T22:18:36.338Z,1527805116.338 [Rowe_600LCM] No Fault, FailCount= 4
2018-05-31T22:18:36.477Z,1527805116.477 [Rowe_600LCM](INFO): Initializing
2018-05-31T22:18:36.477Z,1527805116.477 [Rowe_600LCM](INFO): Checking LCM
2018-05-31T22:18:36.478Z,1527805116.478 [Rowe_600LCM](INFO): LCM OK
2018-05-31T22:18:36.478Z,1527805116.478 [Rowe_600LCM](INFO): Powering up
2018-05-31T22:18:40.603Z,1527805120.603 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-05-31T22:18:40.752Z,1527805120.752 [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 9600 >& /dev/null &
2018-05-31T22:18:40.758Z,1527805120.758 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-05-31T22:18:40.759Z,1527805120.759 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-05-31T22:18:40.760Z,1527805120.760 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-05-31T22:18:56.502Z,1527805136.502 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T22:18:57.297Z,1527805137.297 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T22:19:42.541Z,1527805182.541 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-05-31T22:14:42.5Z
2018-05-31T22:19:42.541Z,1527805182.541 [Default:CheckIn:Read_GPS] Stopped
2018-05-31T22:19:42.541Z,1527805182.541 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-31T22:19:46.946Z,1527805186.946 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180531T215006/Courier0010.lzma
2018-05-31T22:19:47.730Z,1527805187.730 [DataOverHttps](INFO): Moved sent file to Logs/20180531T215006/Courier0010.lzma.bak
2018-05-31T22:19:47.730Z,1527805187.730 [DataOverHttps](INFO): SBD MOMSN=8300867
2018-05-31T22:19:58.610Z,1527805198.610 [DataOverHttps](INFO): Sending 218 bytes from file Logs/20180531T215006/Express0011.lzma
2018-05-31T22:19:59.376Z,1527805199.376 [DataOverHttps](INFO): Moved sent file to Logs/20180531T215006/Express0011.lzma.bak
2018-05-31T22:19:59.376Z,1527805199.376 [DataOverHttps](INFO): SBD MOMSN=8300869
2018-05-31T22:20:00.686Z,1527805200.686 [Default:CheckIn:Read_Iridium] Stopped
2018-05-31T22:20:00.686Z,1527805200.686 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-31T22:20:00.686Z,1527805200.686 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-31T22:20:27.564Z,1527805227.564 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T22:20:27.564Z,1527805227.564 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 10
2018-05-31T22:20:27.564Z,1527805227.564 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T22:20:27.579Z,1527805227.579 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T22:20:28.047Z,1527805228.047 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T22:20:28.047Z,1527805228.047 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 10
2018-05-31T22:20:28.366Z,1527805228.366 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T22:20:28.366Z,1527805228.366 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T22:20:28.367Z,1527805228.367 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T22:20:36.543Z,1527805236.543 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-05-31T22:20:36.543Z,1527805236.543 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-05-31T22:20:36.543Z,1527805236.543 [Rowe_600LCM](ERROR): Communications Fault
2018-05-31T22:20:36.822Z,1527805236.822 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-05-31T22:20:36.822Z,1527805236.822 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-05-31T22:20:36.945Z,1527805236.945 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-05-31T22:20:37.068Z,1527805237.068 [Rowe_600LCM](INFO): Powering down
2018-05-31T22:22:24.443Z,1527805344.443 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-05-31T22:22:24.443Z,1527805344.443 [NAL9602] Data Fault, FailCount= 3
2018-05-31T22:22:24.443Z,1527805344.443 [NAL9602](ERROR): Data Fault
2018-05-31T22:22:24.528Z,1527805344.528 [CBIT](ERROR): Data Fault in component: NAL9602
2018-05-31T22:22:24.842Z,1527805344.842 [NAL9602](INFO): Powering down
2018-05-31T22:22:25.702Z,1527805345.702 [CBIT](INFO): Clearing failed state for component NAL9602
2018-05-31T22:22:25.702Z,1527805345.702 [NAL9602] No Fault, FailCount= 3
2018-05-31T22:22:46.871Z,1527805366.871 [BPC1](ERROR): Failed to receive data from both battery packs
2018-05-31T22:22:46.871Z,1527805366.871 [BPC1] Communications Fault, FailCount= 4
2018-05-31T22:22:46.871Z,1527805366.871 [BPC1](ERROR): Communications Fault
2018-05-31T22:22:46.923Z,1527805366.923 [CBIT](ERROR): Communications Fault in component: BPC1
2018-05-31T22:22:48.109Z,1527805368.109 [CBIT](INFO): Clearing failed state for component BPC1
2018-05-31T22:22:48.109Z,1527805368.109 [BPC1] No Fault, FailCount= 4
2018-05-31T22:22:55.242Z,1527805375.242 [NAL9602](INFO): Powering up NAL9602
2018-05-31T22:23:06.138Z,1527805386.138 [NAL9602](INFO): NAL9602 initialized
2018-05-31T22:23:28.592Z,1527805408.592 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T22:23:28.592Z,1527805408.592 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 11
2018-05-31T22:23:28.592Z,1527805408.592 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T22:23:28.622Z,1527805408.622 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T22:23:28.992Z,1527805408.992 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T22:23:28.992Z,1527805408.992 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 11
2018-05-31T22:23:29.391Z,1527805409.391 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T22:23:29.392Z,1527805409.392 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T22:23:29.392Z,1527805409.392 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T22:25:01.014Z,1527805501.014 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-31T22:25:01.015Z,1527805501.015 [Default:CheckIn:C.Wait] Stopped
2018-05-31T22:25:01.015Z,1527805501.015 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-31T22:25:01.015Z,1527805501.015 [Default:CheckIn:D] Running Loop=1
2018-05-31T22:25:01.417Z,1527805501.417 [Default:CheckIn:D] Stopped
2018-05-31T22:25:01.417Z,1527805501.417 [Default:CheckIn:E] Running Loop=1
2018-05-31T22:25:04.522Z,1527805504.522 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.195703 min 
2018-05-31T22:25:04.523Z,1527805504.523 [Default:CheckIn:E] Stopped
2018-05-31T22:25:04.523Z,1527805504.523 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-31T22:25:04.523Z,1527805504.523 [Default:CheckIn] Stopped
2018-05-31T22:25:04.523Z,1527805504.523 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-31T22:25:04.523Z,1527805504.523 [Default:CheckIn](INFO): Running loop #4
2018-05-31T22:25:04.523Z,1527805504.523 [Default:CheckIn] Running Loop=4
2018-05-31T22:25:04.524Z,1527805504.524 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-31T22:25:04.524Z,1527805504.524 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-31T22:26:29.441Z,1527805589.441 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T22:26:29.441Z,1527805589.441 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 12
2018-05-31T22:26:29.441Z,1527805589.441 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T22:26:29.499Z,1527805589.499 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T22:26:29.873Z,1527805589.873 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T22:26:29.873Z,1527805589.873 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 12
2018-05-31T22:26:30.258Z,1527805590.258 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T22:26:30.258Z,1527805590.258 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T22:26:30.259Z,1527805590.259 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T22:28:55.065Z,1527805735.065 [BPC1](ERROR): Failed to receive data from both battery packs
2018-05-31T22:28:55.066Z,1527805735.066 [BPC1] Communications Fault, FailCount= 5
2018-05-31T22:28:55.066Z,1527805735.066 [BPC1](ERROR): Communications Fault
2018-05-31T22:28:55.106Z,1527805735.106 [CBIT](ERROR): Communications Fault in component: BPC1
2018-05-31T22:28:56.293Z,1527805736.293 [CBIT](INFO): Clearing failed state for component BPC1
2018-05-31T22:28:56.294Z,1527805736.294 [BPC1] No Fault, FailCount= 5
2018-05-31T22:29:30.303Z,1527805770.303 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T22:29:30.303Z,1527805770.303 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 13
2018-05-31T22:29:30.303Z,1527805770.303 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T22:29:30.325Z,1527805770.325 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T22:29:30.718Z,1527805770.718 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T22:29:30.718Z,1527805770.718 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 13
2018-05-31T22:29:31.102Z,1527805771.102 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T22:29:31.103Z,1527805771.103 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T22:29:31.103Z,1527805771.103 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T22:30:05.026Z,1527805805.026 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-05-31T22:25:04.5Z
2018-05-31T22:30:05.026Z,1527805805.026 [Default:CheckIn:Read_GPS] Stopped
2018-05-31T22:30:05.026Z,1527805805.026 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-31T22:30:09.953Z,1527805809.953 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180531T215006/Courier0013.lzma
2018-05-31T22:30:10.736Z,1527805810.736 [DataOverHttps](INFO): Moved sent file to Logs/20180531T215006/Courier0013.lzma.bak
2018-05-31T22:30:10.736Z,1527805810.736 [DataOverHttps](INFO): SBD MOMSN=8300886
2018-05-31T22:30:20.806Z,1527805820.806 [DataOverHttps](INFO): Sending 250 bytes from file Logs/20180531T215006/Express0014.lzma
2018-05-31T22:30:21.592Z,1527805821.592 [DataOverHttps](INFO): Moved sent file to Logs/20180531T215006/Express0014.lzma.bak
2018-05-31T22:30:21.593Z,1527805821.593 [DataOverHttps](INFO): SBD MOMSN=8300888
2018-05-31T22:30:22.768Z,1527805822.768 [Default:CheckIn:Read_Iridium] Stopped
2018-05-31T22:30:22.768Z,1527805822.768 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-31T22:30:22.768Z,1527805822.768 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-31T22:30:36.856Z,1527805836.856 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-05-31T22:30:36.856Z,1527805836.856 [Rowe_600LCM] No Fault, FailCount= 5
2018-05-31T22:30:37.249Z,1527805837.249 [Rowe_600LCM](INFO): Initializing
2018-05-31T22:30:37.250Z,1527805837.250 [Rowe_600LCM](INFO): Checking LCM
2018-05-31T22:30:37.250Z,1527805837.250 [Rowe_600LCM](INFO): LCM OK
2018-05-31T22:30:37.250Z,1527805837.250 [Rowe_600LCM](INFO): Powering up
2018-05-31T22:30:41.541Z,1527805841.541 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-05-31T22:30:41.703Z,1527805841.703 [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 9600 >& /dev/null &
2018-05-31T22:30:41.704Z,1527805841.704 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-05-31T22:30:41.705Z,1527805841.705 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-05-31T22:30:41.706Z,1527805841.706 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-05-31T22:32:31.180Z,1527805951.180 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T22:32:31.180Z,1527805951.180 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 14
2018-05-31T22:32:31.180Z,1527805951.180 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T22:32:31.212Z,1527805951.212 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T22:32:31.593Z,1527805951.593 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T22:32:31.593Z,1527805951.593 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 14
2018-05-31T22:32:31.964Z,1527805951.964 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T22:32:31.964Z,1527805951.964 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T22:32:31.965Z,1527805951.965 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T22:32:37.648Z,1527805957.648 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-05-31T22:32:37.649Z,1527805957.649 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-05-31T22:32:37.649Z,1527805957.649 [Rowe_600LCM](ERROR): Communications Fault
2018-05-31T22:32:37.698Z,1527805957.698 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-05-31T22:32:38.053Z,1527805958.053 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-05-31T22:32:38.156Z,1527805958.156 [Rowe_600LCM](INFO): Powering down
2018-05-31T22:32:39.574Z,1527805959.574 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-05-31T22:32:39.574Z,1527805959.574 [Rowe_600LCM] No Fault, FailCount= 1
2018-05-31T22:32:39.769Z,1527805959.769 [Rowe_600LCM](INFO): Initializing
2018-05-31T22:32:39.770Z,1527805959.770 [Rowe_600LCM](INFO): Checking LCM
2018-05-31T22:32:39.770Z,1527805959.770 [Rowe_600LCM](INFO): LCM OK
2018-05-31T22:32:39.770Z,1527805959.770 [Rowe_600LCM](INFO): Powering up
2018-05-31T22:32:43.917Z,1527805963.917 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-05-31T22:32:44.088Z,1527805964.088 [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 9600 >& /dev/null &
2018-05-31T22:32:44.089Z,1527805964.089 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-05-31T22:32:44.090Z,1527805964.090 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-05-31T22:32:44.091Z,1527805964.091 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-05-31T22:33:07.119Z,1527805987.119 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-05-31T22:33:07.120Z,1527805987.120 [NAL9602] Data Fault, FailCount= 4
2018-05-31T22:33:07.120Z,1527805987.120 [NAL9602](ERROR): Data Fault
2018-05-31T22:33:07.239Z,1527805987.239 [CBIT](ERROR): Data Fault in component: NAL9602
2018-05-31T22:33:07.506Z,1527805987.506 [NAL9602](INFO): Powering down
2018-05-31T22:33:08.364Z,1527805988.364 [CBIT](INFO): Clearing failed state for component NAL9602
2018-05-31T22:33:08.364Z,1527805988.364 [NAL9602] No Fault, FailCount= 4
2018-05-31T22:33:37.813Z,1527806017.813 [NAL9602](INFO): Powering up NAL9602
2018-05-31T22:33:49.254Z,1527806029.254 [NAL9602](INFO): NAL9602 initialized
2018-05-31T22:34:23.926Z,1527806063.926 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T22:34:24.721Z,1527806064.721 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T22:34:57.431Z,1527806097.431 [BPC1](ERROR): Failed to receive data from both battery packs
2018-05-31T22:34:57.431Z,1527806097.431 [BPC1] Communications Fault, FailCount= 6
2018-05-31T22:34:57.431Z,1527806097.431 [BPC1](ERROR): Communications Fault
2018-05-31T22:34:57.493Z,1527806097.493 [CBIT](ERROR): Communications Fault in component: BPC1
2018-05-31T22:34:58.649Z,1527806098.649 [CBIT](INFO): Clearing failed state for component BPC1
2018-05-31T22:34:58.649Z,1527806098.649 [BPC1] No Fault, FailCount= 6
2018-05-31T22:35:23.033Z,1527806123.033 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-31T22:35:23.033Z,1527806123.033 [Default:CheckIn:C.Wait] Stopped
2018-05-31T22:35:23.033Z,1527806123.033 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-31T22:35:23.033Z,1527806123.033 [Default:CheckIn:D] Running Loop=1
2018-05-31T22:35:23.451Z,1527806123.451 [Default:CheckIn:D] Stopped
2018-05-31T22:35:23.451Z,1527806123.451 [Default:CheckIn:E] Running Loop=1
2018-05-31T22:35:23.906Z,1527806123.906 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.562931 min 
2018-05-31T22:35:23.906Z,1527806123.906 [Default:CheckIn:E] Stopped
2018-05-31T22:35:23.906Z,1527806123.906 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-31T22:35:23.906Z,1527806123.906 [Default:CheckIn] Stopped
2018-05-31T22:35:23.906Z,1527806123.906 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-31T22:35:23.907Z,1527806123.907 [Default:CheckIn](INFO): Running loop #5
2018-05-31T22:35:23.907Z,1527806123.907 [Default:CheckIn] Running Loop=5
2018-05-31T22:35:23.907Z,1527806123.907 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-31T22:35:23.907Z,1527806123.907 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-31T22:35:32.189Z,1527806132.189 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T22:35:32.190Z,1527806132.190 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 15
2018-05-31T22:35:32.190Z,1527806132.190 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T22:35:32.219Z,1527806132.219 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T22:35:32.598Z,1527806132.598 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T22:35:32.599Z,1527806132.599 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 15
2018-05-31T22:35:32.984Z,1527806132.984 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T22:35:32.989Z,1527806132.989 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T22:35:32.989Z,1527806132.989 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T22:35:49.944Z,1527806149.944 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-05-31T22:35:49.944Z,1527806149.944 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-05-31T22:35:49.945Z,1527806149.945 [Rowe_600LCM](ERROR): Communications Fault
2018-05-31T22:35:50.252Z,1527806150.252 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-05-31T22:35:50.349Z,1527806150.349 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-05-31T22:35:50.583Z,1527806150.583 [Rowe_600LCM](INFO): Powering down
2018-05-31T22:35:51.811Z,1527806151.811 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-05-31T22:35:51.811Z,1527806151.811 [Rowe_600LCM] No Fault, FailCount= 2
2018-05-31T22:35:52.197Z,1527806152.197 [Rowe_600LCM](INFO): Initializing
2018-05-31T22:35:52.198Z,1527806152.198 [Rowe_600LCM](INFO): Checking LCM
2018-05-31T22:35:52.198Z,1527806152.198 [Rowe_600LCM](INFO): LCM OK
2018-05-31T22:35:52.198Z,1527806152.198 [Rowe_600LCM](INFO): Powering up
2018-05-31T22:35:56.474Z,1527806156.474 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-05-31T22:35:56.625Z,1527806156.625 [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 9600 >& /dev/null &
2018-05-31T22:35:56.626Z,1527806156.626 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-05-31T22:35:56.627Z,1527806156.627 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-05-31T22:35:56.628Z,1527806156.628 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-05-31T22:37:52.957Z,1527806272.957 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-05-31T22:37:52.957Z,1527806272.957 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-05-31T22:37:52.957Z,1527806272.957 [Rowe_600LCM](ERROR): Communications Fault
2018-05-31T22:37:53.306Z,1527806273.306 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-05-31T22:37:53.361Z,1527806273.361 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-05-31T22:37:53.460Z,1527806273.460 [Rowe_600LCM](INFO): Powering down
2018-05-31T22:37:54.913Z,1527806274.913 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-05-31T22:37:54.913Z,1527806274.913 [Rowe_600LCM] No Fault, FailCount= 3
2018-05-31T22:37:55.077Z,1527806275.077 [Rowe_600LCM](INFO): Initializing
2018-05-31T22:37:55.078Z,1527806275.078 [Rowe_600LCM](INFO): Checking LCM
2018-05-31T22:37:55.078Z,1527806275.078 [Rowe_600LCM](INFO): LCM OK
2018-05-31T22:37:55.078Z,1527806275.078 [Rowe_600LCM](INFO): Powering up
2018-05-31T22:37:59.205Z,1527806279.205 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-05-31T22:37:59.336Z,1527806279.336 [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 9600 >& /dev/null &
2018-05-31T22:37:59.338Z,1527806279.338 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-05-31T22:37:59.339Z,1527806279.339 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-05-31T22:37:59.340Z,1527806279.340 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-05-31T22:38:33.134Z,1527806313.134 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T22:38:33.134Z,1527806313.134 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 16
2018-05-31T22:38:33.135Z,1527806313.135 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T22:38:33.232Z,1527806313.232 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T22:38:33.501Z,1527806313.501 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T22:38:33.501Z,1527806313.501 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 16
2018-05-31T22:38:33.885Z,1527806313.885 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T22:38:33.885Z,1527806313.885 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T22:38:33.886Z,1527806313.886 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T22:38:43.322Z,1527806323.322 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T22:38:44.117Z,1527806324.117 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T22:39:55.325Z,1527806395.325 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-05-31T22:39:55.325Z,1527806395.325 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-05-31T22:39:55.325Z,1527806395.325 [Rowe_600LCM](ERROR): Communications Fault
2018-05-31T22:39:55.415Z,1527806395.415 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-05-31T22:39:55.729Z,1527806395.729 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-05-31T22:39:55.837Z,1527806395.837 [Rowe_600LCM](INFO): Powering down
2018-05-31T22:39:57.298Z,1527806397.298 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-05-31T22:39:57.298Z,1527806397.298 [Rowe_600LCM] No Fault, FailCount= 4
2018-05-31T22:39:57.455Z,1527806397.455 [Rowe_600LCM](INFO): Initializing
2018-05-31T22:39:57.455Z,1527806397.455 [Rowe_600LCM](INFO): Checking LCM
2018-05-31T22:39:57.455Z,1527806397.455 [Rowe_600LCM](INFO): LCM OK
2018-05-31T22:39:57.456Z,1527806397.456 [Rowe_600LCM](INFO): Powering up
2018-05-31T22:40:01.579Z,1527806401.579 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-05-31T22:40:01.699Z,1527806401.699 [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 9600 >& /dev/null &
2018-05-31T22:40:01.701Z,1527806401.701 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-05-31T22:40:01.702Z,1527806401.702 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-05-31T22:40:01.703Z,1527806401.703 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-05-31T22:40:24.091Z,1527806424.091 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-05-31T22:35:23.9Z
2018-05-31T22:40:24.091Z,1527806424.091 [Default:CheckIn:Read_GPS] Stopped
2018-05-31T22:40:24.091Z,1527806424.091 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-31T22:40:28.000Z,1527806429.000 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180531T215006/Courier0016.lzma
2018-05-31T22:40:29.792Z,1527806429.792 [DataOverHttps](INFO): Moved sent file to Logs/20180531T215006/Courier0016.lzma.bak
2018-05-31T22:40:29.792Z,1527806429.792 [DataOverHttps](INFO): SBD MOMSN=8300903
2018-05-31T22:40:45.027Z,1527806445.027 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20180531T215006/Express0017.lzma
2018-05-31T22:40:45.752Z,1527806445.752 [DataOverHttps](INFO): Moved sent file to Logs/20180531T215006/Express0017.lzma.bak
2018-05-31T22:40:45.753Z,1527806445.753 [DataOverHttps](INFO): SBD MOMSN=8300905
2018-05-31T22:40:47.238Z,1527806447.238 [Default:CheckIn:Read_Iridium] Stopped
2018-05-31T22:40:47.238Z,1527806447.238 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-31T22:40:47.238Z,1527806447.238 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-31T22:41:13.555Z,1527806473.555 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T22:41:14.354Z,1527806474.354 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T22:41:34.015Z,1527806494.015 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T22:41:34.015Z,1527806494.015 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 17
2018-05-31T22:41:34.015Z,1527806494.015 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T22:41:34.028Z,1527806494.028 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T22:41:34.430Z,1527806494.430 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T22:41:34.430Z,1527806494.430 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 17
2018-05-31T22:41:34.872Z,1527806494.872 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T22:41:34.877Z,1527806494.877 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T22:41:34.877Z,1527806494.877 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T22:41:57.517Z,1527806517.517 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-05-31T22:41:57.517Z,1527806517.517 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-05-31T22:41:57.517Z,1527806517.517 [Rowe_600LCM](ERROR): Communications Fault
2018-05-31T22:41:57.607Z,1527806517.607 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-05-31T22:41:57.608Z,1527806517.608 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-05-31T22:41:57.921Z,1527806517.921 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-05-31T22:41:58.117Z,1527806518.117 [Rowe_600LCM](INFO): Powering down
2018-05-31T22:42:59.562Z,1527806579.562 [BPC1](ERROR): Failed to receive data from both battery packs
2018-05-31T22:42:59.562Z,1527806579.562 [BPC1] Communications Fault, FailCount= 7
2018-05-31T22:42:59.562Z,1527806579.562 [BPC1](ERROR): Communications Fault
2018-05-31T22:42:59.586Z,1527806579.586 [CBIT](ERROR): Communications Fault in component: BPC1
2018-05-31T22:43:00.806Z,1527806580.806 [CBIT](INFO): Clearing failed state for component BPC1
2018-05-31T22:43:00.806Z,1527806580.806 [BPC1] No Fault, FailCount= 7
2018-05-31T22:43:51.518Z,1527806631.518 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-05-31T22:43:51.518Z,1527806631.518 [NAL9602] Data Fault, FailCount= 5
2018-05-31T22:43:51.518Z,1527806631.518 [NAL9602](ERROR): Data Fault
2018-05-31T22:43:51.599Z,1527806631.599 [CBIT](ERROR): Data Fault in component: NAL9602
2018-05-31T22:43:51.599Z,1527806631.599 [CBIT](CRITICAL): Data Fault in component: NAL9602
2018-05-31T22:43:51.918Z,1527806631.918 [NAL9602](INFO): Powering down
2018-05-31T22:43:51.989Z,1527806631.989 [CommandLine](FAULT): Scheduling is paused
2018-05-31T22:43:51.989Z,1527806631.989 [CBIT](INFO): Critical error at 20180531T224351
2018-05-31T22:43:51.990Z,1527806631.990 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2018-05-31T22:44:35.162Z,1527806675.162 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T22:44:35.162Z,1527806675.162 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 18
2018-05-31T22:44:35.162Z,1527806675.162 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T22:44:35.176Z,1527806675.176 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T22:44:35.570Z,1527806675.570 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T22:44:35.570Z,1527806675.570 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 18
2018-05-31T22:44:35.960Z,1527806675.960 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T22:44:35.961Z,1527806675.961 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T22:44:35.961Z,1527806675.961 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T22:45:47.578Z,1527806747.578 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-31T22:45:47.578Z,1527806747.578 [Default:CheckIn:C.Wait] Stopped
2018-05-31T22:45:47.578Z,1527806747.578 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-31T22:45:47.578Z,1527806747.578 [Default:CheckIn:D] Running Loop=1
2018-05-31T22:45:47.988Z,1527806747.988 [Default:CheckIn:D] Stopped
2018-05-31T22:45:47.988Z,1527806747.988 [Default:CheckIn:E] Running Loop=1
2018-05-31T22:45:48.387Z,1527806748.387 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.971887 min 
2018-05-31T22:45:48.387Z,1527806748.387 [Default:CheckIn:E] Stopped
2018-05-31T22:45:48.388Z,1527806748.388 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-31T22:45:48.388Z,1527806748.388 [Default:CheckIn] Stopped
2018-05-31T22:45:48.388Z,1527806748.388 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-31T22:45:48.388Z,1527806748.388 [Default:CheckIn](INFO): Running loop #6
2018-05-31T22:45:48.388Z,1527806748.388 [Default:CheckIn] Running Loop=6
2018-05-31T22:45:48.388Z,1527806748.388 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-31T22:45:48.388Z,1527806748.388 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-31T22:45:53.284Z,1527806753.284 [CBIT](INFO): Clearing failed state for component NAL9602
2018-05-31T22:45:53.284Z,1527806753.284 [NAL9602] No Fault, FailCount= 5
2018-05-31T22:45:53.297Z,1527806753.297 [NAL9602](INFO): Powering up NAL9602
2018-05-31T22:46:04.098Z,1527806764.098 [NAL9602](INFO): NAL9602 initialized
2018-05-31T22:47:36.186Z,1527806856.186 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T22:47:36.187Z,1527806856.187 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 19
2018-05-31T22:47:36.187Z,1527806856.187 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T22:47:36.218Z,1527806856.218 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T22:47:36.616Z,1527806856.616 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T22:47:36.617Z,1527806856.617 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 19
2018-05-31T22:47:36.992Z,1527806856.992 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T22:47:36.992Z,1527806856.992 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T22:47:36.993Z,1527806856.993 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T22:50:36.994Z,1527807036.994 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T22:50:36.994Z,1527807036.994 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 20
2018-05-31T22:50:36.994Z,1527807036.994 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T22:50:37.008Z,1527807037.008 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T22:50:37.418Z,1527807037.418 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T22:50:37.418Z,1527807037.418 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 20
2018-05-31T22:50:37.771Z,1527807037.771 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T22:50:37.772Z,1527807037.772 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T22:50:37.772Z,1527807037.772 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T22:50:48.595Z,1527807048.595 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-05-31T22:45:48.4Z
2018-05-31T22:50:48.595Z,1527807048.595 [Default:CheckIn:Read_GPS] Stopped
2018-05-31T22:50:48.595Z,1527807048.595 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-31T22:50:52.706Z,1527807052.706 [DataOverHttps](INFO): Sending 93 bytes from file Logs/20180531T215006/Courier0019.lzma
2018-05-31T22:50:53.480Z,1527807053.480 [DataOverHttps](INFO): Moved sent file to Logs/20180531T215006/Courier0019.lzma.bak
2018-05-31T22:50:53.481Z,1527807053.481 [DataOverHttps](INFO): SBD MOMSN=8300922
2018-05-31T22:51:03.540Z,1527807063.540 [DataOverHttps](INFO): Sending 274 bytes from file Logs/20180531T215006/Express0020.lzma
2018-05-31T22:51:04.340Z,1527807064.340 [DataOverHttps](INFO): Moved sent file to Logs/20180531T215006/Express0020.lzma.bak
2018-05-31T22:51:04.340Z,1527807064.340 [DataOverHttps](INFO): SBD MOMSN=8300925
2018-05-31T22:51:05.503Z,1527807065.503 [Default:CheckIn:Read_Iridium] Stopped
2018-05-31T22:51:05.503Z,1527807065.503 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-31T22:51:05.503Z,1527807065.503 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-31T22:51:57.740Z,1527807117.740 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-05-31T22:51:57.741Z,1527807117.741 [Rowe_600LCM] No Fault, FailCount= 5
2018-05-31T22:51:57.893Z,1527807117.893 [Rowe_600LCM](INFO): Initializing
2018-05-31T22:51:57.893Z,1527807117.893 [Rowe_600LCM](INFO): Checking LCM
2018-05-31T22:51:57.893Z,1527807117.893 [Rowe_600LCM](INFO): LCM OK
2018-05-31T22:51:57.894Z,1527807117.894 [Rowe_600LCM](INFO): Powering up
2018-05-31T22:52:02.020Z,1527807122.020 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-05-31T22:52:02.132Z,1527807122.132 [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 9600 >& /dev/null &
2018-05-31T22:52:02.133Z,1527807122.133 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-05-31T22:52:02.134Z,1527807122.134 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-05-31T22:52:02.135Z,1527807122.135 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-05-31T22:52:10.864Z,1527807130.864 [BPC1](ERROR): Failed to receive data from both battery packs
2018-05-31T22:52:10.864Z,1527807130.864 [BPC1] Communications Fault, FailCount= 8
2018-05-31T22:52:10.864Z,1527807130.864 [BPC1](ERROR): Communications Fault
2018-05-31T22:52:10.966Z,1527807130.966 [CBIT](ERROR): Communications Fault in component: BPC1
2018-05-31T22:52:12.075Z,1527807132.075 [CBIT](INFO): Clearing failed state for component BPC1
2018-05-31T22:52:12.075Z,1527807132.075 [BPC1] No Fault, FailCount= 8
2018-05-31T22:52:17.562Z,1527807137.562 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T22:52:18.359Z,1527807138.359 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T22:53:37.979Z,1527807217.979 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T22:53:37.979Z,1527807217.979 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 21
2018-05-31T22:53:37.979Z,1527807217.979 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T22:53:38.025Z,1527807218.025 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T22:53:38.368Z,1527807218.368 [BPC1](ERROR): Failed to parse Bank A battery data
2018-05-31T22:53:38.413Z,1527807218.413 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T22:53:38.413Z,1527807218.413 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 21
2018-05-31T22:53:38.807Z,1527807218.807 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T22:53:38.808Z,1527807218.808 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T22:53:38.808Z,1527807218.808 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T22:53:44.794Z,1527807224.794 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T22:53:45.590Z,1527807225.590 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T22:53:46.001Z,1527807226.001 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T22:53:55.722Z,1527807235.722 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T22:53:56.517Z,1527807236.517 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T22:53:57.313Z,1527807237.313 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T22:53:58.018Z,1527807238.018 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T22:53:58.409Z,1527807238.409 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-05-31T22:53:58.409Z,1527807238.409 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-05-31T22:53:58.409Z,1527807238.409 [Rowe_600LCM](ERROR): Communications Fault
2018-05-31T22:53:58.417Z,1527807238.417 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-05-31T22:53:58.813Z,1527807238.813 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-05-31T22:53:58.928Z,1527807238.928 [Rowe_600LCM](INFO): Powering down
2018-05-31T22:53:59.737Z,1527807239.737 [Rowe_600LCM](FAULT): LCB fault:  Software Overcurrent.
2018-05-31T22:53:59.737Z,1527807239.737 [Rowe_600LCM] Hardware Fault, FailCount= 1
2018-05-31T22:53:59.737Z,1527807239.737 [Rowe_600LCM](ERROR): Hardware Fault
2018-05-31T22:54:00.407Z,1527807240.407 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-05-31T22:54:00.407Z,1527807240.407 [Rowe_600LCM] No Fault, FailCount= 1
2018-05-31T22:54:00.546Z,1527807240.546 [Rowe_600LCM](INFO): Initializing
2018-05-31T22:54:00.547Z,1527807240.547 [Rowe_600LCM](INFO): Checking LCM
2018-05-31T22:54:00.547Z,1527807240.547 [Rowe_600LCM](INFO): LCM OK
2018-05-31T22:54:00.547Z,1527807240.547 [Rowe_600LCM](INFO): Powering up
2018-05-31T22:54:04.673Z,1527807244.673 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-05-31T22:54:04.792Z,1527807244.792 [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 9600 >& /dev/null &
2018-05-31T22:54:04.795Z,1527807244.795 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-05-31T22:54:04.795Z,1527807244.795 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-05-31T22:54:04.796Z,1527807244.796 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-05-31T22:56:00.820Z,1527807360.820 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-05-31T22:56:00.820Z,1527807360.820 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-05-31T22:56:00.820Z,1527807360.820 [Rowe_600LCM](ERROR): Communications Fault
2018-05-31T22:56:00.903Z,1527807360.903 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-05-31T22:56:01.225Z,1527807361.225 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-05-31T22:56:01.339Z,1527807361.339 [Rowe_600LCM](INFO): Powering down
2018-05-31T22:56:02.826Z,1527807362.826 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-05-31T22:56:02.829Z,1527807362.829 [Rowe_600LCM] No Fault, FailCount= 2
2018-05-31T22:56:02.953Z,1527807362.953 [Rowe_600LCM](INFO): Initializing
2018-05-31T22:56:02.954Z,1527807362.954 [Rowe_600LCM](INFO): Checking LCM
2018-05-31T22:56:02.954Z,1527807362.954 [Rowe_600LCM](INFO): LCM OK
2018-05-31T22:56:02.954Z,1527807362.954 [Rowe_600LCM](INFO): Powering up
2018-05-31T22:56:05.954Z,1527807365.954 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-05-31T22:56:05.955Z,1527807365.955 [NAL9602] Data Fault, FailCount= 1
2018-05-31T22:56:05.955Z,1527807365.955 [NAL9602](ERROR): Data Fault
2018-05-31T22:56:06.031Z,1527807366.031 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-31T22:56:06.031Z,1527807366.031 [Default:CheckIn:C.Wait] Stopped
2018-05-31T22:56:06.031Z,1527807366.031 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-31T22:56:06.031Z,1527807366.031 [Default:CheckIn:D] Running Loop=1
2018-05-31T22:56:06.042Z,1527807366.042 [CBIT](ERROR): Data Fault in component: NAL9602
2018-05-31T22:56:06.354Z,1527807366.354 [NAL9602](INFO): Powering down
2018-05-31T22:56:06.409Z,1527807366.409 [Default:CheckIn:D] Stopped
2018-05-31T22:56:06.409Z,1527807366.409 [Default:CheckIn:E] Running Loop=1
2018-05-31T22:56:06.802Z,1527807366.802 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.278902 min 
2018-05-31T22:56:06.802Z,1527807366.802 [Default:CheckIn:E] Stopped
2018-05-31T22:56:06.803Z,1527807366.803 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-31T22:56:06.803Z,1527807366.803 [Default:CheckIn] Stopped
2018-05-31T22:56:06.803Z,1527807366.803 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-31T22:56:06.803Z,1527807366.803 [Default:CheckIn](INFO): Running loop #7
2018-05-31T22:56:06.803Z,1527807366.803 [Default:CheckIn] Running Loop=7
2018-05-31T22:56:06.803Z,1527807366.803 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-31T22:56:06.803Z,1527807366.803 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-31T22:56:07.080Z,1527807367.080 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-05-31T22:56:07.137Z,1527807367.137 [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 9600 >& /dev/null &
2018-05-31T22:56:07.138Z,1527807367.138 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-05-31T22:56:07.139Z,1527807367.139 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-05-31T22:56:07.140Z,1527807367.140 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-05-31T22:56:07.315Z,1527807367.315 [CBIT](INFO): Clearing failed state for component NAL9602
2018-05-31T22:56:07.315Z,1527807367.315 [NAL9602] No Fault, FailCount= 1
2018-05-31T22:56:36.531Z,1527807396.531 [NAL9602](INFO): Powering up NAL9602
2018-05-31T22:56:38.957Z,1527807398.957 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T22:56:38.957Z,1527807398.957 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 22
2018-05-31T22:56:38.957Z,1527807398.957 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T22:56:39.018Z,1527807399.018 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T22:56:39.388Z,1527807399.388 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T22:56:39.388Z,1527807399.388 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 22
2018-05-31T22:56:39.758Z,1527807399.758 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T22:56:39.758Z,1527807399.758 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T22:56:39.759Z,1527807399.759 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T22:56:47.718Z,1527807407.718 [NAL9602](INFO): NAL9602 initialized
2018-05-31T22:58:02.990Z,1527807482.990 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-05-31T22:58:02.990Z,1527807482.990 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-05-31T22:58:02.990Z,1527807482.990 [Rowe_600LCM](ERROR): Communications Fault
2018-05-31T22:58:03.298Z,1527807483.298 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-05-31T22:58:03.393Z,1527807483.393 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-05-31T22:58:03.484Z,1527807483.484 [Rowe_600LCM](INFO): Powering down
2018-05-31T22:58:04.920Z,1527807484.920 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-05-31T22:58:04.920Z,1527807484.920 [Rowe_600LCM] No Fault, FailCount= 3
2018-05-31T22:58:05.101Z,1527807485.101 [Rowe_600LCM](INFO): Initializing
2018-05-31T22:58:05.102Z,1527807485.102 [Rowe_600LCM](INFO): Checking LCM
2018-05-31T22:58:05.102Z,1527807485.102 [Rowe_600LCM](INFO): LCM OK
2018-05-31T22:58:05.102Z,1527807485.102 [Rowe_600LCM](INFO): Powering up
2018-05-31T22:58:09.295Z,1527807489.295 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-05-31T22:58:09.413Z,1527807489.413 [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 9600 >& /dev/null &
2018-05-31T22:58:09.414Z,1527807489.414 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-05-31T22:58:09.415Z,1527807489.415 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-05-31T22:58:09.416Z,1527807489.416 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-05-31T22:59:16.562Z,1527807556.562 [BPC1](ERROR): Failed to receive data from both battery packs
2018-05-31T22:59:16.562Z,1527807556.562 [BPC1] Communications Fault, FailCount= 9
2018-05-31T22:59:16.562Z,1527807556.562 [BPC1](ERROR): Communications Fault
2018-05-31T22:59:16.651Z,1527807556.651 [CBIT](ERROR): Communications Fault in component: BPC1
2018-05-31T22:59:17.787Z,1527807557.787 [CBIT](INFO): Clearing failed state for component BPC1
2018-05-31T22:59:17.787Z,1527807557.787 [BPC1] No Fault, FailCount= 9
2018-05-31T22:59:39.776Z,1527807579.776 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T22:59:39.776Z,1527807579.776 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 23
2018-05-31T22:59:39.776Z,1527807579.776 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T22:59:39.791Z,1527807579.791 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T22:59:40.192Z,1527807580.192 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T22:59:40.192Z,1527807580.192 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 23
2018-05-31T22:59:40.603Z,1527807580.603 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T22:59:40.604Z,1527807580.604 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T22:59:40.604Z,1527807580.604 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T23:00:05.181Z,1527807605.181 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-05-31T23:00:05.181Z,1527807605.181 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-05-31T23:00:05.181Z,1527807605.181 [Rowe_600LCM](ERROR): Communications Fault
2018-05-31T23:00:05.390Z,1527807605.390 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-05-31T23:00:05.585Z,1527807605.585 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-05-31T23:00:05.678Z,1527807605.678 [Rowe_600LCM](INFO): Powering down
2018-05-31T23:00:06.991Z,1527807606.991 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-05-31T23:00:06.991Z,1527807606.991 [Rowe_600LCM] No Fault, FailCount= 4
2018-05-31T23:00:07.293Z,1527807607.293 [Rowe_600LCM](INFO): Initializing
2018-05-31T23:00:07.293Z,1527807607.293 [Rowe_600LCM](INFO): Checking LCM
2018-05-31T23:00:07.294Z,1527807607.294 [Rowe_600LCM](INFO): LCM OK
2018-05-31T23:00:07.294Z,1527807607.294 [Rowe_600LCM](INFO): Powering up
2018-05-31T23:00:11.542Z,1527807611.542 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-05-31T23:00:11.602Z,1527807611.602 [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 9600 >& /dev/null &
2018-05-31T23:00:11.603Z,1527807611.603 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-05-31T23:00:11.604Z,1527807611.604 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-05-31T23:00:11.605Z,1527807611.605 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-05-31T23:01:06.978Z,1527807666.978 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-05-31T22:56:06.8Z
2018-05-31T23:01:06.978Z,1527807666.978 [Default:CheckIn:Read_GPS] Stopped
2018-05-31T23:01:06.978Z,1527807666.978 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-31T23:01:12.477Z,1527807672.477 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180531T215006/Courier0022.lzma
2018-05-31T23:01:13.259Z,1527807673.259 [DataOverHttps](INFO): Moved sent file to Logs/20180531T215006/Courier0022.lzma.bak
2018-05-31T23:01:13.259Z,1527807673.259 [DataOverHttps](INFO): SBD MOMSN=8300939
2018-05-31T23:01:23.019Z,1527807683.019 [DataOverHttps](INFO): Sending 255 bytes from file Logs/20180531T215006/Express0023.lzma
2018-05-31T23:01:23.772Z,1527807683.772 [DataOverHttps](INFO): Moved sent file to Logs/20180531T215006/Express0023.lzma.bak
2018-05-31T23:01:23.772Z,1527807683.772 [DataOverHttps](INFO): SBD MOMSN=8300941
2018-05-31T23:01:24.961Z,1527807684.961 [Default:CheckIn:Read_Iridium] Stopped
2018-05-31T23:01:24.962Z,1527807684.962 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-31T23:01:24.962Z,1527807684.962 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-31T23:01:51.454Z,1527807711.454 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:01:52.250Z,1527807712.250 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:02:07.363Z,1527807727.363 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-05-31T23:02:07.363Z,1527807727.363 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-05-31T23:02:07.363Z,1527807727.363 [Rowe_600LCM](ERROR): Communications Fault
2018-05-31T23:02:07.490Z,1527807727.490 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-05-31T23:02:07.491Z,1527807727.491 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-05-31T23:02:07.769Z,1527807727.769 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-05-31T23:02:08.042Z,1527807728.042 [Rowe_600LCM](INFO): Powering down
2018-05-31T23:02:23.959Z,1527807743.959 [CommandLine](IMPORTANT): got command failComponent
2018-05-31T23:02:23.959Z,1527807743.959 [CommandLine](IMPORTANT): Failed components:
2018-05-31T23:02:23.959Z,1527807743.959 [CommandLine](IMPORTANT): Rowe_600LCM: Communications Fault
2018-05-31T23:02:40.662Z,1527807760.662 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T23:02:40.662Z,1527807760.662 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 24
2018-05-31T23:02:40.662Z,1527807760.662 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T23:02:40.676Z,1527807760.676 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T23:02:41.088Z,1527807761.088 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T23:02:41.088Z,1527807761.088 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 24
2018-05-31T23:02:41.467Z,1527807761.467 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T23:02:41.468Z,1527807761.468 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T23:02:41.468Z,1527807761.468 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T23:05:31.835Z,1527807931.835 [BPC1](ERROR): Failed to receive data from both battery packs
2018-05-31T23:05:31.835Z,1527807931.835 [BPC1] Communications Fault, FailCount= 10
2018-05-31T23:05:31.835Z,1527807931.835 [BPC1](ERROR): Communications Fault
2018-05-31T23:05:31.863Z,1527807931.863 [CBIT](ERROR): Communications Fault in component: BPC1
2018-05-31T23:05:31.864Z,1527807931.864 [CBIT](CRITICAL): Communications Fault in component: BPC1
2018-05-31T23:05:32.271Z,1527807932.271 [CBIT](INFO): Critical error at 20180531T230531
2018-05-31T23:05:41.881Z,1527807941.881 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T23:05:41.881Z,1527807941.881 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 25
2018-05-31T23:05:41.881Z,1527807941.881 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T23:05:41.895Z,1527807941.895 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T23:05:42.282Z,1527807942.282 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T23:05:42.282Z,1527807942.282 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 25
2018-05-31T23:05:42.653Z,1527807942.653 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T23:05:42.653Z,1527807942.653 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T23:05:42.654Z,1527807942.654 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T23:06:25.456Z,1527807985.456 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-31T23:06:25.456Z,1527807985.456 [Default:CheckIn:C.Wait] Stopped
2018-05-31T23:06:25.456Z,1527807985.456 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-31T23:06:25.457Z,1527807985.457 [Default:CheckIn:D] Running Loop=1
2018-05-31T23:06:25.849Z,1527807985.849 [Default:CheckIn:D] Stopped
2018-05-31T23:06:25.849Z,1527807985.849 [Default:CheckIn:E] Running Loop=1
2018-05-31T23:06:26.282Z,1527807986.282 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 72.602905 min 
2018-05-31T23:06:26.283Z,1527807986.283 [Default:CheckIn:E] Stopped
2018-05-31T23:06:26.283Z,1527807986.283 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-31T23:06:26.283Z,1527807986.283 [Default:CheckIn] Stopped
2018-05-31T23:06:26.283Z,1527807986.283 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-31T23:06:26.283Z,1527807986.283 [Default:CheckIn](INFO): Running loop #8
2018-05-31T23:06:26.283Z,1527807986.283 [Default:CheckIn] Running Loop=8
2018-05-31T23:06:26.283Z,1527807986.283 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-31T23:06:26.284Z,1527807986.284 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-31T23:06:48.614Z,1527808008.614 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-05-31T23:06:48.614Z,1527808008.614 [NAL9602] Data Fault, FailCount= 2
2018-05-31T23:06:48.614Z,1527808008.614 [NAL9602](ERROR): Data Fault
2018-05-31T23:06:48.705Z,1527808008.705 [CBIT](ERROR): Data Fault in component: NAL9602
2018-05-31T23:06:49.015Z,1527808009.015 [NAL9602](INFO): Powering down
2018-05-31T23:06:49.869Z,1527808009.869 [CBIT](INFO): Clearing failed state for component NAL9602
2018-05-31T23:06:49.869Z,1527808009.869 [NAL9602] No Fault, FailCount= 2
2018-05-31T23:07:19.018Z,1527808039.018 [NAL9602](INFO): Powering up NAL9602
2018-05-31T23:07:29.818Z,1527808049.818 [NAL9602](INFO): NAL9602 initialized
2018-05-31T23:08:42.685Z,1527808122.685 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T23:08:42.685Z,1527808122.685 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 26
2018-05-31T23:08:42.685Z,1527808122.685 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T23:08:42.742Z,1527808122.742 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T23:08:43.121Z,1527808123.121 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T23:08:43.121Z,1527808123.121 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 26
2018-05-31T23:08:43.483Z,1527808123.483 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T23:08:43.483Z,1527808123.483 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T23:08:43.483Z,1527808123.483 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T23:10:31.907Z,1527808231.907 [CBIT](INFO): Clearing failed state for component BPC1
2018-05-31T23:10:31.907Z,1527808231.907 [BPC1] No Fault, FailCount= 10
2018-05-31T23:11:26.296Z,1527808286.296 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-05-31T23:06:26.3Z
2018-05-31T23:11:26.296Z,1527808286.296 [Default:CheckIn:Read_GPS] Stopped
2018-05-31T23:11:26.296Z,1527808286.296 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-31T23:11:30.204Z,1527808290.204 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20180531T215006/Courier0025.lzma
2018-05-31T23:11:31.004Z,1527808291.004 [DataOverHttps](INFO): Moved sent file to Logs/20180531T215006/Courier0025.lzma.bak
2018-05-31T23:11:31.004Z,1527808291.004 [DataOverHttps](INFO): SBD MOMSN=8300959
2018-05-31T23:11:40.442Z,1527808300.442 [DataOverHttps](INFO): Sending 293 bytes from file Logs/20180531T215006/Express0026.lzma
2018-05-31T23:11:41.220Z,1527808301.220 [DataOverHttps](INFO): Moved sent file to Logs/20180531T215006/Express0026.lzma.bak
2018-05-31T23:11:41.221Z,1527808301.221 [DataOverHttps](INFO): SBD MOMSN=8300962
2018-05-31T23:11:42.402Z,1527808302.402 [Default:CheckIn:Read_Iridium] Stopped
2018-05-31T23:11:42.402Z,1527808302.402 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-31T23:11:42.402Z,1527808302.402 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-31T23:11:45.657Z,1527808305.657 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T23:11:45.658Z,1527808305.658 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 27
2018-05-31T23:11:45.658Z,1527808305.658 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T23:11:45.675Z,1527808305.675 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T23:11:45.817Z,1527808305.817 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T23:11:45.817Z,1527808305.817 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 27
2018-05-31T23:11:46.128Z,1527808306.128 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T23:11:46.129Z,1527808306.129 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T23:11:46.129Z,1527808306.129 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T23:12:07.740Z,1527808327.740 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-05-31T23:12:07.741Z,1527808327.741 [Rowe_600LCM] No Fault, FailCount= 5
2018-05-31T23:12:07.885Z,1527808327.885 [Rowe_600LCM](INFO): Initializing
2018-05-31T23:12:07.885Z,1527808327.885 [Rowe_600LCM](INFO): Checking LCM
2018-05-31T23:12:07.885Z,1527808327.885 [Rowe_600LCM](INFO): LCM OK
2018-05-31T23:12:07.886Z,1527808327.886 [Rowe_600LCM](INFO): Powering up
2018-05-31T23:12:12.007Z,1527808332.007 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-05-31T23:12:12.072Z,1527808332.072 [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 9600 >& /dev/null &
2018-05-31T23:12:12.073Z,1527808332.073 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-05-31T23:12:12.074Z,1527808332.074 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-05-31T23:12:12.075Z,1527808332.075 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-05-31T23:14:08.265Z,1527808448.265 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-05-31T23:14:08.265Z,1527808448.265 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-05-31T23:14:08.265Z,1527808448.265 [Rowe_600LCM](ERROR): Communications Fault
2018-05-31T23:14:08.573Z,1527808448.573 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-05-31T23:14:08.669Z,1527808448.669 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-05-31T23:14:08.769Z,1527808448.769 [Rowe_600LCM](INFO): Powering down
2018-05-31T23:14:10.174Z,1527808450.174 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-05-31T23:14:10.174Z,1527808450.174 [Rowe_600LCM] No Fault, FailCount= 1
2018-05-31T23:14:10.385Z,1527808450.385 [Rowe_600LCM](INFO): Initializing
2018-05-31T23:14:10.385Z,1527808450.385 [Rowe_600LCM](INFO): Checking LCM
2018-05-31T23:14:10.386Z,1527808450.386 [Rowe_600LCM](INFO): LCM OK
2018-05-31T23:14:10.386Z,1527808450.386 [Rowe_600LCM](INFO): Powering up
2018-05-31T23:14:14.591Z,1527808454.591 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-05-31T23:14:14.704Z,1527808454.704 [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 9600 >& /dev/null &
2018-05-31T23:14:14.706Z,1527808454.706 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-05-31T23:14:14.707Z,1527808454.707 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-05-31T23:14:14.708Z,1527808454.708 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-05-31T23:14:46.803Z,1527808486.803 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T23:14:46.803Z,1527808486.803 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 28
2018-05-31T23:14:46.803Z,1527808486.803 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T23:14:46.860Z,1527808486.860 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T23:14:46.960Z,1527808486.960 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T23:14:46.960Z,1527808486.960 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 28
2018-05-31T23:14:47.323Z,1527808487.323 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T23:14:47.323Z,1527808487.323 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T23:14:47.324Z,1527808487.324 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T23:15:25.318Z,1527808525.318 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:26.114Z,1527808526.114 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:26.705Z,1527808526.705 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:27.501Z,1527808527.501 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:28.297Z,1527808528.297 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:29.009Z,1527808529.009 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:29.805Z,1527808529.805 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:30.602Z,1527808530.602 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:31.009Z,1527808531.009 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:31.805Z,1527808531.805 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:32.461Z,1527808532.461 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:33.257Z,1527808533.257 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:34.054Z,1527808534.054 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:34.461Z,1527808534.461 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:35.257Z,1527808535.257 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:36.054Z,1527808536.054 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:36.502Z,1527808536.502 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:37.298Z,1527808537.298 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:38.094Z,1527808538.094 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:38.549Z,1527808538.549 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:39.345Z,1527808539.345 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:40.143Z,1527808540.143 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:40.717Z,1527808540.717 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:41.513Z,1527808541.513 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:42.309Z,1527808542.309 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:43.013Z,1527808543.013 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:43.809Z,1527808543.809 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:44.606Z,1527808544.606 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:45.017Z,1527808545.017 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:45.813Z,1527808545.813 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:46.474Z,1527808546.474 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:47.269Z,1527808547.269 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:48.066Z,1527808548.066 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:48.482Z,1527808548.482 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:49.277Z,1527808549.277 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:50.074Z,1527808550.074 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:50.497Z,1527808550.497 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:51.293Z,1527808551.293 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:52.090Z,1527808552.090 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:52.545Z,1527808552.545 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:53.342Z,1527808553.342 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:54.138Z,1527808554.138 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:54.742Z,1527808554.742 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:55.538Z,1527808555.538 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:56.334Z,1527808556.334 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:57.034Z,1527808557.034 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:57.829Z,1527808557.829 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:58.626Z,1527808558.626 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:59.145Z,1527808559.145 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:15:59.942Z,1527808559.942 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:16:00.498Z,1527808560.498 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:16:01.295Z,1527808561.295 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:16:02.090Z,1527808562.090 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:16:02.506Z,1527808562.506 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:16:03.302Z,1527808563.302 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:16:04.098Z,1527808564.098 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:16:04.522Z,1527808564.522 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:16:05.318Z,1527808565.318 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:16:06.114Z,1527808566.114 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:16:06.569Z,1527808566.569 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:16:07.365Z,1527808567.365 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:16:08.162Z,1527808568.162 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:16:08.766Z,1527808568.766 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:16:09.561Z,1527808569.561 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:16:10.358Z,1527808570.358 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:16:10.657Z,1527808570.657 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-05-31T23:16:10.657Z,1527808570.657 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-05-31T23:16:10.657Z,1527808570.657 [Rowe_600LCM](ERROR): Communications Fault
2018-05-31T23:16:10.786Z,1527808570.786 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-05-31T23:16:11.061Z,1527808571.061 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-05-31T23:16:11.271Z,1527808571.271 [Rowe_600LCM](INFO): Powering down
2018-05-31T23:16:12.077Z,1527808572.077 [Rowe_600LCM](FAULT): LCB fault:  Software Overcurrent.
2018-05-31T23:16:12.077Z,1527808572.077 [Rowe_600LCM] Hardware Fault, FailCount= 2
2018-05-31T23:16:12.077Z,1527808572.077 [Rowe_600LCM](ERROR): Hardware Fault
2018-05-31T23:16:12.738Z,1527808572.738 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-05-31T23:16:12.738Z,1527808572.738 [Rowe_600LCM] No Fault, FailCount= 2
2018-05-31T23:16:12.885Z,1527808572.885 [Rowe_600LCM](INFO): Initializing
2018-05-31T23:16:12.885Z,1527808572.885 [Rowe_600LCM](INFO): Checking LCM
2018-05-31T23:16:12.886Z,1527808572.886 [Rowe_600LCM](INFO): LCM OK
2018-05-31T23:16:12.886Z,1527808572.886 [Rowe_600LCM](INFO): Powering up
2018-05-31T23:16:17.005Z,1527808577.005 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-05-31T23:16:17.123Z,1527808577.123 [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 9600 >& /dev/null &
2018-05-31T23:16:17.124Z,1527808577.124 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-05-31T23:16:17.133Z,1527808577.133 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-05-31T23:16:17.134Z,1527808577.134 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-05-31T23:16:42.743Z,1527808602.743 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-31T23:16:42.743Z,1527808602.743 [Default:CheckIn:C.Wait] Stopped
2018-05-31T23:16:42.743Z,1527808602.743 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-31T23:16:42.743Z,1527808602.743 [Default:CheckIn:D] Running Loop=1
2018-05-31T23:16:43.144Z,1527808603.144 [Default:CheckIn:D] Stopped
2018-05-31T23:16:43.144Z,1527808603.144 [Default:CheckIn:E] Running Loop=1
2018-05-31T23:16:43.532Z,1527808603.532 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 82.891146 min 
2018-05-31T23:16:43.532Z,1527808603.532 [Default:CheckIn:E] Stopped
2018-05-31T23:16:43.533Z,1527808603.533 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-31T23:16:43.533Z,1527808603.533 [Default:CheckIn] Stopped
2018-05-31T23:16:43.533Z,1527808603.533 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-31T23:16:43.533Z,1527808603.533 [Default:CheckIn](INFO): Running loop #9
2018-05-31T23:16:43.533Z,1527808603.533 [Default:CheckIn] Running Loop=9
2018-05-31T23:16:43.534Z,1527808603.534 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-31T23:16:43.534Z,1527808603.534 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-31T23:17:14.114Z,1527808634.114 [BPC1](ERROR): Failed to receive data from both battery packs
2018-05-31T23:17:14.114Z,1527808634.114 [BPC1] Communications Fault, FailCount= 1
2018-05-31T23:17:14.114Z,1527808634.114 [BPC1](ERROR): Communications Fault
2018-05-31T23:17:14.139Z,1527808634.139 [CBIT](ERROR): Communications Fault in component: BPC1
2018-05-31T23:17:15.344Z,1527808635.344 [CBIT](INFO): Clearing failed state for component BPC1
2018-05-31T23:17:15.344Z,1527808635.344 [BPC1] No Fault, FailCount= 1
2018-05-31T23:17:30.882Z,1527808650.882 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-05-31T23:17:30.882Z,1527808650.882 [NAL9602] Data Fault, FailCount= 3
2018-05-31T23:17:30.882Z,1527808650.882 [NAL9602](ERROR): Data Fault
2018-05-31T23:17:30.968Z,1527808650.968 [CBIT](ERROR): Data Fault in component: NAL9602
2018-05-31T23:17:31.290Z,1527808651.290 [NAL9602](INFO): Powering down
2018-05-31T23:17:32.144Z,1527808652.144 [CBIT](INFO): Clearing failed state for component NAL9602
2018-05-31T23:17:32.144Z,1527808652.144 [NAL9602] No Fault, FailCount= 3
2018-05-31T23:17:42.910Z,1527808662.910 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:17:43.706Z,1527808663.706 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:17:47.581Z,1527808667.581 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T23:17:47.581Z,1527808667.581 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 29
2018-05-31T23:17:47.581Z,1527808667.581 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T23:17:47.614Z,1527808667.614 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T23:17:47.724Z,1527808667.724 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T23:17:47.725Z,1527808667.725 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 29
2018-05-31T23:17:48.134Z,1527808668.134 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T23:17:48.135Z,1527808668.135 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T23:17:48.136Z,1527808668.136 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T23:18:01.626Z,1527808681.626 [NAL9602](INFO): Powering up NAL9602
2018-05-31T23:18:12.831Z,1527808692.831 [NAL9602](INFO): NAL9602 initialized
2018-05-31T23:18:13.669Z,1527808693.669 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-05-31T23:18:13.669Z,1527808693.669 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-05-31T23:18:13.669Z,1527808693.669 [Rowe_600LCM](ERROR): Communications Fault
2018-05-31T23:18:13.703Z,1527808693.703 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-05-31T23:18:14.073Z,1527808694.073 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-05-31T23:18:14.230Z,1527808694.230 [Rowe_600LCM](INFO): Powering down
2018-05-31T23:18:15.698Z,1527808695.698 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-05-31T23:18:15.698Z,1527808695.698 [Rowe_600LCM] No Fault, FailCount= 3
2018-05-31T23:18:15.846Z,1527808695.846 [Rowe_600LCM](INFO): Initializing
2018-05-31T23:18:15.847Z,1527808695.847 [Rowe_600LCM](INFO): Checking LCM
2018-05-31T23:18:15.847Z,1527808695.847 [Rowe_600LCM](INFO): LCM OK
2018-05-31T23:18:15.847Z,1527808695.847 [Rowe_600LCM](INFO): Powering up
2018-05-31T23:18:19.970Z,1527808699.970 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-05-31T23:18:20.076Z,1527808700.076 [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 9600 >& /dev/null &
2018-05-31T23:18:20.078Z,1527808700.078 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-05-31T23:18:20.078Z,1527808700.078 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-05-31T23:18:20.079Z,1527808700.079 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-05-31T23:19:07.367Z,1527808747.367 [BPC1](ERROR): Failed to parse message.
2018-05-31T23:19:07.368Z,1527808747.368 [BPC1](ERROR): Failed to parse Bank B battery data
2018-05-31T23:19:32.959Z,1527808772.959 [BPC1](ERROR): Failed to parse Bank B battery data
2018-05-31T23:20:02.486Z,1527808802.486 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:20:03.282Z,1527808803.282 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:20:16.409Z,1527808816.409 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-05-31T23:20:16.409Z,1527808816.409 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-05-31T23:20:16.409Z,1527808816.409 [Rowe_600LCM](ERROR): Communications Fault
2018-05-31T23:20:16.691Z,1527808816.691 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-05-31T23:20:16.813Z,1527808816.813 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-05-31T23:20:16.916Z,1527808816.916 [Rowe_600LCM](INFO): Powering down
2018-05-31T23:20:18.296Z,1527808818.296 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-05-31T23:20:18.301Z,1527808818.301 [Rowe_600LCM] No Fault, FailCount= 4
2018-05-31T23:20:18.533Z,1527808818.533 [Rowe_600LCM](INFO): Initializing
2018-05-31T23:20:18.534Z,1527808818.534 [Rowe_600LCM](INFO): Checking LCM
2018-05-31T23:20:18.534Z,1527808818.534 [Rowe_600LCM](INFO): LCM OK
2018-05-31T23:20:18.534Z,1527808818.534 [Rowe_600LCM](INFO): Powering up
2018-05-31T23:20:22.776Z,1527808822.776 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-05-31T23:20:22.850Z,1527808822.850 [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 9600 >& /dev/null &
2018-05-31T23:20:22.851Z,1527808822.851 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-05-31T23:20:22.852Z,1527808822.852 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-05-31T23:20:22.853Z,1527808822.853 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-05-31T23:20:51.312Z,1527808851.312 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T23:20:51.312Z,1527808851.312 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 30
2018-05-31T23:20:51.312Z,1527808851.312 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T23:20:51.326Z,1527808851.326 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T23:20:51.570Z,1527808851.570 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T23:20:51.570Z,1527808851.570 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 30
2018-05-31T23:20:51.802Z,1527808851.802 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T23:20:51.802Z,1527808851.802 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T23:20:51.802Z,1527808851.802 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T23:21:43.817Z,1527808903.817 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-05-31T23:16:43.5Z
2018-05-31T23:21:43.817Z,1527808903.817 [Default:CheckIn:Read_GPS] Stopped
2018-05-31T23:21:43.818Z,1527808903.818 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-31T23:21:48.573Z,1527808908.573 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180531T215006/Courier0028.lzma
2018-05-31T23:21:49.340Z,1527808909.340 [DataOverHttps](INFO): Moved sent file to Logs/20180531T215006/Courier0028.lzma.bak
2018-05-31T23:21:49.340Z,1527808909.340 [DataOverHttps](INFO): SBD MOMSN=8300978
2018-05-31T23:21:59.261Z,1527808919.261 [DataOverHttps](INFO): Sending 256 bytes from file Logs/20180531T215006/Express0029.lzma
2018-05-31T23:22:00.024Z,1527808920.024 [DataOverHttps](INFO): Moved sent file to Logs/20180531T215006/Express0029.lzma.bak
2018-05-31T23:22:00.029Z,1527808920.029 [DataOverHttps](INFO): SBD MOMSN=8300980
2018-05-31T23:22:01.206Z,1527808921.206 [Default:CheckIn:Read_Iridium] Stopped
2018-05-31T23:22:01.206Z,1527808921.206 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-31T23:22:01.207Z,1527808921.207 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-31T23:22:04.310Z,1527808924.310 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:22:05.114Z,1527808925.114 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:22:18.725Z,1527808938.725 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-05-31T23:22:18.725Z,1527808938.725 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-05-31T23:22:18.725Z,1527808938.725 [Rowe_600LCM](ERROR): Communications Fault
2018-05-31T23:22:18.811Z,1527808938.811 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-05-31T23:22:18.811Z,1527808938.811 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-05-31T23:22:19.130Z,1527808939.130 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-05-31T23:22:19.330Z,1527808939.330 [Rowe_600LCM](INFO): Powering down
2018-05-31T23:23:51.945Z,1527809031.945 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T23:23:51.945Z,1527809031.945 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 31
2018-05-31T23:23:51.946Z,1527809031.946 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T23:23:51.959Z,1527809031.959 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T23:23:52.355Z,1527809032.355 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T23:23:52.355Z,1527809032.355 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 31
2018-05-31T23:23:52.750Z,1527809032.750 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T23:23:52.751Z,1527809032.751 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T23:23:52.752Z,1527809032.752 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T23:25:51.952Z,1527809151.952 [BPC1](ERROR): Failed to receive data from both battery packs
2018-05-31T23:25:51.952Z,1527809151.952 [BPC1] Communications Fault, FailCount= 2
2018-05-31T23:25:51.952Z,1527809151.952 [BPC1](ERROR): Communications Fault
2018-05-31T23:25:52.011Z,1527809152.011 [CBIT](ERROR): Communications Fault in component: BPC1
2018-05-31T23:25:53.261Z,1527809153.261 [CBIT](INFO): Clearing failed state for component BPC1
2018-05-31T23:25:53.262Z,1527809153.262 [BPC1] No Fault, FailCount= 2
2018-05-31T23:26:53.123Z,1527809213.123 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T23:26:53.123Z,1527809213.123 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 32
2018-05-31T23:26:53.123Z,1527809213.123 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T23:26:53.138Z,1527809213.138 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T23:26:53.538Z,1527809213.538 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T23:26:53.538Z,1527809213.538 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 32
2018-05-31T23:26:53.922Z,1527809213.922 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T23:26:53.922Z,1527809213.922 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T23:26:53.922Z,1527809213.922 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T23:27:01.518Z,1527809221.518 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-31T23:27:01.518Z,1527809221.518 [Default:CheckIn:C.Wait] Stopped
2018-05-31T23:27:01.518Z,1527809221.518 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-31T23:27:01.518Z,1527809221.518 [Default:CheckIn:D] Running Loop=1
2018-05-31T23:27:01.920Z,1527809221.920 [Default:CheckIn:D] Stopped
2018-05-31T23:27:01.920Z,1527809221.920 [Default:CheckIn:E] Running Loop=1
2018-05-31T23:27:02.327Z,1527809222.327 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 93.204085 min 
2018-05-31T23:27:02.327Z,1527809222.327 [Default:CheckIn:E] Stopped
2018-05-31T23:27:02.327Z,1527809222.327 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-31T23:27:02.327Z,1527809222.327 [Default:CheckIn] Stopped
2018-05-31T23:27:02.327Z,1527809222.327 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-31T23:27:02.327Z,1527809222.327 [Default:CheckIn](INFO): Running loop #10
2018-05-31T23:27:02.328Z,1527809222.328 [Default:CheckIn] Running Loop=10
2018-05-31T23:27:02.328Z,1527809222.328 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-31T23:27:02.328Z,1527809222.328 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-31T23:28:16.302Z,1527809296.302 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-05-31T23:28:16.302Z,1527809296.302 [NAL9602] Data Fault, FailCount= 4
2018-05-31T23:28:16.303Z,1527809296.303 [NAL9602](ERROR): Data Fault
2018-05-31T23:28:16.384Z,1527809296.384 [CBIT](ERROR): Data Fault in component: NAL9602
2018-05-31T23:28:16.698Z,1527809296.698 [NAL9602](INFO): Powering down
2018-05-31T23:28:17.569Z,1527809297.569 [CBIT](INFO): Clearing failed state for component NAL9602
2018-05-31T23:28:17.569Z,1527809297.569 [NAL9602] No Fault, FailCount= 4
2018-05-31T23:28:47.098Z,1527809327.098 [NAL9602](INFO): Powering up NAL9602
2018-05-31T23:28:57.898Z,1527809337.898 [NAL9602](INFO): NAL9602 initialized
2018-05-31T23:29:54.002Z,1527809394.002 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T23:29:54.002Z,1527809394.002 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 33
2018-05-31T23:29:54.002Z,1527809394.002 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T23:29:54.016Z,1527809394.016 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T23:29:54.416Z,1527809394.416 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T23:29:54.417Z,1527809394.417 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 33
2018-05-31T23:29:54.815Z,1527809394.815 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T23:29:54.815Z,1527809394.815 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T23:29:54.815Z,1527809394.815 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T23:32:02.452Z,1527809522.452 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-05-31T23:27:02.3Z
2018-05-31T23:32:02.452Z,1527809522.452 [Default:CheckIn:Read_GPS] Stopped
2018-05-31T23:32:02.452Z,1527809522.452 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-31T23:32:06.486Z,1527809526.486 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180531T215006/Courier0031.lzma
2018-05-31T23:32:07.281Z,1527809527.281 [DataOverHttps](INFO): Moved sent file to Logs/20180531T215006/Courier0031.lzma.bak
2018-05-31T23:32:07.281Z,1527809527.281 [DataOverHttps](INFO): SBD MOMSN=8300998
2018-05-31T23:32:16.513Z,1527809536.513 [DataOverHttps](INFO): Sending 251 bytes from file Logs/20180531T215006/Express0032.lzma
2018-05-31T23:32:17.300Z,1527809537.300 [DataOverHttps](INFO): Moved sent file to Logs/20180531T215006/Express0032.lzma.bak
2018-05-31T23:32:17.300Z,1527809537.300 [DataOverHttps](INFO): SBD MOMSN=8301000
2018-05-31T23:32:18.478Z,1527809538.478 [Default:CheckIn:Read_Iridium] Stopped
2018-05-31T23:32:18.478Z,1527809538.478 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-31T23:32:18.478Z,1527809538.478 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-31T23:32:21.690Z,1527809541.690 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-05-31T23:32:21.690Z,1527809541.690 [Rowe_600LCM] No Fault, FailCount= 5
2018-05-31T23:32:22.029Z,1527809542.029 [Rowe_600LCM](INFO): Initializing
2018-05-31T23:32:22.029Z,1527809542.029 [Rowe_600LCM](INFO): Checking LCM
2018-05-31T23:32:22.029Z,1527809542.029 [Rowe_600LCM](INFO): LCM OK
2018-05-31T23:32:22.030Z,1527809542.030 [Rowe_600LCM](INFO): Powering up
2018-05-31T23:32:26.266Z,1527809546.266 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-05-31T23:32:26.332Z,1527809546.332 [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 9600 >& /dev/null &
2018-05-31T23:32:26.333Z,1527809546.333 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-05-31T23:32:26.334Z,1527809546.334 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-05-31T23:32:26.335Z,1527809546.335 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-05-31T23:32:54.988Z,1527809574.988 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T23:32:54.988Z,1527809574.988 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 34
2018-05-31T23:32:54.988Z,1527809574.988 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T23:32:55.022Z,1527809575.022 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T23:32:55.386Z,1527809575.386 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T23:32:55.386Z,1527809575.386 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 34
2018-05-31T23:32:55.760Z,1527809575.760 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T23:32:55.760Z,1527809575.760 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T23:32:55.760Z,1527809575.760 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T23:32:58.548Z,1527809578.548 [BPC1](ERROR): Failed to receive data from both battery packs
2018-05-31T23:32:58.548Z,1527809578.548 [BPC1] Communications Fault, FailCount= 3
2018-05-31T23:32:58.548Z,1527809578.548 [BPC1](ERROR): Communications Fault
2018-05-31T23:32:58.608Z,1527809578.608 [CBIT](ERROR): Communications Fault in component: BPC1
2018-05-31T23:32:59.774Z,1527809579.774 [CBIT](INFO): Clearing failed state for component BPC1
2018-05-31T23:32:59.774Z,1527809579.774 [BPC1] No Fault, FailCount= 3
2018-05-31T23:34:05.418Z,1527809645.418 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:34:06.214Z,1527809646.214 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:34:22.133Z,1527809662.133 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-05-31T23:34:22.133Z,1527809662.133 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-05-31T23:34:22.133Z,1527809662.133 [Rowe_600LCM](ERROR): Communications Fault
2018-05-31T23:34:22.537Z,1527809662.537 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-05-31T23:34:22.701Z,1527809662.701 [Rowe_600LCM](INFO): Powering down
2018-05-31T23:34:23.469Z,1527809663.469 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-05-31T23:34:23.974Z,1527809663.974 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-05-31T23:34:23.974Z,1527809663.974 [Rowe_600LCM] No Fault, FailCount= 1
2018-05-31T23:34:24.317Z,1527809664.317 [Rowe_600LCM](INFO): Initializing
2018-05-31T23:34:24.318Z,1527809664.318 [Rowe_600LCM](INFO): Checking LCM
2018-05-31T23:34:24.318Z,1527809664.318 [Rowe_600LCM](INFO): LCM OK
2018-05-31T23:34:24.318Z,1527809664.318 [Rowe_600LCM](INFO): Powering up
2018-05-31T23:34:28.471Z,1527809668.471 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-05-31T23:34:28.536Z,1527809668.536 [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 9600 >& /dev/null &
2018-05-31T23:34:28.537Z,1527809668.537 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-05-31T23:34:28.538Z,1527809668.538 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-05-31T23:34:28.539Z,1527809668.539 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-05-31T23:35:55.766Z,1527809755.766 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T23:35:55.766Z,1527809755.766 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 35
2018-05-31T23:35:55.766Z,1527809755.766 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T23:35:55.832Z,1527809755.832 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T23:35:56.232Z,1527809756.232 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T23:35:56.232Z,1527809756.232 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 35
2018-05-31T23:35:56.624Z,1527809756.624 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T23:35:56.624Z,1527809756.624 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T23:35:56.624Z,1527809756.624 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T23:36:24.522Z,1527809784.522 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-05-31T23:36:24.522Z,1527809784.522 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-05-31T23:36:24.522Z,1527809784.522 [Rowe_600LCM](ERROR): Communications Fault
2018-05-31T23:36:24.799Z,1527809784.799 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-05-31T23:36:24.925Z,1527809784.925 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-05-31T23:36:25.013Z,1527809785.013 [Rowe_600LCM](INFO): Powering down
2018-05-31T23:36:25.821Z,1527809785.821 [Rowe_600LCM](FAULT): LCB fault:  Software Overcurrent.
2018-05-31T23:36:25.822Z,1527809785.822 [Rowe_600LCM] Hardware Fault, FailCount= 2
2018-05-31T23:36:25.822Z,1527809785.822 [Rowe_600LCM](ERROR): Hardware Fault
2018-05-31T23:36:26.406Z,1527809786.406 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-05-31T23:36:26.406Z,1527809786.406 [Rowe_600LCM] No Fault, FailCount= 2
2018-05-31T23:36:26.629Z,1527809786.629 [Rowe_600LCM](INFO): Initializing
2018-05-31T23:36:26.630Z,1527809786.630 [Rowe_600LCM](INFO): Checking LCM
2018-05-31T23:36:26.630Z,1527809786.630 [Rowe_600LCM](INFO): LCM OK
2018-05-31T23:36:26.630Z,1527809786.630 [Rowe_600LCM](INFO): Powering up
2018-05-31T23:36:30.856Z,1527809790.856 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-05-31T23:36:30.932Z,1527809790.932 [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 9600 >& /dev/null &
2018-05-31T23:36:30.933Z,1527809790.933 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-05-31T23:36:30.934Z,1527809790.934 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-05-31T23:36:30.935Z,1527809790.935 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-05-31T23:37:18.763Z,1527809838.763 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-31T23:37:18.764Z,1527809838.764 [Default:CheckIn:C.Wait] Stopped
2018-05-31T23:37:18.764Z,1527809838.764 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-31T23:37:18.764Z,1527809838.764 [Default:CheckIn:D] Running Loop=1
2018-05-31T23:37:19.167Z,1527809839.167 [Default:CheckIn:D] Stopped
2018-05-31T23:37:19.167Z,1527809839.167 [Default:CheckIn:E] Running Loop=1
2018-05-31T23:37:19.583Z,1527809839.583 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 103.491536 min 
2018-05-31T23:37:19.583Z,1527809839.583 [Default:CheckIn:E] Stopped
2018-05-31T23:37:19.583Z,1527809839.583 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-31T23:37:19.583Z,1527809839.583 [Default:CheckIn] Stopped
2018-05-31T23:37:19.583Z,1527809839.583 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-31T23:37:19.584Z,1527809839.584 [Default:CheckIn](INFO): Running loop #11
2018-05-31T23:37:19.584Z,1527809839.584 [Default:CheckIn] Running Loop=11
2018-05-31T23:37:19.584Z,1527809839.584 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-31T23:37:19.584Z,1527809839.584 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-31T23:38:26.727Z,1527809906.727 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-05-31T23:38:26.727Z,1527809906.727 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-05-31T23:38:26.727Z,1527809906.727 [Rowe_600LCM](ERROR): Communications Fault
2018-05-31T23:38:26.799Z,1527809906.799 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-05-31T23:38:27.129Z,1527809907.129 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-05-31T23:38:27.281Z,1527809907.281 [Rowe_600LCM](INFO): Powering down
2018-05-31T23:38:28.750Z,1527809908.750 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-05-31T23:38:28.750Z,1527809908.750 [Rowe_600LCM] No Fault, FailCount= 3
2018-05-31T23:38:28.897Z,1527809908.897 [Rowe_600LCM](INFO): Initializing
2018-05-31T23:38:28.897Z,1527809908.897 [Rowe_600LCM](INFO): Checking LCM
2018-05-31T23:38:28.898Z,1527809908.898 [Rowe_600LCM](INFO): LCM OK
2018-05-31T23:38:28.898Z,1527809908.898 [Rowe_600LCM](INFO): Powering up
2018-05-31T23:38:33.023Z,1527809913.023 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-05-31T23:38:33.160Z,1527809913.160 [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 9600 >& /dev/null &
2018-05-31T23:38:33.161Z,1527809913.161 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-05-31T23:38:33.162Z,1527809913.162 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-05-31T23:38:33.163Z,1527809913.163 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-05-31T23:38:51.148Z,1527809931.148 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:38:51.942Z,1527809931.942 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:38:56.720Z,1527809936.720 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T23:38:56.720Z,1527809936.720 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 36
2018-05-31T23:38:56.720Z,1527809936.720 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T23:38:56.735Z,1527809936.735 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T23:38:57.128Z,1527809937.128 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T23:38:57.128Z,1527809937.128 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 36
2018-05-31T23:38:57.524Z,1527809937.524 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T23:38:57.526Z,1527809937.526 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T23:38:57.526Z,1527809937.526 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T23:38:59.075Z,1527809939.075 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-05-31T23:38:59.075Z,1527809939.075 [NAL9602] Data Fault, FailCount= 5
2018-05-31T23:38:59.075Z,1527809939.075 [NAL9602](ERROR): Data Fault
2018-05-31T23:38:59.172Z,1527809939.172 [CBIT](ERROR): Data Fault in component: NAL9602
2018-05-31T23:38:59.172Z,1527809939.172 [CBIT](CRITICAL): Data Fault in component: NAL9602
2018-05-31T23:38:59.473Z,1527809939.473 [NAL9602](INFO): Powering down
2018-05-31T23:38:59.540Z,1527809939.540 [CBIT](INFO): Critical error at 20180531T233859
2018-05-31T23:39:02.878Z,1527809942.878 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:39:03.673Z,1527809943.673 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:40:29.689Z,1527810029.689 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-05-31T23:40:29.689Z,1527810029.689 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-05-31T23:40:29.689Z,1527810029.689 [Rowe_600LCM](ERROR): Communications Fault
2018-05-31T23:40:29.960Z,1527810029.960 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-05-31T23:40:30.093Z,1527810030.093 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-05-31T23:40:30.185Z,1527810030.185 [Rowe_600LCM](INFO): Powering down
2018-05-31T23:40:31.561Z,1527810031.561 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-05-31T23:40:31.562Z,1527810031.562 [Rowe_600LCM] No Fault, FailCount= 4
2018-05-31T23:40:31.801Z,1527810031.801 [Rowe_600LCM](INFO): Initializing
2018-05-31T23:40:31.802Z,1527810031.802 [Rowe_600LCM](INFO): Checking LCM
2018-05-31T23:40:31.802Z,1527810031.802 [Rowe_600LCM](INFO): LCM OK
2018-05-31T23:40:31.802Z,1527810031.802 [Rowe_600LCM](INFO): Powering up
2018-05-31T23:40:31.928Z,1527810031.928 [BPC1](ERROR): Failed to receive data from both battery packs
2018-05-31T23:40:31.928Z,1527810031.928 [BPC1] Communications Fault, FailCount= 4
2018-05-31T23:40:31.928Z,1527810031.928 [BPC1](ERROR): Communications Fault
2018-05-31T23:40:32.007Z,1527810032.007 [CBIT](ERROR): Communications Fault in component: BPC1
2018-05-31T23:40:33.175Z,1527810033.175 [CBIT](INFO): Clearing failed state for component BPC1
2018-05-31T23:40:33.175Z,1527810033.175 [BPC1] No Fault, FailCount= 4
2018-05-31T23:40:36.030Z,1527810036.030 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-05-31T23:40:36.112Z,1527810036.112 [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 9600 >& /dev/null &
2018-05-31T23:40:36.113Z,1527810036.113 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-05-31T23:40:36.114Z,1527810036.114 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-05-31T23:40:36.115Z,1527810036.115 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-05-31T23:40:59.186Z,1527810059.186 [CBIT](INFO): Clearing failed state for component NAL9602
2018-05-31T23:40:59.186Z,1527810059.186 [NAL9602] No Fault, FailCount= 5
2018-05-31T23:40:59.498Z,1527810059.498 [NAL9602](INFO): Powering up NAL9602
2018-05-31T23:41:10.698Z,1527810070.698 [NAL9602](INFO): NAL9602 initialized
2018-05-31T23:41:34.110Z,1527810094.110 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:41:34.905Z,1527810094.905 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:41:57.538Z,1527810117.538 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T23:41:57.538Z,1527810117.538 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 37
2018-05-31T23:41:57.541Z,1527810117.541 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T23:41:57.567Z,1527810117.567 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T23:41:57.880Z,1527810117.880 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T23:41:57.880Z,1527810117.880 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 37
2018-05-31T23:41:58.277Z,1527810118.277 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T23:41:58.278Z,1527810118.278 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T23:41:58.278Z,1527810118.278 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T23:42:19.922Z,1527810139.922 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-05-31T23:37:19.6Z
2018-05-31T23:42:19.922Z,1527810139.922 [Default:CheckIn:Read_GPS] Stopped
2018-05-31T23:42:19.922Z,1527810139.922 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-31T23:42:24.608Z,1527810144.608 [DataOverHttps](INFO): Sending 70 bytes from file Logs/20180531T215006/Courier0034.lzma
2018-05-31T23:42:25.406Z,1527810145.406 [DataOverHttps](INFO): Moved sent file to Logs/20180531T215006/Courier0034.lzma.bak
2018-05-31T23:42:25.407Z,1527810145.407 [DataOverHttps](INFO): SBD MOMSN=8301014
2018-05-31T23:42:32.090Z,1527810152.090 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-05-31T23:42:32.090Z,1527810152.090 [Rowe_600LCM] Communications Fault, FailCount= 5
2018-05-31T23:42:32.090Z,1527810152.090 [Rowe_600LCM](ERROR): Communications Fault
2018-05-31T23:42:32.317Z,1527810152.317 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-05-31T23:42:32.317Z,1527810152.317 [CBIT](FAULT): Communications Fault in component: Rowe_600LCM
2018-05-31T23:42:32.494Z,1527810152.494 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-05-31T23:42:32.591Z,1527810152.591 [Rowe_600LCM](INFO): Powering down
2018-05-31T23:42:37.000Z,1527810157.000 [DataOverHttps](INFO): Sending 256 bytes from file Logs/20180531T215006/Express0035.lzma
2018-05-31T23:42:37.801Z,1527810157.801 [DataOverHttps](INFO): Moved sent file to Logs/20180531T215006/Express0035.lzma.bak
2018-05-31T23:42:37.802Z,1527810157.802 [DataOverHttps](INFO): SBD MOMSN=8301017
2018-05-31T23:42:38.977Z,1527810158.977 [Default:CheckIn:Read_Iridium] Stopped
2018-05-31T23:42:38.977Z,1527810158.977 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-31T23:42:38.977Z,1527810158.977 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-31T23:44:58.292Z,1527810298.292 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T23:44:58.292Z,1527810298.292 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 38
2018-05-31T23:44:58.292Z,1527810298.292 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T23:44:58.306Z,1527810298.306 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T23:44:58.701Z,1527810298.701 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T23:44:58.705Z,1527810298.705 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 38
2018-05-31T23:44:59.075Z,1527810299.075 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T23:44:59.075Z,1527810299.075 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T23:44:59.076Z,1527810299.076 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T23:46:33.887Z,1527810393.887 [BPC1](ERROR): Failed to receive data from both battery packs
2018-05-31T23:46:33.887Z,1527810393.887 [BPC1] Communications Fault, FailCount= 5
2018-05-31T23:46:33.887Z,1527810393.887 [BPC1](ERROR): Communications Fault
2018-05-31T23:46:33.911Z,1527810393.911 [CBIT](ERROR): Communications Fault in component: BPC1
2018-05-31T23:46:35.095Z,1527810395.095 [CBIT](INFO): Clearing failed state for component BPC1
2018-05-31T23:46:35.096Z,1527810395.096 [BPC1] No Fault, FailCount= 5
2018-05-31T23:47:39.097Z,1527810459.097 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-31T23:47:39.098Z,1527810459.098 [Default:CheckIn:C.Wait] Stopped
2018-05-31T23:47:39.098Z,1527810459.098 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-31T23:47:39.098Z,1527810459.098 [Default:CheckIn:D] Running Loop=1
2018-05-31T23:47:39.490Z,1527810459.490 [Default:CheckIn:D] Stopped
2018-05-31T23:47:39.490Z,1527810459.490 [Default:CheckIn:E] Running Loop=1
2018-05-31T23:47:39.902Z,1527810459.902 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 113.830257 min 
2018-05-31T23:47:39.903Z,1527810459.903 [Default:CheckIn:E] Stopped
2018-05-31T23:47:39.903Z,1527810459.903 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-31T23:47:39.903Z,1527810459.903 [Default:CheckIn] Stopped
2018-05-31T23:47:39.903Z,1527810459.903 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-31T23:47:39.903Z,1527810459.903 [Default:CheckIn](INFO): Running loop #12
2018-05-31T23:47:39.903Z,1527810459.903 [Default:CheckIn] Running Loop=12
2018-05-31T23:47:39.904Z,1527810459.904 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-31T23:47:39.904Z,1527810459.904 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-31T23:47:59.109Z,1527810479.109 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T23:47:59.109Z,1527810479.109 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 39
2018-05-31T23:47:59.110Z,1527810479.110 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T23:47:59.143Z,1527810479.143 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T23:47:59.524Z,1527810479.524 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T23:47:59.524Z,1527810479.524 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 39
2018-05-31T23:47:59.912Z,1527810479.912 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T23:47:59.917Z,1527810479.917 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T23:47:59.917Z,1527810479.917 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T23:50:59.972Z,1527810659.972 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T23:50:59.973Z,1527810659.973 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 40
2018-05-31T23:50:59.973Z,1527810659.973 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T23:50:59.987Z,1527810659.987 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T23:51:00.379Z,1527810660.379 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T23:51:00.379Z,1527810660.379 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 40
2018-05-31T23:51:00.766Z,1527810660.766 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T23:51:00.766Z,1527810660.766 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T23:51:00.766Z,1527810660.766 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T23:51:11.514Z,1527810671.514 [NAL9602](FAULT): GPS failed to acquire within timeout.
2018-05-31T23:51:11.514Z,1527810671.514 [NAL9602] Data Fault, FailCount= 1
2018-05-31T23:51:11.514Z,1527810671.514 [NAL9602](ERROR): Data Fault
2018-05-31T23:51:11.589Z,1527810671.589 [CBIT](ERROR): Data Fault in component: NAL9602
2018-05-31T23:51:11.914Z,1527810671.914 [NAL9602](INFO): Powering down
2018-05-31T23:51:12.768Z,1527810672.768 [CBIT](INFO): Clearing failed state for component NAL9602
2018-05-31T23:51:12.768Z,1527810672.768 [NAL9602] No Fault, FailCount= 1
2018-05-31T23:51:43.828Z,1527810703.828 [NAL9602](INFO): Powering up NAL9602
2018-05-31T23:51:54.719Z,1527810714.719 [NAL9602](INFO): NAL9602 initialized
2018-05-31T23:52:32.386Z,1527810752.386 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-05-31T23:52:32.386Z,1527810752.386 [Rowe_600LCM] No Fault, FailCount= 5
2018-05-31T23:52:32.433Z,1527810752.433 [Rowe_600LCM](INFO): Initializing
2018-05-31T23:52:32.434Z,1527810752.434 [Rowe_600LCM](INFO): Checking LCM
2018-05-31T23:52:32.434Z,1527810752.434 [Rowe_600LCM](INFO): LCM OK
2018-05-31T23:52:32.434Z,1527810752.434 [Rowe_600LCM](INFO): Powering up
2018-05-31T23:52:36.550Z,1527810756.550 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-05-31T23:52:36.613Z,1527810756.613 [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 9600 >& /dev/null &
2018-05-31T23:52:36.614Z,1527810756.614 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-05-31T23:52:36.615Z,1527810756.615 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-05-31T23:52:36.616Z,1527810756.616 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-05-31T23:52:39.969Z,1527810759.969 [Default:CheckIn:Read_GPS](INFO): Timed out from 2018-05-31T23:47:39.9Z
2018-05-31T23:52:39.969Z,1527810759.969 [Default:CheckIn:Read_GPS] Stopped
2018-05-31T23:52:39.969Z,1527810759.969 [Default:CheckIn:Read_Iridium] Running Loop=1
2018-05-31T23:52:44.094Z,1527810764.094 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20180531T215006/Courier0037.lzma
2018-05-31T23:52:44.592Z,1527810764.592 [DataOverHttps](INFO): Moved sent file to Logs/20180531T215006/Courier0037.lzma.bak
2018-05-31T23:52:44.593Z,1527810764.593 [DataOverHttps](INFO): SBD MOMSN=8301036
2018-05-31T23:52:54.784Z,1527810774.784 [DataOverHttps](INFO): Sending 251 bytes from file Logs/20180531T215006/Express0038.lzma
2018-05-31T23:52:55.584Z,1527810775.584 [DataOverHttps](INFO): Moved sent file to Logs/20180531T215006/Express0038.lzma.bak
2018-05-31T23:52:55.584Z,1527810775.584 [DataOverHttps](INFO): SBD MOMSN=8301038
2018-05-31T23:52:56.831Z,1527810776.831 [Default:CheckIn:Read_Iridium] Stopped
2018-05-31T23:52:56.831Z,1527810776.831 [Default:CheckIn:C.Wait] Running Loop=1
2018-05-31T23:52:56.831Z,1527810776.831 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2018-05-31T23:53:40.087Z,1527810820.087 [BPC1](ERROR): Failed to receive data from both battery packs
2018-05-31T23:53:40.087Z,1527810820.087 [BPC1] Communications Fault, FailCount= 6
2018-05-31T23:53:40.088Z,1527810820.088 [BPC1](ERROR): Communications Fault
2018-05-31T23:53:40.143Z,1527810820.143 [CBIT](ERROR): Communications Fault in component: BPC1
2018-05-31T23:53:41.324Z,1527810821.324 [CBIT](INFO): Clearing failed state for component BPC1
2018-05-31T23:53:41.329Z,1527810821.329 [BPC1] No Fault, FailCount= 6
2018-05-31T23:54:01.403Z,1527810841.403 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T23:54:01.403Z,1527810841.403 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 41
2018-05-31T23:54:01.403Z,1527810841.403 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T23:54:01.416Z,1527810841.416 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T23:54:01.552Z,1527810841.552 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T23:54:01.552Z,1527810841.552 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 41
2018-05-31T23:54:01.954Z,1527810841.954 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T23:54:01.954Z,1527810841.954 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T23:54:01.954Z,1527810841.954 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T23:54:16.286Z,1527810856.286 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:54:17.082Z,1527810857.082 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:54:32.981Z,1527810872.981 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-05-31T23:54:32.981Z,1527810872.981 [Rowe_600LCM] Communications Fault, FailCount= 1
2018-05-31T23:54:32.981Z,1527810872.981 [Rowe_600LCM](ERROR): Communications Fault
2018-05-31T23:54:33.139Z,1527810873.139 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-05-31T23:54:33.385Z,1527810873.385 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-05-31T23:54:33.532Z,1527810873.532 [Rowe_600LCM](INFO): Powering down
2018-05-31T23:54:35.136Z,1527810875.136 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-05-31T23:54:35.137Z,1527810875.137 [Rowe_600LCM] No Fault, FailCount= 1
2018-05-31T23:54:35.157Z,1527810875.157 [Rowe_600LCM](INFO): Initializing
2018-05-31T23:54:35.158Z,1527810875.158 [Rowe_600LCM](INFO): Checking LCM
2018-05-31T23:54:35.158Z,1527810875.158 [Rowe_600LCM](INFO): LCM OK
2018-05-31T23:54:35.158Z,1527810875.158 [Rowe_600LCM](INFO): Powering up
2018-05-31T23:54:39.286Z,1527810879.286 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-05-31T23:54:39.344Z,1527810879.344 [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 9600 >& /dev/null &
2018-05-31T23:54:39.346Z,1527810879.346 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-05-31T23:54:39.346Z,1527810879.346 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-05-31T23:54:39.347Z,1527810879.347 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-05-31T23:54:55.263Z,1527810895.263 [BPC1](ERROR): Failed to parse Bank B battery data
2018-05-31T23:56:35.392Z,1527810995.392 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-05-31T23:56:35.392Z,1527810995.392 [Rowe_600LCM] Communications Fault, FailCount= 2
2018-05-31T23:56:35.393Z,1527810995.393 [Rowe_600LCM](ERROR): Communications Fault
2018-05-31T23:56:35.460Z,1527810995.460 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-05-31T23:56:35.797Z,1527810995.797 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-05-31T23:56:35.931Z,1527810995.931 [Rowe_600LCM](INFO): Powering down
2018-05-31T23:56:37.370Z,1527810997.370 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-05-31T23:56:37.370Z,1527810997.370 [Rowe_600LCM] No Fault, FailCount= 2
2018-05-31T23:56:37.545Z,1527810997.545 [Rowe_600LCM](INFO): Initializing
2018-05-31T23:56:37.546Z,1527810997.546 [Rowe_600LCM](INFO): Checking LCM
2018-05-31T23:56:37.546Z,1527810997.546 [Rowe_600LCM](INFO): LCM OK
2018-05-31T23:56:37.546Z,1527810997.546 [Rowe_600LCM](INFO): Powering up
2018-05-31T23:56:41.670Z,1527811001.670 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-05-31T23:56:41.824Z,1527811001.824 [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 9600 >& /dev/null &
2018-05-31T23:56:41.825Z,1527811001.825 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-05-31T23:56:41.826Z,1527811001.826 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-05-31T23:56:41.827Z,1527811001.827 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-05-31T23:57:02.174Z,1527811022.174 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-05-31T23:57:02.174Z,1527811022.174 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 42
2018-05-31T23:57:02.174Z,1527811022.174 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-05-31T23:57:02.200Z,1527811022.200 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-05-31T23:57:02.590Z,1527811022.590 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-05-31T23:57:02.590Z,1527811022.590 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 42
2018-05-31T23:57:02.968Z,1527811022.968 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-05-31T23:57:02.969Z,1527811022.969 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-05-31T23:57:02.969Z,1527811022.969 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-05-31T23:57:57.423Z,1527811077.423 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2018-05-31T23:57:57.423Z,1527811077.423 [Default:CheckIn:C.Wait] Stopped
2018-05-31T23:57:57.424Z,1527811077.424 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2018-05-31T23:57:57.424Z,1527811077.424 [Default:CheckIn:D] Running Loop=1
2018-05-31T23:57:57.759Z,1527811077.759 [Default:CheckIn:D] Stopped
2018-05-31T23:57:57.759Z,1527811077.759 [Default:CheckIn:E] Running Loop=1
2018-05-31T23:57:58.186Z,1527811078.186 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 124.134741 min 
2018-05-31T23:57:58.187Z,1527811078.187 [Default:CheckIn:E] Stopped
2018-05-31T23:57:58.187Z,1527811078.187 [Default:CheckIn](INFO): Completed Default:CheckIn
2018-05-31T23:57:58.187Z,1527811078.187 [Default:CheckIn] Stopped
2018-05-31T23:57:58.187Z,1527811078.187 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-05-31T23:57:58.187Z,1527811078.187 [Default:CheckIn](INFO): Running loop #13
2018-05-31T23:57:58.187Z,1527811078.187 [Default:CheckIn] Running Loop=13
2018-05-31T23:57:58.188Z,1527811078.188 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2018-05-31T23:57:58.188Z,1527811078.188 [Default:CheckIn:Read_GPS] Running Loop=1
2018-05-31T23:58:37.572Z,1527811117.572 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-05-31T23:58:37.572Z,1527811117.572 [Rowe_600LCM] Communications Fault, FailCount= 3
2018-05-31T23:58:37.572Z,1527811117.572 [Rowe_600LCM](ERROR): Communications Fault
2018-05-31T23:58:37.642Z,1527811117.642 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-05-31T23:58:37.977Z,1527811117.977 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-05-31T23:58:38.352Z,1527811118.352 [Rowe_600LCM](INFO): Powering down
2018-05-31T23:58:39.806Z,1527811119.806 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-05-31T23:58:39.806Z,1527811119.806 [Rowe_600LCM] No Fault, FailCount= 3
2018-05-31T23:58:39.969Z,1527811119.969 [Rowe_600LCM](INFO): Initializing
2018-05-31T23:58:39.970Z,1527811119.970 [Rowe_600LCM](INFO): Checking LCM
2018-05-31T23:58:39.970Z,1527811119.970 [Rowe_600LCM](INFO): LCM OK
2018-05-31T23:58:39.970Z,1527811119.970 [Rowe_600LCM](INFO): Powering up
2018-05-31T23:58:44.499Z,1527811124.499 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-05-31T23:58:44.746Z,1527811124.746 [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 9600 >& /dev/null &
2018-05-31T23:58:44.747Z,1527811124.747 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-05-31T23:58:44.748Z,1527811124.748 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-05-31T23:58:44.748Z,1527811124.748 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-05-31T23:59:14.734Z,1527811154.734 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-05-31T23:59:15.530Z,1527811155.530 [Rowe_600LCM](DEBUG): LCB error: Software Overcurrent.
2018-06-01T00:00:04.095Z,1527811204.095 [DeadReckonUsingSpeedCalculator](ERROR): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2018-06-01T00:00:04.095Z,1527811204.095 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 43
2018-06-01T00:00:04.095Z,1527811204.095 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2018-06-01T00:00:04.218Z,1527811204.218 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2018-06-01T00:00:04.383Z,1527811204.383 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2018-06-01T00:00:04.383Z,1527811204.383 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 43
2018-06-01T00:00:04.691Z,1527811204.691 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2018-06-01T00:00:04.692Z,1527811204.692 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2018-06-01T00:00:04.692Z,1527811204.692 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2018-06-01T00:00:40.057Z,1527811240.057 [Rowe_600LCM](FAULT): Did not receive valid device response within the specified allowable sample time.
2018-06-01T00:00:40.057Z,1527811240.057 [Rowe_600LCM] Communications Fault, FailCount= 4
2018-06-01T00:00:40.057Z,1527811240.057 [Rowe_600LCM](ERROR): Communications Fault
2018-06-01T00:00:40.329Z,1527811240.329 [CBIT](ERROR): Communications Fault in component: Rowe_600LCM
2018-06-01T00:00:40.461Z,1527811240.461 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-01T00:00:40.951Z,1527811240.951 [Rowe_600LCM](INFO): Powering down
2018-06-01T00:00:42.360Z,1527811242.360 [CBIT](INFO): Clearing failed state for component Rowe_600LCM
2018-06-01T00:00:42.360Z,1527811242.360 [Rowe_600LCM] No Fault, FailCount= 4
2018-06-01T00:00:42.566Z,1527811242.566 [Rowe_600LCM](INFO): Initializing
2018-06-01T00:00:42.566Z,1527811242.566 [Rowe_600LCM](INFO): Checking LCM
2018-06-01T00:00:42.567Z,1527811242.567 [Rowe_600LCM](INFO): LCM OK
2018-06-01T00:00:42.567Z,1527811242.567 [Rowe_600LCM](INFO): Powering up
2018-06-01T00:00:45.639Z,1527811245.639 [CommandLine](IMPORTANT): got command restart application
2018-06-01T00:00:46.651Z,1527811246.651 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2018-06-01T00:00:46.651Z,1527811246.651 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-06-01T00:00:46.685Z,1527811246.685 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2018-06-01T00:00:46.685Z,1527811246.685 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-06-01T00:00:46.686Z,1527811246.686 [CommandLine](INFO): Join timeout helper Thread ID is 5599
2018-06-01T00:00:46.721Z,1527811246.721 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2018-06-01T00:00:46.721Z,1527811246.721 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-06-01T00:00:46.722Z,1527811246.722 [NavChartDb](INFO): Join timeout helper Thread ID is 5600
2018-06-01T00:00:46.852Z,1527811246.852 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2018-06-01T00:00:46.910Z,1527811246.910 [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 9600 >& /dev/null &
2018-06-01T00:00:46.911Z,1527811246.911 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2018-06-01T00:00:46.912Z,1527811246.912 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2018-06-01T00:00:46.913Z,1527811246.913 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2018-06-01T00:00:47.045Z,1527811247.045 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2018-06-01T00:00:47.046Z,1527811247.046 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-06-01T00:00:47.058Z,1527811247.058 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2018-06-01T00:00:47.058Z,1527811247.058 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-06-01T00:00:47.059Z,1527811247.059 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5603
2018-06-01T00:00:47.202Z,1527811247.202 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2018-06-01T00:00:47.203Z,1527811247.203 [WetLabsBB2FL](INFO): Powering down
2018-06-01T00:00:47.204Z,1527811247.204 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-06-01T00:00:47.213Z,1527811247.213 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2018-06-01T00:00:47.213Z,1527811247.213 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-06-01T00:00:47.214Z,1527811247.214 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5605
2018-06-01T00:00:47.258Z,1527811247.258 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2018-06-01T00:00:47.451Z,1527811247.451 [CTD_Seabird](INFO): Stopping potential previous instance(s) of CTD_Seabird LCM interface
2018-06-01T00:00:47.451Z,1527811247.451 [CTD_Seabird](INFO): Powering down
2018-06-01T00:00:47.453Z,1527811247.453 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-06-01T00:00:47.458Z,1527811247.458 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2018-06-01T00:00:47.458Z,1527811247.458 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-06-01T00:00:47.459Z,1527811247.459 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 5607
2018-06-01T00:00:47.725Z,1527811247.725 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2018-06-01T00:00:47.726Z,1527811247.726 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2018-06-01T00:00:47.907Z,1527811247.907 [Rowe_600LCM](INFO): Powering down
2018-06-01T00:00:47.908Z,1527811247.908 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-06-01T00:00:47.922Z,1527811247.922 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2018-06-01T00:00:47.922Z,1527811247.922 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-06-01T00:00:47.923Z,1527811247.923 [Radio_Surface](INFO): Join timeout helper Thread ID is 5609
2018-06-01T00:00:48.061Z,1527811248.061 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2018-06-01T00:00:48.062Z,1527811248.062 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-06-01T00:00:48.074Z,1527811248.074 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2018-06-01T00:00:48.074Z,1527811248.074 [logger ThreadHandler](INFO): Thread cancelled.
2018-06-01T00:00:48.074Z,1527811248.074 [logger](INFO): Join timeout helper Thread ID is 5610
2018-06-01T00:00:48.077Z,1527811248.077 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2018-06-01T00:00:48.077Z,1527811248.077 [logger ThreadHandler](INFO): Thread cancelled.
2018-06-01T00:00:48.094Z,1527811248.094 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2018-06-01T00:00:48.094Z,1527811248.094 [CommandLine ThreadHandler](INFO): Thread cancelled.
2018-06-01T00:00:48.094Z,1527811248.094 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2018-06-01T00:00:48.094Z,1527811248.094 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-06-01T00:00:48.094Z,1527811248.094 [controlThread](INFO): Join timeout helper Thread ID is 5611
2018-06-01T00:00:48.221Z,1527811248.221 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2018-06-01T00:00:48.221Z,1527811248.221 [controlThread](DEBUG): Uninitializing ControlThread
2018-06-01T00:00:48.222Z,1527811248.222 [AcousticModem_Benthos_ATM900](INFO): Powering down
2018-06-01T00:00:48.342Z,1527811248.342 [NAL9602](INFO): Powering down
2018-06-01T00:00:48.343Z,1527811248.343 [PNI_TCM](INFO): Powering down
2018-06-01T00:00:48.426Z,1527811248.426 [Aanderaa_O2](INFO): Powering down
2018-06-01T00:00:48.429Z,1527811248.429 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2018-06-01T00:00:48.430Z,1527811248.430 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2018-06-01T00:00:48.431Z,1527811248.431 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2018-06-01T00:00:48.431Z,1527811248.431 [MissionManager](INFO): Uninitializing Mission Default
2018-06-01T00:00:48.431Z,1527811248.431 [Default] Stopped
2018-06-01T00:00:48.432Z,1527811248.432 [Default](DEBUG): Aggregate::uninitialize Default
2018-06-01T00:00:48.432Z,1527811248.432 [Default:B.GoToSurface] Stopped
2018-06-01T00:00:48.432Z,1527811248.432 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2018-06-01T00:00:48.432Z,1527811248.432 [Default:CheckIn] Stopped
2018-06-01T00:00:48.432Z,1527811248.432 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2018-06-01T00:00:48.432Z,1527811248.432 [Default:CheckIn:Read_GPS] Stopped
2018-06-01T00:00:48.436Z,1527811248.436 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2018-06-01T00:00:48.437Z,1527811248.437 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2018-06-01T00:00:48.437Z,1527811248.437 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2018-06-01T00:00:48.438Z,1527811248.438 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2018-06-01T00:00:48.438Z,1527811248.438 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2018-06-01T00:00:48.438Z,1527811248.438 [BuoyancyServo](INFO): Powering down
2018-06-01T00:00:48.453Z,1527811248.453 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2018-06-01T00:00:48.453Z,1527811248.453 [ElevatorServo](INFO): Powering down
2018-06-01T00:00:48.454Z,1527811248.454 [MassServo](DEBUG): Uninitialize Mass Servo.
2018-06-01T00:00:48.454Z,1527811248.454 [MassServo](INFO): Powering down
2018-06-01T00:00:48.455Z,1527811248.455 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2018-06-01T00:00:48.455Z,1527811248.455 [RudderServo](INFO): Powering down
2018-06-01T00:00:48.456Z,1527811248.456 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2018-06-01T00:00:48.456Z,1527811248.456 [ThrusterServo](INFO): Powering down
2018-06-01T00:00:48.458Z,1527811248.458 [SBIT](DEBUG): Uninitialize SBIT Component.
2018-06-01T00:00:48.458Z,1527811248.458 [IBIT](DEBUG): Uninitialize IBIT Component.
2018-06-01T00:00:48.458Z,1527811248.458 [CBIT](DEBUG): Uninitialize CBIT Component.
2018-06-01T00:00:48.459Z,1527811248.459 [controlThread ThreadHandler](INFO): Thread cancelled.
2018-06-01T00:00:48.567Z,1527811248.567 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2018-06-01T00:00:48.573Z,1527811248.573 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2018-06-01T00:00:48.690Z,1527811248.690 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2018-06-01T00:00:48.701Z,1527811248.701 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2018-06-01T00:00:49.143Z,1527811249.143 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2018-06-01T00:00:49.371Z,1527811249.371 [logger ThreadHandler](INFO): Thread cancelled.