2025-06-09T15:14:19.734Z,1749482059.734 [Supervisor](DEBUG): Initializing supervisor.
2025-06-09T15:14:19.738Z,1749482059.738 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-06-09T15:14:19.739Z,1749482059.739 [SyncHandler](INFO): Protected caller Thread ID is 836
2025-06-09T15:14:19.739Z,1749482059.739 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-06-09T15:14:19.740Z,1749482059.740 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-06-09T15:14:19.741Z,1749482059.741 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 837
2025-06-09T15:14:19.745Z,1749482059.745 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-06-09T15:14:19.765Z,1749482059.765 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-06-09T15:14:19.766Z,1749482059.766 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-06-09T15:14:19.766Z,1749482059.766 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 838
2025-06-09T15:14:19.771Z,1749482059.771 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-06-09T15:14:19.772Z,1749482059.772 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-06-09T15:14:19.772Z,1749482059.772 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 839
2025-06-09T15:14:19.774Z,1749482059.774 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-06-09T15:14:19.775Z,1749482059.775 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-06-09T15:14:19.776Z,1749482059.776 [logger ThreadHandler](INFO): Protected caller Thread ID is 840
2025-06-09T15:14:19.780Z,1749482059.780 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-06-09T15:14:19.780Z,1749482059.780 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-06-09T15:14:19.784Z,1749482059.784 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-06-09T15:14:20.086Z,1749482060.086 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-06-09T15:14:20.088Z,1749482060.088 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-06-09T15:14:20.190Z,1749482060.190 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance
2025-06-09T15:14:20.191Z,1749482060.191 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-06-09T15:14:20.753Z,1749482060.753 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-06-09T15:14:20.755Z,1749482060.755 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-06-09T15:14:21.143Z,1749482061.143 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-06-09T15:14:21.145Z,1749482061.145 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-06-09T15:14:21.254Z,1749482061.254 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-06-09T15:14:21.256Z,1749482061.256 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-06-09T15:14:21.708Z,1749482061.708 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-06-09T15:14:21.709Z,1749482061.709 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-06-09T15:14:21.959Z,1749482061.959 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-06-09T15:14:21.961Z,1749482061.961 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-06-09T15:14:22.965Z,1749482062.965 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-06-09T15:14:23.010Z,1749482063.010 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-06-09T15:14:23.243Z,1749482063.243 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-06-09T15:14:23.245Z,1749482063.245 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-06-09T15:14:23.338Z,1749482063.338 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-06-09T15:14:24.270Z,1749482064.270 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-06-09T15:14:24.272Z,1749482064.272 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-06-09T15:14:24.777Z,1749482064.777 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-06-09T15:14:24.777Z,1749482064.777 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-06-09T15:14:24.984Z,1749482064.984 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-06-09T15:14:24.986Z,1749482064.986 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-06-09T15:14:25.111Z,1749482065.111 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-06-09T15:14:25.112Z,1749482065.112 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-06-09T15:14:25.360Z,1749482065.360 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-06-09T15:14:25.361Z,1749482065.361 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-06-09T15:14:25.641Z,1749482065.641 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-06-09T15:14:25.642Z,1749482065.642 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2025-06-09T15:14:25.646Z,1749482065.646 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2025-06-09T15:14:25.744Z,1749482065.744 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2025-06-09T15:14:25.860Z,1749482065.860 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2025-06-09T15:14:25.955Z,1749482065.955 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2025-06-09T15:14:26.085Z,1749482066.085 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2025-06-09T15:14:26.191Z,1749482066.191 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2025-06-09T15:14:26.322Z,1749482066.322 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2025-06-09T15:14:26.495Z,1749482066.495 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2025-06-09T15:14:26.662Z,1749482066.662 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2025-06-09T15:14:26.789Z,1749482066.789 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2025-06-09T15:14:27.084Z,1749482067.084 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-06-09T15:14:27.086Z,1749482067.086 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2025-06-09T15:14:27.086Z,1749482067.086 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-06-09T15:14:27.099Z,1749482067.099 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-06-09T15:14:27.178Z,1749482067.178 [VerticalControl](DEBUG): Construct VerticalControl.
2025-06-09T15:14:27.244Z,1749482067.244 [VerticalControl] Loaded
2025-06-09T15:14:27.244Z,1749482067.244 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-06-09T15:14:27.247Z,1749482067.247 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-06-09T15:14:27.297Z,1749482067.297 [HorizontalControl] Loaded
2025-06-09T15:14:27.297Z,1749482067.297 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-06-09T15:14:27.300Z,1749482067.300 [SpeedControl](DEBUG): Construct SpeedControl.
2025-06-09T15:14:27.303Z,1749482067.303 [SpeedControl] Loaded
2025-06-09T15:14:27.303Z,1749482067.303 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-06-09T15:14:27.306Z,1749482067.306 [LoopControl](DEBUG): Construct LoopControl.
2025-06-09T15:14:27.307Z,1749482067.307 [LoopControl] Loaded
2025-06-09T15:14:27.307Z,1749482067.307 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-06-09T15:14:27.308Z,1749482067.308 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-06-09T15:14:27.310Z,1749482067.310 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-06-09T15:14:27.326Z,1749482067.326 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-06-09T15:14:27.327Z,1749482067.327 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-06-09T15:14:27.570Z,1749482067.570 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-06-09T15:14:27.571Z,1749482067.571 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-06-09T15:14:27.776Z,1749482067.776 [BuoyancyServo] Loaded
2025-06-09T15:14:27.776Z,1749482067.776 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-06-09T15:14:27.801Z,1749482067.801 [ElevatorServo] Loaded
2025-06-09T15:14:27.801Z,1749482067.801 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-06-09T15:14:27.824Z,1749482067.824 [MassServo] Loaded
2025-06-09T15:14:27.824Z,1749482067.824 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-06-09T15:14:27.846Z,1749482067.846 [RudderServo] Loaded
2025-06-09T15:14:27.846Z,1749482067.846 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-06-09T15:14:27.864Z,1749482067.864 [ThrusterHE] Loaded
2025-06-09T15:14:27.864Z,1749482067.864 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-06-09T15:14:27.865Z,1749482067.865 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-06-09T15:14:27.866Z,1749482067.866 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-06-09T15:14:28.016Z,1749482068.016 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-06-09T15:14:28.017Z,1749482068.017 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-06-09T15:14:28.032Z,1749482068.032 [NavChart] Loaded
2025-06-09T15:14:28.032Z,1749482068.032 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-06-09T15:14:28.038Z,1749482068.038 [UniversalFixResidualReporter] Loaded
2025-06-09T15:14:28.038Z,1749482068.038 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-06-09T15:14:28.039Z,1749482068.039 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-06-09T15:14:28.039Z,1749482068.039 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-06-09T15:14:28.408Z,1749482068.408 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-06-09T15:14:28.409Z,1749482068.409 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-06-09T15:14:29.775Z,1749482069.775 [AHRS_M2] Loaded
2025-06-09T15:14:29.776Z,1749482069.776 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-06-09T15:14:30.669Z,1749482070.669 [BPC1] Loaded
2025-06-09T15:14:30.670Z,1749482070.670 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-06-09T15:14:30.776Z,1749482070.776 [DAT] Loaded
2025-06-09T15:14:30.777Z,1749482070.777 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-06-09T15:14:30.778Z,1749482070.778 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4094B4E0
2025-06-09T15:14:30.778Z,1749482070.778 [DAT ThreadHandler](INFO): Protected caller Thread ID is 920
2025-06-09T15:14:30.922Z,1749482070.922 [DataOverHttps] Loaded
2025-06-09T15:14:30.922Z,1749482070.922 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-06-09T15:14:30.923Z,1749482070.923 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4097B4E0
2025-06-09T15:14:30.924Z,1749482070.924 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 921
2025-06-09T15:14:30.948Z,1749482070.948 [Depth_Keller] Loaded
2025-06-09T15:14:30.949Z,1749482070.949 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-06-09T15:14:30.954Z,1749482070.954 [DropWeight] Loaded
2025-06-09T15:14:30.954Z,1749482070.954 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-06-09T15:14:31.023Z,1749482071.023 [NAL9602] Loaded
2025-06-09T15:14:31.024Z,1749482071.024 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-06-09T15:14:31.056Z,1749482071.056 [Onboard] Loaded
2025-06-09T15:14:31.056Z,1749482071.056 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-06-09T15:14:31.057Z,1749482071.057 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409AB4E0
2025-06-09T15:14:31.057Z,1749482071.057 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 922
2025-06-09T15:14:31.073Z,1749482071.073 [Power24vConverter] Loaded
2025-06-09T15:14:31.073Z,1749482071.073 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-06-09T15:14:31.090Z,1749482071.090 [Radio_Surface] Loaded
2025-06-09T15:14:31.090Z,1749482071.090 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-06-09T15:14:31.091Z,1749482071.091 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409DB4E0
2025-06-09T15:14:31.092Z,1749482071.092 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 923
2025-06-09T15:14:31.108Z,1749482071.108 [Sonardyne_Nano] Loaded
2025-06-09T15:14:31.108Z,1749482071.108 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-06-09T15:14:31.109Z,1749482071.109 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-06-09T15:14:31.110Z,1749482071.110 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-06-09T15:14:31.188Z,1749482071.188 [DepthRateCalculator] Loaded
2025-06-09T15:14:31.188Z,1749482071.188 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-06-09T15:14:31.193Z,1749482071.193 [PitchRateCalculator] Loaded
2025-06-09T15:14:31.194Z,1749482071.194 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-06-09T15:14:31.203Z,1749482071.203 [SpeedCalculator] Loaded
2025-06-09T15:14:31.204Z,1749482071.204 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-06-09T15:14:31.208Z,1749482071.208 [YawRateCalculator] Loaded
2025-06-09T15:14:31.209Z,1749482071.209 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-06-09T15:14:31.229Z,1749482071.229 [ElevatorOffsetCalculator] Loaded
2025-06-09T15:14:31.229Z,1749482071.229 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-06-09T15:14:31.230Z,1749482071.230 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-06-09T15:14:31.231Z,1749482071.231 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-06-09T15:14:31.428Z,1749482071.428 [CANONSampler] Loaded
2025-06-09T15:14:31.428Z,1749482071.428 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2025-06-09T15:14:31.498Z,1749482071.498 [CTD_Seabird] Loaded
2025-06-09T15:14:31.498Z,1749482071.498 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-06-09T15:14:31.499Z,1749482071.499 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A994E0
2025-06-09T15:14:31.500Z,1749482071.500 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 924
2025-06-09T15:14:31.524Z,1749482071.524 [PAR_Licor] Loaded
2025-06-09T15:14:31.524Z,1749482071.524 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-06-09T15:14:31.572Z,1749482071.572 [WetLabsBB2FL] Loaded
2025-06-09T15:14:31.573Z,1749482071.573 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-06-09T15:14:31.574Z,1749482071.574 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AC94E0
2025-06-09T15:14:31.574Z,1749482071.574 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 925
2025-06-09T15:14:31.575Z,1749482071.575 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-06-09T15:14:31.576Z,1749482071.576 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-06-09T15:14:31.626Z,1749482071.626 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-06-09T15:14:31.627Z,1749482071.627 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-06-09T15:14:32.087Z,1749482072.087 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-06-09T15:14:32.088Z,1749482072.088 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-06-09T15:14:32.309Z,1749482072.309 [SBIT](DEBUG): Construct Startup Built In Test.
2025-06-09T15:14:32.318Z,1749482072.318 [SBIT] Loaded
2025-06-09T15:14:32.318Z,1749482072.318 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-06-09T15:14:32.322Z,1749482072.322 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-06-09T15:14:32.335Z,1749482072.335 [IBIT] Loaded
2025-06-09T15:14:32.336Z,1749482072.336 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-06-09T15:14:32.341Z,1749482072.341 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-06-09T15:14:32.428Z,1749482072.428 [CBIT] Loaded
2025-06-09T15:14:32.429Z,1749482072.429 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-06-09T15:14:32.463Z,1749482072.463 [GFScanner] Loaded
2025-06-09T15:14:32.464Z,1749482072.464 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-06-09T15:14:32.464Z,1749482072.464 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-06-09T15:14:32.465Z,1749482072.465 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-06-09T15:14:32.650Z,1749482072.650 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-06-09T15:14:32.656Z,1749482072.656 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-06-09T15:14:32.659Z,1749482072.659 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-06-09T15:14:32.671Z,1749482072.671 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-06-09T15:14:32.672Z,1749482072.672 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C394E0
2025-06-09T15:14:32.672Z,1749482072.672 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 926
2025-06-09T15:14:32.677Z,1749482072.677 [Supervisor](INFO): Main Thread ID is 829
2025-06-09T15:14:32.677Z,1749482072.677 [Supervisor](DEBUG): Running supervisor.
2025-06-09T15:14:32.678Z,1749482072.678 [CommandExec ThreadHandler](INFO): Handler Thread ID is 927
2025-06-09T15:14:32.678Z,1749482072.678 [CommandExec](INFO): Initializing the command executive.
2025-06-09T15:14:32.680Z,1749482072.680 [CommandLine ThreadHandler](INFO): Handler Thread ID is 928
2025-06-09T15:14:32.682Z,1749482072.682 [controlThread ThreadHandler](INFO): Handler Thread ID is 929
2025-06-09T15:14:32.683Z,1749482072.683 [controlThread](DEBUG): Initializing ControlThread
2025-06-09T15:14:32.683Z,1749482072.683 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-06-09T15:14:32.685Z,1749482072.685 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-06-09T15:14:32.686Z,1749482072.686 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-06-09T15:14:32.686Z,1749482072.686 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-06-09T15:14:32.688Z,1749482072.688 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-06-09T15:14:32.689Z,1749482072.689 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-06-09T15:14:32.692Z,1749482072.692 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-06-09T15:14:32.692Z,1749482072.692 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-06-09T15:14:32.692Z,1749482072.692 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-06-09T15:14:32.693Z,1749482072.693 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-06-09T15:14:32.693Z,1749482072.693 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-06-09T15:14:32.696Z,1749482072.696 [SBIT](INFO): Initialize SBIT Component.
2025-06-09T15:14:32.696Z,1749482072.696 [SBIT](IMPORTANT): git: 2025-06-02-12-gf31d2d2cf
2025-06-09T15:14:32.696Z,1749482072.696 [SBIT](INFO): git hash: f31d2d2cff7b1a18a5fb2309356ea947ca1213be
2025-06-09T15:14:32.697Z,1749482072.697 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-06-09T15:14:32.698Z,1749482072.698 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2025-06-09T15:14:32.699Z,1749482072.699 [SBIT](INFO): Beginning SBIT in 77.000000 seconds.
2025-06-09T15:14:32.699Z,1749482072.699 [IBIT](INFO): Initialize IBIT Component.
2025-06-09T15:14:32.700Z,1749482072.700 [CBIT](DEBUG): Initialize CBIT Component.
2025-06-09T15:14:32.701Z,1749482072.701 [logger ThreadHandler](INFO): Handler Thread ID is 930
2025-06-09T15:14:32.711Z,1749482072.711 [CBIT](DEBUG): Initialized mux pins.
2025-06-09T15:14:32.711Z,1749482072.711 [CBIT](DEBUG): Initializing the watchdog timer.
2025-06-09T15:14:32.720Z,1749482072.720 [DAT ThreadHandler](INFO): Handler Thread ID is 931
2025-06-09T15:14:32.721Z,1749482072.721 [DAT](INFO): Powering up
2025-06-09T15:14:32.721Z,1749482072.721 [DAT](DEBUG): Initializing DAT.
2025-06-09T15:14:32.725Z,1749482072.725 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 933
2025-06-09T15:14:32.727Z,1749482072.727 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-06-09T15:14:32.735Z,1749482072.735 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-06-09T15:14:32.736Z,1749482072.736 [CBIT](DEBUG): Initializing heartbeat.
2025-06-09T15:14:32.737Z,1749482072.737 [Onboard ThreadHandler](INFO): Handler Thread ID is 934
2025-06-09T15:14:32.757Z,1749482072.757 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 935
2025-06-09T15:14:32.772Z,1749482072.772 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 936
2025-06-09T15:14:32.772Z,1749482072.772 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-06-09T15:14:32.776Z,1749482072.776 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 938
2025-06-09T15:14:32.781Z,1749482072.781 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 940
2025-06-09T15:14:32.782Z,1749482072.782 [WetLabsBB2FL](INFO): Powering up
2025-06-09T15:14:32.791Z,1749482072.791 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-06-09T15:14:32.792Z,1749482072.792 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-06-09T15:14:32.792Z,1749482072.792 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-06-09T15:14:32.792Z,1749482072.792 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-06-09T15:14:32.793Z,1749482072.793 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-06-09T15:14:32.793Z,1749482072.793 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-06-09T15:14:32.793Z,1749482072.793 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-06-09T15:14:32.793Z,1749482072.793 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-06-09T15:14:32.807Z,1749482072.807 [CBIT](DEBUG): Deactivating emergency mode.
2025-06-09T15:14:32.847Z,1749482072.847 [CBIT](DEBUG): Backplane powered.
2025-06-09T15:14:32.848Z,1749482072.848 [GFScanner](DEBUG): Initializing GFScanner
2025-06-09T15:14:32.848Z,1749482072.848 [GFScanner](DEBUG): Deactivating GF circuits.
2025-06-09T15:14:32.853Z,1749482072.853 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-06-09T15:14:32.853Z,1749482072.853 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-06-09T15:14:32.854Z,1749482072.854 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-06-09T15:14:32.861Z,1749482072.861 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-06-09T15:14:32.948Z,1749482072.948 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="Startup">
    <Guidance:GoToSurface>
        <Progression />
    </Guidance:GoToSurface>
    <Aggregate Id="StartupSatComms">
        <Sequence />
        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix />
        </ReadDatum>
        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications />
        </ReadDatum>
    </Aggregate>
</Mission>

2025-06-09T15:14:32.952Z,1749482072.952 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-06-09T15:14:32.989Z,1749482072.989 [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:14:32.989Z,1749482072.989 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-06-09T15:14:32.989Z,1749482072.989 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-06-09T15:14:32.992Z,1749482072.992 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-06-09T15:14:33.159Z,1749482073.159 [Radio_Surface](INFO): Powering up
2025-06-09T15:14:33.319Z,1749482073.319 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="Default">
    <DefineArg Name="ElapsedSinceDefaultStarted">
        <Units:minute />
        <Value>0</Value>
    </DefineArg>
    <Guidance:Wait>
        <Sequence />
        <Setting>
            <Guidance:Wait.duration />
            <Units:second />
            <Value>13</Value>
        </Setting>
    </Guidance:Wait>
    <Guidance:GoToSurface>
        <Progression />
    </Guidance:GoToSurface>
    <Aggregate Id="CheckIn">
        <Sequence Repeat="288" />
        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M" />
            <Universal:time_fix />
        </ReadDatum>
        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P4H">
                <Guidance:Execute>
                    <Sequence />
                    <Setting>
                        <Guidance:Execute.command />
                        <String>Burn on</String>
                    </Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications />
        </ReadDatum>
        <Guidance:Wait>
            <Sequence />
            <Setting>
                <Guidance:Wait.duration />
                <Units:minute />
                <Value>5</Value>
            </Setting>
        </Guidance:Wait>
        <Assign>
            <Sequence />
            <Arg Name="ElapsedSinceDefaultStarted" />
            <Elapsed>
                <Universal:mission_started />
            </Elapsed>
        </Assign>
        <Syslog Severity="Important">
            Default mission has been running for 
            <Arg Name="ElapsedSinceDefaultStarted" />
            <Units:minute />
        </Syslog>
    </Aggregate>
    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>
    <Guidance:Execute>
        <Sequence />
        <Setting>
            <Guidance:Execute.command />
            <String>restart logs</String>
        </Setting>
    </Guidance:Execute>
</Mission>

2025-06-09T15:14:33.341Z,1749482073.341 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-06-09T15:14:33.346Z,1749482073.346 [Default:A.Wait](DEBUG): Construct Wait.
2025-06-09T15:14:33.349Z,1749482073.349 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-06-09T15:14:33.388Z,1749482073.388 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-06-09T15:14:33.400Z,1749482073.400 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-06-09T15:14:33.415Z,1749482073.415 [Default:E.Execute](DEBUG): Construct Execute.
2025-06-09T15:14:33.418Z,1749482073.418 [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:14:33.432Z,1749482073.432 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,Reporter,LogSplitter,
2025-06-09T15:14:33.455Z,1749482073.455 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-06-09T15:14:33.608Z,1749482073.608 [Depth_Keller](INFO): Initializing.
2025-06-09T15:14:33.609Z,1749482073.609 [Power24vConverter](INFO): Powering up.
2025-06-09T15:14:33.610Z,1749482073.610 [Sonardyne_Nano](INFO): Initializing.
2025-06-09T15:14:33.628Z,1749482073.628 [CANONSampler](INFO): Powering down
2025-06-09T15:14:33.652Z,1749482073.652 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-06-09T15:14:33.678Z,1749482073.678 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-06-09T15:14:33.684Z,1749482073.684 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-06-09T15:14:33.685Z,1749482073.685 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-06-09T15:14:33.692Z,1749482073.692 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-06-09T15:14:33.693Z,1749482073.693 [MassServo](DEBUG): Initializing EZServoServo.
2025-06-09T15:14:33.700Z,1749482073.700 [MassServo](DEBUG): Initializing MassServo.
2025-06-09T15:14:33.700Z,1749482073.700 [RudderServo](DEBUG): Initializing EZServoServo.
2025-06-09T15:14:33.708Z,1749482073.708 [RudderServo](DEBUG): Initializing RudderServo.
2025-06-09T15:14:33.708Z,1749482073.708 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-06-09T15:14:33.716Z,1749482073.716 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-06-09T15:14:34.891Z,1749482074.891 [WetLabsBB2FL](INFO): Powering down
2025-06-09T15:14:35.866Z,1749482075.866 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-06-09T15:14:37.520Z,1749482077.520 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2109
2025-06-09T15:14:39.360Z,1749482079.360 [ThrusterHE](ERROR): Zero Speed Commanded.
2025-06-09T15:14:44.564Z,1749482084.564 [DAT](INFO): DAT read: 

2025-06-09T15:14:44.565Z,1749482084.565 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2025-06-09T15:14:45.827Z,1749482085.827 [DAT](INFO): DAT read: MF Frequency Band

2025-06-09T15:14:46.077Z,1749482086.077 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2025-06-09T15:14:46.078Z,1749482086.078 [DAT](INFO): DAT read: Jun  6 2025 20:00:39 

2025-06-09T15:14:47.085Z,1749482087.085 [DAT](INFO): DAT read: Features enabled [Bearing]

2025-06-09T15:14:47.086Z,1749482087.086 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC

2025-06-09T15:14:47.086Z,1749482087.086 [DAT](INFO): commRate: 1200
2025-06-09T15:14:49.151Z,1749482089.151 [DAT](INFO): entering command mode
2025-06-09T15:14:49.352Z,1749482089.352 [DAT](INFO): DAT read: 

2025-06-09T15:14:49.353Z,1749482089.353 [DAT](INFO): DAT read: user:1>

2025-06-09T15:14:49.353Z,1749482089.353 [DAT](INFO): setting verbose to 3
2025-06-09T15:14:49.604Z,1749482089.604 [DAT](INFO): DAT read: user:1>

2025-06-09T15:14:49.605Z,1749482089.605 [DAT](INFO): DAT read: Verbose         | 3           

2025-06-09T15:14:49.605Z,1749482089.605 [DAT](INFO): set verbose to 3
2025-06-09T15:14:49.606Z,1749482089.606 [DAT](INFO): setting DatVerbose to 27440
2025-06-09T15:14:49.856Z,1749482089.856 [DAT](INFO): DAT read: user:2>

2025-06-09T15:14:49.857Z,1749482089.857 [DAT](INFO): DAT read: DatVerbose      | 27440       

2025-06-09T15:14:49.857Z,1749482089.857 [DAT](INFO): set DatVerbose to 27440
2025-06-09T15:14:49.858Z,1749482089.858 [DAT](INFO): setting transmit power to 8
2025-06-09T15:14:50.108Z,1749482090.108 [DAT](INFO): DAT read: user:3>

2025-06-09T15:14:50.109Z,1749482090.109 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2025-06-09T15:14:50.109Z,1749482090.109 [DAT](INFO): set transmit power to 8
2025-06-09T15:14:50.110Z,1749482090.110 [DAT](INFO): setting local address to 8
2025-06-09T15:14:50.360Z,1749482090.360 [DAT](INFO): DAT read: user:4>

2025-06-09T15:14:50.361Z,1749482090.361 [DAT](INFO): DAT read: LocalAddr       | 8           

2025-06-09T15:14:50.361Z,1749482090.361 [DAT](INFO): set local address to 8
2025-06-09T15:14:50.362Z,1749482090.362 [DAT](INFO): Setting time to: 15:14:50 And date to:6/9/2025
2025-06-09T15:14:50.612Z,1749482090.612 [DAT](INFO): DAT read: user:5>

2025-06-09T15:14:50.613Z,1749482090.613 [DAT](INFO): DAT read: Mon Jun 9, 2025  15:14:50  

2025-06-09T15:14:50.613Z,1749482090.613 [DAT](INFO): Local DAT time set to Mon Jun 9, 2025  15:14:50  

2025-06-09T15:15:01.333Z,1749482101.333 [NAL9602](INFO): Powering up NAL9602
2025-06-09T15:15:12.249Z,1749482112.249 [NAL9602](INFO): NAL9602 initialized
2025-06-09T15:15:38.105Z,1749482138.105 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:15:50.240Z,1749482150.240 [SBIT](IMPORTANT): Beginning Startup BIT
2025-06-09T15:15:50.245Z,1749482150.245 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-06-09T15:15:54.745Z,1749482154.745 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.013013
CHAN A1 (24V): 0.000527
CHAN A2 (12V): -0.002085
CHAN A3 (5V): -0.001278
CHAN B0 (3.3V): -0.000110
CHAN B1 (3.15aV): -0.000219
CHAN B2 (3.15bV): -0.000142
CHAN B3 (GND): -0.000109
OPEN: -0.000288
Full Scale: +/- 1 mA
2025-06-09T15:15:59.505Z,1749482159.505 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T15:16:19.722Z,1749482179.722 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T15:16:39.901Z,1749482199.901 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T15:16:43.971Z,1749482203.971 [SBIT](IMPORTANT): SBIT PASSED
2025-06-09T15:16:43.971Z,1749482203.971 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-06-09T15:16:43.972Z,1749482203.972 [SBIT](IMPORTANT): DATMMP.surfaceThreshold=2 meter;

2025-06-09T15:16:43.972Z,1749482203.972 [SBIT](IMPORTANT): Express linearApproximation DATMMP.acoustic_contact_range 15.000000 meter;

2025-06-09T15:16:43.972Z,1749482203.972 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=260.065004 cubic_centimeter;

2025-06-09T15:16:43.972Z,1749482203.972 [SBIT](IMPORTANT): VerticalControl.massDefault=14.450214 millimeter;

2025-06-09T15:16:44.366Z,1749482204.366 [MissionManager](IMPORTANT): Started mission Startup
2025-06-09T15:16:44.366Z,1749482204.366 [Startup] Running Loop=1
2025-06-09T15:16:44.366Z,1749482204.366 [Startup](DEBUG): Aggregate::initialize Startup
2025-06-09T15:16:44.366Z,1749482204.366 [Startup:A.GoToSurface] Running Loop=1
2025-06-09T15:16:44.366Z,1749482204.366 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-06-09T15:16:44.367Z,1749482204.367 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-06-09T15:16:44.392Z,1749482204.392 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-06-09T15:16:44.392Z,1749482204.392 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-06-09T15:16:44.392Z,1749482204.392 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-06-09T15:16:44.393Z,1749482204.393 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-06-09T15:16:44.393Z,1749482204.393 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-06-09T15:16:44.395Z,1749482204.395 [Startup:StartupSatComms] Running Loop=1
2025-06-09T15:16:44.395Z,1749482204.395 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-06-09T15:16:44.395Z,1749482204.395 [Startup:StartupSatComms:A] Running Loop=1
2025-06-09T15:16:44.786Z,1749482204.786 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-06-09T15:16:46.017Z,1749482206.017 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:17:00.506Z,1749482220.506 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T15:17:00.738Z,1749482220.738 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004813
2025-06-09T15:17:13.174Z,1749482233.174 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:17:32.957Z,1749482252.957 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-06-09T15:17:32.957Z,1749482252.957 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-09T15:17:32.967Z,1749482252.967 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-09T15:17:33.383Z,1749482253.383 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-09T15:17:33.383Z,1749482253.383 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-06-09T15:17:35.801Z,1749482255.801 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:17:44.658Z,1749482264.658 [Startup:StartupSatComms:A](INFO): Timed out from 2025-06-09T15:16:44.4Z
2025-06-09T15:17:44.658Z,1749482264.658 [Startup:StartupSatComms:A] Stopped
2025-06-09T15:17:44.658Z,1749482264.658 [Startup:StartupSatComms:B] Running Loop=1
2025-06-09T15:17:45.057Z,1749482265.057 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-06-09T15:17:52.063Z,1749482272.063 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250609T151419/Courier0000.lzma
2025-06-09T15:17:53.063Z,1749482273.063 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Courier0000.lzma.bak
2025-06-09T15:17:53.064Z,1749482273.064 [DataOverHttps](INFO): SBD MOMSN=25159906
2025-06-09T15:18:08.795Z,1749482288.795 [DataOverHttps](INFO): Sending 818 bytes from file Logs/20250609T151419/Express0001.lzma
2025-06-09T15:18:09.799Z,1749482289.799 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Express0001.lzma.bak
2025-06-09T15:18:09.800Z,1749482289.800 [DataOverHttps](INFO): SBD MOMSN=25159909
2025-06-09T15:18:10.919Z,1749482290.919 [Startup:StartupSatComms:B] Stopped
2025-06-09T15:18:10.920Z,1749482290.920 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-06-09T15:18:10.920Z,1749482290.920 [Startup:StartupSatComms] Stopped
2025-06-09T15:18:10.920Z,1749482290.920 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-06-09T15:18:10.921Z,1749482290.921 [Startup](INFO): Completed Startup
2025-06-09T15:18:10.921Z,1749482290.921 [MissionManager](INFO): Startup is completed.
2025-06-09T15:18:10.921Z,1749482290.921 [MissionManager](INFO): Uninitializing Mission Startup
2025-06-09T15:18:10.921Z,1749482290.921 [Startup] Stopped
2025-06-09T15:18:10.921Z,1749482290.921 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-06-09T15:18:10.921Z,1749482290.921 [Startup:A.GoToSurface] Stopped
2025-06-09T15:18:10.921Z,1749482290.921 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-06-09T15:18:11.320Z,1749482291.320 [MissionManager](IMPORTANT): Started mission Default
2025-06-09T15:18:11.320Z,1749482291.320 [Default] Running Loop=1
2025-06-09T15:18:11.320Z,1749482291.320 [Default](DEBUG): Aggregate::initialize Default
2025-06-09T15:18:11.320Z,1749482291.320 [Default:B.GoToSurface] Running Loop=1
2025-06-09T15:18:11.320Z,1749482291.320 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-06-09T15:18:11.320Z,1749482291.320 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-06-09T15:18:11.321Z,1749482291.321 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-06-09T15:18:11.321Z,1749482291.321 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-06-09T15:18:11.321Z,1749482291.321 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-06-09T15:18:11.346Z,1749482291.346 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-06-09T15:18:11.346Z,1749482291.346 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-06-09T15:18:11.346Z,1749482291.346 [Default:A.Wait] Running Loop=1
2025-06-09T15:18:11.346Z,1749482291.346 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-06-09T15:18:17.433Z,1749482297.433 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:18:24.670Z,1749482304.670 [Default:A.Wait](INFO): Done Waiting.
2025-06-09T15:18:24.670Z,1749482304.670 [Default:A.Wait] Stopped
2025-06-09T15:18:24.670Z,1749482304.670 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T15:18:25.092Z,1749482305.092 [Default:CheckIn] Running Loop=1
2025-06-09T15:18:25.092Z,1749482305.092 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T15:18:25.092Z,1749482305.092 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T15:18:25.484Z,1749482305.484 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-06-09T15:18:29.130Z,1749482309.130 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-06-09T15:18:35.608Z,1749482315.608 [CommandExec](IMPORTANT): got command strobe off
2025-06-09T15:18:35.608Z,1749482315.608 [CommandExec](IMPORTANT): Deactivating strobe
2025-06-09T15:19:01.547Z,1749482341.547 [CommandExec](IMPORTANT): got command burn on
2025-06-09T15:19:01.547Z,1749482341.547 [CommandExec](IMPORTANT): Activating dropweight wire
2025-06-09T15:20:16.151Z,1749482416.151 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-06-09T15:20:33.945Z,1749482433.945 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2025-06-09T15:20:33.945Z,1749482433.945 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-09T15:20:33.955Z,1749482433.955 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-09T15:20:34.371Z,1749482434.371 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-09T15:20:34.371Z,1749482434.371 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2025-06-09T15:20:34.619Z,1749482434.619 [CommandExec](IMPORTANT): got command burn off
2025-06-09T15:20:34.619Z,1749482434.619 [CommandExec](IMPORTANT): Deactivating dropweight wire
2025-06-09T15:21:09.945Z,1749482469.945 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:21:52.357Z,1749482512.357 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:22:19.830Z,1749482539.830 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-06-09T15:23:25.236Z,1749482605.236 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-06-09T15:18:25.1Z
2025-06-09T15:23:25.236Z,1749482605.236 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T15:23:25.236Z,1749482605.236 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T15:23:25.659Z,1749482605.659 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-06-09T15:23:33.017Z,1749482613.017 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250609T151419/Courier0004.lzma
2025-06-09T15:23:34.019Z,1749482614.019 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Courier0004.lzma.bak
2025-06-09T15:23:34.020Z,1749482614.020 [DataOverHttps](INFO): SBD MOMSN=25159963
2025-06-09T15:23:34.955Z,1749482614.955 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2025-06-09T15:23:34.955Z,1749482614.955 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-09T15:23:34.966Z,1749482614.966 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-09T15:23:35.364Z,1749482615.364 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-09T15:23:35.364Z,1749482615.364 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2025-06-09T15:23:49.646Z,1749482629.646 [DataOverHttps](INFO): Sending 291 bytes from file Logs/20250609T151419/Express0005.lzma
2025-06-09T15:23:50.647Z,1749482630.647 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Express0005.lzma.bak
2025-06-09T15:23:50.648Z,1749482630.648 [DataOverHttps](INFO): SBD MOMSN=25159965
2025-06-09T15:23:51.898Z,1749482631.898 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T15:23:51.898Z,1749482631.898 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T15:23:51.898Z,1749482631.898 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T15:24:30.675Z,1749482670.675 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T15:24:36.332Z,1749482676.332 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T15:24:49.665Z,1749482689.665 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T15:25:03.400Z,1749482703.400 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T15:25:13.911Z,1749482713.911 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-06-09T15:25:13.912Z,1749482713.912 [NAL9602] Data Fault, FailCount= 1
2025-06-09T15:25:13.912Z,1749482713.912 [NAL9602](ERROR): Data Fault
2025-06-09T15:25:13.954Z,1749482713.954 [CBIT](ERROR): Data Fault in component: NAL9602
2025-06-09T15:25:14.311Z,1749482714.311 [NAL9602](INFO): Powering down
2025-06-09T15:25:15.140Z,1749482715.140 [CBIT](INFO): Clearing failed state for component NAL9602
2025-06-09T15:25:15.141Z,1749482715.141 [NAL9602] No Fault, FailCount= 1
2025-06-09T15:25:17.136Z,1749482717.136 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T15:25:30.873Z,1749482730.873 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T15:25:44.214Z,1749482744.214 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T15:25:44.696Z,1749482744.696 [NAL9602](INFO): Powering up NAL9602
2025-06-09T15:25:55.531Z,1749482755.531 [NAL9602](INFO): NAL9602 initialized
2025-06-09T15:25:57.536Z,1749482757.536 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T15:26:10.869Z,1749482770.869 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T15:26:24.605Z,1749482784.605 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T15:26:35.934Z,1749482795.934 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2025-06-09T15:26:35.934Z,1749482795.934 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-09T15:26:35.945Z,1749482795.945 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-09T15:26:36.392Z,1749482796.392 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-09T15:26:36.392Z,1749482796.392 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2025-06-09T15:26:38.005Z,1749482798.005 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T15:26:50.868Z,1749482810.868 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T15:28:52.508Z,1749482932.508 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T15:28:52.508Z,1749482932.508 [Default:CheckIn:C.Wait] Stopped
2025-06-09T15:28:52.508Z,1749482932.508 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T15:28:52.508Z,1749482932.508 [Default:CheckIn:D] Running Loop=1
2025-06-09T15:28:52.912Z,1749482932.912 [Default:CheckIn:D] Stopped
2025-06-09T15:28:52.912Z,1749482932.912 [Default:CheckIn:E] Running Loop=1
2025-06-09T15:28:53.316Z,1749482933.316 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  10.693202 min 
2025-06-09T15:28:53.316Z,1749482933.316 [Default:CheckIn:E] Stopped
2025-06-09T15:28:53.316Z,1749482933.316 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T15:28:53.316Z,1749482933.316 [Default:CheckIn] Stopped
2025-06-09T15:28:53.317Z,1749482933.317 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T15:28:53.317Z,1749482933.317 [Default:CheckIn](INFO): Running loop #2
2025-06-09T15:28:53.317Z,1749482933.317 [Default:CheckIn] Running Loop=2
2025-06-09T15:28:53.317Z,1749482933.317 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T15:28:53.317Z,1749482933.317 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T15:29:16.038Z,1749482956.038 [CommandExec](IMPORTANT): got command get 
2025-06-09T15:29:16.038Z,1749482956.038 [CommandExec](FAULT): Incomplete syntax. Try: help get
2025-06-09T15:29:36.942Z,1749482976.942 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2025-06-09T15:29:36.942Z,1749482976.942 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-09T15:29:36.953Z,1749482976.953 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-09T15:29:37.363Z,1749482977.363 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-09T15:29:37.364Z,1749482977.364 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2025-06-09T15:29:46.704Z,1749482986.704 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.chargePercent
2025-06-09T15:29:46.704Z,1749482986.704 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent 46.000000 %
2025-06-09T15:32:37.941Z,1749483157.941 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2025-06-09T15:32:37.941Z,1749483157.941 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-09T15:32:37.952Z,1749483157.952 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-09T15:32:38.360Z,1749483158.360 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-09T15:32:38.360Z,1749483158.360 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2025-06-09T15:33:53.543Z,1749483233.543 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-06-09T15:28:53.3Z
2025-06-09T15:33:53.543Z,1749483233.543 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T15:33:53.544Z,1749483233.544 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T15:34:00.262Z,1749483240.262 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250609T151419/Courier0007.lzma
2025-06-09T15:34:01.264Z,1749483241.264 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Courier0007.lzma.bak
2025-06-09T15:34:01.264Z,1749483241.264 [DataOverHttps](INFO): SBD MOMSN=25160084
2025-06-09T15:34:12.455Z,1749483252.455 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T15:34:16.826Z,1749483256.826 [DataOverHttps](INFO): Sending 279 bytes from file Logs/20250609T151419/Express0008.lzma
2025-06-09T15:34:17.828Z,1749483257.828 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Express0008.lzma.bak
2025-06-09T15:34:17.828Z,1749483257.828 [DataOverHttps](INFO): SBD MOMSN=25160086
2025-06-09T15:34:18.928Z,1749483258.928 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T15:34:18.928Z,1749483258.928 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T15:34:18.928Z,1749483258.928 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T15:34:25.824Z,1749483265.824 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T15:34:39.164Z,1749483279.164 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T15:34:52.495Z,1749483292.495 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T15:35:05.876Z,1749483305.876 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T15:35:19.612Z,1749483319.612 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T15:35:32.944Z,1749483332.944 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T15:35:39.050Z,1749483339.050 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2025-06-09T15:35:39.050Z,1749483339.050 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-09T15:35:39.061Z,1749483339.061 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-09T15:35:39.466Z,1749483339.466 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-09T15:35:39.466Z,1749483339.466 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2025-06-09T15:35:46.680Z,1749483346.680 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T15:35:57.595Z,1749483357.595 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-06-09T15:35:57.596Z,1749483357.596 [NAL9602] Data Fault, FailCount= 2
2025-06-09T15:35:57.596Z,1749483357.596 [NAL9602](ERROR): Data Fault
2025-06-09T15:35:57.614Z,1749483357.614 [CBIT](ERROR): Data Fault in component: NAL9602
2025-06-09T15:35:57.995Z,1749483357.995 [NAL9602](INFO): Powering down
2025-06-09T15:35:58.824Z,1749483358.824 [CBIT](INFO): Clearing failed state for component NAL9602
2025-06-09T15:35:58.824Z,1749483358.824 [NAL9602] No Fault, FailCount= 2
2025-06-09T15:36:00.011Z,1749483360.011 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T15:36:13.343Z,1749483373.343 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T15:36:28.374Z,1749483388.374 [NAL9602](INFO): Powering up NAL9602
2025-06-09T15:36:39.321Z,1749483399.321 [NAL9602](INFO): NAL9602 initialized
2025-06-09T15:38:40.186Z,1749483520.186 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2025-06-09T15:38:40.186Z,1749483520.186 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-09T15:38:40.197Z,1749483520.197 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-09T15:38:40.577Z,1749483520.577 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-09T15:38:40.577Z,1749483520.577 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2025-06-09T15:39:19.716Z,1749483559.716 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T15:39:19.716Z,1749483559.716 [Default:CheckIn:C.Wait] Stopped
2025-06-09T15:39:19.716Z,1749483559.716 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T15:39:19.716Z,1749483559.716 [Default:CheckIn:D] Running Loop=1
2025-06-09T15:39:20.136Z,1749483560.136 [Default:CheckIn:D] Stopped
2025-06-09T15:39:20.136Z,1749483560.136 [Default:CheckIn:E] Running Loop=1
2025-06-09T15:39:20.532Z,1749483560.532 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  21.146932 min 
2025-06-09T15:39:20.532Z,1749483560.532 [Default:CheckIn:E] Stopped
2025-06-09T15:39:20.532Z,1749483560.532 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T15:39:20.532Z,1749483560.532 [Default:CheckIn] Stopped
2025-06-09T15:39:20.533Z,1749483560.533 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T15:39:20.533Z,1749483560.533 [Default:CheckIn](INFO): Running loop #3
2025-06-09T15:39:20.533Z,1749483560.533 [Default:CheckIn] Running Loop=3
2025-06-09T15:39:20.533Z,1749483560.533 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T15:39:20.533Z,1749483560.533 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T15:41:41.140Z,1749483701.140 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2025-06-09T15:41:41.140Z,1749483701.140 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-09T15:41:41.151Z,1749483701.151 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-09T15:41:41.552Z,1749483701.552 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-09T15:41:41.552Z,1749483701.552 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2025-06-09T15:41:54.510Z,1749483714.510 [CommandExec](IMPORTANT): got command failComponent
2025-06-09T15:41:54.510Z,1749483714.510 [CommandExec](IMPORTANT): Failed components:
2025-06-09T15:41:54.511Z,1749483714.511 [CommandExec](IMPORTANT): No failed Components.
2025-06-09T15:41:59.715Z,1749483719.715 [CommandExec](IMPORTANT): got command gfscan
2025-06-09T15:41:59.749Z,1749483719.749 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-06-09T15:42:04.166Z,1749483724.166 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002465
CHAN A1 (24V): 0.000727
CHAN A2 (12V): -0.001886
CHAN A3 (5V): -0.001397
CHAN B0 (3.3V): -0.000289
CHAN B1 (3.15aV): -0.000120
CHAN B2 (3.15bV): -0.000451
CHAN B3 (GND): -0.000377
OPEN: -0.000156
Full Scale: +/- 1 mA
2025-06-09T15:43:37.048Z,1749483817.048 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T15:43:50.384Z,1749483830.384 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T15:44:03.712Z,1749483843.712 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T15:44:17.448Z,1749483857.448 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T15:44:20.716Z,1749483860.716 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-06-09T15:39:20.5Z
2025-06-09T15:44:20.716Z,1749483860.716 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T15:44:20.716Z,1749483860.716 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T15:44:30.177Z,1749483870.177 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250609T151419/Courier0010.lzma
2025-06-09T15:44:30.804Z,1749483870.804 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T15:44:31.179Z,1749483871.179 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Courier0010.lzma.bak
2025-06-09T15:44:31.180Z,1749483871.180 [DataOverHttps](INFO): SBD MOMSN=25160202
2025-06-09T15:44:42.149Z,1749483882.149 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2025-06-09T15:44:42.149Z,1749483882.149 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-09T15:44:42.159Z,1749483882.159 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-09T15:44:42.544Z,1749483882.544 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-09T15:44:42.544Z,1749483882.544 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2025-06-09T15:44:44.542Z,1749483884.542 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T15:44:46.902Z,1749483886.902 [DataOverHttps](INFO): Sending 378 bytes from file Logs/20250609T151419/Express0011.lzma
2025-06-09T15:44:47.904Z,1749483887.904 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Express0011.lzma.bak
2025-06-09T15:44:47.904Z,1749483887.904 [DataOverHttps](INFO): SBD MOMSN=25160204
2025-06-09T15:44:49.057Z,1749483889.057 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T15:44:49.057Z,1749483889.057 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T15:44:49.057Z,1749483889.057 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T15:44:57.924Z,1749483897.924 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T15:45:11.257Z,1749483911.257 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T15:45:24.588Z,1749483924.588 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T15:45:33.880Z,1749483933.880 [BPC1](INFO): Calculating totals. Valid battery stick count: 47. Valid reserve battery stick count: 5.
2025-06-09T15:45:33.883Z,1749483933.883 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2025-06-09T15:46:42.583Z,1749484002.583 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-06-09T15:46:42.583Z,1749484002.583 [NAL9602] Data Fault, FailCount= 3
2025-06-09T15:46:42.583Z,1749484002.583 [NAL9602](ERROR): Data Fault
2025-06-09T15:46:42.601Z,1749484002.601 [CBIT](ERROR): Data Fault in component: NAL9602
2025-06-09T15:46:42.987Z,1749484002.987 [NAL9602](INFO): Powering down
2025-06-09T15:46:43.812Z,1749484003.812 [CBIT](INFO): Clearing failed state for component NAL9602
2025-06-09T15:46:43.812Z,1749484003.812 [NAL9602] No Fault, FailCount= 3
2025-06-09T15:47:13.303Z,1749484033.303 [NAL9602](INFO): Powering up NAL9602
2025-06-09T15:47:24.215Z,1749484044.215 [NAL9602](INFO): NAL9602 initialized
2025-06-09T15:47:43.207Z,1749484063.207 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2025-06-09T15:47:43.207Z,1749484063.207 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-06-09T15:47:43.217Z,1749484063.217 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-06-09T15:47:43.636Z,1749484063.636 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-06-09T15:47:43.636Z,1749484063.636 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2025-06-09T15:49:49.680Z,1749484189.680 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T15:49:49.680Z,1749484189.680 [Default:CheckIn:C.Wait] Stopped
2025-06-09T15:49:49.681Z,1749484189.681 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T15:49:49.681Z,1749484189.681 [Default:CheckIn:D] Running Loop=1
2025-06-09T15:49:50.092Z,1749484190.092 [Default:CheckIn:D] Stopped
2025-06-09T15:49:50.092Z,1749484190.092 [Default:CheckIn:E] Running Loop=1
2025-06-09T15:49:50.488Z,1749484190.488 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  31.646200 min 
2025-06-09T15:49:50.488Z,1749484190.488 [Default:CheckIn:E] Stopped
2025-06-09T15:49:50.488Z,1749484190.488 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T15:49:50.488Z,1749484190.488 [Default:CheckIn] Stopped
2025-06-09T15:49:50.489Z,1749484190.489 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T15:49:50.489Z,1749484190.489 [Default:CheckIn](INFO): Running loop #4
2025-06-09T15:49:50.489Z,1749484190.489 [Default:CheckIn] Running Loop=4
2025-06-09T15:49:50.489Z,1749484190.489 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T15:49:50.489Z,1749484190.489 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T15:50:40.969Z,1749484240.969 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155039.00,A,3648.16038,N,12147.26837,W,1.613,0.00,090625,,,A*76
2025-06-09T15:50:40.972Z,1749484240.972 [NAL9602](INFO): GPS fix at 20250609T155039: (36.802673, -121.787806)
2025-06-09T15:50:40.984Z,1749484240.984 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T15:50:40.984Z,1749484240.984 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T15:50:51.606Z,1749484251.606 [DataOverHttps](INFO): Sending 286 bytes from file Logs/20250609T151419/Courier0013.lzma
2025-06-09T15:50:52.612Z,1749484252.612 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Courier0013.lzma.bak
2025-06-09T15:50:52.612Z,1749484252.612 [DataOverHttps](INFO): SBD MOMSN=25160322
2025-06-09T15:51:12.674Z,1749484272.674 [DataOverHttps](INFO): Sending 368 bytes from file Logs/20250609T151419/Express0014.lzma
2025-06-09T15:51:13.355Z,1749484273.355 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T15:51:13.675Z,1749484273.675 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Express0014.lzma.bak
2025-06-09T15:51:13.676Z,1749484273.676 [DataOverHttps](INFO): SBD MOMSN=25160327
2025-06-09T15:51:14.992Z,1749484274.992 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T15:51:14.992Z,1749484274.992 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T15:51:14.992Z,1749484274.992 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T15:56:15.393Z,1749484575.393 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T15:56:15.393Z,1749484575.393 [Default:CheckIn:C.Wait] Stopped
2025-06-09T15:56:15.393Z,1749484575.393 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T15:56:15.393Z,1749484575.393 [Default:CheckIn:D] Running Loop=1
2025-06-09T15:56:15.758Z,1749484575.758 [Default:CheckIn:D] Stopped
2025-06-09T15:56:15.758Z,1749484575.758 [Default:CheckIn:E] Running Loop=1
2025-06-09T15:56:16.170Z,1749484576.170 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  38.073971 min 
2025-06-09T15:56:16.170Z,1749484576.170 [Default:CheckIn:E] Stopped
2025-06-09T15:56:16.170Z,1749484576.170 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T15:56:16.170Z,1749484576.170 [Default:CheckIn] Stopped
2025-06-09T15:56:16.170Z,1749484576.170 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T15:56:16.170Z,1749484576.170 [Default:CheckIn](INFO): Running loop #5
2025-06-09T15:56:16.171Z,1749484576.171 [Default:CheckIn] Running Loop=5
2025-06-09T15:56:16.171Z,1749484576.171 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T15:56:16.171Z,1749484576.171 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T15:56:18.173Z,1749484578.173 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155617.00,A,3648.17000,N,12147.28290,W,0.175,304.22,090625,,,A*7E
2025-06-09T15:56:18.175Z,1749484578.175 [NAL9602](INFO): GPS fix at 20250609T155617: (36.802833, -121.788048)
2025-06-09T15:56:18.235Z,1749484578.235 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T15:56:18.235Z,1749484578.235 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T15:56:28.809Z,1749484588.809 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250609T151419/Courier0016.lzma
2025-06-09T15:56:29.812Z,1749484589.812 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Courier0016.lzma.bak
2025-06-09T15:56:29.812Z,1749484589.812 [DataOverHttps](INFO): SBD MOMSN=25160398
2025-06-09T15:56:31.617Z,1749484591.617 [NAL9602](INFO): SBD MO Status=2, MOMSN=63447, MT Status=2, MTMSN=0
2025-06-09T15:56:31.617Z,1749484591.617 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-09T15:56:46.042Z,1749484606.042 [DataOverHttps](INFO): Sending 161 bytes from file Logs/20250609T151419/Express0017.lzma
2025-06-09T15:56:47.044Z,1749484607.044 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Express0017.lzma.bak
2025-06-09T15:56:47.044Z,1749484607.044 [DataOverHttps](INFO): SBD MOMSN=25160412
2025-06-09T15:56:48.177Z,1749484608.177 [NAL9602](INFO): SBD MO Status=0, MOMSN=63447, MT Status=0, MTMSN=0
2025-06-09T15:56:48.177Z,1749484608.177 [NAL9602](INFO): No messages in MT queue
2025-06-09T15:56:48.216Z,1749484608.216 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T15:56:48.216Z,1749484608.216 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T15:56:48.216Z,1749484608.216 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T15:57:18.951Z,1749484638.951 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T16:01:31.287Z,1749484891.287 [DataOverHttps](IMPORTANT): SBD MTMSN=20250609T160130
2025-06-09T16:01:38.890Z,1749484898.890 [DataOverHttps](INFO): Received command: failc
2025-06-09T16:01:38.918Z,1749484898.918 [CommandExec](IMPORTANT): got command failComponent
2025-06-09T16:01:38.918Z,1749484898.918 [CommandExec](IMPORTANT): Failed components:
2025-06-09T16:01:38.919Z,1749484898.919 [CommandExec](IMPORTANT): No failed Components.
2025-06-09T16:01:39.891Z,1749484899.891 [DataOverHttps](IMPORTANT): SBD MTMSN=20250609T160138
2025-06-09T16:01:47.309Z,1749484907.309 [DataOverHttps](INFO): Received command: ibit
2025-06-09T16:01:47.380Z,1749484907.380 [CommandExec](IMPORTANT): got command ibit
2025-06-09T16:01:47.746Z,1749484907.746 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-06-09T16:01:47.747Z,1749484907.747 [IBIT](IMPORTANT): Beginning control surface checks.
2025-06-09T16:01:47.754Z,1749484907.754 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-06-09T16:01:49.322Z,1749484909.322 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160148.00,A,3648.14061,N,12147.23382,W,2.216,66.00,090625,,,A*4B
2025-06-09T16:01:49.324Z,1749484909.324 [NAL9602](INFO): GPS fix at 20250609T160148: (36.802343, -121.787230)
2025-06-09T16:01:52.334Z,1749484912.334 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002379
CHAN A1 (24V): 0.000433
CHAN A2 (12V): -0.002060
CHAN A3 (5V): -0.001697
CHAN B0 (3.3V): 0.000238
CHAN B1 (3.15aV): -0.000160
CHAN B2 (3.15bV): -0.000160
CHAN B3 (GND): 0.000326
OPEN: -0.000298
Full Scale: +/- 1 mA
2025-06-09T16:02:04.264Z,1749484924.264 [NAL9602](INFO): SBD MO Status=0, MOMSN=63448, MT Status=0, MTMSN=0
2025-06-09T16:02:04.265Z,1749484924.265 [NAL9602](INFO): No messages in MT queue
2025-06-09T16:02:05.477Z,1749484925.477 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160204.00,A,3648.14513,N,12147.22846,W,0.428,41.71,090625,,,A*48
2025-06-09T16:02:05.479Z,1749484925.479 [NAL9602](INFO): GPS fix at 20250609T160204: (36.802419, -121.787141)
2025-06-09T16:02:07.904Z,1749484927.904 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160207.00,A,3648.14530,N,12147.22831,W,0.097,41.71,090625,,,A*4A
2025-06-09T16:02:07.906Z,1749484927.906 [NAL9602](INFO): GPS fix at 20250609T160207: (36.802422, -121.787138)
2025-06-09T16:02:11.135Z,1749484931.135 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160210.00,A,3648.14551,N,12147.22819,W,0.097,41.71,090625,,,A*41
2025-06-09T16:02:11.137Z,1749484931.137 [NAL9602](INFO): GPS fix at 20250609T160210: (36.802425, -121.787137)
2025-06-09T16:02:13.969Z,1749484933.969 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160213.00,A,3648.14566,N,12147.22825,W,0.058,41.71,090625,,,A*4A
2025-06-09T16:02:13.975Z,1749484933.975 [NAL9602](INFO): GPS fix at 20250609T160213: (36.802428, -121.787138)
2025-06-09T16:02:16.793Z,1749484936.793 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160216.00,A,3648.14570,N,12147.22835,W,0.039,41.71,090625,,,A*4E
2025-06-09T16:02:16.804Z,1749484936.804 [NAL9602](INFO): GPS fix at 20250609T160216: (36.802428, -121.787139)
2025-06-09T16:02:20.023Z,1749484940.023 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160219.00,A,3648.14571,N,12147.22844,W,0.058,41.71,090625,,,A*41
2025-06-09T16:02:20.025Z,1749484940.025 [NAL9602](INFO): GPS fix at 20250609T160219: (36.802428, -121.787141)
2025-06-09T16:02:22.850Z,1749484942.850 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160222.00,A,3648.14572,N,12147.22853,W,0.058,41.71,090625,,,A*4C
2025-06-09T16:02:22.852Z,1749484942.852 [NAL9602](INFO): GPS fix at 20250609T160222: (36.802429, -121.787142)
2025-06-09T16:02:26.086Z,1749484946.086 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160225.00,A,3648.14573,N,12147.22854,W,0.039,41.71,090625,,,A*4A
2025-06-09T16:02:26.088Z,1749484946.088 [NAL9602](INFO): GPS fix at 20250609T160225: (36.802429, -121.787142)
2025-06-09T16:02:28.917Z,1749484948.917 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160228.00,A,3648.14572,N,12147.22863,W,0.058,41.71,090625,,,A*45
2025-06-09T16:02:28.919Z,1749484948.919 [NAL9602](INFO): GPS fix at 20250609T160228: (36.802429, -121.787144)
2025-06-09T16:02:32.143Z,1749484952.143 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160231.00,A,3648.14572,N,12147.22863,W,0.039,41.71,090625,,,A*4A
2025-06-09T16:02:32.145Z,1749484952.145 [NAL9602](INFO): GPS fix at 20250609T160231: (36.802429, -121.787144)
2025-06-09T16:02:34.178Z,1749484954.178 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 3
Latitude: 36.802429 Longitude: -121.787148
2025-06-09T16:02:34.590Z,1749484954.590 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.572001
2025-06-09T16:02:34.590Z,1749484954.590 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2025-06-09T16:02:34.591Z,1749484954.591 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2025-06-09T16:02:34.973Z,1749484954.973 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160234.00,A,3648.14572,N,12147.22862,W,0.058,41.71,090625,,,A*49
2025-06-09T16:02:34.975Z,1749484954.975 [NAL9602](INFO): GPS fix at 20250609T160234: (36.802429, -121.787144)
2025-06-09T16:02:35.015Z,1749484955.015 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2025-06-09T16:02:35.015Z,1749484955.015 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2025-06-09T16:02:35.016Z,1749484955.016 [IBIT](IMPORTANT): Pressure:9.456040 PSI
2025-06-09T16:02:35.016Z,1749484955.016 [IBIT](IMPORTANT): Humidity:17.244537 %
2025-06-09T16:02:35.422Z,1749484955.422 [IBIT](IMPORTANT): Vehicle Pitch:-1.340599 degrees
2025-06-09T16:02:35.423Z,1749484955.423 [IBIT](IMPORTANT): Vehicle  Roll:-3.044917 degrees
2025-06-09T16:02:35.423Z,1749484955.423 [IBIT](IMPORTANT): Vehicle  Heading:16.676329 degrees
2025-06-09T16:02:35.792Z,1749484955.792 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2025-06-09T16:02:35.793Z,1749484955.793 [IBIT](IMPORTANT): buoyancyNeutral: 260.065002 cc
2025-06-09T16:02:35.793Z,1749484955.793 [IBIT](IMPORTANT): massDefault: 1.445022 cm
2025-06-09T16:02:35.793Z,1749484955.793 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2025-06-09T16:02:35.794Z,1749484955.794 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2025-06-09T16:02:35.794Z,1749484955.794 [IBIT](IMPORTANT): IBIT PASSED
2025-06-09T16:02:36.193Z,1749484956.193 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T16:02:36.193Z,1749484956.193 [Default:CheckIn:C.Wait] Stopped
2025-06-09T16:02:36.193Z,1749484956.193 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T16:02:36.194Z,1749484956.194 [Default:CheckIn:D] Running Loop=1
2025-06-09T16:02:36.609Z,1749484956.609 [Default:CheckIn:D] Stopped
2025-06-09T16:02:36.609Z,1749484956.609 [Default:CheckIn:E] Running Loop=1
2025-06-09T16:02:37.009Z,1749484957.009 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  44.421488 min 
2025-06-09T16:02:37.009Z,1749484957.009 [Default:CheckIn:E] Stopped
2025-06-09T16:02:37.010Z,1749484957.010 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T16:02:37.010Z,1749484957.010 [Default:CheckIn] Stopped
2025-06-09T16:02:37.010Z,1749484957.010 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T16:02:37.010Z,1749484957.010 [Default:CheckIn](INFO): Running loop #6
2025-06-09T16:02:37.010Z,1749484957.010 [Default:CheckIn] Running Loop=6
2025-06-09T16:02:37.010Z,1749484957.010 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T16:02:37.010Z,1749484957.010 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T16:02:39.013Z,1749484959.013 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160238.00,A,3648.14567,N,12147.22859,W,0.039,41.71,090625,,,A*4E
2025-06-09T16:02:39.015Z,1749484959.015 [NAL9602](INFO): GPS fix at 20250609T160238: (36.802428, -121.787143)
2025-06-09T16:02:39.047Z,1749484959.047 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T16:02:39.048Z,1749484959.048 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T16:02:47.041Z,1749484967.041 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250609T151419/Courier0019.lzma
2025-06-09T16:02:48.044Z,1749484968.044 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Courier0019.lzma.bak
2025-06-09T16:02:48.044Z,1749484968.044 [DataOverHttps](INFO): SBD MOMSN=25160508
2025-06-09T16:03:07.680Z,1749484987.680 [DataOverHttps](INFO): Sending 1077 bytes from file Logs/20250609T151419/Express0020.lzma
2025-06-09T16:03:08.680Z,1749484988.680 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Express0020.lzma.bak
2025-06-09T16:03:08.680Z,1749484988.680 [DataOverHttps](INFO): SBD MOMSN=25160514
2025-06-09T16:03:11.589Z,1749484991.589 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T16:03:25.225Z,1749485005.225 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20250609T151419/Express0023.lzma
2025-06-09T16:03:26.227Z,1749485006.227 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Express0023.lzma.bak
2025-06-09T16:03:26.228Z,1749485006.228 [DataOverHttps](INFO): SBD MOMSN=25160560
2025-06-09T16:03:27.355Z,1749485007.355 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T16:03:27.355Z,1749485007.355 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T16:03:27.355Z,1749485007.355 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T16:08:27.999Z,1749485307.999 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T16:08:27.999Z,1749485307.999 [Default:CheckIn:C.Wait] Stopped
2025-06-09T16:08:27.999Z,1749485307.999 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T16:08:27.999Z,1749485307.999 [Default:CheckIn:D] Running Loop=1
2025-06-09T16:08:28.395Z,1749485308.395 [Default:CheckIn:D] Stopped
2025-06-09T16:08:28.395Z,1749485308.395 [Default:CheckIn:E] Running Loop=1
2025-06-09T16:08:28.811Z,1749485308.811 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  50.284550 min 
2025-06-09T16:08:28.811Z,1749485308.811 [Default:CheckIn:E] Stopped
2025-06-09T16:08:28.811Z,1749485308.811 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T16:08:28.811Z,1749485308.811 [Default:CheckIn] Stopped
2025-06-09T16:08:28.811Z,1749485308.811 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T16:08:28.811Z,1749485308.811 [Default:CheckIn](INFO): Running loop #7
2025-06-09T16:08:28.811Z,1749485308.811 [Default:CheckIn] Running Loop=7
2025-06-09T16:08:28.811Z,1749485308.811 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T16:08:28.812Z,1749485308.812 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T16:08:30.810Z,1749485310.810 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160830.00,A,3648.14977,N,12147.22425,W,0.019,31.78,090625,,,D*4F
2025-06-09T16:08:30.813Z,1749485310.813 [NAL9602](INFO): GPS fix at 20250609T160830: (36.802496, -121.787071)
2025-06-09T16:08:30.840Z,1749485310.840 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T16:08:30.840Z,1749485310.840 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T16:08:38.565Z,1749485318.565 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250609T151419/Courier0025.lzma
2025-06-09T16:08:39.567Z,1749485319.567 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Courier0025.lzma.bak
2025-06-09T16:08:39.568Z,1749485319.568 [DataOverHttps](INFO): SBD MOMSN=25160713
2025-06-09T16:08:58.698Z,1749485338.698 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20250609T151419/Express0026.lzma
2025-06-09T16:08:59.700Z,1749485339.700 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Express0026.lzma.bak
2025-06-09T16:08:59.700Z,1749485339.700 [DataOverHttps](INFO): SBD MOMSN=25160716
2025-06-09T16:09:00.778Z,1749485340.778 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T16:09:00.778Z,1749485340.778 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T16:09:00.778Z,1749485340.778 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T16:09:13.710Z,1749485353.710 [NAL9602](INFO): SBD MO Status=0, MOMSN=63449, MT Status=0, MTMSN=0
2025-06-09T16:09:13.710Z,1749485353.710 [NAL9602](INFO): No messages in MT queue
2025-06-09T16:09:44.395Z,1749485384.395 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T16:14:01.350Z,1749485641.350 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T16:14:01.350Z,1749485641.350 [Default:CheckIn:C.Wait] Stopped
2025-06-09T16:14:01.350Z,1749485641.350 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T16:14:01.351Z,1749485641.351 [Default:CheckIn:D] Running Loop=1
2025-06-09T16:14:01.754Z,1749485641.754 [Default:CheckIn:D] Stopped
2025-06-09T16:14:01.754Z,1749485641.754 [Default:CheckIn:E] Running Loop=1
2025-06-09T16:14:02.167Z,1749485642.167 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  55.840568 min 
2025-06-09T16:14:02.167Z,1749485642.167 [Default:CheckIn:E] Stopped
2025-06-09T16:14:02.167Z,1749485642.167 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T16:14:02.167Z,1749485642.167 [Default:CheckIn] Stopped
2025-06-09T16:14:02.167Z,1749485642.167 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T16:14:02.167Z,1749485642.167 [Default:CheckIn](INFO): Running loop #8
2025-06-09T16:14:02.167Z,1749485642.167 [Default:CheckIn] Running Loop=8
2025-06-09T16:14:02.168Z,1749485642.168 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T16:14:02.168Z,1749485642.168 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T16:14:04.169Z,1749485644.169 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161403.00,A,3648.14860,N,12147.22775,W,0.000,31.78,090625,,,D*4B
2025-06-09T16:14:04.171Z,1749485644.171 [NAL9602](INFO): GPS fix at 20250609T161403: (36.802477, -121.787129)
2025-06-09T16:14:04.198Z,1749485644.198 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T16:14:04.198Z,1749485644.198 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T16:14:11.345Z,1749485651.345 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250609T151419/Courier0028.lzma
2025-06-09T16:14:12.347Z,1749485652.347 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Courier0028.lzma.bak
2025-06-09T16:14:12.348Z,1749485652.348 [DataOverHttps](INFO): SBD MOMSN=25160791
2025-06-09T16:14:17.092Z,1749485657.092 [NAL9602](INFO): SBD MO Status=0, MOMSN=63450, MT Status=0, MTMSN=0
2025-06-09T16:14:17.092Z,1749485657.092 [NAL9602](INFO): No messages in MT queue
2025-06-09T16:14:31.787Z,1749485671.787 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250609T151419/Express0029.lzma
2025-06-09T16:14:32.783Z,1749485672.783 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Express0029.lzma.bak
2025-06-09T16:14:32.784Z,1749485672.784 [DataOverHttps](INFO): SBD MOMSN=25160797
2025-06-09T16:14:34.126Z,1749485674.126 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T16:14:34.127Z,1749485674.127 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T16:14:34.127Z,1749485674.127 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T16:14:47.851Z,1749485687.851 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T16:19:34.716Z,1749485974.716 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T16:19:34.716Z,1749485974.716 [Default:CheckIn:C.Wait] Stopped
2025-06-09T16:19:34.716Z,1749485974.716 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T16:19:34.717Z,1749485974.717 [Default:CheckIn:D] Running Loop=1
2025-06-09T16:19:35.113Z,1749485975.113 [Default:CheckIn:D] Stopped
2025-06-09T16:19:35.113Z,1749485975.113 [Default:CheckIn:E] Running Loop=1
2025-06-09T16:19:35.522Z,1749485975.522 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  61.396554 min 
2025-06-09T16:19:35.522Z,1749485975.522 [Default:CheckIn:E] Stopped
2025-06-09T16:19:35.522Z,1749485975.522 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T16:19:35.522Z,1749485975.522 [Default:CheckIn] Stopped
2025-06-09T16:19:35.522Z,1749485975.522 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T16:19:35.522Z,1749485975.522 [Default:CheckIn](INFO): Running loop #9
2025-06-09T16:19:35.522Z,1749485975.522 [Default:CheckIn] Running Loop=9
2025-06-09T16:19:35.522Z,1749485975.522 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T16:19:35.523Z,1749485975.523 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T16:19:37.537Z,1749485977.537 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161936.00,A,3648.14992,N,12147.22589,W,0.019,31.78,090625,,,D*45
2025-06-09T16:19:37.539Z,1749485977.539 [NAL9602](INFO): GPS fix at 20250609T161936: (36.802499, -121.787098)
2025-06-09T16:19:37.550Z,1749485977.550 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T16:19:37.550Z,1749485977.550 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T16:19:45.369Z,1749485985.369 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250609T151419/Courier0031.lzma
2025-06-09T16:19:46.371Z,1749485986.371 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Courier0031.lzma.bak
2025-06-09T16:19:46.372Z,1749485986.372 [DataOverHttps](INFO): SBD MOMSN=25160892
2025-06-09T16:20:01.768Z,1749486001.768 [NAL9602](INFO): SBD MO Status=0, MOMSN=63451, MT Status=0, MTMSN=0
2025-06-09T16:20:01.768Z,1749486001.768 [NAL9602](INFO): No messages in MT queue
2025-06-09T16:20:02.490Z,1749486002.490 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250609T151419/Express0032.lzma
2025-06-09T16:20:03.492Z,1749486003.492 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Express0032.lzma.bak
2025-06-09T16:20:03.492Z,1749486003.492 [DataOverHttps](INFO): SBD MOMSN=25160899
2025-06-09T16:20:04.613Z,1749486004.613 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T16:20:04.613Z,1749486004.613 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T16:20:04.614Z,1749486004.614 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T16:20:32.479Z,1749486032.479 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T16:25:05.218Z,1749486305.218 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T16:25:05.218Z,1749486305.218 [Default:CheckIn:C.Wait] Stopped
2025-06-09T16:25:05.218Z,1749486305.218 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T16:25:05.218Z,1749486305.218 [Default:CheckIn:D] Running Loop=1
2025-06-09T16:25:05.617Z,1749486305.617 [Default:CheckIn:D] Stopped
2025-06-09T16:25:05.617Z,1749486305.617 [Default:CheckIn:E] Running Loop=1
2025-06-09T16:25:06.025Z,1749486306.025 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  66.904952 min 
2025-06-09T16:25:06.025Z,1749486306.025 [Default:CheckIn:E] Stopped
2025-06-09T16:25:06.025Z,1749486306.025 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T16:25:06.025Z,1749486306.025 [Default:CheckIn] Stopped
2025-06-09T16:25:06.025Z,1749486306.025 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T16:25:06.030Z,1749486306.030 [Default:CheckIn](INFO): Running loop #10
2025-06-09T16:25:06.030Z,1749486306.030 [Default:CheckIn] Running Loop=10
2025-06-09T16:25:06.030Z,1749486306.030 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T16:25:06.030Z,1749486306.030 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T16:25:08.032Z,1749486308.032 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162507.00,A,3648.15064,N,12147.22419,W,0.078,31.78,090625,,,D*46
2025-06-09T16:25:08.035Z,1749486308.035 [NAL9602](INFO): GPS fix at 20250609T162507: (36.802511, -121.787070)
2025-06-09T16:25:08.064Z,1749486308.064 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T16:25:08.064Z,1749486308.064 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T16:25:15.417Z,1749486315.417 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250609T151419/Courier0034.lzma
2025-06-09T16:25:16.420Z,1749486316.420 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Courier0034.lzma.bak
2025-06-09T16:25:16.420Z,1749486316.420 [DataOverHttps](INFO): SBD MOMSN=25160946
2025-06-09T16:25:21.765Z,1749486321.765 [NAL9602](INFO): SBD MO Status=2, MOMSN=63452, MT Status=2, MTMSN=0
2025-06-09T16:25:21.765Z,1749486321.765 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-09T16:25:32.370Z,1749486332.370 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250609T151419/Express0035.lzma
2025-06-09T16:25:33.372Z,1749486333.372 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Express0035.lzma.bak
2025-06-09T16:25:33.372Z,1749486333.372 [DataOverHttps](INFO): SBD MOMSN=25160949
2025-06-09T16:25:34.709Z,1749486334.709 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T16:25:34.709Z,1749486334.709 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T16:25:34.709Z,1749486334.709 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T16:25:42.373Z,1749486342.373 [NAL9602](INFO): SBD MO Status=0, MOMSN=63452, MT Status=0, MTMSN=0
2025-06-09T16:25:42.374Z,1749486342.374 [NAL9602](INFO): No messages in MT queue
2025-06-09T16:26:13.076Z,1749486373.076 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T16:30:35.281Z,1749486635.281 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T16:30:35.282Z,1749486635.282 [Default:CheckIn:C.Wait] Stopped
2025-06-09T16:30:35.282Z,1749486635.282 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T16:30:35.282Z,1749486635.282 [Default:CheckIn:D] Running Loop=1
2025-06-09T16:30:35.681Z,1749486635.681 [Default:CheckIn:D] Stopped
2025-06-09T16:30:35.681Z,1749486635.681 [Default:CheckIn:E] Running Loop=1
2025-06-09T16:30:36.082Z,1749486636.082 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  72.406014 min 
2025-06-09T16:30:36.082Z,1749486636.082 [Default:CheckIn:E] Stopped
2025-06-09T16:30:36.082Z,1749486636.082 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T16:30:36.082Z,1749486636.082 [Default:CheckIn] Stopped
2025-06-09T16:30:36.082Z,1749486636.082 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T16:30:36.082Z,1749486636.082 [Default:CheckIn](INFO): Running loop #11
2025-06-09T16:30:36.082Z,1749486636.082 [Default:CheckIn] Running Loop=11
2025-06-09T16:30:36.082Z,1749486636.082 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T16:30:36.082Z,1749486636.082 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T16:30:38.094Z,1749486638.094 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163037.00,A,3648.15029,N,12147.22383,W,0.039,31.78,090625,,,D*49
2025-06-09T16:30:38.097Z,1749486638.097 [NAL9602](INFO): GPS fix at 20250609T163037: (36.802505, -121.787064)
2025-06-09T16:30:38.107Z,1749486638.107 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T16:30:38.107Z,1749486638.107 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T16:30:45.729Z,1749486645.729 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250609T151419/Courier0037.lzma
2025-06-09T16:30:46.732Z,1749486646.732 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Courier0037.lzma.bak
2025-06-09T16:30:46.732Z,1749486646.732 [DataOverHttps](INFO): SBD MOMSN=25161044
2025-06-09T16:30:56.364Z,1749486656.364 [NAL9602](INFO): SBD MO Status=0, MOMSN=63453, MT Status=0, MTMSN=0
2025-06-09T16:30:56.364Z,1749486656.364 [NAL9602](INFO): No messages in MT queue
2025-06-09T16:31:05.966Z,1749486665.966 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250609T151419/Express0038.lzma
2025-06-09T16:31:06.968Z,1749486666.968 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Express0038.lzma.bak
2025-06-09T16:31:06.968Z,1749486666.968 [DataOverHttps](INFO): SBD MOMSN=25161047
2025-06-09T16:31:08.077Z,1749486668.077 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T16:31:08.077Z,1749486668.077 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T16:31:08.077Z,1749486668.077 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T16:31:27.046Z,1749486687.046 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T16:36:08.661Z,1749486968.661 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T16:36:08.661Z,1749486968.661 [Default:CheckIn:C.Wait] Stopped
2025-06-09T16:36:08.661Z,1749486968.661 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T16:36:08.661Z,1749486968.661 [Default:CheckIn:D] Running Loop=1
2025-06-09T16:36:09.069Z,1749486969.069 [Default:CheckIn:D] Stopped
2025-06-09T16:36:09.069Z,1749486969.069 [Default:CheckIn:E] Running Loop=1
2025-06-09T16:36:09.466Z,1749486969.466 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  77.962484 min 
2025-06-09T16:36:09.466Z,1749486969.466 [Default:CheckIn:E] Stopped
2025-06-09T16:36:09.466Z,1749486969.466 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T16:36:09.466Z,1749486969.466 [Default:CheckIn] Stopped
2025-06-09T16:36:09.466Z,1749486969.466 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T16:36:09.466Z,1749486969.466 [Default:CheckIn](INFO): Running loop #12
2025-06-09T16:36:09.466Z,1749486969.466 [Default:CheckIn] Running Loop=12
2025-06-09T16:36:09.466Z,1749486969.466 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T16:36:09.467Z,1749486969.467 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T16:36:11.475Z,1749486971.475 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163610.00,A,3648.15070,N,12147.22574,W,0.039,31.78,090625,,,D*48
2025-06-09T16:36:11.477Z,1749486971.477 [NAL9602](INFO): GPS fix at 20250609T163610: (36.802512, -121.787096)
2025-06-09T16:36:11.487Z,1749486971.487 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T16:36:11.488Z,1749486971.488 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T16:36:19.645Z,1749486979.645 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250609T151419/Courier0040.lzma
2025-06-09T16:36:20.648Z,1749486980.648 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Courier0040.lzma.bak
2025-06-09T16:36:20.648Z,1749486980.648 [DataOverHttps](INFO): SBD MOMSN=25161064
2025-06-09T16:36:30.114Z,1749486990.114 [NAL9602](INFO): SBD MO Status=2, MOMSN=63454, MT Status=2, MTMSN=0
2025-06-09T16:36:30.114Z,1749486990.114 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-09T16:36:39.446Z,1749486999.446 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250609T151419/Express0041.lzma
2025-06-09T16:36:40.448Z,1749487000.448 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Express0041.lzma.bak
2025-06-09T16:36:40.448Z,1749487000.448 [DataOverHttps](INFO): SBD MOMSN=25161067
2025-06-09T16:36:41.837Z,1749487001.837 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T16:36:41.837Z,1749487001.837 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T16:36:41.837Z,1749487001.837 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T16:37:16.182Z,1749487036.182 [NAL9602](INFO): SBD MO Status=2, MOMSN=63454, MT Status=2, MTMSN=0
2025-06-09T16:37:16.182Z,1749487036.182 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-09T16:38:10.714Z,1749487090.714 [NAL9602](INFO): SBD MO Status=2, MOMSN=63454, MT Status=2, MTMSN=0
2025-06-09T16:38:10.714Z,1749487090.714 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-09T16:38:29.316Z,1749487109.316 [NAL9602](INFO): SBD MO Status=0, MOMSN=63454, MT Status=0, MTMSN=0
2025-06-09T16:38:29.317Z,1749487109.317 [NAL9602](INFO): No messages in MT queue
2025-06-09T16:39:00.047Z,1749487140.047 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T16:41:42.585Z,1749487302.585 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T16:41:42.585Z,1749487302.585 [Default:CheckIn:C.Wait] Stopped
2025-06-09T16:41:42.585Z,1749487302.585 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T16:41:42.585Z,1749487302.585 [Default:CheckIn:D] Running Loop=1
2025-06-09T16:41:43.001Z,1749487303.001 [Default:CheckIn:D] Stopped
2025-06-09T16:41:43.001Z,1749487303.001 [Default:CheckIn:E] Running Loop=1
2025-06-09T16:41:43.413Z,1749487303.413 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  83.527995 min 
2025-06-09T16:41:43.413Z,1749487303.413 [Default:CheckIn:E] Stopped
2025-06-09T16:41:43.413Z,1749487303.413 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T16:41:43.413Z,1749487303.413 [Default:CheckIn] Stopped
2025-06-09T16:41:43.414Z,1749487303.414 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T16:41:43.414Z,1749487303.414 [Default:CheckIn](INFO): Running loop #13
2025-06-09T16:41:43.414Z,1749487303.414 [Default:CheckIn] Running Loop=13
2025-06-09T16:41:43.414Z,1749487303.414 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T16:41:43.414Z,1749487303.414 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T16:41:45.412Z,1749487305.412 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164144.00,A,3648.15048,N,12147.22559,W,0.039,0.00,090625,,,D*70
2025-06-09T16:41:45.414Z,1749487305.414 [NAL9602](INFO): GPS fix at 20250609T164144: (36.802508, -121.787093)
2025-06-09T16:41:45.450Z,1749487305.450 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T16:41:45.450Z,1749487305.450 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T16:41:53.361Z,1749487313.361 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250609T151419/Courier0043.lzma
2025-06-09T16:41:54.364Z,1749487314.364 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Courier0043.lzma.bak
2025-06-09T16:41:54.364Z,1749487314.364 [DataOverHttps](INFO): SBD MOMSN=25161172
2025-06-09T16:41:57.528Z,1749487317.528 [NAL9602](INFO): SBD MO Status=0, MOMSN=63455, MT Status=0, MTMSN=0
2025-06-09T16:41:57.528Z,1749487317.528 [NAL9602](INFO): No messages in MT queue
2025-06-09T16:42:10.714Z,1749487330.714 [DataOverHttps](INFO): Sending 148 bytes from file Logs/20250609T151419/Express0044.lzma
2025-06-09T16:42:11.716Z,1749487331.716 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Express0044.lzma.bak
2025-06-09T16:42:11.716Z,1749487331.716 [DataOverHttps](INFO): SBD MOMSN=25161178
2025-06-09T16:42:12.902Z,1749487332.902 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T16:42:12.902Z,1749487332.902 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T16:42:12.902Z,1749487332.902 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T16:42:28.235Z,1749487348.235 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T16:46:40.054Z,1749487600.054 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T16:46:51.406Z,1749487611.406 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T16:47:03.554Z,1749487623.554 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T16:47:13.698Z,1749487633.698 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T16:47:13.698Z,1749487633.698 [Default:CheckIn:C.Wait] Stopped
2025-06-09T16:47:13.698Z,1749487633.698 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T16:47:13.698Z,1749487633.698 [Default:CheckIn:D] Running Loop=1
2025-06-09T16:47:14.078Z,1749487634.078 [Default:CheckIn:D] Stopped
2025-06-09T16:47:14.078Z,1749487634.078 [Default:CheckIn:E] Running Loop=1
2025-06-09T16:47:14.477Z,1749487634.477 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  89.045972 min 
2025-06-09T16:47:14.477Z,1749487634.477 [Default:CheckIn:E] Stopped
2025-06-09T16:47:14.477Z,1749487634.477 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T16:47:14.477Z,1749487634.477 [Default:CheckIn] Stopped
2025-06-09T16:47:14.477Z,1749487634.477 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T16:47:14.478Z,1749487634.478 [Default:CheckIn](INFO): Running loop #14
2025-06-09T16:47:14.478Z,1749487634.478 [Default:CheckIn] Running Loop=14
2025-06-09T16:47:14.478Z,1749487634.478 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T16:47:14.478Z,1749487634.478 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T16:47:15.269Z,1749487635.269 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T16:47:16.504Z,1749487636.504 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164715.00,A,3648.15058,N,12147.22601,W,0.058,0.00,090625,,,D*7A
2025-06-09T16:47:16.507Z,1749487636.507 [NAL9602](INFO): GPS fix at 20250609T164715: (36.802510, -121.787100)
2025-06-09T16:47:16.518Z,1749487636.518 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T16:47:16.518Z,1749487636.518 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T16:47:24.241Z,1749487644.241 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250609T151419/Courier0046.lzma
2025-06-09T16:47:25.244Z,1749487645.244 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Courier0046.lzma.bak
2025-06-09T16:47:25.245Z,1749487645.245 [DataOverHttps](INFO): SBD MOMSN=25161208
2025-06-09T16:47:27.391Z,1749487647.391 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T16:47:33.056Z,1749487653.056 [NAL9602](INFO): SBD MO Status=0, MOMSN=63456, MT Status=0, MTMSN=0
2025-06-09T16:47:33.056Z,1749487653.056 [NAL9602](INFO): No messages in MT queue
2025-06-09T16:47:39.105Z,1749487659.105 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T16:47:41.281Z,1749487661.281 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250609T151419/Express0047.lzma
2025-06-09T16:47:42.284Z,1749487662.284 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Express0047.lzma.bak
2025-06-09T16:47:42.284Z,1749487662.284 [DataOverHttps](INFO): SBD MOMSN=25161211
2025-06-09T16:47:43.583Z,1749487663.583 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T16:47:43.583Z,1749487663.583 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T16:47:43.583Z,1749487663.583 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T16:47:50.417Z,1749487670.417 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T16:48:02.134Z,1749487682.134 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T16:48:03.771Z,1749487683.771 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T16:50:37.803Z,1749487837.803 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T16:50:51.957Z,1749487851.957 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T16:51:06.138Z,1749487866.138 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T16:51:19.461Z,1749487879.461 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T16:51:33.206Z,1749487893.206 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T16:51:46.530Z,1749487906.530 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T16:51:59.862Z,1749487919.862 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T16:52:13.223Z,1749487933.223 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T16:52:26.581Z,1749487946.581 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T16:52:39.913Z,1749487959.913 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T16:52:44.381Z,1749487964.381 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T16:52:44.382Z,1749487964.382 [Default:CheckIn:C.Wait] Stopped
2025-06-09T16:52:44.382Z,1749487964.382 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T16:52:44.382Z,1749487964.382 [Default:CheckIn:D] Running Loop=1
2025-06-09T16:52:44.791Z,1749487964.791 [Default:CheckIn:D] Stopped
2025-06-09T16:52:44.791Z,1749487964.791 [Default:CheckIn:E] Running Loop=1
2025-06-09T16:52:45.177Z,1749487965.177 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  94.557764 min 
2025-06-09T16:52:45.177Z,1749487965.177 [Default:CheckIn:E] Stopped
2025-06-09T16:52:45.178Z,1749487965.178 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T16:52:45.178Z,1749487965.178 [Default:CheckIn] Stopped
2025-06-09T16:52:45.178Z,1749487965.178 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T16:52:45.178Z,1749487965.178 [Default:CheckIn](INFO): Running loop #15
2025-06-09T16:52:45.178Z,1749487965.178 [Default:CheckIn] Running Loop=15
2025-06-09T16:52:45.178Z,1749487965.178 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T16:52:45.178Z,1749487965.178 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T16:52:47.190Z,1749487967.190 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165246.00,A,3648.15176,N,12147.22545,W,0.000,0.00,090625,,,D*7B
2025-06-09T16:52:47.192Z,1749487967.192 [NAL9602](INFO): GPS fix at 20250609T165246: (36.802529, -121.787091)
2025-06-09T16:52:47.208Z,1749487967.208 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T16:52:47.208Z,1749487967.208 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T16:52:53.745Z,1749487973.745 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7).
2025-06-09T16:52:57.718Z,1749487977.718 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250609T151419/Courier0049.lzma
2025-06-09T16:52:58.720Z,1749487978.720 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Courier0049.lzma.bak
2025-06-09T16:52:58.720Z,1749487978.720 [DataOverHttps](INFO): SBD MOMSN=25161300
2025-06-09T16:53:01.464Z,1749487981.464 [NAL9602](INFO): SBD MO Status=0, MOMSN=63457, MT Status=0, MTMSN=0
2025-06-09T16:53:01.464Z,1749487981.464 [NAL9602](INFO): No messages in MT queue
2025-06-09T16:53:14.954Z,1749487994.954 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250609T151419/Express0050.lzma
2025-06-09T16:53:15.956Z,1749487995.956 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Express0050.lzma.bak
2025-06-09T16:53:15.956Z,1749487995.956 [DataOverHttps](INFO): SBD MOMSN=25161303
2025-06-09T16:53:17.251Z,1749487997.251 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T16:53:17.251Z,1749487997.251 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T16:53:17.251Z,1749487997.251 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T16:53:32.156Z,1749488012.156 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T16:58:17.998Z,1749488297.998 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T16:58:17.999Z,1749488297.999 [Default:CheckIn:C.Wait] Stopped
2025-06-09T16:58:17.999Z,1749488297.999 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T16:58:17.999Z,1749488297.999 [Default:CheckIn:D] Running Loop=1
2025-06-09T16:58:18.409Z,1749488298.409 [Default:CheckIn:D] Stopped
2025-06-09T16:58:18.409Z,1749488298.409 [Default:CheckIn:E] Running Loop=1
2025-06-09T16:58:18.826Z,1749488298.826 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  100.118156 min 
2025-06-09T16:58:18.826Z,1749488298.826 [Default:CheckIn:E] Stopped
2025-06-09T16:58:18.826Z,1749488298.826 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T16:58:18.826Z,1749488298.826 [Default:CheckIn] Stopped
2025-06-09T16:58:18.827Z,1749488298.827 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T16:58:18.827Z,1749488298.827 [Default:CheckIn](INFO): Running loop #16
2025-06-09T16:58:18.827Z,1749488298.827 [Default:CheckIn] Running Loop=16
2025-06-09T16:58:18.827Z,1749488298.827 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T16:58:18.827Z,1749488298.827 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T16:58:20.812Z,1749488300.812 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165820.00,A,3648.15168,N,12147.22517,W,0.039,0.00,090625,,,D*73
2025-06-09T16:58:20.822Z,1749488300.822 [NAL9602](INFO): GPS fix at 20250609T165820: (36.802528, -121.787086)
2025-06-09T16:58:20.833Z,1749488300.833 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T16:58:20.833Z,1749488300.833 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T16:58:30.409Z,1749488310.409 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250609T151419/Courier0052.lzma
2025-06-09T16:58:31.411Z,1749488311.411 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Courier0052.lzma.bak
2025-06-09T16:58:31.412Z,1749488311.412 [DataOverHttps](INFO): SBD MOMSN=25161322
2025-06-09T16:58:37.536Z,1749488317.536 [NAL9602](INFO): SBD MO Status=0, MOMSN=63458, MT Status=0, MTMSN=0
2025-06-09T16:58:37.537Z,1749488317.537 [NAL9602](INFO): No messages in MT queue
2025-06-09T16:58:47.434Z,1749488327.434 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250609T151419/Express0053.lzma
2025-06-09T16:58:48.436Z,1749488328.436 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Express0053.lzma.bak
2025-06-09T16:58:48.436Z,1749488328.436 [DataOverHttps](INFO): SBD MOMSN=25161326
2025-06-09T16:58:49.679Z,1749488329.679 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T16:58:49.679Z,1749488329.679 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T16:58:49.679Z,1749488329.679 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T16:59:08.291Z,1749488348.291 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T17:00:14.137Z,1749488414.137 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T17:00:28.317Z,1749488428.317 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T17:00:41.658Z,1749488441.658 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T17:00:55.006Z,1749488455.006 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T17:01:08.355Z,1749488468.355 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T17:01:21.686Z,1749488481.686 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T17:01:35.427Z,1749488495.427 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T17:01:49.563Z,1749488509.563 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T17:02:03.298Z,1749488523.298 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T17:02:17.033Z,1749488537.033 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T17:02:31.180Z,1749488551.180 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7).
2025-06-09T17:03:50.374Z,1749488630.374 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T17:03:50.374Z,1749488630.374 [Default:CheckIn:C.Wait] Stopped
2025-06-09T17:03:50.374Z,1749488630.374 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T17:03:50.375Z,1749488630.375 [Default:CheckIn:D] Running Loop=1
2025-06-09T17:03:50.785Z,1749488630.785 [Default:CheckIn:D] Stopped
2025-06-09T17:03:50.786Z,1749488630.786 [Default:CheckIn:E] Running Loop=1
2025-06-09T17:03:51.241Z,1749488631.241 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  105.657756 min 
2025-06-09T17:03:51.241Z,1749488631.241 [Default:CheckIn:E] Stopped
2025-06-09T17:03:51.242Z,1749488631.242 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T17:03:51.242Z,1749488631.242 [Default:CheckIn] Stopped
2025-06-09T17:03:51.242Z,1749488631.242 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T17:03:51.242Z,1749488631.242 [Default:CheckIn](INFO): Running loop #17
2025-06-09T17:03:51.242Z,1749488631.242 [Default:CheckIn] Running Loop=17
2025-06-09T17:03:51.242Z,1749488631.242 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T17:03:51.242Z,1749488631.242 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T17:03:53.193Z,1749488633.193 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170352.00,A,3648.15153,N,12147.22689,W,0.019,0.00,090625,,,D*77
2025-06-09T17:03:53.195Z,1749488633.195 [NAL9602](INFO): GPS fix at 20250609T170352: (36.802526, -121.787115)
2025-06-09T17:03:53.207Z,1749488633.207 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T17:03:53.207Z,1749488633.207 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T17:04:00.368Z,1749488640.368 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250609T151419/Courier0055.lzma
2025-06-09T17:04:01.367Z,1749488641.367 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Courier0055.lzma.bak
2025-06-09T17:04:01.368Z,1749488641.368 [DataOverHttps](INFO): SBD MOMSN=25161420
2025-06-09T17:04:11.382Z,1749488651.382 [NAL9602](INFO): SBD MO Status=0, MOMSN=63459, MT Status=0, MTMSN=0
2025-06-09T17:04:11.382Z,1749488651.382 [NAL9602](INFO): No messages in MT queue
2025-06-09T17:04:17.906Z,1749488657.906 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250609T151419/Express0056.lzma
2025-06-09T17:04:18.908Z,1749488658.908 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Express0056.lzma.bak
2025-06-09T17:04:18.908Z,1749488658.908 [DataOverHttps](INFO): SBD MOMSN=25161423
2025-06-09T17:04:20.280Z,1749488660.280 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T17:04:20.280Z,1749488660.280 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T17:04:20.280Z,1749488660.280 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T17:04:42.113Z,1749488682.113 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T17:09:20.949Z,1749488960.949 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T17:09:20.949Z,1749488960.949 [Default:CheckIn:C.Wait] Stopped
2025-06-09T17:09:20.950Z,1749488960.950 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T17:09:20.950Z,1749488960.950 [Default:CheckIn:D] Running Loop=1
2025-06-09T17:09:21.418Z,1749488961.418 [Default:CheckIn:D] Stopped
2025-06-09T17:09:21.419Z,1749488961.419 [Default:CheckIn:E] Running Loop=1
2025-06-09T17:09:21.767Z,1749488961.767 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  111.168311 min 
2025-06-09T17:09:21.767Z,1749488961.767 [Default:CheckIn:E] Stopped
2025-06-09T17:09:21.767Z,1749488961.767 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T17:09:21.767Z,1749488961.767 [Default:CheckIn] Stopped
2025-06-09T17:09:21.767Z,1749488961.767 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T17:09:21.767Z,1749488961.767 [Default:CheckIn](INFO): Running loop #18
2025-06-09T17:09:21.767Z,1749488961.767 [Default:CheckIn] Running Loop=18
2025-06-09T17:09:21.768Z,1749488961.768 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T17:09:21.768Z,1749488961.768 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T17:09:23.778Z,1749488963.778 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170923.00,A,3648.15066,N,12147.22484,W,0.039,0.00,090625,,,D*71
2025-06-09T17:09:23.780Z,1749488963.780 [NAL9602](INFO): GPS fix at 20250609T170923: (36.802511, -121.787081)
2025-06-09T17:09:23.792Z,1749488963.792 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T17:09:23.792Z,1749488963.792 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T17:09:31.173Z,1749488971.173 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250609T151419/Courier0058.lzma
2025-06-09T17:09:32.176Z,1749488972.176 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Courier0058.lzma.bak
2025-06-09T17:09:32.176Z,1749488972.176 [DataOverHttps](INFO): SBD MOMSN=25161441
2025-06-09T17:09:36.297Z,1749488976.297 [NAL9602](INFO): SBD MO Status=0, MOMSN=63460, MT Status=0, MTMSN=0
2025-06-09T17:09:36.297Z,1749488976.297 [NAL9602](INFO): No messages in MT queue
2025-06-09T17:09:48.138Z,1749488988.138 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250609T151419/Express0059.lzma
2025-06-09T17:09:48.814Z,1749488988.814 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T17:09:49.140Z,1749488989.140 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Express0059.lzma.bak
2025-06-09T17:09:49.140Z,1749488989.140 [DataOverHttps](INFO): SBD MOMSN=25161444
2025-06-09T17:09:50.451Z,1749488990.451 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T17:09:50.451Z,1749488990.451 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T17:09:50.451Z,1749488990.451 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T17:10:02.146Z,1749489002.146 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T17:10:07.003Z,1749489007.003 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T17:10:15.884Z,1749489015.884 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T17:10:29.282Z,1749489029.282 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T17:10:42.610Z,1749489042.610 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T17:10:55.953Z,1749489055.953 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T17:11:09.298Z,1749489069.298 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T17:11:22.630Z,1749489082.630 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T17:11:36.366Z,1749489096.366 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T17:11:49.699Z,1749489109.699 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T17:12:03.434Z,1749489123.434 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T17:14:51.179Z,1749489291.179 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T17:14:51.179Z,1749489291.179 [Default:CheckIn:C.Wait] Stopped
2025-06-09T17:14:51.179Z,1749489291.179 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T17:14:51.179Z,1749489291.179 [Default:CheckIn:D] Running Loop=1
2025-06-09T17:14:51.542Z,1749489291.542 [Default:CheckIn:D] Stopped
2025-06-09T17:14:51.543Z,1749489291.543 [Default:CheckIn:E] Running Loop=1
2025-06-09T17:14:51.947Z,1749489291.947 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  116.670378 min 
2025-06-09T17:14:51.947Z,1749489291.947 [Default:CheckIn:E] Stopped
2025-06-09T17:14:51.947Z,1749489291.947 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T17:14:51.948Z,1749489291.948 [Default:CheckIn] Stopped
2025-06-09T17:14:51.948Z,1749489291.948 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T17:14:51.948Z,1749489291.948 [Default:CheckIn](INFO): Running loop #19
2025-06-09T17:14:51.948Z,1749489291.948 [Default:CheckIn] Running Loop=19
2025-06-09T17:14:51.948Z,1749489291.948 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T17:14:51.948Z,1749489291.948 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T17:14:53.949Z,1749489293.949 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171453.00,A,3648.15154,N,12147.22462,W,0.019,0.00,090625,,,D*70
2025-06-09T17:14:53.951Z,1749489293.951 [NAL9602](INFO): GPS fix at 20250609T171453: (36.802526, -121.787077)
2025-06-09T17:14:53.992Z,1749489293.992 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T17:14:53.992Z,1749489293.992 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T17:15:02.430Z,1749489302.430 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250609T151419/Courier0061.lzma
2025-06-09T17:15:03.432Z,1749489303.432 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Courier0061.lzma.bak
2025-06-09T17:15:03.432Z,1749489303.432 [DataOverHttps](INFO): SBD MOMSN=25161543
2025-06-09T17:15:19.526Z,1749489319.526 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250609T151419/Express0062.lzma
2025-06-09T17:15:20.528Z,1749489320.528 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Express0062.lzma.bak
2025-06-09T17:15:20.528Z,1749489320.528 [DataOverHttps](INFO): SBD MOMSN=25161547
2025-06-09T17:15:20.644Z,1749489320.644 [NAL9602](INFO): SBD MO Status=0, MOMSN=63461, MT Status=0, MTMSN=0
2025-06-09T17:15:20.645Z,1749489320.645 [NAL9602](INFO): No messages in MT queue
2025-06-09T17:15:21.921Z,1749489321.921 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T17:15:21.922Z,1749489321.922 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T17:15:21.922Z,1749489321.922 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T17:15:51.344Z,1749489351.344 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T17:16:34.998Z,1749489394.998 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2025-06-09T17:16:35.000Z,1749489395.000 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2025-06-09T17:20:22.475Z,1749489622.475 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T17:20:22.475Z,1749489622.475 [Default:CheckIn:C.Wait] Stopped
2025-06-09T17:20:22.475Z,1749489622.475 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T17:20:22.475Z,1749489622.475 [Default:CheckIn:D] Running Loop=1
2025-06-09T17:20:22.882Z,1749489622.882 [Default:CheckIn:D] Stopped
2025-06-09T17:20:22.882Z,1749489622.882 [Default:CheckIn:E] Running Loop=1
2025-06-09T17:20:23.300Z,1749489623.300 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  122.192708 min 
2025-06-09T17:20:23.300Z,1749489623.300 [Default:CheckIn:E] Stopped
2025-06-09T17:20:23.300Z,1749489623.300 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T17:20:23.300Z,1749489623.300 [Default:CheckIn] Stopped
2025-06-09T17:20:23.300Z,1749489623.300 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T17:20:23.300Z,1749489623.300 [Default:CheckIn](INFO): Running loop #20
2025-06-09T17:20:23.300Z,1749489623.300 [Default:CheckIn] Running Loop=20
2025-06-09T17:20:23.300Z,1749489623.300 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T17:20:23.300Z,1749489623.300 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T17:20:25.293Z,1749489625.293 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172024.00,A,3648.15128,N,12147.22446,W,0.000,0.00,090625,,,D*72
2025-06-09T17:20:25.295Z,1749489625.295 [NAL9602](INFO): GPS fix at 20250609T172024: (36.802521, -121.787074)
2025-06-09T17:20:25.330Z,1749489625.330 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T17:20:25.331Z,1749489625.331 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T17:20:32.322Z,1749489632.322 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20250609T151419/Courier0064.lzma
2025-06-09T17:20:33.324Z,1749489633.324 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Courier0064.lzma.bak
2025-06-09T17:20:33.324Z,1749489633.324 [DataOverHttps](INFO): SBD MOMSN=25161574
2025-06-09T17:20:45.940Z,1749489645.940 [NAL9602](INFO): SBD MO Status=0, MOMSN=63462, MT Status=0, MTMSN=0
2025-06-09T17:20:45.940Z,1749489645.940 [NAL9602](INFO): No messages in MT queue
2025-06-09T17:20:50.862Z,1749489650.862 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250609T151419/Express0065.lzma
2025-06-09T17:20:51.864Z,1749489651.864 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Express0065.lzma.bak
2025-06-09T17:20:51.864Z,1749489651.864 [DataOverHttps](INFO): SBD MOMSN=25161585
2025-06-09T17:20:53.274Z,1749489653.274 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T17:20:53.274Z,1749489653.274 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T17:20:53.274Z,1749489653.274 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T17:21:16.671Z,1749489676.671 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T17:25:53.881Z,1749489953.881 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T17:25:53.881Z,1749489953.881 [Default:CheckIn:C.Wait] Stopped
2025-06-09T17:25:53.881Z,1749489953.881 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T17:25:53.881Z,1749489953.881 [Default:CheckIn:D] Running Loop=1
2025-06-09T17:25:54.287Z,1749489954.287 [Default:CheckIn:D] Stopped
2025-06-09T17:25:54.288Z,1749489954.288 [Default:CheckIn:E] Running Loop=1
2025-06-09T17:25:54.692Z,1749489954.692 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  127.716130 min 
2025-06-09T17:25:54.692Z,1749489954.692 [Default:CheckIn:E] Stopped
2025-06-09T17:25:54.692Z,1749489954.692 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T17:25:54.692Z,1749489954.692 [Default:CheckIn] Stopped
2025-06-09T17:25:54.692Z,1749489954.692 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T17:25:54.693Z,1749489954.693 [Default:CheckIn](INFO): Running loop #21
2025-06-09T17:25:54.693Z,1749489954.693 [Default:CheckIn] Running Loop=21
2025-06-09T17:25:54.693Z,1749489954.693 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T17:25:54.693Z,1749489954.693 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T17:25:56.702Z,1749489956.702 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172555.00,A,3648.15220,N,12147.22654,W,0.039,0.00,090625,,,D*71
2025-06-09T17:25:56.704Z,1749489956.704 [NAL9602](INFO): GPS fix at 20250609T172555: (36.802537, -121.787109)
2025-06-09T17:25:56.735Z,1749489956.735 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T17:25:56.735Z,1749489956.735 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T17:26:05.073Z,1749489965.073 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250609T151419/Courier0067.lzma
2025-06-09T17:26:11.096Z,1749489971.096 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Courier0067.lzma.bak
2025-06-09T17:26:11.096Z,1749489971.096 [DataOverHttps](INFO): SBD MOMSN=25161666
2025-06-09T17:26:17.308Z,1749489977.308 [NAL9602](INFO): SBD MO Status=0, MOMSN=63463, MT Status=0, MTMSN=0
2025-06-09T17:26:17.308Z,1749489977.308 [NAL9602](INFO): No messages in MT queue
2025-06-09T17:26:38.882Z,1749489998.882 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250609T151419/Express0068.lzma
2025-06-09T17:26:39.884Z,1749489999.884 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Express0068.lzma.bak
2025-06-09T17:26:39.884Z,1749489999.884 [DataOverHttps](INFO): SBD MOMSN=25161669
2025-06-09T17:26:41.162Z,1749490001.162 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T17:26:41.162Z,1749490001.162 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T17:26:41.162Z,1749490001.162 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T17:26:48.023Z,1749490008.023 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T17:31:41.877Z,1749490301.877 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T17:31:41.877Z,1749490301.877 [Default:CheckIn:C.Wait] Stopped
2025-06-09T17:31:41.877Z,1749490301.877 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T17:31:41.877Z,1749490301.877 [Default:CheckIn:D] Running Loop=1
2025-06-09T17:31:42.285Z,1749490302.285 [Default:CheckIn:D] Stopped
2025-06-09T17:31:42.285Z,1749490302.285 [Default:CheckIn:E] Running Loop=1
2025-06-09T17:31:42.683Z,1749490302.683 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  133.516089 min 
2025-06-09T17:31:42.683Z,1749490302.683 [Default:CheckIn:E] Stopped
2025-06-09T17:31:42.683Z,1749490302.683 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T17:31:42.683Z,1749490302.683 [Default:CheckIn] Stopped
2025-06-09T17:31:42.684Z,1749490302.684 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T17:31:42.684Z,1749490302.684 [Default:CheckIn](INFO): Running loop #22
2025-06-09T17:31:42.684Z,1749490302.684 [Default:CheckIn] Running Loop=22
2025-06-09T17:31:42.684Z,1749490302.684 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T17:31:42.684Z,1749490302.684 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T17:31:44.693Z,1749490304.693 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173143.00,A,3648.15148,N,12147.22580,W,0.019,0.00,090625,,,D*76
2025-06-09T17:31:44.695Z,1749490304.695 [NAL9602](INFO): GPS fix at 20250609T173143: (36.802525, -121.787097)
2025-06-09T17:31:44.734Z,1749490304.734 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T17:31:44.734Z,1749490304.734 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T17:31:52.790Z,1749490312.790 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250609T151419/Courier0070.lzma
2025-06-09T17:31:53.792Z,1749490313.792 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Courier0070.lzma.bak
2025-06-09T17:31:53.792Z,1749490313.792 [DataOverHttps](INFO): SBD MOMSN=25161749
2025-06-09T17:32:09.846Z,1749490329.846 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250609T151419/Express0071.lzma
2025-06-09T17:32:10.848Z,1749490330.848 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Express0071.lzma.bak
2025-06-09T17:32:10.848Z,1749490330.848 [DataOverHttps](INFO): SBD MOMSN=25161752
2025-06-09T17:32:12.177Z,1749490332.177 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T17:32:12.177Z,1749490332.177 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T17:32:12.177Z,1749490332.177 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T17:32:39.641Z,1749490359.641 [NAL9602](INFO): SBD MO Status=0, MOMSN=63464, MT Status=0, MTMSN=0
2025-06-09T17:32:39.642Z,1749490359.642 [NAL9602](INFO): No messages in MT queue
2025-06-09T17:33:10.352Z,1749490390.352 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T17:37:12.949Z,1749490632.949 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T17:37:12.949Z,1749490632.949 [Default:CheckIn:C.Wait] Stopped
2025-06-09T17:37:12.949Z,1749490632.949 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T17:37:12.949Z,1749490632.949 [Default:CheckIn:D] Running Loop=1
2025-06-09T17:37:13.362Z,1749490633.362 [Default:CheckIn:D] Stopped
2025-06-09T17:37:13.362Z,1749490633.362 [Default:CheckIn:E] Running Loop=1
2025-06-09T17:37:13.755Z,1749490633.755 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  139.034049 min 
2025-06-09T17:37:13.755Z,1749490633.755 [Default:CheckIn:E] Stopped
2025-06-09T17:37:13.755Z,1749490633.755 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T17:37:13.755Z,1749490633.755 [Default:CheckIn] Stopped
2025-06-09T17:37:13.755Z,1749490633.755 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T17:37:13.755Z,1749490633.755 [Default:CheckIn](INFO): Running loop #23
2025-06-09T17:37:13.756Z,1749490633.756 [Default:CheckIn] Running Loop=23
2025-06-09T17:37:13.756Z,1749490633.756 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T17:37:13.756Z,1749490633.756 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T17:37:15.781Z,1749490635.781 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173715.00,A,3648.15028,N,12147.22598,W,0.019,0.00,090625,,,D*7D
2025-06-09T17:37:15.784Z,1749490635.784 [NAL9602](INFO): GPS fix at 20250609T173715: (36.802505, -121.787100)
2025-06-09T17:37:15.795Z,1749490635.795 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T17:37:15.795Z,1749490635.795 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T17:37:23.529Z,1749490643.529 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250609T151419/Courier0073.lzma
2025-06-09T17:37:24.532Z,1749490644.532 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Courier0073.lzma.bak
2025-06-09T17:37:24.532Z,1749490644.532 [DataOverHttps](INFO): SBD MOMSN=25161794
2025-06-09T17:37:29.504Z,1749490649.504 [NAL9602](INFO): SBD MO Status=0, MOMSN=63465, MT Status=0, MTMSN=0
2025-06-09T17:37:29.505Z,1749490649.505 [NAL9602](INFO): No messages in MT queue
2025-06-09T17:37:40.750Z,1749490660.750 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250609T151419/Express0074.lzma
2025-06-09T17:37:41.752Z,1749490661.752 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Express0074.lzma.bak
2025-06-09T17:37:41.752Z,1749490661.752 [DataOverHttps](INFO): SBD MOMSN=25161797
2025-06-09T17:37:42.844Z,1749490662.844 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T17:37:42.844Z,1749490662.844 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T17:37:42.844Z,1749490662.844 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T17:38:00.211Z,1749490680.211 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T17:42:43.641Z,1749490963.641 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T17:42:43.641Z,1749490963.641 [Default:CheckIn:C.Wait] Stopped
2025-06-09T17:42:43.641Z,1749490963.641 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T17:42:43.641Z,1749490963.641 [Default:CheckIn:D] Running Loop=1
2025-06-09T17:42:44.022Z,1749490964.022 [Default:CheckIn:D] Stopped
2025-06-09T17:42:44.022Z,1749490964.022 [Default:CheckIn:E] Running Loop=1
2025-06-09T17:42:44.429Z,1749490964.429 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  144.545036 min 
2025-06-09T17:42:44.429Z,1749490964.429 [Default:CheckIn:E] Stopped
2025-06-09T17:42:44.429Z,1749490964.429 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T17:42:44.430Z,1749490964.430 [Default:CheckIn] Stopped
2025-06-09T17:42:44.430Z,1749490964.430 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T17:42:44.430Z,1749490964.430 [Default:CheckIn](INFO): Running loop #24
2025-06-09T17:42:44.430Z,1749490964.430 [Default:CheckIn] Running Loop=24
2025-06-09T17:42:44.430Z,1749490964.430 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T17:42:44.430Z,1749490964.430 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T17:42:46.442Z,1749490966.442 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174245.00,A,3648.15116,N,12147.22503,W,0.097,0.00,090625,,,D*72
2025-06-09T17:42:46.444Z,1749490966.444 [NAL9602](INFO): GPS fix at 20250609T174245: (36.802519, -121.787084)
2025-06-09T17:42:46.455Z,1749490966.455 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T17:42:46.455Z,1749490966.455 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T17:42:57.249Z,1749490977.249 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250609T151419/Courier0076.lzma
2025-06-09T17:42:58.252Z,1749490978.252 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Courier0076.lzma.bak
2025-06-09T17:42:58.252Z,1749490978.252 [DataOverHttps](INFO): SBD MOMSN=25161878
2025-06-09T17:43:07.492Z,1749490987.492 [NAL9602](INFO): SBD MO Status=2, MOMSN=63466, MT Status=2, MTMSN=0
2025-06-09T17:43:07.492Z,1749490987.492 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-09T17:43:14.348Z,1749490994.348 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250609T151419/Express0077.lzma
2025-06-09T17:43:15.344Z,1749490995.344 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Express0077.lzma.bak
2025-06-09T17:43:15.344Z,1749490995.344 [DataOverHttps](INFO): SBD MOMSN=25161881
2025-06-09T17:43:16.390Z,1749490996.390 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T17:43:16.390Z,1749490996.390 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T17:43:16.391Z,1749490996.391 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T17:43:22.442Z,1749491002.442 [NAL9602](INFO): SBD MO Status=2, MOMSN=63466, MT Status=2, MTMSN=0
2025-06-09T17:43:22.442Z,1749491002.442 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-09T17:43:54.761Z,1749491034.761 [NAL9602](INFO): SBD MO Status=2, MOMSN=63466, MT Status=2, MTMSN=0
2025-06-09T17:43:54.761Z,1749491034.761 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-09T17:44:25.116Z,1749491065.116 [NAL9602](INFO): SBD MO Status=2, MOMSN=63466, MT Status=2, MTMSN=0
2025-06-09T17:44:25.116Z,1749491065.116 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-06-09T17:44:44.909Z,1749491084.909 [NAL9602](INFO): SBD MO Status=0, MOMSN=63466, MT Status=0, MTMSN=0
2025-06-09T17:44:44.910Z,1749491084.910 [NAL9602](INFO): No messages in MT queue
2025-06-09T17:45:15.668Z,1749491115.668 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T17:48:17.181Z,1749491297.181 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T17:48:17.181Z,1749491297.181 [Default:CheckIn:C.Wait] Stopped
2025-06-09T17:48:17.181Z,1749491297.181 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T17:48:17.181Z,1749491297.181 [Default:CheckIn:D] Running Loop=1
2025-06-09T17:48:17.599Z,1749491297.599 [Default:CheckIn:D] Stopped
2025-06-09T17:48:17.599Z,1749491297.599 [Default:CheckIn:E] Running Loop=1
2025-06-09T17:48:17.999Z,1749491297.999 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  150.104655 min 
2025-06-09T17:48:17.000Z,1749491298.000 [Default:CheckIn:E] Stopped
2025-06-09T17:48:17.000Z,1749491298.000 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T17:48:17.000Z,1749491298.000 [Default:CheckIn] Stopped
2025-06-09T17:48:17.000Z,1749491298.000 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T17:48:18.000Z,1749491298.000 [Default:CheckIn](INFO): Running loop #25
2025-06-09T17:48:18.000Z,1749491298.000 [Default:CheckIn] Running Loop=25
2025-06-09T17:48:18.000Z,1749491298.000 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T17:48:18.000Z,1749491298.000 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T17:48:20.001Z,1749491300.001 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174819.00,A,3648.15003,N,12147.22023,W,0.175,113.15,090625,,,D*79
2025-06-09T17:48:20.004Z,1749491300.004 [NAL9602](INFO): GPS fix at 20250609T174819: (36.802501, -121.787004)
2025-06-09T17:48:20.023Z,1749491300.023 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T17:48:20.023Z,1749491300.023 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T17:48:30.622Z,1749491310.622 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250609T151419/Courier0079.lzma
2025-06-09T17:48:31.626Z,1749491311.626 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Courier0079.lzma.bak
2025-06-09T17:48:31.626Z,1749491311.626 [DataOverHttps](INFO): SBD MOMSN=25161905
2025-06-09T17:48:47.794Z,1749491327.794 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20250609T151419/Express0080.lzma
2025-06-09T17:48:48.796Z,1749491328.796 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Express0080.lzma.bak
2025-06-09T17:48:48.796Z,1749491328.796 [DataOverHttps](INFO): SBD MOMSN=25161908
2025-06-09T17:48:50.012Z,1749491330.012 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T17:48:50.012Z,1749491330.012 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T17:48:50.012Z,1749491330.012 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T17:51:19.228Z,1749491479.228 [NAL9602](INFO): SBD MO Status=0, MOMSN=63467, MT Status=0, MTMSN=0
2025-06-09T17:51:19.228Z,1749491479.228 [NAL9602](INFO): No messages in MT queue
2025-06-09T17:51:49.927Z,1749491509.927 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T17:53:50.766Z,1749491630.766 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T17:53:50.766Z,1749491630.766 [Default:CheckIn:C.Wait] Stopped
2025-06-09T17:53:50.766Z,1749491630.766 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T17:53:50.766Z,1749491630.766 [Default:CheckIn:D] Running Loop=1
2025-06-09T17:53:51.164Z,1749491631.164 [Default:CheckIn:D] Stopped
2025-06-09T17:53:51.164Z,1749491631.164 [Default:CheckIn:E] Running Loop=1
2025-06-09T17:53:51.572Z,1749491631.572 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  155.664062 min 
2025-06-09T17:53:51.572Z,1749491631.572 [Default:CheckIn:E] Stopped
2025-06-09T17:53:51.573Z,1749491631.573 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T17:53:51.573Z,1749491631.573 [Default:CheckIn] Stopped
2025-06-09T17:53:51.573Z,1749491631.573 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T17:53:51.573Z,1749491631.573 [Default:CheckIn](INFO): Running loop #26
2025-06-09T17:53:51.573Z,1749491631.573 [Default:CheckIn] Running Loop=26
2025-06-09T17:53:51.573Z,1749491631.573 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T17:53:51.573Z,1749491631.573 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T17:53:53.577Z,1749491633.577 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175352.00,A,3648.15044,N,12147.21728,W,0.019,113.15,090625,,,D*7B
2025-06-09T17:53:53.579Z,1749491633.579 [NAL9602](INFO): GPS fix at 20250609T175352: (36.802507, -121.786955)
2025-06-09T17:53:53.618Z,1749491633.618 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T17:53:53.618Z,1749491633.618 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T17:54:01.145Z,1749491641.145 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250609T151419/Courier0082.lzma
2025-06-09T17:54:02.148Z,1749491642.148 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Courier0082.lzma.bak
2025-06-09T17:54:02.148Z,1749491642.148 [DataOverHttps](INFO): SBD MOMSN=25161985
2025-06-09T17:54:03.676Z,1749491643.676 [NAL9602](INFO): SBD MO Status=0, MOMSN=63468, MT Status=0, MTMSN=0
2025-06-09T17:54:03.676Z,1749491643.676 [NAL9602](INFO): No messages in MT queue
2025-06-09T17:54:19.569Z,1749491659.569 [DataOverHttps](INFO): Sending 148 bytes from file Logs/20250609T151419/Express0083.lzma
2025-06-09T17:54:20.576Z,1749491660.576 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Express0083.lzma.bak
2025-06-09T17:54:20.576Z,1749491660.576 [DataOverHttps](INFO): SBD MOMSN=25161988
2025-06-09T17:54:21.930Z,1749491661.930 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T17:54:21.931Z,1749491661.931 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T17:54:21.931Z,1749491661.931 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T17:54:34.467Z,1749491674.467 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T17:59:22.537Z,1749491962.537 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T17:59:22.537Z,1749491962.537 [Default:CheckIn:C.Wait] Stopped
2025-06-09T17:59:22.537Z,1749491962.537 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T17:59:22.537Z,1749491962.537 [Default:CheckIn:D] Running Loop=1
2025-06-09T17:59:22.939Z,1749491962.939 [Default:CheckIn:D] Stopped
2025-06-09T17:59:22.939Z,1749491962.939 [Default:CheckIn:E] Running Loop=1
2025-06-09T17:59:23.351Z,1749491963.351 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  161.193652 min 
2025-06-09T17:59:23.351Z,1749491963.351 [Default:CheckIn:E] Stopped
2025-06-09T17:59:23.351Z,1749491963.351 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T17:59:23.351Z,1749491963.351 [Default:CheckIn] Stopped
2025-06-09T17:59:23.352Z,1749491963.352 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T17:59:23.352Z,1749491963.352 [Default:CheckIn](INFO): Running loop #27
2025-06-09T17:59:23.352Z,1749491963.352 [Default:CheckIn] Running Loop=27
2025-06-09T17:59:23.352Z,1749491963.352 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T17:59:23.352Z,1749491963.352 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T17:59:25.353Z,1749491965.353 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175924.00,A,3648.14950,N,12147.21887,W,0.019,113.15,090625,,,D*77
2025-06-09T17:59:25.355Z,1749491965.355 [NAL9602](INFO): GPS fix at 20250609T175924: (36.802492, -121.786981)
2025-06-09T17:59:25.394Z,1749491965.394 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T17:59:25.395Z,1749491965.395 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T17:59:32.461Z,1749491972.461 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250609T151419/Courier0085.lzma
2025-06-09T17:59:33.464Z,1749491973.464 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Courier0085.lzma.bak
2025-06-09T17:59:33.464Z,1749491973.464 [DataOverHttps](INFO): SBD MOMSN=25162018
2025-06-09T17:59:40.707Z,1749491980.707 [NAL9602](INFO): SBD MO Status=0, MOMSN=63469, MT Status=0, MTMSN=0
2025-06-09T17:59:40.707Z,1749491980.707 [NAL9602](INFO): No messages in MT queue
2025-06-09T17:59:49.458Z,1749491989.458 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250609T151419/Express0086.lzma
2025-06-09T17:59:50.460Z,1749491990.460 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Express0086.lzma.bak
2025-06-09T17:59:50.460Z,1749491990.460 [DataOverHttps](INFO): SBD MOMSN=25162021
2025-06-09T17:59:51.622Z,1749491991.622 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T17:59:51.622Z,1749491991.622 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T17:59:51.622Z,1749491991.622 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T18:00:11.409Z,1749492011.409 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T18:04:52.301Z,1749492292.301 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T18:04:52.302Z,1749492292.302 [Default:CheckIn:C.Wait] Stopped
2025-06-09T18:04:52.302Z,1749492292.302 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T18:04:52.302Z,1749492292.302 [Default:CheckIn:D] Running Loop=1
2025-06-09T18:04:52.704Z,1749492292.704 [Default:CheckIn:D] Stopped
2025-06-09T18:04:52.705Z,1749492292.705 [Default:CheckIn:E] Running Loop=1
2025-06-09T18:04:53.097Z,1749492293.097 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  166.689746 min 
2025-06-09T18:04:53.097Z,1749492293.097 [Default:CheckIn:E] Stopped
2025-06-09T18:04:53.097Z,1749492293.097 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T18:04:53.098Z,1749492293.098 [Default:CheckIn] Stopped
2025-06-09T18:04:53.098Z,1749492293.098 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T18:04:53.098Z,1749492293.098 [Default:CheckIn](INFO): Running loop #28
2025-06-09T18:04:53.098Z,1749492293.098 [Default:CheckIn] Running Loop=28
2025-06-09T18:04:53.098Z,1749492293.098 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T18:04:53.098Z,1749492293.098 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T18:04:55.105Z,1749492295.105 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180454.00,A,3648.15134,N,12147.21900,W,0.019,113.15,090625,,,D*72
2025-06-09T18:04:55.123Z,1749492295.123 [NAL9602](INFO): GPS fix at 20250609T180454: (36.802522, -121.786983)
2025-06-09T18:04:55.142Z,1749492295.142 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T18:04:55.142Z,1749492295.142 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T18:05:02.957Z,1749492302.957 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250609T151419/Courier0088.lzma
2025-06-09T18:05:03.960Z,1749492303.960 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Courier0088.lzma.bak
2025-06-09T18:05:03.960Z,1749492303.960 [DataOverHttps](INFO): SBD MOMSN=25162095
2025-06-09T18:05:19.962Z,1749492319.962 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250609T151419/Express0089.lzma
2025-06-09T18:05:20.964Z,1749492320.964 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Express0089.lzma.bak
2025-06-09T18:05:20.964Z,1749492320.964 [DataOverHttps](INFO): SBD MOMSN=25162099
2025-06-09T18:05:22.288Z,1749492322.288 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T18:05:22.288Z,1749492322.288 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T18:05:22.288Z,1749492322.288 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T18:05:25.500Z,1749492325.500 [NAL9602](INFO): SBD MO Status=0, MOMSN=63470, MT Status=0, MTMSN=0
2025-06-09T18:05:25.500Z,1749492325.500 [NAL9602](INFO): No messages in MT queue
2025-06-09T18:05:56.203Z,1749492356.203 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T18:10:22.882Z,1749492622.882 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T18:10:22.882Z,1749492622.882 [Default:CheckIn:C.Wait] Stopped
2025-06-09T18:10:22.882Z,1749492622.882 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T18:10:22.882Z,1749492622.882 [Default:CheckIn:D] Running Loop=1
2025-06-09T18:10:23.277Z,1749492623.277 [Default:CheckIn:D] Stopped
2025-06-09T18:10:23.277Z,1749492623.277 [Default:CheckIn:E] Running Loop=1
2025-06-09T18:10:23.697Z,1749492623.697 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  172.199284 min 
2025-06-09T18:10:23.697Z,1749492623.697 [Default:CheckIn:E] Stopped
2025-06-09T18:10:23.697Z,1749492623.697 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T18:10:23.697Z,1749492623.697 [Default:CheckIn] Stopped
2025-06-09T18:10:23.706Z,1749492623.706 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T18:10:23.706Z,1749492623.706 [Default:CheckIn](INFO): Running loop #29
2025-06-09T18:10:23.707Z,1749492623.707 [Default:CheckIn] Running Loop=29
2025-06-09T18:10:23.707Z,1749492623.707 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T18:10:23.707Z,1749492623.707 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T18:10:25.695Z,1749492625.695 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181024.00,A,3648.15211,N,12147.21869,W,0.039,113.15,090625,,,D*78
2025-06-09T18:10:25.697Z,1749492625.697 [NAL9602](INFO): GPS fix at 20250609T181024: (36.802535, -121.786978)
2025-06-09T18:10:25.708Z,1749492625.708 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T18:10:25.708Z,1749492625.708 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T18:10:33.289Z,1749492633.289 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250609T151419/Courier0091.lzma
2025-06-09T18:10:34.292Z,1749492634.292 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Courier0091.lzma.bak
2025-06-09T18:10:34.292Z,1749492634.292 [DataOverHttps](INFO): SBD MOMSN=25162135
2025-06-09T18:10:40.637Z,1749492640.637 [NAL9602](INFO): SBD MO Status=0, MOMSN=63471, MT Status=0, MTMSN=0
2025-06-09T18:10:40.637Z,1749492640.637 [NAL9602](INFO): No messages in MT queue
2025-06-09T18:10:52.054Z,1749492652.054 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250609T151419/Express0092.lzma
2025-06-09T18:10:53.057Z,1749492653.057 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Express0092.lzma.bak
2025-06-09T18:10:53.058Z,1749492653.058 [DataOverHttps](INFO): SBD MOMSN=25162141
2025-06-09T18:10:54.410Z,1749492654.410 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T18:10:54.410Z,1749492654.410 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T18:10:54.410Z,1749492654.410 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T18:11:11.356Z,1749492671.356 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T18:15:54.973Z,1749492954.973 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T18:15:54.973Z,1749492954.973 [Default:CheckIn:C.Wait] Stopped
2025-06-09T18:15:54.973Z,1749492954.973 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T18:15:54.973Z,1749492954.973 [Default:CheckIn:D] Running Loop=1
2025-06-09T18:15:55.383Z,1749492955.383 [Default:CheckIn:D] Stopped
2025-06-09T18:15:55.383Z,1749492955.383 [Default:CheckIn:E] Running Loop=1
2025-06-09T18:15:55.781Z,1749492955.781 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  177.734391 min 
2025-06-09T18:15:55.781Z,1749492955.781 [Default:CheckIn:E] Stopped
2025-06-09T18:15:55.781Z,1749492955.781 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T18:15:55.798Z,1749492955.798 [Default:CheckIn] Stopped
2025-06-09T18:15:55.798Z,1749492955.798 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T18:15:55.798Z,1749492955.798 [Default:CheckIn](INFO): Running loop #30
2025-06-09T18:15:55.798Z,1749492955.798 [Default:CheckIn] Running Loop=30
2025-06-09T18:15:55.798Z,1749492955.798 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T18:15:55.798Z,1749492955.798 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T18:15:57.793Z,1749492957.793 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181557.00,A,3648.31184,N,12147.12168,W,3.266,6.19,090625,,,D*7A
2025-06-09T18:15:57.795Z,1749492957.795 [NAL9602](INFO): GPS fix at 20250609T181557: (36.805197, -121.785361)
2025-06-09T18:15:57.807Z,1749492957.807 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T18:15:57.807Z,1749492957.807 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T18:16:05.157Z,1749492965.157 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250609T151419/Courier0094.lzma
2025-06-09T18:16:06.160Z,1749492966.160 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Courier0094.lzma.bak
2025-06-09T18:16:06.160Z,1749492966.160 [DataOverHttps](INFO): SBD MOMSN=25162213
2025-06-09T18:16:15.164Z,1749492975.164 [NAL9602](INFO): SBD MO Status=0, MOMSN=63472, MT Status=0, MTMSN=0
2025-06-09T18:16:15.164Z,1749492975.164 [NAL9602](INFO): No messages in MT queue
2025-06-09T18:16:24.977Z,1749492984.977 [DataOverHttps](INFO): Sending 171 bytes from file Logs/20250609T151419/Express0095.lzma
2025-06-09T18:16:25.980Z,1749492985.980 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Express0095.lzma.bak
2025-06-09T18:16:25.980Z,1749492985.980 [DataOverHttps](INFO): SBD MOMSN=25162222
2025-06-09T18:16:27.359Z,1749492987.359 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T18:16:27.359Z,1749492987.359 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T18:16:27.359Z,1749492987.359 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T18:16:45.918Z,1749493005.918 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T18:17:41.270Z,1749493061.270 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:17:52.988Z,1749493072.988 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:18:04.701Z,1749493084.701 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:18:16.824Z,1749493096.824 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:18:28.133Z,1749493108.133 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:18:40.254Z,1749493120.254 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:18:51.975Z,1749493131.975 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:19:04.513Z,1749493144.513 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:21:27.956Z,1749493287.956 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T18:21:27.956Z,1749493287.956 [Default:CheckIn:C.Wait] Stopped
2025-06-09T18:21:27.956Z,1749493287.956 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T18:21:27.956Z,1749493287.956 [Default:CheckIn:D] Running Loop=1
2025-06-09T18:21:28.338Z,1749493288.338 [Default:CheckIn:D] Stopped
2025-06-09T18:21:28.338Z,1749493288.338 [Default:CheckIn:E] Running Loop=1
2025-06-09T18:21:28.737Z,1749493288.737 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  183.283643 min 
2025-06-09T18:21:28.737Z,1749493288.737 [Default:CheckIn:E] Stopped
2025-06-09T18:21:28.737Z,1749493288.737 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T18:21:28.737Z,1749493288.737 [Default:CheckIn] Stopped
2025-06-09T18:21:28.737Z,1749493288.737 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T18:21:28.738Z,1749493288.738 [Default:CheckIn](INFO): Running loop #31
2025-06-09T18:21:28.738Z,1749493288.738 [Default:CheckIn] Running Loop=31
2025-06-09T18:21:28.738Z,1749493288.738 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T18:21:28.738Z,1749493288.738 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T18:21:30.753Z,1749493290.753 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182130.00,A,3648.38839,N,12147.43650,W,8.009,226.22,090625,,,D*7A
2025-06-09T18:21:30.755Z,1749493290.755 [NAL9602](INFO): GPS fix at 20250609T182130: (36.806473, -121.790608)
2025-06-09T18:21:30.783Z,1749493290.783 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T18:21:30.783Z,1749493290.783 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T18:21:32.422Z,1749493292.422 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:21:38.121Z,1749493298.121 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250609T151419/Courier0097.lzma
2025-06-09T18:21:39.124Z,1749493299.124 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Courier0097.lzma.bak
2025-06-09T18:21:39.124Z,1749493299.124 [DataOverHttps](INFO): SBD MOMSN=25162258
2025-06-09T18:21:41.256Z,1749493301.256 [NAL9602](INFO): SBD MO Status=0, MOMSN=63473, MT Status=0, MTMSN=0
2025-06-09T18:21:41.256Z,1749493301.256 [NAL9602](INFO): No messages in MT queue
2025-06-09T18:21:45.757Z,1749493305.757 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:21:55.138Z,1749493315.138 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20250609T151419/Express0098.lzma
2025-06-09T18:21:56.140Z,1749493316.140 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Express0098.lzma.bak
2025-06-09T18:21:56.140Z,1749493316.140 [DataOverHttps](INFO): SBD MOMSN=25162261
2025-06-09T18:21:57.428Z,1749493317.428 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T18:21:57.428Z,1749493317.428 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T18:21:57.428Z,1749493317.428 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T18:21:59.436Z,1749493319.436 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:22:11.964Z,1749493331.964 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T18:22:13.193Z,1749493333.193 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:22:27.042Z,1749493347.042 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:22:40.269Z,1749493360.269 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:22:54.034Z,1749493374.034 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:23:07.750Z,1749493387.750 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:23:21.478Z,1749493401.478 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:23:34.805Z,1749493414.805 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:23:48.137Z,1749493428.137 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:26:58.050Z,1749493618.050 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T18:26:58.050Z,1749493618.050 [Default:CheckIn:C.Wait] Stopped
2025-06-09T18:26:58.050Z,1749493618.050 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T18:26:58.051Z,1749493618.051 [Default:CheckIn:D] Running Loop=1
2025-06-09T18:26:58.442Z,1749493618.442 [Default:CheckIn:D] Stopped
2025-06-09T18:26:58.442Z,1749493618.442 [Default:CheckIn:E] Running Loop=1
2025-06-09T18:26:58.840Z,1749493618.840 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  188.785368 min 
2025-06-09T18:26:58.840Z,1749493618.840 [Default:CheckIn:E] Stopped
2025-06-09T18:26:58.840Z,1749493618.840 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T18:26:58.840Z,1749493618.840 [Default:CheckIn] Stopped
2025-06-09T18:26:58.840Z,1749493618.840 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T18:26:58.840Z,1749493618.840 [Default:CheckIn](INFO): Running loop #32
2025-06-09T18:26:58.840Z,1749493618.840 [Default:CheckIn] Running Loop=32
2025-06-09T18:26:58.840Z,1749493618.840 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T18:26:58.840Z,1749493618.840 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T18:27:00.859Z,1749493620.859 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182700.00,A,3647.97225,N,12148.99567,W,15.629,255.34,090625,,,D*46
2025-06-09T18:27:00.861Z,1749493620.861 [NAL9602](INFO): GPS fix at 20250609T182700: (36.799537, -121.816594)
2025-06-09T18:27:00.872Z,1749493620.872 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T18:27:00.872Z,1749493620.872 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T18:27:07.889Z,1749493627.889 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20250609T151419/Courier0100.lzma
2025-06-09T18:27:08.892Z,1749493628.892 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Courier0100.lzma.bak
2025-06-09T18:27:08.892Z,1749493628.892 [DataOverHttps](INFO): SBD MOMSN=25162355
2025-06-09T18:27:11.759Z,1749493631.759 [NAL9602](INFO): SBD MO Status=0, MOMSN=63474, MT Status=0, MTMSN=0
2025-06-09T18:27:11.760Z,1749493631.760 [NAL9602](INFO): No messages in MT queue
2025-06-09T18:27:27.942Z,1749493647.942 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20250609T151419/Express0101.lzma
2025-06-09T18:27:28.944Z,1749493648.944 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Express0101.lzma.bak
2025-06-09T18:27:28.944Z,1749493648.944 [DataOverHttps](INFO): SBD MOMSN=25162358
2025-06-09T18:27:30.007Z,1749493650.007 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T18:27:30.007Z,1749493650.007 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T18:27:30.007Z,1749493650.007 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T18:27:42.511Z,1749493662.511 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T18:30:02.290Z,1749493802.290 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-09T18:30:33.430Z,1749493833.430 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-09T18:31:04.554Z,1749493864.554 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-09T18:31:15.421Z,1749493875.421 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:31:29.158Z,1749493889.158 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:31:35.678Z,1749493895.678 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-09T18:31:43.298Z,1749493903.298 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:31:56.631Z,1749493916.631 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:32:06.810Z,1749493926.810 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-06-09T18:32:10.365Z,1749493930.365 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:32:13.494Z,1749493933.494 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=184.401932
2025-06-09T18:32:23.708Z,1749493943.708 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:32:30.586Z,1749493950.586 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T18:32:30.586Z,1749493950.586 [Default:CheckIn:C.Wait] Stopped
2025-06-09T18:32:30.586Z,1749493950.586 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T18:32:30.587Z,1749493950.587 [Default:CheckIn:D] Running Loop=1
2025-06-09T18:32:30.983Z,1749493950.983 [Default:CheckIn:D] Stopped
2025-06-09T18:32:30.983Z,1749493950.983 [Default:CheckIn:E] Running Loop=1
2025-06-09T18:32:31.452Z,1749493951.452 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  194.327718 min 
2025-06-09T18:32:31.452Z,1749493951.452 [Default:CheckIn:E] Stopped
2025-06-09T18:32:31.452Z,1749493951.452 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T18:32:31.452Z,1749493951.452 [Default:CheckIn] Stopped
2025-06-09T18:32:31.452Z,1749493951.452 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T18:32:31.453Z,1749493951.453 [Default:CheckIn](INFO): Running loop #33
2025-06-09T18:32:31.453Z,1749493951.453 [Default:CheckIn] Running Loop=33
2025-06-09T18:32:31.453Z,1749493951.453 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T18:32:31.453Z,1749493951.453 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T18:32:33.401Z,1749493953.401 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183232.00,A,3647.82218,N,12150.73657,W,14.404,274.79,090625,,,D*42
2025-06-09T18:32:33.404Z,1749493953.404 [NAL9602](INFO): GPS fix at 20250609T183232: (36.797036, -121.845609)
2025-06-09T18:32:33.434Z,1749493953.434 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T18:32:33.434Z,1749493953.434 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T18:32:37.029Z,1749493957.029 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:32:45.958Z,1749493965.958 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002854
2025-06-09T18:32:48.852Z,1749493968.852 [NAL9602](INFO): SBD MO Status=1, MOMSN=63475, MT Status=0, MTMSN=0
2025-06-09T18:32:48.914Z,1749493968.914 [NAL9602](INFO): Sent 71 bytes from file Logs/20250609T151419/Courier0103.lzma
2025-06-09T18:32:48.914Z,1749493968.914 [NAL9602](INFO): Packets left to send: 0
2025-06-09T18:32:50.469Z,1749493970.469 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:32:56.698Z,1749493976.698 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20250609T151419/Express0104.lzma
2025-06-09T18:33:04.252Z,1749493984.252 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:33:17.582Z,1749493997.582 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:33:17.790Z,1749493997.790 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-06-09T18:33:19.603Z,1749493999.603 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T18:33:26.294Z,1749494006.294 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20250609T151419/Express0104.lzma
2025-06-09T18:33:30.913Z,1749494010.913 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:33:49.382Z,1749494029.382 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-06-09T18:33:56.846Z,1749494036.846 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20250609T151419/Express0104.lzma
2025-06-09T18:33:57.848Z,1749494037.848 [DataOverHttps](INFO): Moved sent file to Logs/20250609T151419/Express0104.lzma.bak
2025-06-09T18:33:57.848Z,1749494037.848 [DataOverHttps](INFO): SBD MOMSN=25162386
2025-06-09T18:33:59.221Z,1749494039.221 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T18:33:59.221Z,1749494039.221 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T18:33:59.222Z,1749494039.222 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T18:38:59.783Z,1749494339.783 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-06-09T18:38:59.783Z,1749494339.783 [Default:CheckIn:C.Wait] Stopped
2025-06-09T18:38:59.783Z,1749494339.783 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-06-09T18:38:59.784Z,1749494339.784 [Default:CheckIn:D] Running Loop=1
2025-06-09T18:39:00.196Z,1749494340.196 [Default:CheckIn:D] Stopped
2025-06-09T18:39:00.196Z,1749494340.196 [Default:CheckIn:E] Running Loop=1
2025-06-09T18:39:00.597Z,1749494340.597 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  200.814600 min 
2025-06-09T18:39:00.614Z,1749494340.614 [Default:CheckIn:E] Stopped
2025-06-09T18:39:00.614Z,1749494340.614 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-06-09T18:39:00.614Z,1749494340.614 [Default:CheckIn] Stopped
2025-06-09T18:39:00.614Z,1749494340.614 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-06-09T18:39:00.614Z,1749494340.614 [Default:CheckIn](INFO): Running loop #34
2025-06-09T18:39:00.614Z,1749494340.614 [Default:CheckIn] Running Loop=34
2025-06-09T18:39:00.614Z,1749494340.614 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-06-09T18:39:00.614Z,1749494340.614 [Default:CheckIn:Read_GPS] Running Loop=1
2025-06-09T18:39:02.602Z,1749494342.602 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183901.00,A,3647.69363,N,12150.88109,W,0.836,93.81,090625,,,D*4D
2025-06-09T18:39:02.604Z,1749494342.604 [NAL9602](INFO): GPS fix at 20250609T183901: (36.794894, -121.848018)
2025-06-09T18:39:02.616Z,1749494342.616 [Default:CheckIn:Read_GPS] Stopped
2025-06-09T18:39:02.616Z,1749494342.616 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-06-09T18:39:03.427Z,1749494343.427 [Default:CheckIn:Read_Iridium] Stopped
2025-06-09T18:39:03.427Z,1749494343.427 [Default:CheckIn:C.Wait] Running Loop=1
2025-06-09T18:39:03.427Z,1749494343.427 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-06-09T18:39:16.349Z,1749494356.349 [NAL9602](INFO): SBD MO Status=0, MOMSN=63476, MT Status=0, MTMSN=0
2025-06-09T18:39:16.349Z,1749494356.349 [NAL9602](INFO): No messages in MT queue
2025-06-09T18:39:47.107Z,1749494387.107 [NAL9602](INFO): Not Powering down - fast GPS
2025-06-09T18:40:46.829Z,1749494446.829 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:41:00.180Z,1749494460.180 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:41:13.920Z,1749494473.920 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:41:27.229Z,1749494487.229 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:41:40.562Z,1749494500.562 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:41:53.893Z,1749494513.893 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:42:07.226Z,1749494527.226 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:42:20.158Z,1749494540.158 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:42:33.893Z,1749494553.893 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:42:45.107Z,1749494565.107 [DataOverHttps](IMPORTANT): SBD MTMSN=20250609T184244
2025-06-09T18:42:47.636Z,1749494567.636 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-06-09T18:42:55.842Z,1749494575.842 [DataOverHttps](INFO): Received command: restart logs