2019-04-09T01:22:30.154Z,1554772950.154 [Supervisor](DEBUG): Initializing supervisor.
2019-04-09T01:22:30.157Z,1554772950.157 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-04-09T01:22:30.158Z,1554772950.158 [SyncHandler](INFO): Protected caller Thread ID is 5644
2019-04-09T01:22:30.158Z,1554772950.158 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-04-09T01:22:30.159Z,1554772950.159 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-04-09T01:22:30.160Z,1554772950.160 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5645
2019-04-09T01:22:30.163Z,1554772950.163 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-04-09T01:22:30.175Z,1554772950.175 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-04-09T01:22:30.176Z,1554772950.176 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-04-09T01:22:30.177Z,1554772950.177 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5646
2019-04-09T01:22:30.177Z,1554772950.177 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-04-09T01:22:30.178Z,1554772950.178 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-04-09T01:22:30.179Z,1554772950.179 [logger ThreadHandler](INFO): Protected caller Thread ID is 5647
2019-04-09T01:22:30.181Z,1554772950.181 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-04-09T01:22:30.181Z,1554772950.181 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-04-09T01:22:30.183Z,1554772950.183 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-04-09T01:22:30.277Z,1554772950.277 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-04-09T01:22:30.277Z,1554772950.277 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-04-09T01:22:30.478Z,1554772950.478 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-04-09T01:22:30.478Z,1554772950.478 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-04-09T01:22:30.614Z,1554772950.614 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-04-09T01:22:30.615Z,1554772950.615 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-04-09T01:22:31.073Z,1554772951.073 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-04-09T01:22:31.074Z,1554772951.074 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-04-09T01:22:31.516Z,1554772951.516 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-04-09T01:22:31.516Z,1554772951.516 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-04-09T01:22:31.985Z,1554772951.985 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-04-09T01:22:31.986Z,1554772951.986 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-04-09T01:22:32.302Z,1554772952.302 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-04-09T01:22:32.303Z,1554772952.303 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-04-09T01:22:32.913Z,1554772952.913 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-04-09T01:22:32.913Z,1554772952.913 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-04-09T01:22:33.309Z,1554772953.309 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-04-09T01:22:33.309Z,1554772953.309 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-04-09T01:22:33.458Z,1554772953.458 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-04-09T01:22:33.459Z,1554772953.459 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-04-09T01:22:33.564Z,1554772953.564 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-04-09T01:22:33.564Z,1554772953.564 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-04-09T01:22:33.644Z,1554772953.644 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-04-09T01:22:33.745Z,1554772953.745 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-04-09T01:22:33.746Z,1554772953.746 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-04-09T01:22:33.939Z,1554772953.939 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-04-09T01:22:33.939Z,1554772953.939 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-04-09T01:22:34.146Z,1554772954.146 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-04-09T01:22:34.148Z,1554772954.148 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2019-04-09T01:22:34.149Z,1554772954.149 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2019-04-09T01:22:34.231Z,1554772954.231 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2019-04-09T01:22:34.463Z,1554772954.463 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-04-09T01:22:34.464Z,1554772954.464 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2019-04-09T01:22:34.558Z,1554772954.558 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2019-04-09T01:22:34.731Z,1554772954.731 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2019-04-09T01:22:34.944Z,1554772954.944 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2019-04-09T01:22:35.028Z,1554772955.028 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2019-04-09T01:22:35.126Z,1554772955.126 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2019-04-09T01:22:35.217Z,1554772955.217 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2019-04-09T01:22:35.368Z,1554772955.368 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2019-04-09T01:22:35.471Z,1554772955.471 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2019-04-09T01:22:35.569Z,1554772955.569 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2019-04-09T01:22:35.569Z,1554772955.569 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-04-09T01:22:35.573Z,1554772955.573 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-04-09T01:22:35.673Z,1554772955.673 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-04-09T01:22:35.673Z,1554772955.673 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-04-09T01:22:35.801Z,1554772955.801 [BuoyancyServo] Loaded
2019-04-09T01:22:35.801Z,1554772955.801 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-04-09T01:22:35.816Z,1554772955.816 [ElevatorServo] Loaded
2019-04-09T01:22:35.817Z,1554772955.817 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-04-09T01:22:35.832Z,1554772955.832 [MassServo] Loaded
2019-04-09T01:22:35.832Z,1554772955.832 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-04-09T01:22:35.847Z,1554772955.847 [RudderServo] Loaded
2019-04-09T01:22:35.848Z,1554772955.848 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-04-09T01:22:35.862Z,1554772955.862 [ThrusterServo] Loaded
2019-04-09T01:22:35.862Z,1554772955.862 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-04-09T01:22:35.863Z,1554772955.863 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-04-09T01:22:35.863Z,1554772955.863 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-04-09T01:22:35.999Z,1554772955.999 [SBIT](DEBUG): Construct Startup Built In Test.
2019-04-09T01:22:36.028Z,1554772956.028 [SBIT] Loaded
2019-04-09T01:22:36.028Z,1554772956.028 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-04-09T01:22:36.029Z,1554772956.029 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-04-09T01:22:36.057Z,1554772956.057 [IBIT] Loaded
2019-04-09T01:22:36.057Z,1554772956.057 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-04-09T01:22:36.060Z,1554772956.060 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-04-09T01:22:36.450Z,1554772956.450 [CBIT] Loaded
2019-04-09T01:22:36.450Z,1554772956.450 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-04-09T01:22:36.451Z,1554772956.451 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-04-09T01:22:36.451Z,1554772956.451 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-04-09T01:22:36.634Z,1554772956.634 [Aanderaa_O2] Loaded
2019-04-09T01:22:36.634Z,1554772956.634 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-04-09T01:22:36.644Z,1554772956.644 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-04-09T01:22:36.650Z,1554772956.650 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-04-09T01:22:36.651Z,1554772956.651 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-04-09T01:22:36.656Z,1554772956.656 [CTD_Seabird](INFO): created writer for : depth
2019-04-09T01:22:36.657Z,1554772956.657 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-04-09T01:22:36.662Z,1554772956.662 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-04-09T01:22:36.663Z,1554772956.663 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-04-09T01:22:36.668Z,1554772956.668 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-04-09T01:22:36.668Z,1554772956.668 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-04-09T01:22:36.674Z,1554772956.674 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-04-09T01:22:36.675Z,1554772956.675 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-04-09T01:22:36.680Z,1554772956.680 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-04-09T01:22:36.681Z,1554772956.681 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-04-09T01:22:36.686Z,1554772956.686 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-04-09T01:22:36.712Z,1554772956.712 [CTD_Seabird] Loaded
2019-04-09T01:22:36.713Z,1554772956.713 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-04-09T01:22:36.714Z,1554772956.714 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406654E0
2019-04-09T01:22:36.715Z,1554772956.715 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 5726
2019-04-09T01:22:36.740Z,1554772956.740 [ESPComponent] Loaded
2019-04-09T01:22:36.741Z,1554772956.741 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-04-09T01:22:36.755Z,1554772956.755 [PAR_Licor] Loaded
2019-04-09T01:22:36.755Z,1554772956.755 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-04-09T01:22:36.803Z,1554772956.803 [WetLabsBB2FL] Loaded
2019-04-09T01:22:36.803Z,1554772956.803 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-04-09T01:22:36.804Z,1554772956.804 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406954E0
2019-04-09T01:22:36.805Z,1554772956.805 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5727
2019-04-09T01:22:36.805Z,1554772956.805 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-04-09T01:22:36.806Z,1554772956.806 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-04-09T01:22:36.847Z,1554772956.847 [DepthRateCalculator] Loaded
2019-04-09T01:22:36.847Z,1554772956.847 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-04-09T01:22:36.853Z,1554772956.853 [PitchRateCalculator] Loaded
2019-04-09T01:22:36.853Z,1554772956.853 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-04-09T01:22:36.869Z,1554772956.869 [SpeedCalculator] Loaded
2019-04-09T01:22:36.870Z,1554772956.870 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-04-09T01:22:36.891Z,1554772956.891 [TempGradientCalculator] Loaded
2019-04-09T01:22:36.891Z,1554772956.891 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-04-09T01:22:36.897Z,1554772956.897 [YawRateCalculator] Loaded
2019-04-09T01:22:36.898Z,1554772956.898 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-04-09T01:22:36.931Z,1554772956.931 [ElevatorOffsetCalculator] Loaded
2019-04-09T01:22:36.931Z,1554772956.931 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-04-09T01:22:36.931Z,1554772956.931 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-04-09T01:22:36.932Z,1554772956.932 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-04-09T01:22:36.959Z,1554772956.959 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-04-09T01:22:36.959Z,1554772956.959 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-04-09T01:22:37.062Z,1554772957.062 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-04-09T01:22:37.062Z,1554772957.062 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-04-09T01:22:37.122Z,1554772957.122 [DeadReckonUsingSpeedCalculator] Loaded
2019-04-09T01:22:37.123Z,1554772957.123 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-04-09T01:22:37.183Z,1554772957.183 [DeadReckonWithRespectToSeafloor] Loaded
2019-04-09T01:22:37.183Z,1554772957.183 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-04-09T01:22:37.204Z,1554772957.204 [NavChart] Loaded
2019-04-09T01:22:37.204Z,1554772957.204 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-04-09T01:22:37.208Z,1554772957.208 [UniversalFixResidualReporter] Loaded
2019-04-09T01:22:37.209Z,1554772957.209 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-04-09T01:22:37.209Z,1554772957.209 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-04-09T01:22:37.210Z,1554772957.210 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-04-09T01:22:37.564Z,1554772957.564 [AcousticModem_Benthos_ATM900] Loaded
2019-04-09T01:22:37.564Z,1554772957.564 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2019-04-09T01:22:37.698Z,1554772957.698 [DataOverHttps] Loaded
2019-04-09T01:22:37.698Z,1554772957.698 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-04-09T01:22:37.700Z,1554772957.700 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409704E0
2019-04-09T01:22:37.700Z,1554772957.700 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5728
2019-04-09T01:22:37.762Z,1554772957.762 [Depth_Keller] Loaded
2019-04-09T01:22:37.762Z,1554772957.762 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-04-09T01:22:37.942Z,1554772957.942 [NAL9602] Loaded
2019-04-09T01:22:37.943Z,1554772957.943 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-04-09T01:22:37.969Z,1554772957.969 [Onboard] Loaded
2019-04-09T01:22:37.969Z,1554772957.969 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-04-09T01:22:37.973Z,1554772957.973 [Radio_Surface] Loaded
2019-04-09T01:22:37.973Z,1554772957.973 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-04-09T01:22:37.974Z,1554772957.974 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409A04E0
2019-04-09T01:22:37.974Z,1554772957.974 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5729
2019-04-09T01:22:39.400Z,1554772959.400 [BPC1] Loaded
2019-04-09T01:22:39.400Z,1554772959.400 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-04-09T01:22:39.403Z,1554772959.403 [BR_Ping1D](INFO): constructor
2019-04-09T01:22:39.431Z,1554772959.431 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minrange
2019-04-09T01:22:39.433Z,1554772959.433 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.maxrange
2019-04-09T01:22:39.435Z,1554772959.435 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.soundspeed
2019-04-09T01:22:39.437Z,1554772959.437 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.automode
2019-04-09T01:22:39.440Z,1554772959.440 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.gainindex
2019-04-09T01:22:39.442Z,1554772959.442 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minconfidence
2019-04-09T01:22:39.444Z,1554772959.444 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.pingenable
2019-04-09T01:22:39.446Z,1554772959.446 [BR_Ping1D] Loaded
2019-04-09T01:22:39.446Z,1554772959.446 [ComponentRegistry](DEBUG): SyncComponent "BR_Ping1D" handled in the control thread.
2019-04-09T01:22:39.447Z,1554772959.447 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-04-09T01:22:39.447Z,1554772959.447 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-04-09T01:22:39.512Z,1554772959.512 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-04-09T01:22:39.512Z,1554772959.512 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-04-09T01:22:39.578Z,1554772959.578 [VerticalControl](DEBUG): Construct VerticalControl.
2019-04-09T01:22:39.664Z,1554772959.664 [VerticalControl] Loaded
2019-04-09T01:22:39.664Z,1554772959.664 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-04-09T01:22:39.665Z,1554772959.665 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-04-09T01:22:39.723Z,1554772959.723 [HorizontalControl] Loaded
2019-04-09T01:22:39.723Z,1554772959.723 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-04-09T01:22:39.724Z,1554772959.724 [SpeedControl](DEBUG): Construct SpeedControl.
2019-04-09T01:22:39.725Z,1554772959.725 [SpeedControl] Loaded
2019-04-09T01:22:39.726Z,1554772959.726 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-04-09T01:22:39.726Z,1554772959.726 [LoopControl](DEBUG): Construct LoopControl.
2019-04-09T01:22:39.727Z,1554772959.727 [LoopControl] Loaded
2019-04-09T01:22:39.727Z,1554772959.727 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-04-09T01:22:39.728Z,1554772959.728 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-04-09T01:22:39.728Z,1554772959.728 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-04-09T01:22:39.742Z,1554772959.742 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-04-09T01:22:39.742Z,1554772959.742 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-04-09T01:22:40.018Z,1554772960.018 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-04-09T01:22:40.022Z,1554772960.022 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-04-09T01:22:40.023Z,1554772960.023 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-04-09T01:22:40.030Z,1554772960.030 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-04-09T01:22:40.031Z,1554772960.031 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACD4E0
2019-04-09T01:22:40.031Z,1554772960.031 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5730
2019-04-09T01:22:40.036Z,1554772960.036 [Supervisor](INFO): Main Thread ID is 4347
2019-04-09T01:22:40.036Z,1554772960.036 [Supervisor](DEBUG): Running supervisor.
2019-04-09T01:22:40.037Z,1554772960.037 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5731
2019-04-09T01:22:40.039Z,1554772960.039 [controlThread ThreadHandler](INFO): Handler Thread ID is 5732
2019-04-09T01:22:40.040Z,1554772960.040 [controlThread](DEBUG): Initializing ControlThread
2019-04-09T01:22:40.041Z,1554772960.041 [SBIT](INFO): Initialize SBIT Component.
2019-04-09T01:22:40.042Z,1554772960.042 [SBIT](IMPORTANT): git: 2019-03-18-31-ge98d5dc
2019-04-09T01:22:40.042Z,1554772960.042 [SBIT](INFO): git hash: e98d5dc50cbc39e402f19b9946ff4dc0776c8725
2019-04-09T01:22:40.042Z,1554772960.042 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-04-09T01:22:40.044Z,1554772960.044 [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:22:40.045Z,1554772960.045 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2019-04-09T01:22:40.045Z,1554772960.045 [IBIT](INFO): Initialize IBIT Component.
2019-04-09T01:22:40.046Z,1554772960.046 [CBIT](DEBUG): Initialize CBIT Component.
2019-04-09T01:22:40.047Z,1554772960.047 [logger ThreadHandler](INFO): Handler Thread ID is 5733
2019-04-09T01:22:40.058Z,1554772960.058 [CBIT](DEBUG): Initialized mux pins.
2019-04-09T01:22:40.058Z,1554772960.058 [CBIT](DEBUG): Initializing the watchdog timer.
2019-04-09T01:22:40.066Z,1554772960.066 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 5734
2019-04-09T01:22:40.067Z,1554772960.067 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T01:22:40.071Z,1554772960.071 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T01:22:40.072Z,1554772960.072 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5736
2019-04-09T01:22:40.073Z,1554772960.073 [WetLabsBB2FL](INFO): Powering down
2019-04-09T01:22:40.082Z,1554772960.082 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-04-09T01:22:40.083Z,1554772960.083 [CBIT](DEBUG): Initializing heartbeat.
2019-04-09T01:22:40.099Z,1554772960.099 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5737
2019-04-09T01:22:40.100Z,1554772960.100 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-04-09T01:22:40.118Z,1554772960.118 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5738
2019-04-09T01:22:40.130Z,1554772960.130 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5739
2019-04-09T01:22:40.133Z,1554772960.133 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-04-09T01:22:40.133Z,1554772960.133 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-04-09T01:22:40.134Z,1554772960.134 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-04-09T01:22:40.134Z,1554772960.134 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-04-09T01:22:40.134Z,1554772960.134 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-04-09T01:22:40.134Z,1554772960.134 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-04-09T01:22:40.135Z,1554772960.135 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-04-09T01:22:40.135Z,1554772960.135 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-04-09T01:22:40.135Z,1554772960.135 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-04-09T01:22:40.135Z,1554772960.135 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-04-09T01:22:40.135Z,1554772960.135 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-04-09T01:22:40.135Z,1554772960.135 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-04-09T01:22:40.136Z,1554772960.136 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-04-09T01:22:40.136Z,1554772960.136 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-04-09T01:22:40.136Z,1554772960.136 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-04-09T01:22:40.136Z,1554772960.136 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-04-09T01:22:40.154Z,1554772960.154 [CBIT](DEBUG): Deactivating GF circuits.
2019-04-09T01:22:40.154Z,1554772960.154 [CBIT](DEBUG): Deactivating emergency mode.
2019-04-09T01:22:40.190Z,1554772960.190 [CBIT](DEBUG): Backplane powered.
2019-04-09T01:22:40.194Z,1554772960.194 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-04-09T01:22:40.195Z,1554772960.195 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-04-09T01:22:40.195Z,1554772960.195 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-04-09T01:22:40.195Z,1554772960.195 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-04-09T01:22:40.196Z,1554772960.196 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-04-09T01:22:40.196Z,1554772960.196 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-04-09T01:22:40.197Z,1554772960.197 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T01:22:40.198Z,1554772960.198 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-04-09T01:22:40.199Z,1554772960.199 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-04-09T01:22:40.203Z,1554772960.203 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-04-09T01:22:40.205Z,1554772960.205 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-04-09T01:22:40.206Z,1554772960.206 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-04-09T01:22:40.207Z,1554772960.207 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-04-09T01:22:40.207Z,1554772960.207 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-04-09T01:22:40.238Z,1554772960.238 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-09T01:22:40.274Z,1554772960.274 [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:22:40.275Z,1554772960.275 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-04-09T01:22:40.363Z,1554772960.363 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-04-09T01:22:40.364Z,1554772960.364 [Default:A.Wait](DEBUG): Construct Wait.
2019-04-09T01:22:40.366Z,1554772960.366 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-09T01:22:40.385Z,1554772960.385 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-04-09T01:22:40.416Z,1554772960.416 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-04-09T01:22:40.421Z,1554772960.421 [Default:E.Execute](DEBUG): Construct Execute.
2019-04-09T01:22:40.459Z,1554772960.459 [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:22:40.464Z,1554772960.464 [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:22:40.495Z,1554772960.495 [ESPComponent](INFO): powering down ESP
2019-04-09T01:22:40.522Z,1554772960.522 [Radio_Surface](INFO): Powering up
2019-04-09T01:22:40.938Z,1554772960.938 [PAR_Licor](INFO): A/D timeout, 8 tries over 132 ms

2019-04-09T01:22:40.939Z,1554772960.939 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T01:22:40.939Z,1554772960.939 [PAR_Licor](ERROR): Data Fault
2019-04-09T01:22:40.940Z,1554772960.940 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T01:22:40.940Z,1554772960.940 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T01:22:40.963Z,1554772960.963 [Depth_Keller](ERROR): Pressure reading out of range: 1895.684326 decibar
2019-04-09T01:22:41.007Z,1554772961.007 [BR_Ping1D](INFO): Start
2019-04-09T01:22:41.011Z,1554772961.011 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-04-09T01:22:41.058Z,1554772961.058 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:22:41.066Z,1554772961.066 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-04-09T01:22:41.067Z,1554772961.067 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:22:41.074Z,1554772961.074 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-04-09T01:22:41.075Z,1554772961.075 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:22:41.082Z,1554772961.082 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T01:22:41.083Z,1554772961.083 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:22:41.090Z,1554772961.090 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-09T01:22:41.091Z,1554772961.091 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:22:41.098Z,1554772961.098 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-04-09T01:22:41.100Z,1554772961.100 [CommandLine](FAULT): Scheduling is paused
2019-04-09T01:22:41.101Z,1554772961.101 [CBIT](INFO): Critical error at 20190409T012239
2019-04-09T01:22:41.101Z,1554772961.101 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-04-09T01:22:41.102Z,1554772961.102 [CBIT](FAULT): Main Battery Failure. Count: 1
2019-04-09T01:22:41.106Z,1554772961.106 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T01:22:41.106Z,1554772961.106 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T01:22:41.106Z,1554772961.106 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T01:22:41.220Z,1554772961.220 [BR_Ping1D](INFO): Starting
2019-04-09T01:22:41.650Z,1554772961.650 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms

2019-04-09T01:22:41.650Z,1554772961.650 [PAR_Licor] Data Fault, FailCount= 2
2019-04-09T01:22:41.650Z,1554772961.650 [PAR_Licor](ERROR): Data Fault
2019-04-09T01:22:41.690Z,1554772961.690 [BR_Ping1D](INFO): Starting
2019-04-09T01:22:41.691Z,1554772961.691 [BR_Ping1D](INFO): readConfig
2019-04-09T01:22:41.691Z,1554772961.691 [BR_Ping1D](INFO): XXXX automode1: 1
2019-04-09T01:22:41.691Z,1554772961.691 [BR_Ping1D](INFO): XXXX automode2: 1
2019-04-09T01:22:41.691Z,1554772961.691 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T01:22:41.702Z,1554772961.702 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T01:22:41.702Z,1554772961.702 [BR_Ping1D](INFO): packet size:10
2019-04-09T01:22:42.038Z,1554772962.038 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-04-09T01:22:42.038Z,1554772962.038 [RudderServo](FAULT): Rudder failed to initialize
2019-04-09T01:22:42.038Z,1554772962.038 [RudderServo] Communications Fault, FailCount= 1
2019-04-09T01:22:42.038Z,1554772962.038 [RudderServo](ERROR): Communications Fault
2019-04-09T01:22:42.149Z,1554772962.149 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-04-09T01:22:42.150Z,1554772962.150 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T01:22:42.150Z,1554772962.150 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T01:22:42.150Z,1554772962.150 [PAR_Licor] No Fault, FailCount= 2
2019-04-09T01:22:42.193Z,1554772962.193 [BR_Ping1D](INFO): Starting
2019-04-09T01:22:42.193Z,1554772962.193 [BR_Ping1D](INFO): readConfig
2019-04-09T01:22:42.194Z,1554772962.194 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:22:42.194Z,1554772962.194 [BR_Ping1D](INFO): XXXX automode2: 1
2019-04-09T01:22:42.194Z,1554772962.194 [BR_Ping1D](INFO): UART requesting status
2019-04-09T01:22:42.194Z,1554772962.194 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:22:42.195Z,1554772962.195 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:22:42.196Z,1554772962.196 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:22:42.379Z,1554772962.379 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-09T01:22:42.379Z,1554772962.379 [RudderServo](INFO): Powering down
2019-04-09T01:22:42.575Z,1554772962.575 [BR_Ping1D](INFO): Starting
2019-04-09T01:22:42.576Z,1554772962.576 [BR_Ping1D](INFO): readConfig
2019-04-09T01:22:42.576Z,1554772962.576 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:22:42.576Z,1554772962.576 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:22:42.576Z,1554772962.576 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:22:42.576Z,1554772962.576 [BR_Ping1D](INFO): elapsed0.8850
2019-04-09T01:22:42.577Z,1554772962.577 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:22:42.577Z,1554772962.577 [BR_Ping1D](INFO): No UART data
2019-04-09T01:22:43.102Z,1554772963.102 [PAR_Licor](INFO): A/D timeout, 9 tries over 138 ms

2019-04-09T01:22:43.102Z,1554772963.102 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T01:22:43.102Z,1554772963.102 [PAR_Licor](ERROR): Data Fault
2019-04-09T01:22:43.123Z,1554772963.123 [BR_Ping1D](INFO): Starting
2019-04-09T01:22:43.123Z,1554772963.123 [BR_Ping1D](INFO): readConfig
2019-04-09T01:22:43.123Z,1554772963.123 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:22:43.123Z,1554772963.123 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:22:43.123Z,1554772963.123 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:22:43.124Z,1554772963.124 [BR_Ping1D](INFO): elapsed1.4322
2019-04-09T01:22:43.124Z,1554772963.124 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:22:43.124Z,1554772963.124 [BR_Ping1D](INFO): No UART data
2019-04-09T01:22:43.151Z,1554772963.151 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:22:43.271Z,1554772963.271 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-09T01:22:43.275Z,1554772963.275 [CBIT](INFO): Clearing failed state for component RudderServo
2019-04-09T01:22:43.275Z,1554772963.275 [RudderServo] No Fault, FailCount= 1
2019-04-09T01:22:43.276Z,1554772963.276 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T01:22:43.276Z,1554772963.276 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T01:22:43.276Z,1554772963.276 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T01:22:43.389Z,1554772963.389 [BR_Ping1D](INFO): Starting
2019-04-09T01:22:43.389Z,1554772963.389 [BR_Ping1D](INFO): readConfig
2019-04-09T01:22:43.390Z,1554772963.390 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:22:43.390Z,1554772963.390 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:22:43.390Z,1554772963.390 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:22:43.390Z,1554772963.390 [BR_Ping1D](INFO): elapsed1.6989
2019-04-09T01:22:43.390Z,1554772963.390 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:22:43.391Z,1554772963.391 [BR_Ping1D](INFO): No UART data
2019-04-09T01:22:43.750Z,1554772963.750 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T01:22:43.750Z,1554772963.750 [MassServo] Communications Fault, FailCount= 1
2019-04-09T01:22:43.750Z,1554772963.750 [MassServo](ERROR): Communications Fault
2019-04-09T01:22:43.777Z,1554772963.777 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T01:22:43.825Z,1554772963.825 [BR_Ping1D](INFO): Starting
2019-04-09T01:22:43.825Z,1554772963.825 [BR_Ping1D](INFO): readConfig
2019-04-09T01:22:43.825Z,1554772963.825 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:22:43.825Z,1554772963.825 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:22:43.826Z,1554772963.826 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:22:43.826Z,1554772963.826 [BR_Ping1D](INFO): elapsed2.1345
2019-04-09T01:22:43.826Z,1554772963.826 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:22:43.826Z,1554772963.826 [BR_Ping1D](INFO): No UART data
2019-04-09T01:22:43.926Z,1554772963.926 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T01:22:43.926Z,1554772963.926 [MassServo](INFO): Powering down
2019-04-09T01:22:44.209Z,1554772964.209 [BR_Ping1D](INFO): Starting
2019-04-09T01:22:44.209Z,1554772964.209 [BR_Ping1D](INFO): readConfig
2019-04-09T01:22:44.209Z,1554772964.209 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:22:44.210Z,1554772964.210 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:22:44.210Z,1554772964.210 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:22:44.210Z,1554772964.210 [BR_Ping1D](INFO): elapsed2.5187
2019-04-09T01:22:44.210Z,1554772964.210 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:22:44.210Z,1554772964.210 [BR_Ping1D](INFO): No UART data
2019-04-09T01:22:44.718Z,1554772964.718 [PAR_Licor](INFO): A/D timeout, 10 tries over 127 ms

2019-04-09T01:22:44.718Z,1554772964.718 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T01:22:44.718Z,1554772964.718 [PAR_Licor](ERROR): Data Fault
2019-04-09T01:22:44.757Z,1554772964.757 [BR_Ping1D](INFO): Starting
2019-04-09T01:22:44.757Z,1554772964.757 [BR_Ping1D](INFO): readConfig
2019-04-09T01:22:44.757Z,1554772964.757 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:22:44.757Z,1554772964.757 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:22:44.758Z,1554772964.758 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:22:44.758Z,1554772964.758 [BR_Ping1D](INFO): elapsed3.0666
2019-04-09T01:22:44.758Z,1554772964.758 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:22:44.758Z,1554772964.758 [BR_Ping1D](INFO): No UART data
2019-04-09T01:22:44.844Z,1554772964.844 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:22:44.963Z,1554772964.963 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T01:22:45.106Z,1554772965.106 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T01:22:45.106Z,1554772965.106 [MassServo] No Fault, FailCount= 1
2019-04-09T01:22:45.106Z,1554772965.106 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T01:22:45.107Z,1554772965.107 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T01:22:45.107Z,1554772965.107 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T01:22:45.173Z,1554772965.173 [BR_Ping1D](INFO): Starting
2019-04-09T01:22:45.173Z,1554772965.173 [BR_Ping1D](INFO): readConfig
2019-04-09T01:22:45.173Z,1554772965.173 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:22:45.174Z,1554772965.174 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:22:45.174Z,1554772965.174 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:22:45.174Z,1554772965.174 [BR_Ping1D](INFO): elapsed3.4826
2019-04-09T01:22:45.174Z,1554772965.174 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:22:45.174Z,1554772965.174 [BR_Ping1D](INFO): No UART data
2019-04-09T01:22:45.246Z,1554772965.246 [CBIT](FAULT): Main Battery Failure. Count: 11
2019-04-09T01:22:45.554Z,1554772965.554 [BR_Ping1D](INFO): Starting
2019-04-09T01:22:45.554Z,1554772965.554 [BR_Ping1D](INFO): readConfig
2019-04-09T01:22:45.554Z,1554772965.554 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:22:45.554Z,1554772965.554 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:22:45.554Z,1554772965.554 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:22:45.555Z,1554772965.555 [BR_Ping1D](INFO): elapsed3.8634
2019-04-09T01:22:45.555Z,1554772965.555 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:22:45.555Z,1554772965.555 [BR_Ping1D](INFO): No UART data
2019-04-09T01:22:46.054Z,1554772966.054 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms

2019-04-09T01:22:46.054Z,1554772966.054 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T01:22:46.054Z,1554772966.054 [PAR_Licor](ERROR): Data Fault
2019-04-09T01:22:46.098Z,1554772966.098 [BR_Ping1D](INFO): Starting
2019-04-09T01:22:46.098Z,1554772966.098 [BR_Ping1D](INFO): readConfig
2019-04-09T01:22:46.098Z,1554772966.098 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:22:46.098Z,1554772966.098 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:22:46.098Z,1554772966.098 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:22:46.099Z,1554772966.099 [BR_Ping1D](INFO): elapsed4.4072
2019-04-09T01:22:46.099Z,1554772966.099 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:22:46.099Z,1554772966.099 [BR_Ping1D](INFO): No UART data
2019-04-09T01:22:46.174Z,1554772966.174 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T01:22:46.175Z,1554772966.175 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T01:22:46.175Z,1554772966.175 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T01:22:46.346Z,1554772966.346 [BR_Ping1D](INFO): Starting
2019-04-09T01:22:46.346Z,1554772966.346 [BR_Ping1D](INFO): readConfig
2019-04-09T01:22:46.347Z,1554772966.347 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:22:46.347Z,1554772966.347 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:22:46.347Z,1554772966.347 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:22:46.347Z,1554772966.347 [BR_Ping1D](INFO): elapsed4.6558
2019-04-09T01:22:46.347Z,1554772966.347 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:22:46.347Z,1554772966.347 [BR_Ping1D](INFO): No UART data
2019-04-09T01:22:46.870Z,1554772966.870 [PAR_Licor](INFO): A/D timeout, 8 tries over 142 ms

2019-04-09T01:22:46.870Z,1554772966.870 [PAR_Licor] Data Fault, FailCount= 2
2019-04-09T01:22:46.870Z,1554772966.870 [PAR_Licor](ERROR): Data Fault
2019-04-09T01:22:46.905Z,1554772966.905 [BR_Ping1D](INFO): Starting
2019-04-09T01:22:46.905Z,1554772966.905 [BR_Ping1D](INFO): readConfig
2019-04-09T01:22:46.906Z,1554772966.906 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:22:46.906Z,1554772966.906 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:22:46.906Z,1554772966.906 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:22:46.906Z,1554772966.906 [BR_Ping1D](INFO): elapsed5.2150
2019-04-09T01:22:46.907Z,1554772966.907 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:22:46.907Z,1554772966.907 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-09T01:22:46.907Z,1554772966.907 [BR_Ping1D](ERROR): Data Fault
2019-04-09T01:22:47.250Z,1554772967.250 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T01:22:47.250Z,1554772967.250 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T01:22:47.250Z,1554772967.250 [PAR_Licor] No Fault, FailCount= 2
2019-04-09T01:22:47.251Z,1554772967.251 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T01:22:47.286Z,1554772967.286 [BR_Ping1D](INFO): Stop
2019-04-09T01:22:47.287Z,1554772967.287 [BR_Ping1D](INFO): uninitialize
2019-04-09T01:22:47.754Z,1554772967.754 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T01:22:47.754Z,1554772967.754 [MassServo] Communications Fault, FailCount= 2
2019-04-09T01:22:47.754Z,1554772967.754 [MassServo](ERROR): Communications Fault
2019-04-09T01:22:47.758Z,1554772967.758 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T01:22:47.906Z,1554772967.906 [PAR_Licor](INFO): A/D timeout, 7 tries over 141 ms

2019-04-09T01:22:47.906Z,1554772967.906 [PAR_Licor] Data Fault, FailCount= 3
2019-04-09T01:22:47.906Z,1554772967.906 [PAR_Licor](ERROR): Data Fault
2019-04-09T01:22:47.941Z,1554772967.941 [BR_Ping1D](INFO): Stopping
2019-04-09T01:22:47.971Z,1554772967.971 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T01:22:47.971Z,1554772967.971 [MassServo](INFO): Powering down
2019-04-09T01:22:47.975Z,1554772967.975 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T01:22:47.976Z,1554772967.976 [CBIT](CRITICAL): Data Fault in component: PAR_Licor
2019-04-09T01:22:48.186Z,1554772968.186 [BR_Ping1D](INFO): Stopped
2019-04-09T01:22:48.220Z,1554772968.220 [CBIT](INFO): Critical error at 20190409T012247
2019-04-09T01:22:48.223Z,1554772968.223 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T01:22:48.223Z,1554772968.223 [BR_Ping1D] No Fault, FailCount= 1
2019-04-09T01:22:48.590Z,1554772968.590 [BR_Ping1D](INFO): Start
2019-04-09T01:22:48.624Z,1554772968.624 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:22:48.743Z,1554772968.743 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T01:22:48.748Z,1554772968.748 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T01:22:48.748Z,1554772968.748 [MassServo] No Fault, FailCount= 2
2019-04-09T01:22:48.994Z,1554772968.994 [BR_Ping1D](INFO): Starting
2019-04-09T01:22:49.398Z,1554772969.398 [BR_Ping1D](INFO): Starting
2019-04-09T01:22:49.398Z,1554772969.398 [BR_Ping1D](INFO): readConfig
2019-04-09T01:22:49.398Z,1554772969.398 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:22:49.398Z,1554772969.398 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:22:49.398Z,1554772969.398 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T01:22:49.411Z,1554772969.411 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T01:22:49.411Z,1554772969.411 [BR_Ping1D](INFO): packet size:10
2019-04-09T01:22:49.424Z,1554772969.424 [CBIT](FAULT): Main Battery Failure. Count: 21
2019-04-09T01:22:49.802Z,1554772969.802 [BR_Ping1D](INFO): Starting
2019-04-09T01:22:50.206Z,1554772970.206 [BR_Ping1D](INFO): Starting
2019-04-09T01:22:50.206Z,1554772970.206 [BR_Ping1D](INFO): readConfig
2019-04-09T01:22:50.206Z,1554772970.206 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:22:50.206Z,1554772970.206 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:22:50.207Z,1554772970.207 [BR_Ping1D](INFO): UART requesting status
2019-04-09T01:22:50.207Z,1554772970.207 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:22:50.207Z,1554772970.207 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:22:50.207Z,1554772970.207 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:22:50.242Z,1554772970.242 [CBIT](CRITICAL): Environmental Failure. Press:14.847911 PSI. Humidity:45%. Temp:26 C. ABORTING MISSION
2019-04-09T01:22:50.590Z,1554772970.590 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T01:22:50.590Z,1554772970.590 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-09T01:22:50.591Z,1554772970.591 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T01:22:50.610Z,1554772970.610 [BR_Ping1D](INFO): Starting
2019-04-09T01:22:50.610Z,1554772970.610 [BR_Ping1D](INFO): readConfig
2019-04-09T01:22:50.611Z,1554772970.611 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:22:50.611Z,1554772970.611 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:22:50.611Z,1554772970.611 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:22:50.611Z,1554772970.611 [BR_Ping1D](INFO): elapsed1.2125
2019-04-09T01:22:50.611Z,1554772970.611 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:22:50.612Z,1554772970.612 [BR_Ping1D](INFO): No UART data
2019-04-09T01:22:50.625Z,1554772970.625 [CBIT](INFO): Critical error at 20190409T012250
2019-04-09T01:22:50.628Z,1554772970.628 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T01:22:51.110Z,1554772971.110 [Aanderaa_O2](INFO): Powering down
2019-04-09T01:22:51.169Z,1554772971.169 [BR_Ping1D](INFO): Starting
2019-04-09T01:22:51.169Z,1554772971.169 [BR_Ping1D](INFO): readConfig
2019-04-09T01:22:51.169Z,1554772971.169 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:22:51.169Z,1554772971.169 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:22:51.169Z,1554772971.169 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:22:51.170Z,1554772971.170 [BR_Ping1D](INFO): elapsed1.7710
2019-04-09T01:22:51.170Z,1554772971.170 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:22:51.170Z,1554772971.170 [BR_Ping1D](INFO): No UART data
2019-04-09T01:22:51.518Z,1554772971.518 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T01:22:51.518Z,1554772971.518 [MassServo] Communications Fault, FailCount= 3
2019-04-09T01:22:51.518Z,1554772971.518 [MassServo](ERROR): Communications Fault
2019-04-09T01:22:51.522Z,1554772971.522 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T01:22:51.551Z,1554772971.551 [BR_Ping1D](INFO): Starting
2019-04-09T01:22:51.551Z,1554772971.551 [BR_Ping1D](INFO): readConfig
2019-04-09T01:22:51.551Z,1554772971.551 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:22:51.551Z,1554772971.551 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:22:51.551Z,1554772971.551 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:22:51.552Z,1554772971.552 [BR_Ping1D](INFO): elapsed2.1529
2019-04-09T01:22:51.552Z,1554772971.552 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:22:51.552Z,1554772971.552 [BR_Ping1D](INFO): No UART data
2019-04-09T01:22:51.571Z,1554772971.571 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T01:22:51.571Z,1554772971.571 [MassServo](INFO): Powering down
2019-04-09T01:22:51.949Z,1554772971.949 [BR_Ping1D](INFO): Starting
2019-04-09T01:22:51.949Z,1554772971.949 [BR_Ping1D](INFO): readConfig
2019-04-09T01:22:51.950Z,1554772971.950 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:22:51.950Z,1554772971.950 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:22:51.950Z,1554772971.950 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:22:51.950Z,1554772971.950 [BR_Ping1D](INFO): elapsed2.5516
2019-04-09T01:22:51.950Z,1554772971.950 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:22:51.951Z,1554772971.951 [BR_Ping1D](INFO): No UART data
2019-04-09T01:22:51.983Z,1554772971.983 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T01:22:51.983Z,1554772971.983 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-09T01:22:52.354Z,1554772972.354 [BR_Ping1D](INFO): Starting
2019-04-09T01:22:52.354Z,1554772972.354 [BR_Ping1D](INFO): readConfig
2019-04-09T01:22:52.355Z,1554772972.355 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:22:52.355Z,1554772972.355 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:22:52.355Z,1554772972.355 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:22:52.355Z,1554772972.355 [BR_Ping1D](INFO): elapsed2.9566
2019-04-09T01:22:52.355Z,1554772972.355 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:22:52.356Z,1554772972.356 [BR_Ping1D](INFO): No UART data
2019-04-09T01:22:52.375Z,1554772972.375 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:22:52.495Z,1554772972.495 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T01:22:52.499Z,1554772972.499 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T01:22:52.499Z,1554772972.499 [MassServo] No Fault, FailCount= 3
2019-04-09T01:22:52.769Z,1554772972.769 [BR_Ping1D](INFO): Starting
2019-04-09T01:22:52.769Z,1554772972.769 [BR_Ping1D](INFO): readConfig
2019-04-09T01:22:52.770Z,1554772972.770 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:22:52.770Z,1554772972.770 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:22:52.770Z,1554772972.770 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:22:52.770Z,1554772972.770 [BR_Ping1D](INFO): elapsed3.3716
2019-04-09T01:22:52.770Z,1554772972.770 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:22:52.771Z,1554772972.771 [BR_Ping1D](INFO): No UART data
2019-04-09T01:22:53.126Z,1554772973.126 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T01:22:53.126Z,1554772973.126 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-09T01:22:53.126Z,1554772973.126 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T01:22:53.162Z,1554772973.162 [BR_Ping1D](INFO): Starting
2019-04-09T01:22:53.162Z,1554772973.162 [BR_Ping1D](INFO): readConfig
2019-04-09T01:22:53.162Z,1554772973.162 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:22:53.162Z,1554772973.162 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:22:53.162Z,1554772973.162 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:22:53.163Z,1554772973.163 [BR_Ping1D](INFO): elapsed3.7640
2019-04-09T01:22:53.163Z,1554772973.163 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:22:53.163Z,1554772973.163 [BR_Ping1D](INFO): No UART data
2019-04-09T01:22:53.240Z,1554772973.240 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T01:22:53.530Z,1554772973.530 [CTD_Seabird](INFO): Powering down
2019-04-09T01:22:53.566Z,1554772973.566 [BR_Ping1D](INFO): Starting
2019-04-09T01:22:53.566Z,1554772973.566 [BR_Ping1D](INFO): readConfig
2019-04-09T01:22:53.567Z,1554772973.567 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:22:53.567Z,1554772973.567 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:22:53.567Z,1554772973.567 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:22:53.567Z,1554772973.567 [BR_Ping1D](INFO): elapsed4.1686
2019-04-09T01:22:53.567Z,1554772973.567 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:22:53.568Z,1554772973.568 [BR_Ping1D](INFO): No UART data
2019-04-09T01:22:53.600Z,1554772973.600 [CBIT](FAULT): Main Battery Failure. Count: 31
2019-04-09T01:22:53.970Z,1554772973.970 [BR_Ping1D](INFO): Starting
2019-04-09T01:22:53.970Z,1554772973.970 [BR_Ping1D](INFO): readConfig
2019-04-09T01:22:53.970Z,1554772973.970 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:22:53.970Z,1554772973.970 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:22:53.971Z,1554772973.971 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:22:53.971Z,1554772973.971 [BR_Ping1D](INFO): elapsed4.5721
2019-04-09T01:22:53.971Z,1554772973.971 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:22:53.971Z,1554772973.971 [BR_Ping1D](INFO): No UART data
2019-04-09T01:22:54.374Z,1554772974.374 [BR_Ping1D](INFO): Starting
2019-04-09T01:22:54.374Z,1554772974.374 [BR_Ping1D](INFO): readConfig
2019-04-09T01:22:54.374Z,1554772974.374 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:22:54.374Z,1554772974.374 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:22:54.374Z,1554772974.374 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:22:54.375Z,1554772974.375 [BR_Ping1D](INFO): elapsed4.9760
2019-04-09T01:22:54.375Z,1554772974.375 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:22:54.375Z,1554772974.375 [BR_Ping1D](INFO): No UART data
2019-04-09T01:22:54.777Z,1554772974.777 [BR_Ping1D](INFO): Starting
2019-04-09T01:22:54.777Z,1554772974.777 [BR_Ping1D](INFO): readConfig
2019-04-09T01:22:54.778Z,1554772974.778 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:22:54.778Z,1554772974.778 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:22:54.778Z,1554772974.778 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:22:54.778Z,1554772974.778 [BR_Ping1D](INFO): elapsed5.3795
2019-04-09T01:22:54.778Z,1554772974.778 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:22:54.778Z,1554772974.778 [BR_Ping1D] Data Fault, FailCount= 2
2019-04-09T01:22:54.779Z,1554772974.779 [BR_Ping1D](ERROR): Data Fault
2019-04-09T01:22:55.098Z,1554772975.098 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T01:22:55.098Z,1554772975.098 [MassServo] Communications Fault, FailCount= 4
2019-04-09T01:22:55.098Z,1554772975.098 [MassServo](ERROR): Communications Fault
2019-04-09T01:22:55.102Z,1554772975.102 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T01:22:55.103Z,1554772975.103 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T01:22:55.103Z,1554772975.103 [CTD_Seabird] No Fault, FailCount= 1
2019-04-09T01:22:55.103Z,1554772975.103 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T01:22:55.181Z,1554772975.181 [BR_Ping1D](INFO): Stop
2019-04-09T01:22:55.181Z,1554772975.181 [BR_Ping1D](INFO): uninitialize
2019-04-09T01:22:55.267Z,1554772975.267 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T01:22:55.267Z,1554772975.267 [MassServo](INFO): Powering down
2019-04-09T01:22:55.390Z,1554772975.390 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T01:22:55.392Z,1554772975.392 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T01:22:55.586Z,1554772975.586 [BR_Ping1D](INFO): Stopping
2019-04-09T01:22:55.990Z,1554772975.990 [BR_Ping1D](INFO): Stopping
2019-04-09T01:22:56.003Z,1554772976.003 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:22:56.123Z,1554772976.123 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T01:22:56.127Z,1554772976.127 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T01:22:56.127Z,1554772976.127 [MassServo] No Fault, FailCount= 4
2019-04-09T01:22:56.394Z,1554772976.394 [BR_Ping1D](INFO): Stopped
2019-04-09T01:22:56.427Z,1554772976.427 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T01:22:56.427Z,1554772976.427 [BR_Ping1D] No Fault, FailCount= 2
2019-04-09T01:22:56.796Z,1554772976.796 [BR_Ping1D](INFO): Start
2019-04-09T01:22:57.202Z,1554772977.202 [BR_Ping1D](INFO): Starting
2019-04-09T01:22:57.605Z,1554772977.605 [BR_Ping1D](INFO): Starting
2019-04-09T01:22:57.605Z,1554772977.605 [BR_Ping1D](INFO): readConfig
2019-04-09T01:22:57.606Z,1554772977.606 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:22:57.606Z,1554772977.606 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:22:57.606Z,1554772977.606 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T01:22:57.618Z,1554772977.618 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T01:22:57.618Z,1554772977.618 [BR_Ping1D](INFO): packet size:10
2019-04-09T01:22:57.631Z,1554772977.631 [CBIT](FAULT): Main Battery Failure. Count: 41
2019-04-09T01:22:58.010Z,1554772978.010 [BR_Ping1D](INFO): Starting
2019-04-09T01:22:58.414Z,1554772978.414 [BR_Ping1D](INFO): Starting
2019-04-09T01:22:58.414Z,1554772978.414 [BR_Ping1D](INFO): readConfig
2019-04-09T01:22:58.415Z,1554772978.415 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:22:58.415Z,1554772978.415 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:22:58.415Z,1554772978.415 [BR_Ping1D](INFO): UART requesting status
2019-04-09T01:22:58.415Z,1554772978.415 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:22:58.415Z,1554772978.415 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:22:58.415Z,1554772978.415 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:22:58.750Z,1554772978.750 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T01:22:58.750Z,1554772978.750 [MassServo] Communications Fault, FailCount= 5
2019-04-09T01:22:58.750Z,1554772978.750 [MassServo](ERROR): Communications Fault
2019-04-09T01:22:58.754Z,1554772978.754 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T01:22:58.755Z,1554772978.755 [CBIT](CRITICAL): Communications Fault in component: MassServo
2019-04-09T01:22:58.819Z,1554772978.819 [BR_Ping1D](INFO): Starting
2019-04-09T01:22:58.819Z,1554772978.819 [BR_Ping1D](INFO): readConfig
2019-04-09T01:22:58.819Z,1554772978.819 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:22:58.819Z,1554772978.819 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:22:58.819Z,1554772978.819 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:22:58.819Z,1554772978.819 [BR_Ping1D](INFO): elapsed1.2132
2019-04-09T01:22:58.820Z,1554772978.820 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:22:58.820Z,1554772978.820 [BR_Ping1D](INFO): No UART data
2019-04-09T01:22:58.831Z,1554772978.831 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T01:22:58.831Z,1554772978.831 [MassServo](INFO): Powering down
2019-04-09T01:22:58.833Z,1554772978.833 [CBIT](INFO): Critical error at 20190409T012258
2019-04-09T01:22:59.222Z,1554772979.222 [BR_Ping1D](INFO): Starting
2019-04-09T01:22:59.222Z,1554772979.222 [BR_Ping1D](INFO): readConfig
2019-04-09T01:22:59.223Z,1554772979.223 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:22:59.223Z,1554772979.223 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:22:59.223Z,1554772979.223 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:22:59.223Z,1554772979.223 [BR_Ping1D](INFO): elapsed1.6169
2019-04-09T01:22:59.223Z,1554772979.223 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:22:59.224Z,1554772979.224 [BR_Ping1D](INFO): No UART data
2019-04-09T01:22:59.647Z,1554772979.647 [BR_Ping1D](INFO): Starting
2019-04-09T01:22:59.648Z,1554772979.648 [BR_Ping1D](INFO): readConfig
2019-04-09T01:22:59.648Z,1554772979.648 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:22:59.648Z,1554772979.648 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:22:59.648Z,1554772979.648 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:22:59.648Z,1554772979.648 [BR_Ping1D](INFO): elapsed2.0422
2019-04-09T01:22:59.649Z,1554772979.649 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:22:59.649Z,1554772979.649 [BR_Ping1D](INFO): No UART data
2019-04-09T01:22:59.672Z,1554772979.672 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T01:22:59.791Z,1554772979.791 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T01:23:00.029Z,1554772980.029 [BR_Ping1D](INFO): Starting
2019-04-09T01:23:00.029Z,1554772980.029 [BR_Ping1D](INFO): readConfig
2019-04-09T01:23:00.030Z,1554772980.030 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:23:00.030Z,1554772980.030 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:23:00.030Z,1554772980.030 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:23:00.030Z,1554772980.030 [BR_Ping1D](INFO): elapsed2.4239
2019-04-09T01:23:00.030Z,1554772980.030 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:23:00.031Z,1554772980.031 [BR_Ping1D](INFO): No UART data
2019-04-09T01:23:00.434Z,1554772980.434 [BR_Ping1D](INFO): Starting
2019-04-09T01:23:00.434Z,1554772980.434 [BR_Ping1D](INFO): readConfig
2019-04-09T01:23:00.434Z,1554772980.434 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:23:00.434Z,1554772980.434 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:23:00.434Z,1554772980.434 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:23:00.435Z,1554772980.435 [BR_Ping1D](INFO): elapsed2.8283
2019-04-09T01:23:00.435Z,1554772980.435 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:23:00.435Z,1554772980.435 [BR_Ping1D](INFO): No UART data
2019-04-09T01:23:00.839Z,1554772980.839 [BR_Ping1D](INFO): Starting
2019-04-09T01:23:00.839Z,1554772980.839 [BR_Ping1D](INFO): readConfig
2019-04-09T01:23:00.839Z,1554772980.839 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:23:00.839Z,1554772980.839 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:23:00.839Z,1554772980.839 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:23:00.840Z,1554772980.840 [BR_Ping1D](INFO): elapsed3.2333
2019-04-09T01:23:00.840Z,1554772980.840 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:23:00.840Z,1554772980.840 [BR_Ping1D](INFO): No UART data
2019-04-09T01:23:01.223Z,1554772981.223 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T01:23:01.223Z,1554772981.223 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1
2019-04-09T01:23:01.223Z,1554772981.223 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T01:23:01.241Z,1554772981.241 [BR_Ping1D](INFO): Starting
2019-04-09T01:23:01.242Z,1554772981.242 [BR_Ping1D](INFO): readConfig
2019-04-09T01:23:01.242Z,1554772981.242 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:23:01.242Z,1554772981.242 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:23:01.242Z,1554772981.242 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:23:01.242Z,1554772981.242 [BR_Ping1D](INFO): elapsed3.6362
2019-04-09T01:23:01.243Z,1554772981.243 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:23:01.243Z,1554772981.243 [BR_Ping1D](INFO): No UART data
2019-04-09T01:23:01.279Z,1554772981.279 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:23:01.627Z,1554772981.627 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:23:01.786Z,1554772981.786 [BR_Ping1D](INFO): Starting
2019-04-09T01:23:01.786Z,1554772981.786 [BR_Ping1D](INFO): readConfig
2019-04-09T01:23:01.786Z,1554772981.786 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:23:01.786Z,1554772981.786 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:23:01.786Z,1554772981.786 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:23:01.787Z,1554772981.787 [BR_Ping1D](INFO): elapsed4.1803
2019-04-09T01:23:01.787Z,1554772981.787 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:23:01.787Z,1554772981.787 [BR_Ping1D](INFO): No UART data
2019-04-09T01:23:01.816Z,1554772981.816 [CBIT](FAULT): Main Battery Failure. Count: 51
2019-04-09T01:23:02.050Z,1554772982.050 [BR_Ping1D](INFO): Starting
2019-04-09T01:23:02.050Z,1554772982.050 [BR_Ping1D](INFO): readConfig
2019-04-09T01:23:02.051Z,1554772982.051 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:23:02.051Z,1554772982.051 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:23:02.051Z,1554772982.051 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:23:02.051Z,1554772982.051 [BR_Ping1D](INFO): elapsed4.4449
2019-04-09T01:23:02.051Z,1554772982.051 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:23:02.052Z,1554772982.052 [BR_Ping1D](INFO): No UART data
2019-04-09T01:23:02.434Z,1554772982.434 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T01:23:02.435Z,1554772982.435 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-09T01:23:02.435Z,1554772982.435 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T01:23:02.453Z,1554772982.453 [BR_Ping1D](INFO): Starting
2019-04-09T01:23:02.453Z,1554772982.453 [BR_Ping1D](INFO): readConfig
2019-04-09T01:23:02.454Z,1554772982.454 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:23:02.454Z,1554772982.454 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:23:02.454Z,1554772982.454 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:23:02.454Z,1554772982.454 [BR_Ping1D](INFO): elapsed4.8481
2019-04-09T01:23:02.455Z,1554772982.455 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:23:02.455Z,1554772982.455 [BR_Ping1D](INFO): No UART data
2019-04-09T01:23:02.479Z,1554772982.479 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T01:23:02.479Z,1554772982.479 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T01:23:02.479Z,1554772982.479 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1
2019-04-09T01:23:02.958Z,1554772982.958 [Aanderaa_O2](INFO): Powering down
2019-04-09T01:23:02.978Z,1554772982.978 [BR_Ping1D](INFO): Starting
2019-04-09T01:23:02.978Z,1554772982.978 [BR_Ping1D](INFO): readConfig
2019-04-09T01:23:02.978Z,1554772982.978 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:23:02.979Z,1554772982.979 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:23:02.979Z,1554772982.979 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:23:02.979Z,1554772982.979 [BR_Ping1D](INFO): elapsed5.3727
2019-04-09T01:23:02.979Z,1554772982.979 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:23:02.979Z,1554772982.979 [BR_Ping1D] Data Fault, FailCount= 3
2019-04-09T01:23:02.979Z,1554772982.979 [BR_Ping1D](ERROR): Data Fault
2019-04-09T01:23:03.007Z,1554772983.007 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T01:23:03.261Z,1554772983.261 [BR_Ping1D](INFO): Stop
2019-04-09T01:23:03.261Z,1554772983.261 [BR_Ping1D](INFO): uninitialize
2019-04-09T01:23:03.665Z,1554772983.665 [BR_Ping1D](INFO): Stopping
2019-04-09T01:23:03.691Z,1554772983.691 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T01:23:03.691Z,1554772983.691 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-09T01:23:04.067Z,1554772984.067 [BR_Ping1D](INFO): Stopping
2019-04-09T01:23:04.487Z,1554772984.487 [BR_Ping1D](INFO): Stopped
2019-04-09T01:23:04.500Z,1554772984.500 [SBIT](IMPORTANT): Beginning Startup BIT
2019-04-09T01:23:04.505Z,1554772984.505 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T01:23:04.505Z,1554772984.505 [BR_Ping1D] No Fault, FailCount= 3
2019-04-09T01:23:04.505Z,1554772984.505 [CBIT](IMPORTANT): Beginning ground fault scan
2019-04-09T01:23:04.858Z,1554772984.858 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T01:23:04.859Z,1554772984.859 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T01:23:04.901Z,1554772984.901 [BR_Ping1D](INFO): Start
2019-04-09T01:23:05.282Z,1554772985.282 [BR_Ping1D](INFO): Starting
2019-04-09T01:23:05.685Z,1554772985.685 [BR_Ping1D](INFO): Starting
2019-04-09T01:23:05.686Z,1554772985.686 [BR_Ping1D](INFO): readConfig
2019-04-09T01:23:05.686Z,1554772985.686 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:23:05.686Z,1554772985.686 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:23:05.687Z,1554772985.687 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T01:23:05.698Z,1554772985.698 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T01:23:05.698Z,1554772985.698 [BR_Ping1D](INFO): packet size:10
2019-04-09T01:23:05.793Z,1554772985.793 [CBIT](FAULT): Main Battery Failure. Count: 61
2019-04-09T01:23:06.090Z,1554772986.090 [BR_Ping1D](INFO): Starting
2019-04-09T01:23:06.493Z,1554772986.493 [BR_Ping1D](INFO): Starting
2019-04-09T01:23:06.494Z,1554772986.494 [BR_Ping1D](INFO): readConfig
2019-04-09T01:23:06.494Z,1554772986.494 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:23:06.494Z,1554772986.494 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:23:06.494Z,1554772986.494 [BR_Ping1D](INFO): UART requesting status
2019-04-09T01:23:06.494Z,1554772986.494 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:23:06.495Z,1554772986.495 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:23:06.495Z,1554772986.495 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:23:06.898Z,1554772986.898 [BR_Ping1D](INFO): Starting
2019-04-09T01:23:06.898Z,1554772986.898 [BR_Ping1D](INFO): readConfig
2019-04-09T01:23:06.899Z,1554772986.899 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:23:06.899Z,1554772986.899 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:23:06.899Z,1554772986.899 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:23:06.899Z,1554772986.899 [BR_Ping1D](INFO): elapsed1.2125
2019-04-09T01:23:06.899Z,1554772986.899 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:23:06.900Z,1554772986.900 [BR_Ping1D](INFO): No UART data
2019-04-09T01:23:07.305Z,1554772987.305 [BR_Ping1D](INFO): Starting
2019-04-09T01:23:07.305Z,1554772987.305 [BR_Ping1D](INFO): readConfig
2019-04-09T01:23:07.305Z,1554772987.305 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:23:07.305Z,1554772987.305 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:23:07.305Z,1554772987.305 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:23:07.305Z,1554772987.305 [BR_Ping1D](INFO): elapsed1.6188
2019-04-09T01:23:07.306Z,1554772987.306 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:23:07.306Z,1554772987.306 [BR_Ping1D](INFO): No UART data
2019-04-09T01:23:07.710Z,1554772987.710 [BR_Ping1D](INFO): Starting
2019-04-09T01:23:07.710Z,1554772987.710 [BR_Ping1D](INFO): readConfig
2019-04-09T01:23:07.711Z,1554772987.711 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:23:07.711Z,1554772987.711 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:23:07.711Z,1554772987.711 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:23:07.711Z,1554772987.711 [BR_Ping1D](INFO): elapsed2.0244
2019-04-09T01:23:07.711Z,1554772987.711 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:23:07.711Z,1554772987.711 [BR_Ping1D](INFO): No UART data
2019-04-09T01:23:08.103Z,1554772988.103 [NAL9602](INFO): Powering up NAL9602
2019-04-09T01:23:08.119Z,1554772988.119 [BR_Ping1D](INFO): Starting
2019-04-09T01:23:08.119Z,1554772988.119 [BR_Ping1D](INFO): readConfig
2019-04-09T01:23:08.119Z,1554772988.119 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:23:08.119Z,1554772988.119 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:23:08.119Z,1554772988.119 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:23:08.120Z,1554772988.120 [BR_Ping1D](INFO): elapsed2.4330
2019-04-09T01:23:08.120Z,1554772988.120 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:23:08.120Z,1554772988.120 [BR_Ping1D](INFO): No UART data
2019-04-09T01:23:08.450Z,1554772988.450 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T01:23:08.450Z,1554772988.450 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-09T01:23:08.450Z,1554772988.450 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T01:23:08.511Z,1554772988.511 [BR_Ping1D](INFO): Starting
2019-04-09T01:23:08.511Z,1554772988.511 [BR_Ping1D](INFO): readConfig
2019-04-09T01:23:08.511Z,1554772988.511 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:23:08.511Z,1554772988.511 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:23:08.511Z,1554772988.511 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:23:08.512Z,1554772988.512 [BR_Ping1D](INFO): elapsed2.8249
2019-04-09T01:23:08.512Z,1554772988.512 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:23:08.512Z,1554772988.512 [BR_Ping1D](INFO): No UART data
2019-04-09T01:23:08.586Z,1554772988.586 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T01:23:08.854Z,1554772988.854 [CTD_Seabird](INFO): Powering down
2019-04-09T01:23:08.917Z,1554772988.917 [BR_Ping1D](INFO): Starting
2019-04-09T01:23:08.917Z,1554772988.917 [BR_Ping1D](INFO): readConfig
2019-04-09T01:23:08.918Z,1554772988.918 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:23:08.918Z,1554772988.918 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:23:08.918Z,1554772988.918 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:23:08.918Z,1554772988.918 [BR_Ping1D](INFO): elapsed3.2315
2019-04-09T01:23:08.918Z,1554772988.918 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:23:08.919Z,1554772988.919 [BR_Ping1D](INFO): No UART data
2019-04-09T01:23:09.323Z,1554772989.323 [BR_Ping1D](INFO): Starting
2019-04-09T01:23:09.323Z,1554772989.323 [BR_Ping1D](INFO): readConfig
2019-04-09T01:23:09.323Z,1554772989.323 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:23:09.323Z,1554772989.323 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:23:09.323Z,1554772989.323 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:23:09.323Z,1554772989.323 [BR_Ping1D](INFO): elapsed3.6368
2019-04-09T01:23:09.324Z,1554772989.324 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:23:09.324Z,1554772989.324 [BR_Ping1D](INFO): No UART data
2019-04-09T01:23:09.725Z,1554772989.725 [BR_Ping1D](INFO): Starting
2019-04-09T01:23:09.725Z,1554772989.725 [BR_Ping1D](INFO): readConfig
2019-04-09T01:23:09.725Z,1554772989.725 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:23:09.725Z,1554772989.725 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:23:09.726Z,1554772989.726 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:23:09.726Z,1554772989.726 [BR_Ping1D](INFO): elapsed4.0391
2019-04-09T01:23:09.726Z,1554772989.726 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:23:09.726Z,1554772989.726 [BR_Ping1D](INFO): No UART data
2019-04-09T01:23:09.741Z,1554772989.741 [CBIT](FAULT): Main Battery Failure. Count: 71
2019-04-09T01:23:10.129Z,1554772990.129 [BR_Ping1D](INFO): Starting
2019-04-09T01:23:10.130Z,1554772990.130 [BR_Ping1D](INFO): readConfig
2019-04-09T01:23:10.130Z,1554772990.130 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:23:10.130Z,1554772990.130 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:23:10.130Z,1554772990.130 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:23:10.130Z,1554772990.130 [BR_Ping1D](INFO): elapsed4.4437
2019-04-09T01:23:10.131Z,1554772990.131 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:23:10.131Z,1554772990.131 [BR_Ping1D](INFO): No UART data
2019-04-09T01:23:10.159Z,1554772990.159 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T01:23:10.159Z,1554772990.159 [CTD_Seabird] No Fault, FailCount= 2
2019-04-09T01:23:10.206Z,1554772990.206 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T01:23:10.210Z,1554772990.210 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T01:23:10.533Z,1554772990.533 [BR_Ping1D](INFO): Starting
2019-04-09T01:23:10.534Z,1554772990.534 [BR_Ping1D](INFO): readConfig
2019-04-09T01:23:10.534Z,1554772990.534 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:23:10.534Z,1554772990.534 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:23:10.534Z,1554772990.534 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:23:10.534Z,1554772990.534 [BR_Ping1D](INFO): elapsed4.8477
2019-04-09T01:23:10.535Z,1554772990.535 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:23:10.535Z,1554772990.535 [BR_Ping1D](INFO): No UART data
2019-04-09T01:23:10.941Z,1554772990.941 [BR_Ping1D](INFO): Starting
2019-04-09T01:23:10.941Z,1554772990.941 [BR_Ping1D](INFO): readConfig
2019-04-09T01:23:10.941Z,1554772990.941 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:23:10.941Z,1554772990.941 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:23:10.941Z,1554772990.941 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:23:10.942Z,1554772990.942 [BR_Ping1D](INFO): elapsed5.2550
2019-04-09T01:23:10.942Z,1554772990.942 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:23:10.942Z,1554772990.942 [BR_Ping1D] Data Fault, FailCount= 4
2019-04-09T01:23:10.942Z,1554772990.942 [BR_Ping1D](ERROR): Data Fault
2019-04-09T01:23:10.971Z,1554772990.971 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T01:23:11.342Z,1554772991.342 [BR_Ping1D](INFO): Stop
2019-04-09T01:23:11.342Z,1554772991.342 [BR_Ping1D](INFO): uninitialize
2019-04-09T01:23:11.746Z,1554772991.746 [BR_Ping1D](INFO): Stopping
2019-04-09T01:23:12.149Z,1554772992.149 [BR_Ping1D](INFO): Stopping
2019-04-09T01:23:12.553Z,1554772992.553 [BR_Ping1D](INFO): Stopped
2019-04-09T01:23:12.583Z,1554772992.583 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T01:23:12.583Z,1554772992.583 [BR_Ping1D] No Fault, FailCount= 4
2019-04-09T01:23:12.957Z,1554772992.957 [BR_Ping1D](INFO): Start
2019-04-09T01:23:13.362Z,1554772993.362 [BR_Ping1D](INFO): Starting
2019-04-09T01:23:13.765Z,1554772993.765 [BR_Ping1D](INFO): Starting
2019-04-09T01:23:13.765Z,1554772993.765 [BR_Ping1D](INFO): readConfig
2019-04-09T01:23:13.765Z,1554772993.765 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:23:13.766Z,1554772993.766 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:23:13.766Z,1554772993.766 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T01:23:13.778Z,1554772993.778 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T01:23:13.778Z,1554772993.778 [BR_Ping1D](INFO): packet size:10
2019-04-09T01:23:13.792Z,1554772993.792 [CBIT](FAULT): Main Battery Failure. Count: 81
2019-04-09T01:23:14.151Z,1554772994.151 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T01:23:14.151Z,1554772994.151 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-09T01:23:14.151Z,1554772994.151 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T01:23:14.186Z,1554772994.186 [BR_Ping1D](INFO): Starting
2019-04-09T01:23:14.212Z,1554772994.212 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T01:23:14.212Z,1554772994.212 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-09T01:23:14.674Z,1554772994.674 [Aanderaa_O2](INFO): Powering down
2019-04-09T01:23:14.699Z,1554772994.699 [BR_Ping1D](INFO): Starting
2019-04-09T01:23:14.700Z,1554772994.700 [BR_Ping1D](INFO): readConfig
2019-04-09T01:23:14.700Z,1554772994.700 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:23:14.700Z,1554772994.700 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:23:14.700Z,1554772994.700 [BR_Ping1D](INFO): UART requesting status
2019-04-09T01:23:14.700Z,1554772994.700 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:23:14.701Z,1554772994.701 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:23:14.701Z,1554772994.701 [BR_Ping1D](INFO): packet size:8
2019-04-09T01:23:14.978Z,1554772994.978 [BR_Ping1D](INFO): Starting
2019-04-09T01:23:14.978Z,1554772994.978 [BR_Ping1D](INFO): readConfig
2019-04-09T01:23:14.978Z,1554772994.978 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:23:14.978Z,1554772994.978 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:23:14.978Z,1554772994.978 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:23:14.978Z,1554772994.978 [BR_Ping1D](INFO): elapsed1.2127
2019-04-09T01:23:14.979Z,1554772994.979 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:23:14.979Z,1554772994.979 [BR_Ping1D](INFO): No UART data
2019-04-09T01:23:15.389Z,1554772995.389 [BR_Ping1D](INFO): Starting
2019-04-09T01:23:15.390Z,1554772995.390 [BR_Ping1D](INFO): readConfig
2019-04-09T01:23:15.390Z,1554772995.390 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:23:15.390Z,1554772995.390 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:23:15.390Z,1554772995.390 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:23:15.390Z,1554772995.390 [BR_Ping1D](INFO): elapsed1.6246
2019-04-09T01:23:15.391Z,1554772995.391 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:23:15.391Z,1554772995.391 [BR_Ping1D](INFO): No UART data
2019-04-09T01:23:15.448Z,1554772995.448 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.006943
CHAN A1 (24V): -0.127846
CHAN A2 (12V): -0.005464
CHAN A3 (5V): -0.003028
CHAN B0 (3.3V): -0.000961
CHAN B1 (3.15aV): -0.000999
CHAN B2 (3.15bV): -0.001255
CHAN B3 (GND): -0.000489
OPEN: -0.000883
Full Scale Calc: 4.765 mA, -1.589 mA
2019-04-09T01:23:15.785Z,1554772995.785 [BR_Ping1D](INFO): Starting
2019-04-09T01:23:15.786Z,1554772995.786 [BR_Ping1D](INFO): readConfig
2019-04-09T01:23:15.786Z,1554772995.786 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:23:15.786Z,1554772995.786 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:23:15.786Z,1554772995.786 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:23:15.786Z,1554772995.786 [BR_Ping1D](INFO): elapsed2.0206
2019-04-09T01:23:15.787Z,1554772995.787 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:23:15.787Z,1554772995.787 [BR_Ping1D](INFO): No UART data
2019-04-09T01:23:16.189Z,1554772996.189 [BR_Ping1D](INFO): Starting
2019-04-09T01:23:16.189Z,1554772996.189 [BR_Ping1D](INFO): readConfig
2019-04-09T01:23:16.189Z,1554772996.189 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:23:16.190Z,1554772996.190 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:23:16.190Z,1554772996.190 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:23:16.190Z,1554772996.190 [BR_Ping1D](INFO): elapsed2.4242
2019-04-09T01:23:16.190Z,1554772996.190 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:23:16.190Z,1554772996.190 [BR_Ping1D](INFO): No UART data
2019-04-09T01:23:16.594Z,1554772996.594 [BR_Ping1D](INFO): Starting
2019-04-09T01:23:16.594Z,1554772996.594 [BR_Ping1D](INFO): readConfig
2019-04-09T01:23:16.594Z,1554772996.594 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:23:16.594Z,1554772996.594 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:23:16.594Z,1554772996.594 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:23:16.595Z,1554772996.595 [BR_Ping1D](INFO): elapsed2.8289
2019-04-09T01:23:16.595Z,1554772996.595 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:23:16.595Z,1554772996.595 [BR_Ping1D](INFO): No UART data
2019-04-09T01:23:16.998Z,1554772996.998 [BR_Ping1D](INFO): Starting
2019-04-09T01:23:16.999Z,1554772996.999 [BR_Ping1D](INFO): readConfig
2019-04-09T01:23:16.999Z,1554772996.999 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:23:16.999Z,1554772996.999 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:23:16.999Z,1554772996.999 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:23:16.999Z,1554772996.999 [BR_Ping1D](INFO): elapsed3.2335
2019-04-09T01:23:16.000Z,1554772997.000 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:23:16.000Z,1554772997.000 [BR_Ping1D](INFO): No UART data
2019-04-09T01:23:17.403Z,1554772997.403 [BR_Ping1D](INFO): Starting
2019-04-09T01:23:17.403Z,1554772997.403 [BR_Ping1D](INFO): readConfig
2019-04-09T01:23:17.404Z,1554772997.404 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:23:17.404Z,1554772997.404 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:23:17.404Z,1554772997.404 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:23:17.404Z,1554772997.404 [BR_Ping1D](INFO): elapsed3.6383
2019-04-09T01:23:17.404Z,1554772997.404 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:23:17.405Z,1554772997.405 [BR_Ping1D](INFO): No UART data
2019-04-09T01:23:17.806Z,1554772997.806 [BR_Ping1D](INFO): Starting
2019-04-09T01:23:17.806Z,1554772997.806 [BR_Ping1D](INFO): readConfig
2019-04-09T01:23:17.806Z,1554772997.806 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:23:17.806Z,1554772997.806 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:23:17.806Z,1554772997.806 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:23:17.807Z,1554772997.807 [BR_Ping1D](INFO): elapsed4.0408
2019-04-09T01:23:17.807Z,1554772997.807 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:23:17.807Z,1554772997.807 [BR_Ping1D](INFO): No UART data
2019-04-09T01:23:17.871Z,1554772997.871 [SBIT](ERROR): Could not read massPosReader_.
2019-04-09T01:23:17.872Z,1554772997.872 [CBIT](FAULT): Main Battery Failure. Count: 91
2019-04-09T01:23:18.210Z,1554772998.210 [BR_Ping1D](INFO): Starting
2019-04-09T01:23:18.210Z,1554772998.210 [BR_Ping1D](INFO): readConfig
2019-04-09T01:23:18.210Z,1554772998.210 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:23:18.211Z,1554772998.211 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:23:18.211Z,1554772998.211 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:23:18.211Z,1554772998.211 [BR_Ping1D](INFO): elapsed4.4450
2019-04-09T01:23:18.211Z,1554772998.211 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:23:18.211Z,1554772998.211 [BR_Ping1D](INFO): No UART data
2019-04-09T01:23:18.625Z,1554772998.625 [BR_Ping1D](INFO): Starting
2019-04-09T01:23:18.625Z,1554772998.625 [BR_Ping1D](INFO): readConfig
2019-04-09T01:23:18.625Z,1554772998.625 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:23:18.625Z,1554772998.625 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:23:18.625Z,1554772998.625 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:23:18.625Z,1554772998.625 [BR_Ping1D](INFO): elapsed4.8597
2019-04-09T01:23:18.626Z,1554772998.626 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:23:18.626Z,1554772998.626 [BR_Ping1D](INFO): No UART data
2019-04-09T01:23:19.011Z,1554772999.011 [NAL9602](INFO): NAL9602 initialized
2019-04-09T01:23:19.026Z,1554772999.026 [BR_Ping1D](INFO): Starting
2019-04-09T01:23:19.026Z,1554772999.026 [BR_Ping1D](INFO): readConfig
2019-04-09T01:23:19.027Z,1554772999.027 [BR_Ping1D](INFO): XXXX automode1: 4962020
2019-04-09T01:23:19.027Z,1554772999.027 [BR_Ping1D](INFO): XXXX automode2: 228
2019-04-09T01:23:19.027Z,1554772999.027 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T01:23:19.027Z,1554772999.027 [BR_Ping1D](INFO): elapsed5.2614
2019-04-09T01:23:19.027Z,1554772999.027 [BR_Ping1D](INFO): timeout5.0000
2019-04-09T01:23:19.028Z,1554772999.028 [BR_Ping1D] Data Fault, FailCount= 5
2019-04-09T01:23:19.028Z,1554772999.028 [BR_Ping1D](ERROR): Data Fault
2019-04-09T01:23:19.104Z,1554772999.104 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T01:23:19.104Z,1554772999.104 [CBIT](CRITICAL): Data Fault in component: BR_Ping1D
2019-04-09T01:23:19.431Z,1554772999.431 [BR_Ping1D](INFO): Stop
2019-04-09T01:23:19.431Z,1554772999.431 [BR_Ping1D](INFO): uninitialize
2019-04-09T01:23:19.533Z,1554772999.533 [CBIT](INFO): Critical error at 20190409T012319
2019-04-09T01:23:19.822Z,1554772999.822 [NAL9602](DEBUG): Fix Requested
2019-04-09T01:23:19.837Z,1554772999.837 [BR_Ping1D](INFO): Stopping
2019-04-09T01:23:20.245Z,1554773000.245 [BR_Ping1D](INFO): Stopping
2019-04-09T01:23:20.657Z,1554773000.657 [BR_Ping1D](INFO): Stopped
2019-04-09T01:23:21.917Z,1554773001.917 [CBIT](FAULT): Main Battery Failure. Count: 101
2019-04-09T01:23:23.258Z,1554773003.258 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T01:23:23.258Z,1554773003.258 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-09T01:23:23.258Z,1554773003.258 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T01:23:23.543Z,1554773003.543 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T01:23:23.544Z,1554773003.544 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-09T01:23:23.662Z,1554773003.662 [CTD_Seabird](INFO): Powering down
2019-04-09T01:23:25.059Z,1554773005.059 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T01:23:25.059Z,1554773005.059 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 2
2019-04-09T01:23:25.059Z,1554773005.059 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T01:23:25.231Z,1554773005.231 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:23:25.464Z,1554773005.464 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:23:25.918Z,1554773005.918 [CBIT](FAULT): Main Battery Failure. Count: 111
2019-04-09T01:23:26.328Z,1554773006.328 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T01:23:26.328Z,1554773006.328 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 2
2019-04-09T01:23:28.699Z,1554773008.699 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T01:23:28.699Z,1554773008.699 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T01:23:29.949Z,1554773009.949 [CBIT](FAULT): Main Battery Failure. Count: 121
2019-04-09T01:23:33.995Z,1554773013.995 [CBIT](FAULT): Main Battery Failure. Count: 131
2019-04-09T01:23:38.045Z,1554773018.045 [CBIT](FAULT): Main Battery Failure. Count: 141
2019-04-09T01:23:42.065Z,1554773022.065 [CBIT](FAULT): Main Battery Failure. Count: 151
2019-04-09T01:23:44.509Z,1554773024.509 [SBIT](ERROR): Could not read massPosReader_.
2019-04-09T01:23:44.509Z,1554773024.509 [SBIT](FAULT): Control surface position failure.
2019-04-09T01:23:46.101Z,1554773026.101 [CBIT](FAULT): Main Battery Failure. Count: 161
2019-04-09T01:23:48.898Z,1554773028.898 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T01:23:48.899Z,1554773028.899 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 3
2019-04-09T01:23:48.899Z,1554773028.899 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T01:23:49.015Z,1554773029.015 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:23:49.303Z,1554773029.303 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:23:50.145Z,1554773030.145 [CBIT](FAULT): Main Battery Failure. Count: 171
2019-04-09T01:23:50.148Z,1554773030.148 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T01:23:50.148Z,1554773030.148 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 3
2019-04-09T01:23:52.534Z,1554773032.534 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T01:23:52.535Z,1554773032.535 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T01:23:54.201Z,1554773034.201 [CBIT](FAULT): Main Battery Failure. Count: 181
2019-04-09T01:23:58.226Z,1554773038.226 [SBIT](ERROR): Could not read massPosReader_.
2019-04-09T01:23:58.226Z,1554773038.226 [SBIT](FAULT): Control surface position failure.
2019-04-09T01:23:58.227Z,1554773038.227 [CBIT](FAULT): Main Battery Failure. Count: 191
2019-04-09T01:23:58.635Z,1554773038.635 [SBIT](CRITICAL): SBIT FAILED
2019-04-09T01:23:58.651Z,1554773038.651 [CBIT](INFO): Critical error at 20190409T012358
2019-04-09T01:23:58.707Z,1554773038.707 [CommandLine](IMPORTANT): got command configSet list
2019-04-09T01:23:58.707Z,1554773038.707 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-04-09T01:23:58.708Z,1554773038.708 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-04-09T01:23:58.708Z,1554773038.708 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2019-04-09T01:23:59.047Z,1554773039.047 [MissionManager](IMPORTANT): Started mission Startup
2019-04-09T01:23:59.047Z,1554773039.047 [Startup] Running Loop=1
2019-04-09T01:23:59.048Z,1554773039.048 [Startup](DEBUG): Aggregate::initialize Startup
2019-04-09T01:23:59.048Z,1554773039.048 [Startup:A.GoToSurface] Running Loop=1
2019-04-09T01:23:59.048Z,1554773039.048 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-09T01:23:59.048Z,1554773039.048 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-09T01:23:59.049Z,1554773039.049 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-09T01:23:59.049Z,1554773039.049 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-09T01:23:59.050Z,1554773039.050 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-09T01:23:59.050Z,1554773039.050 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-09T01:23:59.052Z,1554773039.052 [Startup:StartupSatComms] Running Loop=1
2019-04-09T01:23:59.052Z,1554773039.052 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-04-09T01:23:59.052Z,1554773039.052 [Startup:StartupSatComms:A] Running Loop=1
2019-04-09T01:23:59.444Z,1554773039.444 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-04-09T01:24:02.268Z,1554773042.268 [CBIT](FAULT): Main Battery Failure. Count: 201
2019-04-09T01:24:06.309Z,1554773046.309 [CBIT](FAULT): Main Battery Failure. Count: 211
2019-04-09T01:24:10.358Z,1554773050.358 [CBIT](FAULT): Main Battery Failure. Count: 221
2019-04-09T01:24:12.734Z,1554773052.734 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T01:24:12.735Z,1554773052.735 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 4
2019-04-09T01:24:12.735Z,1554773052.735 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T01:24:12.792Z,1554773052.792 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:24:13.138Z,1554773053.138 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:24:13.984Z,1554773053.984 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T01:24:13.984Z,1554773053.984 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 4
2019-04-09T01:24:14.379Z,1554773054.379 [CBIT](FAULT): Main Battery Failure. Count: 231
2019-04-09T01:24:16.370Z,1554773056.370 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T01:24:16.371Z,1554773056.371 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T01:24:18.427Z,1554773058.427 [CBIT](FAULT): Main Battery Failure. Count: 241
2019-04-09T01:24:22.467Z,1554773062.467 [CBIT](FAULT): Main Battery Failure. Count: 251
2019-04-09T01:24:26.515Z,1554773066.515 [CBIT](FAULT): Main Battery Failure. Count: 261
2019-04-09T01:24:30.568Z,1554773070.568 [CBIT](FAULT): Main Battery Failure. Count: 271
2019-04-09T01:24:34.585Z,1554773074.585 [CBIT](FAULT): Main Battery Failure. Count: 281
2019-04-09T01:24:36.571Z,1554773076.571 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T01:24:36.571Z,1554773076.571 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 5
2019-04-09T01:24:36.571Z,1554773076.571 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T01:24:36.636Z,1554773076.636 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:24:36.974Z,1554773076.974 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:24:37.820Z,1554773077.820 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T01:24:37.820Z,1554773077.820 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 5
2019-04-09T01:24:38.619Z,1554773078.619 [CBIT](FAULT): Main Battery Failure. Count: 291
2019-04-09T01:24:40.207Z,1554773080.207 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T01:24:40.207Z,1554773080.207 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T01:24:40.256Z,1554773080.256 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T01:24:40.256Z,1554773080.256 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-04-09T01:24:40.256Z,1554773080.256 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-09T01:24:40.258Z,1554773080.258 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T01:24:40.258Z,1554773080.258 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-04-09T01:24:40.258Z,1554773080.258 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-09T01:24:40.260Z,1554773080.260 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T01:24:40.260Z,1554773080.260 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-04-09T01:24:40.260Z,1554773080.260 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-09T01:24:40.271Z,1554773080.271 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-09T01:24:40.271Z,1554773080.271 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-09T01:24:40.271Z,1554773080.271 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-09T01:24:40.643Z,1554773080.643 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-09T01:24:40.643Z,1554773080.643 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-04-09T01:24:40.644Z,1554773080.644 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-09T01:24:40.644Z,1554773080.644 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-04-09T01:24:40.644Z,1554773080.644 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-09T01:24:40.644Z,1554773080.644 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-04-09T01:24:41.053Z,1554773081.053 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T01:24:42.666Z,1554773082.666 [CBIT](FAULT): Main Battery Failure. Count: 301
2019-04-09T01:24:46.716Z,1554773086.716 [CBIT](FAULT): Main Battery Failure. Count: 311
2019-04-09T01:24:50.739Z,1554773090.739 [CBIT](FAULT): Main Battery Failure. Count: 321
2019-04-09T01:24:54.785Z,1554773094.786 [CBIT](FAULT): Main Battery Failure. Count: 331
2019-04-09T01:24:58.821Z,1554773098.821 [CBIT](FAULT): Main Battery Failure. Count: 341
2019-04-09T01:24:59.217Z,1554773099.217 [Startup:StartupSatComms:A](INFO): Timed out from 2019-04-09T01:23:59.1Z
2019-04-09T01:24:59.217Z,1554773099.217 [Startup:StartupSatComms:A] Stopped
2019-04-09T01:24:59.217Z,1554773099.217 [Startup:StartupSatComms:B] Running Loop=1
2019-04-09T01:24:59.635Z,1554773099.635 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-04-09T01:25:00.406Z,1554773100.406 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T01:25:00.407Z,1554773100.407 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 6
2019-04-09T01:25:00.407Z,1554773100.407 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T01:25:00.455Z,1554773100.455 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:25:00.810Z,1554773100.810 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:25:01.652Z,1554773101.652 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T01:25:01.652Z,1554773101.652 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 6
2019-04-09T01:25:02.876Z,1554773102.876 [CBIT](FAULT): Main Battery Failure. Count: 351
2019-04-09T01:25:04.042Z,1554773104.042 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T01:25:04.043Z,1554773104.043 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T01:25:06.906Z,1554773106.906 [CBIT](FAULT): Main Battery Failure. Count: 361
2019-04-09T01:25:10.948Z,1554773110.948 [CBIT](FAULT): Main Battery Failure. Count: 371
2019-04-09T01:25:14.985Z,1554773114.985 [CBIT](FAULT): Main Battery Failure. Count: 381
2019-04-09T01:25:19.041Z,1554773119.041 [CBIT](FAULT): Main Battery Failure. Count: 391
2019-04-09T01:25:19.516Z,1554773119.516 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004848
2019-04-09T01:25:23.086Z,1554773123.086 [CBIT](FAULT): Main Battery Failure. Count: 401
2019-04-09T01:25:24.242Z,1554773124.242 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T01:25:24.242Z,1554773124.242 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 7
2019-04-09T01:25:24.243Z,1554773124.243 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T01:25:24.300Z,1554773124.300 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:25:24.550Z,1554773124.550 [DataOverHttps](INFO): Sending 25 bytes from file Logs/20190409T011657/Courier0004.lzma
2019-04-09T01:25:24.646Z,1554773124.646 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:25:25.355Z,1554773125.355 [DataOverHttps](INFO): Moved sent file to Logs/20190409T011657/Courier0004.lzma.bak
2019-04-09T01:25:25.356Z,1554773125.356 [DataOverHttps](INFO): SBD MOMSN=10493160
2019-04-09T01:25:25.488Z,1554773125.488 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T01:25:25.488Z,1554773125.488 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 7
2019-04-09T01:25:27.098Z,1554773127.098 [CBIT](FAULT): Main Battery Failure. Count: 411
2019-04-09T01:25:27.878Z,1554773127.878 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T01:25:27.878Z,1554773127.878 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T01:25:31.155Z,1554773131.155 [CBIT](FAULT): Main Battery Failure. Count: 421
2019-04-09T01:25:35.193Z,1554773135.193 [CBIT](FAULT): Main Battery Failure. Count: 431
2019-04-09T01:25:36.726Z,1554773136.726 [DataOverHttps](INFO): Sending 359 bytes from file Logs/20190409T012230/Courier0000.lzma
2019-04-09T01:25:37.531Z,1554773137.531 [DataOverHttps](INFO): Moved sent file to Logs/20190409T012230/Courier0000.lzma.bak
2019-04-09T01:25:37.531Z,1554773137.531 [DataOverHttps](INFO): SBD MOMSN=10493162
2019-04-09T01:25:39.232Z,1554773139.232 [CBIT](FAULT): Main Battery Failure. Count: 441
2019-04-09T01:25:43.274Z,1554773143.274 [CBIT](FAULT): Main Battery Failure. Count: 451
2019-04-09T01:25:44.500Z,1554773144.500 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T01:25:44.500Z,1554773144.500 [Aanderaa_O2] No Fault, FailCount= 3
2019-04-09T01:25:47.299Z,1554773147.299 [CBIT](FAULT): Main Battery Failure. Count: 461
2019-04-09T01:25:48.079Z,1554773148.079 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T01:25:48.079Z,1554773148.079 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 8
2019-04-09T01:25:48.079Z,1554773148.079 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T01:25:48.148Z,1554773148.148 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:25:48.148Z,1554773148.148 [CBIT](FAULT): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T01:25:48.482Z,1554773148.482 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:25:49.530Z,1554773149.530 [DataOverHttps](INFO): Sending 190 bytes from file Logs/20190409T010736/Express0005.lzma
2019-04-09T01:25:50.335Z,1554773150.335 [DataOverHttps](INFO): Moved sent file to Logs/20190409T010736/Express0005.lzma.bak
2019-04-09T01:25:50.335Z,1554773150.335 [DataOverHttps](INFO): SBD MOMSN=10493179
2019-04-09T01:25:51.354Z,1554773151.354 [CBIT](FAULT): Main Battery Failure. Count: 471
2019-04-09T01:25:53.364Z,1554773153.364 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T01:25:53.364Z,1554773153.364 [CTD_Seabird] No Fault, FailCount= 3
2019-04-09T01:25:53.678Z,1554773153.678 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T01:25:53.686Z,1554773153.686 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T01:25:54.946Z,1554773154.946 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T01:25:54.946Z,1554773154.946 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-09T01:25:54.946Z,1554773154.946 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T01:25:55.012Z,1554773155.012 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T01:25:55.470Z,1554773155.470 [Aanderaa_O2](INFO): Powering down
2019-04-09T01:25:55.537Z,1554773155.537 [CBIT](FAULT): Main Battery Failure. Count: 481
2019-04-09T01:25:56.203Z,1554773156.203 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T01:25:56.203Z,1554773156.203 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-09T01:25:59.420Z,1554773159.420 [Startup:StartupSatComms:B](INFO): Timed out from 2019-04-09T01:24:59.2Z
2019-04-09T01:25:59.420Z,1554773159.420 [Startup:StartupSatComms:B] Stopped
2019-04-09T01:25:59.420Z,1554773159.420 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-04-09T01:25:59.420Z,1554773159.420 [Startup:StartupSatComms] Stopped
2019-04-09T01:25:59.420Z,1554773159.420 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-04-09T01:25:59.421Z,1554773159.421 [Startup](INFO): Completed Startup
2019-04-09T01:25:59.421Z,1554773159.421 [MissionManager](INFO): Startup is completed.
2019-04-09T01:25:59.421Z,1554773159.421 [MissionManager](INFO): Uninitializing Mission Startup
2019-04-09T01:25:59.421Z,1554773159.421 [Startup] Stopped
2019-04-09T01:25:59.421Z,1554773159.421 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-04-09T01:25:59.421Z,1554773159.421 [Startup:A.GoToSurface] Stopped
2019-04-09T01:25:59.421Z,1554773159.421 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-09T01:25:59.429Z,1554773159.429 [CBIT](FAULT): Main Battery Failure. Count: 491
2019-04-09T01:25:59.839Z,1554773159.839 [MissionManager](IMPORTANT): Started mission Default
2019-04-09T01:25:59.839Z,1554773159.839 [Default] Running Loop=1
2019-04-09T01:25:59.839Z,1554773159.839 [Default](DEBUG): Aggregate::initialize Default
2019-04-09T01:25:59.839Z,1554773159.839 [Default:B.GoToSurface] Running Loop=1
2019-04-09T01:25:59.839Z,1554773159.839 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-09T01:25:59.840Z,1554773159.840 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-09T01:25:59.840Z,1554773159.840 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-09T01:25:59.840Z,1554773159.840 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-09T01:25:59.841Z,1554773159.841 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-09T01:25:59.841Z,1554773159.841 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-09T01:25:59.841Z,1554773159.841 [Default:A.Wait] Running Loop=1
2019-04-09T01:25:59.841Z,1554773159.841 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-04-09T01:26:03.273Z,1554773163.273 [DataOverHttps](INFO): Sending 1475 bytes from file Logs/20190409T011026/Express0001.lzma
2019-04-09T01:26:03.465Z,1554773163.465 [CBIT](FAULT): Main Battery Failure. Count: 501
2019-04-09T01:26:04.075Z,1554773164.075 [DataOverHttps](INFO): Moved sent file to Logs/20190409T011026/Express0001.lzma.bak
2019-04-09T01:26:04.075Z,1554773164.075 [DataOverHttps](INFO): SBD MOMSN=10493189
2019-04-09T01:26:06.662Z,1554773166.662 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T01:26:06.662Z,1554773166.662 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-09T01:26:06.663Z,1554773166.663 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T01:26:06.700Z,1554773166.700 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T01:26:06.730Z,1554773166.730 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T01:26:06.730Z,1554773166.730 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-09T01:26:06.730Z,1554773166.730 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T01:26:07.134Z,1554773167.134 [CTD_Seabird](INFO): Powering down
2019-04-09T01:26:07.190Z,1554773167.190 [Aanderaa_O2](INFO): Powering down
2019-04-09T01:26:07.236Z,1554773167.236 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T01:26:07.509Z,1554773167.509 [CBIT](FAULT): Main Battery Failure. Count: 511
2019-04-09T01:26:07.915Z,1554773167.915 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T01:26:07.915Z,1554773167.915 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-09T01:26:08.340Z,1554773168.340 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T01:26:08.340Z,1554773168.340 [CTD_Seabird] No Fault, FailCount= 1
2019-04-09T01:26:08.482Z,1554773168.482 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T01:26:08.491Z,1554773168.491 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T01:26:11.561Z,1554773171.561 [CBIT](FAULT): Main Battery Failure. Count: 521
2019-04-09T01:26:13.148Z,1554773173.148 [Default:A.Wait](INFO): Done Waiting.
2019-04-09T01:26:13.149Z,1554773173.149 [Default:A.Wait] Stopped
2019-04-09T01:26:13.149Z,1554773173.149 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-04-09T01:26:13.567Z,1554773173.567 [Default:CheckIn] Running Loop=1
2019-04-09T01:26:13.568Z,1554773173.568 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-04-09T01:26:13.568Z,1554773173.568 [Default:CheckIn:Read_GPS] Running Loop=1
2019-04-09T01:26:13.963Z,1554773173.963 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-04-09T01:26:15.602Z,1554773175.602 [CBIT](FAULT): Main Battery Failure. Count: 531
2019-04-09T01:26:18.378Z,1554773178.378 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T01:26:18.379Z,1554773178.379 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-09T01:26:18.379Z,1554773178.379 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T01:26:18.432Z,1554773178.432 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T01:26:18.432Z,1554773178.432 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-09T01:26:18.902Z,1554773178.902 [Aanderaa_O2](INFO): Powering down
2019-04-09T01:26:19.626Z,1554773179.626 [CBIT](FAULT): Main Battery Failure. Count: 541
2019-04-09T01:26:21.534Z,1554773181.534 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T01:26:21.534Z,1554773181.534 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-09T01:26:21.534Z,1554773181.534 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T01:26:21.665Z,1554773181.665 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T01:26:21.938Z,1554773181.938 [CTD_Seabird](INFO): Powering down
2019-04-09T01:26:23.261Z,1554773183.261 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T01:26:23.261Z,1554773183.261 [CTD_Seabird] No Fault, FailCount= 2
2019-04-09T01:26:23.282Z,1554773183.282 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T01:26:23.284Z,1554773183.284 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T01:26:23.665Z,1554773183.665 [CBIT](FAULT): Main Battery Failure. Count: 551
2019-04-09T01:26:27.706Z,1554773187.706 [CBIT](FAULT): Main Battery Failure. Count: 561
2019-04-09T01:26:31.758Z,1554773191.758 [CBIT](FAULT): Main Battery Failure. Count: 571
2019-04-09T01:26:35.814Z,1554773195.814 [CBIT](FAULT): Main Battery Failure. Count: 581
2019-04-09T01:26:36.338Z,1554773196.338 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T01:26:36.338Z,1554773196.338 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-09T01:26:36.338Z,1554773196.338 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T01:26:36.628Z,1554773196.628 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T01:26:36.628Z,1554773196.628 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-09T01:26:36.742Z,1554773196.742 [CTD_Seabird](INFO): Powering down
2019-04-09T01:26:39.826Z,1554773199.826 [CBIT](FAULT): Main Battery Failure. Count: 591
2019-04-09T01:26:41.437Z,1554773201.437 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T01:26:41.437Z,1554773201.437 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-04-09T01:26:41.437Z,1554773201.437 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-09T01:26:41.439Z,1554773201.439 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T01:26:41.439Z,1554773201.439 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-04-09T01:26:41.439Z,1554773201.439 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-09T01:26:41.440Z,1554773201.440 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T01:26:41.440Z,1554773201.440 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2019-04-09T01:26:41.440Z,1554773201.440 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-09T01:26:41.451Z,1554773201.451 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-09T01:26:41.452Z,1554773201.452 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-09T01:26:41.452Z,1554773201.452 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-09T01:26:41.859Z,1554773201.859 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-09T01:26:41.859Z,1554773201.859 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-04-09T01:26:41.859Z,1554773201.859 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-09T01:26:41.859Z,1554773201.859 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-04-09T01:26:41.860Z,1554773201.860 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-09T01:26:41.860Z,1554773201.860 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2019-04-09T01:26:42.257Z,1554773202.257 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T01:26:43.866Z,1554773203.866 [CBIT](FAULT): Main Battery Failure. Count: 601
2019-04-09T01:26:47.916Z,1554773207.916 [CBIT](FAULT): Main Battery Failure. Count: 611
2019-04-09T01:26:51.946Z,1554773211.946 [CBIT](FAULT): Main Battery Failure. Count: 621
2019-04-09T01:26:55.985Z,1554773215.985 [CBIT](FAULT): Main Battery Failure. Count: 631
2019-04-09T01:27:00.025Z,1554773220.025 [CBIT](FAULT): Main Battery Failure. Count: 641
2019-04-09T01:27:04.077Z,1554773224.077 [CBIT](FAULT): Main Battery Failure. Count: 651
2019-04-09T01:27:08.126Z,1554773228.126 [CBIT](FAULT): Main Battery Failure. Count: 661
2019-04-09T01:27:12.145Z,1554773232.145 [CBIT](FAULT): Main Battery Failure. Count: 671
2019-04-09T01:27:16.181Z,1554773236.181 [CBIT](FAULT): Main Battery Failure. Count: 681
2019-04-09T01:27:20.228Z,1554773240.228 [CBIT](FAULT): Main Battery Failure. Count: 691
2019-04-09T01:27:24.281Z,1554773244.281 [CBIT](FAULT): Main Battery Failure. Count: 701
2019-04-09T01:27:28.299Z,1554773248.299 [CBIT](FAULT): Main Battery Failure. Count: 711
2019-04-09T01:27:32.346Z,1554773252.346 [CBIT](FAULT): Main Battery Failure. Count: 721
2019-04-09T01:27:36.385Z,1554773256.385 [CBIT](FAULT): Main Battery Failure. Count: 731
2019-04-09T01:27:40.437Z,1554773260.437 [CBIT](FAULT): Main Battery Failure. Count: 741
2019-04-09T01:27:41.221Z,1554773261.221 [BPC1](FAULT): Failed to initialize
2019-04-09T01:27:41.222Z,1554773261.222 [BPC1] Communications Fault, FailCount= 1
2019-04-09T01:27:41.222Z,1554773261.222 [BPC1](ERROR): Communications Fault
2019-04-09T01:27:41.255Z,1554773261.255 [CBIT](ERROR): Communications Fault in component: BPC1
2019-04-09T01:27:44.474Z,1554773264.474 [CBIT](FAULT): Main Battery Failure. Count: 751
2019-04-09T01:27:48.527Z,1554773268.527 [CBIT](FAULT): Main Battery Failure. Count: 761
2019-04-09T01:27:52.594Z,1554773272.594 [CBIT](FAULT): Main Battery Failure. Count: 771
2019-04-09T01:27:56.597Z,1554773276.597 [CBIT](FAULT): Main Battery Failure. Count: 781
2019-04-09T01:27:58.860Z,1554773278.860 [CommandLine](IMPORTANT): got command restart application
2019-04-09T01:27:59.012Z,1554773279.012 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T01:27:59.012Z,1554773279.012 [MassServo] No Fault, FailCount= 5
2019-04-09T01:27:59.866Z,1554773279.866 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:27:59.866Z,1554773279.866 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:27:59.942Z,1554773279.942 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-04-09T01:27:59.942Z,1554773279.942 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:27:59.943Z,1554773279.943 [CommandLine](INFO): Join timeout helper Thread ID is 5802
2019-04-09T01:27:59.943Z,1554773279.943 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-04-09T01:27:59.943Z,1554773279.943 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:27:59.944Z,1554773279.944 [NavChartDb](INFO): Join timeout helper Thread ID is 5803
2019-04-09T01:28:00.114Z,1554773280.114 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:28:00.114Z,1554773280.114 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:28:00.122Z,1554773280.122 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-04-09T01:28:00.122Z,1554773280.122 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:28:00.122Z,1554773280.122 [Radio_Surface](INFO): Join timeout helper Thread ID is 5804
2019-04-09T01:28:00.506Z,1554773280.506 [Radio_Surface](INFO): Powering down
2019-04-09T01:28:00.507Z,1554773280.507 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:28:00.507Z,1554773280.507 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:28:00.518Z,1554773280.518 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-04-09T01:28:00.518Z,1554773280.518 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:28:00.518Z,1554773280.518 [DataOverHttps](INFO): Join timeout helper Thread ID is 5805
2019-04-09T01:28:00.894Z,1554773280.894 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:28:00.894Z,1554773280.894 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:28:00.909Z,1554773280.909 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-04-09T01:28:00.909Z,1554773280.909 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:28:00.909Z,1554773280.909 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5806
2019-04-09T01:28:00.958Z,1554773280.958 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T01:28:00.958Z,1554773280.958 [MassServo] Communications Fault, FailCount= 1
2019-04-09T01:28:00.958Z,1554773280.958 [MassServo](ERROR): Communications Fault
2019-04-09T01:28:00.960Z,1554773280.960 [CBIT](FAULT): Main Battery Failure. Count: 791
2019-04-09T01:28:00.962Z,1554773280.962 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T01:28:01.052Z,1554773281.052 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T01:28:01.052Z,1554773281.052 [MassServo](INFO): Powering down
2019-04-09T01:28:01.294Z,1554773281.294 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:28:01.295Z,1554773281.295 [WetLabsBB2FL](INFO): Powering down
2019-04-09T01:28:01.295Z,1554773281.295 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:28:01.311Z,1554773281.311 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-04-09T01:28:01.311Z,1554773281.311 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:28:01.311Z,1554773281.311 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5807
2019-04-09T01:28:01.312Z,1554773281.312 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:28:01.312Z,1554773281.312 [CTD_Seabird](INFO): Powering down
2019-04-09T01:28:01.326Z,1554773281.326 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:28:01.339Z,1554773281.339 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-04-09T01:28:01.339Z,1554773281.339 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:28:01.339Z,1554773281.339 [logger](INFO): Join timeout helper Thread ID is 5808
2019-04-09T01:28:01.339Z,1554773281.339 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:28:01.340Z,1554773281.340 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:28:01.341Z,1554773281.341 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-04-09T01:28:01.341Z,1554773281.341 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:28:01.341Z,1554773281.341 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-04-09T01:28:01.341Z,1554773281.341 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:28:01.342Z,1554773281.342 [controlThread](INFO): Join timeout helper Thread ID is 5809
2019-04-09T01:28:01.394Z,1554773281.394 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T01:28:01.394Z,1554773281.394 [controlThread](DEBUG): Uninitializing ControlThread
2019-04-09T01:28:01.395Z,1554773281.395 [Aanderaa_O2](INFO): Powering down
2019-04-09T01:28:01.397Z,1554773281.397 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T01:28:01.398Z,1554773281.398 [NAL9602](INFO): Powering down
2019-04-09T01:28:01.399Z,1554773281.399 [BR_Ping1D](INFO): uninitialize
2019-04-09T01:28:01.400Z,1554773281.400 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-04-09T01:28:01.401Z,1554773281.401 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-04-09T01:28:01.401Z,1554773281.401 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-04-09T01:28:01.402Z,1554773281.402 [MissionManager](INFO): Uninitializing Mission Default
2019-04-09T01:28:01.402Z,1554773281.402 [Default] Stopped
2019-04-09T01:28:01.402Z,1554773281.402 [Default](DEBUG): Aggregate::uninitialize Default
2019-04-09T01:28:01.402Z,1554773281.402 [Default:B.GoToSurface] Stopped
2019-04-09T01:28:01.402Z,1554773281.402 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-09T01:28:01.402Z,1554773281.402 [Default:CheckIn] Stopped
2019-04-09T01:28:01.402Z,1554773281.402 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-04-09T01:28:01.402Z,1554773281.402 [Default:CheckIn:Read_GPS] Stopped
2019-04-09T01:28:01.405Z,1554773281.405 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-04-09T01:28:01.405Z,1554773281.405 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-04-09T01:28:01.405Z,1554773281.405 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-04-09T01:28:01.405Z,1554773281.405 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-04-09T01:28:01.406Z,1554773281.406 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-04-09T01:28:01.406Z,1554773281.406 [BuoyancyServo](INFO): Powering down
2019-04-09T01:28:01.418Z,1554773281.418 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-04-09T01:28:01.418Z,1554773281.418 [ElevatorServo](INFO): Powering down
2019-04-09T01:28:01.419Z,1554773281.419 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T01:28:01.419Z,1554773281.419 [MassServo](INFO): Powering down
2019-04-09T01:28:01.420Z,1554773281.420 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-09T01:28:01.420Z,1554773281.420 [RudderServo](INFO): Powering down
2019-04-09T01:28:01.421Z,1554773281.421 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-04-09T01:28:01.421Z,1554773281.421 [ThrusterServo](INFO): Powering down
2019-04-09T01:28:01.422Z,1554773281.422 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-04-09T01:28:01.422Z,1554773281.422 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-04-09T01:28:01.422Z,1554773281.422 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-04-09T01:28:01.423Z,1554773281.423 [CBIT](DEBUG): Powering off loads.
2019-04-09T01:28:01.434Z,1554773281.434 [CBIT](DEBUG): Disabling WDT.
2019-04-09T01:28:01.446Z,1554773281.446 [CBIT](DEBUG): Opening all GF detection circuits.
2019-04-09T01:28:01.447Z,1554773281.447 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:28:01.609Z,1554773281.609 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:28:01.614Z,1554773281.614 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:28:01.682Z,1554773281.682 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:28:01.687Z,1554773281.687 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:28:01.753Z,1554773281.753 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T01:28:01.832Z,1554773281.832 [logger ThreadHandler](INFO): Thread cancelled.