2024-10-15T14:57:06.527Z,1729004226.527 [Supervisor](DEBUG): Initializing supervisor.
2024-10-15T14:57:06.531Z,1729004226.531 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-10-15T14:57:06.532Z,1729004226.532 [SyncHandler](INFO): Protected caller Thread ID is 837
2024-10-15T14:57:06.532Z,1729004226.532 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-10-15T14:57:06.533Z,1729004226.533 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-10-15T14:57:06.534Z,1729004226.534 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 838
2024-10-15T14:57:06.537Z,1729004226.537 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-10-15T14:57:06.555Z,1729004226.555 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-10-15T14:57:06.556Z,1729004226.556 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-10-15T14:57:06.556Z,1729004226.556 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 839
2024-10-15T14:57:06.560Z,1729004226.560 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-10-15T14:57:06.561Z,1729004226.561 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-10-15T14:57:06.562Z,1729004226.562 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 840
2024-10-15T14:57:06.564Z,1729004226.564 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-10-15T14:57:06.565Z,1729004226.565 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-10-15T14:57:06.565Z,1729004226.565 [logger ThreadHandler](INFO): Protected caller Thread ID is 841
2024-10-15T14:57:06.569Z,1729004226.569 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-10-15T14:57:06.569Z,1729004226.569 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-10-15T14:57:06.573Z,1729004226.573 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-10-15T14:57:06.850Z,1729004226.850 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-10-15T14:57:06.852Z,1729004226.852 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-10-15T14:57:06.947Z,1729004226.947 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-10-15T14:57:07.501Z,1729004227.501 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-10-15T14:57:07.503Z,1729004227.503 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-10-15T14:57:07.863Z,1729004227.863 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-10-15T14:57:07.865Z,1729004227.865 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-10-15T14:57:07.972Z,1729004227.972 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-10-15T14:57:07.974Z,1729004227.974 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-10-15T14:57:08.401Z,1729004228.401 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-10-15T14:57:08.402Z,1729004228.402 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-10-15T14:57:08.630Z,1729004228.630 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-10-15T14:57:08.632Z,1729004228.632 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-10-15T14:57:09.190Z,1729004229.190 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-10-15T14:57:09.221Z,1729004229.221 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-10-15T14:57:09.675Z,1729004229.675 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-10-15T14:57:09.685Z,1729004229.685 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-10-15T14:57:10.097Z,1729004230.097 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-10-15T14:57:10.961Z,1729004230.961 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-10-15T14:57:10.962Z,1729004230.962 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-10-15T14:57:11.363Z,1729004231.363 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-10-15T14:57:11.365Z,1729004231.365 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-10-15T14:57:11.565Z,1729004231.565 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-10-15T14:57:11.565Z,1729004231.565 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-10-15T14:57:11.688Z,1729004231.688 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-10-15T14:57:11.689Z,1729004231.689 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-10-15T14:57:11.930Z,1729004231.930 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-10-15T14:57:11.931Z,1729004231.931 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-10-15T14:57:12.196Z,1729004232.196 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-10-15T14:57:12.198Z,1729004232.198 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2024-10-15T14:57:12.200Z,1729004232.200 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2024-10-15T14:57:12.304Z,1729004232.304 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2024-10-15T14:57:12.401Z,1729004232.401 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2024-10-15T14:57:12.516Z,1729004232.516 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2024-10-15T14:57:12.611Z,1729004232.611 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2024-10-15T14:57:12.726Z,1729004232.726 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2024-10-15T14:57:12.830Z,1729004232.830 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2024-10-15T14:57:12.960Z,1729004232.960 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2024-10-15T14:57:13.126Z,1729004233.126 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2024-10-15T14:57:13.287Z,1729004233.287 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2024-10-15T14:57:13.411Z,1729004233.411 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2024-10-15T14:57:13.687Z,1729004233.687 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-10-15T14:57:13.687Z,1729004233.687 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-10-15T14:57:13.731Z,1729004233.731 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-10-15T14:57:13.803Z,1729004233.803 [VerticalControl](DEBUG): Construct VerticalControl.
2024-10-15T14:57:13.863Z,1729004233.863 [VerticalControl] Loaded
2024-10-15T14:57:13.863Z,1729004233.863 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-10-15T14:57:13.866Z,1729004233.866 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-10-15T14:57:13.913Z,1729004233.913 [HorizontalControl] Loaded
2024-10-15T14:57:13.914Z,1729004233.914 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-10-15T14:57:13.916Z,1729004233.916 [SpeedControl](DEBUG): Construct SpeedControl.
2024-10-15T14:57:13.919Z,1729004233.919 [SpeedControl] Loaded
2024-10-15T14:57:13.919Z,1729004233.919 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-10-15T14:57:13.922Z,1729004233.922 [LoopControl](DEBUG): Construct LoopControl.
2024-10-15T14:57:13.923Z,1729004233.923 [LoopControl] Loaded
2024-10-15T14:57:13.923Z,1729004233.923 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-10-15T14:57:13.923Z,1729004233.923 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-10-15T14:57:13.925Z,1729004233.925 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-10-15T14:57:13.940Z,1729004233.940 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-10-15T14:57:13.941Z,1729004233.941 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-10-15T14:57:14.154Z,1729004234.154 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-10-15T14:57:14.154Z,1729004234.154 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-10-15T14:57:14.341Z,1729004234.341 [BuoyancyServo] Loaded
2024-10-15T14:57:14.341Z,1729004234.341 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-10-15T14:57:14.363Z,1729004234.363 [ElevatorServo] Loaded
2024-10-15T14:57:14.363Z,1729004234.363 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-10-15T14:57:14.384Z,1729004234.384 [MassServo] Loaded
2024-10-15T14:57:14.384Z,1729004234.384 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-10-15T14:57:14.404Z,1729004234.404 [RudderServo] Loaded
2024-10-15T14:57:14.405Z,1729004234.405 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-10-15T14:57:14.421Z,1729004234.421 [ThrusterHE] Loaded
2024-10-15T14:57:14.421Z,1729004234.421 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-10-15T14:57:14.421Z,1729004234.421 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-10-15T14:57:14.422Z,1729004234.422 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-10-15T14:57:14.858Z,1729004234.858 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-10-15T14:57:14.858Z,1729004234.858 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-10-15T14:57:14.933Z,1729004234.933 [NavChart] Loaded
2024-10-15T14:57:14.934Z,1729004234.934 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-10-15T14:57:14.978Z,1729004234.978 [UniversalFixResidualReporter] Loaded
2024-10-15T14:57:14.979Z,1729004234.979 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-10-15T14:57:14.979Z,1729004234.979 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-10-15T14:57:15.170Z,1729004235.170 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-10-15T14:57:15.260Z,1729004235.260 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-10-15T14:57:15.260Z,1729004235.260 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-10-15T14:57:16.216Z,1729004236.216 [AHRS_M2] Loaded
2024-10-15T14:57:16.216Z,1729004236.216 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-10-15T14:57:17.045Z,1729004237.045 [BPC1] Loaded
2024-10-15T14:57:17.046Z,1729004237.046 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-10-15T14:57:17.187Z,1729004237.187 [DataOverHttps] Loaded
2024-10-15T14:57:17.187Z,1729004237.187 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-10-15T14:57:17.188Z,1729004237.188 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409314E0
2024-10-15T14:57:17.189Z,1729004237.189 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 923
2024-10-15T14:57:17.212Z,1729004237.212 [Depth_Keller] Loaded
2024-10-15T14:57:17.212Z,1729004237.212 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-10-15T14:57:17.217Z,1729004237.217 [DropWeight] Loaded
2024-10-15T14:57:17.217Z,1729004237.217 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-10-15T14:57:17.281Z,1729004237.281 [NAL9602] Loaded
2024-10-15T14:57:17.281Z,1729004237.281 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-10-15T14:57:17.311Z,1729004237.311 [Onboard] Loaded
2024-10-15T14:57:17.311Z,1729004237.311 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-10-15T14:57:17.312Z,1729004237.312 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409614E0
2024-10-15T14:57:17.312Z,1729004237.312 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 924
2024-10-15T14:57:17.320Z,1729004237.320 [PowerOnly](INFO): Adding load control power supply at /dev/loadC1
2024-10-15T14:57:17.334Z,1729004237.334 [PowerOnly] Loaded
2024-10-15T14:57:17.334Z,1729004237.334 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2024-10-15T14:57:17.348Z,1729004237.348 [Power24vConverter] Loaded
2024-10-15T14:57:17.348Z,1729004237.348 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-10-15T14:57:17.363Z,1729004237.363 [Radio_Surface] Loaded
2024-10-15T14:57:17.363Z,1729004237.363 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-10-15T14:57:17.364Z,1729004237.364 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409914E0
2024-10-15T14:57:17.365Z,1729004237.365 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 925
2024-10-15T14:57:17.366Z,1729004237.366 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-10-15T14:57:17.366Z,1729004237.366 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-10-15T14:57:17.436Z,1729004237.436 [DepthRateCalculator] Loaded
2024-10-15T14:57:17.436Z,1729004237.436 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-10-15T14:57:17.441Z,1729004237.441 [PitchRateCalculator] Loaded
2024-10-15T14:57:17.441Z,1729004237.441 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-10-15T14:57:17.450Z,1729004237.450 [SpeedCalculator] Loaded
2024-10-15T14:57:17.450Z,1729004237.450 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-10-15T14:57:17.455Z,1729004237.455 [YawRateCalculator] Loaded
2024-10-15T14:57:17.455Z,1729004237.455 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-10-15T14:57:17.474Z,1729004237.474 [ElevatorOffsetCalculator] Loaded
2024-10-15T14:57:17.474Z,1729004237.474 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-10-15T14:57:17.475Z,1729004237.475 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-10-15T14:57:17.475Z,1729004237.475 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-10-15T14:57:17.699Z,1729004237.699 [CTD_Seabird] Loaded
2024-10-15T14:57:17.699Z,1729004237.699 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-10-15T14:57:17.700Z,1729004237.700 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A4D4E0
2024-10-15T14:57:17.700Z,1729004237.700 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 926
2024-10-15T14:57:17.723Z,1729004237.723 [PAR_Licor] Loaded
2024-10-15T14:57:17.723Z,1729004237.723 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-10-15T14:57:17.768Z,1729004237.768 [WetLabsBB2FL] Loaded
2024-10-15T14:57:17.768Z,1729004237.768 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-10-15T14:57:17.769Z,1729004237.769 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A7D4E0
2024-10-15T14:57:17.769Z,1729004237.769 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 927
2024-10-15T14:57:17.770Z,1729004237.770 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-10-15T14:57:17.771Z,1729004237.771 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-10-15T14:57:17.815Z,1729004237.815 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-10-15T14:57:17.815Z,1729004237.815 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-10-15T14:57:18.212Z,1729004238.212 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-10-15T14:57:18.212Z,1729004238.212 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-10-15T14:57:18.385Z,1729004238.385 [SBIT](DEBUG): Construct Startup Built In Test.
2024-10-15T14:57:18.394Z,1729004238.394 [SBIT] Loaded
2024-10-15T14:57:18.394Z,1729004238.394 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-10-15T14:57:18.397Z,1729004238.397 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-10-15T14:57:18.410Z,1729004238.410 [IBIT] Loaded
2024-10-15T14:57:18.411Z,1729004238.411 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-10-15T14:57:18.416Z,1729004238.416 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-10-15T14:57:18.522Z,1729004238.522 [CBIT] Loaded
2024-10-15T14:57:18.523Z,1729004238.523 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-10-15T14:57:18.523Z,1729004238.523 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-10-15T14:57:18.524Z,1729004238.524 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-10-15T14:57:18.664Z,1729004238.664 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-10-15T14:57:18.671Z,1729004238.671 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-10-15T14:57:18.673Z,1729004238.673 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-10-15T14:57:18.685Z,1729004238.685 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-10-15T14:57:18.686Z,1729004238.686 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BD74E0
2024-10-15T14:57:18.686Z,1729004238.686 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 928
2024-10-15T14:57:18.690Z,1729004238.690 [Supervisor](INFO): Main Thread ID is 830
2024-10-15T14:57:18.691Z,1729004238.691 [Supervisor](DEBUG): Running supervisor.
2024-10-15T14:57:18.691Z,1729004238.691 [CommandExec ThreadHandler](INFO): Handler Thread ID is 929
2024-10-15T14:57:18.692Z,1729004238.692 [CommandExec](INFO): Initializing the command executive.
2024-10-15T14:57:18.693Z,1729004238.693 [CommandLine ThreadHandler](INFO): Handler Thread ID is 930
2024-10-15T14:57:18.695Z,1729004238.695 [controlThread ThreadHandler](INFO): Handler Thread ID is 931
2024-10-15T14:57:18.696Z,1729004238.696 [controlThread](DEBUG): Initializing ControlThread
2024-10-15T14:57:18.697Z,1729004238.697 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-10-15T14:57:18.698Z,1729004238.698 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-10-15T14:57:18.699Z,1729004238.699 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-10-15T14:57:18.699Z,1729004238.699 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-10-15T14:57:18.701Z,1729004238.701 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-10-15T14:57:18.702Z,1729004238.702 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-10-15T14:57:18.707Z,1729004238.707 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-10-15T14:57:18.707Z,1729004238.707 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-10-15T14:57:18.707Z,1729004238.707 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-10-15T14:57:18.707Z,1729004238.707 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-10-15T14:57:18.708Z,1729004238.708 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-10-15T14:57:18.713Z,1729004238.713 [SBIT](INFO): Initialize SBIT Component.
2024-10-15T14:57:18.713Z,1729004238.713 [SBIT](IMPORTANT): git: 2024-10-14
2024-10-15T14:57:18.714Z,1729004238.714 [SBIT](INFO): git hash: 9dce2eadf13f2d8198d8ed7416351995a1f4c5f3
2024-10-15T14:57:18.714Z,1729004238.714 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-10-15T14:57:18.715Z,1729004238.715 [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-10-15T14:57:18.716Z,1729004238.716 [SBIT](INFO): Beginning SBIT in 39.000000 seconds.
2024-10-15T14:57:18.717Z,1729004238.717 [IBIT](INFO): Initialize IBIT Component.
2024-10-15T14:57:18.718Z,1729004238.718 [CBIT](DEBUG): Initialize CBIT Component.
2024-10-15T14:57:18.718Z,1729004238.718 [logger ThreadHandler](INFO): Handler Thread ID is 932
2024-10-15T14:57:18.729Z,1729004238.729 [CBIT](DEBUG): Initialized mux pins.
2024-10-15T14:57:18.729Z,1729004238.729 [CBIT](DEBUG): Initializing the watchdog timer.
2024-10-15T14:57:18.737Z,1729004238.737 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 933
2024-10-15T14:57:18.739Z,1729004238.739 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-10-15T14:57:18.749Z,1729004238.749 [Onboard ThreadHandler](INFO): Handler Thread ID is 934
2024-10-15T14:57:18.753Z,1729004238.753 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-10-15T14:57:18.754Z,1729004238.754 [CBIT](DEBUG): Initializing heartbeat.
2024-10-15T14:57:18.770Z,1729004238.770 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 935
2024-10-15T14:57:18.785Z,1729004238.785 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 936
2024-10-15T14:57:18.786Z,1729004238.786 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-10-15T14:57:18.790Z,1729004238.790 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 938
2024-10-15T14:57:18.794Z,1729004238.794 [WetLabsBB2FL](INFO): Powering up
2024-10-15T14:57:18.795Z,1729004238.795 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 940
2024-10-15T14:57:18.804Z,1729004238.804 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2024-10-15T14:57:18.805Z,1729004238.805 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2024-10-15T14:57:18.805Z,1729004238.805 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2024-10-15T14:57:18.805Z,1729004238.805 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2024-10-15T14:57:18.805Z,1729004238.805 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2024-10-15T14:57:18.805Z,1729004238.805 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2024-10-15T14:57:18.805Z,1729004238.805 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2024-10-15T14:57:18.806Z,1729004238.806 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2024-10-15T14:57:18.825Z,1729004238.825 [CBIT](DEBUG): Deactivating GF circuits.
2024-10-15T14:57:18.825Z,1729004238.825 [CBIT](DEBUG): Deactivating emergency mode.
2024-10-15T14:57:18.865Z,1729004238.865 [CBIT](DEBUG): Backplane powered.
2024-10-15T14:57:18.865Z,1729004238.865 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-10-15T14:57:18.866Z,1729004238.866 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-10-15T14:57:18.866Z,1729004238.866 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-10-15T14:57:18.871Z,1729004238.871 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-10-15T14:57:18.924Z,1729004238.924 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-10-15T14:57:18.928Z,1729004238.928 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-10-15T14:57:18.980Z,1729004238.980 [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-10-15T14:57:18.981Z,1729004238.981 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-10-15T14:57:18.981Z,1729004238.981 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-10-15T14:57:18.991Z,1729004238.991 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-10-15T14:57:19.173Z,1729004239.173 [Radio_Surface](INFO): Powering up
2024-10-15T14:57:19.254Z,1729004239.254 [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-10-15T14:57:19.274Z,1729004239.274 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-10-15T14:57:19.275Z,1729004239.275 [Default:A.Wait](DEBUG): Construct Wait.
2024-10-15T14:57:19.301Z,1729004239.301 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-10-15T14:57:19.326Z,1729004239.326 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-10-15T14:57:19.328Z,1729004239.328 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-10-15T14:57:19.362Z,1729004239.362 [Default:E.Execute](DEBUG): Construct Execute.
2024-10-15T14:57:19.373Z,1729004239.373 [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-10-15T14:57:19.389Z,1729004239.389 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,PowerOnly,Power24vConverter,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-10-15T14:57:19.395Z,1729004239.395 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-10-15T14:57:19.569Z,1729004239.569 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1831.023193 decibar, 0.000000 m
2024-10-15T14:57:19.571Z,1729004239.571 [PowerOnly](INFO): Powering up loadControl
2024-10-15T14:57:19.589Z,1729004239.589 [Power24vConverter](INFO): Powering up.
2024-10-15T14:57:19.641Z,1729004239.641 [DepthRateCalculator](ERROR): Depth measurement is not active
2024-10-15T14:57:19.667Z,1729004239.667 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-10-15T14:57:19.673Z,1729004239.673 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-10-15T14:57:19.674Z,1729004239.674 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-10-15T14:57:19.681Z,1729004239.681 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-10-15T14:57:19.682Z,1729004239.682 [MassServo](DEBUG): Initializing EZServoServo.
2024-10-15T14:57:19.689Z,1729004239.689 [MassServo](DEBUG): Initializing MassServo.
2024-10-15T14:57:19.690Z,1729004239.690 [RudderServo](DEBUG): Initializing EZServoServo.
2024-10-15T14:57:19.697Z,1729004239.697 [RudderServo](DEBUG): Initializing RudderServo.
2024-10-15T14:57:19.698Z,1729004239.698 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-10-15T14:57:19.705Z,1729004239.705 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-10-15T14:57:21.405Z,1729004241.405 [WetLabsBB2FL](INFO): Powering down
2024-10-15T14:57:25.270Z,1729004245.270 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-10-15T14:57:47.687Z,1729004267.687 [NAL9602](INFO): Powering up NAL9602
2024-10-15T14:57:58.231Z,1729004278.231 [SBIT](IMPORTANT): Beginning Startup BIT
2024-10-15T14:57:58.240Z,1729004278.240 [CBIT](IMPORTANT): Beginning ground fault scan
2024-10-15T14:57:58.590Z,1729004278.590 [NAL9602](INFO): NAL9602 initialized
2024-10-15T14:58:00.538Z,1729004280.538 [CommandExec](IMPORTANT): got command strobe off
2024-10-15T14:58:00.538Z,1729004280.538 [CommandExec](IMPORTANT): Deactivating strobe
2024-10-15T14:58:09.179Z,1729004289.179 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-10-15T14:58:09.581Z,1729004289.581 [CBIT](IMPORTANT): Beginning ground fault scan
2024-10-15T14:58:20.486Z,1729004300.486 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-10-15T14:58:20.854Z,1729004300.854 [CBIT](IMPORTANT): Beginning ground fault scan
2024-10-15T14:58:31.739Z,1729004311.739 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-10-15T14:58:31.739Z,1729004311.739 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2024-10-15T14:58:52.336Z,1729004332.336 [SBIT](IMPORTANT): SBIT PASSED
2024-10-15T14:58:52.336Z,1729004332.336 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-10-15T14:58:52.349Z,1729004332.349 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=0 bool;
2024-10-15T14:58:52.349Z,1729004332.349 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;
2024-10-15T14:58:52.350Z,1729004332.350 [SBIT](IMPORTANT): DATMMP.loadAtStartup=1 bool;
2024-10-15T14:58:52.350Z,1729004332.350 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool;
2024-10-15T14:58:52.350Z,1729004332.350 [SBIT](IMPORTANT): DATMMP.surfaceThreshold=2 meter;
2024-10-15T14:58:52.350Z,1729004332.350 [SBIT](IMPORTANT): Express linearApproximation DATMMP.acoustic_contact_range 15.000000 meter;
2024-10-15T14:58:52.350Z,1729004332.350 [SBIT](IMPORTANT): Express linearApproximation PowerOnly.component_avgCurrent_loadControl 50.000000 milliampere;
2024-10-15T14:58:52.350Z,1729004332.350 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=89.418285 cubic_centimeter;
2024-10-15T14:58:52.350Z,1729004332.350 [SBIT](IMPORTANT): VerticalControl.massDefault=4.795493 millimeter;
2024-10-15T14:58:52.753Z,1729004332.753 [MissionManager](IMPORTANT): Started mission Startup
2024-10-15T14:58:52.753Z,1729004332.753 [Startup] Running Loop=1
2024-10-15T14:58:52.753Z,1729004332.753 [Startup](DEBUG): Aggregate::initialize Startup
2024-10-15T14:58:52.753Z,1729004332.753 [Startup:A.GoToSurface] Running Loop=1
2024-10-15T14:58:52.753Z,1729004332.753 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-10-15T14:58:52.754Z,1729004332.754 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-10-15T14:58:52.755Z,1729004332.755 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-10-15T14:58:52.755Z,1729004332.755 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-10-15T14:58:52.755Z,1729004332.755 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-10-15T14:58:52.755Z,1729004332.755 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-10-15T14:58:52.756Z,1729004332.756 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-10-15T14:58:52.758Z,1729004332.758 [Startup:StartupSatComms] Running Loop=1
2024-10-15T14:58:52.758Z,1729004332.758 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-10-15T14:58:52.758Z,1729004332.758 [Startup:StartupSatComms:A] Running Loop=1
2024-10-15T14:58:53.144Z,1729004333.144 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-10-15T14:58:54.801Z,1729004334.801 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-10-15T14:59:48.572Z,1729004388.572 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005282
2024-10-15T14:59:52.975Z,1729004392.975 [Startup:StartupSatComms:A](INFO): Timed out from 2024-10-15T14:58:52.8Z
2024-10-15T14:59:52.976Z,1729004392.976 [Startup:StartupSatComms:A] Stopped
2024-10-15T14:59:52.976Z,1729004392.976 [Startup:StartupSatComms:B] Running Loop=1
2024-10-15T14:59:53.509Z,1729004393.509 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-10-15T15:00:00.086Z,1729004400.086 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20241015T145706/Courier0000.lzma
2024-10-15T15:00:01.088Z,1729004401.088 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Courier0000.lzma.bak
2024-10-15T15:00:01.088Z,1729004401.088 [DataOverHttps](INFO): SBD MOMSN=23301457
2024-10-15T15:00:16.636Z,1729004416.636 [DataOverHttps](INFO): Sending 879 bytes from file Logs/20241015T145706/Express0001.lzma
2024-10-15T15:00:17.636Z,1729004417.636 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Express0001.lzma.bak
2024-10-15T15:00:17.636Z,1729004417.636 [DataOverHttps](INFO): SBD MOMSN=23301460
2024-10-15T15:00:18.847Z,1729004418.847 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-10-15T15:00:18.847Z,1729004418.847 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-15T15:00:18.848Z,1729004418.848 [Startup:StartupSatComms:B] Stopped
2024-10-15T15:00:18.848Z,1729004418.848 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-10-15T15:00:18.848Z,1729004418.848 [Startup:StartupSatComms] Stopped
2024-10-15T15:00:18.848Z,1729004418.848 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-10-15T15:00:18.849Z,1729004418.849 [Startup](INFO): Completed Startup
2024-10-15T15:00:18.849Z,1729004418.849 [MissionManager](INFO): Startup is completed.
2024-10-15T15:00:18.849Z,1729004418.849 [MissionManager](INFO): Uninitializing Mission Startup
2024-10-15T15:00:18.850Z,1729004418.850 [Startup] Stopped
2024-10-15T15:00:18.850Z,1729004418.850 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-10-15T15:00:18.850Z,1729004418.850 [Startup:A.GoToSurface] Stopped
2024-10-15T15:00:18.850Z,1729004418.850 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-10-15T15:00:18.860Z,1729004418.860 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-15T15:00:19.232Z,1729004419.232 [MissionManager](IMPORTANT): Started mission Default
2024-10-15T15:00:19.232Z,1729004419.232 [Default] Running Loop=1
2024-10-15T15:00:19.232Z,1729004419.232 [Default](DEBUG): Aggregate::initialize Default
2024-10-15T15:00:19.232Z,1729004419.232 [Default:B.GoToSurface] Running Loop=1
2024-10-15T15:00:19.232Z,1729004419.232 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-10-15T15:00:19.232Z,1729004419.232 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-10-15T15:00:19.233Z,1729004419.233 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-10-15T15:00:19.233Z,1729004419.233 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-10-15T15:00:19.233Z,1729004419.233 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-10-15T15:00:19.233Z,1729004419.233 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-10-15T15:00:19.234Z,1729004419.234 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-10-15T15:00:19.234Z,1729004419.234 [Default:A.Wait] Running Loop=1
2024-10-15T15:00:19.234Z,1729004419.234 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-10-15T15:00:19.263Z,1729004419.263 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-15T15:00:19.263Z,1729004419.263 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-10-15T15:00:32.558Z,1729004432.558 [Default:A.Wait](INFO): Done Waiting.
2024-10-15T15:00:32.558Z,1729004432.558 [Default:A.Wait] Stopped
2024-10-15T15:00:32.558Z,1729004432.558 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-10-15T15:00:32.969Z,1729004432.969 [Default:CheckIn] Running Loop=1
2024-10-15T15:00:32.969Z,1729004432.969 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-15T15:00:32.969Z,1729004432.969 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-15T15:00:33.361Z,1729004433.361 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-10-15T15:00:56.372Z,1729004456.372 [BPC1](ERROR): BPC1A: No match for serial number 0000 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2024-10-15T15:01:15.360Z,1729004475.360 [CommandExec](IMPORTANT): got command gfscan
2024-10-15T15:01:15.381Z,1729004475.381 [CBIT](IMPORTANT): Beginning ground fault scan
2024-10-15T15:01:26.319Z,1729004486.319 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.000702
CHAN A1 (24V): 0.000506
CHAN A2 (12V): -0.001936
CHAN A3 (5V): -0.001356
CHAN B0 (3.3V): -0.000016
CHAN B1 (3.15aV): -0.000301
CHAN B2 (3.15bV): -0.000419
CHAN B3 (GND): -0.000300
OPEN: -0.000233
Full Scale: +/- 1 mA
2024-10-15T15:01:30.571Z,1729004490.571 [CommandExec](IMPORTANT): got command failComponent
2024-10-15T15:01:30.571Z,1729004490.571 [CommandExec](IMPORTANT): Failed components:
2024-10-15T15:01:30.571Z,1729004490.571 [CommandExec](IMPORTANT): No failed Components.
2024-10-15T15:03:02.428Z,1729004582.428 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-10-15T15:03:10.095Z,1729004590.095 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-10-15T15:03:13.644Z,1729004593.644 [CommandExec](IMPORTANT): got command burn on
2024-10-15T15:03:13.645Z,1729004593.645 [CommandExec](IMPORTANT): Activating dropweight wire
2024-10-15T15:03:19.808Z,1729004599.808 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-10-15T15:03:19.808Z,1729004599.808 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-15T15:03:19.834Z,1729004599.834 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-15T15:03:20.220Z,1729004600.220 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-15T15:03:20.220Z,1729004600.220 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-10-15T15:03:32.380Z,1729004612.380 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-10-15T15:03:32.823Z,1729004612.823 [CommandExec](IMPORTANT): got command burn off
2024-10-15T15:03:32.823Z,1729004612.823 [CommandExec](IMPORTANT): Deactivating dropweight wire
2024-10-15T15:03:46.923Z,1729004626.923 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-10-15T15:03:57.425Z,1729004637.425 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-10-15T15:04:45.091Z,1729004685.091 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2024-10-15T15:05:33.149Z,1729004733.149 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-10-15T15:00:32.0Z
2024-10-15T15:05:33.149Z,1729004733.149 [Default:CheckIn:Read_GPS] Stopped
2024-10-15T15:05:33.149Z,1729004733.149 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-15T15:05:33.565Z,1729004733.565 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-10-15T15:05:39.579Z,1729004739.579 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T15:05:39.846Z,1729004739.846 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20241015T145706/Courier0004.lzma
2024-10-15T15:05:40.848Z,1729004740.848 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Courier0004.lzma.bak
2024-10-15T15:05:40.848Z,1729004740.848 [DataOverHttps](INFO): SBD MOMSN=23301531
2024-10-15T15:05:52.909Z,1729004752.909 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T15:05:56.487Z,1729004756.487 [DataOverHttps](INFO): Sending 493 bytes from file Logs/20241015T145706/Express0005.lzma
2024-10-15T15:05:57.488Z,1729004757.488 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Express0005.lzma.bak
2024-10-15T15:05:57.488Z,1729004757.488 [DataOverHttps](INFO): SBD MOMSN=23301533
2024-10-15T15:05:58.670Z,1729004758.670 [Default:CheckIn:Read_Iridium] Stopped
2024-10-15T15:05:58.670Z,1729004758.670 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-15T15:05:58.670Z,1729004758.670 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-15T15:06:07.451Z,1729004767.451 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T15:06:20.843Z,1729004780.843 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2024-10-15T15:06:20.843Z,1729004780.843 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-15T15:06:20.854Z,1729004780.854 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-15T15:06:21.189Z,1729004781.189 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T15:06:21.257Z,1729004781.257 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-15T15:06:21.257Z,1729004781.257 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2024-10-15T15:06:34.520Z,1729004794.520 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T15:06:47.857Z,1729004807.857 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T15:07:01.199Z,1729004821.199 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T15:07:14.923Z,1729004834.923 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T15:07:28.255Z,1729004848.255 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T15:07:41.587Z,1729004861.587 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T15:07:54.919Z,1729004874.919 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T15:08:01.804Z,1729004881.804 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-10-15T15:08:01.804Z,1729004881.804 [NAL9602] Data Fault, FailCount= 1
2024-10-15T15:08:01.804Z,1729004881.804 [NAL9602](ERROR): Data Fault
2024-10-15T15:08:01.898Z,1729004881.898 [CBIT](ERROR): Data Fault in component: NAL9602
2024-10-15T15:08:02.208Z,1729004882.208 [NAL9602](INFO): Powering down
2024-10-15T15:08:03.058Z,1729004883.058 [CBIT](INFO): Clearing failed state for component NAL9602
2024-10-15T15:08:03.059Z,1729004883.059 [NAL9602] No Fault, FailCount= 1
2024-10-15T15:08:26.436Z,1729004906.436 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2024-10-15T15:08:32.496Z,1729004912.496 [NAL9602](INFO): Powering up NAL9602
2024-10-15T15:08:43.409Z,1729004923.409 [NAL9602](INFO): NAL9602 initialized
2024-10-15T15:09:21.795Z,1729004961.795 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2024-10-15T15:09:21.795Z,1729004961.795 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-15T15:09:21.805Z,1729004961.805 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-15T15:09:22.210Z,1729004962.210 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-15T15:09:22.210Z,1729004962.210 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2024-10-15T15:10:59.180Z,1729005059.180 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-15T15:10:59.181Z,1729005059.181 [Default:CheckIn:C.Wait] Stopped
2024-10-15T15:10:59.181Z,1729005059.181 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-15T15:10:59.181Z,1729005059.181 [Default:CheckIn:D] Running Loop=1
2024-10-15T15:10:59.595Z,1729005059.595 [Default:CheckIn:D] Stopped
2024-10-15T15:10:59.595Z,1729005059.595 [Default:CheckIn:E] Running Loop=1
2024-10-15T15:10:59.996Z,1729005059.996 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.672713 min
2024-10-15T15:10:59.996Z,1729005059.996 [Default:CheckIn:E] Stopped
2024-10-15T15:10:59.996Z,1729005059.996 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-15T15:10:59.996Z,1729005059.996 [Default:CheckIn] Stopped
2024-10-15T15:10:59.996Z,1729005059.996 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-15T15:10:59.996Z,1729005059.996 [Default:CheckIn](INFO): Running loop #2
2024-10-15T15:10:59.996Z,1729005059.996 [Default:CheckIn] Running Loop=2
2024-10-15T15:10:59.997Z,1729005059.997 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-15T15:10:59.997Z,1729005059.997 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-15T15:12:22.807Z,1729005142.807 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2024-10-15T15:12:22.807Z,1729005142.807 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-15T15:12:22.818Z,1729005142.818 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-15T15:12:23.229Z,1729005143.229 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-15T15:12:23.229Z,1729005143.229 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2024-10-15T15:15:12.456Z,1729005312.456 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T15:15:23.813Z,1729005323.813 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2024-10-15T15:15:23.814Z,1729005323.814 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-15T15:15:23.824Z,1729005323.824 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-15T15:15:24.167Z,1729005324.167 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T15:15:24.244Z,1729005324.244 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-15T15:15:24.244Z,1729005324.244 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2024-10-15T15:15:36.287Z,1729005336.287 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T15:15:48.412Z,1729005348.412 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T15:16:00.215Z,1729005360.215 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-10-15T15:10:59.0Z
2024-10-15T15:16:00.215Z,1729005360.215 [Default:CheckIn:Read_GPS] Stopped
2024-10-15T15:16:00.215Z,1729005360.215 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-15T15:16:00.527Z,1729005360.527 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T15:16:03.014Z,1729005363.014 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20241015T145706/Courier0007.lzma
2024-10-15T15:16:04.016Z,1729005364.016 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Courier0007.lzma.bak
2024-10-15T15:16:04.016Z,1729005364.016 [DataOverHttps](INFO): SBD MOMSN=23301592
2024-10-15T15:16:13.111Z,1729005373.111 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T15:16:20.739Z,1729005380.739 [DataOverHttps](INFO): Sending 313 bytes from file Logs/20241015T145706/Express0008.lzma
2024-10-15T15:16:21.740Z,1729005381.740 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Express0008.lzma.bak
2024-10-15T15:16:21.740Z,1729005381.740 [DataOverHttps](INFO): SBD MOMSN=23301594
2024-10-15T15:16:22.836Z,1729005382.836 [Default:CheckIn:Read_Iridium] Stopped
2024-10-15T15:16:22.836Z,1729005382.836 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-15T15:16:22.836Z,1729005382.836 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-15T15:16:24.874Z,1729005384.874 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T15:16:37.383Z,1729005397.383 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T15:16:49.503Z,1729005409.503 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T15:17:00.815Z,1729005420.815 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T15:17:12.543Z,1729005432.543 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T15:17:24.652Z,1729005444.652 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T15:18:24.877Z,1729005504.877 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2024-10-15T15:18:24.877Z,1729005504.877 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-15T15:18:24.887Z,1729005504.887 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-15T15:18:25.300Z,1729005505.300 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-15T15:18:25.300Z,1729005505.300 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2024-10-15T15:18:46.276Z,1729005526.276 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-10-15T15:18:46.276Z,1729005526.276 [NAL9602] Data Fault, FailCount= 2
2024-10-15T15:18:46.276Z,1729005526.276 [NAL9602](ERROR): Data Fault
2024-10-15T15:18:46.297Z,1729005526.297 [CBIT](ERROR): Data Fault in component: NAL9602
2024-10-15T15:18:46.672Z,1729005526.672 [NAL9602](INFO): Powering down
2024-10-15T15:18:47.520Z,1729005527.520 [CBIT](INFO): Clearing failed state for component NAL9602
2024-10-15T15:18:47.520Z,1729005527.520 [NAL9602] No Fault, FailCount= 2
2024-10-15T15:19:16.972Z,1729005556.972 [NAL9602](INFO): Powering up NAL9602
2024-10-15T15:19:27.876Z,1729005567.876 [NAL9602](INFO): NAL9602 initialized
2024-10-15T15:21:23.442Z,1729005683.442 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-15T15:21:23.442Z,1729005683.442 [Default:CheckIn:C.Wait] Stopped
2024-10-15T15:21:23.442Z,1729005683.442 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-15T15:21:23.442Z,1729005683.442 [Default:CheckIn:D] Running Loop=1
2024-10-15T15:21:23.836Z,1729005683.836 [Default:CheckIn:D] Stopped
2024-10-15T15:21:23.836Z,1729005683.836 [Default:CheckIn:E] Running Loop=1
2024-10-15T15:21:24.240Z,1729005684.240 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.076744 min
2024-10-15T15:21:24.240Z,1729005684.240 [Default:CheckIn:E] Stopped
2024-10-15T15:21:24.240Z,1729005684.240 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-15T15:21:24.241Z,1729005684.241 [Default:CheckIn] Stopped
2024-10-15T15:21:24.241Z,1729005684.241 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-15T15:21:24.241Z,1729005684.241 [Default:CheckIn](INFO): Running loop #3
2024-10-15T15:21:24.241Z,1729005684.241 [Default:CheckIn] Running Loop=3
2024-10-15T15:21:24.241Z,1729005684.241 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-15T15:21:24.241Z,1729005684.241 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-15T15:21:25.872Z,1729005685.872 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2024-10-15T15:21:25.872Z,1729005685.872 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-15T15:21:25.903Z,1729005685.903 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-15T15:21:26.289Z,1729005686.289 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-15T15:21:26.289Z,1729005686.289 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2024-10-15T15:24:26.858Z,1729005866.858 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2024-10-15T15:24:26.858Z,1729005866.858 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-15T15:24:26.869Z,1729005866.869 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-15T15:24:27.309Z,1729005867.309 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-15T15:24:27.309Z,1729005867.309 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2024-10-15T15:24:42.991Z,1729005882.991 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T15:24:56.323Z,1729005896.323 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T15:25:09.655Z,1729005909.655 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T15:25:22.987Z,1729005922.987 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T15:25:36.725Z,1729005936.725 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T15:25:50.056Z,1729005950.056 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T15:26:03.397Z,1729005963.397 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T15:26:17.136Z,1729005977.136 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T15:26:24.431Z,1729005984.431 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-10-15T15:21:24.2Z
2024-10-15T15:26:24.431Z,1729005984.431 [Default:CheckIn:Read_GPS] Stopped
2024-10-15T15:26:24.431Z,1729005984.431 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-15T15:26:30.872Z,1729005990.872 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T15:26:31.462Z,1729005991.462 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20241015T145706/Courier0010.lzma
2024-10-15T15:26:32.465Z,1729005992.465 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Courier0010.lzma.bak
2024-10-15T15:26:32.465Z,1729005992.465 [DataOverHttps](INFO): SBD MOMSN=23301643
2024-10-15T15:26:45.012Z,1729006005.012 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T15:26:48.207Z,1729006008.207 [DataOverHttps](INFO): Sending 234 bytes from file Logs/20241015T145706/Express0011.lzma
2024-10-15T15:26:49.208Z,1729006009.208 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Express0011.lzma.bak
2024-10-15T15:26:49.208Z,1729006009.208 [DataOverHttps](INFO): SBD MOMSN=23301650
2024-10-15T15:26:50.289Z,1729006010.289 [Default:CheckIn:Read_Iridium] Stopped
2024-10-15T15:26:50.289Z,1729006010.289 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-15T15:26:50.289Z,1729006010.289 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-15T15:26:58.387Z,1729006018.387 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T15:27:27.895Z,1729006047.895 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2024-10-15T15:27:27.895Z,1729006047.895 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-15T15:27:27.926Z,1729006047.926 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-15T15:27:28.308Z,1729006048.308 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-15T15:27:28.308Z,1729006048.308 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2024-10-15T15:28:19.596Z,1729006099.596 [BPC1](INFO): Calculating totals. Valid battery stick count: 48. Valid reserve battery stick count: 4.
2024-10-15T15:28:19.599Z,1729006099.599 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2024-10-15T15:29:29.910Z,1729006169.910 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-10-15T15:29:29.910Z,1729006169.910 [NAL9602] Data Fault, FailCount= 3
2024-10-15T15:29:29.911Z,1729006169.911 [NAL9602](ERROR): Data Fault
2024-10-15T15:29:29.928Z,1729006169.928 [CBIT](ERROR): Data Fault in component: NAL9602
2024-10-15T15:29:30.312Z,1729006170.312 [NAL9602](INFO): Powering down
2024-10-15T15:29:31.133Z,1729006171.133 [CBIT](INFO): Clearing failed state for component NAL9602
2024-10-15T15:29:31.133Z,1729006171.133 [NAL9602] No Fault, FailCount= 3
2024-10-15T15:30:00.625Z,1729006200.625 [NAL9602](INFO): Powering up NAL9602
2024-10-15T15:30:11.520Z,1729006211.520 [NAL9602](INFO): NAL9602 initialized
2024-10-15T15:30:28.908Z,1729006228.908 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2024-10-15T15:30:28.908Z,1729006228.908 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-15T15:30:28.919Z,1729006228.919 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-15T15:30:29.315Z,1729006229.315 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-15T15:30:29.315Z,1729006229.315 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2024-10-15T15:31:50.937Z,1729006310.937 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-15T15:31:50.937Z,1729006310.937 [Default:CheckIn:C.Wait] Stopped
2024-10-15T15:31:50.937Z,1729006310.937 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-15T15:31:50.937Z,1729006310.937 [Default:CheckIn:D] Running Loop=1
2024-10-15T15:31:51.332Z,1729006311.332 [Default:CheckIn:D] Stopped
2024-10-15T15:31:51.332Z,1729006311.332 [Default:CheckIn:E] Running Loop=1
2024-10-15T15:31:51.720Z,1729006311.720 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.535008 min
2024-10-15T15:31:51.720Z,1729006311.720 [Default:CheckIn:E] Stopped
2024-10-15T15:31:51.720Z,1729006311.720 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-15T15:31:51.720Z,1729006311.720 [Default:CheckIn] Stopped
2024-10-15T15:31:51.721Z,1729006311.721 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-15T15:31:51.721Z,1729006311.721 [Default:CheckIn](INFO): Running loop #4
2024-10-15T15:31:51.721Z,1729006311.721 [Default:CheckIn] Running Loop=4
2024-10-15T15:31:51.721Z,1729006311.721 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-15T15:31:51.721Z,1729006311.721 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-15T15:33:29.935Z,1729006409.935 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2024-10-15T15:33:29.935Z,1729006409.935 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-15T15:33:29.954Z,1729006409.954 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-15T15:33:30.361Z,1729006410.361 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-15T15:33:30.361Z,1729006410.361 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2024-10-15T15:36:30.939Z,1729006590.939 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2024-10-15T15:36:30.939Z,1729006590.939 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-15T15:36:30.958Z,1729006590.958 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-15T15:36:31.356Z,1729006591.356 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-15T15:36:31.356Z,1729006591.356 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2024-10-15T15:36:51.941Z,1729006611.941 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-10-15T15:31:51.7Z
2024-10-15T15:36:51.941Z,1729006611.941 [Default:CheckIn:Read_GPS] Stopped
2024-10-15T15:36:51.941Z,1729006611.941 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-15T15:36:59.274Z,1729006619.274 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20241015T145706/Courier0013.lzma
2024-10-15T15:37:00.276Z,1729006620.276 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Courier0013.lzma.bak
2024-10-15T15:37:00.276Z,1729006620.276 [DataOverHttps](INFO): SBD MOMSN=23301692
2024-10-15T15:37:15.702Z,1729006635.702 [DataOverHttps](INFO): Sending 194 bytes from file Logs/20241015T145706/Express0014.lzma
2024-10-15T15:37:16.704Z,1729006636.704 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Express0014.lzma.bak
2024-10-15T15:37:16.704Z,1729006636.704 [DataOverHttps](INFO): SBD MOMSN=23301698
2024-10-15T15:37:17.784Z,1729006637.784 [Default:CheckIn:Read_Iridium] Stopped
2024-10-15T15:37:17.784Z,1729006637.784 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-15T15:37:17.784Z,1729006637.784 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-15T15:39:32.023Z,1729006772.023 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2024-10-15T15:39:32.023Z,1729006772.023 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-15T15:39:32.034Z,1729006772.034 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-15T15:39:32.443Z,1729006772.443 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-15T15:39:32.443Z,1729006772.443 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2024-10-15T15:40:13.626Z,1729006813.626 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-10-15T15:40:13.626Z,1729006813.626 [NAL9602] Data Fault, FailCount= 4
2024-10-15T15:40:13.626Z,1729006813.626 [NAL9602](ERROR): Data Fault
2024-10-15T15:40:13.644Z,1729006813.644 [CBIT](ERROR): Data Fault in component: NAL9602
2024-10-15T15:40:14.024Z,1729006814.024 [NAL9602](INFO): Powering down
2024-10-15T15:40:14.852Z,1729006814.852 [CBIT](INFO): Clearing failed state for component NAL9602
2024-10-15T15:40:14.853Z,1729006814.853 [NAL9602] No Fault, FailCount= 4
2024-10-15T15:40:44.336Z,1729006844.336 [NAL9602](INFO): Powering up NAL9602
2024-10-15T15:40:55.244Z,1729006855.244 [NAL9602](INFO): NAL9602 initialized
2024-10-15T15:42:18.477Z,1729006938.477 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-15T15:42:18.477Z,1729006938.477 [Default:CheckIn:C.Wait] Stopped
2024-10-15T15:42:18.477Z,1729006938.477 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-15T15:42:18.478Z,1729006938.478 [Default:CheckIn:D] Running Loop=1
2024-10-15T15:42:18.881Z,1729006938.881 [Default:CheckIn:D] Stopped
2024-10-15T15:42:18.881Z,1729006938.881 [Default:CheckIn:E] Running Loop=1
2024-10-15T15:42:19.309Z,1729006939.309 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 41.994145 min
2024-10-15T15:42:19.309Z,1729006939.309 [Default:CheckIn:E] Stopped
2024-10-15T15:42:19.309Z,1729006939.309 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-15T15:42:19.309Z,1729006939.309 [Default:CheckIn] Stopped
2024-10-15T15:42:19.309Z,1729006939.309 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-15T15:42:19.309Z,1729006939.309 [Default:CheckIn](INFO): Running loop #5
2024-10-15T15:42:19.309Z,1729006939.309 [Default:CheckIn] Running Loop=5
2024-10-15T15:42:19.309Z,1729006939.309 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-15T15:42:19.310Z,1729006939.310 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-15T15:42:33.048Z,1729006953.048 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2024-10-15T15:42:33.048Z,1729006953.048 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-15T15:42:33.058Z,1729006953.058 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-15T15:42:33.436Z,1729006953.436 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-15T15:42:33.436Z,1729006953.436 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2024-10-15T15:45:34.015Z,1729007134.015 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2024-10-15T15:45:34.015Z,1729007134.015 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-15T15:45:34.025Z,1729007134.025 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-15T15:45:34.429Z,1729007134.429 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-15T15:45:34.429Z,1729007134.429 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2024-10-15T15:47:19.464Z,1729007239.464 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-10-15T15:42:19.3Z
2024-10-15T15:47:19.464Z,1729007239.464 [Default:CheckIn:Read_GPS] Stopped
2024-10-15T15:47:19.464Z,1729007239.464 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-15T15:47:26.850Z,1729007246.850 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20241015T145706/Courier0016.lzma
2024-10-15T15:47:27.852Z,1729007247.852 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Courier0016.lzma.bak
2024-10-15T15:47:27.852Z,1729007247.852 [DataOverHttps](INFO): SBD MOMSN=23301741
2024-10-15T15:47:43.330Z,1729007263.330 [DataOverHttps](INFO): Sending 266 bytes from file Logs/20241015T145706/Express0017.lzma
2024-10-15T15:47:44.332Z,1729007264.332 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Express0017.lzma.bak
2024-10-15T15:47:44.332Z,1729007264.332 [DataOverHttps](INFO): SBD MOMSN=23301745
2024-10-15T15:47:45.728Z,1729007265.728 [Default:CheckIn:Read_Iridium] Stopped
2024-10-15T15:47:45.728Z,1729007265.728 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-15T15:47:45.728Z,1729007265.728 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-15T15:48:35.120Z,1729007315.120 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2024-10-15T15:48:35.120Z,1729007315.120 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-15T15:48:35.131Z,1729007315.131 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-15T15:48:35.508Z,1729007315.508 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-15T15:48:35.508Z,1729007315.508 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2024-10-15T15:50:57.032Z,1729007457.032 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-10-15T15:50:57.032Z,1729007457.032 [NAL9602] Data Fault, FailCount= 5
2024-10-15T15:50:57.032Z,1729007457.032 [NAL9602](ERROR): Data Fault
2024-10-15T15:50:57.090Z,1729007457.090 [CBIT](ERROR): Data Fault in component: NAL9602
2024-10-15T15:50:57.111Z,1729007457.111 [CBIT](CRITICAL): Data Fault in component: NAL9602
2024-10-15T15:50:57.436Z,1729007457.436 [NAL9602](INFO): Powering down
2024-10-15T15:50:57.505Z,1729007457.505 [CommandExec](FAULT): Scheduling is paused
2024-10-15T15:50:57.506Z,1729007457.506 [CBIT](INFO): Critical error at 20241015T155057
2024-10-15T15:50:57.506Z,1729007457.506 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2024-10-15T15:51:36.230Z,1729007496.230 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2024-10-15T15:51:36.230Z,1729007496.230 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-15T15:51:36.261Z,1729007496.261 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-15T15:51:36.641Z,1729007496.641 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-15T15:51:36.641Z,1729007496.641 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2024-10-15T15:52:46.167Z,1729007566.167 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-15T15:52:46.167Z,1729007566.167 [Default:CheckIn:C.Wait] Stopped
2024-10-15T15:52:46.167Z,1729007566.167 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-15T15:52:46.167Z,1729007566.167 [Default:CheckIn:D] Running Loop=1
2024-10-15T15:52:46.572Z,1729007566.572 [Default:CheckIn:D] Stopped
2024-10-15T15:52:46.572Z,1729007566.572 [Default:CheckIn:E] Running Loop=1
2024-10-15T15:52:46.989Z,1729007566.989 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.455664 min
2024-10-15T15:52:46.989Z,1729007566.989 [Default:CheckIn:E] Stopped
2024-10-15T15:52:46.989Z,1729007566.989 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-15T15:52:46.989Z,1729007566.989 [Default:CheckIn] Stopped
2024-10-15T15:52:46.989Z,1729007566.989 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-15T15:52:46.989Z,1729007566.989 [Default:CheckIn](INFO): Running loop #6
2024-10-15T15:52:46.989Z,1729007566.989 [Default:CheckIn] Running Loop=6
2024-10-15T15:52:46.989Z,1729007566.989 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-15T15:52:46.990Z,1729007566.990 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-15T15:52:57.135Z,1729007577.135 [CBIT](INFO): Clearing failed state for component NAL9602
2024-10-15T15:52:57.135Z,1729007577.135 [NAL9602] No Fault, FailCount= 5
2024-10-15T15:52:57.484Z,1729007577.484 [NAL9602](INFO): Powering up NAL9602
2024-10-15T15:53:08.396Z,1729007588.396 [NAL9602](INFO): NAL9602 initialized
2024-10-15T15:54:37.306Z,1729007677.306 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2024-10-15T15:54:37.306Z,1729007677.306 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-15T15:54:37.316Z,1729007677.316 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-15T15:54:37.722Z,1729007677.722 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-15T15:54:37.723Z,1729007677.723 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2024-10-15T15:57:38.280Z,1729007858.280 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2024-10-15T15:57:38.280Z,1729007858.280 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-15T15:57:38.290Z,1729007858.290 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-15T15:57:38.291Z,1729007858.291 [CBIT](FAULT): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-15T15:57:47.196Z,1729007867.196 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-10-15T15:52:46.0Z
2024-10-15T15:57:47.196Z,1729007867.196 [Default:CheckIn:Read_GPS] Stopped
2024-10-15T15:57:47.196Z,1729007867.196 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-15T15:57:54.046Z,1729007874.046 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20241015T145706/Courier0019.lzma
2024-10-15T15:57:55.048Z,1729007875.048 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Courier0019.lzma.bak
2024-10-15T15:57:55.048Z,1729007875.048 [DataOverHttps](INFO): SBD MOMSN=23301801
2024-10-15T15:58:10.467Z,1729007890.467 [DataOverHttps](INFO): Sending 365 bytes from file Logs/20241015T145706/Express0020.lzma
2024-10-15T15:58:11.469Z,1729007891.469 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Express0020.lzma.bak
2024-10-15T15:58:11.469Z,1729007891.469 [DataOverHttps](INFO): SBD MOMSN=23301806
2024-10-15T15:58:12.633Z,1729007892.633 [Default:CheckIn:Read_Iridium] Stopped
2024-10-15T15:58:12.633Z,1729007892.633 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-15T15:58:12.633Z,1729007892.633 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-15T16:03:11.296Z,1729008191.296 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-10-15T16:03:11.296Z,1729008191.296 [NAL9602] Data Fault, FailCount= 1
2024-10-15T16:03:11.296Z,1729008191.296 [NAL9602](ERROR): Data Fault
2024-10-15T16:03:11.341Z,1729008191.341 [CBIT](ERROR): Data Fault in component: NAL9602
2024-10-15T16:03:11.700Z,1729008191.700 [NAL9602](INFO): Powering down
2024-10-15T16:03:12.528Z,1729008192.528 [CBIT](INFO): Clearing failed state for component NAL9602
2024-10-15T16:03:12.528Z,1729008192.528 [NAL9602] No Fault, FailCount= 1
2024-10-15T16:03:13.322Z,1729008193.322 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-15T16:03:13.322Z,1729008193.322 [Default:CheckIn:C.Wait] Stopped
2024-10-15T16:03:13.323Z,1729008193.323 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-15T16:03:13.323Z,1729008193.323 [Default:CheckIn:D] Running Loop=1
2024-10-15T16:03:13.726Z,1729008193.726 [Default:CheckIn:D] Stopped
2024-10-15T16:03:13.726Z,1729008193.726 [Default:CheckIn:E] Running Loop=1
2024-10-15T16:03:14.140Z,1729008194.140 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.908240 min
2024-10-15T16:03:14.140Z,1729008194.140 [Default:CheckIn:E] Stopped
2024-10-15T16:03:14.140Z,1729008194.140 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-15T16:03:14.140Z,1729008194.140 [Default:CheckIn] Stopped
2024-10-15T16:03:14.140Z,1729008194.140 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-15T16:03:14.140Z,1729008194.140 [Default:CheckIn](INFO): Running loop #7
2024-10-15T16:03:14.140Z,1729008194.140 [Default:CheckIn] Running Loop=7
2024-10-15T16:03:14.140Z,1729008194.140 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-15T16:03:14.140Z,1729008194.140 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-15T16:03:41.000Z,1729008222.000 [NAL9602](INFO): Powering up NAL9602
2024-10-15T16:03:52.904Z,1729008232.904 [NAL9602](INFO): NAL9602 initialized
2024-10-15T16:08:14.479Z,1729008494.479 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-10-15T16:03:14.1Z
2024-10-15T16:08:14.479Z,1729008494.479 [Default:CheckIn:Read_GPS] Stopped
2024-10-15T16:08:14.479Z,1729008494.479 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-15T16:08:24.558Z,1729008504.558 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20241015T145706/Courier0022.lzma
2024-10-15T16:08:25.560Z,1729008505.560 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Courier0022.lzma.bak
2024-10-15T16:08:25.560Z,1729008505.560 [DataOverHttps](INFO): SBD MOMSN=23301864
2024-10-15T16:08:41.271Z,1729008521.271 [DataOverHttps](INFO): Sending 242 bytes from file Logs/20241015T145706/Express0023.lzma
2024-10-15T16:08:42.272Z,1729008522.272 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Express0023.lzma.bak
2024-10-15T16:08:42.272Z,1729008522.272 [DataOverHttps](INFO): SBD MOMSN=23301866
2024-10-15T16:08:43.595Z,1729008523.595 [Default:CheckIn:Read_Iridium] Stopped
2024-10-15T16:08:43.595Z,1729008523.595 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-15T16:08:43.595Z,1729008523.595 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-15T16:12:38.444Z,1729008758.444 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-15T16:12:38.444Z,1729008758.444 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20
2024-10-15T16:13:44.336Z,1729008824.336 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-15T16:13:44.336Z,1729008824.336 [Default:CheckIn:C.Wait] Stopped
2024-10-15T16:13:44.337Z,1729008824.337 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-15T16:13:44.337Z,1729008824.337 [Default:CheckIn:D] Running Loop=1
2024-10-15T16:13:44.740Z,1729008824.740 [Default:CheckIn:D] Stopped
2024-10-15T16:13:44.741Z,1729008824.741 [Default:CheckIn:E] Running Loop=1
2024-10-15T16:13:45.152Z,1729008825.152 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.425146 min
2024-10-15T16:13:45.152Z,1729008825.152 [Default:CheckIn:E] Stopped
2024-10-15T16:13:45.152Z,1729008825.152 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-15T16:13:45.152Z,1729008825.152 [Default:CheckIn] Stopped
2024-10-15T16:13:45.152Z,1729008825.152 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-15T16:13:45.152Z,1729008825.152 [Default:CheckIn](INFO): Running loop #8
2024-10-15T16:13:45.152Z,1729008825.152 [Default:CheckIn] Running Loop=8
2024-10-15T16:13:45.153Z,1729008825.153 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-15T16:13:45.153Z,1729008825.153 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-15T16:13:55.623Z,1729008835.623 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-10-15T16:13:55.623Z,1729008835.623 [NAL9602] Data Fault, FailCount= 2
2024-10-15T16:13:55.623Z,1729008835.623 [NAL9602](ERROR): Data Fault
2024-10-15T16:13:55.641Z,1729008835.641 [CBIT](ERROR): Data Fault in component: NAL9602
2024-10-15T16:13:56.029Z,1729008836.029 [NAL9602](INFO): Powering down
2024-10-15T16:13:56.868Z,1729008836.868 [CBIT](INFO): Clearing failed state for component NAL9602
2024-10-15T16:13:56.868Z,1729008836.868 [NAL9602] No Fault, FailCount= 2
2024-10-15T16:14:26.370Z,1729008866.370 [NAL9602](INFO): Powering up NAL9602
2024-10-15T16:14:37.272Z,1729008877.272 [NAL9602](INFO): NAL9602 initialized
2024-10-15T16:15:39.159Z,1729008939.159 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-10-15T16:15:39.159Z,1729008939.159 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-15T16:15:39.194Z,1729008939.194 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-15T16:15:39.581Z,1729008939.581 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-15T16:15:39.581Z,1729008939.581 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-10-15T16:18:40.311Z,1729009120.311 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-10-15T16:18:40.311Z,1729009120.311 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-10-15T16:18:40.322Z,1729009120.322 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-10-15T16:18:40.739Z,1729009120.739 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-10-15T16:18:40.739Z,1729009120.739 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-10-15T16:18:45.535Z,1729009125.535 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-10-15T16:13:45.2Z
2024-10-15T16:18:45.535Z,1729009125.535 [Default:CheckIn:Read_GPS] Stopped
2024-10-15T16:18:45.535Z,1729009125.535 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-15T16:18:54.922Z,1729009134.922 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20241015T145706/Courier0025.lzma
2024-10-15T16:18:55.924Z,1729009135.924 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Courier0025.lzma.bak
2024-10-15T16:18:55.924Z,1729009135.924 [DataOverHttps](INFO): SBD MOMSN=23301923
2024-10-15T16:19:01.745Z,1729009141.745 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,161901.00,A,3648.16904,N,12147.28370,W,0.408,0.00,151024,,,A*71
2024-10-15T16:19:01.749Z,1729009141.749 [NAL9602](INFO): GPS fix at 20241015T161901: (36.802817, -121.788062)
2024-10-15T16:19:02.188Z,1729009142.188 [DeadReckonUsingMultipleVelocitySources](INFO): Failure count cleared after critical for DeadReckonUsingMultipleVelocitySources
2024-10-15T16:19:11.958Z,1729009151.958 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20241015T145706/Express0026.lzma
2024-10-15T16:19:12.960Z,1729009152.960 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Express0026.lzma.bak
2024-10-15T16:19:12.960Z,1729009152.960 [DataOverHttps](INFO): SBD MOMSN=23301926
2024-10-15T16:19:14.305Z,1729009154.305 [Default:CheckIn:Read_Iridium] Stopped
2024-10-15T16:19:14.305Z,1729009154.305 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-15T16:19:14.305Z,1729009154.305 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-15T16:19:34.475Z,1729009174.475 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-15T16:24:15.062Z,1729009455.062 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-15T16:24:15.063Z,1729009455.063 [Default:CheckIn:C.Wait] Stopped
2024-10-15T16:24:15.063Z,1729009455.063 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-15T16:24:15.063Z,1729009455.063 [Default:CheckIn:D] Running Loop=1
2024-10-15T16:24:15.484Z,1729009455.484 [Default:CheckIn:D] Stopped
2024-10-15T16:24:15.484Z,1729009455.484 [Default:CheckIn:E] Running Loop=1
2024-10-15T16:24:15.866Z,1729009455.866 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 83.937541 min
2024-10-15T16:24:15.866Z,1729009455.866 [Default:CheckIn:E] Stopped
2024-10-15T16:24:15.866Z,1729009455.866 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-15T16:24:15.866Z,1729009455.866 [Default:CheckIn] Stopped
2024-10-15T16:24:15.866Z,1729009455.866 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-15T16:24:15.866Z,1729009455.866 [Default:CheckIn](INFO): Running loop #9
2024-10-15T16:24:15.867Z,1729009455.867 [Default:CheckIn] Running Loop=9
2024-10-15T16:24:15.867Z,1729009455.867 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-15T16:24:15.867Z,1729009455.867 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-15T16:24:17.877Z,1729009457.877 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162417.00,A,3648.13741,N,12147.22814,W,1.672,256.68,151024,,,A*70
2024-10-15T16:24:17.880Z,1729009457.880 [NAL9602](INFO): GPS fix at 20241015T162417: (36.802290, -121.787136)
2024-10-15T16:24:17.895Z,1729009457.895 [Default:CheckIn:Read_GPS] Stopped
2024-10-15T16:24:17.895Z,1729009457.895 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-15T16:24:27.851Z,1729009467.851 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20241015T145706/Courier0028.lzma
2024-10-15T16:24:28.852Z,1729009468.852 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Courier0028.lzma.bak
2024-10-15T16:24:28.853Z,1729009468.853 [DataOverHttps](INFO): SBD MOMSN=23301936
2024-10-15T16:24:31.461Z,1729009471.461 [NAL9602](INFO): SBD MO Status=0, MOMSN=57833, MT Status=0, MTMSN=0
2024-10-15T16:24:31.461Z,1729009471.461 [NAL9602](INFO): No messages in MT queue
2024-10-15T16:24:44.707Z,1729009484.707 [DataOverHttps](INFO): Sending 283 bytes from file Logs/20241015T145706/Express0029.lzma
2024-10-15T16:24:45.708Z,1729009485.708 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Express0029.lzma.bak
2024-10-15T16:24:45.708Z,1729009485.708 [DataOverHttps](INFO): SBD MOMSN=23301940
2024-10-15T16:24:46.831Z,1729009486.831 [Default:CheckIn:Read_Iridium] Stopped
2024-10-15T16:24:46.831Z,1729009486.831 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-15T16:24:46.831Z,1729009486.831 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-15T16:25:02.279Z,1729009502.279 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-15T16:27:25.940Z,1729009645.940 [DataOverHttps](IMPORTANT): SBD MTMSN=20241015T162725
2024-10-15T16:27:33.334Z,1729009653.334 [DataOverHttps](INFO): Received command: ibit
2024-10-15T16:27:33.379Z,1729009653.379 [CommandExec](IMPORTANT): got command ibit
2024-10-15T16:27:33.487Z,1729009653.487 [IBIT](IMPORTANT): Beginning Initiated BIT
2024-10-15T16:27:33.487Z,1729009653.487 [IBIT](IMPORTANT): Beginning control surface checks.
2024-10-15T16:27:33.490Z,1729009653.490 [CBIT](IMPORTANT): Beginning ground fault scan
2024-10-15T16:27:35.053Z,1729009655.053 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162734.00,A,3648.15079,N,12147.22362,W,0.019,27.44,151024,,,D*47
2024-10-15T16:27:35.055Z,1729009655.055 [NAL9602](INFO): GPS fix at 20241015T162734: (36.802513, -121.787060)
2024-10-15T16:27:44.369Z,1729009664.369 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-10-15T16:27:44.792Z,1729009664.792 [CBIT](IMPORTANT): Beginning ground fault scan
2024-10-15T16:27:55.739Z,1729009675.739 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-10-15T16:27:56.251Z,1729009676.251 [CBIT](IMPORTANT): Beginning ground fault scan
2024-10-15T16:28:07.455Z,1729009687.455 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2024-10-15T16:28:07.455Z,1729009687.455 [CBIT](IMPORTANT): Ground fault scan returned out of bounds value in succession 3 times. Will re-run scan on normal schedule.
2024-10-15T16:28:08.989Z,1729009688.989 [NAL9602](INFO): SBD MO Status=0, MOMSN=57834, MT Status=0, MTMSN=0
2024-10-15T16:28:08.989Z,1729009688.989 [NAL9602](INFO): No messages in MT queue
2024-10-15T16:28:08.989Z,1729009688.989 [NAL9602](INFO): Failure count cleared after critical for NAL9602
2024-10-15T16:28:10.197Z,1729009690.197 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162809.00,A,3648.15035,N,12147.22347,W,0.097,27.44,151024,,,D*4F
2024-10-15T16:28:10.199Z,1729009690.199 [NAL9602](INFO): GPS fix at 20241015T162809: (36.802506, -121.787058)
2024-10-15T16:28:13.024Z,1729009693.024 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162812.00,A,3648.15046,N,12147.22346,W,0.039,27.44,151024,,,D*44
2024-10-15T16:28:13.027Z,1729009693.027 [NAL9602](INFO): GPS fix at 20241015T162812: (36.802508, -121.787058)
2024-10-15T16:28:15.854Z,1729009695.854 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162815.00,A,3648.15065,N,12147.22344,W,0.019,27.44,151024,,,D*42
2024-10-15T16:28:15.856Z,1729009695.856 [NAL9602](INFO): GPS fix at 20241015T162815: (36.802511, -121.787057)
2024-10-15T16:28:19.084Z,1729009699.084 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162818.00,A,3648.15074,N,12147.22355,W,0.117,27.44,151024,,,D*40
2024-10-15T16:28:19.087Z,1729009699.087 [NAL9602](INFO): GPS fix at 20241015T162818: (36.802512, -121.787059)
2024-10-15T16:28:19.919Z,1729009699.919 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 36.802513 Longitude: -121.787056
2024-10-15T16:28:20.413Z,1729009700.413 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.611000
2024-10-15T16:28:20.414Z,1729009700.414 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2024-10-15T16:28:20.414Z,1729009700.414 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2024-10-15T16:28:20.744Z,1729009700.744 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2024-10-15T16:28:20.745Z,1729009700.745 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2024-10-15T16:28:20.745Z,1729009700.745 [IBIT](IMPORTANT): Pressure:9.428249 PSI
2024-10-15T16:28:20.745Z,1729009700.745 [IBIT](IMPORTANT): Humidity:15.571970 %
2024-10-15T16:28:21.148Z,1729009701.148 [IBIT](IMPORTANT): Vehicle Pitch:-0.437601 degrees
2024-10-15T16:28:21.148Z,1729009701.148 [IBIT](IMPORTANT): Vehicle Roll:5.152535 degrees
2024-10-15T16:28:21.148Z,1729009701.148 [IBIT](IMPORTANT): Vehicle Heading:212.203491 degrees
2024-10-15T16:28:21.531Z,1729009701.531 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2024-10-15T16:28:21.532Z,1729009701.532 [IBIT](IMPORTANT): buoyancyNeutral: 89.418282 cc
2024-10-15T16:28:21.532Z,1729009701.532 [IBIT](IMPORTANT): massDefault: 0.479549 cm
2024-10-15T16:28:21.532Z,1729009701.532 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2024-10-15T16:28:21.532Z,1729009701.532 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2024-10-15T16:28:21.533Z,1729009701.533 [IBIT](IMPORTANT): IBIT PASSED
2024-10-15T16:28:35.400Z,1729009715.400 [DataOverHttps](IMPORTANT): SBD MTMSN=20241015T162834
2024-10-15T16:28:43.094Z,1729009723.094 [DataOverHttps](INFO): Received command: failc
2024-10-15T16:28:43.115Z,1729009723.115 [CommandExec](IMPORTANT): got command failComponent
2024-10-15T16:28:43.115Z,1729009723.115 [CommandExec](IMPORTANT): Failed components:
2024-10-15T16:28:43.115Z,1729009723.115 [CommandExec](IMPORTANT): No failed Components.
2024-10-15T16:28:51.403Z,1729009731.403 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-15T16:29:25.746Z,1729009765.746 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:29:37.861Z,1729009777.861 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:29:47.569Z,1729009787.569 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-15T16:29:47.569Z,1729009787.569 [Default:CheckIn:C.Wait] Stopped
2024-10-15T16:29:47.569Z,1729009787.569 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-15T16:29:47.570Z,1729009787.570 [Default:CheckIn:D] Running Loop=1
2024-10-15T16:29:47.974Z,1729009787.974 [Default:CheckIn:D] Stopped
2024-10-15T16:29:47.974Z,1729009787.974 [Default:CheckIn:E] Running Loop=1
2024-10-15T16:29:48.386Z,1729009788.386 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 89.479028 min
2024-10-15T16:29:48.386Z,1729009788.386 [Default:CheckIn:E] Stopped
2024-10-15T16:29:48.386Z,1729009788.386 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-15T16:29:48.386Z,1729009788.386 [Default:CheckIn] Stopped
2024-10-15T16:29:48.387Z,1729009788.387 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-15T16:29:48.387Z,1729009788.387 [Default:CheckIn](INFO): Running loop #10
2024-10-15T16:29:48.387Z,1729009788.387 [Default:CheckIn] Running Loop=10
2024-10-15T16:29:48.387Z,1729009788.387 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-15T16:29:48.387Z,1729009788.387 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-15T16:29:49.577Z,1729009789.577 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:29:50.389Z,1729009790.389 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,162949.00,A,3648.15050,N,12147.22373,W,0.019,27.44,151024,,,D*48
2024-10-15T16:29:50.391Z,1729009790.391 [NAL9602](INFO): GPS fix at 20241015T162949: (36.802508, -121.787062)
2024-10-15T16:29:50.422Z,1729009790.422 [Default:CheckIn:Read_GPS] Stopped
2024-10-15T16:29:50.422Z,1729009790.422 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-15T16:29:57.782Z,1729009797.782 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241015T145706/Courier0031.lzma
2024-10-15T16:29:58.784Z,1729009798.784 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Courier0031.lzma.bak
2024-10-15T16:29:58.784Z,1729009798.784 [DataOverHttps](INFO): SBD MOMSN=23302036
2024-10-15T16:30:01.293Z,1729009801.293 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:30:07.769Z,1729009807.769 [NAL9602](INFO): SBD MO Status=0, MOMSN=57835, MT Status=0, MTMSN=0
2024-10-15T16:30:07.769Z,1729009807.769 [NAL9602](INFO): No messages in MT queue
2024-10-15T16:30:12.605Z,1729009812.605 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:30:15.040Z,1729009815.040 [DataOverHttps](INFO): Sending 834 bytes from file Logs/20241015T145706/Express0032.lzma
2024-10-15T16:30:16.040Z,1729009816.040 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Express0032.lzma.bak
2024-10-15T16:30:16.041Z,1729009816.041 [DataOverHttps](INFO): SBD MOMSN=23302041
2024-10-15T16:30:17.081Z,1729009817.081 [Default:CheckIn:Read_Iridium] Stopped
2024-10-15T16:30:17.081Z,1729009817.081 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-15T16:30:17.081Z,1729009817.081 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-15T16:30:24.377Z,1729009824.377 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:30:36.117Z,1729009836.117 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:30:38.539Z,1729009838.539 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-15T16:30:48.231Z,1729009848.231 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:33:25.949Z,1729010005.949 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:33:38.877Z,1729010018.877 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:33:52.613Z,1729010032.613 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:34:06.349Z,1729010046.349 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:34:20.087Z,1729010060.087 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:34:33.418Z,1729010073.418 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:34:47.961Z,1729010087.961 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:35:02.101Z,1729010102.101 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:35:15.837Z,1729010115.837 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:35:17.874Z,1729010117.874 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-15T16:35:17.874Z,1729010117.874 [Default:CheckIn:C.Wait] Stopped
2024-10-15T16:35:17.874Z,1729010117.874 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-15T16:35:17.874Z,1729010117.874 [Default:CheckIn:D] Running Loop=1
2024-10-15T16:35:18.291Z,1729010118.291 [Default:CheckIn:D] Stopped
2024-10-15T16:35:18.292Z,1729010118.292 [Default:CheckIn:E] Running Loop=1
2024-10-15T16:35:18.746Z,1729010118.746 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 94.984326 min
2024-10-15T16:35:18.746Z,1729010118.746 [Default:CheckIn:E] Stopped
2024-10-15T16:35:18.746Z,1729010118.746 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-15T16:35:18.746Z,1729010118.746 [Default:CheckIn] Stopped
2024-10-15T16:35:18.746Z,1729010118.746 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-15T16:35:18.747Z,1729010118.747 [Default:CheckIn](INFO): Running loop #11
2024-10-15T16:35:18.747Z,1729010118.747 [Default:CheckIn] Running Loop=11
2024-10-15T16:35:18.747Z,1729010118.747 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-15T16:35:18.747Z,1729010118.747 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-15T16:35:20.696Z,1729010120.696 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,163519.00,A,3648.14965,N,12147.22199,W,0.058,27.44,151024,,,D*4D
2024-10-15T16:35:20.698Z,1729010120.698 [NAL9602](INFO): GPS fix at 20241015T163519: (36.802494, -121.787033)
2024-10-15T16:35:20.709Z,1729010120.709 [Default:CheckIn:Read_GPS] Stopped
2024-10-15T16:35:20.709Z,1729010120.709 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-15T16:35:28.862Z,1729010128.862 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241015T145706/Courier0034.lzma
2024-10-15T16:35:29.573Z,1729010129.573 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:35:29.864Z,1729010129.864 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Courier0034.lzma.bak
2024-10-15T16:35:29.864Z,1729010129.864 [DataOverHttps](INFO): SBD MOMSN=23302105
2024-10-15T16:35:46.171Z,1729010146.171 [DataOverHttps](INFO): Sending 144 bytes from file Logs/20241015T145706/Express0036.lzma
2024-10-15T16:35:47.172Z,1729010147.172 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Express0036.lzma.bak
2024-10-15T16:35:47.172Z,1729010147.172 [DataOverHttps](INFO): SBD MOMSN=23302108
2024-10-15T16:35:48.583Z,1729010148.583 [Default:CheckIn:Read_Iridium] Stopped
2024-10-15T16:35:48.583Z,1729010148.583 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-15T16:35:48.584Z,1729010148.584 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-15T16:35:50.224Z,1729010150.224 [NAL9602](INFO): SBD MO Status=2, MOMSN=57836, MT Status=2, MTMSN=0
2024-10-15T16:35:50.225Z,1729010150.225 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-10-15T16:37:11.516Z,1729010231.516 [NAL9602](INFO): SBD MO Status=0, MOMSN=57836, MT Status=0, MTMSN=0
2024-10-15T16:37:11.516Z,1729010231.516 [NAL9602](INFO): No messages in MT queue
2024-10-15T16:37:42.239Z,1729010262.239 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-15T16:40:48.988Z,1729010448.988 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-15T16:40:48.988Z,1729010448.988 [Default:CheckIn:C.Wait] Stopped
2024-10-15T16:40:48.988Z,1729010448.988 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-15T16:40:48.988Z,1729010448.988 [Default:CheckIn:D] Running Loop=1
2024-10-15T16:40:49.339Z,1729010449.339 [Default:CheckIn:D] Stopped
2024-10-15T16:40:49.339Z,1729010449.339 [Default:CheckIn:E] Running Loop=1
2024-10-15T16:40:49.735Z,1729010449.735 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 100.501790 min
2024-10-15T16:40:49.735Z,1729010449.735 [Default:CheckIn:E] Stopped
2024-10-15T16:40:49.735Z,1729010449.735 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-15T16:40:49.735Z,1729010449.735 [Default:CheckIn] Stopped
2024-10-15T16:40:49.735Z,1729010449.735 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-15T16:40:49.736Z,1729010449.736 [Default:CheckIn](INFO): Running loop #12
2024-10-15T16:40:49.736Z,1729010449.736 [Default:CheckIn] Running Loop=12
2024-10-15T16:40:49.736Z,1729010449.736 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-15T16:40:49.736Z,1729010449.736 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-15T16:40:51.726Z,1729010451.726 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164051.00,A,3648.14961,N,12147.22319,W,0.000,27.44,151024,,,D*40
2024-10-15T16:40:51.728Z,1729010451.728 [NAL9602](INFO): GPS fix at 20241015T164051: (36.802493, -121.787053)
2024-10-15T16:40:51.747Z,1729010451.747 [Default:CheckIn:Read_GPS] Stopped
2024-10-15T16:40:51.747Z,1729010451.747 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-15T16:40:52.568Z,1729010452.568 [Default:CheckIn:Read_Iridium] Stopped
2024-10-15T16:40:52.568Z,1729010452.568 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-15T16:40:52.568Z,1729010452.568 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-15T16:41:12.752Z,1729010472.752 [NAL9602](INFO): SBD MO Status=0, MOMSN=57837, MT Status=0, MTMSN=0
2024-10-15T16:41:12.752Z,1729010472.752 [NAL9602](INFO): No messages in MT queue
2024-10-15T16:41:43.456Z,1729010503.456 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-15T16:42:55.777Z,1729010575.777 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:43:09.529Z,1729010589.529 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:43:22.909Z,1729010602.909 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:43:36.999Z,1729010616.999 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:43:49.927Z,1729010629.927 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:44:03.259Z,1729010643.259 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:44:16.593Z,1729010656.593 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:44:29.916Z,1729010669.916 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:44:43.659Z,1729010683.659 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:44:56.991Z,1729010696.991 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:45:10.714Z,1729010710.714 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:45:53.149Z,1729010753.149 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-15T16:45:53.149Z,1729010753.149 [Default:CheckIn:C.Wait] Stopped
2024-10-15T16:45:53.150Z,1729010753.150 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-15T16:45:53.150Z,1729010753.150 [Default:CheckIn:D] Running Loop=1
2024-10-15T16:45:53.611Z,1729010753.611 [Default:CheckIn:D] Stopped
2024-10-15T16:45:53.611Z,1729010753.611 [Default:CheckIn:E] Running Loop=1
2024-10-15T16:45:53.961Z,1729010753.961 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 105.572982 min
2024-10-15T16:45:53.961Z,1729010753.961 [Default:CheckIn:E] Stopped
2024-10-15T16:45:53.962Z,1729010753.962 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-15T16:45:53.962Z,1729010753.962 [Default:CheckIn] Stopped
2024-10-15T16:45:53.962Z,1729010753.962 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-15T16:45:53.962Z,1729010753.962 [Default:CheckIn](INFO): Running loop #13
2024-10-15T16:45:53.962Z,1729010753.962 [Default:CheckIn] Running Loop=13
2024-10-15T16:45:53.962Z,1729010753.962 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-15T16:45:53.962Z,1729010753.962 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-15T16:45:55.965Z,1729010755.965 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,164555.00,A,3648.14956,N,12147.22157,W,0.117,27.44,151024,,,D*4A
2024-10-15T16:45:55.979Z,1729010755.979 [NAL9602](INFO): GPS fix at 20241015T164555: (36.802493, -121.787026)
2024-10-15T16:45:55.989Z,1729010755.989 [Default:CheckIn:Read_GPS] Stopped
2024-10-15T16:45:55.989Z,1729010755.989 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-15T16:46:03.550Z,1729010763.550 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241015T145706/Courier0040.lzma
2024-10-15T16:46:04.552Z,1729010764.552 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Courier0040.lzma.bak
2024-10-15T16:46:04.552Z,1729010764.552 [DataOverHttps](INFO): SBD MOMSN=23302187
2024-10-15T16:46:23.490Z,1729010783.490 [NAL9602](INFO): SBD MO Status=0, MOMSN=57838, MT Status=0, MTMSN=0
2024-10-15T16:46:23.490Z,1729010783.490 [NAL9602](INFO): No messages in MT queue
2024-10-15T16:46:24.250Z,1729010784.250 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241015T145706/Courier0043.lzma
2024-10-15T16:46:25.252Z,1729010785.252 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Courier0043.lzma.bak
2024-10-15T16:46:25.253Z,1729010785.253 [DataOverHttps](INFO): SBD MOMSN=23302190
2024-10-15T16:46:42.018Z,1729010802.018 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20241015T145706/Express0041.lzma
2024-10-15T16:46:43.020Z,1729010803.020 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Express0041.lzma.bak
2024-10-15T16:46:43.020Z,1729010803.020 [DataOverHttps](INFO): SBD MOMSN=23302194
2024-10-15T16:46:53.904Z,1729010813.904 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-15T16:47:03.203Z,1729010823.203 [DataOverHttps](INFO): Sending 138 bytes from file Logs/20241015T145706/Express0044.lzma
2024-10-15T16:47:04.204Z,1729010824.204 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Express0044.lzma.bak
2024-10-15T16:47:04.204Z,1729010824.204 [DataOverHttps](INFO): SBD MOMSN=23302197
2024-10-15T16:47:05.559Z,1729010825.559 [Default:CheckIn:Read_Iridium] Stopped
2024-10-15T16:47:05.559Z,1729010825.559 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-15T16:47:05.559Z,1729010825.559 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-15T16:52:06.137Z,1729011126.137 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-15T16:52:06.137Z,1729011126.137 [Default:CheckIn:C.Wait] Stopped
2024-10-15T16:52:06.138Z,1729011126.138 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-15T16:52:06.138Z,1729011126.138 [Default:CheckIn:D] Running Loop=1
2024-10-15T16:52:06.545Z,1729011126.545 [Default:CheckIn:D] Stopped
2024-10-15T16:52:06.545Z,1729011126.545 [Default:CheckIn:E] Running Loop=1
2024-10-15T16:52:06.956Z,1729011126.956 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 111.788558 min
2024-10-15T16:52:06.956Z,1729011126.956 [Default:CheckIn:E] Stopped
2024-10-15T16:52:06.957Z,1729011126.957 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-15T16:52:06.957Z,1729011126.957 [Default:CheckIn] Stopped
2024-10-15T16:52:06.957Z,1729011126.957 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-15T16:52:06.957Z,1729011126.957 [Default:CheckIn](INFO): Running loop #14
2024-10-15T16:52:06.957Z,1729011126.957 [Default:CheckIn] Running Loop=14
2024-10-15T16:52:06.957Z,1729011126.957 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-15T16:52:06.957Z,1729011126.957 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-15T16:52:08.960Z,1729011128.960 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165208.00,A,3648.14830,N,12147.22266,W,0.019,27.44,151024,,,D*4B
2024-10-15T16:52:08.962Z,1729011128.962 [NAL9602](INFO): GPS fix at 20241015T165208: (36.802472, -121.787044)
2024-10-15T16:52:08.993Z,1729011128.993 [Default:CheckIn:Read_GPS] Stopped
2024-10-15T16:52:08.993Z,1729011128.993 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-15T16:52:16.806Z,1729011136.806 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20241015T145706/Courier0046.lzma
2024-10-15T16:52:17.808Z,1729011137.808 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Courier0046.lzma.bak
2024-10-15T16:52:17.809Z,1729011137.809 [DataOverHttps](INFO): SBD MOMSN=23302285
2024-10-15T16:52:27.076Z,1729011147.076 [NAL9602](INFO): SBD MO Status=0, MOMSN=57839, MT Status=0, MTMSN=0
2024-10-15T16:52:27.076Z,1729011147.076 [NAL9602](INFO): No messages in MT queue
2024-10-15T16:52:35.786Z,1729011155.786 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:52:37.547Z,1729011157.547 [DataOverHttps](INFO): Sending 139 bytes from file Logs/20241015T145706/Express0047.lzma
2024-10-15T16:52:38.548Z,1729011158.548 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Express0047.lzma.bak
2024-10-15T16:52:38.548Z,1729011158.548 [DataOverHttps](INFO): SBD MOMSN=23302290
2024-10-15T16:52:39.852Z,1729011159.852 [Default:CheckIn:Read_Iridium] Stopped
2024-10-15T16:52:39.852Z,1729011159.852 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-15T16:52:39.852Z,1729011159.852 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-15T16:52:47.905Z,1729011167.905 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:52:57.606Z,1729011177.606 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-15T16:53:00.025Z,1729011180.025 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:53:12.147Z,1729011192.147 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:53:24.269Z,1729011204.269 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:53:35.986Z,1729011215.986 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:53:48.111Z,1729011228.111 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:54:00.630Z,1729011240.630 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:54:12.749Z,1729011252.749 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:54:24.874Z,1729011264.874 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:54:27.380Z,1729011267.380 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2024-10-15T16:54:36.989Z,1729011276.989 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:54:49.109Z,1729011289.109 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T16:57:40.481Z,1729011460.481 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-15T16:57:40.482Z,1729011460.482 [Default:CheckIn:C.Wait] Stopped
2024-10-15T16:57:40.482Z,1729011460.482 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-15T16:57:40.482Z,1729011460.482 [Default:CheckIn:D] Running Loop=1
2024-10-15T16:57:40.885Z,1729011460.885 [Default:CheckIn:D] Stopped
2024-10-15T16:57:40.885Z,1729011460.885 [Default:CheckIn:E] Running Loop=1
2024-10-15T16:57:41.289Z,1729011461.289 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 117.360889 min
2024-10-15T16:57:41.290Z,1729011461.290 [Default:CheckIn:E] Stopped
2024-10-15T16:57:41.290Z,1729011461.290 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-15T16:57:41.290Z,1729011461.290 [Default:CheckIn] Stopped
2024-10-15T16:57:41.290Z,1729011461.290 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-15T16:57:41.290Z,1729011461.290 [Default:CheckIn](INFO): Running loop #15
2024-10-15T16:57:41.290Z,1729011461.290 [Default:CheckIn] Running Loop=15
2024-10-15T16:57:41.290Z,1729011461.290 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-15T16:57:41.290Z,1729011461.290 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-15T16:57:43.300Z,1729011463.300 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,165742.00,A,3648.14918,N,12147.22260,W,0.156,27.44,151024,,,D*47
2024-10-15T16:57:43.313Z,1729011463.313 [NAL9602](INFO): GPS fix at 20241015T165742: (36.802486, -121.787043)
2024-10-15T16:57:43.324Z,1729011463.324 [Default:CheckIn:Read_GPS] Stopped
2024-10-15T16:57:43.324Z,1729011463.324 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-15T16:57:51.098Z,1729011471.098 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241015T145706/Courier0049.lzma
2024-10-15T16:57:52.100Z,1729011472.100 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Courier0049.lzma.bak
2024-10-15T16:57:52.100Z,1729011472.100 [DataOverHttps](INFO): SBD MOMSN=23302294
2024-10-15T16:57:55.429Z,1729011475.429 [NAL9602](INFO): SBD MO Status=0, MOMSN=57840, MT Status=0, MTMSN=0
2024-10-15T16:57:55.429Z,1729011475.429 [NAL9602](INFO): No messages in MT queue
2024-10-15T16:58:08.310Z,1729011488.310 [DataOverHttps](INFO): Sending 140 bytes from file Logs/20241015T145706/Express0050.lzma
2024-10-15T16:58:09.312Z,1729011489.312 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Express0050.lzma.bak
2024-10-15T16:58:09.312Z,1729011489.312 [DataOverHttps](INFO): SBD MOMSN=23302301
2024-10-15T16:58:10.377Z,1729011490.377 [Default:CheckIn:Read_Iridium] Stopped
2024-10-15T16:58:10.377Z,1729011490.377 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-15T16:58:10.377Z,1729011490.377 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-15T16:58:26.123Z,1729011506.123 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-15T16:59:20.661Z,1729011560.661 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 6.
2024-10-15T16:59:20.664Z,1729011560.664 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2024-10-15T17:03:11.137Z,1729011791.137 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-15T17:03:11.137Z,1729011791.137 [Default:CheckIn:C.Wait] Stopped
2024-10-15T17:03:11.137Z,1729011791.137 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-15T17:03:11.137Z,1729011791.137 [Default:CheckIn:D] Running Loop=1
2024-10-15T17:03:11.555Z,1729011791.555 [Default:CheckIn:D] Stopped
2024-10-15T17:03:11.555Z,1729011791.555 [Default:CheckIn:E] Running Loop=1
2024-10-15T17:03:11.960Z,1729011791.960 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 122.872054 min
2024-10-15T17:03:11.960Z,1729011791.960 [Default:CheckIn:E] Stopped
2024-10-15T17:03:11.960Z,1729011791.960 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-15T17:03:11.961Z,1729011791.961 [Default:CheckIn] Stopped
2024-10-15T17:03:11.961Z,1729011791.961 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-15T17:03:11.961Z,1729011791.961 [Default:CheckIn](INFO): Running loop #16
2024-10-15T17:03:11.961Z,1729011791.961 [Default:CheckIn] Running Loop=16
2024-10-15T17:03:11.961Z,1729011791.961 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-15T17:03:11.961Z,1729011791.961 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-15T17:03:13.952Z,1729011793.952 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170313.00,A,3648.14994,N,12147.22292,W,0.019,0.00,151024,,,D*75
2024-10-15T17:03:13.955Z,1729011793.955 [NAL9602](INFO): GPS fix at 20241015T170313: (36.802499, -121.787049)
2024-10-15T17:03:13.965Z,1729011793.965 [Default:CheckIn:Read_GPS] Stopped
2024-10-15T17:03:13.965Z,1729011793.965 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-15T17:03:21.738Z,1729011801.738 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20241015T145706/Courier0052.lzma
2024-10-15T17:03:22.740Z,1729011802.740 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Courier0052.lzma.bak
2024-10-15T17:03:22.740Z,1729011802.740 [DataOverHttps](INFO): SBD MOMSN=23302401
2024-10-15T17:03:39.022Z,1729011819.022 [DataOverHttps](INFO): Sending 148 bytes from file Logs/20241015T145706/Express0053.lzma
2024-10-15T17:03:40.024Z,1729011820.024 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Express0053.lzma.bak
2024-10-15T17:03:40.025Z,1729011820.025 [DataOverHttps](INFO): SBD MOMSN=23302404
2024-10-15T17:03:40.213Z,1729011820.213 [NAL9602](INFO): SBD MO Status=2, MOMSN=57841, MT Status=2, MTMSN=0
2024-10-15T17:03:40.213Z,1729011820.213 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-10-15T17:03:41.041Z,1729011821.041 [Default:CheckIn:Read_Iridium] Stopped
2024-10-15T17:03:41.041Z,1729011821.041 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-15T17:03:41.041Z,1729011821.041 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-15T17:04:31.925Z,1729011871.925 [NAL9602](INFO): SBD MO Status=2, MOMSN=57841, MT Status=2, MTMSN=0
2024-10-15T17:04:31.925Z,1729011871.925 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-10-15T17:04:48.898Z,1729011888.898 [NAL9602](INFO): SBD MO Status=0, MOMSN=57841, MT Status=0, MTMSN=0
2024-10-15T17:04:48.906Z,1729011888.906 [NAL9602](INFO): No messages in MT queue
2024-10-15T17:05:19.600Z,1729011919.600 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-15T17:08:41.797Z,1729012121.797 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-15T17:08:41.797Z,1729012121.797 [Default:CheckIn:C.Wait] Stopped
2024-10-15T17:08:41.798Z,1729012121.798 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-15T17:08:41.798Z,1729012121.798 [Default:CheckIn:D] Running Loop=1
2024-10-15T17:08:42.203Z,1729012122.203 [Default:CheckIn:D] Stopped
2024-10-15T17:08:42.203Z,1729012122.203 [Default:CheckIn:E] Running Loop=1
2024-10-15T17:08:42.604Z,1729012122.604 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 128.382853 min
2024-10-15T17:08:42.604Z,1729012122.604 [Default:CheckIn:E] Stopped
2024-10-15T17:08:42.604Z,1729012122.604 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-15T17:08:42.604Z,1729012122.604 [Default:CheckIn] Stopped
2024-10-15T17:08:42.604Z,1729012122.604 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-15T17:08:42.604Z,1729012122.604 [Default:CheckIn](INFO): Running loop #17
2024-10-15T17:08:42.604Z,1729012122.604 [Default:CheckIn] Running Loop=17
2024-10-15T17:08:42.604Z,1729012122.604 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-15T17:08:42.604Z,1729012122.604 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-15T17:08:44.613Z,1729012124.613 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170843.00,A,3648.15056,N,12147.22200,W,0.058,0.00,151024,,,D*73
2024-10-15T17:08:44.626Z,1729012124.626 [NAL9602](INFO): GPS fix at 20241015T170843: (36.802509, -121.787033)
2024-10-15T17:08:44.636Z,1729012124.636 [Default:CheckIn:Read_GPS] Stopped
2024-10-15T17:08:44.636Z,1729012124.636 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-15T17:08:55.235Z,1729012135.235 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20241015T145706/Courier0055.lzma
2024-10-15T17:08:56.228Z,1729012136.228 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Courier0055.lzma.bak
2024-10-15T17:08:56.229Z,1729012136.229 [DataOverHttps](INFO): SBD MOMSN=23302443
2024-10-15T17:09:04.452Z,1729012144.452 [NAL9602](INFO): SBD MO Status=0, MOMSN=57842, MT Status=0, MTMSN=0
2024-10-15T17:09:04.452Z,1729012144.452 [NAL9602](INFO): No messages in MT queue
2024-10-15T17:09:12.698Z,1729012152.698 [DataOverHttps](INFO): Sending 141 bytes from file Logs/20241015T145706/Express0056.lzma
2024-10-15T17:09:13.700Z,1729012153.700 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Express0056.lzma.bak
2024-10-15T17:09:13.700Z,1729012153.700 [DataOverHttps](INFO): SBD MOMSN=23302446
2024-10-15T17:09:14.981Z,1729012154.981 [Default:CheckIn:Read_Iridium] Stopped
2024-10-15T17:09:14.981Z,1729012154.981 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-15T17:09:14.982Z,1729012154.982 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-15T17:09:35.155Z,1729012175.155 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-15T17:14:15.773Z,1729012455.773 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-15T17:14:15.773Z,1729012455.773 [Default:CheckIn:C.Wait] Stopped
2024-10-15T17:14:15.773Z,1729012455.773 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-15T17:14:15.773Z,1729012455.773 [Default:CheckIn:D] Running Loop=1
2024-10-15T17:14:16.182Z,1729012456.182 [Default:CheckIn:D] Stopped
2024-10-15T17:14:16.182Z,1729012456.182 [Default:CheckIn:E] Running Loop=1
2024-10-15T17:14:16.570Z,1729012456.570 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 133.949162 min
2024-10-15T17:14:16.570Z,1729012456.570 [Default:CheckIn:E] Stopped
2024-10-15T17:14:16.570Z,1729012456.570 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-15T17:14:16.570Z,1729012456.570 [Default:CheckIn] Stopped
2024-10-15T17:14:16.570Z,1729012456.570 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-15T17:14:16.570Z,1729012456.570 [Default:CheckIn](INFO): Running loop #18
2024-10-15T17:14:16.570Z,1729012456.570 [Default:CheckIn] Running Loop=18
2024-10-15T17:14:16.571Z,1729012456.571 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-15T17:14:16.571Z,1729012456.571 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-15T17:14:18.578Z,1729012458.578 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171417.00,A,3648.15109,N,12147.22150,W,0.019,0.00,151024,,,D*77
2024-10-15T17:14:18.580Z,1729012458.580 [NAL9602](INFO): GPS fix at 20241015T171417: (36.802518, -121.787025)
2024-10-15T17:14:18.599Z,1729012458.599 [Default:CheckIn:Read_GPS] Stopped
2024-10-15T17:14:18.599Z,1729012458.599 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-15T17:14:28.902Z,1729012468.902 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241015T145706/Courier0058.lzma
2024-10-15T17:14:29.904Z,1729012469.904 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Courier0058.lzma.bak
2024-10-15T17:14:29.904Z,1729012469.904 [DataOverHttps](INFO): SBD MOMSN=23302457
2024-10-15T17:14:41.805Z,1729012481.805 [NAL9602](INFO): SBD MO Status=2, MOMSN=57843, MT Status=2, MTMSN=0
2024-10-15T17:14:41.805Z,1729012481.805 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-10-15T17:14:46.283Z,1729012486.283 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20241015T145706/Express0059.lzma
2024-10-15T17:14:47.281Z,1729012487.281 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Express0059.lzma.bak
2024-10-15T17:14:47.281Z,1729012487.281 [DataOverHttps](INFO): SBD MOMSN=23302460
2024-10-15T17:14:48.309Z,1729012488.309 [Default:CheckIn:Read_Iridium] Stopped
2024-10-15T17:14:48.309Z,1729012488.309 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-15T17:14:48.309Z,1729012488.309 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-15T17:14:59.233Z,1729012499.233 [NAL9602](INFO): SBD MO Status=0, MOMSN=57843, MT Status=0, MTMSN=0
2024-10-15T17:14:59.234Z,1729012499.234 [NAL9602](INFO): No messages in MT queue
2024-10-15T17:15:29.931Z,1729012529.931 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-15T17:16:27.543Z,1729012587.543 [RudderServo](ERROR): getPosition uart error serial timeout
2024-10-15T17:16:27.543Z,1729012587.543 [RudderServo](FAULT): Rudder uart error - getPosition..serial timeout
2024-10-15T17:16:27.543Z,1729012587.543 [RudderServo] Communications Fault, FailCount= 1
2024-10-15T17:16:27.543Z,1729012587.543 [RudderServo](ERROR): Communications Fault
2024-10-15T17:16:27.547Z,1729012587.547 [CBIT](ERROR): Communications Fault in component: RudderServo
2024-10-15T17:16:27.836Z,1729012587.836 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2024-10-15T17:16:27.836Z,1729012587.836 [RudderServo](INFO): Powering down
2024-10-15T17:16:28.597Z,1729012588.597 [RudderServo](DEBUG): Initializing EZServoServo.
2024-10-15T17:16:28.715Z,1729012588.715 [RudderServo](DEBUG): Initializing RudderServo.
2024-10-15T17:16:28.719Z,1729012588.719 [CBIT](INFO): Clearing failed state for component RudderServo
2024-10-15T17:16:28.719Z,1729012588.719 [RudderServo] No Fault, FailCount= 1
2024-10-15T17:19:49.012Z,1729012789.012 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-15T17:19:49.013Z,1729012789.013 [Default:CheckIn:C.Wait] Stopped
2024-10-15T17:19:49.013Z,1729012789.013 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-15T17:19:49.013Z,1729012789.013 [Default:CheckIn:D] Running Loop=1
2024-10-15T17:19:49.431Z,1729012789.431 [Default:CheckIn:D] Stopped
2024-10-15T17:19:49.431Z,1729012789.431 [Default:CheckIn:E] Running Loop=1
2024-10-15T17:19:49.821Z,1729012789.821 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 139.503320 min
2024-10-15T17:19:49.821Z,1729012789.821 [Default:CheckIn:E] Stopped
2024-10-15T17:19:49.821Z,1729012789.821 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-15T17:19:49.821Z,1729012789.821 [Default:CheckIn] Stopped
2024-10-15T17:19:49.821Z,1729012789.821 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-15T17:19:49.821Z,1729012789.821 [Default:CheckIn](INFO): Running loop #19
2024-10-15T17:19:49.821Z,1729012789.821 [Default:CheckIn] Running Loop=19
2024-10-15T17:19:49.821Z,1729012789.821 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-15T17:19:49.821Z,1729012789.821 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-15T17:19:51.835Z,1729012791.835 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171951.00,A,3648.15133,N,12147.22257,W,0.039,0.00,151024,,,D*77
2024-10-15T17:19:51.837Z,1729012791.837 [NAL9602](INFO): GPS fix at 20241015T171951: (36.802522, -121.787043)
2024-10-15T17:19:51.847Z,1729012791.847 [Default:CheckIn:Read_GPS] Stopped
2024-10-15T17:19:51.848Z,1729012791.848 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-15T17:19:59.822Z,1729012799.822 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241015T145706/Courier0061.lzma
2024-10-15T17:20:00.824Z,1729012800.824 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Courier0061.lzma.bak
2024-10-15T17:20:00.824Z,1729012800.824 [DataOverHttps](INFO): SBD MOMSN=23302496
2024-10-15T17:20:04.761Z,1729012804.761 [NAL9602](INFO): SBD MO Status=0, MOMSN=57844, MT Status=0, MTMSN=0
2024-10-15T17:20:04.761Z,1729012804.761 [NAL9602](INFO): No messages in MT queue
2024-10-15T17:20:17.107Z,1729012817.107 [DataOverHttps](INFO): Sending 239 bytes from file Logs/20241015T145706/Express0062.lzma
2024-10-15T17:20:18.108Z,1729012818.108 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Express0062.lzma.bak
2024-10-15T17:20:18.108Z,1729012818.108 [DataOverHttps](INFO): SBD MOMSN=23302499
2024-10-15T17:20:19.320Z,1729012819.320 [Default:CheckIn:Read_Iridium] Stopped
2024-10-15T17:20:19.320Z,1729012819.320 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-15T17:20:19.320Z,1729012819.320 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-15T17:20:35.515Z,1729012835.515 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-15T17:25:20.013Z,1729013120.013 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-15T17:25:20.013Z,1729013120.013 [Default:CheckIn:C.Wait] Stopped
2024-10-15T17:25:20.014Z,1729013120.014 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-15T17:25:20.014Z,1729013120.014 [Default:CheckIn:D] Running Loop=1
2024-10-15T17:25:20.420Z,1729013120.420 [Default:CheckIn:D] Stopped
2024-10-15T17:25:20.420Z,1729013120.420 [Default:CheckIn:E] Running Loop=1
2024-10-15T17:25:20.833Z,1729013120.833 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 145.019808 min
2024-10-15T17:25:20.833Z,1729013120.833 [Default:CheckIn:E] Stopped
2024-10-15T17:25:20.833Z,1729013120.833 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-15T17:25:20.834Z,1729013120.834 [Default:CheckIn] Stopped
2024-10-15T17:25:20.834Z,1729013120.834 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-15T17:25:20.834Z,1729013120.834 [Default:CheckIn](INFO): Running loop #20
2024-10-15T17:25:20.834Z,1729013120.834 [Default:CheckIn] Running Loop=20
2024-10-15T17:25:20.834Z,1729013120.834 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-15T17:25:20.838Z,1729013120.838 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-15T17:25:22.833Z,1729013122.833 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172522.00,A,3648.15067,N,12147.22182,W,0.019,0.00,151024,,,D*75
2024-10-15T17:25:22.836Z,1729013122.836 [NAL9602](INFO): GPS fix at 20241015T172522: (36.802511, -121.787030)
2024-10-15T17:25:22.867Z,1729013122.867 [Default:CheckIn:Read_GPS] Stopped
2024-10-15T17:25:22.867Z,1729013122.867 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-15T17:25:30.890Z,1729013130.890 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241015T145706/Courier0064.lzma
2024-10-15T17:25:31.892Z,1729013131.892 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Courier0064.lzma.bak
2024-10-15T17:25:31.892Z,1729013131.892 [DataOverHttps](INFO): SBD MOMSN=23302510
2024-10-15T17:25:39.801Z,1729013139.801 [NAL9602](INFO): SBD MO Status=0, MOMSN=57845, MT Status=0, MTMSN=0
2024-10-15T17:25:39.801Z,1729013139.801 [NAL9602](INFO): No messages in MT queue
2024-10-15T17:25:48.138Z,1729013148.138 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20241015T145706/Express0065.lzma
2024-10-15T17:25:49.144Z,1729013149.144 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Express0065.lzma.bak
2024-10-15T17:25:49.144Z,1729013149.144 [DataOverHttps](INFO): SBD MOMSN=23302513
2024-10-15T17:25:50.313Z,1729013150.313 [Default:CheckIn:Read_Iridium] Stopped
2024-10-15T17:25:50.313Z,1729013150.313 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-15T17:25:50.313Z,1729013150.313 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-15T17:26:10.498Z,1729013170.498 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-15T17:30:50.979Z,1729013450.979 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-15T17:30:50.979Z,1729013450.979 [Default:CheckIn:C.Wait] Stopped
2024-10-15T17:30:50.979Z,1729013450.979 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-15T17:30:50.979Z,1729013450.979 [Default:CheckIn:D] Running Loop=1
2024-10-15T17:30:51.406Z,1729013451.406 [Default:CheckIn:D] Stopped
2024-10-15T17:30:51.406Z,1729013451.406 [Default:CheckIn:E] Running Loop=1
2024-10-15T17:30:51.790Z,1729013451.790 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 150.535840 min
2024-10-15T17:30:51.790Z,1729013451.790 [Default:CheckIn:E] Stopped
2024-10-15T17:30:51.790Z,1729013451.790 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-15T17:30:51.791Z,1729013451.791 [Default:CheckIn] Stopped
2024-10-15T17:30:51.791Z,1729013451.791 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-15T17:30:51.791Z,1729013451.791 [Default:CheckIn](INFO): Running loop #21
2024-10-15T17:30:51.791Z,1729013451.791 [Default:CheckIn] Running Loop=21
2024-10-15T17:30:51.791Z,1729013451.791 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-15T17:30:51.791Z,1729013451.791 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-15T17:30:53.789Z,1729013453.789 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173053.00,A,3648.15021,N,12147.22157,W,0.078,144.92,151024,,,D*70
2024-10-15T17:30:53.792Z,1729013453.792 [NAL9602](INFO): GPS fix at 20241015T173053: (36.802504, -121.787026)
2024-10-15T17:30:53.838Z,1729013453.838 [Default:CheckIn:Read_GPS] Stopped
2024-10-15T17:30:53.839Z,1729013453.839 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-15T17:31:01.458Z,1729013461.458 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241015T145706/Courier0067.lzma
2024-10-15T17:31:02.460Z,1729013462.460 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Courier0067.lzma.bak
2024-10-15T17:31:02.460Z,1729013462.460 [DataOverHttps](INFO): SBD MOMSN=23302548
2024-10-15T17:31:16.821Z,1729013476.821 [NAL9602](INFO): SBD MO Status=2, MOMSN=57846, MT Status=2, MTMSN=0
2024-10-15T17:31:16.821Z,1729013476.821 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-10-15T17:31:18.794Z,1729013478.794 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20241015T145706/Express0068.lzma
2024-10-15T17:31:19.796Z,1729013479.796 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Express0068.lzma.bak
2024-10-15T17:31:19.797Z,1729013479.797 [DataOverHttps](INFO): SBD MOMSN=23302553
2024-10-15T17:31:20.870Z,1729013480.870 [Default:CheckIn:Read_Iridium] Stopped
2024-10-15T17:31:20.870Z,1729013480.870 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-15T17:31:20.870Z,1729013480.870 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-15T17:32:42.873Z,1729013562.873 [NAL9602](INFO): SBD MO Status=0, MOMSN=57846, MT Status=0, MTMSN=0
2024-10-15T17:32:42.873Z,1729013562.873 [NAL9602](INFO): No messages in MT queue
2024-10-15T17:33:13.571Z,1729013593.571 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-15T17:36:21.450Z,1729013781.450 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-15T17:36:21.450Z,1729013781.450 [Default:CheckIn:C.Wait] Stopped
2024-10-15T17:36:21.450Z,1729013781.450 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-15T17:36:21.451Z,1729013781.451 [Default:CheckIn:D] Running Loop=1
2024-10-15T17:36:21.862Z,1729013781.862 [Default:CheckIn:D] Stopped
2024-10-15T17:36:21.862Z,1729013781.862 [Default:CheckIn:E] Running Loop=1
2024-10-15T17:36:22.280Z,1729013782.280 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 156.043831 min
2024-10-15T17:36:22.280Z,1729013782.280 [Default:CheckIn:E] Stopped
2024-10-15T17:36:22.281Z,1729013782.281 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-15T17:36:22.281Z,1729013782.281 [Default:CheckIn] Stopped
2024-10-15T17:36:22.281Z,1729013782.281 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-15T17:36:22.281Z,1729013782.281 [Default:CheckIn](INFO): Running loop #22
2024-10-15T17:36:22.281Z,1729013782.281 [Default:CheckIn] Running Loop=22
2024-10-15T17:36:22.281Z,1729013782.281 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-15T17:36:22.281Z,1729013782.281 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-15T17:36:24.269Z,1729013784.269 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173623.00,A,3648.16299,N,12147.18297,W,0.136,113.73,151024,,,D*73
2024-10-15T17:36:24.271Z,1729013784.271 [NAL9602](INFO): GPS fix at 20241015T173623: (36.802717, -121.786383)
2024-10-15T17:36:24.300Z,1729013784.300 [Default:CheckIn:Read_GPS] Stopped
2024-10-15T17:36:24.300Z,1729013784.300 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-15T17:36:31.906Z,1729013791.906 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20241015T145706/Courier0070.lzma
2024-10-15T17:36:32.908Z,1729013792.908 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Courier0070.lzma.bak
2024-10-15T17:36:32.908Z,1729013792.908 [DataOverHttps](INFO): SBD MOMSN=23302693
2024-10-15T17:36:37.997Z,1729013797.997 [NAL9602](INFO): SBD MO Status=0, MOMSN=57847, MT Status=0, MTMSN=0
2024-10-15T17:36:37.997Z,1729013797.997 [NAL9602](INFO): No messages in MT queue
2024-10-15T17:36:50.670Z,1729013810.670 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20241015T145706/Express0071.lzma
2024-10-15T17:36:51.672Z,1729013811.672 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Express0071.lzma.bak
2024-10-15T17:36:51.673Z,1729013811.673 [DataOverHttps](INFO): SBD MOMSN=23302696
2024-10-15T17:36:52.976Z,1729013812.976 [Default:CheckIn:Read_Iridium] Stopped
2024-10-15T17:36:52.977Z,1729013812.977 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-15T17:36:52.977Z,1729013812.977 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-15T17:37:08.730Z,1729013828.730 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-15T17:41:53.561Z,1729014113.561 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-15T17:41:53.561Z,1729014113.561 [Default:CheckIn:C.Wait] Stopped
2024-10-15T17:41:53.561Z,1729014113.561 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-15T17:41:53.561Z,1729014113.561 [Default:CheckIn:D] Running Loop=1
2024-10-15T17:41:53.964Z,1729014113.964 [Default:CheckIn:D] Stopped
2024-10-15T17:41:53.964Z,1729014113.964 [Default:CheckIn:E] Running Loop=1
2024-10-15T17:41:54.378Z,1729014114.378 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 161.578874 min
2024-10-15T17:41:54.378Z,1729014114.378 [Default:CheckIn:E] Stopped
2024-10-15T17:41:54.378Z,1729014114.378 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-15T17:41:54.378Z,1729014114.378 [Default:CheckIn] Stopped
2024-10-15T17:41:54.378Z,1729014114.378 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-15T17:41:54.379Z,1729014114.379 [Default:CheckIn](INFO): Running loop #23
2024-10-15T17:41:54.379Z,1729014114.379 [Default:CheckIn] Running Loop=23
2024-10-15T17:41:54.379Z,1729014114.379 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-15T17:41:54.379Z,1729014114.379 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-15T17:41:56.380Z,1729014116.380 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174155.00,A,3648.22203,N,12147.15918,W,0.719,156.24,151024,,,D*7F
2024-10-15T17:41:56.383Z,1729014116.383 [NAL9602](INFO): GPS fix at 20241015T174155: (36.803700, -121.785986)
2024-10-15T17:41:56.412Z,1729014116.412 [Default:CheckIn:Read_GPS] Stopped
2024-10-15T17:41:56.412Z,1729014116.412 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-15T17:42:03.546Z,1729014123.546 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241015T145706/Courier0073.lzma
2024-10-15T17:42:04.548Z,1729014124.548 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Courier0073.lzma.bak
2024-10-15T17:42:04.549Z,1729014124.549 [DataOverHttps](INFO): SBD MOMSN=23302736
2024-10-15T17:42:22.851Z,1729014142.851 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20241015T145706/Express0074.lzma
2024-10-15T17:42:23.853Z,1729014143.853 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Express0074.lzma.bak
2024-10-15T17:42:23.853Z,1729014143.853 [DataOverHttps](INFO): SBD MOMSN=23302739
2024-10-15T17:42:25.100Z,1729014145.100 [NAL9602](INFO): SBD MO Status=2, MOMSN=57848, MT Status=2, MTMSN=0
2024-10-15T17:42:25.100Z,1729014145.100 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-10-15T17:42:25.143Z,1729014145.143 [Default:CheckIn:Read_Iridium] Stopped
2024-10-15T17:42:25.143Z,1729014145.143 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-15T17:42:25.143Z,1729014145.143 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-15T17:42:59.457Z,1729014179.457 [NAL9602](INFO): SBD MO Status=0, MOMSN=57848, MT Status=0, MTMSN=0
2024-10-15T17:42:59.457Z,1729014179.457 [NAL9602](INFO): No messages in MT queue
2024-10-15T17:43:30.164Z,1729014210.164 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-15T17:47:25.710Z,1729014445.710 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-15T17:47:25.710Z,1729014445.710 [Default:CheckIn:C.Wait] Stopped
2024-10-15T17:47:25.710Z,1729014445.710 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-15T17:47:25.710Z,1729014445.710 [Default:CheckIn:D] Running Loop=1
2024-10-15T17:47:26.125Z,1729014446.125 [Default:CheckIn:D] Stopped
2024-10-15T17:47:26.125Z,1729014446.125 [Default:CheckIn:E] Running Loop=1
2024-10-15T17:47:26.522Z,1729014446.522 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 167.114893 min
2024-10-15T17:47:26.522Z,1729014446.522 [Default:CheckIn:E] Stopped
2024-10-15T17:47:26.522Z,1729014446.522 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-15T17:47:26.522Z,1729014446.522 [Default:CheckIn] Stopped
2024-10-15T17:47:26.522Z,1729014446.522 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-15T17:47:26.523Z,1729014446.523 [Default:CheckIn](INFO): Running loop #24
2024-10-15T17:47:26.523Z,1729014446.523 [Default:CheckIn] Running Loop=24
2024-10-15T17:47:26.523Z,1729014446.523 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-15T17:47:26.523Z,1729014446.523 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-15T17:47:28.529Z,1729014448.529 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174727.00,A,3648.13105,N,12147.18780,W,0.136,195.35,151024,,,D*7D
2024-10-15T17:47:28.532Z,1729014448.532 [NAL9602](INFO): GPS fix at 20241015T174727: (36.802184, -121.786463)
2024-10-15T17:47:28.547Z,1729014448.547 [Default:CheckIn:Read_GPS] Stopped
2024-10-15T17:47:28.547Z,1729014448.547 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-15T17:47:39.138Z,1729014459.138 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241015T145706/Courier0076.lzma
2024-10-15T17:47:40.140Z,1729014460.140 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Courier0076.lzma.bak
2024-10-15T17:47:40.141Z,1729014460.141 [DataOverHttps](INFO): SBD MOMSN=23302761
2024-10-15T17:47:42.668Z,1729014462.668 [NAL9602](INFO): SBD MO Status=0, MOMSN=57849, MT Status=0, MTMSN=0
2024-10-15T17:47:42.668Z,1729014462.668 [NAL9602](INFO): No messages in MT queue
2024-10-15T17:47:56.398Z,1729014476.398 [DataOverHttps](INFO): Sending 164 bytes from file Logs/20241015T145706/Express0077.lzma
2024-10-15T17:47:57.400Z,1729014477.400 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Express0077.lzma.bak
2024-10-15T17:47:57.401Z,1729014477.401 [DataOverHttps](INFO): SBD MOMSN=23302764
2024-10-15T17:47:58.451Z,1729014478.451 [Default:CheckIn:Read_Iridium] Stopped
2024-10-15T17:47:58.451Z,1729014478.451 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-15T17:47:58.452Z,1729014478.452 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-15T17:48:13.370Z,1729014493.370 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-15T17:52:59.014Z,1729014779.014 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-15T17:52:59.014Z,1729014779.014 [Default:CheckIn:C.Wait] Stopped
2024-10-15T17:52:59.014Z,1729014779.014 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-15T17:52:59.014Z,1729014779.014 [Default:CheckIn:D] Running Loop=1
2024-10-15T17:52:59.427Z,1729014779.427 [Default:CheckIn:D] Stopped
2024-10-15T17:52:59.427Z,1729014779.427 [Default:CheckIn:E] Running Loop=1
2024-10-15T17:52:59.850Z,1729014779.850 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 172.669922 min
2024-10-15T17:52:59.850Z,1729014779.850 [Default:CheckIn:E] Stopped
2024-10-15T17:52:59.850Z,1729014779.850 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-15T17:52:59.850Z,1729014779.850 [Default:CheckIn] Stopped
2024-10-15T17:52:59.850Z,1729014779.850 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-15T17:52:59.850Z,1729014779.850 [Default:CheckIn](INFO): Running loop #25
2024-10-15T17:52:59.850Z,1729014779.850 [Default:CheckIn] Running Loop=25
2024-10-15T17:52:59.850Z,1729014779.850 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-15T17:52:59.851Z,1729014779.851 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-15T17:53:01.834Z,1729014781.834 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175301.00,A,3648.15613,N,12147.16584,W,0.603,36.77,151024,,,D*4D
2024-10-15T17:53:01.836Z,1729014781.836 [NAL9602](INFO): GPS fix at 20241015T175301: (36.802602, -121.786097)
2024-10-15T17:53:01.847Z,1729014781.847 [Default:CheckIn:Read_GPS] Stopped
2024-10-15T17:53:01.847Z,1729014781.847 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-15T17:53:09.639Z,1729014789.639 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241015T145706/Courier0079.lzma
2024-10-15T17:53:10.640Z,1729014790.640 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Courier0079.lzma.bak
2024-10-15T17:53:10.641Z,1729014790.641 [DataOverHttps](INFO): SBD MOMSN=23302804
2024-10-15T17:53:13.149Z,1729014793.149 [NAL9602](INFO): SBD MO Status=0, MOMSN=57850, MT Status=0, MTMSN=0
2024-10-15T17:53:13.149Z,1729014793.149 [NAL9602](INFO): No messages in MT queue
2024-10-15T17:53:26.810Z,1729014806.810 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20241015T145706/Express0080.lzma
2024-10-15T17:53:27.812Z,1729014807.812 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Express0080.lzma.bak
2024-10-15T17:53:27.813Z,1729014807.813 [DataOverHttps](INFO): SBD MOMSN=23302810
2024-10-15T17:53:28.924Z,1729014808.924 [Default:CheckIn:Read_Iridium] Stopped
2024-10-15T17:53:28.924Z,1729014808.924 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-15T17:53:28.924Z,1729014808.924 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-15T17:53:43.850Z,1729014823.850 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-15T17:58:29.497Z,1729015109.497 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-15T17:58:29.497Z,1729015109.497 [Default:CheckIn:C.Wait] Stopped
2024-10-15T17:58:29.497Z,1729015109.497 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-15T17:58:29.497Z,1729015109.497 [Default:CheckIn:D] Running Loop=1
2024-10-15T17:58:29.910Z,1729015109.910 [Default:CheckIn:D] Stopped
2024-10-15T17:58:29.910Z,1729015109.910 [Default:CheckIn:E] Running Loop=1
2024-10-15T17:58:30.315Z,1729015110.315 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 178.177962 min
2024-10-15T17:58:30.315Z,1729015110.315 [Default:CheckIn:E] Stopped
2024-10-15T17:58:30.315Z,1729015110.315 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-15T17:58:30.315Z,1729015110.315 [Default:CheckIn] Stopped
2024-10-15T17:58:30.315Z,1729015110.315 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-15T17:58:30.315Z,1729015110.315 [Default:CheckIn](INFO): Running loop #26
2024-10-15T17:58:30.315Z,1729015110.315 [Default:CheckIn] Running Loop=26
2024-10-15T17:58:30.316Z,1729015110.316 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-15T17:58:30.316Z,1729015110.316 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-15T17:58:32.322Z,1729015112.322 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175831.00,A,3648.20908,N,12147.13316,W,0.156,354.83,151024,,,D*75
2024-10-15T17:58:32.324Z,1729015112.324 [NAL9602](INFO): GPS fix at 20241015T175831: (36.803485, -121.785553)
2024-10-15T17:58:32.355Z,1729015112.355 [Default:CheckIn:Read_GPS] Stopped
2024-10-15T17:58:32.355Z,1729015112.355 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-15T17:58:40.342Z,1729015120.342 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241015T145706/Courier0082.lzma
2024-10-15T17:58:41.344Z,1729015121.344 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Courier0082.lzma.bak
2024-10-15T17:58:41.345Z,1729015121.345 [DataOverHttps](INFO): SBD MOMSN=23302829
2024-10-15T17:59:01.042Z,1729015141.042 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20241015T145706/Express0083.lzma
2024-10-15T17:59:02.045Z,1729015142.045 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Express0083.lzma.bak
2024-10-15T17:59:02.045Z,1729015142.045 [DataOverHttps](INFO): SBD MOMSN=23302832
2024-10-15T17:59:03.086Z,1729015143.086 [Default:CheckIn:Read_Iridium] Stopped
2024-10-15T17:59:03.086Z,1729015143.086 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-15T17:59:03.086Z,1729015143.086 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-15T17:59:24.067Z,1729015164.067 [NAL9602](INFO): SBD MO Status=2, MOMSN=57851, MT Status=2, MTMSN=0
2024-10-15T17:59:24.068Z,1729015164.068 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-10-15T18:00:26.681Z,1729015226.681 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T18:00:38.801Z,1729015238.801 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T18:00:50.921Z,1729015250.921 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T18:00:59.010Z,1729015259.010 [NAL9602](INFO): SBD MO Status=0, MOMSN=57851, MT Status=0, MTMSN=0
2024-10-15T18:00:59.010Z,1729015259.010 [NAL9602](INFO): No messages in MT queue
2024-10-15T18:01:03.448Z,1729015263.448 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T18:01:15.565Z,1729015275.565 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T18:01:27.685Z,1729015287.685 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T18:01:29.711Z,1729015289.711 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-15T18:01:39.808Z,1729015299.808 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T18:04:03.657Z,1729015443.657 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-15T18:04:03.657Z,1729015443.657 [Default:CheckIn:C.Wait] Stopped
2024-10-15T18:04:03.657Z,1729015443.657 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-15T18:04:03.657Z,1729015443.657 [Default:CheckIn:D] Running Loop=1
2024-10-15T18:04:04.075Z,1729015444.075 [Default:CheckIn:D] Stopped
2024-10-15T18:04:04.075Z,1729015444.075 [Default:CheckIn:E] Running Loop=1
2024-10-15T18:04:04.466Z,1729015444.466 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 183.747380 min
2024-10-15T18:04:04.466Z,1729015444.466 [Default:CheckIn:E] Stopped
2024-10-15T18:04:04.466Z,1729015444.466 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-15T18:04:04.466Z,1729015444.466 [Default:CheckIn] Stopped
2024-10-15T18:04:04.466Z,1729015444.466 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-15T18:04:04.482Z,1729015444.482 [Default:CheckIn](INFO): Running loop #27
2024-10-15T18:04:04.483Z,1729015444.483 [Default:CheckIn] Running Loop=27
2024-10-15T18:04:04.483Z,1729015444.483 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-15T18:04:04.483Z,1729015444.483 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-15T18:04:06.497Z,1729015446.497 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180405.00,A,3648.53950,N,12147.14461,W,5.637,320.98,151024,,,D*71
2024-10-15T18:04:06.500Z,1729015446.500 [NAL9602](INFO): GPS fix at 20241015T180405: (36.808992, -121.785743)
2024-10-15T18:04:06.511Z,1729015446.511 [Default:CheckIn:Read_GPS] Stopped
2024-10-15T18:04:06.511Z,1729015446.511 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-15T18:04:13.786Z,1729015453.786 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241015T145706/Courier0085.lzma
2024-10-15T18:04:14.788Z,1729015454.788 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Courier0085.lzma.bak
2024-10-15T18:04:14.789Z,1729015454.789 [DataOverHttps](INFO): SBD MOMSN=23302865
2024-10-15T18:04:19.435Z,1729015459.435 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T18:04:25.522Z,1729015465.522 [NAL9602](INFO): SBD MO Status=0, MOMSN=57852, MT Status=0, MTMSN=0
2024-10-15T18:04:25.522Z,1729015465.522 [NAL9602](INFO): No messages in MT queue
2024-10-15T18:04:33.193Z,1729015473.193 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T18:04:34.922Z,1729015474.922 [DataOverHttps](INFO): Sending 148 bytes from file Logs/20241015T145706/Express0086.lzma
2024-10-15T18:04:35.924Z,1729015475.924 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Express0086.lzma.bak
2024-10-15T18:04:35.925Z,1729015475.925 [DataOverHttps](INFO): SBD MOMSN=23302868
2024-10-15T18:04:37.246Z,1729015477.246 [Default:CheckIn:Read_Iridium] Stopped
2024-10-15T18:04:37.246Z,1729015477.246 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-15T18:04:37.246Z,1729015477.246 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-15T18:04:46.524Z,1729015486.524 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T18:04:56.223Z,1729015496.223 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-15T18:04:59.871Z,1729015499.871 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T18:05:13.197Z,1729015513.197 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T18:05:26.534Z,1729015526.534 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T18:05:39.917Z,1729015539.917 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T18:05:53.656Z,1729015553.656 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T18:06:06.923Z,1729015566.923 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T18:06:20.253Z,1729015580.253 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T18:06:33.995Z,1729015593.995 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T18:09:37.833Z,1729015777.833 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-15T18:09:37.833Z,1729015777.833 [Default:CheckIn:C.Wait] Stopped
2024-10-15T18:09:37.834Z,1729015777.834 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-15T18:09:37.834Z,1729015777.834 [Default:CheckIn:D] Running Loop=1
2024-10-15T18:09:38.248Z,1729015778.248 [Default:CheckIn:D] Stopped
2024-10-15T18:09:38.249Z,1729015778.249 [Default:CheckIn:E] Running Loop=1
2024-10-15T18:09:38.639Z,1729015778.639 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 189.316943 min
2024-10-15T18:09:38.639Z,1729015778.639 [Default:CheckIn:E] Stopped
2024-10-15T18:09:38.639Z,1729015778.639 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-15T18:09:38.639Z,1729015778.639 [Default:CheckIn] Stopped
2024-10-15T18:09:38.639Z,1729015778.639 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-15T18:09:38.640Z,1729015778.640 [Default:CheckIn](INFO): Running loop #28
2024-10-15T18:09:38.640Z,1729015778.640 [Default:CheckIn] Running Loop=28
2024-10-15T18:09:38.640Z,1729015778.640 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-15T18:09:38.640Z,1729015778.640 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-15T18:09:40.653Z,1729015780.653 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180939.00,A,3648.45011,N,12147.59024,W,5.754,267.34,151024,,,D*74
2024-10-15T18:09:40.656Z,1729015780.656 [NAL9602](INFO): GPS fix at 20241015T180939: (36.807502, -121.793171)
2024-10-15T18:09:40.666Z,1729015780.666 [Default:CheckIn:Read_GPS] Stopped
2024-10-15T18:09:40.666Z,1729015780.666 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-15T18:09:41.466Z,1729015781.466 [Default:CheckIn:Read_Iridium] Stopped
2024-10-15T18:09:41.466Z,1729015781.466 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-15T18:09:41.467Z,1729015781.467 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-15T18:09:55.188Z,1729015795.188 [NAL9602](INFO): SBD MO Status=0, MOMSN=57853, MT Status=0, MTMSN=0
2024-10-15T18:09:55.188Z,1729015795.188 [NAL9602](INFO): No messages in MT queue
2024-10-15T18:10:25.903Z,1729015825.903 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-15T18:12:37.266Z,1729015957.266 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2024-10-15T18:13:57.708Z,1729016037.708 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T18:14:11.047Z,1729016051.047 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T18:14:23.646Z,1729016063.646 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T18:14:36.971Z,1729016076.971 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T18:14:42.222Z,1729016082.222 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-15T18:14:42.223Z,1729016082.223 [Default:CheckIn:C.Wait] Stopped
2024-10-15T18:14:42.223Z,1729016082.223 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-15T18:14:42.223Z,1729016082.223 [Default:CheckIn:D] Running Loop=1
2024-10-15T18:14:42.644Z,1729016082.644 [Default:CheckIn:D] Stopped
2024-10-15T18:14:42.644Z,1729016082.644 [Default:CheckIn:E] Running Loop=1
2024-10-15T18:14:43.035Z,1729016083.035 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 194.390202 min
2024-10-15T18:14:43.035Z,1729016083.035 [Default:CheckIn:E] Stopped
2024-10-15T18:14:43.035Z,1729016083.035 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-15T18:14:43.035Z,1729016083.035 [Default:CheckIn] Stopped
2024-10-15T18:14:43.036Z,1729016083.036 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-15T18:14:43.036Z,1729016083.036 [Default:CheckIn](INFO): Running loop #29
2024-10-15T18:14:43.036Z,1729016083.036 [Default:CheckIn] Running Loop=29
2024-10-15T18:14:43.036Z,1729016083.036 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-15T18:14:43.036Z,1729016083.036 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-15T18:14:45.045Z,1729016085.045 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181444.00,A,3648.50435,N,12148.18005,W,6.220,253.00,151024,,,A*7D
2024-10-15T18:14:45.047Z,1729016085.047 [NAL9602](INFO): GPS fix at 20241015T181444: (36.808406, -121.803001)
2024-10-15T18:14:45.058Z,1729016085.058 [Default:CheckIn:Read_GPS] Stopped
2024-10-15T18:14:45.058Z,1729016085.058 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-15T18:14:50.482Z,1729016090.482 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T18:14:56.134Z,1729016096.134 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241015T145706/Courier0088.lzma
2024-10-15T18:14:57.137Z,1729016097.137 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Courier0088.lzma.bak
2024-10-15T18:14:57.137Z,1729016097.137 [DataOverHttps](INFO): SBD MOMSN=23303124
2024-10-15T18:15:03.890Z,1729016103.890 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T18:15:05.776Z,1729016105.776 [NAL9602](INFO): SBD MO Status=2, MOMSN=57854, MT Status=2, MTMSN=0
2024-10-15T18:15:05.777Z,1729016105.777 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-10-15T18:15:13.838Z,1729016113.838 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20241015T145706/Courier0091.lzma
2024-10-15T18:15:14.842Z,1729016114.842 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Courier0091.lzma.bak
2024-10-15T18:15:14.842Z,1729016114.842 [DataOverHttps](INFO): SBD MOMSN=23303127
2024-10-15T18:15:17.077Z,1729016117.077 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T18:15:23.549Z,1729016123.549 [NAL9602](INFO): SBD MO Status=0, MOMSN=57854, MT Status=0, MTMSN=0
2024-10-15T18:15:23.549Z,1729016123.549 [NAL9602](INFO): No messages in MT queue
2024-10-15T18:15:30.877Z,1729016130.877 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T18:15:31.309Z,1729016131.309 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20241015T145706/Express0089.lzma
2024-10-15T18:15:32.304Z,1729016132.304 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Express0089.lzma.bak
2024-10-15T18:15:32.305Z,1729016132.305 [DataOverHttps](INFO): SBD MOMSN=23303130
2024-10-15T18:15:44.276Z,1729016144.276 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T18:15:48.883Z,1729016148.883 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20241015T145706/Express0092.lzma
2024-10-15T18:15:49.884Z,1729016149.884 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Express0092.lzma.bak
2024-10-15T18:15:49.885Z,1729016149.885 [DataOverHttps](INFO): SBD MOMSN=23303133
2024-10-15T18:15:51.040Z,1729016151.040 [Default:CheckIn:Read_Iridium] Stopped
2024-10-15T18:15:51.040Z,1729016151.040 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-15T18:15:51.040Z,1729016151.040 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-15T18:15:54.256Z,1729016154.256 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-15T18:15:57.478Z,1729016157.478 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T18:16:10.816Z,1729016170.816 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T18:20:51.691Z,1729016451.691 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-15T18:20:51.691Z,1729016451.691 [Default:CheckIn:C.Wait] Stopped
2024-10-15T18:20:51.692Z,1729016451.692 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-15T18:20:51.692Z,1729016451.692 [Default:CheckIn:D] Running Loop=1
2024-10-15T18:20:52.087Z,1729016452.087 [Default:CheckIn:D] Stopped
2024-10-15T18:20:52.087Z,1729016452.087 [Default:CheckIn:E] Running Loop=1
2024-10-15T18:20:52.536Z,1729016452.536 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 200.547591 min
2024-10-15T18:20:52.536Z,1729016452.536 [Default:CheckIn:E] Stopped
2024-10-15T18:20:52.537Z,1729016452.537 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-15T18:20:52.537Z,1729016452.537 [Default:CheckIn] Stopped
2024-10-15T18:20:52.537Z,1729016452.537 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-15T18:20:52.537Z,1729016452.537 [Default:CheckIn](INFO): Running loop #30
2024-10-15T18:20:52.537Z,1729016452.537 [Default:CheckIn] Running Loop=30
2024-10-15T18:20:52.537Z,1729016452.537 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-15T18:20:52.537Z,1729016452.537 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-15T18:20:54.515Z,1729016454.515 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182053.00,A,3648.25279,N,12148.89949,W,7.542,252.63,151024,,,D*7A
2024-10-15T18:20:54.517Z,1729016454.517 [NAL9602](INFO): GPS fix at 20241015T182053: (36.804213, -121.814992)
2024-10-15T18:20:54.527Z,1729016454.527 [Default:CheckIn:Read_GPS] Stopped
2024-10-15T18:20:54.528Z,1729016454.528 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-15T18:21:02.041Z,1729016462.041 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20241015T145706/Courier0094.lzma
2024-10-15T18:21:04.040Z,1729016464.040 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Courier0094.lzma.bak
2024-10-15T18:21:04.041Z,1729016464.041 [DataOverHttps](INFO): SBD MOMSN=23303153
2024-10-15T18:21:16.145Z,1729016476.145 [NAL9602](INFO): SBD MO Status=0, MOMSN=57855, MT Status=0, MTMSN=0
2024-10-15T18:21:16.145Z,1729016476.145 [NAL9602](INFO): No messages in MT queue
2024-10-15T18:21:23.198Z,1729016483.198 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20241015T145706/Express0095.lzma
2024-10-15T18:21:24.201Z,1729016484.201 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Express0095.lzma.bak
2024-10-15T18:21:24.201Z,1729016484.201 [DataOverHttps](INFO): SBD MOMSN=23303156
2024-10-15T18:21:25.425Z,1729016485.425 [Default:CheckIn:Read_Iridium] Stopped
2024-10-15T18:21:25.425Z,1729016485.425 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-15T18:21:25.425Z,1729016485.425 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-15T18:21:46.823Z,1729016506.823 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-15T18:23:35.897Z,1729016615.897 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T18:23:51.654Z,1729016631.654 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T18:24:07.409Z,1729016647.409 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T18:24:23.262Z,1729016663.262 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T18:24:38.923Z,1729016678.923 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T18:24:54.273Z,1729016694.273 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T18:25:09.625Z,1729016709.625 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T18:25:25.793Z,1729016725.793 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T18:25:41.541Z,1729016741.541 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2024-10-15T18:26:26.011Z,1729016786.011 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-15T18:26:26.011Z,1729016786.011 [Default:CheckIn:C.Wait] Stopped
2024-10-15T18:26:26.011Z,1729016786.011 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-15T18:26:26.012Z,1729016786.012 [Default:CheckIn:D] Running Loop=1
2024-10-15T18:26:26.427Z,1729016786.427 [Default:CheckIn:D] Stopped
2024-10-15T18:26:26.427Z,1729016786.427 [Default:CheckIn:E] Running Loop=1
2024-10-15T18:26:26.845Z,1729016786.845 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 206.119922 min
2024-10-15T18:26:26.845Z,1729016786.845 [Default:CheckIn:E] Stopped
2024-10-15T18:26:26.845Z,1729016786.845 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-15T18:26:26.845Z,1729016786.845 [Default:CheckIn] Stopped
2024-10-15T18:26:26.845Z,1729016786.845 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-15T18:26:26.845Z,1729016786.845 [Default:CheckIn](INFO): Running loop #31
2024-10-15T18:26:26.845Z,1729016786.845 [Default:CheckIn] Running Loop=31
2024-10-15T18:26:26.845Z,1729016786.845 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-15T18:26:26.845Z,1729016786.845 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-15T18:26:28.817Z,1729016788.817 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182628.00,A,3648.09658,N,12149.57685,W,6.220,245.82,151024,,,D*7F
2024-10-15T18:26:28.820Z,1729016788.820 [NAL9602](INFO): GPS fix at 20241015T182628: (36.801610, -121.826281)
2024-10-15T18:26:28.831Z,1729016788.831 [Default:CheckIn:Read_GPS] Stopped
2024-10-15T18:26:28.831Z,1729016788.831 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-15T18:26:36.658Z,1729016796.658 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20241015T145706/Courier0097.lzma
2024-10-15T18:26:37.660Z,1729016797.660 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Courier0097.lzma.bak
2024-10-15T18:26:37.661Z,1729016797.661 [DataOverHttps](INFO): SBD MOMSN=23303195
2024-10-15T18:26:49.421Z,1729016809.421 [NAL9602](INFO): SBD MO Status=0, MOMSN=57856, MT Status=0, MTMSN=0
2024-10-15T18:26:49.421Z,1729016809.421 [NAL9602](INFO): No messages in MT queue
2024-10-15T18:26:50.227Z,1729016810.227 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2024-10-15T18:27:01.154Z,1729016821.154 [DataOverHttps](INFO): Sending 155 bytes from file Logs/20241015T145706/Express0098.lzma
2024-10-15T18:27:02.157Z,1729016822.157 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Express0098.lzma.bak
2024-10-15T18:27:02.157Z,1729016822.157 [DataOverHttps](INFO): SBD MOMSN=23303198
2024-10-15T18:27:03.245Z,1729016823.245 [Default:CheckIn:Read_Iridium] Stopped
2024-10-15T18:27:03.245Z,1729016823.245 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-15T18:27:03.245Z,1729016823.245 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-15T18:27:20.153Z,1729016840.153 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-15T18:30:21.551Z,1729017021.551 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2024-10-15T18:30:21.553Z,1729017021.553 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2024-10-15T18:32:03.824Z,1729017123.824 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-15T18:32:03.824Z,1729017123.824 [Default:CheckIn:C.Wait] Stopped
2024-10-15T18:32:03.824Z,1729017123.824 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-15T18:32:03.825Z,1729017123.825 [Default:CheckIn:D] Running Loop=1
2024-10-15T18:32:04.214Z,1729017124.214 [Default:CheckIn:D] Stopped
2024-10-15T18:32:04.214Z,1729017124.214 [Default:CheckIn:E] Running Loop=1
2024-10-15T18:32:04.618Z,1729017124.618 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 211.749691 min
2024-10-15T18:32:04.618Z,1729017124.618 [Default:CheckIn:E] Stopped
2024-10-15T18:32:04.618Z,1729017124.618 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-15T18:32:04.618Z,1729017124.618 [Default:CheckIn] Stopped
2024-10-15T18:32:04.618Z,1729017124.618 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-15T18:32:04.618Z,1729017124.618 [Default:CheckIn](INFO): Running loop #32
2024-10-15T18:32:04.619Z,1729017124.619 [Default:CheckIn] Running Loop=32
2024-10-15T18:32:04.619Z,1729017124.619 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-15T18:32:04.619Z,1729017124.619 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-15T18:32:06.626Z,1729017126.626 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183205.00,A,3647.61385,N,12150.04780,W,6.512,214.11,151024,,,D*73
2024-10-15T18:32:06.628Z,1729017126.628 [NAL9602](INFO): GPS fix at 20241015T183205: (36.793564, -121.834130)
2024-10-15T18:32:06.639Z,1729017126.639 [Default:CheckIn:Read_GPS] Stopped
2024-10-15T18:32:06.639Z,1729017126.639 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-15T18:32:14.454Z,1729017134.454 [DataOverHttps](INFO): Sending 85 bytes from file Logs/20241015T145706/Courier0100.lzma
2024-10-15T18:32:19.473Z,1729017139.473 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Courier0100.lzma.bak
2024-10-15T18:32:19.473Z,1729017139.473 [DataOverHttps](INFO): SBD MOMSN=23303223
2024-10-15T18:32:21.588Z,1729017141.588 [NAL9602](INFO): SBD MO Status=0, MOMSN=57857, MT Status=0, MTMSN=0
2024-10-15T18:32:21.588Z,1729017141.588 [NAL9602](INFO): No messages in MT queue
2024-10-15T18:32:40.151Z,1729017160.151 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20241015T145706/Express0101.lzma
2024-10-15T18:32:41.148Z,1729017161.148 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Express0101.lzma.bak
2024-10-15T18:32:41.149Z,1729017161.149 [DataOverHttps](INFO): SBD MOMSN=23303228
2024-10-15T18:32:42.255Z,1729017162.255 [Default:CheckIn:Read_Iridium] Stopped
2024-10-15T18:32:42.255Z,1729017162.255 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-15T18:32:42.255Z,1729017162.255 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-15T18:32:52.342Z,1729017172.342 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-15T18:37:42.666Z,1729017462.666 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-15T18:37:42.666Z,1729017462.666 [Default:CheckIn:C.Wait] Stopped
2024-10-15T18:37:42.666Z,1729017462.666 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-15T18:37:42.666Z,1729017462.666 [Default:CheckIn:D] Running Loop=1
2024-10-15T18:37:43.084Z,1729017463.084 [Default:CheckIn:D] Stopped
2024-10-15T18:37:43.084Z,1729017463.084 [Default:CheckIn:E] Running Loop=1
2024-10-15T18:37:43.492Z,1729017463.492 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 217.397526 min
2024-10-15T18:37:43.492Z,1729017463.492 [Default:CheckIn:E] Stopped
2024-10-15T18:37:43.492Z,1729017463.492 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-15T18:37:43.492Z,1729017463.492 [Default:CheckIn] Stopped
2024-10-15T18:37:43.492Z,1729017463.492 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-15T18:37:43.492Z,1729017463.492 [Default:CheckIn](INFO): Running loop #33
2024-10-15T18:37:43.492Z,1729017463.492 [Default:CheckIn] Running Loop=33
2024-10-15T18:37:43.493Z,1729017463.493 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-15T18:37:43.493Z,1729017463.493 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-15T18:37:45.489Z,1729017465.489 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183744.00,A,3647.48689,N,12150.13769,W,0.253,148.38,151024,,,D*75
2024-10-15T18:37:45.491Z,1729017465.491 [NAL9602](INFO): GPS fix at 20241015T183744: (36.791448, -121.835628)
2024-10-15T18:37:45.502Z,1729017465.502 [Default:CheckIn:Read_GPS] Stopped
2024-10-15T18:37:45.522Z,1729017465.522 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-15T18:37:54.614Z,1729017474.614 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20241015T145706/Courier0103.lzma
2024-10-15T18:37:55.616Z,1729017475.616 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Courier0103.lzma.bak
2024-10-15T18:37:55.617Z,1729017475.617 [DataOverHttps](INFO): SBD MOMSN=23303271
2024-10-15T18:38:12.702Z,1729017492.702 [DataOverHttps](INFO): Sending 161 bytes from file Logs/20241015T145706/Express0104.lzma
2024-10-15T18:38:13.705Z,1729017493.705 [DataOverHttps](INFO): Moved sent file to Logs/20241015T145706/Express0104.lzma.bak
2024-10-15T18:38:13.705Z,1729017493.705 [DataOverHttps](INFO): SBD MOMSN=23303279
2024-10-15T18:38:14.175Z,1729017494.175 [NAL9602](INFO): SBD MO Status=0, MOMSN=57858, MT Status=0, MTMSN=0
2024-10-15T18:38:14.175Z,1729017494.175 [NAL9602](INFO): No messages in MT queue
2024-10-15T18:38:14.987Z,1729017494.987 [Default:CheckIn:Read_Iridium] Stopped
2024-10-15T18:38:14.988Z,1729017494.988 [Default:CheckIn:C.Wait] Running Loop=1
2024-10-15T18:38:14.988Z,1729017494.988 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-10-15T18:38:44.935Z,1729017524.935 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-15T18:41:48.555Z,1729017708.555 [ElevatorOffsetCalculator](INFO): New estimator for commanded vars: speed 1.00 m/s, pitch 30.00 deg, mass-position -35.00 mm (1 active estimators).
2024-10-15T18:42:20.123Z,1729017740.123 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-10-15T18:42:51.247Z,1729017771.247 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-10-15T18:43:15.419Z,1729017795.419 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-10-15T18:43:15.419Z,1729017795.419 [Default:CheckIn:C.Wait] Stopped
2024-10-15T18:43:15.419Z,1729017795.419 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-10-15T18:43:15.419Z,1729017795.419 [Default:CheckIn:D] Running Loop=1
2024-10-15T18:43:15.831Z,1729017795.831 [Default:CheckIn:D] Stopped
2024-10-15T18:43:15.831Z,1729017795.831 [Default:CheckIn:E] Running Loop=1
2024-10-15T18:43:16.223Z,1729017796.223 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 222.943311 min
2024-10-15T18:43:16.223Z,1729017796.223 [Default:CheckIn:E] Stopped
2024-10-15T18:43:16.223Z,1729017796.223 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-10-15T18:43:16.223Z,1729017796.223 [Default:CheckIn] Stopped
2024-10-15T18:43:16.223Z,1729017796.223 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-10-15T18:43:16.223Z,1729017796.223 [Default:CheckIn](INFO): Running loop #34
2024-10-15T18:43:16.224Z,1729017796.224 [Default:CheckIn] Running Loop=34
2024-10-15T18:43:16.224Z,1729017796.224 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-10-15T18:43:16.224Z,1729017796.224 [Default:CheckIn:Read_GPS] Running Loop=1
2024-10-15T18:43:18.242Z,1729017798.242 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184317.00,A,3647.45372,N,12150.13286,W,0.680,247.71,151024,,,D*73
2024-10-15T18:43:18.244Z,1729017798.244 [NAL9602](INFO): GPS fix at 20241015T184317: (36.790895, -121.835548)
2024-10-15T18:43:18.255Z,1729017798.255 [Default:CheckIn:Read_GPS] Stopped
2024-10-15T18:43:18.255Z,1729017798.255 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-10-15T18:43:22.383Z,1729017802.383 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-10-15T18:43:29.953Z,1729017809.953 [NAL9602](INFO): SBD MO Status=0, MOMSN=57859, MT Status=0, MTMSN=0
2024-10-15T18:43:29.953Z,1729017809.953 [NAL9602](INFO): No messages in MT queue
2024-10-15T18:43:53.507Z,1729017833.507 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-10-15T18:44:00.655Z,1729017840.655 [NAL9602](INFO): Not Powering down - fast GPS
2024-10-15T18:44:24.631Z,1729017864.631 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-10-15T18:44:33.944Z,1729017873.944 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=184.024506
2024-10-15T18:44:41.236Z,1729017881.236 [DataOverHttps](IMPORTANT): SBD MTMSN=20241015T184440
2024-10-15T18:44:41.239Z,1729017881.239 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003217
2024-10-15T18:44:48.335Z,1729017888.335 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20241015T145706/Courier0106.lzma
2024-10-15T18:44:48.337Z,1729017888.337 [DataOverHttps](INFO): Received command: restart logs