2024-07-29T15:30:24.828Z,1722267024.828 [Supervisor](DEBUG): Initializing supervisor.
2024-07-29T15:30:24.833Z,1722267024.833 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-07-29T15:30:24.833Z,1722267024.833 [SyncHandler](INFO): Protected caller Thread ID is 836
2024-07-29T15:30:24.834Z,1722267024.834 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-07-29T15:30:24.834Z,1722267024.834 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-07-29T15:30:24.835Z,1722267024.835 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 837
2024-07-29T15:30:24.839Z,1722267024.839 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-07-29T15:30:24.856Z,1722267024.856 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-07-29T15:30:24.857Z,1722267024.857 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-07-29T15:30:24.857Z,1722267024.857 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 838
2024-07-29T15:30:24.861Z,1722267024.861 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-07-29T15:30:24.862Z,1722267024.862 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-07-29T15:30:24.863Z,1722267024.863 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 839
2024-07-29T15:30:24.865Z,1722267024.865 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-07-29T15:30:24.866Z,1722267024.866 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-07-29T15:30:24.866Z,1722267024.866 [logger ThreadHandler](INFO): Protected caller Thread ID is 840
2024-07-29T15:30:24.870Z,1722267024.870 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-07-29T15:30:24.870Z,1722267024.870 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-07-29T15:30:24.874Z,1722267024.874 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-07-29T15:30:25.152Z,1722267025.152 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-07-29T15:30:25.154Z,1722267025.154 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-07-29T15:30:25.248Z,1722267025.248 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-07-29T15:30:25.802Z,1722267025.802 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-07-29T15:30:25.804Z,1722267025.804 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-07-29T15:30:26.166Z,1722267026.166 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-07-29T15:30:26.168Z,1722267026.168 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-07-29T15:30:26.277Z,1722267026.277 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-07-29T15:30:26.278Z,1722267026.278 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-07-29T15:30:26.698Z,1722267026.698 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-07-29T15:30:26.700Z,1722267026.700 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-07-29T15:30:26.929Z,1722267026.929 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-07-29T15:30:26.931Z,1722267026.931 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-07-29T15:30:27.474Z,1722267027.474 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-07-29T15:30:27.475Z,1722267027.475 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-07-29T15:30:27.623Z,1722267027.623 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-07-29T15:30:27.625Z,1722267027.625 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-07-29T15:30:27.715Z,1722267027.715 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-07-29T15:30:29.063Z,1722267029.063 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-07-29T15:30:29.064Z,1722267029.064 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-07-29T15:30:29.466Z,1722267029.466 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-07-29T15:30:29.468Z,1722267029.468 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-07-29T15:30:29.674Z,1722267029.674 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-07-29T15:30:29.675Z,1722267029.675 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-07-29T15:30:29.799Z,1722267029.799 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-07-29T15:30:29.800Z,1722267029.800 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-07-29T15:30:30.040Z,1722267030.040 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-07-29T15:30:30.041Z,1722267030.041 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-07-29T15:30:30.305Z,1722267030.305 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-07-29T15:30:30.307Z,1722267030.307 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2024-07-29T15:30:30.310Z,1722267030.310 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2024-07-29T15:30:30.413Z,1722267030.413 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2024-07-29T15:30:30.525Z,1722267030.525 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2024-07-29T15:30:30.640Z,1722267030.640 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2024-07-29T15:30:30.735Z,1722267030.735 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2024-07-29T15:30:30.850Z,1722267030.850 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2024-07-29T15:30:30.956Z,1722267030.956 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2024-07-29T15:30:31.084Z,1722267031.084 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2024-07-29T15:30:31.250Z,1722267031.250 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2024-07-29T15:30:31.401Z,1722267031.401 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2024-07-29T15:30:31.524Z,1722267031.524 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2024-07-29T15:30:31.798Z,1722267031.798 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-07-29T15:30:31.799Z,1722267031.799 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-07-29T15:30:31.810Z,1722267031.810 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-07-29T15:30:31.882Z,1722267031.882 [VerticalControl](DEBUG): Construct VerticalControl.
2024-07-29T15:30:31.941Z,1722267031.941 [VerticalControl] Loaded
2024-07-29T15:30:31.941Z,1722267031.941 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-07-29T15:30:31.944Z,1722267031.944 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-07-29T15:30:31.991Z,1722267031.991 [HorizontalControl] Loaded
2024-07-29T15:30:31.991Z,1722267031.991 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-07-29T15:30:31.993Z,1722267031.993 [SpeedControl](DEBUG): Construct SpeedControl.
2024-07-29T15:30:31.996Z,1722267031.996 [SpeedControl] Loaded
2024-07-29T15:30:31.997Z,1722267031.997 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-07-29T15:30:31.999Z,1722267031.999 [LoopControl](DEBUG): Construct LoopControl.
2024-07-29T15:30:31.000Z,1722267032.000 [LoopControl] Loaded
2024-07-29T15:30:32.000Z,1722267032.000 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-07-29T15:30:32.000Z,1722267032.000 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-07-29T15:30:32.002Z,1722267032.002 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-07-29T15:30:32.018Z,1722267032.018 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-07-29T15:30:32.019Z,1722267032.019 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-07-29T15:30:32.272Z,1722267032.272 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-07-29T15:30:32.274Z,1722267032.274 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-07-29T15:30:32.456Z,1722267032.456 [BuoyancyServo] Loaded
2024-07-29T15:30:32.457Z,1722267032.457 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-07-29T15:30:32.478Z,1722267032.478 [ElevatorServo] Loaded
2024-07-29T15:30:32.479Z,1722267032.479 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-07-29T15:30:32.499Z,1722267032.499 [MassServo] Loaded
2024-07-29T15:30:32.499Z,1722267032.499 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-07-29T15:30:32.519Z,1722267032.519 [RudderServo] Loaded
2024-07-29T15:30:32.520Z,1722267032.520 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-07-29T15:30:32.536Z,1722267032.536 [ThrusterHE] Loaded
2024-07-29T15:30:32.536Z,1722267032.536 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-07-29T15:30:32.536Z,1722267032.536 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-07-29T15:30:32.537Z,1722267032.537 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-07-29T15:30:32.672Z,1722267032.672 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-07-29T15:30:32.672Z,1722267032.672 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-07-29T15:30:32.686Z,1722267032.686 [NavChart] Loaded
2024-07-29T15:30:32.686Z,1722267032.686 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-07-29T15:30:32.692Z,1722267032.692 [UniversalFixResidualReporter] Loaded
2024-07-29T15:30:32.692Z,1722267032.692 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-07-29T15:30:32.692Z,1722267032.692 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-07-29T15:30:32.694Z,1722267032.694 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-07-29T15:30:32.776Z,1722267032.776 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-07-29T15:30:32.777Z,1722267032.777 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-07-29T15:30:33.532Z,1722267033.532 [AHRS_M2] Loaded
2024-07-29T15:30:33.532Z,1722267033.532 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-07-29T15:30:34.795Z,1722267034.795 [BPC1] Loaded
2024-07-29T15:30:34.795Z,1722267034.795 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-07-29T15:30:34.940Z,1722267034.940 [DataOverHttps] Loaded
2024-07-29T15:30:34.940Z,1722267034.940 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-07-29T15:30:34.942Z,1722267034.942 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409314E0
2024-07-29T15:30:34.942Z,1722267034.942 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 923
2024-07-29T15:30:34.966Z,1722267034.966 [Depth_Keller] Loaded
2024-07-29T15:30:34.966Z,1722267034.966 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-07-29T15:30:34.971Z,1722267034.971 [DropWeight] Loaded
2024-07-29T15:30:34.971Z,1722267034.971 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-07-29T15:30:35.034Z,1722267035.034 [NAL9602] Loaded
2024-07-29T15:30:35.034Z,1722267035.034 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-07-29T15:30:35.064Z,1722267035.064 [Onboard] Loaded
2024-07-29T15:30:35.064Z,1722267035.064 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-07-29T15:30:35.065Z,1722267035.065 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409614E0
2024-07-29T15:30:35.066Z,1722267035.066 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 924
2024-07-29T15:30:35.073Z,1722267035.073 [PowerOnly](INFO): Adding load control power supply at /dev/loadC1
2024-07-29T15:30:35.086Z,1722267035.086 [PowerOnly] Loaded
2024-07-29T15:30:35.086Z,1722267035.086 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2024-07-29T15:30:35.100Z,1722267035.100 [Power24vConverter] Loaded
2024-07-29T15:30:35.100Z,1722267035.100 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-07-29T15:30:35.115Z,1722267035.115 [Radio_Surface] Loaded
2024-07-29T15:30:35.115Z,1722267035.115 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-07-29T15:30:35.116Z,1722267035.116 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409914E0
2024-07-29T15:30:35.117Z,1722267035.117 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 925
2024-07-29T15:30:35.242Z,1722267035.242 [DAT] Loaded
2024-07-29T15:30:35.242Z,1722267035.242 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2024-07-29T15:30:35.253Z,1722267035.253 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409C14E0
2024-07-29T15:30:35.253Z,1722267035.253 [DAT ThreadHandler](INFO): Protected caller Thread ID is 927
2024-07-29T15:30:35.254Z,1722267035.254 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-07-29T15:30:35.255Z,1722267035.255 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-07-29T15:30:35.360Z,1722267035.360 [DepthRateCalculator] Loaded
2024-07-29T15:30:35.360Z,1722267035.360 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-07-29T15:30:35.365Z,1722267035.365 [PitchRateCalculator] Loaded
2024-07-29T15:30:35.366Z,1722267035.366 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-07-29T15:30:35.374Z,1722267035.374 [SpeedCalculator] Loaded
2024-07-29T15:30:35.374Z,1722267035.374 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-07-29T15:30:35.379Z,1722267035.379 [YawRateCalculator] Loaded
2024-07-29T15:30:35.379Z,1722267035.379 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-07-29T15:30:35.398Z,1722267035.398 [ElevatorOffsetCalculator] Loaded
2024-07-29T15:30:35.398Z,1722267035.398 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-07-29T15:30:35.398Z,1722267035.398 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-07-29T15:30:35.399Z,1722267035.399 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-07-29T15:30:35.584Z,1722267035.584 [CANONSampler] Loaded
2024-07-29T15:30:35.584Z,1722267035.584 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2024-07-29T15:30:35.648Z,1722267035.648 [CTD_Seabird] Loaded
2024-07-29T15:30:35.649Z,1722267035.649 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-07-29T15:30:35.650Z,1722267035.650 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A7D4E0
2024-07-29T15:30:35.650Z,1722267035.650 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 928
2024-07-29T15:30:35.670Z,1722267035.670 [PAR_Licor] Loaded
2024-07-29T15:30:35.670Z,1722267035.670 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-07-29T15:30:35.714Z,1722267035.714 [WetLabsBB2FL] Loaded
2024-07-29T15:30:35.714Z,1722267035.714 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-07-29T15:30:35.715Z,1722267035.715 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AAD4E0
2024-07-29T15:30:35.716Z,1722267035.716 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 929
2024-07-29T15:30:35.716Z,1722267035.716 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-07-29T15:30:35.717Z,1722267035.717 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-07-29T15:30:35.761Z,1722267035.761 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-07-29T15:30:35.761Z,1722267035.761 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-07-29T15:30:36.153Z,1722267036.153 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-07-29T15:30:36.154Z,1722267036.154 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-07-29T15:30:36.320Z,1722267036.320 [SBIT](DEBUG): Construct Startup Built In Test.
2024-07-29T15:30:36.329Z,1722267036.329 [SBIT] Loaded
2024-07-29T15:30:36.330Z,1722267036.330 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-07-29T15:30:36.333Z,1722267036.333 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-07-29T15:30:36.345Z,1722267036.345 [IBIT] Loaded
2024-07-29T15:30:36.346Z,1722267036.346 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-07-29T15:30:36.351Z,1722267036.351 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-07-29T15:30:36.456Z,1722267036.456 [CBIT] Loaded
2024-07-29T15:30:36.456Z,1722267036.456 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-07-29T15:30:36.456Z,1722267036.456 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-07-29T15:30:36.457Z,1722267036.457 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-07-29T15:30:36.595Z,1722267036.595 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-07-29T15:30:36.601Z,1722267036.601 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-07-29T15:30:36.604Z,1722267036.604 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-07-29T15:30:36.615Z,1722267036.615 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-07-29T15:30:36.616Z,1722267036.616 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C074E0
2024-07-29T15:30:36.617Z,1722267036.617 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 930
2024-07-29T15:30:36.621Z,1722267036.621 [Supervisor](INFO): Main Thread ID is 829
2024-07-29T15:30:36.621Z,1722267036.621 [Supervisor](DEBUG): Running supervisor.
2024-07-29T15:30:36.622Z,1722267036.622 [CommandExec ThreadHandler](INFO): Handler Thread ID is 931
2024-07-29T15:30:36.622Z,1722267036.622 [CommandExec](INFO): Initializing the command executive.
2024-07-29T15:30:36.624Z,1722267036.624 [CommandLine ThreadHandler](INFO): Handler Thread ID is 932
2024-07-29T15:30:36.626Z,1722267036.626 [controlThread ThreadHandler](INFO): Handler Thread ID is 933
2024-07-29T15:30:36.627Z,1722267036.627 [controlThread](DEBUG): Initializing ControlThread
2024-07-29T15:30:36.627Z,1722267036.627 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-07-29T15:30:36.629Z,1722267036.629 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-07-29T15:30:36.630Z,1722267036.630 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-07-29T15:30:36.630Z,1722267036.630 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-07-29T15:30:36.632Z,1722267036.632 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-07-29T15:30:36.632Z,1722267036.632 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-07-29T15:30:36.639Z,1722267036.639 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-07-29T15:30:36.640Z,1722267036.640 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-07-29T15:30:36.640Z,1722267036.640 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-07-29T15:30:36.640Z,1722267036.640 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-07-29T15:30:36.641Z,1722267036.641 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-07-29T15:30:36.645Z,1722267036.645 [SBIT](INFO): Initialize SBIT Component.
2024-07-29T15:30:36.646Z,1722267036.646 [SBIT](IMPORTANT): git: 2024-07-24
2024-07-29T15:30:36.646Z,1722267036.646 [SBIT](INFO): git hash: f6cb8c8dea29ad885657024c63abf04d5f6782cd
2024-07-29T15:30:36.646Z,1722267036.646 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-07-29T15:30:36.647Z,1722267036.647 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2024-07-29T15:30:36.648Z,1722267036.648 [SBIT](INFO): Beginning SBIT in 25.000000 seconds.
2024-07-29T15:30:36.649Z,1722267036.649 [IBIT](INFO): Initialize IBIT Component.
2024-07-29T15:30:36.650Z,1722267036.650 [CBIT](DEBUG): Initialize CBIT Component.
2024-07-29T15:30:36.651Z,1722267036.651 [logger ThreadHandler](INFO): Handler Thread ID is 934
2024-07-29T15:30:36.661Z,1722267036.661 [CBIT](DEBUG): Initialized mux pins.
2024-07-29T15:30:36.661Z,1722267036.661 [CBIT](DEBUG): Initializing the watchdog timer.
2024-07-29T15:30:36.669Z,1722267036.669 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 935
2024-07-29T15:30:36.671Z,1722267036.671 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-07-29T15:30:36.681Z,1722267036.681 [Onboard ThreadHandler](INFO): Handler Thread ID is 936
2024-07-29T15:30:36.686Z,1722267036.686 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-07-29T15:30:36.686Z,1722267036.686 [CBIT](DEBUG): Initializing heartbeat.
2024-07-29T15:30:36.702Z,1722267036.702 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 937
2024-07-29T15:30:36.717Z,1722267036.717 [DAT ThreadHandler](INFO): Handler Thread ID is 938
2024-07-29T15:30:36.718Z,1722267036.718 [DAT](INFO): Powering up
2024-07-29T15:30:36.718Z,1722267036.718 [DAT](DEBUG): Initializing DAT.
2024-07-29T15:30:36.722Z,1722267036.722 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 940
2024-07-29T15:30:36.723Z,1722267036.723 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-07-29T15:30:36.727Z,1722267036.727 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 942
2024-07-29T15:30:36.729Z,1722267036.729 [WetLabsBB2FL](INFO): Powering up
2024-07-29T15:30:36.731Z,1722267036.731 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 944
2024-07-29T15:30:36.739Z,1722267036.739 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2024-07-29T15:30:36.739Z,1722267036.739 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2024-07-29T15:30:36.739Z,1722267036.739 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2024-07-29T15:30:36.739Z,1722267036.739 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2024-07-29T15:30:36.739Z,1722267036.739 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2024-07-29T15:30:36.740Z,1722267036.740 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2024-07-29T15:30:36.740Z,1722267036.740 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2024-07-29T15:30:36.740Z,1722267036.740 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2024-07-29T15:30:36.757Z,1722267036.757 [CBIT](DEBUG): Deactivating GF circuits.
2024-07-29T15:30:36.757Z,1722267036.757 [CBIT](DEBUG): Deactivating emergency mode.
2024-07-29T15:30:36.797Z,1722267036.797 [CBIT](DEBUG): Backplane powered.
2024-07-29T15:30:36.798Z,1722267036.798 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-07-29T15:30:36.798Z,1722267036.798 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-07-29T15:30:36.799Z,1722267036.799 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-07-29T15:30:36.802Z,1722267036.802 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-07-29T15:30:36.863Z,1722267036.863 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-07-29T15:30:36.867Z,1722267036.867 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-07-29T15:30:36.908Z,1722267036.908 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Startup {
behavior Guidance:GoToSurface {
run in progression
}
aggregate StartupSatComms {
run in sequence
readDatum {
timeout duration=P1M
Universal:latitude_fix
}
readDatum {
timeout duration=P1M
Universal:platform_communications
}
}
}
2024-07-29T15:30:36.909Z,1722267036.909 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-07-29T15:30:36.909Z,1722267036.909 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-07-29T15:30:36.926Z,1722267036.926 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-07-29T15:30:37.105Z,1722267037.105 [Radio_Surface](INFO): Powering up
2024-07-29T15:30:37.241Z,1722267037.241 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2024-07-29T15:30:37.255Z,1722267037.255 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-07-29T15:30:37.256Z,1722267037.256 [Default:A.Wait](DEBUG): Construct Wait.
2024-07-29T15:30:37.258Z,1722267037.258 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-07-29T15:30:37.285Z,1722267037.285 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-07-29T15:30:37.319Z,1722267037.319 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-07-29T15:30:37.334Z,1722267037.334 [Default:E.Execute](DEBUG): Construct Execute.
2024-07-29T15:30:37.363Z,1722267037.363 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Default {
arguments {
ElapsedSinceDefaultStarted = 0 minute
}
behavior Guidance:Wait {
"""
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
"""
run in sequence
set duration = 13 second
}
behavior Guidance:GoToSurface {
run in progression
}
aggregate CheckIn {
run in sequence repeat=288
readDatum id="Read_GPS" {
timeout duration=P5M
Universal:time_fix
}
readDatum id="Read_Iridium" {
timeout duration=P4H {
behavior Guidance:Execute {
run in sequence
set command = "Burn on"
}
syslog critical "Dropped weight due to communications timeout."
}
Universal:platform_communications
}
behavior Guidance:Wait {
run in sequence
set duration = 5 minute
}
assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )
syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
}
syslog important "Restarting logs and Default mission."
behavior Guidance:Execute {
run in sequence
set command = "restart logs"
}
}
2024-07-29T15:30:37.368Z,1722267037.368 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2024-07-29T15:30:37.390Z,1722267037.390 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-07-29T15:30:37.556Z,1722267037.556 [PowerOnly](INFO): Powering up loadControl
2024-07-29T15:30:37.574Z,1722267037.574 [Power24vConverter](INFO): Powering up.
2024-07-29T15:30:37.574Z,1722267037.574 [CANONSampler](INFO): Powering down
2024-07-29T15:30:37.620Z,1722267037.620 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-07-29T15:30:37.625Z,1722267037.625 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-07-29T15:30:37.627Z,1722267037.627 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-07-29T15:30:37.633Z,1722267037.633 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-07-29T15:30:37.634Z,1722267037.634 [MassServo](DEBUG): Initializing EZServoServo.
2024-07-29T15:30:37.641Z,1722267037.641 [MassServo](DEBUG): Initializing MassServo.
2024-07-29T15:30:37.642Z,1722267037.642 [RudderServo](DEBUG): Initializing EZServoServo.
2024-07-29T15:30:37.649Z,1722267037.649 [RudderServo](DEBUG): Initializing RudderServo.
2024-07-29T15:30:37.650Z,1722267037.650 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-07-29T15:30:37.657Z,1722267037.657 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-07-29T15:30:38.833Z,1722267038.833 [WetLabsBB2FL](INFO): Powering down
2024-07-29T15:30:43.251Z,1722267043.251 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-07-29T15:30:43.753Z,1722267043.753 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2024-07-29T15:30:49.570Z,1722267049.570 [DAT](INFO): DAT read:
2024-07-29T15:30:49.571Z,1722267049.571 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2024-07-29T15:30:51.334Z,1722267051.334 [DAT](INFO): DAT read: MF Frequency Band
2024-07-29T15:30:51.335Z,1722267051.335 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2024-07-29T15:30:51.336Z,1722267051.336 [DAT](INFO): DAT read: Jul 29 2024 15:30:45
2024-07-29T15:30:52.342Z,1722267052.342 [DAT](INFO): DAT read: Features enabled [Bearing]
2024-07-29T15:30:52.343Z,1722267052.343 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC
2024-07-29T15:30:52.344Z,1722267052.344 [DAT](INFO): commRate: 1200
2024-07-29T15:30:54.409Z,1722267054.409 [DAT](INFO): entering command mode
2024-07-29T15:30:54.609Z,1722267054.609 [DAT](INFO): DAT read:
2024-07-29T15:30:54.610Z,1722267054.610 [DAT](INFO): DAT read: uer:1>
2024-07-29T15:30:54.610Z,1722267054.610 [DAT](INFO): unknown deviceResponse_: uer:1>
2024-07-29T15:30:54.611Z,1722267054.611 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:30:54.862Z,1722267054.862 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:30:55.114Z,1722267055.114 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:30:55.365Z,1722267055.365 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:30:55.617Z,1722267055.617 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:30:55.869Z,1722267055.869 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:30:56.121Z,1722267056.121 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:30:56.373Z,1722267056.373 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:30:56.625Z,1722267056.625 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:30:56.878Z,1722267056.878 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:30:57.129Z,1722267057.129 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:30:57.382Z,1722267057.382 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:30:57.634Z,1722267057.634 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:30:57.885Z,1722267057.885 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:30:58.137Z,1722267058.137 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:30:58.389Z,1722267058.389 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:30:58.641Z,1722267058.641 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:30:58.894Z,1722267058.894 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:30:59.145Z,1722267059.145 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:30:59.397Z,1722267059.397 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:30:59.649Z,1722267059.649 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:30:59.901Z,1722267059.901 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:31:00.153Z,1722267060.153 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:31:00.406Z,1722267060.406 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:31:00.657Z,1722267060.657 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:31:00.910Z,1722267060.910 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:31:01.162Z,1722267061.162 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:31:01.413Z,1722267061.413 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:31:01.665Z,1722267061.665 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:31:01.917Z,1722267061.917 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:31:02.169Z,1722267062.169 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:31:02.421Z,1722267062.421 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:31:02.464Z,1722267062.464 [SBIT](IMPORTANT): Beginning Startup BIT
2024-07-29T15:31:02.473Z,1722267062.473 [CBIT](IMPORTANT): Beginning ground fault scan
2024-07-29T15:31:02.673Z,1722267062.673 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:31:02.934Z,1722267062.934 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:31:03.185Z,1722267063.185 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:31:03.438Z,1722267063.438 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:31:03.690Z,1722267063.690 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:31:03.941Z,1722267063.941 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:31:04.202Z,1722267064.202 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:31:04.453Z,1722267064.453 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:31:04.705Z,1722267064.705 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:31:04.958Z,1722267064.958 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:31:05.209Z,1722267065.209 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:31:05.255Z,1722267065.255 [NAL9602](INFO): Powering up NAL9602
2024-07-29T15:31:05.461Z,1722267065.461 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:31:05.714Z,1722267065.714 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:31:05.965Z,1722267065.965 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:31:06.218Z,1722267066.218 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:31:06.469Z,1722267066.469 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:31:06.722Z,1722267066.722 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:31:06.974Z,1722267066.974 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:31:07.226Z,1722267067.226 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:31:07.478Z,1722267067.478 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:31:07.730Z,1722267067.730 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:31:07.981Z,1722267067.981 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:31:08.233Z,1722267068.233 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:31:08.485Z,1722267068.485 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:31:08.738Z,1722267068.738 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:31:08.993Z,1722267068.993 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:31:09.245Z,1722267069.245 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:31:09.497Z,1722267069.497 [DAT](DEBUG): checking for command mode acknowledgment
2024-07-29T15:31:09.498Z,1722267069.498 [DAT](FAULT): failed to enter command mode
2024-07-29T15:31:09.801Z,1722267069.801 [DAT](INFO): entering command mode
2024-07-29T15:31:10.002Z,1722267070.002 [DAT](INFO): DAT read: user:1>
2024-07-29T15:31:10.003Z,1722267070.003 [DAT](INFO): DAT read: Command '+++' not found
2024-07-29T15:31:10.003Z,1722267070.003 [DAT](INFO): DAT read: Error
2024-07-29T15:31:10.003Z,1722267070.003 [DAT](INFO): setting verbose to 3
2024-07-29T15:31:10.254Z,1722267070.254 [DAT](INFO): DAT read: user:2>
2024-07-29T15:31:10.255Z,1722267070.255 [DAT](INFO): DAT read: Verbose | 3
2024-07-29T15:31:10.255Z,1722267070.255 [DAT](INFO): set verbose to 3
2024-07-29T15:31:10.255Z,1722267070.255 [DAT](INFO): setting DatVerbose to 27440
2024-07-29T15:31:10.506Z,1722267070.506 [DAT](INFO): DAT read: user:3>
2024-07-29T15:31:10.507Z,1722267070.507 [DAT](INFO): DAT read: DatVerbose | 27440
2024-07-29T15:31:10.507Z,1722267070.507 [DAT](INFO): set DatVerbose to 27440
2024-07-29T15:31:10.507Z,1722267070.507 [DAT](INFO): setting transmit power to 8
2024-07-29T15:31:10.758Z,1722267070.758 [DAT](INFO): DAT read: user:4>
2024-07-29T15:31:10.759Z,1722267070.759 [DAT](INFO): DAT read: TxPower | 8 (Max)
2024-07-29T15:31:10.759Z,1722267070.759 [DAT](INFO): set transmit power to 8
2024-07-29T15:31:10.759Z,1722267070.759 [DAT](INFO): setting local address to 8
2024-07-29T15:31:11.010Z,1722267071.010 [DAT](INFO): DAT read: user:5>
2024-07-29T15:31:11.011Z,1722267071.011 [DAT](INFO): DAT read: LocalAddr | 8
2024-07-29T15:31:11.011Z,1722267071.011 [DAT](INFO): set local address to 8
2024-07-29T15:31:11.012Z,1722267071.012 [DAT](INFO): Setting time to: 15:31:11 And date to:7/29/2024
2024-07-29T15:31:11.262Z,1722267071.262 [DAT](INFO): DAT read: user:6>
2024-07-29T15:31:11.263Z,1722267071.263 [DAT](INFO): DAT read: Mon Jul 29, 2024 15:31:11
2024-07-29T15:31:11.263Z,1722267071.263 [DAT](INFO): Local DAT time set to Mon Jul 29, 2024 15:31:11
2024-07-29T15:31:13.387Z,1722267073.387 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-07-29T15:31:13.776Z,1722267073.776 [CBIT](IMPORTANT): Beginning ground fault scan
2024-07-29T15:31:13.826Z,1722267073.826 [CommandExec](IMPORTANT): got command strobe off
2024-07-29T15:31:13.827Z,1722267073.827 [CommandExec](IMPORTANT): Deactivating strobe
2024-07-29T15:31:16.175Z,1722267076.175 [NAL9602](INFO): NAL9602 initialized
2024-07-29T15:31:24.739Z,1722267084.739 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-07-29T15:31:25.135Z,1722267085.135 [CBIT](IMPORTANT): Beginning ground fault scan
2024-07-29T15:31:25.190Z,1722267085.190 [CommandExec](IMPORTANT): got command strobe off
2024-07-29T15:31:25.190Z,1722267085.190 [CommandExec](IMPORTANT): Deactivating strobe
2024-07-29T15:31:36.017Z,1722267096.017 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-07-29T15:31:36.018Z,1722267096.018 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2024-07-29T15:31:56.596Z,1722267116.596 [SBIT](IMPORTANT): SBIT PASSED
2024-07-29T15:31:56.596Z,1722267116.596 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-07-29T15:31:56.597Z,1722267116.597 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=20 count;
2024-07-29T15:31:56.598Z,1722267116.598 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=324.584597 cubic_centimeter;
2024-07-29T15:31:56.598Z,1722267116.598 [SBIT](IMPORTANT): VerticalControl.massDefault=-1.280624 millimeter;
2024-07-29T15:31:57.002Z,1722267117.002 [MissionManager](IMPORTANT): Started mission Startup
2024-07-29T15:31:57.003Z,1722267117.003 [Startup] Running Loop=1
2024-07-29T15:31:57.003Z,1722267117.003 [Startup](DEBUG): Aggregate::initialize Startup
2024-07-29T15:31:57.003Z,1722267117.003 [Startup:A.GoToSurface] Running Loop=1
2024-07-29T15:31:57.003Z,1722267117.003 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-07-29T15:31:57.003Z,1722267117.003 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-07-29T15:31:57.004Z,1722267117.004 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-07-29T15:31:57.004Z,1722267117.004 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-07-29T15:31:57.004Z,1722267117.004 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-07-29T15:31:57.005Z,1722267117.005 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-07-29T15:31:57.005Z,1722267117.005 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-07-29T15:31:57.007Z,1722267117.007 [Startup:StartupSatComms] Running Loop=1
2024-07-29T15:31:57.007Z,1722267117.007 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-07-29T15:31:57.007Z,1722267117.007 [Startup:StartupSatComms:A] Running Loop=1
2024-07-29T15:31:57.404Z,1722267117.404 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-07-29T15:32:05.105Z,1722267125.105 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7).
2024-07-29T15:32:11.506Z,1722267131.506 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T15:32:24.838Z,1722267144.838 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T15:32:38.177Z,1722267158.177 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T15:32:51.502Z,1722267171.502 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T15:32:57.187Z,1722267177.187 [Startup:StartupSatComms:A](INFO): Timed out from 2024-07-29T15:31:57.0Z
2024-07-29T15:32:57.187Z,1722267177.187 [Startup:StartupSatComms:A] Stopped
2024-07-29T15:32:57.187Z,1722267177.187 [Startup:StartupSatComms:B] Running Loop=1
2024-07-29T15:32:57.607Z,1722267177.607 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-07-29T15:33:02.792Z,1722267182.792 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004962
2024-07-29T15:33:05.678Z,1722267185.678 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T15:33:10.828Z,1722267190.828 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20240729T152656/Courier0000.lzma
2024-07-29T15:33:11.832Z,1722267191.832 [DataOverHttps](INFO): Moved sent file to Logs/20240729T152656/Courier0000.lzma.bak
2024-07-29T15:33:11.832Z,1722267191.832 [DataOverHttps](INFO): SBD MOMSN=21392223
2024-07-29T15:33:19.413Z,1722267199.413 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T15:33:27.272Z,1722267207.272 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240729T153024/Courier0000.lzma
2024-07-29T15:33:28.274Z,1722267208.274 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Courier0000.lzma.bak
2024-07-29T15:33:28.274Z,1722267208.274 [DataOverHttps](INFO): SBD MOMSN=21392226
2024-07-29T15:33:33.149Z,1722267213.149 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T15:33:36.834Z,1722267216.834 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-07-29T15:33:36.834Z,1722267216.834 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-29T15:33:36.845Z,1722267216.845 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-29T15:33:37.235Z,1722267217.236 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-29T15:33:37.236Z,1722267217.236 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-07-29T15:33:43.960Z,1722267223.960 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20240729T152656/Express0001.lzma
2024-07-29T15:33:44.964Z,1722267224.964 [DataOverHttps](INFO): Moved sent file to Logs/20240729T152656/Express0001.lzma.bak
2024-07-29T15:33:44.964Z,1722267224.964 [DataOverHttps](INFO): SBD MOMSN=21392229
2024-07-29T15:33:46.481Z,1722267226.481 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T15:33:57.426Z,1722267237.426 [Startup:StartupSatComms:B](INFO): Timed out from 2024-07-29T15:32:57.2Z
2024-07-29T15:33:57.426Z,1722267237.426 [Startup:StartupSatComms:B] Stopped
2024-07-29T15:33:57.426Z,1722267237.426 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-07-29T15:33:57.427Z,1722267237.427 [Startup:StartupSatComms] Stopped
2024-07-29T15:33:57.427Z,1722267237.427 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-07-29T15:33:57.428Z,1722267237.428 [Startup](INFO): Completed Startup
2024-07-29T15:33:57.428Z,1722267237.428 [MissionManager](INFO): Startup is completed.
2024-07-29T15:33:57.428Z,1722267237.428 [MissionManager](INFO): Uninitializing Mission Startup
2024-07-29T15:33:57.428Z,1722267237.428 [Startup] Stopped
2024-07-29T15:33:57.428Z,1722267237.428 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-07-29T15:33:57.428Z,1722267237.428 [Startup:A.GoToSurface] Stopped
2024-07-29T15:33:57.428Z,1722267237.428 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-07-29T15:33:57.841Z,1722267237.841 [MissionManager](IMPORTANT): Started mission Default
2024-07-29T15:33:57.842Z,1722267237.842 [Default] Running Loop=1
2024-07-29T15:33:57.842Z,1722267237.842 [Default](DEBUG): Aggregate::initialize Default
2024-07-29T15:33:57.842Z,1722267237.842 [Default:B.GoToSurface] Running Loop=1
2024-07-29T15:33:57.842Z,1722267237.842 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-07-29T15:33:57.842Z,1722267237.842 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-07-29T15:33:57.842Z,1722267237.842 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-07-29T15:33:57.843Z,1722267237.843 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-07-29T15:33:57.843Z,1722267237.843 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-07-29T15:33:57.843Z,1722267237.843 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-07-29T15:33:57.844Z,1722267237.844 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-07-29T15:33:57.844Z,1722267237.844 [Default:A.Wait] Running Loop=1
2024-07-29T15:33:57.844Z,1722267237.844 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-07-29T15:33:59.813Z,1722267239.813 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T15:34:00.606Z,1722267240.606 [DataOverHttps](INFO): Sending 746 bytes from file Logs/20240729T153024/Express0001.lzma
2024-07-29T15:34:01.606Z,1722267241.606 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Express0001.lzma.bak
2024-07-29T15:34:01.606Z,1722267241.606 [DataOverHttps](INFO): SBD MOMSN=21392231
2024-07-29T15:34:11.143Z,1722267251.143 [Default:A.Wait](INFO): Done Waiting.
2024-07-29T15:34:11.143Z,1722267251.143 [Default:A.Wait] Stopped
2024-07-29T15:34:11.143Z,1722267251.143 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-07-29T15:34:11.547Z,1722267251.547 [Default:CheckIn] Running Loop=1
2024-07-29T15:34:11.547Z,1722267251.547 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-29T15:34:11.548Z,1722267251.548 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-29T15:34:11.952Z,1722267251.952 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-07-29T15:34:13.550Z,1722267253.550 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T15:34:27.701Z,1722267267.701 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T15:34:34.565Z,1722267274.565 [BPC1](INFO): Calculating totals. Valid battery stick count: 39. Valid reserve battery stick count: 3.
2024-07-29T15:34:34.568Z,1722267274.568 [BPC1](INFO): Received data from all battery sticks.
2024-07-29T15:36:20.010Z,1722267380.010 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-29T15:36:37.829Z,1722267397.829 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-07-29T15:36:37.829Z,1722267397.829 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-29T15:36:37.844Z,1722267397.844 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-29T15:36:38.245Z,1722267398.245 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-29T15:36:38.245Z,1722267398.245 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-07-29T15:39:11.715Z,1722267551.715 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-07-29T15:34:11.5Z
2024-07-29T15:39:11.715Z,1722267551.715 [Default:CheckIn:Read_GPS] Stopped
2024-07-29T15:39:11.715Z,1722267551.715 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-29T15:39:12.126Z,1722267552.126 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-07-29T15:39:12.364Z,1722267552.364 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20240729T153024/Courier0004.lzma
2024-07-29T15:39:13.362Z,1722267553.362 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Courier0004.lzma.bak
2024-07-29T15:39:13.363Z,1722267553.363 [DataOverHttps](INFO): SBD MOMSN=21392282
2024-07-29T15:39:28.989Z,1722267568.989 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20240729T153024/Express0005.lzma
2024-07-29T15:39:29.990Z,1722267569.990 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Express0005.lzma.bak
2024-07-29T15:39:29.991Z,1722267569.991 [DataOverHttps](INFO): SBD MOMSN=21392286
2024-07-29T15:39:31.127Z,1722267571.127 [Default:CheckIn:Read_Iridium] Stopped
2024-07-29T15:39:31.127Z,1722267571.127 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-29T15:39:31.127Z,1722267571.127 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-29T15:39:38.787Z,1722267578.787 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2024-07-29T15:39:38.787Z,1722267578.787 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-29T15:39:38.798Z,1722267578.798 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-29T15:39:39.199Z,1722267579.199 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-29T15:39:39.199Z,1722267579.199 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2024-07-29T15:41:18.166Z,1722267678.166 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-07-29T15:41:18.166Z,1722267678.166 [NAL9602] Data Fault, FailCount= 1
2024-07-29T15:41:18.166Z,1722267678.166 [NAL9602](ERROR): Data Fault
2024-07-29T15:41:18.185Z,1722267678.185 [CBIT](ERROR): Data Fault in component: NAL9602
2024-07-29T15:41:18.566Z,1722267678.566 [NAL9602](INFO): Powering down
2024-07-29T15:41:19.392Z,1722267679.392 [CBIT](INFO): Clearing failed state for component NAL9602
2024-07-29T15:41:19.393Z,1722267679.393 [NAL9602] No Fault, FailCount= 1
2024-07-29T15:41:48.866Z,1722267708.866 [NAL9602](INFO): Powering up NAL9602
2024-07-29T15:41:59.774Z,1722267719.774 [NAL9602](INFO): NAL9602 initialized
2024-07-29T15:42:39.775Z,1722267759.775 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2024-07-29T15:42:39.775Z,1722267759.775 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-29T15:42:39.809Z,1722267759.809 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-29T15:42:40.188Z,1722267760.188 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-29T15:42:40.188Z,1722267760.188 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2024-07-29T15:44:31.695Z,1722267871.695 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-29T15:44:31.695Z,1722267871.695 [Default:CheckIn:C.Wait] Stopped
2024-07-29T15:44:31.695Z,1722267871.695 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-29T15:44:31.695Z,1722267871.695 [Default:CheckIn:D] Running Loop=1
2024-07-29T15:44:32.091Z,1722267872.091 [Default:CheckIn:D] Stopped
2024-07-29T15:44:32.091Z,1722267872.091 [Default:CheckIn:E] Running Loop=1
2024-07-29T15:44:32.524Z,1722267872.524 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.570828 min
2024-07-29T15:44:32.524Z,1722267872.524 [Default:CheckIn:E] Stopped
2024-07-29T15:44:32.524Z,1722267872.524 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-29T15:44:32.524Z,1722267872.524 [Default:CheckIn] Stopped
2024-07-29T15:44:32.524Z,1722267872.524 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-29T15:44:32.524Z,1722267872.524 [Default:CheckIn](INFO): Running loop #2
2024-07-29T15:44:32.525Z,1722267872.525 [Default:CheckIn] Running Loop=2
2024-07-29T15:44:32.525Z,1722267872.525 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-29T15:44:32.525Z,1722267872.525 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-29T15:45:40.802Z,1722267940.802 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2024-07-29T15:45:40.802Z,1722267940.802 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-29T15:45:40.813Z,1722267940.813 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-29T15:45:41.215Z,1722267941.215 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-29T15:45:41.215Z,1722267941.215 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2024-07-29T15:46:29.913Z,1722267989.913 [CommandExec](IMPORTANT): got command failComponent
2024-07-29T15:46:29.913Z,1722267989.913 [CommandExec](IMPORTANT): Failed components:
2024-07-29T15:46:29.914Z,1722267989.914 [CommandExec](IMPORTANT): No failed Components.
2024-07-29T15:46:33.657Z,1722267993.657 [CommandExec](IMPORTANT): got command gfscan
2024-07-29T15:46:33.730Z,1722267993.730 [CBIT](IMPORTANT): Beginning ground fault scan
2024-07-29T15:46:44.659Z,1722268004.659 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.000736
CHAN A1 (24V): 0.000736
CHAN A2 (12V): -0.002191
CHAN A3 (5V): -0.001107
CHAN B0 (3.3V): -0.000313
CHAN B1 (3.15aV): -0.000366
CHAN B2 (3.15bV): -0.000208
CHAN B3 (GND): 0.000353
OPEN: -0.000268
Full Scale: +/- 1 mA
2024-07-29T15:48:41.785Z,1722268121.785 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2024-07-29T15:48:41.785Z,1722268121.785 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-29T15:48:41.804Z,1722268121.804 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-29T15:48:42.203Z,1722268122.203 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-29T15:48:42.203Z,1722268122.203 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2024-07-29T15:49:32.698Z,1722268172.698 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-07-29T15:44:32.5Z
2024-07-29T15:49:32.698Z,1722268172.698 [Default:CheckIn:Read_GPS] Stopped
2024-07-29T15:49:32.699Z,1722268172.699 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-29T15:49:39.492Z,1722268179.492 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240729T153024/Courier0007.lzma
2024-07-29T15:49:40.495Z,1722268180.495 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Courier0007.lzma.bak
2024-07-29T15:49:40.495Z,1722268180.495 [DataOverHttps](INFO): SBD MOMSN=21392308
2024-07-29T15:49:55.977Z,1722268195.977 [DataOverHttps](INFO): Sending 404 bytes from file Logs/20240729T153024/Express0008.lzma
2024-07-29T15:49:56.978Z,1722268196.978 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Express0008.lzma.bak
2024-07-29T15:49:56.979Z,1722268196.979 [DataOverHttps](INFO): SBD MOMSN=21392310
2024-07-29T15:49:58.155Z,1722268198.155 [Default:CheckIn:Read_Iridium] Stopped
2024-07-29T15:49:58.155Z,1722268198.155 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-29T15:49:58.155Z,1722268198.155 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-29T15:51:42.813Z,1722268302.813 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2024-07-29T15:51:42.813Z,1722268302.813 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-29T15:51:42.829Z,1722268302.829 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-29T15:51:43.239Z,1722268303.239 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-29T15:51:43.239Z,1722268303.239 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2024-07-29T15:52:01.763Z,1722268321.763 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-07-29T15:52:01.763Z,1722268321.763 [NAL9602] Data Fault, FailCount= 2
2024-07-29T15:52:01.764Z,1722268321.764 [NAL9602](ERROR): Data Fault
2024-07-29T15:52:01.782Z,1722268321.782 [CBIT](ERROR): Data Fault in component: NAL9602
2024-07-29T15:52:02.170Z,1722268322.170 [NAL9602](INFO): Powering down
2024-07-29T15:52:03.027Z,1722268323.027 [CBIT](INFO): Clearing failed state for component NAL9602
2024-07-29T15:52:03.027Z,1722268323.027 [NAL9602] No Fault, FailCount= 2
2024-07-29T15:52:32.470Z,1722268352.470 [NAL9602](INFO): Powering up NAL9602
2024-07-29T15:52:43.392Z,1722268363.392 [NAL9602](INFO): NAL9602 initialized
2024-07-29T15:53:25.254Z,1722268405.254 [CommandExec](IMPORTANT): got command burn on
2024-07-29T15:53:25.254Z,1722268405.254 [CommandExec](IMPORTANT): Activating dropweight wire
2024-07-29T15:53:47.426Z,1722268427.426 [CommandExec](IMPORTANT): got command burn off
2024-07-29T15:53:47.426Z,1722268427.426 [CommandExec](IMPORTANT): Deactivating dropweight wire
2024-07-29T15:54:43.777Z,1722268483.777 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2024-07-29T15:54:43.777Z,1722268483.777 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-29T15:54:43.788Z,1722268483.788 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-29T15:54:44.198Z,1722268484.198 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-29T15:54:44.198Z,1722268484.198 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2024-07-29T15:54:58.730Z,1722268498.730 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-29T15:54:58.730Z,1722268498.730 [Default:CheckIn:C.Wait] Stopped
2024-07-29T15:54:58.730Z,1722268498.730 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-29T15:54:58.730Z,1722268498.730 [Default:CheckIn:D] Running Loop=1
2024-07-29T15:54:59.134Z,1722268499.134 [Default:CheckIn:D] Stopped
2024-07-29T15:54:59.134Z,1722268499.134 [Default:CheckIn:E] Running Loop=1
2024-07-29T15:54:59.539Z,1722268499.539 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.021545 min
2024-07-29T15:54:59.539Z,1722268499.539 [Default:CheckIn:E] Stopped
2024-07-29T15:54:59.539Z,1722268499.539 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-29T15:54:59.539Z,1722268499.539 [Default:CheckIn] Stopped
2024-07-29T15:54:59.539Z,1722268499.539 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-29T15:54:59.540Z,1722268499.540 [Default:CheckIn](INFO): Running loop #3
2024-07-29T15:54:59.540Z,1722268499.540 [Default:CheckIn] Running Loop=3
2024-07-29T15:54:59.540Z,1722268499.540 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-29T15:54:59.540Z,1722268499.540 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-29T15:57:44.781Z,1722268664.781 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2024-07-29T15:57:44.781Z,1722268664.781 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-29T15:57:44.791Z,1722268664.791 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-29T15:57:45.203Z,1722268665.203 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-29T15:57:45.203Z,1722268665.203 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2024-07-29T15:59:59.710Z,1722268799.710 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-07-29T15:54:59.5Z
2024-07-29T15:59:59.711Z,1722268799.711 [Default:CheckIn:Read_GPS] Stopped
2024-07-29T15:59:59.711Z,1722268799.711 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-29T16:00:06.820Z,1722268806.820 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240729T153024/Courier0010.lzma
2024-07-29T16:00:07.822Z,1722268807.822 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Courier0010.lzma.bak
2024-07-29T16:00:07.823Z,1722268807.823 [DataOverHttps](INFO): SBD MOMSN=21392342
2024-07-29T16:00:25.837Z,1722268825.837 [DataOverHttps](INFO): Sending 218 bytes from file Logs/20240729T153024/Express0011.lzma
2024-07-29T16:00:26.843Z,1722268826.843 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Express0011.lzma.bak
2024-07-29T16:00:26.843Z,1722268826.843 [DataOverHttps](INFO): SBD MOMSN=21392344
2024-07-29T16:00:28.042Z,1722268828.042 [Default:CheckIn:Read_Iridium] Stopped
2024-07-29T16:00:28.042Z,1722268828.042 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-29T16:00:28.042Z,1722268828.042 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-29T16:00:45.817Z,1722268845.817 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2024-07-29T16:00:45.817Z,1722268845.817 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-29T16:00:45.828Z,1722268845.828 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-29T16:00:46.242Z,1722268846.242 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-29T16:00:46.242Z,1722268846.242 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2024-07-29T16:02:45.379Z,1722268965.379 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-07-29T16:02:45.379Z,1722268965.379 [NAL9602] Data Fault, FailCount= 3
2024-07-29T16:02:45.379Z,1722268965.379 [NAL9602](ERROR): Data Fault
2024-07-29T16:02:45.399Z,1722268965.399 [CBIT](ERROR): Data Fault in component: NAL9602
2024-07-29T16:02:45.787Z,1722268965.787 [NAL9602](INFO): Powering down
2024-07-29T16:02:46.634Z,1722268966.634 [CBIT](INFO): Clearing failed state for component NAL9602
2024-07-29T16:02:46.634Z,1722268966.634 [NAL9602] No Fault, FailCount= 3
2024-07-29T16:03:16.082Z,1722268996.082 [NAL9602](INFO): Powering up NAL9602
2024-07-29T16:03:26.994Z,1722269006.994 [NAL9602](INFO): NAL9602 initialized
2024-07-29T16:03:46.798Z,1722269026.798 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2024-07-29T16:03:46.798Z,1722269026.798 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-29T16:03:46.832Z,1722269026.832 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-29T16:03:47.212Z,1722269027.212 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-29T16:03:47.212Z,1722269027.212 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2024-07-29T16:05:28.606Z,1722269128.606 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-29T16:05:28.606Z,1722269128.606 [Default:CheckIn:C.Wait] Stopped
2024-07-29T16:05:28.606Z,1722269128.606 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-29T16:05:28.606Z,1722269128.606 [Default:CheckIn:D] Running Loop=1
2024-07-29T16:05:29.026Z,1722269129.026 [Default:CheckIn:D] Stopped
2024-07-29T16:05:29.026Z,1722269129.026 [Default:CheckIn:E] Running Loop=1
2024-07-29T16:05:29.439Z,1722269129.439 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.519741 min
2024-07-29T16:05:29.439Z,1722269129.439 [Default:CheckIn:E] Stopped
2024-07-29T16:05:29.439Z,1722269129.439 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-29T16:05:29.439Z,1722269129.439 [Default:CheckIn] Stopped
2024-07-29T16:05:29.439Z,1722269129.439 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-29T16:05:29.439Z,1722269129.439 [Default:CheckIn](INFO): Running loop #4
2024-07-29T16:05:29.440Z,1722269129.440 [Default:CheckIn] Running Loop=4
2024-07-29T16:05:29.440Z,1722269129.440 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-29T16:05:29.440Z,1722269129.440 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-29T16:06:47.801Z,1722269207.801 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2024-07-29T16:06:47.801Z,1722269207.801 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-29T16:06:47.847Z,1722269207.847 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-29T16:06:48.209Z,1722269208.209 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-29T16:06:48.209Z,1722269208.209 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2024-07-29T16:08:02.953Z,1722269282.953 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:08:34.085Z,1722269314.085 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:09:05.221Z,1722269345.221 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:09:36.345Z,1722269376.345 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:09:48.802Z,1722269388.802 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2024-07-29T16:09:48.802Z,1722269388.802 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-29T16:09:48.813Z,1722269388.813 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-29T16:09:49.214Z,1722269389.214 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-29T16:09:49.214Z,1722269389.214 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2024-07-29T16:10:07.473Z,1722269407.473 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:10:11.489Z,1722269411.489 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.759415
2024-07-29T16:10:29.607Z,1722269429.607 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-07-29T16:05:29.4Z
2024-07-29T16:10:29.607Z,1722269429.607 [Default:CheckIn:Read_GPS] Stopped
2024-07-29T16:10:29.607Z,1722269429.607 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-29T16:11:08.737Z,1722269468.737 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:11:39.861Z,1722269499.861 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:12:10.985Z,1722269530.985 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:12:42.133Z,1722269562.133 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:12:49.802Z,1722269569.802 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2024-07-29T16:12:49.802Z,1722269569.802 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-29T16:12:49.813Z,1722269569.813 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-29T16:12:50.191Z,1722269570.191 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-29T16:12:50.191Z,1722269570.191 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2024-07-29T16:13:13.274Z,1722269593.274 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:13:28.954Z,1722269608.954 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-07-29T16:13:28.954Z,1722269608.954 [NAL9602] Data Fault, FailCount= 4
2024-07-29T16:13:28.954Z,1722269608.954 [NAL9602](ERROR): Data Fault
2024-07-29T16:13:28.972Z,1722269608.972 [CBIT](ERROR): Data Fault in component: NAL9602
2024-07-29T16:13:29.359Z,1722269609.359 [NAL9602](INFO): Powering down
2024-07-29T16:13:30.181Z,1722269610.181 [CBIT](INFO): Clearing failed state for component NAL9602
2024-07-29T16:13:30.182Z,1722269610.182 [NAL9602] No Fault, FailCount= 4
2024-07-29T16:13:44.413Z,1722269624.413 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:13:59.658Z,1722269639.658 [NAL9602](INFO): Powering up NAL9602
2024-07-29T16:14:10.570Z,1722269650.570 [NAL9602](INFO): NAL9602 initialized
2024-07-29T16:14:15.537Z,1722269655.537 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:14:46.669Z,1722269686.669 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:15:17.793Z,1722269717.793 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:15:48.933Z,1722269748.933 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:15:50.849Z,1722269750.849 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2024-07-29T16:15:50.849Z,1722269750.849 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-29T16:15:50.892Z,1722269750.892 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-29T16:15:51.196Z,1722269751.196 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-29T16:15:51.196Z,1722269751.196 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2024-07-29T16:16:20.069Z,1722269780.069 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:16:51.205Z,1722269811.205 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:17:22.337Z,1722269842.337 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:17:53.469Z,1722269873.469 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:18:24.617Z,1722269904.617 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:18:51.785Z,1722269931.785 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2024-07-29T16:18:51.785Z,1722269931.785 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-29T16:18:51.795Z,1722269931.795 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-29T16:18:52.201Z,1722269932.201 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-29T16:18:52.201Z,1722269932.201 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2024-07-29T16:18:55.741Z,1722269935.741 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:19:26.873Z,1722269966.873 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:19:58.013Z,1722269998.013 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:20:29.141Z,1722270029.141 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:21:00.269Z,1722270060.269 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:21:31.393Z,1722270091.393 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:21:52.770Z,1722270112.770 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2024-07-29T16:21:52.770Z,1722270112.770 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-29T16:21:52.781Z,1722270112.781 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-29T16:21:53.183Z,1722270113.183 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-29T16:21:53.183Z,1722270113.183 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2024-07-29T16:22:02.538Z,1722270122.538 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:22:33.677Z,1722270153.677 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:23:04.801Z,1722270184.801 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:23:35.929Z,1722270215.929 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:24:07.053Z,1722270247.053 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:24:14.546Z,1722270254.546 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-07-29T16:24:14.546Z,1722270254.546 [NAL9602] Data Fault, FailCount= 5
2024-07-29T16:24:14.546Z,1722270254.546 [NAL9602](ERROR): Data Fault
2024-07-29T16:24:14.564Z,1722270254.564 [CBIT](ERROR): Data Fault in component: NAL9602
2024-07-29T16:24:14.564Z,1722270254.564 [CBIT](CRITICAL): Data Fault in component: NAL9602
2024-07-29T16:24:14.950Z,1722270254.950 [NAL9602](INFO): Powering down
2024-07-29T16:24:14.983Z,1722270254.983 [CommandExec](FAULT): Scheduling is paused
2024-07-29T16:24:14.984Z,1722270254.984 [CBIT](INFO): Critical error at 20240729T162414
2024-07-29T16:24:14.984Z,1722270254.984 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2024-07-29T16:24:38.177Z,1722270278.177 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:24:53.740Z,1722270293.740 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2024-07-29T16:24:53.740Z,1722270293.740 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-29T16:24:53.771Z,1722270293.771 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-29T16:24:54.154Z,1722270294.154 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-29T16:24:54.154Z,1722270294.154 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2024-07-29T16:25:09.302Z,1722270309.302 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:25:40.433Z,1722270340.433 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:26:11.561Z,1722270371.561 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:26:14.551Z,1722270374.551 [CBIT](INFO): Clearing failed state for component NAL9602
2024-07-29T16:26:14.551Z,1722270374.551 [NAL9602] No Fault, FailCount= 5
2024-07-29T16:26:14.943Z,1722270374.943 [NAL9602](INFO): Powering up NAL9602
2024-07-29T16:26:25.843Z,1722270385.843 [NAL9602](INFO): NAL9602 initialized
2024-07-29T16:26:42.685Z,1722270402.685 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:27:13.825Z,1722270433.825 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:27:44.949Z,1722270464.949 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:27:54.765Z,1722270474.765 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2024-07-29T16:27:54.765Z,1722270474.765 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-29T16:27:54.775Z,1722270474.775 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-29T16:27:55.147Z,1722270475.147 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-29T16:27:55.147Z,1722270475.147 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2024-07-29T16:28:16.073Z,1722270496.073 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:28:47.197Z,1722270527.197 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:29:18.321Z,1722270558.321 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:29:49.462Z,1722270589.462 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:30:20.597Z,1722270620.597 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:30:51.721Z,1722270651.721 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:30:55.738Z,1722270655.738 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2024-07-29T16:30:55.738Z,1722270655.738 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-29T16:30:55.769Z,1722270655.769 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-29T16:30:55.769Z,1722270655.769 [CBIT](FAULT): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-29T16:31:22.861Z,1722270682.861 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:31:53.985Z,1722270713.985 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:32:25.117Z,1722270745.117 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T16:32:57.077Z,1722270777.077 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002889
2024-07-29T16:33:04.344Z,1722270784.344 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240729T153024/Courier0013.lzma
2024-07-29T16:33:05.347Z,1722270785.347 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Courier0013.lzma.bak
2024-07-29T16:33:05.347Z,1722270785.347 [DataOverHttps](INFO): SBD MOMSN=21392407
2024-07-29T16:33:24.825Z,1722270804.825 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20240729T153024/Express0014.lzma
2024-07-29T16:33:26.830Z,1722270806.830 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Express0014.lzma.bak
2024-07-29T16:33:26.830Z,1722270806.830 [DataOverHttps](INFO): SBD MOMSN=21392409
2024-07-29T16:33:28.114Z,1722270808.114 [Default:CheckIn:Read_Iridium] Stopped
2024-07-29T16:33:28.114Z,1722270808.114 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-29T16:33:28.114Z,1722270808.114 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-29T16:35:40.614Z,1722270940.614 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T16:35:51.925Z,1722270951.925 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T16:36:03.242Z,1722270963.242 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T16:36:14.557Z,1722270974.557 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T16:36:26.674Z,1722270986.674 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T16:36:27.503Z,1722270987.503 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-07-29T16:36:27.503Z,1722270987.503 [NAL9602] Data Fault, FailCount= 1
2024-07-29T16:36:27.503Z,1722270987.503 [NAL9602](ERROR): Data Fault
2024-07-29T16:36:27.521Z,1722270987.521 [CBIT](ERROR): Data Fault in component: NAL9602
2024-07-29T16:36:27.890Z,1722270987.890 [NAL9602](INFO): Powering down
2024-07-29T16:36:28.719Z,1722270988.719 [CBIT](INFO): Clearing failed state for component NAL9602
2024-07-29T16:36:28.720Z,1722270988.720 [NAL9602] No Fault, FailCount= 1
2024-07-29T16:36:38.394Z,1722270998.394 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T16:36:50.600Z,1722271010.600 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T16:36:58.198Z,1722271018.198 [NAL9602](INFO): Powering up NAL9602
2024-07-29T16:37:03.034Z,1722271023.034 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T16:37:09.103Z,1722271029.103 [NAL9602](INFO): NAL9602 initialized
2024-07-29T16:38:28.718Z,1722271108.718 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-29T16:38:28.718Z,1722271108.718 [Default:CheckIn:C.Wait] Stopped
2024-07-29T16:38:28.718Z,1722271108.718 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-29T16:38:28.718Z,1722271108.718 [Default:CheckIn:D] Running Loop=1
2024-07-29T16:38:29.114Z,1722271109.114 [Default:CheckIn:D] Stopped
2024-07-29T16:38:29.114Z,1722271109.114 [Default:CheckIn:E] Running Loop=1
2024-07-29T16:38:29.506Z,1722271109.506 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 64.521208 min
2024-07-29T16:38:29.506Z,1722271109.506 [Default:CheckIn:E] Stopped
2024-07-29T16:38:29.506Z,1722271109.506 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-29T16:38:29.506Z,1722271109.506 [Default:CheckIn] Stopped
2024-07-29T16:38:29.506Z,1722271109.506 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-29T16:38:29.506Z,1722271109.506 [Default:CheckIn](INFO): Running loop #5
2024-07-29T16:38:29.506Z,1722271109.506 [Default:CheckIn] Running Loop=5
2024-07-29T16:38:29.506Z,1722271109.506 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-29T16:38:29.507Z,1722271109.507 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-29T16:39:38.978Z,1722271178.978 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T16:39:53.117Z,1722271193.117 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T16:40:06.854Z,1722271206.854 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T16:40:20.186Z,1722271220.186 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T16:40:33.517Z,1722271233.517 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T16:40:47.254Z,1722271247.254 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T16:41:00.589Z,1722271260.589 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T16:41:13.922Z,1722271273.922 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T16:41:27.249Z,1722271287.249 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T16:41:41.005Z,1722271301.005 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T16:41:47.109Z,1722271307.109 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-07-29T16:41:53.920Z,1722271313.920 [BPC1](INFO): Calculating totals. Valid battery stick count: 42. Valid reserve battery stick count: 5.
2024-07-29T16:41:53.923Z,1722271313.923 [BPC1](INFO): Received data from all battery sticks.
2024-07-29T16:42:04.828Z,1722271324.828 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164203.00,A,3648.17094,N,12147.27951,W,0.292,0.00,290724,,,A*76
2024-07-29T16:42:04.839Z,1722271324.839 [NAL9602](INFO): GPS fix at 20240729T164203: (36.802849, -121.787992)
2024-07-29T16:42:04.847Z,1722271324.847 [Default:CheckIn:Read_GPS] Stopped
2024-07-29T16:42:04.847Z,1722271324.847 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-29T16:42:10.477Z,1722271330.477 [DataOverHttps](INFO): Sending 290 bytes from file Logs/20240729T153024/Courier0016.lzma
2024-07-29T16:42:10.892Z,1722271330.892 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164209.00,A,3648.17024,N,12147.28112,W,0.661,0.00,290724,,,A*7F
2024-07-29T16:42:10.894Z,1722271330.894 [NAL9602](INFO): GPS fix at 20240729T164209: (36.802837, -121.788019)
2024-07-29T16:42:11.478Z,1722271331.478 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Courier0016.lzma.bak
2024-07-29T16:42:11.479Z,1722271331.479 [DataOverHttps](INFO): SBD MOMSN=21392429
2024-07-29T16:42:13.720Z,1722271333.720 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164212.00,A,3648.17048,N,12147.27969,W,0.097,0.00,290724,,,A*7B
2024-07-29T16:42:13.722Z,1722271333.722 [NAL9602](INFO): GPS fix at 20240729T164212: (36.802841, -121.787995)
2024-07-29T16:42:16.947Z,1722271336.947 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164215.00,A,3648.17030,N,12147.27956,W,0.467,0.00,290724,,,A*74
2024-07-29T16:42:16.950Z,1722271336.950 [NAL9602](INFO): GPS fix at 20240729T164215: (36.802838, -121.787993)
2024-07-29T16:42:19.788Z,1722271339.788 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164218.00,A,3648.16997,N,12147.28082,W,1.322,0.00,290724,,,D*71
2024-07-29T16:42:19.790Z,1722271339.790 [NAL9602](INFO): GPS fix at 20240729T164218: (36.802833, -121.788014)
2024-07-29T16:42:23.016Z,1722271343.016 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164221.00,A,3648.16936,N,12147.28058,W,0.389,0.00,290724,,,D*77
2024-07-29T16:42:23.018Z,1722271343.018 [NAL9602](INFO): GPS fix at 20240729T164221: (36.802823, -121.788010)
2024-07-29T16:42:25.908Z,1722271345.908 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164224.00,A,3648.16947,N,12147.28071,W,0.486,0.00,290724,,,D*77
2024-07-29T16:42:25.911Z,1722271345.911 [NAL9602](INFO): GPS fix at 20240729T164224: (36.802824, -121.788012)
2024-07-29T16:42:29.116Z,1722271349.116 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164227.00,A,3648.16941,N,12147.28088,W,0.292,0.00,290724,,,D*77
2024-07-29T16:42:29.118Z,1722271349.118 [NAL9602](INFO): GPS fix at 20240729T164227: (36.802824, -121.788015)
2024-07-29T16:42:29.781Z,1722271349.781 [DataOverHttps](INFO): Sending 248 bytes from file Logs/20240729T153024/Express0017.lzma
2024-07-29T16:42:30.782Z,1722271350.782 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Express0017.lzma.bak
2024-07-29T16:42:30.782Z,1722271350.782 [DataOverHttps](INFO): SBD MOMSN=21392433
2024-07-29T16:42:31.936Z,1722271351.936 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164230.00,A,3648.16961,N,12147.28071,W,1.127,0.00,290724,,,D*79
2024-07-29T16:42:31.938Z,1722271351.938 [NAL9602](INFO): GPS fix at 20240729T164230: (36.802827, -121.788012)
2024-07-29T16:42:31.946Z,1722271351.946 [Default:CheckIn:Read_Iridium] Stopped
2024-07-29T16:42:31.946Z,1722271351.946 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-29T16:42:31.946Z,1722271351.946 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-29T16:42:34.768Z,1722271354.768 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164233.00,A,3648.16922,N,12147.27974,W,0.078,0.00,290724,,,D*74
2024-07-29T16:42:34.770Z,1722271354.770 [NAL9602](INFO): GPS fix at 20240729T164233: (36.802820, -121.787996)
2024-07-29T16:42:37.996Z,1722271357.996 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164236.00,A,3648.16793,N,12147.27848,W,0.175,0.00,290724,,,D*77
2024-07-29T16:42:37.999Z,1722271357.999 [NAL9602](INFO): GPS fix at 20240729T164236: (36.802799, -121.787975)
2024-07-29T16:42:40.828Z,1722271360.828 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164239.00,A,3648.16735,N,12147.27781,W,0.330,0.00,290724,,,D*7D
2024-07-29T16:42:40.830Z,1722271360.830 [NAL9602](INFO): GPS fix at 20240729T164239: (36.802789, -121.787964)
2024-07-29T16:42:44.053Z,1722271364.053 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164242.00,A,3648.16711,N,12147.27776,W,0.467,0.00,290724,,,D*7A
2024-07-29T16:42:44.055Z,1722271364.055 [NAL9602](INFO): GPS fix at 20240729T164242: (36.802785, -121.787963)
2024-07-29T16:42:46.884Z,1722271366.884 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164245.00,A,3648.16686,N,12147.27766,W,0.505,0.00,290724,,,D*76
2024-07-29T16:42:46.886Z,1722271366.886 [NAL9602](INFO): GPS fix at 20240729T164245: (36.802781, -121.787961)
2024-07-29T16:42:50.117Z,1722271370.117 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164248.00,A,3648.16679,N,12147.27819,W,0.156,0.00,290724,,,D*7E
2024-07-29T16:42:50.119Z,1722271370.119 [NAL9602](INFO): GPS fix at 20240729T164248: (36.802780, -121.787970)
2024-07-29T16:42:52.940Z,1722271372.940 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164251.00,A,3648.16688,N,12147.27894,W,0.272,0.00,290724,,,D*78
2024-07-29T16:42:52.942Z,1722271372.942 [NAL9602](INFO): GPS fix at 20240729T164251: (36.802781, -121.787982)
2024-07-29T16:42:55.774Z,1722271375.774 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164254.00,A,3648.16685,N,12147.27919,W,0.039,0.00,290724,,,D*79
2024-07-29T16:42:55.777Z,1722271375.777 [NAL9602](INFO): GPS fix at 20240729T164254: (36.802781, -121.787987)
2024-07-29T16:42:59.004Z,1722271379.004 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164257.00,A,3648.16691,N,12147.27909,W,0.078,0.00,290724,,,D*7B
2024-07-29T16:42:59.006Z,1722271379.006 [NAL9602](INFO): GPS fix at 20240729T164257: (36.802782, -121.787985)
2024-07-29T16:43:01.832Z,1722271381.832 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164300.00,A,3648.16692,N,12147.27910,W,0.156,0.00,290724,,,D*7E
2024-07-29T16:43:01.834Z,1722271381.834 [NAL9602](INFO): GPS fix at 20240729T164300: (36.802782, -121.787985)
2024-07-29T16:43:05.064Z,1722271385.064 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164303.00,A,3648.16685,N,12147.27908,W,0.194,0.00,290724,,,D*7C
2024-07-29T16:43:05.067Z,1722271385.067 [NAL9602](INFO): GPS fix at 20240729T164303: (36.802781, -121.787985)
2024-07-29T16:43:07.892Z,1722271387.892 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164306.00,A,3648.16675,N,12147.27902,W,0.058,0.00,290724,,,D*7D
2024-07-29T16:43:07.894Z,1722271387.894 [NAL9602](INFO): GPS fix at 20240729T164306: (36.802779, -121.787984)
2024-07-29T16:43:11.124Z,1722271391.124 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164309.00,A,3648.16666,N,12147.27897,W,0.078,0.00,290724,,,D*7F
2024-07-29T16:43:11.136Z,1722271391.136 [NAL9602](INFO): GPS fix at 20240729T164309: (36.802778, -121.787983)
2024-07-29T16:43:13.953Z,1722271393.953 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164312.00,A,3648.16633,N,12147.27862,W,0.525,0.00,290724,,,D*72
2024-07-29T16:43:13.955Z,1722271393.955 [NAL9602](INFO): GPS fix at 20240729T164312: (36.802772, -121.787977)
2024-07-29T16:43:16.780Z,1722271396.780 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164315.00,A,3648.16666,N,12147.27897,W,0.486,0.00,290724,,,D*77
2024-07-29T16:43:16.782Z,1722271396.782 [NAL9602](INFO): GPS fix at 20240729T164315: (36.802778, -121.787983)
2024-07-29T16:43:20.010Z,1722271400.010 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164318.00,A,3648.16668,N,12147.27912,W,0.039,0.00,290724,,,D*78
2024-07-29T16:43:20.012Z,1722271400.012 [NAL9602](INFO): GPS fix at 20240729T164318: (36.802778, -121.787985)
2024-07-29T16:43:22.840Z,1722271402.840 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164321.00,A,3648.16697,N,12147.27951,W,0.486,0.00,290724,,,D*75
2024-07-29T16:43:22.849Z,1722271402.849 [NAL9602](INFO): GPS fix at 20240729T164321: (36.802783, -121.787992)
2024-07-29T16:43:26.099Z,1722271406.099 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164324.00,A,3648.16651,N,12147.27961,W,0.233,0.00,290724,,,D*71
2024-07-29T16:43:26.102Z,1722271406.102 [NAL9602](INFO): GPS fix at 20240729T164324: (36.802775, -121.787993)
2024-07-29T16:43:28.900Z,1722271408.900 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164327.00,A,3648.16645,N,12147.27989,W,0.292,0.00,290724,,,D*7A
2024-07-29T16:43:28.902Z,1722271408.902 [NAL9602](INFO): GPS fix at 20240729T164327: (36.802774, -121.787998)
2024-07-29T16:43:32.132Z,1722271412.132 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164330.00,A,3648.16658,N,12147.28023,W,0.097,0.00,290724,,,D*71
2024-07-29T16:43:32.134Z,1722271412.134 [NAL9602](INFO): GPS fix at 20240729T164330: (36.802776, -121.788004)
2024-07-29T16:43:34.955Z,1722271414.955 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164333.00,A,3648.16654,N,12147.28019,W,0.078,0.00,290724,,,D*76
2024-07-29T16:43:34.958Z,1722271414.958 [NAL9602](INFO): GPS fix at 20240729T164333: (36.802776, -121.788003)
2024-07-29T16:43:37.792Z,1722271417.792 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164336.00,A,3648.16680,N,12147.28088,W,0.486,0.00,290724,,,A*72
2024-07-29T16:43:37.794Z,1722271417.794 [NAL9602](INFO): GPS fix at 20240729T164336: (36.802780, -121.788015)
2024-07-29T16:43:41.020Z,1722271421.020 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164339.00,A,3648.16675,N,12147.28113,W,0.214,0.00,290724,,,A*79
2024-07-29T16:43:41.022Z,1722271421.022 [NAL9602](INFO): GPS fix at 20240729T164339: (36.802779, -121.788019)
2024-07-29T16:43:43.848Z,1722271423.848 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164342.00,A,3648.16678,N,12147.28116,W,0.272,0.00,290724,,,A*7D
2024-07-29T16:43:43.850Z,1722271423.850 [NAL9602](INFO): GPS fix at 20240729T164342: (36.802780, -121.788019)
2024-07-29T16:43:47.073Z,1722271427.073 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164345.00,A,3648.16672,N,12147.28104,W,0.214,0.00,290724,,,A*73
2024-07-29T16:43:47.075Z,1722271427.075 [NAL9602](INFO): GPS fix at 20240729T164345: (36.802779, -121.788017)
2024-07-29T16:43:49.910Z,1722271429.910 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164348.00,A,3648.16669,N,12147.28131,W,0.233,0.00,290724,,,A*77
2024-07-29T16:43:49.912Z,1722271429.912 [NAL9602](INFO): GPS fix at 20240729T164348: (36.802778, -121.788022)
2024-07-29T16:43:52.736Z,1722271432.736 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164351.00,A,3648.16690,N,12147.28127,W,0.117,0.00,290724,,,A*7B
2024-07-29T16:43:52.738Z,1722271432.738 [NAL9602](INFO): GPS fix at 20240729T164351: (36.802782, -121.788021)
2024-07-29T16:43:55.961Z,1722271435.961 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164354.00,A,3648.16747,N,12147.28151,W,0.389,0.00,290724,,,A*71
2024-07-29T16:43:55.963Z,1722271435.963 [NAL9602](INFO): GPS fix at 20240729T164354: (36.802791, -121.788025)
2024-07-29T16:43:58.796Z,1722271438.796 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164357.00,A,3648.16824,N,12147.28157,W,0.972,0.00,290724,,,A*70
2024-07-29T16:43:58.798Z,1722271438.798 [NAL9602](INFO): GPS fix at 20240729T164357: (36.802804, -121.788026)
2024-07-29T16:44:02.028Z,1722271442.028 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164400.00,A,3648.16909,N,12147.28176,W,1.030,0.00,290724,,,A*76
2024-07-29T16:44:02.030Z,1722271442.030 [NAL9602](INFO): GPS fix at 20240729T164400: (36.802818, -121.788029)
2024-07-29T16:44:04.852Z,1722271444.852 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164403.00,A,3648.16914,N,12147.28154,W,0.175,0.00,290724,,,A*78
2024-07-29T16:44:04.854Z,1722271444.854 [NAL9602](INFO): GPS fix at 20240729T164403: (36.802819, -121.788026)
2024-07-29T16:44:08.092Z,1722271448.092 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164406.00,A,3648.16876,N,12147.28148,W,0.350,0.00,290724,,,A*70
2024-07-29T16:44:08.094Z,1722271448.094 [NAL9602](INFO): GPS fix at 20240729T164406: (36.802813, -121.788025)
2024-07-29T16:44:10.916Z,1722271450.916 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164409.00,A,3648.16965,N,12147.28145,W,1.244,0.00,290724,,,A*74
2024-07-29T16:44:10.918Z,1722271450.918 [NAL9602](INFO): GPS fix at 20240729T164409: (36.802827, -121.788024)
2024-07-29T16:44:14.152Z,1722271454.152 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164412.00,A,3648.16955,N,12147.28149,W,0.330,0.00,290724,,,A*72
2024-07-29T16:44:14.161Z,1722271454.161 [NAL9602](INFO): GPS fix at 20240729T164412: (36.802826, -121.788025)
2024-07-29T16:44:16.972Z,1722271456.972 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164415.00,A,3648.16955,N,12147.28191,W,0.428,0.00,290724,,,A*7E
2024-07-29T16:44:16.974Z,1722271456.974 [NAL9602](INFO): GPS fix at 20240729T164415: (36.802826, -121.788032)
2024-07-29T16:44:19.800Z,1722271459.800 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164418.00,A,3648.16916,N,12147.28191,W,0.194,0.00,290724,,,A*76
2024-07-29T16:44:19.802Z,1722271459.802 [NAL9602](INFO): GPS fix at 20240729T164418: (36.802819, -121.788032)
2024-07-29T16:44:23.032Z,1722271463.032 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164421.00,A,3648.16882,N,12147.28170,W,0.214,0.00,290724,,,A*74
2024-07-29T16:44:23.034Z,1722271463.034 [NAL9602](INFO): GPS fix at 20240729T164421: (36.802814, -121.788028)
2024-07-29T16:44:25.860Z,1722271465.860 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164424.00,A,3648.16864,N,12147.28172,W,0.311,0.00,290724,,,A*7F
2024-07-29T16:44:25.862Z,1722271465.862 [NAL9602](INFO): GPS fix at 20240729T164424: (36.802811, -121.788029)
2024-07-29T16:44:29.093Z,1722271469.093 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164427.00,A,3648.16724,N,12147.28206,W,2.080,0.00,290724,,,A*7E
2024-07-29T16:44:29.095Z,1722271469.095 [NAL9602](INFO): GPS fix at 20240729T164427: (36.802787, -121.788034)
2024-07-29T16:44:31.928Z,1722271471.928 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164430.00,A,3648.16637,N,12147.28218,W,1.030,0.00,290724,,,A*7C
2024-07-29T16:44:31.930Z,1722271471.930 [NAL9602](INFO): GPS fix at 20240729T164430: (36.802773, -121.788036)
2024-07-29T16:44:34.752Z,1722271474.752 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164433.00,A,3648.16591,N,12147.28187,W,0.330,0.00,290724,,,A*77
2024-07-29T16:44:34.754Z,1722271474.754 [NAL9602](INFO): GPS fix at 20240729T164433: (36.802765, -121.788031)
2024-07-29T16:44:37.980Z,1722271477.980 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164436.00,A,3648.16582,N,12147.28179,W,0.039,0.00,290724,,,A*7B
2024-07-29T16:44:37.982Z,1722271477.982 [NAL9602](INFO): GPS fix at 20240729T164436: (36.802764, -121.788030)
2024-07-29T16:44:40.808Z,1722271480.808 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164439.00,A,3648.16640,N,12147.28211,W,0.389,0.00,290724,,,A*7C
2024-07-29T16:44:40.810Z,1722271480.810 [NAL9602](INFO): GPS fix at 20240729T164439: (36.802773, -121.788035)
2024-07-29T16:44:44.044Z,1722271484.044 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164442.00,A,3648.16683,N,12147.28229,W,0.408,0.00,290724,,,A*7A
2024-07-29T16:44:44.053Z,1722271484.053 [NAL9602](INFO): GPS fix at 20240729T164442: (36.802780, -121.788038)
2024-07-29T16:44:46.876Z,1722271486.876 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164445.00,A,3648.16726,N,12147.28218,W,0.389,0.00,290724,,,A*7F
2024-07-29T16:44:46.878Z,1722271486.878 [NAL9602](INFO): GPS fix at 20240729T164445: (36.802788, -121.788036)
2024-07-29T16:44:50.100Z,1722271490.100 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164448.00,A,3648.16709,N,12147.28153,W,0.272,0.00,290724,,,A*76
2024-07-29T16:44:50.102Z,1722271490.102 [NAL9602](INFO): GPS fix at 20240729T164448: (36.802785, -121.788026)
2024-07-29T16:44:52.928Z,1722271492.928 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164451.00,A,3648.16719,N,12147.28163,W,0.233,0.00,290724,,,A*79
2024-07-29T16:44:52.930Z,1722271492.930 [NAL9602](INFO): GPS fix at 20240729T164451: (36.802787, -121.788027)
2024-07-29T16:44:55.760Z,1722271495.760 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164454.00,A,3648.16687,N,12147.28182,W,0.350,0.00,290724,,,A*71
2024-07-29T16:44:55.762Z,1722271495.762 [NAL9602](INFO): GPS fix at 20240729T164454: (36.802781, -121.788030)
2024-07-29T16:44:58.988Z,1722271498.988 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164457.00,A,3648.16808,N,12147.28212,W,1.205,0.00,290724,,,A*71
2024-07-29T16:44:58.990Z,1722271498.990 [NAL9602](INFO): GPS fix at 20240729T164457: (36.802801, -121.788035)
2024-07-29T16:45:01.820Z,1722271501.820 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164500.00,A,3648.16814,N,12147.28227,W,0.330,0.00,290724,,,A*7F
2024-07-29T16:45:01.822Z,1722271501.822 [NAL9602](INFO): GPS fix at 20240729T164500: (36.802802, -121.788038)
2024-07-29T16:45:05.052Z,1722271505.052 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164503.00,A,3648.16867,N,12147.28357,W,1.263,0.00,290724,,,A*78
2024-07-29T16:45:05.054Z,1722271505.054 [NAL9602](INFO): GPS fix at 20240729T164503: (36.802811, -121.788060)
2024-07-29T16:45:07.876Z,1722271507.876 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164506.00,A,3648.16891,N,12147.28430,W,0.816,0.00,290724,,,A*7B
2024-07-29T16:45:07.878Z,1722271507.878 [NAL9602](INFO): GPS fix at 20240729T164506: (36.802815, -121.788072)
2024-07-29T16:45:11.111Z,1722271511.111 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164509.00,A,3648.16909,N,12147.28418,W,0.233,0.00,290724,,,A*73
2024-07-29T16:45:11.114Z,1722271511.114 [NAL9602](INFO): GPS fix at 20240729T164509: (36.802818, -121.788070)
2024-07-29T16:45:13.940Z,1722271513.940 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164512.00,A,3648.16995,N,12147.28303,W,0.428,0.00,290724,,,A*7D
2024-07-29T16:45:13.942Z,1722271513.942 [NAL9602](INFO): GPS fix at 20240729T164512: (36.802833, -121.788050)
2024-07-29T16:45:16.768Z,1722271516.768 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164515.00,A,3648.17191,N,12147.28310,W,1.400,337.87,290724,,,A*76
2024-07-29T16:45:16.770Z,1722271516.770 [NAL9602](INFO): GPS fix at 20240729T164515: (36.802865, -121.788052)
2024-07-29T16:45:19.000Z,1722271520.000 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164518.00,A,3648.17185,N,12147.28229,W,0.330,337.87,290724,,,A*70
2024-07-29T16:45:20.002Z,1722271520.002 [NAL9602](INFO): GPS fix at 20240729T164518: (36.802864, -121.788038)
2024-07-29T16:45:22.828Z,1722271522.828 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164521.00,A,3648.17168,N,12147.28172,W,0.233,337.87,290724,,,A*76
2024-07-29T16:45:22.830Z,1722271522.830 [NAL9602](INFO): GPS fix at 20240729T164521: (36.802861, -121.788029)
2024-07-29T16:45:26.060Z,1722271526.060 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164524.00,A,3648.17183,N,12147.28160,W,0.039,337.87,290724,,,A*7D
2024-07-29T16:45:26.062Z,1722271526.062 [NAL9602](INFO): GPS fix at 20240729T164524: (36.802864, -121.788027)
2024-07-29T16:45:28.892Z,1722271528.892 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164527.00,A,3648.17243,N,12147.28127,W,0.078,337.87,290724,,,A*77
2024-07-29T16:45:28.895Z,1722271528.895 [NAL9602](INFO): GPS fix at 20240729T164527: (36.802874, -121.788021)
2024-07-29T16:45:32.120Z,1722271532.120 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164530.00,A,3648.17267,N,12147.28108,W,0.058,337.87,290724,,,A*78
2024-07-29T16:45:32.123Z,1722271532.123 [NAL9602](INFO): GPS fix at 20240729T164530: (36.802878, -121.788018)
2024-07-29T16:45:34.948Z,1722271534.948 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164533.00,A,3648.17303,N,12147.28091,W,0.097,337.87,290724,,,A*7A
2024-07-29T16:45:34.950Z,1722271534.950 [NAL9602](INFO): GPS fix at 20240729T164533: (36.802884, -121.788015)
2024-07-29T16:45:37.772Z,1722271537.772 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164536.00,A,3648.17269,N,12147.28115,W,0.272,337.87,290724,,,A*76
2024-07-29T16:45:37.774Z,1722271537.774 [NAL9602](INFO): GPS fix at 20240729T164536: (36.802878, -121.788019)
2024-07-29T16:45:41.001Z,1722271541.001 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164539.00,A,3648.17291,N,12147.28067,W,0.194,337.87,290724,,,A*71
2024-07-29T16:45:41.003Z,1722271541.003 [NAL9602](INFO): GPS fix at 20240729T164539: (36.802882, -121.788011)
2024-07-29T16:45:43.837Z,1722271543.837 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164542.00,A,3648.17425,N,12147.28074,W,1.322,337.87,290724,,,A*78
2024-07-29T16:45:43.839Z,1722271543.839 [NAL9602](INFO): GPS fix at 20240729T164542: (36.802904, -121.788012)
2024-07-29T16:45:47.068Z,1722271547.068 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164545.00,A,3648.17440,N,12147.28002,W,0.894,348.51,290724,,,A*79
2024-07-29T16:45:47.070Z,1722271547.070 [NAL9602](INFO): GPS fix at 20240729T164545: (36.802907, -121.788000)
2024-07-29T16:45:49.896Z,1722271549.896 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164548.00,A,3648.17525,N,12147.27944,W,1.438,348.51,290724,,,A*79
2024-07-29T16:45:49.898Z,1722271549.898 [NAL9602](INFO): GPS fix at 20240729T164548: (36.802921, -121.787991)
2024-07-29T16:45:53.128Z,1722271553.128 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164551.00,A,3648.17620,N,12147.27781,W,1.672,23.59,290724,,,A*4A
2024-07-29T16:45:53.130Z,1722271553.130 [NAL9602](INFO): GPS fix at 20240729T164551: (36.802937, -121.787964)
2024-07-29T16:45:55.961Z,1722271555.961 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164554.00,A,3648.17606,N,12147.27680,W,0.719,23.59,290724,,,A*46
2024-07-29T16:45:55.963Z,1722271555.963 [NAL9602](INFO): GPS fix at 20240729T164554: (36.802934, -121.787947)
2024-07-29T16:45:55.981Z,1722271555.981 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-29T16:45:55.981Z,1722271555.981 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20
2024-07-29T16:45:56.772Z,1722271556.772 [DeadReckonUsingMultipleVelocitySources](INFO): Failure count cleared after critical for DeadReckonUsingMultipleVelocitySources
2024-07-29T16:46:28.273Z,1722271588.273 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-29T16:47:32.524Z,1722271652.524 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-29T16:47:32.524Z,1722271652.524 [Default:CheckIn:C.Wait] Stopped
2024-07-29T16:47:32.524Z,1722271652.524 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-29T16:47:32.524Z,1722271652.524 [Default:CheckIn:D] Running Loop=1
2024-07-29T16:47:32.933Z,1722271652.933 [Default:CheckIn:D] Stopped
2024-07-29T16:47:32.933Z,1722271652.933 [Default:CheckIn:E] Running Loop=1
2024-07-29T16:47:33.342Z,1722271653.342 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.584847 min
2024-07-29T16:47:33.342Z,1722271653.342 [Default:CheckIn:E] Stopped
2024-07-29T16:47:33.342Z,1722271653.342 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-29T16:47:33.343Z,1722271653.343 [Default:CheckIn] Stopped
2024-07-29T16:47:33.343Z,1722271653.343 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-29T16:47:33.343Z,1722271653.343 [Default:CheckIn](INFO): Running loop #6
2024-07-29T16:47:33.343Z,1722271653.343 [Default:CheckIn] Running Loop=6
2024-07-29T16:47:33.343Z,1722271653.343 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-29T16:47:33.343Z,1722271653.343 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-29T16:47:35.339Z,1722271655.339 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164734.00,A,3648.13899,N,12147.23661,W,1.555,101.62,290724,,,D*72
2024-07-29T16:47:35.342Z,1722271655.342 [NAL9602](INFO): GPS fix at 20240729T164734: (36.802317, -121.787277)
2024-07-29T16:47:35.369Z,1722271655.369 [Default:CheckIn:Read_GPS] Stopped
2024-07-29T16:47:35.369Z,1722271655.369 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-29T16:47:42.160Z,1722271662.160 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240729T153024/Courier0019.lzma
2024-07-29T16:47:43.162Z,1722271663.162 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Courier0019.lzma.bak
2024-07-29T16:47:43.162Z,1722271663.162 [DataOverHttps](INFO): SBD MOMSN=21392444
2024-07-29T16:47:49.079Z,1722271669.079 [NAL9602](INFO): SBD MO Status=0, MOMSN=53434, MT Status=0, MTMSN=0
2024-07-29T16:47:49.079Z,1722271669.079 [NAL9602](INFO): No messages in MT queue
2024-07-29T16:47:59.081Z,1722271679.081 [DataOverHttps](INFO): Sending 270 bytes from file Logs/20240729T153024/Express0020.lzma
2024-07-29T16:48:00.082Z,1722271680.082 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Express0020.lzma.bak
2024-07-29T16:48:00.083Z,1722271680.083 [DataOverHttps](INFO): SBD MOMSN=21392447
2024-07-29T16:48:01.217Z,1722271681.217 [Default:CheckIn:Read_Iridium] Stopped
2024-07-29T16:48:01.217Z,1722271681.217 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-29T16:48:01.217Z,1722271681.217 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-29T16:48:19.781Z,1722271699.781 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-29T16:51:11.914Z,1722271871.914 [DataOverHttps](IMPORTANT): SBD MTMSN=20240729T165110
2024-07-29T16:51:19.245Z,1722271879.245 [DataOverHttps](INFO): Received command: ibit
2024-07-29T16:51:19.309Z,1722271879.309 [CommandExec](IMPORTANT): got command ibit
2024-07-29T16:51:19.584Z,1722271879.584 [IBIT](IMPORTANT): Beginning Initiated BIT
2024-07-29T16:51:19.584Z,1722271879.584 [IBIT](IMPORTANT): Beginning control surface checks.
2024-07-29T16:51:19.587Z,1722271879.587 [CBIT](IMPORTANT): Beginning ground fault scan
2024-07-29T16:51:21.174Z,1722271881.174 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165120.00,A,3648.14059,N,12147.22770,W,0.000,58.07,290724,,,D*49
2024-07-29T16:51:21.176Z,1722271881.176 [NAL9602](INFO): GPS fix at 20240729T165120: (36.802343, -121.787128)
2024-07-29T16:51:30.486Z,1722271890.486 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-07-29T16:51:30.894Z,1722271890.894 [CBIT](IMPORTANT): Beginning ground fault scan
2024-07-29T16:51:36.572Z,1722271896.572 [NAL9602](INFO): SBD MO Status=0, MOMSN=53435, MT Status=0, MTMSN=0
2024-07-29T16:51:36.572Z,1722271896.572 [NAL9602](INFO): No messages in MT queue
2024-07-29T16:51:36.573Z,1722271896.573 [NAL9602](INFO): Failure count cleared after critical for NAL9602
2024-07-29T16:51:37.740Z,1722271897.740 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165137.00,A,3648.14057,N,12147.22763,W,0.039,58.07,290724,,,D*49
2024-07-29T16:51:37.743Z,1722271897.743 [NAL9602](INFO): GPS fix at 20240729T165137: (36.802343, -121.787127)
2024-07-29T16:51:40.976Z,1722271900.976 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165140.00,A,3648.14051,N,12147.22760,W,0.019,58.07,290724,,,D*4E
2024-07-29T16:51:40.979Z,1722271900.979 [NAL9602](INFO): GPS fix at 20240729T165140: (36.802342, -121.787127)
2024-07-29T16:51:41.868Z,1722271901.868 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002405
CHAN A1 (24V): 0.000230
CHAN A2 (12V): -0.002509
CHAN A3 (5V): -0.001545
CHAN B0 (3.3V): -0.000169
CHAN B1 (3.15aV): -0.000516
CHAN B2 (3.15bV): -0.000399
CHAN B3 (GND): 0.000618
OPEN: -0.000097
Full Scale: +/- 1 mA
2024-07-29T16:51:44.203Z,1722271904.203 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165143.00,A,3648.14052,N,12147.22764,W,0.019,58.07,290724,,,D*4A
2024-07-29T16:51:44.206Z,1722271904.206 [NAL9602](INFO): GPS fix at 20240729T165143: (36.802342, -121.787127)
2024-07-29T16:51:47.046Z,1722271907.046 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165146.00,A,3648.14056,N,12147.22767,W,0.058,58.07,290724,,,D*4D
2024-07-29T16:51:47.048Z,1722271907.048 [NAL9602](INFO): GPS fix at 20240729T165146: (36.802343, -121.787128)
2024-07-29T16:51:49.863Z,1722271909.863 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165149.00,A,3648.14066,N,12147.22772,W,0.058,58.07,290724,,,D*45
2024-07-29T16:51:49.866Z,1722271909.866 [NAL9602](INFO): GPS fix at 20240729T165149: (36.802344, -121.787129)
2024-07-29T16:51:51.002Z,1722271911.002 [DataOverHttps](IMPORTANT): SBD MTMSN=20240729T165150
2024-07-29T16:51:53.091Z,1722271913.091 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165152.00,A,3648.14073,N,12147.22773,W,0.019,58.07,290724,,,D*4F
2024-07-29T16:51:53.093Z,1722271913.093 [NAL9602](INFO): GPS fix at 20240729T165152: (36.802346, -121.787129)
2024-07-29T16:51:55.919Z,1722271915.919 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165155.00,A,3648.14077,N,12147.22772,W,0.058,58.07,290724,,,D*48
2024-07-29T16:51:55.922Z,1722271915.922 [NAL9602](INFO): GPS fix at 20240729T165155: (36.802346, -121.787129)
2024-07-29T16:51:58.892Z,1722271918.892 [DataOverHttps](INFO): Received command: failc
2024-07-29T16:51:58.905Z,1722271918.905 [CommandExec](IMPORTANT): got command failComponent
2024-07-29T16:51:58.905Z,1722271918.905 [CommandExec](IMPORTANT): Failed components:
2024-07-29T16:51:58.906Z,1722271918.906 [CommandExec](IMPORTANT): No failed Components.
2024-07-29T16:51:59.160Z,1722271919.160 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165158.00,A,3648.14077,N,12147.22764,W,0.019,58.07,290724,,,D*47
2024-07-29T16:51:59.170Z,1722271919.170 [NAL9602](INFO): GPS fix at 20240729T165158: (36.802346, -121.787127)
2024-07-29T16:52:01.991Z,1722271921.991 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165201.00,A,3648.14076,N,12147.22752,W,0.019,58.07,290724,,,D*4C
2024-07-29T16:52:01.994Z,1722271921.994 [NAL9602](INFO): GPS fix at 20240729T165201: (36.802346, -121.787125)
2024-07-29T16:52:05.214Z,1722271925.214 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165204.00,A,3648.14074,N,12147.22743,W,0.039,58.07,290724,,,D*49
2024-07-29T16:52:05.217Z,1722271925.217 [NAL9602](INFO): GPS fix at 20240729T165204: (36.802346, -121.787124)
2024-07-29T16:52:06.044Z,1722271926.044 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 3
Latitude: 36.802345 Longitude: -121.787125
2024-07-29T16:52:06.455Z,1722271926.455 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.611000
2024-07-29T16:52:06.455Z,1722271926.455 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2024-07-29T16:52:06.455Z,1722271926.455 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2024-07-29T16:52:06.864Z,1722271926.864 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2024-07-29T16:52:06.865Z,1722271926.865 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2024-07-29T16:52:06.865Z,1722271926.865 [IBIT](IMPORTANT): Pressure:10.283246 PSI
2024-07-29T16:52:06.865Z,1722271926.865 [IBIT](IMPORTANT): Humidity:14.528140 %
2024-07-29T16:52:07.254Z,1722271927.254 [IBIT](IMPORTANT): Vehicle Pitch:-0.962307 degrees
2024-07-29T16:52:07.254Z,1722271927.254 [IBIT](IMPORTANT): Vehicle Roll:-1.905992 degrees
2024-07-29T16:52:07.254Z,1722271927.254 [IBIT](IMPORTANT): Vehicle Heading:14.561047 degrees
2024-07-29T16:52:07.658Z,1722271927.658 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2024-07-29T16:52:07.659Z,1722271927.659 [IBIT](IMPORTANT): buoyancyNeutral: 324.584595 cc
2024-07-29T16:52:07.659Z,1722271927.659 [IBIT](IMPORTANT): massDefault: -0.128062 cm
2024-07-29T16:52:07.659Z,1722271927.659 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2024-07-29T16:52:07.660Z,1722271927.660 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2024-07-29T16:52:07.660Z,1722271927.660 [IBIT](IMPORTANT): IBIT PASSED
2024-07-29T16:52:37.538Z,1722271957.538 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-29T16:53:01.792Z,1722271981.792 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-29T16:53:01.793Z,1722271981.793 [Default:CheckIn:C.Wait] Stopped
2024-07-29T16:53:01.793Z,1722271981.793 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-29T16:53:01.793Z,1722271981.793 [Default:CheckIn:D] Running Loop=1
2024-07-29T16:53:02.192Z,1722271982.192 [Default:CheckIn:D] Stopped
2024-07-29T16:53:02.192Z,1722271982.192 [Default:CheckIn:E] Running Loop=1
2024-07-29T16:53:02.592Z,1722271982.592 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 79.072502 min
2024-07-29T16:53:02.592Z,1722271982.592 [Default:CheckIn:E] Stopped
2024-07-29T16:53:02.592Z,1722271982.592 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-29T16:53:02.592Z,1722271982.592 [Default:CheckIn] Stopped
2024-07-29T16:53:02.592Z,1722271982.592 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-29T16:53:02.592Z,1722271982.592 [Default:CheckIn](INFO): Running loop #7
2024-07-29T16:53:02.592Z,1722271982.592 [Default:CheckIn] Running Loop=7
2024-07-29T16:53:02.593Z,1722271982.593 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-29T16:53:02.593Z,1722271982.593 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-29T16:53:04.604Z,1722271984.604 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165303.00,A,3648.14044,N,12147.22599,W,0.019,58.07,290724,,,D*4B
2024-07-29T16:53:04.606Z,1722271984.606 [NAL9602](INFO): GPS fix at 20240729T165303: (36.802341, -121.787100)
2024-07-29T16:53:04.617Z,1722271984.617 [Default:CheckIn:Read_GPS] Stopped
2024-07-29T16:53:04.617Z,1722271984.617 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-29T16:53:12.037Z,1722271992.037 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240729T153024/Courier0022.lzma
2024-07-29T16:53:13.038Z,1722271993.038 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Courier0022.lzma.bak
2024-07-29T16:53:13.038Z,1722271993.038 [DataOverHttps](INFO): SBD MOMSN=21392464
2024-07-29T16:53:28.942Z,1722272008.942 [DataOverHttps](INFO): Sending 858 bytes from file Logs/20240729T153024/Express0023.lzma
2024-07-29T16:53:29.942Z,1722272009.942 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Express0023.lzma.bak
2024-07-29T16:53:29.943Z,1722272009.943 [DataOverHttps](INFO): SBD MOMSN=21392467
2024-07-29T16:53:36.927Z,1722272016.927 [NAL9602](INFO): SBD MO Status=0, MOMSN=53436, MT Status=0, MTMSN=0
2024-07-29T16:53:36.927Z,1722272016.927 [NAL9602](INFO): No messages in MT queue
2024-07-29T16:53:45.804Z,1722272025.804 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20240729T153024/Express0026.lzma
2024-07-29T16:53:46.807Z,1722272026.807 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Express0026.lzma.bak
2024-07-29T16:53:46.807Z,1722272026.807 [DataOverHttps](INFO): SBD MOMSN=21392498
2024-07-29T16:53:47.844Z,1722272027.844 [Default:CheckIn:Read_Iridium] Stopped
2024-07-29T16:53:47.844Z,1722272027.844 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-29T16:53:47.844Z,1722272027.844 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-29T16:54:07.629Z,1722272047.629 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-29T16:58:48.621Z,1722272328.621 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-29T16:58:48.621Z,1722272328.621 [Default:CheckIn:C.Wait] Stopped
2024-07-29T16:58:48.621Z,1722272328.621 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-29T16:58:48.621Z,1722272328.621 [Default:CheckIn:D] Running Loop=1
2024-07-29T16:58:49.000Z,1722272329.000 [Default:CheckIn:D] Stopped
2024-07-29T16:58:49.000Z,1722272329.000 [Default:CheckIn:E] Running Loop=1
2024-07-29T16:58:49.408Z,1722272329.408 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 84.852645 min
2024-07-29T16:58:49.409Z,1722272329.409 [Default:CheckIn:E] Stopped
2024-07-29T16:58:49.409Z,1722272329.409 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-29T16:58:49.409Z,1722272329.409 [Default:CheckIn] Stopped
2024-07-29T16:58:49.409Z,1722272329.409 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-29T16:58:49.409Z,1722272329.409 [Default:CheckIn](INFO): Running loop #8
2024-07-29T16:58:49.409Z,1722272329.409 [Default:CheckIn] Running Loop=8
2024-07-29T16:58:49.409Z,1722272329.409 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-29T16:58:49.409Z,1722272329.409 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-29T16:58:51.407Z,1722272331.407 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165850.00,A,3648.14179,N,12147.22835,W,0.019,25.66,290724,,,D*4F
2024-07-29T16:58:51.410Z,1722272331.410 [NAL9602](INFO): GPS fix at 20240729T165850: (36.802363, -121.787139)
2024-07-29T16:58:51.469Z,1722272331.469 [Default:CheckIn:Read_GPS] Stopped
2024-07-29T16:58:51.469Z,1722272331.469 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-29T16:58:59.852Z,1722272339.852 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240729T153024/Courier0028.lzma
2024-07-29T16:59:00.854Z,1722272340.854 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Courier0028.lzma.bak
2024-07-29T16:59:00.855Z,1722272340.855 [DataOverHttps](INFO): SBD MOMSN=21392504
2024-07-29T16:59:01.955Z,1722272341.955 [NAL9602](INFO): SBD MO Status=0, MOMSN=53437, MT Status=0, MTMSN=0
2024-07-29T16:59:01.956Z,1722272341.956 [NAL9602](INFO): No messages in MT queue
2024-07-29T16:59:16.716Z,1722272356.716 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240729T153024/Express0029.lzma
2024-07-29T16:59:17.719Z,1722272357.719 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Express0029.lzma.bak
2024-07-29T16:59:17.719Z,1722272357.719 [DataOverHttps](INFO): SBD MOMSN=21392509
2024-07-29T16:59:18.920Z,1722272358.920 [Default:CheckIn:Read_Iridium] Stopped
2024-07-29T16:59:18.920Z,1722272358.920 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-29T16:59:18.920Z,1722272358.920 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-29T16:59:32.645Z,1722272372.645 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-29T17:04:19.640Z,1722272659.640 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-29T17:04:19.641Z,1722272659.641 [Default:CheckIn:C.Wait] Stopped
2024-07-29T17:04:19.641Z,1722272659.641 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-29T17:04:19.641Z,1722272659.641 [Default:CheckIn:D] Running Loop=1
2024-07-29T17:04:20.049Z,1722272660.049 [Default:CheckIn:D] Stopped
2024-07-29T17:04:20.049Z,1722272660.049 [Default:CheckIn:E] Running Loop=1
2024-07-29T17:04:20.460Z,1722272660.460 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 90.370109 min
2024-07-29T17:04:20.460Z,1722272660.460 [Default:CheckIn:E] Stopped
2024-07-29T17:04:20.460Z,1722272660.460 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-29T17:04:20.460Z,1722272660.460 [Default:CheckIn] Stopped
2024-07-29T17:04:20.460Z,1722272660.460 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-29T17:04:20.469Z,1722272660.469 [Default:CheckIn](INFO): Running loop #9
2024-07-29T17:04:20.469Z,1722272660.469 [Default:CheckIn] Running Loop=9
2024-07-29T17:04:20.469Z,1722272660.469 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-29T17:04:20.469Z,1722272660.469 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-29T17:04:22.452Z,1722272662.452 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170421.00,A,3648.14180,N,12147.22937,W,0.058,25.66,290724,,,D*41
2024-07-29T17:04:22.454Z,1722272662.454 [NAL9602](INFO): GPS fix at 20240729T170421: (36.802363, -121.787156)
2024-07-29T17:04:22.474Z,1722272662.474 [Default:CheckIn:Read_GPS] Stopped
2024-07-29T17:04:22.474Z,1722272662.474 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-29T17:04:30.192Z,1722272670.192 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240729T153024/Courier0031.lzma
2024-07-29T17:04:31.194Z,1722272671.194 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Courier0031.lzma.bak
2024-07-29T17:04:31.195Z,1722272671.195 [DataOverHttps](INFO): SBD MOMSN=21392522
2024-07-29T17:04:40.631Z,1722272680.631 [NAL9602](INFO): SBD MO Status=0, MOMSN=53438, MT Status=0, MTMSN=0
2024-07-29T17:04:40.632Z,1722272680.632 [NAL9602](INFO): No messages in MT queue
2024-07-29T17:04:47.104Z,1722272687.104 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240729T153024/Express0032.lzma
2024-07-29T17:04:48.107Z,1722272688.107 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Express0032.lzma.bak
2024-07-29T17:04:48.107Z,1722272688.107 [DataOverHttps](INFO): SBD MOMSN=21392525
2024-07-29T17:04:49.144Z,1722272689.144 [Default:CheckIn:Read_Iridium] Stopped
2024-07-29T17:04:49.144Z,1722272689.144 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-29T17:04:49.144Z,1722272689.144 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-29T17:05:11.400Z,1722272711.400 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-29T17:09:49.885Z,1722272989.885 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-29T17:09:49.885Z,1722272989.885 [Default:CheckIn:C.Wait] Stopped
2024-07-29T17:09:49.885Z,1722272989.885 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-29T17:09:49.885Z,1722272989.885 [Default:CheckIn:D] Running Loop=1
2024-07-29T17:09:50.300Z,1722272990.300 [Default:CheckIn:D] Stopped
2024-07-29T17:09:50.300Z,1722272990.300 [Default:CheckIn:E] Running Loop=1
2024-07-29T17:09:50.713Z,1722272990.713 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 95.874308 min
2024-07-29T17:09:50.713Z,1722272990.713 [Default:CheckIn:E] Stopped
2024-07-29T17:09:50.713Z,1722272990.713 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-29T17:09:50.713Z,1722272990.713 [Default:CheckIn] Stopped
2024-07-29T17:09:50.713Z,1722272990.713 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-29T17:09:50.714Z,1722272990.714 [Default:CheckIn](INFO): Running loop #10
2024-07-29T17:09:50.714Z,1722272990.714 [Default:CheckIn] Running Loop=10
2024-07-29T17:09:50.714Z,1722272990.714 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-29T17:09:50.714Z,1722272990.714 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-29T17:09:52.705Z,1722272992.705 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170951.00,A,3648.14072,N,12147.22803,W,0.019,25.66,290724,,,D*44
2024-07-29T17:09:52.707Z,1722272992.707 [NAL9602](INFO): GPS fix at 20240729T170951: (36.802345, -121.787134)
2024-07-29T17:09:52.739Z,1722272992.739 [Default:CheckIn:Read_GPS] Stopped
2024-07-29T17:09:52.739Z,1722272992.739 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-29T17:09:59.888Z,1722272999.888 [DataOverHttps](INFO): Sending 70 bytes from file Logs/20240729T153024/Courier0034.lzma
2024-07-29T17:10:00.890Z,1722273000.890 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Courier0034.lzma.bak
2024-07-29T17:10:00.891Z,1722273000.891 [DataOverHttps](INFO): SBD MOMSN=21392529
2024-07-29T17:10:16.700Z,1722273016.700 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240729T153024/Express0035.lzma
2024-07-29T17:10:17.703Z,1722273017.703 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Express0035.lzma.bak
2024-07-29T17:10:17.703Z,1722273017.703 [DataOverHttps](INFO): SBD MOMSN=21392532
2024-07-29T17:10:17.767Z,1722273017.767 [NAL9602](INFO): SBD MO Status=2, MOMSN=53439, MT Status=2, MTMSN=0
2024-07-29T17:10:17.767Z,1722273017.767 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-29T17:10:18.980Z,1722273018.980 [Default:CheckIn:Read_Iridium] Stopped
2024-07-29T17:10:18.980Z,1722273018.980 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-29T17:10:18.981Z,1722273018.981 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-29T17:10:35.579Z,1722273035.579 [NAL9602](INFO): SBD MO Status=0, MOMSN=53439, MT Status=0, MTMSN=0
2024-07-29T17:10:35.579Z,1722273035.579 [NAL9602](INFO): No messages in MT queue
2024-07-29T17:11:06.338Z,1722273066.338 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-29T17:15:19.717Z,1722273319.717 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-29T17:15:19.717Z,1722273319.717 [Default:CheckIn:C.Wait] Stopped
2024-07-29T17:15:19.717Z,1722273319.717 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-29T17:15:19.717Z,1722273319.717 [Default:CheckIn:D] Running Loop=1
2024-07-29T17:15:20.127Z,1722273320.127 [Default:CheckIn:D] Stopped
2024-07-29T17:15:20.127Z,1722273320.127 [Default:CheckIn:E] Running Loop=1
2024-07-29T17:15:20.526Z,1722273320.526 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 101.371419 min
2024-07-29T17:15:20.526Z,1722273320.526 [Default:CheckIn:E] Stopped
2024-07-29T17:15:20.526Z,1722273320.526 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-29T17:15:20.526Z,1722273320.526 [Default:CheckIn] Stopped
2024-07-29T17:15:20.527Z,1722273320.527 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-29T17:15:20.527Z,1722273320.527 [Default:CheckIn](INFO): Running loop #11
2024-07-29T17:15:20.527Z,1722273320.527 [Default:CheckIn] Running Loop=11
2024-07-29T17:15:20.527Z,1722273320.527 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-29T17:15:20.527Z,1722273320.527 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-29T17:15:22.531Z,1722273322.531 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171521.00,A,3648.14144,N,12147.23006,W,0.039,161.80,290724,,,D*7D
2024-07-29T17:15:22.534Z,1722273322.534 [NAL9602](INFO): GPS fix at 20240729T171521: (36.802357, -121.787168)
2024-07-29T17:15:22.545Z,1722273322.545 [Default:CheckIn:Read_GPS] Stopped
2024-07-29T17:15:22.545Z,1722273322.545 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-29T17:15:30.172Z,1722273330.172 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240729T153024/Courier0037.lzma
2024-07-29T17:15:31.174Z,1722273331.174 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Courier0037.lzma.bak
2024-07-29T17:15:31.175Z,1722273331.175 [DataOverHttps](INFO): SBD MOMSN=21392542
2024-07-29T17:15:47.132Z,1722273347.132 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240729T153024/Express0038.lzma
2024-07-29T17:15:48.135Z,1722273348.135 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Express0038.lzma.bak
2024-07-29T17:15:48.135Z,1722273348.135 [DataOverHttps](INFO): SBD MOMSN=21392545
2024-07-29T17:15:49.203Z,1722273349.203 [Default:CheckIn:Read_Iridium] Stopped
2024-07-29T17:15:49.203Z,1722273349.203 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-29T17:15:49.203Z,1722273349.203 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-29T17:16:02.122Z,1722273362.122 [NAL9602](INFO): SBD MO Status=2, MOMSN=53440, MT Status=2, MTMSN=0
2024-07-29T17:16:02.122Z,1722273362.122 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-29T17:16:17.898Z,1722273377.898 [NAL9602](INFO): SBD MO Status=2, MOMSN=53440, MT Status=2, MTMSN=0
2024-07-29T17:16:17.899Z,1722273377.899 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-29T17:16:43.382Z,1722273403.382 [NAL9602](INFO): SBD MO Status=2, MOMSN=53440, MT Status=2, MTMSN=0
2024-07-29T17:16:43.382Z,1722273403.382 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-29T17:17:11.715Z,1722273431.715 [NAL9602](INFO): SBD MO Status=0, MOMSN=53440, MT Status=0, MTMSN=0
2024-07-29T17:17:11.715Z,1722273431.715 [NAL9602](INFO): No messages in MT queue
2024-07-29T17:17:42.418Z,1722273462.418 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-29T17:20:49.914Z,1722273649.914 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-29T17:20:49.914Z,1722273649.914 [Default:CheckIn:C.Wait] Stopped
2024-07-29T17:20:49.914Z,1722273649.914 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-29T17:20:49.914Z,1722273649.914 [Default:CheckIn:D] Running Loop=1
2024-07-29T17:20:50.361Z,1722273650.361 [Default:CheckIn:D] Stopped
2024-07-29T17:20:50.361Z,1722273650.361 [Default:CheckIn:E] Running Loop=1
2024-07-29T17:20:50.726Z,1722273650.726 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 106.875326 min
2024-07-29T17:20:50.726Z,1722273650.726 [Default:CheckIn:E] Stopped
2024-07-29T17:20:50.726Z,1722273650.726 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-29T17:20:50.726Z,1722273650.726 [Default:CheckIn] Stopped
2024-07-29T17:20:50.726Z,1722273650.726 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-29T17:20:50.726Z,1722273650.726 [Default:CheckIn](INFO): Running loop #12
2024-07-29T17:20:50.726Z,1722273650.726 [Default:CheckIn] Running Loop=12
2024-07-29T17:20:50.727Z,1722273650.727 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-29T17:20:50.727Z,1722273650.727 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-29T17:20:52.722Z,1722273652.722 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172052.00,A,3648.14345,N,12147.22806,W,0.000,161.80,290724,,,A*7A
2024-07-29T17:20:52.742Z,1722273652.742 [NAL9602](INFO): GPS fix at 20240729T172052: (36.802391, -121.787134)
2024-07-29T17:20:52.762Z,1722273652.762 [Default:CheckIn:Read_GPS] Stopped
2024-07-29T17:20:52.762Z,1722273652.762 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-29T17:21:01.773Z,1722273661.773 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240729T153024/Courier0040.lzma
2024-07-29T17:21:02.774Z,1722273662.774 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Courier0040.lzma.bak
2024-07-29T17:21:02.775Z,1722273662.775 [DataOverHttps](INFO): SBD MOMSN=21392550
2024-07-29T17:21:06.607Z,1722273666.607 [NAL9602](INFO): SBD MO Status=0, MOMSN=53441, MT Status=0, MTMSN=0
2024-07-29T17:21:06.607Z,1722273666.607 [NAL9602](INFO): No messages in MT queue
2024-07-29T17:21:18.716Z,1722273678.716 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240729T153024/Express0041.lzma
2024-07-29T17:21:19.718Z,1722273679.718 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Express0041.lzma.bak
2024-07-29T17:21:19.719Z,1722273679.719 [DataOverHttps](INFO): SBD MOMSN=21392554
2024-07-29T17:21:20.759Z,1722273680.759 [Default:CheckIn:Read_Iridium] Stopped
2024-07-29T17:21:20.759Z,1722273680.759 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-29T17:21:20.759Z,1722273680.759 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-29T17:21:37.313Z,1722273697.313 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-29T17:26:21.387Z,1722273981.387 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-29T17:26:21.387Z,1722273981.387 [Default:CheckIn:C.Wait] Stopped
2024-07-29T17:26:21.388Z,1722273981.388 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-29T17:26:21.388Z,1722273981.388 [Default:CheckIn:D] Running Loop=1
2024-07-29T17:26:21.780Z,1722273981.780 [Default:CheckIn:D] Stopped
2024-07-29T17:26:21.780Z,1722273981.780 [Default:CheckIn:E] Running Loop=1
2024-07-29T17:26:22.186Z,1722273982.186 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 112.398966 min
2024-07-29T17:26:22.186Z,1722273982.186 [Default:CheckIn:E] Stopped
2024-07-29T17:26:22.186Z,1722273982.186 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-29T17:26:22.186Z,1722273982.186 [Default:CheckIn] Stopped
2024-07-29T17:26:22.186Z,1722273982.186 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-29T17:26:22.186Z,1722273982.186 [Default:CheckIn](INFO): Running loop #13
2024-07-29T17:26:22.186Z,1722273982.186 [Default:CheckIn] Running Loop=13
2024-07-29T17:26:22.186Z,1722273982.186 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-29T17:26:22.187Z,1722273982.187 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-29T17:26:24.187Z,1722273984.187 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172623.00,A,3648.14168,N,12147.22832,W,0.039,161.80,290724,,,A*7A
2024-07-29T17:26:24.190Z,1722273984.190 [NAL9602](INFO): GPS fix at 20240729T172623: (36.802361, -121.787139)
2024-07-29T17:26:24.233Z,1722273984.233 [Default:CheckIn:Read_GPS] Stopped
2024-07-29T17:26:24.233Z,1722273984.233 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-29T17:26:31.350Z,1722273991.350 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240729T153024/Courier0043.lzma
2024-07-29T17:26:32.346Z,1722273992.346 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Courier0043.lzma.bak
2024-07-29T17:26:32.347Z,1722273992.347 [DataOverHttps](INFO): SBD MOMSN=21392564
2024-07-29T17:26:34.507Z,1722273994.507 [NAL9602](INFO): SBD MO Status=0, MOMSN=53442, MT Status=0, MTMSN=0
2024-07-29T17:26:34.507Z,1722273994.507 [NAL9602](INFO): No messages in MT queue
2024-07-29T17:26:48.645Z,1722274008.645 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20240729T153024/Express0044.lzma
2024-07-29T17:26:49.647Z,1722274009.647 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Express0044.lzma.bak
2024-07-29T17:26:49.647Z,1722274009.647 [DataOverHttps](INFO): SBD MOMSN=21392567
2024-07-29T17:26:50.711Z,1722274010.711 [Default:CheckIn:Read_Iridium] Stopped
2024-07-29T17:26:50.711Z,1722274010.711 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-29T17:26:50.711Z,1722274010.711 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-29T17:27:05.246Z,1722274025.246 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-29T17:31:51.355Z,1722274311.355 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-29T17:31:51.355Z,1722274311.355 [Default:CheckIn:C.Wait] Stopped
2024-07-29T17:31:51.355Z,1722274311.355 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-29T17:31:51.355Z,1722274311.355 [Default:CheckIn:D] Running Loop=1
2024-07-29T17:31:51.777Z,1722274311.777 [Default:CheckIn:D] Stopped
2024-07-29T17:31:51.777Z,1722274311.777 [Default:CheckIn:E] Running Loop=1
2024-07-29T17:31:52.174Z,1722274312.174 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 117.898844 min
2024-07-29T17:31:52.174Z,1722274312.174 [Default:CheckIn:E] Stopped
2024-07-29T17:31:52.174Z,1722274312.174 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-29T17:31:52.174Z,1722274312.174 [Default:CheckIn] Stopped
2024-07-29T17:31:52.174Z,1722274312.174 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-29T17:31:52.174Z,1722274312.174 [Default:CheckIn](INFO): Running loop #14
2024-07-29T17:31:52.174Z,1722274312.174 [Default:CheckIn] Running Loop=14
2024-07-29T17:31:52.174Z,1722274312.174 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-29T17:31:52.174Z,1722274312.174 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-29T17:31:54.179Z,1722274314.179 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173153.00,A,3648.14304,N,12147.22907,W,0.058,161.80,290724,,,A*73
2024-07-29T17:31:54.189Z,1722274314.189 [NAL9602](INFO): GPS fix at 20240729T173153: (36.802384, -121.787151)
2024-07-29T17:31:54.205Z,1722274314.205 [Default:CheckIn:Read_GPS] Stopped
2024-07-29T17:31:54.205Z,1722274314.205 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-29T17:32:01.104Z,1722274321.104 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240729T153024/Courier0046.lzma
2024-07-29T17:32:02.106Z,1722274322.106 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Courier0046.lzma.bak
2024-07-29T17:32:02.107Z,1722274322.107 [DataOverHttps](INFO): SBD MOMSN=21392571
2024-07-29T17:32:06.295Z,1722274326.295 [NAL9602](INFO): SBD MO Status=0, MOMSN=53443, MT Status=0, MTMSN=0
2024-07-29T17:32:06.295Z,1722274326.295 [NAL9602](INFO): No messages in MT queue
2024-07-29T17:32:18.417Z,1722274338.417 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240729T153024/Express0047.lzma
2024-07-29T17:32:19.419Z,1722274339.419 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Express0047.lzma.bak
2024-07-29T17:32:19.419Z,1722274339.419 [DataOverHttps](INFO): SBD MOMSN=21392574
2024-07-29T17:32:20.479Z,1722274340.479 [Default:CheckIn:Read_Iridium] Stopped
2024-07-29T17:32:20.479Z,1722274340.479 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-29T17:32:20.479Z,1722274340.479 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-29T17:32:37.033Z,1722274357.033 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-29T17:37:21.083Z,1722274641.083 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-29T17:37:21.084Z,1722274641.084 [Default:CheckIn:C.Wait] Stopped
2024-07-29T17:37:21.084Z,1722274641.084 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-29T17:37:21.084Z,1722274641.084 [Default:CheckIn:D] Running Loop=1
2024-07-29T17:37:21.488Z,1722274641.488 [Default:CheckIn:D] Stopped
2024-07-29T17:37:21.488Z,1722274641.488 [Default:CheckIn:E] Running Loop=1
2024-07-29T17:37:21.910Z,1722274641.910 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 123.394100 min
2024-07-29T17:37:21.910Z,1722274641.910 [Default:CheckIn:E] Stopped
2024-07-29T17:37:21.910Z,1722274641.910 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-29T17:37:21.910Z,1722274641.910 [Default:CheckIn] Stopped
2024-07-29T17:37:21.910Z,1722274641.910 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-29T17:37:21.911Z,1722274641.911 [Default:CheckIn](INFO): Running loop #15
2024-07-29T17:37:21.911Z,1722274641.911 [Default:CheckIn] Running Loop=15
2024-07-29T17:37:21.911Z,1722274641.911 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-29T17:37:21.911Z,1722274641.911 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-29T17:37:23.926Z,1722274643.926 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173723.00,A,3648.14184,N,12147.22841,W,0.019,161.80,290724,,,A*7E
2024-07-29T17:37:23.928Z,1722274643.928 [NAL9602](INFO): GPS fix at 20240729T173723: (36.802364, -121.787140)
2024-07-29T17:37:23.942Z,1722274643.942 [Default:CheckIn:Read_GPS] Stopped
2024-07-29T17:37:23.943Z,1722274643.943 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-29T17:37:31.484Z,1722274651.484 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240729T153024/Courier0049.lzma
2024-07-29T17:37:32.486Z,1722274652.486 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Courier0049.lzma.bak
2024-07-29T17:37:32.487Z,1722274652.487 [DataOverHttps](INFO): SBD MOMSN=21392586
2024-07-29T17:37:36.831Z,1722274656.831 [NAL9602](INFO): SBD MO Status=0, MOMSN=53444, MT Status=0, MTMSN=0
2024-07-29T17:37:36.831Z,1722274656.831 [NAL9602](INFO): No messages in MT queue
2024-07-29T17:37:48.432Z,1722274668.432 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240729T153024/Express0050.lzma
2024-07-29T17:37:49.435Z,1722274669.435 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Express0050.lzma.bak
2024-07-29T17:37:49.435Z,1722274669.435 [DataOverHttps](INFO): SBD MOMSN=21392589
2024-07-29T17:37:50.577Z,1722274670.577 [Default:CheckIn:Read_Iridium] Stopped
2024-07-29T17:37:50.577Z,1722274670.577 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-29T17:37:50.577Z,1722274670.577 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-29T17:38:07.530Z,1722274687.530 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-29T17:42:51.251Z,1722274971.251 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-29T17:42:51.252Z,1722274971.252 [Default:CheckIn:C.Wait] Stopped
2024-07-29T17:42:51.252Z,1722274971.252 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-29T17:42:51.252Z,1722274971.252 [Default:CheckIn:D] Running Loop=1
2024-07-29T17:42:51.636Z,1722274971.636 [Default:CheckIn:D] Stopped
2024-07-29T17:42:51.636Z,1722274971.636 [Default:CheckIn:E] Running Loop=1
2024-07-29T17:42:52.049Z,1722274972.049 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 128.896574 min
2024-07-29T17:42:52.049Z,1722274972.049 [Default:CheckIn:E] Stopped
2024-07-29T17:42:52.049Z,1722274972.049 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-29T17:42:52.049Z,1722274972.049 [Default:CheckIn] Stopped
2024-07-29T17:42:52.049Z,1722274972.049 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-29T17:42:52.049Z,1722274972.049 [Default:CheckIn](INFO): Running loop #16
2024-07-29T17:42:52.050Z,1722274972.050 [Default:CheckIn] Running Loop=16
2024-07-29T17:42:52.050Z,1722274972.050 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-29T17:42:52.050Z,1722274972.050 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-29T17:42:54.065Z,1722274974.065 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174253.00,A,3648.14175,N,12147.22884,W,0.019,161.80,290724,,,A*7C
2024-07-29T17:42:54.067Z,1722274974.067 [NAL9602](INFO): GPS fix at 20240729T174253: (36.802363, -121.787147)
2024-07-29T17:42:54.079Z,1722274974.079 [Default:CheckIn:Read_GPS] Stopped
2024-07-29T17:42:54.079Z,1722274974.079 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-29T17:43:00.107Z,1722274980.107 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T17:43:01.652Z,1722274981.652 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240729T153024/Courier0052.lzma
2024-07-29T17:43:02.654Z,1722274982.654 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Courier0052.lzma.bak
2024-07-29T17:43:02.655Z,1722274982.655 [DataOverHttps](INFO): SBD MOMSN=21392593
2024-07-29T17:43:12.227Z,1722274992.227 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T17:43:13.447Z,1722274993.447 [NAL9602](INFO): SBD MO Status=2, MOMSN=53445, MT Status=2, MTMSN=0
2024-07-29T17:43:13.447Z,1722274993.447 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-29T17:43:18.620Z,1722274998.620 [DataOverHttps](INFO): Sending 133 bytes from file Logs/20240729T153024/Express0053.lzma
2024-07-29T17:43:19.623Z,1722274999.623 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Express0053.lzma.bak
2024-07-29T17:43:19.623Z,1722274999.623 [DataOverHttps](INFO): SBD MOMSN=21392596
2024-07-29T17:43:20.753Z,1722275000.753 [Default:CheckIn:Read_Iridium] Stopped
2024-07-29T17:43:20.753Z,1722275000.753 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-29T17:43:20.753Z,1722275000.753 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-29T17:43:23.539Z,1722275003.539 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T17:43:35.262Z,1722275015.262 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T17:43:47.375Z,1722275027.375 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T17:43:59.495Z,1722275039.495 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T17:44:11.211Z,1722275051.211 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T17:44:23.363Z,1722275063.363 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T17:44:33.056Z,1722275073.056 [NAL9602](INFO): SBD MO Status=0, MOMSN=53445, MT Status=0, MTMSN=0
2024-07-29T17:44:33.057Z,1722275073.057 [NAL9602](INFO): No messages in MT queue
2024-07-29T17:45:03.829Z,1722275103.829 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-29T17:46:58.500Z,1722275218.500 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T17:47:12.639Z,1722275232.639 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T17:47:26.379Z,1722275246.379 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T17:47:39.717Z,1722275259.717 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T17:47:54.251Z,1722275274.251 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T17:48:08.795Z,1722275288.795 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T17:48:21.368Z,1722275301.368 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-29T17:48:21.373Z,1722275301.373 [Default:CheckIn:C.Wait] Stopped
2024-07-29T17:48:21.373Z,1722275301.373 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-29T17:48:21.373Z,1722275301.373 [Default:CheckIn:D] Running Loop=1
2024-07-29T17:48:21.745Z,1722275301.745 [Default:CheckIn:D] Stopped
2024-07-29T17:48:21.746Z,1722275301.746 [Default:CheckIn:E] Running Loop=1
2024-07-29T17:48:22.198Z,1722275302.198 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 134.398397 min
2024-07-29T17:48:22.198Z,1722275302.198 [Default:CheckIn:E] Stopped
2024-07-29T17:48:22.198Z,1722275302.198 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-29T17:48:22.198Z,1722275302.198 [Default:CheckIn] Stopped
2024-07-29T17:48:22.198Z,1722275302.198 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-29T17:48:22.198Z,1722275302.198 [Default:CheckIn](INFO): Running loop #17
2024-07-29T17:48:22.198Z,1722275302.198 [Default:CheckIn] Running Loop=17
2024-07-29T17:48:22.198Z,1722275302.198 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-29T17:48:22.198Z,1722275302.198 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-29T17:48:22.531Z,1722275302.531 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T17:48:24.151Z,1722275304.151 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174823.00,A,3648.13980,N,12147.22800,W,0.078,161.80,290724,,,A*7F
2024-07-29T17:48:24.171Z,1722275304.171 [NAL9602](INFO): GPS fix at 20240729T174823: (36.802330, -121.787133)
2024-07-29T17:48:24.202Z,1722275304.202 [Default:CheckIn:Read_GPS] Stopped
2024-07-29T17:48:24.202Z,1722275304.202 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-29T17:48:32.004Z,1722275312.004 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240729T153024/Courier0055.lzma
2024-07-29T17:48:33.006Z,1722275313.006 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Courier0055.lzma.bak
2024-07-29T17:48:33.007Z,1722275313.007 [DataOverHttps](INFO): SBD MOMSN=21392607
2024-07-29T17:48:35.864Z,1722275315.864 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T17:48:37.895Z,1722275317.895 [NAL9602](INFO): SBD MO Status=0, MOMSN=53446, MT Status=0, MTMSN=0
2024-07-29T17:48:37.895Z,1722275317.895 [NAL9602](INFO): No messages in MT queue
2024-07-29T17:48:49.195Z,1722275329.195 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T17:48:49.721Z,1722275329.721 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240729T153024/Express0056.lzma
2024-07-29T17:48:50.723Z,1722275330.723 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Express0056.lzma.bak
2024-07-29T17:48:50.723Z,1722275330.723 [DataOverHttps](INFO): SBD MOMSN=21392610
2024-07-29T17:48:52.056Z,1722275332.056 [Default:CheckIn:Read_Iridium] Stopped
2024-07-29T17:48:52.056Z,1722275332.056 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-29T17:48:52.056Z,1722275332.056 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-29T17:49:02.557Z,1722275342.557 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T17:49:08.622Z,1722275348.622 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-29T17:49:13.074Z,1722275353.074 [BPC1](INFO): Calculating totals. Valid battery stick count: 42. Valid reserve battery stick count: 5.
2024-07-29T17:49:13.076Z,1722275353.076 [BPC1](INFO): Received data from all battery sticks.
2024-07-29T17:53:52.745Z,1722275632.745 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-29T17:53:52.745Z,1722275632.745 [Default:CheckIn:C.Wait] Stopped
2024-07-29T17:53:52.745Z,1722275632.745 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-29T17:53:52.745Z,1722275632.745 [Default:CheckIn:D] Running Loop=1
2024-07-29T17:53:53.155Z,1722275633.155 [Default:CheckIn:D] Stopped
2024-07-29T17:53:53.155Z,1722275633.155 [Default:CheckIn:E] Running Loop=1
2024-07-29T17:53:53.543Z,1722275633.543 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 139.921891 min
2024-07-29T17:53:53.543Z,1722275633.543 [Default:CheckIn:E] Stopped
2024-07-29T17:53:53.544Z,1722275633.544 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-29T17:53:53.544Z,1722275633.544 [Default:CheckIn] Stopped
2024-07-29T17:53:53.544Z,1722275633.544 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-29T17:53:53.544Z,1722275633.544 [Default:CheckIn](INFO): Running loop #18
2024-07-29T17:53:53.544Z,1722275633.544 [Default:CheckIn] Running Loop=18
2024-07-29T17:53:53.544Z,1722275633.544 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-29T17:53:53.544Z,1722275633.544 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-29T17:53:55.555Z,1722275635.555 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175354.00,A,3648.14270,N,12147.22918,W,0.039,0.00,290724,,,A*75
2024-07-29T17:53:55.557Z,1722275635.557 [NAL9602](INFO): GPS fix at 20240729T175354: (36.802378, -121.787153)
2024-07-29T17:53:55.568Z,1722275635.568 [Default:CheckIn:Read_GPS] Stopped
2024-07-29T17:53:55.568Z,1722275635.568 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-29T17:54:03.236Z,1722275643.236 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20240729T153024/Courier0058.lzma
2024-07-29T17:54:04.238Z,1722275644.238 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Courier0058.lzma.bak
2024-07-29T17:54:04.239Z,1722275644.239 [DataOverHttps](INFO): SBD MOMSN=21392616
2024-07-29T17:54:06.462Z,1722275646.462 [NAL9602](INFO): SBD MO Status=0, MOMSN=53447, MT Status=0, MTMSN=0
2024-07-29T17:54:06.462Z,1722275646.462 [NAL9602](INFO): No messages in MT queue
2024-07-29T17:54:21.830Z,1722275661.830 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240729T153024/Express0059.lzma
2024-07-29T17:54:22.831Z,1722275662.831 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Express0059.lzma.bak
2024-07-29T17:54:22.831Z,1722275662.831 [DataOverHttps](INFO): SBD MOMSN=21392619
2024-07-29T17:54:23.887Z,1722275663.887 [Default:CheckIn:Read_Iridium] Stopped
2024-07-29T17:54:23.887Z,1722275663.887 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-29T17:54:23.887Z,1722275663.887 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-29T17:54:37.189Z,1722275677.189 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-29T17:59:24.444Z,1722275964.444 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-29T17:59:24.444Z,1722275964.444 [Default:CheckIn:C.Wait] Stopped
2024-07-29T17:59:24.444Z,1722275964.444 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-29T17:59:24.444Z,1722275964.444 [Default:CheckIn:D] Running Loop=1
2024-07-29T17:59:24.852Z,1722275964.852 [Default:CheckIn:D] Stopped
2024-07-29T17:59:24.852Z,1722275964.852 [Default:CheckIn:E] Running Loop=1
2024-07-29T17:59:25.263Z,1722275965.263 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 145.450179 min
2024-07-29T17:59:25.263Z,1722275965.263 [Default:CheckIn:E] Stopped
2024-07-29T17:59:25.263Z,1722275965.263 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-29T17:59:25.263Z,1722275965.263 [Default:CheckIn] Stopped
2024-07-29T17:59:25.263Z,1722275965.263 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-29T17:59:25.264Z,1722275965.264 [Default:CheckIn](INFO): Running loop #19
2024-07-29T17:59:25.264Z,1722275965.264 [Default:CheckIn] Running Loop=19
2024-07-29T17:59:25.264Z,1722275965.264 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-29T17:59:25.264Z,1722275965.264 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-29T17:59:27.267Z,1722275967.267 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175926.00,A,3648.14244,N,12147.22832,W,0.019,0.00,290724,,,A*76
2024-07-29T17:59:27.270Z,1722275967.270 [NAL9602](INFO): GPS fix at 20240729T175926: (36.802374, -121.787139)
2024-07-29T17:59:27.281Z,1722275967.281 [Default:CheckIn:Read_GPS] Stopped
2024-07-29T17:59:27.281Z,1722275967.281 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-29T17:59:34.288Z,1722275974.288 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240729T153024/Courier0061.lzma
2024-07-29T17:59:35.290Z,1722275975.290 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Courier0061.lzma.bak
2024-07-29T17:59:35.291Z,1722275975.291 [DataOverHttps](INFO): SBD MOMSN=21392629
2024-07-29T17:59:51.072Z,1722275991.072 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240729T153024/Express0062.lzma
2024-07-29T17:59:52.075Z,1722275992.075 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Express0062.lzma.bak
2024-07-29T17:59:52.075Z,1722275992.075 [DataOverHttps](INFO): SBD MOMSN=21392632
2024-07-29T17:59:53.132Z,1722275993.132 [Default:CheckIn:Read_Iridium] Stopped
2024-07-29T17:59:53.132Z,1722275993.132 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-29T17:59:53.132Z,1722275993.132 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-29T18:00:00.390Z,1722276000.390 [NAL9602](INFO): SBD MO Status=0, MOMSN=53448, MT Status=0, MTMSN=0
2024-07-29T18:00:00.390Z,1722276000.390 [NAL9602](INFO): No messages in MT queue
2024-07-29T18:00:31.098Z,1722276031.098 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-29T18:04:53.717Z,1722276293.717 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-29T18:04:53.717Z,1722276293.717 [Default:CheckIn:C.Wait] Stopped
2024-07-29T18:04:53.717Z,1722276293.717 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-29T18:04:53.717Z,1722276293.717 [Default:CheckIn:D] Running Loop=1
2024-07-29T18:04:54.141Z,1722276294.141 [Default:CheckIn:D] Stopped
2024-07-29T18:04:54.141Z,1722276294.141 [Default:CheckIn:E] Running Loop=1
2024-07-29T18:04:54.574Z,1722276294.574 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 150.938118 min
2024-07-29T18:04:54.574Z,1722276294.574 [Default:CheckIn:E] Stopped
2024-07-29T18:04:54.574Z,1722276294.574 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-29T18:04:54.574Z,1722276294.574 [Default:CheckIn] Stopped
2024-07-29T18:04:54.574Z,1722276294.574 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-29T18:04:54.574Z,1722276294.574 [Default:CheckIn](INFO): Running loop #20
2024-07-29T18:04:54.575Z,1722276294.575 [Default:CheckIn] Running Loop=20
2024-07-29T18:04:54.575Z,1722276294.575 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-29T18:04:54.575Z,1722276294.575 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-29T18:04:56.526Z,1722276296.526 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180455.00,A,3648.14293,N,12147.22816,W,0.019,0.00,290724,,,A*79
2024-07-29T18:04:56.528Z,1722276296.528 [NAL9602](INFO): GPS fix at 20240729T180455: (36.802382, -121.787136)
2024-07-29T18:04:56.565Z,1722276296.565 [Default:CheckIn:Read_GPS] Stopped
2024-07-29T18:04:56.565Z,1722276296.565 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-29T18:05:03.828Z,1722276303.828 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240729T153024/Courier0064.lzma
2024-07-29T18:05:04.830Z,1722276304.830 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Courier0064.lzma.bak
2024-07-29T18:05:04.831Z,1722276304.831 [DataOverHttps](INFO): SBD MOMSN=21392640
2024-07-29T18:05:09.463Z,1722276309.463 [NAL9602](INFO): SBD MO Status=0, MOMSN=53449, MT Status=0, MTMSN=0
2024-07-29T18:05:09.463Z,1722276309.463 [NAL9602](INFO): No messages in MT queue
2024-07-29T18:05:20.748Z,1722276320.748 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240729T153024/Express0065.lzma
2024-07-29T18:05:21.751Z,1722276321.751 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Express0065.lzma.bak
2024-07-29T18:05:21.751Z,1722276321.751 [DataOverHttps](INFO): SBD MOMSN=21392643
2024-07-29T18:05:22.795Z,1722276322.795 [Default:CheckIn:Read_Iridium] Stopped
2024-07-29T18:05:22.795Z,1722276322.795 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-29T18:05:22.795Z,1722276322.795 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-29T18:05:40.166Z,1722276340.166 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-29T18:10:23.391Z,1722276623.391 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-29T18:10:23.391Z,1722276623.391 [Default:CheckIn:C.Wait] Stopped
2024-07-29T18:10:23.391Z,1722276623.391 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-29T18:10:23.392Z,1722276623.392 [Default:CheckIn:D] Running Loop=1
2024-07-29T18:10:23.818Z,1722276623.818 [Default:CheckIn:D] Stopped
2024-07-29T18:10:23.818Z,1722276623.818 [Default:CheckIn:E] Running Loop=1
2024-07-29T18:10:24.190Z,1722276624.190 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 156.432943 min
2024-07-29T18:10:24.190Z,1722276624.190 [Default:CheckIn:E] Stopped
2024-07-29T18:10:24.191Z,1722276624.191 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-29T18:10:24.191Z,1722276624.191 [Default:CheckIn] Stopped
2024-07-29T18:10:24.191Z,1722276624.191 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-29T18:10:24.191Z,1722276624.191 [Default:CheckIn](INFO): Running loop #21
2024-07-29T18:10:24.191Z,1722276624.191 [Default:CheckIn] Running Loop=21
2024-07-29T18:10:24.191Z,1722276624.191 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-29T18:10:24.191Z,1722276624.191 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-29T18:10:26.192Z,1722276626.192 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181025.00,A,3648.14159,N,12147.22800,W,0.000,0.00,290724,,,A*71
2024-07-29T18:10:26.194Z,1722276626.194 [NAL9602](INFO): GPS fix at 20240729T181025: (36.802360, -121.787133)
2024-07-29T18:10:26.223Z,1722276626.223 [Default:CheckIn:Read_GPS] Stopped
2024-07-29T18:10:26.223Z,1722276626.223 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-29T18:10:33.856Z,1722276633.856 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240729T153024/Courier0067.lzma
2024-07-29T18:10:34.858Z,1722276634.858 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Courier0067.lzma.bak
2024-07-29T18:10:34.859Z,1722276634.859 [DataOverHttps](INFO): SBD MOMSN=21392658
2024-07-29T18:10:52.107Z,1722276652.107 [NAL9602](INFO): SBD MO Status=0, MOMSN=53450, MT Status=0, MTMSN=0
2024-07-29T18:10:52.107Z,1722276652.107 [NAL9602](INFO): No messages in MT queue
2024-07-29T18:10:53.541Z,1722276653.541 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20240729T153024/Express0068.lzma
2024-07-29T18:10:54.547Z,1722276654.547 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Express0068.lzma.bak
2024-07-29T18:10:54.547Z,1722276654.547 [DataOverHttps](INFO): SBD MOMSN=21392661
2024-07-29T18:10:55.759Z,1722276655.759 [Default:CheckIn:Read_Iridium] Stopped
2024-07-29T18:10:55.759Z,1722276655.759 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-29T18:10:55.759Z,1722276655.759 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-29T18:11:22.798Z,1722276682.798 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-29T18:15:56.328Z,1722276956.328 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-29T18:15:56.329Z,1722276956.329 [Default:CheckIn:C.Wait] Stopped
2024-07-29T18:15:56.329Z,1722276956.329 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-29T18:15:56.329Z,1722276956.329 [Default:CheckIn:D] Running Loop=1
2024-07-29T18:15:56.743Z,1722276956.743 [Default:CheckIn:D] Stopped
2024-07-29T18:15:56.743Z,1722276956.743 [Default:CheckIn:E] Running Loop=1
2024-07-29T18:15:57.168Z,1722276957.168 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 161.981689 min
2024-07-29T18:15:57.169Z,1722276957.169 [Default:CheckIn:E] Stopped
2024-07-29T18:15:57.169Z,1722276957.169 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-29T18:15:57.169Z,1722276957.169 [Default:CheckIn] Stopped
2024-07-29T18:15:57.169Z,1722276957.169 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-29T18:15:57.169Z,1722276957.169 [Default:CheckIn](INFO): Running loop #22
2024-07-29T18:15:57.169Z,1722276957.169 [Default:CheckIn] Running Loop=22
2024-07-29T18:15:57.169Z,1722276957.169 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-29T18:15:57.169Z,1722276957.169 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-29T18:15:59.143Z,1722276959.143 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181558.00,A,3648.14991,N,12147.22344,W,0.039,350.28,290724,,,A*7F
2024-07-29T18:15:59.146Z,1722276959.146 [NAL9602](INFO): GPS fix at 20240729T181558: (36.802498, -121.787057)
2024-07-29T18:15:59.157Z,1722276959.157 [Default:CheckIn:Read_GPS] Stopped
2024-07-29T18:15:59.157Z,1722276959.157 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-29T18:16:06.368Z,1722276966.368 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20240729T153024/Courier0070.lzma
2024-07-29T18:16:07.371Z,1722276967.371 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Courier0070.lzma.bak
2024-07-29T18:16:07.371Z,1722276967.371 [DataOverHttps](INFO): SBD MOMSN=21392669
2024-07-29T18:16:23.863Z,1722276983.863 [NAL9602](INFO): SBD MO Status=0, MOMSN=53451, MT Status=0, MTMSN=0
2024-07-29T18:16:23.863Z,1722276983.864 [NAL9602](INFO): No messages in MT queue
2024-07-29T18:16:26.041Z,1722276986.041 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240729T153024/Express0071.lzma
2024-07-29T18:16:27.043Z,1722276987.043 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Express0071.lzma.bak
2024-07-29T18:16:27.043Z,1722276987.043 [DataOverHttps](INFO): SBD MOMSN=21392676
2024-07-29T18:16:28.338Z,1722276988.338 [Default:CheckIn:Read_Iridium] Stopped
2024-07-29T18:16:28.338Z,1722276988.338 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-29T18:16:28.338Z,1722276988.338 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-29T18:16:54.559Z,1722277014.559 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-29T18:19:07.962Z,1722277147.962 [DataOverHttps](IMPORTANT): SBD MTMSN=20240729T181907
2024-07-29T18:19:15.292Z,1722277155.292 [DataOverHttps](INFO): Received command: ibit
2024-07-29T18:19:15.305Z,1722277155.305 [CommandExec](IMPORTANT): got command ibit
2024-07-29T18:19:15.587Z,1722277155.587 [IBIT](IMPORTANT): Beginning Initiated BIT
2024-07-29T18:19:15.587Z,1722277155.587 [IBIT](IMPORTANT): Beginning control surface checks.
2024-07-29T18:19:15.594Z,1722277155.594 [CBIT](IMPORTANT): Beginning ground fault scan
2024-07-29T18:19:17.171Z,1722277157.171 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181916.00,A,3648.15142,N,12147.22208,W,0.058,7.27,290724,,,A*7E
2024-07-29T18:19:17.174Z,1722277157.174 [NAL9602](INFO): GPS fix at 20240729T181916: (36.802524, -121.787035)
2024-07-29T18:19:26.485Z,1722277166.485 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-07-29T18:19:26.905Z,1722277166.905 [CBIT](IMPORTANT): Beginning ground fault scan
2024-07-29T18:19:30.094Z,1722277170.094 [NAL9602](INFO): SBD MO Status=0, MOMSN=53452, MT Status=0, MTMSN=0
2024-07-29T18:19:30.094Z,1722277170.094 [NAL9602](INFO): No messages in MT queue
2024-07-29T18:19:31.316Z,1722277171.316 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181930.00,A,3648.15131,N,12147.22189,W,0.058,7.27,290724,,,A*74
2024-07-29T18:19:31.318Z,1722277171.318 [NAL9602](INFO): GPS fix at 20240729T181930: (36.802522, -121.787031)
2024-07-29T18:19:34.157Z,1722277174.157 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181933.00,A,3648.15122,N,12147.22190,W,0.039,7.27,290724,,,A*7A
2024-07-29T18:19:34.160Z,1722277174.160 [NAL9602](INFO): GPS fix at 20240729T181933: (36.802520, -121.787032)
2024-07-29T18:19:36.962Z,1722277176.962 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181936.00,A,3648.15113,N,12147.22190,W,0.039,7.27,290724,,,A*7D
2024-07-29T18:19:36.965Z,1722277176.965 [NAL9602](INFO): GPS fix at 20240729T181936: (36.802519, -121.787032)
2024-07-29T18:19:37.861Z,1722277177.861 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-07-29T18:19:38.321Z,1722277178.321 [CBIT](IMPORTANT): Beginning ground fault scan
2024-07-29T18:19:40.194Z,1722277180.194 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181939.00,A,3648.15090,N,12147.22194,W,0.019,7.27,290724,,,A*7E
2024-07-29T18:19:40.197Z,1722277180.197 [NAL9602](INFO): GPS fix at 20240729T181939: (36.802515, -121.787032)
2024-07-29T18:19:43.024Z,1722277183.024 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181942.00,A,3648.15063,N,12147.22211,W,0.039,7.27,290724,,,A*72
2024-07-29T18:19:43.026Z,1722277183.026 [NAL9602](INFO): GPS fix at 20240729T181942: (36.802510, -121.787035)
2024-07-29T18:19:45.850Z,1722277185.850 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181945.00,A,3648.15044,N,12147.22220,W,0.039,7.27,290724,,,A*72
2024-07-29T18:19:45.853Z,1722277185.853 [NAL9602](INFO): GPS fix at 20240729T181945: (36.802507, -121.787037)
2024-07-29T18:19:49.083Z,1722277189.083 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181948.00,A,3648.15021,N,12147.22224,W,0.058,7.27,290724,,,A*7F
2024-07-29T18:19:49.085Z,1722277189.085 [NAL9602](INFO): GPS fix at 20240729T181948: (36.802504, -121.787037)
2024-07-29T18:19:49.596Z,1722277189.596 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002516
CHAN A1 (24V): 0.001016
CHAN A2 (12V): -0.002189
CHAN A3 (5V): -0.001843
CHAN B0 (3.3V): -0.000186
CHAN B1 (3.15aV): 0.000308
CHAN B2 (3.15bV): -0.000036
CHAN B3 (GND): 0.000547
OPEN: -0.000492
Full Scale: +/- 1 mA
2024-07-29T18:19:51.910Z,1722277191.910 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181951.00,A,3648.15011,N,12147.22224,W,0.039,7.27,290724,,,A*73
2024-07-29T18:19:51.912Z,1722277191.912 [NAL9602](INFO): GPS fix at 20240729T181951: (36.802502, -121.787037)
2024-07-29T18:19:55.148Z,1722277195.148 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181954.00,A,3648.15001,N,12147.22220,W,0.058,7.27,290724,,,A*74
2024-07-29T18:19:55.158Z,1722277195.158 [NAL9602](INFO): GPS fix at 20240729T181954: (36.802500, -121.787037)
2024-07-29T18:19:57.971Z,1722277197.971 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181957.00,A,3648.14995,N,12147.22219,W,0.039,7.27,290724,,,A*7F
2024-07-29T18:19:57.974Z,1722277197.974 [NAL9602](INFO): GPS fix at 20240729T181957: (36.802499, -121.787036)
2024-07-29T18:20:01.207Z,1722277201.207 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182000.00,A,3648.14990,N,12147.22215,W,0.058,7.27,290724,,,A*79
2024-07-29T18:20:01.218Z,1722277201.218 [NAL9602](INFO): GPS fix at 20240729T182000: (36.802498, -121.787036)
2024-07-29T18:20:02.031Z,1722277202.031 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 36.802498 Longitude: -121.787033
2024-07-29T18:20:02.454Z,1722277202.454 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.650999
2024-07-29T18:20:02.455Z,1722277202.455 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2024-07-29T18:20:02.455Z,1722277202.455 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2024-07-29T18:20:02.854Z,1722277202.854 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2024-07-29T18:20:02.854Z,1722277202.854 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2024-07-29T18:20:02.855Z,1722277202.855 [IBIT](IMPORTANT): Pressure:10.308581 PSI
2024-07-29T18:20:02.855Z,1722277202.855 [IBIT](IMPORTANT): Humidity:14.094739 %
2024-07-29T18:20:03.237Z,1722277203.237 [IBIT](IMPORTANT): Vehicle Pitch:-0.582069 degrees
2024-07-29T18:20:03.237Z,1722277203.237 [IBIT](IMPORTANT): Vehicle Roll:-2.448937 degrees
2024-07-29T18:20:03.238Z,1722277203.238 [IBIT](IMPORTANT): Vehicle Heading:222.559494 degrees
2024-07-29T18:20:03.646Z,1722277203.646 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2024-07-29T18:20:03.646Z,1722277203.646 [IBIT](IMPORTANT): buoyancyNeutral: 324.584595 cc
2024-07-29T18:20:03.647Z,1722277203.647 [IBIT](IMPORTANT): massDefault: -0.128062 cm
2024-07-29T18:20:03.647Z,1722277203.647 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2024-07-29T18:20:03.647Z,1722277203.647 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2024-07-29T18:20:03.647Z,1722277203.647 [IBIT](IMPORTANT): IBIT PASSED
2024-07-29T18:20:33.525Z,1722277233.525 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-29T18:21:28.879Z,1722277288.879 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-29T18:21:28.879Z,1722277288.879 [Default:CheckIn:C.Wait] Stopped
2024-07-29T18:21:28.879Z,1722277288.879 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-29T18:21:28.879Z,1722277288.879 [Default:CheckIn:D] Running Loop=1
2024-07-29T18:21:29.294Z,1722277289.294 [Default:CheckIn:D] Stopped
2024-07-29T18:21:29.294Z,1722277289.294 [Default:CheckIn:E] Running Loop=1
2024-07-29T18:21:29.715Z,1722277289.715 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 167.524202 min
2024-07-29T18:21:29.715Z,1722277289.715 [Default:CheckIn:E] Stopped
2024-07-29T18:21:29.715Z,1722277289.715 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-29T18:21:29.716Z,1722277289.716 [Default:CheckIn] Stopped
2024-07-29T18:21:29.716Z,1722277289.716 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-29T18:21:29.716Z,1722277289.716 [Default:CheckIn](INFO): Running loop #23
2024-07-29T18:21:29.716Z,1722277289.716 [Default:CheckIn] Running Loop=23
2024-07-29T18:21:29.716Z,1722277289.716 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-29T18:21:29.716Z,1722277289.716 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-29T18:21:31.699Z,1722277291.699 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182130.00,A,3648.15091,N,12147.22271,W,0.019,7.27,290724,,,A*75
2024-07-29T18:21:31.701Z,1722277291.701 [NAL9602](INFO): GPS fix at 20240729T182130: (36.802515, -121.787045)
2024-07-29T18:21:31.712Z,1722277291.712 [Default:CheckIn:Read_GPS] Stopped
2024-07-29T18:21:31.712Z,1722277291.712 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-29T18:21:39.853Z,1722277299.853 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240729T153024/Courier0073.lzma
2024-07-29T18:21:40.854Z,1722277300.854 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Courier0073.lzma.bak
2024-07-29T18:21:40.855Z,1722277300.855 [DataOverHttps](INFO): SBD MOMSN=21392683
2024-07-29T18:21:49.883Z,1722277309.883 [NAL9602](INFO): SBD MO Status=0, MOMSN=53453, MT Status=0, MTMSN=0
2024-07-29T18:21:49.883Z,1722277309.883 [NAL9602](INFO): No messages in MT queue
2024-07-29T18:21:57.022Z,1722277317.022 [DataOverHttps](INFO): Sending 785 bytes from file Logs/20240729T153024/Express0074.lzma
2024-07-29T18:21:58.023Z,1722277318.023 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Express0074.lzma.bak
2024-07-29T18:21:58.023Z,1722277318.023 [DataOverHttps](INFO): SBD MOMSN=21392686
2024-07-29T18:22:14.240Z,1722277334.240 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20240729T153024/Express0077.lzma
2024-07-29T18:22:15.243Z,1722277335.243 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Express0077.lzma.bak
2024-07-29T18:22:15.243Z,1722277335.243 [DataOverHttps](INFO): SBD MOMSN=21392714
2024-07-29T18:22:16.560Z,1722277336.560 [Default:CheckIn:Read_Iridium] Stopped
2024-07-29T18:22:16.560Z,1722277336.560 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-29T18:22:16.560Z,1722277336.560 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-29T18:22:20.618Z,1722277340.618 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-29T18:23:24.838Z,1722277404.838 [DataOverHttps](IMPORTANT): SBD MTMSN=20240729T182323
2024-07-29T18:23:32.616Z,1722277412.616 [DataOverHttps](INFO): Received command: failc
2024-07-29T18:23:32.625Z,1722277412.625 [CommandExec](IMPORTANT): got command failComponent
2024-07-29T18:23:32.625Z,1722277412.625 [CommandExec](IMPORTANT): Failed components:
2024-07-29T18:23:32.626Z,1722277412.626 [CommandExec](IMPORTANT): No failed Components.
2024-07-29T18:27:17.235Z,1722277637.235 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-29T18:27:17.235Z,1722277637.235 [Default:CheckIn:C.Wait] Stopped
2024-07-29T18:27:17.235Z,1722277637.235 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-29T18:27:17.235Z,1722277637.235 [Default:CheckIn:D] Running Loop=1
2024-07-29T18:27:17.639Z,1722277637.639 [Default:CheckIn:D] Stopped
2024-07-29T18:27:17.639Z,1722277637.639 [Default:CheckIn:E] Running Loop=1
2024-07-29T18:27:18.048Z,1722277638.048 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 173.329948 min
2024-07-29T18:27:18.048Z,1722277638.048 [Default:CheckIn:E] Stopped
2024-07-29T18:27:18.049Z,1722277638.049 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-29T18:27:18.049Z,1722277638.049 [Default:CheckIn] Stopped
2024-07-29T18:27:18.049Z,1722277638.049 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-29T18:27:18.049Z,1722277638.049 [Default:CheckIn](INFO): Running loop #24
2024-07-29T18:27:18.049Z,1722277638.049 [Default:CheckIn] Running Loop=24
2024-07-29T18:27:18.049Z,1722277638.049 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-29T18:27:18.049Z,1722277638.049 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-29T18:27:20.054Z,1722277640.054 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182719.00,A,3648.14972,N,12147.22238,W,0.019,7.27,290724,,,D*75
2024-07-29T18:27:20.057Z,1722277640.057 [NAL9602](INFO): GPS fix at 20240729T182719: (36.802495, -121.787040)
2024-07-29T18:27:20.089Z,1722277640.089 [Default:CheckIn:Read_GPS] Stopped
2024-07-29T18:27:20.089Z,1722277640.089 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-29T18:27:28.132Z,1722277648.132 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240729T153024/Courier0079.lzma
2024-07-29T18:27:29.135Z,1722277649.135 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Courier0079.lzma.bak
2024-07-29T18:27:29.135Z,1722277649.135 [DataOverHttps](INFO): SBD MOMSN=21392727
2024-07-29T18:27:35.819Z,1722277655.819 [NAL9602](INFO): SBD MO Status=0, MOMSN=53454, MT Status=0, MTMSN=0
2024-07-29T18:27:35.819Z,1722277655.819 [NAL9602](INFO): No messages in MT queue
2024-07-29T18:27:45.393Z,1722277665.393 [DataOverHttps](INFO): Sending 216 bytes from file Logs/20240729T153024/Express0080.lzma
2024-07-29T18:27:46.400Z,1722277666.400 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Express0080.lzma.bak
2024-07-29T18:27:46.400Z,1722277666.400 [DataOverHttps](INFO): SBD MOMSN=21392730
2024-07-29T18:28:02.820Z,1722277682.820 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20240729T153024/Express0083.lzma
2024-07-29T18:28:03.824Z,1722277683.824 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Express0083.lzma.bak
2024-07-29T18:28:03.824Z,1722277683.824 [DataOverHttps](INFO): SBD MOMSN=21392738
2024-07-29T18:28:04.911Z,1722277684.911 [Default:CheckIn:Read_Iridium] Stopped
2024-07-29T18:28:04.911Z,1722277684.911 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-29T18:28:04.911Z,1722277684.911 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-29T18:28:06.528Z,1722277686.528 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-29T18:33:05.513Z,1722277985.513 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-29T18:33:05.513Z,1722277985.513 [Default:CheckIn:C.Wait] Stopped
2024-07-29T18:33:05.513Z,1722277985.513 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-29T18:33:05.513Z,1722277985.513 [Default:CheckIn:D] Running Loop=1
2024-07-29T18:33:05.905Z,1722277985.905 [Default:CheckIn:D] Stopped
2024-07-29T18:33:05.905Z,1722277985.905 [Default:CheckIn:E] Running Loop=1
2024-07-29T18:33:06.306Z,1722277986.306 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 179.134391 min
2024-07-29T18:33:06.306Z,1722277986.306 [Default:CheckIn:E] Stopped
2024-07-29T18:33:06.307Z,1722277986.307 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-29T18:33:06.307Z,1722277986.307 [Default:CheckIn] Stopped
2024-07-29T18:33:06.307Z,1722277986.307 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-29T18:33:06.307Z,1722277986.307 [Default:CheckIn](INFO): Running loop #25
2024-07-29T18:33:06.307Z,1722277986.307 [Default:CheckIn] Running Loop=25
2024-07-29T18:33:06.307Z,1722277986.307 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-29T18:33:06.307Z,1722277986.307 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-29T18:33:08.315Z,1722277988.315 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183307.00,A,3648.14936,N,12147.22208,W,0.039,255.90,290724,,,D*77
2024-07-29T18:33:08.317Z,1722277988.317 [NAL9602](INFO): GPS fix at 20240729T183307: (36.802489, -121.787035)
2024-07-29T18:33:08.347Z,1722277988.347 [Default:CheckIn:Read_GPS] Stopped
2024-07-29T18:33:08.348Z,1722277988.348 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-29T18:33:15.656Z,1722277995.656 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240729T153024/Courier0085.lzma
2024-07-29T18:33:16.659Z,1722277996.659 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Courier0085.lzma.bak
2024-07-29T18:33:16.659Z,1722277996.659 [DataOverHttps](INFO): SBD MOMSN=21392740
2024-07-29T18:33:25.278Z,1722278005.278 [NAL9602](INFO): SBD MO Status=0, MOMSN=53455, MT Status=0, MTMSN=0
2024-07-29T18:33:25.278Z,1722278005.278 [NAL9602](INFO): No messages in MT queue
2024-07-29T18:33:32.657Z,1722278012.657 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240729T153024/Express0086.lzma
2024-07-29T18:33:33.659Z,1722278013.659 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Express0086.lzma.bak
2024-07-29T18:33:33.659Z,1722278013.659 [DataOverHttps](INFO): SBD MOMSN=21392743
2024-07-29T18:33:34.988Z,1722278014.988 [Default:CheckIn:Read_Iridium] Stopped
2024-07-29T18:33:34.988Z,1722278014.988 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-29T18:33:34.988Z,1722278014.988 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-29T18:33:55.991Z,1722278035.991 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-29T18:38:35.562Z,1722278315.562 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-29T18:38:35.562Z,1722278315.562 [Default:CheckIn:C.Wait] Stopped
2024-07-29T18:38:35.562Z,1722278315.562 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-29T18:38:35.562Z,1722278315.562 [Default:CheckIn:D] Running Loop=1
2024-07-29T18:38:35.978Z,1722278315.978 [Default:CheckIn:D] Stopped
2024-07-29T18:38:35.978Z,1722278315.978 [Default:CheckIn:E] Running Loop=1
2024-07-29T18:38:36.373Z,1722278316.373 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 184.635596 min
2024-07-29T18:38:36.373Z,1722278316.373 [Default:CheckIn:E] Stopped
2024-07-29T18:38:36.373Z,1722278316.373 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-29T18:38:36.373Z,1722278316.373 [Default:CheckIn] Stopped
2024-07-29T18:38:36.373Z,1722278316.373 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-29T18:38:36.373Z,1722278316.373 [Default:CheckIn](INFO): Running loop #26
2024-07-29T18:38:36.373Z,1722278316.373 [Default:CheckIn] Running Loop=26
2024-07-29T18:38:36.374Z,1722278316.374 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-29T18:38:36.374Z,1722278316.374 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-29T18:38:38.380Z,1722278318.380 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183837.00,A,3648.14977,N,12147.22271,W,0.039,255.90,290724,,,D*74
2024-07-29T18:38:38.382Z,1722278318.382 [NAL9602](INFO): GPS fix at 20240729T183837: (36.802496, -121.787045)
2024-07-29T18:38:38.398Z,1722278318.398 [Default:CheckIn:Read_GPS] Stopped
2024-07-29T18:38:38.398Z,1722278318.398 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-29T18:38:46.292Z,1722278326.292 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240729T153024/Courier0088.lzma
2024-07-29T18:38:47.295Z,1722278327.295 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Courier0088.lzma.bak
2024-07-29T18:38:47.295Z,1722278327.295 [DataOverHttps](INFO): SBD MOMSN=21392753
2024-07-29T18:39:03.107Z,1722278343.107 [NAL9602](INFO): SBD MO Status=0, MOMSN=53456, MT Status=0, MTMSN=0
2024-07-29T18:39:03.107Z,1722278343.107 [NAL9602](INFO): No messages in MT queue
2024-07-29T18:39:06.432Z,1722278346.432 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240729T153024/Express0089.lzma
2024-07-29T18:39:07.436Z,1722278347.436 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Express0089.lzma.bak
2024-07-29T18:39:07.436Z,1722278347.436 [DataOverHttps](INFO): SBD MOMSN=21392756
2024-07-29T18:39:08.776Z,1722278348.776 [Default:CheckIn:Read_Iridium] Stopped
2024-07-29T18:39:08.776Z,1722278348.776 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-29T18:39:08.776Z,1722278348.776 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-29T18:39:33.808Z,1722278373.808 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-29T18:44:09.388Z,1722278649.388 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-29T18:44:09.388Z,1722278649.388 [Default:CheckIn:C.Wait] Stopped
2024-07-29T18:44:09.388Z,1722278649.388 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-29T18:44:09.388Z,1722278649.388 [Default:CheckIn:D] Running Loop=1
2024-07-29T18:44:09.788Z,1722278649.788 [Default:CheckIn:D] Stopped
2024-07-29T18:44:09.788Z,1722278649.788 [Default:CheckIn:E] Running Loop=1
2024-07-29T18:44:10.204Z,1722278650.204 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 190.199105 min
2024-07-29T18:44:10.204Z,1722278650.204 [Default:CheckIn:E] Stopped
2024-07-29T18:44:10.204Z,1722278650.204 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-29T18:44:10.204Z,1722278650.204 [Default:CheckIn] Stopped
2024-07-29T18:44:10.204Z,1722278650.204 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-29T18:44:10.204Z,1722278650.204 [Default:CheckIn](INFO): Running loop #27
2024-07-29T18:44:10.204Z,1722278650.204 [Default:CheckIn] Running Loop=27
2024-07-29T18:44:10.205Z,1722278650.205 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-29T18:44:10.205Z,1722278650.205 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-29T18:44:12.207Z,1722278652.207 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184411.00,A,3648.14953,N,12147.22252,W,0.039,255.90,290724,,,D*7C
2024-07-29T18:44:12.210Z,1722278652.210 [NAL9602](INFO): GPS fix at 20240729T184411: (36.802492, -121.787042)
2024-07-29T18:44:12.240Z,1722278652.240 [Default:CheckIn:Read_GPS] Stopped
2024-07-29T18:44:12.241Z,1722278652.241 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-29T18:44:21.200Z,1722278661.200 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240729T153024/Courier0091.lzma
2024-07-29T18:44:22.203Z,1722278662.203 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Courier0091.lzma.bak
2024-07-29T18:44:22.203Z,1722278662.203 [DataOverHttps](INFO): SBD MOMSN=21392806
2024-07-29T18:44:40.040Z,1722278680.040 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240729T153024/Express0092.lzma
2024-07-29T18:44:41.044Z,1722278681.044 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Express0092.lzma.bak
2024-07-29T18:44:41.045Z,1722278681.045 [DataOverHttps](INFO): SBD MOMSN=21392809
2024-07-29T18:44:41.315Z,1722278681.315 [NAL9602](INFO): SBD MO Status=0, MOMSN=53457, MT Status=0, MTMSN=0
2024-07-29T18:44:41.315Z,1722278681.315 [NAL9602](INFO): No messages in MT queue
2024-07-29T18:44:42.133Z,1722278682.133 [Default:CheckIn:Read_Iridium] Stopped
2024-07-29T18:44:42.133Z,1722278682.133 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-29T18:44:42.133Z,1722278682.133 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-29T18:45:12.026Z,1722278712.026 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-29T18:49:42.922Z,1722278982.922 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-29T18:49:42.922Z,1722278982.922 [Default:CheckIn:C.Wait] Stopped
2024-07-29T18:49:42.922Z,1722278982.922 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-29T18:49:42.922Z,1722278982.922 [Default:CheckIn:D] Running Loop=1
2024-07-29T18:49:43.307Z,1722278983.307 [Default:CheckIn:D] Stopped
2024-07-29T18:49:43.307Z,1722278983.307 [Default:CheckIn:E] Running Loop=1
2024-07-29T18:49:43.720Z,1722278983.720 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 195.757747 min
2024-07-29T18:49:43.720Z,1722278983.720 [Default:CheckIn:E] Stopped
2024-07-29T18:49:43.720Z,1722278983.720 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-29T18:49:43.720Z,1722278983.720 [Default:CheckIn] Stopped
2024-07-29T18:49:43.721Z,1722278983.721 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-29T18:49:43.721Z,1722278983.721 [Default:CheckIn](INFO): Running loop #28
2024-07-29T18:49:43.721Z,1722278983.721 [Default:CheckIn] Running Loop=28
2024-07-29T18:49:43.721Z,1722278983.721 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-29T18:49:43.721Z,1722278983.721 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-29T18:49:45.732Z,1722278985.732 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184944.00,A,3648.14918,N,12147.22247,W,0.019,255.90,290724,,,D*78
2024-07-29T18:49:45.734Z,1722278985.734 [NAL9602](INFO): GPS fix at 20240729T184944: (36.802486, -121.787041)
2024-07-29T18:49:45.745Z,1722278985.745 [Default:CheckIn:Read_GPS] Stopped
2024-07-29T18:49:45.745Z,1722278985.745 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-29T18:49:53.576Z,1722278993.576 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240729T153024/Courier0094.lzma
2024-07-29T18:49:54.579Z,1722278994.579 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Courier0094.lzma.bak
2024-07-29T18:49:54.579Z,1722278994.579 [DataOverHttps](INFO): SBD MOMSN=21392822
2024-07-29T18:50:10.436Z,1722279010.436 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20240729T153024/Express0095.lzma
2024-07-29T18:50:11.439Z,1722279011.439 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Express0095.lzma.bak
2024-07-29T18:50:11.439Z,1722279011.439 [DataOverHttps](INFO): SBD MOMSN=21392825
2024-07-29T18:50:12.817Z,1722279012.817 [Default:CheckIn:Read_Iridium] Stopped
2024-07-29T18:50:12.817Z,1722279012.817 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-29T18:50:12.817Z,1722279012.817 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-29T18:50:19.259Z,1722279019.259 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T18:50:19.267Z,1722279019.267 [NAL9602](INFO): SBD MO Status=2, MOMSN=53458, MT Status=2, MTMSN=0
2024-07-29T18:50:19.267Z,1722279019.267 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-29T18:50:31.381Z,1722279031.381 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T18:50:42.723Z,1722279042.723 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T18:50:49.975Z,1722279049.975 [NAL9602](INFO): SBD MO Status=0, MOMSN=53458, MT Status=0, MTMSN=0
2024-07-29T18:50:49.975Z,1722279049.975 [NAL9602](INFO): No messages in MT queue
2024-07-29T18:50:54.409Z,1722279054.409 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T18:51:06.123Z,1722279066.123 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T18:51:17.839Z,1722279077.839 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T18:51:20.674Z,1722279080.674 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-29T18:51:29.569Z,1722279089.569 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T18:51:41.746Z,1722279101.746 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T18:54:16.104Z,1722279256.104 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T18:54:29.487Z,1722279269.487 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T18:54:43.223Z,1722279283.223 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T18:54:56.599Z,1722279296.599 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T18:55:09.528Z,1722279309.528 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T18:55:13.591Z,1722279313.591 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-29T18:55:13.591Z,1722279313.591 [Default:CheckIn:C.Wait] Stopped
2024-07-29T18:55:13.592Z,1722279313.592 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-29T18:55:13.592Z,1722279313.592 [Default:CheckIn:D] Running Loop=1
2024-07-29T18:55:13.985Z,1722279313.985 [Default:CheckIn:D] Stopped
2024-07-29T18:55:13.985Z,1722279313.985 [Default:CheckIn:E] Running Loop=1
2024-07-29T18:55:14.393Z,1722279314.393 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 201.269043 min
2024-07-29T18:55:14.393Z,1722279314.393 [Default:CheckIn:E] Stopped
2024-07-29T18:55:14.393Z,1722279314.393 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-29T18:55:14.393Z,1722279314.393 [Default:CheckIn] Stopped
2024-07-29T18:55:14.393Z,1722279314.393 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-29T18:55:14.393Z,1722279314.393 [Default:CheckIn](INFO): Running loop #29
2024-07-29T18:55:14.393Z,1722279314.393 [Default:CheckIn] Running Loop=29
2024-07-29T18:55:14.394Z,1722279314.394 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-29T18:55:14.394Z,1722279314.394 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-29T18:55:16.412Z,1722279316.412 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185515.00,A,3648.20608,N,12147.14425,W,4.724,16.95,290724,,,D*42
2024-07-29T18:55:16.418Z,1722279316.418 [NAL9602](INFO): GPS fix at 20240729T185515: (36.803435, -121.785737)
2024-07-29T18:55:16.465Z,1722279316.465 [Default:CheckIn:Read_GPS] Stopped
2024-07-29T18:55:16.465Z,1722279316.465 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-29T18:55:22.608Z,1722279322.608 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240729T153024/Courier0097.lzma
2024-07-29T18:55:22.863Z,1722279322.863 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T18:55:23.611Z,1722279323.611 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Courier0097.lzma.bak
2024-07-29T18:55:23.611Z,1722279323.611 [DataOverHttps](INFO): SBD MOMSN=21392830
2024-07-29T18:55:36.595Z,1722279336.595 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T18:55:39.556Z,1722279339.556 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20240729T153024/Express0098.lzma
2024-07-29T18:55:40.559Z,1722279340.559 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Express0098.lzma.bak
2024-07-29T18:55:40.559Z,1722279340.559 [DataOverHttps](INFO): SBD MOMSN=21392833
2024-07-29T18:55:41.873Z,1722279341.873 [Default:CheckIn:Read_Iridium] Stopped
2024-07-29T18:55:41.873Z,1722279341.873 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-29T18:55:41.873Z,1722279341.873 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-29T18:55:45.206Z,1722279345.206 [NAL9602](INFO): SBD MO Status=0, MOMSN=53459, MT Status=0, MTMSN=0
2024-07-29T18:55:45.206Z,1722279345.206 [NAL9602](INFO): No messages in MT queue
2024-07-29T18:55:49.928Z,1722279349.928 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T18:56:03.663Z,1722279363.663 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T18:56:15.801Z,1722279375.801 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-29T18:56:17.401Z,1722279377.401 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T18:56:25.910Z,1722279385.910 [BPC1](ERROR): BPC1A: No match for serial number 0000 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2024-07-29T18:56:31.144Z,1722279391.144 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-07-29T18:56:32.766Z,1722279392.766 [BPC1](INFO): Calculating totals. Valid battery stick count: 42. Valid reserve battery stick count: 5.
2024-07-29T18:56:32.768Z,1722279392.768 [BPC1](INFO): Received data from all battery sticks.
2024-07-29T19:00:42.584Z,1722279642.584 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-29T19:00:42.585Z,1722279642.585 [Default:CheckIn:C.Wait] Stopped
2024-07-29T19:00:42.585Z,1722279642.585 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-29T19:00:42.585Z,1722279642.585 [Default:CheckIn:D] Running Loop=1
2024-07-29T19:00:42.995Z,1722279642.995 [Default:CheckIn:D] Stopped
2024-07-29T19:00:42.995Z,1722279642.995 [Default:CheckIn:E] Running Loop=1
2024-07-29T19:00:43.393Z,1722279643.393 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 206.752555 min
2024-07-29T19:00:43.393Z,1722279643.393 [Default:CheckIn:E] Stopped
2024-07-29T19:00:43.393Z,1722279643.393 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-29T19:00:43.393Z,1722279643.393 [Default:CheckIn] Stopped
2024-07-29T19:00:43.393Z,1722279643.393 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-29T19:00:43.393Z,1722279643.393 [Default:CheckIn](INFO): Running loop #30
2024-07-29T19:00:43.393Z,1722279643.393 [Default:CheckIn] Running Loop=30
2024-07-29T19:00:43.393Z,1722279643.393 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-29T19:00:43.394Z,1722279643.394 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-29T19:00:45.398Z,1722279645.398 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190044.00,A,3648.44236,N,12147.35147,W,8.436,234.99,290724,,,D*7C
2024-07-29T19:00:45.401Z,1722279645.401 [NAL9602](INFO): GPS fix at 20240729T190044: (36.807373, -121.789191)
2024-07-29T19:00:45.411Z,1722279645.411 [Default:CheckIn:Read_GPS] Stopped
2024-07-29T19:00:45.412Z,1722279645.412 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-29T19:00:55.568Z,1722279655.568 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20240729T153024/Courier0100.lzma
2024-07-29T19:00:56.571Z,1722279656.571 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Courier0100.lzma.bak
2024-07-29T19:00:56.571Z,1722279656.571 [DataOverHttps](INFO): SBD MOMSN=21392848
2024-07-29T19:01:12.585Z,1722279672.585 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20240729T153024/Express0101.lzma
2024-07-29T19:01:21.140Z,1722279681.140 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20240729T153024/Express0101.lzma
2024-07-29T19:01:22.143Z,1722279682.143 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Express0101.lzma.bak
2024-07-29T19:01:22.143Z,1722279682.143 [DataOverHttps](INFO): SBD MOMSN=21392851
2024-07-29T19:01:23.457Z,1722279683.457 [Default:CheckIn:Read_Iridium] Stopped
2024-07-29T19:01:23.457Z,1722279683.457 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-29T19:01:23.457Z,1722279683.457 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-29T19:01:36.780Z,1722279696.780 [NAL9602](INFO): SBD MO Status=2, MOMSN=53460, MT Status=2, MTMSN=0
2024-07-29T19:01:36.780Z,1722279696.780 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-29T19:02:05.456Z,1722279725.456 [NAL9602](INFO): SBD MO Status=0, MOMSN=53460, MT Status=0, MTMSN=0
2024-07-29T19:02:05.456Z,1722279725.456 [NAL9602](INFO): No messages in MT queue
2024-07-29T19:02:36.157Z,1722279756.157 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-29T19:06:24.115Z,1722279984.115 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-29T19:06:24.115Z,1722279984.115 [Default:CheckIn:C.Wait] Stopped
2024-07-29T19:06:24.115Z,1722279984.115 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-29T19:06:24.115Z,1722279984.115 [Default:CheckIn:D] Running Loop=1
2024-07-29T19:06:24.523Z,1722279984.523 [Default:CheckIn:D] Stopped
2024-07-29T19:06:24.523Z,1722279984.523 [Default:CheckIn:E] Running Loop=1
2024-07-29T19:06:24.940Z,1722279984.940 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 212.444694 min
2024-07-29T19:06:24.945Z,1722279984.945 [Default:CheckIn:E] Stopped
2024-07-29T19:06:24.945Z,1722279984.945 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-29T19:06:24.945Z,1722279984.945 [Default:CheckIn] Stopped
2024-07-29T19:06:24.945Z,1722279984.945 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-29T19:06:24.945Z,1722279984.945 [Default:CheckIn](INFO): Running loop #31
2024-07-29T19:06:24.945Z,1722279984.945 [Default:CheckIn] Running Loop=31
2024-07-29T19:06:24.945Z,1722279984.945 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-29T19:06:24.945Z,1722279984.945 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-29T19:06:26.931Z,1722279986.931 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190626.00,A,3648.32791,N,12148.85270,W,15.803,285.04,290724,,,A*49
2024-07-29T19:06:26.934Z,1722279986.934 [NAL9602](INFO): GPS fix at 20240729T190626: (36.805465, -121.814212)
2024-07-29T19:06:26.948Z,1722279986.948 [Default:CheckIn:Read_GPS] Stopped
2024-07-29T19:06:26.948Z,1722279986.948 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-29T19:06:34.040Z,1722279994.040 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240729T153024/Courier0103.lzma
2024-07-29T19:06:35.055Z,1722279995.055 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Courier0103.lzma.bak
2024-07-29T19:06:35.055Z,1722279995.055 [DataOverHttps](INFO): SBD MOMSN=21392858
2024-07-29T19:06:40.655Z,1722280000.655 [NAL9602](INFO): SBD MO Status=0, MOMSN=53461, MT Status=0, MTMSN=0
2024-07-29T19:06:40.655Z,1722280000.655 [NAL9602](INFO): No messages in MT queue
2024-07-29T19:07:11.358Z,1722280031.358 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-29T19:08:11.688Z,1722280091.688 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20240729T153024/Express0104.lzma
2024-07-29T19:08:12.691Z,1722280092.691 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Express0104.lzma.bak
2024-07-29T19:08:12.691Z,1722280092.691 [DataOverHttps](INFO): SBD MOMSN=21392862
2024-07-29T19:08:13.999Z,1722280093.999 [Default:CheckIn:Read_Iridium] Stopped
2024-07-29T19:08:13.999Z,1722280093.999 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-29T19:08:13.999Z,1722280093.999 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-29T19:13:14.434Z,1722280394.434 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-29T19:13:14.434Z,1722280394.434 [Default:CheckIn:C.Wait] Stopped
2024-07-29T19:13:14.434Z,1722280394.434 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-29T19:13:14.434Z,1722280394.434 [Default:CheckIn:D] Running Loop=1
2024-07-29T19:13:14.845Z,1722280394.845 [Default:CheckIn:D] Stopped
2024-07-29T19:13:14.845Z,1722280394.845 [Default:CheckIn:E] Running Loop=1
2024-07-29T19:13:15.241Z,1722280395.241 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 219.283398 min
2024-07-29T19:13:15.241Z,1722280395.241 [Default:CheckIn:E] Stopped
2024-07-29T19:13:15.241Z,1722280395.241 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-29T19:13:15.241Z,1722280395.241 [Default:CheckIn] Stopped
2024-07-29T19:13:15.241Z,1722280395.241 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-29T19:13:15.242Z,1722280395.242 [Default:CheckIn](INFO): Running loop #32
2024-07-29T19:13:15.242Z,1722280395.242 [Default:CheckIn] Running Loop=32
2024-07-29T19:13:15.242Z,1722280395.242 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-29T19:13:15.242Z,1722280395.242 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-29T19:13:17.251Z,1722280397.251 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191316.00,A,3647.79770,N,12150.86679,W,0.156,55.92,290724,,,D*4E
2024-07-29T19:13:17.253Z,1722280397.253 [NAL9602](INFO): GPS fix at 20240729T191316: (36.796628, -121.847780)
2024-07-29T19:13:17.264Z,1722280397.264 [Default:CheckIn:Read_GPS] Stopped
2024-07-29T19:13:17.264Z,1722280397.264 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-29T19:13:25.080Z,1722280405.080 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240729T153024/Courier0106.lzma
2024-07-29T19:13:26.083Z,1722280406.083 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Courier0106.lzma.bak
2024-07-29T19:13:26.083Z,1722280406.083 [DataOverHttps](INFO): SBD MOMSN=21392865
2024-07-29T19:13:38.659Z,1722280418.659 [NAL9602](INFO): SBD MO Status=0, MOMSN=53462, MT Status=0, MTMSN=0
2024-07-29T19:13:38.659Z,1722280418.659 [NAL9602](INFO): No messages in MT queue
2024-07-29T19:13:41.972Z,1722280421.972 [DataOverHttps](INFO): Sending 147 bytes from file Logs/20240729T153024/Express0107.lzma
2024-07-29T19:13:42.975Z,1722280422.975 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Express0107.lzma.bak
2024-07-29T19:13:42.975Z,1722280422.975 [DataOverHttps](INFO): SBD MOMSN=21392868
2024-07-29T19:13:44.325Z,1722280424.325 [Default:CheckIn:Read_Iridium] Stopped
2024-07-29T19:13:44.325Z,1722280424.325 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-29T19:13:44.325Z,1722280424.325 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-29T19:14:09.366Z,1722280449.366 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-29T19:17:18.585Z,1722280638.585 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-29T19:18:45.125Z,1722280725.125 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-29T19:18:45.125Z,1722280725.125 [Default:CheckIn:C.Wait] Stopped
2024-07-29T19:18:45.125Z,1722280725.125 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-29T19:18:45.125Z,1722280725.125 [Default:CheckIn:D] Running Loop=1
2024-07-29T19:18:45.524Z,1722280725.524 [Default:CheckIn:D] Stopped
2024-07-29T19:18:45.524Z,1722280725.524 [Default:CheckIn:E] Running Loop=1
2024-07-29T19:18:45.932Z,1722280725.932 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 224.794710 min
2024-07-29T19:18:45.932Z,1722280725.932 [Default:CheckIn:E] Stopped
2024-07-29T19:18:45.932Z,1722280725.932 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-29T19:18:45.932Z,1722280725.932 [Default:CheckIn] Stopped
2024-07-29T19:18:45.932Z,1722280725.932 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-29T19:18:45.932Z,1722280725.932 [Default:CheckIn](INFO): Running loop #33
2024-07-29T19:18:45.932Z,1722280725.932 [Default:CheckIn] Running Loop=33
2024-07-29T19:18:45.933Z,1722280725.933 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-29T19:18:45.933Z,1722280725.933 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-29T19:18:47.932Z,1722280727.932 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191847.00,A,3647.80393,N,12150.83063,W,0.058,93.15,290724,,,D*4C
2024-07-29T19:18:47.935Z,1722280727.935 [NAL9602](INFO): GPS fix at 20240729T191847: (36.796732, -121.847177)
2024-07-29T19:18:47.955Z,1722280727.955 [Default:CheckIn:Read_GPS] Stopped
2024-07-29T19:18:47.955Z,1722280727.955 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-29T19:18:58.312Z,1722280738.312 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240729T153024/Courier0109.lzma
2024-07-29T19:18:59.315Z,1722280739.315 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Courier0109.lzma.bak
2024-07-29T19:18:59.315Z,1722280739.315 [DataOverHttps](INFO): SBD MOMSN=21392872
2024-07-29T19:19:09.815Z,1722280749.815 [NAL9602](INFO): SBD MO Status=0, MOMSN=53463, MT Status=0, MTMSN=0
2024-07-29T19:19:09.815Z,1722280749.815 [NAL9602](INFO): No messages in MT queue
2024-07-29T19:19:15.024Z,1722280755.024 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20240729T153024/Express0110.lzma
2024-07-29T19:19:16.027Z,1722280756.027 [DataOverHttps](INFO): Moved sent file to Logs/20240729T153024/Express0110.lzma.bak
2024-07-29T19:19:16.027Z,1722280756.027 [DataOverHttps](INFO): SBD MOMSN=21392875
2024-07-29T19:19:17.096Z,1722280757.096 [Default:CheckIn:Read_Iridium] Stopped
2024-07-29T19:19:17.096Z,1722280757.096 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-29T19:19:17.096Z,1722280757.096 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-29T19:19:40.518Z,1722280780.518 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-29T19:21:57.270Z,1722280917.270 [DataOverHttps](IMPORTANT): SBD MTMSN=20240729T192156