2025-06-09T15:28:32.184Z,1749482912.184 [Supervisor](DEBUG): Initializing supervisor.
2025-06-09T15:28:32.189Z,1749482912.189 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-06-09T15:28:32.190Z,1749482912.190 [SyncHandler](INFO): Protected caller Thread ID is 6262
2025-06-09T15:28:32.190Z,1749482912.190 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-06-09T15:28:32.191Z,1749482912.191 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-06-09T15:28:32.192Z,1749482912.192 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6263
2025-06-09T15:28:32.196Z,1749482912.196 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-06-09T15:28:32.217Z,1749482912.217 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-06-09T15:28:32.218Z,1749482912.218 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-06-09T15:28:32.218Z,1749482912.218 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 6264
2025-06-09T15:28:32.222Z,1749482912.222 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-06-09T15:28:32.223Z,1749482912.223 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-06-09T15:28:32.224Z,1749482912.224 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6265
2025-06-09T15:28:32.226Z,1749482912.226 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-06-09T15:28:32.227Z,1749482912.227 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-06-09T15:28:32.227Z,1749482912.227 [logger ThreadHandler](INFO): Protected caller Thread ID is 6266
2025-06-09T15:28:32.231Z,1749482912.231 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-06-09T15:28:32.231Z,1749482912.231 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-06-09T15:28:32.233Z,1749482912.233 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-06-09T15:28:32.571Z,1749482912.571 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-06-09T15:28:32.571Z,1749482912.571 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-06-09T15:28:32.671Z,1749482912.671 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance
2025-06-09T15:28:32.672Z,1749482912.672 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-06-09T15:28:32.788Z,1749482912.788 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-06-09T15:28:32.788Z,1749482912.788 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-06-09T15:28:32.928Z,1749482912.928 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-06-09T15:28:32.928Z,1749482912.928 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-06-09T15:28:33.280Z,1749482913.280 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-06-09T15:28:33.280Z,1749482913.280 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-06-09T15:28:33.387Z,1749482913.387 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-06-09T15:28:33.387Z,1749482913.387 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-06-09T15:28:33.799Z,1749482913.799 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-06-09T15:28:33.800Z,1749482913.800 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-06-09T15:28:34.035Z,1749482914.035 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-06-09T15:28:34.035Z,1749482914.035 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-06-09T15:28:34.124Z,1749482914.124 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-06-09T15:28:34.375Z,1749482914.375 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-06-09T15:28:34.375Z,1749482914.375 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-06-09T15:28:34.605Z,1749482914.605 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-06-09T15:28:34.605Z,1749482914.605 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-06-09T15:28:35.139Z,1749482915.139 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-06-09T15:28:35.140Z,1749482915.140 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-06-09T15:28:35.335Z,1749482915.335 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-06-09T15:28:35.335Z,1749482915.335 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-06-09T15:28:35.631Z,1749482915.631 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-06-09T15:28:35.632Z,1749482915.632 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-06-09T15:28:36.470Z,1749482916.470 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-06-09T15:28:36.470Z,1749482916.470 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-06-09T15:28:37.052Z,1749482917.052 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-06-09T15:28:37.054Z,1749482917.054 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/
2025-06-09T15:28:37.054Z,1749482917.054 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg
2025-06-09T15:28:37.175Z,1749482917.175 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg
2025-06-09T15:28:37.314Z,1749482917.314 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg
2025-06-09T15:28:37.407Z,1749482917.407 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg
2025-06-09T15:28:37.523Z,1749482917.523 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg
2025-06-09T15:28:37.619Z,1749482917.619 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg
2025-06-09T15:28:37.891Z,1749482917.891 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-06-09T15:28:37.891Z,1749482917.891 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg
2025-06-09T15:28:38.010Z,1749482918.010 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg
2025-06-09T15:28:38.116Z,1749482918.116 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg
2025-06-09T15:28:38.263Z,1749482918.263 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg
2025-06-09T15:28:38.360Z,1749482918.360 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/root/
2025-06-09T15:28:38.360Z,1749482918.360 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-06-09T15:28:38.368Z,1749482918.368 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-06-09T15:28:38.503Z,1749482918.503 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-06-09T15:28:38.503Z,1749482918.503 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-06-09T15:28:38.558Z,1749482918.558 [VerticalControl](DEBUG): Construct VerticalControl.
2025-06-09T15:28:38.619Z,1749482918.619 [VerticalControl] Loaded
2025-06-09T15:28:38.619Z,1749482918.619 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-06-09T15:28:38.622Z,1749482918.622 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-06-09T15:28:38.670Z,1749482918.670 [HorizontalControl] Loaded
2025-06-09T15:28:38.670Z,1749482918.670 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-06-09T15:28:38.672Z,1749482918.672 [SpeedControl](DEBUG): Construct SpeedControl.
2025-06-09T15:28:38.675Z,1749482918.675 [SpeedControl] Loaded
2025-06-09T15:28:38.676Z,1749482918.676 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-06-09T15:28:38.678Z,1749482918.678 [LoopControl](DEBUG): Construct LoopControl.
2025-06-09T15:28:38.679Z,1749482918.679 [LoopControl] Loaded
2025-06-09T15:28:38.679Z,1749482918.679 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-06-09T15:28:38.679Z,1749482918.679 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-06-09T15:28:38.680Z,1749482918.680 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-06-09T15:28:38.739Z,1749482918.739 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-06-09T15:28:38.740Z,1749482918.740 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-06-09T15:28:39.244Z,1749482919.244 [AHRS_M2] Loaded
2025-06-09T15:28:39.244Z,1749482919.244 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-06-09T15:28:39.283Z,1749482919.283 [BackseatComponent] Loaded
2025-06-09T15:28:39.283Z,1749482919.283 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-06-09T15:28:39.284Z,1749482919.284 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408BA4E0
2025-06-09T15:28:39.285Z,1749482919.285 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 6345
2025-06-09T15:28:39.288Z,1749482919.288 [LcmUniversalReporter] Loaded
2025-06-09T15:28:39.288Z,1749482919.288 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-06-09T15:28:40.121Z,1749482920.121 [BPC1] Loaded
2025-06-09T15:28:40.121Z,1749482920.121 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-06-09T15:28:40.219Z,1749482920.219 [DAT] Loaded
2025-06-09T15:28:40.220Z,1749482920.220 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-06-09T15:28:40.221Z,1749482920.221 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 408EA4E0
2025-06-09T15:28:40.221Z,1749482920.221 [DAT ThreadHandler](INFO): Protected caller Thread ID is 6346
2025-06-09T15:28:40.301Z,1749482920.301 [DataOverHttps] Loaded
2025-06-09T15:28:40.302Z,1749482920.302 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-06-09T15:28:40.303Z,1749482920.303 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4091A4E0
2025-06-09T15:28:40.303Z,1749482920.303 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 6347
2025-06-09T15:28:40.326Z,1749482920.326 [Depth_Keller] Loaded
2025-06-09T15:28:40.326Z,1749482920.326 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-06-09T15:28:40.331Z,1749482920.331 [DropWeight] Loaded
2025-06-09T15:28:40.332Z,1749482920.332 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-06-09T15:28:40.397Z,1749482920.397 [NAL9602] Loaded
2025-06-09T15:28:40.397Z,1749482920.397 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-06-09T15:28:40.427Z,1749482920.427 [Onboard] Loaded
2025-06-09T15:28:40.427Z,1749482920.427 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-06-09T15:28:40.428Z,1749482920.428 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4094A4E0
2025-06-09T15:28:40.429Z,1749482920.429 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 6348
2025-06-09T15:28:40.453Z,1749482920.453 [Power24vConverter] Loaded
2025-06-09T15:28:40.453Z,1749482920.453 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-06-09T15:28:40.469Z,1749482920.469 [Radio_Surface] Loaded
2025-06-09T15:28:40.469Z,1749482920.469 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-06-09T15:28:40.470Z,1749482920.470 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4097A4E0
2025-06-09T15:28:40.471Z,1749482920.471 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6349
2025-06-09T15:28:40.486Z,1749482920.486 [Sonardyne_Nano] Loaded
2025-06-09T15:28:40.486Z,1749482920.486 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-06-09T15:28:40.487Z,1749482920.487 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-06-09T15:28:40.488Z,1749482920.488 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-06-09T15:28:40.498Z,1749482920.498 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-06-09T15:28:40.498Z,1749482920.498 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-06-09T15:28:40.528Z,1749482920.528 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-06-09T15:28:40.529Z,1749482920.529 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-06-09T15:28:40.692Z,1749482920.692 [SBIT](DEBUG): Construct Startup Built In Test.
2025-06-09T15:28:40.701Z,1749482920.701 [SBIT] Loaded
2025-06-09T15:28:40.701Z,1749482920.701 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-06-09T15:28:40.704Z,1749482920.704 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-06-09T15:28:40.717Z,1749482920.717 [IBIT] Loaded
2025-06-09T15:28:40.717Z,1749482920.717 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-06-09T15:28:40.723Z,1749482920.723 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-06-09T15:28:40.804Z,1749482920.804 [CBIT] Loaded
2025-06-09T15:28:40.805Z,1749482920.805 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-06-09T15:28:40.838Z,1749482920.838 [GFScanner] Loaded
2025-06-09T15:28:40.838Z,1749482920.838 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-06-09T15:28:40.838Z,1749482920.838 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-06-09T15:28:40.839Z,1749482920.839 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-06-09T15:28:41.189Z,1749482921.189 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-06-09T15:28:41.190Z,1749482921.190 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-06-09T15:28:41.241Z,1749482921.241 [DepthRateCalculator] Loaded
2025-06-09T15:28:41.241Z,1749482921.241 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-06-09T15:28:41.246Z,1749482921.246 [PitchRateCalculator] Loaded
2025-06-09T15:28:41.247Z,1749482921.247 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-06-09T15:28:41.257Z,1749482921.257 [SpeedCalculator] Loaded
2025-06-09T15:28:41.257Z,1749482921.257 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-06-09T15:28:41.261Z,1749482921.261 [YawRateCalculator] Loaded
2025-06-09T15:28:41.262Z,1749482921.262 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-06-09T15:28:41.281Z,1749482921.281 [ElevatorOffsetCalculator] Loaded
2025-06-09T15:28:41.281Z,1749482921.281 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-06-09T15:28:41.282Z,1749482921.282 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-06-09T15:28:41.282Z,1749482921.282 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-06-09T15:28:41.460Z,1749482921.460 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-06-09T15:28:41.461Z,1749482921.461 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-06-09T15:28:41.575Z,1749482921.575 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-06-09T15:28:41.575Z,1749482921.575 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-06-09T15:28:41.590Z,1749482921.590 [NavChart] Loaded
2025-06-09T15:28:41.590Z,1749482921.590 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-06-09T15:28:41.596Z,1749482921.596 [UniversalFixResidualReporter] Loaded
2025-06-09T15:28:41.596Z,1749482921.596 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-06-09T15:28:41.596Z,1749482921.596 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-06-09T15:28:41.597Z,1749482921.597 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-06-09T15:28:41.736Z,1749482921.736 [BuoyancyServo] Loaded
2025-06-09T15:28:41.736Z,1749482921.736 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-06-09T15:28:41.760Z,1749482921.760 [ElevatorServo] Loaded
2025-06-09T15:28:41.760Z,1749482921.760 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-06-09T15:28:41.783Z,1749482921.783 [MassServo] Loaded
2025-06-09T15:28:41.783Z,1749482921.783 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-06-09T15:28:41.804Z,1749482921.804 [RudderServo] Loaded
2025-06-09T15:28:41.804Z,1749482921.804 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-06-09T15:28:41.821Z,1749482921.821 [ThrusterHE] Loaded
2025-06-09T15:28:41.821Z,1749482921.821 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-06-09T15:28:41.822Z,1749482921.822 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-06-09T15:28:41.822Z,1749482921.822 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-06-09T15:28:41.999Z,1749482921.999 [CTD_Seabird] Loaded
2025-06-09T15:28:41.999Z,1749482921.999 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-06-09T15:28:42.000Z,1749482922.000 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40C094E0
2025-06-09T15:28:42.001Z,1749482922.001 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 6350
2025-06-09T15:28:42.038Z,1749482922.038 [ESPComponent] Loaded
2025-06-09T15:28:42.038Z,1749482922.038 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2025-06-09T15:28:42.061Z,1749482922.061 [PAR_Licor] Loaded
2025-06-09T15:28:42.061Z,1749482922.061 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-06-09T15:28:42.106Z,1749482922.106 [WetLabsBB2FL] Loaded
2025-06-09T15:28:42.107Z,1749482922.107 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-06-09T15:28:42.108Z,1749482922.108 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C394E0
2025-06-09T15:28:42.108Z,1749482922.108 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6351
2025-06-09T15:28:42.109Z,1749482922.109 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-06-09T15:28:42.125Z,1749482922.125 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-06-09T15:28:42.131Z,1749482922.131 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-06-09T15:28:42.146Z,1749482922.146 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-06-09T15:28:42.146Z,1749482922.146 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C694E0
2025-06-09T15:28:42.147Z,1749482922.147 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6352
2025-06-09T15:28:42.151Z,1749482922.151 [Supervisor](INFO): Main Thread ID is 6091
2025-06-09T15:28:42.151Z,1749482922.151 [Supervisor](DEBUG): Running supervisor.
2025-06-09T15:28:42.152Z,1749482922.152 [CommandExec ThreadHandler](INFO): Handler Thread ID is 6353
2025-06-09T15:28:42.152Z,1749482922.152 [CommandExec](INFO): Initializing the command executive.
2025-06-09T15:28:42.154Z,1749482922.154 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6354
2025-06-09T15:28:42.156Z,1749482922.156 [controlThread ThreadHandler](INFO): Handler Thread ID is 6355
2025-06-09T15:28:42.157Z,1749482922.157 [controlThread](DEBUG): Initializing ControlThread
2025-06-09T15:28:42.158Z,1749482922.158 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-06-09T15:28:42.159Z,1749482922.159 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-06-09T15:28:42.160Z,1749482922.160 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-06-09T15:28:42.160Z,1749482922.160 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-06-09T15:28:42.163Z,1749482922.163 [SBIT](INFO): Initialize SBIT Component.
2025-06-09T15:28:42.164Z,1749482922.164 [SBIT](IMPORTANT): git: 2025-06-02-12-gf31d2d2cf
2025-06-09T15:28:42.164Z,1749482922.164 [SBIT](INFO): git hash: f31d2d2cff7b1a18a5fb2309356ea947ca1213be
2025-06-09T15:28:42.164Z,1749482922.164 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-06-09T15:28:42.165Z,1749482922.165 [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-06-09T15:28:42.167Z,1749482922.167 [SBIT](INFO): Beginning SBIT in 106.000000 seconds.
2025-06-09T15:28:42.167Z,1749482922.167 [IBIT](INFO): Initialize IBIT Component.
2025-06-09T15:28:42.168Z,1749482922.168 [CBIT](DEBUG): Initialize CBIT Component.
2025-06-09T15:28:42.169Z,1749482922.169 [logger ThreadHandler](INFO): Handler Thread ID is 6356
2025-06-09T15:28:42.181Z,1749482922.181 [CBIT](DEBUG): Initialized mux pins.
2025-06-09T15:28:42.181Z,1749482922.181 [CBIT](DEBUG): Initializing the watchdog timer.
2025-06-09T15:28:42.194Z,1749482922.194 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 6357
2025-06-09T15:28:42.205Z,1749482922.205 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-06-09T15:28:42.206Z,1749482922.206 [CBIT](DEBUG): Initializing heartbeat.
2025-06-09T15:28:42.206Z,1749482922.206 [DAT ThreadHandler](INFO): Handler Thread ID is 6358
2025-06-09T15:28:42.207Z,1749482922.207 [DAT](INFO): Powering up
2025-06-09T15:28:42.207Z,1749482922.207 [DAT](DEBUG): Initializing DAT.
2025-06-09T15:28:42.211Z,1749482922.211 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 6360
2025-06-09T15:28:42.212Z,1749482922.212 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-06-09T15:28:42.234Z,1749482922.234 [Onboard ThreadHandler](INFO): Handler Thread ID is 6361
2025-06-09T15:28:42.251Z,1749482922.251 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6362
2025-06-09T15:28:42.272Z,1749482922.272 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 6363
2025-06-09T15:28:42.272Z,1749482922.272 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-06-09T15:28:42.277Z,1749482922.277 [CBIT](DEBUG): Deactivating emergency mode.
2025-06-09T15:28:42.286Z,1749482922.286 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6365
2025-06-09T15:28:42.290Z,1749482922.290 [WetLabsBB2FL](INFO): Powering up
2025-06-09T15:28:42.291Z,1749482922.291 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6367
2025-06-09T15:28:42.293Z,1749482922.293 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-06-09T15:28:42.294Z,1749482922.294 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-06-09T15:28:42.294Z,1749482922.294 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-06-09T15:28:42.294Z,1749482922.294 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-06-09T15:28:42.294Z,1749482922.294 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-06-09T15:28:42.294Z,1749482922.294 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-06-09T15:28:42.294Z,1749482922.294 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-06-09T15:28:42.295Z,1749482922.295 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-06-09T15:28:42.317Z,1749482922.317 [CBIT](DEBUG): Backplane powered.
2025-06-09T15:28:42.318Z,1749482922.318 [GFScanner](DEBUG): Initializing GFScanner
2025-06-09T15:28:42.318Z,1749482922.318 [GFScanner](DEBUG): Deactivating GF circuits.
2025-06-09T15:28:42.322Z,1749482922.322 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-06-09T15:28:42.322Z,1749482922.322 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-06-09T15:28:42.323Z,1749482922.323 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-06-09T15:28:42.323Z,1749482922.323 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-06-09T15:28:42.323Z,1749482922.323 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-06-09T15:28:42.325Z,1749482922.325 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-06-09T15:28:42.325Z,1749482922.325 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-06-09T15:28:42.330Z,1749482922.330 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-06-09T15:28:42.331Z,1749482922.331 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-06-09T15:28:42.331Z,1749482922.331 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-06-09T15:28:42.332Z,1749482922.332 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-06-09T15:28:42.426Z,1749482922.426 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2025-06-09T15:28:42.431Z,1749482922.431 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-06-09T15:28:42.480Z,1749482922.480 [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-06-09T15:28:42.480Z,1749482922.480 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-06-09T15:28:42.481Z,1749482922.481 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-06-09T15:28:42.482Z,1749482922.482 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-06-09T15:28:42.665Z,1749482922.665 [Radio_Surface](INFO): Powering up
2025-06-09T15:28:42.807Z,1749482922.807 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2025-06-09T15:28:42.818Z,1749482922.818 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-06-09T15:28:42.819Z,1749482922.819 [Default:A.Wait](DEBUG): Construct Wait.
2025-06-09T15:28:42.833Z,1749482922.833 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-06-09T15:28:42.871Z,1749482922.871 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-06-09T15:28:42.874Z,1749482922.874 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-06-09T15:28:42.896Z,1749482922.896 [Default:E.Execute](DEBUG): Construct Execute.
2025-06-09T15:28:42.899Z,1749482922.899 [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-06-09T15:28:42.910Z,1749482922.910 [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-06-09T15:28:42.921Z,1749482922.921 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-06-09T15:28:42.982Z,1749482922.982 [Depth_Keller](INFO): Initializing.
2025-06-09T15:28:42.984Z,1749482922.984 [Power24vConverter](INFO): Powering up.
2025-06-09T15:28:42.984Z,1749482922.984 [Sonardyne_Nano](INFO): Initializing.
2025-06-09T15:28:43.030Z,1749482923.030 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-06-09T15:28:43.060Z,1749482923.060 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-06-09T15:28:43.066Z,1749482923.066 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-06-09T15:28:43.067Z,1749482923.067 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-06-09T15:28:43.074Z,1749482923.074 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-06-09T15:28:43.075Z,1749482923.075 [MassServo](DEBUG): Initializing EZServoServo.
2025-06-09T15:28:43.082Z,1749482923.082 [MassServo](DEBUG): Initializing MassServo.
2025-06-09T15:28:43.082Z,1749482923.082 [RudderServo](DEBUG): Initializing EZServoServo.
2025-06-09T15:28:43.090Z,1749482923.090 [RudderServo](DEBUG): Initializing RudderServo.
2025-06-09T15:28:43.090Z,1749482923.090 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-06-09T15:28:43.098Z,1749482923.098 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-06-09T15:28:44.397Z,1749482924.397 [WetLabsBB2FL](INFO): Powering down
2025-06-09T15:28:44.940Z,1749482924.940 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-06-09T15:28:46.226Z,1749482926.226 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2311
2025-06-09T15:28:48.946Z,1749482928.946 [ThrusterHE](ERROR): Zero Speed Commanded.
2025-06-09T15:28:54.554Z,1749482934.554 [DAT](INFO): DAT read:
2025-06-09T15:28:54.555Z,1749482934.555 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2025-06-09T15:28:56.318Z,1749482936.318 [DAT](INFO): DAT read: MF Frequency Band
2025-06-09T15:28:56.319Z,1749482936.319 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2025-06-09T15:28:56.320Z,1749482936.320 [DAT](INFO): DAT read: Jun 9 2025 15:28:50
2025-06-09T15:28:57.327Z,1749482937.327 [DAT](INFO): DAT read: Features enabled [Bearing]
2025-06-09T15:28:57.328Z,1749482937.328 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2025-06-09T15:28:57.328Z,1749482937.328 [DAT](INFO): commRate: 800
2025-06-09T15:28:59.393Z,1749482939.393 [DAT](INFO): entering command mode
2025-06-09T15:28:59.595Z,1749482939.595 [DAT](INFO): DAT read:
2025-06-09T15:28:59.596Z,1749482939.596 [DAT](INFO): DAT read: user:1>
2025-06-09T15:28:59.596Z,1749482939.596 [DAT](INFO): setting verbose to 3
2025-06-09T15:28:59.846Z,1749482939.846 [DAT](INFO): DAT read: user:1>
2025-06-09T15:28:59.847Z,1749482939.847 [DAT](INFO): DAT read: Verbose | 3
2025-06-09T15:28:59.847Z,1749482939.847 [DAT](INFO): set verbose to 3
2025-06-09T15:28:59.848Z,1749482939.848 [DAT](INFO): setting DatVerbose to 27440
2025-06-09T15:29:00.098Z,1749482940.098 [DAT](INFO): DAT read: user:2>
2025-06-09T15:29:00.099Z,1749482940.099 [DAT](INFO): DAT read: DatVerbose | 27440
2025-06-09T15:29:00.099Z,1749482940.099 [DAT](INFO): set DatVerbose to 27440
2025-06-09T15:29:00.100Z,1749482940.100 [DAT](INFO): setting transmit power to 8
2025-06-09T15:29:00.351Z,1749482940.351 [DAT](INFO): DAT read: user:3>
2025-06-09T15:29:00.351Z,1749482940.351 [DAT](INFO): DAT read: TxPower | 8 (Max)
2025-06-09T15:29:00.352Z,1749482940.352 [DAT](INFO): set transmit power to 8
2025-06-09T15:29:00.352Z,1749482940.352 [DAT](INFO): setting local address to 1
2025-06-09T15:29:00.602Z,1749482940.602 [DAT](INFO): DAT read: user:4>
2025-06-09T15:29:00.603Z,1749482940.603 [DAT](INFO): DAT read: LocalAddr | 1
2025-06-09T15:29:00.603Z,1749482940.603 [DAT](INFO): set local address to 1
2025-06-09T15:29:00.604Z,1749482940.604 [DAT](INFO): Setting time to: 15:29:0 And date to:6/9/2025
2025-06-09T15:29:00.854Z,1749482940.854 [DAT](INFO): DAT read: user:5>
2025-06-09T15:29:00.855Z,1749482940.855 [DAT](INFO): DAT read: Mon Jun 9, 2025 15:29:00
2025-06-09T15:29:00.855Z,1749482940.855 [DAT](INFO): Local DAT time set to Mon Jun 9, 2025 15:29:00
2025-06-09T15:29:10.440Z,1749482950.440 [NAL9602](INFO): Powering up NAL9602
2025-06-09T15:29:21.357Z,1749482961.357 [NAL9602](INFO): NAL9602 initialized
2025-06-09T15:30:17.906Z,1749483017.906 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:30:22.411Z,1749483022.411 [CommandExec](IMPORTANT): got command strobe off
2025-06-09T15:30:22.411Z,1749483022.411 [CommandExec](IMPORTANT): Deactivating strobe
2025-06-09T15:30:28.849Z,1749483028.849 [SBIT](IMPORTANT): Beginning Startup BIT
2025-06-09T15:30:28.853Z,1749483028.853 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-06-09T15:30:29.282Z,1749483029.282 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:30:33.350Z,1749483033.350 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.000999
CHAN A1 (24V): 0.002751
CHAN A2 (12V): 0.000037
CHAN A3 (5V): -0.001524
CHAN B0 (3.3V): -0.000377
CHAN B1 (3.15aV): -0.000532
CHAN B2 (3.15bV): 0.000264
CHAN B3 (GND): -0.001027
OPEN: 0.006839
Full Scale: +/- 1 mA
2025-06-09T15:30:40.191Z,1749483040.191 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:30:44.621Z,1749483044.621 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:30:51.099Z,1749483051.099 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:31:02.007Z,1749483062.007 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:31:12.916Z,1749483072.916 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:31:22.591Z,1749483082.591 [SBIT](IMPORTANT): SBIT PASSED
2025-06-09T15:31:22.592Z,1749483082.592 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-06-09T15:31:22.592Z,1749483082.592 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=15 count;
2025-06-09T15:31:22.592Z,1749483082.592 [SBIT](IMPORTANT): Express linearApproximation TrackAcousticContact.range_to_contact 15.000000 meter;
2025-06-09T15:31:22.592Z,1749483082.592 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_HM_AvgRois 2.000000 count_per_second;
2025-06-09T15:31:22.593Z,1749483082.593 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_LM_AvgRois 100.000000 count_per_second;
2025-06-09T15:31:22.593Z,1749483082.593 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=217.238968 cubic_centimeter;
2025-06-09T15:31:22.593Z,1749483082.593 [SBIT](IMPORTANT): VerticalControl.massDefault=21.523369 millimeter;
2025-06-09T15:31:22.593Z,1749483082.593 [SBIT](IMPORTANT): Waterlinked.loadAtStartup=0 bool;
2025-06-09T15:31:22.991Z,1749483082.991 [MissionManager](IMPORTANT): Started mission Startup
2025-06-09T15:31:22.991Z,1749483082.991 [Startup] Running Loop=1
2025-06-09T15:31:22.991Z,1749483082.991 [Startup](DEBUG): Aggregate::initialize Startup
2025-06-09T15:31:22.991Z,1749483082.991 [Startup:A.GoToSurface] Running Loop=1
2025-06-09T15:31:22.991Z,1749483082.991 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-06-09T15:31:22.992Z,1749483082.992 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-06-09T15:31:22.992Z,1749483082.992 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-06-09T15:31:22.992Z,1749483082.992 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-06-09T15:31:22.993Z,1749483082.993 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-06-09T15:31:22.997Z,1749483082.997 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-06-09T15:31:22.998Z,1749483082.998 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-06-09T15:31:22.999Z,1749483082.999 [Startup:StartupSatComms] Running Loop=1
2025-06-09T15:31:22.000Z,1749483083.000 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-06-09T15:31:22.000Z,1749483083.000 [Startup:StartupSatComms:A] Running Loop=1
2025-06-09T15:31:23.367Z,1749483083.367 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-06-09T15:31:23.823Z,1749483083.823 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:31:25.285Z,1749483085.285 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.009507
2025-06-09T15:31:34.780Z,1749483094.780 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:31:42.350Z,1749483102.350 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-06-09T15:31:42.350Z,1749483102.350 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-09T15:31:42.385Z,1749483102.385 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-09T15:31:42.788Z,1749483102.788 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-09T15:31:42.788Z,1749483102.788 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-06-09T15:31:45.640Z,1749483105.640 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:31:56.951Z,1749483116.951 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:32:07.859Z,1749483127.859 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:32:17.484Z,1749483137.484 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T15:32:18.765Z,1749483138.765 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:32:23.157Z,1749483143.157 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:32:23.176Z,1749483143.176 [Startup:StartupSatComms:A](INFO): Timed out from 2025-06-09T15:31:22.0Z
2025-06-09T15:32:23.176Z,1749483143.176 [Startup:StartupSatComms:A] Stopped
2025-06-09T15:32:23.176Z,1749483143.176 [Startup:StartupSatComms:B] Running Loop=1
2025-06-09T15:32:23.588Z,1749483143.588 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-06-09T15:32:24.360Z,1749483144.360 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250609T152832/Courier0000.lzma
2025-06-09T15:32:25.355Z,1749483145.355 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Courier0000.lzma.bak
2025-06-09T15:32:25.356Z,1749483145.356 [DataOverHttps](INFO): SBD MOMSN=25160059
2025-06-09T15:32:29.790Z,1749483149.790 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:32:38.644Z,1749483158.644 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:32:40.254Z,1749483160.254 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:32:41.078Z,1749483161.078 [DataOverHttps](INFO): Sending 956 bytes from file Logs/20250609T152832/Express0001.lzma
2025-06-09T15:32:42.075Z,1749483162.075 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Express0001.lzma.bak
2025-06-09T15:32:42.075Z,1749483162.075 [DataOverHttps](INFO): SBD MOMSN=25160062
2025-06-09T15:32:43.435Z,1749483163.435 [Startup:StartupSatComms:B] Stopped
2025-06-09T15:32:43.435Z,1749483163.435 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-06-09T15:32:43.435Z,1749483163.435 [Startup:StartupSatComms] Stopped
2025-06-09T15:32:43.435Z,1749483163.435 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-06-09T15:32:43.436Z,1749483163.436 [Startup](INFO): Completed Startup
2025-06-09T15:32:43.436Z,1749483163.436 [MissionManager](INFO): Startup is completed.
2025-06-09T15:32:43.437Z,1749483163.437 [MissionManager](INFO): Uninitializing Mission Startup
2025-06-09T15:32:43.437Z,1749483163.437 [Startup] Stopped
2025-06-09T15:32:43.437Z,1749483163.437 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-06-09T15:32:43.437Z,1749483163.437 [Startup:A.GoToSurface] Stopped
2025-06-09T15:32:43.437Z,1749483163.437 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-06-09T15:32:43.843Z,1749483163.843 [MissionManager](IMPORTANT): Started mission Default
2025-06-09T15:32:43.844Z,1749483163.844 [Default] Running Loop=1
2025-06-09T15:32:43.844Z,1749483163.844 [Default](DEBUG): Aggregate::initialize Default
2025-06-09T15:32:43.844Z,1749483163.844 [Default:B.GoToSurface] Running Loop=1
2025-06-09T15:32:43.844Z,1749483163.844 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-06-09T15:32:43.844Z,1749483163.844 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-06-09T15:32:43.844Z,1749483163.844 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-06-09T15:32:43.845Z,1749483163.845 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-06-09T15:32:43.845Z,1749483163.845 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-06-09T15:32:43.846Z,1749483163.846 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-06-09T15:32:43.846Z,1749483163.846 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-06-09T15:32:43.846Z,1749483163.846 [Default:A.Wait] Running Loop=1
2025-06-09T15:32:43.846Z,1749483163.846 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-06-09T15:32:45.456Z,1749483165.456 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:32:53.566Z,1749483173.566 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:32:55.196Z,1749483175.196 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:32:57.182Z,1749483177.182 [Default:A.Wait](INFO): Done Waiting.
2025-06-09T15:32:57.182Z,1749483177.182 [Default:A.Wait] Stopped
2025-06-09T15:32:57.182Z,1749483177.182 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T15:32:57.576Z,1749483177.576 [Default:CheckIn] Running Loop=1
2025-06-09T15:32:57.576Z,1749483177.576 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T15:32:57.576Z,1749483177.576 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T15:32:57.979Z,1749483177.979 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-06-09T15:33:04.891Z,1749483184.891 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:33:08.920Z,1749483188.920 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:33:14.991Z,1749483194.991 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:33:23.461Z,1749483203.461 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:33:24.688Z,1749483204.688 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:33:34.382Z,1749483214.382 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:33:38.818Z,1749483218.818 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:33:44.078Z,1749483224.078 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:33:53.765Z,1749483233.765 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:33:54.182Z,1749483234.182 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:34:03.876Z,1749483243.876 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:34:09.116Z,1749483249.116 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:34:13.974Z,1749483253.974 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:34:23.661Z,1749483263.661 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:34:24.074Z,1749483264.074 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:34:24.461Z,1749483264.461 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-06-09T15:34:34.175Z,1749483274.175 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:34:36.943Z,1749483276.943 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T15:34:36.944Z,1749483276.944 [BPC1](ERROR): BPC1B failed to parse battery stick message.
2025-06-09T15:34:39.780Z,1749483279.780 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:34:42.604Z,1749483282.604 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:34:43.459Z,1749483283.459 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2025-06-09T15:34:43.459Z,1749483283.459 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-09T15:34:43.490Z,1749483283.490 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-09T15:34:43.872Z,1749483283.872 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:34:43.921Z,1749483283.921 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-09T15:34:43.921Z,1749483283.921 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2025-06-09T15:34:45.431Z,1749483285.431 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:34:48.259Z,1749483288.259 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:34:51.091Z,1749483291.091 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:34:53.567Z,1749483293.567 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:34:53.930Z,1749483293.930 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:34:56.743Z,1749483296.743 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:34:59.975Z,1749483299.975 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:35:03.207Z,1749483303.207 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:35:03.667Z,1749483303.667 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:35:06.036Z,1749483306.036 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:35:08.864Z,1749483308.864 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:35:11.707Z,1749483311.707 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:35:14.519Z,1749483314.519 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:35:17.349Z,1749483317.349 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:35:20.176Z,1749483320.176 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:35:23.003Z,1749483323.003 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:35:25.833Z,1749483325.833 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:35:28.659Z,1749483328.659 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:35:31.487Z,1749483331.487 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:35:34.319Z,1749483334.319 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:35:37.143Z,1749483337.143 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:35:39.215Z,1749483339.215 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:35:39.981Z,1749483339.981 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:35:42.799Z,1749483342.799 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:35:45.630Z,1749483345.630 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:35:48.457Z,1749483348.457 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:35:51.689Z,1749483351.689 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:35:54.525Z,1749483354.525 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:35:57.747Z,1749483357.747 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:36:00.575Z,1749483360.575 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:36:03.405Z,1749483363.405 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:36:06.636Z,1749483366.636 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:36:09.471Z,1749483369.471 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:36:12.292Z,1749483372.292 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:36:15.120Z,1749483375.120 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:36:17.586Z,1749483377.586 [CommandExec](IMPORTANT): got command failComponent
2025-06-09T15:36:17.586Z,1749483377.586 [CommandExec](IMPORTANT): Failed components:
2025-06-09T15:36:17.587Z,1749483377.587 [CommandExec](IMPORTANT): No failed Components.
2025-06-09T15:36:17.947Z,1749483377.947 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:36:20.782Z,1749483380.782 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:36:24.075Z,1749483384.075 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:36:26.835Z,1749483386.835 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:36:29.663Z,1749483389.663 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:36:32.491Z,1749483392.491 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:36:35.322Z,1749483395.322 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:36:38.147Z,1749483398.147 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:36:40.975Z,1749483400.975 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:36:41.187Z,1749483401.187 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.chargePercent
2025-06-09T15:36:41.188Z,1749483401.188 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent 91.000000 %
2025-06-09T15:36:43.804Z,1749483403.804 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:36:46.633Z,1749483406.633 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:36:49.459Z,1749483409.459 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:36:52.288Z,1749483412.288 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:37:06.031Z,1749483426.031 [BPC1](ERROR): BPC1B got IPBS message with 3 sticks (min is 7).
2025-06-09T15:37:06.031Z,1749483426.031 [BPC1](ERROR): BPC1B failed to parse battery stick message.
2025-06-09T15:37:22.992Z,1749483442.992 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T15:37:36.752Z,1749483456.752 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:37:44.442Z,1749483464.442 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2025-06-09T15:37:44.442Z,1749483464.442 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-09T15:37:44.453Z,1749483464.453 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-09T15:37:44.859Z,1749483464.859 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-09T15:37:44.859Z,1749483464.859 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2025-06-09T15:37:52.928Z,1749483472.928 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:37:57.760Z,1749483477.760 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-06-09T15:32:57.6Z
2025-06-09T15:37:57.760Z,1749483477.760 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T15:37:57.760Z,1749483477.760 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T15:37:58.155Z,1749483478.155 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-06-09T15:38:04.909Z,1749483484.909 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20250609T152832/Courier0004.lzma
2025-06-09T15:38:05.911Z,1749483485.911 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Courier0004.lzma.bak
2025-06-09T15:38:05.911Z,1749483485.911 [DataOverHttps](INFO): SBD MOMSN=25160168
2025-06-09T15:38:09.044Z,1749483489.044 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T15:38:21.521Z,1749483501.521 [DataOverHttps](INFO): Sending 286 bytes from file Logs/20250609T152832/Express0005.lzma
2025-06-09T15:38:22.519Z,1749483502.519 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Express0005.lzma.bak
2025-06-09T15:38:22.519Z,1749483502.519 [DataOverHttps](INFO): SBD MOMSN=25160182
2025-06-09T15:38:23.603Z,1749483503.603 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T15:38:23.604Z,1749483503.604 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T15:38:23.604Z,1749483503.604 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T15:38:25.204Z,1749483505.204 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T15:38:42.576Z,1749483522.576 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T15:38:58.735Z,1749483538.735 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T15:39:15.300Z,1749483555.300 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T15:39:22.579Z,1749483562.579 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-06-09T15:39:22.579Z,1749483562.579 [NAL9602] Data Fault, FailCount= 1
2025-06-09T15:39:22.579Z,1749483562.579 [NAL9602](ERROR): Data Fault
2025-06-09T15:39:22.597Z,1749483562.597 [CBIT](ERROR): Data Fault in component: NAL9602
2025-06-09T15:39:22.984Z,1749483562.984 [NAL9602](INFO): Powering down
2025-06-09T15:39:23.827Z,1749483563.827 [CBIT](INFO): Clearing failed state for component NAL9602
2025-06-09T15:39:23.827Z,1749483563.827 [NAL9602] No Fault, FailCount= 1
2025-06-09T15:39:53.352Z,1749483593.352 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:39:53.363Z,1749483593.363 [NAL9602](INFO): Powering up NAL9602
2025-06-09T15:40:01.832Z,1749483601.832 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:40:04.211Z,1749483604.211 [NAL9602](INFO): NAL9602 initialized
2025-06-09T15:40:13.159Z,1749483613.159 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:40:24.470Z,1749483624.470 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:40:35.379Z,1749483635.379 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:40:45.424Z,1749483645.424 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2025-06-09T15:40:45.424Z,1749483645.424 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-09T15:40:45.434Z,1749483645.434 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-09T15:40:45.843Z,1749483645.843 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-09T15:40:45.843Z,1749483645.843 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2025-06-09T15:40:46.287Z,1749483646.287 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:40:57.194Z,1749483657.194 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:41:08.560Z,1749483668.560 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:41:14.444Z,1749483674.444 [CommandExec](IMPORTANT): got command burn on
2025-06-09T15:41:14.444Z,1749483674.444 [CommandExec](IMPORTANT): Activating dropweight wire
2025-06-09T15:41:19.822Z,1749483679.822 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:41:23.847Z,1749483683.847 [CommandExec](IMPORTANT): got command burn off
2025-06-09T15:41:23.847Z,1749483683.847 [CommandExec](IMPORTANT): Deactivating dropweight wire
2025-06-09T15:41:31.181Z,1749483691.181 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:41:42.044Z,1749483702.044 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:41:43.589Z,1749483703.589 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T15:41:49.254Z,1749483709.254 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:41:52.547Z,1749483712.547 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:42:03.454Z,1749483723.454 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:42:04.645Z,1749483724.645 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:42:19.949Z,1749483739.949 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T15:42:35.356Z,1749483755.356 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T15:42:50.248Z,1749483770.248 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T15:43:05.600Z,1749483785.600 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T15:43:20.549Z,1749483800.549 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T15:43:24.268Z,1749483804.268 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T15:43:24.268Z,1749483804.268 [Default:CheckIn:C.Wait] Stopped
2025-06-09T15:43:24.268Z,1749483804.268 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T15:43:24.268Z,1749483804.268 [Default:CheckIn:D] Running Loop=1
2025-06-09T15:43:24.615Z,1749483804.615 [Default:CheckIn:D] Stopped
2025-06-09T15:43:24.616Z,1749483804.616 [Default:CheckIn:E] Running Loop=1
2025-06-09T15:43:25.032Z,1749483805.032 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.679530 min
2025-06-09T15:43:25.032Z,1749483805.032 [Default:CheckIn:E] Stopped
2025-06-09T15:43:25.032Z,1749483805.032 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T15:43:25.032Z,1749483805.032 [Default:CheckIn] Stopped
2025-06-09T15:43:25.032Z,1749483805.032 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T15:43:25.032Z,1749483805.032 [Default:CheckIn](INFO): Running loop #2
2025-06-09T15:43:25.032Z,1749483805.032 [Default:CheckIn] Running Loop=2
2025-06-09T15:43:25.032Z,1749483805.032 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T15:43:25.032Z,1749483805.032 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T15:43:35.498Z,1749483815.498 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T15:43:46.419Z,1749483826.419 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2025-06-09T15:43:46.419Z,1749483826.419 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-09T15:43:46.430Z,1749483826.430 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-09T15:43:46.836Z,1749483826.836 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-09T15:43:46.836Z,1749483826.836 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2025-06-09T15:43:50.848Z,1749483830.848 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T15:44:04.607Z,1749483844.607 [BPC1](ERROR): Battery stick #24 (s/n: 01D3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-06-09T15:44:04.607Z,1749483844.607 [BPC1](ERROR): Battery stick #29 (s/n: 00A7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-06-09T15:44:04.608Z,1749483844.608 [BPC1](ERROR): Battery stick #51 (s/n: 01D6) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-06-09T15:44:04.609Z,1749483844.609 [BPC1](ERROR): Battery stick #57 (s/n: 019F) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-06-09T15:44:04.610Z,1749483844.610 [BPC1](ERROR): Battery stick #59 (s/n: 0184) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-06-09T15:44:04.610Z,1749483844.610 [BPC1](INFO): Calculating totals. Valid battery stick count: 41. Valid reserve battery stick count: 4.
2025-06-09T15:44:04.613Z,1749483844.613 [BPC1](INFO): Received data from all battery sticks.
2025-06-09T15:46:47.421Z,1749484007.421 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2025-06-09T15:46:47.421Z,1749484007.421 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-09T15:46:47.432Z,1749484007.432 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-09T15:46:47.852Z,1749484007.852 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-09T15:46:47.853Z,1749484007.853 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2025-06-09T15:48:25.183Z,1749484105.183 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-06-09T15:43:25.0Z
2025-06-09T15:48:25.184Z,1749484105.184 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T15:48:25.184Z,1749484105.184 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T15:48:25.991Z,1749484105.991 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T15:48:25.991Z,1749484105.991 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T15:48:25.991Z,1749484105.991 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T15:49:48.422Z,1749484188.422 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2025-06-09T15:49:48.422Z,1749484188.422 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-09T15:49:48.433Z,1749484188.433 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-09T15:49:48.844Z,1749484188.844 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-09T15:49:48.844Z,1749484188.844 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2025-06-09T15:50:07.395Z,1749484207.395 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-06-09T15:50:07.395Z,1749484207.395 [NAL9602] Data Fault, FailCount= 2
2025-06-09T15:50:07.395Z,1749484207.395 [NAL9602](ERROR): Data Fault
2025-06-09T15:50:07.412Z,1749484207.412 [CBIT](ERROR): Data Fault in component: NAL9602
2025-06-09T15:50:07.799Z,1749484207.799 [NAL9602](INFO): Powering down
2025-06-09T15:50:08.644Z,1749484208.644 [CBIT](INFO): Clearing failed state for component NAL9602
2025-06-09T15:50:08.644Z,1749484208.644 [NAL9602] No Fault, FailCount= 2
2025-06-09T15:50:38.099Z,1749484238.099 [NAL9602](INFO): Powering up NAL9602
2025-06-09T15:50:49.002Z,1749484249.002 [NAL9602](INFO): NAL9602 initialized
2025-06-09T15:52:49.430Z,1749484369.430 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2025-06-09T15:52:49.430Z,1749484369.430 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-09T15:52:49.476Z,1749484369.476 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-09T15:52:49.848Z,1749484369.848 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-09T15:52:49.848Z,1749484369.848 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2025-06-09T15:53:26.575Z,1749484406.575 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T15:53:26.576Z,1749484406.576 [Default:CheckIn:C.Wait] Stopped
2025-06-09T15:53:26.576Z,1749484406.576 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T15:53:26.576Z,1749484406.576 [Default:CheckIn:D] Running Loop=1
2025-06-09T15:53:26.983Z,1749484406.983 [Default:CheckIn:D] Stopped
2025-06-09T15:53:26.983Z,1749484406.983 [Default:CheckIn:E] Running Loop=1
2025-06-09T15:53:27.394Z,1749484407.394 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 20.718990 min
2025-06-09T15:53:27.394Z,1749484407.394 [Default:CheckIn:E] Stopped
2025-06-09T15:53:27.394Z,1749484407.394 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T15:53:27.394Z,1749484407.394 [Default:CheckIn] Stopped
2025-06-09T15:53:27.395Z,1749484407.395 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T15:53:27.395Z,1749484407.395 [Default:CheckIn](INFO): Running loop #3
2025-06-09T15:53:27.395Z,1749484407.395 [Default:CheckIn] Running Loop=3
2025-06-09T15:53:27.395Z,1749484407.395 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T15:53:27.395Z,1749484407.395 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T15:55:04.745Z,1749484504.745 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155503.00,A,3648.16093,N,12147.28250,W,0.156,0.00,090625,,,A*79
2025-06-09T15:55:04.748Z,1749484504.748 [NAL9602](INFO): GPS fix at 20250609T155503: (36.802682, -121.788042)
2025-06-09T15:55:04.779Z,1749484504.779 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T15:55:04.779Z,1749484504.779 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T15:55:07.749Z,1749484507.749 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20250609T152832/Courier0007.lzma
2025-06-09T15:55:08.751Z,1749484508.751 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Courier0007.lzma.bak
2025-06-09T15:55:08.751Z,1749484508.751 [DataOverHttps](INFO): SBD MOMSN=25160380
2025-06-09T15:55:25.630Z,1749484525.630 [DataOverHttps](INFO): Sending 224 bytes from file Logs/20250609T152832/Courier0010.lzma
2025-06-09T15:55:26.631Z,1749484526.631 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Courier0010.lzma.bak
2025-06-09T15:55:26.631Z,1749484526.631 [DataOverHttps](INFO): SBD MOMSN=25160382
2025-06-09T15:55:37.179Z,1749484537.179 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T15:55:42.558Z,1749484542.558 [DataOverHttps](INFO): Sending 250 bytes from file Logs/20250609T152832/Express0008.lzma
2025-06-09T15:55:43.584Z,1749484543.584 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Express0008.lzma.bak
2025-06-09T15:55:43.584Z,1749484543.584 [DataOverHttps](INFO): SBD MOMSN=25160385
2025-06-09T15:55:59.454Z,1749484559.454 [DataOverHttps](INFO): Sending 384 bytes from file Logs/20250609T152832/Express0011.lzma
2025-06-09T15:56:00.455Z,1749484560.455 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Express0011.lzma.bak
2025-06-09T15:56:00.455Z,1749484560.455 [DataOverHttps](INFO): SBD MOMSN=25160393
2025-06-09T15:56:01.839Z,1749484561.839 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T15:56:01.839Z,1749484561.839 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T15:56:01.839Z,1749484561.839 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T16:01:02.418Z,1749484862.418 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T16:01:02.418Z,1749484862.418 [Default:CheckIn:C.Wait] Stopped
2025-06-09T16:01:02.418Z,1749484862.418 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T16:01:02.418Z,1749484862.418 [Default:CheckIn:D] Running Loop=1
2025-06-09T16:01:02.825Z,1749484862.825 [Default:CheckIn:D] Stopped
2025-06-09T16:01:02.825Z,1749484862.825 [Default:CheckIn:E] Running Loop=1
2025-06-09T16:01:03.229Z,1749484863.229 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.316357 min
2025-06-09T16:01:03.229Z,1749484863.229 [Default:CheckIn:E] Stopped
2025-06-09T16:01:03.230Z,1749484863.230 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T16:01:03.230Z,1749484863.230 [Default:CheckIn] Stopped
2025-06-09T16:01:03.230Z,1749484863.230 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T16:01:03.230Z,1749484863.230 [Default:CheckIn](INFO): Running loop #4
2025-06-09T16:01:03.230Z,1749484863.230 [Default:CheckIn] Running Loop=4
2025-06-09T16:01:03.230Z,1749484863.230 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T16:01:03.230Z,1749484863.230 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T16:01:05.236Z,1749484865.236 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160104.00,A,3648.19441,N,12147.27524,W,0.369,6.36,090625,,,A*7E
2025-06-09T16:01:05.238Z,1749484865.238 [NAL9602](INFO): GPS fix at 20250609T160104: (36.803240, -121.787921)
2025-06-09T16:01:05.249Z,1749484865.249 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T16:01:05.249Z,1749484865.249 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T16:01:12.505Z,1749484872.505 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250609T152832/Courier0013.lzma
2025-06-09T16:01:13.507Z,1749484873.507 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Courier0013.lzma.bak
2025-06-09T16:01:13.508Z,1749484873.508 [DataOverHttps](INFO): SBD MOMSN=25160492
2025-06-09T16:01:29.529Z,1749484889.529 [DataOverHttps](INFO): Sending 166 bytes from file Logs/20250609T152832/Express0014.lzma
2025-06-09T16:01:30.531Z,1749484890.531 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Express0014.lzma.bak
2025-06-09T16:01:30.531Z,1749484890.531 [DataOverHttps](INFO): SBD MOMSN=25160496
2025-06-09T16:01:31.907Z,1749484891.907 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T16:01:31.907Z,1749484891.907 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T16:01:31.907Z,1749484891.907 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T16:01:52.908Z,1749484912.908 [NAL9602](INFO): SBD MO Status=0, MOMSN=25166, MT Status=0, MTMSN=0
2025-06-09T16:01:52.908Z,1749484912.908 [NAL9602](INFO): No messages in MT queue
2025-06-09T16:02:09.579Z,1749484929.579 [DataOverHttps](IMPORTANT): SBD MTMSN=20250609T160208
2025-06-09T16:02:17.891Z,1749484937.891 [DAT](INFO): DAT read: user:6>Rx Time:16:02:16.7552
2025-06-09T16:02:17.891Z,1749484937.891 [DAT](INFO): Rx dataTimestamp_ set to:1749484937.890897
2025-06-09T16:02:17.892Z,1749484937.892 [DAT](INFO): DAT read: $Low SNR acquisition
2025-06-09T16:02:17.892Z,1749484937.892 [DAT](INFO): Received low SNR in chirp
2025-06-09T16:02:22.006Z,1749484942.006 [DataOverHttps](INFO): Received command: failc
2025-06-09T16:02:22.058Z,1749484942.058 [CommandExec](IMPORTANT): got command failComponent
2025-06-09T16:02:22.058Z,1749484942.058 [CommandExec](IMPORTANT): Failed components:
2025-06-09T16:02:22.059Z,1749484942.059 [CommandExec](IMPORTANT): No failed Components.
2025-06-09T16:02:23.007Z,1749484943.007 [DataOverHttps](IMPORTANT): SBD MTMSN=20250609T160222
2025-06-09T16:02:23.630Z,1749484943.630 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T16:02:30.577Z,1749484950.577 [DataOverHttps](INFO): Received command: ibit
2025-06-09T16:02:30.586Z,1749484950.586 [CommandExec](IMPORTANT): got command ibit
2025-06-09T16:02:30.921Z,1749484950.921 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-06-09T16:02:30.921Z,1749484950.921 [IBIT](IMPORTANT): Beginning control surface checks.
2025-06-09T16:02:30.924Z,1749484950.924 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-06-09T16:02:32.520Z,1749484952.520 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160231.00,A,3648.18527,N,12147.26686,W,2.488,110.36,090625,,,A*7D
2025-06-09T16:02:32.523Z,1749484952.523 [NAL9602](INFO): GPS fix at 20250609T160231: (36.803088, -121.787781)
2025-06-09T16:02:35.542Z,1749484955.542 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.001051
CHAN A1 (24V): 0.000547
CHAN A2 (12V): 0.000590
CHAN A3 (5V): -0.001318
CHAN B0 (3.3V): 0.000573
CHAN B1 (3.15aV): 0.000613
CHAN B2 (3.15bV): 0.000127
CHAN B3 (GND): -0.000035
OPEN: 0.006707
Full Scale: +/- 1 mA
2025-06-09T16:02:36.295Z,1749484956.295 [DAT](INFO): DAT read: Rx Time:16:02:35.1982
2025-06-09T16:02:36.295Z,1749484956.295 [DAT](INFO): Rx dataTimestamp_ set to:1749484956.295207
2025-06-09T16:02:37.303Z,1749484957.303 [DAT](INFO): DAT read: $Error in header
2025-06-09T16:02:37.303Z,1749484957.303 [DAT](INFO): Received a bad header
2025-06-09T16:02:38.310Z,1749484958.310 [DAT](INFO): DAT read: Rx Time:16:02:37.1766
2025-06-09T16:02:38.311Z,1749484958.311 [DAT](INFO): Rx dataTimestamp_ set to:1749484958.310531
2025-06-09T16:02:39.067Z,1749484959.067 [DAT](INFO): DAT read: $Error in header
2025-06-09T16:02:39.067Z,1749484959.067 [DAT](INFO): Received a bad header
2025-06-09T16:02:40.075Z,1749484960.075 [DAT](INFO): DAT read: Rx Time:16:02:38.9449
2025-06-09T16:02:40.075Z,1749484960.075 [DAT](INFO): Rx dataTimestamp_ set to:1749484960.074846
2025-06-09T16:02:40.076Z,1749484960.076 [DAT](INFO): DAT read: $Low SNR acquisition
2025-06-09T16:02:40.076Z,1749484960.076 [DAT](INFO): Received low SNR in chirp
2025-06-09T16:02:43.358Z,1749484963.358 [DAT](INFO): DAT read: Rx Time:16:02:42.1719
2025-06-09T16:02:43.359Z,1749484963.359 [DAT](INFO): Rx dataTimestamp_ set to:1749484963.358639
2025-06-09T16:02:43.359Z,1749484963.359 [DAT](INFO): DAT read:
2025-06-09T16:02:43.360Z,1749484963.360 [DAT](INFO): DAT read: $Packet for address 0
2025-06-09T16:02:43.360Z,1749484963.360 [DAT](INFO): received a packet notification
2025-06-09T16:02:45.631Z,1749484965.631 [DAT](INFO): DAT read: Rx Time:16:02:44.4970
2025-06-09T16:02:45.631Z,1749484965.631 [DAT](INFO): Rx dataTimestamp_ set to:1749484965.630978
2025-06-09T16:02:46.396Z,1749484966.396 [DAT](INFO): DAT read: 16:02:44.4970 LVL= 22624, 20113, 15282, 19699, AGC= 82, IDX= 23, 0.38, 0.885, 2.290, 0.489,-0.226, PHS= 1.077, 2.501, 0.703, RAW= 341.4, -42.7, CAL= 341.5, -41.0, ROT= 168.5, 41.0
2025-06-09T16:02:46.398Z,1749484966.398 [DAT](INFO): got valid direction response:
16:02:44.4970 LVL= 22624, 20113, 15282, 19699, AGC= 82, IDX= 23, 0.38, 0.885, 2.290, 0.489,-0.226, PHS= 1.077, 2.501, 0.703, RAW= 341.4, -42.7, CAL= 341.5, -41.0, ROT= 168.5, 41.0
2025-06-09T16:02:46.399Z,1749484966.399 [DAT](INFO): DAT read: $Error in header
2025-06-09T16:02:46.400Z,1749484966.400 [DAT](INFO): Received a bad header
2025-06-09T16:02:46.414Z,1749484966.414 [DAT](INFO): DAT read: ader stats CRC:Fail PSNR:06.7 CCERR:005 Mod:FH
2025-06-09T16:02:46.415Z,1749484966.415 [DAT](INFO): unknown deviceResponse_: ader stats CRC:Fail PSNR:06.7 CCERR:005 Mod:FH
2025-06-09T16:02:46.415Z,1749484966.415 [DAT](INFO): DAT read:
2025-06-09T16:02:46.415Z,1749484966.415 [DAT](INFO): #Rx 1: Read direction message, but no range.
2025-06-09T16:02:46.417Z,1749484966.417 [DAT](INFO): direction in FSK: [-0.739559,0.150465,-0.656059]
2025-06-09T16:02:54.332Z,1749484974.332 [NAL9602](INFO): SBD MO Status=0, MOMSN=25167, MT Status=0, MTMSN=0
2025-06-09T16:02:54.332Z,1749484974.332 [NAL9602](INFO): No messages in MT queue
2025-06-09T16:02:55.548Z,1749484975.548 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160254.00,A,3648.16860,N,12147.25424,W,2.644,102.26,090625,,,A*77
2025-06-09T16:02:55.550Z,1749484975.550 [NAL9602](INFO): GPS fix at 20250609T160254: (36.802810, -121.787571)
2025-06-09T16:02:57.972Z,1749484977.972 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160257.00,A,3648.16720,N,12147.25250,W,2.371,115.65,090625,,,A*78
2025-06-09T16:02:57.975Z,1749484977.975 [NAL9602](INFO): GPS fix at 20250609T160257: (36.802787, -121.787542)
2025-06-09T16:02:58.764Z,1749484978.764 [DAT](INFO): DAT read: Rx Time:16:02:57.7424
2025-06-09T16:02:58.765Z,1749484978.765 [DAT](INFO): Rx dataTimestamp_ set to:1749484978.764639
2025-06-09T16:02:58.766Z,1749484978.766 [DAT](INFO): DAT read: $Low SNR acquisition
2025-06-09T16:02:58.766Z,1749484978.766 [DAT](INFO): Received low SNR in chirp
2025-06-09T16:03:00.799Z,1749484980.799 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160300.00,A,3648.16558,N,12147.25082,W,1.769,139.99,090625,,,A*78
2025-06-09T16:03:00.801Z,1749484980.801 [NAL9602](INFO): GPS fix at 20250609T160300: (36.802760, -121.787514)
2025-06-09T16:03:04.036Z,1749484984.036 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160303.00,A,3648.16426,N,12147.25052,W,0.739,169.36,090625,,,A*7A
2025-06-09T16:03:04.038Z,1749484984.038 [NAL9602](INFO): GPS fix at 20250609T160303: (36.802738, -121.787509)
2025-06-09T16:03:06.858Z,1749484986.858 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160306.00,A,3648.16341,N,12147.25055,W,1.361,180.50,090625,,,A*71
2025-06-09T16:03:06.861Z,1749484986.861 [NAL9602](INFO): GPS fix at 20250609T160306: (36.802723, -121.787509)
2025-06-09T16:03:10.088Z,1749484990.088 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160309.00,A,3648.16192,N,12147.25082,W,2.158,197.77,090625,,,A*70
2025-06-09T16:03:10.090Z,1749484990.090 [NAL9602](INFO): GPS fix at 20250609T160309: (36.802699, -121.787514)
2025-06-09T16:03:12.914Z,1749484992.914 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160312.00,A,3648.16004,N,12147.25163,W,2.974,201.73,090625,,,A*74
2025-06-09T16:03:12.916Z,1749484992.916 [NAL9602](INFO): GPS fix at 20250609T160312: (36.802667, -121.787527)
2025-06-09T16:03:14.913Z,1749484994.913 [DAT](INFO): DAT read: Rx Time:16:03:13.7182
2025-06-09T16:03:14.919Z,1749484994.919 [DAT](INFO): Rx dataTimestamp_ set to:1749484994.912947
2025-06-09T16:03:15.666Z,1749484995.666 [DAT](INFO): DAT read: $Error in header
2025-06-09T16:03:15.667Z,1749484995.667 [DAT](INFO): Received a bad header
2025-06-09T16:03:15.741Z,1749484995.741 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160315.00,A,3648.15807,N,12147.25266,W,2.721,204.59,090625,,,A*7E
2025-06-09T16:03:15.743Z,1749484995.743 [NAL9602](INFO): GPS fix at 20250609T160315: (36.802635, -121.787544)
2025-06-09T16:03:16.675Z,1749484996.675 [DAT](INFO): DAT read: Rx Time:16:03:15.5486
2025-06-09T16:03:16.675Z,1749484996.675 [DAT](INFO): Rx dataTimestamp_ set to:1749484996.674705
2025-06-09T16:03:17.372Z,1749484997.372 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 2
Latitude: 36.802635 Longitude: -121.787544
2025-06-09T16:03:17.431Z,1749484997.431 [DAT](INFO): DAT read: $Error in header
2025-06-09T16:03:17.431Z,1749484997.431 [DAT](INFO): Received a bad header
2025-06-09T16:03:17.790Z,1749484997.790 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.650999
2025-06-09T16:03:17.790Z,1749484997.790 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2025-06-09T16:03:17.791Z,1749484997.791 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2025-06-09T16:03:18.191Z,1749484998.191 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2025-06-09T16:03:18.191Z,1749484998.191 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2025-06-09T16:03:18.192Z,1749484998.192 [IBIT](IMPORTANT): Pressure:10.106466 PSI
2025-06-09T16:03:18.192Z,1749484998.192 [IBIT](IMPORTANT): Humidity:23.464779 %
2025-06-09T16:03:18.602Z,1749484998.602 [IBIT](IMPORTANT): Vehicle Pitch:0.274417 degrees
2025-06-09T16:03:18.602Z,1749484998.602 [IBIT](IMPORTANT): Vehicle Roll:2.050826 degrees
2025-06-09T16:03:18.603Z,1749484998.603 [IBIT](IMPORTANT): Vehicle Heading:197.589661 degrees
2025-06-09T16:03:19.026Z,1749484999.026 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2025-06-09T16:03:19.027Z,1749484999.027 [IBIT](IMPORTANT): buoyancyNeutral: 217.238968 cc
2025-06-09T16:03:19.027Z,1749484999.027 [IBIT](IMPORTANT): massDefault: 2.152337 cm
2025-06-09T16:03:19.027Z,1749484999.027 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2025-06-09T16:03:19.027Z,1749484999.027 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2025-06-09T16:03:19.028Z,1749484999.028 [IBIT](IMPORTANT): IBIT PASSED
2025-06-09T16:03:23.730Z,1749485003.730 [DAT](INFO): DAT read: Rx Time:16:03:22.5622
2025-06-09T16:03:23.731Z,1749485003.731 [DAT](INFO): Rx dataTimestamp_ set to:1749485003.730614
2025-06-09T16:03:23.731Z,1749485003.731 [DAT](INFO): DAT read:
2025-06-09T16:03:23.732Z,1749485003.732 [DAT](INFO): DAT read: $Packet for address 0
2025-06-09T16:03:23.732Z,1749485003.732 [DAT](INFO): received a packet notification
2025-06-09T16:03:25.998Z,1749485005.998 [DAT](INFO): DAT read: Rx Time:16:03:24.8983
2025-06-09T16:03:25.999Z,1749485005.999 [DAT](INFO): Rx dataTimestamp_ set to:1749485005.998664
2025-06-09T16:03:25.999Z,1749485005.999 [DAT](INFO): DAT read:
2025-06-09T16:03:25.000Z,1749485006.000 [DAT](INFO): DAT read: $Packet for address 0
2025-06-09T16:03:26.000Z,1749485006.000 [DAT](INFO): received a packet notification
2025-06-09T16:03:26.754Z,1749485006.754 [DAT](INFO): DAT read: Rx Time:16:03:25.5693
2025-06-09T16:03:26.755Z,1749485006.755 [DAT](INFO): Rx dataTimestamp_ set to:1749485006.754598
2025-06-09T16:03:26.755Z,1749485006.755 [DAT](INFO): DAT read:
2025-06-09T16:03:26.756Z,1749485006.756 [DAT](INFO): DAT read: $Packet for address 0
2025-06-09T16:03:26.756Z,1749485006.756 [DAT](INFO): received a packet notification
2025-06-09T16:03:30.283Z,1749485010.283 [DAT](INFO): DAT read: Rx Time:16:03:29.0978
2025-06-09T16:03:30.283Z,1749485010.283 [DAT](INFO): Rx dataTimestamp_ set to:1749485010.282856
2025-06-09T16:03:31.039Z,1749485011.039 [DAT](INFO): DAT read: $Error in header
2025-06-09T16:03:31.039Z,1749485011.039 [DAT](INFO): Received a bad header
2025-06-09T16:03:32.555Z,1749485012.555 [DAT](INFO): DAT read: Rx Time:16:03:31.4043
2025-06-09T16:03:32.555Z,1749485012.555 [DAT](INFO): Rx dataTimestamp_ set to:1749485012.554703
2025-06-09T16:03:33.311Z,1749485013.311 [DAT](INFO): DAT read: $Error in header
2025-06-09T16:03:33.311Z,1749485013.311 [DAT](INFO): Received a bad header
2025-06-09T16:03:33.814Z,1749485013.814 [DAT](INFO): DAT read: Rx Time:16:03:32.7643
2025-06-09T16:03:33.815Z,1749485013.815 [DAT](INFO): Rx dataTimestamp_ set to:1749485013.814429
2025-06-09T16:03:34.823Z,1749485014.823 [DAT](INFO): DAT read: $Error in header
2025-06-09T16:03:34.823Z,1749485014.823 [DAT](INFO): Received a bad header
2025-06-09T16:03:45.422Z,1749485025.422 [DAT](INFO): DAT read: Rx Time:16:03:44.3077
2025-06-09T16:03:45.423Z,1749485025.423 [DAT](INFO): Rx dataTimestamp_ set to:1749485025.422585
2025-06-09T16:03:45.423Z,1749485025.423 [DAT](INFO): DAT read:
2025-06-09T16:03:45.424Z,1749485025.424 [DAT](INFO): DAT read: $Packet for address 0
2025-06-09T16:03:45.424Z,1749485025.424 [DAT](INFO): received a packet notification
2025-06-09T16:03:48.464Z,1749485028.464 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T16:06:32.516Z,1749485192.516 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T16:06:32.516Z,1749485192.516 [Default:CheckIn:C.Wait] Stopped
2025-06-09T16:06:32.516Z,1749485192.516 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T16:06:32.516Z,1749485192.516 [Default:CheckIn:D] Running Loop=1
2025-06-09T16:06:32.929Z,1749485192.929 [Default:CheckIn:D] Stopped
2025-06-09T16:06:32.929Z,1749485192.929 [Default:CheckIn:E] Running Loop=1
2025-06-09T16:06:33.325Z,1749485193.325 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 33.818083 min
2025-06-09T16:06:33.349Z,1749485193.349 [Default:CheckIn:E] Stopped
2025-06-09T16:06:33.349Z,1749485193.349 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T16:06:33.350Z,1749485193.350 [Default:CheckIn] Stopped
2025-06-09T16:06:33.350Z,1749485193.350 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T16:06:33.350Z,1749485193.350 [Default:CheckIn](INFO): Running loop #5
2025-06-09T16:06:33.350Z,1749485193.350 [Default:CheckIn] Running Loop=5
2025-06-09T16:06:33.350Z,1749485193.350 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T16:06:33.350Z,1749485193.350 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T16:06:35.319Z,1749485195.319 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160634.00,A,3648.14687,N,12147.22607,W,0.039,45.37,090625,,,A*48
2025-06-09T16:06:35.321Z,1749485195.321 [NAL9602](INFO): GPS fix at 20250609T160634: (36.802448, -121.787101)
2025-06-09T16:06:35.333Z,1749485195.333 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T16:06:35.333Z,1749485195.333 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T16:06:44.337Z,1749485204.337 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250609T152832/Courier0016.lzma
2025-06-09T16:06:45.340Z,1749485205.340 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Courier0016.lzma.bak
2025-06-09T16:06:45.340Z,1749485205.340 [DataOverHttps](INFO): SBD MOMSN=25160571
2025-06-09T16:06:48.246Z,1749485208.246 [NAL9602](INFO): SBD MO Status=0, MOMSN=25168, MT Status=0, MTMSN=0
2025-06-09T16:06:48.246Z,1749485208.246 [NAL9602](INFO): No messages in MT queue
2025-06-09T16:07:03.996Z,1749485223.996 [DataOverHttps](INFO): Sending 1067 bytes from file Logs/20250609T152832/Express0017.lzma
2025-06-09T16:07:04.995Z,1749485224.995 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Express0017.lzma.bak
2025-06-09T16:07:04.995Z,1749485224.995 [DataOverHttps](INFO): SBD MOMSN=25160576
2025-06-09T16:07:18.978Z,1749485238.978 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T16:07:22.121Z,1749485242.121 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20250609T152832/Express0020.lzma
2025-06-09T16:07:23.125Z,1749485243.125 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Express0020.lzma.bak
2025-06-09T16:07:23.125Z,1749485243.125 [DataOverHttps](INFO): SBD MOMSN=25160625
2025-06-09T16:07:24.245Z,1749485244.245 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T16:07:24.245Z,1749485244.245 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T16:07:24.245Z,1749485244.245 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T16:12:24.855Z,1749485544.855 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T16:12:24.855Z,1749485544.855 [Default:CheckIn:C.Wait] Stopped
2025-06-09T16:12:24.855Z,1749485544.855 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T16:12:24.855Z,1749485544.855 [Default:CheckIn:D] Running Loop=1
2025-06-09T16:12:25.267Z,1749485545.267 [Default:CheckIn:D] Stopped
2025-06-09T16:12:25.267Z,1749485545.267 [Default:CheckIn:E] Running Loop=1
2025-06-09T16:12:25.661Z,1749485545.661 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 39.690397 min
2025-06-09T16:12:25.661Z,1749485545.661 [Default:CheckIn:E] Stopped
2025-06-09T16:12:25.661Z,1749485545.661 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T16:12:25.662Z,1749485545.662 [Default:CheckIn] Stopped
2025-06-09T16:12:25.662Z,1749485545.662 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T16:12:25.662Z,1749485545.662 [Default:CheckIn](INFO): Running loop #6
2025-06-09T16:12:25.662Z,1749485545.662 [Default:CheckIn] Running Loop=6
2025-06-09T16:12:25.662Z,1749485545.662 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T16:12:25.662Z,1749485545.662 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T16:12:27.676Z,1749485547.676 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161227.00,A,3648.14644,N,12147.22554,W,0.039,45.37,090625,,,D*40
2025-06-09T16:12:27.679Z,1749485547.679 [NAL9602](INFO): GPS fix at 20250609T161227: (36.802441, -121.787092)
2025-06-09T16:12:27.690Z,1749485547.690 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T16:12:27.690Z,1749485547.690 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T16:12:36.142Z,1749485556.142 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250609T152832/Courier0022.lzma
2025-06-09T16:12:37.139Z,1749485557.139 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Courier0022.lzma.bak
2025-06-09T16:12:37.140Z,1749485557.140 [DataOverHttps](INFO): SBD MOMSN=25160777
2025-06-09T16:12:50.336Z,1749485570.336 [NAL9602](INFO): SBD MO Status=0, MOMSN=25169, MT Status=0, MTMSN=0
2025-06-09T16:12:50.337Z,1749485570.337 [NAL9602](INFO): No messages in MT queue
2025-06-09T16:12:55.554Z,1749485575.554 [DataOverHttps](INFO): Sending 157 bytes from file Logs/20250609T152832/Express0023.lzma
2025-06-09T16:12:56.555Z,1749485576.555 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Express0023.lzma.bak
2025-06-09T16:12:56.555Z,1749485576.555 [DataOverHttps](INFO): SBD MOMSN=25160781
2025-06-09T16:12:57.615Z,1749485577.615 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T16:12:57.615Z,1749485577.615 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T16:12:57.615Z,1749485577.615 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T16:13:21.043Z,1749485601.043 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T16:17:58.198Z,1749485878.198 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T16:17:58.198Z,1749485878.198 [Default:CheckIn:C.Wait] Stopped
2025-06-09T16:17:58.198Z,1749485878.198 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T16:17:58.198Z,1749485878.198 [Default:CheckIn:D] Running Loop=1
2025-06-09T16:17:58.603Z,1749485878.603 [Default:CheckIn:D] Stopped
2025-06-09T16:17:58.603Z,1749485878.603 [Default:CheckIn:E] Running Loop=1
2025-06-09T16:17:59.023Z,1749485879.023 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.245984 min
2025-06-09T16:17:59.023Z,1749485879.023 [Default:CheckIn:E] Stopped
2025-06-09T16:17:59.023Z,1749485879.023 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T16:17:59.023Z,1749485879.023 [Default:CheckIn] Stopped
2025-06-09T16:17:59.023Z,1749485879.023 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T16:17:59.023Z,1749485879.023 [Default:CheckIn](INFO): Running loop #7
2025-06-09T16:17:59.024Z,1749485879.024 [Default:CheckIn] Running Loop=7
2025-06-09T16:17:59.024Z,1749485879.024 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T16:17:59.024Z,1749485879.024 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T16:18:01.021Z,1749485881.021 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161800.00,A,3648.14671,N,12147.22686,W,0.039,45.37,090625,,,D*45
2025-06-09T16:18:01.023Z,1749485881.023 [NAL9602](INFO): GPS fix at 20250609T161800: (36.802445, -121.787114)
2025-06-09T16:18:01.034Z,1749485881.034 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T16:18:01.034Z,1749485881.034 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T16:18:08.533Z,1749485888.533 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250609T152832/Courier0025.lzma
2025-06-09T16:18:09.535Z,1749485889.535 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Courier0025.lzma.bak
2025-06-09T16:18:09.535Z,1749485889.535 [DataOverHttps](INFO): SBD MOMSN=25160860
2025-06-09T16:18:16.776Z,1749485896.776 [NAL9602](INFO): SBD MO Status=0, MOMSN=25170, MT Status=0, MTMSN=0
2025-06-09T16:18:16.776Z,1749485896.776 [NAL9602](INFO): No messages in MT queue
2025-06-09T16:18:26.205Z,1749485906.205 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20250609T152832/Express0026.lzma
2025-06-09T16:18:27.207Z,1749485907.207 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Express0026.lzma.bak
2025-06-09T16:18:27.207Z,1749485907.207 [DataOverHttps](INFO): SBD MOMSN=25160868
2025-06-09T16:18:28.506Z,1749485908.506 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T16:18:28.506Z,1749485908.506 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T16:18:28.506Z,1749485908.506 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T16:18:47.478Z,1749485927.478 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T16:23:29.084Z,1749486209.084 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T16:23:29.084Z,1749486209.084 [Default:CheckIn:C.Wait] Stopped
2025-06-09T16:23:29.084Z,1749486209.084 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T16:23:29.084Z,1749486209.084 [Default:CheckIn:D] Running Loop=1
2025-06-09T16:23:29.480Z,1749486209.480 [Default:CheckIn:D] Stopped
2025-06-09T16:23:29.480Z,1749486209.480 [Default:CheckIn:E] Running Loop=1
2025-06-09T16:23:29.882Z,1749486209.882 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 50.760612 min
2025-06-09T16:23:29.882Z,1749486209.882 [Default:CheckIn:E] Stopped
2025-06-09T16:23:29.882Z,1749486209.882 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T16:23:29.882Z,1749486209.882 [Default:CheckIn] Stopped
2025-06-09T16:23:29.882Z,1749486209.882 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T16:23:29.883Z,1749486209.883 [Default:CheckIn](INFO): Running loop #8
2025-06-09T16:23:29.883Z,1749486209.883 [Default:CheckIn] Running Loop=8
2025-06-09T16:23:29.883Z,1749486209.883 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T16:23:29.883Z,1749486209.883 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T16:23:31.894Z,1749486211.894 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162331.00,A,3648.14453,N,12147.22483,W,0.039,45.37,090625,,,D*4A
2025-06-09T16:23:31.896Z,1749486211.896 [NAL9602](INFO): GPS fix at 20250609T162331: (36.802409, -121.787081)
2025-06-09T16:23:31.907Z,1749486211.907 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T16:23:31.907Z,1749486211.907 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T16:23:39.301Z,1749486219.301 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250609T152832/Courier0028.lzma
2025-06-09T16:23:40.303Z,1749486220.303 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Courier0028.lzma.bak
2025-06-09T16:23:40.303Z,1749486220.303 [DataOverHttps](INFO): SBD MOMSN=25160933
2025-06-09T16:23:48.066Z,1749486228.066 [NAL9602](INFO): SBD MO Status=0, MOMSN=25171, MT Status=0, MTMSN=0
2025-06-09T16:23:48.066Z,1749486228.066 [NAL9602](INFO): No messages in MT queue
2025-06-09T16:23:56.134Z,1749486236.134 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20250609T152832/Express0029.lzma
2025-06-09T16:23:57.135Z,1749486237.135 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Express0029.lzma.bak
2025-06-09T16:23:57.135Z,1749486237.135 [DataOverHttps](INFO): SBD MOMSN=25160936
2025-06-09T16:23:58.184Z,1749486238.184 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T16:23:58.184Z,1749486238.184 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T16:23:58.184Z,1749486238.184 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T16:24:18.758Z,1749486258.758 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T16:28:58.743Z,1749486538.743 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T16:28:58.743Z,1749486538.743 [Default:CheckIn:C.Wait] Stopped
2025-06-09T16:28:58.743Z,1749486538.743 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T16:28:58.743Z,1749486538.743 [Default:CheckIn:D] Running Loop=1
2025-06-09T16:28:59.156Z,1749486539.156 [Default:CheckIn:D] Stopped
2025-06-09T16:28:59.156Z,1749486539.156 [Default:CheckIn:E] Running Loop=1
2025-06-09T16:28:59.550Z,1749486539.550 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.255204 min
2025-06-09T16:28:59.550Z,1749486539.550 [Default:CheckIn:E] Stopped
2025-06-09T16:28:59.550Z,1749486539.550 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T16:28:59.550Z,1749486539.550 [Default:CheckIn] Stopped
2025-06-09T16:28:59.550Z,1749486539.550 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T16:28:59.551Z,1749486539.551 [Default:CheckIn](INFO): Running loop #9
2025-06-09T16:28:59.551Z,1749486539.551 [Default:CheckIn] Running Loop=9
2025-06-09T16:28:59.551Z,1749486539.551 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T16:28:59.551Z,1749486539.551 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T16:29:01.564Z,1749486541.564 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162900.00,A,3648.14204,N,12147.21793,W,0.097,45.37,090625,,,D*43
2025-06-09T16:29:01.567Z,1749486541.567 [NAL9602](INFO): GPS fix at 20250609T162900: (36.802367, -121.786965)
2025-06-09T16:29:01.577Z,1749486541.577 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T16:29:01.577Z,1749486541.577 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T16:29:08.905Z,1749486548.905 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250609T152832/Courier0031.lzma
2025-06-09T16:29:09.907Z,1749486549.907 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Courier0031.lzma.bak
2025-06-09T16:29:09.907Z,1749486549.907 [DataOverHttps](INFO): SBD MOMSN=25161015
2025-06-09T16:29:26.547Z,1749486566.547 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20250609T152832/Express0032.lzma
2025-06-09T16:29:27.547Z,1749486567.547 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Express0032.lzma.bak
2025-06-09T16:29:27.547Z,1749486567.547 [DataOverHttps](INFO): SBD MOMSN=25161021
2025-06-09T16:29:28.658Z,1749486568.658 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T16:29:28.658Z,1749486568.658 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T16:29:28.658Z,1749486568.658 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T16:29:30.256Z,1749486570.256 [NAL9602](INFO): SBD MO Status=0, MOMSN=25172, MT Status=0, MTMSN=0
2025-06-09T16:29:30.256Z,1749486570.256 [NAL9602](INFO): No messages in MT queue
2025-06-09T16:30:00.956Z,1749486600.956 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T16:34:29.220Z,1749486869.220 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T16:34:29.220Z,1749486869.220 [Default:CheckIn:C.Wait] Stopped
2025-06-09T16:34:29.220Z,1749486869.220 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T16:34:29.220Z,1749486869.220 [Default:CheckIn:D] Running Loop=1
2025-06-09T16:34:29.632Z,1749486869.632 [Default:CheckIn:D] Stopped
2025-06-09T16:34:29.632Z,1749486869.632 [Default:CheckIn:E] Running Loop=1
2025-06-09T16:34:30.028Z,1749486870.028 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 61.763147 min
2025-06-09T16:34:30.028Z,1749486870.028 [Default:CheckIn:E] Stopped
2025-06-09T16:34:30.029Z,1749486870.029 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T16:34:30.029Z,1749486870.029 [Default:CheckIn] Stopped
2025-06-09T16:34:30.029Z,1749486870.029 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T16:34:30.029Z,1749486870.029 [Default:CheckIn](INFO): Running loop #10
2025-06-09T16:34:30.029Z,1749486870.029 [Default:CheckIn] Running Loop=10
2025-06-09T16:34:30.029Z,1749486870.029 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T16:34:30.029Z,1749486870.029 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T16:34:32.039Z,1749486872.039 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163431.00,A,3648.14336,N,12147.22228,W,0.039,45.37,090625,,,D*4F
2025-06-09T16:34:32.041Z,1749486872.041 [NAL9602](INFO): GPS fix at 20250609T163431: (36.802389, -121.787038)
2025-06-09T16:34:32.052Z,1749486872.052 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T16:34:32.052Z,1749486872.052 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T16:34:39.829Z,1749486879.829 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250609T152832/Courier0034.lzma
2025-06-09T16:34:40.831Z,1749486880.831 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Courier0034.lzma.bak
2025-06-09T16:34:40.831Z,1749486880.831 [DataOverHttps](INFO): SBD MOMSN=25161051
2025-06-09T16:34:59.569Z,1749486899.569 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20250609T152832/Express0035.lzma
2025-06-09T16:34:59.964Z,1749486899.964 [NAL9602](INFO): SBD MO Status=0, MOMSN=25173, MT Status=0, MTMSN=0
2025-06-09T16:34:59.964Z,1749486899.964 [NAL9602](INFO): No messages in MT queue
2025-06-09T16:35:00.571Z,1749486900.571 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Express0035.lzma.bak
2025-06-09T16:35:00.572Z,1749486900.572 [DataOverHttps](INFO): SBD MOMSN=25161057
2025-06-09T16:35:01.598Z,1749486901.598 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T16:35:01.598Z,1749486901.598 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T16:35:01.598Z,1749486901.598 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T16:35:30.702Z,1749486930.702 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T16:40:02.273Z,1749487202.273 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T16:40:02.274Z,1749487202.274 [Default:CheckIn:C.Wait] Stopped
2025-06-09T16:40:02.274Z,1749487202.274 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T16:40:02.274Z,1749487202.274 [Default:CheckIn:D] Running Loop=1
2025-06-09T16:40:02.680Z,1749487202.680 [Default:CheckIn:D] Stopped
2025-06-09T16:40:02.681Z,1749487202.681 [Default:CheckIn:E] Running Loop=1
2025-06-09T16:40:03.093Z,1749487203.093 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 67.313949 min
2025-06-09T16:40:03.093Z,1749487203.093 [Default:CheckIn:E] Stopped
2025-06-09T16:40:03.093Z,1749487203.093 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T16:40:03.093Z,1749487203.093 [Default:CheckIn] Stopped
2025-06-09T16:40:03.094Z,1749487203.094 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T16:40:03.094Z,1749487203.094 [Default:CheckIn](INFO): Running loop #11
2025-06-09T16:40:03.094Z,1749487203.094 [Default:CheckIn] Running Loop=11
2025-06-09T16:40:03.094Z,1749487203.094 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T16:40:03.094Z,1749487203.094 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T16:40:05.096Z,1749487205.096 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164004.00,A,3648.14610,N,12147.22071,W,0.253,0.00,090625,,,D*7E
2025-06-09T16:40:05.099Z,1749487205.099 [NAL9602](INFO): GPS fix at 20250609T164004: (36.802435, -121.787012)
2025-06-09T16:40:05.130Z,1749487205.130 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T16:40:05.130Z,1749487205.130 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T16:40:12.105Z,1749487212.105 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250609T152832/Courier0037.lzma
2025-06-09T16:40:13.107Z,1749487213.107 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Courier0037.lzma.bak
2025-06-09T16:40:13.107Z,1749487213.107 [DataOverHttps](INFO): SBD MOMSN=25161147
2025-06-09T16:40:15.594Z,1749487215.594 [NAL9602](INFO): SBD MO Status=0, MOMSN=25174, MT Status=0, MTMSN=0
2025-06-09T16:40:15.594Z,1749487215.594 [NAL9602](INFO): No messages in MT queue
2025-06-09T16:40:28.909Z,1749487228.909 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20250609T152832/Express0038.lzma
2025-06-09T16:40:29.912Z,1749487229.912 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Express0038.lzma.bak
2025-06-09T16:40:29.912Z,1749487229.912 [DataOverHttps](INFO): SBD MOMSN=25161156
2025-06-09T16:40:30.956Z,1749487230.956 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T16:40:30.956Z,1749487230.956 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T16:40:30.957Z,1749487230.957 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T16:40:46.302Z,1749487246.302 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T16:45:31.587Z,1749487531.587 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T16:45:31.588Z,1749487531.588 [Default:CheckIn:C.Wait] Stopped
2025-06-09T16:45:31.588Z,1749487531.588 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T16:45:31.588Z,1749487531.588 [Default:CheckIn:D] Running Loop=1
2025-06-09T16:45:31.998Z,1749487531.998 [Default:CheckIn:D] Stopped
2025-06-09T16:45:31.998Z,1749487531.998 [Default:CheckIn:E] Running Loop=1
2025-06-09T16:45:32.410Z,1749487532.410 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 72.802563 min
2025-06-09T16:45:32.410Z,1749487532.410 [Default:CheckIn:E] Stopped
2025-06-09T16:45:32.410Z,1749487532.410 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T16:45:32.410Z,1749487532.410 [Default:CheckIn] Stopped
2025-06-09T16:45:32.410Z,1749487532.410 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T16:45:32.410Z,1749487532.410 [Default:CheckIn](INFO): Running loop #12
2025-06-09T16:45:32.410Z,1749487532.410 [Default:CheckIn] Running Loop=12
2025-06-09T16:45:32.411Z,1749487532.411 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T16:45:32.411Z,1749487532.411 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T16:45:34.406Z,1749487534.406 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164533.00,A,3648.14408,N,12147.21492,W,0.019,0.00,090625,,,D*72
2025-06-09T16:45:34.409Z,1749487534.409 [NAL9602](INFO): GPS fix at 20250609T164533: (36.802401, -121.786915)
2025-06-09T16:45:34.419Z,1749487534.419 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T16:45:34.419Z,1749487534.419 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T16:45:42.057Z,1749487542.057 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250609T152832/Courier0040.lzma
2025-06-09T16:45:43.059Z,1749487543.059 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Courier0040.lzma.bak
2025-06-09T16:45:43.059Z,1749487543.059 [DataOverHttps](INFO): SBD MOMSN=25161201
2025-06-09T16:45:51.780Z,1749487551.780 [NAL9602](INFO): SBD MO Status=0, MOMSN=25175, MT Status=0, MTMSN=0
2025-06-09T16:45:51.781Z,1749487551.781 [NAL9602](INFO): No messages in MT queue
2025-06-09T16:45:59.009Z,1749487559.009 [DataOverHttps](INFO): Sending 157 bytes from file Logs/20250609T152832/Express0041.lzma
2025-06-09T16:46:00.012Z,1749487560.012 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Express0041.lzma.bak
2025-06-09T16:46:00.012Z,1749487560.012 [DataOverHttps](INFO): SBD MOMSN=25161204
2025-06-09T16:46:01.079Z,1749487561.079 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T16:46:01.079Z,1749487561.079 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T16:46:01.079Z,1749487561.079 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T16:46:22.480Z,1749487582.480 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T16:47:28.331Z,1749487648.331 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T16:47:35.198Z,1749487655.198 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T16:47:50.145Z,1749487670.145 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T16:48:05.096Z,1749487685.096 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T16:48:20.446Z,1749487700.446 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T16:48:36.201Z,1749487716.201 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T16:48:51.560Z,1749487731.560 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T16:49:06.501Z,1749487746.501 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T16:49:21.449Z,1749487761.449 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T16:49:36.802Z,1749487776.802 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T16:49:58.215Z,1749487798.215 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-06-09T16:51:01.664Z,1749487861.664 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T16:51:01.664Z,1749487861.664 [Default:CheckIn:C.Wait] Stopped
2025-06-09T16:51:01.664Z,1749487861.664 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T16:51:01.665Z,1749487861.665 [Default:CheckIn:D] Running Loop=1
2025-06-09T16:51:02.064Z,1749487862.064 [Default:CheckIn:D] Stopped
2025-06-09T16:51:02.064Z,1749487862.064 [Default:CheckIn:E] Running Loop=1
2025-06-09T16:51:02.476Z,1749487862.476 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 78.303678 min
2025-06-09T16:51:02.476Z,1749487862.476 [Default:CheckIn:E] Stopped
2025-06-09T16:51:02.476Z,1749487862.476 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T16:51:02.477Z,1749487862.477 [Default:CheckIn] Stopped
2025-06-09T16:51:02.477Z,1749487862.477 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T16:51:02.477Z,1749487862.477 [Default:CheckIn](INFO): Running loop #13
2025-06-09T16:51:02.477Z,1749487862.477 [Default:CheckIn] Running Loop=13
2025-06-09T16:51:02.477Z,1749487862.477 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T16:51:02.477Z,1749487862.477 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T16:51:04.535Z,1749487864.535 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165103.00,A,3648.14578,N,12147.21917,W,0.019,0.00,090625,,,D*72
2025-06-09T16:51:04.537Z,1749487864.537 [NAL9602](INFO): GPS fix at 20250609T165103: (36.802430, -121.786986)
2025-06-09T16:51:04.566Z,1749487864.566 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T16:51:04.566Z,1749487864.566 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T16:51:11.813Z,1749487871.813 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250609T152832/Courier0043.lzma
2025-06-09T16:51:12.815Z,1749487872.815 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Courier0043.lzma.bak
2025-06-09T16:51:12.815Z,1749487872.815 [DataOverHttps](INFO): SBD MOMSN=25161274
2025-06-09T16:51:17.123Z,1749487877.123 [NAL9602](INFO): SBD MO Status=0, MOMSN=25176, MT Status=0, MTMSN=0
2025-06-09T16:51:17.124Z,1749487877.124 [NAL9602](INFO): No messages in MT queue
2025-06-09T16:51:28.889Z,1749487888.889 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20250609T152832/Express0044.lzma
2025-06-09T16:51:29.891Z,1749487889.891 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Express0044.lzma.bak
2025-06-09T16:51:29.891Z,1749487889.891 [DataOverHttps](INFO): SBD MOMSN=25161281
2025-06-09T16:51:31.157Z,1749487891.157 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T16:51:31.157Z,1749487891.157 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T16:51:31.157Z,1749487891.157 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T16:51:47.704Z,1749487907.704 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T16:52:12.763Z,1749487932.763 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T16:52:26.901Z,1749487946.901 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T16:52:40.637Z,1749487960.637 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T16:52:54.777Z,1749487974.777 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T16:53:08.917Z,1749487988.917 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T16:53:23.062Z,1749488003.062 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T16:53:36.404Z,1749488016.404 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T16:53:50.532Z,1749488030.532 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T16:54:04.677Z,1749488044.677 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T16:54:18.422Z,1749488058.422 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T16:56:31.750Z,1749488191.750 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T16:56:31.751Z,1749488191.751 [Default:CheckIn:C.Wait] Stopped
2025-06-09T16:56:31.751Z,1749488191.751 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T16:56:31.751Z,1749488191.751 [Default:CheckIn:D] Running Loop=1
2025-06-09T16:56:32.148Z,1749488192.148 [Default:CheckIn:D] Stopped
2025-06-09T16:56:32.148Z,1749488192.148 [Default:CheckIn:E] Running Loop=1
2025-06-09T16:56:32.614Z,1749488192.614 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.805070 min
2025-06-09T16:56:32.614Z,1749488192.614 [Default:CheckIn:E] Stopped
2025-06-09T16:56:32.614Z,1749488192.614 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T16:56:32.614Z,1749488192.614 [Default:CheckIn] Stopped
2025-06-09T16:56:32.615Z,1749488192.615 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T16:56:32.615Z,1749488192.615 [Default:CheckIn](INFO): Running loop #14
2025-06-09T16:56:32.615Z,1749488192.615 [Default:CheckIn] Running Loop=14
2025-06-09T16:56:32.615Z,1749488192.615 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T16:56:32.615Z,1749488192.615 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T16:56:34.560Z,1749488194.560 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165633.00,A,3648.13714,N,12147.20790,W,0.117,0.00,090625,,,D*76
2025-06-09T16:56:34.563Z,1749488194.563 [NAL9602](INFO): GPS fix at 20250609T165633: (36.802286, -121.786798)
2025-06-09T16:56:34.573Z,1749488194.573 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T16:56:34.573Z,1749488194.573 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T16:56:41.593Z,1749488201.593 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250609T152832/Courier0046.lzma
2025-06-09T16:56:42.595Z,1749488202.595 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Courier0046.lzma.bak
2025-06-09T16:56:42.595Z,1749488202.595 [DataOverHttps](INFO): SBD MOMSN=25161313
2025-06-09T16:56:51.544Z,1749488211.544 [NAL9602](INFO): SBD MO Status=0, MOMSN=25177, MT Status=0, MTMSN=0
2025-06-09T16:56:51.544Z,1749488211.544 [NAL9602](INFO): No messages in MT queue
2025-06-09T16:57:01.917Z,1749488221.917 [DataOverHttps](INFO): Sending 164 bytes from file Logs/20250609T152832/Express0047.lzma
2025-06-09T16:57:02.919Z,1749488222.919 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Express0047.lzma.bak
2025-06-09T16:57:02.920Z,1749488222.920 [DataOverHttps](INFO): SBD MOMSN=25161316
2025-06-09T16:57:04.078Z,1749488224.078 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T16:57:04.078Z,1749488224.078 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T16:57:04.078Z,1749488224.078 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T16:57:04.873Z,1749488224.873 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T16:57:19.821Z,1749488239.821 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T16:57:22.249Z,1749488242.249 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T16:57:35.174Z,1749488255.174 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T16:57:50.526Z,1749488270.526 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T16:58:06.690Z,1749488286.690 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T16:58:22.447Z,1749488302.447 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T16:58:37.393Z,1749488317.393 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T16:58:52.342Z,1749488332.342 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T16:59:07.290Z,1749488347.290 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T17:01:37.583Z,1749488497.583 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7).
2025-06-09T17:01:44.451Z,1749488504.451 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T17:01:58.185Z,1749488518.185 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T17:02:04.669Z,1749488524.669 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T17:02:04.669Z,1749488524.669 [Default:CheckIn:C.Wait] Stopped
2025-06-09T17:02:04.669Z,1749488524.669 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T17:02:04.685Z,1749488524.685 [Default:CheckIn:D] Running Loop=1
2025-06-09T17:02:05.075Z,1749488525.075 [Default:CheckIn:D] Stopped
2025-06-09T17:02:05.075Z,1749488525.075 [Default:CheckIn:E] Running Loop=1
2025-06-09T17:02:05.475Z,1749488525.475 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 89.353857 min
2025-06-09T17:02:05.476Z,1749488525.476 [Default:CheckIn:E] Stopped
2025-06-09T17:02:05.476Z,1749488525.476 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T17:02:05.476Z,1749488525.476 [Default:CheckIn] Stopped
2025-06-09T17:02:05.476Z,1749488525.476 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T17:02:05.476Z,1749488525.476 [Default:CheckIn](INFO): Running loop #15
2025-06-09T17:02:05.476Z,1749488525.476 [Default:CheckIn] Running Loop=15
2025-06-09T17:02:05.476Z,1749488525.476 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T17:02:05.476Z,1749488525.476 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T17:02:07.481Z,1749488527.481 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170206.00,A,3648.14246,N,12147.21735,W,0.019,0.00,090625,,,D*74
2025-06-09T17:02:07.498Z,1749488527.498 [NAL9602](INFO): GPS fix at 20250609T170206: (36.802374, -121.786956)
2025-06-09T17:02:07.509Z,1749488527.509 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T17:02:07.509Z,1749488527.509 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T17:02:11.927Z,1749488531.927 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T17:02:15.109Z,1749488535.109 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250609T152832/Courier0049.lzma
2025-06-09T17:02:16.111Z,1749488536.111 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Courier0049.lzma.bak
2025-06-09T17:02:16.111Z,1749488536.111 [DataOverHttps](INFO): SBD MOMSN=25161399
2025-06-09T17:02:25.667Z,1749488545.667 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T17:02:32.033Z,1749488552.033 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20250609T152832/Express0050.lzma
2025-06-09T17:02:33.035Z,1749488553.035 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Express0050.lzma.bak
2025-06-09T17:02:33.036Z,1749488553.036 [DataOverHttps](INFO): SBD MOMSN=25161402
2025-06-09T17:02:34.158Z,1749488554.158 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T17:02:34.158Z,1749488554.158 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T17:02:34.159Z,1749488554.159 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T17:02:35.371Z,1749488555.371 [NAL9602](INFO): SBD MO Status=0, MOMSN=25178, MT Status=0, MTMSN=0
2025-06-09T17:02:35.371Z,1749488555.371 [NAL9602](INFO): No messages in MT queue
2025-06-09T17:02:39.808Z,1749488559.808 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T17:02:53.196Z,1749488573.196 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T17:03:06.072Z,1749488586.072 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T17:03:07.282Z,1749488587.282 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T17:03:20.608Z,1749488600.608 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T17:03:34.754Z,1749488614.754 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T17:03:48.882Z,1749488628.882 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T17:06:15.593Z,1749488775.593 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T17:06:22.401Z,1749488782.401 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T17:06:37.756Z,1749488797.756 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T17:06:53.510Z,1749488813.510 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T17:07:08.861Z,1749488828.861 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T17:07:24.617Z,1749488844.617 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T17:07:34.737Z,1749488854.737 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T17:07:34.737Z,1749488854.737 [Default:CheckIn:C.Wait] Stopped
2025-06-09T17:07:34.737Z,1749488854.737 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T17:07:34.737Z,1749488854.737 [Default:CheckIn:D] Running Loop=1
2025-06-09T17:07:35.137Z,1749488855.137 [Default:CheckIn:D] Stopped
2025-06-09T17:07:35.137Z,1749488855.137 [Default:CheckIn:E] Running Loop=1
2025-06-09T17:07:35.545Z,1749488855.545 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 94.854883 min
2025-06-09T17:07:35.545Z,1749488855.545 [Default:CheckIn:E] Stopped
2025-06-09T17:07:35.545Z,1749488855.545 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T17:07:35.545Z,1749488855.545 [Default:CheckIn] Stopped
2025-06-09T17:07:35.545Z,1749488855.545 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T17:07:35.545Z,1749488855.545 [Default:CheckIn](INFO): Running loop #16
2025-06-09T17:07:35.546Z,1749488855.546 [Default:CheckIn] Running Loop=16
2025-06-09T17:07:35.546Z,1749488855.546 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T17:07:35.546Z,1749488855.546 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T17:07:37.558Z,1749488857.558 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170736.00,A,3648.13927,N,12147.21663,W,0.156,0.00,090625,,,D*71
2025-06-09T17:07:37.560Z,1749488857.560 [NAL9602](INFO): GPS fix at 20250609T170736: (36.802321, -121.786944)
2025-06-09T17:07:37.571Z,1749488857.571 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T17:07:37.571Z,1749488857.571 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T17:07:39.566Z,1749488859.566 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T17:07:45.573Z,1749488865.573 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250609T152832/Courier0052.lzma
2025-06-09T17:07:46.575Z,1749488866.575 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Courier0052.lzma.bak
2025-06-09T17:07:46.575Z,1749488866.575 [DataOverHttps](INFO): SBD MOMSN=25161434
2025-06-09T17:07:50.880Z,1749488870.880 [NAL9602](INFO): SBD MO Status=0, MOMSN=25179, MT Status=0, MTMSN=0
2025-06-09T17:07:50.880Z,1749488870.880 [NAL9602](INFO): No messages in MT queue
2025-06-09T17:07:54.109Z,1749488874.109 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T17:08:02.597Z,1749488882.597 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20250609T152832/Express0053.lzma
2025-06-09T17:08:03.599Z,1749488883.599 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Express0053.lzma.bak
2025-06-09T17:08:03.599Z,1749488883.599 [DataOverHttps](INFO): SBD MOMSN=25161437
2025-06-09T17:08:04.641Z,1749488884.641 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T17:08:04.642Z,1749488884.642 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T17:08:04.642Z,1749488884.642 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T17:08:09.059Z,1749488889.059 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T17:08:21.590Z,1749488901.590 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T17:08:24.815Z,1749488904.815 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T17:09:04.814Z,1749488944.814 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-06-09T17:11:01.162Z,1749489061.162 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T17:11:14.899Z,1749489074.899 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T17:11:28.646Z,1749489088.646 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T17:11:42.773Z,1749489102.773 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T17:11:56.516Z,1749489116.516 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T17:12:10.655Z,1749489130.655 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T17:12:24.790Z,1749489144.790 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T17:12:38.930Z,1749489158.930 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T17:12:52.267Z,1749489172.267 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T17:13:05.207Z,1749489185.207 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T17:13:05.207Z,1749489185.207 [Default:CheckIn:C.Wait] Stopped
2025-06-09T17:13:05.207Z,1749489185.207 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T17:13:05.207Z,1749489185.207 [Default:CheckIn:D] Running Loop=1
2025-06-09T17:13:05.664Z,1749489185.664 [Default:CheckIn:D] Stopped
2025-06-09T17:13:05.664Z,1749489185.664 [Default:CheckIn:E] Running Loop=1
2025-06-09T17:13:06.002Z,1749489186.002 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T17:13:06.041Z,1749489186.041 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 100.363680 min
2025-06-09T17:13:06.041Z,1749489186.041 [Default:CheckIn:E] Stopped
2025-06-09T17:13:06.041Z,1749489186.041 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T17:13:06.041Z,1749489186.041 [Default:CheckIn] Stopped
2025-06-09T17:13:06.041Z,1749489186.041 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T17:13:06.046Z,1749489186.046 [Default:CheckIn](INFO): Running loop #17
2025-06-09T17:13:06.046Z,1749489186.046 [Default:CheckIn] Running Loop=17
2025-06-09T17:13:06.046Z,1749489186.046 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T17:13:06.046Z,1749489186.046 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T17:13:08.029Z,1749489188.029 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171307.00,A,3648.14630,N,12147.22544,W,0.097,0.00,090625,,,D*71
2025-06-09T17:13:08.031Z,1749489188.031 [NAL9602](INFO): GPS fix at 20250609T171307: (36.802438, -121.787091)
2025-06-09T17:13:08.043Z,1749489188.043 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T17:13:08.043Z,1749489188.043 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T17:13:15.827Z,1749489195.827 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250609T152832/Courier0055.lzma
2025-06-09T17:13:16.828Z,1749489196.828 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Courier0055.lzma.bak
2025-06-09T17:13:16.828Z,1749489196.828 [DataOverHttps](INFO): SBD MOMSN=25161530
2025-06-09T17:13:20.141Z,1749489200.141 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T17:13:32.681Z,1749489212.681 [DataOverHttps](INFO): Sending 158 bytes from file Logs/20250609T152832/Express0056.lzma
2025-06-09T17:13:33.683Z,1749489213.683 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Express0056.lzma.bak
2025-06-09T17:13:33.684Z,1749489213.684 [DataOverHttps](INFO): SBD MOMSN=25161540
2025-06-09T17:13:33.882Z,1749489213.882 [NAL9602](INFO): SBD MO Status=2, MOMSN=25180, MT Status=2, MTMSN=0
2025-06-09T17:13:33.883Z,1749489213.883 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-09T17:13:34.719Z,1749489214.719 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T17:13:34.719Z,1749489214.719 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T17:13:34.720Z,1749489214.720 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T17:14:08.640Z,1749489248.640 [NAL9602](INFO): SBD MO Status=2, MOMSN=25180, MT Status=2, MTMSN=0
2025-06-09T17:14:08.640Z,1749489248.640 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-09T17:14:26.401Z,1749489266.401 [NAL9602](INFO): SBD MO Status=2, MOMSN=25180, MT Status=2, MTMSN=0
2025-06-09T17:14:26.401Z,1749489266.401 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-09T17:14:43.833Z,1749489283.833 [NAL9602](INFO): SBD MO Status=0, MOMSN=25180, MT Status=0, MTMSN=0
2025-06-09T17:14:43.833Z,1749489283.833 [NAL9602](INFO): No messages in MT queue
2025-06-09T17:15:05.644Z,1749489305.644 [BPC1](ERROR): Battery stick #24 (s/n: 01D3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-06-09T17:15:05.644Z,1749489305.644 [BPC1](ERROR): Battery stick #29 (s/n: 00A7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-06-09T17:15:05.645Z,1749489305.645 [BPC1](ERROR): Battery stick #51 (s/n: 01D6) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-06-09T17:15:05.646Z,1749489305.646 [BPC1](ERROR): Battery stick #57 (s/n: 019F) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-06-09T17:15:05.647Z,1749489305.647 [BPC1](ERROR): Battery stick #59 (s/n: 0184) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-06-09T17:15:05.647Z,1749489305.647 [BPC1](INFO): Calculating totals. Valid battery stick count: 41. Valid reserve battery stick count: 4.
2025-06-09T17:15:05.650Z,1749489305.650 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2025-06-09T17:15:14.531Z,1749489314.531 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T17:18:35.378Z,1749489515.378 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T17:18:35.378Z,1749489515.378 [Default:CheckIn:C.Wait] Stopped
2025-06-09T17:18:35.378Z,1749489515.378 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T17:18:35.378Z,1749489515.378 [Default:CheckIn:D] Running Loop=1
2025-06-09T17:18:35.765Z,1749489515.765 [Default:CheckIn:D] Stopped
2025-06-09T17:18:35.765Z,1749489515.765 [Default:CheckIn:E] Running Loop=1
2025-06-09T17:18:36.168Z,1749489516.168 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 105.865348 min
2025-06-09T17:18:36.168Z,1749489516.168 [Default:CheckIn:E] Stopped
2025-06-09T17:18:36.168Z,1749489516.168 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T17:18:36.168Z,1749489516.168 [Default:CheckIn] Stopped
2025-06-09T17:18:36.168Z,1749489516.168 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T17:18:36.168Z,1749489516.168 [Default:CheckIn](INFO): Running loop #18
2025-06-09T17:18:36.168Z,1749489516.168 [Default:CheckIn] Running Loop=18
2025-06-09T17:18:36.168Z,1749489516.168 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T17:18:36.168Z,1749489516.168 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T17:18:38.184Z,1749489518.184 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171837.00,A,3648.14650,N,12147.22636,W,0.039,0.00,090625,,,D*7D
2025-06-09T17:18:38.187Z,1749489518.187 [NAL9602](INFO): GPS fix at 20250609T171837: (36.802442, -121.787106)
2025-06-09T17:18:38.207Z,1749489518.207 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T17:18:38.207Z,1749489518.207 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T17:18:45.985Z,1749489525.985 [DataOverHttps](INFO): Sending 86 bytes from file Logs/20250609T152832/Courier0058.lzma
2025-06-09T17:18:46.987Z,1749489526.987 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Courier0058.lzma.bak
2025-06-09T17:18:46.988Z,1749489526.988 [DataOverHttps](INFO): SBD MOMSN=25161564
2025-06-09T17:18:48.280Z,1749489528.280 [NAL9602](INFO): SBD MO Status=0, MOMSN=25181, MT Status=0, MTMSN=0
2025-06-09T17:18:48.280Z,1749489528.280 [NAL9602](INFO): No messages in MT queue
2025-06-09T17:19:03.129Z,1749489543.129 [DataOverHttps](INFO): Sending 157 bytes from file Logs/20250609T152832/Express0059.lzma
2025-06-09T17:19:04.131Z,1749489544.131 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Express0059.lzma.bak
2025-06-09T17:19:04.132Z,1749489544.132 [DataOverHttps](INFO): SBD MOMSN=25161567
2025-06-09T17:19:05.265Z,1749489545.265 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T17:19:05.265Z,1749489545.265 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T17:19:05.265Z,1749489545.265 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T17:19:18.975Z,1749489558.975 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T17:24:05.857Z,1749489845.857 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T17:24:05.857Z,1749489845.857 [Default:CheckIn:C.Wait] Stopped
2025-06-09T17:24:05.858Z,1749489845.858 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T17:24:05.858Z,1749489845.858 [Default:CheckIn:D] Running Loop=1
2025-06-09T17:24:06.257Z,1749489846.257 [Default:CheckIn:D] Stopped
2025-06-09T17:24:06.257Z,1749489846.257 [Default:CheckIn:E] Running Loop=1
2025-06-09T17:24:06.658Z,1749489846.658 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 111.373551 min
2025-06-09T17:24:06.658Z,1749489846.658 [Default:CheckIn:E] Stopped
2025-06-09T17:24:06.658Z,1749489846.658 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T17:24:06.658Z,1749489846.658 [Default:CheckIn] Stopped
2025-06-09T17:24:06.658Z,1749489846.658 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T17:24:06.658Z,1749489846.658 [Default:CheckIn](INFO): Running loop #19
2025-06-09T17:24:06.658Z,1749489846.658 [Default:CheckIn] Running Loop=19
2025-06-09T17:24:06.658Z,1749489846.658 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T17:24:06.658Z,1749489846.658 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T17:24:08.680Z,1749489848.680 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172408.00,A,3648.14722,N,12147.22568,W,0.078,0.00,090625,,,D*77
2025-06-09T17:24:08.683Z,1749489848.683 [NAL9602](INFO): GPS fix at 20250609T172408: (36.802454, -121.787095)
2025-06-09T17:24:08.694Z,1749489848.694 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T17:24:08.694Z,1749489848.694 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T17:24:15.697Z,1749489855.697 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250609T152832/Courier0061.lzma
2025-06-09T17:24:16.700Z,1749489856.700 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Courier0061.lzma.bak
2025-06-09T17:24:16.700Z,1749489856.700 [DataOverHttps](INFO): SBD MOMSN=25161644
2025-06-09T17:24:29.676Z,1749489869.676 [NAL9602](INFO): SBD MO Status=0, MOMSN=25182, MT Status=0, MTMSN=0
2025-06-09T17:24:29.676Z,1749489869.676 [NAL9602](INFO): No messages in MT queue
2025-06-09T17:24:32.521Z,1749489872.521 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20250609T152832/Express0062.lzma
2025-06-09T17:24:33.523Z,1749489873.523 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Express0062.lzma.bak
2025-06-09T17:24:33.524Z,1749489873.524 [DataOverHttps](INFO): SBD MOMSN=25161662
2025-06-09T17:24:34.552Z,1749489874.552 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T17:24:34.552Z,1749489874.552 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T17:24:34.552Z,1749489874.552 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T17:25:00.386Z,1749489900.386 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T17:29:35.121Z,1749490175.121 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T17:29:35.122Z,1749490175.122 [Default:CheckIn:C.Wait] Stopped
2025-06-09T17:29:35.122Z,1749490175.122 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T17:29:35.122Z,1749490175.122 [Default:CheckIn:D] Running Loop=1
2025-06-09T17:29:35.518Z,1749490175.518 [Default:CheckIn:D] Stopped
2025-06-09T17:29:35.518Z,1749490175.518 [Default:CheckIn:E] Running Loop=1
2025-06-09T17:29:35.953Z,1749490175.953 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 116.861239 min
2025-06-09T17:29:35.953Z,1749490175.953 [Default:CheckIn:E] Stopped
2025-06-09T17:29:35.953Z,1749490175.953 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T17:29:35.953Z,1749490175.953 [Default:CheckIn] Stopped
2025-06-09T17:29:35.953Z,1749490175.953 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T17:29:35.953Z,1749490175.953 [Default:CheckIn](INFO): Running loop #20
2025-06-09T17:29:35.954Z,1749490175.954 [Default:CheckIn] Running Loop=20
2025-06-09T17:29:35.954Z,1749490175.954 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T17:29:35.954Z,1749490175.954 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T17:29:37.934Z,1749490177.934 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172937.00,A,3648.14738,N,12147.22752,W,0.117,0.00,090625,,,D*7E
2025-06-09T17:29:37.936Z,1749490177.936 [NAL9602](INFO): GPS fix at 20250609T172937: (36.802456, -121.787125)
2025-06-09T17:29:37.947Z,1749490177.947 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T17:29:37.947Z,1749490177.947 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T17:29:45.925Z,1749490185.925 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250609T152832/Courier0064.lzma
2025-06-09T17:29:46.927Z,1749490186.927 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Courier0064.lzma.bak
2025-06-09T17:29:46.927Z,1749490186.927 [DataOverHttps](INFO): SBD MOMSN=25161680
2025-06-09T17:30:01.368Z,1749490201.368 [NAL9602](INFO): SBD MO Status=0, MOMSN=25183, MT Status=0, MTMSN=0
2025-06-09T17:30:01.368Z,1749490201.368 [NAL9602](INFO): No messages in MT queue
2025-06-09T17:30:02.885Z,1749490202.885 [DataOverHttps](INFO): Sending 157 bytes from file Logs/20250609T152832/Express0065.lzma
2025-06-09T17:30:03.887Z,1749490203.887 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Express0065.lzma.bak
2025-06-09T17:30:03.888Z,1749490203.888 [DataOverHttps](INFO): SBD MOMSN=25161683
2025-06-09T17:30:05.012Z,1749490205.012 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T17:30:05.012Z,1749490205.012 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T17:30:05.012Z,1749490205.012 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T17:30:32.067Z,1749490232.067 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T17:35:05.585Z,1749490505.585 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T17:35:05.585Z,1749490505.585 [Default:CheckIn:C.Wait] Stopped
2025-06-09T17:35:05.585Z,1749490505.585 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T17:35:05.585Z,1749490505.585 [Default:CheckIn:D] Running Loop=1
2025-06-09T17:35:05.996Z,1749490505.996 [Default:CheckIn:D] Stopped
2025-06-09T17:35:05.996Z,1749490505.996 [Default:CheckIn:E] Running Loop=1
2025-06-09T17:35:06.395Z,1749490506.395 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 122.369214 min
2025-06-09T17:35:06.395Z,1749490506.395 [Default:CheckIn:E] Stopped
2025-06-09T17:35:06.395Z,1749490506.395 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T17:35:06.395Z,1749490506.395 [Default:CheckIn] Stopped
2025-06-09T17:35:06.395Z,1749490506.395 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T17:35:06.395Z,1749490506.395 [Default:CheckIn](INFO): Running loop #21
2025-06-09T17:35:06.395Z,1749490506.395 [Default:CheckIn] Running Loop=21
2025-06-09T17:35:06.395Z,1749490506.395 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T17:35:06.396Z,1749490506.396 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T17:35:08.404Z,1749490508.404 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173507.00,A,3648.14722,N,12147.22612,W,0.019,0.00,090625,,,D*71
2025-06-09T17:35:08.416Z,1749490508.416 [NAL9602](INFO): GPS fix at 20250609T173507: (36.802454, -121.787102)
2025-06-09T17:35:08.426Z,1749490508.426 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T17:35:08.427Z,1749490508.427 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T17:35:19.213Z,1749490519.213 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250609T152832/Courier0067.lzma
2025-06-09T17:35:20.215Z,1749490520.215 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Courier0067.lzma.bak
2025-06-09T17:35:20.216Z,1749490520.216 [DataOverHttps](INFO): SBD MOMSN=25161783
2025-06-09T17:35:24.168Z,1749490524.168 [NAL9602](INFO): SBD MO Status=0, MOMSN=25184, MT Status=0, MTMSN=0
2025-06-09T17:35:24.169Z,1749490524.169 [NAL9602](INFO): No messages in MT queue
2025-06-09T17:35:36.237Z,1749490536.237 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20250609T152832/Express0068.lzma
2025-06-09T17:35:37.239Z,1749490537.239 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Express0068.lzma.bak
2025-06-09T17:35:37.240Z,1749490537.240 [DataOverHttps](INFO): SBD MOMSN=25161786
2025-06-09T17:35:38.312Z,1749490538.312 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T17:35:38.312Z,1749490538.312 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T17:35:38.313Z,1749490538.313 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T17:35:54.863Z,1749490554.863 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T17:40:38.966Z,1749490838.966 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T17:40:38.967Z,1749490838.967 [Default:CheckIn:C.Wait] Stopped
2025-06-09T17:40:38.967Z,1749490838.967 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T17:40:38.967Z,1749490838.967 [Default:CheckIn:D] Running Loop=1
2025-06-09T17:40:39.338Z,1749490839.338 [Default:CheckIn:D] Stopped
2025-06-09T17:40:39.338Z,1749490839.338 [Default:CheckIn:E] Running Loop=1
2025-06-09T17:40:39.750Z,1749490839.750 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 127.924902 min
2025-06-09T17:40:39.750Z,1749490839.750 [Default:CheckIn:E] Stopped
2025-06-09T17:40:39.750Z,1749490839.750 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T17:40:39.750Z,1749490839.750 [Default:CheckIn] Stopped
2025-06-09T17:40:39.751Z,1749490839.751 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T17:40:39.751Z,1749490839.751 [Default:CheckIn](INFO): Running loop #22
2025-06-09T17:40:39.751Z,1749490839.751 [Default:CheckIn] Running Loop=22
2025-06-09T17:40:39.751Z,1749490839.751 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T17:40:39.751Z,1749490839.751 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T17:40:41.752Z,1749490841.752 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174041.00,A,3648.14731,N,12147.22903,W,0.117,0.00,090625,,,D*73
2025-06-09T17:40:41.754Z,1749490841.754 [NAL9602](INFO): GPS fix at 20250609T174041: (36.802455, -121.787150)
2025-06-09T17:40:41.765Z,1749490841.765 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T17:40:41.765Z,1749490841.765 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T17:40:51.225Z,1749490851.225 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250609T152832/Courier0070.lzma
2025-06-09T17:40:52.227Z,1749490852.227 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Courier0070.lzma.bak
2025-06-09T17:40:52.228Z,1749490852.228 [DataOverHttps](INFO): SBD MOMSN=25161820
2025-06-09T17:40:59.972Z,1749490859.972 [NAL9602](INFO): SBD MO Status=0, MOMSN=25185, MT Status=0, MTMSN=0
2025-06-09T17:40:59.972Z,1749490859.972 [NAL9602](INFO): No messages in MT queue
2025-06-09T17:41:09.193Z,1749490869.193 [DataOverHttps](INFO): Sending 157 bytes from file Logs/20250609T152832/Express0071.lzma
2025-06-09T17:41:10.195Z,1749490870.195 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Express0071.lzma.bak
2025-06-09T17:41:10.196Z,1749490870.196 [DataOverHttps](INFO): SBD MOMSN=25161823
2025-06-09T17:41:11.294Z,1749490871.294 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T17:41:11.294Z,1749490871.294 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T17:41:11.294Z,1749490871.294 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T17:41:30.676Z,1749490890.676 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T17:46:11.935Z,1749491171.935 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T17:46:11.935Z,1749491171.935 [Default:CheckIn:C.Wait] Stopped
2025-06-09T17:46:11.935Z,1749491171.935 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T17:46:11.935Z,1749491171.935 [Default:CheckIn:D] Running Loop=1
2025-06-09T17:46:12.346Z,1749491172.346 [Default:CheckIn:D] Stopped
2025-06-09T17:46:12.346Z,1749491172.346 [Default:CheckIn:E] Running Loop=1
2025-06-09T17:46:12.742Z,1749491172.742 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 133.475041 min
2025-06-09T17:46:12.742Z,1749491172.742 [Default:CheckIn:E] Stopped
2025-06-09T17:46:12.742Z,1749491172.742 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T17:46:12.742Z,1749491172.742 [Default:CheckIn] Stopped
2025-06-09T17:46:12.742Z,1749491172.742 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T17:46:12.742Z,1749491172.742 [Default:CheckIn](INFO): Running loop #23
2025-06-09T17:46:12.742Z,1749491172.742 [Default:CheckIn] Running Loop=23
2025-06-09T17:46:12.742Z,1749491172.742 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T17:46:12.743Z,1749491172.743 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T17:46:14.761Z,1749491174.761 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174614.00,A,3648.14633,N,12147.22640,W,0.039,0.00,090625,,,D*73
2025-06-09T17:46:14.763Z,1749491174.763 [NAL9602](INFO): GPS fix at 20250609T174614: (36.802439, -121.787107)
2025-06-09T17:46:14.774Z,1749491174.774 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T17:46:14.774Z,1749491174.774 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T17:46:21.937Z,1749491181.937 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250609T152832/Courier0073.lzma
2025-06-09T17:46:22.939Z,1749491182.939 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Courier0073.lzma.bak
2025-06-09T17:46:22.939Z,1749491182.939 [DataOverHttps](INFO): SBD MOMSN=25161898
2025-06-09T17:46:28.088Z,1749491188.088 [NAL9602](INFO): SBD MO Status=0, MOMSN=25186, MT Status=0, MTMSN=0
2025-06-09T17:46:28.088Z,1749491188.088 [NAL9602](INFO): No messages in MT queue
2025-06-09T17:46:38.889Z,1749491198.889 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20250609T152832/Express0074.lzma
2025-06-09T17:46:39.891Z,1749491199.891 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Express0074.lzma.bak
2025-06-09T17:46:39.892Z,1749491199.892 [DataOverHttps](INFO): SBD MOMSN=25161901
2025-06-09T17:46:41.021Z,1749491201.021 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T17:46:41.021Z,1749491201.021 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T17:46:41.021Z,1749491201.021 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T17:46:58.788Z,1749491218.788 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T17:51:41.653Z,1749491501.653 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T17:51:41.653Z,1749491501.653 [Default:CheckIn:C.Wait] Stopped
2025-06-09T17:51:41.653Z,1749491501.653 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T17:51:41.654Z,1749491501.654 [Default:CheckIn:D] Running Loop=1
2025-06-09T17:51:42.060Z,1749491502.060 [Default:CheckIn:D] Stopped
2025-06-09T17:51:42.060Z,1749491502.060 [Default:CheckIn:E] Running Loop=1
2025-06-09T17:51:42.468Z,1749491502.468 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 138.970280 min
2025-06-09T17:51:42.468Z,1749491502.468 [Default:CheckIn:E] Stopped
2025-06-09T17:51:42.469Z,1749491502.469 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T17:51:42.469Z,1749491502.469 [Default:CheckIn] Stopped
2025-06-09T17:51:42.469Z,1749491502.469 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T17:51:42.469Z,1749491502.469 [Default:CheckIn](INFO): Running loop #24
2025-06-09T17:51:42.469Z,1749491502.469 [Default:CheckIn] Running Loop=24
2025-06-09T17:51:42.469Z,1749491502.469 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T17:51:42.469Z,1749491502.469 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T17:51:44.480Z,1749491504.480 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175143.00,A,3648.15259,N,12147.22313,W,0.194,15.92,090625,,,D*44
2025-06-09T17:51:44.483Z,1749491504.483 [NAL9602](INFO): GPS fix at 20250609T175143: (36.802543, -121.787052)
2025-06-09T17:51:44.514Z,1749491504.514 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T17:51:44.515Z,1749491504.515 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T17:51:52.365Z,1749491512.365 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250609T152832/Courier0076.lzma
2025-06-09T17:51:53.367Z,1749491513.367 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Courier0076.lzma.bak
2025-06-09T17:51:53.368Z,1749491513.368 [DataOverHttps](INFO): SBD MOMSN=25161919
2025-06-09T17:52:09.125Z,1749491529.125 [NAL9602](INFO): SBD MO Status=2, MOMSN=25187, MT Status=2, MTMSN=0
2025-06-09T17:52:09.125Z,1749491529.125 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-09T17:52:09.182Z,1749491529.182 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20250609T152832/Express0077.lzma
2025-06-09T17:52:10.183Z,1749491530.183 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Express0077.lzma.bak
2025-06-09T17:52:10.184Z,1749491530.184 [DataOverHttps](INFO): SBD MOMSN=25161922
2025-06-09T17:52:11.549Z,1749491531.549 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T17:52:11.549Z,1749491531.549 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T17:52:11.549Z,1749491531.549 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T17:52:24.469Z,1749491544.469 [NAL9602](INFO): SBD MO Status=2, MOMSN=25187, MT Status=2, MTMSN=0
2025-06-09T17:52:24.469Z,1749491544.469 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-09T17:52:55.616Z,1749491575.616 [NAL9602](INFO): SBD MO Status=0, MOMSN=25187, MT Status=0, MTMSN=0
2025-06-09T17:52:55.616Z,1749491575.616 [NAL9602](INFO): No messages in MT queue
2025-06-09T17:53:26.315Z,1749491606.315 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T17:57:12.264Z,1749491832.264 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T17:57:12.264Z,1749491832.264 [Default:CheckIn:C.Wait] Stopped
2025-06-09T17:57:12.264Z,1749491832.264 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T17:57:12.265Z,1749491832.265 [Default:CheckIn:D] Running Loop=1
2025-06-09T17:57:12.669Z,1749491832.669 [Default:CheckIn:D] Stopped
2025-06-09T17:57:12.669Z,1749491832.669 [Default:CheckIn:E] Running Loop=1
2025-06-09T17:57:13.072Z,1749491833.072 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 144.480420 min
2025-06-09T17:57:13.072Z,1749491833.072 [Default:CheckIn:E] Stopped
2025-06-09T17:57:13.072Z,1749491833.072 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T17:57:13.072Z,1749491833.072 [Default:CheckIn] Stopped
2025-06-09T17:57:13.072Z,1749491833.072 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T17:57:13.072Z,1749491833.072 [Default:CheckIn](INFO): Running loop #25
2025-06-09T17:57:13.073Z,1749491833.073 [Default:CheckIn] Running Loop=25
2025-06-09T17:57:13.073Z,1749491833.073 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T17:57:13.073Z,1749491833.073 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T17:57:15.089Z,1749491835.089 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175714.00,A,3648.15332,N,12147.22163,W,0.136,95.54,090625,,,D*43
2025-06-09T17:57:15.091Z,1749491835.091 [NAL9602](INFO): GPS fix at 20250609T175714: (36.802555, -121.787027)
2025-06-09T17:57:15.102Z,1749491835.102 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T17:57:15.102Z,1749491835.102 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T17:57:22.313Z,1749491842.313 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250609T152832/Courier0079.lzma
2025-06-09T17:57:23.315Z,1749491843.315 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Courier0079.lzma.bak
2025-06-09T17:57:23.316Z,1749491843.316 [DataOverHttps](INFO): SBD MOMSN=25162011
2025-06-09T17:57:32.865Z,1749491852.865 [NAL9602](INFO): SBD MO Status=2, MOMSN=25188, MT Status=2, MTMSN=0
2025-06-09T17:57:32.865Z,1749491852.865 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-09T17:57:39.249Z,1749491859.249 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20250609T152832/Express0080.lzma
2025-06-09T17:57:40.251Z,1749491860.251 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Express0080.lzma.bak
2025-06-09T17:57:40.252Z,1749491860.252 [DataOverHttps](INFO): SBD MOMSN=25162014
2025-06-09T17:57:41.350Z,1749491861.350 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T17:57:41.350Z,1749491861.350 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T17:57:41.350Z,1749491861.350 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T17:57:55.490Z,1749491875.490 [NAL9602](INFO): SBD MO Status=2, MOMSN=25188, MT Status=2, MTMSN=0
2025-06-09T17:57:55.490Z,1749491875.490 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-09T17:58:05.193Z,1749491885.193 [NAL9602](INFO): SBD MO Status=0, MOMSN=25188, MT Status=0, MTMSN=0
2025-06-09T17:58:05.193Z,1749491885.193 [NAL9602](INFO): No messages in MT queue
2025-06-09T17:58:35.882Z,1749491915.882 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T18:02:42.014Z,1749492162.014 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T18:02:42.014Z,1749492162.014 [Default:CheckIn:C.Wait] Stopped
2025-06-09T18:02:42.014Z,1749492162.014 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T18:02:42.014Z,1749492162.014 [Default:CheckIn:D] Running Loop=1
2025-06-09T18:02:42.431Z,1749492162.431 [Default:CheckIn:D] Stopped
2025-06-09T18:02:42.431Z,1749492162.431 [Default:CheckIn:E] Running Loop=1
2025-06-09T18:02:42.846Z,1749492162.846 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 149.976449 min
2025-06-09T18:02:42.846Z,1749492162.846 [Default:CheckIn:E] Stopped
2025-06-09T18:02:42.847Z,1749492162.847 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T18:02:42.847Z,1749492162.847 [Default:CheckIn] Stopped
2025-06-09T18:02:42.847Z,1749492162.847 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T18:02:42.847Z,1749492162.847 [Default:CheckIn](INFO): Running loop #26
2025-06-09T18:02:42.847Z,1749492162.847 [Default:CheckIn] Running Loop=26
2025-06-09T18:02:42.847Z,1749492162.847 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T18:02:42.847Z,1749492162.847 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T18:02:44.898Z,1749492164.898 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180244.00,A,3648.14978,N,12147.21809,W,0.019,95.54,090625,,,D*46
2025-06-09T18:02:44.900Z,1749492164.900 [NAL9602](INFO): GPS fix at 20250609T180244: (36.802496, -121.786968)
2025-06-09T18:02:44.953Z,1749492164.953 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T18:02:44.977Z,1749492164.977 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T18:02:55.341Z,1749492175.341 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250609T152832/Courier0082.lzma
2025-06-09T18:02:56.344Z,1749492176.344 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Courier0082.lzma.bak
2025-06-09T18:02:56.344Z,1749492176.344 [DataOverHttps](INFO): SBD MOMSN=25162032
2025-06-09T18:03:13.229Z,1749492193.229 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20250609T152832/Express0083.lzma
2025-06-09T18:03:14.231Z,1749492194.231 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Express0083.lzma.bak
2025-06-09T18:03:14.232Z,1749492194.232 [DataOverHttps](INFO): SBD MOMSN=25162045
2025-06-09T18:03:14.378Z,1749492194.378 [NAL9602](INFO): SBD MO Status=2, MOMSN=25189, MT Status=2, MTMSN=0
2025-06-09T18:03:14.378Z,1749492194.378 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-09T18:03:15.598Z,1749492195.598 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T18:03:15.598Z,1749492195.598 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T18:03:15.598Z,1749492195.598 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T18:03:46.696Z,1749492226.696 [NAL9602](INFO): SBD MO Status=2, MOMSN=25189, MT Status=2, MTMSN=0
2025-06-09T18:03:46.696Z,1749492226.696 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-09T18:04:10.540Z,1749492250.540 [NAL9602](INFO): SBD MO Status=2, MOMSN=25189, MT Status=2, MTMSN=0
2025-06-09T18:04:10.540Z,1749492250.540 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-09T18:04:41.238Z,1749492281.238 [NAL9602](INFO): SBD MO Status=0, MOMSN=25189, MT Status=0, MTMSN=0
2025-06-09T18:04:41.238Z,1749492281.238 [NAL9602](INFO): No messages in MT queue
2025-06-09T18:05:12.003Z,1749492312.003 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T18:08:16.244Z,1749492496.244 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T18:08:16.245Z,1749492496.245 [Default:CheckIn:C.Wait] Stopped
2025-06-09T18:08:16.245Z,1749492496.245 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T18:08:16.245Z,1749492496.245 [Default:CheckIn:D] Running Loop=1
2025-06-09T18:08:16.643Z,1749492496.643 [Default:CheckIn:D] Stopped
2025-06-09T18:08:16.643Z,1749492496.643 [Default:CheckIn:E] Running Loop=1
2025-06-09T18:08:17.050Z,1749492497.050 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 155.546663 min
2025-06-09T18:08:17.050Z,1749492497.050 [Default:CheckIn:E] Stopped
2025-06-09T18:08:17.050Z,1749492497.050 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T18:08:17.050Z,1749492497.050 [Default:CheckIn] Stopped
2025-06-09T18:08:17.050Z,1749492497.050 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T18:08:17.051Z,1749492497.051 [Default:CheckIn](INFO): Running loop #27
2025-06-09T18:08:17.051Z,1749492497.051 [Default:CheckIn] Running Loop=27
2025-06-09T18:08:17.051Z,1749492497.051 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T18:08:17.051Z,1749492497.051 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T18:08:19.064Z,1749492499.064 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180818.00,A,3648.15009,N,12147.21610,W,0.058,95.54,090625,,,D*48
2025-06-09T18:08:19.067Z,1749492499.067 [NAL9602](INFO): GPS fix at 20250609T180818: (36.802501, -121.786935)
2025-06-09T18:08:19.095Z,1749492499.095 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T18:08:19.095Z,1749492499.095 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T18:08:26.841Z,1749492506.841 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250609T152832/Courier0085.lzma
2025-06-09T18:08:27.843Z,1749492507.843 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Courier0085.lzma.bak
2025-06-09T18:08:27.844Z,1749492507.844 [DataOverHttps](INFO): SBD MOMSN=25162128
2025-06-09T18:08:43.709Z,1749492523.709 [NAL9602](INFO): SBD MO Status=0, MOMSN=25190, MT Status=0, MTMSN=0
2025-06-09T18:08:43.709Z,1749492523.709 [NAL9602](INFO): No messages in MT queue
2025-06-09T18:08:44.345Z,1749492524.345 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20250609T152832/Express0086.lzma
2025-06-09T18:08:45.348Z,1749492525.348 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Express0086.lzma.bak
2025-06-09T18:08:45.348Z,1749492525.348 [DataOverHttps](INFO): SBD MOMSN=25162131
2025-06-09T18:08:46.561Z,1749492526.561 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T18:08:46.561Z,1749492526.561 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T18:08:46.561Z,1749492526.561 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T18:09:14.459Z,1749492554.459 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T18:13:47.294Z,1749492827.294 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T18:13:47.294Z,1749492827.294 [Default:CheckIn:C.Wait] Stopped
2025-06-09T18:13:47.294Z,1749492827.294 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T18:13:47.294Z,1749492827.294 [Default:CheckIn:D] Running Loop=1
2025-06-09T18:13:47.708Z,1749492827.708 [Default:CheckIn:D] Stopped
2025-06-09T18:13:47.708Z,1749492827.708 [Default:CheckIn:E] Running Loop=1
2025-06-09T18:13:48.101Z,1749492828.101 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 161.064404 min
2025-06-09T18:13:48.101Z,1749492828.101 [Default:CheckIn:E] Stopped
2025-06-09T18:13:48.101Z,1749492828.101 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T18:13:48.101Z,1749492828.101 [Default:CheckIn] Stopped
2025-06-09T18:13:48.101Z,1749492828.101 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T18:13:48.102Z,1749492828.102 [Default:CheckIn](INFO): Running loop #28
2025-06-09T18:13:48.102Z,1749492828.102 [Default:CheckIn] Running Loop=28
2025-06-09T18:13:48.102Z,1749492828.102 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T18:13:48.102Z,1749492828.102 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T18:13:50.120Z,1749492830.120 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181349.00,A,3648.20014,N,12147.15058,W,3.207,17.20,090625,,,D*43
2025-06-09T18:13:50.122Z,1749492830.122 [NAL9602](INFO): GPS fix at 20250609T181349: (36.803336, -121.785843)
2025-06-09T18:13:50.136Z,1749492830.136 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T18:13:50.137Z,1749492830.137 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T18:13:57.496Z,1749492837.496 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250609T152832/Courier0088.lzma
2025-06-09T18:13:58.499Z,1749492838.499 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Courier0088.lzma.bak
2025-06-09T18:13:58.499Z,1749492838.499 [DataOverHttps](INFO): SBD MOMSN=25162206
2025-06-09T18:14:04.687Z,1749492844.687 [NAL9602](INFO): SBD MO Status=0, MOMSN=25191, MT Status=0, MTMSN=0
2025-06-09T18:14:04.687Z,1749492844.687 [NAL9602](INFO): No messages in MT queue
2025-06-09T18:14:14.353Z,1749492854.353 [DataOverHttps](INFO): Sending 179 bytes from file Logs/20250609T152832/Express0089.lzma
2025-06-09T18:14:15.355Z,1749492855.355 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Express0089.lzma.bak
2025-06-09T18:14:15.355Z,1749492855.355 [DataOverHttps](INFO): SBD MOMSN=25162209
2025-06-09T18:14:16.407Z,1749492856.407 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T18:14:16.407Z,1749492856.407 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T18:14:16.407Z,1749492856.407 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T18:14:35.382Z,1749492875.382 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T18:16:56.468Z,1749493016.468 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7).
2025-06-09T18:17:03.336Z,1749493023.336 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:17:17.075Z,1749493037.075 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:17:31.219Z,1749493051.219 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:17:44.948Z,1749493064.948 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:17:58.685Z,1749493078.685 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:18:12.825Z,1749493092.825 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:18:26.964Z,1749493106.964 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:18:40.298Z,1749493120.298 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:18:54.032Z,1749493134.032 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:19:07.769Z,1749493147.769 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:19:17.083Z,1749493157.083 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T18:19:17.083Z,1749493157.083 [Default:CheckIn:C.Wait] Stopped
2025-06-09T18:19:17.083Z,1749493157.083 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T18:19:17.083Z,1749493157.083 [Default:CheckIn:D] Running Loop=1
2025-06-09T18:19:17.499Z,1749493157.499 [Default:CheckIn:D] Stopped
2025-06-09T18:19:17.499Z,1749493157.499 [Default:CheckIn:E] Running Loop=1
2025-06-09T18:19:17.882Z,1749493157.882 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 166.560921 min
2025-06-09T18:19:17.882Z,1749493157.882 [Default:CheckIn:E] Stopped
2025-06-09T18:19:17.882Z,1749493157.882 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T18:19:17.882Z,1749493157.882 [Default:CheckIn] Stopped
2025-06-09T18:19:17.882Z,1749493157.882 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T18:19:17.882Z,1749493157.882 [Default:CheckIn](INFO): Running loop #29
2025-06-09T18:19:17.882Z,1749493157.882 [Default:CheckIn] Running Loop=29
2025-06-09T18:19:17.882Z,1749493157.882 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T18:19:17.882Z,1749493157.882 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T18:19:19.896Z,1749493159.896 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181919.00,A,3648.52762,N,12147.14660,W,5.326,297.43,090625,,,D*78
2025-06-09T18:19:19.898Z,1749493159.898 [NAL9602](INFO): GPS fix at 20250609T181919: (36.808794, -121.785777)
2025-06-09T18:19:19.909Z,1749493159.909 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T18:19:19.909Z,1749493159.909 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T18:19:27.292Z,1749493167.292 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250609T152832/Courier0091.lzma
2025-06-09T18:19:28.295Z,1749493168.295 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Courier0091.lzma.bak
2025-06-09T18:19:28.295Z,1749493168.295 [DataOverHttps](INFO): SBD MOMSN=25162245
2025-06-09T18:19:42.115Z,1749493182.115 [NAL9602](INFO): SBD MO Status=2, MOMSN=25192, MT Status=2, MTMSN=0
2025-06-09T18:19:42.116Z,1749493182.116 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-09T18:19:44.201Z,1749493184.201 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20250609T152832/Express0092.lzma
2025-06-09T18:19:45.204Z,1749493185.204 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Express0092.lzma.bak
2025-06-09T18:19:45.205Z,1749493185.205 [DataOverHttps](INFO): SBD MOMSN=25162248
2025-06-09T18:19:46.249Z,1749493186.249 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T18:19:46.249Z,1749493186.249 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T18:19:46.249Z,1749493186.249 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T18:19:52.624Z,1749493192.624 [NAL9602](INFO): SBD MO Status=0, MOMSN=25192, MT Status=0, MTMSN=0
2025-06-09T18:19:52.624Z,1749493192.624 [NAL9602](INFO): No messages in MT queue
2025-06-09T18:20:23.319Z,1749493223.319 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T18:21:42.537Z,1749493302.537 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:21:55.061Z,1749493315.061 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:22:07.587Z,1749493327.587 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:22:20.916Z,1749493340.916 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:22:33.443Z,1749493353.443 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:22:46.773Z,1749493366.773 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:22:59.298Z,1749493379.298 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:23:11.822Z,1749493391.822 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:23:25.152Z,1749493405.152 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:23:38.081Z,1749493418.081 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:23:50.606Z,1749493430.606 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:24:27.779Z,1749493467.779 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-06-09T18:24:46.784Z,1749493486.784 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T18:24:46.784Z,1749493486.784 [Default:CheckIn:C.Wait] Stopped
2025-06-09T18:24:46.785Z,1749493486.785 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T18:24:46.785Z,1749493486.785 [Default:CheckIn:D] Running Loop=1
2025-06-09T18:24:47.183Z,1749493487.183 [Default:CheckIn:D] Stopped
2025-06-09T18:24:47.183Z,1749493487.183 [Default:CheckIn:E] Running Loop=1
2025-06-09T18:24:47.594Z,1749493487.594 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 172.055664 min
2025-06-09T18:24:47.594Z,1749493487.594 [Default:CheckIn:E] Stopped
2025-06-09T18:24:47.594Z,1749493487.594 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T18:24:47.594Z,1749493487.594 [Default:CheckIn] Stopped
2025-06-09T18:24:47.594Z,1749493487.594 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T18:24:47.594Z,1749493487.594 [Default:CheckIn](INFO): Running loop #30
2025-06-09T18:24:47.594Z,1749493487.594 [Default:CheckIn] Running Loop=30
2025-06-09T18:24:47.594Z,1749493487.594 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T18:24:47.595Z,1749493487.595 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T18:24:49.604Z,1749493489.604 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182448.00,A,3648.22531,N,12148.29735,W,14.909,245.26,090625,,,D*4A
2025-06-09T18:24:49.606Z,1749493489.606 [NAL9602](INFO): GPS fix at 20250609T182448: (36.803755, -121.804956)
2025-06-09T18:24:49.634Z,1749493489.634 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T18:24:49.634Z,1749493489.634 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T18:24:57.076Z,1749493497.076 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250609T152832/Courier0094.lzma
2025-06-09T18:24:58.079Z,1749493498.079 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Courier0094.lzma.bak
2025-06-09T18:24:58.079Z,1749493498.079 [DataOverHttps](INFO): SBD MOMSN=25162339
2025-06-09T18:25:11.016Z,1749493511.016 [NAL9602](INFO): SBD MO Status=0, MOMSN=25193, MT Status=0, MTMSN=0
2025-06-09T18:25:11.016Z,1749493511.016 [NAL9602](INFO): No messages in MT queue
2025-06-09T18:25:14.265Z,1749493514.265 [DataOverHttps](INFO): Sending 175 bytes from file Logs/20250609T152832/Express0095.lzma
2025-06-09T18:25:15.271Z,1749493515.271 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Express0095.lzma.bak
2025-06-09T18:25:15.271Z,1749493515.271 [DataOverHttps](INFO): SBD MOMSN=25162343
2025-06-09T18:25:16.307Z,1749493516.307 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T18:25:16.307Z,1749493516.307 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T18:25:16.307Z,1749493516.307 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T18:25:41.734Z,1749493541.734 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T18:26:25.757Z,1749493585.757 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:26:39.885Z,1749493599.885 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:26:53.626Z,1749493613.626 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:27:06.953Z,1749493626.953 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:27:21.093Z,1749493641.093 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:27:35.637Z,1749493655.637 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:27:49.776Z,1749493669.776 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:28:03.918Z,1749493683.918 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:28:18.057Z,1749493698.057 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:28:32.197Z,1749493712.197 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:30:16.847Z,1749493816.847 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T18:30:16.847Z,1749493816.847 [Default:CheckIn:C.Wait] Stopped
2025-06-09T18:30:16.847Z,1749493816.847 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T18:30:16.847Z,1749493816.847 [Default:CheckIn:D] Running Loop=1
2025-06-09T18:30:17.266Z,1749493817.266 [Default:CheckIn:D] Stopped
2025-06-09T18:30:17.266Z,1749493817.266 [Default:CheckIn:E] Running Loop=1
2025-06-09T18:30:17.678Z,1749493817.678 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 177.557048 min
2025-06-09T18:30:17.678Z,1749493817.678 [Default:CheckIn:E] Stopped
2025-06-09T18:30:17.678Z,1749493817.678 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T18:30:17.679Z,1749493817.679 [Default:CheckIn] Stopped
2025-06-09T18:30:17.679Z,1749493817.679 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T18:30:17.679Z,1749493817.679 [Default:CheckIn](INFO): Running loop #31
2025-06-09T18:30:17.679Z,1749493817.679 [Default:CheckIn] Running Loop=31
2025-06-09T18:30:17.679Z,1749493817.679 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T18:30:17.679Z,1749493817.679 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T18:30:19.686Z,1749493819.686 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183018.00,A,3647.85976,N,12150.04799,W,14.384,252.08,090625,,,D*42
2025-06-09T18:30:19.688Z,1749493819.688 [NAL9602](INFO): GPS fix at 20250609T183018: (36.797663, -121.834133)
2025-06-09T18:30:19.699Z,1749493819.699 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T18:30:19.699Z,1749493819.699 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T18:30:27.288Z,1749493827.288 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250609T152832/Courier0097.lzma
2025-06-09T18:30:28.291Z,1749493828.291 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Courier0097.lzma.bak
2025-06-09T18:30:28.291Z,1749493828.291 [DataOverHttps](INFO): SBD MOMSN=25162372
2025-06-09T18:30:37.046Z,1749493837.046 [NAL9602](INFO): SBD MO Status=0, MOMSN=25194, MT Status=0, MTMSN=0
2025-06-09T18:30:37.046Z,1749493837.046 [NAL9602](INFO): No messages in MT queue
2025-06-09T18:30:55.305Z,1749493855.305 [DataOverHttps](INFO): Sending 180 bytes from file Logs/20250609T152832/Express0098.lzma
2025-06-09T18:30:56.307Z,1749493856.307 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Express0098.lzma.bak
2025-06-09T18:30:56.307Z,1749493856.307 [DataOverHttps](INFO): SBD MOMSN=25162376
2025-06-09T18:30:57.703Z,1749493857.703 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T18:30:57.703Z,1749493857.703 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T18:30:57.703Z,1749493857.703 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T18:31:07.795Z,1749493867.795 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T18:31:08.187Z,1749493868.187 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:31:23.134Z,1749493883.134 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:31:38.487Z,1749493898.487 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:31:53.433Z,1749493913.433 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:32:08.787Z,1749493928.787 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:32:23.734Z,1749493943.734 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:32:39.085Z,1749493959.085 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:32:54.842Z,1749493974.842 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:33:10.193Z,1749493990.193 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:35:55.037Z,1749494155.037 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:35:58.281Z,1749494158.281 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T18:35:58.281Z,1749494158.281 [Default:CheckIn:C.Wait] Stopped
2025-06-09T18:35:58.281Z,1749494158.281 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T18:35:58.281Z,1749494158.281 [Default:CheckIn:D] Running Loop=1
2025-06-09T18:35:58.687Z,1749494158.687 [Default:CheckIn:D] Stopped
2025-06-09T18:35:58.688Z,1749494158.688 [Default:CheckIn:E] Running Loop=1
2025-06-09T18:35:59.100Z,1749494159.100 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 183.247396 min
2025-06-09T18:35:59.100Z,1749494159.100 [Default:CheckIn:E] Stopped
2025-06-09T18:35:59.100Z,1749494159.100 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T18:35:59.100Z,1749494159.100 [Default:CheckIn] Stopped
2025-06-09T18:35:59.100Z,1749494159.100 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T18:35:59.100Z,1749494159.100 [Default:CheckIn](INFO): Running loop #32
2025-06-09T18:35:59.100Z,1749494159.100 [Default:CheckIn] Running Loop=32
2025-06-09T18:35:59.100Z,1749494159.100 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T18:35:59.100Z,1749494159.100 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T18:36:01.104Z,1749494161.104 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183600.00,A,3647.69336,N,12150.92229,W,1.477,96.30,090625,,,D*4E
2025-06-09T18:36:01.106Z,1749494161.106 [NAL9602](INFO): GPS fix at 20250609T183600: (36.794889, -121.848705)
2025-06-09T18:36:01.163Z,1749494161.163 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T18:36:01.163Z,1749494161.163 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T18:36:08.881Z,1749494168.881 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250609T152832/Courier0100.lzma
2025-06-09T18:36:09.349Z,1749494169.349 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:36:09.868Z,1749494169.868 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Courier0100.lzma.bak
2025-06-09T18:36:09.868Z,1749494169.868 [DataOverHttps](INFO): SBD MOMSN=25162449
2025-06-09T18:36:14.607Z,1749494174.607 [NAL9602](INFO): SBD MO Status=0, MOMSN=25195, MT Status=0, MTMSN=0
2025-06-09T18:36:14.608Z,1749494174.608 [NAL9602](INFO): No messages in MT queue
2025-06-09T18:36:23.084Z,1749494183.084 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:36:27.913Z,1749494187.913 [DataOverHttps](INFO): Sending 179 bytes from file Logs/20250609T152832/Express0101.lzma
2025-06-09T18:36:28.915Z,1749494188.915 [DataOverHttps](INFO): Moved sent file to Logs/20250609T152832/Express0101.lzma.bak
2025-06-09T18:36:28.915Z,1749494188.915 [DataOverHttps](INFO): SBD MOMSN=25162452
2025-06-09T18:36:29.968Z,1749494189.968 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T18:36:29.968Z,1749494189.968 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T18:36:29.968Z,1749494189.968 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T18:36:36.417Z,1749494196.417 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:36:45.307Z,1749494205.307 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T18:36:50.159Z,1749494210.159 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:37:04.318Z,1749494224.318 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:37:18.458Z,1749494238.458 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:37:32.194Z,1749494252.194 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:37:45.929Z,1749494265.929 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:37:59.260Z,1749494279.260 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-06-09T18:40:12.386Z,1749494412.386 [DataOverHttps](IMPORTANT): SBD MTMSN=20250609T184011
2025-06-09T18:40:19.861Z,1749494419.861 [DataOverHttps](INFO): Received command: restart logs