2025-07-29T17:41:40.170Z,1753810900.170 [Supervisor](DEBUG): Initializing supervisor.
2025-07-29T17:41:40.174Z,1753810900.174 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-07-29T17:41:40.175Z,1753810900.175 [SyncHandler](INFO): Protected caller Thread ID is 836
2025-07-29T17:41:40.175Z,1753810900.175 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-07-29T17:41:40.176Z,1753810900.176 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-07-29T17:41:40.177Z,1753810900.177 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 837
2025-07-29T17:41:40.181Z,1753810900.181 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-07-29T17:41:40.201Z,1753810900.201 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-07-29T17:41:40.202Z,1753810900.202 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-07-29T17:41:40.202Z,1753810900.202 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 838
2025-07-29T17:41:40.207Z,1753810900.207 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-07-29T17:41:40.208Z,1753810900.208 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-07-29T17:41:40.208Z,1753810900.208 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 839
2025-07-29T17:41:40.210Z,1753810900.210 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-07-29T17:41:40.211Z,1753810900.211 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-07-29T17:41:40.212Z,1753810900.212 [logger ThreadHandler](INFO): Protected caller Thread ID is 840
2025-07-29T17:41:40.216Z,1753810900.216 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-07-29T17:41:40.216Z,1753810900.216 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-07-29T17:41:40.220Z,1753810900.220 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-07-29T17:41:40.720Z,1753810900.720 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-07-29T17:41:40.722Z,1753810900.722 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-07-29T17:41:40.945Z,1753810900.945 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-07-29T17:41:40.947Z,1753810900.947 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-07-29T17:41:41.047Z,1753810901.047 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance
2025-07-29T17:41:41.049Z,1753810901.049 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-07-29T17:41:41.296Z,1753810901.296 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-07-29T17:41:41.297Z,1753810901.297 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-07-29T17:41:41.388Z,1753810901.388 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-07-29T17:41:41.494Z,1753810901.494 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-07-29T17:41:41.495Z,1753810901.495 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-07-29T17:41:41.882Z,1753810901.882 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-07-29T17:41:41.883Z,1753810901.883 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-07-29T17:41:42.041Z,1753810902.041 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-07-29T17:41:42.042Z,1753810902.042 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-07-29T17:41:42.595Z,1753810902.595 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-07-29T17:41:42.597Z,1753810902.597 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-07-29T17:41:42.789Z,1753810902.789 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-07-29T17:41:42.790Z,1753810902.790 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-07-29T17:41:43.224Z,1753810903.224 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-07-29T17:41:43.225Z,1753810903.225 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-07-29T17:41:43.584Z,1753810903.584 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-07-29T17:41:43.585Z,1753810903.585 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-07-29T17:41:44.517Z,1753810904.517 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-07-29T17:41:44.518Z,1753810904.518 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-07-29T17:41:45.483Z,1753810905.483 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-07-29T17:41:45.485Z,1753810905.485 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-07-29T17:41:45.862Z,1753810905.862 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-07-29T17:41:45.863Z,1753810905.863 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-07-29T17:41:46.122Z,1753810906.122 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-07-29T17:41:46.123Z,1753810906.123 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2025-07-29T17:41:46.127Z,1753810906.127 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2025-07-29T17:41:46.257Z,1753810906.257 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2025-07-29T17:41:46.352Z,1753810906.352 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2025-07-29T17:41:46.499Z,1753810906.499 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2025-07-29T17:41:46.594Z,1753810906.594 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2025-07-29T17:41:46.876Z,1753810906.876 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-07-29T17:41:46.877Z,1753810906.877 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2025-07-29T17:41:46.993Z,1753810906.993 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2025-07-29T17:41:47.109Z,1753810907.109 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2025-07-29T17:41:47.219Z,1753810907.219 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2025-07-29T17:41:47.383Z,1753810907.383 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2025-07-29T17:41:47.491Z,1753810907.491 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2025-07-29T17:41:47.587Z,1753810907.587 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-07-29T17:41:47.599Z,1753810907.599 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-07-29T17:41:47.616Z,1753810907.616 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-07-29T17:41:47.617Z,1753810907.617 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-07-29T17:41:47.791Z,1753810907.791 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-07-29T17:41:47.791Z,1753810907.791 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-07-29T17:41:47.869Z,1753810907.869 [VerticalControl](DEBUG): Construct VerticalControl.
2025-07-29T17:41:47.933Z,1753810907.933 [VerticalControl] Loaded
2025-07-29T17:41:47.933Z,1753810907.933 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-07-29T17:41:47.936Z,1753810907.936 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-07-29T17:41:47.985Z,1753810907.985 [HorizontalControl] Loaded
2025-07-29T17:41:47.986Z,1753810907.986 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-07-29T17:41:47.988Z,1753810907.988 [SpeedControl](DEBUG): Construct SpeedControl.
2025-07-29T17:41:47.991Z,1753810907.991 [SpeedControl] Loaded
2025-07-29T17:41:47.992Z,1753810907.992 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-07-29T17:41:47.994Z,1753810907.994 [LoopControl](DEBUG): Construct LoopControl.
2025-07-29T17:41:47.995Z,1753810907.995 [LoopControl] Loaded
2025-07-29T17:41:47.995Z,1753810907.995 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-07-29T17:41:47.996Z,1753810907.996 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-07-29T17:41:47.997Z,1753810907.997 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-07-29T17:41:48.193Z,1753810908.193 [BuoyancyServo] Loaded
2025-07-29T17:41:48.193Z,1753810908.193 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-07-29T17:41:48.216Z,1753810908.216 [ElevatorServo] Loaded
2025-07-29T17:41:48.217Z,1753810908.217 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-07-29T17:41:48.239Z,1753810908.239 [MassServo] Loaded
2025-07-29T17:41:48.239Z,1753810908.239 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-07-29T17:41:48.260Z,1753810908.260 [RudderServo] Loaded
2025-07-29T17:41:48.260Z,1753810908.260 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-07-29T17:41:48.277Z,1753810908.277 [ThrusterHE] Loaded
2025-07-29T17:41:48.278Z,1753810908.278 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-07-29T17:41:48.278Z,1753810908.278 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-07-29T17:41:48.279Z,1753810908.279 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-07-29T17:41:48.352Z,1753810908.352 [DepthRateCalculator] Loaded
2025-07-29T17:41:48.352Z,1753810908.352 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-07-29T17:41:48.357Z,1753810908.357 [PitchRateCalculator] Loaded
2025-07-29T17:41:48.357Z,1753810908.357 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-07-29T17:41:48.367Z,1753810908.367 [SpeedCalculator] Loaded
2025-07-29T17:41:48.368Z,1753810908.368 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-07-29T17:41:48.372Z,1753810908.372 [YawRateCalculator] Loaded
2025-07-29T17:41:48.372Z,1753810908.372 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-07-29T17:41:48.392Z,1753810908.392 [ElevatorOffsetCalculator] Loaded
2025-07-29T17:41:48.393Z,1753810908.393 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-07-29T17:41:48.393Z,1753810908.393 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-07-29T17:41:48.394Z,1753810908.394 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-07-29T17:41:48.542Z,1753810908.542 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-07-29T17:41:48.542Z,1753810908.542 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-07-29T17:41:48.557Z,1753810908.557 [NavChart] Loaded
2025-07-29T17:41:48.557Z,1753810908.557 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-07-29T17:41:48.563Z,1753810908.563 [UniversalFixResidualReporter] Loaded
2025-07-29T17:41:48.563Z,1753810908.563 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-07-29T17:41:48.563Z,1753810908.563 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-07-29T17:41:48.564Z,1753810908.564 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-07-29T17:41:50.164Z,1753810910.164 [AHRS_M2] Loaded
2025-07-29T17:41:50.164Z,1753810910.164 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-07-29T17:41:50.419Z,1753810910.419 [BackseatComponent] Loaded
2025-07-29T17:41:50.419Z,1753810910.419 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-07-29T17:41:50.420Z,1753810910.420 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409294E0
2025-07-29T17:41:50.421Z,1753810910.421 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 922
2025-07-29T17:41:50.424Z,1753810910.424 [LcmUniversalReporter] Loaded
2025-07-29T17:41:50.424Z,1753810910.424 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-07-29T17:41:50.430Z,1753810910.430 [BioacousticsDataBridge] Loaded
2025-07-29T17:41:50.431Z,1753810910.431 [ComponentRegistry](DEBUG): SyncComponent "BioacousticsDataBridge" handled in the control thread.
2025-07-29T17:41:51.305Z,1753810911.305 [BPC1] Loaded
2025-07-29T17:41:51.305Z,1753810911.305 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-07-29T17:41:51.408Z,1753810911.408 [DAT] Loaded
2025-07-29T17:41:51.408Z,1753810911.408 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-07-29T17:41:51.410Z,1753810911.410 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 409594E0
2025-07-29T17:41:51.410Z,1753810911.410 [DAT ThreadHandler](INFO): Protected caller Thread ID is 923
2025-07-29T17:41:51.553Z,1753810911.553 [DataOverHttps] Loaded
2025-07-29T17:41:51.553Z,1753810911.553 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-07-29T17:41:51.554Z,1753810911.554 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409894E0
2025-07-29T17:41:51.554Z,1753810911.554 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 924
2025-07-29T17:41:51.579Z,1753810911.579 [Depth_Keller] Loaded
2025-07-29T17:41:51.579Z,1753810911.579 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-07-29T17:41:51.584Z,1753810911.584 [DropWeight] Loaded
2025-07-29T17:41:51.585Z,1753810911.585 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-07-29T17:41:51.651Z,1753810911.651 [NAL9602] Loaded
2025-07-29T17:41:51.651Z,1753810911.651 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-07-29T17:41:51.682Z,1753810911.682 [Onboard] Loaded
2025-07-29T17:41:51.683Z,1753810911.683 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-07-29T17:41:51.684Z,1753810911.684 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409B94E0
2025-07-29T17:41:51.684Z,1753810911.684 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 925
2025-07-29T17:41:51.699Z,1753810911.699 [Power24vConverter] Loaded
2025-07-29T17:41:51.700Z,1753810911.700 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-07-29T17:41:51.718Z,1753810911.718 [Radio_Surface] Loaded
2025-07-29T17:41:51.718Z,1753810911.718 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-07-29T17:41:51.719Z,1753810911.719 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409E94E0
2025-07-29T17:41:51.719Z,1753810911.719 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 926
2025-07-29T17:41:51.734Z,1753810911.734 [Sonardyne_Nano] Loaded
2025-07-29T17:41:51.734Z,1753810911.734 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-07-29T17:41:51.735Z,1753810911.735 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-07-29T17:41:51.735Z,1753810911.735 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-07-29T17:41:51.825Z,1753810911.825 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-07-29T17:41:51.826Z,1753810911.826 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-07-29T17:41:52.055Z,1753810912.055 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-07-29T17:41:52.056Z,1753810912.056 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-07-29T17:41:52.107Z,1753810912.107 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-07-29T17:41:52.107Z,1753810912.107 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-07-29T17:41:52.341Z,1753810912.341 [CTD_Seabird] Loaded
2025-07-29T17:41:52.341Z,1753810912.341 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-07-29T17:41:52.342Z,1753810912.342 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B314E0
2025-07-29T17:41:52.342Z,1753810912.342 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 927
2025-07-29T17:41:52.365Z,1753810912.365 [PAR_Licor] Loaded
2025-07-29T17:41:52.366Z,1753810912.366 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-07-29T17:41:52.413Z,1753810912.413 [WetLabsBB2FL] Loaded
2025-07-29T17:41:52.413Z,1753810912.413 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-07-29T17:41:52.414Z,1753810912.414 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B614E0
2025-07-29T17:41:52.415Z,1753810912.415 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 928
2025-07-29T17:41:52.415Z,1753810912.415 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-07-29T17:41:52.416Z,1753810912.416 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-07-29T17:41:52.844Z,1753810912.844 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-07-29T17:41:52.845Z,1753810912.845 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-07-29T17:41:53.057Z,1753810913.057 [SBIT](DEBUG): Construct Startup Built In Test.
2025-07-29T17:41:53.066Z,1753810913.066 [SBIT] Loaded
2025-07-29T17:41:53.067Z,1753810913.067 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-07-29T17:41:53.070Z,1753810913.070 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-07-29T17:41:53.083Z,1753810913.083 [IBIT] Loaded
2025-07-29T17:41:53.083Z,1753810913.083 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-07-29T17:41:53.089Z,1753810913.089 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-07-29T17:41:53.173Z,1753810913.173 [CBIT] Loaded
2025-07-29T17:41:53.173Z,1753810913.173 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-07-29T17:41:53.207Z,1753810913.207 [GFScanner] Loaded
2025-07-29T17:41:53.207Z,1753810913.207 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-07-29T17:41:53.208Z,1753810913.208 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-07-29T17:41:53.214Z,1753810913.214 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-07-29T17:41:53.217Z,1753810913.217 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-07-29T17:41:53.229Z,1753810913.229 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-07-29T17:41:53.230Z,1753810913.230 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C6A4E0
2025-07-29T17:41:53.230Z,1753810913.230 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 929
2025-07-29T17:41:53.235Z,1753810913.235 [Supervisor](INFO): Main Thread ID is 829
2025-07-29T17:41:53.235Z,1753810913.235 [Supervisor](DEBUG): Running supervisor.
2025-07-29T17:41:53.235Z,1753810913.235 [CommandExec ThreadHandler](INFO): Handler Thread ID is 930
2025-07-29T17:41:53.236Z,1753810913.236 [CommandExec](INFO): Initializing the command executive.
2025-07-29T17:41:53.238Z,1753810913.238 [CommandLine ThreadHandler](INFO): Handler Thread ID is 931
2025-07-29T17:41:53.240Z,1753810913.240 [controlThread ThreadHandler](INFO): Handler Thread ID is 932
2025-07-29T17:41:53.240Z,1753810913.240 [controlThread](DEBUG): Initializing ControlThread
2025-07-29T17:41:53.241Z,1753810913.241 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-07-29T17:41:53.243Z,1753810913.243 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-07-29T17:41:53.243Z,1753810913.243 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-07-29T17:41:53.244Z,1753810913.244 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-07-29T17:41:53.245Z,1753810913.245 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-07-29T17:41:53.245Z,1753810913.245 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-07-29T17:41:53.246Z,1753810913.246 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-07-29T17:41:53.246Z,1753810913.246 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-07-29T17:41:53.246Z,1753810913.246 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-07-29T17:41:53.247Z,1753810913.247 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-07-29T17:41:53.248Z,1753810913.248 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-07-29T17:41:53.253Z,1753810913.253 [SBIT](INFO): Initialize SBIT Component.
2025-07-29T17:41:53.253Z,1753810913.253 [SBIT](IMPORTANT): git: 2025-07-23
2025-07-29T17:41:53.254Z,1753810913.254 [SBIT](INFO): git hash: f703b814b3cae1a9e037dae533fc7fa1c9563a61
2025-07-29T17:41:53.254Z,1753810913.254 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-07-29T17:41:53.255Z,1753810913.255 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2025-07-29T17:41:53.256Z,1753810913.256 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2025-07-29T17:41:53.257Z,1753810913.257 [IBIT](INFO): Initialize IBIT Component.
2025-07-29T17:41:53.258Z,1753810913.258 [CBIT](DEBUG): Initialize CBIT Component.
2025-07-29T17:41:53.258Z,1753810913.258 [logger ThreadHandler](INFO): Handler Thread ID is 933
2025-07-29T17:41:53.269Z,1753810913.269 [CBIT](DEBUG): Initialized mux pins.
2025-07-29T17:41:53.269Z,1753810913.269 [CBIT](DEBUG): Initializing the watchdog timer.
2025-07-29T17:41:53.277Z,1753810913.277 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 934
2025-07-29T17:41:53.289Z,1753810913.289 [DAT ThreadHandler](INFO): Handler Thread ID is 935
2025-07-29T17:41:53.290Z,1753810913.290 [DAT](INFO): Powering up
2025-07-29T17:41:53.290Z,1753810913.290 [DAT](DEBUG): Initializing DAT.
2025-07-29T17:41:53.293Z,1753810913.293 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-07-29T17:41:53.293Z,1753810913.293 [CBIT](DEBUG): Initializing heartbeat.
2025-07-29T17:41:53.295Z,1753810913.295 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 937
2025-07-29T17:41:53.297Z,1753810913.297 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-07-29T17:41:53.305Z,1753810913.305 [Onboard ThreadHandler](INFO): Handler Thread ID is 938
2025-07-29T17:41:53.333Z,1753810913.333 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 939
2025-07-29T17:41:53.349Z,1753810913.349 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 940
2025-07-29T17:41:53.350Z,1753810913.350 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-07-29T17:41:53.354Z,1753810913.354 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 942
2025-07-29T17:41:53.357Z,1753810913.357 [WetLabsBB2FL](INFO): Powering up
2025-07-29T17:41:53.358Z,1753810913.358 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 944
2025-07-29T17:41:53.365Z,1753810913.365 [CBIT](DEBUG): Deactivating emergency mode.
2025-07-29T17:41:53.367Z,1753810913.367 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2MI79M.000
2025-07-29T17:41:53.367Z,1753810913.367 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2MI79M.000
2025-07-29T17:41:53.367Z,1753810913.367 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QL.000
2025-07-29T17:41:53.367Z,1753810913.367 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QL.000
2025-07-29T17:41:53.368Z,1753810913.368 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QM.000
2025-07-29T17:41:53.368Z,1753810913.368 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QM.000
2025-07-29T17:41:53.368Z,1753810913.368 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2PM.000
2025-07-29T17:41:53.368Z,1753810913.368 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2PM.000
2025-07-29T17:41:53.369Z,1753810913.369 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5MI74M.000
2025-07-29T17:41:53.369Z,1753810913.369 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5MI74M.000
2025-07-29T17:41:53.369Z,1753810913.369 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QN.000
2025-07-29T17:41:53.369Z,1753810913.369 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QN.000
2025-07-29T17:41:53.369Z,1753810913.369 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QO.000
2025-07-29T17:41:53.370Z,1753810913.369 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QO.000
2025-07-29T17:41:53.370Z,1753810913.370 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2RO.000
2025-07-29T17:41:53.370Z,1753810913.370 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2RO.000
2025-07-29T17:41:53.370Z,1753810913.370 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2RN.000
2025-07-29T17:41:53.370Z,1753810913.370 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2RN.000
2025-07-29T17:41:53.370Z,1753810913.370 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4MI2QP.000
2025-07-29T17:41:53.371Z,1753810913.371 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4MI2QP.000
2025-07-29T17:41:53.405Z,1753810913.405 [CBIT](DEBUG): Backplane powered.
2025-07-29T17:41:53.405Z,1753810913.405 [GFScanner](DEBUG): Initializing GFScanner
2025-07-29T17:41:53.405Z,1753810913.405 [GFScanner](DEBUG): Deactivating GF circuits.
2025-07-29T17:41:53.410Z,1753810913.410 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-07-29T17:41:53.410Z,1753810913.410 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-07-29T17:41:53.411Z,1753810913.411 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-07-29T17:41:53.421Z,1753810913.421 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-07-29T17:41:53.457Z,1753810913.457 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="Startup">
    <Guidance:GoToSurface>
        <Progression />
    </Guidance:GoToSurface>
    <Aggregate Id="StartupSatComms">
        <Sequence />
        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix />
        </ReadDatum>
        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications />
        </ReadDatum>
    </Aggregate>
</Mission>

2025-07-29T17:41:53.461Z,1753810913.461 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-07-29T17:41:53.515Z,1753810913.515 [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-07-29T17:41:53.515Z,1753810913.515 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-07-29T17:41:53.515Z,1753810913.515 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-07-29T17:41:53.518Z,1753810913.518 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-07-29T17:41:53.737Z,1753810913.737 [Radio_Surface](INFO): Powering up
2025-07-29T17:41:53.823Z,1753810913.823 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="Default">
    <DefineArg Name="ElapsedSinceDefaultStarted">
        <Units:minute />
        <Value>0</Value>
    </DefineArg>
    <Guidance:Wait>
        <Sequence />
        <Setting>
            <Guidance:Wait.duration />
            <Units:second />
            <Value>13</Value>
        </Setting>
    </Guidance:Wait>
    <Guidance:GoToSurface>
        <Progression />
    </Guidance:GoToSurface>
    <Aggregate Id="CheckIn">
        <Sequence Repeat="288" />
        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M" />
            <Universal:time_fix />
        </ReadDatum>
        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P4H">
                <Guidance:Execute>
                    <Sequence />
                    <Setting>
                        <Guidance:Execute.command />
                        <String>Burn on</String>
                    </Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications />
        </ReadDatum>
        <Guidance:Wait>
            <Sequence />
            <Setting>
                <Guidance:Wait.duration />
                <Units:minute />
                <Value>5</Value>
            </Setting>
        </Guidance:Wait>
        <Assign>
            <Sequence />
            <Arg Name="ElapsedSinceDefaultStarted" />
            <Elapsed>
                <Universal:mission_started />
            </Elapsed>
        </Assign>
        <Syslog Severity="Important">
            Default mission has been running for 
            <Arg Name="ElapsedSinceDefaultStarted" />
            <Units:minute />
        </Syslog>
    </Aggregate>
    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>
    <Guidance:Execute>
        <Sequence />
        <Setting>
            <Guidance:Execute.command />
            <String>restart logs</String>
        </Setting>
    </Guidance:Execute>
</Mission>

2025-07-29T17:41:53.845Z,1753810913.845 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-07-29T17:41:53.846Z,1753810913.846 [Default:A.Wait](DEBUG): Construct Wait.
2025-07-29T17:41:53.848Z,1753810913.848 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-07-29T17:41:53.887Z,1753810913.887 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-07-29T17:41:53.918Z,1753810913.918 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-07-29T17:41:53.924Z,1753810913.924 [Default:E.Execute](DEBUG): Construct Execute.
2025-07-29T17:41:53.947Z,1753810913.947 [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-07-29T17:41:53.952Z,1753810913.952 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BioacousticsDataBridge,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
2025-07-29T17:41:53.973Z,1753810913.973 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-07-29T17:41:54.129Z,1753810914.129 [Depth_Keller](INFO): Initializing.
2025-07-29T17:41:54.130Z,1753810914.130 [Power24vConverter](INFO): Powering up.
2025-07-29T17:41:54.131Z,1753810914.131 [Sonardyne_Nano](INFO): Initializing.
2025-07-29T17:41:54.153Z,1753810914.153 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-07-29T17:41:54.176Z,1753810914.176 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-07-29T17:41:54.185Z,1753810914.185 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-07-29T17:41:54.186Z,1753810914.186 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-07-29T17:41:54.193Z,1753810914.193 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-07-29T17:41:54.194Z,1753810914.194 [MassServo](DEBUG): Initializing EZServoServo.
2025-07-29T17:41:54.201Z,1753810914.201 [MassServo](DEBUG): Initializing MassServo.
2025-07-29T17:41:54.202Z,1753810914.202 [RudderServo](DEBUG): Initializing EZServoServo.
2025-07-29T17:41:54.209Z,1753810914.209 [RudderServo](DEBUG): Initializing RudderServo.
2025-07-29T17:41:54.210Z,1753810914.210 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-07-29T17:41:54.217Z,1753810914.217 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-07-29T17:41:54.405Z,1753810914.405 [Depth_Keller](ERROR): Pressure or depth reading out of range: 963.011658 decibar, 0.000000 m
2025-07-29T17:41:54.425Z,1753810914.425 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-07-29T17:41:55.969Z,1753810915.969 [WetLabsBB2FL](INFO): Powering down
2025-07-29T17:41:56.384Z,1753810916.384 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-07-29T17:41:57.625Z,1753810917.625 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2107
2025-07-29T17:41:59.862Z,1753810919.862 [ThrusterHE](ERROR): Zero Speed Commanded.
2025-07-29T17:42:05.648Z,1753810925.648 [DAT](INFO): DAT read: 

2025-07-29T17:42:05.650Z,1753810925.650 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2025-07-29T17:42:07.410Z,1753810927.410 [DAT](INFO): DAT read: MF Frequency Band

2025-07-29T17:42:07.411Z,1753810927.411 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2025-07-29T17:42:07.412Z,1753810927.412 [DAT](INFO): DAT read: Jul 29 2025 17:42:01 

2025-07-29T17:42:08.670Z,1753810928.670 [DAT](INFO): DAT read: Features enabled [Bearing]

2025-07-29T17:42:08.671Z,1753810928.671 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2025-07-29T17:42:08.672Z,1753810928.672 [DAT](INFO): commRate: 800
2025-07-29T17:42:10.737Z,1753810930.737 [DAT](INFO): entering command mode
2025-07-29T17:42:10.937Z,1753810930.937 [DAT](INFO): DAT read: 

2025-07-29T17:42:10.938Z,1753810930.938 [DAT](INFO): DAT read: user:1>

2025-07-29T17:42:10.938Z,1753810930.938 [DAT](INFO): setting verbose to 3
2025-07-29T17:42:11.189Z,1753810931.189 [DAT](INFO): DAT read: user:1>

2025-07-29T17:42:11.191Z,1753810931.191 [DAT](INFO): DAT read: Verbose         | 3           

2025-07-29T17:42:11.191Z,1753810931.191 [DAT](INFO): set verbose to 3
2025-07-29T17:42:11.191Z,1753810931.191 [DAT](INFO): setting DatVerbose to 27440
2025-07-29T17:42:11.442Z,1753810931.442 [DAT](INFO): DAT read: user:2>

2025-07-29T17:42:11.442Z,1753810931.442 [DAT](INFO): DAT read: DatVerbose      | 27440       

2025-07-29T17:42:11.443Z,1753810931.443 [DAT](INFO): set DatVerbose to 27440
2025-07-29T17:42:11.443Z,1753810931.443 [DAT](INFO): setting transmit power to 8
2025-07-29T17:42:11.693Z,1753810931.693 [DAT](INFO): DAT read: user:3>

2025-07-29T17:42:11.694Z,1753810931.694 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2025-07-29T17:42:11.695Z,1753810931.695 [DAT](INFO): set transmit power to 8
2025-07-29T17:42:11.695Z,1753810931.695 [DAT](INFO): setting local address to 12
2025-07-29T17:42:11.947Z,1753810931.947 [DAT](INFO): DAT read: user:4>

2025-07-29T17:42:11.947Z,1753810931.947 [DAT](INFO): DAT read: LocalAddr       | 12          

2025-07-29T17:42:11.948Z,1753810931.948 [DAT](INFO): set local address to 12
2025-07-29T17:42:11.951Z,1753810931.951 [DAT](INFO): Setting time to: 17:42:11 And date to:7/29/2025
2025-07-29T17:42:12.197Z,1753810932.197 [DAT](INFO): DAT read: user:5>

2025-07-29T17:42:12.198Z,1753810932.198 [DAT](INFO): DAT read: Tue Jul 29, 2025  17:42:11  

2025-07-29T17:42:12.199Z,1753810932.199 [DAT](INFO): Local DAT time set to Tue Jul 29, 2025  17:42:11  

2025-07-29T17:42:21.838Z,1753810941.838 [NAL9602](INFO): Powering up NAL9602
2025-07-29T17:42:21.869Z,1753810941.869 [SBIT](IMPORTANT): Beginning Startup BIT
2025-07-29T17:42:21.878Z,1753810941.878 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-07-29T17:42:26.366Z,1753810946.366 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006146
CHAN A1 (24V): -0.005144
CHAN A2 (12V): -0.004349
CHAN A3 (5V): -0.003528
CHAN B0 (3.3V): -0.001499
CHAN B1 (3.15aV): -0.001605
CHAN B2 (3.15bV): -0.001187
CHAN B3 (GND): -0.001065
OPEN: 0.003125
Full Scale: +/- 1 mA
2025-07-29T17:42:32.749Z,1753810952.749 [NAL9602](INFO): NAL9602 initialized
2025-07-29T17:43:16.019Z,1753810996.019 [SBIT](IMPORTANT): SBIT PASSED
2025-07-29T17:43:16.019Z,1753810996.019 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-07-29T17:43:16.020Z,1753810996.020 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=15 count;

2025-07-29T17:43:16.020Z,1753810996.020 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity;

2025-07-29T17:43:16.020Z,1753810996.020 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_temperature;

2025-07-29T17:43:16.021Z,1753810996.021 [SBIT](IMPORTANT): Express none WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water;

2025-07-29T17:43:16.021Z,1753810996.021 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_LM_AvgRois 1.000000 count_per_second;

2025-07-29T17:43:16.021Z,1753810996.021 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=189.253292 cubic_centimeter;

2025-07-29T17:43:16.021Z,1753810996.021 [SBIT](IMPORTANT): VerticalControl.massDefault=-2.044442 millimeter;

2025-07-29T17:43:16.022Z,1753810996.022 [SBIT](IMPORTANT): Waterlinked.loadAtStartup=0 bool;

2025-07-29T17:43:16.426Z,1753810996.426 [MissionManager](IMPORTANT): Started mission Startup
2025-07-29T17:43:16.426Z,1753810996.426 [Startup] Running Loop=1
2025-07-29T17:43:16.426Z,1753810996.426 [Startup](DEBUG): Aggregate::initialize Startup
2025-07-29T17:43:16.427Z,1753810996.427 [Startup:A.GoToSurface] Running Loop=1
2025-07-29T17:43:16.427Z,1753810996.427 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-07-29T17:43:16.427Z,1753810996.427 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-07-29T17:43:16.428Z,1753810996.428 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-07-29T17:43:16.428Z,1753810996.428 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-07-29T17:43:16.428Z,1753810996.428 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-07-29T17:43:16.429Z,1753810996.429 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-07-29T17:43:16.429Z,1753810996.429 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-07-29T17:43:16.431Z,1753810996.431 [Startup:StartupSatComms] Running Loop=1
2025-07-29T17:43:16.431Z,1753810996.431 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-07-29T17:43:16.431Z,1753810996.431 [Startup:StartupSatComms:A] Running Loop=1
2025-07-29T17:43:16.823Z,1753810996.823 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-07-29T17:44:16.650Z,1753811056.650 [Startup:StartupSatComms:A](INFO): Timed out from 2025-07-29T17:43:16.4Z
2025-07-29T17:44:16.650Z,1753811056.650 [Startup:StartupSatComms:A] Stopped
2025-07-29T17:44:16.650Z,1753811056.650 [Startup:StartupSatComms:B] Running Loop=1
2025-07-29T17:44:17.043Z,1753811057.043 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-07-29T17:44:20.559Z,1753811060.559 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004724
2025-07-29T17:44:28.143Z,1753811068.143 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250729T173407/Courier0004.lzma
2025-07-29T17:44:29.145Z,1753811069.145 [DataOverHttps](INFO): Moved sent file to Logs/20250729T173407/Courier0004.lzma.bak
2025-07-29T17:44:29.145Z,1753811069.145 [DataOverHttps](INFO): SBD MOMSN=25630971
2025-07-29T17:44:44.952Z,1753811084.952 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250729T174140/Courier0000.lzma
2025-07-29T17:44:45.953Z,1753811085.953 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Courier0000.lzma.bak
2025-07-29T17:44:45.953Z,1753811085.953 [DataOverHttps](INFO): SBD MOMSN=25630973
2025-07-29T17:44:53.276Z,1753811093.276 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-07-29T17:44:53.276Z,1753811093.276 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-29T17:44:53.286Z,1753811093.286 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-29T17:44:53.725Z,1753811093.725 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-29T17:44:53.725Z,1753811093.725 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-07-29T17:45:01.667Z,1753811101.667 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250729T173407/Express0005.lzma
2025-07-29T17:45:02.669Z,1753811102.669 [DataOverHttps](INFO): Moved sent file to Logs/20250729T173407/Express0005.lzma.bak
2025-07-29T17:45:02.669Z,1753811102.669 [DataOverHttps](INFO): SBD MOMSN=25630976
2025-07-29T17:45:16.729Z,1753811116.729 [Startup:StartupSatComms:B](INFO): Timed out from 2025-07-29T17:44:16.7Z
2025-07-29T17:45:16.729Z,1753811116.729 [Startup:StartupSatComms:B] Stopped
2025-07-29T17:45:16.729Z,1753811116.729 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-07-29T17:45:16.729Z,1753811116.729 [Startup:StartupSatComms] Stopped
2025-07-29T17:45:16.729Z,1753811116.729 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-07-29T17:45:16.730Z,1753811116.730 [Startup](INFO): Completed Startup
2025-07-29T17:45:16.730Z,1753811116.730 [MissionManager](INFO): Startup is completed.
2025-07-29T17:45:16.731Z,1753811116.731 [MissionManager](INFO): Uninitializing Mission Startup
2025-07-29T17:45:16.731Z,1753811116.731 [Startup] Stopped
2025-07-29T17:45:16.731Z,1753811116.731 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-07-29T17:45:16.731Z,1753811116.731 [Startup:A.GoToSurface] Stopped
2025-07-29T17:45:16.731Z,1753811116.731 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-07-29T17:45:17.142Z,1753811117.142 [MissionManager](IMPORTANT): Started mission Default
2025-07-29T17:45:17.142Z,1753811117.142 [Default] Running Loop=1
2025-07-29T17:45:17.142Z,1753811117.142 [Default](DEBUG): Aggregate::initialize Default
2025-07-29T17:45:17.142Z,1753811117.142 [Default:B.GoToSurface] Running Loop=1
2025-07-29T17:45:17.143Z,1753811117.143 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-07-29T17:45:17.143Z,1753811117.143 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-07-29T17:45:17.147Z,1753811117.147 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-07-29T17:45:17.148Z,1753811117.148 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-07-29T17:45:17.148Z,1753811117.148 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-07-29T17:45:17.148Z,1753811117.148 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-07-29T17:45:17.149Z,1753811117.149 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-07-29T17:45:17.149Z,1753811117.149 [Default:A.Wait] Running Loop=1
2025-07-29T17:45:17.149Z,1753811117.149 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-07-29T17:45:18.765Z,1753811118.765 [DataOverHttps](INFO): Sending 933 bytes from file Logs/20250729T174140/Express0001.lzma
2025-07-29T17:45:19.765Z,1753811119.765 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Express0001.lzma.bak
2025-07-29T17:45:19.765Z,1753811119.765 [DataOverHttps](INFO): SBD MOMSN=25630978
2025-07-29T17:45:30.457Z,1753811130.457 [Default:A.Wait](INFO): Done Waiting.
2025-07-29T17:45:30.457Z,1753811130.457 [Default:A.Wait] Stopped
2025-07-29T17:45:30.457Z,1753811130.457 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-07-29T17:45:30.851Z,1753811130.851 [Default:CheckIn] Running Loop=1
2025-07-29T17:45:30.851Z,1753811130.851 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-29T17:45:30.851Z,1753811130.851 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-29T17:45:31.249Z,1753811131.249 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-07-29T17:47:36.077Z,1753811256.077 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-07-29T17:47:54.284Z,1753811274.284 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2025-07-29T17:47:54.284Z,1753811274.284 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-29T17:47:54.295Z,1753811274.295 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-29T17:47:54.685Z,1753811274.685 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-29T17:47:54.685Z,1753811274.685 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2025-07-29T17:48:37.882Z,1753811317.882 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-29T17:48:37.889Z,1753811317.889 [BPC1](INFO): Calculating totals. Valid battery stick count: 48. Valid reserve battery stick count: 4.
2025-07-29T17:48:37.892Z,1753811317.892 [BPC1](INFO): Received data from all battery sticks.
2025-07-29T17:50:31.038Z,1753811431.038 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-29T17:45:30.9Z
2025-07-29T17:50:31.038Z,1753811431.038 [Default:CheckIn:Read_GPS] Stopped
2025-07-29T17:50:31.038Z,1753811431.038 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-29T17:50:31.432Z,1753811431.432 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-07-29T17:50:38.223Z,1753811438.223 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20250729T174140/Courier0004.lzma
2025-07-29T17:50:39.225Z,1753811439.225 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Courier0004.lzma.bak
2025-07-29T17:50:39.225Z,1753811439.225 [DataOverHttps](INFO): SBD MOMSN=25631005
2025-07-29T17:50:54.815Z,1753811454.815 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20250729T174140/Express0005.lzma
2025-07-29T17:50:55.280Z,1753811455.280 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2025-07-29T17:50:55.280Z,1753811455.280 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-29T17:50:55.290Z,1753811455.290 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-29T17:50:55.689Z,1753811455.689 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-29T17:50:55.689Z,1753811455.689 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2025-07-29T17:50:55.817Z,1753811455.817 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Express0005.lzma.bak
2025-07-29T17:50:55.817Z,1753811455.817 [DataOverHttps](INFO): SBD MOMSN=25631007
2025-07-29T17:50:56.882Z,1753811456.882 [Default:CheckIn:Read_Iridium] Stopped
2025-07-29T17:50:56.882Z,1753811456.882 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-29T17:50:56.882Z,1753811456.882 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-29T17:52:35.441Z,1753811555.441 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-07-29T17:52:35.441Z,1753811555.441 [NAL9602] Data Fault, FailCount= 1
2025-07-29T17:52:35.441Z,1753811555.441 [NAL9602](ERROR): Data Fault
2025-07-29T17:52:35.458Z,1753811555.458 [CBIT](ERROR): Data Fault in component: NAL9602
2025-07-29T17:52:35.841Z,1753811555.841 [NAL9602](INFO): Powering down
2025-07-29T17:52:36.669Z,1753811556.669 [CBIT](INFO): Clearing failed state for component NAL9602
2025-07-29T17:52:36.669Z,1753811556.669 [NAL9602] No Fault, FailCount= 1
2025-07-29T17:53:06.141Z,1753811586.141 [NAL9602](INFO): Powering up NAL9602
2025-07-29T17:53:17.053Z,1753811597.053 [NAL9602](INFO): NAL9602 initialized
2025-07-29T17:53:56.271Z,1753811636.271 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2025-07-29T17:53:56.271Z,1753811636.271 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-29T17:53:56.290Z,1753811636.290 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-29T17:53:56.690Z,1753811636.690 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-29T17:53:56.690Z,1753811636.690 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2025-07-29T17:55:57.449Z,1753811757.449 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-29T17:55:57.449Z,1753811757.449 [Default:CheckIn:C.Wait] Stopped
2025-07-29T17:55:57.450Z,1753811757.450 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-29T17:55:57.450Z,1753811757.450 [Default:CheckIn:D] Running Loop=1
2025-07-29T17:55:57.853Z,1753811757.853 [Default:CheckIn:D] Stopped
2025-07-29T17:55:57.853Z,1753811757.853 [Default:CheckIn:E] Running Loop=1
2025-07-29T17:55:58.257Z,1753811758.257 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  10.678517 min 
2025-07-29T17:55:58.257Z,1753811758.257 [Default:CheckIn:E] Stopped
2025-07-29T17:55:58.257Z,1753811758.257 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-29T17:55:58.257Z,1753811758.257 [Default:CheckIn] Stopped
2025-07-29T17:55:58.257Z,1753811758.257 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-29T17:55:58.257Z,1753811758.257 [Default:CheckIn](INFO): Running loop #2
2025-07-29T17:55:58.257Z,1753811758.257 [Default:CheckIn] Running Loop=2
2025-07-29T17:55:58.257Z,1753811758.257 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-29T17:55:58.257Z,1753811758.257 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-29T17:56:57.280Z,1753811817.280 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2025-07-29T17:56:57.280Z,1753811817.280 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-29T17:56:57.291Z,1753811817.291 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-29T17:56:57.696Z,1753811817.696 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-29T17:56:57.696Z,1753811817.696 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2025-07-29T17:59:58.275Z,1753811998.275 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2025-07-29T17:59:58.275Z,1753811998.275 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-29T17:59:58.285Z,1753811998.285 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-29T17:59:58.699Z,1753811998.699 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-29T17:59:58.699Z,1753811998.699 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2025-07-29T18:00:58.486Z,1753812058.486 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-29T17:55:58.3Z
2025-07-29T18:00:58.486Z,1753812058.486 [Default:CheckIn:Read_GPS] Stopped
2025-07-29T18:00:58.486Z,1753812058.486 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-29T18:01:05.451Z,1753812065.451 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250729T174140/Courier0007.lzma
2025-07-29T18:01:06.453Z,1753812066.453 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Courier0007.lzma.bak
2025-07-29T18:01:06.453Z,1753812066.453 [DataOverHttps](INFO): SBD MOMSN=25631023
2025-07-29T18:01:22.167Z,1753812082.167 [DataOverHttps](INFO): Sending 182 bytes from file Logs/20250729T174140/Express0008.lzma
2025-07-29T18:01:23.169Z,1753812083.169 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Express0008.lzma.bak
2025-07-29T18:01:23.169Z,1753812083.169 [DataOverHttps](INFO): SBD MOMSN=25631025
2025-07-29T18:01:24.337Z,1753812084.337 [Default:CheckIn:Read_Iridium] Stopped
2025-07-29T18:01:24.337Z,1753812084.337 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-29T18:01:24.337Z,1753812084.337 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-29T18:01:49.220Z,1753812109.220 [CommandExec](IMPORTANT): got command failComponent
2025-07-29T18:01:49.220Z,1753812109.220 [CommandExec](IMPORTANT): Failed components:
2025-07-29T18:01:49.220Z,1753812109.220 [CommandExec](IMPORTANT): No failed Components.
2025-07-29T18:02:59.328Z,1753812179.328 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2025-07-29T18:02:59.328Z,1753812179.328 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-29T18:02:59.338Z,1753812179.338 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-29T18:02:59.722Z,1753812179.722 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-29T18:02:59.722Z,1753812179.722 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2025-07-29T18:03:17.881Z,1753812197.881 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-07-29T18:03:17.881Z,1753812197.881 [NAL9602] Data Fault, FailCount= 2
2025-07-29T18:03:17.881Z,1753812197.881 [NAL9602](ERROR): Data Fault
2025-07-29T18:03:17.898Z,1753812197.898 [CBIT](ERROR): Data Fault in component: NAL9602
2025-07-29T18:03:18.285Z,1753812198.285 [NAL9602](INFO): Powering down
2025-07-29T18:03:19.122Z,1753812199.122 [CBIT](INFO): Clearing failed state for component NAL9602
2025-07-29T18:03:19.122Z,1753812199.122 [NAL9602] No Fault, FailCount= 2
2025-07-29T18:03:48.585Z,1753812228.585 [NAL9602](INFO): Powering up NAL9602
2025-07-29T18:03:59.497Z,1753812239.497 [NAL9602](INFO): NAL9602 initialized
2025-07-29T18:05:38.684Z,1753812338.684 [CommandExec](IMPORTANT): got command failComponent
2025-07-29T18:05:38.684Z,1753812338.684 [CommandExec](IMPORTANT): Failed components:
2025-07-29T18:05:38.684Z,1753812338.684 [CommandExec](IMPORTANT): No failed Components.
2025-07-29T18:06:00.303Z,1753812360.303 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2025-07-29T18:06:00.303Z,1753812360.303 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-29T18:06:00.314Z,1753812360.314 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-29T18:06:00.725Z,1753812360.725 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-29T18:06:00.725Z,1753812360.725 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2025-07-29T18:06:24.972Z,1753812384.972 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-29T18:06:24.973Z,1753812384.973 [Default:CheckIn:C.Wait] Stopped
2025-07-29T18:06:24.973Z,1753812384.973 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-29T18:06:24.973Z,1753812384.973 [Default:CheckIn:D] Running Loop=1
2025-07-29T18:06:25.385Z,1753812385.385 [Default:CheckIn:D] Stopped
2025-07-29T18:06:25.385Z,1753812385.385 [Default:CheckIn:E] Running Loop=1
2025-07-29T18:06:25.774Z,1753812385.774 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  21.137374 min 
2025-07-29T18:06:25.774Z,1753812385.774 [Default:CheckIn:E] Stopped
2025-07-29T18:06:25.774Z,1753812385.774 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-29T18:06:25.774Z,1753812385.774 [Default:CheckIn] Stopped
2025-07-29T18:06:25.774Z,1753812385.774 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-29T18:06:25.774Z,1753812385.774 [Default:CheckIn](INFO): Running loop #3
2025-07-29T18:06:25.774Z,1753812385.774 [Default:CheckIn] Running Loop=3
2025-07-29T18:06:25.774Z,1753812385.774 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-29T18:06:25.775Z,1753812385.775 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-29T18:09:01.346Z,1753812541.346 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2025-07-29T18:09:01.346Z,1753812541.346 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-29T18:09:01.356Z,1753812541.356 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-29T18:09:01.773Z,1753812541.773 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-29T18:09:01.774Z,1753812541.774 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2025-07-29T18:11:25.998Z,1753812685.998 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-29T18:06:25.8Z
2025-07-29T18:11:25.998Z,1753812685.998 [Default:CheckIn:Read_GPS] Stopped
2025-07-29T18:11:25.998Z,1753812685.998 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-29T18:11:33.367Z,1753812693.367 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20250729T174140/Courier0010.lzma
2025-07-29T18:11:44.409Z,1753812704.409 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Courier0010.lzma.bak
2025-07-29T18:11:44.409Z,1753812704.409 [DataOverHttps](INFO): SBD MOMSN=25631041
2025-07-29T18:12:02.344Z,1753812722.344 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2025-07-29T18:12:02.344Z,1753812722.344 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-29T18:12:02.355Z,1753812722.355 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-29T18:12:02.769Z,1753812722.769 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-29T18:12:02.769Z,1753812722.769 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2025-07-29T18:12:09.131Z,1753812729.131 [DataOverHttps](INFO): Sending 238 bytes from file Logs/20250729T174140/Express0011.lzma
2025-07-29T18:12:20.173Z,1753812740.173 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Express0011.lzma.bak
2025-07-29T18:12:20.173Z,1753812740.173 [DataOverHttps](INFO): SBD MOMSN=25631043
2025-07-29T18:12:21.313Z,1753812741.313 [Default:CheckIn:Read_Iridium] Stopped
2025-07-29T18:12:21.313Z,1753812741.313 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-29T18:12:21.313Z,1753812741.313 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-29T18:13:32.003Z,1753812812.003 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181330.00,A,3648.15167,N,12147.28215,W,0.447,332.33,290725,,,A*7A
2025-07-29T18:13:32.006Z,1753812812.006 [NAL9602](INFO): GPS fix at 20250729T181330: (36.802528, -121.788036)
2025-07-29T18:14:04.328Z,1753812844.328 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-29T18:17:21.916Z,1753813041.916 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-29T18:17:21.916Z,1753813041.916 [Default:CheckIn:C.Wait] Stopped
2025-07-29T18:17:21.916Z,1753813041.916 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-29T18:17:21.917Z,1753813041.917 [Default:CheckIn:D] Running Loop=1
2025-07-29T18:17:22.321Z,1753813042.321 [Default:CheckIn:D] Stopped
2025-07-29T18:17:22.321Z,1753813042.321 [Default:CheckIn:E] Running Loop=1
2025-07-29T18:17:22.729Z,1753813042.729 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  32.086316 min 
2025-07-29T18:17:22.729Z,1753813042.729 [Default:CheckIn:E] Stopped
2025-07-29T18:17:22.729Z,1753813042.729 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-29T18:17:22.729Z,1753813042.729 [Default:CheckIn] Stopped
2025-07-29T18:17:22.729Z,1753813042.729 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-29T18:17:22.729Z,1753813042.729 [Default:CheckIn](INFO): Running loop #4
2025-07-29T18:17:22.729Z,1753813042.729 [Default:CheckIn] Running Loop=4
2025-07-29T18:17:22.729Z,1753813042.729 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-29T18:17:22.729Z,1753813042.729 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-29T18:17:24.731Z,1753813044.731 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181723.00,A,3648.13798,N,12147.23919,W,2.255,110.18,290725,,,A*7C
2025-07-29T18:17:24.733Z,1753813044.733 [NAL9602](INFO): GPS fix at 20250729T181723: (36.802300, -121.787320)
2025-07-29T18:17:24.743Z,1753813044.743 [Default:CheckIn:Read_GPS] Stopped
2025-07-29T18:17:24.759Z,1753813044.759 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-29T18:17:32.315Z,1753813052.315 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20250729T174140/Courier0013.lzma
2025-07-29T18:17:43.365Z,1753813063.365 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Courier0013.lzma.bak
2025-07-29T18:17:43.365Z,1753813063.365 [DataOverHttps](IMPORTANT): SBD MOMSN=25631061, MTMSN=20250729T181742
2025-07-29T18:17:48.978Z,1753813068.978 [NAL9602](INFO): SBD MO Status=0, MOMSN=10213, MT Status=0, MTMSN=0
2025-07-29T18:17:48.978Z,1753813068.978 [NAL9602](INFO): No messages in MT queue
2025-07-29T18:17:50.823Z,1753813070.823 [DataOverHttps](INFO): Received command: ibit
2025-07-29T18:17:50.872Z,1753813070.872 [CommandExec](IMPORTANT): got command ibit
2025-07-29T18:17:51.010Z,1753813071.010 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-07-29T18:17:51.010Z,1753813071.010 [IBIT](IMPORTANT): Beginning control surface checks.
2025-07-29T18:17:51.013Z,1753813071.013 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-07-29T18:17:52.612Z,1753813072.612 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181750.00,A,3648.15068,N,12147.22666,W,0.875,37.98,290725,,,A*46
2025-07-29T18:17:52.614Z,1753813072.614 [NAL9602](INFO): GPS fix at 20250729T181750: (36.802511, -121.787111)
2025-07-29T18:17:55.571Z,1753813075.571 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006963
CHAN A1 (24V): -0.007834
CHAN A2 (12V): -0.005320
CHAN A3 (5V): -0.002754
CHAN B0 (3.3V): -0.001470
CHAN B1 (3.15aV): -0.001467
CHAN B2 (3.15bV): -0.001752
CHAN B3 (GND): -0.000269
OPEN: 0.004850
Full Scale: +/- 1 mA
2025-07-29T18:17:55.876Z,1753813075.876 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181754.00,A,3648.15128,N,12147.22617,W,0.156,37.98,290725,,,A*49
2025-07-29T18:17:55.878Z,1753813075.878 [NAL9602](INFO): GPS fix at 20250729T181754: (36.802521, -121.787103)
2025-07-29T18:17:59.108Z,1753813079.108 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181757.00,A,3648.15154,N,12147.22605,W,0.097,37.98,290725,,,A*4E
2025-07-29T18:17:59.110Z,1753813079.110 [NAL9602](INFO): GPS fix at 20250729T181757: (36.802526, -121.787101)
2025-07-29T18:18:01.865Z,1753813081.865 [DataOverHttps](IMPORTANT): SBD MTMSN=20250729T181800
2025-07-29T18:18:01.934Z,1753813081.934 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181800.00,A,3648.15163,N,12147.22610,W,0.078,37.98,290725,,,A*42
2025-07-29T18:18:01.937Z,1753813081.937 [NAL9602](INFO): GPS fix at 20250729T181800: (36.802527, -121.787102)
2025-07-29T18:18:04.756Z,1753813084.756 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181803.00,A,3648.15157,N,12147.22606,W,0.097,37.98,290725,,,A*40
2025-07-29T18:18:04.758Z,1753813084.758 [NAL9602](INFO): GPS fix at 20250729T181803: (36.802526, -121.787101)
2025-07-29T18:18:07.990Z,1753813087.990 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181806.00,A,3648.15144,N,12147.22594,W,0.058,37.98,290725,,,A*4C
2025-07-29T18:18:07.992Z,1753813087.992 [NAL9602](INFO): GPS fix at 20250729T181806: (36.802524, -121.787099)
2025-07-29T18:18:09.316Z,1753813089.316 [DataOverHttps](INFO): Sending 378 bytes from file Logs/20250729T174140/Express0014.lzma
2025-07-29T18:18:09.319Z,1753813089.319 [DataOverHttps](INFO): Received command: failc
2025-07-29T18:18:09.352Z,1753813089.352 [CommandExec](IMPORTANT): got command failComponent
2025-07-29T18:18:09.352Z,1753813089.352 [CommandExec](IMPORTANT): Failed components:
2025-07-29T18:18:09.352Z,1753813089.352 [CommandExec](IMPORTANT): No failed Components.
2025-07-29T18:18:10.818Z,1753813090.818 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181809.00,A,3648.15124,N,12147.22586,W,0.019,37.98,290725,,,A*43
2025-07-29T18:18:10.820Z,1753813090.820 [NAL9602](INFO): GPS fix at 20250729T181809: (36.802521, -121.787098)
2025-07-29T18:18:14.051Z,1753813094.051 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181812.00,A,3648.15116,N,12147.22575,W,0.078,37.98,290725,,,A*43
2025-07-29T18:18:14.053Z,1753813094.053 [NAL9602](INFO): GPS fix at 20250729T181812: (36.802519, -121.787096)
2025-07-29T18:18:16.876Z,1753813096.876 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181815.00,A,3648.15106,N,12147.22562,W,0.019,37.98,290725,,,A*44
2025-07-29T18:18:16.878Z,1753813096.878 [NAL9602](INFO): GPS fix at 20250729T181815: (36.802518, -121.787094)
2025-07-29T18:18:20.116Z,1753813100.116 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181818.00,A,3648.15099,N,12147.22544,W,0.058,37.98,290725,,,A*4F
2025-07-29T18:18:20.118Z,1753813100.118 [NAL9602](INFO): GPS fix at 20250729T181818: (36.802517, -121.787091)
2025-07-29T18:18:20.357Z,1753813100.357 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Express0014.lzma.bak
2025-07-29T18:18:20.358Z,1753813100.358 [DataOverHttps](INFO): SBD MOMSN=25631066
2025-07-29T18:18:22.938Z,1753813102.938 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181821.00,A,3648.15095,N,12147.22518,W,0.058,37.98,290725,,,A*40
2025-07-29T18:18:22.941Z,1753813102.941 [NAL9602](INFO): GPS fix at 20250729T181821: (36.802516, -121.787086)
2025-07-29T18:18:25.766Z,1753813105.766 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181824.00,A,3648.15098,N,12147.22505,W,0.097,37.98,290725,,,A*47
2025-07-29T18:18:25.769Z,1753813105.769 [NAL9602](INFO): GPS fix at 20250729T181824: (36.802516, -121.787084)
2025-07-29T18:18:28.996Z,1753813108.996 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181827.00,A,3648.15098,N,12147.22502,W,0.039,37.98,290725,,,A*47
2025-07-29T18:18:28.998Z,1753813108.998 [NAL9602](INFO): GPS fix at 20250729T181827: (36.802516, -121.787084)
2025-07-29T18:18:31.856Z,1753813111.856 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181830.00,A,3648.15091,N,12147.22496,W,0.058,37.98,290725,,,A*43
2025-07-29T18:18:31.858Z,1753813111.858 [NAL9602](INFO): GPS fix at 20250729T181830: (36.802515, -121.787083)
2025-07-29T18:18:35.059Z,1753813115.059 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181833.00,A,3648.15020,N,12147.22491,W,0.175,37.98,290725,,,A*43
2025-07-29T18:18:35.061Z,1753813115.061 [NAL9602](INFO): GPS fix at 20250729T181833: (36.802503, -121.787082)
2025-07-29T18:18:37.097Z,1753813117.097 [IBIT](IMPORTANT): Communications Status:
Fix Status: 1
Iridium Signal Strength: 5
Latitude: 36.802502 Longitude: -121.787079
2025-07-29T18:18:37.517Z,1753813117.517 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.690001
2025-07-29T18:18:37.518Z,1753813117.518 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2025-07-29T18:18:37.518Z,1753813117.518 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2025-07-29T18:18:37.882Z,1753813117.882 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,181836.00,A,3648.14953,N,12147.22469,W,0.078,37.98,290725,,,A*41
2025-07-29T18:18:37.884Z,1753813117.884 [NAL9602](INFO): GPS fix at 20250729T181836: (36.802492, -121.787078)
2025-07-29T18:18:37.900Z,1753813117.900 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2025-07-29T18:18:37.900Z,1753813117.900 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2025-07-29T18:18:37.901Z,1753813117.901 [IBIT](IMPORTANT): Pressure:9.735540 PSI
2025-07-29T18:18:37.901Z,1753813117.901 [IBIT](IMPORTANT): Humidity:18.825541 %
2025-07-29T18:18:38.320Z,1753813118.320 [IBIT](IMPORTANT): Vehicle Pitch:-1.269203 degrees
2025-07-29T18:18:38.321Z,1753813118.321 [IBIT](IMPORTANT): Vehicle  Roll:-3.238740 degrees
2025-07-29T18:18:38.321Z,1753813118.321 [IBIT](IMPORTANT): Vehicle  Heading:55.502384 degrees
2025-07-29T18:18:38.717Z,1753813118.717 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2025-07-29T18:18:38.717Z,1753813118.717 [IBIT](IMPORTANT): buoyancyNeutral: 189.253296 cc
2025-07-29T18:18:38.718Z,1753813118.718 [IBIT](IMPORTANT): massDefault: -0.204444 cm
2025-07-29T18:18:38.718Z,1753813118.718 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2025-07-29T18:18:38.718Z,1753813118.718 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2025-07-29T18:18:38.718Z,1753813118.718 [IBIT](IMPORTANT): IBIT PASSED
2025-07-29T18:18:46.719Z,1753813126.719 [DataOverHttps](INFO): Sending 38 bytes from file Logs/20250729T174140/Courier0016.lzma
2025-07-29T18:18:57.761Z,1753813137.761 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Courier0016.lzma.bak
2025-07-29T18:18:57.762Z,1753813137.762 [DataOverHttps](INFO): SBD MOMSN=25631071
2025-07-29T18:19:10.612Z,1753813150.612 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-29T18:19:23.711Z,1753813163.711 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250729T174140/Courier0019.lzma
2025-07-29T18:19:34.753Z,1753813174.753 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Courier0019.lzma.bak
2025-07-29T18:19:34.753Z,1753813174.753 [DataOverHttps](INFO): SBD MOMSN=25631073
2025-07-29T18:20:00.623Z,1753813200.624 [DataOverHttps](INFO): Sending 260 bytes from file Logs/20250729T174140/Express0017.lzma
2025-07-29T18:20:11.665Z,1753813211.665 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Express0017.lzma.bak
2025-07-29T18:20:11.665Z,1753813211.665 [DataOverHttps](INFO): SBD MOMSN=25631076
2025-07-29T18:20:37.912Z,1753813237.912 [DataOverHttps](INFO): Sending 405 bytes from file Logs/20250729T174140/Express0020.lzma
2025-07-29T18:20:48.953Z,1753813248.953 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Express0020.lzma.bak
2025-07-29T18:20:48.953Z,1753813248.953 [DataOverHttps](INFO): SBD MOMSN=25631083
2025-07-29T18:20:49.998Z,1753813249.998 [Default:CheckIn:Read_Iridium] Stopped
2025-07-29T18:20:49.998Z,1753813249.998 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-29T18:20:49.998Z,1753813249.998 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-29T18:25:50.604Z,1753813550.604 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-29T18:25:50.604Z,1753813550.604 [Default:CheckIn:C.Wait] Stopped
2025-07-29T18:25:50.604Z,1753813550.604 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-29T18:25:50.604Z,1753813550.604 [Default:CheckIn:D] Running Loop=1
2025-07-29T18:25:51.017Z,1753813551.017 [Default:CheckIn:D] Stopped
2025-07-29T18:25:51.018Z,1753813551.018 [Default:CheckIn:E] Running Loop=1
2025-07-29T18:25:51.419Z,1753813551.419 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  40.564587 min 
2025-07-29T18:25:51.419Z,1753813551.419 [Default:CheckIn:E] Stopped
2025-07-29T18:25:51.420Z,1753813551.420 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-29T18:25:51.420Z,1753813551.420 [Default:CheckIn] Stopped
2025-07-29T18:25:51.420Z,1753813551.420 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-29T18:25:51.420Z,1753813551.420 [Default:CheckIn](INFO): Running loop #5
2025-07-29T18:25:51.420Z,1753813551.420 [Default:CheckIn] Running Loop=5
2025-07-29T18:25:51.420Z,1753813551.420 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-29T18:25:51.420Z,1753813551.420 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-29T18:25:53.422Z,1753813553.422 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182552.00,A,3648.14585,N,12147.21645,W,0.039,96.53,290725,,,D*49
2025-07-29T18:25:53.425Z,1753813553.425 [NAL9602](INFO): GPS fix at 20250729T182552: (36.802431, -121.786941)
2025-07-29T18:25:53.459Z,1753813553.459 [Default:CheckIn:Read_GPS] Stopped
2025-07-29T18:25:53.459Z,1753813553.459 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-29T18:26:00.371Z,1753813560.371 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250729T174140/Courier0022.lzma
2025-07-29T18:26:06.346Z,1753813566.346 [NAL9602](INFO): SBD MO Status=0, MOMSN=10214, MT Status=0, MTMSN=0
2025-07-29T18:26:06.346Z,1753813566.346 [NAL9602](INFO): No messages in MT queue
2025-07-29T18:26:11.425Z,1753813571.425 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Courier0022.lzma.bak
2025-07-29T18:26:11.425Z,1753813571.425 [DataOverHttps](INFO): SBD MOMSN=25631102
2025-07-29T18:26:37.060Z,1753813597.060 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-29T18:26:39.008Z,1753813599.008 [DataOverHttps](INFO): Sending 720 bytes from file Logs/20250729T174140/Express0023.lzma
2025-07-29T18:26:50.050Z,1753813610.050 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Express0023.lzma.bak
2025-07-29T18:26:50.050Z,1753813610.050 [DataOverHttps](INFO): SBD MOMSN=25631109
2025-07-29T18:26:51.208Z,1753813611.208 [Default:CheckIn:Read_Iridium] Stopped
2025-07-29T18:26:51.208Z,1753813611.208 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-29T18:26:51.208Z,1753813611.208 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-29T18:31:51.790Z,1753813911.790 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-29T18:31:51.790Z,1753813911.790 [Default:CheckIn:C.Wait] Stopped
2025-07-29T18:31:51.790Z,1753813911.790 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-29T18:31:51.790Z,1753813911.790 [Default:CheckIn:D] Running Loop=1
2025-07-29T18:31:52.206Z,1753813912.206 [Default:CheckIn:D] Stopped
2025-07-29T18:31:52.206Z,1753813912.206 [Default:CheckIn:E] Running Loop=1
2025-07-29T18:31:52.616Z,1753813912.616 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  46.584399 min 
2025-07-29T18:31:52.616Z,1753813912.616 [Default:CheckIn:E] Stopped
2025-07-29T18:31:52.616Z,1753813912.616 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-29T18:31:52.616Z,1753813912.616 [Default:CheckIn] Stopped
2025-07-29T18:31:52.616Z,1753813912.616 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-29T18:31:52.617Z,1753813912.617 [Default:CheckIn](INFO): Running loop #6
2025-07-29T18:31:52.617Z,1753813912.617 [Default:CheckIn] Running Loop=6
2025-07-29T18:31:52.617Z,1753813912.617 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-29T18:31:52.617Z,1753813912.617 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-29T18:31:54.612Z,1753813914.612 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183153.00,A,3648.14879,N,12147.22047,W,0.019,96.53,290725,,,D*46
2025-07-29T18:31:54.614Z,1753813914.614 [NAL9602](INFO): GPS fix at 20250729T183153: (36.802480, -121.787008)
2025-07-29T18:31:54.624Z,1753813914.624 [Default:CheckIn:Read_GPS] Stopped
2025-07-29T18:31:54.624Z,1753813914.624 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-29T18:32:02.167Z,1753813922.167 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250729T174140/Courier0025.lzma
2025-07-29T18:32:04.711Z,1753813924.711 [NAL9602](INFO): SBD MO Status=0, MOMSN=10215, MT Status=0, MTMSN=0
2025-07-29T18:32:04.712Z,1753813924.712 [NAL9602](INFO): No messages in MT queue
2025-07-29T18:32:13.209Z,1753813933.209 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Courier0025.lzma.bak
2025-07-29T18:32:13.209Z,1753813933.209 [DataOverHttps](INFO): SBD MOMSN=25631146
2025-07-29T18:32:35.412Z,1753813955.412 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-29T18:32:39.035Z,1753813959.035 [DataOverHttps](INFO): Sending 161 bytes from file Logs/20250729T174140/Express0026.lzma
2025-07-29T18:32:50.077Z,1753813970.077 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Express0026.lzma.bak
2025-07-29T18:32:50.077Z,1753813970.077 [DataOverHttps](INFO): SBD MOMSN=25631153
2025-07-29T18:32:51.202Z,1753813971.202 [Default:CheckIn:Read_Iridium] Stopped
2025-07-29T18:32:51.202Z,1753813971.202 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-29T18:32:51.202Z,1753813971.202 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-29T18:37:51.758Z,1753814271.758 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-29T18:37:51.758Z,1753814271.758 [Default:CheckIn:C.Wait] Stopped
2025-07-29T18:37:51.758Z,1753814271.758 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-29T18:37:51.758Z,1753814271.758 [Default:CheckIn:D] Running Loop=1
2025-07-29T18:37:52.163Z,1753814272.163 [Default:CheckIn:D] Stopped
2025-07-29T18:37:52.163Z,1753814272.163 [Default:CheckIn:E] Running Loop=1
2025-07-29T18:37:52.578Z,1753814272.578 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  52.583671 min 
2025-07-29T18:37:52.578Z,1753814272.578 [Default:CheckIn:E] Stopped
2025-07-29T18:37:52.578Z,1753814272.578 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-29T18:37:52.578Z,1753814272.578 [Default:CheckIn] Stopped
2025-07-29T18:37:52.579Z,1753814272.579 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-29T18:37:52.579Z,1753814272.579 [Default:CheckIn](INFO): Running loop #7
2025-07-29T18:37:52.579Z,1753814272.579 [Default:CheckIn] Running Loop=7
2025-07-29T18:37:52.579Z,1753814272.579 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-29T18:37:52.579Z,1753814272.579 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-29T18:37:54.579Z,1753814274.579 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183753.00,A,3648.14843,N,12147.22330,W,0.000,96.53,290725,,,D*42
2025-07-29T18:37:54.581Z,1753814274.581 [NAL9602](INFO): GPS fix at 20250729T183753: (36.802474, -121.787055)
2025-07-29T18:37:54.608Z,1753814274.608 [Default:CheckIn:Read_GPS] Stopped
2025-07-29T18:37:54.608Z,1753814274.608 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-29T18:38:02.295Z,1753814282.295 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250729T174140/Courier0028.lzma
2025-07-29T18:38:07.105Z,1753814287.105 [NAL9602](INFO): SBD MO Status=0, MOMSN=10216, MT Status=0, MTMSN=0
2025-07-29T18:38:07.105Z,1753814287.105 [NAL9602](INFO): No messages in MT queue
2025-07-29T18:38:13.337Z,1753814293.337 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Courier0028.lzma.bak
2025-07-29T18:38:13.337Z,1753814293.337 [DataOverHttps](INFO): SBD MOMSN=25631162
2025-07-29T18:38:37.816Z,1753814317.816 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-29T18:38:39.192Z,1753814319.192 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250729T174140/Express0029.lzma
2025-07-29T18:38:50.233Z,1753814330.233 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Express0029.lzma.bak
2025-07-29T18:38:50.233Z,1753814330.233 [DataOverHttps](INFO): SBD MOMSN=25631166
2025-07-29T18:38:51.560Z,1753814331.560 [Default:CheckIn:Read_Iridium] Stopped
2025-07-29T18:38:51.560Z,1753814331.560 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-29T18:38:51.560Z,1753814331.560 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-29T18:43:52.128Z,1753814632.128 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-29T18:43:52.128Z,1753814632.128 [Default:CheckIn:C.Wait] Stopped
2025-07-29T18:43:52.128Z,1753814632.128 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-29T18:43:52.128Z,1753814632.128 [Default:CheckIn:D] Running Loop=1
2025-07-29T18:43:52.544Z,1753814632.544 [Default:CheckIn:D] Stopped
2025-07-29T18:43:52.544Z,1753814632.544 [Default:CheckIn:E] Running Loop=1
2025-07-29T18:43:52.938Z,1753814632.938 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  58.590023 min 
2025-07-29T18:43:52.938Z,1753814632.938 [Default:CheckIn:E] Stopped
2025-07-29T18:43:52.938Z,1753814632.938 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-29T18:43:52.938Z,1753814632.938 [Default:CheckIn] Stopped
2025-07-29T18:43:52.938Z,1753814632.938 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-29T18:43:52.939Z,1753814632.939 [Default:CheckIn](INFO): Running loop #8
2025-07-29T18:43:52.939Z,1753814632.939 [Default:CheckIn] Running Loop=8
2025-07-29T18:43:52.939Z,1753814632.939 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-29T18:43:52.939Z,1753814632.939 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-29T18:43:54.954Z,1753814634.954 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184354.00,A,3648.15026,N,12147.22535,W,0.019,96.53,290725,,,D*47
2025-07-29T18:43:54.957Z,1753814634.957 [NAL9602](INFO): GPS fix at 20250729T184354: (36.802504, -121.787089)
2025-07-29T18:43:54.967Z,1753814634.967 [Default:CheckIn:Read_GPS] Stopped
2025-07-29T18:43:54.967Z,1753814634.967 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-29T18:44:02.335Z,1753814642.335 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250729T174140/Courier0031.lzma
2025-07-29T18:44:13.377Z,1753814653.377 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Courier0031.lzma.bak
2025-07-29T18:44:13.377Z,1753814653.377 [DataOverHttps](INFO): SBD MOMSN=25631175
2025-07-29T18:44:20.400Z,1753814660.400 [NAL9602](INFO): SBD MO Status=0, MOMSN=10217, MT Status=0, MTMSN=0
2025-07-29T18:44:20.400Z,1753814660.400 [NAL9602](INFO): No messages in MT queue
2025-07-29T18:44:39.303Z,1753814679.303 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250729T174140/Express0032.lzma
2025-07-29T18:44:50.345Z,1753814690.345 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Express0032.lzma.bak
2025-07-29T18:44:50.345Z,1753814690.345 [DataOverHttps](INFO): SBD MOMSN=25631178
2025-07-29T18:44:51.100Z,1753814691.100 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-29T18:44:51.518Z,1753814691.518 [Default:CheckIn:Read_Iridium] Stopped
2025-07-29T18:44:51.518Z,1753814691.518 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-29T18:44:51.518Z,1753814691.518 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-29T18:49:52.090Z,1753814992.090 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-29T18:49:52.090Z,1753814992.090 [Default:CheckIn:C.Wait] Stopped
2025-07-29T18:49:52.091Z,1753814992.091 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-29T18:49:52.091Z,1753814992.091 [Default:CheckIn:D] Running Loop=1
2025-07-29T18:49:52.497Z,1753814992.497 [Default:CheckIn:D] Stopped
2025-07-29T18:49:52.497Z,1753814992.497 [Default:CheckIn:E] Running Loop=1
2025-07-29T18:49:52.917Z,1753814992.917 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  64.589250 min 
2025-07-29T18:49:52.917Z,1753814992.917 [Default:CheckIn:E] Stopped
2025-07-29T18:49:52.917Z,1753814992.917 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-29T18:49:52.917Z,1753814992.917 [Default:CheckIn] Stopped
2025-07-29T18:49:52.917Z,1753814992.917 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-29T18:49:52.918Z,1753814992.918 [Default:CheckIn](INFO): Running loop #9
2025-07-29T18:49:52.918Z,1753814992.918 [Default:CheckIn] Running Loop=9
2025-07-29T18:49:52.918Z,1753814992.918 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-29T18:49:52.918Z,1753814992.918 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-29T18:49:54.918Z,1753814994.918 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184954.00,A,3648.14968,N,12147.22633,W,0.058,96.53,290725,,,D*4F
2025-07-29T18:49:54.922Z,1753814994.922 [NAL9602](INFO): GPS fix at 20250729T184954: (36.802495, -121.787105)
2025-07-29T18:49:54.949Z,1753814994.949 [Default:CheckIn:Read_GPS] Stopped
2025-07-29T18:49:54.949Z,1753814994.949 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-29T18:50:02.531Z,1753815002.531 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250729T174140/Courier0034.lzma
2025-07-29T18:50:07.851Z,1753815007.851 [NAL9602](INFO): SBD MO Status=0, MOMSN=10218, MT Status=0, MTMSN=0
2025-07-29T18:50:07.851Z,1753815007.851 [NAL9602](INFO): No messages in MT queue
2025-07-29T18:50:13.573Z,1753815013.573 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Courier0034.lzma.bak
2025-07-29T18:50:13.573Z,1753815013.573 [DataOverHttps](INFO): SBD MOMSN=25631188
2025-07-29T18:50:38.548Z,1753815038.548 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-29T18:50:39.672Z,1753815039.672 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250729T174140/Express0035.lzma
2025-07-29T18:50:50.713Z,1753815050.713 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Express0035.lzma.bak
2025-07-29T18:50:50.713Z,1753815050.713 [DataOverHttps](INFO): SBD MOMSN=25631192
2025-07-29T18:50:51.889Z,1753815051.889 [Default:CheckIn:Read_Iridium] Stopped
2025-07-29T18:50:51.889Z,1753815051.889 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-29T18:50:51.889Z,1753815051.889 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-29T18:53:38.723Z,1753815218.723 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-07-29T18:53:39.937Z,1753815219.937 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-29T18:53:53.671Z,1753815233.671 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-07-29T18:53:55.287Z,1753815235.287 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-29T18:54:09.025Z,1753815249.025 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-07-29T18:54:10.236Z,1753815250.236 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-29T18:54:24.375Z,1753815264.375 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-07-29T18:54:25.587Z,1753815265.587 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-29T18:54:39.326Z,1753815279.326 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-07-29T18:54:40.535Z,1753815280.535 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-29T18:54:54.271Z,1753815294.271 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-07-29T18:54:55.483Z,1753815295.483 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-29T18:55:09.221Z,1753815309.221 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-07-29T18:55:10.431Z,1753815310.431 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-29T18:55:24.167Z,1753815324.167 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-07-29T18:55:26.600Z,1753815326.600 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-29T18:55:38.711Z,1753815338.711 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-07-29T18:55:41.137Z,1753815341.137 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7).
2025-07-29T18:55:52.465Z,1753815352.465 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-29T18:55:52.465Z,1753815352.465 [Default:CheckIn:C.Wait] Stopped
2025-07-29T18:55:52.465Z,1753815352.465 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-29T18:55:52.466Z,1753815352.466 [Default:CheckIn:D] Running Loop=1
2025-07-29T18:55:52.910Z,1753815352.910 [Default:CheckIn:D] Stopped
2025-07-29T18:55:52.910Z,1753815352.910 [Default:CheckIn:E] Running Loop=1
2025-07-29T18:55:53.256Z,1753815353.256 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-07-29T18:55:53.263Z,1753815353.263 [BPC1](INFO): Calculating totals. Valid battery stick count: 44. Valid reserve battery stick count: 5.
2025-07-29T18:55:53.266Z,1753815353.266 [BPC1](INFO): Received data from all battery sticks.
2025-07-29T18:55:53.278Z,1753815353.278 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  70.596118 min 
2025-07-29T18:55:53.278Z,1753815353.278 [Default:CheckIn:E] Stopped
2025-07-29T18:55:53.278Z,1753815353.278 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-29T18:55:53.278Z,1753815353.278 [Default:CheckIn] Stopped
2025-07-29T18:55:53.279Z,1753815353.279 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-29T18:55:53.299Z,1753815353.299 [Default:CheckIn](INFO): Running loop #10
2025-07-29T18:55:53.299Z,1753815353.299 [Default:CheckIn] Running Loop=10
2025-07-29T18:55:53.300Z,1753815353.300 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-29T18:55:53.300Z,1753815353.300 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-29T18:55:55.285Z,1753815355.285 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185554.00,A,3648.14915,N,12147.22092,W,0.019,0.00,290725,,,D*79
2025-07-29T18:55:55.287Z,1753815355.287 [NAL9602](INFO): GPS fix at 20250729T185554: (36.802486, -121.787015)
2025-07-29T18:55:55.297Z,1753815355.297 [Default:CheckIn:Read_GPS] Stopped
2025-07-29T18:55:55.297Z,1753815355.297 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-29T18:56:02.273Z,1753815362.273 [DataOverHttps](INFO): Sending 82 bytes from file Logs/20250729T174140/Courier0037.lzma
2025-07-29T18:56:13.313Z,1753815373.313 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Courier0037.lzma.bak
2025-07-29T18:56:13.313Z,1753815373.313 [DataOverHttps](INFO): SBD MOMSN=25631201
2025-07-29T18:56:32.050Z,1753815392.050 [NAL9602](INFO): SBD MO Status=2, MOMSN=10219, MT Status=2, MTMSN=0
2025-07-29T18:56:32.050Z,1753815392.050 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-29T18:56:39.259Z,1753815399.259 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250729T174140/Express0038.lzma
2025-07-29T18:56:50.301Z,1753815410.301 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Express0038.lzma.bak
2025-07-29T18:56:50.301Z,1753815410.301 [DataOverHttps](INFO): SBD MOMSN=25631204
2025-07-29T18:56:51.452Z,1753815411.452 [Default:CheckIn:Read_Iridium] Stopped
2025-07-29T18:56:51.452Z,1753815411.452 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-29T18:56:51.452Z,1753815411.452 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-29T18:57:02.754Z,1753815422.754 [NAL9602](INFO): SBD MO Status=0, MOMSN=10219, MT Status=0, MTMSN=0
2025-07-29T18:57:02.754Z,1753815422.754 [NAL9602](INFO): No messages in MT queue
2025-07-29T18:57:33.456Z,1753815453.456 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-29T19:01:52.029Z,1753815712.029 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-29T19:01:52.029Z,1753815712.029 [Default:CheckIn:C.Wait] Stopped
2025-07-29T19:01:52.029Z,1753815712.029 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-29T19:01:52.029Z,1753815712.029 [Default:CheckIn:D] Running Loop=1
2025-07-29T19:01:52.422Z,1753815712.422 [Default:CheckIn:D] Stopped
2025-07-29T19:01:52.422Z,1753815712.422 [Default:CheckIn:E] Running Loop=1
2025-07-29T19:01:52.834Z,1753815712.834 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  76.587988 min 
2025-07-29T19:01:52.834Z,1753815712.834 [Default:CheckIn:E] Stopped
2025-07-29T19:01:52.834Z,1753815712.834 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-29T19:01:52.834Z,1753815712.834 [Default:CheckIn] Stopped
2025-07-29T19:01:52.834Z,1753815712.834 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-29T19:01:52.835Z,1753815712.835 [Default:CheckIn](INFO): Running loop #11
2025-07-29T19:01:52.835Z,1753815712.835 [Default:CheckIn] Running Loop=11
2025-07-29T19:01:52.835Z,1753815712.835 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-29T19:01:52.835Z,1753815712.835 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-29T19:01:54.841Z,1753815714.841 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190154.00,A,3648.15027,N,12147.22573,W,0.078,0.00,290725,,,D*7D
2025-07-29T19:01:54.844Z,1753815714.844 [NAL9602](INFO): GPS fix at 20250729T190154: (36.802504, -121.787096)
2025-07-29T19:01:54.853Z,1753815714.853 [Default:CheckIn:Read_GPS] Stopped
2025-07-29T19:01:54.853Z,1753815714.853 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-29T19:02:02.275Z,1753815722.275 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250729T174140/Courier0040.lzma
2025-07-29T19:02:13.317Z,1753815733.317 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Courier0040.lzma.bak
2025-07-29T19:02:13.317Z,1753815733.317 [DataOverHttps](INFO): SBD MOMSN=25631217
2025-07-29T19:02:17.482Z,1753815737.482 [NAL9602](INFO): SBD MO Status=0, MOMSN=10220, MT Status=0, MTMSN=0
2025-07-29T19:02:17.482Z,1753815737.482 [NAL9602](INFO): No messages in MT queue
2025-07-29T19:02:39.205Z,1753815759.205 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20250729T174140/Express0041.lzma
2025-07-29T19:02:48.165Z,1753815768.165 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-29T19:02:50.245Z,1753815770.245 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Express0041.lzma.bak
2025-07-29T19:02:50.245Z,1753815770.245 [DataOverHttps](INFO): SBD MOMSN=25631221
2025-07-29T19:02:51.415Z,1753815771.415 [Default:CheckIn:Read_Iridium] Stopped
2025-07-29T19:02:51.415Z,1753815771.415 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-29T19:02:51.431Z,1753815771.431 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-29T19:07:51.983Z,1753816071.983 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-29T19:07:51.983Z,1753816071.983 [Default:CheckIn:C.Wait] Stopped
2025-07-29T19:07:51.983Z,1753816071.983 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-29T19:07:51.983Z,1753816071.983 [Default:CheckIn:D] Running Loop=1
2025-07-29T19:07:52.394Z,1753816072.394 [Default:CheckIn:D] Stopped
2025-07-29T19:07:52.394Z,1753816072.394 [Default:CheckIn:E] Running Loop=1
2025-07-29T19:07:52.802Z,1753816072.802 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  82.587516 min 
2025-07-29T19:07:52.802Z,1753816072.802 [Default:CheckIn:E] Stopped
2025-07-29T19:07:52.803Z,1753816072.803 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-29T19:07:52.803Z,1753816072.803 [Default:CheckIn] Stopped
2025-07-29T19:07:52.803Z,1753816072.803 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-29T19:07:52.804Z,1753816072.804 [Default:CheckIn](INFO): Running loop #12
2025-07-29T19:07:52.804Z,1753816072.804 [Default:CheckIn] Running Loop=12
2025-07-29T19:07:52.804Z,1753816072.804 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-29T19:07:52.804Z,1753816072.804 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-29T19:07:54.803Z,1753816074.803 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190754.00,A,3648.15248,N,12147.22318,W,0.078,37.53,290725,,,D*49
2025-07-29T19:07:54.805Z,1753816074.805 [NAL9602](INFO): GPS fix at 20250729T190754: (36.802541, -121.787053)
2025-07-29T19:07:54.815Z,1753816074.815 [Default:CheckIn:Read_GPS] Stopped
2025-07-29T19:07:54.815Z,1753816074.815 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-29T19:08:02.391Z,1753816082.391 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250729T174140/Courier0043.lzma
2025-07-29T19:08:13.433Z,1753816093.433 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Courier0043.lzma.bak
2025-07-29T19:08:13.433Z,1753816093.433 [DataOverHttps](INFO): SBD MOMSN=25631230
2025-07-29T19:08:14.598Z,1753816094.598 [NAL9602](INFO): SBD MO Status=0, MOMSN=10221, MT Status=0, MTMSN=0
2025-07-29T19:08:14.598Z,1753816094.598 [NAL9602](INFO): No messages in MT queue
2025-07-29T19:08:39.208Z,1753816119.208 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250729T174140/Express0044.lzma
2025-07-29T19:08:45.304Z,1753816125.304 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-29T19:08:50.250Z,1753816130.250 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Express0044.lzma.bak
2025-07-29T19:08:50.250Z,1753816130.250 [DataOverHttps](INFO): SBD MOMSN=25631234
2025-07-29T19:08:51.371Z,1753816131.371 [Default:CheckIn:Read_Iridium] Stopped
2025-07-29T19:08:51.372Z,1753816131.372 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-29T19:08:51.372Z,1753816131.372 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-29T19:13:51.950Z,1753816431.950 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-29T19:13:51.950Z,1753816431.950 [Default:CheckIn:C.Wait] Stopped
2025-07-29T19:13:51.950Z,1753816431.950 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-29T19:13:51.950Z,1753816431.950 [Default:CheckIn:D] Running Loop=1
2025-07-29T19:13:52.360Z,1753816432.360 [Default:CheckIn:D] Stopped
2025-07-29T19:13:52.360Z,1753816432.360 [Default:CheckIn:E] Running Loop=1
2025-07-29T19:13:52.776Z,1753816432.776 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  88.586963 min 
2025-07-29T19:13:52.776Z,1753816432.776 [Default:CheckIn:E] Stopped
2025-07-29T19:13:52.776Z,1753816432.776 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-29T19:13:52.776Z,1753816432.776 [Default:CheckIn] Stopped
2025-07-29T19:13:52.776Z,1753816432.776 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-29T19:13:52.776Z,1753816432.776 [Default:CheckIn](INFO): Running loop #13
2025-07-29T19:13:52.776Z,1753816432.776 [Default:CheckIn] Running Loop=13
2025-07-29T19:13:52.776Z,1753816432.776 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-29T19:13:52.776Z,1753816432.776 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-29T19:13:54.767Z,1753816434.767 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191354.00,A,3648.15005,N,12147.21917,W,0.019,72.40,290725,,,D*45
2025-07-29T19:13:54.770Z,1753816434.770 [NAL9602](INFO): GPS fix at 20250729T191354: (36.802501, -121.786986)
2025-07-29T19:13:54.780Z,1753816434.780 [Default:CheckIn:Read_GPS] Stopped
2025-07-29T19:13:54.781Z,1753816434.781 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-29T19:14:01.638Z,1753816441.638 [NAL9602](INFO): SBD MO Status=0, MOMSN=10222, MT Status=0, MTMSN=0
2025-07-29T19:14:01.638Z,1753816441.638 [NAL9602](INFO): No messages in MT queue
2025-07-29T19:14:02.335Z,1753816442.335 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250729T174140/Courier0046.lzma
2025-07-29T19:14:13.377Z,1753816453.377 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Courier0046.lzma.bak
2025-07-29T19:14:13.377Z,1753816453.377 [DataOverHttps](INFO): SBD MOMSN=25631243
2025-07-29T19:14:32.359Z,1753816472.359 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-29T19:14:39.191Z,1753816479.191 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20250729T174140/Express0047.lzma
2025-07-29T19:14:50.234Z,1753816490.234 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Express0047.lzma.bak
2025-07-29T19:14:50.234Z,1753816490.234 [DataOverHttps](INFO): SBD MOMSN=25631253
2025-07-29T19:14:51.345Z,1753816491.345 [Default:CheckIn:Read_Iridium] Stopped
2025-07-29T19:14:51.346Z,1753816491.346 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-29T19:14:51.346Z,1753816491.346 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-29T19:19:51.925Z,1753816791.925 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-29T19:19:51.925Z,1753816791.925 [Default:CheckIn:C.Wait] Stopped
2025-07-29T19:19:51.925Z,1753816791.925 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-29T19:19:51.925Z,1753816791.925 [Default:CheckIn:D] Running Loop=1
2025-07-29T19:19:52.318Z,1753816792.318 [Default:CheckIn:D] Stopped
2025-07-29T19:19:52.318Z,1753816792.318 [Default:CheckIn:E] Running Loop=1
2025-07-29T19:19:52.724Z,1753816792.724 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  94.586255 min 
2025-07-29T19:19:52.724Z,1753816792.724 [Default:CheckIn:E] Stopped
2025-07-29T19:19:52.725Z,1753816792.725 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-29T19:19:52.725Z,1753816792.725 [Default:CheckIn] Stopped
2025-07-29T19:19:52.725Z,1753816792.725 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-29T19:19:52.725Z,1753816792.725 [Default:CheckIn](INFO): Running loop #14
2025-07-29T19:19:52.725Z,1753816792.725 [Default:CheckIn] Running Loop=14
2025-07-29T19:19:52.725Z,1753816792.725 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-29T19:19:52.725Z,1753816792.725 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-29T19:19:54.739Z,1753816794.739 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,191954.00,A,3648.15245,N,12147.21978,W,0.039,72.40,290725,,,D*42
2025-07-29T19:19:54.741Z,1753816794.741 [NAL9602](INFO): GPS fix at 20250729T191954: (36.802541, -121.786996)
2025-07-29T19:19:54.751Z,1753816794.751 [Default:CheckIn:Read_GPS] Stopped
2025-07-29T19:19:54.751Z,1753816794.751 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-29T19:20:02.247Z,1753816802.247 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250729T174140/Courier0049.lzma
2025-07-29T19:20:13.289Z,1753816813.289 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Courier0049.lzma.bak
2025-07-29T19:20:13.289Z,1753816813.289 [DataOverHttps](INFO): SBD MOMSN=25631262
2025-07-29T19:20:27.863Z,1753816827.863 [NAL9602](INFO): SBD MO Status=0, MOMSN=10223, MT Status=0, MTMSN=0
2025-07-29T19:20:27.863Z,1753816827.863 [NAL9602](INFO): No messages in MT queue
2025-07-29T19:20:39.137Z,1753816839.137 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250729T174140/Express0050.lzma
2025-07-29T19:20:50.178Z,1753816850.178 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Express0050.lzma.bak
2025-07-29T19:20:50.178Z,1753816850.178 [DataOverHttps](INFO): SBD MOMSN=25631265
2025-07-29T19:20:51.304Z,1753816851.304 [Default:CheckIn:Read_Iridium] Stopped
2025-07-29T19:20:51.304Z,1753816851.304 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-29T19:20:51.305Z,1753816851.305 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-29T19:20:58.572Z,1753816858.572 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-29T19:25:51.880Z,1753817151.880 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-29T19:25:51.880Z,1753817151.880 [Default:CheckIn:C.Wait] Stopped
2025-07-29T19:25:51.880Z,1753817151.880 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-29T19:25:51.880Z,1753817151.880 [Default:CheckIn:D] Running Loop=1
2025-07-29T19:25:52.285Z,1753817152.285 [Default:CheckIn:D] Stopped
2025-07-29T19:25:52.285Z,1753817152.285 [Default:CheckIn:E] Running Loop=1
2025-07-29T19:25:52.696Z,1753817152.696 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  100.585710 min 
2025-07-29T19:25:52.696Z,1753817152.696 [Default:CheckIn:E] Stopped
2025-07-29T19:25:52.696Z,1753817152.696 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-29T19:25:52.696Z,1753817152.696 [Default:CheckIn] Stopped
2025-07-29T19:25:52.696Z,1753817152.696 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-29T19:25:52.696Z,1753817152.696 [Default:CheckIn](INFO): Running loop #15
2025-07-29T19:25:52.696Z,1753817152.696 [Default:CheckIn] Running Loop=15
2025-07-29T19:25:52.696Z,1753817152.696 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-29T19:25:52.697Z,1753817152.697 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-29T19:25:54.702Z,1753817154.702 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,192553.00,A,3648.15992,N,12147.21200,W,1.341,35.24,290725,,,D*43
2025-07-29T19:25:54.705Z,1753817154.705 [NAL9602](INFO): GPS fix at 20250729T192553: (36.802665, -121.786867)
2025-07-29T19:25:54.715Z,1753817154.715 [Default:CheckIn:Read_GPS] Stopped
2025-07-29T19:25:54.715Z,1753817154.715 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-29T19:26:02.414Z,1753817162.414 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250729T174140/Courier0052.lzma
2025-07-29T19:26:13.297Z,1753817173.297 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Courier0052.lzma.bak
2025-07-29T19:26:13.297Z,1753817173.297 [DataOverHttps](INFO): SBD MOMSN=25631275
2025-07-29T19:26:20.554Z,1753817180.554 [NAL9602](INFO): SBD MO Status=2, MOMSN=10224, MT Status=2, MTMSN=0
2025-07-29T19:26:20.554Z,1753817180.554 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-07-29T19:26:33.094Z,1753817193.094 [NAL9602](INFO): SBD MO Status=0, MOMSN=10224, MT Status=0, MTMSN=0
2025-07-29T19:26:33.094Z,1753817193.094 [NAL9602](INFO): No messages in MT queue
2025-07-29T19:26:39.111Z,1753817199.111 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250729T174140/Express0053.lzma
2025-07-29T19:26:50.153Z,1753817210.153 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Express0053.lzma.bak
2025-07-29T19:26:50.153Z,1753817210.153 [DataOverHttps](INFO): SBD MOMSN=25631278
2025-07-29T19:26:51.270Z,1753817211.270 [Default:CheckIn:Read_Iridium] Stopped
2025-07-29T19:26:51.270Z,1753817211.270 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-29T19:26:51.270Z,1753817211.270 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-29T19:27:03.805Z,1753817223.805 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-29T19:31:51.878Z,1753817511.878 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-29T19:31:51.878Z,1753817511.878 [Default:CheckIn:C.Wait] Stopped
2025-07-29T19:31:51.878Z,1753817511.878 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-29T19:31:51.878Z,1753817511.878 [Default:CheckIn:D] Running Loop=1
2025-07-29T19:31:52.273Z,1753817512.273 [Default:CheckIn:D] Stopped
2025-07-29T19:31:52.273Z,1753817512.273 [Default:CheckIn:E] Running Loop=1
2025-07-29T19:31:52.681Z,1753817512.681 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  106.585514 min 
2025-07-29T19:31:52.681Z,1753817512.681 [Default:CheckIn:E] Stopped
2025-07-29T19:31:52.681Z,1753817512.681 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-29T19:31:52.681Z,1753817512.681 [Default:CheckIn] Stopped
2025-07-29T19:31:52.681Z,1753817512.681 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-29T19:31:52.681Z,1753817512.681 [Default:CheckIn](INFO): Running loop #16
2025-07-29T19:31:52.681Z,1753817512.681 [Default:CheckIn] Running Loop=16
2025-07-29T19:31:52.682Z,1753817512.682 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-29T19:31:52.682Z,1753817512.682 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-29T19:31:54.705Z,1753817514.705 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193153.00,A,3648.51483,N,12147.12035,W,4.898,329.92,290725,,,D*76
2025-07-29T19:31:54.707Z,1753817514.707 [NAL9602](INFO): GPS fix at 20250729T193153: (36.808580, -121.785339)
2025-07-29T19:31:54.717Z,1753817514.717 [Default:CheckIn:Read_GPS] Stopped
2025-07-29T19:31:54.717Z,1753817514.717 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-29T19:32:02.135Z,1753817522.135 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250729T174140/Courier0055.lzma
2025-07-29T19:32:06.406Z,1753817526.406 [NAL9602](INFO): SBD MO Status=0, MOMSN=10225, MT Status=0, MTMSN=0
2025-07-29T19:32:06.406Z,1753817526.406 [NAL9602](INFO): No messages in MT queue
2025-07-29T19:32:13.177Z,1753817533.177 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Courier0055.lzma.bak
2025-07-29T19:32:13.177Z,1753817533.177 [DataOverHttps](INFO): SBD MOMSN=25631288
2025-07-29T19:32:37.112Z,1753817557.112 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-29T19:32:39.059Z,1753817559.059 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20250729T174140/Express0056.lzma
2025-07-29T19:32:50.101Z,1753817570.101 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Express0056.lzma.bak
2025-07-29T19:32:50.101Z,1753817570.101 [DataOverHttps](INFO): SBD MOMSN=25631291
2025-07-29T19:32:51.259Z,1753817571.259 [Default:CheckIn:Read_Iridium] Stopped
2025-07-29T19:32:51.259Z,1753817571.259 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-29T19:32:51.259Z,1753817571.259 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-29T19:37:51.837Z,1753817871.837 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-29T19:37:51.838Z,1753817871.838 [Default:CheckIn:C.Wait] Stopped
2025-07-29T19:37:51.838Z,1753817871.838 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-29T19:37:51.838Z,1753817871.838 [Default:CheckIn:D] Running Loop=1
2025-07-29T19:37:52.238Z,1753817872.238 [Default:CheckIn:D] Stopped
2025-07-29T19:37:52.238Z,1753817872.238 [Default:CheckIn:E] Running Loop=1
2025-07-29T19:37:52.646Z,1753817872.646 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  112.584928 min 
2025-07-29T19:37:52.646Z,1753817872.646 [Default:CheckIn:E] Stopped
2025-07-29T19:37:52.646Z,1753817872.646 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-29T19:37:52.646Z,1753817872.646 [Default:CheckIn] Stopped
2025-07-29T19:37:52.646Z,1753817872.646 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-29T19:37:52.646Z,1753817872.646 [Default:CheckIn](INFO): Running loop #17
2025-07-29T19:37:52.646Z,1753817872.646 [Default:CheckIn] Running Loop=17
2025-07-29T19:37:52.647Z,1753817872.647 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-29T19:37:52.647Z,1753817872.647 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-29T19:37:54.656Z,1753817874.656 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,193753.00,A,3648.27899,N,12147.90811,W,17.806,258.98,290725,,,D*4A
2025-07-29T19:37:54.658Z,1753817874.658 [NAL9602](INFO): GPS fix at 20250729T193753: (36.804650, -121.798468)
2025-07-29T19:37:54.689Z,1753817874.689 [Default:CheckIn:Read_GPS] Stopped
2025-07-29T19:37:54.689Z,1753817874.689 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-29T19:38:02.091Z,1753817882.091 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250729T174140/Courier0058.lzma
2025-07-29T19:38:12.028Z,1753817892.028 [NAL9602](INFO): SBD MO Status=0, MOMSN=10226, MT Status=0, MTMSN=0
2025-07-29T19:38:12.028Z,1753817892.028 [NAL9602](INFO): No messages in MT queue
2025-07-29T19:38:13.133Z,1753817893.133 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Courier0058.lzma.bak
2025-07-29T19:38:13.133Z,1753817893.133 [DataOverHttps](INFO): SBD MOMSN=25631301
2025-07-29T19:38:39.156Z,1753817919.156 [DataOverHttps](INFO): Sending 167 bytes from file Logs/20250729T174140/Express0059.lzma
2025-07-29T19:38:42.732Z,1753817922.732 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-29T19:38:50.197Z,1753817930.197 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Express0059.lzma.bak
2025-07-29T19:38:50.197Z,1753817930.197 [DataOverHttps](INFO): SBD MOMSN=25631304
2025-07-29T19:38:51.255Z,1753817931.255 [Default:CheckIn:Read_Iridium] Stopped
2025-07-29T19:38:51.256Z,1753817931.256 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-29T19:38:51.256Z,1753817931.256 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-29T19:42:19.412Z,1753818139.412 [DataOverHttps](INFO): Exceeded connection timeout, disconnecting.
2025-07-29T19:42:39.839Z,1753818159.839 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.982140
2025-07-29T19:43:19.380Z,1753818199.380 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002967
2025-07-29T19:43:51.814Z,1753818231.814 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-29T19:43:51.814Z,1753818231.814 [Default:CheckIn:C.Wait] Stopped
2025-07-29T19:43:51.814Z,1753818231.814 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-29T19:43:51.814Z,1753818231.814 [Default:CheckIn:D] Running Loop=1
2025-07-29T19:43:52.206Z,1753818232.206 [Default:CheckIn:D] Stopped
2025-07-29T19:43:52.206Z,1753818232.206 [Default:CheckIn:E] Running Loop=1
2025-07-29T19:43:52.611Z,1753818232.611 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  118.584391 min 
2025-07-29T19:43:52.612Z,1753818232.612 [Default:CheckIn:E] Stopped
2025-07-29T19:43:52.612Z,1753818232.612 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-29T19:43:52.612Z,1753818232.612 [Default:CheckIn] Stopped
2025-07-29T19:43:52.612Z,1753818232.612 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-29T19:43:52.612Z,1753818232.612 [Default:CheckIn](INFO): Running loop #18
2025-07-29T19:43:52.612Z,1753818232.612 [Default:CheckIn] Running Loop=18
2025-07-29T19:43:52.612Z,1753818232.612 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-29T19:43:52.612Z,1753818232.612 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-29T19:43:54.636Z,1753818234.636 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,194353.00,A,3647.96552,N,12149.87922,W,15.531,260.25,290725,,,D*49
2025-07-29T19:43:54.638Z,1753818234.638 [NAL9602](INFO): GPS fix at 20250729T194353: (36.799425, -121.831320)
2025-07-29T19:43:54.653Z,1753818234.653 [Default:CheckIn:Read_GPS] Stopped
2025-07-29T19:43:54.653Z,1753818234.653 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-29T19:44:05.339Z,1753818245.339 [DataOverHttps](INFO): Sending 72 bytes from file Logs/20250729T174140/Courier0061.lzma
2025-07-29T19:44:06.742Z,1753818246.742 [NAL9602](INFO): SBD MO Status=0, MOMSN=10227, MT Status=0, MTMSN=0
2025-07-29T19:44:06.742Z,1753818246.742 [NAL9602](INFO): No messages in MT queue
2025-07-29T19:44:24.413Z,1753818264.413 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Courier0061.lzma.bak
2025-07-29T19:44:24.413Z,1753818264.413 [DataOverHttps](INFO): SBD MOMSN=25631317
2025-07-29T19:44:37.453Z,1753818277.453 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-29T19:44:50.216Z,1753818290.216 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20250729T174140/Express0062.lzma
2025-07-29T19:45:01.257Z,1753818301.257 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Express0062.lzma.bak
2025-07-29T19:45:01.257Z,1753818301.257 [DataOverHttps](INFO): SBD MOMSN=25631321
2025-07-29T19:45:02.519Z,1753818302.519 [Default:CheckIn:Read_Iridium] Stopped
2025-07-29T19:45:02.519Z,1753818302.519 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-29T19:45:02.519Z,1753818302.519 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-29T19:50:03.090Z,1753818603.090 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-29T19:50:03.091Z,1753818603.091 [Default:CheckIn:C.Wait] Stopped
2025-07-29T19:50:03.091Z,1753818603.091 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-29T19:50:03.091Z,1753818603.091 [Default:CheckIn:D] Running Loop=1
2025-07-29T19:50:03.486Z,1753818603.486 [Default:CheckIn:D] Stopped
2025-07-29T19:50:03.486Z,1753818603.486 [Default:CheckIn:E] Running Loop=1
2025-07-29T19:50:03.892Z,1753818603.892 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  124.772388 min 
2025-07-29T19:50:03.892Z,1753818603.892 [Default:CheckIn:E] Stopped
2025-07-29T19:50:03.892Z,1753818603.892 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-29T19:50:03.892Z,1753818603.892 [Default:CheckIn] Stopped
2025-07-29T19:50:03.892Z,1753818603.892 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-29T19:50:03.892Z,1753818603.892 [Default:CheckIn](INFO): Running loop #19
2025-07-29T19:50:03.892Z,1753818603.892 [Default:CheckIn] Running Loop=19
2025-07-29T19:50:03.892Z,1753818603.892 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-29T19:50:03.892Z,1753818603.892 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-29T19:50:05.901Z,1753818605.901 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,195005.00,A,3647.73436,N,12150.91056,W,1.108,111.17,290725,,,D*7A
2025-07-29T19:50:05.903Z,1753818605.903 [NAL9602](INFO): GPS fix at 20250729T195005: (36.795573, -121.848509)
2025-07-29T19:50:05.913Z,1753818605.913 [Default:CheckIn:Read_GPS] Stopped
2025-07-29T19:50:05.913Z,1753818605.913 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-29T19:50:13.479Z,1753818613.479 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250729T174140/Courier0064.lzma
2025-07-29T19:50:17.624Z,1753818617.624 [NAL9602](INFO): SBD MO Status=0, MOMSN=10228, MT Status=0, MTMSN=0
2025-07-29T19:50:17.624Z,1753818617.624 [NAL9602](INFO): No messages in MT queue
2025-07-29T19:50:24.521Z,1753818624.521 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Courier0064.lzma.bak
2025-07-29T19:50:24.521Z,1753818624.521 [DataOverHttps](INFO): SBD MOMSN=25631330
2025-07-29T19:50:48.320Z,1753818648.320 [NAL9602](INFO): Not Powering down - fast GPS
2025-07-29T19:51:02.555Z,1753818662.555 [DataOverHttps](INFO): Sending 170 bytes from file Logs/20250729T174140/Express0065.lzma
2025-07-29T19:51:13.597Z,1753818673.597 [DataOverHttps](INFO): Moved sent file to Logs/20250729T174140/Express0065.lzma.bak
2025-07-29T19:51:13.597Z,1753818673.597 [DataOverHttps](INFO): SBD MOMSN=25631334
2025-07-29T19:51:14.999Z,1753818674.999 [Default:CheckIn:Read_Iridium] Stopped
2025-07-29T19:51:14.999Z,1753818674.999 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-29T19:51:14.000Z,1753818675.000 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-29T19:52:43.177Z,1753818763.177 [DataOverHttps](IMPORTANT): SBD MTMSN=20250729T195242
2025-07-29T19:52:50.607Z,1753818770.607 [DataOverHttps](INFO): Received command: restart logs