2025-12-08T02:55:49.174Z,1765162549.174 [Supervisor](DEBUG): Initializing supervisor. 2025-12-08T02:55:49.178Z,1765162549.178 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2025-12-08T02:55:49.179Z,1765162549.179 [SyncHandler](INFO): Protected caller Thread ID is 1346 2025-12-08T02:55:49.179Z,1765162549.179 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2025-12-08T02:55:49.180Z,1765162549.180 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2025-12-08T02:55:49.181Z,1765162549.181 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1347 2025-12-08T02:55:49.185Z,1765162549.185 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2025-12-08T02:55:49.204Z,1765162549.204 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2025-12-08T02:55:49.205Z,1765162549.205 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2025-12-08T02:55:49.205Z,1765162549.205 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1348 2025-12-08T02:55:49.209Z,1765162549.209 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2025-12-08T02:55:49.210Z,1765162549.210 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2025-12-08T02:55:49.210Z,1765162549.210 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1349 2025-12-08T02:55:49.213Z,1765162549.213 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2025-12-08T02:55:49.213Z,1765162549.213 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2025-12-08T02:55:49.214Z,1765162549.214 [logger ThreadHandler](INFO): Protected caller Thread ID is 1350 2025-12-08T02:55:49.217Z,1765162549.217 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2025-12-08T02:55:49.218Z,1765162549.218 [Supervisor](INFO): Looking for Config files in directory: Config/ 2025-12-08T02:55:49.223Z,1765162549.223 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2025-12-08T02:55:49.405Z,1765162549.405 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2025-12-08T02:55:49.407Z,1765162549.407 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2025-12-08T02:55:49.635Z,1765162549.635 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2025-12-08T02:55:49.636Z,1765162549.636 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2025-12-08T02:55:49.896Z,1765162549.896 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2025-12-08T02:55:49.898Z,1765162549.898 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2025-12-08T02:55:50.012Z,1765162550.012 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2025-12-08T02:55:50.014Z,1765162550.014 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2025-12-08T02:55:50.746Z,1765162550.746 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2025-12-08T02:55:50.746Z,1765162550.746 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2025-12-08T02:55:51.251Z,1765162551.251 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2025-12-08T02:55:51.251Z,1765162551.251 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2025-12-08T02:55:51.772Z,1765162551.772 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2025-12-08T02:55:51.774Z,1765162551.774 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2025-12-08T02:55:52.557Z,1765162552.557 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2025-12-08T02:55:52.561Z,1765162552.561 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2025-12-08T02:55:52.900Z,1765162552.900 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2025-12-08T02:55:52.902Z,1765162552.902 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2025-12-08T02:55:53.293Z,1765162553.293 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2025-12-08T02:55:53.293Z,1765162553.293 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2025-12-08T02:55:53.522Z,1765162553.522 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2025-12-08T02:55:53.524Z,1765162553.524 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2025-12-08T02:55:53.675Z,1765162553.675 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2025-12-08T02:55:53.675Z,1765162553.675 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2025-12-08T02:55:53.775Z,1765162553.775 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance 2025-12-08T02:55:53.776Z,1765162553.776 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2025-12-08T02:55:53.864Z,1765162553.864 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2025-12-08T02:55:54.048Z,1765162554.048 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2025-12-08T02:55:54.049Z,1765162554.049 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2025-12-08T02:55:54.295Z,1765162554.295 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2025-12-08T02:55:54.297Z,1765162554.297 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2025-12-08T02:55:54.299Z,1765162554.299 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2025-12-08T02:55:54.392Z,1765162554.392 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2025-12-08T02:55:54.723Z,1765162554.723 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2025-12-08T02:55:54.724Z,1765162554.724 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2025-12-08T02:55:54.818Z,1765162554.818 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2025-12-08T02:55:54.991Z,1765162554.991 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2025-12-08T02:55:55.100Z,1765162555.100 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2025-12-08T02:55:55.216Z,1765162555.216 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2025-12-08T02:55:55.333Z,1765162555.333 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2025-12-08T02:55:55.435Z,1765162555.435 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2025-12-08T02:55:55.596Z,1765162555.596 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Derivation.cfg 2025-12-08T02:55:55.701Z,1765162555.701 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/workSite.cfg 2025-12-08T02:55:55.794Z,1765162555.794 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2025-12-08T02:55:55.898Z,1765162555.898 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2025-12-08T02:55:55.993Z,1765162555.993 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2025-12-08T02:55:56.003Z,1765162556.003 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2025-12-08T02:55:56.208Z,1765162556.208 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2025-12-08T02:55:56.209Z,1765162556.209 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2025-12-08T02:55:56.393Z,1765162556.393 [BuoyancyServo] Loaded 2025-12-08T02:55:56.393Z,1765162556.393 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2025-12-08T02:55:56.417Z,1765162556.417 [ElevatorServo] Loaded 2025-12-08T02:55:56.417Z,1765162556.417 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2025-12-08T02:55:56.438Z,1765162556.438 [MassServo] Loaded 2025-12-08T02:55:56.439Z,1765162556.439 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2025-12-08T02:55:56.461Z,1765162556.461 [RudderServo] Loaded 2025-12-08T02:55:56.461Z,1765162556.461 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2025-12-08T02:55:56.478Z,1765162556.478 [ThrusterHE] Loaded 2025-12-08T02:55:56.479Z,1765162556.479 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2025-12-08T02:55:56.479Z,1765162556.479 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2025-12-08T02:55:56.479Z,1765162556.479 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2025-12-08T02:55:56.666Z,1765162556.666 [SBIT](DEBUG): Construct Startup Built In Test. 2025-12-08T02:55:56.681Z,1765162556.681 [SBIT] Loaded 2025-12-08T02:55:56.681Z,1765162556.681 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2025-12-08T02:55:56.684Z,1765162556.684 [IBIT](DEBUG): Construct Initiated Built In Test. 2025-12-08T02:55:56.704Z,1765162556.704 [IBIT] Loaded 2025-12-08T02:55:56.704Z,1765162556.704 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2025-12-08T02:55:56.709Z,1765162556.709 [CBIT](DEBUG): Construct Continuous Built In Test. 2025-12-08T02:55:56.903Z,1765162556.903 [CBIT] Loaded 2025-12-08T02:55:56.903Z,1765162556.903 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2025-12-08T02:55:56.933Z,1765162556.933 [GFScanner] Loaded 2025-12-08T02:55:56.934Z,1765162556.934 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread. 2025-12-08T02:55:56.934Z,1765162556.934 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2025-12-08T02:55:56.934Z,1765162556.934 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2025-12-08T02:55:57.138Z,1765162557.138 [CTD_Seabird] Loaded 2025-12-08T02:55:57.138Z,1765162557.138 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2025-12-08T02:55:57.139Z,1765162557.139 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406874E0 2025-12-08T02:55:57.140Z,1765162557.140 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1438 2025-12-08T02:55:57.174Z,1765162557.174 [ESPComponent] Loaded 2025-12-08T02:55:57.175Z,1765162557.175 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2025-12-08T02:55:57.196Z,1765162557.196 [PAR_Licor] Loaded 2025-12-08T02:55:57.196Z,1765162557.196 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2025-12-08T02:55:57.239Z,1765162557.239 [WetLabsBB2FL] Loaded 2025-12-08T02:55:57.239Z,1765162557.239 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2025-12-08T02:55:57.240Z,1765162557.240 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406B74E0 2025-12-08T02:55:57.241Z,1765162557.241 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1439 2025-12-08T02:55:57.241Z,1765162557.241 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2025-12-08T02:55:57.242Z,1765162557.242 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2025-12-08T02:55:57.308Z,1765162557.308 [DepthRateCalculator] Loaded 2025-12-08T02:55:57.308Z,1765162557.308 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2025-12-08T02:55:57.313Z,1765162557.313 [PitchRateCalculator] Loaded 2025-12-08T02:55:57.314Z,1765162557.314 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2025-12-08T02:55:57.324Z,1765162557.324 [SpeedCalculator] Loaded 2025-12-08T02:55:57.324Z,1765162557.324 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2025-12-08T02:55:57.329Z,1765162557.329 [YawRateCalculator] Loaded 2025-12-08T02:55:57.329Z,1765162557.329 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2025-12-08T02:55:57.348Z,1765162557.348 [ElevatorOffsetCalculator] Loaded 2025-12-08T02:55:57.349Z,1765162557.349 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2025-12-08T02:55:57.349Z,1765162557.349 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2025-12-08T02:55:57.349Z,1765162557.349 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2025-12-08T02:55:57.508Z,1765162557.508 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2025-12-08T02:55:57.508Z,1765162557.508 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2025-12-08T02:55:58.394Z,1765162558.394 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2025-12-08T02:55:58.394Z,1765162558.394 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2025-12-08T02:55:58.593Z,1765162558.593 [DeadReckonUsingMultipleVelocitySources] Loaded 2025-12-08T02:55:58.593Z,1765162558.593 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2025-12-08T02:55:58.607Z,1765162558.607 [NavChart] Loaded 2025-12-08T02:55:58.607Z,1765162558.607 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2025-12-08T02:55:58.613Z,1765162558.613 [UniversalFixResidualReporter] Loaded 2025-12-08T02:55:58.613Z,1765162558.613 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2025-12-08T02:55:58.614Z,1765162558.614 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2025-12-08T02:55:58.614Z,1765162558.614 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2025-12-08T02:55:59.234Z,1765162559.234 [AHRS_M2] Loaded 2025-12-08T02:55:59.234Z,1765162559.234 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2025-12-08T02:55:59.273Z,1765162559.273 [BackseatComponent] Loaded 2025-12-08T02:55:59.273Z,1765162559.273 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2025-12-08T02:55:59.274Z,1765162559.274 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A854E0 2025-12-08T02:55:59.274Z,1765162559.274 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1440 2025-12-08T02:55:59.277Z,1765162559.277 [LcmPublisher] Loaded 2025-12-08T02:55:59.278Z,1765162559.278 [ComponentRegistry](DEBUG): SyncComponent "LcmPublisher" handled in the control thread. 2025-12-08T02:56:00.015Z,1765162560.015 [BPC1] Loaded 2025-12-08T02:56:00.016Z,1765162560.016 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2025-12-08T02:56:00.115Z,1765162560.115 [DAT] Loaded 2025-12-08T02:56:00.116Z,1765162560.116 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2025-12-08T02:56:00.117Z,1765162560.117 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40AB54E0 2025-12-08T02:56:00.117Z,1765162560.117 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1441 2025-12-08T02:56:00.190Z,1765162560.190 [DataOverHttps] Loaded 2025-12-08T02:56:00.190Z,1765162560.190 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2025-12-08T02:56:00.191Z,1765162560.191 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40AE54E0 2025-12-08T02:56:00.192Z,1765162560.192 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1442 2025-12-08T02:56:00.215Z,1765162560.215 [Depth_Keller] Loaded 2025-12-08T02:56:00.215Z,1765162560.215 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2025-12-08T02:56:00.220Z,1765162560.220 [DropWeight] Loaded 2025-12-08T02:56:00.221Z,1765162560.221 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2025-12-08T02:56:00.281Z,1765162560.281 [NAL9602] Loaded 2025-12-08T02:56:00.282Z,1765162560.282 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2025-12-08T02:56:00.307Z,1765162560.307 [Onboard] Loaded 2025-12-08T02:56:00.308Z,1765162560.308 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2025-12-08T02:56:00.309Z,1765162560.309 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40B154E0 2025-12-08T02:56:00.309Z,1765162560.309 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1443 2025-12-08T02:56:00.315Z,1765162560.315 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3 2025-12-08T02:56:00.328Z,1765162560.328 [PowerOnly] Loaded 2025-12-08T02:56:00.329Z,1765162560.329 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2025-12-08T02:56:00.342Z,1765162560.342 [Power24vConverter] Loaded 2025-12-08T02:56:00.343Z,1765162560.343 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2025-12-08T02:56:00.359Z,1765162560.359 [Radio_Surface] Loaded 2025-12-08T02:56:00.359Z,1765162560.359 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2025-12-08T02:56:00.360Z,1765162560.360 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40B454E0 2025-12-08T02:56:00.360Z,1765162560.360 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1444 2025-12-08T02:56:00.376Z,1765162560.376 [Sonardyne_Nano] Loaded 2025-12-08T02:56:00.376Z,1765162560.376 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2025-12-08T02:56:00.377Z,1765162560.377 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2025-12-08T02:56:00.377Z,1765162560.377 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2025-12-08T02:56:00.458Z,1765162560.458 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2025-12-08T02:56:00.458Z,1765162560.458 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2025-12-08T02:56:00.522Z,1765162560.522 [VerticalControl](DEBUG): Construct VerticalControl. 2025-12-08T02:56:00.572Z,1765162560.572 [VerticalControl] Loaded 2025-12-08T02:56:00.573Z,1765162560.573 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2025-12-08T02:56:00.576Z,1765162560.576 [HorizontalControl](DEBUG): Construct HorizontalControl. 2025-12-08T02:56:00.619Z,1765162560.619 [HorizontalControl] Loaded 2025-12-08T02:56:00.620Z,1765162560.620 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2025-12-08T02:56:00.622Z,1765162560.622 [SpeedControl](DEBUG): Construct SpeedControl. 2025-12-08T02:56:00.624Z,1765162560.624 [SpeedControl] Loaded 2025-12-08T02:56:00.624Z,1765162560.624 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2025-12-08T02:56:00.627Z,1765162560.627 [LoopControl](DEBUG): Construct LoopControl. 2025-12-08T02:56:00.627Z,1765162560.627 [LoopControl] Loaded 2025-12-08T02:56:00.628Z,1765162560.628 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2025-12-08T02:56:00.628Z,1765162560.628 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2025-12-08T02:56:00.629Z,1765162560.629 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2025-12-08T02:56:00.642Z,1765162560.642 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2025-12-08T02:56:00.643Z,1765162560.643 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2025-12-08T02:56:01.019Z,1765162561.019 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2025-12-08T02:56:01.025Z,1765162561.025 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2025-12-08T02:56:01.028Z,1765162561.028 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2025-12-08T02:56:01.039Z,1765162561.039 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2025-12-08T02:56:01.040Z,1765162561.040 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C6D4E0 2025-12-08T02:56:01.041Z,1765162561.041 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1445 2025-12-08T02:56:01.045Z,1765162561.045 [Supervisor](INFO): Main Thread ID is 823 2025-12-08T02:56:01.045Z,1765162561.045 [Supervisor](DEBUG): Running supervisor. 2025-12-08T02:56:01.046Z,1765162561.046 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1446 2025-12-08T02:56:01.046Z,1765162561.046 [CommandExec](INFO): Initializing the command executive. 2025-12-08T02:56:01.048Z,1765162561.048 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1447 2025-12-08T02:56:01.050Z,1765162561.050 [controlThread ThreadHandler](INFO): Handler Thread ID is 1448 2025-12-08T02:56:01.051Z,1765162561.051 [controlThread](DEBUG): Initializing ControlThread 2025-12-08T02:56:01.052Z,1765162561.052 [SBIT](INFO): Initialize SBIT Component. 2025-12-08T02:56:01.053Z,1765162561.053 [SBIT](IMPORTANT): git: 2025-12-04 2025-12-08T02:56:01.053Z,1765162561.053 [SBIT](INFO): git hash: 9bae910f87c476713ef0b63f9da99d151f0f5f1b 2025-12-08T02:56:01.053Z,1765162561.053 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2025-12-08T02:56:01.055Z,1765162561.055 [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-12-08T02:56:01.056Z,1765162561.056 [SBIT](INFO): Beginning SBIT in 144.000000 seconds. 2025-12-08T02:56:01.056Z,1765162561.056 [IBIT](INFO): Initialize IBIT Component. 2025-12-08T02:56:01.057Z,1765162561.057 [CBIT](DEBUG): Initialize CBIT Component. 2025-12-08T02:56:01.058Z,1765162561.058 [logger ThreadHandler](INFO): Handler Thread ID is 1449 2025-12-08T02:56:01.068Z,1765162561.068 [CBIT](DEBUG): Initialized mux pins. 2025-12-08T02:56:01.068Z,1765162561.068 [CBIT](DEBUG): Initializing the watchdog timer. 2025-12-08T02:56:01.076Z,1765162561.076 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1450 2025-12-08T02:56:01.077Z,1765162561.077 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2025-12-08T02:56:01.081Z,1765162561.081 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1452 2025-12-08T02:56:01.084Z,1765162561.084 [WetLabsBB2FL](INFO): Powering up 2025-12-08T02:56:01.085Z,1765162561.085 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1454 2025-12-08T02:56:01.092Z,1765162561.092 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2025-12-08T02:56:01.092Z,1765162561.092 [CBIT](DEBUG): Initializing heartbeat. 2025-12-08T02:56:01.093Z,1765162561.093 [DAT ThreadHandler](INFO): Handler Thread ID is 1455 2025-12-08T02:56:01.094Z,1765162561.094 [DAT](INFO): Powering up 2025-12-08T02:56:01.094Z,1765162561.094 [DAT](DEBUG): Initializing DAT. 2025-12-08T02:56:01.097Z,1765162561.097 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1457 2025-12-08T02:56:01.098Z,1765162561.098 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2025-12-08T02:56:01.109Z,1765162561.109 [Onboard ThreadHandler](INFO): Handler Thread ID is 1458 2025-12-08T02:56:01.126Z,1765162561.126 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1459 2025-12-08T02:56:01.144Z,1765162561.144 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1460 2025-12-08T02:56:01.150Z,1765162561.150 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2025-12-08T02:56:01.150Z,1765162561.150 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2025-12-08T02:56:01.150Z,1765162561.150 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2025-12-08T02:56:01.151Z,1765162561.151 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2025-12-08T02:56:01.151Z,1765162561.151 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2025-12-08T02:56:01.151Z,1765162561.151 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2025-12-08T02:56:01.151Z,1765162561.151 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2025-12-08T02:56:01.151Z,1765162561.151 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2025-12-08T02:56:01.164Z,1765162561.164 [CBIT](DEBUG): Deactivating emergency mode. 2025-12-08T02:56:01.204Z,1765162561.204 [CBIT](DEBUG): Backplane powered. 2025-12-08T02:56:01.204Z,1765162561.204 [GFScanner](DEBUG): Initializing GFScanner 2025-12-08T02:56:01.204Z,1765162561.204 [GFScanner](DEBUG): Deactivating GF circuits. 2025-12-08T02:56:01.212Z,1765162561.212 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2025-12-08T02:56:01.213Z,1765162561.213 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2025-12-08T02:56:01.213Z,1765162561.213 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2025-12-08T02:56:01.213Z,1765162561.213 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2025-12-08T02:56:01.214Z,1765162561.214 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2025-12-08T02:56:01.215Z,1765162561.215 [NavChart](DEBUG): Initialize NavChart Navigation. 2025-12-08T02:56:01.215Z,1765162561.215 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2025-12-08T02:56:01.218Z,1765162561.218 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2025-12-08T02:56:01.224Z,1765162561.224 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2025-12-08T02:56:01.225Z,1765162561.225 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2025-12-08T02:56:01.225Z,1765162561.225 [LoopControl](DEBUG): Initialize LoopControlComponent. 2025-12-08T02:56:01.226Z,1765162561.226 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2025-12-08T02:56:01.227Z,1765162561.227 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2025-12-08T02:56:01.227Z,1765162561.227 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2025-12-08T02:56:01.239Z,1765162561.239 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2025-12-08T02:56:01.317Z,1765162561.317 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2025-12-08T02:56:01.335Z,1765162561.335 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2025-12-08T02:56:01.387Z,1765162561.387 [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-08T02:56:01.387Z,1765162561.387 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2025-12-08T02:56:01.396Z,1765162561.396 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2025-12-08T02:56:01.398Z,1765162561.398 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2025-12-08T02:56:01.532Z,1765162561.532 [Radio_Surface](INFO): Powering up 2025-12-08T02:56:01.705Z,1765162561.705 [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-08T02:56:01.711Z,1765162561.711 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2025-12-08T02:56:01.729Z,1765162561.729 [Default:A.Wait](DEBUG): Construct Wait. 2025-12-08T02:56:01.731Z,1765162561.731 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-12-08T02:56:01.780Z,1765162561.780 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2025-12-08T02:56:01.783Z,1765162561.783 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2025-12-08T02:56:01.801Z,1765162561.801 [Default:E.Execute](DEBUG): Construct Execute. 2025-12-08T02:56:01.804Z,1765162561.804 [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-08T02:56:01.816Z,1765162561.816 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,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,LcmPublisher,Reporter,LogSplitter, 2025-12-08T02:56:01.849Z,1765162561.849 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2025-12-08T02:56:01.908Z,1765162561.908 [Depth_Keller](INFO): Initializing. 2025-12-08T02:56:01.910Z,1765162561.910 [Power24vConverter](INFO): Powering up. 2025-12-08T02:56:01.911Z,1765162561.911 [Sonardyne_Nano](INFO): Initializing. 2025-12-08T02:56:01.940Z,1765162561.940 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-12-08T02:56:01.963Z,1765162561.963 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-12-08T02:56:01.968Z,1765162561.968 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-12-08T02:56:01.969Z,1765162561.969 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-12-08T02:56:01.976Z,1765162561.976 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-12-08T02:56:01.977Z,1765162561.977 [MassServo](DEBUG): Initializing EZServoServo. 2025-12-08T02:56:01.984Z,1765162561.984 [MassServo](DEBUG): Initializing MassServo. 2025-12-08T02:56:01.985Z,1765162561.985 [RudderServo](DEBUG): Initializing EZServoServo. 2025-12-08T02:56:01.992Z,1765162561.992 [RudderServo](DEBUG): Initializing RudderServo. 2025-12-08T02:56:01.993Z,1765162561.993 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-12-08T02:56:02.000Z,1765162562.000 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-12-08T02:56:02.276Z,1765162562.276 [PowerOnly](INFO): Powering up loadControl 2025-12-08T02:56:03.696Z,1765162563.696 [WetLabsBB2FL](INFO): Powering down 2025-12-08T02:56:03.856Z,1765162563.856 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2025-12-08T02:56:05.505Z,1765162565.505 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2213 2025-12-08T02:56:13.457Z,1765162573.457 [DAT](INFO): DAT read: 2025-12-08T02:56:13.458Z,1765162573.458 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2025-12-08T02:56:15.222Z,1765162575.222 [DAT](INFO): DAT read: MF Frequency Band 2025-12-08T02:56:15.223Z,1765162575.223 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2025-12-08T02:56:15.224Z,1765162575.224 [DAT](INFO): DAT read: Dec 8 2025 02:56:09 2025-12-08T02:56:16.229Z,1765162576.229 [DAT](INFO): DAT read: Features enabled [Bearing] 2025-12-08T02:56:16.230Z,1765162576.230 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC 2025-12-08T02:56:16.230Z,1765162576.230 [DAT](INFO): commRate: 1200 2025-12-08T02:56:18.296Z,1765162578.296 [DAT](INFO): entering command mode 2025-12-08T02:56:18.497Z,1765162578.497 [DAT](INFO): DAT read: 2025-12-08T02:56:18.497Z,1765162578.497 [DAT](INFO): DAT read: user:1> 2025-12-08T02:56:18.497Z,1765162578.497 [DAT](INFO): setting verbose to 3 2025-12-08T02:56:18.748Z,1765162578.748 [DAT](INFO): DAT read: user:1> 2025-12-08T02:56:18.749Z,1765162578.749 [DAT](INFO): DAT read: Verbose | 3 2025-12-08T02:56:18.750Z,1765162578.750 [DAT](INFO): set verbose to 3 2025-12-08T02:56:18.750Z,1765162578.750 [DAT](INFO): setting DatVerbose to 27440 2025-12-08T02:56:19.001Z,1765162579.001 [DAT](INFO): DAT read: user:2> 2025-12-08T02:56:19.002Z,1765162579.002 [DAT](INFO): DAT read: DatVerbose | 27440 2025-12-08T02:56:19.002Z,1765162579.002 [DAT](INFO): set DatVerbose to 27440 2025-12-08T02:56:19.002Z,1765162579.002 [DAT](INFO): setting transmit power to 8 2025-12-08T02:56:19.253Z,1765162579.253 [DAT](INFO): DAT read: user:3> 2025-12-08T02:56:19.253Z,1765162579.253 [DAT](INFO): DAT read: TxPower | 8 (Max) 2025-12-08T02:56:19.254Z,1765162579.254 [DAT](INFO): set transmit power to 8 2025-12-08T02:56:19.254Z,1765162579.254 [DAT](INFO): setting local address to 5 2025-12-08T02:56:19.505Z,1765162579.505 [DAT](INFO): DAT read: user:4> 2025-12-08T02:56:19.506Z,1765162579.506 [DAT](INFO): DAT read: LocalAddr | 5 2025-12-08T02:56:19.506Z,1765162579.506 [DAT](INFO): set local address to 5 2025-12-08T02:56:19.507Z,1765162579.507 [DAT](INFO): Setting time to: 2:56:19 And date to:12/8/2025 2025-12-08T02:56:19.757Z,1765162579.757 [DAT](INFO): DAT read: user:5> 2025-12-08T02:56:19.757Z,1765162579.757 [DAT](INFO): DAT read: Mon Dec 8, 2025 02:56:19 2025-12-08T02:56:19.758Z,1765162579.758 [DAT](INFO): Local DAT time set to Mon Dec 8, 2025 02:56:19 2025-12-08T02:56:30.545Z,1765162590.545 [NAL9602](INFO): Powering up NAL9602 2025-12-08T02:56:41.455Z,1765162601.455 [NAL9602](INFO): NAL9602 initialized 2025-12-08T02:57:07.717Z,1765162627.717 [NAL9602](INFO): SBD MO Status=2, MOMSN=24386, MT Status=2, MTMSN=0 2025-12-08T02:57:07.717Z,1765162627.717 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-08T02:57:57.004Z,1765162677.004 [NAL9602](INFO): SBD MO Status=2, MOMSN=24386, MT Status=2, MTMSN=0 2025-12-08T02:57:57.004Z,1765162677.004 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-12-08T02:58:13.050Z,1765162693.050 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004456 2025-12-08T02:58:25.698Z,1765162705.698 [SBIT](IMPORTANT): Beginning Startup BIT 2025-12-08T02:58:25.702Z,1765162705.702 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-12-08T02:58:30.248Z,1765162710.248 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.002530 CHAN A1 (24V): 0.005682 CHAN A2 (12V): -0.004831 CHAN A3 (5V): -0.016302 CHAN B0 (3.3V): -0.003285 CHAN B1 (3.15aV): -0.001774 CHAN B2 (3.15bV): -0.001111 CHAN B3 (GND): -0.005316 OPEN: 0.012236 Full Scale: +/- 1 mA 2025-12-08T02:59:01.349Z,1765162741.349 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2025-12-08T02:59:01.349Z,1765162741.349 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-12-08T02:59:01.383Z,1765162741.383 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-12-08T02:59:01.783Z,1765162741.783 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-12-08T02:59:01.783Z,1765162741.783 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2025-12-08T02:59:19.965Z,1765162759.965 [SBIT](IMPORTANT): SBIT PASSED 2025-12-08T02:59:19.965Z,1765162759.965 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2025-12-08T02:59:19.966Z,1765162759.966 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=5 count; 2025-12-08T02:59:19.966Z,1765162759.966 [SBIT](IMPORTANT): CBIT.navErrorThreshold=200 percent; 2025-12-08T02:59:19.966Z,1765162759.966 [SBIT](IMPORTANT): CBIT.runNavErrorCritical=1 bool; 2025-12-08T02:59:19.966Z,1765162759.966 [SBIT](IMPORTANT): DVL_micro.loadAtStartup=0 bool; 2025-12-08T02:59:19.966Z,1765162759.966 [SBIT](IMPORTANT): Express linearApproximation PowerOnly.component_avgCurrent_loadControl 50.000000 milliampere; 2025-12-08T02:59:19.966Z,1765162759.966 [SBIT](IMPORTANT): Express linearApproximation PowerOnly.samplePowerOnly 1.000000 bool; 2025-12-08T02:59:19.967Z,1765162759.967 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=384.695336 cubic_centimeter; 2025-12-08T02:59:19.967Z,1765162759.967 [SBIT](IMPORTANT): VerticalControl.massDefault=31.165311 millimeter; 2025-12-08T02:59:20.375Z,1765162760.375 [MissionManager](IMPORTANT): Started mission Startup 2025-12-08T02:59:20.375Z,1765162760.375 [Startup] Running Loop=1 2025-12-08T02:59:20.376Z,1765162760.376 [Startup](DEBUG): Aggregate::initialize Startup 2025-12-08T02:59:20.376Z,1765162760.376 [Startup:A.GoToSurface] Running Loop=1 2025-12-08T02:59:20.376Z,1765162760.376 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-12-08T02:59:20.377Z,1765162760.377 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-12-08T02:59:20.377Z,1765162760.377 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-12-08T02:59:20.377Z,1765162760.377 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-12-08T02:59:20.378Z,1765162760.378 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-12-08T02:59:20.378Z,1765162760.378 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-12-08T02:59:20.378Z,1765162760.378 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-12-08T02:59:20.380Z,1765162760.380 [Startup:StartupSatComms] Running Loop=1 2025-12-08T02:59:20.380Z,1765162760.380 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2025-12-08T02:59:20.380Z,1765162760.380 [Startup:StartupSatComms:A] Running Loop=1 2025-12-08T02:59:20.773Z,1765162760.773 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2025-12-08T03:00:20.560Z,1765162820.560 [Startup:StartupSatComms:A](INFO): Timed out from 2025-12-08T02:59:20.4Z 2025-12-08T03:00:20.560Z,1765162820.560 [Startup:StartupSatComms:A] Stopped 2025-12-08T03:00:20.560Z,1765162820.560 [Startup:StartupSatComms:B] Running Loop=1 2025-12-08T03:00:20.972Z,1765162820.972 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-12-08T03:00:27.481Z,1765162827.481 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20251208T011500/Courier0003.lzma 2025-12-08T03:00:28.474Z,1765162828.474 [DataOverHttps](INFO): Moved sent file to Logs/20251208T011500/Courier0003.lzma.bak 2025-12-08T03:00:28.474Z,1765162828.474 [DataOverHttps](INFO): SBD MOMSN=26457250 2025-12-08T03:00:46.935Z,1765162846.935 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20251208T025549/Courier0000.lzma 2025-12-08T03:00:47.938Z,1765162847.938 [DataOverHttps](INFO): Moved sent file to Logs/20251208T025549/Courier0000.lzma.bak 2025-12-08T03:00:47.938Z,1765162847.938 [DataOverHttps](INFO): SBD MOMSN=26457253 2025-12-08T03:01:04.597Z,1765162864.597 [DataOverHttps](INFO): Sending 978 bytes from file Logs/20251208T011500/Express0004.lzma 2025-12-08T03:01:05.598Z,1765162865.598 [DataOverHttps](INFO): Moved sent file to Logs/20251208T011500/Express0004.lzma.bak 2025-12-08T03:01:05.598Z,1765162865.598 [DataOverHttps](INFO): SBD MOMSN=26457256 2025-12-08T03:01:20.769Z,1765162880.769 [Startup:StartupSatComms:B](INFO): Timed out from 2025-12-08T03:00:20.6Z 2025-12-08T03:01:20.769Z,1765162880.769 [Startup:StartupSatComms:B] Stopped 2025-12-08T03:01:20.770Z,1765162880.770 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2025-12-08T03:01:20.770Z,1765162880.770 [Startup:StartupSatComms] Stopped 2025-12-08T03:01:20.770Z,1765162880.770 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2025-12-08T03:01:20.770Z,1765162880.770 [Startup](INFO): Completed Startup 2025-12-08T03:01:20.771Z,1765162880.771 [MissionManager](INFO): Startup is completed. 2025-12-08T03:01:20.771Z,1765162880.771 [MissionManager](INFO): Uninitializing Mission Startup 2025-12-08T03:01:20.771Z,1765162880.771 [Startup] Stopped 2025-12-08T03:01:20.771Z,1765162880.771 [Startup](DEBUG): Aggregate::uninitialize Startup 2025-12-08T03:01:20.771Z,1765162880.771 [Startup:A.GoToSurface] Stopped 2025-12-08T03:01:20.771Z,1765162880.771 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-12-08T03:01:21.166Z,1765162881.166 [MissionManager](IMPORTANT): Started mission Default 2025-12-08T03:01:21.166Z,1765162881.166 [Default] Running Loop=1 2025-12-08T03:01:21.166Z,1765162881.166 [Default](DEBUG): Aggregate::initialize Default 2025-12-08T03:01:21.166Z,1765162881.166 [Default:B.GoToSurface] Running Loop=1 2025-12-08T03:01:21.167Z,1765162881.167 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-12-08T03:01:21.167Z,1765162881.167 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-12-08T03:01:21.167Z,1765162881.167 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-12-08T03:01:21.167Z,1765162881.167 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-12-08T03:01:21.168Z,1765162881.168 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-12-08T03:01:21.168Z,1765162881.168 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-12-08T03:01:21.169Z,1765162881.169 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-12-08T03:01:21.169Z,1765162881.169 [Default:A.Wait] Running Loop=1 2025-12-08T03:01:21.169Z,1765162881.169 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-12-08T03:01:21.533Z,1765162881.533 [DataOverHttps](INFO): Sending 993 bytes from file Logs/20251208T025549/Express0001.lzma 2025-12-08T03:01:22.534Z,1765162882.534 [DataOverHttps](INFO): Moved sent file to Logs/20251208T025549/Express0001.lzma.bak 2025-12-08T03:01:22.534Z,1765162882.534 [DataOverHttps](INFO): SBD MOMSN=26457270 2025-12-08T03:01:34.502Z,1765162894.502 [Default:A.Wait](INFO): Done Waiting. 2025-12-08T03:01:34.502Z,1765162894.502 [Default:A.Wait] Stopped 2025-12-08T03:01:34.503Z,1765162894.503 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-12-08T03:01:34.901Z,1765162894.901 [Default:CheckIn] Running Loop=1 2025-12-08T03:01:34.901Z,1765162894.901 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-12-08T03:01:34.901Z,1765162894.901 [Default:CheckIn:Read_GPS] Running Loop=1 2025-12-08T03:01:35.333Z,1765162895.333 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-12-08T03:01:45.394Z,1765162905.394 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-12-08T03:01:51.863Z,1765162911.863 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,030150.00,A,3631.36121,N,12200.90340,W,1.127,235.38,081225,,,A*72 2025-12-08T03:01:51.867Z,1765162911.867 [NAL9602](INFO): GPS fix at 20251208T030150: (36.522687, -122.015057) 2025-12-08T03:01:51.897Z,1765162911.897 [Default:CheckIn:Read_GPS] Stopped 2025-12-08T03:01:51.897Z,1765162911.897 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-12-08T03:01:52.307Z,1765162912.307 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-12-08T03:01:59.824Z,1765162919.824 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20251208T025549/Courier0004.lzma 2025-12-08T03:02:00.826Z,1765162920.826 [DataOverHttps](INFO): Moved sent file to Logs/20251208T025549/Courier0004.lzma.bak 2025-12-08T03:02:00.826Z,1765162920.826 [DataOverHttps](INFO): SBD MOMSN=26457291 2025-12-08T03:02:17.966Z,1765162937.966 [DataOverHttps](INFO): Sending 419 bytes from file Logs/20251208T025549/Express0005.lzma 2025-12-08T03:02:18.966Z,1765162938.966 [DataOverHttps](INFO): Moved sent file to Logs/20251208T025549/Express0005.lzma.bak 2025-12-08T03:02:18.966Z,1765162938.966 [DataOverHttps](INFO): SBD MOMSN=26457294 2025-12-08T03:02:20.197Z,1765162940.197 [Default:CheckIn:Read_Iridium] Stopped 2025-12-08T03:02:20.197Z,1765162940.197 [Default:CheckIn:C.Wait] Running Loop=1 2025-12-08T03:02:20.197Z,1765162940.197 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-12-08T03:02:24.222Z,1765162944.222 [NAL9602](INFO): Not Powering down - fast GPS 2025-12-08T03:03:28.761Z,1765163008.761 [DataOverHttps](IMPORTANT): SBD MTMSN=20251208T030326 2025-12-08T03:03:36.161Z,1765163016.161 [DataOverHttps](INFO): Received command: configSet CBIT.navErrorThreshold 200 percent persist;restart logs;schedule resume 2025-12-08T03:03:36.469Z,1765163016.469 [CommandExec](IMPORTANT): got command configSet CBIT.navErrorThreshold 200 percent persist