2026-03-09T16:09:00.261Z,1773072540.261 [Supervisor](DEBUG): Initializing supervisor.
2026-03-09T16:09:00.265Z,1773072540.265 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2026-03-09T16:09:00.266Z,1773072540.266 [SyncHandler](INFO): Protected caller Thread ID is 836
2026-03-09T16:09:00.266Z,1773072540.266 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2026-03-09T16:09:00.267Z,1773072540.267 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2026-03-09T16:09:00.268Z,1773072540.268 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 837
2026-03-09T16:09:00.272Z,1773072540.272 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2026-03-09T16:09:00.291Z,1773072540.291 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2026-03-09T16:09:00.292Z,1773072540.292 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2026-03-09T16:09:00.292Z,1773072540.292 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 838
2026-03-09T16:09:00.296Z,1773072540.296 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2026-03-09T16:09:00.297Z,1773072540.297 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2026-03-09T16:09:00.298Z,1773072540.298 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 839
2026-03-09T16:09:00.300Z,1773072540.300 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2026-03-09T16:09:00.301Z,1773072540.301 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2026-03-09T16:09:00.301Z,1773072540.301 [logger ThreadHandler](INFO): Protected caller Thread ID is 840
2026-03-09T16:09:00.305Z,1773072540.305 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2026-03-09T16:09:00.305Z,1773072540.305 [Supervisor](INFO): Looking for Config files in directory: Config/
2026-03-09T16:09:00.309Z,1773072540.309 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2026-03-09T16:09:00.781Z,1773072540.781 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2026-03-09T16:09:00.783Z,1773072540.783 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2026-03-09T16:09:00.996Z,1773072540.996 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2026-03-09T16:09:00.997Z,1773072540.997 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2026-03-09T16:09:01.097Z,1773072541.097 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance
2026-03-09T16:09:01.098Z,1773072541.098 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2026-03-09T16:09:01.334Z,1773072541.334 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2026-03-09T16:09:01.336Z,1773072541.336 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2026-03-09T16:09:01.424Z,1773072541.424 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2026-03-09T16:09:01.527Z,1773072541.527 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2026-03-09T16:09:01.528Z,1773072541.528 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2026-03-09T16:09:01.896Z,1773072541.896 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2026-03-09T16:09:01.898Z,1773072541.898 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2026-03-09T16:09:02.039Z,1773072542.039 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2026-03-09T16:09:02.039Z,1773072542.039 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2026-03-09T16:09:02.576Z,1773072542.576 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2026-03-09T16:09:02.576Z,1773072542.576 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2026-03-09T16:09:02.760Z,1773072542.760 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2026-03-09T16:09:02.762Z,1773072542.762 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2026-03-09T16:09:03.196Z,1773072543.196 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2026-03-09T16:09:03.197Z,1773072543.197 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2026-03-09T16:09:03.491Z,1773072543.491 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2026-03-09T16:09:04.104Z,1773072544.104 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2026-03-09T16:09:04.464Z,1773072544.464 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2026-03-09T16:09:04.464Z,1773072544.464 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2026-03-09T16:09:05.353Z,1773072545.353 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2026-03-09T16:09:05.355Z,1773072545.355 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2026-03-09T16:09:05.699Z,1773072545.699 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2026-03-09T16:09:05.701Z,1773072545.701 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2026-03-09T16:09:05.957Z,1773072545.957 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2026-03-09T16:09:05.959Z,1773072545.959 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2026-03-09T16:09:05.961Z,1773072545.961 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2026-03-09T16:09:06.089Z,1773072546.089 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2026-03-09T16:09:06.181Z,1773072546.181 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2026-03-09T16:09:06.324Z,1773072546.324 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2026-03-09T16:09:06.418Z,1773072546.418 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2026-03-09T16:09:06.687Z,1773072546.687 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2026-03-09T16:09:06.688Z,1773072546.688 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2026-03-09T16:09:06.803Z,1773072546.803 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2026-03-09T16:09:06.921Z,1773072546.921 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2026-03-09T16:09:07.031Z,1773072547.031 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2026-03-09T16:09:07.197Z,1773072547.197 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2026-03-09T16:09:07.305Z,1773072547.305 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2026-03-09T16:09:07.402Z,1773072547.402 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2026-03-09T16:09:07.415Z,1773072547.415 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2026-03-09T16:09:07.431Z,1773072547.431 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2026-03-09T16:09:07.432Z,1773072547.432 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2026-03-09T16:09:07.596Z,1773072547.596 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2026-03-09T16:09:07.598Z,1773072547.598 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2026-03-09T16:09:07.671Z,1773072547.671 [VerticalControl](DEBUG): Construct VerticalControl.
2026-03-09T16:09:07.732Z,1773072547.732 [VerticalControl] Loaded
2026-03-09T16:09:07.732Z,1773072547.732 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2026-03-09T16:09:07.735Z,1773072547.735 [HorizontalControl](DEBUG): Construct HorizontalControl.
2026-03-09T16:09:07.781Z,1773072547.782 [HorizontalControl] Loaded
2026-03-09T16:09:07.782Z,1773072547.782 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2026-03-09T16:09:07.784Z,1773072547.784 [SpeedControl](DEBUG): Construct SpeedControl.
2026-03-09T16:09:07.787Z,1773072547.787 [SpeedControl] Loaded
2026-03-09T16:09:07.787Z,1773072547.787 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2026-03-09T16:09:07.790Z,1773072547.790 [LoopControl](DEBUG): Construct LoopControl.
2026-03-09T16:09:07.790Z,1773072547.790 [LoopControl] Loaded
2026-03-09T16:09:07.791Z,1773072547.791 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2026-03-09T16:09:07.791Z,1773072547.791 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2026-03-09T16:09:07.792Z,1773072547.792 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2026-03-09T16:09:07.979Z,1773072547.979 [BuoyancyServo] Loaded
2026-03-09T16:09:07.980Z,1773072547.980 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2026-03-09T16:09:08.002Z,1773072548.002 [ElevatorServo] Loaded
2026-03-09T16:09:08.002Z,1773072548.002 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2026-03-09T16:09:08.023Z,1773072548.023 [MassServo] Loaded
2026-03-09T16:09:08.023Z,1773072548.023 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2026-03-09T16:09:08.043Z,1773072548.043 [RudderServo] Loaded
2026-03-09T16:09:08.043Z,1773072548.043 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2026-03-09T16:09:08.060Z,1773072548.060 [ThrusterHE] Loaded
2026-03-09T16:09:08.060Z,1773072548.060 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2026-03-09T16:09:08.060Z,1773072548.060 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2026-03-09T16:09:08.061Z,1773072548.061 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2026-03-09T16:09:08.131Z,1773072548.131 [DepthRateCalculator] Loaded
2026-03-09T16:09:08.131Z,1773072548.131 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2026-03-09T16:09:08.136Z,1773072548.136 [PitchRateCalculator] Loaded
2026-03-09T16:09:08.136Z,1773072548.136 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2026-03-09T16:09:08.146Z,1773072548.146 [SpeedCalculator] Loaded
2026-03-09T16:09:08.146Z,1773072548.146 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2026-03-09T16:09:08.150Z,1773072548.150 [YawRateCalculator] Loaded
2026-03-09T16:09:08.151Z,1773072548.151 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2026-03-09T16:09:08.169Z,1773072548.169 [ElevatorOffsetCalculator] Loaded
2026-03-09T16:09:08.169Z,1773072548.169 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2026-03-09T16:09:08.169Z,1773072548.169 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2026-03-09T16:09:08.170Z,1773072548.170 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2026-03-09T16:09:08.308Z,1773072548.308 [DeadReckonUsingMultipleVelocitySources] Loaded
2026-03-09T16:09:08.309Z,1773072548.309 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2026-03-09T16:09:08.322Z,1773072548.322 [NavChart] Loaded
2026-03-09T16:09:08.322Z,1773072548.322 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2026-03-09T16:09:08.328Z,1773072548.328 [UniversalFixResidualReporter] Loaded
2026-03-09T16:09:08.328Z,1773072548.328 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2026-03-09T16:09:08.329Z,1773072548.329 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2026-03-09T16:09:08.329Z,1773072548.329 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2026-03-09T16:09:09.559Z,1773072549.559 [AHRS_M2] Loaded
2026-03-09T16:09:09.559Z,1773072549.559 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2026-03-09T16:09:09.955Z,1773072549.955 [BackseatComponent] Loaded
2026-03-09T16:09:09.956Z,1773072549.956 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2026-03-09T16:09:09.968Z,1773072549.968 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409454E0
2026-03-09T16:09:09.968Z,1773072549.968 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 922
2026-03-09T16:09:09.971Z,1773072549.971 [LcmPublisher] Loaded
2026-03-09T16:09:09.971Z,1773072549.971 [ComponentRegistry](DEBUG): SyncComponent "LcmPublisher" handled in the control thread.
2026-03-09T16:09:10.013Z,1773072550.013 [BioacousticsDataBridge] Loaded
2026-03-09T16:09:10.015Z,1773072550.015 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread.
2026-03-09T16:09:10.829Z,1773072550.829 [BPC1] Loaded
2026-03-09T16:09:10.829Z,1773072550.829 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2026-03-09T16:09:10.926Z,1773072550.926 [DAT] Loaded
2026-03-09T16:09:10.926Z,1773072550.926 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2026-03-09T16:09:10.927Z,1773072550.927 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409754E0
2026-03-09T16:09:10.927Z,1773072550.927 [DAT ThreadHandler](INFO): Protected caller Thread ID is 923
2026-03-09T16:09:11.110Z,1773072551.110 [DataOverHttps] Loaded
2026-03-09T16:09:11.111Z,1773072551.111 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2026-03-09T16:09:11.112Z,1773072551.112 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409A54E0
2026-03-09T16:09:11.112Z,1773072551.112 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 925
2026-03-09T16:09:11.135Z,1773072551.135 [Depth_Keller] Loaded
2026-03-09T16:09:11.135Z,1773072551.135 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2026-03-09T16:09:11.140Z,1773072551.140 [DropWeight] Loaded
2026-03-09T16:09:11.140Z,1773072551.140 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2026-03-09T16:09:11.203Z,1773072551.203 [NAL9602] Loaded
2026-03-09T16:09:11.203Z,1773072551.203 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2026-03-09T16:09:11.232Z,1773072551.232 [Onboard] Loaded
2026-03-09T16:09:11.233Z,1773072551.233 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2026-03-09T16:09:11.234Z,1773072551.234 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409D54E0
2026-03-09T16:09:11.234Z,1773072551.234 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 926
2026-03-09T16:09:11.240Z,1773072551.240 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3
2026-03-09T16:09:11.253Z,1773072551.253 [PowerOnly] Loaded
2026-03-09T16:09:11.253Z,1773072551.253 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2026-03-09T16:09:11.268Z,1773072551.268 [Power24vConverter] Loaded
2026-03-09T16:09:11.268Z,1773072551.268 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2026-03-09T16:09:11.282Z,1773072551.282 [Radio_Surface] Loaded
2026-03-09T16:09:11.282Z,1773072551.282 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2026-03-09T16:09:11.283Z,1773072551.283 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A054E0
2026-03-09T16:09:11.283Z,1773072551.283 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 927
2026-03-09T16:09:11.299Z,1773072551.299 [Sonardyne_Nano] Loaded
2026-03-09T16:09:11.299Z,1773072551.299 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2026-03-09T16:09:11.299Z,1773072551.299 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2026-03-09T16:09:11.300Z,1773072551.300 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2026-03-09T16:09:11.402Z,1773072551.402 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2026-03-09T16:09:11.402Z,1773072551.402 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2026-03-09T16:09:11.617Z,1773072551.617 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2026-03-09T16:09:11.618Z,1773072551.618 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2026-03-09T16:09:11.666Z,1773072551.666 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2026-03-09T16:09:11.667Z,1773072551.667 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2026-03-09T16:09:11.886Z,1773072551.886 [CTD_Seabird] Loaded
2026-03-09T16:09:11.886Z,1773072551.886 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2026-03-09T16:09:11.887Z,1773072551.887 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B514E0
2026-03-09T16:09:11.888Z,1773072551.888 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 928
2026-03-09T16:09:11.910Z,1773072551.910 [PAR_Licor] Loaded
2026-03-09T16:09:11.910Z,1773072551.910 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2026-03-09T16:09:11.954Z,1773072551.954 [WetLabsBB2FL] Loaded
2026-03-09T16:09:11.955Z,1773072551.955 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2026-03-09T16:09:11.956Z,1773072551.956 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B814E0
2026-03-09T16:09:11.956Z,1773072551.956 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 929
2026-03-09T16:09:11.957Z,1773072551.957 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2026-03-09T16:09:11.958Z,1773072551.958 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2026-03-09T16:09:12.356Z,1773072552.356 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2026-03-09T16:09:12.357Z,1773072552.357 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2026-03-09T16:09:12.552Z,1773072552.552 [SBIT](DEBUG): Construct Startup Built In Test.
2026-03-09T16:09:12.561Z,1773072552.561 [SBIT] Loaded
2026-03-09T16:09:12.561Z,1773072552.561 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2026-03-09T16:09:12.564Z,1773072552.564 [IBIT](DEBUG): Construct Initiated Built In Test.
2026-03-09T16:09:12.577Z,1773072552.577 [IBIT] Loaded
2026-03-09T16:09:12.577Z,1773072552.577 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2026-03-09T16:09:12.582Z,1773072552.582 [CBIT](DEBUG): Construct Continuous Built In Test.
2026-03-09T16:09:12.662Z,1773072552.662 [CBIT] Loaded
2026-03-09T16:09:12.662Z,1773072552.662 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2026-03-09T16:09:12.695Z,1773072552.695 [GFScanner] Loaded
2026-03-09T16:09:12.695Z,1773072552.695 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2026-03-09T16:09:12.695Z,1773072552.695 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2026-03-09T16:09:12.701Z,1773072552.701 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2026-03-09T16:09:12.704Z,1773072552.704 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2026-03-09T16:09:12.715Z,1773072552.715 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2026-03-09T16:09:12.716Z,1773072552.716 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C8A4E0
2026-03-09T16:09:12.717Z,1773072552.717 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 930
2026-03-09T16:09:12.721Z,1773072552.721 [Supervisor](INFO): Main Thread ID is 829
2026-03-09T16:09:12.721Z,1773072552.721 [Supervisor](DEBUG): Running supervisor.
2026-03-09T16:09:12.722Z,1773072552.722 [CommandExec ThreadHandler](INFO): Handler Thread ID is 931
2026-03-09T16:09:12.722Z,1773072552.722 [CommandExec](INFO): Initializing the command executive.
2026-03-09T16:09:12.724Z,1773072552.724 [CommandLine ThreadHandler](INFO): Handler Thread ID is 932
2026-03-09T16:09:12.726Z,1773072552.726 [controlThread ThreadHandler](INFO): Handler Thread ID is 933
2026-03-09T16:09:12.726Z,1773072552.726 [controlThread](DEBUG): Initializing ControlThread
2026-03-09T16:09:12.727Z,1773072552.727 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2026-03-09T16:09:12.729Z,1773072552.729 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2026-03-09T16:09:12.730Z,1773072552.730 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2026-03-09T16:09:12.730Z,1773072552.730 [LoopControl](DEBUG): Initialize LoopControlComponent.
2026-03-09T16:09:12.731Z,1773072552.731 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2026-03-09T16:09:12.732Z,1773072552.732 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2026-03-09T16:09:12.732Z,1773072552.732 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2026-03-09T16:09:12.732Z,1773072552.732 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2026-03-09T16:09:12.733Z,1773072552.733 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2026-03-09T16:09:12.734Z,1773072552.734 [NavChart](DEBUG): Initialize NavChart Navigation.
2026-03-09T16:09:12.734Z,1773072552.734 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2026-03-09T16:09:12.740Z,1773072552.740 [SBIT](INFO): Initialize SBIT Component.
2026-03-09T16:09:12.741Z,1773072552.741 [SBIT](IMPORTANT): git: 2026-03-03
2026-03-09T16:09:12.741Z,1773072552.741 [SBIT](INFO): git hash: f7a2e94ac3e2178d8a146c43cd471ee86a10fe19
2026-03-09T16:09:12.741Z,1773072552.741 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2026-03-09T16:09:12.742Z,1773072552.742 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2026-03-09T16:09:12.743Z,1773072552.743 [SBIT](INFO): Beginning SBIT in 49.000000 seconds.
2026-03-09T16:09:12.744Z,1773072552.744 [IBIT](INFO): Initialize IBIT Component.
2026-03-09T16:09:12.745Z,1773072552.745 [CBIT](DEBUG): Initialize CBIT Component.
2026-03-09T16:09:12.746Z,1773072552.746 [logger ThreadHandler](INFO): Handler Thread ID is 934
2026-03-09T16:09:12.756Z,1773072552.756 [CBIT](DEBUG): Initialized mux pins.
2026-03-09T16:09:12.756Z,1773072552.756 [CBIT](DEBUG): Initializing the watchdog timer.
2026-03-09T16:09:12.764Z,1773072552.764 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 935
2026-03-09T16:09:12.776Z,1773072552.776 [DAT ThreadHandler](INFO): Handler Thread ID is 936
2026-03-09T16:09:12.777Z,1773072552.777 [DAT](INFO): Powering up
2026-03-09T16:09:12.777Z,1773072552.777 [DAT](DEBUG): Initializing DAT.
2026-03-09T16:09:12.780Z,1773072552.780 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2026-03-09T16:09:12.780Z,1773072552.780 [CBIT](DEBUG): Initializing heartbeat.
2026-03-09T16:09:12.782Z,1773072552.782 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 938
2026-03-09T16:09:12.784Z,1773072552.784 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2026-03-09T16:09:12.793Z,1773072552.793 [Onboard ThreadHandler](INFO): Handler Thread ID is 939
2026-03-09T16:09:12.810Z,1773072552.810 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 940
2026-03-09T16:09:12.829Z,1773072552.829 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 941
2026-03-09T16:09:12.829Z,1773072552.829 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2026-03-09T16:09:12.833Z,1773072552.833 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 943
2026-03-09T16:09:12.836Z,1773072552.836 [WetLabsBB2FL](INFO): Powering up
2026-03-09T16:09:12.837Z,1773072552.837 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 945
2026-03-09T16:09:12.846Z,1773072552.846 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI79M.000
2026-03-09T16:09:12.846Z,1773072552.846 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI79M.000
2026-03-09T16:09:12.846Z,1773072552.846 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QL.000
2026-03-09T16:09:12.846Z,1773072552.846 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QL.000
2026-03-09T16:09:12.846Z,1773072552.846 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QM.000
2026-03-09T16:09:12.846Z,1773072552.846 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QM.000
2026-03-09T16:09:12.847Z,1773072552.847 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2PM.000
2026-03-09T16:09:12.847Z,1773072552.847 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2PM.000
2026-03-09T16:09:12.847Z,1773072552.847 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5MI74M.000
2026-03-09T16:09:12.847Z,1773072552.847 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5MI74M.000
2026-03-09T16:09:12.847Z,1773072552.847 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QN.000
2026-03-09T16:09:12.847Z,1773072552.847 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QN.000
2026-03-09T16:09:12.848Z,1773072552.848 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QO.000
2026-03-09T16:09:12.848Z,1773072552.848 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QO.000
2026-03-09T16:09:12.848Z,1773072552.848 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2RO.000
2026-03-09T16:09:12.848Z,1773072552.848 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2RO.000
2026-03-09T16:09:12.848Z,1773072552.848 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2RN.000
2026-03-09T16:09:12.849Z,1773072552.849 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2RN.000
2026-03-09T16:09:12.849Z,1773072552.849 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QP.000
2026-03-09T16:09:12.849Z,1773072552.849 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QP.000
2026-03-09T16:09:12.852Z,1773072552.852 [CBIT](DEBUG): Deactivating emergency mode.
2026-03-09T16:09:12.892Z,1773072552.892 [CBIT](DEBUG): Backplane powered.
2026-03-09T16:09:12.892Z,1773072552.892 [GFScanner](DEBUG): Initializing GFScanner
2026-03-09T16:09:12.892Z,1773072552.892 [GFScanner](DEBUG): Deactivating GF circuits.
2026-03-09T16:09:12.897Z,1773072552.897 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2026-03-09T16:09:12.898Z,1773072552.898 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2026-03-09T16:09:12.898Z,1773072552.898 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2026-03-09T16:09:12.908Z,1773072552.908 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2026-03-09T16:09:12.959Z,1773072552.959 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="Startup">
    <Guidance:GoToSurface>
        <Progression />
    </Guidance:GoToSurface>
    <Aggregate Id="StartupSatComms">
        <Sequence />
        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix />
        </ReadDatum>
        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications />
        </ReadDatum>
    </Aggregate>
</Mission>

2026-03-09T16:09:12.979Z,1773072552.979 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2026-03-09T16:09:13.022Z,1773072553.022 [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-03-09T16:09:13.022Z,1773072553.022 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2026-03-09T16:09:13.023Z,1773072553.023 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2026-03-09T16:09:13.025Z,1773072553.025 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2026-03-09T16:09:13.216Z,1773072553.216 [Radio_Surface](INFO): Powering up
2026-03-09T16:09:13.324Z,1773072553.324 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="Default">
    <DefineArg Name="ElapsedSinceDefaultStarted">
        <Units:minute />
        <Value>0</Value>
    </DefineArg>
    <Guidance:Wait>
        <Sequence />
        <Setting>
            <Guidance:Wait.duration />
            <Units:second />
            <Value>13</Value>
        </Setting>
    </Guidance:Wait>
    <Guidance:GoToSurface>
        <Progression />
    </Guidance:GoToSurface>
    <Aggregate Id="CheckIn">
        <Sequence Repeat="288" />
        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M" />
            <Universal:time_fix />
        </ReadDatum>
        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P4H">
                <Guidance:Execute>
                    <Sequence />
                    <Setting>
                        <Guidance:Execute.command />
                        <String>Burn on</String>
                    </Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications />
        </ReadDatum>
        <Guidance:Wait>
            <Sequence />
            <Setting>
                <Guidance:Wait.duration />
                <Units:minute />
                <Value>5</Value>
            </Setting>
        </Guidance:Wait>
        <Assign>
            <Sequence />
            <Arg Name="ElapsedSinceDefaultStarted" />
            <Elapsed>
                <Universal:mission_started />
            </Elapsed>
        </Assign>
        <Syslog Severity="Important">
            Default mission has been running for 
            <Arg Name="ElapsedSinceDefaultStarted" />
            <Units:minute />
        </Syslog>
    </Aggregate>
    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>
    <Guidance:Execute>
        <Sequence />
        <Setting>
            <Guidance:Execute.command />
            <String>restart logs</String>
        </Setting>
    </Guidance:Execute>
</Mission>

2026-03-09T16:09:13.330Z,1773072553.330 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2026-03-09T16:09:13.331Z,1773072553.331 [Default:A.Wait](DEBUG): Construct Wait.
2026-03-09T16:09:13.350Z,1773072553.350 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2026-03-09T16:09:13.391Z,1773072553.391 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2026-03-09T16:09:13.406Z,1773072553.406 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2026-03-09T16:09:13.419Z,1773072553.419 [Default:E.Execute](DEBUG): Construct Execute.
2026-03-09T16:09:13.422Z,1773072553.422 [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-03-09T16:09:13.428Z,1773072553.428 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,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-03-09T16:09:13.447Z,1773072553.447 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2026-03-09T16:09:13.608Z,1773072553.608 [Depth_Keller](INFO): Initializing.
2026-03-09T16:09:13.610Z,1773072553.610 [Power24vConverter](INFO): Powering up.
2026-03-09T16:09:13.611Z,1773072553.611 [Sonardyne_Nano](INFO): Initializing.
2026-03-09T16:09:13.657Z,1773072553.657 [DepthRateCalculator](ERROR): Depth measurement is not active
2026-03-09T16:09:13.691Z,1773072553.691 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2026-03-09T16:09:13.696Z,1773072553.696 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2026-03-09T16:09:13.697Z,1773072553.697 [ElevatorServo](DEBUG): Initializing EZServoServo.
2026-03-09T16:09:13.704Z,1773072553.704 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2026-03-09T16:09:13.705Z,1773072553.705 [MassServo](DEBUG): Initializing EZServoServo.
2026-03-09T16:09:13.712Z,1773072553.712 [MassServo](DEBUG): Initializing MassServo.
2026-03-09T16:09:13.713Z,1773072553.713 [RudderServo](DEBUG): Initializing EZServoServo.
2026-03-09T16:09:13.720Z,1773072553.720 [RudderServo](DEBUG): Initializing RudderServo.
2026-03-09T16:09:13.721Z,1773072553.721 [ThrusterHE](DEBUG): Initializing EZServoServo.
2026-03-09T16:09:13.728Z,1773072553.728 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2026-03-09T16:09:15.448Z,1773072555.448 [WetLabsBB2FL](INFO): Powering down
2026-03-09T16:09:15.863Z,1773072555.863 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2026-03-09T16:09:17.101Z,1773072557.101 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107
2026-03-09T16:09:19.341Z,1773072559.341 [ThrusterHE](ERROR): Zero Speed Commanded.
2026-03-09T16:09:24.621Z,1773072564.621 [DAT](INFO): DAT read: 

2026-03-09T16:09:24.622Z,1773072564.622 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2026-03-09T16:09:26.385Z,1773072566.385 [DAT](INFO): DAT read: MF Frequency Band 5120 bandwidth

2026-03-09T16:09:26.386Z,1773072566.386 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.19.1

2026-03-09T16:09:26.387Z,1773072566.387 [DAT](INFO): DAT read: Mar  9 2026 16:09:17 

2026-03-09T16:09:27.141Z,1773072567.141 [DAT](INFO): DAT read: Features enabled [Bearing]

2026-03-09T16:09:27.142Z,1773072567.142 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2026-03-09T16:09:27.143Z,1773072567.143 [DAT](INFO): commRate: 800
2026-03-09T16:09:29.208Z,1773072569.208 [DAT](INFO): entering command mode
2026-03-09T16:09:29.408Z,1773072569.408 [DAT](INFO): DAT read: 

2026-03-09T16:09:29.409Z,1773072569.409 [DAT](INFO): DAT read: user:1>

2026-03-09T16:09:29.409Z,1773072569.409 [DAT](INFO): setting verbose to 3
2026-03-09T16:09:29.661Z,1773072569.661 [DAT](INFO): DAT read: user:1>

2026-03-09T16:09:29.662Z,1773072569.662 [DAT](INFO): DAT read: Verbose         | 3           

2026-03-09T16:09:29.662Z,1773072569.662 [DAT](INFO): set verbose to 3
2026-03-09T16:09:29.662Z,1773072569.662 [DAT](INFO): setting DatVerbose to 27440
2026-03-09T16:09:29.913Z,1773072569.913 [DAT](INFO): DAT read: user:2>

2026-03-09T16:09:29.914Z,1773072569.914 [DAT](INFO): DAT read: DatVerbose      | 27440       

2026-03-09T16:09:29.914Z,1773072569.914 [DAT](INFO): set DatVerbose to 27440
2026-03-09T16:09:29.914Z,1773072569.914 [DAT](INFO): setting transmit power to 8
2026-03-09T16:09:30.165Z,1773072570.165 [DAT](INFO): DAT read: user:3>

2026-03-09T16:09:30.166Z,1773072570.166 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2026-03-09T16:09:30.166Z,1773072570.166 [DAT](INFO): set transmit power to 8
2026-03-09T16:09:30.166Z,1773072570.166 [DAT](INFO): setting local address to 12
2026-03-09T16:09:30.416Z,1773072570.416 [DAT](INFO): DAT read: user:4>

2026-03-09T16:09:30.417Z,1773072570.417 [DAT](INFO): DAT read: LocalAddr       | 12          

2026-03-09T16:09:30.418Z,1773072570.418 [DAT](INFO): set local address to 12
2026-03-09T16:09:30.419Z,1773072570.419 [DAT](INFO): Setting time to: 16:9:30 And date to:3/9/2026
2026-03-09T16:09:30.669Z,1773072570.669 [DAT](INFO): DAT read: user:5>

2026-03-09T16:09:30.670Z,1773072570.670 [DAT](INFO): DAT read: Mon Mar 9, 2026  16:09:30  

2026-03-09T16:09:30.670Z,1773072570.670 [DAT](INFO): Local DAT time set to Mon Mar 9, 2026  16:09:30  

2026-03-09T16:09:41.326Z,1773072581.326 [NAL9602](INFO): Powering up NAL9602
2026-03-09T16:09:52.234Z,1773072592.234 [NAL9602](INFO): NAL9602 initialized
2026-03-09T16:10:02.346Z,1773072602.346 [SBIT](IMPORTANT): Beginning Startup BIT
2026-03-09T16:10:02.351Z,1773072602.351 [GFScanner](IMPORTANT): Beginning ground fault scan
2026-03-09T16:10:06.873Z,1773072606.873 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002326
CHAN A1 (24V): -0.008207
CHAN A2 (12V): -0.005243
CHAN A3 (5V): -0.002336
CHAN B0 (3.3V): -0.001360
CHAN B1 (3.15aV): -0.001005
CHAN B2 (3.15bV): -0.000544
CHAN B3 (GND): 0.000333
OPEN: 0.004756
Full Scale: +/- 1 mA
2026-03-09T16:10:56.494Z,1773072656.494 [SBIT](IMPORTANT): SBIT PASSED
2026-03-09T16:10:56.495Z,1773072656.495 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2026-03-09T16:10:56.495Z,1773072656.495 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=15 count;

2026-03-09T16:10:56.495Z,1773072656.495 [SBIT](IMPORTANT): Express linearApproximation DAT.acoustic_contact_range 15.000000 meter;

2026-03-09T16:10:56.496Z,1773072656.496 [SBIT](IMPORTANT): Express linearApproximation acoustic_contact_range 15.000000 meter;

2026-03-09T16:10:56.496Z,1773072656.496 [SBIT](IMPORTANT): PowerOnly.loadAtStartup=1 bool;

2026-03-09T16:10:56.496Z,1773072656.496 [SBIT](IMPORTANT): PowerOnly.simulateHardware=0 bool;

2026-03-09T16:10:56.496Z,1773072656.496 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=170.891507 cubic_centimeter;

2026-03-09T16:10:56.496Z,1773072656.496 [SBIT](IMPORTANT): VerticalControl.massDefault=7.460123 millimeter;

2026-03-09T16:10:56.496Z,1773072656.496 [SBIT](IMPORTANT): Waterlinked.loadAtStartup=0 bool;

2026-03-09T16:10:56.899Z,1773072656.899 [MissionManager](IMPORTANT): Started mission Startup
2026-03-09T16:10:56.899Z,1773072656.899 [Startup] Running Loop=1
2026-03-09T16:10:56.899Z,1773072656.899 [Startup](DEBUG): Aggregate::initialize Startup
2026-03-09T16:10:56.899Z,1773072656.899 [Startup:A.GoToSurface] Running Loop=1
2026-03-09T16:10:56.900Z,1773072656.900 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2026-03-09T16:10:56.900Z,1773072656.900 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2026-03-09T16:10:56.901Z,1773072656.901 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2026-03-09T16:10:56.901Z,1773072656.901 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2026-03-09T16:10:56.901Z,1773072656.901 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2026-03-09T16:10:56.902Z,1773072656.902 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2026-03-09T16:10:56.902Z,1773072656.902 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2026-03-09T16:10:56.903Z,1773072656.903 [Startup:StartupSatComms] Running Loop=1
2026-03-09T16:10:56.903Z,1773072656.903 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2026-03-09T16:10:56.903Z,1773072656.903 [Startup:StartupSatComms:A] Running Loop=1
2026-03-09T16:10:57.302Z,1773072657.302 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2026-03-09T16:11:11.835Z,1773072671.835 [NAL9602](INFO): SBD MO Status=2, MOMSN=12620, MT Status=2, MTMSN=0
2026-03-09T16:11:11.835Z,1773072671.835 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-09T16:11:39.234Z,1773072699.234 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005002
2026-03-09T16:11:36.106Z,1773072696.106 [CommandExec](IMPORTANT): got command strobe off
2026-03-09T16:11:36.106Z,1773072696.106 [CommandExec](IMPORTANT): Deactivating strobe
2026-03-09T16:11:56.915Z,1773072716.915 [Startup:StartupSatComms:A](INFO): Timed out from 2026-03-09T16:10:56.9Z
2026-03-09T16:11:56.915Z,1773072716.915 [Startup:StartupSatComms:A] Stopped
2026-03-09T16:11:56.915Z,1773072716.915 [Startup:StartupSatComms:B] Running Loop=1
2026-03-09T16:11:57.323Z,1773072717.323 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2026-03-09T16:11:58.099Z,1773072718.099 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2026-03-09T16:12:04.228Z,1773072724.228 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20260306T000651/Courier0004.lzma
2026-03-09T16:12:05.230Z,1773072725.230 [DataOverHttps](INFO): Moved sent file to Logs/20260306T000651/Courier0004.lzma.bak
2026-03-09T16:12:05.230Z,1773072725.230 [DataOverHttps](INFO): SBD MOMSN=26703456
2026-03-09T16:12:06.930Z,1773072726.930 [CommandExec](IMPORTANT): got command failComponent
2026-03-09T16:12:06.931Z,1773072726.931 [CommandExec](IMPORTANT): Failed components:
2026-03-09T16:12:06.931Z,1773072726.931 [CommandExec](IMPORTANT): No failed Components.
2026-03-09T16:12:13.042Z,1773072733.042 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-09T16:12:13.086Z,1773072733.086 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2026-03-09T16:12:13.086Z,1773072733.086 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2026-03-09T16:12:13.109Z,1773072733.109 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2026-03-09T16:12:13.503Z,1773072733.503 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2026-03-09T16:12:13.503Z,1773072733.503 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2026-03-09T16:12:20.314Z,1773072740.314 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-09T16:12:20.837Z,1773072740.837 [DataOverHttps](INFO): Sending 211 bytes from file Logs/20260309T160558/Courier0000.lzma
2026-03-09T16:12:21.838Z,1773072741.838 [DataOverHttps](INFO): Moved sent file to Logs/20260309T160558/Courier0000.lzma.bak
2026-03-09T16:12:21.838Z,1773072741.838 [DataOverHttps](INFO): SBD MOMSN=26703460
2026-03-09T16:12:35.667Z,1773072755.667 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-09T16:12:36.533Z,1773072756.533 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20260309T160900/Courier0000.lzma
2026-03-09T16:12:37.535Z,1773072757.535 [DataOverHttps](INFO): Moved sent file to Logs/20260309T160900/Courier0000.lzma.bak
2026-03-09T16:12:37.535Z,1773072757.535 [DataOverHttps](INFO): SBD MOMSN=26703468
2026-03-09T16:12:50.615Z,1773072770.615 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-09T16:12:53.320Z,1773072773.320 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20260306T000651/Express0005.lzma
2026-03-09T16:12:54.322Z,1773072774.322 [DataOverHttps](INFO): Moved sent file to Logs/20260306T000651/Express0005.lzma.bak
2026-03-09T16:12:54.322Z,1773072774.322 [DataOverHttps](INFO): SBD MOMSN=26703471
2026-03-09T16:12:57.097Z,1773072777.097 [Startup:StartupSatComms:B](INFO): Timed out from 2026-03-09T16:11:56.9Z
2026-03-09T16:12:57.097Z,1773072777.097 [Startup:StartupSatComms:B] Stopped
2026-03-09T16:12:57.097Z,1773072777.097 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2026-03-09T16:12:57.097Z,1773072777.097 [Startup:StartupSatComms] Stopped
2026-03-09T16:12:57.098Z,1773072777.098 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2026-03-09T16:12:57.098Z,1773072777.098 [Startup](INFO): Completed Startup
2026-03-09T16:12:57.098Z,1773072777.098 [MissionManager](INFO): Startup is completed.
2026-03-09T16:12:57.099Z,1773072777.099 [MissionManager](INFO): Uninitializing Mission Startup
2026-03-09T16:12:57.099Z,1773072777.099 [Startup] Stopped
2026-03-09T16:12:57.099Z,1773072777.099 [Startup](DEBUG): Aggregate::uninitialize Startup
2026-03-09T16:12:57.099Z,1773072777.099 [Startup:A.GoToSurface] Stopped
2026-03-09T16:12:57.099Z,1773072777.099 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2026-03-09T16:12:57.518Z,1773072777.518 [MissionManager](IMPORTANT): Started mission Default
2026-03-09T16:12:57.519Z,1773072777.519 [Default] Running Loop=1
2026-03-09T16:12:57.519Z,1773072777.519 [Default](DEBUG): Aggregate::initialize Default
2026-03-09T16:12:57.519Z,1773072777.519 [Default:B.GoToSurface] Running Loop=1
2026-03-09T16:12:57.519Z,1773072777.519 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2026-03-09T16:12:57.519Z,1773072777.519 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2026-03-09T16:12:57.519Z,1773072777.519 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2026-03-09T16:12:57.520Z,1773072777.520 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2026-03-09T16:12:57.520Z,1773072777.520 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2026-03-09T16:12:57.525Z,1773072777.525 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2026-03-09T16:12:57.525Z,1773072777.525 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2026-03-09T16:12:57.525Z,1773072777.525 [Default:A.Wait] Running Loop=1
2026-03-09T16:12:57.525Z,1773072777.525 [Default:A.Wait](DEBUG): Initialize Wait Component.
2026-03-09T16:13:05.562Z,1773072785.562 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-09T16:13:10.003Z,1773072790.003 [DataOverHttps](INFO): Sending 755 bytes from file Logs/20260309T160558/Express0001.lzma
2026-03-09T16:13:10.859Z,1773072790.859 [Default:A.Wait](INFO): Done Waiting.
2026-03-09T16:13:10.859Z,1773072790.859 [Default:A.Wait] Stopped
2026-03-09T16:13:10.859Z,1773072790.859 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2026-03-09T16:13:11.002Z,1773072791.002 [DataOverHttps](INFO): Moved sent file to Logs/20260309T160558/Express0001.lzma.bak
2026-03-09T16:13:11.002Z,1773072791.002 [DataOverHttps](INFO): SBD MOMSN=26703473
2026-03-09T16:13:11.271Z,1773072791.271 [Default:CheckIn] Running Loop=1
2026-03-09T16:13:11.271Z,1773072791.271 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-09T16:13:11.271Z,1773072791.271 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-09T16:13:11.639Z,1773072791.639 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2026-03-09T16:13:20.511Z,1773072800.511 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-09T16:13:35.458Z,1773072815.458 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-09T16:13:50.002Z,1773072830.002 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-09T16:14:04.957Z,1773072844.957 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-09T16:14:19.897Z,1773072859.897 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-09T16:14:55.862Z,1773072895.862 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2026-03-09T16:15:14.049Z,1773072914.049 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2026-03-09T16:15:14.049Z,1773072914.049 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2026-03-09T16:15:14.088Z,1773072914.088 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2026-03-09T16:15:14.464Z,1773072914.464 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2026-03-09T16:15:14.464Z,1773072914.464 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2026-03-09T16:16:08.978Z,1773072968.978 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-09T16:16:08.985Z,1773072968.985 [BPC1](INFO): Calculating totals. Valid battery stick count: 46. Valid reserve battery stick count: 4.
2026-03-09T16:16:08.989Z,1773072968.989 [BPC1](INFO): Received data from all battery sticks.
2026-03-09T16:18:11.404Z,1773073091.404 [Default:CheckIn:Read_GPS](INFO): Timed out from 2026-03-09T16:13:11.3Z
2026-03-09T16:18:11.404Z,1773073091.404 [Default:CheckIn:Read_GPS] Stopped
2026-03-09T16:18:11.404Z,1773073091.404 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-09T16:18:11.815Z,1773073091.815 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2026-03-09T16:18:15.052Z,1773073095.052 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2026-03-09T16:18:15.052Z,1773073095.052 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2026-03-09T16:18:15.099Z,1773073095.099 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2026-03-09T16:18:15.497Z,1773073095.497 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2026-03-09T16:18:15.497Z,1773073095.497 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2026-03-09T16:18:18.721Z,1773073098.721 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20260309T160900/Courier0004.lzma
2026-03-09T16:18:19.722Z,1773073099.722 [DataOverHttps](INFO): Moved sent file to Logs/20260309T160900/Courier0004.lzma.bak
2026-03-09T16:18:19.722Z,1773073099.722 [DataOverHttps](INFO): SBD MOMSN=26703492
2026-03-09T16:18:35.302Z,1773073115.302 [DataOverHttps](INFO): Sending 925 bytes from file Logs/20260309T160900/Express0001.lzma
2026-03-09T16:18:36.302Z,1773073116.302 [DataOverHttps](INFO): Moved sent file to Logs/20260309T160900/Express0001.lzma.bak
2026-03-09T16:18:36.302Z,1773073116.302 [DataOverHttps](INFO): SBD MOMSN=26703494
2026-03-09T16:18:51.917Z,1773073131.917 [DataOverHttps](INFO): Sending 238 bytes from file Logs/20260309T160900/Express0005.lzma
2026-03-09T16:18:52.918Z,1773073132.918 [DataOverHttps](INFO): Moved sent file to Logs/20260309T160900/Express0005.lzma.bak
2026-03-09T16:18:52.918Z,1773073132.918 [DataOverHttps](INFO): SBD MOMSN=26703517
2026-03-09T16:18:54.246Z,1773073134.246 [Default:CheckIn:Read_Iridium] Stopped
2026-03-09T16:18:54.246Z,1773073134.246 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-09T16:18:54.247Z,1773073134.247 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-09T16:19:54.418Z,1773073194.418 [NAL9602](FAULT): GPS failed to acquire within timeout.
2026-03-09T16:19:54.418Z,1773073194.418 [NAL9602] Data Fault, FailCount= 1
2026-03-09T16:19:54.418Z,1773073194.418 [NAL9602](ERROR): Data Fault
2026-03-09T16:19:54.435Z,1773073194.435 [CBIT](ERROR): Data Fault in component: NAL9602
2026-03-09T16:19:54.826Z,1773073194.826 [NAL9602](INFO): Powering down
2026-03-09T16:19:55.646Z,1773073195.646 [CBIT](INFO): Clearing failed state for component NAL9602
2026-03-09T16:19:55.646Z,1773073195.646 [NAL9602] No Fault, FailCount= 1
2026-03-09T16:20:25.122Z,1773073225.122 [NAL9602](INFO): Powering up NAL9602
2026-03-09T16:20:36.030Z,1773073236.030 [NAL9602](INFO): NAL9602 initialized
2026-03-09T16:21:16.043Z,1773073276.043 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2026-03-09T16:21:16.043Z,1773073276.043 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2026-03-09T16:21:16.053Z,1773073276.053 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2026-03-09T16:21:16.463Z,1773073276.463 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2026-03-09T16:21:16.463Z,1773073276.463 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2026-03-09T16:21:48.094Z,1773073308.094 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.575645
2026-03-09T16:21:58.401Z,1773073318.401 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003367
2026-03-09T16:23:54.830Z,1773073434.830 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-09T16:23:54.830Z,1773073434.830 [Default:CheckIn:C.Wait] Stopped
2026-03-09T16:23:54.830Z,1773073434.830 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-09T16:23:54.830Z,1773073434.830 [Default:CheckIn:D] Running Loop=1
2026-03-09T16:23:55.230Z,1773073435.230 [Default:CheckIn:D] Stopped
2026-03-09T16:23:55.230Z,1773073435.230 [Default:CheckIn:E] Running Loop=1
2026-03-09T16:23:55.614Z,1773073435.614 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  10.961855 min 
2026-03-09T16:23:55.614Z,1773073435.614 [Default:CheckIn:E] Stopped
2026-03-09T16:23:55.615Z,1773073435.615 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-09T16:23:55.615Z,1773073435.615 [Default:CheckIn] Stopped
2026-03-09T16:23:55.615Z,1773073435.615 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-09T16:23:55.615Z,1773073435.615 [Default:CheckIn](INFO): Running loop #2
2026-03-09T16:23:55.615Z,1773073435.615 [Default:CheckIn] Running Loop=2
2026-03-09T16:23:55.615Z,1773073435.615 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-09T16:23:55.615Z,1773073435.615 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-09T16:24:17.057Z,1773073457.057 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2026-03-09T16:24:17.057Z,1773073457.057 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2026-03-09T16:24:17.095Z,1773073457.095 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2026-03-09T16:24:17.463Z,1773073457.463 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2026-03-09T16:24:17.463Z,1773073457.463 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2026-03-09T16:27:18.017Z,1773073638.017 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2026-03-09T16:27:18.017Z,1773073638.017 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2026-03-09T16:27:18.027Z,1773073638.027 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2026-03-09T16:27:18.432Z,1773073638.432 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2026-03-09T16:27:18.432Z,1773073638.432 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2026-03-09T16:28:50.121Z,1773073730.121 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162848.00,A,3648.16700,N,12147.26808,W,0.505,0.00,090326,,,A*7F
2026-03-09T16:28:50.124Z,1773073730.124 [NAL9602](INFO): GPS fix at 20260309T162848: (36.802783, -121.787801)
2026-03-09T16:28:50.135Z,1773073730.135 [Default:CheckIn:Read_GPS] Stopped
2026-03-09T16:28:50.135Z,1773073730.135 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-09T16:28:57.368Z,1773073737.368 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20260309T160900/Courier0007.lzma
2026-03-09T16:28:58.370Z,1773073738.370 [DataOverHttps](INFO): Moved sent file to Logs/20260309T160900/Courier0007.lzma.bak
2026-03-09T16:28:58.370Z,1773073738.370 [DataOverHttps](INFO): SBD MOMSN=26703545
2026-03-09T16:29:14.065Z,1773073754.065 [DataOverHttps](INFO): Sending 396 bytes from file Logs/20260309T160900/Express0008.lzma
2026-03-09T16:29:15.066Z,1773073755.066 [DataOverHttps](INFO): Moved sent file to Logs/20260309T160900/Express0008.lzma.bak
2026-03-09T16:29:15.066Z,1773073755.066 [DataOverHttps](INFO): SBD MOMSN=26703548
2026-03-09T16:29:16.392Z,1773073756.392 [Default:CheckIn:Read_Iridium] Stopped
2026-03-09T16:29:16.392Z,1773073756.392 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-09T16:29:16.392Z,1773073756.392 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-09T16:29:22.442Z,1773073762.442 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-09T16:31:45.338Z,1773073905.338 [DataOverHttps](IMPORTANT): SBD MTMSN=20260309T163144
2026-03-09T16:31:52.684Z,1773073912.684 [DataOverHttps](INFO): Received command: strobe off
2026-03-09T16:31:52.778Z,1773073912.778 [CommandExec](IMPORTANT): got command strobe off
2026-03-09T16:31:52.778Z,1773073912.778 [CommandExec](IMPORTANT): Deactivating strobe
2026-03-09T16:34:16.977Z,1773074056.977 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-09T16:34:16.977Z,1773074056.977 [Default:CheckIn:C.Wait] Stopped
2026-03-09T16:34:16.977Z,1773074056.977 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-09T16:34:16.977Z,1773074056.977 [Default:CheckIn:D] Running Loop=1
2026-03-09T16:34:17.382Z,1773074057.382 [Default:CheckIn:D] Stopped
2026-03-09T16:34:17.382Z,1773074057.382 [Default:CheckIn:E] Running Loop=1
2026-03-09T16:34:17.799Z,1773074057.799 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  21.331051 min 
2026-03-09T16:34:17.799Z,1773074057.799 [Default:CheckIn:E] Stopped
2026-03-09T16:34:17.799Z,1773074057.799 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-09T16:34:17.799Z,1773074057.799 [Default:CheckIn] Stopped
2026-03-09T16:34:17.799Z,1773074057.799 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-09T16:34:17.799Z,1773074057.799 [Default:CheckIn](INFO): Running loop #3
2026-03-09T16:34:17.799Z,1773074057.799 [Default:CheckIn] Running Loop=3
2026-03-09T16:34:17.800Z,1773074057.800 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-09T16:34:17.800Z,1773074057.800 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-09T16:34:19.796Z,1773074059.796 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163418.00,A,3648.16385,N,12147.28481,W,0.408,298.83,090326,,,A*79
2026-03-09T16:34:19.799Z,1773074059.799 [NAL9602](INFO): GPS fix at 20260309T163418: (36.802731, -121.788080)
2026-03-09T16:34:19.809Z,1773074059.809 [Default:CheckIn:Read_GPS] Stopped
2026-03-09T16:34:19.809Z,1773074059.809 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-09T16:34:27.160Z,1773074067.160 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260309T160900/Courier0010.lzma
2026-03-09T16:34:28.162Z,1773074068.162 [DataOverHttps](INFO): Moved sent file to Logs/20260309T160900/Courier0010.lzma.bak
2026-03-09T16:34:28.162Z,1773074068.162 [DataOverHttps](INFO): SBD MOMSN=26703569
2026-03-09T16:34:44.904Z,1773074084.904 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20260309T160900/Courier0013.lzma
2026-03-09T16:34:45.906Z,1773074085.906 [DataOverHttps](INFO): Moved sent file to Logs/20260309T160900/Courier0013.lzma.bak
2026-03-09T16:34:45.906Z,1773074085.906 [DataOverHttps](INFO): SBD MOMSN=26703572
2026-03-09T16:35:01.928Z,1773074101.928 [DataOverHttps](INFO): Sending 265 bytes from file Logs/20260309T160900/Express0011.lzma
2026-03-09T16:35:02.930Z,1773074102.930 [DataOverHttps](INFO): Moved sent file to Logs/20260309T160900/Express0011.lzma.bak
2026-03-09T16:35:02.931Z,1773074102.931 [DataOverHttps](INFO): SBD MOMSN=26703574
2026-03-09T16:35:19.616Z,1773074119.616 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 170 cubic_centimeter
2026-03-09T16:35:19.644Z,1773074119.644 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread.
2026-03-09T16:35:19.652Z,1773074119.652 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmPublisher,Reporter,LogSplitter,
2026-03-09T16:35:20.468Z,1773074120.468 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20260309T160900/Express0014.lzma
2026-03-09T16:35:21.470Z,1773074121.470 [DataOverHttps](INFO): Moved sent file to Logs/20260309T160900/Express0014.lzma.bak
2026-03-09T16:35:21.470Z,1773074121.470 [DataOverHttps](INFO): SBD MOMSN=26703580
2026-03-09T16:35:22.829Z,1773074122.829 [Default:CheckIn:Read_Iridium] Stopped
2026-03-09T16:35:22.829Z,1773074122.829 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-09T16:35:22.829Z,1773074122.829 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-09T16:35:30.942Z,1773074130.942 [DAT](INFO): DAT read: user:6>Rx Time:16:35:33.4487

2026-03-09T16:35:30.943Z,1773074130.943 [DAT](INFO): Rx dataTimestamp_ set to:1773074130.942341
2026-03-09T16:35:31.446Z,1773074131.446 [DAT](INFO): DAT read: $Error in header

2026-03-09T16:35:31.446Z,1773074131.446 [DAT](INFO): Received a bad header
2026-03-09T16:38:26.968Z,1773074306.968 [CommandExec](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 90 cubic_centimeter
2026-03-09T16:39:22.391Z,1773074362.391 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2026-03-09T16:39:53.095Z,1773074393.095 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-09T16:40:17.586Z,1773074417.586 [CommandExec](IMPORTANT): got command maintain clear
2026-03-09T16:40:17.768Z,1773074417.768 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,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-03-09T16:40:23.404Z,1773074423.404 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-09T16:40:23.404Z,1773074423.404 [Default:CheckIn:C.Wait] Stopped
2026-03-09T16:40:23.404Z,1773074423.404 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-09T16:40:23.405Z,1773074423.405 [Default:CheckIn:D] Running Loop=1
2026-03-09T16:40:23.810Z,1773074423.810 [Default:CheckIn:D] Stopped
2026-03-09T16:40:23.811Z,1773074423.811 [Default:CheckIn:E] Running Loop=1
2026-03-09T16:40:24.229Z,1773074424.229 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  27.438196 min 
2026-03-09T16:40:24.229Z,1773074424.229 [Default:CheckIn:E] Stopped
2026-03-09T16:40:24.230Z,1773074424.230 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-09T16:40:24.230Z,1773074424.230 [Default:CheckIn] Stopped
2026-03-09T16:40:24.230Z,1773074424.230 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-09T16:40:24.230Z,1773074424.230 [Default:CheckIn](INFO): Running loop #4
2026-03-09T16:40:24.230Z,1773074424.230 [Default:CheckIn] Running Loop=4
2026-03-09T16:40:24.230Z,1773074424.230 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-09T16:40:24.230Z,1773074424.230 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-09T16:40:26.229Z,1773074426.229 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164024.00,A,3648.16534,N,12147.28525,W,0.350,298.83,090326,,,A*7C
2026-03-09T16:40:26.231Z,1773074426.231 [NAL9602](INFO): GPS fix at 20260309T164024: (36.802756, -121.788088)
2026-03-09T16:40:26.241Z,1773074426.241 [Default:CheckIn:Read_GPS] Stopped
2026-03-09T16:40:26.241Z,1773074426.241 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-09T16:40:34.444Z,1773074434.444 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20260309T160900/Courier0016.lzma
2026-03-09T16:40:35.446Z,1773074435.446 [DataOverHttps](INFO): Moved sent file to Logs/20260309T160900/Courier0016.lzma.bak
2026-03-09T16:40:35.446Z,1773074435.446 [DataOverHttps](INFO): SBD MOMSN=26703630
2026-03-09T16:40:51.480Z,1773074451.480 [DataOverHttps](INFO): Sending 315 bytes from file Logs/20260309T160900/Express0017.lzma
2026-03-09T16:40:52.037Z,1773074452.037 [CommandExec](IMPORTANT): got command failComponent
2026-03-09T16:40:52.037Z,1773074452.037 [CommandExec](IMPORTANT): Failed components:
2026-03-09T16:40:52.038Z,1773074452.038 [CommandExec](IMPORTANT): No failed Components.
2026-03-09T16:40:52.484Z,1773074452.484 [DataOverHttps](INFO): Moved sent file to Logs/20260309T160900/Express0017.lzma.bak
2026-03-09T16:40:52.484Z,1773074452.484 [DataOverHttps](INFO): SBD MOMSN=26703633
2026-03-09T16:40:53.720Z,1773074453.720 [Default:CheckIn:Read_Iridium] Stopped
2026-03-09T16:40:53.720Z,1773074453.720 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-09T16:40:53.720Z,1773074453.720 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-09T16:40:56.926Z,1773074456.926 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2026-03-09T16:40:57.000Z,1773074457.000 [NAL9602](ERROR): received: 
+CSQ:0

OK620, 2, 0, 0, 0

OK
2026-03-09T16:41:16.319Z,1773074476.319 [NAL9602](INFO): SBD MO Status=2, MOMSN=12620, MT Status=2, MTMSN=0
2026-03-09T16:41:16.319Z,1773074476.319 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-09T16:43:51.459Z,1773074631.459 [NAL9602](INFO): SBD MO Status=2, MOMSN=12620, MT Status=2, MTMSN=0
2026-03-09T16:43:51.459Z,1773074631.459 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-09T16:43:57.919Z,1773074637.919 [NAL9602](INFO): SBD MO Status=0, MOMSN=12620, MT Status=0, MTMSN=0
2026-03-09T16:43:57.919Z,1773074637.919 [NAL9602](INFO): No messages in MT queue
2026-03-09T16:44:28.629Z,1773074668.629 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-09T16:45:54.279Z,1773074754.279 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-09T16:45:54.279Z,1773074754.279 [Default:CheckIn:C.Wait] Stopped
2026-03-09T16:45:54.279Z,1773074754.279 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-09T16:45:54.279Z,1773074754.279 [Default:CheckIn:D] Running Loop=1
2026-03-09T16:45:54.684Z,1773074754.684 [Default:CheckIn:D] Stopped
2026-03-09T16:45:54.684Z,1773074754.684 [Default:CheckIn:E] Running Loop=1
2026-03-09T16:45:55.091Z,1773074755.091 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  32.952755 min 
2026-03-09T16:45:55.091Z,1773074755.091 [Default:CheckIn:E] Stopped
2026-03-09T16:45:55.091Z,1773074755.091 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-09T16:45:55.091Z,1773074755.091 [Default:CheckIn] Stopped
2026-03-09T16:45:55.091Z,1773074755.091 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-09T16:45:55.091Z,1773074755.091 [Default:CheckIn](INFO): Running loop #5
2026-03-09T16:45:55.091Z,1773074755.091 [Default:CheckIn] Running Loop=5
2026-03-09T16:45:55.091Z,1773074755.091 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-09T16:45:55.092Z,1773074755.092 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-09T16:45:57.099Z,1773074757.099 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164555.00,A,3648.17423,N,12147.27650,W,0.039,0.00,090326,,,A*73
2026-03-09T16:45:57.111Z,1773074757.111 [NAL9602](INFO): GPS fix at 20260309T164555: (36.802904, -121.787942)
2026-03-09T16:45:57.122Z,1773074757.122 [Default:CheckIn:Read_GPS] Stopped
2026-03-09T16:45:57.122Z,1773074757.122 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-09T16:46:04.436Z,1773074764.436 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260309T160900/Courier0019.lzma
2026-03-09T16:46:05.438Z,1773074765.438 [DataOverHttps](INFO): Moved sent file to Logs/20260309T160900/Courier0019.lzma.bak
2026-03-09T16:46:05.439Z,1773074765.439 [DataOverHttps](IMPORTANT): SBD MOMSN=26703655, MTMSN=20260309T164604
2026-03-09T16:46:12.817Z,1773074772.817 [DataOverHttps](INFO): Received command: ibit
2026-03-09T16:46:12.897Z,1773074772.897 [CommandExec](IMPORTANT): got command ibit
2026-03-09T16:46:13.282Z,1773074773.282 [IBIT](IMPORTANT): Beginning Initiated BIT
2026-03-09T16:46:13.282Z,1773074773.282 [IBIT](IMPORTANT): Beginning control surface checks.
2026-03-09T16:46:13.286Z,1773074773.286 [GFScanner](IMPORTANT): Beginning ground fault scan
2026-03-09T16:46:17.820Z,1773074777.820 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.007488
CHAN A1 (24V): -0.008019
CHAN A2 (12V): -0.005435
CHAN A3 (5V): -0.002996
CHAN B0 (3.3V): -0.001299
CHAN B1 (3.15aV): -0.001043
CHAN B2 (3.15bV): -0.001403
CHAN B3 (GND): -0.000375
OPEN: 0.004406
Full Scale: +/- 1 mA
2026-03-09T16:46:21.405Z,1773074781.405 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20260309T160900/Express0020.lzma
2026-03-09T16:46:22.406Z,1773074782.406 [DataOverHttps](INFO): Moved sent file to Logs/20260309T160900/Express0020.lzma.bak
2026-03-09T16:46:22.407Z,1773074782.407 [DataOverHttps](INFO): SBD MOMSN=26703659
2026-03-09T16:46:39.564Z,1773074799.564 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20260309T160900/Courier0022.lzma
2026-03-09T16:46:40.566Z,1773074800.566 [DataOverHttps](INFO): Moved sent file to Logs/20260309T160900/Courier0022.lzma.bak
2026-03-09T16:46:40.566Z,1773074800.566 [DataOverHttps](INFO): SBD MOMSN=26703665
2026-03-09T16:46:56.420Z,1773074816.420 [DataOverHttps](INFO): Sending 238 bytes from file Logs/20260309T160900/Express0023.lzma
2026-03-09T16:46:57.422Z,1773074817.422 [DataOverHttps](INFO): Moved sent file to Logs/20260309T160900/Express0023.lzma.bak
2026-03-09T16:46:57.422Z,1773074817.422 [DataOverHttps](INFO): SBD MOMSN=26703667
2026-03-09T16:47:37.395Z,1773074857.395 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 1
Latitude: 36.802902 Longitude: -121.787941
2026-03-09T16:47:37.773Z,1773074857.773 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.650999
2026-03-09T16:47:37.773Z,1773074857.773 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2026-03-09T16:47:37.774Z,1773074857.774 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2026-03-09T16:47:38.153Z,1773074858.153 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2026-03-09T16:47:38.154Z,1773074858.154 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2026-03-09T16:47:38.154Z,1773074858.154 [IBIT](IMPORTANT): Pressure:9.913858 PSI
2026-03-09T16:47:38.154Z,1773074858.154 [IBIT](IMPORTANT): Humidity:20.986448 %
2026-03-09T16:47:38.561Z,1773074858.561 [IBIT](IMPORTANT): Vehicle Pitch:-1.520257 degrees
2026-03-09T16:47:38.561Z,1773074858.561 [IBIT](IMPORTANT): Vehicle  Roll:1.061583 degrees
2026-03-09T16:47:38.561Z,1773074858.561 [IBIT](IMPORTANT): Vehicle  Heading:28.593012 degrees
2026-03-09T16:47:38.967Z,1773074858.967 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2026-03-09T16:47:38.967Z,1773074858.967 [IBIT](IMPORTANT): buoyancyNeutral: 170.891510 cc
2026-03-09T16:47:38.967Z,1773074858.967 [IBIT](IMPORTANT): massDefault: 0.746012 cm
2026-03-09T16:47:38.968Z,1773074858.968 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2026-03-09T16:47:38.968Z,1773074858.968 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2026-03-09T16:47:38.968Z,1773074858.968 [IBIT](IMPORTANT): IBIT PASSED
2026-03-09T16:47:39.362Z,1773074859.362 [Default:CheckIn:Read_Iridium] Stopped
2026-03-09T16:47:39.362Z,1773074859.362 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-09T16:47:39.362Z,1773074859.362 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-09T16:48:10.464Z,1773074890.464 [NAL9602](INFO): SBD MO Status=0, MOMSN=12621, MT Status=0, MTMSN=0
2026-03-09T16:48:10.464Z,1773074890.464 [NAL9602](INFO): No messages in MT queue
2026-03-09T16:48:41.162Z,1773074921.162 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-09T16:52:39.943Z,1773075159.943 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-09T16:52:39.943Z,1773075159.943 [Default:CheckIn:C.Wait] Stopped
2026-03-09T16:52:39.943Z,1773075159.943 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-09T16:52:39.943Z,1773075159.943 [Default:CheckIn:D] Running Loop=1
2026-03-09T16:52:40.340Z,1773075160.340 [Default:CheckIn:D] Stopped
2026-03-09T16:52:40.340Z,1773075160.340 [Default:CheckIn:E] Running Loop=1
2026-03-09T16:52:40.743Z,1773075160.743 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  39.713688 min 
2026-03-09T16:52:40.743Z,1773075160.743 [Default:CheckIn:E] Stopped
2026-03-09T16:52:40.744Z,1773075160.744 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-09T16:52:40.744Z,1773075160.744 [Default:CheckIn] Stopped
2026-03-09T16:52:40.744Z,1773075160.744 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-09T16:52:40.744Z,1773075160.744 [Default:CheckIn](INFO): Running loop #6
2026-03-09T16:52:40.744Z,1773075160.744 [Default:CheckIn] Running Loop=6
2026-03-09T16:52:40.744Z,1773075160.744 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-09T16:52:40.744Z,1773075160.744 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-09T16:52:42.758Z,1773075162.758 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165242.00,A,3648.17338,N,12147.27879,W,0.330,0.00,090326,,,A*71
2026-03-09T16:52:42.760Z,1773075162.760 [NAL9602](INFO): GPS fix at 20260309T165242: (36.802890, -121.787980)
2026-03-09T16:52:42.770Z,1773075162.770 [Default:CheckIn:Read_GPS] Stopped
2026-03-09T16:52:42.770Z,1773075162.770 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-09T16:52:50.352Z,1773075170.352 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260309T160900/Courier0025.lzma
2026-03-09T16:52:51.354Z,1773075171.354 [DataOverHttps](INFO): Moved sent file to Logs/20260309T160900/Courier0025.lzma.bak
2026-03-09T16:52:51.354Z,1773075171.354 [DataOverHttps](INFO): SBD MOMSN=26703688
2026-03-09T16:52:55.283Z,1773075175.283 [NAL9602](INFO): SBD MO Status=0, MOMSN=12622, MT Status=0, MTMSN=0
2026-03-09T16:52:55.283Z,1773075175.283 [NAL9602](INFO): No messages in MT queue
2026-03-09T16:53:07.135Z,1773075187.135 [DataOverHttps](INFO): Sending 690 bytes from file Logs/20260309T160900/Express0026.lzma
2026-03-09T16:53:08.134Z,1773075188.134 [DataOverHttps](INFO): Moved sent file to Logs/20260309T160900/Express0026.lzma.bak
2026-03-09T16:53:08.134Z,1773075188.134 [DataOverHttps](INFO): SBD MOMSN=26703691
2026-03-09T16:53:09.427Z,1773075189.427 [Default:CheckIn:Read_Iridium] Stopped
2026-03-09T16:53:09.427Z,1773075189.427 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-09T16:53:09.427Z,1773075189.427 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-09T16:53:25.982Z,1773075205.982 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-09T16:58:10.011Z,1773075490.011 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-09T16:58:10.011Z,1773075490.011 [Default:CheckIn:C.Wait] Stopped
2026-03-09T16:58:10.011Z,1773075490.011 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-09T16:58:10.011Z,1773075490.011 [Default:CheckIn:D] Running Loop=1
2026-03-09T16:58:10.411Z,1773075490.411 [Default:CheckIn:D] Stopped
2026-03-09T16:58:10.411Z,1773075490.411 [Default:CheckIn:E] Running Loop=1
2026-03-09T16:58:10.813Z,1773075490.813 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  45.214872 min 
2026-03-09T16:58:10.813Z,1773075490.813 [Default:CheckIn:E] Stopped
2026-03-09T16:58:10.814Z,1773075490.814 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-09T16:58:10.814Z,1773075490.814 [Default:CheckIn] Stopped
2026-03-09T16:58:10.814Z,1773075490.814 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-09T16:58:10.814Z,1773075490.814 [Default:CheckIn](INFO): Running loop #7
2026-03-09T16:58:10.814Z,1773075490.814 [Default:CheckIn] Running Loop=7
2026-03-09T16:58:10.814Z,1773075490.814 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-09T16:58:10.814Z,1773075490.814 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-09T16:58:12.822Z,1773075492.822 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165812.00,A,3648.15063,N,12147.22498,W,0.058,36.37,090326,,,A*4B
2026-03-09T16:58:12.835Z,1773075492.835 [NAL9602](INFO): GPS fix at 20260309T165812: (36.802510, -121.787083)
2026-03-09T16:58:12.846Z,1773075492.846 [Default:CheckIn:Read_GPS] Stopped
2026-03-09T16:58:12.847Z,1773075492.847 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-09T16:58:20.228Z,1773075500.228 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20260309T160900/Courier0028.lzma
2026-03-09T16:58:21.230Z,1773075501.230 [DataOverHttps](INFO): Moved sent file to Logs/20260309T160900/Courier0028.lzma.bak
2026-03-09T16:58:21.230Z,1773075501.230 [DataOverHttps](INFO): SBD MOMSN=26703720
2026-03-09T16:58:35.043Z,1773075515.043 [NAL9602](INFO): SBD MO Status=2, MOMSN=12623, MT Status=2, MTMSN=0
2026-03-09T16:58:35.043Z,1773075515.043 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-09T16:58:37.168Z,1773075517.168 [DataOverHttps](INFO): Sending 166 bytes from file Logs/20260309T160900/Express0029.lzma
2026-03-09T16:58:38.170Z,1773075518.170 [DataOverHttps](INFO): Moved sent file to Logs/20260309T160900/Express0029.lzma.bak
2026-03-09T16:58:38.171Z,1773075518.171 [DataOverHttps](INFO): SBD MOMSN=26703723
2026-03-09T16:58:39.510Z,1773075519.510 [Default:CheckIn:Read_Iridium] Stopped
2026-03-09T16:58:39.510Z,1773075519.510 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-09T16:58:39.510Z,1773075519.510 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-09T16:59:06.554Z,1773075546.554 [NAL9602](INFO): SBD MO Status=0, MOMSN=12623, MT Status=0, MTMSN=0
2026-03-09T16:59:06.555Z,1773075546.555 [NAL9602](INFO): No messages in MT queue
2026-03-09T16:59:37.266Z,1773075577.266 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-09T17:03:40.099Z,1773075820.099 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-09T17:03:40.099Z,1773075820.099 [Default:CheckIn:C.Wait] Stopped
2026-03-09T17:03:40.099Z,1773075820.099 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-09T17:03:40.099Z,1773075820.099 [Default:CheckIn:D] Running Loop=1
2026-03-09T17:03:40.510Z,1773075820.510 [Default:CheckIn:D] Stopped
2026-03-09T17:03:40.510Z,1773075820.510 [Default:CheckIn:E] Running Loop=1
2026-03-09T17:03:40.904Z,1773075820.904 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  50.716520 min 
2026-03-09T17:03:40.904Z,1773075820.904 [Default:CheckIn:E] Stopped
2026-03-09T17:03:40.904Z,1773075820.904 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-09T17:03:40.904Z,1773075820.904 [Default:CheckIn] Stopped
2026-03-09T17:03:40.904Z,1773075820.904 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-09T17:03:40.905Z,1773075820.905 [Default:CheckIn](INFO): Running loop #8
2026-03-09T17:03:40.905Z,1773075820.905 [Default:CheckIn] Running Loop=8
2026-03-09T17:03:40.905Z,1773075820.905 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-09T17:03:40.905Z,1773075820.905 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-09T17:03:42.920Z,1773075822.920 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170342.00,A,3648.15215,N,12147.22689,W,0.039,36.37,090326,,,D*42
2026-03-09T17:03:42.923Z,1773075822.923 [NAL9602](INFO): GPS fix at 20260309T170342: (36.802536, -121.787115)
2026-03-09T17:03:42.937Z,1773075822.937 [Default:CheckIn:Read_GPS] Stopped
2026-03-09T17:03:42.938Z,1773075822.938 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-09T17:03:50.720Z,1773075830.720 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260309T160900/Courier0031.lzma
2026-03-09T17:03:51.722Z,1773075831.722 [DataOverHttps](INFO): Moved sent file to Logs/20260309T160900/Courier0031.lzma.bak
2026-03-09T17:03:51.722Z,1773075831.722 [DataOverHttps](INFO): SBD MOMSN=26703741
2026-03-09T17:03:56.659Z,1773075836.659 [NAL9602](INFO): SBD MO Status=0, MOMSN=12624, MT Status=0, MTMSN=0
2026-03-09T17:03:56.659Z,1773075836.659 [NAL9602](INFO): No messages in MT queue
2026-03-09T17:04:07.484Z,1773075847.484 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20260309T160900/Express0032.lzma
2026-03-09T17:04:08.486Z,1773075848.486 [DataOverHttps](INFO): Moved sent file to Logs/20260309T160900/Express0032.lzma.bak
2026-03-09T17:04:08.487Z,1773075848.487 [DataOverHttps](INFO): SBD MOMSN=26703744
2026-03-09T17:04:09.599Z,1773075849.599 [Default:CheckIn:Read_Iridium] Stopped
2026-03-09T17:04:09.599Z,1773075849.599 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-09T17:04:09.599Z,1773075849.599 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-09T17:04:27.371Z,1773075867.371 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-09T17:09:10.165Z,1773076150.165 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-09T17:09:10.165Z,1773076150.165 [Default:CheckIn:C.Wait] Stopped
2026-03-09T17:09:10.165Z,1773076150.165 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-09T17:09:10.165Z,1773076150.165 [Default:CheckIn:D] Running Loop=1
2026-03-09T17:09:10.575Z,1773076150.575 [Default:CheckIn:D] Stopped
2026-03-09T17:09:10.575Z,1773076150.575 [Default:CheckIn:E] Running Loop=1
2026-03-09T17:09:10.971Z,1773076150.971 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  56.217607 min 
2026-03-09T17:09:10.971Z,1773076150.971 [Default:CheckIn:E] Stopped
2026-03-09T17:09:10.971Z,1773076150.971 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-09T17:09:10.971Z,1773076150.971 [Default:CheckIn] Stopped
2026-03-09T17:09:10.971Z,1773076150.971 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-09T17:09:10.971Z,1773076150.971 [Default:CheckIn](INFO): Running loop #9
2026-03-09T17:09:10.972Z,1773076150.972 [Default:CheckIn] Running Loop=9
2026-03-09T17:09:10.972Z,1773076150.972 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-09T17:09:10.972Z,1773076150.972 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-09T17:09:12.983Z,1773076152.983 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170912.00,A,3648.15304,N,12147.22433,W,0.097,16.31,090326,,,D*4F
2026-03-09T17:09:12.986Z,1773076152.986 [NAL9602](INFO): GPS fix at 20260309T170912: (36.802551, -121.787072)
2026-03-09T17:09:12.996Z,1773076152.996 [Default:CheckIn:Read_GPS] Stopped
2026-03-09T17:09:12.996Z,1773076152.996 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-09T17:09:21.052Z,1773076161.052 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20260309T160900/Courier0034.lzma
2026-03-09T17:09:22.054Z,1773076162.054 [DataOverHttps](INFO): Moved sent file to Logs/20260309T160900/Courier0034.lzma.bak
2026-03-09T17:09:22.054Z,1773076162.054 [DataOverHttps](INFO): SBD MOMSN=26703755
2026-03-09T17:09:26.319Z,1773076166.319 [NAL9602](INFO): SBD MO Status=0, MOMSN=12625, MT Status=0, MTMSN=0
2026-03-09T17:09:26.319Z,1773076166.319 [NAL9602](INFO): No messages in MT queue
2026-03-09T17:09:37.804Z,1773076177.804 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20260309T160900/Express0035.lzma
2026-03-09T17:09:38.806Z,1773076178.806 [DataOverHttps](INFO): Moved sent file to Logs/20260309T160900/Express0035.lzma.bak
2026-03-09T17:09:38.807Z,1773076178.807 [DataOverHttps](INFO): SBD MOMSN=26703758
2026-03-09T17:09:40.066Z,1773076180.066 [Default:CheckIn:Read_Iridium] Stopped
2026-03-09T17:09:40.066Z,1773076180.066 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-09T17:09:40.066Z,1773076180.066 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-09T17:09:57.021Z,1773076197.021 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-09T17:14:40.655Z,1773076480.655 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-09T17:14:40.655Z,1773076480.655 [Default:CheckIn:C.Wait] Stopped
2026-03-09T17:14:40.655Z,1773076480.655 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-09T17:14:40.655Z,1773076480.655 [Default:CheckIn:D] Running Loop=1
2026-03-09T17:14:41.059Z,1773076481.059 [Default:CheckIn:D] Stopped
2026-03-09T17:14:41.059Z,1773076481.059 [Default:CheckIn:E] Running Loop=1
2026-03-09T17:14:41.468Z,1773076481.468 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  61.725667 min 
2026-03-09T17:14:41.468Z,1773076481.468 [Default:CheckIn:E] Stopped
2026-03-09T17:14:41.468Z,1773076481.468 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-09T17:14:41.468Z,1773076481.468 [Default:CheckIn] Stopped
2026-03-09T17:14:41.468Z,1773076481.468 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-09T17:14:41.469Z,1773076481.469 [Default:CheckIn](INFO): Running loop #10
2026-03-09T17:14:41.469Z,1773076481.469 [Default:CheckIn] Running Loop=10
2026-03-09T17:14:41.469Z,1773076481.469 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-09T17:14:41.469Z,1773076481.469 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-09T17:14:43.468Z,1773076483.468 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171442.00,A,3648.15170,N,12147.22184,W,0.019,86.22,090326,,,D*43
2026-03-09T17:14:43.470Z,1773076483.470 [NAL9602](INFO): GPS fix at 20260309T171442: (36.802528, -121.787031)
2026-03-09T17:14:43.492Z,1773076483.492 [Default:CheckIn:Read_GPS] Stopped
2026-03-09T17:14:43.493Z,1773076483.493 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-09T17:14:50.840Z,1773076490.840 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260309T160900/Courier0037.lzma
2026-03-09T17:14:51.842Z,1773076491.842 [DataOverHttps](INFO): Moved sent file to Logs/20260309T160900/Courier0037.lzma.bak
2026-03-09T17:14:51.842Z,1773076491.842 [DataOverHttps](INFO): SBD MOMSN=26703778
2026-03-09T17:15:07.608Z,1773076507.608 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20260309T160900/Express0038.lzma
2026-03-09T17:15:08.610Z,1773076508.610 [DataOverHttps](INFO): Moved sent file to Logs/20260309T160900/Express0038.lzma.bak
2026-03-09T17:15:08.610Z,1773076508.610 [DataOverHttps](INFO): SBD MOMSN=26703781
2026-03-09T17:15:09.758Z,1773076509.758 [Default:CheckIn:Read_Iridium] Stopped
2026-03-09T17:15:09.758Z,1773076509.758 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-09T17:15:09.758Z,1773076509.758 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-09T17:16:19.215Z,1773076579.215 [NAL9602](INFO): SBD MO Status=2, MOMSN=12626, MT Status=2, MTMSN=0
2026-03-09T17:16:19.215Z,1773076579.215 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-09T17:16:45.075Z,1773076605.075 [NAL9602](INFO): SBD MO Status=2, MOMSN=12626, MT Status=2, MTMSN=0
2026-03-09T17:16:45.075Z,1773076605.075 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-03-09T17:17:02.862Z,1773076622.862 [NAL9602](INFO): SBD MO Status=0, MOMSN=12626, MT Status=0, MTMSN=0
2026-03-09T17:17:02.862Z,1773076622.862 [NAL9602](INFO): No messages in MT queue
2026-03-09T17:17:33.557Z,1773076653.557 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-09T17:20:10.317Z,1773076810.317 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-09T17:20:10.317Z,1773076810.317 [Default:CheckIn:C.Wait] Stopped
2026-03-09T17:20:10.317Z,1773076810.317 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-09T17:20:10.317Z,1773076810.317 [Default:CheckIn:D] Running Loop=1
2026-03-09T17:20:10.733Z,1773076810.733 [Default:CheckIn:D] Stopped
2026-03-09T17:20:10.733Z,1773076810.733 [Default:CheckIn:E] Running Loop=1
2026-03-09T17:20:11.127Z,1773076811.127 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  67.220235 min 
2026-03-09T17:20:11.127Z,1773076811.127 [Default:CheckIn:E] Stopped
2026-03-09T17:20:11.127Z,1773076811.127 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-09T17:20:11.128Z,1773076811.128 [Default:CheckIn] Stopped
2026-03-09T17:20:11.128Z,1773076811.128 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-09T17:20:11.128Z,1773076811.128 [Default:CheckIn](INFO): Running loop #11
2026-03-09T17:20:11.128Z,1773076811.128 [Default:CheckIn] Running Loop=11
2026-03-09T17:20:11.128Z,1773076811.128 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-09T17:20:11.128Z,1773076811.128 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-09T17:20:13.199Z,1773076813.199 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172012.00,A,3648.18982,N,12147.17490,W,4.199,35.20,090326,,,D*48
2026-03-09T17:20:13.202Z,1773076813.202 [NAL9602](INFO): GPS fix at 20260309T172012: (36.803164, -121.786248)
2026-03-09T17:20:13.212Z,1773076813.212 [Default:CheckIn:Read_GPS] Stopped
2026-03-09T17:20:13.212Z,1773076813.212 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-09T17:20:20.064Z,1773076820.064 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20260309T160900/Courier0040.lzma
2026-03-09T17:20:21.066Z,1773076821.066 [DataOverHttps](INFO): Moved sent file to Logs/20260309T160900/Courier0040.lzma.bak
2026-03-09T17:20:21.066Z,1773076821.066 [DataOverHttps](INFO): SBD MOMSN=26703792
2026-03-09T17:20:25.694Z,1773076825.694 [NAL9602](INFO): SBD MO Status=0, MOMSN=12627, MT Status=0, MTMSN=0
2026-03-09T17:20:25.694Z,1773076825.694 [NAL9602](INFO): No messages in MT queue
2026-03-09T17:20:37.296Z,1773076837.296 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20260309T160900/Express0041.lzma
2026-03-09T17:20:38.298Z,1773076838.298 [DataOverHttps](INFO): Moved sent file to Logs/20260309T160900/Express0041.lzma.bak
2026-03-09T17:20:38.299Z,1773076838.299 [DataOverHttps](INFO): SBD MOMSN=26703795
2026-03-09T17:20:39.412Z,1773076839.412 [Default:CheckIn:Read_Iridium] Stopped
2026-03-09T17:20:39.412Z,1773076839.412 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-09T17:20:39.412Z,1773076839.412 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-09T17:20:56.361Z,1773076856.361 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-09T17:21:07.270Z,1773076867.270 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-09T17:21:09.293Z,1773076869.293 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-09T17:21:22.621Z,1773076882.621 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-09T17:21:24.237Z,1773076884.237 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-09T17:21:38.376Z,1773076898.376 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-09T17:21:39.587Z,1773076899.587 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-09T17:21:54.536Z,1773076914.536 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-09T17:21:54.542Z,1773076914.542 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-09T17:22:09.489Z,1773076929.489 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-09T17:22:09.905Z,1773076929.905 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-09T17:22:24.835Z,1773076944.835 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-09T17:22:25.253Z,1773076945.253 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-09T17:22:40.188Z,1773076960.188 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-09T17:22:40.605Z,1773076960.605 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-09T17:22:55.540Z,1773076975.540 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-09T17:22:56.347Z,1773076976.347 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-09T17:23:10.938Z,1773076990.938 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2026-03-09T17:23:11.295Z,1773076991.295 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2026-03-09T17:23:25.031Z,1773077005.031 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2026-03-09T17:23:25.038Z,1773077005.038 [BPC1](INFO): Calculating totals. Valid battery stick count: 44. Valid reserve battery stick count: 5.
2026-03-09T17:23:25.061Z,1773077005.061 [BPC1](INFO): Received data from all battery sticks.
2026-03-09T17:25:39.990Z,1773077139.990 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-09T17:25:39.990Z,1773077139.990 [Default:CheckIn:C.Wait] Stopped
2026-03-09T17:25:39.990Z,1773077139.990 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-09T17:25:39.990Z,1773077139.990 [Default:CheckIn:D] Running Loop=1
2026-03-09T17:25:40.387Z,1773077140.387 [Default:CheckIn:D] Stopped
2026-03-09T17:25:40.387Z,1773077140.387 [Default:CheckIn:E] Running Loop=1
2026-03-09T17:25:40.791Z,1773077140.791 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  72.714478 min 
2026-03-09T17:25:40.791Z,1773077140.791 [Default:CheckIn:E] Stopped
2026-03-09T17:25:40.791Z,1773077140.791 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-09T17:25:40.792Z,1773077140.792 [Default:CheckIn] Stopped
2026-03-09T17:25:40.792Z,1773077140.792 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-09T17:25:40.792Z,1773077140.792 [Default:CheckIn](INFO): Running loop #12
2026-03-09T17:25:40.792Z,1773077140.792 [Default:CheckIn] Running Loop=12
2026-03-09T17:25:40.792Z,1773077140.792 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-09T17:25:40.792Z,1773077140.792 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-09T17:25:42.802Z,1773077142.802 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172542.00,A,3648.41008,N,12147.39603,W,11.508,231.05,090326,,,D*42
2026-03-09T17:25:42.805Z,1773077142.805 [NAL9602](INFO): GPS fix at 20260309T172542: (36.806835, -121.789934)
2026-03-09T17:25:42.815Z,1773077142.815 [Default:CheckIn:Read_GPS] Stopped
2026-03-09T17:25:42.815Z,1773077142.815 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-09T17:25:50.796Z,1773077150.796 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20260309T160900/Courier0043.lzma
2026-03-09T17:25:51.798Z,1773077151.798 [DataOverHttps](INFO): Moved sent file to Logs/20260309T160900/Courier0043.lzma.bak
2026-03-09T17:25:51.798Z,1773077151.798 [DataOverHttps](INFO): SBD MOMSN=26703814
2026-03-09T17:25:57.347Z,1773077157.347 [NAL9602](INFO): SBD MO Status=0, MOMSN=12628, MT Status=0, MTMSN=0
2026-03-09T17:25:57.347Z,1773077157.347 [NAL9602](INFO): No messages in MT queue
2026-03-09T17:26:07.668Z,1773077167.668 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20260309T160900/Express0044.lzma
2026-03-09T17:26:08.670Z,1773077168.670 [DataOverHttps](INFO): Moved sent file to Logs/20260309T160900/Express0044.lzma.bak
2026-03-09T17:26:08.671Z,1773077168.671 [DataOverHttps](INFO): SBD MOMSN=26703817
2026-03-09T17:26:09.886Z,1773077169.886 [Default:CheckIn:Read_Iridium] Stopped
2026-03-09T17:26:09.886Z,1773077169.886 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-09T17:26:09.887Z,1773077169.887 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-09T17:26:28.045Z,1773077188.045 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-09T17:31:10.461Z,1773077470.461 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-09T17:31:10.461Z,1773077470.461 [Default:CheckIn:C.Wait] Stopped
2026-03-09T17:31:10.461Z,1773077470.461 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-09T17:31:10.461Z,1773077470.461 [Default:CheckIn:D] Running Loop=1
2026-03-09T17:31:10.859Z,1773077470.859 [Default:CheckIn:D] Stopped
2026-03-09T17:31:10.859Z,1773077470.859 [Default:CheckIn:E] Running Loop=1
2026-03-09T17:31:11.263Z,1773077471.263 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  78.222339 min 
2026-03-09T17:31:11.263Z,1773077471.263 [Default:CheckIn:E] Stopped
2026-03-09T17:31:11.264Z,1773077471.264 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-09T17:31:11.264Z,1773077471.264 [Default:CheckIn] Stopped
2026-03-09T17:31:11.264Z,1773077471.264 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-09T17:31:11.264Z,1773077471.264 [Default:CheckIn](INFO): Running loop #13
2026-03-09T17:31:11.264Z,1773077471.264 [Default:CheckIn] Running Loop=13
2026-03-09T17:31:11.264Z,1773077471.264 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-09T17:31:11.264Z,1773077471.264 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-09T17:31:13.273Z,1773077473.273 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173112.00,A,3647.76880,N,12149.34193,W,17.903,280.18,090326,,,D*4B
2026-03-09T17:31:13.275Z,1773077473.275 [NAL9602](INFO): GPS fix at 20260309T173112: (36.796147, -121.822366)
2026-03-09T17:31:13.285Z,1773077473.285 [Default:CheckIn:Read_GPS] Stopped
2026-03-09T17:31:13.285Z,1773077473.285 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-09T17:31:20.500Z,1773077480.500 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20260309T160900/Courier0046.lzma
2026-03-09T17:31:21.502Z,1773077481.502 [DataOverHttps](INFO): Moved sent file to Logs/20260309T160900/Courier0046.lzma.bak
2026-03-09T17:31:21.503Z,1773077481.503 [DataOverHttps](INFO): SBD MOMSN=26703822
2026-03-09T17:31:37.428Z,1773077497.428 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20260309T160900/Express0047.lzma
2026-03-09T17:31:37.924Z,1773077497.924 [NAL9602](INFO): SBD MO Status=0, MOMSN=12629, MT Status=0, MTMSN=0
2026-03-09T17:31:37.924Z,1773077497.924 [NAL9602](INFO): No messages in MT queue
2026-03-09T17:31:38.430Z,1773077498.430 [DataOverHttps](INFO): Moved sent file to Logs/20260309T160900/Express0047.lzma.bak
2026-03-09T17:31:38.431Z,1773077498.431 [DataOverHttps](INFO): SBD MOMSN=26703825
2026-03-09T17:31:39.548Z,1773077499.548 [Default:CheckIn:Read_Iridium] Stopped
2026-03-09T17:31:39.548Z,1773077499.548 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-09T17:31:39.548Z,1773077499.548 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-09T17:32:08.623Z,1773077528.623 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-09T17:36:40.138Z,1773077800.138 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-03-09T17:36:40.138Z,1773077800.138 [Default:CheckIn:C.Wait] Stopped
2026-03-09T17:36:40.138Z,1773077800.138 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-03-09T17:36:40.139Z,1773077800.139 [Default:CheckIn:D] Running Loop=1
2026-03-09T17:36:40.545Z,1773077800.545 [Default:CheckIn:D] Stopped
2026-03-09T17:36:40.545Z,1773077800.545 [Default:CheckIn:E] Running Loop=1
2026-03-09T17:36:40.961Z,1773077800.961 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  83.717098 min 
2026-03-09T17:36:40.961Z,1773077800.961 [Default:CheckIn:E] Stopped
2026-03-09T17:36:40.961Z,1773077800.961 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-03-09T17:36:40.961Z,1773077800.961 [Default:CheckIn] Stopped
2026-03-09T17:36:40.961Z,1773077800.961 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-03-09T17:36:40.961Z,1773077800.961 [Default:CheckIn](INFO): Running loop #14
2026-03-09T17:36:40.961Z,1773077800.961 [Default:CheckIn] Running Loop=14
2026-03-09T17:36:40.961Z,1773077800.961 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-03-09T17:36:40.961Z,1773077800.961 [Default:CheckIn:Read_GPS] Running Loop=1
2026-03-09T17:36:42.963Z,1773077802.963 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173642.00,A,3647.84425,N,12150.69770,W,1.788,299.98,090326,,,D*76
2026-03-09T17:36:42.966Z,1773077802.966 [NAL9602](INFO): GPS fix at 20260309T173642: (36.797404, -121.844962)
2026-03-09T17:36:43.000Z,1773077803.000 [Default:CheckIn:Read_GPS] Stopped
2026-03-09T17:36:43.000Z,1773077803.000 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-03-09T17:36:43.783Z,1773077803.783 [Default:CheckIn:Read_Iridium] Stopped
2026-03-09T17:36:43.783Z,1773077803.783 [Default:CheckIn:C.Wait] Running Loop=1
2026-03-09T17:36:43.783Z,1773077803.783 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-03-09T17:37:05.582Z,1773077825.582 [NAL9602](INFO): SBD MO Status=0, MOMSN=12630, MT Status=0, MTMSN=0
2026-03-09T17:37:05.582Z,1773077825.582 [NAL9602](INFO): No messages in MT queue
2026-03-09T17:37:36.286Z,1773077856.286 [NAL9602](INFO): Not Powering down - fast GPS
2026-03-09T17:40:23.802Z,1773078023.802 [DataOverHttps](IMPORTANT): SBD MTMSN=20260309T174022
2026-03-09T17:40:33.237Z,1773078033.237 [DataOverHttps](INFO): Received command: restart logs