2025-12-07T07:10:59.709Z,1765091459.709 [Supervisor](DEBUG): Initializing supervisor. 2025-12-07T07:10:59.736Z,1765091459.736 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2025-12-07T07:10:59.737Z,1765091459.737 [SyncHandler](INFO): Protected caller Thread ID is 832 2025-12-07T07:10:59.738Z,1765091459.738 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2025-12-07T07:10:59.764Z,1765091459.764 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2025-12-07T07:10:59.765Z,1765091459.765 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 833 2025-12-07T07:10:59.783Z,1765091459.783 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2025-12-07T07:10:59.848Z,1765091459.848 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2025-12-07T07:10:59.852Z,1765091459.852 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2025-12-07T07:10:59.853Z,1765091459.853 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 834 2025-12-07T07:10:59.879Z,1765091459.879 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2025-12-07T07:10:59.881Z,1765091459.881 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2025-12-07T07:10:59.881Z,1765091459.881 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 835 2025-12-07T07:10:59.895Z,1765091459.895 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2025-12-07T07:10:59.900Z,1765091459.900 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2025-12-07T07:10:59.901Z,1765091459.901 [logger ThreadHandler](INFO): Protected caller Thread ID is 836 2025-12-07T07:10:59.905Z,1765091459.905 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2025-12-07T07:10:59.905Z,1765091459.905 [Supervisor](INFO): Looking for Config files in directory: Config/ 2025-12-07T07:10:59.915Z,1765091459.915 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2025-12-07T07:11:00.811Z,1765091460.811 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2025-12-07T07:11:00.812Z,1765091460.812 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2025-12-07T07:11:02.292Z,1765091462.292 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2025-12-07T07:11:02.293Z,1765091462.293 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2025-12-07T07:11:02.482Z,1765091462.482 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2025-12-07T07:11:02.483Z,1765091462.483 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2025-12-07T07:11:03.283Z,1765091463.283 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2025-12-07T07:11:03.284Z,1765091463.284 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2025-12-07T07:11:03.389Z,1765091463.389 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2025-12-07T07:11:03.389Z,1765091463.389 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2025-12-07T07:11:03.488Z,1765091463.488 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance 2025-12-07T07:11:03.489Z,1765091463.489 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2025-12-07T07:11:03.895Z,1765091463.895 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2025-12-07T07:11:03.896Z,1765091463.896 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2025-12-07T07:11:04.119Z,1765091464.119 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2025-12-07T07:11:04.119Z,1765091464.119 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2025-12-07T07:11:04.264Z,1765091464.264 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2025-12-07T07:11:04.265Z,1765091464.265 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2025-12-07T07:11:04.516Z,1765091464.516 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2025-12-07T07:11:04.516Z,1765091464.516 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2025-12-07T07:11:05.078Z,1765091465.078 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2025-12-07T07:11:05.079Z,1765091465.079 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2025-12-07T07:11:05.465Z,1765091465.465 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2025-12-07T07:11:05.466Z,1765091465.466 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2025-12-07T07:11:05.553Z,1765091465.553 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2025-12-07T07:11:05.738Z,1765091465.738 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2025-12-07T07:11:05.738Z,1765091465.738 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2025-12-07T07:11:05.986Z,1765091465.986 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2025-12-07T07:11:05.986Z,1765091465.986 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2025-12-07T07:11:06.283Z,1765091466.283 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2025-12-07T07:11:06.285Z,1765091466.285 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/ 2025-12-07T07:11:06.288Z,1765091466.288 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg 2025-12-07T07:11:06.394Z,1765091466.394 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg 2025-12-07T07:11:06.549Z,1765091466.549 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg 2025-12-07T07:11:06.642Z,1765091466.642 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg 2025-12-07T07:11:06.733Z,1765091466.733 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg 2025-12-07T07:11:06.851Z,1765091466.851 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/workSite.cfg 2025-12-07T07:11:06.952Z,1765091466.952 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Dock.cfg 2025-12-07T07:11:07.070Z,1765091467.070 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg 2025-12-07T07:11:07.181Z,1765091467.181 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg 2025-12-07T07:11:07.455Z,1765091467.455 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2025-12-07T07:11:07.456Z,1765091467.456 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg 2025-12-07T07:11:07.631Z,1765091467.631 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg 2025-12-07T07:11:08.217Z,1765091468.217 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg 2025-12-07T07:11:08.808Z,1765091468.808 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg 2025-12-07T07:11:08.901Z,1765091468.901 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2025-12-07T07:11:08.904Z,1765091468.904 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2025-12-07T07:11:09.072Z,1765091469.072 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2025-12-07T07:11:09.073Z,1765091469.073 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2025-12-07T07:11:09.089Z,1765091469.089 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2025-12-07T07:11:09.089Z,1765091469.089 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2025-12-07T07:11:09.161Z,1765091469.161 [DepthRateCalculator] Loaded 2025-12-07T07:11:09.161Z,1765091469.161 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2025-12-07T07:11:09.167Z,1765091469.167 [PitchRateCalculator] Loaded 2025-12-07T07:11:09.167Z,1765091469.167 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2025-12-07T07:11:09.178Z,1765091469.178 [SpeedCalculator] Loaded 2025-12-07T07:11:09.178Z,1765091469.178 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2025-12-07T07:11:09.183Z,1765091469.183 [YawRateCalculator] Loaded 2025-12-07T07:11:09.183Z,1765091469.183 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2025-12-07T07:11:09.205Z,1765091469.205 [ElevatorOffsetCalculator] Loaded 2025-12-07T07:11:09.205Z,1765091469.205 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2025-12-07T07:11:09.205Z,1765091469.205 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2025-12-07T07:11:09.206Z,1765091469.206 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2025-12-07T07:11:09.277Z,1765091469.277 [VerticalControl](DEBUG): Construct VerticalControl. 2025-12-07T07:11:09.333Z,1765091469.333 [VerticalControl] Loaded 2025-12-07T07:11:09.333Z,1765091469.333 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2025-12-07T07:11:09.336Z,1765091469.336 [HorizontalControl](DEBUG): Construct HorizontalControl. 2025-12-07T07:11:09.384Z,1765091469.384 [HorizontalControl] Loaded 2025-12-07T07:11:09.384Z,1765091469.384 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2025-12-07T07:11:09.387Z,1765091469.387 [SpeedControl](DEBUG): Construct SpeedControl. 2025-12-07T07:11:09.390Z,1765091469.390 [SpeedControl] Loaded 2025-12-07T07:11:09.390Z,1765091469.390 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2025-12-07T07:11:09.392Z,1765091469.392 [LoopControl](DEBUG): Construct LoopControl. 2025-12-07T07:11:09.393Z,1765091469.393 [LoopControl] Loaded 2025-12-07T07:11:09.393Z,1765091469.393 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2025-12-07T07:11:09.394Z,1765091469.394 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2025-12-07T07:11:09.394Z,1765091469.394 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2025-12-07T07:11:09.532Z,1765091469.532 [DeadReckonUsingMultipleVelocitySources] Loaded 2025-12-07T07:11:09.533Z,1765091469.533 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2025-12-07T07:11:09.546Z,1765091469.546 [NavChart] Loaded 2025-12-07T07:11:09.546Z,1765091469.546 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2025-12-07T07:11:09.552Z,1765091469.552 [UniversalFixResidualReporter] Loaded 2025-12-07T07:11:09.552Z,1765091469.552 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2025-12-07T07:11:09.553Z,1765091469.553 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2025-12-07T07:11:09.553Z,1765091469.553 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2025-12-07T07:11:09.767Z,1765091469.767 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2025-12-07T07:11:09.768Z,1765091469.768 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2025-12-07T07:11:10.603Z,1765091470.603 [AHRS_M2] Loaded 2025-12-07T07:11:10.604Z,1765091470.604 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2025-12-07T07:11:10.871Z,1765091470.871 [BackseatComponent] Loaded 2025-12-07T07:11:10.872Z,1765091470.872 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2025-12-07T07:11:10.873Z,1765091470.873 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409404E0 2025-12-07T07:11:10.873Z,1765091470.873 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 924 2025-12-07T07:11:10.877Z,1765091470.877 [LcmPublisher] Loaded 2025-12-07T07:11:10.877Z,1765091470.877 [ComponentRegistry](DEBUG): SyncComponent "LcmPublisher" handled in the control thread. 2025-12-07T07:11:11.717Z,1765091471.717 [BPC1] Loaded 2025-12-07T07:11:11.717Z,1765091471.717 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2025-12-07T07:11:11.816Z,1765091471.816 [DAT] Loaded 2025-12-07T07:11:11.816Z,1765091471.816 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2025-12-07T07:11:11.817Z,1765091471.817 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409704E0 2025-12-07T07:11:11.817Z,1765091471.817 [DAT ThreadHandler](INFO): Protected caller Thread ID is 925 2025-12-07T07:11:11.958Z,1765091471.958 [DataOverHttps] Loaded 2025-12-07T07:11:11.958Z,1765091471.958 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2025-12-07T07:11:11.959Z,1765091471.959 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409A04E0 2025-12-07T07:11:11.959Z,1765091471.959 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 926 2025-12-07T07:11:11.982Z,1765091471.982 [Depth_Keller] Loaded 2025-12-07T07:11:11.983Z,1765091471.983 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2025-12-07T07:11:11.988Z,1765091471.988 [DropWeight] Loaded 2025-12-07T07:11:11.988Z,1765091471.988 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2025-12-07T07:11:12.052Z,1765091472.052 [NAL9602] Loaded 2025-12-07T07:11:12.052Z,1765091472.052 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2025-12-07T07:11:12.082Z,1765091472.082 [Onboard] Loaded 2025-12-07T07:11:12.082Z,1765091472.082 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2025-12-07T07:11:12.083Z,1765091472.083 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409D04E0 2025-12-07T07:11:12.083Z,1765091472.083 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 927 2025-12-07T07:11:12.098Z,1765091472.098 [Power24vConverter] Loaded 2025-12-07T07:11:12.098Z,1765091472.098 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2025-12-07T07:11:12.114Z,1765091472.114 [Radio_Surface] Loaded 2025-12-07T07:11:12.114Z,1765091472.114 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2025-12-07T07:11:12.115Z,1765091472.115 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A004E0 2025-12-07T07:11:12.115Z,1765091472.115 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 928 2025-12-07T07:11:12.162Z,1765091472.162 [RDI_Pathfinder] Loaded 2025-12-07T07:11:12.162Z,1765091472.162 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2025-12-07T07:11:12.178Z,1765091472.178 [Sonardyne_Nano] Loaded 2025-12-07T07:11:12.178Z,1765091472.178 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2025-12-07T07:11:12.179Z,1765091472.179 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2025-12-07T07:11:12.179Z,1765091472.179 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2025-12-07T07:11:12.343Z,1765091472.343 [BuoyancyServo] Loaded 2025-12-07T07:11:12.343Z,1765091472.343 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2025-12-07T07:11:12.367Z,1765091472.367 [ElevatorServo] Loaded 2025-12-07T07:11:12.367Z,1765091472.367 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2025-12-07T07:11:12.398Z,1765091472.398 [DockingStepper] Loaded 2025-12-07T07:11:12.398Z,1765091472.398 [ComponentRegistry](DEBUG): SyncComponent "DockingStepper" handled in the control thread. 2025-12-07T07:11:12.418Z,1765091472.418 [MassServo] Loaded 2025-12-07T07:11:12.418Z,1765091472.418 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2025-12-07T07:11:12.439Z,1765091472.439 [RudderServo] Loaded 2025-12-07T07:11:12.439Z,1765091472.439 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2025-12-07T07:11:12.455Z,1765091472.455 [ThrusterHE] Loaded 2025-12-07T07:11:12.456Z,1765091472.456 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2025-12-07T07:11:12.456Z,1765091472.456 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2025-12-07T07:11:12.457Z,1765091472.457 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2025-12-07T07:11:12.545Z,1765091472.545 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2025-12-07T07:11:12.545Z,1765091472.545 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2025-12-07T07:11:12.594Z,1765091472.594 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2025-12-07T07:11:12.594Z,1765091472.594 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2025-12-07T07:11:13.053Z,1765091473.053 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2025-12-07T07:11:13.053Z,1765091473.053 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2025-12-07T07:11:13.275Z,1765091473.275 [CTD_Seabird] Loaded 2025-12-07T07:11:13.275Z,1765091473.275 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2025-12-07T07:11:13.277Z,1765091473.277 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BC24E0 2025-12-07T07:11:13.277Z,1765091473.277 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 929 2025-12-07T07:11:13.313Z,1765091473.313 [ESPComponent] Loaded 2025-12-07T07:11:13.313Z,1765091473.313 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2025-12-07T07:11:13.336Z,1765091473.336 [PAR_Licor] Loaded 2025-12-07T07:11:13.336Z,1765091473.336 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2025-12-07T07:11:13.382Z,1765091473.382 [WetLabsBB2FL] Loaded 2025-12-07T07:11:13.382Z,1765091473.382 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2025-12-07T07:11:13.383Z,1765091473.383 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BF24E0 2025-12-07T07:11:13.384Z,1765091473.384 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 930 2025-12-07T07:11:13.385Z,1765091473.385 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2025-12-07T07:11:13.385Z,1765091473.385 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2025-12-07T07:11:13.578Z,1765091473.578 [SBIT](DEBUG): Construct Startup Built In Test. 2025-12-07T07:11:13.587Z,1765091473.587 [SBIT] Loaded 2025-12-07T07:11:13.587Z,1765091473.587 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2025-12-07T07:11:13.590Z,1765091473.590 [IBIT](DEBUG): Construct Initiated Built In Test. 2025-12-07T07:11:13.603Z,1765091473.603 [IBIT] Loaded 2025-12-07T07:11:13.603Z,1765091473.603 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2025-12-07T07:11:13.609Z,1765091473.609 [CBIT](DEBUG): Construct Continuous Built In Test. 2025-12-07T07:11:13.690Z,1765091473.690 [CBIT] Loaded 2025-12-07T07:11:13.690Z,1765091473.690 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2025-12-07T07:11:13.722Z,1765091473.722 [GFScanner] Loaded 2025-12-07T07:11:13.723Z,1765091473.723 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread. 2025-12-07T07:11:13.723Z,1765091473.723 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2025-12-07T07:11:13.730Z,1765091473.730 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2025-12-07T07:11:13.733Z,1765091473.733 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2025-12-07T07:11:13.744Z,1765091473.744 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2025-12-07T07:11:13.745Z,1765091473.745 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C6D4E0 2025-12-07T07:11:13.745Z,1765091473.745 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 931 2025-12-07T07:11:13.749Z,1765091473.749 [Supervisor](INFO): Main Thread ID is 825 2025-12-07T07:11:13.749Z,1765091473.749 [Supervisor](DEBUG): Running supervisor. 2025-12-07T07:11:13.750Z,1765091473.750 [CommandExec ThreadHandler](INFO): Handler Thread ID is 932 2025-12-07T07:11:13.751Z,1765091473.751 [CommandExec](INFO): Initializing the command executive. 2025-12-07T07:11:13.752Z,1765091473.752 [CommandLine ThreadHandler](INFO): Handler Thread ID is 933 2025-12-07T07:11:13.754Z,1765091473.754 [controlThread ThreadHandler](INFO): Handler Thread ID is 934 2025-12-07T07:11:13.755Z,1765091473.755 [controlThread](DEBUG): Initializing ControlThread 2025-12-07T07:11:13.756Z,1765091473.756 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2025-12-07T07:11:13.756Z,1765091473.756 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2025-12-07T07:11:13.756Z,1765091473.756 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2025-12-07T07:11:13.757Z,1765091473.757 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2025-12-07T07:11:13.757Z,1765091473.757 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2025-12-07T07:11:13.758Z,1765091473.758 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2025-12-07T07:11:13.759Z,1765091473.759 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2025-12-07T07:11:13.760Z,1765091473.760 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2025-12-07T07:11:13.760Z,1765091473.760 [LoopControl](DEBUG): Initialize LoopControlComponent. 2025-12-07T07:11:13.761Z,1765091473.761 [NavChart](DEBUG): Initialize NavChart Navigation. 2025-12-07T07:11:13.762Z,1765091473.762 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2025-12-07T07:11:13.768Z,1765091473.768 [SBIT](INFO): Initialize SBIT Component. 2025-12-07T07:11:13.769Z,1765091473.769 [SBIT](IMPORTANT): git: 2025-12-04_A 2025-12-07T07:11:13.769Z,1765091473.769 [SBIT](INFO): git hash: ef8e2bb979844518d8925ddee7860b1c5088e3f5 2025-12-07T07:11:13.769Z,1765091473.769 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2025-12-07T07:11:13.770Z,1765091473.770 [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-12-07T07:11:13.771Z,1765091473.771 [SBIT](INFO): Beginning SBIT in 134.000000 seconds. 2025-12-07T07:11:13.772Z,1765091473.772 [IBIT](INFO): Initialize IBIT Component. 2025-12-07T07:11:13.773Z,1765091473.773 [CBIT](DEBUG): Initialize CBIT Component. 2025-12-07T07:11:13.774Z,1765091473.774 [logger ThreadHandler](INFO): Handler Thread ID is 935 2025-12-07T07:11:13.784Z,1765091473.784 [CBIT](DEBUG): Initialized mux pins. 2025-12-07T07:11:13.784Z,1765091473.784 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2025-12-07T07:11:13.784Z,1765091473.784 [CBIT](DEBUG): Initializing the watchdog timer. 2025-12-07T07:11:13.792Z,1765091473.792 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 936 2025-12-07T07:11:13.804Z,1765091473.804 [DAT ThreadHandler](INFO): Handler Thread ID is 937 2025-12-07T07:11:13.805Z,1765091473.805 [DAT](INFO): Powering up 2025-12-07T07:11:13.805Z,1765091473.805 [DAT](DEBUG): Initializing DAT. 2025-12-07T07:11:13.808Z,1765091473.808 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET. 2025-12-07T07:11:13.808Z,1765091473.808 [CBIT](DEBUG): Initializing heartbeat. 2025-12-07T07:11:13.810Z,1765091473.810 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 939 2025-12-07T07:11:13.812Z,1765091473.812 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2025-12-07T07:11:13.821Z,1765091473.821 [Onboard ThreadHandler](INFO): Handler Thread ID is 940 2025-12-07T07:11:13.838Z,1765091473.838 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 941 2025-12-07T07:11:13.857Z,1765091473.857 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 942 2025-12-07T07:11:13.857Z,1765091473.857 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2025-12-07T07:11:13.861Z,1765091473.861 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 944 2025-12-07T07:11:13.864Z,1765091473.864 [WetLabsBB2FL](INFO): Powering up 2025-12-07T07:11:13.865Z,1765091473.865 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 946 2025-12-07T07:11:13.873Z,1765091473.873 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2025-12-07T07:11:13.873Z,1765091473.873 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2025-12-07T07:11:13.873Z,1765091473.873 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2025-12-07T07:11:13.873Z,1765091473.873 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2025-12-07T07:11:13.874Z,1765091473.874 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2025-12-07T07:11:13.874Z,1765091473.874 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2025-12-07T07:11:13.874Z,1765091473.874 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2025-12-07T07:11:13.874Z,1765091473.874 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2025-12-07T07:11:13.880Z,1765091473.880 [CBIT](DEBUG): Deactivating emergency mode. 2025-12-07T07:11:13.920Z,1765091473.920 [CBIT](DEBUG): Backplane powered. 2025-12-07T07:11:13.920Z,1765091473.920 [GFScanner](DEBUG): Initializing GFScanner 2025-12-07T07:11:13.920Z,1765091473.920 [GFScanner](DEBUG): Deactivating GF circuits. 2025-12-07T07:11:13.925Z,1765091473.925 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2025-12-07T07:11:13.926Z,1765091473.926 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2025-12-07T07:11:13.926Z,1765091473.926 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2025-12-07T07:11:13.930Z,1765091473.930 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2025-12-07T07:11:13.992Z,1765091473.992 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2025-12-07T07:11:14.005Z,1765091474.005 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2025-12-07T07:11:14.037Z,1765091474.037 [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 Startup { behavior Guidance:GoToSurface { run in progression } aggregate StartupSatComms { run in sequence readDatum { timeout duration=P1M Universal:latitude_fix } readDatum { timeout duration=P1M Universal:platform_communications } } } 2025-12-07T07:11:14.038Z,1765091474.038 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2025-12-07T07:11:14.038Z,1765091474.038 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2025-12-07T07:11:14.040Z,1765091474.040 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2025-12-07T07:11:14.244Z,1765091474.244 [Radio_Surface](INFO): Powering up 2025-12-07T07:11:14.338Z,1765091474.338 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into: 0 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2025-12-07T07:11:14.373Z,1765091474.373 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2025-12-07T07:11:14.374Z,1765091474.374 [Default:A.Wait](DEBUG): Construct Wait. 2025-12-07T07:11:14.384Z,1765091474.384 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-12-07T07:11:14.421Z,1765091474.421 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2025-12-07T07:11:14.423Z,1765091474.423 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2025-12-07T07:11:14.449Z,1765091474.449 [Default:E.Execute](DEBUG): Construct Execute. 2025-12-07T07:11:14.468Z,1765091474.468 [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 Default { arguments { ElapsedSinceDefaultStarted = 0 minute } 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 = 13 second } 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=P4H { 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-12-07T07:11:14.473Z,1765091474.473 [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,LcmPublisher,Reporter,LogSplitter, 2025-12-07T07:11:14.500Z,1765091474.500 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2025-12-07T07:11:14.664Z,1765091474.664 [Depth_Keller](INFO): Initializing. 2025-12-07T07:11:14.666Z,1765091474.666 [Power24vConverter](INFO): Powering up. 2025-12-07T07:11:14.666Z,1765091474.666 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2025-12-07T07:11:14.669Z,1765091474.669 [Sonardyne_Nano](INFO): Initializing. 2025-12-07T07:11:14.712Z,1765091474.712 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-12-07T07:11:14.738Z,1765091474.738 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-12-07T07:11:14.744Z,1765091474.744 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-12-07T07:11:14.745Z,1765091474.745 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-12-07T07:11:14.752Z,1765091474.752 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-12-07T07:11:14.753Z,1765091474.753 [DockingStepper](DEBUG): Initializing EZServoServo. 2025-12-07T07:11:14.760Z,1765091474.760 [DockingStepper](DEBUG): Initializing DockingStepper. 2025-12-07T07:11:14.761Z,1765091474.761 [MassServo](DEBUG): Initializing EZServoServo. 2025-12-07T07:11:14.768Z,1765091474.768 [MassServo](DEBUG): Initializing MassServo. 2025-12-07T07:11:14.769Z,1765091474.769 [RudderServo](DEBUG): Initializing EZServoServo. 2025-12-07T07:11:14.776Z,1765091474.776 [RudderServo](DEBUG): Initializing RudderServo. 2025-12-07T07:11:14.777Z,1765091474.777 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-12-07T07:11:14.784Z,1765091474.784 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-12-07T07:11:14.786Z,1765091474.786 [CommandExec](FAULT): Scheduling is paused 2025-12-07T07:11:14.787Z,1765091474.787 [CBIT](INFO): Critical error at 20251207T071113 2025-12-07T07:11:14.787Z,1765091474.787 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2025-12-07T07:11:14.922Z,1765091474.922 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-12-07T07:11:14.922Z,1765091474.922 [DropWeight] Hardware Fault, FailCount= 1 2025-12-07T07:11:14.922Z,1765091474.922 [DropWeight](ERROR): Hardware Fault 2025-12-07T07:11:14.959Z,1765091474.959 [CBIT](INFO): Critical error at 20251207T071114 2025-12-07T07:11:14.970Z,1765091474.970 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-12-07T07:11:14.970Z,1765091474.970 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-12-07T07:11:15.310Z,1765091475.310 [CBIT](INFO): Critical error at 20251207T071114 2025-12-07T07:11:15.972Z,1765091475.972 [WetLabsBB2FL](INFO): Powering down 2025-12-07T07:11:16.906Z,1765091476.906 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2025-12-07T07:11:18.157Z,1765091478.157 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2207 2025-12-07T07:11:19.497Z,1765091479.497 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.11 2025-12-07T07:11:19.497Z,1765091479.497 [Radio_Surface](INFO): No local IP found 2025-12-07T07:11:20.365Z,1765091480.365 [ThrusterHE](ERROR): Zero Speed Commanded. 2025-12-07T07:11:25.653Z,1765091485.653 [DAT](INFO): DAT read: 2025-12-07T07:11:25.654Z,1765091485.654 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2025-12-07T07:11:27.165Z,1765091487.165 [DAT](INFO): DAT read: MF Frequency Band 2025-12-07T07:11:27.166Z,1765091487.166 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2025-12-07T07:11:27.167Z,1765091487.167 [DAT](INFO): DAT read: Dec 7 2025 07:11:20 2025-12-07T07:11:28.173Z,1765091488.173 [DAT](INFO): DAT read: Features enabled [Bearing] 2025-12-07T07:11:28.174Z,1765091488.174 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP 2025-12-07T07:11:28.175Z,1765091488.175 [DAT](INFO): commRate: 600 2025-12-07T07:11:30.240Z,1765091490.240 [DAT](INFO): entering command mode 2025-12-07T07:11:30.440Z,1765091490.440 [DAT](INFO): DAT read: 2025-12-07T07:11:30.441Z,1765091490.441 [DAT](INFO): DAT read: user:1> 2025-12-07T07:11:30.441Z,1765091490.441 [DAT](INFO): setting verbose to 3 2025-12-07T07:11:30.693Z,1765091490.693 [DAT](INFO): DAT read: user:1> 2025-12-07T07:11:30.694Z,1765091490.694 [DAT](INFO): DAT read: Verbose | 3 2025-12-07T07:11:30.694Z,1765091490.694 [DAT](INFO): set verbose to 3 2025-12-07T07:11:30.695Z,1765091490.695 [DAT](INFO): setting DatVerbose to 27440 2025-12-07T07:11:30.945Z,1765091490.945 [DAT](INFO): DAT read: user:2> 2025-12-07T07:11:30.946Z,1765091490.946 [DAT](INFO): DAT read: DatVerbose | 27440 2025-12-07T07:11:30.946Z,1765091490.946 [DAT](INFO): set DatVerbose to 27440 2025-12-07T07:11:30.946Z,1765091490.946 [DAT](INFO): setting transmit power to 8 2025-12-07T07:11:31.197Z,1765091491.197 [DAT](INFO): DAT read: user:3> 2025-12-07T07:11:31.198Z,1765091491.198 [DAT](INFO): DAT read: TxPower | 8 (Max) 2025-12-07T07:11:31.198Z,1765091491.198 [DAT](INFO): set transmit power to 8 2025-12-07T07:11:31.198Z,1765091491.198 [DAT](INFO): setting local address to 11 2025-12-07T07:11:31.449Z,1765091491.449 [DAT](INFO): DAT read: user:4> 2025-12-07T07:11:31.450Z,1765091491.450 [DAT](INFO): DAT read: LocalAddr | 11 2025-12-07T07:11:31.450Z,1765091491.450 [DAT](INFO): set local address to 11 2025-12-07T07:11:31.451Z,1765091491.451 [DAT](INFO): Setting time to: 7:11:31 And date to:12/7/2025 2025-12-07T07:11:31.701Z,1765091491.701 [DAT](INFO): DAT read: user:5> 2025-12-07T07:11:31.702Z,1765091491.702 [DAT](INFO): DAT read: Sun Dec 7, 2025 07:11:31 2025-12-07T07:11:31.702Z,1765091491.702 [DAT](INFO): Local DAT time set to Sun Dec 7, 2025 07:11:31 2025-12-07T07:11:42.361Z,1765091502.361 [NAL9602](INFO): Powering up NAL9602 2025-12-07T07:11:53.277Z,1765091513.277 [NAL9602](INFO): NAL9602 initialized 2025-12-07T07:12:31.655Z,1765091551.655 [NAL9602](INFO): SBD MO Status=0, MOMSN=4629, MT Status=0, MTMSN=0 2025-12-07T07:12:31.655Z,1765091551.655 [NAL9602](INFO): No messages in MT queue 2025-12-07T07:12:32.867Z,1765091552.867 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,071227.00,A,3642.09046,N,12156.12552,W,0.719,267.65,071225,,,A*79 2025-12-07T07:12:32.871Z,1765091552.871 [NAL9602](INFO): GPS fix at 20251207T071227: (36.701508, -121.935425) 2025-12-07T07:13:05.189Z,1765091585.189 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-07T07:13:28.230Z,1765091608.230 [SBIT](IMPORTANT): Beginning Startup BIT 2025-12-07T07:13:28.235Z,1765091608.235 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-12-07T07:13:32.960Z,1765091612.960 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.000745 CHAN A1 (24V): 0.000048 CHAN A2 (12V): -0.000225 CHAN A3 (5V): -0.000840 CHAN B0 (3.3V): 0.000146 CHAN B1 (3.15aV): 0.000786 CHAN B2 (3.15bV): 0.000815 CHAN B3 (GND): 0.000173 OPEN: 0.005942 Full Scale: +/- 1 mA 2025-12-07T07:13:39.437Z,1765091619.437 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:13:56.242Z,1765091636.242 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004298 2025-12-07T07:13:57.242Z,1765091637.242 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:14:05.952Z,1765091645.952 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:14:14.750Z,1765091654.750 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:14:21.597Z,1765091661.597 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:14:22.338Z,1765091662.338 [SBIT](IMPORTANT): SBIT PASSED 2025-12-07T07:14:22.338Z,1765091662.338 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2025-12-07T07:14:22.738Z,1765091662.738 [MissionManager](IMPORTANT): Started mission Startup 2025-12-07T07:14:22.738Z,1765091662.738 [Startup] Running Loop=1 2025-12-07T07:14:22.738Z,1765091662.738 [Startup](DEBUG): Aggregate::initialize Startup 2025-12-07T07:14:22.738Z,1765091662.738 [Startup:A.GoToSurface] Running Loop=1 2025-12-07T07:14:22.738Z,1765091662.738 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-12-07T07:14:22.739Z,1765091662.739 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-12-07T07:14:22.739Z,1765091662.739 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-12-07T07:14:22.739Z,1765091662.739 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-12-07T07:14:22.741Z,1765091662.741 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-12-07T07:14:22.741Z,1765091662.741 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-12-07T07:14:22.742Z,1765091662.742 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-12-07T07:14:22.743Z,1765091662.743 [Startup:StartupSatComms] Running Loop=1 2025-12-07T07:14:22.743Z,1765091662.743 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2025-12-07T07:14:22.743Z,1765091662.743 [Startup:StartupSatComms:A] Running Loop=1 2025-12-07T07:14:23.137Z,1765091663.137 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2025-12-07T07:14:24.740Z,1765091664.740 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,071424.00,A,3642.08089,N,12156.14750,W,0.680,250.89,071225,,,A*7F 2025-12-07T07:14:24.742Z,1765091664.742 [NAL9602](INFO): GPS fix at 20251207T071424: (36.701348, -121.935792) 2025-12-07T07:14:24.788Z,1765091664.788 [Startup:StartupSatComms:A] Stopped 2025-12-07T07:14:24.788Z,1765091664.788 [Startup:StartupSatComms:B] Running Loop=1 2025-12-07T07:14:25.195Z,1765091665.195 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-12-07T07:14:27.929Z,1765091667.929 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20251207T033835/Courier0031.lzma 2025-12-07T07:14:28.602Z,1765091668.602 [DataOverHttps](INFO): Moved sent file to Logs/20251207T033835/Courier0031.lzma.bak 2025-12-07T07:14:28.602Z,1765091668.602 [DataOverHttps](INFO): SBD MOMSN=26450628 2025-12-07T07:14:45.188Z,1765091685.188 [DataOverHttps](INFO): Sending 404 bytes from file Logs/20251207T071059/Courier0000.lzma 2025-12-07T07:14:45.946Z,1765091685.946 [NAL9602](INFO): SBD MO Status=0, MOMSN=4630, MT Status=0, MTMSN=0 2025-12-07T07:14:45.946Z,1765091685.946 [NAL9602](INFO): No messages in MT queue 2025-12-07T07:14:46.190Z,1765091686.190 [DataOverHttps](INFO): Moved sent file to Logs/20251207T071059/Courier0000.lzma.bak 2025-12-07T07:14:46.190Z,1765091686.190 [DataOverHttps](INFO): SBD MOMSN=26450659 2025-12-07T07:15:02.269Z,1765091702.269 [DataOverHttps](INFO): Sending 1425 bytes from file Logs/20251207T033835/Express0032.lzma 2025-12-07T07:15:03.266Z,1765091703.266 [DataOverHttps](INFO): Moved sent file to Logs/20251207T033835/Express0032.lzma.bak 2025-12-07T07:15:03.266Z,1765091703.266 [DataOverHttps](INFO): SBD MOMSN=26450666 2025-12-07T07:15:16.653Z,1765091716.653 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-07T07:15:19.655Z,1765091719.655 [DataOverHttps](INFO): Sending 983 bytes from file Logs/20251207T071059/Express0001.lzma 2025-12-07T07:15:20.646Z,1765091720.646 [DataOverHttps](INFO): Moved sent file to Logs/20251207T071059/Express0001.lzma.bak 2025-12-07T07:15:20.646Z,1765091720.646 [DataOverHttps](INFO): SBD MOMSN=26450693 2025-12-07T07:15:21.941Z,1765091721.941 [Startup:StartupSatComms:B] Stopped 2025-12-07T07:15:21.941Z,1765091721.941 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2025-12-07T07:15:21.941Z,1765091721.941 [Startup:StartupSatComms] Stopped 2025-12-07T07:15:21.941Z,1765091721.941 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2025-12-07T07:15:21.942Z,1765091721.942 [Startup](INFO): Completed Startup 2025-12-07T07:15:21.942Z,1765091721.942 [MissionManager](INFO): Startup is completed. 2025-12-07T07:15:21.942Z,1765091721.942 [MissionManager](INFO): Uninitializing Mission Startup 2025-12-07T07:15:21.942Z,1765091721.942 [Startup] Stopped 2025-12-07T07:15:21.942Z,1765091721.942 [Startup](DEBUG): Aggregate::uninitialize Startup 2025-12-07T07:15:21.942Z,1765091721.942 [Startup:A.GoToSurface] Stopped 2025-12-07T07:15:21.943Z,1765091721.943 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-12-07T07:15:22.352Z,1765091722.352 [MissionManager](IMPORTANT): Started mission Default 2025-12-07T07:15:22.352Z,1765091722.352 [Default] Running Loop=1 2025-12-07T07:15:22.353Z,1765091722.353 [Default](DEBUG): Aggregate::initialize Default 2025-12-07T07:15:22.353Z,1765091722.353 [Default:B.GoToSurface] Running Loop=1 2025-12-07T07:15:22.353Z,1765091722.353 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-12-07T07:15:22.353Z,1765091722.353 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-12-07T07:15:22.353Z,1765091722.353 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-12-07T07:15:22.354Z,1765091722.354 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-12-07T07:15:22.354Z,1765091722.354 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-12-07T07:15:22.354Z,1765091722.354 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-12-07T07:15:22.354Z,1765091722.354 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-12-07T07:15:22.355Z,1765091722.355 [Default:A.Wait] Running Loop=1 2025-12-07T07:15:22.355Z,1765091722.355 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-12-07T07:15:30.296Z,1765091730.296 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:15:35.739Z,1765091735.739 [Default:A.Wait](INFO): Done Waiting. 2025-12-07T07:15:35.739Z,1765091735.739 [Default:A.Wait] Stopped 2025-12-07T07:15:35.739Z,1765091735.739 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-12-07T07:15:36.067Z,1765091736.067 [Default:CheckIn] Running Loop=1 2025-12-07T07:15:36.067Z,1765091736.067 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-07T07:15:36.067Z,1765091736.067 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-07T07:15:36.455Z,1765091736.455 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-12-07T07:15:38.063Z,1765091738.063 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,071537.00,A,3642.07431,N,12156.16136,W,1.808,273.98,071225,,,A*7E 2025-12-07T07:15:38.066Z,1765091738.066 [NAL9602](INFO): GPS fix at 20251207T071537: (36.701239, -121.936023) 2025-12-07T07:15:38.077Z,1765091738.077 [Default:CheckIn:Read_GPS] Stopped 2025-12-07T07:15:38.077Z,1765091738.077 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-07T07:15:38.505Z,1765091738.505 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-12-07T07:15:45.642Z,1765091745.642 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20251207T071059/Courier0004.lzma 2025-12-07T07:15:46.638Z,1765091746.638 [DataOverHttps](INFO): Moved sent file to Logs/20251207T071059/Courier0004.lzma.bak 2025-12-07T07:15:46.638Z,1765091746.638 [DataOverHttps](INFO): SBD MOMSN=26450710 2025-12-07T07:15:59.882Z,1765091759.882 [NAL9602](INFO): SBD MO Status=0, MOMSN=4631, MT Status=0, MTMSN=0 2025-12-07T07:15:59.882Z,1765091759.882 [NAL9602](INFO): No messages in MT queue 2025-12-07T07:16:03.946Z,1765091763.946 [DataOverHttps](INFO): Sending 124 bytes from file Logs/20251207T071059/Express0005.lzma 2025-12-07T07:16:04.898Z,1765091764.898 [DataOverHttps](INFO): Moved sent file to Logs/20251207T071059/Express0005.lzma.bak 2025-12-07T07:16:04.898Z,1765091764.898 [DataOverHttps](INFO): SBD MOMSN=26450715 2025-12-07T07:16:06.363Z,1765091766.363 [Default:CheckIn:Read_Iridium] Stopped 2025-12-07T07:16:06.363Z,1765091766.363 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-07T07:16:06.363Z,1765091766.363 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-07T07:16:14.564Z,1765091774.564 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:16:23.168Z,1765091783.168 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:16:30.601Z,1765091790.601 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-07T07:16:31.772Z,1765091791.772 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:16:40.352Z,1765091800.352 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:16:49.360Z,1765091809.360 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:16:57.960Z,1765091817.960 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:17:06.552Z,1765091826.552 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:17:15.152Z,1765091835.152 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:17:23.732Z,1765091843.732 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:17:32.524Z,1765091852.524 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:17:41.072Z,1765091861.072 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:17:49.624Z,1765091869.624 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:17:58.260Z,1765091878.260 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:18:08.764Z,1765091888.764 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:18:17.408Z,1765091897.408 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:18:26.068Z,1765091906.068 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:18:34.662Z,1765091914.662 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:18:43.496Z,1765091923.496 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:18:52.420Z,1765091932.420 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:19:01.028Z,1765091941.028 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:19:09.600Z,1765091949.600 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:19:18.233Z,1765091958.233 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:19:26.824Z,1765091966.824 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:19:35.556Z,1765091975.556 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:19:44.164Z,1765091984.164 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:19:52.740Z,1765091992.740 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:20:01.496Z,1765092001.496 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:20:10.737Z,1765092010.737 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:20:19.252Z,1765092019.252 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:20:27.944Z,1765092027.944 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:20:36.524Z,1765092036.524 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:20:45.072Z,1765092045.072 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:20:53.764Z,1765092053.764 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:21:02.305Z,1765092062.305 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:21:07.084Z,1765092067.084 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-12-07T07:21:07.085Z,1765092067.085 [Default:CheckIn:C.Wait] Stopped 2025-12-07T07:21:07.085Z,1765092067.085 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-12-07T07:21:07.085Z,1765092067.085 [Default:CheckIn:D] Running Loop=1 2025-12-07T07:21:07.445Z,1765092067.445 [Default:CheckIn:D] Stopped 2025-12-07T07:21:07.445Z,1765092067.445 [Default:CheckIn:E] Running Loop=1 2025-12-07T07:21:07.857Z,1765092067.857 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 5.751538 min 2025-12-07T07:21:07.857Z,1765092067.857 [Default:CheckIn:E] Stopped 2025-12-07T07:21:07.857Z,1765092067.857 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-12-07T07:21:07.857Z,1765092067.857 [Default:CheckIn] Stopped 2025-12-07T07:21:07.857Z,1765092067.857 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-12-07T07:21:07.858Z,1765092067.858 [Default:CheckIn](INFO): Running loop #2 2025-12-07T07:21:07.858Z,1765092067.858 [Default:CheckIn] Running Loop=2 2025-12-07T07:21:07.858Z,1765092067.858 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-07T07:21:07.858Z,1765092067.858 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-07T07:21:09.851Z,1765092069.851 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,072109.00,A,3642.04496,N,12156.22794,W,0.389,255.60,071225,,,D*76 2025-12-07T07:21:09.853Z,1765092069.853 [NAL9602](INFO): GPS fix at 20251207T072109: (36.700749, -121.937132) 2025-12-07T07:21:09.885Z,1765092069.885 [Default:CheckIn:Read_GPS] Stopped 2025-12-07T07:21:09.885Z,1765092069.885 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-07T07:21:17.523Z,1765092077.523 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20251207T071059/Courier0007.lzma 2025-12-07T07:21:18.526Z,1765092078.526 [DataOverHttps](INFO): Moved sent file to Logs/20251207T071059/Courier0007.lzma.bak 2025-12-07T07:21:18.526Z,1765092078.526 [DataOverHttps](INFO): SBD MOMSN=26450732 2025-12-07T07:21:34.759Z,1765092094.759 [DataOverHttps](INFO): Sending 182 bytes from file Logs/20251207T071059/Express0008.lzma 2025-12-07T07:21:35.706Z,1765092095.706 [NAL9602](INFO): SBD MO Status=0, MOMSN=4632, MT Status=0, MTMSN=0 2025-12-07T07:21:35.706Z,1765092095.706 [NAL9602](INFO): No messages in MT queue 2025-12-07T07:21:35.762Z,1765092095.762 [DataOverHttps](INFO): Moved sent file to Logs/20251207T071059/Express0008.lzma.bak 2025-12-07T07:21:35.762Z,1765092095.762 [DataOverHttps](INFO): SBD MOMSN=26450735 2025-12-07T07:21:36.951Z,1765092096.951 [Default:CheckIn:Read_Iridium] Stopped 2025-12-07T07:21:36.951Z,1765092096.951 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-07T07:21:36.951Z,1765092096.951 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-07T07:21:45.344Z,1765092105.344 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:21:53.876Z,1765092113.876 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:22:02.592Z,1765092122.592 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:22:06.408Z,1765092126.408 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-07T07:22:11.392Z,1765092131.392 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:22:19.864Z,1765092139.864 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:22:28.532Z,1765092148.532 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:22:36.351Z,1765092156.351 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2025-12-07T07:22:37.180Z,1765092157.180 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:22:45.800Z,1765092165.800 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:22:54.437Z,1765092174.437 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:23:03.088Z,1765092183.088 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:23:11.541Z,1765092191.541 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:23:20.176Z,1765092200.176 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:23:28.896Z,1765092208.896 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:23:37.356Z,1765092217.356 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:23:46.152Z,1765092226.152 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:23:54.757Z,1765092234.757 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:24:02.237Z,1765092242.237 [DataOverHttps](IMPORTANT): SBD MTMSN=20251207T072401 2025-12-07T07:24:10.100Z,1765092250.100 [DataOverHttps](INFO): Received command: restart sys 2025-12-07T07:24:10.189Z,1765092250.189 [CommandExec](IMPORTANT): got command restart system 2025-12-07T07:24:12.104Z,1765092252.104 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-12-07T07:24:12.324Z,1765092252.324 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-07T07:24:12.324Z,1765092252.324 [CommandExec](INFO): Uninitializing the command executive. 2025-12-07T07:24:12.324Z,1765092252.324 [CommandExec](INFO): Uninitializing the command scheduler. 2025-12-07T07:24:12.325Z,1765092252.325 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-12-07T07:24:12.328Z,1765092252.328 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-12-07T07:24:12.328Z,1765092252.328 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-12-07T07:24:12.328Z,1765092252.328 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-12-07T07:24:12.329Z,1765092252.329 [NavChartDb](INFO): Join timeout helper Thread ID is 1008 2025-12-07T07:24:12.713Z,1765092252.713 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-07T07:24:12.714Z,1765092252.714 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-12-07T07:24:12.720Z,1765092252.720 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-12-07T07:24:12.720Z,1765092252.720 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-12-07T07:24:12.722Z,1765092252.722 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1009 2025-12-07T07:24:12.769Z,1765092252.769 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-12-07T07:24:12.770Z,1765092252.770 [WetLabsBB2FL](INFO): Powering down 2025-12-07T07:24:12.773Z,1765092252.773 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-12-07T07:24:12.780Z,176509225