2025-07-21T15:23:56.615Z,1753111436.615 [Supervisor](DEBUG): Initializing supervisor.
2025-07-21T15:23:56.620Z,1753111436.620 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-07-21T15:23:56.620Z,1753111436.620 [SyncHandler](INFO): Protected caller Thread ID is 981
2025-07-21T15:23:56.621Z,1753111436.621 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-07-21T15:23:56.622Z,1753111436.622 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-07-21T15:23:56.622Z,1753111436.622 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 982
2025-07-21T15:23:56.627Z,1753111436.627 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-07-21T15:23:56.647Z,1753111436.647 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-07-21T15:23:56.648Z,1753111436.648 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-07-21T15:23:56.648Z,1753111436.648 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 983
2025-07-21T15:23:56.653Z,1753111436.653 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-07-21T15:23:56.654Z,1753111436.654 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-07-21T15:23:56.654Z,1753111436.654 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 984
2025-07-21T15:23:56.656Z,1753111436.656 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-07-21T15:23:56.657Z,1753111436.657 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-07-21T15:23:56.658Z,1753111436.658 [logger ThreadHandler](INFO): Protected caller Thread ID is 985
2025-07-21T15:23:56.662Z,1753111436.662 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-07-21T15:23:56.662Z,1753111436.662 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-07-21T15:23:56.664Z,1753111436.664 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-07-21T15:23:56.930Z,1753111436.930 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-07-21T15:23:56.931Z,1753111436.931 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-07-21T15:23:57.030Z,1753111437.030 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance
2025-07-21T15:23:57.030Z,1753111437.030 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-07-21T15:23:57.552Z,1753111437.552 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-07-21T15:23:57.553Z,1753111437.553 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-07-21T15:23:57.904Z,1753111437.904 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-07-21T15:23:57.905Z,1753111437.905 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-07-21T15:23:58.010Z,1753111438.010 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-07-21T15:23:58.010Z,1753111438.010 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-07-21T15:23:58.431Z,1753111438.431 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-07-21T15:23:58.431Z,1753111438.431 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-07-21T15:23:58.666Z,1753111438.666 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-07-21T15:23:58.667Z,1753111438.667 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-07-21T15:23:59.209Z,1753111439.209 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-07-21T15:23:59.209Z,1753111439.209 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-07-21T15:23:59.358Z,1753111439.358 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-07-21T15:23:59.358Z,1753111439.358 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-07-21T15:23:59.447Z,1753111439.447 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-07-21T15:24:00.308Z,1753111440.308 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-07-21T15:24:00.308Z,1753111440.308 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-07-21T15:24:00.698Z,1753111440.698 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-07-21T15:24:00.699Z,1753111440.699 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-07-21T15:24:00.914Z,1753111440.914 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-07-21T15:24:00.915Z,1753111440.915 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-07-21T15:24:01.420Z,1753111441.420 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-07-21T15:24:01.420Z,1753111441.420 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-07-21T15:24:01.853Z,1753111441.853 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-07-21T15:24:01.854Z,1753111441.854 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-07-21T15:24:02.116Z,1753111442.116 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-07-21T15:24:02.118Z,1753111442.118 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2025-07-21T15:24:02.119Z,1753111442.119 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2025-07-21T15:24:02.213Z,1753111442.213 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2025-07-21T15:24:02.323Z,1753111442.323 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2025-07-21T15:24:02.416Z,1753111442.416 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2025-07-21T15:24:02.526Z,1753111442.526 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2025-07-21T15:24:02.627Z,1753111442.627 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2025-07-21T15:24:02.751Z,1753111442.751 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2025-07-21T15:24:02.913Z,1753111442.913 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2025-07-21T15:24:03.071Z,1753111443.071 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2025-07-21T15:24:03.191Z,1753111443.191 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2025-07-21T15:24:03.470Z,1753111443.470 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-07-21T15:24:03.471Z,1753111443.471 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2025-07-21T15:24:03.471Z,1753111443.471 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-07-21T15:24:03.477Z,1753111443.477 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-07-21T15:24:03.533Z,1753111443.533 [VerticalControl](DEBUG): Construct VerticalControl.
2025-07-21T15:24:03.596Z,1753111443.596 [VerticalControl] Loaded
2025-07-21T15:24:03.596Z,1753111443.596 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-07-21T15:24:03.599Z,1753111443.599 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-07-21T15:24:03.648Z,1753111443.648 [HorizontalControl] Loaded
2025-07-21T15:24:03.648Z,1753111443.648 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-07-21T15:24:03.650Z,1753111443.650 [SpeedControl](DEBUG): Construct SpeedControl.
2025-07-21T15:24:03.654Z,1753111443.654 [SpeedControl] Loaded
2025-07-21T15:24:03.654Z,1753111443.654 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-07-21T15:24:03.657Z,1753111443.657 [LoopControl](DEBUG): Construct LoopControl.
2025-07-21T15:24:03.657Z,1753111443.657 [LoopControl] Loaded
2025-07-21T15:24:03.657Z,1753111443.657 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-07-21T15:24:03.658Z,1753111443.658 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-07-21T15:24:03.658Z,1753111443.658 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-07-21T15:24:03.669Z,1753111443.669 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-07-21T15:24:03.669Z,1753111443.669 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-07-21T15:24:03.852Z,1753111443.852 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-07-21T15:24:03.853Z,1753111443.853 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-07-21T15:24:04.017Z,1753111444.017 [BuoyancyServo] Loaded
2025-07-21T15:24:04.017Z,1753111444.017 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-07-21T15:24:04.040Z,1753111444.040 [ElevatorServo] Loaded
2025-07-21T15:24:04.040Z,1753111444.040 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-07-21T15:24:04.062Z,1753111444.062 [MassServo] Loaded
2025-07-21T15:24:04.062Z,1753111444.062 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-07-21T15:24:04.083Z,1753111444.083 [RudderServo] Loaded
2025-07-21T15:24:04.083Z,1753111444.083 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-07-21T15:24:04.100Z,1753111444.100 [ThrusterHE] Loaded
2025-07-21T15:24:04.100Z,1753111444.100 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-07-21T15:24:04.101Z,1753111444.101 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-07-21T15:24:04.102Z,1753111444.102 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-07-21T15:24:04.218Z,1753111444.218 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-07-21T15:24:04.219Z,1753111444.219 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-07-21T15:24:04.233Z,1753111444.233 [NavChart] Loaded
2025-07-21T15:24:04.233Z,1753111444.233 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-07-21T15:24:04.239Z,1753111444.239 [UniversalFixResidualReporter] Loaded
2025-07-21T15:24:04.239Z,1753111444.239 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-07-21T15:24:04.239Z,1753111444.239 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-07-21T15:24:04.240Z,1753111444.240 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-07-21T15:24:04.300Z,1753111444.300 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-07-21T15:24:04.301Z,1753111444.301 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-07-21T15:24:04.788Z,1753111444.788 [AHRS_M2] Loaded
2025-07-21T15:24:04.788Z,1753111444.788 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-07-21T15:24:05.654Z,1753111445.654 [BPC1] Loaded
2025-07-21T15:24:05.654Z,1753111445.654 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-07-21T15:24:05.756Z,1753111445.756 [DAT] Loaded
2025-07-21T15:24:05.756Z,1753111445.756 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-07-21T15:24:05.758Z,1753111445.758 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4094B4E0
2025-07-21T15:24:05.758Z,1753111445.758 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1064
2025-07-21T15:24:05.837Z,1753111445.837 [DataOverHttps] Loaded
2025-07-21T15:24:05.837Z,1753111445.837 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-07-21T15:24:05.838Z,1753111445.838 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4097B4E0
2025-07-21T15:24:05.838Z,1753111445.838 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1065
2025-07-21T15:24:05.862Z,1753111445.862 [Depth_Keller] Loaded
2025-07-21T15:24:05.862Z,1753111445.862 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-07-21T15:24:05.867Z,1753111445.867 [DropWeight] Loaded
2025-07-21T15:24:05.868Z,1753111445.868 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-07-21T15:24:05.933Z,1753111445.933 [NAL9602] Loaded
2025-07-21T15:24:05.934Z,1753111445.934 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-07-21T15:24:05.964Z,1753111445.964 [Onboard] Loaded
2025-07-21T15:24:05.964Z,1753111445.964 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-07-21T15:24:05.965Z,1753111445.965 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409AB4E0
2025-07-21T15:24:05.965Z,1753111445.965 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1066
2025-07-21T15:24:05.980Z,1753111445.980 [Power24vConverter] Loaded
2025-07-21T15:24:05.980Z,1753111445.980 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-07-21T15:24:05.997Z,1753111445.997 [Radio_Surface] Loaded
2025-07-21T15:24:05.997Z,1753111445.997 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-07-21T15:24:05.998Z,1753111445.998 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409DB4E0
2025-07-21T15:24:05.998Z,1753111445.998 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1067
2025-07-21T15:24:06.014Z,1753111446.014 [Sonardyne_Nano] Loaded
2025-07-21T15:24:06.014Z,1753111446.014 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-07-21T15:24:06.015Z,1753111446.015 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-07-21T15:24:06.015Z,1753111446.015 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-07-21T15:24:06.219Z,1753111446.219 [DepthRateCalculator] Loaded
2025-07-21T15:24:06.219Z,1753111446.219 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-07-21T15:24:06.224Z,1753111446.224 [PitchRateCalculator] Loaded
2025-07-21T15:24:06.225Z,1753111446.225 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-07-21T15:24:06.368Z,1753111446.368 [SpeedCalculator] Loaded
2025-07-21T15:24:06.369Z,1753111446.369 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-07-21T15:24:06.381Z,1753111446.381 [YawRateCalculator] Loaded
2025-07-21T15:24:06.381Z,1753111446.381 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-07-21T15:24:06.481Z,1753111446.481 [ElevatorOffsetCalculator] Loaded
2025-07-21T15:24:06.481Z,1753111446.481 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-07-21T15:24:06.482Z,1753111446.482 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-07-21T15:24:06.482Z,1753111446.482 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-07-21T15:24:07.009Z,1753111447.009 [CANONSampler] Loaded
2025-07-21T15:24:07.009Z,1753111447.009 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2025-07-21T15:24:07.079Z,1753111447.079 [CTD_Seabird] Loaded
2025-07-21T15:24:07.079Z,1753111447.079 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-07-21T15:24:07.083Z,1753111447.083 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A994E0
2025-07-21T15:24:07.083Z,1753111447.083 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1068
2025-07-21T15:24:07.107Z,1753111447.107 [PAR_Licor] Loaded
2025-07-21T15:24:07.107Z,1753111447.107 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-07-21T15:24:07.153Z,1753111447.153 [WetLabsBB2FL] Loaded
2025-07-21T15:24:07.153Z,1753111447.153 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-07-21T15:24:07.154Z,1753111447.154 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AC94E0
2025-07-21T15:24:07.154Z,1753111447.154 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1069
2025-07-21T15:24:07.155Z,1753111447.155 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-07-21T15:24:07.156Z,1753111447.156 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-07-21T15:24:07.186Z,1753111447.186 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-07-21T15:24:07.186Z,1753111447.186 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-07-21T15:24:07.543Z,1753111447.543 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-07-21T15:24:07.544Z,1753111447.544 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-07-21T15:24:07.711Z,1753111447.711 [SBIT](DEBUG): Construct Startup Built In Test.
2025-07-21T15:24:07.720Z,1753111447.720 [SBIT] Loaded
2025-07-21T15:24:07.720Z,1753111447.720 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-07-21T15:24:07.723Z,1753111447.723 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-07-21T15:24:07.736Z,1753111447.736 [IBIT] Loaded
2025-07-21T15:24:07.737Z,1753111447.737 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-07-21T15:24:07.742Z,1753111447.742 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-07-21T15:24:07.826Z,1753111447.826 [CBIT] Loaded
2025-07-21T15:24:07.826Z,1753111447.826 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-07-21T15:24:07.859Z,1753111447.859 [GFScanner] Loaded
2025-07-21T15:24:07.860Z,1753111447.860 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-07-21T15:24:07.860Z,1753111447.860 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-07-21T15:24:07.861Z,1753111447.861 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-07-21T15:24:07.999Z,1753111447.999 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-07-21T15:24:08.005Z,1753111448.005 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-07-21T15:24:08.008Z,1753111448.008 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-07-21T15:24:08.020Z,1753111448.020 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-07-21T15:24:08.021Z,1753111448.021 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C394E0
2025-07-21T15:24:08.021Z,1753111448.021 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1070
2025-07-21T15:24:08.026Z,1753111448.026 [Supervisor](INFO): Main Thread ID is 829
2025-07-21T15:24:08.026Z,1753111448.026 [Supervisor](DEBUG): Running supervisor.
2025-07-21T15:24:08.026Z,1753111448.026 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1071
2025-07-21T15:24:08.027Z,1753111448.027 [CommandExec](INFO): Initializing the command executive.
2025-07-21T15:24:08.029Z,1753111448.029 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1072
2025-07-21T15:24:08.031Z,1753111448.031 [controlThread ThreadHandler](INFO): Handler Thread ID is 1073
2025-07-21T15:24:08.031Z,1753111448.031 [controlThread](DEBUG): Initializing ControlThread
2025-07-21T15:24:08.032Z,1753111448.032 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-07-21T15:24:08.034Z,1753111448.034 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-07-21T15:24:08.034Z,1753111448.034 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-07-21T15:24:08.035Z,1753111448.035 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-07-21T15:24:08.037Z,1753111448.037 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-07-21T15:24:08.037Z,1753111448.037 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-07-21T15:24:08.040Z,1753111448.040 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-07-21T15:24:08.040Z,1753111448.040 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-07-21T15:24:08.040Z,1753111448.040 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-07-21T15:24:08.040Z,1753111448.040 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-07-21T15:24:08.041Z,1753111448.041 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-07-21T15:24:08.045Z,1753111448.045 [SBIT](INFO): Initialize SBIT Component.
2025-07-21T15:24:08.046Z,1753111448.046 [SBIT](IMPORTANT): git: 2025-06-02-12-gf31d2d2cf
2025-07-21T15:24:08.046Z,1753111448.046 [SBIT](INFO): git hash: f31d2d2cff7b1a18a5fb2309356ea947ca1213be
2025-07-21T15:24:08.046Z,1753111448.046 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-07-21T15:24:08.047Z,1753111448.047 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2025-07-21T15:24:08.048Z,1753111448.048 [SBIT](INFO): Beginning SBIT in 70.000000 seconds.
2025-07-21T15:24:08.049Z,1753111448.049 [IBIT](INFO): Initialize IBIT Component.
2025-07-21T15:24:08.050Z,1753111448.050 [CBIT](DEBUG): Initialize CBIT Component.
2025-07-21T15:24:08.051Z,1753111448.051 [logger ThreadHandler](INFO): Handler Thread ID is 1074
2025-07-21T15:24:08.053Z,1753111448.053 [DAT ThreadHandler](INFO): Handler Thread ID is 1075
2025-07-21T15:24:08.061Z,1753111448.061 [CBIT](DEBUG): Initialized mux pins.
2025-07-21T15:24:08.061Z,1753111448.061 [CBIT](DEBUG): Initializing the watchdog timer.
2025-07-21T15:24:08.066Z,1753111448.066 [DAT](INFO): Powering up
2025-07-21T15:24:08.066Z,1753111448.066 [DAT](DEBUG): Initializing DAT.
2025-07-21T15:24:08.070Z,1753111448.070 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1077
2025-07-21T15:24:08.072Z,1753111448.072 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-07-21T15:24:08.082Z,1753111448.082 [Onboard ThreadHandler](INFO): Handler Thread ID is 1078
2025-07-21T15:24:08.086Z,1753111448.086 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-07-21T15:24:08.086Z,1753111448.086 [CBIT](DEBUG): Initializing heartbeat.
2025-07-21T15:24:08.102Z,1753111448.102 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1079
2025-07-21T15:24:08.117Z,1753111448.117 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1080
2025-07-21T15:24:08.118Z,1753111448.118 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-07-21T15:24:08.122Z,1753111448.122 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1082
2025-07-21T15:24:08.126Z,1753111448.126 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1084
2025-07-21T15:24:08.126Z,1753111448.126 [WetLabsBB2FL](INFO): Powering up
2025-07-21T15:24:08.131Z,1753111448.131 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-07-21T15:24:08.131Z,1753111448.131 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-07-21T15:24:08.131Z,1753111448.131 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-07-21T15:24:08.131Z,1753111448.131 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-07-21T15:24:08.131Z,1753111448.131 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-07-21T15:24:08.132Z,1753111448.132 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-07-21T15:24:08.132Z,1753111448.132 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-07-21T15:24:08.132Z,1753111448.132 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-07-21T15:24:08.157Z,1753111448.157 [CBIT](DEBUG): Deactivating emergency mode.
2025-07-21T15:24:08.197Z,1753111448.197 [CBIT](DEBUG): Backplane powered.
2025-07-21T15:24:08.197Z,1753111448.197 [GFScanner](DEBUG): Initializing GFScanner
2025-07-21T15:24:08.197Z,1753111448.197 [GFScanner](DEBUG): Deactivating GF circuits.
2025-07-21T15:24:08.202Z,1753111448.202 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-07-21T15:24:08.203Z,1753111448.203 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-07-21T15:24:08.203Z,1753111448.203 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-07-21T15:24:08.204Z,1753111448.204 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-07-21T15:24:08.261Z,1753111448.261 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2025-07-21T15:24:08.282Z,1753111448.282 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-07-21T15:24:08.302Z,1753111448.302 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Startup {
behavior Guidance:GoToSurface {
run in progression
}
aggregate StartupSatComms {
run in sequence
readDatum {
timeout duration=P1M
Universal:latitude_fix
}
readDatum {
timeout duration=P1M
Universal:platform_communications
}
}
}
2025-07-21T15:24:08.303Z,1753111448.303 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-07-21T15:24:08.303Z,1753111448.303 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-07-21T15:24:08.304Z,1753111448.304 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-07-21T15:24:08.505Z,1753111448.505 [Radio_Surface](INFO): Powering up
2025-07-21T15:24:08.641Z,1753111448.641 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2025-07-21T15:24:08.663Z,1753111448.663 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-07-21T15:24:08.665Z,1753111448.665 [Default:A.Wait](DEBUG): Construct Wait.
2025-07-21T15:24:08.667Z,1753111448.667 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-07-21T15:24:08.722Z,1753111448.722 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-07-21T15:24:08.724Z,1753111448.724 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-07-21T15:24:08.752Z,1753111448.752 [Default:E.Execute](DEBUG): Construct Execute.
2025-07-21T15:24:08.771Z,1753111448.771 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Default {
arguments {
ElapsedSinceDefaultStarted = 0 minute
}
behavior Guidance:Wait {
"""
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
"""
run in sequence
set duration = 13 second
}
behavior Guidance:GoToSurface {
run in progression
}
aggregate CheckIn {
run in sequence repeat=288
readDatum id="Read_GPS" {
timeout duration=P5M
Universal:time_fix
}
readDatum id="Read_Iridium" {
timeout duration=P4H {
behavior Guidance:Execute {
run in sequence
set command = "Burn on"
}
syslog critical "Dropped weight due to communications timeout."
}
Universal:platform_communications
}
behavior Guidance:Wait {
run in sequence
set duration = 5 minute
}
assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )
syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
}
syslog important "Restarting logs and Default mission."
behavior Guidance:Execute {
run in sequence
set command = "restart logs"
}
}
2025-07-21T15:24:08.776Z,1753111448.776 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,CANONSampler,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,Reporter,LogSplitter,
2025-07-21T15:24:08.799Z,1753111448.799 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-07-21T15:24:08.858Z,1753111448.858 [Depth_Keller](INFO): Initializing.
2025-07-21T15:24:08.859Z,1753111448.859 [Power24vConverter](INFO): Powering up.
2025-07-21T15:24:08.860Z,1753111448.860 [Sonardyne_Nano](INFO): Initializing.
2025-07-21T15:24:08.873Z,1753111448.873 [CANONSampler](INFO): Powering down
2025-07-21T15:24:08.887Z,1753111448.887 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-07-21T15:24:08.914Z,1753111448.914 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-07-21T15:24:08.921Z,1753111448.921 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-07-21T15:24:08.922Z,1753111448.922 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-07-21T15:24:08.929Z,1753111448.929 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-07-21T15:24:08.930Z,1753111448.930 [MassServo](DEBUG): Initializing EZServoServo.
2025-07-21T15:24:08.937Z,1753111448.937 [MassServo](DEBUG): Initializing MassServo.
2025-07-21T15:24:08.938Z,1753111448.938 [RudderServo](DEBUG): Initializing EZServoServo.
2025-07-21T15:24:08.945Z,1753111448.945 [RudderServo](DEBUG): Initializing RudderServo.
2025-07-21T15:24:08.946Z,1753111448.946 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-07-21T15:24:08.953Z,1753111448.953 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-07-21T15:24:10.233Z,1753111450.233 [WetLabsBB2FL](INFO): Powering down
2025-07-21T15:24:10.803Z,1753111450.803 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-07-21T15:24:12.091Z,1753111452.091 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2109
2025-07-21T15:24:14.555Z,1753111454.555 [ThrusterHE](ERROR): Zero Speed Commanded.
2025-07-21T15:24:19.666Z,1753111459.666 [DAT](INFO): DAT read:
2025-07-21T15:24:19.667Z,1753111459.667 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2025-07-21T15:24:21.178Z,1753111461.178 [DAT](INFO): DAT read: MF Frequency Band
2025-07-21T15:24:21.179Z,1753111461.179 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2025-07-21T15:24:21.180Z,1753111461.180 [DAT](INFO): DAT read: Jul 21 2025 15:24:16
2025-07-21T15:24:22.438Z,1753111462.438 [DAT](INFO): DAT read: Features enabled [Bearing]
2025-07-21T15:24:22.439Z,1753111462.439 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC
2025-07-21T15:24:22.440Z,1753111462.440 [DAT](INFO): commRate: 1200
2025-07-21T15:24:24.506Z,1753111464.506 [DAT](INFO): entering command mode
2025-07-21T15:24:24.706Z,1753111464.706 [DAT](INFO): DAT read:
2025-07-21T15:24:24.706Z,1753111464.706 [DAT](INFO): DAT read: user:1>
2025-07-21T15:24:24.707Z,1753111464.707 [DAT](INFO): setting verbose to 3
2025-07-21T15:24:24.958Z,1753111464.958 [DAT](INFO): DAT read: user:1>
2025-07-21T15:24:24.959Z,1753111464.959 [DAT](INFO): DAT read: Verbose | 3
2025-07-21T15:24:24.959Z,1753111464.959 [DAT](INFO): set verbose to 3
2025-07-21T15:24:24.959Z,1753111464.959 [DAT](INFO): setting DatVerbose to 27440
2025-07-21T15:24:25.210Z,1753111465.210 [DAT](INFO): DAT read: user:2>
2025-07-21T15:24:25.211Z,1753111465.211 [DAT](INFO): DAT read: DatVerbose | 27440
2025-07-21T15:24:25.211Z,1753111465.211 [DAT](INFO): set DatVerbose to 27440
2025-07-21T15:24:25.211Z,1753111465.211 [DAT](INFO): setting transmit power to 8
2025-07-21T15:24:25.462Z,1753111465.462 [DAT](INFO): DAT read: user:3>
2025-07-21T15:24:25.463Z,1753111465.463 [DAT](INFO): DAT read: TxPower | 8 (Max)
2025-07-21T15:24:25.463Z,1753111465.463 [DAT](INFO): set transmit power to 8
2025-07-21T15:24:25.463Z,1753111465.463 [DAT](INFO): setting local address to 8
2025-07-21T15:24:25.714Z,1753111465.714 [DAT](INFO): DAT read: user:4>
2025-07-21T15:24:25.715Z,1753111465.715 [DAT](INFO): DAT read: LocalAddr | 8
2025-07-21T15:24:25.715Z,1753111465.715 [DAT](INFO): set local address to 8
2025-07-21T15:24:25.716Z,1753111465.716 [DAT](INFO): Setting time to: 15:24:25 And date to:7/21/2025
2025-07-21T15:24:25.966Z,1753111465.966 [DAT](INFO): DAT read: user:5>
2025-07-21T15:24:25.967Z,1753111465.967 [DAT](INFO): DAT read: Mon Jul 21, 2025 15:24:25
2025-07-21T15:24:25.967Z,1753111465.967 [DAT](INFO): Local DAT time set to Mon Jul 21, 2025 15:24:25
2025-07-21T15:24:36.259Z,1753111476.259 [NAL9602](INFO): Powering up NAL9602
2025-07-21T15:24:47.167Z,1753111487.167 [NAL9602](INFO): NAL9602 initialized
2025-07-21T15:25:05.756Z,1753111505.756 [NAL9602](INFO): SBD MO Status=2, MOMSN=63745, MT Status=2, MTMSN=0
2025-07-21T15:25:05.756Z,1753111505.756 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-21T15:25:17.099Z,1753111517.099 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-07-21T15:25:18.725Z,1753111518.724 [SBIT](IMPORTANT): Beginning Startup BIT
2025-07-21T15:25:18.729Z,1753111518.729 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-07-21T15:25:23.246Z,1753111523.246 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005213
CHAN A1 (24V): 0.000566
CHAN A2 (12V): -0.002176
CHAN A3 (5V): -0.001412
CHAN B0 (3.3V): -0.000239
CHAN B1 (3.15aV): 0.000207
CHAN B2 (3.15bV): 0.000148
CHAN B3 (GND): -0.000257
OPEN: -0.000538
Full Scale: +/- 1 mA
2025-07-21T15:25:31.664Z,1753111531.664 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-07-21T15:26:06.452Z,1753111566.452 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-07-21T15:26:12.467Z,1753111572.467 [SBIT](IMPORTANT): SBIT PASSED
2025-07-21T15:26:12.468Z,1753111572.468 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-07-21T15:26:12.468Z,1753111572.468 [SBIT](IMPORTANT): DATMMP.surfaceThreshold=2 meter;
2025-07-21T15:26:12.468Z,1753111572.468 [SBIT](IMPORTANT): Express linearApproximation DATMMP.acoustic_contact_range 15.000000 meter;
2025-07-21T15:26:12.469Z,1753111572.469 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=249.798756 cubic_centimeter;
2025-07-21T15:26:12.469Z,1753111572.469 [SBIT](IMPORTANT): VerticalControl.massDefault=12.729612 millimeter;
2025-07-21T15:26:12.872Z,1753111572.872 [MissionManager](IMPORTANT): Started mission Startup
2025-07-21T15:26:12.872Z,1753111572.872 [Startup] Running Loop=1
2025-07-21T15:26:12.872Z,1753111572.872 [Startup](DEBUG): Aggregate::initialize Startup
2025-07-21T15:26:12.872Z,1753111572.872 [Startup:A.GoToSurface] Running Loop=1
2025-07-21T15:26:12.872Z,1753111572.872 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-07-21T15:26:12.873Z,1753111572.873 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-07-21T15:26:12.873Z,1753111572.873 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-07-21T15:26:12.874Z,1753111572.874 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-07-21T15:26:12.874Z,1753111572.874 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-07-21T15:26:12.874Z,1753111572.874 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-07-21T15:26:12.875Z,1753111572.875 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-07-21T15:26:12.876Z,1753111572.876 [Startup:StartupSatComms] Running Loop=1
2025-07-21T15:26:12.876Z,1753111572.876 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-07-21T15:26:12.877Z,1753111572.877 [Startup:StartupSatComms:A] Running Loop=1
2025-07-21T15:26:13.280Z,1753111573.280 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-07-21T15:26:50.256Z,1753111610.256 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004865
2025-07-21T15:27:06.917Z,1753111626.917 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.chargePercent
2025-07-21T15:27:06.917Z,1753111626.917 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent 0.000000 %
2025-07-21T15:27:08.216Z,1753111628.216 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-07-21T15:27:08.216Z,1753111628.216 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-21T15:27:08.365Z,1753111628.365 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-21T15:27:08.781Z,1753111628.781 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-21T15:27:08.781Z,1753111628.781 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-07-21T15:27:10.814Z,1753111630.814 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.chargePercent
2025-07-21T15:27:10.814Z,1753111630.814 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent 0.000000 %
2025-07-21T15:27:13.066Z,1753111633.066 [Startup:StartupSatComms:A](INFO): Timed out from 2025-07-21T15:26:12.9Z
2025-07-21T15:27:13.066Z,1753111633.066 [Startup:StartupSatComms:A] Stopped
2025-07-21T15:27:13.066Z,1753111633.066 [Startup:StartupSatComms:B] Running Loop=1
2025-07-21T15:27:13.463Z,1753111633.463 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-07-21T15:27:20.953Z,1753111640.953 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250721T152006/Courier0004.lzma
2025-07-21T15:27:21.956Z,1753111641.956 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152006/Courier0004.lzma.bak
2025-07-21T15:27:21.956Z,1753111641.956 [DataOverHttps](INFO): SBD MOMSN=25562491
2025-07-21T15:27:37.993Z,1753111657.993 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250721T152356/Courier0000.lzma
2025-07-21T15:27:38.995Z,1753111658.995 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Courier0000.lzma.bak
2025-07-21T15:27:38.995Z,1753111658.995 [DataOverHttps](INFO): SBD MOMSN=25562493
2025-07-21T15:27:44.959Z,1753111664.959 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T15:27:55.307Z,1753111675.307 [DataOverHttps](INFO): Sending 106 bytes from file Logs/20250721T152006/Express0005.lzma
2025-07-21T15:27:56.307Z,1753111676.307 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152006/Express0005.lzma.bak
2025-07-21T15:27:56.307Z,1753111676.307 [DataOverHttps](INFO): SBD MOMSN=25562496
2025-07-21T15:28:05.173Z,1753111685.173 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T15:28:12.395Z,1753111692.395 [DataOverHttps](INFO): Sending 877 bytes from file Logs/20250721T152356/Express0001.lzma
2025-07-21T15:28:13.256Z,1753111693.256 [Startup:StartupSatComms:B](INFO): Timed out from 2025-07-21T15:27:13.1Z
2025-07-21T15:28:13.256Z,1753111693.256 [Startup:StartupSatComms:B] Stopped
2025-07-21T15:28:13.256Z,1753111693.256 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-07-21T15:28:13.257Z,1753111693.257 [Startup:StartupSatComms] Stopped
2025-07-21T15:28:13.257Z,1753111693.257 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-07-21T15:28:13.258Z,1753111693.258 [Startup](INFO): Completed Startup
2025-07-21T15:28:13.258Z,1753111693.258 [MissionManager](INFO): Startup is completed.
2025-07-21T15:28:13.258Z,1753111693.258 [MissionManager](INFO): Uninitializing Mission Startup
2025-07-21T15:28:13.258Z,1753111693.258 [Startup] Stopped
2025-07-21T15:28:13.258Z,1753111693.258 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-07-21T15:28:13.258Z,1753111693.258 [Startup:A.GoToSurface] Stopped
2025-07-21T15:28:13.258Z,1753111693.258 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-07-21T15:28:13.395Z,1753111693.395 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Express0001.lzma.bak
2025-07-21T15:28:13.395Z,1753111693.395 [DataOverHttps](INFO): SBD MOMSN=25562500
2025-07-21T15:28:13.667Z,1753111693.667 [MissionManager](IMPORTANT): Started mission Default
2025-07-21T15:28:13.667Z,1753111693.667 [Default] Running Loop=1
2025-07-21T15:28:13.667Z,1753111693.667 [Default](DEBUG): Aggregate::initialize Default
2025-07-21T15:28:13.667Z,1753111693.667 [Default:B.GoToSurface] Running Loop=1
2025-07-21T15:28:13.667Z,1753111693.667 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-07-21T15:28:13.668Z,1753111693.668 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-07-21T15:28:13.668Z,1753111693.668 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-07-21T15:28:13.668Z,1753111693.668 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-07-21T15:28:13.669Z,1753111693.669 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-07-21T15:28:13.669Z,1753111693.669 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-07-21T15:28:13.669Z,1753111693.669 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-07-21T15:28:13.670Z,1753111693.670 [Default:A.Wait] Running Loop=1
2025-07-21T15:28:13.670Z,1753111693.670 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-07-21T15:28:24.147Z,1753111704.147 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T15:28:27.000Z,1753111707.000 [Default:A.Wait](INFO): Done Waiting.
2025-07-21T15:28:27.000Z,1753111707.000 [Default:A.Wait] Stopped
2025-07-21T15:28:27.000Z,1753111707.000 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T15:28:27.395Z,1753111707.395 [Default:CheckIn] Running Loop=1
2025-07-21T15:28:27.395Z,1753111707.395 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T15:28:27.395Z,1753111707.395 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T15:28:27.794Z,1753111707.794 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-07-21T15:28:31.879Z,1753111711.879 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-07-21T15:28:43.995Z,1753111723.995 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-07-21T15:28:44.357Z,1753111724.357 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T15:29:04.011Z,1753111744.011 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.chargePercent
2025-07-21T15:29:04.012Z,1753111744.012 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent 0.000000 %
2025-07-21T15:29:04.600Z,1753111744.600 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T15:29:25.203Z,1753111765.203 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T15:29:45.419Z,1753111785.419 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T15:29:51.063Z,1753111791.063 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-07-21T15:30:09.364Z,1753111809.364 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2025-07-21T15:30:09.364Z,1753111809.364 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-21T15:30:09.503Z,1753111809.503 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-21T15:30:09.821Z,1753111809.821 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-21T15:30:09.821Z,1753111809.821 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2025-07-21T15:30:14.571Z,1753111814.571 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-07-21T15:30:29.498Z,1753111829.498 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-07-21T15:31:22.837Z,1753111882.837 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-07-21T15:32:15.344Z,1753111935.344 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-07-21T15:32:31.448Z,1753111951.448 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T15:32:37.516Z,1753111957.516 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T15:32:55.285Z,1753111975.285 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T15:33:10.266Z,1753111990.266 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2025-07-21T15:33:10.266Z,1753111990.266 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-21T15:33:10.409Z,1753111990.409 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-21T15:33:10.813Z,1753111990.813 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-21T15:33:10.813Z,1753111990.813 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2025-07-21T15:33:27.620Z,1753112007.620 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-21T15:28:27.4Z
2025-07-21T15:33:27.620Z,1753112007.620 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T15:33:27.620Z,1753112007.620 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T15:33:28.024Z,1753112008.024 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-07-21T15:33:35.160Z,1753112015.160 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250721T152356/Courier0004.lzma
2025-07-21T15:33:36.163Z,1753112016.163 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Courier0004.lzma.bak
2025-07-21T15:33:36.163Z,1753112016.163 [DataOverHttps](INFO): SBD MOMSN=25562564
2025-07-21T15:33:52.257Z,1753112032.257 [DataOverHttps](INFO): Sending 246 bytes from file Logs/20250721T152356/Express0005.lzma
2025-07-21T15:33:53.259Z,1753112033.259 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Express0005.lzma.bak
2025-07-21T15:33:53.259Z,1753112033.259 [DataOverHttps](INFO): SBD MOMSN=25562566
2025-07-21T15:33:53.511Z,1753112033.511 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-07-21T15:33:54.319Z,1753112034.319 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T15:33:54.319Z,1753112034.319 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T15:33:54.319Z,1753112034.319 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T15:34:08.548Z,1753112048.548 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.chargePercent
2025-07-21T15:34:08.548Z,1753112048.548 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent 0.000000 %
2025-07-21T15:34:33.914Z,1753112073.914 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-07-21T15:34:38.937Z,1753112078.937 [RudderServo](FAULT): Rudder uart error: serial timeout
2025-07-21T15:34:38.937Z,1753112078.937 [RudderServo] Communications Fault, FailCount= 1
2025-07-21T15:34:38.937Z,1753112078.937 [RudderServo](ERROR): Communications Fault
2025-07-21T15:34:38.942Z,1753112078.942 [CBIT](ERROR): Communications Fault in component: RudderServo
2025-07-21T15:34:39.137Z,1753112079.137 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-07-21T15:34:39.137Z,1753112079.137 [RudderServo](INFO): Powering down
2025-07-21T15:34:39.946Z,1753112079.946 [RudderServo](DEBUG): Initializing EZServoServo.
2025-07-21T15:34:40.062Z,1753112080.062 [RudderServo](DEBUG): Initializing RudderServo.
2025-07-21T15:34:40.066Z,1753112080.066 [CBIT](INFO): Clearing failed state for component RudderServo
2025-07-21T15:34:40.066Z,1753112080.066 [RudderServo] No Fault, FailCount= 1
2025-07-21T15:34:44.250Z,1753112084.250 [CommandExec](IMPORTANT): got command burn on
2025-07-21T15:34:44.250Z,1753112084.250 [CommandExec](IMPORTANT): Activating dropweight wire
2025-07-21T15:34:49.231Z,1753112089.231 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-07-21T15:34:49.231Z,1753112089.231 [NAL9602] Data Fault, FailCount= 1
2025-07-21T15:34:49.231Z,1753112089.231 [NAL9602](ERROR): Data Fault
2025-07-21T15:34:49.409Z,1753112089.409 [CBIT](ERROR): Data Fault in component: NAL9602
2025-07-21T15:34:49.638Z,1753112089.638 [NAL9602](INFO): Powering down
2025-07-21T15:34:50.597Z,1753112090.597 [CBIT](INFO): Clearing failed state for component NAL9602
2025-07-21T15:34:50.597Z,1753112090.597 [NAL9602] No Fault, FailCount= 1
2025-07-21T15:35:03.010Z,1753112103.010 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-07-21T15:35:19.922Z,1753112119.922 [NAL9602](INFO): Powering up NAL9602
2025-07-21T15:35:30.922Z,1753112130.922 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-07-21T15:35:30.925Z,1753112130.925 [NAL9602](INFO): NAL9602 initialized
2025-07-21T15:35:43.403Z,1753112143.403 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-07-21T15:35:55.523Z,1753112155.523 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-07-21T15:36:11.270Z,1753112171.270 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2025-07-21T15:36:11.270Z,1753112171.270 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-21T15:36:11.281Z,1753112171.281 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-21T15:36:11.734Z,1753112171.734 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-21T15:36:11.734Z,1753112171.734 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2025-07-21T15:37:11.082Z,1753112231.082 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-07-21T15:37:11.863Z,1753112231.863 [CommandExec](IMPORTANT): got command burn off
2025-07-21T15:37:11.863Z,1753112231.863 [CommandExec](IMPORTANT): Deactivating dropweight wire
2025-07-21T15:37:11.888Z,1753112231.888 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-07-21T15:37:20.311Z,1753112240.311 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T15:37:40.911Z,1753112260.911 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T15:37:48.950Z,1753112268.950 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.chargePercent
2025-07-21T15:37:48.950Z,1753112268.950 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent 1.000000 %
2025-07-21T15:38:01.515Z,1753112281.515 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T15:38:09.657Z,1753112289.657 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-07-21T15:38:21.715Z,1753112301.715 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T15:38:38.356Z,1753112318.356 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-07-21T15:38:42.319Z,1753112322.319 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T15:38:54.865Z,1753112334.865 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T15:38:54.869Z,1753112334.869 [Default:CheckIn:C.Wait] Stopped
2025-07-21T15:38:54.869Z,1753112334.869 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T15:38:54.869Z,1753112334.869 [Default:CheckIn:D] Running Loop=1
2025-07-21T15:38:55.262Z,1753112335.262 [Default:CheckIn:D] Stopped
2025-07-21T15:38:55.262Z,1753112335.262 [Default:CheckIn:E] Running Loop=1
2025-07-21T15:38:55.701Z,1753112335.701 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.693252 min
2025-07-21T15:38:55.701Z,1753112335.701 [Default:CheckIn:E] Stopped
2025-07-21T15:38:55.702Z,1753112335.702 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T15:38:55.702Z,1753112335.702 [Default:CheckIn] Stopped
2025-07-21T15:38:55.702Z,1753112335.702 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T15:38:55.702Z,1753112335.702 [Default:CheckIn](INFO): Running loop #2
2025-07-21T15:38:55.702Z,1753112335.702 [Default:CheckIn] Running Loop=2
2025-07-21T15:38:55.702Z,1753112335.702 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T15:38:55.702Z,1753112335.702 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T15:39:02.524Z,1753112342.524 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T15:39:12.249Z,1753112352.249 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2025-07-21T15:39:12.249Z,1753112352.249 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-21T15:39:12.284Z,1753112352.284 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-21T15:39:12.660Z,1753112352.660 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-21T15:39:12.660Z,1753112352.660 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2025-07-21T15:39:22.719Z,1753112362.719 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T15:39:37.729Z,1753112377.729 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-07-21T15:39:49.843Z,1753112389.843 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-07-21T15:39:55.896Z,1753112395.896 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-07-21T15:40:52.058Z,1753112452.058 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-07-21T15:41:40.495Z,1753112500.495 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-07-21T15:42:13.241Z,1753112533.241 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2025-07-21T15:42:13.241Z,1753112533.241 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-21T15:42:13.252Z,1753112533.252 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-21T15:42:13.652Z,1753112533.652 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-21T15:42:13.652Z,1753112533.652 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2025-07-21T15:42:34.268Z,1753112554.268 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-07-21T15:43:55.839Z,1753112635.839 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-21T15:38:55.7Z
2025-07-21T15:43:55.839Z,1753112635.839 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T15:43:55.839Z,1753112635.839 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T15:43:57.892Z,1753112637.892 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-07-21T15:44:02.800Z,1753112642.800 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250721T152356/Courier0007.lzma
2025-07-21T15:44:04.807Z,1753112644.807 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Courier0007.lzma.bak
2025-07-21T15:44:04.807Z,1753112644.807 [DataOverHttps](INFO): SBD MOMSN=25562609
2025-07-21T15:44:10.873Z,1753112650.873 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-07-21T15:44:20.490Z,1753112660.490 [DataOverHttps](INFO): Sending 358 bytes from file Logs/20250721T152356/Express0008.lzma
2025-07-21T15:44:21.492Z,1753112661.492 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Express0008.lzma.bak
2025-07-21T15:44:21.492Z,1753112661.492 [DataOverHttps](INFO): SBD MOMSN=25562611
2025-07-21T15:44:22.510Z,1753112662.510 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T15:44:22.511Z,1753112662.511 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T15:44:22.511Z,1753112662.511 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T15:44:49.218Z,1753112689.218 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-07-21T15:45:14.214Z,1753112714.214 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2025-07-21T15:45:14.214Z,1753112714.214 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-21T15:45:14.225Z,1753112714.225 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-21T15:45:14.640Z,1753112714.640 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-21T15:45:14.640Z,1753112714.640 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2025-07-21T15:45:31.991Z,1753112731.991 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-07-21T15:45:31.992Z,1753112731.992 [NAL9602] Data Fault, FailCount= 2
2025-07-21T15:45:31.992Z,1753112731.992 [NAL9602](ERROR): Data Fault
2025-07-21T15:45:32.009Z,1753112732.009 [CBIT](ERROR): Data Fault in component: NAL9602
2025-07-21T15:45:32.386Z,1753112732.386 [NAL9602](INFO): Powering down
2025-07-21T15:45:33.211Z,1753112733.211 [CBIT](INFO): Clearing failed state for component NAL9602
2025-07-21T15:45:33.211Z,1753112733.211 [NAL9602] No Fault, FailCount= 2
2025-07-21T15:46:02.690Z,1753112762.690 [NAL9602](INFO): Powering up NAL9602
2025-07-21T15:46:13.599Z,1753112773.599 [NAL9602](INFO): NAL9602 initialized
2025-07-21T15:46:16.071Z,1753112776.071 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-07-21T15:46:41.533Z,1753112801.533 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-07-21T15:46:47.123Z,1753112807.123 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T15:46:54.047Z,1753112814.047 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-07-21T15:46:54.809Z,1753112814.809 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T15:47:15.403Z,1753112835.403 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T15:47:34.394Z,1753112854.394 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T15:47:53.797Z,1753112873.797 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T15:48:13.984Z,1753112893.984 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T15:48:15.222Z,1753112895.222 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2025-07-21T15:48:15.222Z,1753112895.222 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-21T15:48:15.233Z,1753112895.233 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-21T15:48:15.640Z,1753112895.640 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-21T15:48:15.640Z,1753112895.640 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2025-07-21T15:48:33.784Z,1753112913.784 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T15:48:53.988Z,1753112933.988 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T15:49:23.082Z,1753112963.082 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T15:49:23.083Z,1753112963.083 [Default:CheckIn:C.Wait] Stopped
2025-07-21T15:49:23.083Z,1753112963.083 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T15:49:23.083Z,1753112963.083 [Default:CheckIn:D] Running Loop=1
2025-07-21T15:49:23.503Z,1753112963.503 [Default:CheckIn:D] Stopped
2025-07-21T15:49:23.503Z,1753112963.503 [Default:CheckIn:E] Running Loop=1
2025-07-21T15:49:23.916Z,1753112963.916 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.163932 min
2025-07-21T15:49:23.916Z,1753112963.916 [Default:CheckIn:E] Stopped
2025-07-21T15:49:23.916Z,1753112963.916 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T15:49:23.916Z,1753112963.916 [Default:CheckIn] Stopped
2025-07-21T15:49:23.917Z,1753112963.917 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T15:49:23.917Z,1753112963.917 [Default:CheckIn](INFO): Running loop #3
2025-07-21T15:49:23.917Z,1753112963.917 [Default:CheckIn] Running Loop=3
2025-07-21T15:49:23.917Z,1753112963.917 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T15:49:23.917Z,1753112963.917 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T15:49:39.291Z,1753112979.291 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-07-21T15:49:42.106Z,1753112982.106 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-07-21T15:50:22.503Z,1753113022.503 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-07-21T15:51:16.218Z,1753113076.218 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2025-07-21T15:51:16.218Z,1753113076.218 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-21T15:51:16.257Z,1753113076.257 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-21T15:51:16.615Z,1753113076.615 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-21T15:51:16.616Z,1753113076.616 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2025-07-21T15:54:17.235Z,1753113257.235 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2025-07-21T15:54:17.235Z,1753113257.235 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-21T15:54:17.245Z,1753113257.245 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-21T15:54:17.617Z,1753113257.617 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-21T15:54:17.617Z,1753113257.617 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2025-07-21T15:54:24.122Z,1753113264.122 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-21T15:49:23.9Z
2025-07-21T15:54:24.123Z,1753113264.123 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T15:54:24.123Z,1753113264.123 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T15:54:31.472Z,1753113271.472 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250721T152356/Courier0010.lzma
2025-07-21T15:54:32.475Z,1753113272.475 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Courier0010.lzma.bak
2025-07-21T15:54:32.475Z,1753113272.475 [DataOverHttps](INFO): SBD MOMSN=25562674
2025-07-21T15:54:48.185Z,1753113288.185 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250721T152356/Express0011.lzma
2025-07-21T15:54:49.187Z,1753113289.187 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Express0011.lzma.bak
2025-07-21T15:54:49.187Z,1753113289.187 [DataOverHttps](INFO): SBD MOMSN=25562679
2025-07-21T15:54:50.339Z,1753113290.339 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T15:54:50.339Z,1753113290.339 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T15:54:50.339Z,1753113290.339 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T15:55:09.304Z,1753113309.304 [BPC1](INFO): Calculating totals. Valid battery stick count: 50. Valid reserve battery stick count: 5.
2025-07-21T15:55:09.307Z,1753113309.307 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2025-07-21T15:56:15.163Z,1753113375.163 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-07-21T15:56:15.163Z,1753113375.163 [NAL9602] Data Fault, FailCount= 3
2025-07-21T15:56:15.163Z,1753113375.163 [NAL9602](ERROR): Data Fault
2025-07-21T15:56:15.206Z,1753113375.206 [CBIT](ERROR): Data Fault in component: NAL9602
2025-07-21T15:56:15.566Z,1753113375.566 [NAL9602](INFO): Powering down
2025-07-21T15:56:16.387Z,1753113376.387 [CBIT](INFO): Clearing failed state for component NAL9602
2025-07-21T15:56:16.387Z,1753113376.387 [NAL9602] No Fault, FailCount= 3
2025-07-21T15:56:45.870Z,1753113405.870 [NAL9602](INFO): Powering up NAL9602
2025-07-21T15:56:56.771Z,1753113416.771 [NAL9602](INFO): NAL9602 initialized
2025-07-21T15:57:18.222Z,1753113438.222 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2025-07-21T15:57:18.222Z,1753113438.222 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-21T15:57:18.232Z,1753113438.232 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-21T15:57:18.639Z,1753113438.639 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-21T15:57:18.639Z,1753113438.639 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2025-07-21T15:59:23.857Z,1753113563.857 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-07-21T15:59:50.907Z,1753113590.907 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T15:59:50.907Z,1753113590.907 [Default:CheckIn:C.Wait] Stopped
2025-07-21T15:59:50.907Z,1753113590.907 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T15:59:50.907Z,1753113590.907 [Default:CheckIn:D] Running Loop=1
2025-07-21T15:59:51.310Z,1753113591.310 [Default:CheckIn:D] Stopped
2025-07-21T15:59:51.311Z,1753113591.311 [Default:CheckIn:E] Running Loop=1
2025-07-21T15:59:51.716Z,1753113591.716 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.627386 min
2025-07-21T15:59:51.716Z,1753113591.716 [Default:CheckIn:E] Stopped
2025-07-21T15:59:51.716Z,1753113591.716 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T15:59:51.716Z,1753113591.716 [Default:CheckIn] Stopped
2025-07-21T15:59:51.716Z,1753113591.716 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T15:59:51.716Z,1753113591.716 [Default:CheckIn](INFO): Running loop #4
2025-07-21T15:59:51.717Z,1753113591.717 [Default:CheckIn] Running Loop=4
2025-07-21T15:59:51.717Z,1753113591.717 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T15:59:51.717Z,1753113591.717 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T16:00:19.192Z,1753113619.192 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2025-07-21T16:00:19.192Z,1753113619.192 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-21T16:00:19.211Z,1753113619.211 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-21T16:00:19.627Z,1753113619.627 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-21T16:00:19.627Z,1753113619.627 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2025-07-21T16:03:20.177Z,1753113800.177 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2025-07-21T16:03:20.178Z,1753113800.178 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-21T16:03:20.217Z,1753113800.217 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-21T16:03:20.592Z,1753113800.592 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-21T16:03:20.593Z,1753113800.593 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2025-07-21T16:04:51.903Z,1753113891.903 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-21T15:59:51.7Z
2025-07-21T16:04:51.903Z,1753113891.903 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T16:04:51.904Z,1753113891.904 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T16:04:59.493Z,1753113899.493 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20250721T152356/Courier0013.lzma
2025-07-21T16:05:07.523Z,1753113907.523 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Courier0013.lzma.bak
2025-07-21T16:05:07.523Z,1753113907.523 [DataOverHttps](INFO): SBD MOMSN=25562775
2025-07-21T16:05:48.122Z,1753113948.122 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250721T152356/Express0014.lzma
2025-07-21T16:05:53.139Z,1753113953.139 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Express0014.lzma.bak
2025-07-21T16:05:53.139Z,1753113953.139 [DataOverHttps](INFO): SBD MOMSN=25562780
2025-07-21T16:05:54.535Z,1753113954.535 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T16:05:54.535Z,1753113954.535 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T16:05:54.535Z,1753113954.535 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T16:06:21.184Z,1753113981.184 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2025-07-21T16:06:21.184Z,1753113981.184 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-21T16:06:21.202Z,1753113981.202 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-21T16:06:21.606Z,1753113981.606 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-21T16:06:21.606Z,1753113981.606 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2025-07-21T16:06:59.538Z,1753114019.538 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-07-21T16:06:59.539Z,1753114019.539 [NAL9602] Data Fault, FailCount= 4
2025-07-21T16:06:59.539Z,1753114019.539 [NAL9602](ERROR): Data Fault
2025-07-21T16:06:59.607Z,1753114019.607 [CBIT](ERROR): Data Fault in component: NAL9602
2025-07-21T16:06:59.942Z,1753114019.942 [NAL9602](INFO): Powering down
2025-07-21T16:07:00.786Z,1753114020.786 [CBIT](INFO): Clearing failed state for component NAL9602
2025-07-21T16:07:00.786Z,1753114020.786 [NAL9602] No Fault, FailCount= 4
2025-07-21T16:07:30.242Z,1753114050.242 [NAL9602](INFO): Powering up NAL9602
2025-07-21T16:07:41.155Z,1753114061.155 [NAL9602](INFO): NAL9602 initialized
2025-07-21T16:09:22.186Z,1753114162.186 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2025-07-21T16:09:22.186Z,1753114162.186 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-21T16:09:22.197Z,1753114162.197 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-21T16:09:22.589Z,1753114162.589 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-21T16:09:22.589Z,1753114162.589 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2025-07-21T16:10:55.082Z,1753114255.082 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T16:10:55.083Z,1753114255.083 [Default:CheckIn:C.Wait] Stopped
2025-07-21T16:10:55.083Z,1753114255.083 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T16:10:55.083Z,1753114255.083 [Default:CheckIn:D] Running Loop=1
2025-07-21T16:10:55.517Z,1753114255.517 [Default:CheckIn:D] Stopped
2025-07-21T16:10:55.517Z,1753114255.517 [Default:CheckIn:E] Running Loop=1
2025-07-21T16:10:55.911Z,1753114255.911 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.697498 min
2025-07-21T16:10:55.911Z,1753114255.911 [Default:CheckIn:E] Stopped
2025-07-21T16:10:55.912Z,1753114255.912 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T16:10:55.912Z,1753114255.912 [Default:CheckIn] Stopped
2025-07-21T16:10:55.912Z,1753114255.912 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T16:10:55.912Z,1753114255.912 [Default:CheckIn](INFO): Running loop #5
2025-07-21T16:10:55.912Z,1753114255.912 [Default:CheckIn] Running Loop=5
2025-07-21T16:10:55.912Z,1753114255.912 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T16:10:55.912Z,1753114255.912 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T16:12:23.149Z,1753114343.149 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2025-07-21T16:12:23.149Z,1753114343.149 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-21T16:12:23.198Z,1753114343.198 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-21T16:12:23.599Z,1753114343.599 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-21T16:12:23.600Z,1753114343.600 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2025-07-21T16:14:02.940Z,1753114442.940 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161401.00,A,3648.17135,N,12147.27573,W,0.097,216.11,210725,,,A*7A
2025-07-21T16:14:02.944Z,1753114442.944 [NAL9602](INFO): GPS fix at 20250721T161401: (36.802856, -121.787929)
2025-07-21T16:14:02.966Z,1753114442.966 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T16:14:02.966Z,1753114442.966 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T16:14:07.957Z,1753114447.957 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20250721T152356/Courier0016.lzma
2025-07-21T16:14:08.959Z,1753114448.959 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Courier0016.lzma.bak
2025-07-21T16:14:08.959Z,1753114448.959 [DataOverHttps](INFO): SBD MOMSN=25562814
2025-07-21T16:14:29.354Z,1753114469.354 [DataOverHttps](INFO): Sending 371 bytes from file Logs/20250721T152356/Express0017.lzma
2025-07-21T16:14:30.355Z,1753114470.355 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Express0017.lzma.bak
2025-07-21T16:14:30.355Z,1753114470.355 [DataOverHttps](INFO): SBD MOMSN=25562823
2025-07-21T16:14:31.434Z,1753114471.434 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T16:14:31.434Z,1753114471.434 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T16:14:31.434Z,1753114471.434 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T16:14:35.450Z,1753114475.450 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T16:19:32.000Z,1753114772.000 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T16:19:32.000Z,1753114772.000 [Default:CheckIn:C.Wait] Stopped
2025-07-21T16:19:32.000Z,1753114772.000 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T16:19:32.000Z,1753114772.000 [Default:CheckIn:D] Running Loop=1
2025-07-21T16:19:32.410Z,1753114772.410 [Default:CheckIn:D] Stopped
2025-07-21T16:19:32.410Z,1753114772.410 [Default:CheckIn:E] Running Loop=1
2025-07-21T16:19:32.822Z,1753114772.822 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 51.312378 min
2025-07-21T16:19:32.822Z,1753114772.822 [Default:CheckIn:E] Stopped
2025-07-21T16:19:32.822Z,1753114772.822 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T16:19:32.822Z,1753114772.822 [Default:CheckIn] Stopped
2025-07-21T16:19:32.822Z,1753114772.822 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T16:19:32.822Z,1753114772.822 [Default:CheckIn](INFO): Running loop #6
2025-07-21T16:19:32.822Z,1753114772.822 [Default:CheckIn] Running Loop=6
2025-07-21T16:19:32.822Z,1753114772.822 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T16:19:32.822Z,1753114772.822 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T16:19:34.824Z,1753114774.824 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161933.00,A,3648.16550,N,12147.28536,W,0.505,216.11,210725,,,A*70
2025-07-21T16:19:34.826Z,1753114774.826 [NAL9602](INFO): GPS fix at 20250721T161933: (36.802758, -121.788089)
2025-07-21T16:19:34.862Z,1753114774.862 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T16:19:34.862Z,1753114774.862 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T16:19:42.624Z,1753114782.624 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250721T152356/Courier0019.lzma
2025-07-21T16:19:43.628Z,1753114783.628 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Courier0019.lzma.bak
2025-07-21T16:19:43.628Z,1753114783.628 [DataOverHttps](INFO): SBD MOMSN=25562858
2025-07-21T16:19:59.597Z,1753114799.597 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20250721T152356/Express0020.lzma
2025-07-21T16:20:00.599Z,1753114800.599 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Express0020.lzma.bak
2025-07-21T16:20:00.599Z,1753114800.599 [DataOverHttps](INFO): SBD MOMSN=25562862
2025-07-21T16:20:01.905Z,1753114801.905 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T16:20:01.905Z,1753114801.905 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T16:20:01.905Z,1753114801.905 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T16:23:25.509Z,1753115005.509 [NAL9602](INFO): SBD MO Status=2, MOMSN=63745, MT Status=2, MTMSN=0
2025-07-21T16:23:25.510Z,1753115005.510 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-21T16:24:28.132Z,1753115068.132 [NAL9602](INFO): SBD MO Status=2, MOMSN=63745, MT Status=2, MTMSN=0
2025-07-21T16:24:28.132Z,1753115068.132 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-21T16:24:45.096Z,1753115085.096 [NAL9602](INFO): SBD MO Status=0, MOMSN=63745, MT Status=0, MTMSN=0
2025-07-21T16:24:45.096Z,1753115085.096 [NAL9602](INFO): No messages in MT queue
2025-07-21T16:25:02.485Z,1753115102.485 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T16:25:02.485Z,1753115102.485 [Default:CheckIn:C.Wait] Stopped
2025-07-21T16:25:02.485Z,1753115102.485 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T16:25:02.485Z,1753115102.485 [Default:CheckIn:D] Running Loop=1
2025-07-21T16:25:02.894Z,1753115102.894 [Default:CheckIn:D] Stopped
2025-07-21T16:25:02.895Z,1753115102.895 [Default:CheckIn:E] Running Loop=1
2025-07-21T16:25:03.289Z,1753115103.289 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.820455 min
2025-07-21T16:25:03.289Z,1753115103.289 [Default:CheckIn:E] Stopped
2025-07-21T16:25:03.289Z,1753115103.289 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T16:25:03.289Z,1753115103.289 [Default:CheckIn] Stopped
2025-07-21T16:25:03.290Z,1753115103.290 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T16:25:03.290Z,1753115103.290 [Default:CheckIn](INFO): Running loop #7
2025-07-21T16:25:03.290Z,1753115103.290 [Default:CheckIn] Running Loop=7
2025-07-21T16:25:03.290Z,1753115103.290 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T16:25:03.290Z,1753115103.290 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T16:25:05.297Z,1753115105.297 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162504.00,A,3648.17135,N,12147.28025,W,0.700,216.11,210725,,,A*7D
2025-07-21T16:25:05.299Z,1753115105.299 [NAL9602](INFO): GPS fix at 20250721T162504: (36.802856, -121.788004)
2025-07-21T16:25:05.310Z,1753115105.310 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T16:25:05.310Z,1753115105.310 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T16:25:12.729Z,1753115112.729 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250721T152356/Courier0022.lzma
2025-07-21T16:25:13.731Z,1753115113.731 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Courier0022.lzma.bak
2025-07-21T16:25:13.731Z,1753115113.731 [DataOverHttps](INFO): SBD MOMSN=25562881
2025-07-21T16:25:29.665Z,1753115129.665 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20250721T152356/Express0023.lzma
2025-07-21T16:25:30.667Z,1753115130.667 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Express0023.lzma.bak
2025-07-21T16:25:30.667Z,1753115130.667 [DataOverHttps](INFO): SBD MOMSN=25562884
2025-07-21T16:25:31.992Z,1753115131.992 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T16:25:31.992Z,1753115131.992 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T16:25:31.992Z,1753115131.992 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T16:25:37.614Z,1753115137.614 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T16:30:32.557Z,1753115432.557 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T16:30:32.557Z,1753115432.557 [Default:CheckIn:C.Wait] Stopped
2025-07-21T16:30:32.558Z,1753115432.558 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T16:30:32.558Z,1753115432.558 [Default:CheckIn:D] Running Loop=1
2025-07-21T16:30:32.960Z,1753115432.960 [Default:CheckIn:D] Stopped
2025-07-21T16:30:32.960Z,1753115432.960 [Default:CheckIn:E] Running Loop=1
2025-07-21T16:30:33.368Z,1753115433.368 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.321554 min
2025-07-21T16:30:33.368Z,1753115433.368 [Default:CheckIn:E] Stopped
2025-07-21T16:30:33.368Z,1753115433.368 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T16:30:33.369Z,1753115433.369 [Default:CheckIn] Stopped
2025-07-21T16:30:33.369Z,1753115433.369 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T16:30:33.369Z,1753115433.369 [Default:CheckIn](INFO): Running loop #8
2025-07-21T16:30:33.369Z,1753115433.369 [Default:CheckIn] Running Loop=8
2025-07-21T16:30:33.369Z,1753115433.369 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T16:30:33.369Z,1753115433.369 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T16:30:35.372Z,1753115435.372 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163034.00,A,3648.18169,N,12147.26709,W,0.214,216.11,210725,,,A*7B
2025-07-21T16:30:35.374Z,1753115435.374 [NAL9602](INFO): GPS fix at 20250721T163034: (36.803028, -121.787785)
2025-07-21T16:30:35.409Z,1753115435.409 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T16:30:35.410Z,1753115435.410 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T16:30:42.629Z,1753115442.629 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250721T152356/Courier0025.lzma
2025-07-21T16:30:54.675Z,1753115454.675 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Courier0025.lzma.bak
2025-07-21T16:30:54.675Z,1753115454.675 [DataOverHttps](INFO): SBD MOMSN=25562907
2025-07-21T16:31:08.504Z,1753115468.504 [NAL9602](INFO): SBD MO Status=0, MOMSN=63746, MT Status=0, MTMSN=0
2025-07-21T16:31:08.504Z,1753115468.504 [NAL9602](INFO): No messages in MT queue
2025-07-21T16:31:30.129Z,1753115490.129 [DataOverHttps](INFO): Sending 162 bytes from file Logs/20250721T152356/Express0026.lzma
2025-07-21T16:31:36.151Z,1753115496.151 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Express0026.lzma.bak
2025-07-21T16:31:36.151Z,1753115496.151 [DataOverHttps](INFO): SBD MOMSN=25562924
2025-07-21T16:31:37.194Z,1753115497.194 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T16:31:37.194Z,1753115497.194 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T16:31:37.194Z,1753115497.194 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T16:31:39.218Z,1753115499.218 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T16:35:18.811Z,1753115718.811 [DataOverHttps](IMPORTANT): SBD MTMSN=20250721T163517
2025-07-21T16:35:26.221Z,1753115726.221 [DataOverHttps](INFO): Received command: failc
2025-07-21T16:35:26.290Z,1753115726.290 [CommandExec](IMPORTANT): got command failComponent
2025-07-21T16:35:26.290Z,1753115726.290 [CommandExec](IMPORTANT): Failed components:
2025-07-21T16:35:26.290Z,1753115726.290 [CommandExec](IMPORTANT): No failed Components.
2025-07-21T16:35:27.222Z,1753115727.222 [DataOverHttps](IMPORTANT): SBD MTMSN=20250721T163526
2025-07-21T16:35:34.745Z,1753115734.745 [DataOverHttps](INFO): Received command: ibit
2025-07-21T16:35:34.838Z,1753115734.838 [CommandExec](IMPORTANT): got command ibit
2025-07-21T16:35:35.159Z,1753115735.159 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-07-21T16:35:35.159Z,1753115735.159 [IBIT](IMPORTANT): Beginning control surface checks.
2025-07-21T16:35:35.163Z,1753115735.163 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-07-21T16:35:36.768Z,1753115736.768 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163536.00,A,3648.14577,N,12147.22737,W,0.078,43.91,210725,,,D*45
2025-07-21T16:35:36.770Z,1753115736.770 [NAL9602](INFO): GPS fix at 20250721T163536: (36.802430, -121.787123)
2025-07-21T16:35:39.766Z,1753115739.766 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002665
CHAN A1 (24V): 0.000524
CHAN A2 (12V): -0.002266
CHAN A3 (5V): -0.001860
CHAN B0 (3.3V): -0.000492
CHAN B1 (3.15aV): -0.000664
CHAN B2 (3.15bV): 0.000033
CHAN B3 (GND): 0.000100
OPEN: -0.000673
Full Scale: +/- 1 mA
2025-07-21T16:35:58.972Z,1753115758.972 [NAL9602](INFO): SBD MO Status=2, MOMSN=63747, MT Status=2, MTMSN=0
2025-07-21T16:35:58.972Z,1753115758.972 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-21T16:36:09.483Z,1753115769.483 [NAL9602](INFO): SBD MO Status=0, MOMSN=63747, MT Status=0, MTMSN=0
2025-07-21T16:36:09.484Z,1753115769.484 [NAL9602](INFO): No messages in MT queue
2025-07-21T16:36:10.691Z,1753115770.691 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163609.00,A,3648.14509,N,12147.22656,W,0.039,43.91,210725,,,D*40
2025-07-21T16:36:10.693Z,1753115770.693 [NAL9602](INFO): GPS fix at 20250721T163609: (36.802418, -121.787109)
2025-07-21T16:36:13.925Z,1753115773.925 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163613.00,A,3648.14519,N,12147.22677,W,0.039,43.91,210725,,,D*49
2025-07-21T16:36:13.927Z,1753115773.927 [NAL9602](INFO): GPS fix at 20250721T163613: (36.802420, -121.787113)
2025-07-21T16:36:17.152Z,1753115777.152 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163616.00,A,3648.14527,N,12147.22691,W,0.058,43.91,210725,,,D*4E
2025-07-21T16:36:17.154Z,1753115777.154 [NAL9602](INFO): GPS fix at 20250721T163616: (36.802421, -121.787115)
2025-07-21T16:36:19.980Z,1753115779.980 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163619.00,A,3648.14535,N,12147.22706,W,0.019,43.91,210725,,,D*48
2025-07-21T16:36:19.982Z,1753115779.982 [NAL9602](INFO): GPS fix at 20250721T163619: (36.802422, -121.787118)
2025-07-21T16:36:21.265Z,1753115781.265 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 3
Latitude: 36.802422 Longitude: -121.787117
2025-07-21T16:36:21.660Z,1753115781.660 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.572001
2025-07-21T16:36:21.661Z,1753115781.661 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2025-07-21T16:36:21.661Z,1753115781.661 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2025-07-21T16:36:22.024Z,1753115782.024 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2025-07-21T16:36:22.024Z,1753115782.024 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2025-07-21T16:36:22.025Z,1753115782.025 [IBIT](IMPORTANT): Pressure:9.544440 PSI
2025-07-21T16:36:22.025Z,1753115782.025 [IBIT](IMPORTANT): Humidity:17.629105 %
2025-07-21T16:36:22.448Z,1753115782.448 [IBIT](IMPORTANT): Vehicle Pitch:-1.788135 degrees
2025-07-21T16:36:22.448Z,1753115782.448 [IBIT](IMPORTANT): Vehicle Roll:-1.771343 degrees
2025-07-21T16:36:22.453Z,1753115782.453 [IBIT](IMPORTANT): Vehicle Heading:20.174906 degrees
2025-07-21T16:36:22.854Z,1753115782.854 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2025-07-21T16:36:22.854Z,1753115782.854 [IBIT](IMPORTANT): buoyancyNeutral: 249.798752 cc
2025-07-21T16:36:22.854Z,1753115782.854 [IBIT](IMPORTANT): massDefault: 1.272961 cm
2025-07-21T16:36:22.855Z,1753115782.855 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2025-07-21T16:36:22.855Z,1753115782.855 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2025-07-21T16:36:22.855Z,1753115782.855 [IBIT](IMPORTANT): IBIT PASSED
2025-07-21T16:36:37.789Z,1753115797.789 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T16:36:37.789Z,1753115797.789 [Default:CheckIn:C.Wait] Stopped
2025-07-21T16:36:37.790Z,1753115797.790 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T16:36:37.790Z,1753115797.790 [Default:CheckIn:D] Running Loop=1
2025-07-21T16:36:38.174Z,1753115798.174 [Default:CheckIn:D] Stopped
2025-07-21T16:36:38.174Z,1753115798.174 [Default:CheckIn:E] Running Loop=1
2025-07-21T16:36:38.575Z,1753115798.575 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 68.408447 min
2025-07-21T16:36:38.575Z,1753115798.575 [Default:CheckIn:E] Stopped
2025-07-21T16:36:38.576Z,1753115798.576 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T16:36:38.576Z,1753115798.576 [Default:CheckIn] Stopped
2025-07-21T16:36:38.576Z,1753115798.576 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T16:36:38.576Z,1753115798.576 [Default:CheckIn](INFO): Running loop #9
2025-07-21T16:36:38.576Z,1753115798.576 [Default:CheckIn] Running Loop=9
2025-07-21T16:36:38.576Z,1753115798.576 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T16:36:38.576Z,1753115798.576 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T16:36:40.598Z,1753115800.598 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163639.00,A,3648.14556,N,12147.22774,W,0.019,43.91,210725,,,D*4A
2025-07-21T16:36:40.600Z,1753115800.600 [NAL9602](INFO): GPS fix at 20250721T163639: (36.802426, -121.787129)
2025-07-21T16:36:40.611Z,1753115800.611 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T16:36:40.611Z,1753115800.611 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T16:36:48.761Z,1753115808.761 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250721T152356/Courier0028.lzma
2025-07-21T16:36:49.763Z,1753115809.763 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Courier0028.lzma.bak
2025-07-21T16:36:49.763Z,1753115809.763 [DataOverHttps](INFO): SBD MOMSN=25562947
2025-07-21T16:37:06.028Z,1753115826.028 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250721T152356/Courier0031.lzma
2025-07-21T16:37:08.035Z,1753115828.035 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Courier0031.lzma.bak
2025-07-21T16:37:08.035Z,1753115828.035 [DataOverHttps](INFO): SBD MOMSN=25562955
2025-07-21T16:37:12.514Z,1753115832.514 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T16:37:24.319Z,1753115844.319 [DataOverHttps](INFO): Sending 980 bytes from file Logs/20250721T152356/Express0029.lzma
2025-07-21T16:37:25.319Z,1753115845.319 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Express0029.lzma.bak
2025-07-21T16:37:25.319Z,1753115845.319 [DataOverHttps](INFO): SBD MOMSN=25562962
2025-07-21T16:37:41.885Z,1753115861.885 [DataOverHttps](INFO): Sending 60 bytes from file Logs/20250721T152356/Express0032.lzma
2025-07-21T16:37:43.891Z,1753115863.891 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Express0032.lzma.bak
2025-07-21T16:37:43.891Z,1753115863.891 [DataOverHttps](INFO): SBD MOMSN=25563034
2025-07-21T16:37:45.255Z,1753115865.255 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T16:37:45.255Z,1753115865.255 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T16:37:45.255Z,1753115865.255 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T16:42:46.012Z,1753116166.012 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T16:42:46.012Z,1753116166.012 [Default:CheckIn:C.Wait] Stopped
2025-07-21T16:42:46.012Z,1753116166.012 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T16:42:46.013Z,1753116166.013 [Default:CheckIn:D] Running Loop=1
2025-07-21T16:42:46.408Z,1753116166.408 [Default:CheckIn:D] Stopped
2025-07-21T16:42:46.408Z,1753116166.408 [Default:CheckIn:E] Running Loop=1
2025-07-21T16:42:46.816Z,1753116166.816 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 74.545679 min
2025-07-21T16:42:46.817Z,1753116166.817 [Default:CheckIn:E] Stopped
2025-07-21T16:42:46.817Z,1753116166.817 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T16:42:46.817Z,1753116166.817 [Default:CheckIn] Stopped
2025-07-21T16:42:46.817Z,1753116166.817 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T16:42:46.817Z,1753116166.817 [Default:CheckIn](INFO): Running loop #10
2025-07-21T16:42:46.817Z,1753116166.817 [Default:CheckIn] Running Loop=10
2025-07-21T16:42:46.817Z,1753116166.817 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T16:42:46.818Z,1753116166.818 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T16:42:48.828Z,1753116168.828 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164248.00,A,3648.14658,N,12147.22823,W,0.019,43.91,210725,,,D*4F
2025-07-21T16:42:48.831Z,1753116168.831 [NAL9602](INFO): GPS fix at 20250721T164248: (36.802443, -121.787137)
2025-07-21T16:42:48.842Z,1753116168.842 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T16:42:48.842Z,1753116168.842 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T16:42:57.004Z,1753116177.004 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250721T152356/Courier0034.lzma
2025-07-21T16:42:58.007Z,1753116178.007 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Courier0034.lzma.bak
2025-07-21T16:42:58.007Z,1753116178.007 [DataOverHttps](INFO): SBD MOMSN=25563063
2025-07-21T16:43:04.179Z,1753116184.179 [NAL9602](INFO): SBD MO Status=0, MOMSN=63748, MT Status=0, MTMSN=0
2025-07-21T16:43:04.180Z,1753116184.180 [NAL9602](INFO): No messages in MT queue
2025-07-21T16:43:14.449Z,1753116194.449 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250721T152356/Express0035.lzma
2025-07-21T16:43:15.451Z,1753116195.451 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Express0035.lzma.bak
2025-07-21T16:43:15.451Z,1753116195.451 [DataOverHttps](INFO): SBD MOMSN=25563066
2025-07-21T16:43:16.708Z,1753116196.708 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T16:43:16.708Z,1753116196.708 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T16:43:16.708Z,1753116196.708 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T16:43:34.880Z,1753116214.880 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T16:48:17.472Z,1753116497.472 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T16:48:17.472Z,1753116497.472 [Default:CheckIn:C.Wait] Stopped
2025-07-21T16:48:17.472Z,1753116497.472 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T16:48:17.473Z,1753116497.473 [Default:CheckIn:D] Running Loop=1
2025-07-21T16:48:17.816Z,1753116497.816 [Default:CheckIn:D] Stopped
2025-07-21T16:48:17.816Z,1753116497.816 [Default:CheckIn:E] Running Loop=1
2025-07-21T16:48:18.214Z,1753116498.214 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 80.069141 min
2025-07-21T16:48:18.215Z,1753116498.215 [Default:CheckIn:E] Stopped
2025-07-21T16:48:18.215Z,1753116498.215 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T16:48:18.215Z,1753116498.215 [Default:CheckIn] Stopped
2025-07-21T16:48:18.215Z,1753116498.215 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T16:48:18.215Z,1753116498.215 [Default:CheckIn](INFO): Running loop #11
2025-07-21T16:48:18.215Z,1753116498.215 [Default:CheckIn] Running Loop=11
2025-07-21T16:48:18.215Z,1753116498.215 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T16:48:18.215Z,1753116498.215 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T16:48:20.232Z,1753116500.232 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164819.00,A,3648.14693,N,12147.22947,W,0.019,43.91,210725,,,D*45
2025-07-21T16:48:20.234Z,1753116500.234 [NAL9602](INFO): GPS fix at 20250721T164819: (36.802449, -121.787158)
2025-07-21T16:48:20.254Z,1753116500.254 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T16:48:20.254Z,1753116500.254 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T16:48:30.917Z,1753116510.917 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250721T152356/Courier0037.lzma
2025-07-21T16:48:31.919Z,1753116511.919 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Courier0037.lzma.bak
2025-07-21T16:48:31.919Z,1753116511.919 [DataOverHttps](INFO): SBD MOMSN=25563104
2025-07-21T16:48:32.000Z,1753116513.000 [NAL9602](INFO): SBD MO Status=0, MOMSN=63749, MT Status=0, MTMSN=0
2025-07-21T16:48:32.000Z,1753116513.000 [NAL9602](INFO): No messages in MT queue
2025-07-21T16:48:48.445Z,1753116528.445 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250721T152356/Express0038.lzma
2025-07-21T16:48:49.448Z,1753116529.448 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Express0038.lzma.bak
2025-07-21T16:48:49.448Z,1753116529.448 [DataOverHttps](INFO): SBD MOMSN=25563107
2025-07-21T16:48:50.784Z,1753116530.784 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T16:48:50.784Z,1753116530.784 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T16:48:50.784Z,1753116530.784 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T16:49:03.702Z,1753116543.702 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T16:53:51.374Z,1753116831.374 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T16:53:51.374Z,1753116831.374 [Default:CheckIn:C.Wait] Stopped
2025-07-21T16:53:51.374Z,1753116831.374 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T16:53:51.374Z,1753116831.374 [Default:CheckIn:D] Running Loop=1
2025-07-21T16:53:51.785Z,1753116831.785 [Default:CheckIn:D] Stopped
2025-07-21T16:53:51.785Z,1753116831.785 [Default:CheckIn:E] Running Loop=1
2025-07-21T16:53:52.190Z,1753116832.190 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 85.635286 min
2025-07-21T16:53:52.190Z,1753116832.190 [Default:CheckIn:E] Stopped
2025-07-21T16:53:52.190Z,1753116832.190 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T16:53:52.190Z,1753116832.190 [Default:CheckIn] Stopped
2025-07-21T16:53:52.190Z,1753116832.190 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T16:53:52.190Z,1753116832.190 [Default:CheckIn](INFO): Running loop #12
2025-07-21T16:53:52.190Z,1753116832.190 [Default:CheckIn] Running Loop=12
2025-07-21T16:53:52.191Z,1753116832.191 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T16:53:52.191Z,1753116832.191 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T16:53:54.202Z,1753116834.202 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165353.00,A,3648.14596,N,12147.22793,W,0.019,43.91,210725,,,D*40
2025-07-21T16:53:54.204Z,1753116834.204 [NAL9602](INFO): GPS fix at 20250721T165353: (36.802433, -121.787132)
2025-07-21T16:53:54.215Z,1753116834.215 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T16:53:54.215Z,1753116834.215 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T16:53:55.016Z,1753116835.016 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T16:53:55.017Z,1753116835.017 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T16:53:55.017Z,1753116835.017 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T16:54:32.171Z,1753116872.171 [NAL9602](INFO): SBD MO Status=2, MOMSN=63750, MT Status=2, MTMSN=0
2025-07-21T16:54:32.172Z,1753116872.172 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-21T16:55:11.365Z,1753116911.365 [NAL9602](INFO): SBD MO Status=0, MOMSN=63750, MT Status=0, MTMSN=0
2025-07-21T16:55:11.365Z,1753116911.365 [NAL9602](INFO): No messages in MT queue
2025-07-21T16:55:42.058Z,1753116942.058 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T16:56:15.590Z,1753116975.590 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T16:56:27.713Z,1753116987.713 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T16:56:39.433Z,1753116999.433 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T16:56:51.146Z,1753117011.146 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T16:57:02.053Z,1753117022.053 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T16:57:12.970Z,1753117032.970 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T16:57:24.285Z,1753117044.285 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T16:57:35.585Z,1753117055.585 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T16:58:55.603Z,1753117135.603 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T16:58:55.603Z,1753117135.603 [Default:CheckIn:C.Wait] Stopped
2025-07-21T16:58:55.603Z,1753117135.603 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T16:58:55.603Z,1753117135.603 [Default:CheckIn:D] Running Loop=1
2025-07-21T16:58:56.019Z,1753117136.019 [Default:CheckIn:D] Stopped
2025-07-21T16:58:56.019Z,1753117136.019 [Default:CheckIn:E] Running Loop=1
2025-07-21T16:58:56.406Z,1753117136.406 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 90.705859 min
2025-07-21T16:58:56.406Z,1753117136.406 [Default:CheckIn:E] Stopped
2025-07-21T16:58:56.406Z,1753117136.406 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T16:58:56.406Z,1753117136.406 [Default:CheckIn] Stopped
2025-07-21T16:58:56.406Z,1753117136.406 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T16:58:56.406Z,1753117136.406 [Default:CheckIn](INFO): Running loop #13
2025-07-21T16:58:56.406Z,1753117136.406 [Default:CheckIn] Running Loop=13
2025-07-21T16:58:56.406Z,1753117136.406 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T16:58:56.406Z,1753117136.406 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T16:58:58.432Z,1753117138.432 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165857.00,A,3648.14441,N,12147.22988,W,0.019,43.91,210725,,,D*40
2025-07-21T16:58:58.434Z,1753117138.434 [NAL9602](INFO): GPS fix at 20250721T165857: (36.802407, -121.787165)
2025-07-21T16:58:58.445Z,1753117138.445 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T16:58:58.445Z,1753117138.445 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T16:59:06.461Z,1753117146.461 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250721T152356/Courier0040.lzma
2025-07-21T16:59:12.172Z,1753117152.172 [NAL9602](INFO): SBD MO Status=0, MOMSN=63751, MT Status=0, MTMSN=0
2025-07-21T16:59:12.172Z,1753117152.172 [NAL9602](INFO): No messages in MT queue
2025-07-21T16:59:24.531Z,1753117164.531 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Courier0040.lzma.bak
2025-07-21T16:59:24.531Z,1753117164.531 [DataOverHttps](INFO): SBD MOMSN=25563143
2025-07-21T16:59:42.866Z,1753117182.866 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T16:59:49.813Z,1753117189.813 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250721T152356/Courier0043.lzma
2025-07-21T16:59:50.815Z,1753117190.815 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Courier0043.lzma.bak
2025-07-21T16:59:50.815Z,1753117190.815 [DataOverHttps](INFO): SBD MOMSN=25563150
2025-07-21T17:00:09.116Z,1753117209.116 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:00:22.463Z,1753117222.463 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:00:35.780Z,1753117235.780 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:00:49.113Z,1753117249.113 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:01:02.447Z,1753117262.447 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:01:15.777Z,1753117275.777 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:01:16.657Z,1753117276.657 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250721T152356/Express0041.lzma
2025-07-21T17:01:29.108Z,1753117289.108 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:01:32.735Z,1753117292.735 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Express0041.lzma.bak
2025-07-21T17:01:32.735Z,1753117292.735 [DataOverHttps](INFO): SBD MOMSN=25563154
2025-07-21T17:01:43.254Z,1753117303.254 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:01:56.583Z,1753117316.583 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:02:09.914Z,1753117329.914 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:02:22.996Z,1753117342.996 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:02:29.063Z,1753117349.063 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7).
2025-07-21T17:02:43.597Z,1753117363.597 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250721T152356/Express0044.lzma
2025-07-21T17:02:52.631Z,1753117372.631 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Express0044.lzma.bak
2025-07-21T17:02:52.631Z,1753117372.631 [DataOverHttps](INFO): SBD MOMSN=25563159
2025-07-21T17:02:53.718Z,1753117373.718 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T17:02:53.718Z,1753117373.718 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T17:02:53.718Z,1753117373.718 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T17:07:54.307Z,1753117674.307 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T17:07:54.307Z,1753117674.307 [Default:CheckIn:C.Wait] Stopped
2025-07-21T17:07:54.307Z,1753117674.307 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T17:07:54.307Z,1753117674.307 [Default:CheckIn:D] Running Loop=1
2025-07-21T17:07:54.717Z,1753117674.717 [Default:CheckIn:D] Stopped
2025-07-21T17:07:54.717Z,1753117674.717 [Default:CheckIn:E] Running Loop=1
2025-07-21T17:07:55.133Z,1753117675.133 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 99.684155 min
2025-07-21T17:07:55.133Z,1753117675.133 [Default:CheckIn:E] Stopped
2025-07-21T17:07:55.134Z,1753117675.134 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T17:07:55.134Z,1753117675.134 [Default:CheckIn] Stopped
2025-07-21T17:07:55.134Z,1753117675.134 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T17:07:55.134Z,1753117675.134 [Default:CheckIn](INFO): Running loop #14
2025-07-21T17:07:55.134Z,1753117675.134 [Default:CheckIn] Running Loop=14
2025-07-21T17:07:55.134Z,1753117675.134 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T17:07:55.134Z,1753117675.134 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T17:07:57.119Z,1753117677.119 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170756.00,A,3648.14363,N,12147.22962,W,0.019,43.91,210725,,,D*49
2025-07-21T17:07:57.121Z,1753117677.121 [NAL9602](INFO): GPS fix at 20250721T170756: (36.802394, -121.787160)
2025-07-21T17:07:57.132Z,1753117677.132 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T17:07:57.132Z,1753117677.132 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T17:08:10.444Z,1753117690.444 [NAL9602](INFO): SBD MO Status=0, MOMSN=63752, MT Status=0, MTMSN=0
2025-07-21T17:08:10.444Z,1753117690.444 [NAL9602](INFO): No messages in MT queue
2025-07-21T17:08:11.065Z,1753117691.065 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250721T152356/Courier0046.lzma
2025-07-21T17:08:20.103Z,1753117700.103 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Courier0046.lzma.bak
2025-07-21T17:08:20.103Z,1753117700.103 [DataOverHttps](INFO): SBD MOMSN=25563175
2025-07-21T17:08:40.978Z,1753117720.978 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T17:08:56.797Z,1753117736.797 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250721T152356/Express0047.lzma
2025-07-21T17:09:07.227Z,1753117747.227 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-07-21T17:09:11.855Z,1753117751.855 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Express0047.lzma.bak
2025-07-21T17:09:11.855Z,1753117751.855 [DataOverHttps](INFO): SBD MOMSN=25563178
2025-07-21T17:09:12.898Z,1753117752.898 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T17:09:12.898Z,1753117752.898 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T17:09:12.898Z,1753117752.898 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T17:09:46.410Z,1753117786.410 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:10:00.145Z,1753117800.145 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:10:13.485Z,1753117813.485 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:10:26.853Z,1753117826.853 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:10:40.190Z,1753117840.190 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:10:53.521Z,1753117853.521 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:11:06.855Z,1753117866.855 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:11:19.784Z,1753117879.784 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:11:33.923Z,1753117893.923 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:11:47.253Z,1753117907.253 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:11:48.178Z,1753117908.178 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-07-21T17:12:00.591Z,1753117920.591 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:14:13.556Z,1753118053.556 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T17:14:13.556Z,1753118053.556 [Default:CheckIn:C.Wait] Stopped
2025-07-21T17:14:13.556Z,1753118053.556 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T17:14:13.557Z,1753118053.557 [Default:CheckIn:D] Running Loop=1
2025-07-21T17:14:13.972Z,1753118053.972 [Default:CheckIn:D] Stopped
2025-07-21T17:14:13.972Z,1753118053.972 [Default:CheckIn:E] Running Loop=1
2025-07-21T17:14:14.374Z,1753118054.374 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 106.005078 min
2025-07-21T17:14:14.374Z,1753118054.374 [Default:CheckIn:E] Stopped
2025-07-21T17:14:14.375Z,1753118054.375 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T17:14:14.375Z,1753118054.375 [Default:CheckIn] Stopped
2025-07-21T17:14:14.375Z,1753118054.375 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T17:14:14.375Z,1753118054.375 [Default:CheckIn](INFO): Running loop #15
2025-07-21T17:14:14.375Z,1753118054.375 [Default:CheckIn] Running Loop=15
2025-07-21T17:14:14.375Z,1753118054.375 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T17:14:14.375Z,1753118054.375 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T17:14:16.375Z,1753118056.375 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171415.00,A,3648.15196,N,12147.22635,W,0.019,26.59,210725,,,D*4F
2025-07-21T17:14:16.378Z,1753118056.378 [NAL9602](INFO): GPS fix at 20250721T171415: (36.802533, -121.787106)
2025-07-21T17:14:16.404Z,1753118056.404 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T17:14:16.405Z,1753118056.405 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T17:14:27.249Z,1753118067.249 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250721T152356/Courier0049.lzma
2025-07-21T17:14:28.568Z,1753118068.568 [NAL9602](INFO): SBD MO Status=0, MOMSN=63753, MT Status=0, MTMSN=0
2025-07-21T17:14:28.568Z,1753118068.568 [NAL9602](INFO): No messages in MT queue
2025-07-21T17:14:29.369Z,1753118069.369 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:14:35.038Z,1753118075.038 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:14:42.307Z,1753118082.307 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Courier0049.lzma.bak
2025-07-21T17:14:42.307Z,1753118082.307 [DataOverHttps](INFO): SBD MOMSN=25563210
2025-07-21T17:14:49.177Z,1753118089.177 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:14:59.271Z,1753118099.271 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T17:15:03.710Z,1753118103.710 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:15:17.852Z,1753118117.852 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:15:31.113Z,1753118131.113 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250721T152356/Express0050.lzma
2025-07-21T17:15:31.992Z,1753118131.992 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:15:36.131Z,1753118136.131 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Express0050.lzma.bak
2025-07-21T17:15:36.131Z,1753118136.131 [DataOverHttps](INFO): SBD MOMSN=25563214
2025-07-21T17:15:37.254Z,1753118137.254 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T17:15:37.254Z,1753118137.254 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T17:15:37.254Z,1753118137.254 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T17:15:46.128Z,1753118146.128 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:16:00.672Z,1753118160.672 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:16:14.814Z,1753118174.814 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:16:28.992Z,1753118188.992 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:16:43.136Z,1753118203.136 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:19:24.785Z,1753118364.785 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:19:38.117Z,1753118378.117 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:19:51.853Z,1753118391.853 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:20:05.185Z,1753118405.185 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:20:18.525Z,1753118418.525 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:20:31.861Z,1753118431.861 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:20:37.933Z,1753118437.933 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T17:20:37.933Z,1753118437.933 [Default:CheckIn:C.Wait] Stopped
2025-07-21T17:20:37.933Z,1753118437.933 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T17:20:37.933Z,1753118437.933 [Default:CheckIn:D] Running Loop=1
2025-07-21T17:20:38.339Z,1753118438.339 [Default:CheckIn:D] Stopped
2025-07-21T17:20:38.339Z,1753118438.339 [Default:CheckIn:E] Running Loop=1
2025-07-21T17:20:38.753Z,1753118438.753 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 112.411190 min
2025-07-21T17:20:38.753Z,1753118438.753 [Default:CheckIn:E] Stopped
2025-07-21T17:20:38.753Z,1753118438.753 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T17:20:38.753Z,1753118438.753 [Default:CheckIn] Stopped
2025-07-21T17:20:38.753Z,1753118438.753 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T17:20:38.754Z,1753118438.754 [Default:CheckIn](INFO): Running loop #16
2025-07-21T17:20:38.754Z,1753118438.754 [Default:CheckIn] Running Loop=16
2025-07-21T17:20:38.754Z,1753118438.754 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T17:20:38.754Z,1753118438.754 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T17:20:40.756Z,1753118440.756 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172040.00,A,3648.15197,N,12147.22049,W,0.019,85.05,210725,,,D*44
2025-07-21T17:20:40.758Z,1753118440.758 [NAL9602](INFO): GPS fix at 20250721T172040: (36.802533, -121.787008)
2025-07-21T17:20:40.793Z,1753118440.793 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T17:20:40.793Z,1753118440.793 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T17:20:45.629Z,1753118445.629 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:20:48.085Z,1753118448.085 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250721T152356/Courier0052.lzma
2025-07-21T17:20:56.140Z,1753118456.140 [NAL9602](INFO): SBD MO Status=0, MOMSN=63754, MT Status=0, MTMSN=0
2025-07-21T17:20:56.140Z,1753118456.140 [NAL9602](INFO): No messages in MT queue
2025-07-21T17:20:59.365Z,1753118459.365 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:21:02.139Z,1753118462.139 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Courier0052.lzma.bak
2025-07-21T17:21:02.139Z,1753118462.139 [DataOverHttps](INFO): SBD MOMSN=25563232
2025-07-21T17:21:12.696Z,1753118472.696 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:21:26.434Z,1753118486.434 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:21:26.854Z,1753118486.854 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T17:21:38.369Z,1753118498.369 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250721T152356/Express0053.lzma
2025-07-21T17:21:40.169Z,1753118500.169 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T17:21:59.459Z,1753118519.459 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Express0053.lzma.bak
2025-07-21T17:21:59.459Z,1753118519.459 [DataOverHttps](INFO): SBD MOMSN=25563235
2025-07-21T17:22:00.796Z,1753118520.796 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T17:22:00.796Z,1753118520.796 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T17:22:00.796Z,1753118520.796 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T17:26:05.333Z,1753118765.333 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-07-21T17:26:10.041Z,1753118770.041 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2025-07-21T17:26:10.044Z,1753118770.044 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2025-07-21T17:27:01.366Z,1753118821.366 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T17:27:01.366Z,1753118821.366 [Default:CheckIn:C.Wait] Stopped
2025-07-21T17:27:01.366Z,1753118821.366 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T17:27:01.366Z,1753118821.366 [Default:CheckIn:D] Running Loop=1
2025-07-21T17:27:01.766Z,1753118821.766 [Default:CheckIn:D] Stopped
2025-07-21T17:27:01.766Z,1753118821.766 [Default:CheckIn:E] Running Loop=1
2025-07-21T17:27:02.180Z,1753118822.180 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 118.801644 min
2025-07-21T17:27:02.180Z,1753118822.180 [Default:CheckIn:E] Stopped
2025-07-21T17:27:02.180Z,1753118822.180 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T17:27:02.180Z,1753118822.180 [Default:CheckIn] Stopped
2025-07-21T17:27:02.180Z,1753118822.180 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T17:27:02.181Z,1753118822.181 [Default:CheckIn](INFO): Running loop #17
2025-07-21T17:27:02.181Z,1753118822.181 [Default:CheckIn] Running Loop=17
2025-07-21T17:27:02.181Z,1753118822.181 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T17:27:02.181Z,1753118822.181 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T17:27:04.180Z,1753118824.180 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172703.00,A,3648.15669,N,12147.21360,W,0.117,10.81,210725,,,D*46
2025-07-21T17:27:04.184Z,1753118824.184 [NAL9602](INFO): GPS fix at 20250721T172703: (36.802611, -121.786893)
2025-07-21T17:27:04.215Z,1753118824.215 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T17:27:04.215Z,1753118824.215 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T17:27:07.985Z,1753118827.985 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-07-21T17:27:16.685Z,1753118836.685 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20250721T152356/Courier0055.lzma
2025-07-21T17:27:25.341Z,1753118845.341 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20250721T152356/Courier0055.lzma
2025-07-21T17:27:33.749Z,1753118853.749 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20250721T152356/Courier0055.lzma
2025-07-21T17:27:40.540Z,1753118860.540 [NAL9602](INFO): SBD MO Status=2, MOMSN=63755, MT Status=2, MTMSN=0
2025-07-21T17:27:40.540Z,1753118860.540 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-21T17:27:42.304Z,1753118862.304 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20250721T152356/Courier0055.lzma
2025-07-21T17:27:45.316Z,1753118865.316 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Courier0055.lzma.bak
2025-07-21T17:27:45.316Z,1753118865.316 [DataOverHttps](INFO): SBD MOMSN=25563265
2025-07-21T17:28:00.340Z,1753118880.340 [NAL9602](INFO): SBD MO Status=0, MOMSN=63755, MT Status=0, MTMSN=0
2025-07-21T17:28:00.340Z,1753118880.340 [NAL9602](INFO): No messages in MT queue
2025-07-21T17:28:18.905Z,1753118898.905 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250721T152356/Express0056.lzma
2025-07-21T17:28:19.907Z,1753118899.907 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Express0056.lzma.bak
2025-07-21T17:28:19.907Z,1753118899.907 [DataOverHttps](INFO): SBD MOMSN=25563275
2025-07-21T17:28:20.989Z,1753118900.989 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T17:28:20.989Z,1753118900.989 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T17:28:20.989Z,1753118900.989 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T17:28:31.078Z,1753118911.078 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T17:33:21.580Z,1753119201.580 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T17:33:21.580Z,1753119201.580 [Default:CheckIn:C.Wait] Stopped
2025-07-21T17:33:21.580Z,1753119201.580 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T17:33:21.580Z,1753119201.580 [Default:CheckIn:D] Running Loop=1
2025-07-21T17:33:21.991Z,1753119201.991 [Default:CheckIn:D] Stopped
2025-07-21T17:33:21.991Z,1753119201.991 [Default:CheckIn:E] Running Loop=1
2025-07-21T17:33:22.384Z,1753119202.384 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 125.138737 min
2025-07-21T17:33:22.384Z,1753119202.384 [Default:CheckIn:E] Stopped
2025-07-21T17:33:22.385Z,1753119202.385 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T17:33:22.385Z,1753119202.385 [Default:CheckIn] Stopped
2025-07-21T17:33:22.385Z,1753119202.385 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T17:33:22.385Z,1753119202.385 [Default:CheckIn](INFO): Running loop #18
2025-07-21T17:33:22.385Z,1753119202.385 [Default:CheckIn] Running Loop=18
2025-07-21T17:33:22.385Z,1753119202.385 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T17:33:22.385Z,1753119202.385 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T17:33:24.396Z,1753119204.396 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173323.00,A,3648.16321,N,12147.21403,W,0.000,359.72,210725,,,D*7C
2025-07-21T17:33:24.398Z,1753119204.398 [NAL9602](INFO): GPS fix at 20250721T173323: (36.802720, -121.786901)
2025-07-21T17:33:24.409Z,1753119204.409 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T17:33:24.409Z,1753119204.409 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T17:33:32.456Z,1753119212.456 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250721T152356/Courier0058.lzma
2025-07-21T17:33:33.459Z,1753119213.459 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Courier0058.lzma.bak
2025-07-21T17:33:33.459Z,1753119213.459 [DataOverHttps](INFO): SBD MOMSN=25563319
2025-07-21T17:33:49.509Z,1753119229.509 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20250721T152356/Express0059.lzma
2025-07-21T17:33:50.511Z,1753119230.511 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Express0059.lzma.bak
2025-07-21T17:33:50.511Z,1753119230.511 [DataOverHttps](INFO): SBD MOMSN=25563322
2025-07-21T17:33:51.464Z,1753119231.464 [NAL9602](INFO): SBD MO Status=2, MOMSN=63756, MT Status=2, MTMSN=0
2025-07-21T17:33:51.464Z,1753119231.464 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-21T17:33:51.897Z,1753119231.897 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T17:33:51.897Z,1753119231.897 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T17:33:51.898Z,1753119231.898 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T17:34:14.088Z,1753119254.088 [NAL9602](INFO): SBD MO Status=0, MOMSN=63756, MT Status=0, MTMSN=0
2025-07-21T17:34:14.088Z,1753119254.088 [NAL9602](INFO): No messages in MT queue
2025-07-21T17:34:44.821Z,1753119284.821 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T17:38:52.472Z,1753119532.472 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T17:38:52.472Z,1753119532.472 [Default:CheckIn:C.Wait] Stopped
2025-07-21T17:38:52.472Z,1753119532.472 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T17:38:52.472Z,1753119532.472 [Default:CheckIn:D] Running Loop=1
2025-07-21T17:38:52.880Z,1753119532.880 [Default:CheckIn:D] Stopped
2025-07-21T17:38:52.880Z,1753119532.880 [Default:CheckIn:E] Running Loop=1
2025-07-21T17:38:53.284Z,1753119533.284 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 130.653556 min
2025-07-21T17:38:53.285Z,1753119533.285 [Default:CheckIn:E] Stopped
2025-07-21T17:38:53.285Z,1753119533.285 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T17:38:53.285Z,1753119533.285 [Default:CheckIn] Stopped
2025-07-21T17:38:53.285Z,1753119533.285 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T17:38:53.285Z,1753119533.285 [Default:CheckIn](INFO): Running loop #19
2025-07-21T17:38:53.285Z,1753119533.285 [Default:CheckIn] Running Loop=19
2025-07-21T17:38:53.285Z,1753119533.285 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T17:38:53.286Z,1753119533.286 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T17:38:55.288Z,1753119535.288 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173854.00,A,3648.16421,N,12147.21381,W,0.019,359.72,210725,,,A*70
2025-07-21T17:38:55.290Z,1753119535.290 [NAL9602](INFO): GPS fix at 20250721T173854: (36.802737, -121.786897)
2025-07-21T17:38:55.334Z,1753119535.334 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T17:38:55.334Z,1753119535.334 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T17:39:02.668Z,1753119542.668 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250721T152356/Courier0061.lzma
2025-07-21T17:39:03.671Z,1753119543.671 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Courier0061.lzma.bak
2025-07-21T17:39:03.671Z,1753119543.671 [DataOverHttps](INFO): SBD MOMSN=25563360
2025-07-21T17:39:13.476Z,1753119553.476 [NAL9602](INFO): SBD MO Status=0, MOMSN=63757, MT Status=0, MTMSN=0
2025-07-21T17:39:13.476Z,1753119553.476 [NAL9602](INFO): No messages in MT queue
2025-07-21T17:39:19.681Z,1753119559.681 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20250721T152356/Express0062.lzma
2025-07-21T17:39:20.684Z,1753119560.684 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Express0062.lzma.bak
2025-07-21T17:39:20.684Z,1753119560.684 [DataOverHttps](INFO): SBD MOMSN=25563363
2025-07-21T17:39:21.961Z,1753119561.961 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T17:39:21.961Z,1753119561.961 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T17:39:21.961Z,1753119561.961 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T17:39:44.174Z,1753119584.174 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T17:44:22.556Z,1753119862.556 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T17:44:22.556Z,1753119862.556 [Default:CheckIn:C.Wait] Stopped
2025-07-21T17:44:22.556Z,1753119862.556 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T17:44:22.556Z,1753119862.556 [Default:CheckIn:D] Running Loop=1
2025-07-21T17:44:22.953Z,1753119862.953 [Default:CheckIn:D] Stopped
2025-07-21T17:44:22.953Z,1753119862.953 [Default:CheckIn:E] Running Loop=1
2025-07-21T17:44:23.362Z,1753119863.362 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 136.154753 min
2025-07-21T17:44:23.362Z,1753119863.362 [Default:CheckIn:E] Stopped
2025-07-21T17:44:23.362Z,1753119863.362 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T17:44:23.362Z,1753119863.362 [Default:CheckIn] Stopped
2025-07-21T17:44:23.362Z,1753119863.362 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T17:44:23.362Z,1753119863.362 [Default:CheckIn](INFO): Running loop #20
2025-07-21T17:44:23.362Z,1753119863.362 [Default:CheckIn] Running Loop=20
2025-07-21T17:44:23.363Z,1753119863.363 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T17:44:23.363Z,1753119863.363 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T17:44:25.376Z,1753119865.376 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174424.00,A,3648.16452,N,12147.21326,W,0.019,359.72,210725,,,A*75
2025-07-21T17:44:25.378Z,1753119865.378 [NAL9602](INFO): GPS fix at 20250721T174424: (36.802742, -121.786888)
2025-07-21T17:44:25.402Z,1753119865.402 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T17:44:25.402Z,1753119865.402 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T17:44:32.993Z,1753119872.993 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20250721T152356/Courier0064.lzma
2025-07-21T17:44:33.995Z,1753119873.995 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Courier0064.lzma.bak
2025-07-21T17:44:33.995Z,1753119873.995 [DataOverHttps](INFO): SBD MOMSN=25563382
2025-07-21T17:44:50.049Z,1753119890.049 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20250721T152356/Express0065.lzma
2025-07-21T17:44:51.051Z,1753119891.051 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Express0065.lzma.bak
2025-07-21T17:44:51.051Z,1753119891.051 [DataOverHttps](INFO): SBD MOMSN=25563385
2025-07-21T17:44:52.449Z,1753119892.449 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T17:44:52.450Z,1753119892.450 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T17:44:52.450Z,1753119892.450 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T17:44:54.052Z,1753119894.052 [NAL9602](INFO): SBD MO Status=2, MOMSN=63758, MT Status=2, MTMSN=0
2025-07-21T17:44:54.052Z,1753119894.052 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-21T17:45:12.631Z,1753119912.631 [NAL9602](INFO): SBD MO Status=0, MOMSN=63758, MT Status=0, MTMSN=0
2025-07-21T17:45:12.632Z,1753119912.632 [NAL9602](INFO): No messages in MT queue
2025-07-21T17:45:43.334Z,1753119943.334 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T17:49:53.022Z,1753120193.022 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T17:49:53.022Z,1753120193.022 [Default:CheckIn:C.Wait] Stopped
2025-07-21T17:49:53.022Z,1753120193.022 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T17:49:53.022Z,1753120193.022 [Default:CheckIn:D] Running Loop=1
2025-07-21T17:49:53.428Z,1753120193.428 [Default:CheckIn:D] Stopped
2025-07-21T17:49:53.428Z,1753120193.428 [Default:CheckIn:E] Running Loop=1
2025-07-21T17:49:53.836Z,1753120193.836 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 141.662679 min
2025-07-21T17:49:53.836Z,1753120193.836 [Default:CheckIn:E] Stopped
2025-07-21T17:49:53.836Z,1753120193.836 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T17:49:53.836Z,1753120193.836 [Default:CheckIn] Stopped
2025-07-21T17:49:53.836Z,1753120193.836 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T17:49:53.837Z,1753120193.837 [Default:CheckIn](INFO): Running loop #21
2025-07-21T17:49:53.837Z,1753120193.837 [Default:CheckIn] Running Loop=21
2025-07-21T17:49:53.837Z,1753120193.837 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T17:49:53.837Z,1753120193.837 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T17:49:55.840Z,1753120195.840 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174955.00,A,3648.16409,N,12147.21351,W,0.000,359.72,210725,,,A*78
2025-07-21T17:49:55.842Z,1753120195.842 [NAL9602](INFO): GPS fix at 20250721T174955: (36.802735, -121.786892)
2025-07-21T17:49:55.858Z,1753120195.858 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T17:49:55.858Z,1753120195.858 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T17:50:03.468Z,1753120203.468 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250721T152356/Courier0067.lzma
2025-07-21T17:50:04.471Z,1753120204.471 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Courier0067.lzma.bak
2025-07-21T17:50:04.471Z,1753120204.471 [DataOverHttps](INFO): SBD MOMSN=25563418
2025-07-21T17:50:09.980Z,1753120209.980 [NAL9602](INFO): SBD MO Status=0, MOMSN=63759, MT Status=0, MTMSN=0
2025-07-21T17:50:09.980Z,1753120209.980 [NAL9602](INFO): No messages in MT queue
2025-07-21T17:50:22.561Z,1753120222.561 [DataOverHttps](INFO): Sending 160 bytes from file Logs/20250721T152356/Express0068.lzma
2025-07-21T17:50:23.555Z,1753120223.555 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Express0068.lzma.bak
2025-07-21T17:50:23.555Z,1753120223.555 [DataOverHttps](INFO): SBD MOMSN=25563421
2025-07-21T17:50:24.605Z,1753120224.605 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T17:50:24.605Z,1753120224.605 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T17:50:24.605Z,1753120224.605 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T17:50:40.719Z,1753120240.719 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T17:55:25.147Z,1753120525.147 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T17:55:25.147Z,1753120525.147 [Default:CheckIn:C.Wait] Stopped
2025-07-21T17:55:25.147Z,1753120525.147 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T17:55:25.147Z,1753120525.147 [Default:CheckIn:D] Running Loop=1
2025-07-21T17:55:25.554Z,1753120525.554 [Default:CheckIn:D] Stopped
2025-07-21T17:55:25.554Z,1753120525.554 [Default:CheckIn:E] Running Loop=1
2025-07-21T17:55:25.956Z,1753120525.956 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 147.198112 min
2025-07-21T17:55:25.956Z,1753120525.956 [Default:CheckIn:E] Stopped
2025-07-21T17:55:25.957Z,1753120525.957 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T17:55:25.957Z,1753120525.957 [Default:CheckIn] Stopped
2025-07-21T17:55:25.957Z,1753120525.957 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T17:55:25.957Z,1753120525.957 [Default:CheckIn](INFO): Running loop #22
2025-07-21T17:55:25.957Z,1753120525.957 [Default:CheckIn] Running Loop=22
2025-07-21T17:55:25.957Z,1753120525.957 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T17:55:25.957Z,1753120525.957 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T17:55:27.964Z,1753120527.964 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175527.00,A,3648.16292,N,12147.21402,W,0.039,359.72,210725,,,D*7A
2025-07-21T17:55:27.967Z,1753120527.967 [NAL9602](INFO): GPS fix at 20250721T175527: (36.802715, -121.786900)
2025-07-21T17:55:27.997Z,1753120527.997 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T17:55:27.997Z,1753120527.997 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T17:55:35.545Z,1753120535.545 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250721T152356/Courier0070.lzma
2025-07-21T17:55:36.547Z,1753120536.547 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Courier0070.lzma.bak
2025-07-21T17:55:36.547Z,1753120536.547 [DataOverHttps](INFO): SBD MOMSN=25563438
2025-07-21T17:55:45.336Z,1753120545.336 [NAL9602](INFO): SBD MO Status=0, MOMSN=63760, MT Status=0, MTMSN=0
2025-07-21T17:55:45.336Z,1753120545.336 [NAL9602](INFO): No messages in MT queue
2025-07-21T17:55:52.597Z,1753120552.597 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250721T152356/Express0071.lzma
2025-07-21T17:55:53.599Z,1753120553.599 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Express0071.lzma.bak
2025-07-21T17:55:53.599Z,1753120553.599 [DataOverHttps](INFO): SBD MOMSN=25563443
2025-07-21T17:55:54.651Z,1753120554.651 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T17:55:54.651Z,1753120554.651 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T17:55:54.651Z,1753120554.651 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T17:56:16.034Z,1753120576.034 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T18:00:55.223Z,1753120855.223 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T18:00:55.223Z,1753120855.223 [Default:CheckIn:C.Wait] Stopped
2025-07-21T18:00:55.223Z,1753120855.223 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T18:00:55.223Z,1753120855.223 [Default:CheckIn:D] Running Loop=1
2025-07-21T18:00:55.632Z,1753120855.632 [Default:CheckIn:D] Stopped
2025-07-21T18:00:55.632Z,1753120855.632 [Default:CheckIn:E] Running Loop=1
2025-07-21T18:00:56.042Z,1753120856.042 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 152.699398 min
2025-07-21T18:00:56.042Z,1753120856.042 [Default:CheckIn:E] Stopped
2025-07-21T18:00:56.042Z,1753120856.042 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T18:00:56.042Z,1753120856.042 [Default:CheckIn] Stopped
2025-07-21T18:00:56.042Z,1753120856.042 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T18:00:56.043Z,1753120856.043 [Default:CheckIn](INFO): Running loop #23
2025-07-21T18:00:56.043Z,1753120856.043 [Default:CheckIn] Running Loop=23
2025-07-21T18:00:56.043Z,1753120856.043 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T18:00:56.043Z,1753120856.043 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T18:00:58.040Z,1753120858.040 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180057.00,A,3648.16361,N,12147.21378,W,0.175,359.72,210725,,,D*7C
2025-07-21T18:00:58.043Z,1753120858.043 [NAL9602](INFO): GPS fix at 20250721T180057: (36.802727, -121.786896)
2025-07-21T18:00:58.079Z,1753120858.079 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T18:00:58.079Z,1753120858.079 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T18:01:05.197Z,1753120865.197 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250721T152356/Courier0073.lzma
2025-07-21T18:01:06.199Z,1753120866.199 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Courier0073.lzma.bak
2025-07-21T18:01:06.199Z,1753120866.199 [DataOverHttps](INFO): SBD MOMSN=25563474
2025-07-21T18:01:22.413Z,1753120882.413 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250721T152356/Express0074.lzma
2025-07-21T18:01:23.415Z,1753120883.415 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Express0074.lzma.bak
2025-07-21T18:01:23.415Z,1753120883.415 [DataOverHttps](INFO): SBD MOMSN=25563477
2025-07-21T18:01:24.729Z,1753120884.729 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T18:01:24.729Z,1753120884.729 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T18:01:24.729Z,1753120884.729 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T18:01:27.144Z,1753120887.144 [NAL9602](INFO): SBD MO Status=0, MOMSN=63761, MT Status=0, MTMSN=0
2025-07-21T18:01:27.144Z,1753120887.144 [NAL9602](INFO): No messages in MT queue
2025-07-21T18:01:57.852Z,1753120917.852 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T18:06:25.328Z,1753121185.328 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T18:06:25.328Z,1753121185.328 [Default:CheckIn:C.Wait] Stopped
2025-07-21T18:06:25.329Z,1753121185.329 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T18:06:25.329Z,1753121185.329 [Default:CheckIn:D] Running Loop=1
2025-07-21T18:06:25.758Z,1753121185.758 [Default:CheckIn:D] Stopped
2025-07-21T18:06:25.758Z,1753121185.758 [Default:CheckIn:E] Running Loop=1
2025-07-21T18:06:26.128Z,1753121186.128 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 158.201514 min
2025-07-21T18:06:26.129Z,1753121186.129 [Default:CheckIn:E] Stopped
2025-07-21T18:06:26.129Z,1753121186.129 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T18:06:26.161Z,1753121186.161 [Default:CheckIn] Stopped
2025-07-21T18:06:26.161Z,1753121186.161 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T18:06:26.161Z,1753121186.161 [Default:CheckIn](INFO): Running loop #24
2025-07-21T18:06:26.161Z,1753121186.161 [Default:CheckIn] Running Loop=24
2025-07-21T18:06:26.161Z,1753121186.161 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T18:06:26.162Z,1753121186.162 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T18:06:28.140Z,1753121188.140 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180627.00,A,3648.39484,N,12147.11634,W,3.849,7.31,210725,,,A*7D
2025-07-21T18:06:28.142Z,1753121188.142 [NAL9602](INFO): GPS fix at 20250721T180627: (36.806581, -121.785272)
2025-07-21T18:06:28.154Z,1753121188.154 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T18:06:28.154Z,1753121188.154 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T18:06:36.173Z,1753121196.173 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250721T152356/Courier0076.lzma
2025-07-21T18:06:37.175Z,1753121197.175 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Courier0076.lzma.bak
2025-07-21T18:06:37.175Z,1753121197.175 [DataOverHttps](INFO): SBD MOMSN=25563503
2025-07-21T18:06:50.372Z,1753121210.372 [NAL9602](INFO): SBD MO Status=2, MOMSN=63762, MT Status=2, MTMSN=0
2025-07-21T18:06:50.372Z,1753121210.372 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-21T18:06:54.541Z,1753121214.541 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20250721T152356/Express0077.lzma
2025-07-21T18:06:55.543Z,1753121215.543 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Express0077.lzma.bak
2025-07-21T18:06:55.543Z,1753121215.543 [DataOverHttps](INFO): SBD MOMSN=25563512
2025-07-21T18:06:56.841Z,1753121216.841 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T18:06:56.841Z,1753121216.841 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T18:06:56.841Z,1753121216.841 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T18:07:01.675Z,1753121221.675 [NAL9602](INFO): SBD MO Status=0, MOMSN=63762, MT Status=0, MTMSN=0
2025-07-21T18:07:01.676Z,1753121221.676 [NAL9602](INFO): No messages in MT queue
2025-07-21T18:07:32.386Z,1753121252.386 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T18:11:57.447Z,1753121517.447 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T18:11:57.447Z,1753121517.447 [Default:CheckIn:C.Wait] Stopped
2025-07-21T18:11:57.447Z,1753121517.447 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T18:11:57.448Z,1753121517.448 [Default:CheckIn:D] Running Loop=1
2025-07-21T18:11:57.837Z,1753121517.837 [Default:CheckIn:D] Stopped
2025-07-21T18:11:57.837Z,1753121517.837 [Default:CheckIn:E] Running Loop=1
2025-07-21T18:11:58.234Z,1753121518.234 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 163.736149 min
2025-07-21T18:11:58.234Z,1753121518.234 [Default:CheckIn:E] Stopped
2025-07-21T18:11:58.235Z,1753121518.235 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T18:11:58.235Z,1753121518.235 [Default:CheckIn] Stopped
2025-07-21T18:11:58.235Z,1753121518.235 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T18:11:58.235Z,1753121518.235 [Default:CheckIn](INFO): Running loop #25
2025-07-21T18:11:58.235Z,1753121518.235 [Default:CheckIn] Running Loop=25
2025-07-21T18:11:58.235Z,1753121518.235 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T18:11:58.235Z,1753121518.235 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T18:12:00.244Z,1753121520.244 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181159.00,A,3648.43615,N,12147.36302,W,4.510,235.56,210725,,,A*74
2025-07-21T18:12:00.254Z,1753121520.254 [NAL9602](INFO): GPS fix at 20250721T181159: (36.807269, -121.789384)
2025-07-21T18:12:00.280Z,1753121520.280 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T18:12:00.280Z,1753121520.280 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T18:12:08.209Z,1753121528.209 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250721T152356/Courier0079.lzma
2025-07-21T18:12:09.211Z,1753121529.211 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Courier0079.lzma.bak
2025-07-21T18:12:09.211Z,1753121529.211 [DataOverHttps](INFO): SBD MOMSN=25563530
2025-07-21T18:12:13.979Z,1753121533.979 [NAL9602](INFO): SBD MO Status=0, MOMSN=63763, MT Status=0, MTMSN=0
2025-07-21T18:12:13.980Z,1753121533.980 [NAL9602](INFO): No messages in MT queue
2025-07-21T18:12:28.261Z,1753121548.261 [DataOverHttps](INFO): Sending 170 bytes from file Logs/20250721T152356/Express0080.lzma
2025-07-21T18:12:29.263Z,1753121549.263 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Express0080.lzma.bak
2025-07-21T18:12:29.263Z,1753121549.263 [DataOverHttps](INFO): SBD MOMSN=25563542
2025-07-21T18:12:30.655Z,1753121550.655 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T18:12:30.655Z,1753121550.655 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T18:12:30.655Z,1753121550.655 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T18:12:44.762Z,1753121564.762 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T18:17:31.224Z,1753121851.224 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T18:17:31.225Z,1753121851.225 [Default:CheckIn:C.Wait] Stopped
2025-07-21T18:17:31.225Z,1753121851.225 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T18:17:31.225Z,1753121851.225 [Default:CheckIn:D] Running Loop=1
2025-07-21T18:17:31.638Z,1753121851.638 [Default:CheckIn:D] Stopped
2025-07-21T18:17:31.638Z,1753121851.638 [Default:CheckIn:E] Running Loop=1
2025-07-21T18:17:32.050Z,1753121852.050 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 169.299512 min
2025-07-21T18:17:32.050Z,1753121852.050 [Default:CheckIn:E] Stopped
2025-07-21T18:17:32.050Z,1753121852.050 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T18:17:32.050Z,1753121852.050 [Default:CheckIn] Stopped
2025-07-21T18:17:32.050Z,1753121852.050 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T18:17:32.050Z,1753121852.050 [Default:CheckIn](INFO): Running loop #26
2025-07-21T18:17:32.050Z,1753121852.050 [Default:CheckIn] Running Loop=26
2025-07-21T18:17:32.050Z,1753121852.050 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T18:17:32.050Z,1753121852.050 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T18:17:34.045Z,1753121854.045 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181733.00,A,3648.30053,N,12147.78609,W,3.790,262.17,210725,,,A*70
2025-07-21T18:17:34.047Z,1753121854.047 [NAL9602](INFO): GPS fix at 20250721T181733: (36.805009, -121.796435)
2025-07-21T18:17:34.058Z,1753121854.058 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T18:17:34.058Z,1753121854.058 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T18:17:42.116Z,1753121862.116 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250721T152356/Courier0082.lzma
2025-07-21T18:17:43.119Z,1753121863.119 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Courier0082.lzma.bak
2025-07-21T18:17:43.119Z,1753121863.119 [DataOverHttps](INFO): SBD MOMSN=25563567
2025-07-21T18:17:58.697Z,1753121878.697 [NAL9602](INFO): SBD MO Status=2, MOMSN=63764, MT Status=2, MTMSN=0
2025-07-21T18:17:58.698Z,1753121878.698 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-21T18:17:59.193Z,1753121879.193 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20250721T152356/Express0083.lzma
2025-07-21T18:18:00.196Z,1753121880.196 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Express0083.lzma.bak
2025-07-21T18:18:00.196Z,1753121880.196 [DataOverHttps](INFO): SBD MOMSN=25563570
2025-07-21T18:18:01.534Z,1753121881.534 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T18:18:01.534Z,1753121881.534 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T18:18:01.534Z,1753121881.534 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T18:18:14.052Z,1753121894.052 [NAL9602](INFO): SBD MO Status=2, MOMSN=63764, MT Status=2, MTMSN=0
2025-07-21T18:18:14.052Z,1753121894.052 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-21T18:18:23.340Z,1753121903.340 [NAL9602](INFO): SBD MO Status=0, MOMSN=63764, MT Status=0, MTMSN=0
2025-07-21T18:18:23.340Z,1753121903.340 [NAL9602](INFO): No messages in MT queue
2025-07-21T18:18:54.042Z,1753121934.042 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T18:23:02.120Z,1753122182.120 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T18:23:02.120Z,1753122182.120 [Default:CheckIn:C.Wait] Stopped
2025-07-21T18:23:02.121Z,1753122182.121 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T18:23:02.121Z,1753122182.121 [Default:CheckIn:D] Running Loop=1
2025-07-21T18:23:02.528Z,1753122182.528 [Default:CheckIn:D] Stopped
2025-07-21T18:23:02.528Z,1753122182.528 [Default:CheckIn:E] Running Loop=1
2025-07-21T18:23:02.921Z,1753122182.921 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 174.814339 min
2025-07-21T18:23:02.921Z,1753122182.921 [Default:CheckIn:E] Stopped
2025-07-21T18:23:02.921Z,1753122182.921 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T18:23:02.921Z,1753122182.921 [Default:CheckIn] Stopped
2025-07-21T18:23:02.921Z,1753122182.921 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T18:23:02.921Z,1753122182.921 [Default:CheckIn](INFO): Running loop #27
2025-07-21T18:23:02.921Z,1753122182.921 [Default:CheckIn] Running Loop=27
2025-07-21T18:23:02.921Z,1753122182.921 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T18:23:02.922Z,1753122182.922 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T18:23:04.937Z,1753122184.937 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182304.00,A,3648.28798,N,12148.21619,W,3.538,287.13,210725,,,A*77
2025-07-21T18:23:04.940Z,1753122184.940 [NAL9602](INFO): GPS fix at 20250721T182304: (36.804800, -121.803603)
2025-07-21T18:23:04.950Z,1753122184.950 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T18:23:04.951Z,1753122184.951 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T18:23:12.801Z,1753122192.801 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250721T152356/Courier0085.lzma
2025-07-21T18:23:13.803Z,1753122193.803 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Courier0085.lzma.bak
2025-07-21T18:23:13.803Z,1753122193.803 [DataOverHttps](INFO): SBD MOMSN=25563602
2025-07-21T18:23:27.584Z,1753122207.584 [NAL9602](INFO): SBD MO Status=0, MOMSN=63765, MT Status=0, MTMSN=0
2025-07-21T18:23:27.584Z,1753122207.584 [NAL9602](INFO): No messages in MT queue
2025-07-21T18:23:30.073Z,1753122210.073 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20250721T152356/Express0086.lzma
2025-07-21T18:23:31.059Z,1753122211.059 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Express0086.lzma.bak
2025-07-21T18:23:31.059Z,1753122211.059 [DataOverHttps](INFO): SBD MOMSN=25563605
2025-07-21T18:23:32.441Z,1753122212.441 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T18:23:32.441Z,1753122212.441 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T18:23:32.441Z,1753122212.441 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T18:23:58.285Z,1753122238.285 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T18:27:16.247Z,1753122436.247 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T18:27:27.553Z,1753122447.553 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T18:27:38.902Z,1753122458.902 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T18:27:50.985Z,1753122470.985 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T18:28:02.710Z,1753122482.710 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T18:28:14.066Z,1753122494.066 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T18:28:25.344Z,1753122505.344 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T18:28:33.029Z,1753122513.029 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T18:28:33.029Z,1753122513.029 [Default:CheckIn:C.Wait] Stopped
2025-07-21T18:28:33.029Z,1753122513.029 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T18:28:33.029Z,1753122513.029 [Default:CheckIn:D] Running Loop=1
2025-07-21T18:28:33.420Z,1753122513.420 [Default:CheckIn:D] Stopped
2025-07-21T18:28:33.420Z,1753122513.420 [Default:CheckIn:E] Running Loop=1
2025-07-21T18:28:33.823Z,1753122513.823 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 180.329215 min
2025-07-21T18:28:33.823Z,1753122513.823 [Default:CheckIn:E] Stopped
2025-07-21T18:28:33.823Z,1753122513.823 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T18:28:33.823Z,1753122513.823 [Default:CheckIn] Stopped
2025-07-21T18:28:33.823Z,1753122513.823 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T18:28:33.823Z,1753122513.823 [Default:CheckIn](INFO): Running loop #28
2025-07-21T18:28:33.824Z,1753122513.824 [Default:CheckIn] Running Loop=28
2025-07-21T18:28:33.824Z,1753122513.824 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T18:28:33.824Z,1753122513.824 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T18:28:35.844Z,1753122515.844 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182835.00,A,3648.23314,N,12148.68078,W,4.685,257.63,210725,,,A*71
2025-07-21T18:28:35.846Z,1753122515.846 [NAL9602](INFO): GPS fix at 20250721T182835: (36.803886, -121.811346)
2025-07-21T18:28:35.857Z,1753122515.857 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T18:28:35.857Z,1753122515.857 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T18:28:36.637Z,1753122516.637 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T18:28:43.761Z,1753122523.761 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250721T152356/Courier0088.lzma
2025-07-21T18:28:45.767Z,1753122525.767 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Courier0088.lzma.bak
2025-07-21T18:28:45.767Z,1753122525.767 [DataOverHttps](INFO): SBD MOMSN=25563625
2025-07-21T18:28:50.378Z,1753122530.378 [NAL9602](INFO): SBD MO Status=0, MOMSN=63766, MT Status=0, MTMSN=0
2025-07-21T18:28:50.378Z,1753122530.378 [NAL9602](INFO): No messages in MT queue
2025-07-21T18:29:04.957Z,1753122544.957 [DataOverHttps](INFO): Sending 170 bytes from file Logs/20250721T152356/Express0089.lzma
2025-07-21T18:29:05.959Z,1753122545.959 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Express0089.lzma.bak
2025-07-21T18:29:05.959Z,1753122545.959 [DataOverHttps](INFO): SBD MOMSN=25563632
2025-07-21T18:29:07.354Z,1753122547.354 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T18:29:07.354Z,1753122547.354 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T18:29:07.354Z,1753122547.354 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T18:29:21.079Z,1753122561.079 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T18:31:10.562Z,1753122670.562 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T18:31:23.894Z,1753122683.894 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T18:31:37.225Z,1753122697.225 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T18:31:50.557Z,1753122710.557 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T18:32:03.890Z,1753122723.890 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T18:32:17.227Z,1753122737.227 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T18:32:30.593Z,1753122750.593 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T18:32:43.925Z,1753122763.925 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T18:32:57.257Z,1753122777.257 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T18:33:10.590Z,1753122790.590 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T18:33:23.921Z,1753122803.921 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T18:34:07.982Z,1753122847.982 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T18:34:07.983Z,1753122847.983 [Default:CheckIn:C.Wait] Stopped
2025-07-21T18:34:07.983Z,1753122847.983 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T18:34:07.983Z,1753122847.983 [Default:CheckIn:D] Running Loop=1
2025-07-21T18:34:08.376Z,1753122848.376 [Default:CheckIn:D] Stopped
2025-07-21T18:34:08.376Z,1753122848.376 [Default:CheckIn:E] Running Loop=1
2025-07-21T18:34:08.785Z,1753122848.785 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 185.911816 min
2025-07-21T18:34:08.785Z,1753122848.785 [Default:CheckIn:E] Stopped
2025-07-21T18:34:08.786Z,1753122848.786 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T18:34:08.786Z,1753122848.786 [Default:CheckIn] Stopped
2025-07-21T18:34:08.786Z,1753122848.786 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T18:34:08.786Z,1753122848.786 [Default:CheckIn](INFO): Running loop #29
2025-07-21T18:34:08.786Z,1753122848.786 [Default:CheckIn] Running Loop=29
2025-07-21T18:34:08.786Z,1753122848.786 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T18:34:08.786Z,1753122848.786 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T18:34:10.791Z,1753122850.791 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183410.00,A,3648.30340,N,12149.18933,W,4.413,281.09,210725,,,D*77
2025-07-21T18:34:10.793Z,1753122850.793 [NAL9602](INFO): GPS fix at 20250721T183410: (36.805057, -121.819822)
2025-07-21T18:34:10.804Z,1753122850.804 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T18:34:10.804Z,1753122850.804 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T18:34:23.720Z,1753122863.720 [NAL9602](INFO): SBD MO Status=0, MOMSN=63767, MT Status=0, MTMSN=0
2025-07-21T18:34:23.720Z,1753122863.720 [NAL9602](INFO): No messages in MT queue
2025-07-21T18:34:54.419Z,1753122894.419 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T18:36:15.613Z,1753122975.613 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-21T18:36:46.737Z,1753123006.737 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-21T18:36:50.764Z,1753123010.764 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.061722
2025-07-21T18:37:06.580Z,1753123026.580 [NAL9602](INFO): SBD MO Status=1, MOMSN=63768, MT Status=0, MTMSN=0
2025-07-21T18:37:06.634Z,1753123026.634 [NAL9602](INFO): Sent 73 bytes from file Logs/20250721T152356/Courier0091.lzma
2025-07-21T18:37:06.634Z,1753123026.634 [NAL9602](INFO): Packets left to send: 0
2025-07-21T18:37:15.537Z,1753123035.537 [NAL9602](INFO): SBD MO Status=1, MOMSN=63769, MT Status=0, MTMSN=0
2025-07-21T18:37:15.591Z,1753123035.591 [NAL9602](INFO): Sent 168 bytes from file Logs/20250721T152356/Express0092.lzma
2025-07-21T18:37:15.591Z,1753123035.591 [NAL9602](INFO): Packets left to send: 0
2025-07-21T18:37:21.885Z,1753123041.885 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-21T18:37:24.025Z,1753123044.025 [NAL9602](INFO): SBD MO Status=0, MOMSN=63770, MT Status=0, MTMSN=0
2025-07-21T18:37:24.110Z,1753123044.110 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T18:37:24.110Z,1753123044.110 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T18:37:24.110Z,1753123044.110 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T18:37:53.009Z,1753123073.009 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-21T18:37:54.735Z,1753123074.735 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T18:38:24.149Z,1753123104.149 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-21T18:38:55.290Z,1753123135.290 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-21T18:39:26.413Z,1753123166.413 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-21T18:39:57.537Z,1753123197.537 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-21T18:40:28.661Z,1753123228.661 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-21T18:40:40.007Z,1753123240.007 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-07-21T18:40:46.413Z,1753123246.413 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T18:40:59.785Z,1753123259.785 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-21T18:41:00.957Z,1753123260.957 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T18:41:16.321Z,1753123276.321 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T18:41:30.909Z,1753123290.909 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-21T18:41:31.256Z,1753123291.256 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T18:41:46.608Z,1753123306.608 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T18:42:00.537Z,1753123320.537 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002569
2025-07-21T18:42:02.364Z,1753123322.364 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T18:42:17.718Z,1753123337.718 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T18:42:24.598Z,1753123344.598 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T18:42:24.599Z,1753123344.599 [Default:CheckIn:C.Wait] Stopped
2025-07-21T18:42:24.599Z,1753123344.599 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T18:42:24.599Z,1753123344.599 [Default:CheckIn:D] Running Loop=1
2025-07-21T18:42:25.023Z,1753123345.023 [Default:CheckIn:D] Stopped
2025-07-21T18:42:25.023Z,1753123345.023 [Default:CheckIn:E] Running Loop=1
2025-07-21T18:42:25.411Z,1753123345.411 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 194.189258 min
2025-07-21T18:42:25.411Z,1753123345.411 [Default:CheckIn:E] Stopped
2025-07-21T18:42:25.411Z,1753123345.411 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T18:42:25.411Z,1753123345.411 [Default:CheckIn] Stopped
2025-07-21T18:42:25.411Z,1753123345.411 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T18:42:25.411Z,1753123345.411 [Default:CheckIn](INFO): Running loop #30
2025-07-21T18:42:25.411Z,1753123345.411 [Default:CheckIn] Running Loop=30
2025-07-21T18:42:25.412Z,1753123345.412 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T18:42:25.412Z,1753123345.412 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T18:42:27.418Z,1753123347.418 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184226.00,A,3648.31913,N,12149.44584,W,4.840,49.41,210725,,,D*47
2025-07-21T18:42:27.421Z,1753123347.421 [NAL9602](INFO): GPS fix at 20250721T184226: (36.805319, -121.824097)
2025-07-21T18:42:27.431Z,1753123347.431 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T18:42:27.431Z,1753123347.431 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T18:42:33.069Z,1753123353.069 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T18:42:36.712Z,1753123356.712 [NAL9602](INFO): SBD MO Status=0, MOMSN=63771, MT Status=0, MTMSN=0
2025-07-21T18:42:36.713Z,1753123356.713 [NAL9602](INFO): No messages in MT queue
2025-07-21T18:42:37.733Z,1753123357.733 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250721T152356/Courier0094.lzma
2025-07-21T18:42:38.743Z,1753123358.743 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Courier0094.lzma.bak
2025-07-21T18:42:38.744Z,1753123358.744 [DataOverHttps](INFO): SBD MOMSN=25563690
2025-07-21T18:42:48.440Z,1753123368.440 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T18:42:54.941Z,1753123374.941 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20250721T152356/Express0095.lzma
2025-07-21T18:42:55.943Z,1753123375.943 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Express0095.lzma.bak
2025-07-21T18:42:55.943Z,1753123375.943 [DataOverHttps](INFO): SBD MOMSN=25563693
2025-07-21T18:42:57.332Z,1753123377.332 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T18:42:57.332Z,1753123377.332 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T18:42:57.332Z,1753123377.332 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T18:43:02.964Z,1753123382.964 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T18:43:07.410Z,1753123387.410 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T18:44:29.833Z,1753123469.833 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-21T18:47:57.922Z,1753123677.922 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T18:47:57.922Z,1753123677.922 [Default:CheckIn:C.Wait] Stopped
2025-07-21T18:47:57.922Z,1753123677.922 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T18:47:57.922Z,1753123677.922 [Default:CheckIn:D] Running Loop=1
2025-07-21T18:47:58.318Z,1753123678.318 [Default:CheckIn:D] Stopped
2025-07-21T18:47:58.318Z,1753123678.318 [Default:CheckIn:E] Running Loop=1
2025-07-21T18:47:58.721Z,1753123678.721 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 199.744173 min
2025-07-21T18:47:58.721Z,1753123678.721 [Default:CheckIn:E] Stopped
2025-07-21T18:47:58.722Z,1753123678.722 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T18:47:58.722Z,1753123678.722 [Default:CheckIn] Stopped
2025-07-21T18:47:58.722Z,1753123678.722 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T18:47:58.722Z,1753123678.722 [Default:CheckIn](INFO): Running loop #31
2025-07-21T18:47:58.722Z,1753123678.722 [Default:CheckIn] Running Loop=31
2025-07-21T18:47:58.722Z,1753123678.722 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T18:47:58.722Z,1753123678.722 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T18:48:00.760Z,1753123680.760 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184800.00,A,3648.44584,N,12149.46389,W,2.313,350.24,210725,,,D*73
2025-07-21T18:48:00.762Z,1753123680.762 [NAL9602](INFO): GPS fix at 20250721T184800: (36.807431, -121.824398)
2025-07-21T18:48:00.798Z,1753123680.798 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T18:48:00.798Z,1753123680.798 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T18:48:23.420Z,1753123703.420 [NAL9602](INFO): SBD MO Status=2, MOMSN=63772, MT Status=2, MTMSN=0
2025-07-21T18:48:23.420Z,1753123703.420 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-21T18:48:25.553Z,1753123705.553 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250721T152356/Courier0097.lzma
2025-07-21T18:48:26.556Z,1753123706.556 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Courier0097.lzma.bak
2025-07-21T18:48:26.556Z,1753123706.556 [DataOverHttps](INFO): SBD MOMSN=25563726
2025-07-21T18:48:31.084Z,1753123711.084 [NAL9602](INFO): SBD MO Status=0, MOMSN=63772, MT Status=0, MTMSN=0
2025-07-21T18:48:31.084Z,1753123711.084 [NAL9602](INFO): No messages in MT queue
2025-07-21T18:48:43.069Z,1753123723.069 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20250721T152356/Express0098.lzma
2025-07-21T18:48:44.083Z,1753123724.083 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Express0098.lzma.bak
2025-07-21T18:48:44.083Z,1753123724.083 [DataOverHttps](INFO): SBD MOMSN=25563733
2025-07-21T18:48:45.235Z,1753123725.235 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T18:48:45.235Z,1753123725.235 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T18:48:45.235Z,1753123725.235 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T18:49:01.788Z,1753123741.788 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T18:50:28.693Z,1753123828.693 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T18:50:42.038Z,1753123842.038 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T18:50:55.375Z,1753123855.375 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T18:51:08.700Z,1753123868.700 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T18:51:22.438Z,1753123882.438 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T18:51:35.769Z,1753123895.769 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T18:51:49.100Z,1753123909.100 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T18:52:02.434Z,1753123922.434 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T18:52:16.168Z,1753123936.168 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T18:52:30.715Z,1753123950.715 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-07-21T18:53:45.928Z,1753124025.928 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T18:53:45.928Z,1753124025.928 [Default:CheckIn:C.Wait] Stopped
2025-07-21T18:53:45.928Z,1753124025.928 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T18:53:45.928Z,1753124025.928 [Default:CheckIn:D] Running Loop=1
2025-07-21T18:53:46.329Z,1753124026.329 [Default:CheckIn:D] Stopped
2025-07-21T18:53:46.329Z,1753124026.329 [Default:CheckIn:E] Running Loop=1
2025-07-21T18:53:46.739Z,1753124026.739 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 205.544352 min
2025-07-21T18:53:46.739Z,1753124026.739 [Default:CheckIn:E] Stopped
2025-07-21T18:53:46.739Z,1753124026.739 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T18:53:46.739Z,1753124026.739 [Default:CheckIn] Stopped
2025-07-21T18:53:46.739Z,1753124026.739 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T18:53:46.739Z,1753124026.739 [Default:CheckIn](INFO): Running loop #32
2025-07-21T18:53:46.739Z,1753124026.739 [Default:CheckIn] Running Loop=32
2025-07-21T18:53:46.739Z,1753124026.739 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T18:53:46.739Z,1753124026.739 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T18:53:48.744Z,1753124028.744 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185348.00,A,3648.44381,N,12149.50317,W,1.749,269.63,210725,,,D*76
2025-07-21T18:53:48.757Z,1753124028.757 [NAL9602](INFO): GPS fix at 20250721T185348: (36.807397, -121.825053)
2025-07-21T18:53:48.803Z,1753124028.803 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T18:53:48.803Z,1753124028.803 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T18:54:10.568Z,1753124050.568 [NAL9602](INFO): SBD MO Status=0, MOMSN=63773, MT Status=0, MTMSN=0
2025-07-21T18:54:10.568Z,1753124050.568 [NAL9602](INFO): No messages in MT queue
2025-07-21T18:54:30.981Z,1753124070.981 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250721T152356/Courier0100.lzma
2025-07-21T18:54:31.984Z,1753124071.984 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Courier0100.lzma.bak
2025-07-21T18:54:31.984Z,1753124071.984 [DataOverHttps](INFO): SBD MOMSN=25563750
2025-07-21T18:54:40.974Z,1753124080.974 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T18:54:50.134Z,1753124090.134 [DataOverHttps](INFO): Sending 163 bytes from file Logs/20250721T152356/Express0101.lzma
2025-07-21T18:54:51.135Z,1753124091.135 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Express0101.lzma.bak
2025-07-21T18:54:51.135Z,1753124091.135 [DataOverHttps](INFO): SBD MOMSN=25563754
2025-07-21T18:54:52.246Z,1753124092.246 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T18:54:52.246Z,1753124092.246 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T18:54:52.246Z,1753124092.246 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T18:57:11.205Z,1753124231.205 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2025-07-21T18:57:11.208Z,1753124231.208 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2025-07-21T18:57:57.673Z,1753124277.673 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-21T18:58:28.809Z,1753124308.809 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-21T18:58:59.935Z,1753124339.935 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-21T18:59:31.057Z,1753124371.057 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-21T18:59:52.816Z,1753124392.816 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T18:59:52.816Z,1753124392.816 [Default:CheckIn:C.Wait] Stopped
2025-07-21T18:59:52.817Z,1753124392.817 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T18:59:52.817Z,1753124392.817 [Default:CheckIn:D] Running Loop=1
2025-07-21T18:59:53.228Z,1753124393.228 [Default:CheckIn:D] Stopped
2025-07-21T18:59:53.229Z,1753124393.229 [Default:CheckIn:E] Running Loop=1
2025-07-21T18:59:53.632Z,1753124393.632 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 211.659359 min
2025-07-21T18:59:53.632Z,1753124393.632 [Default:CheckIn:E] Stopped
2025-07-21T18:59:53.633Z,1753124393.633 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T18:59:53.633Z,1753124393.633 [Default:CheckIn] Stopped
2025-07-21T18:59:53.633Z,1753124393.633 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T18:59:53.634Z,1753124393.634 [Default:CheckIn](INFO): Running loop #33
2025-07-21T18:59:53.634Z,1753124393.634 [Default:CheckIn] Running Loop=33
2025-07-21T18:59:53.634Z,1753124393.634 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T18:59:53.634Z,1753124393.634 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T18:59:55.636Z,1753124395.636 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185954.00,A,3648.38929,N,12149.46266,W,1.536,51.11,210725,,,A*41
2025-07-21T18:59:55.638Z,1753124395.638 [NAL9602](INFO): GPS fix at 20250721T185954: (36.806488, -121.824378)
2025-07-21T18:59:55.650Z,1753124395.650 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T18:59:55.650Z,1753124395.650 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T19:00:02.182Z,1753124402.182 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-21T19:00:05.195Z,1753124405.195 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.732590
2025-07-21T19:00:10.991Z,1753124410.991 [NAL9602](INFO): SBD MO Status=0, MOMSN=63774, MT Status=0, MTMSN=0
2025-07-21T19:00:10.991Z,1753124410.991 [NAL9602](INFO): No messages in MT queue
2025-07-21T19:00:28.821Z,1753124428.821 [NAL9602](INFO): SBD MO Status=1, MOMSN=63775, MT Status=0, MTMSN=0
2025-07-21T19:00:28.881Z,1753124428.881 [NAL9602](INFO): Sent 85 bytes from file Logs/20250721T152356/Courier0103.lzma
2025-07-21T19:00:28.882Z,1753124428.882 [NAL9602](INFO): Packets left to send: 0
2025-07-21T19:00:36.317Z,1753124436.317 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-21T19:00:47.077Z,1753124447.077 [NAL9602](INFO): SBD MO Status=1, MOMSN=63776, MT Status=0, MTMSN=0
2025-07-21T19:00:47.141Z,1753124447.141 [NAL9602](INFO): Sent 168 bytes from file Logs/20250721T152356/Express0104.lzma
2025-07-21T19:00:47.141Z,1753124447.141 [NAL9602](INFO): Packets left to send: 0
2025-07-21T19:00:51.520Z,1753124451.520 [NAL9602](INFO): SBD MO Status=0, MOMSN=63777, MT Status=0, MTMSN=0
2025-07-21T19:00:51.611Z,1753124451.611 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T19:00:51.611Z,1753124451.611 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T19:00:51.612Z,1753124451.612 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T19:01:08.281Z,1753124468.281 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002878
2025-07-21T19:01:22.222Z,1753124482.222 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T19:02:40.573Z,1753124560.573 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-21T19:03:11.697Z,1753124591.697 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-21T19:05:52.161Z,1753124752.161 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T19:05:52.161Z,1753124752.161 [Default:CheckIn:C.Wait] Stopped
2025-07-21T19:05:52.161Z,1753124752.161 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T19:05:52.161Z,1753124752.161 [Default:CheckIn:D] Running Loop=1
2025-07-21T19:05:52.566Z,1753124752.566 [Default:CheckIn:D] Stopped
2025-07-21T19:05:52.566Z,1753124752.566 [Default:CheckIn:E] Running Loop=1
2025-07-21T19:05:52.970Z,1753124752.970 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 217.648307 min
2025-07-21T19:05:52.970Z,1753124752.970 [Default:CheckIn:E] Stopped
2025-07-21T19:05:52.970Z,1753124752.970 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T19:05:52.970Z,1753124752.970 [Default:CheckIn] Stopped
2025-07-21T19:05:52.971Z,1753124752.971 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T19:05:52.971Z,1753124752.971 [Default:CheckIn](INFO): Running loop #34
2025-07-21T19:05:52.971Z,1753124752.971 [Default:CheckIn] Running Loop=34
2025-07-21T19:05:52.971Z,1753124752.971 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T19:05:52.971Z,1753124752.971 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T19:05:54.990Z,1753124754.990 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190554.00,A,3648.42638,N,12149.43561,W,1.283,300.52,210725,,,A*77
2025-07-21T19:05:54.992Z,1753124754.992 [NAL9602](INFO): GPS fix at 20250721T190554: (36.807106, -121.823927)
2025-07-21T19:05:55.003Z,1753124755.003 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T19:05:55.003Z,1753124755.003 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T19:06:02.712Z,1753124762.712 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250721T152356/Courier0106.lzma
2025-07-21T19:06:03.715Z,1753124763.715 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Courier0106.lzma.bak
2025-07-21T19:06:03.715Z,1753124763.715 [DataOverHttps](INFO): SBD MOMSN=25563823
2025-07-21T19:06:21.417Z,1753124781.417 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20250721T152356/Express0107.lzma
2025-07-21T19:06:22.419Z,1753124782.419 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Express0107.lzma.bak
2025-07-21T19:06:22.420Z,1753124782.420 [DataOverHttps](INFO): SBD MOMSN=25563834
2025-07-21T19:06:23.693Z,1753124783.693 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T19:06:23.693Z,1753124783.693 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T19:06:23.693Z,1753124783.693 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T19:06:24.075Z,1753124784.075 [NAL9602](INFO): SBD MO Status=0, MOMSN=63778, MT Status=0, MTMSN=0
2025-07-21T19:06:24.076Z,1753124784.076 [NAL9602](INFO): No messages in MT queue
2025-07-21T19:06:54.796Z,1753124814.796 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T19:11:24.332Z,1753125084.332 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T19:11:24.332Z,1753125084.332 [Default:CheckIn:C.Wait] Stopped
2025-07-21T19:11:24.332Z,1753125084.332 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T19:11:24.333Z,1753125084.333 [Default:CheckIn:D] Running Loop=1
2025-07-21T19:11:24.728Z,1753125084.728 [Default:CheckIn:D] Stopped
2025-07-21T19:11:24.728Z,1753125084.728 [Default:CheckIn:E] Running Loop=1
2025-07-21T19:11:25.130Z,1753125085.130 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 223.184359 min
2025-07-21T19:11:25.130Z,1753125085.130 [Default:CheckIn:E] Stopped
2025-07-21T19:11:25.130Z,1753125085.130 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T19:11:25.130Z,1753125085.130 [Default:CheckIn] Stopped
2025-07-21T19:11:25.130Z,1753125085.130 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T19:11:25.130Z,1753125085.130 [Default:CheckIn](INFO): Running loop #35
2025-07-21T19:11:25.130Z,1753125085.130 [Default:CheckIn] Running Loop=35
2025-07-21T19:11:25.130Z,1753125085.130 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T19:11:25.131Z,1753125085.131 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T19:11:27.141Z,1753125087.141 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191126.00,A,3647.83509,N,12150.67047,W,17.650,255.21,210725,,,D*46
2025-07-21T19:11:27.143Z,1753125087.143 [NAL9602](INFO): GPS fix at 20250721T191126: (36.797252, -121.844508)
2025-07-21T19:11:27.155Z,1753125087.155 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T19:11:27.155Z,1753125087.155 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T19:11:34.634Z,1753125094.634 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250721T152356/Courier0109.lzma
2025-07-21T19:11:35.635Z,1753125095.635 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Courier0109.lzma.bak
2025-07-21T19:11:35.635Z,1753125095.635 [DataOverHttps](INFO): SBD MOMSN=25563853
2025-07-21T19:11:42.911Z,1753125102.911 [NAL9602](INFO): SBD MO Status=0, MOMSN=63779, MT Status=0, MTMSN=0
2025-07-21T19:11:42.912Z,1753125102.912 [NAL9602](INFO): No messages in MT queue
2025-07-21T19:11:51.493Z,1753125111.493 [DataOverHttps](INFO): Sending 171 bytes from file Logs/20250721T152356/Express0110.lzma
2025-07-21T19:11:52.496Z,1753125112.496 [DataOverHttps](INFO): Moved sent file to Logs/20250721T152356/Express0110.lzma.bak
2025-07-21T19:11:52.496Z,1753125112.496 [DataOverHttps](INFO): SBD MOMSN=25563860
2025-07-21T19:11:53.816Z,1753125113.816 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T19:11:53.816Z,1753125113.816 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T19:11:53.816Z,1753125113.816 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T19:12:13.598Z,1753125133.598 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T19:16:05.659Z,1753125365.659 [DataOverHttps](IMPORTANT): SBD MTMSN=20250721T191604
2025-07-21T19:16:13.249Z,1753125373.249 [DataOverHttps](INFO): Received command: restart logs