2024-07-22T15:54:14.297Z,1721663654.297 [Supervisor](DEBUG): Initializing supervisor.
2024-07-22T15:54:14.302Z,1721663654.302 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-07-22T15:54:14.302Z,1721663654.302 [SyncHandler](INFO): Protected caller Thread ID is 1008
2024-07-22T15:54:14.303Z,1721663654.303 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-07-22T15:54:14.304Z,1721663654.304 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-07-22T15:54:14.304Z,1721663654.304 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1009
2024-07-22T15:54:14.308Z,1721663654.308 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-07-22T15:54:14.326Z,1721663654.326 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-07-22T15:54:14.327Z,1721663654.327 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-07-22T15:54:14.327Z,1721663654.327 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1010
2024-07-22T15:54:14.331Z,1721663654.331 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-07-22T15:54:14.332Z,1721663654.332 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-07-22T15:54:14.333Z,1721663654.333 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1011
2024-07-22T15:54:14.335Z,1721663654.335 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-07-22T15:54:14.336Z,1721663654.336 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-07-22T15:54:14.336Z,1721663654.336 [logger ThreadHandler](INFO): Protected caller Thread ID is 1012
2024-07-22T15:54:14.340Z,1721663654.340 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-07-22T15:54:14.340Z,1721663654.340 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-07-22T15:54:14.342Z,1721663654.342 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-07-22T15:54:14.617Z,1721663654.617 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-07-22T15:54:14.618Z,1721663654.618 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-07-22T15:54:14.709Z,1721663654.709 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-07-22T15:54:15.256Z,1721663655.256 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-07-22T15:54:15.257Z,1721663655.257 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-07-22T15:54:15.615Z,1721663655.615 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-07-22T15:54:15.615Z,1721663655.615 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-07-22T15:54:15.722Z,1721663655.722 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-07-22T15:54:15.722Z,1721663655.722 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-07-22T15:54:16.138Z,1721663656.138 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-07-22T15:54:16.139Z,1721663656.139 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-07-22T15:54:16.365Z,1721663656.365 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-07-22T15:54:16.366Z,1721663656.366 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-07-22T15:54:16.905Z,1721663656.905 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-07-22T15:54:16.906Z,1721663656.906 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-07-22T15:54:17.052Z,1721663657.052 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-07-22T15:54:17.052Z,1721663657.052 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-07-22T15:54:17.141Z,1721663657.141 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-07-22T15:54:17.990Z,1721663657.990 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-07-22T15:54:17.991Z,1721663657.991 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-07-22T15:54:18.388Z,1721663658.388 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-07-22T15:54:18.388Z,1721663658.388 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-07-22T15:54:18.615Z,1721663658.615 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-07-22T15:54:18.616Z,1721663658.616 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-07-22T15:54:19.022Z,1721663659.022 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-07-22T15:54:19.023Z,1721663659.023 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-07-22T15:54:19.611Z,1721663659.611 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-07-22T15:54:19.611Z,1721663659.611 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-07-22T15:54:19.874Z,1721663659.874 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-07-22T15:54:19.876Z,1721663659.876 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2024-07-22T15:54:19.876Z,1721663659.876 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2024-07-22T15:54:19.977Z,1721663659.977 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2024-07-22T15:54:20.073Z,1721663660.073 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2024-07-22T15:54:20.186Z,1721663660.186 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2024-07-22T15:54:20.280Z,1721663660.280 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2024-07-22T15:54:20.392Z,1721663660.392 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2024-07-22T15:54:20.496Z,1721663660.496 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2024-07-22T15:54:20.623Z,1721663660.623 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2024-07-22T15:54:20.794Z,1721663660.794 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2024-07-22T15:54:20.951Z,1721663660.951 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2024-07-22T15:54:21.076Z,1721663661.076 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2024-07-22T15:54:21.355Z,1721663661.355 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-07-22T15:54:21.356Z,1721663661.356 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2024-07-22T15:54:21.357Z,1721663661.357 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-07-22T15:54:21.361Z,1721663661.361 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-07-22T15:54:21.414Z,1721663661.414 [VerticalControl](DEBUG): Construct VerticalControl.
2024-07-22T15:54:21.475Z,1721663661.475 [VerticalControl] Loaded
2024-07-22T15:54:21.475Z,1721663661.475 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-07-22T15:54:21.478Z,1721663661.478 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-07-22T15:54:21.525Z,1721663661.525 [HorizontalControl] Loaded
2024-07-22T15:54:21.525Z,1721663661.525 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-07-22T15:54:21.527Z,1721663661.527 [SpeedControl](DEBUG): Construct SpeedControl.
2024-07-22T15:54:21.530Z,1721663661.530 [SpeedControl] Loaded
2024-07-22T15:54:21.530Z,1721663661.530 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-07-22T15:54:21.533Z,1721663661.533 [LoopControl](DEBUG): Construct LoopControl.
2024-07-22T15:54:21.533Z,1721663661.533 [LoopControl] Loaded
2024-07-22T15:54:21.534Z,1721663661.534 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-07-22T15:54:21.534Z,1721663661.534 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-07-22T15:54:21.535Z,1721663661.535 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-07-22T15:54:21.545Z,1721663661.545 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-07-22T15:54:21.545Z,1721663661.545 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-07-22T15:54:21.714Z,1721663661.714 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-07-22T15:54:21.714Z,1721663661.714 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-07-22T15:54:21.870Z,1721663661.870 [BuoyancyServo] Loaded
2024-07-22T15:54:21.870Z,1721663661.870 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-07-22T15:54:21.892Z,1721663661.892 [ElevatorServo] Loaded
2024-07-22T15:54:21.892Z,1721663661.892 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-07-22T15:54:21.913Z,1721663661.913 [MassServo] Loaded
2024-07-22T15:54:21.913Z,1721663661.913 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-07-22T15:54:21.933Z,1721663661.933 [RudderServo] Loaded
2024-07-22T15:54:21.933Z,1721663661.933 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-07-22T15:54:21.949Z,1721663661.949 [ThrusterHE] Loaded
2024-07-22T15:54:21.950Z,1721663661.950 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-07-22T15:54:21.950Z,1721663661.950 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-07-22T15:54:21.951Z,1721663661.951 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-07-22T15:54:22.061Z,1721663662.061 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-07-22T15:54:22.061Z,1721663662.061 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-07-22T15:54:22.075Z,1721663662.075 [NavChart] Loaded
2024-07-22T15:54:22.075Z,1721663662.075 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-07-22T15:54:22.080Z,1721663662.080 [UniversalFixResidualReporter] Loaded
2024-07-22T15:54:22.081Z,1721663662.081 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-07-22T15:54:22.081Z,1721663662.081 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-07-22T15:54:22.081Z,1721663662.081 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-07-22T15:54:22.137Z,1721663662.137 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-07-22T15:54:22.137Z,1721663662.137 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-07-22T15:54:22.568Z,1721663662.568 [AHRS_M2] Loaded
2024-07-22T15:54:22.568Z,1721663662.568 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-07-22T15:54:23.385Z,1721663663.385 [BPC1] Loaded
2024-07-22T15:54:23.386Z,1721663663.386 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-07-22T15:54:23.459Z,1721663663.459 [DataOverHttps] Loaded
2024-07-22T15:54:23.459Z,1721663663.459 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-07-22T15:54:23.460Z,1721663663.460 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409314E0
2024-07-22T15:54:23.460Z,1721663663.460 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1094
2024-07-22T15:54:23.484Z,1721663663.484 [Depth_Keller] Loaded
2024-07-22T15:54:23.484Z,1721663663.484 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-07-22T15:54:23.489Z,1721663663.489 [DropWeight] Loaded
2024-07-22T15:54:23.489Z,1721663663.489 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-07-22T15:54:23.552Z,1721663663.552 [NAL9602] Loaded
2024-07-22T15:54:23.552Z,1721663663.552 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-07-22T15:54:23.582Z,1721663663.582 [Onboard] Loaded
2024-07-22T15:54:23.582Z,1721663663.582 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-07-22T15:54:23.583Z,1721663663.583 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409614E0
2024-07-22T15:54:23.583Z,1721663663.583 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1095
2024-07-22T15:54:23.598Z,1721663663.598 [Power24vConverter] Loaded
2024-07-22T15:54:23.599Z,1721663663.599 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-07-22T15:54:23.613Z,1721663663.613 [Radio_Surface] Loaded
2024-07-22T15:54:23.614Z,1721663663.614 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-07-22T15:54:23.615Z,1721663663.615 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409914E0
2024-07-22T15:54:23.615Z,1721663663.615 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1096
2024-07-22T15:54:23.709Z,1721663663.709 [DAT] Loaded
2024-07-22T15:54:23.710Z,1721663663.710 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2024-07-22T15:54:23.711Z,1721663663.711 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409C14E0
2024-07-22T15:54:23.711Z,1721663663.711 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1097
2024-07-22T15:54:23.712Z,1721663663.712 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-07-22T15:54:23.712Z,1721663663.712 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-07-22T15:54:23.817Z,1721663663.817 [DepthRateCalculator] Loaded
2024-07-22T15:54:23.817Z,1721663663.817 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-07-22T15:54:23.830Z,1721663663.830 [PitchRateCalculator] Loaded
2024-07-22T15:54:23.830Z,1721663663.830 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-07-22T15:54:23.851Z,1721663663.851 [SpeedCalculator] Loaded
2024-07-22T15:54:23.851Z,1721663663.851 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-07-22T15:54:23.855Z,1721663663.855 [YawRateCalculator] Loaded
2024-07-22T15:54:23.855Z,1721663663.855 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-07-22T15:54:23.916Z,1721663663.916 [ElevatorOffsetCalculator] Loaded
2024-07-22T15:54:23.917Z,1721663663.917 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-07-22T15:54:23.917Z,1721663663.917 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-07-22T15:54:23.938Z,1721663663.938 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-07-22T15:54:24.229Z,1721663664.229 [CANONSampler] Loaded
2024-07-22T15:54:24.230Z,1721663664.230 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2024-07-22T15:54:24.399Z,1721663664.399 [CTD_Seabird] Loaded
2024-07-22T15:54:24.399Z,1721663664.399 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-07-22T15:54:24.400Z,1721663664.400 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A7D4E0
2024-07-22T15:54:24.400Z,1721663664.400 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1098
2024-07-22T15:54:24.424Z,1721663664.424 [PAR_Licor] Loaded
2024-07-22T15:54:24.424Z,1721663664.424 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-07-22T15:54:24.502Z,1721663664.502 [WetLabsBB2FL] Loaded
2024-07-22T15:54:24.502Z,1721663664.502 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-07-22T15:54:24.503Z,1721663664.503 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AAD4E0
2024-07-22T15:54:24.504Z,1721663664.504 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1099
2024-07-22T15:54:24.504Z,1721663664.504 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-07-22T15:54:24.505Z,1721663664.505 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-07-22T15:54:24.590Z,1721663664.590 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-07-22T15:54:24.591Z,1721663664.591 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-07-22T15:54:24.922Z,1721663664.922 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-07-22T15:54:24.923Z,1721663664.923 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-07-22T15:54:25.063Z,1721663665.063 [SBIT](DEBUG): Construct Startup Built In Test.
2024-07-22T15:54:25.072Z,1721663665.072 [SBIT] Loaded
2024-07-22T15:54:25.072Z,1721663665.072 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-07-22T15:54:25.075Z,1721663665.075 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-07-22T15:54:25.088Z,1721663665.088 [IBIT] Loaded
2024-07-22T15:54:25.089Z,1721663665.089 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-07-22T15:54:25.094Z,1721663665.094 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-07-22T15:54:25.199Z,1721663665.199 [CBIT] Loaded
2024-07-22T15:54:25.199Z,1721663665.199 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-07-22T15:54:25.200Z,1721663665.200 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-07-22T15:54:25.200Z,1721663665.200 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-07-22T15:54:25.309Z,1721663665.309 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-07-22T15:54:25.316Z,1721663665.316 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-07-22T15:54:25.318Z,1721663665.318 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-07-22T15:54:25.330Z,1721663665.330 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-07-22T15:54:25.331Z,1721663665.331 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C074E0
2024-07-22T15:54:25.331Z,1721663665.331 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1100
2024-07-22T15:54:25.336Z,1721663665.336 [Supervisor](INFO): Main Thread ID is 830
2024-07-22T15:54:25.336Z,1721663665.336 [Supervisor](DEBUG): Running supervisor.
2024-07-22T15:54:25.336Z,1721663665.336 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1101
2024-07-22T15:54:25.337Z,1721663665.337 [CommandExec](INFO): Initializing the command executive.
2024-07-22T15:54:25.338Z,1721663665.338 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1102
2024-07-22T15:54:25.341Z,1721663665.341 [controlThread ThreadHandler](INFO): Handler Thread ID is 1103
2024-07-22T15:54:25.341Z,1721663665.341 [controlThread](DEBUG): Initializing ControlThread
2024-07-22T15:54:25.342Z,1721663665.342 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-07-22T15:54:25.343Z,1721663665.343 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-07-22T15:54:25.344Z,1721663665.344 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-07-22T15:54:25.345Z,1721663665.345 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-07-22T15:54:25.346Z,1721663665.346 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-07-22T15:54:25.347Z,1721663665.347 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-07-22T15:54:25.352Z,1721663665.352 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-07-22T15:54:25.352Z,1721663665.352 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-07-22T15:54:25.352Z,1721663665.352 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-07-22T15:54:25.353Z,1721663665.353 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-07-22T15:54:25.353Z,1721663665.353 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-07-22T15:54:25.358Z,1721663665.358 [SBIT](INFO): Initialize SBIT Component.
2024-07-22T15:54:25.359Z,1721663665.359 [SBIT](IMPORTANT): git: 2024-07-20
2024-07-22T15:54:25.359Z,1721663665.359 [SBIT](INFO): git hash: 7565847ab9f19e42dc9154a38871a4050adf1927
2024-07-22T15:54:25.359Z,1721663665.359 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-07-22T15:54:25.360Z,1721663665.360 [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
2024-07-22T15:54:25.361Z,1721663665.361 [SBIT](INFO): Beginning SBIT in 37.000000 seconds.
2024-07-22T15:54:25.362Z,1721663665.362 [IBIT](INFO): Initialize IBIT Component.
2024-07-22T15:54:25.363Z,1721663665.363 [CBIT](DEBUG): Initialize CBIT Component.
2024-07-22T15:54:25.364Z,1721663665.364 [logger ThreadHandler](INFO): Handler Thread ID is 1104
2024-07-22T15:54:25.374Z,1721663665.374 [CBIT](DEBUG): Initialized mux pins.
2024-07-22T15:54:25.374Z,1721663665.374 [CBIT](DEBUG): Initializing the watchdog timer.
2024-07-22T15:54:25.382Z,1721663665.382 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1105
2024-07-22T15:54:25.384Z,1721663665.384 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-07-22T15:54:25.394Z,1721663665.394 [Onboard ThreadHandler](INFO): Handler Thread ID is 1106
2024-07-22T15:54:25.399Z,1721663665.399 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-07-22T15:54:25.399Z,1721663665.399 [CBIT](DEBUG): Initializing heartbeat.
2024-07-22T15:54:25.415Z,1721663665.415 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1107
2024-07-22T15:54:25.431Z,1721663665.431 [DAT ThreadHandler](INFO): Handler Thread ID is 1108
2024-07-22T15:54:25.431Z,1721663665.431 [DAT](INFO): Powering up
2024-07-22T15:54:25.431Z,1721663665.431 [DAT](DEBUG): Initializing DAT.
2024-07-22T15:54:25.435Z,1721663665.435 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1110
2024-07-22T15:54:25.436Z,1721663665.436 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-07-22T15:54:25.440Z,1721663665.440 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1112
2024-07-22T15:54:25.442Z,1721663665.442 [WetLabsBB2FL](INFO): Powering up
2024-07-22T15:54:25.444Z,1721663665.444 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1114
2024-07-22T15:54:25.447Z,1721663665.447 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2024-07-22T15:54:25.447Z,1721663665.447 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2024-07-22T15:54:25.447Z,1721663665.447 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2024-07-22T15:54:25.448Z,1721663665.448 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2024-07-22T15:54:25.448Z,1721663665.448 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2024-07-22T15:54:25.448Z,1721663665.448 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2024-07-22T15:54:25.448Z,1721663665.448 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2024-07-22T15:54:25.448Z,1721663665.448 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2024-07-22T15:54:25.470Z,1721663665.470 [CBIT](DEBUG): Deactivating GF circuits.
2024-07-22T15:54:25.470Z,1721663665.470 [CBIT](DEBUG): Deactivating emergency mode.
2024-07-22T15:54:25.510Z,1721663665.510 [CBIT](DEBUG): Backplane powered.
2024-07-22T15:54:25.511Z,1721663665.511 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-07-22T15:54:25.511Z,1721663665.511 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-07-22T15:54:25.512Z,1721663665.512 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-07-22T15:54:25.513Z,1721663665.513 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-07-22T15:54:25.559Z,1721663665.559 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-07-22T15:54:25.564Z,1721663665.564 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-07-22T15:54:25.611Z,1721663665.611 [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
}
}
}
2024-07-22T15:54:25.612Z,1721663665.612 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-07-22T15:54:25.612Z,1721663665.612 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-07-22T15:54:25.613Z,1721663665.613 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-07-22T15:54:25.818Z,1721663665.818 [Radio_Surface](INFO): Powering up
2024-07-22T15:54:25.907Z,1721663665.907 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2024-07-22T15:54:25.929Z,1721663665.929 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-07-22T15:54:25.931Z,1721663665.931 [Default:A.Wait](DEBUG): Construct Wait.
2024-07-22T15:54:25.933Z,1721663665.933 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-07-22T15:54:25.998Z,1721663665.998 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-07-22T15:54:26.000Z,1721663666.000 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-07-22T15:54:26.007Z,1721663666.007 [Default:E.Execute](DEBUG): Construct Execute.
2024-07-22T15:54:26.027Z,1721663666.027 [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"
}
}
2024-07-22T15:54:26.031Z,1721663666.031 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,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,Reporter,LogSplitter,
2024-07-22T15:54:26.062Z,1721663666.062 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-07-22T15:54:26.132Z,1721663666.132 [Power24vConverter](INFO): Powering up.
2024-07-22T15:54:26.133Z,1721663666.133 [CANONSampler](INFO): Powering down
2024-07-22T15:54:26.176Z,1721663666.176 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-07-22T15:54:26.182Z,1721663666.182 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-07-22T15:54:26.183Z,1721663666.183 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-07-22T15:54:26.190Z,1721663666.190 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-07-22T15:54:26.191Z,1721663666.191 [MassServo](DEBUG): Initializing EZServoServo.
2024-07-22T15:54:26.198Z,1721663666.198 [MassServo](DEBUG): Initializing MassServo.
2024-07-22T15:54:26.199Z,1721663666.199 [RudderServo](DEBUG): Initializing EZServoServo.
2024-07-22T15:54:26.206Z,1721663666.206 [RudderServo](DEBUG): Initializing RudderServo.
2024-07-22T15:54:26.207Z,1721663666.207 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-07-22T15:54:26.214Z,1721663666.214 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-07-22T15:54:27.550Z,1721663667.550 [WetLabsBB2FL](INFO): Powering down
2024-07-22T15:54:32.051Z,1721663672.051 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-07-22T15:54:38.043Z,1721663678.043 [DAT](INFO): DAT read:
2024-07-22T15:54:38.044Z,1721663678.044 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-07-22T15:54:40.059Z,1721663680.059 [DAT](INFO): DAT read: MF Frequency Band
2024-07-22T15:54:40.061Z,1721663680.061 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2024-07-22T15:54:40.061Z,1721663680.061 [DAT](INFO): DAT read: Jul 22 2024 15:54:50
2024-07-22T15:54:41.067Z,1721663681.067 [DAT](INFO): DAT read: Features enabled [Bearing]
2024-07-22T15:54:41.069Z,1721663681.069 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC
2024-07-22T15:54:41.069Z,1721663681.069 [DAT](INFO): commRate: 1200
2024-07-22T15:54:43.134Z,1721663683.134 [DAT](INFO): entering command mode
2024-07-22T15:54:43.334Z,1721663683.334 [DAT](INFO): DAT read:
2024-07-22T15:54:43.335Z,1721663683.335 [DAT](INFO): DAT read: user:1>
2024-07-22T15:54:43.335Z,1721663683.335 [DAT](INFO): setting verbose to 3
2024-07-22T15:54:43.587Z,1721663683.587 [DAT](INFO): DAT read: user:1>
2024-07-22T15:54:43.588Z,1721663683.588 [DAT](INFO): DAT read: Verbose | 3
2024-07-22T15:54:43.588Z,1721663683.588 [DAT](INFO): set verbose to 3
2024-07-22T15:54:43.588Z,1721663683.588 [DAT](INFO): setting DatVerbose to 27440
2024-07-22T15:54:43.839Z,1721663683.839 [DAT](INFO): DAT read: user:2>
2024-07-22T15:54:43.840Z,1721663683.840 [DAT](INFO): DAT read: DatVerbose | 27440
2024-07-22T15:54:43.840Z,1721663683.840 [DAT](INFO): set DatVerbose to 27440
2024-07-22T15:54:43.840Z,1721663683.840 [DAT](INFO): setting transmit power to 8
2024-07-22T15:54:44.091Z,1721663684.091 [DAT](INFO): DAT read: user:3>
2024-07-22T15:54:44.092Z,1721663684.092 [DAT](INFO): DAT read: TxPower | 8 (Max)
2024-07-22T15:54:44.092Z,1721663684.092 [DAT](INFO): set transmit power to 8
2024-07-22T15:54:44.092Z,1721663684.092 [DAT](INFO): setting local address to 8
2024-07-22T15:54:44.343Z,1721663684.343 [DAT](INFO): DAT read: user:4>
2024-07-22T15:54:44.344Z,1721663684.344 [DAT](INFO): DAT read: LocalAddr | 8
2024-07-22T15:54:44.344Z,1721663684.344 [DAT](INFO): set local address to 8
2024-07-22T15:54:44.345Z,1721663684.345 [DAT](INFO): Setting time to: 15:54:44 And date to:7/22/2024
2024-07-22T15:54:44.595Z,1721663684.595 [DAT](INFO): DAT read: user:5>
2024-07-22T15:54:44.596Z,1721663684.596 [DAT](INFO): DAT read: Mon Jul 22, 2024 15:54:44
2024-07-22T15:54:44.596Z,1721663684.596 [DAT](INFO): Local DAT time set to Mon Jul 22, 2024 15:54:44
2024-07-22T15:54:53.948Z,1721663693.948 [NAL9602](INFO): Powering up NAL9602
2024-07-22T15:55:02.868Z,1721663702.868 [SBIT](IMPORTANT): Beginning Startup BIT
2024-07-22T15:55:02.877Z,1721663702.877 [CBIT](IMPORTANT): Beginning ground fault scan
2024-07-22T15:55:04.852Z,1721663704.852 [NAL9602](INFO): NAL9602 initialized
2024-07-22T15:55:13.519Z,1721663713.519 [CommandExec](IMPORTANT): got command failComponent
2024-07-22T15:55:13.519Z,1721663713.519 [CommandExec](IMPORTANT): Failed components:
2024-07-22T15:55:13.519Z,1721663713.519 [CommandExec](IMPORTANT): No failed Components.
2024-07-22T15:55:13.787Z,1721663713.787 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-07-22T15:55:14.201Z,1721663714.201 [CBIT](IMPORTANT): Beginning ground fault scan
2024-07-22T15:55:16.859Z,1721663716.859 [CommandExec](IMPORTANT): got command strobe off
2024-07-22T15:55:16.859Z,1721663716.859 [CommandExec](IMPORTANT): Deactivating strobe
2024-07-22T15:55:25.183Z,1721663725.183 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-07-22T15:55:25.579Z,1721663725.579 [CBIT](IMPORTANT): Beginning ground fault scan
2024-07-22T15:55:36.435Z,1721663736.435 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-07-22T15:55:36.435Z,1721663736.435 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2024-07-22T15:55:57.060Z,1721663757.060 [SBIT](IMPORTANT): SBIT PASSED
2024-07-22T15:55:57.061Z,1721663757.061 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-07-22T15:55:57.061Z,1721663757.061 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=20 count;
2024-07-22T15:55:57.062Z,1721663757.062 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=244.133390 cubic_centimeter;
2024-07-22T15:55:57.062Z,1721663757.062 [SBIT](IMPORTANT): VerticalControl.massDefault=4.349136 millimeter;
2024-07-22T15:55:57.423Z,1721663757.423 [MissionManager](IMPORTANT): Started mission Startup
2024-07-22T15:55:57.423Z,1721663757.423 [Startup] Running Loop=1
2024-07-22T15:55:57.423Z,1721663757.423 [Startup](DEBUG): Aggregate::initialize Startup
2024-07-22T15:55:57.424Z,1721663757.424 [Startup:A.GoToSurface] Running Loop=1
2024-07-22T15:55:57.424Z,1721663757.424 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-07-22T15:55:57.424Z,1721663757.424 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-07-22T15:55:57.425Z,1721663757.425 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-07-22T15:55:57.425Z,1721663757.425 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-07-22T15:55:57.425Z,1721663757.425 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-07-22T15:55:57.426Z,1721663757.426 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-07-22T15:55:57.430Z,1721663757.430 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-07-22T15:55:57.432Z,1721663757.432 [Startup:StartupSatComms] Running Loop=1
2024-07-22T15:55:57.432Z,1721663757.432 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-07-22T15:55:57.432Z,1721663757.432 [Startup:StartupSatComms:A] Running Loop=1
2024-07-22T15:55:57.811Z,1721663757.811 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-07-22T15:56:18.048Z,1721663778.048 [CommandExec](IMPORTANT): got command show variable canonsampler
2024-07-22T15:56:18.110Z,1721663778.110 [CommandExec](IMPORTANT): CANONSampler.loadControl (none)
2024-07-22T15:56:18.110Z,1721663778.110 [CommandExec](IMPORTANT): CANONSampler.uart (none)
2024-07-22T15:56:18.111Z,1721663778.111 [CommandExec](IMPORTANT): CANONSampler.baud (bit_per_second)
2024-07-22T15:56:18.134Z,1721663778.134 [CommandExec](IMPORTANT): CANONSampler.loadAtStartup (bool)
2024-07-22T15:56:18.134Z,1721663778.134 [CommandExec](IMPORTANT): CANONSampler.simulateHardware (bool)
2024-07-22T15:56:18.135Z,1721663778.135 [CommandExec](IMPORTANT): CANONSampler.rotateOnly (bool)
2024-07-22T15:56:18.135Z,1721663778.135 [CommandExec](IMPORTANT): CANONSampler.sampleTimeout (minute)
2024-07-22T15:56:18.262Z,1721663778.262 [CommandExec](IMPORTANT): CANONSampler.enableBroadcast (bool)
2024-07-22T15:56:18.262Z,1721663778.262 [CommandExec](IMPORTANT): CANONSampler.component_voltage (volt)
2024-07-22T15:56:18.263Z,1721663778.263 [CommandExec](IMPORTANT): CANONSampler.component_avgVoltage (volt)
2024-07-22T15:56:18.263Z,1721663778.263 [CommandExec](IMPORTANT): CANONSampler.component_current (milliampere)
2024-07-22T15:56:18.263Z,1721663778.263 [CommandExec](IMPORTANT): CANONSampler.component_avgCurrent (milliampere)
2024-07-22T15:56:18.264Z,1721663778.264 [CommandExec](IMPORTANT): CANONSampler.component_discreteIn (bool)
2024-07-22T15:56:18.264Z,1721663778.264 [CommandExec](IMPORTANT): CANONSampler.sampling (bool)
2024-07-22T15:56:18.264Z,1721663778.264 [CommandExec](IMPORTANT): CANONSampler.sample_number (count)
2024-07-22T15:56:22.013Z,1721663782.013 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-22T15:56:34.488Z,1721663794.488 [CommandExec](IMPORTANT): got command get CANONSampler.loadAtStartup
2024-07-22T15:56:34.488Z,1721663794.488 [CommandExec](IMPORTANT): CANONSampler.loadAtStartup 1 bool
2024-07-22T15:56:35.344Z,1721663795.344 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-22T15:56:48.270Z,1721663808.270 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-22T15:56:57.590Z,1721663817.590 [Startup:StartupSatComms:A](INFO): Timed out from 2024-07-22T15:55:57.4Z
2024-07-22T15:56:57.590Z,1721663817.590 [Startup:StartupSatComms:A] Stopped
2024-07-22T15:56:57.591Z,1721663817.591 [Startup:StartupSatComms:B] Running Loop=1
2024-07-22T15:56:58.042Z,1721663818.042 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-07-22T15:57:00.796Z,1721663820.796 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-22T15:57:08.273Z,1721663828.273 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005198
2024-07-22T15:57:13.723Z,1721663833.723 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-22T15:57:15.678Z,1721663835.678 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20240722T154156/Courier0007.lzma
2024-07-22T15:57:16.680Z,1721663836.680 [DataOverHttps](INFO): Moved sent file to Logs/20240722T154156/Courier0007.lzma.bak
2024-07-22T15:57:16.680Z,1721663836.680 [DataOverHttps](INFO): SBD MOMSN=21379125
2024-07-22T15:57:25.487Z,1721663845.487 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-07-22T15:57:25.487Z,1721663845.487 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-22T15:57:25.520Z,1721663845.520 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-22T15:57:25.920Z,1721663845.920 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-22T15:57:25.920Z,1721663845.920 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-07-22T15:57:26.247Z,1721663846.247 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-22T15:57:32.222Z,1721663852.222 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240722T155414/Courier0000.lzma
2024-07-22T15:57:33.224Z,1721663853.224 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Courier0000.lzma.bak
2024-07-22T15:57:33.224Z,1721663853.224 [DataOverHttps](INFO): SBD MOMSN=21379127
2024-07-22T15:57:39.186Z,1721663859.186 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-22T15:57:45.899Z,1721663865.899 [CommandExec](IMPORTANT): got command burn on
2024-07-22T15:57:45.899Z,1721663865.899 [CommandExec](IMPORTANT): Activating dropweight wire
2024-07-22T15:57:48.898Z,1721663868.898 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240722T154156/Express0008.lzma
2024-07-22T15:57:49.900Z,1721663869.900 [DataOverHttps](INFO): Moved sent file to Logs/20240722T154156/Express0008.lzma.bak
2024-07-22T15:57:49.900Z,1721663869.900 [DataOverHttps](INFO): SBD MOMSN=21379130
2024-07-22T15:57:50.059Z,1721663870.059 [CommandExec](IMPORTANT): got command burn off
2024-07-22T15:57:50.059Z,1721663870.059 [CommandExec](IMPORTANT): Deactivating dropweight wire
2024-07-22T15:57:52.114Z,1721663872.114 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-22T15:57:57.781Z,1721663877.781 [Startup:StartupSatComms:B](INFO): Timed out from 2024-07-22T15:56:57.6Z
2024-07-22T15:57:57.781Z,1721663877.781 [Startup:StartupSatComms:B] Stopped
2024-07-22T15:57:57.781Z,1721663877.781 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-07-22T15:57:57.781Z,1721663877.781 [Startup:StartupSatComms] Stopped
2024-07-22T15:57:57.781Z,1721663877.781 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-07-22T15:57:57.782Z,1721663877.782 [Startup](INFO): Completed Startup
2024-07-22T15:57:57.783Z,1721663877.783 [MissionManager](INFO): Startup is completed.
2024-07-22T15:57:57.783Z,1721663877.783 [MissionManager](INFO): Uninitializing Mission Startup
2024-07-22T15:57:57.783Z,1721663877.783 [Startup] Stopped
2024-07-22T15:57:57.783Z,1721663877.783 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-07-22T15:57:57.783Z,1721663877.783 [Startup:A.GoToSurface] Stopped
2024-07-22T15:57:57.783Z,1721663877.783 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-07-22T15:57:58.194Z,1721663878.194 [MissionManager](IMPORTANT): Started mission Default
2024-07-22T15:57:58.195Z,1721663878.195 [Default] Running Loop=1
2024-07-22T15:57:58.195Z,1721663878.195 [Default](DEBUG): Aggregate::initialize Default
2024-07-22T15:57:58.195Z,1721663878.195 [Default:B.GoToSurface] Running Loop=1
2024-07-22T15:57:58.195Z,1721663878.195 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-07-22T15:57:58.195Z,1721663878.195 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-07-22T15:57:58.195Z,1721663878.195 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-07-22T15:57:58.196Z,1721663878.196 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-07-22T15:57:58.196Z,1721663878.196 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-07-22T15:57:58.196Z,1721663878.196 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-07-22T15:57:58.196Z,1721663878.196 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-07-22T15:57:58.197Z,1721663878.197 [Default:A.Wait] Running Loop=1
2024-07-22T15:57:58.197Z,1721663878.197 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-07-22T15:58:01.939Z,1721663881.939 [CommandExec](IMPORTANT): got command gfscan
2024-07-22T15:58:02.276Z,1721663882.276 [CBIT](IMPORTANT): Beginning ground fault scan
2024-07-22T15:58:04.224Z,1721663884.224 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-22T15:58:05.494Z,1721663885.494 [DataOverHttps](INFO): Sending 1008 bytes from file Logs/20240722T155414/Express0001.lzma
2024-07-22T15:58:06.492Z,1721663886.492 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Express0001.lzma.bak
2024-07-22T15:58:06.492Z,1721663886.492 [DataOverHttps](INFO): SBD MOMSN=21379134
2024-07-22T15:58:11.536Z,1721663891.536 [Default:A.Wait](INFO): Done Waiting.
2024-07-22T15:58:11.536Z,1721663891.536 [Default:A.Wait] Stopped
2024-07-22T15:58:11.536Z,1721663891.536 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-07-22T15:58:11.937Z,1721663891.937 [Default:CheckIn] Running Loop=1
2024-07-22T15:58:11.937Z,1721663891.937 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-22T15:58:11.937Z,1721663891.937 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-22T15:58:12.349Z,1721663892.349 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-07-22T15:58:13.144Z,1721663893.144 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.000078
CHAN A1 (24V): 0.000390
CHAN A2 (12V): -0.002362
CHAN A3 (5V): -0.001869
CHAN B0 (3.3V): -0.000051
CHAN B1 (3.15aV): -0.000646
CHAN B2 (3.15bV): -0.000108
CHAN B3 (GND): -0.000051
OPEN: -0.000435
Full Scale: +/- 1 mA
2024-07-22T15:58:16.747Z,1721663896.747 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-22T15:58:29.675Z,1721663909.675 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-22T15:58:43.828Z,1721663923.828 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 4.
2024-07-22T15:58:43.832Z,1721663923.832 [BPC1](INFO): Received data from all battery sticks.
2024-07-22T16:00:08.704Z,1721664008.704 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-22T16:00:26.511Z,1721664026.511 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-07-22T16:00:26.511Z,1721664026.511 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-22T16:00:26.522Z,1721664026.522 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-22T16:00:26.915Z,1721664026.915 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-22T16:00:26.915Z,1721664026.915 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-07-22T16:03:12.132Z,1721664192.132 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-07-22T15:58:11.9Z
2024-07-22T16:03:12.132Z,1721664192.132 [Default:CheckIn:Read_GPS] Stopped
2024-07-22T16:03:12.132Z,1721664192.132 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-22T16:03:12.561Z,1721664192.561 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-07-22T16:03:19.439Z,1721664199.439 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20240722T155414/Courier0004.lzma
2024-07-22T16:03:20.440Z,1721664200.440 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Courier0004.lzma.bak
2024-07-22T16:03:20.440Z,1721664200.440 [DataOverHttps](INFO): SBD MOMSN=21379176
2024-07-22T16:03:27.499Z,1721664207.499 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2024-07-22T16:03:27.499Z,1721664207.499 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-22T16:03:27.530Z,1721664207.530 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-22T16:03:27.917Z,1721664207.917 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-22T16:03:27.917Z,1721664207.917 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2024-07-22T16:03:35.863Z,1721664215.863 [DataOverHttps](INFO): Sending 429 bytes from file Logs/20240722T155414/Express0005.lzma
2024-07-22T16:03:36.864Z,1721664216.864 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Express0005.lzma.bak
2024-07-22T16:03:36.864Z,1721664216.864 [DataOverHttps](INFO): SBD MOMSN=21379180
2024-07-22T16:03:37.987Z,1721664217.987 [Default:CheckIn:Read_Iridium] Stopped
2024-07-22T16:03:37.987Z,1721664217.987 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-22T16:03:37.987Z,1721664217.987 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-22T16:05:07.690Z,1721664307.690 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-07-22T16:05:07.690Z,1721664307.690 [NAL9602] Data Fault, FailCount= 1
2024-07-22T16:05:07.690Z,1721664307.690 [NAL9602](ERROR): Data Fault
2024-07-22T16:05:07.707Z,1721664307.707 [CBIT](ERROR): Data Fault in component: NAL9602
2024-07-22T16:05:08.100Z,1721664308.100 [NAL9602](INFO): Powering down
2024-07-22T16:05:08.921Z,1721664308.921 [CBIT](INFO): Clearing failed state for component NAL9602
2024-07-22T16:05:08.921Z,1721664308.921 [NAL9602] No Fault, FailCount= 1
2024-07-22T16:05:38.393Z,1721664338.393 [NAL9602](INFO): Powering up NAL9602
2024-07-22T16:05:49.332Z,1721664349.332 [NAL9602](INFO): NAL9602 initialized
2024-07-22T16:06:28.553Z,1721664388.553 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2024-07-22T16:06:28.553Z,1721664388.553 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-22T16:06:28.564Z,1721664388.564 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-22T16:06:28.977Z,1721664388.977 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-22T16:06:28.977Z,1721664388.977 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2024-07-22T16:08:38.617Z,1721664518.617 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-22T16:08:38.617Z,1721664518.617 [Default:CheckIn:C.Wait] Stopped
2024-07-22T16:08:38.618Z,1721664518.618 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-22T16:08:38.618Z,1721664518.618 [Default:CheckIn:D] Running Loop=1
2024-07-22T16:08:39.024Z,1721664519.024 [Default:CheckIn:D] Stopped
2024-07-22T16:08:39.025Z,1721664519.025 [Default:CheckIn:E] Running Loop=1
2024-07-22T16:08:39.445Z,1721664519.445 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.680498 min
2024-07-22T16:08:39.445Z,1721664519.445 [Default:CheckIn:E] Stopped
2024-07-22T16:08:39.445Z,1721664519.445 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-22T16:08:39.445Z,1721664519.445 [Default:CheckIn] Stopped
2024-07-22T16:08:39.445Z,1721664519.445 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-22T16:08:39.445Z,1721664519.445 [Default:CheckIn](INFO): Running loop #2
2024-07-22T16:08:39.445Z,1721664519.445 [Default:CheckIn] Running Loop=2
2024-07-22T16:08:39.446Z,1721664519.446 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-22T16:08:39.446Z,1721664519.446 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-22T16:09:29.528Z,1721664569.528 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2024-07-22T16:09:29.528Z,1721664569.528 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-22T16:09:29.538Z,1721664569.538 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-22T16:09:29.948Z,1721664569.948 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-22T16:09:29.948Z,1721664569.948 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2024-07-22T16:10:01.025Z,1721664601.025 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160959.00,A,3648.16729,N,12147.25716,W,1.244,0.00,220724,,,A*78
2024-07-22T16:10:01.033Z,1721664601.033 [NAL9602](INFO): GPS fix at 20240722T160959: (36.802788, -121.787619)
2024-07-22T16:10:01.044Z,1721664601.044 [Default:CheckIn:Read_GPS] Stopped
2024-07-22T16:10:01.044Z,1721664601.044 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-22T16:10:10.431Z,1721664610.431 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20240722T155414/Courier0007.lzma
2024-07-22T16:10:11.432Z,1721664611.432 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Courier0007.lzma.bak
2024-07-22T16:10:11.432Z,1721664611.432 [DataOverHttps](INFO): SBD MOMSN=21379193
2024-07-22T16:10:32.206Z,1721664632.206 [DataOverHttps](INFO): Sending 310 bytes from file Logs/20240722T155414/Express0008.lzma
2024-07-22T16:10:33.208Z,1721664633.208 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Express0008.lzma.bak
2024-07-22T16:10:33.208Z,1721664633.208 [DataOverHttps](INFO): SBD MOMSN=21379196
2024-07-22T16:10:33.382Z,1721664633.382 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-22T16:10:34.617Z,1721664634.617 [Default:CheckIn:Read_Iridium] Stopped
2024-07-22T16:10:34.617Z,1721664634.617 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-22T16:10:34.617Z,1721664634.617 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-22T16:11:12.388Z,1721664672.388 [DataOverHttps](IMPORTANT): SBD MTMSN=20240722T161111
2024-07-22T16:11:19.782Z,1721664679.782 [DataOverHttps](INFO): Received command: ibit
2024-07-22T16:11:19.787Z,1721664679.787 [CommandExec](IMPORTANT): got command ibit
2024-07-22T16:11:19.884Z,1721664679.884 [IBIT](IMPORTANT): Beginning Initiated BIT
2024-07-22T16:11:19.884Z,1721664679.884 [IBIT](IMPORTANT): Beginning control surface checks.
2024-07-22T16:11:19.887Z,1721664679.887 [CBIT](IMPORTANT): Beginning ground fault scan
2024-07-22T16:11:20.783Z,1721664680.783 [DataOverHttps](IMPORTANT): SBD MTMSN=20240722T161119
2024-07-22T16:11:21.473Z,1721664681.473 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161119.00,A,3648.14435,N,12147.25218,W,0.583,123.12,220724,,,A*7C
2024-07-22T16:11:21.484Z,1721664681.484 [NAL9602](INFO): GPS fix at 20240722T161119: (36.802406, -121.787536)
2024-07-22T16:11:28.358Z,1721664688.358 [DataOverHttps](INFO): Received command: failc
2024-07-22T16:11:28.383Z,1721664688.383 [CommandExec](IMPORTANT): got command failComponent
2024-07-22T16:11:28.383Z,1721664688.383 [CommandExec](IMPORTANT): Failed components:
2024-07-22T16:11:28.383Z,1721664688.383 [CommandExec](IMPORTANT): No failed Components.
2024-07-22T16:11:30.784Z,1721664690.784 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-07-22T16:11:31.201Z,1721664691.201 [CBIT](IMPORTANT): Beginning ground fault scan
2024-07-22T16:11:40.053Z,1721664700.053 [NAL9602](INFO): SBD MO Status=0, MOMSN=53088, MT Status=0, MTMSN=0
2024-07-22T16:11:40.053Z,1721664700.053 [NAL9602](INFO): No messages in MT queue
2024-07-22T16:11:41.264Z,1721664701.264 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161139.00,A,3648.13831,N,12147.24210,W,2.294,109.97,220724,,,D*7B
2024-07-22T16:11:41.266Z,1721664701.266 [NAL9602](INFO): GPS fix at 20240722T161139: (36.802305, -121.787368)
2024-07-22T16:11:42.180Z,1721664702.180 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002889
CHAN A1 (24V): 0.000484
CHAN A2 (12V): -0.002463
CHAN A3 (5V): -0.002113
CHAN B0 (3.3V): 0.000207
CHAN B1 (3.15aV): -0.000629
CHAN B2 (3.15bV): -0.000196
CHAN B3 (GND): -0.000129
OPEN: -0.000657
Full Scale: +/- 1 mA
2024-07-22T16:11:44.092Z,1721664704.092 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161142.00,A,3648.13808,N,12147.23976,W,2.216,102.93,220724,,,D*74
2024-07-22T16:11:44.094Z,1721664704.094 [NAL9602](INFO): GPS fix at 20240722T161142: (36.802301, -121.787329)
2024-07-22T16:11:46.922Z,1721664706.922 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161145.00,A,3648.13772,N,12147.23762,W,2.158,108.88,220724,,,D*73
2024-07-22T16:11:46.924Z,1721664706.924 [NAL9602](INFO): GPS fix at 20240722T161145: (36.802295, -121.787294)
2024-07-22T16:11:50.162Z,1721664710.162 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161148.00,A,3648.13732,N,12147.23531,W,1.924,106.84,220724,,,D*7C
2024-07-22T16:11:50.164Z,1721664710.164 [NAL9602](INFO): GPS fix at 20240722T161148: (36.802289, -121.787255)
2024-07-22T16:11:52.984Z,1721664712.984 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161151.00,A,3648.13673,N,12147.23303,W,2.313,110.21,220724,,,D*72
2024-07-22T16:11:52.987Z,1721664712.987 [NAL9602](INFO): GPS fix at 20240722T161151: (36.802279, -121.787217)
2024-07-22T16:11:55.809Z,1721664715.809 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161154.00,A,3648.13587,N,12147.23065,W,2.333,112.75,220724,,,D*7D
2024-07-22T16:11:55.811Z,1721664715.811 [NAL9602](INFO): GPS fix at 20240722T161154: (36.802264, -121.787177)
2024-07-22T16:11:59.040Z,1721664719.040 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161157.00,A,3648.13504,N,12147.22805,W,2.119,109.09,220724,,,D*71
2024-07-22T16:11:59.042Z,1721664719.042 [NAL9602](INFO): GPS fix at 20240722T161157: (36.802251, -121.787134)
2024-07-22T16:12:01.873Z,1721664721.873 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161200.00,A,3648.13438,N,12147.22534,W,2.449,106.84,220724,,,D*7B
2024-07-22T16:12:01.882Z,1721664721.882 [NAL9602](INFO): GPS fix at 20240722T161200: (36.802240, -121.787089)
2024-07-22T16:12:05.100Z,1721664725.100 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161203.00,A,3648.13414,N,12147.22300,W,2.488,100.85,220724,,,D*7D
2024-07-22T16:12:05.110Z,1721664725.110 [NAL9602](INFO): GPS fix at 20240722T161203: (36.802236, -121.787050)
2024-07-22T16:12:06.372Z,1721664726.372 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 3
Latitude: 36.802235 Longitude: -121.787048
2024-07-22T16:12:06.748Z,1721664726.748 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.650999
2024-07-22T16:12:06.748Z,1721664726.748 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2024-07-22T16:12:06.748Z,1721664726.748 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2024-07-22T16:12:07.154Z,1721664727.154 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2024-07-22T16:12:07.154Z,1721664727.154 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2024-07-22T16:12:07.155Z,1721664727.155 [IBIT](IMPORTANT): Pressure:10.298913 PSI
2024-07-22T16:12:07.155Z,1721664727.155 [IBIT](IMPORTANT): Humidity:14.656331 %
2024-07-22T16:12:07.543Z,1721664727.543 [IBIT](IMPORTANT): Vehicle Pitch:0.047676 degrees
2024-07-22T16:12:07.543Z,1721664727.543 [IBIT](IMPORTANT): Vehicle Roll:0.874196 degrees
2024-07-22T16:12:07.543Z,1721664727.543 [IBIT](IMPORTANT): Vehicle Heading:78.932907 degrees
2024-07-22T16:12:07.947Z,1721664727.947 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2024-07-22T16:12:07.947Z,1721664727.947 [IBIT](IMPORTANT): buoyancyNeutral: 244.133392 cc
2024-07-22T16:12:07.947Z,1721664727.947 [IBIT](IMPORTANT): massDefault: 0.434914 cm
2024-07-22T16:12:07.948Z,1721664727.948 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2024-07-22T16:12:07.948Z,1721664727.948 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2024-07-22T16:12:07.948Z,1721664727.948 [IBIT](IMPORTANT): IBIT PASSED
2024-07-22T16:12:37.423Z,1721664757.423 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-22T16:15:35.215Z,1721664935.215 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-22T16:15:35.216Z,1721664935.216 [Default:CheckIn:C.Wait] Stopped
2024-07-22T16:15:35.216Z,1721664935.216 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-22T16:15:35.216Z,1721664935.216 [Default:CheckIn:D] Running Loop=1
2024-07-22T16:15:35.608Z,1721664935.608 [Default:CheckIn:D] Stopped
2024-07-22T16:15:35.608Z,1721664935.608 [Default:CheckIn:E] Running Loop=1
2024-07-22T16:15:36.008Z,1721664936.008 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.623560 min
2024-07-22T16:15:36.008Z,1721664936.008 [Default:CheckIn:E] Stopped
2024-07-22T16:15:36.008Z,1721664936.008 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-22T16:15:36.009Z,1721664936.009 [Default:CheckIn] Stopped
2024-07-22T16:15:36.009Z,1721664936.009 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-22T16:15:36.009Z,1721664936.009 [Default:CheckIn](INFO): Running loop #3
2024-07-22T16:15:36.009Z,1721664936.009 [Default:CheckIn] Running Loop=3
2024-07-22T16:15:36.009Z,1721664936.009 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-22T16:15:36.009Z,1721664936.009 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-22T16:15:38.021Z,1721664938.021 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161536.00,A,3648.13668,N,12147.21071,W,0.039,56.76,220724,,,D*41
2024-07-22T16:15:38.023Z,1721664938.023 [NAL9602](INFO): GPS fix at 20240722T161536: (36.802278, -121.786845)
2024-07-22T16:15:38.033Z,1721664938.033 [Default:CheckIn:Read_GPS] Stopped
2024-07-22T16:15:38.033Z,1721664938.033 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-22T16:15:45.210Z,1721664945.210 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240722T155414/Courier0010.lzma
2024-07-22T16:15:46.212Z,1721664946.212 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Courier0010.lzma.bak
2024-07-22T16:15:46.212Z,1721664946.212 [DataOverHttps](INFO): SBD MOMSN=21379215
2024-07-22T16:15:51.357Z,1721664951.357 [NAL9602](INFO): SBD MO Status=0, MOMSN=53089, MT Status=0, MTMSN=0
2024-07-22T16:15:51.357Z,1721664951.357 [NAL9602](INFO): No messages in MT queue
2024-07-22T16:16:01.908Z,1721664961.908 [DataOverHttps](INFO): Sending 897 bytes from file Logs/20240722T155414/Express0011.lzma
2024-07-22T16:16:02.908Z,1721664962.908 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Express0011.lzma.bak
2024-07-22T16:16:02.908Z,1721664962.908 [DataOverHttps](INFO): SBD MOMSN=21379218
2024-07-22T16:16:19.630Z,1721664979.630 [DataOverHttps](INFO): Sending 53 bytes from file Logs/20240722T155414/Express0014.lzma
2024-07-22T16:16:20.632Z,1721664980.632 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Express0014.lzma.bak
2024-07-22T16:16:20.632Z,1721664980.632 [DataOverHttps](INFO): SBD MOMSN=21379250
2024-07-22T16:16:21.681Z,1721664981.681 [Default:CheckIn:Read_Iridium] Stopped
2024-07-22T16:16:21.681Z,1721664981.681 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-22T16:16:21.681Z,1721664981.681 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-22T16:16:22.072Z,1721664982.072 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-22T16:21:22.320Z,1721665282.320 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-22T16:21:22.320Z,1721665282.320 [Default:CheckIn:C.Wait] Stopped
2024-07-22T16:21:22.320Z,1721665282.320 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-22T16:21:22.321Z,1721665282.321 [Default:CheckIn:D] Running Loop=1
2024-07-22T16:21:22.712Z,1721665282.712 [Default:CheckIn:D] Stopped
2024-07-22T16:21:22.712Z,1721665282.712 [Default:CheckIn:E] Running Loop=1
2024-07-22T16:21:23.113Z,1721665283.113 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.408628 min
2024-07-22T16:21:23.113Z,1721665283.113 [Default:CheckIn:E] Stopped
2024-07-22T16:21:23.113Z,1721665283.113 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-22T16:21:23.113Z,1721665283.113 [Default:CheckIn] Stopped
2024-07-22T16:21:23.113Z,1721665283.113 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-22T16:21:23.114Z,1721665283.114 [Default:CheckIn](INFO): Running loop #4
2024-07-22T16:21:23.114Z,1721665283.114 [Default:CheckIn] Running Loop=4
2024-07-22T16:21:23.114Z,1721665283.114 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-22T16:21:23.114Z,1721665283.114 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-22T16:21:25.127Z,1721665285.127 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162124.00,A,3648.13722,N,12147.20985,W,0.019,56.76,220724,,,D*4B
2024-07-22T16:21:25.129Z,1721665285.129 [NAL9602](INFO): GPS fix at 20240722T162124: (36.802287, -121.786831)
2024-07-22T16:21:25.139Z,1721665285.139 [Default:CheckIn:Read_GPS] Stopped
2024-07-22T16:21:25.139Z,1721665285.139 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-22T16:21:32.902Z,1721665292.902 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240722T155414/Courier0016.lzma
2024-07-22T16:21:33.904Z,1721665293.904 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Courier0016.lzma.bak
2024-07-22T16:21:33.904Z,1721665293.904 [DataOverHttps](INFO): SBD MOMSN=21379264
2024-07-22T16:21:36.437Z,1721665296.437 [NAL9602](INFO): SBD MO Status=0, MOMSN=53090, MT Status=0, MTMSN=0
2024-07-22T16:21:36.437Z,1721665296.437 [NAL9602](INFO): No messages in MT queue
2024-07-22T16:21:49.783Z,1721665309.783 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240722T155414/Express0017.lzma
2024-07-22T16:21:50.780Z,1721665310.780 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Express0017.lzma.bak
2024-07-22T16:21:50.780Z,1721665310.780 [DataOverHttps](INFO): SBD MOMSN=21379268
2024-07-22T16:21:51.845Z,1721665311.845 [Default:CheckIn:Read_Iridium] Stopped
2024-07-22T16:21:51.845Z,1721665311.845 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-22T16:21:51.845Z,1721665311.845 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-22T16:22:07.159Z,1721665327.159 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-22T16:26:52.461Z,1721665612.461 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-22T16:26:52.461Z,1721665612.461 [Default:CheckIn:C.Wait] Stopped
2024-07-22T16:26:52.461Z,1721665612.461 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-22T16:26:52.461Z,1721665612.461 [Default:CheckIn:D] Running Loop=1
2024-07-22T16:26:52.844Z,1721665612.844 [Default:CheckIn:D] Stopped
2024-07-22T16:26:52.844Z,1721665612.844 [Default:CheckIn:E] Running Loop=1
2024-07-22T16:26:53.249Z,1721665613.249 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.910819 min
2024-07-22T16:26:53.249Z,1721665613.249 [Default:CheckIn:E] Stopped
2024-07-22T16:26:53.249Z,1721665613.249 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-22T16:26:53.249Z,1721665613.249 [Default:CheckIn] Stopped
2024-07-22T16:26:53.249Z,1721665613.249 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-22T16:26:53.249Z,1721665613.249 [Default:CheckIn](INFO): Running loop #5
2024-07-22T16:26:53.249Z,1721665613.249 [Default:CheckIn] Running Loop=5
2024-07-22T16:26:53.250Z,1721665613.250 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-22T16:26:53.250Z,1721665613.250 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-22T16:26:55.249Z,1721665615.249 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162654.00,A,3648.13711,N,12147.20943,W,0.000,56.76,220724,,,D*49
2024-07-22T16:26:55.251Z,1721665615.251 [NAL9602](INFO): GPS fix at 20240722T162654: (36.802285, -121.786824)
2024-07-22T16:26:55.287Z,1721665615.287 [Default:CheckIn:Read_GPS] Stopped
2024-07-22T16:26:55.287Z,1721665615.287 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-22T16:27:02.738Z,1721665622.738 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240722T155414/Courier0019.lzma
2024-07-22T16:27:03.740Z,1721665623.740 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Courier0019.lzma.bak
2024-07-22T16:27:03.740Z,1721665623.740 [DataOverHttps](INFO): SBD MOMSN=21379279
2024-07-22T16:27:06.564Z,1721665626.564 [NAL9602](INFO): SBD MO Status=0, MOMSN=53091, MT Status=0, MTMSN=0
2024-07-22T16:27:06.565Z,1721665626.565 [NAL9602](INFO): No messages in MT queue
2024-07-22T16:27:19.506Z,1721665639.506 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240722T155414/Express0020.lzma
2024-07-22T16:27:20.508Z,1721665640.508 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Express0020.lzma.bak
2024-07-22T16:27:20.508Z,1721665640.508 [DataOverHttps](INFO): SBD MOMSN=21379282
2024-07-22T16:27:21.540Z,1721665641.540 [Default:CheckIn:Read_Iridium] Stopped
2024-07-22T16:27:21.540Z,1721665641.540 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-22T16:27:21.540Z,1721665641.540 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-22T16:27:37.281Z,1721665657.281 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-22T16:32:22.165Z,1721665942.165 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-22T16:32:22.165Z,1721665942.165 [Default:CheckIn:C.Wait] Stopped
2024-07-22T16:32:22.165Z,1721665942.165 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-22T16:32:22.165Z,1721665942.165 [Default:CheckIn:D] Running Loop=1
2024-07-22T16:32:22.545Z,1721665942.545 [Default:CheckIn:D] Stopped
2024-07-22T16:32:22.545Z,1721665942.545 [Default:CheckIn:E] Running Loop=1
2024-07-22T16:32:22.948Z,1721665942.948 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 34.405835 min
2024-07-22T16:32:22.948Z,1721665942.948 [Default:CheckIn:E] Stopped
2024-07-22T16:32:22.948Z,1721665942.948 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-22T16:32:22.948Z,1721665942.948 [Default:CheckIn] Stopped
2024-07-22T16:32:22.949Z,1721665942.949 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-22T16:32:22.949Z,1721665942.949 [Default:CheckIn](INFO): Running loop #6
2024-07-22T16:32:22.949Z,1721665942.949 [Default:CheckIn] Running Loop=6
2024-07-22T16:32:22.949Z,1721665942.949 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-22T16:32:22.949Z,1721665942.949 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-22T16:32:24.952Z,1721665944.952 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163224.00,A,3648.13700,N,12147.21018,W,0.058,56.76,220724,,,D*40
2024-07-22T16:32:24.954Z,1721665944.954 [NAL9602](INFO): GPS fix at 20240722T163224: (36.802283, -121.786836)
2024-07-22T16:32:24.964Z,1721665944.964 [Default:CheckIn:Read_GPS] Stopped
2024-07-22T16:32:24.965Z,1721665944.965 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-22T16:32:32.794Z,1721665952.794 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240722T155414/Courier0022.lzma
2024-07-22T16:32:33.796Z,1721665953.796 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Courier0022.lzma.bak
2024-07-22T16:32:33.796Z,1721665953.796 [DataOverHttps](INFO): SBD MOMSN=21379287
2024-07-22T16:32:41.925Z,1721665961.925 [NAL9602](INFO): SBD MO Status=0, MOMSN=53092, MT Status=0, MTMSN=0
2024-07-22T16:32:41.925Z,1721665961.925 [NAL9602](INFO): No messages in MT queue
2024-07-22T16:32:51.327Z,1721665971.327 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240722T155414/Express0023.lzma
2024-07-22T16:32:52.328Z,1721665972.328 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Express0023.lzma.bak
2024-07-22T16:32:52.328Z,1721665972.328 [DataOverHttps](INFO): SBD MOMSN=21379294
2024-07-22T16:32:53.669Z,1721665973.669 [Default:CheckIn:Read_Iridium] Stopped
2024-07-22T16:32:53.669Z,1721665973.669 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-22T16:32:53.669Z,1721665973.669 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-22T16:33:12.638Z,1721665992.638 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-22T16:37:54.245Z,1721666274.245 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-22T16:37:54.245Z,1721666274.245 [Default:CheckIn:C.Wait] Stopped
2024-07-22T16:37:54.245Z,1721666274.245 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-22T16:37:54.245Z,1721666274.245 [Default:CheckIn:D] Running Loop=1
2024-07-22T16:37:54.645Z,1721666274.645 [Default:CheckIn:D] Stopped
2024-07-22T16:37:54.645Z,1721666274.645 [Default:CheckIn:E] Running Loop=1
2024-07-22T16:37:55.051Z,1721666275.051 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 39.940837 min
2024-07-22T16:37:55.051Z,1721666275.051 [Default:CheckIn:E] Stopped
2024-07-22T16:37:55.051Z,1721666275.051 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-22T16:37:55.051Z,1721666275.051 [Default:CheckIn] Stopped
2024-07-22T16:37:55.051Z,1721666275.051 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-22T16:37:55.051Z,1721666275.051 [Default:CheckIn](INFO): Running loop #7
2024-07-22T16:37:55.051Z,1721666275.051 [Default:CheckIn] Running Loop=7
2024-07-22T16:37:55.052Z,1721666275.052 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-22T16:37:55.052Z,1721666275.052 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-22T16:37:57.061Z,1721666277.061 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163756.00,A,3648.13661,N,12147.21002,W,0.058,56.76,220724,,,D*4D
2024-07-22T16:37:57.063Z,1721666277.063 [NAL9602](INFO): GPS fix at 20240722T163756: (36.802277, -121.786834)
2024-07-22T16:37:57.073Z,1721666277.073 [Default:CheckIn:Read_GPS] Stopped
2024-07-22T16:37:57.073Z,1721666277.073 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-22T16:38:03.990Z,1721666283.990 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240722T155414/Courier0025.lzma
2024-07-22T16:38:04.992Z,1721666284.992 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Courier0025.lzma.bak
2024-07-22T16:38:04.992Z,1721666284.992 [DataOverHttps](INFO): SBD MOMSN=21379311
2024-07-22T16:38:12.816Z,1721666292.816 [NAL9602](INFO): SBD MO Status=0, MOMSN=53093, MT Status=0, MTMSN=0
2024-07-22T16:38:12.817Z,1721666292.817 [NAL9602](INFO): No messages in MT queue
2024-07-22T16:38:23.314Z,1721666303.314 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240722T155414/Express0026.lzma
2024-07-22T16:38:24.316Z,1721666304.316 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Express0026.lzma.bak
2024-07-22T16:38:24.316Z,1721666304.316 [DataOverHttps](INFO): SBD MOMSN=21379314
2024-07-22T16:38:25.408Z,1721666305.408 [Default:CheckIn:Read_Iridium] Stopped
2024-07-22T16:38:25.408Z,1721666305.408 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-22T16:38:25.408Z,1721666305.408 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-22T16:38:43.558Z,1721666323.558 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-22T16:43:25.975Z,1721666605.975 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-22T16:43:25.976Z,1721666605.976 [Default:CheckIn:C.Wait] Stopped
2024-07-22T16:43:25.976Z,1721666605.976 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-22T16:43:25.976Z,1721666605.976 [Default:CheckIn:D] Running Loop=1
2024-07-22T16:43:26.373Z,1721666606.373 [Default:CheckIn:D] Stopped
2024-07-22T16:43:26.373Z,1721666606.373 [Default:CheckIn:E] Running Loop=1
2024-07-22T16:43:26.774Z,1721666606.774 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 45.469645 min
2024-07-22T16:43:26.774Z,1721666606.774 [Default:CheckIn:E] Stopped
2024-07-22T16:43:26.774Z,1721666606.774 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-22T16:43:26.774Z,1721666606.774 [Default:CheckIn] Stopped
2024-07-22T16:43:26.774Z,1721666606.774 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-22T16:43:26.775Z,1721666606.775 [Default:CheckIn](INFO): Running loop #8
2024-07-22T16:43:26.775Z,1721666606.775 [Default:CheckIn] Running Loop=8
2024-07-22T16:43:26.775Z,1721666606.775 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-22T16:43:26.775Z,1721666606.775 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-22T16:43:28.786Z,1721666608.786 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164328.00,A,3648.13639,N,12147.20934,W,0.019,56.76,220724,,,D*42
2024-07-22T16:43:28.789Z,1721666608.789 [NAL9602](INFO): GPS fix at 20240722T164328: (36.802273, -121.786822)
2024-07-22T16:43:28.799Z,1721666608.799 [Default:CheckIn:Read_GPS] Stopped
2024-07-22T16:43:28.799Z,1721666608.799 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-22T16:43:35.778Z,1721666615.778 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240722T155414/Courier0028.lzma
2024-07-22T16:43:36.780Z,1721666616.780 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Courier0028.lzma.bak
2024-07-22T16:43:36.780Z,1721666616.780 [DataOverHttps](INFO): SBD MOMSN=21379318
2024-07-22T16:43:40.501Z,1721666620.501 [NAL9602](INFO): SBD MO Status=0, MOMSN=53094, MT Status=0, MTMSN=0
2024-07-22T16:43:40.501Z,1721666620.501 [NAL9602](INFO): No messages in MT queue
2024-07-22T16:43:52.518Z,1721666632.518 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240722T155414/Express0029.lzma
2024-07-22T16:43:53.520Z,1721666633.520 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Express0029.lzma.bak
2024-07-22T16:43:53.520Z,1721666633.520 [DataOverHttps](INFO): SBD MOMSN=21379321
2024-07-22T16:43:54.648Z,1721666634.648 [Default:CheckIn:Read_Iridium] Stopped
2024-07-22T16:43:54.649Z,1721666634.649 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-22T16:43:54.649Z,1721666634.649 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-22T16:44:11.211Z,1721666651.211 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-22T16:48:55.254Z,1721666935.254 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-22T16:48:55.254Z,1721666935.254 [Default:CheckIn:C.Wait] Stopped
2024-07-22T16:48:55.254Z,1721666935.254 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-22T16:48:55.254Z,1721666935.254 [Default:CheckIn:D] Running Loop=1
2024-07-22T16:48:55.635Z,1721666935.635 [Default:CheckIn:D] Stopped
2024-07-22T16:48:55.635Z,1721666935.635 [Default:CheckIn:E] Running Loop=1
2024-07-22T16:48:56.041Z,1721666936.041 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 50.957336 min
2024-07-22T16:48:56.041Z,1721666936.041 [Default:CheckIn:E] Stopped
2024-07-22T16:48:56.041Z,1721666936.041 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-22T16:48:56.041Z,1721666936.041 [Default:CheckIn] Stopped
2024-07-22T16:48:56.042Z,1721666936.042 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-22T16:48:56.042Z,1721666936.042 [Default:CheckIn](INFO): Running loop #9
2024-07-22T16:48:56.042Z,1721666936.042 [Default:CheckIn] Running Loop=9
2024-07-22T16:48:56.042Z,1721666936.042 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-22T16:48:56.042Z,1721666936.042 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-22T16:48:58.049Z,1721666938.049 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164857.00,A,3648.13672,N,12147.20956,W,0.039,56.76,220724,,,D*48
2024-07-22T16:48:58.051Z,1721666938.051 [NAL9602](INFO): GPS fix at 20240722T164857: (36.802279, -121.786826)
2024-07-22T16:48:58.080Z,1721666938.080 [Default:CheckIn:Read_GPS] Stopped
2024-07-22T16:48:58.080Z,1721666938.080 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-22T16:49:05.698Z,1721666945.698 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240722T155414/Courier0031.lzma
2024-07-22T16:49:06.700Z,1721666946.700 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Courier0031.lzma.bak
2024-07-22T16:49:06.700Z,1721666946.700 [DataOverHttps](INFO): SBD MOMSN=21379331
2024-07-22T16:49:15.016Z,1721666955.016 [NAL9602](INFO): SBD MO Status=0, MOMSN=53095, MT Status=0, MTMSN=0
2024-07-22T16:49:15.017Z,1721666955.017 [NAL9602](INFO): No messages in MT queue
2024-07-22T16:49:22.458Z,1721666962.458 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240722T155414/Express0032.lzma
2024-07-22T16:49:23.462Z,1721666963.462 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Express0032.lzma.bak
2024-07-22T16:49:23.462Z,1721666963.462 [DataOverHttps](INFO): SBD MOMSN=21379334
2024-07-22T16:49:24.728Z,1721666964.728 [Default:CheckIn:Read_Iridium] Stopped
2024-07-22T16:49:24.728Z,1721666964.728 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-22T16:49:24.728Z,1721666964.728 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-22T16:49:45.723Z,1721666985.723 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-22T16:54:25.335Z,1721667265.335 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-22T16:54:25.335Z,1721667265.335 [Default:CheckIn:C.Wait] Stopped
2024-07-22T16:54:25.335Z,1721667265.335 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-22T16:54:25.335Z,1721667265.335 [Default:CheckIn:D] Running Loop=1
2024-07-22T16:54:25.705Z,1721667265.705 [Default:CheckIn:D] Stopped
2024-07-22T16:54:25.705Z,1721667265.705 [Default:CheckIn:E] Running Loop=1
2024-07-22T16:54:26.118Z,1721667266.118 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.458508 min
2024-07-22T16:54:26.119Z,1721667266.119 [Default:CheckIn:E] Stopped
2024-07-22T16:54:26.119Z,1721667266.119 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-22T16:54:26.119Z,1721667266.119 [Default:CheckIn] Stopped
2024-07-22T16:54:26.119Z,1721667266.119 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-22T16:54:26.119Z,1721667266.119 [Default:CheckIn](INFO): Running loop #10
2024-07-22T16:54:26.119Z,1721667266.119 [Default:CheckIn] Running Loop=10
2024-07-22T16:54:26.119Z,1721667266.119 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-22T16:54:26.119Z,1721667266.119 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-22T16:54:28.113Z,1721667268.113 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165427.00,A,3648.13666,N,12147.20982,W,0.000,56.76,220724,,,D*44
2024-07-22T16:54:28.116Z,1721667268.116 [NAL9602](INFO): GPS fix at 20240722T165427: (36.802278, -121.786830)
2024-07-22T16:54:28.146Z,1721667268.146 [Default:CheckIn:Read_GPS] Stopped
2024-07-22T16:54:28.146Z,1721667268.146 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-22T16:54:35.598Z,1721667275.598 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240722T155414/Courier0034.lzma
2024-07-22T16:54:36.600Z,1721667276.600 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Courier0034.lzma.bak
2024-07-22T16:54:36.600Z,1721667276.600 [DataOverHttps](INFO): SBD MOMSN=21379338
2024-07-22T16:54:41.445Z,1721667281.445 [NAL9602](INFO): SBD MO Status=0, MOMSN=53096, MT Status=0, MTMSN=0
2024-07-22T16:54:41.445Z,1721667281.445 [NAL9602](INFO): No messages in MT queue
2024-07-22T16:54:55.190Z,1721667295.190 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240722T155414/Express0035.lzma
2024-07-22T16:54:56.192Z,1721667296.192 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Express0035.lzma.bak
2024-07-22T16:54:56.192Z,1721667296.192 [DataOverHttps](INFO): SBD MOMSN=21379342
2024-07-22T16:54:57.280Z,1721667297.280 [Default:CheckIn:Read_Iridium] Stopped
2024-07-22T16:54:57.280Z,1721667297.280 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-22T16:54:57.281Z,1721667297.281 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-22T16:55:12.207Z,1721667312.207 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-22T16:59:50.157Z,1721667590.157 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-22T16:59:57.862Z,1721667597.862 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-22T16:59:57.862Z,1721667597.862 [Default:CheckIn:C.Wait] Stopped
2024-07-22T16:59:57.863Z,1721667597.863 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-22T16:59:57.863Z,1721667597.863 [Default:CheckIn:D] Running Loop=1
2024-07-22T16:59:58.249Z,1721667598.249 [Default:CheckIn:D] Stopped
2024-07-22T16:59:58.249Z,1721667598.249 [Default:CheckIn:E] Running Loop=1
2024-07-22T16:59:58.653Z,1721667598.653 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.000903 min
2024-07-22T16:59:58.653Z,1721667598.653 [Default:CheckIn:E] Stopped
2024-07-22T16:59:58.654Z,1721667598.654 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-22T16:59:58.654Z,1721667598.654 [Default:CheckIn] Stopped
2024-07-22T16:59:58.654Z,1721667598.654 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-22T16:59:58.654Z,1721667598.654 [Default:CheckIn](INFO): Running loop #11
2024-07-22T16:59:58.654Z,1721667598.654 [Default:CheckIn] Running Loop=11
2024-07-22T16:59:58.654Z,1721667598.654 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-22T16:59:58.654Z,1721667598.654 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-22T17:00:00.673Z,1721667600.673 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165959.00,A,3648.13691,N,12147.21012,W,0.019,0.00,220724,,,D*73
2024-07-22T17:00:00.682Z,1721667600.682 [NAL9602](INFO): GPS fix at 20240722T165959: (36.802282, -121.786835)
2024-07-22T17:00:00.693Z,1721667600.693 [Default:CheckIn:Read_GPS] Stopped
2024-07-22T17:00:00.693Z,1721667600.693 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-22T17:00:02.849Z,1721667602.849 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-22T17:00:11.034Z,1721667611.034 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240722T155414/Courier0037.lzma
2024-07-22T17:00:12.036Z,1721667612.036 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Courier0037.lzma.bak
2024-07-22T17:00:12.036Z,1721667612.036 [DataOverHttps](INFO): SBD MOMSN=21379351
2024-07-22T17:00:13.361Z,1721667613.361 [NAL9602](INFO): SBD MO Status=0, MOMSN=53097, MT Status=0, MTMSN=0
2024-07-22T17:00:13.361Z,1721667613.361 [NAL9602](INFO): No messages in MT queue
2024-07-22T17:00:15.776Z,1721667615.776 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-22T17:00:28.365Z,1721667628.365 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-22T17:00:31.246Z,1721667631.246 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240722T155414/Express0038.lzma
2024-07-22T17:00:32.248Z,1721667632.248 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Express0038.lzma.bak
2024-07-22T17:00:32.248Z,1721667632.248 [DataOverHttps](INFO): SBD MOMSN=21379355
2024-07-22T17:00:33.632Z,1721667633.632 [Default:CheckIn:Read_Iridium] Stopped
2024-07-22T17:00:33.633Z,1721667633.633 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-22T17:00:33.633Z,1721667633.633 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-22T17:00:41.711Z,1721667641.711 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-22T17:00:44.125Z,1721667644.125 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-22T17:00:55.029Z,1721667655.029 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-22T17:01:07.150Z,1721667667.150 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-22T17:03:47.142Z,1721667827.142 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 6.
2024-07-22T17:03:47.144Z,1721667827.144 [BPC1](INFO): Received data from all battery sticks.
2024-07-22T17:05:34.236Z,1721667934.236 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-22T17:05:34.236Z,1721667934.236 [Default:CheckIn:C.Wait] Stopped
2024-07-22T17:05:34.237Z,1721667934.237 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-22T17:05:34.237Z,1721667934.237 [Default:CheckIn:D] Running Loop=1
2024-07-22T17:05:34.652Z,1721667934.652 [Default:CheckIn:D] Stopped
2024-07-22T17:05:34.652Z,1721667934.652 [Default:CheckIn:E] Running Loop=1
2024-07-22T17:05:35.044Z,1721667935.044 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 67.607625 min
2024-07-22T17:05:35.044Z,1721667935.044 [Default:CheckIn:E] Stopped
2024-07-22T17:05:35.044Z,1721667935.044 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-22T17:05:35.044Z,1721667935.044 [Default:CheckIn] Stopped
2024-07-22T17:05:35.044Z,1721667935.044 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-22T17:05:35.045Z,1721667935.045 [Default:CheckIn](INFO): Running loop #12
2024-07-22T17:05:35.045Z,1721667935.045 [Default:CheckIn] Running Loop=12
2024-07-22T17:05:35.045Z,1721667935.045 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-22T17:05:35.045Z,1721667935.045 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-22T17:05:37.061Z,1721667937.061 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170536.00,A,3648.13705,N,12147.20958,W,0.019,0.00,220724,,,D*78
2024-07-22T17:05:37.063Z,1721667937.063 [NAL9602](INFO): GPS fix at 20240722T170536: (36.802284, -121.786826)
2024-07-22T17:05:37.073Z,1721667937.073 [Default:CheckIn:Read_GPS] Stopped
2024-07-22T17:05:37.074Z,1721667937.074 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-22T17:05:44.082Z,1721667944.082 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20240722T155414/Courier0040.lzma
2024-07-22T17:05:45.084Z,1721667945.084 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Courier0040.lzma.bak
2024-07-22T17:05:45.084Z,1721667945.084 [DataOverHttps](INFO): SBD MOMSN=21379358
2024-07-22T17:05:59.285Z,1721667959.285 [NAL9602](INFO): SBD MO Status=0, MOMSN=53098, MT Status=0, MTMSN=0
2024-07-22T17:05:59.285Z,1721667959.285 [NAL9602](INFO): No messages in MT queue
2024-07-22T17:06:00.878Z,1721667960.878 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240722T155414/Express0041.lzma
2024-07-22T17:06:01.880Z,1721667961.880 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Express0041.lzma.bak
2024-07-22T17:06:01.880Z,1721667961.880 [DataOverHttps](INFO): SBD MOMSN=21379361
2024-07-22T17:06:02.936Z,1721667962.936 [Default:CheckIn:Read_Iridium] Stopped
2024-07-22T17:06:02.936Z,1721667962.936 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-22T17:06:02.936Z,1721667962.936 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-22T17:06:29.987Z,1721667989.987 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-22T17:11:03.524Z,1721668263.524 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-22T17:11:03.524Z,1721668263.524 [Default:CheckIn:C.Wait] Stopped
2024-07-22T17:11:03.525Z,1721668263.525 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-22T17:11:03.525Z,1721668263.525 [Default:CheckIn:D] Running Loop=1
2024-07-22T17:11:03.933Z,1721668263.933 [Default:CheckIn:D] Stopped
2024-07-22T17:11:03.933Z,1721668263.933 [Default:CheckIn:E] Running Loop=1
2024-07-22T17:11:04.341Z,1721668264.341 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.095638 min
2024-07-22T17:11:04.341Z,1721668264.341 [Default:CheckIn:E] Stopped
2024-07-22T17:11:04.341Z,1721668264.341 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-22T17:11:04.341Z,1721668264.341 [Default:CheckIn] Stopped
2024-07-22T17:11:04.341Z,1721668264.341 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-22T17:11:04.341Z,1721668264.341 [Default:CheckIn](INFO): Running loop #13
2024-07-22T17:11:04.341Z,1721668264.341 [Default:CheckIn] Running Loop=13
2024-07-22T17:11:04.341Z,1721668264.341 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-22T17:11:04.342Z,1721668264.342 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-22T17:11:06.349Z,1721668266.349 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171105.00,A,3648.13678,N,12147.20964,W,0.039,0.00,220724,,,D*7B
2024-07-22T17:11:06.351Z,1721668266.351 [NAL9602](INFO): GPS fix at 20240722T171105: (36.802280, -121.786827)
2024-07-22T17:11:06.361Z,1721668266.361 [Default:CheckIn:Read_GPS] Stopped
2024-07-22T17:11:06.361Z,1721668266.361 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-22T17:11:07.162Z,1721668267.162 [Default:CheckIn:Read_Iridium] Stopped
2024-07-22T17:11:07.162Z,1721668267.162 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-22T17:11:07.162Z,1721668267.162 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-22T17:11:18.468Z,1721668278.468 [NAL9602](INFO): SBD MO Status=0, MOMSN=53099, MT Status=0, MTMSN=0
2024-07-22T17:11:18.469Z,1721668278.469 [NAL9602](INFO): No messages in MT queue
2024-07-22T17:11:49.167Z,1721668309.167 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-22T17:16:07.748Z,1721668567.748 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-22T17:16:07.748Z,1721668567.748 [Default:CheckIn:C.Wait] Stopped
2024-07-22T17:16:07.748Z,1721668567.748 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-22T17:16:07.749Z,1721668567.749 [Default:CheckIn:D] Running Loop=1
2024-07-22T17:16:08.145Z,1721668568.145 [Default:CheckIn:D] Stopped
2024-07-22T17:16:08.145Z,1721668568.145 [Default:CheckIn:E] Running Loop=1
2024-07-22T17:16:08.545Z,1721668568.545 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 78.165845 min
2024-07-22T17:16:08.545Z,1721668568.545 [Default:CheckIn:E] Stopped
2024-07-22T17:16:08.545Z,1721668568.545 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-22T17:16:08.545Z,1721668568.545 [Default:CheckIn] Stopped
2024-07-22T17:16:08.545Z,1721668568.545 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-22T17:16:08.545Z,1721668568.545 [Default:CheckIn](INFO): Running loop #14
2024-07-22T17:16:08.545Z,1721668568.545 [Default:CheckIn] Running Loop=14
2024-07-22T17:16:08.545Z,1721668568.545 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-22T17:16:08.545Z,1721668568.545 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-22T17:16:10.559Z,1721668570.559 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171609.00,A,3648.13778,N,12147.21020,W,0.039,0.00,220724,,,D*79
2024-07-22T17:16:10.561Z,1721668570.561 [NAL9602](INFO): GPS fix at 20240722T171609: (36.802296, -121.786837)
2024-07-22T17:16:10.571Z,1721668570.571 [Default:CheckIn:Read_GPS] Stopped
2024-07-22T17:16:10.571Z,1721668570.571 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-22T17:16:19.105Z,1721668579.105 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240722T155414/Courier0043.lzma
2024-07-22T17:16:20.108Z,1721668580.108 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Courier0043.lzma.bak
2024-07-22T17:16:20.108Z,1721668580.108 [DataOverHttps](INFO): SBD MOMSN=21379375
2024-07-22T17:16:29.561Z,1721668589.561 [NAL9602](INFO): SBD MO Status=0, MOMSN=53100, MT Status=0, MTMSN=0
2024-07-22T17:16:29.561Z,1721668589.561 [NAL9602](INFO): No messages in MT queue
2024-07-22T17:16:38.334Z,1721668598.334 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240722T155414/Courier0046.lzma
2024-07-22T17:16:39.336Z,1721668599.336 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Courier0046.lzma.bak
2024-07-22T17:16:39.336Z,1721668599.336 [DataOverHttps](INFO): SBD MOMSN=21379380
2024-07-22T17:16:57.891Z,1721668617.891 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240722T155414/Express0044.lzma
2024-07-22T17:16:58.892Z,1721668618.892 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Express0044.lzma.bak
2024-07-22T17:16:58.892Z,1721668618.892 [DataOverHttps](INFO): SBD MOMSN=21379388
2024-07-22T17:17:00.311Z,1721668620.311 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-22T17:17:14.662Z,1721668634.662 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240722T155414/Express0047.lzma
2024-07-22T17:17:15.664Z,1721668635.664 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Express0047.lzma.bak
2024-07-22T17:17:15.664Z,1721668635.664 [DataOverHttps](INFO): SBD MOMSN=21379391
2024-07-22T17:17:16.881Z,1721668636.881 [Default:CheckIn:Read_Iridium] Stopped
2024-07-22T17:17:16.881Z,1721668636.881 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-22T17:17:16.881Z,1721668636.881 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-22T17:22:17.293Z,1721668937.293 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-22T17:22:17.294Z,1721668937.294 [Default:CheckIn:C.Wait] Stopped
2024-07-22T17:22:17.294Z,1721668937.294 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-22T17:22:17.294Z,1721668937.294 [Default:CheckIn:D] Running Loop=1
2024-07-22T17:22:17.700Z,1721668937.700 [Default:CheckIn:D] Stopped
2024-07-22T17:22:17.700Z,1721668937.700 [Default:CheckIn:E] Running Loop=1
2024-07-22T17:22:18.085Z,1721668938.085 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 84.325098 min
2024-07-22T17:22:18.085Z,1721668938.085 [Default:CheckIn:E] Stopped
2024-07-22T17:22:18.085Z,1721668938.085 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-22T17:22:18.085Z,1721668938.085 [Default:CheckIn] Stopped
2024-07-22T17:22:18.085Z,1721668938.085 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-22T17:22:18.085Z,1721668938.085 [Default:CheckIn](INFO): Running loop #15
2024-07-22T17:22:18.085Z,1721668938.085 [Default:CheckIn] Running Loop=15
2024-07-22T17:22:18.086Z,1721668938.086 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-22T17:22:18.086Z,1721668938.086 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-22T17:22:20.097Z,1721668940.097 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172219.00,A,3648.13730,N,12147.20685,W,0.097,114.78,220724,,,D*74
2024-07-22T17:22:20.116Z,1721668940.116 [NAL9602](INFO): GPS fix at 20240722T172219: (36.802288, -121.786781)
2024-07-22T17:22:20.135Z,1721668940.135 [Default:CheckIn:Read_GPS] Stopped
2024-07-22T17:22:20.135Z,1721668940.135 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-22T17:22:29.614Z,1721668949.614 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240722T155414/Courier0049.lzma
2024-07-22T17:22:30.616Z,1721668950.616 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Courier0049.lzma.bak
2024-07-22T17:22:30.616Z,1721668950.616 [DataOverHttps](INFO): SBD MOMSN=21379402
2024-07-22T17:22:33.920Z,1721668953.920 [NAL9602](INFO): SBD MO Status=0, MOMSN=53101, MT Status=0, MTMSN=0
2024-07-22T17:22:33.921Z,1721668953.921 [NAL9602](INFO): No messages in MT queue
2024-07-22T17:22:46.386Z,1721668966.386 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240722T155414/Express0050.lzma
2024-07-22T17:22:47.388Z,1721668967.388 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Express0050.lzma.bak
2024-07-22T17:22:47.388Z,1721668967.388 [DataOverHttps](INFO): SBD MOMSN=21379413
2024-07-22T17:22:48.481Z,1721668968.481 [Default:CheckIn:Read_Iridium] Stopped
2024-07-22T17:22:48.481Z,1721668968.481 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-22T17:22:48.481Z,1721668968.481 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-22T17:23:04.671Z,1721668984.671 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-22T17:27:49.265Z,1721669269.265 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-22T17:27:49.265Z,1721669269.265 [Default:CheckIn:C.Wait] Stopped
2024-07-22T17:27:49.265Z,1721669269.265 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-22T17:27:49.265Z,1721669269.265 [Default:CheckIn:D] Running Loop=1
2024-07-22T17:27:49.676Z,1721669269.676 [Default:CheckIn:D] Stopped
2024-07-22T17:27:49.676Z,1721669269.676 [Default:CheckIn:E] Running Loop=1
2024-07-22T17:27:50.074Z,1721669270.074 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 89.858024 min
2024-07-22T17:27:50.075Z,1721669270.075 [Default:CheckIn:E] Stopped
2024-07-22T17:27:50.075Z,1721669270.075 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-22T17:27:50.075Z,1721669270.075 [Default:CheckIn] Stopped
2024-07-22T17:27:50.075Z,1721669270.075 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-22T17:27:50.075Z,1721669270.075 [Default:CheckIn](INFO): Running loop #16
2024-07-22T17:27:50.075Z,1721669270.075 [Default:CheckIn] Running Loop=16
2024-07-22T17:27:50.075Z,1721669270.075 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-22T17:27:50.075Z,1721669270.075 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-22T17:27:52.085Z,1721669272.085 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172751.00,A,3648.13718,N,12147.20667,W,0.058,114.78,220724,,,D*78
2024-07-22T17:27:52.087Z,1721669272.087 [NAL9602](INFO): GPS fix at 20240722T172751: (36.802286, -121.786778)
2024-07-22T17:27:52.098Z,1721669272.098 [Default:CheckIn:Read_GPS] Stopped
2024-07-22T17:27:52.098Z,1721669272.098 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-22T17:27:59.662Z,1721669279.662 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240722T155414/Courier0052.lzma
2024-07-22T17:28:00.664Z,1721669280.664 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Courier0052.lzma.bak
2024-07-22T17:28:00.664Z,1721669280.664 [DataOverHttps](INFO): SBD MOMSN=21379424
2024-07-22T17:28:16.386Z,1721669296.386 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240722T155414/Express0053.lzma
2024-07-22T17:28:17.388Z,1721669297.388 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Express0053.lzma.bak
2024-07-22T17:28:17.388Z,1721669297.388 [DataOverHttps](INFO): SBD MOMSN=21379427
2024-07-22T17:28:18.762Z,1721669298.762 [Default:CheckIn:Read_Iridium] Stopped
2024-07-22T17:28:18.762Z,1721669298.762 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-22T17:28:18.762Z,1721669298.762 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-22T17:28:26.089Z,1721669306.089 [NAL9602](INFO): SBD MO Status=2, MOMSN=53102, MT Status=2, MTMSN=0
2024-07-22T17:28:26.089Z,1721669306.089 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-22T17:28:35.361Z,1721669315.361 [NAL9602](INFO): SBD MO Status=0, MOMSN=53102, MT Status=0, MTMSN=0
2024-07-22T17:28:35.361Z,1721669315.361 [NAL9602](INFO): No messages in MT queue
2024-07-22T17:29:06.119Z,1721669346.119 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-22T17:33:19.561Z,1721669599.561 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-22T17:33:19.561Z,1721669599.561 [Default:CheckIn:C.Wait] Stopped
2024-07-22T17:33:19.561Z,1721669599.561 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-22T17:33:19.561Z,1721669599.561 [Default:CheckIn:D] Running Loop=1
2024-07-22T17:33:19.962Z,1721669599.962 [Default:CheckIn:D] Stopped
2024-07-22T17:33:19.962Z,1721669599.962 [Default:CheckIn:E] Running Loop=1
2024-07-22T17:33:20.378Z,1721669600.378 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 95.362785 min
2024-07-22T17:33:20.378Z,1721669600.378 [Default:CheckIn:E] Stopped
2024-07-22T17:33:20.379Z,1721669600.379 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-22T17:33:20.379Z,1721669600.379 [Default:CheckIn] Stopped
2024-07-22T17:33:20.379Z,1721669600.379 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-22T17:33:20.379Z,1721669600.379 [Default:CheckIn](INFO): Running loop #17
2024-07-22T17:33:20.379Z,1721669600.379 [Default:CheckIn] Running Loop=17
2024-07-22T17:33:20.379Z,1721669600.379 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-22T17:33:20.379Z,1721669600.379 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-22T17:33:22.376Z,1721669602.376 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173321.00,A,3648.13681,N,12147.20650,W,0.039,114.78,220724,,,D*78
2024-07-22T17:33:22.378Z,1721669602.378 [NAL9602](INFO): GPS fix at 20240722T173321: (36.802280, -121.786775)
2024-07-22T17:33:22.388Z,1721669602.388 [Default:CheckIn:Read_GPS] Stopped
2024-07-22T17:33:22.388Z,1721669602.388 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-22T17:33:28.918Z,1721669608.918 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240722T155414/Courier0055.lzma
2024-07-22T17:33:29.920Z,1721669609.920 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Courier0055.lzma.bak
2024-07-22T17:33:29.920Z,1721669609.920 [DataOverHttps](INFO): SBD MOMSN=21379431
2024-07-22T17:33:44.193Z,1721669624.193 [NAL9602](INFO): SBD MO Status=0, MOMSN=53103, MT Status=0, MTMSN=0
2024-07-22T17:33:44.193Z,1721669624.193 [NAL9602](INFO): No messages in MT queue
2024-07-22T17:33:45.678Z,1721669625.678 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240722T155414/Express0056.lzma
2024-07-22T17:33:46.680Z,1721669626.680 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Express0056.lzma.bak
2024-07-22T17:33:46.680Z,1721669626.680 [DataOverHttps](INFO): SBD MOMSN=21379434
2024-07-22T17:33:47.845Z,1721669627.845 [Default:CheckIn:Read_Iridium] Stopped
2024-07-22T17:33:47.845Z,1721669627.845 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-22T17:33:47.845Z,1721669627.845 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-22T17:34:14.899Z,1721669654.899 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-22T17:38:48.656Z,1721669928.656 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-22T17:38:48.657Z,1721669928.657 [Default:CheckIn:C.Wait] Stopped
2024-07-22T17:38:48.657Z,1721669928.657 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-22T17:38:48.657Z,1721669928.657 [Default:CheckIn:D] Running Loop=1
2024-07-22T17:38:49.062Z,1721669929.062 [Default:CheckIn:D] Stopped
2024-07-22T17:38:49.063Z,1721669929.063 [Default:CheckIn:E] Running Loop=1
2024-07-22T17:38:49.477Z,1721669929.477 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 100.847795 min
2024-07-22T17:38:49.477Z,1721669929.477 [Default:CheckIn:E] Stopped
2024-07-22T17:38:49.477Z,1721669929.477 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-22T17:38:49.477Z,1721669929.477 [Default:CheckIn] Stopped
2024-07-22T17:38:49.478Z,1721669929.478 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-22T17:38:49.478Z,1721669929.478 [Default:CheckIn](INFO): Running loop #18
2024-07-22T17:38:49.490Z,1721669929.490 [Default:CheckIn] Running Loop=18
2024-07-22T17:38:49.490Z,1721669929.490 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-22T17:38:49.490Z,1721669929.490 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-22T17:38:51.469Z,1721669931.469 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173850.00,A,3648.13621,N,12147.20675,W,0.058,114.78,220724,,,D*7F
2024-07-22T17:38:51.471Z,1721669931.471 [NAL9602](INFO): GPS fix at 20240722T173850: (36.802270, -121.786779)
2024-07-22T17:38:51.490Z,1721669931.490 [Default:CheckIn:Read_GPS] Stopped
2024-07-22T17:38:51.490Z,1721669931.490 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-22T17:39:00.034Z,1721669940.034 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240722T155414/Courier0058.lzma
2024-07-22T17:39:01.036Z,1721669941.036 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Courier0058.lzma.bak
2024-07-22T17:39:01.036Z,1721669941.036 [DataOverHttps](INFO): SBD MOMSN=21379446
2024-07-22T17:39:16.946Z,1721669956.946 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240722T155414/Express0059.lzma
2024-07-22T17:39:17.948Z,1721669957.948 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Express0059.lzma.bak
2024-07-22T17:39:17.948Z,1721669957.948 [DataOverHttps](INFO): SBD MOMSN=21379449
2024-07-22T17:39:19.034Z,1721669959.034 [Default:CheckIn:Read_Iridium] Stopped
2024-07-22T17:39:19.034Z,1721669959.034 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-22T17:39:19.034Z,1721669959.034 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-22T17:40:33.781Z,1721670033.781 [NAL9602](INFO): SBD MO Status=0, MOMSN=53104, MT Status=0, MTMSN=0
2024-07-22T17:40:33.781Z,1721670033.781 [NAL9602](INFO): No messages in MT queue
2024-07-22T17:41:04.532Z,1721670064.532 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-22T17:44:19.692Z,1721670259.692 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-22T17:44:19.692Z,1721670259.692 [Default:CheckIn:C.Wait] Stopped
2024-07-22T17:44:19.692Z,1721670259.692 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-22T17:44:19.692Z,1721670259.692 [Default:CheckIn:D] Running Loop=1
2024-07-22T17:44:20.098Z,1721670260.098 [Default:CheckIn:D] Stopped
2024-07-22T17:44:20.098Z,1721670260.098 [Default:CheckIn:E] Running Loop=1
2024-07-22T17:44:20.508Z,1721670260.508 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 106.365063 min
2024-07-22T17:44:20.508Z,1721670260.508 [Default:CheckIn:E] Stopped
2024-07-22T17:44:20.509Z,1721670260.509 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-22T17:44:20.509Z,1721670260.509 [Default:CheckIn] Stopped
2024-07-22T17:44:20.509Z,1721670260.509 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-22T17:44:20.509Z,1721670260.509 [Default:CheckIn](INFO): Running loop #19
2024-07-22T17:44:20.509Z,1721670260.509 [Default:CheckIn] Running Loop=19
2024-07-22T17:44:20.509Z,1721670260.509 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-22T17:44:20.509Z,1721670260.509 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-22T17:44:22.505Z,1721670262.505 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174421.00,A,3648.13612,N,12147.20828,W,0.078,114.78,220724,,,D*76
2024-07-22T17:44:22.516Z,1721670262.516 [NAL9602](INFO): GPS fix at 20240722T174421: (36.802269, -121.786805)
2024-07-22T17:44:22.550Z,1721670262.550 [Default:CheckIn:Read_GPS] Stopped
2024-07-22T17:44:22.551Z,1721670262.551 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-22T17:44:30.426Z,1721670270.426 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240722T155414/Courier0061.lzma
2024-07-22T17:44:31.428Z,1721670271.428 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Courier0061.lzma.bak
2024-07-22T17:44:31.429Z,1721670271.429 [DataOverHttps](INFO): SBD MOMSN=21379454
2024-07-22T17:44:37.049Z,1721670277.049 [NAL9602](INFO): SBD MO Status=0, MOMSN=53105, MT Status=0, MTMSN=0
2024-07-22T17:44:37.049Z,1721670277.049 [NAL9602](INFO): No messages in MT queue
2024-07-22T17:44:47.202Z,1721670287.202 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240722T155414/Express0062.lzma
2024-07-22T17:44:48.204Z,1721670288.204 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Express0062.lzma.bak
2024-07-22T17:44:48.204Z,1721670288.204 [DataOverHttps](INFO): SBD MOMSN=21379457
2024-07-22T17:44:49.584Z,1721670289.584 [Default:CheckIn:Read_Iridium] Stopped
2024-07-22T17:44:49.584Z,1721670289.584 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-22T17:44:49.584Z,1721670289.584 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-22T17:45:07.804Z,1721670307.804 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-22T17:49:50.288Z,1721670590.288 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-22T17:49:50.288Z,1721670590.288 [Default:CheckIn:C.Wait] Stopped
2024-07-22T17:49:50.288Z,1721670590.288 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-22T17:49:50.288Z,1721670590.288 [Default:CheckIn:D] Running Loop=1
2024-07-22T17:49:50.695Z,1721670590.695 [Default:CheckIn:D] Stopped
2024-07-22T17:49:50.695Z,1721670590.695 [Default:CheckIn:E] Running Loop=1
2024-07-22T17:49:51.108Z,1721670591.108 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 111.875000 min
2024-07-22T17:49:51.108Z,1721670591.108 [Default:CheckIn:E] Stopped
2024-07-22T17:49:51.108Z,1721670591.108 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-22T17:49:51.108Z,1721670591.108 [Default:CheckIn] Stopped
2024-07-22T17:49:51.108Z,1721670591.108 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-22T17:49:51.109Z,1721670591.109 [Default:CheckIn](INFO): Running loop #20
2024-07-22T17:49:51.109Z,1721670591.109 [Default:CheckIn] Running Loop=20
2024-07-22T17:49:51.109Z,1721670591.109 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-22T17:49:51.109Z,1721670591.109 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-22T17:49:53.113Z,1721670593.113 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174952.00,A,3648.21959,N,12147.14643,W,3.479,20.54,220724,,,D*44
2024-07-22T17:49:53.115Z,1721670593.115 [NAL9602](INFO): GPS fix at 20240722T174952: (36.803660, -121.785774)
2024-07-22T17:49:53.125Z,1721670593.125 [Default:CheckIn:Read_GPS] Stopped
2024-07-22T17:49:53.125Z,1721670593.125 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-22T17:50:00.777Z,1721670600.777 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240722T155414/Courier0064.lzma
2024-07-22T17:50:01.780Z,1721670601.780 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Courier0064.lzma.bak
2024-07-22T17:50:01.780Z,1721670601.780 [DataOverHttps](INFO): SBD MOMSN=21379467
2024-07-22T17:50:04.015Z,1721670604.015 [NAL9602](INFO): SBD MO Status=0, MOMSN=53106, MT Status=0, MTMSN=0
2024-07-22T17:50:04.016Z,1721670604.016 [NAL9602](INFO): No messages in MT queue
2024-07-22T17:50:17.750Z,1721670617.750 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20240722T155414/Express0065.lzma
2024-07-22T17:50:18.752Z,1721670618.752 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Express0065.lzma.bak
2024-07-22T17:50:18.752Z,1721670618.752 [DataOverHttps](INFO): SBD MOMSN=21379471
2024-07-22T17:50:19.817Z,1721670619.817 [Default:CheckIn:Read_Iridium] Stopped
2024-07-22T17:50:19.817Z,1721670619.817 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-22T17:50:19.817Z,1721670619.817 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-22T17:50:34.790Z,1721670634.790 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-22T17:55:20.261Z,1721670920.261 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-22T17:55:20.261Z,1721670920.261 [Default:CheckIn:C.Wait] Stopped
2024-07-22T17:55:20.261Z,1721670920.261 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-22T17:55:20.262Z,1721670920.262 [Default:CheckIn:D] Running Loop=1
2024-07-22T17:55:20.670Z,1721670920.670 [Default:CheckIn:D] Stopped
2024-07-22T17:55:20.670Z,1721670920.670 [Default:CheckIn:E] Running Loop=1
2024-07-22T17:55:21.064Z,1721670921.064 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 117.374585 min
2024-07-22T17:55:21.065Z,1721670921.065 [Default:CheckIn:E] Stopped
2024-07-22T17:55:21.065Z,1721670921.065 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-22T17:55:21.065Z,1721670921.065 [Default:CheckIn] Stopped
2024-07-22T17:55:21.065Z,1721670921.065 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-22T17:55:21.065Z,1721670921.065 [Default:CheckIn](INFO): Running loop #21
2024-07-22T17:55:21.065Z,1721670921.065 [Default:CheckIn] Running Loop=21
2024-07-22T17:55:21.065Z,1721670921.065 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-22T17:55:21.065Z,1721670921.065 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-22T17:55:23.076Z,1721670923.076 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175522.00,A,3648.47738,N,12147.27354,W,12.499,234.70,220724,,,D*4B
2024-07-22T17:55:23.079Z,1721670923.079 [NAL9602](INFO): GPS fix at 20240722T175522: (36.807956, -121.787892)
2024-07-22T17:55:23.089Z,1721670923.089 [Default:CheckIn:Read_GPS] Stopped
2024-07-22T17:55:23.090Z,1721670923.090 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-22T17:55:29.509Z,1721670929.509 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240722T155414/Courier0067.lzma
2024-07-22T17:55:30.512Z,1721670930.512 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Courier0067.lzma.bak
2024-07-22T17:55:30.512Z,1721670930.512 [DataOverHttps](INFO): SBD MOMSN=21379474
2024-07-22T17:55:38.831Z,1721670938.831 [NAL9602](INFO): SBD MO Status=0, MOMSN=53107, MT Status=0, MTMSN=0
2024-07-22T17:55:38.831Z,1721670938.831 [NAL9602](INFO): No messages in MT queue
2024-07-22T17:55:46.250Z,1721670946.250 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20240722T155414/Express0068.lzma
2024-07-22T17:55:47.252Z,1721670947.252 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Express0068.lzma.bak
2024-07-22T17:55:47.252Z,1721670947.252 [DataOverHttps](INFO): SBD MOMSN=21379477
2024-07-22T17:55:48.541Z,1721670948.541 [Default:CheckIn:Read_Iridium] Stopped
2024-07-22T17:55:48.541Z,1721670948.541 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-22T17:55:48.541Z,1721670948.541 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-22T17:56:09.533Z,1721670969.533 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-22T18:00:49.293Z,1721671249.293 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-22T18:00:49.294Z,1721671249.294 [Default:CheckIn:C.Wait] Stopped
2024-07-22T18:00:49.294Z,1721671249.294 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-22T18:00:49.294Z,1721671249.294 [Default:CheckIn:D] Running Loop=1
2024-07-22T18:00:49.693Z,1721671249.693 [Default:CheckIn:D] Stopped
2024-07-22T18:00:49.693Z,1721671249.693 [Default:CheckIn:E] Running Loop=1
2024-07-22T18:00:50.109Z,1721671250.109 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 122.858309 min
2024-07-22T18:00:50.109Z,1721671250.109 [Default:CheckIn:E] Stopped
2024-07-22T18:00:50.109Z,1721671250.109 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-22T18:00:50.109Z,1721671250.109 [Default:CheckIn] Stopped
2024-07-22T18:00:50.109Z,1721671250.109 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-22T18:00:50.110Z,1721671250.110 [Default:CheckIn](INFO): Running loop #22
2024-07-22T18:00:50.110Z,1721671250.110 [Default:CheckIn] Running Loop=22
2024-07-22T18:00:50.122Z,1721671250.122 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-22T18:00:50.122Z,1721671250.122 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-22T18:00:52.101Z,1721671252.101 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180051.00,A,3648.19877,N,12148.41765,W,11.216,255.11,220724,,,D*44
2024-07-22T18:00:52.103Z,1721671252.103 [NAL9602](INFO): GPS fix at 20240722T180051: (36.803313, -121.806961)
2024-07-22T18:00:52.114Z,1721671252.114 [Default:CheckIn:Read_GPS] Stopped
2024-07-22T18:00:52.114Z,1721671252.114 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-22T18:01:00.166Z,1721671260.166 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240722T155414/Courier0070.lzma
2024-07-22T18:01:01.168Z,1721671261.168 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Courier0070.lzma.bak
2024-07-22T18:01:01.168Z,1721671261.168 [DataOverHttps](INFO): SBD MOMSN=21379491
2024-07-22T18:01:01.398Z,1721671261.398 [NAL9602](INFO): SBD MO Status=0, MOMSN=53108, MT Status=0, MTMSN=0
2024-07-22T18:01:01.398Z,1721671261.398 [NAL9602](INFO): No messages in MT queue
2024-07-22T18:01:17.031Z,1721671277.031 [DataOverHttps](INFO): Sending 146 bytes from file Logs/20240722T155414/Express0071.lzma
2024-07-22T18:01:18.032Z,1721671278.032 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Express0071.lzma.bak
2024-07-22T18:01:18.032Z,1721671278.032 [DataOverHttps](INFO): SBD MOMSN=21379494
2024-07-22T18:01:19.185Z,1721671279.185 [Default:CheckIn:Read_Iridium] Stopped
2024-07-22T18:01:19.185Z,1721671279.185 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-22T18:01:19.185Z,1721671279.185 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-22T18:01:32.099Z,1721671292.099 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-22T18:04:54.237Z,1721671494.237 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-22T18:05:07.165Z,1721671507.165 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-22T18:05:20.092Z,1721671520.092 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-22T18:05:33.020Z,1721671533.020 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-22T18:05:45.949Z,1721671545.949 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-22T18:05:58.473Z,1721671558.473 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-22T18:06:11.415Z,1721671571.415 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-22T18:06:19.896Z,1721671579.896 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-22T18:06:19.896Z,1721671579.896 [Default:CheckIn:C.Wait] Stopped
2024-07-22T18:06:19.896Z,1721671579.896 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-22T18:06:19.896Z,1721671579.896 [Default:CheckIn:D] Running Loop=1
2024-07-22T18:06:20.319Z,1721671580.319 [Default:CheckIn:D] Stopped
2024-07-22T18:06:20.319Z,1721671580.319 [Default:CheckIn:E] Running Loop=1
2024-07-22T18:06:20.712Z,1721671580.712 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 128.368734 min
2024-07-22T18:06:20.712Z,1721671580.712 [Default:CheckIn:E] Stopped
2024-07-22T18:06:20.712Z,1721671580.712 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-22T18:06:20.712Z,1721671580.712 [Default:CheckIn] Stopped
2024-07-22T18:06:20.712Z,1721671580.712 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-22T18:06:20.712Z,1721671580.712 [Default:CheckIn](INFO): Running loop #23
2024-07-22T18:06:20.713Z,1721671580.713 [Default:CheckIn] Running Loop=23
2024-07-22T18:06:20.713Z,1721671580.713 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-22T18:06:20.713Z,1721671580.713 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-22T18:06:22.717Z,1721671582.717 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180622.00,A,3648.01031,N,12149.60340,W,10.205,253.98,220724,,,D*40
2024-07-22T18:06:22.720Z,1721671582.720 [NAL9602](INFO): GPS fix at 20240722T180622: (36.800172, -121.826723)
2024-07-22T18:06:22.747Z,1721671582.747 [Default:CheckIn:Read_GPS] Stopped
2024-07-22T18:06:22.747Z,1721671582.747 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-22T18:06:23.537Z,1721671583.537 [Default:CheckIn:Read_Iridium] Stopped
2024-07-22T18:06:23.537Z,1721671583.537 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-22T18:06:23.537Z,1721671583.537 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-22T18:06:42.636Z,1721671602.636 [NAL9602](INFO): SBD MO Status=0, MOMSN=53109, MT Status=0, MTMSN=0
2024-07-22T18:06:42.636Z,1721671602.636 [NAL9602](INFO): No messages in MT queue
2024-07-22T18:07:13.215Z,1721671633.215 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-22T18:08:52.205Z,1721671732.205 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 6.
2024-07-22T18:08:52.218Z,1721671732.218 [BPC1](INFO): Received data from all battery sticks.
2024-07-22T18:11:24.168Z,1721671884.168 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-22T18:11:24.168Z,1721671884.168 [Default:CheckIn:C.Wait] Stopped
2024-07-22T18:11:24.168Z,1721671884.168 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-22T18:11:24.168Z,1721671884.168 [Default:CheckIn:D] Running Loop=1
2024-07-22T18:11:24.564Z,1721671884.564 [Default:CheckIn:D] Stopped
2024-07-22T18:11:24.564Z,1721671884.564 [Default:CheckIn:E] Running Loop=1
2024-07-22T18:11:24.969Z,1721671884.969 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 133.439494 min
2024-07-22T18:11:24.969Z,1721671884.969 [Default:CheckIn:E] Stopped
2024-07-22T18:11:24.969Z,1721671884.969 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-22T18:11:24.969Z,1721671884.969 [Default:CheckIn] Stopped
2024-07-22T18:11:24.970Z,1721671884.970 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-22T18:11:24.970Z,1721671884.970 [Default:CheckIn](INFO): Running loop #24
2024-07-22T18:11:24.970Z,1721671884.970 [Default:CheckIn] Running Loop=24
2024-07-22T18:11:24.970Z,1721671884.970 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-22T18:11:24.970Z,1721671884.970 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-22T18:11:26.979Z,1721671886.979 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181126.00,A,3647.84993,N,12150.61362,W,8.495,255.05,220724,,,D*7C
2024-07-22T18:11:26.981Z,1721671886.981 [NAL9602](INFO): GPS fix at 20240722T181126: (36.797499, -121.843560)
2024-07-22T18:11:26.997Z,1721671886.997 [Default:CheckIn:Read_GPS] Stopped
2024-07-22T18:11:26.997Z,1721671886.997 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-22T18:11:29.843Z,1721671889.843 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2024-07-22T18:11:39.909Z,1721671899.909 [NAL9602](INFO): SBD MO Status=0, MOMSN=53110, MT Status=0, MTMSN=0
2024-07-22T18:11:39.909Z,1721671899.909 [NAL9602](INFO): No messages in MT queue
2024-07-22T18:12:00.970Z,1721671920.970 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-22T18:12:10.606Z,1721671930.606 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-22T18:12:32.102Z,1721671952.102 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-22T18:13:03.227Z,1721671983.227 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-22T18:13:34.350Z,1721672014.350 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-22T18:13:37.363Z,1721672017.363 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.106812
2024-07-22T18:13:56.108Z,1721672036.108 [NAL9602](INFO): SBD MO Status=1, MOMSN=53111, MT Status=0, MTMSN=0
2024-07-22T18:13:56.170Z,1721672036.170 [NAL9602](INFO): Sent 73 bytes from file Logs/20240722T155414/Courier0073.lzma
2024-07-22T18:13:56.170Z,1721672036.170 [NAL9602](INFO): Packets left to send: 0
2024-07-22T18:14:08.486Z,1721672048.486 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-22T18:14:14.724Z,1721672054.724 [NAL9602](INFO): SBD MO Status=1, MOMSN=53112, MT Status=0, MTMSN=0
2024-07-22T18:14:14.778Z,1721672054.778 [NAL9602](INFO): Sent 84 bytes from file Logs/20240722T155414/Courier0076.lzma
2024-07-22T18:14:14.778Z,1721672054.778 [NAL9602](INFO): Packets left to send: 0
2024-07-22T18:14:34.177Z,1721672074.177 [NAL9602](INFO): SBD MO Status=1, MOMSN=53113, MT Status=0, MTMSN=0
2024-07-22T18:14:34.234Z,1721672074.234 [NAL9602](INFO): Sent 149 bytes from file Logs/20240722T155414/Express0074.lzma
2024-07-22T18:14:34.234Z,1721672074.234 [NAL9602](INFO): Packets left to send: 0
2024-07-22T18:14:39.610Z,1721672079.610 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-22T18:14:43.130Z,1721672083.130 [NAL9602](INFO): SBD MO Status=1, MOMSN=53114, MT Status=0, MTMSN=0
2024-07-22T18:14:43.182Z,1721672083.182 [NAL9602](INFO): Sent 148 bytes from file Logs/20240722T155414/Express0077.lzma
2024-07-22T18:14:43.182Z,1721672083.182 [NAL9602](INFO): Packets left to send: 0
2024-07-22T18:14:48.182Z,1721672088.182 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002916
2024-07-22T18:14:49.252Z,1721672089.252 [Default:CheckIn:Read_Iridium] Stopped
2024-07-22T18:14:49.252Z,1721672089.252 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-22T18:14:49.252Z,1721672089.252 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-22T18:14:51.247Z,1721672091.247 [NAL9602](INFO): SBD MO Status=0, MOMSN=53115, MT Status=0, MTMSN=0
2024-07-22T18:15:21.955Z,1721672121.955 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-22T18:19:50.005Z,1721672390.005 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-22T18:19:50.006Z,1721672390.006 [Default:CheckIn:C.Wait] Stopped
2024-07-22T18:19:50.006Z,1721672390.006 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-22T18:19:50.006Z,1721672390.006 [Default:CheckIn:D] Running Loop=1
2024-07-22T18:19:50.400Z,1721672390.400 [Default:CheckIn:D] Stopped
2024-07-22T18:19:50.400Z,1721672390.400 [Default:CheckIn:E] Running Loop=1
2024-07-22T18:19:50.806Z,1721672390.806 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 141.870101 min
2024-07-22T18:19:50.806Z,1721672390.806 [Default:CheckIn:E] Stopped
2024-07-22T18:19:50.806Z,1721672390.806 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-22T18:19:50.806Z,1721672390.806 [Default:CheckIn] Stopped
2024-07-22T18:19:50.806Z,1721672390.806 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-22T18:19:50.807Z,1721672390.807 [Default:CheckIn](INFO): Running loop #25
2024-07-22T18:19:50.807Z,1721672390.807 [Default:CheckIn] Running Loop=25
2024-07-22T18:19:50.807Z,1721672390.807 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-22T18:19:50.807Z,1721672390.807 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-22T18:19:52.814Z,1721672392.814 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181952.00,A,3647.78773,N,12150.95886,W,0.933,105.18,220724,,,D*7D
2024-07-22T18:19:52.816Z,1721672392.816 [NAL9602](INFO): GPS fix at 20240722T181952: (36.796462, -121.849314)
2024-07-22T18:19:52.827Z,1721672392.827 [Default:CheckIn:Read_GPS] Stopped
2024-07-22T18:19:52.827Z,1721672392.827 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-22T18:20:01.774Z,1721672401.774 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20240722T155414/Courier0079.lzma
2024-07-22T18:20:02.776Z,1721672402.776 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Courier0079.lzma.bak
2024-07-22T18:20:02.777Z,1721672402.777 [DataOverHttps](INFO): SBD MOMSN=21379529
2024-07-22T18:20:12.203Z,1721672412.203 [NAL9602](INFO): SBD MO Status=0, MOMSN=53116, MT Status=0, MTMSN=0
2024-07-22T18:20:12.204Z,1721672412.204 [NAL9602](INFO): No messages in MT queue
2024-07-22T18:20:18.806Z,1721672418.806 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20240722T155414/Express0080.lzma
2024-07-22T18:20:19.808Z,1721672419.808 [DataOverHttps](INFO): Moved sent file to Logs/20240722T155414/Express0080.lzma.bak
2024-07-22T18:20:19.808Z,1721672419.808 [DataOverHttps](INFO): SBD MOMSN=21379532
2024-07-22T18:20:21.121Z,1721672421.121 [Default:CheckIn:Read_Iridium] Stopped
2024-07-22T18:20:21.121Z,1721672421.121 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-22T18:20:21.121Z,1721672421.121 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-22T18:20:42.942Z,1721672442.942 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-22T18:21:59.611Z,1721672519.611 [DataOverHttps](IMPORTANT): SBD MTMSN=20240722T182158
2024-07-22T18:22:07.122Z,1721672527.122 [DataOverHttps](INFO): Received command: restart logs