2019-04-09T01:16:57.039Z,1554772617.039 [Supervisor](DEBUG): Initializing supervisor.
2019-04-09T01:16:57.043Z,1554772617.043 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-04-09T01:16:57.043Z,1554772617.043 [SyncHandler](INFO): Protected caller Thread ID is 5491
2019-04-09T01:16:57.044Z,1554772617.044 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-04-09T01:16:57.045Z,1554772617.045 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-04-09T01:16:57.045Z,1554772617.045 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5492
2019-04-09T01:16:57.048Z,1554772617.048 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-04-09T01:16:57.061Z,1554772617.061 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-04-09T01:16:57.063Z,1554772617.063 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-04-09T01:16:57.063Z,1554772617.063 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5493
2019-04-09T01:16:57.064Z,1554772617.064 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-04-09T01:16:57.065Z,1554772617.065 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-04-09T01:16:57.065Z,1554772617.065 [logger ThreadHandler](INFO): Protected caller Thread ID is 5494
2019-04-09T01:16:57.067Z,1554772617.067 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-04-09T01:16:57.068Z,1554772617.068 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-04-09T01:16:57.069Z,1554772617.069 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-04-09T01:16:57.164Z,1554772617.164 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-04-09T01:16:57.164Z,1554772617.164 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-04-09T01:16:57.366Z,1554772617.366 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-04-09T01:16:57.366Z,1554772617.366 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-04-09T01:16:57.503Z,1554772617.503 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-04-09T01:16:57.504Z,1554772617.504 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-04-09T01:16:57.965Z,1554772617.965 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-04-09T01:16:57.966Z,1554772617.966 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-04-09T01:16:58.410Z,1554772618.410 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-04-09T01:16:58.411Z,1554772618.411 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-04-09T01:16:58.882Z,1554772618.882 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-04-09T01:16:58.883Z,1554772618.883 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-04-09T01:16:59.190Z,1554772619.190 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-04-09T01:16:59.191Z,1554772619.191 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-04-09T01:16:59.525Z,1554772619.525 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-04-09T01:16:59.525Z,1554772619.525 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-04-09T01:16:59.923Z,1554772619.923 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-04-09T01:16:59.924Z,1554772619.924 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-04-09T01:17:00.129Z,1554772620.129 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-04-09T01:17:00.130Z,1554772620.130 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-04-09T01:17:00.234Z,1554772620.234 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-04-09T01:17:00.235Z,1554772620.235 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-04-09T01:17:00.315Z,1554772620.315 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-04-09T01:17:00.416Z,1554772620.416 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-04-09T01:17:00.417Z,1554772620.417 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-04-09T01:17:00.612Z,1554772620.612 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-04-09T01:17:00.612Z,1554772620.612 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-04-09T01:17:00.821Z,1554772620.821 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-04-09T01:17:00.823Z,1554772620.823 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2019-04-09T01:17:00.823Z,1554772620.823 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2019-04-09T01:17:00.905Z,1554772620.905 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2019-04-09T01:17:01.139Z,1554772621.139 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-04-09T01:17:01.139Z,1554772621.139 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2019-04-09T01:17:01.234Z,1554772621.234 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2019-04-09T01:17:01.408Z,1554772621.408 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2019-04-09T01:17:01.635Z,1554772621.635 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2019-04-09T01:17:01.809Z,1554772621.809 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2019-04-09T01:17:01.934Z,1554772621.934 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2019-04-09T01:17:02.053Z,1554772622.053 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2019-04-09T01:17:02.252Z,1554772622.252 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2019-04-09T01:17:02.519Z,1554772622.519 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2019-04-09T01:17:02.755Z,1554772622.755 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2019-04-09T01:17:02.755Z,1554772622.755 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-04-09T01:17:02.759Z,1554772622.759 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-04-09T01:17:02.861Z,1554772622.861 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-04-09T01:17:02.861Z,1554772622.861 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-04-09T01:17:02.989Z,1554772622.989 [BuoyancyServo] Loaded
2019-04-09T01:17:02.990Z,1554772622.990 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-04-09T01:17:03.005Z,1554772623.005 [ElevatorServo] Loaded
2019-04-09T01:17:03.006Z,1554772623.006 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-04-09T01:17:03.021Z,1554772623.021 [MassServo] Loaded
2019-04-09T01:17:03.022Z,1554772623.022 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-04-09T01:17:03.037Z,1554772623.037 [RudderServo] Loaded
2019-04-09T01:17:03.037Z,1554772623.037 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-04-09T01:17:03.053Z,1554772623.053 [ThrusterServo] Loaded
2019-04-09T01:17:03.053Z,1554772623.053 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-04-09T01:17:03.054Z,1554772623.054 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-04-09T01:17:03.054Z,1554772623.054 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-04-09T01:17:03.193Z,1554772623.193 [SBIT](DEBUG): Construct Startup Built In Test.
2019-04-09T01:17:03.223Z,1554772623.223 [SBIT] Loaded
2019-04-09T01:17:03.223Z,1554772623.223 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-04-09T01:17:03.224Z,1554772623.224 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-04-09T01:17:03.253Z,1554772623.253 [IBIT] Loaded
2019-04-09T01:17:03.253Z,1554772623.253 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-04-09T01:17:03.256Z,1554772623.256 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-04-09T01:17:03.658Z,1554772623.658 [CBIT] Loaded
2019-04-09T01:17:03.658Z,1554772623.658 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-04-09T01:17:03.659Z,1554772623.659 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-04-09T01:17:03.659Z,1554772623.659 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-04-09T01:17:03.846Z,1554772623.846 [Aanderaa_O2] Loaded
2019-04-09T01:17:03.847Z,1554772623.847 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-04-09T01:17:03.857Z,1554772623.857 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-04-09T01:17:03.862Z,1554772623.862 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-04-09T01:17:03.864Z,1554772623.864 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-04-09T01:17:03.869Z,1554772623.869 [CTD_Seabird](INFO): created writer for : depth
2019-04-09T01:17:03.869Z,1554772623.869 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-04-09T01:17:03.875Z,1554772623.875 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-04-09T01:17:03.875Z,1554772623.875 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-04-09T01:17:03.881Z,1554772623.881 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-04-09T01:17:03.881Z,1554772623.881 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-04-09T01:17:03.887Z,1554772623.887 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-04-09T01:17:03.888Z,1554772623.888 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-04-09T01:17:03.893Z,1554772623.893 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-04-09T01:17:03.894Z,1554772623.894 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-04-09T01:17:03.899Z,1554772623.899 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-04-09T01:17:03.927Z,1554772623.927 [CTD_Seabird] Loaded
2019-04-09T01:17:03.927Z,1554772623.927 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-04-09T01:17:03.928Z,1554772623.928 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406654E0
2019-04-09T01:17:03.928Z,1554772623.928 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 5573
2019-04-09T01:17:03.955Z,1554772623.955 [ESPComponent] Loaded
2019-04-09T01:17:03.955Z,1554772623.955 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-04-09T01:17:03.970Z,1554772623.970 [PAR_Licor] Loaded
2019-04-09T01:17:03.970Z,1554772623.970 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-04-09T01:17:04.020Z,1554772624.020 [WetLabsBB2FL] Loaded
2019-04-09T01:17:04.020Z,1554772624.020 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-04-09T01:17:04.021Z,1554772624.021 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406954E0
2019-04-09T01:17:04.022Z,1554772624.022 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5574
2019-04-09T01:17:04.022Z,1554772624.022 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-04-09T01:17:04.023Z,1554772624.023 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-04-09T01:17:04.065Z,1554772624.065 [DepthRateCalculator] Loaded
2019-04-09T01:17:04.065Z,1554772624.065 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-04-09T01:17:04.071Z,1554772624.071 [PitchRateCalculator] Loaded
2019-04-09T01:17:04.071Z,1554772624.071 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-04-09T01:17:04.088Z,1554772624.088 [SpeedCalculator] Loaded
2019-04-09T01:17:04.088Z,1554772624.088 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-04-09T01:17:04.111Z,1554772624.111 [TempGradientCalculator] Loaded
2019-04-09T01:17:04.111Z,1554772624.111 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-04-09T01:17:04.117Z,1554772624.117 [YawRateCalculator] Loaded
2019-04-09T01:17:04.117Z,1554772624.117 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-04-09T01:17:04.152Z,1554772624.152 [ElevatorOffsetCalculator] Loaded
2019-04-09T01:17:04.152Z,1554772624.152 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-04-09T01:17:04.152Z,1554772624.152 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-04-09T01:17:04.153Z,1554772624.153 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-04-09T01:17:04.180Z,1554772624.180 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-04-09T01:17:04.181Z,1554772624.181 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-04-09T01:17:04.287Z,1554772624.287 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-04-09T01:17:04.288Z,1554772624.288 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-04-09T01:17:04.349Z,1554772624.349 [DeadReckonUsingSpeedCalculator] Loaded
2019-04-09T01:17:04.350Z,1554772624.350 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-04-09T01:17:04.412Z,1554772624.412 [DeadReckonWithRespectToSeafloor] Loaded
2019-04-09T01:17:04.412Z,1554772624.412 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-04-09T01:17:04.434Z,1554772624.434 [NavChart] Loaded
2019-04-09T01:17:04.434Z,1554772624.434 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-04-09T01:17:04.438Z,1554772624.438 [UniversalFixResidualReporter] Loaded
2019-04-09T01:17:04.439Z,1554772624.439 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-04-09T01:17:04.439Z,1554772624.439 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-04-09T01:17:04.440Z,1554772624.440 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-04-09T01:17:04.758Z,1554772624.758 [AcousticModem_Benthos_ATM900] Loaded
2019-04-09T01:17:04.758Z,1554772624.758 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2019-04-09T01:17:04.836Z,1554772624.836 [DataOverHttps] Loaded
2019-04-09T01:17:04.836Z,1554772624.836 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-04-09T01:17:04.837Z,1554772624.837 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409704E0
2019-04-09T01:17:04.838Z,1554772624.838 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5575
2019-04-09T01:17:04.851Z,1554772624.851 [Depth_Keller] Loaded
2019-04-09T01:17:04.852Z,1554772624.852 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-04-09T01:17:04.947Z,1554772624.947 [NAL9602] Loaded
2019-04-09T01:17:04.948Z,1554772624.948 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-04-09T01:17:04.953Z,1554772624.953 [Onboard] Loaded
2019-04-09T01:17:04.953Z,1554772624.953 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-04-09T01:17:04.957Z,1554772624.957 [Radio_Surface] Loaded
2019-04-09T01:17:04.957Z,1554772624.957 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-04-09T01:17:04.958Z,1554772624.958 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409A04E0
2019-04-09T01:17:04.958Z,1554772624.958 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5576
2019-04-09T01:17:06.281Z,1554772626.281 [BPC1] Loaded
2019-04-09T01:17:06.281Z,1554772626.281 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-04-09T01:17:06.283Z,1554772626.283 [BR_Ping1D](INFO): constructor
2019-04-09T01:17:06.313Z,1554772626.313 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minrange
2019-04-09T01:17:06.315Z,1554772626.315 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.maxrange
2019-04-09T01:17:06.317Z,1554772626.317 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.soundspeed
2019-04-09T01:17:06.319Z,1554772626.319 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.automode
2019-04-09T01:17:06.322Z,1554772626.322 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.gainindex
2019-04-09T01:17:06.324Z,1554772626.324 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minconfidence
2019-04-09T01:17:06.326Z,1554772626.326 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.pingenable
2019-04-09T01:17:06.328Z,1554772626.328 [BR_Ping1D] Loaded
2019-04-09T01:17:06.328Z,1554772626.328 [ComponentRegistry](DEBUG): SyncComponent "BR_Ping1D" handled in the control thread.
2019-04-09T01:17:06.329Z,1554772626.329 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-04-09T01:17:06.329Z,1554772626.329 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-04-09T01:17:06.394Z,1554772626.394 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-04-09T01:17:06.395Z,1554772626.395 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-04-09T01:17:06.462Z,1554772626.462 [VerticalControl](DEBUG): Construct VerticalControl.
2019-04-09T01:17:06.550Z,1554772626.550 [VerticalControl] Loaded
2019-04-09T01:17:06.551Z,1554772626.551 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-04-09T01:17:06.551Z,1554772626.551 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-04-09T01:17:06.612Z,1554772626.612 [HorizontalControl] Loaded
2019-04-09T01:17:06.612Z,1554772626.612 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-04-09T01:17:06.613Z,1554772626.613 [SpeedControl](DEBUG): Construct SpeedControl.
2019-04-09T01:17:06.615Z,1554772626.615 [SpeedControl] Loaded
2019-04-09T01:17:06.615Z,1554772626.615 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-04-09T01:17:06.616Z,1554772626.616 [LoopControl](DEBUG): Construct LoopControl.
2019-04-09T01:17:06.616Z,1554772626.616 [LoopControl] Loaded
2019-04-09T01:17:06.617Z,1554772626.617 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-04-09T01:17:06.617Z,1554772626.617 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-04-09T01:17:06.618Z,1554772626.618 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-04-09T01:17:06.631Z,1554772626.631 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-04-09T01:17:06.632Z,1554772626.632 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-04-09T01:17:07.149Z,1554772627.149 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-04-09T01:17:07.153Z,1554772627.153 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-04-09T01:17:07.154Z,1554772627.154 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-04-09T01:17:07.160Z,1554772627.160 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-04-09T01:17:07.161Z,1554772627.161 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACD4E0
2019-04-09T01:17:07.162Z,1554772627.162 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5577
2019-04-09T01:17:07.167Z,1554772627.167 [Supervisor](INFO): Main Thread ID is 4347
2019-04-09T01:17:07.167Z,1554772627.167 [Supervisor](DEBUG): Running supervisor.
2019-04-09T01:17:07.168Z,1554772627.168 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5578
2019-04-09T01:17:07.170Z,1554772627.170 [controlThread ThreadHandler](INFO): Handler Thread ID is 5579
2019-04-09T01:17:07.170Z,1554772627.170 [controlThread](DEBUG): Initializing ControlThread
2019-04-09T01:17:07.172Z,1554772627.172 [SBIT](INFO): Initialize SBIT Component.
2019-04-09T01:17:07.173Z,1554772627.173 [SBIT](IMPORTANT): git: 2019-03-18-31-ge98d5dc
2019-04-09T01:17:07.173Z,1554772627.173 [SBIT](INFO): git hash: e98d5dc50cbc39e402f19b9946ff4dc0776c8725
2019-04-09T01:17:07.173Z,1554772627.173 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-04-09T01:17:07.175Z,1554772627.175 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2019-04-09T01:17:07.176Z,1554772627.176 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2019-04-09T01:17:07.176Z,1554772627.176 [IBIT](INFO): Initialize IBIT Component.
2019-04-09T01:17:07.177Z,1554772627.177 [CBIT](DEBUG): Initialize CBIT Component.
2019-04-09T01:17:07.178Z,1554772627.178 [logger ThreadHandler](INFO): Handler Thread ID is 5580
2019-04-09T01:17:07.190Z,1554772627.190 [CBIT](DEBUG): Initialized mux pins.
2019-04-09T01:17:07.190Z,1554772627.190 [CBIT](DEBUG): Initializing the watchdog timer.
2019-04-09T01:17:07.198Z,1554772627.198 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 5581
2019-04-09T01:17:07.199Z,1554772627.199 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T01:17:07.203Z,1554772627.203 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T01:17:07.204Z,1554772627.204 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5583
2019-04-09T01:17:07.205Z,1554772627.205 [WetLabsBB2FL](INFO): Powering down
2019-04-09T01:17:07.214Z,1554772627.214 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-04-09T01:17:07.214Z,1554772627.214 [CBIT](DEBUG): Initializing heartbeat.
2019-04-09T01:17:07.234Z,1554772627.234 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5584
2019-04-09T01:17:07.235Z,1554772627.235 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-04-09T01:17:07.244Z,1554772627.244 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5585
2019-04-09T01:17:07.262Z,1554772627.262 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5586
2019-04-09T01:17:07.265Z,1554772627.265 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-04-09T01:17:07.265Z,1554772627.265 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-04-09T01:17:07.266Z,1554772627.266 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-04-09T01:17:07.266Z,1554772627.266 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-04-09T01:17:07.266Z,1554772627.266 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-04-09T01:17:07.266Z,1554772627.266 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-04-09T01:17:07.267Z,1554772627.267 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-04-09T01:17:07.267Z,1554772627.267 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-04-09T01:17:07.267Z,1554772627.267 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-04-09T01:17:07.267Z,1554772627.267 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-04-09T01:17:07.267Z,1554772627.267 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-04-09T01:17:07.268Z,1554772627.268 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-04-09T01:17:07.268Z,1554772627.268 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-04-09T01:17:07.268Z,1554772627.268 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-04-09T01:17:07.268Z,1554772627.268 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-04-09T01:17:07.268Z,1554772627.268 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-04-09T01:17:07.286Z,1554772627.286 [CBIT](DEBUG): Deactivating GF circuits.
2019-04-09T01:17:07.286Z,1554772627.286 [CBIT](DEBUG): Deactivating emergency mode.
2019-04-09T01:17:07.322Z,1554772627.322 [CBIT](DEBUG): Backplane powered.
2019-04-09T01:17:07.326Z,1554772627.326 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-04-09T01:17:07.327Z,1554772627.327 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-04-09T01:17:07.327Z,1554772627.327 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-04-09T01:17:07.327Z,1554772627.327 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-04-09T01:17:07.328Z,1554772627.328 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-04-09T01:17:07.328Z,1554772627.328 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-04-09T01:17:07.329Z,1554772627.329 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T01:17:07.330Z,1554772627.330 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-04-09T01:17:07.331Z,1554772627.331 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-04-09T01:17:07.335Z,1554772627.335 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-04-09T01:17:07.337Z,1554772627.337 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-04-09T01:17:07.338Z,1554772627.338 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-04-09T01:17:07.339Z,1554772627.339 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-04-09T01:17:07.340Z,1554772627.340 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-04-09T01:17:07.362Z,1554772627.362 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-09T01:17:07.398Z,1554772627.398 [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>


2019-04-09T01:17:07.407Z,1554772627.407 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-04-09T01:17:07.462Z,1554772627.462 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-04-09T01:17:07.463Z,1554772627.463 [Default:A.Wait](DEBUG): Construct Wait.
2019-04-09T01:17:07.465Z,1554772627.465 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-09T01:17:07.525Z,1554772627.525 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-04-09T01:17:07.552Z,1554772627.552 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-04-09T01:17:07.578Z,1554772627.578 [Default:E.Execute](DEBUG): Construct Execute.
2019-04-09T01:17:07.582Z,1554772627.582 [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 on</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>


2019-04-09T01:17:07.597Z,1554772627.597 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AcousticModem_Benthos_ATM900,Depth_Keller,NAL9602,Onboard,BPC1,BR_Ping1D,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-04-09T01:17:07.640Z,1554772627.640 [ESPComponent](INFO): powering down ESP
2019-04-09T01:17:07.646Z,1554772627.646 [Radio_Surface](INFO): Powering up
2019-04-09T01:17:08.051Z,1554772628.051 [PAR_Licor](INFO): A/D timeout, 8 tries over 129 ms

2019-04-09T01:17:08.051Z,1554772628.051 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T01:17:08.051Z,1554772628.051 [PAR_Licor](ERROR): Data Fault
2019-04-09T01:17:08.052Z,1554772628.052 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T01:17:08.052Z,1554772628.052 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T01:17:08.226Z,1554772628.226 [BR_Ping1D](INFO): Start
2019-04-09T01:17:08.264Z,1554772628.264 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:17:08.270Z,1554772628.270 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-04-09T01:17:08.272Z,1554772628.272 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:17:08.278Z,1554772628.278 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-04-09T01:17:08.279Z,1554772628.279 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:17:08.286Z,1554772628.286 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T01:17:08.287Z,1554772628.287 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:17:08.294Z,1554772628.294 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-09T01:17:08.295Z,1554772628.295 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:17:08.302Z,1554772628.302 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-04-09T01:17:08.305Z,1554772628.305 [CommandLine](FAULT): Scheduling is paused
2019-04-09T01:17:08.305Z,1554772628.305 [CBIT](INFO): Critical error at 20190409T011706
2019-04-09T01:17:08.305Z,1554772628.305 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-04-09T01:17:08.306Z,1554772628.306 [CBIT](FAULT): Main Battery Failure. Count: 1
2019-04-09T01:17:08.310Z,1554772628.310 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T01:17:08.310Z,1554772628.310 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T01:17:08.310Z,1554772628.310 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T01:17:08.436Z,1554772628.436 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:08.858Z,1554772628.858 [PAR_Licor](INFO): A/D timeout, 8 tries over 139 ms

2019-04-09T01:17:08.859Z,1554772628.859 [PAR_Licor] Data Fault, FailCount= 2
2019-04-09T01:17:08.859Z,1554772628.859 [PAR_Licor](ERROR): Data Fault
2019-04-09T01:17:08.878Z,1554772628.878 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:08.879Z,1554772628.879 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:08.879Z,1554772628.879 [BR_Ping1D](INFO): XXXX automode1: 1
2019-04-09T01:17:08.879Z,1554772628.879 [BR_Ping1D](INFO): XXXX automode2: 1
2019-04-09T01:17:08.879Z,1554772628.879 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T01:17:08.890Z,1554772628.890 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T01:17:09.222Z,1554772629.222 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-04-09T01:17:09.222Z,1554772629.222 [RudderServo](FAULT): Rudder failed to initialize
2019-04-09T01:17:09.222Z,1554772629.222 [RudderServo] Communications Fault, FailCount= 1
2019-04-09T01:17:09.222Z,1554772629.222 [RudderServo](ERROR): Communications Fault
2019-04-09T01:17:09.333Z,1554772629.333 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-04-09T01:17:09.334Z,1554772629.334 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T01:17:09.334Z,1554772629.334 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T01:17:09.334Z,1554772629.334 [PAR_Licor] No Fault, FailCount= 2
2019-04-09T01:17:09.377Z,1554772629.377 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:09.527Z,1554772629.527 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-09T01:17:09.527Z,1554772629.527 [RudderServo](INFO): Powering down
2019-04-09T01:17:09.765Z,1554772629.765 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:09.765Z,1554772629.765 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:09.766Z,1554772629.766 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:09.766Z,1554772629.766 [BR_Ping1D](INFO): XXXX automode2: 1
2019-04-09T01:17:09.766Z,1554772629.766 [BR_Ping1D](INFO): UART requesting status
2019-04-09T01:17:10.282Z,1554772630.282 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms

2019-04-09T01:17:10.282Z,1554772630.282 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T01:17:10.282Z,1554772630.282 [PAR_Licor](ERROR): Data Fault
2019-04-09T01:17:10.325Z,1554772630.325 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:10.325Z,1554772630.325 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:10.325Z,1554772630.325 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:10.325Z,1554772630.325 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:10.326Z,1554772630.326 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:10.326Z,1554772630.326 [BR_Ping1D](INFO): elapsed1.4464
2019-04-09T01:17:10.326Z,1554772630.326 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:10.326Z,1554772630.326 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:10.339Z,1554772630.339 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:17:10.459Z,1554772630.459 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-09T01:17:10.463Z,1554772630.463 [CBIT](INFO): Clearing failed state for component RudderServo
2019-04-09T01:17:10.463Z,1554772630.463 [RudderServo] No Fault, FailCount= 1
2019-04-09T01:17:10.463Z,1554772630.463 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T01:17:10.464Z,1554772630.464 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T01:17:10.464Z,1554772630.464 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T01:17:10.570Z,1554772630.570 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:10.570Z,1554772630.570 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:10.570Z,1554772630.570 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:10.571Z,1554772630.571 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:10.571Z,1554772630.571 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:10.571Z,1554772630.571 [BR_Ping1D](INFO): elapsed1.6915
2019-04-09T01:17:10.571Z,1554772630.571 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:10.571Z,1554772630.571 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:10.914Z,1554772630.914 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T01:17:10.914Z,1554772630.914 [MassServo] Communications Fault, FailCount= 1
2019-04-09T01:17:10.914Z,1554772630.914 [MassServo](ERROR): Communications Fault
2019-04-09T01:17:10.942Z,1554772630.942 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T01:17:10.991Z,1554772630.991 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:10.991Z,1554772630.991 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:10.991Z,1554772630.991 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:10.991Z,1554772630.991 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:10.991Z,1554772630.991 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:10.992Z,1554772630.992 [BR_Ping1D](INFO): elapsed2.1121
2019-04-09T01:17:10.992Z,1554772630.992 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:10.992Z,1554772630.992 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:11.058Z,1554772631.058 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T01:17:11.058Z,1554772631.058 [MassServo](INFO): Powering down
2019-04-09T01:17:11.381Z,1554772631.381 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:11.381Z,1554772631.381 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:11.382Z,1554772631.382 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:11.382Z,1554772631.382 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:11.382Z,1554772631.382 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:11.382Z,1554772631.382 [BR_Ping1D](INFO): elapsed2.5027
2019-04-09T01:17:11.382Z,1554772631.382 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:11.383Z,1554772631.383 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:11.906Z,1554772631.906 [PAR_Licor](INFO): A/D timeout, 9 tries over 142 ms

2019-04-09T01:17:11.906Z,1554772631.906 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T01:17:11.906Z,1554772631.906 [PAR_Licor](ERROR): Data Fault
2019-04-09T01:17:11.941Z,1554772631.941 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:11.942Z,1554772631.942 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:11.942Z,1554772631.942 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:11.942Z,1554772631.942 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:11.942Z,1554772631.942 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:11.943Z,1554772631.943 [BR_Ping1D](INFO): elapsed3.0630
2019-04-09T01:17:11.943Z,1554772631.943 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:11.943Z,1554772631.943 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:12.032Z,1554772632.032 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:17:12.151Z,1554772632.151 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T01:17:12.322Z,1554772632.322 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T01:17:12.322Z,1554772632.322 [MassServo] No Fault, FailCount= 1
2019-04-09T01:17:12.322Z,1554772632.322 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T01:17:12.322Z,1554772632.322 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T01:17:12.322Z,1554772632.322 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T01:17:12.348Z,1554772632.348 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:12.349Z,1554772632.349 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:12.349Z,1554772632.349 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:12.349Z,1554772632.349 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:12.349Z,1554772632.349 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:12.349Z,1554772632.349 [BR_Ping1D](INFO): elapsed3.4700
2019-04-09T01:17:12.366Z,1554772632.366 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:12.366Z,1554772632.366 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:12.444Z,1554772632.444 [CBIT](FAULT): Main Battery Failure. Count: 11
2019-04-09T01:17:12.866Z,1554772632.866 [PAR_Licor](INFO): A/D timeout, 9 tries over 134 ms

2019-04-09T01:17:12.866Z,1554772632.866 [PAR_Licor] Data Fault, FailCount= 2
2019-04-09T01:17:12.866Z,1554772632.866 [PAR_Licor](ERROR): Data Fault
2019-04-09T01:17:12.896Z,1554772632.896 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:12.897Z,1554772632.897 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:12.897Z,1554772632.897 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:12.897Z,1554772632.897 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:12.897Z,1554772632.897 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:12.897Z,1554772632.897 [BR_Ping1D](INFO): elapsed4.0178
2019-04-09T01:17:12.898Z,1554772632.898 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:12.899Z,1554772632.899 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:12.950Z,1554772632.950 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T01:17:12.950Z,1554772632.950 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T01:17:12.950Z,1554772632.950 [PAR_Licor] No Fault, FailCount= 2
2019-04-09T01:17:13.157Z,1554772633.157 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:13.158Z,1554772633.158 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:13.158Z,1554772633.158 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:13.158Z,1554772633.158 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:13.158Z,1554772633.158 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:13.158Z,1554772633.158 [BR_Ping1D](INFO): elapsed4.2789
2019-04-09T01:17:13.159Z,1554772633.159 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:13.159Z,1554772633.159 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:13.678Z,1554772633.678 [PAR_Licor](INFO): A/D timeout, 9 tries over 138 ms

2019-04-09T01:17:13.678Z,1554772633.678 [PAR_Licor] Data Fault, FailCount= 3
2019-04-09T01:17:13.678Z,1554772633.678 [PAR_Licor](ERROR): Data Fault
2019-04-09T01:17:13.719Z,1554772633.719 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:13.719Z,1554772633.719 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:13.720Z,1554772633.720 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:13.720Z,1554772633.720 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:13.720Z,1554772633.720 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:13.720Z,1554772633.720 [BR_Ping1D](INFO): elapsed4.8405
2019-04-09T01:17:13.720Z,1554772633.720 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:13.720Z,1554772633.720 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:13.818Z,1554772633.818 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T01:17:13.819Z,1554772633.819 [CBIT](CRITICAL): Data Fault in component: PAR_Licor
2019-04-09T01:17:13.961Z,1554772633.961 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:13.961Z,1554772633.961 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:13.962Z,1554772633.962 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:13.962Z,1554772633.962 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:13.962Z,1554772633.962 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:13.962Z,1554772633.962 [BR_Ping1D](INFO): elapsed5.0826
2019-04-09T01:17:13.962Z,1554772633.962 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:13.962Z,1554772633.962 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-09T01:17:13.963Z,1554772633.963 [BR_Ping1D](ERROR): Data Fault
2019-04-09T01:17:14.300Z,1554772634.300 [CBIT](INFO): Critical error at 20190409T011713
2019-04-09T01:17:14.303Z,1554772634.303 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T01:17:14.364Z,1554772634.364 [BR_Ping1D](INFO): Stop
2019-04-09T01:17:14.365Z,1554772634.365 [BR_Ping1D](INFO): uninitialize
2019-04-09T01:17:14.794Z,1554772634.794 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T01:17:14.794Z,1554772634.794 [MassServo] Communications Fault, FailCount= 2
2019-04-09T01:17:14.794Z,1554772634.794 [MassServo](ERROR): Communications Fault
2019-04-09T01:17:14.798Z,1554772634.798 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T01:17:14.826Z,1554772634.826 [BR_Ping1D](INFO): Stopping
2019-04-09T01:17:14.847Z,1554772634.847 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T01:17:14.847Z,1554772634.847 [MassServo](INFO): Powering down
2019-04-09T01:17:15.225Z,1554772635.225 [BR_Ping1D](INFO): Stopping
2019-04-09T01:17:15.629Z,1554772635.629 [BR_Ping1D](INFO): Stopped
2019-04-09T01:17:15.639Z,1554772635.639 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:17:15.759Z,1554772635.759 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T01:17:15.764Z,1554772635.764 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T01:17:15.764Z,1554772635.764 [MassServo] No Fault, FailCount= 2
2019-04-09T01:17:15.764Z,1554772635.764 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T01:17:15.764Z,1554772635.764 [BR_Ping1D] No Fault, FailCount= 1
2019-04-09T01:17:16.034Z,1554772636.034 [BR_Ping1D](INFO): Start
2019-04-09T01:17:16.437Z,1554772636.437 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:16.449Z,1554772636.449 [CBIT](FAULT): Main Battery Failure. Count: 21
2019-04-09T01:17:16.842Z,1554772636.842 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:16.842Z,1554772636.842 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:16.842Z,1554772636.842 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:16.842Z,1554772636.842 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:16.842Z,1554772636.842 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T01:17:16.854Z,1554772636.854 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T01:17:17.246Z,1554772637.246 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:17.263Z,1554772637.263 [CBIT](CRITICAL): Environmental Failure. Press:14.847911 PSI. Humidity:45%. Temp:26 C. ABORTING MISSION
2019-04-09T01:17:17.650Z,1554772637.650 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:17.650Z,1554772637.650 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:17.650Z,1554772637.650 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:17.651Z,1554772637.651 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:17.651Z,1554772637.651 [BR_Ping1D](INFO): UART requesting status
2019-04-09T01:17:17.689Z,1554772637.689 [CBIT](INFO): Critical error at 20190409T011717
2019-04-09T01:17:18.034Z,1554772638.034 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T01:17:18.034Z,1554772638.034 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-09T01:17:18.035Z,1554772638.035 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T01:17:18.053Z,1554772638.053 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:18.053Z,1554772638.053 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:18.053Z,1554772638.053 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:18.054Z,1554772638.054 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:18.054Z,1554772638.054 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:18.054Z,1554772638.054 [BR_Ping1D](INFO): elapsed1.2120
2019-04-09T01:17:18.055Z,1554772638.055 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:18.055Z,1554772638.055 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:18.378Z,1554772638.378 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T01:17:18.378Z,1554772638.378 [MassServo] Communications Fault, FailCount= 3
2019-04-09T01:17:18.378Z,1554772638.378 [MassServo](ERROR): Communications Fault
2019-04-09T01:17:18.382Z,1554772638.382 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T01:17:18.383Z,1554772638.383 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T01:17:18.558Z,1554772638.558 [Aanderaa_O2](INFO): Powering down
2019-04-09T01:17:18.639Z,1554772638.639 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:18.639Z,1554772638.639 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:18.639Z,1554772638.639 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:18.639Z,1554772638.639 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:18.639Z,1554772638.639 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:18.639Z,1554772638.639 [BR_Ping1D](INFO): elapsed1.7970
2019-04-09T01:17:18.640Z,1554772638.640 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:18.640Z,1554772638.640 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:18.651Z,1554772638.651 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T01:17:18.651Z,1554772638.651 [MassServo](INFO): Powering down
2019-04-09T01:17:18.861Z,1554772638.861 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:18.861Z,1554772638.861 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:18.861Z,1554772638.861 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:18.862Z,1554772638.862 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:18.862Z,1554772638.862 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:18.862Z,1554772638.862 [BR_Ping1D](INFO): elapsed2.0196
2019-04-09T01:17:18.862Z,1554772638.862 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:18.862Z,1554772638.862 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:19.266Z,1554772639.266 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:19.266Z,1554772639.266 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:19.267Z,1554772639.267 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:19.267Z,1554772639.267 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:19.267Z,1554772639.267 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:19.267Z,1554772639.267 [BR_Ping1D](INFO): elapsed2.4245
2019-04-09T01:17:19.267Z,1554772639.267 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:19.267Z,1554772639.267 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:19.295Z,1554772639.295 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:17:19.415Z,1554772639.415 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T01:17:19.420Z,1554772639.420 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T01:17:19.420Z,1554772639.420 [MassServo] No Fault, FailCount= 3
2019-04-09T01:17:19.420Z,1554772639.420 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T01:17:19.420Z,1554772639.420 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-09T01:17:19.669Z,1554772639.669 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:19.669Z,1554772639.669 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:19.670Z,1554772639.670 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:19.670Z,1554772639.670 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:19.670Z,1554772639.670 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:19.670Z,1554772639.670 [BR_Ping1D](INFO): elapsed2.8278
2019-04-09T01:17:19.670Z,1554772639.670 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:19.671Z,1554772639.671 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:20.086Z,1554772640.086 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:20.086Z,1554772640.086 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:20.086Z,1554772640.086 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:20.086Z,1554772640.086 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:20.086Z,1554772640.086 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:20.087Z,1554772640.087 [BR_Ping1D](INFO): elapsed3.2442
2019-04-09T01:17:20.087Z,1554772640.087 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:20.087Z,1554772640.087 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:20.250Z,1554772640.250 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T01:17:20.250Z,1554772640.250 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-09T01:17:20.250Z,1554772640.250 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T01:17:20.477Z,1554772640.477 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:20.477Z,1554772640.477 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:20.477Z,1554772640.477 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:20.478Z,1554772640.478 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:20.478Z,1554772640.478 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:20.478Z,1554772640.478 [BR_Ping1D](INFO): elapsed3.6355
2019-04-09T01:17:20.478Z,1554772640.478 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:20.478Z,1554772640.478 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:20.535Z,1554772640.535 [CBIT](FAULT): Main Battery Failure. Count: 31
2019-04-09T01:17:20.554Z,1554772640.554 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T01:17:20.654Z,1554772640.654 [CTD_Seabird](INFO): Powering down
2019-04-09T01:17:20.882Z,1554772640.882 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:20.882Z,1554772640.882 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:20.882Z,1554772640.882 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:20.882Z,1554772640.882 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:20.882Z,1554772640.882 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:20.883Z,1554772640.883 [BR_Ping1D](INFO): elapsed4.0401
2019-04-09T01:17:20.883Z,1554772640.883 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:20.883Z,1554772640.883 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:21.285Z,1554772641.285 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:21.285Z,1554772641.285 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:21.286Z,1554772641.286 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:21.286Z,1554772641.286 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:21.286Z,1554772641.286 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:21.286Z,1554772641.286 [BR_Ping1D](INFO): elapsed4.4440
2019-04-09T01:17:21.287Z,1554772641.287 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:21.287Z,1554772641.287 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:21.690Z,1554772641.690 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:21.690Z,1554772641.690 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:21.690Z,1554772641.690 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:21.690Z,1554772641.690 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:21.691Z,1554772641.691 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:21.691Z,1554772641.691 [BR_Ping1D](INFO): elapsed4.8483
2019-04-09T01:17:21.691Z,1554772641.691 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:21.691Z,1554772641.691 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:22.014Z,1554772642.014 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T01:17:22.014Z,1554772642.014 [MassServo] Communications Fault, FailCount= 4
2019-04-09T01:17:22.014Z,1554772642.014 [MassServo](ERROR): Communications Fault
2019-04-09T01:17:22.018Z,1554772642.018 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T01:17:22.019Z,1554772642.019 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T01:17:22.019Z,1554772642.019 [CTD_Seabird] No Fault, FailCount= 1
2019-04-09T01:17:22.030Z,1554772642.030 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T01:17:22.032Z,1554772642.032 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T01:17:22.093Z,1554772642.093 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:22.094Z,1554772642.094 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:22.094Z,1554772642.094 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:22.094Z,1554772642.094 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:22.094Z,1554772642.094 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:22.094Z,1554772642.094 [BR_Ping1D](INFO): elapsed5.2520
2019-04-09T01:17:22.095Z,1554772642.095 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:22.095Z,1554772642.095 [BR_Ping1D] Data Fault, FailCount= 2
2019-04-09T01:17:22.095Z,1554772642.095 [BR_Ping1D](ERROR): Data Fault
2019-04-09T01:17:22.123Z,1554772642.123 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T01:17:22.123Z,1554772642.123 [MassServo](INFO): Powering down
2019-04-09T01:17:22.128Z,1554772642.128 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T01:17:22.498Z,1554772642.498 [BR_Ping1D](INFO): Stop
2019-04-09T01:17:22.498Z,1554772642.498 [BR_Ping1D](INFO): uninitialize
2019-04-09T01:17:22.901Z,1554772642.901 [BR_Ping1D](INFO): Stopping
2019-04-09T01:17:22.917Z,1554772642.917 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:17:23.035Z,1554772643.035 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T01:17:23.040Z,1554772643.040 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T01:17:23.040Z,1554772643.040 [MassServo] No Fault, FailCount= 4
2019-04-09T01:17:23.306Z,1554772643.306 [BR_Ping1D](INFO): Stopping
2019-04-09T01:17:23.710Z,1554772643.710 [BR_Ping1D](INFO): Stopped
2019-04-09T01:17:23.727Z,1554772643.727 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T01:17:23.727Z,1554772643.727 [BR_Ping1D] No Fault, FailCount= 2
2019-04-09T01:17:24.114Z,1554772644.114 [BR_Ping1D](INFO): Start
2019-04-09T01:17:24.518Z,1554772644.518 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:24.545Z,1554772644.545 [CBIT](FAULT): Main Battery Failure. Count: 41
2019-04-09T01:17:24.922Z,1554772644.922 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:24.922Z,1554772644.922 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:24.922Z,1554772644.922 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:24.923Z,1554772644.923 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:24.923Z,1554772644.923 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T01:17:24.934Z,1554772644.934 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T01:17:25.326Z,1554772645.326 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:25.642Z,1554772645.642 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T01:17:25.642Z,1554772645.642 [MassServo] Communications Fault, FailCount= 5
2019-04-09T01:17:25.642Z,1554772645.642 [MassServo](ERROR): Communications Fault
2019-04-09T01:17:25.646Z,1554772645.646 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T01:17:25.647Z,1554772645.647 [CBIT](CRITICAL): Communications Fault in component: MassServo
2019-04-09T01:17:25.731Z,1554772645.731 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:25.731Z,1554772645.731 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:25.731Z,1554772645.731 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:25.731Z,1554772645.731 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:25.731Z,1554772645.731 [BR_Ping1D](INFO): UART requesting status
2019-04-09T01:17:25.767Z,1554772645.767 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T01:17:25.767Z,1554772645.767 [MassServo](INFO): Powering down
2019-04-09T01:17:25.774Z,1554772645.774 [CBIT](INFO): Critical error at 20190409T011725
2019-04-09T01:17:26.134Z,1554772646.134 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:26.134Z,1554772646.134 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:26.134Z,1554772646.134 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:26.134Z,1554772646.134 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:26.134Z,1554772646.134 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:26.135Z,1554772646.135 [BR_Ping1D](INFO): elapsed1.2117
2019-04-09T01:17:26.135Z,1554772646.135 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:26.135Z,1554772646.135 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:26.537Z,1554772646.537 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:26.538Z,1554772646.538 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:26.538Z,1554772646.538 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:26.538Z,1554772646.538 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:26.538Z,1554772646.538 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:26.538Z,1554772646.538 [BR_Ping1D](INFO): elapsed1.6155
2019-04-09T01:17:26.539Z,1554772646.539 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:26.539Z,1554772646.539 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:26.552Z,1554772646.552 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:17:26.671Z,1554772646.671 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T01:17:26.942Z,1554772646.942 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:26.942Z,1554772646.942 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:26.942Z,1554772646.942 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:26.942Z,1554772646.942 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:26.943Z,1554772646.943 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:26.943Z,1554772646.943 [BR_Ping1D](INFO): elapsed2.0199
2019-04-09T01:17:26.943Z,1554772646.943 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:26.943Z,1554772646.943 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:27.346Z,1554772647.346 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:27.347Z,1554772647.347 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:27.347Z,1554772647.347 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:27.347Z,1554772647.347 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:27.347Z,1554772647.347 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:27.347Z,1554772647.347 [BR_Ping1D](INFO): elapsed2.4245
2019-04-09T01:17:27.348Z,1554772647.348 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:27.348Z,1554772647.348 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:27.749Z,1554772647.749 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:27.749Z,1554772647.749 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:27.750Z,1554772647.750 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:27.750Z,1554772647.750 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:27.750Z,1554772647.750 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:27.750Z,1554772647.750 [BR_Ping1D](INFO): elapsed2.8274
2019-04-09T01:17:27.750Z,1554772647.750 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:27.751Z,1554772647.751 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:28.135Z,1554772648.135 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T01:17:28.135Z,1554772648.135 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1
2019-04-09T01:17:28.135Z,1554772648.135 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T01:17:28.154Z,1554772648.154 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:28.155Z,1554772648.155 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:28.155Z,1554772648.155 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:28.155Z,1554772648.155 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:28.155Z,1554772648.155 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:28.155Z,1554772648.155 [BR_Ping1D](INFO): elapsed3.2324
2019-04-09T01:17:28.156Z,1554772648.156 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:28.156Z,1554772648.156 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:28.171Z,1554772648.171 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:17:28.539Z,1554772648.539 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:17:28.702Z,1554772648.702 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:28.702Z,1554772648.702 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:28.702Z,1554772648.702 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:28.703Z,1554772648.703 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:28.703Z,1554772648.703 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:28.703Z,1554772648.703 [BR_Ping1D](INFO): elapsed3.7800
2019-04-09T01:17:28.703Z,1554772648.703 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:28.703Z,1554772648.703 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:28.792Z,1554772648.792 [CBIT](FAULT): Main Battery Failure. Count: 51
2019-04-09T01:17:28.962Z,1554772648.962 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:28.962Z,1554772648.962 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:28.962Z,1554772648.962 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:28.963Z,1554772648.963 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:28.963Z,1554772648.963 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:28.963Z,1554772648.963 [BR_Ping1D](INFO): elapsed4.0400
2019-04-09T01:17:28.963Z,1554772648.963 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:28.963Z,1554772648.963 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:29.366Z,1554772649.366 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:29.366Z,1554772649.366 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:29.366Z,1554772649.366 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:29.366Z,1554772649.366 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:29.367Z,1554772649.367 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:29.367Z,1554772649.367 [BR_Ping1D](INFO): elapsed4.4439
2019-04-09T01:17:29.367Z,1554772649.367 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:29.367Z,1554772649.367 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:29.383Z,1554772649.383 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T01:17:29.383Z,1554772649.383 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1
2019-04-09T01:17:29.750Z,1554772649.750 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T01:17:29.750Z,1554772649.750 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-09T01:17:29.750Z,1554772649.750 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T01:17:29.769Z,1554772649.769 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:29.769Z,1554772649.769 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:29.769Z,1554772649.769 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:29.770Z,1554772649.770 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:29.770Z,1554772649.770 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:29.770Z,1554772649.770 [BR_Ping1D](INFO): elapsed4.8471
2019-04-09T01:17:29.770Z,1554772649.770 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:29.771Z,1554772649.771 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:29.799Z,1554772649.799 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T01:17:30.274Z,1554772650.274 [Aanderaa_O2](INFO): Powering down
2019-04-09T01:17:30.294Z,1554772650.294 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:30.295Z,1554772650.295 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:30.295Z,1554772650.295 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:30.295Z,1554772650.295 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:30.295Z,1554772650.295 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:30.295Z,1554772650.295 [BR_Ping1D](INFO): elapsed5.3724
2019-04-09T01:17:30.296Z,1554772650.296 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:30.296Z,1554772650.296 [BR_Ping1D] Data Fault, FailCount= 3
2019-04-09T01:17:30.296Z,1554772650.296 [BR_Ping1D](ERROR): Data Fault
2019-04-09T01:17:30.320Z,1554772650.320 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T01:17:30.585Z,1554772650.585 [BR_Ping1D](INFO): Stop
2019-04-09T01:17:30.585Z,1554772650.585 [BR_Ping1D](INFO): uninitialize
2019-04-09T01:17:30.979Z,1554772650.979 [BR_Ping1D](INFO): Stopping
2019-04-09T01:17:30.995Z,1554772650.995 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T01:17:30.995Z,1554772650.995 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-09T01:17:31.397Z,1554772651.397 [BR_Ping1D](INFO): Stopping
2019-04-09T01:17:31.782Z,1554772651.782 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T01:17:31.783Z,1554772651.783 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T01:17:31.826Z,1554772651.826 [BR_Ping1D](INFO): Stopped
2019-04-09T01:17:31.840Z,1554772651.840 [SBIT](IMPORTANT): Beginning Startup BIT
2019-04-09T01:17:31.844Z,1554772651.844 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T01:17:31.844Z,1554772651.844 [BR_Ping1D] No Fault, FailCount= 3
2019-04-09T01:17:31.845Z,1554772651.845 [CBIT](IMPORTANT): Beginning ground fault scan
2019-04-09T01:17:32.194Z,1554772652.194 [BR_Ping1D](INFO): Start
2019-04-09T01:17:32.597Z,1554772652.597 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:32.644Z,1554772652.644 [CBIT](FAULT): Main Battery Failure. Count: 61
2019-04-09T01:17:33.015Z,1554772653.015 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:33.015Z,1554772653.015 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:33.016Z,1554772653.016 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:33.016Z,1554772653.016 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:33.016Z,1554772653.016 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T01:17:33.030Z,1554772653.030 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T01:17:33.407Z,1554772653.407 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:33.809Z,1554772653.809 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:33.810Z,1554772653.810 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:33.810Z,1554772653.810 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:33.810Z,1554772653.810 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:33.810Z,1554772653.810 [BR_Ping1D](INFO): UART requesting status
2019-04-09T01:17:34.213Z,1554772654.213 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:34.213Z,1554772654.213 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:34.214Z,1554772654.214 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:34.214Z,1554772654.214 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:34.214Z,1554772654.214 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:34.214Z,1554772654.214 [BR_Ping1D](INFO): elapsed1.1983
2019-04-09T01:17:34.214Z,1554772654.214 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:34.215Z,1554772654.215 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:34.618Z,1554772654.618 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:34.618Z,1554772654.618 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:34.618Z,1554772654.618 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:34.618Z,1554772654.618 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:34.618Z,1554772654.618 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:34.619Z,1554772654.619 [BR_Ping1D](INFO): elapsed1.6026
2019-04-09T01:17:34.619Z,1554772654.619 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:34.619Z,1554772654.619 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:35.007Z,1554772655.007 [NAL9602](INFO): Powering up NAL9602
2019-04-09T01:17:35.021Z,1554772655.021 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:35.021Z,1554772655.021 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:35.021Z,1554772655.021 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:35.022Z,1554772655.022 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:35.022Z,1554772655.022 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:35.022Z,1554772655.022 [BR_Ping1D](INFO): elapsed2.0060
2019-04-09T01:17:35.022Z,1554772655.022 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:35.022Z,1554772655.022 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:35.083Z,1554772655.083 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T01:17:35.084Z,1554772655.084 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-09T01:17:35.084Z,1554772655.084 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T01:17:35.191Z,1554772655.191 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T01:17:35.429Z,1554772655.429 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:35.429Z,1554772655.429 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:35.430Z,1554772655.430 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:35.430Z,1554772655.430 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:35.430Z,1554772655.430 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:35.430Z,1554772655.430 [BR_Ping1D](INFO): elapsed2.4143
2019-04-09T01:17:35.431Z,1554772655.431 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:35.431Z,1554772655.431 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:35.486Z,1554772655.486 [CTD_Seabird](INFO): Powering down
2019-04-09T01:17:35.830Z,1554772655.830 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:35.830Z,1554772655.830 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:35.830Z,1554772655.830 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:35.830Z,1554772655.830 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:35.830Z,1554772655.830 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:35.831Z,1554772655.831 [BR_Ping1D](INFO): elapsed2.8146
2019-04-09T01:17:35.831Z,1554772655.831 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:35.831Z,1554772655.831 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:36.233Z,1554772656.233 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:36.234Z,1554772656.234 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:36.234Z,1554772656.234 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:36.234Z,1554772656.234 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:36.234Z,1554772656.234 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:36.235Z,1554772656.235 [BR_Ping1D](INFO): elapsed3.2185
2019-04-09T01:17:36.235Z,1554772656.235 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:36.235Z,1554772656.235 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:36.637Z,1554772656.637 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:36.637Z,1554772656.637 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:36.638Z,1554772656.638 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:36.638Z,1554772656.638 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:36.638Z,1554772656.638 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:36.638Z,1554772656.638 [BR_Ping1D](INFO): elapsed3.6223
2019-04-09T01:17:36.639Z,1554772656.639 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:36.639Z,1554772656.639 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:36.772Z,1554772656.772 [CBIT](FAULT): Main Battery Failure. Count: 71
2019-04-09T01:17:36.775Z,1554772656.775 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T01:17:36.775Z,1554772656.775 [CTD_Seabird] No Fault, FailCount= 2
2019-04-09T01:17:36.830Z,1554772656.830 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T01:17:36.838Z,1554772656.838 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T01:17:37.042Z,1554772657.042 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:37.042Z,1554772657.042 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:37.042Z,1554772657.042 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:37.042Z,1554772657.042 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:37.042Z,1554772657.042 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:37.043Z,1554772657.043 [BR_Ping1D](INFO): elapsed4.0265
2019-04-09T01:17:37.043Z,1554772657.043 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:37.043Z,1554772657.043 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:37.445Z,1554772657.445 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:37.445Z,1554772657.445 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:37.445Z,1554772657.445 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:37.446Z,1554772657.446 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:37.446Z,1554772657.446 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:37.446Z,1554772657.446 [BR_Ping1D](INFO): elapsed4.4300
2019-04-09T01:17:37.446Z,1554772657.446 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:37.446Z,1554772657.446 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:37.850Z,1554772657.850 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:37.850Z,1554772657.850 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:37.850Z,1554772657.850 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:37.850Z,1554772657.850 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:37.851Z,1554772657.851 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:37.851Z,1554772657.851 [BR_Ping1D](INFO): elapsed4.8348
2019-04-09T01:17:37.851Z,1554772657.851 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:37.851Z,1554772657.851 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:38.255Z,1554772658.255 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:38.255Z,1554772658.255 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:38.256Z,1554772658.256 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:38.256Z,1554772658.256 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:38.256Z,1554772658.256 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:38.256Z,1554772658.256 [BR_Ping1D](INFO): elapsed5.2401
2019-04-09T01:17:38.256Z,1554772658.256 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:38.256Z,1554772658.256 [BR_Ping1D] Data Fault, FailCount= 4
2019-04-09T01:17:38.256Z,1554772658.256 [BR_Ping1D](ERROR): Data Fault
2019-04-09T01:17:38.272Z,1554772658.272 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T01:17:38.661Z,1554772658.661 [BR_Ping1D](INFO): Stop
2019-04-09T01:17:38.662Z,1554772658.662 [BR_Ping1D](INFO): uninitialize
2019-04-09T01:17:39.061Z,1554772659.061 [BR_Ping1D](INFO): Stopping
2019-04-09T01:17:39.466Z,1554772659.466 [BR_Ping1D](INFO): Stopping
2019-04-09T01:17:39.869Z,1554772659.869 [BR_Ping1D](INFO): Stopped
2019-04-09T01:17:39.884Z,1554772659.884 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T01:17:39.884Z,1554772659.884 [BR_Ping1D] No Fault, FailCount= 4
2019-04-09T01:17:40.274Z,1554772660.274 [BR_Ping1D](INFO): Start
2019-04-09T01:17:40.674Z,1554772660.674 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:40.701Z,1554772660.701 [CBIT](FAULT): Main Battery Failure. Count: 81
2019-04-09T01:17:41.081Z,1554772661.081 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:41.082Z,1554772661.082 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:41.082Z,1554772661.082 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:41.082Z,1554772661.082 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:41.082Z,1554772661.082 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T01:17:41.094Z,1554772661.094 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T01:17:41.467Z,1554772661.467 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T01:17:41.467Z,1554772661.467 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-09T01:17:41.467Z,1554772661.467 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T01:17:41.486Z,1554772661.486 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:41.516Z,1554772661.516 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T01:17:41.516Z,1554772661.516 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-09T01:17:41.990Z,1554772661.990 [Aanderaa_O2](INFO): Powering down
2019-04-09T01:17:42.009Z,1554772662.009 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:42.009Z,1554772662.009 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:42.009Z,1554772662.009 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:42.009Z,1554772662.009 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:42.009Z,1554772662.009 [BR_Ping1D](INFO): UART requesting status
2019-04-09T01:17:42.294Z,1554772662.294 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:42.297Z,1554772662.297 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:42.298Z,1554772662.298 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:42.298Z,1554772662.298 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:42.298Z,1554772662.298 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:42.298Z,1554772662.298 [BR_Ping1D](INFO): elapsed1.2158
2019-04-09T01:17:42.298Z,1554772662.298 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:42.299Z,1554772662.299 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:42.698Z,1554772662.698 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:42.698Z,1554772662.698 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:42.698Z,1554772662.698 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:42.698Z,1554772662.698 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:42.698Z,1554772662.698 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:42.699Z,1554772662.699 [BR_Ping1D](INFO): elapsed1.6161
2019-04-09T01:17:42.699Z,1554772662.699 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:42.699Z,1554772662.699 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:42.779Z,1554772662.779 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.006345
CHAN A1 (24V): -0.127594
CHAN A2 (12V): -0.004769
CHAN A3 (5V): -0.002433
CHAN B0 (3.3V): -0.001119
CHAN B1 (3.15aV): -0.001244
CHAN B2 (3.15bV): -0.001240
CHAN B3 (GND): -0.000454
OPEN: -0.000932
Full Scale Calc: 4.765 mA, -1.589 mA
2019-04-09T01:17:43.102Z,1554772663.102 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:43.102Z,1554772663.102 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:43.102Z,1554772663.102 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:43.102Z,1554772663.102 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:43.103Z,1554772663.103 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:43.103Z,1554772663.103 [BR_Ping1D](INFO): elapsed2.0204
2019-04-09T01:17:43.103Z,1554772663.103 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:43.103Z,1554772663.103 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:43.507Z,1554772663.507 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:43.507Z,1554772663.507 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:43.507Z,1554772663.507 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:43.507Z,1554772663.507 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:43.507Z,1554772663.507 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:43.507Z,1554772663.507 [BR_Ping1D](INFO): elapsed2.4251
2019-04-09T01:17:43.508Z,1554772663.508 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:43.508Z,1554772663.508 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:43.909Z,1554772663.909 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:43.909Z,1554772663.909 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:43.909Z,1554772663.909 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:43.910Z,1554772663.910 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:43.910Z,1554772663.910 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:43.910Z,1554772663.910 [BR_Ping1D](INFO): elapsed2.8277
2019-04-09T01:17:43.910Z,1554772663.910 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:43.910Z,1554772663.910 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:44.313Z,1554772664.313 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:44.314Z,1554772664.314 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:44.314Z,1554772664.314 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:44.314Z,1554772664.314 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:44.314Z,1554772664.314 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:44.314Z,1554772664.314 [BR_Ping1D](INFO): elapsed3.2321
2019-04-09T01:17:44.315Z,1554772664.315 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:44.315Z,1554772664.315 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:44.717Z,1554772664.717 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:44.717Z,1554772664.717 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:44.717Z,1554772664.717 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:44.718Z,1554772664.718 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:44.718Z,1554772664.718 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:44.718Z,1554772664.718 [BR_Ping1D](INFO): elapsed3.6357
2019-04-09T01:17:44.718Z,1554772664.718 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:44.718Z,1554772664.718 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:44.745Z,1554772664.745 [CBIT](FAULT): Main Battery Failure. Count: 91
2019-04-09T01:17:45.120Z,1554772665.120 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:45.120Z,1554772665.120 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:45.120Z,1554772665.120 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:45.120Z,1554772665.120 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:45.120Z,1554772665.120 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:45.121Z,1554772665.121 [BR_Ping1D](INFO): elapsed4.0383
2019-04-09T01:17:45.121Z,1554772665.121 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:45.121Z,1554772665.121 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:45.132Z,1554772665.132 [SBIT](ERROR): Could not read massPosReader_.
2019-04-09T01:17:45.537Z,1554772665.537 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:45.537Z,1554772665.537 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:45.537Z,1554772665.537 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:45.537Z,1554772665.537 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:45.537Z,1554772665.537 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:45.538Z,1554772665.538 [BR_Ping1D](INFO): elapsed4.4552
2019-04-09T01:17:45.538Z,1554772665.538 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:45.538Z,1554772665.538 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:45.915Z,1554772665.915 [NAL9602](INFO): NAL9602 initialized
2019-04-09T01:17:45.930Z,1554772665.930 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:45.930Z,1554772665.930 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:45.930Z,1554772665.930 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:45.931Z,1554772665.931 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:45.931Z,1554772665.931 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:45.931Z,1554772665.931 [BR_Ping1D](INFO): elapsed4.8485
2019-04-09T01:17:45.931Z,1554772665.931 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:45.931Z,1554772665.931 [BR_Ping1D](INFO): No UART data
2019-04-09T01:17:46.343Z,1554772666.343 [BR_Ping1D](INFO): Starting
2019-04-09T01:17:46.343Z,1554772666.343 [BR_Ping1D](INFO): readConfig
2019-04-09T01:17:46.343Z,1554772666.343 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:17:46.343Z,1554772666.343 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:17:46.344Z,1554772666.344 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:17:46.344Z,1554772666.344 [BR_Ping1D](INFO): elapsed5.2614
2019-04-09T01:17:46.344Z,1554772666.344 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:17:46.344Z,1554772666.344 [BR_Ping1D] Data Fault, FailCount= 5
2019-04-09T01:17:46.344Z,1554772666.344 [BR_Ping1D](ERROR): Data Fault
2019-04-09T01:17:46.431Z,1554772666.431 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T01:17:46.432Z,1554772666.432 [CBIT](CRITICAL): Data Fault in component: BR_Ping1D
2019-04-09T01:17:46.734Z,1554772666.734 [NAL9602](DEBUG): Fix Requested
2019-04-09T01:17:46.749Z,1554772666.749 [BR_Ping1D](INFO): Stop
2019-04-09T01:17:46.749Z,1554772666.749 [BR_Ping1D](INFO): uninitialize
2019-04-09T01:17:46.843Z,1554772666.843 [CBIT](INFO): Critical error at 20190409T011746
2019-04-09T01:17:47.156Z,1554772667.156 [BR_Ping1D](INFO): Stopping
2019-04-09T01:17:47.569Z,1554772667.569 [BR_Ping1D](INFO): Stopping
2019-04-09T01:17:47.950Z,1554772667.950 [BR_Ping1D](INFO): Stopped
2019-04-09T01:17:48.821Z,1554772668.821 [CBIT](FAULT): Main Battery Failure. Count: 101
2019-04-09T01:17:49.882Z,1554772669.882 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T01:17:49.882Z,1554772669.882 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-09T01:17:49.882Z,1554772669.882 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T01:17:50.059Z,1554772670.059 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T01:17:50.059Z,1554772670.059 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-09T01:17:50.286Z,1554772670.286 [CTD_Seabird](INFO): Powering down
2019-04-09T01:17:51.971Z,1554772671.971 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T01:17:51.971Z,1554772671.971 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 2
2019-04-09T01:17:51.971Z,1554772671.971 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T01:17:52.051Z,1554772672.051 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:17:52.374Z,1554772672.374 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:17:53.060Z,1554772673.060 [CBIT](FAULT): Main Battery Failure. Count: 111
2019-04-09T01:17:53.225Z,1554772673.225 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T01:17:53.225Z,1554772673.225 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 2
2019-04-09T01:17:55.607Z,1554772675.607 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T01:17:55.607Z,1554772675.607 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T01:17:56.849Z,1554772676.849 [CBIT](FAULT): Main Battery Failure. Count: 121
2019-04-09T01:18:00.903Z,1554772680.903 [CBIT](FAULT): Main Battery Failure. Count: 131
2019-04-09T01:18:04.949Z,1554772684.949 [CBIT](FAULT): Main Battery Failure. Count: 141
2019-04-09T01:18:08.973Z,1554772688.973 [CBIT](FAULT): Main Battery Failure. Count: 151
2019-04-09T01:18:11.801Z,1554772691.801 [SBIT](ERROR): Could not read massPosReader_.
2019-04-09T01:18:11.801Z,1554772691.801 [SBIT](FAULT): Control surface position failure.
2019-04-09T01:18:13.015Z,1554772693.015 [CBIT](FAULT): Main Battery Failure. Count: 161
2019-04-09T01:18:15.807Z,1554772695.807 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T01:18:15.807Z,1554772695.807 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 3
2019-04-09T01:18:15.807Z,1554772695.807 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T01:18:15.920Z,1554772695.920 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:18:16.210Z,1554772696.210 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:18:17.296Z,1554772697.296 [CBIT](FAULT): Main Battery Failure. Count: 171
2019-04-09T01:18:17.299Z,1554772697.299 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T01:18:17.299Z,1554772697.299 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 3
2019-04-09T01:18:19.442Z,1554772699.442 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T01:18:19.443Z,1554772699.443 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T01:18:21.109Z,1554772701.109 [CBIT](FAULT): Main Battery Failure. Count: 181
2019-04-09T01:18:25.134Z,1554772705.134 [CBIT](FAULT): Main Battery Failure. Count: 191
2019-04-09T01:18:25.536Z,1554772705.536 [SBIT](ERROR): Could not read massPosReader_.
2019-04-09T01:18:25.536Z,1554772705.536 [SBIT](FAULT): Control surface position failure.
2019-04-09T01:18:25.954Z,1554772705.954 [SBIT](CRITICAL): SBIT FAILED
2019-04-09T01:18:25.969Z,1554772705.969 [CBIT](INFO): Critical error at 20190409T011825
2019-04-09T01:18:26.019Z,1554772706.019 [CommandLine](IMPORTANT): got command configSet list
2019-04-09T01:18:26.019Z,1554772706.019 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-04-09T01:18:26.020Z,1554772706.020 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-04-09T01:18:26.020Z,1554772706.020 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2019-04-09T01:18:26.348Z,1554772706.348 [MissionManager](IMPORTANT): Started mission Startup
2019-04-09T01:18:26.348Z,1554772706.348 [Startup] Running Loop=1
2019-04-09T01:18:26.348Z,1554772706.348 [Startup](DEBUG): Aggregate::initialize Startup
2019-04-09T01:18:26.348Z,1554772706.348 [Startup:A.GoToSurface] Running Loop=1
2019-04-09T01:18:26.348Z,1554772706.348 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-09T01:18:26.349Z,1554772706.349 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-09T01:18:26.350Z,1554772706.350 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-09T01:18:26.350Z,1554772706.350 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-09T01:18:26.351Z,1554772706.351 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-09T01:18:26.351Z,1554772706.351 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-09T01:18:26.352Z,1554772706.352 [Startup:StartupSatComms] Running Loop=1
2019-04-09T01:18:26.353Z,1554772706.353 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-04-09T01:18:26.353Z,1554772706.353 [Startup:StartupSatComms:A] Running Loop=1
2019-04-09T01:18:26.763Z,1554772706.763 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-04-09T01:18:29.173Z,1554772709.173 [CBIT](FAULT): Main Battery Failure. Count: 201
2019-04-09T01:18:33.206Z,1554772713.206 [CBIT](FAULT): Main Battery Failure. Count: 211
2019-04-09T01:18:37.269Z,1554772717.269 [CBIT](FAULT): Main Battery Failure. Count: 221
2019-04-09T01:18:39.642Z,1554772719.642 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T01:18:39.643Z,1554772719.643 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 4
2019-04-09T01:18:39.643Z,1554772719.643 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T01:18:39.680Z,1554772719.680 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:18:40.046Z,1554772720.046 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:18:40.895Z,1554772720.895 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T01:18:40.895Z,1554772720.895 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 4
2019-04-09T01:18:41.297Z,1554772721.297 [CBIT](FAULT): Main Battery Failure. Count: 231
2019-04-09T01:18:43.278Z,1554772723.278 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T01:18:43.279Z,1554772723.279 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T01:18:45.327Z,1554772725.327 [CBIT](FAULT): Main Battery Failure. Count: 241
2019-04-09T01:18:49.373Z,1554772729.373 [CBIT](FAULT): Main Battery Failure. Count: 251
2019-04-09T01:18:50.387Z,1554772730.387 [CommandLine](INFO): End of History
2019-04-09T01:18:53.424Z,1554772733.424 [CBIT](FAULT): Main Battery Failure. Count: 261
2019-04-09T01:18:57.473Z,1554772737.473 [CBIT](FAULT): Main Battery Failure. Count: 271
2019-04-09T01:19:00.124Z,1554772740.124 [CommandLine](IMPORTANT): got command failComponent none BR_Ping1D
2019-04-09T01:19:00.124Z,1554772740.124 [BR_Ping1D] No Fault, FailCount= 5
2019-04-09T01:19:00.124Z,1554772740.124 [CommandLine](IMPORTANT): BR_Ping1D failureMode is No Fault
2019-04-09T01:19:00.266Z,1554772740.266 [BR_Ping1D](INFO): Start
2019-04-09T01:19:00.671Z,1554772740.671 [BR_Ping1D](INFO): Starting
2019-04-09T01:19:01.075Z,1554772741.075 [BR_Ping1D](INFO): Starting
2019-04-09T01:19:01.075Z,1554772741.075 [BR_Ping1D](INFO): readConfig
2019-04-09T01:19:01.075Z,1554772741.075 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:19:01.075Z,1554772741.075 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:19:01.075Z,1554772741.075 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T01:19:01.090Z,1554772741.090 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T01:19:01.485Z,1554772741.485 [BR_Ping1D](INFO): Starting
2019-04-09T01:19:01.513Z,1554772741.513 [CBIT](FAULT): Main Battery Failure. Count: 281
2019-04-09T01:19:01.886Z,1554772741.886 [BR_Ping1D](INFO): Starting
2019-04-09T01:19:01.886Z,1554772741.886 [BR_Ping1D](INFO): readConfig
2019-04-09T01:19:01.886Z,1554772741.886 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:19:01.887Z,1554772741.887 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:19:01.887Z,1554772741.887 [BR_Ping1D](INFO): UART requesting status
2019-04-09T01:19:02.283Z,1554772742.283 [BR_Ping1D](INFO): Starting
2019-04-09T01:19:02.283Z,1554772742.283 [BR_Ping1D](INFO): readConfig
2019-04-09T01:19:02.283Z,1554772742.283 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:19:02.284Z,1554772742.284 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:19:02.284Z,1554772742.284 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:19:02.284Z,1554772742.284 [BR_Ping1D](INFO): elapsed1.2083
2019-04-09T01:19:02.284Z,1554772742.284 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:19:02.284Z,1554772742.284 [BR_Ping1D](INFO): No UART data
2019-04-09T01:19:02.700Z,1554772742.700 [BR_Ping1D](INFO): Starting
2019-04-09T01:19:02.701Z,1554772742.701 [BR_Ping1D](INFO): readConfig
2019-04-09T01:19:02.701Z,1554772742.701 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:19:02.701Z,1554772742.701 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:19:02.701Z,1554772742.701 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:19:02.701Z,1554772742.701 [BR_Ping1D](INFO): elapsed1.6258
2019-04-09T01:19:02.702Z,1554772742.702 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:19:02.702Z,1554772742.702 [BR_Ping1D](INFO): No UART data
2019-04-09T01:19:03.104Z,1554772743.104 [BR_Ping1D](INFO): Starting
2019-04-09T01:19:03.105Z,1554772743.105 [BR_Ping1D](INFO): readConfig
2019-04-09T01:19:03.105Z,1554772743.105 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:19:03.105Z,1554772743.105 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:19:03.105Z,1554772743.105 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:19:03.105Z,1554772743.105 [BR_Ping1D](INFO): elapsed2.0297
2019-04-09T01:19:03.106Z,1554772743.106 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:19:03.106Z,1554772743.106 [BR_Ping1D](INFO): No UART data
2019-04-09T01:19:03.478Z,1554772743.478 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T01:19:03.478Z,1554772743.478 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 5
2019-04-09T01:19:03.479Z,1554772743.479 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T01:19:03.509Z,1554772743.509 [BR_Ping1D](INFO): Starting
2019-04-09T01:19:03.509Z,1554772743.509 [BR_Ping1D](INFO): readConfig
2019-04-09T01:19:03.509Z,1554772743.509 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:19:03.509Z,1554772743.509 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:19:03.509Z,1554772743.509 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:19:03.510Z,1554772743.510 [BR_Ping1D](INFO): elapsed2.4340
2019-04-09T01:19:03.510Z,1554772743.510 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:19:03.510Z,1554772743.510 [BR_Ping1D](INFO): No UART data
2019-04-09T01:19:03.532Z,1554772743.532 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:19:03.883Z,1554772743.883 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:19:04.037Z,1554772744.037 [BR_Ping1D](INFO): Starting
2019-04-09T01:19:04.037Z,1554772744.037 [BR_Ping1D](INFO): readConfig
2019-04-09T01:19:04.037Z,1554772744.037 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:19:04.038Z,1554772744.038 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:19:04.038Z,1554772744.038 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:19:04.039Z,1554772744.039 [BR_Ping1D](INFO): elapsed2.9630
2019-04-09T01:19:04.039Z,1554772744.039 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:19:04.039Z,1554772744.039 [BR_Ping1D](INFO): No UART data
2019-04-09T01:19:04.310Z,1554772744.310 [BR_Ping1D](INFO): Starting
2019-04-09T01:19:04.311Z,1554772744.311 [BR_Ping1D](INFO): readConfig
2019-04-09T01:19:04.311Z,1554772744.311 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:19:04.311Z,1554772744.311 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:19:04.311Z,1554772744.311 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:19:04.311Z,1554772744.311 [BR_Ping1D](INFO): elapsed3.2357
2019-04-09T01:19:04.312Z,1554772744.312 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:19:04.312Z,1554772744.312 [BR_Ping1D](INFO): No UART data
2019-04-09T01:19:04.709Z,1554772744.709 [BR_Ping1D](INFO): Starting
2019-04-09T01:19:04.709Z,1554772744.709 [BR_Ping1D](INFO): readConfig
2019-04-09T01:19:04.710Z,1554772744.710 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:19:04.710Z,1554772744.710 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:19:04.710Z,1554772744.710 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:19:04.710Z,1554772744.710 [BR_Ping1D](INFO): elapsed3.6347
2019-04-09T01:19:04.711Z,1554772744.711 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:19:04.711Z,1554772744.711 [BR_Ping1D](INFO): No UART data
2019-04-09T01:19:04.778Z,1554772744.778 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T01:19:04.778Z,1554772744.778 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 5
2019-04-09T01:19:05.114Z,1554772745.114 [BR_Ping1D](INFO): Starting
2019-04-09T01:19:05.114Z,1554772745.114 [BR_Ping1D](INFO): readConfig
2019-04-09T01:19:05.115Z,1554772745.115 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:19:05.115Z,1554772745.115 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:19:05.115Z,1554772745.115 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:19:05.115Z,1554772745.115 [BR_Ping1D](INFO): elapsed4.0394
2019-04-09T01:19:05.115Z,1554772745.115 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:19:05.115Z,1554772745.115 [BR_Ping1D](INFO): No UART data
2019-04-09T01:19:05.518Z,1554772745.518 [BR_Ping1D](INFO): Starting
2019-04-09T01:19:05.518Z,1554772745.518 [BR_Ping1D](INFO): readConfig
2019-04-09T01:19:05.518Z,1554772745.518 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:19:05.519Z,1554772745.519 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:19:05.519Z,1554772745.519 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:19:05.519Z,1554772745.519 [BR_Ping1D](INFO): elapsed4.4433
2019-04-09T01:19:05.519Z,1554772745.519 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:19:05.519Z,1554772745.519 [BR_Ping1D](INFO): No UART data
2019-04-09T01:19:05.531Z,1554772745.531 [CBIT](FAULT): Main Battery Failure. Count: 291
2019-04-09T01:19:05.922Z,1554772745.922 [BR_Ping1D](INFO): Starting
2019-04-09T01:19:05.922Z,1554772745.922 [BR_Ping1D](INFO): readConfig
2019-04-09T01:19:05.922Z,1554772745.922 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:19:05.922Z,1554772745.922 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:19:05.922Z,1554772745.922 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:19:05.923Z,1554772745.923 [BR_Ping1D](INFO): elapsed4.8470
2019-04-09T01:19:05.923Z,1554772745.923 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:19:05.923Z,1554772745.923 [BR_Ping1D](INFO): No UART data
2019-04-09T01:19:06.326Z,1554772746.326 [BR_Ping1D](INFO): Starting
2019-04-09T01:19:06.326Z,1554772746.326 [BR_Ping1D](INFO): readConfig
2019-04-09T01:19:06.326Z,1554772746.326 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:19:06.326Z,1554772746.326 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:19:06.327Z,1554772746.327 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:19:06.327Z,1554772746.327 [BR_Ping1D](INFO): elapsed5.2511
2019-04-09T01:19:06.327Z,1554772746.327 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:19:06.327Z,1554772746.327 [BR_Ping1D] Data Fault, FailCount= 6
2019-04-09T01:19:06.327Z,1554772746.327 [BR_Ping1D](ERROR): Data Fault
2019-04-09T01:19:06.348Z,1554772746.348 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T01:19:06.348Z,1554772746.348 [CBIT](CRITICAL): Data Fault in component: BR_Ping1D
2019-04-09T01:19:06.738Z,1554772746.738 [BR_Ping1D](INFO): Stop
2019-04-09T01:19:06.738Z,1554772746.738 [BR_Ping1D](INFO): uninitialize
2019-04-09T01:19:06.830Z,1554772746.830 [CBIT](INFO): Critical error at 20190409T011906
2019-04-09T01:19:07.115Z,1554772747.115 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T01:19:07.115Z,1554772747.115 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T01:19:07.154Z,1554772747.154 [BR_Ping1D](INFO): Stopping
2019-04-09T01:19:07.539Z,1554772747.539 [BR_Ping1D](INFO): Stopping
2019-04-09T01:19:07.545Z,1554772747.545 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T01:19:07.545Z,1554772747.545 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-04-09T01:19:07.545Z,1554772747.545 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-09T01:19:07.547Z,1554772747.547 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T01:19:07.547Z,1554772747.547 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-04-09T01:19:07.547Z,1554772747.547 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-09T01:19:07.548Z,1554772747.548 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T01:19:07.549Z,1554772747.549 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-04-09T01:19:07.549Z,1554772747.549 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-09T01:19:07.559Z,1554772747.559 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-09T01:19:07.560Z,1554772747.560 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-09T01:19:07.560Z,1554772747.560 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-09T01:19:07.955Z,1554772747.955 [BR_Ping1D](INFO): Stopped
2019-04-09T01:19:07.975Z,1554772747.975 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-09T01:19:07.975Z,1554772747.975 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-04-09T01:19:07.976Z,1554772747.976 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-09T01:19:07.976Z,1554772747.976 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-04-09T01:19:07.976Z,1554772747.976 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-09T01:19:07.976Z,1554772747.976 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-04-09T01:19:08.369Z,1554772748.369 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T01:19:09.569Z,1554772749.569 [CBIT](FAULT): Main Battery Failure. Count: 301
2019-04-09T01:19:13.624Z,1554772753.624 [CBIT](FAULT): Main Battery Failure. Count: 311
2019-04-09T01:19:17.654Z,1554772757.654 [CBIT](FAULT): Main Battery Failure. Count: 321
2019-04-09T01:19:21.695Z,1554772761.695 [CBIT](FAULT): Main Battery Failure. Count: 331
2019-04-09T01:19:25.733Z,1554772765.733 [CBIT](FAULT): Main Battery Failure. Count: 341
2019-04-09T01:19:26.535Z,1554772766.535 [Startup:StartupSatComms:A](INFO): Timed out from 2019-04-09T01:18:26.4Z
2019-04-09T01:19:26.535Z,1554772766.535 [Startup:StartupSatComms:A] Stopped
2019-04-09T01:19:26.535Z,1554772766.535 [Startup:StartupSatComms:B] Running Loop=1
2019-04-09T01:19:26.940Z,1554772766.940 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-04-09T01:19:27.314Z,1554772767.314 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T01:19:27.315Z,1554772767.315 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 6
2019-04-09T01:19:27.315Z,1554772767.315 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T01:19:27.346Z,1554772767.346 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:19:27.718Z,1554772767.718 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:19:28.568Z,1554772768.568 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T01:19:28.568Z,1554772768.568 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 6
2019-04-09T01:19:29.784Z,1554772769.784 [CBIT](FAULT): Main Battery Failure. Count: 351
2019-04-09T01:19:30.950Z,1554772770.950 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T01:19:30.951Z,1554772770.951 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T01:19:33.813Z,1554772773.813 [CBIT](FAULT): Main Battery Failure. Count: 361
2019-04-09T01:19:37.854Z,1554772777.854 [CBIT](FAULT): Main Battery Failure. Count: 371
2019-04-09T01:19:41.894Z,1554772781.894 [CBIT](FAULT): Main Battery Failure. Count: 381
2019-04-09T01:19:45.947Z,1554772785.947 [CBIT](FAULT): Main Battery Failure. Count: 391
2019-04-09T01:19:46.648Z,1554772786.648 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004761
2019-04-09T01:19:49.996Z,1554772789.996 [CBIT](FAULT): Main Battery Failure. Count: 401
2019-04-09T01:19:51.150Z,1554772791.150 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T01:19:51.150Z,1554772791.150 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 7
2019-04-09T01:19:51.151Z,1554772791.151 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T01:19:51.200Z,1554772791.200 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:19:51.318Z,1554772791.318 [DataOverHttps](INFO): Sending 79 bytes from file Logs/20190409T011026/Courier0004.lzma
2019-04-09T01:19:51.554Z,1554772791.554 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:19:52.124Z,1554772792.124 [DataOverHttps](INFO): Moved sent file to Logs/20190409T011026/Courier0004.lzma.bak
2019-04-09T01:19:52.124Z,1554772792.124 [DataOverHttps](INFO): SBD MOMSN=10492932
2019-04-09T01:19:52.404Z,1554772792.404 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T01:19:52.404Z,1554772792.404 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 7
2019-04-09T01:19:54.025Z,1554772794.025 [CBIT](FAULT): Main Battery Failure. Count: 411
2019-04-09T01:19:54.787Z,1554772794.787 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T01:19:54.787Z,1554772794.787 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T01:19:58.047Z,1554772798.047 [CBIT](FAULT): Main Battery Failure. Count: 421
2019-04-09T01:20:02.110Z,1554772802.110 [CBIT](FAULT): Main Battery Failure. Count: 431
2019-04-09T01:20:03.478Z,1554772803.478 [DataOverHttps](INFO): Sending 367 bytes from file Logs/20190409T011657/Courier0000.lzma
2019-04-09T01:20:04.283Z,1554772804.283 [DataOverHttps](INFO): Moved sent file to Logs/20190409T011657/Courier0000.lzma.bak
2019-04-09T01:20:04.284Z,1554772804.284 [DataOverHttps](INFO): SBD MOMSN=10492935
2019-04-09T01:20:06.140Z,1554772806.140 [CBIT](FAULT): Main Battery Failure. Count: 441
2019-04-09T01:20:10.167Z,1554772810.167 [CBIT](FAULT): Main Battery Failure. Count: 451
2019-04-09T01:20:11.802Z,1554772811.802 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T01:20:11.802Z,1554772811.802 [Aanderaa_O2] No Fault, FailCount= 3
2019-04-09T01:20:14.217Z,1554772814.217 [CBIT](FAULT): Main Battery Failure. Count: 461
2019-04-09T01:20:14.986Z,1554772814.986 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T01:20:14.987Z,1554772814.987 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 8
2019-04-09T01:20:14.987Z,1554772814.987 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T01:20:15.028Z,1554772815.028 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:20:15.029Z,1554772815.029 [CBIT](FAULT): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:20:15.390Z,1554772815.390 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:20:17.855Z,1554772817.855 [DataOverHttps](INFO): Sending 1102 bytes from file Logs/20190409T005229/Express0008.lzma
2019-04-09T01:20:18.249Z,1554772818.249 [CBIT](FAULT): Main Battery Failure. Count: 471
2019-04-09T01:20:18.640Z,1554772818.640 [DataOverHttps](INFO): Moved sent file to Logs/20190409T005229/Express0008.lzma.bak
2019-04-09T01:20:18.640Z,1554772818.640 [DataOverHttps](INFO): SBD MOMSN=10492951
2019-04-09T01:20:20.280Z,1554772820.280 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T01:20:20.280Z,1554772820.280 [CTD_Seabird] No Fault, FailCount= 3
2019-04-09T01:20:20.322Z,1554772820.322 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T01:20:20.324Z,1554772820.324 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T01:20:22.258Z,1554772822.258 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T01:20:22.258Z,1554772822.258 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-09T01:20:22.259Z,1554772822.259 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T01:20:22.300Z,1554772822.300 [CBIT](FAULT): Main Battery Failure. Count: 481
2019-04-09T01:20:22.302Z,1554772822.302 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T01:20:22.782Z,1554772822.782 [Aanderaa_O2](INFO): Powering down
2019-04-09T01:20:23.521Z,1554772823.521 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T01:20:23.521Z,1554772823.521 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-09T01:20:26.335Z,1554772826.335 [CBIT](FAULT): Main Battery Failure. Count: 491
2019-04-09T01:20:26.751Z,1554772826.751 [Startup:StartupSatComms:B](INFO): Timed out from 2019-04-09T01:19:26.5Z
2019-04-09T01:20:26.751Z,1554772826.751 [Startup:StartupSatComms:B] Stopped
2019-04-09T01:20:26.752Z,1554772826.752 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-04-09T01:20:26.752Z,1554772826.752 [Startup:StartupSatComms] Stopped
2019-04-09T01:20:26.752Z,1554772826.752 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-04-09T01:20:26.753Z,1554772826.753 [Startup](INFO): Completed Startup
2019-04-09T01:20:26.753Z,1554772826.753 [MissionManager](INFO): Startup is completed.
2019-04-09T01:20:26.753Z,1554772826.753 [MissionManager](INFO): Uninitializing Mission Startup
2019-04-09T01:20:26.753Z,1554772826.753 [Startup] Stopped
2019-04-09T01:20:26.753Z,1554772826.753 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-04-09T01:20:26.753Z,1554772826.753 [Startup:A.GoToSurface] Stopped
2019-04-09T01:20:26.753Z,1554772826.753 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-09T01:20:27.143Z,1554772827.143 [MissionManager](IMPORTANT): Started mission Default
2019-04-09T01:20:27.143Z,1554772827.143 [Default] Running Loop=1
2019-04-09T01:20:27.144Z,1554772827.144 [Default](DEBUG): Aggregate::initialize Default
2019-04-09T01:20:27.144Z,1554772827.144 [Default:B.GoToSurface] Running Loop=1
2019-04-09T01:20:27.144Z,1554772827.144 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-09T01:20:27.144Z,1554772827.144 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-09T01:20:27.144Z,1554772827.144 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-09T01:20:27.145Z,1554772827.145 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-09T01:20:27.145Z,1554772827.145 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-09T01:20:27.145Z,1554772827.145 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-09T01:20:27.146Z,1554772827.146 [Default:A.Wait] Running Loop=1
2019-04-09T01:20:27.146Z,1554772827.146 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-04-09T01:20:29.980Z,1554772829.980 [DataOverHttps](INFO): Sending 1356 bytes from file Logs/20190409T010736/Express0001.lzma
2019-04-09T01:20:30.377Z,1554772830.377 [CBIT](FAULT): Main Battery Failure. Count: 501
2019-04-09T01:20:30.752Z,1554772830.752 [DataOverHttps](INFO): Moved sent file to Logs/20190409T010736/Express0001.lzma.bak
2019-04-09T01:20:30.752Z,1554772830.752 [DataOverHttps](INFO): SBD MOMSN=10493087
2019-04-09T01:20:33.374Z,1554772833.374 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T01:20:33.374Z,1554772833.374 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-09T01:20:33.374Z,1554772833.374 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T01:20:33.639Z,1554772833.639 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T01:20:33.778Z,1554772833.778 [CTD_Seabird](INFO): Powering down
2019-04-09T01:20:33.974Z,1554772833.974 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T01:20:33.974Z,1554772833.974 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-09T01:20:33.975Z,1554772833.975 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T01:20:34.048Z,1554772834.048 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T01:20:34.498Z,1554772834.498 [Aanderaa_O2](INFO): Powering down
2019-04-09T01:20:34.565Z,1554772834.565 [CBIT](FAULT): Main Battery Failure. Count: 511
2019-04-09T01:20:34.828Z,1554772834.828 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T01:20:34.829Z,1554772834.829 [CTD_Seabird] No Fault, FailCount= 1
2019-04-09T01:20:35.122Z,1554772835.122 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T01:20:35.124Z,1554772835.124 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T01:20:35.219Z,1554772835.219 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T01:20:35.219Z,1554772835.219 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-09T01:20:38.469Z,1554772838.469 [CBIT](FAULT): Main Battery Failure. Count: 521
2019-04-09T01:20:40.461Z,1554772840.461 [Default:A.Wait](INFO): Done Waiting.
2019-04-09T01:20:40.461Z,1554772840.461 [Default:A.Wait] Stopped
2019-04-09T01:20:40.461Z,1554772840.461 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-04-09T01:20:40.868Z,1554772840.868 [Default:CheckIn] Running Loop=1
2019-04-09T01:20:40.868Z,1554772840.868 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-04-09T01:20:40.868Z,1554772840.868 [Default:CheckIn:Read_GPS] Running Loop=1
2019-04-09T01:20:41.279Z,1554772841.279 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-04-09T01:20:42.505Z,1554772842.505 [CBIT](FAULT): Main Battery Failure. Count: 531
2019-04-09T01:20:45.690Z,1554772845.690 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T01:20:45.690Z,1554772845.690 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-09T01:20:45.690Z,1554772845.690 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T01:20:45.728Z,1554772845.728 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T01:20:45.728Z,1554772845.728 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-09T01:20:46.214Z,1554772846.214 [Aanderaa_O2](INFO): Powering down
2019-04-09T01:20:46.530Z,1554772846.530 [CBIT](FAULT): Main Battery Failure. Count: 541
2019-04-09T01:20:48.174Z,1554772848.174 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T01:20:48.174Z,1554772848.174 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-09T01:20:48.174Z,1554772848.174 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T01:20:48.563Z,1554772848.563 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T01:20:48.578Z,1554772848.578 [CTD_Seabird](INFO): Powering down
2019-04-09T01:20:49.768Z,1554772849.768 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T01:20:49.768Z,1554772849.768 [CTD_Seabird] No Fault, FailCount= 2
2019-04-09T01:20:49.923Z,1554772849.923 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T01:20:49.925Z,1554772849.925 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T01:20:50.577Z,1554772850.577 [CBIT](FAULT): Main Battery Failure. Count: 551
2019-04-09T01:20:54.607Z,1554772854.607 [CBIT](FAULT): Main Battery Failure. Count: 561
2019-04-09T01:20:58.666Z,1554772858.666 [CBIT](FAULT): Main Battery Failure. Count: 571
2019-04-09T01:21:02.693Z,1554772862.693 [CBIT](FAULT): Main Battery Failure. Count: 581
2019-04-09T01:21:02.974Z,1554772862.974 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T01:21:02.974Z,1554772862.974 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-09T01:21:02.974Z,1554772862.974 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T01:21:03.128Z,1554772863.128 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T01:21:03.128Z,1554772863.128 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-09T01:21:03.378Z,1554772863.378 [CTD_Seabird](INFO): Powering down
2019-04-09T01:21:06.734Z,1554772866.734 [CBIT](FAULT): Main Battery Failure. Count: 591
2019-04-09T01:21:08.756Z,1554772868.756 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T01:21:08.756Z,1554772868.756 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-04-09T01:21:08.756Z,1554772868.756 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-09T01:21:08.758Z,1554772868.758 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T01:21:08.758Z,1554772868.758 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-04-09T01:21:08.758Z,1554772868.758 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-09T01:21:08.759Z,1554772868.759 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T01:21:08.759Z,1554772868.759 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2019-04-09T01:21:08.759Z,1554772868.759 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-09T01:21:08.799Z,1554772868.799 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-09T01:21:08.799Z,1554772868.799 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-09T01:21:08.799Z,1554772868.799 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-09T01:21:09.165Z,1554772869.165 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-09T01:21:09.165Z,1554772869.165 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-04-09T01:21:09.166Z,1554772869.166 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-09T01:21:09.166Z,1554772869.166 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-04-09T01:21:09.167Z,1554772869.167 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-09T01:21:09.167Z,1554772869.167 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2019-04-09T01:21:09.565Z,1554772869.565 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T01:21:10.776Z,1554772870.776 [CBIT](FAULT): Main Battery Failure. Count: 601
2019-04-09T01:21:14.827Z,1554772874.827 [CBIT](FAULT): Main Battery Failure. Count: 611
2019-04-09T01:21:18.846Z,1554772878.846 [CBIT](FAULT): Main Battery Failure. Count: 621
2019-04-09T01:21:22.894Z,1554772882.894 [CBIT](FAULT): Main Battery Failure. Count: 631
2019-04-09T01:21:26.933Z,1554772886.933 [CBIT](FAULT): Main Battery Failure. Count: 641
2019-04-09T01:21:30.980Z,1554772890.980 [CBIT](FAULT): Main Battery Failure. Count: 651
2019-04-09T01:21:35.028Z,1554772895.028 [CBIT](FAULT): Main Battery Failure. Count: 661
2019-04-09T01:21:39.053Z,1554772899.053 [CBIT](FAULT): Main Battery Failure. Count: 671
2019-04-09T01:21:43.087Z,1554772903.087 [CBIT](FAULT): Main Battery Failure. Count: 681
2019-04-09T01:21:47.127Z,1554772907.127 [CBIT](FAULT): Main Battery Failure. Count: 691
2019-04-09T01:21:51.184Z,1554772911.184 [CBIT](FAULT): Main Battery Failure. Count: 701
2019-04-09T01:21:55.210Z,1554772915.210 [CBIT](FAULT): Main Battery Failure. Count: 711
2019-04-09T01:21:59.253Z,1554772919.253 [CBIT](FAULT): Main Battery Failure. Count: 721
2019-04-09T01:22:03.342Z,1554772923.342 [CBIT](FAULT): Main Battery Failure. Count: 731
2019-04-09T01:22:07.340Z,1554772927.340 [CBIT](FAULT): Main Battery Failure. Count: 741
2019-04-09T01:22:08.527Z,1554772928.527 [BPC1](FAULT): Failed to initialize
2019-04-09T01:22:08.527Z,1554772928.527 [BPC1] Communications Fault, FailCount= 1
2019-04-09T01:22:08.527Z,1554772928.527 [BPC1](ERROR): Communications Fault
2019-04-09T01:22:08.542Z,1554772928.542 [CBIT](ERROR): Communications Fault in component: BPC1
2019-04-09T01:22:11.366Z,1554772931.366 [CBIT](FAULT): Main Battery Failure. Count: 751
2019-04-09T01:22:15.413Z,1554772935.413 [CBIT](FAULT): Main Battery Failure. Count: 761
2019-04-09T01:22:19.449Z,1554772939.449 [CBIT](FAULT): Main Battery Failure. Count: 771
2019-04-09T01:22:23.512Z,1554772943.512 [CBIT](FAULT): Main Battery Failure. Count: 781
2019-04-09T01:22:25.922Z,1554772945.922 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T01:22:25.922Z,1554772945.922 [MassServo] No Fault, FailCount= 5
2019-04-09T01:22:26.776Z,1554772946.776 [CommandLine](IMPORTANT): got command restart application
2019-04-09T01:22:27.782Z,1554772947.782 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:22:27.782Z,1554772947.782 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:22:27.806Z,1554772947.806 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-04-09T01:22:27.806Z,1554772947.806 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:22:27.807Z,1554772947.807 [CommandLine](INFO): Join timeout helper Thread ID is 5635
2019-04-09T01:22:27.807Z,1554772947.807 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-04-09T01:22:27.807Z,1554772947.807 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:22:27.808Z,1554772947.808 [NavChartDb](INFO): Join timeout helper Thread ID is 5636
2019-04-09T01:22:27.846Z,1554772947.846 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T01:22:27.846Z,1554772947.846 [MassServo] Communications Fault, FailCount= 1
2019-04-09T01:22:27.846Z,1554772947.846 [MassServo](ERROR): Communications Fault
2019-04-09T01:22:27.848Z,1554772947.848 [CBIT](FAULT): Main Battery Failure. Count: 791
2019-04-09T01:22:27.850Z,1554772947.850 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T01:22:27.894Z,1554772947.894 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:22:27.894Z,1554772947.894 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:22:27.906Z,1554772947.906 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-04-09T01:22:27.906Z,1554772947.906 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:22:27.906Z,1554772947.906 [Radio_Surface](INFO): Join timeout helper Thread ID is 5637
2019-04-09T01:22:27.942Z,1554772947.942 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T01:22:27.943Z,1554772947.943 [MassServo](INFO): Powering down
2019-04-09T01:22:28.050Z,1554772948.050 [Radio_Surface](INFO): Powering down
2019-04-09T01:22:28.051Z,1554772948.051 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:22:28.051Z,1554772948.051 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:22:28.054Z,1554772948.054 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-04-09T01:22:28.054Z,1554772948.054 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:22:28.055Z,1554772948.055 [DataOverHttps](INFO): Join timeout helper Thread ID is 5638
2019-04-09T01:22:28.134Z,1554772948.134 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:22:28.134Z,1554772948.134 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:22:28.154Z,1554772948.154 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-04-09T01:22:28.154Z,1554772948.154 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:22:28.154Z,1554772948.154 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5639
2019-04-09T01:22:28.390Z,1554772948.390 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:22:28.391Z,1554772948.391 [WetLabsBB2FL](INFO): Powering down
2019-04-09T01:22:28.391Z,1554772948.391 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:22:28.407Z,1554772948.407 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-04-09T01:22:28.407Z,1554772948.407 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:22:28.408Z,1554772948.408 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5640
2019-04-09T01:22:28.758Z,1554772948.758 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:22:28.758Z,1554772948.758 [CTD_Seabird](INFO): Powering down
2019-04-09T01:22:28.760Z,1554772948.760 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:22:28.770Z,1554772948.770 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:22:28.783Z,1554772948.783 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-04-09T01:22:28.783Z,1554772948.783 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:22:28.783Z,1554772948.783 [logger](INFO): Join timeout helper Thread ID is 5641
2019-04-09T01:22:28.806Z,1554772948.806 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:22:28.806Z,1554772948.806 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:22:28.823Z,1554772948.823 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-04-09T01:22:28.823Z,1554772948.823 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:22:28.823Z,1554772948.823 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-04-09T01:22:28.823Z,1554772948.823 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:22:28.823Z,1554772948.823 [controlThread](INFO): Join timeout helper Thread ID is 5642
2019-04-09T01:22:28.879Z,1554772948.879 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T01:22:28.883Z,1554772948.883 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T01:22:28.883Z,1554772948.883 [MassServo] No Fault, FailCount= 1
2019-04-09T01:22:28.885Z,1554772948.885 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:22:28.885Z,1554772948.885 [controlThread](DEBUG): Uninitializing ControlThread
2019-04-09T01:22:28.885Z,1554772948.885 [Aanderaa_O2](INFO): Powering down
2019-04-09T01:22:28.888Z,1554772948.888 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:22:28.889Z,1554772948.889 [NAL9602](INFO): Powering down
2019-04-09T01:22:28.890Z,1554772948.890 [BR_Ping1D](INFO): uninitialize
2019-04-09T01:22:28.891Z,1554772948.891 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-04-09T01:22:28.892Z,1554772948.892 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-04-09T01:22:28.893Z,1554772948.893 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-04-09T01:22:28.894Z,1554772948.894 [MissionManager](INFO): Uninitializing Mission Default
2019-04-09T01:22:28.894Z,1554772948.894 [Default] Stopped
2019-04-09T01:22:28.894Z,1554772948.894 [Default](DEBUG): Aggregate::uninitialize Default
2019-04-09T01:22:28.894Z,1554772948.894 [Default:B.GoToSurface] Stopped
2019-04-09T01:22:28.894Z,1554772948.894 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-09T01:22:28.894Z,1554772948.894 [Default:CheckIn] Stopped
2019-04-09T01:22:28.894Z,1554772948.894 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-04-09T01:22:28.894Z,1554772948.894 [Default:CheckIn:Read_GPS] Stopped
2019-04-09T01:22:28.897Z,1554772948.897 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-04-09T01:22:28.897Z,1554772948.897 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-04-09T01:22:28.897Z,1554772948.897 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-04-09T01:22:28.898Z,1554772948.898 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-04-09T01:22:28.898Z,1554772948.898 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-04-09T01:22:28.898Z,1554772948.898 [BuoyancyServo](INFO): Powering down
2019-04-09T01:22:28.910Z,1554772948.910 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-04-09T01:22:28.910Z,1554772948.910 [ElevatorServo](INFO): Powering down
2019-04-09T01:22:28.911Z,1554772948.911 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T01:22:28.911Z,1554772948.911 [MassServo](INFO): Powering down
2019-04-09T01:22:28.912Z,1554772948.912 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-09T01:22:28.912Z,1554772948.912 [RudderServo](INFO): Powering down
2019-04-09T01:22:28.913Z,1554772948.913 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-04-09T01:22:28.913Z,1554772948.913 [ThrusterServo](INFO): Powering down
2019-04-09T01:22:28.914Z,1554772948.914 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-04-09T01:22:28.914Z,1554772948.914 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-04-09T01:22:28.914Z,1554772948.914 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-04-09T01:22:28.914Z,1554772948.914 [CBIT](DEBUG): Powering off loads.
2019-04-09T01:22:28.926Z,1554772948.926 [CBIT](DEBUG): Disabling WDT.
2019-04-09T01:22:28.938Z,1554772948.938 [CBIT](DEBUG): Opening all GF detection circuits.
2019-04-09T01:22:28.939Z,1554772948.939 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:22:29.085Z,1554772949.085 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:22:29.090Z,1554772949.090 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:22:29.159Z,1554772949.159 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:22:29.164Z,1554772949.164 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:22:29.215Z,1554772949.215 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:22:29.274Z,1554772949.274 [logger ThreadHandler](INFO): Thread cancelled.