2025-07-08T17:26:27.863Z,1751995587.863 [Supervisor](DEBUG): Initializing supervisor. 2025-07-08T17:26:27.867Z,1751995587.867 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2025-07-08T17:26:27.868Z,1751995587.868 [SyncHandler](INFO): Protected caller Thread ID is 1021 2025-07-08T17:26:27.868Z,1751995587.868 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2025-07-08T17:26:27.869Z,1751995587.869 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2025-07-08T17:26:27.869Z,1751995587.869 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1022 2025-07-08T17:26:27.873Z,1751995587.873 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2025-07-08T17:26:27.893Z,1751995587.893 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2025-07-08T17:26:27.895Z,1751995587.895 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2025-07-08T17:26:27.895Z,1751995587.895 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1023 2025-07-08T17:26:27.899Z,1751995587.899 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2025-07-08T17:26:27.900Z,1751995587.900 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2025-07-08T17:26:27.901Z,1751995587.901 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1024 2025-07-08T17:26:27.903Z,1751995587.903 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2025-07-08T17:26:27.904Z,1751995587.904 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2025-07-08T17:26:27.904Z,1751995587.904 [logger ThreadHandler](INFO): Protected caller Thread ID is 1025 2025-07-08T17:26:27.908Z,1751995587.908 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2025-07-08T17:26:27.908Z,1751995587.908 [Supervisor](INFO): Looking for Config files in directory: Config/ 2025-07-08T17:26:27.910Z,1751995587.910 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2025-07-08T17:26:28.248Z,1751995588.248 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2025-07-08T17:26:28.249Z,1751995588.249 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2025-07-08T17:26:28.347Z,1751995588.347 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance 2025-07-08T17:26:28.348Z,1751995588.348 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2025-07-08T17:26:28.463Z,1751995588.463 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2025-07-08T17:26:28.464Z,1751995588.464 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2025-07-08T17:26:28.603Z,1751995588.603 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2025-07-08T17:26:28.604Z,1751995588.604 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2025-07-08T17:26:28.962Z,1751995588.962 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2025-07-08T17:26:28.962Z,1751995588.962 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2025-07-08T17:26:29.068Z,1751995589.068 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2025-07-08T17:26:29.068Z,1751995589.068 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2025-07-08T17:26:29.474Z,1751995589.474 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2025-07-08T17:26:29.475Z,1751995589.475 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2025-07-08T17:26:29.711Z,1751995589.711 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2025-07-08T17:26:29.712Z,1751995589.712 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2025-07-08T17:26:29.800Z,1751995589.800 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2025-07-08T17:26:30.050Z,1751995590.050 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2025-07-08T17:26:30.050Z,1751995590.050 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2025-07-08T17:26:30.280Z,1751995590.280 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2025-07-08T17:26:30.281Z,1751995590.281 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2025-07-08T17:26:30.824Z,1751995590.824 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2025-07-08T17:26:30.825Z,1751995590.825 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2025-07-08T17:26:31.024Z,1751995591.024 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2025-07-08T17:26:31.025Z,1751995591.025 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2025-07-08T17:26:31.315Z,1751995591.315 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2025-07-08T17:26:31.316Z,1751995591.316 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2025-07-08T17:26:32.163Z,1751995592.163 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2025-07-08T17:26:32.163Z,1751995592.163 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2025-07-08T17:26:32.744Z,1751995592.744 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2025-07-08T17:26:32.746Z,1751995592.746 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/ 2025-07-08T17:26:32.747Z,1751995592.747 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Control.cfg 2025-07-08T17:26:32.865Z,1751995592.865 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Science.cfg 2025-07-08T17:26:33.014Z,1751995593.014 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/secure.cfg 2025-07-08T17:26:33.106Z,1751995593.106 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Servo.cfg 2025-07-08T17:26:33.221Z,1751995593.221 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/BIT.cfg 2025-07-08T17:26:33.323Z,1751995593.323 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Battery.cfg 2025-07-08T17:26:33.597Z,1751995593.597 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2025-07-08T17:26:33.597Z,1751995593.597 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/vehicle.cfg 2025-07-08T17:26:33.715Z,1751995593.715 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/logger.cfg 2025-07-08T17:26:33.821Z,1751995593.821 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Sensor.cfg 2025-07-08T17:26:33.967Z,1751995593.967 [Supervisor](INFO): Opening Config file at: Config/lrauv-ahi/Simulator.cfg 2025-07-08T17:26:34.062Z,1751995594.062 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-ahi/root/ 2025-07-08T17:26:34.063Z,1751995594.063 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2025-07-08T17:26:34.072Z,1751995594.072 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2025-07-08T17:26:34.212Z,1751995594.212 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2025-07-08T17:26:34.212Z,1751995594.212 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2025-07-08T17:26:34.267Z,1751995594.267 [VerticalControl](DEBUG): Construct VerticalControl. 2025-07-08T17:26:34.330Z,1751995594.330 [VerticalControl] Loaded 2025-07-08T17:26:34.330Z,1751995594.330 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2025-07-08T17:26:34.333Z,1751995594.333 [HorizontalControl](DEBUG): Construct HorizontalControl. 2025-07-08T17:26:34.381Z,1751995594.381 [HorizontalControl] Loaded 2025-07-08T17:26:34.381Z,1751995594.381 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2025-07-08T17:26:34.384Z,1751995594.384 [SpeedControl](DEBUG): Construct SpeedControl. 2025-07-08T17:26:34.387Z,1751995594.387 [SpeedControl] Loaded 2025-07-08T17:26:34.387Z,1751995594.387 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2025-07-08T17:26:34.390Z,1751995594.390 [LoopControl](DEBUG): Construct LoopControl. 2025-07-08T17:26:34.391Z,1751995594.391 [LoopControl] Loaded 2025-07-08T17:26:34.391Z,1751995594.391 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2025-07-08T17:26:34.391Z,1751995594.391 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2025-07-08T17:26:34.392Z,1751995594.392 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2025-07-08T17:26:34.453Z,1751995594.453 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2025-07-08T17:26:34.453Z,1751995594.453 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2025-07-08T17:26:34.960Z,1751995594.960 [AHRS_M2] Loaded 2025-07-08T17:26:34.961Z,1751995594.961 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2025-07-08T17:26:34.000Z,1751995595.000 [BackseatComponent] Loaded 2025-07-08T17:26:35.000Z,1751995595.000 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2025-07-08T17:26:35.001Z,1751995595.001 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 408BA4E0 2025-07-08T17:26:35.002Z,1751995595.002 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1104 2025-07-08T17:26:35.005Z,1751995595.005 [LcmUniversalReporter] Loaded 2025-07-08T17:26:35.005Z,1751995595.005 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2025-07-08T17:26:35.846Z,1751995595.846 [BPC1] Loaded 2025-07-08T17:26:35.846Z,1751995595.846 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2025-07-08T17:26:35.945Z,1751995595.945 [DAT] Loaded 2025-07-08T17:26:35.946Z,1751995595.946 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2025-07-08T17:26:35.947Z,1751995595.947 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 408EA4E0 2025-07-08T17:26:35.947Z,1751995595.947 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1105 2025-07-08T17:26:36.020Z,1751995596.020 [DataOverHttps] Loaded 2025-07-08T17:26:36.020Z,1751995596.020 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2025-07-08T17:26:36.021Z,1751995596.021 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4091A4E0 2025-07-08T17:26:36.022Z,1751995596.022 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1106 2025-07-08T17:26:36.045Z,1751995596.045 [Depth_Keller] Loaded 2025-07-08T17:26:36.046Z,1751995596.046 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2025-07-08T17:26:36.051Z,1751995596.051 [DropWeight] Loaded 2025-07-08T17:26:36.051Z,1751995596.051 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2025-07-08T17:26:36.117Z,1751995596.117 [NAL9602] Loaded 2025-07-08T17:26:36.117Z,1751995596.117 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2025-07-08T17:26:36.147Z,1751995596.147 [Onboard] Loaded 2025-07-08T17:26:36.148Z,1751995596.148 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2025-07-08T17:26:36.149Z,1751995596.149 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4094A4E0 2025-07-08T17:26:36.149Z,1751995596.149 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1107 2025-07-08T17:26:36.163Z,1751995596.163 [Power24vConverter] Loaded 2025-07-08T17:26:36.163Z,1751995596.163 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2025-07-08T17:26:36.179Z,1751995596.179 [Radio_Surface] Loaded 2025-07-08T17:26:36.180Z,1751995596.180 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2025-07-08T17:26:36.181Z,1751995596.181 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4097A4E0 2025-07-08T17:26:36.181Z,1751995596.181 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1108 2025-07-08T17:26:36.197Z,1751995596.197 [Sonardyne_Nano] Loaded 2025-07-08T17:26:36.197Z,1751995596.197 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2025-07-08T17:26:36.198Z,1751995596.198 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2025-07-08T17:26:36.198Z,1751995596.198 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2025-07-08T17:26:36.209Z,1751995596.209 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2025-07-08T17:26:36.209Z,1751995596.209 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2025-07-08T17:26:36.239Z,1751995596.239 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2025-07-08T17:26:36.240Z,1751995596.240 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2025-07-08T17:26:36.405Z,1751995596.405 [SBIT](DEBUG): Construct Startup Built In Test. 2025-07-08T17:26:36.415Z,1751995596.415 [SBIT] Loaded 2025-07-08T17:26:36.415Z,1751995596.415 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2025-07-08T17:26:36.418Z,1751995596.418 [IBIT](DEBUG): Construct Initiated Built In Test. 2025-07-08T17:26:36.431Z,1751995596.431 [IBIT] Loaded 2025-07-08T17:26:36.431Z,1751995596.431 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2025-07-08T17:26:36.437Z,1751995596.437 [CBIT](DEBUG): Construct Continuous Built In Test. 2025-07-08T17:26:36.519Z,1751995596.519 [CBIT] Loaded 2025-07-08T17:26:36.519Z,1751995596.519 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2025-07-08T17:26:36.553Z,1751995596.553 [GFScanner] Loaded 2025-07-08T17:26:36.553Z,1751995596.553 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread. 2025-07-08T17:26:36.554Z,1751995596.554 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2025-07-08T17:26:36.554Z,1751995596.554 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2025-07-08T17:26:36.914Z,1751995596.914 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2025-07-08T17:26:36.915Z,1751995596.915 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2025-07-08T17:26:36.967Z,1751995596.967 [DepthRateCalculator] Loaded 2025-07-08T17:26:36.967Z,1751995596.967 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2025-07-08T17:26:36.972Z,1751995596.972 [PitchRateCalculator] Loaded 2025-07-08T17:26:36.973Z,1751995596.973 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2025-07-08T17:26:36.983Z,1751995596.983 [SpeedCalculator] Loaded 2025-07-08T17:26:36.983Z,1751995596.983 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2025-07-08T17:26:36.988Z,1751995596.988 [YawRateCalculator] Loaded 2025-07-08T17:26:36.988Z,1751995596.988 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2025-07-08T17:26:37.008Z,1751995597.008 [ElevatorOffsetCalculator] Loaded 2025-07-08T17:26:37.008Z,1751995597.008 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2025-07-08T17:26:37.009Z,1751995597.009 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2025-07-08T17:26:37.009Z,1751995597.009 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2025-07-08T17:26:37.191Z,1751995597.191 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2025-07-08T17:26:37.192Z,1751995597.192 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2025-07-08T17:26:37.317Z,1751995597.317 [DeadReckonUsingMultipleVelocitySources] Loaded 2025-07-08T17:26:37.317Z,1751995597.317 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2025-07-08T17:26:37.332Z,1751995597.332 [NavChart] Loaded 2025-07-08T17:26:37.332Z,1751995597.332 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2025-07-08T17:26:37.338Z,1751995597.338 [UniversalFixResidualReporter] Loaded 2025-07-08T17:26:37.339Z,1751995597.339 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2025-07-08T17:26:37.339Z,1751995597.339 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2025-07-08T17:26:37.340Z,1751995597.340 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2025-07-08T17:26:37.481Z,1751995597.481 [BuoyancyServo] Loaded 2025-07-08T17:26:37.481Z,1751995597.481 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2025-07-08T17:26:37.505Z,1751995597.505 [ElevatorServo] Loaded 2025-07-08T17:26:37.505Z,1751995597.505 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2025-07-08T17:26:37.528Z,1751995597.528 [MassServo] Loaded 2025-07-08T17:26:37.528Z,1751995597.528 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2025-07-08T17:26:37.549Z,1751995597.549 [RudderServo] Loaded 2025-07-08T17:26:37.549Z,1751995597.549 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2025-07-08T17:26:37.567Z,1751995597.567 [ThrusterHE] Loaded 2025-07-08T17:26:37.567Z,1751995597.567 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2025-07-08T17:26:37.568Z,1751995597.568 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2025-07-08T17:26:37.568Z,1751995597.568 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2025-07-08T17:26:37.746Z,1751995597.746 [CTD_Seabird] Loaded 2025-07-08T17:26:37.747Z,1751995597.747 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2025-07-08T17:26:37.748Z,1751995597.748 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40C094E0 2025-07-08T17:26:37.748Z,1751995597.748 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1109 2025-07-08T17:26:37.785Z,1751995597.785 [ESPComponent] Loaded 2025-07-08T17:26:37.786Z,1751995597.786 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2025-07-08T17:26:37.811Z,1751995597.811 [PAR_Licor] Loaded 2025-07-08T17:26:37.812Z,1751995597.812 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2025-07-08T17:26:37.858Z,1751995597.858 [WetLabsBB2FL] Loaded 2025-07-08T17:26:37.858Z,1751995597.858 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2025-07-08T17:26:37.859Z,1751995597.859 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C394E0 2025-07-08T17:26:37.859Z,1751995597.859 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1110 2025-07-08T17:26:37.860Z,1751995597.860 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2025-07-08T17:26:37.876Z,1751995597.876 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2025-07-08T17:26:37.879Z,1751995597.879 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2025-07-08T17:26:37.892Z,1751995597.892 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2025-07-08T17:26:37.893Z,1751995597.893 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C694E0 2025-07-08T17:26:37.893Z,1751995597.893 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1111 2025-07-08T17:26:37.900Z,1751995597.900 [Supervisor](INFO): Main Thread ID is 828 2025-07-08T17:26:37.900Z,1751995597.900 [Supervisor](DEBUG): Running supervisor. 2025-07-08T17:26:37.901Z,1751995597.901 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1112 2025-07-08T17:26:37.901Z,1751995597.901 [CommandExec](INFO): Initializing the command executive. 2025-07-08T17:26:37.903Z,1751995597.903 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1113 2025-07-08T17:26:37.907Z,1751995597.907 [controlThread ThreadHandler](INFO): Handler Thread ID is 1114 2025-07-08T17:26:37.907Z,1751995597.907 [controlThread](DEBUG): Initializing ControlThread 2025-07-08T17:26:37.908Z,1751995597.908 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2025-07-08T17:26:37.909Z,1751995597.909 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2025-07-08T17:26:37.910Z,1751995597.910 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2025-07-08T17:26:37.911Z,1751995597.911 [LoopControl](DEBUG): Initialize LoopControlComponent. 2025-07-08T17:26:37.914Z,1751995597.914 [SBIT](INFO): Initialize SBIT Component. 2025-07-08T17:26:37.914Z,1751995597.914 [SBIT](IMPORTANT): git: 2025-07-08 2025-07-08T17:26:37.914Z,1751995597.914 [SBIT](INFO): git hash: d1f1bd5ba1b889ed1cf57764a2754a5d4dfbcce9 2025-07-08T17:26:37.915Z,1751995597.915 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2025-07-08T17:26:37.916Z,1751995597.916 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014 Kernel Reported: #1 PREEMPT Tue Nov 28 15:04:22 PST 2023 2025-07-08T17:26:37.917Z,1751995597.917 [SBIT](INFO): Beginning SBIT in 120.000000 seconds. 2025-07-08T17:26:37.917Z,1751995597.917 [IBIT](INFO): Initialize IBIT Component. 2025-07-08T17:26:37.918Z,1751995597.918 [CBIT](DEBUG): Initialize CBIT Component. 2025-07-08T17:26:37.919Z,1751995597.919 [logger ThreadHandler](INFO): Handler Thread ID is 1115 2025-07-08T17:26:37.930Z,1751995597.930 [CBIT](DEBUG): Initialized mux pins. 2025-07-08T17:26:37.930Z,1751995597.930 [CBIT](DEBUG): Initializing the watchdog timer. 2025-07-08T17:26:37.938Z,1751995597.938 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1116 2025-07-08T17:26:37.954Z,1751995597.954 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2025-07-08T17:26:37.954Z,1751995597.954 [CBIT](DEBUG): Initializing heartbeat. 2025-07-08T17:26:37.955Z,1751995597.955 [DAT ThreadHandler](INFO): Handler Thread ID is 1117 2025-07-08T17:26:37.956Z,1751995597.956 [DAT](INFO): Powering up 2025-07-08T17:26:37.956Z,1751995597.956 [DAT](DEBUG): Initializing DAT. 2025-07-08T17:26:37.960Z,1751995597.960 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1119 2025-07-08T17:26:37.962Z,1751995597.962 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2025-07-08T17:26:37.982Z,1751995597.982 [Onboard ThreadHandler](INFO): Handler Thread ID is 1120 2025-07-08T17:26:37.000Z,1751995598.000 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1121 2025-07-08T17:26:38.018Z,1751995598.018 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1122 2025-07-08T17:26:38.019Z,1751995598.019 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2025-07-08T17:26:38.026Z,1751995598.026 [CBIT](DEBUG): Deactivating emergency mode. 2025-07-08T17:26:38.027Z,1751995598.027 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1124 2025-07-08T17:26:38.030Z,1751995598.030 [WetLabsBB2FL](INFO): Powering up 2025-07-08T17:26:38.031Z,1751995598.031 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1126 2025-07-08T17:26:38.034Z,1751995598.034 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2025-07-08T17:26:38.034Z,1751995598.034 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2025-07-08T17:26:38.034Z,1751995598.034 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2025-07-08T17:26:38.034Z,1751995598.034 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2025-07-08T17:26:38.035Z,1751995598.035 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2025-07-08T17:26:38.035Z,1751995598.035 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2025-07-08T17:26:38.035Z,1751995598.035 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2025-07-08T17:26:38.035Z,1751995598.035 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2025-07-08T17:26:38.066Z,1751995598.066 [CBIT](DEBUG): Backplane powered. 2025-07-08T17:26:38.066Z,1751995598.066 [GFScanner](DEBUG): Initializing GFScanner 2025-07-08T17:26:38.066Z,1751995598.066 [GFScanner](DEBUG): Deactivating GF circuits. 2025-07-08T17:26:38.071Z,1751995598.071 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2025-07-08T17:26:38.071Z,1751995598.071 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2025-07-08T17:26:38.071Z,1751995598.071 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2025-07-08T17:26:38.072Z,1751995598.072 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2025-07-08T17:26:38.072Z,1751995598.072 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2025-07-08T17:26:38.073Z,1751995598.073 [NavChart](DEBUG): Initialize NavChart Navigation. 2025-07-08T17:26:38.073Z,1751995598.073 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2025-07-08T17:26:38.086Z,1751995598.086 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2025-07-08T17:26:38.087Z,1751995598.087 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2025-07-08T17:26:38.087Z,1751995598.087 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2025-07-08T17:26:38.089Z,1751995598.089 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2025-07-08T17:26:38.166Z,1751995598.166 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2025-07-08T17:26:38.190Z,1751995598.190 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2025-07-08T17:26:38.224Z,1751995598.224 [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-08T17:26:38.224Z,1751995598.224 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2025-07-08T17:26:38.225Z,1751995598.225 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2025-07-08T17:26:38.242Z,1751995598.242 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2025-07-08T17:26:38.406Z,1751995598.406 [Radio_Surface](INFO): Powering up 2025-07-08T17:26:38.545Z,1751995598.545 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into: 0 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2025-07-08T17:26:38.567Z,1751995598.567 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2025-07-08T17:26:38.568Z,1751995598.568 [Default:A.Wait](DEBUG): Construct Wait. 2025-07-08T17:26:38.571Z,1751995598.571 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-07-08T17:26:38.611Z,1751995598.611 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2025-07-08T17:26:38.634Z,1751995598.634 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2025-07-08T17:26:38.640Z,1751995598.640 [Default:E.Execute](DEBUG): Construct Execute. 2025-07-08T17:26:38.659Z,1751995598.659 [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-08T17:26:38.664Z,1751995598.664 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,ESPComponent,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-08T17:26:38.686Z,1751995598.686 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2025-07-08T17:26:38.755Z,1751995598.755 [Depth_Keller](INFO): Initializing. 2025-07-08T17:26:38.756Z,1751995598.756 [Power24vConverter](INFO): Powering up. 2025-07-08T17:26:38.757Z,1751995598.757 [Sonardyne_Nano](INFO): Initializing. 2025-07-08T17:26:38.780Z,1751995598.780 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-07-08T17:26:38.799Z,1751995598.799 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-07-08T17:26:38.806Z,1751995598.806 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-07-08T17:26:38.807Z,1751995598.807 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-07-08T17:26:38.814Z,1751995598.814 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-07-08T17:26:38.815Z,1751995598.815 [MassServo](DEBUG): Initializing EZServoServo. 2025-07-08T17:26:38.822Z,1751995598.822 [MassServo](DEBUG): Initializing MassServo. 2025-07-08T17:26:38.823Z,1751995598.823 [RudderServo](DEBUG): Initializing EZServoServo. 2025-07-08T17:26:38.830Z,1751995598.830 [RudderServo](DEBUG): Initializing RudderServo. 2025-07-08T17:26:38.831Z,1751995598.831 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-07-08T17:26:38.838Z,1751995598.838 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-07-08T17:26:40.138Z,1751995600.138 [WetLabsBB2FL](INFO): Powering down 2025-07-08T17:26:40.692Z,1751995600.692 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2025-07-08T17:26:41.979Z,1751995601.979 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2311 2025-07-08T17:26:44.575Z,1751995604.575 [ThrusterHE](ERROR): Zero Speed Commanded. 2025-07-08T17:26:50.303Z,1751995610.303 [DAT](INFO): DAT read: 2025-07-08T17:26:50.304Z,1751995610.304 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2025-07-08T17:26:52.067Z,1751995612.067 [DAT](INFO): DAT read: MF Frequency Band 2025-07-08T17:26:52.068Z,1751995612.068 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0 2025-07-08T17:26:52.069Z,1751995612.069 [DAT](INFO): DAT read: Jul 8 2025 17:26:45 2025-07-08T17:26:53.079Z,1751995613.079 [DAT](INFO): DAT read: Features enabled [Bearing] 2025-07-08T17:26:53.080Z,1751995613.080 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2025-07-08T17:26:53.081Z,1751995613.081 [DAT](INFO): commRate: 800 2025-07-08T17:26:55.146Z,1751995615.146 [DAT](INFO): entering command mode 2025-07-08T17:26:55.347Z,1751995615.347 [DAT](INFO): DAT read: 2025-07-08T17:26:55.347Z,1751995615.347 [DAT](INFO): DAT read: user:1> 2025-07-08T17:26:55.348Z,1751995615.348 [DAT](INFO): setting verbose to 3 2025-07-08T17:26:55.599Z,1751995615.599 [DAT](INFO): DAT read: user:1> 2025-07-08T17:26:55.600Z,1751995615.600 [DAT](INFO): DAT read: Verbose | 3 2025-07-08T17:26:55.600Z,1751995615.600 [DAT](INFO): set verbose to 3 2025-07-08T17:26:55.600Z,1751995615.600 [DAT](INFO): setting DatVerbose to 27440 2025-07-08T17:26:55.851Z,1751995615.851 [DAT](INFO): DAT read: user:2> 2025-07-08T17:26:55.852Z,1751995615.852 [DAT](INFO): DAT read: DatVerbose | 27440 2025-07-08T17:26:55.852Z,1751995615.852 [DAT](INFO): set DatVerbose to 27440 2025-07-08T17:26:55.852Z,1751995615.852 [DAT](INFO): setting transmit power to 8 2025-07-08T17:26:56.103Z,1751995616.103 [DAT](INFO): DAT read: user:3> 2025-07-08T17:26:56.103Z,1751995616.103 [DAT](INFO): DAT read: TxPower | 8 (Max) 2025-07-08T17:26:56.104Z,1751995616.104 [DAT](INFO): set transmit power to 8 2025-07-08T17:26:56.104Z,1751995616.104 [DAT](INFO): setting local address to 1 2025-07-08T17:26:56.355Z,1751995616.355 [DAT](INFO): DAT read: user:4> 2025-07-08T17:26:56.356Z,1751995616.356 [DAT](INFO): DAT read: LocalAddr | 1 2025-07-08T17:26:56.356Z,1751995616.356 [DAT](INFO): set local address to 1 2025-07-08T17:26:56.357Z,1751995616.357 [DAT](INFO): Setting time to: 17:26:56 And date to:7/8/2025 2025-07-08T17:26:56.607Z,1751995616.607 [DAT](INFO): DAT read: user:5> 2025-07-08T17:26:56.608Z,1751995616.608 [DAT](INFO): DAT read: Tue Jul 8, 2025 17:26:56 2025-07-08T17:26:56.608Z,1751995616.608 [DAT](INFO): Local DAT time set to Tue Jul 8, 2025 17:26:56 2025-07-08T17:27:06.188Z,1751995626.188 [NAL9602](INFO): Powering up NAL9602 2025-07-08T17:27:17.100Z,1751995637.100 [NAL9602](INFO): NAL9602 initialized 2025-07-08T17:27:54.665Z,1751995674.665 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-08T17:28:10.420Z,1751995690.420 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-08T17:28:25.772Z,1751995705.772 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-08T17:28:33.873Z,1751995713.873 [NAL9602](INFO): SBD MO Status=0, MOMSN=25389, MT Status=0, MTMSN=0 2025-07-08T17:28:33.873Z,1751995713.873 [NAL9602](INFO): No messages in MT queue 2025-07-08T17:28:38.327Z,1751995718.327 [SBIT](IMPORTANT): Beginning Startup BIT 2025-07-08T17:28:38.332Z,1751995718.332 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-07-08T17:28:41.932Z,1751995721.932 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-08T17:28:42.852Z,1751995722.852 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.000199 CHAN A1 (24V): 0.000975 CHAN A2 (12V): 0.000106 CHAN A3 (5V): -0.001896 CHAN B0 (3.3V): 0.000516 CHAN B1 (3.15aV): 0.000157 CHAN B2 (3.15bV): 0.000290 CHAN B3 (GND): -0.000338 OPEN: 0.006319 Full Scale: +/- 1 mA 2025-07-08T17:28:56.904Z,1751995736.904 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-08T17:29:02.169Z,1751995742.169 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172900.00,A,3648.16636,N,12147.25403,W,1.808,121.24,080725,,,A*70 2025-07-08T17:29:02.180Z,1751995742.180 [NAL9602](INFO): GPS fix at 20250708T172900: (36.802773, -121.787567) 2025-07-08T17:29:11.861Z,1751995751.861 [BPC1](ERROR): BPC1B got IPBS message with 5 sticks (min is 7). 2025-07-08T17:29:32.499Z,1751995772.499 [SBIT](IMPORTANT): SBIT PASSED 2025-07-08T17:29:32.499Z,1751995772.499 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2025-07-08T17:29:32.500Z,1751995772.500 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=15 count; 2025-07-08T17:29:32.500Z,1751995772.500 [SBIT](IMPORTANT): Express linearApproximation TrackAcousticContact.range_to_contact 15.000000 meter; 2025-07-08T17:29:32.500Z,1751995772.500 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_HM_AvgRois 2.000000 count_per_second; 2025-07-08T17:29:32.500Z,1751995772.500 [SBIT](IMPORTANT): Express linearApproximation _.planktivore_LM_AvgRois 100.000000 count_per_second; 2025-07-08T17:29:32.500Z,1751995772.500 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=208.461783 cubic_centimeter; 2025-07-08T17:29:32.500Z,1751995772.500 [SBIT](IMPORTANT): VerticalControl.massDefault=25.111432 millimeter; 2025-07-08T17:29:32.500Z,1751995772.500 [SBIT](IMPORTANT): Waterlinked.loadAtStartup=0 bool; 2025-07-08T17:29:32.501Z,1751995772.501 [SBIT](IMPORTANT): Waypoint.wptRecalcBearing=1 bool; 2025-07-08T17:29:32.909Z,1751995772.909 [MissionManager](IMPORTANT): Started mission Startup 2025-07-08T17:29:32.909Z,1751995772.909 [Startup] Running Loop=1 2025-07-08T17:29:32.909Z,1751995772.909 [Startup](DEBUG): Aggregate::initialize Startup 2025-07-08T17:29:32.909Z,1751995772.909 [Startup:A.GoToSurface] Running Loop=1 2025-07-08T17:29:32.910Z,1751995772.910 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-07-08T17:29:32.910Z,1751995772.910 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-07-08T17:29:32.911Z,1751995772.911 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-07-08T17:29:32.911Z,1751995772.911 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-07-08T17:29:32.911Z,1751995772.911 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-07-08T17:29:32.912Z,1751995772.912 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-07-08T17:29:32.912Z,1751995772.912 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-07-08T17:29:32.914Z,1751995772.914 [Startup:StartupSatComms] Running Loop=1 2025-07-08T17:29:32.914Z,1751995772.914 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2025-07-08T17:29:32.914Z,1751995772.914 [Startup:StartupSatComms:A] Running Loop=1 2025-07-08T17:29:33.317Z,1751995773.317 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2025-07-08T17:29:34.921Z,1751995774.921 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,172933.00,A,3648.14547,N,12147.25726,W,1.477,200.79,080725,,,A*7F 2025-07-08T17:29:34.924Z,1751995774.924 [NAL9602](INFO): GPS fix at 20250708T172933: (36.802425, -121.787621) 2025-07-08T17:29:34.935Z,1751995774.935 [Startup:StartupSatComms:A] Stopped 2025-07-08T17:29:34.935Z,1751995774.935 [Startup:StartupSatComms:B] Running Loop=1 2025-07-08T17:29:35.355Z,1751995775.355 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-07-08T17:30:11.366Z,1751995811.366 [NAL9602](INFO): SBD MO Status=2, MOMSN=25390, MT Status=2, MTMSN=0 2025-07-08T17:30:11.367Z,1751995811.367 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2 2025-07-08T17:30:33.611Z,1751995833.611 [DataOverHttps](IMPORTANT): SBD MTMSN=20250708T173032 2025-07-08T17:30:33.616Z,1751995833.616 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004442 2025-07-08T17:30:35.259Z,1751995835.259 [Startup:StartupSatComms:B](INFO): Timed out from 2025-07-08T17:29:34.9Z 2025-07-08T17:30:35.259Z,1751995835.259 [Startup:StartupSatComms:B] Stopped 2025-07-08T17:30:35.259Z,1751995835.259 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2025-07-08T17:30:35.259Z,1751995835.259 [Startup:StartupSatComms] Stopped 2025-07-08T17:30:35.259Z,1751995835.259 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2025-07-08T17:30:35.260Z,1751995835.260 [Startup](INFO): Completed Startup 2025-07-08T17:30:35.260Z,1751995835.260 [MissionManager](INFO): Startup is completed. 2025-07-08T17:30:35.261Z,1751995835.261 [MissionManager](INFO): Uninitializing Mission Startup 2025-07-08T17:30:35.261Z,1751995835.261 [Startup] Stopped 2025-07-08T17:30:35.261Z,1751995835.261 [Startup](DEBUG): Aggregate::uninitialize Startup 2025-07-08T17:30:35.261Z,1751995835.261 [Startup:A.GoToSurface] Stopped 2025-07-08T17:30:35.261Z,1751995835.261 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-07-08T17:30:35.622Z,1751995835.622 [MissionManager](IMPORTANT): Started mission Default 2025-07-08T17:30:35.622Z,1751995835.622 [Default] Running Loop=1 2025-07-08T17:30:35.622Z,1751995835.622 [Default](DEBUG): Aggregate::initialize Default 2025-07-08T17:30:35.622Z,1751995835.622 [Default:B.GoToSurface] Running Loop=1 2025-07-08T17:30:35.622Z,1751995835.622 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-07-08T17:30:35.623Z,1751995835.623 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-07-08T17:30:35.623Z,1751995835.623 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-07-08T17:30:35.623Z,1751995835.623 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-07-08T17:30:35.623Z,1751995835.623 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-07-08T17:30:35.624Z,1751995835.624 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-07-08T17:30:35.624Z,1751995835.624 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-07-08T17:30:35.624Z,1751995835.624 [Default:A.Wait] Running Loop=1 2025-07-08T17:30:35.624Z,1751995835.624 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-07-08T17:30:41.105Z,1751995841.105 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250708T171307/Courier0010.lzma 2025-07-08T17:30:41.108Z,1751995841.108 [DataOverHttps](INFO): Received command: failc 2025-07-08T17:30:41.163Z,1751995841.163 [CommandExec](IMPORTANT): got command failComponent 2025-07-08T17:30:41.163Z,1751995841.163 [CommandExec](IMPORTANT): Failed components: 2025-07-08T17:30:41.163Z,1751995841.163 [CommandExec](IMPORTANT): No failed Components. 2025-07-08T17:30:49.025Z,1751995849.025 [Default:A.Wait](INFO): Done Waiting. 2025-07-08T17:30:49.025Z,1751995849.025 [Default:A.Wait] Stopped 2025-07-08T17:30:49.026Z,1751995849.026 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-07-08T17:30:49.377Z,1751995849.377 [Default:CheckIn] Running Loop=1 2025-07-08T17:30:49.377Z,1751995849.377 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-08T17:30:49.377Z,1751995849.377 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-08T17:30:49.784Z,1751995849.784 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-07-08T17:30:51.358Z,1751995851.358 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173049.00,A,3648.15544,N,12147.22107,W,0.039,77.27,080725,,,A*4C 2025-07-08T17:30:51.360Z,1751995851.360 [NAL9602](INFO): GPS fix at 20250708T173049: (36.802591, -121.787018) 2025-07-08T17:30:51.381Z,1751995851.381 [Default:CheckIn:Read_GPS] Stopped 2025-07-08T17:30:51.381Z,1751995851.381 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-08T17:30:51.789Z,1751995851.789 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-07-08T17:30:52.529Z,1751995852.529 [DataOverHttps](INFO): Moved sent file to Logs/20250708T171307/Courier0010.lzma.bak 2025-07-08T17:30:52.530Z,1751995852.530 [DataOverHttps](IMPORTANT): SBD MOMSN=25430968, MTMSN=20250708T173051 2025-07-08T17:31:00.802Z,1751995860.802 [DataOverHttps](INFO): Received command: ibit 2025-07-08T17:31:00.831Z,1751995860.831 [CommandExec](IMPORTANT): got command ibit 2025-07-08T17:31:01.095Z,1751995861.095 [IBIT](IMPORTANT): Beginning Initiated BIT 2025-07-08T17:31:01.095Z,1751995861.095 [IBIT](IMPORTANT): Beginning control surface checks. 2025-07-08T17:31:01.099Z,1751995861.099 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-07-08T17:31:02.688Z,1751995862.688 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173101.00,A,3648.15658,N,12147.22251,W,0.058,77.27,080725,,,A*48 2025-07-08T17:31:02.690Z,1751995862.690 [NAL9602](INFO): GPS fix at 20250708T173101: (36.802610, -121.787042) 2025-07-08T17:31:05.726Z,1751995865.726 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.000255 CHAN A1 (24V): 0.000332 CHAN A2 (12V): 0.000381 CHAN A3 (5V): -0.001966 CHAN B0 (3.3V): -0.000192 CHAN B1 (3.15aV): -0.000248 CHAN B2 (3.15bV): -0.000046 CHAN B3 (GND): 0.000103 OPEN: 0.006578 Full Scale: +/- 1 mA 2025-07-08T17:31:05.927Z,1751995865.927 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173104.00,A,3648.15648,N,12147.22245,W,0.019,77.27,080725,,,A*4C 2025-07-08T17:31:05.929Z,1751995865.929 [NAL9602](INFO): GPS fix at 20250708T173104: (36.802608, -121.787041) 2025-07-08T17:31:08.758Z,1751995868.758 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173107.00,A,3648.15630,N,12147.22234,W,0.019,77.27,080725,,,A*46 2025-07-08T17:31:08.760Z,1751995868.760 [NAL9602](INFO): GPS fix at 20250708T173107: (36.802605, -121.787039) 2025-07-08T17:31:11.981Z,1751995871.981 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173110.00,A,3648.15606,N,12147.22226,W,0.078,77.27,080725,,,A*41 2025-07-08T17:31:11.983Z,1751995871.983 [NAL9602](INFO): GPS fix at 20250708T173110: (36.802601, -121.787038) 2025-07-08T17:31:15.213Z,1751995875.213 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173113.00,A,3648.15580,N,12147.22217,W,0.019,77.27,080725,,,A*4A 2025-07-08T17:31:15.218Z,1751995875.218 [NAL9602](INFO): GPS fix at 20250708T173113: (36.802597, -121.787036) 2025-07-08T17:31:18.041Z,1751995878.041 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173116.00,A,3648.15555,N,12147.22196,W,0.039,77.27,080725,,,A*4F 2025-07-08T17:31:18.045Z,1751995878.045 [NAL9602](INFO): GPS fix at 20250708T173116: (36.802593, -121.787033) 2025-07-08T17:31:19.614Z,1751995879.614 [DataOverHttps](INFO): Sending 268 bytes from file Logs/20250708T172627/Courier0000.lzma 2025-07-08T17:31:20.881Z,1751995880.881 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173119.00,A,3648.15525,N,12147.22168,W,0.019,77.27,080725,,,A*44 2025-07-08T17:31:20.883Z,1751995880.883 [NAL9602](INFO): GPS fix at 20250708T173119: (36.802588, -121.787028) 2025-07-08T17:31:24.099Z,1751995884.099 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173122.00,A,3648.15502,N,12147.22151,W,0.000,77.27,080725,,,A*4B 2025-07-08T17:31:24.102Z,1751995884.102 [NAL9602](INFO): GPS fix at 20250708T173122: (36.802584, -121.787025) 2025-07-08T17:31:26.927Z,1751995886.927 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173125.00,A,3648.15496,N,12147.22156,W,0.019,77.27,080725,,,A*4F 2025-07-08T17:31:26.929Z,1751995886.929 [NAL9602](INFO): GPS fix at 20250708T173125: (36.802583, -121.787026) 2025-07-08T17:31:30.158Z,1751995890.158 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173128.00,A,3648.15513,N,12147.22206,W,0.039,77.27,080725,,,A*4A 2025-07-08T17:31:30.161Z,1751995890.161 [NAL9602](INFO): GPS fix at 20250708T173128: (36.802585, -121.787034) 2025-07-08T17:31:30.656Z,1751995890.656 [DataOverHttps](INFO): Moved sent file to Logs/20250708T172627/Courier0000.lzma.bak 2025-07-08T17:31:30.656Z,1751995890.656 [DataOverHttps](INFO): SBD MOMSN=25431089 2025-07-08T17:31:33.008Z,1751995893.008 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173131.00,A,3648.15529,N,12147.22264,W,0.019,77.27,080725,,,A*4D 2025-07-08T17:31:33.011Z,1751995893.011 [NAL9602](INFO): GPS fix at 20250708T173131: (36.802588, -121.787044) 2025-07-08T17:31:35.817Z,1751995895.817 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173134.00,A,3648.15539,N,12147.22300,W,0.039,77.27,080725,,,A*48 2025-07-08T17:31:35.831Z,1751995895.831 [NAL9602](INFO): GPS fix at 20250708T173134: (36.802590, -121.787050) 2025-07-08T17:31:38.643Z,1751995898.643 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-08T17:31:39.066Z,1751995899.066 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173137.00,A,3648.15536,N,12147.22317,W,0.058,77.27,080725,,,A*45 2025-07-08T17:31:39.068Z,1751995899.068 [NAL9602](INFO): GPS fix at 20250708T173137: (36.802589, -121.787053) 2025-07-08T17:31:41.878Z,1751995901.878 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173140.00,A,3648.15533,N,12147.22338,W,0.019,77.27,080725,,,A*48 2025-07-08T17:31:41.880Z,1751995901.880 [NAL9602](INFO): GPS fix at 20250708T173140: (36.802589, -121.787056) 2025-07-08T17:31:45.107Z,1751995905.107 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173143.00,A,3648.15535,N,12147.22353,W,0.039,77.27,080725,,,A*42 2025-07-08T17:31:45.109Z,1751995905.109 [NAL9602](INFO): GPS fix at 20250708T173143: (36.802589, -121.787059) 2025-07-08T17:31:47.941Z,1751995907.941 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173146.00,A,3648.15544,N,12147.22362,W,0.058,77.27,080725,,,A*44 2025-07-08T17:31:47.943Z,1751995907.943 [NAL9602](INFO): GPS fix at 20250708T173146: (36.802591, -121.787060) 2025-07-08T17:31:51.166Z,1751995911.166 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173149.00,A,3648.15539,N,12147.22341,W,0.039,77.27,080725,,,A*47 2025-07-08T17:31:51.168Z,1751995911.168 [NAL9602](INFO): GPS fix at 20250708T173149: (36.802590, -121.787057) 2025-07-08T17:31:52.373Z,1751995912.373 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-08T17:31:53.997Z,1751995913.997 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173152.00,A,3648.15525,N,12147.22313,W,0.078,77.27,080725,,,A*42 2025-07-08T17:31:53.999Z,1751995913.999 [NAL9602](INFO): GPS fix at 20250708T173152: (36.802588, -121.787052) 2025-07-08T17:31:56.822Z,1751995916.822 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173155.00,A,3648.15509,N,12147.22289,W,0.058,77.27,080725,,,A*4B 2025-07-08T17:31:56.825Z,1751995916.825 [NAL9602](INFO): GPS fix at 20250708T173155: (36.802585, -121.787048) 2025-07-08T17:31:56.902Z,1751995916.902 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250708T172627/Courier0004.lzma 2025-07-08T17:32:00.057Z,1751995920.057 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173158.00,A,3648.15496,N,12147.22265,W,0.019,77.27,080725,,,A*46 2025-07-08T17:32:00.060Z,1751995920.060 [NAL9602](INFO): GPS fix at 20250708T173158: (36.802583, -121.787044) 2025-07-08T17:32:02.883Z,1751995922.883 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173201.00,A,3648.15489,N,12147.22253,W,0.019,77.27,080725,,,A*42 2025-07-08T17:32:02.885Z,1751995922.885 [NAL9602](INFO): GPS fix at 20250708T173201: (36.802582, -121.787042) 2025-07-08T17:32:05.719Z,1751995925.719 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-08T17:32:06.139Z,1751995926.139 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173204.00,A,3648.15469,N,12147.22237,W,0.058,77.27,080725,,,A*4E 2025-07-08T17:32:06.141Z,1751995926.141 [NAL9602](INFO): GPS fix at 20250708T173204: (36.802578, -121.787040) 2025-07-08T17:32:07.948Z,1751995927.948 [DataOverHttps](INFO): Moved sent file to Logs/20250708T172627/Courier0004.lzma.bak 2025-07-08T17:32:07.948Z,1751995927.948 [DataOverHttps](INFO): SBD MOMSN=25431093 2025-07-08T17:32:08.950Z,1751995928.950 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173207.00,A,3648.15454,N,12147.22224,W,0.019,77.27,080725,,,A*44 2025-07-08T17:32:08.960Z,1751995928.960 [NAL9602](INFO): GPS fix at 20250708T173207: (36.802576, -121.787037) 2025-07-08T17:32:12.177Z,1751995932.177 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173210.00,A,3648.15443,N,12147.22216,W,0.058,77.27,080725,,,A*40 2025-07-08T17:32:12.188Z,1751995932.188 [NAL9602](INFO): GPS fix at 20250708T173210: (36.802574, -121.787036) 2025-07-08T17:32:15.001Z,1751995935.001 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173213.00,A,3648.15435,N,12147.22230,W,0.039,77.27,080725,,,A*41 2025-07-08T17:32:15.004Z,1751995935.004 [NAL9602](INFO): GPS fix at 20250708T173213: (36.802572, -121.787038) 2025-07-08T17:32:17.896Z,1751995937.896 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173216.00,A,3648.15449,N,12147.22268,W,0.019,77.27,080725,,,A*40 2025-07-08T17:32:17.899Z,1751995937.899 [NAL9602](INFO): GPS fix at 20250708T173216: (36.802575, -121.787045) 2025-07-08T17:32:19.050Z,1751995939.050 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-08T17:32:21.061Z,1751995941.061 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173219.00,A,3648.15469,N,12147.22298,W,0.058,77.27,080725,,,A*47 2025-07-08T17:32:21.071Z,1751995941.071 [NAL9602](INFO): GPS fix at 20250708T173219: (36.802578, -121.787050) 2025-07-08T17:32:23.891Z,1751995943.891 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173222.00,A,3648.15497,N,12147.22319,W,0.000,77.27,080725,,,A*4B 2025-07-08T17:32:23.893Z,1751995943.893 [NAL9602](INFO): GPS fix at 20250708T173222: (36.802583, -121.787053) 2025-07-08T17:32:27.123Z,1751995947.123 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173225.00,A,3648.15525,N,12147.22342,W,0.039,77.27,080725,,,A*40 2025-07-08T17:32:27.125Z,1751995947.125 [NAL9602](INFO): GPS fix at 20250708T173225: (36.802588, -121.787057) 2025-07-08T17:32:29.953Z,1751995949.953 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173228.00,A,3648.15544,N,12147.22358,W,0.078,77.27,080725,,,A*44 2025-07-08T17:32:29.955Z,1751995949.955 [NAL9602](INFO): GPS fix at 20250708T173228: (36.802591, -121.787060) 2025-07-08T17:32:32.786Z,1751995952.786 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-08T17:32:32.821Z,1751995952.821 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173231.00,A,3648.15565,N,12147.22358,W,0.039,77.27,080725,,,A*4A 2025-07-08T17:32:32.831Z,1751995952.831 [NAL9602](INFO): GPS fix at 20250708T173231: (36.802594, -121.787060) 2025-07-08T17:32:34.185Z,1751995954.185 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250708T172627/Courier0010.lzma 2025-07-08T17:32:36.009Z,1751995956.009 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173234.00,A,3648.15569,N,12147.22348,W,0.078,77.27,080725,,,A*47 2025-07-08T17:32:36.012Z,1751995956.012 [NAL9602](INFO): GPS fix at 20250708T173234: (36.802595, -121.787058) 2025-07-08T17:32:38.842Z,1751995958.842 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173237.00,A,3648.15569,N,12147.22332,W,0.058,77.27,080725,,,A*4B 2025-07-08T17:32:38.844Z,1751995958.844 [NAL9602](INFO): GPS fix at 20250708T173237: (36.802595, -121.787055) 2025-07-08T17:32:42.070Z,1751995962.070 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173240.00,A,3648.15572,N,12147.22311,W,0.019,77.27,080725,,,A*45 2025-07-08T17:32:42.072Z,1751995962.072 [NAL9602](INFO): GPS fix at 20250708T173240: (36.802595, -121.787052) 2025-07-08T17:32:44.963Z,1751995964.963 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173243.00,A,3648.15568,N,12147.22297,W,0.058,77.27,080725,,,A*47 2025-07-08T17:32:44.965Z,1751995964.965 [NAL9602](INFO): GPS fix at 20250708T173243: (36.802595, -121.787049) 2025-07-08T17:32:45.228Z,1751995965.228 [DataOverHttps](INFO): Moved sent file to Logs/20250708T172627/Courier0010.lzma.bak 2025-07-08T17:32:45.228Z,1751995965.228 [DataOverHttps](INFO): SBD MOMSN=25431096 2025-07-08T17:32:46.916Z,1751995966.916 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-08T17:32:48.129Z,1751995968.129 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173246.00,A,3648.15583,N,12147.22292,W,0.428,77.27,080725,,,A*41 2025-07-08T17:32:48.132Z,1751995968.132 [NAL9602](INFO): GPS fix at 20250708T173246: (36.802597, -121.787049) 2025-07-08T17:32:50.991Z,1751995970.991 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173249.00,A,3648.15583,N,12147.22294,W,0.292,77.27,080725,,,A*4F 2025-07-08T17:32:50.993Z,1751995970.993 [NAL9602](INFO): GPS fix at 20250708T173249: (36.802597, -121.787049) 2025-07-08T17:32:51.897Z,1751995971.897 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired. 2025-07-08T17:32:52.325Z,1751995972.325 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active. 2025-07-08T17:32:52.741Z,1751995972.741 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2025-07-08T17:32:52.756Z,1751995972.756 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2025-07-08T17:32:52.756Z,1751995972.756 [IBIT](IMPORTANT): Pressure:10.181072 PSI 2025-07-08T17:32:52.757Z,1751995972.757 [IBIT](IMPORTANT): Humidity:24.221706 % 2025-07-08T17:32:53.096Z,1751995973.096 [IBIT](IMPORTANT): Vehicle Pitch:-2.106447 degrees 2025-07-08T17:32:53.097Z,1751995973.097 [IBIT](IMPORTANT): Vehicle Roll:-1.329670 degrees 2025-07-08T17:32:53.097Z,1751995973.097 [IBIT](IMPORTANT): Vehicle Heading:210.734589 degrees 2025-07-08T17:32:53.532Z,1751995973.532 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2025-07-08T17:32:53.533Z,1751995973.533 [IBIT](IMPORTANT): buoyancyNeutral: 208.461777 cc 2025-07-08T17:32:53.533Z,1751995973.533 [IBIT](IMPORTANT): massDefault: 2.511143 cm 2025-07-08T17:32:53.533Z,1751995973.533 [IBIT](IMPORTANT): stopDepth: 290.000000 m 2025-07-08T17:32:53.533Z,1751995973.533 [IBIT](IMPORTANT): abortDepth: 300.000000 m 2025-07-08T17:32:53.534Z,1751995973.534 [IBIT](IMPORTANT): IBIT FAILED 2025-07-08T17:32:53.842Z,1751995973.842 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,173252.00,A,3648.15589,N,12147.22321,W,0.097,77.27,080725,,,A*47 2025-07-08T17:32:53.844Z,1751995973.844 [NAL9602](INFO): GPS fix at 20250708T173252: (36.802598, -121.787053) 2025-07-08T17:33:00.714Z,1751995980.714 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-08T17:33:14.970Z,1751995994.970 [DataOverHttps](INFO): Sending 130 bytes from file Logs/20250708T171307/Express0011.lzma 2025-07-08T17:33:15.257Z,1751995995.257 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-08T17:33:25.000Z,1751996006.000 [DataOverHttps](INFO): Moved sent file to Logs/20250708T171307/Express0011.lzma.bak 2025-07-08T17:33:25.000Z,1751996006.000 [DataOverHttps](INFO): SBD MOMSN=25431098 2025-07-08T17:33:26.576Z,1751996006.576 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-08T17:33:28.999Z,1751996008.999 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-08T17:33:42.735Z,1751996022.735 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-08T17:33:51.977Z,1751996031.977 [DataOverHttps](INFO): Sending 1120 bytes from file Logs/20250708T172627/Express0001.lzma 2025-07-08T17:33:56.469Z,1751996036.469 [BPC1](ERROR): BPC1B got IPBS message with 6 sticks (min is 7). 2025-07-08T17:34:03.016Z,1751996043.016 [DataOverHttps](INFO): Moved sent file to Logs/20250708T172627/Express0001.lzma.bak 2025-07-08T17:34:03.016Z,1751996043.016 [DataOverHttps](INFO): SBD MOMSN=25431102 2025-07-08T17:34:29.185Z,1751996069.185 [DataOverHttps](INFO): Sending 273 bytes from file Logs/20250708T172627/Express0005.lzma 2025-07-08T17:34:32.029Z,1751996072.029 [BPC1](ERROR): Battery stick #24 (s/n: 01D3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-07-08T17:34:32.030Z,1751996072.030 [BPC1](ERROR): Battery stick #29 (s/n: 00A7) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4A50. 2025-07-08T17:34:32.031Z,1751996072.031 [BPC1](ERROR): Battery stick #51 (s/n: 01D6) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-07-08T17:34:32.031Z,1751996072.031 [BPC1](ERROR): Battery stick #57 (s/n: 019F) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0. 2025-07-08T17:34:32.032Z,1751996072.032 [BPC1](ERROR): Battery stick #59 (s/n: 0184) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AC0. 2025-07-08T17:34:32.032Z,1751996072.032 [BPC1](INFO): Calculating totals. Valid battery stick count: 41. Valid reserve battery stick count: 4. 2025-07-08T17:34:32.036Z,1751996072.036 [BPC1](INFO): Received data from all battery sticks. 2025-07-08T17:34:40.220Z,1751996080.220 [DataOverHttps](INFO): Moved sent file to Logs/20250708T172627/Express0005.lzma.bak 2025-07-08T17:34:40.220Z,1751996080.220 [DataOverHttps](INFO): SBD MOMSN=25431130 2025-07-08T17:35:06.278Z,1751996106.278 [DataOverHttps](INFO): Sending 230 bytes from file Logs/20250708T172627/Express0011.lzma 2025-07-08T17:35:17.320Z,1751996117.320 [DataOverHttps](INFO): Moved sent file to Logs/20250708T172627/Express0011.lzma.bak 2025-07-08T17:35:17.320Z,1751996117.320 [DataOverHttps](INFO): SBD MOMSN=25431137 2025-07-08T17:35:18.509Z,1751996118.509 [Default:CheckIn:Read_Iridium] Stopped 2025-07-08T17:35:18.509Z,1751996118.509 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-08T17:35:18.509Z,1751996118.509 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-08T17:40:19.111Z,1751996419.111 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-08T17:40:19.111Z,1751996419.111 [Default:CheckIn:C.Wait] Stopped 2025-07-08T17:40:19.111Z,1751996419.111 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-08T17:40:19.111Z,1751996419.111 [Default:CheckIn:D] Running Loop=1 2025-07-08T17:40:19.527Z,1751996419.527 [Default:CheckIn:D] Stopped 2025-07-08T17:40:19.527Z,1751996419.527 [Default:CheckIn:E] Running Loop=1 2025-07-08T17:40:19.925Z,1751996419.925 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 9.731754 min 2025-07-08T17:40:19.925Z,1751996419.925 [Default:CheckIn:E] Stopped 2025-07-08T17:40:19.925Z,1751996419.925 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-08T17:40:19.925Z,1751996419.925 [Default:CheckIn] Stopped 2025-07-08T17:40:19.926Z,1751996419.926 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-08T17:40:19.942Z,1751996419.942 [Default:CheckIn](INFO): Running loop #2 2025-07-08T17:40:19.942Z,1751996419.942 [Default:CheckIn] Running Loop=2 2025-07-08T17:40:19.942Z,1751996419.942 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-08T17:40:19.942Z,1751996419.942 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-08T17:40:21.937Z,1751996421.937 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174021.00,A,3648.15259,N,12147.21943,W,0.253,150.13,080725,,,D*7D 2025-07-08T17:40:21.939Z,1751996421.939 [NAL9602](INFO): GPS fix at 20250708T174021: (36.802543, -121.786991) 2025-07-08T17:40:21.951Z,1751996421.951 [Default:CheckIn:Read_GPS] Stopped 2025-07-08T17:40:21.951Z,1751996421.951 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-08T17:40:29.250Z,1751996429.250 [DataOverHttps](INFO): Sending 202 bytes from file Logs/20250708T172627/Courier0013.lzma 2025-07-08T17:40:36.069Z,1751996436.069 [NAL9602](INFO): SBD MO Status=1, MOMSN=25390, MT Status=0, MTMSN=0 2025-07-08T17:40:36.069Z,1751996436.069 [NAL9602](INFO): No messages in MT queue 2025-07-08T17:40:40.292Z,1751996440.292 [DataOverHttps](INFO): Moved sent file to Logs/20250708T172627/Courier0013.lzma.bak 2025-07-08T17:40:40.292Z,1751996440.292 [DataOverHttps](INFO): SBD MOMSN=25431150 2025-07-08T17:41:06.339Z,1751996466.339 [DataOverHttps](INFO): Sending 1209 bytes from file Logs/20250708T172627/Express0014.lzma 2025-07-08T17:41:06.786Z,1751996466.786 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-08T17:41:17.383Z,1751996477.383 [DataOverHttps](INFO): Moved sent file to Logs/20250708T172627/Express0014.lzma.bak 2025-07-08T17:41:17.383Z,1751996477.383 [DataOverHttps](INFO): SBD MOMSN=25431154 2025-07-08T17:41:18.499Z,1751996478.499 [Default:CheckIn:Read_Iridium] Stopped 2025-07-08T17:41:18.499Z,1751996478.499 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-08T17:41:18.499Z,1751996478.499 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-08T17:45:10.623Z,1751996710.623 [DataOverHttps](IMPORTANT): SBD MTMSN=20250708T174509 2025-07-08T17:45:18.194Z,1751996718.194 [DataOverHttps](INFO): Received command: ibit 2025-07-08T17:45:18.279Z,1751996718.279 [CommandExec](IMPORTANT): got command ibit 2025-07-08T17:45:18.521Z,1751996718.521 [IBIT](IMPORTANT): Beginning Initiated BIT 2025-07-08T17:45:18.521Z,1751996718.521 [IBIT](IMPORTANT): Beginning control surface checks. 2025-07-08T17:45:18.541Z,1751996718.541 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-07-08T17:45:20.121Z,1751996720.121 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174519.00,A,3648.41950,N,12147.10704,W,4.529,6.45,080725,,,D*70 2025-07-08T17:45:20.123Z,1751996720.123 [NAL9602](INFO): GPS fix at 20250708T174519: (36.806992, -121.785117) 2025-07-08T17:45:23.122Z,1751996723.122 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.000405 CHAN A1 (24V): 0.000383 CHAN A2 (12V): 0.000516 CHAN A3 (5V): -0.001448 CHAN B0 (3.3V): 0.000175 CHAN B1 (3.15aV): -0.000052 CHAN B2 (3.15bV): -0.000705 CHAN B3 (GND): -0.000188 OPEN: 0.006203 Full Scale: +/- 1 mA 2025-07-08T17:45:40.322Z,1751996740.322 [NAL9602](INFO): SBD MO Status=1, MOMSN=25391, MT Status=0, MTMSN=0 2025-07-08T17:45:40.323Z,1751996740.323 [NAL9602](INFO): No messages in MT queue 2025-07-08T17:45:41.541Z,1751996741.541 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174540.00,A,3648.44621,N,12147.10251,W,4.646,7.64,080725,,,D*7D 2025-07-08T17:45:41.543Z,1751996741.543 [NAL9602](INFO): GPS fix at 20250708T174540: (36.807437, -121.785042) 2025-07-08T17:45:43.981Z,1751996743.981 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174543.00,A,3648.45005,N,12147.10187,W,4.626,8.16,080725,,,D*7B 2025-07-08T17:45:43.984Z,1751996743.984 [NAL9602](INFO): GPS fix at 20250708T174543: (36.807501, -121.785031) 2025-07-08T17:45:47.205Z,1751996747.205 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174546.00,A,3648.45382,N,12147.10109,W,4.646,10.04,080725,,,D*48 2025-07-08T17:45:47.207Z,1751996747.207 [NAL9602](INFO): GPS fix at 20250708T174546: (36.807564, -121.785018) 2025-07-08T17:45:50.018Z,1751996750.018 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174549.00,A,3648.45760,N,12147.10046,W,4.665,8.19,080725,,,D*71 2025-07-08T17:45:50.021Z,1751996750.021 [NAL9602](INFO): GPS fix at 20250708T174549: (36.807627, -121.785008) 2025-07-08T17:45:52.845Z,1751996752.845 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174552.00,A,3648.46151,N,12147.09999,W,4.646,4.24,080725,,,D*7C 2025-07-08T17:45:52.848Z,1751996752.848 [NAL9602](INFO): GPS fix at 20250708T174552: (36.807692, -121.785000) 2025-07-08T17:45:56.081Z,1751996756.081 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174555.00,A,3648.46534,N,12147.09996,W,4.607,0.36,080725,,,D*71 2025-07-08T17:45:56.083Z,1751996756.083 [NAL9602](INFO): GPS fix at 20250708T174555: (36.807756, -121.784999) 2025-07-08T17:45:58.909Z,1751996758.909 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174558.00,A,3648.46919,N,12147.10017,W,4.568,356.78,080725,,,D*77 2025-07-08T17:45:58.911Z,1751996758.911 [NAL9602](INFO): GPS fix at 20250708T174558: (36.807820, -121.785003) 2025-07-08T17:46:02.141Z,1751996762.141 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174601.00,A,3648.47296,N,12147.10074,W,4.529,352.45,080725,,,D*7F 2025-07-08T17:46:02.143Z,1751996762.143 [NAL9602](INFO): GPS fix at 20250708T174601: (36.807883, -121.785012) 2025-07-08T17:46:04.965Z,1751996764.965 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174604.00,A,3648.47668,N,12147.10177,W,4.529,348.50,080725,,,D*72 2025-07-08T17:46:04.976Z,1751996764.976 [NAL9602](INFO): GPS fix at 20250708T174604: (36.807945, -121.785029) 2025-07-08T17:46:04.994Z,1751996764.994 [IBIT](IMPORTANT): Communications Status: Fix Status: 1 Iridium Signal Strength: 3 Latitude: 36.807945 Longitude: -121.785027 2025-07-08T17:46:05.412Z,1751996765.412 [IBIT](IMPORTANT): Battery Status: Battery Charge (AH): 0.000000 Voltage: 16.254000 2025-07-08T17:46:05.412Z,1751996765.412 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah 2025-07-08T17:46:05.413Z,1751996765.413 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V 2025-07-08T17:46:05.791Z,1751996765.791 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 % 2025-07-08T17:46:05.791Z,1751996765.791 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi 2025-07-08T17:46:05.792Z,1751996765.792 [IBIT](IMPORTANT): Pressure:10.226425 PSI 2025-07-08T17:46:05.792Z,1751996765.792 [IBIT](IMPORTANT): Humidity:23.464779 % 2025-07-08T17:46:06.199Z,1751996766.199 [IBIT](IMPORTANT): Vehicle Pitch:0.018562 degrees 2025-07-08T17:46:06.199Z,1751996766.199 [IBIT](IMPORTANT): Vehicle Roll:7.920898 degrees 2025-07-08T17:46:06.199Z,1751996766.199 [IBIT](IMPORTANT): Vehicle Heading:338.404358 degrees 2025-07-08T17:46:06.607Z,1751996766.607 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m 2025-07-08T17:46:06.608Z,1751996766.608 [IBIT](IMPORTANT): buoyancyNeutral: 208.461777 cc 2025-07-08T17:46:06.608Z,1751996766.608 [IBIT](IMPORTANT): massDefault: 2.511143 cm 2025-07-08T17:46:06.608Z,1751996766.608 [IBIT](IMPORTANT): stopDepth: 290.000000 m 2025-07-08T17:46:06.608Z,1751996766.608 [IBIT](IMPORTANT): abortDepth: 300.000000 m 2025-07-08T17:46:06.609Z,1751996766.609 [IBIT](IMPORTANT): IBIT PASSED 2025-07-08T17:46:19.121Z,1751996779.121 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-08T17:46:19.121Z,1751996779.121 [Default:CheckIn:C.Wait] Stopped 2025-07-08T17:46:19.121Z,1751996779.121 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-08T17:46:19.121Z,1751996779.121 [Default:CheckIn:D] Running Loop=1 2025-07-08T17:46:19.519Z,1751996779.519 [Default:CheckIn:D] Stopped 2025-07-08T17:46:19.519Z,1751996779.519 [Default:CheckIn:E] Running Loop=1 2025-07-08T17:46:19.925Z,1751996779.925 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 15.731609 min 2025-07-08T17:46:19.925Z,1751996779.925 [Default:CheckIn:E] Stopped 2025-07-08T17:46:19.926Z,1751996779.926 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-08T17:46:19.926Z,1751996779.926 [Default:CheckIn] Stopped 2025-07-08T17:46:19.926Z,1751996779.926 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-08T17:46:19.926Z,1751996779.926 [Default:CheckIn](INFO): Running loop #3 2025-07-08T17:46:19.926Z,1751996779.926 [Default:CheckIn] Running Loop=3 2025-07-08T17:46:19.926Z,1751996779.926 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-08T17:46:19.926Z,1751996779.926 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-08T17:46:21.936Z,1751996781.936 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,174621.00,A,3648.49695,N,12147.11228,W,4.918,327.47,080725,,,D*70 2025-07-08T17:46:21.938Z,1751996781.938 [NAL9602](INFO): GPS fix at 20250708T174621: (36.808282, -121.785205) 2025-07-08T17:46:21.949Z,1751996781.949 [Default:CheckIn:Read_GPS] Stopped 2025-07-08T17:46:21.949Z,1751996781.949 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-08T17:46:37.742Z,1751996797.742 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250708T172627/Courier0016.lzma 2025-07-08T17:46:48.797Z,1751996808.797 [DataOverHttps](INFO): Moved sent file to Logs/20250708T172627/Courier0016.lzma.bak 2025-07-08T17:46:48.797Z,1751996808.797 [DataOverHttps](INFO): SBD MOMSN=25431367 2025-07-08T17:46:54.723Z,1751996814.723 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-08T17:47:18.673Z,1751996838.673 [DataOverHttps](INFO): Sending 1021 bytes from file Logs/20250708T172627/Express0017.lzma 2025-07-08T17:47:29.708Z,1751996849.708 [DataOverHttps](INFO): Moved sent file to Logs/20250708T172627/Express0017.lzma.bak 2025-07-08T17:47:29.708Z,1751996849.708 [DataOverHttps](INFO): SBD MOMSN=25431370 2025-07-08T17:47:55.746Z,1751996875.746 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20250708T172627/Express0020.lzma 2025-07-08T17:48:06.788Z,1751996886.788 [DataOverHttps](INFO): Moved sent file to Logs/20250708T172627/Express0020.lzma.bak 2025-07-08T17:48:06.788Z,1751996886.788 [DataOverHttps](INFO): SBD MOMSN=25431407 2025-07-08T17:48:07.885Z,1751996887.885 [Default:CheckIn:Read_Iridium] Stopped 2025-07-08T17:48:07.885Z,1751996887.885 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-08T17:48:07.885Z,1751996887.885 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-08T17:53:08.469Z,1751997188.469 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-08T17:53:08.470Z,1751997188.470 [Default:CheckIn:C.Wait] Stopped 2025-07-08T17:53:08.470Z,1751997188.470 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-08T17:53:08.470Z,1751997188.470 [Default:CheckIn:D] Running Loop=1 2025-07-08T17:53:08.881Z,1751997188.881 [Default:CheckIn:D] Stopped 2025-07-08T17:53:08.881Z,1751997188.881 [Default:CheckIn:E] Running Loop=1 2025-07-08T17:53:09.282Z,1751997189.282 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.554321 min 2025-07-08T17:53:09.282Z,1751997189.282 [Default:CheckIn:E] Stopped 2025-07-08T17:53:09.282Z,1751997189.282 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-08T17:53:09.282Z,1751997189.282 [Default:CheckIn] Stopped 2025-07-08T17:53:09.283Z,1751997189.283 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-08T17:53:09.283Z,1751997189.283 [Default:CheckIn](INFO): Running loop #4 2025-07-08T17:53:09.283Z,1751997189.283 [Default:CheckIn] Running Loop=4 2025-07-08T17:53:09.283Z,1751997189.283 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-08T17:53:09.283Z,1751997189.283 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-08T17:53:11.298Z,1751997191.298 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175310.00,A,3647.82823,N,12149.94630,W,29.041,265.23,080725,,,D*4C 2025-07-08T17:53:11.300Z,1751997191.300 [NAL9602](INFO): GPS fix at 20250708T175310: (36.797137, -121.832438) 2025-07-08T17:53:11.311Z,1751997191.311 [Default:CheckIn:Read_GPS] Stopped 2025-07-08T17:53:11.311Z,1751997191.311 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-08T17:53:19.143Z,1751997199.143 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250708T172627/Courier0022.lzma 2025-07-08T17:53:30.184Z,1751997210.184 [DataOverHttps](INFO): Moved sent file to Logs/20250708T172627/Courier0022.lzma.bak 2025-07-08T17:53:30.184Z,1751997210.184 [DataOverHttps](INFO): SBD MOMSN=25431576 2025-07-08T17:53:37.146Z,1751997217.146 [NAL9602](INFO): SBD MO Status=1, MOMSN=25392, MT Status=0, MTMSN=0 2025-07-08T17:53:37.146Z,1751997217.146 [NAL9602](INFO): No messages in MT queue 2025-07-08T17:53:58.257Z,1751997238.257 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20250708T172627/Express0023.lzma 2025-07-08T17:54:07.849Z,1751997247.849 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-08T17:54:09.308Z,1751997249.308 [DataOverHttps](INFO): Moved sent file to Logs/20250708T172627/Express0023.lzma.bak 2025-07-08T17:54:09.308Z,1751997249.308 [DataOverHttps](INFO): SBD MOMSN=25431581 2025-07-08T17:54:10.697Z,1751997250.697 [Default:CheckIn:Read_Iridium] Stopped 2025-07-08T17:54:10.697Z,1751997250.697 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-08T17:54:10.697Z,1751997250.697 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-08T17:59:11.306Z,1751997551.306 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2025-07-08T17:59:11.306Z,1751997551.306 [Default:CheckIn:C.Wait] Stopped 2025-07-08T17:59:11.307Z,1751997551.307 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2025-07-08T17:59:11.307Z,1751997551.307 [Default:CheckIn:D] Running Loop=1 2025-07-08T17:59:11.717Z,1751997551.717 [Default:CheckIn:D] Stopped 2025-07-08T17:59:11.717Z,1751997551.717 [Default:CheckIn:E] Running Loop=1 2025-07-08T17:59:12.115Z,1751997552.115 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 28.601587 min 2025-07-08T17:59:12.115Z,1751997552.115 [Default:CheckIn:E] Stopped 2025-07-08T17:59:12.116Z,1751997552.116 [Default:CheckIn](INFO): Completed Default:CheckIn 2025-07-08T17:59:12.116Z,1751997552.116 [Default:CheckIn] Stopped 2025-07-08T17:59:12.116Z,1751997552.116 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-07-08T17:59:12.116Z,1751997552.116 [Default:CheckIn](INFO): Running loop #5 2025-07-08T17:59:12.116Z,1751997552.116 [Default:CheckIn] Running Loop=5 2025-07-08T17:59:12.116Z,1751997552.116 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-07-08T17:59:12.116Z,1751997552.116 [Default:CheckIn:Read_GPS] Running Loop=1 2025-07-08T17:59:14.125Z,1751997554.125 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,175913.00,A,3647.69617,N,12150.81303,W,0.700,140.04,080725,,,D*78 2025-07-08T17:59:14.127Z,1751997554.127 [NAL9602](INFO): GPS fix at 20250708T175913: (36.794936, -121.846884) 2025-07-08T17:59:14.158Z,1751997554.158 [Default:CheckIn:Read_GPS] Stopped 2025-07-08T17:59:14.158Z,1751997554.158 [Default:CheckIn:Read_Iridium] Running Loop=1 2025-07-08T17:59:21.370Z,1751997561.370 [DataOverHttps](INFO): Sending 73 bytes from file Logs/20250708T172627/Courier0025.lzma 2025-07-08T17:59:24.664Z,1751997564.664 [NAL9602](INFO): SBD MO Status=1, MOMSN=25393, MT Status=0, MTMSN=0 2025-07-08T17:59:24.664Z,1751997564.664 [NAL9602](INFO): No messages in MT queue 2025-07-08T17:59:32.408Z,1751997572.408 [DataOverHttps](INFO): Moved sent file to Logs/20250708T172627/Courier0025.lzma.bak 2025-07-08T17:59:32.408Z,1751997572.408 [DataOverHttps](INFO): SBD MOMSN=25431597 2025-07-08T17:59:55.339Z,1751997595.339 [NAL9602](INFO): Not Powering down - fast GPS 2025-07-08T17:59:58.534Z,1751997598.534 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20250708T172627/Express0026.lzma 2025-07-08T18:00:09.576Z,1751997609.576 [DataOverHttps](INFO): Moved sent file to Logs/20250708T172627/Express0026.lzma.bak 2025-07-08T18:00:09.576Z,1751997609.576 [DataOverHttps](INFO): SBD MOMSN=25431603 2025-07-08T18:00:10.710Z,1751997610.710 [Default:CheckIn:Read_Iridium] Stopped 2025-07-08T18:00:10.710Z,1751997610.710 [Default:CheckIn:C.Wait] Running Loop=1 2025-07-08T18:00:10.710Z,1751997610.710 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2025-07-08T18:00:28.159Z,1751997628.159 [DataOverHttps](IMPORTANT): SBD MTMSN=20250708T180027