2025-09-03T20:22:51.936Z,1756930971.936 [Supervisor](DEBUG): Initializing supervisor.
2025-09-03T20:22:51.940Z,1756930971.940 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-09-03T20:22:51.940Z,1756930971.940 [SyncHandler](INFO): Protected caller Thread ID is 5306
2025-09-03T20:22:51.941Z,1756930971.941 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-09-03T20:22:51.942Z,1756930971.942 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-09-03T20:22:51.942Z,1756930971.942 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5307
2025-09-03T20:22:51.946Z,1756930971.946 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-09-03T20:22:51.966Z,1756930971.966 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-09-03T20:22:51.967Z,1756930971.967 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-09-03T20:22:51.967Z,1756930971.967 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 5308
2025-09-03T20:22:51.971Z,1756930971.971 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-09-03T20:22:51.973Z,1756930971.973 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-09-03T20:22:51.973Z,1756930971.973 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5309
2025-09-03T20:22:51.975Z,1756930971.975 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-09-03T20:22:51.976Z,1756930971.976 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-09-03T20:22:51.976Z,1756930971.976 [logger ThreadHandler](INFO): Protected caller Thread ID is 5310
2025-09-03T20:22:51.980Z,1756930971.980 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-09-03T20:22:51.981Z,1756930971.981 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-09-03T20:22:51.982Z,1756930971.982 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-09-03T20:22:52.159Z,1756930972.159 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-09-03T20:22:52.160Z,1756930972.160 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-09-03T20:22:52.402Z,1756930972.402 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-09-03T20:22:52.402Z,1756930972.402 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-09-03T20:22:52.679Z,1756930972.679 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-09-03T20:22:52.679Z,1756930972.679 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-09-03T20:22:52.799Z,1756930972.799 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-09-03T20:22:52.800Z,1756930972.800 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-09-03T20:22:53.570Z,1756930973.570 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-09-03T20:22:53.570Z,1756930973.570 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-09-03T20:22:54.117Z,1756930974.117 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-09-03T20:22:54.118Z,1756930974.118 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-09-03T20:22:54.667Z,1756930974.667 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-09-03T20:22:54.668Z,1756930974.668 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-09-03T20:22:55.111Z,1756930975.111 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-09-03T20:22:55.112Z,1756930975.112 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-09-03T20:22:55.482Z,1756930975.482 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-09-03T20:22:55.483Z,1756930975.483 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-09-03T20:22:56.308Z,1756930976.308 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-09-03T20:22:56.309Z,1756930976.309 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-09-03T20:22:56.553Z,1756930976.553 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-09-03T20:22:56.553Z,1756930976.553 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-09-03T20:22:56.710Z,1756930976.710 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-09-03T20:22:56.711Z,1756930976.711 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-09-03T20:22:56.817Z,1756930976.817 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance
2025-09-03T20:22:56.818Z,1756930976.818 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-09-03T20:22:56.913Z,1756930976.913 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-09-03T20:22:57.108Z,1756930977.108 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-09-03T20:22:57.109Z,1756930977.109 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-09-03T20:22:57.368Z,1756930977.368 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-09-03T20:22:57.370Z,1756930977.370 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2025-09-03T20:22:57.373Z,1756930977.373 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2025-09-03T20:22:57.472Z,1756930977.472 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2025-09-03T20:22:57.760Z,1756930977.760 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-09-03T20:22:57.761Z,1756930977.761 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2025-09-03T20:22:57.863Z,1756930977.863 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2025-09-03T20:22:58.045Z,1756930978.045 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2025-09-03T20:22:58.159Z,1756930978.159 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2025-09-03T20:22:58.282Z,1756930978.282 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2025-09-03T20:22:58.408Z,1756930978.408 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2025-09-03T20:22:58.516Z,1756930978.516 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2025-09-03T20:22:58.687Z,1756930978.687 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Derivation.cfg
2025-09-03T20:22:58.796Z,1756930978.796 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/workSite.cfg
2025-09-03T20:22:58.895Z,1756930978.895 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2025-09-03T20:22:59.007Z,1756930979.007 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2025-09-03T20:22:59.110Z,1756930979.110 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2025-09-03T20:22:59.110Z,1756930979.110 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-09-03T20:22:59.123Z,1756930979.123 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-09-03T20:22:59.348Z,1756930979.348 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-09-03T20:22:59.349Z,1756930979.349 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-09-03T20:22:59.522Z,1756930979.522 [BuoyancyServo] Loaded
2025-09-03T20:22:59.522Z,1756930979.522 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-09-03T20:22:59.546Z,1756930979.546 [ElevatorServo] Loaded
2025-09-03T20:22:59.546Z,1756930979.546 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-09-03T20:22:59.569Z,1756930979.569 [MassServo] Loaded
2025-09-03T20:22:59.569Z,1756930979.569 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-09-03T20:22:59.592Z,1756930979.592 [RudderServo] Loaded
2025-09-03T20:22:59.592Z,1756930979.592 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-09-03T20:22:59.610Z,1756930979.610 [ThrusterHE] Loaded
2025-09-03T20:22:59.610Z,1756930979.610 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-09-03T20:22:59.610Z,1756930979.610 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-09-03T20:22:59.611Z,1756930979.611 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-09-03T20:22:59.813Z,1756930979.813 [SBIT](DEBUG): Construct Startup Built In Test.
2025-09-03T20:22:59.828Z,1756930979.828 [SBIT] Loaded
2025-09-03T20:22:59.828Z,1756930979.828 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-09-03T20:22:59.831Z,1756930979.831 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-09-03T20:22:59.852Z,1756930979.852 [IBIT] Loaded
2025-09-03T20:22:59.852Z,1756930979.852 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-09-03T20:22:59.857Z,1756930979.857 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-09-03T20:23:00.058Z,1756930980.058 [CBIT] Loaded
2025-09-03T20:23:00.058Z,1756930980.058 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-09-03T20:23:00.090Z,1756930980.090 [GFScanner] Loaded
2025-09-03T20:23:00.090Z,1756930980.090 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-09-03T20:23:00.090Z,1756930980.090 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-09-03T20:23:00.091Z,1756930980.091 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-09-03T20:23:00.317Z,1756930980.317 [CTD_Seabird] Loaded
2025-09-03T20:23:00.317Z,1756930980.317 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-09-03T20:23:00.318Z,1756930980.318 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406874E0
2025-09-03T20:23:00.319Z,1756930980.319 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 5398
2025-09-03T20:23:00.354Z,1756930980.354 [ESPComponent] Loaded
2025-09-03T20:23:00.354Z,1756930980.354 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2025-09-03T20:23:00.377Z,1756930980.377 [PAR_Licor] Loaded
2025-09-03T20:23:00.377Z,1756930980.377 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-09-03T20:23:00.422Z,1756930980.422 [WetLabsBB2FL] Loaded
2025-09-03T20:23:00.423Z,1756930980.423 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-09-03T20:23:00.424Z,1756930980.424 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406B74E0
2025-09-03T20:23:00.424Z,1756930980.424 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5399
2025-09-03T20:23:00.425Z,1756930980.425 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-09-03T20:23:00.425Z,1756930980.425 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-09-03T20:23:00.497Z,1756930980.497 [DepthRateCalculator] Loaded
2025-09-03T20:23:00.498Z,1756930980.498 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-09-03T20:23:00.503Z,1756930980.503 [PitchRateCalculator] Loaded
2025-09-03T20:23:00.503Z,1756930980.503 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-09-03T20:23:00.514Z,1756930980.514 [SpeedCalculator] Loaded
2025-09-03T20:23:00.514Z,1756930980.514 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-09-03T20:23:00.518Z,1756930980.518 [YawRateCalculator] Loaded
2025-09-03T20:23:00.519Z,1756930980.519 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-09-03T20:23:00.539Z,1756930980.539 [ElevatorOffsetCalculator] Loaded
2025-09-03T20:23:00.539Z,1756930980.539 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-09-03T20:23:00.539Z,1756930980.539 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-09-03T20:23:00.540Z,1756930980.540 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-09-03T20:23:01.316Z,1756930981.316 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-09-03T20:23:01.317Z,1756930981.317 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-09-03T20:23:01.486Z,1756930981.486 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-09-03T20:23:01.486Z,1756930981.486 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-09-03T20:23:01.632Z,1756930981.632 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-09-03T20:23:01.632Z,1756930981.632 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-09-03T20:23:01.646Z,1756930981.646 [NavChart] Loaded
2025-09-03T20:23:01.647Z,1756930981.647 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-09-03T20:23:01.652Z,1756930981.652 [UniversalFixResidualReporter] Loaded
2025-09-03T20:23:01.653Z,1756930981.653 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-09-03T20:23:01.653Z,1756930981.653 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-09-03T20:23:01.654Z,1756930981.654 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-09-03T20:23:02.566Z,1756930982.566 [AHRS_M2] Loaded
2025-09-03T20:23:02.566Z,1756930982.566 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-09-03T20:23:02.822Z,1756930982.822 [BackseatComponent] Loaded
2025-09-03T20:23:02.822Z,1756930982.822 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-09-03T20:23:02.824Z,1756930982.824 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A854E0
2025-09-03T20:23:02.824Z,1756930982.824 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 5400
2025-09-03T20:23:02.827Z,1756930982.827 [LcmUniversalReporter] Loaded
2025-09-03T20:23:02.827Z,1756930982.827 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-09-03T20:23:03.604Z,1756930983.604 [BPC1] Loaded
2025-09-03T20:23:03.605Z,1756930983.605 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-09-03T20:23:03.709Z,1756930983.709 [DAT] Loaded
2025-09-03T20:23:03.709Z,1756930983.709 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-09-03T20:23:03.710Z,1756930983.710 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40AB54E0
2025-09-03T20:23:03.710Z,1756930983.710 [DAT ThreadHandler](INFO): Protected caller Thread ID is 5401
2025-09-03T20:23:03.928Z,1756930983.928 [DataOverHttps] Loaded
2025-09-03T20:23:03.929Z,1756930983.929 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-09-03T20:23:03.930Z,1756930983.930 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40AE54E0
2025-09-03T20:23:03.930Z,1756930983.930 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5402
2025-09-03T20:23:03.954Z,1756930983.954 [Depth_Keller] Loaded
2025-09-03T20:23:03.954Z,1756930983.954 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-09-03T20:23:03.960Z,1756930983.960 [DropWeight] Loaded
2025-09-03T20:23:03.960Z,1756930983.960 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-09-03T20:23:04.024Z,1756930984.024 [NAL9602] Loaded
2025-09-03T20:23:04.024Z,1756930984.024 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-09-03T20:23:04.051Z,1756930984.051 [Onboard] Loaded
2025-09-03T20:23:04.052Z,1756930984.052 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-09-03T20:23:04.053Z,1756930984.053 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40B154E0
2025-09-03T20:23:04.053Z,1756930984.053 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 5403
2025-09-03T20:23:04.059Z,1756930984.059 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3
2025-09-03T20:23:04.074Z,1756930984.074 [PowerOnly] Loaded
2025-09-03T20:23:04.074Z,1756930984.074 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2025-09-03T20:23:04.088Z,1756930984.088 [Power24vConverter] Loaded
2025-09-03T20:23:04.088Z,1756930984.088 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-09-03T20:23:04.105Z,1756930984.105 [Radio_Surface] Loaded
2025-09-03T20:23:04.105Z,1756930984.105 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-09-03T20:23:04.106Z,1756930984.106 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40B454E0
2025-09-03T20:23:04.107Z,1756930984.107 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5404
2025-09-03T20:23:04.123Z,1756930984.123 [Sonardyne_Nano] Loaded
2025-09-03T20:23:04.123Z,1756930984.123 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-09-03T20:23:04.124Z,1756930984.124 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-09-03T20:23:04.125Z,1756930984.125 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-09-03T20:23:04.235Z,1756930984.235 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-09-03T20:23:04.237Z,1756930984.237 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-09-03T20:23:04.310Z,1756930984.310 [VerticalControl](DEBUG): Construct VerticalControl.
2025-09-03T20:23:04.362Z,1756930984.362 [VerticalControl] Loaded
2025-09-03T20:23:04.362Z,1756930984.362 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-09-03T20:23:04.365Z,1756930984.365 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-09-03T20:23:04.410Z,1756930984.410 [HorizontalControl] Loaded
2025-09-03T20:23:04.410Z,1756930984.410 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-09-03T20:23:04.413Z,1756930984.413 [SpeedControl](DEBUG): Construct SpeedControl.
2025-09-03T20:23:04.414Z,1756930984.414 [SpeedControl] Loaded
2025-09-03T20:23:04.415Z,1756930984.415 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-09-03T20:23:04.418Z,1756930984.418 [LoopControl](DEBUG): Construct LoopControl.
2025-09-03T20:23:04.418Z,1756930984.418 [LoopControl] Loaded
2025-09-03T20:23:04.418Z,1756930984.418 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-09-03T20:23:04.419Z,1756930984.419 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-09-03T20:23:04.419Z,1756930984.419 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-09-03T20:23:04.435Z,1756930984.435 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-09-03T20:23:04.436Z,1756930984.436 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-09-03T20:23:04.856Z,1756930984.856 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-09-03T20:23:04.862Z,1756930984.862 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-09-03T20:23:04.865Z,1756930984.865 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-09-03T20:23:04.877Z,1756930984.877 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-09-03T20:23:04.878Z,1756930984.878 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C6D4E0
2025-09-03T20:23:04.878Z,1756930984.878 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5405
2025-09-03T20:23:04.883Z,1756930984.883 [Supervisor](INFO): Main Thread ID is 5304
2025-09-03T20:23:04.883Z,1756930984.883 [Supervisor](DEBUG): Running supervisor.
2025-09-03T20:23:04.883Z,1756930984.883 [CommandExec ThreadHandler](INFO): Handler Thread ID is 5406
2025-09-03T20:23:04.884Z,1756930984.884 [CommandExec](INFO): Initializing the command executive.
2025-09-03T20:23:04.886Z,1756930984.886 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5407
2025-09-03T20:23:04.889Z,1756930984.889 [controlThread ThreadHandler](INFO): Handler Thread ID is 5408
2025-09-03T20:23:04.890Z,1756930984.890 [controlThread](DEBUG): Initializing ControlThread
2025-09-03T20:23:04.891Z,1756930984.891 [SBIT](INFO): Initialize SBIT Component.
2025-09-03T20:23:04.892Z,1756930984.892 [SBIT](IMPORTANT): git: 2025-08-26
2025-09-03T20:23:04.892Z,1756930984.892 [SBIT](INFO): git hash: bb7b608ae095ad33f3a1ba1dc4d6b0b36a605f14
2025-09-03T20:23:04.892Z,1756930984.892 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-09-03T20:23:04.894Z,1756930984.894 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2025-09-03T20:23:04.895Z,1756930984.895 [SBIT](INFO): Beginning SBIT in 142.000000 seconds.
2025-09-03T20:23:04.895Z,1756930984.895 [IBIT](INFO): Initialize IBIT Component.
2025-09-03T20:23:04.896Z,1756930984.896 [CBIT](DEBUG): Initialize CBIT Component.
2025-09-03T20:23:04.897Z,1756930984.897 [logger ThreadHandler](INFO): Handler Thread ID is 5409
2025-09-03T20:23:04.908Z,1756930984.908 [CBIT](DEBUG): Initialized mux pins.
2025-09-03T20:23:04.908Z,1756930984.908 [CBIT](DEBUG): Initializing the watchdog timer.
2025-09-03T20:23:04.916Z,1756930984.916 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 5410
2025-09-03T20:23:04.917Z,1756930984.917 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-09-03T20:23:04.921Z,1756930984.921 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5412
2025-09-03T20:23:04.924Z,1756930984.924 [WetLabsBB2FL](INFO): Powering up
2025-09-03T20:23:04.926Z,1756930984.926 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 5414
2025-09-03T20:23:04.932Z,1756930984.932 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-09-03T20:23:04.932Z,1756930984.932 [CBIT](DEBUG): Initializing heartbeat.
2025-09-03T20:23:04.933Z,1756930984.933 [DAT ThreadHandler](INFO): Handler Thread ID is 5415
2025-09-03T20:23:04.934Z,1756930984.934 [DAT](INFO): Powering up
2025-09-03T20:23:04.934Z,1756930984.934 [DAT](DEBUG): Initializing DAT.
2025-09-03T20:23:04.937Z,1756930984.937 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5417
2025-09-03T20:23:04.939Z,1756930984.939 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-09-03T20:23:04.949Z,1756930984.949 [Onboard ThreadHandler](INFO): Handler Thread ID is 5418
2025-09-03T20:23:04.966Z,1756930984.966 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5419
2025-09-03T20:23:04.984Z,1756930984.984 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5420
2025-09-03T20:23:04.996Z,1756930984.996 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-09-03T20:23:04.996Z,1756930984.996 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-09-03T20:23:04.997Z,1756930984.997 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-09-03T20:23:04.997Z,1756930984.997 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-09-03T20:23:04.997Z,1756930984.997 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-09-03T20:23:04.997Z,1756930984.997 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-09-03T20:23:04.997Z,1756930984.997 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-09-03T20:23:04.997Z,1756930984.997 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-09-03T20:23:05.004Z,1756930985.004 [CBIT](DEBUG): Deactivating emergency mode.
2025-09-03T20:23:05.044Z,1756930985.044 [CBIT](DEBUG): Backplane powered.
2025-09-03T20:23:05.044Z,1756930985.044 [GFScanner](DEBUG): Initializing GFScanner
2025-09-03T20:23:05.044Z,1756930985.044 [GFScanner](DEBUG): Deactivating GF circuits.
2025-09-03T20:23:05.052Z,1756930985.052 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-09-03T20:23:05.053Z,1756930985.053 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-09-03T20:23:05.053Z,1756930985.053 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-09-03T20:23:05.053Z,1756930985.053 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-09-03T20:23:05.054Z,1756930985.054 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-09-03T20:23:05.055Z,1756930985.055 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-09-03T20:23:05.055Z,1756930985.055 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-09-03T20:23:05.059Z,1756930985.059 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-09-03T20:23:05.061Z,1756930985.061 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-09-03T20:23:05.061Z,1756930985.061 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-09-03T20:23:05.062Z,1756930985.062 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-09-03T20:23:05.063Z,1756930985.063 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-09-03T20:23:05.064Z,1756930985.064 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-09-03T20:23:05.064Z,1756930985.064 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-09-03T20:23:05.069Z,1756930985.069 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-09-03T20:23:05.160Z,1756930985.160 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2025-09-03T20:23:05.165Z,1756930985.165 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-09-03T20:23:05.224Z,1756930985.224 [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-09-03T20:23:05.224Z,1756930985.224 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-09-03T20:23:05.225Z,1756930985.225 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-09-03T20:23:05.236Z,1756930985.236 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-09-03T20:23:05.372Z,1756930985.372 [Radio_Surface](INFO): Powering up
2025-09-03T20:23:05.577Z,1756930985.577 [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-09-03T20:23:05.583Z,1756930985.583 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-09-03T20:23:05.600Z,1756930985.600 [Default:A.Wait](DEBUG): Construct Wait.
2025-09-03T20:23:05.602Z,1756930985.602 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-09-03T20:23:05.640Z,1756930985.640 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-09-03T20:23:05.643Z,1756930985.643 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-09-03T20:23:05.665Z,1756930985.665 [Default:E.Execute](DEBUG): Construct Execute.
2025-09-03T20:23:05.684Z,1756930985.684 [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-09-03T20:23:05.688Z,1756930985.688 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
2025-09-03T20:23:05.718Z,1756930985.718 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T20:23:05.836Z,1756930985.836 [Depth_Keller](INFO): Initializing.
2025-09-03T20:23:05.838Z,1756930985.838 [Power24vConverter](INFO): Powering up.
2025-09-03T20:23:05.839Z,1756930985.839 [Sonardyne_Nano](INFO): Initializing.
2025-09-03T20:23:05.864Z,1756930985.864 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-09-03T20:23:05.891Z,1756930985.891 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-09-03T20:23:05.896Z,1756930985.896 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-09-03T20:23:05.897Z,1756930985.897 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-09-03T20:23:05.904Z,1756930985.904 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-09-03T20:23:05.905Z,1756930985.905 [MassServo](DEBUG): Initializing EZServoServo.
2025-09-03T20:23:05.912Z,1756930985.912 [MassServo](DEBUG): Initializing MassServo.
2025-09-03T20:23:05.913Z,1756930985.913 [RudderServo](DEBUG): Initializing EZServoServo.
2025-09-03T20:23:05.921Z,1756930985.921 [RudderServo](DEBUG): Initializing RudderServo.
2025-09-03T20:23:05.921Z,1756930985.921 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-09-03T20:23:05.928Z,1756930985.928 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-09-03T20:23:06.144Z,1756930986.144 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2025-09-03T20:23:06.144Z,1756930986.144 [DropWeight] Hardware Fault, FailCount= 1
2025-09-03T20:23:06.144Z,1756930986.144 [DropWeight](ERROR): Hardware Fault
2025-09-03T20:23:06.145Z,1756930986.145 [PowerOnly](INFO): Powering up loadControl
2025-09-03T20:23:06.194Z,1756930986.194 [CommandExec](FAULT): Scheduling is paused
2025-09-03T20:23:06.194Z,1756930986.194 [CBIT](INFO): Critical error at 20250903T202306
2025-09-03T20:23:06.194Z,1756930986.194 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2025-09-03T20:23:06.205Z,1756930986.205 [CBIT](ERROR): Hardware Fault in component: DropWeight
2025-09-03T20:23:06.205Z,1756930986.205 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2025-09-03T20:23:06.549Z,1756930986.549 [CBIT](INFO): Critical error at 20250903T202306
2025-09-03T20:23:07.536Z,1756930987.536 [WetLabsBB2FL](INFO): Powering down
2025-09-03T20:23:08.128Z,1756930988.128 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-09-03T20:23:09.365Z,1756930989.365 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2213
2025-09-03T20:23:11.601Z,1756930991.601 [ThrusterHE](ERROR): Zero Speed Commanded.
2025-09-03T20:23:17.104Z,1756930997.104 [DAT](INFO): DAT read:
2025-09-03T20:23:17.106Z,1756930997.106 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2025-09-03T20:23:18.869Z,1756930998.869 [DAT](INFO): DAT read: MF Frequency Band
2025-09-03T20:23:18.870Z,1756930998.870 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2025-09-03T20:23:18.871Z,1756930998.871 [DAT](INFO): DAT read: Aug 29 2025 00:15:24
2025-09-03T20:23:19.493Z,1756930999.493 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005731
2025-09-03T20:23:20.129Z,1756931000.129 [DAT](INFO): DAT read: Features enabled [Bearing]
2025-09-03T20:23:20.130Z,1756931000.130 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC
2025-09-03T20:23:20.130Z,1756931000.130 [DAT](INFO): commRate: 1200
2025-09-03T20:23:22.196Z,1756931002.196 [DAT](INFO): entering command mode
2025-09-03T20:23:22.397Z,1756931002.397 [DAT](INFO): DAT read:
2025-09-03T20:23:22.397Z,1756931002.397 [DAT](INFO): DAT read: user:1>
2025-09-03T20:23:22.397Z,1756931002.397 [DAT](INFO): setting verbose to 3
2025-09-03T20:23:22.649Z,1756931002.649 [DAT](INFO): DAT read: user:1>
2025-09-03T20:23:22.650Z,1756931002.650 [DAT](INFO): DAT read: Verbose | 3
2025-09-03T20:23:22.650Z,1756931002.650 [DAT](INFO): set verbose to 3
2025-09-03T20:23:22.650Z,1756931002.650 [DAT](INFO): setting DatVerbose to 27440
2025-09-03T20:23:22.901Z,1756931002.901 [DAT](INFO): DAT read: user:2>
2025-09-03T20:23:22.902Z,1756931002.902 [DAT](INFO): DAT read: DatVerbose | 27440
2025-09-03T20:23:22.902Z,1756931002.902 [DAT](INFO): set DatVerbose to 27440
2025-09-03T20:23:22.902Z,1756931002.902 [DAT](INFO): setting transmit power to 8
2025-09-03T20:23:23.153Z,1756931003.153 [DAT](INFO): DAT read: user:3>
2025-09-03T20:23:23.154Z,1756931003.154 [DAT](INFO): DAT read: TxPower | 8 (Max)
2025-09-03T20:23:23.154Z,1756931003.154 [DAT](INFO): set transmit power to 8
2025-09-03T20:23:23.154Z,1756931003.154 [DAT](INFO): setting local address to 5
2025-09-03T20:23:23.405Z,1756931003.405 [DAT](INFO): DAT read: user:4>
2025-09-03T20:23:23.406Z,1756931003.406 [DAT](INFO): DAT read: LocalAddr | 5
2025-09-03T20:23:23.406Z,1756931003.406 [DAT](INFO): set local address to 5
2025-09-03T20:23:23.407Z,1756931003.407 [DAT](INFO): Setting time to: 20:23:23 And date to:9/3/2025
2025-09-03T20:23:23.657Z,1756931003.657 [DAT](INFO): DAT read: user:5>
2025-09-03T20:23:23.658Z,1756931003.658 [DAT](INFO): DAT read: Wed Sep 3, 2025 20:23:23
2025-09-03T20:23:23.658Z,1756931003.658 [DAT](INFO): Local DAT time set to Wed Sep 3, 2025 20:23:23
2025-09-03T20:23:34.397Z,1756931014.397 [NAL9602](INFO): Powering up NAL9602
2025-09-03T20:23:45.317Z,1756931025.317 [NAL9602](INFO): NAL9602 initialized
2025-09-03T20:25:27.531Z,1756931127.531 [SBIT](IMPORTANT): Beginning Startup BIT
2025-09-03T20:25:27.536Z,1756931127.536 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-09-03T20:25:32.197Z,1756931132.197 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.010968
CHAN A1 (24V): 0.000981
CHAN A2 (12V): -0.005590
CHAN A3 (5V): -0.003820
CHAN B0 (3.3V): -0.001275
CHAN B1 (3.15aV): -0.001049
CHAN B2 (3.15bV): -0.000746
CHAN B3 (GND): -0.000843
OPEN: 0.007050
Full Scale: +/- 1 mA
2025-09-03T20:25:42.594Z,1756931142.594 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T20:26:05.220Z,1756931165.220 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-09-03T20:26:05.220Z,1756931165.220 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-09-03T20:26:05.230Z,1756931165.230 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-09-03T20:26:05.637Z,1756931165.637 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-09-03T20:26:05.637Z,1756931165.638 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-09-03T20:26:09.718Z,1756931169.718 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T20:26:10.655Z,1756931170.655 [CommandExec](IMPORTANT): got command configSet list
2025-09-03T20:26:10.660Z,1756931170.660 [CommandExec](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-09-03T20:26:10.660Z,1756931170.660 [CommandExec](IMPORTANT): BPC1.batteryMissingStickThreshold=5 count;
2025-09-03T20:26:10.661Z,1756931170.661 [CommandExec](IMPORTANT): CBIT.runNavErrorCritical=0 bool;
2025-09-03T20:26:10.662Z,1756931170.662 [CommandExec](IMPORTANT): DVL_micro.loadAtStartup=0 bool;
2025-09-03T20:26:10.663Z,1756931170.663 [CommandExec](IMPORTANT): ElevatorServo.currLimit=25 percent;
2025-09-03T20:26:10.663Z,1756931170.663 [CommandExec](IMPORTANT): Express linearApproximation PowerOnly.component_avgCurrent_loadControl 50.000000 milliampere;
2025-09-03T20:26:10.692Z,1756931170.692 [CommandExec](IMPORTANT): HorizontalControl.rudLimit=12 degree;
2025-09-03T20:26:10.694Z,1756931170.694 [CommandExec](IMPORTANT): IBIT.batteryCapacityThreshold=-1 ampere_hour;
2025-09-03T20:26:10.695Z,1756931170.695 [CommandExec](IMPORTANT): IBIT.batteryVoltageThreshold=10 volt;
2025-09-03T20:26:10.695Z,1756931170.695 [CommandExec](IMPORTANT): PowerOnly.loadAtStartup=1 bool;
2025-09-03T20:26:10.708Z,1756931170.708 [CommandExec](IMPORTANT): RDI_Pathfinder.loadAtStartup=0 bool;
2025-09-03T20:26:10.709Z,1756931170.709 [CommandExec](IMPORTANT): RudderServo.currLimit=25 percent;
2025-09-03T20:26:10.710Z,1756931170.710 [CommandExec](IMPORTANT): ThrusterHE.bestEffortMode=1 bool;
2025-09-03T20:26:10.711Z,1756931170.711 [CommandExec](IMPORTANT): VerticalControl.buoyancyNeutral=302.770621 cubic_centimeter;
2025-09-03T20:26:10.766Z,1756931170.766 [CommandExec](IMPORTANT): VerticalControl.massDefault=30.599857 millimeter;
2025-09-03T20:26:21.507Z,1756931181.507 [SBIT](IMPORTANT): SBIT PASSED
2025-09-03T20:26:21.508Z,1756931181.508 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-09-03T20:26:21.509Z,1756931181.509 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=5 count;
2025-09-03T20:26:21.509Z,1756931181.509 [SBIT](IMPORTANT): CBIT.runNavErrorCritical=0 bool;
2025-09-03T20:26:21.509Z,1756931181.509 [SBIT](IMPORTANT): DVL_micro.loadAtStartup=0 bool;
2025-09-03T20:26:21.509Z,1756931181.509 [SBIT](IMPORTANT): ElevatorServo.currLimit=25 percent;
2025-09-03T20:26:21.509Z,1756931181.509 [SBIT](IMPORTANT): Express linearApproximation PowerOnly.component_avgCurrent_loadControl 50.000000 milliampere;
2025-09-03T20:26:21.509Z,1756931181.509 [SBIT](IMPORTANT): HorizontalControl.rudLimit=12 degree;
2025-09-03T20:26:21.509Z,1756931181.509 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=-1 ampere_hour;
2025-09-03T20:26:21.509Z,1756931181.509 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=10 volt;
2025-09-03T20:26:21.510Z,1756931181.510 [SBIT](IMPORTANT): PowerOnly.loadAtStartup=1 bool;
2025-09-03T20:26:21.510Z,1756931181.510 [SBIT](IMPORTANT): RDI_Pathfinder.loadAtStartup=0 bool;
2025-09-03T20:26:21.510Z,1756931181.510 [SBIT](IMPORTANT): RudderServo.currLimit=25 percent;
2025-09-03T20:26:21.510Z,1756931181.510 [SBIT](IMPORTANT): ThrusterHE.bestEffortMode=1 bool;
2025-09-03T20:26:21.510Z,1756931181.510 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=302.770621 cubic_centimeter;
2025-09-03T20:26:21.510Z,1756931181.510 [SBIT](IMPORTANT): VerticalControl.massDefault=30.599857 millimeter;
2025-09-03T20:26:21.902Z,1756931181.902 [MissionManager](IMPORTANT): Started mission Startup
2025-09-03T20:26:21.902Z,1756931181.902 [Startup] Running Loop=1
2025-09-03T20:26:21.902Z,1756931181.902 [Startup](DEBUG): Aggregate::initialize Startup
2025-09-03T20:26:21.902Z,1756931181.902 [Startup:A.GoToSurface] Running Loop=1
2025-09-03T20:26:21.902Z,1756931181.902 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-09-03T20:26:21.903Z,1756931181.903 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-09-03T20:26:21.903Z,1756931181.903 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-09-03T20:26:21.903Z,1756931181.903 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-09-03T20:26:21.904Z,1756931181.904 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-09-03T20:26:21.904Z,1756931181.904 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-09-03T20:26:21.905Z,1756931181.905 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-09-03T20:26:21.906Z,1756931181.906 [Startup:StartupSatComms] Running Loop=1
2025-09-03T20:26:21.906Z,1756931181.906 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-09-03T20:26:21.906Z,1756931181.906 [Startup:StartupSatComms:A] Running Loop=1
2025-09-03T20:26:22.305Z,1756931182.305 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-09-03T20:27:22.114Z,1756931242.114 [Startup:StartupSatComms:A](INFO): Timed out from 2025-09-03T20:26:21.9Z
2025-09-03T20:27:22.114Z,1756931242.114 [Startup:StartupSatComms:A] Stopped
2025-09-03T20:27:22.114Z,1756931242.114 [Startup:StartupSatComms:B] Running Loop=1
2025-09-03T20:27:22.525Z,1756931242.525 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-09-03T20:27:24.376Z,1756931244.376 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:27:24.862Z,1756931244.862 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T20:27:28.150Z,1756931248.150 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction 15 degree
2025-09-03T20:27:28.158Z,1756931248.158 [ComponentRegistry](DEBUG): SyncComponent "Maintain_HorizontalControl.rudderAngleAction" handled in the control thread.
2025-09-03T20:27:28.438Z,1756931248.438 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_HorizontalControl.rudderAngleAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
2025-09-03T20:27:30.633Z,1756931250.633 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250828T225329/Courier0043.lzma
2025-09-03T20:27:30.796Z,1756931250.796 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:27:31.634Z,1756931251.634 [DataOverHttps](INFO): Moved sent file to Logs/20250828T225329/Courier0043.lzma.bak
2025-09-03T20:27:31.634Z,1756931251.634 [DataOverHttps](INFO): SBD MOMSN=25924425
2025-09-03T20:27:40.166Z,1756931260.166 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction -15 degree
2025-09-03T20:27:41.804Z,1756931261.804 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:27:50.663Z,1756931270.663 [DataOverHttps](INFO): Sending 241 bytes from file Logs/20250903T202251/Courier0000.lzma
2025-09-03T20:27:51.662Z,1756931271.662 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Courier0000.lzma.bak
2025-09-03T20:27:51.662Z,1756931271.662 [DataOverHttps](INFO): SBD MOMSN=25924427
2025-09-03T20:27:52.683Z,1756931272.683 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:27:56.237Z,1756931276.237 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:28:04.242Z,1756931284.242 [CommandExec](IMPORTANT): got command configSet RudderServo.currLimit 10 percent persist
2025-09-03T20:28:07.205Z,1756931287.205 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:28:08.133Z,1756931288.133 [DataOverHttps](INFO): Sending 244 bytes from file Logs/20250828T225329/Express0041.lzma
2025-09-03T20:28:08.818Z,1756931288.818 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T20:28:09.134Z,1756931289.134 [DataOverHttps](INFO): Moved sent file to Logs/20250828T225329/Express0041.lzma.bak
2025-09-03T20:28:09.134Z,1756931289.134 [DataOverHttps](INFO): SBD MOMSN=25924432
2025-09-03T20:28:18.326Z,1756931298.326 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:28:22.120Z,1756931302.120 [Startup:StartupSatComms:B](INFO): Timed out from 2025-09-03T20:27:22.1Z
2025-09-03T20:28:22.121Z,1756931302.121 [Startup:StartupSatComms:B] Stopped
2025-09-03T20:28:22.121Z,1756931302.121 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-09-03T20:28:22.121Z,1756931302.121 [Startup:StartupSatComms] Stopped
2025-09-03T20:28:22.121Z,1756931302.121 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-09-03T20:28:22.122Z,1756931302.122 [Startup](INFO): Completed Startup
2025-09-03T20:28:22.122Z,1756931302.122 [MissionManager](INFO): Startup is completed.
2025-09-03T20:28:22.122Z,1756931302.122 [MissionManager](INFO): Uninitializing Mission Startup
2025-09-03T20:28:22.122Z,1756931302.122 [Startup] Stopped
2025-09-03T20:28:22.122Z,1756931302.122 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-09-03T20:28:22.122Z,1756931302.122 [Startup:A.GoToSurface] Stopped
2025-09-03T20:28:22.122Z,1756931302.122 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-09-03T20:28:22.514Z,1756931302.514 [MissionManager](IMPORTANT): Started mission Default
2025-09-03T20:28:22.514Z,1756931302.514 [Default] Running Loop=1
2025-09-03T20:28:22.514Z,1756931302.514 [Default](DEBUG): Aggregate::initialize Default
2025-09-03T20:28:22.514Z,1756931302.514 [Default:B.GoToSurface] Running Loop=1
2025-09-03T20:28:22.514Z,1756931302.514 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-09-03T20:28:22.514Z,1756931302.514 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-09-03T20:28:22.515Z,1756931302.515 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-09-03T20:28:22.515Z,1756931302.515 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-09-03T20:28:22.515Z,1756931302.515 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-09-03T20:28:22.520Z,1756931302.520 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-09-03T20:28:22.520Z,1756931302.520 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-09-03T20:28:22.521Z,1756931302.521 [Default:A.Wait] Running Loop=1
2025-09-03T20:28:22.521Z,1756931302.521 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-09-03T20:28:25.005Z,1756931305.005 [DataOverHttps](INFO): Sending 98 bytes from file Logs/20250828T225329/Express0044.lzma
2025-09-03T20:28:26.006Z,1756931306.006 [DataOverHttps](INFO): Moved sent file to Logs/20250828T225329/Express0044.lzma.bak
2025-09-03T20:28:26.006Z,1756931306.006 [DataOverHttps](INFO): SBD MOMSN=25924436
2025-09-03T20:28:29.494Z,1756931309.494 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:28:35.866Z,1756931315.866 [Default:A.Wait](INFO): Done Waiting.
2025-09-03T20:28:35.866Z,1756931315.866 [Default:A.Wait] Stopped
2025-09-03T20:28:35.866Z,1756931315.866 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-09-03T20:28:36.270Z,1756931316.270 [Default:CheckIn] Running Loop=1
2025-09-03T20:28:36.270Z,1756931316.270 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-03T20:28:36.270Z,1756931316.270 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-03T20:28:36.707Z,1756931316.707 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-09-03T20:28:40.805Z,1756931320.805 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:28:40.950Z,1756931320.950 [CommandExec](IMPORTANT): got command configSet RudderServo.currLimit 10 percent persist
2025-09-03T20:28:48.758Z,1756931328.758 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-09-03T20:28:51.257Z,1756931331.257 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:28:53.619Z,1756931333.619 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202845.00,A,3648.16462,N,12147.28905,W,0.058,0.00,030925,,,A*7C
2025-09-03T20:28:53.622Z,1756931333.622 [NAL9602](INFO): GPS fix at 20250903T202845: (36.802744, -121.788151)
2025-09-03T20:28:53.634Z,1756931333.634 [Default:CheckIn:Read_GPS] Stopped
2025-09-03T20:28:53.634Z,1756931333.634 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-03T20:28:54.061Z,1756931334.061 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-09-03T20:29:00.041Z,1756931340.041 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction 15 degree
2025-09-03T20:29:02.307Z,1756931342.307 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:29:03.108Z,1756931343.108 [DataOverHttps](INFO): Sending 227 bytes from file Logs/20250903T202251/Courier0004.lzma
2025-09-03T20:29:04.110Z,1756931344.110 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Courier0004.lzma.bak
2025-09-03T20:29:04.110Z,1756931344.110 [DataOverHttps](INFO): SBD MOMSN=25924445
2025-09-03T20:29:11.505Z,1756931351.505 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction -15 degree
2025-09-03T20:29:13.231Z,1756931353.231 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:29:16.386Z,1756931356.386 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T20:29:24.509Z,1756931364.509 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:29:25.387Z,1756931365.387 [DataOverHttps](INFO): Sending 1219 bytes from file Logs/20250903T202251/Express0001.lzma
2025-09-03T20:29:26.030Z,1756931366.030 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-03T20:29:26.386Z,1756931366.386 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Express0001.lzma.bak
2025-09-03T20:29:26.386Z,1756931366.386 [DataOverHttps](INFO): SBD MOMSN=25924451
2025-09-03T20:29:27.335Z,1756931367.335 [CommandExec](IMPORTANT): got command get platform_rudder_angle
2025-09-03T20:29:27.336Z,1756931367.336 [CommandExec](IMPORTANT): platform_rudder_angle -0.261109 rad
2025-09-03T20:29:28.139Z,1756931368.139 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T20:29:32.131Z,1756931372.131 [CommandExec](IMPORTANT): got command get platform_rudder_angle degree
2025-09-03T20:29:32.168Z,1756931372.168 [CommandExec](IMPORTANT): platform_rudder_angle -14.960433 arcdeg
2025-09-03T20:29:35.407Z,1756931375.407 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:29:45.133Z,1756931385.133 [DataOverHttps](INFO): Sending 535 bytes from file Logs/20250903T202251/Express0005.lzma
2025-09-03T20:29:46.134Z,1756931386.134 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Express0005.lzma.bak
2025-09-03T20:29:46.134Z,1756931386.134 [DataOverHttps](INFO): SBD MOMSN=25924499
2025-09-03T20:29:46.765Z,1756931386.765 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:29:47.868Z,1756931387.868 [Default:CheckIn:Read_Iridium] Stopped
2025-09-03T20:29:47.868Z,1756931387.868 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-03T20:29:47.868Z,1756931387.868 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-03T20:29:48.379Z,1756931388.379 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction 15 degree
2025-09-03T20:30:00.192Z,1756931400.192 [CommandExec](IMPORTANT): got command get platform_rudder_angle degree
2025-09-03T20:30:00.193Z,1756931400.193 [CommandExec](IMPORTANT): platform_rudder_angle 14.973630 arcdeg
2025-09-03T20:30:19.717Z,1756931419.717 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction 15 degree
2025-09-03T20:30:31.149Z,1756931431.149 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:30:55.694Z,1756931455.694 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction 0 degree
2025-09-03T20:31:03.740Z,1756931463.740 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T20:31:47.043Z,1756931507.043 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T20:32:00.298Z,1756931520.298 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:32:14.506Z,1756931534.506 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T20:32:29.400Z,1756931549.400 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:33:11.811Z,1756931591.811 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:33:12.228Z,1756931592.228 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T20:33:41.373Z,1756931621.373 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T20:34:26.207Z,1756931666.207 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T20:34:48.331Z,1756931688.331 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-03T20:34:48.332Z,1756931688.332 [Default:CheckIn:C.Wait] Stopped
2025-09-03T20:34:48.332Z,1756931688.332 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-03T20:34:48.332Z,1756931688.332 [Default:CheckIn:D] Running Loop=1
2025-09-03T20:34:48.754Z,1756931688.754 [Default:CheckIn:D] Stopped
2025-09-03T20:34:48.754Z,1756931688.754 [Default:CheckIn:E] Running Loop=1
2025-09-03T20:34:49.172Z,1756931689.172 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.437330 min
2025-09-03T20:34:49.172Z,1756931689.172 [Default:CheckIn:E] Stopped
2025-09-03T20:34:49.173Z,1756931689.173 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-03T20:34:49.173Z,1756931689.173 [Default:CheckIn] Stopped
2025-09-03T20:34:49.173Z,1756931689.173 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-03T20:34:49.173Z,1756931689.173 [Default:CheckIn](INFO): Running loop #2
2025-09-03T20:34:49.173Z,1756931689.173 [Default:CheckIn] Running Loop=2
2025-09-03T20:34:49.173Z,1756931689.173 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-03T20:34:49.173Z,1756931689.173 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-03T20:34:51.148Z,1756931691.148 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203442.00,A,3648.16329,N,12147.28731,W,0.369,80.85,030925,,,A*43
2025-09-03T20:34:51.150Z,1756931691.150 [NAL9602](INFO): GPS fix at 20250903T203442: (36.802721, -121.788122)
2025-09-03T20:34:51.161Z,1756931691.161 [Default:CheckIn:Read_GPS] Stopped
2025-09-03T20:34:51.161Z,1756931691.161 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-03T20:34:59.111Z,1756931699.111 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250903T202251/Courier0007.lzma
2025-09-03T20:35:00.114Z,1756931700.114 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Courier0007.lzma.bak
2025-09-03T20:35:00.114Z,1756931700.114 [DataOverHttps](INFO): SBD MOMSN=25924513
2025-09-03T20:35:16.261Z,1756931716.261 [DataOverHttps](INFO): Sending 411 bytes from file Logs/20250903T202251/Express0008.lzma
2025-09-03T20:35:18.312Z,1756931718.312 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Express0008.lzma.bak
2025-09-03T20:35:18.312Z,1756931718.312 [DataOverHttps](INFO): SBD MOMSN=25924516
2025-09-03T20:35:19.059Z,1756931719.059 [Default:CheckIn:Read_Iridium] Stopped
2025-09-03T20:35:19.059Z,1756931719.059 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-03T20:35:19.059Z,1756931719.059 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-03T20:35:21.846Z,1756931721.846 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-09-03T20:35:21.924Z,1756931721.924 [NAL9602](ERROR): received:
+CSQ:0
OK
2025-09-03T20:35:30.379Z,1756931730.379 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:35:58.731Z,1756931758.731 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T20:36:28.175Z,1756931788.175 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T20:37:07.287Z,1756931827.287 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-09-03T20:37:07.287Z,1756931827.287 [BPC1](ERROR): BPC1A failed to parse battery stick message.
2025-09-03T20:37:18.671Z,1756931838.671 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:37:29.571Z,1756931849.571 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:37:39.286Z,1756931859.286 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T20:37:40.487Z,1756931860.487 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:37:51.790Z,1756931871.790 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:38:03.109Z,1756931883.109 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:38:06.320Z,1756931886.320 [CBIT](INFO): Clearing failed state for component DropWeight
2025-09-03T20:38:06.320Z,1756931886.320 [DropWeight] No Fault, FailCount= 1
2025-09-03T20:38:13.186Z,1756931893.186 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T20:38:14.413Z,1756931894.413 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:38:25.325Z,1756931905.325 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:38:26.977Z,1756931906.977 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T20:38:36.765Z,1756931916.765 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:38:47.952Z,1756931927.952 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:38:58.862Z,1756931938.862 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:39:05.318Z,1756931945.318 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T20:39:10.176Z,1756931950.176 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:39:21.078Z,1756931961.078 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:39:31.986Z,1756931971.986 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:39:43.706Z,1756931983.706 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T20:39:52.930Z,1756931992.930 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-09-03T20:40:19.622Z,1756932019.622 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-03T20:40:19.622Z,1756932019.622 [Default:CheckIn:C.Wait] Stopped
2025-09-03T20:40:19.622Z,1756932019.622 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-03T20:40:19.622Z,1756932019.622 [Default:CheckIn:D] Running Loop=1
2025-09-03T20:40:20.042Z,1756932020.042 [Default:CheckIn:D] Stopped
2025-09-03T20:40:20.042Z,1756932020.042 [Default:CheckIn:E] Running Loop=1
2025-09-03T20:40:20.426Z,1756932020.426 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.958804 min
2025-09-03T20:40:20.426Z,1756932020.426 [Default:CheckIn:E] Stopped
2025-09-03T20:40:20.426Z,1756932020.426 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-03T20:40:20.427Z,1756932020.427 [Default:CheckIn] Stopped
2025-09-03T20:40:20.427Z,1756932020.427 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-03T20:40:20.427Z,1756932020.427 [Default:CheckIn](INFO): Running loop #3
2025-09-03T20:40:20.427Z,1756932020.427 [Default:CheckIn] Running Loop=3
2025-09-03T20:40:20.427Z,1756932020.427 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-03T20:40:20.427Z,1756932020.427 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-03T20:40:22.423Z,1756932022.423 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204013.00,A,3648.16217,N,12147.28850,W,0.350,85.28,030925,,,A*48
2025-09-03T20:40:22.434Z,1756932022.434 [NAL9602](INFO): GPS fix at 20250903T204013: (36.802703, -121.788142)
2025-09-03T20:40:22.444Z,1756932022.444 [Default:CheckIn:Read_GPS] Stopped
2025-09-03T20:40:22.444Z,1756932022.444 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-03T20:40:25.593Z,1756932025.593 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T20:40:33.111Z,1756932033.111 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20250903T202251/Courier0010.lzma
2025-09-03T20:40:34.114Z,1756932034.114 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Courier0010.lzma.bak
2025-09-03T20:40:34.114Z,1756932034.114 [DataOverHttps](INFO): SBD MOMSN=25924579
2025-09-03T20:40:50.931Z,1756932050.931 [DataOverHttps](INFO): Sending 271 bytes from file Logs/20250903T202251/Express0011.lzma
2025-09-03T20:40:51.930Z,1756932051.930 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Express0011.lzma.bak
2025-09-03T20:40:51.930Z,1756932051.930 [DataOverHttps](INFO): SBD MOMSN=25924582
2025-09-03T20:40:53.331Z,1756932053.331 [Default:CheckIn:Read_Iridium] Stopped
2025-09-03T20:40:53.331Z,1756932053.331 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-03T20:40:53.331Z,1756932053.331 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-03T20:40:55.505Z,1756932055.505 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-03T20:41:18.304Z,1756932078.304 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:42:41.294Z,1756932161.294 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T20:43:34.975Z,1756932214.975 [BPC1](ERROR): BPC1A failed to parse battery stick message.
2025-09-03T20:44:25.934Z,1756932265.934 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T20:45:42.698Z,1756932342.698 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T20:45:53.961Z,1756932353.961 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-03T20:45:53.961Z,1756932353.961 [Default:CheckIn:C.Wait] Stopped
2025-09-03T20:45:53.961Z,1756932353.961 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-03T20:45:53.961Z,1756932353.961 [Default:CheckIn:D] Running Loop=1
2025-09-03T20:45:54.358Z,1756932354.358 [Default:CheckIn:D] Stopped
2025-09-03T20:45:54.358Z,1756932354.358 [Default:CheckIn:E] Running Loop=1
2025-09-03T20:45:54.840Z,1756932354.840 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.530737 min
2025-09-03T20:45:54.841Z,1756932354.841 [Default:CheckIn:E] Stopped
2025-09-03T20:45:54.841Z,1756932354.841 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-03T20:45:54.841Z,1756932354.841 [Default:CheckIn] Stopped
2025-09-03T20:45:54.841Z,1756932354.841 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-03T20:45:54.841Z,1756932354.841 [Default:CheckIn](INFO): Running loop #4
2025-09-03T20:45:54.841Z,1756932354.841 [Default:CheckIn] Running Loop=4
2025-09-03T20:45:54.841Z,1756932354.841 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-03T20:45:54.841Z,1756932354.841 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-03T20:45:56.793Z,1756932356.793 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204548.00,A,3648.16368,N,12147.28313,W,0.097,85.28,030925,,,A*4E
2025-09-03T20:45:56.795Z,1756932356.795 [NAL9602](INFO): GPS fix at 20250903T204548: (36.802728, -121.788052)
2025-09-03T20:45:56.806Z,1756932356.806 [Default:CheckIn:Read_GPS] Stopped
2025-09-03T20:45:56.806Z,1756932356.806 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-03T20:46:04.809Z,1756932364.809 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250903T202251/Courier0013.lzma
2025-09-03T20:46:05.806Z,1756932365.806 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Courier0013.lzma.bak
2025-09-03T20:46:05.807Z,1756932365.807 [DataOverHttps](INFO): SBD MOMSN=25924623
2025-09-03T20:46:21.936Z,1756932381.936 [DataOverHttps](INFO): Sending 177 bytes from file Logs/20250903T202251/Express0014.lzma
2025-09-03T20:46:23.235Z,1756932383.235 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Express0014.lzma.bak
2025-09-03T20:46:23.235Z,1756932383.235 [DataOverHttps](INFO): SBD MOMSN=25924626
2025-09-03T20:46:24.317Z,1756932384.317 [Default:CheckIn:Read_Iridium] Stopped
2025-09-03T20:46:24.317Z,1756932384.317 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-03T20:46:24.317Z,1756932384.317 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-03T20:46:25.532Z,1756932385.532 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T20:46:27.492Z,1756932387.492 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-09-03T20:46:27.568Z,1756932387.568 [NAL9602](ERROR): received:
+CSQ:0
OK
2025-09-03T20:46:50.098Z,1756932410.098 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-09-03T20:46:50.099Z,1756932410.099 [BPC1](ERROR): BPC1A failed to parse battery stick message.
2025-09-03T20:46:55.421Z,1756932415.421 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T20:46:59.814Z,1756932419.814 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-09-03T20:46:59.815Z,1756932419.815 [BPC1](ERROR): BPC1A failed to parse battery stick message.
2025-09-03T20:47:10.768Z,1756932430.768 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:47:21.682Z,1756932441.682 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:47:22.485Z,1756932442.485 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T20:47:32.593Z,1756932452.593 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:47:43.892Z,1756932463.892 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:47:54.812Z,1756932474.812 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:48:05.715Z,1756932485.715 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:48:15.352Z,1756932495.352 [NAL9602](INFO): SBD MO Status=2, MOMSN=22863, MT Status=2, MTMSN=0
2025-09-03T20:48:15.352Z,1756932495.352 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-03T20:48:17.029Z,1756932497.029 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:48:28.338Z,1756932508.338 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:48:39.653Z,1756932519.653 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:48:50.559Z,1756932530.559 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:49:00.416Z,1756932540.416 [CommandExec](IMPORTANT): got command failComponent
2025-09-03T20:49:00.417Z,1756932540.417 [CommandExec](IMPORTANT): Failed components:
2025-09-03T20:49:00.417Z,1756932540.417 [CommandExec](IMPORTANT): No failed Components.
2025-09-03T20:49:01.524Z,1756932541.524 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:49:12.370Z,1756932552.370 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T20:49:22.815Z,1756932562.815 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-09-03T20:49:22.815Z,1756932562.815 [BPC1](ERROR): BPC1A failed to parse battery stick message.
2025-09-03T20:49:30.583Z,1756932570.583 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T20:50:10.933Z,1756932610.933 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T20:50:49.389Z,1756932649.389 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T20:50:59.025Z,1756932659.025 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-09-03T20:51:24.900Z,1756932684.900 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-03T20:51:24.900Z,1756932684.900 [Default:CheckIn:C.Wait] Stopped
2025-09-03T20:51:24.900Z,1756932684.900 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-03T20:51:24.900Z,1756932684.900 [Default:CheckIn:D] Running Loop=1
2025-09-03T20:51:25.298Z,1756932685.298 [Default:CheckIn:D] Stopped
2025-09-03T20:51:25.298Z,1756932685.298 [Default:CheckIn:E] Running Loop=1
2025-09-03T20:51:25.702Z,1756932685.702 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.046403 min
2025-09-03T20:51:25.702Z,1756932685.702 [Default:CheckIn:E] Stopped
2025-09-03T20:51:25.702Z,1756932685.702 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-03T20:51:25.702Z,1756932685.702 [Default:CheckIn] Stopped
2025-09-03T20:51:25.702Z,1756932685.702 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-03T20:51:25.703Z,1756932685.703 [Default:CheckIn](INFO): Running loop #5
2025-09-03T20:51:25.703Z,1756932685.703 [Default:CheckIn] Running Loop=5
2025-09-03T20:51:25.703Z,1756932685.703 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-03T20:51:25.703Z,1756932685.703 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-03T20:51:27.712Z,1756932687.712 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205119.00,A,3648.16577,N,12147.27838,W,0.136,98.47,030925,,,A*45
2025-09-03T20:51:27.714Z,1756932687.714 [NAL9602](INFO): GPS fix at 20250903T205119: (36.802763, -121.787973)
2025-09-03T20:51:27.725Z,1756932687.725 [Default:CheckIn:Read_GPS] Stopped
2025-09-03T20:51:27.725Z,1756932687.725 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-03T20:51:35.108Z,1756932695.108 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250903T202251/Courier0016.lzma
2025-09-03T20:51:36.110Z,1756932696.110 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Courier0016.lzma.bak
2025-09-03T20:51:36.110Z,1756932696.110 [DataOverHttps](INFO): SBD MOMSN=25924639
2025-09-03T20:51:48.825Z,1756932708.825 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T20:51:52.052Z,1756932712.052 [DataOverHttps](INFO): Sending 300 bytes from file Logs/20250903T202251/Express0017.lzma
2025-09-03T20:51:53.054Z,1756932713.054 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Express0017.lzma.bak
2025-09-03T20:51:53.054Z,1756932713.054 [DataOverHttps](INFO): SBD MOMSN=25924642
2025-09-03T20:51:54.407Z,1756932714.407 [Default:CheckIn:Read_Iridium] Stopped
2025-09-03T20:51:54.407Z,1756932714.407 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-03T20:51:54.407Z,1756932714.407 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-03T20:52:00.027Z,1756932720.027 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-03T20:53:33.009Z,1756932813.009 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T20:54:06.075Z,1756932846.075 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2025-09-03T20:54:06.078Z,1756932846.078 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2025-09-03T20:56:54.958Z,1756933014.958 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-03T20:56:54.958Z,1756933014.958 [Default:CheckIn:C.Wait] Stopped
2025-09-03T20:56:54.958Z,1756933014.958 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-03T20:56:54.958Z,1756933014.958 [Default:CheckIn:D] Running Loop=1
2025-09-03T20:56:55.367Z,1756933015.367 [Default:CheckIn:D] Stopped
2025-09-03T20:56:55.367Z,1756933015.367 [Default:CheckIn:E] Running Loop=1
2025-09-03T20:56:55.776Z,1756933015.776 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.547557 min
2025-09-03T20:56:55.776Z,1756933015.776 [Default:CheckIn:E] Stopped
2025-09-03T20:56:55.776Z,1756933015.776 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-03T20:56:55.776Z,1756933015.776 [Default:CheckIn] Stopped
2025-09-03T20:56:55.776Z,1756933015.776 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-03T20:56:55.777Z,1756933015.777 [Default:CheckIn](INFO): Running loop #6
2025-09-03T20:56:55.777Z,1756933015.777 [Default:CheckIn] Running Loop=6
2025-09-03T20:56:55.777Z,1756933015.777 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-03T20:56:55.777Z,1756933015.777 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-03T20:56:57.781Z,1756933017.781 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205649.00,A,3648.16329,N,12147.28256,W,0.214,98.47,030925,,,A*44
2025-09-03T20:56:57.784Z,1756933017.784 [NAL9602](INFO): GPS fix at 20250903T205649: (36.802721, -121.788043)
2025-09-03T20:56:57.824Z,1756933017.824 [Default:CheckIn:Read_GPS] Stopped
2025-09-03T20:56:57.824Z,1756933017.824 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-03T20:57:04.940Z,1756933024.940 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20250903T202251/Courier0019.lzma
2025-09-03T20:57:05.942Z,1756933025.942 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Courier0019.lzma.bak
2025-09-03T20:57:05.942Z,1756933025.942 [DataOverHttps](INFO): SBD MOMSN=25924649
2025-09-03T20:57:20.968Z,1756933040.968 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20250903T202251/Express0020.lzma
2025-09-03T20:57:21.970Z,1756933041.970 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Express0020.lzma.bak
2025-09-03T20:57:21.970Z,1756933041.970 [DataOverHttps](INFO): SBD MOMSN=25924652
2025-09-03T20:57:23.258Z,1756933043.258 [Default:CheckIn:Read_Iridium] Stopped
2025-09-03T20:57:23.258Z,1756933043.258 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-03T20:57:23.258Z,1756933043.258 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-03T20:57:28.501Z,1756933048.501 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-09-03T20:57:28.585Z,1756933048.585 [NAL9602](ERROR): received:
+CSQ:0
OK863, 2, 0, 0, 0
OK
2025-09-03T21:02:00.014Z,1756933320.014 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-09-03T21:02:23.853Z,1756933343.853 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-03T21:02:23.853Z,1756933343.853 [Default:CheckIn:C.Wait] Stopped
2025-09-03T21:02:23.853Z,1756933343.853 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-03T21:02:23.854Z,1756933343.854 [Default:CheckIn:D] Running Loop=1
2025-09-03T21:02:24.258Z,1756933344.258 [Default:CheckIn:D] Stopped
2025-09-03T21:02:24.258Z,1756933344.258 [Default:CheckIn:E] Running Loop=1
2025-09-03T21:02:24.671Z,1756933344.671 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.029061 min
2025-09-03T21:02:24.671Z,1756933344.671 [Default:CheckIn:E] Stopped
2025-09-03T21:02:24.671Z,1756933344.671 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-03T21:02:24.671Z,1756933344.671 [Default:CheckIn] Stopped
2025-09-03T21:02:24.671Z,1756933344.671 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-03T21:02:24.672Z,1756933344.672 [Default:CheckIn](INFO): Running loop #7
2025-09-03T21:02:24.672Z,1756933344.672 [Default:CheckIn] Running Loop=7
2025-09-03T21:02:24.672Z,1756933344.672 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-03T21:02:24.672Z,1756933344.672 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-03T21:02:26.679Z,1756933346.679 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210218.00,A,3648.16141,N,12147.28290,W,0.097,98.47,030925,,,A*4F
2025-09-03T21:02:26.682Z,1756933346.682 [NAL9602](INFO): GPS fix at 20250903T210218: (36.802690, -121.788048)
2025-09-03T21:02:26.693Z,1756933346.693 [Default:CheckIn:Read_GPS] Stopped
2025-09-03T21:02:26.693Z,1756933346.693 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-03T21:02:32.791Z,1756933352.791 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250903T202251/Courier0022.lzma
2025-09-03T21:02:33.766Z,1756933353.766 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Courier0022.lzma.bak
2025-09-03T21:02:33.766Z,1756933353.766 [DataOverHttps](INFO): SBD MOMSN=25924661
2025-09-03T21:02:49.772Z,1756933369.772 [DataOverHttps](INFO): Sending 254 bytes from file Logs/20250903T202251/Express0023.lzma
2025-09-03T21:02:50.774Z,1756933370.774 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Express0023.lzma.bak
2025-09-03T21:02:50.774Z,1756933370.774 [DataOverHttps](INFO): SBD MOMSN=25924664
2025-09-03T21:02:52.141Z,1756933372.141 [Default:CheckIn:Read_Iridium] Stopped
2025-09-03T21:02:52.141Z,1756933372.141 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-03T21:02:52.141Z,1756933372.141 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-03T21:02:59.406Z,1756933379.406 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-03T21:07:52.716Z,1756933672.716 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-03T21:07:52.717Z,1756933672.717 [Default:CheckIn:C.Wait] Stopped
2025-09-03T21:07:52.717Z,1756933672.717 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-03T21:07:52.717Z,1756933672.717 [Default:CheckIn:D] Running Loop=1
2025-09-03T21:07:53.114Z,1756933673.114 [Default:CheckIn:D] Stopped
2025-09-03T21:07:53.114Z,1756933673.114 [Default:CheckIn:E] Running Loop=1
2025-09-03T21:07:53.518Z,1756933673.518 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 39.510006 min
2025-09-03T21:07:53.518Z,1756933673.518 [Default:CheckIn:E] Stopped
2025-09-03T21:07:53.518Z,1756933673.518 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-03T21:07:53.518Z,1756933673.518 [Default:CheckIn] Stopped
2025-09-03T21:07:53.519Z,1756933673.519 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-03T21:07:53.519Z,1756933673.519 [Default:CheckIn](INFO): Running loop #8
2025-09-03T21:07:53.519Z,1756933673.519 [Default:CheckIn] Running Loop=8
2025-09-03T21:07:53.519Z,1756933673.519 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-03T21:07:53.519Z,1756933673.519 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-03T21:07:55.535Z,1756933675.535 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210747.00,A,3648.16465,N,12147.28257,W,0.097,0.00,030925,,,A*7A
2025-09-03T21:07:55.538Z,1756933675.538 [NAL9602](INFO): GPS fix at 20250903T210747: (36.802744, -121.788043)
2025-09-03T21:07:55.549Z,1756933675.549 [Default:CheckIn:Read_GPS] Stopped
2025-09-03T21:07:55.549Z,1756933675.549 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-03T21:08:04.460Z,1756933684.460 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250903T202251/Courier0025.lzma
2025-09-03T21:08:05.462Z,1756933685.462 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Courier0025.lzma.bak
2025-09-03T21:08:05.462Z,1756933685.462 [DataOverHttps](INFO): SBD MOMSN=25924668
2025-09-03T21:08:07.649Z,1756933687.649 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2025-09-03T21:08:07.649Z,1756933687.649 [DropWeight] Hardware Fault, FailCount= 1
2025-09-03T21:08:07.649Z,1756933687.649 [DropWeight](ERROR): Hardware Fault
2025-09-03T21:08:07.667Z,1756933687.667 [CBIT](INFO): Critical error at 20250903T210807
2025-09-03T21:08:07.669Z,1756933687.669 [CBIT](ERROR): Hardware Fault in component: DropWeight
2025-09-03T21:08:07.670Z,1756933687.670 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2025-09-03T21:08:08.079Z,1756933688.079 [CBIT](INFO): Critical error at 20250903T210807
2025-09-03T21:08:21.477Z,1756933701.477 [DataOverHttps](INFO): Sending 166 bytes from file Logs/20250903T202251/Express0026.lzma
2025-09-03T21:08:22.478Z,1756933702.478 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Express0026.lzma.bak
2025-09-03T21:08:22.478Z,1756933702.478 [DataOverHttps](INFO): SBD MOMSN=25924671
2025-09-03T21:08:23.823Z,1756933703.823 [Default:CheckIn:Read_Iridium] Stopped
2025-09-03T21:08:23.823Z,1756933703.823 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-03T21:08:23.823Z,1756933703.823 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-03T21:08:26.252Z,1756933706.252 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-09-03T21:08:26.328Z,1756933706.328 [NAL9602](ERROR): received:
+CSQ:0
OK863, 2, 0, 0, 0
OK
2025-09-03T21:12:11.669Z,1756933931.669 [NAL9602](INFO): SBD MO Status=2, MOMSN=22863, MT Status=2, MTMSN=0
2025-09-03T21:12:11.669Z,1756933931.669 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-03T21:12:29.037Z,1756933949.037 [NAL9602](INFO): SBD MO Status=2, MOMSN=22863, MT Status=2, MTMSN=0
2025-09-03T21:12:29.037Z,1756933949.037 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-03T21:13:03.398Z,1756933983.398 [NAL9602](INFO): SBD MO Status=2, MOMSN=22863, MT Status=2, MTMSN=0
2025-09-03T21:13:03.398Z,1756933983.398 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-03T21:13:03.786Z,1756933983.786 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-09-03T21:13:24.398Z,1756934004.398 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-03T21:13:24.398Z,1756934004.398 [Default:CheckIn:C.Wait] Stopped
2025-09-03T21:13:24.398Z,1756934004.398 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-03T21:13:24.398Z,1756934004.398 [Default:CheckIn:D] Running Loop=1
2025-09-03T21:13:24.800Z,1756934004.800 [Default:CheckIn:D] Stopped
2025-09-03T21:13:24.800Z,1756934004.800 [Default:CheckIn:E] Running Loop=1
2025-09-03T21:13:25.209Z,1756934005.209 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.038094 min
2025-09-03T21:13:25.209Z,1756934005.209 [Default:CheckIn:E] Stopped
2025-09-03T21:13:25.210Z,1756934005.210 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-03T21:13:25.210Z,1756934005.210 [Default:CheckIn] Stopped
2025-09-03T21:13:25.210Z,1756934005.210 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-03T21:13:25.210Z,1756934005.210 [Default:CheckIn](INFO): Running loop #9
2025-09-03T21:13:25.210Z,1756934005.210 [Default:CheckIn] Running Loop=9
2025-09-03T21:13:25.210Z,1756934005.210 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-03T21:13:25.210Z,1756934005.210 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-03T21:13:27.213Z,1756934007.213 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211318.00,A,3648.16128,N,12147.28430,W,0.097,0.00,030925,,,A*7E
2025-09-03T21:13:27.216Z,1756934007.216 [NAL9602](INFO): GPS fix at 20250903T211318: (36.802688, -121.788072)
2025-09-03T21:13:27.226Z,1756934007.226 [Default:CheckIn:Read_GPS] Stopped
2025-09-03T21:13:27.227Z,1756934007.227 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-03T21:13:34.616Z,1756934014.616 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250903T202251/Courier0028.lzma
2025-09-03T21:13:35.618Z,1756934015.618 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Courier0028.lzma.bak
2025-09-03T21:13:35.618Z,1756934015.618 [DataOverHttps](INFO): SBD MOMSN=25924686
2025-09-03T21:13:52.592Z,1756934032.592 [DataOverHttps](INFO): Sending 249 bytes from file Logs/20250903T202251/Express0029.lzma
2025-09-03T21:13:53.595Z,1756934033.595 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Express0029.lzma.bak
2025-09-03T21:13:53.595Z,1756934033.595 [DataOverHttps](INFO): SBD MOMSN=25924691
2025-09-03T21:13:55.119Z,1756934035.119 [Default:CheckIn:Read_Iridium] Stopped
2025-09-03T21:13:55.120Z,1756934035.120 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-03T21:13:55.120Z,1756934035.120 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-03T21:13:59.149Z,1756934039.149 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-03T21:18:55.706Z,1756934335.706 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-03T21:18:55.706Z,1756934335.706 [Default:CheckIn:C.Wait] Stopped
2025-09-03T21:18:55.706Z,1756934335.706 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-03T21:18:55.706Z,1756934335.706 [Default:CheckIn:D] Running Loop=1
2025-09-03T21:18:56.114Z,1756934336.114 [Default:CheckIn:D] Stopped
2025-09-03T21:18:56.115Z,1756934336.115 [Default:CheckIn:E] Running Loop=1
2025-09-03T21:18:56.519Z,1756934336.519 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 50.560010 min
2025-09-03T21:18:56.519Z,1756934336.519 [Default:CheckIn:E] Stopped
2025-09-03T21:18:56.519Z,1756934336.519 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-03T21:18:56.519Z,1756934336.519 [Default:CheckIn] Stopped
2025-09-03T21:18:56.519Z,1756934336.519 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-03T21:18:56.520Z,1756934336.520 [Default:CheckIn](INFO): Running loop #10
2025-09-03T21:18:56.520Z,1756934336.520 [Default:CheckIn] Running Loop=10
2025-09-03T21:18:56.520Z,1756934336.520 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-03T21:18:56.520Z,1756934336.520 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-03T21:18:58.514Z,1756934338.514 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211850.00,A,3648.16475,N,12147.28419,W,0.117,0.00,030925,,,A*76
2025-09-03T21:18:58.516Z,1756934338.516 [NAL9602](INFO): GPS fix at 20250903T211850: (36.802746, -121.788070)
2025-09-03T21:18:58.527Z,1756934338.527 [Default:CheckIn:Read_GPS] Stopped
2025-09-03T21:18:58.527Z,1756934338.527 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-03T21:19:06.003Z,1756934346.003 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250903T202251/Courier0031.lzma
2025-09-03T21:19:07.006Z,1756934347.006 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Courier0031.lzma.bak
2025-09-03T21:19:07.006Z,1756934347.006 [DataOverHttps](INFO): SBD MOMSN=25924695
2025-09-03T21:19:23.065Z,1756934363.065 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20250903T202251/Express0032.lzma
2025-09-03T21:19:24.066Z,1756934364.066 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Express0032.lzma.bak
2025-09-03T21:19:24.066Z,1756934364.066 [DataOverHttps](INFO): SBD MOMSN=25924701
2025-09-03T21:19:25.596Z,1756934365.596 [Default:CheckIn:Read_Iridium] Stopped
2025-09-03T21:19:25.596Z,1756934365.596 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-03T21:19:25.596Z,1756934365.596 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-03T21:19:43.362Z,1756934383.362 [NAL9602](INFO): SBD MO Status=0, MOMSN=22863, MT Status=0, MTMSN=0
2025-09-03T21:19:43.362Z,1756934383.362 [NAL9602](INFO): No messages in MT queue
2025-09-03T21:20:14.063Z,1756934414.063 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-03T21:23:07.815Z,1756934587.815 [CBIT](INFO): Clearing failed state for component DropWeight
2025-09-03T21:23:07.815Z,1756934587.815 [DropWeight] No Fault, FailCount= 1
2025-09-03T21:24:26.184Z,1756934666.184 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-03T21:24:26.184Z,1756934666.184 [Default:CheckIn:C.Wait] Stopped
2025-09-03T21:24:26.185Z,1756934666.185 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-03T21:24:26.185Z,1756934666.185 [Default:CheckIn:D] Running Loop=1
2025-09-03T21:24:26.602Z,1756934666.602 [Default:CheckIn:D] Stopped
2025-09-03T21:24:26.602Z,1756934666.602 [Default:CheckIn:E] Running Loop=1
2025-09-03T21:24:26.990Z,1756934666.990 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.068127 min
2025-09-03T21:24:26.990Z,1756934666.990 [Default:CheckIn:E] Stopped
2025-09-03T21:24:26.990Z,1756934666.990 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-03T21:24:26.990Z,1756934666.990 [Default:CheckIn] Stopped
2025-09-03T21:24:26.990Z,1756934666.990 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-03T21:24:26.990Z,1756934666.990 [Default:CheckIn](INFO): Running loop #11
2025-09-03T21:24:26.991Z,1756934666.991 [Default:CheckIn] Running Loop=11
2025-09-03T21:24:26.991Z,1756934666.991 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-03T21:24:26.991Z,1756934666.991 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-03T21:24:28.999Z,1756934668.999 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212420.00,A,3648.16232,N,12147.28521,W,0.078,0.00,030925,,,A*79
2025-09-03T21:24:29.001Z,1756934669.001 [NAL9602](INFO): GPS fix at 20250903T212420: (36.802705, -121.788087)
2025-09-03T21:24:29.011Z,1756934669.011 [Default:CheckIn:Read_GPS] Stopped
2025-09-03T21:24:29.011Z,1756934669.011 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-03T21:24:36.423Z,1756934676.423 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250903T202251/Courier0034.lzma
2025-09-03T21:24:37.426Z,1756934677.426 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Courier0034.lzma.bak
2025-09-03T21:24:37.426Z,1756934677.426 [DataOverHttps](INFO): SBD MOMSN=25924712
2025-09-03T21:24:53.296Z,1756934693.296 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20250903T202251/Express0035.lzma
2025-09-03T21:24:54.298Z,1756934694.298 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Express0035.lzma.bak
2025-09-03T21:24:54.298Z,1756934694.298 [DataOverHttps](INFO): SBD MOMSN=25924715
2025-09-03T21:24:55.677Z,1756934695.677 [Default:CheckIn:Read_Iridium] Stopped
2025-09-03T21:24:55.677Z,1756934695.677 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-03T21:24:55.677Z,1756934695.677 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-03T21:27:44.542Z,1756934864.542 [NAL9602](INFO): SBD MO Status=0, MOMSN=22864, MT Status=0, MTMSN=0
2025-09-03T21:27:44.542Z,1756934864.542 [NAL9602](INFO): No messages in MT queue
2025-09-03T21:28:15.237Z,1756934895.237 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-03T21:29:56.250Z,1756934996.250 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-03T21:29:56.251Z,1756934996.251 [Default:CheckIn:C.Wait] Stopped
2025-09-03T21:29:56.251Z,1756934996.251 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-03T21:29:56.251Z,1756934996.251 [Default:CheckIn:D] Running Loop=1
2025-09-03T21:29:56.652Z,1756934996.652 [Default:CheckIn:D] Stopped
2025-09-03T21:29:56.652Z,1756934996.652 [Default:CheckIn:E] Running Loop=1
2025-09-03T21:29:57.060Z,1756934997.060 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 61.568970 min
2025-09-03T21:29:57.060Z,1756934997.060 [Default:CheckIn:E] Stopped
2025-09-03T21:29:57.060Z,1756934997.060 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-03T21:29:57.060Z,1756934997.060 [Default:CheckIn] Stopped
2025-09-03T21:29:57.060Z,1756934997.060 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-03T21:29:57.061Z,1756934997.061 [Default:CheckIn](INFO): Running loop #12
2025-09-03T21:29:57.061Z,1756934997.061 [Default:CheckIn] Running Loop=12
2025-09-03T21:29:57.061Z,1756934997.061 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-03T21:29:57.061Z,1756934997.061 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-03T21:29:59.066Z,1756934999.066 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212950.00,A,3648.15676,N,12147.27076,W,0.719,0.00,030925,,,A*7C
2025-09-03T21:29:59.068Z,1756934999.068 [NAL9602](INFO): GPS fix at 20250903T212950: (36.802613, -121.787846)
2025-09-03T21:29:59.099Z,1756934999.099 [Default:CheckIn:Read_GPS] Stopped
2025-09-03T21:29:59.099Z,1756934999.099 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-03T21:30:06.343Z,1756935006.343 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250903T202251/Courier0037.lzma
2025-09-03T21:30:07.346Z,1756935007.346 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Courier0037.lzma.bak
2025-09-03T21:30:07.346Z,1756935007.346 [DataOverHttps](INFO): SBD MOMSN=25924726
2025-09-03T21:30:20.121Z,1756935020.121 [NAL9602](INFO): SBD MO Status=2, MOMSN=22865, MT Status=2, MTMSN=0
2025-09-03T21:30:20.122Z,1756935020.122 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-03T21:30:25.824Z,1756935025.824 [DataOverHttps](INFO): Sending 194 bytes from file Logs/20250903T202251/Express0038.lzma
2025-09-03T21:30:26.827Z,1756935026.827 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Express0038.lzma.bak
2025-09-03T21:30:26.827Z,1756935026.827 [DataOverHttps](INFO): SBD MOMSN=25924749
2025-09-03T21:30:28.643Z,1756935028.643 [Default:CheckIn:Read_Iridium] Stopped
2025-09-03T21:30:28.643Z,1756935028.643 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-03T21:30:28.643Z,1756935028.643 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-03T21:30:37.090Z,1756935037.090 [NAL9602](INFO): SBD MO Status=2, MOMSN=22865, MT Status=2, MTMSN=0
2025-09-03T21:30:37.091Z,1756935037.091 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-03T21:31:27.998Z,1756935087.998 [NAL9602](INFO): SBD MO Status=2, MOMSN=22865, MT Status=2, MTMSN=0
2025-09-03T21:31:27.999Z,1756935087.999 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-03T21:32:20.109Z,1756935140.109 [NAL9602](INFO): SBD MO Status=2, MOMSN=22865, MT Status=2, MTMSN=0
2025-09-03T21:32:20.109Z,1756935140.109 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-03T21:32:53.642Z,1756935173.642 [NAL9602](INFO): SBD MO Status=2, MOMSN=22865, MT Status=2, MTMSN=0
2025-09-03T21:32:53.642Z,1756935173.642 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-03T21:33:11.019Z,1756935191.019 [NAL9602](INFO): SBD MO Status=2, MOMSN=22865, MT Status=2, MTMSN=0
2025-09-03T21:33:11.019Z,1756935191.019 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-03T21:35:01.304Z,1756935301.304 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-09-03T21:35:29.191Z,1756935329.191 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-03T21:35:29.191Z,1756935329.191 [Default:CheckIn:C.Wait] Stopped
2025-09-03T21:35:29.191Z,1756935329.191 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-03T21:35:29.191Z,1756935329.191 [Default:CheckIn:D] Running Loop=1
2025-09-03T21:35:29.600Z,1756935329.600 [Default:CheckIn:D] Stopped
2025-09-03T21:35:29.600Z,1756935329.600 [Default:CheckIn:E] Running Loop=1
2025-09-03T21:35:30.015Z,1756935330.015 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 67.118103 min
2025-09-03T21:35:30.015Z,1756935330.015 [Default:CheckIn:E] Stopped
2025-09-03T21:35:30.015Z,1756935330.015 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-03T21:35:30.015Z,1756935330.015 [Default:CheckIn] Stopped
2025-09-03T21:35:30.015Z,1756935330.015 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-03T21:35:30.015Z,1756935330.015 [Default:CheckIn](INFO): Running loop #13
2025-09-03T21:35:30.015Z,1756935330.015 [Default:CheckIn] Running Loop=13
2025-09-03T21:35:30.015Z,1756935330.015 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-03T21:35:30.015Z,1756935330.015 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-03T21:35:32.015Z,1756935332.015 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213523.00,A,3648.16776,N,12147.28703,W,0.389,170.16,030925,,,A*71
2025-09-03T21:35:32.018Z,1756935332.018 [NAL9602](INFO): GPS fix at 20250903T213523: (36.802796, -121.788117)
2025-09-03T21:35:32.029Z,1756935332.029 [Default:CheckIn:Read_GPS] Stopped
2025-09-03T21:35:32.029Z,1756935332.029 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-03T21:35:39.316Z,1756935339.316 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250903T202251/Courier0040.lzma
2025-09-03T21:35:40.318Z,1756935340.318 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Courier0040.lzma.bak
2025-09-03T21:35:40.318Z,1756935340.318 [DataOverHttps](INFO): SBD MOMSN=25924755
2025-09-03T21:35:56.268Z,1756935356.268 [DataOverHttps](INFO): Sending 307 bytes from file Logs/20250903T202251/Express0041.lzma
2025-09-03T21:35:57.270Z,1756935357.270 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Express0041.lzma.bak
2025-09-03T21:35:57.270Z,1756935357.270 [DataOverHttps](INFO): SBD MOMSN=25924761
2025-09-03T21:35:58.689Z,1756935358.689 [Default:CheckIn:Read_Iridium] Stopped
2025-09-03T21:35:58.689Z,1756935358.689 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-03T21:35:58.689Z,1756935358.689 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-03T21:36:04.351Z,1756935364.351 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-03T21:40:59.265Z,1756935659.265 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-03T21:40:59.265Z,1756935659.265 [Default:CheckIn:C.Wait] Stopped
2025-09-03T21:40:59.266Z,1756935659.266 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-03T21:40:59.266Z,1756935659.266 [Default:CheckIn:D] Running Loop=1
2025-09-03T21:40:59.670Z,1756935659.670 [Default:CheckIn:D] Stopped
2025-09-03T21:40:59.671Z,1756935659.671 [Default:CheckIn:E] Running Loop=1
2025-09-03T21:41:00.080Z,1756935660.080 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 72.619279 min
2025-09-03T21:41:00.080Z,1756935660.080 [Default:CheckIn:E] Stopped
2025-09-03T21:41:00.081Z,1756935660.081 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-03T21:41:00.081Z,1756935660.081 [Default:CheckIn] Stopped
2025-09-03T21:41:00.081Z,1756935660.081 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-03T21:41:00.081Z,1756935660.081 [Default:CheckIn](INFO): Running loop #14
2025-09-03T21:41:00.081Z,1756935660.081 [Default:CheckIn] Running Loop=14
2025-09-03T21:41:00.081Z,1756935660.081 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-03T21:41:00.081Z,1756935660.081 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-03T21:41:02.087Z,1756935662.087 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214053.00,A,3648.16763,N,12147.28365,W,0.583,170.16,030925,,,A*78
2025-09-03T21:41:02.089Z,1756935662.089 [NAL9602](INFO): GPS fix at 20250903T214053: (36.802794, -121.788061)
2025-09-03T21:41:02.120Z,1756935662.120 [Default:CheckIn:Read_GPS] Stopped
2025-09-03T21:41:02.120Z,1756935662.120 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-03T21:41:10.243Z,1756935670.243 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250903T202251/Courier0043.lzma
2025-09-03T21:41:11.246Z,1756935671.246 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Courier0043.lzma.bak
2025-09-03T21:41:11.246Z,1756935671.246 [DataOverHttps](INFO): SBD MOMSN=25924779
2025-09-03T21:41:27.145Z,1756935687.145 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20250903T202251/Express0044.lzma
2025-09-03T21:41:28.146Z,1756935688.146 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Express0044.lzma.bak
2025-09-03T21:41:28.146Z,1756935688.146 [DataOverHttps](INFO): SBD MOMSN=25924782
2025-09-03T21:41:29.576Z,1756935689.576 [Default:CheckIn:Read_Iridium] Stopped
2025-09-03T21:41:29.576Z,1756935689.576 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-03T21:41:29.576Z,1756935689.576 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-03T21:45:59.057Z,1756935959.057 [NAL9602](INFO): SBD MO Status=2, MOMSN=22865, MT Status=2, MTMSN=0
2025-09-03T21:45:59.057Z,1756935959.057 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-03T21:46:04.309Z,1756935964.309 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-09-03T21:46:30.180Z,1756935990.180 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-03T21:46:30.180Z,1756935990.180 [Default:CheckIn:C.Wait] Stopped
2025-09-03T21:46:30.180Z,1756935990.180 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-03T21:46:30.180Z,1756935990.180 [Default:CheckIn:D] Running Loop=1
2025-09-03T21:46:30.593Z,1756935990.593 [Default:CheckIn:D] Stopped
2025-09-03T21:46:30.594Z,1756935990.594 [Default:CheckIn:E] Running Loop=1
2025-09-03T21:46:30.983Z,1756935990.983 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 78.134660 min
2025-09-03T21:46:30.983Z,1756935990.983 [Default:CheckIn:E] Stopped
2025-09-03T21:46:30.983Z,1756935990.983 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-03T21:46:30.983Z,1756935990.983 [Default:CheckIn] Stopped
2025-09-03T21:46:30.984Z,1756935990.984 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-03T21:46:30.984Z,1756935990.984 [Default:CheckIn](INFO): Running loop #15
2025-09-03T21:46:30.984Z,1756935990.984 [Default:CheckIn] Running Loop=15
2025-09-03T21:46:30.984Z,1756935990.984 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-03T21:46:30.984Z,1756935990.984 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-03T21:46:32.996Z,1756935992.996 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214624.00,A,3648.16871,N,12147.28954,W,0.622,55.51,030925,,,A*47
2025-09-03T21:46:32.998Z,1756935992.998 [NAL9602](INFO): GPS fix at 20250903T214624: (36.802812, -121.788159)
2025-09-03T21:46:33.009Z,1756935993.009 [Default:CheckIn:Read_GPS] Stopped
2025-09-03T21:46:33.009Z,1756935993.009 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-03T21:46:41.427Z,1756936001.427 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250903T202251/Courier0046.lzma
2025-09-03T21:46:42.435Z,1756936002.435 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Courier0046.lzma.bak
2025-09-03T21:46:42.435Z,1756936002.435 [DataOverHttps](INFO): SBD MOMSN=25924786
2025-09-03T21:46:58.440Z,1756936018.440 [DataOverHttps](INFO): Sending 281 bytes from file Logs/20250903T202251/Express0047.lzma
2025-09-03T21:46:59.442Z,1756936019.442 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Express0047.lzma.bak
2025-09-03T21:46:59.442Z,1756936019.442 [DataOverHttps](INFO): SBD MOMSN=25924789
2025-09-03T21:47:00.915Z,1756936020.915 [Default:CheckIn:Read_Iridium] Stopped
2025-09-03T21:47:00.916Z,1756936020.916 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-03T21:47:00.916Z,1756936020.916 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-03T21:47:05.337Z,1756936025.337 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-03T21:52:01.474Z,1756936321.474 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-03T21:52:01.474Z,1756936321.474 [Default:CheckIn:C.Wait] Stopped
2025-09-03T21:52:01.474Z,1756936321.474 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-03T21:52:01.474Z,1756936321.474 [Default:CheckIn:D] Running Loop=1
2025-09-03T21:52:01.880Z,1756936321.880 [Default:CheckIn:D] Stopped
2025-09-03T21:52:01.880Z,1756936321.880 [Default:CheckIn:E] Running Loop=1
2025-09-03T21:52:02.283Z,1756936322.283 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.656104 min
2025-09-03T21:52:02.283Z,1756936322.283 [Default:CheckIn:E] Stopped
2025-09-03T21:52:02.283Z,1756936322.283 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-03T21:52:02.283Z,1756936322.283 [Default:CheckIn] Stopped
2025-09-03T21:52:02.283Z,1756936322.283 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-03T21:52:02.284Z,1756936322.284 [Default:CheckIn](INFO): Running loop #16
2025-09-03T21:52:02.284Z,1756936322.284 [Default:CheckIn] Running Loop=16
2025-09-03T21:52:02.284Z,1756936322.284 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-03T21:52:02.284Z,1756936322.284 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-03T21:52:04.293Z,1756936324.293 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215155.00,A,3648.16346,N,12147.28598,W,0.700,55.51,030925,,,A*45
2025-09-03T21:52:04.295Z,1756936324.295 [NAL9602](INFO): GPS fix at 20250903T215155: (36.802724, -121.788100)
2025-09-03T21:52:04.317Z,1756936324.317 [Default:CheckIn:Read_GPS] Stopped
2025-09-03T21:52:04.317Z,1756936324.317 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-03T21:52:11.515Z,1756936331.515 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250903T202251/Courier0049.lzma
2025-09-03T21:52:12.518Z,1756936332.518 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Courier0049.lzma.bak
2025-09-03T21:52:12.518Z,1756936332.518 [DataOverHttps](INFO): SBD MOMSN=25924803
2025-09-03T21:52:28.532Z,1756936348.532 [DataOverHttps](INFO): Sending 188 bytes from file Logs/20250903T202251/Express0050.lzma
2025-09-03T21:52:29.534Z,1756936349.534 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Express0050.lzma.bak
2025-09-03T21:52:29.534Z,1756936349.534 [DataOverHttps](INFO): SBD MOMSN=25924806
2025-09-03T21:52:30.972Z,1756936350.972 [Default:CheckIn:Read_Iridium] Stopped
2025-09-03T21:52:30.972Z,1756936350.972 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-03T21:52:30.972Z,1756936350.972 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-03T21:52:34.997Z,1756936354.997 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-09-03T21:52:35.080Z,1756936355.080 [NAL9602](ERROR): received:
+CSQ:0
OK865, 2, 0, 0, 0
OK
2025-09-03T21:53:09.337Z,1756936389.337 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2025-09-03T21:53:09.337Z,1756936389.337 [DropWeight] Hardware Fault, FailCount= 1
2025-09-03T21:53:09.337Z,1756936389.337 [DropWeight](ERROR): Hardware Fault
2025-09-03T21:53:09.375Z,1756936389.375 [CBIT](INFO): Critical error at 20250903T215309
2025-09-03T21:53:09.398Z,1756936389.398 [CBIT](ERROR): Hardware Fault in component: DropWeight
2025-09-03T21:53:09.399Z,1756936389.399 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2025-09-03T21:53:09.783Z,1756936389.783 [CBIT](INFO): Critical error at 20250903T215309
2025-09-03T21:55:13.386Z,1756936513.386 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T21:55:24.750Z,1756936524.750 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T21:55:36.057Z,1756936536.057 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T21:55:46.972Z,1756936546.972 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T21:55:55.392Z,1756936555.392 [NAL9602](INFO): SBD MO Status=2, MOMSN=22865, MT Status=2, MTMSN=0
2025-09-03T21:55:55.392Z,1756936555.392 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-03T21:55:58.273Z,1756936558.273 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T21:56:09.654Z,1756936569.654 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T21:56:12.762Z,1756936572.762 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T21:56:20.038Z,1756936580.038 [NAL9602](INFO): SBD MO Status=2, MOMSN=22865, MT Status=2, MTMSN=0
2025-09-03T21:56:20.038Z,1756936580.038 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-03T21:56:23.729Z,1756936583.729 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T21:56:34.633Z,1756936594.633 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T21:56:36.999Z,1756936596.999 [NAL9602](INFO): SBD MO Status=2, MOMSN=22865, MT Status=2, MTMSN=0
2025-09-03T21:56:36.000Z,1756936597.000 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-03T21:56:45.961Z,1756936605.961 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T21:56:55.986Z,1756936615.986 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-09-03T21:56:55.986Z,1756936615.986 [BPC1](ERROR): BPC1A failed to parse battery stick message.
2025-09-03T21:57:06.086Z,1756936626.086 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-09-03T21:57:31.563Z,1756936651.563 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-03T21:57:31.563Z,1756936651.563 [Default:CheckIn:C.Wait] Stopped
2025-09-03T21:57:31.563Z,1756936651.563 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-03T21:57:31.563Z,1756936651.563 [Default:CheckIn:D] Running Loop=1
2025-09-03T21:57:31.954Z,1756936651.954 [Default:CheckIn:D] Stopped
2025-09-03T21:57:31.954Z,1756936651.954 [Default:CheckIn:E] Running Loop=1
2025-09-03T21:57:32.356Z,1756936652.356 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 89.157340 min
2025-09-03T21:57:32.356Z,1756936652.356 [Default:CheckIn:E] Stopped
2025-09-03T21:57:32.357Z,1756936652.357 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-03T21:57:32.357Z,1756936652.357 [Default:CheckIn] Stopped
2025-09-03T21:57:32.357Z,1756936652.357 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-03T21:57:32.357Z,1756936652.357 [Default:CheckIn](INFO): Running loop #17
2025-09-03T21:57:32.357Z,1756936652.357 [Default:CheckIn] Running Loop=17
2025-09-03T21:57:32.357Z,1756936652.357 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-03T21:57:32.357Z,1756936652.357 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-03T21:57:34.373Z,1756936654.373 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215725.00,A,3648.16379,N,12147.28543,W,0.622,7.96,030925,,,A*73
2025-09-03T21:57:34.375Z,1756936654.375 [NAL9602](INFO): GPS fix at 20250903T215725: (36.802730, -121.788090)
2025-09-03T21:57:34.386Z,1756936654.386 [Default:CheckIn:Read_GPS] Stopped
2025-09-03T21:57:34.386Z,1756936654.386 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-03T21:57:42.312Z,1756936662.312 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250903T202251/Courier0052.lzma
2025-09-03T21:57:43.314Z,1756936663.314 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Courier0052.lzma.bak
2025-09-03T21:57:43.314Z,1756936663.314 [DataOverHttps](INFO): SBD MOMSN=25924809
2025-09-03T21:57:59.388Z,1756936679.388 [DataOverHttps](INFO): Sending 259 bytes from file Logs/20250903T202251/Express0053.lzma
2025-09-03T21:58:00.390Z,1756936680.390 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Express0053.lzma.bak
2025-09-03T21:58:00.390Z,1756936680.390 [DataOverHttps](INFO): SBD MOMSN=25924814
2025-09-03T21:58:01.850Z,1756936681.850 [Default:CheckIn:Read_Iridium] Stopped
2025-09-03T21:58:01.850Z,1756936681.850 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-03T21:58:01.850Z,1756936681.850 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-03T21:58:07.106Z,1756936687.106 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-03T21:58:12.405Z,1756936692.405 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T21:58:18.042Z,1756936698.042 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T21:58:58.395Z,1756936738.395 [BPC1](ERROR): BPC1A: No match for serial number 0000 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-09-03T21:59:22.254Z,1756936762.254 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T21:59:51.380Z,1756936791.380 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T22:00:20.908Z,1756936820.908 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T22:00:56.018Z,1756936856.018 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T22:01:49.749Z,1756936909.749 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T22:03:02.440Z,1756936982.440 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-03T22:03:02.440Z,1756936982.440 [Default:CheckIn:C.Wait] Stopped
2025-09-03T22:03:02.440Z,1756936982.440 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-03T22:03:02.441Z,1756936982.441 [Default:CheckIn:D] Running Loop=1
2025-09-03T22:03:02.826Z,1756936982.826 [Default:CheckIn:D] Stopped
2025-09-03T22:03:02.826Z,1756936982.826 [Default:CheckIn:E] Running Loop=1
2025-09-03T22:03:03.231Z,1756936983.231 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 94.671875 min
2025-09-03T22:03:03.231Z,1756936983.231 [Default:CheckIn:E] Stopped
2025-09-03T22:03:03.232Z,1756936983.232 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-03T22:03:03.232Z,1756936983.232 [Default:CheckIn] Stopped
2025-09-03T22:03:03.232Z,1756936983.232 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-03T22:03:03.232Z,1756936983.232 [Default:CheckIn](INFO): Running loop #18
2025-09-03T22:03:03.232Z,1756936983.232 [Default:CheckIn] Running Loop=18
2025-09-03T22:03:03.232Z,1756936983.232 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-03T22:03:03.233Z,1756936983.233 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-03T22:03:05.309Z,1756936985.309 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220256.00,A,3648.16022,N,12147.27860,W,0.292,19.85,030925,,,A*48
2025-09-03T22:03:05.311Z,1756936985.311 [NAL9602](INFO): GPS fix at 20250903T220256: (36.802670, -121.787977)
2025-09-03T22:03:05.321Z,1756936985.321 [Default:CheckIn:Read_GPS] Stopped
2025-09-03T22:03:05.321Z,1756936985.321 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-03T22:03:12.951Z,1756936992.951 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250903T202251/Courier0055.lzma
2025-09-03T22:03:13.954Z,1756936993.954 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Courier0055.lzma.bak
2025-09-03T22:03:13.954Z,1756936993.954 [DataOverHttps](INFO): SBD MOMSN=25924864
2025-09-03T22:03:30.180Z,1756937010.180 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20250903T202251/Express0056.lzma
2025-09-03T22:03:31.182Z,1756937011.182 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Express0056.lzma.bak
2025-09-03T22:03:31.182Z,1756937011.182 [DataOverHttps](INFO): SBD MOMSN=25924867
2025-09-03T22:03:32.733Z,1756937012.733 [Default:CheckIn:Read_Iridium] Stopped
2025-09-03T22:03:32.733Z,1756937012.733 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-03T22:03:32.733Z,1756937012.733 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-03T22:03:36.081Z,1756937016.081 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-09-03T22:03:36.160Z,1756937016.160 [NAL9602](ERROR): received:
+CSQ:1
OK865, 2, 0, 0, 0
OK
2025-09-03T22:04:16.389Z,1756937056.389 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T22:04:22.823Z,1756937062.823 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T22:04:33.786Z,1756937073.786 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T22:04:44.691Z,1756937084.691 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T22:04:55.597Z,1756937095.597 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T22:05:06.961Z,1756937106.961 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T22:05:18.222Z,1756937118.222 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T22:05:18.622Z,1756937118.622 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T22:05:29.540Z,1756937129.540 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T22:05:40.520Z,1756937140.520 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T22:05:50.070Z,1756937150.070 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-09-03T22:05:50.070Z,1756937150.070 [BPC1](ERROR): BPC1A failed to parse battery stick message.
2025-09-03T22:05:53.306Z,1756937153.306 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T22:06:04.281Z,1756937164.281 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T22:06:15.208Z,1756937175.208 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T22:06:26.504Z,1756937186.504 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T22:06:37.822Z,1756937197.822 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T22:07:08.450Z,1756937228.450 [NAL9602](INFO): SBD MO Status=0, MOMSN=22865, MT Status=0, MTMSN=0
2025-09-03T22:07:08.450Z,1756937228.450 [NAL9602](INFO): No messages in MT queue
2025-09-03T22:07:39.159Z,1756937259.159 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-03T22:08:04.262Z,1756937284.262 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T22:08:09.476Z,1756937289.476 [CBIT](INFO): Clearing failed state for component DropWeight
2025-09-03T22:08:09.476Z,1756937289.476 [DropWeight] No Fault, FailCount= 1
2025-09-03T22:08:33.316Z,1756937313.316 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-03T22:08:33.316Z,1756937313.316 [Default:CheckIn:C.Wait] Stopped
2025-09-03T22:08:33.316Z,1756937313.316 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-03T22:08:33.316Z,1756937313.316 [Default:CheckIn:D] Running Loop=1
2025-09-03T22:08:33.718Z,1756937313.718 [Default:CheckIn:D] Stopped
2025-09-03T22:08:33.718Z,1756937313.718 [Default:CheckIn:E] Running Loop=1
2025-09-03T22:08:34.183Z,1756937314.183 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 100.186727 min
2025-09-03T22:08:34.183Z,1756937314.183 [Default:CheckIn:E] Stopped
2025-09-03T22:08:34.183Z,1756937314.183 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-03T22:08:34.183Z,1756937314.183 [Default:CheckIn] Stopped
2025-09-03T22:08:34.183Z,1756937314.183 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-03T22:08:34.183Z,1756937314.183 [Default:CheckIn](INFO): Running loop #19
2025-09-03T22:08:34.183Z,1756937314.183 [Default:CheckIn] Running Loop=19
2025-09-03T22:08:34.183Z,1756937314.183 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-03T22:08:34.183Z,1756937314.183 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-03T22:08:36.132Z,1756937316.132 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220827.00,A,3648.16405,N,12147.27992,W,0.447,352.15,030925,,,A*72
2025-09-03T22:08:36.135Z,1756937316.135 [NAL9602](INFO): GPS fix at 20250903T220827: (36.802734, -121.787999)
2025-09-03T22:08:36.145Z,1756937316.145 [Default:CheckIn:Read_GPS] Stopped
2025-09-03T22:08:36.145Z,1756937316.145 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-03T22:08:41.043Z,1756937321.043 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T22:08:44.047Z,1756937324.047 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250903T202251/Courier0058.lzma
2025-09-03T22:08:45.050Z,1756937325.050 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Courier0058.lzma.bak
2025-09-03T22:08:45.050Z,1756937325.050 [DataOverHttps](INFO): SBD MOMSN=25924872
2025-09-03T22:09:01.176Z,1756937341.176 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20250903T202251/Express0059.lzma
2025-09-03T22:09:02.183Z,1756937342.183 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Express0059.lzma.bak
2025-09-03T22:09:02.183Z,1756937342.183 [DataOverHttps](INFO): SBD MOMSN=25924875
2025-09-03T22:09:03.625Z,1756937343.625 [Default:CheckIn:Read_Iridium] Stopped
2025-09-03T22:09:03.625Z,1756937343.625 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-03T22:09:03.625Z,1756937343.625 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-03T22:12:18.781Z,1756937538.781 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T22:12:45.839Z,1756937565.839 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T22:13:29.885Z,1756937609.885 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T22:13:37.910Z,1756937617.910 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-09-03T22:13:58.614Z,1756937638.614 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T22:13:58.933Z,1756937638.933 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-09-03T22:13:58.933Z,1756937638.933 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-09-03T22:13:58.934Z,1756937638.934 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-09-03T22:13:58.935Z,1756937638.935 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 5.
2025-09-03T22:13:58.938Z,1756937638.938 [BPC1](INFO): Received data from all battery sticks.
2025-09-03T22:14:04.179Z,1756937644.179 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-03T22:14:04.179Z,1756937644.179 [Default:CheckIn:C.Wait] Stopped
2025-09-03T22:14:04.179Z,1756937644.179 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-03T22:14:04.180Z,1756937644.180 [Default:CheckIn:D] Running Loop=1
2025-09-03T22:14:04.596Z,1756937644.596 [Default:CheckIn:D] Stopped
2025-09-03T22:14:04.596Z,1756937644.596 [Default:CheckIn:E] Running Loop=1
2025-09-03T22:14:04.982Z,1756937644.982 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 105.701359 min
2025-09-03T22:14:04.982Z,1756937644.982 [Default:CheckIn:E] Stopped
2025-09-03T22:14:04.983Z,1756937644.983 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-03T22:14:04.983Z,1756937644.983 [Default:CheckIn] Stopped
2025-09-03T22:14:04.983Z,1756937644.983 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-03T22:14:04.983Z,1756937644.983 [Default:CheckIn](INFO): Running loop #20
2025-09-03T22:14:04.983Z,1756937644.983 [Default:CheckIn] Running Loop=20
2025-09-03T22:14:04.983Z,1756937644.983 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-03T22:14:04.983Z,1756937644.983 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-03T22:14:06.995Z,1756937646.995 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221358.00,A,3648.16090,N,12147.28875,W,0.739,352.15,030925,,,A*75
2025-09-03T22:14:06.997Z,1756937646.997 [NAL9602](INFO): GPS fix at 20250903T221358: (36.802682, -121.788146)
2025-09-03T22:14:07.008Z,1756937647.008 [Default:CheckIn:Read_GPS] Stopped
2025-09-03T22:14:07.008Z,1756937647.008 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-03T22:14:15.015Z,1756937655.015 [DataOverHttps](INFO): Sending 89 bytes from file Logs/20250903T202251/Courier0061.lzma
2025-09-03T22:14:16.018Z,1756937656.018 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Courier0061.lzma.bak
2025-09-03T22:14:16.018Z,1756937656.018 [DataOverHttps](INFO): SBD MOMSN=25924884
2025-09-03T22:14:32.876Z,1756937672.876 [DataOverHttps](INFO): Sending 253 bytes from file Logs/20250903T202251/Express0062.lzma
2025-09-03T22:14:33.878Z,1756937673.878 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Express0062.lzma.bak
2025-09-03T22:14:33.878Z,1756937673.878 [DataOverHttps](INFO): SBD MOMSN=25924887
2025-09-03T22:14:35.303Z,1756937675.303 [Default:CheckIn:Read_Iridium] Stopped
2025-09-03T22:14:35.303Z,1756937675.303 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-03T22:14:35.303Z,1756937675.303 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-03T22:14:39.321Z,1756937679.321 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-03T22:19:35.881Z,1756937975.881 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-03T22:19:35.881Z,1756937975.881 [Default:CheckIn:C.Wait] Stopped
2025-09-03T22:19:35.881Z,1756937975.881 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-03T22:19:35.882Z,1756937975.882 [Default:CheckIn:D] Running Loop=1
2025-09-03T22:19:36.286Z,1756937976.286 [Default:CheckIn:D] Stopped
2025-09-03T22:19:36.286Z,1756937976.286 [Default:CheckIn:E] Running Loop=1
2025-09-03T22:19:36.696Z,1756937976.696 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 111.229525 min
2025-09-03T22:19:36.696Z,1756937976.696 [Default:CheckIn:E] Stopped
2025-09-03T22:19:36.696Z,1756937976.696 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-03T22:19:36.696Z,1756937976.696 [Default:CheckIn] Stopped
2025-09-03T22:19:36.696Z,1756937976.696 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-03T22:19:36.697Z,1756937976.697 [Default:CheckIn](INFO): Running loop #21
2025-09-03T22:19:36.697Z,1756937976.697 [Default:CheckIn] Running Loop=21
2025-09-03T22:19:36.697Z,1756937976.697 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-03T22:19:36.697Z,1756937976.697 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-03T22:19:38.707Z,1756937978.707 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221930.00,A,3648.16477,N,12147.28307,W,0.156,357.39,030925,,,A*76
2025-09-03T22:19:38.710Z,1756937978.710 [NAL9602](INFO): GPS fix at 20250903T221930: (36.802746, -121.788051)
2025-09-03T22:19:38.720Z,1756937978.720 [Default:CheckIn:Read_GPS] Stopped
2025-09-03T22:19:38.720Z,1756937978.720 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-03T22:19:46.867Z,1756937986.867 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250903T202251/Courier0064.lzma
2025-09-03T22:19:47.870Z,1756937987.870 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Courier0064.lzma.bak
2025-09-03T22:19:47.870Z,1756937987.870 [DataOverHttps](INFO): SBD MOMSN=25924918
2025-09-03T22:20:04.676Z,1756938004.676 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20250903T202251/Express0065.lzma
2025-09-03T22:20:05.679Z,1756938005.679 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Express0065.lzma.bak
2025-09-03T22:20:05.679Z,1756938005.679 [DataOverHttps](INFO): SBD MOMSN=25924921
2025-09-03T22:20:06.999Z,1756938006.999 [Default:CheckIn:Read_Iridium] Stopped
2025-09-03T22:20:06.999Z,1756938006.999 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-03T22:20:06.999Z,1756938006.999 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-03T22:20:09.405Z,1756938009.405 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-09-03T22:20:09.484Z,1756938009.484 [NAL9602](ERROR): received:
+CSQ:0
OK865, 0, 0, 0, 0
OK
2025-09-03T22:24:40.900Z,1756938280.900 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-09-03T22:25:07.583Z,1756938307.583 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-03T22:25:07.583Z,1756938307.583 [Default:CheckIn:C.Wait] Stopped
2025-09-03T22:25:07.583Z,1756938307.583 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-03T22:25:07.583Z,1756938307.583 [Default:CheckIn:D] Running Loop=1
2025-09-03T22:25:07.991Z,1756938307.991 [Default:CheckIn:D] Stopped
2025-09-03T22:25:07.991Z,1756938307.991 [Default:CheckIn:E] Running Loop=1
2025-09-03T22:25:08.385Z,1756938308.385 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 116.757951 min
2025-09-03T22:25:08.385Z,1756938308.385 [Default:CheckIn:E] Stopped
2025-09-03T22:25:08.386Z,1756938308.386 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-03T22:25:08.386Z,1756938308.386 [Default:CheckIn] Stopped
2025-09-03T22:25:08.386Z,1756938308.386 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-03T22:25:08.386Z,1756938308.386 [Default:CheckIn](INFO): Running loop #22
2025-09-03T22:25:08.386Z,1756938308.386 [Default:CheckIn] Running Loop=22
2025-09-03T22:25:08.386Z,1756938308.386 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-03T22:25:08.386Z,1756938308.386 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-03T22:25:10.400Z,1756938310.400 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222501.00,A,3648.16577,N,12147.28725,W,0.097,19.89,030925,,,A*40
2025-09-03T22:25:10.402Z,1756938310.402 [NAL9602](INFO): GPS fix at 20250903T222501: (36.802763, -121.788121)
2025-09-03T22:25:10.413Z,1756938310.413 [Default:CheckIn:Read_GPS] Stopped
2025-09-03T22:25:10.413Z,1756938310.413 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-03T22:25:17.643Z,1756938317.643 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20250903T202251/Courier0067.lzma
2025-09-03T22:25:18.646Z,1756938318.646 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Courier0067.lzma.bak
2025-09-03T22:25:18.646Z,1756938318.646 [DataOverHttps](INFO): SBD MOMSN=25924943
2025-09-03T22:25:34.704Z,1756938334.704 [DataOverHttps](INFO): Sending 242 bytes from file Logs/20250903T202251/Express0068.lzma
2025-09-03T22:25:35.706Z,1756938335.706 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Express0068.lzma.bak
2025-09-03T22:25:35.706Z,1756938335.706 [DataOverHttps](INFO): SBD MOMSN=25924946
2025-09-03T22:25:37.076Z,1756938337.076 [Default:CheckIn:Read_Iridium] Stopped
2025-09-03T22:25:37.076Z,1756938337.076 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-03T22:25:37.076Z,1756938337.076 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-03T22:25:43.125Z,1756938343.125 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-03T22:30:37.646Z,1756938637.646 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-03T22:30:37.647Z,1756938637.647 [Default:CheckIn:C.Wait] Stopped
2025-09-03T22:30:37.647Z,1756938637.647 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-03T22:30:37.647Z,1756938637.647 [Default:CheckIn:D] Running Loop=1
2025-09-03T22:30:38.056Z,1756938638.056 [Default:CheckIn:D] Stopped
2025-09-03T22:30:38.056Z,1756938638.056 [Default:CheckIn:E] Running Loop=1
2025-09-03T22:30:38.474Z,1756938638.474 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 122.259033 min
2025-09-03T22:30:38.474Z,1756938638.474 [Default:CheckIn:E] Stopped
2025-09-03T22:30:38.474Z,1756938638.474 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-03T22:30:38.474Z,1756938638.474 [Default:CheckIn] Stopped
2025-09-03T22:30:38.474Z,1756938638.474 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-03T22:30:38.474Z,1756938638.474 [Default:CheckIn](INFO): Running loop #23
2025-09-03T22:30:38.474Z,1756938638.474 [Default:CheckIn] Running Loop=23
2025-09-03T22:30:38.475Z,1756938638.475 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-03T22:30:38.475Z,1756938638.475 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-03T22:30:40.466Z,1756938640.466 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223032.00,A,3648.15765,N,12147.28305,W,0.311,14.47,030925,,,A*42
2025-09-03T22:30:40.468Z,1756938640.468 [NAL9602](INFO): GPS fix at 20250903T223032: (36.802627, -121.788051)
2025-09-03T22:30:40.479Z,1756938640.479 [Default:CheckIn:Read_GPS] Stopped
2025-09-03T22:30:40.479Z,1756938640.479 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-03T22:30:49.360Z,1756938649.360 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250903T202251/Courier0070.lzma
2025-09-03T22:30:50.363Z,1756938650.363 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Courier0070.lzma.bak
2025-09-03T22:30:50.364Z,1756938650.364 [DataOverHttps](INFO): SBD MOMSN=25924956
2025-09-03T22:31:06.340Z,1756938666.340 [DataOverHttps](INFO): Sending 166 bytes from file Logs/20250903T202251/Express0071.lzma
2025-09-03T22:31:07.342Z,1756938667.342 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Express0071.lzma.bak
2025-09-03T22:31:07.342Z,1756938667.342 [DataOverHttps](INFO): SBD MOMSN=25924959
2025-09-03T22:31:08.812Z,1756938668.812 [Default:CheckIn:Read_Iridium] Stopped
2025-09-03T22:31:08.812Z,1756938668.812 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-03T22:31:08.813Z,1756938668.813 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-03T22:35:43.125Z,1756938943.125 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-09-03T22:36:09.391Z,1756938969.391 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-03T22:36:09.392Z,1756938969.392 [Default:CheckIn:C.Wait] Stopped
2025-09-03T22:36:09.392Z,1756938969.392 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-03T22:36:09.392Z,1756938969.392 [Default:CheckIn:D] Running Loop=1
2025-09-03T22:36:09.800Z,1756938969.800 [Default:CheckIn:D] Stopped
2025-09-03T22:36:09.800Z,1756938969.800 [Default:CheckIn:E] Running Loop=1
2025-09-03T22:36:10.217Z,1756938970.217 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 127.788094 min
2025-09-03T22:36:10.217Z,1756938970.217 [Default:CheckIn:E] Stopped
2025-09-03T22:36:10.217Z,1756938970.217 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-03T22:36:10.218Z,1756938970.218 [Default:CheckIn] Stopped
2025-09-03T22:36:10.218Z,1756938970.218 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-03T22:36:10.218Z,1756938970.218 [Default:CheckIn](INFO): Running loop #24
2025-09-03T22:36:10.218Z,1756938970.218 [Default:CheckIn] Running Loop=24
2025-09-03T22:36:10.218Z,1756938970.218 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-03T22:36:10.218Z,1756938970.218 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-03T22:36:12.210Z,1756938972.210 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223603.00,A,3648.16259,N,12147.28886,W,0.641,14.47,030925,,,A*4F
2025-09-03T22:36:12.212Z,1756938972.212 [NAL9602](INFO): GPS fix at 20250903T223603: (36.802710, -121.788148)
2025-09-03T22:36:12.223Z,1756938972.223 [Default:CheckIn:Read_GPS] Stopped
2025-09-03T22:36:12.223Z,1756938972.223 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-03T22:36:19.839Z,1756938979.839 [DataOverHttps](INFO): Sending 78 bytes from file Logs/20250903T202251/Courier0073.lzma
2025-09-03T22:36:20.842Z,1756938980.842 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Courier0073.lzma.bak
2025-09-03T22:36:20.842Z,1756938980.842 [DataOverHttps](INFO): SBD MOMSN=25924996
2025-09-03T22:36:37.233Z,1756938997.233 [DataOverHttps](INFO): Sending 247 bytes from file Logs/20250903T202251/Express0074.lzma
2025-09-03T22:36:38.234Z,1756938998.234 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Express0074.lzma.bak
2025-09-03T22:36:38.234Z,1756938998.234 [DataOverHttps](INFO): SBD MOMSN=25924999
2025-09-03T22:36:39.698Z,1756938999.698 [Default:CheckIn:Read_Iridium] Stopped
2025-09-03T22:36:39.698Z,1756938999.698 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-03T22:36:39.698Z,1756938999.698 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-03T22:36:44.126Z,1756939004.126 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-03T22:38:10.987Z,1756939090.987 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2025-09-03T22:38:10.987Z,1756939090.987 [DropWeight] Hardware Fault, FailCount= 1
2025-09-03T22:38:10.987Z,1756939090.987 [DropWeight](ERROR): Hardware Fault
2025-09-03T22:38:11.005Z,1756939091.005 [CBIT](INFO): Critical error at 20250903T223810
2025-09-03T22:38:11.029Z,1756939091.029 [CBIT](ERROR): Hardware Fault in component: DropWeight
2025-09-03T22:38:11.029Z,1756939091.029 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2025-09-03T22:38:11.411Z,1756939091.411 [CBIT](INFO): Critical error at 20250903T223811
2025-09-03T22:41:40.284Z,1756939300.284 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-03T22:41:40.285Z,1756939300.285 [Default:CheckIn:C.Wait] Stopped
2025-09-03T22:41:40.285Z,1756939300.285 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-03T22:41:40.285Z,1756939300.285 [Default:CheckIn:D] Running Loop=1
2025-09-03T22:41:40.675Z,1756939300.675 [Default:CheckIn:D] Stopped
2025-09-03T22:41:40.675Z,1756939300.675 [Default:CheckIn:E] Running Loop=1
2025-09-03T22:41:41.083Z,1756939301.083 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 133.302686 min
2025-09-03T22:41:41.083Z,1756939301.083 [Default:CheckIn:E] Stopped
2025-09-03T22:41:41.083Z,1756939301.083 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-03T22:41:41.083Z,1756939301.083 [Default:CheckIn] Stopped
2025-09-03T22:41:41.084Z,1756939301.084 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-03T22:41:41.084Z,1756939301.084 [Default:CheckIn](INFO): Running loop #25
2025-09-03T22:41:41.084Z,1756939301.084 [Default:CheckIn] Running Loop=25
2025-09-03T22:41:41.084Z,1756939301.084 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-03T22:41:41.084Z,1756939301.084 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-03T22:41:43.090Z,1756939303.090 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224134.00,A,3648.16969,N,12147.28296,W,0.952,26.68,030925,,,A*49
2025-09-03T22:41:43.092Z,1756939303.092 [NAL9602](INFO): GPS fix at 20250903T224134: (36.802828, -121.788049)
2025-09-03T22:41:43.119Z,1756939303.119 [Default:CheckIn:Read_GPS] Stopped
2025-09-03T22:41:43.119Z,1756939303.119 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-03T22:41:50.920Z,1756939310.920 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20250903T202251/Courier0076.lzma
2025-09-03T22:41:51.923Z,1756939311.923 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Courier0076.lzma.bak
2025-09-03T22:41:51.923Z,1756939311.923 [DataOverHttps](INFO): SBD MOMSN=25925028
2025-09-03T22:42:08.131Z,1756939328.131 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20250903T202251/Express0077.lzma
2025-09-03T22:42:08.774Z,1756939328.774 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Express0077.lzma.bak
2025-09-03T22:42:08.774Z,1756939328.774 [DataOverHttps](INFO): SBD MOMSN=25925033
2025-09-03T22:42:10.171Z,1756939330.171 [Default:CheckIn:Read_Iridium] Stopped
2025-09-03T22:42:10.171Z,1756939330.171 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-03T22:42:10.171Z,1756939330.171 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-03T22:42:13.789Z,1756939333.789 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-09-03T22:42:13.872Z,1756939333.872 [NAL9602](ERROR): received:
+CSQ:0
OK865, 0, 0, 0, 0
OK
2025-09-03T22:46:44.885Z,1756939604.885 [Depth_Keller](ERROR): Pressure or depth reading out of range: 947.322021 decibar, 0.000000 m
2025-09-03T22:46:44.886Z,1756939604.886 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-09-03T22:46:46.774Z,1756939606.774 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-09-03T22:46:48.280Z,1756939608.280 [MassServo](FAULT): Mass Shifter uart error: serial timeout
2025-09-03T22:46:48.280Z,1756939608.280 [MassServo] Communications Fault, FailCount= 1
2025-09-03T22:46:48.280Z,1756939608.280 [MassServo](ERROR): Communications Fault
2025-09-03T22:46:48.287Z,1756939608.287 [CBIT](ERROR): Communications Fault in component: MassServo
2025-09-03T22:46:48.881Z,1756939608.881 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-09-03T22:46:48.881Z,1756939608.881 [MassServo](INFO): Powering down
2025-09-03T22:46:49.638Z,1756939609.638 [CBIT](INFO): Clearing failed state for component MassServo
2025-09-03T22:46:49.638Z,1756939609.638 [MassServo] No Fault, FailCount= 1
2025-09-03T22:46:50.041Z,1756939610.041 [MassServo](DEBUG): Initializing EZServoServo.
2025-09-03T22:46:50.042Z,1756939610.042 [MassServo](DEBUG): Initializing MassServo.
2025-09-03T22:46:52.624Z,1756939612.624 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2025-09-03T22:46:52.624Z,1756939612.624 [MassServo] Communications Fault, FailCount= 2
2025-09-03T22:46:52.624Z,1756939612.624 [MassServo](ERROR): Communications Fault
2025-09-03T22:46:52.628Z,1756939612.628 [CBIT](ERROR): Communications Fault in component: MassServo
2025-09-03T22:46:52.867Z,1756939612.867 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-09-03T22:46:52.867Z,1756939612.867 [MassServo](INFO): Powering down
2025-09-03T22:46:53.685Z,1756939613.685 [CBIT](INFO): Clearing failed state for component MassServo
2025-09-03T22:46:53.685Z,1756939613.685 [MassServo] No Fault, FailCount= 2
2025-09-03T22:46:54.043Z,1756939614.043 [MassServo](DEBUG): Initializing EZServoServo.
2025-09-03T22:46:54.043Z,1756939614.043 [MassServo](DEBUG): Initializing MassServo.
2025-09-03T22:46:56.536Z,1756939616.536 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout
2025-09-03T22:46:56.536Z,1756939616.536 [MassServo] Communications Fault, FailCount= 3
2025-09-03T22:46:56.536Z,1756939616.536 [MassServo](ERROR): Communications Fault
2025-09-03T22:46:56.541Z,1756939616.541 [CBIT](ERROR): Communications Fault in component: MassServo
2025-09-03T22:46:56.712Z,1756939616.712 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-09-03T22:46:56.712Z,1756939616.712 [MassServo](INFO): Powering down
2025-09-03T22:46:57.519Z,1756939617.519 [CBIT](INFO): Clearing failed state for component MassServo
2025-09-03T22:46:57.520Z,1756939617.520 [MassServo] No Fault, FailCount= 3
2025-09-03T22:46:57.851Z,1756939617.851 [MassServo](DEBUG): Initializing EZServoServo.
2025-09-03T22:46:57.882Z,1756939617.882 [MassServo](DEBUG): Initializing MassServo.
2025-09-03T22:47:00.620Z,1756939620.620 [MassServo](FAULT): Mass initialization uart error:serial timeout
2025-09-03T22:47:00.620Z,1756939620.620 [MassServo] Communications Fault, FailCount= 4
2025-09-03T22:47:00.620Z,1756939620.620 [MassServo](ERROR): Communications Fault
2025-09-03T22:47:00.625Z,1756939620.625 [CBIT](ERROR): Communications Fault in component: MassServo
2025-09-03T22:47:00.785Z,1756939620.785 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-09-03T22:47:00.785Z,1756939620.785 [MassServo](INFO): Powering down
2025-09-03T22:47:01.625Z,1756939621.625 [CBIT](INFO): Clearing failed state for component MassServo
2025-09-03T22:47:01.625Z,1756939621.625 [MassServo] No Fault, FailCount= 4
2025-09-03T22:47:01.917Z,1756939621.917 [MassServo](DEBUG): Initializing EZServoServo.
2025-09-03T22:47:01.917Z,1756939621.917 [MassServo](DEBUG): Initializing MassServo.
2025-09-03T22:47:10.813Z,1756939630.813 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-03T22:47:10.813Z,1756939630.813 [Default:CheckIn:C.Wait] Stopped
2025-09-03T22:47:10.813Z,1756939630.813 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-03T22:47:10.813Z,1756939630.813 [Default:CheckIn:D] Running Loop=1
2025-09-03T22:47:11.255Z,1756939631.255 [Default:CheckIn:D] Stopped
2025-09-03T22:47:11.300Z,1756939631.300 [Default:CheckIn:E] Running Loop=1
2025-09-03T22:47:11.617Z,1756939631.617 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 138.812354 min
2025-09-03T22:47:11.617Z,1756939631.617 [Default:CheckIn:E] Stopped
2025-09-03T22:47:11.617Z,1756939631.617 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-03T22:47:11.618Z,1756939631.618 [Default:CheckIn] Stopped
2025-09-03T22:47:11.618Z,1756939631.618 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-03T22:47:11.618Z,1756939631.618 [Default:CheckIn](INFO): Running loop #26
2025-09-03T22:47:11.618Z,1756939631.618 [Default:CheckIn] Running Loop=26
2025-09-03T22:47:11.618Z,1756939631.618 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-03T22:47:11.618Z,1756939631.618 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-03T22:47:13.607Z,1756939633.607 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224705.00,A,3648.16889,N,12147.28146,W,1.108,342.84,030925,,,A*79
2025-09-03T22:47:13.617Z,1756939633.617 [NAL9602](INFO): GPS fix at 20250903T224705: (36.802815, -121.788024)
2025-09-03T22:47:13.644Z,1756939633.644 [Default:CheckIn:Read_GPS] Stopped
2025-09-03T22:47:13.644Z,1756939633.644 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-03T22:47:23.540Z,1756939643.540 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T22:47:23.540Z,1756939643.540 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T22:47:23.540Z,1756939643.540 [AHRS_M2](ERROR): Data Fault
2025-09-03T22:47:23.696Z,1756939643.696 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T22:47:23.929Z,1756939643.929 [AHRS_M2](INFO): Powering down
2025-09-03T22:47:23.933Z,1756939643.933 [Depth_Keller](ERROR): Pressure or depth reading out of range: -0.800452 decibar, -0.794549 m
2025-09-03T22:47:24.920Z,1756939644.920 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T22:47:24.920Z,1756939644.920 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T22:47:25.141Z,1756939645.141 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T22:47:37.212Z,1756939657.212 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250903T202251/Courier0079.lzma
2025-09-03T22:47:38.214Z,1756939658.214 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Courier0079.lzma.bak
2025-09-03T22:47:38.214Z,1756939658.214 [DataOverHttps](INFO): SBD MOMSN=25925046
2025-09-03T22:47:46.173Z,1756939666.173 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-03T22:47:53.249Z,1756939673.249 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.444336 decibar, 0.000000 m
2025-09-03T22:48:02.233Z,1756939682.233 [DAT](INFO): DAT read: user:6>[Bearing]
2025-09-03T22:48:02.234Z,1756939682.234 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC
2025-09-03T22:48:02.235Z,1756939682.235 [DAT](INFO): commRate: 1200
2025-09-03T22:48:15.063Z,1756939695.063 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250903T224626/Courier0000.lzma
2025-09-03T22:48:16.066Z,1756939696.066 [DataOverHttps](INFO): Moved sent file to Logs/20250903T224626/Courier0000.lzma.bak
2025-09-03T22:48:16.066Z,1756939696.066 [DataOverHttps](INFO): SBD MOMSN=25925049
2025-09-03T22:48:29.656Z,1756939709.656 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T22:48:29.656Z,1756939709.656 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T22:48:29.656Z,1756939709.656 [AHRS_M2](ERROR): Data Fault
2025-09-03T22:48:29.708Z,1756939709.708 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T22:48:30.061Z,1756939710.061 [AHRS_M2](INFO): Powering down
2025-09-03T22:48:30.957Z,1756939710.957 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T22:48:30.957Z,1756939710.957 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T22:48:31.249Z,1756939711.249 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T22:48:42.157Z,1756939722.157 [DataOverHttps](INFO): Sending 358 bytes from file Logs/20250903T202251/Express0080.lzma
2025-09-03T22:48:43.155Z,1756939723.155 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Express0080.lzma.bak
2025-09-03T22:48:43.155Z,1756939723.155 [DataOverHttps](INFO): SBD MOMSN=25925052
2025-09-03T22:48:46.277Z,1756939726.277 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.444336 decibar, 0.000000 m
2025-09-03T22:48:49.087Z,1756939729.087 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.444336 decibar, 0.000000 m
2025-09-03T22:49:02.125Z,1756939742.125 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.444336 decibar, 0.000000 m
2025-09-03T22:49:04.806Z,1756939744.806 [DAT](INFO): DAT read: ectional Acoustic Transponder version 8.15.0
2025-09-03T22:49:04.807Z,1756939744.807 [DAT](INFO): DAT read: Sep 3 2025 22:48:59
2025-09-03T22:49:05.820Z,1756939745.820 [DAT](INFO): DAT read: [Bearing]
2025-09-03T22:49:05.820Z,1756939745.820 [DAT](INFO): unknown deviceResponse_: [Bearing]
2025-09-03T22:49:05.821Z,1756939745.821 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC
2025-09-03T22:49:05.822Z,1756939745.822 [DAT](INFO): commRate: 1200
2025-09-03T22:49:09.411Z,1756939749.411 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250903T224626/Express0001.lzma
2025-09-03T22:49:10.414Z,1756939750.414 [DataOverHttps](INFO): Moved sent file to Logs/20250903T224626/Express0001.lzma.bak
2025-09-03T22:49:10.414Z,1756939750.414 [DataOverHttps](INFO): SBD MOMSN=25925060
2025-09-03T22:49:12.141Z,1756939752.141 [Default:CheckIn:Read_Iridium] Stopped
2025-09-03T22:49:12.141Z,1756939752.141 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-03T22:49:12.141Z,1756939752.141 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-03T22:49:25.486Z,1756939765.486 [Depth_Keller](ERROR): Pressure or depth reading out of range: -0.800452 decibar, -0.794549 m
2025-09-03T22:49:58.644Z,1756939798.644 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T22:49:58.644Z,1756939798.644 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T22:49:58.644Z,1756939798.644 [AHRS_M2](ERROR): Data Fault
2025-09-03T22:49:58.665Z,1756939798.665 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T22:49:59.033Z,1756939799.033 [AHRS_M2](INFO): Powering down
2025-09-03T22:49:59.872Z,1756939799.872 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T22:49:59.872Z,1756939799.872 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T22:50:00.248Z,1756939800.248 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T22:50:08.445Z,1756939808.445 [DAT](INFO): DAT read: Transponder version 8.15.0
2025-09-03T22:50:08.446Z,1756939808.446 [DAT](INFO): DAT read: Sep 3 2025 22:50:02
2025-09-03T22:50:09.421Z,1756939809.421 [DAT](INFO): DAT read: [Bearing]
2025-09-03T22:50:09.421Z,1756939809.421 [DAT](INFO): unknown deviceResponse_: [Bearing]
2025-09-03T22:50:09.422Z,1756939809.422 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC
2025-09-03T22:50:09.423Z,1756939809.423 [DAT](INFO): commRate: 1200
2025-09-03T22:50:33.797Z,1756939833.797 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T22:50:33.797Z,1756939833.797 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T22:50:33.797Z,1756939833.797 [AHRS_M2](ERROR): Data Fault
2025-09-03T22:50:33.845Z,1756939833.845 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T22:50:34.172Z,1756939834.172 [AHRS_M2](INFO): Powering down
2025-09-03T22:50:35.167Z,1756939835.167 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T22:50:35.167Z,1756939835.167 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T22:50:35.384Z,1756939835.384 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T22:50:37.817Z,1756939837.817 [Depth_Keller](ERROR): Pressure or depth reading out of range: -0.800452 decibar, -0.794549 m
2025-09-03T22:50:43.876Z,1756939843.876 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T22:51:06.929Z,1756939866.929 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.444336 decibar, 0.000000 m
2025-09-03T22:51:09.356Z,1756939869.356 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T22:51:09.356Z,1756939869.356 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T22:51:09.356Z,1756939869.356 [AHRS_M2](ERROR): Data Fault
2025-09-03T22:51:09.384Z,1756939869.384 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T22:51:09.733Z,1756939869.733 [AHRS_M2](INFO): Powering down
2025-09-03T22:51:10.196Z,1756939870.196 [DAT](INFO): DAT read:
2025-09-03T22:51:10.197Z,1756939870.197 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2025-09-03T22:51:10.631Z,1756939870.631 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T22:51:10.631Z,1756939870.631 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T22:51:10.943Z,1756939870.943 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T22:51:12.213Z,1756939872.213 [DAT](INFO): DAT read: MF Frequency Band
2025-09-03T22:51:12.214Z,1756939872.214 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2025-09-03T22:51:12.215Z,1756939872.215 [DAT](INFO): DAT read: Sep 3 2025 22:51:06
2025-09-03T22:51:13.225Z,1756939873.225 [DAT](INFO): DAT read: Features enabled [Bearing]
2025-09-03T22:51:13.226Z,1756939873.226 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC
2025-09-03T22:51:13.227Z,1756939873.227 [DAT](INFO): commRate: 1200
2025-09-03T22:51:15.004Z,1756939875.004 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T22:51:46.140Z,1756939906.140 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T22:51:46.539Z,1756939906.539 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T22:51:46.539Z,1756939906.539 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T22:51:46.539Z,1756939906.539 [AHRS_M2](ERROR): Data Fault
2025-09-03T22:51:46.568Z,1756939906.568 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T22:51:46.922Z,1756939906.922 [AHRS_M2](INFO): Powering down
2025-09-03T22:51:47.825Z,1756939907.825 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T22:51:47.825Z,1756939907.825 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T22:51:48.165Z,1756939908.165 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T22:52:15.808Z,1756939935.808 [DAT](INFO): DAT read: MF Frequency Band
2025-09-03T22:52:15.810Z,1756939935.810 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2025-09-03T22:52:15.811Z,1756939935.811 [DAT](INFO): DAT read: Sep 3 2025 22:52:10
2025-09-03T22:52:16.813Z,1756939936.813 [DAT](INFO): DAT read: Feature[Bearing]
2025-09-03T22:52:16.814Z,1756939936.814 [DAT](INFO): unknown deviceResponse_: Feature[Bearing]
2025-09-03T22:52:16.815Z,1756939936.815 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC
2025-09-03T22:52:16.815Z,1756939936.815 [DAT](INFO): commRate: 1200
2025-09-03T22:52:17.276Z,1756939937.276 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T22:52:22.510Z,1756939942.510 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T22:52:22.510Z,1756939942.510 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T22:52:22.510Z,1756939942.510 [AHRS_M2](ERROR): Data Fault
2025-09-03T22:52:22.558Z,1756939942.558 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T22:52:22.894Z,1756939942.894 [AHRS_M2](INFO): Powering down
2025-09-03T22:52:23.754Z,1756939943.754 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T22:52:23.754Z,1756939943.754 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T22:52:24.145Z,1756939944.145 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T22:52:48.408Z,1756939968.408 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T22:52:55.437Z,1756939975.437 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.795303
2025-09-03T22:52:56.880Z,1756939976.880 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T22:52:56.880Z,1756939976.880 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T22:52:56.880Z,1756939976.880 [AHRS_M2](ERROR): Data Fault
2025-09-03T22:52:56.941Z,1756939976.941 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T22:52:57.264Z,1756939977.264 [AHRS_M2](INFO): Powering down
2025-09-03T22:52:58.106Z,1756939978.106 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T22:52:58.106Z,1756939978.106 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T22:52:58.497Z,1756939978.497 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T22:53:11.064Z,1756939991.064 [CBIT](INFO): Clearing failed state for component DropWeight
2025-09-03T22:53:11.064Z,1756939991.064 [DropWeight] No Fault, FailCount= 1
2025-09-03T22:53:19.373Z,1756939999.373 [DAT](INFO): DAT read: 3 2025 22:53:13
2025-09-03T22:53:20.381Z,1756940000.381 [DAT](INFO): DAT read: [Bearing]
2025-09-03T22:53:20.381Z,1756940000.381 [DAT](INFO): unknown deviceResponse_: [Bearing]
2025-09-03T22:53:20.382Z,1756940000.382 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC
2025-09-03T22:53:20.383Z,1756940000.383 [DAT](INFO): commRate: 1200
2025-09-03T22:53:34.477Z,1756940014.477 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003358
2025-09-03T22:53:59.675Z,1756940039.675 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.444336 decibar, 0.000000 m
2025-09-03T22:54:12.264Z,1756940052.264 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T22:54:12.264Z,1756940052.264 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T22:54:12.264Z,1756940052.264 [AHRS_M2](ERROR): Data Fault
2025-09-03T22:54:12.363Z,1756940052.363 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T22:54:12.749Z,1756940052.749 [AHRS_M2](INFO): Powering down
2025-09-03T22:54:12.786Z,1756940052.786 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-03T22:54:12.786Z,1756940052.786 [Default:CheckIn:C.Wait] Stopped
2025-09-03T22:54:12.786Z,1756940052.786 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-03T22:54:12.786Z,1756940052.786 [Default:CheckIn:D] Running Loop=1
2025-09-03T22:54:13.079Z,1756940053.079 [Default:CheckIn:D] Stopped
2025-09-03T22:54:13.079Z,1756940053.079 [Default:CheckIn:E] Running Loop=1
2025-09-03T22:54:13.487Z,1756940053.487 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 145.842741 min
2025-09-03T22:54:13.487Z,1756940053.487 [Default:CheckIn:E] Stopped
2025-09-03T22:54:13.487Z,1756940053.487 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-03T22:54:13.487Z,1756940053.487 [Default:CheckIn] Stopped
2025-09-03T22:54:13.487Z,1756940053.487 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-03T22:54:13.492Z,1756940053.492 [Default:CheckIn](INFO): Running loop #27
2025-09-03T22:54:13.492Z,1756940053.492 [Default:CheckIn] Running Loop=27
2025-09-03T22:54:13.493Z,1756940053.493 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-03T22:54:13.493Z,1756940053.493 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-03T22:54:13.516Z,1756940053.516 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T22:54:13.516Z,1756940053.516 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T22:54:13.877Z,1756940053.877 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T22:54:15.514Z,1756940055.514 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225404.00,A,3648.16577,N,12147.28345,W,0.641,5.32,030925,,,A*71
2025-09-03T22:54:15.526Z,1756940055.526 [NAL9602](INFO): GPS fix at 20250903T225404: (36.802763, -121.788057)
2025-09-03T22:54:15.535Z,1756940055.535 [Default:CheckIn:Read_GPS] Stopped
2025-09-03T22:54:15.535Z,1756940055.535 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-03T22:54:31.371Z,1756940071.371 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.444336 decibar, 0.000000 m
2025-09-03T22:54:38.592Z,1756940078.592 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T22:54:38.592Z,1756940078.592 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T22:54:38.592Z,1756940078.592 [AHRS_M2](ERROR): Data Fault
2025-09-03T22:54:38.622Z,1756940078.622 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T22:54:38.990Z,1756940078.990 [AHRS_M2](INFO): Powering down
2025-09-03T22:54:39.341Z,1756940079.341 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.444336 decibar, 0.000000 m
2025-09-03T22:54:39.764Z,1756940079.764 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T22:54:39.764Z,1756940079.764 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T22:54:40.165Z,1756940080.165 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T22:54:46.270Z,1756940086.270 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-09-03T22:54:46.352Z,1756940086.352 [NAL9602](ERROR): received:
+CSQ:0
OK865, 0, 0, 0, 0
OK
2025-09-03T22:55:18.587Z,1756940118.587 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T22:55:20.220Z,1756940120.220 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T22:55:20.220Z,1756940120.220 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T22:55:20.220Z,1756940120.220 [AHRS_M2](ERROR): Data Fault
2025-09-03T22:55:20.285Z,1756940120.285 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T22:55:20.585Z,1756940120.585 [AHRS_M2](INFO): Powering down
2025-09-03T22:55:21.385Z,1756940121.385 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T22:55:21.385Z,1756940121.385 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T22:55:21.805Z,1756940121.805 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T22:55:35.622Z,1756940135.622 [Depth_Keller](ERROR): Pressure or depth reading out of range: 947.322021 decibar, 0.000000 m
2025-09-03T22:55:49.688Z,1756940149.688 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T22:55:49.688Z,1756940149.688 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T22:55:49.688Z,1756940149.688 [AHRS_M2](ERROR): Data Fault
2025-09-03T22:55:49.712Z,1756940149.712 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T22:55:49.781Z,1756940149.781 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T22:55:50.029Z,1756940150.029 [AHRS_M2](INFO): Powering down
2025-09-03T22:55:51.021Z,1756940151.021 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T22:55:51.021Z,1756940151.021 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T22:55:51.263Z,1756940151.263 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T22:56:20.054Z,1756940180.054 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T22:56:20.054Z,1756940180.054 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T22:56:20.054Z,1756940180.054 [AHRS_M2](ERROR): Data Fault
2025-09-03T22:56:20.207Z,1756940180.207 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T22:56:20.393Z,1756940180.393 [AHRS_M2](INFO): Powering down
2025-09-03T22:56:20.836Z,1756940180.836 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-09-03T22:56:21.298Z,1756940181.298 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T22:56:21.298Z,1756940181.298 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T22:56:21.592Z,1756940181.592 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T22:56:36.147Z,1756940196.147 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250903T224626/Courier0007.lzma
2025-09-03T22:56:36.204Z,1756940196.204 [Depth_Keller](ERROR): Pressure or depth reading out of range: 947.322021 decibar, 0.000000 m
2025-09-03T22:56:37.150Z,1756940197.150 [DataOverHttps](INFO): Moved sent file to Logs/20250903T224626/Courier0007.lzma.bak
2025-09-03T22:56:37.150Z,1756940197.150 [DataOverHttps](INFO): SBD MOMSN=25925077
2025-09-03T22:56:47.161Z,1756940207.161 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.444336 decibar, 0.000000 m
2025-09-03T22:57:04.277Z,1756940224.277 [DataOverHttps](INFO): Sending 324 bytes from file Logs/20250903T202251/Express0083.lzma
2025-09-03T22:57:05.273Z,1756940225.273 [DataOverHttps](INFO): Moved sent file to Logs/20250903T202251/Express0083.lzma.bak
2025-09-03T22:57:05.273Z,1756940225.273 [DataOverHttps](INFO): SBD MOMSN=25925081
2025-09-03T22:57:29.664Z,1756940249.664 [DataOverHttps](INFO): Sending 670 bytes from file Logs/20250903T224626/Express0005.lzma
2025-09-03T22:57:30.686Z,1756940250.686 [DataOverHttps](INFO): Moved sent file to Logs/20250903T224626/Express0005.lzma.bak
2025-09-03T22:57:30.686Z,1756940250.686 [DataOverHttps](INFO): SBD MOMSN=25925096
2025-09-03T22:57:46.924Z,1756940266.924 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T22:57:46.924Z,1756940266.924 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T22:57:46.924Z,1756940266.924 [AHRS_M2](ERROR): Data Fault
2025-09-03T22:57:46.972Z,1756940266.972 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T22:57:47.304Z,1756940267.304 [AHRS_M2](INFO): Powering down
2025-09-03T22:57:48.178Z,1756940268.178 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T22:57:48.178Z,1756940268.178 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T22:57:48.537Z,1756940268.537 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T22:57:51.430Z,1756940271.430 [Depth_Keller](ERROR): Pressure or depth reading out of range: 947.322021 decibar, 0.000000 m
2025-09-03T22:57:52.203Z,1756940272.203 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.444336 decibar, 0.000000 m
2025-09-03T22:57:58.701Z,1756940278.701 [Depth_Keller](ERROR): Pressure or depth reading out of range: 947.322021 decibar, 0.000000 m
2025-09-03T22:57:59.162Z,1756940279.162 [Default:CheckIn:Read_Iridium] Stopped
2025-09-03T22:57:59.162Z,1756940279.162 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-03T22:57:59.162Z,1756940279.162 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-03T22:58:18.536Z,1756940298.536 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T22:58:18.536Z,1756940298.536 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T22:58:18.536Z,1756940298.536 [AHRS_M2](ERROR): Data Fault
2025-09-03T22:58:18.587Z,1756940298.587 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T22:58:18.927Z,1756940298.927 [AHRS_M2](INFO): Powering down
2025-09-03T22:58:19.783Z,1756940299.783 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T22:58:19.783Z,1756940299.783 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T22:58:20.132Z,1756940300.132 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T22:58:51.288Z,1756940331.288 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T22:58:51.288Z,1756940331.288 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T22:58:51.288Z,1756940331.288 [AHRS_M2](ERROR): Data Fault
2025-09-03T22:58:51.353Z,1756940331.353 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T22:58:51.676Z,1756940331.676 [AHRS_M2](INFO): Powering down
2025-09-03T22:58:52.555Z,1756940332.555 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T22:58:52.555Z,1756940332.555 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T22:58:52.872Z,1756940332.872 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T22:59:18.362Z,1756940358.362 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-09-03T22:59:30.480Z,1756940370.480 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T22:59:30.480Z,1756940370.480 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T22:59:30.480Z,1756940370.480 [AHRS_M2](ERROR): Data Fault
2025-09-03T22:59:30.535Z,1756940370.535 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T22:59:30.871Z,1756940370.871 [AHRS_M2](INFO): Powering down
2025-09-03T22:59:31.759Z,1756940371.759 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T22:59:31.759Z,1756940371.759 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T22:59:32.097Z,1756940372.097 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T22:59:49.169Z,1756940389.169 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-03T23:00:06.456Z,1756940406.456 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T23:00:06.456Z,1756940406.456 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T23:00:06.456Z,1756940406.456 [AHRS_M2](ERROR): Data Fault
2025-09-03T23:00:06.516Z,1756940406.516 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T23:00:06.845Z,1756940406.845 [AHRS_M2](INFO): Powering down
2025-09-03T23:00:07.733Z,1756940407.733 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T23:00:07.733Z,1756940407.733 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T23:00:08.048Z,1756940408.048 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T23:01:19.040Z,1756940479.040 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T23:01:19.040Z,1756940479.040 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T23:01:19.040Z,1756940479.040 [AHRS_M2](ERROR): Data Fault
2025-09-03T23:01:19.110Z,1756940479.110 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T23:01:19.429Z,1756940479.429 [AHRS_M2](INFO): Powering down
2025-09-03T23:01:20.385Z,1756940480.385 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T23:01:20.385Z,1756940480.385 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T23:01:20.642Z,1756940480.642 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T23:01:33.638Z,1756940493.638 [Depth_Keller](ERROR): Pressure or depth reading out of range: 947.322021 decibar, 0.000000 m
2025-09-03T23:01:52.604Z,1756940512.604 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T23:01:52.604Z,1756940512.604 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T23:01:52.604Z,1756940512.604 [AHRS_M2](ERROR): Data Fault
2025-09-03T23:01:52.626Z,1756940512.626 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T23:01:52.965Z,1756940512.965 [AHRS_M2](INFO): Powering down
2025-09-03T23:01:53.865Z,1756940513.865 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T23:01:53.865Z,1756940513.865 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T23:01:54.187Z,1756940514.187 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T23:02:10.810Z,1756940530.810 [Depth_Keller](ERROR): Pressure or depth reading out of range: -0.800452 decibar, -0.794549 m
2025-09-03T23:02:39.092Z,1756940559.092 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T23:02:39.092Z,1756940559.092 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T23:02:39.092Z,1756940559.092 [AHRS_M2](ERROR): Data Fault
2025-09-03T23:02:39.176Z,1756940559.176 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T23:02:39.457Z,1756940559.457 [AHRS_M2](INFO): Powering down
2025-09-03T23:02:40.359Z,1756940560.359 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T23:02:40.359Z,1756940560.359 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T23:02:40.673Z,1756940560.673 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T23:02:59.811Z,1756940579.811 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-03T23:02:59.811Z,1756940579.811 [Default:CheckIn:C.Wait] Stopped
2025-09-03T23:02:59.811Z,1756940579.811 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-03T23:02:59.811Z,1756940579.811 [Default:CheckIn:D] Running Loop=1
2025-09-03T23:03:00.274Z,1756940580.274 [Default:CheckIn:D] Stopped
2025-09-03T23:03:00.274Z,1756940580.274 [Default:CheckIn:E] Running Loop=1
2025-09-03T23:03:00.680Z,1756940580.680 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 154.629346 min
2025-09-03T23:03:00.681Z,1756940580.681 [Default:CheckIn:E] Stopped
2025-09-03T23:03:00.681Z,1756940580.681 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-03T23:03:00.681Z,1756940580.681 [Default:CheckIn] Stopped
2025-09-03T23:03:00.681Z,1756940580.681 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-03T23:03:00.681Z,1756940580.681 [Default:CheckIn](INFO): Running loop #28
2025-09-03T23:03:00.681Z,1756940580.681 [Default:CheckIn] Running Loop=28
2025-09-03T23:03:00.681Z,1756940580.681 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-03T23:03:00.681Z,1756940580.681 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-03T23:03:09.461Z,1756940589.461 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T23:03:09.461Z,1756940589.461 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T23:03:09.461Z,1756940589.461 [AHRS_M2](ERROR): Data Fault
2025-09-03T23:03:09.645Z,1756940589.645 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T23:03:09.841Z,1756940589.841 [AHRS_M2](INFO): Powering down
2025-09-03T23:03:10.731Z,1756940590.731 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T23:03:10.731Z,1756940590.731 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T23:03:11.062Z,1756940591.062 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T23:03:12.301Z,1756940592.301 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.444336 decibar, 0.000000 m
2025-09-03T23:03:16.449Z,1756940596.449 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.444336 decibar, 0.000000 m
2025-09-03T23:03:17.513Z,1756940597.513 [Depth_Keller](ERROR): Pressure or depth reading out of range: -0.800452 decibar, -0.794549 m
2025-09-03T23:03:32.097Z,1756940612.097 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.444336 decibar, 0.000000 m
2025-09-03T23:03:35.744Z,1756940615.744 [Depth_Keller](ERROR): Pressure or depth reading out of range: 947.322021 decibar, 0.000000 m
2025-09-03T23:03:35.812Z,1756940615.812 [NAL9602](ERROR): parseGSV uart error: serial timeout
2025-09-03T23:03:45.464Z,1756940625.464 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T23:03:45.464Z,1756940625.464 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T23:03:45.464Z,1756940625.464 [AHRS_M2](ERROR): Data Fault
2025-09-03T23:03:45.561Z,1756940625.561 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T23:03:45.845Z,1756940625.845 [AHRS_M2](INFO): Powering down
2025-09-03T23:03:46.611Z,1756940626.611 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.444336 decibar, 0.000000 m
2025-09-03T23:03:46.711Z,1756940626.711 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T23:03:46.711Z,1756940626.711 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T23:03:47.020Z,1756940627.020 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T23:03:57.946Z,1756940637.946 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.444336 decibar, 0.000000 m
2025-09-03T23:04:01.688Z,1756940641.688 [ElevatorServo](ERROR): getPosition uart error serial timeout
2025-09-03T23:04:01.688Z,1756940641.688 [ElevatorServo](FAULT): Elevator uart error - getPosition.serial timeout
2025-09-03T23:04:01.688Z,1756940641.688 [ElevatorServo] Communications Fault, FailCount= 1
2025-09-03T23:04:01.688Z,1756940641.688 [ElevatorServo](ERROR): Communications Fault
2025-09-03T23:04:01.808Z,1756940641.808 [CBIT](ERROR): Communications Fault in component: ElevatorServo
2025-09-03T23:04:01.886Z,1756940641.886 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-09-03T23:04:01.886Z,1756940641.886 [ElevatorServo](INFO): Powering down
2025-09-03T23:04:02.675Z,1756940642.675 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-09-03T23:04:02.676Z,1756940642.676 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-09-03T23:04:02.695Z,1756940642.695 [CBIT](INFO): Clearing failed state for component ElevatorServo
2025-09-03T23:04:02.695Z,1756940642.695 [ElevatorServo] No Fault, FailCount= 1
2025-09-03T23:04:19.369Z,1756940659.369 [Depth_Keller](ERROR): Pressure or depth reading out of range: -0.800452 decibar, -0.794549 m
2025-09-03T23:04:33.482Z,1756940673.482 [Depth_Keller](ERROR): Pressure or depth reading out of range: -0.800452 decibar, -0.794549 m
2025-09-03T23:04:39.078Z,1756940679.078 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.444336 decibar, 0.000000 m
2025-09-03T23:04:42.357Z,1756940682.357 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.444336 decibar, 0.000000 m
2025-09-03T23:04:50.375Z,1756940690.375 [Depth_Keller](ERROR): Pressure or depth reading out of range: 947.322021 decibar, 0.000000 m
2025-09-03T23:05:11.045Z,1756940711.045 [Depth_Keller](ERROR): Pressure or depth reading out of range: -0.800452 decibar, -0.794549 m
2025-09-03T23:05:17.529Z,1756940717.529 [Depth_Keller](ERROR): Pressure or depth reading out of range: -0.800452 decibar, -0.794549 m
2025-09-03T23:05:28.808Z,1756940728.808 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T23:05:28.808Z,1756940728.808 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T23:05:28.808Z,1756940728.808 [AHRS_M2](ERROR): Data Fault
2025-09-03T23:05:28.861Z,1756940728.861 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T23:05:29.185Z,1756940729.185 [AHRS_M2](INFO): Powering down
2025-09-03T23:05:30.030Z,1756940730.030 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T23:05:30.030Z,1756940730.030 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T23:05:30.419Z,1756940730.419 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T23:06:01.572Z,1756940761.572 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T23:06:01.572Z,1756940761.572 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T23:06:01.572Z,1756940761.572 [AHRS_M2](ERROR): Data Fault
2025-09-03T23:06:01.625Z,1756940761.625 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T23:06:01.957Z,1756940761.957 [AHRS_M2](INFO): Powering down
2025-09-03T23:06:02.875Z,1756940762.875 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T23:06:02.875Z,1756940762.875 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T23:06:03.192Z,1756940763.192 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T23:06:35.317Z,1756940795.317 [Depth_Keller](ERROR): Pressure or depth reading out of range: 947.322021 decibar, 0.000000 m
2025-09-03T23:06:37.676Z,1756940797.676 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T23:06:37.676Z,1756940797.676 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T23:06:37.676Z,1756940797.676 [AHRS_M2](ERROR): Data Fault
2025-09-03T23:06:37.764Z,1756940797.764 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T23:06:38.070Z,1756940798.070 [AHRS_M2](INFO): Powering down
2025-09-03T23:06:38.999Z,1756940798.999 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T23:06:38.999Z,1756940798.999 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T23:06:39.306Z,1756940799.306 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T23:06:42.477Z,1756940802.477 [Depth_Keller](ERROR): Pressure or depth reading out of range: 947.322021 decibar, 0.000000 m
2025-09-03T23:06:49.762Z,1756940809.762 [Depth_Keller](ERROR): Pressure or depth reading out of range: 947.322021 decibar, 0.000000 m
2025-09-03T23:07:12.808Z,1756940832.808 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T23:07:12.808Z,1756940832.808 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T23:07:12.808Z,1756940832.808 [AHRS_M2](ERROR): Data Fault
2025-09-03T23:07:12.896Z,1756940832.896 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T23:07:13.166Z,1756940833.166 [AHRS_M2](INFO): Powering down
2025-09-03T23:07:14.106Z,1756940834.106 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T23:07:14.106Z,1756940834.106 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T23:07:14.365Z,1756940834.365 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T23:07:46.300Z,1756940866.300 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T23:07:46.300Z,1756940866.300 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T23:07:46.300Z,1756940866.300 [AHRS_M2](ERROR): Data Fault
2025-09-03T23:07:46.470Z,1756940866.470 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T23:07:46.745Z,1756940866.745 [AHRS_M2](INFO): Powering down
2025-09-03T23:07:47.621Z,1756940867.621 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T23:07:47.621Z,1756940867.621 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T23:07:47.999Z,1756940867.999 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T23:07:48.321Z,1756940868.321 [Depth_Keller](ERROR): Pressure or depth reading out of range: 947.322021 decibar, 0.000000 m
2025-09-03T23:07:59.227Z,1756940879.227 [NAL9602](INFO): No messages in MT queue
2025-09-03T23:08:00.942Z,1756940880.942 [NAL9602](ERROR): getFix uart error: serial timeout
2025-09-03T23:08:01.002Z,1756940881.002 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-09-03T23:03:00.7Z
2025-09-03T23:08:01.002Z,1756940881.002 [Default:CheckIn:Read_GPS] Stopped
2025-09-03T23:08:01.002Z,1756940881.002 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-03T23:08:15.132Z,1756940895.132 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20250903T224626/Courier0010.lzma
2025-09-03T23:08:16.382Z,1756940896.382 [DataOverHttps](FAULT): Server acknowledged file Logs/20250903T224626/Courier0010.lzma, notLogs/20250903T224626/Courier0013.lzma
2025-09-03T23:08:16.382Z,1756940896.382 [DataOverHttps](INFO): SBD MOMSN=25925111
2025-09-03T23:08:19.096Z,1756940899.096 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T23:08:19.096Z,1756940899.096 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T23:08:19.096Z,1756940899.096 [AHRS_M2](ERROR): Data Fault
2025-09-03T23:08:19.277Z,1756940899.277 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T23:08:19.493Z,1756940899.493 [AHRS_M2](INFO): Powering down
2025-09-03T23:08:20.484Z,1756940900.484 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T23:08:20.484Z,1756940900.484 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T23:08:20.692Z,1756940900.692 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T23:08:31.624Z,1756940911.624 [NAL9602](ERROR): parseGSV uart error: serial timeout
2025-09-03T23:08:46.312Z,1756940926.312 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250903T224626/Courier0013.lzma
2025-09-03T23:08:47.314Z,1756940927.314 [DataOverHttps](INFO): Moved sent file to Logs/20250903T224626/Courier0013.lzma.bak
2025-09-03T23:08:47.314Z,1756940927.314 [DataOverHttps](INFO): SBD MOMSN=25925117
2025-09-03T23:08:54.668Z,1756940934.668 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T23:08:54.668Z,1756940934.668 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T23:08:54.668Z,1756940934.668 [AHRS_M2](ERROR): Data Fault
2025-09-03T23:08:54.827Z,1756940934.827 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T23:08:55.053Z,1756940935.053 [AHRS_M2](INFO): Powering down
2025-09-03T23:08:55.958Z,1756940935.958 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T23:08:55.958Z,1756940935.958 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T23:08:56.265Z,1756940936.265 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T23:09:02.397Z,1756940942.397 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-03T23:09:14.825Z,1756940954.825 [DataOverHttps](INFO): Sending 392 bytes from file Logs/20250903T224626/Express0011.lzma
2025-09-03T23:09:14.862Z,1756940954.862 [DataOverHttps](INFO): Moved sent file to Logs/20250903T224626/Express0011.lzma.bak
2025-09-03T23:09:14.862Z,1756940954.862 [DataOverHttps](INFO): SBD MOMSN=25925121
2025-09-03T23:09:15.680Z,1756940955.680 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T23:09:15.680Z,1756940955.680 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T23:09:15.680Z,1756940955.680 [AHRS_M2](ERROR): Data Fault
2025-09-03T23:09:15.751Z,1756940955.751 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T23:09:16.065Z,1756940956.065 [AHRS_M2](INFO): Powering down
2025-09-03T23:09:16.916Z,1756940956.916 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T23:09:16.916Z,1756940956.916 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T23:09:17.272Z,1756940957.272 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T23:09:41.749Z,1756940981.749 [Default:CheckIn:Read_Iridium] Stopped
2025-09-03T23:09:41.749Z,1756940981.749 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-03T23:09:41.749Z,1756940981.749 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-03T23:09:56.076Z,1756940996.076 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T23:09:56.077Z,1756940996.077 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T23:09:56.077Z,1756940996.077 [AHRS_M2](ERROR): Data Fault
2025-09-03T23:09:56.159Z,1756940996.159 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T23:09:56.457Z,1756940996.457 [AHRS_M2](INFO): Powering down
2025-09-03T23:09:57.316Z,1756940997.316 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T23:09:57.316Z,1756940997.316 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T23:09:57.677Z,1756940997.677 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T23:10:27.489Z,1756941027.489 [Depth_Keller](ERROR): Pressure or depth reading out of range: -0.800452 decibar, -0.794549 m
2025-09-03T23:10:30.152Z,1756941030.152 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.444336 decibar, 0.000000 m
2025-09-03T23:10:31.737Z,1756941031.737 [Depth_Keller](ERROR): Pressure or depth reading out of range: -0.800452 decibar, -0.794549 m
2025-09-03T23:10:33.277Z,1756941033.277 [Depth_Keller](ERROR): Pressure or depth reading out of range: -0.800452 decibar, -0.794549 m
2025-09-03T23:11:33.080Z,1756941093.080 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T23:11:33.080Z,1756941093.080 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T23:11:33.080Z,1756941093.080 [AHRS_M2](ERROR): Data Fault
2025-09-03T23:11:33.105Z,1756941093.105 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T23:11:33.456Z,1756941093.456 [AHRS_M2](INFO): Powering down
2025-09-03T23:11:34.362Z,1756941094.362 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T23:11:34.362Z,1756941094.362 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T23:11:34.677Z,1756941094.677 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T23:11:57.765Z,1756941117.765 [Depth_Keller](ERROR): Pressure or depth reading out of range: -0.800452 decibar, -0.794549 m
2025-09-03T23:12:03.022Z,1756941123.022 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T23:12:03.022Z,1756941123.022 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T23:12:03.022Z,1756941123.022 [AHRS_M2](ERROR): Data Fault
2025-09-03T23:12:03.122Z,1756941123.122 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T23:12:03.393Z,1756941123.393 [AHRS_M2](INFO): Powering down
2025-09-03T23:12:04.260Z,1756941124.260 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T23:12:04.260Z,1756941124.260 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T23:12:04.600Z,1756941124.600 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T23:12:38.964Z,1756941158.964 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T23:12:38.964Z,1756941158.964 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T23:12:38.964Z,1756941158.964 [AHRS_M2](ERROR): Data Fault
2025-09-03T23:12:38.993Z,1756941158.993 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T23:12:39.353Z,1756941159.353 [AHRS_M2](INFO): Powering down
2025-09-03T23:12:40.253Z,1756941160.253 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T23:12:40.253Z,1756941160.253 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T23:12:40.560Z,1756941160.560 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T23:13:11.700Z,1756941191.700 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T23:13:11.700Z,1756941191.700 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T23:13:11.700Z,1756941191.700 [AHRS_M2](ERROR): Data Fault
2025-09-03T23:13:11.728Z,1756941191.728 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T23:13:12.114Z,1756941192.114 [AHRS_M2](INFO): Powering down
2025-09-03T23:13:12.929Z,1756941192.929 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T23:13:12.929Z,1756941192.929 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T23:13:13.288Z,1756941193.288 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T23:13:46.036Z,1756941226.036 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T23:13:46.036Z,1756941226.036 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T23:13:46.036Z,1756941226.036 [AHRS_M2](ERROR): Data Fault
2025-09-03T23:13:46.085Z,1756941226.085 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T23:13:46.454Z,1756941226.454 [AHRS_M2](INFO): Powering down
2025-09-03T23:13:47.269Z,1756941227.269 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T23:13:47.269Z,1756941227.269 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T23:13:47.632Z,1756941227.632 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T23:14:24.820Z,1756941264.820 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T23:14:24.820Z,1756941264.820 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T23:14:24.820Z,1756941264.820 [AHRS_M2](ERROR): Data Fault
2025-09-03T23:14:24.860Z,1756941264.860 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T23:14:25.213Z,1756941265.213 [AHRS_M2](INFO): Powering down
2025-09-03T23:14:26.074Z,1756941266.074 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T23:14:26.074Z,1756941266.074 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T23:14:26.421Z,1756941266.421 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T23:14:42.403Z,1756941282.403 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-03T23:14:42.403Z,1756941282.403 [Default:CheckIn:C.Wait] Stopped
2025-09-03T23:14:42.403Z,1756941282.403 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-03T23:14:42.403Z,1756941282.403 [Default:CheckIn:D] Running Loop=1
2025-09-03T23:14:42.721Z,1756941282.721 [Default:CheckIn:D] Stopped
2025-09-03T23:14:42.721Z,1756941282.721 [Default:CheckIn:E] Running Loop=1
2025-09-03T23:14:43.058Z,1756941283.058 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 166.336784 min
2025-09-03T23:14:43.058Z,1756941283.058 [Default:CheckIn:E] Stopped
2025-09-03T23:14:43.058Z,1756941283.058 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-03T23:14:43.058Z,1756941283.058 [Default:CheckIn] Stopped
2025-09-03T23:14:43.058Z,1756941283.058 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-03T23:14:43.058Z,1756941283.058 [Default:CheckIn](INFO): Running loop #29
2025-09-03T23:14:43.058Z,1756941283.058 [Default:CheckIn] Running Loop=29
2025-09-03T23:14:43.059Z,1756941283.059 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-03T23:14:43.059Z,1756941283.059 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-03T23:14:44.704Z,1756941284.704 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-09-03T23:14:44.705Z,1756941284.705 [NAL9602] Data Fault, FailCount= 1
2025-09-03T23:14:44.705Z,1756941284.705 [NAL9602](ERROR): Data Fault
2025-09-03T23:14:44.761Z,1756941284.761 [CBIT](ERROR): Data Fault in component: NAL9602
2025-09-03T23:14:45.102Z,1756941285.102 [NAL9602](INFO): Powering down
2025-09-03T23:14:45.934Z,1756941285.934 [CBIT](INFO): Clearing failed state for component NAL9602
2025-09-03T23:14:45.934Z,1756941285.934 [NAL9602] No Fault, FailCount= 1
2025-09-03T23:15:00.004Z,1756941300.004 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T23:15:00.004Z,1756941300.004 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T23:15:00.004Z,1756941300.004 [AHRS_M2](ERROR): Data Fault
2025-09-03T23:15:00.108Z,1756941300.108 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T23:15:00.394Z,1756941300.394 [AHRS_M2](INFO): Powering down
2025-09-03T23:15:01.236Z,1756941301.236 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T23:15:01.236Z,1756941301.236 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T23:15:01.607Z,1756941301.607 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T23:15:15.369Z,1756941315.369 [NAL9602](INFO): Powering up NAL9602
2025-09-03T23:15:28.427Z,1756941328.427 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T23:15:36.040Z,1756941336.040 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T23:15:36.040Z,1756941336.040 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T23:15:36.040Z,1756941336.040 [AHRS_M2](ERROR): Data Fault
2025-09-03T23:15:36.049Z,1756941336.049 [NAL9602](ERROR): NAL9602 initialization error.
2025-09-03T23:15:36.049Z,1756941336.049 [NAL9602] Communications Fault, FailCount= 2
2025-09-03T23:15:36.049Z,1756941336.049 [NAL9602](ERROR): Communications Fault
2025-09-03T23:15:36.122Z,1756941336.122 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T23:15:36.122Z,1756941336.122 [CBIT](ERROR): Communications Fault in component: NAL9602
2025-09-03T23:15:36.424Z,1756941336.424 [AHRS_M2](INFO): Powering down
2025-09-03T23:15:36.437Z,1756941336.437 [NAL9602](INFO): Powering down
2025-09-03T23:15:37.407Z,1756941337.407 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T23:15:37.407Z,1756941337.407 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T23:15:37.407Z,1756941337.407 [CBIT](INFO): Clearing failed state for component NAL9602
2025-09-03T23:15:37.407Z,1756941337.407 [NAL9602] No Fault, FailCount= 2
2025-09-03T23:15:37.638Z,1756941337.638 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T23:16:06.765Z,1756941366.765 [NAL9602](INFO): Powering up NAL9602
2025-09-03T23:16:12.012Z,1756941372.012 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T23:16:12.012Z,1756941372.012 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T23:16:12.012Z,1756941372.012 [AHRS_M2](ERROR): Data Fault
2025-09-03T23:16:12.040Z,1756941372.040 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T23:16:12.398Z,1756941372.398 [AHRS_M2](INFO): Powering down
2025-09-03T23:16:13.239Z,1756941373.239 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T23:16:13.239Z,1756941373.239 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T23:16:13.596Z,1756941373.596 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T23:16:14.409Z,1756941374.409 [Depth_Keller](ERROR): Pressure or depth reading out of range: -0.800452 decibar, -0.794549 m
2025-09-03T23:16:27.408Z,1756941387.408 [NAL9602](ERROR): NAL9602 initialization error.
2025-09-03T23:16:27.408Z,1756941387.408 [NAL9602] Communications Fault, FailCount= 3
2025-09-03T23:16:27.408Z,1756941387.408 [NAL9602](ERROR): Communications Fault
2025-09-03T23:16:27.447Z,1756941387.447 [CBIT](ERROR): Communications Fault in component: NAL9602
2025-09-03T23:16:27.810Z,1756941387.810 [NAL9602](INFO): Powering down
2025-09-03T23:16:28.658Z,1756941388.658 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.444336 decibar, 0.000000 m
2025-09-03T23:16:28.791Z,1756941388.791 [CBIT](INFO): Clearing failed state for component NAL9602
2025-09-03T23:16:28.791Z,1756941388.791 [NAL9602] No Fault, FailCount= 3
2025-09-03T23:16:46.396Z,1756941406.396 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T23:16:46.396Z,1756941406.396 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T23:16:46.396Z,1756941406.396 [AHRS_M2](ERROR): Data Fault
2025-09-03T23:16:46.430Z,1756941406.430 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T23:16:46.780Z,1756941406.780 [AHRS_M2](INFO): Powering down
2025-09-03T23:16:47.669Z,1756941407.669 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T23:16:47.669Z,1756941407.669 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T23:16:47.992Z,1756941407.992 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T23:16:57.860Z,1756941417.860 [NAL9602](INFO): Powering up NAL9602
2025-09-03T23:17:00.468Z,1756941420.468 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.444336 decibar, 0.000000 m
2025-09-03T23:17:08.773Z,1756941428.773 [NAL9602](INFO): NAL9602 initialized
2025-09-03T23:17:16.041Z,1756941436.041 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.444336 decibar, 0.000000 m
2025-09-03T23:17:22.287Z,1756941442.287 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.444336 decibar, 0.000000 m
2025-09-03T23:17:26.767Z,1756941446.767 [PAR_Licor](INFO): A/D timeout, 2 tries over 139 ms
2025-09-03T23:17:26.767Z,1756941446.767 [PAR_Licor] Data Fault, FailCount= 1
2025-09-03T23:17:26.767Z,1756941446.767 [PAR_Licor](ERROR): Data Fault
2025-09-03T23:17:27.077Z,1756941447.077 [CBIT](ERROR): Data Fault in component: PAR_Licor
2025-09-03T23:17:27.077Z,1756941447.077 [CBIT](INFO): Clearing failed state for component PAR_Licor
2025-09-03T23:17:27.077Z,1756941447.077 [PAR_Licor] No Fault, FailCount= 1
2025-09-03T23:17:29.706Z,1756941449.706 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.444336 decibar, 0.000000 m
2025-09-03T23:17:45.004Z,1756941465.004 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T23:17:45.004Z,1756941465.004 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T23:17:45.004Z,1756941465.004 [AHRS_M2](ERROR): Data Fault
2025-09-03T23:17:45.036Z,1756941465.036 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T23:17:45.395Z,1756941465.395 [AHRS_M2](INFO): Powering down
2025-09-03T23:17:46.190Z,1756941466.190 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T23:17:46.191Z,1756941466.191 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T23:17:46.585Z,1756941466.585 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T23:17:46.973Z,1756941466.973 [Depth_Keller](ERROR): Pressure or depth reading out of range: 947.322021 decibar, 0.000000 m
2025-09-03T23:17:48.674Z,1756941468.674 [NAL9602](ERROR): parseGSV uart error: serial timeout
2025-09-03T23:18:10.896Z,1756941490.896 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T23:18:12.345Z,1756941492.345 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T23:18:18.928Z,1756941498.928 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T23:18:18.928Z,1756941498.928 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T23:18:18.928Z,1756941498.928 [AHRS_M2](ERROR): Data Fault
2025-09-03T23:18:18.994Z,1756941498.994 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T23:18:19.321Z,1756941499.321 [AHRS_M2](INFO): Powering down
2025-09-03T23:18:20.281Z,1756941500.281 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T23:18:20.281Z,1756941500.281 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T23:18:20.516Z,1756941500.516 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T23:18:45.660Z,1756941525.660 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T23:18:45.660Z,1756941525.660 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T23:18:45.660Z,1756941525.660 [AHRS_M2](ERROR): Data Fault
2025-09-03T23:18:45.688Z,1756941525.688 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T23:18:46.040Z,1756941526.040 [AHRS_M2](INFO): Powering down
2025-09-03T23:18:46.890Z,1756941526.890 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T23:18:46.890Z,1756941526.890 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T23:18:47.252Z,1756941527.252 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T23:18:47.321Z,1756941527.321 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T23:18:54.978Z,1756941534.978 [NAL9602](INFO): SBD MO Status=2, MOMSN=22866, MT Status=2, MTMSN=0
2025-09-03T23:18:54.978Z,1756941534.978 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-03T23:19:17.988Z,1756941557.988 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T23:19:17.988Z,1756941557.988 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T23:19:17.988Z,1756941557.988 [AHRS_M2](ERROR): Data Fault
2025-09-03T23:19:18.041Z,1756941558.041 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T23:19:18.383Z,1756941558.383 [AHRS_M2](INFO): Powering down
2025-09-03T23:19:19.228Z,1756941559.228 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T23:19:19.228Z,1756941559.228 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T23:19:19.593Z,1756941559.593 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T23:19:21.617Z,1756941561.617 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.444336 decibar, 0.000000 m
2025-09-03T23:19:22.552Z,1756941562.552 [PAR_Licor](INFO): A/D timeout, 8 tries over 137 ms
2025-09-03T23:19:22.553Z,1756941562.553 [PAR_Licor] Data Fault, FailCount= 1
2025-09-03T23:19:22.553Z,1756941562.553 [PAR_Licor](ERROR): Data Fault
2025-09-03T23:19:22.662Z,1756941562.662 [CBIT](ERROR): Data Fault in component: PAR_Licor
2025-09-03T23:19:22.662Z,1756941562.662 [CBIT](INFO): Clearing failed state for component PAR_Licor
2025-09-03T23:19:22.662Z,1756941562.662 [PAR_Licor] No Fault, FailCount= 1
2025-09-03T23:19:22.861Z,1756941562.861 [Depth_Keller](ERROR): Pressure or depth reading out of range: 947.322021 decibar, 0.000000 m
2025-09-03T23:19:24.138Z,1756941564.138 [Depth_Keller](ERROR): Pressure or depth reading out of range: -0.800452 decibar, -0.794549 m
2025-09-03T23:19:25.315Z,1756941565.315 [NAL9602](INFO): SBD MO Status=2, MOMSN=22866, MT Status=2, MTMSN=0
2025-09-03T23:19:25.315Z,1756941565.315 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-03T23:19:30.743Z,1756941570.743 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T23:19:31.889Z,1756941571.889 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.444336 decibar, 0.000000 m
2025-09-03T23:19:37.880Z,1756941577.880 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T23:19:37.880Z,1756941577.880 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T23:19:37.880Z,1756941577.880 [AHRS_M2](ERROR): Data Fault
2025-09-03T23:19:37.938Z,1756941577.938 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T23:19:38.253Z,1756941578.253 [AHRS_M2](INFO): Powering down
2025-09-03T23:19:39.090Z,1756941579.090 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T23:19:39.090Z,1756941579.090 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T23:19:39.475Z,1756941579.475 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T23:19:43.220Z,1756941583.220 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-09-03T23:14:43.1Z
2025-09-03T23:19:43.220Z,1756941583.220 [Default:CheckIn:Read_GPS] Stopped
2025-09-03T23:19:43.220Z,1756941583.220 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-03T23:19:53.401Z,1756941593.401 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250903T224626/Courier0016.lzma
2025-09-03T23:19:54.362Z,1756941594.362 [DataOverHttps](INFO): Moved sent file to Logs/20250903T224626/Courier0016.lzma.bak
2025-09-03T23:19:54.362Z,1756941594.362 [DataOverHttps](INFO): SBD MOMSN=25925215
2025-09-03T23:19:58.610Z,1756941598.610 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T23:20:01.403Z,1756941601.403 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T23:20:11.002Z,1756941611.002 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T23:20:11.002Z,1756941611.002 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T23:20:11.002Z,1756941611.002 [AHRS_M2](ERROR): Data Fault
2025-09-03T23:20:11.041Z,1756941611.041 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T23:20:11.388Z,1756941611.388 [AHRS_M2](INFO): Powering down
2025-09-03T23:20:12.257Z,1756941612.257 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T23:20:12.257Z,1756941612.257 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T23:20:12.600Z,1756941612.600 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T23:20:15.091Z,1756941615.091 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-03T23:20:15.972Z,1756941615.972 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250903T224626/Express0017.lzma
2025-09-03T23:20:16.948Z,1756941616.948 [DataOverHttps](INFO): Moved sent file to Logs/20250903T224626/Express0017.lzma.bak
2025-09-03T23:20:16.949Z,1756941616.949 [DataOverHttps](INFO): SBD MOMSN=25925217
2025-09-03T23:20:18.715Z,1756941618.715 [Default:CheckIn:Read_Iridium] Stopped
2025-09-03T23:20:18.715Z,1756941618.715 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-03T23:20:18.715Z,1756941618.715 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-03T23:20:20.716Z,1756941620.716 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-09-03T23:20:21.256Z,1756941621.256 [NAL9602](ERROR): received:
2025-09-03T23:20:25.670Z,1756941625.670 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.444336 decibar, 0.000000 m
2025-09-03T23:20:27.313Z,1756941627.313 [Depth_Keller](ERROR): Pressure or depth reading out of range: -0.579375 decibar, -0.575102 m
2025-09-03T23:20:29.225Z,1756941629.225 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T23:20:38.353Z,1756941638.353 [Depth_Keller](ERROR): Pressure or depth reading out of range: -0.576210 decibar, -0.571961 m
2025-09-03T23:20:42.309Z,1756941642.309 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.444336 decibar, 0.000000 m
2025-09-03T23:20:43.589Z,1756941643.589 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.444336 decibar, 0.000000 m
2025-09-03T23:20:51.870Z,1756941651.870 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-09-03T23:20:52.392Z,1756941652.392 [NAL9602](ERROR): received:
2025-09-03T23:20:54.829Z,1756941654.829 [Depth_Keller](ERROR): Pressure or depth reading out of range: -0.800452 decibar, -0.794549 m
2025-09-03T23:20:59.623Z,1756941659.623 [Depth_Keller](ERROR): Pressure or depth reading out of range: 947.322021 decibar, 0.000000 m
2025-09-03T23:21:13.189Z,1756941673.189 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-03T23:21:22.745Z,1756941682.745 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-09-03T23:21:23.261Z,1756941683.261 [NAL9602](ERROR): received:
2025-09-03T23:21:23.534Z,1756941683.534 [Depth_Keller](ERROR): Pressure or depth reading out of range: 947.322021 decibar, 0.000000 m
2025-09-03T23:21:27.908Z,1756941687.908 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T23:21:27.908Z,1756941687.908 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T23:21:27.908Z,1756941687.908 [AHRS_M2](ERROR): Data Fault
2025-09-03T23:21:28.296Z,1756941688.296 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T23:21:28.314Z,1756941688.314 [AHRS_M2](INFO): Powering down
2025-09-03T23:21:29.191Z,1756941689.191 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T23:21:29.191Z,1756941689.191 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T23:21:29.541Z,1756941689.541 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T23:21:42.482Z,1756941702.482 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-09-03T23:21:42.483Z,1756941702.483 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-09-03T23:21:42.484Z,1756941702.484 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-09-03T23:21:42.485Z,1756941702.485 [BPC1](INFO): Calculating totals. Valid battery stick count: 52. Valid reserve battery stick count: 5.
2025-09-03T23:21:42.487Z,1756941702.487 [BPC1](INFO): Received data from all battery sticks.
2025-09-03T23:21:55.005Z,1756941715.005 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-09-03T23:21:55.528Z,1756941715.528 [NAL9602](ERROR): received:
2025-09-03T23:21:55.848Z,1756941715.848 [Depth_Keller](INFO): A/D timeout, 6 tries over 141 ms
2025-09-03T23:21:55.848Z,1756941715.848 [Depth_Keller] Data Fault, FailCount= 1
2025-09-03T23:21:55.848Z,1756941715.848 [Depth_Keller](ERROR): Data Fault
2025-09-03T23:21:55.977Z,1756941715.977 [CBIT](ERROR): Data Fault in component: Depth_Keller
2025-09-03T23:21:56.238Z,1756941716.238 [CBIT](INFO): Clearing failed state for component Depth_Keller
2025-09-03T23:21:56.238Z,1756941716.238 [Depth_Keller] No Fault, FailCount= 1
2025-09-03T23:21:56.473Z,1756941716.473 [Depth_Keller](INFO): Initializing.
2025-09-03T23:22:00.625Z,1756941720.625 [Depth_Keller](ERROR): Pressure or depth reading out of range: -0.800452 decibar, -0.794549 m
2025-09-03T23:22:03.016Z,1756941723.016 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T23:22:03.016Z,1756941723.016 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T23:22:03.016Z,1756941723.016 [AHRS_M2](ERROR): Data Fault
2025-09-03T23:22:03.226Z,1756941723.226 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T23:22:03.337Z,1756941723.337 [AHRS_M2](INFO): Powering down
2025-09-03T23:22:03.339Z,1756941723.339 [Depth_Keller](ERROR): Pressure or depth reading out of range: -0.800452 decibar, -0.794549 m
2025-09-03T23:22:04.195Z,1756941724.195 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T23:22:04.195Z,1756941724.195 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T23:22:04.545Z,1756941724.545 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T23:22:31.245Z,1756941751.245 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-09-03T23:22:31.768Z,1756941751.768 [NAL9602](ERROR): received:
2025-09-03T23:22:42.752Z,1756941762.752 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2025-09-03T23:22:42.752Z,1756941762.752 [AHRS_M2] Data Fault, FailCount= 1
2025-09-03T23:22:42.752Z,1756941762.752 [AHRS_M2](ERROR): Data Fault
2025-09-03T23:22:42.805Z,1756941762.805 [CBIT](ERROR): Data Fault in component: AHRS_M2
2025-09-03T23:22:43.069Z,1756941763.069 [AHRS_M2](INFO): Powering down
2025-09-03T23:22:43.909Z,1756941763.909 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-09-03T23:22:43.909Z,1756941763.909 [AHRS_M2] No Fault, FailCount= 1
2025-09-03T23:22:44.293Z,1756941764.293 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-03T23:22:44.345Z,1756941764.345 [Depth_Keller](ERROR): Pressure or depth reading out of range: -0.800452 decibar, -0.794549 m
2025-09-03T23:22:45.541Z,1756941765.541 [Depth_Keller](ERROR): Pressure or depth reading out of range: 947.322021 decibar, 0.000000 m
2025-09-03T23:22:48.406Z,1756941768.406 [Depth_Keller](ERROR): Pressure or depth reading out of range: -0.800452 decibar, -0.794549 m
2025-09-03T23:22:49.221Z,1756941769.221 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-09-03T23:22:54.818Z,1756941774.818 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1895.444336 decibar, 0.000000 m
2025-09-03T23:22:56.837Z,1756941776.837 [Depth_Keller](ERROR): Pressure or depth reading out of range: -0.800452 decibar, -0.794549 m
2025-09-03T23:23:01.701Z,1756941781.701 [Depth_Keller](ERROR): Pressure or depth reading out of range: -0.800452 decibar, -0.794549 m
2025-09-03T23:23:02.501Z,1756941782.501 [Depth_Keller](ERROR): Pressure or depth reading out of range: -0.800452 decibar, -0.794549 m