2025-02-05T18:48:25.478Z,1738781305.478 [Supervisor](DEBUG): Initializing supervisor.
2025-02-05T18:48:25.483Z,1738781305.483 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-02-05T18:48:25.483Z,1738781305.483 [SyncHandler](INFO): Protected caller Thread ID is 1525
2025-02-05T18:48:25.483Z,1738781305.483 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-02-05T18:48:25.484Z,1738781305.484 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-02-05T18:48:25.485Z,1738781305.485 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1526
2025-02-05T18:48:25.489Z,1738781305.489 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-02-05T18:48:25.506Z,1738781305.506 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-02-05T18:48:25.507Z,1738781305.507 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-02-05T18:48:25.508Z,1738781305.508 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1527
2025-02-05T18:48:25.512Z,1738781305.512 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-02-05T18:48:25.513Z,1738781305.513 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-02-05T18:48:25.513Z,1738781305.513 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1528
2025-02-05T18:48:25.515Z,1738781305.515 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-02-05T18:48:25.516Z,1738781305.516 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-02-05T18:48:25.517Z,1738781305.517 [logger ThreadHandler](INFO): Protected caller Thread ID is 1529
2025-02-05T18:48:25.520Z,1738781305.520 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-02-05T18:48:25.521Z,1738781305.521 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-02-05T18:48:25.525Z,1738781305.525 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-02-05T18:48:25.834Z,1738781305.834 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-02-05T18:48:25.836Z,1738781305.836 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-02-05T18:48:26.060Z,1738781306.060 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-02-05T18:48:26.062Z,1738781306.062 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-02-05T18:48:26.171Z,1738781306.171 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-02-05T18:48:26.173Z,1738781306.173 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-02-05T18:48:26.371Z,1738781306.371 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-02-05T18:48:26.372Z,1738781306.372 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-02-05T18:48:26.876Z,1738781306.876 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-02-05T18:48:26.878Z,1738781306.878 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-02-05T18:48:27.000Z,1738781307.000 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-02-05T18:48:27.001Z,1738781307.001 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-02-05T18:48:27.296Z,1738781307.296 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-02-05T18:48:27.297Z,1738781307.297 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-02-05T18:48:27.523Z,1738781307.523 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-02-05T18:48:27.524Z,1738781307.524 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-02-05T18:48:28.084Z,1738781308.084 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-02-05T18:48:28.085Z,1738781308.085 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-02-05T18:48:28.570Z,1738781308.570 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-02-05T18:48:32.327Z,1738781312.327 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-02-05T18:48:32.422Z,1738781312.422 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-02-05T18:48:33.617Z,1738781313.617 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-02-05T18:48:33.618Z,1738781313.618 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-02-05T18:48:34.308Z,1738781314.308 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-02-05T18:48:34.309Z,1738781314.309 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-02-05T18:48:38.666Z,1738781318.666 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-02-05T18:48:38.666Z,1738781318.666 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-02-05T18:48:39.039Z,1738781319.039 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-02-05T18:48:39.042Z,1738781319.042 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-02-05T18:48:39.142Z,1738781319.142 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-aku/
2025-02-05T18:48:39.145Z,1738781319.145 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/secure.cfg
2025-02-05T18:48:39.245Z,1738781319.245 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/logger.cfg
2025-02-05T18:48:39.364Z,1738781319.364 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/vehicle.cfg
2025-02-05T18:48:42.154Z,1738781322.154 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/BIT.cfg
2025-02-05T18:48:42.255Z,1738781322.255 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Simulator.cfg
2025-02-05T18:48:42.356Z,1738781322.356 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Servo.cfg
2025-02-05T18:48:42.478Z,1738781322.478 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Science.cfg
2025-02-05T18:48:42.639Z,1738781322.639 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Sensor.cfg
2025-02-05T18:48:42.827Z,1738781322.827 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Control.cfg
2025-02-05T18:48:42.937Z,1738781322.937 [Supervisor](INFO): Opening Config file at: Config/lrauv-aku/Battery.cfg
2025-02-05T18:48:43.216Z,1738781323.216 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-02-05T18:48:43.216Z,1738781323.216 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-02-05T18:48:43.229Z,1738781323.229 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-02-05T18:48:43.632Z,1738781323.632 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-02-05T18:48:43.633Z,1738781323.633 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-02-05T18:48:44.293Z,1738781324.293 [AHRS_M2] Loaded
2025-02-05T18:48:44.293Z,1738781324.293 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-02-05T18:48:44.550Z,1738781324.550 [BackseatComponent] Loaded
2025-02-05T18:48:44.551Z,1738781324.551 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-02-05T18:48:44.573Z,1738781324.573 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408C14E0
2025-02-05T18:48:44.574Z,1738781324.574 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1608
2025-02-05T18:48:44.576Z,1738781324.576 [LcmUniversalReporter] Loaded
2025-02-05T18:48:44.577Z,1738781324.577 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-02-05T18:48:44.583Z,1738781324.583 [BioacousticsDataBridge] Loaded
2025-02-05T18:48:44.583Z,1738781324.583 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread.
2025-02-05T18:48:45.400Z,1738781325.400 [BPC1] Loaded
2025-02-05T18:48:45.400Z,1738781325.400 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-02-05T18:48:45.485Z,1738781325.485 [DATMMP] Loaded
2025-02-05T18:48:45.485Z,1738781325.485 [ComponentRegistry](DEBUG): Component "DATMMP" handled in its own thread.
2025-02-05T18:48:45.486Z,1738781325.486 [DATMMP ThreadHandler](DEBUG): Created PCaller Thread at 408F24E0
2025-02-05T18:48:45.487Z,1738781325.487 [DATMMP ThreadHandler](INFO): Protected caller Thread ID is 1609
2025-02-05T18:48:45.621Z,1738781325.621 [DataOverHttps] Loaded
2025-02-05T18:48:45.621Z,1738781325.621 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-02-05T18:48:45.622Z,1738781325.622 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409224E0
2025-02-05T18:48:45.622Z,1738781325.622 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1610
2025-02-05T18:48:45.645Z,1738781325.645 [Depth_Keller] Loaded
2025-02-05T18:48:45.645Z,1738781325.645 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-02-05T18:48:45.650Z,1738781325.650 [DropWeight] Loaded
2025-02-05T18:48:45.650Z,1738781325.650 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-02-05T18:48:45.713Z,1738781325.713 [NAL9602] Loaded
2025-02-05T18:48:45.713Z,1738781325.713 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-02-05T18:48:45.742Z,1738781325.742 [Onboard] Loaded
2025-02-05T18:48:45.743Z,1738781325.743 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-02-05T18:48:45.744Z,1738781325.744 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409524E0
2025-02-05T18:48:45.744Z,1738781325.744 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1611
2025-02-05T18:48:45.758Z,1738781325.758 [Power24vConverter] Loaded
2025-02-05T18:48:45.758Z,1738781325.758 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-02-05T18:48:45.774Z,1738781325.774 [Radio_Surface] Loaded
2025-02-05T18:48:45.775Z,1738781325.775 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-02-05T18:48:45.775Z,1738781325.775 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409824E0
2025-02-05T18:48:45.776Z,1738781325.776 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1612
2025-02-05T18:48:45.791Z,1738781325.791 [Sonardyne_Nano] Loaded
2025-02-05T18:48:45.791Z,1738781325.791 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-02-05T18:48:45.792Z,1738781325.792 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-02-05T18:48:45.792Z,1738781325.792 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-02-05T18:48:46.549Z,1738781326.549 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-02-05T18:48:46.550Z,1738781326.550 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-02-05T18:48:46.619Z,1738781326.619 [VerticalControl](DEBUG): Construct VerticalControl.
2025-02-05T18:48:46.677Z,1738781326.677 [VerticalControl] Loaded
2025-02-05T18:48:46.677Z,1738781326.677 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-02-05T18:48:46.680Z,1738781326.680 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-02-05T18:48:46.727Z,1738781326.727 [HorizontalControl] Loaded
2025-02-05T18:48:46.728Z,1738781326.728 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-02-05T18:48:46.730Z,1738781326.730 [SpeedControl](DEBUG): Construct SpeedControl.
2025-02-05T18:48:46.733Z,1738781326.733 [SpeedControl] Loaded
2025-02-05T18:48:46.733Z,1738781326.733 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-02-05T18:48:46.736Z,1738781326.736 [LoopControl](DEBUG): Construct LoopControl.
2025-02-05T18:48:46.737Z,1738781326.737 [LoopControl] Loaded
2025-02-05T18:48:46.737Z,1738781326.737 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-02-05T18:48:46.737Z,1738781326.737 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-02-05T18:48:46.738Z,1738781326.738 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-02-05T18:48:46.754Z,1738781326.754 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-02-05T18:48:46.755Z,1738781326.755 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-02-05T18:48:46.800Z,1738781326.800 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-02-05T18:48:46.801Z,1738781326.801 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-02-05T18:48:46.944Z,1738781326.944 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-02-05T18:48:46.945Z,1738781326.945 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-02-05T18:48:46.959Z,1738781326.959 [NavChart] Loaded
2025-02-05T18:48:46.960Z,1738781326.960 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-02-05T18:48:46.965Z,1738781326.965 [UniversalFixResidualReporter] Loaded
2025-02-05T18:48:46.966Z,1738781326.966 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-02-05T18:48:46.966Z,1738781326.966 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-02-05T18:48:46.967Z,1738781326.967 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-02-05T18:48:47.131Z,1738781327.131 [SBIT](DEBUG): Construct Startup Built In Test.
2025-02-05T18:48:47.141Z,1738781327.141 [SBIT] Loaded
2025-02-05T18:48:47.141Z,1738781327.141 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-02-05T18:48:47.144Z,1738781327.144 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-02-05T18:48:47.158Z,1738781327.158 [IBIT] Loaded
2025-02-05T18:48:47.158Z,1738781327.158 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-02-05T18:48:47.164Z,1738781327.164 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-02-05T18:48:47.270Z,1738781327.270 [CBIT] Loaded
2025-02-05T18:48:47.270Z,1738781327.270 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-02-05T18:48:47.271Z,1738781327.271 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-02-05T18:48:47.271Z,1738781327.271 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-02-05T18:48:47.486Z,1738781327.486 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-02-05T18:48:47.487Z,1738781327.487 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-02-05T18:48:47.662Z,1738781327.662 [BuoyancyServo] Loaded
2025-02-05T18:48:47.662Z,1738781327.662 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-02-05T18:48:47.689Z,1738781327.689 [ElevatorServo] Loaded
2025-02-05T18:48:47.689Z,1738781327.689 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-02-05T18:48:47.710Z,1738781327.710 [MassServo] Loaded
2025-02-05T18:48:47.710Z,1738781327.710 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-02-05T18:48:47.731Z,1738781327.731 [RudderServo] Loaded
2025-02-05T18:48:47.731Z,1738781327.731 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-02-05T18:48:47.749Z,1738781327.749 [ThrusterHE] Loaded
2025-02-05T18:48:47.749Z,1738781327.749 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-02-05T18:48:47.749Z,1738781327.749 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-02-05T18:48:47.750Z,1738781327.750 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-02-05T18:48:47.985Z,1738781327.985 [CTD_Seabird] Loaded
2025-02-05T18:48:47.985Z,1738781327.985 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-02-05T18:48:48.005Z,1738781328.005 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BAA4E0
2025-02-05T18:48:48.005Z,1738781328.005 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1613
2025-02-05T18:48:48.028Z,1738781328.028 [PAR_Licor] Loaded
2025-02-05T18:48:48.028Z,1738781328.028 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-02-05T18:48:48.074Z,1738781328.074 [WetLabsBB2FL] Loaded
2025-02-05T18:48:48.074Z,1738781328.074 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-02-05T18:48:48.075Z,1738781328.075 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BDA4E0
2025-02-05T18:48:48.076Z,1738781328.076 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1614
2025-02-05T18:48:48.076Z,1738781328.076 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-02-05T18:48:48.077Z,1738781328.077 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-02-05T18:48:48.147Z,1738781328.147 [DepthRateCalculator] Loaded
2025-02-05T18:48:48.147Z,1738781328.147 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-02-05T18:48:48.152Z,1738781328.152 [PitchRateCalculator] Loaded
2025-02-05T18:48:48.153Z,1738781328.153 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-02-05T18:48:48.163Z,1738781328.163 [SpeedCalculator] Loaded
2025-02-05T18:48:48.163Z,1738781328.163 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-02-05T18:48:48.167Z,1738781328.167 [YawRateCalculator] Loaded
2025-02-05T18:48:48.168Z,1738781328.168 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-02-05T18:48:48.187Z,1738781328.187 [ElevatorOffsetCalculator] Loaded
2025-02-05T18:48:48.187Z,1738781328.187 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-02-05T18:48:48.188Z,1738781328.188 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-02-05T18:48:48.188Z,1738781328.188 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-02-05T18:48:48.289Z,1738781328.289 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-02-05T18:48:48.295Z,1738781328.295 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-02-05T18:48:48.298Z,1738781328.298 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-02-05T18:48:48.309Z,1738781328.309 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-02-05T18:48:48.310Z,1738781328.310 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C6A4E0
2025-02-05T18:48:48.310Z,1738781328.310 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1615
2025-02-05T18:48:48.315Z,1738781328.315 [Supervisor](INFO): Main Thread ID is 1523
2025-02-05T18:48:48.315Z,1738781328.315 [Supervisor](DEBUG): Running supervisor.
2025-02-05T18:48:48.316Z,1738781328.316 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1616
2025-02-05T18:48:48.316Z,1738781328.316 [CommandExec](INFO): Initializing the command executive.
2025-02-05T18:48:48.318Z,1738781328.318 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1617
2025-02-05T18:48:48.320Z,1738781328.320 [controlThread ThreadHandler](INFO): Handler Thread ID is 1618
2025-02-05T18:48:48.320Z,1738781328.320 [controlThread](DEBUG): Initializing ControlThread
2025-02-05T18:48:48.326Z,1738781328.326 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-02-05T18:48:48.327Z,1738781328.327 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-02-05T18:48:48.328Z,1738781328.328 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-02-05T18:48:48.329Z,1738781328.329 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-02-05T18:48:48.329Z,1738781328.329 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-02-05T18:48:48.330Z,1738781328.330 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-02-05T18:48:48.330Z,1738781328.330 [SBIT](INFO): Initialize SBIT Component.
2025-02-05T18:48:48.331Z,1738781328.331 [SBIT](IMPORTANT): git: 2025-01-21
2025-02-05T18:48:48.331Z,1738781328.331 [SBIT](INFO): git hash: 6b2695e6425a6d448ed529835007678bdc61cede
2025-02-05T18:48:48.331Z,1738781328.331 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-02-05T18:48:48.332Z,1738781328.332 [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 Dec 4 01:17:04 UTC 2024
2025-02-05T18:48:48.334Z,1738781328.334 [SBIT](INFO): Beginning SBIT in 46.000000 seconds.
2025-02-05T18:48:48.334Z,1738781328.334 [IBIT](INFO): Initialize IBIT Component.
2025-02-05T18:48:48.335Z,1738781328.335 [CBIT](DEBUG): Initialize CBIT Component.
2025-02-05T18:48:48.336Z,1738781328.336 [logger ThreadHandler](INFO): Handler Thread ID is 1619
2025-02-05T18:48:48.348Z,1738781328.348 [CBIT](DEBUG): Initialized mux pins.
2025-02-05T18:48:48.348Z,1738781328.348 [CBIT](DEBUG): Initializing the watchdog timer.
2025-02-05T18:48:48.358Z,1738781328.358 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1620
2025-02-05T18:48:48.368Z,1738781328.368 [DATMMP ThreadHandler](INFO): Handler Thread ID is 1621
2025-02-05T18:48:48.369Z,1738781328.369 [DATMMP](INFO): Start
2025-02-05T18:48:48.369Z,1738781328.369 [DATMMP](INFO): Powering up
2025-02-05T18:48:48.369Z,1738781328.369 [DATMMP](DEBUG): Initializing DATMMP.
2025-02-05T18:48:48.372Z,1738781328.372 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-02-05T18:48:48.373Z,1738781328.373 [CBIT](DEBUG): Initializing heartbeat.
2025-02-05T18:48:48.374Z,1738781328.374 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1623
2025-02-05T18:48:48.376Z,1738781328.376 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-02-05T18:48:48.385Z,1738781328.385 [Onboard ThreadHandler](INFO): Handler Thread ID is 1624
2025-02-05T18:48:48.410Z,1738781328.410 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1625
2025-02-05T18:48:48.421Z,1738781328.421 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1626
2025-02-05T18:48:48.421Z,1738781328.421 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-02-05T18:48:48.425Z,1738781328.425 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1628
2025-02-05T18:48:48.428Z,1738781328.428 [WetLabsBB2FL](INFO): Powering up
2025-02-05T18:48:48.430Z,1738781328.430 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1630
2025-02-05T18:48:48.441Z,1738781328.441 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-02-05T18:48:48.441Z,1738781328.441 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-02-05T18:48:48.441Z,1738781328.441 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-02-05T18:48:48.441Z,1738781328.441 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-02-05T18:48:48.441Z,1738781328.441 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-02-05T18:48:48.441Z,1738781328.441 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-02-05T18:48:48.442Z,1738781328.442 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-02-05T18:48:48.442Z,1738781328.442 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-02-05T18:48:48.444Z,1738781328.444 [CBIT](DEBUG): Deactivating GF circuits.
2025-02-05T18:48:48.444Z,1738781328.444 [CBIT](DEBUG): Deactivating emergency mode.
2025-02-05T18:48:48.484Z,1738781328.484 [CBIT](DEBUG): Backplane powered.
2025-02-05T18:48:48.488Z,1738781328.488 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-02-05T18:48:48.489Z,1738781328.489 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-02-05T18:48:48.489Z,1738781328.489 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-02-05T18:48:48.489Z,1738781328.489 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-02-05T18:48:48.490Z,1738781328.490 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-02-05T18:48:48.491Z,1738781328.491 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-02-05T18:48:48.492Z,1738781328.492 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-02-05T18:48:48.492Z,1738781328.492 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-02-05T18:48:48.505Z,1738781328.505 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-02-05T18:48:48.560Z,1738781328.560 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2025-02-05T18:48:48.588Z,1738781328.588 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-02-05T18:48:48.608Z,1738781328.608 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Startup {
behavior Guidance:GoToSurface {
run in progression
}
aggregate StartupSatComms {
run in sequence
readDatum {
timeout duration=P1M
Universal:latitude_fix
}
readDatum {
timeout duration=P1M
Universal:platform_communications
}
}
}
2025-02-05T18:48:48.609Z,1738781328.609 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-02-05T18:48:48.609Z,1738781328.609 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-02-05T18:48:48.613Z,1738781328.613 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-02-05T18:48:48.620Z,1738781328.620 [DATMMP](INFO): Starting
2025-02-05T18:48:48.816Z,1738781328.816 [Radio_Surface](INFO): Powering up
2025-02-05T18:48:48.872Z,1738781328.872 [DATMMP](INFO): Starting
2025-02-05T18:48:48.912Z,1738781328.912 [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
2025-02-05T18:48:48.918Z,1738781328.918 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-02-05T18:48:48.919Z,1738781328.919 [Default:A.Wait](DEBUG): Construct Wait.
2025-02-05T18:48:48.938Z,1738781328.938 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-02-05T18:48:48.986Z,1738781328.986 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-02-05T18:48:49.001Z,1738781329.001 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-02-05T18:48:49.007Z,1738781329.007 [Default:E.Execute](DEBUG): Construct Execute.
2025-02-05T18:48:49.018Z,1738781329.018 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Default {
arguments {
ElapsedSinceDefaultStarted = 0 minute
}
behavior Guidance:Wait {
"""
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
"""
run in sequence
set duration = 13 second
}
behavior Guidance:GoToSurface {
run in progression
}
aggregate CheckIn {
run in sequence repeat=288
readDatum id="Read_GPS" {
timeout duration=P5M
Universal:time_fix
}
readDatum id="Read_Iridium" {
timeout duration=P4H {
behavior Guidance:Execute {
run in sequence
set command = "Burn on"
}
syslog critical "Dropped weight due to communications timeout."
}
Universal:platform_communications
}
behavior Guidance:Wait {
run in sequence
set duration = 5 minute
}
assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )
syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
}
syslog important "Restarting logs and Default mission."
behavior Guidance:Execute {
run in sequence
set command = "restart logs"
}
}
2025-02-05T18:48:49.028Z,1738781329.028 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,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,LcmUniversalReporter,Reporter,LogSplitter,
2025-02-05T18:48:49.038Z,1738781329.038 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-02-05T18:48:49.124Z,1738781329.124 [DATMMP](INFO): Starting
2025-02-05T18:48:49.126Z,1738781329.126 [Power24vConverter](INFO): Powering up.
2025-02-05T18:48:49.126Z,1738781329.126 [Sonardyne_Nano](INFO): Initializing.
2025-02-05T18:48:49.194Z,1738781329.194 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-02-05T18:48:49.200Z,1738781329.200 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-02-05T18:48:49.201Z,1738781329.201 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-02-05T18:48:49.208Z,1738781329.208 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-02-05T18:48:49.209Z,1738781329.209 [MassServo](DEBUG): Initializing EZServoServo.
2025-02-05T18:48:49.216Z,1738781329.216 [MassServo](DEBUG): Initializing MassServo.
2025-02-05T18:48:49.217Z,1738781329.217 [RudderServo](DEBUG): Initializing EZServoServo.
2025-02-05T18:48:49.224Z,1738781329.224 [RudderServo](DEBUG): Initializing RudderServo.
2025-02-05T18:48:49.225Z,1738781329.225 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-02-05T18:48:49.232Z,1738781329.232 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-02-05T18:48:49.377Z,1738781329.377 [DATMMP](INFO): Starting
2025-02-05T18:48:49.481Z,1738781329.481 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2025-02-05T18:48:49.481Z,1738781329.481 [DropWeight] Hardware Fault, FailCount= 1
2025-02-05T18:48:49.481Z,1738781329.481 [DropWeight](ERROR): Hardware Fault
2025-02-05T18:48:49.532Z,1738781329.532 [CommandExec](FAULT): Scheduling is paused
2025-02-05T18:48:49.533Z,1738781329.533 [CBIT](INFO): Critical error at 20250205T184849
2025-02-05T18:48:49.533Z,1738781329.533 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2025-02-05T18:48:49.535Z,1738781329.535 [CBIT](ERROR): Hardware Fault in component: DropWeight
2025-02-05T18:48:49.544Z,1738781329.544 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2025-02-05T18:48:49.628Z,1738781329.628 [DATMMP](INFO): Starting
2025-02-05T18:48:49.873Z,1738781329.873 [CBIT](INFO): Critical error at 20250205T184849
2025-02-05T18:48:49.880Z,1738781329.880 [DATMMP](INFO): Starting
2025-02-05T18:48:50.132Z,1738781330.132 [DATMMP](INFO): Starting
2025-02-05T18:48:50.384Z,1738781330.384 [DATMMP](INFO): Starting
2025-02-05T18:48:50.560Z,1738781330.560 [WetLabsBB2FL](INFO): Powering down
2025-02-05T18:48:50.636Z,1738781330.636 [DATMMP](INFO): Starting
2025-02-05T18:48:50.888Z,1738781330.888 [DATMMP](INFO): Starting
2025-02-05T18:48:51.058Z,1738781331.058 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-02-05T18:48:51.141Z,1738781331.141 [DATMMP](INFO): Starting
2025-02-05T18:48:51.392Z,1738781331.392 [DATMMP](INFO): Starting
2025-02-05T18:48:51.644Z,1738781331.644 [DATMMP](INFO): Starting
2025-02-05T18:48:51.896Z,1738781331.896 [DATMMP](INFO): Starting
2025-02-05T18:48:52.148Z,1738781332.148 [DATMMP](INFO): Starting
2025-02-05T18:48:52.344Z,1738781332.344 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2409
2025-02-05T18:48:52.400Z,1738781332.400 [DATMMP](INFO): Starting
2025-02-05T18:48:52.652Z,1738781332.652 [DATMMP](INFO): Starting
2025-02-05T18:48:52.904Z,1738781332.904 [DATMMP](INFO): Starting
2025-02-05T18:48:53.156Z,1738781333.156 [DATMMP](INFO): Starting
2025-02-05T18:48:53.408Z,1738781333.408 [DATMMP](INFO): Starting
2025-02-05T18:48:53.660Z,1738781333.660 [DATMMP](INFO): Starting
2025-02-05T18:48:53.912Z,1738781333.912 [DATMMP](INFO): Starting
2025-02-05T18:48:54.164Z,1738781334.164 [DATMMP](INFO): Starting
2025-02-05T18:48:54.416Z,1738781334.416 [DATMMP](INFO): Starting
2025-02-05T18:48:54.668Z,1738781334.668 [DATMMP](INFO): Starting
2025-02-05T18:48:54.920Z,1738781334.920 [DATMMP](INFO): Starting
2025-02-05T18:48:54.941Z,1738781334.941 [ThrusterHE](ERROR): Zero Speed Commanded.
2025-02-05T18:48:55.172Z,1738781335.172 [DATMMP](INFO): Starting
2025-02-05T18:48:55.424Z,1738781335.424 [DATMMP](INFO): Starting
2025-02-05T18:48:55.452Z,1738781335.452 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2025-02-05T18:48:55.676Z,1738781335.676 [DATMMP](INFO): Starting
2025-02-05T18:48:55.928Z,1738781335.928 [DATMMP](INFO): Starting
2025-02-05T18:48:56.180Z,1738781336.180 [DATMMP](INFO): Starting
2025-02-05T18:48:56.432Z,1738781336.432 [DATMMP](INFO): Starting
2025-02-05T18:48:56.684Z,1738781336.684 [DATMMP](INFO): Starting
2025-02-05T18:48:56.936Z,1738781336.936 [DATMMP](INFO): Starting
2025-02-05T18:48:57.188Z,1738781337.188 [DATMMP](INFO): Starting
2025-02-05T18:48:57.440Z,1738781337.440 [DATMMP](INFO): Starting
2025-02-05T18:48:57.692Z,1738781337.692 [DATMMP](INFO): Starting
2025-02-05T18:48:57.944Z,1738781337.944 [DATMMP](INFO): Starting
2025-02-05T18:48:58.196Z,1738781338.196 [DATMMP](INFO): Starting
2025-02-05T18:48:58.449Z,1738781338.449 [DATMMP](INFO): Starting
2025-02-05T18:48:58.700Z,1738781338.700 [DATMMP](INFO): Starting
2025-02-05T18:48:58.952Z,1738781338.952 [DATMMP](INFO): Starting
2025-02-05T18:48:59.204Z,1738781339.204 [DATMMP](INFO): Starting
2025-02-05T18:48:59.456Z,1738781339.456 [DATMMP](INFO): Starting
2025-02-05T18:48:59.708Z,1738781339.708 [DATMMP](INFO): Starting
2025-02-05T18:48:59.960Z,1738781339.960 [DATMMP](INFO): Starting
2025-02-05T18:49:00.212Z,1738781340.212 [DATMMP](INFO): Starting
2025-02-05T18:49:00.213Z,1738781340.213 [DATMMP](INFO): DAT read:
2025-02-05T18:49:00.213Z,1738781340.213 [DATMMP](INFO): DAT read: Teledyne Benthos DAT-900 Series
2025-02-05T18:49:00.464Z,1738781340.464 [DATMMP](INFO): Starting
2025-02-05T18:49:00.716Z,1738781340.716 [DATMMP](INFO): Starting
2025-02-05T18:49:00.968Z,1738781340.968 [DATMMP](INFO): Starting
2025-02-05T18:49:01.220Z,1738781341.220 [DATMMP](INFO): Starting
2025-02-05T18:49:01.472Z,1738781341.472 [DATMMP](INFO): Starting
2025-02-05T18:49:01.724Z,1738781341.724 [DATMMP](INFO): Starting
2025-02-05T18:49:01.725Z,1738781341.725 [DATMMP](INFO): DAT read: MF Frequency Band
2025-02-05T18:49:01.726Z,1738781341.726 [DATMMP](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2025-02-05T18:49:01.727Z,1738781341.727 [DATMMP](INFO): DAT read: Jan 22 2000 22:08:07
2025-02-05T18:49:01.976Z,1738781341.976 [DATMMP](INFO): Starting
2025-02-05T18:49:02.228Z,1738781342.228 [DATMMP](INFO): Starting
2025-02-05T18:49:02.480Z,1738781342.480 [DATMMP](INFO): Starting
2025-02-05T18:49:02.664Z,1738781342.664 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.030751
2025-02-05T18:49:02.732Z,1738781342.732 [DATMMP](INFO): Starting
2025-02-05T18:49:02.733Z,1738781342.733 [DATMMP](INFO): DAT read: Features enabled [Bearing]
2025-02-05T18:49:02.734Z,1738781342.734 [DATMMP](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP
2025-02-05T18:49:02.734Z,1738781342.734 [DATMMP](INFO): commRate: 800
2025-02-05T18:49:02.984Z,1738781342.984 [DATMMP](INFO): Starting
2025-02-05T18:49:03.236Z,1738781343.236 [DATMMP](INFO): Starting
2025-02-05T18:49:03.488Z,1738781343.488 [DATMMP](INFO): Starting
2025-02-05T18:49:03.740Z,1738781343.740 [DATMMP](INFO): Starting
2025-02-05T18:49:03.992Z,1738781343.992 [DATMMP](INFO): Starting
2025-02-05T18:49:04.244Z,1738781344.244 [DATMMP](INFO): Starting
2025-02-05T18:49:04.496Z,1738781344.496 [DATMMP](INFO): Starting
2025-02-05T18:49:04.748Z,1738781344.748 [DATMMP](INFO): Starting
2025-02-05T18:49:04.800Z,1738781344.800 [DATMMP](INFO): entering command mode
2025-02-05T18:49:05.000Z,1738781345.000 [DATMMP](INFO): Starting
2025-02-05T18:49:05.000Z,1738781345.000 [DATMMP](INFO): DAT read:
2025-02-05T18:49:05.001Z,1738781345.001 [DATMMP](INFO): DAT read: user:1>
2025-02-05T18:49:05.001Z,1738781345.001 [DATMMP](DEBUG): read user prompt 1: user:1>
2025-02-05T18:49:05.001Z,1738781345.001 [DATMMP](INFO): entering MMP mode
2025-02-05T18:49:05.252Z,1738781345.252 [DATMMP](INFO): Starting
2025-02-05T18:49:05.253Z,1738781345.253 [DATMMP](INFO): GSXN notify for xid: 0
2025-02-05T18:49:05.255Z,1738781345.255 [DATMMP](INFO): status rx: x1
2025-02-05T18:49:05.255Z,1738781345.255 [DATMMP](INFO): MMP status: 1: started
2025-02-05T18:49:05.256Z,1738781345.256 [DATMMP](INFO): Received message type: status
2025-02-05T18:49:05.256Z,1738781345.256 [DATMMP](INFO): Handled
2025-02-05T18:49:05.504Z,1738781345.504 [DATMMP](INFO): Starting
2025-02-05T18:49:05.505Z,1738781345.505 [DATMMP](INFO): Sent Tx Power Config 8
2025-02-05T18:49:05.506Z,1738781345.506 [DATMMP](INFO): Setting local address: 2
2025-02-05T18:49:05.508Z,1738781345.508 [DATMMP](INFO): Setting time to: 18:49:5 And date to:2/5/2025
2025-02-05T18:49:05.756Z,1738781345.756 [DATMMP](INFO): Starting
2025-02-05T18:49:05.757Z,1738781345.757 [DATMMP](INFO): GSXN notify for xid: 0
2025-02-05T18:49:05.758Z,1738781345.758 [DATMMP](INFO): mdmpower rx: x8 x0 x5 x0
2025-02-05T18:49:05.758Z,1738781345.758 [DATMMP](INFO): Received message type: mdmpower
2025-02-05T18:49:05.758Z,1738781345.758 [DATMMP](INFO): Handled
2025-02-05T18:49:06.008Z,1738781346.008 [DATMMP](INFO): Starting
2025-02-05T18:49:06.008Z,1738781346.008 [DATMMP](INFO): GSXN notify for xid: 10
2025-02-05T18:49:06.009Z,1738781346.009 [DATMMP](INFO): sreg_txpower rx: x8
2025-02-05T18:49:06.009Z,1738781346.009 [DATMMP](INFO): Received message type: sreg_txpower
2025-02-05T18:49:06.009Z,1738781346.009 [DATMMP](INFO): Match for : sreg_txpower
2025-02-05T18:49:06.010Z,1738781346.010 [DATMMP](INFO): ACK
2025-02-05T18:49:06.260Z,1738781346.260 [DATMMP](INFO): Starting
2025-02-05T18:49:06.260Z,1738781346.260 [DATMMP](INFO): GSXN notify for xid: 0
2025-02-05T18:49:06.261Z,1738781346.261 [DATMMP](INFO): mdmlocal rx: x2 x0 x0 x0
2025-02-05T18:49:06.262Z,1738781346.262 [DATMMP](INFO): Received message type: mdmlocal
2025-02-05T18:49:06.262Z,1738781346.262 [DATMMP](INFO): Handled
2025-02-05T18:49:06.512Z,1738781346.512 [DATMMP](INFO): Starting
2025-02-05T18:49:06.513Z,1738781346.513 [DATMMP](INFO): GSXN notify for xid: 11
2025-02-05T18:49:06.513Z,1738781346.513 [DATMMP](INFO): sreg_locaddr rx: x2
2025-02-05T18:49:06.513Z,1738781346.513 [DATMMP](INFO): Received message type: sreg_locaddr
2025-02-05T18:49:06.514Z,1738781346.514 [DATMMP](INFO): Match for : sreg_locaddr
2025-02-05T18:49:06.514Z,1738781346.514 [DATMMP](INFO): ACK
2025-02-05T18:49:06.764Z,1738781346.764 [DATMMP](INFO): Starting
2025-02-05T18:49:06.765Z,1738781346.765 [DATMMP](INFO): GSXN notify for xid: 12
2025-02-05T18:49:06.766Z,1738781346.766 [DATMMP](INFO): timedate rx: x0 x12 x31 x5 x1 x5 x7D x0
2025-02-05T18:49:06.766Z,1738781346.766 [DATMMP](INFO): Received message type: timedate
2025-02-05T18:49:06.766Z,1738781346.766 [DATMMP](INFO): Match for : timedate
2025-02-05T18:49:06.766Z,1738781346.766 [DATMMP](INFO): ACK
2025-02-05T18:49:07.016Z,1738781347.016 [DATMMP](INFO): Starting
2025-02-05T18:49:07.017Z,1738781347.017 [DATMMP](INFO): GSXN notify for xid: 0
2025-02-05T18:49:07.018Z,1738781347.018 [DATMMP](INFO): sys_verb rx: x0 x0 x0 x0
2025-02-05T18:49:07.018Z,1738781347.018 [DATMMP](INFO): Received message type: sys_verb
2025-02-05T18:49:07.018Z,1738781347.018 [DATMMP](INFO): Handled
2025-02-05T18:49:07.268Z,1738781347.268 [DATMMP](INFO): Starting
2025-02-05T18:49:07.269Z,1738781347.269 [DATMMP](INFO): GSXN notify for xid: 13
2025-02-05T18:49:07.269Z,1738781347.269 [DATMMP](INFO): sreg_verbosity rx: x0
2025-02-05T18:49:07.269Z,1738781347.269 [DATMMP](INFO): Received message type: sreg_verbosity
2025-02-05T18:49:07.270Z,1738781347.270 [DATMMP](INFO): Match for : sreg_verbosity
2025-02-05T18:49:07.270Z,1738781347.270 [DATMMP](INFO): ACK
2025-02-05T18:49:07.521Z,1738781347.521 [DATMMP](INFO): Starting
2025-02-05T18:49:07.523Z,1738781347.523 [DATMMP](INFO): GSXN notify for xid: 14
2025-02-05T18:49:07.525Z,1738781347.525 [DATMMP](INFO): version rx: x2 x5
2025-02-05T18:49:07.525Z,1738781347.525 [DATMMP](INFO): DatMMPVersion: 2.5
2025-02-05T18:49:07.525Z,1738781347.525 [DATMMP](INFO): Received message type: version
2025-02-05T18:49:07.525Z,1738781347.525 [DATMMP](INFO): Match for : version
2025-02-05T18:49:07.525Z,1738781347.525 [DATMMP](INFO): ACK
2025-02-05T18:49:07.526Z,1738781347.526 [DATMMP](INFO): in MMP mode: config complete going to runnable
2025-02-05T18:49:16.111Z,1738781356.111 [NAL9602](INFO): Powering up NAL9602
2025-02-05T18:49:22.137Z,1738781362.137 [DATMMP](INFO): GSXN notify for xid: 0
2025-02-05T18:49:22.137Z,1738781362.137 [DATMMP](INFO): timesync rx: x0 x1 x1 x0
2025-02-05T18:49:22.138Z,1738781362.138 [DATMMP](INFO): Received message type: timesync
2025-02-05T18:49:22.138Z,1738781362.138 [DATMMP](INFO): Handled
2025-02-05T18:49:27.017Z,1738781367.017 [NAL9602](INFO): NAL9602 initialized
2025-02-05T18:49:31.985Z,1738781371.985 [DATMMP](INFO): GSXN notify for xid: 0
2025-02-05T18:49:31.986Z,1738781371.986 [DATMMP](INFO): timesync rx: x1 x0 x1 x0
2025-02-05T18:49:31.986Z,1738781371.986 [DATMMP](INFO): Received message type: timesync
2025-02-05T18:49:31.986Z,1738781371.986 [DATMMP](INFO): Handled
2025-02-05T18:49:33.233Z,1738781373.233 [CommandExec](IMPORTANT): got command strobe off
2025-02-05T18:49:33.234Z,1738781373.234 [CommandExec](IMPORTANT): Deactivating strobe
2025-02-05T18:49:35.144Z,1738781375.144 [SBIT](IMPORTANT): Beginning Startup BIT
2025-02-05T18:49:35.148Z,1738781375.148 [CBIT](IMPORTANT): Beginning ground fault scan
2025-02-05T18:49:46.116Z,1738781386.116 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.036782
CHAN A1 (24V): -0.000662
CHAN A2 (12V): 0.000193
CHAN A3 (5V): -0.001495
CHAN B0 (3.3V): -0.001329
CHAN B1 (3.15aV): -0.001529
CHAN B2 (3.15bV): -0.001328
CHAN B3 (GND): -0.002005
OPEN: 0.004970
Full Scale: +/- 1 mA
2025-02-05T18:49:53.872Z,1738781393.872 [CommandExec](IMPORTANT): got command get Sonardyne_Nano.chargePercent
2025-02-05T18:49:53.873Z,1738781393.873 [CommandExec](IMPORTANT): Sonardyne_Nano.chargePercent 58.999996 %
2025-02-05T18:50:28.915Z,1738781428.915 [SBIT](IMPORTANT): SBIT PASSED
2025-02-05T18:50:28.915Z,1738781428.915 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-02-05T18:50:28.916Z,1738781428.916 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;
2025-02-05T18:50:28.916Z,1738781428.916 [SBIT](IMPORTANT): DATMMP.loadAtStartup=1 bool;
2025-02-05T18:50:28.916Z,1738781428.916 [SBIT](IMPORTANT): DATMMP.simulateHardware=0 bool;
2025-02-05T18:50:28.916Z,1738781428.916 [SBIT](IMPORTANT): Express linearApproximation acoustic_contact_range 100.000000 meter;
2025-02-05T18:50:28.916Z,1738781428.916 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 10.000000 meter;
2025-02-05T18:50:28.916Z,1738781428.916 [SBIT](IMPORTANT): Express none platform_roll_angle;
2025-02-05T18:50:28.917Z,1738781428.917 [SBIT](IMPORTANT): SpeedControl.rollOptimum=0.925 meter_per_second;
2025-02-05T18:50:28.917Z,1738781428.917 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=424.293083 cubic_centimeter;
2025-02-05T18:50:28.917Z,1738781428.917 [SBIT](IMPORTANT): VerticalControl.massDefault=6.708742 millimeter;
2025-02-05T18:50:29.329Z,1738781429.329 [MissionManager](IMPORTANT): Started mission Startup
2025-02-05T18:50:29.330Z,1738781429.330 [Startup] Running Loop=1
2025-02-05T18:50:29.330Z,1738781429.330 [Startup](DEBUG): Aggregate::initialize Startup
2025-02-05T18:50:29.330Z,1738781429.330 [Startup:A.GoToSurface] Running Loop=1
2025-02-05T18:50:29.330Z,1738781429.330 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-05T18:50:29.330Z,1738781429.330 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-02-05T18:50:29.331Z,1738781429.331 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-02-05T18:50:29.331Z,1738781429.331 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-02-05T18:50:29.331Z,1738781429.331 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-02-05T18:50:29.332Z,1738781429.332 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-05T18:50:29.332Z,1738781429.332 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-05T18:50:29.334Z,1738781429.334 [Startup:StartupSatComms] Running Loop=1
2025-02-05T18:50:29.335Z,1738781429.335 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-02-05T18:50:29.335Z,1738781429.335 [Startup:StartupSatComms:A] Running Loop=1
2025-02-05T18:50:29.731Z,1738781429.731 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-02-05T18:50:41.277Z,1738781441.277 [CommandExec](IMPORTANT): got command burn on
2025-02-05T18:50:41.277Z,1738781441.277 [CommandExec](IMPORTANT): Activating dropweight wire
2025-02-05T18:51:07.413Z,1738781467.413 [CommandExec](IMPORTANT): got command burn off
2025-02-05T18:51:07.413Z,1738781467.413 [CommandExec](IMPORTANT): Deactivating dropweight wire
2025-02-05T18:51:29.515Z,1738781489.515 [Startup:StartupSatComms:A](INFO): Timed out from 2025-02-05T18:50:29.3Z
2025-02-05T18:51:29.515Z,1738781489.515 [Startup:StartupSatComms:A] Stopped
2025-02-05T18:51:29.515Z,1738781489.515 [Startup:StartupSatComms:B] Running Loop=1
2025-02-05T18:51:29.918Z,1738781489.918 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-02-05T18:51:36.808Z,1738781496.808 [DataOverHttps](INFO): Sending 243 bytes from file Logs/20250205T184825/Courier0000.lzma
2025-02-05T18:51:37.810Z,1738781497.810 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Courier0000.lzma.bak
2025-02-05T18:51:37.810Z,1738781497.810 [DataOverHttps](INFO): SBD MOMSN=23957256
2025-02-05T18:51:48.509Z,1738781508.509 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-02-05T18:51:48.509Z,1738781508.509 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T18:51:48.520Z,1738781508.520 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T18:51:48.931Z,1738781508.931 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T18:51:48.931Z,1738781508.931 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-02-05T18:51:53.334Z,1738781513.334 [DataOverHttps](INFO): Sending 1041 bytes from file Logs/20250205T184825/Express0001.lzma
2025-02-05T18:51:54.334Z,1738781514.334 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Express0001.lzma.bak
2025-02-05T18:51:54.334Z,1738781514.334 [DataOverHttps](INFO): SBD MOMSN=23957261
2025-02-05T18:51:55.379Z,1738781515.379 [Startup:StartupSatComms:B] Stopped
2025-02-05T18:51:55.380Z,1738781515.380 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-02-05T18:51:55.380Z,1738781515.380 [Startup:StartupSatComms] Stopped
2025-02-05T18:51:55.380Z,1738781515.380 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-02-05T18:51:55.381Z,1738781515.381 [Startup](INFO): Completed Startup
2025-02-05T18:51:55.381Z,1738781515.381 [MissionManager](INFO): Startup is completed.
2025-02-05T18:51:55.381Z,1738781515.381 [MissionManager](INFO): Uninitializing Mission Startup
2025-02-05T18:51:55.381Z,1738781515.381 [Startup] Stopped
2025-02-05T18:51:55.381Z,1738781515.381 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-02-05T18:51:55.381Z,1738781515.381 [Startup:A.GoToSurface] Stopped
2025-02-05T18:51:55.382Z,1738781515.382 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-05T18:51:55.778Z,1738781515.778 [MissionManager](IMPORTANT): Started mission Default
2025-02-05T18:51:55.778Z,1738781515.778 [Default] Running Loop=1
2025-02-05T18:51:55.778Z,1738781515.778 [Default](DEBUG): Aggregate::initialize Default
2025-02-05T18:51:55.779Z,1738781515.779 [Default:B.GoToSurface] Running Loop=1
2025-02-05T18:51:55.779Z,1738781515.779 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-05T18:51:55.779Z,1738781515.779 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-02-05T18:51:55.779Z,1738781515.779 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-02-05T18:51:55.779Z,1738781515.779 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-02-05T18:51:55.808Z,1738781515.808 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-02-05T18:51:55.808Z,1738781515.808 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-05T18:51:55.809Z,1738781515.809 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-05T18:51:55.809Z,1738781515.809 [Default:A.Wait] Running Loop=1
2025-02-05T18:51:55.809Z,1738781515.809 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-02-05T18:52:09.103Z,1738781529.103 [Default:A.Wait](INFO): Done Waiting.
2025-02-05T18:52:09.104Z,1738781529.104 [Default:A.Wait] Stopped
2025-02-05T18:52:09.104Z,1738781529.104 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-02-05T18:52:09.510Z,1738781529.510 [Default:CheckIn] Running Loop=1
2025-02-05T18:52:09.510Z,1738781529.510 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-05T18:52:09.510Z,1738781529.510 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-05T18:52:09.919Z,1738781529.919 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-02-05T18:54:30.493Z,1738781670.493 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-02-05T18:54:49.520Z,1738781689.520 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2025-02-05T18:54:49.520Z,1738781689.520 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T18:54:49.531Z,1738781689.531 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T18:54:49.903Z,1738781689.903 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T18:54:49.903Z,1738781689.903 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2025-02-05T18:57:09.724Z,1738781829.724 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-02-05T18:52:09.5Z
2025-02-05T18:57:09.724Z,1738781829.724 [Default:CheckIn:Read_GPS] Stopped
2025-02-05T18:57:09.724Z,1738781829.724 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-05T18:57:10.103Z,1738781830.103 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-02-05T18:57:16.471Z,1738781836.471 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250205T184825/Courier0004.lzma
2025-02-05T18:57:17.474Z,1738781837.474 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Courier0004.lzma.bak
2025-02-05T18:57:17.474Z,1738781837.474 [DataOverHttps](INFO): SBD MOMSN=23957292
2025-02-05T18:57:33.093Z,1738781853.093 [DataOverHttps](INFO): Sending 186 bytes from file Logs/20250205T184825/Express0005.lzma
2025-02-05T18:57:34.090Z,1738781854.090 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Express0005.lzma.bak
2025-02-05T18:57:34.090Z,1738781854.090 [DataOverHttps](INFO): SBD MOMSN=23957294
2025-02-05T18:57:35.147Z,1738781855.147 [Default:CheckIn:Read_Iridium] Stopped
2025-02-05T18:57:35.147Z,1738781855.147 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-05T18:57:35.147Z,1738781855.147 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-05T18:57:50.499Z,1738781870.499 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2025-02-05T18:57:50.499Z,1738781870.499 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T18:57:50.510Z,1738781870.510 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T18:57:50.906Z,1738781870.906 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T18:57:50.906Z,1738781870.906 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2025-02-05T18:59:27.839Z,1738781967.839 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-02-05T18:59:27.843Z,1738781967.843 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-02-05T18:59:27.843Z,1738781967.843 [NAL9602] Data Fault, FailCount= 1
2025-02-05T18:59:27.843Z,1738781967.843 [NAL9602](ERROR): Data Fault
2025-02-05T18:59:27.882Z,1738781967.882 [CBIT](ERROR): Data Fault in component: NAL9602
2025-02-05T18:59:28.258Z,1738781968.258 [NAL9602](INFO): Powering down
2025-02-05T18:59:29.089Z,1738781969.089 [CBIT](INFO): Clearing failed state for component NAL9602
2025-02-05T18:59:29.090Z,1738781969.090 [NAL9602] No Fault, FailCount= 1
2025-02-05T18:59:58.550Z,1738781998.550 [NAL9602](INFO): Powering up NAL9602
2025-02-05T19:00:09.449Z,1738782009.449 [NAL9602](INFO): NAL9602 initialized
2025-02-05T19:00:51.498Z,1738782051.498 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2025-02-05T19:00:51.498Z,1738782051.498 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T19:00:51.558Z,1738782051.558 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T19:00:51.955Z,1738782051.955 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T19:00:51.955Z,1738782051.955 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2025-02-05T19:02:35.709Z,1738782155.709 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-05T19:02:35.709Z,1738782155.709 [Default:CheckIn:C.Wait] Stopped
2025-02-05T19:02:35.710Z,1738782155.710 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-05T19:02:35.710Z,1738782155.710 [Default:CheckIn:D] Running Loop=1
2025-02-05T19:02:36.134Z,1738782156.134 [Default:CheckIn:D] Stopped
2025-02-05T19:02:36.134Z,1738782156.134 [Default:CheckIn:E] Running Loop=1
2025-02-05T19:02:36.539Z,1738782156.539 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.672600 min
2025-02-05T19:02:36.539Z,1738782156.539 [Default:CheckIn:E] Stopped
2025-02-05T19:02:36.539Z,1738782156.539 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-05T19:02:36.539Z,1738782156.539 [Default:CheckIn] Stopped
2025-02-05T19:02:36.539Z,1738782156.539 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-05T19:02:36.539Z,1738782156.539 [Default:CheckIn](INFO): Running loop #2
2025-02-05T19:02:36.539Z,1738782156.539 [Default:CheckIn] Running Loop=2
2025-02-05T19:02:36.539Z,1738782156.539 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-05T19:02:36.539Z,1738782156.539 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-05T19:03:49.675Z,1738782229.675 [CBIT](INFO): Clearing failed state for component DropWeight
2025-02-05T19:03:49.675Z,1738782229.675 [DropWeight] No Fault, FailCount= 1
2025-02-05T19:03:52.469Z,1738782232.469 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2025-02-05T19:03:52.470Z,1738782232.470 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T19:03:52.480Z,1738782232.480 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T19:03:52.903Z,1738782232.903 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T19:03:52.903Z,1738782232.903 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2025-02-05T19:06:53.488Z,1738782413.488 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2025-02-05T19:06:53.488Z,1738782413.488 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T19:06:53.500Z,1738782413.500 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T19:06:53.895Z,1738782413.895 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T19:06:53.895Z,1738782413.895 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2025-02-05T19:07:36.718Z,1738782456.718 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-02-05T19:02:36.5Z
2025-02-05T19:07:36.718Z,1738782456.718 [Default:CheckIn:Read_GPS] Stopped
2025-02-05T19:07:36.718Z,1738782456.718 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-05T19:07:44.279Z,1738782464.279 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250205T184825/Courier0007.lzma
2025-02-05T19:07:45.282Z,1738782465.282 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Courier0007.lzma.bak
2025-02-05T19:07:45.282Z,1738782465.282 [DataOverHttps](INFO): SBD MOMSN=23957302
2025-02-05T19:08:01.032Z,1738782481.032 [DataOverHttps](INFO): Sending 182 bytes from file Logs/20250205T184825/Express0008.lzma
2025-02-05T19:08:03.775Z,1738782483.775 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Express0008.lzma.bak
2025-02-05T19:08:03.775Z,1738782483.775 [DataOverHttps](INFO): SBD MOMSN=23957305
2025-02-05T19:08:03.846Z,1738782483.846 [Default:CheckIn:Read_Iridium] Stopped
2025-02-05T19:08:03.846Z,1738782483.846 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-05T19:08:03.846Z,1738782483.846 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-05T19:09:54.468Z,1738782594.468 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2025-02-05T19:09:54.468Z,1738782594.468 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T19:09:54.508Z,1738782594.508 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T19:09:54.883Z,1738782594.883 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T19:09:54.883Z,1738782594.883 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2025-02-05T19:10:11.818Z,1738782611.818 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-02-05T19:10:11.818Z,1738782611.818 [NAL9602] Data Fault, FailCount= 2
2025-02-05T19:10:11.818Z,1738782611.818 [NAL9602](ERROR): Data Fault
2025-02-05T19:10:11.871Z,1738782611.871 [CBIT](ERROR): Data Fault in component: NAL9602
2025-02-05T19:10:12.221Z,1738782612.221 [NAL9602](INFO): Powering down
2025-02-05T19:10:13.059Z,1738782613.059 [CBIT](INFO): Clearing failed state for component NAL9602
2025-02-05T19:10:13.059Z,1738782613.059 [NAL9602] No Fault, FailCount= 2
2025-02-05T19:10:42.546Z,1738782642.546 [NAL9602](INFO): Powering up NAL9602
2025-02-05T19:10:53.430Z,1738782653.430 [NAL9602](INFO): NAL9602 initialized
2025-02-05T19:12:55.447Z,1738782775.447 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2025-02-05T19:12:55.447Z,1738782775.447 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T19:12:55.458Z,1738782775.458 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T19:12:55.900Z,1738782775.900 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T19:12:55.900Z,1738782775.900 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2025-02-05T19:13:04.362Z,1738782784.362 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-05T19:13:04.362Z,1738782784.362 [Default:CheckIn:C.Wait] Stopped
2025-02-05T19:13:04.362Z,1738782784.362 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-05T19:13:04.362Z,1738782784.362 [Default:CheckIn:D] Running Loop=1
2025-02-05T19:13:04.763Z,1738782784.763 [Default:CheckIn:D] Stopped
2025-02-05T19:13:04.763Z,1738782784.763 [Default:CheckIn:E] Running Loop=1
2025-02-05T19:13:05.171Z,1738782785.171 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.149736 min
2025-02-05T19:13:05.171Z,1738782785.171 [Default:CheckIn:E] Stopped
2025-02-05T19:13:05.171Z,1738782785.171 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-05T19:13:05.171Z,1738782785.171 [Default:CheckIn] Stopped
2025-02-05T19:13:05.171Z,1738782785.171 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-05T19:13:05.171Z,1738782785.171 [Default:CheckIn](INFO): Running loop #3
2025-02-05T19:13:05.171Z,1738782785.171 [Default:CheckIn] Running Loop=3
2025-02-05T19:13:05.171Z,1738782785.171 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-05T19:13:05.171Z,1738782785.171 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-05T19:15:56.461Z,1738782956.461 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2025-02-05T19:15:56.461Z,1738782956.461 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T19:15:56.472Z,1738782956.472 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T19:15:56.901Z,1738782956.901 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T19:15:56.901Z,1738782956.901 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2025-02-05T19:18:05.363Z,1738783085.363 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-02-05T19:13:05.2Z
2025-02-05T19:18:05.363Z,1738783085.363 [Default:CheckIn:Read_GPS] Stopped
2025-02-05T19:18:05.363Z,1738783085.363 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-05T19:18:12.591Z,1738783092.591 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250205T184825/Courier0010.lzma
2025-02-05T19:18:13.594Z,1738783093.594 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Courier0010.lzma.bak
2025-02-05T19:18:13.594Z,1738783093.594 [DataOverHttps](INFO): SBD MOMSN=23957316
2025-02-05T19:18:29.320Z,1738783109.320 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250205T184825/Express0011.lzma
2025-02-05T19:18:30.322Z,1738783110.322 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Express0011.lzma.bak
2025-02-05T19:18:30.322Z,1738783110.322 [DataOverHttps](INFO): SBD MOMSN=23957318
2025-02-05T19:18:31.625Z,1738783111.625 [Default:CheckIn:Read_Iridium] Stopped
2025-02-05T19:18:31.625Z,1738783111.625 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-05T19:18:31.625Z,1738783111.625 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-05T19:18:57.469Z,1738783137.469 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2025-02-05T19:18:57.469Z,1738783137.469 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T19:18:57.510Z,1738783137.510 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T19:18:57.887Z,1738783137.887 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T19:18:57.887Z,1738783137.887 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2025-02-05T19:19:49.570Z,1738783189.570 [BPC1](ERROR): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-02-05T19:19:49.570Z,1738783189.570 [BPC1](INFO): Calculating totals. Valid battery stick count: 27. Valid reserve battery stick count: 3.
2025-02-05T19:19:49.573Z,1738783189.573 [BPC1](CRITICAL): Battery Capacity Below Threshold. 24.375000 Ah.
2025-02-05T19:19:49.574Z,1738783189.574 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2025-02-05T19:19:49.653Z,1738783189.653 [CBIT](INFO): Critical error at 20250205T191949
2025-02-05T19:20:55.818Z,1738783255.818 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-02-05T19:20:55.818Z,1738783255.818 [NAL9602] Data Fault, FailCount= 3
2025-02-05T19:20:55.818Z,1738783255.818 [NAL9602](ERROR): Data Fault
2025-02-05T19:20:55.836Z,1738783255.836 [CBIT](ERROR): Data Fault in component: NAL9602
2025-02-05T19:20:56.222Z,1738783256.222 [NAL9602](INFO): Powering down
2025-02-05T19:20:57.042Z,1738783257.042 [CBIT](INFO): Clearing failed state for component NAL9602
2025-02-05T19:20:57.042Z,1738783257.042 [NAL9602] No Fault, FailCount= 3
2025-02-05T19:21:26.518Z,1738783286.518 [NAL9602](INFO): Powering up NAL9602
2025-02-05T19:21:37.426Z,1738783297.426 [NAL9602](INFO): NAL9602 initialized
2025-02-05T19:21:58.444Z,1738783318.444 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2025-02-05T19:21:58.444Z,1738783318.444 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T19:21:58.456Z,1738783318.456 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T19:21:58.880Z,1738783318.880 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T19:21:58.880Z,1738783318.880 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2025-02-05T19:23:32.193Z,1738783412.193 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-05T19:23:32.193Z,1738783412.193 [Default:CheckIn:C.Wait] Stopped
2025-02-05T19:23:32.194Z,1738783412.194 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-05T19:23:32.194Z,1738783412.194 [Default:CheckIn:D] Running Loop=1
2025-02-05T19:23:32.594Z,1738783412.594 [Default:CheckIn:D] Stopped
2025-02-05T19:23:32.594Z,1738783412.594 [Default:CheckIn:E] Running Loop=1
2025-02-05T19:23:33.003Z,1738783413.003 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 31.613586 min
2025-02-05T19:23:33.003Z,1738783413.003 [Default:CheckIn:E] Stopped
2025-02-05T19:23:33.003Z,1738783413.003 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-05T19:23:33.003Z,1738783413.003 [Default:CheckIn] Stopped
2025-02-05T19:23:33.003Z,1738783413.003 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-05T19:23:33.003Z,1738783413.003 [Default:CheckIn](INFO): Running loop #4
2025-02-05T19:23:33.004Z,1738783413.004 [Default:CheckIn] Running Loop=4
2025-02-05T19:23:33.004Z,1738783413.004 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-05T19:23:33.004Z,1738783413.004 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-05T19:24:59.453Z,1738783499.453 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2025-02-05T19:24:59.453Z,1738783499.453 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T19:24:59.492Z,1738783499.492 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T19:24:59.897Z,1738783499.897 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T19:24:59.897Z,1738783499.897 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2025-02-05T19:28:00.430Z,1738783680.430 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2025-02-05T19:28:00.430Z,1738783680.430 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T19:28:00.441Z,1738783680.441 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T19:28:00.822Z,1738783680.822 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T19:28:00.822Z,1738783680.822 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2025-02-05T19:28:33.111Z,1738783713.111 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-02-05T19:23:33.0Z
2025-02-05T19:28:33.111Z,1738783713.111 [Default:CheckIn:Read_GPS] Stopped
2025-02-05T19:28:33.111Z,1738783713.111 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-05T19:28:40.289Z,1738783720.289 [DataOverHttps](INFO): Sending 229 bytes from file Logs/20250205T184825/Courier0013.lzma
2025-02-05T19:28:41.290Z,1738783721.290 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Courier0013.lzma.bak
2025-02-05T19:28:41.290Z,1738783721.290 [DataOverHttps](INFO): SBD MOMSN=23957322
2025-02-05T19:28:57.492Z,1738783737.492 [DataOverHttps](INFO): Sending 160 bytes from file Logs/20250205T184825/Express0014.lzma
2025-02-05T19:28:58.494Z,1738783738.494 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Express0014.lzma.bak
2025-02-05T19:28:58.494Z,1738783738.494 [DataOverHttps](INFO): SBD MOMSN=23957325
2025-02-05T19:28:59.779Z,1738783739.779 [Default:CheckIn:Read_Iridium] Stopped
2025-02-05T19:28:59.779Z,1738783739.779 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-05T19:28:59.780Z,1738783739.780 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-05T19:31:01.433Z,1738783861.433 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2025-02-05T19:31:01.433Z,1738783861.433 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T19:31:01.454Z,1738783861.454 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T19:31:01.881Z,1738783861.881 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T19:31:01.881Z,1738783861.881 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2025-02-05T19:31:39.810Z,1738783899.810 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-02-05T19:31:39.810Z,1738783899.810 [NAL9602] Data Fault, FailCount= 4
2025-02-05T19:31:39.810Z,1738783899.810 [NAL9602](ERROR): Data Fault
2025-02-05T19:31:39.827Z,1738783899.827 [CBIT](ERROR): Data Fault in component: NAL9602
2025-02-05T19:31:40.213Z,1738783900.213 [NAL9602](INFO): Powering down
2025-02-05T19:31:41.044Z,1738783901.044 [CBIT](INFO): Clearing failed state for component NAL9602
2025-02-05T19:31:41.044Z,1738783901.044 [NAL9602] No Fault, FailCount= 4
2025-02-05T19:32:10.515Z,1738783930.515 [NAL9602](INFO): Powering up NAL9602
2025-02-05T19:32:21.426Z,1738783941.426 [NAL9602](INFO): NAL9602 initialized
2025-02-05T19:33:51.165Z,1738784031.165 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2025-02-05T19:33:51.165Z,1738784031.165 [DropWeight] Hardware Fault, FailCount= 1
2025-02-05T19:33:51.165Z,1738784031.165 [DropWeight](ERROR): Hardware Fault
2025-02-05T19:33:51.182Z,1738784031.182 [CBIT](INFO): Critical error at 20250205T193351
2025-02-05T19:33:51.184Z,1738784031.184 [CBIT](ERROR): Hardware Fault in component: DropWeight
2025-02-05T19:33:51.185Z,1738784031.185 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2025-02-05T19:33:51.589Z,1738784031.589 [CBIT](INFO): Critical error at 20250205T193351
2025-02-05T19:34:00.472Z,1738784040.472 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-05T19:34:00.472Z,1738784040.472 [Default:CheckIn:C.Wait] Stopped
2025-02-05T19:34:00.472Z,1738784040.472 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-05T19:34:00.472Z,1738784040.472 [Default:CheckIn:D] Running Loop=1
2025-02-05T19:34:00.890Z,1738784040.890 [Default:CheckIn:D] Stopped
2025-02-05T19:34:00.890Z,1738784040.890 [Default:CheckIn:E] Running Loop=1
2025-02-05T19:34:01.305Z,1738784041.305 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 42.085185 min
2025-02-05T19:34:01.305Z,1738784041.305 [Default:CheckIn:E] Stopped
2025-02-05T19:34:01.305Z,1738784041.305 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-05T19:34:01.305Z,1738784041.305 [Default:CheckIn] Stopped
2025-02-05T19:34:01.306Z,1738784041.306 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-05T19:34:01.306Z,1738784041.306 [Default:CheckIn](INFO): Running loop #5
2025-02-05T19:34:01.306Z,1738784041.306 [Default:CheckIn] Running Loop=5
2025-02-05T19:34:01.306Z,1738784041.306 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-05T19:34:01.306Z,1738784041.306 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-05T19:34:02.484Z,1738784042.484 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2025-02-05T19:34:02.484Z,1738784042.484 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T19:34:02.495Z,1738784042.495 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T19:34:02.898Z,1738784042.898 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T19:34:02.898Z,1738784042.898 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2025-02-05T19:35:47.481Z,1738784147.481 [CommandExec](IMPORTANT): got command burn on
2025-02-05T19:35:47.481Z,1738784147.481 [CommandExec](IMPORTANT): Activating dropweight wire
2025-02-05T19:37:03.560Z,1738784223.560 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2025-02-05T19:37:03.560Z,1738784223.560 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T19:37:03.579Z,1738784223.579 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T19:37:03.973Z,1738784223.973 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T19:37:03.973Z,1738784223.973 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2025-02-05T19:37:04.569Z,1738784224.569 [CommandExec](IMPORTANT): got command burn off
2025-02-05T19:37:04.569Z,1738784224.569 [CommandExec](IMPORTANT): Deactivating dropweight wire
2025-02-05T19:39:01.640Z,1738784341.640 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-02-05T19:34:01.3Z
2025-02-05T19:39:01.640Z,1738784341.640 [Default:CheckIn:Read_GPS] Stopped
2025-02-05T19:39:01.640Z,1738784341.640 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-05T19:39:11.396Z,1738784351.396 [DataOverHttps](INFO): Sending 115 bytes from file Logs/20250205T184825/Courier0016.lzma
2025-02-05T19:39:12.398Z,1738784352.398 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Courier0016.lzma.bak
2025-02-05T19:39:12.398Z,1738784352.398 [DataOverHttps](INFO): SBD MOMSN=23957329
2025-02-05T19:39:28.424Z,1738784368.424 [DataOverHttps](INFO): Sending 217 bytes from file Logs/20250205T184825/Express0017.lzma
2025-02-05T19:39:29.427Z,1738784369.427 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Express0017.lzma.bak
2025-02-05T19:39:29.427Z,1738784369.427 [DataOverHttps](INFO): SBD MOMSN=23957333
2025-02-05T19:39:30.502Z,1738784370.502 [Default:CheckIn:Read_Iridium] Stopped
2025-02-05T19:39:30.503Z,1738784370.503 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-05T19:39:30.503Z,1738784370.503 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-05T19:40:04.432Z,1738784404.432 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2025-02-05T19:40:04.432Z,1738784404.432 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T19:40:04.507Z,1738784404.507 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T19:40:04.846Z,1738784404.846 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T19:40:04.846Z,1738784404.846 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2025-02-05T19:42:23.830Z,1738784543.830 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-02-05T19:42:23.831Z,1738784543.831 [NAL9602] Data Fault, FailCount= 5
2025-02-05T19:42:23.831Z,1738784543.831 [NAL9602](ERROR): Data Fault
2025-02-05T19:42:23.849Z,1738784543.849 [CBIT](ERROR): Data Fault in component: NAL9602
2025-02-05T19:42:23.849Z,1738784543.849 [CBIT](CRITICAL): Data Fault in component: NAL9602
2025-02-05T19:42:24.221Z,1738784544.221 [NAL9602](INFO): Powering down
2025-02-05T19:42:24.265Z,1738784544.265 [CBIT](INFO): Critical error at 20250205T194223
2025-02-05T19:43:05.443Z,1738784585.443 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2025-02-05T19:43:05.443Z,1738784585.443 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T19:43:05.525Z,1738784585.525 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T19:43:05.867Z,1738784585.867 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T19:43:05.867Z,1738784585.867 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2025-02-05T19:44:23.874Z,1738784663.874 [CBIT](INFO): Clearing failed state for component NAL9602
2025-02-05T19:44:23.874Z,1738784663.874 [NAL9602] No Fault, FailCount= 5
2025-02-05T19:44:24.266Z,1738784664.266 [NAL9602](INFO): Powering up NAL9602
2025-02-05T19:44:31.139Z,1738784671.139 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-05T19:44:31.139Z,1738784671.139 [Default:CheckIn:C.Wait] Stopped
2025-02-05T19:44:31.139Z,1738784671.139 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-05T19:44:31.139Z,1738784671.139 [Default:CheckIn:D] Running Loop=1
2025-02-05T19:44:31.549Z,1738784671.549 [Default:CheckIn:D] Stopped
2025-02-05T19:44:31.549Z,1738784671.549 [Default:CheckIn:E] Running Loop=1
2025-02-05T19:44:31.945Z,1738784671.945 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 52.596179 min
2025-02-05T19:44:31.945Z,1738784671.945 [Default:CheckIn:E] Stopped
2025-02-05T19:44:31.945Z,1738784671.945 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-05T19:44:31.946Z,1738784671.946 [Default:CheckIn] Stopped
2025-02-05T19:44:31.946Z,1738784671.946 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-05T19:44:31.946Z,1738784671.946 [Default:CheckIn](INFO): Running loop #6
2025-02-05T19:44:31.946Z,1738784671.946 [Default:CheckIn] Running Loop=6
2025-02-05T19:44:31.946Z,1738784671.946 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-05T19:44:31.946Z,1738784671.946 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-05T19:44:35.169Z,1738784675.169 [NAL9602](INFO): NAL9602 initialized
2025-02-05T19:46:06.489Z,1738784766.489 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2025-02-05T19:46:06.489Z,1738784766.489 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T19:46:06.530Z,1738784766.530 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T19:46:06.914Z,1738784766.914 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T19:46:06.914Z,1738784766.914 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2025-02-05T19:48:51.344Z,1738784931.344 [CBIT](INFO): Clearing failed state for component DropWeight
2025-02-05T19:48:51.344Z,1738784931.344 [DropWeight] No Fault, FailCount= 1
2025-02-05T19:49:07.492Z,1738784947.492 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2025-02-05T19:49:07.492Z,1738784947.492 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T19:49:07.503Z,1738784947.503 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T19:49:07.503Z,1738784947.503 [CBIT](FAULT): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T19:49:32.131Z,1738784972.131 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-02-05T19:44:31.9Z
2025-02-05T19:49:32.131Z,1738784972.131 [Default:CheckIn:Read_GPS] Stopped
2025-02-05T19:49:32.131Z,1738784972.131 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-05T19:49:39.727Z,1738784979.727 [DataOverHttps](INFO): Sending 81 bytes from file Logs/20250205T184825/Courier0019.lzma
2025-02-05T19:49:40.730Z,1738784980.730 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Courier0019.lzma.bak
2025-02-05T19:49:40.730Z,1738784980.730 [DataOverHttps](INFO): SBD MOMSN=23957341
2025-02-05T19:49:56.109Z,1738784996.109 [DataOverHttps](INFO): Sending 212 bytes from file Logs/20250205T184825/Express0020.lzma
2025-02-05T19:49:57.110Z,1738784997.110 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Express0020.lzma.bak
2025-02-05T19:49:57.110Z,1738784997.110 [DataOverHttps](INFO): SBD MOMSN=23957344
2025-02-05T19:49:58.414Z,1738784998.414 [Default:CheckIn:Read_Iridium] Stopped
2025-02-05T19:49:58.414Z,1738784998.414 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-05T19:49:58.414Z,1738784998.414 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-05T19:51:19.379Z,1738785079.379 [CommandExec](IMPORTANT): got command failComponent hardware DropWeight
2025-02-05T19:51:19.383Z,1738785079.383 [DropWeight] Hardware Fault, FailCount= 1
2025-02-05T19:51:19.383Z,1738785079.383 [DropWeight](ERROR): Hardware Fault
2025-02-05T19:51:19.383Z,1738785079.383 [CommandExec](IMPORTANT): DropWeight failureMode is Hardware Fault
2025-02-05T19:51:19.634Z,1738785079.634 [CBIT](ERROR): Hardware Fault in component: DropWeight
2025-02-05T19:51:19.634Z,1738785079.634 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2025-02-05T19:51:20.049Z,1738785080.049 [CBIT](INFO): Critical error at 20250205T195119
2025-02-05T19:51:23.332Z,1738785083.332 [CommandExec](IMPORTANT): got command failComponent none DropWeight
2025-02-05T19:51:23.332Z,1738785083.332 [DropWeight] No Fault, FailCount= 1
2025-02-05T19:51:23.333Z,1738785083.333 [CommandExec](IMPORTANT): DropWeight failureMode is No Fault
2025-02-05T19:54:35.989Z,1738785275.989 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-02-05T19:54:35.990Z,1738785275.990 [NAL9602] Data Fault, FailCount= 1
2025-02-05T19:54:35.990Z,1738785275.990 [NAL9602](ERROR): Data Fault
2025-02-05T19:54:36.053Z,1738785276.053 [CBIT](ERROR): Data Fault in component: NAL9602
2025-02-05T19:54:36.394Z,1738785276.394 [NAL9602](INFO): Powering down
2025-02-05T19:54:37.232Z,1738785277.232 [CBIT](INFO): Clearing failed state for component NAL9602
2025-02-05T19:54:37.232Z,1738785277.232 [NAL9602] No Fault, FailCount= 1
2025-02-05T19:54:59.028Z,1738785299.028 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-05T19:54:59.028Z,1738785299.028 [Default:CheckIn:C.Wait] Stopped
2025-02-05T19:54:59.028Z,1738785299.028 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-05T19:54:59.028Z,1738785299.028 [Default:CheckIn:D] Running Loop=1
2025-02-05T19:54:59.426Z,1738785299.426 [Default:CheckIn:D] Stopped
2025-02-05T19:54:59.426Z,1738785299.426 [Default:CheckIn:E] Running Loop=1
2025-02-05T19:54:59.836Z,1738785299.836 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.060791 min
2025-02-05T19:54:59.836Z,1738785299.836 [Default:CheckIn:E] Stopped
2025-02-05T19:54:59.837Z,1738785299.837 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-05T19:54:59.837Z,1738785299.837 [Default:CheckIn] Stopped
2025-02-05T19:54:59.837Z,1738785299.837 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-05T19:54:59.837Z,1738785299.837 [Default:CheckIn](INFO): Running loop #7
2025-02-05T19:54:59.837Z,1738785299.837 [Default:CheckIn] Running Loop=7
2025-02-05T19:54:59.837Z,1738785299.837 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-05T19:54:59.837Z,1738785299.837 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-05T19:55:06.698Z,1738785306.698 [NAL9602](INFO): Powering up NAL9602
2025-02-05T19:55:17.600Z,1738785317.600 [NAL9602](INFO): NAL9602 initialized
2025-02-05T20:00:00.042Z,1738785600.042 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-02-05T19:54:59.8Z
2025-02-05T20:00:00.042Z,1738785600.042 [Default:CheckIn:Read_GPS] Stopped
2025-02-05T20:00:00.042Z,1738785600.042 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-05T20:00:06.952Z,1738785606.952 [DataOverHttps](INFO): Sending 87 bytes from file Logs/20250205T184825/Courier0022.lzma
2025-02-05T20:00:07.954Z,1738785607.954 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Courier0022.lzma.bak
2025-02-05T20:00:07.954Z,1738785607.954 [DataOverHttps](INFO): SBD MOMSN=23957349
2025-02-05T20:00:25.800Z,1738785625.800 [DataOverHttps](INFO): Sending 259 bytes from file Logs/20250205T184825/Express0023.lzma
2025-02-05T20:00:26.802Z,1738785626.802 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Express0023.lzma.bak
2025-02-05T20:00:26.802Z,1738785626.802 [DataOverHttps](INFO): SBD MOMSN=23957352
2025-02-05T20:00:27.937Z,1738785627.937 [Default:CheckIn:Read_Iridium] Stopped
2025-02-05T20:00:27.937Z,1738785627.937 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-05T20:00:27.937Z,1738785627.937 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-05T20:04:07.723Z,1738785847.723 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T20:04:07.723Z,1738785847.723 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20
2025-02-05T20:05:20.022Z,1738785920.022 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-02-05T20:05:20.022Z,1738785920.022 [NAL9602] Data Fault, FailCount= 2
2025-02-05T20:05:20.022Z,1738785920.022 [NAL9602](ERROR): Data Fault
2025-02-05T20:05:20.063Z,1738785920.063 [CBIT](ERROR): Data Fault in component: NAL9602
2025-02-05T20:05:20.425Z,1738785920.425 [NAL9602](INFO): Powering down
2025-02-05T20:05:21.245Z,1738785921.245 [CBIT](INFO): Clearing failed state for component NAL9602
2025-02-05T20:05:21.245Z,1738785921.245 [NAL9602] No Fault, FailCount= 2
2025-02-05T20:05:28.513Z,1738785928.513 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-05T20:05:28.513Z,1738785928.513 [Default:CheckIn:C.Wait] Stopped
2025-02-05T20:05:28.514Z,1738785928.514 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-05T20:05:28.514Z,1738785928.514 [Default:CheckIn:D] Running Loop=1
2025-02-05T20:05:28.922Z,1738785928.922 [Default:CheckIn:D] Stopped
2025-02-05T20:05:28.922Z,1738785928.922 [Default:CheckIn:E] Running Loop=1
2025-02-05T20:05:29.329Z,1738785929.329 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 73.552376 min
2025-02-05T20:05:29.329Z,1738785929.329 [Default:CheckIn:E] Stopped
2025-02-05T20:05:29.329Z,1738785929.329 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-05T20:05:29.329Z,1738785929.329 [Default:CheckIn] Stopped
2025-02-05T20:05:29.330Z,1738785929.330 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-05T20:05:29.330Z,1738785929.330 [Default:CheckIn](INFO): Running loop #8
2025-02-05T20:05:29.330Z,1738785929.330 [Default:CheckIn] Running Loop=8
2025-02-05T20:05:29.330Z,1738785929.330 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-05T20:05:29.330Z,1738785929.330 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-05T20:05:50.721Z,1738785950.721 [NAL9602](INFO): Powering up NAL9602
2025-02-05T20:06:01.634Z,1738785961.634 [NAL9602](INFO): NAL9602 initialized
2025-02-05T20:07:08.301Z,1738786028.301 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-02-05T20:07:08.301Z,1738786028.301 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T20:07:08.337Z,1738786028.337 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T20:07:08.714Z,1738786028.714 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T20:07:08.714Z,1738786028.714 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-02-05T20:10:09.293Z,1738786209.293 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2025-02-05T20:10:09.293Z,1738786209.293 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T20:10:09.343Z,1738786209.343 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T20:10:09.720Z,1738786209.720 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T20:10:09.720Z,1738786209.720 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2025-02-05T20:10:29.495Z,1738786229.495 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-02-05T20:05:29.3Z
2025-02-05T20:10:29.495Z,1738786229.495 [Default:CheckIn:Read_GPS] Stopped
2025-02-05T20:10:29.495Z,1738786229.495 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-05T20:10:37.095Z,1738786237.095 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250205T184825/Courier0025.lzma
2025-02-05T20:10:38.098Z,1738786238.098 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Courier0025.lzma.bak
2025-02-05T20:10:38.098Z,1738786238.098 [DataOverHttps](INFO): SBD MOMSN=23957360
2025-02-05T20:10:59.561Z,1738786259.561 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250205T184825/Express0026.lzma
2025-02-05T20:11:00.562Z,1738786260.562 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Express0026.lzma.bak
2025-02-05T20:11:00.562Z,1738786260.562 [DataOverHttps](INFO): SBD MOMSN=23957362
2025-02-05T20:11:01.878Z,1738786261.878 [Default:CheckIn:Read_Iridium] Stopped
2025-02-05T20:11:01.878Z,1738786261.878 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-05T20:11:01.878Z,1738786261.878 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-05T20:13:10.353Z,1738786390.353 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2025-02-05T20:13:10.353Z,1738786390.353 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T20:13:10.380Z,1738786390.380 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T20:13:10.782Z,1738786390.782 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T20:13:10.783Z,1738786390.783 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2025-02-05T20:16:02.454Z,1738786562.454 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-05T20:16:02.454Z,1738786562.454 [Default:CheckIn:C.Wait] Stopped
2025-02-05T20:16:02.454Z,1738786562.454 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-05T20:16:02.455Z,1738786562.455 [Default:CheckIn:D] Running Loop=1
2025-02-05T20:16:02.870Z,1738786562.870 [Default:CheckIn:D] Stopped
2025-02-05T20:16:02.870Z,1738786562.870 [Default:CheckIn:E] Running Loop=1
2025-02-05T20:16:03.243Z,1738786563.243 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 84.118188 min
2025-02-05T20:16:03.243Z,1738786563.243 [Default:CheckIn:E] Stopped
2025-02-05T20:16:03.243Z,1738786563.243 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-05T20:16:03.243Z,1738786563.243 [Default:CheckIn] Stopped
2025-02-05T20:16:03.243Z,1738786563.243 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-05T20:16:03.243Z,1738786563.243 [Default:CheckIn](INFO): Running loop #9
2025-02-05T20:16:03.243Z,1738786563.243 [Default:CheckIn] Running Loop=9
2025-02-05T20:16:03.243Z,1738786563.243 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-05T20:16:03.243Z,1738786563.243 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-05T20:16:04.042Z,1738786564.042 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-02-05T20:16:04.042Z,1738786564.042 [NAL9602] Data Fault, FailCount= 3
2025-02-05T20:16:04.042Z,1738786564.042 [NAL9602](ERROR): Data Fault
2025-02-05T20:16:04.087Z,1738786564.087 [CBIT](ERROR): Data Fault in component: NAL9602
2025-02-05T20:16:04.445Z,1738786564.445 [NAL9602](INFO): Powering down
2025-02-05T20:16:05.273Z,1738786565.273 [CBIT](INFO): Clearing failed state for component NAL9602
2025-02-05T20:16:05.273Z,1738786565.273 [NAL9602] No Fault, FailCount= 3
2025-02-05T20:16:11.324Z,1738786571.324 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2025-02-05T20:16:11.324Z,1738786571.324 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T20:16:11.335Z,1738786571.335 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T20:16:11.740Z,1738786571.740 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T20:16:11.740Z,1738786571.740 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2025-02-05T20:16:34.749Z,1738786594.749 [NAL9602](INFO): Powering up NAL9602
2025-02-05T20:16:45.654Z,1738786605.654 [NAL9602](INFO): NAL9602 initialized
2025-02-05T20:19:12.323Z,1738786752.323 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2025-02-05T20:19:12.323Z,1738786752.323 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T20:19:12.347Z,1738786752.347 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T20:19:12.745Z,1738786752.745 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T20:19:12.745Z,1738786752.745 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2025-02-05T20:21:03.426Z,1738786863.426 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-02-05T20:16:03.2Z
2025-02-05T20:21:03.426Z,1738786863.426 [Default:CheckIn:Read_GPS] Stopped
2025-02-05T20:21:03.426Z,1738786863.426 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-05T20:21:10.923Z,1738786870.923 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250205T184825/Courier0028.lzma
2025-02-05T20:21:11.926Z,1738786871.926 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Courier0028.lzma.bak
2025-02-05T20:21:11.926Z,1738786871.926 [DataOverHttps](INFO): SBD MOMSN=23957376
2025-02-05T20:21:27.580Z,1738786887.580 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250205T184825/Express0029.lzma
2025-02-05T20:21:28.582Z,1738786888.582 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Express0029.lzma.bak
2025-02-05T20:21:28.582Z,1738786888.582 [DataOverHttps](INFO): SBD MOMSN=23957378
2025-02-05T20:21:29.693Z,1738786889.693 [Default:CheckIn:Read_Iridium] Stopped
2025-02-05T20:21:29.693Z,1738786889.693 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-05T20:21:29.693Z,1738786889.693 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-05T20:22:13.337Z,1738786933.337 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2025-02-05T20:22:13.337Z,1738786933.337 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T20:22:13.348Z,1738786933.348 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T20:22:13.747Z,1738786933.747 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T20:22:13.747Z,1738786933.747 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2025-02-05T20:25:14.308Z,1738787114.308 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2025-02-05T20:25:14.308Z,1738787114.308 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T20:25:14.320Z,1738787114.320 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T20:25:14.734Z,1738787114.734 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T20:25:14.735Z,1738787114.735 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2025-02-05T20:26:30.263Z,1738787190.263 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-05T20:26:30.263Z,1738787190.263 [Default:CheckIn:C.Wait] Stopped
2025-02-05T20:26:30.263Z,1738787190.263 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-05T20:26:30.264Z,1738787190.264 [Default:CheckIn:D] Running Loop=1
2025-02-05T20:26:30.663Z,1738787190.663 [Default:CheckIn:D] Stopped
2025-02-05T20:26:30.663Z,1738787190.663 [Default:CheckIn:E] Running Loop=1
2025-02-05T20:26:31.094Z,1738787191.094 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 94.581413 min
2025-02-05T20:26:31.094Z,1738787191.094 [Default:CheckIn:E] Stopped
2025-02-05T20:26:31.095Z,1738787191.095 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-05T20:26:31.095Z,1738787191.095 [Default:CheckIn] Stopped
2025-02-05T20:26:31.095Z,1738787191.095 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-05T20:26:31.095Z,1738787191.095 [Default:CheckIn](INFO): Running loop #10
2025-02-05T20:26:31.095Z,1738787191.095 [Default:CheckIn] Running Loop=10
2025-02-05T20:26:31.095Z,1738787191.095 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-05T20:26:31.095Z,1738787191.095 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-05T20:26:48.030Z,1738787208.030 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-02-05T20:26:48.030Z,1738787208.030 [NAL9602] Data Fault, FailCount= 4
2025-02-05T20:26:48.030Z,1738787208.030 [NAL9602](ERROR): Data Fault
2025-02-05T20:26:48.067Z,1738787208.067 [CBIT](ERROR): Data Fault in component: NAL9602
2025-02-05T20:26:48.495Z,1738787208.495 [NAL9602](INFO): Powering down
2025-02-05T20:26:49.264Z,1738787209.264 [CBIT](INFO): Clearing failed state for component NAL9602
2025-02-05T20:26:49.264Z,1738787209.264 [NAL9602] No Fault, FailCount= 4
2025-02-05T20:27:14.685Z,1738787234.685 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-02-05T20:27:18.734Z,1738787238.734 [NAL9602](INFO): Powering up NAL9602
2025-02-05T20:27:29.642Z,1738787249.642 [NAL9602](INFO): NAL9602 initialized
2025-02-05T20:28:15.315Z,1738787295.315 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2025-02-05T20:28:15.315Z,1738787295.315 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T20:28:15.326Z,1738787295.326 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T20:28:15.738Z,1738787295.738 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T20:28:15.738Z,1738787295.738 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2025-02-05T20:31:16.313Z,1738787476.313 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2025-02-05T20:31:16.313Z,1738787476.313 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T20:31:16.326Z,1738787476.326 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T20:31:16.731Z,1738787476.731 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T20:31:16.731Z,1738787476.731 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2025-02-05T20:31:31.240Z,1738787491.240 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-02-05T20:26:31.1Z
2025-02-05T20:31:31.240Z,1738787491.240 [Default:CheckIn:Read_GPS] Stopped
2025-02-05T20:31:31.240Z,1738787491.240 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-05T20:31:38.447Z,1738787498.447 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250205T184825/Courier0031.lzma
2025-02-05T20:31:39.450Z,1738787499.450 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Courier0031.lzma.bak
2025-02-05T20:31:39.450Z,1738787499.450 [DataOverHttps](INFO): SBD MOMSN=23957433
2025-02-05T20:31:54.984Z,1738787514.984 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250205T184825/Express0032.lzma
2025-02-05T20:31:55.986Z,1738787515.986 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Express0032.lzma.bak
2025-02-05T20:31:55.987Z,1738787515.987 [DataOverHttps](INFO): SBD MOMSN=23957435
2025-02-05T20:31:57.115Z,1738787517.115 [Default:CheckIn:Read_Iridium] Stopped
2025-02-05T20:31:57.116Z,1738787517.116 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-05T20:31:57.116Z,1738787517.116 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-05T20:34:17.295Z,1738787657.295 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2025-02-05T20:34:17.295Z,1738787657.295 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T20:34:17.306Z,1738787657.306 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T20:34:17.698Z,1738787657.698 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T20:34:17.698Z,1738787657.698 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2025-02-05T20:36:57.685Z,1738787817.685 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-05T20:36:57.685Z,1738787817.685 [Default:CheckIn:C.Wait] Stopped
2025-02-05T20:36:57.685Z,1738787817.685 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-05T20:36:57.685Z,1738787817.685 [Default:CheckIn:D] Running Loop=1
2025-02-05T20:36:58.102Z,1738787818.102 [Default:CheckIn:D] Stopped
2025-02-05T20:36:58.102Z,1738787818.102 [Default:CheckIn:E] Running Loop=1
2025-02-05T20:36:58.517Z,1738787818.517 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 105.038729 min
2025-02-05T20:36:58.517Z,1738787818.517 [Default:CheckIn:E] Stopped
2025-02-05T20:36:58.517Z,1738787818.517 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-05T20:36:58.517Z,1738787818.517 [Default:CheckIn] Stopped
2025-02-05T20:36:58.517Z,1738787818.517 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-05T20:36:58.518Z,1738787818.518 [Default:CheckIn](INFO): Running loop #11
2025-02-05T20:36:58.518Z,1738787818.518 [Default:CheckIn] Running Loop=11
2025-02-05T20:36:58.518Z,1738787818.518 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-05T20:36:58.518Z,1738787818.518 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-05T20:37:18.277Z,1738787838.277 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2025-02-05T20:37:18.277Z,1738787838.277 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T20:37:18.288Z,1738787838.288 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T20:37:18.691Z,1738787838.691 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T20:37:18.691Z,1738787838.691 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2025-02-05T20:37:32.004Z,1738787852.004 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-02-05T20:37:32.004Z,1738787852.004 [NAL9602] Data Fault, FailCount= 5
2025-02-05T20:37:32.004Z,1738787852.004 [NAL9602](ERROR): Data Fault
2025-02-05T20:37:32.023Z,1738787852.023 [CBIT](ERROR): Data Fault in component: NAL9602
2025-02-05T20:37:32.023Z,1738787852.023 [CBIT](CRITICAL): Data Fault in component: NAL9602
2025-02-05T20:37:32.418Z,1738787852.418 [NAL9602](INFO): Powering down
2025-02-05T20:37:32.435Z,1738787852.435 [CBIT](INFO): Critical error at 20250205T203732
2025-02-05T20:39:32.011Z,1738787972.011 [CBIT](INFO): Clearing failed state for component NAL9602
2025-02-05T20:39:32.011Z,1738787972.011 [NAL9602] No Fault, FailCount= 5
2025-02-05T20:39:32.398Z,1738787972.398 [NAL9602](INFO): Powering up NAL9602
2025-02-05T20:39:43.306Z,1738787983.306 [NAL9602](INFO): NAL9602 initialized
2025-02-05T20:40:19.348Z,1738788019.348 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2025-02-05T20:40:19.348Z,1738788019.348 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T20:40:19.360Z,1738788019.360 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T20:40:19.728Z,1738788019.728 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T20:40:19.728Z,1738788019.728 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2025-02-05T20:41:58.654Z,1738788118.654 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-02-05T20:36:58.5Z
2025-02-05T20:41:58.654Z,1738788118.654 [Default:CheckIn:Read_GPS] Stopped
2025-02-05T20:41:58.654Z,1738788118.654 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-05T20:42:05.899Z,1738788125.899 [DataOverHttps](INFO): Sending 81 bytes from file Logs/20250205T184825/Courier0034.lzma
2025-02-05T20:42:06.902Z,1738788126.902 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Courier0034.lzma.bak
2025-02-05T20:42:06.902Z,1738788126.902 [DataOverHttps](INFO): SBD MOMSN=23957439
2025-02-05T20:42:22.488Z,1738788142.488 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250205T184825/Express0035.lzma
2025-02-05T20:42:23.490Z,1738788143.490 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Express0035.lzma.bak
2025-02-05T20:42:23.490Z,1738788143.490 [DataOverHttps](INFO): SBD MOMSN=23957442
2025-02-05T20:42:24.528Z,1738788144.528 [Default:CheckIn:Read_Iridium] Stopped
2025-02-05T20:42:24.528Z,1738788144.528 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-05T20:42:24.528Z,1738788144.528 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-05T20:43:20.289Z,1738788200.289 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2025-02-05T20:43:20.289Z,1738788200.289 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T20:43:20.300Z,1738788200.300 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T20:43:20.707Z,1738788200.707 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T20:43:20.708Z,1738788200.708 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2025-02-05T20:43:53.782Z,1738788233.782 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-02-05T20:46:21.312Z,1738788381.312 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2025-02-05T20:46:21.312Z,1738788381.312 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T20:46:21.336Z,1738788381.336 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T20:46:21.713Z,1738788381.713 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T20:46:21.713Z,1738788381.713 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2025-02-05T20:47:25.114Z,1738788445.114 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-05T20:47:25.114Z,1738788445.114 [Default:CheckIn:C.Wait] Stopped
2025-02-05T20:47:25.114Z,1738788445.114 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-05T20:47:25.114Z,1738788445.114 [Default:CheckIn:D] Running Loop=1
2025-02-05T20:47:25.530Z,1738788445.530 [Default:CheckIn:D] Stopped
2025-02-05T20:47:25.530Z,1738788445.530 [Default:CheckIn:E] Running Loop=1
2025-02-05T20:47:25.918Z,1738788445.918 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 115.495850 min
2025-02-05T20:47:25.918Z,1738788445.918 [Default:CheckIn:E] Stopped
2025-02-05T20:47:25.918Z,1738788445.918 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-05T20:47:25.918Z,1738788445.918 [Default:CheckIn] Stopped
2025-02-05T20:47:25.918Z,1738788445.918 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-05T20:47:25.919Z,1738788445.919 [Default:CheckIn](INFO): Running loop #12
2025-02-05T20:47:25.919Z,1738788445.919 [Default:CheckIn] Running Loop=12
2025-02-05T20:47:25.919Z,1738788445.919 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-05T20:47:25.919Z,1738788445.919 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-05T20:48:56.388Z,1738788536.388 [Sonardyne_Nano](INFO): Battery at maximum, disabling charging
2025-02-05T20:49:22.279Z,1738788562.279 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2025-02-05T20:49:22.279Z,1738788562.279 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T20:49:22.314Z,1738788562.314 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T20:49:22.717Z,1738788562.717 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T20:49:22.717Z,1738788562.717 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2025-02-05T20:49:45.706Z,1738788585.706 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-02-05T20:49:45.706Z,1738788585.706 [NAL9602] Data Fault, FailCount= 1
2025-02-05T20:49:45.706Z,1738788585.706 [NAL9602](ERROR): Data Fault
2025-02-05T20:49:45.723Z,1738788585.723 [CBIT](ERROR): Data Fault in component: NAL9602
2025-02-05T20:49:46.109Z,1738788586.109 [NAL9602](INFO): Powering down
2025-02-05T20:49:46.934Z,1738788586.934 [CBIT](INFO): Clearing failed state for component NAL9602
2025-02-05T20:49:46.934Z,1738788586.934 [NAL9602] No Fault, FailCount= 1
2025-02-05T20:50:16.406Z,1738788616.406 [NAL9602](INFO): Powering up NAL9602
2025-02-05T20:50:27.314Z,1738788627.314 [NAL9602](INFO): NAL9602 initialized
2025-02-05T20:50:50.742Z,1738788650.742 [BPC1](ERROR): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-02-05T20:50:50.743Z,1738788650.743 [BPC1](INFO): Calculating totals. Valid battery stick count: 27. Valid reserve battery stick count: 3.
2025-02-05T20:50:50.746Z,1738788650.746 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2025-02-05T20:52:23.287Z,1738788743.287 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2025-02-05T20:52:23.287Z,1738788743.287 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T20:52:23.320Z,1738788743.320 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T20:52:23.706Z,1738788743.706 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T20:52:23.706Z,1738788743.706 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2025-02-05T20:52:26.123Z,1738788746.123 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-02-05T20:47:25.9Z
2025-02-05T20:52:26.123Z,1738788746.123 [Default:CheckIn:Read_GPS] Stopped
2025-02-05T20:52:26.123Z,1738788746.123 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-05T20:52:33.535Z,1738788753.535 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20250205T184825/Courier0037.lzma
2025-02-05T20:52:34.538Z,1738788754.538 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Courier0037.lzma.bak
2025-02-05T20:52:34.538Z,1738788754.538 [DataOverHttps](INFO): SBD MOMSN=23957446
2025-02-05T20:52:50.020Z,1738788770.020 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250205T184825/Express0038.lzma
2025-02-05T20:52:51.022Z,1738788771.022 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Express0038.lzma.bak
2025-02-05T20:52:51.022Z,1738788771.022 [DataOverHttps](INFO): SBD MOMSN=23957448
2025-02-05T20:52:53.171Z,1738788773.171 [Default:CheckIn:Read_Iridium] Stopped
2025-02-05T20:52:53.171Z,1738788773.171 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-05T20:52:53.171Z,1738788773.171 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-05T20:55:24.295Z,1738788924.295 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2025-02-05T20:55:24.295Z,1738788924.295 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T20:55:24.306Z,1738788924.306 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T20:55:24.720Z,1738788924.720 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T20:55:24.720Z,1738788924.720 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2025-02-05T20:57:53.771Z,1738789073.771 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-05T20:57:53.771Z,1738789073.771 [Default:CheckIn:C.Wait] Stopped
2025-02-05T20:57:53.771Z,1738789073.771 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-05T20:57:53.771Z,1738789073.771 [Default:CheckIn:D] Running Loop=1
2025-02-05T20:57:54.181Z,1738789074.181 [Default:CheckIn:D] Stopped
2025-02-05T20:57:54.181Z,1738789074.181 [Default:CheckIn:E] Running Loop=1
2025-02-05T20:57:54.571Z,1738789074.571 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 125.973372 min
2025-02-05T20:57:54.572Z,1738789074.572 [Default:CheckIn:E] Stopped
2025-02-05T20:57:54.572Z,1738789074.572 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-05T20:57:54.572Z,1738789074.572 [Default:CheckIn] Stopped
2025-02-05T20:57:54.572Z,1738789074.572 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-05T20:57:54.572Z,1738789074.572 [Default:CheckIn](INFO): Running loop #13
2025-02-05T20:57:54.572Z,1738789074.572 [Default:CheckIn] Running Loop=13
2025-02-05T20:57:54.572Z,1738789074.572 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-05T20:57:54.573Z,1738789074.573 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-05T20:58:25.265Z,1738789105.265 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2025-02-05T20:58:25.265Z,1738789105.265 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T20:58:25.275Z,1738789105.275 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T20:58:25.681Z,1738789105.681 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T20:58:25.681Z,1738789105.681 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2025-02-05T21:00:29.686Z,1738789229.686 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-02-05T21:00:29.686Z,1738789229.686 [NAL9602] Data Fault, FailCount= 2
2025-02-05T21:00:29.686Z,1738789229.686 [NAL9602](ERROR): Data Fault
2025-02-05T21:00:29.708Z,1738789229.708 [CBIT](ERROR): Data Fault in component: NAL9602
2025-02-05T21:00:30.117Z,1738789230.117 [NAL9602](INFO): Powering down
2025-02-05T21:00:30.929Z,1738789230.929 [CBIT](INFO): Clearing failed state for component NAL9602
2025-02-05T21:00:30.929Z,1738789230.929 [NAL9602] No Fault, FailCount= 2
2025-02-05T21:01:00.402Z,1738789260.402 [NAL9602](INFO): Powering up NAL9602
2025-02-05T21:01:11.310Z,1738789271.310 [NAL9602](INFO): NAL9602 initialized
2025-02-05T21:01:26.279Z,1738789286.279 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2025-02-05T21:01:26.279Z,1738789286.279 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T21:01:26.290Z,1738789286.290 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T21:01:26.682Z,1738789286.682 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T21:01:26.682Z,1738789286.682 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2025-02-05T21:02:54.770Z,1738789374.770 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-02-05T20:57:54.6Z
2025-02-05T21:02:54.770Z,1738789374.770 [Default:CheckIn:Read_GPS] Stopped
2025-02-05T21:02:54.770Z,1738789374.770 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-05T21:03:00.452Z,1738789380.452 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250205T184825/Courier0040.lzma
2025-02-05T21:03:01.454Z,1738789381.454 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Courier0040.lzma.bak
2025-02-05T21:03:01.454Z,1738789381.454 [DataOverHttps](INFO): SBD MOMSN=23957452
2025-02-05T21:03:16.892Z,1738789396.892 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250205T184825/Express0041.lzma
2025-02-05T21:03:17.894Z,1738789397.894 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Express0041.lzma.bak
2025-02-05T21:03:17.894Z,1738789397.894 [DataOverHttps](INFO): SBD MOMSN=23957454
2025-02-05T21:03:19.026Z,1738789399.026 [Default:CheckIn:Read_Iridium] Stopped
2025-02-05T21:03:19.026Z,1738789399.026 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-05T21:03:19.026Z,1738789399.026 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-05T21:04:27.301Z,1738789467.301 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2025-02-05T21:04:27.301Z,1738789467.301 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T21:04:27.312Z,1738789467.312 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T21:04:27.312Z,1738789467.312 [CBIT](FAULT): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T21:08:19.621Z,1738789699.621 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-05T21:08:19.622Z,1738789699.622 [Default:CheckIn:C.Wait] Stopped
2025-02-05T21:08:19.622Z,1738789699.622 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-05T21:08:19.622Z,1738789699.622 [Default:CheckIn:D] Running Loop=1
2025-02-05T21:08:20.006Z,1738789700.006 [Default:CheckIn:D] Stopped
2025-02-05T21:08:20.006Z,1738789700.006 [Default:CheckIn:E] Running Loop=1
2025-02-05T21:08:20.503Z,1738789700.503 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 136.403792 min
2025-02-05T21:08:20.503Z,1738789700.503 [Default:CheckIn:E] Stopped
2025-02-05T21:08:20.504Z,1738789700.504 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-05T21:08:20.504Z,1738789700.504 [Default:CheckIn] Stopped
2025-02-05T21:08:20.504Z,1738789700.504 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-05T21:08:20.504Z,1738789700.504 [Default:CheckIn](INFO): Running loop #14
2025-02-05T21:08:20.504Z,1738789700.504 [Default:CheckIn] Running Loop=14
2025-02-05T21:08:20.504Z,1738789700.504 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-05T21:08:20.504Z,1738789700.504 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-05T21:11:12.134Z,1738789872.134 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-02-05T21:11:12.134Z,1738789872.134 [NAL9602] Data Fault, FailCount= 3
2025-02-05T21:11:12.134Z,1738789872.134 [NAL9602](ERROR): Data Fault
2025-02-05T21:11:12.175Z,1738789872.175 [CBIT](ERROR): Data Fault in component: NAL9602
2025-02-05T21:11:12.542Z,1738789872.542 [NAL9602](INFO): Powering down
2025-02-05T21:11:13.370Z,1738789873.370 [CBIT](INFO): Clearing failed state for component NAL9602
2025-02-05T21:11:13.370Z,1738789873.370 [NAL9602] No Fault, FailCount= 3
2025-02-05T21:11:42.835Z,1738789902.835 [NAL9602](INFO): Powering up NAL9602
2025-02-05T21:11:53.750Z,1738789913.750 [NAL9602](INFO): NAL9602 initialized
2025-02-05T21:13:20.617Z,1738790000.617 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-02-05T21:08:20.5Z
2025-02-05T21:13:20.617Z,1738790000.617 [Default:CheckIn:Read_GPS] Stopped
2025-02-05T21:13:20.617Z,1738790000.617 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-05T21:13:21.425Z,1738790001.425 [Default:CheckIn:Read_Iridium] Stopped
2025-02-05T21:13:21.426Z,1738790001.426 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-05T21:13:21.426Z,1738790001.426 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-05T21:18:22.016Z,1738790302.016 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-05T21:18:22.016Z,1738790302.016 [Default:CheckIn:C.Wait] Stopped
2025-02-05T21:18:22.016Z,1738790302.016 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-05T21:18:22.016Z,1738790302.016 [Default:CheckIn:D] Running Loop=1
2025-02-05T21:18:22.432Z,1738790302.432 [Default:CheckIn:D] Stopped
2025-02-05T21:18:22.432Z,1738790302.432 [Default:CheckIn:E] Running Loop=1
2025-02-05T21:18:22.838Z,1738790302.838 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 146.444238 min
2025-02-05T21:18:22.838Z,1738790302.838 [Default:CheckIn:E] Stopped
2025-02-05T21:18:22.838Z,1738790302.838 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-05T21:18:22.838Z,1738790302.838 [Default:CheckIn] Stopped
2025-02-05T21:18:22.838Z,1738790302.838 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-05T21:18:22.838Z,1738790302.838 [Default:CheckIn](INFO): Running loop #15
2025-02-05T21:18:22.838Z,1738790302.838 [Default:CheckIn] Running Loop=15
2025-02-05T21:18:22.838Z,1738790302.838 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-05T21:18:22.838Z,1738790302.838 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-05T21:19:27.490Z,1738790367.490 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T21:19:27.490Z,1738790367.490 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20
2025-02-05T21:21:56.118Z,1738790516.118 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-02-05T21:21:56.118Z,1738790516.118 [NAL9602] Data Fault, FailCount= 4
2025-02-05T21:21:56.118Z,1738790516.118 [NAL9602](ERROR): Data Fault
2025-02-05T21:21:56.185Z,1738790516.185 [CBIT](ERROR): Data Fault in component: NAL9602
2025-02-05T21:21:56.526Z,1738790516.526 [NAL9602](INFO): Powering down
2025-02-05T21:21:57.344Z,1738790517.344 [CBIT](INFO): Clearing failed state for component NAL9602
2025-02-05T21:21:57.345Z,1738790517.345 [NAL9602] No Fault, FailCount= 4
2025-02-05T21:22:26.822Z,1738790546.822 [NAL9602](INFO): Powering up NAL9602
2025-02-05T21:22:28.040Z,1738790548.040 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-02-05T21:22:28.040Z,1738790548.040 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T21:22:28.051Z,1738790548.051 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T21:22:28.490Z,1738790548.490 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T21:22:28.490Z,1738790548.490 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-02-05T21:22:37.730Z,1738790557.730 [NAL9602](INFO): NAL9602 initialized
2025-02-05T21:23:22.979Z,1738790602.979 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-02-05T21:18:22.8Z
2025-02-05T21:23:22.979Z,1738790602.979 [Default:CheckIn:Read_GPS] Stopped
2025-02-05T21:23:22.979Z,1738790602.979 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-05T21:23:30.171Z,1738790610.171 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250205T184825/Courier0043.lzma
2025-02-05T21:23:31.174Z,1738790611.174 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Courier0043.lzma.bak
2025-02-05T21:23:31.174Z,1738790611.174 [DataOverHttps](INFO): SBD MOMSN=23957458
2025-02-05T21:23:46.707Z,1738790626.707 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250205T184825/Courier0046.lzma
2025-02-05T21:23:47.710Z,1738790627.710 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Courier0046.lzma.bak
2025-02-05T21:23:47.710Z,1738790627.710 [DataOverHttps](INFO): SBD MOMSN=23957460
2025-02-05T21:24:03.152Z,1738790643.152 [DataOverHttps](INFO): Sending 215 bytes from file Logs/20250205T184825/Express0044.lzma
2025-02-05T21:24:04.154Z,1738790644.154 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Express0044.lzma.bak
2025-02-05T21:24:04.155Z,1738790644.155 [DataOverHttps](INFO): SBD MOMSN=23957462
2025-02-05T21:24:19.668Z,1738790659.668 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250205T184825/Express0047.lzma
2025-02-05T21:24:20.670Z,1738790660.670 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Express0047.lzma.bak
2025-02-05T21:24:20.670Z,1738790660.670 [DataOverHttps](INFO): SBD MOMSN=23957467
2025-02-05T21:24:21.994Z,1738790661.994 [Default:CheckIn:Read_Iridium] Stopped
2025-02-05T21:24:21.994Z,1738790661.994 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-05T21:24:21.994Z,1738790661.994 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-05T21:25:29.061Z,1738790729.061 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2025-02-05T21:25:29.061Z,1738790729.061 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T21:25:29.072Z,1738790729.072 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T21:25:29.466Z,1738790729.466 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T21:25:29.466Z,1738790729.466 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2025-02-05T21:28:30.045Z,1738790910.045 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2025-02-05T21:28:30.045Z,1738790910.045 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T21:28:30.056Z,1738790910.056 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T21:28:30.444Z,1738790910.444 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T21:28:30.444Z,1738790910.444 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2025-02-05T21:29:22.570Z,1738790962.570 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-05T21:29:22.570Z,1738790962.570 [Default:CheckIn:C.Wait] Stopped
2025-02-05T21:29:22.570Z,1738790962.570 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-05T21:29:22.571Z,1738790962.571 [Default:CheckIn:D] Running Loop=1
2025-02-05T21:29:22.980Z,1738790962.980 [Default:CheckIn:D] Stopped
2025-02-05T21:29:22.980Z,1738790962.980 [Default:CheckIn:E] Running Loop=1
2025-02-05T21:29:23.355Z,1738790963.355 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 157.453369 min
2025-02-05T21:29:23.355Z,1738790963.355 [Default:CheckIn:E] Stopped
2025-02-05T21:29:23.355Z,1738790963.355 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-05T21:29:23.355Z,1738790963.355 [Default:CheckIn] Stopped
2025-02-05T21:29:23.355Z,1738790963.355 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-05T21:29:23.355Z,1738790963.355 [Default:CheckIn](INFO): Running loop #16
2025-02-05T21:29:23.356Z,1738790963.356 [Default:CheckIn] Running Loop=16
2025-02-05T21:29:23.356Z,1738790963.356 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-05T21:29:23.356Z,1738790963.356 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-05T21:31:31.072Z,1738791091.072 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2025-02-05T21:31:31.072Z,1738791091.072 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T21:31:31.091Z,1738791091.091 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T21:31:31.497Z,1738791091.497 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T21:31:31.497Z,1738791091.497 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2025-02-05T21:32:40.131Z,1738791160.131 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-02-05T21:32:40.131Z,1738791160.131 [NAL9602] Data Fault, FailCount= 5
2025-02-05T21:32:40.131Z,1738791160.131 [NAL9602](ERROR): Data Fault
2025-02-05T21:32:40.149Z,1738791160.149 [CBIT](ERROR): Data Fault in component: NAL9602
2025-02-05T21:32:40.149Z,1738791160.149 [CBIT](CRITICAL): Data Fault in component: NAL9602
2025-02-05T21:32:40.529Z,1738791160.529 [NAL9602](INFO): Powering down
2025-02-05T21:32:40.562Z,1738791160.562 [CBIT](INFO): Critical error at 20250205T213240
2025-02-05T21:34:23.565Z,1738791263.565 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-02-05T21:29:23.4Z
2025-02-05T21:34:23.565Z,1738791263.565 [Default:CheckIn:Read_GPS] Stopped
2025-02-05T21:34:23.565Z,1738791263.565 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-05T21:34:31.067Z,1738791271.067 [DataOverHttps](INFO): Sending 81 bytes from file Logs/20250205T184825/Courier0049.lzma
2025-02-05T21:34:32.041Z,1738791272.041 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2025-02-05T21:34:32.041Z,1738791272.041 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T21:34:32.052Z,1738791272.052 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T21:34:32.070Z,1738791272.070 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Courier0049.lzma.bak
2025-02-05T21:34:32.070Z,1738791272.070 [DataOverHttps](INFO): SBD MOMSN=23957471
2025-02-05T21:34:32.458Z,1738791272.458 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T21:34:32.458Z,1738791272.458 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2025-02-05T21:34:40.141Z,1738791280.141 [CBIT](INFO): Clearing failed state for component NAL9602
2025-02-05T21:34:40.141Z,1738791280.141 [NAL9602] No Fault, FailCount= 5
2025-02-05T21:34:40.521Z,1738791280.521 [NAL9602](INFO): Powering up NAL9602
2025-02-05T21:34:47.511Z,1738791287.511 [DataOverHttps](INFO): Sending 153 bytes from file Logs/20250205T184825/Express0050.lzma
2025-02-05T21:34:48.510Z,1738791288.510 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Express0050.lzma.bak
2025-02-05T21:34:48.510Z,1738791288.510 [DataOverHttps](INFO): SBD MOMSN=23957474
2025-02-05T21:34:49.821Z,1738791289.821 [Default:CheckIn:Read_Iridium] Stopped
2025-02-05T21:34:49.821Z,1738791289.821 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-05T21:34:49.821Z,1738791289.821 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-05T21:34:51.430Z,1738791291.430 [NAL9602](INFO): NAL9602 initialized
2025-02-05T21:37:33.033Z,1738791453.033 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2025-02-05T21:37:33.034Z,1738791453.034 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T21:37:33.044Z,1738791453.044 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T21:37:33.455Z,1738791453.455 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T21:37:33.455Z,1738791453.455 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2025-02-05T21:39:50.421Z,1738791590.421 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-05T21:39:50.421Z,1738791590.421 [Default:CheckIn:C.Wait] Stopped
2025-02-05T21:39:50.422Z,1738791590.422 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-05T21:39:50.422Z,1738791590.422 [Default:CheckIn:D] Running Loop=1
2025-02-05T21:39:50.818Z,1738791590.818 [Default:CheckIn:D] Stopped
2025-02-05T21:39:50.818Z,1738791590.818 [Default:CheckIn:E] Running Loop=1
2025-02-05T21:39:51.199Z,1738791591.199 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 167.917334 min
2025-02-05T21:39:51.199Z,1738791591.199 [Default:CheckIn:E] Stopped
2025-02-05T21:39:51.200Z,1738791591.200 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-05T21:39:51.200Z,1738791591.200 [Default:CheckIn] Stopped
2025-02-05T21:39:51.201Z,1738791591.201 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-05T21:39:51.201Z,1738791591.201 [Default:CheckIn](INFO): Running loop #17
2025-02-05T21:39:51.201Z,1738791591.201 [Default:CheckIn] Running Loop=17
2025-02-05T21:39:51.202Z,1738791591.202 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-05T21:39:51.202Z,1738791591.202 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-05T21:40:34.055Z,1738791634.055 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2025-02-05T21:40:34.055Z,1738791634.055 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T21:40:34.075Z,1738791634.075 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T21:40:34.482Z,1738791634.482 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T21:40:34.482Z,1738791634.482 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2025-02-05T21:43:34.945Z,1738791814.945 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2025-02-05T21:43:34.945Z,1738791814.945 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T21:43:34.978Z,1738791814.978 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T21:43:35.342Z,1738791815.342 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T21:43:35.342Z,1738791815.342 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2025-02-05T21:44:51.352Z,1738791891.352 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-02-05T21:39:51.2Z
2025-02-05T21:44:51.352Z,1738791891.352 [Default:CheckIn:Read_GPS] Stopped
2025-02-05T21:44:51.352Z,1738791891.352 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-05T21:44:54.158Z,1738791894.158 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-02-05T21:44:54.158Z,1738791894.158 [NAL9602] Data Fault, FailCount= 1
2025-02-05T21:44:54.158Z,1738791894.158 [NAL9602](ERROR): Data Fault
2025-02-05T21:44:54.224Z,1738791894.224 [CBIT](ERROR): Data Fault in component: NAL9602
2025-02-05T21:44:54.557Z,1738791894.557 [NAL9602](INFO): Powering down
2025-02-05T21:44:55.425Z,1738791895.425 [CBIT](INFO): Clearing failed state for component NAL9602
2025-02-05T21:44:55.425Z,1738791895.425 [NAL9602] No Fault, FailCount= 1
2025-02-05T21:44:58.171Z,1738791898.171 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250205T184825/Courier0052.lzma
2025-02-05T21:44:59.174Z,1738791899.174 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Courier0052.lzma.bak
2025-02-05T21:44:59.174Z,1738791899.174 [DataOverHttps](INFO): SBD MOMSN=23957492
2025-02-05T21:45:18.000Z,1738791918.000 [DataOverHttps](INFO): Sending 111 bytes from file Logs/20250205T184825/Express0053.lzma
2025-02-05T21:45:18.872Z,1738791918.872 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Express0053.lzma.bak
2025-02-05T21:45:18.872Z,1738791918.872 [DataOverHttps](INFO): SBD MOMSN=23957494
2025-02-05T21:45:19.257Z,1738791919.257 [Default:CheckIn:Read_Iridium] Stopped
2025-02-05T21:45:19.257Z,1738791919.257 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-05T21:45:19.257Z,1738791919.257 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-05T21:45:24.899Z,1738791924.899 [NAL9602](INFO): Powering up NAL9602
2025-02-05T21:45:35.810Z,1738791935.810 [NAL9602](INFO): NAL9602 initialized
2025-02-05T21:46:36.037Z,1738791996.037 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2025-02-05T21:46:36.037Z,1738791996.037 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T21:46:36.048Z,1738791996.048 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T21:46:36.432Z,1738791996.432 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T21:46:36.432Z,1738791996.432 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2025-02-05T21:49:37.035Z,1738792177.035 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2025-02-05T21:49:37.035Z,1738792177.035 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-02-05T21:49:37.068Z,1738792177.068 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-02-05T21:49:37.445Z,1738792177.445 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-02-05T21:49:37.445Z,1738792177.445 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2025-02-05T21:49:58.021Z,1738792198.021 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214954.00,A,3648.13694,N,12147.21778,W,0.467,347.01,050225,,,A*7D
2025-02-05T21:49:58.038Z,1738792198.038 [NAL9602](INFO): GPS fix at 20250205T214954: (36.802282, -121.786963)
2025-02-05T21:49:58.451Z,1738792198.451 [DeadReckonUsingMultipleVelocitySources](INFO): Failure count cleared after critical for DeadReckonUsingMultipleVelocitySources
2025-02-05T21:50:19.839Z,1738792219.839 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-05T21:50:19.839Z,1738792219.839 [Default:CheckIn:C.Wait] Stopped
2025-02-05T21:50:19.839Z,1738792219.839 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-05T21:50:19.839Z,1738792219.839 [Default:CheckIn:D] Running Loop=1
2025-02-05T21:50:20.256Z,1738792220.256 [Default:CheckIn:D] Stopped
2025-02-05T21:50:20.256Z,1738792220.256 [Default:CheckIn:E] Running Loop=1
2025-02-05T21:50:20.649Z,1738792220.649 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 178.407959 min
2025-02-05T21:50:20.649Z,1738792220.649 [Default:CheckIn:E] Stopped
2025-02-05T21:50:20.649Z,1738792220.649 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-05T21:50:20.649Z,1738792220.649 [Default:CheckIn] Stopped
2025-02-05T21:50:20.649Z,1738792220.649 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-05T21:50:20.649Z,1738792220.649 [Default:CheckIn](INFO): Running loop #18
2025-02-05T21:50:20.649Z,1738792220.649 [Default:CheckIn] Running Loop=18
2025-02-05T21:50:20.649Z,1738792220.649 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-05T21:50:20.650Z,1738792220.650 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-05T21:50:22.659Z,1738792222.659 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215019.00,A,3648.14558,N,12147.23366,W,0.078,78.01,050225,,,A*44
2025-02-05T21:50:22.662Z,1738792222.662 [NAL9602](INFO): GPS fix at 20250205T215019: (36.802426, -121.787228)
2025-02-05T21:50:22.678Z,1738792222.678 [Default:CheckIn:Read_GPS] Stopped
2025-02-05T21:50:22.678Z,1738792222.678 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-05T21:50:29.493Z,1738792229.493 [DataOverHttps](INFO): Sending 221 bytes from file Logs/20250205T184825/Courier0055.lzma
2025-02-05T21:50:30.494Z,1738792230.494 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Courier0055.lzma.bak
2025-02-05T21:50:30.494Z,1738792230.494 [DataOverHttps](INFO): SBD MOMSN=23957497
2025-02-05T21:50:46.488Z,1738792246.488 [DataOverHttps](INFO): Sending 304 bytes from file Logs/20250205T184825/Express0056.lzma
2025-02-05T21:50:47.491Z,1738792247.491 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Express0056.lzma.bak
2025-02-05T21:50:47.491Z,1738792247.491 [DataOverHttps](INFO): SBD MOMSN=23957500
2025-02-05T21:50:48.539Z,1738792248.539 [Default:CheckIn:Read_Iridium] Stopped
2025-02-05T21:50:48.539Z,1738792248.539 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-05T21:50:48.539Z,1738792248.539 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-05T21:50:55.389Z,1738792255.389 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-05T21:55:49.142Z,1738792549.142 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-05T21:55:49.142Z,1738792549.142 [Default:CheckIn:C.Wait] Stopped
2025-02-05T21:55:49.142Z,1738792549.142 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-05T21:55:49.142Z,1738792549.142 [Default:CheckIn:D] Running Loop=1
2025-02-05T21:55:49.508Z,1738792549.508 [Default:CheckIn:D] Stopped
2025-02-05T21:55:49.508Z,1738792549.508 [Default:CheckIn:E] Running Loop=1
2025-02-05T21:55:49.919Z,1738792549.919 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 183.895492 min
2025-02-05T21:55:49.920Z,1738792549.920 [Default:CheckIn:E] Stopped
2025-02-05T21:55:49.920Z,1738792549.920 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-05T21:55:49.920Z,1738792549.920 [Default:CheckIn] Stopped
2025-02-05T21:55:49.920Z,1738792549.920 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-05T21:55:49.920Z,1738792549.920 [Default:CheckIn](INFO): Running loop #19
2025-02-05T21:55:49.920Z,1738792549.920 [Default:CheckIn] Running Loop=19
2025-02-05T21:55:49.920Z,1738792549.920 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-05T21:55:49.920Z,1738792549.921 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-05T21:55:51.928Z,1738792551.928 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215551.00,A,3648.15137,N,12147.22253,W,0.078,336.18,050225,,,D*73
2025-02-05T21:55:51.931Z,1738792551.931 [NAL9602](INFO): GPS fix at 20250205T215551: (36.802523, -121.787042)
2025-02-05T21:55:51.961Z,1738792551.961 [Default:CheckIn:Read_GPS] Stopped
2025-02-05T21:55:51.962Z,1738792551.962 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-05T21:56:32.727Z,1738792592.727 [NAL9602](INFO): SBD MO Status=2, MOMSN=12481, MT Status=2, MTMSN=0
2025-02-05T21:56:32.727Z,1738792592.727 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-05T21:56:39.115Z,1738792599.115 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250205T184825/Courier0058.lzma
2025-02-05T21:56:40.118Z,1738792600.118 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Courier0058.lzma.bak
2025-02-05T21:56:40.118Z,1738792600.118 [DataOverHttps](INFO): SBD MOMSN=23957504
2025-02-05T21:56:50.907Z,1738792610.907 [NAL9602](INFO): SBD MO Status=0, MOMSN=12481, MT Status=0, MTMSN=0
2025-02-05T21:56:50.908Z,1738792610.908 [NAL9602](INFO): No messages in MT queue
2025-02-05T21:56:56.100Z,1738792616.100 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20250205T184825/Express0059.lzma
2025-02-05T21:56:57.102Z,1738792617.102 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Express0059.lzma.bak
2025-02-05T21:56:57.102Z,1738792617.102 [DataOverHttps](INFO): SBD MOMSN=23957507
2025-02-05T21:56:58.193Z,1738792618.193 [Default:CheckIn:Read_Iridium] Stopped
2025-02-05T21:56:58.193Z,1738792618.193 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-05T21:56:58.193Z,1738792618.193 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-05T21:57:21.617Z,1738792641.617 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-05T22:01:58.891Z,1738792918.891 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-05T22:01:58.891Z,1738792918.891 [Default:CheckIn:C.Wait] Stopped
2025-02-05T22:01:58.891Z,1738792918.891 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-05T22:01:58.892Z,1738792918.892 [Default:CheckIn:D] Running Loop=1
2025-02-05T22:01:59.299Z,1738792919.299 [Default:CheckIn:D] Stopped
2025-02-05T22:01:59.299Z,1738792919.299 [Default:CheckIn:E] Running Loop=1
2025-02-05T22:01:59.683Z,1738792919.683 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 190.058691 min
2025-02-05T22:01:59.684Z,1738792919.684 [Default:CheckIn:E] Stopped
2025-02-05T22:01:59.684Z,1738792919.684 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-05T22:01:59.684Z,1738792919.684 [Default:CheckIn] Stopped
2025-02-05T22:01:59.684Z,1738792919.684 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-05T22:01:59.684Z,1738792919.684 [Default:CheckIn](INFO): Running loop #20
2025-02-05T22:01:59.684Z,1738792919.684 [Default:CheckIn] Running Loop=20
2025-02-05T22:01:59.684Z,1738792919.684 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-05T22:01:59.684Z,1738792919.684 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-05T22:02:01.692Z,1738792921.692 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220201.00,A,3648.15016,N,12147.22115,W,0.136,336.18,050225,,,D*7F
2025-02-05T22:02:01.694Z,1738792921.694 [NAL9602](INFO): GPS fix at 20250205T220201: (36.802503, -121.787019)
2025-02-05T22:02:01.706Z,1738792921.706 [Default:CheckIn:Read_GPS] Stopped
2025-02-05T22:02:01.706Z,1738792921.706 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-05T22:02:08.851Z,1738792928.851 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250205T184825/Courier0061.lzma
2025-02-05T22:02:09.855Z,1738792929.855 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Courier0061.lzma.bak
2025-02-05T22:02:09.855Z,1738792929.855 [DataOverHttps](INFO): SBD MOMSN=23957511
2025-02-05T22:02:22.707Z,1738792942.707 [NAL9602](INFO): SBD MO Status=0, MOMSN=12482, MT Status=0, MTMSN=0
2025-02-05T22:02:22.707Z,1738792942.707 [NAL9602](INFO): No messages in MT queue
2025-02-05T22:02:22.707Z,1738792942.707 [NAL9602](INFO): Failure count cleared after critical for NAL9602
2025-02-05T22:02:26.080Z,1738792946.080 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20250205T184825/Express0062.lzma
2025-02-05T22:02:27.082Z,1738792947.082 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Express0062.lzma.bak
2025-02-05T22:02:27.082Z,1738792947.082 [DataOverHttps](INFO): SBD MOMSN=23957514
2025-02-05T22:02:28.367Z,1738792948.367 [Default:CheckIn:Read_Iridium] Stopped
2025-02-05T22:02:28.367Z,1738792948.367 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-05T22:02:28.367Z,1738792948.367 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-05T22:02:53.425Z,1738792973.425 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-05T22:07:29.036Z,1738793249.036 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-05T22:07:29.037Z,1738793249.037 [Default:CheckIn:C.Wait] Stopped
2025-02-05T22:07:29.037Z,1738793249.037 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-05T22:07:29.037Z,1738793249.037 [Default:CheckIn:D] Running Loop=1
2025-02-05T22:07:29.444Z,1738793249.444 [Default:CheckIn:D] Stopped
2025-02-05T22:07:29.444Z,1738793249.444 [Default:CheckIn:E] Running Loop=1
2025-02-05T22:07:29.851Z,1738793249.851 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 195.561084 min
2025-02-05T22:07:29.852Z,1738793249.852 [Default:CheckIn:E] Stopped
2025-02-05T22:07:29.852Z,1738793249.852 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-05T22:07:29.852Z,1738793249.852 [Default:CheckIn] Stopped
2025-02-05T22:07:29.852Z,1738793249.852 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-05T22:07:29.852Z,1738793249.852 [Default:CheckIn](INFO): Running loop #21
2025-02-05T22:07:29.852Z,1738793249.852 [Default:CheckIn] Running Loop=21
2025-02-05T22:07:29.852Z,1738793249.852 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-05T22:07:29.853Z,1738793249.853 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-05T22:07:31.863Z,1738793251.863 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220731.00,A,3648.15013,N,12147.22492,W,0.039,336.18,050225,,,D*78
2025-02-05T22:07:31.866Z,1738793251.866 [NAL9602](INFO): GPS fix at 20250205T220731: (36.802502, -121.787082)
2025-02-05T22:07:31.876Z,1738793251.876 [Default:CheckIn:Read_GPS] Stopped
2025-02-05T22:07:31.877Z,1738793251.877 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-05T22:07:39.272Z,1738793259.272 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250205T184825/Courier0064.lzma
2025-02-05T22:07:40.274Z,1738793260.274 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Courier0064.lzma.bak
2025-02-05T22:07:40.274Z,1738793260.274 [DataOverHttps](INFO): SBD MOMSN=23957518
2025-02-05T22:07:48.091Z,1738793268.091 [NAL9602](INFO): SBD MO Status=0, MOMSN=12483, MT Status=0, MTMSN=0
2025-02-05T22:07:48.091Z,1738793268.091 [NAL9602](INFO): No messages in MT queue
2025-02-05T22:07:56.144Z,1738793276.144 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20250205T184825/Express0065.lzma
2025-02-05T22:07:57.146Z,1738793277.146 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Express0065.lzma.bak
2025-02-05T22:07:57.146Z,1738793277.146 [DataOverHttps](INFO): SBD MOMSN=23957521
2025-02-05T22:07:58.541Z,1738793278.541 [Default:CheckIn:Read_Iridium] Stopped
2025-02-05T22:07:58.541Z,1738793278.541 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-05T22:07:58.541Z,1738793278.541 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-05T22:08:18.718Z,1738793298.718 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-05T22:12:59.216Z,1738793579.216 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-05T22:12:59.216Z,1738793579.216 [Default:CheckIn:C.Wait] Stopped
2025-02-05T22:12:59.216Z,1738793579.216 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-05T22:12:59.216Z,1738793579.216 [Default:CheckIn:D] Running Loop=1
2025-02-05T22:12:59.630Z,1738793579.630 [Default:CheckIn:D] Stopped
2025-02-05T22:12:59.630Z,1738793579.630 [Default:CheckIn:E] Running Loop=1
2025-02-05T22:13:00.027Z,1738793580.027 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 201.064193 min
2025-02-05T22:13:00.027Z,1738793580.027 [Default:CheckIn:E] Stopped
2025-02-05T22:13:00.028Z,1738793580.028 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-05T22:13:00.028Z,1738793580.028 [Default:CheckIn] Stopped
2025-02-05T22:13:00.028Z,1738793580.028 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-05T22:13:00.028Z,1738793580.028 [Default:CheckIn](INFO): Running loop #22
2025-02-05T22:13:00.028Z,1738793580.028 [Default:CheckIn] Running Loop=22
2025-02-05T22:13:00.028Z,1738793580.028 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-05T22:13:00.028Z,1738793580.028 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-05T22:13:02.039Z,1738793582.039 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221301.00,A,3648.15123,N,12147.22493,W,0.097,336.18,050225,,,D*79
2025-02-05T22:13:02.042Z,1738793582.042 [NAL9602](INFO): GPS fix at 20250205T221301: (36.802520, -121.787082)
2025-02-05T22:13:02.124Z,1738793582.124 [Default:CheckIn:Read_GPS] Stopped
2025-02-05T22:13:02.124Z,1738793582.124 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-05T22:13:12.587Z,1738793592.587 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250205T184825/Courier0067.lzma
2025-02-05T22:13:13.590Z,1738793593.590 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Courier0067.lzma.bak
2025-02-05T22:13:13.590Z,1738793593.590 [DataOverHttps](INFO): SBD MOMSN=23957525
2025-02-05T22:13:24.731Z,1738793604.731 [NAL9602](INFO): SBD MO Status=2, MOMSN=12484, MT Status=2, MTMSN=0
2025-02-05T22:13:24.731Z,1738793604.731 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-05T22:13:29.912Z,1738793609.912 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20250205T184825/Express0068.lzma
2025-02-05T22:13:30.914Z,1738793610.914 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Express0068.lzma.bak
2025-02-05T22:13:30.914Z,1738793610.914 [DataOverHttps](INFO): SBD MOMSN=23957528
2025-02-05T22:13:32.009Z,1738793612.009 [Default:CheckIn:Read_Iridium] Stopped
2025-02-05T22:13:32.011Z,1738793612.011 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-05T22:13:32.011Z,1738793612.011 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-05T22:15:52.259Z,1738793752.259 [NAL9602](INFO): SBD MO Status=0, MOMSN=12484, MT Status=0, MTMSN=0
2025-02-05T22:15:52.259Z,1738793752.259 [NAL9602](INFO): No messages in MT queue
2025-02-05T22:16:22.949Z,1738793782.949 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-05T22:18:32.651Z,1738793912.651 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-05T22:18:32.651Z,1738793912.651 [Default:CheckIn:C.Wait] Stopped
2025-02-05T22:18:32.652Z,1738793912.652 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-05T22:18:32.652Z,1738793912.652 [Default:CheckIn:D] Running Loop=1
2025-02-05T22:18:33.059Z,1738793913.059 [Default:CheckIn:D] Stopped
2025-02-05T22:18:33.059Z,1738793913.059 [Default:CheckIn:E] Running Loop=1
2025-02-05T22:18:33.467Z,1738793913.467 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 206.621354 min
2025-02-05T22:18:33.467Z,1738793913.467 [Default:CheckIn:E] Stopped
2025-02-05T22:18:33.467Z,1738793913.467 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-05T22:18:33.467Z,1738793913.467 [Default:CheckIn] Stopped
2025-02-05T22:18:33.467Z,1738793913.467 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-05T22:18:33.468Z,1738793913.468 [Default:CheckIn](INFO): Running loop #23
2025-02-05T22:18:33.468Z,1738793913.468 [Default:CheckIn] Running Loop=23
2025-02-05T22:18:33.468Z,1738793913.468 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-05T22:18:33.468Z,1738793913.468 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-05T22:18:35.475Z,1738793915.475 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221834.00,A,3648.15153,N,12147.22274,W,0.136,336.18,050225,,,D*76
2025-02-05T22:18:35.477Z,1738793915.477 [NAL9602](INFO): GPS fix at 20250205T221834: (36.802526, -121.787046)
2025-02-05T22:18:35.514Z,1738793915.514 [Default:CheckIn:Read_GPS] Stopped
2025-02-05T22:18:35.514Z,1738793915.514 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-05T22:18:43.052Z,1738793923.052 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250205T184825/Courier0070.lzma
2025-02-05T22:18:44.055Z,1738793924.055 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Courier0070.lzma.bak
2025-02-05T22:18:44.055Z,1738793924.055 [DataOverHttps](INFO): SBD MOMSN=23957532
2025-02-05T22:18:50.106Z,1738793930.106 [NAL9602](INFO): SBD MO Status=0, MOMSN=12485, MT Status=0, MTMSN=0
2025-02-05T22:18:50.106Z,1738793930.106 [NAL9602](INFO): No messages in MT queue
2025-02-05T22:19:00.256Z,1738793940.256 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20250205T184825/Express0071.lzma
2025-02-05T22:19:01.258Z,1738793941.258 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Express0071.lzma.bak
2025-02-05T22:19:01.258Z,1738793941.258 [DataOverHttps](INFO): SBD MOMSN=23957536
2025-02-05T22:19:02.295Z,1738793942.295 [Default:CheckIn:Read_Iridium] Stopped
2025-02-05T22:19:02.295Z,1738793942.295 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-05T22:19:02.295Z,1738793942.295 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-05T22:19:20.773Z,1738793960.773 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-05T22:21:51.901Z,1738794111.901 [BPC1](FAULT): Battery stick #62 (s/n: 00CC) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-02-05T22:21:51.902Z,1738794111.902 [BPC1](INFO): Calculating totals. Valid battery stick count: 27. Valid reserve battery stick count: 3.
2025-02-05T22:21:51.905Z,1738794111.905 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2025-02-05T22:24:02.827Z,1738794242.827 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-05T22:24:02.827Z,1738794242.827 [Default:CheckIn:C.Wait] Stopped
2025-02-05T22:24:02.827Z,1738794242.827 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-05T22:24:02.827Z,1738794242.827 [Default:CheckIn:D] Running Loop=1
2025-02-05T22:24:03.235Z,1738794243.235 [Default:CheckIn:D] Stopped
2025-02-05T22:24:03.235Z,1738794243.235 [Default:CheckIn:E] Running Loop=1
2025-02-05T22:24:03.643Z,1738794243.643 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 212.124284 min
2025-02-05T22:24:03.643Z,1738794243.643 [Default:CheckIn:E] Stopped
2025-02-05T22:24:03.644Z,1738794243.644 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-05T22:24:03.644Z,1738794243.644 [Default:CheckIn] Stopped
2025-02-05T22:24:03.644Z,1738794243.644 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-05T22:24:03.644Z,1738794243.644 [Default:CheckIn](INFO): Running loop #24
2025-02-05T22:24:03.644Z,1738794243.644 [Default:CheckIn] Running Loop=24
2025-02-05T22:24:03.644Z,1738794243.644 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-05T22:24:03.644Z,1738794243.644 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-05T22:24:05.647Z,1738794245.647 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222404.00,A,3648.15042,N,12147.22198,W,0.117,0.00,050225,,,D*76
2025-02-05T22:24:05.649Z,1738794245.649 [NAL9602](INFO): GPS fix at 20250205T222404: (36.802507, -121.787033)
2025-02-05T22:24:05.680Z,1738794245.680 [Default:CheckIn:Read_GPS] Stopped
2025-02-05T22:24:05.681Z,1738794245.681 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-05T22:24:13.595Z,1738794253.595 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20250205T184825/Courier0073.lzma
2025-02-05T22:24:14.598Z,1738794254.598 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Courier0073.lzma.bak
2025-02-05T22:24:14.598Z,1738794254.598 [DataOverHttps](INFO): SBD MOMSN=23957539
2025-02-05T22:24:30.868Z,1738794270.868 [DataOverHttps](INFO): Sending 237 bytes from file Logs/20250205T184825/Express0074.lzma
2025-02-05T22:24:31.870Z,1738794271.870 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Express0074.lzma.bak
2025-02-05T22:24:31.870Z,1738794271.870 [DataOverHttps](INFO): SBD MOMSN=23957542
2025-02-05T22:24:33.127Z,1738794273.127 [Default:CheckIn:Read_Iridium] Stopped
2025-02-05T22:24:33.127Z,1738794273.127 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-05T22:24:33.127Z,1738794273.127 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-05T22:26:02.850Z,1738794362.850 [NAL9602](INFO): SBD MO Status=0, MOMSN=12486, MT Status=0, MTMSN=0
2025-02-05T22:26:02.850Z,1738794362.850 [NAL9602](INFO): No messages in MT queue
2025-02-05T22:26:33.560Z,1738794393.560 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-05T22:29:33.846Z,1738794573.846 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-05T22:29:33.846Z,1738794573.846 [Default:CheckIn:C.Wait] Stopped
2025-02-05T22:29:33.847Z,1738794573.847 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-05T22:29:33.847Z,1738794573.847 [Default:CheckIn:D] Running Loop=1
2025-02-05T22:29:34.255Z,1738794574.255 [Default:CheckIn:D] Stopped
2025-02-05T22:29:34.255Z,1738794574.255 [Default:CheckIn:E] Running Loop=1
2025-02-05T22:29:34.672Z,1738794574.672 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 217.641276 min
2025-02-05T22:29:34.672Z,1738794574.672 [Default:CheckIn:E] Stopped
2025-02-05T22:29:34.672Z,1738794574.672 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-05T22:29:34.672Z,1738794574.672 [Default:CheckIn] Stopped
2025-02-05T22:29:34.673Z,1738794574.673 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-05T22:29:34.673Z,1738794574.673 [Default:CheckIn](INFO): Running loop #25
2025-02-05T22:29:34.673Z,1738794574.673 [Default:CheckIn] Running Loop=25
2025-02-05T22:29:34.673Z,1738794574.673 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-05T22:29:34.673Z,1738794574.673 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-05T22:29:36.667Z,1738794576.667 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222935.00,A,3648.15011,N,12147.22138,W,0.039,0.00,050225,,,D*78
2025-02-05T22:29:36.669Z,1738794576.669 [NAL9602](INFO): GPS fix at 20250205T222935: (36.802502, -121.787023)
2025-02-05T22:29:36.682Z,1738794576.682 [Default:CheckIn:Read_GPS] Stopped
2025-02-05T22:29:36.682Z,1738794576.682 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-05T22:29:44.399Z,1738794584.399 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250205T184825/Courier0076.lzma
2025-02-05T22:29:45.402Z,1738794585.402 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Courier0076.lzma.bak
2025-02-05T22:29:45.402Z,1738794585.402 [DataOverHttps](INFO): SBD MOMSN=23957560
2025-02-05T22:29:50.807Z,1738794590.807 [NAL9602](INFO): SBD MO Status=0, MOMSN=12487, MT Status=0, MTMSN=0
2025-02-05T22:29:50.807Z,1738794590.807 [NAL9602](INFO): No messages in MT queue
2025-02-05T22:30:01.368Z,1738794601.368 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20250205T184825/Express0077.lzma
2025-02-05T22:30:02.370Z,1738794602.370 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Express0077.lzma.bak
2025-02-05T22:30:02.370Z,1738794602.370 [DataOverHttps](INFO): SBD MOMSN=23957564
2025-02-05T22:30:03.760Z,1738794603.760 [Default:CheckIn:Read_Iridium] Stopped
2025-02-05T22:30:03.760Z,1738794603.760 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-05T22:30:03.760Z,1738794603.760 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-05T22:30:21.518Z,1738794621.518 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-05T22:35:04.372Z,1738794904.372 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-05T22:35:04.372Z,1738794904.372 [Default:CheckIn:C.Wait] Stopped
2025-02-05T22:35:04.372Z,1738794904.372 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-05T22:35:04.372Z,1738794904.372 [Default:CheckIn:D] Running Loop=1
2025-02-05T22:35:04.783Z,1738794904.783 [Default:CheckIn:D] Stopped
2025-02-05T22:35:04.783Z,1738794904.783 [Default:CheckIn:E] Running Loop=1
2025-02-05T22:35:05.175Z,1738794905.175 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 223.150081 min
2025-02-05T22:35:05.175Z,1738794905.175 [Default:CheckIn:E] Stopped
2025-02-05T22:35:05.176Z,1738794905.176 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-05T22:35:05.176Z,1738794905.176 [Default:CheckIn] Stopped
2025-02-05T22:35:05.176Z,1738794905.176 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-05T22:35:05.176Z,1738794905.176 [Default:CheckIn](INFO): Running loop #26
2025-02-05T22:35:05.176Z,1738794905.176 [Default:CheckIn] Running Loop=26
2025-02-05T22:35:05.176Z,1738794905.176 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-05T22:35:05.176Z,1738794905.176 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-05T22:35:07.179Z,1738794907.179 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,223506.00,A,3648.15000,N,12147.22268,W,0.117,0.00,050225,,,D*7E
2025-02-05T22:35:07.181Z,1738794907.181 [NAL9602](INFO): GPS fix at 20250205T223506: (36.802500, -121.787045)
2025-02-05T22:35:07.233Z,1738794907.233 [Default:CheckIn:Read_GPS] Stopped
2025-02-05T22:35:07.233Z,1738794907.233 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-05T22:35:15.235Z,1738794915.235 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250205T184825/Courier0079.lzma
2025-02-05T22:35:16.245Z,1738794916.245 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Courier0079.lzma.bak
2025-02-05T22:35:16.245Z,1738794916.245 [DataOverHttps](INFO): SBD MOMSN=23957573
2025-02-05T22:35:33.063Z,1738794933.063 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20250205T184825/Express0080.lzma
2025-02-05T22:35:33.929Z,1738794933.929 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Express0080.lzma.bak
2025-02-05T22:35:33.929Z,1738794933.929 [DataOverHttps](INFO): SBD MOMSN=23957576
2025-02-05T22:35:34.678Z,1738794934.678 [Default:CheckIn:Read_Iridium] Stopped
2025-02-05T22:35:34.678Z,1738794934.678 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-05T22:35:34.679Z,1738794934.679 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-05T22:36:13.843Z,1738794973.843 [NAL9602](INFO): SBD MO Status=0, MOMSN=12488, MT Status=0, MTMSN=0
2025-02-05T22:36:13.843Z,1738794973.843 [NAL9602](INFO): No messages in MT queue
2025-02-05T22:36:44.560Z,1738795004.560 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-05T22:40:35.323Z,1738795235.323 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-05T22:40:35.324Z,1738795235.324 [Default:CheckIn:C.Wait] Stopped
2025-02-05T22:40:35.324Z,1738795235.324 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-05T22:40:35.324Z,1738795235.324 [Default:CheckIn:D] Running Loop=1
2025-02-05T22:40:35.733Z,1738795235.733 [Default:CheckIn:D] Stopped
2025-02-05T22:40:35.733Z,1738795235.733 [Default:CheckIn:E] Running Loop=1
2025-02-05T22:40:36.129Z,1738795236.129 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 228.665918 min
2025-02-05T22:40:36.129Z,1738795236.129 [Default:CheckIn:E] Stopped
2025-02-05T22:40:36.129Z,1738795236.129 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-05T22:40:36.129Z,1738795236.129 [Default:CheckIn] Stopped
2025-02-05T22:40:36.129Z,1738795236.129 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-05T22:40:36.129Z,1738795236.129 [Default:CheckIn](INFO): Running loop #27
2025-02-05T22:40:36.129Z,1738795236.129 [Default:CheckIn] Running Loop=27
2025-02-05T22:40:36.130Z,1738795236.130 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-05T22:40:36.130Z,1738795236.130 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-05T22:40:38.141Z,1738795238.141 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224037.00,A,3648.15037,N,12147.22293,W,0.039,0.00,050225,,,D*73
2025-02-05T22:40:38.143Z,1738795238.143 [NAL9602](INFO): GPS fix at 20250205T224037: (36.802506, -121.787049)
2025-02-05T22:40:38.154Z,1738795238.154 [Default:CheckIn:Read_GPS] Stopped
2025-02-05T22:40:38.154Z,1738795238.154 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-05T22:40:45.703Z,1738795245.703 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250205T184825/Courier0082.lzma
2025-02-05T22:40:46.706Z,1738795246.706 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Courier0082.lzma.bak
2025-02-05T22:40:46.706Z,1738795246.706 [DataOverHttps](INFO): SBD MOMSN=23957580
2025-02-05T22:41:03.752Z,1738795263.752 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20250205T184825/Express0083.lzma
2025-02-05T22:41:04.754Z,1738795264.754 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Express0083.lzma.bak
2025-02-05T22:41:04.754Z,1738795264.754 [DataOverHttps](INFO): SBD MOMSN=23957583
2025-02-05T22:41:06.039Z,1738795266.039 [Default:CheckIn:Read_Iridium] Stopped
2025-02-05T22:41:06.039Z,1738795266.039 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-05T22:41:06.040Z,1738795266.040 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-05T22:41:23.459Z,1738795283.459 [NAL9602](INFO): SBD MO Status=2, MOMSN=12489, MT Status=2, MTMSN=0
2025-02-05T22:41:23.459Z,1738795283.459 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-05T22:41:40.847Z,1738795300.847 [NAL9602](INFO): SBD MO Status=0, MOMSN=12489, MT Status=0, MTMSN=0
2025-02-05T22:41:40.847Z,1738795300.847 [NAL9602](INFO): No messages in MT queue
2025-02-05T22:42:11.559Z,1738795331.559 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-05T22:46:06.823Z,1738795566.823 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-05T22:46:06.823Z,1738795566.823 [Default:CheckIn:C.Wait] Stopped
2025-02-05T22:46:06.823Z,1738795566.823 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-05T22:46:06.823Z,1738795566.823 [Default:CheckIn:D] Running Loop=1
2025-02-05T22:46:07.223Z,1738795567.223 [Default:CheckIn:D] Stopped
2025-02-05T22:46:07.223Z,1738795567.223 [Default:CheckIn:E] Running Loop=1
2025-02-05T22:46:07.627Z,1738795567.627 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 234.190755 min
2025-02-05T22:46:07.627Z,1738795567.627 [Default:CheckIn:E] Stopped
2025-02-05T22:46:07.627Z,1738795567.627 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-05T22:46:07.627Z,1738795567.627 [Default:CheckIn] Stopped
2025-02-05T22:46:07.627Z,1738795567.627 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-05T22:46:07.628Z,1738795567.628 [Default:CheckIn](INFO): Running loop #28
2025-02-05T22:46:07.628Z,1738795567.628 [Default:CheckIn] Running Loop=28
2025-02-05T22:46:07.628Z,1738795567.628 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-05T22:46:07.628Z,1738795567.628 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-05T22:46:09.643Z,1738795569.643 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,224608.00,A,3648.15031,N,12147.22365,W,0.058,0.00,050225,,,D*70
2025-02-05T22:46:09.645Z,1738795569.645 [NAL9602](INFO): GPS fix at 20250205T224608: (36.802505, -121.787061)
2025-02-05T22:46:09.656Z,1738795569.656 [Default:CheckIn:Read_GPS] Stopped
2025-02-05T22:46:09.656Z,1738795569.656 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-05T22:46:17.228Z,1738795577.228 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20250205T184825/Courier0085.lzma
2025-02-05T22:46:18.230Z,1738795578.230 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Courier0085.lzma.bak
2025-02-05T22:46:18.230Z,1738795578.230 [DataOverHttps](INFO): SBD MOMSN=23957587
2025-02-05T22:46:34.188Z,1738795594.188 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20250205T184825/Express0086.lzma
2025-02-05T22:46:35.191Z,1738795595.191 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Express0086.lzma.bak
2025-02-05T22:46:35.191Z,1738795595.191 [DataOverHttps](INFO): SBD MOMSN=23957590
2025-02-05T22:46:36.311Z,1738795596.311 [Default:CheckIn:Read_Iridium] Stopped
2025-02-05T22:46:36.311Z,1738795596.311 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-05T22:46:36.312Z,1738795596.312 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-05T22:47:18.742Z,1738795638.742 [NAL9602](INFO): SBD MO Status=2, MOMSN=12490, MT Status=2, MTMSN=0
2025-02-05T22:47:18.743Z,1738795638.743 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-05T22:47:53.079Z,1738795673.079 [NAL9602](INFO): SBD MO Status=0, MOMSN=12490, MT Status=0, MTMSN=0
2025-02-05T22:47:53.079Z,1738795673.079 [NAL9602](INFO): No messages in MT queue
2025-02-05T22:48:23.781Z,1738795703.781 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-05T22:51:37.018Z,1738795897.018 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-05T22:51:37.018Z,1738795897.018 [Default:CheckIn:C.Wait] Stopped
2025-02-05T22:51:37.018Z,1738795897.018 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-05T22:51:37.018Z,1738795897.018 [Default:CheckIn:D] Running Loop=1
2025-02-05T22:51:37.407Z,1738795897.407 [Default:CheckIn:D] Stopped
2025-02-05T22:51:37.407Z,1738795897.407 [Default:CheckIn:E] Running Loop=1
2025-02-05T22:51:37.809Z,1738795897.809 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 239.693799 min
2025-02-05T22:51:37.809Z,1738795897.809 [Default:CheckIn:E] Stopped
2025-02-05T22:51:37.809Z,1738795897.809 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-05T22:51:37.809Z,1738795897.809 [Default:CheckIn] Stopped
2025-02-05T22:51:37.809Z,1738795897.809 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-05T22:51:37.810Z,1738795897.810 [Default:CheckIn](INFO): Running loop #29
2025-02-05T22:51:37.810Z,1738795897.810 [Default:CheckIn] Running Loop=29
2025-02-05T22:51:37.810Z,1738795897.810 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-05T22:51:37.810Z,1738795897.810 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-05T22:51:39.819Z,1738795899.819 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225139.00,A,3648.15004,N,12147.22253,W,0.000,0.00,050225,,,D*7B
2025-02-05T22:51:39.830Z,1738795899.830 [NAL9602](INFO): GPS fix at 20250205T225139: (36.802501, -121.787042)
2025-02-05T22:51:39.841Z,1738795899.841 [Default:CheckIn:Read_GPS] Stopped
2025-02-05T22:51:39.841Z,1738795899.841 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-05T22:51:47.832Z,1738795907.832 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250205T184825/Courier0088.lzma
2025-02-05T22:51:48.834Z,1738795908.834 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Courier0088.lzma.bak
2025-02-05T22:51:48.834Z,1738795908.834 [DataOverHttps](INFO): SBD MOMSN=23957605
2025-02-05T22:52:05.292Z,1738795925.292 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20250205T184825/Express0089.lzma
2025-02-05T22:52:06.294Z,1738795926.294 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Express0089.lzma.bak
2025-02-05T22:52:06.294Z,1738795926.294 [DataOverHttps](INFO): SBD MOMSN=23957608
2025-02-05T22:52:08.121Z,1738795928.121 [Default:CheckIn:Read_Iridium] Stopped
2025-02-05T22:52:08.121Z,1738795928.121 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-05T22:52:08.121Z,1738795928.121 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-05T22:55:41.471Z,1738796141.471 [NAL9602](INFO): SBD MO Status=2, MOMSN=12491, MT Status=2, MTMSN=0
2025-02-05T22:55:41.471Z,1738796141.471 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-05T22:56:42.462Z,1738796202.462 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-02-05T22:57:08.743Z,1738796228.743 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-05T22:57:08.743Z,1738796228.743 [Default:CheckIn:C.Wait] Stopped
2025-02-05T22:57:08.743Z,1738796228.743 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-05T22:57:08.743Z,1738796228.743 [Default:CheckIn:D] Running Loop=1
2025-02-05T22:57:09.159Z,1738796229.159 [Default:CheckIn:D] Stopped
2025-02-05T22:57:09.159Z,1738796229.159 [Default:CheckIn:E] Running Loop=1
2025-02-05T22:57:09.571Z,1738796229.571 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 245.223014 min
2025-02-05T22:57:09.571Z,1738796229.571 [Default:CheckIn:E] Stopped
2025-02-05T22:57:09.571Z,1738796229.571 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-05T22:57:09.572Z,1738796229.572 [Default:CheckIn] Stopped
2025-02-05T22:57:09.572Z,1738796229.572 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-05T22:57:09.572Z,1738796229.572 [Default:CheckIn](INFO): Running loop #30
2025-02-05T22:57:09.572Z,1738796229.572 [Default:CheckIn] Running Loop=30
2025-02-05T22:57:09.572Z,1738796229.572 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-05T22:57:09.572Z,1738796229.572 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-05T22:57:11.562Z,1738796231.562 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,225710.00,A,3648.15001,N,12147.22299,W,0.019,0.00,050225,,,D*7D
2025-02-05T22:57:11.564Z,1738796231.564 [NAL9602](INFO): GPS fix at 20250205T225710: (36.802500, -121.787050)
2025-02-05T22:57:11.575Z,1738796231.575 [Default:CheckIn:Read_GPS] Stopped
2025-02-05T22:57:11.575Z,1738796231.575 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-05T22:57:19.156Z,1738796239.156 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250205T184825/Courier0091.lzma
2025-02-05T22:57:20.159Z,1738796240.159 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Courier0091.lzma.bak
2025-02-05T22:57:20.160Z,1738796240.160 [DataOverHttps](INFO): SBD MOMSN=23957611
2025-02-05T22:57:36.228Z,1738796256.228 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20250205T184825/Express0092.lzma
2025-02-05T22:57:38.877Z,1738796258.877 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Express0092.lzma.bak
2025-02-05T22:57:38.877Z,1738796258.877 [DataOverHttps](INFO): SBD MOMSN=23957614
2025-02-05T22:57:39.035Z,1738796259.035 [Default:CheckIn:Read_Iridium] Stopped
2025-02-05T22:57:39.035Z,1738796259.035 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-05T22:57:39.035Z,1738796259.035 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-05T22:57:43.469Z,1738796263.469 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-05T23:02:39.635Z,1738796559.635 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-05T23:02:39.635Z,1738796559.635 [Default:CheckIn:C.Wait] Stopped
2025-02-05T23:02:39.635Z,1738796559.635 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-05T23:02:39.635Z,1738796559.635 [Default:CheckIn:D] Running Loop=1
2025-02-05T23:02:40.040Z,1738796560.040 [Default:CheckIn:D] Stopped
2025-02-05T23:02:40.040Z,1738796560.040 [Default:CheckIn:E] Running Loop=1
2025-02-05T23:02:40.445Z,1738796560.445 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 250.737695 min
2025-02-05T23:02:40.445Z,1738796560.445 [Default:CheckIn:E] Stopped
2025-02-05T23:02:40.445Z,1738796560.445 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-05T23:02:40.445Z,1738796560.445 [Default:CheckIn] Stopped
2025-02-05T23:02:40.445Z,1738796560.445 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-05T23:02:40.445Z,1738796560.445 [Default:CheckIn](INFO): Running loop #31
2025-02-05T23:02:40.446Z,1738796560.446 [Default:CheckIn] Running Loop=31
2025-02-05T23:02:40.446Z,1738796560.446 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-05T23:02:40.446Z,1738796560.446 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-05T23:02:42.459Z,1738796562.459 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230241.00,A,3648.14894,N,12147.22307,W,0.078,0.00,050225,,,D*7C
2025-02-05T23:02:42.462Z,1738796562.462 [NAL9602](INFO): GPS fix at 20250205T230241: (36.802482, -121.787051)
2025-02-05T23:02:42.473Z,1738796562.473 [Default:CheckIn:Read_GPS] Stopped
2025-02-05T23:02:42.473Z,1738796562.473 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-05T23:02:49.832Z,1738796569.832 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250205T184825/Courier0094.lzma
2025-02-05T23:02:50.834Z,1738796570.834 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Courier0094.lzma.bak
2025-02-05T23:02:50.834Z,1738796570.834 [DataOverHttps](INFO): SBD MOMSN=23957618
2025-02-05T23:03:09.736Z,1738796589.736 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20250205T184825/Express0095.lzma
2025-02-05T23:03:10.738Z,1738796590.738 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Express0095.lzma.bak
2025-02-05T23:03:10.738Z,1738796590.738 [DataOverHttps](INFO): SBD MOMSN=23957621
2025-02-05T23:03:12.007Z,1738796592.007 [Default:CheckIn:Read_Iridium] Stopped
2025-02-05T23:03:12.007Z,1738796592.007 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-05T23:03:12.008Z,1738796592.008 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-05T23:03:13.201Z,1738796593.201 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-02-05T23:03:13.284Z,1738796593.284 [NAL9602](ERROR): received:
+CSQ:0
OK491, 2, 0, 0, 0
OK
2025-02-05T23:04:47.339Z,1738796687.339 [NAL9602](INFO): SBD MO Status=2, MOMSN=12491, MT Status=2, MTMSN=0
2025-02-05T23:04:47.339Z,1738796687.339 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-05T23:04:51.478Z,1738796691.478 [DataOverHttps](IMPORTANT): SBD MTMSN=20250205T230450
2025-02-05T23:04:58.985Z,1738796698.985 [DataOverHttps](INFO): Received command: ibit
2025-02-05T23:04:59.033Z,1738796699.033 [CommandExec](IMPORTANT): got command ibit
2025-02-05T23:04:59.075Z,1738796699.075 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-02-05T23:04:59.075Z,1738796699.075 [IBIT](IMPORTANT): Beginning control surface checks.
2025-02-05T23:04:59.079Z,1738796699.079 [CBIT](IMPORTANT): Beginning ground fault scan
2025-02-05T23:05:01.882Z,1738796701.882 [NAL9602](INFO): SBD MO Status=2, MOMSN=12491, MT Status=2, MTMSN=0
2025-02-05T23:05:01.882Z,1738796701.882 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-05T23:05:10.039Z,1738796710.039 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.000504
CHAN A1 (24V): -0.000964
CHAN A2 (12V): 0.000483
CHAN A3 (5V): -0.002455
CHAN B0 (3.3V): -0.001098
CHAN B1 (3.15aV): -0.001628
CHAN B2 (3.15bV): -0.001640
CHAN B3 (GND): -0.002298
OPEN: 0.004899
Full Scale: +/- 1 mA
2025-02-05T23:05:54.187Z,1738796754.187 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 4
Latitude: 36.802483 Longitude: -121.787048
2025-02-05T23:05:54.583Z,1738796754.583 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 13.717000
2025-02-05T23:05:54.583Z,1738796754.583 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2025-02-05T23:05:54.583Z,1738796754.583 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2025-02-05T23:05:54.998Z,1738796754.998 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2025-02-05T23:05:54.998Z,1738796754.998 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2025-02-05T23:05:54.999Z,1738796754.999 [IBIT](IMPORTANT): Pressure:9.797086 PSI
2025-02-05T23:05:54.999Z,1738796754.999 [IBIT](IMPORTANT): Humidity:11.189110 %
2025-02-05T23:05:55.394Z,1738796755.394 [IBIT](IMPORTANT): Vehicle Pitch:-1.250078 degrees
2025-02-05T23:05:55.394Z,1738796755.394 [IBIT](IMPORTANT): Vehicle Roll:7.558716 degrees
2025-02-05T23:05:55.395Z,1738796755.395 [IBIT](IMPORTANT): Vehicle Heading:199.954346 degrees
2025-02-05T23:05:55.826Z,1738796755.826 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2025-02-05T23:05:55.826Z,1738796755.826 [IBIT](IMPORTANT): buoyancyNeutral: 424.293091 cc
2025-02-05T23:05:55.826Z,1738796755.826 [IBIT](IMPORTANT): massDefault: 0.670874 cm
2025-02-05T23:05:55.827Z,1738796755.827 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2025-02-05T23:05:55.827Z,1738796755.827 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2025-02-05T23:05:55.827Z,1738796755.827 [IBIT](IMPORTANT): IBIT PASSED
2025-02-05T23:06:01.714Z,1738796761.714 [NAL9602](INFO): SBD MO Status=0, MOMSN=12491, MT Status=0, MTMSN=0
2025-02-05T23:06:01.714Z,1738796761.714 [NAL9602](INFO): No messages in MT queue
2025-02-05T23:06:32.397Z,1738796792.397 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-05T23:08:12.607Z,1738796892.607 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-05T23:08:12.607Z,1738796892.607 [Default:CheckIn:C.Wait] Stopped
2025-02-05T23:08:12.607Z,1738796892.607 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-05T23:08:12.607Z,1738796892.607 [Default:CheckIn:D] Running Loop=1
2025-02-05T23:08:13.021Z,1738796893.021 [Default:CheckIn:D] Stopped
2025-02-05T23:08:13.021Z,1738796893.021 [Default:CheckIn:E] Running Loop=1
2025-02-05T23:08:13.416Z,1738796893.416 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 256.287386 min
2025-02-05T23:08:13.416Z,1738796893.416 [Default:CheckIn:E] Stopped
2025-02-05T23:08:13.416Z,1738796893.416 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-05T23:08:13.416Z,1738796893.416 [Default:CheckIn] Stopped
2025-02-05T23:08:13.417Z,1738796893.417 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-05T23:08:13.417Z,1738796893.417 [Default:CheckIn](INFO): Running loop #32
2025-02-05T23:08:13.417Z,1738796893.417 [Default:CheckIn] Running Loop=32
2025-02-05T23:08:13.417Z,1738796893.417 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-05T23:08:13.417Z,1738796893.417 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-05T23:08:15.423Z,1738796895.423 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,230814.00,A,3648.15066,N,12147.22536,W,0.058,0.00,050225,,,D*74
2025-02-05T23:08:15.425Z,1738796895.425 [NAL9602](INFO): GPS fix at 20250205T230814: (36.802511, -121.787089)
2025-02-05T23:08:15.459Z,1738796895.459 [Default:CheckIn:Read_GPS] Stopped
2025-02-05T23:08:15.459Z,1738796895.459 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-05T23:08:23.387Z,1738796903.387 [DataOverHttps](INFO): Sending 74 bytes from file Logs/20250205T184825/Courier0097.lzma
2025-02-05T23:08:24.390Z,1738796904.390 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Courier0097.lzma.bak
2025-02-05T23:08:24.390Z,1738796904.390 [DataOverHttps](INFO): SBD MOMSN=23957627
2025-02-05T23:08:32.391Z,1738796912.391 [NAL9602](INFO): SBD MO Status=0, MOMSN=12492, MT Status=0, MTMSN=0
2025-02-05T23:08:32.391Z,1738796912.391 [NAL9602](INFO): No messages in MT queue
2025-02-05T23:08:40.436Z,1738796920.436 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250205T184825/Courier0100.lzma
2025-02-05T23:08:41.439Z,1738796921.439 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Courier0100.lzma.bak
2025-02-05T23:08:41.439Z,1738796921.439 [DataOverHttps](INFO): SBD MOMSN=23957630
2025-02-05T23:08:57.589Z,1738796937.589 [DataOverHttps](INFO): Sending 814 bytes from file Logs/20250205T184825/Express0098.lzma
2025-02-05T23:08:58.591Z,1738796938.591 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Express0098.lzma.bak
2025-02-05T23:08:58.591Z,1738796938.591 [DataOverHttps](INFO): SBD MOMSN=23957633
2025-02-05T23:09:03.118Z,1738796943.118 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-05T23:09:21.224Z,1738796961.224 [DataOverHttps](INFO): Sending 61 bytes from file Logs/20250205T184825/Express0101.lzma
2025-02-05T23:09:23.230Z,1738796963.230 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Express0101.lzma.bak
2025-02-05T23:09:23.230Z,1738796963.230 [DataOverHttps](INFO): SBD MOMSN=23957659
2025-02-05T23:09:24.619Z,1738796964.619 [Default:CheckIn:Read_Iridium] Stopped
2025-02-05T23:09:24.620Z,1738796964.620 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-05T23:09:24.620Z,1738796964.620 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-05T23:14:25.259Z,1738797265.259 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-05T23:14:25.259Z,1738797265.259 [Default:CheckIn:C.Wait] Stopped
2025-02-05T23:14:25.259Z,1738797265.259 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-05T23:14:25.259Z,1738797265.259 [Default:CheckIn:D] Running Loop=1
2025-02-05T23:14:25.667Z,1738797265.667 [Default:CheckIn:D] Stopped
2025-02-05T23:14:25.667Z,1738797265.667 [Default:CheckIn:E] Running Loop=1
2025-02-05T23:14:26.083Z,1738797266.083 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 262.498145 min
2025-02-05T23:14:26.083Z,1738797266.083 [Default:CheckIn:E] Stopped
2025-02-05T23:14:26.084Z,1738797266.084 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-05T23:14:26.084Z,1738797266.084 [Default:CheckIn] Stopped
2025-02-05T23:14:26.084Z,1738797266.084 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-05T23:14:26.084Z,1738797266.084 [Default:CheckIn](INFO): Running loop #33
2025-02-05T23:14:26.084Z,1738797266.084 [Default:CheckIn] Running Loop=33
2025-02-05T23:14:26.084Z,1738797266.084 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-05T23:14:26.084Z,1738797266.084 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-05T23:14:28.083Z,1738797268.083 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231427.00,A,3648.14947,N,12147.22205,W,0.019,137.56,050225,,,D*76
2025-02-05T23:14:28.085Z,1738797268.085 [NAL9602](INFO): GPS fix at 20250205T231427: (36.802491, -121.787034)
2025-02-05T23:14:28.115Z,1738797268.115 [Default:CheckIn:Read_GPS] Stopped
2025-02-05T23:14:28.115Z,1738797268.115 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-05T23:14:35.887Z,1738797275.887 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250205T184825/Courier0103.lzma
2025-02-05T23:14:36.890Z,1738797276.890 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Courier0103.lzma.bak
2025-02-05T23:14:36.890Z,1738797276.890 [DataOverHttps](INFO): SBD MOMSN=23957661
2025-02-05T23:14:52.884Z,1738797292.884 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20250205T184825/Express0104.lzma
2025-02-05T23:14:53.887Z,1738797293.887 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Express0104.lzma.bak
2025-02-05T23:14:53.887Z,1738797293.887 [DataOverHttps](INFO): SBD MOMSN=23957664
2025-02-05T23:14:55.161Z,1738797295.161 [Default:CheckIn:Read_Iridium] Stopped
2025-02-05T23:14:55.161Z,1738797295.161 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-05T23:14:55.161Z,1738797295.161 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-05T23:15:25.043Z,1738797325.043 [NAL9602](INFO): SBD MO Status=2, MOMSN=12493, MT Status=2, MTMSN=0
2025-02-05T23:15:25.043Z,1738797325.043 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-02-05T23:15:40.397Z,1738797340.397 [NAL9602](INFO): SBD MO Status=0, MOMSN=12493, MT Status=0, MTMSN=0
2025-02-05T23:15:40.397Z,1738797340.397 [NAL9602](INFO): No messages in MT queue
2025-02-05T23:16:11.101Z,1738797371.101 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-05T23:19:55.824Z,1738797595.824 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-05T23:19:55.824Z,1738797595.824 [Default:CheckIn:C.Wait] Stopped
2025-02-05T23:19:55.824Z,1738797595.824 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-05T23:19:55.824Z,1738797595.824 [Default:CheckIn:D] Running Loop=1
2025-02-05T23:19:56.225Z,1738797596.225 [Default:CheckIn:D] Stopped
2025-02-05T23:19:56.225Z,1738797596.225 [Default:CheckIn:E] Running Loop=1
2025-02-05T23:19:56.636Z,1738797596.636 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 268.007438 min
2025-02-05T23:19:56.636Z,1738797596.636 [Default:CheckIn:E] Stopped
2025-02-05T23:19:56.637Z,1738797596.637 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-05T23:19:56.637Z,1738797596.637 [Default:CheckIn] Stopped
2025-02-05T23:19:56.637Z,1738797596.637 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-05T23:19:56.637Z,1738797596.637 [Default:CheckIn](INFO): Running loop #34
2025-02-05T23:19:56.637Z,1738797596.637 [Default:CheckIn] Running Loop=34
2025-02-05T23:19:56.637Z,1738797596.637 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-05T23:19:56.637Z,1738797596.637 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-05T23:19:58.639Z,1738797598.639 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,231957.00,A,3648.35033,N,12147.12206,W,5.190,4.11,050225,,,D*72
2025-02-05T23:19:58.642Z,1738797598.642 [NAL9602](INFO): GPS fix at 20250205T231957: (36.805839, -121.785368)
2025-02-05T23:19:58.668Z,1738797598.668 [Default:CheckIn:Read_GPS] Stopped
2025-02-05T23:19:58.668Z,1738797598.668 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-05T23:20:06.203Z,1738797606.203 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250205T184825/Courier0106.lzma
2025-02-05T23:20:07.973Z,1738797607.973 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Courier0106.lzma.bak
2025-02-05T23:20:07.973Z,1738797607.973 [DataOverHttps](INFO): SBD MOMSN=23957668
2025-02-05T23:20:11.982Z,1738797611.982 [NAL9602](INFO): SBD MO Status=0, MOMSN=12494, MT Status=0, MTMSN=0
2025-02-05T23:20:11.982Z,1738797611.982 [NAL9602](INFO): No messages in MT queue
2025-02-05T23:20:23.224Z,1738797623.224 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20250205T184825/Express0107.lzma
2025-02-05T23:20:24.226Z,1738797624.226 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Express0107.lzma.bak
2025-02-05T23:20:24.226Z,1738797624.226 [DataOverHttps](INFO): SBD MOMSN=23957671
2025-02-05T23:20:25.331Z,1738797625.331 [Default:CheckIn:Read_Iridium] Stopped
2025-02-05T23:20:25.331Z,1738797625.331 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-05T23:20:25.331Z,1738797625.331 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-05T23:20:42.682Z,1738797642.682 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-05T23:21:03.382Z,1738797663.382 [DataOverHttps](IMPORTANT): SBD MTMSN=20250205T232102
2025-02-05T23:21:13.960Z,1738797673.960 [DataOverHttps](INFO): Received command: sched asap "load Transport/transit.tl;set transit.MissionTimeout 30 min;set transit.NeedCommsTime 12 min;set transit.Latitude 36.797 degree;set transit.Longitude -121.847 degree;set transit.Depth 3 m;set transit.MaxDepth 9 m" 1a8uw 1 2
2025-02-05T23:21:14.045Z,1738797674.045 [CommandExec](IMPORTANT): got command schedule asap "load Transport/transit.tl;set transit.MissionTimeout 30 min;set transit.NeedCommsTime 12 min;set transit.Latitude 36.797 degree;set transit.Longitude -121.847 degree;set transit.Depth 3 m;set transit.MaxDepth 9 m" 1a8uw 1 2.000000
2025-02-05T23:21:14.048Z,1738797674.048 [CommandExec](IMPORTANT): Scheduling command #1 of 2 with id=1a8uw
2025-02-05T23:21:14.058Z,1738797674.058 [CommandExec](IMPORTANT): Scheduled #1 (#1 of 2 with id='1a8uw'): "load Transport/transit.tl;set transit.MissionTimeout 30 min;set transit.NeedCommsTime 12 min;set transit.Latitude 36.797 degree;set transit.Longitude -121.847 degree;set transit.Depth 3 m;set transit.MaxDepth 9 m", ASAP
2025-02-05T23:21:14.962Z,1738797674.962 [DataOverHttps](IMPORTANT): SBD MTMSN=20250205T232113
2025-02-05T23:21:22.640Z,1738797682.640 [DataOverHttps](INFO): Received command: sched asap "set transit.MinOffshore 0.0001 km;run" 1a8uw 2 2
2025-02-05T23:21:22.699Z,1738797682.699 [CommandExec](IMPORTANT): got command schedule asap "set transit.MinOffshore 0.0001 km;run" 1a8uw 2 2.000000
2025-02-05T23:21:22.700Z,1738797682.700 [CommandExec](IMPORTANT): Scheduling command #2 of 2 with id=1a8uw
2025-02-05T23:21:22.701Z,1738797682.701 [CommandExec](IMPORTANT): Scheduled #2 (#2 of 2 with id='1a8uw'): "set transit.MinOffshore 0.0001 km;run", ASAP
2025-02-05T23:21:22.818Z,1738797682.818 [CommandExec](IMPORTANT): got command load ./Missions/Transport/transit.tl
2025-02-05T23:21:22.819Z,1738797682.819 [MissionManager](INFO): Loading Mission from file: ./Missions/Transport/transit.tl
2025-02-05T23:21:22.819Z,1738797682.819 [MissionManager](DEBUG): TethyslAPI: loading: ./Missions/Transport/transit.tl
2025-02-05T23:21:22.823Z,1738797682.823 [MissionManager](DEBUG): TethyslAPI: mission file loaded: ./Missions/Transport/transit.tl
2025-02-05T23:21:23.480Z,1738797683.480 [MissionManager](DEBUG): TethyslAPI: ./Missions/Transport/transit.tl translated into:
60
30
NaN
NaN
0
0
10
1
20
1
7
1
2025-02-05T23:21:23.486Z,1738797683.486 [MissionManager](INFO): DefineArg transit.MissionTimeout = 60.000000 min
2025-02-05T23:21:23.489Z,1738797683.489 [MissionManager](INFO): DefineArg transit.NeedCommsTime = 30.000000 min
2025-02-05T23:21:23.566Z,1738797683.566 [MissionManager](INFO): DefineArg transit.Latitude = nan arcdeg
2025-02-05T23:21:23.581Z,1738797683.581 [MissionManager](INFO): DefineArg transit.Longitude = nan arcdeg
2025-02-05T23:21:23.584Z,1738797683.584 [MissionManager](INFO): DefineArg transit.Northings = 0.000000 m
2025-02-05T23:21:23.587Z,1738797683.587 [MissionManager](INFO): DefineArg transit.Eastings = 0.000000 m
2025-02-05T23:21:23.596Z,1738797683.596 [MissionManager](INFO): DefineArg transit.Depth = 10.000000 m
2025-02-05T23:21:23.599Z,1738797683.599 [MissionManager](INFO): DefineArg transit.Speed = 1.000000 m/s
2025-02-05T23:21:23.610Z,1738797683.610 [MissionManager](INFO): DefineArg transit.MaxDepth = 20.000000 m
2025-02-05T23:21:23.614Z,1738797683.614 [MissionManager](INFO): DefineArg transit.MinOffshore = 1.000000 km
2025-02-05T23:21:23.625Z,1738797683.625 [MissionManager](INFO): DefineArg transit.MinAltitude = 7.000000 m
2025-02-05T23:21:23.628Z,1738797683.628 [MissionManager](INFO): DefineArg transit.MassHold = 1 bool
2025-02-05T23:21:23.631Z,1738797683.631 [MissionManager](INFO): DefineArg transit.BuoyancyHold = 1 bool
2025-02-05T23:21:23.631Z,1738797683.631 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/NeedComms.tl
2025-02-05T23:21:23.631Z,1738797683.631 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/NeedComms.tl
2025-02-05T23:21:23.649Z,1738797683.649 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/NeedComms.tl
2025-02-05T23:21:23.898Z,1738797683.898 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/NeedComms.tl translated into:
1
10
20
NaN
1
1000
7
30
0
last time_fix was:
first GPS update timeout
Comms timed out after
minutes
second GPS update timeout
2025-02-05T23:21:23.903Z,1738797683.903 [MissionManager](INFO): DefineArg transit:NeedComms.DiveInterval = 1.000000 h
2025-02-05T23:21:23.906Z,1738797683.906 [MissionManager](INFO): DefineArg transit:NeedComms.WaitForPitchUp = 10.000000 min
2025-02-05T23:21:23.909Z,1738797683.909 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacePitch = 20.000000 arcdeg
2025-02-05T23:21:23.914Z,1738797683.914 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceDepthRate = nan m/s
2025-02-05T23:21:23.917Z,1738797683.917 [MissionManager](INFO): DefineArg transit:NeedComms.SurfaceSpeed = 1.000000 m/s
2025-02-05T23:21:23.920Z,1738797683.920 [MissionManager](INFO): DefineArg transit:NeedComms.SurfacingTimeout = 1000.000000 s
2025-02-05T23:21:23.979Z,1738797683.979 [MissionManager](INFO): DefineArg transit:NeedComms.GPSTimeout = 7.000000 min
2025-02-05T23:21:23.991Z,1738797683.991 [MissionManager](INFO): DefineArg transit:NeedComms.CommsTimeout = 30.000000 min
2025-02-05T23:21:24.015Z,1738797684.015 [transit:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-02-05T23:21:24.067Z,1738797684.067 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/StandardEnvelopes.tl
2025-02-05T23:21:24.067Z,1738797684.067 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/StandardEnvelopes.tl
2025-02-05T23:21:24.072Z,1738797684.072 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/StandardEnvelopes.tl
2025-02-05T23:21:24.206Z,1738797684.206 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/StandardEnvelopes.tl translated into:
5
0
200
2000
2025-02-05T23:21:24.215Z,1738797684.215 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinAltitude = 5.000000 m
2025-02-05T23:21:24.219Z,1738797684.219 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepthIgnore = 0.000000 m
2025-02-05T23:21:24.231Z,1738797684.231 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MaxDepth = 200.000000 m
2025-02-05T23:21:24.238Z,1738797684.238 [MissionManager](INFO): DefineArg transit:StandardEnvelopes.MinOffshore = 2000.000000 m
2025-02-05T23:21:24.239Z,1738797684.239 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2025-02-05T23:21:24.261Z,1738797684.261 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2025-02-05T23:21:24.278Z,1738797684.278 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2025-02-05T23:21:24.302Z,1738797684.302 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/BackseatDriver.tl
2025-02-05T23:21:24.302Z,1738797684.302 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/BackseatDriver.tl
2025-02-05T23:21:24.303Z,1738797684.303 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/BackseatDriver.tl
2025-02-05T23:21:24.377Z,1738797684.377 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/BackseatDriver.tl translated into:
2025-02-05T23:21:24.395Z,1738797684.395 [MissionManager](INFO): DefineArg transit:BackseatDriver.EnableBackseat = 0 bool
2025-02-05T23:21:24.414Z,1738797684.414 [transit:BackseatDriver:A.BackseatDriver](DEBUG): Construct BackseatDriver.
2025-02-05T23:21:24.419Z,1738797684.419 [MissionManager](INFO): Inserting Stack from file: Missions/Insert/PowerOnly.tl
2025-02-05T23:21:24.419Z,1738797684.419 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Insert/PowerOnly.tl
2025-02-05T23:21:24.421Z,1738797684.421 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Insert/PowerOnly.tl
2025-02-05T23:21:24.661Z,1738797684.661 [MissionManager](DEBUG): TethyslAPI: Missions/Insert/PowerOnly.tl translated into:
99999
2025-02-05T23:21:24.665Z,1738797684.665 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleAll = 0 bool
2025-02-05T23:21:24.667Z,1738797684.667 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad1 = 0 bool
2025-02-05T23:21:24.682Z,1738797684.682 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad2 = 0 bool
2025-02-05T23:21:24.685Z,1738797684.685 [MissionManager](INFO): DefineArg transit:PowerOnly.SampleLoad3 = 0 bool
2025-02-05T23:21:24.688Z,1738797684.688 [MissionManager](INFO): DefineArg transit:PowerOnly.EnabledPowerOnly = 0 bool
2025-02-05T23:21:24.712Z,1738797684.712 [transit:PowerOnly:E.Wait](DEBUG): Construct Wait.
2025-02-05T23:21:24.714Z,1738797684.714 [transit:BuoyancyHold.Buoyancy](DEBUG): Construct Buoyancy.
2025-02-05T23:21:24.718Z,1738797684.718 [transit:MassHold.Pitch](DEBUG): Construct.
2025-02-05T23:21:24.773Z,1738797684.773 [transit:Transit:A.Pitch](DEBUG): Construct.
2025-02-05T23:21:24.783Z,1738797684.783 [transit:Transit:B.SetSpeed](DEBUG): Construct.
2025-02-05T23:21:24.798Z,1738797684.798 [transit:Transit:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2025-02-05T23:21:24.836Z,1738797684.836 [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 transit {
"""
Vehicle transits to desired waypoint and can be commanded to use/hold
mass or buoyancy.
"""
arguments {
MissionTimeout = 60 minute
"""
Maximum duration of mission
"""
NeedCommsTime = 30 minute
"""
How often to surface for commumications
"""
Latitude = NaN degree
"""
Latitude of waypoint to seek. If set to NaN, uses latitude at mission
initialization.
"""
Longitude = NaN degree
"""
Longitude of waypoint to seek. If set to NaN, uses longitude at mission
initialization.
"""
Northings = 0 meter
"""
Northward distance of the waypoint to seek. If combined with Latitude
and/or Longitude, this is an offset from the specified Latitude and/or
Longitude.
"""
Eastings = 0 meter
"""
Eastward distance of the waypoint to seek. If combined with Latitude
and/or Longitude, this is an offset from the specified Latitude and/or
Longitude.
"""
Depth = 10 meter
"""
Depth of flat and level flight during the mission.
"""
Speed = 1 meter_per_second
"""
Speed of vehicle (relative to water) during the mission.
"""
MaxDepth = 20 meter
"""
Maximum depth for the entire mission.
"""
MinOffshore = 1 kilometer
"""
Minimum distance offshore for the entire mission.
"""
MinAltitude = 7 meter
"""
Minimum height above the sea floor for the entire mission.
"""
MassHold = true
"""
Set to True in order to hold mass at default position, False to allow
mass to run on its own.
"""
BuoyancyHold = true
"""
Set to True in order to hold buoyancy at neutral position, False to
allow buoyancy to run on its own.
"""
}
timeout duration=MissionTimeout
insert id="NeedComms" Insert/NeedComms.tl
assign in sequence NeedComms:DiveInterval = NeedCommsTime
assign in sequence NeedComms:WaitForPitchUp = 1 second
insert Insert/StandardEnvelopes.tl
assign in sequence StandardEnvelopes:MinAltitude = MinAltitude
assign in sequence StandardEnvelopes:MaxDepth = MaxDepth
assign in sequence StandardEnvelopes:MinOffshore = MinOffshore
insert Insert/BackseatDriver.tl
insert Insert/PowerOnly.tl
behavior Guidance:Buoyancy id="BuoyancyHold" {
run while ( BuoyancyHold )
set position = Control:VerticalControl.buoyancyNeutral
}
behavior Guidance:Pitch id="MassHold" {
run while ( MassHold )
set massPosition = Control:VerticalControl.massDefault
}
aggregate Transit {
run in sequence
behavior Guidance:Pitch {
run in parallel
set depth = Depth
}
behavior Guidance:SetSpeed {
run in parallel
set speed = Speed
}
behavior Guidance:Waypoint id="Wpt1" {
run in sequence
set latitude = Latitude
set longitude = Longitude
set northingsDelta = Northings
set eastingsDelta = Eastings
}
call id="PhoneHome" refId="NeedComms"
}
}
2025-02-05T23:21:24.841Z,1738797684.841 [CommandExec](IMPORTANT): Loaded ./Missions/Transport/transit.tl id=transit
2025-02-05T23:21:32.979Z,1738797692.979 [CommandExec](IMPORTANT): got command set transit.MissionTimeout 30 minute
2025-02-05T23:21:32.980Z,1738797692.980 [CommandExec](IMPORTANT): got command set transit.NeedCommsTime 12 minute
2025-02-05T23:21:32.981Z,1738797692.981 [CommandExec](IMPORTANT): got command set transit.Latitude 36.797 degree
2025-02-05T23:21:32.981Z,1738797692.981 [CommandExec](IMPORTANT): got command set transit.Longitude -121.847 degree
2025-02-05T23:21:32.982Z,1738797692.982 [CommandExec](IMPORTANT): got command set transit.Depth 3 meter
2025-02-05T23:21:32.983Z,1738797692.983 [CommandExec](IMPORTANT): got command set transit.MaxDepth 9 meter
2025-02-05T23:21:34.415Z,1738797694.415 [CommandExec](IMPORTANT): got command set transit.MinOffshore 0.0001 kilometer
2025-02-05T23:21:34.415Z,1738797694.415 [CommandExec](IMPORTANT): got command run
2025-02-05T23:21:34.462Z,1738797694.462 [CommandExec](IMPORTANT): Running
2025-02-05T23:21:34.484Z,1738797694.484 [Default] Stopped
2025-02-05T23:21:34.484Z,1738797694.484 [Default](DEBUG): Aggregate::uninitialize Default
2025-02-05T23:21:34.485Z,1738797694.485 [Default:B.GoToSurface] Stopped
2025-02-05T23:21:34.485Z,1738797694.485 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-02-05T23:21:34.485Z,1738797694.485 [Default:CheckIn] Stopped
2025-02-05T23:21:34.485Z,1738797694.485 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-05T23:21:34.485Z,1738797694.485 [Default:CheckIn:C.Wait] Stopped
2025-02-05T23:21:34.485Z,1738797694.485 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-05T23:21:34.485Z,1738797694.485 [MissionManager](IMPORTANT): Started mission transit
2025-02-05T23:21:34.485Z,1738797694.485 [transit] Running Loop=1
2025-02-05T23:21:34.485Z,1738797694.485 [transit](DEBUG): Aggregate::initialize transit
2025-02-05T23:21:34.486Z,1738797694.486 [transit:StandardEnvelopes] Running Loop=1
2025-02-05T23:21:34.486Z,1738797694.486 [transit:StandardEnvelopes](DEBUG): Aggregate::initialize transit:StandardEnvelopes
2025-02-05T23:21:34.486Z,1738797694.486 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-02-05T23:21:34.486Z,1738797694.486 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2025-02-05T23:21:34.486Z,1738797694.486 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-02-05T23:21:34.486Z,1738797694.486 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2025-02-05T23:21:34.486Z,1738797694.486 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-02-05T23:21:34.486Z,1738797694.486 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2025-02-05T23:21:34.486Z,1738797694.486 [transit:BackseatDriver] Running Loop=1
2025-02-05T23:21:34.487Z,1738797694.487 [transit:BackseatDriver](DEBUG): Aggregate::initialize transit:BackseatDriver
2025-02-05T23:21:34.487Z,1738797694.487 [transit:BackseatDriver:A.BackseatDriver] Running Loop=1
2025-02-05T23:21:34.487Z,1738797694.487 [transit:PowerOnly] Running Loop=1
2025-02-05T23:21:34.487Z,1738797694.487 [transit:PowerOnly](DEBUG): Aggregate::initialize transit:PowerOnly
2025-02-05T23:21:34.487Z,1738797694.487 [transit:PowerOnly:A] Running Loop=1
2025-02-05T23:21:34.487Z,1738797694.487 [transit:PowerOnly:B] Running Loop=1
2025-02-05T23:21:34.487Z,1738797694.487 [transit:PowerOnly:C] Running Loop=1
2025-02-05T23:21:34.487Z,1738797694.487 [transit:PowerOnly:D] Running Loop=1
2025-02-05T23:21:34.487Z,1738797694.487 [transit:PowerOnly:E.Wait] Running Loop=1
2025-02-05T23:21:34.487Z,1738797694.487 [transit:PowerOnly:E.Wait](DEBUG): Initialize Wait Component.
2025-02-05T23:21:34.488Z,1738797694.488 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2025-02-05T23:21:34.488Z,1738797694.488 [transit:BuoyancyHold.Buoyancy](DEBUG): Initialize Buoyancy Component.
2025-02-05T23:21:34.488Z,1738797694.488 [transit:MassHold.Pitch] Running Loop=1
2025-02-05T23:21:34.488Z,1738797694.488 [transit:MassHold.Pitch](DEBUG): Initialize.
2025-02-05T23:21:34.488Z,1738797694.488 [transit:B] Running Loop=1
2025-02-05T23:21:34.489Z,1738797694.489 [transit:MassHold.Pitch] Running Loop=1
2025-02-05T23:21:34.489Z,1738797694.489 [transit:BuoyancyHold.Buoyancy] Running Loop=1
2025-02-05T23:21:34.490Z,1738797694.490 [transit:PowerOnly] Running Loop=1
2025-02-05T23:21:34.490Z,1738797694.490 [transit:PowerOnly:D] Stopped
2025-02-05T23:21:34.490Z,1738797694.490 [transit:PowerOnly:C] Stopped
2025-02-05T23:21:34.490Z,1738797694.490 [transit:PowerOnly:B] Stopped
2025-02-05T23:21:34.490Z,1738797694.490 [transit:PowerOnly:A] Stopped
2025-02-05T23:21:34.490Z,1738797694.490 [transit:BackseatDriver] Running Loop=1
2025-02-05T23:21:34.491Z,1738797694.491 [transit:StandardEnvelopes] Running Loop=1
2025-02-05T23:21:34.491Z,1738797694.491 [transit:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2025-02-05T23:21:34.492Z,1738797694.492 [transit:StandardEnvelopes:C.OffshoreEnvelope](IMPORTANT): Offshore envelope ACTIVE
2025-02-05T23:21:34.502Z,1738797694.502 [transit:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2025-02-05T23:21:34.503Z,1738797694.503 [transit:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2025-02-05T23:21:34.503Z,1738797694.503 [transit:B] Stopped
2025-02-05T23:21:34.503Z,1738797694.503 [transit:C] Running Loop=1
2025-02-05T23:21:34.508Z,1738797694.508 [HorizontalControl](DEBUG): kpHeading = 0.400000
2025-02-05T23:21:34.508Z,1738797694.508 [HorizontalControl](DEBUG): kiHeading = 0.001000
2025-02-05T23:21:34.509Z,1738797694.509 [HorizontalControl](DEBUG): kdHeading = 0.050000
2025-02-05T23:21:34.877Z,1738797694.877 [transit:C] Stopped
2025-02-05T23:21:34.877Z,1738797694.877 [transit:E] Running Loop=1
2025-02-05T23:21:35.261Z,1738797695.261 [transit:E] Stopped
2025-02-05T23:21:35.261Z,1738797695.261 [transit:F] Running Loop=1
2025-02-05T23:21:35.665Z,1738797695.665 [transit:F] Stopped
2025-02-05T23:21:35.665Z,1738797695.665 [transit:G] Running Loop=1
2025-02-05T23:21:36.073Z,1738797696.073 [transit:G] Stopped
2025-02-05T23:21:36.074Z,1738797696.074 [transit:Transit] Running Loop=1
2025-02-05T23:21:36.074Z,1738797696.074 [transit:Transit](DEBUG): Aggregate::initialize transit:Transit
2025-02-05T23:21:36.074Z,1738797696.074 [transit:Transit:A.Pitch] Running Loop=1
2025-02-05T23:21:36.074Z,1738797696.074 [transit:Transit:A.Pitch](DEBUG): Initialize.
2025-02-05T23:21:36.074Z,1738797696.074 [transit:Transit:B.SetSpeed] Running Loop=1
2025-02-05T23:21:36.074Z,1738797696.074 [transit:Transit:B.SetSpeed](DEBUG): Initialize.
2025-02-05T23:21:36.074Z,1738797696.074 [transit:Transit:Wpt1.Waypoint] Running Loop=1
2025-02-05T23:21:36.075Z,1738797696.075 [transit:Transit:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2025-02-05T23:21:36.146Z,1738797696.146 [transit:Transit:Wpt1.Waypoint](IMPORTANT): Navigating to waypoint: 36.797001,-121.847000
2025-02-05T23:21:36.147Z,1738797696.147 [transit:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2025-02-05T23:21:36.482Z,1738797696.482 [transit:Transit:Wpt1.Waypoint](INFO): Navigating to waypoint: 36.797001,-121.847000
2025-02-05T23:21:36.483Z,1738797696.483 [transit:Transit:B.SetSpeed] Running Loop=1
2025-02-05T23:21:36.483Z,1738797696.483 [transit:Transit:A.Pitch] Running Loop=1
2025-02-05T23:22:25.846Z,1738797745.846 [DataOverHttps](IMPORTANT): SBD MTMSN=20250205T232225
2025-02-05T23:22:33.972Z,1738797753.972 [DataOverHttps](INFO): Received command: stop
2025-02-05T23:22:33.979Z,1738797753.979 [CommandExec](IMPORTANT): got command stop
2025-02-05T23:22:33.979Z,1738797753.979 [CommandExec](IMPORTANT): Scheduling is paused
2025-02-05T23:22:33.979Z,1738797753.979 [Supervisor](INFO): Stop Mission called by CommandExec::commandStop
2025-02-05T23:22:34.318Z,1738797754.318 [MissionManager](INFO): MissionManager is completed.
2025-02-05T23:22:34.318Z,1738797754.318 [MissionManager](INFO): Uninitializing Mission transit
2025-02-05T23:22:34.318Z,1738797754.318 [transit] Stopped
2025-02-05T23:22:34.319Z,1738797754.319 [transit](DEBUG): Aggregate::uninitialize transit
2025-02-05T23:22:34.319Z,1738797754.319 [transit:StandardEnvelopes] Stopped
2025-02-05T23:22:34.319Z,1738797754.319 [transit:StandardEnvelopes](DEBUG): Aggregate::uninitialize transit:StandardEnvelopes
2025-02-05T23:22:34.319Z,1738797754.319 [transit:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2025-02-05T23:22:34.319Z,1738797754.319 [transit:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2025-02-05T23:22:34.319Z,1738797754.319 [transit:StandardEnvelopes:B.DepthEnvelope] Stopped
2025-02-05T23:22:34.319Z,1738797754.319 [transit:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2025-02-05T23:22:34.319Z,1738797754.319 [transit:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2025-02-05T23:22:34.319Z,1738797754.319 [transit:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2025-02-05T23:22:34.319Z,1738797754.319 [transit:BackseatDriver] Stopped
2025-02-05T23:22:34.319Z,1738797754.319 [transit:BackseatDriver](DEBUG): Aggregate::uninitialize transit:BackseatDriver
2025-02-05T23:22:34.319Z,1738797754.319 [transit:BackseatDriver:A.BackseatDriver] Stopped
2025-02-05T23:22:34.319Z,1738797754.319 [transit:PowerOnly] Stopped
2025-02-05T23:22:34.320Z,1738797754.320 [transit:PowerOnly](DEBUG): Aggregate::uninitialize transit:PowerOnly
2025-02-05T23:22:34.320Z,1738797754.320 [transit:PowerOnly:E.Wait] Stopped
2025-02-05T23:22:34.320Z,1738797754.320 [transit:PowerOnly:E.Wait](DEBUG): Uninitialize Wait Component.
2025-02-05T23:22:34.320Z,1738797754.320 [transit:BuoyancyHold.Buoyancy] Stopped
2025-02-05T23:22:34.320Z,1738797754.320 [transit:BuoyancyHold.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2025-02-05T23:22:34.320Z,1738797754.320 [transit:MassHold.Pitch] Stopped
2025-02-05T23:22:34.320Z,1738797754.320 [transit:Transit] Stopped
2025-02-05T23:22:34.320Z,1738797754.320 [transit:Transit](DEBUG): Aggregate::uninitialize transit:Transit
2025-02-05T23:22:34.320Z,1738797754.320 [transit:Transit:A.Pitch] Stopped
2025-02-05T23:22:34.320Z,1738797754.320 [transit:Transit:B.SetSpeed] Stopped
2025-02-05T23:22:34.321Z,1738797754.321 [transit:Transit:B.SetSpeed](DEBUG): Uninitialize.
2025-02-05T23:22:34.321Z,1738797754.321 [transit:Transit:Wpt1.Waypoint] Stopped
2025-02-05T23:22:34.321Z,1738797754.321 [transit:Transit:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2025-02-05T23:22:34.719Z,1738797754.719 [MissionManager](IMPORTANT): Started mission Default
2025-02-05T23:22:34.719Z,1738797754.719 [Default] Running Loop=1
2025-02-05T23:22:34.720Z,1738797754.720 [Default](DEBUG): Aggregate::initialize Default
2025-02-05T23:22:34.720Z,1738797754.720 [Default:B.GoToSurface] Running Loop=1
2025-02-05T23:22:34.720Z,1738797754.720 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-02-05T23:22:34.720Z,1738797754.720 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-02-05T23:22:34.721Z,1738797754.721 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-02-05T23:22:34.721Z,1738797754.721 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-02-05T23:22:34.721Z,1738797754.721 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-02-05T23:22:34.721Z,1738797754.721 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-02-05T23:22:34.722Z,1738797754.722 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-02-05T23:22:34.722Z,1738797754.722 [Default:A.Wait] Running Loop=1
2025-02-05T23:22:34.722Z,1738797754.722 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-02-05T23:22:48.111Z,1738797768.111 [Default:A.Wait](INFO): Done Waiting.
2025-02-05T23:22:48.111Z,1738797768.111 [Default:A.Wait] Stopped
2025-02-05T23:22:48.111Z,1738797768.111 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-02-05T23:22:48.520Z,1738797768.520 [Default:CheckIn] Running Loop=1
2025-02-05T23:22:48.520Z,1738797768.520 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-05T23:22:48.520Z,1738797768.520 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-05T23:22:50.515Z,1738797770.515 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232249.00,A,3648.47215,N,12147.25476,W,9.622,233.78,050225,,,D*78
2025-02-05T23:22:50.517Z,1738797770.517 [NAL9602](INFO): GPS fix at 20250205T232249: (36.807869, -121.787579)
2025-02-05T23:22:50.528Z,1738797770.528 [Default:CheckIn:Read_GPS] Stopped
2025-02-05T23:22:50.528Z,1738797770.528 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-05T23:23:01.092Z,1738797781.092 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250205T184825/Courier0109.lzma
2025-02-05T23:23:02.095Z,1738797782.095 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Courier0109.lzma.bak
2025-02-05T23:23:02.095Z,1738797782.095 [DataOverHttps](INFO): SBD MOMSN=23957680
2025-02-05T23:23:20.467Z,1738797800.467 [NAL9602](INFO): SBD MO Status=0, MOMSN=12495, MT Status=0, MTMSN=0
2025-02-05T23:23:20.467Z,1738797800.467 [NAL9602](INFO): No messages in MT queue
2025-02-05T23:23:21.317Z,1738797801.317 [DataOverHttps](INFO): Sending 687 bytes from file Logs/20250205T184825/Express0110.lzma
2025-02-05T23:23:22.318Z,1738797802.318 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Express0110.lzma.bak
2025-02-05T23:23:22.318Z,1738797802.318 [DataOverHttps](INFO): SBD MOMSN=23957683
2025-02-05T23:23:23.710Z,1738797803.710 [Default:CheckIn:Read_Iridium] Stopped
2025-02-05T23:23:23.711Z,1738797803.711 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-05T23:23:23.711Z,1738797803.711 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-05T23:23:51.169Z,1738797831.169 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-05T23:28:24.421Z,1738798104.421 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-02-05T23:28:24.421Z,1738798104.421 [Default:CheckIn:C.Wait] Stopped
2025-02-05T23:28:24.421Z,1738798104.421 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-02-05T23:28:24.422Z,1738798104.422 [Default:CheckIn:D] Running Loop=1
2025-02-05T23:28:24.899Z,1738798104.899 [Default:CheckIn:D] Stopped
2025-02-05T23:28:24.899Z,1738798104.899 [Default:CheckIn:E] Running Loop=1
2025-02-05T23:28:25.197Z,1738798105.197 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 5.836327 min
2025-02-05T23:28:25.197Z,1738798105.197 [Default:CheckIn:E] Stopped
2025-02-05T23:28:25.198Z,1738798105.198 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-02-05T23:28:25.198Z,1738798105.198 [Default:CheckIn] Stopped
2025-02-05T23:28:25.198Z,1738798105.198 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-02-05T23:28:25.198Z,1738798105.198 [Default:CheckIn](INFO): Running loop #2
2025-02-05T23:28:25.198Z,1738798105.198 [Default:CheckIn] Running Loop=2
2025-02-05T23:28:25.198Z,1738798105.198 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-02-05T23:28:25.198Z,1738798105.198 [Default:CheckIn:Read_GPS] Running Loop=1
2025-02-05T23:28:27.205Z,1738798107.205 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,232826.00,A,3647.89864,N,12148.12994,W,1.263,257.17,050225,,,D*72
2025-02-05T23:28:27.207Z,1738798107.207 [NAL9602](INFO): GPS fix at 20250205T232826: (36.798311, -121.802166)
2025-02-05T23:28:27.218Z,1738798107.218 [Default:CheckIn:Read_GPS] Stopped
2025-02-05T23:28:27.218Z,1738798107.218 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-02-05T23:28:35.104Z,1738798115.104 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250205T184825/Courier0112.lzma
2025-02-05T23:28:36.106Z,1738798116.106 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Courier0112.lzma.bak
2025-02-05T23:28:36.106Z,1738798116.106 [DataOverHttps](INFO): SBD MOMSN=23957712
2025-02-05T23:28:41.339Z,1738798121.339 [NAL9602](INFO): SBD MO Status=0, MOMSN=12496, MT Status=0, MTMSN=0
2025-02-05T23:28:41.339Z,1738798121.339 [NAL9602](INFO): No messages in MT queue
2025-02-05T23:28:53.127Z,1738798133.127 [DataOverHttps](INFO): Sending 154 bytes from file Logs/20250205T184825/Express0114.lzma
2025-02-05T23:28:53.966Z,1738798133.966 [DataOverHttps](INFO): Moved sent file to Logs/20250205T184825/Express0114.lzma.bak
2025-02-05T23:28:53.966Z,1738798133.966 [DataOverHttps](INFO): SBD MOMSN=23957715
2025-02-05T23:28:55.102Z,1738798135.102 [Default:CheckIn:Read_Iridium] Stopped
2025-02-05T23:28:55.102Z,1738798135.102 [Default:CheckIn:C.Wait] Running Loop=1
2025-02-05T23:28:55.102Z,1738798135.102 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-02-05T23:29:12.085Z,1738798152.085 [NAL9602](INFO): Not Powering down - fast GPS
2025-02-05T23:31:34.833Z,1738798294.833 [DataOverHttps](IMPORTANT): SBD MTMSN=20250205T233133
2025-02-05T23:31:42.608Z,1738798302.608 [DataOverHttps](INFO): Received command: restart logs