2025-04-28T18:20:26.120Z,1745864426.120 [Supervisor](DEBUG): Initializing supervisor. 2025-04-28T18:20:26.125Z,1745864426.125 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2025-04-28T18:20:26.125Z,1745864426.125 [SyncHandler](INFO): Protected caller Thread ID is 5354 2025-04-28T18:20:26.126Z,1745864426.126 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2025-04-28T18:20:26.127Z,1745864426.127 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2025-04-28T18:20:26.127Z,1745864426.127 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5355 2025-04-28T18:20:26.131Z,1745864426.131 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2025-04-28T18:20:26.152Z,1745864426.152 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2025-04-28T18:20:26.153Z,1745864426.153 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2025-04-28T18:20:26.153Z,1745864426.153 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 5356 2025-04-28T18:20:26.157Z,1745864426.157 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2025-04-28T18:20:26.158Z,1745864426.158 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2025-04-28T18:20:26.159Z,1745864426.159 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5357 2025-04-28T18:20:26.161Z,1745864426.161 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2025-04-28T18:20:26.162Z,1745864426.162 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2025-04-28T18:20:26.162Z,1745864426.162 [logger ThreadHandler](INFO): Protected caller Thread ID is 5358 2025-04-28T18:20:26.166Z,1745864426.166 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2025-04-28T18:20:26.167Z,1745864426.167 [Supervisor](INFO): Looking for Config files in directory: Config/ 2025-04-28T18:20:26.168Z,1745864426.168 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2025-04-28T18:20:26.535Z,1745864426.535 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2025-04-28T18:20:26.536Z,1745864426.536 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2025-04-28T18:20:26.816Z,1745864426.816 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2025-04-28T18:20:26.816Z,1745864426.816 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2025-04-28T18:20:27.322Z,1745864427.322 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2025-04-28T18:20:27.322Z,1745864427.322 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2025-04-28T18:20:27.568Z,1745864427.568 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2025-04-28T18:20:27.568Z,1745864427.568 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2025-04-28T18:20:27.807Z,1745864427.807 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2025-04-28T18:20:27.807Z,1745864427.807 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2025-04-28T18:20:28.714Z,1745864428.714 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2025-04-28T18:20:28.715Z,1745864428.715 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2025-04-28T18:20:29.075Z,1745864429.075 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2025-04-28T18:20:29.075Z,1745864429.075 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2025-04-28T18:20:29.199Z,1745864429.199 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2025-04-28T18:20:29.200Z,1745864429.200 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2025-04-28T18:20:29.290Z,1745864429.290 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2025-04-28T18:20:29.487Z,1745864429.487 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2025-04-28T18:20:29.488Z,1745864429.488 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2025-04-28T18:20:29.596Z,1745864429.596 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2025-04-28T18:20:29.596Z,1745864429.596 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2025-04-28T18:20:29.830Z,1745864429.830 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2025-04-28T18:20:29.831Z,1745864429.831 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2025-04-28T18:20:29.981Z,1745864429.981 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2025-04-28T18:20:29.981Z,1745864429.981 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2025-04-28T18:20:30.103Z,1745864430.103 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2025-04-28T18:20:30.979Z,1745864430.979 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2025-04-28T18:20:30.980Z,1745864430.980 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2025-04-28T18:20:31.554Z,1745864431.554 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2025-04-28T18:20:31.556Z,1745864431.556 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-opah/ 2025-04-28T18:20:31.557Z,1745864431.557 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Science.cfg 2025-04-28T18:20:31.671Z,1745864431.671 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Battery.cfg 2025-04-28T18:20:31.949Z,1745864431.949 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2025-04-28T18:20:31.949Z,1745864431.949 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Navigation.cfg 2025-04-28T18:20:32.063Z,1745864432.063 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/vehicle.cfg 2025-04-28T18:20:32.170Z,1745864432.170 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/BIT.cfg 2025-04-28T18:20:32.291Z,1745864432.291 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Sensor.cfg 2025-04-28T18:20:32.434Z,1745864432.434 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Control.cfg 2025-04-28T18:20:32.544Z,1745864432.544 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/logger.cfg 2025-04-28T18:20:32.664Z,1745864432.664 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/secure.cfg 2025-04-28T18:20:32.761Z,1745864432.761 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Servo.cfg 2025-04-28T18:20:32.891Z,1745864432.891 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Simulator.cfg 2025-04-28T18:20:32.988Z,1745864432.988 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-opah/root/ 2025-04-28T18:20:32.989Z,1745864432.989 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2025-04-28T18:20:32.996Z,1745864432.996 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2025-04-28T18:20:33.007Z,1745864433.007 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2025-04-28T18:20:33.007Z,1745864433.007 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2025-04-28T18:20:33.124Z,1745864433.124 [DeadReckonUsingMultipleVelocitySources] Loaded 2025-04-28T18:20:33.124Z,1745864433.124 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2025-04-28T18:20:33.164Z,1745864433.164 [DeadReckonWithRespectToSeafloor] Loaded 2025-04-28T18:20:33.164Z,1745864433.164 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread. 2025-04-28T18:20:33.177Z,1745864433.177 [NavChart] Loaded 2025-04-28T18:20:33.177Z,1745864433.177 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2025-04-28T18:20:33.183Z,1745864433.183 [UniversalFixResidualReporter] Loaded 2025-04-28T18:20:33.183Z,1745864433.183 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2025-04-28T18:20:33.184Z,1745864433.184 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2025-04-28T18:20:33.184Z,1745864433.184 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2025-04-28T18:20:33.824Z,1745864433.824 [AHRS_M2] Loaded 2025-04-28T18:20:33.824Z,1745864433.824 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2025-04-28T18:20:34.077Z,1745864434.077 [BackseatComponent](INFO): Found secondary power supply at: /dev/loadC3 2025-04-28T18:20:34.077Z,1745864434.077 [BackseatComponent] Loaded 2025-04-28T18:20:34.078Z,1745864434.078 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2025-04-28T18:20:34.079Z,1745864434.079 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4085D4E0 2025-04-28T18:20:34.079Z,1745864434.079 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 5441 2025-04-28T18:20:34.082Z,1745864434.082 [LcmUniversalReporter] Loaded 2025-04-28T18:20:34.082Z,1745864434.082 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread. 2025-04-28T18:20:34.178Z,1745864434.178 [DAT] Loaded 2025-04-28T18:20:34.179Z,1745864434.179 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2025-04-28T18:20:34.180Z,1745864434.180 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4088D4E0 2025-04-28T18:20:34.180Z,1745864434.180 [DAT ThreadHandler](INFO): Protected caller Thread ID is 5442 2025-04-28T18:20:34.275Z,1745864434.275 [DataOverHttps] Loaded 2025-04-28T18:20:34.276Z,1745864434.276 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2025-04-28T18:20:34.276Z,1745864434.276 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408BD4E0 2025-04-28T18:20:34.277Z,1745864434.277 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5443 2025-04-28T18:20:34.298Z,1745864434.298 [DepthKeller33X] Loaded 2025-04-28T18:20:34.299Z,1745864434.299 [ComponentRegistry](DEBUG): SyncComponent "DepthKeller33X" handled in the control thread. 2025-04-28T18:20:34.361Z,1745864434.361 [NAL9602] Loaded 2025-04-28T18:20:34.362Z,1745864434.362 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2025-04-28T18:20:34.391Z,1745864434.391 [Onboard] Loaded 2025-04-28T18:20:34.392Z,1745864434.392 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2025-04-28T18:20:34.393Z,1745864434.393 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408ED4E0 2025-04-28T18:20:34.393Z,1745864434.393 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 5444 2025-04-28T18:20:34.407Z,1745864434.407 [Power24vConverter] Loaded 2025-04-28T18:20:34.407Z,1745864434.407 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2025-04-28T18:20:34.424Z,1745864434.424 [Radio_Surface] Loaded 2025-04-28T18:20:34.424Z,1745864434.424 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2025-04-28T18:20:34.425Z,1745864434.425 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4091D4E0 2025-04-28T18:20:34.425Z,1745864434.425 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5445 2025-04-28T18:20:34.471Z,1745864434.471 [RDI_Pathfinder] Loaded 2025-04-28T18:20:34.471Z,1745864434.471 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2025-04-28T18:20:34.472Z,1745864434.472 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2025-04-28T18:20:34.473Z,1745864434.473 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2025-04-28T18:20:34.527Z,1745864434.527 [DepthRateCalculator] Loaded 2025-04-28T18:20:34.527Z,1745864434.527 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2025-04-28T18:20:34.532Z,1745864434.532 [PitchRateCalculator] Loaded 2025-04-28T18:20:34.532Z,1745864434.532 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2025-04-28T18:20:34.542Z,1745864434.542 [SpeedCalculator] Loaded 2025-04-28T18:20:34.542Z,1745864434.542 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2025-04-28T18:20:34.547Z,1745864434.547 [YawRateCalculator] Loaded 2025-04-28T18:20:34.547Z,1745864434.547 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2025-04-28T18:20:34.569Z,1745864434.569 [ElevatorOffsetCalculator] Loaded 2025-04-28T18:20:34.569Z,1745864434.569 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2025-04-28T18:20:34.569Z,1745864434.569 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2025-04-28T18:20:34.570Z,1745864434.570 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2025-04-28T18:20:34.635Z,1745864434.635 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2025-04-28T18:20:34.636Z,1745864434.636 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2025-04-28T18:20:34.778Z,1745864434.778 [BuoyancyServo] Loaded 2025-04-28T18:20:34.778Z,1745864434.778 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2025-04-28T18:20:34.802Z,1745864434.802 [ElevatorServo] Loaded 2025-04-28T18:20:34.802Z,1745864434.802 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2025-04-28T18:20:34.825Z,1745864434.825 [MassServo] Loaded 2025-04-28T18:20:34.825Z,1745864434.825 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2025-04-28T18:20:34.847Z,1745864434.847 [RudderServo] Loaded 2025-04-28T18:20:34.848Z,1745864434.848 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2025-04-28T18:20:34.865Z,1745864434.865 [ThrusterHE] Loaded 2025-04-28T18:20:34.866Z,1745864434.866 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread. 2025-04-28T18:20:34.866Z,1745864434.866 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2025-04-28T18:20:34.867Z,1745864434.867 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2025-04-28T18:20:35.043Z,1745864435.043 [CTD_Seabird] Loaded 2025-04-28T18:20:35.044Z,1745864435.044 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2025-04-28T18:20:35.045Z,1745864435.045 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A4E4E0 2025-04-28T18:20:35.045Z,1745864435.045 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 5446 2025-04-28T18:20:35.047Z,1745864435.047 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2025-04-28T18:20:35.047Z,1745864435.047 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2025-04-28T18:20:35.646Z,1745864435.646 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2025-04-28T18:20:35.646Z,1745864435.646 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2025-04-28T18:20:36.034Z,1745864436.034 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2025-04-28T18:20:36.035Z,1745864436.035 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2025-04-28T18:20:36.398Z,1745864436.398 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2025-04-28T18:20:36.398Z,1745864436.398 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2025-04-28T18:20:36.454Z,1745864436.454 [VerticalControl](DEBUG): Construct VerticalControl. 2025-04-28T18:20:36.506Z,1745864436.506 [VerticalControl] Loaded 2025-04-28T18:20:36.506Z,1745864436.506 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2025-04-28T18:20:36.509Z,1745864436.509 [HorizontalControl](DEBUG): Construct HorizontalControl. 2025-04-28T18:20:36.555Z,1745864436.555 [HorizontalControl] Loaded 2025-04-28T18:20:36.555Z,1745864436.555 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2025-04-28T18:20:36.558Z,1745864436.558 [SpeedControl](DEBUG): Construct SpeedControl. 2025-04-28T18:20:36.559Z,1745864436.559 [SpeedControl] Loaded 2025-04-28T18:20:36.559Z,1745864436.559 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2025-04-28T18:20:36.562Z,1745864436.562 [LoopControl](DEBUG): Construct LoopControl. 2025-04-28T18:20:36.563Z,1745864436.563 [LoopControl] Loaded 2025-04-28T18:20:36.563Z,1745864436.563 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2025-04-28T18:20:36.563Z,1745864436.563 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2025-04-28T18:20:36.564Z,1745864436.564 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2025-04-28T18:20:36.731Z,1745864436.731 [SBIT](DEBUG): Construct Startup Built In Test. 2025-04-28T18:20:36.743Z,1745864436.743 [SBIT] Loaded 2025-04-28T18:20:36.743Z,1745864436.743 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2025-04-28T18:20:36.746Z,1745864436.746 [IBIT](DEBUG): Construct Initiated Built In Test. 2025-04-28T18:20:36.760Z,1745864436.760 [IBIT] Loaded 2025-04-28T18:20:36.760Z,1745864436.760 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2025-04-28T18:20:36.766Z,1745864436.766 [CBIT](DEBUG): Construct Continuous Built In Test. 2025-04-28T18:20:36.966Z,1745864436.966 [CBIT] Loaded 2025-04-28T18:20:36.966Z,1745864436.966 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2025-04-28T18:20:36.997Z,1745864436.997 [GFScanner] Loaded 2025-04-28T18:20:36.998Z,1745864436.998 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread. 2025-04-28T18:20:36.998Z,1745864436.998 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2025-04-28T18:20:36.999Z,1745864436.999 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2025-04-28T18:20:37.030Z,1745864437.030 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2025-04-28T18:20:37.036Z,1745864437.036 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2025-04-28T18:20:37.039Z,1745864437.039 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2025-04-28T18:20:37.050Z,1745864437.050 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2025-04-28T18:20:37.051Z,1745864437.051 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C354E0 2025-04-28T18:20:37.052Z,1745864437.052 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5447 2025-04-28T18:20:37.056Z,1745864437.056 [Supervisor](INFO): Main Thread ID is 5353 2025-04-28T18:20:37.057Z,1745864437.057 [Supervisor](DEBUG): Running supervisor. 2025-04-28T18:20:37.057Z,1745864437.057 [CommandExec ThreadHandler](INFO): Handler Thread ID is 5448 2025-04-28T18:20:37.058Z,1745864437.058 [CommandExec](INFO): Initializing the command executive. 2025-04-28T18:20:37.060Z,1745864437.060 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5449 2025-04-28T18:20:37.062Z,1745864437.062 [controlThread ThreadHandler](INFO): Handler Thread ID is 5450 2025-04-28T18:20:37.062Z,1745864437.062 [controlThread](DEBUG): Initializing ControlThread 2025-04-28T18:20:37.064Z,1745864437.064 [NavChart](DEBUG): Initialize NavChart Navigation. 2025-04-28T18:20:37.064Z,1745864437.064 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2025-04-28T18:20:37.066Z,1745864437.066 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2025-04-28T18:20:37.067Z,1745864437.067 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2025-04-28T18:20:37.067Z,1745864437.067 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2025-04-28T18:20:37.067Z,1745864437.067 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2025-04-28T18:20:37.068Z,1745864437.068 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2025-04-28T18:20:37.069Z,1745864437.069 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2025-04-28T18:20:37.071Z,1745864437.071 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2025-04-28T18:20:37.072Z,1745864437.072 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2025-04-28T18:20:37.072Z,1745864437.072 [LoopControl](DEBUG): Initialize LoopControlComponent. 2025-04-28T18:20:37.072Z,1745864437.072 [SBIT](INFO): Initialize SBIT Component. 2025-04-28T18:20:37.073Z,1745864437.073 [SBIT](IMPORTANT): git: 2025-04-28 2025-04-28T18:20:37.073Z,1745864437.073 [SBIT](INFO): git hash: e1c55ba82c6df7d78839db3c81b116d55d70e7b0 2025-04-28T18:20:37.073Z,1745864437.073 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2025-04-28T18:20:37.075Z,1745864437.075 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Mon Nov 27 01:04:18 PST 2023 2025-04-28T18:20:37.076Z,1745864437.076 [SBIT](INFO): Beginning SBIT in 39.000000 seconds. 2025-04-28T18:20:37.076Z,1745864437.076 [IBIT](INFO): Initialize IBIT Component. 2025-04-28T18:20:37.077Z,1745864437.077 [CBIT](DEBUG): Initialize CBIT Component. 2025-04-28T18:20:37.078Z,1745864437.078 [logger ThreadHandler](INFO): Handler Thread ID is 5451 2025-04-28T18:20:37.090Z,1745864437.090 [CBIT](DEBUG): Initialized mux pins. 2025-04-28T18:20:37.090Z,1745864437.090 [CBIT](DEBUG): Initializing the watchdog timer. 2025-04-28T18:20:37.099Z,1745864437.099 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 5452 2025-04-28T18:20:37.114Z,1745864437.114 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2025-04-28T18:20:37.114Z,1745864437.114 [CBIT](DEBUG): Initializing heartbeat. 2025-04-28T18:20:37.115Z,1745864437.115 [DAT ThreadHandler](INFO): Handler Thread ID is 5453 2025-04-28T18:20:37.115Z,1745864437.115 [DAT](INFO): Powering up 2025-04-28T18:20:37.116Z,1745864437.116 [DAT](DEBUG): Initializing DAT. 2025-04-28T18:20:37.119Z,1745864437.119 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5455 2025-04-28T18:20:37.121Z,1745864437.121 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2025-04-28T18:20:37.142Z,1745864437.142 [Onboard ThreadHandler](INFO): Handler Thread ID is 5456 2025-04-28T18:20:37.160Z,1745864437.160 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5457 2025-04-28T18:20:37.178Z,1745864437.178 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 5458 2025-04-28T18:20:37.179Z,1745864437.179 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2025-04-28T18:20:37.183Z,1745864437.183 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5460 2025-04-28T18:20:37.186Z,1745864437.186 [CBIT](DEBUG): Deactivating emergency mode. 2025-04-28T18:20:37.186Z,1745864437.186 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2025-04-28T18:20:37.186Z,1745864437.186 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2025-04-28T18:20:37.186Z,1745864437.186 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2025-04-28T18:20:37.187Z,1745864437.187 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2025-04-28T18:20:37.187Z,1745864437.187 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2025-04-28T18:20:37.187Z,1745864437.187 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2025-04-28T18:20:37.187Z,1745864437.187 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2025-04-28T18:20:37.187Z,1745864437.187 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2025-04-28T18:20:37.226Z,1745864437.226 [CBIT](DEBUG): Backplane powered. 2025-04-28T18:20:37.226Z,1745864437.226 [GFScanner](DEBUG): Initializing GFScanner 2025-04-28T18:20:37.226Z,1745864437.226 [GFScanner](DEBUG): Deactivating GF circuits. 2025-04-28T18:20:37.231Z,1745864437.231 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2025-04-28T18:20:37.232Z,1745864437.232 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl 2025-04-28T18:20:37.232Z,1745864437.232 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl 2025-04-28T18:20:37.233Z,1745864437.233 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl 2025-04-28T18:20:37.296Z,1745864437.296 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into: 2025-04-28T18:20:37.319Z,1745864437.319 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2025-04-28T18:20:37.361Z,1745864437.361 [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-04-28T18:20:37.370Z,1745864437.370 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl 2025-04-28T18:20:37.371Z,1745864437.371 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl 2025-04-28T18:20:37.372Z,1745864437.372 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl 2025-04-28T18:20:37.566Z,1745864437.566 [Radio_Surface](INFO): Powering up 2025-04-28T18:20:37.686Z,1745864437.686 [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-04-28T18:20:37.708Z,1745864437.708 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2025-04-28T18:20:37.709Z,1745864437.709 [Default:A.Wait](DEBUG): Construct Wait. 2025-04-28T18:20:37.727Z,1745864437.727 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2025-04-28T18:20:37.753Z,1745864437.753 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2025-04-28T18:20:37.776Z,1745864437.776 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2025-04-28T18:20:37.802Z,1745864437.802 [Default:E.Execute](DEBUG): Construct Execute. 2025-04-28T18:20:37.805Z,1745864437.805 [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-04-28T18:20:37.809Z,1745864437.809 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,DepthKeller33X,NAL9602,Power24vConverter,RDI_Pathfinder,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter, 2025-04-28T18:20:37.817Z,1745864437.817 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2025-04-28T18:20:37.847Z,1745864437.847 [DepthKeller33X](INFO): Initializing. 2025-04-28T18:20:37.866Z,1745864437.866 [Power24vConverter](INFO): Powering up. 2025-04-28T18:20:37.867Z,1745864437.867 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2025-04-28T18:20:37.875Z,1745864437.875 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-04-28T18:20:37.899Z,1745864437.899 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-04-28T18:20:37.906Z,1745864437.906 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-04-28T18:20:37.907Z,1745864437.907 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-04-28T18:20:37.914Z,1745864437.914 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-04-28T18:20:37.915Z,1745864437.915 [MassServo](DEBUG): Initializing EZServoServo. 2025-04-28T18:20:37.922Z,1745864437.922 [MassServo](DEBUG): Initializing MassServo. 2025-04-28T18:20:37.923Z,1745864437.923 [RudderServo](DEBUG): Initializing EZServoServo. 2025-04-28T18:20:37.930Z,1745864437.930 [RudderServo](DEBUG): Initializing RudderServo. 2025-04-28T18:20:37.931Z,1745864437.931 [ThrusterHE](DEBUG): Initializing EZServoServo. 2025-04-28T18:20:37.938Z,1745864437.938 [ThrusterHE](DEBUG): Initializing ThrusterHE. 2025-04-28T18:20:38.253Z,1745864438.253 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-04-28T18:20:38.627Z,1745864438.627 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-04-28T18:20:39.031Z,1745864439.031 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-04-28T18:20:39.038Z,1745864439.038 [BuoyancyServo](INFO): INIT BUOYANCY 2025-04-28T18:20:39.451Z,1745864439.451 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-04-28T18:20:42.386Z,1745864442.386 [BuoyancyServo](INFO): INIT BUOYANCY 2025-04-28T18:20:43.604Z,1745864443.604 [ThrusterHE](ERROR): Zero Speed Commanded. 2025-04-28T18:20:48.467Z,1745864448.467 [DAT](INFO): DAT read: 2025-04-28T18:20:48.468Z,1745864448.468 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2025-04-28T18:20:49.979Z,1745864449.979 [DAT](INFO): DAT read: MF Frequency Band 2025-04-28T18:20:49.980Z,1745864449.980 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.14.0 2025-04-28T18:20:49.980Z,1745864449.980 [DAT](INFO): DAT read: Apr 28 2025 18:13:48 2025-04-28T18:20:50.987Z,1745864450.987 [DAT](INFO): DAT read: Features enabled [Bearing] 2025-04-28T18:20:50.988Z,1745864450.988 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2025-04-28T18:20:50.989Z,1745864450.989 [DAT](INFO): commRate: 800 2025-04-28T18:20:53.054Z,1745864453.054 [DAT](INFO): entering command mode 2025-04-28T18:20:53.254Z,1745864453.254 [DAT](INFO): DAT read: 2025-04-28T18:20:53.255Z,1745864453.255 [DAT](INFO): DAT read: user:1> 2025-04-28T18:20:53.255Z,1745864453.255 [DAT](INFO): setting verbose to 3 2025-04-28T18:20:53.506Z,1745864453.506 [DAT](INFO): DAT read: user:1> 2025-04-28T18:20:53.507Z,1745864453.507 [DAT](INFO): DAT read: Verbose | 3 2025-04-28T18:20:53.508Z,1745864453.508 [DAT](INFO): set verbose to 3 2025-04-28T18:20:53.508Z,1745864453.508 [DAT](INFO): setting DatVerbose to 27440 2025-04-28T18:20:53.759Z,1745864453.759 [DAT](INFO): DAT read: user:2> 2025-04-28T18:20:53.760Z,1745864453.760 [DAT](INFO): DAT read: DatVerbose | 27440 2025-04-28T18:20:53.760Z,1745864453.760 [DAT](INFO): set DatVerbose to 27440 2025-04-28T18:20:53.761Z,1745864453.761 [DAT](INFO): setting transmit power to 8 2025-04-28T18:20:54.011Z,1745864454.011 [DAT](INFO): DAT read: user:3> 2025-04-28T18:20:54.011Z,1745864454.011 [DAT](INFO): DAT read: TxPower | 8 (Max) 2025-04-28T18:20:54.012Z,1745864454.012 [DAT](INFO): set transmit power to 8 2025-04-28T18:20:54.012Z,1745864454.012 [DAT](INFO): setting local address to 3 2025-04-28T18:20:54.265Z,1745864454.265 [DAT](INFO): DAT read: user:4> 2025-04-28T18:20:54.269Z,1745864454.269 [DAT](INFO): DAT read: LocalAddr | 3 2025-04-28T18:20:54.270Z,1745864454.270 [DAT](INFO): set local address to 3 2025-04-28T18:20:54.273Z,1745864454.273 [DAT](INFO): Setting time to: 18:20:54 And date to:4/28/2025 2025-04-28T18:20:54.514Z,1745864454.514 [DAT](INFO): DAT read: user:5> 2025-04-28T18:20:54.515Z,1745864454.515 [DAT](INFO): DAT read: Mon Apr 28, 2025 18:20:54 2025-04-28T18:20:54.516Z,1745864454.516 [DAT](INFO): Local DAT time set to Mon Apr 28, 2025 18:20:54 2025-04-28T18:20:55.572Z,1745864455.572 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004572 2025-04-28T18:20:56.919Z,1745864456.919 [CommandExec](IMPORTANT): got command report mod platform_buoyancy_position 2025-04-28T18:20:57.016Z,1745864457.016 [Reporter](INFO): platform_buoyancy_position 1962.903442 cc 2025-04-28T18:20:57.408Z,1745864457.408 [Reporter](INFO): platform_buoyancy_position 1961.088745 cc 2025-04-28T18:20:58.272Z,1745864458.272 [Reporter](INFO): platform_buoyancy_position 1958.064453 cc 2025-04-28T18:21:04.547Z,1745864464.547 [NAL9602](INFO): Powering up NAL9602 2025-04-28T18:21:14.903Z,1745864474.903 [CommandExec](IMPORTANT): got command get depth 2025-04-28T18:21:14.904Z,1745864474.904 [CommandExec](IMPORTANT): depth 0.220521 m 2025-04-28T18:21:15.455Z,1745864475.455 [NAL9602](INFO): NAL9602 initialized 2025-04-28T18:21:16.705Z,1745864476.705 [SBIT](IMPORTANT): Beginning Startup BIT 2025-04-28T18:21:16.747Z,1745864476.747 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-04-28T18:21:21.019Z,1745864481.019 [CommandExec](IMPORTANT): got command show best depth 2025-04-28T18:21:21.020Z,1745864481.020 [CommandExec](IMPORTANT): depth best is DepthKeller33X.depth 2025-04-28T18:21:21.699Z,1745864481.699 [GFScanner](IMPORTANT): Low side ground fault detected mA: CHAN A0 (Batt): 0.947597 CHAN A1 (24V): 0.879859 CHAN A2 (12V): 0.971853 CHAN A3 (5V): -0.128303 CHAN B0 (3.3V): -0.420248 CHAN B1 (3.15aV): -0.782292 CHAN B2 (3.15bV): 0.994649 CHAN B3 (GND): 0.032232 OPEN: -0.012887 Full Scale: +/- 1 mA 2025-04-28T18:21:28.747Z,1745864488.747 [CommandExec](IMPORTANT): got command strobe off 2025-04-28T18:21:28.747Z,1745864488.747 [CommandExec](IMPORTANT): Deactivating strobe 2025-04-28T18:22:10.091Z,1745864530.091 [SBIT](IMPORTANT): SBIT PASSED 2025-04-28T18:22:10.091Z,1745864530.091 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2025-04-28T18:22:10.092Z,1745864530.092 [SBIT](IMPORTANT): BPC1.loadAtStartup=0 bool; 2025-04-28T18:22:10.092Z,1745864530.092 [SBIT](IMPORTANT): DepthKeller33X.maxPressBound=2500 decibar; 2025-04-28T18:22:10.092Z,1745864530.092 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2025-04-28T18:22:10.092Z,1745864530.092 [SBIT](IMPORTANT): GFScanner.simulateHardware=0 bool; 2025-04-28T18:22:10.092Z,1745864530.092 [SBIT](IMPORTANT): MassServo.limitLo=-700000 count; 2025-04-28T18:22:10.092Z,1745864530.092 [SBIT](IMPORTANT): VerticalControl.massPositionLimitAft=-5.5 centimeter; 2025-04-28T18:22:10.496Z,1745864530.496 [MissionManager](IMPORTANT): Started mission Startup 2025-04-28T18:22:10.497Z,1745864530.497 [Startup] Running Loop=1 2025-04-28T18:22:10.497Z,1745864530.497 [Startup](DEBUG): Aggregate::initialize Startup 2025-04-28T18:22:10.497Z,1745864530.497 [Startup:A.GoToSurface] Running Loop=1 2025-04-28T18:22:10.497Z,1745864530.497 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-04-28T18:22:10.498Z,1745864530.498 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-04-28T18:22:10.498Z,1745864530.498 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-04-28T18:22:10.499Z,1745864530.499 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-04-28T18:22:10.499Z,1745864530.499 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-04-28T18:22:10.499Z,1745864530.499 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-04-28T18:22:10.500Z,1745864530.500 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-04-28T18:22:10.501Z,1745864530.501 [Startup:StartupSatComms] Running Loop=1 2025-04-28T18:22:10.502Z,1745864530.502 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2025-04-28T18:22:10.502Z,1745864530.502 [Startup:StartupSatComms:A] Running Loop=1 2025-04-28T18:22:10.881Z,1745864530.881 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2025-04-28T18:22:12.485Z,1745864532.485 [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-04-28T18:22:12.688Z,1745864532.688 [Reporter](INFO): platform_buoyancy_position 1970.766113 cc 2025-04-28T18:22:13.063Z,1745864533.063 [Reporter](INFO): platform_buoyancy_position 1970.967773 cc 2025-04-28T18:22:13.464Z,1745864533.464 [Reporter](INFO): platform_buoyancy_position 1970.564453 cc 2025-04-28T18:22:13.766Z,1745864533.766 [Reporter](INFO): platform_buoyancy_position 1970.766113 cc 2025-04-28T18:22:14.573Z,1745864534.573 [Reporter](INFO): platform_buoyancy_position 1973.991943 cc 2025-04-28T18:22:14.961Z,1745864534.961 [Reporter](INFO): platform_buoyancy_position 1961.088745 cc 2025-04-28T18:22:15.355Z,1745864535.355 [Reporter](INFO): platform_buoyancy_position 1964.515991 cc 2025-04-28T18:22:15.884Z,1745864535.884 [Reporter](INFO): platform_buoyancy_position 1970.362793 cc 2025-04-28T18:22:16.304Z,1745864536.304 [Reporter](INFO): platform_buoyancy_position 1973.991943 cc 2025-04-28T18:22:16.716Z,1745864536.716 [Reporter](INFO): platform_buoyancy_position 1977.419434 cc 2025-04-28T18:22:17.096Z,1745864537.096 [Reporter](INFO): platform_buoyancy_position 1980.443726 cc 2025-04-28T18:22:17.484Z,1745864537.484 [Reporter](INFO): platform_buoyancy_position 1983.669312 cc 2025-04-28T18:22:17.896Z,1745864537.896 [Reporter](INFO): platform_buoyancy_position 1983.870728 cc 2025-04-28T18:22:18.308Z,1745864538.308 [Reporter](INFO): platform_buoyancy_position 1986.895264 cc 2025-04-28T18:22:18.744Z,1745864538.744 [Reporter](INFO): platform_buoyancy_position 1989.717773 cc 2025-04-28T18:22:19.096Z,1745864539.096 [Reporter](INFO): platform_buoyancy_position 1990.120850 cc 2025-04-28T18:22:32.346Z,1745864552.346 [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-04-28T18:23:10.693Z,1745864590.693 [Startup:StartupSatComms:A](INFO): Timed out from 2025-04-28T18:22:10.5Z 2025-04-28T18:23:10.693Z,1745864590.693 [Startup:StartupSatComms:A] Stopped 2025-04-28T18:23:10.695Z,1745864590.695 [Startup:StartupSatComms:B] Running Loop=1 2025-04-28T18:23:11.064Z,1745864591.064 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2025-04-28T18:23:17.698Z,1745864597.698 [DataOverHttps](INFO): Sending 119 bytes from file Logs/20250428T173605/Courier0013.lzma 2025-04-28T18:23:18.700Z,1745864598.700 [DataOverHttps](INFO): Moved sent file to Logs/20250428T173605/Courier0013.lzma.bak 2025-04-28T18:23:18.700Z,1745864598.700 [DataOverHttps](INFO): SBD MOMSN=24739700 2025-04-28T18:23:36.130Z,1745864616.130 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20250428T182026/Courier0000.lzma 2025-04-28T18:23:37.132Z,1745864617.132 [DataOverHttps](INFO): Moved sent file to Logs/20250428T182026/Courier0000.lzma.bak 2025-04-28T18:23:37.132Z,1745864617.132 [DataOverHttps](INFO): SBD MOMSN=24739708 2025-04-28T18:23:37.359Z,1745864617.359 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2025-04-28T18:23:37.359Z,1745864617.359 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-28T18:23:37.361Z,1745864617.361 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 1 2025-04-28T18:23:37.361Z,1745864617.361 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2025-04-28T18:23:37.406Z,1745864617.406 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-28T18:23:37.407Z,1745864617.407 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2025-04-28T18:23:37.770Z,1745864617.770 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-28T18:23:37.770Z,1745864617.770 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2025-04-28T18:23:37.771Z,1745864617.771 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2025-04-28T18:23:37.771Z,1745864617.771 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 1 2025-04-28T18:23:53.514Z,1745864633.514 [DataOverHttps](INFO): Sending 396 bytes from file Logs/20250428T173605/Express0014.lzma 2025-04-28T18:23:54.516Z,1745864634.516 [DataOverHttps](INFO): Moved sent file to Logs/20250428T173605/Express0014.lzma.bak 2025-04-28T18:23:54.517Z,1745864634.517 [DataOverHttps](INFO): SBD MOMSN=24739711 2025-04-28T18:23:54.563Z,1745864634.563 [DAT](INFO): DAT read: user:6>Lowpower 2025-04-28T18:23:54.563Z,1745864634.563 [DAT](DEBUG): Re-entering command mode due to deviceResponse_: user:6>Lowpower 2025-04-28T18:24:00.619Z,1745864640.619 [DAT](INFO): DAT read: Acoustic Wakeup 2025-04-28T18:24:00.619Z,1745864640.619 [DAT](INFO): unknown deviceResponse_: Acoustic Wakeup 2025-04-28T18:24:00.621Z,1745864640.621 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2025-04-28T18:24:00.621Z,1745864640.621 [DAT](INFO): commRate: 800 2025-04-28T18:24:01.374Z,1745864641.374 [DAT](INFO): DAT read: Lowpower 2025-04-28T18:24:01.375Z,1745864641.375 [DAT](INFO): unknown deviceResponse_: Lowpower 2025-04-28T18:24:02.635Z,1745864642.635 [DAT](INFO): DAT read: Acoustic Wakeup 2025-04-28T18:24:02.635Z,1745864642.635 [DAT](INFO): unknown deviceResponse_: Acoustic Wakeup 2025-04-28T18:24:02.636Z,1745864642.636 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2025-04-28T18:24:02.636Z,1745864642.636 [DAT](INFO): commRate: 800 2025-04-28T18:24:03.390Z,1745864643.390 [DAT](INFO): DAT read: Lowpower 2025-04-28T18:24:03.391Z,1745864643.391 [DAT](INFO): unknown deviceResponse_: Lowpower 2025-04-28T18:24:10.894Z,1745864650.894 [Startup:StartupSatComms:B](INFO): Timed out from 2025-04-28T18:23:10.7Z 2025-04-28T18:24:10.894Z,1745864650.894 [Startup:StartupSatComms:B] Stopped 2025-04-28T18:24:10.894Z,1745864650.894 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2025-04-28T18:24:10.894Z,1745864650.894 [Startup:StartupSatComms] Stopped 2025-04-28T18:24:10.894Z,1745864650.894 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2025-04-28T18:24:10.895Z,1745864650.895 [Startup](INFO): Completed Startup 2025-04-28T18:24:10.895Z,1745864650.895 [MissionManager](INFO): Startup is completed. 2025-04-28T18:24:10.896Z,1745864650.896 [MissionManager](INFO): Uninitializing Mission Startup 2025-04-28T18:24:10.896Z,1745864650.896 [Startup] Stopped 2025-04-28T18:24:10.896Z,1745864650.896 [Startup](DEBUG): Aggregate::uninitialize Startup 2025-04-28T18:24:10.896Z,1745864650.896 [Startup:A.GoToSurface] Stopped 2025-04-28T18:24:10.896Z,1745864650.896 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-04-28T18:24:11.311Z,1745864651.311 [MissionManager](IMPORTANT): Started mission Default 2025-04-28T18:24:11.312Z,1745864651.312 [Default] Running Loop=1 2025-04-28T18:24:11.312Z,1745864651.312 [Default](DEBUG): Aggregate::initialize Default 2025-04-28T18:24:11.312Z,1745864651.312 [Default:B.GoToSurface] Running Loop=1 2025-04-28T18:24:11.312Z,1745864651.312 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2025-04-28T18:24:11.313Z,1745864651.313 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2025-04-28T18:24:11.313Z,1745864651.313 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2025-04-28T18:24:11.318Z,1745864651.318 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2025-04-28T18:24:11.318Z,1745864651.318 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2025-04-28T18:24:11.318Z,1745864651.318 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds. 2025-04-28T18:24:11.319Z,1745864651.319 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2025-04-28T18:24:11.319Z,1745864651.319 [Default:A.Wait] Running Loop=1 2025-04-28T18:24:11.319Z,1745864651.319 [Default:A.Wait](DEBUG): Initialize Wait Component. 2025-04-28T18:24:12.304Z,1745864652.304 [DataOverHttps](INFO): Sending 946 bytes from file Logs/20250428T182026/Express0001.lzma 2025-04-28T18:24:13.304Z,1745864653.304 [DataOverHttps](INFO): Moved sent file to Logs/20250428T182026/Express0001.lzma.bak 2025-04-28T18:24:13.304Z,1745864653.304 [DataOverHttps](INFO): SBD MOMSN=24739747 2025-04-28T18:24:16.503Z,1745864656.503 [DAT](INFO): DAT read: Acoustic Wakeup 2025-04-28T18:24:16.503Z,1745864656.503 [DAT](INFO): unknown deviceResponse_: Acoustic Wakeup 2025-04-28T18:24:16.504Z,1745864656.504 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2025-04-28T18:24:16.504Z,1745864656.504 [DAT](INFO): commRate: 800 2025-04-28T18:24:17.511Z,1745864657.511 [DAT](INFO): DAT read: Lowpower 2025-04-28T18:24:17.511Z,1745864657.511 [DAT](INFO): unknown deviceResponse_: Lowpower 2025-04-28T18:24:24.325Z,1745864664.325 [Default:A.Wait](INFO): Done Waiting. 2025-04-28T18:24:24.325Z,1745864664.325 [Default:A.Wait] Stopped 2025-04-28T18:24:24.325Z,1745864664.325 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2025-04-28T18:24:24.661Z,1745864664.661 [Default:CheckIn] Running Loop=1 2025-04-28T18:24:24.661Z,1745864664.661 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2025-04-28T18:24:24.661Z,1745864664.661 [Default:CheckIn:Read_GPS] Running Loop=1 2025-04-28T18:24:25.076Z,1745864665.076 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2025-04-28T18:24:38.435Z,1745864678.435 [DAT](INFO): DAT read: Acoustic Wakeup 2025-04-28T18:24:38.435Z,1745864678.435 [DAT](INFO): unknown deviceResponse_: Acoustic Wakeup 2025-04-28T18:24:38.436Z,1745864678.436 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2025-04-28T18:24:38.436Z,1745864678.436 [DAT](INFO): commRate: 800 2025-04-28T18:24:39.442Z,1745864679.442 [DAT](INFO): DAT read: Lowpower 2025-04-28T18:24:39.443Z,1745864679.443 [DAT](INFO): unknown deviceResponse_: Lowpower 2025-04-28T18:24:48.520Z,1745864688.520 [DAT](INFO): DAT read: Acoustic Wakeup 2025-04-28T18:24:48.520Z,1745864688.520 [DAT](INFO): unknown deviceResponse_: Acoustic Wakeup 2025-04-28T18:24:48.521Z,1745864688.521 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2025-04-28T18:24:48.522Z,1745864688.522 [DAT](INFO): commRate: 800 2025-04-28T18:24:49.275Z,1745864689.275 [DAT](INFO): DAT read: Lowpower 2025-04-28T18:24:49.275Z,1745864689.275 [DAT](INFO): unknown deviceResponse_: Lowpower 2025-04-28T18:25:08.427Z,1745864708.427 [DAT](INFO): DAT read: Acoustic Wakeup 2025-04-28T18:25:08.427Z,1745864708.427 [DAT](INFO): unknown deviceResponse_: Acoustic Wakeup 2025-04-28T18:25:08.428Z,1745864708.428 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2025-04-28T18:25:08.428Z,1745864708.428 [DAT](INFO): commRate: 800 2025-04-28T18:25:09.434Z,1745864709.434 [DAT](INFO): DAT read: Lowpower 2025-04-28T18:25:09.435Z,1745864709.435 [DAT](INFO): unknown deviceResponse_: Lowpower 2025-04-28T18:25:16.510Z,1745864716.510 [DAT](INFO): DAT read: Acoustic Wakeup 2025-04-28T18:25:16.511Z,1745864716.511 [DAT](INFO): unknown deviceResponse_: Acoustic Wakeup 2025-04-28T18:25:16.512Z,1745864716.512 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2025-04-28T18:25:16.513Z,1745864716.513 [DAT](INFO): commRate: 800 2025-04-28T18:25:17.263Z,1745864717.263 [DAT](INFO): DAT read: Lowpower 2025-04-28T18:25:17.263Z,1745864717.263 [DAT](INFO): unknown deviceResponse_: Lowpower 2025-04-28T18:25:18.527Z,1745864718.527 [DAT](INFO): DAT read: Acoustic Wakeup 2025-04-28T18:25:18.528Z,1745864718.528 [DAT](INFO): unknown deviceResponse_: Acoustic Wakeup 2025-04-28T18:25:18.529Z,1745864718.529 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2025-04-28T18:25:18.529Z,1745864718.529 [DAT](INFO): commRate: 800 2025-04-28T18:25:19.278Z,1745864719.278 [DAT](INFO): DAT read: Lowpower 2025-04-28T18:25:19.279Z,1745864719.279 [DAT](INFO): unknown deviceResponse_: Lowpower 2025-04-28T18:25:24.579Z,1745864724.579 [DAT](INFO): DAT read: Acoustic Wakeup 2025-04-28T18:25:24.579Z,1745864724.579 [DAT](INFO): unknown deviceResponse_: Acoustic Wakeup 2025-04-28T18:25:24.580Z,1745864724.580 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2025-04-28T18:25:24.580Z,1745864724.580 [DAT](INFO): commRate: 800 2025-04-28T18:25:25.334Z,1745864725.334 [DAT](INFO): DAT read: Lowpower 2025-04-28T18:25:25.335Z,1745864725.335 [DAT](INFO): unknown deviceResponse_: Lowpower 2025-04-28T18:25:30.627Z,1745864730.627 [DAT](INFO): DAT read: Acoustic Wakeup 2025-04-28T18:25:30.627Z,1745864730.627 [DAT](INFO): unknown deviceResponse_: Acoustic Wakeup 2025-04-28T18:25:30.628Z,1745864730.628 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2025-04-28T18:25:30.628Z,1745864730.628 [DAT](INFO): commRate: 800 2025-04-28T18:25:31.382Z,1745864731.382 [DAT](INFO): DAT read: Lowpower 2025-04-28T18:25:31.383Z,1745864731.383 [DAT](INFO): unknown deviceResponse_: Lowpower 2025-04-28T18:25:46.507Z,1745864746.507 [DAT](INFO): DAT read: Acoustic Wakeup 2025-04-28T18:25:46.507Z,1745864746.507 [DAT](INFO): unknown deviceResponse_: Acoustic Wakeup 2025-04-28T18:25:46.508Z,1745864746.508 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2025-04-28T18:25:46.509Z,1745864746.509 [DAT](INFO): commRate: 800 2025-04-28T18:25:47.518Z,1745864747.518 [DAT](INFO): DAT read: Lowpower 2025-04-28T18:25:47.519Z,1745864747.519 [DAT](INFO): unknown deviceResponse_: Lowpower 2025-04-28T18:26:06.431Z,1745864766.431 [DAT](INFO): DAT read: Acoustic Wakeup 2025-04-28T18:26:06.431Z,1745864766.431 [DAT](INFO): unknown deviceResponse_: Acoustic Wakeup 2025-04-28T18:26:06.432Z,1745864766.432 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2025-04-28T18:26:06.432Z,1745864766.432 [DAT](INFO): commRate: 800 2025-04-28T18:26:07.439Z,1745864767.439 [DAT](INFO): DAT read: Lowpower 2025-04-28T18:26:07.439Z,1745864767.439 [DAT](INFO): unknown deviceResponse_: Lowpower 2025-04-28T18:26:19.391Z,1745864779.391 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2025-04-28T18:26:32.497Z,1745864792.497 [CommandExec](IMPORTANT): got command failComponent hardware BuoyancyServo 2025-04-28T18:26:32.498Z,1745864792.498 [BuoyancyServo] Hardware Fault, FailCount= 1 2025-04-28T18:26:32.498Z,1745864792.498 [BuoyancyServo](ERROR): Hardware Fault 2025-04-28T18:26:32.498Z,1745864792.498 [CommandExec](IMPORTANT): BuoyancyServo failureMode is Hardware Fault 2025-04-28T18:26:32.733Z,1745864792.733 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2025-04-28T18:26:34.415Z,1745864794.415 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-04-28T18:26:34.415Z,1745864794.415 [BuoyancyServo](INFO): Powering down 2025-04-28T18:26:35.167Z,1745864795.167 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2025-04-28T18:26:35.167Z,1745864795.167 [BuoyancyServo] No Fault, FailCount= 1 2025-04-28T18:26:35.562Z,1745864795.562 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-04-28T18:26:35.571Z,1745864795.571 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-04-28T18:26:36.412Z,1745864796.412 [BuoyancyServo](INFO): INIT BUOYANCY 2025-04-28T18:26:38.410Z,1745864798.410 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2025-04-28T18:26:38.410Z,1745864798.410 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2025-04-28T18:26:38.411Z,1745864798.411 [DeadReckonWithRespectToSeafloor] Software Fault, FailCount= 2 2025-04-28T18:26:38.411Z,1745864798.411 [DeadReckonWithRespectToSeafloor](ERROR): Software Fault 2025-04-28T18:26:38.439Z,1745864798.439 [DAT](INFO): DAT read: Acoustic Wakeup 2025-04-28T18:26:38.439Z,1745864798.439 [DAT](INFO): unknown deviceResponse_: Acoustic Wakeup 2025-04-28T18:26:38.440Z,1745864798.440 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2025-04-28T18:26:38.440Z,1745864798.440 [DAT](INFO): commRate: 800 2025-04-28T18:26:38.451Z,1745864798.451 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2025-04-28T18:26:38.451Z,1745864798.451 [CBIT](ERROR): Software Fault in component: DeadReckonWithRespectToSeafloor 2025-04-28T18:26:38.952Z,1745864798.952 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2025-04-28T18:26:38.952Z,1745864798.952 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2025-04-28T18:26:38.953Z,1745864798.953 [CBIT](INFO): Clearing failed state for component DeadReckonWithRespectToSeafloor 2025-04-28T18:26:38.953Z,1745864798.953 [DeadReckonWithRespectToSeafloor] No Fault, FailCount= 2 2025-04-28T18:26:38.955Z,1745864798.955 [Reporter](INFO): platform_buoyancy_position 2009.475830 cc 2025-04-28T18:26:39.447Z,1745864799.447 [DAT](INFO): DAT read: Lowpower 2025-04-28T18:26:39.447Z,1745864799.447 [DAT](INFO): unknown deviceResponse_: Lowpower 2025-04-28T18:27:28.619Z,1745864848.619 [DAT](INFO): DAT read: Acoustic Wakeup 2025-04-28T18:27:28.619Z,1745864848.619 [DAT](INFO): unknown deviceResponse_: Acoustic Wakeup 2025-04-28T18:27:28.620Z,1745864848.620 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2025-04-28T18:27:28.620Z,1745864848.620 [DAT](INFO): commRate: 800 2025-04-28T18:27:29.383Z,1745864849.383 [DAT](INFO): DAT read: Lowpower 2025-04-28T18:27:29.383Z,1745864849.383 [DAT](INFO): unknown deviceResponse_: Lowpower 2025-04-28T18:27:38.467Z,1745864858.467 [DAT](INFO): DAT read: Acoustic Wakeup 2025-04-28T18:27:38.468Z,1745864858.468 [DAT](INFO): unknown deviceResponse_: Acoustic Wakeup 2025-04-28T18:27:38.469Z,1745864858.469 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2025-04-28T18:27:38.469Z,1745864858.469 [DAT](INFO): commRate: 800 2025-04-28T18:27:39.482Z,1745864859.482 [DAT](INFO): DAT read: Lowpower 2025-04-28T18:27:39.483Z,1745864859.483 [DAT](INFO): unknown deviceResponse_: Lowpower 2025-04-28T18:27:42.513Z,1745864862.513 [DAT](INFO): DAT read: Acoustic Wakeup 2025-04-28T18:27:42.513Z,1745864862.513 [DAT](INFO): unknown deviceResponse_: Acoustic Wakeup 2025-04-28T18:27:42.514Z,1745864862.514 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2025-04-28T18:27:42.515Z,1745864862.515 [DAT](INFO): commRate: 800 2025-04-28T18:27:43.266Z,1745864863.266 [DAT](INFO): DAT read: Lowpower 2025-04-28T18:27:43.267Z,1745864863.267 [DAT](INFO): unknown deviceResponse_: Lowpower 2025-04-28T18:28:01.851Z,1745864881.851 [CommandExec](IMPORTANT): got command failComponent hardware BuoyancyServo 2025-04-28T18:28:01.851Z,1745864881.851 [BuoyancyServo] Hardware Fault, FailCount= 1 2025-04-28T18:28:01.851Z,1745864881.851 [BuoyancyServo](ERROR): Hardware Fault 2025-04-28T18:28:01.851Z,1745864881.851 [CommandExec](IMPORTANT): BuoyancyServo failureMode is Hardware Fault 2025-04-28T18:28:02.079Z,1745864882.079 [CBIT](ERROR): Hardware Fault in component: BuoyancyServo 2025-04-28T18:28:02.440Z,1745864882.440 [DAT](INFO): DAT read: Acoustic Wakeup 2025-04-28T18:28:02.441Z,1745864882.441 [DAT](INFO): unknown deviceResponse_: Acoustic Wakeup 2025-04-28T18:28:02.442Z,1745864882.442 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2025-04-28T18:28:02.443Z,1745864882.443 [DAT](INFO): commRate: 800 2025-04-28T18:28:03.447Z,1745864883.447 [DAT](INFO): DAT read: Lowpower 2025-04-28T18:28:03.447Z,1745864883.447 [DAT](INFO): unknown deviceResponse_: Lowpower 2025-04-28T18:28:03.651Z,1745864883.651 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-04-28T18:28:03.651Z,1745864883.651 [BuoyancyServo](INFO): Powering down 2025-04-28T18:28:04.487Z,1745864884.487 [CBIT](INFO): Clearing failed state for component BuoyancyServo 2025-04-28T18:28:04.487Z,1745864884.487 [BuoyancyServo] No Fault, FailCount= 1 2025-04-28T18:28:04.862Z,1745864884.862 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-04-28T18:28:04.863Z,1745864884.863 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-04-28T18:28:05.668Z,1745864885.668 [BuoyancyServo](INFO): INIT BUOYANCY 2025-04-28T18:28:06.480Z,1745864886.480 [DAT](INFO): DAT read: Acoustic Wakeup 2025-04-28T18:28:06.480Z,1745864886.480 [DAT](INFO): unknown deviceResponse_: Acoustic Wakeup 2025-04-28T18:28:06.481Z,1745864886.481 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2025-04-28T18:28:06.482Z,1745864886.482 [DAT](INFO): commRate: 800 2025-04-28T18:28:07.486Z,1745864887.486 [DAT](INFO): DAT read: Lowpower 2025-04-28T18:28:07.487Z,1745864887.487 [DAT](INFO): unknown deviceResponse_: Lowpower 2025-04-28T18:28:18.291Z,1745864898.291 [CommandExec](IMPORTANT): got command show variable velocity 2025-04-28T18:28:18.295Z,1745864898.295 [CommandExec](IMPORTANT): direction_of_sea_water_velocity (unknown) 2025-04-28T18:28:18.295Z,1745864898.295 [CommandExec](IMPORTANT): downward_sea_water_velocity (unknown) 2025-04-28T18:28:18.295Z,1745864898.295 [CommandExec](IMPORTANT): eastward_sea_water_velocity (unknown) 2025-04-28T18:28:18.296Z,1745864898.296 [CommandExec](IMPORTANT): northward_sea_water_velocity (unknown) 2025-04-28T18:28:18.298Z,1745864898.298 [CommandExec](IMPORTANT): platform_velocity_wrt_ground (unknown) 2025-04-28T18:28:18.298Z,1745864898.298 [CommandExec](IMPORTANT): platform_velocity_wrt_sea_water (unknown) 2025-04-28T18:28:18.299Z,1745864898.299 [CommandExec](IMPORTANT): platform_x_sea_water_velocity (unknown) 2025-04-28T18:28:18.299Z,1745864898.299 [CommandExec](IMPORTANT): platform_x_velocity_wrt_ground (unknown) 2025-04-28T18:28:18.299Z,1745864898.299 [CommandExec](IMPORTANT): platform_x_velocity_wrt_sea_water (meter_per_second) 2025-04-28T18:28:18.300Z,1745864898.300 [CommandExec](IMPORTANT): platform_x_velocity_current (unknown) 2025-04-28T18:28:18.300Z,1745864898.300 [CommandExec](IMPORTANT): platform_y_sea_water_velocity (unknown) 2025-04-28T18:28:18.300Z,1745864898.300 [CommandExec](IMPORTANT): platform_y_velocity_wrt_ground (unknown) 2025-04-28T18:28:18.301Z,1745864898.301 [CommandExec](IMPORTANT): platform_y_velocity_wrt_sea_water (unknown) 2025-04-28T18:28:18.301Z,1745864898.301 [CommandExec](IMPORTANT): platform_y_velocity_current (unknown) 2025-04-28T18:28:18.301Z,1745864898.301 [CommandExec](IMPORTANT): platform_z_sea_water_velocity (unknown) 2025-04-28T18:28:18.302Z,1745864898.302 [CommandExec](IMPORTANT): platform_z_velocity_wrt_ground (unknown) 2025-04-28T18:28:18.302Z,1745864898.302 [CommandExec](IMPORTANT): platform_z_velocity_wrt_sea_water (unknown) 2025-04-28T18:28:18.303Z,1745864898.303 [CommandExec](IMPORTANT): platform_z_velocity_current (unknown) 2025-04-28T18:28:18.303Z,1745864898.303 [CommandExec](IMPORTANT): surface_eastward_sea_water_velocity (unknown) 2025-04-28T18:28:18.304Z,1745864898.304 [CommandExec](IMPORTANT): surface_northward_sea_water_velocity (unknown) 2025-04-28T18:28:18.310Z,1745864898.310 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.loadAtStartup (bool) 2025-04-28T18:28:18.310Z,1745864898.310 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.accuracyPremultiplier (none) 2025-04-28T18:28:18.310Z,1745864898.310 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.allowableFailures (count) 2025-04-28T18:28:18.311Z,1745864898.311 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.orientationStaleAfter (minute) 2025-04-28T18:28:18.311Z,1745864898.311 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.velocityStaleAfter (second) 2025-04-28T18:28:18.311Z,1745864898.311 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.verbosity (count) 2025-04-28T18:28:18.312Z,1745864898.312 [CommandExec](IMPORTANT): DeadReckonUsingSpeedCalculator.velocityStaleAfter (second) 2025-04-28T18:28:18.312Z,1745864898.312 [CommandExec](IMPORTANT): DeadReckonWithRespectToWater.velocityStaleAfter (second) 2025-04-28T18:28:18.313Z,1745864898.313 [CommandExec](IMPORTANT): DeadReckonWithRespectToSeafloor.velocityStaleAfter (second) 2025-04-28T18:28:18.315Z,1745864898.315 [CommandExec](IMPORTANT): DeadReckonUsingDVLWaterTrack.velocityStaleAfter (second) 2025-04-28T18:28:18.315Z,1745864898.315 [CommandExec](IMPORTANT): DeadReckonUsingCompactModelForecast.velocityStaleAfter (second) 2025-04-28T18:28:18.390Z,1745864898.390 [CommandExec](IMPORTANT): Rowe_600.bottomTrackVelocityAccuracy (meter_per_second) 2025-04-28T18:28:18.391Z,1745864898.391 [CommandExec](IMPORTANT): Rowe_600.waterTrackVelocityAccuracy (meter_per_second) 2025-04-28T18:28:18.391Z,1745864898.391 [CommandExec](IMPORTANT): Rowe_600.writeBeamVelocityProfile (bool) 2025-04-28T18:28:18.392Z,1745864898.392 [CommandExec](IMPORTANT): Rowe_600.writeEarthVelocityProfile (bool) 2025-04-28T18:28:18.392Z,1745864898.392 [CommandExec](IMPORTANT): Rowe_600.writeInstrumentVelocityProfile (bool) 2025-04-28T18:28:18.432Z,1745864898.432 [CommandExec](IMPORTANT): BuoyancyServo.velocity (none) 2025-04-28T18:28:18.443Z,1745864898.443 [CommandExec](IMPORTANT): DockingStepper.velocity (none) 2025-04-28T18:28:18.450Z,1745864898.450 [CommandExec](IMPORTANT): MassServo.velocity (none) 2025-04-28T18:28:18.452Z,1745864898.452 [CommandExec](IMPORTANT): ThrusterHE.allowableBadVelocity (count) 2025-04-28T18:28:18.453Z,1745864898.453 [CommandExec](IMPORTANT): ThrusterServo.allowableBadVelocity (count) 2025-04-28T18:28:18.474Z,1745864898.474 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.enableBroadcast (bool) 2025-04-28T18:28:18.475Z,1745864898.475 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.latitude (degree) 2025-04-28T18:28:18.475Z,1745864898.475 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.longitude (degree) 2025-04-28T18:28:18.476Z,1745864898.476 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.depth (meter) 2025-04-28T18:28:18.476Z,1745864898.476 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.platform_speed_wrt_ground (meter_per_second) 2025-04-28T18:28:18.476Z,1745864898.476 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.platform_course (unspecified) 2025-04-28T18:28:18.477Z,1745864898.477 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.horizontal_path_length_since_last_fix (meter) 2025-04-28T18:28:18.477Z,1745864898.477 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.fix_distance_made_good (meter) 2025-04-28T18:28:18.482Z,1745864898.482 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.fix_horizontal_path_length_since_last_fix (meter) 2025-04-28T18:28:18.482Z,1745864898.482 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.fix_residual_distance (meter) 2025-04-28T18:28:18.483Z,1745864898.483 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.fix_residual_bearing (radian) 2025-04-28T18:28:18.483Z,1745864898.483 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.fix_residual_percent_distance_traveled (percent) 2025-04-28T18:28:18.484Z,1745864898.484 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.elapsed_since_orientation_read (second) 2025-04-28T18:28:18.485Z,1745864898.485 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.elapsed_since_velocity_read (second) 2025-04-28T18:28:18.485Z,1745864898.485 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.latitude_accuracy (radian) 2025-04-28T18:28:18.490Z,1745864898.490 [CommandExec](IMPORTANT): DeadReckonUsingMultipleVelocitySources.velocity_source (count) 2025-04-28T18:28:18.491Z,1745864898.491 [CommandExec](IMPORTANT): DeadReckonWithRespectToSeafloor.elapsed_since_velocity_read (second) 2025-04-28T18:28:18.492Z,1745864898.492 [CommandExec](IMPORTANT): AHRS_M2.angular_velocity_along_xyz (radian_per_second) 2025-04-28T18:28:18.520Z,1745864898.520 [CommandExec](IMPORTANT): RDI_Pathfinder.platform_velocity_wrt_sea_water (meter_per_second) 2025-04-28T18:28:18.521Z,1745864898.521 [CommandExec](IMPORTANT): RDI_Pathfinder.platform_x_velocity_wrt_sea_water (meter_per_second) 2025-04-28T18:28:18.521Z,1745864898.521 [CommandExec](IMPORTANT): RDI_Pathfinder.platform_y_velocity_wrt_sea_water (meter_per_second) 2025-04-28T18:28:18.526Z,1745864898.526 [CommandExec](IMPORTANT): RDI_Pathfinder.platform_z_velocity_wrt_sea_water (meter_per_second) 2025-04-28T18:28:18.526Z,1745864898.526 [CommandExec](IMPORTANT): RDI_Pathfinder.water_mass_velocity_flag (count) 2025-04-28T18:28:18.527Z,1745864898.527 [CommandExec](IMPORTANT): RDI_Pathfinder.platform_velocity_wrt_ground (meter_per_second) 2025-04-28T18:28:18.527Z,1745864898.527 [CommandExec](IMPORTANT): RDI_Pathfinder.platform_x_velocity_wrt_ground (meter_per_second) 2025-04-28T18:28:18.527Z,1745864898.527 [CommandExec](IMPORTANT): RDI_Pathfinder.platform_y_velocity_wrt_ground (meter_per_second) 2025-04-28T18:28:18.528Z,1745864898.528 [CommandExec](IMPORTANT): RDI_Pathfinder.platform_z_velocity_wrt_ground (meter_per_second) 2025-04-28T18:28:18.528Z,1745864898.528 [CommandExec](IMPORTANT): RDI_Pathfinder.BottomVelocityFlag (count) 2025-04-28T18:28:18.529Z,1745864898.529 [CommandExec](IMPORTANT): SpeedCalculator.platform_x_velocity_wrt_sea_water (meter_per_second) 2025-04-28T18:28:22.611Z,1745864902.611 [DAT](INFO): DAT read: Acoustic Wakeup 2025-04-28T18:28:22.611Z,1745864902.611 [DAT](INFO): unknown deviceResponse_: Acoustic Wakeup 2025-04-28T18:28:22.612Z,1745864902.612 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2025-04-28T18:28:22.613Z,1745864902.613 [DAT](INFO): commRate: 800 2025-04-28T18:28:23.367Z,1745864903.367 [DAT](INFO): DAT read: Lowpower 2025-04-28T18:28:23.367Z,1745864903.367 [DAT](INFO): unknown deviceResponse_: Lowpower 2025-04-28T18:28:27.204Z,1745864907.204 [CommandExec](IMPORTANT): got command get BuoyancyServo.velocity 2025-04-28T18:28:27.204Z,1745864907.204 [CommandExec](IMPORTANT): BuoyancyServo.velocity 400.000000 n/a 2025-04-28T18:28:54.603Z,1745864934.603 [CommandExec](IMPORTANT): got command restart application 2025-04-28T18:28:55.606Z,1745864935.606 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2025-04-28T18:28:55.606Z,1745864935.606 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-28T18:28:55.606Z,1745864935.606 [CommandExec](INFO): Uninitializing the command executive. 2025-04-28T18:28:55.606Z,1745864935.606 [CommandExec](INFO): Uninitializing the command scheduler. 2025-04-28T18:28:55.607Z,1745864935.607 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-04-28T18:28:55.722Z,1745864935.722 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-04-28T18:28:55.722Z,1745864935.722 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-04-28T18:28:55.722Z,1745864935.722 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-04-28T18:28:55.723Z,1745864935.723 [NavChartDb](INFO): Join timeout helper Thread ID is 5493 2025-04-28T18:28:56.010Z,1745864936.010 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-28T18:28:56.011Z,1745864936.011 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-04-28T18:28:56.026Z,1745864936.026 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-04-28T18:28:56.026Z,1745864936.026 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-04-28T18:28:56.026Z,1745864936.026 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5494 2025-04-28T18:28:56.263Z,1745864936.263 [CTD_Seabird](INFO): Powering down 2025-04-28T18:28:56.282Z,1745864936.282 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-28T18:28:56.283Z,1745864936.283 [CTD_Seabird](INFO): Powering down 2025-04-28T18:28:56.298Z,1745864936.298 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-04-28T18:28:56.302Z,1745864936.302 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-04-28T18:28:56.302Z,1745864936.302 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-04-28T18:28:56.302Z,1745864936.302 [Radio_Surface](INFO): Join timeout helper Thread ID is 5495 2025-04-28T18:28:56.694Z,1745864936.694 [Radio_Surface](INFO): Powering down 2025-04-28T18:28:56.695Z,1745864936.695 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-28T18:28:56.696Z,1745864936.696 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-04-28T18:28:56.706Z,1745864936.706 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-04-28T18:28:56.706Z,1745864936.706 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-04-28T18:28:56.706Z,1745864936.706 [Onboard](INFO): Join timeout helper Thread ID is 5496 2025-04-28T18:28:57.570Z,1745864937.570 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-28T18:28:57.570Z,1745864937.570 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-04-28T18:28:57.574Z,1745864937.574 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2025-04-28T18:28:57.574Z,1745864937.574 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-04-28T18:28:57.574Z,1745864937.574 [DataOverHttps](INFO): Join timeout helper Thread ID is 5497 2025-04-28T18:28:58.162Z,1745864938.162 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-28T18:28:58.165Z,1745864938.165 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-04-28T18:28:58.170Z,1745864938.170 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2025-04-28T18:28:58.170Z,1745864938.170 [DAT ThreadHandler](INFO): Thread cancelled. 2025-04-28T18:28:58.170Z,1745864938.170 [DAT](INFO): Powering down 2025-04-28T18:28:58.172Z,1745864938.172 [DAT](INFO): Join timeout helper Thread ID is 5498 2025-04-28T18:28:58.244Z,1745864938.244 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-28T18:28:58.245Z,1745864938.245 [DAT](INFO): Powering down 2025-04-28T18:28:58.247Z,1745864938.247 [DAT ThreadHandler](INFO): Thread cancelled. 2025-04-28T18:28:58.256Z,1745864938.256 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2025-04-28T18:28:58.256Z,1745864938.256 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-04-28T18:28:58.258Z,1745864938.258 [BackseatComponent](INFO): Join timeout helper Thread ID is 5499 2025-04-28T18:28:58.438Z,1745864938.438 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-28T18:28:58.438Z,1745864938.438 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-04-28T18:28:58.442Z,1745864938.442 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2025-04-28T18:28:58.443Z,1745864938.443 [logger ThreadHandler](INFO): Thread cancelled. 2025-04-28T18:28:58.443Z,1745864938.443 [logger](INFO): Join timeout helper Thread ID is 5500 2025-04-28T18:28:58.466Z,1745864938.466 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-28T18:28:58.466Z,1745864938.466 [logger ThreadHandler](INFO): Thread cancelled. 2025-04-28T18:28:58.482Z,1745864938.482 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2025-04-28T18:28:58.483Z,1745864938.483 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-04-28T18:28:58.483Z,1745864938.483 [CommandLine](INFO): Join timeout helper Thread ID is 5501 2025-04-28T18:28:58.514Z,1745864938.514 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-28T18:28:58.515Z,1745864938.515 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-04-28T18:28:58.522Z,1745864938.522 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2025-04-28T18:28:58.523Z,1745864938.523 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-04-28T18:28:58.523Z,1745864938.523 [CommandExec](INFO): Join timeout helper Thread ID is 5502 2025-04-28T18:28:58.524Z,1745864938.524 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2025-04-28T18:28:58.524Z,1745864938.524 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-04-28T18:28:58.525Z,1745864938.525 [controlThread](INFO): Join timeout helper Thread ID is 5503 2025-04-28T18:28:58.554Z,1745864938.554 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2025-04-28T18:28:58.554Z,1745864938.554 [controlThread](DEBUG): Uninitializing ControlThread 2025-04-28T18:28:58.555Z,1745864938.555 [AHRS_M2](INFO): Powering down 2025-04-28T18:28:58.626Z,1745864938.626 [DepthKeller33X](INFO): Powering down 2025-04-28T18:28:58.722Z,1745864938.722 [NAL9602](INFO): Powering down 2025-04-28T18:28:58.794Z,1745864938.794 [RDI_Pathfinder](INFO): Powering down 2025-04-28T18:28:58.795Z,1745864938.795 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2025-04-28T18:28:58.796Z,1745864938.796 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2025-04-28T18:28:58.796Z,1745864938.796 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2025-04-28T18:28:58.797Z,1745864938.797 [MissionManager](INFO): Uninitializing Mission Default 2025-04-28T18:28:58.797Z,1745864938.797 [Default] Stopped 2025-04-28T18:28:58.797Z,1745864938.797 [Default](DEBUG): Aggregate::uninitialize Default 2025-04-28T18:28:58.797Z,1745864938.797 [Default:B.GoToSurface] Stopped 2025-04-28T18:28:58.797Z,1745864938.797 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2025-04-28T18:28:58.797Z,1745864938.797 [Default:CheckIn] Stopped 2025-04-28T18:28:58.797Z,1745864938.797 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2025-04-28T18:28:58.797Z,1745864938.797 [Default:CheckIn:Read_GPS] Stopped 2025-04-28T18:28:58.800Z,1745864938.800 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2025-04-28T18:28:58.801Z,1745864938.801 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2025-04-28T18:28:58.801Z,1745864938.801 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2025-04-28T18:28:58.801Z,1745864938.801 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2025-04-28T18:28:58.802Z,1745864938.802 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-04-28T18:28:58.802Z,1745864938.802 [BuoyancyServo](INFO): Powering down 2025-04-28T18:28:58.814Z,1745864938.814 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-04-28T18:28:58.814Z,1745864938.814 [ElevatorServo](INFO): Powering down 2025-04-28T18:28:58.815Z,1745864938.815 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-04-28T18:28:58.815Z,1745864938.815 [MassServo](INFO): Powering down 2025-04-28T18:28:58.816Z,1745864938.816 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2025-04-28T18:28:58.816Z,1745864938.816 [RudderServo](INFO): Powering down 2025-04-28T18:28:58.816Z,1745864938.816 [ThrusterHE](DEBUG): Uninitialize Thruster Servo. 2025-04-28T18:28:58.817Z,1745864938.817 [ThrusterHE](INFO): Powering down 2025-04-28T18:28:58.817Z,1745864938.817 [SBIT](DEBUG): Uninitialize SBIT Component. 2025-04-28T18:28:58.818Z,1745864938.818 [IBIT](DEBUG): Uninitialize IBIT Component. 2025-04-28T18:28:58.818Z,1745864938.818 [CBIT](DEBUG): Uninitialize CBIT Component. 2025-04-28T18:28:58.818Z,1745864938.818 [CBIT](DEBUG): Powering off loads. 2025-04-28T18:28:58.830Z,1745864938.830 [CBIT](DEBUG): Disabling WDT. 2025-04-28T18:28:58.842Z,1745864938.842 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-04-28T18:28:58.842Z,1745864938.842 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-04-28T18:28:58.843Z,1745864938.843 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-04-28T18:28:58.877Z,1745864938.877 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-04-28T18:28:58.880Z,1745864938.880 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-04-28T18:28:58.885Z,1745864938.885 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-04-28T18:28:58.892Z,1745864938.892 [DAT ThreadHandler](INFO): Thread cancelled. 2025-04-28T18:28:58.896Z,1745864938.896 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-04-28T18:28:58.943Z,1745864938.943 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-04-28T18:28:58.983Z,1745864938.983 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-04-28T18:28:58.983Z,1745864938.983 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-04-28T18:28:59.001Z,1745864939.001 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-04-28T18:28:59.067Z,1745864939.067 [logger ThreadHandler](INFO): Thread cancelled.