2026-01-12T20:56:42.874Z,1768251402.874 [Supervisor](DEBUG): Initializing supervisor. 2026-01-12T20:56:42.879Z,1768251402.879 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2026-01-12T20:56:42.879Z,1768251402.879 [SyncHandler](INFO): Protected caller Thread ID is 1103 2026-01-12T20:56:42.880Z,1768251402.880 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2026-01-12T20:56:42.881Z,1768251402.881 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2026-01-12T20:56:42.881Z,1768251402.881 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1104 2026-01-12T20:56:42.885Z,1768251402.885 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2026-01-12T20:56:42.904Z,1768251402.904 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2026-01-12T20:56:42.905Z,1768251402.905 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2026-01-12T20:56:42.905Z,1768251402.905 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1105 2026-01-12T20:56:42.909Z,1768251402.909 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2026-01-12T20:56:42.910Z,1768251402.910 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2026-01-12T20:56:42.911Z,1768251402.911 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1106 2026-01-12T20:56:42.913Z,1768251402.913 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2026-01-12T20:56:42.914Z,1768251402.914 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2026-01-12T20:56:42.914Z,1768251402.914 [logger ThreadHandler](INFO): Protected caller Thread ID is 1107 2026-01-12T20:56:42.918Z,1768251402.918 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2026-01-12T20:56:42.918Z,1768251402.918 [Supervisor](INFO): Looking for Config files in directory: Config/ 2026-01-12T20:56:42.922Z,1768251402.922 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2026-01-12T20:56:43.175Z,1768251403.175 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2026-01-12T20:56:43.176Z,1768251403.176 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2026-01-12T20:56:43.404Z,1768251403.404 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2026-01-12T20:56:43.405Z,1768251403.405 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2026-01-12T20:56:43.664Z,1768251403.664 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2026-01-12T20:56:43.665Z,1768251403.665 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2026-01-12T20:56:43.778Z,1768251403.778 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2026-01-12T20:56:43.778Z,1768251403.778 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2026-01-12T20:56:44.508Z,1768251404.508 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2026-01-12T20:56:44.509Z,1768251404.509 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2026-01-12T20:56:45.013Z,1768251405.013 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2026-01-12T20:56:45.014Z,1768251405.014 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2026-01-12T20:56:45.535Z,1768251405.535 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2026-01-12T20:56:45.535Z,1768251405.535 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2026-01-12T20:56:45.944Z,1768251405.944 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2026-01-12T20:56:45.945Z,1768251405.945 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2026-01-12T20:56:46.282Z,1768251406.282 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2026-01-12T20:56:46.283Z,1768251406.283 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2026-01-12T20:56:46.663Z,1768251406.663 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2026-01-12T20:56:46.664Z,1768251406.664 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2026-01-12T20:56:46.891Z,1768251406.891 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2026-01-12T20:56:46.892Z,1768251406.892 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2026-01-12T20:56:47.043Z,1768251407.043 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2026-01-12T20:56:47.043Z,1768251407.043 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2026-01-12T20:56:47.142Z,1768251407.142 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance 2026-01-12T20:56:47.143Z,1768251407.143 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2026-01-12T20:56:47.231Z,1768251407.231 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2026-01-12T20:56:47.414Z,1768251407.414 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2026-01-12T20:56:47.415Z,1768251407.415 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2026-01-12T20:56:47.660Z,1768251407.660 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2026-01-12T20:56:47.662Z,1768251407.662 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2026-01-12T20:56:47.663Z,1768251407.663 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2026-01-12T20:56:47.756Z,1768251407.756 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2026-01-12T20:56:48.042Z,1768251408.042 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2026-01-12T20:56:48.042Z,1768251408.042 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2026-01-12T20:56:48.139Z,1768251408.139 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2026-01-12T20:56:48.479Z,1768251408.479 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2026-01-12T20:56:48.762Z,1768251408.762 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2026-01-12T20:56:49.084Z,1768251409.084 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2026-01-12T20:56:49.200Z,1768251409.200 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2026-01-12T20:56:49.301Z,1768251409.301 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2026-01-12T20:56:49.462Z,1768251409.462 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Derivation.cfg 2026-01-12T20:56:49.566Z,1768251409.566 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/workSite.cfg 2026-01-12T20:56:49.658Z,1768251409.658 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2026-01-12T20:56:49.762Z,1768251409.762 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2026-01-12T20:56:49.856Z,1768251409.856 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2026-01-12T20:56:49.864Z,1768251409.864 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2026-01-12T20:56:50.041Z,1768251410.041 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2026-01-12T20:56:50.041Z,1768251410.041 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2026-01-12T20:56:50.205Z,1768251410.205 [BuoyancyServo] Loaded 2026-01-12T20:56:50.205Z,1768251410.205 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2026-01-12T20:56:50.228Z,1768251410.228 [ElevatorServo] Loaded 2026-01-12T20:56:50.229Z,1768251410.229 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2026-01-12T20:56:50.250Z,1768251410.250 [MassServo] Loaded 2026-01-12T20:56:50.250Z,1768251410.250 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2026-01-12T20:56:50.273Z,1768251410.273 [RudderServo] Loaded 2026-01-12T20:56:50.273Z,1768251410.273 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2026-01-12T20:56:50.290Z,1768251410.290 [ThrusterHE] Loaded 2026-01-12T20:56:50.290Z,1768251410.290 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2026-01-12T20:56:50.291Z,1768251410.291 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2026-01-12T20:56:50.291Z,1768251410.291 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2026-01-12T20:56:50.452Z,1768251410.452 [SBIT](DEBUG): Construct Startup Built In Test. 2026-01-12T20:56:50.467Z,1768251410.467 [SBIT] Loaded 2026-01-12T20:56:50.467Z,1768251410.467 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2026-01-12T20:56:50.470Z,1768251410.470 [IBIT](DEBUG): Construct Initiated Built In Test. 2026-01-12T20:56:50.490Z,1768251410.490 [IBIT] Loaded 2026-01-12T20:56:50.490Z,1768251410.490 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2026-01-12T20:56:50.495Z,1768251410.495 [CBIT](DEBUG): Construct Continuous Built In Test. 2026-01-12T20:56:50.688Z,1768251410.688 [CBIT] Loaded 2026-01-12T20:56:50.688Z,1768251410.688 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2026-01-12T20:56:50.719Z,1768251410.719 [GFScanner] Loaded 2026-01-12T20:56:50.719Z,1768251410.719 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread. 2026-01-12T20:56:50.720Z,1768251410.720 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2026-01-12T20:56:50.720Z,1768251410.720 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2026-01-12T20:56:50.887Z,1768251410.887 [CTD_Seabird] Loaded 2026-01-12T20:56:50.888Z,1768251410.888 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2026-01-12T20:56:50.889Z,1768251410.889 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406874E0 2026-01-12T20:56:50.889Z,1768251410.889 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1195 2026-01-12T20:56:50.924Z,1768251410.924 [ESPComponent] Loaded 2026-01-12T20:56:50.924Z,1768251410.924 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2026-01-12T20:56:50.946Z,1768251410.945 [PAR_Licor] Loaded 2026-01-12T20:56:50.946Z,1768251410.946 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2026-01-12T20:56:50.989Z,1768251410.989 [WetLabsBB2FL] Loaded 2026-01-12T20:56:50.989Z,1768251410.989 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2026-01-12T20:56:50.990Z,1768251410.990 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406B74E0 2026-01-12T20:56:50.990Z,1768251410.990 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1196 2026-01-12T20:56:50.991Z,1768251410.991 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2026-01-12T20:56:50.992Z,1768251410.992 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2026-01-12T20:56:51.043Z,1768251411.043 [DepthRateCalculator] Loaded 2026-01-12T20:56:51.043Z,1768251411.043 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2026-01-12T20:56:51.048Z,1768251411.048 [PitchRateCalculator] Loaded 2026-01-12T20:56:51.049Z,1768251411.049 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2026-01-12T20:56:51.058Z,1768251411.058 [SpeedCalculator] Loaded 2026-01-12T20:56:51.059Z,1768251411.059 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2026-01-12T20:56:51.063Z,1768251411.063 [YawRateCalculator] Loaded 2026-01-12T20:56:51.064Z,1768251411.064 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2026-01-12T20:56:51.083Z,1768251411.083 [ElevatorOffsetCalculator] Loaded 2026-01-12T20:56:51.083Z,1768251411.083 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2026-01-12T20:56:51.084Z,1768251411.084 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2026-01-12T20:56:51.084Z,1768251411.084 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2026-01-12T20:56:51.220Z,1768251411.220 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2026-01-12T20:56:51.220Z,1768251411.220 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2026-01-12T20:56:51.250Z,1768251411.250 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2026-01-12T20:56:51.250Z,1768251411.250 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2026-01-12T20:56:51.362Z,1768251411.362 [DeadReckonUsingMultipleVelocitySources] Loaded 2026-01-12T20:56:51.363Z,1768251411.363 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2026-01-12T20:56:51.376Z,1768251411.376 [NavChart] Loaded 2026-01-12T20:56:51.377Z,1768251411.377 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2026-01-12T20:56:51.382Z,1768251411.382 [UniversalFixResidualReporter] Loaded 2026-01-12T20:56:51.382Z,1768251411.382 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2026-01-12T20:56:51.383Z,1768251411.383 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2026-01-12T20:56:51.383Z,1768251411.383 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2026-01-12T20:56:51.861Z,1768251411.861 [AHRS_M2] Loaded 2026-01-12T20:56:51.862Z,1768251411.862 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2026-01-12T20:56:51.900Z,1768251411.900 [BackseatComponent] Loaded 2026-01-12T20:56:51.900Z,1768251411.900 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2026-01-12T20:56:51.901Z,1768251411.901 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A854E0 2026-01-12T20:56:51.902Z,1768251411.902 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1197 2026-01-12T20:56:51.905Z,1768251411.905 [LcmPublisher] Loaded 2026-01-12T20:56:51.905Z,1768251411.905 [ComponentRegistry](DEBUG): SyncComponent "LcmPublisher" handled in the control thread. 2026-01-12T20:56:52.646Z,1768251412.646 [BPC1] Loaded 2026-01-12T20:56:52.646Z,1768251412.646 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2026-01-12T20:56:52.746Z,1768251412.746 [DAT] Loaded 2026-01-12T20:56:52.747Z,1768251412.747 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2026-01-12T20:56:52.748Z,1768251412.748 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40AB54E0 2026-01-12T20:56:52.748Z,1768251412.748 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1198 2026-01-12T20:56:52.821Z,1768251412.821 [DataOverHttps] Loaded 2026-01-12T20:56:52.821Z,1768251412.821 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2026-01-12T20:56:52.822Z,1768251412.822 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40AE54E0 2026-01-12T20:56:52.822Z,1768251412.822 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1199 2026-01-12T20:56:52.846Z,1768251412.846 [Depth_Keller] Loaded 2026-01-12T20:56:52.846Z,1768251412.846 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2026-01-12T20:56:52.851Z,1768251412.851 [DropWeight] Loaded 2026-01-12T20:56:52.851Z,1768251412.851 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2026-01-12T20:56:52.892Z,1768251412.892 [DVL_micro] Loaded 2026-01-12T20:56:52.893Z,1768251412.893 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2026-01-12T20:56:52.954Z,1768251412.954 [NAL9602] Loaded 2026-01-12T20:56:52.954Z,1768251412.954 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2026-01-12T20:56:52.980Z,1768251412.980 [Onboard] Loaded 2026-01-12T20:56:52.980Z,1768251412.980 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2026-01-12T20:56:52.981Z,1768251412.981 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40B154E0 2026-01-12T20:56:52.982Z,1768251412.982 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1200 2026-01-12T20:56:52.987Z,1768251412.987 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3 2026-01-12T20:56:53.001Z,1768251413.001 [PowerOnly] Loaded 2026-01-12T20:56:53.001Z,1768251413.001 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread. 2026-01-12T20:56:53.015Z,1768251413.015 [Power24vConverter] Loaded 2026-01-12T20:56:53.016Z,1768251413.016 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2026-01-12T20:56:53.031Z,1768251413.031 [Radio_Surface] Loaded 2026-01-12T20:56:53.032Z,1768251413.032 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2026-01-12T20:56:53.033Z,1768251413.033 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40B454E0 2026-01-12T20:56:53.033Z,1768251413.033 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1201 2026-01-12T20:56:53.048Z,1768251413.048 [Sonardyne_Nano] Loaded 2026-01-12T20:56:53.049Z,1768251413.049 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2026-01-12T20:56:53.049Z,1768251413.049 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2026-01-12T20:56:53.050Z,1768251413.050 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2026-01-12T20:56:53.211Z,1768251413.211 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2026-01-12T20:56:53.212Z,1768251413.212 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2026-01-12T20:56:53.395Z,1768251413.395 [VerticalControl](DEBUG): Construct VerticalControl. 2026-01-12T20:56:53.447Z,1768251413.447 [VerticalControl] Loaded 2026-01-12T20:56:53.447Z,1768251413.447 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2026-01-12T20:56:53.450Z,1768251413.450 [HorizontalControl](DEBUG): Construct HorizontalControl. 2026-01-12T20:56:53.498Z,1768251413.498 [HorizontalControl] Loaded 2026-01-12T20:56:53.498Z,1768251413.498 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2026-01-12T20:56:53.501Z,1768251413.501 [SpeedControl](DEBUG): Construct SpeedControl. 2026-01-12T20:56:53.502Z,1768251413.502 [SpeedControl] Loaded 2026-01-12T20:56:53.503Z,1768251413.503 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2026-01-12T20:56:53.506Z,1768251413.506 [LoopControl](DEBUG): Construct LoopControl. 2026-01-12T20:56:53.506Z,1768251413.506 [LoopControl] Loaded 2026-01-12T20:56:53.506Z,1768251413.506 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2026-01-12T20:56:53.507Z,1768251413.507 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2026-01-12T20:56:53.507Z,1768251413.507 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2026-01-12T20:56:53.517Z,1768251413.517 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2026-01-12T20:56:53.518Z,1768251413.518 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2026-01-12T20:56:53.858Z,1768251413.858 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2026-01-12T20:56:53.864Z,1768251413.864 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2026-01-12T20:56:53.867Z,1768251413.867 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2026-01-12T20:56:53.878Z,1768251413.878 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2026-01-12T20:56:53.879Z,1768251413.879 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C6D4E0 2026-01-12T20:56:53.880Z,1768251413.880 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1202 2026-01-12T20:56:53.884Z,1768251413.884 [Supervisor](INFO): Main Thread ID is 824 2026-01-12T20:56:53.884Z,1768251413.884 [Supervisor](DEBUG): Running supervisor. 2026-01-12T20:56:53.885Z,1768251413.885 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1203 2026-01-12T20:56:53.885Z,1768251413.885 [CommandExec](INFO): Initializing the command executive. 2026-01-12T20:56:53.887Z,1768251413.887 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1204 2026-01-12T20:56:53.889Z,1768251413.889 [controlThread ThreadHandler](INFO): Handler Thread ID is 1205 2026-01-12T20:56:53.889Z,1768251413.889 [controlThread](DEBUG): Initializing ControlThread 2026-01-12T20:56:53.891Z,1768251413.891 [SBIT](INFO): Initialize SBIT Component. 2026-01-12T20:56:53.892Z,1768251413.892 [SBIT](IMPORTANT): git: 2025-12-04 2026-01-12T20:56:53.892Z,1768251413.892 [SBIT](INFO): git hash: 9bae910f87c476713ef0b63f9da99d151f0f5f1b 2026-01-12T20:56:53.892Z,1768251413.892 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2026-01-12T20:56:53.894Z,1768251413.894 [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 2026-01-12T20:56:53.895Z,1768251413.895 [SBIT](INFO): Beginning SBIT in 144.000000 seconds. 2026-01-12T20:56:53.895Z,1768251413.895 [IBIT](INFO): Initialize IBIT Component. 2026-01-12T20:56:53.896Z,1768251413.896 [CBIT](DEBUG): Initialize CBIT Component. 2026-01-12T20:56:53.897Z,1768251413.897 [logger ThreadHandler](INFO): Handler Thread ID is 1206 2026-01-12T20:56:53.908Z,1768251413.908 [CBIT](DEBUG): Initialized mux pins. 2026-01-12T20:56:53.908Z,1768251413.908 [CBIT](DEBUG): Initializing the watchdog timer. 2026-01-12T20:56:53.916Z,1768251413.916 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1207 2026-01-12T20:56:53.917Z,1768251413.917 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2026-01-12T20:56:53.921Z,1768251413.921 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1209 2026-01-12T20:56:53.924Z,1768251413.924 [WetLabsBB2FL](INFO): Powering up 2026-01-12T20:56:53.925Z,1768251413.925 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1211 2026-01-12T20:56:53.932Z,1768251413.932 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2026-01-12T20:56:53.932Z,1768251413.932 [CBIT](DEBUG): Initializing heartbeat. 2026-01-12T20:56:53.933Z,1768251413.933 [DAT ThreadHandler](INFO): Handler Thread ID is 1212 2026-01-12T20:56:53.934Z,1768251413.934 [DAT](INFO): Powering up 2026-01-12T20:56:53.934Z,1768251413.934 [DAT](DEBUG): Initializing DAT. 2026-01-12T20:56:53.937Z,1768251413.937 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1214 2026-01-12T20:56:53.938Z,1768251413.938 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2026-01-12T20:56:53.949Z,1768251413.949 [Onboard ThreadHandler](INFO): Handler Thread ID is 1215 2026-01-12T20:56:53.966Z,1768251413.966 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1216 2026-01-12T20:56:53.984Z,1768251413.984 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1217 2026-01-12T20:56:53.986Z,1768251413.986 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2026-01-12T20:56:53.987Z,1768251413.987 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2026-01-12T20:56:53.987Z,1768251413.987 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2026-01-12T20:56:53.987Z,1768251413.987 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2026-01-12T20:56:53.987Z,1768251413.987 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2026-01-12T20:56:53.987Z,1768251413.987 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2026-01-12T20:56:53.987Z,1768251413.987 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2026-01-12T20:56:53.989Z,1768251413.989 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2026-01-12T20:56:54.004Z,1768251414.004 [CBIT](DEBUG): Deactivating emergency mode. 2026-01-12T20:56:54.044Z,1768251414.044 [CBIT](DEBUG): Backplane powered. 2026-01-12T20:56:54.044Z,1768251414.044 [GFScanner](DEBUG): Initializing GFScanner 2026-01-12T20:56:54.044Z,1768251414.044 [GFScanner](DEBUG): Deactivating GF circuits. 2026-01-12T20:56:54.052Z,1768251414.052 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2026-01-12T20:56:54.053Z,1768251414.053 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2026-01-12T20:56:54.053Z,1768251414.053 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2026-01-12T20:56:54.053Z,1768251414.053 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2026-01-12T20:56:54.054Z,1768251414.054 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2026-01-12T20:56:54.055Z,1768251414.055 [NavChart](DEBUG): Initialize NavChart Navigation. 2026-01-12T20:56:54.055Z,1768251414.055 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2026-01-12T20:56:54.066Z,1768251414.066 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2026-01-12T20:56:54.068Z,1768251414.068 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2026-01-12T20:56:54.069Z,1768251414.069 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2026-01-12T20:56:54.069Z,1768251414.069 [LoopControl](DEBUG): Initialize LoopControlComponent. 2026-01-12T20:56:54.070Z,1768251414.070 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2026-01-12T20:56:54.071Z,1768251414.071 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2026-01-12T20:56:54.071Z,1768251414.071 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2026-01-12T20:56:54.076Z,1768251414.076 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2026-01-12T20:56:54.163Z,1768251414.163 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2026-01-12T20:56:54.184Z,1768251414.184 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2026-01-12T20:56:54.223Z,1768251414.223 [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 } } } 2026-01-12T20:56:54.228Z,1768251414.228 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2026-01-12T20:56:54.228Z,1768251414.228 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2026-01-12T20:56:54.229Z,1768251414.229 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2026-01-12T20:56:54.372Z,1768251414.372 [Radio_Surface](INFO): Powering up 2026-01-12T20:56:54.526Z,1768251414.526 [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 2026-01-12T20:56:54.542Z,1768251414.542 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2026-01-12T20:56:54.543Z,1768251414.543 [Default:A.Wait](DEBUG): Construct Wait. 2026-01-12T20:56:54.545Z,1768251414.545 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2026-01-12T20:56:54.591Z,1768251414.591 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2026-01-12T20:56:54.594Z,1768251414.594 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2026-01-12T20:56:54.608Z,1768251414.608 [Default:E.Execute](DEBUG): Construct Execute. 2026-01-12T20:56:54.611Z,1768251414.611 [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" } } 2026-01-12T20:56:54.640Z,1768251414.640 [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,LcmPublisher,Reporter,LogSplitter, 2026-01-12T20:56:54.673Z,1768251414.673 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2026-01-12T20:56:54.736Z,1768251414.736 [Depth_Keller](INFO): Initializing. 2026-01-12T20:56:54.742Z,1768251414.742 [Power24vConverter](INFO): Powering up. 2026-01-12T20:56:54.743Z,1768251414.743 [Sonardyne_Nano](INFO): Initializing. 2026-01-12T20:56:54.768Z,1768251414.768 [DepthRateCalculator](ERROR): Depth measurement is not active 2026-01-12T20:56:54.790Z,1768251414.790 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2026-01-12T20:56:54.796Z,1768251414.796 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2026-01-12T20:56:54.797Z,1768251414.797 [ElevatorServo](DEBUG): Initializing EZServoServo. 2026-01-12T20:56:54.804Z,1768251414.804 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2026-01-12T20:56:54.805Z,1768251414.805 [MassServo](DEBUG): Initializing EZServoServo. 2026-01-12T20:56:54.812Z,1768251414.812 [MassServo](DEBUG): Initializing MassServo. 2026-01-12T20:56:54.813Z,1768251414.813 [RudderServo](DEBUG): Initializing EZServoServo. 2026-01-12T20:56:54.821Z,1768251414.821 [RudderServo](DEBUG): Initializing RudderServo. 2026-01-12T20:56:54.821Z,1768251414.821 [ThrusterHE](DEBUG): Initializing EZServoServo. 2026-01-12T20:56:54.828Z,1768251414.828 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2026-01-12T20:56:55.110Z,1768251415.110 [PowerOnly](INFO): Powering up loadControl 2026-01-12T20:56:56.536Z,1768251416.536 [WetLabsBB2FL](INFO): Powering down 2026-01-12T20:56:56.675Z,1768251416.675 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2026-01-12T20:56:57.962Z,1768251417.962 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2213 2026-01-12T20:57:07.053Z,1768251427.053 [DAT](INFO): DAT read: 2026-01-12T20:57:07.054Z,1768251427.054 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2026-01-12T20:57:08.817Z,1768251428.817 [DAT](INFO): DAT read: MF Frequency Band 2026-01-12T20:57:08.818Z,1768251428.818 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2026-01-12T20:57:08.819Z,1768251428.819 [DAT](INFO): DAT read: Jan 12 2026 20:57:02 2026-01-12T20:57:10.077Z,1768251430.077 [DAT](INFO): DAT read: Features enabled [Bearing] 2026-01-12T20:57:10.078Z,1768251430.078 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC 2026-01-12T20:57:10.078Z,1768251430.078 [DAT](INFO): commRate: 1200 2026-01-12T20:57:12.144Z,1768251432.144 [DAT](INFO): entering command mode 2026-01-12T20:57:12.345Z,1768251432.345 [DAT](INFO): DAT read: 2026-01-12T20:57:12.345Z,1768251432.345 [DAT](INFO): DAT read: user:1> 2026-01-12T20:57:12.346Z,1768251432.346 [DAT](INFO): setting verbose to 3 2026-01-12T20:57:12.597Z,1768251432.597 [DAT](INFO): DAT read: user:1> 2026-01-12T20:57:12.599Z,1768251432.599 [DAT](INFO): DAT read: Verbose | 3 2026-01-12T20:57:12.600Z,1768251432.600 [DAT](INFO): set verbose to 3 2026-01-12T20:57:12.600Z,1768251432.600 [DAT](INFO): setting DatVerbose to 27440 2026-01-12T20:57:12.849Z,1768251432.849 [DAT](INFO): DAT read: user:2> 2026-01-12T20:57:12.850Z,1768251432.850 [DAT](INFO): DAT read: DatVerbose | 27440 2026-01-12T20:57:12.850Z,1768251432.850 [DAT](INFO): set DatVerbose to 27440 2026-01-12T20:57:12.850Z,1768251432.850 [DAT](INFO): setting transmit power to 8 2026-01-12T20:57:13.101Z,1768251433.101 [DAT](INFO): DAT read: user:3> 2026-01-12T20:57:13.102Z,1768251433.102 [DAT](INFO): DAT read: TxPower | 8 (Max) 2026-01-12T20:57:13.102Z,1768251433.102 [DAT](INFO): set transmit power to 8 2026-01-12T20:57:13.102Z,1768251433.102 [DAT](INFO): setting local address to 5 2026-01-12T20:57:13.353Z,1768251433.353 [DAT](INFO): DAT read: user:4> 2026-01-12T20:57:13.354Z,1768251433.354 [DAT](INFO): DAT read: LocalAddr | 5 2026-01-12T20:57:13.354Z,1768251433.354 [DAT](INFO): set local address to 5 2026-01-12T20:57:13.355Z,1768251433.355 [DAT](INFO): Setting time to: 20:57:13 And date to:1/12/2026 2026-01-12T20:57:13.605Z,1768251433.605 [DAT](INFO): DAT read: user:5> 2026-01-12T20:57:13.606Z,1768251433.606 [DAT](INFO): DAT read: Mon Jan 12, 2026 20:57:13 2026-01-12T20:57:13.606Z,1768251433.606 [DAT](INFO): Local DAT time set to Mon Jan 12, 2026 20:57:13 2026-01-12T20:57:22.998Z,1768251442.998 [NAL9602](INFO): Powering up NAL9602 2026-01-12T20:57:33.915Z,1768251453.915 [NAL9602](INFO): NAL9602 initialized 2026-01-12T20:57:49.266Z,1768251469.266 [NAL9602](INFO): SBD MO Status=0, MOMSN=25088, MT Status=0, MTMSN=0 2026-01-12T20:57:49.266Z,1768251469.266 [NAL9602](INFO): No messages in MT queue 2026-01-12T20:58:26.049Z,1768251506.049 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205823.00,A,3648.15071,N,12147.21501,W,0.311,156.16,120126,,,A*7F 2026-01-12T20:58:26.053Z,1768251506.053 [NAL9602](INFO): GPS fix at 20260112T205823: (36.802512, -121.786917) 2026-01-12T20:58:58.352Z,1768251538.352 [NAL9602](INFO): Not Powering down - fast GPS 2026-01-12T20:59:05.938Z,1768251545.938 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004243 2026-01-12T20:59:18.578Z,1768251558.578 [SBIT](IMPORTANT): Beginning Startup BIT 2026-01-12T20:59:18.582Z,1768251558.582 [GFScanner](IMPORTANT): Beginning ground fault scan 2026-01-12T20:59:23.133Z,1768251563.133 [GFScanner](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.123025 CHAN A1 (24V): 0.091256 CHAN A2 (12V): -0.024394 CHAN A3 (5V): -0.077871 CHAN B0 (3.3V): -0.035026 CHAN B1 (3.15aV): -0.021317 CHAN B2 (3.15bV): -0.010651 CHAN B3 (GND): -0.036784 OPEN: 0.008202 Full Scale: +/- 1 mA 2026-01-12T20:59:32.468Z,1768251572.468 [CommandExec](IMPORTANT): got command failComponent 2026-01-12T20:59:32.469Z,1768251572.469 [CommandExec](IMPORTANT): Failed components: 2026-01-12T20:59:32.469Z,1768251572.469 [CommandExec](IMPORTANT): No failed Components. 2026-01-12T20:59:37.053Z,1768251577.053 [DataOverHttps](IMPORTANT): SBD MTMSN=20260112T205939 2026-01-12T20:59:44.708Z,1768251584.708 [DataOverHttps](INFO): Received command: configSet GFScanner.isolationThreshold 0.05 mA persist 2026-01-12T20:59:45.146Z,1768251585.146 [CommandExec](IMPORTANT): got command configSet GFScanner.isolationThreshold 0.05 milliampere persist 2026-01-12T21:00:12.527Z,1768251612.527 [SBIT](IMPORTANT): SBIT PASSED 2026-01-12T21:00:12.528Z,1768251612.528 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2026-01-12T21:00:12.528Z,1768251612.528 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=5 count; 2026-01-12T21:00:12.529Z,1768251612.529 [SBIT](IMPORTANT): CBIT.navErrorThreshold=100 percent; 2026-01-12T21:00:12.529Z,1768251612.529 [SBIT](IMPORTANT): CBIT.runNavErrorCritical=1 bool; 2026-01-12T21:00:12.529Z,1768251612.529 [SBIT](IMPORTANT): Express linearApproximation PowerOnly.component_avgCurrent_loadControl 50.000000 milliampere; 2026-01-12T21:00:12.529Z,1768251612.529 [SBIT](IMPORTANT): Express linearApproximation PowerOnly.samplePowerOnly 1.000000 bool; 2026-01-12T21:00:12.529Z,1768251612.529 [SBIT](IMPORTANT): GFScanner.isolationThreshold=0.05 milliampere; 2026-01-12T21:00:12.529Z,1768251612.529 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=414.894053 cubic_centimeter; 2026-01-12T21:00:12.529Z,1768251612.529 [SBIT](IMPORTANT): VerticalControl.massDefault=31.001089 millimeter; 2026-01-12T21:00:12.912Z,1768251612.912 [MissionManager](IMPORTANT): Started mission Startup 2026-01-12T21:00:12.913Z,1768251612.913 [Startup] Running Loop=1 2026-01-12T21:00:12.913Z,1768251612.913 [Startup](DEBUG): Aggregate::initialize Startup 2026-01-12T21:00:12.913Z,1768251612.913 [Startup:A.GoToSurface] Running Loop=1 2026-01-12T21:00:12.913Z,1768251612.913 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2026-01-12T21:00:12.913Z,1768251612.913 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2026-01-12T21:00:12.914Z,1768251612.914 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2026-01-12T21:00:12.914Z,1768251612.914 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2026-01-12T21:00:12.915Z,1768251612.915 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2026-01-12T21:00:12.915Z,1768251612.915 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2026-01-12T21:00:12.915Z,1768251612.915 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2026-01-12T21:00:12.921Z,1768251612.921 [Startup:StartupSatComms] Running Loop=1 2026-01-12T21:00:12.921Z,1768251612.921 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2026-01-12T21:00:12.921Z,1768251612.921 [Startup:StartupSatComms:A] Running Loop=1 2026-01-12T21:00:13.352Z,1768251613.352 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2026-01-12T21:00:14.903Z,1768251614.903 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210015.00,A,3648.15101,N,12147.21946,W,0.019,156.16,120126,,,A*74 2026-01-12T21:00:14.906Z,1768251614.906 [NAL9602](INFO): GPS fix at 20260112T210015: (36.802517, -121.786991) 2026-01-12T21:00:14.916Z,1768251614.916 [Startup:StartupSatComms:A] Stopped 2026-01-12T21:00:14.916Z,1768251614.916 [Startup:StartupSatComms:B] Running Loop=1 2026-01-12T21:00:15.322Z,1768251615.322 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2026-01-12T21:00:16.005Z,1768251616.005 [DataOverHttps](INFO): Sending 241 bytes from file Logs/20260112T204536/Courier0007.lzma 2026-01-12T21:00:16.710Z,1768251616.710 [DataOverHttps](INFO): Moved sent file to Logs/20260112T204536/Courier0007.lzma.bak 2026-01-12T21:00:16.710Z,1768251616.710 [DataOverHttps](INFO): SBD MOMSN=26557663 2026-01-12T21:00:31.933Z,1768251631.933 [NAL9602](INFO): SBD MO Status=0, MOMSN=25089, MT Status=0, MTMSN=0 2026-01-12T21:00:31.933Z,1768251631.933 [NAL9602](INFO): No messages in MT queue 2026-01-12T21:00:33.800Z,1768251633.800 [DataOverHttps](INFO): Sending 271 bytes from file Logs/20260112T205642/Courier0000.lzma 2026-01-12T21:00:34.802Z,1768251634.802 [DataOverHttps](INFO): Moved sent file to Logs/20260112T205642/Courier0000.lzma.bak 2026-01-12T21:00:34.802Z,1768251634.802 [DataOverHttps](INFO): SBD MOMSN=26557668 2026-01-12T21:00:51.033Z,1768251651.033 [DataOverHttps](INFO): Sending 1241 bytes from file Logs/20260112T204536/Express0008.lzma 2026-01-12T21:00:52.034Z,1768251652.034 [DataOverHttps](INFO): Moved sent file to Logs/20260112T204536/Express0008.lzma.bak 2026-01-12T21:00:52.034Z,1768251652.034 [DataOverHttps](INFO): SBD MOMSN=26557672 2026-01-12T21:01:02.593Z,1768251662.593 [NAL9602](INFO): Not Powering down - fast GPS 2026-01-12T21:01:08.250Z,1768251668.250 [DataOverHttps](INFO): Sending 1306 bytes from file Logs/20260112T205642/Express0001.lzma 2026-01-12T21:01:09.250Z,1768251669.250 [DataOverHttps](INFO): Moved sent file to Logs/20260112T205642/Express0001.lzma.bak 2026-01-12T21:01:09.250Z,1768251669.250 [DataOverHttps](INFO): SBD MOMSN=26557725 2026-01-12T21:01:15.138Z,1768251675.138 [Startup:StartupSatComms:B](INFO): Timed out from 2026-01-12T21:00:14.9Z 2026-01-12T21:01:15.138Z,1768251675.138 [Startup:StartupSatComms:B] Stopped 2026-01-12T21:01:15.138Z,1768251675.138 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2026-01-12T21:01:15.138Z,1768251675.138 [Startup:StartupSatComms] Stopped 2026-01-12T21:01:15.138Z,1768251675.138 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2026-01-12T21:01:15.139Z,1768251675.139 [Startup](INFO): Completed Startup 2026-01-12T21:01:15.139Z,1768251675.139 [MissionManager](INFO): Startup is completed. 2026-01-12T21:01:15.139Z,1768251675.139 [MissionManager](INFO): Uninitializing Mission Startup 2026-01-12T21:01:15.144Z,1768251675.144 [Startup] Stopped 2026-01-12T21:01:15.144Z,1768251675.144 [Startup](DEBUG): Aggregate::uninitialize Startup 2026-01-12T21:01:15.144Z,1768251675.144 [Startup:A.GoToSurface] Stopped 2026-01-12T21:01:15.144Z,1768251675.144 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2026-01-12T21:01:15.551Z,1768251675.551 [MissionManager](IMPORTANT): Started mission Default 2026-01-12T21:01:15.556Z,1768251675.556 [Default] Running Loop=1 2026-01-12T21:01:15.557Z,1768251675.557 [Default](DEBUG): Aggregate::initialize Default 2026-01-12T21:01:15.557Z,1768251675.557 [Default:B.GoToSurface] Running Loop=1 2026-01-12T21:01:15.558Z,1768251675.558 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2026-01-12T21:01:15.559Z,1768251675.559 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2026-01-12T21:01:15.559Z,1768251675.559 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2026-01-12T21:01:15.559Z,1768251675.559 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2026-01-12T21:01:15.564Z,1768251675.564 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2026-01-12T21:01:15.564Z,1768251675.564 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2026-01-12T21:01:15.565Z,1768251675.565 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2026-01-12T21:01:15.565Z,1768251675.565 [Default:A.Wait] Running Loop=1 2026-01-12T21:01:15.565Z,1768251675.565 [Default:A.Wait](DEBUG): Initialize Wait Component. 2026-01-12T21:01:25.575Z,1768251685.575 [DataOverHttps](INFO): Sending 59 bytes from file Logs/20260112T205642/Express0005.lzma 2026-01-12T21:01:26.577Z,1768251686.577 [DataOverHttps](INFO): Moved sent file to Logs/20260112T205642/Express0005.lzma.bak 2026-01-12T21:01:26.578Z,1768251686.578 [DataOverHttps](INFO): SBD MOMSN=26557753 2026-01-12T21:01:28.859Z,1768251688.859 [Default:A.Wait](INFO): Done Waiting. 2026-01-12T21:01:28.859Z,1768251688.859 [Default:A.Wait] Stopped 2026-01-12T21:01:28.859Z,1768251688.859 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2026-01-12T21:01:29.288Z,1768251689.288 [Default:CheckIn] Running Loop=1 2026-01-12T21:01:29.288Z,1768251689.288 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2026-01-12T21:01:29.288Z,1768251689.288 [Default:CheckIn:Read_GPS] Running Loop=1 2026-01-12T21:01:29.668Z,1768251689.668 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2026-01-12T21:01:31.299Z,1768251691.299 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210131.00,A,3648.15014,N,12147.22013,W,0.000,156.16,120126,,,D*71 2026-01-12T21:01:31.301Z,1768251691.301 [NAL9602](INFO): GPS fix at 20260112T210131: (36.802502, -121.787002) 2026-01-12T21:01:31.332Z,1768251691.332 [Default:CheckIn:Read_GPS] Stopped 2026-01-12T21:01:31.332Z,1768251691.332 [Default:CheckIn:Read_Iridium] Running Loop=1 2026-01-12T21:01:31.738Z,1768251691.738 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2026-01-12T21:01:48.053Z,1768251708.053 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20260112T205642/Courier0007.lzma 2026-01-12T21:01:49.054Z,1768251709.054 [DataOverHttps](INFO): Moved sent file to Logs/20260112T205642/Courier0007.lzma.bak 2026-01-12T21:01:49.054Z,1768251709.054 [DataOverHttps](INFO): SBD MOMSN=26557755 2026-01-12T21:02:00.798Z,1768251720.798 [NAL9602](INFO): SBD MO Status=0, MOMSN=25090, MT Status=0, MTMSN=0 2026-01-12T21:02:00.799Z,1768251720.799 [NAL9602](INFO): No messages in MT queue 2026-01-12T21:02:06.359Z,1768251726.359 [DataOverHttps](INFO): Sending 148 bytes from file Logs/20260112T205642/Express0008.lzma 2026-01-12T21:02:07.362Z,1768251727.362 [DataOverHttps](INFO): Moved sent file to Logs/20260112T205642/Express0008.lzma.bak 2026-01-12T21:02:07.362Z,1768251727.362 [DataOverHttps](INFO): SBD MOMSN=26557758 2026-01-12T21:02:08.510Z,1768251728.510 [Default:CheckIn:Read_Iridium] Stopped 2026-01-12T21:02:08.511Z,1768251728.511 [Default:CheckIn:C.Wait] Running Loop=1 2026-01-12T21:02:08.511Z,1768251728.511 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2026-01-12T21:02:31.488Z,1768251751.488 [NAL9602](INFO): Not Powering down - fast GPS 2026-01-12T21:06:32.674Z,1768251992.674 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2026-01-12T21:07:09.034Z,1768252029.034 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2026-01-12T21:07:09.035Z,1768252029.035 [Default:CheckIn:C.Wait] Stopped 2026-01-12T21:07:09.035Z,1768252029.035 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2026-01-12T21:07:09.035Z,1768252029.035 [Default:CheckIn:D] Running Loop=1 2026-01-12T21:07:09.439Z,1768252029.439 [Default:CheckIn:D] Stopped 2026-01-12T21:07:09.439Z,1768252029.439 [Default:CheckIn:E] Running Loop=1 2026-01-12T21:07:09.860Z,1768252029.860 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 5.898120 min 2026-01-12T21:07:09.860Z,1768252029.860 [Default:CheckIn:E] Stopped 2026-01-12T21:07:09.860Z,1768252029.860 [Default:CheckIn](INFO): Completed Default:CheckIn 2026-01-12T21:07:09.860Z,1768252029.860 [Default:CheckIn] Stopped 2026-01-12T21:07:09.860Z,1768252029.860 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2026-01-12T21:07:09.861Z,1768252029.861 [Default:CheckIn](INFO): Running loop #2 2026-01-12T21:07:09.861Z,1768252029.861 [Default:CheckIn] Running Loop=2 2026-01-12T21:07:09.861Z,1768252029.861 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2026-01-12T21:07:09.861Z,1768252029.861 [Default:CheckIn:Read_GPS] Running Loop=1 2026-01-12T21:07:11.856Z,1768252031.856 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210712.00,A,3648.15179,N,12147.22059,W,0.019,156.16,120126,,,D*7A 2026-01-12T21:07:11.858Z,1768252031.858 [NAL9602](INFO): GPS fix at 20260112T210712: (36.802530, -121.787010) 2026-01-12T21:07:11.869Z,1768252031.869 [Default:CheckIn:Read_GPS] Stopped 2026-01-12T21:07:11.869Z,1768252031.869 [Default:CheckIn:Read_Iridium] Running Loop=1 2026-01-12T21:07:19.531Z,1768252039.531 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20260112T205642/Courier0010.lzma 2026-01-12T21:07:20.534Z,1768252040.534 [DataOverHttps](INFO): Moved sent file to Logs/20260112T205642/Courier0010.lzma.bak 2026-01-12T21:07:20.534Z,1768252040.534 [DataOverHttps](INFO): SBD MOMSN=26557806 2026-01-12T21:07:36.783Z,1768252056.783 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20260112T205642/Express0011.lzma 2026-01-12T21:07:37.786Z,1768252057.786 [DataOverHttps](INFO): Moved sent file to Logs/20260112T205642/Express0011.lzma.bak 2026-01-12T21:07:37.786Z,1768252057.786 [DataOverHttps](INFO): SBD MOMSN=26557812 2026-01-12T21:07:38.939Z,1768252058.939 [Default:CheckIn:Read_Iridium] Stopped 2026-01-12T21:07:38.939Z,1768252058.939 [Default:CheckIn:C.Wait] Running Loop=1 2026-01-12T21:07:38.940Z,1768252058.940 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2026-01-12T21:07:49.421Z,1768252069.421 [NAL9602](INFO): SBD MO Status=0, MOMSN=25091, MT Status=0, MTMSN=0 2026-01-12T21:07:49.421Z,1768252069.421 [NAL9602](INFO): No messages in MT queue 2026-01-12T21:08:20.121Z,1768252100.121 [NAL9602](INFO): Not Powering down - fast GPS 2026-01-12T21:12:39.504Z,1768252359.504 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2026-01-12T21:12:39.504Z,1768252359.504 [Default:CheckIn:C.Wait] Stopped 2026-01-12T21:12:39.504Z,1768252359.504 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2026-01-12T21:12:39.505Z,1768252359.505 [Default:CheckIn:D] Running Loop=1 2026-01-12T21:12:39.919Z,1768252359.919 [Default:CheckIn:D] Stopped 2026-01-12T21:12:39.919Z,1768252359.919 [Default:CheckIn:E] Running Loop=1 2026-01-12T21:12:40.306Z,1768252360.306 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.406126 min 2026-01-12T21:12:40.306Z,1768252360.306 [Default:CheckIn:E] Stopped 2026-01-12T21:12:40.307Z,1768252360.307 [Default:CheckIn](INFO): Completed Default:CheckIn 2026-01-12T21:12:40.307Z,1768252360.307 [Default:CheckIn] Stopped 2026-01-12T21:12:40.307Z,1768252360.307 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2026-01-12T21:12:40.307Z,1768252360.307 [Default:CheckIn](INFO): Running loop #3 2026-01-12T21:12:40.307Z,1768252360.307 [Default:CheckIn] Running Loop=3 2026-01-12T21:12:40.307Z,1768252360.307 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2026-01-12T21:12:40.307Z,1768252360.307 [Default:CheckIn:Read_GPS] Running Loop=1 2026-01-12T21:12:42.327Z,1768252362.327 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211242.00,A,3648.15243,N,12147.22101,W,0.058,156.16,120126,,,D*78 2026-01-12T21:12:42.330Z,1768252362.330 [NAL9602](INFO): GPS fix at 20260112T211242: (36.802540, -121.787017) 2026-01-12T21:12:42.340Z,1768252362.340 [Default:CheckIn:Read_GPS] Stopped 2026-01-12T21:12:42.340Z,1768252362.340 [Default:CheckIn:Read_Iridium] Running Loop=1 2026-01-12T21:12:49.573Z,1768252369.573 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20260112T205642/Courier0013.lzma 2026-01-12T21:12:50.574Z,1768252370.574 [DataOverHttps](INFO): Moved sent file to Logs/20260112T205642/Courier0013.lzma.bak 2026-01-12T21:12:50.574Z,1768252370.574 [DataOverHttps](INFO): SBD MOMSN=26557819 2026-01-12T21:12:58.149Z,1768252378.149 [NAL9602](INFO): SBD MO Status=0, MOMSN=25092, MT Status=0, MTMSN=0 2026-01-12T21:12:58.149Z,1768252378.149 [NAL9602](INFO): No messages in MT queue 2026-01-12T21:13:06.611Z,1768252386.611 [DataOverHttps](INFO): Sending 174 bytes from file Logs/20260112T205642/Express0014.lzma 2026-01-12T21:13:07.614Z,1768252387.614 [DataOverHttps](INFO): Moved sent file to Logs/20260112T205642/Express0014.lzma.bak 2026-01-12T21:13:07.614Z,1768252387.614 [DataOverHttps](INFO): SBD MOMSN=26557824 2026-01-12T21:13:08.999Z,1768252388.999 [Default:CheckIn:Read_Iridium] Stopped 2026-01-12T21:13:08.999Z,1768252388.999 [Default:CheckIn:C.Wait] Running Loop=1 2026-01-12T21:13:08.999Z,1768252388.999 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2026-01-12T21:13:28.787Z,1768252408.787 [NAL9602](INFO): Not Powering down - fast GPS 2026-01-12T21:16:12.817Z,1768252572.817 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2026-01-12T21:18:09.574Z,1768252689.574 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2026-01-12T21:18:09.574Z,1768252689.574 [Default:CheckIn:C.Wait] Stopped 2026-01-12T21:18:09.574Z,1768252689.574 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2026-01-12T21:18:09.574Z,1768252689.574 [Default:CheckIn:D] Running Loop=1 2026-01-12T21:18:09.978Z,1768252689.978 [Default:CheckIn:D] Stopped 2026-01-12T21:18:09.978Z,1768252689.978 [Default:CheckIn:E] Running Loop=1 2026-01-12T21:18:10.387Z,1768252690.387 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 16.907107 min 2026-01-12T21:18:10.388Z,1768252690.388 [Default:CheckIn:E] Stopped 2026-01-12T21:18:10.388Z,1768252690.388 [Default:CheckIn](INFO): Completed Default:CheckIn 2026-01-12T21:18:10.388Z,1768252690.388 [Default:CheckIn] Stopped 2026-01-12T21:18:10.388Z,1768252690.388 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2026-01-12T21:18:10.388Z,1768252690.388 [Default:CheckIn](INFO): Running loop #4 2026-01-12T21:18:10.388Z,1768252690.388 [Default:CheckIn] Running Loop=4 2026-01-12T21:18:10.388Z,1768252690.388 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2026-01-12T21:18:10.389Z,1768252690.389 [Default:CheckIn:Read_GPS] Running Loop=1 2026-01-12T21:18:12.399Z,1768252692.399 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211812.00,A,3648.24059,N,12147.13634,W,4.510,14.86,120126,,,D*4C 2026-01-12T21:18:12.402Z,1768252692.402 [NAL9602](INFO): GPS fix at 20260112T211812: (36.804010, -121.785606) 2026-01-12T21:18:12.413Z,1768252692.413 [Default:CheckIn:Read_GPS] Stopped 2026-01-12T21:18:12.413Z,1768252692.413 [Default:CheckIn:Read_Iridium] Running Loop=1 2026-01-12T21:18:20.515Z,1768252700.515 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20260112T205642/Courier0016.lzma 2026-01-12T21:18:21.518Z,1768252701.518 [DataOverHttps](INFO): Moved sent file to Logs/20260112T205642/Courier0016.lzma.bak 2026-01-12T21:18:21.518Z,1768252701.518 [DataOverHttps](INFO): SBD MOMSN=26557878 2026-01-12T21:18:37.775Z,1768252717.775 [DataOverHttps](INFO): Sending 189 bytes from file Logs/20260112T205642/Express0017.lzma 2026-01-12T21:18:38.778Z,1768252718.778 [DataOverHttps](INFO): Moved sent file to Logs/20260112T205642/Express0017.lzma.bak 2026-01-12T21:18:38.778Z,1768252718.778 [DataOverHttps](INFO): SBD MOMSN=26557881 2026-01-12T21:18:39.874Z,1768252719.874 [Default:CheckIn:Read_Iridium] Stopped 2026-01-12T21:18:39.874Z,1768252719.874 [Default:CheckIn:C.Wait] Running Loop=1 2026-01-12T21:18:39.874Z,1768252719.874 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2026-01-12T21:18:42.302Z,1768252722.302 [NAL9602](INFO): SBD MO Status=0, MOMSN=25093, MT Status=0, MTMSN=0 2026-01-12T21:18:42.302Z,1768252722.302 [NAL9602](INFO): No messages in MT queue 2026-01-12T21:19:12.993Z,1768252752.993 [NAL9602](INFO): Not Powering down - fast GPS 2026-01-12T21:23:40.463Z,1768253020.463 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2026-01-12T21:23:40.463Z,1768253020.463 [Default:CheckIn:C.Wait] Stopped 2026-01-12T21:23:40.463Z,1768253020.463 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2026-01-12T21:23:40.463Z,1768253020.463 [Default:CheckIn:D] Running Loop=1 2026-01-12T21:23:40.866Z,1768253020.866 [Default:CheckIn:D] Stopped 2026-01-12T21:23:40.866Z,1768253020.866 [Default:CheckIn:E] Running Loop=1 2026-01-12T21:23:41.263Z,1768253021.263 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.421901 min 2026-01-12T21:23:41.263Z,1768253021.263 [Default:CheckIn:E] Stopped 2026-01-12T21:23:41.263Z,1768253021.263 [Default:CheckIn](INFO): Completed Default:CheckIn 2026-01-12T21:23:41.263Z,1768253021.263 [Default:CheckIn] Stopped 2026-01-12T21:23:41.263Z,1768253021.263 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2026-01-12T21:23:41.263Z,1768253021.263 [Default:CheckIn](INFO): Running loop #5 2026-01-12T21:23:41.276Z,1768253021.276 [Default:CheckIn] Running Loop=5 2026-01-12T21:23:41.276Z,1768253021.276 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2026-01-12T21:23:41.276Z,1768253021.276 [Default:CheckIn:Read_GPS] Running Loop=1 2026-01-12T21:23:43.331Z,1768253023.331 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212343.00,A,3648.34701,N,12147.53520,W,18.078,242.77,120126,,,D*40 2026-01-12T21:23:43.333Z,1768253023.333 [NAL9602](INFO): GPS fix at 20260112T212343: (36.805783, -121.792253) 2026-01-12T21:23:43.366Z,1768253023.366 [Default:CheckIn:Read_GPS] Stopped 2026-01-12T21:23:43.366Z,1768253023.366 [Default:CheckIn:Read_Iridium] Running Loop=1 2026-01-12T21:23:51.499Z,1768253031.499 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20260112T205642/Courier0019.lzma 2026-01-12T21:23:52.502Z,1768253032.502 [DataOverHttps](INFO): Moved sent file to Logs/20260112T205642/Courier0019.lzma.bak 2026-01-12T21:23:52.502Z,1768253032.502 [DataOverHttps](INFO): SBD MOMSN=26557887 2026-01-12T21:24:08.484Z,1768253048.484 [DataOverHttps](INFO): Sending 194 bytes from file Logs/20260112T205642/Express0020.lzma 2026-01-12T21:24:09.486Z,1768253049.486 [DataOverHttps](INFO): Moved sent file to Logs/20260112T205642/Express0020.lzma.bak 2026-01-12T21:24:09.486Z,1768253049.486 [DataOverHttps](INFO): SBD MOMSN=26557890 2026-01-12T21:24:10.759Z,1768253050.759 [Default:CheckIn:Read_Iridium] Stopped 2026-01-12T21:24:10.759Z,1768253050.759 [Default:CheckIn:C.Wait] Running Loop=1 2026-01-12T21:24:10.759Z,1768253050.759 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2026-01-12T21:24:22.495Z,1768253062.495 [NAL9602](INFO): SBD MO Status=0, MOMSN=25094, MT Status=0, MTMSN=0 2026-01-12T21:24:22.495Z,1768253062.495 [NAL9602](INFO): No messages in MT queue 2026-01-12T21:24:53.176Z,1768253093.176 [NAL9602](INFO): Not Powering down - fast GPS 2026-01-12T21:27:54.971Z,1768253274.971 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5. 2026-01-12T21:27:54.974Z,1768253274.974 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle. 2026-01-12T21:29:11.347Z,1768253351.347 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2026-01-12T21:29:11.347Z,1768253351.347 [Default:CheckIn:C.Wait] Stopped 2026-01-12T21:29:11.347Z,1768253351.347 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2026-01-12T21:29:11.347Z,1768253351.347 [Default:CheckIn:D] Running Loop=1 2026-01-12T21:29:11.741Z,1768253351.741 [Default:CheckIn:D] Stopped 2026-01-12T21:29:11.741Z,1768253351.741 [Default:CheckIn:E] Running Loop=1 2026-01-12T21:29:12.159Z,1768253352.159 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 27.936491 min 2026-01-12T21:29:12.159Z,1768253352.159 [Default:CheckIn:E] Stopped 2026-01-12T21:29:12.159Z,1768253352.159 [Default:CheckIn](INFO): Completed Default:CheckIn 2026-01-12T21:29:12.159Z,1768253352.159 [Default:CheckIn] Stopped 2026-01-12T21:29:12.159Z,1768253352.159 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2026-01-12T21:29:12.159Z,1768253352.159 [Default:CheckIn](INFO): Running loop #6 2026-01-12T21:29:12.159Z,1768253352.159 [Default:CheckIn] Running Loop=6 2026-01-12T21:29:12.160Z,1768253352.160 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2026-01-12T21:29:12.160Z,1768253352.160 [Default:CheckIn:Read_GPS] Running Loop=1 2026-01-12T21:29:14.163Z,1768253354.163 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212914.00,A,3647.93311,N,12150.10041,W,22.490,259.23,120126,,,D*4C 2026-01-12T21:29:14.165Z,1768253354.165 [NAL9602](INFO): GPS fix at 20260112T212914: (36.798885, -121.835007) 2026-01-12T21:29:14.175Z,1768253354.175 [Default:CheckIn:Read_GPS] Stopped 2026-01-12T21:29:14.175Z,1768253354.175 [Default:CheckIn:Read_Iridium] Running Loop=1 2026-01-12T21:29:21.980Z,1768253361.980 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20260112T205642/Courier0022.lzma 2026-01-12T21:29:22.982Z,1768253362.982 [DataOverHttps](INFO): Moved sent file to Logs/20260112T205642/Courier0022.lzma.bak 2026-01-12T21:29:22.982Z,1768253362.982 [DataOverHttps](INFO): SBD MOMSN=26557939 2026-01-12T21:29:39.609Z,1768253379.609 [NAL9602](INFO): SBD MO Status=0, MOMSN=25095, MT Status=0, MTMSN=0 2026-01-12T21:29:39.610Z,1768253379.610 [NAL9602](INFO): No messages in MT queue 2026-01-12T21:29:39.908Z,1768253379.908 [DataOverHttps](INFO): Sending 195 bytes from file Logs/20260112T205642/Express0023.lzma 2026-01-12T21:29:40.910Z,1768253380.910 [DataOverHttps](INFO): Moved sent file to Logs/20260112T205642/Express0023.lzma.bak 2026-01-12T21:29:40.910Z,1768253380.910 [DataOverHttps](INFO): SBD MOMSN=26557942 2026-01-12T21:29:42.047Z,1768253382.047 [Default:CheckIn:Read_Iridium] Stopped 2026-01-12T21:29:42.047Z,1768253382.047 [Default:CheckIn:C.Wait] Running Loop=1 2026-01-12T21:29:42.047Z,1768253382.047 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2026-01-12T21:30:10.319Z,1768253410.319 [NAL9602](INFO): Not Powering down - fast GPS 2026-01-12T21:34:42.619Z,1768253682.619 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2026-01-12T21:34:42.619Z,1768253682.619 [Default:CheckIn:C.Wait] Stopped 2026-01-12T21:34:42.619Z,1768253682.619 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2026-01-12T21:34:42.619Z,1768253682.619 [Default:CheckIn:D] Running Loop=1 2026-01-12T21:34:43.027Z,1768253683.027 [Default:CheckIn:D] Stopped 2026-01-12T21:34:43.027Z,1768253683.027 [Default:CheckIn:E] Running Loop=1 2026-01-12T21:34:43.430Z,1768253683.430 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 33.457920 min 2026-01-12T21:34:43.430Z,1768253683.430 [Default:CheckIn:E] Stopped 2026-01-12T21:34:43.430Z,1768253683.430 [Default:CheckIn](INFO): Completed Default:CheckIn 2026-01-12T21:34:43.430Z,1768253683.430 [Default:CheckIn] Stopped 2026-01-12T21:34:43.430Z,1768253683.430 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2026-01-12T21:34:43.430Z,1768253683.430 [Default:CheckIn](INFO): Running loop #7 2026-01-12T21:34:43.430Z,1768253683.430 [Default:CheckIn] Running Loop=7 2026-01-12T21:34:43.430Z,1768253683.430 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2026-01-12T21:34:43.430Z,1768253683.430 [Default:CheckIn:Read_GPS] Running Loop=1 2026-01-12T21:34:45.450Z,1768253685.450 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213445.00,A,3647.77574,N,12150.76683,W,1.147,130.47,120126,,,D*73 2026-01-12T21:34:45.452Z,1768253685.452 [NAL9602](INFO): GPS fix at 20260112T213445: (36.796262, -121.846114) 2026-01-12T21:34:45.462Z,1768253685.462 [Default:CheckIn:Read_GPS] Stopped 2026-01-12T21:34:45.463Z,1768253685.463 [Default:CheckIn:Read_Iridium] Running Loop=1 2026-01-12T21:34:53.215Z,1768253693.215 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20260112T205642/Courier0025.lzma 2026-01-12T21:34:54.218Z,1768253694.218 [DataOverHttps](INFO): Moved sent file to Logs/20260112T205642/Courier0025.lzma.bak 2026-01-12T21:34:54.218Z,1768253694.218 [DataOverHttps](INFO): SBD MOMSN=26557946 2026-01-12T21:35:12.519Z,1768253712.519 [NAL9602](INFO): SBD MO Status=2, MOMSN=25096, MT Status=2, MTMSN=0 2026-01-12T21:35:12.519Z,1768253712.519 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-01-12T21:35:20.466Z,1768253720.466 [DataOverHttps](INFO): Sending 195 bytes from file Logs/20260112T205642/Express0026.lzma 2026-01-12T21:35:21.466Z,1768253721.466 [DataOverHttps](INFO): Moved sent file to Logs/20260112T205642/Express0026.lzma.bak 2026-01-12T21:35:21.466Z,1768253721.466 [DataOverHttps](INFO): SBD MOMSN=26557949 2026-01-12T21:35:22.633Z,1768253722.633 [Default:CheckIn:Read_Iridium] Stopped 2026-01-12T21:35:22.633Z,1768253722.633 [Default:CheckIn:C.Wait] Running Loop=1 2026-01-12T21:35:22.633Z,1768253722.633 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2026-01-12T21:35:27.859Z,1768253727.859 [NAL9602](INFO): SBD MO Status=2, MOMSN=25096, MT Status=2, MTMSN=0 2026-01-12T21:35:27.859Z,1768253727.859 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-01-12T21:35:56.568Z,1768253756.568 [NAL9602](INFO): SBD MO Status=2, MOMSN=25096, MT Status=2, MTMSN=0 2026-01-12T21:35:56.568Z,1768253756.568 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2026-01-12T21:36:21.593Z,1768253781.593 [NAL9602](INFO): SBD MO Status=0, MOMSN=25096, MT Status=0, MTMSN=0 2026-01-12T21:36:21.593Z,1768253781.593 [NAL9602](INFO): No messages in MT queue 2026-01-12T21:36:52.301Z,1768253812.301 [NAL9602](INFO): Not Powering down - fast GPS 2026-01-12T21:39:05.773Z,1768253945.773 [DataOverHttps](IMPORTANT): SBD MTMSN=20260112T213908 2026-01-12T21:39:09.716Z,1768253949.716 [DVL_micro](ERROR): Failed to parse: :SA,+03.26,-05.66,270.8 2026-01-12T21:39:13.340Z,1768253953.340 [DataOverHttps](INFO): Received command: restart logs