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.