2025-11-13T00:26:41.476Z,1762993601.476 [Supervisor](DEBUG): Initializing supervisor. 2025-11-13T00:26:41.481Z,1762993601.481 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2025-11-13T00:26:41.482Z,1762993601.482 [SyncHandler](INFO): Protected caller Thread ID is 9078 2025-11-13T00:26:41.482Z,1762993601.482 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2025-11-13T00:26:41.484Z,1762993601.484 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2025-11-13T00:26:41.484Z,1762993601.484 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9079 2025-11-13T00:26:41.488Z,1762993601.488 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2025-11-13T00:26:41.507Z,1762993601.507 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread. 2025-11-13T00:26:41.508Z,1762993601.508 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2025-11-13T00:26:41.508Z,1762993601.508 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 9080 2025-11-13T00:26:41.512Z,1762993601.512 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2025-11-13T00:26:41.513Z,1762993601.513 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2025-11-13T00:26:41.514Z,1762993601.514 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9081 2025-11-13T00:26:41.516Z,1762993601.516 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2025-11-13T00:26:41.517Z,1762993601.517 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0 2025-11-13T00:26:41.517Z,1762993601.517 [logger ThreadHandler](INFO): Protected caller Thread ID is 9082 2025-11-13T00:26:41.520Z,1762993601.520 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2025-11-13T00:26:41.521Z,1762993601.521 [Supervisor](INFO): Looking for Config files in directory: Config/ 2025-11-13T00:26:41.522Z,1762993601.522 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2025-11-13T00:26:41.628Z,1762993601.628 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2025-11-13T00:26:41.629Z,1762993601.629 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2025-11-13T00:26:42.337Z,1762993602.337 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2025-11-13T00:26:42.338Z,1762993602.338 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2025-11-13T00:26:42.581Z,1762993602.581 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2025-11-13T00:26:42.582Z,1762993602.582 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2025-11-13T00:26:42.672Z,1762993602.672 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2025-11-13T00:26:42.813Z,1762993602.813 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2025-11-13T00:26:42.814Z,1762993602.814 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2025-11-13T00:26:42.915Z,1762993602.915 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance 2025-11-13T00:26:42.915Z,1762993602.915 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2025-11-13T00:26:43.033Z,1762993603.033 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2025-11-13T00:26:43.033Z,1762993603.033 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2025-11-13T00:26:43.421Z,1762993603.421 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2025-11-13T00:26:43.421Z,1762993603.421 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2025-11-13T00:26:43.948Z,1762993603.948 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2025-11-13T00:26:43.948Z,1762993603.948 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2025-11-13T00:26:44.170Z,1762993604.170 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2025-11-13T00:26:44.170Z,1762993604.170 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2025-11-13T00:26:44.361Z,1762993604.361 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2025-11-13T00:26:44.361Z,1762993604.361 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2025-11-13T00:26:44.901Z,1762993604.901 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2025-11-13T00:26:44.902Z,1762993604.902 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2025-11-13T00:26:45.510Z,1762993605.510 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2025-11-13T00:26:45.510Z,1762993605.510 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2025-11-13T00:26:45.751Z,1762993605.751 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2025-11-13T00:26:45.752Z,1762993605.752 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2025-11-13T00:26:46.132Z,1762993606.132 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2025-11-13T00:26:46.133Z,1762993606.133 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2025-11-13T00:26:46.486Z,1762993606.486 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2025-11-13T00:26:46.488Z,1762993606.488 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/ 2025-11-13T00:26:46.488Z,1762993606.488 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg 2025-11-13T00:26:46.585Z,1762993606.585 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg 2025-11-13T00:26:46.762Z,1762993606.762 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Dock.cfg 2025-11-13T00:26:46.879Z,1762993606.879 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/workSite.cfg 2025-11-13T00:26:46.986Z,1762993606.986 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg 2025-11-13T00:26:47.103Z,1762993607.103 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg 2025-11-13T00:26:47.200Z,1762993607.200 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg 2025-11-13T00:26:47.306Z,1762993607.306 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg 2025-11-13T00:26:47.427Z,1762993607.427 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg 2025-11-13T00:26:47.695Z,1762993607.695 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2025-11-13T00:26:47.695Z,1762993607.695 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg 2025-11-13T00:26:47.791Z,1762993607.791 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg 2025-11-13T00:26:47.894Z,1762993607.894 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg 2025-11-13T00:26:48.042Z,1762993608.042 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg 2025-11-13T00:26:48.150Z,1762993608.150 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/ 2025-11-13T00:26:48.150Z,1762993608.150 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg 2025-11-13T00:26:48.158Z,1762993608.158 [controlThread](FAULT): Changing base units of DAT.transponderAddress from enum to count 2025-11-13T00:26:48.166Z,1762993608.166 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2025-11-13T00:26:48.294Z,1762993608.294 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2025-11-13T00:26:48.294Z,1762993608.294 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2025-11-13T00:26:48.354Z,1762993608.354 [VerticalControl](DEBUG): Construct VerticalControl. 2025-11-13T00:26:48.412Z,1762993608.412 [VerticalControl] Loaded 2025-11-13T00:26:48.413Z,1762993608.413 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2025-11-13T00:26:48.415Z,1762993608.415 [HorizontalControl](DEBUG): Construct HorizontalControl. 2025-11-13T00:26:48.460Z,1762993608.460 [HorizontalControl] Loaded 2025-11-13T00:26:48.460Z,1762993608.460 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2025-11-13T00:26:48.462Z,1762993608.462 [SpeedControl](DEBUG): Construct SpeedControl. 2025-11-13T00:26:48.465Z,1762993608.465 [SpeedControl] Loaded 2025-11-13T00:26:48.466Z,1762993608.466 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2025-11-13T00:26:48.468Z,1762993608.468 [LoopControl](DEBUG): Construct LoopControl. 2025-11-13T00:26:48.469Z,1762993608.469 [LoopControl] Loaded 2025-11-13T00:26:48.469Z,1762993608.469 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2025-11-13T00:26:48.469Z,1762993608.469 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2025-11-13T00:26:48.470Z,1762993608.470 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2025-11-13T00:26:48.517Z,1762993608.517 [DepthRateCalculator] Loaded 2025-11-13T00:26:48.518Z,1762993608.518 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2025-11-13T00:26:48.522Z,1762993608.522 [PitchRateCalculator] Loaded 2025-11-13T00:26:48.523Z,1762993608.523 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2025-11-13T00:26:48.532Z,1762993608.532 [SpeedCalculator] Loaded 2025-11-13T00:26:48.532Z,1762993608.532 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2025-11-13T00:26:48.536Z,1762993608.536 [YawRateCalculator] Loaded 2025-11-13T00:26:48.537Z,1762993608.537 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2025-11-13T00:26:48.554Z,1762993608.554 [ElevatorOffsetCalculator] Loaded 2025-11-13T00:26:48.554Z,1762993608.554 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2025-11-13T00:26:48.555Z,1762993608.555 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2025-11-13T00:26:48.555Z,1762993608.555 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2025-11-13T00:26:48.584Z,1762993608.584 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2025-11-13T00:26:48.585Z,1762993608.585 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2025-11-13T00:26:48.641Z,1762993608.641 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2025-11-13T00:26:48.642Z,1762993608.642 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2025-11-13T00:26:48.966Z,1762993608.966 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2025-11-13T00:26:48.966Z,1762993608.966 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2025-11-13T00:26:49.134Z,1762993609.134 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2025-11-13T00:26:49.134Z,1762993609.134 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2025-11-13T00:26:49.612Z,1762993609.612 [AHRS_M2] Loaded 2025-11-13T00:26:49.612Z,1762993609.612 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2025-11-13T00:26:49.650Z,1762993609.650 [BackseatComponent] Loaded 2025-11-13T00:26:49.650Z,1762993609.650 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread. 2025-11-13T00:26:49.652Z,1762993609.652 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409EB4E0 2025-11-13T00:26:49.652Z,1762993609.652 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 9170 2025-11-13T00:26:49.655Z,1762993609.655 [LcmPublisher](INFO): Full slate access is ENABLED. All requested slate values (including non-universals) will be published on LCM. 2025-11-13T00:26:49.655Z,1762993609.655 [LcmPublisher] Loaded 2025-11-13T00:26:49.655Z,1762993609.655 [ComponentRegistry](DEBUG): SyncComponent "LcmPublisher" handled in the control thread. 2025-11-13T00:26:50.485Z,1762993610.485 [BPC1] Loaded 2025-11-13T00:26:50.486Z,1762993610.486 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2025-11-13T00:26:50.681Z,1762993610.681 [DAT] Loaded 2025-11-13T00:26:50.682Z,1762993610.682 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread. 2025-11-13T00:26:50.683Z,1762993610.683 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A1B4E0 2025-11-13T00:26:50.683Z,1762993610.683 [DAT ThreadHandler](INFO): Protected caller Thread ID is 9171 2025-11-13T00:26:50.852Z,1762993610.852 [DataOverHttps] Loaded 2025-11-13T00:26:50.853Z,1762993610.853 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2025-11-13T00:26:50.854Z,1762993610.854 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A4B4E0 2025-11-13T00:26:50.854Z,1762993610.854 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 9172 2025-11-13T00:26:50.880Z,1762993610.880 [Depth_Keller] Loaded 2025-11-13T00:26:50.880Z,1762993610.880 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2025-11-13T00:26:50.885Z,1762993610.885 [DropWeight] Loaded 2025-11-13T00:26:50.885Z,1762993610.885 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2025-11-13T00:26:50.986Z,1762993610.986 [NAL9602] Loaded 2025-11-13T00:26:50.986Z,1762993610.986 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2025-11-13T00:26:51.063Z,1762993611.063 [Onboard] Loaded 2025-11-13T00:26:51.064Z,1762993611.064 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2025-11-13T00:26:51.064Z,1762993611.064 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A7B4E0 2025-11-13T00:26:51.065Z,1762993611.065 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 9173 2025-11-13T00:26:51.078Z,1762993611.078 [Power24vConverter] Loaded 2025-11-13T00:26:51.079Z,1762993611.079 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread. 2025-11-13T00:26:51.094Z,1762993611.094 [Radio_Surface] Loaded 2025-11-13T00:26:51.094Z,1762993611.094 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2025-11-13T00:26:51.095Z,1762993611.095 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40AAB4E0 2025-11-13T00:26:51.095Z,1762993611.095 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9174 2025-11-13T00:26:51.141Z,1762993611.141 [RDI_Pathfinder] Loaded 2025-11-13T00:26:51.141Z,1762993611.141 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2025-11-13T00:26:51.156Z,1762993611.156 [Sonardyne_Nano] Loaded 2025-11-13T00:26:51.156Z,1762993611.156 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread. 2025-11-13T00:26:51.156Z,1762993611.156 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2025-11-13T00:26:51.157Z,1762993611.157 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2025-11-13T00:26:51.265Z,1762993611.265 [DeadReckonUsingMultipleVelocitySources] Loaded 2025-11-13T00:26:51.265Z,1762993611.265 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2025-11-13T00:26:51.278Z,1762993611.278 [NavChart] Loaded 2025-11-13T00:26:51.278Z,1762993611.278 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2025-11-13T00:26:51.284Z,1762993611.284 [UniversalFixResidualReporter] Loaded 2025-11-13T00:26:51.284Z,1762993611.284 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2025-11-13T00:26:51.284Z,1762993611.284 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2025-11-13T00:26:51.285Z,1762993611.285 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2025-11-13T00:26:51.435Z,1762993611.435 [SBIT](DEBUG): Construct Startup Built In Test. 2025-11-13T00:26:51.444Z,1762993611.444 [SBIT] Loaded 2025-11-13T00:26:51.444Z,1762993611.444 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2025-11-13T00:26:51.447Z,1762993611.447 [IBIT](DEBUG): Construct Initiated Built In Test. 2025-11-13T00:26:51.459Z,1762993611.459 [IBIT] Loaded 2025-11-13T00:26:51.459Z,1762993611.459 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2025-11-13T00:26:51.465Z,1762993611.465 [CBIT](DEBUG): Construct Continuous Built In Test. 2025-11-13T00:26:51.542Z,1762993611.542 [CBIT] Loaded 2025-11-13T00:26:51.542Z,1762993611.542 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2025-11-13T00:26:51.573Z,1762993611.573 [GFScanner] Loaded 2025-11-13T00:26:51.573Z,1762993611.573 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread. 2025-11-13T00:26:51.574Z,1762993611.574 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2025-11-13T00:26:51.574Z,1762993611.574 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2025-11-13T00:26:51.703Z,1762993611.703 [BuoyancyServo] Loaded 2025-11-13T00:26:51.704Z,1762993611.704 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2025-11-13T00:26:51.726Z,1762993611.726 [ElevatorServo] Loaded 2025-11-13T00:26:51.726Z,1762993611.726 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2025-11-13T00:26:51.754Z,1762993611.754 [DockingStepper] Loaded 2025-11-13T00:26:51.754Z,1762993611.754 [ComponentRegistry](DEBUG): SyncComponent "DockingStepper" handled in the control thread. 2025-11-13T00:26:51.775Z,1762993611.775 [MassServo] Loaded 2025-11-13T00:26:51.775Z,1762993611.775 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2025-11-13T00:26:51.795Z,1762993611.795 [RudderServo] Loaded 2025-11-13T00:26:51.795Z,1762993611.795 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2025-11-13T00:26:51.814Z,1762993611.814 [ThrusterServo] Loaded 2025-11-13T00:26:51.814Z,1762993611.814 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2025-11-13T00:26:51.814Z,1762993611.814 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2025-11-13T00:26:51.815Z,1762993611.815 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2025-11-13T00:26:51.825Z,1762993611.825 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2025-11-13T00:26:51.826Z,1762993611.826 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2025-11-13T00:26:51.990Z,1762993611.990 [CTD_Seabird] Loaded 2025-11-13T00:26:51.990Z,1762993611.990 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2025-11-13T00:26:51.992Z,1762993611.992 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40C0E4E0 2025-11-13T00:26:51.992Z,1762993611.992 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 9175 2025-11-13T00:26:52.014Z,1762993612.014 [PAR_Licor] Loaded 2025-11-13T00:26:52.014Z,1762993612.014 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2025-11-13T00:26:52.058Z,1762993612.058 [WetLabsBB2FL] Loaded 2025-11-13T00:26:52.058Z,1762993612.058 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2025-11-13T00:26:52.059Z,1762993612.059 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C3E4E0 2025-11-13T00:26:52.059Z,1762993612.059 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 9176 2025-11-13T00:26:52.080Z,1762993612.080 [WetLabsUBAT] Loaded 2025-11-13T00:26:52.080Z,1762993612.080 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread. 2025-11-13T00:26:52.081Z,1762993612.081 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40C6E4E0 2025-11-13T00:26:52.081Z,1762993612.081 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 9177 2025-11-13T00:26:52.082Z,1762993612.082 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2025-11-13T00:26:52.088Z,1762993612.088 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2025-11-13T00:26:52.091Z,1762993612.091 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2025-11-13T00:26:52.102Z,1762993612.102 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2025-11-13T00:26:52.102Z,1762993612.102 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C9E4E0 2025-11-13T00:26:52.103Z,1762993612.103 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9178 2025-11-13T00:26:52.107Z,1762993612.107 [Supervisor](INFO): Main Thread ID is 7518 2025-11-13T00:26:52.107Z,1762993612.107 [Supervisor](DEBUG): Running supervisor. 2025-11-13T00:26:52.108Z,1762993612.108 [CommandExec ThreadHandler](INFO): Handler Thread ID is 9179 2025-11-13T00:26:52.109Z,1762993612.109 [CommandExec](INFO): Initializing the command executive. 2025-11-13T00:26:52.110Z,1762993612.110 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9180 2025-11-13T00:26:52.112Z,1762993612.112 [controlThread ThreadHandler](INFO): Handler Thread ID is 9181 2025-11-13T00:26:52.113Z,1762993612.113 [controlThread](DEBUG): Initializing ControlThread 2025-11-13T00:26:52.114Z,1762993612.114 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2025-11-13T00:26:52.115Z,1762993612.115 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2025-11-13T00:26:52.116Z,1762993612.116 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2025-11-13T00:26:52.116Z,1762993612.116 [LoopControl](DEBUG): Initialize LoopControlComponent. 2025-11-13T00:26:52.117Z,1762993612.117 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2025-11-13T00:26:52.117Z,1762993612.117 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2025-11-13T00:26:52.118Z,1762993612.118 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2025-11-13T00:26:52.118Z,1762993612.118 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2025-11-13T00:26:52.118Z,1762993612.118 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2025-11-13T00:26:52.123Z,1762993612.123 [NavChart](DEBUG): Initialize NavChart Navigation. 2025-11-13T00:26:52.123Z,1762993612.123 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2025-11-13T00:26:52.123Z,1762993612.123 [SBIT](INFO): Initialize SBIT Component. 2025-11-13T00:26:52.124Z,1762993612.124 [SBIT](IMPORTANT): git: 2025-11-06-2-g37d2ccc16 2025-11-13T00:26:52.124Z,1762993612.124 [SBIT](INFO): git hash: 37d2ccc16c3820cae3b4f7d56ca89ff0df066476 2025-11-13T00:26:52.124Z,1762993612.124 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2025-11-13T00:26:52.125Z,1762993612.125 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019 Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021 2025-11-13T00:26:52.127Z,1762993612.127 [SBIT](INFO): Beginning SBIT in 75.000000 seconds. 2025-11-13T00:26:52.127Z,1762993612.127 [IBIT](INFO): Initialize IBIT Component. 2025-11-13T00:26:52.128Z,1762993612.128 [CBIT](DEBUG): Initialize CBIT Component. 2025-11-13T00:26:52.129Z,1762993612.129 [logger ThreadHandler](INFO): Handler Thread ID is 9182 2025-11-13T00:26:52.140Z,1762993612.140 [CBIT](DEBUG): Initialized mux pins. 2025-11-13T00:26:52.140Z,1762993612.140 [CBIT](DEBUG): Initializing the watchdog timer. 2025-11-13T00:26:52.148Z,1762993612.148 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 9183 2025-11-13T00:26:52.164Z,1762993612.164 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2025-11-13T00:26:52.164Z,1762993612.164 [CBIT](DEBUG): Initializing heartbeat. 2025-11-13T00:26:52.165Z,1762993612.165 [DAT ThreadHandler](INFO): Handler Thread ID is 9184 2025-11-13T00:26:52.166Z,1762993612.166 [DAT](INFO): Powering up 2025-11-13T00:26:52.166Z,1762993612.166 [DAT](DEBUG): Initializing DAT. 2025-11-13T00:26:52.169Z,1762993612.169 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 9186 2025-11-13T00:26:52.171Z,1762993612.171 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2025-11-13T00:26:52.192Z,1762993612.192 [Onboard ThreadHandler](INFO): Handler Thread ID is 9187 2025-11-13T00:26:52.209Z,1762993612.209 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9188 2025-11-13T00:26:52.232Z,1762993612.232 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 9189 2025-11-13T00:26:52.236Z,1762993612.236 [CBIT](DEBUG): Deactivating emergency mode. 2025-11-13T00:26:52.236Z,1762993612.236 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2025-11-13T00:26:52.240Z,1762993612.240 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 9191 2025-11-13T00:26:52.244Z,1762993612.244 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 9193 2025-11-13T00:26:52.246Z,1762993612.246 [WetLabsBB2FL](INFO): Powering up 2025-11-13T00:26:52.248Z,1762993612.248 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9195 2025-11-13T00:26:52.251Z,1762993612.251 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2025-11-13T00:26:52.251Z,1762993612.251 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2025-11-13T00:26:52.252Z,1762993612.252 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2025-11-13T00:26:52.252Z,1762993612.252 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2025-11-13T00:26:52.252Z,1762993612.252 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2025-11-13T00:26:52.252Z,1762993612.252 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2025-11-13T00:26:52.253Z,1762993612.253 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2025-11-13T00:26:52.253Z,1762993612.253 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2025-11-13T00:26:52.264Z,1762993612.264 [WetLabsUBAT](INFO): Powering up 2025-11-13T00:26:52.276Z,1762993612.276 [CBIT](DEBUG): Backplane powered. 2025-11-13T00:26:52.276Z,1762993612.276 [GFScanner](DEBUG): Initializing GFScanner 2025-11-13T00:26:52.276Z,1762993612.276 [GFScanner](DEBUG): Deactivating GF circuits. 2025-11-13T00:26:52.284Z,1762993612.284 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser 2025-11-13T00:26:52.285Z,1762993612.285 [MissionManager](INFO): Loading Mission from file: Missions/underIce/StartupUnder.tl 2025-11-13T00:26:52.285Z,1762993612.285 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/StartupUnder.tl 2025-11-13T00:26:52.286Z,1762993612.286 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/StartupUnder.tl 2025-11-13T00:26:52.304Z,1762993612.304 [MissionManager](DEBUG): TethyslAPI: Missions/underIce/StartupUnder.tl translated into: 2025-11-13T00:26:52.304Z,1762993612.304 [MissionManager](ERROR): Top node has no child nodes: Mission 2025-11-13T00:26:52.304Z,1762993612.304 [MissionManager](ERROR): Could not load startup mission. 2025-11-13T00:26:52.305Z,1762993612.305 [MissionManager](INFO): Loading Mission from file: Missions/underIce/DefaultWithUndock.tl 2025-11-13T00:26:52.305Z,1762993612.305 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/DefaultWithUndock.tl 2025-11-13T00:26:52.306Z,1762993612.306 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/DefaultWithUndock.tl 2025-11-13T00:26:52.616Z,1762993612.616 [Radio_Surface](INFO): Powering up 2025-11-13T00:26:52.924Z,1762993612.924 [MissionManager](DEBUG): TethyslAPI: Missions/underIce/DefaultWithUndock.tl translated into: 0 6 10 1 3 30 Timed out while trying to detach from dock at current depth of . Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2025-11-13T00:26:52.931Z,1762993612.931 [MissionManager](INFO): DefineArg DefaultWithUndock.ElapsedSinceDefaultStarted = 0.000000 min 2025-11-13T00:26:52.955Z,1762993612.955 [MissionManager](INFO): DefineArg DefaultWithUndock.DetachTimeout = 6.000000 h 2025-11-13T00:26:52.979Z,1762993612.979 [MissionManager](INFO): DefineArg DefaultWithUndock.TransponderCode = 50 count 2025-11-13T00:26:52.983Z,1762993612.983 [MissionManager](INFO): DefineArg DefaultWithUndock.TrackingUpdatePeriod = 10.000000 s 2025-11-13T00:26:52.987Z,1762993612.987 [MissionManager](INFO): DefineArg DefaultWithUndock.NumberOfPings = 1.000000 count 2025-11-13T00:26:53.003Z,1762993613.003 [MissionManager](INFO): DefineArg DefaultWithUndock.DriftTimeout = 3.000000 min 2025-11-13T00:26:53.004Z,1762993613.004 [DefaultWithUndock:A.Wait](DEBUG): Construct Wait. 2025-11-13T00:26:53.174Z,1762993613.174 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Construct. 2025-11-13T00:26:53.174Z,1762993613.174 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values. 2025-11-13T00:26:53.219Z,1762993613.219 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Construct GoToSurface. 2025-11-13T00:26:53.277Z,1762993613.277 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2025-11-13T00:26:53.296Z,1762993613.296 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Construct Wait. 2025-11-13T00:26:53.302Z,1762993613.302 [DefaultWithUndock:E.Execute](DEBUG): Construct Execute. 2025-11-13T00:26:53.319Z,1762993613.319 [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 DefaultWithUndock { arguments { ElapsedSinceDefaultStarted = 0 minute DetachTimeout = 6 hour """ Maximum time duration for the vehicle to detach form the dock. """ TransponderCode = Dock:Dock.transponderCode """ Dock transponder address. """ TrackingUpdatePeriod = 10 second """ Time duration between acoustic range queries while the mission is detaching from the dock. """ NumberOfPings = 1 count """ Number of pings requested each time. """ DriftTimeout = 3 minute """ How long to allow vehicle to drift away from dock before retrying reverse """ } 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 = 30 second } aggregate LeaveDock { run in sequence timeout duration=DetachTimeout { syslog important "Timed out while trying to detach from dock at current depth of " + Universal:depth~meter + "." } # Acoustic tracking directives behavior Estimation:TrackAcousticContact { run while ( Universal:depth > Control:VerticalControl.surfaceThreshold ) set contactLabelSetting = TransponderCode set numberOfSamplesSetting = NumberOfPings set updatePeriodSetting = TrackingUpdatePeriod } # Leave the dock behavior Dock:Undock { run in sequence set driftTimeout = DriftTimeout } } aggregate SurfaceDefault { run in sequence # This mission assumes open water so from here on it's the same old default that's always used 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=P2H { 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-11-13T00:26:53.323Z,1762993613.323 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,RDI_Pathfinder,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,DockingStepper,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,GFScanner,LcmPublisher,Reporter,LogSplitter, 2025-11-13T00:26:53.340Z,1762993613.340 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2025-11-13T00:26:53.400Z,1762993613.400 [Depth_Keller](INFO): Initializing. 2025-11-13T00:26:53.401Z,1762993613.401 [Power24vConverter](INFO): Powering up. 2025-11-13T00:26:53.402Z,1762993613.402 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format 2025-11-13T00:26:53.409Z,1762993613.409 [Sonardyne_Nano](INFO): Initializing. 2025-11-13T00:26:53.440Z,1762993613.440 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-11-13T00:26:53.472Z,1762993613.472 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2025-11-13T00:26:53.480Z,1762993613.480 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2025-11-13T00:26:53.481Z,1762993613.481 [ElevatorServo](DEBUG): Initializing EZServoServo. 2025-11-13T00:26:53.488Z,1762993613.488 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2025-11-13T00:26:53.489Z,1762993613.489 [DockingStepper](DEBUG): Initializing EZServoServo. 2025-11-13T00:26:53.496Z,1762993613.496 [DockingStepper](DEBUG): Initializing DockingStepper. 2025-11-13T00:26:53.497Z,1762993613.497 [MassServo](DEBUG): Initializing EZServoServo. 2025-11-13T00:26:53.504Z,1762993613.504 [MassServo](DEBUG): Initializing MassServo. 2025-11-13T00:26:53.505Z,1762993613.505 [RudderServo](DEBUG): Initializing EZServoServo. 2025-11-13T00:26:53.512Z,1762993613.512 [RudderServo](DEBUG): Initializing RudderServo. 2025-11-13T00:26:53.513Z,1762993613.513 [ThrusterServo](DEBUG): Initializing EZServoServo. 2025-11-13T00:26:53.520Z,1762993613.520 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2025-11-13T00:26:53.777Z,1762993613.777 [Depth_Keller](ERROR): Pressure or depth reading out of range: 1650.297119 decibar, 0.000000 m 2025-11-13T00:26:53.777Z,1762993613.777 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2025-11-13T00:26:53.778Z,1762993613.778 [DropWeight] Hardware Fault, FailCount= 1 2025-11-13T00:26:53.778Z,1762993613.778 [DropWeight](ERROR): Hardware Fault 2025-11-13T00:26:53.836Z,1762993613.836 [DepthRateCalculator](ERROR): Depth measurement is not active 2025-11-13T00:26:53.862Z,1762993613.862 [CommandExec](FAULT): Scheduling is paused 2025-11-13T00:26:53.862Z,1762993613.862 [CBIT](INFO): Critical error at 20251113T002653 2025-11-13T00:26:53.862Z,1762993613.862 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2025-11-13T00:26:53.873Z,1762993613.873 [CBIT](ERROR): Hardware Fault in component: DropWeight 2025-11-13T00:26:53.873Z,1762993613.873 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2025-11-13T00:26:54.169Z,1762993614.169 [CBIT](INFO): Critical error at 20251113T002653 2025-11-13T00:26:54.856Z,1762993614.856 [WetLabsBB2FL](INFO): Powering down 2025-11-13T00:26:55.354Z,1762993615.354 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART 2025-11-13T00:26:57.417Z,1762993617.417 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2103 2025-11-13T00:26:57.868Z,1762993617.868 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.10 2025-11-13T00:26:57.869Z,1762993617.869 [Radio_Surface](INFO): Found local IP address 10.89.10.2 for eth0 2025-11-13T00:26:57.880Z,1762993617.880 [Sonardyne_Nano](INFO): Battery below minimum, enabling charging 2025-11-13T00:27:01.508Z,1762993621.508 [WetLabsUBAT](INFO): Powering down 2025-11-13T00:27:04.512Z,1762993624.512 [DockingStepper](FAULT): Timed out moving from: 15519 to: 16500 response: ÿ 2025-11-13T00:27:04.512Z,1762993624.512 [DockingStepper] Hardware Fault, FailCount= 1 2025-11-13T00:27:04.513Z,1762993624.513 [DockingStepper](ERROR): Hardware Fault 2025-11-13T00:27:04.518Z,1762993624.518 [DAT](INFO): DAT read: 2025-11-13T00:27:04.520Z,1762993624.520 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series 2025-11-13T00:27:04.552Z,1762993624.552 [CBIT](ERROR): Hardware Fault in component: DockingStepper 2025-11-13T00:27:04.822Z,1762993624.822 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2025-11-13T00:27:04.822Z,1762993624.822 [DockingStepper](INFO): Powering down 2025-11-13T00:27:07.231Z,1762993627.231 [RDI_Pathfinder](ERROR): only read 0 of 4 data items for water velocity. Device response is::WS68,V 2025-11-13T00:27:10.313Z,1762993630.313 [DAT](INFO): DAT read: MF Frequency Band 2025-11-13T00:27:10.314Z,1762993630.314 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.17.2 2025-11-13T00:27:10.315Z,1762993630.315 [DAT](INFO): DAT read: Nov 13 2025 00:27:00 2025-11-13T00:27:11.321Z,1762993631.321 [DAT](INFO): DAT read: Features enabled [Bearing] 2025-11-13T00:27:11.322Z,1762993631.322 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP 2025-11-13T00:27:11.322Z,1762993631.322 [DAT](INFO): commRate: 600 2025-11-13T00:27:13.388Z,1762993633.388 [DAT](INFO): entering command mode 2025-11-13T00:27:13.588Z,1762993633.588 [DAT](INFO): DAT read: 2025-11-13T00:27:13.589Z,1762993633.589 [DAT](INFO): DAT read: user:1> 2025-11-13T00:27:13.589Z,1762993633.589 [DAT](INFO): setting verbose to 3 2025-11-13T00:27:13.840Z,1762993633.840 [DAT](INFO): DAT read: user:1> 2025-11-13T00:27:13.841Z,1762993633.842 [DAT](INFO): DAT read: Verbose | 3 2025-11-13T00:27:13.842Z,1762993633.842 [DAT](INFO): set verbose to 3 2025-11-13T00:27:13.842Z,1762993633.842 [DAT](INFO): setting DatVerbose to 27440 2025-11-13T00:27:14.008Z,1762993634.008 [CommandExec](IMPORTANT): got command failComponent 2025-11-13T00:27:14.008Z,1762993634.008 [CommandExec](IMPORTANT): Failed components: 2025-11-13T00:27:14.009Z,1762993634.009 [CommandExec](IMPORTANT): DropWeight: Hardware Fault 2025-11-13T00:27:14.009Z,1762993634.009 [CommandExec](IMPORTANT): DockingStepper: Hardware Fault 2025-11-13T00:27:14.092Z,1762993634.092 [DAT](INFO): DAT read: user:2> 2025-11-13T00:27:14.093Z,1762993634.093 [DAT](INFO): DAT read: DatVerbose | 27440 2025-11-13T00:27:14.094Z,1762993634.094 [DAT](INFO): set DatVerbose to 27440 2025-11-13T00:27:14.094Z,1762993634.094 [DAT](INFO): setting transmit power to 8 2025-11-13T00:27:14.346Z,1762993634.346 [DAT](INFO): DAT read: user:3> 2025-11-13T00:27:14.347Z,1762993634.347 [DAT](INFO): DAT read: TxPower | 8 (Max) 2025-11-13T00:27:14.347Z,1762993634.347 [DAT](INFO): set transmit power to 8 2025-11-13T00:27:14.348Z,1762993634.348 [DAT](INFO): setting local address to 10 2025-11-13T00:27:14.596Z,1762993634.596 [DAT](INFO): DAT read: user:4> 2025-11-13T00:27:14.597Z,1762993634.597 [DAT](INFO): DAT read: LocalAddr | 10 2025-11-13T00:27:14.598Z,1762993634.598 [DAT](INFO): set local address to 10 2025-11-13T00:27:14.599Z,1762993634.599 [DAT](INFO): Setting time to: 0:27:14 And date to:11/13/2025 2025-11-13T00:27:14.848Z,1762993634.848 [DAT](INFO): DAT read: user:5> 2025-11-13T00:27:14.849Z,1762993634.849 [DAT](INFO): DAT read: Thu Nov 13, 2025 00:27:14 2025-11-13T00:27:14.850Z,1762993634.850 [DAT](INFO): Local DAT time set to Thu Nov 13, 2025 00:27:14 2025-11-13T00:27:16.296Z,1762993636.296 [CommandExec](IMPORTANT): got command failComponent 2025-11-13T00:27:16.296Z,1762993636.296 [CommandExec](IMPORTANT): Failed components: 2025-11-13T00:27:16.297Z,1762993636.297 [CommandExec](IMPORTANT): DropWeight: Hardware Fault 2025-11-13T00:27:16.297Z,1762993636.297 [CommandExec](IMPORTANT): DockingStepper: Hardware Fault 2025-11-13T00:27:19.643Z,1762993639.643 [CommandExec](IMPORTANT): got command failComponent none DockingStepper 2025-11-13T00:27:19.643Z,1762993639.643 [DockingStepper] No Fault, FailCount= 1 2025-11-13T00:27:19.643Z,1762993639.643 [CommandExec](IMPORTANT): DockingStepper failureMode is No Fault 2025-11-13T00:27:21.361Z,1762993641.361 [NAL9602](INFO): Powering up NAL9602 2025-11-13T00:27:21.705Z,1762993641.705 [CommandExec](IMPORTANT): got command docking arm 2025-11-13T00:27:21.705Z,1762993641.705 [CommandExec](IMPORTANT): Switching docking module to ARM. 2025-11-13T00:27:23.292Z,1762993643.292 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-11-13T00:27:25.849Z,1762993645.849 [DockingStepper](DEBUG): Initializing EZServoServo. 2025-11-13T00:27:25.965Z,1762993645.965 [DockingStepper](DEBUG): Initializing DockingStepper. 2025-11-13T00:27:29.486Z,1762993649.486 [DockingStepper](INFO): Changing to mode: 2 2025-11-13T00:27:32.269Z,1762993652.269 [NAL9602](INFO): NAL9602 initialized 2025-11-13T00:27:42.645Z,1762993662.645 [CommandExec](IMPORTANT): got command docking standby 2025-11-13T00:27:42.645Z,1762993662.645 [CommandExec](IMPORTANT): Switching docking module to STANDBY. 2025-11-13T00:27:42.805Z,1762993662.805 [DockingStepper](INFO): Changing to mode: 1 2025-11-13T00:27:52.181Z,1762993672.181 [DockingStepper](FAULT): Timed out moving from: 11231 to: 16500 response: ÿ 2025-11-13T00:27:52.181Z,1762993672.181 [DockingStepper] Hardware Fault, FailCount= 1 2025-11-13T00:27:52.181Z,1762993672.181 [DockingStepper](ERROR): Hardware Fault 2025-11-13T00:27:52.186Z,1762993672.186 [CBIT](ERROR): Hardware Fault in component: DockingStepper 2025-11-13T00:27:52.482Z,1762993672.482 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2025-11-13T00:27:52.483Z,1762993672.483 [DockingStepper](INFO): Powering down 2025-11-13T00:27:54.436Z,1762993674.436 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-11-13T00:28:01.628Z,1762993681.628 [CommandExec](IMPORTANT): got command failComponent 2025-11-13T00:28:01.628Z,1762993681.628 [CommandExec](IMPORTANT): Failed components: 2025-11-13T00:28:01.629Z,1762993681.629 [CommandExec](IMPORTANT): DropWeight: Hardware Fault 2025-11-13T00:28:01.629Z,1762993681.629 [CommandExec](IMPORTANT): DockingStepper: Hardware Fault 2025-11-13T00:28:04.661Z,1762993684.661 [CommandExec](IMPORTANT): got command failComponent none DockingStepper 2025-11-13T00:28:04.661Z,1762993684.661 [DockingStepper] No Fault, FailCount= 1 2025-11-13T00:28:04.661Z,1762993684.661 [CommandExec](IMPORTANT): DockingStepper failureMode is No Fault 2025-11-13T00:28:07.839Z,1762993687.839 [SBIT](IMPORTANT): Beginning Startup BIT 2025-11-13T00:28:07.844Z,1762993687.844 [GFScanner](IMPORTANT): Beginning ground fault scan 2025-11-13T00:28:12.481Z,1762993692.481 [GFScanner](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.002040 CHAN A1 (24V): -0.009979 CHAN A2 (12V): 0.000178 CHAN A3 (5V): -0.001121 CHAN B0 (3.3V): 0.000922 CHAN B1 (3.15aV): 0.000354 CHAN B2 (3.15bV): 0.000672 CHAN B3 (GND): 0.000141 OPEN: 0.005224 Full Scale: +/- 1 mA 2025-11-13T00:28:13.514Z,1762993693.514 [DockingStepper](DEBUG): Initializing EZServoServo. 2025-11-13T00:28:13.629Z,1762993693.629 [DockingStepper](DEBUG): Initializing DockingStepper. 2025-11-13T00:28:16.091Z,1762993696.091 [CommandExec](IMPORTANT): got command docking unrequest 2025-11-13T00:28:16.091Z,1762993696.091 [CommandExec](IMPORTANT): Setting data request false. 2025-11-13T00:28:17.184Z,1762993697.184 [DockingStepper](INFO): Changing to mode: 1 2025-11-13T00:28:23.480Z,1762993703.480 [CommandExec](IMPORTANT): got command quit 2025-11-13T00:28:24.484Z,1762993704.484 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread. 2025-11-13T00:28:24.484Z,1762993704.484 [CommandExec](INFO): Uninitializing the command executive. 2025-11-13T00:28:24.484Z,1762993704.484 [CommandExec](INFO): Uninitializing the command scheduler. 2025-11-13T00:28:24.484Z,1762993704.484 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-11-13T00:28:24.576Z,1762993704.576 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2025-11-13T00:28:24.576Z,1762993704.576 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2025-11-13T00:28:24.576Z,1762993704.576 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-11-13T00:28:24.577Z,1762993704.577 [NavChartDb](INFO): Join timeout helper Thread ID is 9211 2025-11-13T00:28:24.732Z,1762993704.732 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2025-11-13T00:28:24.732Z,1762993704.732 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-11-13T00:28:24.740Z,1762993704.740 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler 2025-11-13T00:28:24.740Z,1762993704.740 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2025-11-13T00:28:24.740Z,1762993704.740 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 9212 2025-11-13T00:28:25.046Z,1762993705.046 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread. 2025-11-13T00:28:25.047Z,1762993705.047 [WetLabsUBAT](INFO): Powering down 2025-11-13T00:28:25.050Z,1762993705.050 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2025-11-13T00:28:25.052Z,1762993705.052 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2025-11-13T00:28:25.052Z,1762993705.052 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-11-13T00:28:25.052Z,1762993705.052 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 9213 2025-11-13T00:28:25.548Z,1762993705.548 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2025-11-13T00:28:25.548Z,1762993705.548 [WetLabsBB2FL](INFO): Powering down 2025-11-13T00:28:25.549Z,1762993705.549 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-11-13T00:28:25.564Z,1762993705.564 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2025-11-13T00:28:25.564Z,1762993705.564 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-11-13T00:28:25.564Z,1762993705.564 [CTD_Seabird](INFO): Join timeout helper Thread ID is 9214 2025-11-13T00:28:25.568Z,1762993705.568 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off 2025-11-13T00:28:25.620Z,1762993705.620 [CTD_Seabird](INFO): Powering down 2025-11-13T00:28:25.632Z,1762993705.632 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2025-11-13T00:28:25.632Z,1762993705.632 [CTD_Seabird](INFO): Powering down 2025-11-13T00:28:25.644Z,1762993705.644 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-11-13T00:28:25.652Z,1762993705.652 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2025-11-13T00:28:25.652Z,1762993705.652 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-11-13T00:28:25.652Z,1762993705.652 [Radio_Surface](INFO): Join timeout helper Thread ID is 9215 2025-11-13T00:28:25.956Z,1762993705.956 [Radio_Surface](INFO): Powering down 2025-11-13T00:28:25.957Z,1762993705.957 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2025-11-13T00:28:25.957Z,1762993705.957 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-11-13T00:28:25.961Z,1762993705.961 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2025-11-13T00:28:25.961Z,1762993705.961 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-11-13T00:28:25.961Z,1762993705.961 [Onboard](INFO): Join timeout helper Thread ID is 9216 2025-11-13T00:28:27.016Z,1762993707.016 [DockingStepper](FAULT): Timed out moving from: 15424 to: 16500 response: ÿ 2025-11-13T00:28:27.016Z,1762993707.016 [DockingStepper] Hardware Fault, FailCount= 2 2025-11-13T00:28:27.016Z,1762993707.016 [DockingStepper](ERROR): Hardware Fault 2025-11-13T00:28:27.177Z,1762993707.177 [CBIT](ERROR): Hardware Fault in component: DockingStepper 2025-11-13T00:28:27.268Z,1762993707.268 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2025-11-13T00:28:27.268Z,1762993707.268 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-11-13T00:28:27.289Z,1762993707.289 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2025-11-13T00:28:27.289Z,1762993707.289 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-11-13T00:28:27.290Z,1762993707.290 [DataOverHttps](INFO): Join timeout helper Thread ID is 9217 2025-11-13T00:28:27.336Z,1762993707.336 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2025-11-13T00:28:27.337Z,1762993707.337 [DockingStepper](INFO): Powering down 2025-11-13T00:28:27.576Z,1762993707.576 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2025-11-13T00:28:27.576Z,1762993707.576 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-11-13T00:28:27.580Z,1762993707.580 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler 2025-11-13T00:28:27.580Z,1762993707.580 [DAT ThreadHandler](INFO): Thread cancelled. 2025-11-13T00:28:27.581Z,1762993707.581 [DAT](INFO): Join timeout helper Thread ID is 9218 2025-11-13T00:28:27.697Z,1762993707.697 [DAT](INFO): Powering down 2025-11-13T00:28:27.769Z,1762993707.769 [DAT ThreadHandler](INFO): Uninitializing protected caller thread. 2025-11-13T00:28:27.769Z,1762993707.769 [DAT](INFO): Powering down 2025-11-13T00:28:27.770Z,1762993707.770 [DAT ThreadHandler](INFO): Thread cancelled. 2025-11-13T00:28:27.788Z,1762993707.788 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler 2025-11-13T00:28:27.789Z,1762993707.789 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-11-13T00:28:27.789Z,1762993707.789 [BackseatComponent](INFO): Join timeout helper Thread ID is 9219 2025-11-13T00:28:27.800Z,1762993707.800 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread. 2025-11-13T00:28:27.800Z,1762993707.800 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-11-13T00:28:27.808Z,1762993707.808 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2025-11-13T00:28:27.809Z,1762993707.809 [logger ThreadHandler](INFO): Thread cancelled. 2025-11-13T00:28:27.809Z,1762993707.809 [logger](INFO): Join timeout helper Thread ID is 9220 2025-11-13T00:28:27.812Z,1762993707.812 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2025-11-13T00:28:27.812Z,1762993707.812 [logger ThreadHandler](INFO): Thread cancelled. 2025-11-13T00:28:27.828Z,1762993707.828 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2025-11-13T00:28:27.829Z,1762993707.829 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-11-13T00:28:27.829Z,1762993707.829 [CommandLine](INFO): Join timeout helper Thread ID is 9221 2025-11-13T00:28:27.896Z,1762993707.896 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2025-11-13T00:28:27.896Z,1762993707.896 [CommandLine ThreadHandler](INFO): Thread cancelled. 2025-11-13T00:28:27.908Z,1762993707.908 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler 2025-11-13T00:28:27.908Z,1762993707.908 [CommandExec ThreadHandler](INFO): Thread cancelled. 2025-11-13T00:28:27.909Z,1762993707.909 [CommandExec](INFO): Join timeout helper Thread ID is 9222 2025-11-13T00:28:27.910Z,1762993707.910 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2025-11-13T00:28:27.910Z,1762993707.910 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-11-13T00:28:27.911Z,1762993707.911 [controlThread](INFO): Join timeout helper Thread ID is 9223 2025-11-13T00:28:28.024Z,1762993708.024 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2025-11-13T00:28:28.024Z,1762993708.024 [controlThread](DEBUG): Uninitializing ControlThread 2025-11-13T00:28:28.024Z,1762993708.024 [AHRS_M2](INFO): Powering down 2025-11-13T00:28:28.104Z,1762993708.104 [NAL9602](INFO): Powering down 2025-11-13T00:28:28.176Z,1762993708.176 [RDI_Pathfinder](INFO): Powering down 2025-11-13T00:28:28.177Z,1762993708.177 [Sonardyne_Nano](INFO): Powering down 2025-11-13T00:28:28.304Z,1762993708.304 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2025-11-13T00:28:28.305Z,1762993708.305 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2025-11-13T00:28:28.305Z,1762993708.305 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2025-11-13T00:28:28.306Z,1762993708.306 [MissionManager](INFO): Uninitializing Mission DefaultWithUndock 2025-11-13T00:28:28.315Z,1762993708.315 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2025-11-13T00:28:28.315Z,1762993708.315 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2025-11-13T00:28:28.315Z,1762993708.315 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2025-11-13T00:28:28.316Z,1762993708.316 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2025-11-13T00:28:28.316Z,1762993708.316 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2025-11-13T00:28:28.316Z,1762993708.316 [BuoyancyServo](INFO): Powering down 2025-11-13T00:28:28.328Z,1762993708.328 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2025-11-13T00:28:28.328Z,1762993708.328 [ElevatorServo](INFO): Powering down 2025-11-13T00:28:28.329Z,1762993708.329 [DockingStepper](DEBUG): Uninitialize Docking Stepper. 2025-11-13T00:28:28.329Z,1762993708.329 [DockingStepper](INFO): Powering down 2025-11-13T00:28:28.330Z,1762993708.330 [MassServo](DEBUG): Uninitialize Mass Servo. 2025-11-13T00:28:28.330Z,1762993708.330 [MassServo](INFO): Powering down 2025-11-13T00:28:28.331Z,1762993708.331 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2025-11-13T00:28:28.331Z,1762993708.331 [RudderServo](INFO): Powering down 2025-11-13T00:28:28.332Z,1762993708.332 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2025-11-13T00:28:28.332Z,1762993708.332 [ThrusterServo](INFO): Powering down 2025-11-13T00:28:28.333Z,1762993708.333 [SBIT](DEBUG): Uninitialize SBIT Component. 2025-11-13T00:28:28.333Z,1762993708.333 [IBIT](DEBUG): Uninitialize IBIT Component. 2025-11-13T00:28:28.333Z,1762993708.333 [CBIT](DEBUG): Uninitialize CBIT Component. 2025-11-13T00:28:28.333Z,1762993708.333 [CBIT](DEBUG): Powering off loads. 2025-11-13T00:28:28.348Z,1762993708.348 [CBIT](DEBUG): Disabling WDT. 2025-11-13T00:28:28.360Z,1762993708.360 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-11-13T00:28:28.360Z,1762993708.360 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-11-13T00:28:28.361Z,1762993708.361 [controlThread ThreadHandler](INFO): Thread cancelled. 2025-11-13T00:28:28.420Z,1762993708.420 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2025-11-13T00:28:28.423Z,1762993708.423 [Onboard ThreadHandler](INFO): Thread cancelled. 2025-11-13T00:28:28.428Z,1762993708.428 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2025-11-13T00:28:28.435Z,1762993708.435 [DAT ThreadHandler](INFO): Thread cancelled. 2025-11-13T00:28:28.476Z,1762993708.476 [BackseatComponent ThreadHandler](INFO): Thread cancelled. 2025-11-13T00:28:28.509Z,1762993708.509 [GFScanner](DEBUG): Uninitialize GFScanner component. 2025-11-13T00:28:28.509Z,1762993708.509 [GFScanner](DEBUG): Opening all GF detection circuits. 2025-11-13T00:28:29.013Z,1762993709.013 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2025-11-13T00:28:29.015Z,1762993709.015 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2025-11-13T00:28:29.019Z,1762993709.019 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2025-11-13T00:28:29.027Z,1762993709.027 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2025-11-13T00:28:29.089Z,1762993709.089 [logger ThreadHandler](INFO): Thread cancelled.