2025-07-21T15:50:13.025Z,1753113013.025 [Supervisor](DEBUG): Initializing supervisor.
2025-07-21T15:50:13.030Z,1753113013.030 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-07-21T15:50:13.030Z,1753113013.030 [SyncHandler](INFO): Protected caller Thread ID is 839
2025-07-21T15:50:13.031Z,1753113013.031 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-07-21T15:50:13.032Z,1753113013.032 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-07-21T15:50:13.032Z,1753113013.032 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 840
2025-07-21T15:50:13.036Z,1753113013.036 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-07-21T15:50:13.057Z,1753113013.057 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-07-21T15:50:13.058Z,1753113013.058 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-07-21T15:50:13.058Z,1753113013.058 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 841
2025-07-21T15:50:13.062Z,1753113013.062 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-07-21T15:50:13.063Z,1753113013.063 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-07-21T15:50:13.064Z,1753113013.064 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 842
2025-07-21T15:50:13.066Z,1753113013.066 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-07-21T15:50:13.067Z,1753113013.067 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-07-21T15:50:13.067Z,1753113013.067 [logger ThreadHandler](INFO): Protected caller Thread ID is 843
2025-07-21T15:50:13.071Z,1753113013.071 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-07-21T15:50:13.072Z,1753113013.072 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-07-21T15:50:13.076Z,1753113013.076 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-07-21T15:50:13.467Z,1753113013.467 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-07-21T15:50:13.468Z,1753113013.468 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-07-21T15:50:13.568Z,1753113013.568 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance
2025-07-21T15:50:13.570Z,1753113013.570 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-07-21T15:50:13.685Z,1753113013.685 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-07-21T15:50:13.687Z,1753113013.687 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-07-21T15:50:13.831Z,1753113013.831 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-07-21T15:50:13.832Z,1753113013.832 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-07-21T15:50:14.192Z,1753113014.192 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-07-21T15:50:14.193Z,1753113014.193 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-07-21T15:50:14.299Z,1753113014.299 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-07-21T15:50:14.299Z,1753113014.299 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-07-21T15:50:14.721Z,1753113014.721 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-07-21T15:50:14.722Z,1753113014.722 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-07-21T15:50:14.959Z,1753113014.959 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-07-21T15:50:14.960Z,1753113014.960 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-07-21T15:50:15.049Z,1753113015.049 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-07-21T15:50:15.301Z,1753113015.301 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-07-21T15:50:15.303Z,1753113015.303 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-07-21T15:50:15.535Z,1753113015.535 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-07-21T15:50:15.536Z,1753113015.536 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-07-21T15:50:16.124Z,1753113016.124 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-07-21T15:50:16.125Z,1753113016.125 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-07-21T15:50:16.319Z,1753113016.319 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-07-21T15:50:16.321Z,1753113016.321 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-07-21T15:50:16.614Z,1753113016.614 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-07-21T15:50:16.615Z,1753113016.615 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-07-21T15:50:17.559Z,1753113017.559 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-07-21T15:50:17.561Z,1753113017.561 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-07-21T15:50:18.145Z,1753113018.145 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-07-21T15:50:18.147Z,1753113018.147 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/
2025-07-21T15:50:18.149Z,1753113018.149 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg
2025-07-21T15:50:18.256Z,1753113018.256 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg
2025-07-21T15:50:18.398Z,1753113018.398 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg
2025-07-21T15:50:18.491Z,1753113018.491 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg
2025-07-21T15:50:18.607Z,1753113018.607 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg
2025-07-21T15:50:18.709Z,1753113018.709 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg
2025-07-21T15:50:18.984Z,1753113018.984 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-07-21T15:50:18.985Z,1753113018.985 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg
2025-07-21T15:50:19.104Z,1753113019.104 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg
2025-07-21T15:50:19.210Z,1753113019.210 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg
2025-07-21T15:50:19.358Z,1753113019.358 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg
2025-07-21T15:50:19.453Z,1753113019.453 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/root/
2025-07-21T15:50:19.454Z,1753113019.454 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-07-21T15:50:19.467Z,1753113019.467 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-07-21T15:50:19.640Z,1753113019.640 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-07-21T15:50:19.641Z,1753113019.641 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-07-21T15:50:19.714Z,1753113019.714 [VerticalControl](DEBUG): Construct VerticalControl.
2025-07-21T15:50:19.777Z,1753113019.777 [VerticalControl] Loaded
2025-07-21T15:50:19.777Z,1753113019.777 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-07-21T15:50:19.780Z,1753113019.780 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-07-21T15:50:19.829Z,1753113019.829 [HorizontalControl] Loaded
2025-07-21T15:50:19.829Z,1753113019.829 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-07-21T15:50:19.832Z,1753113019.832 [SpeedControl](DEBUG): Construct SpeedControl.
2025-07-21T15:50:19.835Z,1753113019.835 [SpeedControl] Loaded
2025-07-21T15:50:19.835Z,1753113019.835 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-07-21T15:50:19.838Z,1753113019.838 [LoopControl](DEBUG): Construct LoopControl.
2025-07-21T15:50:19.838Z,1753113019.838 [LoopControl] Loaded
2025-07-21T15:50:19.839Z,1753113019.839 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-07-21T15:50:19.839Z,1753113019.839 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-07-21T15:50:19.840Z,1753113019.840 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-07-21T15:50:19.927Z,1753113019.927 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-07-21T15:50:19.928Z,1753113019.928 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-07-21T15:50:20.928Z,1753113020.928 [AHRS_M2] Loaded
2025-07-21T15:50:20.929Z,1753113020.929 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-07-21T15:50:21.183Z,1753113021.183 [BackseatComponent] Loaded
2025-07-21T15:50:21.183Z,1753113021.183 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-07-21T15:50:21.184Z,1753113021.184 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408BA4E0
2025-07-21T15:50:21.185Z,1753113021.185 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 923
2025-07-21T15:50:21.188Z,1753113021.188 [LcmUniversalReporter] Loaded
2025-07-21T15:50:21.188Z,1753113021.188 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-07-21T15:50:22.050Z,1753113022.050 [BPC1] Loaded
2025-07-21T15:50:22.050Z,1753113022.050 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-07-21T15:50:22.150Z,1753113022.150 [DAT] Loaded
2025-07-21T15:50:22.150Z,1753113022.150 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-07-21T15:50:22.151Z,1753113022.151 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 408EA4E0
2025-07-21T15:50:22.151Z,1753113022.151 [DAT ThreadHandler](INFO): Protected caller Thread ID is 924
2025-07-21T15:50:22.480Z,1753113022.480 [DataOverHttps] Loaded
2025-07-21T15:50:22.481Z,1753113022.481 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-07-21T15:50:22.482Z,1753113022.482 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4091A4E0
2025-07-21T15:50:22.482Z,1753113022.482 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 925
2025-07-21T15:50:22.506Z,1753113022.506 [Depth_Keller] Loaded
2025-07-21T15:50:22.506Z,1753113022.506 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-07-21T15:50:22.511Z,1753113022.511 [DropWeight] Loaded
2025-07-21T15:50:22.511Z,1753113022.511 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-07-21T15:50:22.578Z,1753113022.578 [NAL9602] Loaded
2025-07-21T15:50:22.578Z,1753113022.578 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-07-21T15:50:22.609Z,1753113022.609 [Onboard] Loaded
2025-07-21T15:50:22.609Z,1753113022.609 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-07-21T15:50:22.610Z,1753113022.610 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4094A4E0
2025-07-21T15:50:22.611Z,1753113022.611 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 926
2025-07-21T15:50:22.625Z,1753113022.625 [Power24vConverter] Loaded
2025-07-21T15:50:22.625Z,1753113022.625 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-07-21T15:50:22.641Z,1753113022.641 [Radio_Surface] Loaded
2025-07-21T15:50:22.642Z,1753113022.642 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-07-21T15:50:22.643Z,1753113022.643 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4097A4E0
2025-07-21T15:50:22.643Z,1753113022.643 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 927
2025-07-21T15:50:22.659Z,1753113022.659 [Sonardyne_Nano] Loaded
2025-07-21T15:50:22.659Z,1753113022.659 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-07-21T15:50:22.660Z,1753113022.660 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-07-21T15:50:22.661Z,1753113022.661 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-07-21T15:50:22.677Z,1753113022.677 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-07-21T15:50:22.677Z,1753113022.677 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-07-21T15:50:22.725Z,1753113022.725 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-07-21T15:50:22.726Z,1753113022.726 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-07-21T15:50:22.926Z,1753113022.926 [SBIT](DEBUG): Construct Startup Built In Test.
2025-07-21T15:50:22.935Z,1753113022.935 [SBIT] Loaded
2025-07-21T15:50:22.936Z,1753113022.936 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-07-21T15:50:22.939Z,1753113022.939 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-07-21T15:50:22.952Z,1753113022.952 [IBIT] Loaded
2025-07-21T15:50:22.952Z,1753113022.952 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-07-21T15:50:22.958Z,1753113022.958 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-07-21T15:50:23.040Z,1753113023.040 [CBIT] Loaded
2025-07-21T15:50:23.041Z,1753113023.041 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-07-21T15:50:23.074Z,1753113023.074 [GFScanner] Loaded
2025-07-21T15:50:23.075Z,1753113023.075 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-07-21T15:50:23.075Z,1753113023.075 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-07-21T15:50:23.076Z,1753113023.076 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-07-21T15:50:23.499Z,1753113023.499 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-07-21T15:50:23.500Z,1753113023.500 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-07-21T15:50:23.572Z,1753113023.572 [DepthRateCalculator] Loaded
2025-07-21T15:50:23.572Z,1753113023.572 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-07-21T15:50:23.577Z,1753113023.577 [PitchRateCalculator] Loaded
2025-07-21T15:50:23.578Z,1753113023.578 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-07-21T15:50:23.588Z,1753113023.588 [SpeedCalculator] Loaded
2025-07-21T15:50:23.589Z,1753113023.589 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-07-21T15:50:23.593Z,1753113023.593 [YawRateCalculator] Loaded
2025-07-21T15:50:23.594Z,1753113023.594 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-07-21T15:50:23.613Z,1753113023.613 [ElevatorOffsetCalculator] Loaded
2025-07-21T15:50:23.614Z,1753113023.614 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-07-21T15:50:23.614Z,1753113023.614 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-07-21T15:50:23.615Z,1753113023.615 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-07-21T15:50:23.837Z,1753113023.837 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-07-21T15:50:23.838Z,1753113023.838 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-07-21T15:50:23.979Z,1753113023.979 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-07-21T15:50:23.980Z,1753113023.980 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-07-21T15:50:23.995Z,1753113023.995 [NavChart] Loaded
2025-07-21T15:50:23.995Z,1753113023.995 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-07-21T15:50:24.001Z,1753113024.001 [UniversalFixResidualReporter] Loaded
2025-07-21T15:50:24.002Z,1753113024.002 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-07-21T15:50:24.002Z,1753113024.002 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-07-21T15:50:24.003Z,1753113024.003 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-07-21T15:50:24.174Z,1753113024.174 [BuoyancyServo] Loaded
2025-07-21T15:50:24.174Z,1753113024.174 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-07-21T15:50:24.198Z,1753113024.198 [ElevatorServo] Loaded
2025-07-21T15:50:24.199Z,1753113024.199 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-07-21T15:50:24.221Z,1753113024.221 [MassServo] Loaded
2025-07-21T15:50:24.222Z,1753113024.222 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-07-21T15:50:24.243Z,1753113024.243 [RudderServo] Loaded
2025-07-21T15:50:24.243Z,1753113024.243 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-07-21T15:50:24.261Z,1753113024.261 [ThrusterHE] Loaded
2025-07-21T15:50:24.261Z,1753113024.261 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-07-21T15:50:24.261Z,1753113024.261 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-07-21T15:50:24.262Z,1753113024.262 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-07-21T15:50:24.491Z,1753113024.491 [CTD_Seabird] Loaded
2025-07-21T15:50:24.492Z,1753113024.492 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-07-21T15:50:24.493Z,1753113024.493 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40C094E0
2025-07-21T15:50:24.493Z,1753113024.493 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 928
2025-07-21T15:50:24.533Z,1753113024.533 [ESPComponent] Loaded
2025-07-21T15:50:24.533Z,1753113024.533 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2025-07-21T15:50:24.556Z,1753113024.556 [PAR_Licor] Loaded
2025-07-21T15:50:24.556Z,1753113024.556 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-07-21T15:50:24.603Z,1753113024.603 [WetLabsBB2FL] Loaded
2025-07-21T15:50:24.603Z,1753113024.603 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-07-21T15:50:24.604Z,1753113024.604 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C394E0
2025-07-21T15:50:24.605Z,1753113024.605 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 929
2025-07-21T15:50:24.606Z,1753113024.606 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-07-21T15:50:24.612Z,1753113024.612 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-07-21T15:50:24.615Z,1753113024.615 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-07-21T15:50:24.627Z,1753113024.627 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-07-21T15:50:24.628Z,1753113024.628 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C694E0
2025-07-21T15:50:24.628Z,1753113024.628 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 930
2025-07-21T15:50:24.632Z,1753113024.632 [Supervisor](INFO): Main Thread ID is 830
2025-07-21T15:50:24.633Z,1753113024.633 [Supervisor](DEBUG): Running supervisor.
2025-07-21T15:50:24.633Z,1753113024.633 [CommandExec ThreadHandler](INFO): Handler Thread ID is 931
2025-07-21T15:50:24.634Z,1753113024.634 [CommandExec](INFO): Initializing the command executive.
2025-07-21T15:50:24.635Z,1753113024.635 [CommandLine ThreadHandler](INFO): Handler Thread ID is 932
2025-07-21T15:50:24.638Z,1753113024.638 [controlThread ThreadHandler](INFO): Handler Thread ID is 933
2025-07-21T15:50:24.638Z,1753113024.638 [controlThread](DEBUG): Initializing ControlThread
2025-07-21T15:50:24.639Z,1753113024.639 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-07-21T15:50:24.641Z,1753113024.641 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-07-21T15:50:24.641Z,1753113024.641 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-07-21T15:50:24.642Z,1753113024.642 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-07-21T15:50:24.645Z,1753113024.645 [SBIT](INFO): Initialize SBIT Component.
2025-07-21T15:50:24.645Z,1753113024.645 [SBIT](IMPORTANT): git: 2025-07-16
2025-07-21T15:50:24.645Z,1753113024.645 [SBIT](INFO): git hash: d69d2715ddd00fa751675e8858faf22d094d5d81
2025-07-21T15:50:24.646Z,1753113024.646 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-07-21T15:50:24.647Z,1753113024.647 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #1 PREEMPT Tue Nov 28 15:04:22 PST 2023
2025-07-21T15:50:24.648Z,1753113024.648 [SBIT](INFO): Beginning SBIT in 118.000000 seconds.
2025-07-21T15:50:24.648Z,1753113024.648 [IBIT](INFO): Initialize IBIT Component.
2025-07-21T15:50:24.649Z,1753113024.649 [CBIT](DEBUG): Initialize CBIT Component.
2025-07-21T15:50:24.650Z,1753113024.650 [logger ThreadHandler](INFO): Handler Thread ID is 934
2025-07-21T15:50:24.660Z,1753113024.660 [CBIT](DEBUG): Initialized mux pins.
2025-07-21T15:50:24.660Z,1753113024.660 [CBIT](DEBUG): Initializing the watchdog timer.
2025-07-21T15:50:24.668Z,1753113024.668 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 935
2025-07-21T15:50:24.684Z,1753113024.684 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-07-21T15:50:24.684Z,1753113024.684 [CBIT](DEBUG): Initializing heartbeat.
2025-07-21T15:50:24.685Z,1753113024.685 [DAT ThreadHandler](INFO): Handler Thread ID is 936
2025-07-21T15:50:24.686Z,1753113024.686 [DAT](INFO): Powering up
2025-07-21T15:50:24.686Z,1753113024.686 [DAT](DEBUG): Initializing DAT.
2025-07-21T15:50:24.690Z,1753113024.690 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 938
2025-07-21T15:50:24.691Z,1753113024.691 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-07-21T15:50:24.704Z,1753113024.704 [Onboard ThreadHandler](INFO): Handler Thread ID is 939
2025-07-21T15:50:24.722Z,1753113024.722 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 940
2025-07-21T15:50:24.741Z,1753113024.741 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 941
2025-07-21T15:50:24.741Z,1753113024.741 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-07-21T15:50:24.745Z,1753113024.745 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 943
2025-07-21T15:50:24.748Z,1753113024.748 [WetLabsBB2FL](INFO): Powering up
2025-07-21T15:50:24.749Z,1753113024.749 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 945
2025-07-21T15:50:24.756Z,1753113024.756 [CBIT](DEBUG): Deactivating emergency mode.
2025-07-21T15:50:24.757Z,1753113024.757 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-07-21T15:50:24.757Z,1753113024.757 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-07-21T15:50:24.757Z,1753113024.757 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-07-21T15:50:24.757Z,1753113024.757 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-07-21T15:50:24.757Z,1753113024.757 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-07-21T15:50:24.757Z,1753113024.757 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-07-21T15:50:24.758Z,1753113024.758 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-07-21T15:50:24.758Z,1753113024.758 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-07-21T15:50:24.796Z,1753113024.796 [CBIT](DEBUG): Backplane powered.
2025-07-21T15:50:24.796Z,1753113024.796 [GFScanner](DEBUG): Initializing GFScanner
2025-07-21T15:50:24.796Z,1753113024.796 [GFScanner](DEBUG): Deactivating GF circuits.
2025-07-21T15:50:24.800Z,1753113024.800 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-07-21T15:50:24.801Z,1753113024.801 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-07-21T15:50:24.801Z,1753113024.801 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-07-21T15:50:24.801Z,1753113024.801 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-07-21T15:50:24.802Z,1753113024.802 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-07-21T15:50:24.803Z,1753113024.803 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-07-21T15:50:24.803Z,1753113024.803 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-07-21T15:50:24.812Z,1753113024.812 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-07-21T15:50:24.813Z,1753113024.813 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-07-21T15:50:24.813Z,1753113024.813 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-07-21T15:50:24.820Z,1753113024.820 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-07-21T15:50:24.904Z,1753113024.904 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="Startup">
    <Guidance:GoToSurface>
        <Progression />
    </Guidance:GoToSurface>
    <Aggregate Id="StartupSatComms">
        <Sequence />
        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix />
        </ReadDatum>
        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications />
        </ReadDatum>
    </Aggregate>
</Mission>

2025-07-21T15:50:24.908Z,1753113024.908 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-07-21T15:50:24.953Z,1753113024.953 [MissionManager](DEBUG): 
#   Copyright (c) 2024 MBARI
#   MBARI Proprietary Information. Confidential. All Rights Reserved
#   Unauthorized copying or distribution of this file via any medium is strictly
#   prohibited.
#
#   WARNING - This file contains information whose export is restricted by the
#   Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
#   amended. Violations of these export laws are subject to severe civil and/or
#   criminal penalties.

mission Startup {
  behavior Guidance:GoToSurface {
    run in progression
  }

  aggregate StartupSatComms {
    run in sequence

    readDatum {
      timeout duration=P1M

      Universal:latitude_fix
    }

    readDatum {
      timeout duration=P1M

      Universal:platform_communications
    }
  }
}


2025-07-21T15:50:24.953Z,1753113024.953 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-07-21T15:50:24.954Z,1753113024.954 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-07-21T15:50:24.958Z,1753113024.958 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-07-21T15:50:25.128Z,1753113025.128 [Radio_Surface](INFO): Powering up
2025-07-21T15:50:25.287Z,1753113025.287 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="Default">
    <DefineArg Name="ElapsedSinceDefaultStarted">
        <Units:minute />
        <Value>0</Value>
    </DefineArg>
    <Guidance:Wait>
        <Sequence />
        <Setting>
            <Guidance:Wait.duration />
            <Units:second />
            <Value>13</Value>
        </Setting>
    </Guidance:Wait>
    <Guidance:GoToSurface>
        <Progression />
    </Guidance:GoToSurface>
    <Aggregate Id="CheckIn">
        <Sequence Repeat="288" />
        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M" />
            <Universal:time_fix />
        </ReadDatum>
        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P4H">
                <Guidance:Execute>
                    <Sequence />
                    <Setting>
                        <Guidance:Execute.command />
                        <String>Burn on</String>
                    </Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications />
        </ReadDatum>
        <Guidance:Wait>
            <Sequence />
            <Setting>
                <Guidance:Wait.duration />
                <Units:minute />
                <Value>5</Value>
            </Setting>
        </Guidance:Wait>
        <Assign>
            <Sequence />
            <Arg Name="ElapsedSinceDefaultStarted" />
            <Elapsed>
                <Universal:mission_started />
            </Elapsed>
        </Assign>
        <Syslog Severity="Important">
            Default mission has been running for 
            <Arg Name="ElapsedSinceDefaultStarted" />
            <Units:minute />
        </Syslog>
    </Aggregate>
    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>
    <Guidance:Execute>
        <Sequence />
        <Setting>
            <Guidance:Execute.command />
            <String>restart logs</String>
        </Setting>
    </Guidance:Execute>
</Mission>

2025-07-21T15:50:25.309Z,1753113025.309 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-07-21T15:50:25.310Z,1753113025.310 [Default:A.Wait](DEBUG): Construct Wait.
2025-07-21T15:50:25.312Z,1753113025.312 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-07-21T15:50:25.373Z,1753113025.373 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-07-21T15:50:25.376Z,1753113025.376 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-07-21T15:50:25.398Z,1753113025.398 [Default:E.Execute](DEBUG): Construct Execute.
2025-07-21T15:50:25.401Z,1753113025.401 [MissionManager](DEBUG): 
#   Copyright (c) 2024 MBARI
#   MBARI Proprietary Information. Confidential. All Rights Reserved
#   Unauthorized copying or distribution of this file via any medium is strictly
#   prohibited.
#
#   WARNING - This file contains information whose export is restricted by the
#   Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
#   amended. Violations of these export laws are subject to severe civil and/or
#   criminal penalties.

mission Default {
  arguments {
    ElapsedSinceDefaultStarted = 0 minute
  }

  behavior Guidance:Wait {
    """
    Wait a moment to see if the scheduler starts a new mission before
    starting to actually run Default.
    """

    run in sequence

    set duration = 13 second
  }

  behavior Guidance:GoToSurface {
    run in progression
  }

  aggregate CheckIn {
    run in sequence repeat=288

    readDatum id="Read_GPS" {
      timeout duration=P5M

      Universal:time_fix
    }

    readDatum id="Read_Iridium" {
      timeout duration=P4H {
        behavior Guidance:Execute {
          run in sequence

          set command = "Burn on"
        }

        syslog critical "Dropped weight due to communications timeout."
      }

      Universal:platform_communications
    }

    behavior Guidance:Wait {
      run in sequence

      set duration = 5 minute
    }

    assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )

    syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
  }

  syslog important "Restarting logs and Default mission."

  behavior Guidance:Execute {
    run in sequence

    set command = "restart logs"
  }
}


2025-07-21T15:50:25.412Z,1753113025.412 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,ESPComponent,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,LcmUniversalReporter,Reporter,LogSplitter,
2025-07-21T15:50:25.424Z,1753113025.424 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-07-21T15:50:25.500Z,1753113025.500 [Depth_Keller](INFO): Initializing.
2025-07-21T15:50:25.502Z,1753113025.501 [Power24vConverter](INFO): Powering up.
2025-07-21T15:50:25.502Z,1753113025.502 [Sonardyne_Nano](INFO): Initializing.
2025-07-21T15:50:25.529Z,1753113025.529 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-07-21T15:50:25.593Z,1753113025.593 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-07-21T15:50:25.600Z,1753113025.600 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-07-21T15:50:25.601Z,1753113025.601 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-07-21T15:50:25.608Z,1753113025.608 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-07-21T15:50:25.609Z,1753113025.609 [MassServo](DEBUG): Initializing EZServoServo.
2025-07-21T15:50:25.616Z,1753113025.616 [MassServo](DEBUG): Initializing MassServo.
2025-07-21T15:50:25.617Z,1753113025.617 [RudderServo](DEBUG): Initializing EZServoServo.
2025-07-21T15:50:25.624Z,1753113025.624 [RudderServo](DEBUG): Initializing RudderServo.
2025-07-21T15:50:25.625Z,1753113025.625 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-07-21T15:50:25.632Z,1753113025.632 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-07-21T15:50:26.856Z,1753113026.856 [WetLabsBB2FL](INFO): Powering down
2025-07-21T15:50:27.443Z,1753113027.443 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-07-21T15:50:28.732Z,1753113028.732 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2311
2025-07-21T15:50:29.088Z,1753113029.088 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-07-21T15:50:31.341Z,1753113031.341 [ThrusterHE](ERROR): Zero Speed Commanded.
2025-07-21T15:50:38.049Z,1753113038.049 [DAT](INFO): DAT read: 

2025-07-21T15:50:38.050Z,1753113038.050 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2025-07-21T15:50:39.813Z,1753113039.813 [DAT](INFO): DAT read: MF Frequency Band

2025-07-21T15:50:39.814Z,1753113039.814 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2025-07-21T15:50:39.815Z,1753113039.815 [DAT](INFO): DAT read: Jul 21 2025 15:50:33 

2025-07-21T15:50:40.821Z,1753113040.821 [DAT](INFO): DAT read: Features enabled [Bearing]

2025-07-21T15:50:40.823Z,1753113040.823 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2025-07-21T15:50:40.823Z,1753113040.823 [DAT](INFO): commRate: 800
2025-07-21T15:50:42.888Z,1753113042.888 [DAT](INFO): entering command mode
2025-07-21T15:50:43.088Z,1753113043.088 [DAT](INFO): DAT read: 

2025-07-21T15:50:43.089Z,1753113043.089 [DAT](INFO): DAT read: user:1>

2025-07-21T15:50:43.089Z,1753113043.089 [DAT](INFO): setting verbose to 3
2025-07-21T15:50:43.341Z,1753113043.341 [DAT](INFO): DAT read: user:1>

2025-07-21T15:50:43.342Z,1753113043.342 [DAT](INFO): DAT read: Verbose         | 3           

2025-07-21T15:50:43.342Z,1753113043.342 [DAT](INFO): set verbose to 3
2025-07-21T15:50:43.342Z,1753113043.342 [DAT](INFO): setting DatVerbose to 27440
2025-07-21T15:50:43.592Z,1753113043.592 [DAT](INFO): DAT read: user:2>

2025-07-21T15:50:43.593Z,1753113043.593 [DAT](INFO): DAT read: DatVerbose      | 27440       

2025-07-21T15:50:43.594Z,1753113043.594 [DAT](INFO): set DatVerbose to 27440
2025-07-21T15:50:43.594Z,1753113043.594 [DAT](INFO): setting transmit power to 8
2025-07-21T15:50:43.844Z,1753113043.844 [DAT](INFO): DAT read: user:3>

2025-07-21T15:50:43.845Z,1753113043.845 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2025-07-21T15:50:43.846Z,1753113043.846 [DAT](INFO): set transmit power to 8
2025-07-21T15:50:43.846Z,1753113043.846 [DAT](INFO): setting local address to 1
2025-07-21T15:50:44.097Z,1753113044.097 [DAT](INFO): DAT read: user:4>

2025-07-21T15:50:44.098Z,1753113044.098 [DAT](INFO): DAT read: LocalAddr       | 1           

2025-07-21T15:50:44.098Z,1753113044.098 [DAT](INFO): set local address to 1
2025-07-21T15:50:44.099Z,1753113044.099 [DAT](INFO): Setting time to: 15:50:44 And date to:7/21/2025
2025-07-21T15:50:44.349Z,1753113044.349 [DAT](INFO): DAT read: user:5>

2025-07-21T15:50:44.350Z,1753113044.350 [DAT](INFO): DAT read: Mon Jul 21, 2025  15:50:44  

2025-07-21T15:50:44.350Z,1753113044.350 [DAT](INFO): Local DAT time set to Mon Jul 21, 2025  15:50:44  

2025-07-21T15:50:52.933Z,1753113052.933 [NAL9602](INFO): Powering up NAL9602
2025-07-21T15:51:03.834Z,1753113063.834 [NAL9602](INFO): NAL9602 initialized
2025-07-21T15:51:32.106Z,1753113092.106 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T15:51:45.841Z,1753113105.841 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T15:51:59.174Z,1753113119.174 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T15:52:12.910Z,1753113132.910 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T15:52:23.434Z,1753113143.434 [SBIT](IMPORTANT): Beginning Startup BIT
2025-07-21T15:52:23.439Z,1753113143.439 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-07-21T15:52:27.052Z,1753113147.052 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T15:52:27.977Z,1753113147.977 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.000204
CHAN A1 (24V): 0.000277
CHAN A2 (12V): 0.000825
CHAN A3 (5V): -0.001150
CHAN B0 (3.3V): 0.000662
CHAN B1 (3.15aV): 0.000213
CHAN B2 (3.15bV): -0.000334
CHAN B3 (GND): -0.000153
OPEN: 0.006198
Full Scale: +/- 1 mA
2025-07-21T15:52:40.786Z,1753113160.786 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T15:52:54.927Z,1753113174.927 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T15:52:58.866Z,1753113178.866 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004991
2025-07-21T15:53:08.670Z,1753113188.670 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T15:53:17.195Z,1753113197.195 [SBIT](IMPORTANT): SBIT PASSED
2025-07-21T15:53:17.195Z,1753113197.195 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-07-21T15:53:17.196Z,1753113197.196 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=15 count;

2025-07-21T15:53:17.196Z,1753113197.196 [SBIT](IMPORTANT): Express linearApproximation TrackAcousticContact.range_to_contact 15.000000 meter;

2025-07-21T15:53:17.196Z,1753113197.196 [SBIT](IMPORTANT): Express mostRecent _.planktivore_HM_AvgRois;

2025-07-21T15:53:17.196Z,1753113197.196 [SBIT](IMPORTANT): Express mostRecent _.planktivore_LM_AvgRois;

2025-07-21T15:53:17.197Z,1753113197.197 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=187.340798 cubic_centimeter;

2025-07-21T15:53:17.197Z,1753113197.197 [SBIT](IMPORTANT): VerticalControl.massDefault=24.710863 millimeter;

2025-07-21T15:53:17.197Z,1753113197.197 [SBIT](IMPORTANT): Waterlinked.loadAtStartup=0 bool;

2025-07-21T15:53:17.197Z,1753113197.197 [SBIT](IMPORTANT): Waypoint.wptRecalcBearing=1 bool;

2025-07-21T15:53:17.597Z,1753113197.597 [MissionManager](IMPORTANT): Started mission Startup
2025-07-21T15:53:17.597Z,1753113197.597 [Startup] Running Loop=1
2025-07-21T15:53:17.597Z,1753113197.597 [Startup](DEBUG): Aggregate::initialize Startup
2025-07-21T15:53:17.597Z,1753113197.597 [Startup:A.GoToSurface] Running Loop=1
2025-07-21T15:53:17.598Z,1753113197.598 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-07-21T15:53:17.598Z,1753113197.598 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-07-21T15:53:17.599Z,1753113197.599 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-07-21T15:53:17.599Z,1753113197.599 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-07-21T15:53:17.599Z,1753113197.599 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-07-21T15:53:17.600Z,1753113197.600 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-07-21T15:53:17.600Z,1753113197.600 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-07-21T15:53:17.602Z,1753113197.602 [Startup:StartupSatComms] Running Loop=1
2025-07-21T15:53:17.602Z,1753113197.602 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-07-21T15:53:17.602Z,1753113197.602 [Startup:StartupSatComms:A] Running Loop=1
2025-07-21T15:53:17.995Z,1753113197.995 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-07-21T15:53:22.865Z,1753113202.865 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T15:53:24.875Z,1753113204.875 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-07-21T15:53:24.876Z,1753113204.876 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-21T15:53:24.917Z,1753113204.917 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-21T15:53:25.281Z,1753113205.281 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-21T15:53:25.281Z,1753113205.281 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-07-21T15:54:17.829Z,1753113257.829 [Startup:StartupSatComms:A](INFO): Timed out from 2025-07-21T15:53:17.6Z
2025-07-21T15:54:17.829Z,1753113257.829 [Startup:StartupSatComms:A] Stopped
2025-07-21T15:54:17.829Z,1753113257.829 [Startup:StartupSatComms:B] Running Loop=1
2025-07-21T15:54:18.177Z,1753113258.177 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-07-21T15:54:25.173Z,1753113265.173 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20250721T154256/Courier0004.lzma
2025-07-21T15:54:26.174Z,1753113266.174 [DataOverHttps](INFO): Moved sent file to Logs/20250721T154256/Courier0004.lzma.bak
2025-07-21T15:54:26.174Z,1753113266.174 [DataOverHttps](INFO): SBD MOMSN=25562672
2025-07-21T15:54:41.911Z,1753113281.911 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250721T155012/Courier0000.lzma
2025-07-21T15:54:42.914Z,1753113282.914 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Courier0000.lzma.bak
2025-07-21T15:54:42.914Z,1753113282.914 [DataOverHttps](INFO): SBD MOMSN=25562676
2025-07-21T15:54:58.570Z,1753113298.570 [DataOverHttps](INFO): Sending 415 bytes from file Logs/20250721T153712/Express0001.lzma
2025-07-21T15:54:59.570Z,1753113299.570 [DataOverHttps](INFO): Moved sent file to Logs/20250721T153712/Express0001.lzma.bak
2025-07-21T15:54:59.570Z,1753113299.570 [DataOverHttps](INFO): SBD MOMSN=25562683
2025-07-21T15:55:15.435Z,1753113315.435 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250721T153850/Express0001.lzma
2025-07-21T15:55:17.994Z,1753113317.994 [Startup:StartupSatComms:B](INFO): Timed out from 2025-07-21T15:54:17.8Z
2025-07-21T15:55:17.994Z,1753113317.994 [Startup:StartupSatComms:B] Stopped
2025-07-21T15:55:17.994Z,1753113317.994 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-07-21T15:55:17.994Z,1753113317.994 [Startup:StartupSatComms] Stopped
2025-07-21T15:55:17.995Z,1753113317.995 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-07-21T15:55:17.996Z,1753113317.996 [Startup](INFO): Completed Startup
2025-07-21T15:55:17.996Z,1753113317.996 [MissionManager](INFO): Startup is completed.
2025-07-21T15:55:17.996Z,1753113317.996 [MissionManager](INFO): Uninitializing Mission Startup
2025-07-21T15:55:17.996Z,1753113317.996 [Startup] Stopped
2025-07-21T15:55:17.996Z,1753113317.996 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-07-21T15:55:17.996Z,1753113317.996 [Startup:A.GoToSurface] Stopped
2025-07-21T15:55:17.996Z,1753113317.996 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-07-21T15:55:18.393Z,1753113318.393 [MissionManager](IMPORTANT): Started mission Default
2025-07-21T15:55:18.393Z,1753113318.393 [Default] Running Loop=1
2025-07-21T15:55:18.393Z,1753113318.393 [Default](DEBUG): Aggregate::initialize Default
2025-07-21T15:55:18.393Z,1753113318.393 [Default:B.GoToSurface] Running Loop=1
2025-07-21T15:55:18.393Z,1753113318.393 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-07-21T15:55:18.394Z,1753113318.394 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-07-21T15:55:18.394Z,1753113318.394 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-07-21T15:55:18.394Z,1753113318.394 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-07-21T15:55:18.395Z,1753113318.395 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-07-21T15:55:18.395Z,1753113318.395 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-07-21T15:55:18.395Z,1753113318.395 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-07-21T15:55:18.395Z,1753113318.395 [Default:A.Wait] Running Loop=1
2025-07-21T15:55:18.395Z,1753113318.395 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-07-21T15:55:22.461Z,1753113322.461 [DataOverHttps](INFO): Moved sent file to Logs/20250721T153850/Express0001.lzma.bak
2025-07-21T15:55:22.462Z,1753113322.462 [DataOverHttps](INFO): SBD MOMSN=25562691
2025-07-21T15:55:31.706Z,1753113331.706 [Default:A.Wait](INFO): Done Waiting.
2025-07-21T15:55:31.706Z,1753113331.706 [Default:A.Wait] Stopped
2025-07-21T15:55:31.706Z,1753113331.706 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T15:55:32.113Z,1753113332.113 [Default:CheckIn] Running Loop=1
2025-07-21T15:55:32.113Z,1753113332.113 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T15:55:32.113Z,1753113332.113 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T15:55:32.510Z,1753113332.510 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-07-21T15:55:57.138Z,1753113357.138 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T15:56:06.841Z,1753113366.841 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-07-21T15:56:09.664Z,1753113369.664 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T15:56:22.994Z,1753113382.994 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T15:56:22.998Z,1753113382.998 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-07-21T15:56:25.834Z,1753113385.834 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2025-07-21T15:56:25.834Z,1753113385.834 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-21T15:56:25.845Z,1753113385.845 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-21T15:56:26.291Z,1753113386.291 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-21T15:56:26.291Z,1753113386.291 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2025-07-21T15:56:36.326Z,1753113396.326 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T15:56:48.446Z,1753113408.446 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T15:57:01.374Z,1753113421.374 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T15:57:14.302Z,1753113434.302 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T15:57:27.230Z,1753113447.230 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T15:57:39.364Z,1753113459.364 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T15:57:45.717Z,1753113465.717 [CommandExec](IMPORTANT): got command burn on
2025-07-21T15:57:45.717Z,1753113465.717 [CommandExec](IMPORTANT): Activating dropweight wire
2025-07-21T15:57:52.280Z,1753113472.280 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T15:58:04.802Z,1753113484.802 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T15:58:12.496Z,1753113492.496 [CommandExec](IMPORTANT): got command burn off
2025-07-21T15:58:12.496Z,1753113492.496 [CommandExec](IMPORTANT): Deactivating dropweight wire
2025-07-21T15:58:17.326Z,1753113497.326 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7).
2025-07-21T15:58:17.332Z,1753113497.332 [BPC1](ERROR): Battery stick #24 (s/n: 01D3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-07-21T15:58:17.333Z,1753113497.333 [BPC1](ERROR): Battery stick #29 (s/n: 00A7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-21T15:58:17.334Z,1753113497.334 [BPC1](ERROR): Battery stick #51 (s/n: 01D6) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-07-21T15:58:17.334Z,1753113497.334 [BPC1](ERROR): Battery stick #57 (s/n: 019F) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-07-21T15:58:17.335Z,1753113497.335 [BPC1](ERROR): Battery stick #59 (s/n: 0184) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2025-07-21T15:58:17.335Z,1753113497.335 [BPC1](INFO): Calculating totals. Valid battery stick count: 39. Valid reserve battery stick count: 4.
2025-07-21T15:58:17.359Z,1753113497.359 [BPC1](INFO): Received data from all battery sticks.
2025-07-21T15:58:58.385Z,1753113538.385 [CommandExec](IMPORTANT): got command configSet list
2025-07-21T15:58:58.385Z,1753113538.385 [CommandExec](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-07-21T15:58:58.386Z,1753113538.386 [CommandExec](IMPORTANT): BPC1.batteryMissingStickThreshold=15 count;

2025-07-21T15:58:58.387Z,1753113538.387 [CommandExec](IMPORTANT): Express linearApproximation TrackAcousticContact.range_to_contact 15.000000 meter;

2025-07-21T15:58:58.387Z,1753113538.387 [CommandExec](IMPORTANT): Express mostRecent _.planktivore_HM_AvgRois;

2025-07-21T15:58:58.388Z,1753113538.388 [CommandExec](IMPORTANT): Express mostRecent _.planktivore_LM_AvgRois;

2025-07-21T15:58:58.389Z,1753113538.389 [CommandExec](IMPORTANT): VerticalControl.buoyancyNeutral=187.340798 cubic_centimeter;

2025-07-21T15:58:58.389Z,1753113538.389 [CommandExec](IMPORTANT): VerticalControl.massDefault=24.710863 millimeter;

2025-07-21T15:58:58.390Z,1753113538.390 [CommandExec](IMPORTANT): Waterlinked.loadAtStartup=0 bool;

2025-07-21T15:58:58.391Z,1753113538.391 [CommandExec](IMPORTANT): Waypoint.wptRecalcBearing=1 bool;

2025-07-21T15:59:26.833Z,1753113566.833 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2025-07-21T15:59:26.833Z,1753113566.833 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-21T15:59:26.872Z,1753113566.872 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-21T15:59:27.242Z,1753113567.242 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-21T15:59:27.242Z,1753113567.242 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2025-07-21T16:00:32.290Z,1753113632.290 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-21T15:55:32.1Z
2025-07-21T16:00:32.290Z,1753113632.290 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T16:00:32.291Z,1753113632.291 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T16:00:32.709Z,1753113632.709 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-07-21T16:00:33.389Z,1753113633.389 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20250721T155012/Courier0004.lzma
2025-07-21T16:00:35.386Z,1753113635.386 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Courier0004.lzma.bak
2025-07-21T16:00:35.386Z,1753113635.386 [DataOverHttps](INFO): SBD MOMSN=25562699
2025-07-21T16:00:51.027Z,1753113651.027 [DataOverHttps](INFO): Sending 916 bytes from file Logs/20250721T154256/Express0001.lzma
2025-07-21T16:00:53.030Z,1753113653.030 [DataOverHttps](INFO): Moved sent file to Logs/20250721T154256/Express0001.lzma.bak
2025-07-21T16:00:53.030Z,1753113653.030 [DataOverHttps](INFO): SBD MOMSN=25562701
2025-07-21T16:01:06.665Z,1753113666.665 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-07-21T16:01:06.665Z,1753113666.665 [NAL9602] Data Fault, FailCount= 1
2025-07-21T16:01:06.665Z,1753113666.665 [NAL9602](ERROR): Data Fault
2025-07-21T16:01:06.699Z,1753113666.699 [CBIT](ERROR): Data Fault in component: NAL9602
2025-07-21T16:01:07.057Z,1753113667.057 [NAL9602](INFO): Powering down
2025-07-21T16:01:07.892Z,1753113667.892 [CBIT](INFO): Clearing failed state for component NAL9602
2025-07-21T16:01:07.893Z,1753113667.893 [NAL9602] No Fault, FailCount= 1
2025-07-21T16:01:08.649Z,1753113668.649 [DataOverHttps](INFO): Sending 111 bytes from file Logs/20250721T154256/Express0005.lzma
2025-07-21T16:01:09.650Z,1753113669.650 [DataOverHttps](INFO): Moved sent file to Logs/20250721T154256/Express0005.lzma.bak
2025-07-21T16:01:09.650Z,1753113669.650 [DataOverHttps](INFO): SBD MOMSN=25562722
2025-07-21T16:01:24.338Z,1753113684.338 [DataOverHttps](INFO): Sending 914 bytes from file Logs/20250721T155012/Express0001.lzma
2025-07-21T16:01:35.377Z,1753113695.377 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Express0001.lzma.bak
2025-07-21T16:01:35.378Z,1753113695.378 [DataOverHttps](INFO): SBD MOMSN=25562725
2025-07-21T16:01:37.361Z,1753113697.361 [NAL9602](INFO): Powering up NAL9602
2025-07-21T16:01:48.265Z,1753113708.265 [NAL9602](INFO): NAL9602 initialized
2025-07-21T16:02:27.944Z,1753113747.944 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2025-07-21T16:02:27.944Z,1753113747.944 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-21T16:02:27.967Z,1753113747.967 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-21T16:02:28.370Z,1753113748.370 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-21T16:02:28.370Z,1753113748.370 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2025-07-21T16:02:39.693Z,1753113759.693 [DataOverHttps](INFO): Sending 580 bytes from file Logs/20250721T155012/Express0005.lzma
2025-07-21T16:02:45.714Z,1753113765.714 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Express0005.lzma.bak
2025-07-21T16:02:45.714Z,1753113765.714 [DataOverHttps](INFO): SBD MOMSN=25562748
2025-07-21T16:02:46.934Z,1753113766.934 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T16:02:46.934Z,1753113766.934 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T16:02:46.934Z,1753113766.934 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T16:05:28.916Z,1753113928.916 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2025-07-21T16:05:28.916Z,1753113928.916 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-21T16:05:28.927Z,1753113928.927 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-21T16:05:29.330Z,1753113929.330 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-21T16:05:29.330Z,1753113929.330 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2025-07-21T16:07:47.509Z,1753114067.509 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T16:07:47.510Z,1753114067.510 [Default:CheckIn:C.Wait] Stopped
2025-07-21T16:07:47.510Z,1753114067.510 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T16:07:47.510Z,1753114067.510 [Default:CheckIn:D] Running Loop=1
2025-07-21T16:07:47.920Z,1753114067.920 [Default:CheckIn:D] Stopped
2025-07-21T16:07:47.920Z,1753114067.920 [Default:CheckIn:E] Running Loop=1
2025-07-21T16:07:48.318Z,1753114068.318 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  12.492116 min 
2025-07-21T16:07:48.318Z,1753114068.318 [Default:CheckIn:E] Stopped
2025-07-21T16:07:48.318Z,1753114068.318 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T16:07:48.318Z,1753114068.318 [Default:CheckIn] Stopped
2025-07-21T16:07:48.318Z,1753114068.318 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T16:07:48.318Z,1753114068.318 [Default:CheckIn](INFO): Running loop #2
2025-07-21T16:07:48.318Z,1753114068.318 [Default:CheckIn] Running Loop=2
2025-07-21T16:07:48.318Z,1753114068.318 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T16:07:48.319Z,1753114068.319 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T16:08:29.929Z,1753114109.929 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2025-07-21T16:08:29.929Z,1753114109.929 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-21T16:08:29.939Z,1753114109.939 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-21T16:08:30.342Z,1753114110.342 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-21T16:08:30.342Z,1753114110.342 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2025-07-21T16:11:30.922Z,1753114290.922 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2025-07-21T16:11:30.922Z,1753114290.922 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-21T16:11:30.933Z,1753114290.933 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-21T16:11:31.339Z,1753114291.339 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-21T16:11:31.339Z,1753114291.339 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2025-07-21T16:11:50.305Z,1753114310.305 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-07-21T16:11:50.305Z,1753114310.305 [NAL9602] Data Fault, FailCount= 2
2025-07-21T16:11:50.305Z,1753114310.305 [NAL9602](ERROR): Data Fault
2025-07-21T16:11:50.323Z,1753114310.323 [CBIT](ERROR): Data Fault in component: NAL9602
2025-07-21T16:11:50.710Z,1753114310.710 [NAL9602](INFO): Powering down
2025-07-21T16:11:51.534Z,1753114311.534 [CBIT](INFO): Clearing failed state for component NAL9602
2025-07-21T16:11:51.534Z,1753114311.534 [NAL9602] No Fault, FailCount= 2
2025-07-21T16:12:21.009Z,1753114341.009 [NAL9602](INFO): Powering up NAL9602
2025-07-21T16:12:31.917Z,1753114351.917 [NAL9602](INFO): NAL9602 initialized
2025-07-21T16:12:48.510Z,1753114368.510 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-21T16:07:48.3Z
2025-07-21T16:12:48.510Z,1753114368.510 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T16:12:48.510Z,1753114368.510 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T16:12:55.619Z,1753114375.619 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250721T155012/Courier0007.lzma
2025-07-21T16:13:08.670Z,1753114388.670 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Courier0007.lzma.bak
2025-07-21T16:13:08.670Z,1753114388.670 [DataOverHttps](INFO): SBD MOMSN=25562807
2025-07-21T16:13:34.340Z,1753114414.340 [DataOverHttps](INFO): Sending 190 bytes from file Logs/20250721T155012/Express0008.lzma
2025-07-21T16:13:45.382Z,1753114425.382 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Express0008.lzma.bak
2025-07-21T16:13:45.382Z,1753114425.382 [DataOverHttps](INFO): SBD MOMSN=25562809
2025-07-21T16:13:46.670Z,1753114426.670 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T16:13:46.671Z,1753114426.671 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T16:13:46.671Z,1753114426.671 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T16:14:31.936Z,1753114471.936 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2025-07-21T16:14:31.936Z,1753114471.936 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-21T16:14:31.947Z,1753114471.947 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-21T16:14:32.323Z,1753114472.323 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-21T16:14:32.323Z,1753114472.323 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2025-07-21T16:17:32.964Z,1753114652.964 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2025-07-21T16:17:32.964Z,1753114652.964 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-21T16:17:32.999Z,1753114652.999 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-21T16:17:33.375Z,1753114653.375 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-21T16:17:33.375Z,1753114653.375 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2025-07-21T16:18:47.314Z,1753114727.314 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T16:18:47.314Z,1753114727.314 [Default:CheckIn:C.Wait] Stopped
2025-07-21T16:18:47.314Z,1753114727.314 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T16:18:47.314Z,1753114727.314 [Default:CheckIn:D] Running Loop=1
2025-07-21T16:18:47.722Z,1753114727.722 [Default:CheckIn:D] Stopped
2025-07-21T16:18:47.722Z,1753114727.722 [Default:CheckIn:E] Running Loop=1
2025-07-21T16:18:48.106Z,1753114728.106 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  23.488806 min 
2025-07-21T16:18:48.106Z,1753114728.106 [Default:CheckIn:E] Stopped
2025-07-21T16:18:48.107Z,1753114728.107 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T16:18:48.107Z,1753114728.107 [Default:CheckIn] Stopped
2025-07-21T16:18:48.107Z,1753114728.107 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T16:18:48.107Z,1753114728.107 [Default:CheckIn](INFO): Running loop #3
2025-07-21T16:18:48.107Z,1753114728.107 [Default:CheckIn] Running Loop=3
2025-07-21T16:18:48.107Z,1753114728.107 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T16:18:48.107Z,1753114728.107 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T16:19:11.300Z,1753114751.300 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.chargePercent
2025-07-21T16:19:11.301Z,1753114751.301 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent 70.000000 %
2025-07-21T16:20:33.981Z,1753114833.981 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2025-07-21T16:20:33.981Z,1753114833.981 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-21T16:20:33.992Z,1753114833.992 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-21T16:20:34.401Z,1753114834.401 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-21T16:20:34.401Z,1753114834.401 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2025-07-21T16:22:33.532Z,1753114953.532 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-07-21T16:22:33.532Z,1753114953.532 [NAL9602] Data Fault, FailCount= 3
2025-07-21T16:22:33.532Z,1753114953.532 [NAL9602](ERROR): Data Fault
2025-07-21T16:22:33.550Z,1753114953.550 [CBIT](ERROR): Data Fault in component: NAL9602
2025-07-21T16:22:33.941Z,1753114953.941 [NAL9602](INFO): Powering down
2025-07-21T16:22:34.775Z,1753114954.775 [CBIT](INFO): Clearing failed state for component NAL9602
2025-07-21T16:22:34.775Z,1753114954.775 [NAL9602] No Fault, FailCount= 3
2025-07-21T16:23:04.236Z,1753114984.236 [NAL9602](INFO): Powering up NAL9602
2025-07-21T16:23:15.149Z,1753114995.149 [NAL9602](INFO): NAL9602 initialized
2025-07-21T16:23:34.952Z,1753115014.952 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2025-07-21T16:23:34.953Z,1753115014.953 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-21T16:23:34.964Z,1753115014.964 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-21T16:23:35.367Z,1753115015.367 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-21T16:23:35.367Z,1753115015.367 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2025-07-21T16:23:48.283Z,1753115028.283 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-21T16:18:48.1Z
2025-07-21T16:23:48.283Z,1753115028.283 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T16:23:48.283Z,1753115028.283 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T16:23:55.307Z,1753115035.307 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250721T155012/Courier0010.lzma
2025-07-21T16:24:06.350Z,1753115046.350 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Courier0010.lzma.bak
2025-07-21T16:24:06.350Z,1753115046.350 [DataOverHttps](INFO): SBD MOMSN=25562867
2025-07-21T16:24:31.996Z,1753115071.996 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20250721T155012/Express0011.lzma
2025-07-21T16:24:43.038Z,1753115083.038 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Express0011.lzma.bak
2025-07-21T16:24:43.038Z,1753115083.038 [DataOverHttps](INFO): SBD MOMSN=25562869
2025-07-21T16:24:44.070Z,1753115084.070 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T16:24:44.070Z,1753115084.070 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T16:24:44.070Z,1753115084.070 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T16:26:35.984Z,1753115195.984 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2025-07-21T16:26:35.985Z,1753115195.985 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-21T16:26:36.019Z,1753115196.019 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-21T16:26:36.429Z,1753115196.429 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-21T16:26:36.429Z,1753115196.429 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2025-07-21T16:29:30.713Z,1753115370.713 [DAT](INFO): DAT read: user:6>Rx Time:16:29:30.0641

2025-07-21T16:29:30.714Z,1753115370.714 [DAT](INFO): Rx dataTimestamp_ set to:1753115370.713402
2025-07-21T16:29:31.725Z,1753115371.725 [DAT](INFO): DAT read: $Error in header

2025-07-21T16:29:31.726Z,1753115371.726 [DAT](INFO): Received a bad header
2025-07-21T16:29:37.039Z,1753115377.039 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2025-07-21T16:29:37.039Z,1753115377.039 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-21T16:29:37.050Z,1753115377.050 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-21T16:29:37.474Z,1753115377.474 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-21T16:29:37.475Z,1753115377.475 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2025-07-21T16:29:42.569Z,1753115382.569 [DAT](INFO): DAT read: Rx Time:16:29:42.0002

2025-07-21T16:29:42.569Z,1753115382.569 [DAT](INFO): Rx dataTimestamp_ set to:1753115382.569020
2025-07-21T16:29:43.586Z,1753115383.586 [DAT](INFO): DAT read: 16:29:42.0002 LVL= 12400, 13617, 13106, 18163, AGC= 87, IDX= 492, 0.12, 1.637, 0.534, 0.519, 0.083, PHS= 1.521, 0.436, 0.424, RAW=  89.5, -37.7, CAL=  93.6, -36.1, ROT=  56.4,  36.1

2025-07-21T16:29:43.587Z,1753115383.587 [DAT](INFO): got valid direction response:
 16:29:42.0002 LVL= 12400, 13617, 13106, 18163, AGC= 87, IDX= 492, 0.12, 1.637, 0.534, 0.519, 0.083, PHS= 1.521, 0.436, 0.424, RAW=  89.5, -37.7, CAL=  93.6, -36.1, ROT=  56.4,  36.1

2025-07-21T16:29:43.588Z,1753115383.588 [DAT](INFO): DAT read: $Error in header

2025-07-21T16:29:43.589Z,1753115383.589 [DAT](INFO): Received a bad header
2025-07-21T16:29:43.600Z,1753115383.600 [DAT](INFO): #Rx 1: Read direction message, but no range.
2025-07-21T16:29:43.601Z,1753115383.601 [DAT](INFO): direction in FSK: [0.447135,0.672992,-0.589196]
2025-07-21T16:29:44.085Z,1753115384.085 [DAT](INFO): DAT read: Rx Time:16:29:43.3532

2025-07-21T16:29:44.085Z,1753115384.085 [DAT](INFO): Rx dataTimestamp_ set to:1753115384.084997
2025-07-21T16:29:44.709Z,1753115384.709 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T16:29:44.710Z,1753115384.710 [Default:CheckIn:C.Wait] Stopped
2025-07-21T16:29:44.710Z,1753115384.710 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T16:29:44.710Z,1753115384.710 [Default:CheckIn:D] Running Loop=1
2025-07-21T16:29:44.842Z,1753115384.842 [DAT](INFO): DAT read: $Error in header

2025-07-21T16:29:44.843Z,1753115384.843 [DAT](INFO): Received a bad header
2025-07-21T16:29:44.856Z,1753115384.856 [DAT](INFO): DAT read: RR:005 Mod:FH

2025-07-21T16:29:44.857Z,1753115384.857 [DAT](INFO): unknown deviceResponse_: RR:005 Mod:FH

2025-07-21T16:29:44.857Z,1753115384.857 [DAT](INFO): DAT read: 

2025-07-21T16:29:45.133Z,1753115385.133 [Default:CheckIn:D] Stopped
2025-07-21T16:29:45.133Z,1753115385.133 [Default:CheckIn:E] Running Loop=1
2025-07-21T16:29:45.523Z,1753115385.523 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  34.445662 min 
2025-07-21T16:29:45.523Z,1753115385.523 [Default:CheckIn:E] Stopped
2025-07-21T16:29:45.523Z,1753115385.523 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T16:29:45.523Z,1753115385.523 [Default:CheckIn] Stopped
2025-07-21T16:29:45.523Z,1753115385.523 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T16:29:45.523Z,1753115385.523 [Default:CheckIn](INFO): Running loop #4
2025-07-21T16:29:45.523Z,1753115385.523 [Default:CheckIn] Running Loop=4
2025-07-21T16:29:45.523Z,1753115385.523 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T16:29:45.524Z,1753115385.524 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T16:29:46.101Z,1753115386.101 [DAT](INFO): DAT read: Rx Time:16:29:45.3799

2025-07-21T16:29:46.101Z,1753115386.101 [DAT](INFO): Rx dataTimestamp_ set to:1753115386.100957
2025-07-21T16:29:46.102Z,1753115386.102 [DAT](INFO): DAT read: 

2025-07-21T16:29:46.102Z,1753115386.102 [DAT](INFO): DAT read: $Packet for address 0

2025-07-21T16:29:46.102Z,1753115386.102 [DAT](INFO): received a packet notification
2025-07-21T16:30:19.851Z,1753115419.851 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163018.00,A,3648.16630,N,12147.25500,W,0.330,0.00,210725,,,A*7A
2025-07-21T16:30:19.855Z,1753115419.855 [NAL9602](INFO): GPS fix at 20250721T163018: (36.802772, -121.787583)
2025-07-21T16:30:19.882Z,1753115419.882 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T16:30:19.882Z,1753115419.882 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T16:30:26.732Z,1753115426.732 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20250721T155012/Courier0013.lzma
2025-07-21T16:30:40.553Z,1753115440.553 [DAT](INFO): DAT read: Rx Time:16:30:39.7958

2025-07-21T16:30:40.553Z,1753115440.553 [DAT](INFO): Rx dataTimestamp_ set to:1753115440.553032
2025-07-21T16:30:40.554Z,1753115440.554 [DAT](INFO): DAT read: 

2025-07-21T16:30:40.554Z,1753115440.554 [DAT](INFO): DAT read: $Packet for address 0

2025-07-21T16:30:40.555Z,1753115440.555 [DAT](INFO): received a packet notification
2025-07-21T16:30:40.786Z,1753115440.786 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Courier0013.lzma.bak
2025-07-21T16:30:40.786Z,1753115440.786 [DataOverHttps](INFO): SBD MOMSN=25562905
2025-07-21T16:30:43.829Z,1753115443.829 [DAT](INFO): DAT read: Rx Time:16:30:43.1063

2025-07-21T16:30:43.830Z,1753115443.830 [DAT](INFO): Rx dataTimestamp_ set to:1753115443.829276
2025-07-21T16:30:43.830Z,1753115443.830 [DAT](INFO): DAT read: 

2025-07-21T16:30:43.831Z,1753115443.831 [DAT](INFO): DAT read: $Packet for address 0

2025-07-21T16:30:43.831Z,1753115443.831 [DAT](INFO): received a packet notification
2025-07-21T16:30:52.637Z,1753115452.637 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T16:31:09.894Z,1753115469.894 [DataOverHttps](INFO): Sending 333 bytes from file Logs/20250721T155012/Express0014.lzma
2025-07-21T16:31:17.861Z,1753115477.861 [DAT](INFO): DAT read: Rx Time:16:31:17.0604

2025-07-21T16:31:17.862Z,1753115477.862 [DAT](INFO): Rx dataTimestamp_ set to:1753115477.861282
2025-07-21T16:31:17.862Z,1753115477.862 [DAT](INFO): DAT read: 

2025-07-21T16:31:17.863Z,1753115477.863 [DAT](INFO): DAT read: $Packet for address 0

2025-07-21T16:31:17.863Z,1753115477.863 [DAT](INFO): received a packet notification
2025-07-21T16:31:24.665Z,1753115484.665 [DAT](INFO): DAT read: Rx Time:16:31:24.0315

2025-07-21T16:31:24.666Z,1753115484.666 [DAT](INFO): Rx dataTimestamp_ set to:1753115484.665323
2025-07-21T16:31:24.666Z,1753115484.666 [DAT](INFO): DAT read: 

2025-07-21T16:31:24.667Z,1753115484.667 [DAT](INFO): DAT read: $Packet for address 0

2025-07-21T16:31:24.667Z,1753115484.667 [DAT](INFO): received a packet notification
2025-07-21T16:31:24.946Z,1753115484.946 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Express0014.lzma.bak
2025-07-21T16:31:24.946Z,1753115484.946 [DataOverHttps](INFO): SBD MOMSN=25562922
2025-07-21T16:31:26.185Z,1753115486.185 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T16:31:26.185Z,1753115486.185 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T16:31:26.185Z,1753115486.185 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T16:31:46.589Z,1753115506.589 [DAT](INFO): DAT read: Rx Time:16:31:45.9068

2025-07-21T16:31:46.590Z,1753115506.590 [DAT](INFO): Rx dataTimestamp_ set to:1753115506.589263
2025-07-21T16:31:46.590Z,1753115506.590 [DAT](INFO): DAT read: 

2025-07-21T16:31:46.591Z,1753115506.591 [DAT](INFO): DAT read: $Packet for address 0

2025-07-21T16:31:46.591Z,1753115506.591 [DAT](INFO): received a packet notification
2025-07-21T16:31:49.873Z,1753115509.873 [DAT](INFO): DAT read: Rx Time:16:31:49.3023

2025-07-21T16:31:49.873Z,1753115509.873 [DAT](INFO): Rx dataTimestamp_ set to:1753115509.873024
2025-07-21T16:31:49.874Z,1753115509.874 [DAT](INFO): DAT read: 

2025-07-21T16:31:49.874Z,1753115509.874 [DAT](INFO): DAT read: $Packet for address 0

2025-07-21T16:31:49.875Z,1753115509.875 [DAT](INFO): received a packet notification
2025-07-21T16:34:46.794Z,1753115686.794 [DataOverHttps](IMPORTANT): SBD MTMSN=20250721T163445
2025-07-21T16:34:55.026Z,1753115695.026 [DataOverHttps](INFO): Received command: failc
2025-07-21T16:34:55.072Z,1753115695.072 [CommandExec](IMPORTANT): got command failComponent
2025-07-21T16:34:55.073Z,1753115695.073 [CommandExec](IMPORTANT): Failed components:
2025-07-21T16:34:55.073Z,1753115695.073 [CommandExec](IMPORTANT): No failed Components.
2025-07-21T16:35:06.061Z,1753115706.061 [DataOverHttps](IMPORTANT): SBD MTMSN=20250721T163505
2025-07-21T16:35:13.607Z,1753115713.607 [DataOverHttps](INFO): Received command: ibit
2025-07-21T16:35:13.696Z,1753115713.696 [CommandExec](IMPORTANT): got command ibit
2025-07-21T16:35:14.070Z,1753115714.070 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-07-21T16:35:14.070Z,1753115714.070 [IBIT](IMPORTANT): Beginning control surface checks.
2025-07-21T16:35:14.078Z,1753115714.078 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-07-21T16:35:15.647Z,1753115715.647 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163514.00,A,3648.14821,N,12147.22682,W,0.039,36.75,210725,,,A*4C
2025-07-21T16:35:15.649Z,1753115715.649 [NAL9602](INFO): GPS fix at 20250721T163514: (36.802470, -121.787114)
2025-07-21T16:35:18.676Z,1753115718.676 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.000313
CHAN A1 (24V): 0.000433
CHAN A2 (12V): -0.000341
CHAN A3 (5V): -0.001494
CHAN B0 (3.3V): 0.000202
CHAN B1 (3.15aV): -0.000549
CHAN B2 (3.15bV): -0.000161
CHAN B3 (GND): -0.000807
OPEN: 0.006157
Full Scale: +/- 1 mA
2025-07-21T16:35:41.910Z,1753115741.910 [NAL9602](INFO): SBD MO Status=2, MOMSN=25766, MT Status=2, MTMSN=0
2025-07-21T16:35:41.911Z,1753115741.911 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-21T16:35:51.199Z,1753115751.199 [NAL9602](INFO): SBD MO Status=0, MOMSN=25766, MT Status=0, MTMSN=0
2025-07-21T16:35:51.199Z,1753115751.199 [NAL9602](INFO): No messages in MT queue
2025-07-21T16:35:52.411Z,1753115752.411 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163551.00,A,3648.14787,N,12147.22625,W,0.136,36.75,210725,,,A*4D
2025-07-21T16:35:52.413Z,1753115752.413 [NAL9602](INFO): GPS fix at 20250721T163551: (36.802464, -121.787104)
2025-07-21T16:35:54.838Z,1753115754.838 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163554.00,A,3648.14792,N,12147.22630,W,0.078,36.75,210725,,,A*43
2025-07-21T16:35:54.840Z,1753115754.840 [NAL9602](INFO): GPS fix at 20250721T163554: (36.802465, -121.787105)
2025-07-21T16:35:58.069Z,1753115758.069 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163557.00,A,3648.14803,N,12147.22633,W,0.019,36.75,210725,,,A*43
2025-07-21T16:35:58.071Z,1753115758.071 [NAL9602](INFO): GPS fix at 20250721T163557: (36.802467, -121.787105)
2025-07-21T16:36:00.110Z,1753115760.110 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 2
Latitude: 36.802467 Longitude: -121.787102
2025-07-21T16:36:00.520Z,1753115760.520 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.572001
2025-07-21T16:36:00.521Z,1753115760.521 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2025-07-21T16:36:00.521Z,1753115760.521 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2025-07-21T16:36:00.899Z,1753115760.899 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163600.00,A,3648.14810,N,12147.22634,W,0.097,36.75,210725,,,A*41
2025-07-21T16:36:00.901Z,1753115760.901 [NAL9602](INFO): GPS fix at 20250721T163600: (36.802468, -121.787106)
2025-07-21T16:36:00.946Z,1753115760.946 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2025-07-21T16:36:00.946Z,1753115760.946 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2025-07-21T16:36:00.946Z,1753115760.946 [IBIT](IMPORTANT): Pressure:10.173535 PSI
2025-07-21T16:36:00.947Z,1753115760.947 [IBIT](IMPORTANT): Humidity:23.721157 %
2025-07-21T16:36:01.353Z,1753115761.353 [IBIT](IMPORTANT): Vehicle Pitch:-0.834538 degrees
2025-07-21T16:36:01.354Z,1753115761.354 [IBIT](IMPORTANT): Vehicle  Roll:2.029432 degrees
2025-07-21T16:36:01.354Z,1753115761.354 [IBIT](IMPORTANT): Vehicle  Heading:6.561326 degrees
2025-07-21T16:36:01.719Z,1753115761.719 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2025-07-21T16:36:01.719Z,1753115761.719 [IBIT](IMPORTANT): buoyancyNeutral: 187.340805 cc
2025-07-21T16:36:01.720Z,1753115761.720 [IBIT](IMPORTANT): massDefault: 2.471087 cm
2025-07-21T16:36:01.720Z,1753115761.720 [IBIT](IMPORTANT): stopDepth: 290.000000 m
2025-07-21T16:36:01.720Z,1753115761.720 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2025-07-21T16:36:01.720Z,1753115761.720 [IBIT](IMPORTANT): IBIT PASSED
2025-07-21T16:36:26.759Z,1753115786.759 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T16:36:26.759Z,1753115786.759 [Default:CheckIn:C.Wait] Stopped
2025-07-21T16:36:26.759Z,1753115786.759 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T16:36:26.759Z,1753115786.759 [Default:CheckIn:D] Running Loop=1
2025-07-21T16:36:27.167Z,1753115787.167 [Default:CheckIn:D] Stopped
2025-07-21T16:36:27.167Z,1753115787.167 [Default:CheckIn:E] Running Loop=1
2025-07-21T16:36:27.576Z,1753115787.576 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  41.146232 min 
2025-07-21T16:36:27.576Z,1753115787.576 [Default:CheckIn:E] Stopped
2025-07-21T16:36:27.576Z,1753115787.576 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T16:36:27.576Z,1753115787.576 [Default:CheckIn] Stopped
2025-07-21T16:36:27.576Z,1753115787.576 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T16:36:27.576Z,1753115787.576 [Default:CheckIn](INFO): Running loop #5
2025-07-21T16:36:27.576Z,1753115787.576 [Default:CheckIn] Running Loop=5
2025-07-21T16:36:27.576Z,1753115787.576 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T16:36:27.577Z,1753115787.577 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T16:36:29.588Z,1753115789.588 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163628.00,A,3648.14746,N,12147.22605,W,0.039,36.75,210725,,,D*44
2025-07-21T16:36:29.590Z,1753115789.590 [NAL9602](INFO): GPS fix at 20250721T163628: (36.802458, -121.787101)
2025-07-21T16:36:29.606Z,1753115789.606 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T16:36:29.606Z,1753115789.606 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T16:36:37.363Z,1753115797.363 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250721T155012/Courier0016.lzma
2025-07-21T16:36:48.406Z,1753115808.406 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Courier0016.lzma.bak
2025-07-21T16:36:48.406Z,1753115808.406 [DataOverHttps](INFO): SBD MOMSN=25562944
2025-07-21T16:37:01.505Z,1753115821.505 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T16:37:14.410Z,1753115834.410 [DataOverHttps](INFO): Sending 982 bytes from file Logs/20250721T155012/Express0017.lzma
2025-07-21T16:37:25.450Z,1753115845.450 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Express0017.lzma.bak
2025-07-21T16:37:25.450Z,1753115845.450 [DataOverHttps](INFO): SBD MOMSN=25562963
2025-07-21T16:37:51.479Z,1753115871.479 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20250721T155012/Express0020.lzma
2025-07-21T16:38:02.522Z,1753115882.522 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Express0020.lzma.bak
2025-07-21T16:38:02.522Z,1753115882.522 [DataOverHttps](INFO): SBD MOMSN=25563036
2025-07-21T16:38:03.731Z,1753115883.731 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T16:38:03.731Z,1753115883.731 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T16:38:03.731Z,1753115883.731 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T16:43:04.317Z,1753116184.317 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T16:43:04.317Z,1753116184.317 [Default:CheckIn:C.Wait] Stopped
2025-07-21T16:43:04.317Z,1753116184.317 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T16:43:04.317Z,1753116184.317 [Default:CheckIn:D] Running Loop=1
2025-07-21T16:43:04.715Z,1753116184.715 [Default:CheckIn:D] Stopped
2025-07-21T16:43:04.715Z,1753116184.715 [Default:CheckIn:E] Running Loop=1
2025-07-21T16:43:05.125Z,1753116185.125 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  47.772034 min 
2025-07-21T16:43:05.125Z,1753116185.125 [Default:CheckIn:E] Stopped
2025-07-21T16:43:05.125Z,1753116185.125 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T16:43:05.125Z,1753116185.125 [Default:CheckIn] Stopped
2025-07-21T16:43:05.125Z,1753116185.125 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T16:43:05.126Z,1753116185.126 [Default:CheckIn](INFO): Running loop #6
2025-07-21T16:43:05.126Z,1753116185.126 [Default:CheckIn] Running Loop=6
2025-07-21T16:43:05.126Z,1753116185.126 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T16:43:05.126Z,1753116185.126 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T16:43:07.129Z,1753116187.129 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164306.00,A,3648.14623,N,12147.22699,W,0.000,36.75,210725,,,D*47
2025-07-21T16:43:07.131Z,1753116187.131 [NAL9602](INFO): GPS fix at 20250721T164306: (36.802437, -121.787116)
2025-07-21T16:43:07.143Z,1753116187.143 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T16:43:07.143Z,1753116187.143 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T16:43:14.963Z,1753116194.963 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250721T155012/Courier0022.lzma
2025-07-21T16:43:20.062Z,1753116200.062 [NAL9602](INFO): SBD MO Status=0, MOMSN=25767, MT Status=0, MTMSN=0
2025-07-21T16:43:20.062Z,1753116200.062 [NAL9602](INFO): No messages in MT queue
2025-07-21T16:43:26.002Z,1753116206.002 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Courier0022.lzma.bak
2025-07-21T16:43:26.002Z,1753116206.002 [DataOverHttps](INFO): SBD MOMSN=25563069
2025-07-21T16:43:50.766Z,1753116230.766 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T16:43:51.960Z,1753116231.960 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20250721T155012/Express0023.lzma
2025-07-21T16:44:04.007Z,1753116244.007 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Express0023.lzma.bak
2025-07-21T16:44:04.007Z,1753116244.007 [DataOverHttps](INFO): SBD MOMSN=25563074
2025-07-21T16:44:05.323Z,1753116245.323 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T16:44:05.323Z,1753116245.323 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T16:44:05.323Z,1753116245.323 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T16:49:05.900Z,1753116545.900 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T16:49:05.900Z,1753116545.900 [Default:CheckIn:C.Wait] Stopped
2025-07-21T16:49:05.900Z,1753116545.900 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T16:49:05.900Z,1753116545.900 [Default:CheckIn:D] Running Loop=1
2025-07-21T16:49:06.295Z,1753116546.295 [Default:CheckIn:D] Stopped
2025-07-21T16:49:06.295Z,1753116546.295 [Default:CheckIn:E] Running Loop=1
2025-07-21T16:49:06.696Z,1753116546.696 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  53.798364 min 
2025-07-21T16:49:06.696Z,1753116546.696 [Default:CheckIn:E] Stopped
2025-07-21T16:49:06.696Z,1753116546.696 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T16:49:06.697Z,1753116546.697 [Default:CheckIn] Stopped
2025-07-21T16:49:06.697Z,1753116546.697 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T16:49:06.697Z,1753116546.697 [Default:CheckIn](INFO): Running loop #7
2025-07-21T16:49:06.697Z,1753116546.697 [Default:CheckIn] Running Loop=7
2025-07-21T16:49:06.697Z,1753116546.697 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T16:49:06.697Z,1753116546.697 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T16:49:08.710Z,1753116548.710 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164907.00,A,3648.14752,N,12147.22846,W,0.019,36.75,210725,,,D*4F
2025-07-21T16:49:08.713Z,1753116548.713 [NAL9602](INFO): GPS fix at 20250721T164907: (36.802459, -121.787141)
2025-07-21T16:49:08.723Z,1753116548.723 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T16:49:08.723Z,1753116548.723 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T16:49:16.499Z,1753116556.499 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250721T155012/Courier0025.lzma
2025-07-21T16:49:20.020Z,1753116560.020 [NAL9602](INFO): SBD MO Status=0, MOMSN=25768, MT Status=0, MTMSN=0
2025-07-21T16:49:20.021Z,1753116560.021 [NAL9602](INFO): No messages in MT queue
2025-07-21T16:49:27.542Z,1753116567.542 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Courier0025.lzma.bak
2025-07-21T16:49:27.542Z,1753116567.542 [DataOverHttps](INFO): SBD MOMSN=25563111
2025-07-21T16:49:50.725Z,1753116590.725 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T16:49:53.512Z,1753116593.512 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20250721T155012/Express0026.lzma
2025-07-21T16:50:04.554Z,1753116604.554 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Express0026.lzma.bak
2025-07-21T16:50:04.554Z,1753116604.554 [DataOverHttps](INFO): SBD MOMSN=25563116
2025-07-21T16:50:05.688Z,1753116605.688 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T16:50:05.688Z,1753116605.688 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T16:50:05.689Z,1753116605.689 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T16:54:09.945Z,1753116849.945 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-07-21T16:55:06.323Z,1753116906.323 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T16:55:06.323Z,1753116906.323 [Default:CheckIn:C.Wait] Stopped
2025-07-21T16:55:06.323Z,1753116906.323 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T16:55:06.323Z,1753116906.323 [Default:CheckIn:D] Running Loop=1
2025-07-21T16:55:06.724Z,1753116906.724 [Default:CheckIn:D] Stopped
2025-07-21T16:55:06.725Z,1753116906.725 [Default:CheckIn:E] Running Loop=1
2025-07-21T16:55:07.136Z,1753116907.136 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  59.805505 min 
2025-07-21T16:55:07.136Z,1753116907.136 [Default:CheckIn:E] Stopped
2025-07-21T16:55:07.136Z,1753116907.136 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T16:55:07.136Z,1753116907.136 [Default:CheckIn] Stopped
2025-07-21T16:55:07.136Z,1753116907.136 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T16:55:07.137Z,1753116907.137 [Default:CheckIn](INFO): Running loop #8
2025-07-21T16:55:07.137Z,1753116907.137 [Default:CheckIn] Running Loop=8
2025-07-21T16:55:07.137Z,1753116907.137 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T16:55:07.137Z,1753116907.137 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T16:55:09.140Z,1753116909.140 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165508.00,A,3648.14707,N,12147.22710,W,0.039,36.75,210725,,,D*43
2025-07-21T16:55:09.142Z,1753116909.142 [NAL9602](INFO): GPS fix at 20250721T165508: (36.802451, -121.787118)
2025-07-21T16:55:09.153Z,1753116909.153 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T16:55:09.153Z,1753116909.153 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T16:55:16.343Z,1753116916.343 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250721T155012/Courier0028.lzma
2025-07-21T16:55:19.646Z,1753116919.646 [NAL9602](INFO): SBD MO Status=0, MOMSN=25769, MT Status=0, MTMSN=0
2025-07-21T16:55:19.646Z,1753116919.646 [NAL9602](INFO): No messages in MT queue
2025-07-21T16:55:38.442Z,1753116938.442 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Courier0028.lzma.bak
2025-07-21T16:55:38.442Z,1753116938.442 [DataOverHttps](INFO): SBD MOMSN=25563127
2025-07-21T16:55:50.345Z,1753116950.345 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T16:56:27.016Z,1753116987.016 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20250721T155012/Express0029.lzma
2025-07-21T16:56:44.098Z,1753117004.098 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Express0029.lzma.bak
2025-07-21T16:56:44.098Z,1753117004.098 [DataOverHttps](INFO): SBD MOMSN=25563132
2025-07-21T16:56:45.319Z,1753117005.319 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T16:56:45.319Z,1753117005.319 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T16:56:45.319Z,1753117005.319 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T16:59:24.552Z,1753117164.552 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:01:46.024Z,1753117306.024 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T17:01:46.025Z,1753117306.025 [Default:CheckIn:C.Wait] Stopped
2025-07-21T17:01:46.025Z,1753117306.025 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T17:01:46.025Z,1753117306.025 [Default:CheckIn:D] Running Loop=1
2025-07-21T17:01:46.375Z,1753117306.375 [Default:CheckIn:D] Stopped
2025-07-21T17:01:46.375Z,1753117306.375 [Default:CheckIn:E] Running Loop=1
2025-07-21T17:01:46.791Z,1753117306.791 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  66.466357 min 
2025-07-21T17:01:46.791Z,1753117306.791 [Default:CheckIn:E] Stopped
2025-07-21T17:01:46.791Z,1753117306.791 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T17:01:46.792Z,1753117306.792 [Default:CheckIn] Stopped
2025-07-21T17:01:46.792Z,1753117306.792 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T17:01:46.792Z,1753117306.792 [Default:CheckIn](INFO): Running loop #9
2025-07-21T17:01:46.792Z,1753117306.792 [Default:CheckIn] Running Loop=9
2025-07-21T17:01:46.792Z,1753117306.792 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T17:01:46.792Z,1753117306.792 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T17:01:48.790Z,1753117308.790 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170148.00,A,3648.14708,N,12147.22769,W,0.058,36.75,210725,,,D*41
2025-07-21T17:01:48.792Z,1753117308.792 [NAL9602](INFO): GPS fix at 20250721T170148: (36.802451, -121.787128)
2025-07-21T17:01:48.804Z,1753117308.804 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T17:01:48.804Z,1753117308.804 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T17:01:52.832Z,1753117312.832 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:02:05.343Z,1753117325.343 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:02:07.483Z,1753117327.483 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250721T155012/Courier0031.lzma
2025-07-21T17:02:13.832Z,1753117333.832 [NAL9602](INFO): SBD MO Status=2, MOMSN=25770, MT Status=2, MTMSN=0
2025-07-21T17:02:13.833Z,1753117333.833 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-21T17:02:17.465Z,1753117337.465 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:02:20.532Z,1753117340.532 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-07-21T17:02:28.935Z,1753117348.935 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250721T155012/Courier0031.lzma
2025-07-21T17:02:30.392Z,1753117350.392 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:02:30.818Z,1753117350.818 [NAL9602](INFO): SBD MO Status=2, MOMSN=25770, MT Status=2, MTMSN=0
2025-07-21T17:02:30.818Z,1753117350.818 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-21T17:02:43.322Z,1753117363.322 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:02:46.002Z,1753117366.002 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Courier0031.lzma.bak
2025-07-21T17:02:46.002Z,1753117366.002 [DataOverHttps](INFO): SBD MOMSN=25563156
2025-07-21T17:02:49.406Z,1753117369.406 [NAL9602](INFO): SBD MO Status=0, MOMSN=25770, MT Status=0, MTMSN=0
2025-07-21T17:02:49.407Z,1753117369.407 [NAL9602](INFO): No messages in MT queue
2025-07-21T17:02:56.293Z,1753117376.293 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:03:09.220Z,1753117389.220 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:03:20.130Z,1753117400.130 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T17:03:21.339Z,1753117401.339 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:03:34.671Z,1753117414.671 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:03:47.601Z,1753117427.601 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:03:56.868Z,1753117436.868 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20250721T155012/Express0032.lzma
2025-07-21T17:04:00.532Z,1753117440.532 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:04:20.960Z,1753117460.960 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-07-21T17:04:28.485Z,1753117468.485 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20250721T155012/Express0032.lzma
2025-07-21T17:04:40.530Z,1753117480.530 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Express0032.lzma.bak
2025-07-21T17:04:40.530Z,1753117480.530 [DataOverHttps](INFO): SBD MOMSN=25563168
2025-07-21T17:04:41.757Z,1753117481.757 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T17:04:41.757Z,1753117481.757 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T17:04:41.758Z,1753117481.758 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T17:06:34.103Z,1753117594.103 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:06:47.449Z,1753117607.449 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:07:00.811Z,1753117620.811 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:07:14.952Z,1753117634.952 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:07:28.687Z,1753117648.687 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:07:42.828Z,1753117662.828 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:07:56.162Z,1753117676.162 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:08:09.493Z,1753117689.493 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:08:23.240Z,1753117703.240 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:08:37.368Z,1753117717.368 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:08:51.134Z,1753117731.134 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:09:42.550Z,1753117782.550 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T17:09:42.550Z,1753117782.550 [Default:CheckIn:C.Wait] Stopped
2025-07-21T17:09:42.550Z,1753117782.550 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T17:09:42.550Z,1753117782.550 [Default:CheckIn:D] Running Loop=1
2025-07-21T17:09:42.891Z,1753117782.891 [Default:CheckIn:D] Stopped
2025-07-21T17:09:42.891Z,1753117782.891 [Default:CheckIn:E] Running Loop=1
2025-07-21T17:09:43.307Z,1753117783.307 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  74.408293 min 
2025-07-21T17:09:43.307Z,1753117783.307 [Default:CheckIn:E] Stopped
2025-07-21T17:09:43.307Z,1753117783.307 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T17:09:43.311Z,1753117783.311 [Default:CheckIn] Stopped
2025-07-21T17:09:43.311Z,1753117783.311 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T17:09:43.313Z,1753117783.313 [Default:CheckIn](INFO): Running loop #10
2025-07-21T17:09:43.313Z,1753117783.313 [Default:CheckIn] Running Loop=10
2025-07-21T17:09:43.313Z,1753117783.313 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T17:09:43.313Z,1753117783.313 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T17:09:45.300Z,1753117785.300 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170944.00,A,3648.15208,N,12147.22576,W,0.039,8.54,210725,,,D*74
2025-07-21T17:09:45.302Z,1753117785.302 [NAL9602](INFO): GPS fix at 20250721T170944: (36.802535, -121.787096)
2025-07-21T17:09:45.345Z,1753117785.345 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T17:09:45.345Z,1753117785.345 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T17:09:52.955Z,1753117792.955 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250721T155012/Courier0034.lzma
2025-07-21T17:10:12.778Z,1753117812.778 [NAL9602](INFO): SBD MO Status=2, MOMSN=25771, MT Status=2, MTMSN=0
2025-07-21T17:10:12.778Z,1753117812.778 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-21T17:10:17.056Z,1753117817.056 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-07-21T17:10:24.539Z,1753117824.539 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250721T155012/Courier0034.lzma
2025-07-21T17:10:39.598Z,1753117839.598 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Courier0034.lzma.bak
2025-07-21T17:10:39.598Z,1753117839.598 [DataOverHttps](INFO): SBD MOMSN=25563191
2025-07-21T17:10:44.291Z,1753117844.291 [NAL9602](INFO): SBD MO Status=2, MOMSN=25771, MT Status=2, MTMSN=0
2025-07-21T17:10:44.291Z,1753117844.291 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-21T17:11:05.702Z,1753117865.702 [NAL9602](INFO): SBD MO Status=2, MOMSN=25771, MT Status=2, MTMSN=0
2025-07-21T17:11:05.702Z,1753117865.702 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-21T17:11:06.520Z,1753117866.520 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20250721T155012/Express0035.lzma
2025-07-21T17:11:14.177Z,1753117874.177 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:11:20.641Z,1753117880.641 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:11:30.612Z,1753117890.612 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-07-21T17:11:30.745Z,1753117890.745 [NAL9602](INFO): SBD MO Status=0, MOMSN=25771, MT Status=0, MTMSN=0
2025-07-21T17:11:30.745Z,1753117890.745 [NAL9602](INFO): No messages in MT queue
2025-07-21T17:11:35.993Z,1753117895.993 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:11:38.344Z,1753117898.344 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20250721T155012/Express0035.lzma
2025-07-21T17:11:51.344Z,1753117911.344 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:11:52.398Z,1753117912.398 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Express0035.lzma.bak
2025-07-21T17:11:52.398Z,1753117912.398 [DataOverHttps](INFO): SBD MOMSN=25563198
2025-07-21T17:11:53.783Z,1753117913.783 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T17:11:53.783Z,1753117913.783 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T17:11:53.783Z,1753117913.783 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T17:12:01.465Z,1753117921.465 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T17:12:06.293Z,1753117926.293 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:12:21.643Z,1753117941.643 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:12:37.406Z,1753117957.406 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:12:52.347Z,1753117972.347 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:13:07.298Z,1753117987.298 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:13:22.244Z,1753118002.244 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:15:55.783Z,1753118155.783 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:16:09.520Z,1753118169.520 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:16:22.852Z,1753118182.852 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:16:36.208Z,1753118196.208 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:16:49.542Z,1753118209.542 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:16:54.421Z,1753118214.421 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T17:16:54.421Z,1753118214.421 [Default:CheckIn:C.Wait] Stopped
2025-07-21T17:16:54.421Z,1753118214.421 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T17:16:54.421Z,1753118214.421 [Default:CheckIn:D] Running Loop=1
2025-07-21T17:16:54.857Z,1753118214.857 [Default:CheckIn:D] Stopped
2025-07-21T17:16:54.857Z,1753118214.857 [Default:CheckIn:E] Running Loop=1
2025-07-21T17:16:55.270Z,1753118215.270 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  81.607731 min 
2025-07-21T17:16:55.271Z,1753118215.271 [Default:CheckIn:E] Stopped
2025-07-21T17:16:55.271Z,1753118215.271 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T17:16:55.271Z,1753118215.271 [Default:CheckIn] Stopped
2025-07-21T17:16:55.271Z,1753118215.271 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T17:16:55.271Z,1753118215.271 [Default:CheckIn](INFO): Running loop #11
2025-07-21T17:16:55.271Z,1753118215.271 [Default:CheckIn] Running Loop=11
2025-07-21T17:16:55.271Z,1753118215.271 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T17:16:55.271Z,1753118215.271 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T17:16:57.227Z,1753118217.227 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171656.00,A,3648.15335,N,12147.22029,W,0.000,88.80,210725,,,D*42
2025-07-21T17:16:57.229Z,1753118217.229 [NAL9602](INFO): GPS fix at 20250721T171656: (36.802556, -121.787005)
2025-07-21T17:16:57.240Z,1753118217.240 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T17:16:57.240Z,1753118217.240 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T17:17:03.283Z,1753118223.283 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:17:07.447Z,1753118227.447 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250721T155012/Courier0037.lzma
2025-07-21T17:17:17.020Z,1753118237.020 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:17:20.663Z,1753118240.663 [NAL9602](INFO): SBD MO Status=2, MOMSN=25772, MT Status=2, MTMSN=0
2025-07-21T17:17:20.663Z,1753118240.663 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-21T17:17:28.529Z,1753118248.529 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-07-21T17:17:30.758Z,1753118250.758 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:17:40.491Z,1753118260.491 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250721T155012/Courier0037.lzma
2025-07-21T17:17:44.899Z,1753118264.899 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:17:55.419Z,1753118275.419 [NAL9602](INFO): SBD MO Status=2, MOMSN=25772, MT Status=2, MTMSN=0
2025-07-21T17:17:55.421Z,1753118275.421 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-21T17:17:55.550Z,1753118275.550 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Courier0037.lzma.bak
2025-07-21T17:17:55.550Z,1753118275.550 [DataOverHttps](INFO): SBD MOMSN=25563219
2025-07-21T17:17:58.638Z,1753118278.638 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:18:12.775Z,1753118292.775 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:18:28.567Z,1753118308.567 [NAL9602](INFO): SBD MO Status=2, MOMSN=25772, MT Status=2, MTMSN=0
2025-07-21T17:18:28.567Z,1753118308.567 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-21T17:18:43.945Z,1753118323.945 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20250721T155012/Express0038.lzma
2025-07-21T17:18:57.279Z,1753118337.279 [NAL9602](INFO): SBD MO Status=0, MOMSN=25772, MT Status=0, MTMSN=0
2025-07-21T17:18:57.279Z,1753118337.279 [NAL9602](INFO): No messages in MT queue
2025-07-21T17:19:07.044Z,1753118347.044 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-07-21T17:19:19.380Z,1753118359.380 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20250721T155012/Express0038.lzma
2025-07-21T17:19:27.985Z,1753118367.985 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T17:19:34.438Z,1753118374.438 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Express0038.lzma.bak
2025-07-21T17:19:34.438Z,1753118374.438 [DataOverHttps](INFO): SBD MOMSN=25563227
2025-07-21T17:19:35.649Z,1753118375.649 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T17:19:35.649Z,1753118375.649 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T17:19:35.650Z,1753118375.650 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T17:20:41.904Z,1753118441.904 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:20:56.848Z,1753118456.848 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:21:11.398Z,1753118471.398 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:21:26.340Z,1753118486.340 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:21:41.696Z,1753118501.696 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:21:57.460Z,1753118517.460 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:22:12.824Z,1753118532.824 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:22:28.972Z,1753118548.972 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:22:43.949Z,1753118563.949 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:24:36.339Z,1753118676.339 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T17:24:36.339Z,1753118676.339 [Default:CheckIn:C.Wait] Stopped
2025-07-21T17:24:36.339Z,1753118676.339 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T17:24:36.339Z,1753118676.339 [Default:CheckIn:D] Running Loop=1
2025-07-21T17:24:36.751Z,1753118676.751 [Default:CheckIn:D] Stopped
2025-07-21T17:24:36.751Z,1753118676.751 [Default:CheckIn:E] Running Loop=1
2025-07-21T17:24:37.160Z,1753118677.160 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  89.305957 min 
2025-07-21T17:24:37.160Z,1753118677.160 [Default:CheckIn:E] Stopped
2025-07-21T17:24:37.161Z,1753118677.161 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T17:24:37.161Z,1753118677.161 [Default:CheckIn] Stopped
2025-07-21T17:24:37.161Z,1753118677.161 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T17:24:37.161Z,1753118677.161 [Default:CheckIn](INFO): Running loop #12
2025-07-21T17:24:37.161Z,1753118677.161 [Default:CheckIn] Running Loop=12
2025-07-21T17:24:37.161Z,1753118677.161 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T17:24:37.161Z,1753118677.161 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T17:24:39.163Z,1753118679.163 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172438.00,A,3648.15218,N,12147.22076,W,0.039,88.80,210725,,,D*45
2025-07-21T17:24:39.169Z,1753118679.169 [NAL9602](INFO): GPS fix at 20250721T172438: (36.802536, -121.787013)
2025-07-21T17:24:39.217Z,1753118679.217 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T17:24:39.217Z,1753118679.217 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T17:24:44.016Z,1753118684.016 [NAL9602](INFO): SBD MO Status=0, MOMSN=25773, MT Status=0, MTMSN=0
2025-07-21T17:24:44.016Z,1753118684.016 [NAL9602](INFO): No messages in MT queue
2025-07-21T17:24:54.723Z,1753118694.723 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250721T155012/Courier0040.lzma
2025-07-21T17:25:10.784Z,1753118710.784 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-07-21T17:25:14.761Z,1753118714.761 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T17:25:27.686Z,1753118727.686 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:25:36.791Z,1753118736.791 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250721T155012/Courier0040.lzma
2025-07-21T17:25:41.028Z,1753118741.028 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:25:41.808Z,1753118741.808 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-07-21T17:25:55.186Z,1753118755.186 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:25:55.335Z,1753118755.335 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250721T155012/Courier0040.lzma
2025-07-21T17:26:08.921Z,1753118768.921 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:26:08.922Z,1753118768.922 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-07-21T17:26:13.404Z,1753118773.404 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-07-21T17:26:22.655Z,1753118782.655 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:26:37.200Z,1753118797.200 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:26:51.397Z,1753118811.397 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:26:59.924Z,1753118819.924 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250721T155012/Courier0040.lzma
2025-07-21T17:27:05.131Z,1753118825.131 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:27:18.878Z,1753118838.878 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:27:21.023Z,1753118841.023 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250721T155012/Courier0040.lzma
2025-07-21T17:27:32.210Z,1753118852.210 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T17:27:43.108Z,1753118863.108 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-07-21T17:27:50.543Z,1753118870.543 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250721T155012/Courier0040.lzma
2025-07-21T17:28:03.594Z,1753118883.594 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Courier0040.lzma.bak
2025-07-21T17:28:03.594Z,1753118883.594 [DataOverHttps](INFO): SBD MOMSN=25563266
2025-07-21T17:28:32.268Z,1753118912.268 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20250721T155012/Express0041.lzma
2025-07-21T17:28:43.326Z,1753118923.326 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Express0041.lzma.bak
2025-07-21T17:28:43.326Z,1753118923.326 [DataOverHttps](INFO): SBD MOMSN=25563307
2025-07-21T17:28:44.571Z,1753118924.571 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T17:28:44.571Z,1753118924.571 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T17:28:44.571Z,1753118924.571 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T17:29:18.152Z,1753118958.152 [BPC1](ERROR): Battery stick #24 (s/n: 01D3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-07-21T17:29:18.153Z,1753118958.153 [BPC1](ERROR): Battery stick #29 (s/n: 00A7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-21T17:29:18.153Z,1753118958.153 [BPC1](ERROR): Battery stick #51 (s/n: 01D6) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-07-21T17:29:18.154Z,1753118958.154 [BPC1](ERROR): Battery stick #52 (s/n: 0160) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-07-21T17:29:18.155Z,1753118958.155 [BPC1](ERROR): Battery stick #57 (s/n: 019F) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-07-21T17:29:18.168Z,1753118958.168 [BPC1](ERROR): Battery stick #59 (s/n: 0184) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2025-07-21T17:29:18.169Z,1753118958.169 [BPC1](INFO): Calculating totals. Valid battery stick count: 41. Valid reserve battery stick count: 4.
2025-07-21T17:29:18.171Z,1753118958.171 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2025-07-21T17:33:45.321Z,1753119225.321 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T17:33:45.321Z,1753119225.321 [Default:CheckIn:C.Wait] Stopped
2025-07-21T17:33:45.322Z,1753119225.322 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T17:33:45.322Z,1753119225.322 [Default:CheckIn:D] Running Loop=1
2025-07-21T17:33:45.713Z,1753119225.713 [Default:CheckIn:D] Stopped
2025-07-21T17:33:45.713Z,1753119225.713 [Default:CheckIn:E] Running Loop=1
2025-07-21T17:33:46.116Z,1753119226.116 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  98.455322 min 
2025-07-21T17:33:46.116Z,1753119226.116 [Default:CheckIn:E] Stopped
2025-07-21T17:33:46.116Z,1753119226.116 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T17:33:46.116Z,1753119226.116 [Default:CheckIn] Stopped
2025-07-21T17:33:46.117Z,1753119226.117 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T17:33:46.117Z,1753119226.117 [Default:CheckIn](INFO): Running loop #13
2025-07-21T17:33:46.117Z,1753119226.117 [Default:CheckIn] Running Loop=13
2025-07-21T17:33:46.117Z,1753119226.117 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T17:33:46.117Z,1753119226.117 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T17:33:48.123Z,1753119228.123 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173347.00,A,3648.16366,N,12147.21594,W,0.019,17.12,210725,,,D*45
2025-07-21T17:33:48.126Z,1753119228.126 [NAL9602](INFO): GPS fix at 20250721T173347: (36.802728, -121.786932)
2025-07-21T17:33:48.166Z,1753119228.166 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T17:33:48.166Z,1753119228.166 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T17:33:57.067Z,1753119237.067 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20250721T155012/Courier0043.lzma
2025-07-21T17:34:01.867Z,1753119241.867 [NAL9602](INFO): SBD MO Status=0, MOMSN=25774, MT Status=0, MTMSN=0
2025-07-21T17:34:01.867Z,1753119241.867 [NAL9602](INFO): No messages in MT queue
2025-07-21T17:34:08.110Z,1753119248.110 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Courier0043.lzma.bak
2025-07-21T17:34:08.110Z,1753119248.110 [DataOverHttps](INFO): SBD MOMSN=25563325
2025-07-21T17:34:32.569Z,1753119272.569 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T17:34:34.200Z,1753119274.200 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20250721T155012/Express0044.lzma
2025-07-21T17:34:45.242Z,1753119285.242 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Express0044.lzma.bak
2025-07-21T17:34:45.242Z,1753119285.242 [DataOverHttps](INFO): SBD MOMSN=25563328
2025-07-21T17:34:46.344Z,1753119286.344 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T17:34:46.345Z,1753119286.345 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T17:34:46.345Z,1753119286.345 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T17:39:47.053Z,1753119587.053 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T17:39:47.053Z,1753119587.053 [Default:CheckIn:C.Wait] Stopped
2025-07-21T17:39:47.053Z,1753119587.053 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T17:39:47.053Z,1753119587.053 [Default:CheckIn:D] Running Loop=1
2025-07-21T17:39:47.462Z,1753119587.462 [Default:CheckIn:D] Stopped
2025-07-21T17:39:47.462Z,1753119587.462 [Default:CheckIn:E] Running Loop=1
2025-07-21T17:39:47.860Z,1753119587.860 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  104.484481 min 
2025-07-21T17:39:47.861Z,1753119587.861 [Default:CheckIn:E] Stopped
2025-07-21T17:39:47.861Z,1753119587.861 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T17:39:47.861Z,1753119587.861 [Default:CheckIn] Stopped
2025-07-21T17:39:47.861Z,1753119587.861 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T17:39:47.861Z,1753119587.861 [Default:CheckIn](INFO): Running loop #14
2025-07-21T17:39:47.861Z,1753119587.861 [Default:CheckIn] Running Loop=14
2025-07-21T17:39:47.861Z,1753119587.861 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T17:39:47.861Z,1753119587.861 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T17:39:49.865Z,1753119589.865 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173949.00,A,3648.16430,N,12147.21489,W,0.019,17.12,210725,,,D*48
2025-07-21T17:39:49.867Z,1753119589.867 [NAL9602](INFO): GPS fix at 20250721T173949: (36.802738, -121.786915)
2025-07-21T17:39:49.877Z,1753119589.877 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T17:39:49.878Z,1753119589.878 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T17:39:58.695Z,1753119598.695 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250721T155012/Courier0046.lzma
2025-07-21T17:40:05.232Z,1753119605.232 [NAL9602](INFO): SBD MO Status=0, MOMSN=25775, MT Status=0, MTMSN=0
2025-07-21T17:40:05.232Z,1753119605.232 [NAL9602](INFO): No messages in MT queue
2025-07-21T17:40:09.738Z,1753119609.738 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Courier0046.lzma.bak
2025-07-21T17:40:09.738Z,1753119609.738 [DataOverHttps](INFO): SBD MOMSN=25563368
2025-07-21T17:40:30.683Z,1753119630.683 [Sonardyne_Nano](INFO): Battery at maximum, disabling charging
2025-07-21T17:40:35.736Z,1753119635.736 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20250721T155012/Express0047.lzma
2025-07-21T17:40:35.937Z,1753119635.937 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T17:40:46.778Z,1753119646.778 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Express0047.lzma.bak
2025-07-21T17:40:46.778Z,1753119646.778 [DataOverHttps](INFO): SBD MOMSN=25563377
2025-07-21T17:40:48.125Z,1753119648.125 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T17:40:48.125Z,1753119648.125 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T17:40:48.125Z,1753119648.125 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T17:45:48.755Z,1753119948.755 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T17:45:48.756Z,1753119948.756 [Default:CheckIn:C.Wait] Stopped
2025-07-21T17:45:48.756Z,1753119948.756 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T17:45:48.756Z,1753119948.756 [Default:CheckIn:D] Running Loop=1
2025-07-21T17:45:49.161Z,1753119949.161 [Default:CheckIn:D] Stopped
2025-07-21T17:45:49.161Z,1753119949.161 [Default:CheckIn:E] Running Loop=1
2025-07-21T17:45:49.576Z,1753119949.576 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  110.512793 min 
2025-07-21T17:45:49.576Z,1753119949.576 [Default:CheckIn:E] Stopped
2025-07-21T17:45:49.576Z,1753119949.576 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T17:45:49.576Z,1753119949.576 [Default:CheckIn] Stopped
2025-07-21T17:45:49.576Z,1753119949.576 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T17:45:49.576Z,1753119949.576 [Default:CheckIn](INFO): Running loop #15
2025-07-21T17:45:49.576Z,1753119949.576 [Default:CheckIn] Running Loop=15
2025-07-21T17:45:49.576Z,1753119949.576 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T17:45:49.577Z,1753119949.577 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T17:45:51.579Z,1753119951.579 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174550.00,A,3648.16474,N,12147.21531,W,0.000,17.12,210725,,,D*41
2025-07-21T17:45:51.581Z,1753119951.581 [NAL9602](INFO): GPS fix at 20250721T174550: (36.802746, -121.786922)
2025-07-21T17:45:51.592Z,1753119951.592 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T17:45:51.592Z,1753119951.592 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T17:45:59.143Z,1753119959.143 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250721T155012/Courier0049.lzma
2025-07-21T17:46:09.365Z,1753119969.365 [NAL9602](INFO): SBD MO Status=0, MOMSN=25776, MT Status=0, MTMSN=0
2025-07-21T17:46:09.365Z,1753119969.365 [NAL9602](INFO): No messages in MT queue
2025-07-21T17:46:10.198Z,1753119970.198 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Courier0049.lzma.bak
2025-07-21T17:46:10.198Z,1753119970.198 [DataOverHttps](INFO): SBD MOMSN=25563401
2025-07-21T17:46:36.180Z,1753119996.180 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20250721T155012/Express0050.lzma
2025-07-21T17:46:40.058Z,1753120000.058 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T17:46:47.224Z,1753120007.224 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Express0050.lzma.bak
2025-07-21T17:46:47.224Z,1753120007.224 [DataOverHttps](INFO): SBD MOMSN=25563405
2025-07-21T17:46:48.614Z,1753120008.614 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T17:46:48.614Z,1753120008.614 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T17:46:48.614Z,1753120008.614 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T17:51:49.201Z,1753120309.201 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T17:51:49.201Z,1753120309.201 [Default:CheckIn:C.Wait] Stopped
2025-07-21T17:51:49.201Z,1753120309.201 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T17:51:49.201Z,1753120309.201 [Default:CheckIn:D] Running Loop=1
2025-07-21T17:51:49.614Z,1753120309.614 [Default:CheckIn:D] Stopped
2025-07-21T17:51:49.614Z,1753120309.614 [Default:CheckIn:E] Running Loop=1
2025-07-21T17:51:50.007Z,1753120310.007 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  116.520353 min 
2025-07-21T17:51:50.007Z,1753120310.007 [Default:CheckIn:E] Stopped
2025-07-21T17:51:50.007Z,1753120310.007 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T17:51:50.007Z,1753120310.007 [Default:CheckIn] Stopped
2025-07-21T17:51:50.007Z,1753120310.007 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T17:51:50.008Z,1753120310.008 [Default:CheckIn](INFO): Running loop #16
2025-07-21T17:51:50.008Z,1753120310.008 [Default:CheckIn] Running Loop=16
2025-07-21T17:51:50.008Z,1753120310.008 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T17:51:50.008Z,1753120310.008 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T17:51:52.027Z,1753120312.027 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175151.00,A,3648.16402,N,12147.21512,W,0.019,17.12,210725,,,D*4D
2025-07-21T17:51:52.029Z,1753120312.029 [NAL9602](INFO): GPS fix at 20250721T175151: (36.802734, -121.786919)
2025-07-21T17:51:52.040Z,1753120312.040 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T17:51:52.040Z,1753120312.040 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T17:51:59.055Z,1753120319.055 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250721T155012/Courier0052.lzma
2025-07-21T17:52:04.152Z,1753120324.152 [NAL9602](INFO): SBD MO Status=0, MOMSN=25777, MT Status=0, MTMSN=0
2025-07-21T17:52:04.152Z,1753120324.152 [NAL9602](INFO): No messages in MT queue
2025-07-21T17:52:10.098Z,1753120330.098 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Courier0052.lzma.bak
2025-07-21T17:52:10.098Z,1753120330.098 [DataOverHttps](INFO): SBD MOMSN=25563431
2025-07-21T17:52:34.841Z,1753120354.841 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T17:52:36.012Z,1753120356.012 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20250721T155012/Express0053.lzma
2025-07-21T17:52:47.055Z,1753120367.055 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Express0053.lzma.bak
2025-07-21T17:52:47.055Z,1753120367.055 [DataOverHttps](INFO): SBD MOMSN=25563435
2025-07-21T17:52:48.193Z,1753120368.193 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T17:52:48.194Z,1753120368.194 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T17:52:48.194Z,1753120368.194 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T17:57:48.759Z,1753120668.759 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T17:57:48.760Z,1753120668.760 [Default:CheckIn:C.Wait] Stopped
2025-07-21T17:57:48.760Z,1753120668.760 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T17:57:48.760Z,1753120668.760 [Default:CheckIn:D] Running Loop=1
2025-07-21T17:57:49.166Z,1753120669.166 [Default:CheckIn:D] Stopped
2025-07-21T17:57:49.166Z,1753120669.166 [Default:CheckIn:E] Running Loop=1
2025-07-21T17:57:49.584Z,1753120669.584 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  122.512874 min 
2025-07-21T17:57:49.584Z,1753120669.584 [Default:CheckIn:E] Stopped
2025-07-21T17:57:49.584Z,1753120669.584 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T17:57:49.584Z,1753120669.584 [Default:CheckIn] Stopped
2025-07-21T17:57:49.585Z,1753120669.585 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T17:57:49.585Z,1753120669.585 [Default:CheckIn](INFO): Running loop #17
2025-07-21T17:57:49.585Z,1753120669.585 [Default:CheckIn] Running Loop=17
2025-07-21T17:57:49.585Z,1753120669.585 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T17:57:49.585Z,1753120669.585 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T17:57:51.583Z,1753120671.583 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175750.00,A,3648.16471,N,12147.21443,W,0.039,17.12,210725,,,D*49
2025-07-21T17:57:51.585Z,1753120671.585 [NAL9602](INFO): GPS fix at 20250721T175750: (36.802745, -121.786907)
2025-07-21T17:57:51.620Z,1753120671.620 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T17:57:51.620Z,1753120671.620 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T17:57:58.811Z,1753120678.811 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250721T155012/Courier0055.lzma
2025-07-21T17:58:06.931Z,1753120686.931 [NAL9602](INFO): SBD MO Status=0, MOMSN=25778, MT Status=0, MTMSN=0
2025-07-21T17:58:06.931Z,1753120686.931 [NAL9602](INFO): No messages in MT queue
2025-07-21T17:58:09.854Z,1753120689.854 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Courier0055.lzma.bak
2025-07-21T17:58:09.854Z,1753120689.854 [DataOverHttps](INFO): SBD MOMSN=25563457
2025-07-21T17:58:35.756Z,1753120715.756 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20250721T155012/Express0056.lzma
2025-07-21T17:58:37.633Z,1753120717.633 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T17:58:46.798Z,1753120726.798 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Express0056.lzma.bak
2025-07-21T17:58:46.798Z,1753120726.798 [DataOverHttps](INFO): SBD MOMSN=25563461
2025-07-21T17:58:48.163Z,1753120728.163 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T17:58:48.163Z,1753120728.163 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T17:58:48.163Z,1753120728.163 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T18:03:48.733Z,1753121028.733 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T18:03:48.734Z,1753121028.734 [Default:CheckIn:C.Wait] Stopped
2025-07-21T18:03:48.734Z,1753121028.734 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T18:03:48.734Z,1753121028.734 [Default:CheckIn:D] Running Loop=1
2025-07-21T18:03:49.149Z,1753121029.149 [Default:CheckIn:D] Stopped
2025-07-21T18:03:49.149Z,1753121029.149 [Default:CheckIn:E] Running Loop=1
2025-07-21T18:03:49.543Z,1753121029.543 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  128.512598 min 
2025-07-21T18:03:49.543Z,1753121029.543 [Default:CheckIn:E] Stopped
2025-07-21T18:03:49.560Z,1753121029.560 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T18:03:49.560Z,1753121029.560 [Default:CheckIn] Stopped
2025-07-21T18:03:49.560Z,1753121029.560 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T18:03:49.560Z,1753121029.560 [Default:CheckIn](INFO): Running loop #18
2025-07-21T18:03:49.560Z,1753121029.560 [Default:CheckIn] Running Loop=18
2025-07-21T18:03:49.560Z,1753121029.560 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T18:03:49.560Z,1753121029.560 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T18:03:51.553Z,1753121031.553 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180350.00,A,3648.23047,N,12147.13831,W,2.935,13.64,210725,,,D*4A
2025-07-21T18:03:51.555Z,1753121031.555 [NAL9602](INFO): GPS fix at 20250721T180350: (36.803841, -121.785639)
2025-07-21T18:03:51.570Z,1753121031.570 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T18:03:51.570Z,1753121031.570 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T18:03:59.295Z,1753121039.295 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250721T155012/Courier0058.lzma
2025-07-21T18:04:08.138Z,1753121048.138 [NAL9602](INFO): SBD MO Status=0, MOMSN=25779, MT Status=0, MTMSN=0
2025-07-21T18:04:08.138Z,1753121048.138 [NAL9602](INFO): No messages in MT queue
2025-07-21T18:04:10.339Z,1753121050.339 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Courier0058.lzma.bak
2025-07-21T18:04:10.339Z,1753121050.339 [DataOverHttps](INFO): SBD MOMSN=25563487
2025-07-21T18:04:36.320Z,1753121076.320 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20250721T155012/Express0059.lzma
2025-07-21T18:04:38.829Z,1753121078.829 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T18:04:47.362Z,1753121087.362 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Express0059.lzma.bak
2025-07-21T18:04:47.362Z,1753121087.362 [DataOverHttps](INFO): SBD MOMSN=25563491
2025-07-21T18:04:48.555Z,1753121088.555 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T18:04:48.555Z,1753121088.555 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T18:04:48.555Z,1753121088.555 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T18:09:49.124Z,1753121389.124 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T18:09:49.124Z,1753121389.124 [Default:CheckIn:C.Wait] Stopped
2025-07-21T18:09:49.124Z,1753121389.124 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T18:09:49.124Z,1753121389.124 [Default:CheckIn:D] Running Loop=1
2025-07-21T18:09:49.540Z,1753121389.540 [Default:CheckIn:D] Stopped
2025-07-21T18:09:49.540Z,1753121389.540 [Default:CheckIn:E] Running Loop=1
2025-07-21T18:09:49.931Z,1753121389.931 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  134.519108 min 
2025-07-21T18:09:49.931Z,1753121389.931 [Default:CheckIn:E] Stopped
2025-07-21T18:09:49.931Z,1753121389.931 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T18:09:49.931Z,1753121389.931 [Default:CheckIn] Stopped
2025-07-21T18:09:49.932Z,1753121389.932 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T18:09:49.932Z,1753121389.932 [Default:CheckIn](INFO): Running loop #19
2025-07-21T18:09:49.932Z,1753121389.932 [Default:CheckIn] Running Loop=19
2025-07-21T18:09:49.932Z,1753121389.932 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T18:09:49.932Z,1753121389.932 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T18:09:51.941Z,1753121391.941 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180951.00,A,3648.51848,N,12147.19772,W,4.510,245.83,210725,,,D*74
2025-07-21T18:09:51.943Z,1753121391.943 [NAL9602](INFO): GPS fix at 20250721T180951: (36.808641, -121.786629)
2025-07-21T18:09:51.954Z,1753121391.954 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T18:09:51.954Z,1753121391.954 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T18:09:59.211Z,1753121399.211 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250721T155012/Courier0061.lzma
2025-07-21T18:10:10.254Z,1753121410.254 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Courier0061.lzma.bak
2025-07-21T18:10:10.254Z,1753121410.254 [DataOverHttps](INFO): SBD MOMSN=25563516
2025-07-21T18:10:24.662Z,1753121424.662 [NAL9602](INFO): SBD MO Status=0, MOMSN=25780, MT Status=0, MTMSN=0
2025-07-21T18:10:24.662Z,1753121424.662 [NAL9602](INFO): No messages in MT queue
2025-07-21T18:10:36.224Z,1753121436.224 [DataOverHttps](INFO): Sending 175 bytes from file Logs/20250721T155012/Express0062.lzma
2025-07-21T18:10:47.274Z,1753121447.274 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Express0062.lzma.bak
2025-07-21T18:10:47.274Z,1753121447.274 [DataOverHttps](INFO): SBD MOMSN=25563519
2025-07-21T18:10:48.523Z,1753121448.523 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T18:10:48.523Z,1753121448.523 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T18:10:48.523Z,1753121448.523 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T18:10:55.369Z,1753121455.369 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T18:15:49.092Z,1753121749.092 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T18:15:49.092Z,1753121749.092 [Default:CheckIn:C.Wait] Stopped
2025-07-21T18:15:49.092Z,1753121749.092 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T18:15:49.092Z,1753121749.092 [Default:CheckIn:D] Running Loop=1
2025-07-21T18:15:49.503Z,1753121749.503 [Default:CheckIn:D] Stopped
2025-07-21T18:15:49.503Z,1753121749.503 [Default:CheckIn:E] Running Loop=1
2025-07-21T18:15:49.902Z,1753121749.902 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  140.518490 min 
2025-07-21T18:15:49.902Z,1753121749.902 [Default:CheckIn:E] Stopped
2025-07-21T18:15:49.903Z,1753121749.903 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T18:15:49.903Z,1753121749.903 [Default:CheckIn] Stopped
2025-07-21T18:15:49.903Z,1753121749.903 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T18:15:49.903Z,1753121749.903 [Default:CheckIn](INFO): Running loop #20
2025-07-21T18:15:49.903Z,1753121749.903 [Default:CheckIn] Running Loop=20
2025-07-21T18:15:49.903Z,1753121749.903 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T18:15:49.903Z,1753121749.903 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T18:15:51.920Z,1753121751.920 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181551.00,A,3648.31120,N,12147.64671,W,4.335,257.87,210725,,,D*76
2025-07-21T18:15:51.922Z,1753121751.922 [NAL9602](INFO): GPS fix at 20250721T181551: (36.805187, -121.794112)
2025-07-21T18:15:51.933Z,1753121751.933 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T18:15:51.934Z,1753121751.934 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T18:15:59.315Z,1753121759.315 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250721T155012/Courier0064.lzma
2025-07-21T18:16:10.358Z,1753121770.358 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Courier0064.lzma.bak
2025-07-21T18:16:10.358Z,1753121770.358 [DataOverHttps](INFO): SBD MOMSN=25563548
2025-07-21T18:16:16.968Z,1753121776.968 [NAL9602](INFO): SBD MO Status=0, MOMSN=25781, MT Status=0, MTMSN=0
2025-07-21T18:16:16.968Z,1753121776.968 [NAL9602](INFO): No messages in MT queue
2025-07-21T18:16:36.288Z,1753121796.288 [DataOverHttps](INFO): Sending 175 bytes from file Logs/20250721T155012/Express0065.lzma
2025-07-21T18:16:47.330Z,1753121807.330 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Express0065.lzma.bak
2025-07-21T18:16:47.330Z,1753121807.330 [DataOverHttps](INFO): SBD MOMSN=25563552
2025-07-21T18:16:47.665Z,1753121807.665 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T18:16:48.499Z,1753121808.499 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T18:16:48.499Z,1753121808.499 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T18:16:48.499Z,1753121808.499 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T18:21:49.115Z,1753122109.115 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T18:21:49.115Z,1753122109.115 [Default:CheckIn:C.Wait] Stopped
2025-07-21T18:21:49.115Z,1753122109.115 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T18:21:49.115Z,1753122109.115 [Default:CheckIn:D] Running Loop=1
2025-07-21T18:21:49.520Z,1753122109.520 [Default:CheckIn:D] Stopped
2025-07-21T18:21:49.520Z,1753122109.520 [Default:CheckIn:E] Running Loop=1
2025-07-21T18:21:49.928Z,1753122109.928 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  146.518783 min 
2025-07-21T18:21:49.928Z,1753122109.928 [Default:CheckIn:E] Stopped
2025-07-21T18:21:49.928Z,1753122109.928 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T18:21:49.928Z,1753122109.928 [Default:CheckIn] Stopped
2025-07-21T18:21:49.929Z,1753122109.929 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T18:21:49.929Z,1753122109.929 [Default:CheckIn](INFO): Running loop #21
2025-07-21T18:21:49.929Z,1753122109.929 [Default:CheckIn] Running Loop=21
2025-07-21T18:21:49.929Z,1753122109.929 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T18:21:49.929Z,1753122109.929 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T18:21:51.935Z,1753122111.935 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182151.00,A,3648.29987,N,12148.12325,W,3.654,266.82,210725,,,D*75
2025-07-21T18:21:51.937Z,1753122111.937 [NAL9602](INFO): GPS fix at 20250721T182151: (36.804998, -121.802054)
2025-07-21T18:21:51.977Z,1753122111.977 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T18:21:51.977Z,1753122111.977 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T18:21:59.459Z,1753122119.459 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250721T155012/Courier0067.lzma
2025-07-21T18:22:07.701Z,1753122127.701 [NAL9602](INFO): SBD MO Status=0, MOMSN=25782, MT Status=0, MTMSN=0
2025-07-21T18:22:07.701Z,1753122127.701 [NAL9602](INFO): No messages in MT queue
2025-07-21T18:22:10.502Z,1753122130.502 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Courier0067.lzma.bak
2025-07-21T18:22:10.502Z,1753122130.502 [DataOverHttps](INFO): SBD MOMSN=25563577
2025-07-21T18:22:36.392Z,1753122156.392 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20250721T155012/Express0068.lzma
2025-07-21T18:22:38.389Z,1753122158.389 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T18:22:47.442Z,1753122167.442 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Express0068.lzma.bak
2025-07-21T18:22:47.442Z,1753122167.442 [DataOverHttps](INFO): SBD MOMSN=25563587
2025-07-21T18:22:48.507Z,1753122168.507 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T18:22:48.508Z,1753122168.508 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T18:22:48.508Z,1753122168.508 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T18:27:49.115Z,1753122469.115 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T18:27:49.115Z,1753122469.115 [Default:CheckIn:C.Wait] Stopped
2025-07-21T18:27:49.115Z,1753122469.115 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T18:27:49.115Z,1753122469.115 [Default:CheckIn:D] Running Loop=1
2025-07-21T18:27:49.526Z,1753122469.526 [Default:CheckIn:D] Stopped
2025-07-21T18:27:49.526Z,1753122469.526 [Default:CheckIn:E] Running Loop=1
2025-07-21T18:27:49.940Z,1753122469.940 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  152.518880 min 
2025-07-21T18:27:49.940Z,1753122469.940 [Default:CheckIn:E] Stopped
2025-07-21T18:27:49.941Z,1753122469.941 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T18:27:49.941Z,1753122469.941 [Default:CheckIn] Stopped
2025-07-21T18:27:49.941Z,1753122469.941 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T18:27:49.941Z,1753122469.941 [Default:CheckIn](INFO): Running loop #22
2025-07-21T18:27:49.941Z,1753122469.941 [Default:CheckIn] Running Loop=22
2025-07-21T18:27:49.941Z,1753122469.941 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T18:27:49.941Z,1753122469.941 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T18:27:51.935Z,1753122471.935 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182751.00,A,3648.24050,N,12148.61118,W,4.724,263.20,210725,,,D*79
2025-07-21T18:27:51.937Z,1753122471.937 [NAL9602](INFO): GPS fix at 20250721T182751: (36.804008, -121.810186)
2025-07-21T18:27:51.948Z,1753122471.948 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T18:27:51.948Z,1753122471.948 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T18:27:59.751Z,1753122479.751 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250721T155012/Courier0070.lzma
2025-07-21T18:28:10.794Z,1753122490.794 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Courier0070.lzma.bak
2025-07-21T18:28:10.794Z,1753122490.794 [DataOverHttps](INFO): SBD MOMSN=25563622
2025-07-21T18:28:22.643Z,1753122502.643 [NAL9602](INFO): SBD MO Status=2, MOMSN=25783, MT Status=2, MTMSN=0
2025-07-21T18:28:22.643Z,1753122502.643 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-21T18:28:31.934Z,1753122511.934 [NAL9602](INFO): SBD MO Status=0, MOMSN=25783, MT Status=0, MTMSN=0
2025-07-21T18:28:31.934Z,1753122511.934 [NAL9602](INFO): No messages in MT queue
2025-07-21T18:28:36.716Z,1753122516.716 [DataOverHttps](INFO): Sending 177 bytes from file Logs/20250721T155012/Express0071.lzma
2025-07-21T18:28:47.766Z,1753122527.766 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Express0071.lzma.bak
2025-07-21T18:28:47.766Z,1753122527.766 [DataOverHttps](INFO): SBD MOMSN=25563628
2025-07-21T18:28:48.928Z,1753122528.928 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T18:28:48.928Z,1753122528.928 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T18:28:48.928Z,1753122528.928 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T18:29:02.645Z,1753122542.645 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T18:31:16.368Z,1753122676.368 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:31:30.103Z,1753122690.103 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:31:44.243Z,1753122704.243 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:31:57.575Z,1753122717.575 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:32:11.314Z,1753122731.314 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:32:25.048Z,1753122745.048 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:32:38.392Z,1753122758.392 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:32:51.711Z,1753122771.711 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:33:05.455Z,1753122785.455 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:33:19.194Z,1753122799.194 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:33:49.523Z,1753122829.523 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T18:33:49.523Z,1753122829.523 [Default:CheckIn:C.Wait] Stopped
2025-07-21T18:33:49.523Z,1753122829.523 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T18:33:49.523Z,1753122829.523 [Default:CheckIn:D] Running Loop=1
2025-07-21T18:33:49.910Z,1753122829.910 [Default:CheckIn:D] Stopped
2025-07-21T18:33:49.910Z,1753122829.910 [Default:CheckIn:E] Running Loop=1
2025-07-21T18:33:50.329Z,1753122830.329 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  158.525277 min 
2025-07-21T18:33:50.329Z,1753122830.329 [Default:CheckIn:E] Stopped
2025-07-21T18:33:50.330Z,1753122830.330 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T18:33:50.330Z,1753122830.330 [Default:CheckIn] Stopped
2025-07-21T18:33:50.330Z,1753122830.330 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T18:33:50.330Z,1753122830.330 [Default:CheckIn](INFO): Running loop #23
2025-07-21T18:33:50.330Z,1753122830.330 [Default:CheckIn] Running Loop=23
2025-07-21T18:33:50.330Z,1753122830.330 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T18:33:50.330Z,1753122830.330 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T18:33:52.331Z,1753122832.331 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183351.00,A,3648.29374,N,12149.16339,W,4.140,298.66,210725,,,D*76
2025-07-21T18:33:52.333Z,1753122832.333 [NAL9602](INFO): GPS fix at 20250721T183351: (36.804896, -121.819390)
2025-07-21T18:33:52.377Z,1753122832.377 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T18:33:52.378Z,1753122832.378 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T18:33:59.983Z,1753122839.983 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250721T155012/Courier0073.lzma
2025-07-21T18:34:07.287Z,1753122847.287 [NAL9602](INFO): SBD MO Status=0, MOMSN=25784, MT Status=0, MTMSN=0
2025-07-21T18:34:07.287Z,1753122847.287 [NAL9602](INFO): No messages in MT queue
2025-07-21T18:34:11.026Z,1753122851.026 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Courier0073.lzma.bak
2025-07-21T18:34:11.026Z,1753122851.026 [DataOverHttps](INFO): SBD MOMSN=25563653
2025-07-21T18:34:36.948Z,1753122876.948 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20250721T155012/Express0074.lzma
2025-07-21T18:34:37.994Z,1753122877.994 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T18:34:47.990Z,1753122887.990 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Express0074.lzma.bak
2025-07-21T18:34:47.990Z,1753122887.990 [DataOverHttps](INFO): SBD MOMSN=25563657
2025-07-21T18:34:49.335Z,1753122889.335 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T18:34:49.335Z,1753122889.335 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T18:34:49.335Z,1753122889.335 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T18:35:54.003Z,1753122954.003 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:36:09.357Z,1753122969.357 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:36:25.112Z,1753122985.112 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:36:40.059Z,1753123000.059 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:36:55.028Z,1753123015.028 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:37:11.224Z,1753123031.224 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:37:26.159Z,1753123046.159 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:37:41.108Z,1753123061.108 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:37:56.055Z,1753123076.055 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:38:11.004Z,1753123091.004 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7).
2025-07-21T18:39:50.012Z,1753123190.012 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T18:39:50.012Z,1753123190.012 [Default:CheckIn:C.Wait] Stopped
2025-07-21T18:39:50.012Z,1753123190.012 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T18:39:50.013Z,1753123190.013 [Default:CheckIn:D] Running Loop=1
2025-07-21T18:39:50.472Z,1753123190.472 [Default:CheckIn:D] Stopped
2025-07-21T18:39:50.472Z,1753123190.472 [Default:CheckIn:E] Running Loop=1
2025-07-21T18:39:50.842Z,1753123190.842 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  164.534652 min 
2025-07-21T18:39:50.842Z,1753123190.842 [Default:CheckIn:E] Stopped
2025-07-21T18:39:50.842Z,1753123190.842 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T18:39:50.843Z,1753123190.843 [Default:CheckIn] Stopped
2025-07-21T18:39:50.843Z,1753123190.843 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T18:39:50.843Z,1753123190.843 [Default:CheckIn](INFO): Running loop #24
2025-07-21T18:39:50.843Z,1753123190.843 [Default:CheckIn] Running Loop=24
2025-07-21T18:39:50.843Z,1753123190.843 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T18:39:50.843Z,1753123190.843 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T18:39:52.835Z,1753123192.835 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183952.00,A,3648.29716,N,12149.37621,W,2.371,243.60,210725,,,D*76
2025-07-21T18:39:52.837Z,1753123192.837 [NAL9602](INFO): GPS fix at 20250721T183952: (36.804953, -121.822937)
2025-07-21T18:39:52.848Z,1753123192.848 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T18:39:52.848Z,1753123192.848 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T18:40:01.619Z,1753123201.619 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250721T155012/Courier0076.lzma
2025-07-21T18:40:07.394Z,1753123207.394 [NAL9602](INFO): SBD MO Status=0, MOMSN=25785, MT Status=0, MTMSN=0
2025-07-21T18:40:07.394Z,1753123207.394 [NAL9602](INFO): No messages in MT queue
2025-07-21T18:40:12.662Z,1753123212.662 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Courier0076.lzma.bak
2025-07-21T18:40:12.662Z,1753123212.662 [DataOverHttps](INFO): SBD MOMSN=25563681
2025-07-21T18:40:38.113Z,1753123238.113 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T18:40:38.542Z,1753123238.542 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:40:41.084Z,1753123241.084 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20250721T155012/Express0077.lzma
2025-07-21T18:40:51.837Z,1753123251.837 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:40:52.138Z,1753123252.138 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Express0077.lzma.bak
2025-07-21T18:40:52.138Z,1753123252.138 [DataOverHttps](INFO): SBD MOMSN=25563685
2025-07-21T18:40:53.481Z,1753123253.481 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T18:40:53.481Z,1753123253.481 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T18:40:53.481Z,1753123253.481 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T18:41:05.167Z,1753123265.167 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:41:18.907Z,1753123278.907 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:41:32.647Z,1753123292.647 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:41:46.378Z,1753123306.378 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:41:59.709Z,1753123319.709 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:42:13.443Z,1753123333.443 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:42:27.583Z,1753123347.583 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:42:41.322Z,1753123361.322 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:42:55.089Z,1753123375.089 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:43:02.373Z,1753123382.373 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-07-21T18:45:18.116Z,1753123518.116 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:45:24.579Z,1753123524.579 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:45:24.582Z,1753123524.582 [BPC1](ERROR): BPC1B: No match for serial number FFFF in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-07-21T18:45:39.931Z,1753123539.931 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:45:54.084Z,1753123554.084 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T18:45:54.084Z,1753123554.084 [Default:CheckIn:C.Wait] Stopped
2025-07-21T18:45:54.084Z,1753123554.084 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T18:45:54.085Z,1753123554.085 [Default:CheckIn:D] Running Loop=1
2025-07-21T18:45:54.491Z,1753123554.491 [Default:CheckIn:D] Stopped
2025-07-21T18:45:54.491Z,1753123554.491 [Default:CheckIn:E] Running Loop=1
2025-07-21T18:45:54.939Z,1753123554.939 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  170.601628 min 
2025-07-21T18:45:54.939Z,1753123554.939 [Default:CheckIn:E] Stopped
2025-07-21T18:45:54.939Z,1753123554.939 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T18:45:54.939Z,1753123554.939 [Default:CheckIn] Stopped
2025-07-21T18:45:54.956Z,1753123554.956 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T18:45:54.956Z,1753123554.956 [Default:CheckIn](INFO): Running loop #25
2025-07-21T18:45:54.956Z,1753123554.956 [Default:CheckIn] Running Loop=25
2025-07-21T18:45:54.956Z,1753123554.956 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T18:45:54.957Z,1753123554.957 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T18:45:55.284Z,1753123555.284 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:45:56.903Z,1753123556.903 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184556.00,A,3648.39022,N,12149.44978,W,1.672,349.92,210725,,,D*7C
2025-07-21T18:45:56.905Z,1753123556.905 [NAL9602](INFO): GPS fix at 20250721T184556: (36.806504, -121.824163)
2025-07-21T18:45:56.917Z,1753123556.917 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T18:45:56.917Z,1753123556.917 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T18:46:05.591Z,1753123565.591 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250721T155012/Courier0079.lzma
2025-07-21T18:46:10.232Z,1753123570.232 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:46:16.634Z,1753123576.634 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Courier0079.lzma.bak
2025-07-21T18:46:16.634Z,1753123576.634 [DataOverHttps](INFO): SBD MOMSN=25563715
2025-07-21T18:46:21.955Z,1753123581.955 [NAL9602](INFO): SBD MO Status=0, MOMSN=25786, MT Status=0, MTMSN=0
2025-07-21T18:46:21.955Z,1753123581.955 [NAL9602](INFO): No messages in MT queue
2025-07-21T18:46:25.583Z,1753123585.583 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:46:40.532Z,1753123600.532 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:46:42.708Z,1753123602.708 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20250721T155012/Express0080.lzma
2025-07-21T18:46:52.673Z,1753123612.673 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T18:46:53.750Z,1753123613.750 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Express0080.lzma.bak
2025-07-21T18:46:53.750Z,1753123613.750 [DataOverHttps](INFO): SBD MOMSN=25563718
2025-07-21T18:46:55.150Z,1753123615.150 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T18:46:55.150Z,1753123615.150 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T18:46:55.150Z,1753123615.150 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T18:46:55.491Z,1753123615.491 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:47:10.845Z,1753123630.845 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:47:25.792Z,1753123645.792 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:50:02.139Z,1753123802.139 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:50:15.472Z,1753123815.472 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:50:28.805Z,1753123828.805 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:50:42.539Z,1753123842.539 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:50:56.685Z,1753123856.685 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:51:10.420Z,1753123870.420 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:51:23.752Z,1753123883.752 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:51:30.512Z,1753123890.512 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-07-21T18:51:37.892Z,1753123897.892 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:51:52.031Z,1753123912.031 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:51:55.691Z,1753123915.691 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T18:51:55.691Z,1753123915.691 [Default:CheckIn:C.Wait] Stopped
2025-07-21T18:51:55.692Z,1753123915.692 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T18:51:55.692Z,1753123915.692 [Default:CheckIn:D] Running Loop=1
2025-07-21T18:51:56.091Z,1753123916.091 [Default:CheckIn:D] Stopped
2025-07-21T18:51:56.091Z,1753123916.091 [Default:CheckIn:E] Running Loop=1
2025-07-21T18:51:56.508Z,1753123916.508 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  176.628288 min 
2025-07-21T18:51:56.508Z,1753123916.508 [Default:CheckIn:E] Stopped
2025-07-21T18:51:56.508Z,1753123916.508 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T18:51:56.508Z,1753123916.508 [Default:CheckIn] Stopped
2025-07-21T18:51:56.508Z,1753123916.508 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T18:51:56.509Z,1753123916.509 [Default:CheckIn](INFO): Running loop #26
2025-07-21T18:51:56.509Z,1753123916.509 [Default:CheckIn] Running Loop=26
2025-07-21T18:51:56.509Z,1753123916.509 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T18:51:56.509Z,1753123916.509 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T18:51:58.499Z,1753123918.499 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185157.00,A,3648.46279,N,12149.44169,W,2.644,106.36,210725,,,D*75
2025-07-21T18:51:58.502Z,1753123918.502 [NAL9602](INFO): GPS fix at 20250721T185157: (36.807713, -121.824028)
2025-07-21T18:51:58.514Z,1753123918.514 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T18:51:58.514Z,1753123918.514 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T18:52:05.511Z,1753123925.511 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250721T155012/Courier0082.lzma
2025-07-21T18:52:05.763Z,1753123925.763 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:52:13.451Z,1753123933.451 [NAL9602](INFO): SBD MO Status=0, MOMSN=25787, MT Status=0, MTMSN=0
2025-07-21T18:52:13.451Z,1753123933.451 [NAL9602](INFO): No messages in MT queue
2025-07-21T18:52:16.554Z,1753123936.554 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Courier0082.lzma.bak
2025-07-21T18:52:16.554Z,1753123936.554 [DataOverHttps](INFO): SBD MOMSN=25563743
2025-07-21T18:52:19.499Z,1753123939.499 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:52:44.149Z,1753123964.149 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T18:52:48.117Z,1753123968.117 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-07-21T18:53:18.944Z,1753123998.944 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20250721T155012/Express0083.lzma
2025-07-21T18:53:30.998Z,1753124010.998 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Express0083.lzma.bak
2025-07-21T18:53:30.998Z,1753124010.998 [DataOverHttps](INFO): SBD MOMSN=25563747
2025-07-21T18:53:32.296Z,1753124012.296 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T18:53:32.296Z,1753124012.296 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T18:53:32.296Z,1753124012.296 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T18:54:42.572Z,1753124082.572 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:54:49.504Z,1753124089.504 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:55:04.796Z,1753124104.796 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:55:19.745Z,1753124119.745 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:55:34.692Z,1753124134.692 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:55:50.044Z,1753124150.044 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:56:04.993Z,1753124164.993 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:56:19.941Z,1753124179.941 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:56:35.292Z,1753124195.292 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:56:50.252Z,1753124210.252 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:58:32.886Z,1753124312.886 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T18:58:32.886Z,1753124312.886 [Default:CheckIn:C.Wait] Stopped
2025-07-21T18:58:32.886Z,1753124312.886 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T18:58:32.886Z,1753124312.886 [Default:CheckIn:D] Running Loop=1
2025-07-21T18:58:33.304Z,1753124313.304 [Default:CheckIn:D] Stopped
2025-07-21T18:58:33.305Z,1753124313.305 [Default:CheckIn:E] Running Loop=1
2025-07-21T18:58:33.695Z,1753124313.695 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  183.248519 min 
2025-07-21T18:58:33.695Z,1753124313.695 [Default:CheckIn:E] Stopped
2025-07-21T18:58:33.695Z,1753124313.695 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T18:58:33.696Z,1753124313.696 [Default:CheckIn] Stopped
2025-07-21T18:58:33.696Z,1753124313.696 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T18:58:33.696Z,1753124313.696 [Default:CheckIn](INFO): Running loop #27
2025-07-21T18:58:33.696Z,1753124313.696 [Default:CheckIn] Running Loop=27
2025-07-21T18:58:33.696Z,1753124313.696 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T18:58:33.696Z,1753124313.696 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T18:58:35.687Z,1753124315.687 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185834.00,A,3648.37327,N,12149.47671,W,1.263,249.81,210725,,,D*7E
2025-07-21T18:58:35.689Z,1753124315.689 [NAL9602](INFO): GPS fix at 20250721T185834: (36.806221, -121.824612)
2025-07-21T18:58:35.712Z,1753124315.712 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T18:58:35.712Z,1753124315.712 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T18:58:43.703Z,1753124323.703 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250721T155012/Courier0085.lzma
2025-07-21T18:58:52.655Z,1753124332.655 [NAL9602](INFO): SBD MO Status=0, MOMSN=25788, MT Status=0, MTMSN=0
2025-07-21T18:58:52.655Z,1753124332.655 [NAL9602](INFO): No messages in MT queue
2025-07-21T18:58:54.750Z,1753124334.750 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Courier0085.lzma.bak
2025-07-21T18:58:54.750Z,1753124334.750 [DataOverHttps](INFO): SBD MOMSN=25563779
2025-07-21T18:59:20.820Z,1753124360.820 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20250721T155012/Express0086.lzma
2025-07-21T18:59:23.363Z,1753124363.363 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T18:59:26.184Z,1753124366.184 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:59:31.862Z,1753124371.862 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Express0086.lzma.bak
2025-07-21T18:59:31.862Z,1753124371.862 [DataOverHttps](INFO): SBD MOMSN=25563787
2025-07-21T18:59:33.071Z,1753124373.071 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T18:59:33.071Z,1753124373.071 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T18:59:33.071Z,1753124373.071 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T18:59:39.515Z,1753124379.515 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T18:59:52.848Z,1753124392.848 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T19:00:06.583Z,1753124406.583 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-21T19:00:19.119Z,1753124419.119 [BPC1](ERROR): Battery stick #16 (s/n: 00F8) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-07-21T19:00:19.120Z,1753124419.120 [BPC1](FAULT): Battery stick #24 (s/n: 01D3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-07-21T19:00:19.120Z,1753124419.120 [BPC1](FAULT): Battery stick #29 (s/n: 00A7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-21T19:00:19.121Z,1753124419.121 [BPC1](FAULT): Battery stick #51 (s/n: 01D6) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-07-21T19:00:19.122Z,1753124419.122 [BPC1](ERROR): Battery stick #52 (s/n: 0160) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-07-21T19:00:19.123Z,1753124419.123 [BPC1](FAULT): Battery stick #57 (s/n: 019F) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-07-21T19:00:19.123Z,1753124419.123 [BPC1](FAULT): Battery stick #59 (s/n: 0184) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0.
2025-07-21T19:00:19.124Z,1753124419.124 [BPC1](INFO): Calculating totals. Valid battery stick count: 41. Valid reserve battery stick count: 4.
2025-07-21T19:00:19.126Z,1753124419.126 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2025-07-21T19:04:33.643Z,1753124673.643 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T19:04:33.643Z,1753124673.643 [Default:CheckIn:C.Wait] Stopped
2025-07-21T19:04:33.643Z,1753124673.643 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T19:04:33.643Z,1753124673.643 [Default:CheckIn:D] Running Loop=1
2025-07-21T19:04:34.043Z,1753124674.043 [Default:CheckIn:D] Stopped
2025-07-21T19:04:34.043Z,1753124674.043 [Default:CheckIn:E] Running Loop=1
2025-07-21T19:04:34.450Z,1753124674.450 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  189.260824 min 
2025-07-21T19:04:34.450Z,1753124674.450 [Default:CheckIn:E] Stopped
2025-07-21T19:04:34.450Z,1753124674.450 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T19:04:34.450Z,1753124674.450 [Default:CheckIn] Stopped
2025-07-21T19:04:34.450Z,1753124674.450 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T19:04:34.450Z,1753124674.450 [Default:CheckIn](INFO): Running loop #28
2025-07-21T19:04:34.450Z,1753124674.450 [Default:CheckIn] Running Loop=28
2025-07-21T19:04:34.450Z,1753124674.450 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T19:04:34.451Z,1753124674.451 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T19:04:36.458Z,1753124676.458 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190435.00,A,3648.43397,N,12149.44576,W,1.711,105.15,210725,,,D*7E
2025-07-21T19:04:36.461Z,1753124676.461 [NAL9602](INFO): GPS fix at 20250721T190435: (36.807233, -121.824096)
2025-07-21T19:04:36.497Z,1753124676.497 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T19:04:36.497Z,1753124676.497 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T19:04:43.884Z,1753124683.884 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20250721T155012/Courier0088.lzma
2025-07-21T19:04:54.926Z,1753124694.926 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Courier0088.lzma.bak
2025-07-21T19:04:54.926Z,1753124694.926 [DataOverHttps](INFO): SBD MOMSN=25563803
2025-07-21T19:04:58.686Z,1753124698.686 [NAL9602](INFO): SBD MO Status=0, MOMSN=25789, MT Status=0, MTMSN=0
2025-07-21T19:04:58.687Z,1753124698.687 [NAL9602](INFO): No messages in MT queue
2025-07-21T19:05:25.880Z,1753124725.880 [DataOverHttps](INFO): Sending 322 bytes from file Logs/20250721T155012/Express0089.lzma
2025-07-21T19:05:29.381Z,1753124729.381 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T19:05:36.922Z,1753124736.922 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Express0089.lzma.bak
2025-07-21T19:05:36.922Z,1753124736.922 [DataOverHttps](INFO): SBD MOMSN=25563806
2025-07-21T19:05:38.283Z,1753124738.283 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T19:05:38.283Z,1753124738.283 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T19:05:38.283Z,1753124738.283 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T19:10:38.921Z,1753125038.921 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-21T19:10:38.921Z,1753125038.921 [Default:CheckIn:C.Wait] Stopped
2025-07-21T19:10:38.921Z,1753125038.921 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-21T19:10:38.921Z,1753125038.921 [Default:CheckIn:D] Running Loop=1
2025-07-21T19:10:39.319Z,1753125039.319 [Default:CheckIn:D] Stopped
2025-07-21T19:10:39.319Z,1753125039.319 [Default:CheckIn:E] Running Loop=1
2025-07-21T19:10:39.720Z,1753125039.720 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  195.348763 min 
2025-07-21T19:10:39.720Z,1753125039.720 [Default:CheckIn:E] Stopped
2025-07-21T19:10:39.720Z,1753125039.720 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-21T19:10:39.720Z,1753125039.720 [Default:CheckIn] Stopped
2025-07-21T19:10:39.720Z,1753125039.720 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-21T19:10:39.721Z,1753125039.721 [Default:CheckIn](INFO): Running loop #29
2025-07-21T19:10:39.721Z,1753125039.721 [Default:CheckIn] Running Loop=29
2025-07-21T19:10:39.721Z,1753125039.721 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-21T19:10:39.721Z,1753125039.721 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-21T19:10:41.734Z,1753125041.734 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191041.00,A,3647.93444,N,12150.42156,W,9.816,244.44,210725,,,D*79
2025-07-21T19:10:41.736Z,1753125041.736 [NAL9602](INFO): GPS fix at 20250721T191041: (36.798907, -121.840359)
2025-07-21T19:10:41.747Z,1753125041.747 [Default:CheckIn:Read_GPS] Stopped
2025-07-21T19:10:41.747Z,1753125041.747 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-21T19:10:51.396Z,1753125051.396 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250721T155012/Courier0091.lzma
2025-07-21T19:10:59.526Z,1753125059.526 [NAL9602](INFO): SBD MO Status=0, MOMSN=25790, MT Status=0, MTMSN=0
2025-07-21T19:10:59.526Z,1753125059.526 [NAL9602](INFO): No messages in MT queue
2025-07-21T19:11:02.438Z,1753125062.438 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Courier0091.lzma.bak
2025-07-21T19:11:02.438Z,1753125062.438 [DataOverHttps](INFO): SBD MOMSN=25563845
2025-07-21T19:11:28.380Z,1753125088.380 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20250721T155012/Express0092.lzma
2025-07-21T19:11:30.237Z,1753125090.237 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-21T19:11:39.427Z,1753125099.427 [DataOverHttps](INFO): Moved sent file to Logs/20250721T155012/Express0092.lzma.bak
2025-07-21T19:11:39.428Z,1753125099.428 [DataOverHttps](INFO): SBD MOMSN=25563856
2025-07-21T19:11:40.766Z,1753125100.766 [Default:CheckIn:Read_Iridium] Stopped
2025-07-21T19:11:40.766Z,1753125100.766 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-21T19:11:40.766Z,1753125100.766 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-21T19:14:38.385Z,1753125278.385 [DataOverHttps](IMPORTANT): SBD MTMSN=20250721T191437
2025-07-21T19:14:48.004Z,1753125288.004 [DataOverHttps](INFO): Received command: restart logs