2025-08-11T23:43:02.852Z,1754955782.852 [Supervisor](DEBUG): Initializing supervisor. 2025-08-11T23:43:02.857Z,1754955782.857 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2025-08-11T23:43:02.857Z,1754955782.857 [SyncHandler](INFO): Protected caller Thread ID is 8095 2025-08-11T23:43:02.857Z,1754955782.857 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2025-08-11T23:43:02.858Z,1754955782.858 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2025-08-11T23:43:02.859Z,1754955782.859 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 8096 2025-08-11T23:43:02.863Z,1754955782.863 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2025-08-11T23:43:02.883Z,1754955782.883 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2025-08-11T23:43:02.884Z,1754955782.884 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2025-08-11T23:43:02.884Z,1754955782.884 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 8097 2025-08-11T23:43:02.888Z,1754955782.888 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2025-08-11T23:43:02.889Z,1754955782.889 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2025-08-11T23:43:02.890Z,1754955782.890 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 8098 2025-08-11T23:43:02.892Z,1754955782.892 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2025-08-11T23:43:02.893Z,1754955782.893 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2025-08-11T23:43:02.893Z,1754955782.893 [logger ThreadHandler](INFO): Protected caller Thread ID is 8099 2025-08-11T23:43:02.897Z,1754955782.897 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2025-08-11T23:43:02.898Z,1754955782.898 [Supervisor](INFO): Looking for Config files in directory: Config/ 2025-08-11T23:43:02.899Z,1754955782.899 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2025-08-11T23:43:03.005Z,1754955783.005 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2025-08-11T23:43:03.006Z,1754955783.006 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2025-08-11T23:43:03.239Z,1754955783.239 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2025-08-11T23:43:03.239Z,1754955783.239 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2025-08-11T23:43:03.511Z,1754955783.511 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2025-08-11T23:43:03.511Z,1754955783.511 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2025-08-11T23:43:03.629Z,1754955783.629 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2025-08-11T23:43:03.629Z,1754955783.629 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2025-08-11T23:43:04.393Z,1754955784.393 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2025-08-11T23:43:04.393Z,1754955784.393 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2025-08-11T23:43:04.915Z,1754955784.915 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2025-08-11T23:43:04.916Z,1754955784.916 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2025-08-11T23:43:05.472Z,1754955785.472 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2025-08-11T23:43:05.473Z,1754955785.473 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2025-08-11T23:43:05.900Z,1754955785.900 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2025-08-11T23:43:05.900Z,1754955785.900 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2025-08-11T23:43:06.264Z,1754955786.264 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2025-08-11T23:43:06.264Z,1754955786.264 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2025-08-11T23:43:06.655Z,1754955786.655 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2025-08-11T23:43:06.655Z,1754955786.655 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2025-08-11T23:43:06.890Z,1754955786.890 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2025-08-11T23:43:06.891Z,1754955786.891 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2025-08-11T23:43:07.043Z,1754955787.043 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2025-08-11T23:43:07.044Z,1754955787.044 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2025-08-11T23:43:07.148Z,1754955787.148 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance 2025-08-11T23:43:07.148Z,1754955787.148 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2025-08-11T23:43:07.239Z,1754955787.239 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2025-08-11T23:43:07.436Z,1754955787.436 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2025-08-11T23:43:07.437Z,1754955787.437 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2025-08-11T23:43:07.744Z,1754955787.744 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2025-08-11T23:43:07.747Z,1754955787.747 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2025-08-11T23:43:07.748Z,1754955787.748 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2025-08-11T23:43:08.029Z,1754955788.029 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2025-08-11T23:43:08.623Z,1754955788.623 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2025-08-11T23:43:08.624Z,1754955788.624 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2025-08-11T23:43:08.806Z,1754955788.806 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2025-08-11T23:43:08.997Z,1754955788.997 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2025-08-11T23:43:09.109Z,1754955789.109 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2025-08-11T23:43:09.231Z,1754955789.231 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2025-08-11T23:43:09.356Z,1754955789.356 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2025-08-11T23:43:09.461Z,1754955789.461 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2025-08-11T23:43:09.635Z,1754955789.635 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Derivation.cfg 2025-08-11T23:43:09.744Z,1754955789.744 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/workSite.cfg 2025-08-11T23:43:09.840Z,1754955789.840 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2025-08-11T23:43:09.949Z,1754955789.949 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2025-08-11T23:43:10.048Z,1754955790.048 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2025-08-11T23:43:10.055Z,1754955790.055 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2025-08-11T23:43:10.239Z,1754955790.239 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2025-08-11T23:43:10.239Z,1754955790.239 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2025-08-11T23:43:10.381Z,1754955790.381 [BuoyancyServo] Loaded 2025-08-11T23:43:10.381Z,1754955790.381 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2025-08-11T23:43:10.405Z,1754955790.405 [ElevatorServo] Loaded 2025-08-11T23:43:10.405Z,1754955790.405 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2025-08-11T23:43:10.428Z,1754955790.428 [MassServo] Loaded 2025-08-11T23:43:10.428Z,1754955790.428 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2025-08-11T23:43:10.450Z,1754955790.450 [RudderServo] Loaded 2025-08-11T23:43:10.450Z,1754955790.450 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2025-08-11T23:43:10.468Z,1754955790.468 [ThrusterHE] Loaded 2025-08-11T23:43:10.468Z,1754955790.468 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2025-08-11T23:43:10.469Z,1754955790.469 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2025-08-11T23:43:10.469Z,1754955790.469 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2025-08-11T23:43:10.651Z,1754955790.651 [SBIT](DEBUG): Construct Startup Built In Test. 2025-08-11T23:43:10.710Z,1754955790.710 [SBIT] Loaded 2025-08-11T23:43:10.710Z,1754955790.710 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2025-08-11T23:43:10.713Z,1754955790.713 [IBIT](DEBUG): Construct Initiated Built In Test. 2025-08-11T23:43:10.734Z,1754955790.734 [IBIT] Loaded 2025-08-11T23:43:10.734Z,1754955790.734 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2025-08-11T23:43:10.739Z,1754955790.739 [CBIT](DEBUG): Construct Continuous Built In Test. 2025-08-11T23:43:10.933Z,1754955790.933 [CBIT] Loaded 2025-08-11T23:43:10.934Z,1754955790.934 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2025-08-11T23:43:10.964Z,1754955790.964 [GFScanner] Loaded 2025-08-11T23:43:10.965Z,1754955790.965 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread. 2025-08-11T23:43:10.965Z,1754955790.965 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2025-08-11T23:43:10.966Z,1754955790.966 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2025-08-11T23:43:11.138Z,1754955791.138 [CTD_Seabird] Loaded 2025-08-11T23:43:11.138Z,1754955791.138 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2025-08-11T23:43:11.139Z,1754955791.139 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406864E0 2025-08-11T23:43:11.139Z,1754955791.139 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 8187 2025-08-11T23:43:11.174Z,1754955791.174 [ESPComponent] Loaded 2025-08-11T23:43:11.174Z,1754955791.174 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2025-08-11T23:43:11.196Z,1754955791.196 [PAR_Licor] Loaded 2025-08-11T23:43:11.196Z,1754955791.196 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2025-08-11T23:43:11.241Z,1754955791.241 [WetLabsBB2FL] Loaded 2025-08-11T23:43:11.242Z,1754955791.242 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2025-08-11T23:43:11.243Z,1754955791.243 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406B64E0 2025-08-11T23:43:11.243Z,1754955791.243 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 8188 2025-08-11T23:43:11.244Z,1754955791.244 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2025-08-11T23:43:11.244Z,1754955791.244 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2025-08-11T23:43:11.297Z,1754955791.297 [DepthRateCalculator] Loaded 2025-08-11T23:43:11.297Z,1754955791.297 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2025-08-11T23:43:11.302Z,1754955791.302 [PitchRateCalculator] Loaded 2025-08-11T23:43:11.303Z,1754955791.303 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2025-08-11T23:43:11.313Z,1754955791.313 [SpeedCalculator] Loaded 2025-08-11T23:43:11.313Z,1754955791.313 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2025-08-11T23:43:11.318Z,1754955791.318 [YawRateCalculator] Loaded 2025-08-11T23:43:11.318Z,1754955791.318 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2025-08-11T23:43:11.338Z,1754955791.338 [ElevatorOffsetCalculator] Loaded 2025-08-11T23:43:11.338Z,1754955791.338 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2025-08-11T23:43:11.339Z,1754955791.339 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2025-08-11T23:43:11.339Z,1754955791.339 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2025-08-11T23:43:11.478Z,1754955791.478 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2025-08-11T23:43:11.478Z,1754955791.478 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2025-08-11T23:43:11.509Z,1754955791.509 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2025-08-11T23:43:11.509Z,1754955791.509 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2025-08-11T23:43:11.624Z,1754955791.624 [DeadReckonUsingMultipleVelocitySources] Loaded 2025-08-11T23:43:11.624Z,1754955791.624 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2025-08-11T23:43:11.638Z,1754955791.638 [NavChart] Loaded 2025-08-11T23:43:11.638Z,1754955791.638 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2025-08-11T23:43:11.644Z,1754955791.644 [UniversalFixResidualReporter] Loaded 2025-08-11T23:43:11.644Z,1754955791.644 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2025-08-11T23:43:11.645Z,1754955791.645 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2025-08-11T23:43:11.645Z,1754955791.645 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2025-08-11T23:43:12.136Z,1754955792.136 [AHRS_M2] Loaded 2025-08-11T23:43:12.136Z,1754955792.136 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2025-08-11T23:43:12.175Z,1754955792.175 [BackseatComponent] Loaded 2025-08-11T23:43:12.175Z,1754955792.175 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2025-08-11T23:43:12.176Z,1754955792.176 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A824E0 2025-08-11T23:43:12.177Z,1754955792.177 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 8189 2025-08-11T23:43:12.180Z,1754955792.180 [LcmUniversalReporter] Loaded 2025-08-11T23:43:12.180Z,1754955792.180 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2025-08-11T23:43:12.923Z,1754955792.923 [BPC1] Loaded 2025-08-11T23:43:12.923Z,1754955792.923 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2025-08-11T23:43:13.025Z,1754955793.025 [DAT] Loaded 2025-08-11T23:43:13.025Z,1754955793.025 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2025-08-11T23:43:13.026Z,1754955793.026 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40AB24E0 2025-08-11T23:43:13.026Z,1754955793.026 [DAT ThreadHandler](INFO): Protected caller Thread ID is 8190 2025-08-11T23:43:13.099Z,1754955793.099 [DataOverHttps] Loaded 2025-08-11T23:43:13.100Z,1754955793.100 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2025-08-11T23:43:13.101Z,1754955793.101 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40AE24E0 2025-08-11T23:43:13.101Z,1754955793.101 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 8191 2025-08-11T23:43:13.125Z,1754955793.125 [Depth_Keller] Loaded 2025-08-11T23:43:13.125Z,1754955793.125 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2025-08-11T23:43:13.130Z,1754955793.130 [DropWeight] Loaded 2025-08-11T23:43:13.131Z,1754955793.131 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2025-08-11T23:43:13.172Z,1754955793.172 [DVL_micro] Loaded 2025-08-11T23:43:13.173Z,1754955793.173 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2025-08-11T23:43:13.236Z,1754955793.236 [NAL9602] Loaded 2025-08-11T23:43:13.236Z,1754955793.236 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2025-08-11T23:43:13.262Z,1754955793.262 [Onboard] Loaded 2025-08-11T23:43:13.262Z,1754955793.262 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2025-08-11T23:43:13.263Z,1754955793.263 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40B124E0 2025-08-11T23:43:13.264Z,1754955793.264 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 8192 2025-08-11T23:43:13.270Z,1754955793.270 [PowerOnly](INFO): Adding load control power supply at /dev/loadB1 2025-08-11T23:43:13.284Z,1754955793.284 [PowerOnly] Loaded 2025-08-11T23:43:13.284Z,1754955793.284 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2025-08-11T23:43:13.298Z,1754955793.298 [Power24vConverter] Loaded 2025-08-11T23:43:13.299Z,1754955793.299 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2025-08-11T23:43:13.315Z,1754955793.315 [Radio_Surface] Loaded 2025-08-11T23:43:13.315Z,1754955793.315 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2025-08-11T23:43:13.316Z,1754955793.316 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40B424E0 2025-08-11T23:43:13.317Z,1754955793.317 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 8193 2025-08-11T23:43:13.332Z,1754955793.332 [Sonardyne_Nano] Loaded 2025-08-11T23:43:13.333Z,1754955793.333 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2025-08-11T23:43:13.333Z,1754955793.333 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2025-08-11T23:43:13.334Z,1754955793.334 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2025-08-11T23:43:13.642Z,1754955793.642 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2025-08-11T23:43:13.643Z,1754955793.643 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2025-08-11T23:43:13.807Z,1754955793.807 [VerticalControl](DEBUG): Construct VerticalControl. 2025-08-11T23:43:13.859Z,1754955793.859 [VerticalControl] Loaded 2025-08-11T23:43:13.860Z,1754955793.860 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2025-08-11T23:43:13.863Z,1754955793.863 [HorizontalControl](DEBUG): Construct HorizontalControl. 2025-08-11T23:43:13.907Z,1754955793.907 [HorizontalControl] Loaded 2025-08-11T23:43:13.907Z,1754955793.907 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2025-08-11T23:43:13.910Z,1754955793.910 [SpeedControl](DEBUG): Construct SpeedControl. 2025-08-11T23:43:13.911Z,1754955793.911 [SpeedControl] Loaded 2025-08-11T23:43:13.912Z,1754955793.912 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2025-08-11T23:43:13.914Z,1754955793.914 [LoopControl](DEBUG): Construct LoopControl. 2025-08-11T23:43:13.915Z,1754955793.915 [LoopControl] Loaded 2025-08-11T23:43:13.915Z,1754955793.915 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2025-08-11T23:43:13.916Z,1754955793.916 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2025-08-11T23:43:13.916Z,1754955793.916 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2025-08-11T23:43:13.927Z,1754955793.927 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2025-08-11T23:43:13.927Z,1754955793.927 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2025-08-11T23:43:14.288Z,1754955794.288 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2025-08-11T23:43:14.294Z,1754955794.294 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2025-08-11T23:43:14.297Z,1754955794.297 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2025-08-11T23:43:14.308Z,1754955794.308 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2025-08-11T23:43:14.309Z,1754955794.309 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C684E0 2025-08-11T23:43:14.310Z,1754955794.310 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 8194 2025-08-11T23:43:14.314Z,1754955794.314 [Supervisor](INFO): Main Thread ID is 8094 2025-08-11T23:43:14.314Z,1754955794.314 [Supervisor](DEBUG): Running supervisor. 2025-08-11T23:43:14.315Z,1754955794.315 [CommandExec ThreadHandler](INFO): Handler Thread ID is 8195 2025-08-11T23:43:14.316Z,1754955794.316 [CommandExec](INFO): Initializing the command executive. 2025-08-11T23:43:14.317Z,1754955794.317 [CommandLine ThreadHandler](INFO): Handler Thread ID is 8196 2025-08-11T23:43:14.319Z,1754955794.319 [controlThread ThreadHandler](INFO): Handler Thread ID is 8197 2025-08-11T23:43:14.320Z,1754955794.320 [controlThread](DEBUG): Initializing ControlThread 2025-08-11T23:43:14.322Z,1754955794.322 [SBIT](INFO): Initialize SBIT Component. 2025-08-11T23:43:14.322Z,1754955794.322 [SBIT](IMPORTANT): git: 2025-05-29 2025-08-11T23:43:14.322Z,1754955794.322 [SBIT](INFO): git hash: c78c975c7bb2a66b27244d2837f68dff299fa024 2025-08-11T23:43:14.323Z,1754955794.323 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2025-08-11T23:43:14.324Z,1754955794.324 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2025-08-11T23:43:14.325Z,1754955794.325 [SBIT](INFO): Beginning SBIT in 152.000000 seconds. 2025-08-11T23:43:14.326Z,1754955794.326 [IBIT](INFO): Initialize IBIT Component. 2025-08-11T23:43:14.327Z,1754955794.327 [CBIT](DEBUG): Initialize CBIT Component. 2025-08-11T23:43:14.328Z,1754955794.328 [logger ThreadHandler](INFO): Handler Thread ID is 8198 2025-08-11T23:43:14.339Z,1754955794.339 [CBIT](DEBUG): Initialized mux pins. 2025-08-11T23:43:14.339Z,1754955794.339 [CBIT](DEBUG): Initializing the watchdog timer. 2025-08-11T23:43:14.348Z,1754955794.348 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 8199 2025-08-11T23:43:14.348Z,1754955794.348 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2025-08-11T23:43:14.353Z,1754955794.353 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 8201 2025-08-11T23:43:14.356Z,1754955794.356 [WetLabsBB2FL](INFO): Powering up 2025-08-11T23:43:14.357Z,1754955794.357 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 8203 2025-08-11T23:43:14.363Z,1754955794.363 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2025-08-11T23:43:14.364Z,1754955794.364 [CBIT](DEBUG): Initializing heartbeat. 2025-08-11T23:43:14.365Z,1754955794.365 [DAT ThreadHandler](INFO): Handler Thread ID is 8204 2025-08-11T23:43:14.366Z,1754955794.366 [DAT](INFO): Powering up 2025-08-11T23:43:14.366Z,1754955794.366 [DAT](DEBUG): Initializing DAT. 2025-08-11T23:43:14.369Z,1754955794.369 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 8206 2025-08-11T23:43:14.370Z,1754955794.370 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2025-08-11T23:43:14.392Z,1754955794.392 [Onboard ThreadHandler](INFO): Handler Thread ID is 8207 2025-08-11T23:43:14.409Z,1754955794.409 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 8208 2025-08-11T23:43:14.430Z,1754955794.430 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 8209 2025-08-11T23:43:14.432Z,1754955794.432 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2025-08-11T23:43:14.433Z,1754955794.433 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2025-08-11T23:43:14.433Z,1754955794.433 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2025-08-11T23:43:14.433Z,1754955794.433 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2025-08-11T23:43:14.433Z,1754955794.433 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2025-08-11T23:43:14.433Z,1754955794.433 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2025-08-11T23:43:14.433Z,1754955794.433 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2025-08-11T23:43:14.433Z,1754955794.434 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2025-08-11T23:43:14.435Z,1754955794.435 [CBIT](DEBUG): Deactivating emergency mode. 2025-08-11T23:43:14.475Z,1754955794.475 [CBIT](DEBUG): Backplane powered. 2025-08-11T23:43:14.476Z,1754955794.476 [GFScanner](DEBUG): Initializing GFScanner 2025-08-11T23:43:14.476Z,1754955794.476 [GFScanner](DEBUG): Deactivating GF circuits. 2025-08-11T23:43:14.488Z,1754955794.488 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2025-08-11T23:43:14.488Z,1754955794.488 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2025-08-11T23:43:14.488Z,1754955794.488 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2025-08-11T23:43:14.489Z,1754955794.489 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2025-08-11T23:43:14.489Z,1754955794.489 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2025-08-11T23:43:14.490Z,1754955794.490 [NavChart](DEBUG): Initialize NavChart Navigation. 2025-08-11T23:43:14.491Z,1754955794.491 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2025-08-11T23:43:14.502Z,1754955794.502 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2025-08-11T23:43:14.512Z,1754955794.512 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2025-08-11T23:43:14.513Z,1754955794.513 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2025-08-11T23:43:14.513Z,1754955794.513 [LoopControl](DEBUG): Initialize LoopControlComponent. 2025-08-11T23:43:14.514Z,1754955794.514 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2025-08-11T23:43:14.515Z,1754955794.515 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2025-08-11T23:43:14.515Z,1754955794.515 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2025-08-11T23:43:14.525Z,1754955794.525 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2025-08-11T23:43:14.610Z,1754955794.610 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2025-08-11T23:43:14.614Z,1754955794.614 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2025-08-11T23:43:14.663Z,1754955794.663 [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-08-11T23:43:14.664Z,1754955794.664 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2025-08-11T23:43:14.664Z,1754955794.664 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2025-08-11T23:43:14.665Z,1754955794.665 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2025-08-11T23:43:14.823Z,1754955794.823 [Radio_Surface](INFO): Powering up 2025-08-11T23:43:14.971Z,1754955794.971 [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-08-11T23:43:14.989Z,1754955794.989 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2025-08-11T23:43:14.990Z,1754955794.990 [Default:A.Wait](DEBUG): Construct Wait. 2025-08-11T23:43:14.992Z,1754955794.992 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-08-11T23:43:15.053Z,1754955795.053 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2025-08-11T23:43:15.055Z,1754955795.055 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2025-08-11T23:43:15.077Z,1754955795.077 [Default:E.Execute](DEBUG): Construct Execute. 2025-08-11T23:43:15.081Z,1754955795.081 [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-08-11T23:43:15.088Z,1754955795.088 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,PowerOnly,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter, 2025-08-11T23:43:15.133Z,1754955795.133 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2025-08-11T23:43:15.196Z,1754955795.196 [Depth_Keller](INFO): Initializing. 2025-08-11T23:43:15.205Z,1754955795.205 [PowerOnly](INFO): Powering up loadControl 2025-08-11T23:43:15.224Z,1754955795.224 [Power24vConverter](INFO): Powering up. 2025-08-11T23:43:15.225Z,1754955795.225 [Sonardyne_Nano](INFO): Initializing. 2025-08-11T23:43:15.252Z,1754955795.252 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-08-11T23:43:15.275Z,1754955795.275 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-08-11T23:43:15.284Z,1754955795.284 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-08-11T23:43:15.285Z,1754955795.285 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-08-11T23:43:15.292Z,1754955795.292 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-08-11T23:43:15.293Z,1754955795.293 [MassServo](DEBUG): Initializing EZServoServo. 2025-08-11T23:43:15.300Z,1754955795.300 [MassServo](DEBUG): Initializing MassServo. 2025-08-11T23:43:15.300Z,1754955795.300 [RudderServo](DEBUG): Initializing EZServoServo. 2025-08-11T23:43:15.308Z,1754955795.308 [RudderServo](DEBUG): Initializing RudderServo. 2025-08-11T23:43:15.308Z,1754955795.308 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-08-11T23:43:15.316Z,1754955795.316 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-08-11T23:43:15.547Z,1754955795.547 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-08-11T23:43:15.547Z,1754955795.547 [DropWeight] Hardware Fault, FailCount= 1 2025-08-11T23:43:15.547Z,1754955795.547 [DropWeight](ERROR): Hardware Fault 2025-08-11T23:43:15.591Z,1754955795.591 [CommandExec](FAULT): Scheduling is paused 2025-08-11T23:43:15.591Z,1754955795.591 [CBIT](INFO): Critical error at 20250811T234315 2025-08-11T23:43:15.600Z,1754955795.600 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2025-08-11T23:43:15.602Z,1754955795.602 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-08-11T23:43:15.603Z,1754955795.603 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-08-11T23:43:15.958Z,1754955795.958 [CBIT](INFO): Critical error at 20250811T234315 2025-08-11T23:43:16.000Z,1754955797.000 [WetLabsBB2FL](INFO): Powering down 2025-08-11T23:43:17.555Z,1754955797.555 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2025-08-11T23:43:18.801Z,1754955798.801 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2213 2025-08-11T23:43:20.929Z,1754955800.929 [ThrusterHE](ERROR): Zero Speed Commanded. 2025-08-11T23:43:21.452Z,1754955801.452 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2025-08-11T23:43:24.756Z,1754955804.756 [CBIT](CRITICAL): Environmental Failure. Press:14.751667 PSI. Humidity:42%. Temp:26 C. ABORTING MISSION 2025-08-11T23:43:25.025Z,1754955805.025 [CBIT](INFO): Critical error at 20250811T234324 2025-08-11T23:43:29.036Z,1754955809.036 [DAT](INFO): DAT read: 2025-08-11T23:43:29.037Z,1754955809.037 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2025-08-11T23:43:30.800Z,1754955810.800 [DAT](INFO): DAT read: MF Frequency Band 2025-08-11T23:43:30.802Z,1754955810.802 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2025-08-11T23:43:30.802Z,1754955810.802 [DAT](INFO): DAT read: Aug 11 2025 22:07:30 2025-08-11T23:43:32.061Z,1754955812.061 [DAT](INFO): DAT read: Features enabled [Bearing] 2025-08-11T23:43:32.062Z,1754955812.062 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC 2025-08-11T23:43:32.062Z,1754955812.062 [DAT](INFO): commRate: 1200 2025-08-11T23:43:34.127Z,1754955814.127 [DAT](INFO): entering command mode 2025-08-11T23:43:34.328Z,1754955814.328 [DAT](INFO): DAT read: 2025-08-11T23:43:34.329Z,1754955814.329 [DAT](INFO): DAT read: user:1> 2025-08-11T23:43:34.329Z,1754955814.329 [DAT](INFO): setting verbose to 3 2025-08-11T23:43:34.580Z,1754955814.580 [DAT](INFO): DAT read: user:1> 2025-08-11T23:43:34.581Z,1754955814.581 [DAT](INFO): DAT read: Verbose | 3 2025-08-11T23:43:34.582Z,1754955814.582 [DAT](INFO): set verbose to 3 2025-08-11T23:43:34.582Z,1754955814.582 [DAT](INFO): setting DatVerbose to 27440 2025-08-11T23:43:34.832Z,1754955814.832 [DAT](INFO): DAT read: user:2> 2025-08-11T23:43:34.833Z,1754955814.833 [DAT](INFO): DAT read: DatVerbose | 27440 2025-08-11T23:43:34.834Z,1754955814.834 [DAT](INFO): set DatVerbose to 27440 2025-08-11T23:43:34.834Z,1754955814.834 [DAT](INFO): setting transmit power to 8 2025-08-11T23:43:35.084Z,1754955815.084 [DAT](INFO): DAT read: user:3> 2025-08-11T23:43:35.085Z,1754955815.085 [DAT](INFO): DAT read: TxPower | 8 (Max) 2025-08-11T23:43:35.086Z,1754955815.086 [DAT](INFO): set transmit power to 8 2025-08-11T23:43:35.086Z,1754955815.086 [DAT](INFO): setting local address to 5 2025-08-11T23:43:35.336Z,1754955815.336 [DAT](INFO): DAT read: user:4> 2025-08-11T23:43:35.337Z,1754955815.337 [DAT](INFO): DAT read: LocalAddr | 5 2025-08-11T23:43:35.337Z,1754955815.337 [DAT](INFO): set local address to 5 2025-08-11T23:43:35.339Z,1754955815.339 [DAT](INFO): Setting time to: 23:43:35 And date to:8/11/2025 2025-08-11T23:43:35.588Z,1754955815.588 [DAT](INFO): DAT read: user:5> 2025-08-11T23:43:35.589Z,1754955815.589 [DAT](INFO): DAT read: Mon Aug 11, 2025 23:43:35 2025-08-11T23:43:35.589Z,1754955815.589 [DAT](INFO): Local DAT time set to Mon Aug 11, 2025 23:43:35 2025-08-11T23:43:35.688Z,1754955815.688 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.008538 2025-08-11T23:43:43.537Z,1754955823.537 [NAL9602](INFO): Powering up NAL9602 2025-08-11T23:43:54.511Z,1754955834.511 [NAL9602](INFO): NAL9602 initialized 2025-08-11T23:45:46.790Z,1754955946.790 [SBIT](IMPORTANT): Beginning Startup BIT 2025-08-11T23:45:46.795Z,1754955946.795 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-08-11T23:45:51.440Z,1754955951.440 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.035658 CHAN A1 (24V): 0.019678 CHAN A2 (12V): 0.001858 CHAN A3 (5V): 0.001534 CHAN B0 (3.3V): 0.001646 CHAN B1 (3.15aV): 0.001331 CHAN B2 (3.15bV): 0.001835 CHAN B3 (GND): 0.001420 OPEN: 0.007807 Full Scale: +/- 1 mA 2025-08-11T23:46:14.745Z,1754955974.745 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2025-08-11T23:46:14.745Z,1754955974.745 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-08-11T23:46:14.760Z,1754955974.760 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-08-11T23:46:15.160Z,1754955975.160 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-08-11T23:46:15.160Z,1754955975.160 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2025-08-11T23:46:41.065Z,1754956001.065 [SBIT](IMPORTANT): SBIT PASSED 2025-08-11T23:46:41.065Z,1754956001.065 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2025-08-11T23:46:41.065Z,1754956001.065 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=5 count; 2025-08-11T23:46:41.066Z,1754956001.066 [SBIT](IMPORTANT): CBIT.runNavErrorCritical=0 bool; 2025-08-11T23:46:41.066Z,1754956001.066 [SBIT](IMPORTANT): ElevatorServo.currLimit=20 percent; 2025-08-11T23:46:41.066Z,1754956001.066 [SBIT](IMPORTANT): ThrusterHE.bestEffortMode=1 bool; 2025-08-11T23:46:41.066Z,1754956001.066 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=165.065282 cubic_centimeter; 2025-08-11T23:46:41.066Z,1754956001.066 [SBIT](IMPORTANT): VerticalControl.massDefault=33.117764 millimeter; 2025-08-11T23:46:41.470Z,1754956001.470 [MissionManager](IMPORTANT): Started mission Startup 2025-08-11T23:46:41.470Z,1754956001.470 [Startup] Running Loop=1 2025-08-11T23:46:41.471Z,1754956001.471 [Startup](DEBUG): Aggregate::initialize Startup 2025-08-11T23:46:41.471Z,1754956001.471 [Startup:A.GoToSurface] Running Loop=1 2025-08-11T23:46:41.471Z,1754956001.471 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-08-11T23:46:41.472Z,1754956001.472 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-08-11T23:46:41.473Z,1754956001.473 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-08-11T23:46:41.473Z,1754956001.473 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-08-11T23:46:41.473Z,1754956001.473 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-08-11T23:46:41.474Z,1754956001.474 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-08-11T23:46:41.474Z,1754956001.474 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-08-11T23:46:41.476Z,1754956001.476 [Startup:StartupSatComms] Running Loop=1 2025-08-11T23:46:41.476Z,1754956001.476 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2025-08-11T23:46:41.476Z,1754956001.476 [Startup:StartupSatComms:A] Running Loop=1 2025-08-11T23:46:41.866Z,1754956001.866 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2025-08-11T23:47:41.665Z,1754956061.665 [Startup:StartupSatComms:A](INFO): Timed out from 2025-08-11T23:46:41.5Z 2025-08-11T23:47:41.665Z,1754956061.665 [Startup:StartupSatComms:A] Stopped 2025-08-11T23:47:41.665Z,1754956061.665 [Startup:StartupSatComms:B] Running Loop=1 2025-08-11T23:47:42.079Z,1754956062.079 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-08-11T23:47:49.159Z,1754956069.159 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250811T184655/Courier0058.lzma 2025-08-11T23:47:50.162Z,1754956070.162 [DataOverHttps](INFO): Moved sent file to Logs/20250811T184655/Courier0058.lzma.bak 2025-08-11T23:47:50.163Z,1754956070.163 [DataOverHttps](INFO): SBD MOMSN=25841815 2025-08-11T23:48:05.952Z,1754956085.952 [DataOverHttps](INFO): Sending 318 bytes from file Logs/20250811T234302/Courier0000.lzma 2025-08-11T23:48:06.953Z,1754956086.953 [DataOverHttps](INFO): Moved sent file to Logs/20250811T234302/Courier0000.lzma.bak 2025-08-11T23:48:06.953Z,1754956086.953 [DataOverHttps](INFO): SBD MOMSN=25841817 2025-08-11T23:48:25.159Z,1754956105.159 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20250811T184655/Express0059.lzma 2025-08-11T23:48:26.161Z,1754956106.161 [DataOverHttps](INFO): Moved sent file to Logs/20250811T184655/Express0059.lzma.bak 2025-08-11T23:48:26.162Z,1754956106.162 [DataOverHttps](INFO): SBD MOMSN=25841823 2025-08-11T23:48:41.862Z,1754956121.862 [Startup:StartupSatComms:B](INFO): Timed out from 2025-08-11T23:47:41.7Z 2025-08-11T23:48:41.862Z,1754956121.862 [Startup:StartupSatComms:B] Stopped 2025-08-11T23:48:41.863Z,1754956121.863 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2025-08-11T23:48:41.863Z,1754956121.863 [Startup:StartupSatComms] Stopped 2025-08-11T23:48:41.863Z,1754956121.863 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2025-08-11T23:48:41.868Z,1754956121.868 [Startup](INFO): Completed Startup 2025-08-11T23:48:41.868Z,1754956121.868 [MissionManager](INFO): Startup is completed. 2025-08-11T23:48:41.868Z,1754956121.868 [MissionManager](INFO): Uninitializing Mission Startup 2025-08-11T23:48:41.868Z,1754956121.868 [Startup] Stopped 2025-08-11T23:48:41.868Z,1754956121.868 [Startup](DEBUG): Aggregate::uninitialize Startup 2025-08-11T23:48:41.869Z,1754956121.869 [Startup:A.GoToSurface] Stopped 2025-08-11T23:48:41.869Z,1754956121.869 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-08-11T23:48:42.329Z,1754956122.329 [MissionManager](IMPORTANT): Started mission Default 2025-08-11T23:48:42.329Z,1754956122.329 [Default] Running Loop=1 2025-08-11T23:48:42.329Z,1754956122.329 [Default](DEBUG): Aggregate::initialize Default 2025-08-11T23:48:42.329Z,1754956122.329 [Default:B.GoToSurface] Running Loop=1 2025-08-11T23:48:42.329Z,1754956122.329 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-08-11T23:48:42.329Z,1754956122.329 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-08-11T23:48:42.330Z,1754956122.330 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-08-11T23:48:42.330Z,1754956122.330 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-08-11T23:48:42.330Z,1754956122.330 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-08-11T23:48:42.331Z,1754956122.331 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-08-11T23:48:42.331Z,1754956122.331 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-08-11T23:48:42.331Z,1754956122.331 [Default:A.Wait] Running Loop=1 2025-08-11T23:48:42.331Z,1754956122.331 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-08-11T23:48:43.188Z,1754956123.188 [DataOverHttps](INFO): Sending 877 bytes from file Logs/20250811T234302/Express0001.lzma 2025-08-11T23:48:44.185Z,1754956124.185 [DataOverHttps](INFO): Moved sent file to Logs/20250811T234302/Express0001.lzma.bak 2025-08-11T23:48:44.185Z,1754956124.185 [DataOverHttps](INFO): SBD MOMSN=25841827 2025-08-11T23:48:55.646Z,1754956135.646 [Default:A.Wait](INFO): Done Waiting. 2025-08-11T23:48:55.646Z,1754956135.646 [Default:A.Wait] Stopped 2025-08-11T23:48:55.646Z,1754956135.646 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-08-11T23:48:55.994Z,1754956135.994 [Default:CheckIn] Running Loop=1 2025-08-11T23:48:55.994Z,1754956135.994 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-08-11T23:48:55.994Z,1754956135.994 [Default:CheckIn:Read_GPS] Running Loop=1 2025-08-11T23:48:56.411Z,1754956136.411 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-08-11T23:48:57.582Z,1754956137.582 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-08-11T23:49:15.788Z,1754956155.788 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2025-08-11T23:49:15.788Z,1754956155.788 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-08-11T23:49:15.808Z,1754956155.808 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-08-11T23:49:16.209Z,1754956156.209 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-08-11T23:49:16.209Z,1754956156.209 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2025-08-11T23:50:51.127Z,1754956251.127 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-08-11T23:50:51.128Z,1754956251.128 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6. 2025-08-11T23:50:51.131Z,1754956251.131 [BPC1](INFO): Received data from all battery sticks. 2025-08-11T23:52:16.788Z,1754956336.788 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2025-08-11T23:52:16.788Z,1754956336.788 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-08-11T23:52:16.799Z,1754956336.799 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-08-11T23:52:17.210Z,1754956337.210 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-08-11T23:52:17.210Z,1754956337.210 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2025-08-11T23:53:08.502Z,1754956388.502 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00000000000000,35.0,+22.0,0000.0,1489.0,I 2025-08-11T23:53:08.502Z,1754956388.502 [DVL_micro](ERROR): Failed to parse: :TS,00000000000000,35.0,+22.0,0000.0,1489.0,I 2025-08-11T23:53:55.761Z,1754956435.761 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-08-11T23:53:55.761Z,1754956435.761 [NAL9602] Data Fault, FailCount= 1 2025-08-11T23:53:55.761Z,1754956435.761 [NAL9602](ERROR): Data Fault 2025-08-11T23:53:55.924Z,1754956435.924 [CBIT](ERROR): Data Fault in component: NAL9602 2025-08-11T23:53:56.261Z,1754956436.261 [NAL9602](INFO): Powering down 2025-08-11T23:53:56.278Z,1754956436.278 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-08-11T23:48:55.0Z 2025-08-11T23:53:56.278Z,1754956436.278 [Default:CheckIn:Read_GPS] Stopped 2025-08-11T23:53:56.278Z,1754956436.278 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-08-11T23:53:56.581Z,1754956436.581 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-08-11T23:53:57.321Z,1754956437.321 [CBIT](INFO): Clearing failed state for component NAL9602 2025-08-11T23:53:57.321Z,1754956437.321 [NAL9602] No Fault, FailCount= 1 2025-08-11T23:53:59.137Z,1754956439.137 [DataOverHttps](INFO): Sending 170 bytes from file Logs/20250811T234302/Courier0004.lzma 2025-08-11T23:53:59.605Z,1754956439.605 [DataOverHttps](INFO): Moved sent file to Logs/20250811T234302/Courier0004.lzma.bak 2025-08-11T23:53:59.605Z,1754956439.605 [DataOverHttps](INFO): SBD MOMSN=25841847 2025-08-11T23:54:16.975Z,1754956456.975 [DataOverHttps](INFO): Sending 205 bytes from file Logs/20250811T234302/Express0005.lzma 2025-08-11T23:54:17.977Z,1754956457.977 [DataOverHttps](INFO): Moved sent file to Logs/20250811T234302/Express0005.lzma.bak 2025-08-11T23:54:17.977Z,1754956457.977 [DataOverHttps](INFO): SBD MOMSN=25841849 2025-08-11T23:54:20.260Z,1754956460.260 [Default:CheckIn:Read_Iridium] Stopped 2025-08-11T23:54:20.261Z,1754956460.261 [Default:CheckIn:C.Wait] Running Loop=1 2025-08-11T23:54:20.261Z,1754956460.261 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-08-11T23:54:26.298Z,1754956466.298 [NAL9602](INFO): Powering up NAL9602 2025-08-11T23:54:37.200Z,1754956477.200 [NAL9602](INFO): NAL9602 initialized 2025-08-11T23:55:17.628Z,1754956517.628 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2025-08-11T23:55:17.628Z,1754956517.628 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-08-11T23:55:17.638Z,1754956517.638 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-08-11T23:55:18.109Z,1754956518.109 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-08-11T23:55:18.109Z,1754956518.109 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2025-08-11T23:58:15.829Z,1754956695.829 [CBIT](INFO): Clearing failed state for component DropWeight 2025-08-11T23:58:15.829Z,1754956695.829 [DropWeight] No Fault, FailCount= 1 2025-08-11T23:58:18.617Z,1754956698.617 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2025-08-11T23:58:18.617Z,1754956698.617 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-08-11T23:58:18.628Z,1754956698.628 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-08-11T23:58:19.039Z,1754956699.039 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-08-11T23:58:19.039Z,1754956699.039 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2025-08-11T23:59:20.841Z,1754956760.841 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-08-11T23:59:20.841Z,1754956760.841 [Default:CheckIn:C.Wait] Stopped 2025-08-11T23:59:20.841Z,1754956760.841 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-08-11T23:59:20.841Z,1754956760.841 [Default:CheckIn:D] Running Loop=1 2025-08-11T23:59:21.237Z,1754956761.237 [Default:CheckIn:D] Stopped 2025-08-11T23:59:21.237Z,1754956761.237 [Default:CheckIn:E] Running Loop=1 2025-08-11T23:59:21.675Z,1754956761.675 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.648463 min 2025-08-11T23:59:21.675Z,1754956761.675 [Default:CheckIn:E] Stopped 2025-08-11T23:59:21.675Z,1754956761.675 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-08-11T23:59:21.675Z,1754956761.675 [Default:CheckIn] Stopped 2025-08-11T23:59:21.675Z,1754956761.675 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-08-11T23:59:21.675Z,1754956761.675 [Default:CheckIn](INFO): Running loop #2 2025-08-11T23:59:21.676Z,1754956761.676 [Default:CheckIn] Running Loop=2 2025-08-11T23:59:21.676Z,1754956761.676 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-08-11T23:59:21.676Z,1754956761.676 [Default:CheckIn:Read_GPS] Running Loop=1 2025-08-12T00:01:19.606Z,1754956879.606 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2025-08-12T00:01:19.606Z,1754956879.606 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-08-12T00:01:19.616Z,1754956879.616 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-08-12T00:01:20.031Z,1754956880.031 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-08-12T00:01:20.031Z,1754956880.031 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2025-08-12T00:04:20.620Z,1754957060.620 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7 2025-08-12T00:04:20.620Z,1754957060.620 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-08-12T00:04:20.669Z,1754957060.669 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-08-12T00:04:21.046Z,1754957061.046 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-08-12T00:04:21.046Z,1754957061.046 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7 2025-08-12T00:04:21.837Z,1754957061.837 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-08-11T23:59:21.7Z 2025-08-12T00:04:21.837Z,1754957061.837 [Default:CheckIn:Read_GPS] Stopped 2025-08-12T00:04:21.837Z,1754957061.837 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-08-12T00:04:29.170Z,1754957069.170 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250811T234302/Courier0007.lzma 2025-08-12T00:04:30.169Z,1754957070.169 [DataOverHttps](INFO): Moved sent file to Logs/20250811T234302/Courier0007.lzma.bak 2025-08-12T00:04:30.169Z,1754957070.169 [DataOverHttps](INFO): SBD MOMSN=25841887 2025-08-12T00:04:40.834Z,1754957080.834 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-08-12T00:04:40.834Z,1754957080.834 [NAL9602] Data Fault, FailCount= 2 2025-08-12T00:04:40.834Z,1754957080.834 [NAL9602](ERROR): Data Fault 2025-08-12T00:04:40.922Z,1754957080.922 [CBIT](ERROR): Data Fault in component: NAL9602 2025-08-12T00:04:41.234Z,1754957081.234 [NAL9602](INFO): Powering down 2025-08-12T00:04:42.193Z,1754957082.193 [CBIT](INFO): Clearing failed state for component NAL9602 2025-08-12T00:04:42.193Z,1754957082.193 [NAL9602] No Fault, FailCount= 2 2025-08-12T00:04:49.519Z,1754957089.519 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20250811T234302/Express0008.lzma 2025-08-12T00:04:50.529Z,1754957090.529 [DataOverHttps](INFO): Moved sent file to Logs/20250811T234302/Express0008.lzma.bak 2025-08-12T00:04:50.529Z,1754957090.529 [DataOverHttps](INFO): SBD MOMSN=25841889 2025-08-12T00:04:52.960Z,1754957092.960 [Default:CheckIn:Read_Iridium] Stopped 2025-08-12T00:04:52.960Z,1754957092.960 [Default:CheckIn:C.Wait] Running Loop=1 2025-08-12T00:04:52.961Z,1754957092.961 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-08-12T00:05:11.535Z,1754957111.535 [NAL9602](INFO): Powering up NAL9602 2025-08-12T00:05:22.449Z,1754957122.449 [NAL9602](INFO): NAL9602 initialized 2025-08-12T00:07:21.636Z,1754957241.636 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8 2025-08-12T00:07:21.636Z,1754957241.636 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-08-12T00:07:21.646Z,1754957241.646 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-08-12T00:07:22.069Z,1754957242.069 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-08-12T00:07:22.069Z,1754957242.069 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8 2025-08-12T00:09:53.552Z,1754957393.552 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-08-12T00:09:53.552Z,1754957393.552 [Default:CheckIn:C.Wait] Stopped 2025-08-12T00:09:53.552Z,1754957393.552 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-08-12T00:09:53.553Z,1754957393.553 [Default:CheckIn:D] Running Loop=1 2025-08-12T00:09:53.930Z,1754957393.930 [Default:CheckIn:D] Stopped 2025-08-12T00:09:53.930Z,1754957393.930 [Default:CheckIn:E] Running Loop=1 2025-08-12T00:09:54.337Z,1754957394.337 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.193357 min 2025-08-12T00:09:54.338Z,1754957394.338 [Default:CheckIn:E] Stopped 2025-08-12T00:09:54.338Z,1754957394.338 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-08-12T00:09:54.338Z,1754957394.338 [Default:CheckIn] Stopped 2025-08-12T00:09:54.338Z,1754957394.338 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-08-12T00:09:54.338Z,1754957394.338 [Default:CheckIn](INFO): Running loop #3 2025-08-12T00:09:54.338Z,1754957394.338 [Default:CheckIn] Running Loop=3 2025-08-12T00:09:54.338Z,1754957394.338 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-08-12T00:09:54.338Z,1754957394.338 [Default:CheckIn:Read_GPS] Running Loop=1 2025-08-12T00:10:22.643Z,1754957422.643 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9 2025-08-12T00:10:22.643Z,1754957422.643 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-08-12T00:10:22.654Z,1754957422.654 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-08-12T00:10:23.029Z,1754957423.029 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-08-12T00:10:23.029Z,1754957423.029 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9 2025-08-12T00:13:23.608Z,1754957603.608 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10 2025-08-12T00:13:23.608Z,1754957603.608 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-08-12T00:13:23.618Z,1754957603.618 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-08-12T00:13:24.030Z,1754957604.030 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-08-12T00:13:24.031Z,1754957604.031 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10 2025-08-12T00:14:54.588Z,1754957694.588 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-08-12T00:09:54.3Z 2025-08-12T00:14:54.588Z,1754957694.588 [Default:CheckIn:Read_GPS] Stopped 2025-08-12T00:14:54.588Z,1754957694.588 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-08-12T00:15:02.279Z,1754957702.279 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250811T234302/Courier0010.lzma 2025-08-12T00:15:03.281Z,1754957703.281 [DataOverHttps](INFO): Moved sent file to Logs/20250811T234302/Courier0010.lzma.bak 2025-08-12T00:15:03.281Z,1754957703.281 [DataOverHttps](INFO): SBD MOMSN=25841916 2025-08-12T00:15:20.299Z,1754957720.299 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20250811T234302/Express0011.lzma 2025-08-12T00:15:21.302Z,1754957721.302 [DataOverHttps](INFO): Moved sent file to Logs/20250811T234302/Express0011.lzma.bak 2025-08-12T00:15:21.302Z,1754957721.302 [DataOverHttps](INFO): SBD MOMSN=25841918 2025-08-12T00:15:23.673Z,1754957723.673 [Default:CheckIn:Read_Iridium] Stopped 2025-08-12T00:15:23.673Z,1754957723.673 [Default:CheckIn:C.Wait] Running Loop=1 2025-08-12T00:15:23.673Z,1754957723.673 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-08-12T00:15:24.043Z,1754957724.043 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-08-12T00:15:24.043Z,1754957724.043 [NAL9602] Data Fault, FailCount= 3 2025-08-12T00:15:24.043Z,1754957724.043 [NAL9602](ERROR): Data Fault 2025-08-12T00:15:24.112Z,1754957724.112 [CBIT](ERROR): Data Fault in component: NAL9602 2025-08-12T00:15:24.453Z,1754957724.453 [NAL9602](INFO): Powering down 2025-08-12T00:15:25.262Z,1754957725.262 [CBIT](INFO): Clearing failed state for component NAL9602 2025-08-12T00:15:25.262Z,1754957725.262 [NAL9602] No Fault, FailCount= 3 2025-08-12T00:15:54.740Z,1754957754.740 [NAL9602](INFO): Powering up NAL9602 2025-08-12T00:16:05.649Z,1754957765.649 [NAL9602](INFO): NAL9602 initialized 2025-08-12T00:16:24.645Z,1754957784.645 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11 2025-08-12T00:16:24.645Z,1754957784.645 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-08-12T00:16:24.689Z,1754957784.689 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-08-12T00:16:25.087Z,1754957785.087 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-08-12T00:16:25.089Z,1754957785.089 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11 2025-08-12T00:19:25.641Z,1754957965.641 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12 2025-08-12T00:19:25.641Z,1754957965.641 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-08-12T00:19:25.652Z,1754957965.652 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-08-12T00:19:26.064Z,1754957966.064 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-08-12T00:19:26.065Z,1754957966.065 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12 2025-08-12T00:20:24.215Z,1754958024.215 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-08-12T00:20:24.215Z,1754958024.215 [Default:CheckIn:C.Wait] Stopped 2025-08-12T00:20:24.215Z,1754958024.215 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-08-12T00:20:24.215Z,1754958024.215 [Default:CheckIn:D] Running Loop=1 2025-08-12T00:20:24.617Z,1754958024.617 [Default:CheckIn:D] Stopped 2025-08-12T00:20:24.617Z,1754958024.617 [Default:CheckIn:E] Running Loop=1 2025-08-12T00:20:25.049Z,1754958025.049 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.704810 min 2025-08-12T00:20:25.049Z,1754958025.049 [Default:CheckIn:E] Stopped 2025-08-12T00:20:25.049Z,1754958025.049 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-08-12T00:20:25.050Z,1754958025.050 [Default:CheckIn] Stopped 2025-08-12T00:20:25.050Z,1754958025.050 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-08-12T00:20:25.050Z,1754958025.050 [Default:CheckIn](INFO): Running loop #4 2025-08-12T00:20:25.050Z,1754958025.050 [Default:CheckIn] Running Loop=4 2025-08-12T00:20:25.050Z,1754958025.050 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-08-12T00:20:25.050Z,1754958025.050 [Default:CheckIn:Read_GPS] Running Loop=1 2025-08-12T00:22:26.629Z,1754958146.629 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13 2025-08-12T00:22:26.629Z,1754958146.629 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-08-12T00:22:26.639Z,1754958146.639 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-08-12T00:22:27.046Z,1754958147.046 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-08-12T00:22:27.046Z,1754958147.046 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13 2025-08-12T00:25:25.230Z,1754958325.230 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-08-12T00:20:25.1Z 2025-08-12T00:25:25.230Z,1754958325.230 [Default:CheckIn:Read_GPS] Stopped 2025-08-12T00:25:25.230Z,1754958325.230 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-08-12T00:25:27.626Z,1754958327.626 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14 2025-08-12T00:25:27.626Z,1754958327.626 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-08-12T00:25:27.642Z,1754958327.642 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-08-12T00:25:28.102Z,1754958328.102 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-08-12T00:25:28.102Z,1754958328.102 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14 2025-08-12T00:25:32.399Z,1754958332.399 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250811T234302/Courier0013.lzma 2025-08-12T00:25:33.401Z,1754958333.401 [DataOverHttps](INFO): Moved sent file to Logs/20250811T234302/Courier0013.lzma.bak 2025-08-12T00:25:33.401Z,1754958333.401 [DataOverHttps](INFO): SBD MOMSN=25841931 2025-08-12T00:25:50.671Z,1754958350.671 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250811T234302/Express0014.lzma 2025-08-12T00:25:51.674Z,1754958351.674 [DataOverHttps](INFO): Moved sent file to Logs/20250811T234302/Express0014.lzma.bak 2025-08-12T00:25:51.674Z,1754958351.674 [DataOverHttps](INFO): SBD MOMSN=25841933 2025-08-12T00:25:53.904Z,1754958353.904 [Default:CheckIn:Read_Iridium] Stopped 2025-08-12T00:25:53.905Z,1754958353.905 [Default:CheckIn:C.Wait] Running Loop=1 2025-08-12T00:25:53.905Z,1754958353.905 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-08-12T00:26:08.014Z,1754958368.014 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-08-12T00:26:08.014Z,1754958368.014 [NAL9602] Data Fault, FailCount= 4 2025-08-12T00:26:08.015Z,1754958368.015 [NAL9602](ERROR): Data Fault 2025-08-12T00:26:08.032Z,1754958368.032 [CBIT](ERROR): Data Fault in component: NAL9602 2025-08-12T00:26:08.419Z,1754958368.419 [NAL9602](INFO): Powering down 2025-08-12T00:26:09.256Z,1754958369.256 [CBIT](INFO): Clearing failed state for component NAL9602 2025-08-12T00:26:09.256Z,1754958369.256 [NAL9602] No Fault, FailCount= 4 2025-08-12T00:26:38.713Z,1754958398.713 [NAL9602](INFO): Powering up NAL9602 2025-08-12T00:26:49.622Z,1754958409.622 [NAL9602](INFO): NAL9602 initialized 2025-08-12T00:28:17.309Z,1754958497.309 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-08-12T00:28:17.309Z,1754958497.309 [DropWeight] Hardware Fault, FailCount= 1 2025-08-12T00:28:17.309Z,1754958497.309 [DropWeight](ERROR): Hardware Fault 2025-08-12T00:28:17.394Z,1754958497.394 [CBIT](INFO): Critical error at 20250812T002817 2025-08-12T00:28:17.396Z,1754958497.396 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-08-12T00:28:17.397Z,1754958497.397 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-08-12T00:28:17.760Z,1754958497.760 [CBIT](INFO): Critical error at 20250812T002817 2025-08-12T00:28:28.652Z,1754958508.652 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15 2025-08-12T00:28:28.652Z,1754958508.652 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-08-12T00:28:28.663Z,1754958508.663 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-08-12T00:28:29.059Z,1754958509.059 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-08-12T00:28:29.059Z,1754958509.059 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15 2025-08-12T00:30:54.553Z,1754958654.553 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-08-12T00:30:54.553Z,1754958654.553 [Default:CheckIn:C.Wait] Stopped 2025-08-12T00:30:54.553Z,1754958654.553 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-08-12T00:30:54.554Z,1754958654.554 [Default:CheckIn:D] Running Loop=1 2025-08-12T00:30:54.969Z,1754958654.969 [Default:CheckIn:D] Stopped 2025-08-12T00:30:54.969Z,1754958654.969 [Default:CheckIn:E] Running Loop=1 2025-08-12T00:30:55.381Z,1754958655.381 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.210665 min 2025-08-12T00:30:55.381Z,1754958655.381 [Default:CheckIn:E] Stopped 2025-08-12T00:30:55.381Z,1754958655.381 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-08-12T00:30:55.381Z,1754958655.381 [Default:CheckIn] Stopped 2025-08-12T00:30:55.381Z,1754958655.381 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-08-12T00:30:55.382Z,1754958655.382 [Default:CheckIn](INFO): Running loop #5 2025-08-12T00:30:55.382Z,1754958655.382 [Default:CheckIn] Running Loop=5 2025-08-12T00:30:55.382Z,1754958655.382 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-08-12T00:30:55.382Z,1754958655.382 [Default:CheckIn:Read_GPS] Running Loop=1 2025-08-12T00:31:29.704Z,1754958689.704 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16 2025-08-12T00:31:29.704Z,1754958689.704 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-08-12T00:31:29.714Z,1754958689.714 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-08-12T00:31:30.095Z,1754958690.095 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-08-12T00:31:30.095Z,1754958690.095 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16 2025-08-12T00:34:30.680Z,1754958870.680 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17 2025-08-12T00:34:30.680Z,1754958870.680 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-08-12T00:34:30.696Z,1754958870.696 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-08-12T00:34:31.105Z,1754958871.105 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-08-12T00:34:31.105Z,1754958871.105 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17 2025-08-12T00:35:55.523Z,1754958955.523 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-08-12T00:30:55.4Z 2025-08-12T00:35:55.539Z,1754958955.539 [Default:CheckIn:Read_GPS] Stopped 2025-08-12T00:35:55.539Z,1754958955.539 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-08-12T00:36:03.131Z,1754958963.131 [DataOverHttps](INFO): Sending 115 bytes from file Logs/20250811T234302/Courier0016.lzma 2025-08-12T00:36:04.133Z,1754958964.133 [DataOverHttps](INFO): Moved sent file to Logs/20250811T234302/Courier0016.lzma.bak 2025-08-12T00:36:04.133Z,1754958964.133 [DataOverHttps](INFO): SBD MOMSN=25841937 2025-08-12T00:36:22.397Z,1754958982.397 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20250811T234302/Express0017.lzma 2025-08-12T00:36:23.401Z,1754958983.401 [DataOverHttps](INFO): Moved sent file to Logs/20250811T234302/Express0017.lzma.bak 2025-08-12T00:36:23.401Z,1754958983.401 [DataOverHttps](INFO): SBD MOMSN=25841941 2025-08-12T00:36:25.844Z,1754958985.844 [Default:CheckIn:Read_Iridium] Stopped 2025-08-12T00:36:25.844Z,1754958985.844 [Default:CheckIn:C.Wait] Running Loop=1 2025-08-12T00:36:25.844Z,1754958985.844 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-08-12T00:36:50.452Z,1754959010.452 [NAL9602](FAULT): GPS failed to acquire within timeout. 2025-08-12T00:36:50.452Z,1754959010.452 [NAL9602] Data Fault, FailCount= 5 2025-08-12T00:36:50.452Z,1754959010.452 [NAL9602](ERROR): Data Fault 2025-08-12T00:36:50.469Z,1754959010.469 [CBIT](ERROR): Data Fault in component: NAL9602 2025-08-12T00:36:50.469Z,1754959010.469 [CBIT](CRITICAL): Data Fault in component: NAL9602 2025-08-12T00:36:50.866Z,1754959010.866 [NAL9602](INFO): Powering down 2025-08-12T00:36:50.882Z,1754959010.882 [CBIT](INFO): Critical error at 20250812T003650 2025-08-12T00:37:31.665Z,1754959051.665 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18 2025-08-12T00:37:31.665Z,1754959051.665 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-08-12T00:37:31.676Z,1754959051.676 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-08-12T00:37:32.098Z,1754959052.098 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-08-12T00:37:32.098Z,1754959052.098 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18 2025-08-12T00:38:50.479Z,1754959130.479 [CBIT](INFO): Clearing failed state for component NAL9602 2025-08-12T00:38:50.479Z,1754959130.479 [NAL9602] No Fault, FailCount= 5 2025-08-12T00:38:50.845Z,1754959130.845 [NAL9602](INFO): Powering up NAL9602 2025-08-12T00:39:01.750Z,1754959141.750 [NAL9602](INFO): NAL9602 initialized 2025-08-12T00:40:24.585Z,1754959224.585 [CommandExec](IMPORTANT): got command quit 2025-08-12T00:40:25.588Z,1754959225.588 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-08-12T00:40:25.588Z,1754959225.588 [CommandExec](INFO): Uninitializing the command executive. 2025-08-12T00:40:25.588Z,1754959225.588 [CommandExec](INFO): Uninitializing the command scheduler. 2025-08-12T00:40:25.588Z,1754959225.588 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-08-12T00:40:25.652Z,1754959225.652 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-08-12T00:40:25.652Z,1754959225.652 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-08-12T00:40:25.652Z,1754959225.652 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-08-12T00:40:25.653Z,1754959225.653 [NavChartDb](INFO): Join timeout helper Thread ID is 8284 2025-08-12T00:40:25.896Z,1754959225.896 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-08-12T00:40:25.896Z,1754959225.896 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-08-12T00:40:25.899Z,1754959225.899 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-08-12T00:40:25.899Z,1754959225.899 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-08-12T00:40:25.900Z,1754959225.900 [Radio_Surface](INFO): Join timeout helper Thread ID is 8285 2025-08-12T00:40:26.104Z,1754959226.104 [Radio_Surface](INFO): Powering down 2025-08-12T00:40:26.105Z,1754959226.105 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-08-12T00:40:26.105Z,1754959226.105 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-08-12T00:40:26.119Z,1754959226.119 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-08-12T00:40:26.119Z,1754959226.119 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-08-12T00:40:26.120Z,1754959226.120 [Onboard](INFO): Join timeout helper Thread ID is 8286 2025-08-12T00:40:26.724Z,1754959226.724 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2025-08-12T00:40:27.200Z,1754959227.200 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2025-08-12T00:40:27.200Z,1754959227.200 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-08-12T00:40:27.211Z,1754959227.211 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2025-08-12T00:40:27.211Z,1754959227.211 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-08-12T00:40:27.212Z,1754959227.212 [DataOverHttps](INFO): Join timeout helper Thread ID is 8287 2025-08-12T00:40:27.728Z,1754959227.728 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2025-08-12T00:40:27.728Z,1754959227.728 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-08-12T00:40:27.731Z,1754959227.731 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2025-08-12T00:40:27.731Z,1754959227.731 [DAT ThreadHandler](INFO): Thread cancelled. 2025-08-12T00:40:27.732Z,1754959227.732 [DAT](INFO): Join timeout helper Thread ID is 8288 2025-08-12T00:40:27.900Z,1754959227.900 [DAT](INFO): Powering down 2025-08-12T00:40:27.976Z,1754959227.976 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2025-08-12T00:40:27.976Z,1754959227.976 [DAT](INFO): Powering down 2025-08-12T00:40:27.977Z,1754959227.977 [DAT ThreadHandler](INFO): Thread cancelled. 2025-08-12T00:40:27.996Z,1754959227.996 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2025-08-12T00:40:27.996Z,1754959227.996 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-08-12T00:40:27.997Z,1754959227.997 [BackseatComponent](INFO): Join timeout helper Thread ID is 8289 2025-08-12T00:40:28.108Z,1754959228.108 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2025-08-12T00:40:28.108Z,1754959228.108 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-08-12T00:40:28.125Z,1754959228.125 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-08-12T00:40:28.125Z,1754959228.125 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-08-12T00:40:28.125Z,1754959228.125 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 8290 2025-08-12T00:40:28.428Z,1754959228.428 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-08-12T00:40:28.428Z,1754959228.428 [WetLabsBB2FL](INFO): Powering down 2025-08-12T00:40:28.429Z,1754959228.429 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-08-12T00:40:28.448Z,1754959228.448 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-08-12T00:40:28.448Z,1754959228.448 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-08-12T00:40:28.449Z,1754959228.449 [CTD_Seabird](INFO): Join timeout helper Thread ID is 8291 2025-08-12T00:40:28.800Z,1754959228.800 [CTD_Seabird](INFO): Powering down 2025-08-12T00:40:28.811Z,1754959228.811 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-08-12T00:40:28.812Z,1754959228.812 [CTD_Seabird](INFO): Powering down 2025-08-12T00:40:28.823Z,1754959228.823 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-08-12T00:40:28.828Z,1754959228.828 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2025-08-12T00:40:28.828Z,1754959228.828 [logger ThreadHandler](INFO): Thread cancelled. 2025-08-12T00:40:28.829Z,1754959228.829 [logger](INFO): Join timeout helper Thread ID is 8292 2025-08-12T00:40:28.843Z,1754959228.843 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2025-08-12T00:40:28.844Z,1754959228.844 [logger ThreadHandler](INFO): Thread cancelled. 2025-08-12T00:40:28.848Z,1754959228.848 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2025-08-12T00:40:28.848Z,1754959228.848 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-08-12T00:40:28.849Z,1754959228.849 [CommandLine](INFO): Join timeout helper Thread ID is 8293 2025-08-12T00:40:28.904Z,1754959228.904 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2025-08-12T00:40:28.904Z,1754959228.904 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-08-12T00:40:28.908Z,1754959228.908 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2025-08-12T00:40:28.908Z,1754959228.908 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-08-12T00:40:28.909Z,1754959228.909 [CommandExec](INFO): Join timeout helper Thread ID is 8294 2025-08-12T00:40:28.910Z,1754959228.910 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2025-08-12T00:40:28.910Z,1754959228.910 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-08-12T00:40:28.910Z,1754959228.910 [controlThread](INFO): Join timeout helper Thread ID is 8295 2025-08-12T00:40:28.996Z,1754959228.996 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2025-08-12T00:40:28.996Z,1754959228.996 [controlThread](DEBUG): Uninitializing ControlThread 2025-08-12T00:40:28.997Z,1754959228.997 [AHRS_M2](INFO): Powering down 2025-08-12T00:40:29.171Z,1754959229.171 [DVL_micro](INFO): Powering down 2025-08-12T00:40:29.172Z,1754959229.172 [NAL9602](INFO): Powering down 2025-08-12T00:40:29.174Z,1754959229.174 [Sonardyne_Nano](INFO): Powering down 2025-08-12T00:40:29.380Z,1754959229.380 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2025-08-12T00:40:29.380Z,1754959229.380 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2025-08-12T00:40:29.381Z,1754959229.381 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2025-08-12T00:40:29.381Z,1754959229.381 [MissionManager](INFO): Uninitializing Mission Default 2025-08-12T00:40:29.381Z,1754959229.381 [Default] Stopped 2025-08-12T00:40:29.382Z,1754959229.382 [Default](DEBUG): Aggregate::uninitialize Default 2025-08-12T00:40:29.382Z,1754959229.382 [Default:B.GoToSurface] Stopped 2025-08-12T00:40:29.382Z,1754959229.382 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-08-12T00:40:29.382Z,1754959229.382 [Default:CheckIn] Stopped 2025-08-12T00:40:29.382Z,1754959229.382 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-08-12T00:40:29.382Z,1754959229.382 [Default:CheckIn:C.Wait] Stopped 2025-08-12T00:40:29.382Z,1754959229.382 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-08-12T00:40:29.385Z,1754959229.385 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2025-08-12T00:40:29.385Z,1754959229.385 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2025-08-12T00:40:29.386Z,1754959229.386 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2025-08-12T00:40:29.386Z,1754959229.386 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2025-08-12T00:40:29.386Z,1754959229.386 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-08-12T00:40:29.386Z,1754959229.386 [BuoyancyServo](INFO): Powering down 2025-08-12T00:40:29.399Z,1754959229.399 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-08-12T00:40:29.400Z,1754959229.400 [ElevatorServo](INFO): Powering down 2025-08-12T00:40:29.400Z,1754959229.400 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-08-12T00:40:29.401Z,1754959229.401 [MassServo](INFO): Powering down 2025-08-12T00:40:29.401Z,1754959229.401 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2025-08-12T00:40:29.401Z,1754959229.401 [RudderServo](INFO): Powering down 2025-08-12T00:40:29.402Z,1754959229.402 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-08-12T00:40:29.402Z,1754959229.402 [ThrusterHE](INFO): Powering down 2025-08-12T00:40:29.403Z,1754959229.403 [SBIT](DEBUG): Uninitialize SBIT Component. 2025-08-12T00:40:29.404Z,1754959229.404 [IBIT](DEBUG): Uninitialize IBIT Component. 2025-08-12T00:40:29.404Z,1754959229.404 [CBIT](DEBUG): Uninitialize CBIT Component. 2025-08-12T00:40:29.404Z,1754959229.404 [CBIT](DEBUG): Powering off loads. 2025-08-12T00:40:29.415Z,1754959229.415 [CBIT](DEBUG): Disabling WDT. 2025-08-12T00:40:29.428Z,1754959229.428 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-08-12T00:40:29.428Z,1754959229.428 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-08-12T00:40:29.429Z,1754959229.429 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-08-12T00:40:29.465Z,1754959229.465 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-08-12T00:40:29.465Z,1754959229.465 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-08-12T00:40:29.480Z,1754959229.480 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-08-12T00:40:29.487Z,1754959229.487 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-08-12T00:40:29.517Z,1754959229.517 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-08-12T00:40:29.520Z,1754959229.520 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-08-12T00:40:29.527Z,1754959229.527 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-08-12T00:40:29.533Z,1754959229.533 [DAT ThreadHandler](INFO): Thread cancelled. 2025-08-12T00:40:29.574Z,1754959229.574 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-08-12T00:40:29.627Z,1754959229.627 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-08-12T00:40:29.691Z,1754959229.691 [logger ThreadHandler](INFO): Thread cancelled.