2025-09-04T16:59:42.182Z,1757005182.182 [Supervisor](DEBUG): Initializing supervisor.
2025-09-04T16:59:42.186Z,1757005182.186 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-09-04T16:59:42.187Z,1757005182.187 [SyncHandler](INFO): Protected caller Thread ID is 1886
2025-09-04T16:59:42.187Z,1757005182.187 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-09-04T16:59:42.188Z,1757005182.188 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-09-04T16:59:42.189Z,1757005182.189 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1887
2025-09-04T16:59:42.193Z,1757005182.193 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-09-04T16:59:42.214Z,1757005182.214 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-09-04T16:59:42.215Z,1757005182.215 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-09-04T16:59:42.215Z,1757005182.215 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1888
2025-09-04T16:59:42.220Z,1757005182.220 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-09-04T16:59:42.221Z,1757005182.221 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-09-04T16:59:42.221Z,1757005182.221 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1889
2025-09-04T16:59:42.223Z,1757005182.223 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-09-04T16:59:42.224Z,1757005182.224 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-09-04T16:59:42.225Z,1757005182.225 [logger ThreadHandler](INFO): Protected caller Thread ID is 1890
2025-09-04T16:59:42.229Z,1757005182.229 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-09-04T16:59:42.229Z,1757005182.229 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-09-04T16:59:42.234Z,1757005182.234 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-09-04T16:59:42.399Z,1757005182.399 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-09-04T16:59:42.400Z,1757005182.400 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-09-04T16:59:42.646Z,1757005182.646 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-09-04T16:59:42.647Z,1757005182.647 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-09-04T16:59:42.926Z,1757005182.926 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-09-04T16:59:42.927Z,1757005182.927 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-09-04T16:59:43.046Z,1757005183.046 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-09-04T16:59:43.046Z,1757005183.046 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-09-04T16:59:43.832Z,1757005183.832 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-09-04T16:59:43.832Z,1757005183.832 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-09-04T16:59:44.386Z,1757005184.386 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-09-04T16:59:44.387Z,1757005184.387 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-09-04T16:59:44.945Z,1757005184.945 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-09-04T16:59:44.945Z,1757005184.945 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-09-04T16:59:45.394Z,1757005185.394 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-09-04T16:59:45.395Z,1757005185.395 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-09-04T16:59:45.760Z,1757005185.760 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-09-04T16:59:45.761Z,1757005185.761 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-09-04T16:59:46.672Z,1757005186.672 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-09-04T16:59:46.673Z,1757005186.673 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-09-04T16:59:46.915Z,1757005186.915 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-09-04T16:59:46.915Z,1757005186.915 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-09-04T16:59:47.073Z,1757005187.073 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-09-04T16:59:47.073Z,1757005187.073 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-09-04T16:59:47.176Z,1757005187.176 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance
2025-09-04T16:59:47.176Z,1757005187.176 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-09-04T16:59:47.266Z,1757005187.266 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-09-04T16:59:47.474Z,1757005187.474 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-09-04T16:59:47.475Z,1757005187.475 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-09-04T16:59:47.734Z,1757005187.734 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-09-04T16:59:47.736Z,1757005187.736 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2025-09-04T16:59:47.739Z,1757005187.739 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2025-09-04T16:59:47.835Z,1757005187.835 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2025-09-04T16:59:48.119Z,1757005188.119 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-09-04T16:59:48.120Z,1757005188.120 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2025-09-04T16:59:48.215Z,1757005188.215 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2025-09-04T16:59:48.398Z,1757005188.398 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2025-09-04T16:59:48.511Z,1757005188.511 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2025-09-04T16:59:48.632Z,1757005188.632 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2025-09-04T16:59:48.755Z,1757005188.755 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2025-09-04T16:59:48.861Z,1757005188.861 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2025-09-04T16:59:49.033Z,1757005189.033 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Derivation.cfg
2025-09-04T16:59:49.141Z,1757005189.141 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/workSite.cfg
2025-09-04T16:59:49.236Z,1757005189.236 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2025-09-04T16:59:49.345Z,1757005189.345 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2025-09-04T16:59:49.443Z,1757005189.443 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2025-09-04T16:59:49.444Z,1757005189.444 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-09-04T16:59:49.452Z,1757005189.452 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-09-04T16:59:49.678Z,1757005189.678 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-09-04T16:59:49.678Z,1757005189.678 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-09-04T16:59:49.876Z,1757005189.876 [BuoyancyServo] Loaded
2025-09-04T16:59:49.877Z,1757005189.877 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-09-04T16:59:49.901Z,1757005189.901 [ElevatorServo] Loaded
2025-09-04T16:59:49.901Z,1757005189.901 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-09-04T16:59:49.924Z,1757005189.924 [MassServo] Loaded
2025-09-04T16:59:49.925Z,1757005189.925 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-09-04T16:59:49.947Z,1757005189.947 [RudderServo] Loaded
2025-09-04T16:59:49.948Z,1757005189.948 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-09-04T16:59:49.966Z,1757005189.966 [ThrusterHE] Loaded
2025-09-04T16:59:49.966Z,1757005189.966 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-09-04T16:59:49.967Z,1757005189.967 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-09-04T16:59:49.967Z,1757005189.967 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-09-04T16:59:50.171Z,1757005190.171 [SBIT](DEBUG): Construct Startup Built In Test.
2025-09-04T16:59:50.186Z,1757005190.186 [SBIT] Loaded
2025-09-04T16:59:50.187Z,1757005190.187 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-09-04T16:59:50.189Z,1757005190.189 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-09-04T16:59:50.211Z,1757005190.211 [IBIT] Loaded
2025-09-04T16:59:50.212Z,1757005190.212 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-09-04T16:59:50.217Z,1757005190.217 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-09-04T16:59:50.420Z,1757005190.420 [CBIT] Loaded
2025-09-04T16:59:50.420Z,1757005190.420 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-09-04T16:59:50.452Z,1757005190.452 [GFScanner] Loaded
2025-09-04T16:59:50.453Z,1757005190.453 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-09-04T16:59:50.453Z,1757005190.453 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-09-04T16:59:50.454Z,1757005190.454 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-09-04T16:59:50.678Z,1757005190.678 [CTD_Seabird] Loaded
2025-09-04T16:59:50.679Z,1757005190.679 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-09-04T16:59:50.680Z,1757005190.680 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406874E0
2025-09-04T16:59:50.680Z,1757005190.680 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1978
2025-09-04T16:59:50.716Z,1757005190.716 [ESPComponent] Loaded
2025-09-04T16:59:50.717Z,1757005190.717 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2025-09-04T16:59:50.739Z,1757005190.739 [PAR_Licor] Loaded
2025-09-04T16:59:50.740Z,1757005190.740 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-09-04T16:59:50.786Z,1757005190.786 [WetLabsBB2FL] Loaded
2025-09-04T16:59:50.786Z,1757005190.786 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-09-04T16:59:50.787Z,1757005190.787 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406B74E0
2025-09-04T16:59:50.787Z,1757005190.787 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1979
2025-09-04T16:59:50.788Z,1757005190.788 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-09-04T16:59:50.789Z,1757005190.789 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-09-04T16:59:50.861Z,1757005190.861 [DepthRateCalculator] Loaded
2025-09-04T16:59:50.861Z,1757005190.861 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-09-04T16:59:50.867Z,1757005190.867 [PitchRateCalculator] Loaded
2025-09-04T16:59:50.867Z,1757005190.867 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-09-04T16:59:50.878Z,1757005190.878 [SpeedCalculator] Loaded
2025-09-04T16:59:50.878Z,1757005190.878 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-09-04T16:59:50.883Z,1757005190.883 [YawRateCalculator] Loaded
2025-09-04T16:59:50.883Z,1757005190.883 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-09-04T16:59:50.904Z,1757005190.904 [ElevatorOffsetCalculator] Loaded
2025-09-04T16:59:50.905Z,1757005190.905 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-09-04T16:59:50.905Z,1757005190.905 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-09-04T16:59:50.906Z,1757005190.906 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-09-04T16:59:51.081Z,1757005191.081 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-09-04T16:59:51.082Z,1757005191.082 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-09-04T16:59:51.130Z,1757005191.130 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-09-04T16:59:51.131Z,1757005191.131 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-09-04T16:59:51.835Z,1757005191.835 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-09-04T16:59:51.835Z,1757005191.835 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-09-04T16:59:51.900Z,1757005191.900 [NavChart] Loaded
2025-09-04T16:59:51.900Z,1757005191.900 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-09-04T16:59:51.906Z,1757005191.906 [UniversalFixResidualReporter] Loaded
2025-09-04T16:59:51.907Z,1757005191.907 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-09-04T16:59:51.907Z,1757005191.907 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-09-04T16:59:51.908Z,1757005191.908 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-09-04T16:59:52.706Z,1757005192.706 [AHRS_M2] Loaded
2025-09-04T16:59:52.706Z,1757005192.706 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-09-04T16:59:52.746Z,1757005192.746 [BackseatComponent] Loaded
2025-09-04T16:59:52.746Z,1757005192.746 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-09-04T16:59:52.747Z,1757005192.747 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A854E0
2025-09-04T16:59:52.747Z,1757005192.747 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1980
2025-09-04T16:59:52.751Z,1757005192.751 [LcmUniversalReporter] Loaded
2025-09-04T16:59:52.751Z,1757005192.751 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-09-04T16:59:53.525Z,1757005193.525 [BPC1] Loaded
2025-09-04T16:59:53.525Z,1757005193.525 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-09-04T16:59:53.630Z,1757005193.630 [DAT] Loaded
2025-09-04T16:59:53.630Z,1757005193.630 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-09-04T16:59:53.631Z,1757005193.631 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40AB54E0
2025-09-04T16:59:53.631Z,1757005193.631 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1981
2025-09-04T16:59:53.705Z,1757005193.705 [DataOverHttps] Loaded
2025-09-04T16:59:53.706Z,1757005193.706 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-09-04T16:59:53.707Z,1757005193.707 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40AE54E0
2025-09-04T16:59:53.707Z,1757005193.707 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1982
2025-09-04T16:59:53.732Z,1757005193.732 [Depth_Keller] Loaded
2025-09-04T16:59:53.732Z,1757005193.732 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-09-04T16:59:53.737Z,1757005193.737 [DropWeight] Loaded
2025-09-04T16:59:53.738Z,1757005193.738 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-09-04T16:59:53.781Z,1757005193.781 [DVL_micro] Loaded
2025-09-04T16:59:53.781Z,1757005193.781 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2025-09-04T16:59:53.846Z,1757005193.846 [NAL9602] Loaded
2025-09-04T16:59:53.846Z,1757005193.846 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-09-04T16:59:53.873Z,1757005193.873 [Onboard] Loaded
2025-09-04T16:59:53.873Z,1757005193.873 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-09-04T16:59:53.874Z,1757005193.874 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40B154E0
2025-09-04T16:59:53.874Z,1757005193.874 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1983
2025-09-04T16:59:53.881Z,1757005193.881 [PowerOnly](INFO): Adding load control power supply at /dev/loadC3
2025-09-04T16:59:53.895Z,1757005193.895 [PowerOnly] Loaded
2025-09-04T16:59:53.895Z,1757005193.895 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2025-09-04T16:59:53.910Z,1757005193.910 [Power24vConverter] Loaded
2025-09-04T16:59:53.910Z,1757005193.910 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-09-04T16:59:53.926Z,1757005193.926 [Radio_Surface] Loaded
2025-09-04T16:59:53.927Z,1757005193.927 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-09-04T16:59:53.928Z,1757005193.928 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40B454E0
2025-09-04T16:59:53.928Z,1757005193.928 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1984
2025-09-04T16:59:53.944Z,1757005193.944 [Sonardyne_Nano] Loaded
2025-09-04T16:59:53.944Z,1757005193.944 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-09-04T16:59:53.945Z,1757005193.945 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-09-04T16:59:53.946Z,1757005193.946 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-09-04T16:59:54.036Z,1757005194.036 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-09-04T16:59:54.037Z,1757005194.037 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-09-04T16:59:54.113Z,1757005194.113 [VerticalControl](DEBUG): Construct VerticalControl.
2025-09-04T16:59:54.167Z,1757005194.167 [VerticalControl] Loaded
2025-09-04T16:59:54.168Z,1757005194.168 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-09-04T16:59:54.171Z,1757005194.171 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-09-04T16:59:54.217Z,1757005194.217 [HorizontalControl] Loaded
2025-09-04T16:59:54.218Z,1757005194.218 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-09-04T16:59:54.220Z,1757005194.220 [SpeedControl](DEBUG): Construct SpeedControl.
2025-09-04T16:59:54.222Z,1757005194.222 [SpeedControl] Loaded
2025-09-04T16:59:54.222Z,1757005194.222 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-09-04T16:59:54.225Z,1757005194.225 [LoopControl](DEBUG): Construct LoopControl.
2025-09-04T16:59:54.226Z,1757005194.226 [LoopControl] Loaded
2025-09-04T16:59:54.226Z,1757005194.226 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-09-04T16:59:54.227Z,1757005194.227 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-09-04T16:59:54.227Z,1757005194.227 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-09-04T16:59:54.243Z,1757005194.243 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-09-04T16:59:54.244Z,1757005194.244 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-09-04T16:59:54.662Z,1757005194.662 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-09-04T16:59:54.669Z,1757005194.669 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-09-04T16:59:54.672Z,1757005194.672 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-09-04T16:59:54.684Z,1757005194.684 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-09-04T16:59:54.685Z,1757005194.685 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C6D4E0
2025-09-04T16:59:54.685Z,1757005194.685 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1985
2025-09-04T16:59:54.690Z,1757005194.690 [Supervisor](INFO): Main Thread ID is 823
2025-09-04T16:59:54.690Z,1757005194.690 [Supervisor](DEBUG): Running supervisor.
2025-09-04T16:59:54.691Z,1757005194.691 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1986
2025-09-04T16:59:54.691Z,1757005194.691 [CommandExec](INFO): Initializing the command executive.
2025-09-04T16:59:54.693Z,1757005194.693 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1987
2025-09-04T16:59:54.695Z,1757005194.695 [controlThread ThreadHandler](INFO): Handler Thread ID is 1988
2025-09-04T16:59:54.695Z,1757005194.695 [controlThread](DEBUG): Initializing ControlThread
2025-09-04T16:59:54.697Z,1757005194.697 [SBIT](INFO): Initialize SBIT Component.
2025-09-04T16:59:54.697Z,1757005194.697 [SBIT](IMPORTANT): git: 2025-09-02
2025-09-04T16:59:54.698Z,1757005194.698 [SBIT](INFO): git hash: 79ab46e6183b98c19ab2829623a4adc5bf79cefb
2025-09-04T16:59:54.698Z,1757005194.698 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-09-04T16:59:54.700Z,1757005194.700 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2025-09-04T16:59:54.701Z,1757005194.701 [SBIT](INFO): Beginning SBIT in 142.000000 seconds.
2025-09-04T16:59:54.701Z,1757005194.701 [IBIT](INFO): Initialize IBIT Component.
2025-09-04T16:59:54.702Z,1757005194.702 [CBIT](DEBUG): Initialize CBIT Component.
2025-09-04T16:59:54.703Z,1757005194.703 [logger ThreadHandler](INFO): Handler Thread ID is 1989
2025-09-04T16:59:54.714Z,1757005194.714 [CBIT](DEBUG): Initialized mux pins.
2025-09-04T16:59:54.714Z,1757005194.714 [CBIT](DEBUG): Initializing the watchdog timer.
2025-09-04T16:59:54.723Z,1757005194.723 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1990
2025-09-04T16:59:54.723Z,1757005194.723 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-09-04T16:59:54.728Z,1757005194.728 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1992
2025-09-04T16:59:54.731Z,1757005194.731 [WetLabsBB2FL](INFO): Powering up
2025-09-04T16:59:54.732Z,1757005194.732 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1994
2025-09-04T16:59:54.738Z,1757005194.738 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-09-04T16:59:54.739Z,1757005194.739 [CBIT](DEBUG): Initializing heartbeat.
2025-09-04T16:59:54.740Z,1757005194.740 [DAT ThreadHandler](INFO): Handler Thread ID is 1995
2025-09-04T16:59:54.741Z,1757005194.741 [DAT](INFO): Powering up
2025-09-04T16:59:54.741Z,1757005194.741 [DAT](DEBUG): Initializing DAT.
2025-09-04T16:59:54.744Z,1757005194.744 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1997
2025-09-04T16:59:54.745Z,1757005194.745 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-09-04T16:59:54.755Z,1757005194.755 [Onboard ThreadHandler](INFO): Handler Thread ID is 1998
2025-09-04T16:59:54.772Z,1757005194.772 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1999
2025-09-04T16:59:54.795Z,1757005194.795 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2000
2025-09-04T16:59:54.803Z,1757005194.803 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-09-04T16:59:54.803Z,1757005194.803 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-09-04T16:59:54.803Z,1757005194.803 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-09-04T16:59:54.803Z,1757005194.803 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-09-04T16:59:54.803Z,1757005194.803 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-09-04T16:59:54.803Z,1757005194.803 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-09-04T16:59:54.804Z,1757005194.804 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-09-04T16:59:54.804Z,1757005194.804 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-09-04T16:59:54.811Z,1757005194.811 [CBIT](DEBUG): Deactivating emergency mode.
2025-09-04T16:59:54.850Z,1757005194.850 [CBIT](DEBUG): Backplane powered.
2025-09-04T16:59:54.851Z,1757005194.851 [GFScanner](DEBUG): Initializing GFScanner
2025-09-04T16:59:54.851Z,1757005194.851 [GFScanner](DEBUG): Deactivating GF circuits.
2025-09-04T16:59:54.859Z,1757005194.859 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-09-04T16:59:54.859Z,1757005194.859 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-09-04T16:59:54.860Z,1757005194.860 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-09-04T16:59:54.860Z,1757005194.860 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-09-04T16:59:54.860Z,1757005194.860 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-09-04T16:59:54.862Z,1757005194.862 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-09-04T16:59:54.863Z,1757005194.863 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-09-04T16:59:54.871Z,1757005194.871 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-09-04T16:59:54.873Z,1757005194.873 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-09-04T16:59:54.874Z,1757005194.874 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-09-04T16:59:54.874Z,1757005194.874 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-09-04T16:59:54.883Z,1757005194.883 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-09-04T16:59:54.884Z,1757005194.884 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-09-04T16:59:54.884Z,1757005194.884 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-09-04T16:59:54.889Z,1757005194.889 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-09-04T16:59:54.981Z,1757005194.981 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2025-09-04T16:59:55.013Z,1757005195.013 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-09-04T16:59:55.041Z,1757005195.041 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Startup {
behavior Guidance:GoToSurface {
run in progression
}
aggregate StartupSatComms {
run in sequence
readDatum {
timeout duration=P1M
Universal:latitude_fix
}
readDatum {
timeout duration=P1M
Universal:platform_communications
}
}
}
2025-09-04T16:59:55.042Z,1757005195.042 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-09-04T16:59:55.042Z,1757005195.042 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-09-04T16:59:55.047Z,1757005195.047 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-09-04T16:59:55.178Z,1757005195.178 [Radio_Surface](INFO): Powering up
2025-09-04T16:59:55.379Z,1757005195.379 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
0
13
Burn on
Dropped weight due to communications timeout.
5
Default mission has been running for
Restarting logs and Default mission.
restart logs
2025-09-04T16:59:55.398Z,1757005195.398 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-09-04T16:59:55.399Z,1757005195.399 [Default:A.Wait](DEBUG): Construct Wait.
2025-09-04T16:59:55.412Z,1757005195.412 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-09-04T16:59:55.433Z,1757005195.433 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-09-04T16:59:55.452Z,1757005195.452 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-09-04T16:59:55.458Z,1757005195.458 [Default:E.Execute](DEBUG): Construct Execute.
2025-09-04T16:59:55.477Z,1757005195.477 [MissionManager](DEBUG):
# Copyright (c) 2024 MBARI
# MBARI Proprietary Information. Confidential. All Rights Reserved
# Unauthorized copying or distribution of this file via any medium is strictly
# prohibited.
#
# WARNING - This file contains information whose export is restricted by the
# Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
# amended. Violations of these export laws are subject to severe civil and/or
# criminal penalties.
mission Default {
arguments {
ElapsedSinceDefaultStarted = 0 minute
}
behavior Guidance:Wait {
"""
Wait a moment to see if the scheduler starts a new mission before
starting to actually run Default.
"""
run in sequence
set duration = 13 second
}
behavior Guidance:GoToSurface {
run in progression
}
aggregate CheckIn {
run in sequence repeat=288
readDatum id="Read_GPS" {
timeout duration=P5M
Universal:time_fix
}
readDatum id="Read_Iridium" {
timeout duration=P4H {
behavior Guidance:Execute {
run in sequence
set command = "Burn on"
}
syslog critical "Dropped weight due to communications timeout."
}
Universal:platform_communications
}
behavior Guidance:Wait {
run in sequence
set duration = 5 minute
}
assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )
syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
}
syslog important "Restarting logs and Default mission."
behavior Guidance:Execute {
run in sequence
set command = "restart logs"
}
}
2025-09-04T16:59:55.482Z,1757005195.482 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,PowerOnly,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
2025-09-04T16:59:55.492Z,1757005195.492 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-09-04T16:59:55.567Z,1757005195.567 [Depth_Keller](INFO): Initializing.
2025-09-04T16:59:55.576Z,1757005195.576 [Power24vConverter](INFO): Powering up.
2025-09-04T16:59:55.577Z,1757005195.577 [Sonardyne_Nano](INFO): Initializing.
2025-09-04T16:59:55.603Z,1757005195.603 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-09-04T16:59:55.625Z,1757005195.625 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-09-04T16:59:55.631Z,1757005195.631 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-09-04T16:59:55.632Z,1757005195.632 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-09-04T16:59:55.639Z,1757005195.639 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-09-04T16:59:55.640Z,1757005195.640 [MassServo](DEBUG): Initializing EZServoServo.
2025-09-04T16:59:55.647Z,1757005195.647 [MassServo](DEBUG): Initializing MassServo.
2025-09-04T16:59:55.647Z,1757005195.647 [RudderServo](DEBUG): Initializing EZServoServo.
2025-09-04T16:59:55.655Z,1757005195.655 [RudderServo](DEBUG): Initializing RudderServo.
2025-09-04T16:59:55.656Z,1757005195.656 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-09-04T16:59:55.663Z,1757005195.663 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-09-04T16:59:55.957Z,1757005195.957 [PowerOnly](INFO): Powering up loadControl
2025-09-04T16:59:57.343Z,1757005197.343 [WetLabsBB2FL](INFO): Powering down
2025-09-04T16:59:57.519Z,1757005197.519 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-09-04T16:59:59.160Z,1757005199.160 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2213
2025-09-04T17:00:01.387Z,1757005201.387 [ThrusterHE](ERROR): Zero Speed Commanded.
2025-09-04T17:00:01.755Z,1757005201.755 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2025-09-04T17:00:06.839Z,1757005206.839 [DAT](INFO): DAT read:
2025-09-04T17:00:06.840Z,1757005206.840 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2025-09-04T17:00:08.855Z,1757005208.855 [DAT](INFO): DAT read: MF Frequency Band
2025-09-04T17:00:08.857Z,1757005208.857 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2025-09-04T17:00:08.857Z,1757005208.857 [DAT](INFO): DAT read: Sep 4 2025 17:00:15
2025-09-04T17:00:09.864Z,1757005209.864 [DAT](INFO): DAT read: Features enabled [Bearing]
2025-09-04T17:00:09.865Z,1757005209.865 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC
2025-09-04T17:00:09.865Z,1757005209.865 [DAT](INFO): commRate: 1200
2025-09-04T17:00:11.934Z,1757005211.934 [DAT](INFO): entering command mode
2025-09-04T17:00:12.135Z,1757005212.135 [DAT](INFO): DAT read:
2025-09-04T17:00:12.136Z,1757005212.136 [DAT](INFO): DAT read: user:1>
2025-09-04T17:00:12.136Z,1757005212.136 [DAT](INFO): setting verbose to 3
2025-09-04T17:00:12.387Z,1757005212.387 [DAT](INFO): DAT read: user:1>
2025-09-04T17:00:12.388Z,1757005212.388 [DAT](INFO): DAT read: Verbose | 3
2025-09-04T17:00:12.389Z,1757005212.389 [DAT](INFO): set verbose to 3
2025-09-04T17:00:12.389Z,1757005212.389 [DAT](INFO): setting DatVerbose to 27440
2025-09-04T17:00:12.639Z,1757005212.639 [DAT](INFO): DAT read: user:2>
2025-09-04T17:00:12.640Z,1757005212.640 [DAT](INFO): DAT read: DatVerbose | 27440
2025-09-04T17:00:12.640Z,1757005212.640 [DAT](INFO): set DatVerbose to 27440
2025-09-04T17:00:12.641Z,1757005212.641 [DAT](INFO): setting transmit power to 8
2025-09-04T17:00:12.891Z,1757005212.891 [DAT](INFO): DAT read: user:3>
2025-09-04T17:00:12.892Z,1757005212.892 [DAT](INFO): DAT read: TxPower | 8 (Max)
2025-09-04T17:00:12.892Z,1757005212.892 [DAT](INFO): set transmit power to 8
2025-09-04T17:00:12.893Z,1757005212.893 [DAT](INFO): setting local address to 5
2025-09-04T17:00:13.143Z,1757005213.143 [DAT](INFO): DAT read: user:4>
2025-09-04T17:00:13.144Z,1757005213.144 [DAT](INFO): DAT read: LocalAddr | 5
2025-09-04T17:00:13.145Z,1757005213.145 [DAT](INFO): set local address to 5
2025-09-04T17:00:13.146Z,1757005213.146 [DAT](INFO): Setting time to: 17:0:13 And date to:9/4/2025
2025-09-04T17:00:13.396Z,1757005213.396 [DAT](INFO): DAT read: user:5>
2025-09-04T17:00:13.398Z,1757005213.398 [DAT](INFO): DAT read: Thu Sep 4, 2025 17:00:13
2025-09-04T17:00:13.399Z,1757005213.399 [DAT](INFO): Local DAT time set to Thu Sep 4, 2025 17:00:13
2025-09-04T17:00:24.222Z,1757005224.222 [NAL9602](INFO): Powering up NAL9602
2025-09-04T17:00:35.097Z,1757005235.097 [NAL9602](INFO): NAL9602 initialized
2025-09-04T17:01:17.531Z,1757005277.531 [NAL9602](INFO): SBD MO Status=2, MOMSN=22896, MT Status=2, MTMSN=0
2025-09-04T17:01:17.531Z,1757005277.531 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-04T17:02:06.613Z,1757005326.613 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004877
2025-09-04T17:02:13.323Z,1757005333.323 [CommandExec](IMPORTANT): got command failComponent
2025-09-04T17:02:13.323Z,1757005333.323 [CommandExec](IMPORTANT): Failed components:
2025-09-04T17:02:13.324Z,1757005333.324 [CommandExec](IMPORTANT): No failed Components.
2025-09-04T17:02:17.348Z,1757005337.348 [SBIT](IMPORTANT): Beginning Startup BIT
2025-09-04T17:02:17.353Z,1757005337.353 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-09-04T17:02:21.847Z,1757005341.847 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.002644
CHAN A1 (24V): 0.005419
CHAN A2 (12V): -0.005840
CHAN A3 (5V): -0.006136
CHAN B0 (3.3V): -0.002061
CHAN B1 (3.15aV): -0.001025
CHAN B2 (3.15bV): -0.001788
CHAN B3 (GND): -0.001462
OPEN: 0.006959
Full Scale: +/- 1 mA
2025-09-04T17:02:40.800Z,1757005360.800 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-04T17:02:53.663Z,1757005373.663 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-09-04T17:02:53.663Z,1757005373.663 [BPC1](ERROR): BPC1A failed to parse battery stick message.
2025-09-04T17:02:54.547Z,1757005374.547 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-04T17:02:54.956Z,1757005374.956 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-09-04T17:02:54.956Z,1757005374.956 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-09-04T17:02:55.115Z,1757005375.115 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-09-04T17:02:55.443Z,1757005375.443 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-09-04T17:02:55.443Z,1757005375.443 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-09-04T17:03:04.806Z,1757005384.806 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-04T17:03:11.107Z,1757005391.107 [SBIT](IMPORTANT): SBIT PASSED
2025-09-04T17:03:11.107Z,1757005391.107 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-09-04T17:03:11.108Z,1757005391.108 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=5 count;
2025-09-04T17:03:11.108Z,1757005391.108 [SBIT](IMPORTANT): ElevatorServo.currLimit=25 percent;
2025-09-04T17:03:11.108Z,1757005391.108 [SBIT](IMPORTANT): Express linearApproximation PowerOnly.component_avgCurrent_loadControl 50.000000 milliampere;
2025-09-04T17:03:11.108Z,1757005391.108 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=302.770621 cubic_centimeter;
2025-09-04T17:03:11.108Z,1757005391.108 [SBIT](IMPORTANT): VerticalControl.massDefault=30.599857 millimeter;
2025-09-04T17:03:11.512Z,1757005391.512 [MissionManager](IMPORTANT): Started mission Startup
2025-09-04T17:03:11.513Z,1757005391.513 [Startup] Running Loop=1
2025-09-04T17:03:11.513Z,1757005391.513 [Startup](DEBUG): Aggregate::initialize Startup
2025-09-04T17:03:11.513Z,1757005391.513 [Startup:A.GoToSurface] Running Loop=1
2025-09-04T17:03:11.513Z,1757005391.513 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-09-04T17:03:11.514Z,1757005391.514 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-09-04T17:03:11.514Z,1757005391.514 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-09-04T17:03:11.516Z,1757005391.516 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-09-04T17:03:11.516Z,1757005391.516 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-09-04T17:03:11.516Z,1757005391.516 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-09-04T17:03:11.517Z,1757005391.517 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-09-04T17:03:11.518Z,1757005391.518 [Startup:StartupSatComms] Running Loop=1
2025-09-04T17:03:11.518Z,1757005391.518 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-09-04T17:03:11.519Z,1757005391.519 [Startup:StartupSatComms:A] Running Loop=1
2025-09-04T17:03:11.921Z,1757005391.921 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-09-04T17:03:16.063Z,1757005396.063 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-04T17:03:26.910Z,1757005406.910 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-04T17:03:37.805Z,1757005417.805 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-04T17:03:49.136Z,1757005429.136 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-04T17:03:52.357Z,1757005432.357 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-04T17:04:00.058Z,1757005440.058 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-04T17:04:05.311Z,1757005445.311 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-04T17:04:11.376Z,1757005451.376 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-04T17:04:11.725Z,1757005451.725 [Startup:StartupSatComms:A](INFO): Timed out from 2025-09-04T17:03:11.5Z
2025-09-04T17:04:11.726Z,1757005451.726 [Startup:StartupSatComms:A] Stopped
2025-09-04T17:04:11.726Z,1757005451.726 [Startup:StartupSatComms:B] Running Loop=1
2025-09-04T17:04:12.126Z,1757005452.126 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-09-04T17:04:19.523Z,1757005459.523 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250904T165942/Courier0000.lzma
2025-09-04T17:04:20.524Z,1757005460.524 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Courier0000.lzma.bak
2025-09-04T17:04:20.524Z,1757005460.524 [DataOverHttps](INFO): SBD MOMSN=25928839
2025-09-04T17:04:22.254Z,1757005462.254 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-04T17:04:33.216Z,1757005473.216 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-04T17:04:36.465Z,1757005476.465 [DataOverHttps](INFO): Sending 966 bytes from file Logs/20250904T165942/Express0001.lzma
2025-09-04T17:04:37.464Z,1757005477.464 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Express0001.lzma.bak
2025-09-04T17:04:37.464Z,1757005477.464 [DataOverHttps](INFO): SBD MOMSN=25928842
2025-09-04T17:04:38.767Z,1757005478.767 [Startup:StartupSatComms:B] Stopped
2025-09-04T17:04:38.767Z,1757005478.767 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-09-04T17:04:38.767Z,1757005478.767 [Startup:StartupSatComms] Stopped
2025-09-04T17:04:38.767Z,1757005478.767 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-09-04T17:04:38.768Z,1757005478.768 [Startup](INFO): Completed Startup
2025-09-04T17:04:38.768Z,1757005478.768 [MissionManager](INFO): Startup is completed.
2025-09-04T17:04:38.768Z,1757005478.768 [MissionManager](INFO): Uninitializing Mission Startup
2025-09-04T17:04:38.768Z,1757005478.768 [Startup] Stopped
2025-09-04T17:04:38.768Z,1757005478.768 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-09-04T17:04:38.769Z,1757005478.769 [Startup:A.GoToSurface] Stopped
2025-09-04T17:04:38.769Z,1757005478.769 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-09-04T17:04:39.172Z,1757005479.172 [MissionManager](IMPORTANT): Started mission Default
2025-09-04T17:04:39.173Z,1757005479.173 [Default] Running Loop=1
2025-09-04T17:04:39.173Z,1757005479.173 [Default](DEBUG): Aggregate::initialize Default
2025-09-04T17:04:39.173Z,1757005479.173 [Default:B.GoToSurface] Running Loop=1
2025-09-04T17:04:39.173Z,1757005479.173 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-09-04T17:04:39.173Z,1757005479.173 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-09-04T17:04:39.173Z,1757005479.173 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-09-04T17:04:39.174Z,1757005479.174 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-09-04T17:04:39.174Z,1757005479.174 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-09-04T17:04:39.175Z,1757005479.175 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-09-04T17:04:39.175Z,1757005479.175 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-09-04T17:04:39.175Z,1757005479.175 [Default:A.Wait] Running Loop=1
2025-09-04T17:04:39.175Z,1757005479.175 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-09-04T17:04:44.478Z,1757005484.478 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-04T17:04:52.515Z,1757005492.515 [Default:A.Wait](INFO): Done Waiting.
2025-09-04T17:04:52.515Z,1757005492.515 [Default:A.Wait] Stopped
2025-09-04T17:04:52.515Z,1757005492.515 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-09-04T17:04:52.926Z,1757005492.926 [Default:CheckIn] Running Loop=1
2025-09-04T17:04:52.927Z,1757005492.927 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-04T17:04:52.927Z,1757005492.927 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-04T17:04:53.314Z,1757005493.314 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-09-04T17:04:55.788Z,1757005495.788 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-04T17:05:05.823Z,1757005505.823 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-09-04T17:05:05.823Z,1757005505.823 [BPC1](ERROR): BPC1A failed to parse battery stick message.
2025-09-04T17:05:17.201Z,1757005517.201 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-04T17:05:36.185Z,1757005536.185 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-04T17:05:38.147Z,1757005538.147 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-09-04T17:05:43.808Z,1757005543.808 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,170543.00,A,3648.16328,N,12147.28239,W,0.078,0.00,040925,,,A*79
2025-09-04T17:05:43.815Z,1757005543.815 [NAL9602](INFO): GPS fix at 20250904T170543: (36.802721, -121.788040)
2025-09-04T17:05:43.834Z,1757005543.834 [Default:CheckIn:Read_GPS] Stopped
2025-09-04T17:05:43.834Z,1757005543.834 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-04T17:05:44.284Z,1757005544.284 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-09-04T17:05:45.252Z,1757005545.252 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20250904T165942/Courier0004.lzma
2025-09-04T17:05:46.248Z,1757005546.248 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Courier0004.lzma.bak
2025-09-04T17:05:46.248Z,1757005546.248 [DataOverHttps](INFO): SBD MOMSN=25928863
2025-09-04T17:06:02.919Z,1757005562.919 [DataOverHttps](INFO): Sending 421 bytes from file Logs/20250904T165942/Express0005.lzma
2025-09-04T17:06:03.924Z,1757005563.924 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Express0005.lzma.bak
2025-09-04T17:06:03.924Z,1757005563.924 [DataOverHttps](INFO): SBD MOMSN=25928866
2025-09-04T17:06:04.967Z,1757005564.967 [Default:CheckIn:Read_Iridium] Stopped
2025-09-04T17:06:04.967Z,1757005564.967 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-04T17:06:04.967Z,1757005564.967 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-04T17:06:16.268Z,1757005576.268 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-04T17:06:31.680Z,1757005591.680 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-04T17:06:35.721Z,1757005595.721 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-04T17:07:00.766Z,1757005620.766 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-04T17:08:13.111Z,1757005693.111 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-04T17:08:16.718Z,1757005696.718 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-04T17:08:26.817Z,1757005706.817 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-04T17:08:45.808Z,1757005725.808 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-04T17:08:59.538Z,1757005739.538 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-04T17:09:58.525Z,1757005798.525 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-04T17:10:31.656Z,1757005831.656 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-04T17:10:45.847Z,1757005845.847 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-04T17:11:05.546Z,1757005865.546 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-04T17:11:05.546Z,1757005865.546 [Default:CheckIn:C.Wait] Stopped
2025-09-04T17:11:05.546Z,1757005865.546 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-04T17:11:05.546Z,1757005865.546 [Default:CheckIn:D] Running Loop=1
2025-09-04T17:11:05.956Z,1757005865.956 [Default:CheckIn:D] Stopped
2025-09-04T17:11:05.956Z,1757005865.956 [Default:CheckIn:E] Running Loop=1
2025-09-04T17:11:06.346Z,1757005866.346 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 6.446384 min
2025-09-04T17:11:06.346Z,1757005866.346 [Default:CheckIn:E] Stopped
2025-09-04T17:11:06.346Z,1757005866.346 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-04T17:11:06.346Z,1757005866.346 [Default:CheckIn] Stopped
2025-09-04T17:11:06.347Z,1757005866.347 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-04T17:11:06.347Z,1757005866.347 [Default:CheckIn](INFO): Running loop #2
2025-09-04T17:11:06.347Z,1757005866.347 [Default:CheckIn] Running Loop=2
2025-09-04T17:11:06.347Z,1757005866.347 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-04T17:11:06.347Z,1757005866.347 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-04T17:11:08.360Z,1757005868.360 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171107.00,A,3648.16483,N,12147.28320,W,0.253,0.00,040925,,,A*78
2025-09-04T17:11:08.363Z,1757005868.363 [NAL9602](INFO): GPS fix at 20250904T171107: (36.802747, -121.788053)
2025-09-04T17:11:08.373Z,1757005868.373 [Default:CheckIn:Read_GPS] Stopped
2025-09-04T17:11:08.373Z,1757005868.373 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-04T17:11:16.054Z,1757005876.054 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250904T165942/Courier0007.lzma
2025-09-04T17:11:17.056Z,1757005877.056 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Courier0007.lzma.bak
2025-09-04T17:11:17.056Z,1757005877.056 [DataOverHttps](INFO): SBD MOMSN=25928876
2025-09-04T17:11:28.609Z,1757005888.609 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-04T17:11:34.303Z,1757005894.303 [DataOverHttps](INFO): Sending 197 bytes from file Logs/20250904T165942/Express0008.lzma
2025-09-04T17:11:35.305Z,1757005895.305 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Express0008.lzma.bak
2025-09-04T17:11:35.305Z,1757005895.305 [DataOverHttps](INFO): SBD MOMSN=25928879
2025-09-04T17:11:35.481Z,1757005895.481 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-04T17:11:36.649Z,1757005896.649 [Default:CheckIn:Read_Iridium] Stopped
2025-09-04T17:11:36.650Z,1757005896.650 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-04T17:11:36.650Z,1757005896.650 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-04T17:11:39.066Z,1757005899.066 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-09-04T17:11:39.138Z,1757005899.138 [NAL9602](ERROR): received:
+CSQ:1
OK896, 2, 0, 0, 0
OK
2025-09-04T17:12:04.110Z,1757005924.110 [BPC1](ERROR): BPC1A: No match for serial number 0000 in BPC1A's battery stick inventory (sticks 1-31 in onboard configuration file).
2025-09-04T17:12:13.056Z,1757005933.056 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-04T17:12:28.007Z,1757005948.007 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-04T17:12:30.371Z,1757005950.371 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-09-04T17:12:30.371Z,1757005950.371 [BPC1](ERROR): BPC1A failed to parse battery stick message.
2025-09-04T17:12:41.827Z,1757005961.827 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-04T17:12:42.214Z,1757005962.214 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-09-04T17:12:53.060Z,1757005973.060 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-04T17:13:03.585Z,1757005983.585 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-04T17:13:14.994Z,1757005994.994 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-04T17:13:26.260Z,1757006006.260 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-04T17:13:37.173Z,1757006017.173 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-04T17:13:48.078Z,1757006028.078 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-04T17:13:53.275Z,1757006033.275 [NAL9602](INFO): SBD MO Status=2, MOMSN=22896, MT Status=2, MTMSN=0
2025-09-04T17:13:53.275Z,1757006033.275 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-04T17:13:58.985Z,1757006038.985 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-04T17:14:09.892Z,1757006049.892 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-04T17:14:19.526Z,1757006059.526 [NAL9602](INFO): SBD MO Status=2, MOMSN=22896, MT Status=2, MTMSN=0
2025-09-04T17:14:19.527Z,1757006059.527 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-04T17:14:21.203Z,1757006061.203 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-04T17:14:32.104Z,1757006072.104 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-04T17:14:42.669Z,1757006082.669 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-04T17:14:53.522Z,1757006093.522 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-04T17:15:31.340Z,1757006131.340 [CommandExec](IMPORTANT): got command burn on
2025-09-04T17:15:31.340Z,1757006131.340 [CommandExec](IMPORTANT): Activating dropweight wire
2025-09-04T17:15:36.697Z,1757006136.697 [NAL9602](INFO): SBD MO Status=2, MOMSN=22896, MT Status=2, MTMSN=0
2025-09-04T17:15:36.698Z,1757006136.698 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-04T17:15:52.407Z,1757006152.407 [CommandExec](IMPORTANT): got command burn off
2025-09-04T17:15:52.408Z,1757006152.408 [CommandExec](IMPORTANT): Deactivating dropweight wire
2025-09-04T17:16:10.295Z,1757006170.295 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-09-04T17:16:37.306Z,1757006197.306 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-04T17:16:37.306Z,1757006197.306 [Default:CheckIn:C.Wait] Stopped
2025-09-04T17:16:37.307Z,1757006197.307 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-04T17:16:37.307Z,1757006197.307 [Default:CheckIn:D] Running Loop=1
2025-09-04T17:16:37.707Z,1757006197.707 [Default:CheckIn:D] Stopped
2025-09-04T17:16:37.707Z,1757006197.707 [Default:CheckIn:E] Running Loop=1
2025-09-04T17:16:38.116Z,1757006198.116 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.975568 min
2025-09-04T17:16:38.116Z,1757006198.116 [Default:CheckIn:E] Stopped
2025-09-04T17:16:38.116Z,1757006198.116 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-04T17:16:38.116Z,1757006198.116 [Default:CheckIn] Stopped
2025-09-04T17:16:38.116Z,1757006198.116 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-04T17:16:38.116Z,1757006198.116 [Default:CheckIn](INFO): Running loop #3
2025-09-04T17:16:38.116Z,1757006198.116 [Default:CheckIn] Running Loop=3
2025-09-04T17:16:38.117Z,1757006198.117 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-04T17:16:38.117Z,1757006198.117 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-04T17:16:40.161Z,1757006200.161 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,171639.00,A,3648.16038,N,12147.28409,W,0.311,0.00,040925,,,A*7D
2025-09-04T17:16:40.168Z,1757006200.168 [NAL9602](INFO): GPS fix at 20250904T171639: (36.802673, -121.788068)
2025-09-04T17:16:40.195Z,1757006200.195 [Default:CheckIn:Read_GPS] Stopped
2025-09-04T17:16:40.195Z,1757006200.195 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-04T17:16:48.142Z,1757006208.142 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250904T165942/Courier0010.lzma
2025-09-04T17:16:49.144Z,1757006209.144 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Courier0010.lzma.bak
2025-09-04T17:16:49.145Z,1757006209.145 [DataOverHttps](INFO): SBD MOMSN=25928882
2025-09-04T17:16:55.178Z,1757006215.178 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-04T17:17:06.479Z,1757006226.479 [DataOverHttps](INFO): Sending 306 bytes from file Logs/20250904T165942/Express0011.lzma
2025-09-04T17:17:07.468Z,1757006227.468 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Express0011.lzma.bak
2025-09-04T17:17:07.468Z,1757006227.468 [DataOverHttps](INFO): SBD MOMSN=25928888
2025-09-04T17:17:08.837Z,1757006228.837 [Default:CheckIn:Read_Iridium] Stopped
2025-09-04T17:17:08.837Z,1757006228.837 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-04T17:17:08.837Z,1757006228.837 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-04T17:17:12.440Z,1757006232.440 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-04T17:17:22.591Z,1757006242.591 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-09-04T17:22:09.397Z,1757006529.397 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-04T17:22:09.397Z,1757006529.397 [Default:CheckIn:C.Wait] Stopped
2025-09-04T17:22:09.397Z,1757006529.397 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-04T17:22:09.397Z,1757006529.397 [Default:CheckIn:D] Running Loop=1
2025-09-04T17:22:09.799Z,1757006529.799 [Default:CheckIn:D] Stopped
2025-09-04T17:22:09.799Z,1757006529.799 [Default:CheckIn:E] Running Loop=1
2025-09-04T17:22:10.208Z,1757006530.208 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 17.510439 min
2025-09-04T17:22:10.208Z,1757006530.208 [Default:CheckIn:E] Stopped
2025-09-04T17:22:10.208Z,1757006530.208 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-04T17:22:10.208Z,1757006530.208 [Default:CheckIn] Stopped
2025-09-04T17:22:10.208Z,1757006530.208 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-04T17:22:10.208Z,1757006530.208 [Default:CheckIn](INFO): Running loop #4
2025-09-04T17:22:10.208Z,1757006530.208 [Default:CheckIn] Running Loop=4
2025-09-04T17:22:10.208Z,1757006530.208 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-04T17:22:10.208Z,1757006530.208 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-04T17:22:11.417Z,1757006531.417 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-09-04T17:22:11.418Z,1757006531.418 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-09-04T17:22:11.419Z,1757006531.419 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-09-04T17:22:11.420Z,1757006531.420 [BPC1](INFO): Calculating totals. Valid battery stick count: 53. Valid reserve battery stick count: 6.
2025-09-04T17:22:11.424Z,1757006531.424 [BPC1](INFO): Received data from all battery sticks.
2025-09-04T17:22:12.212Z,1757006532.212 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172211.00,A,3648.16371,N,12147.28310,W,0.058,201.98,040925,,,A*7D
2025-09-04T17:22:12.214Z,1757006532.214 [NAL9602](INFO): GPS fix at 20250904T172211: (36.802729, -121.788052)
2025-09-04T17:22:12.225Z,1757006532.225 [Default:CheckIn:Read_GPS] Stopped
2025-09-04T17:22:12.225Z,1757006532.225 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-04T17:22:20.247Z,1757006540.247 [DataOverHttps](INFO): Sending 206 bytes from file Logs/20250904T165942/Courier0013.lzma
2025-09-04T17:22:21.248Z,1757006541.248 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Courier0013.lzma.bak
2025-09-04T17:22:21.249Z,1757006541.249 [DataOverHttps](INFO): SBD MOMSN=25928965
2025-09-04T17:22:36.215Z,1757006556.215 [DataOverHttps](INFO): Sending 177 bytes from file Logs/20250904T165942/Express0014.lzma
2025-09-04T17:22:37.216Z,1757006557.216 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Express0014.lzma.bak
2025-09-04T17:22:37.216Z,1757006557.216 [DataOverHttps](INFO): SBD MOMSN=25928970
2025-09-04T17:22:38.484Z,1757006558.484 [Default:CheckIn:Read_Iridium] Stopped
2025-09-04T17:22:38.484Z,1757006558.484 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-04T17:22:38.484Z,1757006558.484 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-04T17:22:42.919Z,1757006562.919 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-09-04T17:22:42.994Z,1757006562.994 [NAL9602](ERROR): received:
+CSQ:0
OK896, 2, 0, 0, 0
OK
2025-09-04T17:23:26.148Z,1757006606.148 [NAL9602](INFO): SBD MO Status=0, MOMSN=22896, MT Status=0, MTMSN=0
2025-09-04T17:23:26.148Z,1757006606.148 [NAL9602](INFO): No messages in MT queue
2025-09-04T17:23:56.846Z,1757006636.846 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-04T17:27:39.064Z,1757006859.064 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-04T17:27:39.065Z,1757006859.065 [Default:CheckIn:C.Wait] Stopped
2025-09-04T17:27:39.065Z,1757006859.065 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-04T17:27:39.065Z,1757006859.065 [Default:CheckIn:D] Running Loop=1
2025-09-04T17:27:39.468Z,1757006859.468 [Default:CheckIn:D] Stopped
2025-09-04T17:27:39.468Z,1757006859.468 [Default:CheckIn:E] Running Loop=1
2025-09-04T17:27:39.874Z,1757006859.874 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.004915 min
2025-09-04T17:27:39.875Z,1757006859.875 [Default:CheckIn:E] Stopped
2025-09-04T17:27:39.875Z,1757006859.875 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-04T17:27:39.875Z,1757006859.875 [Default:CheckIn] Stopped
2025-09-04T17:27:39.875Z,1757006859.875 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-04T17:27:39.875Z,1757006859.875 [Default:CheckIn](INFO): Running loop #5
2025-09-04T17:27:39.875Z,1757006859.875 [Default:CheckIn] Running Loop=5
2025-09-04T17:27:39.875Z,1757006859.875 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-04T17:27:39.875Z,1757006859.875 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-04T17:27:41.881Z,1757006861.881 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172741.00,A,3648.16534,N,12147.28319,W,0.058,201.98,040925,,,A*73
2025-09-04T17:27:41.884Z,1757006861.884 [NAL9602](INFO): GPS fix at 20250904T172741: (36.802756, -121.788053)
2025-09-04T17:27:41.918Z,1757006861.918 [Default:CheckIn:Read_GPS] Stopped
2025-09-04T17:27:41.918Z,1757006861.918 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-04T17:27:49.478Z,1757006869.478 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250904T165942/Courier0016.lzma
2025-09-04T17:28:00.532Z,1757006880.532 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Courier0016.lzma.bak
2025-09-04T17:28:00.532Z,1757006880.532 [DataOverHttps](INFO): SBD MOMSN=25929034
2025-09-04T17:28:26.662Z,1757006906.662 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20250904T165942/Express0017.lzma
2025-09-04T17:28:37.708Z,1757006917.708 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Express0017.lzma.bak
2025-09-04T17:28:37.708Z,1757006917.708 [DataOverHttps](INFO): SBD MOMSN=25929045
2025-09-04T17:28:38.861Z,1757006918.861 [Default:CheckIn:Read_Iridium] Stopped
2025-09-04T17:28:38.861Z,1757006918.861 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-04T17:28:38.861Z,1757006918.861 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-04T17:32:44.084Z,1757007164.084 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-09-04T17:33:14.789Z,1757007194.789 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-04T17:33:39.434Z,1757007219.434 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-04T17:33:39.434Z,1757007219.434 [Default:CheckIn:C.Wait] Stopped
2025-09-04T17:33:39.434Z,1757007219.434 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-04T17:33:39.435Z,1757007219.435 [Default:CheckIn:D] Running Loop=1
2025-09-04T17:33:39.854Z,1757007219.854 [Default:CheckIn:D] Stopped
2025-09-04T17:33:39.854Z,1757007219.854 [Default:CheckIn:E] Running Loop=1
2025-09-04T17:33:40.241Z,1757007220.241 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 29.011353 min
2025-09-04T17:33:40.241Z,1757007220.241 [Default:CheckIn:E] Stopped
2025-09-04T17:33:40.241Z,1757007220.241 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-04T17:33:40.241Z,1757007220.241 [Default:CheckIn] Stopped
2025-09-04T17:33:40.241Z,1757007220.241 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-04T17:33:40.241Z,1757007220.241 [Default:CheckIn](INFO): Running loop #6
2025-09-04T17:33:40.241Z,1757007220.241 [Default:CheckIn] Running Loop=6
2025-09-04T17:33:40.241Z,1757007220.241 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-04T17:33:40.241Z,1757007220.241 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-04T17:33:42.254Z,1757007222.254 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173341.00,A,3648.16431,N,12147.28560,W,0.311,201.98,040925,,,A*74
2025-09-04T17:33:42.256Z,1757007222.256 [NAL9602](INFO): GPS fix at 20250904T173341: (36.802738, -121.788093)
2025-09-04T17:33:42.267Z,1757007222.267 [Default:CheckIn:Read_GPS] Stopped
2025-09-04T17:33:42.267Z,1757007222.267 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-04T17:33:50.170Z,1757007230.170 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250904T165942/Courier0019.lzma
2025-09-04T17:34:01.212Z,1757007241.212 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Courier0019.lzma.bak
2025-09-04T17:34:01.212Z,1757007241.212 [DataOverHttps](INFO): SBD MOMSN=25929055
2025-09-04T17:34:12.960Z,1757007252.960 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-09-04T17:34:13.034Z,1757007253.034 [NAL9602](ERROR): received:
+CSQ:0
OK896, 0, 0, 0, 0
OK
2025-09-04T17:34:27.428Z,1757007267.428 [DataOverHttps](INFO): Sending 245 bytes from file Logs/20250904T165942/Express0020.lzma
2025-09-04T17:34:38.468Z,1757007278.468 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Express0020.lzma.bak
2025-09-04T17:34:38.469Z,1757007278.469 [DataOverHttps](INFO): SBD MOMSN=25929059
2025-09-04T17:34:39.628Z,1757007279.628 [Default:CheckIn:Read_Iridium] Stopped
2025-09-04T17:34:39.628Z,1757007279.628 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-04T17:34:39.628Z,1757007279.628 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-04T17:34:45.686Z,1757007285.686 [NAL9602](INFO): SBD MO Status=2, MOMSN=22897, MT Status=2, MTMSN=0
2025-09-04T17:34:45.687Z,1757007285.687 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-04T17:35:13.170Z,1757007313.170 [NAL9602](INFO): SBD MO Status=2, MOMSN=22897, MT Status=2, MTMSN=0
2025-09-04T17:35:13.175Z,1757007313.175 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-04T17:35:29.727Z,1757007329.727 [NAL9602](INFO): SBD MO Status=2, MOMSN=22897, MT Status=2, MTMSN=0
2025-09-04T17:35:29.727Z,1757007329.727 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-04T17:38:44.048Z,1757007524.048 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-09-04T17:39:14.757Z,1757007554.757 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-04T17:39:40.213Z,1757007580.213 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-04T17:39:40.213Z,1757007580.213 [Default:CheckIn:C.Wait] Stopped
2025-09-04T17:39:40.213Z,1757007580.213 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-04T17:39:40.213Z,1757007580.213 [Default:CheckIn:D] Running Loop=1
2025-09-04T17:39:40.629Z,1757007580.629 [Default:CheckIn:D] Stopped
2025-09-04T17:39:40.630Z,1757007580.630 [Default:CheckIn:E] Running Loop=1
2025-09-04T17:39:41.034Z,1757007581.034 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.024280 min
2025-09-04T17:39:41.035Z,1757007581.035 [Default:CheckIn:E] Stopped
2025-09-04T17:39:41.035Z,1757007581.035 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-04T17:39:41.035Z,1757007581.035 [Default:CheckIn] Stopped
2025-09-04T17:39:41.035Z,1757007581.035 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-04T17:39:41.035Z,1757007581.035 [Default:CheckIn](INFO): Running loop #7
2025-09-04T17:39:41.035Z,1757007581.035 [Default:CheckIn] Running Loop=7
2025-09-04T17:39:41.035Z,1757007581.035 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-04T17:39:41.035Z,1757007581.035 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-04T17:39:43.038Z,1757007583.038 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173942.00,A,3648.16560,N,12147.28355,W,0.058,0.00,040925,,,A*74
2025-09-04T17:39:43.040Z,1757007583.040 [NAL9602](INFO): GPS fix at 20250904T173942: (36.802760, -121.788059)
2025-09-04T17:39:43.051Z,1757007583.051 [Default:CheckIn:Read_GPS] Stopped
2025-09-04T17:39:43.051Z,1757007583.051 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-04T17:39:43.853Z,1757007583.853 [Default:CheckIn:Read_Iridium] Stopped
2025-09-04T17:39:43.853Z,1757007583.853 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-04T17:39:43.853Z,1757007583.853 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-04T17:40:13.737Z,1757007613.737 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-09-04T17:40:13.814Z,1757007613.814 [NAL9602](ERROR): received:
+CSQ:0
OK897, 2, 0, 0, 0
OK
2025-09-04T17:42:58.978Z,1757007778.978 [NAL9602](INFO): SBD MO Status=2, MOMSN=22897, MT Status=2, MTMSN=0
2025-09-04T17:42:58.978Z,1757007778.978 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-04T17:44:44.441Z,1757007884.441 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-04T17:44:44.441Z,1757007884.441 [Default:CheckIn:C.Wait] Stopped
2025-09-04T17:44:44.441Z,1757007884.441 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-04T17:44:44.441Z,1757007884.441 [Default:CheckIn:D] Running Loop=1
2025-09-04T17:44:44.840Z,1757007884.840 [Default:CheckIn:D] Stopped
2025-09-04T17:44:44.840Z,1757007884.840 [Default:CheckIn:E] Running Loop=1
2025-09-04T17:44:45.220Z,1757007885.220 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-09-04T17:44:45.231Z,1757007885.231 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 40.094450 min
2025-09-04T17:44:45.231Z,1757007885.231 [Default:CheckIn:E] Stopped
2025-09-04T17:44:45.231Z,1757007885.231 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-04T17:44:45.231Z,1757007885.231 [Default:CheckIn] Stopped
2025-09-04T17:44:45.232Z,1757007885.232 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-04T17:44:45.232Z,1757007885.232 [Default:CheckIn](INFO): Running loop #8
2025-09-04T17:44:45.232Z,1757007885.232 [Default:CheckIn] Running Loop=8
2025-09-04T17:44:45.232Z,1757007885.232 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-04T17:44:45.232Z,1757007885.232 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-04T17:44:50.881Z,1757007890.881 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174450.00,A,3648.16636,N,12147.28448,W,0.136,213.02,040925,,,A*7D
2025-09-04T17:44:50.883Z,1757007890.883 [NAL9602](INFO): GPS fix at 20250904T174450: (36.802773, -121.788075)
2025-09-04T17:44:50.894Z,1757007890.894 [Default:CheckIn:Read_GPS] Stopped
2025-09-04T17:44:50.894Z,1757007890.894 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-04T17:44:57.358Z,1757007897.358 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250904T165942/Courier0022.lzma
2025-09-04T17:45:08.410Z,1757007908.410 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Courier0022.lzma.bak
2025-09-04T17:45:08.410Z,1757007908.410 [DataOverHttps](INFO): SBD MOMSN=25929086
2025-09-04T17:45:23.598Z,1757007923.598 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-04T17:45:35.402Z,1757007935.402 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250904T165942/Courier0025.lzma
2025-09-04T17:45:46.444Z,1757007946.444 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Courier0025.lzma.bak
2025-09-04T17:45:46.444Z,1757007946.444 [DataOverHttps](INFO): SBD MOMSN=25929089
2025-09-04T17:46:12.539Z,1757007972.539 [DataOverHttps](INFO): Sending 245 bytes from file Logs/20250904T165942/Express0023.lzma
2025-09-04T17:46:23.608Z,1757007983.608 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Express0023.lzma.bak
2025-09-04T17:46:23.609Z,1757007983.609 [DataOverHttps](INFO): SBD MOMSN=25929092
2025-09-04T17:46:50.015Z,1757008010.015 [DataOverHttps](INFO): Sending 251 bytes from file Logs/20250904T165942/Express0026.lzma
2025-09-04T17:47:01.056Z,1757008021.056 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Express0026.lzma.bak
2025-09-04T17:47:01.056Z,1757008021.056 [DataOverHttps](INFO): SBD MOMSN=25929096
2025-09-04T17:47:02.201Z,1757008022.201 [Default:CheckIn:Read_Iridium] Stopped
2025-09-04T17:47:02.201Z,1757008022.201 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-04T17:47:02.202Z,1757008022.202 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-04T17:48:51.829Z,1757008131.829 [DataOverHttps](IMPORTANT): SBD MTMSN=20250904T174850
2025-09-04T17:48:59.331Z,1757008139.331 [DataOverHttps](INFO): Received command: ibit
2025-09-04T17:48:59.415Z,1757008139.415 [CommandExec](IMPORTANT): got command ibit
2025-09-04T17:48:59.760Z,1757008139.760 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-09-04T17:48:59.760Z,1757008139.760 [IBIT](IMPORTANT): Beginning control surface checks.
2025-09-04T17:48:59.779Z,1757008139.779 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-09-04T17:49:01.374Z,1757008141.374 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174900.00,A,3648.14803,N,12147.22796,W,0.039,27.19,040925,,,A*44
2025-09-04T17:49:01.376Z,1757008141.376 [NAL9602](INFO): GPS fix at 20250904T174900: (36.802467, -121.787133)
2025-09-04T17:49:04.307Z,1757008144.307 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006783
CHAN A1 (24V): 0.001169
CHAN A2 (12V): -0.004948
CHAN A3 (5V): -0.002399
CHAN B0 (3.3V): -0.000587
CHAN B1 (3.15aV): -0.001475
CHAN B2 (3.15bV): -0.001391
CHAN B3 (GND): 0.000252
OPEN: 0.006959
Full Scale: +/- 1 mA
2025-09-04T17:49:10.372Z,1757008150.372 [DataOverHttps](IMPORTANT): SBD MTMSN=20250904T174909
2025-09-04T17:49:18.126Z,1757008158.126 [DataOverHttps](INFO): Received command: failc
2025-09-04T17:49:18.203Z,1757008158.203 [CommandExec](IMPORTANT): got command failComponent
2025-09-04T17:49:18.203Z,1757008158.203 [CommandExec](IMPORTANT): Failed components:
2025-09-04T17:49:18.204Z,1757008158.204 [CommandExec](IMPORTANT): No failed Components.
2025-09-04T17:49:32.091Z,1757008172.091 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-09-04T17:49:32.174Z,1757008172.174 [NAL9602](ERROR): received:
+CSQ:0
OK897, 2, 0, 0, 0
OK
2025-09-04T17:49:45.876Z,1757008185.876 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 3
Latitude: 36.802467 Longitude: -121.787132
2025-09-04T17:49:46.318Z,1757008186.318 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.532000
2025-09-04T17:49:46.335Z,1757008186.335 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2025-09-04T17:49:46.335Z,1757008186.335 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2025-09-04T17:49:46.672Z,1757008186.672 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2025-09-04T17:49:46.672Z,1757008186.672 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2025-09-04T17:49:46.672Z,1757008186.672 [IBIT](IMPORTANT): Pressure:9.964945 PSI
2025-09-04T17:49:46.673Z,1757008186.673 [IBIT](IMPORTANT): Humidity:7.319008 %
2025-09-04T17:49:47.078Z,1757008187.078 [IBIT](IMPORTANT): Vehicle Pitch:-0.895958 degrees
2025-09-04T17:49:47.083Z,1757008187.083 [IBIT](IMPORTANT): Vehicle Roll:8.475417 degrees
2025-09-04T17:49:47.083Z,1757008187.083 [IBIT](IMPORTANT): Vehicle Heading:13.454292 degrees
2025-09-04T17:49:47.494Z,1757008187.494 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2025-09-04T17:49:47.498Z,1757008187.498 [IBIT](IMPORTANT): buoyancyNeutral: 302.770630 cc
2025-09-04T17:49:47.499Z,1757008187.499 [IBIT](IMPORTANT): massDefault: 3.059986 cm
2025-09-04T17:49:47.499Z,1757008187.499 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2025-09-04T17:49:47.499Z,1757008187.499 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2025-09-04T17:49:47.499Z,1757008187.499 [IBIT](IMPORTANT): IBIT PASSED
2025-09-04T17:49:53.101Z,1757008193.101 [NAL9602](INFO): SBD MO Status=0, MOMSN=22897, MT Status=0, MTMSN=0
2025-09-04T17:49:53.101Z,1757008193.101 [NAL9602](INFO): No messages in MT queue
2025-09-04T17:50:23.812Z,1757008223.812 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-04T17:52:02.796Z,1757008322.796 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-04T17:52:02.797Z,1757008322.797 [Default:CheckIn:C.Wait] Stopped
2025-09-04T17:52:02.797Z,1757008322.797 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-04T17:52:02.797Z,1757008322.797 [Default:CheckIn:D] Running Loop=1
2025-09-04T17:52:03.198Z,1757008323.198 [Default:CheckIn:D] Stopped
2025-09-04T17:52:03.198Z,1757008323.198 [Default:CheckIn:E] Running Loop=1
2025-09-04T17:52:03.609Z,1757008323.609 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 47.400415 min
2025-09-04T17:52:03.609Z,1757008323.609 [Default:CheckIn:E] Stopped
2025-09-04T17:52:03.609Z,1757008323.609 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-04T17:52:03.609Z,1757008323.609 [Default:CheckIn] Stopped
2025-09-04T17:52:03.609Z,1757008323.609 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-04T17:52:03.609Z,1757008323.609 [Default:CheckIn](INFO): Running loop #9
2025-09-04T17:52:03.609Z,1757008323.609 [Default:CheckIn] Running Loop=9
2025-09-04T17:52:03.610Z,1757008323.610 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-04T17:52:03.610Z,1757008323.610 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-04T17:52:05.612Z,1757008325.612 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175204.00,A,3648.14717,N,12147.22494,W,0.019,27.19,040925,,,D*46
2025-09-04T17:52:05.615Z,1757008325.615 [NAL9602](INFO): GPS fix at 20250904T175204: (36.802453, -121.787082)
2025-09-04T17:52:05.645Z,1757008325.645 [Default:CheckIn:Read_GPS] Stopped
2025-09-04T17:52:05.645Z,1757008325.645 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-04T17:52:13.534Z,1757008333.534 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250904T165942/Courier0028.lzma
2025-09-04T17:52:18.549Z,1757008338.549 [NAL9602](INFO): SBD MO Status=0, MOMSN=22898, MT Status=0, MTMSN=0
2025-09-04T17:52:18.549Z,1757008338.549 [NAL9602](INFO): No messages in MT queue
2025-09-04T17:52:24.576Z,1757008344.576 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Courier0028.lzma.bak
2025-09-04T17:52:24.577Z,1757008344.577 [DataOverHttps](INFO): SBD MOMSN=25929112
2025-09-04T17:52:49.256Z,1757008369.256 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-04T17:52:50.769Z,1757008370.769 [DataOverHttps](INFO): Sending 956 bytes from file Logs/20250904T165942/Express0029.lzma
2025-09-04T17:53:01.808Z,1757008381.808 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Express0029.lzma.bak
2025-09-04T17:53:01.809Z,1757008381.809 [DataOverHttps](INFO): SBD MOMSN=25929116
2025-09-04T17:53:27.906Z,1757008407.906 [DataOverHttps](INFO): Sending 64 bytes from file Logs/20250904T165942/Express0032.lzma
2025-09-04T17:53:38.948Z,1757008418.948 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Express0032.lzma.bak
2025-09-04T17:53:38.949Z,1757008418.949 [DataOverHttps](INFO): SBD MOMSN=25929148
2025-09-04T17:53:40.164Z,1757008420.164 [Default:CheckIn:Read_Iridium] Stopped
2025-09-04T17:53:40.165Z,1757008420.165 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-04T17:53:40.165Z,1757008420.165 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-04T17:58:40.775Z,1757008720.775 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-04T17:58:40.776Z,1757008720.776 [Default:CheckIn:C.Wait] Stopped
2025-09-04T17:58:40.776Z,1757008720.776 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-04T17:58:40.776Z,1757008720.776 [Default:CheckIn:D] Running Loop=1
2025-09-04T17:58:41.198Z,1757008721.198 [Default:CheckIn:D] Stopped
2025-09-04T17:58:41.198Z,1757008721.198 [Default:CheckIn:E] Running Loop=1
2025-09-04T17:58:41.565Z,1757008721.565 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 54.033691 min
2025-09-04T17:58:41.565Z,1757008721.565 [Default:CheckIn:E] Stopped
2025-09-04T17:58:41.565Z,1757008721.565 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-04T17:58:41.565Z,1757008721.565 [Default:CheckIn] Stopped
2025-09-04T17:58:41.565Z,1757008721.565 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-04T17:58:41.565Z,1757008721.565 [Default:CheckIn](INFO): Running loop #10
2025-09-04T17:58:41.565Z,1757008721.565 [Default:CheckIn] Running Loop=10
2025-09-04T17:58:41.566Z,1757008721.566 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-04T17:58:41.566Z,1757008721.566 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-04T17:58:43.590Z,1757008723.590 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175842.00,A,3648.15070,N,12147.22170,W,0.039,41.42,040925,,,D*4A
2025-09-04T17:58:43.592Z,1757008723.592 [NAL9602](INFO): GPS fix at 20250904T175842: (36.802512, -121.787028)
2025-09-04T17:58:43.603Z,1757008723.603 [Default:CheckIn:Read_GPS] Stopped
2025-09-04T17:58:43.603Z,1757008723.603 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-04T17:58:51.154Z,1757008731.154 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250904T165942/Courier0034.lzma
2025-09-04T17:58:59.738Z,1757008739.738 [NAL9602](INFO): SBD MO Status=0, MOMSN=22899, MT Status=0, MTMSN=0
2025-09-04T17:58:59.738Z,1757008739.738 [NAL9602](INFO): No messages in MT queue
2025-09-04T17:59:02.200Z,1757008742.200 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Courier0034.lzma.bak
2025-09-04T17:59:02.201Z,1757008742.201 [DataOverHttps](INFO): SBD MOMSN=25929150
2025-09-04T17:59:28.323Z,1757008768.323 [DataOverHttps](INFO): Sending 166 bytes from file Logs/20250904T165942/Express0035.lzma
2025-09-04T17:59:30.440Z,1757008770.440 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-04T17:59:39.364Z,1757008779.364 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Express0035.lzma.bak
2025-09-04T17:59:39.365Z,1757008779.365 [DataOverHttps](INFO): SBD MOMSN=25929158
2025-09-04T17:59:40.561Z,1757008780.561 [Default:CheckIn:Read_Iridium] Stopped
2025-09-04T17:59:40.561Z,1757008780.561 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-04T17:59:40.561Z,1757008780.561 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-04T18:04:41.136Z,1757009081.136 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-04T18:04:41.136Z,1757009081.136 [Default:CheckIn:C.Wait] Stopped
2025-09-04T18:04:41.136Z,1757009081.136 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-04T18:04:41.136Z,1757009081.136 [Default:CheckIn:D] Running Loop=1
2025-09-04T18:04:41.530Z,1757009081.530 [Default:CheckIn:D] Stopped
2025-09-04T18:04:41.530Z,1757009081.530 [Default:CheckIn:E] Running Loop=1
2025-09-04T18:04:41.940Z,1757009081.940 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 60.039290 min
2025-09-04T18:04:41.940Z,1757009081.940 [Default:CheckIn:E] Stopped
2025-09-04T18:04:41.940Z,1757009081.940 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-04T18:04:41.940Z,1757009081.940 [Default:CheckIn] Stopped
2025-09-04T18:04:41.940Z,1757009081.940 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-04T18:04:41.940Z,1757009081.940 [Default:CheckIn](INFO): Running loop #11
2025-09-04T18:04:41.940Z,1757009081.940 [Default:CheckIn] Running Loop=11
2025-09-04T18:04:41.940Z,1757009081.940 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-04T18:04:41.940Z,1757009081.940 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-04T18:04:43.948Z,1757009083.948 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,180443.00,A,3648.15242,N,12147.22348,W,0.058,41.42,040925,,,D*40
2025-09-04T18:04:43.951Z,1757009083.951 [NAL9602](INFO): GPS fix at 20250904T180443: (36.802540, -121.787058)
2025-09-04T18:04:43.982Z,1757009083.982 [Default:CheckIn:Read_GPS] Stopped
2025-09-04T18:04:43.982Z,1757009083.982 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-04T18:04:51.726Z,1757009091.726 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250904T165942/Courier0037.lzma
2025-09-04T18:04:58.495Z,1757009098.495 [NAL9602](INFO): SBD MO Status=0, MOMSN=22900, MT Status=0, MTMSN=0
2025-09-04T18:04:58.495Z,1757009098.495 [NAL9602](INFO): No messages in MT queue
2025-09-04T18:05:02.769Z,1757009102.769 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Courier0037.lzma.bak
2025-09-04T18:05:02.769Z,1757009102.769 [DataOverHttps](INFO): SBD MOMSN=25929188
2025-09-04T18:05:29.190Z,1757009129.190 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-04T18:05:30.175Z,1757009130.175 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20250904T165942/Express0038.lzma
2025-09-04T18:05:41.217Z,1757009141.217 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Express0038.lzma.bak
2025-09-04T18:05:41.217Z,1757009141.217 [DataOverHttps](INFO): SBD MOMSN=25929192
2025-09-04T18:05:42.532Z,1757009142.532 [Default:CheckIn:Read_Iridium] Stopped
2025-09-04T18:05:42.533Z,1757009142.533 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-04T18:05:42.533Z,1757009142.533 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-04T18:05:52.226Z,1757009152.226 [DVL_micro](ERROR): Failed to parse:
:WI,+01372,+01302,-00566,+00000,A
2025-09-04T18:08:27.828Z,1757009307.828 [DAT](INFO): DAT read: user:6> Not Received
2025-09-04T18:09:41.937Z,1757009381.937 [DAT](INFO): DAT read: user:14>irectional Acoustic Transponder version 8.15.0
2025-09-04T18:09:41.937Z,1757009381.937 [DAT](INFO): DAT read: Sep 4 2025 18:09:36
2025-09-04T18:10:01.490Z,1757009401.490 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-09-04T18:10:43.108Z,1757009443.108 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-04T18:10:43.108Z,1757009443.108 [Default:CheckIn:C.Wait] Stopped
2025-09-04T18:10:43.108Z,1757009443.108 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-04T18:10:43.108Z,1757009443.108 [Default:CheckIn:D] Running Loop=1
2025-09-04T18:10:43.530Z,1757009443.530 [Default:CheckIn:D] Stopped
2025-09-04T18:10:43.530Z,1757009443.530 [Default:CheckIn:E] Running Loop=1
2025-09-04T18:10:43.927Z,1757009443.927 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 66.072616 min
2025-09-04T18:10:43.928Z,1757009443.928 [Default:CheckIn:E] Stopped
2025-09-04T18:10:43.928Z,1757009443.928 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-04T18:10:43.928Z,1757009443.928 [Default:CheckIn] Stopped
2025-09-04T18:10:43.928Z,1757009443.928 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-04T18:10:43.928Z,1757009443.928 [Default:CheckIn](INFO): Running loop #12
2025-09-04T18:10:43.928Z,1757009443.928 [Default:CheckIn] Running Loop=12
2025-09-04T18:10:43.928Z,1757009443.928 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-04T18:10:43.928Z,1757009443.928 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-04T18:10:46.021Z,1757009446.021 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181045.00,A,3648.15125,N,12147.22291,W,0.039,41.42,040925,,,D*43
2025-09-04T18:10:46.039Z,1757009446.039 [NAL9602](INFO): GPS fix at 20250904T181045: (36.802521, -121.787048)
2025-09-04T18:10:46.069Z,1757009446.069 [Default:CheckIn:Read_GPS] Stopped
2025-09-04T18:10:46.069Z,1757009446.069 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-04T18:10:53.992Z,1757009453.992 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250904T165942/Courier0040.lzma
2025-09-04T18:11:02.502Z,1757009462.502 [NAL9602](INFO): SBD MO Status=0, MOMSN=22901, MT Status=0, MTMSN=0
2025-09-04T18:11:02.502Z,1757009462.502 [NAL9602](INFO): No messages in MT queue
2025-09-04T18:11:05.033Z,1757009465.033 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Courier0040.lzma.bak
2025-09-04T18:11:05.033Z,1757009465.033 [DataOverHttps](IMPORTANT): SBD MOMSN=25929229, MTMSN=20250904T181104
2025-09-04T18:11:12.655Z,1757009472.655 [DataOverHttps](INFO): Received command: ! echo 0 > /dev/loadB1
2025-09-04T18:11:12.660Z,1757009472.660 [CommandExec](IMPORTANT): got command ! echo 0 > /dev/loadB1
2025-09-04T18:11:14.770Z,1757009474.770 [DAT](INFO): DAT read: esponse Not Rec-------------------------
2025-09-04T18:11:14.773Z,1757009474.773 [DAT](INFO): unknown deviceResponse_: esponse Not Rec-------------------------
2025-09-04T18:11:14.791Z,1757009474.791 [DAT](INFO): DAT read: | BearingResp | 0 (Abs) | 0 (Abs), 1 (Rel), 2 (Comp),
2025-09-04T18:11:14.792Z,1757009474.792 [DAT](INFO): unknown deviceResponse_: | BearingResp | 0 (Abs) | 0 (Abs), 1 (Rel), 2 (Comp),
2025-09-04T18:11:14.889Z,1757009474.889 [DAT](INFO): DAT read: | | | 3 (Tilt), 4 (AbBrg), 5 (AbInc),
2025-09-04T18:11:14.899Z,1757009474.899 [DAT](INFO): unknown deviceResponse_: | | | 3 (Tilt), 4 (AbBrg), 5 (AbInc),
2025-09-04T18:11:14.937Z,1757009474.937 [DAT](INFO): DAT read: | | | 6 (RlBrg), 7 (RlElev), 8 (CompHead),
2025-09-04T18:11:14.937Z,1757009474.937 [DAT](INFO): unknown deviceResponse_: | | | 6 (RlBrg), 7 (RlElev), 8 (CompHead),
2025-09-04T18:11:14.960Z,1757009474.960 [DAT](INFO): DAT read: | | | 9 (CompPitch), 10 (CompRoll),
2025-09-04T18:11:14.961Z,1757009474.961 [DAT](INFO): unknown deviceResponse_: | | | 9 (CompPitch), 10 (CompRoll),
2025-09-04T18:11:14.986Z,1757009474.986 [DAT](INFO): DAT read: | | | 11 (Depth), 15 (RngOnly)
2025-09-04T18:11:14.987Z,1757009474.987 [DAT](INFO): unknown deviceResponse_: | | | 11 (Depth), 15 (RngOnly)
2025-09-04T18:11:14.995Z,1757009474.995 [DAT](INFO): DAT read: | DatVerbose | 2 | 0..65535
2025-09-04T18:11:15.031Z,1757009475.031 [DAT](INFO): DAT read: | RXonDAT | Dis | na, Dis
2025-09-04T18:11:15.031Z,1757009475.031 [DAT](INFO): unknown deviceResponse_: | RXonDAT | Dis | na, Dis
2025-09-04T18:11:15.043Z,1757009475.043 [DAT](INFO): DAT read: | PreGain | 0 | -89..89
2025-09-04T18:11:15.043Z,1757009475.043 [DAT](INFO): unknown deviceResponse_: | PreGain | 0 | -89..89
2025-09-04T18:11:15.056Z,1757009475.056 [DAT](INFO): DAT read: ^Rotation | 150.0 | 0 to 359.9 degrees
2025-09-04T18:11:15.056Z,1757009475.056 [DAT](INFO): unknown deviceResponse_: ^Rotation | 150.0 | 0 to 359.9 degrees
2025-09-04T18:11:15.069Z,1757009475.069 [DAT](INFO): DAT read: | ^Orientation | 0 (up) | 0 (up), 1 (down), 2 (fwd)
2025-09-04T18:11:15.069Z,1757009475.069 [DAT](INFO): unknown deviceResponse_: | ^Orientation | 0 (up) | 0 (up), 1 (down), 2 (fwd)
2025-09-04T18:11:15.081Z,1757009475.081 [DAT](INFO): DAT read: | ^PhaseA | -0.068 | radians
2025-09-04T18:11:15.081Z,1757009475.081 [DAT](INFO): unknown deviceResponse_: | ^PhaseA | -0.068 | radians
2025-09-04T18:11:15.106Z,1757009475.106 [DAT](INFO): DAT read: | ^PhaseB | -0.010 | radians
2025-09-04T18:11:15.114Z,1757009475.114 [DAT](INFO): unknown deviceResponse_: | ^PhaseB | -0.010 | radians
2025-09-04T18:11:15.117Z,1757009475.117 [DAT](INFO): DAT read: | ^PhaseC | -0.032 | radians
2025-09-04T18:11:15.117Z,1757009475.117 [DAT](INFO): unknown deviceResponse_: | ^PhaseC | -0.032 | radians
2025-09-04T18:11:15.128Z,1757009475.128 [DAT](INFO): DAT read: | ^PhaseD | 0.000 | radians
2025-09-04T18:11:15.129Z,1757009475.129 [DAT](INFO): unknown deviceResponse_: | ^PhaseD | 0.000 | radians
2025-09-04T18:11:15.139Z,1757009475.139 [DAT](INFO): DAT read: | ^VThresh | 0.700 | 0.000..1.000
2025-09-04T18:11:15.140Z,1757009475.140 [DAT](INFO): unknown deviceResponse_: | ^VThresh | 0.700 | 0.000..1.000
2025-09-04T18:11:15.142Z,1757009475.142 [DAT](INFO): DAT read: | MinElev | -90.0 | degrees
2025-09-04T18:11:15.150Z,1757009475.150 [DAT](INFO): unknown deviceResponse_: | MinElev | -90.0 | degrees
2025-09-04T18:11:15.152Z,1757009475.152 [DAT](INFO): DAT read: | MaxElev | 90.0 | degrees
2025-09-04T18:11:15.153Z,1757009475.153 [DAT](INFO): unknown deviceResponse_: | MaxElev | 90.0 | degrees
2025-09-04T18:11:15.164Z,1757009475.164 [DAT](INFO): DAT read: | PhaseRef | 0 (Active) | 0 (Active), 1 (Passive)
2025-09-04T18:11:15.165Z,1757009475.165 [DAT](INFO): unknown deviceResponse_: | PhaseRef | 0 (Active) | 0 (Active), 1 (Passive)
2025-09-04T18:11:15.175Z,1757009475.175 [DAT](INFO): DAT read: | ^Arrival | 0 | 1 (First), 0 (Peak)
2025-09-04T18:11:15.176Z,1757009475.176 [DAT](INFO): unknown deviceResponse_: | ^Arrival | 0 | 1 (First), 0 (Peak)
2025-09-04T18:11:15.176Z,1757009475.176 [DAT](INFO): DAT read:
2025-09-04T18:11:31.523Z,1757009491.523 [DataOverHttps](INFO): Sending 178 bytes from file Logs/20250904T165942/Express0041.lzma
2025-09-04T18:11:33.207Z,1757009493.207 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-04T18:11:42.565Z,1757009502.565 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Express0041.lzma.bak
2025-09-04T18:11:42.565Z,1757009502.565 [DataOverHttps](IMPORTANT): SBD MOMSN=25929235, MTMSN=20250904T181141
2025-09-04T18:11:50.228Z,1757009510.228 [DataOverHttps](INFO): Received command: ! echo 1 > /dev/loadB1
2025-09-04T18:11:50.312Z,1757009510.312 [CommandExec](IMPORTANT): got command ! echo 1 > /dev/loadB1
2025-09-04T18:12:08.963Z,1757009528.963 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250904T165942/Courier0043.lzma
2025-09-04T18:12:19.908Z,1757009539.908 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Courier0043.lzma.bak
2025-09-04T18:12:19.909Z,1757009539.909 [DataOverHttps](INFO): SBD MOMSN=25929239
2025-09-04T18:12:46.094Z,1757009566.094 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250904T165942/Courier0046.lzma
2025-09-04T18:12:57.136Z,1757009577.136 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Courier0046.lzma.bak
2025-09-04T18:12:57.137Z,1757009577.137 [DataOverHttps](INFO): SBD MOMSN=25929241
2025-09-04T18:13:07.878Z,1757009587.878 [DAT](INFO): DAT read: user:8>esponse Not Received
2025-09-04T18:13:23.531Z,1757009603.531 [DataOverHttps](INFO): Sending 161 bytes from file Logs/20250904T165942/Express0044.lzma
2025-09-04T18:13:34.564Z,1757009614.564 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Express0044.lzma.bak
2025-09-04T18:13:34.565Z,1757009614.565 [DataOverHttps](INFO): SBD MOMSN=25929243
2025-09-04T18:14:01.210Z,1757009641.210 [DataOverHttps](INFO): Sending 161 bytes from file Logs/20250904T165942/Express0047.lzma
2025-09-04T18:14:12.253Z,1757009652.253 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Express0047.lzma.bak
2025-09-04T18:14:12.254Z,1757009652.254 [DataOverHttps](INFO): SBD MOMSN=25929247
2025-09-04T18:14:13.632Z,1757009653.632 [Default:CheckIn:Read_Iridium] Stopped
2025-09-04T18:14:13.632Z,1757009653.632 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-04T18:14:13.632Z,1757009653.632 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-04T18:18:48.738Z,1757009928.738 [DVL_micro](ERROR): only read 0 of 4 data items for beam range. Device response is::RD9,+9999.99,+9999.99,+9999.99
2025-09-04T18:18:48.738Z,1757009928.738 [DVL_micro](ERROR): Failed to parse:
:RD9,+9999.99,+9999.99,+9999.99
2025-09-04T18:19:14.179Z,1757009954.179 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-04T18:19:14.180Z,1757009954.180 [Default:CheckIn:C.Wait] Stopped
2025-09-04T18:19:14.180Z,1757009954.180 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-04T18:19:14.180Z,1757009954.180 [Default:CheckIn:D] Running Loop=1
2025-09-04T18:19:14.595Z,1757009954.595 [Default:CheckIn:D] Stopped
2025-09-04T18:19:14.595Z,1757009954.595 [Default:CheckIn:E] Running Loop=1
2025-09-04T18:19:15.016Z,1757009955.016 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 74.590373 min
2025-09-04T18:19:15.016Z,1757009955.016 [Default:CheckIn:E] Stopped
2025-09-04T18:19:15.016Z,1757009955.016 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-04T18:19:15.016Z,1757009955.016 [Default:CheckIn] Stopped
2025-09-04T18:19:15.016Z,1757009955.016 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-04T18:19:15.016Z,1757009955.016 [Default:CheckIn](INFO): Running loop #13
2025-09-04T18:19:15.016Z,1757009955.016 [Default:CheckIn] Running Loop=13
2025-09-04T18:19:15.017Z,1757009955.017 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-04T18:19:15.017Z,1757009955.017 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-04T18:19:17.000Z,1757009957.000 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181916.00,A,3648.15136,N,12147.22425,W,0.000,41.42,040925,,,D*4D
2025-09-04T18:19:17.003Z,1757009957.003 [NAL9602](INFO): GPS fix at 20250904T181916: (36.802523, -121.787071)
2025-09-04T18:19:17.013Z,1757009957.013 [Default:CheckIn:Read_GPS] Stopped
2025-09-04T18:19:17.014Z,1757009957.014 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-04T18:19:25.102Z,1757009965.102 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250904T165942/Courier0049.lzma
2025-09-04T18:19:36.144Z,1757009976.144 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Courier0049.lzma.bak
2025-09-04T18:19:36.144Z,1757009976.144 [DataOverHttps](INFO): SBD MOMSN=25929320
2025-09-04T18:19:42.045Z,1757009982.045 [NAL9602](INFO): SBD MO Status=0, MOMSN=22902, MT Status=0, MTMSN=0
2025-09-04T18:19:42.046Z,1757009982.046 [NAL9602](INFO): No messages in MT queue
2025-09-04T18:20:02.417Z,1757010002.417 [DataOverHttps](INFO): Sending 166 bytes from file Logs/20250904T165942/Express0050.lzma
2025-09-04T18:20:12.750Z,1757010012.750 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-04T18:20:13.457Z,1757010013.457 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Express0050.lzma.bak
2025-09-04T18:20:13.457Z,1757010013.457 [DataOverHttps](INFO): SBD MOMSN=25929324
2025-09-04T18:20:14.778Z,1757010014.778 [Default:CheckIn:Read_Iridium] Stopped
2025-09-04T18:20:14.778Z,1757010014.778 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-04T18:20:14.778Z,1757010014.778 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-04T18:25:15.358Z,1757010315.358 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-04T18:25:15.358Z,1757010315.358 [Default:CheckIn:C.Wait] Stopped
2025-09-04T18:25:15.358Z,1757010315.358 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-04T18:25:15.358Z,1757010315.358 [Default:CheckIn:D] Running Loop=1
2025-09-04T18:25:15.763Z,1757010315.763 [Default:CheckIn:D] Stopped
2025-09-04T18:25:15.763Z,1757010315.763 [Default:CheckIn:E] Running Loop=1
2025-09-04T18:25:16.172Z,1757010316.172 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 80.609831 min
2025-09-04T18:25:16.172Z,1757010316.172 [Default:CheckIn:E] Stopped
2025-09-04T18:25:16.172Z,1757010316.172 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-04T18:25:16.173Z,1757010316.173 [Default:CheckIn] Stopped
2025-09-04T18:25:16.173Z,1757010316.173 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-04T18:25:16.173Z,1757010316.173 [Default:CheckIn](INFO): Running loop #14
2025-09-04T18:25:16.173Z,1757010316.173 [Default:CheckIn] Running Loop=14
2025-09-04T18:25:16.173Z,1757010316.173 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-04T18:25:16.173Z,1757010316.173 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-04T18:25:18.178Z,1757010318.178 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182517.00,A,3648.15124,N,12147.22451,W,0.039,41.42,040925,,,D*49
2025-09-04T18:25:18.180Z,1757010318.180 [NAL9602](INFO): GPS fix at 20250904T182517: (36.802521, -121.787075)
2025-09-04T18:25:18.213Z,1757010318.213 [Default:CheckIn:Read_GPS] Stopped
2025-09-04T18:25:18.213Z,1757010318.213 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-04T18:25:26.391Z,1757010326.391 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250904T165942/Courier0052.lzma
2025-09-04T18:25:27.465Z,1757010327.465 [NAL9602](INFO): SBD MO Status=0, MOMSN=22903, MT Status=0, MTMSN=0
2025-09-04T18:25:27.465Z,1757010327.465 [NAL9602](INFO): No messages in MT queue
2025-09-04T18:25:37.428Z,1757010337.428 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Courier0052.lzma.bak
2025-09-04T18:25:37.429Z,1757010337.429 [DataOverHttps](INFO): SBD MOMSN=25929333
2025-09-04T18:25:58.167Z,1757010358.167 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-04T18:26:03.747Z,1757010363.747 [DataOverHttps](INFO): Sending 165 bytes from file Logs/20250904T165942/Express0053.lzma
2025-09-04T18:26:14.800Z,1757010374.800 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Express0053.lzma.bak
2025-09-04T18:26:14.801Z,1757010374.801 [DataOverHttps](INFO): SBD MOMSN=25929337
2025-09-04T18:26:15.971Z,1757010375.971 [Default:CheckIn:Read_Iridium] Stopped
2025-09-04T18:26:15.971Z,1757010375.971 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-04T18:26:15.971Z,1757010375.971 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-04T18:27:34.740Z,1757010454.740 [BPC1](ERROR): BPC1B: No match for serial number 1717 in BPC1B's battery stick inventory (sticks 32-62 in onboard configuration file).
2025-09-04T18:29:47.652Z,1757010587.652 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-09-04T18:29:47.653Z,1757010587.653 [BPC1](ERROR): Battery stick #3 (s/n: 0162) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-09-04T18:29:47.653Z,1757010587.653 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-09-04T18:29:47.655Z,1757010587.655 [BPC1](ERROR): Battery stick #60 (s/n: 045D) reported OVER_TEMP_ALARM. Status code: 0x1717.
2025-09-04T18:29:47.655Z,1757010587.655 [BPC1](INFO): Calculating totals. Valid battery stick count: 52. Valid reserve battery stick count: 6.
2025-09-04T18:29:47.658Z,1757010587.658 [BPC1](INFO): Received data from all battery sticks.
2025-09-04T18:31:16.528Z,1757010676.528 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-04T18:31:16.528Z,1757010676.528 [Default:CheckIn:C.Wait] Stopped
2025-09-04T18:31:16.528Z,1757010676.528 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-04T18:31:16.528Z,1757010676.528 [Default:CheckIn:D] Running Loop=1
2025-09-04T18:31:16.941Z,1757010676.941 [Default:CheckIn:D] Stopped
2025-09-04T18:31:16.941Z,1757010676.941 [Default:CheckIn:E] Running Loop=1
2025-09-04T18:31:17.348Z,1757010677.348 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 86.629468 min
2025-09-04T18:31:17.348Z,1757010677.348 [Default:CheckIn:E] Stopped
2025-09-04T18:31:17.348Z,1757010677.348 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-04T18:31:17.348Z,1757010677.348 [Default:CheckIn] Stopped
2025-09-04T18:31:17.348Z,1757010677.348 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-04T18:31:17.348Z,1757010677.348 [Default:CheckIn](INFO): Running loop #15
2025-09-04T18:31:17.348Z,1757010677.348 [Default:CheckIn] Running Loop=15
2025-09-04T18:31:17.349Z,1757010677.349 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-04T18:31:17.349Z,1757010677.349 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-04T18:31:19.349Z,1757010679.349 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183118.00,A,3648.15043,N,12147.22292,W,0.019,41.42,040925,,,D*48
2025-09-04T18:31:19.352Z,1757010679.352 [NAL9602](INFO): GPS fix at 20250904T183118: (36.802507, -121.787049)
2025-09-04T18:31:19.382Z,1757010679.382 [Default:CheckIn:Read_GPS] Stopped
2025-09-04T18:31:19.383Z,1757010679.383 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-04T18:31:27.086Z,1757010687.086 [DataOverHttps](INFO): Sending 89 bytes from file Logs/20250904T165942/Courier0055.lzma
2025-09-04T18:31:38.136Z,1757010698.136 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Courier0055.lzma.bak
2025-09-04T18:31:38.137Z,1757010698.137 [DataOverHttps](INFO): SBD MOMSN=25929351
2025-09-04T18:32:04.343Z,1757010724.343 [DataOverHttps](INFO): Sending 190 bytes from file Logs/20250904T165942/Express0056.lzma
2025-09-04T18:32:15.384Z,1757010735.384 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Express0056.lzma.bak
2025-09-04T18:32:15.385Z,1757010735.385 [DataOverHttps](INFO): SBD MOMSN=25929354
2025-09-04T18:32:16.725Z,1757010736.725 [Default:CheckIn:Read_Iridium] Stopped
2025-09-04T18:32:16.725Z,1757010736.725 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-04T18:32:16.725Z,1757010736.725 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-04T18:32:22.376Z,1757010742.376 [NAL9602](INFO): SBD MO Status=0, MOMSN=22904, MT Status=0, MTMSN=0
2025-09-04T18:32:22.376Z,1757010742.376 [NAL9602](INFO): No messages in MT queue
2025-09-04T18:32:53.100Z,1757010773.100 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-04T18:35:38.084Z,1757010938.084 [DataOverHttps](IMPORTANT): SBD MTMSN=20250904T183537
2025-09-04T18:35:45.618Z,1757010945.618 [DataOverHttps](INFO): Received command: ! echo 0 > /dev/loadB1
2025-09-04T18:35:45.632Z,1757010945.632 [CommandExec](IMPORTANT): got command ! echo 0 > /dev/loadB1
2025-09-04T18:37:17.307Z,1757011037.307 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-04T18:37:17.307Z,1757011037.307 [Default:CheckIn:C.Wait] Stopped
2025-09-04T18:37:17.308Z,1757011037.308 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-04T18:37:17.308Z,1757011037.308 [Default:CheckIn:D] Running Loop=1
2025-09-04T18:37:17.732Z,1757011037.732 [Default:CheckIn:D] Stopped
2025-09-04T18:37:17.732Z,1757011037.732 [Default:CheckIn:E] Running Loop=1
2025-09-04T18:37:18.117Z,1757011038.117 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 92.642651 min
2025-09-04T18:37:18.117Z,1757011038.117 [Default:CheckIn:E] Stopped
2025-09-04T18:37:18.118Z,1757011038.118 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-04T18:37:18.118Z,1757011038.118 [Default:CheckIn] Stopped
2025-09-04T18:37:18.118Z,1757011038.118 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-04T18:37:18.118Z,1757011038.118 [Default:CheckIn](INFO): Running loop #16
2025-09-04T18:37:18.118Z,1757011038.118 [Default:CheckIn] Running Loop=16
2025-09-04T18:37:18.134Z,1757011038.134 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-04T18:37:18.134Z,1757011038.134 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-04T18:37:20.138Z,1757011040.138 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183719.00,A,3648.15193,N,12147.22188,W,0.019,208.81,040925,,,D*7B
2025-09-04T18:37:20.141Z,1757011040.141 [NAL9602](INFO): GPS fix at 20250904T183719: (36.802532, -121.787031)
2025-09-04T18:37:20.167Z,1757011040.167 [Default:CheckIn:Read_GPS] Stopped
2025-09-04T18:37:20.168Z,1757011040.168 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-04T18:37:36.343Z,1757011056.343 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250904T165942/Courier0058.lzma
2025-09-04T18:37:47.384Z,1757011067.384 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Courier0058.lzma.bak
2025-09-04T18:37:47.385Z,1757011067.385 [DataOverHttps](INFO): SBD MOMSN=25929366
2025-09-04T18:38:07.805Z,1757011087.805 [NAL9602](INFO): SBD MO Status=0, MOMSN=22905, MT Status=0, MTMSN=0
2025-09-04T18:38:07.805Z,1757011087.805 [NAL9602](INFO): No messages in MT queue
2025-09-04T18:38:13.503Z,1757011093.503 [DataOverHttps](INFO): Sending 240 bytes from file Logs/20250904T165942/Express0059.lzma
2025-09-04T18:38:24.544Z,1757011104.544 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Express0059.lzma.bak
2025-09-04T18:38:24.545Z,1757011104.545 [DataOverHttps](INFO): SBD MOMSN=25929371
2025-09-04T18:38:25.600Z,1757011105.600 [Default:CheckIn:Read_Iridium] Stopped
2025-09-04T18:38:25.600Z,1757011105.600 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-04T18:38:25.600Z,1757011105.600 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-04T18:38:38.510Z,1757011118.510 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-04T18:41:10.418Z,1757011270.418 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999.99,+9999.99
2025-09-04T18:43:26.174Z,1757011406.174 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-04T18:43:26.174Z,1757011406.174 [Default:CheckIn:C.Wait] Stopped
2025-09-04T18:43:26.174Z,1757011406.174 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-04T18:43:26.174Z,1757011406.174 [Default:CheckIn:D] Running Loop=1
2025-09-04T18:43:26.585Z,1757011406.585 [Default:CheckIn:D] Stopped
2025-09-04T18:43:26.586Z,1757011406.586 [Default:CheckIn:E] Running Loop=1
2025-09-04T18:43:26.978Z,1757011406.978 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 98.790210 min
2025-09-04T18:43:26.978Z,1757011406.978 [Default:CheckIn:E] Stopped
2025-09-04T18:43:26.978Z,1757011406.978 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-04T18:43:26.978Z,1757011406.978 [Default:CheckIn] Stopped
2025-09-04T18:43:26.978Z,1757011406.978 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-04T18:43:26.978Z,1757011406.978 [Default:CheckIn](INFO): Running loop #17
2025-09-04T18:43:26.978Z,1757011406.978 [Default:CheckIn] Running Loop=17
2025-09-04T18:43:26.979Z,1757011406.979 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-04T18:43:26.979Z,1757011406.979 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-04T18:43:28.986Z,1757011408.986 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184328.00,A,3648.15468,N,12147.22075,W,0.097,104.37,040925,,,D*7C
2025-09-04T18:43:28.989Z,1757011408.989 [NAL9602](INFO): GPS fix at 20250904T184328: (36.802578, -121.787013)
2025-09-04T18:43:28.999Z,1757011408.999 [Default:CheckIn:Read_GPS] Stopped
2025-09-04T18:43:28.999Z,1757011408.999 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-04T18:43:37.090Z,1757011417.090 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250904T165942/Courier0063.lzma
2025-09-04T18:43:41.916Z,1757011421.916 [NAL9602](INFO): SBD MO Status=0, MOMSN=22906, MT Status=0, MTMSN=0
2025-09-04T18:43:41.916Z,1757011421.916 [NAL9602](INFO): No messages in MT queue
2025-09-04T18:43:48.132Z,1757011428.132 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Courier0063.lzma.bak
2025-09-04T18:43:48.133Z,1757011428.133 [DataOverHttps](INFO): SBD MOMSN=25929403
2025-09-04T18:44:12.629Z,1757011452.629 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-04T18:44:17.471Z,1757011457.471 [DataOverHttps](INFO): Sending 188 bytes from file Logs/20250904T165942/Express0065.lzma
2025-09-04T18:44:28.516Z,1757011468.516 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Express0065.lzma.bak
2025-09-04T18:44:28.516Z,1757011468.516 [DataOverHttps](INFO): SBD MOMSN=25929409
2025-09-04T18:44:29.610Z,1757011469.610 [Default:CheckIn:Read_Iridium] Stopped
2025-09-04T18:44:29.610Z,1757011469.610 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-04T18:44:29.611Z,1757011469.611 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-04T18:49:30.208Z,1757011770.208 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-04T18:49:30.208Z,1757011770.208 [Default:CheckIn:C.Wait] Stopped
2025-09-04T18:49:30.208Z,1757011770.208 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-04T18:49:30.208Z,1757011770.208 [Default:CheckIn:D] Running Loop=1
2025-09-04T18:49:30.615Z,1757011770.615 [Default:CheckIn:D] Stopped
2025-09-04T18:49:30.615Z,1757011770.615 [Default:CheckIn:E] Running Loop=1
2025-09-04T18:49:31.004Z,1757011771.004 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 104.857365 min
2025-09-04T18:49:31.004Z,1757011771.004 [Default:CheckIn:E] Stopped
2025-09-04T18:49:31.005Z,1757011771.005 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-04T18:49:31.005Z,1757011771.005 [Default:CheckIn] Stopped
2025-09-04T18:49:31.005Z,1757011771.005 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-04T18:49:31.005Z,1757011771.005 [Default:CheckIn](INFO): Running loop #18
2025-09-04T18:49:31.005Z,1757011771.005 [Default:CheckIn] Running Loop=18
2025-09-04T18:49:31.005Z,1757011771.005 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-04T18:49:31.005Z,1757011771.005 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-04T18:49:33.016Z,1757011773.016 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184932.00,A,3648.15399,N,12147.22056,W,0.000,171.04,040925,,,A*7C
2025-09-04T18:49:33.019Z,1757011773.019 [NAL9602](INFO): GPS fix at 20250904T184932: (36.802566, -121.787009)
2025-09-04T18:49:33.029Z,1757011773.029 [Default:CheckIn:Read_GPS] Stopped
2025-09-04T18:49:33.029Z,1757011773.029 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-04T18:49:40.858Z,1757011780.858 [DataOverHttps](INFO): Sending 75 bytes from file Logs/20250904T165942/Courier0067.lzma
2025-09-04T18:49:51.900Z,1757011791.900 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Courier0067.lzma.bak
2025-09-04T18:49:51.901Z,1757011791.901 [DataOverHttps](INFO): SBD MOMSN=25929460
2025-09-04T18:50:17.979Z,1757011817.979 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20250904T165942/Express0068.lzma
2025-09-04T18:50:29.020Z,1757011829.020 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Express0068.lzma.bak
2025-09-04T18:50:29.021Z,1757011829.021 [DataOverHttps](INFO): SBD MOMSN=25929463
2025-09-04T18:50:30.422Z,1757011830.422 [Default:CheckIn:Read_Iridium] Stopped
2025-09-04T18:50:30.422Z,1757011830.422 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-04T18:50:30.422Z,1757011830.422 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-04T18:51:32.203Z,1757011892.203 [NAL9602](INFO): SBD MO Status=0, MOMSN=22907, MT Status=0, MTMSN=0
2025-09-04T18:51:32.203Z,1757011892.203 [NAL9602](INFO): No messages in MT queue
2025-09-04T18:52:02.906Z,1757011922.906 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-04T18:55:30.990Z,1757012130.990 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-04T18:55:30.990Z,1757012130.990 [Default:CheckIn:C.Wait] Stopped
2025-09-04T18:55:30.990Z,1757012130.990 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-04T18:55:30.990Z,1757012130.990 [Default:CheckIn:D] Running Loop=1
2025-09-04T18:55:31.380Z,1757012131.380 [Default:CheckIn:D] Stopped
2025-09-04T18:55:31.380Z,1757012131.380 [Default:CheckIn:E] Running Loop=1
2025-09-04T18:55:31.782Z,1757012131.782 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 110.870125 min
2025-09-04T18:55:31.782Z,1757012131.782 [Default:CheckIn:E] Stopped
2025-09-04T18:55:31.783Z,1757012131.783 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-04T18:55:31.783Z,1757012131.783 [Default:CheckIn] Stopped
2025-09-04T18:55:31.783Z,1757012131.783 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-04T18:55:31.783Z,1757012131.783 [Default:CheckIn](INFO): Running loop #19
2025-09-04T18:55:31.783Z,1757012131.783 [Default:CheckIn] Running Loop=19
2025-09-04T18:55:31.783Z,1757012131.783 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-04T18:55:31.783Z,1757012131.783 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-04T18:55:33.795Z,1757012133.795 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185533.00,A,3648.15322,N,12147.21773,W,0.039,171.04,040925,,,A*79
2025-09-04T18:55:33.797Z,1757012133.797 [NAL9602](INFO): GPS fix at 20250904T185533: (36.802554, -121.786962)
2025-09-04T18:55:33.808Z,1757012133.808 [Default:CheckIn:Read_GPS] Stopped
2025-09-04T18:55:33.808Z,1757012133.808 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-04T18:55:40.858Z,1757012140.858 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250904T165942/Courier0070.lzma
2025-09-04T18:55:51.909Z,1757012151.909 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Courier0070.lzma.bak
2025-09-04T18:55:51.910Z,1757012151.910 [DataOverHttps](INFO): SBD MOMSN=25929522
2025-09-04T18:55:57.252Z,1757012157.252 [NAL9602](INFO): SBD MO Status=2, MOMSN=22908, MT Status=2, MTMSN=0
2025-09-04T18:55:57.252Z,1757012157.252 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-04T18:56:18.267Z,1757012178.267 [DataOverHttps](INFO): Sending 219 bytes from file Logs/20250904T165942/Express0071.lzma
2025-09-04T18:56:21.464Z,1757012181.464 [NAL9602](INFO): SBD MO Status=2, MOMSN=22908, MT Status=2, MTMSN=0
2025-09-04T18:56:21.464Z,1757012181.464 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-04T18:56:29.311Z,1757012189.311 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Express0071.lzma.bak
2025-09-04T18:56:29.311Z,1757012189.311 [DataOverHttps](INFO): SBD MOMSN=25929525
2025-09-04T18:56:30.369Z,1757012190.369 [Default:CheckIn:Read_Iridium] Stopped
2025-09-04T18:56:30.369Z,1757012190.369 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-04T18:56:30.369Z,1757012190.369 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-04T18:58:13.793Z,1757012293.793 [NAL9602](INFO): SBD MO Status=2, MOMSN=22908, MT Status=2, MTMSN=0
2025-09-04T18:58:13.793Z,1757012293.793 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-09-04T18:58:23.493Z,1757012303.493 [NAL9602](INFO): SBD MO Status=0, MOMSN=22908, MT Status=0, MTMSN=0
2025-09-04T18:58:23.493Z,1757012303.493 [NAL9602](INFO): No messages in MT queue
2025-09-04T18:58:54.196Z,1757012334.196 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-04T19:01:30.956Z,1757012490.956 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-04T19:01:30.957Z,1757012490.957 [Default:CheckIn:C.Wait] Stopped
2025-09-04T19:01:30.957Z,1757012490.957 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-04T19:01:30.957Z,1757012490.957 [Default:CheckIn:D] Running Loop=1
2025-09-04T19:01:31.370Z,1757012491.370 [Default:CheckIn:D] Stopped
2025-09-04T19:01:31.371Z,1757012491.371 [Default:CheckIn:E] Running Loop=1
2025-09-04T19:01:31.784Z,1757012491.784 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 116.869954 min
2025-09-04T19:01:31.784Z,1757012491.784 [Default:CheckIn:E] Stopped
2025-09-04T19:01:31.784Z,1757012491.784 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-04T19:01:31.784Z,1757012491.784 [Default:CheckIn] Stopped
2025-09-04T19:01:31.784Z,1757012491.784 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-04T19:01:31.784Z,1757012491.784 [Default:CheckIn](INFO): Running loop #20
2025-09-04T19:01:31.784Z,1757012491.784 [Default:CheckIn] Running Loop=20
2025-09-04T19:01:31.785Z,1757012491.785 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-04T19:01:31.785Z,1757012491.785 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-04T19:01:33.772Z,1757012493.772 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190133.00,A,3648.36223,N,12147.11528,W,4.568,12.68,040925,,,D*49
2025-09-04T19:01:33.775Z,1757012493.775 [NAL9602](INFO): GPS fix at 20250904T190133: (36.806037, -121.785255)
2025-09-04T19:01:33.785Z,1757012493.785 [Default:CheckIn:Read_GPS] Stopped
2025-09-04T19:01:33.785Z,1757012493.785 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-04T19:01:41.510Z,1757012501.510 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20250904T165942/Courier0073.lzma
2025-09-04T19:01:45.898Z,1757012505.898 [NAL9602](INFO): SBD MO Status=0, MOMSN=22909, MT Status=0, MTMSN=0
2025-09-04T19:01:45.898Z,1757012505.898 [NAL9602](INFO): No messages in MT queue
2025-09-04T19:01:52.554Z,1757012512.554 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Courier0073.lzma.bak
2025-09-04T19:01:52.554Z,1757012512.554 [DataOverHttps](INFO): SBD MOMSN=25929529
2025-09-04T19:02:16.617Z,1757012536.617 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-04T19:02:18.747Z,1757012538.747 [DataOverHttps](INFO): Sending 188 bytes from file Logs/20250904T165942/Express0074.lzma
2025-09-04T19:02:29.788Z,1757012549.788 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Express0074.lzma.bak
2025-09-04T19:02:29.789Z,1757012549.789 [DataOverHttps](INFO): SBD MOMSN=25929534
2025-09-04T19:02:31.157Z,1757012551.157 [Default:CheckIn:Read_Iridium] Stopped
2025-09-04T19:02:31.157Z,1757012551.157 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-04T19:02:31.157Z,1757012551.157 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-04T19:07:31.740Z,1757012851.740 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-04T19:07:31.740Z,1757012851.740 [Default:CheckIn:C.Wait] Stopped
2025-09-04T19:07:31.740Z,1757012851.740 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-04T19:07:31.740Z,1757012851.740 [Default:CheckIn:D] Running Loop=1
2025-09-04T19:07:32.154Z,1757012852.154 [Default:CheckIn:D] Stopped
2025-09-04T19:07:32.154Z,1757012852.154 [Default:CheckIn:E] Running Loop=1
2025-09-04T19:07:32.545Z,1757012852.545 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 122.883024 min
2025-09-04T19:07:32.545Z,1757012852.545 [Default:CheckIn:E] Stopped
2025-09-04T19:07:32.545Z,1757012852.545 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-04T19:07:32.545Z,1757012852.545 [Default:CheckIn] Stopped
2025-09-04T19:07:32.545Z,1757012852.545 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-04T19:07:32.546Z,1757012852.546 [Default:CheckIn](INFO): Running loop #21
2025-09-04T19:07:32.546Z,1757012852.546 [Default:CheckIn] Running Loop=21
2025-09-04T19:07:32.546Z,1757012852.546 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-04T19:07:32.546Z,1757012852.546 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-04T19:07:34.557Z,1757012854.557 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190733.00,A,3648.30726,N,12147.67514,W,11.177,250.42,040925,,,D*45
2025-09-04T19:07:34.559Z,1757012854.559 [NAL9602](INFO): GPS fix at 20250904T190733: (36.805121, -121.794586)
2025-09-04T19:07:34.569Z,1757012854.569 [Default:CheckIn:Read_GPS] Stopped
2025-09-04T19:07:34.569Z,1757012854.569 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-04T19:07:41.822Z,1757012861.822 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250904T165942/Courier0076.lzma
2025-09-04T19:07:49.908Z,1757012869.908 [NAL9602](INFO): SBD MO Status=0, MOMSN=22910, MT Status=0, MTMSN=0
2025-09-04T19:07:49.908Z,1757012869.908 [NAL9602](INFO): No messages in MT queue
2025-09-04T19:07:52.864Z,1757012872.864 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Courier0076.lzma.bak
2025-09-04T19:07:52.865Z,1757012872.865 [DataOverHttps](INFO): SBD MOMSN=25929548
2025-09-04T19:08:19.015Z,1757012899.015 [DataOverHttps](INFO): Sending 195 bytes from file Logs/20250904T165942/Express0077.lzma
2025-09-04T19:08:20.611Z,1757012900.611 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-04T19:08:30.060Z,1757012910.060 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Express0077.lzma.bak
2025-09-04T19:08:30.061Z,1757012910.061 [DataOverHttps](INFO): SBD MOMSN=25929552
2025-09-04T19:08:31.130Z,1757012911.130 [Default:CheckIn:Read_Iridium] Stopped
2025-09-04T19:08:31.130Z,1757012911.130 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-04T19:08:31.151Z,1757012911.151 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-04T19:13:31.713Z,1757013211.713 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-04T19:13:31.713Z,1757013211.713 [Default:CheckIn:C.Wait] Stopped
2025-09-04T19:13:31.713Z,1757013211.713 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-04T19:13:31.713Z,1757013211.713 [Default:CheckIn:D] Running Loop=1
2025-09-04T19:13:32.105Z,1757013212.105 [Default:CheckIn:D] Stopped
2025-09-04T19:13:32.105Z,1757013212.105 [Default:CheckIn:E] Running Loop=1
2025-09-04T19:13:32.509Z,1757013212.509 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 128.882202 min
2025-09-04T19:13:32.509Z,1757013212.509 [Default:CheckIn:E] Stopped
2025-09-04T19:13:32.509Z,1757013212.509 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-04T19:13:32.509Z,1757013212.509 [Default:CheckIn] Stopped
2025-09-04T19:13:32.509Z,1757013212.509 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-04T19:13:32.510Z,1757013212.510 [Default:CheckIn](INFO): Running loop #22
2025-09-04T19:13:32.510Z,1757013212.510 [Default:CheckIn] Running Loop=22
2025-09-04T19:13:32.510Z,1757013212.510 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-04T19:13:32.510Z,1757013212.510 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-04T19:13:34.528Z,1757013214.528 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191333.00,A,3647.94205,N,12150.03407,W,22.918,258.84,040925,,,A*44
2025-09-04T19:13:34.531Z,1757013214.531 [NAL9602](INFO): GPS fix at 20250904T191333: (36.799034, -121.833901)
2025-09-04T19:13:34.541Z,1757013214.541 [Default:CheckIn:Read_GPS] Stopped
2025-09-04T19:13:34.541Z,1757013214.541 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-04T19:13:42.142Z,1757013222.142 [DataOverHttps](INFO): Sending 78 bytes from file Logs/20250904T165942/Courier0079.lzma
2025-09-04T19:13:56.197Z,1757013236.197 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Courier0079.lzma.bak
2025-09-04T19:13:56.197Z,1757013236.197 [DataOverHttps](INFO): SBD MOMSN=25929565
2025-09-04T19:13:59.168Z,1757013239.168 [NAL9602](INFO): SBD MO Status=0, MOMSN=22911, MT Status=0, MTMSN=0
2025-09-04T19:13:59.168Z,1757013239.168 [NAL9602](INFO): No messages in MT queue
2025-09-04T19:14:22.507Z,1757013262.507 [DataOverHttps](INFO): Sending 189 bytes from file Logs/20250904T165942/Express0080.lzma
2025-09-04T19:14:29.871Z,1757013269.871 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-04T19:14:33.549Z,1757013273.549 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Express0080.lzma.bak
2025-09-04T19:14:33.549Z,1757013273.549 [DataOverHttps](INFO): SBD MOMSN=25929568
2025-09-04T19:14:34.725Z,1757013274.725 [Default:CheckIn:Read_Iridium] Stopped
2025-09-04T19:14:34.725Z,1757013274.725 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-04T19:14:34.725Z,1757013274.725 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-04T19:19:35.313Z,1757013575.313 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-09-04T19:19:35.313Z,1757013575.313 [Default:CheckIn:C.Wait] Stopped
2025-09-04T19:19:35.314Z,1757013575.314 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-09-04T19:19:35.314Z,1757013575.314 [Default:CheckIn:D] Running Loop=1
2025-09-04T19:19:35.707Z,1757013575.707 [Default:CheckIn:D] Stopped
2025-09-04T19:19:35.707Z,1757013575.707 [Default:CheckIn:E] Running Loop=1
2025-09-04T19:19:36.119Z,1757013576.119 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 134.942236 min
2025-09-04T19:19:36.119Z,1757013576.119 [Default:CheckIn:E] Stopped
2025-09-04T19:19:36.120Z,1757013576.120 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-09-04T19:19:36.120Z,1757013576.120 [Default:CheckIn] Stopped
2025-09-04T19:19:36.120Z,1757013576.120 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-09-04T19:19:36.120Z,1757013576.120 [Default:CheckIn](INFO): Running loop #23
2025-09-04T19:19:36.120Z,1757013576.120 [Default:CheckIn] Running Loop=23
2025-09-04T19:19:36.120Z,1757013576.120 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-09-04T19:19:36.120Z,1757013576.120 [Default:CheckIn:Read_GPS] Running Loop=1
2025-09-04T19:19:38.125Z,1757013578.125 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191937.00,A,3647.87344,N,12150.77219,W,0.525,20.05,040925,,,D*45
2025-09-04T19:19:38.127Z,1757013578.127 [NAL9602](INFO): GPS fix at 20250904T191937: (36.797891, -121.846203)
2025-09-04T19:19:38.162Z,1757013578.162 [Default:CheckIn:Read_GPS] Stopped
2025-09-04T19:19:38.162Z,1757013578.162 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-09-04T19:19:47.355Z,1757013587.355 [DataOverHttps](INFO): Sending 76 bytes from file Logs/20250904T165942/Courier0082.lzma
2025-09-04T19:19:53.473Z,1757013593.473 [NAL9602](INFO): SBD MO Status=0, MOMSN=22912, MT Status=0, MTMSN=0
2025-09-04T19:19:53.473Z,1757013593.473 [NAL9602](INFO): No messages in MT queue
2025-09-04T19:19:58.399Z,1757013598.399 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Courier0082.lzma.bak
2025-09-04T19:19:58.399Z,1757013598.399 [DataOverHttps](INFO): SBD MOMSN=25929574
2025-09-04T19:20:24.174Z,1757013624.174 [NAL9602](INFO): Not Powering down - fast GPS
2025-09-04T19:20:24.691Z,1757013624.691 [DataOverHttps](INFO): Sending 226 bytes from file Logs/20250904T165942/Express0083.lzma
2025-09-04T19:20:35.732Z,1757013635.732 [DataOverHttps](INFO): Moved sent file to Logs/20250904T165942/Express0083.lzma.bak
2025-09-04T19:20:35.733Z,1757013635.733 [DataOverHttps](INFO): SBD MOMSN=25929578
2025-09-04T19:20:37.125Z,1757013637.125 [Default:CheckIn:Read_Iridium] Stopped
2025-09-04T19:20:37.125Z,1757013637.125 [Default:CheckIn:C.Wait] Running Loop=1
2025-09-04T19:20:37.125Z,1757013637.125 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-09-04T19:20:54.528Z,1757013654.528 [DataOverHttps](IMPORTANT): SBD MTMSN=20250904T192053
2025-09-04T19:21:03.095Z,1757013663.095 [DataOverHttps](INFO): Received command: restart logs