2023-08-01T16:16:49.469Z,1690906609.469 [Supervisor](DEBUG): Initializing supervisor.
2023-08-01T16:16:49.488Z,1690906609.488 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-08-01T16:16:49.489Z,1690906609.489 [SyncHandler](INFO): Protected caller Thread ID is 1134
2023-08-01T16:16:49.489Z,1690906609.489 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-08-01T16:16:49.490Z,1690906609.490 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-08-01T16:16:49.491Z,1690906609.491 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1135
2023-08-01T16:16:49.506Z,1690906609.506 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-08-01T16:16:49.545Z,1690906609.545 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-08-01T16:16:49.556Z,1690906609.556 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-08-01T16:16:49.556Z,1690906609.556 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1136
2023-08-01T16:16:49.560Z,1690906609.560 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-08-01T16:16:49.568Z,1690906609.568 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-08-01T16:16:49.570Z,1690906609.570 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1137
2023-08-01T16:16:49.573Z,1690906609.573 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-08-01T16:16:49.584Z,1690906609.584 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-08-01T16:16:49.584Z,1690906609.584 [logger ThreadHandler](INFO): Protected caller Thread ID is 1138
2023-08-01T16:16:49.588Z,1690906609.588 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-08-01T16:16:49.588Z,1690906609.588 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-08-01T16:16:49.609Z,1690906609.609 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-08-01T16:16:50.271Z,1690906610.271 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-08-01T16:16:50.274Z,1690906610.274 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-08-01T16:16:50.513Z,1690906610.513 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-08-01T16:16:50.785Z,1690906610.785 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-08-01T16:16:51.445Z,1690906611.445 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-08-01T16:16:51.445Z,1690906611.445 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-08-01T16:16:51.757Z,1690906611.757 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-08-01T16:16:51.840Z,1690906611.840 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-08-01T16:16:53.150Z,1690906613.150 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-08-01T16:16:53.152Z,1690906613.152 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-08-01T16:16:54.233Z,1690906614.233 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-08-01T16:16:54.233Z,1690906614.233 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-08-01T16:16:55.691Z,1690906615.691 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-08-01T16:16:55.693Z,1690906615.693 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-08-01T16:16:56.387Z,1690906616.387 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-08-01T16:16:56.388Z,1690906616.388 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-08-01T16:16:57.272Z,1690906617.272 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-08-01T16:16:57.273Z,1690906617.273 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-08-01T16:16:58.421Z,1690906618.421 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-08-01T16:16:58.421Z,1690906618.421 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-08-01T16:16:58.900Z,1690906618.900 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-08-01T16:16:59.117Z,1690906619.117 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-08-01T16:16:59.469Z,1690906619.469 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-08-01T16:16:59.470Z,1690906619.470 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-08-01T16:16:59.720Z,1690906619.720 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-08-01T16:16:59.996Z,1690906619.996 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-08-01T16:17:00.765Z,1690906620.765 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-08-01T16:17:00.766Z,1690906620.766 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-08-01T16:17:01.348Z,1690906621.348 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-08-01T16:17:01.350Z,1690906621.350 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2023-08-01T16:17:01.496Z,1690906621.496 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2023-08-01T16:17:01.662Z,1690906621.662 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2023-08-01T16:17:02.094Z,1690906622.094 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-08-01T16:17:02.094Z,1690906622.094 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2023-08-01T16:17:03.010Z,1690906623.010 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2023-08-01T16:17:03.356Z,1690906623.356 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2023-08-01T16:17:03.564Z,1690906623.564 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2023-08-01T16:17:03.815Z,1690906623.815 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2023-08-01T16:17:04.024Z,1690906624.024 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2023-08-01T16:17:04.245Z,1690906624.245 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2023-08-01T16:17:04.590Z,1690906624.590 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Derivation.cfg
2023-08-01T16:17:04.866Z,1690906624.866 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2023-08-01T16:17:05.205Z,1690906625.205 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2023-08-01T16:17:05.404Z,1690906625.404 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2023-08-01T16:17:05.405Z,1690906625.405 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-08-01T16:17:06.012Z,1690906626.012 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-08-01T16:17:06.489Z,1690906626.489 [InternalEnvSim] Loaded
2023-08-01T16:17:06.490Z,1690906626.490 [ComponentRegistry](DEBUG): SyncComponent "InternalEnvSim" handled in the control thread.
2023-08-01T16:17:06.490Z,1690906626.490 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-08-01T16:17:06.491Z,1690906626.491 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-08-01T16:17:07.025Z,1690906627.025 [BuoyancyServo] Loaded
2023-08-01T16:17:07.025Z,1690906627.025 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-08-01T16:17:07.086Z,1690906627.086 [ElevatorServo] Loaded
2023-08-01T16:17:07.087Z,1690906627.087 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-08-01T16:17:07.107Z,1690906627.107 [MassServo] Loaded
2023-08-01T16:17:07.107Z,1690906627.107 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-08-01T16:17:07.127Z,1690906627.127 [RudderServo] Loaded
2023-08-01T16:17:07.127Z,1690906627.127 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-08-01T16:17:07.143Z,1690906627.143 [ThrusterHE] Loaded
2023-08-01T16:17:07.143Z,1690906627.143 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2023-08-01T16:17:07.143Z,1690906627.143 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-08-01T16:17:07.144Z,1690906627.144 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-08-01T16:17:07.607Z,1690906627.607 [SBIT](DEBUG): Construct Startup Built In Test.
2023-08-01T16:17:07.642Z,1690906627.642 [SBIT] Loaded
2023-08-01T16:17:07.643Z,1690906627.643 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-08-01T16:17:07.651Z,1690906627.651 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-08-01T16:17:07.687Z,1690906627.687 [IBIT] Loaded
2023-08-01T16:17:07.687Z,1690906627.687 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-08-01T16:17:07.705Z,1690906627.705 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-08-01T16:17:08.227Z,1690906628.227 [CBIT] Loaded
2023-08-01T16:17:08.228Z,1690906628.228 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-08-01T16:17:08.228Z,1690906628.228 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-08-01T16:17:08.229Z,1690906628.229 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-08-01T16:17:08.703Z,1690906628.703 [CTD_Seabird] Loaded
2023-08-01T16:17:08.703Z,1690906628.703 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-08-01T16:17:08.728Z,1690906628.728 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406674E0
2023-08-01T16:17:08.728Z,1690906628.728 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1223
2023-08-01T16:17:08.832Z,1690906628.832 [ESPComponent] Loaded
2023-08-01T16:17:08.832Z,1690906628.832 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2023-08-01T16:17:08.867Z,1690906628.867 [PAR_Licor] Loaded
2023-08-01T16:17:08.867Z,1690906628.867 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-08-01T16:17:08.956Z,1690906628.956 [WetLabsBB2FL] Loaded
2023-08-01T16:17:08.956Z,1690906628.956 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-08-01T16:17:08.968Z,1690906628.968 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406974E0
2023-08-01T16:17:08.969Z,1690906628.969 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1224
2023-08-01T16:17:09.030Z,1690906629.030 [WetLabsSeaOWL_UV_A] Loaded
2023-08-01T16:17:09.030Z,1690906629.030 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2023-08-01T16:17:09.040Z,1690906629.040 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 406C74E0
2023-08-01T16:17:09.040Z,1690906629.040 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 1225
2023-08-01T16:17:09.041Z,1690906629.041 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-08-01T16:17:09.054Z,1690906629.054 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-08-01T16:17:09.178Z,1690906629.178 [DepthRateCalculator] Loaded
2023-08-01T16:17:09.178Z,1690906629.178 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-08-01T16:17:09.192Z,1690906629.192 [PitchRateCalculator] Loaded
2023-08-01T16:17:09.193Z,1690906629.193 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-08-01T16:17:09.209Z,1690906629.209 [SpeedCalculator] Loaded
2023-08-01T16:17:09.210Z,1690906629.210 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-08-01T16:17:09.214Z,1690906629.214 [YawRateCalculator] Loaded
2023-08-01T16:17:09.214Z,1690906629.214 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-08-01T16:17:09.262Z,1690906629.262 [ElevatorOffsetCalculator] Loaded
2023-08-01T16:17:09.262Z,1690906629.262 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-08-01T16:17:09.319Z,1690906629.319 [ShortestDistanceToPolygonSidesCalculator] Loaded
2023-08-01T16:17:09.319Z,1690906629.319 [ComponentRegistry](DEBUG): SyncComponent "ShortestDistanceToPolygonSidesCalculator" handled in the control thread.
2023-08-01T16:17:09.319Z,1690906629.319 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-08-01T16:17:09.324Z,1690906629.324 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-08-01T16:17:09.574Z,1690906629.574 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-08-01T16:17:09.575Z,1690906629.575 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-08-01T16:17:09.645Z,1690906629.645 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-08-01T16:17:09.646Z,1690906629.646 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-08-01T16:17:09.885Z,1690906629.885 [DeadReckonUsingSpeedCalculator] Loaded
2023-08-01T16:17:09.885Z,1690906629.885 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2023-08-01T16:17:09.899Z,1690906629.899 [NavChart] Loaded
2023-08-01T16:17:09.899Z,1690906629.899 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-08-01T16:17:09.905Z,1690906629.905 [UniversalFixResidualReporter] Loaded
2023-08-01T16:17:09.905Z,1690906629.905 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-08-01T16:17:09.906Z,1690906629.906 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-08-01T16:17:09.906Z,1690906629.906 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-08-01T16:17:12.820Z,1690906632.820 [AHRS_M2] Loaded
2023-08-01T16:17:12.820Z,1690906632.820 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-08-01T16:17:13.159Z,1690906633.159 [BackseatComponent] Loaded
2023-08-01T16:17:13.159Z,1690906633.159 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2023-08-01T16:17:13.169Z,1690906633.169 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A5D4E0
2023-08-01T16:17:13.169Z,1690906633.169 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1226
2023-08-01T16:17:13.172Z,1690906633.172 [LcmUniversalReporter] Loaded
2023-08-01T16:17:13.172Z,1690906633.172 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2023-08-01T16:17:14.885Z,1690906634.885 [BPC1] Loaded
2023-08-01T16:17:14.886Z,1690906634.886 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-08-01T16:17:15.214Z,1690906635.214 [DataOverHttps] Loaded
2023-08-01T16:17:15.214Z,1690906635.214 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-08-01T16:17:15.218Z,1690906635.218 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A8D4E0
2023-08-01T16:17:15.218Z,1690906635.218 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1227
2023-08-01T16:17:15.241Z,1690906635.241 [Depth_Keller] Loaded
2023-08-01T16:17:15.241Z,1690906635.241 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-08-01T16:17:15.253Z,1690906635.253 [DropWeight] Loaded
2023-08-01T16:17:15.253Z,1690906635.253 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-08-01T16:17:15.351Z,1690906635.351 [DVL_micro] Loaded
2023-08-01T16:17:15.351Z,1690906635.351 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2023-08-01T16:17:15.567Z,1690906635.567 [NAL9602] Loaded
2023-08-01T16:17:15.567Z,1690906635.567 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-08-01T16:17:15.621Z,1690906635.621 [Onboard] Loaded
2023-08-01T16:17:15.621Z,1690906635.621 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-08-01T16:17:15.639Z,1690906635.639 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40ABD4E0
2023-08-01T16:17:15.639Z,1690906635.639 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1228
2023-08-01T16:17:15.661Z,1690906635.661 [Power24vConverter] Loaded
2023-08-01T16:17:15.661Z,1690906635.661 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-08-01T16:17:15.734Z,1690906635.734 [Radio_Surface] Loaded
2023-08-01T16:17:15.734Z,1690906635.734 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-08-01T16:17:15.750Z,1690906635.750 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40AED4E0
2023-08-01T16:17:15.751Z,1690906635.751 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1229
2023-08-01T16:17:15.782Z,1690906635.782 [Sonardyne_Nano] Loaded
2023-08-01T16:17:15.783Z,1690906635.783 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2023-08-01T16:17:15.784Z,1690906635.784 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-08-01T16:17:15.784Z,1690906635.784 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-08-01T16:17:16.094Z,1690906636.094 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-08-01T16:17:16.094Z,1690906636.094 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-08-01T16:17:16.329Z,1690906636.329 [VerticalControl](DEBUG): Construct VerticalControl.
2023-08-01T16:17:16.441Z,1690906636.441 [VerticalControl] Loaded
2023-08-01T16:17:16.442Z,1690906636.442 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-08-01T16:17:16.447Z,1690906636.447 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-08-01T16:17:16.487Z,1690906636.487 [HorizontalControl] Loaded
2023-08-01T16:17:16.487Z,1690906636.487 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-08-01T16:17:16.489Z,1690906636.489 [SpeedControl](DEBUG): Construct SpeedControl.
2023-08-01T16:17:16.491Z,1690906636.491 [SpeedControl] Loaded
2023-08-01T16:17:16.491Z,1690906636.491 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-08-01T16:17:16.494Z,1690906636.494 [LoopControl](DEBUG): Construct LoopControl.
2023-08-01T16:17:16.495Z,1690906636.495 [LoopControl] Loaded
2023-08-01T16:17:16.495Z,1690906636.495 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-08-01T16:17:16.495Z,1690906636.495 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-08-01T16:17:16.496Z,1690906636.496 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-08-01T16:17:16.714Z,1690906636.714 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-08-01T16:17:16.715Z,1690906636.715 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-08-01T16:17:17.502Z,1690906637.502 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-08-01T16:17:17.512Z,1690906637.512 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-08-01T16:17:17.775Z,1690906637.775 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-08-01T16:17:17.799Z,1690906637.799 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-08-01T16:17:17.824Z,1690906637.824 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BF94E0
2023-08-01T16:17:17.824Z,1690906637.824 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1230
2023-08-01T16:17:17.886Z,1690906637.886 [Supervisor](INFO): Main Thread ID is 825
2023-08-01T16:17:17.886Z,1690906637.886 [Supervisor](DEBUG): Running supervisor.
2023-08-01T16:17:17.886Z,1690906637.886 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1231
2023-08-01T16:17:17.892Z,1690906637.892 [CommandExec](INFO): Initializing the command executive.
2023-08-01T16:17:17.894Z,1690906637.894 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1232
2023-08-01T16:17:17.901Z,1690906637.901 [controlThread ThreadHandler](INFO): Handler Thread ID is 1233
2023-08-01T16:17:17.901Z,1690906637.901 [controlThread](DEBUG): Initializing ControlThread
2023-08-01T16:17:17.902Z,1690906637.902 [InternalEnvSim](DEBUG):  InternaEnvlSim initializing...
2023-08-01T16:17:17.914Z,1690906637.914 [logger ThreadHandler](INFO): Handler Thread ID is 1234
2023-08-01T16:17:17.968Z,1690906637.968 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1235
2023-08-01T16:17:17.970Z,1690906637.970 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-08-01T16:17:17.973Z,1690906637.973 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1237
2023-08-01T16:17:17.983Z,1690906637.983 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 1239
2023-08-01T16:17:17.984Z,1690906637.984 [WetLabsBB2FL](INFO): Powering up
2023-08-01T16:17:17.990Z,1690906637.990 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1240
2023-08-01T16:17:18.000Z,1690906638.000 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1241
2023-08-01T16:17:18.006Z,1690906638.006 [Onboard ThreadHandler](INFO): Handler Thread ID is 1242
2023-08-01T16:17:18.011Z,1690906638.011 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-08-01T16:17:18.076Z,1690906638.076 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1243
2023-08-01T16:17:18.092Z,1690906638.092 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1244
2023-08-01T16:17:18.172Z,1690906638.172 [SBIT](INFO): Initialize SBIT Component.
2023-08-01T16:17:18.173Z,1690906638.173 [SBIT](IMPORTANT): git: 2023-07-31-1-gc2e44910c
2023-08-01T16:17:18.173Z,1690906638.173 [SBIT](INFO): git hash: c2e44910c6e56a8023a8b1a81178d1dbbd33b4c5
2023-08-01T16:17:18.173Z,1690906638.173 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-08-01T16:17:18.174Z,1690906638.174 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2023-08-01T16:17:18.192Z,1690906638.192 [SBIT](INFO): Beginning SBIT in 54.000000 seconds.
2023-08-01T16:17:18.193Z,1690906638.193 [IBIT](INFO): Initialize IBIT Component.
2023-08-01T16:17:18.194Z,1690906638.194 [CBIT](DEBUG): Initialize CBIT Component.
2023-08-01T16:17:18.208Z,1690906638.208 [CBIT](DEBUG): Initialized mux pins.
2023-08-01T16:17:18.208Z,1690906638.208 [CBIT](DEBUG): Initializing the watchdog timer.
2023-08-01T16:17:18.232Z,1690906638.232 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-08-01T16:17:18.262Z,1690906638.262 [CBIT](DEBUG): Initializing heartbeat.
2023-08-01T16:17:18.267Z,1690906638.267 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI01M.000
2023-08-01T16:17:18.268Z,1690906638.268 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI01M.000
2023-08-01T16:17:18.268Z,1690906638.268 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI80M.000
2023-08-01T16:17:18.268Z,1690906638.268 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI80M.000
2023-08-01T16:17:18.268Z,1690906638.268 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI57M.000
2023-08-01T16:17:18.268Z,1690906638.268 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI57M.000
2023-08-01T16:17:18.269Z,1690906638.269 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI56M.000
2023-08-01T16:17:18.269Z,1690906638.269 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI56M.000
2023-08-01T16:17:18.269Z,1690906638.269 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI90M.000
2023-08-01T16:17:18.269Z,1690906638.269 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI90M.000
2023-08-01T16:17:18.269Z,1690906638.269 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI89M.000
2023-08-01T16:17:18.269Z,1690906638.269 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI89M.000
2023-08-01T16:17:18.270Z,1690906638.270 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH01M.000
2023-08-01T16:17:18.270Z,1690906638.270 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4OH01M.000
2023-08-01T16:17:18.270Z,1690906638.270 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH04M.000
2023-08-01T16:17:18.270Z,1690906638.270 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4OH04M.000
2023-08-01T16:17:18.270Z,1690906638.270 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4OH02M.000
2023-08-01T16:17:18.270Z,1690906638.270 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4OH02M.000
2023-08-01T16:17:18.271Z,1690906638.271 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4PA20M.000
2023-08-01T16:17:18.271Z,1690906638.271 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4PA20M.000
2023-08-01T16:17:18.271Z,1690906638.271 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4PA21M.000
2023-08-01T16:17:18.271Z,1690906638.271 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4PA21M.000
2023-08-01T16:17:18.332Z,1690906638.332 [CBIT](DEBUG): Deactivating GF circuits.
2023-08-01T16:17:18.332Z,1690906638.332 [CBIT](DEBUG): Deactivating emergency mode.
2023-08-01T16:17:18.372Z,1690906638.372 [CBIT](DEBUG): Backplane powered.
2023-08-01T16:17:18.376Z,1690906638.376 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-08-01T16:17:18.377Z,1690906638.377 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-08-01T16:17:18.377Z,1690906638.377 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-08-01T16:17:18.377Z,1690906638.377 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-08-01T16:17:18.378Z,1690906638.378 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-08-01T16:17:18.378Z,1690906638.378 [ShortestDistanceToPolygonSidesCalculator](DEBUG): Initializing.
2023-08-01T16:17:18.379Z,1690906638.379 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-08-01T16:17:18.380Z,1690906638.380 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-08-01T16:17:18.380Z,1690906638.380 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-08-01T16:17:18.387Z,1690906638.387 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-08-01T16:17:18.389Z,1690906638.389 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-08-01T16:17:18.389Z,1690906638.389 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-08-01T16:17:18.390Z,1690906638.390 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-08-01T16:17:18.397Z,1690906638.397 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-08-01T16:17:18.457Z,1690906638.457 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-08-01T16:17:18.480Z,1690906638.480 [Radio_Surface](INFO): Powering up
2023-08-01T16:17:18.518Z,1690906638.518 [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-08-01T16:17:18.519Z,1690906638.519 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-08-01T16:17:18.622Z,1690906638.622 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-08-01T16:17:18.623Z,1690906638.623 [Default:A.Wait](DEBUG): Construct Wait.
2023-08-01T16:17:18.625Z,1690906638.625 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-08-01T16:17:18.709Z,1690906638.709 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-08-01T16:17:18.712Z,1690906638.712 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-08-01T16:17:18.744Z,1690906638.744 [Default:E.Execute](DEBUG): Construct Execute.
2023-08-01T16:17:18.766Z,1690906638.766 [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-08-01T16:17:18.776Z,1690906638.776 [controlThread](DEBUG): Component order: CycleStarter,InternalEnvSim,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,ShortestDistanceToPolygonSidesCalculator,DeadReckonUsingSpeedCalculator,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,LcmUniversalReporter,Reporter,LogSplitter,
2023-08-01T16:17:18.790Z,1690906638.790 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-08-01T16:17:19.173Z,1690906639.173 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.894409 decibar, 0.000000 m
2023-08-01T16:17:19.185Z,1690906639.185 [Power24vConverter](INFO): Powering up.
2023-08-01T16:17:19.188Z,1690906639.188 [Sonardyne_Nano](INFO): Initializing.
2023-08-01T16:17:19.213Z,1690906639.213 [DepthRateCalculator](ERROR): Depth measurement is not active
2023-08-01T16:17:19.351Z,1690906639.351 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-08-01T16:17:19.354Z,1690906639.354 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-08-01T16:17:19.355Z,1690906639.355 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-08-01T16:17:19.376Z,1690906639.376 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-08-01T16:17:19.377Z,1690906639.377 [MassServo](DEBUG): Initializing EZServoServo.
2023-08-01T16:17:19.388Z,1690906639.388 [MassServo](DEBUG): Initializing MassServo.
2023-08-01T16:17:19.389Z,1690906639.389 [RudderServo](DEBUG): Initializing EZServoServo.
2023-08-01T16:17:19.400Z,1690906639.400 [RudderServo](DEBUG): Initializing RudderServo.
2023-08-01T16:17:19.401Z,1690906639.401 [ThrusterHE](DEBUG): Initializing EZServoServo.
2023-08-01T16:17:19.412Z,1690906639.412 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2023-08-01T16:17:19.620Z,1690906639.620 [DVL_micro](INFO): Powering down
2023-08-01T16:17:19.622Z,1690906639.622 [Sonardyne_Nano](INFO): Starting
2023-08-01T16:17:19.838Z,1690906639.838 [Sonardyne_Nano](INFO): Starting
2023-08-01T16:17:20.084Z,1690906640.084 [WetLabsBB2FL](INFO): Powering down
2023-08-01T16:17:20.242Z,1690906640.242 [Sonardyne_Nano](INFO): Starting
2023-08-01T16:17:20.646Z,1690906640.646 [Sonardyne_Nano](INFO): Starting
2023-08-01T16:17:21.050Z,1690906641.050 [Sonardyne_Nano](INFO): Starting
2023-08-01T16:17:21.455Z,1690906641.455 [Sonardyne_Nano](INFO): Received response:>+++?

2023-08-01T16:17:21.455Z,1690906641.455 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2023-08-01T16:17:21.469Z,1690906641.469 [Sonardyne_Nano](INFO): Command indicated, sending CKHW
2023-08-01T16:17:21.858Z,1690906641.858 [Sonardyne_Nano](INFO): Command indicated, sending CKHW
2023-08-01T16:17:22.338Z,1690906642.338 [Sonardyne_Nano](INFO): Received response:>CKHW:PASS

2023-08-01T16:17:22.339Z,1690906642.339 [Sonardyne_Nano](INFO): Received self-test result
2023-08-01T16:17:22.339Z,1690906642.339 [Sonardyne_Nano](INFO): Command indicated, sending FS
2023-08-01T16:17:22.694Z,1690906642.694 [Sonardyne_Nano](INFO): Received response:>FS:2213,U006D25,FL0010,FV5.11.00.07,PV2.03,TDR;MF;OMNI;184;175;169,PR5000;SG;AC0.700,T-40;110;AC2.00,INC-90.00;+90.00;AC1.00

2023-08-01T16:17:22.694Z,1690906642.694 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2213
2023-08-01T16:17:22.694Z,1690906642.694 [Sonardyne_Nano](INFO): Read UUID: %x27941
2023-08-01T16:17:22.694Z,1690906642.694 [Sonardyne_Nano](INFO): Command indicated, sending VS
2023-08-01T16:17:22.695Z,1690906642.695 [Sonardyne_Nano](INFO): Writing last charge 0.000000 2023-08-01T16:17:22.694Z
2023-08-01T16:17:23.112Z,1690906643.112 [Sonardyne_Nano](INFO): Received response:>VS:2213,HPR45,EXT,TILT,BT1;LI_ION;VLT4.1;IDC-17.0;CAP3.4/96;T25.4

2023-08-01T16:17:23.112Z,1690906643.112 [Sonardyne_Nano](INFO): Parsing volatile status
2023-08-01T16:17:23.113Z,1690906643.113 [Sonardyne_Nano](INFO): Parsing charge percentage, response: >VS:2213,HPR45,EXT,TILT,BT1;LI_ION;VLT4.1;IDC-17.0;CAP3.4/96;T25.4

2023-08-01T16:17:23.113Z,1690906643.113 [Sonardyne_Nano](INFO): Found fwd slash
2023-08-01T16:17:23.113Z,1690906643.113 [Sonardyne_Nano](INFO): Charge percentage: 96
2023-08-01T16:17:23.114Z,1690906643.114 [Sonardyne_Nano](INFO): Writing last charge 96.000000 2023-08-01T16:17:22.694Z
2023-08-01T16:17:24.937Z,1690906644.937 [ThrusterHE](ERROR): Zero Speed Commanded.
2023-08-01T16:17:25.006Z,1690906645.006 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2023-08-01T16:17:45.698Z,1690906665.698 [NAL9602](INFO): Powering up NAL9602
2023-08-01T16:17:56.602Z,1690906676.602 [NAL9602](INFO): NAL9602 initialized
2023-08-01T16:18:12.825Z,1690906692.825 [SBIT](IMPORTANT): Beginning Startup BIT
2023-08-01T16:18:12.835Z,1690906692.835 [CBIT](IMPORTANT): Beginning ground fault scan
2023-08-01T16:18:23.790Z,1690906703.790 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005651
CHAN A1 (24V): 0.002563
CHAN A2 (12V): -0.005697
CHAN A3 (5V): -0.002717
CHAN B0 (3.3V): -0.001337
CHAN B1 (3.15aV): -0.000988
CHAN B2 (3.15bV): -0.001196
CHAN B3 (GND): 0.000170
OPEN: 0.000600
Full Scale: +/- 1 mA
2023-08-01T16:19:06.558Z,1690906746.558 [SBIT](IMPORTANT): SBIT PASSED
2023-08-01T16:19:06.558Z,1690906746.558 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-08-01T16:19:06.562Z,1690906746.562 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2023-08-01T16:19:06.562Z,1690906746.562 [SBIT](IMPORTANT): DVL_micro.loadAtStartup=1 bool;

2023-08-01T16:19:06.562Z,1690906746.562 [SBIT](IMPORTANT): DeadReckonUsingMultipleVelocitySources.loadAtStartup=0 bool;

2023-08-01T16:19:06.562Z,1690906746.562 [SBIT](IMPORTANT): DeadReckonUsingSpeedCalculator.loadAtStartup=1 bool;

2023-08-01T16:19:06.562Z,1690906746.562 [SBIT](IMPORTANT): Depth_Keller.offset=-0.35 decibar;

2023-08-01T16:19:06.562Z,1690906746.562 [SBIT](IMPORTANT): DropWeight.loadAtStartup=1 bool;

2023-08-01T16:19:06.562Z,1690906746.562 [SBIT](IMPORTANT): ESPComponent.sampleTimeout=3 minute;

2023-08-01T16:19:06.563Z,1690906746.563 [SBIT](IMPORTANT): ESPComponent.simulateHardware=0 bool;

2023-08-01T16:19:06.563Z,1690906746.563 [SBIT](IMPORTANT): ElevatorServo.offsetAngle=1 degree;

2023-08-01T16:19:06.563Z,1690906746.563 [SBIT](IMPORTANT): Express linearApproximation WetLabsSeaOWL_UV_A.mass_concentration_of_petroleum_hydrocarbons_in_sea_water 1.000000 kilogram_per_cubic_meter;

2023-08-01T16:19:06.563Z,1690906746.563 [SBIT](IMPORTANT): ShortestDistanceToPolygonSidesCalculator.loadAtStartup=1 bool;

2023-08-01T16:19:06.563Z,1690906746.563 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=1 bool;

2023-08-01T16:19:06.563Z,1690906746.563 [SBIT](IMPORTANT): StratificationFrontDetector.loadAtStartup=0 bool;

2023-08-01T16:19:06.563Z,1690906746.563 [SBIT](IMPORTANT): StratificationFrontDetector.threshold_VTHI=0.05 celsius;

2023-08-01T16:19:06.563Z,1690906746.563 [SBIT](IMPORTANT): StratificationFrontDetector.verbosity=1 count;

2023-08-01T16:19:06.568Z,1690906746.568 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=234.769539 cubic_centimeter;

2023-08-01T16:19:06.568Z,1690906746.568 [SBIT](IMPORTANT): VerticalControl.massDefault=8.539099 millimeter;

2023-08-01T16:19:06.568Z,1690906746.568 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth3=20 meter;

2023-08-01T16:19:06.569Z,1690906746.569 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.depth4=30 meter;

2023-08-01T16:19:06.569Z,1690906746.569 [SBIT](IMPORTANT): VerticalHomogeneityIndexCalculator.loadAtStartup=0 bool;

2023-08-01T16:19:06.937Z,1690906746.937 [MissionManager](IMPORTANT): Started mission Startup
2023-08-01T16:19:06.941Z,1690906746.941 [Startup] Running Loop=1
2023-08-01T16:19:06.941Z,1690906746.941 [Startup](DEBUG): Aggregate::initialize Startup
2023-08-01T16:19:06.941Z,1690906746.941 [Startup:A.GoToSurface] Running Loop=1
2023-08-01T16:19:06.942Z,1690906746.942 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-08-01T16:19:06.942Z,1690906746.942 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-08-01T16:19:06.943Z,1690906746.943 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-08-01T16:19:06.943Z,1690906746.943 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-08-01T16:19:06.943Z,1690906746.943 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-08-01T16:19:06.944Z,1690906746.944 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-08-01T16:19:06.945Z,1690906746.945 [Startup:StartupSatComms] Running Loop=1
2023-08-01T16:19:06.946Z,1690906746.946 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-08-01T16:19:06.946Z,1690906746.946 [Startup:StartupSatComms:A] Running Loop=1
2023-08-01T16:19:07.340Z,1690906747.340 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-08-01T16:19:48.113Z,1690906788.113 [NAL9602](INFO): SBD MO Status=2, MOMSN=7065, MT Status=2, MTMSN=0
2023-08-01T16:19:48.113Z,1690906788.113 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-08-01T16:20:07.104Z,1690906807.104 [Startup:StartupSatComms:A](INFO): Timed out from 2023-08-01T16:19:06.9Z
2023-08-01T16:20:07.104Z,1690906807.104 [Startup:StartupSatComms:A] Stopped
2023-08-01T16:20:07.104Z,1690906807.104 [Startup:StartupSatComms:B] Running Loop=1
2023-08-01T16:20:07.546Z,1690906807.546 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-08-01T16:20:18.418Z,1690906818.418 [DeadReckonUsingSpeedCalculator] Software Fault, FailCount= 1
2023-08-01T16:20:18.418Z,1690906818.418 [DeadReckonUsingSpeedCalculator](ERROR): Software Fault
2023-08-01T16:20:18.428Z,1690906818.428 [CBIT](ERROR): Software Fault in component: DeadReckonUsingSpeedCalculator
2023-08-01T16:20:18.832Z,1690906818.832 [CBIT](INFO): Clearing failed state for component DeadReckonUsingSpeedCalculator
2023-08-01T16:20:18.832Z,1690906818.832 [DeadReckonUsingSpeedCalculator] No Fault, FailCount= 1
2023-08-01T16:20:19.235Z,1690906819.235 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2023-08-01T16:20:26.183Z,1690906826.183 [NAL9602](INFO): SBD MO Status=0, MOMSN=7065, MT Status=0, MTMSN=0
2023-08-01T16:20:26.183Z,1690906826.183 [NAL9602](INFO): No messages in MT queue
2023-08-01T16:20:53.158Z,1690906853.158 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004513
2023-08-01T16:21:00.867Z,1690906860.867 [NAL9602](INFO): SBD MO Status=2, MOMSN=7066, MT Status=2, MTMSN=0
2023-08-01T16:21:00.867Z,1690906860.867 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-08-01T16:21:03.176Z,1690906863.176 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20230801T154709/Courier0016.lzma
2023-08-01T16:21:04.178Z,1690906864.178 [DataOverHttps](INFO): Moved sent file to Logs/20230801T154709/Courier0016.lzma.bak
2023-08-01T16:21:04.178Z,1690906864.178 [DataOverHttps](INFO): SBD MOMSN=18505079
2023-08-01T16:21:07.399Z,1690906867.399 [Startup:StartupSatComms:B](INFO): Timed out from 2023-08-01T16:20:07.1Z
2023-08-01T16:21:07.399Z,1690906867.399 [Startup:StartupSatComms:B] Stopped
2023-08-01T16:21:07.399Z,1690906867.399 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-08-01T16:21:07.399Z,1690906867.399 [Startup:StartupSatComms] Stopped
2023-08-01T16:21:07.399Z,1690906867.399 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-08-01T16:21:07.408Z,1690906867.408 [Startup](INFO): Completed Startup
2023-08-01T16:21:07.409Z,1690906867.409 [MissionManager](INFO): Startup is completed.
2023-08-01T16:21:07.409Z,1690906867.409 [MissionManager](INFO): Uninitializing Mission Startup
2023-08-01T16:21:07.409Z,1690906867.409 [Startup] Stopped
2023-08-01T16:21:07.409Z,1690906867.409 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-08-01T16:21:07.409Z,1690906867.409 [Startup:A.GoToSurface] Stopped
2023-08-01T16:21:07.409Z,1690906867.409 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-08-01T16:21:07.830Z,1690906867.830 [MissionManager](IMPORTANT): Started mission Default
2023-08-01T16:21:07.831Z,1690906867.831 [Default] Running Loop=1
2023-08-01T16:21:07.831Z,1690906867.831 [Default](DEBUG): Aggregate::initialize Default
2023-08-01T16:21:07.831Z,1690906867.831 [Default:B.GoToSurface] Running Loop=1
2023-08-01T16:21:07.831Z,1690906867.831 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-08-01T16:21:07.831Z,1690906867.831 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-08-01T16:21:07.840Z,1690906867.840 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-08-01T16:21:07.841Z,1690906867.841 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-08-01T16:21:07.841Z,1690906867.841 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-08-01T16:21:07.841Z,1690906867.841 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-08-01T16:21:07.842Z,1690906867.842 [Default:A.Wait] Running Loop=1
2023-08-01T16:21:07.842Z,1690906867.842 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-08-01T16:21:08.236Z,1690906868.236 [DVL_micro](INFO): Powering down
2023-08-01T16:21:21.146Z,1690906881.146 [Default:A.Wait](INFO): Done Waiting.
2023-08-01T16:21:21.146Z,1690906881.146 [Default:A.Wait] Stopped
2023-08-01T16:21:21.146Z,1690906881.146 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-08-01T16:21:21.550Z,1690906881.550 [Default:CheckIn] Running Loop=1
2023-08-01T16:21:21.550Z,1690906881.550 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-01T16:21:21.551Z,1690906881.551 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-01T16:21:21.965Z,1690906881.965 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-08-01T16:21:23.107Z,1690906883.107 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20230801T161649/Courier0000.lzma
2023-08-01T16:21:24.109Z,1690906884.109 [DataOverHttps](INFO): Moved sent file to Logs/20230801T161649/Courier0000.lzma.bak
2023-08-01T16:21:24.110Z,1690906884.110 [DataOverHttps](INFO): SBD MOMSN=18505081
2023-08-01T16:21:41.112Z,1690906901.112 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.chargePercent
2023-08-01T16:21:41.113Z,1690906901.113 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent 96.000000 %
2023-08-01T16:22:21.756Z,1690906941.756 [DVL_micro](ERROR): only read 3 of 4 data items
2023-08-01T16:22:21.756Z,1690906941.756 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999.999
2023-08-01T16:22:29.008Z,1690906949.008 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162208.00,A,4313.59625,N,08620.40326,W,1.691,0.00,010823,,,A*73
2023-08-01T16:22:29.011Z,1690906949.011 [NAL9602](INFO): GPS fix at 20230801T162208: (43.226604, -86.340054)
2023-08-01T16:22:29.022Z,1690906949.022 [Default:CheckIn:Read_GPS] Stopped
2023-08-01T16:22:29.022Z,1690906949.022 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-01T16:22:29.597Z,1690906949.597 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-08-01T16:22:36.536Z,1690906956.536 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20230801T161649/Courier0004.lzma
2023-08-01T16:22:37.538Z,1690906957.538 [DataOverHttps](INFO): Moved sent file to Logs/20230801T161649/Courier0004.lzma.bak
2023-08-01T16:22:37.538Z,1690906957.538 [DataOverHttps](INFO): SBD MOMSN=18505084
2023-08-01T16:22:55.885Z,1690906975.885 [DataOverHttps](INFO): Sending 113 bytes from file Logs/20230801T154709/Express0017.lzma
2023-08-01T16:22:56.886Z,1690906976.886 [DataOverHttps](INFO): Moved sent file to Logs/20230801T154709/Express0017.lzma.bak
2023-08-01T16:22:56.886Z,1690906976.886 [DataOverHttps](INFO): SBD MOMSN=18505088
2023-08-01T16:23:01.457Z,1690906981.457 [NAL9602](INFO): Not Powering down - fast GPS
2023-08-01T16:23:18.513Z,1690906998.513 [DataOverHttps](INFO): Sending 1114 bytes from file Logs/20230801T161649/Express0001.lzma
2023-08-01T16:23:19.514Z,1690906999.514 [DataOverHttps](INFO): Moved sent file to Logs/20230801T161649/Express0001.lzma.bak
2023-08-01T16:23:19.514Z,1690906999.514 [DataOverHttps](INFO): SBD MOMSN=18505091
2023-08-01T16:23:35.684Z,1690907015.684 [DataOverHttps](INFO): Sending 296 bytes from file Logs/20230801T161649/Express0005.lzma
2023-08-01T16:23:36.692Z,1690907016.692 [DataOverHttps](INFO): Moved sent file to Logs/20230801T161649/Express0005.lzma.bak
2023-08-01T16:23:36.693Z,1690907016.693 [DataOverHttps](INFO): SBD MOMSN=18505123
2023-08-01T16:23:38.637Z,1690907018.637 [Default:CheckIn:Read_Iridium] Stopped
2023-08-01T16:23:38.637Z,1690907018.637 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-01T16:23:38.637Z,1690907018.637 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-01T16:24:58.609Z,1690907098.609 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2023-08-01T16:24:58.621Z,1690907098.621 [BPC1](INFO): Received data from all battery sticks.
2023-08-01T16:26:27.085Z,1690907187.085 [DVL_micro](ERROR): only read 2 of 4 data items
2023-08-01T16:26:27.085Z,1690907187.085 [DVL_micro](ERROR): Failed to parse:
:BI,+00169,+00089+00000,I
2023-08-01T16:27:19.609Z,1690907239.609 [DVL_micro](ERROR): only read 3 of 4 data items
2023-08-01T16:27:19.609Z,1690907239.609 [DVL_micro](ERROR): Failed to parse:
:RD,+99999.99,+9999.99,+9999.99
2023-08-01T16:27:22.838Z,1690907242.838 [Sonardyne_Nano](INFO): Command indicated, sending VS
2023-08-01T16:27:23.249Z,1690907243.249 [Sonardyne_Nano](INFO): Received response:>VS:2213,HPR45,EXT,TILT,BT1;LI_ION;VLT4.1;IDC-17.0;CAP3.4/96;T25.6

2023-08-01T16:27:23.249Z,1690907243.249 [Sonardyne_Nano](INFO): Parsing volatile status
2023-08-01T16:27:23.249Z,1690907243.249 [Sonardyne_Nano](INFO): Parsing charge percentage, response: >VS:2213,HPR45,EXT,TILT,BT1;LI_ION;VLT4.1;IDC-17.0;CAP3.4/96;T25.6

2023-08-01T16:27:23.249Z,1690907243.249 [Sonardyne_Nano](INFO): Found fwd slash
2023-08-01T16:27:23.250Z,1690907243.250 [Sonardyne_Nano](INFO): Charge percentage: 96
2023-08-01T16:27:23.250Z,1690907243.250 [Sonardyne_Nano](INFO): Writing last charge 96.000000 2023-08-01T16:27:22.838Z
2023-08-01T16:28:39.197Z,1690907319.197 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-08-01T16:28:39.197Z,1690907319.197 [Default:CheckIn:C.Wait] Stopped
2023-08-01T16:28:39.197Z,1690907319.197 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-01T16:28:39.197Z,1690907319.197 [Default:CheckIn:D] Running Loop=1
2023-08-01T16:28:39.611Z,1690907319.611 [Default:CheckIn:D] Stopped
2023-08-01T16:28:39.611Z,1690907319.611 [Default:CheckIn:E] Running Loop=1
2023-08-01T16:28:40.008Z,1690907320.008 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 7.529670 min 
2023-08-01T16:28:40.008Z,1690907320.008 [Default:CheckIn:E] Stopped
2023-08-01T16:28:40.008Z,1690907320.008 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-08-01T16:28:40.008Z,1690907320.008 [Default:CheckIn] Stopped
2023-08-01T16:28:40.008Z,1690907320.008 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-01T16:28:40.008Z,1690907320.008 [Default:CheckIn](INFO): Running loop #2
2023-08-01T16:28:40.009Z,1690907320.009 [Default:CheckIn] Running Loop=2
2023-08-01T16:28:40.009Z,1690907320.009 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-08-01T16:28:40.009Z,1690907320.009 [Default:CheckIn:Read_GPS] Running Loop=1
2023-08-01T16:28:42.033Z,1690907322.033 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162821.00,A,4313.61087,N,08620.34499,W,0.194,246.40,010823,,,A*70
2023-08-01T16:28:42.035Z,1690907322.035 [NAL9602](INFO): GPS fix at 20230801T162821: (43.226848, -86.339083)
2023-08-01T16:28:42.046Z,1690907322.046 [Default:CheckIn:Read_GPS] Stopped
2023-08-01T16:28:42.046Z,1690907322.046 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-08-01T16:28:49.767Z,1690907329.767 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20230801T161649/Courier0007.lzma
2023-08-01T16:28:50.770Z,1690907330.770 [DataOverHttps](INFO): Moved sent file to Logs/20230801T161649/Courier0007.lzma.bak
2023-08-01T16:28:50.770Z,1690907330.770 [DataOverHttps](INFO): SBD MOMSN=18505128
2023-08-01T16:28:59.897Z,1690907339.897 [NAL9602](INFO): SBD MO Status=2, MOMSN=7066, MT Status=2, MTMSN=0
2023-08-01T16:28:59.898Z,1690907339.898 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-08-01T16:29:11.976Z,1690907351.976 [DataOverHttps](INFO): Sending 175 bytes from file Logs/20230801T161649/Express0008.lzma
2023-08-01T16:29:12.977Z,1690907352.977 [DataOverHttps](INFO): Moved sent file to Logs/20230801T161649/Express0008.lzma.bak
2023-08-01T16:29:12.978Z,1690907352.978 [DataOverHttps](INFO): SBD MOMSN=18505131
2023-08-01T16:29:15.235Z,1690907355.235 [Default:CheckIn:Read_Iridium] Stopped
2023-08-01T16:29:15.240Z,1690907355.240 [Default:CheckIn:C.Wait] Running Loop=1
2023-08-01T16:29:15.240Z,1690907355.240 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-08-01T16:30:05.367Z,1690907405.367 [NAL9602](INFO): SBD MO Status=2, MOMSN=7066, MT Status=2, MTMSN=0
2023-08-01T16:30:05.367Z,1690907405.367 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-08-01T16:30:36.873Z,1690907436.873 [NAL9602](INFO): SBD MO Status=2, MOMSN=7066, MT Status=2, MTMSN=0
2023-08-01T16:30:36.873Z,1690907436.873 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-08-01T16:30:49.411Z,1690907449.411 [NAL9602](INFO): SBD MO Status=2, MOMSN=7066, MT Status=2, MTMSN=0
2023-08-01T16:30:49.411Z,1690907449.411 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-08-01T16:31:04.521Z,1690907464.521 [CommandExec](IMPORTANT): got command restart application
2023-08-01T16:31:05.528Z,1690907465.528 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2023-08-01T16:31:05.528Z,1690907465.528 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-01T16:31:05.550Z,1690907465.550 [CommandExec](INFO): Uninitializing the command executive.
2023-08-01T16:31:05.550Z,1690907465.550 [CommandExec](INFO): Uninitializing the command scheduler.
2023-08-01T16:31:05.555Z,1690907465.555 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-08-01T16:31:05.640Z,1690907465.640 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-08-01T16:31:05.640Z,1690907465.640 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-08-01T16:31:05.640Z,1690907465.640 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-08-01T16:31:05.641Z,1690907465.641 [NavChartDb](INFO): Join timeout helper Thread ID is 1330
2023-08-01T16:31:05.768Z,1690907465.768 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-01T16:31:05.769Z,1690907465.769 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-08-01T16:31:05.788Z,1690907465.788 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-08-01T16:31:05.788Z,1690907465.788 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-08-01T16:31:05.788Z,1690907465.788 [Radio_Surface](INFO): Join timeout helper Thread ID is 1332
2023-08-01T16:31:05.864Z,1690907465.864 [Radio_Surface](INFO): Powering down
2023-08-01T16:31:05.865Z,1690907465.865 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-01T16:31:05.865Z,1690907465.865 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-08-01T16:31:05.880Z,1690907465.880 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-08-01T16:31:05.880Z,1690907465.880 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-08-01T16:31:05.880Z,1690907465.880 [Onboard](INFO): Join timeout helper Thread ID is 1333
2023-08-01T16:31:06.536Z,1690907466.536 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2023-08-01T16:31:08.696Z,1690907468.696 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-01T16:31:08.696Z,1690907468.696 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-08-01T16:31:08.700Z,1690907468.700 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-08-01T16:31:08.700Z,1690907468.700 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-08-01T16:31:08.700Z,1690907468.700 [DataOverHttps](INFO): Join timeout helper Thread ID is 1334
2023-08-01T16:31:09.548Z,1690907469.548 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-01T16:31:09.548Z,1690907469.548 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-08-01T16:31:09.552Z,1690907469.552 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2023-08-01T16:31:09.552Z,1690907469.552 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-08-01T16:31:09.552Z,1690907469.552 [BackseatComponent](INFO): Join timeout helper Thread ID is 1335
2023-08-01T16:31:09.744Z,1690907469.744 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-01T16:31:09.744Z,1690907469.744 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-08-01T16:31:09.753Z,1690907469.753 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2023-08-01T16:31:09.753Z,1690907469.753 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2023-08-01T16:31:09.753Z,1690907469.753 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1336
2023-08-01T16:31:09.788Z,1690907469.788 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-01T16:31:09.788Z,1690907469.788 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2023-08-01T16:31:09.798Z,1690907469.798 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-08-01T16:31:09.798Z,1690907469.798 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-08-01T16:31:09.798Z,1690907469.798 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1337
2023-08-01T16:31:10.088Z,1690907470.088 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-01T16:31:10.088Z,1690907470.088 [WetLabsBB2FL](INFO): Powering down
2023-08-01T16:31:10.089Z,1690907470.089 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-08-01T16:31:10.101Z,1690907470.101 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-08-01T16:31:10.101Z,1690907470.101 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-08-01T16:31:10.101Z,1690907470.101 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1338
2023-08-01T16:31:10.640Z,1690907470.640 [CTD_Seabird](INFO): Powering down
2023-08-01T16:31:10.652Z,1690907470.652 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-01T16:31:10.652Z,1690907470.652 [CTD_Seabird](INFO): Powering down
2023-08-01T16:31:10.664Z,1690907470.664 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-08-01T16:31:10.685Z,1690907470.685 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-08-01T16:31:10.685Z,1690907470.685 [logger ThreadHandler](INFO): Thread cancelled.
2023-08-01T16:31:10.686Z,1690907470.686 [logger](INFO): Join timeout helper Thread ID is 1339
2023-08-01T16:31:10.721Z,1690907470.721 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-01T16:31:10.722Z,1690907470.722 [logger ThreadHandler](INFO): Thread cancelled.
2023-08-01T16:31:10.740Z,1690907470.740 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-08-01T16:31:10.741Z,1690907470.741 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-08-01T16:31:10.741Z,1690907470.741 [CommandLine](INFO): Join timeout helper Thread ID is 1340
2023-08-01T16:31:10.820Z,1690907470.820 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-01T16:31:10.820Z,1690907470.820 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-08-01T16:31:10.840Z,1690907470.840 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-08-01T16:31:10.841Z,1690907470.841 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-08-01T16:31:10.841Z,1690907470.841 [CommandExec](INFO): Join timeout helper Thread ID is 1341
2023-08-01T16:31:10.842Z,1690907470.842 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-08-01T16:31:10.842Z,1690907470.842 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-08-01T16:31:10.843Z,1690907470.843 [controlThread](INFO): Join timeout helper Thread ID is 1342
2023-08-01T16:31:10.852Z,1690907470.852 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-08-01T16:31:10.852Z,1690907470.852 [controlThread](DEBUG): Uninitializing ControlThread
2023-08-01T16:31:10.855Z,1690907470.855 [AHRS_M2](INFO): Powering down
2023-08-01T16:31:10.000Z,1690907471.000 [DVL_micro](INFO): Powering down
2023-08-01T16:31:11.001Z,1690907471.001 [NAL9602](INFO): Powering down
2023-08-01T16:31:11.002Z,1690907471.002 [Sonardyne_Nano](INFO): Uninitializing.
2023-08-01T16:31:11.002Z,1690907471.002 [Sonardyne_Nano](INFO): Powering down
2023-08-01T16:31:11.120Z,1690907471.120 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-08-01T16:31:11.121Z,1690907471.121 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-08-01T16:31:11.122Z,1690907471.122 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-08-01T16:31:11.122Z,1690907471.122 [MissionManager](INFO): Uninitializing Mission Default
2023-08-01T16:31:11.122Z,1690907471.122 [Default] Stopped
2023-08-01T16:31:11.123Z,1690907471.123 [Default](DEBUG): Aggregate::uninitialize Default
2023-08-01T16:31:11.123Z,1690907471.123 [Default:B.GoToSurface] Stopped
2023-08-01T16:31:11.123Z,1690907471.123 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-08-01T16:31:11.123Z,1690907471.123 [Default:CheckIn] Stopped
2023-08-01T16:31:11.123Z,1690907471.123 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-08-01T16:31:11.123Z,1690907471.123 [Default:CheckIn:C.Wait] Stopped
2023-08-01T16:31:11.123Z,1690907471.123 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-08-01T16:31:11.126Z,1690907471.126 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-08-01T16:31:11.126Z,1690907471.126 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-08-01T16:31:11.126Z,1690907471.126 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-08-01T16:31:11.127Z,1690907471.127 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-08-01T16:31:11.127Z,1690907471.127 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-08-01T16:31:11.127Z,1690907471.127 [BuoyancyServo](INFO): Powering down
2023-08-01T16:31:11.140Z,1690907471.140 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-08-01T16:31:11.140Z,1690907471.140 [ElevatorServo](INFO): Powering down
2023-08-01T16:31:11.141Z,1690907471.141 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-08-01T16:31:11.141Z,1690907471.141 [MassServo](INFO): Powering down
2023-08-01T16:31:11.142Z,1690907471.142 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-08-01T16:31:11.142Z,1690907471.142 [RudderServo](INFO): Powering down
2023-08-01T16:31:11.143Z,1690907471.143 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2023-08-01T16:31:11.143Z,1690907471.143 [ThrusterHE](INFO): Powering down
2023-08-01T16:31:11.144Z,1690907471.144 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-08-01T16:31:11.144Z,1690907471.144 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-08-01T16:31:11.144Z,1690907471.144 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-08-01T16:31:11.145Z,1690907471.145 [CBIT](DEBUG): Powering off loads.
2023-08-01T16:31:11.156Z,1690907471.156 [CBIT](DEBUG): Disabling WDT.
2023-08-01T16:31:11.168Z,1690907471.168 [CBIT](DEBUG): Opening all GF detection circuits.
2023-08-01T16:31:11.169Z,1690907471.169 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-08-01T16:31:11.211Z,1690907471.211 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2023-08-01T16:31:11.215Z,1690907471.215 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-08-01T16:31:11.220Z,1690907471.220 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-08-01T16:31:11.250Z,1690907471.250 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-08-01T16:31:11.252Z,1690907471.252 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-08-01T16:31:11.260Z,1690907471.260 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-08-01T16:31:11.303Z,1690907471.303 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2023-08-01T16:31:11.366Z,1690907471.366 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-08-01T16:31:11.429Z,1690907471.429 [logger ThreadHandler](INFO): Thread cancelled.