2025-10-01T20:32:33.846Z,1759350753.846 [Supervisor](DEBUG): Initializing supervisor.
2025-10-01T20:32:33.851Z,1759350753.851 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-10-01T20:32:33.851Z,1759350753.851 [SyncHandler](INFO): Protected caller Thread ID is 839
2025-10-01T20:32:33.852Z,1759350753.852 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-10-01T20:32:33.853Z,1759350753.853 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-10-01T20:32:33.853Z,1759350753.853 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 840
2025-10-01T20:32:33.857Z,1759350753.857 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-10-01T20:32:33.877Z,1759350753.877 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-10-01T20:32:33.878Z,1759350753.878 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-10-01T20:32:33.878Z,1759350753.878 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 841
2025-10-01T20:32:33.883Z,1759350753.883 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-10-01T20:32:33.884Z,1759350753.884 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-10-01T20:32:33.884Z,1759350753.884 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 842
2025-10-01T20:32:33.886Z,1759350753.886 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-10-01T20:32:33.887Z,1759350753.887 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-10-01T20:32:33.888Z,1759350753.888 [logger ThreadHandler](INFO): Protected caller Thread ID is 843
2025-10-01T20:32:33.892Z,1759350753.892 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-10-01T20:32:33.892Z,1759350753.892 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-10-01T20:32:33.896Z,1759350753.896 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-10-01T20:32:34.005Z,1759350754.005 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-10-01T20:32:34.007Z,1759350754.007 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-10-01T20:32:34.814Z,1759350754.814 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-10-01T20:32:34.816Z,1759350754.816 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-10-01T20:32:35.078Z,1759350755.078 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-10-01T20:32:35.080Z,1759350755.080 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-10-01T20:32:35.171Z,1759350755.171 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-10-01T20:32:35.321Z,1759350755.321 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-10-01T20:32:35.323Z,1759350755.323 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-10-01T20:32:35.428Z,1759350755.428 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance
2025-10-01T20:32:35.428Z,1759350755.428 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-10-01T20:32:35.551Z,1759350755.551 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-10-01T20:32:35.552Z,1759350755.552 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-10-01T20:32:35.999Z,1759350755.999 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-10-01T20:32:36.001Z,1759350756.001 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-10-01T20:32:36.606Z,1759350756.606 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-10-01T20:32:36.608Z,1759350756.608 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-10-01T20:32:36.850Z,1759350756.850 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-10-01T20:32:36.851Z,1759350756.851 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-10-01T20:32:37.141Z,1759350757.141 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-10-01T20:32:37.678Z,1759350757.678 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-10-01T20:32:38.481Z,1759350758.481 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-10-01T20:32:38.483Z,1759350758.483 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-10-01T20:32:38.802Z,1759350758.802 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-10-01T20:32:38.804Z,1759350758.804 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-10-01T20:32:39.066Z,1759350759.066 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-10-01T20:32:39.067Z,1759350759.067 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-10-01T20:32:39.489Z,1759350759.489 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-10-01T20:32:39.491Z,1759350759.491 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-10-01T20:32:39.876Z,1759350759.876 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-10-01T20:32:39.878Z,1759350759.878 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2025-10-01T20:32:39.882Z,1759350759.882 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2025-10-01T20:32:39.979Z,1759350759.979 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2025-10-01T20:32:40.170Z,1759350760.170 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Dock.cfg
2025-10-01T20:32:40.291Z,1759350760.291 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/workSite.cfg
2025-10-01T20:32:40.401Z,1759350760.401 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2025-10-01T20:32:40.521Z,1759350760.521 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2025-10-01T20:32:40.619Z,1759350760.619 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2025-10-01T20:32:40.727Z,1759350760.727 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2025-10-01T20:32:40.854Z,1759350760.854 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2025-10-01T20:32:41.146Z,1759350761.146 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-10-01T20:32:41.147Z,1759350761.147 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2025-10-01T20:32:41.243Z,1759350761.243 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2025-10-01T20:32:41.349Z,1759350761.349 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2025-10-01T20:32:41.506Z,1759350761.506 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2025-10-01T20:32:41.615Z,1759350761.615 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-10-01T20:32:41.641Z,1759350761.641 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-10-01T20:32:41.832Z,1759350761.832 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-10-01T20:32:41.833Z,1759350761.833 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-10-01T20:32:41.912Z,1759350761.912 [VerticalControl](DEBUG): Construct VerticalControl.
2025-10-01T20:32:41.976Z,1759350761.976 [VerticalControl] Loaded
2025-10-01T20:32:41.977Z,1759350761.977 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-10-01T20:32:41.980Z,1759350761.980 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-10-01T20:32:42.030Z,1759350762.030 [HorizontalControl] Loaded
2025-10-01T20:32:42.030Z,1759350762.030 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-10-01T20:32:42.033Z,1759350762.033 [SpeedControl](DEBUG): Construct SpeedControl.
2025-10-01T20:32:42.036Z,1759350762.036 [SpeedControl] Loaded
2025-10-01T20:32:42.036Z,1759350762.036 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-10-01T20:32:42.039Z,1759350762.039 [LoopControl](DEBUG): Construct LoopControl.
2025-10-01T20:32:42.040Z,1759350762.040 [LoopControl] Loaded
2025-10-01T20:32:42.040Z,1759350762.040 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-10-01T20:32:42.040Z,1759350762.040 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-10-01T20:32:42.041Z,1759350762.041 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-10-01T20:32:42.117Z,1759350762.117 [DepthRateCalculator] Loaded
2025-10-01T20:32:42.118Z,1759350762.118 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-10-01T20:32:42.123Z,1759350762.123 [PitchRateCalculator] Loaded
2025-10-01T20:32:42.123Z,1759350762.123 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-10-01T20:32:42.134Z,1759350762.134 [SpeedCalculator] Loaded
2025-10-01T20:32:42.134Z,1759350762.134 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-10-01T20:32:42.139Z,1759350762.139 [YawRateCalculator] Loaded
2025-10-01T20:32:42.139Z,1759350762.139 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-10-01T20:32:42.158Z,1759350762.158 [ElevatorOffsetCalculator] Loaded
2025-10-01T20:32:42.158Z,1759350762.158 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-10-01T20:32:42.159Z,1759350762.159 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-10-01T20:32:42.161Z,1759350762.161 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-10-01T20:32:42.254Z,1759350762.254 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-10-01T20:32:42.255Z,1759350762.255 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-10-01T20:32:43.129Z,1759350763.129 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-10-01T20:32:43.130Z,1759350763.130 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-10-01T20:32:43.590Z,1759350763.590 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-10-01T20:32:43.592Z,1759350763.592 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-10-01T20:32:43.833Z,1759350763.833 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-10-01T20:32:43.834Z,1759350763.834 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-10-01T20:32:44.739Z,1759350764.739 [AHRS_M2] Loaded
2025-10-01T20:32:44.739Z,1759350764.739 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-10-01T20:32:44.994Z,1759350764.994 [BackseatComponent] Loaded
2025-10-01T20:32:44.995Z,1759350764.995 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-10-01T20:32:44.996Z,1759350764.996 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409EA4E0
2025-10-01T20:32:44.996Z,1759350764.996 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 932
2025-10-01T20:32:44.999Z,1759350764.999 [LcmUniversalReporter] Loaded
2025-10-01T20:32:44.000Z,1759350765.000 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-10-01T20:32:45.880Z,1759350765.880 [BPC1] Loaded
2025-10-01T20:32:45.880Z,1759350765.880 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-10-01T20:32:45.984Z,1759350765.984 [DAT] Loaded
2025-10-01T20:32:45.984Z,1759350765.984 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-10-01T20:32:45.985Z,1759350765.985 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A1A4E0
2025-10-01T20:32:45.985Z,1759350765.985 [DAT ThreadHandler](INFO): Protected caller Thread ID is 933
2025-10-01T20:32:46.127Z,1759350766.127 [DataOverHttps] Loaded
2025-10-01T20:32:46.128Z,1759350766.128 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-10-01T20:32:46.129Z,1759350766.129 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A4A4E0
2025-10-01T20:32:46.129Z,1759350766.129 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 934
2025-10-01T20:32:46.154Z,1759350766.154 [Depth_Keller] Loaded
2025-10-01T20:32:46.154Z,1759350766.154 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-10-01T20:32:46.223Z,1759350766.223 [NAL9602] Loaded
2025-10-01T20:32:46.223Z,1759350766.223 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-10-01T20:32:46.255Z,1759350766.255 [Onboard] Loaded
2025-10-01T20:32:46.255Z,1759350766.255 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-10-01T20:32:46.256Z,1759350766.256 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A7A4E0
2025-10-01T20:32:46.257Z,1759350766.257 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 935
2025-10-01T20:32:46.272Z,1759350766.272 [Power24vConverter] Loaded
2025-10-01T20:32:46.272Z,1759350766.272 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-10-01T20:32:46.289Z,1759350766.289 [Radio_Surface] Loaded
2025-10-01T20:32:46.289Z,1759350766.289 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-10-01T20:32:46.290Z,1759350766.290 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40AAA4E0
2025-10-01T20:32:46.291Z,1759350766.291 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 936
2025-10-01T20:32:46.307Z,1759350766.307 [Sonardyne_Nano] Loaded
2025-10-01T20:32:46.308Z,1759350766.308 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-10-01T20:32:46.308Z,1759350766.308 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-10-01T20:32:46.309Z,1759350766.309 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-10-01T20:32:46.458Z,1759350766.458 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-10-01T20:32:46.459Z,1759350766.459 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-10-01T20:32:46.473Z,1759350766.473 [NavChart] Loaded
2025-10-01T20:32:46.474Z,1759350766.474 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-10-01T20:32:46.480Z,1759350766.480 [UniversalFixResidualReporter] Loaded
2025-10-01T20:32:46.480Z,1759350766.480 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-10-01T20:32:46.480Z,1759350766.480 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-10-01T20:32:46.481Z,1759350766.481 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-10-01T20:32:46.705Z,1759350766.705 [SBIT](DEBUG): Construct Startup Built In Test.
2025-10-01T20:32:46.714Z,1759350766.714 [SBIT] Loaded
2025-10-01T20:32:46.714Z,1759350766.714 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-10-01T20:32:46.717Z,1759350766.717 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-10-01T20:32:46.731Z,1759350766.731 [IBIT] Loaded
2025-10-01T20:32:46.731Z,1759350766.731 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-10-01T20:32:46.737Z,1759350766.737 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-10-01T20:32:46.823Z,1759350766.823 [CBIT] Loaded
2025-10-01T20:32:46.823Z,1759350766.823 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-10-01T20:32:46.858Z,1759350766.858 [GFScanner] Loaded
2025-10-01T20:32:46.858Z,1759350766.858 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-10-01T20:32:46.859Z,1759350766.859 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-10-01T20:32:46.859Z,1759350766.859 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-10-01T20:32:47.043Z,1759350767.043 [BuoyancyServo] Loaded
2025-10-01T20:32:47.043Z,1759350767.043 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-10-01T20:32:47.068Z,1759350767.068 [ElevatorServo] Loaded
2025-10-01T20:32:47.068Z,1759350767.068 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-10-01T20:32:47.091Z,1759350767.091 [MassServo] Loaded
2025-10-01T20:32:47.091Z,1759350767.091 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-10-01T20:32:47.113Z,1759350767.113 [RudderServo] Loaded
2025-10-01T20:32:47.113Z,1759350767.113 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-10-01T20:32:47.134Z,1759350767.134 [ThrusterServo] Loaded
2025-10-01T20:32:47.134Z,1759350767.134 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2025-10-01T20:32:47.135Z,1759350767.135 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-10-01T20:32:47.136Z,1759350767.136 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-10-01T20:32:47.152Z,1759350767.152 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-10-01T20:32:47.153Z,1759350767.153 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-10-01T20:32:47.363Z,1759350767.363 [ESPComponent] Loaded
2025-10-01T20:32:47.364Z,1759350767.364 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2025-10-01T20:32:47.388Z,1759350767.388 [PAR_Licor] Loaded
2025-10-01T20:32:47.388Z,1759350767.388 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-10-01T20:32:47.436Z,1759350767.436 [WetLabsBB2FL] Loaded
2025-10-01T20:32:47.437Z,1759350767.437 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-10-01T20:32:47.438Z,1759350767.438 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C0D4E0
2025-10-01T20:32:47.438Z,1759350767.438 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 937
2025-10-01T20:32:47.439Z,1759350767.439 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-10-01T20:32:47.446Z,1759350767.446 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-10-01T20:32:47.448Z,1759350767.448 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-10-01T20:32:47.460Z,1759350767.460 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-10-01T20:32:47.461Z,1759350767.461 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C3D4E0
2025-10-01T20:32:47.462Z,1759350767.462 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 938
2025-10-01T20:32:47.466Z,1759350767.466 [Supervisor](INFO): Main Thread ID is 830
2025-10-01T20:32:47.466Z,1759350767.466 [Supervisor](DEBUG): Running supervisor.
2025-10-01T20:32:47.467Z,1759350767.467 [CommandExec ThreadHandler](INFO): Handler Thread ID is 939
2025-10-01T20:32:47.467Z,1759350767.467 [CommandExec](INFO): Initializing the command executive.
2025-10-01T20:32:47.469Z,1759350767.469 [CommandLine ThreadHandler](INFO): Handler Thread ID is 940
2025-10-01T20:32:47.471Z,1759350767.471 [controlThread ThreadHandler](INFO): Handler Thread ID is 941
2025-10-01T20:32:47.472Z,1759350767.472 [controlThread](DEBUG): Initializing ControlThread
2025-10-01T20:32:47.472Z,1759350767.472 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-10-01T20:32:47.474Z,1759350767.474 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-10-01T20:32:47.475Z,1759350767.475 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-10-01T20:32:47.475Z,1759350767.475 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-10-01T20:32:47.476Z,1759350767.476 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-10-01T20:32:47.476Z,1759350767.476 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-10-01T20:32:47.476Z,1759350767.476 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-10-01T20:32:47.477Z,1759350767.477 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-10-01T20:32:47.477Z,1759350767.477 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-10-01T20:32:47.480Z,1759350767.480 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-10-01T20:32:47.480Z,1759350767.480 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-10-01T20:32:47.480Z,1759350767.480 [SBIT](INFO): Initialize SBIT Component.
2025-10-01T20:32:47.481Z,1759350767.481 [SBIT](IMPORTANT): git: 2025-09-29-5-gca3d702be
2025-10-01T20:32:47.481Z,1759350767.481 [SBIT](INFO): git hash: ca3d702bec2166a617311a106ec8835d0a9ff1c8
2025-10-01T20:32:47.481Z,1759350767.481 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-10-01T20:32:47.483Z,1759350767.483 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2025-10-01T20:32:47.484Z,1759350767.484 [SBIT](INFO): Beginning SBIT in 92.000000 seconds.
2025-10-01T20:32:47.484Z,1759350767.484 [IBIT](INFO): Initialize IBIT Component.
2025-10-01T20:32:47.485Z,1759350767.485 [CBIT](DEBUG): Initialize CBIT Component.
2025-10-01T20:32:47.486Z,1759350767.486 [logger ThreadHandler](INFO): Handler Thread ID is 942
2025-10-01T20:32:47.498Z,1759350767.498 [CBIT](DEBUG): Initialized mux pins.
2025-10-01T20:32:47.498Z,1759350767.498 [CBIT](DEBUG): Initializing the watchdog timer.
2025-10-01T20:32:47.506Z,1759350767.506 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 943
2025-10-01T20:32:47.518Z,1759350767.518 [DAT ThreadHandler](INFO): Handler Thread ID is 944
2025-10-01T20:32:47.519Z,1759350767.519 [DAT](INFO): Powering up
2025-10-01T20:32:47.519Z,1759350767.519 [DAT](DEBUG): Initializing DAT.
2025-10-01T20:32:47.522Z,1759350767.522 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-10-01T20:32:47.522Z,1759350767.522 [CBIT](DEBUG): Initializing heartbeat.
2025-10-01T20:32:47.524Z,1759350767.524 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 946
2025-10-01T20:32:47.526Z,1759350767.526 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-10-01T20:32:47.546Z,1759350767.546 [Onboard ThreadHandler](INFO): Handler Thread ID is 947
2025-10-01T20:32:47.563Z,1759350767.563 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 948
2025-10-01T20:32:47.586Z,1759350767.586 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 949
2025-10-01T20:32:47.590Z,1759350767.590 [WetLabsBB2FL](INFO): Powering up
2025-10-01T20:32:47.592Z,1759350767.592 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 951
2025-10-01T20:32:47.594Z,1759350767.594 [CBIT](DEBUG): Deactivating emergency mode.
2025-10-01T20:32:47.600Z,1759350767.600 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-10-01T20:32:47.600Z,1759350767.600 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-10-01T20:32:47.601Z,1759350767.601 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-10-01T20:32:47.601Z,1759350767.601 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-10-01T20:32:47.601Z,1759350767.601 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-10-01T20:32:47.601Z,1759350767.601 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-10-01T20:32:47.601Z,1759350767.601 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-10-01T20:32:47.601Z,1759350767.601 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-10-01T20:32:47.634Z,1759350767.634 [CBIT](DEBUG): Backplane powered.
2025-10-01T20:32:47.634Z,1759350767.634 [GFScanner](DEBUG): Initializing GFScanner
2025-10-01T20:32:47.634Z,1759350767.634 [GFScanner](DEBUG): Deactivating GF circuits.
2025-10-01T20:32:47.646Z,1759350767.646 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-10-01T20:32:47.647Z,1759350767.647 [MissionManager](INFO): Loading Mission from file: Missions/underIce/StartupUnder.tl
2025-10-01T20:32:47.647Z,1759350767.647 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/StartupUnder.tl
2025-10-01T20:32:47.666Z,1759350767.666 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/StartupUnder.tl
2025-10-01T20:32:47.685Z,1759350767.685 [MissionManager](DEBUG): TethyslAPI: Missions/underIce/StartupUnder.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="StartupUnder" />

2025-10-01T20:32:47.685Z,1759350767.685 [MissionManager](ERROR): Top node has no child nodes: Mission
2025-10-01T20:32:47.685Z,1759350767.685 [MissionManager](ERROR): Could not load startup mission.
2025-10-01T20:32:47.686Z,1759350767.686 [MissionManager](INFO): Loading Mission from file: Missions/underIce/DefaultWithUndock.tl
2025-10-01T20:32:47.686Z,1759350767.686 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/DefaultWithUndock.tl
2025-10-01T20:32:47.698Z,1759350767.698 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/DefaultWithUndock.tl
2025-10-01T20:32:47.970Z,1759350767.970 [Radio_Surface](INFO): Powering up
2025-10-01T20:32:48.254Z,1759350768.254 [MissionManager](DEBUG): TethyslAPI: Missions/underIce/DefaultWithUndock.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="DefaultWithUndock">
    <DefineArg Name="ElapsedSinceDefaultStarted">
        <Units:minute />
        <Value>0</Value>
    </DefineArg>
    <DefineArg Name="DetachTimeout">
        <Units:hour />
        <Value>6</Value>
    </DefineArg>
    <DefineArg Name="TransponderCode">
        <Dock:Dock.transponderCode />
    </DefineArg>
    <DefineArg Name="TrackingUpdatePeriod">
        <Units:second />
        <Value>10</Value>
    </DefineArg>
    <DefineArg Name="NumberOfPings">
        <Units:count />
        <Value>1</Value>
    </DefineArg>
    <DefineArg Name="DriftTimeout">
        <Units:minute />
        <Value>3</Value>
    </DefineArg>
    <Guidance:Wait>
        <Sequence />
        <Setting>
            <Guidance:Wait.duration />
            <Units:second />
            <Value>30</Value>
        </Setting>
    </Guidance:Wait>
    <Aggregate Id="LeaveDock">
        <Sequence />
        <Timeout Duration="DetachTimeout">
            <Syslog Severity="Important">
                Timed out while trying to detach from dock at current depth of 
                <Universal:depth />
                <Units:meter />
                .
            </Syslog>
        </Timeout>
        <Estimation:TrackAcousticContact>
            <While>
                <Universal:depth />
                <Gt>
                    <Control:VerticalControl.surfaceThreshold />
                </Gt>
            </While>
            <Setting>
                <Estimation:TrackAcousticContact.contactLabelSetting />
                <Arg Name="TransponderCode" />
            </Setting>
            <Setting>
                <Estimation:TrackAcousticContact.numberOfSamplesSetting />
                <Arg Name="NumberOfPings" />
            </Setting>
            <Setting>
                <Estimation:TrackAcousticContact.updatePeriodSetting />
                <Arg Name="TrackingUpdatePeriod" />
            </Setting>
        </Estimation:TrackAcousticContact>
        <Dock:Undock>
            <Sequence />
            <Setting>
                <Dock:Undock.driftTimeout />
                <Arg Name="DriftTimeout" />
            </Setting>
        </Dock:Undock>
    </Aggregate>
    <Aggregate Id="SurfaceDefault">
        <Sequence />
        <Guidance:GoToSurface>
            <Progression />
        </Guidance:GoToSurface>
        <Aggregate Id="CheckIn">
            <Sequence Repeat="288" />
            <ReadDatum Id="Read_GPS">
                <Timeout Duration="P5M" />
                <Universal:time_fix />
            </ReadDatum>
            <ReadDatum Id="Read_Iridium">
                <Timeout Duration="P2H">
                    <Guidance:Execute>
                        <Sequence />
                        <Setting>
                            <Guidance:Execute.command />
                            <String>Burn on</String>
                        </Setting>
                    </Guidance:Execute>
                    <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
                </Timeout>
                <Universal:platform_communications />
            </ReadDatum>
            <Guidance:Wait>
                <Sequence />
                <Setting>
                    <Guidance:Wait.duration />
                    <Units:minute />
                    <Value>5</Value>
                </Setting>
            </Guidance:Wait>
            <Assign>
                <Sequence />
                <Arg Name="ElapsedSinceDefaultStarted" />
                <Elapsed>
                    <Universal:mission_started />
                </Elapsed>
            </Assign>
            <Syslog Severity="Important">
                Default mission has been running for 
                <Arg Name="ElapsedSinceDefaultStarted" />
                <Units:minute />
            </Syslog>
        </Aggregate>
    </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>

2025-10-01T20:32:48.261Z,1759350768.261 [MissionManager](INFO): DefineArg DefaultWithUndock.ElapsedSinceDefaultStarted = 0.000000 min
2025-10-01T20:32:48.281Z,1759350768.281 [MissionManager](INFO): DefineArg DefaultWithUndock.DetachTimeout = 6.000000 h
2025-10-01T20:32:48.285Z,1759350768.285 [MissionManager](INFO): DefineArg DefaultWithUndock.TransponderCode = 50 count
2025-10-01T20:32:48.309Z,1759350768.309 [MissionManager](INFO): DefineArg DefaultWithUndock.TrackingUpdatePeriod = 10.000000 s
2025-10-01T20:32:48.321Z,1759350768.321 [MissionManager](INFO): DefineArg DefaultWithUndock.NumberOfPings = 1.000000 count
2025-10-01T20:32:48.333Z,1759350768.333 [MissionManager](INFO): DefineArg DefaultWithUndock.DriftTimeout = 3.000000 min
2025-10-01T20:32:48.335Z,1759350768.335 [DefaultWithUndock:A.Wait](DEBUG): Construct Wait.
2025-10-01T20:32:48.524Z,1759350768.524 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Construct.
2025-10-01T20:32:48.524Z,1759350768.524 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2025-10-01T20:32:48.577Z,1759350768.577 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-10-01T20:32:48.628Z,1759350768.628 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-10-01T20:32:48.638Z,1759350768.638 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-10-01T20:32:48.652Z,1759350768.652 [DefaultWithUndock:E.Execute](DEBUG): Construct Execute.
2025-10-01T20:32:48.665Z,1759350768.665 [MissionManager](DEBUG): 
#   Copyright (c) 2024 MBARI
#   MBARI Proprietary Information. Confidential. All Rights Reserved
#   Unauthorized copying or distribution of this file via any medium is strictly
#   prohibited.
#
#   WARNING - This file contains information whose export is restricted by the
#   Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
#   amended. Violations of these export laws are subject to severe civil and/or
#   criminal penalties.

mission DefaultWithUndock {
  arguments {
    ElapsedSinceDefaultStarted = 0 minute

    DetachTimeout = 6 hour
      """
      Maximum time duration for the vehicle to detach form the dock.
      """

    TransponderCode = Dock:Dock.transponderCode
      """
      Dock transponder address.
      """

    TrackingUpdatePeriod = 10 second
      """
      Time duration between acoustic range queries while the mission is
      detaching from the dock.
      """

    NumberOfPings = 1 count
      """
      Number of pings requested each time.
      """

    DriftTimeout = 3 minute
      """
      How long to allow vehicle to drift away from dock before retrying reverse
      """
  }

  behavior Guidance:Wait {
    """
    Wait a moment to see if the scheduler starts a new mission before
    starting to actually run Default.
    """

    run in sequence

    set duration = 30 second
  }

  aggregate LeaveDock {
    run in sequence

    timeout duration=DetachTimeout {
      syslog important "Timed out while trying to detach from dock at current depth of "
           + Universal:depth~meter + "."
    }

    # Acoustic tracking directives

    behavior Estimation:TrackAcousticContact {
      run while (
        Universal:depth > Control:VerticalControl.surfaceThreshold
      )

      set contactLabelSetting = TransponderCode
      set numberOfSamplesSetting = NumberOfPings
      set updatePeriodSetting = TrackingUpdatePeriod
    }

    # Leave the dock

    behavior Dock:Undock {
      run in sequence

      set driftTimeout = DriftTimeout
    }
  }

  aggregate SurfaceDefault {
    run in sequence

    # This mission assumes open water so from here on it's the same old default that's always used

    behavior Guidance:GoToSurface {
      run in progression
    }

    aggregate CheckIn {
      run in sequence repeat=288

      readDatum id="Read_GPS" {
        timeout duration=P5M

        Universal:time_fix
      }

      readDatum id="Read_Iridium" {
        timeout duration=P2H {
          behavior Guidance:Execute {
            run in sequence

            set command = "Burn on"
          }

          syslog critical "Dropped weight due to communications timeout."
        }

        Universal:platform_communications
      }

      behavior Guidance:Wait {
        run in sequence

        set duration = 5 minute
      }

      assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )

      syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
    }
  }

  syslog important "Restarting logs and Default mission."

  behavior Guidance:Execute {
    run in sequence

    set command = "restart logs"
  }
}


2025-10-01T20:32:48.670Z,1759350768.670 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,Sonardyne_Nano,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
2025-10-01T20:32:48.678Z,1759350768.678 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-10-01T20:32:48.814Z,1759350768.814 [Depth_Keller](INFO): Initializing.
2025-10-01T20:32:48.815Z,1759350768.815 [Power24vConverter](INFO): Powering up.
2025-10-01T20:32:48.816Z,1759350768.816 [Sonardyne_Nano](INFO): Initializing.
2025-10-01T20:32:48.854Z,1759350768.854 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-10-01T20:32:48.894Z,1759350768.894 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-10-01T20:32:48.902Z,1759350768.902 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-10-01T20:32:48.903Z,1759350768.903 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-10-01T20:32:48.910Z,1759350768.910 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-10-01T20:32:48.911Z,1759350768.911 [MassServo](DEBUG): Initializing EZServoServo.
2025-10-01T20:32:48.918Z,1759350768.918 [MassServo](DEBUG): Initializing MassServo.
2025-10-01T20:32:48.919Z,1759350768.919 [RudderServo](DEBUG): Initializing EZServoServo.
2025-10-01T20:32:48.926Z,1759350768.926 [RudderServo](DEBUG): Initializing RudderServo.
2025-10-01T20:32:48.927Z,1759350768.927 [ThrusterServo](DEBUG): Initializing EZServoServo.
2025-10-01T20:32:48.935Z,1759350768.935 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2025-10-01T20:32:49.119Z,1759350769.119 [Depth_Keller](ERROR): Pressure or depth reading out of range: 824.648376 decibar, 0.000000 m
2025-10-01T20:32:49.131Z,1759350769.131 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-10-01T20:32:50.202Z,1759350770.202 [WetLabsBB2FL](INFO): Powering down
2025-10-01T20:32:51.104Z,1759350771.104 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-10-01T20:32:52.346Z,1759350772.346 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2103
2025-10-01T20:32:53.222Z,1759350773.222 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.10
2025-10-01T20:32:53.223Z,1759350773.223 [Radio_Surface](INFO): No local IP found
2025-10-01T20:33:01.127Z,1759350781.127 [DAT](INFO): DAT read: 

2025-10-01T20:33:01.128Z,1759350781.128 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2025-10-01T20:33:06.671Z,1759350786.671 [DAT](INFO): DAT read: MF Frequency Band

2025-10-01T20:33:06.672Z,1759350786.672 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.17.2

2025-10-01T20:33:06.673Z,1759350786.673 [DAT](INFO): DAT read: Oct  1 2025 20:31:48 

2025-10-01T20:33:07.679Z,1759350787.679 [DAT](INFO): DAT read: Features enabled [Bearing]

2025-10-01T20:33:07.680Z,1759350787.680 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP

2025-10-01T20:33:07.680Z,1759350787.680 [DAT](INFO): commRate: 600
2025-10-01T20:33:09.746Z,1759350789.746 [DAT](INFO): entering command mode
2025-10-01T20:33:09.946Z,1759350789.946 [DAT](INFO): DAT read: 

2025-10-01T20:33:09.947Z,1759350789.947 [DAT](INFO): DAT read: user:1>

2025-10-01T20:33:09.947Z,1759350789.947 [DAT](INFO): setting verbose to 3
2025-10-01T20:33:10.199Z,1759350790.199 [DAT](INFO): DAT read: user:1>

2025-10-01T20:33:10.200Z,1759350790.200 [DAT](INFO): DAT read: Verbose         | 3           

2025-10-01T20:33:10.202Z,1759350790.202 [DAT](INFO): set verbose to 3
2025-10-01T20:33:10.203Z,1759350790.203 [DAT](INFO): setting DatVerbose to 27440
2025-10-01T20:33:10.450Z,1759350790.450 [DAT](INFO): DAT read: user:2>

2025-10-01T20:33:10.451Z,1759350790.451 [DAT](INFO): DAT read: DatVerbose      | 27440       

2025-10-01T20:33:10.452Z,1759350790.452 [DAT](INFO): set DatVerbose to 27440
2025-10-01T20:33:10.452Z,1759350790.452 [DAT](INFO): setting transmit power to 8
2025-10-01T20:33:10.703Z,1759350790.703 [DAT](INFO): DAT read: user:3>

2025-10-01T20:33:10.704Z,1759350790.704 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2025-10-01T20:33:10.704Z,1759350790.704 [DAT](INFO): set transmit power to 8
2025-10-01T20:33:10.704Z,1759350790.704 [DAT](INFO): setting local address to 10
2025-10-01T20:33:10.955Z,1759350790.955 [DAT](INFO): DAT read: user:4>

2025-10-01T20:33:10.956Z,1759350790.956 [DAT](INFO): DAT read: LocalAddr       | 10          

2025-10-01T20:33:10.956Z,1759350790.956 [DAT](INFO): set local address to 10
2025-10-01T20:33:10.957Z,1759350790.957 [DAT](INFO): Setting time to: 20:33:10 And date to:10/1/2025
2025-10-01T20:33:11.207Z,1759350791.207 [DAT](INFO): DAT read: user:5>

2025-10-01T20:33:11.208Z,1759350791.208 [DAT](INFO): DAT read: Wed Oct 1, 2025  20:33:10  

2025-10-01T20:33:11.208Z,1759350791.208 [DAT](INFO): Local DAT time set to Wed Oct 1, 2025  20:33:10  

2025-10-01T20:33:16.559Z,1759350796.559 [NAL9602](INFO): Powering up NAL9602
2025-10-01T20:33:27.467Z,1759350807.467 [NAL9602](INFO): NAL9602 initialized
2025-10-01T20:34:20.035Z,1759350860.035 [SBIT](IMPORTANT): Beginning Startup BIT
2025-10-01T20:34:20.040Z,1759350860.040 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-10-01T20:34:24.528Z,1759350864.528 [GFScanner](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.043668
CHAN A1 (24V): -0.029273
CHAN A2 (12V): -0.007330
CHAN A3 (5V): -0.002711
CHAN B0 (3.3V): -0.000146
CHAN B1 (3.15aV): -0.000849
CHAN B2 (3.15bV): 0.000030
CHAN B3 (GND): 0.001686
OPEN: -0.004974
Full Scale: +/- 1 mA
2025-10-01T20:34:30.493Z,1759350870.493 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-10-01T20:35:13.409Z,1759350913.409 [SBIT](IMPORTANT): SBIT PASSED
2025-10-01T20:35:13.409Z,1759350913.409 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-10-01T20:35:13.411Z,1759350913.411 [SBIT](IMPORTANT): BackseatComponent.simulateHardware=1 bool;

2025-10-01T20:35:13.411Z,1759350913.411 [SBIT](IMPORTANT): CBIT.gf24Offset=173 microampere;

2025-10-01T20:35:13.411Z,1759350913.411 [SBIT](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool;

2025-10-01T20:35:13.411Z,1759350913.411 [SBIT](IMPORTANT): Docked.verbose=0 bool;

2025-10-01T20:35:13.411Z,1759350913.411 [SBIT](IMPORTANT): DockingStepper.closedValue=14000 count;

2025-10-01T20:35:13.411Z,1759350913.411 [SBIT](IMPORTANT): DockingStepper.loadAtStartup=0 bool;

2025-10-01T20:35:13.411Z,1759350913.411 [SBIT](IMPORTANT): DockingStepper.openValue=3300 count;

2025-10-01T20:35:13.411Z,1759350913.411 [SBIT](IMPORTANT): DockingStepper.slideValue=13750 count;

2025-10-01T20:35:13.412Z,1759350913.412 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2025-10-01T20:35:13.412Z,1759350913.412 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool;

2025-10-01T20:35:13.412Z,1759350913.412 [SBIT](IMPORTANT): ESPComponent.simulateHardware=1 bool;

2025-10-01T20:35:13.412Z,1759350913.412 [SBIT](IMPORTANT): Express mostRecent WetLabsUBAT.average_bioluminescence;

2025-10-01T20:35:13.412Z,1759350913.412 [SBIT](IMPORTANT): LineCapture.rolloutSpeed=0 meter_per_second;

2025-10-01T20:35:13.412Z,1759350913.412 [SBIT](IMPORTANT): NAL9602.iridiumMTQueueTimeout=1 minute;

2025-10-01T20:35:13.412Z,1759350913.412 [SBIT](IMPORTANT): Normal linearApproximation _.dinoflagellates 10000000000.000000 none;

2025-10-01T20:35:13.412Z,1759350913.412 [SBIT](IMPORTANT): RDI_Pathfinder.loadAtStartup=0 bool;

2025-10-01T20:35:13.412Z,1759350913.412 [SBIT](IMPORTANT): RDI_Pathfinder.simulateHardware=1 bool;

2025-10-01T20:35:13.413Z,1759350913.413 [SBIT](IMPORTANT): RudderServo.offsetAngle=1 degree;

2025-10-01T20:35:13.413Z,1759350913.413 [SBIT](IMPORTANT): SpeedControl.rollOptimum=1.025000 m/s;

2025-10-01T20:35:13.413Z,1759350913.413 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=431.122100 cubic_centimeter;

2025-10-01T20:35:13.413Z,1759350913.413 [SBIT](IMPORTANT): VerticalControl.massDefault=18.074537 millimeter;

2025-10-01T20:35:13.413Z,1759350913.413 [SBIT](IMPORTANT): WetLabsUBAT.loadAtStartup=0 bool;

2025-10-01T20:35:13.801Z,1759350913.801 [MissionManager](IMPORTANT): Started mission DefaultWithUndock
2025-10-01T20:35:13.801Z,1759350913.801 [DefaultWithUndock] Running Loop=1
2025-10-01T20:35:13.801Z,1759350913.801 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock
2025-10-01T20:35:13.802Z,1759350913.802 [DefaultWithUndock:A.Wait] Running Loop=1
2025-10-01T20:35:13.802Z,1759350913.802 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component.
2025-10-01T20:35:31.500Z,1759350931.500 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004726
2025-10-01T20:35:32.498Z,1759350932.498 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:35:40.838Z,1759350940.838 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:35:44.131Z,1759350944.131 [DefaultWithUndock:A.Wait](INFO): Done Waiting.
2025-10-01T20:35:44.131Z,1759350944.131 [DefaultWithUndock:A.Wait] Stopped
2025-10-01T20:35:44.131Z,1759350944.131 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component.
2025-10-01T20:35:44.131Z,1759350944.131 [DefaultWithUndock:LeaveDock] Running Loop=1
2025-10-01T20:35:44.131Z,1759350944.131 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock
2025-10-01T20:35:44.131Z,1759350944.131 [DefaultWithUndock:LeaveDock:A.] Running Loop=1
2025-10-01T20:35:44.132Z,1759350944.132 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact.
2025-10-01T20:35:44.132Z,1759350944.132 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1
2025-10-01T20:35:44.132Z,1759350944.132 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize.
2025-10-01T20:35:44.132Z,1759350944.132 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2025-10-01T20:35:44.527Z,1759350944.527 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize.
2025-10-01T20:35:44.528Z,1759350944.528 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2025-10-01T20:35:44.529Z,1759350944.529 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached from dock at depth -0.225493 m.
2025-10-01T20:35:44.529Z,1759350944.529 [DefaultWithUndock:LeaveDock:A.] Stopped
2025-10-01T20:35:44.529Z,1759350944.529 [DefaultWithUndock:LeaveDock:A.](DEBUG): Uninitializing TrackAcousticContact.
2025-10-01T20:35:44.963Z,1759350944.963 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer.
2025-10-01T20:35:47.786Z,1759350947.786 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-10-01T20:35:47.786Z,1759350947.786 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-10-01T20:35:47.839Z,1759350947.839 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-10-01T20:35:48.215Z,1759350948.215 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-10-01T20:35:48.216Z,1759350948.216 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-10-01T20:35:49.222Z,1759350949.222 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:35:57.738Z,1759350957.738 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:36:05.194Z,1759350965.194 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached at range: nan m. Transitioning docking module to standby.
2025-10-01T20:36:05.527Z,1759350965.527 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Docking module hardware isn't loaded, so simply satisfying DockingState request.
2025-10-01T20:36:05.527Z,1759350965.527 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Docking module at standby.
2025-10-01T20:36:05.936Z,1759350965.936 [DefaultWithUndock:LeaveDock:B.Undock](IMPORTANT): Undocking sequence complete.
2025-10-01T20:36:05.937Z,1759350965.937 [DefaultWithUndock:LeaveDock:B.Undock] Stopped
2025-10-01T20:36:05.937Z,1759350965.937 [DefaultWithUndock:LeaveDock](INFO): Completed DefaultWithUndock:LeaveDock
2025-10-01T20:36:05.937Z,1759350965.937 [DefaultWithUndock:LeaveDock] Stopped
2025-10-01T20:36:05.937Z,1759350965.937 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock
2025-10-01T20:36:05.937Z,1759350965.937 [DefaultWithUndock:SurfaceDefault] Running Loop=1
2025-10-01T20:36:05.937Z,1759350965.937 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault
2025-10-01T20:36:05.937Z,1759350965.937 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Running Loop=1
2025-10-01T20:36:05.937Z,1759350965.937 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-10-01T20:36:05.938Z,1759350965.938 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-10-01T20:36:05.938Z,1759350965.938 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-10-01T20:36:05.939Z,1759350965.939 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-10-01T20:36:05.939Z,1759350965.939 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-10-01T20:36:05.939Z,1759350965.939 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-10-01T20:36:05.940Z,1759350965.940 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-10-01T20:36:06.190Z,1759350966.190 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:36:06.349Z,1759350966.349 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=1
2025-10-01T20:36:06.349Z,1759350966.349 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-10-01T20:36:06.349Z,1759350966.349 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2025-10-01T20:36:06.755Z,1759350966.755 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-10-01T20:36:14.730Z,1759350974.730 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:36:23.222Z,1759350983.222 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:36:31.638Z,1759350991.638 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:36:39.922Z,1759350999.922 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:36:48.246Z,1759351008.246 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:36:56.546Z,1759351016.546 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:37:04.902Z,1759351024.902 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:37:13.278Z,1759351033.278 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:37:21.591Z,1759351041.591 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:37:25.904Z,1759351045.904 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-10-01T20:37:29.998Z,1759351049.998 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:37:38.430Z,1759351058.430 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:37:46.802Z,1759351066.802 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:37:55.095Z,1759351075.095 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:38:03.494Z,1759351083.494 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:38:11.890Z,1759351091.890 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:38:20.150Z,1759351100.150 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:38:28.442Z,1759351108.442 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:38:36.886Z,1759351116.886 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:38:45.214Z,1759351125.214 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:38:48.750Z,1759351128.750 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2025-10-01T20:38:48.750Z,1759351128.750 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-10-01T20:38:48.769Z,1759351128.769 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-10-01T20:38:49.175Z,1759351129.175 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-10-01T20:38:49.175Z,1759351129.175 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2025-10-01T20:38:53.526Z,1759351133.526 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:39:01.866Z,1759351141.866 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:39:10.218Z,1759351150.218 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:39:18.622Z,1759351158.622 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:39:27.066Z,1759351167.066 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:39:35.342Z,1759351175.342 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:39:43.718Z,1759351183.718 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:39:53.654Z,1759351193.654 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:40:02.266Z,1759351202.266 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:40:10.862Z,1759351210.862 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:40:19.198Z,1759351219.198 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:40:27.550Z,1759351227.550 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:40:36.126Z,1759351236.126 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:40:44.550Z,1759351244.550 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:40:52.886Z,1759351252.886 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:41:01.199Z,1759351261.199 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:41:06.513Z,1759351266.513 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS](INFO): Timed out from 2025-10-01T20:36:06.3Z
2025-10-01T20:41:06.513Z,1759351266.513 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2025-10-01T20:41:06.513Z,1759351266.513 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2025-10-01T20:41:06.962Z,1759351266.962 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-10-01T20:41:08.083Z,1759351268.083 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20251001T185116/Courier0057.lzma
2025-10-01T20:41:08.612Z,1759351268.612 [DataOverHttps](INFO): Moved sent file to Logs/20251001T185116/Courier0057.lzma.bak
2025-10-01T20:41:08.612Z,1759351268.612 [DataOverHttps](INFO): SBD MOMSN=26089451
2025-10-01T20:41:24.366Z,1759351284.366 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20251001T203233/Courier0000.lzma
2025-10-01T20:41:25.368Z,1759351285.368 [DataOverHttps](INFO): Moved sent file to Logs/20251001T203233/Courier0000.lzma.bak
2025-10-01T20:41:25.368Z,1759351285.368 [DataOverHttps](INFO): SBD MOMSN=26089453
2025-10-01T20:41:40.041Z,1759351300.041 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20251001T185116/Express0058.lzma
2025-10-01T20:41:41.036Z,1759351301.036 [DataOverHttps](INFO): Moved sent file to Logs/20251001T185116/Express0058.lzma.bak
2025-10-01T20:41:41.036Z,1759351301.036 [DataOverHttps](INFO): SBD MOMSN=26089456
2025-10-01T20:41:42.999Z,1759351302.999 [CommandExec](IMPORTANT): got command show variable stepper
2025-10-01T20:41:43.087Z,1759351303.087 [CommandExec](IMPORTANT): DockingStepper.loadAtStartup (bool)
2025-10-01T20:41:43.087Z,1759351303.087 [CommandExec](IMPORTANT): DockingStepper.simulateHardware (bool)
2025-10-01T20:41:43.087Z,1759351303.087 [CommandExec](IMPORTANT): DockingStepper.openValue (count)
2025-10-01T20:41:43.088Z,1759351303.088 [CommandExec](IMPORTANT): DockingStepper.closedValue (count)
2025-10-01T20:41:43.088Z,1759351303.088 [CommandExec](IMPORTANT): DockingStepper.slideValue (count)
2025-10-01T20:41:43.088Z,1759351303.088 [CommandExec](IMPORTANT): DockingStepper.deviationValue (count)
2025-10-01T20:41:43.089Z,1759351303.089 [CommandExec](IMPORTANT): DockingStepper.currLimit (percent)
2025-10-01T20:41:43.089Z,1759351303.089 [CommandExec](IMPORTANT): DockingStepper.velocity (none)
2025-10-01T20:41:43.090Z,1759351303.090 [CommandExec](IMPORTANT): DockingStepper.powerOnTimeout (second)
2025-10-01T20:41:43.090Z,1759351303.090 [CommandExec](IMPORTANT): DockingStepper.powerOffTimeout (second)
2025-10-01T20:41:43.118Z,1759351303.118 [CommandExec](IMPORTANT): DockingStepper.loadControl (none)
2025-10-01T20:41:43.118Z,1759351303.118 [CommandExec](IMPORTANT): DockingStepper.uart (none)
2025-10-01T20:41:43.119Z,1759351303.119 [CommandExec](IMPORTANT): DockingStepper.baud (bit_per_second)
2025-10-01T20:41:49.773Z,1759351309.773 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2025-10-01T20:41:49.773Z,1759351309.773 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-10-01T20:41:49.783Z,1759351309.783 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-10-01T20:41:50.204Z,1759351310.204 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-10-01T20:41:50.204Z,1759351310.204 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2025-10-01T20:41:50.857Z,1759351310.857 [CommandExec](IMPORTANT): got command get DockingStepper.loadAtStartup
2025-10-01T20:41:50.857Z,1759351310.857 [CommandExec](IMPORTANT): DockingStepper.loadAtStartup 0 bool
2025-10-01T20:41:58.097Z,1759351318.097 [DataOverHttps](INFO): Sending 1269 bytes from file Logs/20251001T203233/Express0001.lzma
2025-10-01T20:41:59.096Z,1759351319.096 [DataOverHttps](INFO): Moved sent file to Logs/20251001T203233/Express0001.lzma.bak
2025-10-01T20:41:59.096Z,1759351319.096 [DataOverHttps](INFO): SBD MOMSN=26089458
2025-10-01T20:42:00.677Z,1759351320.677 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2025-10-01T20:42:00.677Z,1759351320.677 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2025-10-01T20:42:00.677Z,1759351320.677 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-10-01T20:42:08.542Z,1759351328.542 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:42:16.858Z,1759351336.858 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:42:25.198Z,1759351345.198 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:42:33.714Z,1759351353.714 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:42:42.170Z,1759351362.170 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:42:50.430Z,1759351370.430 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:42:58.910Z,1759351378.910 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:43:07.290Z,1759351387.290 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:43:15.602Z,1759351395.602 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:43:26.858Z,1759351406.858 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:43:28.359Z,1759351408.359 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-10-01T20:43:28.359Z,1759351408.359 [NAL9602] Data Fault, FailCount= 1
2025-10-01T20:43:28.359Z,1759351408.359 [NAL9602](ERROR): Data Fault
2025-10-01T20:43:28.377Z,1759351408.377 [CBIT](ERROR): Data Fault in component: NAL9602
2025-10-01T20:43:28.759Z,1759351408.759 [NAL9602](INFO): Powering down
2025-10-01T20:43:29.669Z,1759351409.669 [CBIT](INFO): Clearing failed state for component NAL9602
2025-10-01T20:43:29.669Z,1759351409.669 [NAL9602] No Fault, FailCount= 1
2025-10-01T20:43:39.570Z,1759351419.570 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:43:47.083Z,1759351427.083 [DataOverHttps](IMPORTANT): SBD MTMSN=20251001T204346
2025-10-01T20:43:54.387Z,1759351434.387 [DataOverHttps](INFO): Received command: restart application
2025-10-01T20:43:54.443Z,1759351434.443 [CommandExec](IMPORTANT): got command restart application
2025-10-01T20:43:55.446Z,1759351435.446 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2025-10-01T20:43:55.446Z,1759351435.446 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-10-01T20:43:55.446Z,1759351435.446 [CommandExec](INFO): Uninitializing the command executive.
2025-10-01T20:43:55.446Z,1759351435.446 [CommandExec](INFO): Uninitializing the command scheduler.
2025-10-01T20:43:55.447Z,1759351435.447 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:43:55.486Z,1759351435.486 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2025-10-01T20:43:55.486Z,1759351435.486 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-10-01T20:43:55.486Z,1759351435.486 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:43:55.487Z,1759351435.487 [NavChartDb](INFO): Join timeout helper Thread ID is 998
2025-10-01T20:43:55.614Z,1759351435.614 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-10-01T20:43:55.614Z,1759351435.614 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:43:55.632Z,1759351435.632 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-10-01T20:43:55.633Z,1759351435.633 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:43:55.633Z,1759351435.633 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 999
2025-10-01T20:43:55.714Z,1759351435.714 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-10-01T20:43:55.714Z,1759351435.714 [WetLabsBB2FL](INFO): Powering down
2025-10-01T20:43:55.715Z,1759351435.715 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:43:55.726Z,1759351435.726 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-10-01T20:43:55.726Z,1759351435.726 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:43:55.726Z,1759351435.726 [Radio_Surface](INFO): Join timeout helper Thread ID is 1000
2025-10-01T20:43:55.962Z,1759351435.962 [Radio_Surface](INFO): Powering down
2025-10-01T20:43:55.963Z,1759351435.963 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-10-01T20:43:55.963Z,1759351435.963 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:43:55.966Z,1759351435.966 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-10-01T20:43:55.966Z,1759351435.966 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:43:55.966Z,1759351435.966 [Onboard](INFO): Join timeout helper Thread ID is 1001
2025-10-01T20:43:56.390Z,1759351436.390 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-10-01T20:43:56.390Z,1759351436.390 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-10-01T20:43:58.118Z,1759351438.118 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-10-01T20:43:58.118Z,1759351438.118 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:43:58.122Z,1759351438.122 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-10-01T20:43:58.122Z,1759351438.122 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:43:58.122Z,1759351438.122 [DataOverHttps](INFO): Join timeout helper Thread ID is 1002
2025-10-01T20:43:58.398Z,1759351438.398 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-10-01T20:43:58.398Z,1759351438.398 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:43:58.411Z,1759351438.411 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2025-10-01T20:43:58.411Z,1759351438.411 [DAT ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:43:58.411Z,1759351438.411 [DAT](INFO): Join timeout helper Thread ID is 1003
2025-10-01T20:43:58.426Z,1759351438.426 [DAT](INFO): Powering down
2025-10-01T20:43:58.495Z,1759351438.495 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-10-01T20:43:58.495Z,1759351438.495 [DAT](INFO): Powering down
2025-10-01T20:43:58.496Z,1759351438.496 [DAT ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:43:58.510Z,1759351438.510 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2025-10-01T20:43:58.511Z,1759351438.511 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:43:58.511Z,1759351438.511 [BackseatComponent](INFO): Join timeout helper Thread ID is 1004
2025-10-01T20:43:58.702Z,1759351438.702 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2025-10-01T20:43:58.702Z,1759351438.702 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:43:58.723Z,1759351438.723 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-10-01T20:43:58.723Z,1759351438.723 [logger ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:43:58.723Z,1759351438.723 [logger](INFO): Join timeout helper Thread ID is 1005
2025-10-01T20:43:58.745Z,1759351438.745 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-10-01T20:43:58.745Z,1759351438.745 [logger ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:43:58.751Z,1759351438.751 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-10-01T20:43:58.751Z,1759351438.751 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:43:58.751Z,1759351438.751 [CommandLine](INFO): Join timeout helper Thread ID is 1006
2025-10-01T20:43:58.786Z,1759351438.786 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-10-01T20:43:58.786Z,1759351438.786 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:43:58.791Z,1759351438.791 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-10-01T20:43:58.791Z,1759351438.791 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:43:58.791Z,1759351438.791 [CommandExec](INFO): Join timeout helper Thread ID is 1007
2025-10-01T20:43:58.792Z,1759351438.792 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-10-01T20:43:58.792Z,1759351438.792 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:43:58.793Z,1759351438.793 [controlThread](INFO): Join timeout helper Thread ID is 1008
2025-10-01T20:43:59.046Z,1759351439.046 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-10-01T20:43:59.046Z,1759351439.046 [controlThread](DEBUG): Uninitializing ControlThread
2025-10-01T20:43:59.047Z,1759351439.047 [AHRS_M2](INFO): Powering down
2025-10-01T20:43:59.126Z,1759351439.126 [NAL9602](INFO): Powering down
2025-10-01T20:43:59.127Z,1759351439.127 [Sonardyne_Nano](INFO): Powering down
2025-10-01T20:43:59.254Z,1759351439.254 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-10-01T20:43:59.255Z,1759351439.255 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-10-01T20:43:59.255Z,1759351439.255 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-10-01T20:43:59.256Z,1759351439.256 [MissionManager](INFO): Uninitializing Mission DefaultWithUndock
2025-10-01T20:43:59.256Z,1759351439.256 [DefaultWithUndock] Stopped
2025-10-01T20:43:59.256Z,1759351439.256 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock
2025-10-01T20:43:59.256Z,1759351439.256 [DefaultWithUndock:SurfaceDefault] Stopped
2025-10-01T20:43:59.256Z,1759351439.256 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault
2025-10-01T20:43:59.256Z,1759351439.256 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Stopped
2025-10-01T20:43:59.256Z,1759351439.256 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-10-01T20:43:59.256Z,1759351439.256 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2025-10-01T20:43:59.257Z,1759351439.257 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-10-01T20:43:59.257Z,1759351439.257 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2025-10-01T20:43:59.257Z,1759351439.257 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-10-01T20:43:59.265Z,1759351439.265 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-10-01T20:43:59.266Z,1759351439.266 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-10-01T20:43:59.266Z,1759351439.266 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-10-01T20:43:59.266Z,1759351439.266 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-10-01T20:43:59.267Z,1759351439.267 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-10-01T20:43:59.267Z,1759351439.267 [BuoyancyServo](INFO): Powering down
2025-10-01T20:43:59.282Z,1759351439.282 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-10-01T20:43:59.282Z,1759351439.282 [ElevatorServo](INFO): Powering down
2025-10-01T20:43:59.283Z,1759351439.283 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-10-01T20:43:59.283Z,1759351439.283 [MassServo](INFO): Powering down
2025-10-01T20:43:59.284Z,1759351439.284 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-10-01T20:43:59.284Z,1759351439.284 [RudderServo](INFO): Powering down
2025-10-01T20:43:59.285Z,1759351439.285 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2025-10-01T20:43:59.285Z,1759351439.285 [ThrusterServo](INFO): Powering down
2025-10-01T20:43:59.286Z,1759351439.286 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-10-01T20:43:59.286Z,1759351439.286 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-10-01T20:43:59.286Z,1759351439.286 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-10-01T20:43:59.286Z,1759351439.286 [CBIT](DEBUG): Powering off loads.
2025-10-01T20:43:59.298Z,1759351439.298 [CBIT](DEBUG): Disabling WDT.
2025-10-01T20:43:59.310Z,1759351439.310 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-10-01T20:43:59.310Z,1759351439.310 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-10-01T20:43:59.311Z,1759351439.311 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:43:59.362Z,1759351439.362 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:43:59.365Z,1759351439.365 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:43:59.371Z,1759351439.371 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:43:59.377Z,1759351439.377 [DAT ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:43:59.419Z,1759351439.419 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:43:59.453Z,1759351439.453 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-10-01T20:43:59.453Z,1759351439.453 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-10-01T20:43:59.481Z,1759351439.481 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:43:59.492Z,1759351439.492 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-10-01T20:43:59.558Z,1759351439.558 [logger ThreadHandler](INFO): Thread cancelled.