2025-08-15T22:05:08.109Z,1755295508.109 [Supervisor](DEBUG): Initializing supervisor.
2025-08-15T22:05:08.113Z,1755295508.113 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-08-15T22:05:08.114Z,1755295508.114 [SyncHandler](INFO): Protected caller Thread ID is 2257
2025-08-15T22:05:08.114Z,1755295508.114 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-08-15T22:05:08.115Z,1755295508.115 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-08-15T22:05:08.115Z,1755295508.115 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2258
2025-08-15T22:05:08.119Z,1755295508.119 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-08-15T22:05:08.140Z,1755295508.140 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-08-15T22:05:08.141Z,1755295508.141 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-08-15T22:05:08.141Z,1755295508.141 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 2259
2025-08-15T22:05:08.145Z,1755295508.145 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-08-15T22:05:08.146Z,1755295508.146 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-08-15T22:05:08.147Z,1755295508.147 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2260
2025-08-15T22:05:08.149Z,1755295508.149 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-08-15T22:05:08.150Z,1755295508.150 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-08-15T22:05:08.150Z,1755295508.150 [logger ThreadHandler](INFO): Protected caller Thread ID is 2261
2025-08-15T22:05:08.154Z,1755295508.154 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-08-15T22:05:08.155Z,1755295508.155 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-08-15T22:05:08.156Z,1755295508.156 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-08-15T22:05:08.264Z,1755295508.264 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-08-15T22:05:08.264Z,1755295508.264 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-08-15T22:05:08.504Z,1755295508.504 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-08-15T22:05:08.505Z,1755295508.505 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-08-15T22:05:08.778Z,1755295508.778 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-08-15T22:05:08.779Z,1755295508.779 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-08-15T22:05:08.897Z,1755295508.897 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-08-15T22:05:08.897Z,1755295508.897 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-08-15T22:05:09.665Z,1755295509.665 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-08-15T22:05:09.666Z,1755295509.666 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-08-15T22:05:10.208Z,1755295510.208 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-08-15T22:05:10.209Z,1755295510.209 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-08-15T22:05:10.755Z,1755295510.755 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-08-15T22:05:10.756Z,1755295510.756 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-08-15T22:05:11.195Z,1755295511.195 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-08-15T22:05:11.196Z,1755295511.196 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-08-15T22:05:11.553Z,1755295511.553 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-08-15T22:05:11.554Z,1755295511.554 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-08-15T22:05:12.439Z,1755295512.439 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-08-15T22:05:12.440Z,1755295512.440 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-08-15T22:05:12.677Z,1755295512.677 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-08-15T22:05:12.678Z,1755295512.678 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-08-15T22:05:12.833Z,1755295512.833 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-08-15T22:05:12.833Z,1755295512.833 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-08-15T22:05:12.936Z,1755295512.936 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance
2025-08-15T22:05:12.936Z,1755295512.936 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-08-15T22:05:13.027Z,1755295513.027 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-08-15T22:05:13.221Z,1755295513.221 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-08-15T22:05:13.221Z,1755295513.221 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-08-15T22:05:13.477Z,1755295513.477 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-08-15T22:05:13.479Z,1755295513.479 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2025-08-15T22:05:13.480Z,1755295513.480 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2025-08-15T22:05:13.576Z,1755295513.576 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2025-08-15T22:05:13.859Z,1755295513.859 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-08-15T22:05:13.860Z,1755295513.860 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2025-08-15T22:05:13.955Z,1755295513.955 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2025-08-15T22:05:14.135Z,1755295514.135 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2025-08-15T22:05:14.247Z,1755295514.247 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2025-08-15T22:05:14.367Z,1755295514.367 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2025-08-15T22:05:14.487Z,1755295514.487 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2025-08-15T22:05:14.592Z,1755295514.592 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2025-08-15T22:05:14.761Z,1755295514.761 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Derivation.cfg
2025-08-15T22:05:14.869Z,1755295514.869 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/workSite.cfg
2025-08-15T22:05:14.965Z,1755295514.965 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2025-08-15T22:05:15.073Z,1755295515.073 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2025-08-15T22:05:15.171Z,1755295515.171 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2025-08-15T22:05:15.172Z,1755295515.172 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-08-15T22:05:15.180Z,1755295515.180 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-08-15T22:05:15.367Z,1755295515.367 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-08-15T22:05:15.367Z,1755295515.367 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-08-15T22:05:15.538Z,1755295515.538 [BuoyancyServo] Loaded
2025-08-15T22:05:15.538Z,1755295515.538 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-08-15T22:05:15.562Z,1755295515.562 [ElevatorServo] Loaded
2025-08-15T22:05:15.562Z,1755295515.562 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-08-15T22:05:15.585Z,1755295515.585 [MassServo] Loaded
2025-08-15T22:05:15.585Z,1755295515.585 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-08-15T22:05:15.608Z,1755295515.608 [RudderServo] Loaded
2025-08-15T22:05:15.608Z,1755295515.608 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-08-15T22:05:15.626Z,1755295515.626 [ThrusterHE] Loaded
2025-08-15T22:05:15.626Z,1755295515.626 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-08-15T22:05:15.627Z,1755295515.627 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-08-15T22:05:15.627Z,1755295515.627 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-08-15T22:05:15.799Z,1755295515.799 [SBIT](DEBUG): Construct Startup Built In Test.
2025-08-15T22:05:15.814Z,1755295515.814 [SBIT] Loaded
2025-08-15T22:05:15.815Z,1755295515.815 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-08-15T22:05:15.818Z,1755295515.818 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-08-15T22:05:15.839Z,1755295515.839 [IBIT] Loaded
2025-08-15T22:05:15.839Z,1755295515.839 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-08-15T22:05:15.844Z,1755295515.844 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-08-15T22:05:16.045Z,1755295516.045 [CBIT] Loaded
2025-08-15T22:05:16.045Z,1755295516.045 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-08-15T22:05:16.077Z,1755295516.077 [GFScanner] Loaded
2025-08-15T22:05:16.077Z,1755295516.077 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-08-15T22:05:16.078Z,1755295516.078 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-08-15T22:05:16.078Z,1755295516.078 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-08-15T22:05:16.252Z,1755295516.252 [CTD_Seabird] Loaded
2025-08-15T22:05:16.253Z,1755295516.253 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-08-15T22:05:16.254Z,1755295516.254 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406874E0
2025-08-15T22:05:16.254Z,1755295516.254 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2349
2025-08-15T22:05:16.290Z,1755295516.290 [ESPComponent] Loaded
2025-08-15T22:05:16.290Z,1755295516.290 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2025-08-15T22:05:16.312Z,1755295516.312 [PAR_Licor] Loaded
2025-08-15T22:05:16.313Z,1755295516.313 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-08-15T22:05:16.358Z,1755295516.358 [WetLabsBB2FL] Loaded
2025-08-15T22:05:16.358Z,1755295516.358 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-08-15T22:05:16.359Z,1755295516.359 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406B74E0
2025-08-15T22:05:16.360Z,1755295516.360 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2350
2025-08-15T22:05:16.360Z,1755295516.360 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-08-15T22:05:16.361Z,1755295516.361 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-08-15T22:05:16.413Z,1755295516.413 [DepthRateCalculator] Loaded
2025-08-15T22:05:16.413Z,1755295516.413 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-08-15T22:05:16.419Z,1755295516.419 [PitchRateCalculator] Loaded
2025-08-15T22:05:16.419Z,1755295516.419 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-08-15T22:05:16.429Z,1755295516.429 [SpeedCalculator] Loaded
2025-08-15T22:05:16.430Z,1755295516.430 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-08-15T22:05:16.434Z,1755295516.434 [YawRateCalculator] Loaded
2025-08-15T22:05:16.435Z,1755295516.435 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-08-15T22:05:16.455Z,1755295516.455 [ElevatorOffsetCalculator] Loaded
2025-08-15T22:05:16.455Z,1755295516.455 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-08-15T22:05:16.456Z,1755295516.456 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-08-15T22:05:16.456Z,1755295516.456 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-08-15T22:05:16.597Z,1755295516.597 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-08-15T22:05:16.597Z,1755295516.597 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-08-15T22:05:16.627Z,1755295516.627 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-08-15T22:05:16.628Z,1755295516.628 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-08-15T22:05:16.745Z,1755295516.745 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-08-15T22:05:16.746Z,1755295516.746 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-08-15T22:05:16.760Z,1755295516.760 [NavChart] Loaded
2025-08-15T22:05:16.760Z,1755295516.760 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-08-15T22:05:16.766Z,1755295516.766 [UniversalFixResidualReporter] Loaded
2025-08-15T22:05:16.766Z,1755295516.766 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-08-15T22:05:16.767Z,1755295516.767 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-08-15T22:05:16.767Z,1755295516.767 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-08-15T22:05:17.263Z,1755295517.263 [AHRS_M2] Loaded
2025-08-15T22:05:17.264Z,1755295517.264 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-08-15T22:05:17.303Z,1755295517.303 [BackseatComponent] Loaded
2025-08-15T22:05:17.303Z,1755295517.303 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-08-15T22:05:17.304Z,1755295517.304 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A834E0
2025-08-15T22:05:17.305Z,1755295517.305 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 2351
2025-08-15T22:05:17.308Z,1755295517.308 [LcmUniversalReporter] Loaded
2025-08-15T22:05:17.308Z,1755295517.308 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-08-15T22:05:18.616Z,1755295518.616 [BPC1] Loaded
2025-08-15T22:05:18.616Z,1755295518.616 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-08-15T22:05:18.720Z,1755295518.720 [DAT] Loaded
2025-08-15T22:05:18.720Z,1755295518.720 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-08-15T22:05:18.721Z,1755295518.721 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40AB34E0
2025-08-15T22:05:18.722Z,1755295518.722 [DAT ThreadHandler](INFO): Protected caller Thread ID is 2352
2025-08-15T22:05:18.797Z,1755295518.797 [DataOverHttps] Loaded
2025-08-15T22:05:18.798Z,1755295518.798 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-08-15T22:05:18.799Z,1755295518.799 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40AE34E0
2025-08-15T22:05:18.799Z,1755295518.799 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2353
2025-08-15T22:05:18.823Z,1755295518.823 [Depth_Keller] Loaded
2025-08-15T22:05:18.824Z,1755295518.824 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-08-15T22:05:18.829Z,1755295518.829 [DropWeight] Loaded
2025-08-15T22:05:18.829Z,1755295518.829 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-08-15T22:05:18.871Z,1755295518.871 [DVL_micro] Loaded
2025-08-15T22:05:18.872Z,1755295518.872 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2025-08-15T22:05:18.936Z,1755295518.936 [NAL9602] Loaded
2025-08-15T22:05:18.936Z,1755295518.936 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-08-15T22:05:18.963Z,1755295518.963 [Onboard] Loaded
2025-08-15T22:05:18.963Z,1755295518.963 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-08-15T22:05:18.964Z,1755295518.964 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40B134E0
2025-08-15T22:05:18.965Z,1755295518.965 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 2354
2025-08-15T22:05:18.979Z,1755295518.979 [Power24vConverter] Loaded
2025-08-15T22:05:18.980Z,1755295518.980 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-08-15T22:05:18.996Z,1755295518.996 [Radio_Surface] Loaded
2025-08-15T22:05:18.997Z,1755295518.997 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-08-15T22:05:18.998Z,1755295518.998 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40B434E0
2025-08-15T22:05:18.998Z,1755295518.998 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2355
2025-08-15T22:05:19.014Z,1755295519.014 [Sonardyne_Nano] Loaded
2025-08-15T22:05:19.014Z,1755295519.014 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-08-15T22:05:19.015Z,1755295519.015 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-08-15T22:05:19.015Z,1755295519.015 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-08-15T22:05:19.077Z,1755295519.077 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-08-15T22:05:19.078Z,1755295519.078 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-08-15T22:05:19.133Z,1755295519.133 [VerticalControl](DEBUG): Construct VerticalControl.
2025-08-15T22:05:19.186Z,1755295519.186 [VerticalControl] Loaded
2025-08-15T22:05:19.187Z,1755295519.187 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-08-15T22:05:19.190Z,1755295519.190 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-08-15T22:05:19.235Z,1755295519.235 [HorizontalControl] Loaded
2025-08-15T22:05:19.235Z,1755295519.235 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-08-15T22:05:19.238Z,1755295519.238 [SpeedControl](DEBUG): Construct SpeedControl.
2025-08-15T22:05:19.240Z,1755295519.240 [SpeedControl] Loaded
2025-08-15T22:05:19.240Z,1755295519.240 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-08-15T22:05:19.243Z,1755295519.243 [LoopControl](DEBUG): Construct LoopControl.
2025-08-15T22:05:19.243Z,1755295519.243 [LoopControl] Loaded
2025-08-15T22:05:19.244Z,1755295519.244 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-08-15T22:05:19.244Z,1755295519.244 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-08-15T22:05:19.245Z,1755295519.245 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-08-15T22:05:19.255Z,1755295519.255 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-08-15T22:05:19.256Z,1755295519.256 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-08-15T22:05:19.617Z,1755295519.617 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-08-15T22:05:19.624Z,1755295519.624 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-08-15T22:05:19.627Z,1755295519.627 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-08-15T22:05:19.639Z,1755295519.639 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-08-15T22:05:19.640Z,1755295519.640 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C6A4E0
2025-08-15T22:05:19.640Z,1755295519.640 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2356
2025-08-15T22:05:19.644Z,1755295519.644 [Supervisor](INFO): Main Thread ID is 822
2025-08-15T22:05:19.645Z,1755295519.645 [Supervisor](DEBUG): Running supervisor.
2025-08-15T22:05:19.645Z,1755295519.645 [CommandExec ThreadHandler](INFO): Handler Thread ID is 2357
2025-08-15T22:05:19.646Z,1755295519.646 [CommandExec](INFO): Initializing the command executive.
2025-08-15T22:05:19.647Z,1755295519.647 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2358
2025-08-15T22:05:19.650Z,1755295519.650 [controlThread ThreadHandler](INFO): Handler Thread ID is 2359
2025-08-15T22:05:19.651Z,1755295519.651 [controlThread](DEBUG): Initializing ControlThread
2025-08-15T22:05:19.653Z,1755295519.653 [SBIT](INFO): Initialize SBIT Component.
2025-08-15T22:05:19.653Z,1755295519.653 [SBIT](IMPORTANT): git: 2025-08-15
2025-08-15T22:05:19.653Z,1755295519.653 [SBIT](INFO): git hash: aa1cd939c0bb5080a316c7313ffe69d6279cb338
2025-08-15T22:05:19.654Z,1755295519.654 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-08-15T22:05:19.655Z,1755295519.655 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2025-08-15T22:05:19.656Z,1755295519.656 [SBIT](INFO): Beginning SBIT in 147.000000 seconds.
2025-08-15T22:05:19.657Z,1755295519.657 [IBIT](INFO): Initialize IBIT Component.
2025-08-15T22:05:19.657Z,1755295519.657 [CBIT](DEBUG): Initialize CBIT Component.
2025-08-15T22:05:19.658Z,1755295519.658 [logger ThreadHandler](INFO): Handler Thread ID is 2360
2025-08-15T22:05:19.672Z,1755295519.672 [CBIT](DEBUG): Initialized mux pins.
2025-08-15T22:05:19.672Z,1755295519.672 [CBIT](DEBUG): Initializing the watchdog timer.
2025-08-15T22:05:19.684Z,1755295519.684 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2361
2025-08-15T22:05:19.685Z,1755295519.685 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-08-15T22:05:19.689Z,1755295519.689 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2363
2025-08-15T22:05:19.692Z,1755295519.692 [WetLabsBB2FL](INFO): Powering up
2025-08-15T22:05:19.694Z,1755295519.694 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 2365
2025-08-15T22:05:19.696Z,1755295519.696 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-08-15T22:05:19.696Z,1755295519.696 [CBIT](DEBUG): Initializing heartbeat.
2025-08-15T22:05:19.710Z,1755295519.710 [DAT ThreadHandler](INFO): Handler Thread ID is 2366
2025-08-15T22:05:19.710Z,1755295519.710 [DAT](INFO): Powering up
2025-08-15T22:05:19.711Z,1755295519.711 [DAT](DEBUG): Initializing DAT.
2025-08-15T22:05:19.717Z,1755295519.717 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2368
2025-08-15T22:05:19.719Z,1755295519.719 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-08-15T22:05:19.732Z,1755295519.732 [Onboard ThreadHandler](INFO): Handler Thread ID is 2369
2025-08-15T22:05:19.751Z,1755295519.751 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2370
2025-08-15T22:05:19.768Z,1755295519.768 [CBIT](DEBUG): Deactivating emergency mode.
2025-08-15T22:05:19.780Z,1755295519.780 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2371
2025-08-15T22:05:19.783Z,1755295519.783 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-08-15T22:05:19.783Z,1755295519.783 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-08-15T22:05:19.783Z,1755295519.783 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-08-15T22:05:19.783Z,1755295519.783 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-08-15T22:05:19.783Z,1755295519.783 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-08-15T22:05:19.784Z,1755295519.784 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-08-15T22:05:19.784Z,1755295519.784 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-08-15T22:05:19.784Z,1755295519.784 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-08-15T22:05:19.808Z,1755295519.808 [CBIT](DEBUG): Backplane powered.
2025-08-15T22:05:19.808Z,1755295519.808 [GFScanner](DEBUG): Initializing GFScanner
2025-08-15T22:05:19.808Z,1755295519.808 [GFScanner](DEBUG): Deactivating GF circuits.
2025-08-15T22:05:19.816Z,1755295519.816 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-08-15T22:05:19.817Z,1755295519.817 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-08-15T22:05:19.817Z,1755295519.817 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-08-15T22:05:19.817Z,1755295519.817 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-08-15T22:05:19.818Z,1755295519.818 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-08-15T22:05:19.819Z,1755295519.819 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-08-15T22:05:19.819Z,1755295519.819 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-08-15T22:05:19.823Z,1755295519.823 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-08-15T22:05:19.824Z,1755295519.824 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-08-15T22:05:19.825Z,1755295519.825 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-08-15T22:05:19.826Z,1755295519.826 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-08-15T22:05:19.826Z,1755295519.826 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-08-15T22:05:19.827Z,1755295519.827 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-08-15T22:05:19.827Z,1755295519.827 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-08-15T22:05:19.833Z,1755295519.833 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-08-15T22:05:19.902Z,1755295519.902 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="Startup">
    <Guidance:GoToSurface>
        <Progression />
    </Guidance:GoToSurface>
    <Aggregate Id="StartupSatComms">
        <Sequence />
        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix />
        </ReadDatum>
        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications />
        </ReadDatum>
    </Aggregate>
</Mission>

2025-08-15T22:05:19.943Z,1755295519.943 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-08-15T22:05:19.989Z,1755295519.989 [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-08-15T22:05:19.990Z,1755295519.990 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-08-15T22:05:19.990Z,1755295519.990 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-08-15T22:05:19.991Z,1755295519.991 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-08-15T22:05:20.156Z,1755295520.156 [Radio_Surface](INFO): Powering up
2025-08-15T22:05:20.313Z,1755295520.313 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="Default">
    <DefineArg Name="ElapsedSinceDefaultStarted">
        <Units:minute />
        <Value>0</Value>
    </DefineArg>
    <Guidance:Wait>
        <Sequence />
        <Setting>
            <Guidance:Wait.duration />
            <Units:second />
            <Value>13</Value>
        </Setting>
    </Guidance:Wait>
    <Guidance:GoToSurface>
        <Progression />
    </Guidance:GoToSurface>
    <Aggregate Id="CheckIn">
        <Sequence Repeat="288" />
        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M" />
            <Universal:time_fix />
        </ReadDatum>
        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P4H">
                <Guidance:Execute>
                    <Sequence />
                    <Setting>
                        <Guidance:Execute.command />
                        <String>Burn on</String>
                    </Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications />
        </ReadDatum>
        <Guidance:Wait>
            <Sequence />
            <Setting>
                <Guidance:Wait.duration />
                <Units:minute />
                <Value>5</Value>
            </Setting>
        </Guidance:Wait>
        <Assign>
            <Sequence />
            <Arg Name="ElapsedSinceDefaultStarted" />
            <Elapsed>
                <Universal:mission_started />
            </Elapsed>
        </Assign>
        <Syslog Severity="Important">
            Default mission has been running for 
            <Arg Name="ElapsedSinceDefaultStarted" />
            <Units:minute />
        </Syslog>
    </Aggregate>
    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>
    <Guidance:Execute>
        <Sequence />
        <Setting>
            <Guidance:Execute.command />
            <String>restart logs</String>
        </Setting>
    </Guidance:Execute>
</Mission>

2025-08-15T22:05:20.319Z,1755295520.319 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-08-15T22:05:20.336Z,1755295520.336 [Default:A.Wait](DEBUG): Construct Wait.
2025-08-15T22:05:20.338Z,1755295520.338 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-08-15T22:05:20.388Z,1755295520.388 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-08-15T22:05:20.391Z,1755295520.391 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-08-15T22:05:20.413Z,1755295520.413 [Default:E.Execute](DEBUG): Construct Execute.
2025-08-15T22:05:20.416Z,1755295520.416 [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-08-15T22:05:20.421Z,1755295520.421 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
2025-08-15T22:05:20.446Z,1755295520.446 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-08-15T22:05:20.504Z,1755295520.504 [Depth_Keller](INFO): Initializing.
2025-08-15T22:05:20.513Z,1755295520.513 [Power24vConverter](INFO): Powering up.
2025-08-15T22:05:20.514Z,1755295520.514 [Sonardyne_Nano](INFO): Initializing.
2025-08-15T22:05:20.540Z,1755295520.540 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-08-15T22:05:20.567Z,1755295520.566 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-08-15T22:05:20.572Z,1755295520.572 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-08-15T22:05:20.573Z,1755295520.573 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-08-15T22:05:20.581Z,1755295520.581 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-08-15T22:05:20.582Z,1755295520.582 [MassServo](DEBUG): Initializing EZServoServo.
2025-08-15T22:05:20.588Z,1755295520.588 [MassServo](DEBUG): Initializing MassServo.
2025-08-15T22:05:20.589Z,1755295520.589 [RudderServo](DEBUG): Initializing EZServoServo.
2025-08-15T22:05:20.596Z,1755295520.596 [RudderServo](DEBUG): Initializing RudderServo.
2025-08-15T22:05:20.597Z,1755295520.597 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-08-15T22:05:20.604Z,1755295520.604 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-08-15T22:05:22.304Z,1755295522.304 [WetLabsBB2FL](INFO): Powering down
2025-08-15T22:05:22.459Z,1755295522.459 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-08-15T22:05:23.741Z,1755295523.741 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2213
2025-08-15T22:05:31.821Z,1755295531.821 [DAT](INFO): DAT read: 

2025-08-15T22:05:31.822Z,1755295531.822 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2025-08-15T22:05:33.585Z,1755295533.585 [DAT](INFO): DAT read: MF Frequency Band

2025-08-15T22:05:33.586Z,1755295533.586 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2025-08-15T22:05:33.587Z,1755295533.587 [DAT](INFO): DAT read: Aug 15 2025 22:05:27 

2025-08-15T22:05:34.845Z,1755295534.845 [DAT](INFO): DAT read: Features enabled [Bearing]

2025-08-15T22:05:34.846Z,1755295534.846 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC

2025-08-15T22:05:34.846Z,1755295534.846 [DAT](INFO): commRate: 1200
2025-08-15T22:05:36.912Z,1755295536.912 [DAT](INFO): entering command mode
2025-08-15T22:05:37.113Z,1755295537.113 [DAT](INFO): DAT read: 

2025-08-15T22:05:37.113Z,1755295537.113 [DAT](INFO): DAT read: user:1>

2025-08-15T22:05:37.113Z,1755295537.113 [DAT](INFO): setting verbose to 3
2025-08-15T22:05:37.365Z,1755295537.365 [DAT](INFO): DAT read: user:1>

2025-08-15T22:05:37.366Z,1755295537.366 [DAT](INFO): DAT read: Verbose         | 3           

2025-08-15T22:05:37.366Z,1755295537.366 [DAT](INFO): set verbose to 3
2025-08-15T22:05:37.366Z,1755295537.366 [DAT](INFO): setting DatVerbose to 27440
2025-08-15T22:05:37.617Z,1755295537.617 [DAT](INFO): DAT read: user:2>

2025-08-15T22:05:37.618Z,1755295537.618 [DAT](INFO): DAT read: DatVerbose      | 27440       

2025-08-15T22:05:37.618Z,1755295537.618 [DAT](INFO): set DatVerbose to 27440
2025-08-15T22:05:37.618Z,1755295537.618 [DAT](INFO): setting transmit power to 8
2025-08-15T22:05:37.869Z,1755295537.869 [DAT](INFO): DAT read: user:3>

2025-08-15T22:05:37.869Z,1755295537.869 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2025-08-15T22:05:37.870Z,1755295537.870 [DAT](INFO): set transmit power to 8
2025-08-15T22:05:37.870Z,1755295537.870 [DAT](INFO): setting local address to 5
2025-08-15T22:05:38.121Z,1755295538.121 [DAT](INFO): DAT read: user:4>

2025-08-15T22:05:38.122Z,1755295538.122 [DAT](INFO): DAT read: LocalAddr       | 5           

2025-08-15T22:05:38.122Z,1755295538.122 [DAT](INFO): set local address to 5
2025-08-15T22:05:38.123Z,1755295538.123 [DAT](INFO): Setting time to: 22:5:38 And date to:8/15/2025
2025-08-15T22:05:38.373Z,1755295538.373 [DAT](INFO): DAT read: user:5>

2025-08-15T22:05:38.374Z,1755295538.374 [DAT](INFO): DAT read: Fri Aug 15, 2025  22:05:38  

2025-08-15T22:05:38.374Z,1755295538.374 [DAT](INFO): Local DAT time set to Fri Aug 15, 2025  22:05:38  

2025-08-15T22:05:49.131Z,1755295549.131 [NAL9602](INFO): Powering up NAL9602
2025-08-15T22:06:00.033Z,1755295560.033 [NAL9602](INFO): NAL9602 initialized
2025-08-15T22:07:31.650Z,1755295651.650 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005030
2025-08-15T22:07:47.169Z,1755295667.169 [SBIT](IMPORTANT): Beginning Startup BIT
2025-08-15T22:07:47.174Z,1755295667.174 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-08-15T22:07:51.624Z,1755295671.624 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.003780
CHAN A1 (24V): 0.005389
CHAN A2 (12V): -0.005376
CHAN A3 (5V): -0.004123
CHAN B0 (3.3V): -0.002503
CHAN B1 (3.15aV): -0.002202
CHAN B2 (3.15bV): -0.001365
CHAN B3 (GND): -0.001446
OPEN: 0.007184
Full Scale: +/- 1 mA
2025-08-15T22:08:00.206Z,1755295680.206 [SBIT](FAULT): Rudder: EXPECTED:15.000000 ACTUAL:14.677588
2025-08-15T22:08:19.846Z,1755295699.846 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-08-15T22:08:19.846Z,1755295699.846 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-08-15T22:08:19.881Z,1755295699.881 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-08-15T22:08:20.236Z,1755295700.236 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-08-15T22:08:20.237Z,1755295700.237 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-08-15T22:08:41.029Z,1755295721.029 [SBIT](CRITICAL): SBIT FAILED
2025-08-15T22:08:41.029Z,1755295721.029 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-08-15T22:08:41.030Z,1755295721.030 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=5 count;

2025-08-15T22:08:41.030Z,1755295721.030 [SBIT](IMPORTANT): CBIT.runNavErrorCritical=0 bool;

2025-08-15T22:08:41.030Z,1755295721.030 [SBIT](IMPORTANT): ElevatorServo.currLimit=50 percent;

2025-08-15T22:08:41.030Z,1755295721.030 [SBIT](IMPORTANT): RudderServo.offsetAngle=-0.3 degree;

2025-08-15T22:08:41.030Z,1755295721.030 [SBIT](IMPORTANT): ThrusterHE.bestEffortMode=1 bool;

2025-08-15T22:08:41.030Z,1755295721.030 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=264.941384 cubic_centimeter;

2025-08-15T22:08:41.030Z,1755295721.030 [SBIT](IMPORTANT): VerticalControl.massDefault=31.919755 millimeter;

2025-08-15T22:08:41.032Z,1755295721.032 [CommandExec](FAULT): Scheduling is paused
2025-08-15T22:08:41.032Z,1755295721.032 [CBIT](INFO): Critical error at 20250815T220841
2025-08-15T22:08:41.032Z,1755295721.032 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2025-08-15T22:08:41.278Z,1755295721.278 [MissionManager](IMPORTANT): Started mission Startup
2025-08-15T22:08:41.278Z,1755295721.278 [Startup] Running Loop=1
2025-08-15T22:08:41.278Z,1755295721.278 [Startup](DEBUG): Aggregate::initialize Startup
2025-08-15T22:08:41.278Z,1755295721.278 [Startup:A.GoToSurface] Running Loop=1
2025-08-15T22:08:41.279Z,1755295721.279 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-08-15T22:08:41.279Z,1755295721.279 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-08-15T22:08:41.279Z,1755295721.279 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-08-15T22:08:41.281Z,1755295721.281 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-08-15T22:08:41.281Z,1755295721.281 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-08-15T22:08:41.281Z,1755295721.281 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-08-15T22:08:41.282Z,1755295721.282 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-08-15T22:08:41.283Z,1755295721.283 [Startup:StartupSatComms] Running Loop=1
2025-08-15T22:08:41.283Z,1755295721.283 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-08-15T22:08:41.283Z,1755295721.283 [Startup:StartupSatComms:A] Running Loop=1
2025-08-15T22:08:41.691Z,1755295721.691 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-08-15T22:09:41.517Z,1755295781.517 [Startup:StartupSatComms:A](INFO): Timed out from 2025-08-15T22:08:41.3Z
2025-08-15T22:09:41.517Z,1755295781.517 [Startup:StartupSatComms:A] Stopped
2025-08-15T22:09:41.517Z,1755295781.517 [Startup:StartupSatComms:B] Running Loop=1
2025-08-15T22:09:41.899Z,1755295781.899 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-08-15T22:09:49.112Z,1755295789.112 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20250815T215537/Courier0007.lzma
2025-08-15T22:09:50.121Z,1755295790.121 [DataOverHttps](INFO): Moved sent file to Logs/20250815T215537/Courier0007.lzma.bak
2025-08-15T22:09:50.121Z,1755295790.121 [DataOverHttps](INFO): SBD MOMSN=25853667
2025-08-15T22:10:06.597Z,1755295806.597 [DataOverHttps](INFO): Sending 185 bytes from file Logs/20250815T220508/Courier0000.lzma
2025-08-15T22:10:07.582Z,1755295807.582 [DataOverHttps](INFO): Moved sent file to Logs/20250815T220508/Courier0000.lzma.bak
2025-08-15T22:10:07.582Z,1755295807.582 [DataOverHttps](INFO): SBD MOMSN=25853669
2025-08-15T22:10:25.092Z,1755295825.092 [DataOverHttps](INFO): Sending 165 bytes from file Logs/20250815T215537/Express0008.lzma
2025-08-15T22:10:26.094Z,1755295826.094 [DataOverHttps](INFO): Moved sent file to Logs/20250815T215537/Express0008.lzma.bak
2025-08-15T22:10:26.094Z,1755295826.094 [DataOverHttps](INFO): SBD MOMSN=25853673
2025-08-15T22:10:41.723Z,1755295841.723 [Startup:StartupSatComms:B](INFO): Timed out from 2025-08-15T22:09:41.5Z
2025-08-15T22:10:41.723Z,1755295841.723 [Startup:StartupSatComms:B] Stopped
2025-08-15T22:10:41.723Z,1755295841.723 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-08-15T22:10:41.723Z,1755295841.723 [Startup:StartupSatComms] Stopped
2025-08-15T22:10:41.723Z,1755295841.723 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-08-15T22:10:41.730Z,1755295841.730 [Startup](INFO): Completed Startup
2025-08-15T22:10:41.730Z,1755295841.730 [MissionManager](INFO): Startup is completed.
2025-08-15T22:10:41.730Z,1755295841.730 [MissionManager](INFO): Uninitializing Mission Startup
2025-08-15T22:10:41.731Z,1755295841.731 [Startup] Stopped
2025-08-15T22:10:41.731Z,1755295841.731 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-08-15T22:10:41.731Z,1755295841.731 [Startup:A.GoToSurface] Stopped
2025-08-15T22:10:41.731Z,1755295841.731 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-08-15T22:10:42.284Z,1755295842.284 [MissionManager](IMPORTANT): Started mission Default
2025-08-15T22:10:42.285Z,1755295842.285 [Default] Running Loop=1
2025-08-15T22:10:42.285Z,1755295842.285 [Default](DEBUG): Aggregate::initialize Default
2025-08-15T22:10:42.285Z,1755295842.285 [Default:B.GoToSurface] Running Loop=1
2025-08-15T22:10:42.285Z,1755295842.285 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-08-15T22:10:42.286Z,1755295842.286 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-08-15T22:10:42.286Z,1755295842.286 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-08-15T22:10:42.287Z,1755295842.287 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-08-15T22:10:42.287Z,1755295842.287 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-08-15T22:10:42.292Z,1755295842.292 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-08-15T22:10:42.293Z,1755295842.293 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-08-15T22:10:42.293Z,1755295842.293 [Default:A.Wait] Running Loop=1
2025-08-15T22:10:42.293Z,1755295842.293 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-08-15T22:10:44.713Z,1755295844.713 [CommandExec](IMPORTANT): got command ibit
2025-08-15T22:10:45.010Z,1755295845.010 [DataOverHttps](INFO): Sending 941 bytes from file Logs/20250815T220508/Express0001.lzma
2025-08-15T22:10:45.077Z,1755295845.077 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-08-15T22:10:45.078Z,1755295845.078 [IBIT](IMPORTANT): Beginning control surface checks.
2025-08-15T22:10:45.081Z,1755295845.081 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-08-15T22:10:46.006Z,1755295846.006 [DataOverHttps](INFO): Moved sent file to Logs/20250815T220508/Express0001.lzma.bak
2025-08-15T22:10:46.006Z,1755295846.006 [DataOverHttps](INFO): SBD MOMSN=25853677
2025-08-15T22:10:49.584Z,1755295849.584 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.003700
CHAN A1 (24V): 0.005874
CHAN A2 (12V): -0.005316
CHAN A3 (5V): -0.003363
CHAN B0 (3.3V): -0.001894
CHAN B1 (3.15aV): -0.001923
CHAN B2 (3.15bV): -0.001632
CHAN B3 (GND): -0.000709
OPEN: 0.007048
Full Scale: +/- 1 mA
2025-08-15T22:11:00.426Z,1755295860.426 [IBIT](FAULT): Rudder: EXPECTED:15.000000 ACTUAL:14.778761
2025-08-15T22:11:00.426Z,1755295860.426 [IBIT](FAULT): Control surface position failure.
2025-08-15T22:11:03.511Z,1755295863.511 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-08-15T22:11:04.727Z,1755295864.727 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221101.00,A,3648.16618,N,12147.27872,W,0.564,329.35,150825,,,A*77
2025-08-15T22:11:04.730Z,1755295864.730 [NAL9602](INFO): GPS fix at 20250815T221101: (36.802770, -121.787979)
2025-08-15T22:11:07.556Z,1755295867.556 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221104.00,A,3648.16550,N,12147.27923,W,0.369,329.35,150825,,,A*73
2025-08-15T22:11:07.558Z,1755295867.558 [NAL9602](INFO): GPS fix at 20250815T221104: (36.802758, -121.787987)
2025-08-15T22:11:10.682Z,1755295870.682 [CommandLine](INFO): End of History
2025-08-15T22:11:10.787Z,1755295870.787 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221107.00,A,3648.16507,N,12147.27994,W,0.505,329.35,150825,,,A*72
2025-08-15T22:11:10.790Z,1755295870.790 [NAL9602](INFO): GPS fix at 20250815T221107: (36.802751, -121.787999)
2025-08-15T22:11:11.257Z,1755295871.257 [CommandLine](INFO): End of History
2025-08-15T22:11:13.622Z,1755295873.622 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221110.00,A,3648.16538,N,12147.28040,W,0.564,329.35,150825,,,A*70
2025-08-15T22:11:13.638Z,1755295873.638 [NAL9602](INFO): GPS fix at 20250815T221110: (36.802756, -121.788007)
2025-08-15T22:11:16.468Z,1755295876.468 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221113.00,A,3648.16528,N,12147.28088,W,0.078,329.35,150825,,,A*7E
2025-08-15T22:11:16.470Z,1755295876.470 [NAL9602](INFO): GPS fix at 20250815T221113: (36.802755, -121.788015)
2025-08-15T22:11:19.703Z,1755295879.703 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221116.00,A,3648.16505,N,12147.28144,W,0.117,329.35,150825,,,A*7D
2025-08-15T22:11:19.705Z,1755295879.705 [NAL9602](INFO): GPS fix at 20250815T221116: (36.802751, -121.788024)
2025-08-15T22:11:22.538Z,1755295882.538 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221119.00,A,3648.16502,N,12147.28192,W,0.097,329.35,150825,,,A*77
2025-08-15T22:11:22.565Z,1755295882.565 [NAL9602](INFO): GPS fix at 20250815T221119: (36.802750, -121.788032)
2025-08-15T22:11:25.757Z,1755295885.757 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221122.00,A,3648.16485,N,12147.28217,W,0.078,329.35,150825,,,A*7E
2025-08-15T22:11:25.759Z,1755295885.759 [NAL9602](INFO): GPS fix at 20250815T221122: (36.802748, -121.788036)
2025-08-15T22:11:28.650Z,1755295888.650 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221125.00,A,3648.16470,N,12147.28251,W,0.214,329.35,150825,,,A*79
2025-08-15T22:11:28.652Z,1755295888.652 [NAL9602](INFO): GPS fix at 20250815T221125: (36.802745, -121.788042)
2025-08-15T22:11:31.412Z,1755295891.412 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221128.00,A,3648.16471,N,12147.28269,W,0.078,329.35,150825,,,A*76
2025-08-15T22:11:31.414Z,1755295891.414 [NAL9602](INFO): GPS fix at 20250815T221128: (36.802745, -121.788045)
2025-08-15T22:11:34.645Z,1755295894.645 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221131.00,A,3648.16469,N,12147.28294,W,0.058,329.35,150825,,,A*77
2025-08-15T22:11:34.647Z,1755295894.647 [NAL9602](INFO): GPS fix at 20250815T221131: (36.802745, -121.788049)
2025-08-15T22:11:37.470Z,1755295897.470 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221134.00,A,3648.16457,N,12147.28314,W,0.272,329.35,150825,,,A*7C
2025-08-15T22:11:37.472Z,1755295897.472 [NAL9602](INFO): GPS fix at 20250815T221134: (36.802743, -121.788052)
2025-08-15T22:11:40.709Z,1755295900.709 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221137.00,A,3648.16469,N,12147.28346,W,0.272,329.35,150825,,,A*75
2025-08-15T22:11:40.712Z,1755295900.712 [NAL9602](INFO): GPS fix at 20250815T221137: (36.802745, -121.788058)
2025-08-15T22:11:43.539Z,1755295903.539 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221140.00,A,3648.16481,N,12147.28361,W,0.233,329.35,150825,,,A*73
2025-08-15T22:11:43.559Z,1755295903.559 [NAL9602](INFO): GPS fix at 20250815T221140: (36.802747, -121.788060)
2025-08-15T22:11:46.764Z,1755295906.764 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221143.00,A,3648.16484,N,12147.28366,W,0.156,329.35,150825,,,A*72
2025-08-15T22:11:46.766Z,1755295906.766 [NAL9602](INFO): GPS fix at 20250815T221143: (36.802747, -121.788061)
2025-08-15T22:11:49.606Z,1755295909.606 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221146.00,A,3648.16489,N,12147.28369,W,0.117,329.35,150825,,,A*70
2025-08-15T22:11:49.608Z,1755295909.608 [NAL9602](INFO): GPS fix at 20250815T221146: (36.802748, -121.788061)
2025-08-15T22:11:52.419Z,1755295912.419 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221149.00,A,3648.16487,N,12147.28368,W,0.019,329.35,150825,,,A*7F
2025-08-15T22:11:52.422Z,1755295912.422 [NAL9602](INFO): GPS fix at 20250815T221149: (36.802748, -121.788061)
2025-08-15T22:11:55.659Z,1755295915.659 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221152.00,A,3648.16490,N,12147.28368,W,0.078,329.35,150825,,,A*74
2025-08-15T22:11:55.662Z,1755295915.662 [NAL9602](INFO): GPS fix at 20250815T221152: (36.802748, -121.788061)
2025-08-15T22:11:58.478Z,1755295918.478 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221155.00,A,3648.16490,N,12147.28371,W,0.078,329.35,150825,,,A*7B
2025-08-15T22:11:58.480Z,1755295918.480 [NAL9602](INFO): GPS fix at 20250815T221155: (36.802748, -121.788062)
2025-08-15T22:12:01.727Z,1755295921.727 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221158.00,A,3648.16483,N,12147.28368,W,0.058,329.35,150825,,,A*7E
2025-08-15T22:12:01.730Z,1755295921.730 [NAL9602](INFO): GPS fix at 20250815T221158: (36.802747, -121.788061)
2025-08-15T22:12:04.566Z,1755295924.566 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221201.00,A,3648.16465,N,12147.28349,W,0.058,329.35,150825,,,A*7A
2025-08-15T22:12:04.568Z,1755295924.568 [NAL9602](INFO): GPS fix at 20250815T221201: (36.802744, -121.788058)
2025-08-15T22:12:07.771Z,1755295927.771 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221204.00,A,3648.16463,N,12147.28335,W,0.039,329.35,150825,,,A*75
2025-08-15T22:12:07.773Z,1755295927.773 [NAL9602](INFO): GPS fix at 20250815T221204: (36.802744, -121.788056)
2025-08-15T22:12:10.606Z,1755295930.606 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221207.00,A,3648.16450,N,12147.28317,W,0.058,329.35,150825,,,A*71
2025-08-15T22:12:10.608Z,1755295930.608 [NAL9602](INFO): GPS fix at 20250815T221207: (36.802742, -121.788053)
2025-08-15T22:12:13.428Z,1755295933.428 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221210.00,A,3648.16441,N,12147.28320,W,0.097,329.35,150825,,,A*70
2025-08-15T22:12:13.430Z,1755295933.430 [NAL9602](INFO): GPS fix at 20250815T221210: (36.802740, -121.788053)
2025-08-15T22:12:16.675Z,1755295936.675 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221213.00,A,3648.16430,N,12147.28322,W,0.175,329.35,150825,,,A*7A
2025-08-15T22:12:16.677Z,1755295936.677 [NAL9602](INFO): GPS fix at 20250815T221213: (36.802738, -121.788054)
2025-08-15T22:12:19.495Z,1755295939.495 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221216.00,A,3648.16412,N,12147.28320,W,0.350,329.35,150825,,,A*78
2025-08-15T22:12:19.497Z,1755295939.497 [NAL9602](INFO): GPS fix at 20250815T221216: (36.802735, -121.788053)
2025-08-15T22:12:22.953Z,1755295942.953 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221219.00,A,3648.16391,N,12147.28314,W,0.330,329.35,150825,,,A*7A
2025-08-15T22:12:22.980Z,1755295942.980 [NAL9602](INFO): GPS fix at 20250815T221219: (36.802732, -121.788052)
2025-08-15T22:12:25.751Z,1755295945.751 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221222.00,A,3648.16375,N,12147.28307,W,0.117,329.35,150825,,,A*7D
2025-08-15T22:12:25.790Z,1755295945.790 [NAL9602](INFO): GPS fix at 20250815T221222: (36.802729, -121.788051)
2025-08-15T22:12:28.551Z,1755295948.551 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221225.00,A,3648.16353,N,12147.28293,W,0.369,329.35,150825,,,A*79
2025-08-15T22:12:28.553Z,1755295948.553 [NAL9602](INFO): GPS fix at 20250815T221225: (36.802726, -121.788049)
2025-08-15T22:12:31.371Z,1755295951.371 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221227.00,A,3648.16339,N,12147.28288,W,0.350,329.35,150825,,,A*77
2025-08-15T22:12:31.374Z,1755295951.374 [NAL9602](INFO): GPS fix at 20250815T221227: (36.802723, -121.788048)
2025-08-15T22:12:34.649Z,1755295954.649 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221231.00,A,3648.16327,N,12147.28264,W,0.078,329.35,150825,,,A*74
2025-08-15T22:12:34.660Z,1755295954.660 [NAL9602](INFO): GPS fix at 20250815T221231: (36.802721, -121.788044)
2025-08-15T22:12:35.781Z,1755295955.781 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2025-08-15T22:12:36.153Z,1755295956.153 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active.
2025-08-15T22:12:36.513Z,1755295956.513 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2025-08-15T22:12:36.514Z,1755295956.514 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2025-08-15T22:12:36.514Z,1755295956.514 [IBIT](IMPORTANT): Pressure:9.991120 PSI
2025-08-15T22:12:36.514Z,1755295956.514 [IBIT](IMPORTANT): Humidity:5.957758 %
2025-08-15T22:12:36.881Z,1755295956.881 [IBIT](IMPORTANT): Vehicle Pitch:-0.863278 degrees
2025-08-15T22:12:36.882Z,1755295956.882 [IBIT](IMPORTANT): Vehicle  Roll:0.919823 degrees
2025-08-15T22:12:36.882Z,1755295956.882 [IBIT](IMPORTANT): Vehicle  Heading:227.172058 degrees
2025-08-15T22:12:37.233Z,1755295957.233 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2025-08-15T22:12:37.234Z,1755295957.234 [IBIT](IMPORTANT): buoyancyNeutral: 264.941376 cc
2025-08-15T22:12:37.234Z,1755295957.234 [IBIT](IMPORTANT): massDefault: 3.191976 cm
2025-08-15T22:12:37.234Z,1755295957.234 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2025-08-15T22:12:37.234Z,1755295957.234 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2025-08-15T22:12:37.235Z,1755295957.235 [IBIT](IMPORTANT): IBIT FAILED
2025-08-15T22:12:37.451Z,1755295957.451 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221234.00,A,3648.16313,N,12147.28245,W,0.253,329.35,150825,,,A*7E
2025-08-15T22:12:37.459Z,1755295957.459 [NAL9602](INFO): GPS fix at 20250815T221234: (36.802719, -121.788041)
2025-08-15T22:12:37.511Z,1755295957.511 [Default:A.Wait](INFO): Done Waiting.
2025-08-15T22:12:37.511Z,1755295957.511 [Default:A.Wait] Stopped
2025-08-15T22:12:37.511Z,1755295957.511 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-08-15T22:12:37.873Z,1755295957.873 [Default:CheckIn] Running Loop=1
2025-08-15T22:12:37.873Z,1755295957.873 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-08-15T22:12:37.873Z,1755295957.873 [Default:CheckIn:Read_GPS] Running Loop=1
2025-08-15T22:12:38.286Z,1755295958.286 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-08-15T22:12:40.667Z,1755295960.667 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221237.00,A,3648.16316,N,12147.28221,W,0.233,329.35,150825,,,A*7C
2025-08-15T22:12:40.669Z,1755295960.669 [NAL9602](INFO): GPS fix at 20250815T221237: (36.802719, -121.788037)
2025-08-15T22:12:40.743Z,1755295960.743 [Default:CheckIn:Read_GPS] Stopped
2025-08-15T22:12:40.743Z,1755295960.743 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-08-15T22:12:41.113Z,1755295961.113 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-08-15T22:12:52.196Z,1755295972.196 [DataOverHttps](INFO): Sending 228 bytes from file Logs/20250815T220508/Courier0004.lzma
2025-08-15T22:12:53.198Z,1755295973.198 [DataOverHttps](INFO): Moved sent file to Logs/20250815T220508/Courier0004.lzma.bak
2025-08-15T22:12:53.198Z,1755295973.198 [DataOverHttps](INFO): SBD MOMSN=25853698
2025-08-15T22:13:11.111Z,1755295991.111 [DataOverHttps](INFO): Sending 1459 bytes from file Logs/20250815T220508/Express0005.lzma
2025-08-15T22:13:12.110Z,1755295992.110 [DataOverHttps](INFO): Moved sent file to Logs/20250815T220508/Express0005.lzma.bak
2025-08-15T22:13:12.110Z,1755295992.110 [DataOverHttps](INFO): SBD MOMSN=25853701
2025-08-15T22:13:12.811Z,1755295992.811 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-15T22:13:14.557Z,1755295994.557 [Default:CheckIn:Read_Iridium] Stopped
2025-08-15T22:13:14.557Z,1755295994.557 [Default:CheckIn:C.Wait] Running Loop=1
2025-08-15T22:13:14.557Z,1755295994.557 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-08-15T22:13:22.122Z,1755296002.122 [BPC1](ERROR): Battery stick #2 (s/n: 0124) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2025-08-15T22:13:22.123Z,1755296002.123 [BPC1](ERROR): Battery stick #4 (s/n: 017A) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2025-08-15T22:13:22.123Z,1755296002.123 [BPC1](INFO): Calculating totals. Valid battery stick count: 54. Valid reserve battery stick count: 6.
2025-08-15T22:13:22.127Z,1755296002.127 [BPC1](INFO): Received data from all battery sticks.
2025-08-15T22:18:15.019Z,1755296295.019 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-08-15T22:18:15.019Z,1755296295.019 [Default:CheckIn:C.Wait] Stopped
2025-08-15T22:18:15.019Z,1755296295.019 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-08-15T22:18:15.019Z,1755296295.019 [Default:CheckIn:D] Running Loop=1
2025-08-15T22:18:15.458Z,1755296295.458 [Default:CheckIn:D] Stopped
2025-08-15T22:18:15.458Z,1755296295.458 [Default:CheckIn:E] Running Loop=1
2025-08-15T22:18:15.826Z,1755296295.826 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  7.552887 min 
2025-08-15T22:18:15.826Z,1755296295.826 [Default:CheckIn:E] Stopped
2025-08-15T22:18:15.826Z,1755296295.826 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-08-15T22:18:15.826Z,1755296295.826 [Default:CheckIn] Stopped
2025-08-15T22:18:15.826Z,1755296295.826 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-08-15T22:18:15.827Z,1755296295.827 [Default:CheckIn](INFO): Running loop #2
2025-08-15T22:18:15.827Z,1755296295.827 [Default:CheckIn] Running Loop=2
2025-08-15T22:18:15.827Z,1755296295.827 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-08-15T22:18:15.827Z,1755296295.827 [Default:CheckIn:Read_GPS] Running Loop=1
2025-08-15T22:18:17.836Z,1755296297.836 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,221817.00,A,3648.16550,N,12147.28024,W,0.272,288.03,150825,,,A*7D
2025-08-15T22:18:17.838Z,1755296297.838 [NAL9602](INFO): GPS fix at 20250815T221817: (36.802758, -121.788004)
2025-08-15T22:18:17.848Z,1755296297.848 [Default:CheckIn:Read_GPS] Stopped
2025-08-15T22:18:17.848Z,1755296297.848 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-08-15T22:18:25.300Z,1755296305.300 [DataOverHttps](INFO): Sending 208 bytes from file Logs/20250815T220508/Courier0007.lzma
2025-08-15T22:18:26.302Z,1755296306.302 [DataOverHttps](INFO): Moved sent file to Logs/20250815T220508/Courier0007.lzma.bak
2025-08-15T22:18:26.302Z,1755296306.302 [DataOverHttps](INFO): SBD MOMSN=25853761
2025-08-15T22:18:44.096Z,1755296324.096 [DataOverHttps](INFO): Sending 188 bytes from file Logs/20250815T220508/Express0008.lzma
2025-08-15T22:18:45.098Z,1755296325.098 [DataOverHttps](INFO): Moved sent file to Logs/20250815T220508/Express0008.lzma.bak
2025-08-15T22:18:45.098Z,1755296325.098 [DataOverHttps](INFO): SBD MOMSN=25853767
2025-08-15T22:18:47.824Z,1755296327.824 [Default:CheckIn:Read_Iridium] Stopped
2025-08-15T22:18:47.824Z,1755296327.824 [Default:CheckIn:C.Wait] Running Loop=1
2025-08-15T22:18:47.824Z,1755296327.824 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-08-15T22:18:48.618Z,1755296328.618 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-08-15T22:18:48.700Z,1755296328.700 [NAL9602](ERROR): received: 
+CSQ:0

OK
2025-08-15T22:21:27.699Z,1755296487.699 [CommandExec](IMPORTANT): got command configSet list
2025-08-15T22:21:27.700Z,1755296487.700 [CommandExec](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-08-15T22:21:27.700Z,1755296487.700 [CommandExec](IMPORTANT): BPC1.batteryMissingStickThreshold=5 count;

2025-08-15T22:21:27.701Z,1755296487.701 [CommandExec](IMPORTANT): CBIT.runNavErrorCritical=0 bool;

2025-08-15T22:21:27.702Z,1755296487.702 [CommandExec](IMPORTANT): ElevatorServo.currLimit=50 percent;

2025-08-15T22:21:27.703Z,1755296487.703 [CommandExec](IMPORTANT): RudderServo.offsetAngle=-0.3 degree;

2025-08-15T22:21:27.703Z,1755296487.703 [CommandExec](IMPORTANT): ThrusterHE.bestEffortMode=1 bool;

2025-08-15T22:21:27.704Z,1755296487.704 [CommandExec](IMPORTANT): VerticalControl.buoyancyNeutral=264.941384 cubic_centimeter;

2025-08-15T22:21:27.705Z,1755296487.705 [CommandExec](IMPORTANT): VerticalControl.massDefault=31.919755 millimeter;

2025-08-15T22:21:38.128Z,1755296498.128 [CommandLine](INFO): End of History
2025-08-15T22:22:17.858Z,1755296537.858 [CommandExec](IMPORTANT): got command configSet RudderServo.offsetAngle 0.3 degree persist
2025-08-15T22:22:19.633Z,1755296539.633 [CommandExec](IMPORTANT): got command ibit
2025-08-15T22:22:19.937Z,1755296539.937 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-08-15T22:22:19.937Z,1755296539.937 [IBIT](IMPORTANT): Beginning control surface checks.
2025-08-15T22:22:19.941Z,1755296539.941 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-08-15T22:22:24.480Z,1755296544.480 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.006469
CHAN A1 (24V): 0.002445
CHAN A2 (12V): -0.004490
CHAN A3 (5V): -0.003017
CHAN B0 (3.3V): -0.001126
CHAN B1 (3.15aV): -0.001834
CHAN B2 (3.15bV): -0.000593
CHAN B3 (GND): -0.000212
OPEN: 0.007187
Full Scale: +/- 1 mA
2025-08-15T22:22:35.178Z,1755296555.178 [IBIT](FAULT): Rudder: EXPECTED:15.000000 ACTUAL:14.389905
2025-08-15T22:22:35.178Z,1755296555.178 [IBIT](FAULT): Control surface position failure.
2025-08-15T22:22:50.194Z,1755296570.194 [CommandExec](IMPORTANT): got command configSet RudderServo.offsetAngle -0.4 degree persist
2025-08-15T22:22:51.238Z,1755296571.238 [CommandExec](IMPORTANT): got command ibit
2025-08-15T22:23:20.144Z,1755296600.144 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-08-15T22:23:24.590Z,1755296604.590 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222324.00,A,3648.16472,N,12147.28085,W,0.136,288.03,150825,,,A*7C
2025-08-15T22:23:24.593Z,1755296604.593 [NAL9602](INFO): GPS fix at 20250815T222324: (36.802745, -121.788014)
2025-08-15T22:23:27.822Z,1755296607.822 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222327.00,A,3648.16468,N,12147.28094,W,0.233,288.03,150825,,,A*72
2025-08-15T22:23:27.824Z,1755296607.824 [NAL9602](INFO): GPS fix at 20250815T222327: (36.802745, -121.788016)
2025-08-15T22:23:30.683Z,1755296610.683 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222330.00,A,3648.16468,N,12147.28062,W,0.311,288.03,150825,,,A*7C
2025-08-15T22:23:30.686Z,1755296610.686 [NAL9602](INFO): GPS fix at 20250815T222330: (36.802745, -121.788010)
2025-08-15T22:23:33.480Z,1755296613.480 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222333.00,A,3648.16477,N,12147.28047,W,0.058,288.03,150825,,,A*78
2025-08-15T22:23:33.482Z,1755296613.482 [NAL9602](INFO): GPS fix at 20250815T222333: (36.802746, -121.788008)
2025-08-15T22:23:36.726Z,1755296616.726 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222336.00,A,3648.16475,N,12147.28124,W,0.311,288.03,150825,,,A*75
2025-08-15T22:23:36.732Z,1755296616.732 [NAL9602](INFO): GPS fix at 20250815T222336: (36.802746, -121.788021)
2025-08-15T22:23:39.544Z,1755296619.544 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222339.00,A,3648.16481,N,12147.28160,W,0.292,288.03,150825,,,A*7B
2025-08-15T22:23:39.549Z,1755296619.549 [NAL9602](INFO): GPS fix at 20250815T222339: (36.802747, -121.788027)
2025-08-15T22:23:42.771Z,1755296622.771 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222342.00,A,3648.16462,N,12147.28218,W,0.408,288.03,150825,,,A*73
2025-08-15T22:23:42.778Z,1755296622.778 [NAL9602](INFO): GPS fix at 20250815T222342: (36.802744, -121.788036)
2025-08-15T22:23:45.600Z,1755296625.600 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222345.00,A,3648.16429,N,12147.28244,W,0.447,288.03,150825,,,A*79
2025-08-15T22:23:45.602Z,1755296625.602 [NAL9602](INFO): GPS fix at 20250815T222345: (36.802738, -121.788041)
2025-08-15T22:23:48.830Z,1755296628.830 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222348.00,A,3648.16408,N,12147.28293,W,0.428,288.03,150825,,,A*74
2025-08-15T22:23:48.832Z,1755296628.832 [NAL9602](INFO): GPS fix at 20250815T222348: (36.802735, -121.788049)
2025-08-15T22:23:51.665Z,1755296631.665 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222351.00,A,3648.16397,N,12147.28331,W,0.350,288.03,150825,,,A*7C
2025-08-15T22:23:51.667Z,1755296631.667 [NAL9602](INFO): GPS fix at 20250815T222351: (36.802733, -121.788055)
2025-08-15T22:23:54.484Z,1755296634.484 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222354.00,A,3648.16383,N,12147.28350,W,0.156,288.03,150825,,,A*7F
2025-08-15T22:23:54.486Z,1755296634.486 [NAL9602](INFO): GPS fix at 20250815T222354: (36.802731, -121.788058)
2025-08-15T22:23:57.715Z,1755296637.715 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222357.00,A,3648.16345,N,12147.28392,W,0.156,288.03,150825,,,A*78
2025-08-15T22:23:57.718Z,1755296637.718 [NAL9602](INFO): GPS fix at 20250815T222357: (36.802724, -121.788065)
2025-08-15T22:24:00.546Z,1755296640.546 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222400.00,A,3648.16336,N,12147.28398,W,0.078,288.03,150825,,,A*7E
2025-08-15T22:24:00.548Z,1755296640.548 [NAL9602](INFO): GPS fix at 20250815T222400: (36.802723, -121.788066)
2025-08-15T22:24:03.778Z,1755296643.778 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222403.00,A,3648.16344,N,12147.28411,W,0.058,288.03,150825,,,A*7C
2025-08-15T22:24:03.781Z,1755296643.781 [NAL9602](INFO): GPS fix at 20250815T222403: (36.802724, -121.788068)
2025-08-15T22:24:06.603Z,1755296646.603 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222406.00,A,3648.16338,N,12147.28425,W,0.058,288.03,150825,,,A*75
2025-08-15T22:24:06.606Z,1755296646.606 [NAL9602](INFO): GPS fix at 20250815T222406: (36.802723, -121.788071)
2025-08-15T22:24:09.842Z,1755296649.842 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222409.00,A,3648.16337,N,12147.28446,W,0.058,288.03,150825,,,A*70
2025-08-15T22:24:09.845Z,1755296649.845 [NAL9602](INFO): GPS fix at 20250815T222409: (36.802723, -121.788074)
2025-08-15T22:24:10.413Z,1755296650.413 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2025-08-15T22:24:10.793Z,1755296650.793 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.254000
2025-08-15T22:24:10.794Z,1755296650.794 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2025-08-15T22:24:10.794Z,1755296650.794 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2025-08-15T22:24:11.220Z,1755296651.220 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2025-08-15T22:24:11.220Z,1755296651.220 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2025-08-15T22:24:11.221Z,1755296651.221 [IBIT](IMPORTANT): Pressure:10.003646 PSI
2025-08-15T22:24:11.221Z,1755296651.221 [IBIT](IMPORTANT): Humidity:5.933342 %
2025-08-15T22:24:11.621Z,1755296651.621 [IBIT](IMPORTANT): Vehicle Pitch:-0.753758 degrees
2025-08-15T22:24:11.622Z,1755296651.622 [IBIT](IMPORTANT): Vehicle  Roll:2.339931 degrees
2025-08-15T22:24:11.622Z,1755296651.622 [IBIT](IMPORTANT): Vehicle  Heading:253.557053 degrees
2025-08-15T22:24:12.010Z,1755296652.010 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2025-08-15T22:24:12.010Z,1755296652.010 [IBIT](IMPORTANT): buoyancyNeutral: 264.941376 cc
2025-08-15T22:24:12.010Z,1755296652.010 [IBIT](IMPORTANT): massDefault: 3.191976 cm
2025-08-15T22:24:12.011Z,1755296652.011 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2025-08-15T22:24:12.011Z,1755296652.011 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2025-08-15T22:24:12.011Z,1755296652.011 [IBIT](IMPORTANT): IBIT FAILED
2025-08-15T22:24:12.299Z,1755296652.299 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2025-08-15T22:24:12.299Z,1755296652.299 [Default:CheckIn:C.Wait] Stopped
2025-08-15T22:24:12.299Z,1755296652.299 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-08-15T22:24:12.299Z,1755296652.299 [Default:CheckIn:D] Running Loop=1
2025-08-15T22:24:12.670Z,1755296652.670 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222412.00,A,3648.16353,N,12147.28484,W,0.117,288.03,150825,,,A*7C
2025-08-15T22:24:12.672Z,1755296652.672 [NAL9602](INFO): GPS fix at 20250815T222412: (36.802726, -121.788081)
2025-08-15T22:24:12.682Z,1755296652.682 [Default:CheckIn:D] Stopped
2025-08-15T22:24:12.682Z,1755296652.682 [Default:CheckIn:E] Running Loop=1
2025-08-15T22:24:13.100Z,1755296653.100 [Default:CheckIn:E](IMPORTANT): Default mission has been running for  13.506620 min 
2025-08-15T22:24:13.100Z,1755296653.100 [Default:CheckIn:E] Stopped
2025-08-15T22:24:13.100Z,1755296653.100 [Default:CheckIn](INFO): Completed Default:CheckIn
2025-08-15T22:24:13.101Z,1755296653.101 [Default:CheckIn] Stopped
2025-08-15T22:24:13.101Z,1755296653.101 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-08-15T22:24:13.101Z,1755296653.101 [Default:CheckIn](INFO): Running loop #3
2025-08-15T22:24:13.101Z,1755296653.101 [Default:CheckIn] Running Loop=3
2025-08-15T22:24:13.101Z,1755296653.101 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-08-15T22:24:13.101Z,1755296653.101 [Default:CheckIn:Read_GPS] Running Loop=1
2025-08-15T22:24:15.494Z,1755296655.494 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222415.00,A,3648.16370,N,12147.28508,W,0.136,288.03,150825,,,A*7C
2025-08-15T22:24:15.496Z,1755296655.496 [NAL9602](INFO): GPS fix at 20250815T222415: (36.802728, -121.788085)
2025-08-15T22:24:15.506Z,1755296655.506 [Default:CheckIn:Read_GPS] Stopped
2025-08-15T22:24:15.506Z,1755296655.506 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-08-15T22:24:23.323Z,1755296663.323 [DataOverHttps](INFO): Sending 77 bytes from file Logs/20250815T220508/Courier0010.lzma
2025-08-15T22:24:24.326Z,1755296664.326 [DataOverHttps](INFO): Moved sent file to Logs/20250815T220508/Courier0010.lzma.bak
2025-08-15T22:24:24.326Z,1755296664.326 [DataOverHttps](INFO): SBD MOMSN=25853787
2025-08-15T22:24:43.649Z,1755296683.649 [DataOverHttps](INFO): Sending 1385 bytes from file Logs/20250815T220508/Express0011.lzma
2025-08-15T22:24:44.646Z,1755296684.646 [DataOverHttps](INFO): Moved sent file to Logs/20250815T220508/Express0011.lzma.bak
2025-08-15T22:24:44.646Z,1755296684.646 [DataOverHttps](INFO): SBD MOMSN=25853790
2025-08-15T22:24:47.441Z,1755296687.441 [Default:CheckIn:Read_Iridium] Stopped
2025-08-15T22:24:47.441Z,1755296687.441 [Default:CheckIn:C.Wait] Running Loop=1
2025-08-15T22:24:47.441Z,1755296687.441 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-08-15T22:24:48.243Z,1755296688.243 [NAL9602](INFO): Not Powering down - fast GPS
2025-08-15T22:25:04.529Z,1755296704.529 [CommandExec](IMPORTANT): got command ibit
2025-08-15T22:25:04.561Z,1755296704.561 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-08-15T22:25:04.561Z,1755296704.561 [IBIT](IMPORTANT): Beginning control surface checks.
2025-08-15T22:25:04.566Z,1755296704.566 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-08-15T22:25:06.010Z,1755296706.010 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,222505.00,A,3648.16502,N,12147.28673,W,0.292,288.03,150825,,,A*7D
2025-08-15T22:25:06.012Z,1755296706.012 [NAL9602](INFO): GPS fix at 20250815T222505: (36.802750, -121.788112)
2025-08-15T22:25:09.032Z,1755296709.032 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.008290
CHAN A1 (24V): 0.003010
CHAN A2 (12V): -0.004798
CHAN A3 (5V): -0.002577
CHAN B0 (3.3V): -0.001547
CHAN B1 (3.15aV): -0.001502
CHAN B2 (3.15bV): -0.001478
CHAN B3 (GND): -0.000277
OPEN: 0.007025
Full Scale: +/- 1 mA
2025-08-15T22:25:19.911Z,1755296719.911 [IBIT](FAULT): Rudder: EXPECTED:15.000000 ACTUAL:14.705007
2025-08-15T22:25:19.912Z,1755296719.912 [IBIT](FAULT): Control surface position failure.
2025-08-15T22:25:36.714Z,1755296736.714 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2025-08-15T22:25:36.796Z,1755296736.796 [NAL9602](ERROR): received: 
+CSQ:0

OK
2025-08-15T22:26:55.261Z,1755296815.261 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2025-08-15T22:26:55.669Z,1755296815.669 [IBIT](IMPORTANT): Battery Status:
Battery Charge (AH): 0.000000
Voltage: 16.294001
2025-08-15T22:26:55.670Z,1755296815.670 [IBIT](IMPORTANT): batteryCapacityThreshold: 50.000000 Ah
2025-08-15T22:26:55.670Z,1755296815.670 [IBIT](IMPORTANT): batteryVoltageThreshold: 13.700000 V
2025-08-15T22:26:56.057Z,1755296816.057 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2025-08-15T22:26:56.057Z,1755296816.057 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2025-08-15T22:26:56.058Z,1755296816.058 [IBIT](IMPORTANT): Pressure:9.986827 PSI
2025-08-15T22:26:56.058Z,1755296816.058 [IBIT](IMPORTANT): Humidity:5.933342 %
2025-08-15T22:26:56.461Z,1755296816.461 [IBIT](IMPORTANT): Vehicle Pitch:-0.749469 degrees
2025-08-15T22:26:56.462Z,1755296816.462 [IBIT](IMPORTANT): Vehicle  Roll:2.346425 degrees
2025-08-15T22:26:56.462Z,1755296816.462 [IBIT](IMPORTANT): Vehicle  Heading:253.556839 degrees
2025-08-15T22:26:56.849Z,1755296816.849 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2025-08-15T22:26:56.849Z,1755296816.849 [IBIT](IMPORTANT): buoyancyNeutral: 264.941376 cc
2025-08-15T22:26:56.850Z,1755296816.850 [IBIT](IMPORTANT): massDefault: 3.191976 cm
2025-08-15T22:26:56.850Z,1755296816.850 [IBIT](IMPORTANT): stopDepth: 275.000000 m
2025-08-15T22:26:56.850Z,1755296816.850 [IBIT](IMPORTANT): abortDepth: 300.000000 m
2025-08-15T22:26:56.850Z,1755296816.850 [IBIT](IMPORTANT): IBIT FAILED
2025-08-15T22:28:30.877Z,1755296910.877 [CommandExec](IMPORTANT): got command restart application
2025-08-15T22:28:31.880Z,1755296911.880 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-15T22:28:31.880Z,1755296911.880 [CommandExec](INFO): Uninitializing the command executive.
2025-08-15T22:28:31.880Z,1755296911.880 [CommandExec](INFO): Uninitializing the command scheduler.
2025-08-15T22:28:31.880Z,1755296911.880 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-08-15T22:28:32.016Z,1755296912.016 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2025-08-15T22:28:32.016Z,1755296912.016 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-08-15T22:28:32.016Z,1755296912.016 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-08-15T22:28:32.017Z,1755296912.017 [NavChartDb](INFO): Join timeout helper Thread ID is 2411
2025-08-15T22:28:32.172Z,1755296912.172 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-15T22:28:32.172Z,1755296912.172 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-08-15T22:28:32.186Z,1755296912.186 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-08-15T22:28:32.186Z,1755296912.186 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-08-15T22:28:32.187Z,1755296912.187 [Radio_Surface](INFO): Join timeout helper Thread ID is 2412
2025-08-15T22:28:32.276Z,1755296912.276 [Radio_Surface](INFO): Powering down
2025-08-15T22:28:32.277Z,1755296912.277 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-15T22:28:32.277Z,1755296912.277 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-08-15T22:28:32.300Z,1755296912.300 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-08-15T22:28:32.300Z,1755296912.300 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-08-15T22:28:32.300Z,1755296912.300 [Onboard](INFO): Join timeout helper Thread ID is 2413
2025-08-15T22:28:35.896Z,1755296915.896 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-15T22:28:35.897Z,1755296915.897 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-08-15T22:28:35.912Z,1755296915.912 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-08-15T22:28:35.912Z,1755296915.912 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-08-15T22:28:35.912Z,1755296915.912 [DataOverHttps](INFO): Join timeout helper Thread ID is 2414
2025-08-15T22:28:36.072Z,1755296916.072 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-15T22:28:36.073Z,1755296916.073 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-08-15T22:28:36.084Z,1755296916.084 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2025-08-15T22:28:36.084Z,1755296916.084 [DAT ThreadHandler](INFO): Thread cancelled.
2025-08-15T22:28:36.084Z,1755296916.084 [DAT](INFO): Join timeout helper Thread ID is 2415
2025-08-15T22:28:36.152Z,1755296916.152 [DAT](INFO): Powering down
2025-08-15T22:28:36.225Z,1755296916.225 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-15T22:28:36.225Z,1755296916.225 [DAT](INFO): Powering down
2025-08-15T22:28:36.226Z,1755296916.226 [DAT ThreadHandler](INFO): Thread cancelled.
2025-08-15T22:28:36.229Z,1755296916.229 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2025-08-15T22:28:36.229Z,1755296916.229 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-08-15T22:28:36.229Z,1755296916.229 [BackseatComponent](INFO): Join timeout helper Thread ID is 2416
2025-08-15T22:28:36.308Z,1755296916.308 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-15T22:28:36.309Z,1755296916.309 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-08-15T22:28:36.313Z,1755296916.313 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-08-15T22:28:36.313Z,1755296916.313 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-08-15T22:28:36.314Z,1755296916.314 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2417
2025-08-15T22:28:36.528Z,1755296916.528 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-15T22:28:36.528Z,1755296916.528 [WetLabsBB2FL](INFO): Powering down
2025-08-15T22:28:36.529Z,1755296916.529 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-08-15T22:28:36.537Z,1755296916.537 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-08-15T22:28:36.537Z,1755296916.537 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-08-15T22:28:36.537Z,1755296916.537 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2418
2025-08-15T22:28:37.436Z,1755296917.436 [CTD_Seabird](INFO): Powering down
2025-08-15T22:28:37.449Z,1755296917.449 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-15T22:28:37.450Z,1755296917.450 [CTD_Seabird](INFO): Powering down
2025-08-15T22:28:37.464Z,1755296917.464 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-08-15T22:28:37.469Z,1755296917.469 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-08-15T22:28:37.469Z,1755296917.469 [logger ThreadHandler](INFO): Thread cancelled.
2025-08-15T22:28:37.470Z,1755296917.470 [logger](INFO): Join timeout helper Thread ID is 2419
2025-08-15T22:28:37.476Z,1755296917.476 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-15T22:28:37.476Z,1755296917.476 [logger ThreadHandler](INFO): Thread cancelled.
2025-08-15T22:28:37.492Z,1755296917.492 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-08-15T22:28:37.493Z,1755296917.493 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-08-15T22:28:37.493Z,1755296917.493 [CommandLine](INFO): Join timeout helper Thread ID is 2420
2025-08-15T22:28:37.588Z,1755296917.588 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-15T22:28:37.588Z,1755296917.588 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-08-15T22:28:37.593Z,1755296917.593 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-08-15T22:28:37.593Z,1755296917.593 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-08-15T22:28:37.593Z,1755296917.593 [CommandExec](INFO): Join timeout helper Thread ID is 2421
2025-08-15T22:28:37.594Z,1755296917.594 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-08-15T22:28:37.594Z,1755296917.594 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-08-15T22:28:37.595Z,1755296917.595 [controlThread](INFO): Join timeout helper Thread ID is 2422
2025-08-15T22:28:37.680Z,1755296917.680 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-08-15T22:28:37.680Z,1755296917.680 [controlThread](DEBUG): Uninitializing ControlThread
2025-08-15T22:28:37.681Z,1755296917.681 [AHRS_M2](INFO): Powering down
2025-08-15T22:28:37.856Z,1755296917.856 [DVL_micro](INFO): Powering down
2025-08-15T22:28:37.857Z,1755296917.857 [NAL9602](INFO): Powering down
2025-08-15T22:28:37.858Z,1755296917.858 [Sonardyne_Nano](INFO): Powering down
2025-08-15T22:28:38.060Z,1755296918.060 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-08-15T22:28:38.061Z,1755296918.061 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-08-15T22:28:38.061Z,1755296918.061 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-08-15T22:28:38.062Z,1755296918.062 [MissionManager](INFO): Uninitializing Mission Default
2025-08-15T22:28:38.062Z,1755296918.062 [Default] Stopped
2025-08-15T22:28:38.062Z,1755296918.062 [Default](DEBUG): Aggregate::uninitialize Default
2025-08-15T22:28:38.062Z,1755296918.062 [Default:B.GoToSurface] Stopped
2025-08-15T22:28:38.062Z,1755296918.062 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-08-15T22:28:38.062Z,1755296918.062 [Default:CheckIn] Stopped
2025-08-15T22:28:38.062Z,1755296918.062 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-08-15T22:28:38.062Z,1755296918.062 [Default:CheckIn:C.Wait] Stopped
2025-08-15T22:28:38.062Z,1755296918.062 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-08-15T22:28:38.065Z,1755296918.065 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-08-15T22:28:38.066Z,1755296918.066 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-08-15T22:28:38.066Z,1755296918.066 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-08-15T22:28:38.066Z,1755296918.066 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-08-15T22:28:38.067Z,1755296918.067 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-08-15T22:28:38.067Z,1755296918.067 [BuoyancyServo](INFO): Powering down
2025-08-15T22:28:38.080Z,1755296918.080 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-08-15T22:28:38.080Z,1755296918.080 [ElevatorServo](INFO): Powering down
2025-08-15T22:28:38.081Z,1755296918.081 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-08-15T22:28:38.081Z,1755296918.081 [MassServo](INFO): Powering down
2025-08-15T22:28:38.082Z,1755296918.082 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-08-15T22:28:38.082Z,1755296918.082 [RudderServo](INFO): Powering down
2025-08-15T22:28:38.083Z,1755296918.083 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-08-15T22:28:38.083Z,1755296918.083 [ThrusterHE](INFO): Powering down
2025-08-15T22:28:38.084Z,1755296918.084 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-08-15T22:28:38.084Z,1755296918.084 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-08-15T22:28:38.084Z,1755296918.084 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-08-15T22:28:38.085Z,1755296918.085 [CBIT](DEBUG): Powering off loads.
2025-08-15T22:28:38.096Z,1755296918.096 [CBIT](DEBUG): Disabling WDT.
2025-08-15T22:28:38.108Z,1755296918.108 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-08-15T22:28:38.108Z,1755296918.108 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-08-15T22:28:38.109Z,1755296918.109 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-08-15T22:28:38.249Z,1755296918.249 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-08-15T22:28:38.249Z,1755296918.249 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-08-15T22:28:38.264Z,1755296918.264 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-08-15T22:28:38.270Z,1755296918.270 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-08-15T22:28:38.301Z,1755296918.301 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-08-15T22:28:38.303Z,1755296918.303 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-08-15T22:28:38.311Z,1755296918.311 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-08-15T22:28:38.317Z,1755296918.317 [DAT ThreadHandler](INFO): Thread cancelled.
2025-08-15T22:28:38.359Z,1755296918.359 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-08-15T22:28:38.413Z,1755296918.413 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-08-15T22:28:38.487Z,1755296918.487 [logger ThreadHandler](INFO): Thread cancelled.