2025-09-09T19:02:28.936Z,1757444548.936 [Supervisor](DEBUG): Initializing supervisor.
2025-09-09T19:02:28.942Z,1757444548.942 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-09-09T19:02:28.942Z,1757444548.942 [SyncHandler](INFO): Protected caller Thread ID is 831
2025-09-09T19:02:28.951Z,1757444548.951 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-09-09T19:02:28.953Z,1757444548.953 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-09-09T19:02:28.953Z,1757444548.953 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 832
2025-09-09T19:02:28.962Z,1757444548.962 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-09-09T19:02:28.986Z,1757444548.986 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-09-09T19:02:28.987Z,1757444548.987 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-09-09T19:02:28.987Z,1757444548.987 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 833
2025-09-09T19:02:28.992Z,1757444548.992 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-09-09T19:02:28.993Z,1757444548.993 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-09-09T19:02:28.993Z,1757444548.993 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 834
2025-09-09T19:02:28.995Z,1757444548.995 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-09-09T19:02:28.996Z,1757444548.996 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-09-09T19:02:28.997Z,1757444548.997 [logger ThreadHandler](INFO): Protected caller Thread ID is 835
2025-09-09T19:02:29.001Z,1757444549.001 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-09-09T19:02:29.001Z,1757444549.001 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-09-09T19:02:29.004Z,1757444549.004 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-09-09T19:02:29.347Z,1757444549.347 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-09-09T19:02:29.348Z,1757444549.348 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-09-09T19:02:30.111Z,1757444550.111 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-09-09T19:02:30.112Z,1757444550.112 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-09-09T19:02:30.230Z,1757444550.230 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-09-09T19:02:30.230Z,1757444550.230 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-09-09T19:02:30.785Z,1757444550.785 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-09-09T19:02:30.786Z,1757444550.786 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-09-09T19:02:30.892Z,1757444550.892 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-09-09T19:02:30.893Z,1757444550.893 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-09-09T19:02:30.993Z,1757444550.993 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance
2025-09-09T19:02:30.993Z,1757444550.993 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-09-09T19:02:31.433Z,1757444551.433 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-09-09T19:02:31.433Z,1757444551.433 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-09-09T19:02:31.663Z,1757444551.663 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-09-09T19:02:31.664Z,1757444551.664 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-09-09T19:02:31.811Z,1757444551.811 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-09-09T19:02:31.812Z,1757444551.812 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-09-09T19:02:32.070Z,1757444552.070 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-09-09T19:02:32.071Z,1757444552.071 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-09-09T19:02:32.642Z,1757444552.642 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-09-09T19:02:32.642Z,1757444552.642 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-09-09T19:02:33.041Z,1757444553.041 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-09-09T19:02:33.042Z,1757444553.042 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-09-09T19:02:33.131Z,1757444553.131 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-09-09T19:02:33.324Z,1757444553.324 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-09-09T19:02:33.324Z,1757444553.324 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-09-09T19:02:33.584Z,1757444553.584 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-09-09T19:02:33.585Z,1757444553.585 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-09-09T19:02:33.916Z,1757444553.916 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-09-09T19:02:33.918Z,1757444553.918 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/
2025-09-09T19:02:34.118Z,1757444554.118 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg
2025-09-09T19:02:34.326Z,1757444554.326 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg
2025-09-09T19:02:34.659Z,1757444554.659 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg
2025-09-09T19:02:34.826Z,1757444554.826 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg
2025-09-09T19:02:34.920Z,1757444554.920 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg
2025-09-09T19:02:35.042Z,1757444555.042 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/workSite.cfg
2025-09-09T19:02:35.146Z,1757444555.146 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Dock.cfg
2025-09-09T19:02:35.281Z,1757444555.281 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg
2025-09-09T19:02:35.395Z,1757444555.395 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg
2025-09-09T19:02:35.675Z,1757444555.675 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-09-09T19:02:35.676Z,1757444555.676 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg
2025-09-09T19:02:35.818Z,1757444555.818 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg
2025-09-09T19:02:35.920Z,1757444555.920 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg
2025-09-09T19:02:36.024Z,1757444556.024 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg
2025-09-09T19:02:36.118Z,1757444556.118 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2025-09-09T19:02:36.121Z,1757444556.121 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-09-09T19:02:36.298Z,1757444556.298 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-09-09T19:02:36.299Z,1757444556.299 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-09-09T19:02:36.315Z,1757444556.315 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-09-09T19:02:36.315Z,1757444556.315 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-09-09T19:02:36.389Z,1757444556.389 [DepthRateCalculator] Loaded
2025-09-09T19:02:36.389Z,1757444556.389 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-09-09T19:02:36.395Z,1757444556.395 [PitchRateCalculator] Loaded
2025-09-09T19:02:36.395Z,1757444556.395 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-09-09T19:02:36.406Z,1757444556.406 [SpeedCalculator] Loaded
2025-09-09T19:02:36.406Z,1757444556.406 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-09-09T19:02:36.411Z,1757444556.411 [YawRateCalculator] Loaded
2025-09-09T19:02:36.411Z,1757444556.411 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-09-09T19:02:36.434Z,1757444556.434 [ElevatorOffsetCalculator] Loaded
2025-09-09T19:02:36.434Z,1757444556.434 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-09-09T19:02:36.434Z,1757444556.434 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-09-09T19:02:36.435Z,1757444556.435 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-09-09T19:02:36.509Z,1757444556.509 [VerticalControl](DEBUG): Construct VerticalControl.
2025-09-09T19:02:36.567Z,1757444556.567 [VerticalControl] Loaded
2025-09-09T19:02:36.567Z,1757444556.567 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-09-09T19:02:36.570Z,1757444556.570 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-09-09T19:02:36.618Z,1757444556.618 [HorizontalControl] Loaded
2025-09-09T19:02:36.618Z,1757444556.618 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-09-09T19:02:36.621Z,1757444556.621 [SpeedControl](DEBUG): Construct SpeedControl.
2025-09-09T19:02:36.624Z,1757444556.624 [SpeedControl] Loaded
2025-09-09T19:02:36.624Z,1757444556.624 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-09-09T19:02:36.627Z,1757444556.627 [LoopControl](DEBUG): Construct LoopControl.
2025-09-09T19:02:36.627Z,1757444556.627 [LoopControl] Loaded
2025-09-09T19:02:36.627Z,1757444556.627 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-09-09T19:02:36.628Z,1757444556.628 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-09-09T19:02:36.628Z,1757444556.628 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-09-09T19:02:36.776Z,1757444556.776 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-09-09T19:02:36.776Z,1757444556.776 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-09-09T19:02:36.790Z,1757444556.790 [NavChart] Loaded
2025-09-09T19:02:36.791Z,1757444556.791 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-09-09T19:02:36.797Z,1757444556.797 [UniversalFixResidualReporter] Loaded
2025-09-09T19:02:36.797Z,1757444556.797 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-09-09T19:02:36.797Z,1757444556.797 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-09-09T19:02:36.798Z,1757444556.798 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-09-09T19:02:37.021Z,1757444557.021 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-09-09T19:02:37.022Z,1757444557.022 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-09-09T19:02:37.880Z,1757444557.880 [AHRS_M2] Loaded
2025-09-09T19:02:37.880Z,1757444557.880 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-09-09T19:02:38.238Z,1757444558.238 [BackseatComponent] Loaded
2025-09-09T19:02:38.238Z,1757444558.238 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-09-09T19:02:38.239Z,1757444558.239 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409404E0
2025-09-09T19:02:38.240Z,1757444558.240 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 923
2025-09-09T19:02:38.243Z,1757444558.243 [LcmUniversalReporter] Loaded
2025-09-09T19:02:38.243Z,1757444558.243 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-09-09T19:02:39.213Z,1757444559.213 [BPC1] Loaded
2025-09-09T19:02:39.213Z,1757444559.213 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-09-09T19:02:39.314Z,1757444559.314 [DAT] Loaded
2025-09-09T19:02:39.315Z,1757444559.315 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-09-09T19:02:39.316Z,1757444559.316 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409704E0
2025-09-09T19:02:39.316Z,1757444559.316 [DAT ThreadHandler](INFO): Protected caller Thread ID is 924
2025-09-09T19:02:39.597Z,1757444559.597 [DataOverHttps] Loaded
2025-09-09T19:02:39.598Z,1757444559.598 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-09-09T19:02:39.599Z,1757444559.599 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409A04E0
2025-09-09T19:02:39.599Z,1757444559.599 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 925
2025-09-09T19:02:39.624Z,1757444559.624 [Depth_Keller] Loaded
2025-09-09T19:02:39.624Z,1757444559.624 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-09-09T19:02:39.630Z,1757444559.630 [DropWeight] Loaded
2025-09-09T19:02:39.630Z,1757444559.630 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-09-09T19:02:39.745Z,1757444559.745 [NAL9602] Loaded
2025-09-09T19:02:39.745Z,1757444559.745 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-09-09T19:02:39.807Z,1757444559.807 [Onboard] Loaded
2025-09-09T19:02:39.807Z,1757444559.807 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-09-09T19:02:39.824Z,1757444559.824 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409D04E0
2025-09-09T19:02:39.824Z,1757444559.824 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 926
2025-09-09T19:02:39.866Z,1757444559.866 [Power24vConverter] Loaded
2025-09-09T19:02:39.867Z,1757444559.867 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-09-09T19:02:39.946Z,1757444559.946 [Radio_Surface] Loaded
2025-09-09T19:02:39.946Z,1757444559.946 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-09-09T19:02:39.953Z,1757444559.953 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A004E0
2025-09-09T19:02:39.953Z,1757444559.953 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 927
2025-09-09T19:02:40.000Z,1757444560.000 [RDI_Pathfinder] Loaded
2025-09-09T19:02:40.001Z,1757444560.001 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2025-09-09T19:02:40.016Z,1757444560.016 [Sonardyne_Nano] Loaded
2025-09-09T19:02:40.017Z,1757444560.017 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-09-09T19:02:40.017Z,1757444560.017 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-09-09T19:02:40.018Z,1757444560.018 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-09-09T19:02:40.425Z,1757444560.425 [BuoyancyServo] Loaded
2025-09-09T19:02:40.426Z,1757444560.426 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-09-09T19:02:40.449Z,1757444560.449 [ElevatorServo] Loaded
2025-09-09T19:02:40.450Z,1757444560.450 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-09-09T19:02:40.480Z,1757444560.480 [DockingStepper] Loaded
2025-09-09T19:02:40.481Z,1757444560.481 [ComponentRegistry](DEBUG): SyncComponent "DockingStepper" handled in the control thread.
2025-09-09T19:02:40.501Z,1757444560.501 [MassServo] Loaded
2025-09-09T19:02:40.501Z,1757444560.501 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-09-09T19:02:40.522Z,1757444560.522 [RudderServo] Loaded
2025-09-09T19:02:40.523Z,1757444560.523 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-09-09T19:02:40.540Z,1757444560.540 [ThrusterHE] Loaded
2025-09-09T19:02:40.540Z,1757444560.540 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-09-09T19:02:40.540Z,1757444560.540 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-09-09T19:02:40.541Z,1757444560.541 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-09-09T19:02:40.645Z,1757444560.645 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-09-09T19:02:40.645Z,1757444560.645 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-09-09T19:02:40.694Z,1757444560.694 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-09-09T19:02:40.695Z,1757444560.695 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-09-09T19:02:41.124Z,1757444561.124 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-09-09T19:02:41.124Z,1757444561.124 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-09-09T19:02:41.367Z,1757444561.367 [CTD_Seabird] Loaded
2025-09-09T19:02:41.368Z,1757444561.368 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-09-09T19:02:41.369Z,1757444561.369 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BC24E0
2025-09-09T19:02:41.369Z,1757444561.369 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 928
2025-09-09T19:02:41.406Z,1757444561.406 [ESPComponent] Loaded
2025-09-09T19:02:41.407Z,1757444561.407 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2025-09-09T19:02:41.430Z,1757444561.430 [PAR_Licor] Loaded
2025-09-09T19:02:41.430Z,1757444561.430 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-09-09T19:02:41.476Z,1757444561.476 [WetLabsBB2FL] Loaded
2025-09-09T19:02:41.476Z,1757444561.476 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-09-09T19:02:41.477Z,1757444561.477 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BF24E0
2025-09-09T19:02:41.478Z,1757444561.478 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 929
2025-09-09T19:02:41.478Z,1757444561.478 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-09-09T19:02:41.479Z,1757444561.479 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-09-09T19:02:41.688Z,1757444561.688 [SBIT](DEBUG): Construct Startup Built In Test.
2025-09-09T19:02:41.697Z,1757444561.697 [SBIT] Loaded
2025-09-09T19:02:41.698Z,1757444561.698 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-09-09T19:02:41.701Z,1757444561.701 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-09-09T19:02:41.714Z,1757444561.714 [IBIT] Loaded
2025-09-09T19:02:41.714Z,1757444561.714 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-09-09T19:02:41.719Z,1757444561.719 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-09-09T19:02:41.803Z,1757444561.803 [CBIT] Loaded
2025-09-09T19:02:41.804Z,1757444561.804 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-09-09T19:02:41.837Z,1757444561.837 [GFScanner] Loaded
2025-09-09T19:02:41.837Z,1757444561.837 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-09-09T19:02:41.838Z,1757444561.838 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-09-09T19:02:41.844Z,1757444561.844 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-09-09T19:02:41.847Z,1757444561.847 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-09-09T19:02:41.859Z,1757444561.859 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-09-09T19:02:41.860Z,1757444561.860 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C6D4E0
2025-09-09T19:02:41.860Z,1757444561.860 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 930
2025-09-09T19:02:41.865Z,1757444561.865 [Supervisor](INFO): Main Thread ID is 824
2025-09-09T19:02:41.865Z,1757444561.865 [Supervisor](DEBUG): Running supervisor.
2025-09-09T19:02:41.866Z,1757444561.866 [CommandExec ThreadHandler](INFO): Handler Thread ID is 931
2025-09-09T19:02:41.866Z,1757444561.866 [CommandExec](INFO): Initializing the command executive.
2025-09-09T19:02:41.868Z,1757444561.868 [CommandLine ThreadHandler](INFO): Handler Thread ID is 932
2025-09-09T19:02:41.870Z,1757444561.870 [controlThread ThreadHandler](INFO): Handler Thread ID is 933
2025-09-09T19:02:41.870Z,1757444561.870 [controlThread](DEBUG): Initializing ControlThread
2025-09-09T19:02:41.871Z,1757444561.871 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-09-09T19:02:41.871Z,1757444561.871 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-09-09T19:02:41.872Z,1757444561.872 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-09-09T19:02:41.872Z,1757444561.872 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-09-09T19:02:41.873Z,1757444561.873 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-09-09T19:02:41.873Z,1757444561.873 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-09-09T19:02:41.875Z,1757444561.875 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-09-09T19:02:41.875Z,1757444561.875 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-09-09T19:02:41.876Z,1757444561.876 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-09-09T19:02:41.877Z,1757444561.877 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-09-09T19:02:41.877Z,1757444561.877 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-09-09T19:02:41.884Z,1757444561.884 [SBIT](INFO): Initialize SBIT Component.
2025-09-09T19:02:41.885Z,1757444561.885 [SBIT](IMPORTANT): git: 2025-09-02
2025-09-09T19:02:41.885Z,1757444561.885 [SBIT](INFO): git hash: 79ab46e6183b98c19ab2829623a4adc5bf79cefb
2025-09-09T19:02:41.885Z,1757444561.885 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-09-09T19:02:41.886Z,1757444561.886 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Sep 24 11:12:27 PDT 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2025-09-09T19:02:41.887Z,1757444561.887 [SBIT](INFO): Beginning SBIT in 134.000000 seconds.
2025-09-09T19:02:41.888Z,1757444561.888 [IBIT](INFO): Initialize IBIT Component.
2025-09-09T19:02:41.889Z,1757444561.889 [CBIT](DEBUG): Initialize CBIT Component.
2025-09-09T19:02:41.890Z,1757444561.890 [logger ThreadHandler](INFO): Handler Thread ID is 934
2025-09-09T19:02:41.900Z,1757444561.900 [CBIT](DEBUG): Initialized mux pins.
2025-09-09T19:02:41.900Z,1757444561.900 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2025-09-09T19:02:41.900Z,1757444561.900 [CBIT](DEBUG): Initializing the watchdog timer.
2025-09-09T19:02:41.908Z,1757444561.908 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 935
2025-09-09T19:02:41.920Z,1757444561.920 [DAT ThreadHandler](INFO): Handler Thread ID is 936
2025-09-09T19:02:41.921Z,1757444561.921 [DAT](INFO): Powering up
2025-09-09T19:02:41.921Z,1757444561.921 [DAT](DEBUG): Initializing DAT.
2025-09-09T19:02:41.924Z,1757444561.924 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2025-09-09T19:02:41.927Z,1757444561.927 [CBIT](FAULT): Found backtrace file:
1757444418.868216 [MissionManager]: SIGSEGV: address not mapped to object
1757444418.868216 [MissionManager]: Backtrace:
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x1b46d0]
./bin/LRAUV(_ZN11MissionItem10initializeEv+0x2a0) [0x19ab74]
./bin/LRAUV(_ZN9Aggregate10initializeEv+0x12c) [0x18bbec]
./bin/LRAUV(_ZN11MissionItem10initializeEv+0x2a0) [0x19ab74]
./bin/LRAUV(_ZN9Aggregate10initializeEv+0x12c) [0x18bbec]
./bin/LRAUV(_ZN11MissionItem10initializeEv+0x2a0) [0x19ab74]
./bin/LRAUV(_ZN14MissionManager3runEv+0x250) [0x19cb74]
./bin/LRAUV(_ZN9Component15executeRunStateEv+0x5c) [0xef194]
./bin/LRAUV(_ZN7PCaller10PThreadRunEPv+0xc8) [0x1b411c]


2025-09-09T19:02:41.931Z,1757444561.931 [CBIT](FAULT): Renaming backtrace file to Data/backtrace6
2025-09-09T19:02:41.931Z,1757444561.931 [CBIT](DEBUG): Initializing heartbeat.
2025-09-09T19:02:41.931Z,1757444561.931 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 938
2025-09-09T19:02:41.934Z,1757444561.934 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-09-09T19:02:41.941Z,1757444561.941 [Onboard ThreadHandler](INFO): Handler Thread ID is 939
2025-09-09T19:02:41.961Z,1757444561.961 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 940
2025-09-09T19:02:41.980Z,1757444561.980 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 941
2025-09-09T19:02:41.981Z,1757444561.981 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-09-09T19:02:41.985Z,1757444561.985 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 943
2025-09-09T19:02:41.988Z,1757444561.988 [WetLabsBB2FL](INFO): Powering up
2025-09-09T19:02:41.990Z,1757444561.990 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 945
2025-09-09T19:02:41.997Z,1757444561.997 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-09-09T19:02:41.997Z,1757444561.997 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-09-09T19:02:41.997Z,1757444561.997 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-09-09T19:02:41.997Z,1757444561.997 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-09-09T19:02:41.998Z,1757444561.998 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-09-09T19:02:41.998Z,1757444561.998 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-09-09T19:02:41.998Z,1757444561.998 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-09-09T19:02:41.998Z,1757444561.998 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-09-09T19:02:41.000Z,1757444562.000 [CBIT](DEBUG): Deactivating emergency mode.
2025-09-09T19:02:42.040Z,1757444562.040 [CBIT](DEBUG): Backplane powered.
2025-09-09T19:02:42.040Z,1757444562.040 [GFScanner](DEBUG): Initializing GFScanner
2025-09-09T19:02:42.040Z,1757444562.040 [GFScanner](DEBUG): Deactivating GF circuits.
2025-09-09T19:02:42.045Z,1757444562.045 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-09-09T19:02:42.046Z,1757444562.046 [MissionManager](INFO): Loading Mission from file: Missions/underIce/StartupUnder.tl
2025-09-09T19:02:42.046Z,1757444562.046 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/StartupUnder.tl
2025-09-09T19:02:42.052Z,1757444562.052 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/StartupUnder.tl
2025-09-09T19:02:42.077Z,1757444562.077 [MissionManager](DEBUG): TethyslAPI: Missions/underIce/StartupUnder.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="StartupUnder" />

2025-09-09T19:02:42.077Z,1757444562.077 [MissionManager](ERROR): Top node has no child nodes: Mission
2025-09-09T19:02:42.077Z,1757444562.077 [MissionManager](ERROR): Could not load startup mission.
2025-09-09T19:02:42.078Z,1757444562.078 [MissionManager](INFO): Loading Mission from file: Missions/underIce/DefaultWithUndock.tl
2025-09-09T19:02:42.078Z,1757444562.078 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/DefaultWithUndock.tl
2025-09-09T19:02:42.081Z,1757444562.081 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/DefaultWithUndock.tl
2025-09-09T19:02:42.364Z,1757444562.364 [Radio_Surface](INFO): Powering up
2025-09-09T19:02:42.628Z,1757444562.628 [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-09-09T19:02:42.641Z,1757444562.641 [MissionManager](INFO): DefineArg DefaultWithUndock.ElapsedSinceDefaultStarted = 0.000000 min
2025-09-09T19:02:42.645Z,1757444562.645 [MissionManager](INFO): DefineArg DefaultWithUndock.DetachTimeout = 6.000000 h
2025-09-09T19:02:42.658Z,1757444562.658 [MissionManager](INFO): DefineArg DefaultWithUndock.TransponderCode = 50 count
2025-09-09T19:02:42.662Z,1757444562.662 [MissionManager](INFO): DefineArg DefaultWithUndock.TrackingUpdatePeriod = 10.000000 s
2025-09-09T19:02:42.685Z,1757444562.685 [MissionManager](INFO): DefineArg DefaultWithUndock.NumberOfPings = 1.000000 count
2025-09-09T19:02:42.689Z,1757444562.689 [MissionManager](INFO): DefineArg DefaultWithUndock.DriftTimeout = 3.000000 min
2025-09-09T19:02:42.691Z,1757444562.691 [DefaultWithUndock:A.Wait](DEBUG): Construct Wait.
2025-09-09T19:02:42.863Z,1757444562.863 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Construct.
2025-09-09T19:02:42.863Z,1757444562.863 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2025-09-09T19:02:42.930Z,1757444562.930 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-09-09T19:02:42.985Z,1757444562.985 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-09-09T19:02:42.988Z,1757444562.988 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-09-09T19:02:43.011Z,1757444563.011 [DefaultWithUndock:E.Execute](DEBUG): Construct Execute.
2025-09-09T19:02:43.027Z,1757444563.027 [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-09-09T19:02:43.032Z,1757444563.032 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,RDI_Pathfinder,Sonardyne_Nano,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,DockingStepper,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
2025-09-09T19:02:43.040Z,1757444563.040 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-09T19:02:43.184Z,1757444563.184 [Depth_Keller](INFO): Initializing.
2025-09-09T19:02:43.186Z,1757444563.186 [Power24vConverter](INFO): Powering up.
2025-09-09T19:02:43.186Z,1757444563.187 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2025-09-09T19:02:43.192Z,1757444563.192 [Sonardyne_Nano](INFO): Initializing.
2025-09-09T19:02:43.240Z,1757444563.240 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-09-09T19:02:43.274Z,1757444563.274 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-09-09T19:02:43.280Z,1757444563.280 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-09-09T19:02:43.281Z,1757444563.281 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-09-09T19:02:43.288Z,1757444563.288 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-09-09T19:02:43.289Z,1757444563.289 [DockingStepper](DEBUG): Initializing EZServoServo.
2025-09-09T19:02:43.296Z,1757444563.296 [DockingStepper](DEBUG): Initializing DockingStepper.
2025-09-09T19:02:43.297Z,1757444563.297 [MassServo](DEBUG): Initializing EZServoServo.
2025-09-09T19:02:43.304Z,1757444563.304 [MassServo](DEBUG): Initializing MassServo.
2025-09-09T19:02:43.305Z,1757444563.305 [RudderServo](DEBUG): Initializing EZServoServo.
2025-09-09T19:02:43.312Z,1757444563.312 [RudderServo](DEBUG): Initializing RudderServo.
2025-09-09T19:02:43.313Z,1757444563.313 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-09-09T19:02:43.321Z,1757444563.321 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-09-09T19:02:43.323Z,1757444563.323 [CommandExec](FAULT): Scheduling is paused
2025-09-09T19:02:43.323Z,1757444563.323 [CBIT](INFO): Critical error at 20250909T190241
2025-09-09T19:02:43.323Z,1757444563.323 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2025-09-09T19:02:43.474Z,1757444563.474 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2025-09-09T19:02:43.474Z,1757444563.474 [DropWeight] Hardware Fault, FailCount= 1
2025-09-09T19:02:43.474Z,1757444563.474 [DropWeight](ERROR): Hardware Fault
2025-09-09T19:02:43.546Z,1757444563.546 [CBIT](INFO): Critical error at 20250909T190243
2025-09-09T19:02:43.556Z,1757444563.556 [CBIT](ERROR): Hardware Fault in component: DropWeight
2025-09-09T19:02:43.557Z,1757444563.557 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2025-09-09T19:02:43.869Z,1757444563.869 [CBIT](INFO): Critical error at 20250909T190243
2025-09-09T19:02:44.096Z,1757444564.096 [WetLabsBB2FL](INFO): Powering down
2025-09-09T19:02:45.466Z,1757444565.466 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-09-09T19:02:46.720Z,1757444566.720 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2207
2025-09-09T19:02:47.616Z,1757444567.616 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.11
2025-09-09T19:02:47.617Z,1757444567.617 [Radio_Surface](INFO): No local IP found
2025-09-09T19:02:48.821Z,1757444568.821 [ThrusterHE](ERROR): Zero Speed Commanded.
2025-09-09T19:02:54.021Z,1757444574.021 [DAT](INFO): DAT read: 

2025-09-09T19:02:54.022Z,1757444574.022 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2025-09-09T19:02:55.785Z,1757444575.785 [DAT](INFO): DAT read: MF Frequency Band

2025-09-09T19:02:55.786Z,1757444575.786 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2025-09-09T19:02:55.787Z,1757444575.787 [DAT](INFO): DAT read: Sep  9 2025 19:02:46 

2025-09-09T19:02:56.793Z,1757444576.793 [DAT](INFO): DAT read: Features enabled [Bearing]

2025-09-09T19:02:56.794Z,1757444576.794 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP

2025-09-09T19:02:56.794Z,1757444576.794 [DAT](INFO): commRate: 600
2025-09-09T19:02:58.860Z,1757444578.860 [DAT](INFO): entering command mode
2025-09-09T19:02:59.061Z,1757444579.061 [DAT](INFO): DAT read: 

2025-09-09T19:02:59.061Z,1757444579.061 [DAT](INFO): DAT read: user:1>

2025-09-09T19:02:59.062Z,1757444579.062 [DAT](INFO): setting verbose to 3
2025-09-09T19:02:59.313Z,1757444579.313 [DAT](INFO): DAT read: user:1>

2025-09-09T19:02:59.314Z,1757444579.314 [DAT](INFO): DAT read: Verbose         | 3           

2025-09-09T19:02:59.314Z,1757444579.314 [DAT](INFO): set verbose to 3
2025-09-09T19:02:59.314Z,1757444579.314 [DAT](INFO): setting DatVerbose to 27440
2025-09-09T19:02:59.565Z,1757444579.565 [DAT](INFO): DAT read: user:2>

2025-09-09T19:02:59.566Z,1757444579.566 [DAT](INFO): DAT read: DatVerbose      | 27440       

2025-09-09T19:02:59.566Z,1757444579.566 [DAT](INFO): set DatVerbose to 27440
2025-09-09T19:02:59.566Z,1757444579.566 [DAT](INFO): setting transmit power to 8
2025-09-09T19:02:59.817Z,1757444579.817 [DAT](INFO): DAT read: user:3>

2025-09-09T19:02:59.818Z,1757444579.818 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2025-09-09T19:02:59.818Z,1757444579.818 [DAT](INFO): set transmit power to 8
2025-09-09T19:02:59.818Z,1757444579.818 [DAT](INFO): setting local address to 11
2025-09-09T19:03:00.069Z,1757444580.069 [DAT](INFO): DAT read: user:4>

2025-09-09T19:03:00.070Z,1757444580.070 [DAT](INFO): DAT read: LocalAddr       | 11          

2025-09-09T19:03:00.070Z,1757444580.070 [DAT](INFO): set local address to 11
2025-09-09T19:03:00.071Z,1757444580.071 [DAT](INFO): Setting time to: 19:3:0 And date to:9/9/2025
2025-09-09T19:03:00.321Z,1757444580.321 [DAT](INFO): DAT read: user:5>

2025-09-09T19:03:00.322Z,1757444580.322 [DAT](INFO): DAT read: Tue Sep 9, 2025  19:03:00  

2025-09-09T19:03:00.322Z,1757444580.322 [DAT](INFO): Local DAT time set to Tue Sep 9, 2025  19:03:00  

2025-09-09T19:03:10.113Z,1757444590.113 [NAL9602](INFO): Powering up NAL9602
2025-09-09T19:03:21.021Z,1757444601.021 [NAL9602](INFO): NAL9602 initialized
2025-09-09T19:03:54.155Z,1757444634.155 [NAL9602](INFO): SBD MO Status=2, MOMSN=1482, MT Status=2, MTMSN=0
2025-09-09T19:03:54.155Z,1757444634.155 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-09T19:04:11.527Z,1757444651.527 [NAL9602](INFO): SBD MO Status=0, MOMSN=1482, MT Status=0, MTMSN=0
2025-09-09T19:04:11.527Z,1757444651.527 [NAL9602](INFO): No messages in MT queue
2025-09-09T19:04:12.739Z,1757444652.739 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190404.00,A,3646.92329,N,12152.39187,W,0.816,249.33,090925,,,A*76
2025-09-09T19:04:12.742Z,1757444652.742 [NAL9602](INFO): GPS fix at 20250909T190404: (36.782055, -121.873198)
2025-09-09T19:04:44.653Z,1757444684.653 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-09T19:04:51.785Z,1757444691.785 [DAT](INFO): DAT read: user:6>Rx Time:19:04:51.0716

2025-09-09T19:04:51.785Z,1757444691.785 [DAT](INFO): Rx dataTimestamp_ set to:1757444691.785083
2025-09-09T19:04:52.289Z,1757444692.289 [DAT](INFO): DAT read: $Error in header

2025-09-09T19:04:52.289Z,1757444692.289 [DAT](INFO): Received a bad header
2025-09-09T19:04:56.398Z,1757444696.398 [SBIT](IMPORTANT): Beginning Startup BIT
2025-09-09T19:04:56.403Z,1757444696.403 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-09-09T19:05:00.937Z,1757444700.937 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.019776
CHAN A1 (24V): 0.008274
CHAN A2 (12V): -0.000778
CHAN A3 (5V): -0.010781
CHAN B0 (3.3V): -0.000387
CHAN B1 (3.15aV): 0.000216
CHAN B2 (3.15bV): -0.000283
CHAN B3 (GND): -0.009659
OPEN: -0.001860
Full Scale: +/- 1 mA
2025-09-09T19:05:10.540Z,1757444710.540 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:05:21.494Z,1757444721.494 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004454
2025-09-09T19:05:22.492Z,1757444722.492 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:05:31.260Z,1757444731.260 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:05:44.204Z,1757444744.204 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:05:50.029Z,1757444750.029 [SBIT](IMPORTANT): SBIT PASSED
2025-09-09T19:05:50.029Z,1757444750.029 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2025-09-09T19:05:50.407Z,1757444750.407 [MissionManager](IMPORTANT): Started mission DefaultWithUndock
2025-09-09T19:05:50.407Z,1757444750.407 [DefaultWithUndock] Running Loop=1
2025-09-09T19:05:50.407Z,1757444750.407 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock
2025-09-09T19:05:50.408Z,1757444750.408 [DefaultWithUndock:A.Wait] Running Loop=1
2025-09-09T19:05:50.408Z,1757444750.408 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component.
2025-09-09T19:05:48.241Z,1757444748.241 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:05:57.149Z,1757444757.149 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:06:07.261Z,1757444767.261 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:06:20.667Z,1757444780.667 [DefaultWithUndock:A.Wait](INFO): Done Waiting.
2025-09-09T19:06:20.667Z,1757444780.667 [DefaultWithUndock:A.Wait] Stopped
2025-09-09T19:06:20.667Z,1757444780.667 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component.
2025-09-09T19:06:20.667Z,1757444780.667 [DefaultWithUndock:LeaveDock] Running Loop=1
2025-09-09T19:06:20.667Z,1757444780.667 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock
2025-09-09T19:06:20.667Z,1757444780.667 [DefaultWithUndock:LeaveDock:A.] Running Loop=1
2025-09-09T19:06:20.667Z,1757444780.667 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact.
2025-09-09T19:06:20.667Z,1757444780.667 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1
2025-09-09T19:06:20.668Z,1757444780.668 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize.
2025-09-09T19:06:20.668Z,1757444780.668 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2025-09-09T19:06:21.082Z,1757444781.082 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize.
2025-09-09T19:06:21.083Z,1757444781.083 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2025-09-09T19:06:21.084Z,1757444781.084 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached from dock at depth 0.341973 m.
2025-09-09T19:06:21.085Z,1757444781.085 [DefaultWithUndock:LeaveDock:A.] Stopped
2025-09-09T19:06:21.085Z,1757444781.085 [DefaultWithUndock:LeaveDock:A.](DEBUG): Uninitializing TrackAcousticContact.
2025-09-09T19:06:21.520Z,1757444781.520 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer.
2025-09-09T19:06:21.891Z,1757444781.891 [DockingStepper](DEBUG): Initializing EZServoServo.
2025-09-09T19:06:22.009Z,1757444782.009 [DockingStepper](DEBUG): Initializing DockingStepper.
2025-09-09T19:06:34.921Z,1757444794.921 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:06:41.699Z,1757444801.699 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached at range: nan m. Transitioning docking module to standby.
2025-09-09T19:06:42.100Z,1757444802.100 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Docking module at standby.
2025-09-09T19:06:42.517Z,1757444802.517 [DefaultWithUndock:LeaveDock:B.Undock](IMPORTANT): Undocking sequence complete.
2025-09-09T19:06:42.517Z,1757444802.517 [DefaultWithUndock:LeaveDock:B.Undock] Stopped
2025-09-09T19:06:42.517Z,1757444802.517 [DefaultWithUndock:LeaveDock](INFO): Completed DefaultWithUndock:LeaveDock
2025-09-09T19:06:42.518Z,1757444802.518 [DefaultWithUndock:LeaveDock] Stopped
2025-09-09T19:06:42.518Z,1757444802.518 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock
2025-09-09T19:06:42.518Z,1757444802.518 [DefaultWithUndock:SurfaceDefault] Running Loop=1
2025-09-09T19:06:42.518Z,1757444802.518 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault
2025-09-09T19:06:42.518Z,1757444802.518 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Running Loop=1
2025-09-09T19:06:42.518Z,1757444802.518 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-09-09T19:06:42.519Z,1757444802.519 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-09-09T19:06:42.519Z,1757444802.519 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-09-09T19:06:42.519Z,1757444802.519 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-09-09T19:06:42.520Z,1757444802.520 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-09-09T19:06:42.520Z,1757444802.520 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-09-09T19:06:42.520Z,1757444802.520 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-09-09T19:06:42.905Z,1757444802.905 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=1
2025-09-09T19:06:42.906Z,1757444802.906 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-09-09T19:06:42.906Z,1757444802.906 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2025-09-09T19:06:43.314Z,1757444803.314 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-09-09T19:06:44.899Z,1757444804.899 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190644.00,A,3646.90773,N,12152.42455,W,1.322,236.86,090925,,,A*74
2025-09-09T19:06:44.902Z,1757444804.902 [NAL9602](INFO): GPS fix at 20250909T190644: (36.781796, -121.873743)
2025-09-09T19:06:44.922Z,1757444804.922 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2025-09-09T19:06:44.922Z,1757444804.922 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2025-09-09T19:06:45.334Z,1757444805.334 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-09-09T19:06:47.189Z,1757444807.189 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250909T185230/Courier0006.lzma
2025-09-09T19:06:53.210Z,1757444813.210 [DataOverHttps](INFO): Moved sent file to Logs/20250909T185230/Courier0006.lzma.bak
2025-09-09T19:06:53.211Z,1757444813.211 [DataOverHttps](INFO): SBD MOMSN=25955142
2025-09-09T19:07:23.533Z,1757444843.533 [DataOverHttps](INFO): Sending 404 bytes from file Logs/20250909T190228/Courier0000.lzma
2025-09-09T19:07:24.534Z,1757444844.534 [DataOverHttps](INFO): Moved sent file to Logs/20250909T190228/Courier0000.lzma.bak
2025-09-09T19:07:24.535Z,1757444844.535 [DataOverHttps](INFO): SBD MOMSN=25955149
2025-09-09T19:07:30.356Z,1757444850.356 [NAL9602](INFO): SBD MO Status=2, MOMSN=1483, MT Status=2, MTMSN=0
2025-09-09T19:07:30.356Z,1757444850.356 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-09T19:07:36.956Z,1757444856.956 [DataOverHttps](IMPORTANT): SBD MTMSN=20250909T190735
2025-09-09T19:07:45.640Z,1757444865.640 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250909T185230/Express0007.lzma
2025-09-09T19:07:45.643Z,1757444865.643 [DataOverHttps](INFO): Received command: sched "load Science/profile_station.tl;set profile_station.MissionTimeout 10 h;set profile_station.Lat 36.7723 degree;set profile_station.Lon -121.8647 degree;set profile_station.YoYoMinDepth 4 m;set profile_station.YoYoMaxDepth 70 m" 23jsf 1 2
2025-09-09T19:07:45.688Z,1757444865.688 [CommandExec](IMPORTANT): got command schedule "load Science/profile_station.tl;set profile_station.MissionTimeout 10 h;set profile_station.Lat 36.7723 degree;set profile_station.Lon -121.8647 degree;set profile_station.YoYoMinDepth 4 m;set profile_station.YoYoMaxDepth 70 m" 23jsf 1 2.000000
2025-09-09T19:07:45.723Z,1757444865.723 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=23jsf
2025-09-09T19:07:45.724Z,1757444865.724 [CommandExec](IMPORTANT): Scheduled #1 (#1 of 2 with id='23jsf'): "load Science/profile_station.tl;set profile_station.MissionTimeout 10 h;set profile_station.Lat 36.7723 degree;set profile_station.Lon -121.8647 degree;set profile_station.YoYoMinDepth 4 m;set profile_station.YoYoMaxDepth 70 m", AFTER MISSION
2025-09-09T19:07:45.724Z,1757444865.724 [CommandExec](FAULT): Scheduling was paused by an error at 2025-09-09T19:02:43Z
2025-09-09T19:07:46.519Z,1757444866.519 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-09T19:07:46.833Z,1757444866.833 [DataOverHttps](INFO): Moved sent file to Logs/20250909T185230/Express0007.lzma.bak
2025-09-09T19:07:46.834Z,1757444866.834 [DataOverHttps](IMPORTANT): SBD MOMSN=25955163, MTMSN=20250909T190745
2025-09-09T19:07:48.538Z,1757444868.538 [NAL9602](INFO): SBD MO Status=2, MOMSN=1483, MT Status=2, MTMSN=0
2025-09-09T19:07:48.538Z,1757444868.538 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-09T19:07:56.301Z,1757444876.301 [DataOverHttps](INFO): Received command: sched "set profile_station.Speed 0.75 m/s;set profile_station.MaxDepth 80 m;run" 23jsf 2 2
2025-09-09T19:07:56.395Z,1757444876.395 [CommandExec](IMPORTANT): got command schedule "set profile_station.Speed 0.75 m/s;set profile_station.MaxDepth 80 m;run" 23jsf 2 2.000000
2025-09-09T19:07:56.395Z,1757444876.395 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=23jsf
2025-09-09T19:07:56.396Z,1757444876.396 [CommandExec](IMPORTANT): Scheduled #2 (#2 of 2 with id='23jsf'): "set profile_station.Speed 0.75 m/s;set profile_station.MaxDepth 80 m;run", AFTER MISSION
2025-09-09T19:07:56.397Z,1757444876.397 [CommandExec](FAULT): Scheduling was paused by an error at 2025-09-09T19:02:43Z
2025-09-09T19:08:10.948Z,1757444890.948 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250909T190228/Courier0004.lzma
2025-09-09T19:08:11.950Z,1757444891.950 [DataOverHttps](INFO): Moved sent file to Logs/20250909T190228/Courier0004.lzma.bak
2025-09-09T19:08:11.951Z,1757444891.951 [DataOverHttps](INFO): SBD MOMSN=25955175
2025-09-09T19:08:19.269Z,1757444899.269 [NAL9602](INFO): SBD MO Status=2, MOMSN=1483, MT Status=2, MTMSN=0
2025-09-09T19:08:19.269Z,1757444899.269 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-09T19:08:29.596Z,1757444909.596 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250909T190228/Courier0007.lzma
2025-09-09T19:08:30.599Z,1757444910.599 [DataOverHttps](INFO): Moved sent file to Logs/20250909T190228/Courier0007.lzma.bak
2025-09-09T19:08:30.599Z,1757444910.599 [DataOverHttps](IMPORTANT): SBD MOMSN=25955178, MTMSN=20250909T190829
2025-09-09T19:08:39.484Z,1757444919.484 [DataOverHttps](INFO): Received command: schedule clear
2025-09-09T19:08:39.566Z,1757444919.566 [CommandExec](IMPORTANT): got command schedule clear
2025-09-09T19:08:39.567Z,1757444919.567 [CommandExec](IMPORTANT): Cleared 2 scheduled commands.
2025-09-09T19:08:39.891Z,1757444919.891 [NAL9602](INFO): SBD MO Status=0, MOMSN=1483, MT Status=0, MTMSN=0
2025-09-09T19:08:39.891Z,1757444919.891 [NAL9602](INFO): No messages in MT queue
2025-09-09T19:08:48.011Z,1757444928.011 [DataOverHttps](INFO): Sending 1306 bytes from file Logs/20250909T190228/Express0001.lzma
2025-09-09T19:08:49.010Z,1757444929.010 [DataOverHttps](INFO): Moved sent file to Logs/20250909T190228/Express0001.lzma.bak
2025-09-09T19:08:49.011Z,1757444929.011 [DataOverHttps](INFO): SBD MOMSN=25955181
2025-09-09T19:09:05.668Z,1757444945.668 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250909T190228/Courier0010.lzma
2025-09-09T19:09:06.670Z,1757444946.670 [DataOverHttps](INFO): Moved sent file to Logs/20250909T190228/Courier0010.lzma.bak
2025-09-09T19:09:06.671Z,1757444946.671 [DataOverHttps](INFO): SBD MOMSN=25955201
2025-09-09T19:09:10.582Z,1757444950.582 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-09T19:09:23.173Z,1757444963.173 [DataOverHttps](INFO): Sending 391 bytes from file Logs/20250909T190228/Express0005.lzma
2025-09-09T19:09:24.174Z,1757444964.174 [DataOverHttps](INFO): Moved sent file to Logs/20250909T190228/Express0005.lzma.bak
2025-09-09T19:09:24.175Z,1757444964.175 [DataOverHttps](INFO): SBD MOMSN=25955205
2025-09-09T19:09:41.661Z,1757444981.661 [DataOverHttps](INFO): Sending 307 bytes from file Logs/20250909T190228/Express0008.lzma
2025-09-09T19:09:42.662Z,1757444982.662 [DataOverHttps](INFO): Moved sent file to Logs/20250909T190228/Express0008.lzma.bak
2025-09-09T19:09:42.663Z,1757444982.663 [DataOverHttps](INFO): SBD MOMSN=25955212
2025-09-09T19:09:48.214Z,1757444988.214 [DAT](INFO): DAT read: Rx Time:19:09:52.1675

2025-09-09T19:09:48.214Z,1757444988.214 [DAT](INFO): Rx dataTimestamp_ set to:1757444988.213759
2025-09-09T19:09:48.975Z,1757444988.975 [DAT](INFO): DAT read: 19:09:52.1675 LVL= 32752, 32753, 32754, 32755, AGC= 80, IDX= 435,-0.27,-2.841,-2.018,-1.082,-2.013, PHS=-0.726, 0.039, 0.887, RAW= 238.3,  -2.9, CAL= 235.2,  -4.2, ROT= 274.8,   4.2

2025-09-09T19:09:48.976Z,1757444988.976 [DAT](INFO): got valid direction response:
 19:09:52.1675 LVL= 32752, 32753, 32754, 32755, AGC= 80, IDX= 435,-0.27,-2.841,-2.018,-1.082,-2.013, PHS=-0.726, 0.039, 0.887, RAW= 238.3,  -2.9, CAL= 235.2,  -4.2, ROT= 274.8,   4.2

2025-09-09T19:09:48.977Z,1757444988.977 [DAT](INFO): DAT read: $Error in header

2025-09-09T19:09:48.977Z,1757444988.977 [DAT](INFO): Received a bad header
2025-09-09T19:09:48.989Z,1757444988.989 [DAT](INFO): #Rx 1: Read direction message, but no range.
2025-09-09T19:09:48.990Z,1757444988.990 [DAT](INFO): direction in FSK: [0.083453,-0.993817,-0.073238]
2025-09-09T19:10:00.421Z,1757445000.421 [DataOverHttps](INFO): Sending 165 bytes from file Logs/20250909T190228/Express0011.lzma
2025-09-09T19:10:01.428Z,1757445001.428 [DataOverHttps](INFO): Moved sent file to Logs/20250909T190228/Express0011.lzma.bak
2025-09-09T19:10:01.428Z,1757445001.428 [DataOverHttps](INFO): SBD MOMSN=25955219
2025-09-09T19:10:03.144Z,1757445003.144 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2025-09-09T19:10:03.144Z,1757445003.144 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2025-09-09T19:10:03.145Z,1757445003.145 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-09T19:10:11.277Z,1757445011.277 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:10:20.113Z,1757445020.113 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:10:29.097Z,1757445029.097 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:10:37.813Z,1757445037.813 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:10:46.685Z,1757445046.685 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:10:48.474Z,1757445048.474 [DAT](INFO): DAT read: Rx Time:19:10:52.4039

2025-09-09T19:10:48.474Z,1757445048.474 [DAT](INFO): Rx dataTimestamp_ set to:1757445048.473761
2025-09-09T19:10:49.236Z,1757445049.236 [DAT](INFO): DAT read: 19:10:52.4039 LVL= 32752, 32753, 32754, 32755, AGC= 69, IDX= 331,-0.28, 2.700,-2.736,-1.926,-2.761, PHS=-0.720, 0.070, 0.791, RAW= 241.5,  -2.2, CAL= 238.1,  -3.3, ROT= 271.9,   3.3

2025-09-09T19:10:49.237Z,1757445049.237 [DAT](INFO): got valid direction response:
 19:10:52.4039 LVL= 32752, 32753, 32754, 32755, AGC= 69, IDX= 331,-0.28, 2.700,-2.736,-1.926,-2.761, PHS=-0.720, 0.070, 0.791, RAW= 241.5,  -2.2, CAL= 238.1,  -3.3, ROT= 271.9,   3.3

2025-09-09T19:10:49.238Z,1757445049.238 [DAT](INFO): DAT read: 

2025-09-09T19:10:49.238Z,1757445049.238 [DAT](INFO): DAT read: $Packet for address 21

2025-09-09T19:10:49.239Z,1757445049.239 [DAT](INFO): received a packet notification
2025-09-09T19:10:49.253Z,1757445049.253 [DAT](INFO): #Rx 2: Read direction message, but no range.
2025-09-09T19:10:49.254Z,1757445049.254 [DAT](INFO): direction in FSK: [0.033100,-0.997793,-0.057564]
2025-09-09T19:10:55.369Z,1757445055.369 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:11:04.373Z,1757445064.373 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:11:13.129Z,1757445073.129 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:11:17.877Z,1757445077.877 [RDI_Pathfinder](ERROR): DVL uart error: serial timeout
2025-09-09T19:11:17.877Z,1757445077.877 [RDI_Pathfinder] Communications Fault, FailCount= 1
2025-09-09T19:11:17.877Z,1757445077.877 [RDI_Pathfinder](ERROR): Communications Fault
2025-09-09T19:11:17.965Z,1757445077.965 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2025-09-09T19:11:18.317Z,1757445078.317 [RDI_Pathfinder](INFO): Powering down
2025-09-09T19:11:19.081Z,1757445079.081 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2025-09-09T19:11:19.081Z,1757445079.081 [RDI_Pathfinder] No Fault, FailCount= 1
2025-09-09T19:11:19.514Z,1757445079.514 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2025-09-09T19:11:21.777Z,1757445081.777 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:11:30.373Z,1757445090.373 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:11:39.177Z,1757445099.177 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:11:48.474Z,1757445108.474 [DAT](INFO): DAT read: Rx Time:19:11:52.4292

2025-09-09T19:11:48.474Z,1757445108.474 [DAT](INFO): Rx dataTimestamp_ set to:1757445108.473694
2025-09-09T19:11:48.978Z,1757445108.978 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:11:49.234Z,1757445109.234 [DAT](INFO): DAT read: 19:11:52.4292 LVL= 32752, 32753, 32754, 32755, AGC= 68, IDX= 227,-0.48, 1.032, 1.998, 2.741, 1.853, PHS=-0.719, 0.190, 0.844, RAW= 245.4,  -4.7, CAL= 242.8,  -7.7, ROT= 267.2,   7.7

2025-09-09T19:11:49.235Z,1757445109.235 [DAT](INFO): got valid direction response:
 19:11:52.4292 LVL= 32752, 32753, 32754, 32755, AGC= 68, IDX= 227,-0.48, 1.032, 1.998, 2.741, 1.853, PHS=-0.719, 0.190, 0.844, RAW= 245.4,  -4.7, CAL= 242.8,  -7.7, ROT= 267.2,   7.7

2025-09-09T19:11:49.235Z,1757445109.235 [DAT](INFO): DAT read: 

2025-09-09T19:11:49.236Z,1757445109.236 [DAT](INFO): DAT read: $Packet for address 21

2025-09-09T19:11:49.236Z,1757445109.236 [DAT](INFO): received a packet notification
2025-09-09T19:11:49.249Z,1757445109.249 [DAT](INFO): #Rx 3: Read direction message, but no range.
2025-09-09T19:11:49.250Z,1757445109.250 [DAT](INFO): direction in FSK: [-0.048409,-0.989800,-0.133986]
2025-09-09T19:11:50.490Z,1757445110.490 [DAT](INFO): DAT read: Rx Time:19:11:54.3959

2025-09-09T19:11:50.490Z,1757445110.490 [DAT](INFO): Rx dataTimestamp_ set to:1757445110.489754
2025-09-09T19:11:51.010Z,1757445111.010 [DAT](INFO): DAT read: 19:11:54.3959 LVL= 32752, 32753, 32754, 32755, AGC= 81, IDX= 126, 0.38,-1.707,-0.054,-0.484,-0.733, PHS=-0.873, 0.724, 0.205, RAW= 288.6,  -0.8, CAL= 287.6,  -5.9, ROT= 222.4,   5.9

2025-09-09T19:11:51.011Z,1757445111.011 [DAT](INFO): got valid direction response:
 19:11:54.3959 LVL= 32752, 32753, 32754, 32755, AGC= 81, IDX= 126, 0.38,-1.707,-0.054,-0.484,-0.733, PHS=-0.873, 0.724, 0.205, RAW= 288.6,  -0.8, CAL= 287.6,  -5.9, ROT= 222.4,   5.9

2025-09-09T19:11:51.012Z,1757445111.012 [DAT](INFO): DAT read: 

2025-09-09T19:11:51.037Z,1757445111.037 [DAT](INFO): DAT read: $Packet for address 12

2025-09-09T19:11:51.037Z,1757445111.037 [DAT](INFO): received a packet notification
2025-09-09T19:11:51.049Z,1757445111.049 [DAT](INFO): #Rx 4: Read direction message, but no range.
2025-09-09T19:11:51.050Z,1757445111.050 [DAT](INFO): direction in FSK: [-0.734544,-0.670730,-0.102793]
2025-09-09T19:11:57.877Z,1757445117.877 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:12:07.942Z,1757445127.942 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:12:16.781Z,1757445136.781 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:12:25.577Z,1757445145.577 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:12:34.233Z,1757445154.233 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:12:42.833Z,1757445162.833 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:12:48.734Z,1757445168.734 [DAT](INFO): DAT read: Rx Time:19:12:52.6583

2025-09-09T19:12:48.734Z,1757445168.734 [DAT](INFO): Rx dataTimestamp_ set to:1757445168.733706
2025-09-09T19:12:49.245Z,1757445169.245 [DAT](INFO): DAT read: 19:12:52.6583 LVL= 32752, 32753, 32754, 32755, AGC= 73, IDX=  97,-0.24,-0.705, 0.415, 1.017, 0.215, PHS=-0.818, 0.245, 0.758, RAW= 251.4,  -2.7, CAL= 248.1,  -5.7, ROT= 261.9,   5.7

2025-09-09T19:12:49.246Z,1757445169.246 [DAT](INFO): got valid direction response:
 19:12:52.6583 LVL= 32752, 32753, 32754, 32755, AGC= 73, IDX=  97,-0.24,-0.705, 0.415, 1.017, 0.215, PHS=-0.818, 0.245, 0.758, RAW= 251.4,  -2.7, CAL= 248.1,  -5.7, ROT= 261.9,   5.7

2025-09-09T19:12:49.246Z,1757445169.246 [DAT](INFO): DAT read: 

2025-09-09T19:12:49.247Z,1757445169.247 [DAT](INFO): DAT read: $Packet for address 21

2025-09-09T19:12:49.247Z,1757445169.247 [DAT](INFO): received a packet notification
2025-09-09T19:12:49.261Z,1757445169.261 [DAT](INFO): #Rx 5: Read direction message, but no range.
2025-09-09T19:12:49.262Z,1757445169.262 [DAT](INFO): direction in FSK: [-0.140205,-0.985129,-0.099320]
2025-09-09T19:12:50.750Z,1757445170.750 [DAT](INFO): DAT read: Rx Time:19:12:54.6411

2025-09-09T19:12:50.750Z,1757445170.750 [DAT](INFO): Rx dataTimestamp_ set to:1757445170.749760
2025-09-09T19:12:51.258Z,1757445171.258 [DAT](INFO): DAT read: 19:12:54.6411 LVL= 32752, 32753, 32754, 32755, AGC= 80, IDX= 304,-0.02, 0.428, 2.030, 1.698, 1.543, PHS=-1.012, 0.533, 0.112, RAW= 285.3,   5.4, CAL= 283.5,   0.5, ROT= 226.5,  -0.5

2025-09-09T19:12:51.259Z,1757445171.259 [DAT](INFO): got valid direction response:
 19:12:54.6411 LVL= 32752, 32753, 32754, 32755, AGC= 80, IDX= 304,-0.02, 0.428, 2.030, 1.698, 1.543, PHS=-1.012, 0.533, 0.112, RAW= 285.3,   5.4, CAL= 283.5,   0.5, ROT= 226.5,  -0.5

2025-09-09T19:12:51.259Z,1757445171.259 [DAT](INFO): DAT read: 

2025-09-09T19:12:51.260Z,1757445171.260 [DAT](INFO): DAT read: $Packet for address 12

2025-09-09T19:12:51.261Z,1757445171.260 [DAT](INFO): received a packet notification
2025-09-09T19:12:51.273Z,1757445171.273 [DAT](INFO): #Rx 6: Read direction message, but no range.
2025-09-09T19:12:51.274Z,1757445171.274 [DAT](INFO): direction in FSK: [-0.688328,-0.725347,0.008727]
2025-09-09T19:12:51.545Z,1757445171.545 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:13:01.201Z,1757445181.201 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:13:09.897Z,1757445189.897 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:13:18.545Z,1757445198.545 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:13:27.169Z,1757445207.169 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:13:35.882Z,1757445215.882 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:13:44.601Z,1757445224.601 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:13:49.002Z,1757445229.002 [DAT](INFO): DAT read: Rx Time:19:13:52.8456

2025-09-09T19:13:49.002Z,1757445229.002 [DAT](INFO): Rx dataTimestamp_ set to:1757445229.002086
2025-09-09T19:13:49.510Z,1757445229.510 [DAT](INFO): DAT read: 19:13:52.8456 LVL= 32752, 32753, 32754, 32755, AGC= 79, IDX=   2,-0.06,-1.232, 0.096, 0.532,-0.237, PHS=-0.893, 0.378, 0.725, RAW= 258.3,  -2.9, CAL= 255.6,  -7.2, ROT= 254.4,   7.2

2025-09-09T19:13:49.511Z,1757445229.511 [DAT](INFO): got valid direction response:
 19:13:52.8456 LVL= 32752, 32753, 32754, 32755, AGC= 79, IDX=   2,-0.06,-1.232, 0.096, 0.532,-0.237, PHS=-0.893, 0.378, 0.725, RAW= 258.3,  -2.9, CAL= 255.6,  -7.2, ROT= 254.4,   7.2

2025-09-09T19:13:49.511Z,1757445229.511 [DAT](INFO): DAT read: 

2025-09-09T19:13:49.512Z,1757445229.512 [DAT](INFO): DAT read: $Packet for address 21

2025-09-09T19:13:49.512Z,1757445229.512 [DAT](INFO): received a packet notification
2025-09-09T19:13:49.525Z,1757445229.525 [DAT](INFO): #Rx 7: Read direction message, but no range.
2025-09-09T19:13:49.526Z,1757445229.526 [DAT](INFO): direction in FSK: [-0.266800,-0.955568,-0.125333]
2025-09-09T19:13:53.341Z,1757445233.341 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:14:02.177Z,1757445242.177 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:14:10.905Z,1757445250.905 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:14:19.545Z,1757445259.545 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:14:28.277Z,1757445268.277 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:14:36.965Z,1757445276.965 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:14:45.629Z,1757445285.629 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:14:49.246Z,1757445289.246 [DAT](INFO): DAT read: Rx Time:19:14:53.1208

2025-09-09T19:14:49.246Z,1757445289.246 [DAT](INFO): Rx dataTimestamp_ set to:1757445289.245778
2025-09-09T19:14:49.754Z,1757445289.754 [DAT](INFO): DAT read: 19:14:53.1208 LVL= 32752, 32753, 32754, 32755, AGC= 78, IDX= 218,-0.04,-0.217, 1.199, 1.557, 0.718, PHS=-0.832, 0.526, 0.795, RAW= 261.1,  -6.5, CAL= 259.6, -11.8, ROT= 250.4,  11.8

2025-09-09T19:14:49.756Z,1757445289.756 [DAT](INFO): got valid direction response:
 19:14:53.1208 LVL= 32752, 32753, 32754, 32755, AGC= 78, IDX= 218,-0.04,-0.217, 1.199, 1.557, 0.718, PHS=-0.832, 0.526, 0.795, RAW= 261.1,  -6.5, CAL= 259.6, -11.8, ROT= 250.4,  11.8

2025-09-09T19:14:49.756Z,1757445289.756 [DAT](INFO): DAT read: 

2025-09-09T19:14:49.757Z,1757445289.757 [DAT](INFO): DAT read: $Packet for address 21

2025-09-09T19:14:49.757Z,1757445289.757 [DAT](INFO): received a packet notification
2025-09-09T19:14:49.769Z,1757445289.769 [DAT](INFO): #Rx 8: Read direction message, but no range.
2025-09-09T19:14:49.770Z,1757445289.770 [DAT](INFO): direction in FSK: [-0.328363,-0.922149,-0.204496]
2025-09-09T19:14:54.433Z,1757445294.433 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:15:03.089Z,1757445303.089 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:15:03.754Z,1757445303.754 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-09T19:15:03.754Z,1757445303.754 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2025-09-09T19:15:03.754Z,1757445303.754 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-09T19:15:03.754Z,1757445303.754 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Running Loop=1
2025-09-09T19:15:04.108Z,1757445304.108 [DefaultWithUndock:SurfaceDefault:CheckIn:D] Stopped
2025-09-09T19:15:04.108Z,1757445304.108 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Running Loop=1
2025-09-09T19:15:04.515Z,1757445304.515 [DefaultWithUndock:SurfaceDefault:CheckIn:E](IMPORTANT): Default mission has been running for  9.228339 min 
2025-09-09T19:15:04.515Z,1757445304.515 [DefaultWithUndock:SurfaceDefault:CheckIn:E] Stopped
2025-09-09T19:15:04.515Z,1757445304.515 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Completed DefaultWithUndock:SurfaceDefault:CheckIn
2025-09-09T19:15:04.515Z,1757445304.515 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2025-09-09T19:15:04.515Z,1757445304.515 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-09-09T19:15:04.516Z,1757445304.516 [DefaultWithUndock:SurfaceDefault:CheckIn](INFO): Running loop #2
2025-09-09T19:15:04.516Z,1757445304.516 [DefaultWithUndock:SurfaceDefault:CheckIn] Running Loop=2
2025-09-09T19:15:04.516Z,1757445304.516 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::initialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-09-09T19:15:04.516Z,1757445304.516 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Running Loop=1
2025-09-09T19:15:06.523Z,1757445306.523 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191505.00,A,3646.86241,N,12152.52240,W,0.680,246.45,090925,,,A*77
2025-09-09T19:15:06.526Z,1757445306.526 [NAL9602](INFO): GPS fix at 20250909T191505: (36.781040, -121.875373)
2025-09-09T19:15:06.567Z,1757445306.567 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_GPS] Stopped
2025-09-09T19:15:06.567Z,1757445306.567 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Running Loop=1
2025-09-09T19:15:09.322Z,1757445309.322 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250909T190228/Courier0013.lzma
2025-09-09T19:15:10.202Z,1757445310.202 [DataOverHttps](INFO): Moved sent file to Logs/20250909T190228/Courier0013.lzma.bak
2025-09-09T19:15:10.202Z,1757445310.202 [DataOverHttps](INFO): SBD MOMSN=25955235
2025-09-09T19:15:26.621Z,1757445326.621 [DataOverHttps](INFO): Sending 233 bytes from file Logs/20250909T190228/Express0014.lzma
2025-09-09T19:15:27.622Z,1757445327.622 [DataOverHttps](INFO): Moved sent file to Logs/20250909T190228/Express0014.lzma.bak
2025-09-09T19:15:27.623Z,1757445327.623 [DataOverHttps](INFO): SBD MOMSN=25955238
2025-09-09T19:15:29.241Z,1757445329.241 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium] Stopped
2025-09-09T19:15:29.241Z,1757445329.241 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Running Loop=1
2025-09-09T19:15:29.241Z,1757445329.241 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-09T19:15:36.888Z,1757445336.888 [NAL9602](INFO): SBD MO Status=0, MOMSN=1484, MT Status=0, MTMSN=0
2025-09-09T19:15:36.888Z,1757445336.888 [NAL9602](INFO): No messages in MT queue
2025-09-09T19:15:37.042Z,1757445337.042 [DataOverHttps](IMPORTANT): SBD MTMSN=20250909T191536
2025-09-09T19:15:44.656Z,1757445344.656 [DataOverHttps](INFO): Received command: restart sys
2025-09-09T19:15:44.730Z,1757445344.730 [CommandExec](IMPORTANT): got command restart system
2025-09-09T19:15:46.661Z,1757445346.661 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-09-09T19:15:46.837Z,1757445346.837 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-09T19:15:46.837Z,1757445346.837 [CommandExec](INFO): Uninitializing the command executive.
2025-09-09T19:15:46.837Z,1757445346.837 [CommandExec](INFO): Uninitializing the command scheduler.
2025-09-09T19:15:46.837Z,1757445346.837 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-09-09T19:15:46.920Z,1757445346.920 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2025-09-09T19:15:46.921Z,1757445346.921 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-09-09T19:15:46.921Z,1757445346.921 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-09-09T19:15:46.922Z,1757445346.922 [NavChartDb](INFO): Join timeout helper Thread ID is 1005
2025-09-09T19:15:47.093Z,1757445347.093 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-09T19:15:47.093Z,1757445347.093 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-09-09T19:15:47.101Z,1757445347.101 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-09-09T19:15:47.101Z,1757445347.101 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-09-09T19:15:47.101Z,1757445347.101 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1006
2025-09-09T19:15:47.474Z,1757445347.474 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-09T19:15:47.474Z,1757445347.474 [WetLabsBB2FL](INFO): Powering down
2025-09-09T19:15:47.475Z,1757445347.475 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-09-09T19:15:47.492Z,1757445347.492 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-09-09T19:15:47.493Z,1757445347.493 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-09-09T19:15:47.493Z,1757445347.493 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1007
2025-09-09T19:15:47.813Z,1757445347.813 [CTD_Seabird](INFO): Powering down
2025-09-09T19:15:47.825Z,1757445347.825 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-09T19:15:47.825Z,1757445347.825 [CTD_Seabird](INFO): Powering down
2025-09-09T19:15:47.837Z,1757445347.837 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-09-09T19:15:47.857Z,1757445347.857 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-09-09T19:15:47.857Z,1757445347.857 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-09-09T19:15:47.857Z,1757445347.857 [Radio_Surface](INFO): Join timeout helper Thread ID is 1008
2025-09-09T19:15:47.937Z,1757445347.937 [Radio_Surface](INFO): Powering down
2025-09-09T19:15:47.938Z,1757445347.938 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-09T19:15:47.938Z,1757445347.938 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-09-09T19:15:47.957Z,1757445347.957 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-09-09T19:15:47.958Z,1757445347.958 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-09-09T19:15:47.959Z,1757445347.959 [Onboard](INFO): Join timeout helper Thread ID is 1009
2025-09-09T19:15:48.113Z,1757445348.113 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-09T19:15:48.113Z,1757445348.113 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-09-09T19:15:48.120Z,1757445348.120 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-09-09T19:15:48.120Z,1757445348.120 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-09-09T19:15:48.130Z,1757445348.130 [DataOverHttps](INFO): Join timeout helper Thread ID is 1010
2025-09-09T19:15:48.669Z,1757445348.669 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-09T19:15:48.670Z,1757445348.670 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-09-09T19:15:48.689Z,1757445348.689 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2025-09-09T19:15:48.689Z,1757445348.689 [DAT ThreadHandler](INFO): Thread cancelled.
2025-09-09T19:15:48.690Z,1757445348.690 [DAT](INFO): Join timeout helper Thread ID is 1011
2025-09-09T19:15:48.733Z,1757445348.733 [DAT](INFO): Powering down
2025-09-09T19:15:48.805Z,1757445348.805 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-09T19:15:48.806Z,1757445348.806 [DAT](INFO): Powering down
2025-09-09T19:15:48.806Z,1757445348.806 [DAT ThreadHandler](INFO): Thread cancelled.
2025-09-09T19:15:48.825Z,1757445348.825 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2025-09-09T19:15:48.825Z,1757445348.825 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-09-09T19:15:48.826Z,1757445348.826 [BackseatComponent](INFO): Join timeout helper Thread ID is 1012
2025-09-09T19:15:48.909Z,1757445348.909 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-09T19:15:48.909Z,1757445348.909 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-09-09T19:15:48.929Z,1757445348.929 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-09-09T19:15:48.929Z,1757445348.929 [logger ThreadHandler](INFO): Thread cancelled.
2025-09-09T19:15:48.930Z,1757445348.930 [logger](INFO): Join timeout helper Thread ID is 1013
2025-09-09T19:15:48.949Z,1757445348.949 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-09T19:15:48.949Z,1757445348.949 [logger ThreadHandler](INFO): Thread cancelled.
2025-09-09T19:15:48.969Z,1757445348.969 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-09-09T19:15:48.969Z,1757445348.969 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-09-09T19:15:48.970Z,1757445348.970 [CommandLine](INFO): Join timeout helper Thread ID is 1014
2025-09-09T19:15:48.997Z,1757445348.997 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-09T19:15:48.997Z,1757445348.997 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-09-09T19:15:49.013Z,1757445349.013 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-09-09T19:15:49.013Z,1757445349.013 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-09-09T19:15:49.014Z,1757445349.014 [CommandExec](INFO): Join timeout helper Thread ID is 1015
2025-09-09T19:15:49.018Z,1757445349.018 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-09-09T19:15:49.018Z,1757445349.018 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-09-09T19:15:49.018Z,1757445349.018 [controlThread](INFO): Join timeout helper Thread ID is 1016
2025-09-09T19:15:49.041Z,1757445349.041 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-09-09T19:15:49.042Z,1757445349.042 [controlThread](DEBUG): Uninitializing ControlThread
2025-09-09T19:15:49.042Z,1757445349.042 [AHRS_M2](INFO): Powering down
2025-09-09T19:15:49.121Z,1757445349.121 [NAL9602](INFO): Powering down
2025-09-09T19:15:49.193Z,1757445349.193 [RDI_Pathfinder](INFO): Powering down
2025-09-09T19:15:49.194Z,1757445349.194 [Sonardyne_Nano](INFO): Powering down
2025-09-09T19:15:49.401Z,1757445349.401 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-09-09T19:15:49.402Z,1757445349.402 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-09-09T19:15:49.402Z,1757445349.402 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-09-09T19:15:49.403Z,1757445349.403 [MissionManager](INFO): Uninitializing Mission DefaultWithUndock
2025-09-09T19:15:49.403Z,1757445349.403 [DefaultWithUndock] Stopped
2025-09-09T19:15:49.403Z,1757445349.403 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock
2025-09-09T19:15:49.403Z,1757445349.403 [DefaultWithUndock:SurfaceDefault] Stopped
2025-09-09T19:15:49.403Z,1757445349.403 [DefaultWithUndock:SurfaceDefault](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault
2025-09-09T19:15:49.403Z,1757445349.403 [DefaultWithUndock:SurfaceDefault:A.GoToSurface] Stopped
2025-09-09T19:15:49.403Z,1757445349.403 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-09-09T19:15:49.403Z,1757445349.403 [DefaultWithUndock:SurfaceDefault:CheckIn] Stopped
2025-09-09T19:15:49.404Z,1757445349.404 [DefaultWithUndock:SurfaceDefault:CheckIn](DEBUG): Aggregate::uninitialize DefaultWithUndock:SurfaceDefault:CheckIn
2025-09-09T19:15:49.404Z,1757445349.404 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait] Stopped
2025-09-09T19:15:49.404Z,1757445349.404 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-09T19:15:49.413Z,1757445349.413 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-09-09T19:15:49.413Z,1757445349.413 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-09-09T19:15:49.414Z,1757445349.414 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-09-09T19:15:49.414Z,1757445349.414 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-09-09T19:15:49.414Z,1757445349.414 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-09-09T19:15:49.415Z,1757445349.415 [BuoyancyServo](INFO): Powering down
2025-09-09T19:15:49.429Z,1757445349.429 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-09-09T19:15:49.429Z,1757445349.429 [ElevatorServo](INFO): Powering down
2025-09-09T19:15:49.430Z,1757445349.430 [DockingStepper](DEBUG): Uninitialize Docking Stepper.
2025-09-09T19:15:49.430Z,1757445349.430 [DockingStepper](INFO): Powering down
2025-09-09T19:15:49.431Z,1757445349.431 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-09-09T19:15:49.431Z,1757445349.431 [MassServo](INFO): Powering down
2025-09-09T19:15:49.432Z,1757445349.432 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-09-09T19:15:49.432Z,1757445349.432 [RudderServo](INFO): Powering down
2025-09-09T19:15:49.433Z,1757445349.433 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-09-09T19:15:49.433Z,1757445349.433 [ThrusterHE](INFO): Powering down
2025-09-09T19:15:49.434Z,1757445349.434 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-09-09T19:15:49.434Z,1757445349.434 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-09-09T19:15:49.435Z,1757445349.435 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-09-09T19:15:49.435Z,1757445349.435 [CBIT](DEBUG): Powering off loads.
2025-09-09T19:15:49.448Z,1757445349.448 [CBIT](DEBUG): Disabling WDT.
2025-09-09T19:15:49.461Z,1757445349.461 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-09-09T19:15:49.461Z,1757445349.461 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-09-09T19:15:49.462Z,1757445349.462 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-09-09T19:15:49.525Z,1757445349.525 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-09-09T19:15:49.528Z,1757445349.528 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-09-09T19:15:49.535Z,1757445349.535 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-09-09T19:15:49.541Z,1757445349.541 [DAT ThreadHandler](INFO): Thread cancelled.
2025-09-09T19:15:49.587Z,1757445349.587 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-09-09T19:15:49.648Z,1757445349.648 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-09-09T19:15:49.654Z,1757445349.654 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-09-09T19:15:49.663Z,1757445349.663 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-09-09T19:15:49.663Z,1757445349.663 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-09-09T19:15:49.679Z,1757445349.679 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-09-09T19:15:49.756Z,1757445349.756 [logger ThreadHandler](INFO): Thread cancelled.