2023-07-10T15:10:42.665Z,1689001842.665 [Supervisor](DEBUG): Initializing supervisor.
2023-07-10T15:10:42.669Z,1689001842.669 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-07-10T15:10:42.669Z,1689001842.669 [SyncHandler](INFO): Protected caller Thread ID is 5464
2023-07-10T15:10:42.670Z,1689001842.670 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-07-10T15:10:42.671Z,1689001842.671 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-07-10T15:10:42.671Z,1689001842.671 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5465
2023-07-10T15:10:42.675Z,1689001842.675 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-07-10T15:10:42.700Z,1689001842.700 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-07-10T15:10:42.708Z,1689001842.708 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-07-10T15:10:42.708Z,1689001842.708 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 5466
2023-07-10T15:10:42.712Z,1689001842.712 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-07-10T15:10:42.713Z,1689001842.713 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-07-10T15:10:42.713Z,1689001842.713 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5467
2023-07-10T15:10:42.715Z,1689001842.715 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-07-10T15:10:42.716Z,1689001842.716 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-07-10T15:10:42.717Z,1689001842.717 [logger ThreadHandler](INFO): Protected caller Thread ID is 5468
2023-07-10T15:10:42.720Z,1689001842.720 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-07-10T15:10:42.720Z,1689001842.720 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-07-10T15:10:42.722Z,1689001842.722 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-07-10T15:10:43.118Z,1689001843.118 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-07-10T15:10:43.118Z,1689001843.118 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-07-10T15:10:43.228Z,1689001843.228 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-07-10T15:10:43.932Z,1689001843.932 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-07-10T15:10:43.933Z,1689001843.933 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-07-10T15:10:44.394Z,1689001844.394 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-07-10T15:10:44.394Z,1689001844.394 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-07-10T15:10:44.534Z,1689001844.534 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-07-10T15:10:44.534Z,1689001844.534 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-07-10T15:10:45.108Z,1689001845.108 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-07-10T15:10:45.109Z,1689001845.109 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-07-10T15:10:45.411Z,1689001845.411 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-07-10T15:10:45.420Z,1689001845.420 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-07-10T15:10:46.171Z,1689001846.171 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-07-10T15:10:46.172Z,1689001846.172 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-07-10T15:10:46.378Z,1689001846.378 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-07-10T15:10:46.378Z,1689001846.378 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-07-10T15:10:46.488Z,1689001846.488 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-07-10T15:10:48.068Z,1689001848.068 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-07-10T15:10:48.069Z,1689001848.069 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-07-10T15:10:48.610Z,1689001848.610 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-07-10T15:10:48.610Z,1689001848.610 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-07-10T15:10:48.874Z,1689001848.874 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-07-10T15:10:48.874Z,1689001848.874 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-07-10T15:10:49.040Z,1689001849.040 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-07-10T15:10:49.041Z,1689001849.041 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-07-10T15:10:49.363Z,1689001849.363 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-07-10T15:10:49.364Z,1689001849.364 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-07-10T15:10:49.714Z,1689001849.714 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-07-10T15:10:49.716Z,1689001849.716 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2023-07-10T15:10:49.717Z,1689001849.717 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2023-07-10T15:10:49.847Z,1689001849.847 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2023-07-10T15:10:50.048Z,1689001850.048 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2023-07-10T15:10:50.413Z,1689001850.413 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2023-07-10T15:10:50.530Z,1689001850.530 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2023-07-10T15:10:50.674Z,1689001850.674 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2023-07-10T15:10:50.818Z,1689001850.818 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2023-07-10T15:10:50.971Z,1689001850.971 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2023-07-10T15:10:51.087Z,1689001851.087 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2023-07-10T15:10:51.304Z,1689001851.304 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2023-07-10T15:10:51.512Z,1689001851.512 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2023-07-10T15:10:51.667Z,1689001851.667 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2023-07-10T15:10:52.066Z,1689001852.066 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-07-10T15:10:52.066Z,1689001852.066 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-07-10T15:10:52.076Z,1689001852.076 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-07-10T15:10:52.149Z,1689001852.149 [VerticalControl](DEBUG): Construct VerticalControl.
2023-07-10T15:10:52.232Z,1689001852.232 [VerticalControl] Loaded
2023-07-10T15:10:52.232Z,1689001852.232 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-07-10T15:10:52.235Z,1689001852.235 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-07-10T15:10:52.308Z,1689001852.308 [HorizontalControl] Loaded
2023-07-10T15:10:52.309Z,1689001852.309 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-07-10T15:10:52.311Z,1689001852.311 [SpeedControl](DEBUG): Construct SpeedControl.
2023-07-10T15:10:52.314Z,1689001852.314 [SpeedControl] Loaded
2023-07-10T15:10:52.314Z,1689001852.314 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-07-10T15:10:52.317Z,1689001852.317 [LoopControl](DEBUG): Construct LoopControl.
2023-07-10T15:10:52.317Z,1689001852.317 [LoopControl] Loaded
2023-07-10T15:10:52.317Z,1689001852.317 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-07-10T15:10:52.318Z,1689001852.318 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-07-10T15:10:52.318Z,1689001852.318 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-07-10T15:10:52.336Z,1689001852.336 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-07-10T15:10:52.337Z,1689001852.337 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-07-10T15:10:52.551Z,1689001852.551 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-07-10T15:10:52.552Z,1689001852.552 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-07-10T15:10:52.742Z,1689001852.742 [BuoyancyServo] Loaded
2023-07-10T15:10:52.742Z,1689001852.742 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-07-10T15:10:52.770Z,1689001852.770 [ElevatorServo] Loaded
2023-07-10T15:10:52.770Z,1689001852.770 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-07-10T15:10:52.797Z,1689001852.797 [MassServo] Loaded
2023-07-10T15:10:52.798Z,1689001852.798 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-07-10T15:10:52.832Z,1689001852.832 [RudderServo] Loaded
2023-07-10T15:10:52.832Z,1689001852.832 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-07-10T15:10:52.857Z,1689001852.857 [ThrusterServo] Loaded
2023-07-10T15:10:52.857Z,1689001852.857 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2023-07-10T15:10:52.858Z,1689001852.858 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-07-10T15:10:52.858Z,1689001852.858 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-07-10T15:10:53.025Z,1689001853.025 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-07-10T15:10:53.025Z,1689001853.025 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-07-10T15:10:53.038Z,1689001853.038 [NavChart] Loaded
2023-07-10T15:10:53.039Z,1689001853.039 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-07-10T15:10:53.052Z,1689001853.052 [UniversalFixResidualReporter] Loaded
2023-07-10T15:10:53.052Z,1689001853.052 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-07-10T15:10:53.053Z,1689001853.053 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-07-10T15:10:53.053Z,1689001853.053 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-07-10T15:10:53.133Z,1689001853.133 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-07-10T15:10:53.133Z,1689001853.133 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-07-10T15:10:54.454Z,1689001854.454 [AHRS_M2] Loaded
2023-07-10T15:10:54.454Z,1689001854.454 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-07-10T15:10:56.054Z,1689001856.054 [BPC1] Loaded
2023-07-10T15:10:56.054Z,1689001856.054 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-07-10T15:10:56.128Z,1689001856.128 [DataOverHttps] Loaded
2023-07-10T15:10:56.129Z,1689001856.129 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-07-10T15:10:56.130Z,1689001856.130 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4090B4E0
2023-07-10T15:10:56.130Z,1689001856.130 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5555
2023-07-10T15:10:56.150Z,1689001856.150 [Depth_Keller] Loaded
2023-07-10T15:10:56.151Z,1689001856.151 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-07-10T15:10:56.156Z,1689001856.156 [DropWeight] Loaded
2023-07-10T15:10:56.156Z,1689001856.156 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-07-10T15:10:56.230Z,1689001856.230 [NAL9602] Loaded
2023-07-10T15:10:56.231Z,1689001856.231 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-07-10T15:10:56.260Z,1689001856.260 [Onboard] Loaded
2023-07-10T15:10:56.260Z,1689001856.260 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-07-10T15:10:56.261Z,1689001856.261 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4093B4E0
2023-07-10T15:10:56.261Z,1689001856.261 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 5556
2023-07-10T15:10:56.274Z,1689001856.274 [Power24vConverter] Loaded
2023-07-10T15:10:56.275Z,1689001856.275 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-07-10T15:10:56.288Z,1689001856.288 [Radio_Surface] Loaded
2023-07-10T15:10:56.288Z,1689001856.288 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-07-10T15:10:56.289Z,1689001856.289 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4096B4E0
2023-07-10T15:10:56.290Z,1689001856.290 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5557
2023-07-10T15:10:56.291Z,1689001856.291 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-07-10T15:10:56.291Z,1689001856.291 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-07-10T15:10:56.340Z,1689001856.340 [DepthRateCalculator] Loaded
2023-07-10T15:10:56.340Z,1689001856.340 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-07-10T15:10:56.345Z,1689001856.345 [PitchRateCalculator] Loaded
2023-07-10T15:10:56.345Z,1689001856.345 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-07-10T15:10:56.354Z,1689001856.354 [SpeedCalculator] Loaded
2023-07-10T15:10:56.354Z,1689001856.354 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-07-10T15:10:56.358Z,1689001856.358 [YawRateCalculator] Loaded
2023-07-10T15:10:56.358Z,1689001856.358 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-07-10T15:10:56.377Z,1689001856.377 [ElevatorOffsetCalculator] Loaded
2023-07-10T15:10:56.377Z,1689001856.377 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-07-10T15:10:56.377Z,1689001856.377 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-07-10T15:10:56.378Z,1689001856.378 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-07-10T15:10:56.502Z,1689001856.502 [CANONSampler] Loaded
2023-07-10T15:10:56.502Z,1689001856.502 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2023-07-10T15:10:56.561Z,1689001856.561 [CTD_Seabird] Loaded
2023-07-10T15:10:56.561Z,1689001856.561 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-07-10T15:10:56.562Z,1689001856.562 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A244E0
2023-07-10T15:10:56.562Z,1689001856.562 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 5558
2023-07-10T15:10:56.582Z,1689001856.582 [PAR_Licor] Loaded
2023-07-10T15:10:56.582Z,1689001856.582 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-07-10T15:10:56.613Z,1689001856.613 [WetLabsBB2FL] Loaded
2023-07-10T15:10:56.613Z,1689001856.613 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-07-10T15:10:56.614Z,1689001856.614 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A544E0
2023-07-10T15:10:56.614Z,1689001856.614 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5559
2023-07-10T15:10:56.615Z,1689001856.615 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-07-10T15:10:56.616Z,1689001856.616 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-07-10T15:10:56.642Z,1689001856.642 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-07-10T15:10:56.643Z,1689001856.643 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-07-10T15:10:56.936Z,1689001856.936 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-07-10T15:10:56.937Z,1689001856.937 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-07-10T15:10:57.069Z,1689001857.069 [SBIT](DEBUG): Construct Startup Built In Test.
2023-07-10T15:10:57.078Z,1689001857.078 [SBIT] Loaded
2023-07-10T15:10:57.078Z,1689001857.078 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-07-10T15:10:57.081Z,1689001857.081 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-07-10T15:10:57.094Z,1689001857.094 [IBIT] Loaded
2023-07-10T15:10:57.094Z,1689001857.094 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-07-10T15:10:57.100Z,1689001857.100 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-07-10T15:10:57.203Z,1689001857.203 [CBIT] Loaded
2023-07-10T15:10:57.204Z,1689001857.204 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-07-10T15:10:57.204Z,1689001857.204 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-07-10T15:10:57.205Z,1689001857.204 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-07-10T15:10:57.301Z,1689001857.301 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-07-10T15:10:57.307Z,1689001857.307 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-07-10T15:10:57.310Z,1689001857.310 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-07-10T15:10:57.320Z,1689001857.320 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-07-10T15:10:57.321Z,1689001857.321 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B944E0
2023-07-10T15:10:57.322Z,1689001857.322 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5560
2023-07-10T15:10:57.326Z,1689001857.326 [Supervisor](INFO): Main Thread ID is 5463
2023-07-10T15:10:57.327Z,1689001857.327 [Supervisor](DEBUG): Running supervisor.
2023-07-10T15:10:57.327Z,1689001857.327 [CommandExec ThreadHandler](INFO): Handler Thread ID is 5561
2023-07-10T15:10:57.328Z,1689001857.328 [CommandExec](INFO): Initializing the command executive.
2023-07-10T15:10:57.329Z,1689001857.329 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5562
2023-07-10T15:10:57.332Z,1689001857.332 [controlThread ThreadHandler](INFO): Handler Thread ID is 5563
2023-07-10T15:10:57.332Z,1689001857.332 [controlThread](DEBUG): Initializing ControlThread
2023-07-10T15:10:57.333Z,1689001857.333 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-07-10T15:10:57.334Z,1689001857.334 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-07-10T15:10:57.335Z,1689001857.335 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-07-10T15:10:57.336Z,1689001857.336 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-07-10T15:10:57.338Z,1689001857.338 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-07-10T15:10:57.338Z,1689001857.338 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-07-10T15:10:57.341Z,1689001857.341 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-07-10T15:10:57.342Z,1689001857.342 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-07-10T15:10:57.342Z,1689001857.342 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-07-10T15:10:57.342Z,1689001857.342 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-07-10T15:10:57.342Z,1689001857.342 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-07-10T15:10:57.348Z,1689001857.348 [SBIT](INFO): Initialize SBIT Component.
2023-07-10T15:10:57.348Z,1689001857.348 [SBIT](IMPORTANT): git: 2023-06-27_B
2023-07-10T15:10:57.348Z,1689001857.348 [SBIT](INFO): git hash: ff84f56c2b48606b9e34bb544c9bbe31deaa129e
2023-07-10T15:10:57.349Z,1689001857.349 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-07-10T15:10:57.350Z,1689001857.350 [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
2023-07-10T15:10:57.351Z,1689001857.351 [SBIT](INFO): Beginning SBIT in 58.000000 seconds.
2023-07-10T15:10:57.352Z,1689001857.352 [IBIT](INFO): Initialize IBIT Component.
2023-07-10T15:10:57.352Z,1689001857.352 [CBIT](DEBUG): Initialize CBIT Component.
2023-07-10T15:10:57.353Z,1689001857.353 [logger ThreadHandler](INFO): Handler Thread ID is 5564
2023-07-10T15:10:57.363Z,1689001857.363 [CBIT](DEBUG): Initialized mux pins.
2023-07-10T15:10:57.364Z,1689001857.364 [CBIT](DEBUG): Initializing the watchdog timer.
2023-07-10T15:10:57.372Z,1689001857.372 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5565
2023-07-10T15:10:57.373Z,1689001857.373 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-07-10T15:10:57.384Z,1689001857.384 [Onboard ThreadHandler](INFO): Handler Thread ID is 5566
2023-07-10T15:10:57.388Z,1689001857.388 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-07-10T15:10:57.388Z,1689001857.388 [CBIT](DEBUG): Initializing heartbeat.
2023-07-10T15:10:57.404Z,1689001857.404 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5567
2023-07-10T15:10:57.420Z,1689001857.420 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 5568
2023-07-10T15:10:57.420Z,1689001857.420 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-07-10T15:10:57.425Z,1689001857.425 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5570
2023-07-10T15:10:57.428Z,1689001857.428 [WetLabsBB2FL](INFO): Powering up
2023-07-10T15:10:57.429Z,1689001857.429 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5572
2023-07-10T15:10:57.433Z,1689001857.433 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-07-10T15:10:57.433Z,1689001857.433 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-07-10T15:10:57.433Z,1689001857.433 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-07-10T15:10:57.433Z,1689001857.433 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-07-10T15:10:57.433Z,1689001857.433 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-07-10T15:10:57.433Z,1689001857.433 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-07-10T15:10:57.434Z,1689001857.434 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-07-10T15:10:57.434Z,1689001857.434 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-07-10T15:10:57.459Z,1689001857.459 [CBIT](DEBUG): Deactivating GF circuits.
2023-07-10T15:10:57.460Z,1689001857.460 [CBIT](DEBUG): Deactivating emergency mode.
2023-07-10T15:10:57.499Z,1689001857.499 [CBIT](DEBUG): Backplane powered.
2023-07-10T15:10:57.500Z,1689001857.500 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-07-10T15:10:57.513Z,1689001857.513 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-07-10T15:10:57.544Z,1689001857.544 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2023-07-10T15:10:57.545Z,1689001857.545 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-07-10T15:10:57.618Z,1689001857.618 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-07-10T15:10:57.619Z,1689001857.619 [Default:A.Wait](DEBUG): Construct Wait.
2023-07-10T15:10:57.621Z,1689001857.621 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-07-10T15:10:57.673Z,1689001857.673 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-07-10T15:10:57.676Z,1689001857.676 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-07-10T15:10:57.701Z,1689001857.701 [Default:E.Execute](DEBUG): Construct Execute.
2023-07-10T15:10:57.720Z,1689001857.720 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P4H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2023-07-10T15:10:57.725Z,1689001857.725 [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,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2023-07-10T15:10:57.739Z,1689001857.739 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-07-10T15:10:57.807Z,1689001857.807 [Radio_Surface](INFO): Powering up
2023-07-10T15:10:57.814Z,1689001857.814 [Power24vConverter](INFO): Powering up.
2023-07-10T15:10:57.815Z,1689001857.815 [CANONSampler](INFO): Powering down
2023-07-10T15:10:57.861Z,1689001857.861 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-07-10T15:10:57.872Z,1689001857.872 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-07-10T15:10:57.873Z,1689001857.873 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-07-10T15:10:57.880Z,1689001857.880 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-07-10T15:10:57.881Z,1689001857.881 [MassServo](DEBUG): Initializing EZServoServo.
2023-07-10T15:10:57.888Z,1689001857.888 [MassServo](DEBUG): Initializing MassServo.
2023-07-10T15:10:57.889Z,1689001857.889 [RudderServo](DEBUG): Initializing EZServoServo.
2023-07-10T15:10:57.896Z,1689001857.896 [RudderServo](DEBUG): Initializing RudderServo.
2023-07-10T15:10:57.897Z,1689001857.897 [ThrusterServo](DEBUG): Initializing EZServoServo.
2023-07-10T15:10:57.904Z,1689001857.904 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2023-07-10T15:11:00.039Z,1689001860.039 [WetLabsBB2FL](INFO): Powering down
2023-07-10T15:11:09.898Z,1689001869.898 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004682
2023-07-10T15:11:26.421Z,1689001886.421 [NAL9602](INFO): Powering up NAL9602
2023-07-10T15:11:37.341Z,1689001897.341 [NAL9602](INFO): NAL9602 initialized
2023-07-10T15:11:55.982Z,1689001915.982 [SBIT](IMPORTANT): Beginning Startup BIT
2023-07-10T15:11:55.986Z,1689001915.986 [CBIT](IMPORTANT): Beginning ground fault scan
2023-07-10T15:12:06.871Z,1689001926.871 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-07-10T15:12:07.279Z,1689001927.279 [CBIT](IMPORTANT): Beginning ground fault scan
2023-07-10T15:12:18.324Z,1689001938.324 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-07-10T15:12:18.729Z,1689001938.729 [CBIT](IMPORTANT): Beginning ground fault scan
2023-07-10T15:12:29.532Z,1689001949.532 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-07-10T15:12:29.532Z,1689001949.532 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2023-07-10T15:12:50.078Z,1689001970.078 [SBIT](IMPORTANT): SBIT PASSED
2023-07-10T15:12:50.078Z,1689001970.078 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-07-10T15:12:50.079Z,1689001970.079 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2023-07-10T15:12:50.079Z,1689001970.079 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=20 count;

2023-07-10T15:12:50.079Z,1689001970.079 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool;

2023-07-10T15:12:50.079Z,1689001970.079 [SBIT](IMPORTANT): CBIT.runElevOffsetCalc=1 bool;

2023-07-10T15:12:50.079Z,1689001970.079 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2023-07-10T15:12:50.079Z,1689001970.079 [SBIT](IMPORTANT): RudderServo.offsetAngle=0 degree;

2023-07-10T15:12:50.079Z,1689001970.079 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=0 bool;

2023-07-10T15:12:50.080Z,1689001970.080 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=212.769452 cubic_centimeter;

2023-07-10T15:12:50.080Z,1689001970.080 [SBIT](IMPORTANT): VerticalControl.massDefault=-9.553429 millimeter;

2023-07-10T15:12:50.484Z,1689001970.484 [MissionManager](IMPORTANT): Started mission Startup
2023-07-10T15:12:50.485Z,1689001970.485 [Startup] Running Loop=1
2023-07-10T15:12:50.485Z,1689001970.485 [Startup](DEBUG): Aggregate::initialize Startup
2023-07-10T15:12:50.485Z,1689001970.485 [Startup:A.GoToSurface] Running Loop=1
2023-07-10T15:12:50.485Z,1689001970.485 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-07-10T15:12:50.485Z,1689001970.485 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-07-10T15:12:50.486Z,1689001970.486 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-07-10T15:12:50.486Z,1689001970.486 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-07-10T15:12:50.486Z,1689001970.486 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-07-10T15:12:50.487Z,1689001970.487 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-07-10T15:12:50.489Z,1689001970.489 [Startup:StartupSatComms] Running Loop=1
2023-07-10T15:12:50.489Z,1689001970.489 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-07-10T15:12:50.489Z,1689001970.489 [Startup:StartupSatComms:A] Running Loop=1
2023-07-10T15:12:50.878Z,1689001970.878 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-07-10T15:13:50.672Z,1689002030.672 [Startup:StartupSatComms:A](INFO): Timed out from 2023-07-10T15:12:50.5Z
2023-07-10T15:13:50.672Z,1689002030.672 [Startup:StartupSatComms:A] Stopped
2023-07-10T15:13:50.672Z,1689002030.672 [Startup:StartupSatComms:B] Running Loop=1
2023-07-10T15:13:51.075Z,1689002031.075 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-07-10T15:13:57.503Z,1689002037.503 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20230710T151042/Courier0000.lzma
2023-07-10T15:13:57.570Z,1689002037.570 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-07-10T15:13:57.570Z,1689002037.570 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-07-10T15:13:57.582Z,1689002037.582 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-07-10T15:13:57.963Z,1689002037.963 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-07-10T15:13:57.963Z,1689002037.963 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-07-10T15:13:58.506Z,1689002038.506 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Courier0000.lzma.bak
2023-07-10T15:13:58.506Z,1689002038.506 [DataOverHttps](INFO): SBD MOMSN=18458283
2023-07-10T15:14:13.856Z,1689002053.856 [DataOverHttps](INFO): Sending 738 bytes from file Logs/20230710T151042/Express0001.lzma
2023-07-10T15:14:14.857Z,1689002054.857 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Express0001.lzma.bak
2023-07-10T15:14:14.858Z,1689002054.858 [DataOverHttps](INFO): SBD MOMSN=18458286
2023-07-10T15:14:16.123Z,1689002056.123 [Startup:StartupSatComms:B] Stopped
2023-07-10T15:14:16.123Z,1689002056.123 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-07-10T15:14:16.123Z,1689002056.123 [Startup:StartupSatComms] Stopped
2023-07-10T15:14:16.124Z,1689002056.124 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-07-10T15:14:16.124Z,1689002056.124 [Startup](INFO): Completed Startup
2023-07-10T15:14:16.124Z,1689002056.124 [MissionManager](INFO): Startup is completed.
2023-07-10T15:14:16.125Z,1689002056.125 [MissionManager](INFO): Uninitializing Mission Startup
2023-07-10T15:14:16.125Z,1689002056.125 [Startup] Stopped
2023-07-10T15:14:16.125Z,1689002056.125 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-07-10T15:14:16.125Z,1689002056.125 [Startup:A.GoToSurface] Stopped
2023-07-10T15:14:16.125Z,1689002056.125 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-07-10T15:14:16.537Z,1689002056.537 [MissionManager](IMPORTANT): Started mission Default
2023-07-10T15:14:16.537Z,1689002056.537 [Default] Running Loop=1
2023-07-10T15:14:16.537Z,1689002056.537 [Default](DEBUG): Aggregate::initialize Default
2023-07-10T15:14:16.537Z,1689002056.537 [Default:B.GoToSurface] Running Loop=1
2023-07-10T15:14:16.537Z,1689002056.537 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-07-10T15:14:16.538Z,1689002056.538 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-07-10T15:14:16.538Z,1689002056.538 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-07-10T15:14:16.538Z,1689002056.538 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-07-10T15:14:16.539Z,1689002056.539 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-07-10T15:14:16.539Z,1689002056.539 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-07-10T15:14:16.539Z,1689002056.539 [Default:A.Wait] Running Loop=1
2023-07-10T15:14:16.539Z,1689002056.539 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-07-10T15:14:29.861Z,1689002069.861 [Default:A.Wait](INFO): Done Waiting.
2023-07-10T15:14:29.861Z,1689002069.861 [Default:A.Wait] Stopped
2023-07-10T15:14:29.861Z,1689002069.861 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T15:14:30.269Z,1689002070.269 [Default:CheckIn] Running Loop=1
2023-07-10T15:14:30.269Z,1689002070.269 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T15:14:30.270Z,1689002070.270 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T15:14:30.665Z,1689002070.665 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-07-10T15:15:57.104Z,1689002157.104 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-07-10T15:16:12.456Z,1689002172.456 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-07-10T15:16:27.808Z,1689002187.808 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-07-10T15:16:40.745Z,1689002200.745 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-07-10T15:16:43.564Z,1689002203.564 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-07-10T15:16:58.108Z,1689002218.108 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-07-10T15:16:58.545Z,1689002218.545 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2023-07-10T15:16:58.545Z,1689002218.545 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-07-10T15:16:58.565Z,1689002218.565 [CommandExec](IMPORTANT): got command ! /etc/rc.d/init.d/settime restart
2023-07-10T15:16:58.607Z,1689002218.607 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-07-10T15:16:58.955Z,1689002218.955 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-07-10T15:16:58.956Z,1689002218.956 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2023-07-10T15:17:01.201Z,1689002221.201 [CommandExec](IMPORTANT): settime stop or restart
  Setting hardware clock from system clock
  Mon Jul 10 15:16:58 2023  0.000000 seconds
  settime start or restart
  Setting clock frequency and tick values from nonvolatile memory.
      mode:         16386
  -o  offset:       793
  -f  frequency:    886544
      maxerror:     240187
      esterror:     987
      status:       16385 (PLL)
  -p  timeconstant: 10
      precision:    1
      tolerance:    32768000
  -t  tick:         10000
      time.tv_sec:  1689002218
      time.tv_usec: 847394
      return value: 0 (clock synchronized)
  ping returned from NTP server: 216.239.35.4
  45115 55020.060   44008.0      0.0      48.7     91.6    886544
  Time set from NTP server: 216.239.35.4
  Setting hardware clock from system clock
  Mon Jul 10 15:17:01 2023  0.000000 seconds
  NTPD already running.
  
2023-07-10T15:17:13.034Z,1689002233.034 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-07-10T15:17:27.983Z,1689002247.983 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-07-10T15:17:42.930Z,1689002262.930 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-07-10T15:17:57.890Z,1689002277.890 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-07-10T15:17:58.223Z,1689002278.223 [CommandExec](IMPORTANT): got command burn on
2023-07-10T15:17:58.223Z,1689002278.223 [CommandExec](IMPORTANT): Activating dropweight wire
2023-07-10T15:18:03.215Z,1689002283.215 [CommandExec](IMPORTANT): got command burn off
2023-07-10T15:18:03.215Z,1689002283.215 [CommandExec](IMPORTANT): Deactivating dropweight wire
2023-07-10T15:18:12.023Z,1689002292.023 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2023-07-10T15:18:18.899Z,1689002298.899 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 5.
2023-07-10T15:18:18.903Z,1689002298.903 [BPC1](INFO): Received data from all battery sticks.
2023-07-10T15:19:30.416Z,1689002370.416 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-07-10T15:14:30.3Z
2023-07-10T15:19:30.416Z,1689002370.416 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T15:19:30.416Z,1689002370.416 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T15:19:30.823Z,1689002370.823 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-07-10T15:19:37.657Z,1689002377.657 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20230710T151042/Courier0004.lzma
2023-07-10T15:19:38.660Z,1689002378.660 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Courier0004.lzma.bak
2023-07-10T15:19:38.660Z,1689002378.660 [DataOverHttps](INFO): SBD MOMSN=18458315
2023-07-10T15:19:54.011Z,1689002394.011 [DataOverHttps](INFO): Sending 684 bytes from file Logs/20230710T151042/Express0005.lzma
2023-07-10T15:19:55.011Z,1689002395.011 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Express0005.lzma.bak
2023-07-10T15:19:55.012Z,1689002395.012 [DataOverHttps](INFO): SBD MOMSN=18458317
2023-07-10T15:19:56.281Z,1689002396.281 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T15:19:56.281Z,1689002396.281 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T15:19:56.282Z,1689002396.282 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T15:19:59.520Z,1689002399.520 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2023-07-10T15:19:59.520Z,1689002399.520 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-07-10T15:19:59.558Z,1689002399.558 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-07-10T15:19:59.943Z,1689002399.943 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-07-10T15:19:59.943Z,1689002399.943 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2023-07-10T15:20:58.880Z,1689002458.880 [Power24vConverter](INFO): Powering down.
2023-07-10T15:21:26.714Z,1689002486.714 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T15:21:40.084Z,1689002500.084 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-07-10T15:21:40.084Z,1689002500.084 [NAL9602] Data Fault, FailCount= 1
2023-07-10T15:21:40.084Z,1689002500.084 [NAL9602](ERROR): Data Fault
2023-07-10T15:21:40.101Z,1689002500.101 [CBIT](ERROR): Data Fault in component: NAL9602
2023-07-10T15:21:40.488Z,1689002500.488 [NAL9602](INFO): Powering down
2023-07-10T15:21:41.328Z,1689002501.328 [CBIT](INFO): Clearing failed state for component NAL9602
2023-07-10T15:21:41.328Z,1689002501.328 [NAL9602] No Fault, FailCount= 1
2023-07-10T15:21:57.838Z,1689002517.838 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T15:22:10.795Z,1689002530.795 [NAL9602](INFO): Powering up NAL9602
2023-07-10T15:22:21.699Z,1689002541.699 [NAL9602](INFO): NAL9602 initialized
2023-07-10T15:22:28.962Z,1689002548.962 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T15:23:00.086Z,1689002580.086 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T15:23:00.507Z,1689002580.507 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2023-07-10T15:23:00.507Z,1689002580.507 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-07-10T15:23:00.545Z,1689002580.545 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-07-10T15:23:00.924Z,1689002580.924 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-07-10T15:23:00.924Z,1689002580.924 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2023-07-10T15:23:31.210Z,1689002611.210 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T15:23:34.223Z,1689002614.223 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.731888
2023-07-10T15:24:32.454Z,1689002672.454 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T15:24:56.874Z,1689002696.874 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T15:24:56.874Z,1689002696.874 [Default:CheckIn:C.Wait] Stopped
2023-07-10T15:24:56.874Z,1689002696.874 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T15:24:56.874Z,1689002696.874 [Default:CheckIn:D] Running Loop=1
2023-07-10T15:24:57.250Z,1689002697.250 [Default:CheckIn:D] Stopped
2023-07-10T15:24:57.251Z,1689002697.251 [Default:CheckIn:E] Running Loop=1
2023-07-10T15:24:57.676Z,1689002697.676 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.678556 min 
2023-07-10T15:24:57.676Z,1689002697.676 [Default:CheckIn:E] Stopped
2023-07-10T15:24:57.677Z,1689002697.677 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T15:24:57.677Z,1689002697.677 [Default:CheckIn] Stopped
2023-07-10T15:24:57.677Z,1689002697.677 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T15:24:57.677Z,1689002697.677 [Default:CheckIn](INFO): Running loop #2
2023-07-10T15:24:57.677Z,1689002697.677 [Default:CheckIn] Running Loop=2
2023-07-10T15:24:57.677Z,1689002697.677 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T15:24:57.677Z,1689002697.677 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T15:25:03.582Z,1689002703.582 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T15:25:34.706Z,1689002734.706 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T15:26:01.495Z,1689002761.495 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2023-07-10T15:26:01.495Z,1689002761.495 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-07-10T15:26:01.526Z,1689002761.526 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-07-10T15:26:01.916Z,1689002761.916 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-07-10T15:26:01.916Z,1689002761.916 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2023-07-10T15:26:05.838Z,1689002765.838 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T15:26:36.966Z,1689002796.966 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T15:27:08.098Z,1689002828.098 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T15:27:39.234Z,1689002859.234 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T15:28:10.358Z,1689002890.358 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T15:28:41.490Z,1689002921.490 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T15:29:02.491Z,1689002942.491 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2023-07-10T15:29:02.491Z,1689002942.491 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-07-10T15:29:02.526Z,1689002942.526 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-07-10T15:29:02.888Z,1689002942.888 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-07-10T15:29:02.888Z,1689002942.888 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2023-07-10T15:29:12.614Z,1689002952.614 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T15:29:43.754Z,1689002983.754 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T15:29:57.830Z,1689002997.830 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-07-10T15:24:57.7Z
2023-07-10T15:29:57.830Z,1689002997.830 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T15:29:57.830Z,1689002997.830 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T15:30:14.886Z,1689003014.886 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T15:30:46.014Z,1689003046.014 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T15:31:17.146Z,1689003077.146 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T15:31:48.290Z,1689003108.290 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T15:32:03.479Z,1689003123.479 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2023-07-10T15:32:03.479Z,1689003123.479 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-07-10T15:32:03.489Z,1689003123.489 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-07-10T15:32:03.911Z,1689003123.911 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-07-10T15:32:03.911Z,1689003123.911 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2023-07-10T15:32:19.414Z,1689003139.414 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T15:32:24.867Z,1689003144.867 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-07-10T15:32:24.867Z,1689003144.867 [NAL9602] Data Fault, FailCount= 2
2023-07-10T15:32:24.867Z,1689003144.867 [NAL9602](ERROR): Data Fault
2023-07-10T15:32:24.890Z,1689003144.890 [CBIT](ERROR): Data Fault in component: NAL9602
2023-07-10T15:32:25.275Z,1689003145.275 [NAL9602](INFO): Powering down
2023-07-10T15:32:26.099Z,1689003146.099 [CBIT](INFO): Clearing failed state for component NAL9602
2023-07-10T15:32:26.099Z,1689003146.099 [NAL9602] No Fault, FailCount= 2
2023-07-10T15:32:50.538Z,1689003170.538 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T15:32:55.571Z,1689003175.571 [NAL9602](INFO): Powering up NAL9602
2023-07-10T15:33:06.479Z,1689003186.479 [NAL9602](INFO): NAL9602 initialized
2023-07-10T15:33:21.674Z,1689003201.674 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T15:33:52.798Z,1689003232.798 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T15:34:23.922Z,1689003263.922 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T15:34:55.058Z,1689003295.058 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T15:35:04.481Z,1689003304.481 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2023-07-10T15:35:04.481Z,1689003304.481 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-07-10T15:35:04.491Z,1689003304.491 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-07-10T15:35:04.904Z,1689003304.904 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-07-10T15:35:04.904Z,1689003304.904 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2023-07-10T15:35:26.182Z,1689003326.182 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T15:35:57.314Z,1689003357.314 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T15:36:28.438Z,1689003388.438 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T15:36:59.562Z,1689003419.562 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T15:37:30.686Z,1689003450.686 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T15:38:01.810Z,1689003481.810 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T15:38:05.456Z,1689003485.456 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2023-07-10T15:38:05.456Z,1689003485.456 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-07-10T15:38:05.466Z,1689003485.466 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-07-10T15:38:05.880Z,1689003485.880 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-07-10T15:38:05.880Z,1689003485.880 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2023-07-10T15:38:23.665Z,1689003503.665 [NAL9602](INFO): SBD MO Status=2, MOMSN=43899, MT Status=2, MTMSN=0
2023-07-10T15:38:23.665Z,1689003503.665 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-07-10T15:38:32.946Z,1689003512.946 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T15:38:41.089Z,1689003521.089 [NAL9602](INFO): SBD MO Status=2, MOMSN=43899, MT Status=2, MTMSN=0
2023-07-10T15:38:41.089Z,1689003521.089 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-07-10T15:39:04.074Z,1689003544.074 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T15:39:16.677Z,1689003556.677 [NAL9602](INFO): SBD MO Status=1, MOMSN=43899, MT Status=0, MTMSN=0
2023-07-10T15:39:16.726Z,1689003556.726 [NAL9602](INFO): Sent 39 bytes from file Logs/20230710T151042/Courier0007.lzma
2023-07-10T15:39:16.726Z,1689003556.726 [NAL9602](INFO): Packets left to send: 0
2023-07-10T15:39:32.322Z,1689003572.322 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002816
2023-07-10T15:39:35.709Z,1689003575.709 [NAL9602](INFO): SBD MO Status=1, MOMSN=43900, MT Status=0, MTMSN=0
2023-07-10T15:39:35.762Z,1689003575.762 [NAL9602](INFO): Sent 181 bytes from file Logs/20230710T151042/Express0008.lzma
2023-07-10T15:39:35.762Z,1689003575.762 [NAL9602](INFO): Packets left to send: 0
2023-07-10T15:39:36.921Z,1689003576.921 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,153935.00,A,3648.13984,N,12147.23463,W,3.071,61.57,100723,,,A*4C
2023-07-10T15:39:36.924Z,1689003576.924 [NAL9602](INFO): GPS fix at 20230710T153935: (36.802331, -121.787244)
2023-07-10T15:39:38.952Z,1689003578.952 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T15:39:38.952Z,1689003578.952 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T15:39:38.952Z,1689003578.952 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T15:40:09.242Z,1689003609.242 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T15:42:54.411Z,1689003774.411 [DataOverHttps](IMPORTANT): SBD MTMSN=20230710T154253
2023-07-10T15:43:02.674Z,1689003782.674 [DataOverHttps](INFO): Received command: ibit
2023-07-10T15:43:02.707Z,1689003782.707 [CommandExec](IMPORTANT): got command ibit
2023-07-10T15:43:03.091Z,1689003783.091 [IBIT](IMPORTANT): Beginning Initiated BIT
2023-07-10T15:43:03.091Z,1689003783.091 [IBIT](IMPORTANT): Beginning control surface checks.
2023-07-10T15:43:03.094Z,1689003783.094 [CBIT](IMPORTANT): Beginning ground fault scan
2023-07-10T15:43:03.675Z,1689003783.675 [DataOverHttps](IMPORTANT): SBD MTMSN=20230710T154302
2023-07-10T15:43:04.697Z,1689003784.697 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154302.00,A,3648.14012,N,12147.23117,W,0.019,12.17,100723,,,D*4A
2023-07-10T15:43:04.699Z,1689003784.699 [NAL9602](INFO): GPS fix at 20230710T154302: (36.802335, -121.787186)
2023-07-10T15:43:05.971Z,1689003785.971 [CBIT](ERROR): Error reading 24V bus power status. Assuming off for scan purposes.
2023-07-10T15:43:11.293Z,1689003791.293 [DataOverHttps](INFO): Received command: failc
2023-07-10T15:43:11.366Z,1689003791.366 [CommandExec](IMPORTANT): got command failComponent
2023-07-10T15:43:11.367Z,1689003791.367 [CommandExec](IMPORTANT): Failed components:
2023-07-10T15:43:11.367Z,1689003791.367 [CommandExec](IMPORTANT): No failed Components.
2023-07-10T15:43:14.004Z,1689003794.004 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002277
CHAN A1 (24V) N/A reporting off
CHAN A2 (12V): -0.002353
CHAN A3 (5V): -0.001628
CHAN B0 (3.3V): 0.000307
CHAN B1 (3.15aV): -0.000137
CHAN B2 (3.15bV): 0.000030
CHAN B3 (GND): -0.000389
OPEN: -0.000626
Full Scale: +/- 1 mA
2023-07-10T15:43:15.188Z,1689003795.188 [NAL9602](INFO): SBD MO Status=0, MOMSN=43901, MT Status=0, MTMSN=0
2023-07-10T15:43:15.189Z,1689003795.189 [NAL9602](INFO): No messages in MT queue
2023-07-10T15:43:16.470Z,1689003796.470 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154314.00,A,3648.14012,N,12147.23188,W,0.078,12.17,100723,,,D*4C
2023-07-10T15:43:16.472Z,1689003796.472 [NAL9602](INFO): GPS fix at 20230710T154314: (36.802335, -121.787198)
2023-07-10T15:43:18.829Z,1689003798.829 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154317.00,A,3648.14011,N,12147.23200,W,0.039,12.17,100723,,,D*4A
2023-07-10T15:43:18.831Z,1689003798.831 [NAL9602](INFO): GPS fix at 20230710T154317: (36.802335, -121.787200)
2023-07-10T15:43:22.061Z,1689003802.061 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154320.00,A,3648.14008,N,12147.23220,W,0.000,12.17,100723,,,D*4E
2023-07-10T15:43:22.063Z,1689003802.063 [NAL9602](INFO): GPS fix at 20230710T154320: (36.802335, -121.787203)
2023-07-10T15:43:24.883Z,1689003804.883 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154323.00,A,3648.14000,N,12147.23228,W,0.039,12.17,100723,,,D*47
2023-07-10T15:43:24.886Z,1689003804.886 [NAL9602](INFO): GPS fix at 20230710T154323: (36.802333, -121.787205)
2023-07-10T15:43:28.116Z,1689003808.116 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154326.00,A,3648.13997,N,12147.23234,W,0.019,12.17,100723,,,D*4D
2023-07-10T15:43:28.118Z,1689003808.118 [NAL9602](INFO): GPS fix at 20230710T154326: (36.802333, -121.787206)
2023-07-10T15:43:30.949Z,1689003810.949 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154329.00,A,3648.14003,N,12147.23237,W,0.019,12.17,100723,,,D*42
2023-07-10T15:43:30.951Z,1689003810.951 [NAL9602](INFO): GPS fix at 20230710T154329: (36.802334, -121.787206)
2023-07-10T15:43:33.777Z,1689003813.777 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154332.00,A,3648.14015,N,12147.23236,W,0.019,12.17,100723,,,D*4E
2023-07-10T15:43:33.779Z,1689003813.779 [NAL9602](INFO): GPS fix at 20230710T154332: (36.802336, -121.787206)
2023-07-10T15:43:37.003Z,1689003817.003 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154335.00,A,3648.14026,N,12147.23237,W,0.039,12.17,100723,,,D*4A
2023-07-10T15:43:37.006Z,1689003817.006 [NAL9602](INFO): GPS fix at 20230710T154335: (36.802338, -121.787206)
2023-07-10T15:43:39.837Z,1689003819.837 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154339.00,A,3648.14030,N,12147.23236,W,0.019,12.17,100723,,,D*42
2023-07-10T15:43:39.839Z,1689003819.839 [NAL9602](INFO): GPS fix at 20230710T154339: (36.802338, -121.787206)
2023-07-10T15:43:43.073Z,1689003823.073 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154342.00,A,3648.14032,N,12147.23235,W,0.039,12.17,100723,,,D*4D
2023-07-10T15:43:43.075Z,1689003823.075 [NAL9602](INFO): GPS fix at 20230710T154342: (36.802339, -121.787206)
2023-07-10T15:43:45.896Z,1689003825.896 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154345.00,A,3648.14036,N,12147.23226,W,0.000,12.17,100723,,,D*46
2023-07-10T15:43:45.899Z,1689003825.899 [NAL9602](INFO): GPS fix at 20230710T154345: (36.802339, -121.787204)
2023-07-10T15:43:49.125Z,1689003829.125 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154348.00,A,3648.14041,N,12147.23219,W,0.019,12.17,100723,,,D*4F
2023-07-10T15:43:49.127Z,1689003829.127 [NAL9602](INFO): GPS fix at 20230710T154348: (36.802340, -121.787203)
2023-07-10T15:43:49.568Z,1689003829.568 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 3
Latitude: 36.802341 Longitude: -121.787201
2023-07-10T15:43:49.945Z,1689003829.945 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.572001
2023-07-10T15:43:49.946Z,1689003829.946 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2023-07-10T15:43:49.946Z,1689003829.946 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2023-07-10T15:43:50.352Z,1689003830.352 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2023-07-10T15:43:50.352Z,1689003830.352 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2023-07-10T15:43:50.353Z,1689003830.353 [IBIT](IMPORTANT): Pressure:10.082972 PSI
2023-07-10T15:43:50.353Z,1689003830.353 [IBIT](IMPORTANT): Humidity:3.204737 %
2023-07-10T15:43:50.776Z,1689003830.776 [IBIT](IMPORTANT): Vehicle Pitch:-0.273299 degrees
2023-07-10T15:43:50.776Z,1689003830.776 [IBIT](IMPORTANT): Vehicle  Roll:-7.107750 degrees
2023-07-10T15:43:50.776Z,1689003830.776 [IBIT](IMPORTANT): Vehicle  Heading:214.866714 degrees
2023-07-10T15:43:51.179Z,1689003831.179 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2023-07-10T15:43:51.179Z,1689003831.179 [IBIT](IMPORTANT): buoyancyNeutral: 212.769455 cc
2023-07-10T15:43:51.179Z,1689003831.179 [IBIT](IMPORTANT): massDefault: -0.955343 cm
2023-07-10T15:43:51.179Z,1689003831.179 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2023-07-10T15:43:51.180Z,1689003831.180 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2023-07-10T15:43:51.180Z,1689003831.180 [IBIT](IMPORTANT): IBIT PASSED
2023-07-10T15:44:21.441Z,1689003861.441 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T15:44:39.669Z,1689003879.669 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T15:44:39.669Z,1689003879.669 [Default:CheckIn:C.Wait] Stopped
2023-07-10T15:44:39.669Z,1689003879.669 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T15:44:39.669Z,1689003879.669 [Default:CheckIn:D] Running Loop=1
2023-07-10T15:44:40.044Z,1689003880.044 [Default:CheckIn:D] Stopped
2023-07-10T15:44:40.044Z,1689003880.044 [Default:CheckIn:E] Running Loop=1
2023-07-10T15:44:40.465Z,1689003880.465 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 30.391781 min 
2023-07-10T15:44:40.465Z,1689003880.465 [Default:CheckIn:E] Stopped
2023-07-10T15:44:40.465Z,1689003880.465 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T15:44:40.465Z,1689003880.465 [Default:CheckIn] Stopped
2023-07-10T15:44:40.465Z,1689003880.465 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T15:44:40.465Z,1689003880.465 [Default:CheckIn](INFO): Running loop #3
2023-07-10T15:44:40.465Z,1689003880.465 [Default:CheckIn] Running Loop=3
2023-07-10T15:44:40.465Z,1689003880.465 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T15:44:40.466Z,1689003880.466 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T15:44:42.461Z,1689003882.461 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,154441.00,A,3648.13996,N,12147.23012,W,0.019,12.17,100723,,,D*4C
2023-07-10T15:44:42.463Z,1689003882.463 [NAL9602](INFO): GPS fix at 20230710T154441: (36.802333, -121.787169)
2023-07-10T15:44:42.490Z,1689003882.490 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T15:44:42.491Z,1689003882.491 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T15:44:43.576Z,1689003883.576 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20230710T151042/Courier0010.lzma
2023-07-10T15:44:44.363Z,1689003884.363 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Courier0010.lzma.bak
2023-07-10T15:44:44.364Z,1689003884.364 [DataOverHttps](INFO): SBD MOMSN=18458381
2023-07-10T15:45:03.104Z,1689003903.104 [DataOverHttps](INFO): Sending 1003 bytes from file Logs/20230710T151042/Express0011.lzma
2023-07-10T15:45:04.100Z,1689003904.100 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Express0011.lzma.bak
2023-07-10T15:45:04.100Z,1689003904.100 [DataOverHttps](INFO): SBD MOMSN=18458387
2023-07-10T15:45:19.906Z,1689003919.906 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20230710T151042/Express0014.lzma
2023-07-10T15:45:20.907Z,1689003920.907 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Express0014.lzma.bak
2023-07-10T15:45:20.908Z,1689003920.908 [DataOverHttps](INFO): SBD MOMSN=18458422
2023-07-10T15:45:22.113Z,1689003922.113 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T15:45:22.113Z,1689003922.113 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T15:45:22.113Z,1689003922.113 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T15:46:46.144Z,1689004006.144 [NAL9602](INFO): SBD MO Status=0, MOMSN=43902, MT Status=0, MTMSN=0
2023-07-10T15:46:46.145Z,1689004006.145 [NAL9602](INFO): No messages in MT queue
2023-07-10T15:47:16.847Z,1689004036.847 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T15:50:22.691Z,1689004222.691 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T15:50:22.691Z,1689004222.691 [Default:CheckIn:C.Wait] Stopped
2023-07-10T15:50:22.692Z,1689004222.692 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T15:50:22.692Z,1689004222.692 [Default:CheckIn:D] Running Loop=1
2023-07-10T15:50:23.096Z,1689004223.096 [Default:CheckIn:D] Stopped
2023-07-10T15:50:23.096Z,1689004223.096 [Default:CheckIn:E] Running Loop=1
2023-07-10T15:50:23.517Z,1689004223.517 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 36.109318 min 
2023-07-10T15:50:23.517Z,1689004223.517 [Default:CheckIn:E] Stopped
2023-07-10T15:50:23.517Z,1689004223.517 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T15:50:23.517Z,1689004223.517 [Default:CheckIn] Stopped
2023-07-10T15:50:23.517Z,1689004223.517 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T15:50:23.517Z,1689004223.517 [Default:CheckIn](INFO): Running loop #4
2023-07-10T15:50:23.517Z,1689004223.517 [Default:CheckIn] Running Loop=4
2023-07-10T15:50:23.518Z,1689004223.518 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T15:50:23.518Z,1689004223.518 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T15:50:25.517Z,1689004225.517 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155024.00,A,3648.13895,N,12147.23061,W,0.000,12.17,100723,,,D*44
2023-07-10T15:50:25.519Z,1689004225.519 [NAL9602](INFO): GPS fix at 20230710T155024: (36.802316, -121.787177)
2023-07-10T15:50:25.546Z,1689004225.546 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T15:50:25.546Z,1689004225.546 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T15:50:33.101Z,1689004233.101 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230710T151042/Courier0016.lzma
2023-07-10T15:50:34.103Z,1689004234.103 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Courier0016.lzma.bak
2023-07-10T15:50:34.104Z,1689004234.104 [DataOverHttps](INFO): SBD MOMSN=18458433
2023-07-10T15:50:38.844Z,1689004238.844 [NAL9602](INFO): SBD MO Status=0, MOMSN=43903, MT Status=0, MTMSN=0
2023-07-10T15:50:38.844Z,1689004238.844 [NAL9602](INFO): No messages in MT queue
2023-07-10T15:50:51.841Z,1689004251.841 [DataOverHttps](INFO): Sending 127 bytes from file Logs/20230710T151042/Express0017.lzma
2023-07-10T15:50:52.844Z,1689004252.844 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Express0017.lzma.bak
2023-07-10T15:50:52.844Z,1689004252.844 [DataOverHttps](INFO): SBD MOMSN=18458436
2023-07-10T15:50:53.866Z,1689004253.866 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T15:50:53.866Z,1689004253.866 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T15:50:53.866Z,1689004253.866 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T15:51:09.581Z,1689004269.581 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T15:55:54.429Z,1689004554.429 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T15:55:54.429Z,1689004554.429 [Default:CheckIn:C.Wait] Stopped
2023-07-10T15:55:54.429Z,1689004554.429 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T15:55:54.430Z,1689004554.430 [Default:CheckIn:D] Running Loop=1
2023-07-10T15:55:54.845Z,1689004554.845 [Default:CheckIn:D] Stopped
2023-07-10T15:55:54.845Z,1689004554.845 [Default:CheckIn:E] Running Loop=1
2023-07-10T15:55:55.242Z,1689004555.242 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.638464 min 
2023-07-10T15:55:55.243Z,1689004555.243 [Default:CheckIn:E] Stopped
2023-07-10T15:55:55.243Z,1689004555.243 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T15:55:55.243Z,1689004555.243 [Default:CheckIn] Stopped
2023-07-10T15:55:55.243Z,1689004555.243 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T15:55:55.243Z,1689004555.243 [Default:CheckIn](INFO): Running loop #5
2023-07-10T15:55:55.243Z,1689004555.243 [Default:CheckIn] Running Loop=5
2023-07-10T15:55:55.243Z,1689004555.243 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T15:55:55.243Z,1689004555.243 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T15:55:57.248Z,1689004557.248 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,155556.00,A,3648.14850,N,12147.22530,W,1.186,20.19,100723,,,D*4B
2023-07-10T15:55:57.250Z,1689004557.250 [NAL9602](INFO): GPS fix at 20230710T155556: (36.802475, -121.787088)
2023-07-10T15:55:57.260Z,1689004557.260 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T15:55:57.260Z,1689004557.260 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T15:56:04.453Z,1689004564.453 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230710T151042/Courier0019.lzma
2023-07-10T15:56:05.456Z,1689004565.456 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Courier0019.lzma.bak
2023-07-10T15:56:05.456Z,1689004565.456 [DataOverHttps](INFO): SBD MOMSN=18458458
2023-07-10T15:56:08.155Z,1689004568.155 [NAL9602](INFO): SBD MO Status=0, MOMSN=43904, MT Status=0, MTMSN=0
2023-07-10T15:56:08.155Z,1689004568.155 [NAL9602](INFO): No messages in MT queue
2023-07-10T15:56:23.677Z,1689004583.677 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20230710T151042/Express0020.lzma
2023-07-10T15:56:24.680Z,1689004584.680 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Express0020.lzma.bak
2023-07-10T15:56:24.680Z,1689004584.680 [DataOverHttps](INFO): SBD MOMSN=18458461
2023-07-10T15:56:25.994Z,1689004585.994 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T15:56:25.994Z,1689004585.994 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T15:56:25.994Z,1689004585.994 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T15:56:38.903Z,1689004598.903 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T16:01:26.593Z,1689004886.593 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T16:01:26.593Z,1689004886.593 [Default:CheckIn:C.Wait] Stopped
2023-07-10T16:01:26.593Z,1689004886.593 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T16:01:26.594Z,1689004886.594 [Default:CheckIn:D] Running Loop=1
2023-07-10T16:01:26.984Z,1689004886.984 [Default:CheckIn:D] Stopped
2023-07-10T16:01:26.984Z,1689004886.984 [Default:CheckIn:E] Running Loop=1
2023-07-10T16:01:27.392Z,1689004887.392 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 47.174105 min 
2023-07-10T16:01:27.393Z,1689004887.393 [Default:CheckIn:E] Stopped
2023-07-10T16:01:27.393Z,1689004887.393 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T16:01:27.393Z,1689004887.393 [Default:CheckIn] Stopped
2023-07-10T16:01:27.393Z,1689004887.393 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T16:01:27.393Z,1689004887.393 [Default:CheckIn](INFO): Running loop #6
2023-07-10T16:01:27.393Z,1689004887.393 [Default:CheckIn] Running Loop=6
2023-07-10T16:01:27.393Z,1689004887.393 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T16:01:27.393Z,1689004887.393 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T16:01:29.406Z,1689004889.406 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160128.00,A,3648.14983,N,12147.22050,W,0.039,145.48,100723,,,D*7E
2023-07-10T16:01:29.408Z,1689004889.408 [NAL9602](INFO): GPS fix at 20230710T160128: (36.802497, -121.787008)
2023-07-10T16:01:29.427Z,1689004889.427 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T16:01:29.427Z,1689004889.427 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T16:01:36.641Z,1689004896.641 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230710T151042/Courier0022.lzma
2023-07-10T16:01:37.644Z,1689004897.644 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Courier0022.lzma.bak
2023-07-10T16:01:37.644Z,1689004897.644 [DataOverHttps](INFO): SBD MOMSN=18458472
2023-07-10T16:01:53.313Z,1689004913.313 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20230710T151042/Express0023.lzma
2023-07-10T16:01:54.316Z,1689004914.316 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Express0023.lzma.bak
2023-07-10T16:01:54.316Z,1689004914.316 [DataOverHttps](INFO): SBD MOMSN=18458475
2023-07-10T16:01:55.668Z,1689004915.668 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T16:01:55.668Z,1689004915.668 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T16:01:55.668Z,1689004915.668 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T16:02:46.568Z,1689004966.568 [NAL9602](INFO): SBD MO Status=0, MOMSN=43905, MT Status=0, MTMSN=0
2023-07-10T16:02:46.569Z,1689004966.569 [NAL9602](INFO): No messages in MT queue
2023-07-10T16:03:17.267Z,1689004997.267 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T16:06:56.252Z,1689005216.252 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T16:06:56.252Z,1689005216.252 [Default:CheckIn:C.Wait] Stopped
2023-07-10T16:06:56.253Z,1689005216.253 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T16:06:56.253Z,1689005216.253 [Default:CheckIn:D] Running Loop=1
2023-07-10T16:06:56.663Z,1689005216.663 [Default:CheckIn:D] Stopped
2023-07-10T16:06:56.663Z,1689005216.663 [Default:CheckIn:E] Running Loop=1
2023-07-10T16:06:57.066Z,1689005217.066 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.668762 min 
2023-07-10T16:06:57.067Z,1689005217.067 [Default:CheckIn:E] Stopped
2023-07-10T16:06:57.067Z,1689005217.067 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T16:06:57.067Z,1689005217.067 [Default:CheckIn] Stopped
2023-07-10T16:06:57.067Z,1689005217.067 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T16:06:57.067Z,1689005217.067 [Default:CheckIn](INFO): Running loop #7
2023-07-10T16:06:57.067Z,1689005217.067 [Default:CheckIn] Running Loop=7
2023-07-10T16:06:57.067Z,1689005217.067 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T16:06:57.067Z,1689005217.067 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T16:06:59.072Z,1689005219.072 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,160658.00,A,3648.15035,N,12147.22320,W,0.019,145.48,100723,,,D*7D
2023-07-10T16:06:59.074Z,1689005219.074 [NAL9602](INFO): GPS fix at 20230710T160658: (36.802506, -121.787053)
2023-07-10T16:06:59.085Z,1689005219.085 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T16:06:59.085Z,1689005219.085 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T16:07:05.657Z,1689005225.657 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230710T151042/Courier0025.lzma
2023-07-10T16:07:06.660Z,1689005226.660 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Courier0025.lzma.bak
2023-07-10T16:07:06.660Z,1689005226.660 [DataOverHttps](INFO): SBD MOMSN=18458492
2023-07-10T16:07:12.808Z,1689005232.808 [NAL9602](INFO): SBD MO Status=0, MOMSN=43906, MT Status=0, MTMSN=0
2023-07-10T16:07:12.808Z,1689005232.808 [NAL9602](INFO): No messages in MT queue
2023-07-10T16:07:22.445Z,1689005242.445 [DataOverHttps](INFO): Sending 127 bytes from file Logs/20230710T151042/Express0026.lzma
2023-07-10T16:07:23.448Z,1689005243.448 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Express0026.lzma.bak
2023-07-10T16:07:23.448Z,1689005243.448 [DataOverHttps](INFO): SBD MOMSN=18458495
2023-07-10T16:07:24.536Z,1689005244.536 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T16:07:24.537Z,1689005244.537 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T16:07:24.537Z,1689005244.537 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T16:07:43.519Z,1689005263.519 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T16:12:25.115Z,1689005545.115 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T16:12:25.115Z,1689005545.115 [Default:CheckIn:C.Wait] Stopped
2023-07-10T16:12:25.116Z,1689005545.116 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T16:12:25.116Z,1689005545.116 [Default:CheckIn:D] Running Loop=1
2023-07-10T16:12:25.520Z,1689005545.520 [Default:CheckIn:D] Stopped
2023-07-10T16:12:25.521Z,1689005545.521 [Default:CheckIn:E] Running Loop=1
2023-07-10T16:12:25.929Z,1689005545.929 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 58.149723 min 
2023-07-10T16:12:25.929Z,1689005545.929 [Default:CheckIn:E] Stopped
2023-07-10T16:12:25.929Z,1689005545.929 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T16:12:25.929Z,1689005545.929 [Default:CheckIn] Stopped
2023-07-10T16:12:25.929Z,1689005545.929 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T16:12:25.929Z,1689005545.929 [Default:CheckIn](INFO): Running loop #8
2023-07-10T16:12:25.929Z,1689005545.929 [Default:CheckIn] Running Loop=8
2023-07-10T16:12:25.929Z,1689005545.929 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T16:12:25.930Z,1689005545.930 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T16:12:27.941Z,1689005547.941 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161227.00,A,3648.15017,N,12147.22167,W,0.019,145.48,100723,,,D*71
2023-07-10T16:12:27.952Z,1689005547.952 [NAL9602](INFO): GPS fix at 20230710T161227: (36.802503, -121.787028)
2023-07-10T16:12:27.963Z,1689005547.963 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T16:12:27.963Z,1689005547.963 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T16:12:35.149Z,1689005555.149 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230710T151042/Courier0028.lzma
2023-07-10T16:12:36.152Z,1689005556.152 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Courier0028.lzma.bak
2023-07-10T16:12:36.152Z,1689005556.152 [DataOverHttps](INFO): SBD MOMSN=18458506
2023-07-10T16:12:41.268Z,1689005561.268 [NAL9602](INFO): SBD MO Status=0, MOMSN=43907, MT Status=0, MTMSN=0
2023-07-10T16:12:41.268Z,1689005561.268 [NAL9602](INFO): No messages in MT queue
2023-07-10T16:12:54.757Z,1689005574.757 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230710T151042/Express0029.lzma
2023-07-10T16:12:55.760Z,1689005575.760 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Express0029.lzma.bak
2023-07-10T16:12:55.760Z,1689005575.760 [DataOverHttps](INFO): SBD MOMSN=18458509
2023-07-10T16:12:57.092Z,1689005577.092 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T16:12:57.092Z,1689005577.092 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T16:12:57.092Z,1689005577.092 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T16:13:12.029Z,1689005592.029 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T16:17:57.708Z,1689005877.708 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T16:17:57.708Z,1689005877.708 [Default:CheckIn:C.Wait] Stopped
2023-07-10T16:17:57.708Z,1689005877.708 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T16:17:57.708Z,1689005877.708 [Default:CheckIn:D] Running Loop=1
2023-07-10T16:17:58.091Z,1689005878.091 [Default:CheckIn:D] Stopped
2023-07-10T16:17:58.091Z,1689005878.091 [Default:CheckIn:E] Running Loop=1
2023-07-10T16:17:58.497Z,1689005878.497 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.692562 min 
2023-07-10T16:17:58.497Z,1689005878.497 [Default:CheckIn:E] Stopped
2023-07-10T16:17:58.497Z,1689005878.497 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T16:17:58.497Z,1689005878.497 [Default:CheckIn] Stopped
2023-07-10T16:17:58.497Z,1689005878.497 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T16:17:58.498Z,1689005878.498 [Default:CheckIn](INFO): Running loop #9
2023-07-10T16:17:58.498Z,1689005878.498 [Default:CheckIn] Running Loop=9
2023-07-10T16:17:58.498Z,1689005878.498 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T16:17:58.498Z,1689005878.498 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T16:18:00.509Z,1689005880.509 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161759.00,A,3648.15012,N,12147.22079,W,0.039,145.48,100723,,,D*74
2023-07-10T16:18:00.511Z,1689005880.511 [NAL9602](INFO): GPS fix at 20230710T161759: (36.802502, -121.787013)
2023-07-10T16:18:00.544Z,1689005880.544 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T16:18:00.544Z,1689005880.544 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T16:18:07.469Z,1689005887.469 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230710T151042/Courier0031.lzma
2023-07-10T16:18:08.472Z,1689005888.471 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Courier0031.lzma.bak
2023-07-10T16:18:08.472Z,1689005888.472 [DataOverHttps](INFO): SBD MOMSN=18458529
2023-07-10T16:18:24.800Z,1689005904.800 [NAL9602](INFO): SBD MO Status=0, MOMSN=43908, MT Status=0, MTMSN=0
2023-07-10T16:18:24.800Z,1689005904.800 [NAL9602](INFO): No messages in MT queue
2023-07-10T16:18:27.385Z,1689005907.385 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20230710T151042/Express0032.lzma
2023-07-10T16:18:28.388Z,1689005908.388 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Express0032.lzma.bak
2023-07-10T16:18:28.388Z,1689005908.388 [DataOverHttps](INFO): SBD MOMSN=18458532
2023-07-10T16:18:29.665Z,1689005909.665 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T16:18:29.665Z,1689005909.665 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T16:18:29.665Z,1689005909.665 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T16:18:55.535Z,1689005935.535 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T16:23:15.716Z,1689006195.716 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-07-10T16:23:22.585Z,1689006202.585 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-07-10T16:23:30.271Z,1689006210.271 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T16:23:30.271Z,1689006210.271 [Default:CheckIn:C.Wait] Stopped
2023-07-10T16:23:30.271Z,1689006210.271 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T16:23:30.272Z,1689006210.272 [Default:CheckIn:D] Running Loop=1
2023-07-10T16:23:30.677Z,1689006210.677 [Default:CheckIn:D] Stopped
2023-07-10T16:23:30.677Z,1689006210.677 [Default:CheckIn:E] Running Loop=1
2023-07-10T16:23:31.093Z,1689006211.093 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 69.235661 min 
2023-07-10T16:23:31.093Z,1689006211.093 [Default:CheckIn:E] Stopped
2023-07-10T16:23:31.093Z,1689006211.093 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T16:23:31.093Z,1689006211.093 [Default:CheckIn] Stopped
2023-07-10T16:23:31.093Z,1689006211.093 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T16:23:31.094Z,1689006211.094 [Default:CheckIn](INFO): Running loop #10
2023-07-10T16:23:31.094Z,1689006211.094 [Default:CheckIn] Running Loop=10
2023-07-10T16:23:31.094Z,1689006211.094 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T16:23:31.094Z,1689006211.094 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T16:23:33.121Z,1689006213.121 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162332.00,A,3648.14962,N,12147.22074,W,0.058,145.48,100723,,,D*7B
2023-07-10T16:23:33.123Z,1689006213.123 [NAL9602](INFO): GPS fix at 20230710T162332: (36.802494, -121.787012)
2023-07-10T16:23:33.133Z,1689006213.133 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T16:23:33.133Z,1689006213.133 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T16:23:37.936Z,1689006217.936 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-07-10T16:23:40.797Z,1689006220.797 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230710T151042/Courier0034.lzma
2023-07-10T16:23:41.800Z,1689006221.800 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Courier0034.lzma.bak
2023-07-10T16:23:41.800Z,1689006221.800 [DataOverHttps](INFO): SBD MOMSN=18458543
2023-07-10T16:23:48.444Z,1689006228.444 [NAL9602](INFO): SBD MO Status=0, MOMSN=43909, MT Status=0, MTMSN=0
2023-07-10T16:23:48.445Z,1689006228.445 [NAL9602](INFO): No messages in MT queue
2023-07-10T16:23:53.289Z,1689006233.289 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-07-10T16:23:57.685Z,1689006237.685 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20230710T151042/Express0035.lzma
2023-07-10T16:23:58.688Z,1689006238.688 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Express0035.lzma.bak
2023-07-10T16:23:58.688Z,1689006238.688 [DataOverHttps](INFO): SBD MOMSN=18458546
2023-07-10T16:23:59.773Z,1689006239.773 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T16:23:59.773Z,1689006239.773 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T16:23:59.773Z,1689006239.773 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T16:24:08.238Z,1689006248.238 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-07-10T16:24:19.147Z,1689006259.147 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T16:24:24.400Z,1689006264.400 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-07-10T16:24:39.752Z,1689006279.752 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-07-10T16:24:55.508Z,1689006295.508 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-07-10T16:25:10.860Z,1689006310.860 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-07-10T16:25:25.810Z,1689006325.810 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-07-10T16:25:39.958Z,1689006339.958 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 5.
2023-07-10T16:25:39.961Z,1689006339.961 [BPC1](INFO): Received data from all battery sticks.
2023-07-10T16:29:00.363Z,1689006540.363 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T16:29:00.363Z,1689006540.363 [Default:CheckIn:C.Wait] Stopped
2023-07-10T16:29:00.363Z,1689006540.363 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T16:29:00.363Z,1689006540.363 [Default:CheckIn:D] Running Loop=1
2023-07-10T16:29:00.760Z,1689006540.760 [Default:CheckIn:D] Stopped
2023-07-10T16:29:00.760Z,1689006540.760 [Default:CheckIn:E] Running Loop=1
2023-07-10T16:29:01.166Z,1689006541.166 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 74.737052 min 
2023-07-10T16:29:01.166Z,1689006541.166 [Default:CheckIn:E] Stopped
2023-07-10T16:29:01.166Z,1689006541.166 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T16:29:01.166Z,1689006541.166 [Default:CheckIn] Stopped
2023-07-10T16:29:01.166Z,1689006541.166 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T16:29:01.166Z,1689006541.166 [Default:CheckIn](INFO): Running loop #11
2023-07-10T16:29:01.166Z,1689006541.166 [Default:CheckIn] Running Loop=11
2023-07-10T16:29:01.167Z,1689006541.167 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T16:29:01.167Z,1689006541.167 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T16:29:03.173Z,1689006543.173 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162902.00,A,3648.14907,N,12147.22188,W,0.019,145.48,100723,,,D*76
2023-07-10T16:29:03.175Z,1689006543.175 [NAL9602](INFO): GPS fix at 20230710T162902: (36.802484, -121.787031)
2023-07-10T16:29:03.189Z,1689006543.189 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T16:29:03.189Z,1689006543.189 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T16:29:10.194Z,1689006550.194 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20230710T151042/Courier0037.lzma
2023-07-10T16:29:11.196Z,1689006551.196 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Courier0037.lzma.bak
2023-07-10T16:29:11.196Z,1689006551.196 [DataOverHttps](INFO): SBD MOMSN=18458567
2023-07-10T16:29:26.945Z,1689006566.945 [DataOverHttps](INFO): Sending 127 bytes from file Logs/20230710T151042/Express0038.lzma
2023-07-10T16:29:27.948Z,1689006567.948 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Express0038.lzma.bak
2023-07-10T16:29:27.948Z,1689006567.948 [DataOverHttps](INFO): SBD MOMSN=18458570
2023-07-10T16:29:29.035Z,1689006569.035 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T16:29:29.035Z,1689006569.035 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T16:29:29.036Z,1689006569.036 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T16:29:39.947Z,1689006579.947 [NAL9602](INFO): SBD MO Status=2, MOMSN=43910, MT Status=2, MTMSN=0
2023-07-10T16:29:39.947Z,1689006579.947 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-07-10T16:29:50.079Z,1689006590.079 [NAL9602](INFO): SBD MO Status=0, MOMSN=43910, MT Status=0, MTMSN=0
2023-07-10T16:29:50.079Z,1689006590.079 [NAL9602](INFO): No messages in MT queue
2023-07-10T16:30:20.739Z,1689006620.739 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T16:34:29.614Z,1689006869.614 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T16:34:29.614Z,1689006869.614 [Default:CheckIn:C.Wait] Stopped
2023-07-10T16:34:29.615Z,1689006869.615 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T16:34:29.615Z,1689006869.615 [Default:CheckIn:D] Running Loop=1
2023-07-10T16:34:30.022Z,1689006870.022 [Default:CheckIn:D] Stopped
2023-07-10T16:34:30.022Z,1689006870.022 [Default:CheckIn:E] Running Loop=1
2023-07-10T16:34:30.433Z,1689006870.433 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 80.224756 min 
2023-07-10T16:34:30.433Z,1689006870.433 [Default:CheckIn:E] Stopped
2023-07-10T16:34:30.433Z,1689006870.433 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T16:34:30.433Z,1689006870.433 [Default:CheckIn] Stopped
2023-07-10T16:34:30.433Z,1689006870.433 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T16:34:30.434Z,1689006870.434 [Default:CheckIn](INFO): Running loop #12
2023-07-10T16:34:30.434Z,1689006870.434 [Default:CheckIn] Running Loop=12
2023-07-10T16:34:30.434Z,1689006870.434 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T16:34:30.434Z,1689006870.434 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T16:34:32.437Z,1689006872.437 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163431.00,A,3648.14834,N,12147.22258,W,0.039,0.00,100723,,,D*7B
2023-07-10T16:34:32.439Z,1689006872.439 [NAL9602](INFO): GPS fix at 20230710T163431: (36.802472, -121.787043)
2023-07-10T16:34:32.450Z,1689006872.450 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T16:34:32.450Z,1689006872.450 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T16:34:39.865Z,1689006879.865 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230710T151042/Courier0040.lzma
2023-07-10T16:34:40.868Z,1689006880.868 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Courier0040.lzma.bak
2023-07-10T16:34:40.868Z,1689006880.868 [DataOverHttps](INFO): SBD MOMSN=18458581
2023-07-10T16:34:54.701Z,1689006894.701 [NAL9602](INFO): SBD MO Status=0, MOMSN=43911, MT Status=0, MTMSN=0
2023-07-10T16:34:54.701Z,1689006894.701 [NAL9602](INFO): No messages in MT queue
2023-07-10T16:34:59.725Z,1689006899.725 [DataOverHttps](INFO): Sending 127 bytes from file Logs/20230710T151042/Express0041.lzma
2023-07-10T16:35:00.728Z,1689006900.728 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Express0041.lzma.bak
2023-07-10T16:35:00.728Z,1689006900.728 [DataOverHttps](INFO): SBD MOMSN=18458584
2023-07-10T16:35:01.984Z,1689006901.984 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T16:35:01.984Z,1689006901.984 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T16:35:01.984Z,1689006901.984 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T16:35:25.401Z,1689006925.401 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T16:40:02.592Z,1689007202.592 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T16:40:02.592Z,1689007202.592 [Default:CheckIn:C.Wait] Stopped
2023-07-10T16:40:02.592Z,1689007202.592 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T16:40:02.592Z,1689007202.592 [Default:CheckIn:D] Running Loop=1
2023-07-10T16:40:02.994Z,1689007202.994 [Default:CheckIn:D] Stopped
2023-07-10T16:40:02.994Z,1689007202.994 [Default:CheckIn:E] Running Loop=1
2023-07-10T16:40:03.397Z,1689007203.397 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 85.774292 min 
2023-07-10T16:40:03.397Z,1689007203.397 [Default:CheckIn:E] Stopped
2023-07-10T16:40:03.397Z,1689007203.397 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T16:40:03.397Z,1689007203.397 [Default:CheckIn] Stopped
2023-07-10T16:40:03.397Z,1689007203.397 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T16:40:03.398Z,1689007203.398 [Default:CheckIn](INFO): Running loop #13
2023-07-10T16:40:03.398Z,1689007203.398 [Default:CheckIn] Running Loop=13
2023-07-10T16:40:03.398Z,1689007203.398 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T16:40:03.398Z,1689007203.398 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T16:40:05.412Z,1689007205.412 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164004.00,A,3648.14848,N,12147.22285,W,0.097,0.00,100723,,,D*71
2023-07-10T16:40:05.425Z,1689007205.425 [NAL9602](INFO): GPS fix at 20230710T164004: (36.802475, -121.787047)
2023-07-10T16:40:05.435Z,1689007205.435 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T16:40:05.435Z,1689007205.435 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T16:40:12.777Z,1689007212.777 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230710T151042/Courier0043.lzma
2023-07-10T16:40:13.780Z,1689007213.780 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Courier0043.lzma.bak
2023-07-10T16:40:13.780Z,1689007213.780 [DataOverHttps](INFO): SBD MOMSN=18458604
2023-07-10T16:40:28.076Z,1689007228.076 [NAL9602](INFO): SBD MO Status=0, MOMSN=43912, MT Status=0, MTMSN=0
2023-07-10T16:40:28.076Z,1689007228.076 [NAL9602](INFO): No messages in MT queue
2023-07-10T16:40:32.621Z,1689007232.621 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20230710T151042/Express0044.lzma
2023-07-10T16:40:33.624Z,1689007233.624 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Express0044.lzma.bak
2023-07-10T16:40:33.624Z,1689007233.624 [DataOverHttps](INFO): SBD MOMSN=18458607
2023-07-10T16:40:34.951Z,1689007234.951 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T16:40:34.951Z,1689007234.951 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T16:40:34.951Z,1689007234.951 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T16:40:58.778Z,1689007258.778 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T16:45:35.530Z,1689007535.530 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T16:45:35.530Z,1689007535.530 [Default:CheckIn:C.Wait] Stopped
2023-07-10T16:45:35.530Z,1689007535.530 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T16:45:35.530Z,1689007535.530 [Default:CheckIn:D] Running Loop=1
2023-07-10T16:45:35.951Z,1689007535.951 [Default:CheckIn:D] Stopped
2023-07-10T16:45:35.951Z,1689007535.951 [Default:CheckIn:E] Running Loop=1
2023-07-10T16:45:36.337Z,1689007536.337 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 91.323560 min 
2023-07-10T16:45:36.337Z,1689007536.337 [Default:CheckIn:E] Stopped
2023-07-10T16:45:36.337Z,1689007536.337 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T16:45:36.337Z,1689007536.337 [Default:CheckIn] Stopped
2023-07-10T16:45:36.337Z,1689007536.337 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T16:45:36.337Z,1689007536.337 [Default:CheckIn](INFO): Running loop #14
2023-07-10T16:45:36.337Z,1689007536.337 [Default:CheckIn] Running Loop=14
2023-07-10T16:45:36.338Z,1689007536.338 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T16:45:36.338Z,1689007536.338 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T16:45:38.357Z,1689007538.357 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164537.00,A,3648.15014,N,12147.22029,W,0.039,0.00,100723,,,D*74
2023-07-10T16:45:38.359Z,1689007538.359 [NAL9602](INFO): GPS fix at 20230710T164537: (36.802502, -121.787005)
2023-07-10T16:45:38.369Z,1689007538.369 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T16:45:38.369Z,1689007538.369 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T16:45:45.485Z,1689007545.485 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230710T151042/Courier0046.lzma
2023-07-10T16:45:46.488Z,1689007546.488 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Courier0046.lzma.bak
2023-07-10T16:45:46.488Z,1689007546.488 [DataOverHttps](INFO): SBD MOMSN=18458618
2023-07-10T16:46:02.133Z,1689007562.133 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230710T151042/Express0047.lzma
2023-07-10T16:46:03.136Z,1689007563.136 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Express0047.lzma.bak
2023-07-10T16:46:03.136Z,1689007563.136 [DataOverHttps](INFO): SBD MOMSN=18458621
2023-07-10T16:46:04.212Z,1689007564.212 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T16:46:04.212Z,1689007564.212 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T16:46:04.212Z,1689007564.212 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T16:46:14.308Z,1689007574.308 [NAL9602](INFO): SBD MO Status=0, MOMSN=43913, MT Status=0, MTMSN=0
2023-07-10T16:46:14.308Z,1689007574.308 [NAL9602](INFO): No messages in MT queue
2023-07-10T16:46:45.007Z,1689007605.007 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T16:51:04.787Z,1689007864.787 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T16:51:04.787Z,1689007864.787 [Default:CheckIn:C.Wait] Stopped
2023-07-10T16:51:04.787Z,1689007864.787 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T16:51:04.788Z,1689007864.788 [Default:CheckIn:D] Running Loop=1
2023-07-10T16:51:05.193Z,1689007865.193 [Default:CheckIn:D] Stopped
2023-07-10T16:51:05.193Z,1689007865.193 [Default:CheckIn:E] Running Loop=1
2023-07-10T16:51:05.599Z,1689007865.599 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 96.810929 min 
2023-07-10T16:51:05.599Z,1689007865.599 [Default:CheckIn:E] Stopped
2023-07-10T16:51:05.599Z,1689007865.599 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T16:51:05.599Z,1689007865.599 [Default:CheckIn] Stopped
2023-07-10T16:51:05.599Z,1689007865.599 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T16:51:05.599Z,1689007865.599 [Default:CheckIn](INFO): Running loop #15
2023-07-10T16:51:05.599Z,1689007865.599 [Default:CheckIn] Running Loop=15
2023-07-10T16:51:05.599Z,1689007865.599 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T16:51:05.599Z,1689007865.599 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T16:51:07.616Z,1689007867.616 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165106.00,A,3648.14944,N,12147.22040,W,0.039,0.00,100723,,,D*71
2023-07-10T16:51:07.618Z,1689007867.618 [NAL9602](INFO): GPS fix at 20230710T165106: (36.802491, -121.787007)
2023-07-10T16:51:07.628Z,1689007867.628 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T16:51:07.628Z,1689007867.628 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T16:51:14.853Z,1689007874.853 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230710T151042/Courier0049.lzma
2023-07-10T16:51:15.856Z,1689007875.856 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Courier0049.lzma.bak
2023-07-10T16:51:15.856Z,1689007875.856 [DataOverHttps](INFO): SBD MOMSN=18458633
2023-07-10T16:51:26.193Z,1689007886.193 [NAL9602](INFO): SBD MO Status=0, MOMSN=43914, MT Status=0, MTMSN=0
2023-07-10T16:51:26.193Z,1689007886.193 [NAL9602](INFO): No messages in MT queue
2023-07-10T16:51:31.773Z,1689007891.773 [DataOverHttps](INFO): Sending 127 bytes from file Logs/20230710T151042/Express0050.lzma
2023-07-10T16:51:32.776Z,1689007892.776 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Express0050.lzma.bak
2023-07-10T16:51:32.776Z,1689007892.776 [DataOverHttps](INFO): SBD MOMSN=18458636
2023-07-10T16:51:33.877Z,1689007893.877 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T16:51:33.877Z,1689007893.877 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T16:51:33.877Z,1689007893.877 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T16:51:56.896Z,1689007916.896 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T16:56:34.466Z,1689008194.466 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T16:56:34.466Z,1689008194.466 [Default:CheckIn:C.Wait] Stopped
2023-07-10T16:56:34.466Z,1689008194.466 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T16:56:34.466Z,1689008194.466 [Default:CheckIn:D] Running Loop=1
2023-07-10T16:56:34.863Z,1689008194.863 [Default:CheckIn:D] Stopped
2023-07-10T16:56:34.863Z,1689008194.863 [Default:CheckIn:E] Running Loop=1
2023-07-10T16:56:35.265Z,1689008195.265 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 102.305428 min 
2023-07-10T16:56:35.265Z,1689008195.265 [Default:CheckIn:E] Stopped
2023-07-10T16:56:35.265Z,1689008195.265 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T16:56:35.265Z,1689008195.265 [Default:CheckIn] Stopped
2023-07-10T16:56:35.265Z,1689008195.265 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T16:56:35.265Z,1689008195.265 [Default:CheckIn](INFO): Running loop #16
2023-07-10T16:56:35.265Z,1689008195.265 [Default:CheckIn] Running Loop=16
2023-07-10T16:56:35.265Z,1689008195.265 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T16:56:35.265Z,1689008195.265 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T16:56:37.273Z,1689008197.273 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165636.00,A,3648.14913,N,12147.21994,W,0.019,0.00,100723,,,D*76
2023-07-10T16:56:37.275Z,1689008197.275 [NAL9602](INFO): GPS fix at 20230710T165636: (36.802486, -121.786999)
2023-07-10T16:56:37.309Z,1689008197.309 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T16:56:37.309Z,1689008197.309 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T16:56:44.893Z,1689008204.893 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230710T151042/Courier0052.lzma
2023-07-10T16:56:45.896Z,1689008205.896 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Courier0052.lzma.bak
2023-07-10T16:56:45.896Z,1689008205.896 [DataOverHttps](INFO): SBD MOMSN=18458647
2023-07-10T16:56:59.956Z,1689008219.956 [NAL9602](INFO): SBD MO Status=2, MOMSN=43915, MT Status=2, MTMSN=0
2023-07-10T16:56:59.956Z,1689008219.956 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-07-10T16:57:04.761Z,1689008224.761 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20230710T151042/Express0053.lzma
2023-07-10T16:57:05.764Z,1689008225.764 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Express0053.lzma.bak
2023-07-10T16:57:05.764Z,1689008225.764 [DataOverHttps](INFO): SBD MOMSN=18458650
2023-07-10T16:57:06.841Z,1689008226.841 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T16:57:06.841Z,1689008226.841 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T16:57:06.841Z,1689008226.841 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T16:57:17.328Z,1689008237.328 [NAL9602](INFO): SBD MO Status=2, MOMSN=43915, MT Status=2, MTMSN=0
2023-07-10T16:57:17.328Z,1689008237.328 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-07-10T16:57:53.685Z,1689008273.685 [NAL9602](INFO): SBD MO Status=0, MOMSN=43915, MT Status=0, MTMSN=0
2023-07-10T16:57:53.685Z,1689008273.685 [NAL9602](INFO): No messages in MT queue
2023-07-10T16:58:24.387Z,1689008304.387 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T17:02:07.419Z,1689008527.419 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T17:02:07.419Z,1689008527.419 [Default:CheckIn:C.Wait] Stopped
2023-07-10T17:02:07.419Z,1689008527.419 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T17:02:07.420Z,1689008527.420 [Default:CheckIn:D] Running Loop=1
2023-07-10T17:02:07.807Z,1689008527.807 [Default:CheckIn:D] Stopped
2023-07-10T17:02:07.807Z,1689008527.807 [Default:CheckIn:E] Running Loop=1
2023-07-10T17:02:08.217Z,1689008528.217 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 107.854500 min 
2023-07-10T17:02:08.217Z,1689008528.217 [Default:CheckIn:E] Stopped
2023-07-10T17:02:08.217Z,1689008528.217 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T17:02:08.217Z,1689008528.217 [Default:CheckIn] Stopped
2023-07-10T17:02:08.217Z,1689008528.217 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T17:02:08.218Z,1689008528.218 [Default:CheckIn](INFO): Running loop #17
2023-07-10T17:02:08.218Z,1689008528.218 [Default:CheckIn] Running Loop=17
2023-07-10T17:02:08.218Z,1689008528.218 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T17:02:08.218Z,1689008528.218 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T17:02:10.225Z,1689008530.225 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170209.00,A,3648.15014,N,12147.21869,W,0.136,0.00,100723,,,D*7A
2023-07-10T17:02:10.227Z,1689008530.227 [NAL9602](INFO): GPS fix at 20230710T170209: (36.802502, -121.786978)
2023-07-10T17:02:10.258Z,1689008530.258 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T17:02:10.258Z,1689008530.258 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T17:02:18.265Z,1689008538.265 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230710T151042/Courier0055.lzma
2023-07-10T17:02:19.268Z,1689008539.268 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Courier0055.lzma.bak
2023-07-10T17:02:19.268Z,1689008539.268 [DataOverHttps](INFO): SBD MOMSN=18458661
2023-07-10T17:02:21.941Z,1689008541.941 [NAL9602](INFO): SBD MO Status=0, MOMSN=43916, MT Status=0, MTMSN=0
2023-07-10T17:02:21.941Z,1689008541.941 [NAL9602](INFO): No messages in MT queue
2023-07-10T17:02:37.657Z,1689008557.657 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20230710T151042/Express0056.lzma
2023-07-10T17:02:38.660Z,1689008558.660 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Express0056.lzma.bak
2023-07-10T17:02:38.660Z,1689008558.660 [DataOverHttps](INFO): SBD MOMSN=18458664
2023-07-10T17:02:39.760Z,1689008559.760 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T17:02:39.761Z,1689008559.761 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T17:02:39.761Z,1689008559.761 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T17:02:52.676Z,1689008572.676 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T17:07:40.373Z,1689008860.373 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T17:07:40.373Z,1689008860.373 [Default:CheckIn:C.Wait] Stopped
2023-07-10T17:07:40.373Z,1689008860.373 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T17:07:40.373Z,1689008860.373 [Default:CheckIn:D] Running Loop=1
2023-07-10T17:07:40.793Z,1689008860.793 [Default:CheckIn:D] Stopped
2023-07-10T17:07:40.793Z,1689008860.793 [Default:CheckIn:E] Running Loop=1
2023-07-10T17:07:41.184Z,1689008861.184 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 113.404256 min 
2023-07-10T17:07:41.184Z,1689008861.184 [Default:CheckIn:E] Stopped
2023-07-10T17:07:41.184Z,1689008861.184 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T17:07:41.184Z,1689008861.184 [Default:CheckIn] Stopped
2023-07-10T17:07:41.184Z,1689008861.184 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T17:07:41.184Z,1689008861.184 [Default:CheckIn](INFO): Running loop #18
2023-07-10T17:07:41.184Z,1689008861.184 [Default:CheckIn] Running Loop=18
2023-07-10T17:07:41.184Z,1689008861.184 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T17:07:41.184Z,1689008861.184 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T17:07:43.226Z,1689008863.226 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170742.00,A,3648.15026,N,12147.22054,W,0.019,0.00,100723,,,D*78
2023-07-10T17:07:43.228Z,1689008863.228 [NAL9602](INFO): GPS fix at 20230710T170742: (36.802504, -121.787009)
2023-07-10T17:07:43.238Z,1689008863.238 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T17:07:43.238Z,1689008863.238 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T17:07:50.817Z,1689008870.817 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230710T151042/Courier0058.lzma
2023-07-10T17:07:51.820Z,1689008871.820 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Courier0058.lzma.bak
2023-07-10T17:07:51.820Z,1689008871.820 [DataOverHttps](INFO): SBD MOMSN=18458675
2023-07-10T17:07:58.952Z,1689008878.952 [NAL9602](INFO): SBD MO Status=0, MOMSN=43917, MT Status=0, MTMSN=0
2023-07-10T17:07:58.953Z,1689008878.953 [NAL9602](INFO): No messages in MT queue
2023-07-10T17:08:07.505Z,1689008887.505 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230710T151042/Express0059.lzma
2023-07-10T17:08:08.508Z,1689008888.508 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Express0059.lzma.bak
2023-07-10T17:08:08.508Z,1689008888.508 [DataOverHttps](INFO): SBD MOMSN=18458678
2023-07-10T17:08:09.868Z,1689008889.868 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T17:08:09.868Z,1689008889.868 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T17:08:09.868Z,1689008889.868 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T17:08:29.655Z,1689008909.655 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T17:13:10.512Z,1689009190.512 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T17:13:10.512Z,1689009190.512 [Default:CheckIn:C.Wait] Stopped
2023-07-10T17:13:10.512Z,1689009190.512 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T17:13:10.513Z,1689009190.513 [Default:CheckIn:D] Running Loop=1
2023-07-10T17:13:10.928Z,1689009190.928 [Default:CheckIn:D] Stopped
2023-07-10T17:13:10.928Z,1689009190.928 [Default:CheckIn:E] Running Loop=1
2023-07-10T17:13:11.307Z,1689009191.307 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 118.906510 min 
2023-07-10T17:13:11.308Z,1689009191.308 [Default:CheckIn:E] Stopped
2023-07-10T17:13:11.308Z,1689009191.308 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T17:13:11.308Z,1689009191.308 [Default:CheckIn] Stopped
2023-07-10T17:13:11.308Z,1689009191.308 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T17:13:11.309Z,1689009191.309 [Default:CheckIn](INFO): Running loop #19
2023-07-10T17:13:11.309Z,1689009191.309 [Default:CheckIn] Running Loop=19
2023-07-10T17:13:11.309Z,1689009191.309 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T17:13:11.309Z,1689009191.309 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T17:13:13.328Z,1689009193.328 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171312.00,A,3648.14999,N,12147.22074,W,0.039,0.00,100723,,,D*74
2023-07-10T17:13:13.331Z,1689009193.331 [NAL9602](INFO): GPS fix at 20230710T171312: (36.802500, -121.787012)
2023-07-10T17:13:13.341Z,1689009193.341 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T17:13:13.341Z,1689009193.341 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T17:13:20.913Z,1689009200.913 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20230710T151042/Courier0061.lzma
2023-07-10T17:13:21.916Z,1689009201.916 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Courier0061.lzma.bak
2023-07-10T17:13:21.916Z,1689009201.916 [DataOverHttps](INFO): SBD MOMSN=18458689
2023-07-10T17:13:37.657Z,1689009217.657 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20230710T151042/Express0062.lzma
2023-07-10T17:13:38.660Z,1689009218.660 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Express0062.lzma.bak
2023-07-10T17:13:38.660Z,1689009218.660 [DataOverHttps](INFO): SBD MOMSN=18458692
2023-07-10T17:13:39.994Z,1689009219.994 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T17:13:39.994Z,1689009219.994 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T17:13:39.994Z,1689009219.994 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T17:14:16.748Z,1689009256.748 [NAL9602](INFO): SBD MO Status=2, MOMSN=43918, MT Status=2, MTMSN=0
2023-07-10T17:14:16.748Z,1689009256.748 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-07-10T17:14:28.066Z,1689009268.066 [NAL9602](INFO): SBD MO Status=0, MOMSN=43918, MT Status=0, MTMSN=0
2023-07-10T17:14:28.066Z,1689009268.066 [NAL9602](INFO): No messages in MT queue
2023-07-10T17:14:58.763Z,1689009298.763 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T17:18:40.578Z,1689009520.578 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T17:18:40.578Z,1689009520.578 [Default:CheckIn:C.Wait] Stopped
2023-07-10T17:18:40.578Z,1689009520.578 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T17:18:40.578Z,1689009520.578 [Default:CheckIn:D] Running Loop=1
2023-07-10T17:18:40.995Z,1689009520.995 [Default:CheckIn:D] Stopped
2023-07-10T17:18:40.995Z,1689009520.995 [Default:CheckIn:E] Running Loop=1
2023-07-10T17:18:41.390Z,1689009521.390 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 124.407633 min 
2023-07-10T17:18:41.391Z,1689009521.391 [Default:CheckIn:E] Stopped
2023-07-10T17:18:41.391Z,1689009521.391 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T17:18:41.391Z,1689009521.391 [Default:CheckIn] Stopped
2023-07-10T17:18:41.391Z,1689009521.391 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T17:18:41.391Z,1689009521.391 [Default:CheckIn](INFO): Running loop #20
2023-07-10T17:18:41.391Z,1689009521.391 [Default:CheckIn] Running Loop=20
2023-07-10T17:18:41.391Z,1689009521.391 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T17:18:41.391Z,1689009521.391 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T17:18:43.397Z,1689009523.397 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171842.00,A,3648.19686,N,12147.16032,W,5.190,43.45,100723,,,D*42
2023-07-10T17:18:43.399Z,1689009523.399 [NAL9602](INFO): GPS fix at 20230710T171842: (36.803281, -121.786005)
2023-07-10T17:18:43.410Z,1689009523.410 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T17:18:43.410Z,1689009523.410 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T17:18:53.129Z,1689009533.129 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230710T151042/Courier0064.lzma
2023-07-10T17:18:54.132Z,1689009534.132 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Courier0064.lzma.bak
2023-07-10T17:18:54.132Z,1689009534.132 [DataOverHttps](INFO): SBD MOMSN=18458703
2023-07-10T17:19:08.072Z,1689009548.072 [NAL9602](INFO): SBD MO Status=0, MOMSN=43919, MT Status=0, MTMSN=0
2023-07-10T17:19:08.073Z,1689009548.073 [NAL9602](INFO): No messages in MT queue
2023-07-10T17:19:10.465Z,1689009550.465 [DataOverHttps](INFO): Sending 143 bytes from file Logs/20230710T151042/Express0065.lzma
2023-07-10T17:19:11.468Z,1689009551.468 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Express0065.lzma.bak
2023-07-10T17:19:11.468Z,1689009551.468 [DataOverHttps](INFO): SBD MOMSN=18458706
2023-07-10T17:19:12.526Z,1689009552.526 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T17:19:12.526Z,1689009552.526 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T17:19:12.526Z,1689009552.526 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T17:19:38.779Z,1689009578.779 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T17:24:13.288Z,1689009853.288 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T17:24:13.288Z,1689009853.288 [Default:CheckIn:C.Wait] Stopped
2023-07-10T17:24:13.288Z,1689009853.288 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T17:24:13.288Z,1689009853.288 [Default:CheckIn:D] Running Loop=1
2023-07-10T17:24:13.684Z,1689009853.684 [Default:CheckIn:D] Stopped
2023-07-10T17:24:13.684Z,1689009853.684 [Default:CheckIn:E] Running Loop=1
2023-07-10T17:24:14.109Z,1689009854.109 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 129.952450 min 
2023-07-10T17:24:14.109Z,1689009854.109 [Default:CheckIn:E] Stopped
2023-07-10T17:24:14.109Z,1689009854.109 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T17:24:14.110Z,1689009854.110 [Default:CheckIn] Stopped
2023-07-10T17:24:14.110Z,1689009854.110 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T17:24:14.110Z,1689009854.110 [Default:CheckIn](INFO): Running loop #21
2023-07-10T17:24:14.110Z,1689009854.110 [Default:CheckIn] Running Loop=21
2023-07-10T17:24:14.110Z,1689009854.110 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T17:24:14.110Z,1689009854.110 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T17:24:16.106Z,1689009856.106 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172415.00,A,3648.51528,N,12147.11770,W,3.499,314.58,100723,,,D*75
2023-07-10T17:24:16.108Z,1689009856.108 [NAL9602](INFO): GPS fix at 20230710T172415: (36.808588, -121.785295)
2023-07-10T17:24:16.120Z,1689009856.120 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T17:24:16.120Z,1689009856.120 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T17:24:26.249Z,1689009866.249 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230710T151042/Courier0067.lzma
2023-07-10T17:24:27.252Z,1689009867.252 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Courier0067.lzma.bak
2023-07-10T17:24:27.252Z,1689009867.252 [DataOverHttps](INFO): SBD MOMSN=18458721
2023-07-10T17:24:39.984Z,1689009879.984 [NAL9602](INFO): SBD MO Status=0, MOMSN=43920, MT Status=0, MTMSN=0
2023-07-10T17:24:39.984Z,1689009879.984 [NAL9602](INFO): No messages in MT queue
2023-07-10T17:24:42.933Z,1689009882.933 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20230710T151042/Express0068.lzma
2023-07-10T17:24:43.936Z,1689009883.936 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Express0068.lzma.bak
2023-07-10T17:24:43.936Z,1689009883.936 [DataOverHttps](INFO): SBD MOMSN=18458725
2023-07-10T17:24:45.264Z,1689009885.264 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T17:24:45.264Z,1689009885.264 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T17:24:45.264Z,1689009885.264 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T17:25:10.727Z,1689009910.727 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T17:29:45.818Z,1689010185.818 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T17:29:45.818Z,1689010185.818 [Default:CheckIn:C.Wait] Stopped
2023-07-10T17:29:45.818Z,1689010185.818 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T17:29:45.818Z,1689010185.818 [Default:CheckIn:D] Running Loop=1
2023-07-10T17:29:46.211Z,1689010186.211 [Default:CheckIn:D] Stopped
2023-07-10T17:29:46.211Z,1689010186.211 [Default:CheckIn:E] Running Loop=1
2023-07-10T17:29:46.612Z,1689010186.612 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 135.494564 min 
2023-07-10T17:29:46.613Z,1689010186.613 [Default:CheckIn:E] Stopped
2023-07-10T17:29:46.613Z,1689010186.613 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T17:29:46.613Z,1689010186.613 [Default:CheckIn] Stopped
2023-07-10T17:29:46.613Z,1689010186.613 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T17:29:46.613Z,1689010186.613 [Default:CheckIn](INFO): Running loop #22
2023-07-10T17:29:46.613Z,1689010186.613 [Default:CheckIn] Running Loop=22
2023-07-10T17:29:46.613Z,1689010186.613 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T17:29:46.613Z,1689010186.613 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T17:29:48.621Z,1689010188.621 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172947.00,A,3648.31902,N,12147.75720,W,9.972,268.06,100723,,,D*79
2023-07-10T17:29:48.624Z,1689010188.624 [NAL9602](INFO): GPS fix at 20230710T172947: (36.805317, -121.795953)
2023-07-10T17:29:48.634Z,1689010188.634 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T17:29:48.634Z,1689010188.634 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T17:29:56.201Z,1689010196.201 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230710T151042/Courier0070.lzma
2023-07-10T17:29:57.204Z,1689010197.204 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Courier0070.lzma.bak
2023-07-10T17:29:57.204Z,1689010197.204 [DataOverHttps](INFO): SBD MOMSN=18458738
2023-07-10T17:30:08.420Z,1689010208.420 [NAL9602](INFO): SBD MO Status=0, MOMSN=43921, MT Status=0, MTMSN=0
2023-07-10T17:30:08.420Z,1689010208.420 [NAL9602](INFO): No messages in MT queue
2023-07-10T17:30:13.177Z,1689010213.177 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20230710T151042/Express0071.lzma
2023-07-10T17:30:14.180Z,1689010214.180 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Express0071.lzma.bak
2023-07-10T17:30:14.180Z,1689010214.180 [DataOverHttps](INFO): SBD MOMSN=18458741
2023-07-10T17:30:15.296Z,1689010215.296 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T17:30:15.296Z,1689010215.296 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T17:30:15.296Z,1689010215.296 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T17:30:37.741Z,1689010237.741 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-07-10T17:30:38.954Z,1689010238.954 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T17:30:53.128Z,1689010253.128 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-07-10T17:31:09.299Z,1689010269.299 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-07-10T17:31:24.649Z,1689010284.649 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-07-10T17:31:40.000Z,1689010300.000 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-07-10T17:31:54.948Z,1689010314.948 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-07-10T17:32:09.899Z,1689010329.899 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-07-10T17:32:24.910Z,1689010344.910 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-07-10T17:32:40.258Z,1689010360.258 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-07-10T17:32:55.228Z,1689010375.228 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2023-07-10T17:33:01.696Z,1689010381.696 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 5.
2023-07-10T17:33:01.699Z,1689010381.699 [BPC1](INFO): Received data from all battery sticks.
2023-07-10T17:35:15.925Z,1689010515.925 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T17:35:15.925Z,1689010515.925 [Default:CheckIn:C.Wait] Stopped
2023-07-10T17:35:15.925Z,1689010515.925 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T17:35:15.925Z,1689010515.925 [Default:CheckIn:D] Running Loop=1
2023-07-10T17:35:16.341Z,1689010516.341 [Default:CheckIn:D] Stopped
2023-07-10T17:35:16.341Z,1689010516.341 [Default:CheckIn:E] Running Loop=1
2023-07-10T17:35:16.735Z,1689010516.735 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 140.996729 min 
2023-07-10T17:35:16.735Z,1689010516.735 [Default:CheckIn:E] Stopped
2023-07-10T17:35:16.735Z,1689010516.735 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T17:35:16.735Z,1689010516.735 [Default:CheckIn] Stopped
2023-07-10T17:35:16.735Z,1689010516.735 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T17:35:16.735Z,1689010516.735 [Default:CheckIn](INFO): Running loop #23
2023-07-10T17:35:16.735Z,1689010516.735 [Default:CheckIn] Running Loop=23
2023-07-10T17:35:16.735Z,1689010516.735 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T17:35:16.735Z,1689010516.735 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T17:35:18.749Z,1689010518.749 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173518.00,A,3648.02368,N,12149.25965,W,13.024,256.74,100723,,,D*45
2023-07-10T17:35:18.751Z,1689010518.751 [NAL9602](INFO): GPS fix at 20230710T173518: (36.800395, -121.820994)
2023-07-10T17:35:18.761Z,1689010518.761 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T17:35:18.761Z,1689010518.761 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T17:35:26.661Z,1689010526.661 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20230710T151042/Courier0073.lzma
2023-07-10T17:35:27.664Z,1689010527.664 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Courier0073.lzma.bak
2023-07-10T17:35:27.664Z,1689010527.664 [DataOverHttps](INFO): SBD MOMSN=18458754
2023-07-10T17:35:43.429Z,1689010543.429 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20230710T151042/Express0074.lzma
2023-07-10T17:35:43.791Z,1689010543.791 [NAL9602](INFO): SBD MO Status=0, MOMSN=43922, MT Status=0, MTMSN=0
2023-07-10T17:35:43.791Z,1689010543.791 [NAL9602](INFO): No messages in MT queue
2023-07-10T17:35:44.432Z,1689010544.432 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Express0074.lzma.bak
2023-07-10T17:35:44.432Z,1689010544.432 [DataOverHttps](INFO): SBD MOMSN=18458757
2023-07-10T17:35:45.824Z,1689010545.824 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T17:35:45.824Z,1689010545.824 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T17:35:45.824Z,1689010545.824 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T17:36:14.495Z,1689010574.495 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T17:40:46.273Z,1689010846.273 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T17:40:46.273Z,1689010846.273 [Default:CheckIn:C.Wait] Stopped
2023-07-10T17:40:46.273Z,1689010846.273 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T17:40:46.274Z,1689010846.274 [Default:CheckIn:D] Running Loop=1
2023-07-10T17:40:46.717Z,1689010846.717 [Default:CheckIn:D] Stopped
2023-07-10T17:40:46.717Z,1689010846.717 [Default:CheckIn:E] Running Loop=1
2023-07-10T17:40:47.114Z,1689010847.114 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 146.503011 min 
2023-07-10T17:40:47.114Z,1689010847.114 [Default:CheckIn:E] Stopped
2023-07-10T17:40:47.114Z,1689010847.114 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T17:40:47.114Z,1689010847.114 [Default:CheckIn] Stopped
2023-07-10T17:40:47.114Z,1689010847.114 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T17:40:47.114Z,1689010847.114 [Default:CheckIn](INFO): Running loop #24
2023-07-10T17:40:47.115Z,1689010847.115 [Default:CheckIn] Running Loop=24
2023-07-10T17:40:47.115Z,1689010847.115 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T17:40:47.115Z,1689010847.115 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T17:40:49.085Z,1689010849.085 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174048.00,A,3647.72448,N,12151.03134,W,16.698,246.15,100723,,,D*4C
2023-07-10T17:40:49.087Z,1689010849.087 [NAL9602](INFO): GPS fix at 20230710T174048: (36.795408, -121.850522)
2023-07-10T17:40:49.138Z,1689010849.138 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T17:40:49.138Z,1689010849.138 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T17:40:59.193Z,1689010859.193 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230710T151042/Courier0076.lzma
2023-07-10T17:41:00.196Z,1689010860.196 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Courier0076.lzma.bak
2023-07-10T17:41:00.196Z,1689010860.196 [DataOverHttps](INFO): SBD MOMSN=18458770
2023-07-10T17:41:06.513Z,1689010866.513 [NAL9602](INFO): SBD MO Status=0, MOMSN=43923, MT Status=0, MTMSN=0
2023-07-10T17:41:06.513Z,1689010866.513 [NAL9602](INFO): No messages in MT queue
2023-07-10T17:41:16.101Z,1689010876.101 [DataOverHttps](INFO): Sending 145 bytes from file Logs/20230710T151042/Express0077.lzma
2023-07-10T17:41:17.104Z,1689010877.104 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Express0077.lzma.bak
2023-07-10T17:41:17.104Z,1689010877.104 [DataOverHttps](INFO): SBD MOMSN=18458773
2023-07-10T17:41:18.240Z,1689010878.240 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T17:41:18.240Z,1689010878.240 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T17:41:18.240Z,1689010878.240 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T17:41:37.219Z,1689010897.219 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T17:46:19.022Z,1689011179.022 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T17:46:19.022Z,1689011179.022 [Default:CheckIn:C.Wait] Stopped
2023-07-10T17:46:19.022Z,1689011179.022 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T17:46:19.022Z,1689011179.022 [Default:CheckIn:D] Running Loop=1
2023-07-10T17:46:19.432Z,1689011179.432 [Default:CheckIn:D] Stopped
2023-07-10T17:46:19.432Z,1689011179.432 [Default:CheckIn:E] Running Loop=1
2023-07-10T17:46:19.855Z,1689011179.855 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 152.048242 min 
2023-07-10T17:46:19.855Z,1689011179.855 [Default:CheckIn:E] Stopped
2023-07-10T17:46:19.856Z,1689011179.856 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T17:46:19.856Z,1689011179.856 [Default:CheckIn] Stopped
2023-07-10T17:46:19.856Z,1689011179.856 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T17:46:19.856Z,1689011179.856 [Default:CheckIn](INFO): Running loop #25
2023-07-10T17:46:19.856Z,1689011179.856 [Default:CheckIn] Running Loop=25
2023-07-10T17:46:19.856Z,1689011179.856 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T17:46:19.856Z,1689011179.856 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T17:46:21.832Z,1689011181.832 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174621.00,A,3647.54559,N,12152.89238,W,16.717,268.03,100723,,,D*43
2023-07-10T17:46:21.834Z,1689011181.834 [NAL9602](INFO): GPS fix at 20230710T174621: (36.792426, -121.881540)
2023-07-10T17:46:21.846Z,1689011181.846 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T17:46:21.846Z,1689011181.846 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T17:46:30.513Z,1689011190.513 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20230710T151042/Courier0079.lzma
2023-07-10T17:46:31.516Z,1689011191.516 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Courier0079.lzma.bak
2023-07-10T17:46:31.516Z,1689011191.516 [DataOverHttps](INFO): SBD MOMSN=18458786
2023-07-10T17:46:47.578Z,1689011207.578 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20230710T151042/Express0080.lzma
2023-07-10T17:46:48.288Z,1689011208.288 [NAL9602](INFO): SBD MO Status=0, MOMSN=43924, MT Status=0, MTMSN=0
2023-07-10T17:46:48.288Z,1689011208.288 [NAL9602](INFO): No messages in MT queue
2023-07-10T17:46:48.580Z,1689011208.580 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Express0080.lzma.bak
2023-07-10T17:46:48.580Z,1689011208.580 [DataOverHttps](INFO): SBD MOMSN=18458789
2023-07-10T17:46:49.898Z,1689011209.898 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T17:46:49.898Z,1689011209.898 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T17:46:49.898Z,1689011209.898 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T17:47:19.025Z,1689011239.025 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T17:51:24.526Z,1689011484.526 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T17:51:50.593Z,1689011510.593 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T17:51:50.593Z,1689011510.593 [Default:CheckIn:C.Wait] Stopped
2023-07-10T17:51:50.593Z,1689011510.593 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T17:51:50.593Z,1689011510.593 [Default:CheckIn:D] Running Loop=1
2023-07-10T17:51:51.002Z,1689011511.002 [Default:CheckIn:D] Stopped
2023-07-10T17:51:51.002Z,1689011511.002 [Default:CheckIn:E] Running Loop=1
2023-07-10T17:51:51.395Z,1689011511.395 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 157.574414 min 
2023-07-10T17:51:51.395Z,1689011511.395 [Default:CheckIn:E] Stopped
2023-07-10T17:51:51.395Z,1689011511.395 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T17:51:51.395Z,1689011511.395 [Default:CheckIn] Stopped
2023-07-10T17:51:51.396Z,1689011511.396 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T17:51:51.396Z,1689011511.396 [Default:CheckIn](INFO): Running loop #26
2023-07-10T17:51:51.396Z,1689011511.396 [Default:CheckIn] Running Loop=26
2023-07-10T17:51:51.396Z,1689011511.396 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T17:51:51.396Z,1689011511.396 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T17:51:53.409Z,1689011513.409 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175152.00,A,3647.50530,N,12153.26554,W,1.263,95.36,100723,,,D*45
2023-07-10T17:51:53.411Z,1689011513.411 [NAL9602](INFO): GPS fix at 20230710T175152: (36.791755, -121.887759)
2023-07-10T17:51:53.429Z,1689011513.429 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T17:51:53.429Z,1689011513.429 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T17:51:55.650Z,1689011515.650 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T17:52:04.317Z,1689011524.317 [NAL9602](INFO): SBD MO Status=0, MOMSN=43925, MT Status=0, MTMSN=0
2023-07-10T17:52:04.317Z,1689011524.317 [NAL9602](INFO): No messages in MT queue
2023-07-10T17:52:26.774Z,1689011546.774 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T17:52:35.018Z,1689011555.018 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T17:52:57.926Z,1689011577.926 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T17:53:16.441Z,1689011596.441 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20230710T151042/Courier0082.lzma
2023-07-10T17:53:17.444Z,1689011597.444 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Courier0082.lzma.bak
2023-07-10T17:53:17.444Z,1689011597.444 [DataOverHttps](INFO): SBD MOMSN=18458803
2023-07-10T17:53:46.141Z,1689011626.141 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20230710T151042/Express0083.lzma
2023-07-10T17:53:47.144Z,1689011627.144 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Express0083.lzma.bak
2023-07-10T17:53:47.144Z,1689011627.144 [DataOverHttps](INFO): SBD MOMSN=18458807
2023-07-10T17:53:48.180Z,1689011628.180 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T17:53:48.180Z,1689011628.180 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T17:53:48.180Z,1689011628.180 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T17:55:50.372Z,1689011750.372 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T17:56:21.514Z,1689011781.514 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T17:56:52.670Z,1689011812.670 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T17:57:23.794Z,1689011843.794 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T17:57:28.814Z,1689011848.814 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.075241
2023-07-10T17:58:25.062Z,1689011905.062 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T17:58:48.779Z,1689011928.779 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T17:58:48.779Z,1689011928.779 [Default:CheckIn:C.Wait] Stopped
2023-07-10T17:58:48.779Z,1689011928.779 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T17:58:48.779Z,1689011928.779 [Default:CheckIn:D] Running Loop=1
2023-07-10T17:58:49.185Z,1689011929.185 [Default:CheckIn:D] Stopped
2023-07-10T17:58:49.185Z,1689011929.185 [Default:CheckIn:E] Running Loop=1
2023-07-10T17:58:49.607Z,1689011929.607 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 164.544124 min 
2023-07-10T17:58:49.607Z,1689011929.607 [Default:CheckIn:E] Stopped
2023-07-10T17:58:49.608Z,1689011929.608 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T17:58:49.608Z,1689011929.608 [Default:CheckIn] Stopped
2023-07-10T17:58:49.608Z,1689011929.608 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T17:58:49.608Z,1689011929.608 [Default:CheckIn](INFO): Running loop #27
2023-07-10T17:58:49.608Z,1689011929.608 [Default:CheckIn] Running Loop=27
2023-07-10T17:58:49.608Z,1689011929.608 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T17:58:49.608Z,1689011929.608 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T17:58:51.597Z,1689011931.597 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175850.00,A,3647.63950,N,12151.77554,W,26.359,80.88,100723,,,D*7E
2023-07-10T17:58:51.599Z,1689011931.599 [NAL9602](INFO): GPS fix at 20230710T175850: (36.793992, -121.862926)
2023-07-10T17:58:51.609Z,1689011931.609 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T17:58:51.610Z,1689011931.610 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T17:58:56.186Z,1689011936.186 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T17:59:14.277Z,1689011954.277 [NAL9602](INFO): SBD MO Status=2, MOMSN=43926, MT Status=2, MTMSN=0
2023-07-10T17:59:14.277Z,1689011954.277 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-07-10T17:59:27.310Z,1689011967.310 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T17:59:31.702Z,1689011971.702 [NAL9602](INFO): SBD MO Status=2, MOMSN=43926, MT Status=2, MTMSN=0
2023-07-10T17:59:31.702Z,1689011971.702 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-07-10T17:59:51.156Z,1689011991.156 [NAL9602](INFO): SBD MO Status=1, MOMSN=43926, MT Status=0, MTMSN=0
2023-07-10T17:59:51.214Z,1689011991.214 [NAL9602](INFO): Sent 73 bytes from file Logs/20230710T151042/Courier0085.lzma
2023-07-10T17:59:51.214Z,1689011991.214 [NAL9602](INFO): Packets left to send: 0
2023-07-10T17:59:58.434Z,1689011998.434 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T18:00:10.612Z,1689012010.612 [NAL9602](INFO): SBD MO Status=1, MOMSN=43927, MT Status=0, MTMSN=0
2023-07-10T18:00:10.674Z,1689012010.674 [NAL9602](INFO): Sent 142 bytes from file Logs/20230710T151042/Express0086.lzma
2023-07-10T18:00:10.674Z,1689012010.674 [NAL9602](INFO): Packets left to send: 0
2023-07-10T18:00:27.984Z,1689012027.984 [NAL9602](INFO): SBD MO Status=0, MOMSN=43928, MT Status=0, MTMSN=0
2023-07-10T18:00:28.073Z,1689012028.073 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T18:00:28.073Z,1689012028.073 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T18:00:28.073Z,1689012028.073 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T18:00:29.558Z,1689012029.558 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T18:00:58.683Z,1689012058.683 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T18:01:00.682Z,1689012060.682 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T18:01:31.810Z,1689012091.810 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T18:02:02.934Z,1689012122.934 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T18:02:34.058Z,1689012154.058 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T18:03:05.214Z,1689012185.214 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2023-07-10T18:03:36.634Z,1689012216.634 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2023-07-10T18:03:50.946Z,1689012230.946 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003181
2023-07-10T18:05:28.662Z,1689012328.662 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-07-10T18:05:28.662Z,1689012328.662 [Default:CheckIn:C.Wait] Stopped
2023-07-10T18:05:28.662Z,1689012328.662 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-07-10T18:05:28.662Z,1689012328.662 [Default:CheckIn:D] Running Loop=1
2023-07-10T18:05:29.043Z,1689012329.043 [Default:CheckIn:D] Stopped
2023-07-10T18:05:29.043Z,1689012329.043 [Default:CheckIn:E] Running Loop=1
2023-07-10T18:05:29.445Z,1689012329.445 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 171.208431 min 
2023-07-10T18:05:29.445Z,1689012329.445 [Default:CheckIn:E] Stopped
2023-07-10T18:05:29.445Z,1689012329.445 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-07-10T18:05:29.445Z,1689012329.445 [Default:CheckIn] Stopped
2023-07-10T18:05:29.445Z,1689012329.445 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-07-10T18:05:29.445Z,1689012329.445 [Default:CheckIn](INFO): Running loop #28
2023-07-10T18:05:29.445Z,1689012329.445 [Default:CheckIn] Running Loop=28
2023-07-10T18:05:29.445Z,1689012329.445 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-07-10T18:05:29.445Z,1689012329.445 [Default:CheckIn:Read_GPS] Running Loop=1
2023-07-10T18:05:31.457Z,1689012331.457 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180530.00,A,3647.76162,N,12150.85276,W,1.302,75.04,100723,,,D*4C
2023-07-10T18:05:31.459Z,1689012331.459 [NAL9602](INFO): GPS fix at 20230710T180530: (36.796027, -121.847546)
2023-07-10T18:05:31.470Z,1689012331.470 [Default:CheckIn:Read_GPS] Stopped
2023-07-10T18:05:31.470Z,1689012331.470 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-07-10T18:05:50.777Z,1689012350.777 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20230710T151042/Courier0088.lzma
2023-07-10T18:05:51.780Z,1689012351.780 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Courier0088.lzma.bak
2023-07-10T18:05:51.780Z,1689012351.780 [DataOverHttps](INFO): SBD MOMSN=18458845
2023-07-10T18:05:52.863Z,1689012352.863 [NAL9602](INFO): SBD MO Status=2, MOMSN=43929, MT Status=2, MTMSN=0
2023-07-10T18:05:52.863Z,1689012352.863 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2023-07-10T18:06:08.653Z,1689012368.653 [DataOverHttps](INFO): Sending 142 bytes from file Logs/20230710T151042/Express0089.lzma
2023-07-10T18:06:09.656Z,1689012369.656 [DataOverHttps](INFO): Moved sent file to Logs/20230710T151042/Express0089.lzma.bak
2023-07-10T18:06:09.656Z,1689012369.656 [DataOverHttps](INFO): SBD MOMSN=18458848
2023-07-10T18:06:11.067Z,1689012371.067 [Default:CheckIn:Read_Iridium] Stopped
2023-07-10T18:06:11.067Z,1689012371.067 [Default:CheckIn:C.Wait] Running Loop=1
2023-07-10T18:06:11.067Z,1689012371.067 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-07-10T18:06:11.856Z,1689012371.856 [NAL9602](INFO): SBD MO Status=0, MOMSN=43929, MT Status=0, MTMSN=0
2023-07-10T18:06:11.856Z,1689012371.856 [NAL9602](INFO): No messages in MT queue
2023-07-10T18:06:42.557Z,1689012402.557 [NAL9602](INFO): Not Powering down - fast GPS
2023-07-10T18:07:19.231Z,1689012439.231 [DataOverHttps](IMPORTANT): SBD MTMSN=20230710T180718
2023-07-10T18:07:26.441Z,1689012446.441 [DataOverHttps](INFO): Received command: restart logs