2024-07-08T19:05:13.815Z,1720465513.815 [Supervisor](DEBUG): Initializing supervisor.
2024-07-08T19:05:13.819Z,1720465513.819 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2024-07-08T19:05:13.820Z,1720465513.820 [SyncHandler](INFO): Protected caller Thread ID is 2373
2024-07-08T19:05:13.820Z,1720465513.820 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2024-07-08T19:05:13.821Z,1720465513.821 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2024-07-08T19:05:13.822Z,1720465513.822 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2374
2024-07-08T19:05:13.825Z,1720465513.825 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2024-07-08T19:05:13.842Z,1720465513.842 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2024-07-08T19:05:13.843Z,1720465513.843 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2024-07-08T19:05:13.844Z,1720465513.844 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 2375
2024-07-08T19:05:13.848Z,1720465513.848 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2024-07-08T19:05:13.849Z,1720465513.849 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2024-07-08T19:05:13.849Z,1720465513.849 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2376
2024-07-08T19:05:13.851Z,1720465513.851 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2024-07-08T19:05:13.852Z,1720465513.852 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2024-07-08T19:05:13.852Z,1720465513.852 [logger ThreadHandler](INFO): Protected caller Thread ID is 2377
2024-07-08T19:05:13.856Z,1720465513.856 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2024-07-08T19:05:13.856Z,1720465513.856 [Supervisor](INFO): Looking for Config files in directory: Config/
2024-07-08T19:05:13.858Z,1720465513.858 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2024-07-08T19:05:14.404Z,1720465514.404 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2024-07-08T19:05:14.404Z,1720465514.404 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2024-07-08T19:05:14.631Z,1720465514.631 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2024-07-08T19:05:14.632Z,1720465514.632 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2024-07-08T19:05:14.735Z,1720465514.735 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2024-07-08T19:05:14.982Z,1720465514.982 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2024-07-08T19:05:14.982Z,1720465514.982 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2024-07-08T19:05:15.072Z,1720465515.072 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2024-07-08T19:05:15.179Z,1720465515.179 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2024-07-08T19:05:15.179Z,1720465515.179 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2024-07-08T19:05:15.538Z,1720465515.538 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2024-07-08T19:05:15.538Z,1720465515.538 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2024-07-08T19:05:15.683Z,1720465515.683 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2024-07-08T19:05:15.684Z,1720465515.684 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2024-07-08T19:05:16.238Z,1720465516.238 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2024-07-08T19:05:16.239Z,1720465516.239 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2024-07-08T19:05:16.441Z,1720465516.441 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2024-07-08T19:05:16.441Z,1720465516.441 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2024-07-08T19:05:16.868Z,1720465516.868 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2024-07-08T19:05:16.869Z,1720465516.869 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2024-07-08T19:05:17.173Z,1720465517.173 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2024-07-08T19:05:17.174Z,1720465517.174 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2024-07-08T19:05:17.294Z,1720465517.294 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2024-07-08T19:05:17.295Z,1720465517.295 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2024-07-08T19:05:18.226Z,1720465518.226 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2024-07-08T19:05:18.226Z,1720465518.226 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2024-07-08T19:05:18.685Z,1720465518.685 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2024-07-08T19:05:18.686Z,1720465518.686 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2024-07-08T19:05:19.092Z,1720465519.092 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2024-07-08T19:05:19.094Z,1720465519.094 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2024-07-08T19:05:19.095Z,1720465519.095 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2024-07-08T19:05:19.225Z,1720465519.225 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2024-07-08T19:05:19.321Z,1720465519.321 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2024-07-08T19:05:19.470Z,1720465519.470 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2024-07-08T19:05:19.567Z,1720465519.567 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2024-07-08T19:05:19.839Z,1720465519.839 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2024-07-08T19:05:19.839Z,1720465519.839 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2024-07-08T19:05:19.956Z,1720465519.956 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2024-07-08T19:05:20.074Z,1720465520.074 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2024-07-08T19:05:20.181Z,1720465520.181 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2024-07-08T19:05:20.343Z,1720465520.343 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2024-07-08T19:05:20.452Z,1720465520.452 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2024-07-08T19:05:20.551Z,1720465520.551 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2024-07-08T19:05:20.551Z,1720465520.551 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2024-07-08T19:05:20.557Z,1720465520.557 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2024-07-08T19:05:20.567Z,1720465520.567 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2024-07-08T19:05:20.568Z,1720465520.568 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2024-07-08T19:05:20.676Z,1720465520.676 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2024-07-08T19:05:20.676Z,1720465520.676 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2024-07-08T19:05:20.879Z,1720465520.879 [VerticalControl](DEBUG): Construct VerticalControl.
2024-07-08T19:05:20.939Z,1720465520.939 [VerticalControl] Loaded
2024-07-08T19:05:20.940Z,1720465520.940 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2024-07-08T19:05:20.942Z,1720465520.942 [HorizontalControl](DEBUG): Construct HorizontalControl.
2024-07-08T19:05:20.989Z,1720465520.989 [HorizontalControl] Loaded
2024-07-08T19:05:20.989Z,1720465520.989 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2024-07-08T19:05:20.991Z,1720465520.991 [SpeedControl](DEBUG): Construct SpeedControl.
2024-07-08T19:05:20.995Z,1720465520.995 [SpeedControl] Loaded
2024-07-08T19:05:20.995Z,1720465520.995 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2024-07-08T19:05:20.997Z,1720465520.997 [LoopControl](DEBUG): Construct LoopControl.
2024-07-08T19:05:20.998Z,1720465520.998 [LoopControl] Loaded
2024-07-08T19:05:20.998Z,1720465520.998 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2024-07-08T19:05:20.998Z,1720465520.998 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2024-07-08T19:05:20.999Z,1720465520.999 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2024-07-08T19:05:21.126Z,1720465521.126 [BuoyancyServo] Loaded
2024-07-08T19:05:21.126Z,1720465521.126 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2024-07-08T19:05:21.148Z,1720465521.148 [ElevatorServo] Loaded
2024-07-08T19:05:21.148Z,1720465521.148 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2024-07-08T19:05:21.169Z,1720465521.169 [MassServo] Loaded
2024-07-08T19:05:21.169Z,1720465521.169 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2024-07-08T19:05:21.189Z,1720465521.189 [RudderServo] Loaded
2024-07-08T19:05:21.189Z,1720465521.189 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2024-07-08T19:05:21.206Z,1720465521.206 [ThrusterHE] Loaded
2024-07-08T19:05:21.206Z,1720465521.206 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2024-07-08T19:05:21.206Z,1720465521.206 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2024-07-08T19:05:21.207Z,1720465521.207 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2024-07-08T19:05:21.255Z,1720465521.255 [DepthRateCalculator] Loaded
2024-07-08T19:05:21.255Z,1720465521.255 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2024-07-08T19:05:21.260Z,1720465521.260 [PitchRateCalculator] Loaded
2024-07-08T19:05:21.260Z,1720465521.260 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2024-07-08T19:05:21.270Z,1720465521.270 [SpeedCalculator] Loaded
2024-07-08T19:05:21.270Z,1720465521.270 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2024-07-08T19:05:21.274Z,1720465521.274 [YawRateCalculator] Loaded
2024-07-08T19:05:21.275Z,1720465521.275 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2024-07-08T19:05:21.293Z,1720465521.293 [ElevatorOffsetCalculator] Loaded
2024-07-08T19:05:21.293Z,1720465521.293 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2024-07-08T19:05:21.293Z,1720465521.293 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2024-07-08T19:05:21.294Z,1720465521.294 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2024-07-08T19:05:21.402Z,1720465521.402 [DeadReckonUsingMultipleVelocitySources] Loaded
2024-07-08T19:05:21.402Z,1720465521.402 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2024-07-08T19:05:21.415Z,1720465521.415 [NavChart] Loaded
2024-07-08T19:05:21.416Z,1720465521.416 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2024-07-08T19:05:21.421Z,1720465521.421 [UniversalFixResidualReporter] Loaded
2024-07-08T19:05:21.421Z,1720465521.421 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2024-07-08T19:05:21.422Z,1720465521.422 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2024-07-08T19:05:21.422Z,1720465521.422 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2024-07-08T19:05:21.839Z,1720465521.839 [AHRS_M2] Loaded
2024-07-08T19:05:21.839Z,1720465521.839 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2024-07-08T19:05:21.878Z,1720465521.878 [BackseatComponent] Loaded
2024-07-08T19:05:21.878Z,1720465521.878 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2024-07-08T19:05:21.879Z,1720465521.879 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4090B4E0
2024-07-08T19:05:21.880Z,1720465521.880 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 2460
2024-07-08T19:05:21.883Z,1720465521.883 [LcmUniversalReporter] Loaded
2024-07-08T19:05:21.883Z,1720465521.883 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2024-07-08T19:05:21.889Z,1720465521.889 [BioacousticsDataBridge] Loaded
2024-07-08T19:05:21.889Z,1720465521.889 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread.
2024-07-08T19:05:22.709Z,1720465522.709 [BPC1] Loaded
2024-07-08T19:05:22.710Z,1720465522.710 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2024-07-08T19:05:22.780Z,1720465522.780 [DataOverHttps] Loaded
2024-07-08T19:05:22.780Z,1720465522.780 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2024-07-08T19:05:22.781Z,1720465522.781 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4093B4E0
2024-07-08T19:05:22.781Z,1720465522.781 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2461
2024-07-08T19:05:22.805Z,1720465522.805 [Depth_Keller] Loaded
2024-07-08T19:05:22.805Z,1720465522.805 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2024-07-08T19:05:22.810Z,1720465522.810 [DropWeight] Loaded
2024-07-08T19:05:22.810Z,1720465522.810 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2024-07-08T19:05:22.874Z,1720465522.874 [NAL9602] Loaded
2024-07-08T19:05:22.874Z,1720465522.874 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2024-07-08T19:05:22.904Z,1720465522.904 [Onboard] Loaded
2024-07-08T19:05:22.904Z,1720465522.904 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2024-07-08T19:05:22.905Z,1720465522.905 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4096B4E0
2024-07-08T19:05:22.905Z,1720465522.905 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 2462
2024-07-08T19:05:22.919Z,1720465522.919 [Power24vConverter] Loaded
2024-07-08T19:05:22.920Z,1720465522.920 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2024-07-08T19:05:22.935Z,1720465522.935 [Radio_Surface] Loaded
2024-07-08T19:05:22.935Z,1720465522.935 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2024-07-08T19:05:22.937Z,1720465522.937 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4099B4E0
2024-07-08T19:05:22.937Z,1720465522.937 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2463
2024-07-08T19:05:22.952Z,1720465522.952 [Sonardyne_Nano] Loaded
2024-07-08T19:05:22.952Z,1720465522.952 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2024-07-08T19:05:22.953Z,1720465522.953 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2024-07-08T19:05:22.953Z,1720465522.953 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2024-07-08T19:05:23.006Z,1720465523.006 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2024-07-08T19:05:23.007Z,1720465523.007 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2024-07-08T19:05:23.168Z,1720465523.168 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2024-07-08T19:05:23.168Z,1720465523.168 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2024-07-08T19:05:23.194Z,1720465523.194 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2024-07-08T19:05:23.195Z,1720465523.195 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2024-07-08T19:05:23.366Z,1720465523.366 [CTD_Seabird] Loaded
2024-07-08T19:05:23.366Z,1720465523.366 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2024-07-08T19:05:23.367Z,1720465523.367 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40ADB4E0
2024-07-08T19:05:23.367Z,1720465523.367 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2464
2024-07-08T19:05:23.390Z,1720465523.390 [PAR_Licor] Loaded
2024-07-08T19:05:23.390Z,1720465523.390 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2024-07-08T19:05:23.434Z,1720465523.434 [WetLabsBB2FL] Loaded
2024-07-08T19:05:23.434Z,1720465523.434 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2024-07-08T19:05:23.435Z,1720465523.435 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B0B4E0
2024-07-08T19:05:23.435Z,1720465523.435 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2465
2024-07-08T19:05:23.436Z,1720465523.436 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2024-07-08T19:05:23.437Z,1720465523.437 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2024-07-08T19:05:23.760Z,1720465523.760 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2024-07-08T19:05:23.760Z,1720465523.760 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2024-07-08T19:05:24.137Z,1720465524.137 [SBIT](DEBUG): Construct Startup Built In Test.
2024-07-08T19:05:24.146Z,1720465524.146 [SBIT] Loaded
2024-07-08T19:05:24.147Z,1720465524.147 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2024-07-08T19:05:24.149Z,1720465524.149 [IBIT](DEBUG): Construct Initiated Built In Test.
2024-07-08T19:05:24.162Z,1720465524.162 [IBIT] Loaded
2024-07-08T19:05:24.162Z,1720465524.162 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2024-07-08T19:05:24.168Z,1720465524.168 [CBIT](DEBUG): Construct Continuous Built In Test.
2024-07-08T19:05:24.271Z,1720465524.271 [CBIT] Loaded
2024-07-08T19:05:24.271Z,1720465524.271 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2024-07-08T19:05:24.271Z,1720465524.271 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2024-07-08T19:05:24.278Z,1720465524.278 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2024-07-08T19:05:24.281Z,1720465524.281 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2024-07-08T19:05:24.292Z,1720465524.292 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2024-07-08T19:05:24.293Z,1720465524.293 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C074E0
2024-07-08T19:05:24.293Z,1720465524.293 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2466
2024-07-08T19:05:24.298Z,1720465524.298 [Supervisor](INFO): Main Thread ID is 2372
2024-07-08T19:05:24.298Z,1720465524.298 [Supervisor](DEBUG): Running supervisor.
2024-07-08T19:05:24.298Z,1720465524.298 [CommandExec ThreadHandler](INFO): Handler Thread ID is 2467
2024-07-08T19:05:24.299Z,1720465524.299 [CommandExec](INFO): Initializing the command executive.
2024-07-08T19:05:24.301Z,1720465524.301 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2468
2024-07-08T19:05:24.303Z,1720465524.303 [controlThread ThreadHandler](INFO): Handler Thread ID is 2469
2024-07-08T19:05:24.304Z,1720465524.304 [controlThread](DEBUG): Initializing ControlThread
2024-07-08T19:05:24.305Z,1720465524.305 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2024-07-08T19:05:24.306Z,1720465524.306 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2024-07-08T19:05:24.307Z,1720465524.307 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2024-07-08T19:05:24.307Z,1720465524.307 [LoopControl](DEBUG): Initialize LoopControlComponent.
2024-07-08T19:05:24.309Z,1720465524.309 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2024-07-08T19:05:24.309Z,1720465524.309 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2024-07-08T19:05:24.309Z,1720465524.309 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2024-07-08T19:05:24.310Z,1720465524.310 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2024-07-08T19:05:24.310Z,1720465524.310 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2024-07-08T19:05:24.311Z,1720465524.311 [NavChart](DEBUG): Initialize NavChart Navigation.
2024-07-08T19:05:24.311Z,1720465524.311 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2024-07-08T19:05:24.317Z,1720465524.317 [SBIT](INFO): Initialize SBIT Component.
2024-07-08T19:05:24.317Z,1720465524.317 [SBIT](IMPORTANT): git: 2024-06-03
2024-07-08T19:05:24.318Z,1720465524.318 [SBIT](INFO): git hash: 7fa19da3a148abf4baea4b65318d99ca26f8c915
2024-07-08T19:05:24.318Z,1720465524.318 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2024-07-08T19:05:24.319Z,1720465524.319 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2024-07-08T19:05:24.320Z,1720465524.320 [SBIT](INFO): Beginning SBIT in 20.000000 seconds.
2024-07-08T19:05:24.321Z,1720465524.321 [IBIT](INFO): Initialize IBIT Component.
2024-07-08T19:05:24.321Z,1720465524.321 [CBIT](DEBUG): Initialize CBIT Component.
2024-07-08T19:05:24.322Z,1720465524.322 [logger ThreadHandler](INFO): Handler Thread ID is 2470
2024-07-08T19:05:24.333Z,1720465524.333 [CBIT](DEBUG): Initialized mux pins.
2024-07-08T19:05:24.333Z,1720465524.333 [CBIT](DEBUG): Initializing the watchdog timer.
2024-07-08T19:05:24.341Z,1720465524.341 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 2471
2024-07-08T19:05:24.353Z,1720465524.353 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2472
2024-07-08T19:05:24.355Z,1720465524.355 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2024-07-08T19:05:24.357Z,1720465524.357 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2024-07-08T19:05:24.358Z,1720465524.358 [CBIT](DEBUG): Initializing heartbeat.
2024-07-08T19:05:24.365Z,1720465524.365 [Onboard ThreadHandler](INFO): Handler Thread ID is 2473
2024-07-08T19:05:24.395Z,1720465524.395 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2474
2024-07-08T19:05:24.402Z,1720465524.402 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2475
2024-07-08T19:05:24.402Z,1720465524.402 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2024-07-08T19:05:24.406Z,1720465524.406 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2477
2024-07-08T19:05:24.409Z,1720465524.409 [WetLabsBB2FL](INFO): Powering up
2024-07-08T19:05:24.410Z,1720465524.410 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2479
2024-07-08T19:05:24.414Z,1720465524.414 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2024-07-08T19:05:24.414Z,1720465524.414 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2024-07-08T19:05:24.414Z,1720465524.414 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2024-07-08T19:05:24.415Z,1720465524.415 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2024-07-08T19:05:24.415Z,1720465524.415 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2024-07-08T19:05:24.415Z,1720465524.415 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2024-07-08T19:05:24.415Z,1720465524.415 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2024-07-08T19:05:24.415Z,1720465524.415 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2024-07-08T19:05:24.429Z,1720465524.429 [CBIT](DEBUG): Deactivating GF circuits.
2024-07-08T19:05:24.429Z,1720465524.429 [CBIT](DEBUG): Deactivating emergency mode.
2024-07-08T19:05:24.469Z,1720465524.469 [CBIT](DEBUG): Backplane powered.
2024-07-08T19:05:24.469Z,1720465524.469 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2024-07-08T19:05:24.470Z,1720465524.470 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2024-07-08T19:05:24.470Z,1720465524.470 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2024-07-08T19:05:24.474Z,1720465524.474 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2024-07-08T19:05:24.526Z,1720465524.526 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2024-07-08T19:05:24.546Z,1720465524.546 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2024-07-08T19:05:24.570Z,1720465524.570 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Startup {
behavior Guidance:GoToSurface {
run in progression
}
aggregate StartupSatComms {
run in sequence
readDatum {
timeout duration=P1M
Universal:latitude_fix
}
readDatum {
timeout duration=P1M
Universal:platform_communications
}
}
}
2024-07-08T19:05:24.571Z,1720465524.571 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2024-07-08T19:05:24.571Z,1720465524.571 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2024-07-08T19:05:24.581Z,1720465524.581 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2024-07-08T19:05:24.797Z,1720465524.797 [Radio_Surface](INFO): Powering up
2024-07-08T19:05:24.852Z,1720465524.852 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2024-07-08T19:05:24.882Z,1720465524.882 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2024-07-08T19:05:24.883Z,1720465524.883 [Default:A.Wait](DEBUG): Construct Wait.
2024-07-08T19:05:24.901Z,1720465524.901 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2024-07-08T19:05:24.936Z,1720465524.936 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2024-07-08T19:05:24.954Z,1720465524.954 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2024-07-08T19:05:24.961Z,1720465524.961 [Default:E.Execute](DEBUG): Construct Execute.
2024-07-08T19:05:24.971Z,1720465524.971 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Default {
arguments {
ElapsedSinceDefaultStarted = 0 minute
}
behavior Guidance:Wait {
"""
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
"""
run in sequence
set duration = 13 second
}
behavior Guidance:GoToSurface {
run in progression
}
aggregate CheckIn {
run in sequence repeat=288
readDatum id="Read_GPS" {
timeout duration=P5M
Universal:time_fix
}
readDatum id="Read_Iridium" {
timeout duration=P4H {
behavior Guidance:Execute {
run in sequence
set command = "Burn on"
}
syslog critical "Dropped weight due to communications timeout."
}
Universal:platform_communications
}
behavior Guidance:Wait {
run in sequence
set duration = 5 minute
}
assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )
syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
}
syslog important "Restarting logs and Default mission."
behavior Guidance:Execute {
run in sequence
set command = "restart logs"
}
}
2024-07-08T19:05:24.975Z,1720465524.975 [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,
2024-07-08T19:05:24.993Z,1720465524.993 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2024-07-08T19:05:25.059Z,1720465525.059 [Power24vConverter](INFO): Powering up.
2024-07-08T19:05:25.060Z,1720465525.060 [Sonardyne_Nano](INFO): Initializing.
2024-07-08T19:05:25.154Z,1720465525.154 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2024-07-08T19:05:25.161Z,1720465525.161 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2024-07-08T19:05:25.162Z,1720465525.162 [ElevatorServo](DEBUG): Initializing EZServoServo.
2024-07-08T19:05:25.169Z,1720465525.169 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2024-07-08T19:05:25.170Z,1720465525.170 [MassServo](DEBUG): Initializing EZServoServo.
2024-07-08T19:05:25.177Z,1720465525.177 [MassServo](DEBUG): Initializing MassServo.
2024-07-08T19:05:25.178Z,1720465525.178 [RudderServo](DEBUG): Initializing EZServoServo.
2024-07-08T19:05:25.185Z,1720465525.185 [RudderServo](DEBUG): Initializing RudderServo.
2024-07-08T19:05:25.186Z,1720465525.186 [ThrusterHE](DEBUG): Initializing EZServoServo.
2024-07-08T19:05:25.193Z,1720465525.193 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2024-07-08T19:05:26.517Z,1720465526.517 [WetLabsBB2FL](INFO): Powering down
2024-07-08T19:05:27.007Z,1720465527.007 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2024-07-08T19:05:28.289Z,1720465528.289 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107
2024-07-08T19:05:28.644Z,1720465528.644 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-07-08T19:05:30.814Z,1720465530.814 [ThrusterHE](ERROR): Zero Speed Commanded.
2024-07-08T19:05:31.433Z,1720465531.433 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2024-07-08T19:05:38.271Z,1720465538.271 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004615
2024-07-08T19:05:44.832Z,1720465544.832 [SBIT](IMPORTANT): Beginning Startup BIT
2024-07-08T19:05:44.844Z,1720465544.844 [CBIT](IMPORTANT): Beginning ground fault scan
2024-07-08T19:05:53.266Z,1720465553.266 [NAL9602](INFO): Powering up NAL9602
2024-07-08T19:05:55.715Z,1720465555.715 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.019340
CHAN A1 (24V): -0.007174
CHAN A2 (12V): -0.004702
CHAN A3 (5V): -0.002772
CHAN B0 (3.3V): -0.000215
CHAN B1 (3.15aV): -0.000800
CHAN B2 (3.15bV): -0.001044
CHAN B3 (GND): -0.000092
OPEN: 0.003776
Full Scale: +/- 1 mA
2024-07-08T19:06:04.186Z,1720465564.186 [NAL9602](INFO): NAL9602 initialized
2024-07-08T19:06:15.874Z,1720465575.874 [CommandExec](IMPORTANT): got command get Onboard.Humidity
2024-07-08T19:06:15.874Z,1720465575.874 [CommandExec](IMPORTANT): Onboard.Humidity 11.213527 %
2024-07-08T19:06:38.970Z,1720465598.970 [SBIT](IMPORTANT): SBIT PASSED
2024-07-08T19:06:38.970Z,1720465598.970 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-07-08T19:06:38.971Z,1720465598.971 [SBIT](IMPORTANT): AHRS_M2.loadAtStartup=1 bool;
2024-07-08T19:06:38.971Z,1720465598.971 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count;
2024-07-08T19:06:38.972Z,1720465598.972 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;
2024-07-08T19:06:39.341Z,1720465599.341 [MissionManager](IMPORTANT): Started mission Startup
2024-07-08T19:06:39.342Z,1720465599.342 [Startup] Running Loop=1
2024-07-08T19:06:39.342Z,1720465599.342 [Startup](DEBUG): Aggregate::initialize Startup
2024-07-08T19:06:39.342Z,1720465599.342 [Startup:A.GoToSurface] Running Loop=1
2024-07-08T19:06:39.342Z,1720465599.342 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-07-08T19:06:39.343Z,1720465599.343 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-07-08T19:06:39.343Z,1720465599.343 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-07-08T19:06:39.343Z,1720465599.343 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-07-08T19:06:39.344Z,1720465599.344 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-07-08T19:06:39.344Z,1720465599.344 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-07-08T19:06:39.344Z,1720465599.344 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-07-08T19:06:39.350Z,1720465599.350 [Startup:StartupSatComms] Running Loop=1
2024-07-08T19:06:39.350Z,1720465599.350 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2024-07-08T19:06:39.350Z,1720465599.350 [Startup:StartupSatComms:A] Running Loop=1
2024-07-08T19:06:39.755Z,1720465599.755 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2024-07-08T19:07:39.543Z,1720465659.543 [Startup:StartupSatComms:A](INFO): Timed out from 2024-07-08T19:06:39.4Z
2024-07-08T19:07:39.543Z,1720465659.543 [Startup:StartupSatComms:A] Stopped
2024-07-08T19:07:39.543Z,1720465659.543 [Startup:StartupSatComms:B] Running Loop=1
2024-07-08T19:07:39.951Z,1720465659.951 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-07-08T19:07:49.372Z,1720465669.372 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240708T162215/Courier0067.lzma
2024-07-08T19:07:50.375Z,1720465670.375 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Courier0067.lzma.bak
2024-07-08T19:07:50.375Z,1720465670.375 [DataOverHttps](INFO): SBD MOMSN=21341330
2024-07-08T19:08:09.584Z,1720465689.584 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240708T190513/Courier0000.lzma
2024-07-08T19:08:10.587Z,1720465690.587 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0000.lzma.bak
2024-07-08T19:08:10.587Z,1720465690.587 [DataOverHttps](INFO): SBD MOMSN=21341332
2024-07-08T19:08:24.373Z,1720465704.373 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2024-07-08T19:08:24.373Z,1720465704.373 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-08T19:08:24.405Z,1720465704.405 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-08T19:08:24.786Z,1720465704.786 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-08T19:08:24.786Z,1720465704.786 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2024-07-08T19:08:30.932Z,1720465710.932 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20240708T162215/Express0068.lzma
2024-07-08T19:08:31.935Z,1720465711.935 [DataOverHttps](INFO): Moved sent file to Logs/20240708T162215/Express0068.lzma.bak
2024-07-08T19:08:31.935Z,1720465711.935 [DataOverHttps](INFO): SBD MOMSN=21341335
2024-07-08T19:08:39.770Z,1720465719.770 [Startup:StartupSatComms:B](INFO): Timed out from 2024-07-08T19:07:39.5Z
2024-07-08T19:08:39.770Z,1720465719.770 [Startup:StartupSatComms:B] Stopped
2024-07-08T19:08:39.771Z,1720465719.771 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2024-07-08T19:08:39.771Z,1720465719.771 [Startup:StartupSatComms] Stopped
2024-07-08T19:08:39.771Z,1720465719.771 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2024-07-08T19:08:39.772Z,1720465719.772 [Startup](INFO): Completed Startup
2024-07-08T19:08:39.772Z,1720465719.772 [MissionManager](INFO): Startup is completed.
2024-07-08T19:08:39.772Z,1720465719.772 [MissionManager](INFO): Uninitializing Mission Startup
2024-07-08T19:08:39.772Z,1720465719.772 [Startup] Stopped
2024-07-08T19:08:39.772Z,1720465719.772 [Startup](DEBUG): Aggregate::uninitialize Startup
2024-07-08T19:08:39.772Z,1720465719.772 [Startup:A.GoToSurface] Stopped
2024-07-08T19:08:39.772Z,1720465719.772 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-07-08T19:08:40.145Z,1720465720.145 [MissionManager](IMPORTANT): Started mission Default
2024-07-08T19:08:40.146Z,1720465720.146 [Default] Running Loop=1
2024-07-08T19:08:40.146Z,1720465720.146 [Default](DEBUG): Aggregate::initialize Default
2024-07-08T19:08:40.146Z,1720465720.146 [Default:B.GoToSurface] Running Loop=1
2024-07-08T19:08:40.146Z,1720465720.146 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2024-07-08T19:08:40.146Z,1720465720.146 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2024-07-08T19:08:40.146Z,1720465720.146 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2024-07-08T19:08:40.147Z,1720465720.147 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2024-07-08T19:08:40.147Z,1720465720.147 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2024-07-08T19:08:40.147Z,1720465720.147 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2024-07-08T19:08:40.148Z,1720465720.148 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2024-07-08T19:08:40.148Z,1720465720.148 [Default:A.Wait] Running Loop=1
2024-07-08T19:08:40.148Z,1720465720.148 [Default:A.Wait](DEBUG): Initialize Wait Component.
2024-07-08T19:08:41.926Z,1720465721.926 [CommandExec](IMPORTANT): got command strobe off
2024-07-08T19:08:41.926Z,1720465721.926 [CommandExec](IMPORTANT): Deactivating strobe
2024-07-08T19:08:53.477Z,1720465733.477 [Default:A.Wait](INFO): Done Waiting.
2024-07-08T19:08:53.477Z,1720465733.477 [Default:A.Wait] Stopped
2024-07-08T19:08:53.477Z,1720465733.477 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T19:08:53.867Z,1720465733.867 [Default:CheckIn] Running Loop=1
2024-07-08T19:08:53.867Z,1720465733.867 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T19:08:53.867Z,1720465733.867 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T19:08:54.137Z,1720465734.137 [DataOverHttps](INFO): Sending 778 bytes from file Logs/20240708T190513/Express0001.lzma
2024-07-08T19:08:54.296Z,1720465734.296 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2024-07-08T19:08:55.138Z,1720465735.138 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0001.lzma.bak
2024-07-08T19:08:55.138Z,1720465735.138 [DataOverHttps](INFO): SBD MOMSN=21341339
2024-07-08T19:09:57.062Z,1720465797.062 [CommandExec](IMPORTANT): got command gfscan
2024-07-08T19:09:57.333Z,1720465797.333 [CBIT](IMPORTANT): Beginning ground fault scan
2024-07-08T19:10:08.244Z,1720465808.244 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.019686
CHAN A1 (24V): -0.007475
CHAN A2 (12V): -0.004822
CHAN A3 (5V): -0.001869
CHAN B0 (3.3V): -0.001295
CHAN B1 (3.15aV): -0.000728
CHAN B2 (3.15bV): -0.000710
CHAN B3 (GND): -0.000328
OPEN: 0.003822
Full Scale: +/- 1 mA
2024-07-08T19:10:19.499Z,1720465819.499 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T19:10:20.309Z,1720465820.309 [CommandExec](IMPORTANT): got command get platform_buoyancy_position
2024-07-08T19:10:20.310Z,1720465820.310 [CommandExec](IMPORTANT): platform_buoyancy_position 998.896179 cc
2024-07-08T19:10:26.366Z,1720465826.366 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T19:10:41.313Z,1720465841.313 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T19:10:56.666Z,1720465856.666 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T19:11:06.773Z,1720465866.773 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-08T19:11:12.826Z,1720465872.826 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T19:11:14.017Z,1720465874.017 [CommandExec](IMPORTANT): got command configSet list
2024-07-08T19:11:14.017Z,1720465874.017 [CommandExec](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2024-07-08T19:11:14.018Z,1720465874.018 [CommandExec](IMPORTANT): AHRS_M2.loadAtStartup=1 bool;
2024-07-08T19:11:14.019Z,1720465874.019 [CommandExec](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count;
2024-07-08T19:11:14.019Z,1720465874.019 [CommandExec](IMPORTANT): DAT.loadAtStartup=0 bool;
2024-07-08T19:11:25.385Z,1720465885.385 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2024-07-08T19:11:25.385Z,1720465885.385 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-08T19:11:25.395Z,1720465885.395 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-08T19:11:25.808Z,1720465885.808 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-08T19:11:25.809Z,1720465885.809 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2024-07-08T19:11:27.775Z,1720465887.775 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T19:11:42.733Z,1720465902.733 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T19:11:57.673Z,1720465917.673 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T19:12:12.623Z,1720465932.623 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T19:12:27.973Z,1720465947.973 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T19:12:42.922Z,1720465962.922 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T19:12:49.399Z,1720465969.399 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5.
2024-07-08T19:12:49.402Z,1720465969.402 [BPC1](INFO): Received data from all battery sticks.
2024-07-08T19:13:54.046Z,1720466034.046 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-07-08T19:08:53.9Z
2024-07-08T19:13:54.046Z,1720466034.046 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T19:13:54.046Z,1720466034.046 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T19:13:54.454Z,1720466034.454 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2024-07-08T19:14:03.880Z,1720466043.880 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20240708T190513/Courier0004.lzma
2024-07-08T19:14:04.882Z,1720466044.882 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0004.lzma.bak
2024-07-08T19:14:04.883Z,1720466044.883 [DataOverHttps](INFO): SBD MOMSN=21341363
2024-07-08T19:14:25.406Z,1720466065.406 [DataOverHttps](INFO): Sending 590 bytes from file Logs/20240708T190513/Express0005.lzma
2024-07-08T19:14:26.407Z,1720466066.407 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0005.lzma.bak
2024-07-08T19:14:26.407Z,1720466066.407 [DataOverHttps](INFO): SBD MOMSN=21341365
2024-07-08T19:14:26.525Z,1720466066.525 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2024-07-08T19:14:26.525Z,1720466066.525 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-08T19:14:26.557Z,1720466066.557 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-08T19:14:26.939Z,1720466066.939 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-08T19:14:26.939Z,1720466066.939 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2024-07-08T19:14:35.835Z,1720466075.835 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T19:14:35.835Z,1720466075.835 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T19:14:35.835Z,1720466075.835 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T19:15:25.921Z,1720466125.921 [Power24vConverter](INFO): Powering down.
2024-07-08T19:16:05.506Z,1720466165.506 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-07-08T19:16:05.506Z,1720466165.506 [NAL9602] Data Fault, FailCount= 1
2024-07-08T19:16:05.507Z,1720466165.507 [NAL9602](ERROR): Data Fault
2024-07-08T19:16:05.523Z,1720466165.523 [CBIT](ERROR): Data Fault in component: NAL9602
2024-07-08T19:16:05.906Z,1720466165.906 [NAL9602](INFO): Powering down
2024-07-08T19:16:06.730Z,1720466166.730 [CBIT](INFO): Clearing failed state for component NAL9602
2024-07-08T19:16:06.730Z,1720466166.730 [NAL9602] No Fault, FailCount= 1
2024-07-08T19:16:36.202Z,1720466196.202 [NAL9602](INFO): Powering up NAL9602
2024-07-08T19:16:47.118Z,1720466207.118 [NAL9602](INFO): NAL9602 initialized
2024-07-08T19:17:27.515Z,1720466247.515 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2024-07-08T19:17:27.515Z,1720466247.515 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-08T19:17:27.526Z,1720466247.526 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-08T19:17:27.955Z,1720466247.955 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-08T19:17:27.955Z,1720466247.955 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2024-07-08T19:19:36.411Z,1720466376.411 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T19:19:36.411Z,1720466376.411 [Default:CheckIn:C.Wait] Stopped
2024-07-08T19:19:36.411Z,1720466376.411 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T19:19:36.411Z,1720466376.411 [Default:CheckIn:D] Running Loop=1
2024-07-08T19:19:36.822Z,1720466376.822 [Default:CheckIn:D] Stopped
2024-07-08T19:19:36.822Z,1720466376.822 [Default:CheckIn:E] Running Loop=1
2024-07-08T19:19:37.219Z,1720466377.219 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.944602 min
2024-07-08T19:19:37.219Z,1720466377.219 [Default:CheckIn:E] Stopped
2024-07-08T19:19:37.219Z,1720466377.219 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T19:19:37.219Z,1720466377.219 [Default:CheckIn] Stopped
2024-07-08T19:19:37.219Z,1720466377.219 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T19:19:37.220Z,1720466377.220 [Default:CheckIn](INFO): Running loop #2
2024-07-08T19:19:37.220Z,1720466377.220 [Default:CheckIn] Running Loop=2
2024-07-08T19:19:37.220Z,1720466377.220 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T19:19:37.220Z,1720466377.220 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T19:20:28.546Z,1720466428.546 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2024-07-08T19:20:28.546Z,1720466428.546 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-08T19:20:28.557Z,1720466428.557 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-08T19:20:28.955Z,1720466428.955 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-08T19:20:28.955Z,1720466428.955 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2024-07-08T19:23:29.532Z,1720466609.532 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2024-07-08T19:23:29.532Z,1720466609.532 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-08T19:23:29.542Z,1720466609.542 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-08T19:23:29.947Z,1720466609.947 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-08T19:23:29.947Z,1720466609.947 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2024-07-08T19:24:37.384Z,1720466677.384 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-07-08T19:19:37.2Z
2024-07-08T19:24:37.384Z,1720466677.384 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T19:24:37.384Z,1720466677.384 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T19:24:47.020Z,1720466687.020 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240708T190513/Courier0007.lzma
2024-07-08T19:24:48.022Z,1720466688.022 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0007.lzma.bak
2024-07-08T19:24:48.023Z,1720466688.023 [DataOverHttps](INFO): SBD MOMSN=21341409
2024-07-08T19:25:10.374Z,1720466710.374 [DataOverHttps](INFO): Sending 181 bytes from file Logs/20240708T190513/Express0008.lzma
2024-07-08T19:25:11.374Z,1720466711.374 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0008.lzma.bak
2024-07-08T19:25:11.375Z,1720466711.375 [DataOverHttps](INFO): SBD MOMSN=21341411
2024-07-08T19:25:17.434Z,1720466717.434 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T19:25:17.434Z,1720466717.434 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T19:25:17.434Z,1720466717.434 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T19:26:30.530Z,1720466790.530 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2024-07-08T19:26:30.530Z,1720466790.530 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-08T19:26:30.541Z,1720466790.541 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-08T19:26:30.928Z,1720466790.928 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-08T19:26:30.928Z,1720466790.928 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2024-07-08T19:26:48.275Z,1720466808.275 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-07-08T19:26:48.275Z,1720466808.275 [NAL9602] Data Fault, FailCount= 2
2024-07-08T19:26:48.275Z,1720466808.275 [NAL9602](ERROR): Data Fault
2024-07-08T19:26:48.292Z,1720466808.292 [CBIT](ERROR): Data Fault in component: NAL9602
2024-07-08T19:26:48.682Z,1720466808.682 [NAL9602](INFO): Powering down
2024-07-08T19:26:49.519Z,1720466809.519 [CBIT](INFO): Clearing failed state for component NAL9602
2024-07-08T19:26:49.519Z,1720466809.519 [NAL9602] No Fault, FailCount= 2
2024-07-08T19:27:18.978Z,1720466838.978 [NAL9602](INFO): Powering up NAL9602
2024-07-08T19:27:29.890Z,1720466849.890 [NAL9602](INFO): NAL9602 initialized
2024-07-08T19:29:31.506Z,1720466971.506 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2024-07-08T19:29:31.506Z,1720466971.506 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-08T19:29:31.517Z,1720466971.517 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-08T19:29:31.926Z,1720466971.926 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-08T19:29:31.926Z,1720466971.926 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2024-07-08T19:30:17.962Z,1720467017.962 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T19:30:17.962Z,1720467017.962 [Default:CheckIn:C.Wait] Stopped
2024-07-08T19:30:17.962Z,1720467017.962 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T19:30:17.962Z,1720467017.962 [Default:CheckIn:D] Running Loop=1
2024-07-08T19:30:18.376Z,1720467018.376 [Default:CheckIn:D] Stopped
2024-07-08T19:30:18.376Z,1720467018.376 [Default:CheckIn:E] Running Loop=1
2024-07-08T19:30:18.771Z,1720467018.771 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.637174 min
2024-07-08T19:30:18.771Z,1720467018.771 [Default:CheckIn:E] Stopped
2024-07-08T19:30:18.771Z,1720467018.771 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T19:30:18.771Z,1720467018.771 [Default:CheckIn] Stopped
2024-07-08T19:30:18.771Z,1720467018.771 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T19:30:18.771Z,1720467018.771 [Default:CheckIn](INFO): Running loop #3
2024-07-08T19:30:18.771Z,1720467018.771 [Default:CheckIn] Running Loop=3
2024-07-08T19:30:18.771Z,1720467018.771 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T19:30:18.772Z,1720467018.772 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T19:32:32.565Z,1720467152.565 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2024-07-08T19:32:32.565Z,1720467152.565 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-08T19:32:32.576Z,1720467152.576 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-08T19:32:32.985Z,1720467152.985 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-08T19:32:32.985Z,1720467152.985 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2024-07-08T19:35:19.020Z,1720467319.020 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-07-08T19:30:18.8Z
2024-07-08T19:35:19.020Z,1720467319.020 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T19:35:19.020Z,1720467319.020 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T19:35:28.527Z,1720467328.527 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240708T190513/Courier0010.lzma
2024-07-08T19:35:29.529Z,1720467329.529 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0010.lzma.bak
2024-07-08T19:35:29.529Z,1720467329.529 [DataOverHttps](INFO): SBD MOMSN=21341441
2024-07-08T19:35:33.551Z,1720467333.551 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2024-07-08T19:35:33.551Z,1720467333.551 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-08T19:35:33.574Z,1720467333.574 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-08T19:35:33.970Z,1720467333.970 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-08T19:35:33.970Z,1720467333.970 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2024-07-08T19:35:49.427Z,1720467349.427 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240708T190513/Express0011.lzma
2024-07-08T19:35:50.429Z,1720467350.429 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0011.lzma.bak
2024-07-08T19:35:50.429Z,1720467350.429 [DataOverHttps](INFO): SBD MOMSN=21341443
2024-07-08T19:35:59.408Z,1720467359.408 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T19:35:59.408Z,1720467359.408 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T19:35:59.408Z,1720467359.408 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T19:37:32.174Z,1720467452.174 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-07-08T19:37:32.174Z,1720467452.174 [NAL9602] Data Fault, FailCount= 3
2024-07-08T19:37:32.174Z,1720467452.174 [NAL9602](ERROR): Data Fault
2024-07-08T19:37:32.191Z,1720467452.191 [CBIT](ERROR): Data Fault in component: NAL9602
2024-07-08T19:37:32.569Z,1720467452.569 [NAL9602](INFO): Powering down
2024-07-08T19:37:33.398Z,1720467453.398 [CBIT](INFO): Clearing failed state for component NAL9602
2024-07-08T19:37:33.398Z,1720467453.398 [NAL9602] No Fault, FailCount= 3
2024-07-08T19:38:02.870Z,1720467482.870 [NAL9602](INFO): Powering up NAL9602
2024-07-08T19:38:13.781Z,1720467493.781 [NAL9602](INFO): NAL9602 initialized
2024-07-08T19:38:34.405Z,1720467514.405 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2024-07-08T19:38:34.405Z,1720467514.405 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-08T19:38:34.416Z,1720467514.416 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-08T19:38:34.834Z,1720467514.834 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-08T19:38:34.834Z,1720467514.834 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2024-07-08T19:40:59.851Z,1720467659.851 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T19:40:59.851Z,1720467659.851 [Default:CheckIn:C.Wait] Stopped
2024-07-08T19:40:59.851Z,1720467659.851 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T19:40:59.851Z,1720467659.851 [Default:CheckIn:D] Running Loop=1
2024-07-08T19:41:00.258Z,1720467660.258 [Default:CheckIn:D] Stopped
2024-07-08T19:41:00.258Z,1720467660.258 [Default:CheckIn:E] Running Loop=1
2024-07-08T19:41:00.666Z,1720467660.666 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 32.335207 min
2024-07-08T19:41:00.666Z,1720467660.666 [Default:CheckIn:E] Stopped
2024-07-08T19:41:00.666Z,1720467660.666 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T19:41:00.666Z,1720467660.666 [Default:CheckIn] Stopped
2024-07-08T19:41:00.666Z,1720467660.666 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T19:41:00.666Z,1720467660.666 [Default:CheckIn](INFO): Running loop #4
2024-07-08T19:41:00.666Z,1720467660.666 [Default:CheckIn] Running Loop=4
2024-07-08T19:41:00.666Z,1720467660.666 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T19:41:00.666Z,1720467660.666 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T19:41:35.403Z,1720467695.403 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2024-07-08T19:41:35.403Z,1720467695.403 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-08T19:41:35.413Z,1720467695.413 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-08T19:41:35.791Z,1720467695.791 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-08T19:41:35.791Z,1720467695.791 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2024-07-08T19:44:36.376Z,1720467876.376 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2024-07-08T19:44:36.376Z,1720467876.376 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-08T19:44:36.387Z,1720467876.387 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-08T19:44:36.800Z,1720467876.800 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-08T19:44:36.800Z,1720467876.800 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2024-07-08T19:46:00.813Z,1720467960.813 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-07-08T19:41:00.7Z
2024-07-08T19:46:00.814Z,1720467960.814 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T19:46:00.814Z,1720467960.814 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T19:46:09.979Z,1720467969.979 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240708T190513/Courier0013.lzma
2024-07-08T19:46:10.982Z,1720467970.982 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0013.lzma.bak
2024-07-08T19:46:10.982Z,1720467970.982 [DataOverHttps](INFO): SBD MOMSN=21341472
2024-07-08T19:46:34.856Z,1720467994.856 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20240708T190513/Express0014.lzma
2024-07-08T19:46:35.858Z,1720467995.858 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0014.lzma.bak
2024-07-08T19:46:35.858Z,1720467995.858 [DataOverHttps](INFO): SBD MOMSN=21341474
2024-07-08T19:46:41.840Z,1720468001.840 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T19:46:41.841Z,1720468001.841 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T19:46:41.841Z,1720468001.841 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T19:47:37.199Z,1720468057.199 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2024-07-08T19:47:37.199Z,1720468057.199 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-08T19:47:37.210Z,1720468057.210 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-08T19:47:37.621Z,1720468057.621 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-08T19:47:37.621Z,1720468057.621 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2024-07-08T19:48:14.748Z,1720468094.748 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-07-08T19:48:14.748Z,1720468094.748 [NAL9602] Data Fault, FailCount= 4
2024-07-08T19:48:14.748Z,1720468094.748 [NAL9602](ERROR): Data Fault
2024-07-08T19:48:14.789Z,1720468094.789 [CBIT](ERROR): Data Fault in component: NAL9602
2024-07-08T19:48:15.145Z,1720468095.145 [NAL9602](INFO): Powering down
2024-07-08T19:48:15.976Z,1720468095.976 [CBIT](INFO): Clearing failed state for component NAL9602
2024-07-08T19:48:15.976Z,1720468095.976 [NAL9602] No Fault, FailCount= 4
2024-07-08T19:48:45.449Z,1720468125.449 [NAL9602](INFO): Powering up NAL9602
2024-07-08T19:48:56.360Z,1720468136.360 [NAL9602](INFO): NAL9602 initialized
2024-07-08T19:50:38.199Z,1720468238.199 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2024-07-08T19:50:38.199Z,1720468238.199 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-08T19:50:38.210Z,1720468238.210 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-08T19:50:38.589Z,1720468238.589 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-08T19:50:38.589Z,1720468238.589 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2024-07-08T19:51:42.496Z,1720468302.496 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T19:51:42.496Z,1720468302.496 [Default:CheckIn:C.Wait] Stopped
2024-07-08T19:51:42.496Z,1720468302.496 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T19:51:42.496Z,1720468302.496 [Default:CheckIn:D] Running Loop=1
2024-07-08T19:51:42.888Z,1720468302.888 [Default:CheckIn:D] Stopped
2024-07-08T19:51:42.889Z,1720468302.889 [Default:CheckIn:E] Running Loop=1
2024-07-08T19:51:43.331Z,1720468303.331 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 43.045715 min
2024-07-08T19:51:43.331Z,1720468303.331 [Default:CheckIn:E] Stopped
2024-07-08T19:51:43.331Z,1720468303.331 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T19:51:43.331Z,1720468303.331 [Default:CheckIn] Stopped
2024-07-08T19:51:43.331Z,1720468303.331 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T19:51:43.331Z,1720468303.331 [Default:CheckIn](INFO): Running loop #5
2024-07-08T19:51:43.331Z,1720468303.331 [Default:CheckIn] Running Loop=5
2024-07-08T19:51:43.331Z,1720468303.331 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T19:51:43.331Z,1720468303.331 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T19:53:39.235Z,1720468419.235 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2024-07-08T19:53:39.235Z,1720468419.235 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-08T19:53:39.268Z,1720468419.268 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-08T19:53:39.630Z,1720468419.630 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-08T19:53:39.630Z,1720468419.630 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2024-07-08T19:56:40.219Z,1720468600.219 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2024-07-08T19:56:40.219Z,1720468600.219 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-08T19:56:40.229Z,1720468600.229 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-08T19:56:40.642Z,1720468600.642 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-08T19:56:40.643Z,1720468600.643 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2024-07-08T19:56:43.473Z,1720468603.473 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-07-08T19:51:43.3Z
2024-07-08T19:56:43.473Z,1720468603.473 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T19:56:43.473Z,1720468603.473 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T19:56:52.638Z,1720468612.638 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240708T190513/Courier0016.lzma
2024-07-08T19:56:53.640Z,1720468613.640 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0016.lzma.bak
2024-07-08T19:56:53.640Z,1720468613.640 [DataOverHttps](INFO): SBD MOMSN=21341504
2024-07-08T19:57:13.806Z,1720468633.806 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20240708T190513/Express0017.lzma
2024-07-08T19:57:14.808Z,1720468634.808 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0017.lzma.bak
2024-07-08T19:57:14.808Z,1720468634.808 [DataOverHttps](INFO): SBD MOMSN=21341506
2024-07-08T19:57:20.623Z,1720468640.623 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T19:57:20.623Z,1720468640.623 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T19:57:20.624Z,1720468640.624 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T19:58:57.552Z,1720468737.552 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-07-08T19:58:57.552Z,1720468737.552 [NAL9602] Data Fault, FailCount= 5
2024-07-08T19:58:57.552Z,1720468737.552 [NAL9602](ERROR): Data Fault
2024-07-08T19:58:57.569Z,1720468737.569 [CBIT](ERROR): Data Fault in component: NAL9602
2024-07-08T19:58:57.569Z,1720468737.569 [CBIT](CRITICAL): Data Fault in component: NAL9602
2024-07-08T19:58:57.956Z,1720468737.956 [NAL9602](INFO): Powering down
2024-07-08T19:58:57.997Z,1720468737.997 [CommandExec](FAULT): Scheduling is paused
2024-07-08T19:58:57.997Z,1720468737.997 [CBIT](INFO): Critical error at 20240708T195857
2024-07-08T19:58:57.997Z,1720468737.997 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2024-07-08T19:59:41.193Z,1720468781.193 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2024-07-08T19:59:41.193Z,1720468781.193 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-08T19:59:41.228Z,1720468781.228 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-08T19:59:41.611Z,1720468781.611 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-08T19:59:41.611Z,1720468781.611 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2024-07-08T20:00:57.570Z,1720468857.570 [CBIT](INFO): Clearing failed state for component NAL9602
2024-07-08T20:00:57.570Z,1720468857.570 [NAL9602] No Fault, FailCount= 5
2024-07-08T20:00:57.944Z,1720468857.944 [NAL9602](INFO): Powering up NAL9602
2024-07-08T20:01:08.852Z,1720468868.852 [NAL9602](INFO): NAL9602 initialized
2024-07-08T20:02:21.188Z,1720468941.188 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T20:02:21.188Z,1720468941.188 [Default:CheckIn:C.Wait] Stopped
2024-07-08T20:02:21.188Z,1720468941.188 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T20:02:21.188Z,1720468941.188 [Default:CheckIn:D] Running Loop=1
2024-07-08T20:02:21.601Z,1720468941.601 [Default:CheckIn:D] Stopped
2024-07-08T20:02:21.601Z,1720468941.601 [Default:CheckIn:E] Running Loop=1
2024-07-08T20:02:22.063Z,1720468942.063 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 53.690918 min
2024-07-08T20:02:22.063Z,1720468942.063 [Default:CheckIn:E] Stopped
2024-07-08T20:02:22.063Z,1720468942.063 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T20:02:22.063Z,1720468942.063 [Default:CheckIn] Stopped
2024-07-08T20:02:22.063Z,1720468942.063 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T20:02:22.063Z,1720468942.063 [Default:CheckIn](INFO): Running loop #6
2024-07-08T20:02:22.063Z,1720468942.063 [Default:CheckIn] Running Loop=6
2024-07-08T20:02:22.063Z,1720468942.063 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T20:02:22.063Z,1720468942.063 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T20:02:42.189Z,1720468962.189 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2024-07-08T20:02:42.189Z,1720468962.189 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-08T20:02:42.200Z,1720468962.200 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-08T20:02:42.613Z,1720468962.613 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-08T20:02:42.613Z,1720468962.613 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2024-07-08T20:05:43.175Z,1720469143.175 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2024-07-08T20:05:43.175Z,1720469143.175 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2024-07-08T20:05:43.185Z,1720469143.185 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2024-07-08T20:05:43.589Z,1720469143.589 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2024-07-08T20:05:43.589Z,1720469143.589 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20
2024-07-08T20:06:14.507Z,1720469174.507 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,200613.00,A,3648.16450,N,12147.28247,W,0.661,202.61,080724,,,A*70
2024-07-08T20:06:14.510Z,1720469174.510 [NAL9602](INFO): GPS fix at 20240708T200613: (36.802742, -121.788041)
2024-07-08T20:06:14.521Z,1720469174.521 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T20:06:14.521Z,1720469174.521 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T20:06:26.275Z,1720469186.275 [DataOverHttps](INFO): Sending 278 bytes from file Logs/20240708T190513/Courier0019.lzma
2024-07-08T20:06:27.277Z,1720469187.277 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0019.lzma.bak
2024-07-08T20:06:27.277Z,1720469187.277 [DataOverHttps](INFO): SBD MOMSN=21341538
2024-07-08T20:06:47.234Z,1720469207.234 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T20:06:52.616Z,1720469212.616 [DataOverHttps](INFO): Sending 307 bytes from file Logs/20240708T190513/Express0020.lzma
2024-07-08T20:06:53.617Z,1720469213.617 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0020.lzma.bak
2024-07-08T20:06:53.617Z,1720469213.617 [DataOverHttps](INFO): SBD MOMSN=21341542
2024-07-08T20:06:59.770Z,1720469219.770 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T20:06:59.771Z,1720469219.771 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T20:06:59.771Z,1720469219.771 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T20:07:32.529Z,1720469252.529 [CommandExec](IMPORTANT): got command ! echo 1 > /dev/loadB2
2024-07-08T20:08:05.441Z,1720469285.441 [CommandExec](IMPORTANT): got command ! echo 1 > /dev/loadC3
2024-07-08T20:12:00.378Z,1720469520.378 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T20:12:00.379Z,1720469520.379 [Default:CheckIn:C.Wait] Stopped
2024-07-08T20:12:00.379Z,1720469520.379 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T20:12:00.379Z,1720469520.379 [Default:CheckIn:D] Running Loop=1
2024-07-08T20:12:00.779Z,1720469520.779 [Default:CheckIn:D] Stopped
2024-07-08T20:12:00.801Z,1720469520.801 [Default:CheckIn:E] Running Loop=1
2024-07-08T20:12:01.178Z,1720469521.178 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 63.343888 min
2024-07-08T20:12:01.178Z,1720469521.178 [Default:CheckIn:E] Stopped
2024-07-08T20:12:01.179Z,1720469521.179 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T20:12:01.179Z,1720469521.179 [Default:CheckIn] Stopped
2024-07-08T20:12:01.179Z,1720469521.179 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T20:12:01.179Z,1720469521.179 [Default:CheckIn](INFO): Running loop #7
2024-07-08T20:12:01.179Z,1720469521.179 [Default:CheckIn] Running Loop=7
2024-07-08T20:12:01.179Z,1720469521.179 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T20:12:01.179Z,1720469521.179 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T20:12:03.186Z,1720469523.186 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201202.00,A,3648.16702,N,12147.28169,W,0.058,155.15,080724,,,D*75
2024-07-08T20:12:03.189Z,1720469523.189 [NAL9602](INFO): GPS fix at 20240708T201202: (36.802784, -121.788028)
2024-07-08T20:12:03.199Z,1720469523.199 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T20:12:03.199Z,1720469523.199 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T20:12:12.883Z,1720469532.883 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240708T190513/Courier0022.lzma
2024-07-08T20:12:13.885Z,1720469533.885 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0022.lzma.bak
2024-07-08T20:12:13.885Z,1720469533.885 [DataOverHttps](INFO): SBD MOMSN=21341566
2024-07-08T20:12:20.966Z,1720469540.966 [NAL9602](INFO): SBD MO Status=0, MOMSN=5075, MT Status=0, MTMSN=0
2024-07-08T20:12:20.966Z,1720469540.966 [NAL9602](INFO): No messages in MT queue
2024-07-08T20:12:20.966Z,1720469540.966 [NAL9602](INFO): Failure count cleared after critical for NAL9602
2024-07-08T20:12:34.733Z,1720469554.733 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20240708T190513/Express0023.lzma
2024-07-08T20:12:35.736Z,1720469555.736 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0023.lzma.bak
2024-07-08T20:12:35.736Z,1720469555.736 [DataOverHttps](INFO): SBD MOMSN=21341569
2024-07-08T20:12:41.511Z,1720469561.511 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T20:12:41.511Z,1720469561.511 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T20:12:41.511Z,1720469561.511 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T20:12:51.582Z,1720469571.582 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T20:17:42.082Z,1720469862.082 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T20:17:42.082Z,1720469862.082 [Default:CheckIn:C.Wait] Stopped
2024-07-08T20:17:42.082Z,1720469862.082 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T20:17:42.082Z,1720469862.082 [Default:CheckIn:D] Running Loop=1
2024-07-08T20:17:42.496Z,1720469862.496 [Default:CheckIn:D] Stopped
2024-07-08T20:17:42.496Z,1720469862.496 [Default:CheckIn:E] Running Loop=1
2024-07-08T20:17:42.902Z,1720469862.902 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 69.039168 min
2024-07-08T20:17:42.902Z,1720469862.902 [Default:CheckIn:E] Stopped
2024-07-08T20:17:42.902Z,1720469862.902 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T20:17:42.902Z,1720469862.902 [Default:CheckIn] Stopped
2024-07-08T20:17:42.902Z,1720469862.902 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T20:17:42.902Z,1720469862.902 [Default:CheckIn](INFO): Running loop #8
2024-07-08T20:17:42.902Z,1720469862.902 [Default:CheckIn] Running Loop=8
2024-07-08T20:17:42.903Z,1720469862.903 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T20:17:42.903Z,1720469862.903 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T20:17:44.888Z,1720469864.888 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,201744.00,A,3648.16340,N,12147.28212,W,0.194,336.88,080724,,,A*78
2024-07-08T20:17:44.894Z,1720469864.894 [NAL9602](INFO): GPS fix at 20240708T201744: (36.802723, -121.788035)
2024-07-08T20:17:44.905Z,1720469864.905 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T20:17:44.905Z,1720469864.905 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T20:17:48.130Z,1720469868.130 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T20:17:54.961Z,1720469874.961 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240708T190513/Courier0025.lzma
2024-07-08T20:17:55.963Z,1720469875.963 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0025.lzma.bak
2024-07-08T20:17:55.964Z,1720469875.964 [DataOverHttps](INFO): SBD MOMSN=21341581
2024-07-08T20:18:03.075Z,1720469883.075 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T20:18:18.013Z,1720469898.013 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T20:18:22.973Z,1720469902.973 [DataOverHttps](INFO): Sending 129 bytes from file Logs/20240708T190513/Express0026.lzma
2024-07-08T20:18:23.975Z,1720469903.975 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0026.lzma.bak
2024-07-08T20:18:23.976Z,1720469903.976 [DataOverHttps](INFO): SBD MOMSN=21341584
2024-07-08T20:18:30.168Z,1720469910.168 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T20:18:30.168Z,1720469910.168 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T20:18:30.169Z,1720469910.169 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T20:18:33.375Z,1720469913.375 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T20:18:48.716Z,1720469928.716 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T20:18:53.577Z,1720469933.577 [NAL9602](INFO): SBD MO Status=2, MOMSN=5076, MT Status=2, MTMSN=0
2024-07-08T20:18:53.577Z,1720469933.577 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-08T20:19:03.665Z,1720469943.665 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T20:19:04.092Z,1720469944.092 [NAL9602](INFO): SBD MO Status=0, MOMSN=5076, MT Status=0, MTMSN=0
2024-07-08T20:19:04.092Z,1720469944.092 [NAL9602](INFO): No messages in MT queue
2024-07-08T20:19:19.034Z,1720469959.034 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T20:19:33.986Z,1720469973.986 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T20:19:34.814Z,1720469974.814 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T20:19:49.346Z,1720469989.346 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T20:20:04.685Z,1720470004.685 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T20:20:15.208Z,1720470015.208 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5.
2024-07-08T20:20:15.215Z,1720470015.215 [BPC1](INFO): Received data from all battery sticks.
2024-07-08T20:21:36.894Z,1720470096.894 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2024-07-08T20:21:44.934Z,1720470104.934 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.464104
2024-07-08T20:23:00.894Z,1720470180.894 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002876
2024-07-08T20:23:30.740Z,1720470210.740 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T20:23:30.740Z,1720470210.740 [Default:CheckIn:C.Wait] Stopped
2024-07-08T20:23:30.740Z,1720470210.740 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T20:23:30.740Z,1720470210.740 [Default:CheckIn:D] Running Loop=1
2024-07-08T20:23:31.140Z,1720470211.140 [Default:CheckIn:D] Stopped
2024-07-08T20:23:31.140Z,1720470211.140 [Default:CheckIn:E] Running Loop=1
2024-07-08T20:23:31.545Z,1720470211.545 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 74.849902 min
2024-07-08T20:23:31.545Z,1720470211.545 [Default:CheckIn:E] Stopped
2024-07-08T20:23:31.545Z,1720470211.545 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T20:23:31.545Z,1720470211.545 [Default:CheckIn] Stopped
2024-07-08T20:23:31.546Z,1720470211.546 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T20:23:31.546Z,1720470211.546 [Default:CheckIn](INFO): Running loop #9
2024-07-08T20:23:31.546Z,1720470211.546 [Default:CheckIn] Running Loop=9
2024-07-08T20:23:31.546Z,1720470211.546 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T20:23:31.546Z,1720470211.546 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T20:23:33.556Z,1720470213.556 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202333.00,A,3648.16462,N,12147.28657,W,0.117,336.88,080724,,,A*76
2024-07-08T20:23:33.568Z,1720470213.568 [NAL9602](INFO): GPS fix at 20240708T202333: (36.802744, -121.788110)
2024-07-08T20:23:33.590Z,1720470213.590 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T20:23:33.590Z,1720470213.590 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T20:23:38.046Z,1720470218.046 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20240708T190513/Courier0028.lzma
2024-07-08T20:23:39.051Z,1720470219.051 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0028.lzma.bak
2024-07-08T20:23:39.052Z,1720470219.052 [DataOverHttps](INFO): SBD MOMSN=21341606
2024-07-08T20:24:02.573Z,1720470242.573 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240708T190513/Express0029.lzma
2024-07-08T20:24:03.575Z,1720470243.575 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0029.lzma.bak
2024-07-08T20:24:03.576Z,1720470243.576 [DataOverHttps](INFO): SBD MOMSN=21341609
2024-07-08T20:24:10.839Z,1720470250.839 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T20:24:10.839Z,1720470250.839 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T20:24:10.839Z,1720470250.839 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T20:24:12.041Z,1720470252.041 [CommandLine](INFO): End of History
2024-07-08T20:24:13.903Z,1720470253.903 [CommandLine](INFO): End of History
2024-07-08T20:24:26.350Z,1720470266.350 [CommandExec](IMPORTANT): got command failComponent
2024-07-08T20:24:26.350Z,1720470266.350 [CommandExec](IMPORTANT): Failed components:
2024-07-08T20:24:26.351Z,1720470266.351 [CommandExec](IMPORTANT): No failed Components.
2024-07-08T20:28:36.251Z,1720470516.251 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-08T20:29:06.960Z,1720470546.960 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T20:29:11.418Z,1720470551.418 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T20:29:11.418Z,1720470551.418 [Default:CheckIn:C.Wait] Stopped
2024-07-08T20:29:11.418Z,1720470551.418 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T20:29:11.418Z,1720470551.418 [Default:CheckIn:D] Running Loop=1
2024-07-08T20:29:11.823Z,1720470551.823 [Default:CheckIn:D] Stopped
2024-07-08T20:29:11.823Z,1720470551.823 [Default:CheckIn:E] Running Loop=1
2024-07-08T20:29:12.217Z,1720470552.217 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 80.527962 min
2024-07-08T20:29:12.217Z,1720470552.217 [Default:CheckIn:E] Stopped
2024-07-08T20:29:12.218Z,1720470552.218 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T20:29:12.218Z,1720470552.218 [Default:CheckIn] Stopped
2024-07-08T20:29:12.218Z,1720470552.218 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T20:29:12.218Z,1720470552.218 [Default:CheckIn](INFO): Running loop #10
2024-07-08T20:29:12.218Z,1720470552.218 [Default:CheckIn] Running Loop=10
2024-07-08T20:29:12.218Z,1720470552.218 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T20:29:12.218Z,1720470552.218 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T20:29:14.246Z,1720470554.246 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,202913.00,A,3648.16279,N,12147.28102,W,0.194,152.15,080724,,,D*7F
2024-07-08T20:29:14.248Z,1720470554.248 [NAL9602](INFO): GPS fix at 20240708T202913: (36.802713, -121.788017)
2024-07-08T20:29:14.323Z,1720470554.323 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T20:29:14.323Z,1720470554.323 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T20:29:19.641Z,1720470559.641 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240708T190513/Courier0031.lzma
2024-07-08T20:29:20.645Z,1720470560.645 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0031.lzma.bak
2024-07-08T20:29:20.645Z,1720470560.645 [DataOverHttps](INFO): SBD MOMSN=21341618
2024-07-08T20:29:45.025Z,1720470585.025 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-07-08T20:29:45.102Z,1720470585.102 [NAL9602](ERROR): received:
+CSQ:0
OK76, 0, 0, 0, 0
OK
2024-07-08T20:29:46.307Z,1720470586.307 [DataOverHttps](INFO): Sending 256 bytes from file Logs/20240708T190513/Express0032.lzma
2024-07-08T20:29:47.309Z,1720470587.309 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0032.lzma.bak
2024-07-08T20:29:47.309Z,1720470587.309 [DataOverHttps](INFO): SBD MOMSN=21341624
2024-07-08T20:29:53.541Z,1720470593.541 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T20:29:53.541Z,1720470593.541 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T20:29:53.541Z,1720470593.541 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T20:30:56.537Z,1720470656.537 [NAL9602](INFO): SBD MO Status=2, MOMSN=5077, MT Status=2, MTMSN=0
2024-07-08T20:30:56.538Z,1720470656.538 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-08T20:31:15.121Z,1720470675.121 [NAL9602](INFO): SBD MO Status=0, MOMSN=5077, MT Status=0, MTMSN=0
2024-07-08T20:31:15.122Z,1720470675.122 [NAL9602](INFO): No messages in MT queue
2024-07-08T20:31:45.820Z,1720470705.820 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T20:34:54.120Z,1720470894.120 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T20:34:54.120Z,1720470894.120 [Default:CheckIn:C.Wait] Stopped
2024-07-08T20:34:54.120Z,1720470894.120 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T20:34:54.120Z,1720470894.120 [Default:CheckIn:D] Running Loop=1
2024-07-08T20:34:54.515Z,1720470894.515 [Default:CheckIn:D] Stopped
2024-07-08T20:34:54.519Z,1720470894.519 [Default:CheckIn:E] Running Loop=1
2024-07-08T20:34:54.949Z,1720470894.949 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 86.239486 min
2024-07-08T20:34:54.949Z,1720470894.949 [Default:CheckIn:E] Stopped
2024-07-08T20:34:54.949Z,1720470894.949 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T20:34:54.949Z,1720470894.949 [Default:CheckIn] Stopped
2024-07-08T20:34:54.949Z,1720470894.949 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T20:34:54.949Z,1720470894.949 [Default:CheckIn](INFO): Running loop #11
2024-07-08T20:34:54.950Z,1720470894.950 [Default:CheckIn] Running Loop=11
2024-07-08T20:34:54.950Z,1720470894.950 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T20:34:54.950Z,1720470894.950 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T20:34:56.914Z,1720470896.914 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,203456.00,A,3648.16459,N,12147.29078,W,0.447,152.15,080724,,,D*70
2024-07-08T20:34:56.916Z,1720470896.916 [NAL9602](INFO): GPS fix at 20240708T203456: (36.802743, -121.788180)
2024-07-08T20:34:56.926Z,1720470896.926 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T20:34:56.926Z,1720470896.926 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T20:35:06.563Z,1720470906.563 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240708T190513/Courier0034.lzma
2024-07-08T20:35:07.565Z,1720470907.565 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0034.lzma.bak
2024-07-08T20:35:07.565Z,1720470907.565 [DataOverHttps](INFO): SBD MOMSN=21341653
2024-07-08T20:35:28.391Z,1720470928.391 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20240708T190513/Express0035.lzma
2024-07-08T20:35:29.393Z,1720470929.393 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0035.lzma.bak
2024-07-08T20:35:29.393Z,1720470929.393 [DataOverHttps](INFO): SBD MOMSN=21341659
2024-07-08T20:35:39.376Z,1720470939.376 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T20:35:39.376Z,1720470939.376 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T20:35:39.376Z,1720470939.376 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T20:38:57.326Z,1720471137.326 [NAL9602](INFO): SBD MO Status=2, MOMSN=5078, MT Status=2, MTMSN=0
2024-07-08T20:38:57.326Z,1720471137.326 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-08T20:39:47.827Z,1720471187.827 [NAL9602](INFO): SBD MO Status=2, MOMSN=5078, MT Status=2, MTMSN=0
2024-07-08T20:39:47.827Z,1720471187.827 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-08T20:40:17.317Z,1720471217.317 [NAL9602](INFO): SBD MO Status=2, MOMSN=5078, MT Status=2, MTMSN=0
2024-07-08T20:40:17.318Z,1720471217.318 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-08T20:40:17.717Z,1720471217.717 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-08T20:40:39.978Z,1720471239.978 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T20:40:39.978Z,1720471239.978 [Default:CheckIn:C.Wait] Stopped
2024-07-08T20:40:39.978Z,1720471239.978 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T20:40:39.978Z,1720471239.978 [Default:CheckIn:D] Running Loop=1
2024-07-08T20:40:40.391Z,1720471240.391 [Default:CheckIn:D] Stopped
2024-07-08T20:40:40.391Z,1720471240.391 [Default:CheckIn:E] Running Loop=1
2024-07-08T20:40:40.796Z,1720471240.796 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 92.004077 min
2024-07-08T20:40:40.796Z,1720471240.796 [Default:CheckIn:E] Stopped
2024-07-08T20:40:40.796Z,1720471240.796 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T20:40:40.796Z,1720471240.796 [Default:CheckIn] Stopped
2024-07-08T20:40:40.796Z,1720471240.796 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T20:40:40.796Z,1720471240.796 [Default:CheckIn](INFO): Running loop #12
2024-07-08T20:40:40.797Z,1720471240.797 [Default:CheckIn] Running Loop=12
2024-07-08T20:40:40.797Z,1720471240.797 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T20:40:40.797Z,1720471240.797 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T20:40:42.798Z,1720471242.798 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204042.00,A,3648.16321,N,12147.27937,W,0.272,231.70,080724,,,A*72
2024-07-08T20:40:42.800Z,1720471242.800 [NAL9602](INFO): GPS fix at 20240708T204042: (36.802720, -121.787989)
2024-07-08T20:40:42.832Z,1720471242.832 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T20:40:42.832Z,1720471242.832 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T20:40:52.338Z,1720471252.338 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240708T190513/Courier0037.lzma
2024-07-08T20:40:53.341Z,1720471253.341 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0037.lzma.bak
2024-07-08T20:40:53.341Z,1720471253.341 [DataOverHttps](INFO): SBD MOMSN=21341668
2024-07-08T20:41:13.823Z,1720471273.823 [DataOverHttps](INFO): Sending 204 bytes from file Logs/20240708T190513/Express0038.lzma
2024-07-08T20:41:14.825Z,1720471274.825 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0038.lzma.bak
2024-07-08T20:41:14.825Z,1720471274.825 [DataOverHttps](INFO): SBD MOMSN=21341673
2024-07-08T20:41:15.112Z,1720471275.112 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T20:41:24.021Z,1720471284.021 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T20:41:24.021Z,1720471284.021 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T20:41:24.021Z,1720471284.021 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T20:46:24.604Z,1720471584.604 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T20:46:24.604Z,1720471584.604 [Default:CheckIn:C.Wait] Stopped
2024-07-08T20:46:24.604Z,1720471584.604 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T20:46:24.608Z,1720471584.608 [Default:CheckIn:D] Running Loop=1
2024-07-08T20:46:24.994Z,1720471584.994 [Default:CheckIn:D] Stopped
2024-07-08T20:46:24.995Z,1720471584.995 [Default:CheckIn:E] Running Loop=1
2024-07-08T20:46:25.412Z,1720471585.412 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 97.747477 min
2024-07-08T20:46:25.412Z,1720471585.412 [Default:CheckIn:E] Stopped
2024-07-08T20:46:25.412Z,1720471585.412 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T20:46:25.412Z,1720471585.412 [Default:CheckIn] Stopped
2024-07-08T20:46:25.412Z,1720471585.412 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T20:46:25.413Z,1720471585.413 [Default:CheckIn](INFO): Running loop #13
2024-07-08T20:46:25.413Z,1720471585.413 [Default:CheckIn] Running Loop=13
2024-07-08T20:46:25.413Z,1720471585.413 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T20:46:25.413Z,1720471585.413 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T20:46:27.411Z,1720471587.411 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,204626.00,A,3648.16481,N,12147.28230,W,0.719,231.70,080724,,,A*70
2024-07-08T20:46:27.413Z,1720471587.413 [NAL9602](INFO): GPS fix at 20240708T204626: (36.802747, -121.788038)
2024-07-08T20:46:27.446Z,1720471587.446 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T20:46:27.446Z,1720471587.446 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T20:46:37.440Z,1720471597.440 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240708T190513/Courier0040.lzma
2024-07-08T20:46:38.444Z,1720471598.444 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0040.lzma.bak
2024-07-08T20:46:38.444Z,1720471598.444 [DataOverHttps](INFO): SBD MOMSN=21341698
2024-07-08T20:47:02.238Z,1720471622.238 [DataOverHttps](INFO): Sending 128 bytes from file Logs/20240708T190513/Express0041.lzma
2024-07-08T20:47:02.718Z,1720471622.718 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0041.lzma.bak
2024-07-08T20:47:02.718Z,1720471622.718 [DataOverHttps](INFO): SBD MOMSN=21341701
2024-07-08T20:47:08.644Z,1720471628.644 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T20:47:08.644Z,1720471628.644 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T20:47:08.644Z,1720471628.644 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T20:50:42.754Z,1720471842.754 [NAL9602](INFO): SBD MO Status=2, MOMSN=5078, MT Status=2, MTMSN=0
2024-07-08T20:50:42.754Z,1720471842.754 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-08T20:51:29.214Z,1720471889.214 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-08T20:51:59.937Z,1720471919.937 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T20:52:09.243Z,1720471929.243 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T20:52:09.243Z,1720471929.243 [Default:CheckIn:C.Wait] Stopped
2024-07-08T20:52:09.243Z,1720471929.243 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T20:52:09.243Z,1720471929.243 [Default:CheckIn:D] Running Loop=1
2024-07-08T20:52:09.644Z,1720471929.644 [Default:CheckIn:D] Stopped
2024-07-08T20:52:09.644Z,1720471929.644 [Default:CheckIn:E] Running Loop=1
2024-07-08T20:52:10.064Z,1720471930.064 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 103.491634 min
2024-07-08T20:52:10.064Z,1720471930.064 [Default:CheckIn:E] Stopped
2024-07-08T20:52:10.064Z,1720471930.064 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T20:52:10.065Z,1720471930.065 [Default:CheckIn] Stopped
2024-07-08T20:52:10.065Z,1720471930.065 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T20:52:10.065Z,1720471930.065 [Default:CheckIn](INFO): Running loop #14
2024-07-08T20:52:10.065Z,1720471930.065 [Default:CheckIn] Running Loop=14
2024-07-08T20:52:10.065Z,1720471930.065 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T20:52:10.065Z,1720471930.065 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T20:52:12.059Z,1720471932.059 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205211.00,A,3648.16432,N,12147.28262,W,0.117,0.00,080724,,,A*71
2024-07-08T20:52:12.061Z,1720471932.061 [NAL9602](INFO): GPS fix at 20240708T205211: (36.802739, -121.788044)
2024-07-08T20:52:12.125Z,1720471932.125 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T20:52:12.125Z,1720471932.125 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T20:52:23.220Z,1720471943.220 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240708T190513/Courier0043.lzma
2024-07-08T20:52:24.222Z,1720471944.222 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0043.lzma.bak
2024-07-08T20:52:24.222Z,1720471944.222 [DataOverHttps](INFO): SBD MOMSN=21341718
2024-07-08T20:52:42.686Z,1720471962.686 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-07-08T20:52:42.760Z,1720471962.760 [NAL9602](ERROR): received:
+CSQ:0
OK78, 2, 0, 0, 0
OK
2024-07-08T20:52:48.260Z,1720471968.260 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20240708T190513/Express0044.lzma
2024-07-08T20:52:49.262Z,1720471969.262 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0044.lzma.bak
2024-07-08T20:52:49.262Z,1720471969.262 [DataOverHttps](INFO): SBD MOMSN=21341723
2024-07-08T20:52:55.639Z,1720471975.639 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T20:52:55.639Z,1720471975.639 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T20:52:55.639Z,1720471975.639 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T20:57:14.173Z,1720472234.173 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-08T20:57:44.885Z,1720472264.885 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T20:57:56.229Z,1720472276.229 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T20:57:56.229Z,1720472276.229 [Default:CheckIn:C.Wait] Stopped
2024-07-08T20:57:56.229Z,1720472276.229 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T20:57:56.229Z,1720472276.229 [Default:CheckIn:D] Running Loop=1
2024-07-08T20:57:56.625Z,1720472276.625 [Default:CheckIn:D] Stopped
2024-07-08T20:57:56.625Z,1720472276.625 [Default:CheckIn:E] Running Loop=1
2024-07-08T20:57:57.079Z,1720472277.079 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 109.274658 min
2024-07-08T20:57:57.079Z,1720472277.079 [Default:CheckIn:E] Stopped
2024-07-08T20:57:57.079Z,1720472277.079 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T20:57:57.080Z,1720472277.080 [Default:CheckIn] Stopped
2024-07-08T20:57:57.080Z,1720472277.080 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T20:57:57.080Z,1720472277.080 [Default:CheckIn](INFO): Running loop #15
2024-07-08T20:57:57.080Z,1720472277.080 [Default:CheckIn] Running Loop=15
2024-07-08T20:57:57.080Z,1720472277.080 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T20:57:57.080Z,1720472277.080 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T20:57:59.023Z,1720472279.023 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,205758.00,A,3648.15758,N,12147.27890,W,0.253,278.59,080724,,,A*7F
2024-07-08T20:57:59.025Z,1720472279.025 [NAL9602](INFO): GPS fix at 20240708T205758: (36.802626, -121.787982)
2024-07-08T20:57:59.036Z,1720472279.036 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T20:57:59.036Z,1720472279.036 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T20:58:08.832Z,1720472288.832 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240708T190513/Courier0046.lzma
2024-07-08T20:58:09.834Z,1720472289.834 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0046.lzma.bak
2024-07-08T20:58:09.834Z,1720472289.834 [DataOverHttps](INFO): SBD MOMSN=21341737
2024-07-08T20:58:29.720Z,1720472309.720 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-07-08T20:58:29.796Z,1720472309.796 [NAL9602](ERROR): received:
+CSQ:0
OK78, 2, 0, 0, 0
OK
2024-07-08T20:58:34.268Z,1720472314.268 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20240708T190513/Express0047.lzma
2024-07-08T20:58:35.271Z,1720472315.271 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0047.lzma.bak
2024-07-08T20:58:35.271Z,1720472315.271 [DataOverHttps](INFO): SBD MOMSN=21341740
2024-07-08T20:58:41.483Z,1720472321.483 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T20:58:41.483Z,1720472321.483 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T20:58:41.483Z,1720472321.483 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T20:59:58.793Z,1720472398.793 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-08T21:00:02.809Z,1720472402.809 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.239269
2024-07-08T21:00:33.933Z,1720472433.933 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-08T21:01:09.073Z,1720472469.073 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-08T21:01:21.385Z,1720472481.385 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002962
2024-07-08T21:03:00.809Z,1720472580.809 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-08T21:03:31.517Z,1720472611.517 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T21:03:42.026Z,1720472622.026 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T21:03:42.026Z,1720472622.026 [Default:CheckIn:C.Wait] Stopped
2024-07-08T21:03:42.026Z,1720472622.026 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T21:03:42.026Z,1720472622.026 [Default:CheckIn:D] Running Loop=1
2024-07-08T21:03:42.435Z,1720472622.435 [Default:CheckIn:D] Stopped
2024-07-08T21:03:42.435Z,1720472622.435 [Default:CheckIn:E] Running Loop=1
2024-07-08T21:03:42.842Z,1720472622.842 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 115.038151 min
2024-07-08T21:03:42.842Z,1720472622.842 [Default:CheckIn:E] Stopped
2024-07-08T21:03:42.843Z,1720472622.843 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T21:03:42.843Z,1720472622.843 [Default:CheckIn] Stopped
2024-07-08T21:03:42.843Z,1720472622.843 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T21:03:42.843Z,1720472622.843 [Default:CheckIn](INFO): Running loop #16
2024-07-08T21:03:42.843Z,1720472622.843 [Default:CheckIn] Running Loop=16
2024-07-08T21:03:42.843Z,1720472622.843 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T21:03:42.843Z,1720472622.843 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T21:03:44.851Z,1720472624.851 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210344.00,A,3648.16220,N,12147.28227,W,0.233,278.59,080724,,,A*74
2024-07-08T21:03:44.853Z,1720472624.853 [NAL9602](INFO): GPS fix at 20240708T210344: (36.802703, -121.788038)
2024-07-08T21:03:44.879Z,1720472624.879 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T21:03:44.879Z,1720472624.879 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T21:03:55.060Z,1720472635.060 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240708T190513/Courier0049.lzma
2024-07-08T21:03:56.063Z,1720472636.063 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0049.lzma.bak
2024-07-08T21:03:56.063Z,1720472636.063 [DataOverHttps](INFO): SBD MOMSN=21341764
2024-07-08T21:04:15.550Z,1720472655.550 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-07-08T21:04:15.628Z,1720472655.628 [NAL9602](ERROR): received:
+CSQ:0
OK78, 2, 0, 0, 0
OK
2024-07-08T21:04:16.839Z,1720472656.839 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20240708T190513/Express0050.lzma
2024-07-08T21:04:17.838Z,1720472657.838 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0050.lzma.bak
2024-07-08T21:04:17.838Z,1720472657.838 [DataOverHttps](INFO): SBD MOMSN=21341767
2024-07-08T21:04:25.335Z,1720472665.335 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T21:04:25.335Z,1720472665.335 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T21:04:25.336Z,1720472665.336 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T21:05:31.119Z,1720472731.119 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-07-08T21:08:46.658Z,1720472926.658 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-08T21:09:17.356Z,1720472957.356 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T21:09:25.851Z,1720472965.851 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T21:09:25.851Z,1720472965.851 [Default:CheckIn:C.Wait] Stopped
2024-07-08T21:09:25.851Z,1720472965.851 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T21:09:25.852Z,1720472965.852 [Default:CheckIn:D] Running Loop=1
2024-07-08T21:09:26.269Z,1720472966.269 [Default:CheckIn:D] Stopped
2024-07-08T21:09:26.269Z,1720472966.269 [Default:CheckIn:E] Running Loop=1
2024-07-08T21:09:26.675Z,1720472966.675 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 120.768726 min
2024-07-08T21:09:26.675Z,1720472966.675 [Default:CheckIn:E] Stopped
2024-07-08T21:09:26.675Z,1720472966.675 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T21:09:26.675Z,1720472966.675 [Default:CheckIn] Stopped
2024-07-08T21:09:26.675Z,1720472966.675 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T21:09:26.676Z,1720472966.676 [Default:CheckIn](INFO): Running loop #17
2024-07-08T21:09:26.676Z,1720472966.676 [Default:CheckIn] Running Loop=17
2024-07-08T21:09:26.676Z,1720472966.676 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T21:09:26.676Z,1720472966.676 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T21:09:28.676Z,1720472968.676 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,210928.00,A,3648.16546,N,12147.28227,W,0.156,184.64,080724,,,A*7D
2024-07-08T21:09:28.678Z,1720472968.678 [NAL9602](INFO): GPS fix at 20240708T210928: (36.802758, -121.788038)
2024-07-08T21:09:28.689Z,1720472968.689 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T21:09:28.689Z,1720472968.689 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T21:09:38.388Z,1720472978.388 [DataOverHttps](INFO): Sending 71 bytes from file Logs/20240708T190513/Courier0052.lzma
2024-07-08T21:09:39.390Z,1720472979.390 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0052.lzma.bak
2024-07-08T21:09:39.390Z,1720472979.390 [DataOverHttps](INFO): SBD MOMSN=21341777
2024-07-08T21:10:00.540Z,1720473000.540 [DataOverHttps](INFO): Sending 217 bytes from file Logs/20240708T190513/Express0053.lzma
2024-07-08T21:10:01.542Z,1720473001.542 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0053.lzma.bak
2024-07-08T21:10:01.543Z,1720473001.543 [DataOverHttps](INFO): SBD MOMSN=21341780
2024-07-08T21:10:07.472Z,1720473007.472 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T21:10:07.476Z,1720473007.476 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T21:10:07.476Z,1720473007.476 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T21:14:30.486Z,1720473270.486 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-08T21:15:01.213Z,1720473301.213 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T21:15:08.091Z,1720473308.091 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T21:15:08.091Z,1720473308.091 [Default:CheckIn:C.Wait] Stopped
2024-07-08T21:15:08.091Z,1720473308.091 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T21:15:08.091Z,1720473308.091 [Default:CheckIn:D] Running Loop=1
2024-07-08T21:15:08.500Z,1720473308.500 [Default:CheckIn:D] Stopped
2024-07-08T21:15:08.504Z,1720473308.504 [Default:CheckIn:E] Running Loop=1
2024-07-08T21:15:08.940Z,1720473308.940 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 126.472567 min
2024-07-08T21:15:08.940Z,1720473308.940 [Default:CheckIn:E] Stopped
2024-07-08T21:15:08.941Z,1720473308.941 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T21:15:08.941Z,1720473308.941 [Default:CheckIn] Stopped
2024-07-08T21:15:08.941Z,1720473308.941 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T21:15:08.941Z,1720473308.941 [Default:CheckIn](INFO): Running loop #18
2024-07-08T21:15:08.941Z,1720473308.941 [Default:CheckIn] Running Loop=18
2024-07-08T21:15:08.941Z,1720473308.941 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T21:15:08.941Z,1720473308.941 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T21:15:10.903Z,1720473310.903 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,211510.00,A,3648.16009,N,12147.28359,W,0.156,92.16,080724,,,A*4E
2024-07-08T21:15:10.905Z,1720473310.905 [NAL9602](INFO): GPS fix at 20240708T211510: (36.802668, -121.788060)
2024-07-08T21:15:10.932Z,1720473310.932 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T21:15:10.932Z,1720473310.932 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T21:15:20.760Z,1720473320.760 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240708T190513/Courier0055.lzma
2024-07-08T21:15:21.762Z,1720473321.762 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0055.lzma.bak
2024-07-08T21:15:21.762Z,1720473321.762 [DataOverHttps](INFO): SBD MOMSN=21341803
2024-07-08T21:15:31.511Z,1720473331.511 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-07-08T21:15:41.606Z,1720473341.606 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-07-08T21:15:41.688Z,1720473341.688 [NAL9602](ERROR): received:
+CSQ:0
OK78, 2, 0, 0, 0
OK
2024-07-08T21:15:46.016Z,1720473346.016 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20240708T190513/Express0056.lzma
2024-07-08T21:15:47.018Z,1720473347.018 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0056.lzma.bak
2024-07-08T21:15:47.018Z,1720473347.018 [DataOverHttps](INFO): SBD MOMSN=21341806
2024-07-08T21:15:52.948Z,1720473352.948 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T21:15:52.970Z,1720473352.970 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T21:15:52.970Z,1720473352.970 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T21:20:12.690Z,1720473612.690 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-08T21:20:43.401Z,1720473643.401 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T21:20:53.521Z,1720473653.521 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T21:20:53.521Z,1720473653.521 [Default:CheckIn:C.Wait] Stopped
2024-07-08T21:20:53.521Z,1720473653.521 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T21:20:53.522Z,1720473653.522 [Default:CheckIn:D] Running Loop=1
2024-07-08T21:20:53.927Z,1720473653.927 [Default:CheckIn:D] Stopped
2024-07-08T21:20:53.927Z,1720473653.927 [Default:CheckIn:E] Running Loop=1
2024-07-08T21:20:54.313Z,1720473654.313 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 132.229696 min
2024-07-08T21:20:54.313Z,1720473654.313 [Default:CheckIn:E] Stopped
2024-07-08T21:20:54.313Z,1720473654.313 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T21:20:54.313Z,1720473654.313 [Default:CheckIn] Stopped
2024-07-08T21:20:54.313Z,1720473654.313 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T21:20:54.313Z,1720473654.313 [Default:CheckIn](INFO): Running loop #19
2024-07-08T21:20:54.313Z,1720473654.313 [Default:CheckIn] Running Loop=19
2024-07-08T21:20:54.313Z,1720473654.313 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T21:20:54.314Z,1720473654.314 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T21:20:56.328Z,1720473656.328 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212056.00,A,3648.16333,N,12147.28184,W,0.972,50.11,080724,,,A*45
2024-07-08T21:20:56.330Z,1720473656.330 [NAL9602](INFO): GPS fix at 20240708T212056: (36.802722, -121.788031)
2024-07-08T21:20:56.340Z,1720473656.340 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T21:20:56.340Z,1720473656.340 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T21:21:06.128Z,1720473666.128 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240708T190513/Courier0058.lzma
2024-07-08T21:21:07.130Z,1720473667.130 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0058.lzma.bak
2024-07-08T21:21:07.130Z,1720473667.130 [DataOverHttps](INFO): SBD MOMSN=21341816
2024-07-08T21:21:27.030Z,1720473687.030 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-07-08T21:21:27.108Z,1720473687.108 [NAL9602](ERROR): received:
+CSQ:0
OK78, 2, 0, 0, 0
OK
2024-07-08T21:21:29.184Z,1720473689.184 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20240708T190513/Express0059.lzma
2024-07-08T21:21:30.186Z,1720473690.186 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0059.lzma.bak
2024-07-08T21:21:30.186Z,1720473690.186 [DataOverHttps](INFO): SBD MOMSN=21341819
2024-07-08T21:21:36.483Z,1720473696.483 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T21:21:36.483Z,1720473696.483 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T21:21:36.483Z,1720473696.483 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T21:25:13.080Z,1720473913.080 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T21:25:28.418Z,1720473928.418 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T21:25:31.654Z,1720473931.654 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-07-08T21:25:43.372Z,1720473943.372 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T21:25:53.872Z,1720473953.872 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2024-07-08T21:25:58.761Z,1720473958.761 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-08T21:25:59.121Z,1720473959.121 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T21:26:14.474Z,1720473974.474 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T21:26:29.466Z,1720473989.466 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T21:26:29.826Z,1720473989.826 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T21:26:37.136Z,1720473997.136 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T21:26:37.136Z,1720473997.136 [Default:CheckIn:C.Wait] Stopped
2024-07-08T21:26:37.136Z,1720473997.136 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T21:26:37.136Z,1720473997.136 [Default:CheckIn:D] Running Loop=1
2024-07-08T21:26:37.552Z,1720473997.552 [Default:CheckIn:D] Stopped
2024-07-08T21:26:37.552Z,1720473997.552 [Default:CheckIn:E] Running Loop=1
2024-07-08T21:26:37.948Z,1720473997.948 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 137.956771 min
2024-07-08T21:26:37.948Z,1720473997.948 [Default:CheckIn:E] Stopped
2024-07-08T21:26:37.948Z,1720473997.948 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T21:26:37.948Z,1720473997.948 [Default:CheckIn] Stopped
2024-07-08T21:26:37.948Z,1720473997.948 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T21:26:37.948Z,1720473997.948 [Default:CheckIn](INFO): Running loop #20
2024-07-08T21:26:37.948Z,1720473997.948 [Default:CheckIn] Running Loop=20
2024-07-08T21:26:37.949Z,1720473997.949 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T21:26:37.949Z,1720473997.949 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T21:26:39.930Z,1720473999.930 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,212639.00,A,3648.16460,N,12147.28617,W,0.078,51.02,080724,,,A*46
2024-07-08T21:26:39.932Z,1720473999.932 [NAL9602](INFO): GPS fix at 20240708T212639: (36.802743, -121.788103)
2024-07-08T21:26:39.962Z,1720473999.962 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T21:26:39.962Z,1720473999.962 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T21:26:44.774Z,1720474004.774 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T21:26:50.098Z,1720474010.098 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240708T190513/Courier0061.lzma
2024-07-08T21:26:51.101Z,1720474011.101 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0061.lzma.bak
2024-07-08T21:26:51.101Z,1720474011.101 [DataOverHttps](INFO): SBD MOMSN=21341845
2024-07-08T21:26:59.319Z,1720474019.319 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T21:27:10.640Z,1720474030.640 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-07-08T21:27:10.723Z,1720474030.723 [NAL9602](ERROR): received:
+CSQ:0
OK78, 2, 0, 0, 0
OK
2024-07-08T21:27:12.515Z,1720474032.515 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20240708T190513/Express0062.lzma
2024-07-08T21:27:13.517Z,1720474033.517 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0062.lzma.bak
2024-07-08T21:27:13.517Z,1720474033.517 [DataOverHttps](INFO): SBD MOMSN=21341848
2024-07-08T21:27:14.266Z,1720474034.266 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T21:27:19.943Z,1720474039.943 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T21:27:19.944Z,1720474039.944 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T21:27:19.944Z,1720474039.944 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T21:27:29.222Z,1720474049.222 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T21:27:41.758Z,1720474061.758 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5.
2024-07-08T21:27:41.765Z,1720474061.765 [BPC1](INFO): Received data from all battery sticks.
2024-07-08T21:31:42.129Z,1720474302.129 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-08T21:32:12.823Z,1720474332.823 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T21:32:20.535Z,1720474340.535 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T21:32:20.536Z,1720474340.536 [Default:CheckIn:C.Wait] Stopped
2024-07-08T21:32:20.536Z,1720474340.536 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T21:32:20.536Z,1720474340.536 [Default:CheckIn:D] Running Loop=1
2024-07-08T21:32:20.913Z,1720474340.913 [Default:CheckIn:D] Stopped
2024-07-08T21:32:20.913Z,1720474340.913 [Default:CheckIn:E] Running Loop=1
2024-07-08T21:32:21.324Z,1720474341.324 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 143.679460 min
2024-07-08T21:32:21.324Z,1720474341.324 [Default:CheckIn:E] Stopped
2024-07-08T21:32:21.324Z,1720474341.324 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T21:32:21.324Z,1720474341.324 [Default:CheckIn] Stopped
2024-07-08T21:32:21.324Z,1720474341.324 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T21:32:21.325Z,1720474341.325 [Default:CheckIn](INFO): Running loop #21
2024-07-08T21:32:21.325Z,1720474341.325 [Default:CheckIn] Running Loop=21
2024-07-08T21:32:21.325Z,1720474341.325 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T21:32:21.325Z,1720474341.325 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T21:32:23.330Z,1720474343.330 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213222.00,A,3648.16055,N,12147.28157,W,0.078,51.02,080724,,,A*48
2024-07-08T21:32:23.332Z,1720474343.332 [NAL9602](INFO): GPS fix at 20240708T213222: (36.802676, -121.788026)
2024-07-08T21:32:23.366Z,1720474343.366 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T21:32:23.366Z,1720474343.366 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T21:32:33.339Z,1720474353.339 [DataOverHttps](INFO): Sending 84 bytes from file Logs/20240708T190513/Courier0064.lzma
2024-07-08T21:32:34.342Z,1720474354.342 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0064.lzma.bak
2024-07-08T21:32:34.342Z,1720474354.342 [DataOverHttps](INFO): SBD MOMSN=21341858
2024-07-08T21:32:53.988Z,1720474373.988 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-07-08T21:32:54.072Z,1720474374.072 [NAL9602](ERROR): received:
+CSQ:0
OK78, 2, 0, 0, 0
OK
2024-07-08T21:32:55.211Z,1720474375.211 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20240708T190513/Express0065.lzma
2024-07-08T21:32:56.214Z,1720474376.214 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0065.lzma.bak
2024-07-08T21:32:56.214Z,1720474376.214 [DataOverHttps](INFO): SBD MOMSN=21341861
2024-07-08T21:33:02.491Z,1720474382.491 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T21:33:02.491Z,1720474382.491 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T21:33:02.491Z,1720474382.491 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T21:35:31.930Z,1720474531.930 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-07-08T21:35:32.744Z,1720474532.744 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2024-07-08T21:35:32.745Z,1720474532.745 [DropWeight] Hardware Fault, FailCount= 1
2024-07-08T21:35:32.745Z,1720474532.745 [DropWeight](ERROR): Hardware Fault
2024-07-08T21:35:32.762Z,1720474532.762 [CBIT](INFO): Critical error at 20240708T213532
2024-07-08T21:35:32.765Z,1720474532.765 [CBIT](ERROR): Hardware Fault in component: DropWeight
2024-07-08T21:35:32.765Z,1720474532.765 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2024-07-08T21:35:33.174Z,1720474533.174 [CBIT](INFO): Critical error at 20240708T213532
2024-07-08T21:37:25.857Z,1720474645.857 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-08T21:37:56.560Z,1720474676.560 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T21:38:03.077Z,1720474683.077 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T21:38:03.078Z,1720474683.078 [Default:CheckIn:C.Wait] Stopped
2024-07-08T21:38:03.078Z,1720474683.078 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T21:38:03.078Z,1720474683.078 [Default:CheckIn:D] Running Loop=1
2024-07-08T21:38:03.442Z,1720474683.442 [Default:CheckIn:D] Stopped
2024-07-08T21:38:03.442Z,1720474683.442 [Default:CheckIn:E] Running Loop=1
2024-07-08T21:38:03.853Z,1720474683.853 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 149.388281 min
2024-07-08T21:38:03.853Z,1720474683.853 [Default:CheckIn:E] Stopped
2024-07-08T21:38:03.853Z,1720474683.853 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T21:38:03.853Z,1720474683.853 [Default:CheckIn] Stopped
2024-07-08T21:38:03.853Z,1720474683.853 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T21:38:03.854Z,1720474683.854 [Default:CheckIn](INFO): Running loop #22
2024-07-08T21:38:03.854Z,1720474683.854 [Default:CheckIn] Running Loop=22
2024-07-08T21:38:03.854Z,1720474683.854 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T21:38:03.854Z,1720474683.854 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T21:38:05.855Z,1720474685.855 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,213805.00,A,3648.16340,N,12147.28479,W,0.214,51.02,080724,,,A*41
2024-07-08T21:38:05.857Z,1720474685.857 [NAL9602](INFO): GPS fix at 20240708T213805: (36.802723, -121.788080)
2024-07-08T21:38:05.867Z,1720474685.867 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T21:38:05.867Z,1720474685.867 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T21:38:15.488Z,1720474695.488 [DataOverHttps](INFO): Sending 171 bytes from file Logs/20240708T190513/Courier0067.lzma
2024-07-08T21:38:16.486Z,1720474696.486 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0067.lzma.bak
2024-07-08T21:38:16.486Z,1720474696.486 [DataOverHttps](INFO): SBD MOMSN=21341885
2024-07-08T21:38:36.569Z,1720474716.569 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-07-08T21:38:36.648Z,1720474716.648 [NAL9602](ERROR): received:
+CSQ:1
OK78, 2, 0, 0, 0
OK
2024-07-08T21:38:46.392Z,1720474726.392 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20240708T190513/Express0068.lzma
2024-07-08T21:38:47.394Z,1720474727.394 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0068.lzma.bak
2024-07-08T21:38:47.394Z,1720474727.394 [DataOverHttps](INFO): SBD MOMSN=21341890
2024-07-08T21:38:53.567Z,1720474733.567 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T21:38:53.589Z,1720474733.589 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T21:38:53.589Z,1720474733.589 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T21:41:58.340Z,1720474918.340 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2024-07-08T21:42:00.348Z,1720474920.348 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.575191
2024-07-08T21:43:08.204Z,1720474988.204 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-08T21:43:09.227Z,1720474989.227 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2024-07-08T21:43:26.603Z,1720475006.603 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002976
2024-07-08T21:43:38.905Z,1720475018.905 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T21:43:54.269Z,1720475034.269 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T21:43:54.269Z,1720475034.269 [Default:CheckIn:C.Wait] Stopped
2024-07-08T21:43:54.269Z,1720475034.269 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T21:43:54.269Z,1720475034.269 [Default:CheckIn:D] Running Loop=1
2024-07-08T21:43:54.673Z,1720475034.673 [Default:CheckIn:D] Stopped
2024-07-08T21:43:54.673Z,1720475034.673 [Default:CheckIn:E] Running Loop=1
2024-07-08T21:43:55.075Z,1720475035.075 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 155.242122 min
2024-07-08T21:43:55.075Z,1720475035.075 [Default:CheckIn:E] Stopped
2024-07-08T21:43:55.075Z,1720475035.075 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T21:43:55.075Z,1720475035.075 [Default:CheckIn] Stopped
2024-07-08T21:43:55.075Z,1720475035.075 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T21:43:55.075Z,1720475035.075 [Default:CheckIn](INFO): Running loop #23
2024-07-08T21:43:55.075Z,1720475035.075 [Default:CheckIn] Running Loop=23
2024-07-08T21:43:55.075Z,1720475035.075 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T21:43:55.076Z,1720475035.076 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T21:43:57.089Z,1720475037.089 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214356.00,A,3648.16430,N,12147.27955,W,0.253,0.00,080724,,,A*72
2024-07-08T21:43:57.101Z,1720475037.101 [NAL9602](INFO): GPS fix at 20240708T214356: (36.802738, -121.787993)
2024-07-08T21:43:57.111Z,1720475037.111 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T21:43:57.111Z,1720475037.111 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T21:44:07.782Z,1720475047.782 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20240708T190513/Courier0070.lzma
2024-07-08T21:44:08.785Z,1720475048.785 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0070.lzma.bak
2024-07-08T21:44:08.785Z,1720475048.785 [DataOverHttps](INFO): SBD MOMSN=21341903
2024-07-08T21:44:27.796Z,1720475067.796 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-07-08T21:44:27.875Z,1720475067.875 [NAL9602](ERROR): received:
+CSQ:0
OK78, 2, 0, 0, 0
OK
2024-07-08T21:44:48.399Z,1720475088.399 [DataOverHttps](INFO): Sending 210 bytes from file Logs/20240708T190513/Express0071.lzma
2024-07-08T21:44:49.401Z,1720475089.401 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0071.lzma.bak
2024-07-08T21:44:49.401Z,1720475089.401 [DataOverHttps](INFO): SBD MOMSN=21341915
2024-07-08T21:44:55.715Z,1720475095.715 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T21:44:55.719Z,1720475095.719 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T21:44:55.719Z,1720475095.719 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T21:45:32.029Z,1720475132.029 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-07-08T21:47:09.393Z,1720475229.393 [NAL9602](INFO): SBD MO Status=2, MOMSN=5078, MT Status=2, MTMSN=0
2024-07-08T21:47:09.394Z,1720475229.394 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-08T21:47:41.712Z,1720475261.712 [NAL9602](INFO): SBD MO Status=2, MOMSN=5078, MT Status=2, MTMSN=0
2024-07-08T21:47:41.712Z,1720475261.712 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-08T21:48:59.280Z,1720475339.280 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-08T21:49:29.988Z,1720475369.988 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T21:49:56.266Z,1720475396.266 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T21:49:56.267Z,1720475396.267 [Default:CheckIn:C.Wait] Stopped
2024-07-08T21:49:56.271Z,1720475396.271 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T21:49:56.271Z,1720475396.271 [Default:CheckIn:D] Running Loop=1
2024-07-08T21:49:56.673Z,1720475396.673 [Default:CheckIn:D] Stopped
2024-07-08T21:49:56.674Z,1720475396.674 [Default:CheckIn:E] Running Loop=1
2024-07-08T21:49:57.106Z,1720475397.106 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 161.275456 min
2024-07-08T21:49:57.106Z,1720475397.106 [Default:CheckIn:E] Stopped
2024-07-08T21:49:57.106Z,1720475397.106 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T21:49:57.107Z,1720475397.107 [Default:CheckIn] Stopped
2024-07-08T21:49:57.130Z,1720475397.130 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T21:49:57.130Z,1720475397.130 [Default:CheckIn](INFO): Running loop #24
2024-07-08T21:49:57.130Z,1720475397.130 [Default:CheckIn] Running Loop=24
2024-07-08T21:49:57.130Z,1720475397.130 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T21:49:57.130Z,1720475397.130 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T21:49:59.078Z,1720475399.078 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,214958.00,A,3648.16521,N,12147.28477,W,0.117,0.00,080724,,,A*76
2024-07-08T21:49:59.080Z,1720475399.080 [NAL9602](INFO): GPS fix at 20240708T214958: (36.802754, -121.788079)
2024-07-08T21:49:59.090Z,1720475399.090 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T21:49:59.090Z,1720475399.090 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T21:50:09.186Z,1720475409.186 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240708T190513/Courier0073.lzma
2024-07-08T21:50:10.189Z,1720475410.189 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0073.lzma.bak
2024-07-08T21:50:10.189Z,1720475410.189 [DataOverHttps](INFO): SBD MOMSN=21341932
2024-07-08T21:50:29.776Z,1720475429.776 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-07-08T21:50:29.859Z,1720475429.859 [NAL9602](ERROR): received:
+CSQ:0
OK78, 2, 0, 0, 0
OK
2024-07-08T21:50:30.967Z,1720475430.967 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20240708T190513/Express0074.lzma
2024-07-08T21:50:31.969Z,1720475431.969 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0074.lzma.bak
2024-07-08T21:50:31.969Z,1720475431.969 [DataOverHttps](INFO): SBD MOMSN=21341935
2024-07-08T21:50:33.063Z,1720475433.063 [CBIT](INFO): Clearing failed state for component DropWeight
2024-07-08T21:50:33.063Z,1720475433.063 [DropWeight] No Fault, FailCount= 1
2024-07-08T21:50:39.911Z,1720475439.911 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T21:50:39.911Z,1720475439.911 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T21:50:39.911Z,1720475439.911 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T21:54:20.490Z,1720475660.490 [NAL9602](INFO): SBD MO Status=2, MOMSN=5078, MT Status=2, MTMSN=0
2024-07-08T21:54:20.490Z,1720475660.490 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-08T21:55:00.882Z,1720475700.882 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-08T21:55:31.584Z,1720475731.584 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T21:55:32.393Z,1720475732.393 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-07-08T21:55:40.498Z,1720475740.498 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T21:55:40.498Z,1720475740.498 [Default:CheckIn:C.Wait] Stopped
2024-07-08T21:55:40.499Z,1720475740.499 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T21:55:40.499Z,1720475740.499 [Default:CheckIn:D] Running Loop=1
2024-07-08T21:55:40.889Z,1720475740.889 [Default:CheckIn:D] Stopped
2024-07-08T21:55:40.889Z,1720475740.889 [Default:CheckIn:E] Running Loop=1
2024-07-08T21:55:41.313Z,1720475741.313 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 167.012402 min
2024-07-08T21:55:41.313Z,1720475741.313 [Default:CheckIn:E] Stopped
2024-07-08T21:55:41.313Z,1720475741.313 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T21:55:41.313Z,1720475741.313 [Default:CheckIn] Stopped
2024-07-08T21:55:41.313Z,1720475741.313 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T21:55:41.313Z,1720475741.313 [Default:CheckIn](INFO): Running loop #25
2024-07-08T21:55:41.313Z,1720475741.313 [Default:CheckIn] Running Loop=25
2024-07-08T21:55:41.313Z,1720475741.313 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T21:55:41.313Z,1720475741.313 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T21:55:43.302Z,1720475743.302 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,215542.00,A,3648.16604,N,12147.28288,W,0.564,0.00,080724,,,A*72
2024-07-08T21:55:43.305Z,1720475743.305 [NAL9602](INFO): GPS fix at 20240708T215542: (36.802767, -121.788048)
2024-07-08T21:55:43.316Z,1720475743.316 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T21:55:43.316Z,1720475743.316 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T21:55:53.318Z,1720475753.318 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240708T190513/Courier0076.lzma
2024-07-08T21:55:54.321Z,1720475754.321 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0076.lzma.bak
2024-07-08T21:55:54.321Z,1720475754.321 [DataOverHttps](INFO): SBD MOMSN=21341958
2024-07-08T21:56:14.004Z,1720475774.004 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-07-08T21:56:14.087Z,1720475774.087 [NAL9602](ERROR): received:
+CSQ:0
OK78, 2, 0, 0, 0
OK
2024-07-08T21:56:18.071Z,1720475778.071 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20240708T190513/Express0077.lzma
2024-07-08T21:56:19.073Z,1720475779.073 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0077.lzma.bak
2024-07-08T21:56:19.073Z,1720475779.073 [DataOverHttps](INFO): SBD MOMSN=21341961
2024-07-08T21:56:24.937Z,1720475784.937 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T21:56:24.937Z,1720475784.937 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T21:56:24.937Z,1720475784.937 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T21:56:59.262Z,1720475819.262 [NAL9602](INFO): SBD MO Status=2, MOMSN=5078, MT Status=2, MTMSN=0
2024-07-08T21:56:59.262Z,1720475819.262 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-08T21:57:21.073Z,1720475841.073 [NAL9602](INFO): SBD MO Status=2, MOMSN=5078, MT Status=2, MTMSN=0
2024-07-08T21:57:21.074Z,1720475841.074 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2024-07-08T22:00:45.088Z,1720476045.088 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-08T22:01:15.792Z,1720476075.792 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T22:01:25.509Z,1720476085.509 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T22:01:25.509Z,1720476085.509 [Default:CheckIn:C.Wait] Stopped
2024-07-08T22:01:25.509Z,1720476085.509 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T22:01:25.509Z,1720476085.509 [Default:CheckIn:D] Running Loop=1
2024-07-08T22:01:25.911Z,1720476085.911 [Default:CheckIn:D] Stopped
2024-07-08T22:01:25.928Z,1720476085.928 [Default:CheckIn:E] Running Loop=1
2024-07-08T22:01:26.306Z,1720476086.306 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 172.762744 min
2024-07-08T22:01:26.306Z,1720476086.306 [Default:CheckIn:E] Stopped
2024-07-08T22:01:26.306Z,1720476086.306 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T22:01:26.306Z,1720476086.306 [Default:CheckIn] Stopped
2024-07-08T22:01:26.306Z,1720476086.306 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T22:01:26.306Z,1720476086.306 [Default:CheckIn](INFO): Running loop #26
2024-07-08T22:01:26.306Z,1720476086.306 [Default:CheckIn] Running Loop=26
2024-07-08T22:01:26.306Z,1720476086.306 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T22:01:26.306Z,1720476086.306 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T22:01:28.326Z,1720476088.326 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220127.00,A,3648.17774,N,12147.24306,W,1.244,65.24,080724,,,A*4E
2024-07-08T22:01:28.328Z,1720476088.328 [NAL9602](INFO): GPS fix at 20240708T220127: (36.802962, -121.787384)
2024-07-08T22:01:28.338Z,1720476088.338 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T22:01:28.338Z,1720476088.338 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T22:01:51.811Z,1720476111.811 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-08T22:01:58.840Z,1720476118.840 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.727478
2024-07-08T22:01:59.021Z,1720476119.021 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-07-08T22:01:59.095Z,1720476119.095 [NAL9602](ERROR): received:
+CSQ:0
OK78, 2, 0, 0, 0
OK
2024-07-08T22:02:29.967Z,1720476149.967 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2024-07-08T22:03:02.368Z,1720476182.368 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002999
2024-07-08T22:03:12.158Z,1720476192.158 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240708T190513/Courier0079.lzma
2024-07-08T22:03:13.161Z,1720476193.161 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0079.lzma.bak
2024-07-08T22:03:13.161Z,1720476193.161 [DataOverHttps](INFO): SBD MOMSN=21341971
2024-07-08T22:03:33.843Z,1720476213.843 [DataOverHttps](INFO): Sending 220 bytes from file Logs/20240708T190513/Express0080.lzma
2024-07-08T22:03:34.846Z,1720476214.846 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0080.lzma.bak
2024-07-08T22:03:34.846Z,1720476214.846 [DataOverHttps](INFO): SBD MOMSN=21341974
2024-07-08T22:03:40.845Z,1720476220.845 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T22:03:40.845Z,1720476220.845 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T22:03:40.845Z,1720476220.845 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T22:05:02.179Z,1720476302.179 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.499268
2024-07-08T22:05:11.551Z,1720476311.551 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002960
2024-07-08T22:05:32.745Z,1720476332.745 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-07-08T22:06:30.108Z,1720476390.108 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-08T22:07:00.807Z,1720476420.807 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T22:08:41.595Z,1720476521.595 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T22:08:41.595Z,1720476521.595 [Default:CheckIn:C.Wait] Stopped
2024-07-08T22:08:41.595Z,1720476521.595 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T22:08:41.595Z,1720476521.595 [Default:CheckIn:D] Running Loop=1
2024-07-08T22:08:42.007Z,1720476522.007 [Default:CheckIn:D] Stopped
2024-07-08T22:08:42.007Z,1720476522.007 [Default:CheckIn:E] Running Loop=1
2024-07-08T22:08:42.446Z,1720476522.446 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 180.031022 min
2024-07-08T22:08:42.446Z,1720476522.446 [Default:CheckIn:E] Stopped
2024-07-08T22:08:42.446Z,1720476522.446 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T22:08:42.446Z,1720476522.446 [Default:CheckIn] Stopped
2024-07-08T22:08:42.446Z,1720476522.446 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T22:08:42.447Z,1720476522.447 [Default:CheckIn](INFO): Running loop #27
2024-07-08T22:08:42.447Z,1720476522.447 [Default:CheckIn] Running Loop=27
2024-07-08T22:08:42.447Z,1720476522.447 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T22:08:42.447Z,1720476522.447 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T22:08:44.407Z,1720476524.407 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,220843.00,A,3648.14051,N,12147.31627,W,0.272,242.18,080724,,,A*78
2024-07-08T22:08:44.409Z,1720476524.409 [NAL9602](INFO): GPS fix at 20240708T220843: (36.802342, -121.788605)
2024-07-08T22:08:44.446Z,1720476524.446 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T22:08:44.446Z,1720476524.446 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T22:08:54.355Z,1720476534.355 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240708T190513/Courier0082.lzma
2024-07-08T22:08:55.374Z,1720476535.374 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0082.lzma.bak
2024-07-08T22:08:55.374Z,1720476535.374 [DataOverHttps](INFO): SBD MOMSN=21341999
2024-07-08T22:09:15.123Z,1720476555.123 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-07-08T22:09:15.196Z,1720476555.196 [NAL9602](ERROR): received:
+CSQ:0
OK78, 2, 0, 0, 0
OK
2024-07-08T22:09:15.958Z,1720476555.958 [DataOverHttps](INFO): Sending 221 bytes from file Logs/20240708T190513/Express0083.lzma
2024-07-08T22:09:16.958Z,1720476556.958 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0083.lzma.bak
2024-07-08T22:09:16.958Z,1720476556.958 [DataOverHttps](INFO): SBD MOMSN=21342002
2024-07-08T22:09:24.850Z,1720476564.850 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T22:09:24.850Z,1720476564.850 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T22:09:24.850Z,1720476564.850 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T22:13:46.201Z,1720476826.201 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-08T22:14:16.905Z,1720476856.905 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T22:14:25.422Z,1720476865.422 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T22:14:25.422Z,1720476865.422 [Default:CheckIn:C.Wait] Stopped
2024-07-08T22:14:25.422Z,1720476865.422 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T22:14:25.423Z,1720476865.423 [Default:CheckIn:D] Running Loop=1
2024-07-08T22:14:25.811Z,1720476865.811 [Default:CheckIn:D] Stopped
2024-07-08T22:14:25.811Z,1720476865.811 [Default:CheckIn:E] Running Loop=1
2024-07-08T22:14:26.252Z,1720476866.252 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 185.761084 min
2024-07-08T22:14:26.252Z,1720476866.252 [Default:CheckIn:E] Stopped
2024-07-08T22:14:26.252Z,1720476866.252 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T22:14:26.253Z,1720476866.253 [Default:CheckIn] Stopped
2024-07-08T22:14:26.253Z,1720476866.253 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T22:14:26.253Z,1720476866.253 [Default:CheckIn](INFO): Running loop #28
2024-07-08T22:14:26.253Z,1720476866.253 [Default:CheckIn] Running Loop=28
2024-07-08T22:14:26.253Z,1720476866.253 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T22:14:26.253Z,1720476866.253 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T22:14:28.227Z,1720476868.227 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221427.00,A,3648.16318,N,12147.25595,W,1.244,209.45,080724,,,A*77
2024-07-08T22:14:28.229Z,1720476868.229 [NAL9602](INFO): GPS fix at 20240708T221427: (36.802720, -121.787599)
2024-07-08T22:14:28.240Z,1720476868.240 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T22:14:28.240Z,1720476868.240 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T22:14:38.079Z,1720476878.079 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20240708T190513/Courier0085.lzma
2024-07-08T22:14:39.082Z,1720476879.082 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0085.lzma.bak
2024-07-08T22:14:39.082Z,1720476879.082 [DataOverHttps](INFO): SBD MOMSN=21342012
2024-07-08T22:14:58.921Z,1720476898.921 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-07-08T22:14:59.004Z,1720476899.004 [NAL9602](ERROR): received:
+CSQ:0
OK78, 2, 0, 0, 0
OK
2024-07-08T22:14:59.952Z,1720476899.952 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20240708T190513/Express0086.lzma
2024-07-08T22:15:00.954Z,1720476900.954 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0086.lzma.bak
2024-07-08T22:15:00.954Z,1720476900.954 [DataOverHttps](INFO): SBD MOMSN=21342015
2024-07-08T22:15:07.030Z,1720476907.030 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T22:15:07.030Z,1720476907.030 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T22:15:07.030Z,1720476907.030 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T22:15:32.860Z,1720476932.860 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-07-08T22:19:30.009Z,1720477170.009 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-08T22:20:00.717Z,1720477200.717 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T22:20:07.599Z,1720477207.599 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T22:20:07.599Z,1720477207.599 [Default:CheckIn:C.Wait] Stopped
2024-07-08T22:20:07.599Z,1720477207.599 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T22:20:07.599Z,1720477207.599 [Default:CheckIn:D] Running Loop=1
2024-07-08T22:20:08.006Z,1720477208.006 [Default:CheckIn:D] Stopped
2024-07-08T22:20:08.006Z,1720477208.006 [Default:CheckIn:E] Running Loop=1
2024-07-08T22:20:08.415Z,1720477208.415 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 191.464339 min
2024-07-08T22:20:08.415Z,1720477208.415 [Default:CheckIn:E] Stopped
2024-07-08T22:20:08.415Z,1720477208.415 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T22:20:08.415Z,1720477208.415 [Default:CheckIn] Stopped
2024-07-08T22:20:08.415Z,1720477208.415 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T22:20:08.415Z,1720477208.415 [Default:CheckIn](INFO): Running loop #29
2024-07-08T22:20:08.415Z,1720477208.415 [Default:CheckIn] Running Loop=29
2024-07-08T22:20:08.415Z,1720477208.415 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T22:20:08.416Z,1720477208.416 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T22:20:34.243Z,1720477234.243 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2024-07-08T22:20:34.244Z,1720477234.244 [DropWeight] Hardware Fault, FailCount= 1
2024-07-08T22:20:34.244Z,1720477234.244 [DropWeight](ERROR): Hardware Fault
2024-07-08T22:20:34.262Z,1720477234.262 [CBIT](INFO): Critical error at 20240708T222034
2024-07-08T22:20:34.265Z,1720477234.265 [CBIT](ERROR): Hardware Fault in component: DropWeight
2024-07-08T22:20:34.265Z,1720477234.265 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2024-07-08T22:20:34.671Z,1720477234.671 [CBIT](INFO): Critical error at 20240708T222034
2024-07-08T22:20:41.117Z,1720477241.117 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-07-08T22:20:41.200Z,1720477241.200 [NAL9602](ERROR): received:
+CSQ:0
OK78, 2, 0, 0, 0
OK
2024-07-08T22:25:08.590Z,1720477508.590 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-07-08T22:20:08.4Z
2024-07-08T22:25:08.590Z,1720477508.590 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T22:25:08.590Z,1720477508.590 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T22:25:13.010Z,1720477513.010 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-08T22:25:17.963Z,1720477517.963 [DataOverHttps](INFO): Sending 117 bytes from file Logs/20240708T190513/Courier0088.lzma
2024-07-08T22:25:18.966Z,1720477518.966 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0088.lzma.bak
2024-07-08T22:25:18.966Z,1720477518.966 [DataOverHttps](INFO): SBD MOMSN=21342046
2024-07-08T22:25:33.212Z,1720477533.212 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-07-08T22:25:39.264Z,1720477539.264 [DataOverHttps](INFO): Sending 207 bytes from file Logs/20240708T190513/Express0089.lzma
2024-07-08T22:25:40.262Z,1720477540.262 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0089.lzma.bak
2024-07-08T22:25:40.262Z,1720477540.262 [DataOverHttps](INFO): SBD MOMSN=21342050
2024-07-08T22:25:43.713Z,1720477543.713 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T22:25:46.578Z,1720477546.578 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T22:25:46.578Z,1720477546.578 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T22:25:46.578Z,1720477546.578 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T22:30:47.159Z,1720477847.159 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T22:30:47.159Z,1720477847.159 [Default:CheckIn:C.Wait] Stopped
2024-07-08T22:30:47.159Z,1720477847.159 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T22:30:47.159Z,1720477847.159 [Default:CheckIn:D] Running Loop=1
2024-07-08T22:30:47.554Z,1720477847.554 [Default:CheckIn:D] Stopped
2024-07-08T22:30:47.554Z,1720477847.554 [Default:CheckIn:E] Running Loop=1
2024-07-08T22:30:47.937Z,1720477847.937 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 202.123470 min
2024-07-08T22:30:47.937Z,1720477847.937 [Default:CheckIn:E] Stopped
2024-07-08T22:30:47.938Z,1720477847.938 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T22:30:47.938Z,1720477847.938 [Default:CheckIn] Stopped
2024-07-08T22:30:47.938Z,1720477847.938 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T22:30:47.938Z,1720477847.938 [Default:CheckIn](INFO): Running loop #30
2024-07-08T22:30:47.938Z,1720477847.938 [Default:CheckIn] Running Loop=30
2024-07-08T22:30:47.938Z,1720477847.938 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T22:30:47.938Z,1720477847.938 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T22:30:49.561Z,1720477849.561 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-07-08T22:30:49.561Z,1720477849.561 [NAL9602] Data Fault, FailCount= 1
2024-07-08T22:30:49.561Z,1720477849.561 [NAL9602](ERROR): Data Fault
2024-07-08T22:30:49.606Z,1720477849.606 [CBIT](ERROR): Data Fault in component: NAL9602
2024-07-08T22:30:49.945Z,1720477849.945 [NAL9602](INFO): Powering down
2024-07-08T22:30:50.841Z,1720477850.841 [CBIT](INFO): Clearing failed state for component NAL9602
2024-07-08T22:30:50.841Z,1720477850.841 [NAL9602] No Fault, FailCount= 1
2024-07-08T22:31:20.250Z,1720477880.250 [NAL9602](INFO): Powering up NAL9602
2024-07-08T22:31:31.161Z,1720477891.161 [NAL9602](INFO): NAL9602 initialized
2024-07-08T22:32:39.427Z,1720477959.427 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T22:32:54.779Z,1720477974.779 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T22:33:09.726Z,1720477989.726 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T22:33:24.677Z,1720478004.677 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T22:33:40.031Z,1720478020.031 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T22:33:55.379Z,1720478035.379 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T22:34:10.744Z,1720478050.744 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T22:34:26.082Z,1720478066.082 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T22:34:41.435Z,1720478081.435 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T22:34:56.786Z,1720478096.786 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T22:35:03.268Z,1720478103.268 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 5.
2024-07-08T22:35:03.270Z,1720478103.270 [BPC1](INFO): Received data from all battery sticks.
2024-07-08T22:35:33.555Z,1720478133.555 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-07-08T22:35:34.406Z,1720478134.406 [CBIT](INFO): Clearing failed state for component DropWeight
2024-07-08T22:35:34.407Z,1720478134.407 [DropWeight] No Fault, FailCount= 1
2024-07-08T22:35:48.125Z,1720478148.125 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-07-08T22:30:47.9Z
2024-07-08T22:35:48.125Z,1720478148.125 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T22:35:48.125Z,1720478148.125 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T22:35:58.100Z,1720478158.100 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20240708T190513/Courier0091.lzma
2024-07-08T22:35:59.103Z,1720478159.103 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0091.lzma.bak
2024-07-08T22:35:59.104Z,1720478159.104 [DataOverHttps](INFO): SBD MOMSN=21342078
2024-07-08T22:36:21.100Z,1720478181.100 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20240708T190513/Express0092.lzma
2024-07-08T22:36:22.104Z,1720478182.104 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0092.lzma.bak
2024-07-08T22:36:22.104Z,1720478182.104 [DataOverHttps](INFO): SBD MOMSN=21342080
2024-07-08T22:36:28.120Z,1720478188.120 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T22:36:28.124Z,1720478188.124 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T22:36:28.124Z,1720478188.124 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T22:36:34.961Z,1720478194.961 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-08T22:37:05.669Z,1720478225.669 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T22:41:28.565Z,1720478488.565 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T22:41:28.565Z,1720478488.565 [Default:CheckIn:C.Wait] Stopped
2024-07-08T22:41:28.565Z,1720478488.565 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T22:41:28.565Z,1720478488.565 [Default:CheckIn:D] Running Loop=1
2024-07-08T22:41:29.004Z,1720478489.004 [Default:CheckIn:D] Stopped
2024-07-08T22:41:29.004Z,1720478489.004 [Default:CheckIn:E] Running Loop=1
2024-07-08T22:41:29.425Z,1720478489.425 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 212.814225 min
2024-07-08T22:41:29.425Z,1720478489.425 [Default:CheckIn:E] Stopped
2024-07-08T22:41:29.425Z,1720478489.425 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T22:41:29.425Z,1720478489.425 [Default:CheckIn] Stopped
2024-07-08T22:41:29.425Z,1720478489.425 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T22:41:29.425Z,1720478489.425 [Default:CheckIn](INFO): Running loop #31
2024-07-08T22:41:29.425Z,1720478489.425 [Default:CheckIn] Running Loop=31
2024-07-08T22:41:29.426Z,1720478489.426 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T22:41:29.426Z,1720478489.426 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T22:42:02.077Z,1720478522.077 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-07-08T22:42:02.160Z,1720478522.160 [NAL9602](ERROR): received:
+CSQ:0
OK78, 2, 0, 0, 0
OK
2024-07-08T22:45:33.775Z,1720478733.775 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-07-08T22:46:29.552Z,1720478789.552 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-07-08T22:41:29.4Z
2024-07-08T22:46:29.553Z,1720478789.553 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T22:46:29.553Z,1720478789.553 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T22:46:33.165Z,1720478793.165 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-08T22:46:39.215Z,1720478799.215 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240708T190513/Courier0094.lzma
2024-07-08T22:46:40.218Z,1720478800.218 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0094.lzma.bak
2024-07-08T22:46:40.218Z,1720478800.218 [DataOverHttps](INFO): SBD MOMSN=21342111
2024-07-08T22:46:59.841Z,1720478819.841 [DataOverHttps](INFO): Sending 211 bytes from file Logs/20240708T190513/Express0095.lzma
2024-07-08T22:47:00.834Z,1720478820.834 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0095.lzma.bak
2024-07-08T22:47:00.834Z,1720478820.834 [DataOverHttps](INFO): SBD MOMSN=21342115
2024-07-08T22:47:03.868Z,1720478823.868 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T22:47:07.525Z,1720478827.525 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T22:47:07.525Z,1720478827.525 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T22:47:07.525Z,1720478827.525 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T22:52:08.160Z,1720479128.160 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T22:52:08.160Z,1720479128.160 [Default:CheckIn:C.Wait] Stopped
2024-07-08T22:52:08.160Z,1720479128.160 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T22:52:08.160Z,1720479128.160 [Default:CheckIn:D] Running Loop=1
2024-07-08T22:52:08.530Z,1720479128.530 [Default:CheckIn:D] Stopped
2024-07-08T22:52:08.530Z,1720479128.530 [Default:CheckIn:E] Running Loop=1
2024-07-08T22:52:08.935Z,1720479128.935 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 223.473063 min
2024-07-08T22:52:08.935Z,1720479128.935 [Default:CheckIn:E] Stopped
2024-07-08T22:52:08.935Z,1720479128.935 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T22:52:08.935Z,1720479128.935 [Default:CheckIn] Stopped
2024-07-08T22:52:08.935Z,1720479128.935 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T22:52:08.936Z,1720479128.936 [Default:CheckIn](INFO): Running loop #32
2024-07-08T22:52:08.936Z,1720479128.936 [Default:CheckIn] Running Loop=32
2024-07-08T22:52:08.936Z,1720479128.936 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T22:52:08.936Z,1720479128.936 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T22:52:10.541Z,1720479130.541 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-07-08T22:52:10.541Z,1720479130.541 [NAL9602] Data Fault, FailCount= 2
2024-07-08T22:52:10.541Z,1720479130.541 [NAL9602](ERROR): Data Fault
2024-07-08T22:52:10.560Z,1720479130.560 [CBIT](ERROR): Data Fault in component: NAL9602
2024-07-08T22:52:10.945Z,1720479130.945 [NAL9602](INFO): Powering down
2024-07-08T22:52:11.775Z,1720479131.775 [CBIT](INFO): Clearing failed state for component NAL9602
2024-07-08T22:52:11.775Z,1720479131.775 [NAL9602] No Fault, FailCount= 2
2024-07-08T22:52:41.244Z,1720479161.244 [NAL9602](INFO): Powering up NAL9602
2024-07-08T22:52:52.154Z,1720479172.154 [NAL9602](INFO): NAL9602 initialized
2024-07-08T22:55:34.022Z,1720479334.022 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-07-08T22:57:08.977Z,1720479428.977 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-07-08T22:52:08.9Z
2024-07-08T22:57:08.977Z,1720479428.977 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T22:57:08.977Z,1720479428.977 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T22:57:18.575Z,1720479438.575 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240708T190513/Courier0097.lzma
2024-07-08T22:57:19.578Z,1720479439.578 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0097.lzma.bak
2024-07-08T22:57:19.578Z,1720479439.578 [DataOverHttps](INFO): SBD MOMSN=21342144
2024-07-08T22:57:42.760Z,1720479462.760 [DataOverHttps](INFO): Sending 225 bytes from file Logs/20240708T190513/Express0098.lzma
2024-07-08T22:57:43.763Z,1720479463.763 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0098.lzma.bak
2024-07-08T22:57:43.763Z,1720479463.763 [DataOverHttps](INFO): SBD MOMSN=21342148
2024-07-08T22:57:50.197Z,1720479470.197 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T22:57:50.197Z,1720479470.197 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T22:57:50.197Z,1720479470.197 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T22:57:54.597Z,1720479474.597 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-08T22:58:25.301Z,1720479505.301 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T23:02:50.765Z,1720479770.765 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T23:02:50.765Z,1720479770.765 [Default:CheckIn:C.Wait] Stopped
2024-07-08T23:02:50.765Z,1720479770.765 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T23:02:50.765Z,1720479770.765 [Default:CheckIn:D] Running Loop=1
2024-07-08T23:02:51.149Z,1720479771.149 [Default:CheckIn:D] Stopped
2024-07-08T23:02:51.149Z,1720479771.149 [Default:CheckIn:E] Running Loop=1
2024-07-08T23:02:51.550Z,1720479771.550 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 234.183382 min
2024-07-08T23:02:51.550Z,1720479771.550 [Default:CheckIn:E] Stopped
2024-07-08T23:02:51.550Z,1720479771.550 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T23:02:51.550Z,1720479771.550 [Default:CheckIn] Stopped
2024-07-08T23:02:51.551Z,1720479771.551 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T23:02:51.551Z,1720479771.551 [Default:CheckIn](INFO): Running loop #33
2024-07-08T23:02:51.551Z,1720479771.551 [Default:CheckIn] Running Loop=33
2024-07-08T23:02:51.551Z,1720479771.551 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T23:02:51.551Z,1720479771.551 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T23:02:53.158Z,1720479773.158 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-07-08T23:02:53.158Z,1720479773.158 [NAL9602] Data Fault, FailCount= 3
2024-07-08T23:02:53.158Z,1720479773.158 [NAL9602](ERROR): Data Fault
2024-07-08T23:02:53.177Z,1720479773.177 [CBIT](ERROR): Data Fault in component: NAL9602
2024-07-08T23:02:53.566Z,1720479773.566 [NAL9602](INFO): Powering down
2024-07-08T23:02:54.417Z,1720479774.417 [CBIT](INFO): Clearing failed state for component NAL9602
2024-07-08T23:02:54.417Z,1720479774.417 [NAL9602] No Fault, FailCount= 3
2024-07-08T23:03:23.865Z,1720479803.865 [NAL9602](INFO): Powering up NAL9602
2024-07-08T23:03:34.773Z,1720479814.773 [NAL9602](INFO): NAL9602 initialized
2024-07-08T23:05:34.363Z,1720479934.363 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-07-08T23:05:35.969Z,1720479935.969 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2024-07-08T23:05:35.969Z,1720479935.969 [DropWeight] Hardware Fault, FailCount= 1
2024-07-08T23:05:35.969Z,1720479935.969 [DropWeight](ERROR): Hardware Fault
2024-07-08T23:05:36.016Z,1720479936.016 [CBIT](INFO): Critical error at 20240708T230535
2024-07-08T23:05:36.018Z,1720479936.018 [CBIT](ERROR): Hardware Fault in component: DropWeight
2024-07-08T23:05:36.019Z,1720479936.019 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2024-07-08T23:05:36.394Z,1720479936.394 [CBIT](INFO): Critical error at 20240708T230536
2024-07-08T23:07:51.824Z,1720480071.824 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-07-08T23:02:51.6Z
2024-07-08T23:07:51.824Z,1720480071.824 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T23:07:51.824Z,1720480071.824 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T23:08:01.272Z,1720480081.272 [DataOverHttps](INFO): Sending 117 bytes from file Logs/20240708T190513/Courier0100.lzma
2024-07-08T23:08:02.275Z,1720480082.275 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0100.lzma.bak
2024-07-08T23:08:02.275Z,1720480082.275 [DataOverHttps](INFO): SBD MOMSN=21342181
2024-07-08T23:08:22.716Z,1720480102.716 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20240708T190513/Express0101.lzma
2024-07-08T23:08:23.719Z,1720480103.719 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0101.lzma.bak
2024-07-08T23:08:23.719Z,1720480103.719 [DataOverHttps](INFO): SBD MOMSN=21342189
2024-07-08T23:08:29.868Z,1720480109.868 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T23:08:29.868Z,1720480109.868 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T23:08:29.868Z,1720480109.868 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T23:08:37.510Z,1720480117.510 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-08T23:09:08.214Z,1720480148.214 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T23:13:30.463Z,1720480410.463 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T23:13:30.463Z,1720480410.463 [Default:CheckIn:C.Wait] Stopped
2024-07-08T23:13:30.464Z,1720480410.464 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T23:13:30.464Z,1720480410.464 [Default:CheckIn:D] Running Loop=1
2024-07-08T23:13:30.835Z,1720480410.835 [Default:CheckIn:D] Stopped
2024-07-08T23:13:30.836Z,1720480410.836 [Default:CheckIn:E] Running Loop=1
2024-07-08T23:13:31.254Z,1720480411.254 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 244.844824 min
2024-07-08T23:13:31.254Z,1720480411.254 [Default:CheckIn:E] Stopped
2024-07-08T23:13:31.254Z,1720480411.254 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T23:13:31.254Z,1720480411.254 [Default:CheckIn] Stopped
2024-07-08T23:13:31.254Z,1720480411.254 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T23:13:31.254Z,1720480411.254 [Default:CheckIn](INFO): Running loop #34
2024-07-08T23:13:31.254Z,1720480411.254 [Default:CheckIn] Running Loop=34
2024-07-08T23:13:31.254Z,1720480411.254 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T23:13:31.254Z,1720480411.254 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T23:14:03.953Z,1720480443.953 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-07-08T23:14:04.037Z,1720480444.037 [NAL9602](ERROR): received:
+CSQ:0
OK78, 2, 0, 0, 0
OK
2024-07-08T23:15:34.763Z,1720480534.763 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-07-08T23:18:31.316Z,1720480711.316 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-07-08T23:13:31.3Z
2024-07-08T23:18:31.316Z,1720480711.316 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T23:18:31.316Z,1720480711.316 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T23:18:35.743Z,1720480715.743 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-08T23:18:39.861Z,1720480719.861 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240708T190513/Courier0103.lzma
2024-07-08T23:18:40.863Z,1720480720.863 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0103.lzma.bak
2024-07-08T23:18:40.863Z,1720480720.863 [DataOverHttps](INFO): SBD MOMSN=21342216
2024-07-08T23:19:01.449Z,1720480741.449 [DataOverHttps](INFO): Sending 209 bytes from file Logs/20240708T190513/Express0104.lzma
2024-07-08T23:19:02.452Z,1720480742.452 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0104.lzma.bak
2024-07-08T23:19:02.452Z,1720480742.452 [DataOverHttps](INFO): SBD MOMSN=21342223
2024-07-08T23:19:06.451Z,1720480746.451 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T23:19:09.292Z,1720480749.292 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T23:19:09.292Z,1720480749.292 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T23:19:09.292Z,1720480749.292 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T23:20:36.185Z,1720480836.185 [CBIT](INFO): Clearing failed state for component DropWeight
2024-07-08T23:20:36.185Z,1720480836.185 [DropWeight] No Fault, FailCount= 1
2024-07-08T23:24:09.868Z,1720481049.868 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T23:24:09.868Z,1720481049.868 [Default:CheckIn:C.Wait] Stopped
2024-07-08T23:24:09.868Z,1720481049.868 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T23:24:09.868Z,1720481049.868 [Default:CheckIn:D] Running Loop=1
2024-07-08T23:24:10.276Z,1720481050.276 [Default:CheckIn:D] Stopped
2024-07-08T23:24:10.276Z,1720481050.276 [Default:CheckIn:E] Running Loop=1
2024-07-08T23:24:10.672Z,1720481050.672 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 255.502165 min
2024-07-08T23:24:10.673Z,1720481050.673 [Default:CheckIn:E] Stopped
2024-07-08T23:24:10.673Z,1720481050.673 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T23:24:10.673Z,1720481050.673 [Default:CheckIn] Stopped
2024-07-08T23:24:10.673Z,1720481050.673 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T23:24:10.673Z,1720481050.673 [Default:CheckIn](INFO): Running loop #35
2024-07-08T23:24:10.673Z,1720481050.673 [Default:CheckIn] Running Loop=35
2024-07-08T23:24:10.673Z,1720481050.673 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T23:24:10.674Z,1720481050.674 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T23:24:12.274Z,1720481052.274 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-07-08T23:24:12.274Z,1720481052.274 [NAL9602] Data Fault, FailCount= 4
2024-07-08T23:24:12.274Z,1720481052.274 [NAL9602](ERROR): Data Fault
2024-07-08T23:24:12.294Z,1720481052.294 [CBIT](ERROR): Data Fault in component: NAL9602
2024-07-08T23:24:12.679Z,1720481052.679 [NAL9602](INFO): Powering down
2024-07-08T23:24:13.521Z,1720481053.521 [CBIT](INFO): Clearing failed state for component NAL9602
2024-07-08T23:24:13.521Z,1720481053.521 [NAL9602] No Fault, FailCount= 4
2024-07-08T23:24:42.979Z,1720481082.979 [NAL9602](INFO): Powering up NAL9602
2024-07-08T23:24:53.891Z,1720481093.891 [NAL9602](INFO): NAL9602 initialized
2024-07-08T23:25:35.115Z,1720481135.115 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-07-08T23:29:10.851Z,1720481350.851 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-07-08T23:24:10.7Z
2024-07-08T23:29:10.851Z,1720481350.851 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T23:29:10.851Z,1720481350.851 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T23:29:21.045Z,1720481361.045 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240708T190513/Courier0106.lzma
2024-07-08T23:29:22.048Z,1720481362.048 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0106.lzma.bak
2024-07-08T23:29:22.048Z,1720481362.048 [DataOverHttps](INFO): SBD MOMSN=21342251
2024-07-08T23:29:42.746Z,1720481382.746 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20240708T190513/Express0107.lzma
2024-07-08T23:29:43.748Z,1720481383.748 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0107.lzma.bak
2024-07-08T23:29:43.748Z,1720481383.748 [DataOverHttps](INFO): SBD MOMSN=21342253
2024-07-08T23:29:54.079Z,1720481394.079 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T23:29:54.079Z,1720481394.079 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T23:29:54.079Z,1720481394.079 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T23:29:57.672Z,1720481397.672 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-08T23:30:28.385Z,1720481428.385 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T23:34:54.699Z,1720481694.699 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T23:34:54.699Z,1720481694.699 [Default:CheckIn:C.Wait] Stopped
2024-07-08T23:34:54.699Z,1720481694.699 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T23:34:54.699Z,1720481694.699 [Default:CheckIn:D] Running Loop=1
2024-07-08T23:34:55.122Z,1720481695.122 [Default:CheckIn:D] Stopped
2024-07-08T23:34:55.122Z,1720481695.122 [Default:CheckIn:E] Running Loop=1
2024-07-08T23:34:55.512Z,1720481695.512 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 266.249609 min
2024-07-08T23:34:55.512Z,1720481695.512 [Default:CheckIn:E] Stopped
2024-07-08T23:34:55.512Z,1720481695.512 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T23:34:55.512Z,1720481695.512 [Default:CheckIn] Stopped
2024-07-08T23:34:55.512Z,1720481695.512 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T23:34:55.512Z,1720481695.512 [Default:CheckIn](INFO): Running loop #36
2024-07-08T23:34:55.512Z,1720481695.512 [Default:CheckIn] Running Loop=36
2024-07-08T23:34:55.512Z,1720481695.512 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T23:34:55.512Z,1720481695.512 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T23:34:57.095Z,1720481697.095 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-07-08T23:34:57.095Z,1720481697.095 [NAL9602] Data Fault, FailCount= 5
2024-07-08T23:34:57.095Z,1720481697.095 [NAL9602](ERROR): Data Fault
2024-07-08T23:34:57.134Z,1720481697.134 [CBIT](ERROR): Data Fault in component: NAL9602
2024-07-08T23:34:57.135Z,1720481697.135 [CBIT](CRITICAL): Data Fault in component: NAL9602
2024-07-08T23:34:57.500Z,1720481697.500 [NAL9602](INFO): Powering down
2024-07-08T23:34:57.521Z,1720481697.521 [CBIT](INFO): Critical error at 20240708T233457
2024-07-08T23:35:35.500Z,1720481735.500 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-07-08T23:36:57.106Z,1720481817.106 [CBIT](INFO): Clearing failed state for component NAL9602
2024-07-08T23:36:57.106Z,1720481817.106 [NAL9602] No Fault, FailCount= 5
2024-07-08T23:36:57.495Z,1720481817.495 [NAL9602](INFO): Powering up NAL9602
2024-07-08T23:37:08.400Z,1720481828.400 [NAL9602](INFO): NAL9602 initialized
2024-07-08T23:39:55.921Z,1720481995.921 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-07-08T23:34:55.5Z
2024-07-08T23:39:55.921Z,1720481995.921 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T23:39:55.921Z,1720481995.921 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T23:39:57.918Z,1720481997.918 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T23:40:04.384Z,1720482004.384 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T23:40:05.570Z,1720482005.570 [DataOverHttps](INFO): Sending 81 bytes from file Logs/20240708T190513/Courier0109.lzma
2024-07-08T23:40:06.573Z,1720482006.573 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0109.lzma.bak
2024-07-08T23:40:06.573Z,1720482006.573 [DataOverHttps](INFO): SBD MOMSN=21342283
2024-07-08T23:40:17.309Z,1720482017.309 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T23:40:30.238Z,1720482030.238 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T23:40:30.959Z,1720482030.959 [DataOverHttps](INFO): Sending 225 bytes from file Logs/20240708T190513/Express0110.lzma
2024-07-08T23:40:31.961Z,1720482031.961 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0110.lzma.bak
2024-07-08T23:40:31.961Z,1720482031.961 [DataOverHttps](INFO): SBD MOMSN=21342286
2024-07-08T23:40:38.338Z,1720482038.338 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T23:40:38.338Z,1720482038.338 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T23:40:38.338Z,1720482038.338 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T23:40:43.166Z,1720482043.166 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T23:40:56.093Z,1720482056.093 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T23:41:09.056Z,1720482069.056 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T23:41:21.963Z,1720482081.963 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T23:41:34.884Z,1720482094.884 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T23:41:47.808Z,1720482107.808 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T23:42:00.739Z,1720482120.739 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T23:42:10.836Z,1720482130.836 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-08T23:42:13.662Z,1720482133.662 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2024-07-08T23:42:27.818Z,1720482147.818 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5.
2024-07-08T23:42:27.825Z,1720482147.825 [BPC1](INFO): Received data from all battery sticks.
2024-07-08T23:42:41.540Z,1720482161.540 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T23:45:35.543Z,1720482335.543 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-07-08T23:45:38.819Z,1720482338.819 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T23:45:38.819Z,1720482338.819 [Default:CheckIn:C.Wait] Stopped
2024-07-08T23:45:38.819Z,1720482338.819 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T23:45:38.819Z,1720482338.819 [Default:CheckIn:D] Running Loop=1
2024-07-08T23:45:39.187Z,1720482339.187 [Default:CheckIn:D] Stopped
2024-07-08T23:45:39.187Z,1720482339.187 [Default:CheckIn:E] Running Loop=1
2024-07-08T23:45:39.601Z,1720482339.601 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 276.984017 min
2024-07-08T23:45:39.602Z,1720482339.602 [Default:CheckIn:E] Stopped
2024-07-08T23:45:39.602Z,1720482339.602 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T23:45:39.602Z,1720482339.602 [Default:CheckIn] Stopped
2024-07-08T23:45:39.602Z,1720482339.602 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T23:45:39.602Z,1720482339.602 [Default:CheckIn](INFO): Running loop #37
2024-07-08T23:45:39.602Z,1720482339.602 [Default:CheckIn] Running Loop=37
2024-07-08T23:45:39.602Z,1720482339.602 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T23:45:39.602Z,1720482339.602 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T23:46:12.306Z,1720482372.306 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2024-07-08T23:46:12.389Z,1720482372.389 [NAL9602](ERROR): received:
+CSQ:0
OK78, 2, 0, 0, 0
OK
2024-07-08T23:50:37.738Z,1720482637.738 [DropWeight](CRITICAL): DROP WEIGHT MISSING.
2024-07-08T23:50:37.739Z,1720482637.739 [DropWeight] Hardware Fault, FailCount= 1
2024-07-08T23:50:37.739Z,1720482637.739 [DropWeight](ERROR): Hardware Fault
2024-07-08T23:50:37.757Z,1720482637.757 [CBIT](INFO): Critical error at 20240708T235037
2024-07-08T23:50:37.760Z,1720482637.760 [CBIT](ERROR): Hardware Fault in component: DropWeight
2024-07-08T23:50:37.760Z,1720482637.760 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2024-07-08T23:50:38.167Z,1720482638.167 [CBIT](INFO): Critical error at 20240708T235037
2024-07-08T23:50:39.787Z,1720482639.787 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-07-08T23:45:39.6Z
2024-07-08T23:50:39.787Z,1720482639.787 [Default:CheckIn:Read_GPS] Stopped
2024-07-08T23:50:39.787Z,1720482639.787 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-08T23:50:43.793Z,1720482643.793 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-08T23:50:49.289Z,1720482649.289 [DataOverHttps](INFO): Sending 126 bytes from file Logs/20240708T190513/Courier0112.lzma
2024-07-08T23:50:50.291Z,1720482650.291 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0112.lzma.bak
2024-07-08T23:50:50.292Z,1720482650.292 [DataOverHttps](INFO): SBD MOMSN=21342320
2024-07-08T23:51:13.001Z,1720482673.001 [DataOverHttps](INFO): Sending 211 bytes from file Logs/20240708T190513/Express0113.lzma
2024-07-08T23:51:14.004Z,1720482674.004 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0113.lzma.bak
2024-07-08T23:51:14.004Z,1720482674.004 [DataOverHttps](INFO): SBD MOMSN=21342326
2024-07-08T23:51:14.502Z,1720482674.502 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-08T23:51:20.172Z,1720482680.172 [Default:CheckIn:Read_Iridium] Stopped
2024-07-08T23:51:20.173Z,1720482680.173 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-08T23:51:20.173Z,1720482680.173 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-08T23:55:35.706Z,1720482935.706 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-07-08T23:56:20.987Z,1720482980.987 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-08T23:56:20.987Z,1720482980.987 [Default:CheckIn:C.Wait] Stopped
2024-07-08T23:56:20.987Z,1720482980.987 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-08T23:56:20.987Z,1720482980.987 [Default:CheckIn:D] Running Loop=1
2024-07-08T23:56:21.370Z,1720482981.370 [Default:CheckIn:D] Stopped
2024-07-08T23:56:21.370Z,1720482981.370 [Default:CheckIn:E] Running Loop=1
2024-07-08T23:56:21.771Z,1720482981.771 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 287.687077 min
2024-07-08T23:56:21.771Z,1720482981.771 [Default:CheckIn:E] Stopped
2024-07-08T23:56:21.771Z,1720482981.771 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-08T23:56:21.771Z,1720482981.771 [Default:CheckIn] Stopped
2024-07-08T23:56:21.771Z,1720482981.771 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-08T23:56:21.771Z,1720482981.771 [Default:CheckIn](INFO): Running loop #38
2024-07-08T23:56:21.772Z,1720482981.772 [Default:CheckIn] Running Loop=38
2024-07-08T23:56:21.772Z,1720482981.772 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-08T23:56:21.772Z,1720482981.772 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-08T23:56:23.384Z,1720482983.384 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-07-08T23:56:23.385Z,1720482983.385 [NAL9602] Data Fault, FailCount= 1
2024-07-08T23:56:23.385Z,1720482983.385 [NAL9602](ERROR): Data Fault
2024-07-08T23:56:23.404Z,1720482983.404 [CBIT](ERROR): Data Fault in component: NAL9602
2024-07-08T23:56:23.780Z,1720482983.780 [NAL9602](INFO): Powering down
2024-07-08T23:56:24.620Z,1720482984.620 [CBIT](INFO): Clearing failed state for component NAL9602
2024-07-08T23:56:24.620Z,1720482984.620 [NAL9602] No Fault, FailCount= 1
2024-07-08T23:56:54.088Z,1720483014.088 [NAL9602](INFO): Powering up NAL9602
2024-07-08T23:57:04.996Z,1720483024.996 [NAL9602](INFO): NAL9602 initialized
2024-07-09T00:01:21.943Z,1720483281.943 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-07-08T23:56:21.8Z
2024-07-09T00:01:21.943Z,1720483281.943 [Default:CheckIn:Read_GPS] Stopped
2024-07-09T00:01:21.943Z,1720483281.943 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-09T00:01:31.511Z,1720483291.511 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240708T190513/Courier0115.lzma
2024-07-09T00:01:32.514Z,1720483292.514 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0115.lzma.bak
2024-07-09T00:01:32.514Z,1720483292.514 [DataOverHttps](INFO): SBD MOMSN=21342353
2024-07-09T00:01:57.544Z,1720483317.544 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20240708T190513/Express0116.lzma
2024-07-09T00:01:58.542Z,1720483318.542 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0116.lzma.bak
2024-07-09T00:01:58.542Z,1720483318.542 [DataOverHttps](INFO): SBD MOMSN=21342357
2024-07-09T00:02:07.646Z,1720483327.646 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-09T00:02:10.881Z,1720483330.881 [Default:CheckIn:Read_Iridium] Stopped
2024-07-09T00:02:10.881Z,1720483330.881 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-09T00:02:10.881Z,1720483330.881 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-09T00:02:38.345Z,1720483358.345 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-09T00:05:36.110Z,1720483536.110 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-07-09T00:05:37.811Z,1720483537.811 [CBIT](INFO): Clearing failed state for component DropWeight
2024-07-09T00:05:37.811Z,1720483537.811 [DropWeight] No Fault, FailCount= 1
2024-07-09T00:07:11.353Z,1720483631.353 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2024-07-09T00:07:11.353Z,1720483631.353 [Default:CheckIn:C.Wait] Stopped
2024-07-09T00:07:11.353Z,1720483631.353 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-09T00:07:11.353Z,1720483631.353 [Default:CheckIn:D] Running Loop=1
2024-07-09T00:07:11.753Z,1720483631.753 [Default:CheckIn:D] Stopped
2024-07-09T00:07:11.753Z,1720483631.753 [Default:CheckIn:E] Running Loop=1
2024-07-09T00:07:12.157Z,1720483632.157 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 298.526790 min
2024-07-09T00:07:12.157Z,1720483632.157 [Default:CheckIn:E] Stopped
2024-07-09T00:07:12.157Z,1720483632.157 [Default:CheckIn](INFO): Completed Default:CheckIn
2024-07-09T00:07:12.157Z,1720483632.157 [Default:CheckIn] Stopped
2024-07-09T00:07:12.157Z,1720483632.157 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-09T00:07:12.158Z,1720483632.158 [Default:CheckIn](INFO): Running loop #39
2024-07-09T00:07:12.158Z,1720483632.158 [Default:CheckIn] Running Loop=39
2024-07-09T00:07:12.158Z,1720483632.158 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2024-07-09T00:07:12.158Z,1720483632.158 [Default:CheckIn:Read_GPS] Running Loop=1
2024-07-09T00:07:13.759Z,1720483633.759 [NAL9602](FAULT): GPS failed to acquire within timeout.
2024-07-09T00:07:13.759Z,1720483633.759 [NAL9602] Data Fault, FailCount= 2
2024-07-09T00:07:13.759Z,1720483633.759 [NAL9602](ERROR): Data Fault
2024-07-09T00:07:13.779Z,1720483633.779 [CBIT](ERROR): Data Fault in component: NAL9602
2024-07-09T00:07:14.171Z,1720483634.171 [NAL9602](INFO): Powering down
2024-07-09T00:07:15.007Z,1720483635.007 [CBIT](INFO): Clearing failed state for component NAL9602
2024-07-09T00:07:15.007Z,1720483635.007 [NAL9602] No Fault, FailCount= 2
2024-07-09T00:07:44.468Z,1720483664.468 [NAL9602](INFO): Powering up NAL9602
2024-07-09T00:07:55.376Z,1720483675.376 [NAL9602](INFO): NAL9602 initialized
2024-07-09T00:12:01.651Z,1720483921.651 [CommandExec](IMPORTANT): got command failComponent
2024-07-09T00:12:01.651Z,1720483921.651 [CommandExec](IMPORTANT): Failed components:
2024-07-09T00:12:01.652Z,1720483921.652 [CommandExec](IMPORTANT): No failed Components.
2024-07-09T00:12:12.342Z,1720483932.342 [Default:CheckIn:Read_GPS](INFO): Timed out from 2024-07-09T00:07:12.2Z
2024-07-09T00:12:12.342Z,1720483932.342 [Default:CheckIn:Read_GPS] Stopped
2024-07-09T00:12:12.342Z,1720483932.342 [Default:CheckIn:Read_Iridium] Running Loop=1
2024-07-09T00:12:30.066Z,1720483950.066 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20240708T190513/Courier0118.lzma
2024-07-09T00:12:31.069Z,1720483951.069 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Courier0118.lzma.bak
2024-07-09T00:12:31.069Z,1720483951.069 [DataOverHttps](INFO): SBD MOMSN=21342388
2024-07-09T00:12:52.919Z,1720483972.919 [DataOverHttps](INFO): Sending 272 bytes from file Logs/20240708T190513/Express0119.lzma
2024-07-09T00:12:53.921Z,1720483973.921 [DataOverHttps](INFO): Moved sent file to Logs/20240708T190513/Express0119.lzma.bak
2024-07-09T00:12:53.921Z,1720483973.921 [DataOverHttps](INFO): SBD MOMSN=21342394
2024-07-09T00:12:58.013Z,1720483978.013 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2024-07-09T00:13:00.092Z,1720483980.092 [Default:CheckIn:Read_Iridium] Stopped
2024-07-09T00:13:00.092Z,1720483980.092 [Default:CheckIn:C.Wait] Running Loop=1
2024-07-09T00:13:00.092Z,1720483980.092 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2024-07-09T00:13:28.535Z,1720484008.535 [NAL9602](INFO): Not Powering down - fast GPS
2024-07-09T00:15:33.867Z,1720484133.867 [CommandExec](IMPORTANT): got command quit
2024-07-09T00:15:34.874Z,1720484134.874 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-09T00:15:34.874Z,1720484134.874 [CommandExec](INFO): Uninitializing the command executive.
2024-07-09T00:15:34.874Z,1720484134.874 [CommandExec](INFO): Uninitializing the command scheduler.
2024-07-09T00:15:34.875Z,1720484134.875 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-07-09T00:15:35.022Z,1720484135.022 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2024-07-09T00:15:35.022Z,1720484135.022 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2024-07-09T00:15:35.022Z,1720484135.022 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-07-09T00:15:35.023Z,1720484135.023 [NavChartDb](INFO): Join timeout helper Thread ID is 2642
2024-07-09T00:15:35.174Z,1720484135.174 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-09T00:15:35.174Z,1720484135.174 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-07-09T00:15:35.182Z,1720484135.182 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2024-07-09T00:15:35.182Z,1720484135.182 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-07-09T00:15:35.183Z,1720484135.183 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2643
2024-07-09T00:15:35.662Z,1720484135.662 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-09T00:15:35.663Z,1720484135.663 [WetLabsBB2FL](INFO): Powering down
2024-07-09T00:15:35.663Z,1720484135.663 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-07-09T00:15:35.666Z,1720484135.666 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2024-07-09T00:15:35.666Z,1720484135.666 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-07-09T00:15:35.667Z,1720484135.667 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2644
2024-07-09T00:15:36.197Z,1720484136.197 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2024-07-09T00:15:36.286Z,1720484136.286 [CTD_Seabird](INFO): Powering down
2024-07-09T00:15:36.298Z,1720484136.298 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-09T00:15:36.298Z,1720484136.298 [CTD_Seabird](INFO): Powering down
2024-07-09T00:15:36.310Z,1720484136.310 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-07-09T00:15:36.327Z,1720484136.327 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2024-07-09T00:15:36.327Z,1720484136.327 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-07-09T00:15:36.327Z,1720484136.327 [Radio_Surface](INFO): Join timeout helper Thread ID is 2645
2024-07-09T00:15:36.531Z,1720484136.531 [Radio_Surface](INFO): Powering down
2024-07-09T00:15:36.531Z,1720484136.531 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-09T00:15:36.532Z,1720484136.532 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-07-09T00:15:36.534Z,1720484136.534 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2024-07-09T00:15:36.534Z,1720484136.534 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-07-09T00:15:36.535Z,1720484136.535 [Onboard](INFO): Join timeout helper Thread ID is 2646
2024-07-09T00:15:38.754Z,1720484138.754 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-09T00:15:38.754Z,1720484138.754 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-07-09T00:15:38.759Z,1720484138.759 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2024-07-09T00:15:38.759Z,1720484138.759 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-07-09T00:15:38.759Z,1720484138.759 [DataOverHttps](INFO): Join timeout helper Thread ID is 2647
2024-07-09T00:15:39.745Z,1720484139.745 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-09T00:15:39.748Z,1720484139.748 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-07-09T00:15:39.751Z,1720484139.751 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2024-07-09T00:15:39.751Z,1720484139.751 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-07-09T00:15:39.751Z,1720484139.751 [BackseatComponent](INFO): Join timeout helper Thread ID is 2648
2024-07-09T00:15:39.894Z,1720484139.894 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-09T00:15:39.895Z,1720484139.895 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-07-09T00:15:39.915Z,1720484139.915 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2024-07-09T00:15:39.915Z,1720484139.915 [logger ThreadHandler](INFO): Thread cancelled.
2024-07-09T00:15:39.915Z,1720484139.915 [logger](INFO): Join timeout helper Thread ID is 2649
2024-07-09T00:15:39.933Z,1720484139.933 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-09T00:15:39.934Z,1720484139.934 [logger ThreadHandler](INFO): Thread cancelled.
2024-07-09T00:15:39.935Z,1720484139.935 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2024-07-09T00:15:39.935Z,1720484139.935 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-07-09T00:15:39.935Z,1720484139.935 [CommandLine](INFO): Join timeout helper Thread ID is 2650
2024-07-09T00:15:39.966Z,1720484139.966 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-09T00:15:39.967Z,1720484139.967 [CommandLine ThreadHandler](INFO): Thread cancelled.
2024-07-09T00:15:39.975Z,1720484139.975 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2024-07-09T00:15:39.975Z,1720484139.975 [CommandExec ThreadHandler](INFO): Thread cancelled.
2024-07-09T00:15:39.975Z,1720484139.975 [CommandExec](INFO): Join timeout helper Thread ID is 2651
2024-07-09T00:15:39.976Z,1720484139.976 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2024-07-09T00:15:39.977Z,1720484139.977 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-07-09T00:15:39.977Z,1720484139.977 [controlThread](INFO): Join timeout helper Thread ID is 2652
2024-07-09T00:15:40.242Z,1720484140.242 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2024-07-09T00:15:40.242Z,1720484140.242 [controlThread](DEBUG): Uninitializing ControlThread
2024-07-09T00:15:40.243Z,1720484140.243 [AHRS_M2](INFO): Powering down
2024-07-09T00:15:40.315Z,1720484140.315 [NAL9602](INFO): Powering down
2024-07-09T00:15:40.316Z,1720484140.316 [Sonardyne_Nano](INFO): Powering down
2024-07-09T00:15:40.435Z,1720484140.435 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2024-07-09T00:15:40.436Z,1720484140.436 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2024-07-09T00:15:40.436Z,1720484140.436 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2024-07-09T00:15:40.437Z,1720484140.437 [MissionManager](INFO): Uninitializing Mission Default
2024-07-09T00:15:40.437Z,1720484140.437 [Default] Stopped
2024-07-09T00:15:40.437Z,1720484140.437 [Default](DEBUG): Aggregate::uninitialize Default
2024-07-09T00:15:40.437Z,1720484140.437 [Default:B.GoToSurface] Stopped
2024-07-09T00:15:40.437Z,1720484140.437 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2024-07-09T00:15:40.437Z,1720484140.437 [Default:CheckIn] Stopped
2024-07-09T00:15:40.437Z,1720484140.437 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2024-07-09T00:15:40.437Z,1720484140.437 [Default:CheckIn:C.Wait] Stopped
2024-07-09T00:15:40.437Z,1720484140.437 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2024-07-09T00:15:40.440Z,1720484140.440 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2024-07-09T00:15:40.440Z,1720484140.440 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2024-07-09T00:15:40.441Z,1720484140.441 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2024-07-09T00:15:40.441Z,1720484140.441 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2024-07-09T00:15:40.441Z,1720484140.441 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2024-07-09T00:15:40.441Z,1720484140.441 [BuoyancyServo](INFO): Powering down
2024-07-09T00:15:40.454Z,1720484140.454 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2024-07-09T00:15:40.454Z,1720484140.454 [ElevatorServo](INFO): Powering down
2024-07-09T00:15:40.455Z,1720484140.455 [MassServo](DEBUG): Uninitialize Mass Servo.
2024-07-09T00:15:40.455Z,1720484140.455 [MassServo](INFO): Powering down
2024-07-09T00:15:40.456Z,1720484140.456 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2024-07-09T00:15:40.456Z,1720484140.456 [RudderServo](INFO): Powering down
2024-07-09T00:15:40.457Z,1720484140.457 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2024-07-09T00:15:40.457Z,1720484140.457 [ThrusterHE](INFO): Powering down
2024-07-09T00:15:40.458Z,1720484140.458 [SBIT](DEBUG): Uninitialize SBIT Component.
2024-07-09T00:15:40.458Z,1720484140.458 [IBIT](DEBUG): Uninitialize IBIT Component.
2024-07-09T00:15:40.459Z,1720484140.459 [CBIT](DEBUG): Uninitialize CBIT Component.
2024-07-09T00:15:40.459Z,1720484140.459 [CBIT](DEBUG): Powering off loads.
2024-07-09T00:15:40.470Z,1720484140.470 [CBIT](DEBUG): Disabling WDT.
2024-07-09T00:15:40.482Z,1720484140.482 [CBIT](DEBUG): Opening all GF detection circuits.
2024-07-09T00:15:40.483Z,1720484140.483 [controlThread ThreadHandler](INFO): Thread cancelled.
2024-07-09T00:15:40.537Z,1720484140.537 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2024-07-09T00:15:40.540Z,1720484140.540 [Onboard ThreadHandler](INFO): Thread cancelled.
2024-07-09T00:15:40.545Z,1720484140.545 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2024-07-09T00:15:40.589Z,1720484140.589 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2024-07-09T00:15:40.630Z,1720484140.630 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2024-07-09T00:15:40.634Z,1720484140.634 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2024-07-09T00:15:40.669Z,1720484140.669 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2024-07-09T00:15:40.743Z,1720484140.743 [logger ThreadHandler](INFO): Thread cancelled.