2026-02-09T16:14:54.030Z,1770653694.030 [Supervisor](DEBUG): Initializing supervisor.
2026-02-09T16:14:54.034Z,1770653694.034 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2026-02-09T16:14:54.034Z,1770653694.034 [SyncHandler](INFO): Protected caller Thread ID is 836
2026-02-09T16:14:54.035Z,1770653694.035 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2026-02-09T16:14:54.036Z,1770653694.036 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2026-02-09T16:14:54.036Z,1770653694.036 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 837
2026-02-09T16:14:54.040Z,1770653694.040 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2026-02-09T16:14:54.059Z,1770653694.059 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2026-02-09T16:14:54.060Z,1770653694.060 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2026-02-09T16:14:54.061Z,1770653694.061 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 838
2026-02-09T16:14:54.065Z,1770653694.065 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2026-02-09T16:14:54.066Z,1770653694.066 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2026-02-09T16:14:54.066Z,1770653694.066 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 839
2026-02-09T16:14:54.068Z,1770653694.068 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2026-02-09T16:14:54.069Z,1770653694.069 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2026-02-09T16:14:54.070Z,1770653694.070 [logger ThreadHandler](INFO): Protected caller Thread ID is 840
2026-02-09T16:14:54.073Z,1770653694.073 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2026-02-09T16:14:54.074Z,1770653694.074 [Supervisor](INFO): Looking for Config files in directory: Config/
2026-02-09T16:14:54.078Z,1770653694.078 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2026-02-09T16:14:54.345Z,1770653694.345 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2026-02-09T16:14:54.347Z,1770653694.347 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2026-02-09T16:14:54.447Z,1770653694.447 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance
2026-02-09T16:14:54.447Z,1770653694.447 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2026-02-09T16:14:54.967Z,1770653694.967 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2026-02-09T16:14:54.969Z,1770653694.969 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2026-02-09T16:14:55.312Z,1770653695.312 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2026-02-09T16:14:55.314Z,1770653695.314 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2026-02-09T16:14:55.421Z,1770653695.421 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2026-02-09T16:14:55.423Z,1770653695.423 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2026-02-09T16:14:55.834Z,1770653695.834 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2026-02-09T16:14:55.836Z,1770653695.836 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2026-02-09T16:14:56.077Z,1770653696.077 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2026-02-09T16:14:56.079Z,1770653696.079 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2026-02-09T16:14:56.731Z,1770653696.731 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2026-02-09T16:14:56.733Z,1770653696.733 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2026-02-09T16:14:57.189Z,1770653697.189 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2026-02-09T16:14:57.296Z,1770653697.296 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2026-02-09T16:14:57.484Z,1770653697.484 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2026-02-09T16:14:58.327Z,1770653698.327 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2026-02-09T16:14:58.327Z,1770653698.327 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2026-02-09T16:14:58.721Z,1770653698.721 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2026-02-09T16:14:58.721Z,1770653698.721 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2026-02-09T16:14:58.913Z,1770653698.913 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2026-02-09T16:14:58.915Z,1770653698.915 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2026-02-09T16:14:59.038Z,1770653699.038 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2026-02-09T16:14:59.039Z,1770653699.039 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2026-02-09T16:14:59.272Z,1770653699.272 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2026-02-09T16:14:59.274Z,1770653699.274 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2026-02-09T16:14:59.537Z,1770653699.537 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2026-02-09T16:14:59.539Z,1770653699.539 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2026-02-09T16:14:59.543Z,1770653699.543 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2026-02-09T16:14:59.638Z,1770653699.638 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2026-02-09T16:14:59.750Z,1770653699.750 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2026-02-09T16:14:59.842Z,1770653699.842 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2026-02-09T16:14:59.949Z,1770653699.949 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2026-02-09T16:15:00.051Z,1770653700.051 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2026-02-09T16:15:00.174Z,1770653700.174 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2026-02-09T16:15:00.334Z,1770653700.334 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2026-02-09T16:15:00.481Z,1770653700.481 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2026-02-09T16:15:00.578Z,1770653700.578 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2026-02-09T16:15:00.854Z,1770653700.854 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2026-02-09T16:15:00.855Z,1770653700.855 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2026-02-09T16:15:00.856Z,1770653700.856 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2026-02-09T16:15:00.869Z,1770653700.869 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2026-02-09T16:15:00.942Z,1770653700.942 [VerticalControl](DEBUG): Construct VerticalControl.
2026-02-09T16:15:01.003Z,1770653701.003 [VerticalControl] Loaded
2026-02-09T16:15:01.004Z,1770653701.004 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2026-02-09T16:15:01.007Z,1770653701.007 [HorizontalControl](DEBUG): Construct HorizontalControl.
2026-02-09T16:15:01.055Z,1770653701.055 [HorizontalControl] Loaded
2026-02-09T16:15:01.055Z,1770653701.055 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2026-02-09T16:15:01.058Z,1770653701.058 [SpeedControl](DEBUG): Construct SpeedControl.
2026-02-09T16:15:01.061Z,1770653701.061 [SpeedControl] Loaded
2026-02-09T16:15:01.061Z,1770653701.061 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2026-02-09T16:15:01.064Z,1770653701.064 [LoopControl](DEBUG): Construct LoopControl.
2026-02-09T16:15:01.065Z,1770653701.065 [LoopControl] Loaded
2026-02-09T16:15:01.065Z,1770653701.065 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2026-02-09T16:15:01.065Z,1770653701.065 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2026-02-09T16:15:01.067Z,1770653701.067 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2026-02-09T16:15:01.083Z,1770653701.083 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2026-02-09T16:15:01.083Z,1770653701.083 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2026-02-09T16:15:01.301Z,1770653701.301 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2026-02-09T16:15:01.301Z,1770653701.301 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2026-02-09T16:15:01.492Z,1770653701.492 [BuoyancyServo] Loaded
2026-02-09T16:15:01.492Z,1770653701.492 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2026-02-09T16:15:01.514Z,1770653701.514 [ElevatorServo] Loaded
2026-02-09T16:15:01.514Z,1770653701.514 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2026-02-09T16:15:01.536Z,1770653701.536 [MassServo] Loaded
2026-02-09T16:15:01.536Z,1770653701.536 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2026-02-09T16:15:01.556Z,1770653701.556 [RudderServo] Loaded
2026-02-09T16:15:01.556Z,1770653701.556 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2026-02-09T16:15:01.573Z,1770653701.573 [ThrusterHE] Loaded
2026-02-09T16:15:01.574Z,1770653701.574 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2026-02-09T16:15:01.574Z,1770653701.574 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2026-02-09T16:15:01.575Z,1770653701.575 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2026-02-09T16:15:01.715Z,1770653701.715 [DeadReckonUsingMultipleVelocitySources] Loaded
2026-02-09T16:15:01.716Z,1770653701.716 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2026-02-09T16:15:01.729Z,1770653701.729 [NavChart] Loaded
2026-02-09T16:15:01.730Z,1770653701.730 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2026-02-09T16:15:01.735Z,1770653701.735 [UniversalFixResidualReporter] Loaded
2026-02-09T16:15:01.735Z,1770653701.735 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2026-02-09T16:15:01.736Z,1770653701.736 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2026-02-09T16:15:01.737Z,1770653701.737 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2026-02-09T16:15:01.948Z,1770653701.948 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2026-02-09T16:15:01.949Z,1770653701.949 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2026-02-09T16:15:03.525Z,1770653703.525 [AHRS_M2] Loaded
2026-02-09T16:15:03.526Z,1770653703.526 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2026-02-09T16:15:04.359Z,1770653704.359 [BPC1] Loaded
2026-02-09T16:15:04.360Z,1770653704.360 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2026-02-09T16:15:04.460Z,1770653704.460 [DAT] Loaded
2026-02-09T16:15:04.460Z,1770653704.460 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2026-02-09T16:15:04.462Z,1770653704.462 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4094D4E0
2026-02-09T16:15:04.462Z,1770653704.462 [DAT ThreadHandler](INFO): Protected caller Thread ID is 920
2026-02-09T16:15:04.604Z,1770653704.604 [DataOverHttps] Loaded
2026-02-09T16:15:04.604Z,1770653704.604 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2026-02-09T16:15:04.605Z,1770653704.605 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4097D4E0
2026-02-09T16:15:04.605Z,1770653704.605 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 921
2026-02-09T16:15:04.629Z,1770653704.629 [Depth_Keller] Loaded
2026-02-09T16:15:04.630Z,1770653704.630 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2026-02-09T16:15:04.634Z,1770653704.634 [DropWeight] Loaded
2026-02-09T16:15:04.635Z,1770653704.635 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2026-02-09T16:15:04.699Z,1770653704.699 [NAL9602] Loaded
2026-02-09T16:15:04.699Z,1770653704.699 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2026-02-09T16:15:04.729Z,1770653704.729 [Onboard] Loaded
2026-02-09T16:15:04.730Z,1770653704.730 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2026-02-09T16:15:04.730Z,1770653704.730 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409AD4E0
2026-02-09T16:15:04.731Z,1770653704.731 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 922
2026-02-09T16:15:04.745Z,1770653704.745 [Power24vConverter] Loaded
2026-02-09T16:15:04.745Z,1770653704.745 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2026-02-09T16:15:04.761Z,1770653704.761 [Radio_Surface] Loaded
2026-02-09T16:15:04.761Z,1770653704.761 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2026-02-09T16:15:04.762Z,1770653704.762 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409DD4E0
2026-02-09T16:15:04.763Z,1770653704.763 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 923
2026-02-09T16:15:04.778Z,1770653704.778 [Sonardyne_Nano] Loaded
2026-02-09T16:15:04.779Z,1770653704.779 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2026-02-09T16:15:04.779Z,1770653704.779 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2026-02-09T16:15:04.780Z,1770653704.780 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2026-02-09T16:15:04.852Z,1770653704.852 [DepthRateCalculator] Loaded
2026-02-09T16:15:04.852Z,1770653704.852 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2026-02-09T16:15:04.858Z,1770653704.858 [PitchRateCalculator] Loaded
2026-02-09T16:15:04.858Z,1770653704.858 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2026-02-09T16:15:04.867Z,1770653704.867 [SpeedCalculator] Loaded
2026-02-09T16:15:04.867Z,1770653704.867 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2026-02-09T16:15:04.872Z,1770653704.872 [YawRateCalculator] Loaded
2026-02-09T16:15:04.872Z,1770653704.872 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2026-02-09T16:15:04.891Z,1770653704.891 [ElevatorOffsetCalculator] Loaded
2026-02-09T16:15:04.891Z,1770653704.891 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2026-02-09T16:15:04.892Z,1770653704.892 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2026-02-09T16:15:04.893Z,1770653704.893 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2026-02-09T16:15:05.077Z,1770653705.077 [CANONSampler] Loaded
2026-02-09T16:15:05.077Z,1770653705.077 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2026-02-09T16:15:05.140Z,1770653705.140 [CTD_Seabird] Loaded
2026-02-09T16:15:05.140Z,1770653705.140 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2026-02-09T16:15:05.141Z,1770653705.141 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A9B4E0
2026-02-09T16:15:05.141Z,1770653705.141 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 924
2026-02-09T16:15:05.164Z,1770653705.164 [PAR_Licor] Loaded
2026-02-09T16:15:05.164Z,1770653705.164 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2026-02-09T16:15:05.209Z,1770653705.209 [WetLabsBB2FL] Loaded
2026-02-09T16:15:05.209Z,1770653705.209 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2026-02-09T16:15:05.210Z,1770653705.210 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40ACB4E0
2026-02-09T16:15:05.211Z,1770653705.211 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 925
2026-02-09T16:15:05.211Z,1770653705.211 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2026-02-09T16:15:05.212Z,1770653705.212 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2026-02-09T16:15:05.261Z,1770653705.261 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2026-02-09T16:15:05.262Z,1770653705.262 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2026-02-09T16:15:05.676Z,1770653705.676 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2026-02-09T16:15:05.678Z,1770653705.678 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2026-02-09T16:15:05.876Z,1770653705.876 [SBIT](DEBUG): Construct Startup Built In Test.
2026-02-09T16:15:05.885Z,1770653705.885 [SBIT] Loaded
2026-02-09T16:15:05.885Z,1770653705.885 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2026-02-09T16:15:05.888Z,1770653705.888 [IBIT](DEBUG): Construct Initiated Built In Test.
2026-02-09T16:15:05.901Z,1770653705.901 [IBIT] Loaded
2026-02-09T16:15:05.902Z,1770653705.902 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2026-02-09T16:15:05.907Z,1770653705.907 [CBIT](DEBUG): Construct Continuous Built In Test.
2026-02-09T16:15:05.990Z,1770653705.990 [CBIT] Loaded
2026-02-09T16:15:05.990Z,1770653705.990 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2026-02-09T16:15:06.023Z,1770653706.023 [GFScanner] Loaded
2026-02-09T16:15:06.023Z,1770653706.023 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2026-02-09T16:15:06.024Z,1770653706.024 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2026-02-09T16:15:06.024Z,1770653706.024 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2026-02-09T16:15:06.194Z,1770653706.194 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2026-02-09T16:15:06.200Z,1770653706.200 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2026-02-09T16:15:06.203Z,1770653706.203 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2026-02-09T16:15:06.215Z,1770653706.215 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2026-02-09T16:15:06.216Z,1770653706.216 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C3D4E0
2026-02-09T16:15:06.216Z,1770653706.216 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 926
2026-02-09T16:15:06.220Z,1770653706.220 [Supervisor](INFO): Main Thread ID is 829
2026-02-09T16:15:06.221Z,1770653706.221 [Supervisor](DEBUG): Running supervisor.
2026-02-09T16:15:06.221Z,1770653706.221 [CommandExec ThreadHandler](INFO): Handler Thread ID is 927
2026-02-09T16:15:06.222Z,1770653706.222 [CommandExec](INFO): Initializing the command executive.
2026-02-09T16:15:06.223Z,1770653706.223 [CommandLine ThreadHandler](INFO): Handler Thread ID is 928
2026-02-09T16:15:06.226Z,1770653706.226 [controlThread ThreadHandler](INFO): Handler Thread ID is 929
2026-02-09T16:15:06.226Z,1770653706.226 [controlThread](DEBUG): Initializing ControlThread
2026-02-09T16:15:06.227Z,1770653706.227 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2026-02-09T16:15:06.229Z,1770653706.229 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2026-02-09T16:15:06.229Z,1770653706.229 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2026-02-09T16:15:06.230Z,1770653706.230 [LoopControl](DEBUG): Initialize LoopControlComponent.
2026-02-09T16:15:06.232Z,1770653706.232 [NavChart](DEBUG): Initialize NavChart Navigation.
2026-02-09T16:15:06.232Z,1770653706.232 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2026-02-09T16:15:06.235Z,1770653706.235 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2026-02-09T16:15:06.236Z,1770653706.236 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2026-02-09T16:15:06.236Z,1770653706.236 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2026-02-09T16:15:06.236Z,1770653706.236 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2026-02-09T16:15:06.237Z,1770653706.237 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2026-02-09T16:15:06.240Z,1770653706.240 [SBIT](INFO): Initialize SBIT Component.
2026-02-09T16:15:06.241Z,1770653706.241 [SBIT](IMPORTANT): git: 2026-02-05
2026-02-09T16:15:06.241Z,1770653706.241 [SBIT](INFO): git hash: 2c49374f7e1dd0ac66edaf2b178ef330b988eff4
2026-02-09T16:15:06.241Z,1770653706.241 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2026-02-09T16:15:06.242Z,1770653706.242 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2026-02-09T16:15:06.243Z,1770653706.243 [SBIT](INFO): Beginning SBIT in 24.000000 seconds.
2026-02-09T16:15:06.244Z,1770653706.244 [IBIT](INFO): Initialize IBIT Component.
2026-02-09T16:15:06.245Z,1770653706.245 [CBIT](DEBUG): Initialize CBIT Component.
2026-02-09T16:15:06.246Z,1770653706.246 [logger ThreadHandler](INFO): Handler Thread ID is 930
2026-02-09T16:15:06.256Z,1770653706.256 [CBIT](DEBUG): Initialized mux pins.
2026-02-09T16:15:06.256Z,1770653706.256 [CBIT](DEBUG): Initializing the watchdog timer.
2026-02-09T16:15:06.264Z,1770653706.264 [DAT ThreadHandler](INFO): Handler Thread ID is 931
2026-02-09T16:15:06.265Z,1770653706.265 [DAT](INFO): Powering up
2026-02-09T16:15:06.265Z,1770653706.265 [DAT](DEBUG): Initializing DAT.
2026-02-09T16:15:06.269Z,1770653706.269 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 933
2026-02-09T16:15:06.271Z,1770653706.271 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2026-02-09T16:15:06.280Z,1770653706.280 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2026-02-09T16:15:06.280Z,1770653706.280 [CBIT](DEBUG): Initializing heartbeat.
2026-02-09T16:15:06.281Z,1770653706.281 [Onboard ThreadHandler](INFO): Handler Thread ID is 934
2026-02-09T16:15:06.301Z,1770653706.301 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 935
2026-02-09T16:15:06.316Z,1770653706.316 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 936
2026-02-09T16:15:06.317Z,1770653706.317 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2026-02-09T16:15:06.321Z,1770653706.321 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 938
2026-02-09T16:15:06.325Z,1770653706.325 [WetLabsBB2FL](INFO): Powering up
2026-02-09T16:15:06.327Z,1770653706.327 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 940
2026-02-09T16:15:06.338Z,1770653706.338 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2026-02-09T16:15:06.338Z,1770653706.338 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2026-02-09T16:15:06.338Z,1770653706.338 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2026-02-09T16:15:06.338Z,1770653706.338 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2026-02-09T16:15:06.338Z,1770653706.338 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2026-02-09T16:15:06.338Z,1770653706.338 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2026-02-09T16:15:06.339Z,1770653706.339 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2026-02-09T16:15:06.339Z,1770653706.339 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2026-02-09T16:15:06.352Z,1770653706.352 [CBIT](DEBUG): Deactivating emergency mode.
2026-02-09T16:15:06.392Z,1770653706.392 [CBIT](DEBUG): Backplane powered.
2026-02-09T16:15:06.392Z,1770653706.392 [GFScanner](DEBUG): Initializing GFScanner
2026-02-09T16:15:06.392Z,1770653706.392 [GFScanner](DEBUG): Deactivating GF circuits.
2026-02-09T16:15:06.397Z,1770653706.397 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2026-02-09T16:15:06.398Z,1770653706.398 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2026-02-09T16:15:06.398Z,1770653706.398 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2026-02-09T16:15:06.408Z,1770653706.408 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2026-02-09T16:15:06.459Z,1770653706.459 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2026-02-09T16:15:06.480Z,1770653706.480 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2026-02-09T16:15:06.505Z,1770653706.505 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Startup {
behavior Guidance:GoToSurface {
run in progression
}
aggregate StartupSatComms {
run in sequence
readDatum {
timeout duration=P1M
Universal:latitude_fix
}
readDatum {
timeout duration=P1M
Universal:platform_communications
}
}
}
2026-02-09T16:15:06.506Z,1770653706.506 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2026-02-09T16:15:06.506Z,1770653706.506 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2026-02-09T16:15:06.522Z,1770653706.522 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2026-02-09T16:15:06.704Z,1770653706.704 [Radio_Surface](INFO): Powering up
2026-02-09T16:15:06.874Z,1770653706.874 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2026-02-09T16:15:06.904Z,1770653706.904 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2026-02-09T16:15:06.905Z,1770653706.905 [Default:A.Wait](DEBUG): Construct Wait.
2026-02-09T16:15:06.907Z,1770653706.907 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2026-02-09T16:15:06.949Z,1770653706.949 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2026-02-09T16:15:06.952Z,1770653706.952 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2026-02-09T16:15:06.973Z,1770653706.973 [Default:E.Execute](DEBUG): Construct Execute.
2026-02-09T16:15:06.976Z,1770653706.976 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Default {
arguments {
ElapsedSinceDefaultStarted = 0 minute
}
behavior Guidance:Wait {
"""
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
"""
run in sequence
set duration = 13 second
}
behavior Guidance:GoToSurface {
run in progression
}
aggregate CheckIn {
run in sequence repeat=288
readDatum id="Read_GPS" {
timeout duration=P5M
Universal:time_fix
}
readDatum id="Read_Iridium" {
timeout duration=P4H {
behavior Guidance:Execute {
run in sequence
set command = "Burn on"
}
syslog critical "Dropped weight due to communications timeout."
}
Universal:platform_communications
}
behavior Guidance:Wait {
run in sequence
set duration = 5 minute
}
assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )
syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
}
syslog important "Restarting logs and Default mission."
behavior Guidance:Execute {
run in sequence
set command = "restart logs"
}
}
2026-02-09T16:15:06.992Z,1770653706.992 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,Reporter,LogSplitter,
2026-02-09T16:15:07.004Z,1770653707.004 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2026-02-09T16:15:07.160Z,1770653707.160 [Depth_Keller](INFO): Initializing.
2026-02-09T16:15:07.162Z,1770653707.162 [Power24vConverter](INFO): Powering up.
2026-02-09T16:15:07.162Z,1770653707.162 [Sonardyne_Nano](INFO): Initializing.
2026-02-09T16:15:07.180Z,1770653707.180 [CANONSampler](INFO): Powering down
2026-02-09T16:15:07.204Z,1770653707.204 [DepthRateCalculator](ERROR): Depth measurement is not active
2026-02-09T16:15:07.230Z,1770653707.230 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2026-02-09T16:15:07.236Z,1770653707.236 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2026-02-09T16:15:07.237Z,1770653707.237 [ElevatorServo](DEBUG): Initializing EZServoServo.
2026-02-09T16:15:07.244Z,1770653707.244 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2026-02-09T16:15:07.245Z,1770653707.245 [MassServo](DEBUG): Initializing EZServoServo.
2026-02-09T16:15:07.252Z,1770653707.252 [MassServo](DEBUG): Initializing MassServo.
2026-02-09T16:15:07.253Z,1770653707.253 [RudderServo](DEBUG): Initializing EZServoServo.
2026-02-09T16:15:07.261Z,1770653707.261 [RudderServo](DEBUG): Initializing RudderServo.
2026-02-09T16:15:07.261Z,1770653707.261 [ThrusterHE](DEBUG): Initializing EZServoServo.
2026-02-09T16:15:07.268Z,1770653707.268 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2026-02-09T16:15:08.936Z,1770653708.936 [WetLabsBB2FL](INFO): Powering down
2026-02-09T16:15:09.426Z,1770653709.426 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2026-02-09T16:15:11.080Z,1770653711.080 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2109
2026-02-09T16:15:11.535Z,1770653711.535 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2026-02-09T16:15:13.101Z,1770653713.101 [ThrusterHE](ERROR): Zero Speed Commanded.
2026-02-09T16:15:13.348Z,1770653713.348 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2026-02-09T16:15:18.109Z,1770653718.109 [DAT](INFO): DAT read:
2026-02-09T16:15:18.110Z,1770653718.110 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2026-02-09T16:15:19.621Z,1770653719.621 [DAT](INFO): DAT read: MF Frequency Band
2026-02-09T16:15:19.622Z,1770653719.622 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2026-02-09T16:15:19.625Z,1770653719.625 [DAT](INFO): DAT read: Feb 9 2026 16:13:30
2026-02-09T16:15:20.881Z,1770653720.881 [DAT](INFO): DAT read: Features enabled [Bearing]
2026-02-09T16:15:20.882Z,1770653720.882 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC
2026-02-09T16:15:20.882Z,1770653720.882 [DAT](INFO): commRate: 1200
2026-02-09T16:15:22.948Z,1770653722.948 [DAT](INFO): entering command mode
2026-02-09T16:15:23.149Z,1770653723.149 [DAT](INFO): DAT read:
2026-02-09T16:15:23.149Z,1770653723.149 [DAT](INFO): DAT read: user:1>
2026-02-09T16:15:23.149Z,1770653723.149 [DAT](INFO): setting verbose to 3
2026-02-09T16:15:23.401Z,1770653723.401 [DAT](INFO): DAT read: user:1>
2026-02-09T16:15:23.402Z,1770653723.402 [DAT](INFO): DAT read: Verbose | 3
2026-02-09T16:15:23.402Z,1770653723.402 [DAT](INFO): set verbose to 3
2026-02-09T16:15:23.402Z,1770653723.402 [DAT](INFO): setting DatVerbose to 27440
2026-02-09T16:15:23.653Z,1770653723.653 [DAT](INFO): DAT read: user:2>
2026-02-09T16:15:23.654Z,1770653723.654 [DAT](INFO): DAT read: DatVerbose | 27440
2026-02-09T16:15:23.654Z,1770653723.654 [DAT](INFO): set DatVerbose to 27440
2026-02-09T16:15:23.654Z,1770653723.654 [DAT](INFO): setting transmit power to 8
2026-02-09T16:15:23.904Z,1770653723.904 [DAT](INFO): DAT read: user:3>
2026-02-09T16:15:23.905Z,1770653723.905 [DAT](INFO): DAT read: TxPower | 8 (Max)
2026-02-09T16:15:23.906Z,1770653723.906 [DAT](INFO): set transmit power to 8
2026-02-09T16:15:23.906Z,1770653723.906 [DAT](INFO): setting local address to 8
2026-02-09T16:15:24.157Z,1770653724.157 [DAT](INFO): DAT read: user:4>
2026-02-09T16:15:24.157Z,1770653724.157 [DAT](INFO): DAT read: LocalAddr | 8
2026-02-09T16:15:24.158Z,1770653724.158 [DAT](INFO): set local address to 8
2026-02-09T16:15:24.159Z,1770653724.159 [DAT](INFO): Setting time to: 16:15:24 And date to:2/9/2026
2026-02-09T16:15:24.409Z,1770653724.409 [DAT](INFO): DAT read: user:5>
2026-02-09T16:15:24.410Z,1770653724.410 [DAT](INFO): DAT read: Mon Feb 9, 2026 16:15:24
2026-02-09T16:15:24.410Z,1770653724.410 [DAT](INFO): Local DAT time set to Mon Feb 9, 2026 16:15:24
2026-02-09T16:15:30.978Z,1770653730.978 [SBIT](IMPORTANT): Beginning Startup BIT
2026-02-09T16:15:30.987Z,1770653730.987 [GFScanner](IMPORTANT): Beginning ground fault scan
2026-02-09T16:15:35.002Z,1770653735.002 [NAL9602](INFO): Powering up NAL9602
2026-02-09T16:15:35.288Z,1770653735.288 [CommandExec](IMPORTANT): got command failComponent
2026-02-09T16:15:35.289Z,1770653735.289 [CommandExec](IMPORTANT): Failed components:
2026-02-09T16:15:35.289Z,1770653735.289 [CommandExec](IMPORTANT): No failed Components.
2026-02-09T16:15:35.507Z,1770653735.507 [GFScanner](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.025787
CHAN A1 (24V): 0.050640
CHAN A2 (12V): 0.013437
CHAN A3 (5V): -0.000401
CHAN B0 (3.3V): -0.000884
CHAN B1 (3.15aV): -0.001834
CHAN B2 (3.15bV): -0.000092
CHAN B3 (GND): -0.008547
OPEN: 0.006603
Full Scale: +/- 1 mA
2026-02-09T16:15:45.901Z,1770653745.901 [NAL9602](INFO): NAL9602 initialized
2026-02-09T16:15:49.399Z,1770653749.399 [CommandExec](IMPORTANT): got command get Depth_Keller.depth
2026-02-09T16:15:49.399Z,1770653749.399 [CommandExec](IMPORTANT): Depth_Keller.depth -0.042461 m
2026-02-09T16:16:24.801Z,1770653784.801 [SBIT](IMPORTANT): SBIT PASSED
2026-02-09T16:16:24.801Z,1770653784.801 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2026-02-09T16:16:24.802Z,1770653784.802 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=11 count;
2026-02-09T16:16:24.802Z,1770653784.802 [SBIT](IMPORTANT): DATMMP.surfaceThreshold=2 meter;
2026-02-09T16:16:24.802Z,1770653784.802 [SBIT](IMPORTANT): Express linearApproximation DATMMP.acoustic_contact_range 15.000000 meter;
2026-02-09T16:16:24.802Z,1770653784.802 [SBIT](IMPORTANT): Express linearApproximation PAR_Licor.downwelling_photosynthetic_photon_flux_in_sea_water 100.000000 micromole_per_second_per_square_meter;
2026-02-09T16:16:24.802Z,1770653784.802 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=282.753401 cubic_centimeter;
2026-02-09T16:16:24.802Z,1770653784.802 [SBIT](IMPORTANT): VerticalControl.massDefault=1.176086 millimeter;
2026-02-09T16:16:25.150Z,1770653785.150 [MissionManager](IMPORTANT): Started mission Startup
2026-02-09T16:16:25.150Z,1770653785.150 [Startup] Running Loop=1
2026-02-09T16:16:25.150Z,1770653785.150 [Startup](DEBUG): Aggregate::initialize Startup
2026-02-09T16:16:25.150Z,1770653785.150 [Startup:A.GoToSurface] Running Loop=1
2026-02-09T16:16:25.151Z,1770653785.151 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2026-02-09T16:16:25.151Z,1770653785.151 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2026-02-09T16:16:25.152Z,1770653785.152 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2026-02-09T16:16:25.152Z,1770653785.152 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2026-02-09T16:16:25.153Z,1770653785.153 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2026-02-09T16:16:25.153Z,1770653785.153 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2026-02-09T16:16:25.153Z,1770653785.153 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2026-02-09T16:16:25.154Z,1770653785.154 [Startup:StartupSatComms] Running Loop=1
2026-02-09T16:16:25.155Z,1770653785.155 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2026-02-09T16:16:25.155Z,1770653785.155 [Startup:StartupSatComms:A] Running Loop=1
2026-02-09T16:16:25.563Z,1770653785.563 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2026-02-09T16:17:25.330Z,1770653845.330 [Startup:StartupSatComms:A](INFO): Timed out from 2026-02-09T16:16:25.2Z
2026-02-09T16:17:25.330Z,1770653845.330 [Startup:StartupSatComms:A] Stopped
2026-02-09T16:17:25.330Z,1770653845.330 [Startup:StartupSatComms:B] Running Loop=1
2026-02-09T16:17:25.734Z,1770653845.734 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2026-02-09T16:17:36.226Z,1770653856.226 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004926
2026-02-09T16:17:43.262Z,1770653863.262 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20260209T161454/Courier0000.lzma
2026-02-09T16:17:44.265Z,1770653864.265 [DataOverHttps](INFO): Moved sent file to Logs/20260209T161454/Courier0000.lzma.bak
2026-02-09T16:17:44.265Z,1770653864.265 [DataOverHttps](INFO): SBD MOMSN=26641360
2026-02-09T16:18:00.304Z,1770653880.304 [DataOverHttps](INFO): Sending 1044 bytes from file Logs/20260209T161454/Express0001.lzma
2026-02-09T16:18:01.304Z,1770653881.304 [DataOverHttps](INFO): Moved sent file to Logs/20260209T161454/Express0001.lzma.bak
2026-02-09T16:18:01.305Z,1770653881.305 [DataOverHttps](INFO): SBD MOMSN=26641363
2026-02-09T16:18:02.368Z,1770653882.368 [Startup:StartupSatComms:B] Stopped
2026-02-09T16:18:02.369Z,1770653882.369 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2026-02-09T16:18:02.369Z,1770653882.369 [Startup:StartupSatComms] Stopped
2026-02-09T16:18:02.369Z,1770653882.369 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2026-02-09T16:18:02.370Z,1770653882.370 [Startup](INFO): Completed Startup
2026-02-09T16:18:02.370Z,1770653882.370 [MissionManager](INFO): Startup is completed.
2026-02-09T16:18:02.370Z,1770653882.370 [MissionManager](INFO): Uninitializing Mission Startup
2026-02-09T16:18:02.370Z,1770653882.370 [Startup] Stopped
2026-02-09T16:18:02.370Z,1770653882.370 [Startup](DEBUG): Aggregate::uninitialize Startup
2026-02-09T16:18:02.370Z,1770653882.370 [Startup:A.GoToSurface] Stopped
2026-02-09T16:18:02.370Z,1770653882.370 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2026-02-09T16:18:02.704Z,1770653882.704 [MissionManager](IMPORTANT): Started mission Default
2026-02-09T16:18:02.704Z,1770653882.704 [Default] Running Loop=1
2026-02-09T16:18:02.705Z,1770653882.705 [Default](DEBUG): Aggregate::initialize Default
2026-02-09T16:18:02.705Z,1770653882.705 [Default:B.GoToSurface] Running Loop=1
2026-02-09T16:18:02.705Z,1770653882.705 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2026-02-09T16:18:02.705Z,1770653882.705 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2026-02-09T16:18:02.705Z,1770653882.705 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2026-02-09T16:18:02.706Z,1770653882.706 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2026-02-09T16:18:02.706Z,1770653882.706 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2026-02-09T16:18:02.706Z,1770653882.706 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2026-02-09T16:18:02.707Z,1770653882.707 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2026-02-09T16:18:02.707Z,1770653882.707 [Default:A.Wait] Running Loop=1
2026-02-09T16:18:02.707Z,1770653882.707 [Default:A.Wait](DEBUG): Initialize Wait Component.
2026-02-09T16:18:06.336Z,1770653886.336 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2026-02-09T16:18:06.336Z,1770653886.336 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2026-02-09T16:18:06.360Z,1770653886.360 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2026-02-09T16:18:06.764Z,1770653886.764 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2026-02-09T16:18:06.764Z,1770653886.764 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2026-02-09T16:18:16.064Z,1770653896.064 [Default:A.Wait](INFO): Done Waiting.
2026-02-09T16:18:16.064Z,1770653896.064 [Default:A.Wait] Stopped
2026-02-09T16:18:16.064Z,1770653896.064 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2026-02-09T16:18:16.437Z,1770653896.437 [Default:CheckIn] Running Loop=1
2026-02-09T16:18:16.437Z,1770653896.437 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-02-09T16:18:16.437Z,1770653896.437 [Default:CheckIn:Read_GPS] Running Loop=1
2026-02-09T16:18:16.825Z,1770653896.825 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2026-02-09T16:18:28.811Z,1770653908.811 [CommandExec](IMPORTANT): got command failComponent
2026-02-09T16:18:28.811Z,1770653908.811 [CommandExec](IMPORTANT): Failed components:
2026-02-09T16:18:28.812Z,1770653908.812 [CommandExec](IMPORTANT): No failed Components.
2026-02-09T16:18:31.203Z,1770653911.203 [CommandExec](IMPORTANT): got command gfscan
2026-02-09T16:18:31.401Z,1770653911.401 [GFScanner](IMPORTANT): Beginning ground fault scan
2026-02-09T16:18:35.872Z,1770653915.872 [GFScanner](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.034762
CHAN A1 (24V): 0.049987
CHAN A2 (12V): 0.015092
CHAN A3 (5V): 0.001969
CHAN B0 (3.3V): 0.001652
CHAN B1 (3.15aV): 0.001060
CHAN B2 (3.15bV): 0.001255
CHAN B3 (GND): -0.002781
OPEN: 0.006797
Full Scale: +/- 1 mA
2026-02-09T16:19:57.010Z,1770653997.010 [NAL9602](INFO): SBD MO Status=2, MOMSN=521, MT Status=2, MTMSN=0
2026-02-09T16:19:57.010Z,1770653997.010 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-02-09T16:20:48.720Z,1770654048.720 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2026-02-09T16:21:07.334Z,1770654067.334 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2026-02-09T16:21:07.334Z,1770654067.334 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2026-02-09T16:21:07.357Z,1770654067.357 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2026-02-09T16:21:07.760Z,1770654067.760 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2026-02-09T16:21:07.760Z,1770654067.760 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2026-02-09T16:21:17.810Z,1770654077.810 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7).
2026-02-09T16:21:17.816Z,1770654077.816 [BPC1](INFO): Calculating totals. Valid battery stick count: 47. Valid reserve battery stick count: 5.
2026-02-09T16:21:17.819Z,1770654077.819 [BPC1](INFO): Received data from all battery sticks.
2026-02-09T16:23:16.604Z,1770654196.604 [Default:CheckIn:Read_GPS](INFO): Timed out from 2026-02-09T16:18:16.4Z
2026-02-09T16:23:16.604Z,1770654196.604 [Default:CheckIn:Read_GPS] Stopped
2026-02-09T16:23:16.604Z,1770654196.604 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-02-09T16:23:16.997Z,1770654196.997 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2026-02-09T16:23:23.518Z,1770654203.518 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20260209T161454/Courier0004.lzma
2026-02-09T16:23:24.520Z,1770654204.520 [DataOverHttps](INFO): Moved sent file to Logs/20260209T161454/Courier0004.lzma.bak
2026-02-09T16:23:24.520Z,1770654204.520 [DataOverHttps](INFO): SBD MOMSN=26641387
2026-02-09T16:23:40.079Z,1770654220.079 [DataOverHttps](INFO): Sending 428 bytes from file Logs/20260209T161454/Express0005.lzma
2026-02-09T16:23:41.080Z,1770654221.080 [DataOverHttps](INFO): Moved sent file to Logs/20260209T161454/Express0005.lzma.bak
2026-02-09T16:23:41.081Z,1770654221.081 [DataOverHttps](INFO): SBD MOMSN=26641389
2026-02-09T16:23:42.263Z,1770654222.263 [CommandExec](IMPORTANT): got command failComponent
2026-02-09T16:23:42.263Z,1770654222.263 [CommandExec](IMPORTANT): Failed components:
2026-02-09T16:23:42.264Z,1770654222.264 [CommandExec](IMPORTANT): No failed Components.
2026-02-09T16:23:42.476Z,1770654222.476 [Default:CheckIn:Read_Iridium] Stopped
2026-02-09T16:23:42.476Z,1770654222.476 [Default:CheckIn:C.Wait] Running Loop=1
2026-02-09T16:23:42.477Z,1770654222.477 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-02-09T16:24:08.307Z,1770654248.307 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2026-02-09T16:24:08.307Z,1770654248.307 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2026-02-09T16:24:08.317Z,1770654248.317 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2026-02-09T16:24:08.720Z,1770654248.720 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2026-02-09T16:24:08.720Z,1770654248.720 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2026-02-09T16:25:46.872Z,1770654346.872 [NAL9602](FAULT): GPS failed to acquire within timeout.
2026-02-09T16:25:46.872Z,1770654346.872 [NAL9602] Data Fault, FailCount= 1
2026-02-09T16:25:46.872Z,1770654346.872 [NAL9602](ERROR): Data Fault
2026-02-09T16:25:46.944Z,1770654346.944 [CBIT](ERROR): Data Fault in component: NAL9602
2026-02-09T16:25:47.276Z,1770654347.276 [NAL9602](INFO): Powering down
2026-02-09T16:25:48.129Z,1770654348.129 [CBIT](INFO): Clearing failed state for component NAL9602
2026-02-09T16:25:48.129Z,1770654348.129 [NAL9602] No Fault, FailCount= 1
2026-02-09T16:26:17.576Z,1770654377.576 [NAL9602](INFO): Powering up NAL9602
2026-02-09T16:26:28.489Z,1770654388.489 [NAL9602](INFO): NAL9602 initialized
2026-02-09T16:26:40.564Z,1770654400.564 [CommandExec](IMPORTANT): got command burn on
2026-02-09T16:26:40.564Z,1770654400.564 [CommandExec](IMPORTANT): Activating dropweight wire
2026-02-09T16:26:47.232Z,1770654407.232 [CommandExec](IMPORTANT): got command burn off
2026-02-09T16:26:47.232Z,1770654407.232 [CommandExec](IMPORTANT): Deactivating dropweight wire
2026-02-09T16:27:09.325Z,1770654429.325 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2026-02-09T16:27:09.325Z,1770654429.325 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2026-02-09T16:27:09.336Z,1770654429.336 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2026-02-09T16:27:09.749Z,1770654429.749 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2026-02-09T16:27:09.749Z,1770654429.749 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2026-02-09T16:28:43.049Z,1770654523.049 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-02-09T16:28:43.049Z,1770654523.049 [Default:CheckIn:C.Wait] Stopped
2026-02-09T16:28:43.049Z,1770654523.049 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-02-09T16:28:43.050Z,1770654523.050 [Default:CheckIn:D] Running Loop=1
2026-02-09T16:28:43.453Z,1770654523.453 [Default:CheckIn:D] Stopped
2026-02-09T16:28:43.453Z,1770654523.453 [Default:CheckIn:E] Running Loop=1
2026-02-09T16:28:43.843Z,1770654523.843 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.679138 min
2026-02-09T16:28:43.844Z,1770654523.844 [Default:CheckIn:E] Stopped
2026-02-09T16:28:43.844Z,1770654523.844 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-02-09T16:28:43.844Z,1770654523.844 [Default:CheckIn] Stopped
2026-02-09T16:28:43.844Z,1770654523.844 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-02-09T16:28:43.844Z,1770654523.844 [Default:CheckIn](INFO): Running loop #2
2026-02-09T16:28:43.844Z,1770654523.844 [Default:CheckIn] Running Loop=2
2026-02-09T16:28:43.844Z,1770654523.844 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-02-09T16:28:43.844Z,1770654523.844 [Default:CheckIn:Read_GPS] Running Loop=1
2026-02-09T16:30:10.328Z,1770654610.328 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2026-02-09T16:30:10.328Z,1770654610.328 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2026-02-09T16:30:10.338Z,1770654610.338 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2026-02-09T16:30:10.757Z,1770654610.757 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2026-02-09T16:30:10.757Z,1770654610.757 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2026-02-09T16:33:11.352Z,1770654791.352 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2026-02-09T16:33:11.352Z,1770654791.352 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2026-02-09T16:33:11.362Z,1770654791.362 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2026-02-09T16:33:11.773Z,1770654791.773 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2026-02-09T16:33:11.773Z,1770654791.773 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2026-02-09T16:33:44.067Z,1770654824.067 [Default:CheckIn:Read_GPS](INFO): Timed out from 2026-02-09T16:28:43.8Z
2026-02-09T16:33:44.067Z,1770654824.067 [Default:CheckIn:Read_GPS] Stopped
2026-02-09T16:33:44.068Z,1770654824.068 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-02-09T16:33:50.890Z,1770654830.890 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20260209T161454/Courier0007.lzma
2026-02-09T16:33:51.892Z,1770654831.892 [DataOverHttps](INFO): Moved sent file to Logs/20260209T161454/Courier0007.lzma.bak
2026-02-09T16:33:51.893Z,1770654831.893 [DataOverHttps](INFO): SBD MOMSN=26641399
2026-02-09T16:34:08.687Z,1770654848.687 [DataOverHttps](INFO): Sending 302 bytes from file Logs/20260209T161454/Express0008.lzma
2026-02-09T16:34:09.689Z,1770654849.689 [DataOverHttps](INFO): Moved sent file to Logs/20260209T161454/Express0008.lzma.bak
2026-02-09T16:34:09.689Z,1770654849.689 [DataOverHttps](INFO): SBD MOMSN=26641401
2026-02-09T16:34:10.832Z,1770654850.832 [Default:CheckIn:Read_Iridium] Stopped
2026-02-09T16:34:10.832Z,1770654850.832 [Default:CheckIn:C.Wait] Running Loop=1
2026-02-09T16:34:10.832Z,1770654850.832 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-02-09T16:34:35.340Z,1770654875.340 [CommandExec](IMPORTANT): got command strobe off
2026-02-09T16:34:35.340Z,1770654875.340 [CommandExec](IMPORTANT): Deactivating strobe
2026-02-09T16:36:12.372Z,1770654972.372 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2026-02-09T16:36:12.372Z,1770654972.372 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2026-02-09T16:36:12.382Z,1770654972.382 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2026-02-09T16:36:12.804Z,1770654972.804 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2026-02-09T16:36:12.805Z,1770654972.805 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2026-02-09T16:36:31.348Z,1770654991.348 [NAL9602](FAULT): GPS failed to acquire within timeout.
2026-02-09T16:36:31.348Z,1770654991.348 [NAL9602] Data Fault, FailCount= 2
2026-02-09T16:36:31.348Z,1770654991.348 [NAL9602](ERROR): Data Fault
2026-02-09T16:36:31.365Z,1770654991.365 [CBIT](ERROR): Data Fault in component: NAL9602
2026-02-09T16:36:31.752Z,1770654991.752 [NAL9602](INFO): Powering down
2026-02-09T16:36:32.589Z,1770654992.589 [CBIT](INFO): Clearing failed state for component NAL9602
2026-02-09T16:36:32.589Z,1770654992.589 [NAL9602] No Fault, FailCount= 2
2026-02-09T16:37:02.052Z,1770655022.052 [NAL9602](INFO): Powering up NAL9602
2026-02-09T16:37:12.960Z,1770655032.960 [NAL9602](INFO): NAL9602 initialized
2026-02-09T16:39:11.379Z,1770655151.379 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-02-09T16:39:11.379Z,1770655151.379 [Default:CheckIn:C.Wait] Stopped
2026-02-09T16:39:11.379Z,1770655151.379 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-02-09T16:39:11.379Z,1770655151.379 [Default:CheckIn:D] Running Loop=1
2026-02-09T16:39:11.756Z,1770655151.756 [Default:CheckIn:D] Stopped
2026-02-09T16:39:11.756Z,1770655151.756 [Default:CheckIn:E] Running Loop=1
2026-02-09T16:39:12.165Z,1770655152.165 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.150854 min
2026-02-09T16:39:12.165Z,1770655152.165 [Default:CheckIn:E] Stopped
2026-02-09T16:39:12.165Z,1770655152.165 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-02-09T16:39:12.165Z,1770655152.165 [Default:CheckIn] Stopped
2026-02-09T16:39:12.165Z,1770655152.165 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-02-09T16:39:12.165Z,1770655152.165 [Default:CheckIn](INFO): Running loop #3
2026-02-09T16:39:12.166Z,1770655152.166 [Default:CheckIn] Running Loop=3
2026-02-09T16:39:12.166Z,1770655152.166 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-02-09T16:39:12.166Z,1770655152.166 [Default:CheckIn:Read_GPS] Running Loop=1
2026-02-09T16:39:13.388Z,1770655153.388 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2026-02-09T16:39:13.388Z,1770655153.388 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2026-02-09T16:39:13.419Z,1770655153.419 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2026-02-09T16:39:13.809Z,1770655153.809 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2026-02-09T16:39:13.810Z,1770655153.810 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2026-02-09T16:42:07.102Z,1770655327.102 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164205.00,A,3648.17149,N,12147.28359,W,0.467,0.00,090226,,,A*75
2026-02-09T16:42:07.105Z,1770655327.105 [NAL9602](INFO): GPS fix at 20260209T164205: (36.802858, -121.788060)
2026-02-09T16:42:07.118Z,1770655327.118 [Default:CheckIn:Read_GPS] Stopped
2026-02-09T16:42:07.118Z,1770655327.118 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-02-09T16:42:22.042Z,1770655342.042 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20260209T161454/Courier0010.lzma
2026-02-09T16:42:23.044Z,1770655343.044 [DataOverHttps](INFO): Moved sent file to Logs/20260209T161454/Courier0010.lzma.bak
2026-02-09T16:42:23.044Z,1770655343.044 [DataOverHttps](INFO): SBD MOMSN=26641412
2026-02-09T16:42:39.519Z,1770655359.519 [NAL9602](INFO): Not Powering down - fast GPS
2026-02-09T16:42:39.599Z,1770655359.599 [DataOverHttps](INFO): Sending 431 bytes from file Logs/20260209T161454/Express0011.lzma
2026-02-09T16:42:40.600Z,1770655360.600 [DataOverHttps](INFO): Moved sent file to Logs/20260209T161454/Express0011.lzma.bak
2026-02-09T16:42:40.601Z,1770655360.601 [DataOverHttps](INFO): SBD MOMSN=26641415
2026-02-09T16:42:41.950Z,1770655361.950 [Default:CheckIn:Read_Iridium] Stopped
2026-02-09T16:42:41.951Z,1770655361.951 [Default:CheckIn:C.Wait] Running Loop=1
2026-02-09T16:42:41.951Z,1770655361.951 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-02-09T16:45:12.233Z,1770655512.233 [Sonardyne_Nano](ERROR): Failed to parse response: >VS:2109,HPR45,EXT
2026-02-09T16:45:12.233Z,1770655512.233 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2026-02-09T16:47:16.047Z,1770655636.047 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-02-09T16:47:42.529Z,1770655662.529 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-02-09T16:47:42.529Z,1770655662.529 [Default:CheckIn:C.Wait] Stopped
2026-02-09T16:47:42.529Z,1770655662.529 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-02-09T16:47:42.529Z,1770655662.529 [Default:CheckIn:D] Running Loop=1
2026-02-09T16:47:42.947Z,1770655662.947 [Default:CheckIn:D] Stopped
2026-02-09T16:47:42.947Z,1770655662.947 [Default:CheckIn:E] Running Loop=1
2026-02-09T16:47:43.360Z,1770655663.360 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.670711 min
2026-02-09T16:47:43.361Z,1770655663.361 [Default:CheckIn:E] Stopped
2026-02-09T16:47:43.361Z,1770655663.361 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-02-09T16:47:43.361Z,1770655663.361 [Default:CheckIn] Stopped
2026-02-09T16:47:43.361Z,1770655663.361 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-02-09T16:47:43.361Z,1770655663.361 [Default:CheckIn](INFO): Running loop #4
2026-02-09T16:47:43.361Z,1770655663.361 [Default:CheckIn] Running Loop=4
2026-02-09T16:47:43.361Z,1770655663.361 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-02-09T16:47:43.361Z,1770655663.361 [Default:CheckIn:Read_GPS] Running Loop=1
2026-02-09T16:47:45.349Z,1770655665.349 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164744.00,A,3648.15397,N,12147.22433,W,0.039,20.43,090226,,,A*4D
2026-02-09T16:47:45.352Z,1770655665.352 [NAL9602](INFO): GPS fix at 20260209T164744: (36.802566, -121.787072)
2026-02-09T16:47:45.362Z,1770655665.362 [Default:CheckIn:Read_GPS] Stopped
2026-02-09T16:47:45.362Z,1770655665.362 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-02-09T16:47:47.179Z,1770655667.179 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-02-09T16:48:01.104Z,1770655681.104 [NAL9602](INFO): SBD MO Status=0, MOMSN=521, MT Status=0, MTMSN=0
2026-02-09T16:48:01.104Z,1770655681.104 [NAL9602](INFO): No messages in MT queue
2026-02-09T16:48:18.303Z,1770655698.303 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-02-09T16:48:31.807Z,1770655711.807 [NAL9602](INFO): Not Powering down - fast GPS
2026-02-09T16:48:49.427Z,1770655729.427 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-02-09T16:49:20.551Z,1770655760.551 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-02-09T16:49:23.576Z,1770655763.576 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.740463
2026-02-09T16:49:49.424Z,1770655789.424 [NAL9602](INFO): SBD MO Status=2, MOMSN=522, MT Status=2, MTMSN=0
2026-02-09T16:49:49.424Z,1770655789.424 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-02-09T16:49:54.695Z,1770655794.695 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-02-09T16:50:14.121Z,1770655814.121 [NAL9602](INFO): SBD MO Status=2, MOMSN=522, MT Status=2, MTMSN=0
2026-02-09T16:50:14.121Z,1770655814.121 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-02-09T16:50:25.823Z,1770655825.823 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-02-09T16:50:40.013Z,1770655840.013 [NAL9602](INFO): SBD MO Status=2, MOMSN=522, MT Status=2, MTMSN=0
2026-02-09T16:50:40.013Z,1770655840.013 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-02-09T16:50:56.947Z,1770655856.947 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-02-09T16:51:05.915Z,1770655865.915 [NAL9602](INFO): SBD MO Status=2, MOMSN=522, MT Status=2, MTMSN=0
2026-02-09T16:51:05.915Z,1770655865.915 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-02-09T16:51:28.075Z,1770655888.075 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-02-09T16:51:33.446Z,1770655893.446 [NAL9602](INFO): SBD MO Status=1, MOMSN=522, MT Status=0, MTMSN=0
2026-02-09T16:51:33.498Z,1770655893.498 [NAL9602](INFO): Sent 72 bytes from file Logs/20260209T161454/Courier0013.lzma
2026-02-09T16:51:33.498Z,1770655893.498 [NAL9602](INFO): Packets left to send: 0
2026-02-09T16:51:44.416Z,1770655904.416 [NAL9602](INFO): SBD MO Status=1, MOMSN=523, MT Status=0, MTMSN=0
2026-02-09T16:51:44.467Z,1770655904.467 [NAL9602](INFO): Sent 275 bytes from file Logs/20260209T161454/Express0014.lzma
2026-02-09T16:51:44.467Z,1770655904.467 [NAL9602](INFO): Packets left to send: 0
2026-02-09T16:51:51.693Z,1770655911.693 [NAL9602](INFO): SBD MO Status=0, MOMSN=524, MT Status=0, MTMSN=0
2026-02-09T16:51:51.771Z,1770655911.771 [Default:CheckIn:Read_Iridium] Stopped
2026-02-09T16:51:51.771Z,1770655911.771 [Default:CheckIn:C.Wait] Running Loop=1
2026-02-09T16:51:51.771Z,1770655911.771 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-02-09T16:51:59.200Z,1770655919.200 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-02-09T16:52:22.392Z,1770655942.392 [NAL9602](INFO): Not Powering down - fast GPS
2026-02-09T16:52:30.323Z,1770655950.323 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2026-02-09T16:52:38.656Z,1770655958.656 [DataOverHttps](IMPORTANT): SBD MTMSN=20260209T165237
2026-02-09T16:52:38.659Z,1770655958.659 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003019
2026-02-09T16:52:46.203Z,1770655966.203 [DataOverHttps](INFO): Received command: failc
2026-02-09T16:52:46.279Z,1770655966.279 [CommandExec](IMPORTANT): got command failComponent
2026-02-09T16:52:46.279Z,1770655966.279 [CommandExec](IMPORTANT): Failed components:
2026-02-09T16:52:46.279Z,1770655966.279 [CommandExec](IMPORTANT): No failed Components.
2026-02-09T16:52:47.204Z,1770655967.204 [DataOverHttps](IMPORTANT): SBD MTMSN=20260209T165246
2026-02-09T16:52:54.614Z,1770655974.614 [DataOverHttps](INFO): Received command: ibit
2026-02-09T16:52:54.707Z,1770655974.707 [CommandExec](IMPORTANT): got command ibit
2026-02-09T16:52:54.736Z,1770655974.736 [IBIT](IMPORTANT): Beginning Initiated BIT
2026-02-09T16:52:54.736Z,1770655974.736 [IBIT](IMPORTANT): Beginning control surface checks.
2026-02-09T16:52:54.740Z,1770655974.740 [GFScanner](IMPORTANT): Beginning ground fault scan
2026-02-09T16:52:55.616Z,1770655975.616 [DataOverHttps](IMPORTANT): SBD MTMSN=20260209T165254
2026-02-09T16:52:56.331Z,1770655976.331 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165255.00,A,3648.15419,N,12147.22246,W,0.039,20.43,090226,,,A*4C
2026-02-09T16:52:56.333Z,1770655976.333 [NAL9602](INFO): GPS fix at 20260209T165255: (36.802570, -121.787041)
2026-02-09T16:52:59.267Z,1770655979.267 [GFScanner](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.015285
CHAN A1 (24V): 0.029095
CHAN A2 (12V): 0.014158
CHAN A3 (5V): 0.001030
CHAN B0 (3.3V): -0.000902
CHAN B1 (3.15aV): -0.001247
CHAN B2 (3.15bV): -0.001370
CHAN B3 (GND): 4.792929
OPEN: 0.006901
Full Scale: +/- 1 mA
2026-02-09T16:53:03.270Z,1770655983.270 [DataOverHttps](INFO): Received command: failc
2026-02-09T16:53:03.363Z,1770655983.363 [CommandExec](IMPORTANT): got command failComponent
2026-02-09T16:53:03.363Z,1770655983.363 [CommandExec](IMPORTANT): Failed components:
2026-02-09T16:53:03.364Z,1770655983.364 [CommandExec](IMPORTANT): No failed Components.
2026-02-09T16:53:10.468Z,1770655990.468 [NAL9602](INFO): SBD MO Status=0, MOMSN=525, MT Status=0, MTMSN=0
2026-02-09T16:53:10.468Z,1770655990.468 [NAL9602](INFO): No messages in MT queue
2026-02-09T16:53:11.680Z,1770655991.680 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165310.00,A,3648.15400,N,12147.22175,W,0.194,20.43,090226,,,A*41
2026-02-09T16:53:11.682Z,1770655991.682 [NAL9602](INFO): GPS fix at 20260209T165310: (36.802567, -121.787029)
2026-02-09T16:53:14.913Z,1770655994.913 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165314.00,A,3648.15406,N,12147.22176,W,0.000,20.43,090226,,,A*4C
2026-02-09T16:53:14.916Z,1770655994.916 [NAL9602](INFO): GPS fix at 20260209T165314: (36.802568, -121.787029)
2026-02-09T16:53:18.148Z,1770655998.148 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165317.00,A,3648.15400,N,12147.22159,W,0.019,20.43,090226,,,A*4C
2026-02-09T16:53:18.150Z,1770655998.150 [NAL9602](INFO): GPS fix at 20260209T165317: (36.802567, -121.787026)
2026-02-09T16:53:20.974Z,1770656000.974 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165320.00,A,3648.15404,N,12147.22175,W,0.058,20.43,090226,,,A*47
2026-02-09T16:53:20.976Z,1770656000.976 [NAL9602](INFO): GPS fix at 20260209T165320: (36.802567, -121.787029)
2026-02-09T16:53:24.218Z,1770656004.218 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165323.00,A,3648.15403,N,12147.22179,W,0.039,20.43,090226,,,A*48
2026-02-09T16:53:24.220Z,1770656004.220 [NAL9602](INFO): GPS fix at 20260209T165323: (36.802567, -121.787030)
2026-02-09T16:53:27.034Z,1770656007.034 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165326.00,A,3648.15395,N,12147.22160,W,0.058,20.43,090226,,,A*4A
2026-02-09T16:53:27.036Z,1770656007.036 [NAL9602](INFO): GPS fix at 20260209T165326: (36.802566, -121.787027)
2026-02-09T16:53:29.861Z,1770656009.861 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165329.00,A,3648.15400,N,12147.22160,W,0.078,20.43,090226,,,A*4C
2026-02-09T16:53:29.864Z,1770656009.864 [NAL9602](INFO): GPS fix at 20260209T165329: (36.802567, -121.787027)
2026-02-09T16:53:33.094Z,1770656013.094 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165332.00,A,3648.15404,N,12147.22170,W,0.000,20.43,090226,,,A*4C
2026-02-09T16:53:33.096Z,1770656013.096 [NAL9602](INFO): GPS fix at 20260209T165332: (36.802567, -121.787028)
2026-02-09T16:53:35.925Z,1770656015.925 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165335.00,A,3648.15412,N,12147.22191,W,0.175,20.43,090226,,,A*40
2026-02-09T16:53:35.928Z,1770656015.928 [NAL9602](INFO): GPS fix at 20260209T165335: (36.802569, -121.787032)
2026-02-09T16:53:39.155Z,1770656019.155 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165338.00,A,3648.15420,N,12147.22214,W,0.058,20.43,090226,,,A*4C
2026-02-09T16:53:39.160Z,1770656019.160 [NAL9602](INFO): GPS fix at 20260209T165338: (36.802570, -121.787036)
2026-02-09T16:53:40.803Z,1770656020.803 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 36.802570 Longitude: -121.787033
2026-02-09T16:53:41.192Z,1770656021.192 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.572001
2026-02-09T16:53:41.192Z,1770656021.192 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2026-02-09T16:53:41.192Z,1770656021.192 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2026-02-09T16:53:41.596Z,1770656021.596 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2026-02-09T16:53:41.597Z,1770656021.597 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2026-02-09T16:53:41.597Z,1770656021.597 [IBIT](IMPORTANT): Pressure:9.836850 PSI
2026-02-09T16:53:41.597Z,1770656021.597 [IBIT](IMPORTANT): Humidity:6.061531 %
2026-02-09T16:53:41.998Z,1770656021.998 [IBIT](IMPORTANT): Vehicle Pitch:-0.583954 degrees
2026-02-09T16:53:41.998Z,1770656021.998 [IBIT](IMPORTANT): Vehicle Roll:5.727371 degrees
2026-02-09T16:53:41.998Z,1770656021.998 [IBIT](IMPORTANT): Vehicle Heading:2.433050 degrees
2026-02-09T16:53:42.418Z,1770656022.418 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2026-02-09T16:53:42.418Z,1770656022.418 [IBIT](IMPORTANT): buoyancyNeutral: 282.753387 cc
2026-02-09T16:53:42.419Z,1770656022.419 [IBIT](IMPORTANT): massDefault: 0.117609 cm
2026-02-09T16:53:42.419Z,1770656022.419 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2026-02-09T16:53:42.419Z,1770656022.419 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2026-02-09T16:53:42.419Z,1770656022.419 [IBIT](IMPORTANT): IBIT PASSED
2026-02-09T16:54:11.471Z,1770656051.471 [NAL9602](INFO): Not Powering down - fast GPS
2026-02-09T16:56:52.285Z,1770656212.285 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-02-09T16:56:52.285Z,1770656212.285 [Default:CheckIn:C.Wait] Stopped
2026-02-09T16:56:52.285Z,1770656212.285 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-02-09T16:56:52.286Z,1770656212.286 [Default:CheckIn:D] Running Loop=1
2026-02-09T16:56:52.692Z,1770656212.692 [Default:CheckIn:D] Stopped
2026-02-09T16:56:52.692Z,1770656212.692 [Default:CheckIn:E] Running Loop=1
2026-02-09T16:56:53.101Z,1770656213.101 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 38.833126 min
2026-02-09T16:56:53.101Z,1770656213.101 [Default:CheckIn:E] Stopped
2026-02-09T16:56:53.101Z,1770656213.101 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-02-09T16:56:53.101Z,1770656213.101 [Default:CheckIn] Stopped
2026-02-09T16:56:53.101Z,1770656213.101 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-02-09T16:56:53.101Z,1770656213.101 [Default:CheckIn](INFO): Running loop #5
2026-02-09T16:56:53.101Z,1770656213.101 [Default:CheckIn] Running Loop=5
2026-02-09T16:56:53.101Z,1770656213.101 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-02-09T16:56:53.101Z,1770656213.101 [Default:CheckIn:Read_GPS] Running Loop=1
2026-02-09T16:56:55.109Z,1770656215.109 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165654.00,A,3648.15479,N,12147.22511,W,0.078,20.43,090226,,,A*4F
2026-02-09T16:56:55.112Z,1770656215.112 [NAL9602](INFO): GPS fix at 20260209T165654: (36.802580, -121.787085)
2026-02-09T16:56:55.142Z,1770656215.142 [Default:CheckIn:Read_GPS] Stopped
2026-02-09T16:56:55.142Z,1770656215.142 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-02-09T16:57:02.398Z,1770656222.398 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260209T161454/Courier0016.lzma
2026-02-09T16:57:03.400Z,1770656223.400 [DataOverHttps](INFO): Moved sent file to Logs/20260209T161454/Courier0016.lzma.bak
2026-02-09T16:57:03.401Z,1770656223.401 [DataOverHttps](INFO): SBD MOMSN=26641461
2026-02-09T16:57:19.424Z,1770656239.424 [DataOverHttps](INFO): Sending 1069 bytes from file Logs/20260209T161454/Express0017.lzma
2026-02-09T16:57:20.424Z,1770656240.424 [DataOverHttps](INFO): Moved sent file to Logs/20260209T161454/Express0017.lzma.bak
2026-02-09T16:57:20.424Z,1770656240.424 [DataOverHttps](INFO): SBD MOMSN=26641464
2026-02-09T16:57:36.406Z,1770656256.406 [DataOverHttps](INFO): Sending 51 bytes from file Logs/20260209T161454/Express0020.lzma
2026-02-09T16:57:37.408Z,1770656257.408 [DataOverHttps](INFO): Moved sent file to Logs/20260209T161454/Express0020.lzma.bak
2026-02-09T16:57:37.409Z,1770656257.409 [DataOverHttps](INFO): SBD MOMSN=26641510
2026-02-09T16:57:38.757Z,1770656258.757 [Default:CheckIn:Read_Iridium] Stopped
2026-02-09T16:57:38.757Z,1770656258.757 [Default:CheckIn:C.Wait] Running Loop=1
2026-02-09T16:57:38.757Z,1770656258.757 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-02-09T16:59:25.028Z,1770656365.028 [NAL9602](INFO): SBD MO Status=0, MOMSN=526, MT Status=0, MTMSN=0
2026-02-09T16:59:25.028Z,1770656365.028 [NAL9602](INFO): No messages in MT queue
2026-02-09T16:59:55.741Z,1770656395.741 [NAL9602](INFO): Not Powering down - fast GPS
2026-02-09T17:02:39.429Z,1770656559.429 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-02-09T17:02:39.430Z,1770656559.430 [Default:CheckIn:C.Wait] Stopped
2026-02-09T17:02:39.430Z,1770656559.430 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-02-09T17:02:39.430Z,1770656559.430 [Default:CheckIn:D] Running Loop=1
2026-02-09T17:02:39.838Z,1770656559.838 [Default:CheckIn:D] Stopped
2026-02-09T17:02:39.838Z,1770656559.838 [Default:CheckIn:E] Running Loop=1
2026-02-09T17:02:40.246Z,1770656560.246 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 44.618892 min
2026-02-09T17:02:40.246Z,1770656560.246 [Default:CheckIn:E] Stopped
2026-02-09T17:02:40.246Z,1770656560.246 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-02-09T17:02:40.246Z,1770656560.246 [Default:CheckIn] Stopped
2026-02-09T17:02:40.246Z,1770656560.246 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-02-09T17:02:40.247Z,1770656560.247 [Default:CheckIn](INFO): Running loop #6
2026-02-09T17:02:40.247Z,1770656560.247 [Default:CheckIn] Running Loop=6
2026-02-09T17:02:40.247Z,1770656560.247 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-02-09T17:02:40.247Z,1770656560.247 [Default:CheckIn:Read_GPS] Running Loop=1
2026-02-09T17:02:42.244Z,1770656562.244 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170241.00,A,3648.15174,N,12147.22030,W,0.058,20.43,090226,,,A*47
2026-02-09T17:02:42.247Z,1770656562.247 [NAL9602](INFO): GPS fix at 20260209T170241: (36.802529, -121.787005)
2026-02-09T17:02:42.257Z,1770656562.257 [Default:CheckIn:Read_GPS] Stopped
2026-02-09T17:02:42.257Z,1770656562.257 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-02-09T17:02:49.250Z,1770656569.250 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260209T161454/Courier0022.lzma
2026-02-09T17:02:50.252Z,1770656570.252 [DataOverHttps](INFO): Moved sent file to Logs/20260209T161454/Courier0022.lzma.bak
2026-02-09T17:02:50.253Z,1770656570.253 [DataOverHttps](INFO): SBD MOMSN=26641555
2026-02-09T17:03:02.445Z,1770656582.445 [NAL9602](INFO): SBD MO Status=0, MOMSN=527, MT Status=0, MTMSN=0
2026-02-09T17:03:02.445Z,1770656582.445 [NAL9602](INFO): No messages in MT queue
2026-02-09T17:03:06.222Z,1770656586.222 [DataOverHttps](INFO): Sending 163 bytes from file Logs/20260209T161454/Express0023.lzma
2026-02-09T17:03:07.224Z,1770656587.224 [DataOverHttps](INFO): Moved sent file to Logs/20260209T161454/Express0023.lzma.bak
2026-02-09T17:03:07.224Z,1770656587.224 [DataOverHttps](INFO): SBD MOMSN=26641558
2026-02-09T17:03:08.536Z,1770656588.536 [Default:CheckIn:Read_Iridium] Stopped
2026-02-09T17:03:08.536Z,1770656588.536 [Default:CheckIn:C.Wait] Running Loop=1
2026-02-09T17:03:08.536Z,1770656588.536 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-02-09T17:03:33.158Z,1770656613.158 [NAL9602](INFO): Not Powering down - fast GPS
2026-02-09T17:08:09.201Z,1770656889.201 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-02-09T17:08:09.201Z,1770656889.201 [Default:CheckIn:C.Wait] Stopped
2026-02-09T17:08:09.201Z,1770656889.201 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-02-09T17:08:09.201Z,1770656889.201 [Default:CheckIn:D] Running Loop=1
2026-02-09T17:08:09.606Z,1770656889.606 [Default:CheckIn:D] Stopped
2026-02-09T17:08:09.606Z,1770656889.606 [Default:CheckIn:E] Running Loop=1
2026-02-09T17:08:10.012Z,1770656890.012 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 50.115027 min
2026-02-09T17:08:10.012Z,1770656890.012 [Default:CheckIn:E] Stopped
2026-02-09T17:08:10.012Z,1770656890.012 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-02-09T17:08:10.012Z,1770656890.012 [Default:CheckIn] Stopped
2026-02-09T17:08:10.012Z,1770656890.012 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-02-09T17:08:10.012Z,1770656890.012 [Default:CheckIn](INFO): Running loop #7
2026-02-09T17:08:10.012Z,1770656890.012 [Default:CheckIn] Running Loop=7
2026-02-09T17:08:10.012Z,1770656890.012 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-02-09T17:08:10.013Z,1770656890.013 [Default:CheckIn:Read_GPS] Running Loop=1
2026-02-09T17:08:12.012Z,1770656892.012 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170811.00,A,3648.15338,N,12147.22101,W,0.039,20.43,090226,,,A*46
2026-02-09T17:08:12.015Z,1770656892.015 [NAL9602](INFO): GPS fix at 20260209T170811: (36.802556, -121.787017)
2026-02-09T17:08:12.051Z,1770656892.051 [Default:CheckIn:Read_GPS] Stopped
2026-02-09T17:08:12.051Z,1770656892.051 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-02-09T17:08:21.402Z,1770656901.402 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260209T161454/Courier0025.lzma
2026-02-09T17:08:22.404Z,1770656902.404 [DataOverHttps](INFO): Moved sent file to Logs/20260209T161454/Courier0025.lzma.bak
2026-02-09T17:08:22.404Z,1770656902.404 [DataOverHttps](INFO): SBD MOMSN=26641610
2026-02-09T17:08:33.833Z,1770656913.833 [NAL9602](INFO): SBD MO Status=2, MOMSN=528, MT Status=2, MTMSN=0
2026-02-09T17:08:33.833Z,1770656913.833 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2026-02-09T17:08:38.226Z,1770656918.226 [DataOverHttps](INFO): Sending 162 bytes from file Logs/20260209T161454/Express0026.lzma
2026-02-09T17:08:39.229Z,1770656919.229 [DataOverHttps](INFO): Moved sent file to Logs/20260209T161454/Express0026.lzma.bak
2026-02-09T17:08:39.229Z,1770656919.229 [DataOverHttps](INFO): SBD MOMSN=26641613
2026-02-09T17:08:40.314Z,1770656920.314 [Default:CheckIn:Read_Iridium] Stopped
2026-02-09T17:08:40.315Z,1770656920.315 [Default:CheckIn:C.Wait] Running Loop=1
2026-02-09T17:08:40.315Z,1770656920.315 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-02-09T17:08:52.425Z,1770656932.425 [NAL9602](INFO): SBD MO Status=0, MOMSN=528, MT Status=0, MTMSN=0
2026-02-09T17:08:52.425Z,1770656932.425 [NAL9602](INFO): No messages in MT queue
2026-02-09T17:09:23.124Z,1770656963.124 [NAL9602](INFO): Not Powering down - fast GPS
2026-02-09T17:13:40.900Z,1770657220.900 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-02-09T17:13:40.901Z,1770657220.901 [Default:CheckIn:C.Wait] Stopped
2026-02-09T17:13:40.901Z,1770657220.901 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-02-09T17:13:40.901Z,1770657220.901 [Default:CheckIn:D] Running Loop=1
2026-02-09T17:13:41.307Z,1770657221.307 [Default:CheckIn:D] Stopped
2026-02-09T17:13:41.307Z,1770657221.307 [Default:CheckIn:E] Running Loop=1
2026-02-09T17:13:41.723Z,1770657221.723 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 55.643376 min
2026-02-09T17:13:41.723Z,1770657221.723 [Default:CheckIn:E] Stopped
2026-02-09T17:13:41.723Z,1770657221.723 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-02-09T17:13:41.723Z,1770657221.723 [Default:CheckIn] Stopped
2026-02-09T17:13:41.723Z,1770657221.723 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-02-09T17:13:41.723Z,1770657221.723 [Default:CheckIn](INFO): Running loop #8
2026-02-09T17:13:41.723Z,1770657221.723 [Default:CheckIn] Running Loop=8
2026-02-09T17:13:41.723Z,1770657221.723 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-02-09T17:13:41.723Z,1770657221.723 [Default:CheckIn:Read_GPS] Running Loop=1
2026-02-09T17:13:43.720Z,1770657223.720 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171343.00,A,3648.15289,N,12147.22013,W,0.058,20.43,090226,,,A*45
2026-02-09T17:13:43.723Z,1770657223.723 [NAL9602](INFO): GPS fix at 20260209T171343: (36.802548, -121.787002)
2026-02-09T17:13:43.733Z,1770657223.733 [Default:CheckIn:Read_GPS] Stopped
2026-02-09T17:13:43.733Z,1770657223.733 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-02-09T17:13:51.038Z,1770657231.038 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260209T161454/Courier0028.lzma
2026-02-09T17:13:52.040Z,1770657232.040 [DataOverHttps](INFO): Moved sent file to Logs/20260209T161454/Courier0028.lzma.bak
2026-02-09T17:13:52.041Z,1770657232.041 [DataOverHttps](INFO): SBD MOMSN=26641618
2026-02-09T17:14:01.093Z,1770657241.093 [NAL9602](INFO): SBD MO Status=0, MOMSN=529, MT Status=0, MTMSN=0
2026-02-09T17:14:01.093Z,1770657241.093 [NAL9602](INFO): No messages in MT queue
2026-02-09T17:14:07.934Z,1770657247.934 [DataOverHttps](INFO): Sending 161 bytes from file Logs/20260209T161454/Express0029.lzma
2026-02-09T17:14:08.937Z,1770657248.937 [DataOverHttps](INFO): Moved sent file to Logs/20260209T161454/Express0029.lzma.bak
2026-02-09T17:14:08.937Z,1770657248.937 [DataOverHttps](INFO): SBD MOMSN=26641622
2026-02-09T17:14:09.997Z,1770657249.997 [Default:CheckIn:Read_Iridium] Stopped
2026-02-09T17:14:09.997Z,1770657249.997 [Default:CheckIn:C.Wait] Running Loop=1
2026-02-09T17:14:09.997Z,1770657249.997 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-02-09T17:14:31.849Z,1770657271.849 [NAL9602](INFO): Not Powering down - fast GPS
2026-02-09T17:19:10.658Z,1770657550.658 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-02-09T17:19:10.658Z,1770657550.658 [Default:CheckIn:C.Wait] Stopped
2026-02-09T17:19:10.658Z,1770657550.658 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-02-09T17:19:10.658Z,1770657550.658 [Default:CheckIn:D] Running Loop=1
2026-02-09T17:19:11.075Z,1770657551.075 [Default:CheckIn:D] Stopped
2026-02-09T17:19:11.075Z,1770657551.075 [Default:CheckIn:E] Running Loop=1
2026-02-09T17:19:11.466Z,1770657551.466 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 61.139514 min
2026-02-09T17:19:11.466Z,1770657551.466 [Default:CheckIn:E] Stopped
2026-02-09T17:19:11.466Z,1770657551.466 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-02-09T17:19:11.467Z,1770657551.467 [Default:CheckIn] Stopped
2026-02-09T17:19:11.467Z,1770657551.467 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-02-09T17:19:11.467Z,1770657551.467 [Default:CheckIn](INFO): Running loop #9
2026-02-09T17:19:11.467Z,1770657551.467 [Default:CheckIn] Running Loop=9
2026-02-09T17:19:11.467Z,1770657551.467 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-02-09T17:19:11.467Z,1770657551.467 [Default:CheckIn:Read_GPS] Running Loop=1
2026-02-09T17:19:13.472Z,1770657553.472 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171912.00,A,3648.15434,N,12147.22211,W,0.058,20.43,090226,,,A*4B
2026-02-09T17:19:13.475Z,1770657553.475 [NAL9602](INFO): GPS fix at 20260209T171912: (36.802572, -121.787035)
2026-02-09T17:19:13.485Z,1770657553.485 [Default:CheckIn:Read_GPS] Stopped
2026-02-09T17:19:13.485Z,1770657553.485 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-02-09T17:19:20.894Z,1770657560.894 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20260209T161454/Courier0031.lzma
2026-02-09T17:19:21.896Z,1770657561.896 [DataOverHttps](INFO): Moved sent file to Logs/20260209T161454/Courier0031.lzma.bak
2026-02-09T17:19:21.897Z,1770657561.897 [DataOverHttps](INFO): SBD MOMSN=26641683
2026-02-09T17:19:22.369Z,1770657562.369 [NAL9602](INFO): SBD MO Status=0, MOMSN=530, MT Status=0, MTMSN=0
2026-02-09T17:19:22.370Z,1770657562.370 [NAL9602](INFO): No messages in MT queue
2026-02-09T17:19:37.806Z,1770657577.806 [DataOverHttps](INFO): Sending 159 bytes from file Logs/20260209T161454/Express0032.lzma
2026-02-09T17:19:38.808Z,1770657578.808 [DataOverHttps](INFO): Moved sent file to Logs/20260209T161454/Express0032.lzma.bak
2026-02-09T17:19:38.809Z,1770657578.809 [DataOverHttps](INFO): SBD MOMSN=26641686
2026-02-09T17:19:40.156Z,1770657580.156 [Default:CheckIn:Read_Iridium] Stopped
2026-02-09T17:19:40.156Z,1770657580.156 [Default:CheckIn:C.Wait] Running Loop=1
2026-02-09T17:19:40.156Z,1770657580.156 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-02-09T17:19:53.064Z,1770657593.064 [NAL9602](INFO): Not Powering down - fast GPS
2026-02-09T17:24:40.801Z,1770657880.801 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-02-09T17:24:40.801Z,1770657880.801 [Default:CheckIn:C.Wait] Stopped
2026-02-09T17:24:40.802Z,1770657880.802 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-02-09T17:24:40.802Z,1770657880.802 [Default:CheckIn:D] Running Loop=1
2026-02-09T17:24:41.213Z,1770657881.213 [Default:CheckIn:D] Stopped
2026-02-09T17:24:41.213Z,1770657881.213 [Default:CheckIn:E] Running Loop=1
2026-02-09T17:24:41.680Z,1770657881.680 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 66.641801 min
2026-02-09T17:24:41.680Z,1770657881.680 [Default:CheckIn:E] Stopped
2026-02-09T17:24:41.680Z,1770657881.680 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-02-09T17:24:41.680Z,1770657881.680 [Default:CheckIn] Stopped
2026-02-09T17:24:41.680Z,1770657881.680 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-02-09T17:24:41.680Z,1770657881.680 [Default:CheckIn](INFO): Running loop #10
2026-02-09T17:24:41.680Z,1770657881.680 [Default:CheckIn] Running Loop=10
2026-02-09T17:24:41.680Z,1770657881.680 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-02-09T17:24:41.681Z,1770657881.681 [Default:CheckIn:Read_GPS] Running Loop=1
2026-02-09T17:24:43.601Z,1770657883.601 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172442.00,A,3648.15362,N,12147.21902,W,0.019,20.43,090226,,,A*4B
2026-02-09T17:24:43.604Z,1770657883.604 [NAL9602](INFO): GPS fix at 20260209T172442: (36.802560, -121.786984)
2026-02-09T17:24:43.629Z,1770657883.629 [Default:CheckIn:Read_GPS] Stopped
2026-02-09T17:24:43.629Z,1770657883.629 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-02-09T17:24:50.934Z,1770657890.934 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260209T161454/Courier0034.lzma
2026-02-09T17:24:51.936Z,1770657891.936 [DataOverHttps](INFO): Moved sent file to Logs/20260209T161454/Courier0034.lzma.bak
2026-02-09T17:24:51.937Z,1770657891.937 [DataOverHttps](INFO): SBD MOMSN=26641697
2026-02-09T17:25:00.165Z,1770657900.165 [NAL9602](INFO): SBD MO Status=0, MOMSN=531, MT Status=0, MTMSN=0
2026-02-09T17:25:00.165Z,1770657900.165 [NAL9602](INFO): No messages in MT queue
2026-02-09T17:25:07.862Z,1770657907.862 [DataOverHttps](INFO): Sending 162 bytes from file Logs/20260209T161454/Express0035.lzma
2026-02-09T17:25:08.864Z,1770657908.864 [DataOverHttps](INFO): Moved sent file to Logs/20260209T161454/Express0035.lzma.bak
2026-02-09T17:25:08.865Z,1770657908.865 [DataOverHttps](INFO): SBD MOMSN=26641700
2026-02-09T17:25:10.269Z,1770657910.269 [Default:CheckIn:Read_Iridium] Stopped
2026-02-09T17:25:10.269Z,1770657910.269 [Default:CheckIn:C.Wait] Running Loop=1
2026-02-09T17:25:10.269Z,1770657910.269 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-02-09T17:25:12.693Z,1770657912.693 [Sonardyne_Nano](ERROR): Failed to parse response: >VS:2109,HPR45,EXT,TILT
2026-02-09T17:25:12.693Z,1770657912.693 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2026-02-09T17:25:30.872Z,1770657930.872 [NAL9602](INFO): Not Powering down - fast GPS
2026-02-09T17:25:35.717Z,1770657935.717 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2026-02-09T17:28:38.412Z,1770658118.412 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7).
2026-02-09T17:28:38.418Z,1770658118.418 [BPC1](INFO): Calculating totals. Valid battery stick count: 47. Valid reserve battery stick count: 5.
2026-02-09T17:28:38.421Z,1770658118.421 [BPC1](INFO): Received data from all battery sticks.
2026-02-09T17:30:10.957Z,1770658210.957 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-02-09T17:30:10.957Z,1770658210.957 [Default:CheckIn:C.Wait] Stopped
2026-02-09T17:30:10.957Z,1770658210.957 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-02-09T17:30:10.957Z,1770658210.957 [Default:CheckIn:D] Running Loop=1
2026-02-09T17:30:11.349Z,1770658211.349 [Default:CheckIn:D] Stopped
2026-02-09T17:30:11.349Z,1770658211.349 [Default:CheckIn:E] Running Loop=1
2026-02-09T17:30:11.756Z,1770658211.756 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 72.144076 min
2026-02-09T17:30:11.756Z,1770658211.756 [Default:CheckIn:E] Stopped
2026-02-09T17:30:11.756Z,1770658211.756 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-02-09T17:30:11.757Z,1770658211.757 [Default:CheckIn] Stopped
2026-02-09T17:30:11.757Z,1770658211.757 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-02-09T17:30:11.757Z,1770658211.757 [Default:CheckIn](INFO): Running loop #11
2026-02-09T17:30:11.757Z,1770658211.757 [Default:CheckIn] Running Loop=11
2026-02-09T17:30:11.757Z,1770658211.757 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-02-09T17:30:11.757Z,1770658211.757 [Default:CheckIn:Read_GPS] Running Loop=1
2026-02-09T17:30:13.757Z,1770658213.757 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173013.00,A,3648.15562,N,12147.22278,W,0.097,0.00,090226,,,A*7A
2026-02-09T17:30:13.760Z,1770658213.760 [NAL9602](INFO): GPS fix at 20260209T173013: (36.802594, -121.787046)
2026-02-09T17:30:13.805Z,1770658213.805 [Default:CheckIn:Read_GPS] Stopped
2026-02-09T17:30:13.805Z,1770658213.805 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-02-09T17:30:21.530Z,1770658221.530 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20260209T161454/Courier0037.lzma
2026-02-09T17:30:22.532Z,1770658222.532 [DataOverHttps](INFO): Moved sent file to Logs/20260209T161454/Courier0037.lzma.bak
2026-02-09T17:30:22.533Z,1770658222.533 [DataOverHttps](INFO): SBD MOMSN=26641734
2026-02-09T17:30:27.101Z,1770658227.101 [NAL9602](INFO): SBD MO Status=0, MOMSN=532, MT Status=0, MTMSN=0
2026-02-09T17:30:27.101Z,1770658227.101 [NAL9602](INFO): No messages in MT queue
2026-02-09T17:30:38.714Z,1770658238.714 [DataOverHttps](INFO): Sending 162 bytes from file Logs/20260209T161454/Express0038.lzma
2026-02-09T17:30:39.717Z,1770658239.717 [DataOverHttps](INFO): Moved sent file to Logs/20260209T161454/Express0038.lzma.bak
2026-02-09T17:30:39.717Z,1770658239.717 [DataOverHttps](INFO): SBD MOMSN=26641737
2026-02-09T17:30:40.850Z,1770658240.850 [Default:CheckIn:Read_Iridium] Stopped
2026-02-09T17:30:40.850Z,1770658240.850 [Default:CheckIn:C.Wait] Running Loop=1
2026-02-09T17:30:40.850Z,1770658240.850 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-02-09T17:30:57.804Z,1770658257.804 [NAL9602](INFO): Not Powering down - fast GPS
2026-02-09T17:35:41.459Z,1770658541.459 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-02-09T17:35:41.459Z,1770658541.459 [Default:CheckIn:C.Wait] Stopped
2026-02-09T17:35:41.459Z,1770658541.459 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-02-09T17:35:41.459Z,1770658541.459 [Default:CheckIn:D] Running Loop=1
2026-02-09T17:35:41.854Z,1770658541.854 [Default:CheckIn:D] Stopped
2026-02-09T17:35:41.854Z,1770658541.854 [Default:CheckIn:E] Running Loop=1
2026-02-09T17:35:42.254Z,1770658542.254 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 77.652482 min
2026-02-09T17:35:42.254Z,1770658542.254 [Default:CheckIn:E] Stopped
2026-02-09T17:35:42.254Z,1770658542.254 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-02-09T17:35:42.254Z,1770658542.254 [Default:CheckIn] Stopped
2026-02-09T17:35:42.254Z,1770658542.254 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-02-09T17:35:42.254Z,1770658542.254 [Default:CheckIn](INFO): Running loop #12
2026-02-09T17:35:42.254Z,1770658542.254 [Default:CheckIn] Running Loop=12
2026-02-09T17:35:42.255Z,1770658542.255 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-02-09T17:35:42.255Z,1770658542.255 [Default:CheckIn:Read_GPS] Running Loop=1
2026-02-09T17:35:44.267Z,1770658544.267 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173543.00,A,3648.15615,N,12147.22181,W,0.039,0.00,090226,,,A*78
2026-02-09T17:35:44.269Z,1770658544.269 [NAL9602](INFO): GPS fix at 20260209T173543: (36.802602, -121.787030)
2026-02-09T17:35:44.279Z,1770658544.279 [Default:CheckIn:Read_GPS] Stopped
2026-02-09T17:35:44.280Z,1770658544.280 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-02-09T17:35:52.006Z,1770658552.006 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260209T161454/Courier0040.lzma
2026-02-09T17:35:53.009Z,1770658553.009 [DataOverHttps](INFO): Moved sent file to Logs/20260209T161454/Courier0040.lzma.bak
2026-02-09T17:35:53.009Z,1770658553.009 [DataOverHttps](INFO): SBD MOMSN=26641740
2026-02-09T17:35:57.197Z,1770658557.197 [NAL9602](INFO): SBD MO Status=0, MOMSN=533, MT Status=0, MTMSN=0
2026-02-09T17:35:57.197Z,1770658557.197 [NAL9602](INFO): No messages in MT queue
2026-02-09T17:36:09.490Z,1770658569.490 [DataOverHttps](INFO): Sending 162 bytes from file Logs/20260209T161454/Express0041.lzma
2026-02-09T17:36:10.493Z,1770658570.493 [DataOverHttps](INFO): Moved sent file to Logs/20260209T161454/Express0041.lzma.bak
2026-02-09T17:36:10.493Z,1770658570.493 [DataOverHttps](INFO): SBD MOMSN=26641743
2026-02-09T17:36:11.758Z,1770658571.758 [Default:CheckIn:Read_Iridium] Stopped
2026-02-09T17:36:11.758Z,1770658571.758 [Default:CheckIn:C.Wait] Running Loop=1
2026-02-09T17:36:11.758Z,1770658571.758 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-02-09T17:36:27.928Z,1770658587.928 [NAL9602](INFO): Not Powering down - fast GPS
2026-02-09T17:41:12.362Z,1770658872.362 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-02-09T17:41:12.362Z,1770658872.362 [Default:CheckIn:C.Wait] Stopped
2026-02-09T17:41:12.362Z,1770658872.362 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-02-09T17:41:12.362Z,1770658872.362 [Default:CheckIn:D] Running Loop=1
2026-02-09T17:41:12.766Z,1770658872.766 [Default:CheckIn:D] Stopped
2026-02-09T17:41:12.766Z,1770658872.766 [Default:CheckIn:E] Running Loop=1
2026-02-09T17:41:13.171Z,1770658873.171 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.167684 min
2026-02-09T17:41:13.172Z,1770658873.172 [Default:CheckIn:E] Stopped
2026-02-09T17:41:13.172Z,1770658873.172 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-02-09T17:41:13.172Z,1770658873.172 [Default:CheckIn] Stopped
2026-02-09T17:41:13.172Z,1770658873.172 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-02-09T17:41:13.172Z,1770658873.172 [Default:CheckIn](INFO): Running loop #13
2026-02-09T17:41:13.172Z,1770658873.172 [Default:CheckIn] Running Loop=13
2026-02-09T17:41:13.172Z,1770658873.172 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-02-09T17:41:13.172Z,1770658873.172 [Default:CheckIn:Read_GPS] Running Loop=1
2026-02-09T17:41:15.177Z,1770658875.177 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174114.00,A,3648.15459,N,12147.22407,W,0.039,0.00,090226,,,A*78
2026-02-09T17:41:15.194Z,1770658875.194 [NAL9602](INFO): GPS fix at 20260209T174114: (36.802577, -121.787068)
2026-02-09T17:41:15.205Z,1770658875.205 [Default:CheckIn:Read_GPS] Stopped
2026-02-09T17:41:15.205Z,1770658875.205 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-02-09T17:41:22.874Z,1770658882.874 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20260209T161454/Courier0043.lzma
2026-02-09T17:41:23.879Z,1770658883.879 [DataOverHttps](INFO): Moved sent file to Logs/20260209T161454/Courier0043.lzma.bak
2026-02-09T17:41:23.879Z,1770658883.879 [DataOverHttps](INFO): SBD MOMSN=26641758
2026-02-09T17:41:33.358Z,1770658893.358 [NAL9602](INFO): SBD MO Status=0, MOMSN=534, MT Status=0, MTMSN=0
2026-02-09T17:41:33.358Z,1770658893.358 [NAL9602](INFO): No messages in MT queue
2026-02-09T17:41:39.886Z,1770658899.886 [DataOverHttps](INFO): Sending 161 bytes from file Logs/20260209T161454/Express0044.lzma
2026-02-09T17:41:40.888Z,1770658900.888 [DataOverHttps](INFO): Moved sent file to Logs/20260209T161454/Express0044.lzma.bak
2026-02-09T17:41:40.889Z,1770658900.889 [DataOverHttps](INFO): SBD MOMSN=26641761
2026-02-09T17:41:42.253Z,1770658902.253 [Default:CheckIn:Read_Iridium] Stopped
2026-02-09T17:41:42.253Z,1770658902.253 [Default:CheckIn:C.Wait] Running Loop=1
2026-02-09T17:41:42.253Z,1770658902.253 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-02-09T17:42:04.068Z,1770658924.068 [NAL9602](INFO): Not Powering down - fast GPS
2026-02-09T17:46:42.889Z,1770659202.889 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2026-02-09T17:46:42.889Z,1770659202.889 [Default:CheckIn:C.Wait] Stopped
2026-02-09T17:46:42.889Z,1770659202.889 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2026-02-09T17:46:42.889Z,1770659202.889 [Default:CheckIn:D] Running Loop=1
2026-02-09T17:46:43.300Z,1770659203.300 [Default:CheckIn:D] Stopped
2026-02-09T17:46:43.300Z,1770659203.300 [Default:CheckIn:E] Running Loop=1
2026-02-09T17:46:43.698Z,1770659203.698 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 88.676587 min
2026-02-09T17:46:43.698Z,1770659203.698 [Default:CheckIn:E] Stopped
2026-02-09T17:46:43.698Z,1770659203.698 [Default:CheckIn](INFO): Completed Default:CheckIn
2026-02-09T17:46:43.714Z,1770659203.714 [Default:CheckIn] Stopped
2026-02-09T17:46:43.715Z,1770659203.715 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2026-02-09T17:46:43.715Z,1770659203.715 [Default:CheckIn](INFO): Running loop #14
2026-02-09T17:46:43.715Z,1770659203.715 [Default:CheckIn] Running Loop=14
2026-02-09T17:46:43.715Z,1770659203.715 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2026-02-09T17:46:43.715Z,1770659203.715 [Default:CheckIn:Read_GPS] Running Loop=1
2026-02-09T17:46:45.704Z,1770659205.704 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174644.00,A,3648.15465,N,12147.22064,W,0.039,0.00,090226,,,A*74
2026-02-09T17:46:45.707Z,1770659205.707 [NAL9602](INFO): GPS fix at 20260209T174644: (36.802577, -121.787011)
2026-02-09T17:46:45.717Z,1770659205.717 [Default:CheckIn:Read_GPS] Stopped
2026-02-09T17:46:45.717Z,1770659205.717 [Default:CheckIn:Read_Iridium] Running Loop=1
2026-02-09T17:46:53.682Z,1770659213.682 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20260209T161454/Courier0046.lzma
2026-02-09T17:46:54.684Z,1770659214.684 [DataOverHttps](INFO): Moved sent file to Logs/20260209T161454/Courier0046.lzma.bak
2026-02-09T17:46:54.685Z,1770659214.685 [DataOverHttps](INFO): SBD MOMSN=26641770
2026-02-09T17:46:55.814Z,1770659215.814 [NAL9602](INFO): SBD MO Status=0, MOMSN=535, MT Status=0, MTMSN=0
2026-02-09T17:46:55.815Z,1770659215.815 [NAL9602](INFO): No messages in MT queue
2026-02-09T17:47:10.706Z,1770659230.706 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20260209T161454/Express0047.lzma
2026-02-09T17:47:11.709Z,1770659231.709 [DataOverHttps](INFO): Moved sent file to Logs/20260209T161454/Express0047.lzma.bak
2026-02-09T17:47:11.709Z,1770659231.709 [DataOverHttps](INFO): SBD MOMSN=26641773
2026-02-09T17:47:12.786Z,1770659232.786 [Default:CheckIn:Read_Iridium] Stopped
2026-02-09T17:47:12.786Z,1770659232.786 [Default:CheckIn:C.Wait] Running Loop=1
2026-02-09T17:47:12.786Z,1770659232.786 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2026-02-09T17:47:26.508Z,1770659246.508 [NAL9602](INFO): Not Powering down - fast GPS
2026-02-09T17:48:51.116Z,1770659331.116 [DataOverHttps](IMPORTANT): SBD MTMSN=20260209T174850
2026-02-09T17:48:58.522Z,1770659338.522 [DataOverHttps](INFO): Received command: restart sys
2026-02-09T17:48:58.549Z,1770659338.549 [CommandExec](IMPORTANT): got command restart system
2026-02-09T17:49:00.619Z,1770659340.619 [Supervisor](INFO): Stop Mission called by Supervisor::t