2019-04-09T15:35:04.677Z,1554824104.677 [Supervisor](DEBUG): Initializing supervisor.
2019-04-09T15:35:04.680Z,1554824104.680 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-04-09T15:35:04.681Z,1554824104.681 [SyncHandler](INFO): Protected caller Thread ID is 2810
2019-04-09T15:35:04.682Z,1554824104.682 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-04-09T15:35:04.683Z,1554824104.683 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-04-09T15:35:04.683Z,1554824104.683 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2811
2019-04-09T15:35:04.686Z,1554824104.686 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-04-09T15:35:04.698Z,1554824104.698 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-04-09T15:35:04.699Z,1554824104.699 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-04-09T15:35:04.700Z,1554824104.700 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2812
2019-04-09T15:35:04.701Z,1554824104.701 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-04-09T15:35:04.702Z,1554824104.702 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-04-09T15:35:04.702Z,1554824104.702 [logger ThreadHandler](INFO): Protected caller Thread ID is 2813
2019-04-09T15:35:04.704Z,1554824104.704 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-04-09T15:35:04.704Z,1554824104.704 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-04-09T15:35:04.706Z,1554824104.706 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-04-09T15:35:04.800Z,1554824104.800 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-04-09T15:35:04.801Z,1554824104.801 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-04-09T15:35:05.001Z,1554824105.001 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-04-09T15:35:05.002Z,1554824105.002 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-04-09T15:35:05.138Z,1554824105.138 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-04-09T15:35:05.139Z,1554824105.139 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-04-09T15:35:05.735Z,1554824105.735 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-04-09T15:35:05.736Z,1554824105.736 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-04-09T15:35:06.244Z,1554824106.244 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-04-09T15:35:06.244Z,1554824106.244 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-04-09T15:35:06.709Z,1554824106.709 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-04-09T15:35:06.710Z,1554824106.710 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-04-09T15:35:07.014Z,1554824107.014 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-04-09T15:35:07.015Z,1554824107.015 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-04-09T15:35:07.344Z,1554824107.344 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-04-09T15:35:07.345Z,1554824107.345 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-04-09T15:35:07.737Z,1554824107.737 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-04-09T15:35:07.738Z,1554824107.738 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-04-09T15:35:07.887Z,1554824107.887 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-04-09T15:35:07.888Z,1554824107.888 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-04-09T15:35:07.992Z,1554824107.992 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-04-09T15:35:07.993Z,1554824107.993 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-04-09T15:35:08.073Z,1554824108.073 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-04-09T15:35:08.175Z,1554824108.175 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-04-09T15:35:08.176Z,1554824108.176 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-04-09T15:35:08.369Z,1554824108.369 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-04-09T15:35:08.370Z,1554824108.370 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-04-09T15:35:08.577Z,1554824108.577 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-04-09T15:35:08.579Z,1554824108.579 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2019-04-09T15:35:08.579Z,1554824108.579 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2019-04-09T15:35:08.662Z,1554824108.662 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2019-04-09T15:35:08.893Z,1554824108.893 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-04-09T15:35:08.894Z,1554824108.894 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2019-04-09T15:35:08.989Z,1554824108.989 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2019-04-09T15:35:09.162Z,1554824109.162 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2019-04-09T15:35:09.375Z,1554824109.375 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2019-04-09T15:35:09.460Z,1554824109.460 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2019-04-09T15:35:09.558Z,1554824109.558 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2019-04-09T15:35:09.652Z,1554824109.652 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2019-04-09T15:35:09.803Z,1554824109.803 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2019-04-09T15:35:09.907Z,1554824109.907 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2019-04-09T15:35:10.005Z,1554824110.005 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2019-04-09T15:35:10.006Z,1554824110.006 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-04-09T15:35:10.010Z,1554824110.010 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-04-09T15:35:10.106Z,1554824110.106 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-04-09T15:35:10.107Z,1554824110.107 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-04-09T15:35:10.232Z,1554824110.232 [BuoyancyServo] Loaded
2019-04-09T15:35:10.233Z,1554824110.233 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-04-09T15:35:10.248Z,1554824110.248 [ElevatorServo] Loaded
2019-04-09T15:35:10.248Z,1554824110.248 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-04-09T15:35:10.263Z,1554824110.263 [MassServo] Loaded
2019-04-09T15:35:10.264Z,1554824110.264 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-04-09T15:35:10.279Z,1554824110.279 [RudderServo] Loaded
2019-04-09T15:35:10.279Z,1554824110.279 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-04-09T15:35:10.293Z,1554824110.293 [ThrusterServo] Loaded
2019-04-09T15:35:10.293Z,1554824110.293 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-04-09T15:35:10.294Z,1554824110.294 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-04-09T15:35:10.294Z,1554824110.294 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-04-09T15:35:10.427Z,1554824110.427 [SBIT](DEBUG): Construct Startup Built In Test.
2019-04-09T15:35:10.455Z,1554824110.455 [SBIT] Loaded
2019-04-09T15:35:10.456Z,1554824110.456 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-04-09T15:35:10.456Z,1554824110.456 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-04-09T15:35:10.484Z,1554824110.484 [IBIT] Loaded
2019-04-09T15:35:10.484Z,1554824110.484 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-04-09T15:35:10.488Z,1554824110.488 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-04-09T15:35:10.873Z,1554824110.873 [CBIT] Loaded
2019-04-09T15:35:10.873Z,1554824110.873 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-04-09T15:35:10.874Z,1554824110.874 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-04-09T15:35:10.874Z,1554824110.874 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-04-09T15:35:11.301Z,1554824111.301 [Aanderaa_O2] Loaded
2019-04-09T15:35:11.301Z,1554824111.301 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-04-09T15:35:11.310Z,1554824111.310 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-04-09T15:35:11.316Z,1554824111.316 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-04-09T15:35:11.317Z,1554824111.317 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-04-09T15:35:11.330Z,1554824111.330 [CTD_Seabird](INFO): created writer for : depth
2019-04-09T15:35:11.330Z,1554824111.330 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-04-09T15:35:11.347Z,1554824111.347 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-04-09T15:35:11.348Z,1554824111.348 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-04-09T15:35:11.353Z,1554824111.353 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-04-09T15:35:11.354Z,1554824111.354 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-04-09T15:35:11.379Z,1554824111.379 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-04-09T15:35:11.380Z,1554824111.380 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-04-09T15:35:11.396Z,1554824111.396 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-04-09T15:35:11.397Z,1554824111.397 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-04-09T15:35:11.414Z,1554824111.414 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-04-09T15:35:11.445Z,1554824111.445 [CTD_Seabird] Loaded
2019-04-09T15:35:11.445Z,1554824111.445 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-04-09T15:35:11.446Z,1554824111.446 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406654E0
2019-04-09T15:35:11.447Z,1554824111.447 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2892
2019-04-09T15:35:11.488Z,1554824111.488 [ESPComponent] Loaded
2019-04-09T15:35:11.488Z,1554824111.488 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-04-09T15:35:11.502Z,1554824111.502 [PAR_Licor] Loaded
2019-04-09T15:35:11.503Z,1554824111.503 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-04-09T15:35:11.550Z,1554824111.550 [WetLabsBB2FL] Loaded
2019-04-09T15:35:11.550Z,1554824111.550 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-04-09T15:35:11.551Z,1554824111.551 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406954E0
2019-04-09T15:35:11.552Z,1554824111.552 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2893
2019-04-09T15:35:11.553Z,1554824111.553 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-04-09T15:35:11.553Z,1554824111.553 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-04-09T15:35:11.747Z,1554824111.747 [DepthRateCalculator] Loaded
2019-04-09T15:35:11.747Z,1554824111.747 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-04-09T15:35:11.753Z,1554824111.753 [PitchRateCalculator] Loaded
2019-04-09T15:35:11.753Z,1554824111.753 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-04-09T15:35:11.769Z,1554824111.769 [SpeedCalculator] Loaded
2019-04-09T15:35:11.769Z,1554824111.769 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-04-09T15:35:11.791Z,1554824111.791 [TempGradientCalculator] Loaded
2019-04-09T15:35:11.791Z,1554824111.791 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-04-09T15:35:11.797Z,1554824111.797 [YawRateCalculator] Loaded
2019-04-09T15:35:11.797Z,1554824111.797 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-04-09T15:35:11.830Z,1554824111.830 [ElevatorOffsetCalculator] Loaded
2019-04-09T15:35:11.830Z,1554824111.830 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-04-09T15:35:11.831Z,1554824111.831 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-04-09T15:35:11.831Z,1554824111.831 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-04-09T15:35:11.858Z,1554824111.858 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-04-09T15:35:11.858Z,1554824111.858 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-04-09T15:35:11.960Z,1554824111.960 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-04-09T15:35:11.960Z,1554824111.960 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-04-09T15:35:12.020Z,1554824112.020 [DeadReckonUsingSpeedCalculator] Loaded
2019-04-09T15:35:12.020Z,1554824112.020 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2019-04-09T15:35:12.080Z,1554824112.080 [DeadReckonWithRespectToSeafloor] Loaded
2019-04-09T15:35:12.080Z,1554824112.080 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2019-04-09T15:35:12.101Z,1554824112.101 [NavChart] Loaded
2019-04-09T15:35:12.101Z,1554824112.101 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-04-09T15:35:12.105Z,1554824112.105 [UniversalFixResidualReporter] Loaded
2019-04-09T15:35:12.106Z,1554824112.106 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-04-09T15:35:12.106Z,1554824112.106 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-04-09T15:35:12.107Z,1554824112.107 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-04-09T15:35:12.413Z,1554824112.413 [AcousticModem_Benthos_ATM900] Loaded
2019-04-09T15:35:12.413Z,1554824112.413 [ComponentRegistry](DEBUG): SyncComponent "AcousticModem_Benthos_ATM900" handled in the control thread.
2019-04-09T15:35:12.484Z,1554824112.484 [DataOverHttps] Loaded
2019-04-09T15:35:12.485Z,1554824112.485 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-04-09T15:35:12.486Z,1554824112.486 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409714E0
2019-04-09T15:35:12.486Z,1554824112.486 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2894
2019-04-09T15:35:12.499Z,1554824112.499 [Depth_Keller] Loaded
2019-04-09T15:35:12.500Z,1554824112.500 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-04-09T15:35:12.590Z,1554824112.590 [NAL9602] Loaded
2019-04-09T15:35:12.591Z,1554824112.591 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-04-09T15:35:12.596Z,1554824112.596 [Onboard] Loaded
2019-04-09T15:35:12.596Z,1554824112.596 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-04-09T15:35:12.600Z,1554824112.600 [Radio_Surface] Loaded
2019-04-09T15:35:12.600Z,1554824112.600 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-04-09T15:35:12.601Z,1554824112.601 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409A14E0
2019-04-09T15:35:12.601Z,1554824112.601 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2895
2019-04-09T15:35:13.884Z,1554824113.884 [BPC1] Loaded
2019-04-09T15:35:13.884Z,1554824113.884 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-04-09T15:35:13.914Z,1554824113.914 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minrange
2019-04-09T15:35:13.916Z,1554824113.916 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.maxrange
2019-04-09T15:35:13.918Z,1554824113.918 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.soundspeed
2019-04-09T15:35:13.920Z,1554824113.920 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.automode
2019-04-09T15:35:13.923Z,1554824113.923 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.gainindex
2019-04-09T15:35:13.925Z,1554824113.925 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.minconfidence
2019-04-09T15:35:13.927Z,1554824113.927 [BR_Ping1D](CRITICAL): No configuration setting for: BR_Ping1D.pingenable
2019-04-09T15:35:13.929Z,1554824113.929 [BR_Ping1D] Loaded
2019-04-09T15:35:13.929Z,1554824113.929 [ComponentRegistry](DEBUG): SyncComponent "BR_Ping1D" handled in the control thread.
2019-04-09T15:35:13.930Z,1554824113.930 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-04-09T15:35:13.930Z,1554824113.930 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-04-09T15:35:13.993Z,1554824113.993 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-04-09T15:35:13.993Z,1554824113.993 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-04-09T15:35:14.059Z,1554824114.059 [VerticalControl](DEBUG): Construct VerticalControl.
2019-04-09T15:35:14.143Z,1554824114.143 [VerticalControl] Loaded
2019-04-09T15:35:14.143Z,1554824114.143 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-04-09T15:35:14.144Z,1554824114.144 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-04-09T15:35:14.201Z,1554824114.201 [HorizontalControl] Loaded
2019-04-09T15:35:14.201Z,1554824114.201 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-04-09T15:35:14.202Z,1554824114.202 [SpeedControl](DEBUG): Construct SpeedControl.
2019-04-09T15:35:14.203Z,1554824114.203 [SpeedControl] Loaded
2019-04-09T15:35:14.204Z,1554824114.204 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-04-09T15:35:14.204Z,1554824114.204 [LoopControl](DEBUG): Construct LoopControl.
2019-04-09T15:35:14.205Z,1554824114.205 [LoopControl] Loaded
2019-04-09T15:35:14.205Z,1554824114.205 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-04-09T15:35:14.206Z,1554824114.206 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-04-09T15:35:14.206Z,1554824114.206 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-04-09T15:35:14.219Z,1554824114.219 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-04-09T15:35:14.220Z,1554824114.220 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-04-09T15:35:14.491Z,1554824114.491 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-04-09T15:35:14.495Z,1554824114.495 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-04-09T15:35:14.496Z,1554824114.496 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-04-09T15:35:14.503Z,1554824114.503 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-04-09T15:35:14.504Z,1554824114.504 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACE4E0
2019-04-09T15:35:14.504Z,1554824114.504 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2896
2019-04-09T15:35:14.509Z,1554824114.509 [Supervisor](INFO): Main Thread ID is 4347
2019-04-09T15:35:14.509Z,1554824114.509 [Supervisor](DEBUG): Running supervisor.
2019-04-09T15:35:14.510Z,1554824114.510 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2897
2019-04-09T15:35:14.512Z,1554824114.512 [controlThread ThreadHandler](INFO): Handler Thread ID is 2898
2019-04-09T15:35:14.513Z,1554824114.513 [controlThread](DEBUG): Initializing ControlThread
2019-04-09T15:35:14.514Z,1554824114.514 [SBIT](INFO): Initialize SBIT Component.
2019-04-09T15:35:14.515Z,1554824114.515 [SBIT](IMPORTANT): git: 2019-03-18-31-ge98d5dc
2019-04-09T15:35:14.515Z,1554824114.515 [SBIT](INFO): git hash: e98d5dc50cbc39e402f19b9946ff4dc0776c8725
2019-04-09T15:35:14.515Z,1554824114.515 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-04-09T15:35:14.517Z,1554824114.517 [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-09T15:35:14.518Z,1554824114.518 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2019-04-09T15:35:14.519Z,1554824114.519 [IBIT](INFO): Initialize IBIT Component.
2019-04-09T15:35:14.520Z,1554824114.520 [CBIT](DEBUG): Initialize CBIT Component.
2019-04-09T15:35:14.520Z,1554824114.520 [logger ThreadHandler](INFO): Handler Thread ID is 2899
2019-04-09T15:35:14.534Z,1554824114.534 [CBIT](DEBUG): Initialized mux pins.
2019-04-09T15:35:14.534Z,1554824114.534 [CBIT](DEBUG): Initializing the watchdog timer.
2019-04-09T15:35:14.546Z,1554824114.546 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2900
2019-04-09T15:35:14.547Z,1554824114.547 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T15:35:14.550Z,1554824114.550 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T15:35:14.552Z,1554824114.552 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2902
2019-04-09T15:35:14.553Z,1554824114.553 [WetLabsBB2FL](INFO): Powering down
2019-04-09T15:35:14.558Z,1554824114.558 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-04-09T15:35:14.558Z,1554824114.558 [CBIT](DEBUG): Initializing heartbeat.
2019-04-09T15:35:14.582Z,1554824114.582 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2903
2019-04-09T15:35:14.583Z,1554824114.583 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-04-09T15:35:14.591Z,1554824114.591 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2904
2019-04-09T15:35:14.606Z,1554824114.606 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2905
2019-04-09T15:35:14.609Z,1554824114.609 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-04-09T15:35:14.609Z,1554824114.609 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-04-09T15:35:14.610Z,1554824114.610 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-04-09T15:35:14.610Z,1554824114.610 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-04-09T15:35:14.610Z,1554824114.610 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-04-09T15:35:14.611Z,1554824114.611 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-04-09T15:35:14.611Z,1554824114.611 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-04-09T15:35:14.611Z,1554824114.611 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-04-09T15:35:14.611Z,1554824114.611 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-04-09T15:35:14.611Z,1554824114.611 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-04-09T15:35:14.611Z,1554824114.611 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-04-09T15:35:14.612Z,1554824114.612 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-04-09T15:35:14.612Z,1554824114.612 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-04-09T15:35:14.612Z,1554824114.612 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-04-09T15:35:14.612Z,1554824114.612 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-04-09T15:35:14.612Z,1554824114.612 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-04-09T15:35:14.630Z,1554824114.630 [CBIT](DEBUG): Deactivating GF circuits.
2019-04-09T15:35:14.630Z,1554824114.630 [CBIT](DEBUG): Deactivating emergency mode.
2019-04-09T15:35:14.666Z,1554824114.666 [CBIT](DEBUG): Backplane powered.
2019-04-09T15:35:14.670Z,1554824114.670 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-04-09T15:35:14.671Z,1554824114.671 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-04-09T15:35:14.671Z,1554824114.671 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-04-09T15:35:14.671Z,1554824114.671 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-04-09T15:35:14.672Z,1554824114.672 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-04-09T15:35:14.672Z,1554824114.672 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-04-09T15:35:14.673Z,1554824114.673 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T15:35:14.674Z,1554824114.674 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-04-09T15:35:14.675Z,1554824114.675 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-04-09T15:35:14.679Z,1554824114.679 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-04-09T15:35:14.681Z,1554824114.681 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-04-09T15:35:14.682Z,1554824114.682 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-04-09T15:35:14.682Z,1554824114.682 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-04-09T15:35:14.683Z,1554824114.683 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-04-09T15:35:14.705Z,1554824114.705 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-09T15:35:14.741Z,1554824114.741 [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-09T15:35:14.742Z,1554824114.742 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-04-09T15:35:14.817Z,1554824114.817 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-04-09T15:35:14.818Z,1554824114.818 [Default:A.Wait](DEBUG): Construct Wait.
2019-04-09T15:35:14.820Z,1554824114.820 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-09T15:35:14.873Z,1554824114.873 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-04-09T15:35:14.875Z,1554824114.875 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-04-09T15:35:14.889Z,1554824114.889 [Default:E.Execute](DEBUG): Construct Execute.
2019-04-09T15:35:14.892Z,1554824114.892 [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-09T15:35:14.918Z,1554824114.918 [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-09T15:35:14.939Z,1554824114.939 [ESPComponent](INFO): powering down ESP
2019-04-09T15:35:15.026Z,1554824115.026 [Radio_Surface](INFO): Powering up
2019-04-09T15:35:15.598Z,1554824115.598 [PAR_Licor](INFO): A/D timeout, 12 tries over 146 ms

2019-04-09T15:35:15.599Z,1554824115.599 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T15:35:15.599Z,1554824115.599 [PAR_Licor](ERROR): Data Fault
2019-04-09T15:35:15.599Z,1554824115.599 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T15:35:15.600Z,1554824115.600 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T15:35:15.616Z,1554824115.616 [Depth_Keller](ERROR): Pressure reading out of range: 1895.684326 decibar
2019-04-09T15:35:15.663Z,1554824115.663 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-04-09T15:35:15.710Z,1554824115.710 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-04-09T15:35:15.715Z,1554824115.715 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-04-09T15:35:15.716Z,1554824115.716 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-04-09T15:35:15.730Z,1554824115.730 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-04-09T15:35:15.731Z,1554824115.731 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T15:35:15.734Z,1554824115.734 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T15:35:15.735Z,1554824115.735 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-09T15:35:15.738Z,1554824115.738 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-09T15:35:15.739Z,1554824115.739 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-04-09T15:35:15.778Z,1554824115.778 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-04-09T15:35:15.781Z,1554824115.781 [CommandLine](FAULT): Scheduling is paused
2019-04-09T15:35:15.781Z,1554824115.781 [CBIT](INFO): Critical error at 20190409T153513
2019-04-09T15:35:15.782Z,1554824115.782 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-04-09T15:35:15.783Z,1554824115.783 [CBIT](FAULT): Main Battery Failure. Count: 1
2019-04-09T15:35:15.790Z,1554824115.790 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T15:35:15.790Z,1554824115.790 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T15:35:15.791Z,1554824115.791 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T15:35:16.334Z,1554824116.334 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms

2019-04-09T15:35:16.334Z,1554824116.334 [PAR_Licor] Data Fault, FailCount= 2
2019-04-09T15:35:16.334Z,1554824116.334 [PAR_Licor](ERROR): Data Fault
2019-04-09T15:35:16.368Z,1554824116.368 [BR_Ping1D](INFO): startup: 0
2019-04-09T15:35:16.368Z,1554824116.368 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T15:35:16.382Z,1554824116.382 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T15:35:16.382Z,1554824116.382 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T15:35:16.383Z,1554824116.383 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T15:35:16.383Z,1554824116.383 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T15:35:16.705Z,1554824116.705 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T15:35:16.706Z,1554824116.706 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T15:35:16.706Z,1554824116.706 [PAR_Licor] No Fault, FailCount= 2
2019-04-09T15:35:16.731Z,1554824116.731 [BR_Ping1D](INFO): Starting
2019-04-09T15:35:17.137Z,1554824117.137 [BR_Ping1D](INFO): Starting
2019-04-09T15:35:17.137Z,1554824117.137 [BR_Ping1D](INFO): readConfig
2019-04-09T15:35:17.138Z,1554824117.138 [BR_Ping1D](INFO): startup: 1
2019-04-09T15:35:17.138Z,1554824117.138 [BR_Ping1D](INFO): UART requesting status
2019-04-09T15:35:17.658Z,1554824117.658 [PAR_Licor](INFO): A/D timeout, 9 tries over 138 ms

2019-04-09T15:35:17.658Z,1554824117.658 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T15:35:17.658Z,1554824117.658 [PAR_Licor](ERROR): Data Fault
2019-04-09T15:35:17.694Z,1554824117.694 [BR_Ping1D](INFO): Starting
2019-04-09T15:35:17.694Z,1554824117.694 [BR_Ping1D](INFO): readConfig
2019-04-09T15:35:17.694Z,1554824117.694 [BR_Ping1D](INFO): startup: 2
2019-04-09T15:35:17.694Z,1554824117.694 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T15:35:17.694Z,1554824117.694 [BR_Ping1D](INFO): elapsed 1.3260
2019-04-09T15:35:17.695Z,1554824117.695 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T15:35:17.695Z,1554824117.695 [BR_Ping1D](INFO): received full packet, type: 1210
2019-04-09T15:35:17.696Z,1554824117.696 [BR_Ping1D](INFO): parsing data, res: 20
2019-04-09T15:35:17.696Z,1554824117.696 [BR_Ping1D](INFO): Parsing UART packet of type 1210
2019-04-09T15:35:17.696Z,1554824117.696 [BR_Ping1D](INFO):  voltage: 5056
2019-04-09T15:35:17.696Z,1554824117.696 [BR_Ping1D](INFO):  interval: 7681
2019-04-09T15:35:17.696Z,1554824117.696 [BR_Ping1D](INFO):  gain: 4
2019-04-09T15:35:17.696Z,1554824117.696 [BR_Ping1D](INFO):  auto: 1
2019-04-09T15:35:17.697Z,1554824117.697 [BR_Ping1D](INFO): received full packet, type: 1215
2019-04-09T15:35:17.697Z,1554824117.697 [BR_Ping1D](INFO): CRC error in received UART packet
2019-04-09T15:35:17.697Z,1554824117.697 [BR_Ping1D](INFO): received UART packets: 1
2019-04-09T15:35:17.697Z,1554824117.697 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-04-09T15:35:17.697Z,1554824117.697 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-04-09T15:35:17.698Z,1554824117.698 [BR_Ping1D](INFO): (ping interval) 500 <-> 0
2019-04-09T15:35:17.698Z,1554824117.698 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-04-09T15:35:17.698Z,1554824117.698 [BR_Ping1D](INFO): (soundspeed) 1500000 <-> 65535
2019-04-09T15:35:17.699Z,1554824117.699 [BR_Ping1D](INFO): (ping enable) 1 <-> 0
2019-04-09T15:35:17.731Z,1554824117.731 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T15:35:17.731Z,1554824117.731 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T15:35:17.731Z,1554824117.731 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T15:35:17.945Z,1554824117.945 [BR_Ping1D](INFO): Starting
2019-04-09T15:35:17.945Z,1554824117.945 [BR_Ping1D](INFO): readConfig
2019-04-09T15:35:17.945Z,1554824117.945 [BR_Ping1D](INFO): startup: 0
2019-04-09T15:35:17.946Z,1554824117.946 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T15:35:17.958Z,1554824117.958 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T15:35:17.958Z,1554824117.958 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T15:35:17.958Z,1554824117.958 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T15:35:17.959Z,1554824117.959 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T15:35:18.302Z,1554824118.302 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T15:35:18.302Z,1554824118.302 [MassServo] Communications Fault, FailCount= 1
2019-04-09T15:35:18.302Z,1554824118.302 [MassServo](ERROR): Communications Fault
2019-04-09T15:35:18.353Z,1554824118.353 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T15:35:18.409Z,1554824118.409 [BR_Ping1D](INFO): Starting
2019-04-09T15:35:18.482Z,1554824118.482 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T15:35:18.482Z,1554824118.482 [MassServo](INFO): Powering down
2019-04-09T15:35:18.898Z,1554824118.898 [PAR_Licor](INFO): A/D timeout, 8 tries over 134 ms

2019-04-09T15:35:18.898Z,1554824118.898 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T15:35:18.898Z,1554824118.898 [PAR_Licor](ERROR): Data Fault
2019-04-09T15:35:18.941Z,1554824118.941 [BR_Ping1D](INFO): Starting
2019-04-09T15:35:18.941Z,1554824118.941 [BR_Ping1D](INFO): readConfig
2019-04-09T15:35:18.941Z,1554824118.941 [BR_Ping1D](INFO): startup: 1
2019-04-09T15:35:18.942Z,1554824118.942 [BR_Ping1D](INFO): UART requesting status
2019-04-09T15:35:19.074Z,1554824119.074 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T15:35:19.074Z,1554824119.074 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T15:35:19.074Z,1554824119.074 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T15:35:19.190Z,1554824119.190 [BR_Ping1D](INFO): Starting
2019-04-09T15:35:19.190Z,1554824119.190 [BR_Ping1D](INFO): readConfig
2019-04-09T15:35:19.191Z,1554824119.191 [BR_Ping1D](INFO): startup: 2
2019-04-09T15:35:19.191Z,1554824119.191 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T15:35:19.191Z,1554824119.191 [BR_Ping1D](INFO): elapsed 1.2452
2019-04-09T15:35:19.191Z,1554824119.191 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T15:35:19.192Z,1554824119.192 [BR_Ping1D](INFO): received full packet, type: 1210
2019-04-09T15:35:19.192Z,1554824119.192 [BR_Ping1D](INFO): parsing data, res: 20
2019-04-09T15:35:19.192Z,1554824119.192 [BR_Ping1D](INFO): Parsing UART packet of type 1210
2019-04-09T15:35:19.192Z,1554824119.192 [BR_Ping1D](INFO):  voltage: 5056
2019-04-09T15:35:19.192Z,1554824119.192 [BR_Ping1D](INFO):  interval: 7681
2019-04-09T15:35:19.192Z,1554824119.192 [BR_Ping1D](INFO):  gain: 4
2019-04-09T15:35:19.193Z,1554824119.193 [BR_Ping1D](INFO):  auto: 1
2019-04-09T15:35:19.193Z,1554824119.193 [BR_Ping1D](INFO): received full packet, type: 1215
2019-04-09T15:35:19.193Z,1554824119.193 [BR_Ping1D](INFO): CRC error in received UART packet
2019-04-09T15:35:19.193Z,1554824119.193 [BR_Ping1D](INFO): received UART packets: 1
2019-04-09T15:35:19.193Z,1554824119.193 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-04-09T15:35:19.194Z,1554824119.194 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-04-09T15:35:19.194Z,1554824119.194 [BR_Ping1D](INFO): (ping interval) 500 <-> 0
2019-04-09T15:35:19.194Z,1554824119.194 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-04-09T15:35:19.195Z,1554824119.195 [BR_Ping1D](INFO): (soundspeed) 1500000 <-> 65535
2019-04-09T15:35:19.195Z,1554824119.195 [BR_Ping1D](INFO): (ping enable) 1 <-> 0
2019-04-09T15:35:19.296Z,1554824119.296 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T15:35:19.415Z,1554824119.415 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T15:35:19.730Z,1554824119.730 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T15:35:19.730Z,1554824119.730 [MassServo] No Fault, FailCount= 1
2019-04-09T15:35:19.862Z,1554824119.862 [PAR_Licor](INFO): A/D timeout, 6 tries over 125 ms

2019-04-09T15:35:19.862Z,1554824119.862 [PAR_Licor] Data Fault, FailCount= 2
2019-04-09T15:35:19.862Z,1554824119.862 [PAR_Licor](ERROR): Data Fault
2019-04-09T15:35:19.897Z,1554824119.897 [BR_Ping1D](INFO): Starting
2019-04-09T15:35:19.897Z,1554824119.897 [BR_Ping1D](INFO): readConfig
2019-04-09T15:35:19.897Z,1554824119.897 [BR_Ping1D](INFO): startup: 0
2019-04-09T15:35:19.898Z,1554824119.898 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T15:35:19.910Z,1554824119.910 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T15:35:19.910Z,1554824119.910 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T15:35:19.910Z,1554824119.910 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T15:35:19.911Z,1554824119.911 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T15:35:19.956Z,1554824119.956 [CBIT](FAULT): Main Battery Failure. Count: 11
2019-04-09T15:35:19.958Z,1554824119.958 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T15:35:19.959Z,1554824119.959 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T15:35:19.959Z,1554824119.959 [PAR_Licor] No Fault, FailCount= 2
2019-04-09T15:35:20.162Z,1554824120.162 [BR_Ping1D](INFO): Starting
2019-04-09T15:35:20.565Z,1554824120.565 [BR_Ping1D](INFO): Starting
2019-04-09T15:35:20.565Z,1554824120.565 [BR_Ping1D](INFO): readConfig
2019-04-09T15:35:20.565Z,1554824120.565 [BR_Ping1D](INFO): startup: 1
2019-04-09T15:35:20.565Z,1554824120.565 [BR_Ping1D](INFO): UART requesting status
2019-04-09T15:35:21.079Z,1554824121.079 [PAR_Licor](INFO): A/D timeout, 8 tries over 130 ms

2019-04-09T15:35:21.079Z,1554824121.079 [PAR_Licor] Data Fault, FailCount= 1
2019-04-09T15:35:21.079Z,1554824121.079 [PAR_Licor](ERROR): Data Fault
2019-04-09T15:35:21.099Z,1554824121.099 [BR_Ping1D](INFO): Starting
2019-04-09T15:35:21.099Z,1554824121.099 [BR_Ping1D](INFO): readConfig
2019-04-09T15:35:21.099Z,1554824121.099 [BR_Ping1D](INFO): startup: 2
2019-04-09T15:35:21.099Z,1554824121.099 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T15:35:21.099Z,1554824121.099 [BR_Ping1D](INFO): elapsed 1.2015
2019-04-09T15:35:21.099Z,1554824121.099 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T15:35:21.100Z,1554824121.100 [BR_Ping1D](INFO): received full packet, type: 1210
2019-04-09T15:35:21.100Z,1554824121.100 [BR_Ping1D](INFO): CRC error in received UART packet
2019-04-09T15:35:21.100Z,1554824121.100 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T15:35:21.100Z,1554824121.100 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-04-09T15:35:21.100Z,1554824121.100 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-04-09T15:35:21.101Z,1554824121.101 [BR_Ping1D](INFO): (ping interval) 500 <-> 0
2019-04-09T15:35:21.101Z,1554824121.101 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-04-09T15:35:21.101Z,1554824121.101 [BR_Ping1D](INFO): (soundspeed) 1500000 <-> 65535
2019-04-09T15:35:21.102Z,1554824121.102 [BR_Ping1D](INFO): (ping enable) 1 <-> 0
2019-04-09T15:35:21.135Z,1554824121.135 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T15:35:21.135Z,1554824121.135 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T15:35:21.135Z,1554824121.135 [PAR_Licor] No Fault, FailCount= 1
2019-04-09T15:35:21.373Z,1554824121.373 [BR_Ping1D](INFO): Starting
2019-04-09T15:35:21.373Z,1554824121.373 [BR_Ping1D](INFO): readConfig
2019-04-09T15:35:21.374Z,1554824121.374 [BR_Ping1D](INFO): startup: 0
2019-04-09T15:35:21.374Z,1554824121.374 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T15:35:21.386Z,1554824121.386 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T15:35:21.386Z,1554824121.386 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T15:35:21.386Z,1554824121.386 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T15:35:21.387Z,1554824121.387 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T15:35:21.710Z,1554824121.710 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T15:35:21.710Z,1554824121.710 [MassServo] Communications Fault, FailCount= 2
2019-04-09T15:35:21.710Z,1554824121.710 [MassServo](ERROR): Communications Fault
2019-04-09T15:35:21.715Z,1554824121.715 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T15:35:21.884Z,1554824121.884 [PAR_Licor](INFO): A/D timeout, 9 tries over 127 ms

2019-04-09T15:35:21.884Z,1554824121.884 [PAR_Licor] Data Fault, FailCount= 2
2019-04-09T15:35:21.884Z,1554824121.884 [PAR_Licor](ERROR): Data Fault
2019-04-09T15:35:21.913Z,1554824121.913 [BR_Ping1D](INFO): Starting
2019-04-09T15:35:21.913Z,1554824121.913 [BR_Ping1D](INFO): readConfig
2019-04-09T15:35:21.914Z,1554824121.914 [BR_Ping1D](INFO): startup: 1
2019-04-09T15:35:21.914Z,1554824121.914 [BR_Ping1D](INFO): UART requesting status
2019-04-09T15:35:22.123Z,1554824122.123 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T15:35:22.123Z,1554824122.123 [MassServo](INFO): Powering down
2019-04-09T15:35:22.127Z,1554824122.127 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T15:35:22.128Z,1554824122.128 [CBIT](INFO): Clearing failed state for component PAR_Licor
2019-04-09T15:35:22.128Z,1554824122.128 [PAR_Licor] No Fault, FailCount= 2
2019-04-09T15:35:22.182Z,1554824122.182 [BR_Ping1D](INFO): Starting
2019-04-09T15:35:22.182Z,1554824122.182 [BR_Ping1D](INFO): readConfig
2019-04-09T15:35:22.182Z,1554824122.182 [BR_Ping1D](INFO): startup: 2
2019-04-09T15:35:22.182Z,1554824122.182 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T15:35:22.183Z,1554824122.183 [BR_Ping1D](INFO): elapsed 0.8088
2019-04-09T15:35:22.183Z,1554824122.183 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T15:35:22.183Z,1554824122.183 [BR_Ping1D](INFO): received full packet, type: 1210
2019-04-09T15:35:22.183Z,1554824122.183 [BR_Ping1D](INFO): parsing data, res: 20
2019-04-09T15:35:22.184Z,1554824122.184 [BR_Ping1D](INFO): Parsing UART packet of type 1210
2019-04-09T15:35:22.184Z,1554824122.184 [BR_Ping1D](INFO):  voltage: 5056
2019-04-09T15:35:22.184Z,1554824122.184 [BR_Ping1D](INFO):  interval: 7681
2019-04-09T15:35:22.184Z,1554824122.184 [BR_Ping1D](INFO):  gain: 4
2019-04-09T15:35:22.184Z,1554824122.184 [BR_Ping1D](INFO):  auto: 1
2019-04-09T15:35:22.185Z,1554824122.185 [BR_Ping1D](INFO): received full packet, type: 1215
2019-04-09T15:35:22.185Z,1554824122.185 [BR_Ping1D](INFO): CRC error in received UART packet
2019-04-09T15:35:22.185Z,1554824122.185 [BR_Ping1D](INFO): received UART packets: 1
2019-04-09T15:35:22.185Z,1554824122.185 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-04-09T15:35:22.185Z,1554824122.185 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-04-09T15:35:22.185Z,1554824122.185 [BR_Ping1D](INFO): (ping interval) 500 <-> 0
2019-04-09T15:35:22.198Z,1554824122.198 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-04-09T15:35:22.198Z,1554824122.198 [BR_Ping1D](INFO): (soundspeed) 1500000 <-> 65535
2019-04-09T15:35:22.199Z,1554824122.199 [BR_Ping1D](INFO): (ping enable) 1 <-> 0
2019-04-09T15:35:22.199Z,1554824122.199 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-04-09T15:35:22.199Z,1554824122.199 [BR_Ping1D] Data Fault, FailCount= 1
2019-04-09T15:35:22.199Z,1554824122.199 [BR_Ping1D](ERROR): Data Fault
2019-04-09T15:35:22.237Z,1554824122.237 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T15:35:22.690Z,1554824122.690 [PAR_Licor](INFO): A/D timeout, 7 tries over 126 ms

2019-04-09T15:35:22.690Z,1554824122.690 [PAR_Licor] Data Fault, FailCount= 3
2019-04-09T15:35:22.690Z,1554824122.690 [PAR_Licor](ERROR): Data Fault
2019-04-09T15:35:22.727Z,1554824122.727 [BR_Ping1D](INFO): Stop
2019-04-09T15:35:22.727Z,1554824122.727 [BR_Ping1D](INFO): uninitialize
2019-04-09T15:35:22.727Z,1554824122.727 [BR_Ping1D](INFO): Powering down
2019-04-09T15:35:22.851Z,1554824122.851 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T15:35:22.971Z,1554824122.971 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T15:35:22.975Z,1554824122.975 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T15:35:22.975Z,1554824122.975 [MassServo] No Fault, FailCount= 2
2019-04-09T15:35:22.976Z,1554824122.976 [CBIT](ERROR): Data Fault in component: PAR_Licor
2019-04-09T15:35:22.976Z,1554824122.976 [CBIT](CRITICAL): Data Fault in component: PAR_Licor
2019-04-09T15:35:23.015Z,1554824123.015 [BR_Ping1D](INFO): Stopping
2019-04-09T15:35:23.044Z,1554824123.044 [CBIT](INFO): Critical error at 20190409T153522
2019-04-09T15:35:23.402Z,1554824123.402 [BR_Ping1D](INFO): Stopping
2019-04-09T15:35:23.805Z,1554824123.805 [BR_Ping1D](INFO): Stopped
2019-04-09T15:35:23.844Z,1554824123.844 [CBIT](FAULT): Main Battery Failure. Count: 21
2019-04-09T15:35:23.859Z,1554824123.859 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T15:35:23.859Z,1554824123.859 [BR_Ping1D] No Fault, FailCount= 1
2019-04-09T15:35:24.209Z,1554824124.209 [BR_Ping1D](INFO): Start
2019-04-09T15:35:24.613Z,1554824124.613 [BR_Ping1D](INFO): Starting
2019-04-09T15:35:24.627Z,1554824124.627 [CBIT](CRITICAL): Environmental Failure. Press:14.904530 PSI. Humidity:35%. Temp:25 C. ABORTING MISSION
2019-04-09T15:35:25.010Z,1554824125.010 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T15:35:25.010Z,1554824125.010 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-09T15:35:25.010Z,1554824125.010 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T15:35:25.030Z,1554824125.030 [BR_Ping1D](INFO): Starting
2019-04-09T15:35:25.030Z,1554824125.030 [BR_Ping1D](INFO): readConfig
2019-04-09T15:35:25.030Z,1554824125.030 [BR_Ping1D](INFO): startup: 0
2019-04-09T15:35:25.030Z,1554824125.030 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T15:35:25.042Z,1554824125.042 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T15:35:25.042Z,1554824125.042 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T15:35:25.043Z,1554824125.043 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T15:35:25.043Z,1554824125.043 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T15:35:25.382Z,1554824125.382 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T15:35:25.382Z,1554824125.382 [MassServo] Communications Fault, FailCount= 3
2019-04-09T15:35:25.382Z,1554824125.382 [MassServo](ERROR): Communications Fault
2019-04-09T15:35:25.384Z,1554824125.384 [CBIT](INFO): Critical error at 20190409T153524
2019-04-09T15:35:25.387Z,1554824125.387 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T15:35:25.387Z,1554824125.387 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T15:35:25.522Z,1554824125.522 [Aanderaa_O2](INFO): Powering down
2019-04-09T15:35:25.602Z,1554824125.602 [BR_Ping1D](INFO): Starting
2019-04-09T15:35:25.602Z,1554824125.602 [BR_Ping1D](INFO): readConfig
2019-04-09T15:35:25.602Z,1554824125.602 [BR_Ping1D](INFO): startup: 1
2019-04-09T15:35:25.603Z,1554824125.603 [BR_Ping1D](INFO): UART requesting status
2019-04-09T15:35:25.658Z,1554824125.658 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T15:35:25.658Z,1554824125.658 [MassServo](INFO): Powering down
2019-04-09T15:35:25.826Z,1554824125.826 [BR_Ping1D](INFO): Starting
2019-04-09T15:35:25.826Z,1554824125.826 [BR_Ping1D](INFO): readConfig
2019-04-09T15:35:25.826Z,1554824125.826 [BR_Ping1D](INFO): startup: 2
2019-04-09T15:35:25.826Z,1554824125.826 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T15:35:25.827Z,1554824125.827 [BR_Ping1D](INFO): elapsed 0.7962
2019-04-09T15:35:25.827Z,1554824125.827 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T15:35:25.827Z,1554824125.827 [BR_Ping1D](INFO): received full packet, type: 1210
2019-04-09T15:35:25.827Z,1554824125.827 [BR_Ping1D](INFO): parsing data, res: 20
2019-04-09T15:35:25.828Z,1554824125.828 [BR_Ping1D](INFO): Parsing UART packet of type 1210
2019-04-09T15:35:25.828Z,1554824125.828 [BR_Ping1D](INFO):  voltage: 5056
2019-04-09T15:35:25.828Z,1554824125.828 [BR_Ping1D](INFO):  interval: 7681
2019-04-09T15:35:25.828Z,1554824125.828 [BR_Ping1D](INFO):  gain: 4
2019-04-09T15:35:25.828Z,1554824125.828 [BR_Ping1D](INFO):  auto: 1
2019-04-09T15:35:25.829Z,1554824125.829 [BR_Ping1D](INFO): received full packet, type: 1215
2019-04-09T15:35:25.829Z,1554824125.829 [BR_Ping1D](INFO): CRC error in received UART packet
2019-04-09T15:35:25.829Z,1554824125.829 [BR_Ping1D](INFO): received UART packets: 1
2019-04-09T15:35:25.829Z,1554824125.829 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-04-09T15:35:25.829Z,1554824125.829 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-04-09T15:35:25.829Z,1554824125.829 [BR_Ping1D](INFO): (ping interval) 500 <-> 0
2019-04-09T15:35:25.830Z,1554824125.830 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-04-09T15:35:25.830Z,1554824125.830 [BR_Ping1D](INFO): (soundspeed) 1500000 <-> 65535
2019-04-09T15:35:25.830Z,1554824125.830 [BR_Ping1D](INFO): (ping enable) 1 <-> 0
2019-04-09T15:35:25.831Z,1554824125.831 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-04-09T15:35:25.831Z,1554824125.831 [BR_Ping1D] Data Fault, FailCount= 2
2019-04-09T15:35:25.831Z,1554824125.831 [BR_Ping1D](ERROR): Data Fault
2019-04-09T15:35:25.863Z,1554824125.863 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T15:35:26.229Z,1554824126.229 [BR_Ping1D](INFO): Stop
2019-04-09T15:35:26.229Z,1554824126.229 [BR_Ping1D](INFO): uninitialize
2019-04-09T15:35:26.229Z,1554824126.229 [BR_Ping1D](INFO): Powering down
2019-04-09T15:35:26.316Z,1554824126.316 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T15:35:26.435Z,1554824126.435 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T15:35:26.439Z,1554824126.439 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T15:35:26.439Z,1554824126.439 [MassServo] No Fault, FailCount= 3
2019-04-09T15:35:26.440Z,1554824126.440 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T15:35:26.440Z,1554824126.440 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-09T15:35:26.632Z,1554824126.632 [BR_Ping1D](INFO): Stopping
2019-04-09T15:35:27.049Z,1554824127.049 [BR_Ping1D](INFO): Stopping
2019-04-09T15:35:27.442Z,1554824127.442 [BR_Ping1D](INFO): Stopped
2019-04-09T15:35:27.501Z,1554824127.501 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T15:35:27.501Z,1554824127.501 [BR_Ping1D] No Fault, FailCount= 2
2019-04-09T15:35:27.598Z,1554824127.598 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T15:35:27.598Z,1554824127.598 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-09T15:35:27.598Z,1554824127.598 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T15:35:27.845Z,1554824127.845 [BR_Ping1D](INFO): Start
2019-04-09T15:35:27.869Z,1554824127.869 [CBIT](FAULT): Main Battery Failure. Count: 31
2019-04-09T15:35:27.887Z,1554824127.887 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T15:35:28.002Z,1554824128.002 [CTD_Seabird](INFO): Powering down
2019-04-09T15:35:28.250Z,1554824128.250 [BR_Ping1D](INFO): Starting
2019-04-09T15:35:28.653Z,1554824128.653 [BR_Ping1D](INFO): Starting
2019-04-09T15:35:28.653Z,1554824128.653 [BR_Ping1D](INFO): readConfig
2019-04-09T15:35:28.653Z,1554824128.653 [BR_Ping1D](INFO): startup: 0
2019-04-09T15:35:28.654Z,1554824128.654 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T15:35:28.666Z,1554824128.666 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T15:35:28.666Z,1554824128.666 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T15:35:28.666Z,1554824128.666 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T15:35:28.667Z,1554824128.667 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T15:35:29.002Z,1554824129.002 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T15:35:29.002Z,1554824129.002 [MassServo] Communications Fault, FailCount= 4
2019-04-09T15:35:29.002Z,1554824129.002 [MassServo](ERROR): Communications Fault
2019-04-09T15:35:29.006Z,1554824129.006 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T15:35:29.007Z,1554824129.007 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T15:35:29.007Z,1554824129.007 [CTD_Seabird] No Fault, FailCount= 1
2019-04-09T15:35:29.055Z,1554824129.055 [BR_Ping1D](INFO): Starting
2019-04-09T15:35:29.067Z,1554824129.067 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T15:35:29.067Z,1554824129.067 [MassServo](INFO): Powering down
2019-04-09T15:35:29.378Z,1554824129.378 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T15:35:29.380Z,1554824129.380 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T15:35:29.461Z,1554824129.461 [BR_Ping1D](INFO): Starting
2019-04-09T15:35:29.461Z,1554824129.461 [BR_Ping1D](INFO): readConfig
2019-04-09T15:35:29.462Z,1554824129.462 [BR_Ping1D](INFO): startup: 1
2019-04-09T15:35:29.462Z,1554824129.462 [BR_Ping1D](INFO): UART requesting status
2019-04-09T15:35:29.866Z,1554824129.866 [BR_Ping1D](INFO): Starting
2019-04-09T15:35:29.866Z,1554824129.866 [BR_Ping1D](INFO): readConfig
2019-04-09T15:35:29.866Z,1554824129.866 [BR_Ping1D](INFO): startup: 2
2019-04-09T15:35:29.866Z,1554824129.866 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T15:35:29.866Z,1554824129.866 [BR_Ping1D](INFO): elapsed 1.2127
2019-04-09T15:35:29.867Z,1554824129.867 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T15:35:29.867Z,1554824129.867 [BR_Ping1D](INFO): received full packet, type: 1210
2019-04-09T15:35:29.867Z,1554824129.867 [BR_Ping1D](INFO): parsing data, res: 20
2019-04-09T15:35:29.867Z,1554824129.867 [BR_Ping1D](INFO): Parsing UART packet of type 1210
2019-04-09T15:35:29.868Z,1554824129.868 [BR_Ping1D](INFO):  voltage: 5056
2019-04-09T15:35:29.868Z,1554824129.868 [BR_Ping1D](INFO):  interval: 7681
2019-04-09T15:35:29.868Z,1554824129.868 [BR_Ping1D](INFO):  gain: 1
2019-04-09T15:35:29.868Z,1554824129.868 [BR_Ping1D](INFO):  auto: 1
2019-04-09T15:35:29.868Z,1554824129.868 [BR_Ping1D](INFO): received full packet, type: 1215
2019-04-09T15:35:29.869Z,1554824129.869 [BR_Ping1D](INFO): CRC error in received UART packet
2019-04-09T15:35:29.869Z,1554824129.869 [BR_Ping1D](INFO): received UART packets: 1
2019-04-09T15:35:29.869Z,1554824129.869 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-04-09T15:35:29.869Z,1554824129.869 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-04-09T15:35:29.869Z,1554824129.869 [BR_Ping1D](INFO): (ping interval) 500 <-> 0
2019-04-09T15:35:29.869Z,1554824129.869 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-04-09T15:35:29.870Z,1554824129.870 [BR_Ping1D](INFO): (soundspeed) 1500000 <-> 65535
2019-04-09T15:35:29.870Z,1554824129.870 [BR_Ping1D](INFO): (ping enable) 1 <-> 0
2019-04-09T15:35:29.870Z,1554824129.870 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-04-09T15:35:29.870Z,1554824129.870 [BR_Ping1D] Data Fault, FailCount= 3
2019-04-09T15:35:29.870Z,1554824129.870 [BR_Ping1D](ERROR): Data Fault
2019-04-09T15:35:29.899Z,1554824129.899 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T15:35:30.019Z,1554824130.019 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T15:35:30.023Z,1554824130.023 [CBIT](INFO): Clearing failed state for component MassServo
2019-04-09T15:35:30.023Z,1554824130.023 [MassServo] No Fault, FailCount= 4
2019-04-09T15:35:30.024Z,1554824130.024 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T15:35:30.269Z,1554824130.269 [BR_Ping1D](INFO): Stop
2019-04-09T15:35:30.269Z,1554824130.269 [BR_Ping1D](INFO): uninitialize
2019-04-09T15:35:30.269Z,1554824130.269 [BR_Ping1D](INFO): Powering down
2019-04-09T15:35:30.674Z,1554824130.674 [BR_Ping1D](INFO): Stopping
2019-04-09T15:35:31.077Z,1554824131.077 [BR_Ping1D](INFO): Stopping
2019-04-09T15:35:31.481Z,1554824131.481 [BR_Ping1D](INFO): Stopped
2019-04-09T15:35:31.499Z,1554824131.499 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T15:35:31.499Z,1554824131.499 [BR_Ping1D] No Fault, FailCount= 3
2019-04-09T15:35:31.887Z,1554824131.887 [BR_Ping1D](INFO): Start
2019-04-09T15:35:31.934Z,1554824131.934 [CBIT](FAULT): Main Battery Failure. Count: 41
2019-04-09T15:35:32.290Z,1554824132.290 [BR_Ping1D](INFO): Starting
2019-04-09T15:35:32.626Z,1554824132.626 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2019-04-09T15:35:32.626Z,1554824132.626 [MassServo] Communications Fault, FailCount= 5
2019-04-09T15:35:32.626Z,1554824132.626 [MassServo](ERROR): Communications Fault
2019-04-09T15:35:32.630Z,1554824132.630 [CBIT](ERROR): Communications Fault in component: MassServo
2019-04-09T15:35:32.631Z,1554824132.631 [CBIT](CRITICAL): Communications Fault in component: MassServo
2019-04-09T15:35:32.691Z,1554824132.691 [BR_Ping1D](INFO): Starting
2019-04-09T15:35:32.691Z,1554824132.691 [BR_Ping1D](INFO): readConfig
2019-04-09T15:35:32.692Z,1554824132.692 [BR_Ping1D](INFO): startup: 0
2019-04-09T15:35:32.692Z,1554824132.692 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T15:35:32.706Z,1554824132.706 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T15:35:32.706Z,1554824132.706 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T15:35:32.706Z,1554824132.706 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T15:35:32.707Z,1554824132.707 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T15:35:32.756Z,1554824132.756 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T15:35:32.756Z,1554824132.756 [MassServo](INFO): Powering down
2019-04-09T15:35:32.763Z,1554824132.763 [CBIT](INFO): Critical error at 20190409T153532
2019-04-09T15:35:33.098Z,1554824133.098 [BR_Ping1D](INFO): Starting
2019-04-09T15:35:33.502Z,1554824133.502 [BR_Ping1D](INFO): Starting
2019-04-09T15:35:33.502Z,1554824133.502 [BR_Ping1D](INFO): readConfig
2019-04-09T15:35:33.503Z,1554824133.503 [BR_Ping1D](INFO): startup: 1
2019-04-09T15:35:33.503Z,1554824133.503 [BR_Ping1D](INFO): UART requesting status
2019-04-09T15:35:33.541Z,1554824133.541 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-09T15:35:33.659Z,1554824133.659 [MassServo](DEBUG): Initializing MassServo.
2019-04-09T15:35:33.907Z,1554824133.907 [BR_Ping1D](INFO): Starting
2019-04-09T15:35:33.907Z,1554824133.907 [BR_Ping1D](INFO): readConfig
2019-04-09T15:35:33.908Z,1554824133.908 [BR_Ping1D](INFO): startup: 2
2019-04-09T15:35:33.908Z,1554824133.908 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T15:35:33.908Z,1554824133.908 [BR_Ping1D](INFO): elapsed 1.2160
2019-04-09T15:35:33.908Z,1554824133.908 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T15:35:33.910Z,1554824133.910 [BR_Ping1D](INFO): received full packet, type: 1215
2019-04-09T15:35:33.910Z,1554824133.910 [BR_Ping1D](INFO): CRC error in received UART packet
2019-04-09T15:35:33.910Z,1554824133.910 [BR_Ping1D](INFO): received UART packets: 0
2019-04-09T15:35:33.910Z,1554824133.910 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-04-09T15:35:33.910Z,1554824133.910 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-04-09T15:35:33.910Z,1554824133.910 [BR_Ping1D](INFO): (ping interval) 500 <-> 0
2019-04-09T15:35:33.911Z,1554824133.911 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-04-09T15:35:33.911Z,1554824133.911 [BR_Ping1D](INFO): (soundspeed) 1500000 <-> 65535
2019-04-09T15:35:33.911Z,1554824133.911 [BR_Ping1D](INFO): (ping enable) 1 <-> 0
2019-04-09T15:35:33.911Z,1554824133.911 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-04-09T15:35:33.911Z,1554824133.911 [BR_Ping1D] Data Fault, FailCount= 4
2019-04-09T15:35:33.912Z,1554824133.912 [BR_Ping1D](ERROR): Data Fault
2019-04-09T15:35:33.943Z,1554824133.943 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T15:35:34.310Z,1554824134.310 [BR_Ping1D](INFO): Stop
2019-04-09T15:35:34.311Z,1554824134.311 [BR_Ping1D](INFO): uninitialize
2019-04-09T15:35:34.311Z,1554824134.311 [BR_Ping1D](INFO): Powering down
2019-04-09T15:35:34.714Z,1554824134.714 [BR_Ping1D](INFO): Stopping
2019-04-09T15:35:35.117Z,1554824135.117 [BR_Ping1D](INFO): Stopping
2019-04-09T15:35:35.522Z,1554824135.522 [BR_Ping1D](INFO): Stopped
2019-04-09T15:35:35.539Z,1554824135.539 [CBIT](INFO): Clearing failed state for component BR_Ping1D
2019-04-09T15:35:35.539Z,1554824135.539 [BR_Ping1D] No Fault, FailCount= 4
2019-04-09T15:35:35.907Z,1554824135.907 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T15:35:35.907Z,1554824135.907 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 1
2019-04-09T15:35:35.907Z,1554824135.907 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T15:35:35.926Z,1554824135.926 [BR_Ping1D](INFO): Start
2019-04-09T15:35:35.948Z,1554824135.948 [CBIT](FAULT): Main Battery Failure. Count: 51
2019-04-09T15:35:35.975Z,1554824135.975 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T15:35:36.311Z,1554824136.311 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T15:35:36.487Z,1554824136.487 [BR_Ping1D](INFO): Starting
2019-04-09T15:35:36.487Z,1554824136.487 [BR_Ping1D](INFO): readConfig
2019-04-09T15:35:36.487Z,1554824136.487 [BR_Ping1D](INFO): startup: 0
2019-04-09T15:35:36.487Z,1554824136.487 [BR_Ping1D](INFO): UART setting parameters ...
2019-04-09T15:35:36.498Z,1554824136.498 [BR_Ping1D](INFO): sending request for Ping Interval: 500
2019-04-09T15:35:36.498Z,1554824136.498 [BR_Ping1D](INFO): sending request for Auto Mode: 1
2019-04-09T15:35:36.498Z,1554824136.498 [BR_Ping1D](INFO): sending request for Soundspeed: 1500000
2019-04-09T15:35:36.499Z,1554824136.499 [BR_Ping1D](INFO): sending request for Ping Enable: 1
2019-04-09T15:35:36.714Z,1554824136.714 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T15:35:36.714Z,1554824136.714 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-09T15:35:36.715Z,1554824136.715 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T15:35:36.733Z,1554824136.733 [BR_Ping1D](INFO): Starting
2019-04-09T15:35:36.763Z,1554824136.763 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T15:35:37.238Z,1554824137.238 [Aanderaa_O2](INFO): Powering down
2019-04-09T15:35:37.266Z,1554824137.266 [BR_Ping1D](INFO): Starting
2019-04-09T15:35:37.266Z,1554824137.266 [BR_Ping1D](INFO): readConfig
2019-04-09T15:35:37.266Z,1554824137.266 [BR_Ping1D](INFO): startup: 1
2019-04-09T15:35:37.266Z,1554824137.266 [BR_Ping1D](INFO): UART requesting status
2019-04-09T15:35:37.300Z,1554824137.300 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T15:35:37.300Z,1554824137.300 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 1
2019-04-09T15:35:37.541Z,1554824137.541 [BR_Ping1D](INFO): Starting
2019-04-09T15:35:37.541Z,1554824137.541 [BR_Ping1D](INFO): readConfig
2019-04-09T15:35:37.541Z,1554824137.541 [BR_Ping1D](INFO): startup: 2
2019-04-09T15:35:37.542Z,1554824137.542 [BR_Ping1D](INFO): UART waiting for response
2019-04-09T15:35:37.542Z,1554824137.542 [BR_Ping1D](INFO): elapsed 1.0544
2019-04-09T15:35:37.542Z,1554824137.542 [BR_Ping1D](INFO): timeout 5.0000
2019-04-09T15:35:37.542Z,1554824137.542 [BR_Ping1D](INFO): received full packet, type: 1210
2019-04-09T15:35:37.543Z,1554824137.543 [BR_Ping1D](INFO): parsing data, res: 20
2019-04-09T15:35:37.543Z,1554824137.543 [BR_Ping1D](INFO): Parsing UART packet of type 1210
2019-04-09T15:35:37.543Z,1554824137.543 [BR_Ping1D](INFO):  voltage: 5054
2019-04-09T15:35:37.543Z,1554824137.543 [BR_Ping1D](INFO):  interval: 7681
2019-04-09T15:35:37.543Z,1554824137.543 [BR_Ping1D](INFO):  gain: 4
2019-04-09T15:35:37.543Z,1554824137.543 [BR_Ping1D](INFO):  auto: 1
2019-04-09T15:35:37.544Z,1554824137.544 [BR_Ping1D](INFO): received full packet, type: 1215
2019-04-09T15:35:37.544Z,1554824137.544 [BR_Ping1D](INFO): CRC error in received UART packet
2019-04-09T15:35:37.544Z,1554824137.544 [BR_Ping1D](INFO): received UART packets: 1
2019-04-09T15:35:37.544Z,1554824137.544 [BR_Ping1D](ERROR): Verification of parameters failed, trying again ...
2019-04-09T15:35:37.544Z,1554824137.544 [BR_Ping1D](INFO): (<parameter>) <requested> <-> <received>
2019-04-09T15:35:37.545Z,1554824137.545 [BR_Ping1D](INFO): (ping interval) 500 <-> 0
2019-04-09T15:35:37.545Z,1554824137.545 [BR_Ping1D](INFO): (auto mode) 1 <-> 1
2019-04-09T15:35:37.545Z,1554824137.545 [BR_Ping1D](INFO): (soundspeed) 1500000 <-> 65535
2019-04-09T15:35:37.545Z,1554824137.545 [BR_Ping1D](INFO): (ping enable) 1 <-> 0
2019-04-09T15:35:37.546Z,1554824137.546 [BR_Ping1D](ERROR): Verification of parameters failed 3 times, giving up!
2019-04-09T15:35:37.546Z,1554824137.546 [BR_Ping1D] Data Fault, FailCount= 5
2019-04-09T15:35:37.546Z,1554824137.546 [BR_Ping1D](ERROR): Data Fault
2019-04-09T15:35:37.634Z,1554824137.634 [CBIT](ERROR): Data Fault in component: BR_Ping1D
2019-04-09T15:35:37.635Z,1554824137.635 [CBIT](CRITICAL): Data Fault in component: BR_Ping1D
2019-04-09T15:35:37.945Z,1554824137.945 [BR_Ping1D](INFO): Stop
2019-04-09T15:35:37.946Z,1554824137.946 [BR_Ping1D](INFO): uninitialize
2019-04-09T15:35:37.946Z,1554824137.946 [BR_Ping1D](INFO): Powering down
2019-04-09T15:35:38.045Z,1554824138.045 [CBIT](INFO): Critical error at 20190409T153537
2019-04-09T15:35:38.048Z,1554824138.048 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T15:35:38.048Z,1554824138.048 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-09T15:35:38.357Z,1554824138.357 [BR_Ping1D](INFO): Stopping
2019-04-09T15:35:38.766Z,1554824138.766 [BR_Ping1D](INFO): Stopping
2019-04-09T15:35:39.157Z,1554824139.157 [BR_Ping1D](INFO): Stopped
2019-04-09T15:35:39.168Z,1554824139.168 [SBIT](IMPORTANT): Beginning Startup BIT
2019-04-09T15:35:39.172Z,1554824139.172 [CBIT](IMPORTANT): Beginning ground fault scan
2019-04-09T15:35:39.542Z,1554824139.542 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T15:35:39.543Z,1554824139.543 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T15:35:40.012Z,1554824140.012 [CBIT](FAULT): Main Battery Failure. Count: 61
2019-04-09T15:35:42.430Z,1554824142.430 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T15:35:42.430Z,1554824142.430 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-09T15:35:42.430Z,1554824142.430 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T15:35:42.491Z,1554824142.491 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T15:35:42.779Z,1554824142.779 [NAL9602](INFO): Powering up NAL9602
2019-04-09T15:35:42.834Z,1554824142.834 [CTD_Seabird](INFO): Powering down
2019-04-09T15:35:44.140Z,1554824144.140 [CBIT](FAULT): Main Battery Failure. Count: 71
2019-04-09T15:35:44.143Z,1554824144.143 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T15:35:44.143Z,1554824144.143 [CTD_Seabird] No Fault, FailCount= 2
2019-04-09T15:35:44.174Z,1554824144.174 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T15:35:44.182Z,1554824144.182 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T15:35:48.057Z,1554824148.057 [CBIT](FAULT): Main Battery Failure. Count: 81
2019-04-09T15:35:48.430Z,1554824148.430 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T15:35:48.430Z,1554824148.430 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-09T15:35:48.430Z,1554824148.430 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T15:35:48.463Z,1554824148.463 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T15:35:48.464Z,1554824148.464 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-09T15:35:48.954Z,1554824148.954 [Aanderaa_O2](INFO): Powering down
2019-04-09T15:35:50.083Z,1554824150.083 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): -0.006316
CHAN A1 (24V): -0.127826
CHAN A2 (12V): -0.005078
CHAN A3 (5V): -0.002757
CHAN B0 (3.3V): -0.000680
CHAN B1 (3.15aV): -0.001370
CHAN B2 (3.15bV): -0.001434
CHAN B3 (GND): 0.000288
OPEN: -0.000879
Full Scale Calc: 4.765 mA, -1.589 mA
2019-04-09T15:35:52.097Z,1554824152.097 [CBIT](FAULT): Main Battery Failure. Count: 91
2019-04-09T15:35:52.500Z,1554824152.500 [SBIT](ERROR): Could not read massPosReader_.
2019-04-09T15:35:53.687Z,1554824153.687 [NAL9602](INFO): NAL9602 initialized
2019-04-09T15:35:54.506Z,1554824154.506 [NAL9602](DEBUG): Fix Requested
2019-04-09T15:35:56.193Z,1554824156.193 [CBIT](FAULT): Main Battery Failure. Count: 101
2019-04-09T15:35:57.226Z,1554824157.226 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T15:35:57.226Z,1554824157.226 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-09T15:35:57.226Z,1554824157.226 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T15:35:57.420Z,1554824157.420 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T15:35:57.420Z,1554824157.420 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-09T15:35:57.630Z,1554824157.630 [CTD_Seabird](INFO): Powering down
2019-04-09T15:35:59.743Z,1554824159.743 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T15:35:59.743Z,1554824159.743 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 2
2019-04-09T15:35:59.743Z,1554824159.743 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T15:35:59.903Z,1554824159.903 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T15:36:00.147Z,1554824160.147 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T15:36:00.544Z,1554824160.544 [CBIT](FAULT): Main Battery Failure. Count: 111
2019-04-09T15:36:01.003Z,1554824161.003 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T15:36:01.003Z,1554824161.003 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 2
2019-04-09T15:36:03.378Z,1554824163.378 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T15:36:03.378Z,1554824163.378 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T15:36:04.221Z,1554824164.221 [CBIT](FAULT): Main Battery Failure. Count: 121
2019-04-09T15:36:08.274Z,1554824168.274 [CBIT](FAULT): Main Battery Failure. Count: 131
2019-04-09T15:36:12.301Z,1554824172.301 [CBIT](FAULT): Main Battery Failure. Count: 141
2019-04-09T15:36:16.341Z,1554824176.341 [CBIT](FAULT): Main Battery Failure. Count: 151
2019-04-09T15:36:19.185Z,1554824179.185 [SBIT](ERROR): Could not read massPosReader_.
2019-04-09T15:36:19.186Z,1554824179.186 [SBIT](FAULT): Control surface position failure.
2019-04-09T15:36:20.382Z,1554824180.382 [CBIT](FAULT): Main Battery Failure. Count: 161
2019-04-09T15:36:23.578Z,1554824183.578 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T15:36:23.579Z,1554824183.579 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 3
2019-04-09T15:36:23.579Z,1554824183.579 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T15:36:23.696Z,1554824183.696 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T15:36:23.982Z,1554824183.982 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T15:36:24.676Z,1554824184.676 [CBIT](FAULT): Main Battery Failure. Count: 171
2019-04-09T15:36:24.829Z,1554824184.829 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T15:36:24.829Z,1554824184.829 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 3
2019-04-09T15:36:27.215Z,1554824187.215 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T15:36:27.215Z,1554824187.215 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T15:36:28.473Z,1554824188.473 [CBIT](FAULT): Main Battery Failure. Count: 181
2019-04-09T15:36:32.511Z,1554824192.511 [CBIT](FAULT): Main Battery Failure. Count: 191
2019-04-09T15:36:32.898Z,1554824192.898 [SBIT](ERROR): Could not read massPosReader_.
2019-04-09T15:36:32.898Z,1554824192.898 [SBIT](FAULT): Control surface position failure.
2019-04-09T15:36:33.307Z,1554824193.307 [SBIT](CRITICAL): SBIT FAILED
2019-04-09T15:36:33.322Z,1554824193.322 [CommandLine](IMPORTANT): got command configSet list
2019-04-09T15:36:33.322Z,1554824193.322 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-04-09T15:36:33.323Z,1554824193.323 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-04-09T15:36:33.323Z,1554824193.323 [CommandLine](IMPORTANT): Rowe_600LCM.loadAtStartup=0 bool;

2019-04-09T15:36:33.326Z,1554824193.326 [CBIT](INFO): Critical error at 20190409T153633
2019-04-09T15:36:33.719Z,1554824193.719 [MissionManager](IMPORTANT): Started mission Startup
2019-04-09T15:36:33.719Z,1554824193.719 [Startup] Running Loop=1
2019-04-09T15:36:33.719Z,1554824193.719 [Startup](DEBUG): Aggregate::initialize Startup
2019-04-09T15:36:33.719Z,1554824193.719 [Startup:A.GoToSurface] Running Loop=1
2019-04-09T15:36:33.719Z,1554824193.719 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-09T15:36:33.720Z,1554824193.720 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-09T15:36:33.720Z,1554824193.720 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-09T15:36:33.721Z,1554824193.721 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-09T15:36:33.721Z,1554824193.721 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-09T15:36:33.721Z,1554824193.721 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-09T15:36:33.723Z,1554824193.723 [Startup:StartupSatComms] Running Loop=1
2019-04-09T15:36:33.723Z,1554824193.723 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-04-09T15:36:33.723Z,1554824193.723 [Startup:StartupSatComms:A] Running Loop=1
2019-04-09T15:36:34.124Z,1554824194.124 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-04-09T15:36:36.535Z,1554824196.535 [CBIT](FAULT): Main Battery Failure. Count: 201
2019-04-09T15:36:40.581Z,1554824200.581 [CBIT](FAULT): Main Battery Failure. Count: 211
2019-04-09T15:36:44.632Z,1554824204.632 [CBIT](FAULT): Main Battery Failure. Count: 221
2019-04-09T15:36:47.414Z,1554824207.414 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T15:36:47.414Z,1554824207.414 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 4
2019-04-09T15:36:47.415Z,1554824207.415 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T15:36:47.456Z,1554824207.456 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T15:36:47.818Z,1554824207.818 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T15:36:48.661Z,1554824208.661 [CBIT](FAULT): Main Battery Failure. Count: 231
2019-04-09T15:36:48.664Z,1554824208.664 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T15:36:48.664Z,1554824208.664 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 4
2019-04-09T15:36:51.050Z,1554824211.050 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T15:36:51.051Z,1554824211.051 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T15:36:52.701Z,1554824212.701 [CBIT](FAULT): Main Battery Failure. Count: 241
2019-04-09T15:36:56.753Z,1554824216.753 [CBIT](FAULT): Main Battery Failure. Count: 251
2019-04-09T15:37:00.793Z,1554824220.793 [CBIT](FAULT): Main Battery Failure. Count: 261
2019-04-09T15:37:04.814Z,1554824224.814 [CBIT](FAULT): Main Battery Failure. Count: 271
2019-04-09T15:37:08.862Z,1554824228.862 [CBIT](FAULT): Main Battery Failure. Count: 281
2019-04-09T15:37:11.250Z,1554824231.250 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T15:37:11.251Z,1554824231.251 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 5
2019-04-09T15:37:11.251Z,1554824231.251 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T15:37:11.304Z,1554824231.304 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T15:37:11.654Z,1554824231.654 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T15:37:12.500Z,1554824232.500 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T15:37:12.500Z,1554824232.500 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 5
2019-04-09T15:37:12.897Z,1554824232.897 [CBIT](FAULT): Main Battery Failure. Count: 291
2019-04-09T15:37:14.886Z,1554824234.886 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T15:37:14.887Z,1554824234.887 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T15:37:14.925Z,1554824234.925 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T15:37:14.925Z,1554824234.925 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-04-09T15:37:14.925Z,1554824234.925 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-09T15:37:14.927Z,1554824234.927 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T15:37:14.927Z,1554824234.927 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2019-04-09T15:37:14.927Z,1554824234.927 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-09T15:37:14.928Z,1554824234.928 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T15:37:14.928Z,1554824234.928 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2019-04-09T15:37:14.928Z,1554824234.928 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-09T15:37:14.939Z,1554824234.939 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-09T15:37:14.939Z,1554824234.939 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-09T15:37:14.940Z,1554824234.940 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-09T15:37:15.326Z,1554824235.326 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-09T15:37:15.327Z,1554824235.327 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-04-09T15:37:15.327Z,1554824235.327 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-09T15:37:15.327Z,1554824235.327 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2019-04-09T15:37:15.328Z,1554824235.328 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-09T15:37:15.328Z,1554824235.328 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2019-04-09T15:37:15.733Z,1554824235.733 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T15:37:16.957Z,1554824236.957 [CBIT](FAULT): Main Battery Failure. Count: 301
2019-04-09T15:37:20.999Z,1554824240.999 [CBIT](FAULT): Main Battery Failure. Count: 311
2019-04-09T15:37:25.014Z,1554824245.014 [CBIT](FAULT): Main Battery Failure. Count: 321
2019-04-09T15:37:29.055Z,1554824249.055 [CBIT](FAULT): Main Battery Failure. Count: 331
2019-04-09T15:37:33.101Z,1554824253.101 [CBIT](FAULT): Main Battery Failure. Count: 341
2019-04-09T15:37:33.899Z,1554824253.899 [Startup:StartupSatComms:A](INFO): Timed out from 2019-04-09T15:36:33.7Z
2019-04-09T15:37:33.899Z,1554824253.899 [Startup:StartupSatComms:A] Stopped
2019-04-09T15:37:33.899Z,1554824253.899 [Startup:StartupSatComms:B] Running Loop=1
2019-04-09T15:37:34.315Z,1554824254.315 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-04-09T15:37:35.086Z,1554824255.086 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T15:37:35.087Z,1554824255.087 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 6
2019-04-09T15:37:35.087Z,1554824255.087 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T15:37:35.124Z,1554824255.124 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T15:37:35.490Z,1554824255.490 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T15:37:36.335Z,1554824256.335 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T15:37:36.335Z,1554824256.335 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 6
2019-04-09T15:37:37.153Z,1554824257.153 [CBIT](FAULT): Main Battery Failure. Count: 351
2019-04-09T15:37:38.722Z,1554824258.722 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T15:37:38.723Z,1554824258.723 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T15:37:41.184Z,1554824261.184 [CBIT](FAULT): Main Battery Failure. Count: 361
2019-04-09T15:37:45.221Z,1554824265.221 [CBIT](FAULT): Main Battery Failure. Count: 371
2019-04-09T15:37:49.262Z,1554824269.262 [CBIT](FAULT): Main Battery Failure. Count: 381
2019-04-09T15:37:53.317Z,1554824273.317 [CBIT](FAULT): Main Battery Failure. Count: 391
2019-04-09T15:37:56.432Z,1554824276.432 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004870
2019-04-09T15:37:57.345Z,1554824277.345 [CBIT](FAULT): Main Battery Failure. Count: 401
2019-04-09T15:37:58.923Z,1554824278.923 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T15:37:58.923Z,1554824278.923 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 7
2019-04-09T15:37:58.923Z,1554824278.923 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T15:37:58.992Z,1554824278.992 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T15:37:59.326Z,1554824279.326 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T15:38:00.168Z,1554824280.168 [CBIT](INFO): Clearing failed state for component AcousticModem_Benthos_ATM900
2019-04-09T15:38:00.168Z,1554824280.168 [AcousticModem_Benthos_ATM900] No Fault, FailCount= 7
2019-04-09T15:38:01.393Z,1554824281.393 [CBIT](FAULT): Main Battery Failure. Count: 411
2019-04-09T15:38:02.558Z,1554824282.558 [AcousticModem_Benthos_ATM900](INFO): Powering up
2019-04-09T15:38:02.558Z,1554824282.558 [AcousticModem_Benthos_ATM900](DEBUG): Initializing AcousticModem_Benthos_ATM900.
2019-04-09T15:38:02.738Z,1554824282.738 [DataOverHttps](INFO): Sending 79 bytes from file Logs/20190409T152156/Courier0007.lzma
2019-04-09T15:38:03.544Z,1554824283.544 [DataOverHttps](INFO): Moved sent file to Logs/20190409T152156/Courier0007.lzma.bak
2019-04-09T15:38:03.544Z,1554824283.544 [DataOverHttps](INFO): SBD MOMSN=10513673
2019-04-09T15:38:05.447Z,1554824285.447 [CBIT](FAULT): Main Battery Failure. Count: 421
2019-04-09T15:38:09.473Z,1554824289.473 [CBIT](FAULT): Main Battery Failure. Count: 431
2019-04-09T15:38:13.517Z,1554824293.517 [CBIT](FAULT): Main Battery Failure. Count: 441
2019-04-09T15:38:17.551Z,1554824297.551 [CBIT](FAULT): Main Battery Failure. Count: 451
2019-04-09T15:38:18.023Z,1554824298.023 [DataOverHttps](INFO): Sending 359 bytes from file Logs/20190409T153504/Courier0000.lzma
2019-04-09T15:38:18.763Z,1554824298.763 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T15:38:18.763Z,1554824298.763 [Aanderaa_O2] No Fault, FailCount= 3
2019-04-09T15:38:18.827Z,1554824298.827 [DataOverHttps](INFO): Moved sent file to Logs/20190409T153504/Courier0000.lzma.bak
2019-04-09T15:38:18.828Z,1554824298.828 [DataOverHttps](INFO): SBD MOMSN=10513676
2019-04-09T15:38:21.589Z,1554824301.589 [CBIT](FAULT): Main Battery Failure. Count: 461
2019-04-09T15:38:22.759Z,1554824302.759 [AcousticModem_Benthos_ATM900](FAULT): failed to initialize, no bytes available on serial interface
2019-04-09T15:38:22.759Z,1554824302.759 [AcousticModem_Benthos_ATM900] Communications Fault, FailCount= 8
2019-04-09T15:38:22.759Z,1554824302.759 [AcousticModem_Benthos_ATM900](ERROR): Communications Fault
2019-04-09T15:38:22.797Z,1554824302.797 [CBIT](ERROR): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T15:38:22.797Z,1554824302.797 [CBIT](FAULT): Communications Fault in component: AcousticModem_Benthos_ATM900
2019-04-09T15:38:23.163Z,1554824303.163 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T15:38:25.633Z,1554824305.633 [CBIT](FAULT): Main Battery Failure. Count: 471
2019-04-09T15:38:27.244Z,1554824307.244 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T15:38:27.244Z,1554824307.244 [CTD_Seabird] No Fault, FailCount= 3
2019-04-09T15:38:27.266Z,1554824307.266 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T15:38:27.268Z,1554824307.268 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T15:38:29.222Z,1554824309.222 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T15:38:29.222Z,1554824309.222 [Aanderaa_O2] Communications Fault, FailCount= 1
2019-04-09T15:38:29.222Z,1554824309.222 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T15:38:29.270Z,1554824309.270 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T15:38:29.746Z,1554824309.746 [Aanderaa_O2](INFO): Powering down
2019-04-09T15:38:29.815Z,1554824309.815 [CBIT](FAULT): Main Battery Failure. Count: 481
2019-04-09T15:38:30.483Z,1554824310.483 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T15:38:30.483Z,1554824310.483 [Aanderaa_O2] No Fault, FailCount= 1
2019-04-09T15:38:33.701Z,1554824313.701 [CBIT](FAULT): Main Battery Failure. Count: 491
2019-04-09T15:38:34.093Z,1554824314.093 [Startup:StartupSatComms:B](INFO): Timed out from 2019-04-09T15:37:33.9Z
2019-04-09T15:38:34.093Z,1554824314.093 [Startup:StartupSatComms:B] Stopped
2019-04-09T15:38:34.093Z,1554824314.093 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-04-09T15:38:34.093Z,1554824314.093 [Startup:StartupSatComms] Stopped
2019-04-09T15:38:34.093Z,1554824314.093 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-04-09T15:38:34.094Z,1554824314.094 [Startup](INFO): Completed Startup
2019-04-09T15:38:34.094Z,1554824314.094 [MissionManager](INFO): Startup is completed.
2019-04-09T15:38:34.094Z,1554824314.094 [MissionManager](INFO): Uninitializing Mission Startup
2019-04-09T15:38:34.094Z,1554824314.094 [Startup] Stopped
2019-04-09T15:38:34.094Z,1554824314.094 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-04-09T15:38:34.094Z,1554824314.094 [Startup:A.GoToSurface] Stopped
2019-04-09T15:38:34.094Z,1554824314.094 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-09T15:38:34.523Z,1554824314.523 [MissionManager](IMPORTANT): Started mission Default
2019-04-09T15:38:34.523Z,1554824314.523 [Default] Running Loop=1
2019-04-09T15:38:34.523Z,1554824314.523 [Default](DEBUG): Aggregate::initialize Default
2019-04-09T15:38:34.523Z,1554824314.523 [Default:B.GoToSurface] Running Loop=1
2019-04-09T15:38:34.523Z,1554824314.523 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-09T15:38:34.524Z,1554824314.524 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-09T15:38:34.524Z,1554824314.524 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-09T15:38:34.524Z,1554824314.524 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-09T15:38:34.525Z,1554824314.525 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-09T15:38:34.525Z,1554824314.525 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-09T15:38:34.525Z,1554824314.525 [Default:A.Wait] Running Loop=1
2019-04-09T15:38:34.525Z,1554824314.525 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-04-09T15:38:37.741Z,1554824317.741 [CBIT](FAULT): Main Battery Failure. Count: 501
2019-04-09T15:38:40.318Z,1554824320.318 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T15:38:40.318Z,1554824320.318 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-09T15:38:40.318Z,1554824320.318 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T15:38:40.588Z,1554824320.588 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T15:38:40.722Z,1554824320.722 [CTD_Seabird](INFO): Powering down
2019-04-09T15:38:40.938Z,1554824320.938 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T15:38:40.938Z,1554824320.938 [Aanderaa_O2] Communications Fault, FailCount= 2
2019-04-09T15:38:40.938Z,1554824320.938 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T15:38:40.995Z,1554824320.995 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T15:38:41.325Z,1554824321.325 [DataOverHttps](INFO): Sending 1196 bytes from file Logs/20190409T152156/Express0005.lzma
2019-04-09T15:38:41.462Z,1554824321.462 [Aanderaa_O2](INFO): Powering down
2019-04-09T15:38:41.780Z,1554824321.780 [CBIT](FAULT): Main Battery Failure. Count: 511
2019-04-09T15:38:41.783Z,1554824321.783 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T15:38:41.783Z,1554824321.783 [CTD_Seabird] No Fault, FailCount= 1
2019-04-09T15:38:42.066Z,1554824322.066 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T15:38:42.068Z,1554824322.068 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T15:38:42.127Z,1554824322.127 [DataOverHttps](INFO): Moved sent file to Logs/20190409T152156/Express0005.lzma.bak
2019-04-09T15:38:42.128Z,1554824322.128 [DataOverHttps](INFO): SBD MOMSN=10513691
2019-04-09T15:38:42.203Z,1554824322.203 [CBIT](INFO): Clearing failed state for component Aanderaa_O2
2019-04-09T15:38:42.203Z,1554824322.203 [Aanderaa_O2] No Fault, FailCount= 2
2019-04-09T15:38:45.844Z,1554824325.844 [CBIT](FAULT): Main Battery Failure. Count: 521
2019-04-09T15:38:47.829Z,1554824327.829 [Default:A.Wait](INFO): Done Waiting.
2019-04-09T15:38:47.829Z,1554824327.829 [Default:A.Wait] Stopped
2019-04-09T15:38:47.829Z,1554824327.829 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-04-09T15:38:48.243Z,1554824328.243 [Default:CheckIn] Running Loop=1
2019-04-09T15:38:48.244Z,1554824328.244 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-04-09T15:38:48.244Z,1554824328.244 [Default:CheckIn:Read_GPS] Running Loop=1
2019-04-09T15:38:48.641Z,1554824328.641 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-04-09T15:38:49.861Z,1554824329.861 [CBIT](FAULT): Main Battery Failure. Count: 531
2019-04-09T15:38:52.654Z,1554824332.654 [Aanderaa_O2](FAULT): Failed to initialize within timeout.
2019-04-09T15:38:52.654Z,1554824332.654 [Aanderaa_O2] Communications Fault, FailCount= 3
2019-04-09T15:38:52.654Z,1554824332.654 [Aanderaa_O2](ERROR): Communications Fault
2019-04-09T15:38:52.712Z,1554824332.712 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2
2019-04-09T15:38:52.712Z,1554824332.712 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2
2019-04-09T15:38:53.178Z,1554824333.178 [Aanderaa_O2](INFO): Powering down
2019-04-09T15:38:53.901Z,1554824333.901 [CBIT](FAULT): Main Battery Failure. Count: 541
2019-04-09T15:38:55.118Z,1554824335.118 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T15:38:55.118Z,1554824335.118 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-09T15:38:55.118Z,1554824335.118 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T15:38:55.522Z,1554824335.522 [CTD_Seabird](INFO): Powering down
2019-04-09T15:38:55.537Z,1554824335.537 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T15:38:56.743Z,1554824336.743 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-09T15:38:56.743Z,1554824336.743 [CTD_Seabird] No Fault, FailCount= 2
2019-04-09T15:38:56.870Z,1554824336.870 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-09T15:38:56.872Z,1554824336.872 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-09T15:38:57.941Z,1554824337.941 [CBIT](FAULT): Main Battery Failure. Count: 551
2019-04-09T15:39:02.007Z,1554824342.007 [CBIT](FAULT): Main Battery Failure. Count: 561
2019-04-09T15:39:06.032Z,1554824346.032 [CBIT](FAULT): Main Battery Failure. Count: 571
2019-04-09T15:39:09.922Z,1554824349.922 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-09T15:39:09.922Z,1554824349.922 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-09T15:39:09.923Z,1554824349.923 [CTD_Seabird](ERROR): Communications Fault
2019-04-09T15:39:10.095Z,1554824350.095 [CBIT](FAULT): Main Battery Failure. Count: 581
2019-04-09T15:39:10.098Z,1554824350.098 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-09T15:39:10.098Z,1554824350.098 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-09T15:39:10.326Z,1554824350.326 [CTD_Seabird](INFO): Powering down
2019-04-09T15:39:14.094Z,1554824354.094 [CBIT](FAULT): Main Battery Failure. Count: 591
2019-04-09T15:39:16.121Z,1554824356.121 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T15:39:16.121Z,1554824356.121 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-04-09T15:39:16.122Z,1554824356.122 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-09T15:39:16.126Z,1554824356.126 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T15:39:16.126Z,1554824356.126 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2019-04-09T15:39:16.127Z,1554824356.127 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2019-04-09T15:39:16.128Z,1554824356.128 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-09T15:39:16.128Z,1554824356.128 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2019-04-09T15:39:16.128Z,1554824356.128 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2019-04-09T15:39:16.150Z,1554824356.150 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-09T15:39:16.151Z,1554824356.151 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2019-04-09T15:39:16.151Z,1554824356.151 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2019-04-09T15:39:16.555Z,1554824356.555 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-09T15:39:16.555Z,1554824356.555 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-04-09T15:39:16.555Z,1554824356.555 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2019-04-09T15:39:16.555Z,1554824356.555 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2019-04-09T15:39:16.556Z,1554824356.556 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2019-04-09T15:39:16.556Z,1554824356.556 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2019-04-09T15:39:16.929Z,1554824356.929 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2019-04-09T15:39:18.141Z,1554824358.141 [CBIT](FAULT): Main Battery Failure. Count: 601
2019-04-09T15:39:22.196Z,1554824362.196 [CBIT](FAULT): Main Battery Failure. Count: 611
2019-04-09T15:39:26.225Z,1554824366.225 [CBIT](FAULT): Main Battery Failure. Count: 621
2019-04-09T15:39:30.261Z,1554824370.261 [CBIT](FAULT): Main Battery Failure. Count: 631
2019-04-09T15:39:34.301Z,1554824374.301 [CBIT](FAULT): Main Battery Failure. Count: 641
2019-04-09T15:39:38.366Z,1554824378.366 [CBIT](FAULT): Main Battery Failure. Count: 651
2019-04-09T15:39:42.381Z,1554824382.381 [CBIT](FAULT): Main Battery Failure. Count: 661
2019-04-09T15:39:46.424Z,1554824386.424 [CBIT](FAULT): Main Battery Failure. Count: 671
2019-04-09T15:39:48.187Z,1554824388.187 [CommandLine](IMPORTANT): got command restart application
2019-04-09T15:39:49.190Z,1554824389.190 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T15:39:49.190Z,1554824389.190 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:39:49.338Z,1554824389.338 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-04-09T15:39:49.338Z,1554824389.338 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:39:49.339Z,1554824389.339 [CommandLine](INFO): Join timeout helper Thread ID is 2960
2019-04-09T15:39:49.346Z,1554824389.346 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-04-09T15:39:49.346Z,1554824389.346 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:39:49.347Z,1554824389.347 [NavChartDb](INFO): Join timeout helper Thread ID is 2961
2019-04-09T15:39:49.422Z,1554824389.422 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T15:39:49.422Z,1554824389.422 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:39:49.438Z,1554824389.438 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-04-09T15:39:49.438Z,1554824389.438 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:39:49.438Z,1554824389.438 [Radio_Surface](INFO): Join timeout helper Thread ID is 2962
2019-04-09T15:39:49.786Z,1554824389.786 [Radio_Surface](INFO): Powering down
2019-04-09T15:39:49.787Z,1554824389.787 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T15:39:49.787Z,1554824389.787 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:39:49.790Z,1554824389.790 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-04-09T15:39:49.790Z,1554824389.790 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:39:49.791Z,1554824389.791 [DataOverHttps](INFO): Join timeout helper Thread ID is 2963
2019-04-09T15:39:50.335Z,1554824390.335 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T15:39:50.339Z,1554824390.339 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:39:50.358Z,1554824390.358 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-04-09T15:39:50.358Z,1554824390.358 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:39:50.359Z,1554824390.359 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2964
2019-04-09T15:39:50.468Z,1554824390.468 [CBIT](FAULT): Main Battery Failure. Count: 681
2019-04-09T15:39:50.770Z,1554824390.770 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T15:39:50.771Z,1554824390.771 [WetLabsBB2FL](INFO): Powering down
2019-04-09T15:39:50.771Z,1554824390.771 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:39:50.783Z,1554824390.783 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-04-09T15:39:50.783Z,1554824390.783 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:39:50.783Z,1554824390.783 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2965
2019-04-09T15:39:50.866Z,1554824390.866 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T15:39:50.866Z,1554824390.866 [CTD_Seabird](INFO): Powering down
2019-04-09T15:39:50.878Z,1554824390.878 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:39:50.883Z,1554824390.883 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-04-09T15:39:50.883Z,1554824390.883 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:39:50.883Z,1554824390.883 [logger](INFO): Join timeout helper Thread ID is 2966
2019-04-09T15:39:50.903Z,1554824390.903 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T15:39:50.903Z,1554824390.903 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:39:50.915Z,1554824390.915 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-04-09T15:39:50.915Z,1554824390.915 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:39:50.915Z,1554824390.915 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-04-09T15:39:50.915Z,1554824390.915 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:39:50.915Z,1554824390.915 [controlThread](INFO): Join timeout helper Thread ID is 2967
2019-04-09T15:39:51.230Z,1554824391.230 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-09T15:39:51.230Z,1554824391.230 [controlThread](DEBUG): Uninitializing ControlThread
2019-04-09T15:39:51.231Z,1554824391.231 [Aanderaa_O2](INFO): Powering down
2019-04-09T15:39:51.233Z,1554824391.233 [AcousticModem_Benthos_ATM900](INFO): Powering down
2019-04-09T15:39:51.234Z,1554824391.234 [NAL9602](INFO): Powering down
2019-04-09T15:39:51.236Z,1554824391.236 [BR_Ping1D](INFO): uninitialize
2019-04-09T15:39:51.236Z,1554824391.236 [BR_Ping1D](INFO): Powering down
2019-04-09T15:39:51.237Z,1554824391.237 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-04-09T15:39:51.237Z,1554824391.237 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-04-09T15:39:51.238Z,1554824391.238 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-04-09T15:39:51.238Z,1554824391.238 [MissionManager](INFO): Uninitializing Mission Default
2019-04-09T15:39:51.239Z,1554824391.239 [Default] Stopped
2019-04-09T15:39:51.239Z,1554824391.239 [Default](DEBUG): Aggregate::uninitialize Default
2019-04-09T15:39:51.239Z,1554824391.239 [Default:B.GoToSurface] Stopped
2019-04-09T15:39:51.239Z,1554824391.239 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-09T15:39:51.239Z,1554824391.239 [Default:CheckIn] Stopped
2019-04-09T15:39:51.239Z,1554824391.239 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-04-09T15:39:51.239Z,1554824391.239 [Default:CheckIn:Read_GPS] Stopped
2019-04-09T15:39:51.242Z,1554824391.242 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-04-09T15:39:51.242Z,1554824391.242 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-04-09T15:39:51.242Z,1554824391.242 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-04-09T15:39:51.243Z,1554824391.243 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-04-09T15:39:51.243Z,1554824391.243 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-04-09T15:39:51.243Z,1554824391.243 [BuoyancyServo](INFO): Powering down
2019-04-09T15:39:51.254Z,1554824391.254 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-04-09T15:39:51.254Z,1554824391.254 [ElevatorServo](INFO): Powering down
2019-04-09T15:39:51.255Z,1554824391.255 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-09T15:39:51.255Z,1554824391.255 [MassServo](INFO): Powering down
2019-04-09T15:39:51.256Z,1554824391.256 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-09T15:39:51.256Z,1554824391.256 [RudderServo](INFO): Powering down
2019-04-09T15:39:51.256Z,1554824391.256 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-04-09T15:39:51.257Z,1554824391.257 [ThrusterServo](INFO): Powering down
2019-04-09T15:39:51.257Z,1554824391.257 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-04-09T15:39:51.257Z,1554824391.257 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-04-09T15:39:51.258Z,1554824391.258 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-04-09T15:39:51.258Z,1554824391.258 [CBIT](DEBUG): Powering off loads.
2019-04-09T15:39:51.270Z,1554824391.270 [CBIT](DEBUG): Disabling WDT.
2019-04-09T15:39:51.282Z,1554824391.282 [CBIT](DEBUG): Opening all GF detection circuits.
2019-04-09T15:39:51.282Z,1554824391.282 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:39:51.445Z,1554824391.445 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:39:51.450Z,1554824391.450 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:39:51.518Z,1554824391.518 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:39:51.522Z,1554824391.522 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:39:51.573Z,1554824391.573 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-09T15:39:51.633Z,1554824391.633 [logger ThreadHandler](INFO): Thread cancelled.