2023-06-23T16:34:04.310Z,1687538044.310 [Supervisor](DEBUG): Initializing supervisor.
2023-06-23T16:34:04.315Z,1687538044.315 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-06-23T16:34:04.315Z,1687538044.315 [SyncHandler](INFO): Protected caller Thread ID is 3261
2023-06-23T16:34:04.316Z,1687538044.316 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-06-23T16:34:04.317Z,1687538044.317 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-06-23T16:34:04.318Z,1687538044.318 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3262
2023-06-23T16:34:04.322Z,1687538044.322 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-06-23T16:34:04.339Z,1687538044.339 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-06-23T16:34:04.340Z,1687538044.340 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-06-23T16:34:04.340Z,1687538044.340 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 3263
2023-06-23T16:34:04.344Z,1687538044.344 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-06-23T16:34:04.345Z,1687538044.345 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-06-23T16:34:04.345Z,1687538044.345 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3264
2023-06-23T16:34:04.347Z,1687538044.347 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-06-23T16:34:04.348Z,1687538044.348 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-06-23T16:34:04.349Z,1687538044.349 [logger ThreadHandler](INFO): Protected caller Thread ID is 3265
2023-06-23T16:34:04.352Z,1687538044.352 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-06-23T16:34:04.352Z,1687538044.352 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-06-23T16:34:04.354Z,1687538044.354 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-06-23T16:34:04.684Z,1687538044.684 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-06-23T16:34:04.685Z,1687538044.685 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-06-23T16:34:04.766Z,1687538044.766 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-06-23T16:34:04.873Z,1687538044.873 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-06-23T16:34:04.874Z,1687538044.874 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-06-23T16:34:05.003Z,1687538045.003 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-06-23T16:34:05.003Z,1687538045.003 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-06-23T16:34:05.331Z,1687538045.331 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-06-23T16:34:05.331Z,1687538045.331 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-06-23T16:34:05.428Z,1687538045.428 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-06-23T16:34:05.429Z,1687538045.429 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-06-23T16:34:05.809Z,1687538045.809 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-06-23T16:34:05.810Z,1687538045.810 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-06-23T16:34:06.012Z,1687538046.012 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-06-23T16:34:06.013Z,1687538046.013 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-06-23T16:34:06.093Z,1687538046.093 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-06-23T16:34:06.315Z,1687538046.315 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-06-23T16:34:06.315Z,1687538046.315 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-06-23T16:34:06.533Z,1687538046.533 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-06-23T16:34:06.534Z,1687538046.534 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-06-23T16:34:07.052Z,1687538047.052 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-06-23T16:34:07.052Z,1687538047.052 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-06-23T16:34:07.256Z,1687538047.256 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-06-23T16:34:07.256Z,1687538047.256 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-06-23T16:34:07.533Z,1687538047.533 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-06-23T16:34:07.534Z,1687538047.534 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-06-23T16:34:08.300Z,1687538048.300 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-06-23T16:34:08.300Z,1687538048.300 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-06-23T16:34:08.827Z,1687538048.827 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-06-23T16:34:08.829Z,1687538048.829 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/
2023-06-23T16:34:08.830Z,1687538048.830 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg
2023-06-23T16:34:08.928Z,1687538048.928 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg
2023-06-23T16:34:09.047Z,1687538049.047 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg
2023-06-23T16:34:09.133Z,1687538049.133 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg
2023-06-23T16:34:09.242Z,1687538049.242 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg
2023-06-23T16:34:09.342Z,1687538049.342 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg
2023-06-23T16:34:09.604Z,1687538049.604 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-06-23T16:34:09.605Z,1687538049.605 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg
2023-06-23T16:34:09.712Z,1687538049.712 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg
2023-06-23T16:34:09.808Z,1687538049.808 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Navigation.cfg
2023-06-23T16:34:09.912Z,1687538049.912 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg
2023-06-23T16:34:10.045Z,1687538050.045 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg
2023-06-23T16:34:10.133Z,1687538050.133 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/root/
2023-06-23T16:34:10.133Z,1687538050.133 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-06-23T16:34:10.136Z,1687538050.136 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-06-23T16:34:10.241Z,1687538050.241 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-06-23T16:34:10.242Z,1687538050.242 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-06-23T16:34:10.294Z,1687538050.294 [VerticalControl](DEBUG): Construct VerticalControl.
2023-06-23T16:34:10.352Z,1687538050.352 [VerticalControl] Loaded
2023-06-23T16:34:10.352Z,1687538050.352 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-06-23T16:34:10.355Z,1687538050.355 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-06-23T16:34:10.395Z,1687538050.395 [HorizontalControl] Loaded
2023-06-23T16:34:10.395Z,1687538050.395 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-06-23T16:34:10.397Z,1687538050.397 [SpeedControl](DEBUG): Construct SpeedControl.
2023-06-23T16:34:10.400Z,1687538050.400 [SpeedControl] Loaded
2023-06-23T16:34:10.400Z,1687538050.400 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-06-23T16:34:10.403Z,1687538050.403 [LoopControl](DEBUG): Construct LoopControl.
2023-06-23T16:34:10.403Z,1687538050.403 [LoopControl] Loaded
2023-06-23T16:34:10.404Z,1687538050.404 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-06-23T16:34:10.404Z,1687538050.404 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-06-23T16:34:10.404Z,1687538050.404 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-06-23T16:34:10.463Z,1687538050.463 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-06-23T16:34:10.464Z,1687538050.464 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-06-23T16:34:10.916Z,1687538050.916 [AHRS_M2] Loaded
2023-06-23T16:34:10.916Z,1687538050.916 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-06-23T16:34:11.733Z,1687538051.733 [BPC1] Loaded
2023-06-23T16:34:11.733Z,1687538051.733 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-06-23T16:34:11.806Z,1687538051.806 [DataOverHttps] Loaded
2023-06-23T16:34:11.806Z,1687538051.806 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-06-23T16:34:11.807Z,1687538051.807 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4087C4E0
2023-06-23T16:34:11.807Z,1687538051.807 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3347
2023-06-23T16:34:11.853Z,1687538051.853 [Depth_Keller] Loaded
2023-06-23T16:34:11.853Z,1687538051.853 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-06-23T16:34:11.858Z,1687538051.858 [DropWeight] Loaded
2023-06-23T16:34:11.858Z,1687538051.858 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-06-23T16:34:11.921Z,1687538051.921 [NAL9602] Loaded
2023-06-23T16:34:11.922Z,1687538051.922 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-06-23T16:34:11.951Z,1687538051.951 [Onboard] Loaded
2023-06-23T16:34:11.952Z,1687538051.952 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-06-23T16:34:11.953Z,1687538051.953 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408AC4E0
2023-06-23T16:34:11.953Z,1687538051.953 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 3348
2023-06-23T16:34:11.960Z,1687538051.960 [PowerOnly](INFO): Adding load control power supply at /dev/loadC4
2023-06-23T16:34:11.972Z,1687538051.972 [PowerOnly] Loaded
2023-06-23T16:34:11.972Z,1687538051.972 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2023-06-23T16:34:11.984Z,1687538051.984 [Power24vConverter] Loaded
2023-06-23T16:34:11.984Z,1687538051.984 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-06-23T16:34:11.997Z,1687538051.997 [Radio_Surface] Loaded
2023-06-23T16:34:11.997Z,1687538051.997 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-06-23T16:34:11.998Z,1687538051.998 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408DC4E0
2023-06-23T16:34:11.999Z,1687538051.999 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3349
2023-06-23T16:34:12.089Z,1687538052.089 [DAT] Loaded
2023-06-23T16:34:12.089Z,1687538052.089 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2023-06-23T16:34:12.090Z,1687538052.090 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4090C4E0
2023-06-23T16:34:12.090Z,1687538052.090 [DAT ThreadHandler](INFO): Protected caller Thread ID is 3350
2023-06-23T16:34:12.091Z,1687538052.091 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-06-23T16:34:12.091Z,1687538052.091 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-06-23T16:34:12.107Z,1687538052.107 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-06-23T16:34:12.108Z,1687538052.108 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-06-23T16:34:12.149Z,1687538052.149 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-06-23T16:34:12.149Z,1687538052.149 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-06-23T16:34:12.293Z,1687538052.293 [SBIT](DEBUG): Construct Startup Built In Test.
2023-06-23T16:34:12.302Z,1687538052.302 [SBIT] Loaded
2023-06-23T16:34:12.302Z,1687538052.302 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-06-23T16:34:12.305Z,1687538052.305 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-06-23T16:34:12.317Z,1687538052.317 [IBIT] Loaded
2023-06-23T16:34:12.317Z,1687538052.317 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-06-23T16:34:12.323Z,1687538052.323 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-06-23T16:34:12.423Z,1687538052.423 [CBIT] Loaded
2023-06-23T16:34:12.424Z,1687538052.424 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-06-23T16:34:12.424Z,1687538052.424 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-06-23T16:34:12.425Z,1687538052.425 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-06-23T16:34:12.763Z,1687538052.763 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-06-23T16:34:12.764Z,1687538052.764 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-06-23T16:34:12.816Z,1687538052.816 [DepthRateCalculator] Loaded
2023-06-23T16:34:12.816Z,1687538052.816 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-06-23T16:34:12.821Z,1687538052.821 [PitchRateCalculator] Loaded
2023-06-23T16:34:12.821Z,1687538052.821 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-06-23T16:34:12.831Z,1687538052.831 [SpeedCalculator] Loaded
2023-06-23T16:34:12.831Z,1687538052.831 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-06-23T16:34:12.835Z,1687538052.835 [YawRateCalculator] Loaded
2023-06-23T16:34:12.836Z,1687538052.836 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-06-23T16:34:12.854Z,1687538052.854 [ElevatorOffsetCalculator] Loaded
2023-06-23T16:34:12.854Z,1687538052.854 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-06-23T16:34:12.855Z,1687538052.855 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-06-23T16:34:12.855Z,1687538052.855 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-06-23T16:34:13.011Z,1687538053.011 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-06-23T16:34:13.011Z,1687538053.011 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-06-23T16:34:13.123Z,1687538053.123 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-06-23T16:34:13.124Z,1687538053.124 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-06-23T16:34:13.165Z,1687538053.165 [DeadReckonUsingSpeedCalculator] Loaded
2023-06-23T16:34:13.165Z,1687538053.165 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2023-06-23T16:34:13.207Z,1687538053.207 [DeadReckonWithRespectToSeafloor] Loaded
2023-06-23T16:34:13.207Z,1687538053.207 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2023-06-23T16:34:13.221Z,1687538053.221 [NavChart] Loaded
2023-06-23T16:34:13.221Z,1687538053.221 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-06-23T16:34:13.226Z,1687538053.226 [UniversalFixResidualReporter] Loaded
2023-06-23T16:34:13.227Z,1687538053.227 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-06-23T16:34:13.227Z,1687538053.227 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-06-23T16:34:13.228Z,1687538053.228 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-06-23T16:34:13.364Z,1687538053.364 [BuoyancyServo] Loaded
2023-06-23T16:34:13.364Z,1687538053.364 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-06-23T16:34:13.384Z,1687538053.384 [ElevatorServo] Loaded
2023-06-23T16:34:13.385Z,1687538053.385 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-06-23T16:34:13.404Z,1687538053.404 [MassServo] Loaded
2023-06-23T16:34:13.404Z,1687538053.404 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-06-23T16:34:13.423Z,1687538053.423 [RudderServo] Loaded
2023-06-23T16:34:13.423Z,1687538053.423 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-06-23T16:34:13.437Z,1687538053.437 [ThrusterHE] Loaded
2023-06-23T16:34:13.437Z,1687538053.437 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-06-23T16:34:13.438Z,1687538053.438 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-06-23T16:34:13.438Z,1687538053.438 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-06-23T16:34:13.613Z,1687538053.613 [CTD_Seabird] Loaded
2023-06-23T16:34:13.614Z,1687538053.614 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-06-23T16:34:13.615Z,1687538053.615 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B634E0
2023-06-23T16:34:13.615Z,1687538053.615 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3351
2023-06-23T16:34:13.649Z,1687538053.649 [ESPComponent] Loaded
2023-06-23T16:34:13.649Z,1687538053.649 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2023-06-23T16:34:13.669Z,1687538053.669 [PAR_Licor] Loaded
2023-06-23T16:34:13.669Z,1687538053.669 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-06-23T16:34:13.700Z,1687538053.700 [WetLabsBB2FL] Loaded
2023-06-23T16:34:13.700Z,1687538053.700 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-06-23T16:34:13.701Z,1687538053.701 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B934E0
2023-06-23T16:34:13.701Z,1687538053.701 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3352
2023-06-23T16:34:13.702Z,1687538053.702 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-06-23T16:34:13.708Z,1687538053.708 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-06-23T16:34:13.711Z,1687538053.711 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-06-23T16:34:13.722Z,1687538053.722 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-06-23T16:34:13.723Z,1687538053.723 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BC34E0
2023-06-23T16:34:13.723Z,1687538053.723 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3353
2023-06-23T16:34:13.728Z,1687538053.728 [Supervisor](INFO): Main Thread ID is 1460
2023-06-23T16:34:13.728Z,1687538053.728 [Supervisor](DEBUG): Running supervisor.
2023-06-23T16:34:13.728Z,1687538053.728 [CommandExec ThreadHandler](INFO): Handler Thread ID is 3354
2023-06-23T16:34:13.729Z,1687538053.729 [CommandExec](INFO): Initializing the command executive.
2023-06-23T16:34:13.730Z,1687538053.730 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3355
2023-06-23T16:34:13.732Z,1687538053.732 [controlThread ThreadHandler](INFO): Handler Thread ID is 3356
2023-06-23T16:34:13.733Z,1687538053.733 [controlThread](DEBUG): Initializing ControlThread
2023-06-23T16:34:13.734Z,1687538053.734 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-06-23T16:34:13.736Z,1687538053.736 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-06-23T16:34:13.736Z,1687538053.736 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-06-23T16:34:13.737Z,1687538053.737 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-06-23T16:34:13.741Z,1687538053.741 [SBIT](INFO): Initialize SBIT Component.
2023-06-23T16:34:13.741Z,1687538053.741 [SBIT](IMPORTANT): git: 2023-05-23-14-gd957ee9d8
2023-06-23T16:34:13.741Z,1687538053.741 [SBIT](INFO): git hash: d957ee9d857d054abd03546ddf42b65141472a60
2023-06-23T16:34:13.742Z,1687538053.742 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-06-23T16:34:13.743Z,1687538053.743 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2023-06-23T16:34:13.744Z,1687538053.744 [SBIT](INFO): Beginning SBIT in 71.000000 seconds.
2023-06-23T16:34:13.745Z,1687538053.745 [IBIT](INFO): Initialize IBIT Component.
2023-06-23T16:34:13.745Z,1687538053.745 [CBIT](DEBUG): Initialize CBIT Component.
2023-06-23T16:34:13.746Z,1687538053.746 [logger ThreadHandler](INFO): Handler Thread ID is 3357
2023-06-23T16:34:13.759Z,1687538053.759 [CBIT](DEBUG): Initialized mux pins.
2023-06-23T16:34:13.759Z,1687538053.759 [CBIT](DEBUG): Initializing the watchdog timer.
2023-06-23T16:34:13.767Z,1687538053.767 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3358
2023-06-23T16:34:13.768Z,1687538053.768 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-06-23T16:34:13.779Z,1687538053.779 [Onboard ThreadHandler](INFO): Handler Thread ID is 3359
2023-06-23T16:34:13.783Z,1687538053.783 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-06-23T16:34:13.784Z,1687538053.784 [CBIT](DEBUG): Initializing heartbeat.
2023-06-23T16:34:13.799Z,1687538053.799 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3360
2023-06-23T16:34:13.815Z,1687538053.815 [DAT ThreadHandler](INFO): Handler Thread ID is 3361
2023-06-23T16:34:13.816Z,1687538053.816 [DAT](INFO): Powering up
2023-06-23T16:34:13.816Z,1687538053.816 [DAT](DEBUG): Initializing DAT.
2023-06-23T16:34:13.820Z,1687538053.820 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3363
2023-06-23T16:34:13.821Z,1687538053.821 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-06-23T16:34:13.824Z,1687538053.824 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3365
2023-06-23T16:34:13.827Z,1687538053.827 [WetLabsBB2FL](INFO): Powering up
2023-06-23T16:34:13.828Z,1687538053.828 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3367
2023-06-23T16:34:13.832Z,1687538053.832 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2HA05M.000
2023-06-23T16:34:13.832Z,1687538053.832 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2HA05M.000
2023-06-23T16:34:13.832Z,1687538053.832 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3HA20M.000
2023-06-23T16:34:13.832Z,1687538053.832 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3HA20M.000
2023-06-23T16:34:13.833Z,1687538053.833 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000
2023-06-23T16:34:13.833Z,1687538053.833 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000
2023-06-23T16:34:13.833Z,1687538053.833 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4HA51M.000
2023-06-23T16:34:13.833Z,1687538053.833 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4HA51M.000
2023-06-23T16:34:13.833Z,1687538053.833 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA53M.000
2023-06-23T16:34:13.833Z,1687538053.833 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA53M.000
2023-06-23T16:34:13.834Z,1687538053.834 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5HA55M.000
2023-06-23T16:34:13.834Z,1687538053.834 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5HA55M.000
2023-06-23T16:34:13.854Z,1687538053.854 [CBIT](DEBUG): Deactivating GF circuits.
2023-06-23T16:34:13.855Z,1687538053.855 [CBIT](DEBUG): Deactivating emergency mode.
2023-06-23T16:34:13.895Z,1687538053.895 [CBIT](DEBUG): Backplane powered.
2023-06-23T16:34:13.895Z,1687538053.895 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-06-23T16:34:13.895Z,1687538053.895 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-06-23T16:34:13.896Z,1687538053.896 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-06-23T16:34:13.896Z,1687538053.896 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-06-23T16:34:13.896Z,1687538053.896 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-06-23T16:34:13.897Z,1687538053.897 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-06-23T16:34:13.898Z,1687538053.898 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-06-23T16:34:13.898Z,1687538053.898 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-06-23T16:34:13.904Z,1687538053.904 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-06-23T16:34:13.925Z,1687538053.925 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-06-23T16:34:13.953Z,1687538053.953 [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>


2023-06-23T16:34:13.953Z,1687538053.953 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-06-23T16:34:14.004Z,1687538054.004 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-06-23T16:34:14.005Z,1687538054.005 [Default:A.Wait](DEBUG): Construct Wait.
2023-06-23T16:34:14.008Z,1687538054.008 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-06-23T16:34:14.057Z,1687538054.057 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-06-23T16:34:14.060Z,1687538054.060 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-06-23T16:34:14.066Z,1687538054.066 [Default:E.Execute](DEBUG): Construct Execute.
2023-06-23T16:34:14.084Z,1687538054.084 [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="P4H">
                <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>


2023-06-23T16:34:14.092Z,1687538054.092 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2023-06-23T16:34:14.111Z,1687538054.111 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-06-23T16:34:14.161Z,1687538054.161 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.140412 decibar, -1.132002 m
2023-06-23T16:34:14.163Z,1687538054.163 [Power24vConverter](INFO): Powering up.
2023-06-23T16:34:14.169Z,1687538054.169 [DepthRateCalculator](ERROR): Depth measurement is not active
2023-06-23T16:34:14.195Z,1687538054.195 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-06-23T16:34:14.203Z,1687538054.203 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-06-23T16:34:14.204Z,1687538054.204 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-06-23T16:34:14.207Z,1687538054.207 [Radio_Surface](INFO): Powering up
2023-06-23T16:34:14.211Z,1687538054.211 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-06-23T16:34:14.212Z,1687538054.212 [MassServo](DEBUG): Initializing EZServoServo.
2023-06-23T16:34:14.219Z,1687538054.219 [MassServo](DEBUG): Initializing MassServo.
2023-06-23T16:34:14.220Z,1687538054.220 [RudderServo](DEBUG): Initializing EZServoServo.
2023-06-23T16:34:14.227Z,1687538054.227 [RudderServo](DEBUG): Initializing RudderServo.
2023-06-23T16:34:14.228Z,1687538054.228 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-06-23T16:34:14.235Z,1687538054.235 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-06-23T16:34:14.533Z,1687538054.533 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-06-23T16:34:14.533Z,1687538054.533 [DropWeight] Hardware Fault, FailCount= 1
2023-06-23T16:34:14.533Z,1687538054.533 [DropWeight](ERROR): Hardware Fault
2023-06-23T16:34:14.563Z,1687538054.563 [CommandExec](FAULT): Scheduling is paused
2023-06-23T16:34:14.563Z,1687538054.563 [CBIT](INFO): Critical error at 20230623T163414
2023-06-23T16:34:14.563Z,1687538054.563 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2023-06-23T16:34:14.566Z,1687538054.566 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-06-23T16:34:14.571Z,1687538054.571 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-06-23T16:34:14.941Z,1687538054.941 [CBIT](INFO): Critical error at 20230623T163414
2023-06-23T16:34:15.935Z,1687538055.935 [WetLabsBB2FL](INFO): Powering down
2023-06-23T16:34:17.328Z,1687538057.328 [AHRS_M2](DEBUG): boresightMatrix m[ decimal 0 0 2 2 f0.0 f0.0 f-1.0 f0.0 f-1.0 f0.0 f-1.0 f0.0 f0.0 ]m set drop

2023-06-23T16:34:17.733Z,1687538057.733 [AHRS_M2](DEBUG): chan0Format 2 set drop

2023-06-23T16:34:18.136Z,1687538058.136 [AHRS_M2](DEBUG): chan0Trigger 5 set drop

2023-06-23T16:34:18.540Z,1687538058.540 [AHRS_M2](DEBUG): chan0Enables array[ 0 15 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 ]array set drop

2023-06-23T16:34:18.540Z,1687538058.540 [AHRS_M2](DEBUG): chan0EnableBit pitch dvid@ set drop

2023-06-23T16:34:18.944Z,1687538058.944 [AHRS_M2](DEBUG): chan0EnableBit roll dvid@ set drop

2023-06-23T16:34:19.347Z,1687538059.347 [AHRS_M2](DEBUG): chan0EnableBit yaw dvid@ set drop

2023-06-23T16:34:19.753Z,1687538059.753 [AHRS_M2](DEBUG): chan0EnableBit magp dvid@ set drop

2023-06-23T16:34:19.932Z,1687538059.932 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-06-23T16:34:20.156Z,1687538060.156 [AHRS_M2](DEBUG): chan0EnableBit accelp dvid@ set drop

2023-06-23T16:34:20.560Z,1687538060.560 [AHRS_M2](DEBUG): chan0EnableBit gyrop dvid@ set drop

2023-06-23T16:34:20.851Z,1687538060.851 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2023-06-23T16:34:20.963Z,1687538060.963 [AHRS_M2](DEBUG): chan0EnableBit yawErrEst dvid@ set drop

2023-06-23T16:34:21.368Z,1687538061.368 [AHRS_M2](DEBUG): chan0EnableBit temperature dvid@ set drop

2023-06-23T16:34:21.772Z,1687538061.772 [AHRS_M2](DEBUG): chan0EnableBit magBufferActiveIndex dvid@ set drop

2023-06-23T16:34:22.176Z,1687538062.176 [AHRS_M2](DEBUG): chan0TriggerDivisor 40 set drop

2023-06-23T16:34:23.860Z,1687538063.860 [CBIT](CRITICAL): Environmental Failure. Press:14.773823 PSI. Humidity:43%. Temp:26 C. ABORTING MISSION
2023-06-23T16:34:24.245Z,1687538064.245 [CBIT](INFO): Critical error at 20230623T163423
2023-06-23T16:34:32.276Z,1687538072.276 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2023-06-23T16:34:32.276Z,1687538072.276 [AHRS_M2] Data Fault, FailCount= 1
2023-06-23T16:34:32.276Z,1687538072.276 [AHRS_M2](ERROR): Data Fault
2023-06-23T16:34:32.347Z,1687538072.347 [CBIT](ERROR): Data Fault in component: AHRS_M2
2023-06-23T16:34:32.347Z,1687538072.347 [CBIT](CRITICAL): Data Fault in component: AHRS_M2
2023-06-23T16:34:32.725Z,1687538072.725 [CBIT](INFO): Critical error at 20230623T163432
2023-06-23T16:34:41.980Z,1687538081.980 [NAL9602](INFO): Powering up NAL9602
2023-06-23T16:34:52.889Z,1687538092.889 [NAL9602](INFO): NAL9602 initialized
2023-06-23T16:35:14.055Z,1687538114.055 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2023-06-23T16:35:14.055Z,1687538114.055 [DAT] Communications Fault, FailCount= 1
2023-06-23T16:35:14.055Z,1687538114.055 [DAT](ERROR): Communications Fault
2023-06-23T16:35:14.317Z,1687538114.317 [CBIT](ERROR): Communications Fault in component: DAT
2023-06-23T16:35:14.459Z,1687538114.459 [DAT](INFO): Powering down
2023-06-23T16:35:15.528Z,1687538115.528 [CBIT](INFO): Clearing failed state for component DAT
2023-06-23T16:35:15.528Z,1687538115.528 [DAT] No Fault, FailCount= 1
2023-06-23T16:35:17.503Z,1687538117.503 [DAT](INFO): Powering up
2023-06-23T16:35:17.503Z,1687538117.503 [DAT](DEBUG): Initializing DAT.
2023-06-23T16:35:25.229Z,1687538125.229 [SBIT](IMPORTANT): Beginning Startup BIT
2023-06-23T16:35:25.234Z,1687538125.234 [CBIT](IMPORTANT): Beginning ground fault scan
2023-06-23T16:35:32.068Z,1687538132.068 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-06-23T16:35:36.133Z,1687538136.133 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.067699
CHAN A1 (24V): 0.051674
CHAN A2 (12V): 0.000212
CHAN A3 (5V): -0.001110
CHAN B0 (3.3V): 0.000013
CHAN B1 (3.15aV): -0.000128
CHAN B2 (3.15bV): 0.000239
CHAN B3 (GND): 0.001677
OPEN: 0.006560
Full Scale: +/- 1 mA
2023-06-23T16:35:47.480Z,1687538147.480 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-06-23T16:36:00.240Z,1687538160.240 [CommandExec](IMPORTANT): got command configSet PowerOnly.sampleTime1 0 second
2023-06-23T16:36:00.353Z,1687538160.353 [PowerOnly](INFO): Powering up loadControl
2023-06-23T16:36:02.368Z,1687538162.368 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-06-23T16:36:14.151Z,1687538174.151 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-06-23T16:36:14.152Z,1687538174.152 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-06-23T16:36:14.152Z,1687538174.152 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-06-23T16:36:14.153Z,1687538174.153 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-06-23T16:36:14.153Z,1687538174.153 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2023-06-23T16:36:14.153Z,1687538174.153 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-06-23T16:36:14.155Z,1687538174.155 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-06-23T16:36:14.155Z,1687538174.155 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1
2023-06-23T16:36:14.155Z,1687538174.155 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2023-06-23T16:36:14.219Z,1687538174.219 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-06-23T16:36:14.219Z,1687538174.219 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-06-23T16:36:14.220Z,1687538174.220 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2023-06-23T16:36:14.560Z,1687538174.560 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-06-23T16:36:14.560Z,1687538174.560 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-06-23T16:36:14.561Z,1687538174.561 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-06-23T16:36:14.561Z,1687538174.561 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2023-06-23T16:36:14.561Z,1687538174.561 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2023-06-23T16:36:14.562Z,1687538174.562 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1
2023-06-23T16:36:14.935Z,1687538174.935 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-06-23T16:36:17.507Z,1687538177.507 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2023-06-23T16:36:17.507Z,1687538177.507 [DAT] Communications Fault, FailCount= 2
2023-06-23T16:36:17.507Z,1687538177.507 [DAT](ERROR): Communications Fault
2023-06-23T16:36:17.720Z,1687538177.720 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-06-23T16:36:17.785Z,1687538177.785 [CBIT](ERROR): Communications Fault in component: DAT
2023-06-23T16:36:17.911Z,1687538177.911 [DAT](INFO): Powering down
2023-06-23T16:36:18.967Z,1687538178.967 [CBIT](INFO): Clearing failed state for component DAT
2023-06-23T16:36:18.967Z,1687538178.967 [DAT] No Fault, FailCount= 2
2023-06-23T16:36:19.373Z,1687538179.373 [SBIT](IMPORTANT): SBIT PASSED
2023-06-23T16:36:19.374Z,1687538179.374 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-06-23T16:36:19.375Z,1687538179.375 [SBIT](IMPORTANT): PowerOnly.sampleTime1=1 second;

2023-06-23T16:36:19.788Z,1687538179.788 [MissionManager](IMPORTANT): Started mission Startup
2023-06-23T16:36:19.789Z,1687538179.789 [Startup] Running Loop=1
2023-06-23T16:36:19.789Z,1687538179.789 [Startup](DEBUG): Aggregate::initialize Startup
2023-06-23T16:36:19.789Z,1687538179.789 [Startup:A.GoToSurface] Running Loop=1
2023-06-23T16:36:19.789Z,1687538179.789 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-06-23T16:36:19.790Z,1687538179.790 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-06-23T16:36:19.790Z,1687538179.790 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-06-23T16:36:19.791Z,1687538179.791 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-06-23T16:36:19.791Z,1687538179.791 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-06-23T16:36:19.791Z,1687538179.791 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-06-23T16:36:19.793Z,1687538179.793 [Startup:StartupSatComms] Running Loop=1
2023-06-23T16:36:19.793Z,1687538179.793 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-06-23T16:36:19.793Z,1687538179.793 [Startup:StartupSatComms:A] Running Loop=1
2023-06-23T16:36:20.172Z,1687538180.172 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-06-23T16:36:20.963Z,1687538180.963 [DAT](INFO): Powering up
2023-06-23T16:36:20.963Z,1687538180.963 [DAT](DEBUG): Initializing DAT.
2023-06-23T16:36:26.706Z,1687538186.706 [CommandExec](IMPORTANT): got command configSet PowerOnly.sampleTime2 0 second
2023-06-23T16:36:32.668Z,1687538192.668 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-06-23T16:36:47.616Z,1687538207.616 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-06-23T16:37:03.372Z,1687538223.372 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-06-23T16:37:03.965Z,1687538223.965 [CommandExec](IMPORTANT): got command get PowerOnly.sampleTime1
2023-06-23T16:37:03.966Z,1687538223.966 [CommandExec](IMPORTANT): PowerOnly.sampleTime1 0.000000 s
2023-06-23T16:37:15.764Z,1687538235.764 [CommandExec](IMPORTANT): got command get PowerOnly.sampleTime2
2023-06-23T16:37:15.765Z,1687538235.765 [CommandExec](IMPORTANT): PowerOnly.sampleTime2 0.000000 s
2023-06-23T16:37:19.128Z,1687538239.128 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-06-23T16:37:19.965Z,1687538239.965 [Startup:StartupSatComms:A](INFO): Timed out from 2023-06-23T16:36:19.8Z
2023-06-23T16:37:19.965Z,1687538239.965 [Startup:StartupSatComms:A] Stopped
2023-06-23T16:37:19.965Z,1687538239.965 [Startup:StartupSatComms:B] Running Loop=1
2023-06-23T16:37:20.357Z,1687538240.357 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-06-23T16:37:20.991Z,1687538240.991 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2023-06-23T16:37:20.991Z,1687538240.991 [DAT] Communications Fault, FailCount= 3
2023-06-23T16:37:20.991Z,1687538240.991 [DAT](ERROR): Communications Fault
2023-06-23T16:37:21.235Z,1687538241.235 [CBIT](ERROR): Communications Fault in component: DAT
2023-06-23T16:37:21.395Z,1687538241.395 [DAT](INFO): Powering down
2023-06-23T16:37:22.398Z,1687538242.398 [CBIT](INFO): Clearing failed state for component DAT
2023-06-23T16:37:22.398Z,1687538242.398 [DAT] No Fault, FailCount= 3
2023-06-23T16:37:24.452Z,1687538244.452 [DAT](INFO): Powering up
2023-06-23T16:37:24.452Z,1687538244.452 [DAT](DEBUG): Initializing DAT.
2023-06-23T16:37:34.076Z,1687538254.076 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-06-23T16:37:49.439Z,1687538269.439 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2023-06-23T16:37:58.809Z,1687538278.809 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004559
2023-06-23T16:38:05.995Z,1687538285.995 [DataOverHttps](INFO): Sending 332 bytes from file Logs/20230623T162835/Courier0000.lzma
2023-06-23T16:38:06.997Z,1687538286.997 [DataOverHttps](INFO): Moved sent file to Logs/20230623T162835/Courier0000.lzma.bak
2023-06-23T16:38:06.997Z,1687538286.997 [DataOverHttps](INFO): SBD MOMSN=18422150
2023-06-23T16:38:14.974Z,1687538294.974 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-06-23T16:38:14.974Z,1687538294.974 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2023-06-23T16:38:14.974Z,1687538294.974 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-06-23T16:38:15.020Z,1687538295.020 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-06-23T16:38:15.020Z,1687538295.020 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 2
2023-06-23T16:38:15.020Z,1687538295.020 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-06-23T16:38:15.022Z,1687538295.022 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-06-23T16:38:15.022Z,1687538295.022 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2
2023-06-23T16:38:15.022Z,1687538295.022 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2023-06-23T16:38:15.089Z,1687538295.089 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-06-23T16:38:15.089Z,1687538295.089 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-06-23T16:38:15.090Z,1687538295.090 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2023-06-23T16:38:15.545Z,1687538295.545 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-06-23T16:38:15.546Z,1687538295.546 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2023-06-23T16:38:15.546Z,1687538295.546 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-06-23T16:38:15.546Z,1687538295.546 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 2
2023-06-23T16:38:15.595Z,1687538295.595 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2023-06-23T16:38:15.595Z,1687538295.595 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2
2023-06-23T16:38:15.789Z,1687538295.789 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-06-23T16:38:20.209Z,1687538300.209 [Startup:StartupSatComms:B](INFO): Timed out from 2023-06-23T16:37:19.0Z
2023-06-23T16:38:20.209Z,1687538300.209 [Startup:StartupSatComms:B] Stopped
2023-06-23T16:38:20.210Z,1687538300.210 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-06-23T16:38:20.210Z,1687538300.210 [Startup:StartupSatComms] Stopped
2023-06-23T16:38:20.210Z,1687538300.210 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-06-23T16:38:20.211Z,1687538300.211 [Startup](INFO): Completed Startup
2023-06-23T16:38:20.211Z,1687538300.211 [MissionManager](INFO): Startup is completed.
2023-06-23T16:38:20.211Z,1687538300.211 [MissionManager](INFO): Uninitializing Mission Startup
2023-06-23T16:38:20.211Z,1687538300.211 [Startup] Stopped
2023-06-23T16:38:20.211Z,1687538300.211 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-06-23T16:38:20.211Z,1687538300.211 [Startup:A.GoToSurface] Stopped
2023-06-23T16:38:20.211Z,1687538300.211 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-06-23T16:38:20.612Z,1687538300.612 [MissionManager](IMPORTANT): Started mission Default
2023-06-23T16:38:20.612Z,1687538300.612 [Default] Running Loop=1
2023-06-23T16:38:20.612Z,1687538300.612 [Default](DEBUG): Aggregate::initialize Default
2023-06-23T16:38:20.612Z,1687538300.612 [Default:B.GoToSurface] Running Loop=1
2023-06-23T16:38:20.613Z,1687538300.613 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-06-23T16:38:20.613Z,1687538300.613 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-06-23T16:38:20.613Z,1687538300.613 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-06-23T16:38:20.613Z,1687538300.613 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-06-23T16:38:20.614Z,1687538300.614 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-06-23T16:38:20.614Z,1687538300.614 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-06-23T16:38:20.615Z,1687538300.615 [Default:A.Wait] Running Loop=1
2023-06-23T16:38:20.615Z,1687538300.615 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-06-23T16:38:24.463Z,1687538304.463 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2023-06-23T16:38:24.463Z,1687538304.463 [DAT] Communications Fault, FailCount= 4
2023-06-23T16:38:24.463Z,1687538304.463 [DAT](ERROR): Communications Fault
2023-06-23T16:38:24.659Z,1687538304.659 [CBIT](ERROR): Communications Fault in component: DAT
2023-06-23T16:38:24.867Z,1687538304.867 [DAT](INFO): Powering down
2023-06-23T16:38:24.958Z,1687538304.958 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230623T162835/Courier0004.lzma
2023-06-23T16:38:25.878Z,1687538305.878 [CBIT](INFO): Clearing failed state for component DAT
2023-06-23T16:38:25.878Z,1687538305.878 [DAT] No Fault, FailCount= 4
2023-06-23T16:38:25.960Z,1687538305.960 [DataOverHttps](INFO): Moved sent file to Logs/20230623T162835/Courier0004.lzma.bak
2023-06-23T16:38:25.960Z,1687538305.960 [DataOverHttps](INFO): SBD MOMSN=18422157
2023-06-23T16:38:27.915Z,1687538307.915 [DAT](INFO): Powering up
2023-06-23T16:38:27.915Z,1687538307.915 [DAT](DEBUG): Initializing DAT.
2023-06-23T16:38:33.934Z,1687538313.934 [Default:A.Wait](INFO): Done Waiting.
2023-06-23T16:38:33.934Z,1687538313.934 [Default:A.Wait] Stopped
2023-06-23T16:38:33.935Z,1687538313.935 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-06-23T16:38:34.333Z,1687538314.333 [Default:CheckIn] Running Loop=1
2023-06-23T16:38:34.333Z,1687538314.333 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-06-23T16:38:34.334Z,1687538314.334 [Default:CheckIn:Read_GPS] Running Loop=1
2023-06-23T16:38:34.737Z,1687538314.737 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-06-23T16:39:27.959Z,1687538367.959 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2023-06-23T16:39:27.959Z,1687538367.959 [DAT] Communications Fault, FailCount= 5
2023-06-23T16:39:27.959Z,1687538367.959 [DAT](ERROR): Communications Fault
2023-06-23T16:39:28.074Z,1687538368.074 [CBIT](ERROR): Communications Fault in component: DAT
2023-06-23T16:39:28.363Z,1687538368.363 [DAT](INFO): Powering down
2023-06-23T16:39:29.305Z,1687538369.305 [CBIT](INFO): Clearing failed state for component DAT
2023-06-23T16:39:29.306Z,1687538369.306 [DAT] No Fault, FailCount= 5
2023-06-23T16:39:31.407Z,1687538371.407 [DAT](INFO): Powering up
2023-06-23T16:39:31.407Z,1687538371.407 [DAT](DEBUG): Initializing DAT.
2023-06-23T16:39:56.336Z,1687538396.336 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-06-23T16:40:16.150Z,1687538416.150 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-06-23T16:40:16.150Z,1687538416.150 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2023-06-23T16:40:16.150Z,1687538416.150 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-06-23T16:40:16.152Z,1687538416.152 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-06-23T16:40:16.152Z,1687538416.152 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 3
2023-06-23T16:40:16.152Z,1687538416.152 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-06-23T16:40:16.154Z,1687538416.154 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-06-23T16:40:16.154Z,1687538416.154 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 3
2023-06-23T16:40:16.154Z,1687538416.154 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2023-06-23T16:40:16.165Z,1687538416.165 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-06-23T16:40:16.165Z,1687538416.165 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-06-23T16:40:16.165Z,1687538416.165 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2023-06-23T16:40:16.585Z,1687538416.585 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-06-23T16:40:16.585Z,1687538416.585 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2023-06-23T16:40:16.586Z,1687538416.586 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-06-23T16:40:16.586Z,1687538416.586 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 3
2023-06-23T16:40:16.615Z,1687538416.615 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2023-06-23T16:40:16.615Z,1687538416.615 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 3
2023-06-23T16:40:17.025Z,1687538417.025 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-06-23T16:40:31.655Z,1687538431.655 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2023-06-23T16:40:31.655Z,1687538431.655 [DAT] Communications Fault, FailCount= 6
2023-06-23T16:40:31.655Z,1687538431.655 [DAT](ERROR): Communications Fault
2023-06-23T16:40:31.950Z,1687538431.950 [CBIT](ERROR): Communications Fault in component: DAT
2023-06-23T16:40:32.059Z,1687538432.059 [DAT](INFO): Powering down
2023-06-23T16:40:33.178Z,1687538433.178 [CBIT](INFO): Clearing failed state for component DAT
2023-06-23T16:40:33.178Z,1687538433.178 [DAT] No Fault, FailCount= 6
2023-06-23T16:40:35.107Z,1687538435.107 [DAT](INFO): Powering up
2023-06-23T16:40:35.107Z,1687538435.107 [DAT](DEBUG): Initializing DAT.
2023-06-23T16:41:15.670Z,1687538475.670 [CommandExec](IMPORTANT): got command configSet PowerOnly.sampleTime1 1 second
2023-06-23T16:41:16.351Z,1687538476.351 [PowerOnly](INFO): Powering down loadControl
2023-06-23T16:41:35.184Z,1687538495.184 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 
2023-06-23T16:41:35.184Z,1687538495.184 [DAT] Communications Fault, FailCount= 7
2023-06-23T16:41:35.184Z,1687538495.184 [DAT](ERROR): Communications Fault
2023-06-23T16:41:35.359Z,1687538495.359 [CBIT](ERROR): Communications Fault in component: DAT
2023-06-23T16:41:35.587Z,1687538495.587 [DAT](INFO): Powering down
2023-06-23T16:41:36.582Z,1687538496.582 [CBIT](INFO): Clearing failed state for component DAT
2023-06-23T16:41:36.583Z,1687538496.583 [DAT] No Fault, FailCount= 7
2023-06-23T16:41:38.635Z,1687538498.635 [DAT](INFO): Powering up
2023-06-23T16:41:38.635Z,1687538498.635 [DAT](DEBUG): Initializing DAT.
2023-06-23T16:41:41.979Z,1687538501.979 [CommandExec](IMPORTANT): got command get PowerOnly.loadControl
2023-06-23T16:41:41.979Z,1687538501.979 [CommandExec](IMPORTANT): PowerOnly.loadControl /dev/loadC4
2023-06-23T16:41:47.305Z,1687538507.305 [CommandExec](IMPORTANT): got command get PowerOnly.loadControl2
2023-06-23T16:41:47.305Z,1687538507.305 [CommandExec](IMPORTANT): PowerOnly.loadControl2 /dev/null
2023-06-23T16:42:17.388Z,1687538537.388 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-06-23T16:42:17.389Z,1687538537.389 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2023-06-23T16:42:17.389Z,1687538537.389 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-06-23T16:42:17.390Z,1687538537.390 [DeadReckonUsingSpeedCalculator](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-06-23T16:42:17.390Z,1687538537.390 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 4
2023-06-23T16:42:17.390Z,1687538537.390 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-06-23T16:42:17.392Z,1687538537.392 [DeadReckonWithRespectToSeafloor](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2023-06-23T16:42:17.392Z,1687538537.392 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 4
2023-06-23T16:42:17.392Z,1687538537.392 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault
2023-06-23T16:42:17.404Z,1687538537.404 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-06-23T16:42:17.404Z,1687538537.404 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-06-23T16:42:17.404Z,1687538537.404 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor
2023-06-23T16:42:17.576Z,1687538537.576 [CommandExec](IMPORTANT): got command restart application
2023-06-23T16:42:17.811Z,1687538537.811 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-06-23T16:42:17.812Z,1687538537.812 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2023-06-23T16:42:17.812Z,1687538537.812 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-06-23T16:42:17.812Z,1687538537.812 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 4
2023-06-23T16:42:17.813Z,1687538537.813 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor
2023-06-23T16:42:17.813Z,1687538537.813 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 4
2023-06-23T16:42:18.240Z,1687538538.240 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-06-23T16:42:18.579Z,1687538538.579 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-06-23T16:42:18.579Z,1687538538.579 [CommandExec](INFO): Uninitializing the command executive.
2023-06-23T16:42:18.579Z,1687538538.579 [CommandExec](INFO): Uninitializing the command scheduler.
2023-06-23T16:42:18.579Z,1687538538.579 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-06-23T16:42:18.750Z,1687538538.750 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-06-23T16:42:18.751Z,1687538538.751 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-06-23T16:42:18.751Z,1687538538.751 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-06-23T16:42:18.751Z,1687538538.751 [NavChartDb](INFO): Join timeout helper Thread ID is 3434
2023-06-23T16:42:18.831Z,1687538538.831 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-06-23T16:42:18.831Z,1687538538.831 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-06-23T16:42:18.851Z,1687538538.851 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-06-23T16:42:18.851Z,1687538538.851 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-06-23T16:42:18.851Z,1687538538.851 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3435
2023-06-23T16:42:18.907Z,1687538538.907 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-06-23T16:42:18.907Z,1687538538.907 [WetLabsBB2FL](INFO): Powering down
2023-06-23T16:42:18.908Z,1687538538.908 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-06-23T16:42:18.915Z,1687538538.915 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-06-23T16:42:18.915Z,1687538538.915 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-06-23T16:42:18.915Z,1687538538.915 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3436
2023-06-23T16:42:19.875Z,1687538539.875 [CTD_Seabird](INFO): Powering down
2023-06-23T16:42:19.887Z,1687538539.887 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-06-23T16:42:19.887Z,1687538539.887 [CTD_Seabird](INFO): Powering down
2023-06-23T16:42:19.899Z,1687538539.899 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-06-23T16:42:19.919Z,1687538539.919 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2023-06-23T16:42:19.919Z,1687538539.919 [DAT ThreadHandler](INFO): Thread cancelled.
2023-06-23T16:42:19.919Z,1687538539.919 [DAT](INFO): Join timeout helper Thread ID is 3437
2023-06-23T16:42:20.023Z,1687538540.023 [DAT](INFO): Powering down
2023-06-23T16:42:20.103Z,1687538540.103 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2023-06-23T16:42:20.103Z,1687538540.103 [DAT](INFO): Powering down
2023-06-23T16:42:20.104Z,1687538540.104 [DAT ThreadHandler](INFO): Thread cancelled.
2023-06-23T16:42:20.104Z,1687538540.104 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-06-23T16:42:20.104Z,1687538540.104 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-06-23T16:42:20.104Z,1687538540.104 [Radio_Surface](INFO): Join timeout helper Thread ID is 3438
2023-06-23T16:42:20.319Z,1687538540.319 [Radio_Surface](INFO): Powering down
2023-06-23T16:42:20.320Z,1687538540.320 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-06-23T16:42:20.320Z,1687538540.320 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-06-23T16:42:20.339Z,1687538540.339 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-06-23T16:42:20.340Z,1687538540.340 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-06-23T16:42:20.340Z,1687538540.340 [Onboard](INFO): Join timeout helper Thread ID is 3439
2023-06-23T16:42:20.803Z,1687538540.803 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-06-23T16:42:24.199Z,1687538544.199 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-06-23T16:42:24.199Z,1687538544.199 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-06-23T16:42:24.219Z,1687538544.219 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-06-23T16:42:24.219Z,1687538544.219 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-06-23T16:42:24.220Z,1687538544.220 [DataOverHttps](INFO): Join timeout helper Thread ID is 3440
2023-06-23T16:42:24.819Z,1687538544.819 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-06-23T16:42:24.819Z,1687538544.819 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-06-23T16:42:24.827Z,1687538544.827 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-06-23T16:42:24.828Z,1687538544.828 [logger ThreadHandler](INFO): Thread cancelled.
2023-06-23T16:42:24.828Z,1687538544.828 [logger](INFO): Join timeout helper Thread ID is 3441
2023-06-23T16:42:24.831Z,1687538544.831 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-06-23T16:42:24.831Z,1687538544.831 [logger ThreadHandler](INFO): Thread cancelled.
2023-06-23T16:42:24.847Z,1687538544.847 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-06-23T16:42:24.847Z,1687538544.847 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-06-23T16:42:24.848Z,1687538544.848 [CommandLine](INFO): Join timeout helper Thread ID is 3442
2023-06-23T16:42:24.927Z,1687538544.927 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-06-23T16:42:24.927Z,1687538544.927 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-06-23T16:42:24.947Z,1687538544.947 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-06-23T16:42:24.947Z,1687538544.947 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-06-23T16:42:24.948Z,1687538544.948 [CommandExec](INFO): Join timeout helper Thread ID is 3443
2023-06-23T16:42:24.949Z,1687538544.949 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-06-23T16:42:24.949Z,1687538544.949 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-06-23T16:42:24.949Z,1687538544.949 [controlThread](INFO): Join timeout helper Thread ID is 3444
2023-06-23T16:42:25.031Z,1687538545.031 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-06-23T16:42:25.031Z,1687538545.031 [controlThread](DEBUG): Uninitializing ControlThread
2023-06-23T16:42:25.033Z,1687538545.033 [NAL9602](INFO): Powering down
2023-06-23T16:42:25.036Z,1687538545.036 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-06-23T16:42:25.036Z,1687538545.036 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-06-23T16:42:25.037Z,1687538545.037 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-06-23T16:42:25.037Z,1687538545.037 [MissionManager](INFO): Uninitializing Mission Default
2023-06-23T16:42:25.037Z,1687538545.037 [Default] Stopped
2023-06-23T16:42:25.037Z,1687538545.037 [Default](DEBUG): Aggregate::uninitialize Default
2023-06-23T16:42:25.037Z,1687538545.037 [Default:B.GoToSurface] Stopped
2023-06-23T16:42:25.038Z,1687538545.038 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-06-23T16:42:25.038Z,1687538545.038 [Default:CheckIn] Stopped
2023-06-23T16:42:25.038Z,1687538545.038 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-06-23T16:42:25.038Z,1687538545.038 [Default:CheckIn:Read_GPS] Stopped
2023-06-23T16:42:25.040Z,1687538545.040 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-06-23T16:42:25.041Z,1687538545.041 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-06-23T16:42:25.041Z,1687538545.041 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-06-23T16:42:25.041Z,1687538545.041 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-06-23T16:42:25.041Z,1687538545.041 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-06-23T16:42:25.042Z,1687538545.042 [BuoyancyServo](INFO): Powering down
2023-06-23T16:42:25.055Z,1687538545.055 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-06-23T16:42:25.055Z,1687538545.055 [ElevatorServo](INFO): Powering down
2023-06-23T16:42:25.056Z,1687538545.056 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-06-23T16:42:25.056Z,1687538545.056 [MassServo](INFO): Powering down
2023-06-23T16:42:25.057Z,1687538545.057 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-06-23T16:42:25.057Z,1687538545.057 [RudderServo](INFO): Powering down
2023-06-23T16:42:25.058Z,1687538545.058 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-06-23T16:42:25.058Z,1687538545.058 [ThrusterHE](INFO): Powering down
2023-06-23T16:42:25.059Z,1687538545.059 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-06-23T16:42:25.059Z,1687538545.059 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-06-23T16:42:25.059Z,1687538545.059 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-06-23T16:42:25.059Z,1687538545.059 [CBIT](DEBUG): Powering off loads.
2023-06-23T16:42:25.070Z,1687538545.070 [CBIT](DEBUG): Disabling WDT.
2023-06-23T16:42:25.082Z,1687538545.082 [CBIT](DEBUG): Opening all GF detection circuits.
2023-06-23T16:42:25.083Z,1687538545.083 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-06-23T16:42:25.105Z,1687538545.105 [DAT ThreadHandler](INFO): Thread cancelled.
2023-06-23T16:42:25.110Z,1687538545.110 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-06-23T16:42:25.113Z,1687538545.113 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-06-23T16:42:25.119Z,1687538545.119 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-06-23T16:42:25.245Z,1687538545.245 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-06-23T16:42:25.250Z,1687538545.250 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-06-23T16:42:25.257Z,1687538545.257 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-06-23T16:42:25.304Z,1687538545.304 [logger ThreadHandler](INFO): Thread cancelled.