2025-07-16T22:36:38.147Z,1752705398.147 [Supervisor](DEBUG): Initializing supervisor.
2025-07-16T22:36:38.151Z,1752705398.151 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-07-16T22:36:38.151Z,1752705398.151 [SyncHandler](INFO): Protected caller Thread ID is 1041
2025-07-16T22:36:38.152Z,1752705398.152 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-07-16T22:36:38.153Z,1752705398.153 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-07-16T22:36:38.153Z,1752705398.153 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1042
2025-07-16T22:36:38.157Z,1752705398.157 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-07-16T22:36:38.178Z,1752705398.178 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-07-16T22:36:38.179Z,1752705398.179 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-07-16T22:36:38.179Z,1752705398.179 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1043
2025-07-16T22:36:38.183Z,1752705398.183 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-07-16T22:36:38.184Z,1752705398.184 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-07-16T22:36:38.185Z,1752705398.185 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1044
2025-07-16T22:36:38.187Z,1752705398.187 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-07-16T22:36:38.188Z,1752705398.188 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-07-16T22:36:38.188Z,1752705398.188 [logger ThreadHandler](INFO): Protected caller Thread ID is 1045
2025-07-16T22:36:38.192Z,1752705398.192 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-07-16T22:36:38.193Z,1752705398.193 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-07-16T22:36:38.194Z,1752705398.194 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-07-16T22:36:38.531Z,1752705398.531 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-07-16T22:36:38.531Z,1752705398.531 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-07-16T22:36:39.288Z,1752705399.288 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-07-16T22:36:39.288Z,1752705399.288 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-07-16T22:36:39.404Z,1752705399.404 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-07-16T22:36:39.405Z,1752705399.405 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-07-16T22:36:39.955Z,1752705399.955 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-07-16T22:36:39.956Z,1752705399.956 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-07-16T22:36:40.061Z,1752705400.061 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-07-16T22:36:40.061Z,1752705400.061 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-07-16T22:36:40.161Z,1752705400.161 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance
2025-07-16T22:36:40.161Z,1752705400.161 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-07-16T22:36:40.581Z,1752705400.581 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-07-16T22:36:40.582Z,1752705400.582 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-07-16T22:36:40.809Z,1752705400.809 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-07-16T22:36:40.810Z,1752705400.810 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-07-16T22:36:40.957Z,1752705400.957 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-07-16T22:36:40.957Z,1752705400.957 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-07-16T22:36:41.213Z,1752705401.213 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-07-16T22:36:41.213Z,1752705401.213 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-07-16T22:36:41.786Z,1752705401.786 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-07-16T22:36:41.787Z,1752705401.787 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-07-16T22:36:42.701Z,1752705402.701 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-07-16T22:36:42.702Z,1752705402.702 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-07-16T22:36:42.790Z,1752705402.790 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-07-16T22:36:42.980Z,1752705402.980 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-07-16T22:36:42.980Z,1752705402.980 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-07-16T22:36:43.238Z,1752705403.238 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-07-16T22:36:43.239Z,1752705403.239 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-07-16T22:36:43.550Z,1752705403.550 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-07-16T22:36:43.552Z,1752705403.552 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/
2025-07-16T22:36:43.553Z,1752705403.553 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg
2025-07-16T22:36:43.659Z,1752705403.659 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg
2025-07-16T22:36:43.819Z,1752705403.819 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg
2025-07-16T22:36:43.914Z,1752705403.914 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg
2025-07-16T22:36:44.007Z,1752705404.007 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg
2025-07-16T22:36:44.127Z,1752705404.127 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/workSite.cfg
2025-07-16T22:36:44.222Z,1752705404.222 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg
2025-07-16T22:36:44.336Z,1752705404.336 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg
2025-07-16T22:36:44.616Z,1752705404.616 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-07-16T22:36:44.617Z,1752705404.617 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg
2025-07-16T22:36:44.757Z,1752705404.757 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg
2025-07-16T22:36:44.858Z,1752705404.858 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg
2025-07-16T22:36:44.960Z,1752705404.960 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg
2025-07-16T22:36:45.052Z,1752705405.052 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-07-16T22:36:45.056Z,1752705405.056 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-07-16T22:36:45.196Z,1752705405.196 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-07-16T22:36:45.197Z,1752705405.197 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-07-16T22:36:45.207Z,1752705405.207 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-07-16T22:36:45.208Z,1752705405.208 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-07-16T22:36:45.260Z,1752705405.260 [DepthRateCalculator] Loaded
2025-07-16T22:36:45.260Z,1752705405.260 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-07-16T22:36:45.266Z,1752705405.266 [PitchRateCalculator] Loaded
2025-07-16T22:36:45.266Z,1752705405.266 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-07-16T22:36:45.277Z,1752705405.277 [SpeedCalculator] Loaded
2025-07-16T22:36:45.277Z,1752705405.277 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-07-16T22:36:45.282Z,1752705405.282 [YawRateCalculator] Loaded
2025-07-16T22:36:45.283Z,1752705405.283 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-07-16T22:36:45.305Z,1752705405.305 [ElevatorOffsetCalculator] Loaded
2025-07-16T22:36:45.305Z,1752705405.305 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-07-16T22:36:45.306Z,1752705405.306 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-07-16T22:36:45.306Z,1752705405.306 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-07-16T22:36:45.362Z,1752705405.362 [VerticalControl](DEBUG): Construct VerticalControl.
2025-07-16T22:36:45.420Z,1752705405.420 [VerticalControl] Loaded
2025-07-16T22:36:45.421Z,1752705405.421 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-07-16T22:36:45.424Z,1752705405.424 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-07-16T22:36:45.472Z,1752705405.472 [HorizontalControl] Loaded
2025-07-16T22:36:45.472Z,1752705405.472 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-07-16T22:36:45.474Z,1752705405.474 [SpeedControl](DEBUG): Construct SpeedControl.
2025-07-16T22:36:45.477Z,1752705405.477 [SpeedControl] Loaded
2025-07-16T22:36:45.478Z,1752705405.478 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-07-16T22:36:45.480Z,1752705405.480 [LoopControl](DEBUG): Construct LoopControl.
2025-07-16T22:36:45.481Z,1752705405.481 [LoopControl] Loaded
2025-07-16T22:36:45.481Z,1752705405.481 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-07-16T22:36:45.481Z,1752705405.481 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-07-16T22:36:45.482Z,1752705405.482 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-07-16T22:36:45.599Z,1752705405.599 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-07-16T22:36:45.599Z,1752705405.599 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-07-16T22:36:45.613Z,1752705405.613 [NavChart] Loaded
2025-07-16T22:36:45.613Z,1752705405.613 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-07-16T22:36:45.619Z,1752705405.619 [UniversalFixResidualReporter] Loaded
2025-07-16T22:36:45.619Z,1752705405.619 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-07-16T22:36:45.620Z,1752705405.620 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-07-16T22:36:45.620Z,1752705405.620 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-07-16T22:36:45.804Z,1752705405.804 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-07-16T22:36:45.804Z,1752705405.804 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-07-16T22:36:46.320Z,1752705406.320 [AHRS_M2] Loaded
2025-07-16T22:36:46.320Z,1752705406.320 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-07-16T22:36:46.360Z,1752705406.360 [BackseatComponent] Loaded
2025-07-16T22:36:46.360Z,1752705406.360 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-07-16T22:36:46.361Z,1752705406.361 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4093E4E0
2025-07-16T22:36:46.362Z,1752705406.362 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 1130
2025-07-16T22:36:46.364Z,1752705406.364 [LcmUniversalReporter] Loaded
2025-07-16T22:36:46.365Z,1752705406.365 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-07-16T22:36:47.306Z,1752705407.306 [BPC1] Loaded
2025-07-16T22:36:47.307Z,1752705407.307 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-07-16T22:36:47.502Z,1752705407.502 [DAT] Loaded
2025-07-16T22:36:47.502Z,1752705407.502 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-07-16T22:36:47.567Z,1752705407.567 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4096E4E0
2025-07-16T22:36:47.567Z,1752705407.567 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1131
2025-07-16T22:36:47.644Z,1752705407.644 [DataOverHttps] Loaded
2025-07-16T22:36:47.645Z,1752705407.645 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-07-16T22:36:47.646Z,1752705407.646 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4099E4E0
2025-07-16T22:36:47.646Z,1752705407.646 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1132
2025-07-16T22:36:47.711Z,1752705407.711 [Depth_Keller] Loaded
2025-07-16T22:36:47.711Z,1752705407.711 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-07-16T22:36:47.716Z,1752705407.716 [DropWeight] Loaded
2025-07-16T22:36:47.716Z,1752705407.716 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-07-16T22:36:47.809Z,1752705407.809 [NAL9602] Loaded
2025-07-16T22:36:47.810Z,1752705407.810 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-07-16T22:36:47.875Z,1752705407.875 [Onboard] Loaded
2025-07-16T22:36:47.875Z,1752705407.875 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-07-16T22:36:47.885Z,1752705407.885 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409CE4E0
2025-07-16T22:36:47.885Z,1752705407.885 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1133
2025-07-16T22:36:47.902Z,1752705407.902 [Power24vConverter] Loaded
2025-07-16T22:36:47.902Z,1752705407.902 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-07-16T22:36:47.919Z,1752705407.919 [Radio_Surface] Loaded
2025-07-16T22:36:47.919Z,1752705407.919 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-07-16T22:36:47.920Z,1752705407.920 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409FE4E0
2025-07-16T22:36:47.920Z,1752705407.920 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1134
2025-07-16T22:36:47.969Z,1752705407.969 [RDI_Pathfinder] Loaded
2025-07-16T22:36:47.971Z,1752705407.971 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2025-07-16T22:36:47.988Z,1752705407.988 [Sonardyne_Nano] Loaded
2025-07-16T22:36:47.989Z,1752705407.989 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-07-16T22:36:47.989Z,1752705407.989 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-07-16T22:36:47.990Z,1752705407.990 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-07-16T22:36:48.133Z,1752705408.133 [BuoyancyServo] Loaded
2025-07-16T22:36:48.133Z,1752705408.133 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-07-16T22:36:48.158Z,1752705408.158 [ElevatorServo] Loaded
2025-07-16T22:36:48.158Z,1752705408.158 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-07-16T22:36:48.191Z,1752705408.191 [DockingStepper] Loaded
2025-07-16T22:36:48.191Z,1752705408.191 [ComponentRegistry](DEBUG): SyncComponent "DockingStepper" handled in the control thread.
2025-07-16T22:36:48.211Z,1752705408.211 [MassServo] Loaded
2025-07-16T22:36:48.211Z,1752705408.211 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-07-16T22:36:48.232Z,1752705408.232 [RudderServo] Loaded
2025-07-16T22:36:48.233Z,1752705408.233 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-07-16T22:36:48.250Z,1752705408.250 [ThrusterHE] Loaded
2025-07-16T22:36:48.250Z,1752705408.250 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-07-16T22:36:48.250Z,1752705408.250 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-07-16T22:36:48.251Z,1752705408.251 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-07-16T22:36:48.312Z,1752705408.312 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-07-16T22:36:48.312Z,1752705408.312 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-07-16T22:36:48.342Z,1752705408.342 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-07-16T22:36:48.343Z,1752705408.343 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-07-16T22:36:48.699Z,1752705408.699 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-07-16T22:36:48.700Z,1752705408.700 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-07-16T22:36:48.878Z,1752705408.878 [CTD_Seabird] Loaded
2025-07-16T22:36:48.879Z,1752705408.879 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-07-16T22:36:48.880Z,1752705408.880 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40BBE4E0
2025-07-16T22:36:48.880Z,1752705408.880 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1135
2025-07-16T22:36:48.917Z,1752705408.917 [ESPComponent] Loaded
2025-07-16T22:36:48.917Z,1752705408.917 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2025-07-16T22:36:48.940Z,1752705408.940 [PAR_Licor] Loaded
2025-07-16T22:36:48.940Z,1752705408.940 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-07-16T22:36:48.986Z,1752705408.986 [WetLabsBB2FL] Loaded
2025-07-16T22:36:48.987Z,1752705408.987 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-07-16T22:36:48.988Z,1752705408.988 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40BEE4E0
2025-07-16T22:36:48.988Z,1752705408.988 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1136
2025-07-16T22:36:48.989Z,1752705408.989 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-07-16T22:36:48.989Z,1752705408.989 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-07-16T22:36:49.158Z,1752705409.158 [SBIT](DEBUG): Construct Startup Built In Test.
2025-07-16T22:36:49.167Z,1752705409.167 [SBIT] Loaded
2025-07-16T22:36:49.168Z,1752705409.168 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-07-16T22:36:49.171Z,1752705409.171 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-07-16T22:36:49.184Z,1752705409.184 [IBIT] Loaded
2025-07-16T22:36:49.184Z,1752705409.184 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-07-16T22:36:49.189Z,1752705409.189 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-07-16T22:36:49.273Z,1752705409.273 [CBIT] Loaded
2025-07-16T22:36:49.273Z,1752705409.273 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-07-16T22:36:49.306Z,1752705409.306 [GFScanner] Loaded
2025-07-16T22:36:49.307Z,1752705409.307 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-07-16T22:36:49.307Z,1752705409.307 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-07-16T22:36:49.314Z,1752705409.314 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-07-16T22:36:49.316Z,1752705409.316 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-07-16T22:36:49.328Z,1752705409.328 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-07-16T22:36:49.329Z,1752705409.329 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C694E0
2025-07-16T22:36:49.330Z,1752705409.330 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1137
2025-07-16T22:36:49.334Z,1752705409.334 [Supervisor](INFO): Main Thread ID is 825
2025-07-16T22:36:49.334Z,1752705409.334 [Supervisor](DEBUG): Running supervisor.
2025-07-16T22:36:49.335Z,1752705409.335 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1138
2025-07-16T22:36:49.335Z,1752705409.335 [CommandExec](INFO): Initializing the command executive.
2025-07-16T22:36:49.337Z,1752705409.337 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1139
2025-07-16T22:36:49.339Z,1752705409.339 [controlThread ThreadHandler](INFO): Handler Thread ID is 1140
2025-07-16T22:36:49.339Z,1752705409.339 [controlThread](DEBUG): Initializing ControlThread
2025-07-16T22:36:49.340Z,1752705409.340 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-07-16T22:36:49.341Z,1752705409.341 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-07-16T22:36:49.341Z,1752705409.341 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-07-16T22:36:49.341Z,1752705409.341 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-07-16T22:36:49.342Z,1752705409.342 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-07-16T22:36:49.342Z,1752705409.342 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-07-16T22:36:49.344Z,1752705409.344 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-07-16T22:36:49.344Z,1752705409.344 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-07-16T22:36:49.345Z,1752705409.345 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-07-16T22:36:49.346Z,1752705409.346 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-07-16T22:36:49.346Z,1752705409.346 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-07-16T22:36:49.353Z,1752705409.353 [SBIT](INFO): Initialize SBIT Component.
2025-07-16T22:36:49.354Z,1752705409.354 [SBIT](IMPORTANT): git: 2025-07-16
2025-07-16T22:36:49.354Z,1752705409.354 [SBIT](INFO): git hash: d69d2715ddd00fa751675e8858faf22d094d5d81
2025-07-16T22:36:49.354Z,1752705409.354 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-07-16T22:36:49.355Z,1752705409.355 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Sep 24 11:12:27 PDT 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2025-07-16T22:36:49.356Z,1752705409.356 [SBIT](INFO): Beginning SBIT in 95.000000 seconds.
2025-07-16T22:36:49.357Z,1752705409.357 [IBIT](INFO): Initialize IBIT Component.
2025-07-16T22:36:49.358Z,1752705409.358 [CBIT](DEBUG): Initialize CBIT Component.
2025-07-16T22:36:49.359Z,1752705409.359 [logger ThreadHandler](INFO): Handler Thread ID is 1141
2025-07-16T22:36:49.369Z,1752705409.369 [CBIT](DEBUG): Initialized mux pins.
2025-07-16T22:36:49.369Z,1752705409.369 [CBIT](DEBUG): Initializing the watchdog timer.
2025-07-16T22:36:49.377Z,1752705409.377 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 1142
2025-07-16T22:36:49.389Z,1752705409.389 [DAT ThreadHandler](INFO): Handler Thread ID is 1143
2025-07-16T22:36:49.390Z,1752705409.390 [DAT](INFO): Powering up
2025-07-16T22:36:49.390Z,1752705409.390 [DAT](DEBUG): Initializing DAT.
2025-07-16T22:36:49.393Z,1752705409.393 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-07-16T22:36:49.394Z,1752705409.394 [CBIT](DEBUG): Initializing heartbeat.
2025-07-16T22:36:49.395Z,1752705409.395 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1145
2025-07-16T22:36:49.397Z,1752705409.397 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-07-16T22:36:49.406Z,1752705409.406 [Onboard ThreadHandler](INFO): Handler Thread ID is 1146
2025-07-16T22:36:49.435Z,1752705409.435 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1147
2025-07-16T22:36:49.449Z,1752705409.449 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1148
2025-07-16T22:36:49.450Z,1752705409.450 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-07-16T22:36:49.454Z,1752705409.454 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1150
2025-07-16T22:36:49.457Z,1752705409.457 [WetLabsBB2FL](INFO): Powering up
2025-07-16T22:36:49.459Z,1752705409.459 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1152
2025-07-16T22:36:49.461Z,1752705409.461 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-07-16T22:36:49.461Z,1752705409.461 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-07-16T22:36:49.461Z,1752705409.461 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-07-16T22:36:49.462Z,1752705409.462 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-07-16T22:36:49.462Z,1752705409.462 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-07-16T22:36:49.462Z,1752705409.462 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-07-16T22:36:49.462Z,1752705409.462 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-07-16T22:36:49.462Z,1752705409.462 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-07-16T22:36:49.465Z,1752705409.465 [CBIT](DEBUG): Deactivating emergency mode.
2025-07-16T22:36:49.505Z,1752705409.505 [CBIT](DEBUG): Backplane powered.
2025-07-16T22:36:49.505Z,1752705409.505 [GFScanner](DEBUG): Initializing GFScanner
2025-07-16T22:36:49.505Z,1752705409.505 [GFScanner](DEBUG): Deactivating GF circuits.
2025-07-16T22:36:49.510Z,1752705409.510 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-07-16T22:36:49.511Z,1752705409.511 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-07-16T22:36:49.511Z,1752705409.511 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-07-16T22:36:49.512Z,1752705409.512 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-07-16T22:36:49.556Z,1752705409.556 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
2025-07-16T22:36:49.577Z,1752705409.577 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-07-16T22:36:49.633Z,1752705409.633 [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-16T22:36:49.634Z,1752705409.634 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-07-16T22:36:49.634Z,1752705409.634 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-07-16T22:36:49.635Z,1752705409.635 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-07-16T22:36:49.837Z,1752705409.837 [Radio_Surface](INFO): Powering up
2025-07-16T22:36:49.930Z,1752705409.930 [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-16T22:36:49.956Z,1752705409.956 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-07-16T22:36:49.959Z,1752705409.959 [Default:A.Wait](DEBUG): Construct Wait.
2025-07-16T22:36:49.961Z,1752705409.961 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-07-16T22:36:50.029Z,1752705410.029 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-07-16T22:36:50.032Z,1752705410.032 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-07-16T22:36:50.046Z,1752705410.046 [Default:E.Execute](DEBUG): Construct Execute.
2025-07-16T22:36:50.049Z,1752705410.049 [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-16T22:36:50.054Z,1752705410.054 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,RDI_Pathfinder,Sonardyne_Nano,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,DockingStepper,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
2025-07-16T22:36:50.078Z,1752705410.078 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-07-16T22:36:50.137Z,1752705410.137 [Depth_Keller](INFO): Initializing.
2025-07-16T22:36:50.139Z,1752705410.139 [Power24vConverter](INFO): Powering up.
2025-07-16T22:36:50.140Z,1752705410.140 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2025-07-16T22:36:50.146Z,1752705410.146 [Sonardyne_Nano](INFO): Initializing.
2025-07-16T22:36:50.177Z,1752705410.177 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-07-16T22:36:50.204Z,1752705410.204 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-07-16T22:36:50.209Z,1752705410.209 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-07-16T22:36:50.210Z,1752705410.210 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-07-16T22:36:50.217Z,1752705410.217 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-07-16T22:36:50.218Z,1752705410.218 [DockingStepper](DEBUG): Initializing EZServoServo.
2025-07-16T22:36:50.225Z,1752705410.225 [DockingStepper](DEBUG): Initializing DockingStepper.
2025-07-16T22:36:50.226Z,1752705410.226 [MassServo](DEBUG): Initializing EZServoServo.
2025-07-16T22:36:50.233Z,1752705410.233 [MassServo](DEBUG): Initializing MassServo.
2025-07-16T22:36:50.234Z,1752705410.234 [RudderServo](DEBUG): Initializing EZServoServo.
2025-07-16T22:36:50.242Z,1752705410.242 [RudderServo](DEBUG): Initializing RudderServo.
2025-07-16T22:36:50.242Z,1752705410.242 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-07-16T22:36:50.249Z,1752705410.249 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-07-16T22:36:51.565Z,1752705411.565 [WetLabsBB2FL](INFO): Powering down
2025-07-16T22:36:52.084Z,1752705412.084 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-07-16T22:36:53.742Z,1752705413.742 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2207
2025-07-16T22:36:54.145Z,1752705414.145 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging
2025-07-16T22:37:01.490Z,1752705421.490 [DAT](INFO): DAT read:
2025-07-16T22:37:01.492Z,1752705421.492 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series
2025-07-16T22:37:03.254Z,1752705423.254 [DAT](INFO): DAT read: MF Frequency Band
2025-07-16T22:37:03.255Z,1752705423.255 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0
2025-07-16T22:37:03.256Z,1752705423.256 [DAT](INFO): DAT read: Jul 16 2025 22:36:57
2025-07-16T22:37:04.514Z,1752705424.514 [DAT](INFO): DAT read: Features enabled [Bearing]
2025-07-16T22:37:04.515Z,1752705424.515 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP
2025-07-16T22:37:04.516Z,1752705424.516 [DAT](INFO): commRate: 600
2025-07-16T22:37:06.581Z,1752705426.581 [DAT](INFO): entering command mode
2025-07-16T22:37:06.783Z,1752705426.783 [DAT](INFO): DAT read:
2025-07-16T22:37:06.785Z,1752705426.785 [DAT](INFO): DAT read: user:1>
2025-07-16T22:37:06.786Z,1752705426.786 [DAT](INFO): setting verbose to 3
2025-07-16T22:37:07.034Z,1752705427.034 [DAT](INFO): DAT read: user:1>
2025-07-16T22:37:07.035Z,1752705427.035 [DAT](INFO): DAT read: Verbose | 3
2025-07-16T22:37:07.035Z,1752705427.035 [DAT](INFO): set verbose to 3
2025-07-16T22:37:07.035Z,1752705427.035 [DAT](INFO): setting DatVerbose to 27440
2025-07-16T22:37:07.286Z,1752705427.286 [DAT](INFO): DAT read: user:2>
2025-07-16T22:37:07.287Z,1752705427.287 [DAT](INFO): DAT read: DatVerbose | 27440
2025-07-16T22:37:07.287Z,1752705427.287 [DAT](INFO): set DatVerbose to 27440
2025-07-16T22:37:07.287Z,1752705427.287 [DAT](INFO): setting transmit power to 8
2025-07-16T22:37:07.538Z,1752705427.538 [DAT](INFO): DAT read: user:3>
2025-07-16T22:37:07.539Z,1752705427.539 [DAT](INFO): DAT read: TxPower | 8 (Max)
2025-07-16T22:37:07.539Z,1752705427.539 [DAT](INFO): set transmit power to 8
2025-07-16T22:37:07.539Z,1752705427.539 [DAT](INFO): setting local address to 11
2025-07-16T22:37:07.790Z,1752705427.790 [DAT](INFO): DAT read: user:4>
2025-07-16T22:37:07.791Z,1752705427.791 [DAT](INFO): DAT read: LocalAddr | 11
2025-07-16T22:37:07.791Z,1752705427.791 [DAT](INFO): set local address to 11
2025-07-16T22:37:07.792Z,1752705427.792 [DAT](INFO): Setting time to: 22:37:7 And date to:7/16/2025
2025-07-16T22:37:08.042Z,1752705428.042 [DAT](INFO): DAT read: user:5>
2025-07-16T22:37:08.043Z,1752705428.043 [DAT](INFO): DAT read: Wed Jul 16, 2025 22:37:07
2025-07-16T22:37:08.043Z,1752705428.043 [DAT](INFO): Local DAT time set to Wed Jul 16, 2025 22:37:07
2025-07-16T22:37:17.943Z,1752705437.943 [NAL9602](INFO): Powering up NAL9602
2025-07-16T22:37:28.855Z,1752705448.855 [NAL9602](INFO): NAL9602 initialized
2025-07-16T22:38:25.052Z,1752705505.052 [SBIT](IMPORTANT): Beginning Startup BIT
2025-07-16T22:38:25.073Z,1752705505.073 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-07-16T22:38:29.560Z,1752705509.560 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.003954
CHAN A1 (24V): -0.001506
CHAN A2 (12V): -0.000702
CHAN A3 (5V): -0.000343
CHAN B0 (3.3V): 0.000475
CHAN B1 (3.15aV): 0.000492
CHAN B2 (3.15bV): -0.000088
CHAN B3 (GND): 0.000328
OPEN: 0.004938
Full Scale: +/- 1 mA
2025-07-16T22:39:19.256Z,1752705559.256 [SBIT](IMPORTANT): SBIT PASSED
2025-07-16T22:39:19.257Z,1752705559.257 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-07-16T22:39:19.257Z,1752705559.257 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=207.059715 cubic_centimeter;
2025-07-16T22:39:19.258Z,1752705559.258 [SBIT](IMPORTANT): VerticalControl.massDefault=18.810418 millimeter;
2025-07-16T22:39:19.663Z,1752705559.663 [MissionManager](IMPORTANT): Started mission Startup
2025-07-16T22:39:19.663Z,1752705559.663 [Startup] Running Loop=1
2025-07-16T22:39:19.663Z,1752705559.663 [Startup](DEBUG): Aggregate::initialize Startup
2025-07-16T22:39:19.663Z,1752705559.663 [Startup:A.GoToSurface] Running Loop=1
2025-07-16T22:39:19.663Z,1752705559.663 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-07-16T22:39:19.664Z,1752705559.664 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-07-16T22:39:19.664Z,1752705559.664 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-07-16T22:39:19.665Z,1752705559.665 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-07-16T22:39:19.665Z,1752705559.665 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-07-16T22:39:19.666Z,1752705559.666 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-07-16T22:39:19.666Z,1752705559.666 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-07-16T22:39:19.668Z,1752705559.668 [Startup:StartupSatComms] Running Loop=1
2025-07-16T22:39:19.668Z,1752705559.668 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-07-16T22:39:19.668Z,1752705559.668 [Startup:StartupSatComms:A] Running Loop=1
2025-07-16T22:39:20.059Z,1752705560.059 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-07-16T22:39:26.478Z,1752705566.478 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004139
2025-07-16T22:39:49.596Z,1752705589.596 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-07-16T22:39:49.596Z,1752705589.596 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-16T22:39:49.627Z,1752705589.627 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-16T22:39:49.994Z,1752705589.994 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-16T22:39:49.994Z,1752705589.994 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-07-16T22:40:19.851Z,1752705619.851 [Startup:StartupSatComms:A](INFO): Timed out from 2025-07-16T22:39:19.7Z
2025-07-16T22:40:19.851Z,1752705619.851 [Startup:StartupSatComms:A] Stopped
2025-07-16T22:40:19.852Z,1752705619.852 [Startup:StartupSatComms:B] Running Loop=1
2025-07-16T22:40:20.259Z,1752705620.259 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-07-16T22:40:29.073Z,1752705629.073 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250716T220851/Courier0016.lzma
2025-07-16T22:40:30.075Z,1752705630.075 [DataOverHttps](INFO): Moved sent file to Logs/20250716T220851/Courier0016.lzma.bak
2025-07-16T22:40:30.075Z,1752705630.075 [DataOverHttps](INFO): SBD MOMSN=25536010
2025-07-16T22:40:55.070Z,1752705655.070 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00012202274219,35.0, +20.6, 0.0,1523.1,A, 0.00, 0.00, 0.00, 0.00, 0.00
2025-07-16T22:40:56.373Z,1752705656.373 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20250716T223638/Courier0000.lzma
2025-07-16T22:40:57.371Z,1752705657.371 [DataOverHttps](INFO): Moved sent file to Logs/20250716T223638/Courier0000.lzma.bak
2025-07-16T22:40:57.371Z,1752705657.371 [DataOverHttps](INFO): SBD MOMSN=25536012
2025-07-16T22:41:00.952Z,1752705660.952 [CommandExec](IMPORTANT): got command get latitude
2025-07-16T22:41:00.952Z,1752705660.952 [CommandExec](FAULT): Element has no value
2025-07-16T22:41:16.932Z,1752705676.932 [DataOverHttps](INFO): Sending 1593 bytes from file Logs/20250716T220851/Express0017.lzma
2025-07-16T22:41:17.931Z,1752705677.931 [DataOverHttps](INFO): Moved sent file to Logs/20250716T220851/Express0017.lzma.bak
2025-07-16T22:41:17.931Z,1752705677.931 [DataOverHttps](INFO): SBD MOMSN=25536015
2025-07-16T22:41:20.070Z,1752705680.070 [Startup:StartupSatComms:B](INFO): Timed out from 2025-07-16T22:40:19.9Z
2025-07-16T22:41:20.070Z,1752705680.070 [Startup:StartupSatComms:B] Stopped
2025-07-16T22:41:20.070Z,1752705680.070 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-07-16T22:41:20.071Z,1752705680.070 [Startup:StartupSatComms] Stopped
2025-07-16T22:41:20.071Z,1752705680.071 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-07-16T22:41:20.072Z,1752705680.072 [Startup](INFO): Completed Startup
2025-07-16T22:41:20.072Z,1752705680.072 [MissionManager](INFO): Startup is completed.
2025-07-16T22:41:20.072Z,1752705680.072 [MissionManager](INFO): Uninitializing Mission Startup
2025-07-16T22:41:20.072Z,1752705680.072 [Startup] Stopped
2025-07-16T22:41:20.072Z,1752705680.072 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-07-16T22:41:20.072Z,1752705680.072 [Startup:A.GoToSurface] Stopped
2025-07-16T22:41:20.072Z,1752705680.072 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-07-16T22:41:20.487Z,1752705680.487 [MissionManager](IMPORTANT): Started mission Default
2025-07-16T22:41:20.487Z,1752705680.487 [Default] Running Loop=1
2025-07-16T22:41:20.487Z,1752705680.487 [Default](DEBUG): Aggregate::initialize Default
2025-07-16T22:41:20.487Z,1752705680.487 [Default:B.GoToSurface] Running Loop=1
2025-07-16T22:41:20.487Z,1752705680.487 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-07-16T22:41:20.488Z,1752705680.488 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-07-16T22:41:20.488Z,1752705680.488 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-07-16T22:41:20.488Z,1752705680.488 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-07-16T22:41:20.489Z,1752705680.489 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-07-16T22:41:20.493Z,1752705680.493 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-07-16T22:41:20.493Z,1752705680.493 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-07-16T22:41:20.494Z,1752705680.494 [Default:A.Wait] Running Loop=1
2025-07-16T22:41:20.494Z,1752705680.494 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-07-16T22:41:29.042Z,1752705689.042 [CommandExec](IMPORTANT): got command show variable satellite
2025-07-16T22:41:29.175Z,1752705689.175 [CommandExec](IMPORTANT): NAL9602.SNRSatellite_0 (count)
2025-07-16T22:41:29.175Z,1752705689.175 [CommandExec](IMPORTANT): NAL9602.SNRSatellite_1 (count)
2025-07-16T22:41:29.175Z,1752705689.175 [CommandExec](IMPORTANT): NAL9602.SNRSatellite_2 (count)
2025-07-16T22:41:29.176Z,1752705689.176 [CommandExec](IMPORTANT): NAL9602.SNRSatellite_3 (count)
2025-07-16T22:41:29.176Z,1752705689.176 [CommandExec](IMPORTANT): NAL9602.SNRSatellite_4 (count)
2025-07-16T22:41:29.176Z,1752705689.176 [CommandExec](IMPORTANT): NAL9602.SNRSatellite_5 (count)
2025-07-16T22:41:29.177Z,1752705689.177 [CommandExec](IMPORTANT): NAL9602.SNRSatellite_6 (count)
2025-07-16T22:41:29.178Z,1752705689.178 [CommandExec](IMPORTANT): NAL9602.SNRSatellite_7 (count)
2025-07-16T22:41:29.178Z,1752705689.178 [CommandExec](IMPORTANT): NAL9602.SNRSatellite_8 (count)
2025-07-16T22:41:29.179Z,1752705689.179 [CommandExec](IMPORTANT): NAL9602.SNRSatellite_9 (count)
2025-07-16T22:41:29.179Z,1752705689.179 [CommandExec](IMPORTANT): NAL9602.SNRSatellite_10 (count)
2025-07-16T22:41:29.179Z,1752705689.179 [CommandExec](IMPORTANT): NAL9602.SNRSatellite_11 (count)
2025-07-16T22:41:29.180Z,1752705689.180 [CommandExec](IMPORTANT): NAL9602.numSatellites (count)
2025-07-16T22:41:33.816Z,1752705693.816 [Default:A.Wait](INFO): Done Waiting.
2025-07-16T22:41:33.816Z,1752705693.816 [Default:A.Wait] Stopped
2025-07-16T22:41:33.816Z,1752705693.816 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-07-16T22:41:34.222Z,1752705694.222 [Default:CheckIn] Running Loop=1
2025-07-16T22:41:34.223Z,1752705694.223 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-16T22:41:34.223Z,1752705694.223 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-16T22:41:34.854Z,1752705694.854 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-07-16T22:41:41.402Z,1752705701.402 [CommandExec](IMPORTANT): got command report touch NAL9602.numSatellites
2025-07-16T22:41:45.982Z,1752705705.982 [CommandExec](IMPORTANT): got command ibit
2025-07-16T22:41:46.322Z,1752705706.322 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-07-16T22:41:46.322Z,1752705706.322 [IBIT](IMPORTANT): Beginning control surface checks.
2025-07-16T22:41:46.326Z,1752705706.326 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-07-16T22:41:51.005Z,1752705711.005 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.004566
CHAN A1 (24V): -0.002236
CHAN A2 (12V): -0.000566
CHAN A3 (5V): 0.000067
CHAN B0 (3.3V): 0.000416
CHAN B1 (3.15aV): 0.000366
CHAN B2 (3.15bV): -0.000224
CHAN B3 (GND): 0.000806
OPEN: 0.004602
Full Scale: +/- 1 mA
2025-07-16T22:42:04.147Z,1752705724.147 [DataOverHttps](INFO): Sending 780 bytes from file Logs/20250716T223638/Express0001.lzma
2025-07-16T22:42:05.147Z,1752705725.147 [DataOverHttps](INFO): Moved sent file to Logs/20250716T223638/Express0001.lzma.bak
2025-07-16T22:42:05.147Z,1752705725.147 [DataOverHttps](INFO): SBD MOMSN=25536137
2025-07-16T22:42:28.750Z,1752705748.750 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-07-16T22:42:31.999Z,1752705751.999 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-07-16T22:42:50.619Z,1752705770.619 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2025-07-16T22:42:50.619Z,1752705770.619 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-16T22:42:50.651Z,1752705770.651 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-16T22:42:51.006Z,1752705771.006 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-16T22:42:51.006Z,1752705771.006 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2025-07-16T22:43:37.464Z,1752705817.464 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2025-07-16T22:43:37.874Z,1752705817.874 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active.
2025-07-16T22:43:38.252Z,1752705818.252 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2025-07-16T22:43:38.252Z,1752705818.252 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2025-07-16T22:43:38.253Z,1752705818.253 [IBIT](IMPORTANT): Pressure:9.832088 PSI
2025-07-16T22:43:38.253Z,1752705818.253 [IBIT](IMPORTANT): Humidity:12.269565 %
2025-07-16T22:43:38.676Z,1752705818.676 [IBIT](IMPORTANT): Vehicle Pitch:6.029231 degrees
2025-07-16T22:43:38.676Z,1752705818.676 [IBIT](IMPORTANT): Vehicle Roll:4.659247 degrees
2025-07-16T22:43:38.676Z,1752705818.676 [IBIT](IMPORTANT): Vehicle Heading:138.833344 degrees
2025-07-16T22:43:39.056Z,1752705819.056 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2025-07-16T22:43:39.058Z,1752705819.058 [IBIT](IMPORTANT): buoyancyNeutral: 207.059708 cc
2025-07-16T22:43:39.058Z,1752705819.058 [IBIT](IMPORTANT): massDefault: 1.881042 cm
2025-07-16T22:43:39.058Z,1752705819.058 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2025-07-16T22:43:39.058Z,1752705819.058 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2025-07-16T22:43:39.058Z,1752705819.058 [IBIT](IMPORTANT): IBIT FAILED
2025-07-16T22:45:51.583Z,1752705951.583 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2025-07-16T22:45:51.583Z,1752705951.583 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-16T22:45:51.603Z,1752705951.603 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-16T22:45:52.011Z,1752705952.011 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-16T22:45:52.012Z,1752705952.012 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2025-07-16T22:46:34.415Z,1752705994.415 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-16T22:41:34.2Z
2025-07-16T22:46:34.415Z,1752705994.415 [Default:CheckIn:Read_GPS] Stopped
2025-07-16T22:46:34.415Z,1752705994.415 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-16T22:46:34.848Z,1752705994.848 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-07-16T22:46:43.352Z,1752706003.352 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250716T223638/Courier0004.lzma
2025-07-16T22:46:44.355Z,1752706004.355 [DataOverHttps](INFO): Moved sent file to Logs/20250716T223638/Courier0004.lzma.bak
2025-07-16T22:46:44.355Z,1752706004.355 [DataOverHttps](INFO): SBD MOMSN=25536163
2025-07-16T22:47:04.294Z,1752706024.294 [DataOverHttps](INFO): Sending 924 bytes from file Logs/20250716T223638/Express0005.lzma
2025-07-16T22:47:05.295Z,1752706025.295 [DataOverHttps](INFO): Moved sent file to Logs/20250716T223638/Express0005.lzma.bak
2025-07-16T22:47:05.295Z,1752706025.295 [DataOverHttps](INFO): SBD MOMSN=25536165
2025-07-16T22:47:10.076Z,1752706030.076 [Default:CheckIn:Read_Iridium] Stopped
2025-07-16T22:47:10.076Z,1752706030.076 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-16T22:47:10.076Z,1752706030.076 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-16T22:47:18.287Z,1752706038.287 [CommandExec](IMPORTANT): got command ibit
2025-07-16T22:47:18.554Z,1752706038.554 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-07-16T22:47:18.554Z,1752706038.554 [IBIT](IMPORTANT): Beginning control surface checks.
2025-07-16T22:47:18.562Z,1752706038.562 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-07-16T22:47:23.206Z,1752706043.206 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005125
CHAN A1 (24V): -0.001680
CHAN A2 (12V): -0.000913
CHAN A3 (5V): -0.000104
CHAN B0 (3.3V): 0.000767
CHAN B1 (3.15aV): 0.000154
CHAN B2 (3.15bV): 0.000489
CHAN B3 (GND): 0.000925
OPEN: 0.005083
Full Scale: +/- 1 mA
2025-07-16T22:47:30.628Z,1752706050.628 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-07-16T22:47:30.628Z,1752706050.628 [NAL9602] Data Fault, FailCount= 1
2025-07-16T22:47:30.628Z,1752706050.628 [NAL9602](ERROR): Data Fault
2025-07-16T22:47:30.647Z,1752706050.647 [CBIT](ERROR): Data Fault in component: NAL9602
2025-07-16T22:47:31.028Z,1752706051.028 [NAL9602](INFO): Powering down
2025-07-16T22:47:31.853Z,1752706051.853 [CBIT](INFO): Clearing failed state for component NAL9602
2025-07-16T22:47:31.853Z,1752706051.853 [NAL9602] No Fault, FailCount= 1
2025-07-16T22:48:01.332Z,1752706081.332 [NAL9602](INFO): Powering up NAL9602
2025-07-16T22:48:12.256Z,1752706092.256 [NAL9602](INFO): NAL9602 initialized
2025-07-16T22:48:52.674Z,1752706132.674 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2025-07-16T22:48:52.674Z,1752706132.674 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-16T22:48:52.683Z,1752706132.683 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-16T22:48:53.106Z,1752706133.106 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-16T22:48:53.106Z,1752706133.106 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2025-07-16T22:49:09.677Z,1752706149.677 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2025-07-16T22:49:10.088Z,1752706150.088 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active.
2025-07-16T22:49:10.460Z,1752706150.460 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2025-07-16T22:49:10.461Z,1752706150.461 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2025-07-16T22:49:10.461Z,1752706150.461 [IBIT](IMPORTANT): Pressure:9.875242 PSI
2025-07-16T22:49:10.461Z,1752706150.461 [IBIT](IMPORTANT): Humidity:12.342815 %
2025-07-16T22:49:10.857Z,1752706150.857 [IBIT](IMPORTANT): Vehicle Pitch:2.883493 degrees
2025-07-16T22:49:10.858Z,1752706150.858 [IBIT](IMPORTANT): Vehicle Roll:-1.802531 degrees
2025-07-16T22:49:10.858Z,1752706150.858 [IBIT](IMPORTANT): Vehicle Heading:95.014297 degrees
2025-07-16T22:49:11.326Z,1752706151.326 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2025-07-16T22:49:11.330Z,1752706151.330 [IBIT](IMPORTANT): buoyancyNeutral: 207.059708 cc
2025-07-16T22:49:11.331Z,1752706151.331 [IBIT](IMPORTANT): massDefault: 1.881042 cm
2025-07-16T22:49:11.331Z,1752706151.331 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2025-07-16T22:49:11.331Z,1752706151.331 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2025-07-16T22:49:11.331Z,1752706151.331 [IBIT](IMPORTANT): IBIT FAILED
2025-07-16T22:51:53.669Z,1752706313.669 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2025-07-16T22:51:53.669Z,1752706313.669 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-16T22:51:53.681Z,1752706313.681 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-16T22:51:54.097Z,1752706314.097 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-16T22:51:54.097Z,1752706314.097 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2025-07-16T22:52:09.865Z,1752706329.865 [BPC1](ERROR): Failed to receive data from all battery packs.
2025-07-16T22:52:09.865Z,1752706329.865 [BPC1] Communications Fault, FailCount= 1
2025-07-16T22:52:09.865Z,1752706329.865 [BPC1](ERROR): Communications Fault
2025-07-16T22:52:09.994Z,1752706329.994 [CBIT](ERROR): Communications Fault in component: BPC1
2025-07-16T22:52:10.652Z,1752706330.652 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-16T22:52:10.652Z,1752706330.652 [Default:CheckIn:C.Wait] Stopped
2025-07-16T22:52:10.652Z,1752706330.652 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-16T22:52:10.652Z,1752706330.652 [Default:CheckIn:D] Running Loop=1
2025-07-16T22:52:11.059Z,1752706331.059 [Default:CheckIn:D] Stopped
2025-07-16T22:52:11.059Z,1752706331.059 [Default:CheckIn:E] Running Loop=1
2025-07-16T22:52:11.457Z,1752706331.457 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.842860 min
2025-07-16T22:52:11.457Z,1752706331.457 [Default:CheckIn:E] Stopped
2025-07-16T22:52:11.457Z,1752706331.457 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-16T22:52:11.457Z,1752706331.457 [Default:CheckIn] Stopped
2025-07-16T22:52:11.457Z,1752706331.457 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-16T22:52:11.457Z,1752706331.457 [Default:CheckIn](INFO): Running loop #2
2025-07-16T22:52:11.457Z,1752706331.457 [Default:CheckIn] Running Loop=2
2025-07-16T22:52:11.457Z,1752706331.457 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-16T22:52:11.457Z,1752706331.457 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-16T22:53:25.837Z,1752706405.837 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 0.00, 0.00, 0.00
2025-07-16T22:54:05.844Z,1752706445.844 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD, +0.00, , +0.00, 0.00,1023.01
2025-07-16T22:54:54.675Z,1752706494.675 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2025-07-16T22:54:54.675Z,1752706494.675 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-16T22:54:54.686Z,1752706494.686 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-16T22:54:55.092Z,1752706495.092 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-16T22:54:55.092Z,1752706495.092 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2025-07-16T22:57:11.617Z,1752706631.617 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-16T22:52:11.5Z
2025-07-16T22:57:11.617Z,1752706631.617 [Default:CheckIn:Read_GPS] Stopped
2025-07-16T22:57:11.617Z,1752706631.617 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-16T22:57:20.751Z,1752706640.751 [CommandExec](IMPORTANT): got command failComponent
2025-07-16T22:57:20.751Z,1752706640.751 [CommandExec](IMPORTANT): Failed components:
2025-07-16T22:57:20.751Z,1752706640.751 [CommandExec](IMPORTANT): BPC1: Communications Fault
2025-07-16T22:57:20.811Z,1752706640.811 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250716T223638/Courier0007.lzma
2025-07-16T22:57:21.797Z,1752706641.796 [DataOverHttps](INFO): Moved sent file to Logs/20250716T223638/Courier0007.lzma.bak
2025-07-16T22:57:21.797Z,1752706641.797 [DataOverHttps](INFO): SBD MOMSN=25536244
2025-07-16T22:57:28.867Z,1752706648.867 [CommandExec](IMPORTANT): got command ibit
2025-07-16T22:57:29.060Z,1752706649.060 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-07-16T22:57:29.060Z,1752706649.060 [IBIT](IMPORTANT): Beginning control surface checks.
2025-07-16T22:57:29.067Z,1752706649.067 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-07-16T22:57:33.722Z,1752706653.722 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.004380
CHAN A1 (24V): -0.001898
CHAN A2 (12V): -0.000928
CHAN A3 (5V): -0.000261
CHAN B0 (3.3V): 0.000643
CHAN B1 (3.15aV): 0.000733
CHAN B2 (3.15bV): 0.001033
CHAN B3 (GND): 0.000617
OPEN: 0.005347
Full Scale: +/- 1 mA
2025-07-16T22:57:41.596Z,1752706661.596 [DataOverHttps](INFO): Sending 748 bytes from file Logs/20250716T223638/Express0008.lzma
2025-07-16T22:57:42.596Z,1752706662.596 [DataOverHttps](INFO): Moved sent file to Logs/20250716T223638/Express0008.lzma.bak
2025-07-16T22:57:42.596Z,1752706662.596 [DataOverHttps](INFO): SBD MOMSN=25536246
2025-07-16T22:57:55.838Z,1752706675.838 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2025-07-16T22:57:55.838Z,1752706675.838 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-16T22:57:55.854Z,1752706675.854 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-16T22:57:56.168Z,1752706676.168 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-16T22:57:56.168Z,1752706676.168 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2025-07-16T22:58:01.155Z,1752706681.155 [CommandExec](IMPORTANT): got command failComponent
2025-07-16T22:58:01.155Z,1752706681.155 [CommandExec](IMPORTANT): Failed components:
2025-07-16T22:58:01.155Z,1752706681.155 [CommandExec](IMPORTANT): BPC1: Communications Fault
2025-07-16T22:58:06.913Z,1752706686.913 [CommandExec](IMPORTANT): got command failComponent none BPC1
2025-07-16T22:58:06.913Z,1752706686.913 [BPC1] No Fault, FailCount= 1
2025-07-16T22:58:06.914Z,1752706686.914 [CommandExec](IMPORTANT): BPC1 failureMode is No Fault
2025-07-16T22:58:12.731Z,1752706692.731 [CommandExec](IMPORTANT): got command failComponent
2025-07-16T22:58:12.731Z,1752706692.731 [CommandExec](IMPORTANT): Failed components:
2025-07-16T22:58:12.731Z,1752706692.731 [CommandExec](IMPORTANT): No failed Components.
2025-07-16T22:58:15.116Z,1752706695.116 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-07-16T22:58:15.116Z,1752706695.116 [NAL9602] Data Fault, FailCount= 2
2025-07-16T22:58:15.116Z,1752706695.116 [NAL9602](ERROR): Data Fault
2025-07-16T22:58:15.189Z,1752706695.189 [CBIT](ERROR): Data Fault in component: NAL9602
2025-07-16T22:58:15.524Z,1752706695.524 [NAL9602](INFO): Powering down
2025-07-16T22:58:16.375Z,1752706696.375 [CBIT](INFO): Clearing failed state for component NAL9602
2025-07-16T22:58:16.375Z,1752706696.375 [NAL9602] No Fault, FailCount= 2
2025-07-16T22:58:45.820Z,1752706725.820 [NAL9602](INFO): Powering up NAL9602
2025-07-16T22:58:56.736Z,1752706736.736 [NAL9602](INFO): NAL9602 initialized
2025-07-16T22:59:20.201Z,1752706760.201 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2025-07-16T22:59:20.600Z,1752706760.600 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active.
2025-07-16T22:59:21.025Z,1752706761.025 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2025-07-16T22:59:21.025Z,1752706761.025 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2025-07-16T22:59:21.026Z,1752706761.026 [IBIT](IMPORTANT): Pressure:9.930064 PSI
2025-07-16T22:59:21.030Z,1752706761.030 [IBIT](IMPORTANT): Humidity:12.342815 %
2025-07-16T22:59:21.425Z,1752706761.425 [IBIT](IMPORTANT): Vehicle Pitch:-3.172272 degrees
2025-07-16T22:59:21.425Z,1752706761.425 [IBIT](IMPORTANT): Vehicle Roll:-1.019427 degrees
2025-07-16T22:59:21.426Z,1752706761.426 [IBIT](IMPORTANT): Vehicle Heading:90.336464 degrees
2025-07-16T22:59:21.873Z,1752706761.873 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2025-07-16T22:59:21.874Z,1752706761.874 [IBIT](IMPORTANT): buoyancyNeutral: 207.059708 cc
2025-07-16T22:59:21.874Z,1752706761.874 [IBIT](IMPORTANT): massDefault: 1.881042 cm
2025-07-16T22:59:21.880Z,1752706761.880 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2025-07-16T22:59:21.881Z,1752706761.881 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2025-07-16T22:59:21.886Z,1752706761.886 [IBIT](IMPORTANT): IBIT FAILED
2025-07-16T22:59:22.208Z,1752706762.208 [Default:CheckIn:Read_Iridium] Stopped
2025-07-16T22:59:22.209Z,1752706762.209 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-16T22:59:22.209Z,1752706762.209 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-16T22:59:29.587Z,1752706769.587 [CommandExec](IMPORTANT): got command failComponent
2025-07-16T22:59:29.587Z,1752706769.587 [CommandExec](IMPORTANT): Failed components:
2025-07-16T22:59:29.587Z,1752706769.587 [CommandExec](IMPORTANT): No failed Components.
2025-07-16T22:59:31.983Z,1752706771.983 [CommandExec](IMPORTANT): got command failComponent
2025-07-16T22:59:31.983Z,1752706771.983 [CommandExec](IMPORTANT): Failed components:
2025-07-16T22:59:31.983Z,1752706771.983 [CommandExec](IMPORTANT): No failed Components.
2025-07-16T22:59:36.039Z,1752706776.039 [CommandExec](IMPORTANT): got command failComponent
2025-07-16T22:59:36.039Z,1752706776.039 [CommandExec](IMPORTANT): Failed components:
2025-07-16T22:59:36.039Z,1752706776.039 [CommandExec](IMPORTANT): No failed Components.
2025-07-16T23:00:56.727Z,1752706856.727 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2025-07-16T23:00:56.727Z,1752706856.727 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-16T23:00:56.767Z,1752706856.767 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-16T23:00:57.144Z,1752706857.144 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-16T23:00:57.145Z,1752706857.145 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2025-07-16T23:03:57.759Z,1752707037.759 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2025-07-16T23:03:57.759Z,1752707037.759 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-16T23:03:57.791Z,1752707037.791 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-16T23:03:58.149Z,1752707038.149 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-16T23:03:58.149Z,1752707038.149 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2025-07-16T23:04:22.795Z,1752707062.795 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-16T23:04:22.795Z,1752707062.795 [Default:CheckIn:C.Wait] Stopped
2025-07-16T23:04:22.795Z,1752707062.795 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-16T23:04:22.795Z,1752707062.795 [Default:CheckIn:D] Running Loop=1
2025-07-16T23:04:23.195Z,1752707063.195 [Default:CheckIn:D] Stopped
2025-07-16T23:04:23.195Z,1752707063.195 [Default:CheckIn:E] Running Loop=1
2025-07-16T23:04:23.628Z,1752707063.628 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 23.045133 min
2025-07-16T23:04:23.628Z,1752707063.628 [Default:CheckIn:E] Stopped
2025-07-16T23:04:23.628Z,1752707063.628 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-16T23:04:23.629Z,1752707063.629 [Default:CheckIn] Stopped
2025-07-16T23:04:23.629Z,1752707063.629 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-16T23:04:23.629Z,1752707063.629 [Default:CheckIn](INFO): Running loop #3
2025-07-16T23:04:23.629Z,1752707063.629 [Default:CheckIn] Running Loop=3
2025-07-16T23:04:23.629Z,1752707063.629 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-16T23:04:23.629Z,1752707063.629 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-16T23:06:58.895Z,1752707218.895 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2025-07-16T23:06:58.895Z,1752707218.895 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-16T23:06:58.906Z,1752707218.906 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-16T23:06:59.317Z,1752707219.317 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-16T23:06:59.317Z,1752707219.317 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2025-07-16T23:07:32.043Z,1752707252.043 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD, +0.00 +0.00, +0.00, 0.00,1829.02
2025-07-16T23:08:57.659Z,1752707337.659 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-07-16T23:08:57.659Z,1752707337.659 [NAL9602] Data Fault, FailCount= 3
2025-07-16T23:08:57.659Z,1752707337.659 [NAL9602](ERROR): Data Fault
2025-07-16T23:08:57.705Z,1752707337.705 [CBIT](ERROR): Data Fault in component: NAL9602
2025-07-16T23:08:58.067Z,1752707338.067 [NAL9602](INFO): Powering down
2025-07-16T23:08:58.899Z,1752707338.899 [CBIT](INFO): Clearing failed state for component NAL9602
2025-07-16T23:08:58.899Z,1752707338.899 [NAL9602] No Fault, FailCount= 3
2025-07-16T23:09:23.983Z,1752707363.983 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-16T23:04:23.6Z
2025-07-16T23:09:23.983Z,1752707363.983 [Default:CheckIn:Read_GPS] Stopped
2025-07-16T23:09:23.983Z,1752707363.983 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-16T23:09:28.368Z,1752707368.368 [NAL9602](INFO): Powering up NAL9602
2025-07-16T23:09:33.225Z,1752707373.225 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250716T223638/Courier0010.lzma
2025-07-16T23:09:34.227Z,1752707374.227 [DataOverHttps](INFO): Moved sent file to Logs/20250716T223638/Courier0010.lzma.bak
2025-07-16T23:09:34.227Z,1752707374.227 [DataOverHttps](INFO): SBD MOMSN=25536319
2025-07-16T23:09:39.280Z,1752707379.280 [NAL9602](INFO): NAL9602 initialized
2025-07-16T23:09:53.323Z,1752707393.323 [DataOverHttps](INFO): Sending 882 bytes from file Logs/20250716T223638/Express0011.lzma
2025-07-16T23:09:54.323Z,1752707394.323 [DataOverHttps](INFO): Moved sent file to Logs/20250716T223638/Express0011.lzma.bak
2025-07-16T23:09:54.323Z,1752707394.323 [DataOverHttps](INFO): SBD MOMSN=25536327
2025-07-16T23:09:58.702Z,1752707398.702 [Default:CheckIn:Read_Iridium] Stopped
2025-07-16T23:09:58.703Z,1752707398.703 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-16T23:09:58.703Z,1752707398.703 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-16T23:10:00.111Z,1752707400.111 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2025-07-16T23:10:00.111Z,1752707400.111 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-16T23:10:00.134Z,1752707400.134 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-16T23:10:00.365Z,1752707400.365 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-16T23:10:00.369Z,1752707400.369 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2025-07-16T23:11:27.796Z,1752707487.796 [DataOverHttps](IMPORTANT): SBD MTMSN=20250716T231127
2025-07-16T23:11:35.281Z,1752707495.281 [DataOverHttps](INFO): Received command: report clear
2025-07-16T23:11:35.319Z,1752707495.319 [CommandExec](IMPORTANT): got command report clear
2025-07-16T23:13:00.898Z,1752707580.898 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2025-07-16T23:13:00.898Z,1752707580.898 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-16T23:13:00.909Z,1752707580.909 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-16T23:13:01.314Z,1752707581.314 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-16T23:13:01.315Z,1752707581.315 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2025-07-16T23:14:59.262Z,1752707699.262 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-16T23:14:59.262Z,1752707699.262 [Default:CheckIn:C.Wait] Stopped
2025-07-16T23:14:59.262Z,1752707699.262 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-16T23:14:59.262Z,1752707699.262 [Default:CheckIn:D] Running Loop=1
2025-07-16T23:14:59.656Z,1752707699.656 [Default:CheckIn:D] Stopped
2025-07-16T23:14:59.656Z,1752707699.656 [Default:CheckIn:E] Running Loop=1
2025-07-16T23:15:00.096Z,1752707700.096 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 33.652820 min
2025-07-16T23:15:00.096Z,1752707700.096 [Default:CheckIn:E] Stopped
2025-07-16T23:15:00.096Z,1752707700.096 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-16T23:15:00.096Z,1752707700.096 [Default:CheckIn] Stopped
2025-07-16T23:15:00.096Z,1752707700.096 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-16T23:15:00.096Z,1752707700.096 [Default:CheckIn](INFO): Running loop #4
2025-07-16T23:15:00.096Z,1752707700.096 [Default:CheckIn] Running Loop=4
2025-07-16T23:15:00.096Z,1752707700.096 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-16T23:15:00.096Z,1752707700.096 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-16T23:16:01.895Z,1752707761.895 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2025-07-16T23:16:01.895Z,1752707761.895 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-16T23:16:01.922Z,1752707761.922 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-16T23:16:02.295Z,1752707762.295 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-16T23:16:02.296Z,1752707762.296 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2025-07-16T23:19:02.863Z,1752707942.863 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2025-07-16T23:19:02.863Z,1752707942.863 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-16T23:19:02.874Z,1752707942.874 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-16T23:19:03.287Z,1752707943.287 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-16T23:19:03.287Z,1752707943.287 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2025-07-16T23:19:17.822Z,1752707957.822 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. Device response is::WD, +0.00, , +0.00, 20.00,2534.95
2025-07-16T23:19:42.047Z,1752707982.047 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-07-16T23:19:42.047Z,1752707982.047 [NAL9602] Data Fault, FailCount= 4
2025-07-16T23:19:42.047Z,1752707982.047 [NAL9602](ERROR): Data Fault
2025-07-16T23:19:42.066Z,1752707982.066 [CBIT](ERROR): Data Fault in component: NAL9602
2025-07-16T23:19:42.443Z,1752707982.443 [NAL9602](INFO): Powering down
2025-07-16T23:19:43.269Z,1752707983.269 [CBIT](INFO): Clearing failed state for component NAL9602
2025-07-16T23:19:43.269Z,1752707983.269 [NAL9602] No Fault, FailCount= 4
2025-07-16T23:20:00.239Z,1752708000.239 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-16T23:15:00.1Z
2025-07-16T23:20:00.239Z,1752708000.239 [Default:CheckIn:Read_GPS] Stopped
2025-07-16T23:20:00.239Z,1752708000.239 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-16T23:20:09.773Z,1752708009.773 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250716T223638/Courier0013.lzma
2025-07-16T23:20:10.775Z,1752708010.775 [DataOverHttps](INFO): Moved sent file to Logs/20250716T223638/Courier0013.lzma.bak
2025-07-16T23:20:10.775Z,1752708010.775 [DataOverHttps](INFO): SBD MOMSN=25536389
2025-07-16T23:20:12.743Z,1752708012.743 [NAL9602](INFO): Powering up NAL9602
2025-07-16T23:20:23.655Z,1752708023.655 [NAL9602](INFO): NAL9602 initialized
2025-07-16T23:20:29.857Z,1752708029.857 [DataOverHttps](INFO): Sending 239 bytes from file Logs/20250716T223638/Express0014.lzma
2025-07-16T23:20:32.298Z,1752708032.298 [DataOverHttps](INFO): Moved sent file to Logs/20250716T223638/Express0014.lzma.bak
2025-07-16T23:20:32.298Z,1752708032.298 [DataOverHttps](INFO): SBD MOMSN=25536397
2025-07-16T23:20:50.685Z,1752708050.685 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250716T223638/Express0017.lzma
2025-07-16T23:20:51.687Z,1752708051.687 [DataOverHttps](INFO): Moved sent file to Logs/20250716T223638/Express0017.lzma.bak
2025-07-16T23:20:51.687Z,1752708051.687 [DataOverHttps](INFO): SBD MOMSN=25536403
2025-07-16T23:20:56.002Z,1752708056.002 [Default:CheckIn:Read_Iridium] Stopped
2025-07-16T23:20:56.002Z,1752708056.002 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-16T23:20:56.002Z,1752708056.002 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-16T23:22:03.949Z,1752708123.949 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2025-07-16T23:22:03.949Z,1752708123.949 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-16T23:22:03.992Z,1752708123.992 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-16T23:22:04.321Z,1752708124.321 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-16T23:22:04.321Z,1752708124.321 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2025-07-16T23:25:04.859Z,1752708304.859 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2025-07-16T23:25:04.859Z,1752708304.859 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-16T23:25:04.870Z,1752708304.870 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-16T23:25:05.245Z,1752708305.245 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-16T23:25:05.245Z,1752708305.245 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2025-07-16T23:25:56.563Z,1752708356.563 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-16T23:25:56.563Z,1752708356.563 [Default:CheckIn:C.Wait] Stopped
2025-07-16T23:25:56.563Z,1752708356.563 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-16T23:25:56.563Z,1752708356.563 [Default:CheckIn:D] Running Loop=1
2025-07-16T23:25:56.959Z,1752708356.959 [Default:CheckIn:D] Stopped
2025-07-16T23:25:56.959Z,1752708356.959 [Default:CheckIn:E] Running Loop=1
2025-07-16T23:25:57.364Z,1752708357.364 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 44.607857 min
2025-07-16T23:25:57.364Z,1752708357.364 [Default:CheckIn:E] Stopped
2025-07-16T23:25:57.364Z,1752708357.364 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-16T23:25:57.364Z,1752708357.364 [Default:CheckIn] Stopped
2025-07-16T23:25:57.365Z,1752708357.365 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-16T23:25:57.365Z,1752708357.365 [Default:CheckIn](INFO): Running loop #5
2025-07-16T23:25:57.365Z,1752708357.365 [Default:CheckIn] Running Loop=5
2025-07-16T23:25:57.365Z,1752708357.365 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-16T23:25:57.365Z,1752708357.365 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-16T23:26:03.396Z,1752708363.396 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-07-16T23:26:16.324Z,1752708376.324 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-07-16T23:26:29.252Z,1752708389.252 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-07-16T23:26:42.179Z,1752708402.179 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-07-16T23:26:55.108Z,1752708415.108 [BPC1](ERROR): BPC1A got IPBS message with 5 sticks (min is 7).
2025-07-16T23:28:05.858Z,1752708485.858 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2025-07-16T23:28:05.858Z,1752708485.858 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-16T23:28:05.873Z,1752708485.873 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-16T23:28:06.244Z,1752708486.244 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-16T23:28:06.244Z,1752708486.244 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2025-07-16T23:30:25.718Z,1752708625.718 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-07-16T23:30:25.718Z,1752708625.718 [NAL9602] Data Fault, FailCount= 5
2025-07-16T23:30:25.718Z,1752708625.718 [NAL9602](ERROR): Data Fault
2025-07-16T23:30:25.765Z,1752708625.765 [CBIT](ERROR): Data Fault in component: NAL9602
2025-07-16T23:30:25.765Z,1752708625.765 [CBIT](CRITICAL): Data Fault in component: NAL9602
2025-07-16T23:30:26.134Z,1752708626.134 [NAL9602](INFO): Powering down
2025-07-16T23:30:26.186Z,1752708626.186 [CommandExec](FAULT): Scheduling is paused
2025-07-16T23:30:26.187Z,1752708626.187 [CBIT](INFO): Critical error at 20250716T233025
2025-07-16T23:30:26.187Z,1752708626.187 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2025-07-16T23:30:57.654Z,1752708657.654 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-16T23:25:57.4Z
2025-07-16T23:30:57.654Z,1752708657.654 [Default:CheckIn:Read_GPS] Stopped
2025-07-16T23:30:57.654Z,1752708657.654 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-16T23:31:06.363Z,1752708666.363 [DataOverHttps](INFO): Sending 105 bytes from file Logs/20250716T223638/Courier0019.lzma
2025-07-16T23:31:06.935Z,1752708666.935 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2025-07-16T23:31:06.935Z,1752708666.935 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-16T23:31:06.970Z,1752708666.970 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-16T23:31:07.362Z,1752708667.362 [DataOverHttps](INFO): Moved sent file to Logs/20250716T223638/Courier0019.lzma.bak
2025-07-16T23:31:07.362Z,1752708667.362 [DataOverHttps](INFO): SBD MOMSN=25536460
2025-07-16T23:31:07.374Z,1752708667.374 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-16T23:31:07.374Z,1752708667.374 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2025-07-16T23:31:26.464Z,1752708686.464 [DataOverHttps](INFO): Sending 176 bytes from file Logs/20250716T223638/Express0020.lzma
2025-07-16T23:31:27.466Z,1752708687.466 [DataOverHttps](INFO): Moved sent file to Logs/20250716T223638/Express0020.lzma.bak
2025-07-16T23:31:27.466Z,1752708687.466 [DataOverHttps](INFO): SBD MOMSN=25536463
2025-07-16T23:31:31.586Z,1752708691.586 [Default:CheckIn:Read_Iridium] Stopped
2025-07-16T23:31:31.586Z,1752708691.586 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-16T23:31:31.586Z,1752708691.586 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-16T23:32:25.824Z,1752708745.824 [CBIT](INFO): Clearing failed state for component NAL9602
2025-07-16T23:32:25.824Z,1752708745.824 [NAL9602] No Fault, FailCount= 5
2025-07-16T23:32:26.210Z,1752708746.210 [NAL9602](INFO): Powering up NAL9602
2025-07-16T23:32:37.120Z,1752708757.120 [NAL9602](INFO): NAL9602 initialized
2025-07-16T23:33:11.453Z,1752708791.453 [BPC1](ERROR): Battery stick #5 (s/n: 00C2) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-07-16T23:33:11.454Z,1752708791.454 [BPC1](ERROR): Battery stick #8 (s/n: 027C) reported TERMINATE_CHARGE_ALARM. Status code: 0x40C0.
2025-07-16T23:33:11.455Z,1752708791.455 [BPC1](ERROR): Battery stick #17 (s/n: 00C9) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-16T23:33:11.455Z,1752708791.455 [BPC1](ERROR): Battery stick #18 (s/n: 0186) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-16T23:33:11.456Z,1752708791.456 [BPC1](ERROR): Battery stick #24 (s/n: 011E) reported TERMINATE_CHARGE_ALARM. Status code: 0x40C0.
2025-07-16T23:33:11.457Z,1752708791.457 [BPC1](ERROR): Battery stick #26 (s/n: 0112) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-07-16T23:33:11.458Z,1752708791.458 [BPC1](ERROR): Battery stick #27 (s/n: 00AF) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-16T23:33:11.459Z,1752708791.459 [BPC1](ERROR): Battery stick #37 (s/n: 00A3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-07-16T23:33:11.459Z,1752708791.459 [BPC1](ERROR): Battery stick #58 (s/n: 00F3) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-07-16T23:33:11.460Z,1752708791.460 [BPC1](INFO): Calculating totals. Valid battery stick count: 35. Valid reserve battery stick count: 2.
2025-07-16T23:33:11.463Z,1752708791.463 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2025-07-16T23:34:02.362Z,1752708842.362 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS +0.00, +0.00, 20.00,3418.84
2025-07-16T23:34:08.122Z,1752708848.122 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2025-07-16T23:34:08.122Z,1752708848.122 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-16T23:34:08.170Z,1752708848.170 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-16T23:34:08.455Z,1752708848.455 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-16T23:34:08.455Z,1752708848.455 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2025-07-16T23:36:32.295Z,1752708992.295 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-16T23:36:32.295Z,1752708992.295 [Default:CheckIn:C.Wait] Stopped
2025-07-16T23:36:32.295Z,1752708992.295 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-16T23:36:32.295Z,1752708992.295 [Default:CheckIn:D] Running Loop=1
2025-07-16T23:36:32.675Z,1752708992.675 [Default:CheckIn:D] Stopped
2025-07-16T23:36:32.676Z,1752708992.676 [Default:CheckIn:E] Running Loop=1
2025-07-16T23:36:33.078Z,1752708993.078 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 55.203137 min
2025-07-16T23:36:33.078Z,1752708993.078 [Default:CheckIn:E] Stopped
2025-07-16T23:36:33.078Z,1752708993.078 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-16T23:36:33.078Z,1752708993.078 [Default:CheckIn] Stopped
2025-07-16T23:36:33.078Z,1752708993.078 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-16T23:36:33.079Z,1752708993.079 [Default:CheckIn](INFO): Running loop #6
2025-07-16T23:36:33.079Z,1752708993.079 [Default:CheckIn] Running Loop=6
2025-07-16T23:36:33.079Z,1752708993.079 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-16T23:36:33.079Z,1752708993.079 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-16T23:37:09.033Z,1752709029.033 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2025-07-16T23:37:09.033Z,1752709029.033 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-16T23:37:09.044Z,1752709029.044 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-16T23:37:09.447Z,1752709029.447 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-16T23:37:09.447Z,1752709029.447 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20
2025-07-16T23:40:10.042Z,1752709210.042 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 21
2025-07-16T23:40:10.042Z,1752709210.042 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-16T23:40:10.053Z,1752709210.053 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-16T23:40:10.419Z,1752709210.419 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-16T23:40:10.419Z,1752709210.419 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 21
2025-07-16T23:41:33.275Z,1752709293.275 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-16T23:36:33.1Z
2025-07-16T23:41:33.275Z,1752709293.275 [Default:CheckIn:Read_GPS] Stopped
2025-07-16T23:41:33.275Z,1752709293.275 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-16T23:41:42.068Z,1752709302.068 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20250716T223638/Courier0022.lzma
2025-07-16T23:41:43.070Z,1752709303.070 [DataOverHttps](INFO): Moved sent file to Logs/20250716T223638/Courier0022.lzma.bak
2025-07-16T23:41:43.070Z,1752709303.070 [DataOverHttps](INFO): SBD MOMSN=25536486
2025-07-16T23:42:02.204Z,1752709322.204 [DataOverHttps](INFO): Sending 110 bytes from file Logs/20250716T223638/Express0023.lzma
2025-07-16T23:42:03.206Z,1752709323.206 [DataOverHttps](INFO): Moved sent file to Logs/20250716T223638/Express0023.lzma.bak
2025-07-16T23:42:03.206Z,1752709323.206 [DataOverHttps](INFO): SBD MOMSN=25536491
2025-07-16T23:42:07.586Z,1752709327.586 [Default:CheckIn:Read_Iridium] Stopped
2025-07-16T23:42:07.586Z,1752709327.586 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-16T23:42:07.586Z,1752709327.586 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-16T23:42:38.270Z,1752709358.270 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-07-16T23:42:38.270Z,1752709358.270 [NAL9602] Data Fault, FailCount= 1
2025-07-16T23:42:38.270Z,1752709358.270 [NAL9602](ERROR): Data Fault
2025-07-16T23:42:38.288Z,1752709358.288 [CBIT](ERROR): Data Fault in component: NAL9602
2025-07-16T23:42:38.669Z,1752709358.669 [NAL9602](INFO): Powering down
2025-07-16T23:42:39.496Z,1752709359.496 [CBIT](INFO): Clearing failed state for component NAL9602
2025-07-16T23:42:39.496Z,1752709359.496 [NAL9602] No Fault, FailCount= 1
2025-07-16T23:43:08.977Z,1752709388.977 [NAL9602](INFO): Powering up NAL9602
2025-07-16T23:43:11.026Z,1752709391.026 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 22
2025-07-16T23:43:11.026Z,1752709391.026 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-16T23:43:11.037Z,1752709391.037 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-16T23:43:11.434Z,1752709391.434 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-16T23:43:11.434Z,1752709391.434 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 22
2025-07-16T23:43:19.882Z,1752709399.882 [NAL9602](INFO): NAL9602 initialized
2025-07-16T23:46:12.041Z,1752709572.041 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 23
2025-07-16T23:46:12.041Z,1752709572.041 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-16T23:46:12.091Z,1752709572.091 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-16T23:46:12.410Z,1752709572.410 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-16T23:46:12.410Z,1752709572.410 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 23
2025-07-16T23:47:08.169Z,1752709628.169 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-16T23:47:08.169Z,1752709628.169 [Default:CheckIn:C.Wait] Stopped
2025-07-16T23:47:08.169Z,1752709628.169 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-16T23:47:08.169Z,1752709628.169 [Default:CheckIn:D] Running Loop=1
2025-07-16T23:47:08.593Z,1752709628.593 [Default:CheckIn:D] Stopped
2025-07-16T23:47:08.593Z,1752709628.593 [Default:CheckIn:E] Running Loop=1
2025-07-16T23:47:09.109Z,1752709629.109 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 65.801766 min
2025-07-16T23:47:09.109Z,1752709629.109 [Default:CheckIn:E] Stopped
2025-07-16T23:47:09.109Z,1752709629.109 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-16T23:47:09.109Z,1752709629.109 [Default:CheckIn] Stopped
2025-07-16T23:47:09.109Z,1752709629.109 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-16T23:47:09.109Z,1752709629.109 [Default:CheckIn](INFO): Running loop #7
2025-07-16T23:47:09.109Z,1752709629.109 [Default:CheckIn] Running Loop=7
2025-07-16T23:47:09.109Z,1752709629.109 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-16T23:47:09.109Z,1752709629.109 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-16T23:49:13.052Z,1752709753.052 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 24
2025-07-16T23:49:13.052Z,1752709753.052 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-16T23:49:13.078Z,1752709753.078 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-16T23:49:13.490Z,1752709753.490 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-16T23:49:13.490Z,1752709753.490 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 24
2025-07-16T23:49:14.656Z,1752709754.656 [NAL9602](ERROR): parseGSV uart error: serial timeout
2025-07-16T23:49:46.265Z,1752709786.265 [NAL9602](ERROR): getFix uart error: serial timeout
2025-07-16T23:50:16.647Z,1752709816.647 [NAL9602](ERROR): parseGSV uart error: serial timeout
2025-07-16T23:50:48.265Z,1752709848.265 [NAL9602](ERROR): getFix uart error: serial timeout
2025-07-16T23:51:18.634Z,1752709878.634 [NAL9602](ERROR): parseGSV uart error: serial timeout
2025-07-16T23:51:50.249Z,1752709910.249 [NAL9602](ERROR): getFix uart error: serial timeout
2025-07-16T23:52:09.351Z,1752709929.351 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-16T23:47:09.1Z
2025-07-16T23:52:09.352Z,1752709929.352 [Default:CheckIn:Read_GPS] Stopped
2025-07-16T23:52:09.352Z,1752709929.352 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-16T23:52:14.168Z,1752709934.168 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 25
2025-07-16T23:52:14.169Z,1752709934.169 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-16T23:52:14.212Z,1752709934.212 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-16T23:52:14.602Z,1752709934.602 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-16T23:52:14.602Z,1752709934.602 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 25
2025-07-16T23:52:18.104Z,1752709938.104 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250716T223638/Courier0025.lzma
2025-07-16T23:52:19.106Z,1752709939.106 [DataOverHttps](INFO): Moved sent file to Logs/20250716T223638/Courier0025.lzma.bak
2025-07-16T23:52:19.107Z,1752709939.107 [DataOverHttps](INFO): SBD MOMSN=25536511
2025-07-16T23:52:20.610Z,1752709940.610 [NAL9602](ERROR): parseGSV uart error: serial timeout
2025-07-16T23:52:38.285Z,1752709958.285 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250716T223638/Express0026.lzma
2025-07-16T23:52:39.287Z,1752709959.286 [DataOverHttps](INFO): Moved sent file to Logs/20250716T223638/Express0026.lzma.bak
2025-07-16T23:52:39.287Z,1752709959.287 [DataOverHttps](INFO): SBD MOMSN=25536516
2025-07-16T23:52:43.663Z,1752709963.663 [Default:CheckIn:Read_Iridium] Stopped
2025-07-16T23:52:43.663Z,1752709963.663 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-16T23:52:43.663Z,1752709963.663 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-16T23:52:52.225Z,1752709972.225 [NAL9602](ERROR): getFix uart error: serial timeout
2025-07-16T23:53:22.574Z,1752710002.574 [NAL9602](ERROR): parseGSV uart error: serial timeout
2025-07-16T23:53:23.438Z,1752710003.438 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-07-16T23:53:23.438Z,1752710003.438 [NAL9602] Data Fault, FailCount= 2
2025-07-16T23:53:23.438Z,1752710003.438 [NAL9602](ERROR): Data Fault
2025-07-16T23:53:23.543Z,1752710003.543 [CBIT](ERROR): Data Fault in component: NAL9602
2025-07-16T23:53:23.790Z,1752710003.790 [NAL9602](INFO): Powering down
2025-07-16T23:53:24.610Z,1752710004.610 [CBIT](INFO): Clearing failed state for component NAL9602
2025-07-16T23:53:24.610Z,1752710004.610 [NAL9602] No Fault, FailCount= 2
2025-07-16T23:53:54.086Z,1752710034.086 [NAL9602](INFO): Powering up NAL9602
2025-07-16T23:54:04.998Z,1752710044.998 [NAL9602](INFO): NAL9602 initialized
2025-07-16T23:55:15.341Z,1752710115.341 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 26
2025-07-16T23:55:15.341Z,1752710115.341 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-16T23:55:15.352Z,1752710115.352 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-16T23:55:15.720Z,1752710115.720 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-16T23:55:15.721Z,1752710115.721 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 26
2025-07-16T23:57:44.403Z,1752710264.403 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-16T23:57:44.403Z,1752710264.403 [Default:CheckIn:C.Wait] Stopped
2025-07-16T23:57:44.403Z,1752710264.403 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-16T23:57:44.403Z,1752710264.403 [Default:CheckIn:D] Running Loop=1
2025-07-16T23:57:44.842Z,1752710264.842 [Default:CheckIn:D] Stopped
2025-07-16T23:57:44.842Z,1752710264.842 [Default:CheckIn:E] Running Loop=1
2025-07-16T23:57:45.237Z,1752710265.237 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 76.405908 min
2025-07-16T23:57:45.237Z,1752710265.237 [Default:CheckIn:E] Stopped
2025-07-16T23:57:45.237Z,1752710265.237 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-16T23:57:45.237Z,1752710265.237 [Default:CheckIn] Stopped
2025-07-16T23:57:45.237Z,1752710265.237 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-16T23:57:45.237Z,1752710265.237 [Default:CheckIn](INFO): Running loop #8
2025-07-16T23:57:45.237Z,1752710265.237 [Default:CheckIn] Running Loop=8
2025-07-16T23:57:45.237Z,1752710265.237 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-16T23:57:45.238Z,1752710265.238 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-16T23:58:00.938Z,1752710280.938 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA, 0.00, 0.00, 0.00, 0.00
2025-07-16T23:58:16.301Z,1752710296.301 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 27
2025-07-16T23:58:16.301Z,1752710296.301 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-16T23:58:16.348Z,1752710296.348 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-16T23:58:16.727Z,1752710296.727 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-16T23:58:16.727Z,1752710296.727 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 27
2025-07-17T00:01:17.328Z,1752710477.328 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 28
2025-07-17T00:01:17.328Z,1752710477.328 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-17T00:01:17.381Z,1752710477.381 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-17T00:01:17.715Z,1752710477.715 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-17T00:01:17.715Z,1752710477.715 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 28
2025-07-17T00:02:45.382Z,1752710565.382 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-16T23:57:45.2Z
2025-07-17T00:02:45.382Z,1752710565.382 [Default:CheckIn:Read_GPS] Stopped
2025-07-17T00:02:45.382Z,1752710565.382 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-17T00:02:54.737Z,1752710574.737 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250716T223638/Courier0028.lzma
2025-07-17T00:02:55.739Z,1752710575.739 [DataOverHttps](INFO): Moved sent file to Logs/20250716T223638/Courier0028.lzma.bak
2025-07-17T00:02:55.740Z,1752710575.740 [DataOverHttps](INFO): SBD MOMSN=25536577
2025-07-17T00:03:16.425Z,1752710596.425 [DataOverHttps](INFO): Sending 152 bytes from file Logs/20250716T223638/Express0029.lzma
2025-07-17T00:03:17.427Z,1752710597.427 [DataOverHttps](INFO): Moved sent file to Logs/20250716T223638/Express0029.lzma.bak
2025-07-17T00:03:17.428Z,1752710597.428 [DataOverHttps](INFO): SBD MOMSN=25536595
2025-07-17T00:03:21.736Z,1752710601.736 [Default:CheckIn:Read_Iridium] Stopped
2025-07-17T00:03:21.736Z,1752710601.736 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-17T00:03:21.736Z,1752710601.736 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-17T00:03:27.037Z,1752710607.037 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude. Device response is::BD, +0.00, +52
2025-07-17T00:04:07.363Z,1752710647.363 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-07-17T00:04:07.363Z,1752710647.363 [NAL9602] Data Fault, FailCount= 3
2025-07-17T00:04:07.363Z,1752710647.363 [NAL9602](ERROR): Data Fault
2025-07-17T00:04:07.382Z,1752710647.382 [CBIT](ERROR): Data Fault in component: NAL9602
2025-07-17T00:04:07.769Z,1752710647.769 [NAL9602](INFO): Powering down
2025-07-17T00:04:08.588Z,1752710648.588 [CBIT](INFO): Clearing failed state for component NAL9602
2025-07-17T00:04:08.588Z,1752710648.588 [NAL9602] No Fault, FailCount= 3
2025-07-17T00:04:18.269Z,1752710658.269 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 29
2025-07-17T00:04:18.270Z,1752710658.270 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-17T00:04:18.281Z,1752710658.281 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-17T00:04:18.689Z,1752710658.689 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-17T00:04:18.689Z,1752710658.689 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 29
2025-07-17T00:04:38.058Z,1752710678.058 [NAL9602](INFO): Powering up NAL9602
2025-07-17T00:04:48.975Z,1752710688.975 [NAL9602](INFO): NAL9602 initialized
2025-07-17T00:07:19.294Z,1752710839.294 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 30
2025-07-17T00:07:19.294Z,1752710839.294 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-17T00:07:19.305Z,1752710839.305 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-17T00:07:19.680Z,1752710839.680 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-17T00:07:19.680Z,1752710839.680 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 30
2025-07-17T00:08:22.304Z,1752710902.304 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-17T00:08:22.304Z,1752710902.304 [Default:CheckIn:C.Wait] Stopped
2025-07-17T00:08:22.304Z,1752710902.304 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-17T00:08:22.304Z,1752710902.304 [Default:CheckIn:D] Running Loop=1
2025-07-17T00:08:22.696Z,1752710902.696 [Default:CheckIn:D] Stopped
2025-07-17T00:08:22.696Z,1752710902.696 [Default:CheckIn:E] Running Loop=1
2025-07-17T00:08:23.143Z,1752710903.143 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 87.036808 min
2025-07-17T00:08:23.143Z,1752710903.143 [Default:CheckIn:E] Stopped
2025-07-17T00:08:23.143Z,1752710903.143 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-17T00:08:23.143Z,1752710903.143 [Default:CheckIn] Stopped
2025-07-17T00:08:23.143Z,1752710903.143 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-17T00:08:23.143Z,1752710903.143 [Default:CheckIn](INFO): Running loop #9
2025-07-17T00:08:23.143Z,1752710903.143 [Default:CheckIn] Running Loop=9
2025-07-17T00:08:23.144Z,1752710903.144 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-17T00:08:23.144Z,1752710903.144 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-17T00:09:49.155Z,1752710989.155 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,00012203563631,35.0, +23.0, 0.0 +0.00, 20.00,5565.43
2025-07-17T00:10:20.259Z,1752711020.259 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 31
2025-07-17T00:10:20.259Z,1752711020.259 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-17T00:10:20.298Z,1752711020.298 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-17T00:10:20.667Z,1752711020.667 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-17T00:10:20.667Z,1752711020.667 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 31
2025-07-17T00:10:57.021Z,1752711057.021 [RDI_Pathfinder](ERROR): only read 1 of 4 data items for water velocity. Device response is::WS,-32768,
2025-07-17T00:13:21.253Z,1752711201.253 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 32
2025-07-17T00:13:21.253Z,1752711201.253 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-17T00:13:21.292Z,1752711201.292 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-17T00:13:21.659Z,1752711201.659 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-17T00:13:21.659Z,1752711201.659 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 32
2025-07-17T00:13:23.272Z,1752711203.272 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-17T00:08:23.1Z
2025-07-17T00:13:23.272Z,1752711203.272 [Default:CheckIn:Read_GPS] Stopped
2025-07-17T00:13:23.272Z,1752711203.272 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-17T00:13:32.505Z,1752711212.505 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250716T223638/Courier0031.lzma
2025-07-17T00:13:33.507Z,1752711213.507 [DataOverHttps](INFO): Moved sent file to Logs/20250716T223638/Courier0031.lzma.bak
2025-07-17T00:13:33.507Z,1752711213.507 [DataOverHttps](INFO): SBD MOMSN=25536616
2025-07-17T00:13:52.518Z,1752711232.518 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250716T223638/Express0032.lzma
2025-07-17T00:13:53.519Z,1752711233.519 [DataOverHttps](INFO): Moved sent file to Logs/20250716T223638/Express0032.lzma.bak
2025-07-17T00:13:53.520Z,1752711233.520 [DataOverHttps](INFO): SBD MOMSN=25536621
2025-07-17T00:13:57.631Z,1752711237.631 [Default:CheckIn:Read_Iridium] Stopped
2025-07-17T00:13:57.632Z,1752711237.632 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-17T00:13:57.632Z,1752711237.632 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-17T00:14:51.739Z,1752711291.739 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-07-17T00:14:51.739Z,1752711291.739 [NAL9602] Data Fault, FailCount= 4
2025-07-17T00:14:51.739Z,1752711291.739 [NAL9602](ERROR): Data Fault
2025-07-17T00:14:51.792Z,1752711291.792 [CBIT](ERROR): Data Fault in component: NAL9602
2025-07-17T00:14:52.151Z,1752711292.151 [NAL9602](INFO): Powering down
2025-07-17T00:14:52.994Z,1752711292.994 [CBIT](INFO): Clearing failed state for component NAL9602
2025-07-17T00:14:52.994Z,1752711292.994 [NAL9602] No Fault, FailCount= 4
2025-07-17T00:15:22.451Z,1752711322.451 [NAL9602](INFO): Powering up NAL9602
2025-07-17T00:15:33.351Z,1752711333.351 [NAL9602](INFO): NAL9602 initialized
2025-07-17T00:16:22.253Z,1752711382.253 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 33
2025-07-17T00:16:22.254Z,1752711382.254 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-17T00:16:22.264Z,1752711382.264 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-17T00:16:22.733Z,1752711382.733 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-17T00:16:22.734Z,1752711382.734 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 33
2025-07-17T00:18:47.154Z,1752711527.154 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-17T00:18:49.166Z,1752711529.166 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=60.251038
2025-07-17T00:18:58.204Z,1752711538.204 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-07-17T00:18:58.204Z,1752711538.204 [Default:CheckIn:C.Wait] Stopped
2025-07-17T00:18:58.204Z,1752711538.204 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-07-17T00:18:58.204Z,1752711538.204 [Default:CheckIn:D] Running Loop=1
2025-07-17T00:18:58.624Z,1752711538.624 [Default:CheckIn:D] Stopped
2025-07-17T00:18:58.624Z,1752711538.624 [Default:CheckIn:E] Running Loop=1
2025-07-17T00:18:58.996Z,1752711538.996 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 97.635612 min
2025-07-17T00:18:58.996Z,1752711538.996 [Default:CheckIn:E] Stopped
2025-07-17T00:18:58.996Z,1752711538.996 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-07-17T00:18:58.996Z,1752711538.996 [Default:CheckIn] Stopped
2025-07-17T00:18:58.996Z,1752711538.996 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-07-17T00:18:58.996Z,1752711538.996 [Default:CheckIn](INFO): Running loop #10
2025-07-17T00:18:58.996Z,1752711538.996 [Default:CheckIn] Running Loop=10
2025-07-17T00:18:58.997Z,1752711538.997 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-07-17T00:18:58.997Z,1752711538.997 [Default:CheckIn:Read_GPS] Running Loop=1
2025-07-17T00:19:22.298Z,1752711562.298 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-17T00:19:23.262Z,1752711563.262 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 34
2025-07-17T00:19:23.262Z,1752711563.262 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-17T00:19:23.273Z,1752711563.273 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-17T00:19:23.675Z,1752711563.675 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-17T00:19:23.675Z,1752711563.675 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 34
2025-07-17T00:19:57.446Z,1752711597.446 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2025-07-17T00:20:29.994Z,1752711629.994 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.002844
2025-07-17T00:21:22.067Z,1752711682.067 [DataOverHttps](IMPORTANT): SBD MTMSN=20250717T002121
2025-07-17T00:21:29.629Z,1752711689.629 [DataOverHttps](INFO): Received command: ibit
2025-07-17T00:21:29.640Z,1752711689.640 [CommandExec](IMPORTANT): got command ibit
2025-07-17T00:21:29.705Z,1752711689.705 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-07-17T00:21:29.706Z,1752711689.706 [IBIT](IMPORTANT): Beginning control surface checks.
2025-07-17T00:21:29.709Z,1752711689.709 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-07-17T00:21:34.333Z,1752711694.333 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.004001
CHAN A1 (24V): -0.000861
CHAN A2 (12V): -0.000498
CHAN A3 (5V): 0.000027
CHAN B0 (3.3V): 0.000758
CHAN B1 (3.15aV): 0.000636
CHAN B2 (3.15bV): 0.000769
CHAN B3 (GND): 0.000799
OPEN: 0.005055
Full Scale: +/- 1 mA
2025-07-17T00:22:24.226Z,1752711744.226 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 35
2025-07-17T00:22:24.226Z,1752711744.226 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-17T00:22:24.237Z,1752711744.237 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-17T00:22:24.658Z,1752711744.658 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-17T00:22:24.658Z,1752711744.658 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 35
2025-07-17T00:23:21.031Z,1752711801.031 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2025-07-17T00:23:21.392Z,1752711801.392 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.452999
2025-07-17T00:23:21.393Z,1752711801.393 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2025-07-17T00:23:21.393Z,1752711801.393 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2025-07-17T00:23:21.797Z,1752711801.797 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2025-07-17T00:23:21.797Z,1752711801.797 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2025-07-17T00:23:21.798Z,1752711801.798 [IBIT](IMPORTANT): Pressure:9.974279 PSI
2025-07-17T00:23:21.798Z,1752711801.798 [IBIT](IMPORTANT): Humidity:12.208522 %
2025-07-17T00:23:22.221Z,1752711802.221 [IBIT](IMPORTANT): Vehicle Pitch:-0.918031 degrees
2025-07-17T00:23:22.222Z,1752711802.222 [IBIT](IMPORTANT): Vehicle Roll:-12.167588 degrees
2025-07-17T00:23:22.222Z,1752711802.222 [IBIT](IMPORTANT): Vehicle Heading:184.371994 degrees
2025-07-17T00:23:22.663Z,1752711802.663 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2025-07-17T00:23:22.663Z,1752711802.663 [IBIT](IMPORTANT): buoyancyNeutral: 207.059708 cc
2025-07-17T00:23:22.663Z,1752711802.663 [IBIT](IMPORTANT): massDefault: 1.881042 cm
2025-07-17T00:23:22.664Z,1752711802.664 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2025-07-17T00:23:22.664Z,1752711802.664 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2025-07-17T00:23:22.664Z,1752711802.664 [IBIT](IMPORTANT): IBIT FAILED
2025-07-17T00:23:59.378Z,1752711839.378 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-07-17T00:18:58.0Z
2025-07-17T00:23:59.378Z,1752711839.378 [Default:CheckIn:Read_GPS] Stopped
2025-07-17T00:23:59.378Z,1752711839.378 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-07-17T00:24:08.672Z,1752711848.672 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250716T223638/Courier0034.lzma
2025-07-17T00:24:09.675Z,1752711849.675 [DataOverHttps](INFO): Moved sent file to Logs/20250716T223638/Courier0034.lzma.bak
2025-07-17T00:24:09.675Z,1752711849.675 [DataOverHttps](INFO): SBD MOMSN=25536672
2025-07-17T00:24:30.090Z,1752711870.090 [DataOverHttps](INFO): Sending 759 bytes from file Logs/20250716T223638/Express0035.lzma
2025-07-17T00:24:31.091Z,1752711871.091 [DataOverHttps](INFO): Moved sent file to Logs/20250716T223638/Express0035.lzma.bak
2025-07-17T00:24:31.091Z,1752711871.091 [DataOverHttps](INFO): SBD MOMSN=25536677
2025-07-17T00:24:35.338Z,1752711875.338 [Default:CheckIn:Read_Iridium] Stopped
2025-07-17T00:24:35.338Z,1752711875.338 [Default:CheckIn:C.Wait] Running Loop=1
2025-07-17T00:24:35.338Z,1752711875.338 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-07-17T00:25:14.091Z,1752711914.091 [NAL9602](ERROR): parseGSV uart error: serial timeout
2025-07-17T00:25:25.429Z,1752711925.429 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 36
2025-07-17T00:25:25.429Z,1752711925.429 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-07-17T00:25:25.448Z,1752711925.448 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-07-17T00:25:25.854Z,1752711925.854 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-07-17T00:25:25.855Z,1752711925.855 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 36
2025-07-17T00:25:34.295Z,1752711934.295 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-07-17T00:25:34.295Z,1752711934.295 [NAL9602] Data Fault, FailCount= 5
2025-07-17T00:25:34.295Z,1752711934.295 [NAL9602](ERROR): Data Fault
2025-07-17T00:25:34.313Z,1752711934.313 [CBIT](ERROR): Data Fault in component: NAL9602
2025-07-17T00:25:34.314Z,1752711934.314 [CBIT](CRITICAL): Data Fault in component: NAL9602
2025-07-17T00:25:34.695Z,1752711934.695 [NAL9602](INFO): Powering down
2025-07-17T00:25:34.756Z,1752711934.756 [CBIT](INFO): Critical error at 20250717T002534
2025-07-17T00:26:04.398Z,1752711964.398 [DataOverHttps](IMPORTANT): SBD MTMSN=20250717T002604
2025-07-17T00:26:11.901Z,1752711971.901 [DataOverHttps](INFO): Received command: ibit
2025-07-17T00:26:11.938Z,1752711971.938 [CommandExec](IMPORTANT): got command ibit
2025-07-17T00:26:12.286Z,1752711972.286 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-07-17T00:26:12.287Z,1752711972.287 [IBIT](IMPORTANT): Beginning control surface checks.
2025-07-17T00:26:12.290Z,1752711972.290 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-07-17T00:26:16.942Z,1752711976.942 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.004562
CHAN A1 (24V): -0.002120
CHAN A2 (12V): -0.001444
CHAN A3 (5V): -0.000113
CHAN B0 (3.3V): 0.000254
CHAN B1 (3.15aV): 0.000166
CHAN B2 (3.15bV): 0.000354
CHAN B3 (GND): 0.000373
OPEN: 0.005202
Full Scale: +/- 1 mA
2025-07-17T00:27:34.315Z,1752712054.315 [CBIT](INFO): Clearing failed state for component NAL9602
2025-07-17T00:27:34.315Z,1752712054.315 [NAL9602] No Fault, FailCount= 5
2025-07-17T00:27:34.687Z,1752712054.687 [NAL9602](INFO): Powering up NAL9602
2025-07-17T00:27:45.595Z,1752712065.595 [NAL9602](INFO): NAL9602 initialized
2025-07-17T00:27:59.435Z,1752712079.435 [DataOverHttps](IMPORTANT): SBD MTMSN=20250717T002759
2025-07-17T00:28:03.414Z,1752712083.414 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2025-07-17T00:28:03.813Z,1752712083.813 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.452999
2025-07-17T00:28:03.814Z,1752712083.814 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2025-07-17T00:28:03.814Z,1752712083.814 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2025-07-17T00:28:04.216Z,1752712084.216 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2025-07-17T00:28:04.217Z,1752712084.217 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2025-07-17T00:28:04.221Z,1752712084.221 [IBIT](IMPORTANT): Pressure:9.978699 PSI
2025-07-17T00:28:04.222Z,1752712084.222 [IBIT](IMPORTANT): Humidity:12.257356 %
2025-07-17T00:28:04.670Z,1752712084.670 [IBIT](IMPORTANT): Vehicle Pitch:-0.926650 degrees
2025-07-17T00:28:04.670Z,1752712084.670 [IBIT](IMPORTANT): Vehicle Roll:-12.155788 degrees
2025-07-17T00:28:04.670Z,1752712084.670 [IBIT](IMPORTANT): Vehicle Heading:184.332474 degrees
2025-07-17T00:28:05.015Z,1752712085.015 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2025-07-17T00:28:05.016Z,1752712085.016 [IBIT](IMPORTANT): buoyancyNeutral: 207.059708 cc
2025-07-17T00:28:05.016Z,1752712085.016 [IBIT](IMPORTANT): massDefault: 1.881042 cm
2025-07-17T00:28:05.016Z,1752712085.016 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2025-07-17T00:28:05.016Z,1752712085.016 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2025-07-17T00:28:05.017Z,1752712085.017 [IBIT](IMPORTANT): IBIT FAILED
2025-07-17T00:28:07.106Z,1752712087.106 [DataOverHttps](INFO): Received command: restart sys
2025-07-17T00:28:07.150Z,1752712087.150 [CommandExec](IMPORTANT): got command restart system
2025-07-17T00:28:09.229Z,1752712089.229 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-17T00:28:09.229Z,1752712089.229 [CommandExec](INFO): Uninitializing the command executive.
2025-07-17T00:28:09.230Z,1752712089.230 [CommandExec](INFO): Uninitializing the command scheduler.
2025-07-17T00:28:09.230Z,1752712089.230 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-07-17T00:28:09.317Z,1752712089.317 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye!
2025-07-17T00:28:09.317Z,1752712089.317 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-07-17T00:28:09.318Z,1752712089.318 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-07-17T00:28:09.318Z,1752712089.318 [NavChartDb](INFO): Join timeout helper Thread ID is 1405
2025-07-17T00:28:09.661Z,1752712089.661 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-17T00:28:09.661Z,1752712089.661 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-07-17T00:28:09.673Z,1752712089.673 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-07-17T00:28:09.673Z,1752712089.673 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-07-17T00:28:09.674Z,1752712089.674 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1406
2025-07-17T00:28:09.989Z,1752712089.989 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-17T00:28:09.989Z,1752712089.989 [WetLabsBB2FL](INFO): Powering down
2025-07-17T00:28:09.990Z,1752712089.990 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-07-17T00:28:09.993Z,1752712089.993 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-07-17T00:28:09.993Z,1752712089.993 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-07-17T00:28:09.993Z,1752712089.993 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1407
2025-07-17T00:28:10.117Z,1752712090.117 [CTD_Seabird](INFO): Powering down
2025-07-17T00:28:10.129Z,1752712090.129 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-17T00:28:10.129Z,1752712090.129 [CTD_Seabird](INFO): Powering down
2025-07-17T00:28:10.141Z,1752712090.141 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-07-17T00:28:10.153Z,1752712090.153 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-07-17T00:28:10.153Z,1752712090.153 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-07-17T00:28:10.154Z,1752712090.154 [Radio_Surface](INFO): Join timeout helper Thread ID is 1408
2025-07-17T00:28:10.474Z,1752712090.474 [Radio_Surface](INFO): Powering down
2025-07-17T00:28:10.476Z,1752712090.476 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-17T00:28:10.478Z,1752712090.478 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-07-17T00:28:10.481Z,1752712090.481 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-07-17T00:28:10.482Z,1752712090.482 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-07-17T00:28:10.483Z,1752712090.483 [Onboard](INFO): Join timeout helper Thread ID is 1409
2025-07-17T00:28:11.117Z,1752712091.117 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-07-17T00:28:12.781Z,1752712092.781 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-07-17T00:28:12.782Z,1752712092.782 [Onbo