2025-05-22T22:52:35.127Z,1747954355.127 [Supervisor](DEBUG): Initializing supervisor.
2025-05-22T22:52:35.132Z,1747954355.132 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-05-22T22:52:35.132Z,1747954355.132 [SyncHandler](INFO): Protected caller Thread ID is 2129
2025-05-22T22:52:35.133Z,1747954355.133 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-05-22T22:52:35.134Z,1747954355.134 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-05-22T22:52:35.134Z,1747954355.134 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2130
2025-05-22T22:52:35.138Z,1747954355.138 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-05-22T22:52:35.162Z,1747954355.162 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-05-22T22:52:35.163Z,1747954355.163 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-05-22T22:52:35.163Z,1747954355.163 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 2131
2025-05-22T22:52:35.167Z,1747954355.167 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-05-22T22:52:35.168Z,1747954355.168 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-05-22T22:52:35.168Z,1747954355.168 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2132
2025-05-22T22:52:35.170Z,1747954355.170 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-05-22T22:52:35.171Z,1747954355.171 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-05-22T22:52:35.172Z,1747954355.172 [logger ThreadHandler](INFO): Protected caller Thread ID is 2133
2025-05-22T22:52:35.175Z,1747954355.175 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-05-22T22:52:35.176Z,1747954355.176 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-05-22T22:52:35.181Z,1747954355.181 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-05-22T22:52:35.482Z,1747954355.482 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-05-22T22:52:35.484Z,1747954355.484 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-05-22T22:52:36.249Z,1747954356.249 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-05-22T22:52:36.251Z,1747954356.251 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-05-22T22:52:36.527Z,1747954356.527 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-05-22T22:52:36.592Z,1747954356.592 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-05-22T22:52:36.694Z,1747954356.694 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-05-22T22:52:36.835Z,1747954356.835 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-05-22T22:52:36.837Z,1747954356.837 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-05-22T22:52:36.931Z,1747954356.931 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-05-22T22:52:37.049Z,1747954357.049 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-05-22T22:52:37.050Z,1747954357.050 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-05-22T22:52:37.451Z,1747954357.451 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-05-22T22:52:37.453Z,1747954357.453 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-05-22T22:52:37.988Z,1747954357.988 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-05-22T22:52:37.990Z,1747954357.990 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-05-22T22:52:38.218Z,1747954358.218 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-05-22T22:52:38.220Z,1747954358.220 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-05-22T22:52:38.412Z,1747954358.412 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-05-22T22:52:38.413Z,1747954358.413 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-05-22T22:52:38.960Z,1747954358.960 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-05-22T22:52:38.962Z,1747954358.962 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-05-22T22:52:39.265Z,1747954359.265 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-05-22T22:52:39.267Z,1747954359.267 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-05-22T22:52:39.510Z,1747954359.510 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-05-22T22:52:39.510Z,1747954359.510 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-05-22T22:52:39.892Z,1747954359.892 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-05-22T22:52:39.894Z,1747954359.894 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-05-22T22:52:40.250Z,1747954360.250 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-05-22T22:52:40.252Z,1747954360.252 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2025-05-22T22:52:40.255Z,1747954360.255 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2025-05-22T22:52:40.350Z,1747954360.350 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2025-05-22T22:52:40.528Z,1747954360.528 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Dock.cfg
2025-05-22T22:52:40.645Z,1747954360.645 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/workSite.cfg
2025-05-22T22:52:40.752Z,1747954360.752 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2025-05-22T22:52:40.870Z,1747954360.870 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2025-05-22T22:52:40.968Z,1747954360.968 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2025-05-22T22:52:41.075Z,1747954361.075 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2025-05-22T22:52:41.197Z,1747954361.197 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2025-05-22T22:52:41.470Z,1747954361.470 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-05-22T22:52:41.471Z,1747954361.471 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2025-05-22T22:52:41.681Z,1747954361.681 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2025-05-22T22:52:41.936Z,1747954361.936 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2025-05-22T22:52:42.095Z,1747954362.095 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2025-05-22T22:52:42.215Z,1747954362.215 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2025-05-22T22:52:42.215Z,1747954362.215 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-05-22T22:52:42.232Z,1747954362.232 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-05-22T22:52:42.396Z,1747954362.396 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-05-22T22:52:42.397Z,1747954362.397 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-05-22T22:52:42.466Z,1747954362.466 [VerticalControl](DEBUG): Construct VerticalControl.
2025-05-22T22:52:42.525Z,1747954362.525 [VerticalControl] Loaded
2025-05-22T22:52:42.525Z,1747954362.525 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-05-22T22:52:42.528Z,1747954362.528 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-05-22T22:52:42.575Z,1747954362.575 [HorizontalControl] Loaded
2025-05-22T22:52:42.575Z,1747954362.575 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-05-22T22:52:42.577Z,1747954362.577 [SpeedControl](DEBUG): Construct SpeedControl.
2025-05-22T22:52:42.580Z,1747954362.580 [SpeedControl] Loaded
2025-05-22T22:52:42.580Z,1747954362.580 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-05-22T22:52:42.583Z,1747954362.583 [LoopControl](DEBUG): Construct LoopControl.
2025-05-22T22:52:42.583Z,1747954362.583 [LoopControl] Loaded
2025-05-22T22:52:42.584Z,1747954362.584 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-05-22T22:52:42.584Z,1747954362.584 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-05-22T22:52:42.585Z,1747954362.585 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-05-22T22:52:42.655Z,1747954362.655 [DepthRateCalculator] Loaded
2025-05-22T22:52:42.655Z,1747954362.655 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-05-22T22:52:42.660Z,1747954362.660 [PitchRateCalculator] Loaded
2025-05-22T22:52:42.660Z,1747954362.660 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-05-22T22:52:42.669Z,1747954362.669 [SpeedCalculator] Loaded
2025-05-22T22:52:42.670Z,1747954362.670 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-05-22T22:52:42.674Z,1747954362.674 [YawRateCalculator] Loaded
2025-05-22T22:52:42.674Z,1747954362.674 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-05-22T22:52:42.692Z,1747954362.692 [ElevatorOffsetCalculator] Loaded
2025-05-22T22:52:42.692Z,1747954362.692 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-05-22T22:52:42.693Z,1747954362.693 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-05-22T22:52:42.694Z,1747954362.694 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-05-22T22:52:42.737Z,1747954362.737 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-05-22T22:52:42.739Z,1747954362.739 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-05-22T22:52:42.828Z,1747954362.828 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-05-22T22:52:42.829Z,1747954362.829 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-05-22T22:52:43.242Z,1747954363.242 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-05-22T22:52:43.242Z,1747954363.242 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-05-22T22:52:43.460Z,1747954363.460 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-05-22T22:52:43.461Z,1747954363.461 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-05-22T22:52:44.310Z,1747954364.310 [AHRS_M2] Loaded
2025-05-22T22:52:44.310Z,1747954364.310 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-05-22T22:52:44.573Z,1747954364.573 [BackseatComponent] Loaded
2025-05-22T22:52:44.573Z,1747954364.573 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-05-22T22:52:44.584Z,1747954364.584 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409E24E0
2025-05-22T22:52:44.584Z,1747954364.584 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 2221
2025-05-22T22:52:44.589Z,1747954364.589 [LcmUniversalReporter] Loaded
2025-05-22T22:52:44.589Z,1747954364.589 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-05-22T22:52:45.412Z,1747954365.412 [BPC1] Loaded
2025-05-22T22:52:45.412Z,1747954365.412 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-05-22T22:52:45.509Z,1747954365.509 [DAT] Loaded
2025-05-22T22:52:45.509Z,1747954365.509 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-05-22T22:52:45.510Z,1747954365.510 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A124E0
2025-05-22T22:52:45.510Z,1747954365.510 [DAT ThreadHandler](INFO): Protected caller Thread ID is 2222
2025-05-22T22:52:45.743Z,1747954365.743 [DataOverHttps] Loaded
2025-05-22T22:52:45.743Z,1747954365.743 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-05-22T22:52:45.744Z,1747954365.744 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A424E0
2025-05-22T22:52:45.744Z,1747954365.744 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2223
2025-05-22T22:52:45.767Z,1747954365.767 [Depth_Keller] Loaded
2025-05-22T22:52:45.767Z,1747954365.767 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-05-22T22:52:45.831Z,1747954365.831 [NAL9602] Loaded
2025-05-22T22:52:45.831Z,1747954365.831 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-05-22T22:52:45.860Z,1747954365.860 [Onboard] Loaded
2025-05-22T22:52:45.860Z,1747954365.860 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-05-22T22:52:45.861Z,1747954365.861 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A724E0
2025-05-22T22:52:45.861Z,1747954365.861 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 2224
2025-05-22T22:52:45.875Z,1747954365.875 [Power24vConverter] Loaded
2025-05-22T22:52:45.875Z,1747954365.875 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-05-22T22:52:45.891Z,1747954365.891 [Radio_Surface] Loaded
2025-05-22T22:52:45.891Z,1747954365.891 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-05-22T22:52:45.892Z,1747954365.892 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40AA24E0
2025-05-22T22:52:45.892Z,1747954365.892 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2225
2025-05-22T22:52:45.939Z,1747954365.939 [RDI_Pathfinder] Loaded
2025-05-22T22:52:45.939Z,1747954365.939 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2025-05-22T22:52:45.954Z,1747954365.954 [Sonardyne_Nano] Loaded
2025-05-22T22:52:45.954Z,1747954365.954 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-05-22T22:52:46.026Z,1747954366.026 [TrnDataBridge] Loaded
2025-05-22T22:52:46.026Z,1747954366.026 [ComponentRegistry](DEBUG): Component "TrnDataBridge" handled in its own thread.
2025-05-22T22:52:46.027Z,1747954366.027 [TrnDataBridge ThreadHandler](DEBUG): Created PCaller Thread at 40AD24E0
2025-05-22T22:52:46.027Z,1747954366.027 [TrnDataBridge ThreadHandler](INFO): Protected caller Thread ID is 2226
2025-05-22T22:52:46.028Z,1747954366.028 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-05-22T22:52:46.028Z,1747954366.028 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-05-22T22:52:46.162Z,1747954366.162 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-05-22T22:52:46.162Z,1747954366.162 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-05-22T22:52:46.176Z,1747954366.176 [NavChart] Loaded
2025-05-22T22:52:46.176Z,1747954366.176 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-05-22T22:52:46.181Z,1747954366.181 [UniversalFixResidualReporter] Loaded
2025-05-22T22:52:46.181Z,1747954366.181 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-05-22T22:52:46.182Z,1747954366.182 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-05-22T22:52:46.184Z,1747954366.184 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-05-22T22:52:46.350Z,1747954366.350 [SBIT](DEBUG): Construct Startup Built In Test.
2025-05-22T22:52:46.358Z,1747954366.358 [SBIT] Loaded
2025-05-22T22:52:46.359Z,1747954366.359 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-05-22T22:52:46.362Z,1747954366.362 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-05-22T22:52:46.374Z,1747954366.374 [IBIT] Loaded
2025-05-22T22:52:46.374Z,1747954366.374 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-05-22T22:52:46.381Z,1747954366.381 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-05-22T22:52:46.483Z,1747954366.483 [CBIT] Loaded
2025-05-22T22:52:46.483Z,1747954366.483 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-05-22T22:52:46.483Z,1747954366.483 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-05-22T22:52:46.484Z,1747954366.484 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-05-22T22:52:46.652Z,1747954366.652 [BuoyancyServo] Loaded
2025-05-22T22:52:46.652Z,1747954366.652 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-05-22T22:52:46.675Z,1747954366.675 [ElevatorServo] Loaded
2025-05-22T22:52:46.675Z,1747954366.675 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-05-22T22:52:46.710Z,1747954366.710 [DockingStepper] Loaded
2025-05-22T22:52:46.711Z,1747954366.711 [ComponentRegistry](DEBUG): SyncComponent "DockingStepper" handled in the control thread.
2025-05-22T22:52:46.732Z,1747954366.732 [MassServo] Loaded
2025-05-22T22:52:46.732Z,1747954366.732 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-05-22T22:52:46.752Z,1747954366.752 [RudderServo] Loaded
2025-05-22T22:52:46.753Z,1747954366.753 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-05-22T22:52:46.771Z,1747954366.771 [ThrusterServo] Loaded
2025-05-22T22:52:46.772Z,1747954366.772 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2025-05-22T22:52:46.772Z,1747954366.772 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-05-22T22:52:46.773Z,1747954366.773 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-05-22T22:52:46.789Z,1747954366.789 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-05-22T22:52:46.792Z,1747954366.792 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-05-22T22:52:47.038Z,1747954367.038 [CTD_Seabird] Loaded
2025-05-22T22:52:47.039Z,1747954367.039 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-05-22T22:52:47.067Z,1747954367.067 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40C1F4E0
2025-05-22T22:52:47.067Z,1747954367.067 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 2227
2025-05-22T22:52:47.090Z,1747954367.090 [PAR_Licor] Loaded
2025-05-22T22:52:47.090Z,1747954367.090 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-05-22T22:52:47.134Z,1747954367.134 [WetLabsBB2FL] Loaded
2025-05-22T22:52:47.134Z,1747954367.134 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-05-22T22:52:47.135Z,1747954367.135 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C4F4E0
2025-05-22T22:52:47.136Z,1747954367.136 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 2228
2025-05-22T22:52:47.156Z,1747954367.156 [WetLabsUBAT] Loaded
2025-05-22T22:52:47.156Z,1747954367.156 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2025-05-22T22:52:47.157Z,1747954367.157 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40C7F4E0
2025-05-22T22:52:47.162Z,1747954367.162 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 2229
2025-05-22T22:52:47.163Z,1747954367.163 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-05-22T22:52:47.169Z,1747954367.169 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-05-22T22:52:47.173Z,1747954367.173 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-05-22T22:52:47.184Z,1747954367.184 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-05-22T22:52:47.185Z,1747954367.185 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40CAF4E0
2025-05-22T22:52:47.185Z,1747954367.185 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2230
2025-05-22T22:52:47.190Z,1747954367.190 [Supervisor](INFO): Main Thread ID is 2127
2025-05-22T22:52:47.190Z,1747954367.190 [Supervisor](DEBUG): Running supervisor.
2025-05-22T22:52:47.191Z,1747954367.191 [CommandExec ThreadHandler](INFO): Handler Thread ID is 2231
2025-05-22T22:52:47.191Z,1747954367.191 [CommandExec](INFO): Initializing the command executive.
2025-05-22T22:52:47.193Z,1747954367.193 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2232
2025-05-22T22:52:47.195Z,1747954367.195 [controlThread ThreadHandler](INFO): Handler Thread ID is 2233
2025-05-22T22:52:47.195Z,1747954367.195 [controlThread](DEBUG): Initializing ControlThread
2025-05-22T22:52:47.196Z,1747954367.196 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-05-22T22:52:47.198Z,1747954367.198 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-05-22T22:52:47.198Z,1747954367.198 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-05-22T22:52:47.199Z,1747954367.199 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-05-22T22:52:47.199Z,1747954367.199 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-05-22T22:52:47.200Z,1747954367.200 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-05-22T22:52:47.200Z,1747954367.200 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-05-22T22:52:47.200Z,1747954367.200 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-05-22T22:52:47.201Z,1747954367.201 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-05-22T22:52:47.207Z,1747954367.207 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-05-22T22:52:47.207Z,1747954367.207 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-05-22T22:52:47.208Z,1747954367.208 [SBIT](INFO): Initialize SBIT Component.
2025-05-22T22:52:47.208Z,1747954367.208 [SBIT](IMPORTANT): git: 2025-01-23
2025-05-22T22:52:47.208Z,1747954367.208 [SBIT](INFO): git hash: e941ed7ca4296489cfe1bf7015177657c0080d4b
2025-05-22T22:52:47.209Z,1747954367.209 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-05-22T22:52:47.210Z,1747954367.210 [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-05-22T22:52:47.211Z,1747954367.211 [SBIT](INFO): Beginning SBIT in 101.000000 seconds.
2025-05-22T22:52:47.212Z,1747954367.212 [IBIT](INFO): Initialize IBIT Component.
2025-05-22T22:52:47.212Z,1747954367.212 [CBIT](DEBUG): Initialize CBIT Component.
2025-05-22T22:52:47.214Z,1747954367.214 [logger ThreadHandler](INFO): Handler Thread ID is 2234
2025-05-22T22:52:47.226Z,1747954367.226 [CBIT](DEBUG): Initialized mux pins.
2025-05-22T22:52:47.226Z,1747954367.226 [CBIT](DEBUG): Initializing the watchdog timer.
2025-05-22T22:52:47.234Z,1747954367.234 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 2235
2025-05-22T22:52:47.246Z,1747954367.246 [DAT ThreadHandler](INFO): Handler Thread ID is 2236
2025-05-22T22:52:47.247Z,1747954367.247 [DAT](INFO): Powering up
2025-05-22T22:52:47.247Z,1747954367.247 [DAT](DEBUG): Initializing DAT.
2025-05-22T22:52:47.250Z,1747954367.250 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-05-22T22:52:47.250Z,1747954367.250 [CBIT](DEBUG): Initializing heartbeat.
2025-05-22T22:52:47.258Z,1747954367.258 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2237
2025-05-22T22:52:47.260Z,1747954367.260 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-05-22T22:52:47.275Z,1747954367.275 [Onboard ThreadHandler](INFO): Handler Thread ID is 2238
2025-05-22T22:52:47.314Z,1747954367.314 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2239
2025-05-22T22:52:47.315Z,1747954367.315 [TrnDataBridge ThreadHandler](INFO): Handler Thread ID is 2240
2025-05-22T22:52:47.316Z,1747954367.316 [TrnDataBridge](INFO): Start
2025-05-22T22:52:47.316Z,1747954367.316 [TrnDataBridge](IMPORTANT): LCM Instance1
2025-05-22T22:52:47.322Z,1747954367.322 [CBIT](DEBUG): Deactivating GF circuits.
2025-05-22T22:52:47.322Z,1747954367.322 [CBIT](DEBUG): Deactivating emergency mode.
2025-05-22T22:52:47.350Z,1747954367.350 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 2242
2025-05-22T22:52:47.351Z,1747954367.351 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-05-22T22:52:47.362Z,1747954367.362 [CBIT](DEBUG): Backplane powered.
2025-05-22T22:52:47.402Z,1747954367.402 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-05-22T22:52:47.403Z,1747954367.403 [MissionManager](INFO): Loading Mission from file: Missions/underIce/StartupUnder.tl
2025-05-22T22:52:47.403Z,1747954367.403 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/StartupUnder.tl
2025-05-22T22:52:47.405Z,1747954367.405 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 2244
2025-05-22T22:52:47.418Z,1747954367.418 [WetLabsBB2FL](INFO): Powering up
2025-05-22T22:52:47.419Z,1747954367.419 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 2246
2025-05-22T22:52:47.423Z,1747954367.423 [WetLabsUBAT](INFO): Powering up
2025-05-22T22:52:47.426Z,1747954367.426 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/StartupUnder.tl
2025-05-22T22:52:47.434Z,1747954367.434 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2248
2025-05-22T22:52:47.443Z,1747954367.443 [MissionManager](DEBUG): TethyslAPI: Missions/underIce/StartupUnder.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="StartupUnder" />

2025-05-22T22:52:47.444Z,1747954367.444 [MissionManager](ERROR): Top node has no child nodes: Mission
2025-05-22T22:52:47.444Z,1747954367.444 [MissionManager](ERROR): Could not load startup mission.
2025-05-22T22:52:47.444Z,1747954367.444 [MissionManager](INFO): Loading Mission from file: Missions/Engineering/DefaultTankUndock.tl
2025-05-22T22:52:47.445Z,1747954367.445 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Engineering/DefaultTankUndock.tl
2025-05-22T22:52:47.452Z,1747954367.452 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-05-22T22:52:47.453Z,1747954367.453 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-05-22T22:52:47.453Z,1747954367.453 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-05-22T22:52:47.453Z,1747954367.453 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-05-22T22:52:47.453Z,1747954367.453 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-05-22T22:52:47.453Z,1747954367.453 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-05-22T22:52:47.453Z,1747954367.453 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-05-22T22:52:47.454Z,1747954367.454 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-05-22T22:52:47.492Z,1747954367.492 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Engineering/DefaultTankUndock.tl
2025-05-22T22:52:47.718Z,1747954367.718 [Radio_Surface](INFO): Powering up
2025-05-22T22:52:47.741Z,1747954367.741 [MissionManager](DEBUG): TethyslAPI: Missions/Engineering/DefaultTankUndock.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="DefaultTankUndock">
    <DefineArg Name="ElapsedSinceDefaultStarted">
        <Units:minute />
        <Value>0</Value>
    </DefineArg>
    <Guidance:Wait>
        <Sequence />
        <Setting>
            <Guidance:Wait.duration />
            <Units:second />
            <Value>13</Value>
        </Setting>
    </Guidance:Wait>
    <Guidance:GoToSurface>
        <Progression />
    </Guidance:GoToSurface>
    <Guidance:Execute>
        <Sequence />
        <Setting>
            <Guidance:Execute.command />
            <String>dock arm</String>
        </Setting>
    </Guidance:Execute>
    <Aggregate Id="CheckIn">
        <Sequence Repeat="288" />
        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M" />
            <Universal:time_fix />
        </ReadDatum>
        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P4H" />
            <Universal:platform_communications />
        </ReadDatum>
        <Guidance:Wait>
            <Sequence />
            <Setting>
                <Guidance:Wait.duration />
                <Units:minute />
                <Value>5</Value>
            </Setting>
        </Guidance:Wait>
        <Assign>
            <Sequence />
            <Arg Name="ElapsedSinceDefaultStarted" />
            <Elapsed>
                <Universal:mission_started />
            </Elapsed>
        </Assign>
        <Syslog Severity="Important">
            Default mission has been running for 
            <Arg Name="ElapsedSinceDefaultStarted" />
            <Units:minute />
        </Syslog>
    </Aggregate>
    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>
    <Guidance:Execute>
        <Sequence />
        <Setting>
            <Guidance:Execute.command />
            <String>restart logs</String>
        </Setting>
    </Guidance:Execute>
</Mission>

2025-05-22T22:52:47.768Z,1747954367.768 [MissionManager](INFO): DefineArg DefaultTankUndock.ElapsedSinceDefaultStarted = 0.000000 min
2025-05-22T22:52:47.769Z,1747954367.769 [DefaultTankUndock:A.Wait](DEBUG): Construct Wait.
2025-05-22T22:52:47.793Z,1747954367.793 [DefaultTankUndock:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-05-22T22:52:47.811Z,1747954367.811 [DefaultTankUndock:C.Execute](DEBUG): Construct Execute.
2025-05-22T22:52:47.848Z,1747954367.848 [DefaultTankUndock:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-05-22T22:52:47.857Z,1747954367.857 [DefaultTankUndock:F.Execute](DEBUG): Construct Execute.
2025-05-22T22:52:47.860Z,1747954367.860 [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 DefaultTankUndock {
  arguments {
    ElapsedSinceDefaultStarted = 0 minute
  }

  behavior Guidance:Wait {
    """
    Wait a moment to see if the scheduler starts a new mission before
    starting to actually run Default.
    """

    run in sequence

    set duration = 13 second
  }

  behavior Guidance:GoToSurface {
    run in progression
  }

  behavior Guidance:Execute {
    run in sequence

    set command = "dock arm"
  }

  aggregate CheckIn {
    run in sequence repeat=288

    readDatum id="Read_GPS" {
      timeout duration=P5M

      Universal:time_fix
    }

    readDatum id="Read_Iridium" {
      timeout duration=P4H

      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-05-22T22:52:47.870Z,1747954367.870 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,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,LcmUniversalReporter,Reporter,LogSplitter,
2025-05-22T22:52:47.884Z,1747954367.884 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-05-22T22:52:48.108Z,1747954368.108 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.000394 decibar, -0.993022 m
2025-05-22T22:52:48.109Z,1747954368.109 [Power24vConverter](INFO): Powering up.
2025-05-22T22:52:48.109Z,1747954368.109 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2025-05-22T22:52:48.123Z,1747954368.123 [Sonardyne_Nano](INFO): Initializing.
2025-05-22T22:52:48.144Z,1747954368.144 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-05-22T22:52:48.252Z,1747954368.252 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-05-22T22:52:48.252Z,1747954368.252 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-05-22T22:52:48.253Z,1747954368.253 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-05-22T22:52:48.260Z,1747954368.260 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-05-22T22:52:48.261Z,1747954368.261 [DockingStepper](INFO): Start
2025-05-22T22:52:48.261Z,1747954368.261 [DockingStepper](DEBUG): Initializing EZServoServo.
2025-05-22T22:52:48.261Z,1747954368.261 [DockingStepper](DEBUG): Initializing DockingStepper.
2025-05-22T22:52:48.275Z,1747954368.275 [MassServo](DEBUG): Initializing EZServoServo.
2025-05-22T22:52:48.276Z,1747954368.276 [MassServo](DEBUG): Initializing MassServo.
2025-05-22T22:52:48.276Z,1747954368.276 [RudderServo](DEBUG): Initializing EZServoServo.
2025-05-22T22:52:48.282Z,1747954368.282 [RudderServo](DEBUG): Initializing RudderServo.
2025-05-22T22:52:48.283Z,1747954368.283 [ThrusterServo](DEBUG): Initializing EZServoServo.
2025-05-22T22:52:48.283Z,1747954368.283 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2025-05-22T22:52:48.457Z,1747954368.457 [DockingStepper](INFO): Starting
2025-05-22T22:52:48.721Z,1747954368.721 [DockingStepper](INFO): Starting
2025-05-22T22:52:49.246Z,1747954369.246 [DockingStepper](INFO): Starting
2025-05-22T22:52:49.554Z,1747954369.554 [DockingStepper](INFO): Starting
2025-05-22T22:52:49.603Z,1747954369.603 [DockingStepper](INFO): Init string: /1z16368aE13561N3m50
2025-05-22T22:52:49.945Z,1747954369.945 [DockingStepper](INFO): Starting
2025-05-22T22:52:50.019Z,1747954370.019 [WetLabsBB2FL](INFO): Powering down
2025-05-22T22:52:50.324Z,1747954370.324 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-05-22T22:52:50.385Z,1747954370.385 [DockingStepper](INFO): Starting
2025-05-22T22:52:50.806Z,1747954370.806 [DockingStepper](INFO): Starting
2025-05-22T22:52:51.276Z,1747954371.276 [DockingStepper](INFO): Starting
2025-05-22T22:52:51.302Z,1747954371.302 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@
2025-05-22T22:52:51.571Z,1747954371.571 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2103
2025-05-22T22:52:51.647Z,1747954371.647 [DockingStepper](INFO): Starting
2025-05-22T22:52:52.531Z,1747954372.531 [DockingStepper](INFO): Pause
2025-05-22T22:52:52.970Z,1747954372.970 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.10
2025-05-22T22:52:52.971Z,1747954372.971 [Radio_Surface](INFO): Found local IP address 10.89.10.2 for eth0
2025-05-22T22:52:56.670Z,1747954376.670 [WetLabsUBAT](INFO): Powering down
2025-05-22T22:52:59.598Z,1747954379.598 [DAT](INFO): DAT read: 

2025-05-22T22:52:59.600Z,1747954379.600 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2025-05-22T22:53:02.510Z,1747954382.510 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.006472
2025-05-22T22:53:03.506Z,1747954383.506 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:53:05.143Z,1747954385.143 [DAT](INFO): DAT read: MF Frequency Band

2025-05-22T22:53:05.144Z,1747954385.144 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.17.2

2025-05-22T22:53:05.144Z,1747954385.144 [DAT](INFO): DAT read: May 15 2025 17:52:50 

2025-05-22T22:53:06.151Z,1747954386.151 [DAT](INFO): DAT read: Features enabled [Bearing]

2025-05-22T22:53:06.152Z,1747954386.152 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP

2025-05-22T22:53:06.152Z,1747954386.152 [DAT](INFO): commRate: 600
2025-05-22T22:53:08.218Z,1747954388.218 [DAT](INFO): entering command mode
2025-05-22T22:53:08.418Z,1747954388.418 [DAT](INFO): DAT read: 

2025-05-22T22:53:08.419Z,1747954388.419 [DAT](INFO): DAT read: user:1>

2025-05-22T22:53:08.419Z,1747954388.419 [DAT](INFO): setting verbose to 3
2025-05-22T22:53:08.670Z,1747954388.670 [DAT](INFO): DAT read: user:1>

2025-05-22T22:53:08.671Z,1747954388.671 [DAT](INFO): DAT read: Verbose         | 3           

2025-05-22T22:53:08.672Z,1747954388.672 [DAT](INFO): set verbose to 3
2025-05-22T22:53:08.672Z,1747954388.672 [DAT](INFO): setting DatVerbose to 27440
2025-05-22T22:53:08.925Z,1747954388.925 [DAT](INFO): DAT read: user:2>

2025-05-22T22:53:08.926Z,1747954388.926 [DAT](INFO): DAT read: DatVerbose      | 27440       

2025-05-22T22:53:08.927Z,1747954388.927 [DAT](INFO): set DatVerbose to 27440
2025-05-22T22:53:08.927Z,1747954388.927 [DAT](INFO): setting transmit power to 8
2025-05-22T22:53:09.174Z,1747954389.174 [DAT](INFO): DAT read: user:3>

2025-05-22T22:53:09.175Z,1747954389.175 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2025-05-22T22:53:09.176Z,1747954389.176 [DAT](INFO): set transmit power to 8
2025-05-22T22:53:09.176Z,1747954389.176 [DAT](INFO): setting local address to 10
2025-05-22T22:53:09.427Z,1747954389.427 [DAT](INFO): DAT read: user:4>

2025-05-22T22:53:09.427Z,1747954389.427 [DAT](INFO): DAT read: LocalAddr       | 10          

2025-05-22T22:53:09.428Z,1747954389.428 [DAT](INFO): set local address to 10
2025-05-22T22:53:09.429Z,1747954389.429 [DAT](INFO): Setting time to: 22:53:9 And date to:5/22/2025
2025-05-22T22:53:09.681Z,1747954389.681 [DAT](INFO): DAT read: user:5>

2025-05-22T22:53:09.684Z,1747954389.684 [DAT](INFO): DAT read: Thu May 22, 2025  22:53:09  

2025-05-22T22:53:09.685Z,1747954389.685 [DAT](INFO): Local DAT time set to Thu May 22, 2025  22:53:09  

2025-05-22T22:53:16.179Z,1747954396.179 [NAL9602](INFO): Powering up NAL9602
2025-05-22T22:53:19.042Z,1747954399.042 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:53:27.099Z,1747954407.099 [NAL9602](INFO): NAL9602 initialized
2025-05-22T22:53:31.182Z,1747954411.182 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:53:43.254Z,1747954423.254 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:53:54.963Z,1747954434.963 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-05-22T22:53:55.266Z,1747954435.266 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:53:58.472Z,1747954438.472 [CommandExec](IMPORTANT): got command show variable platform_average_current
2025-05-22T22:53:58.528Z,1747954438.528 [CommandExec](IMPORTANT): platform_average_current (unknown)
2025-05-22T22:53:58.954Z,1747954438.954 [CommandExec](IMPORTANT): Onboard.platform_average_current (milliampere)
2025-05-22T22:54:05.290Z,1747954445.290 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:54:08.295Z,1747954448.295 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-05-22T22:54:08.648Z,1747954448.648 [CommandExec](IMPORTANT): got command report touch Onboard.platform_average_current
2025-05-22T22:54:14.430Z,1747954454.430 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:54:20.819Z,1747954460.819 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-05-22T22:54:22.835Z,1747954462.835 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:54:28.941Z,1747954468.941 [SBIT](IMPORTANT): Beginning Startup BIT
2025-05-22T22:54:28.946Z,1747954468.946 [CBIT](IMPORTANT): Beginning ground fault scan
2025-05-22T22:54:32.314Z,1747954472.314 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:54:33.746Z,1747954473.746 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-05-22T22:54:39.925Z,1747954479.925 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.002123
CHAN A1 (24V): -0.024325
CHAN A2 (12V): -0.005288
CHAN A3 (5V): -0.001477
CHAN B0 (3.3V): -0.000321
CHAN B1 (3.15aV): -0.000908
CHAN B2 (3.15bV): 0.000057
CHAN B3 (GND): 0.000998
OPEN: 0.004802
Full Scale: +/- 1 mA
2025-05-22T22:54:41.034Z,1747954481.034 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:54:42.661Z,1747954482.661 [Reporter](INFO): Onboard.platform_average_current 0.000000 mA
2025-05-22T22:54:46.743Z,1747954486.743 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-05-22T22:54:50.806Z,1747954490.806 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:54:59.518Z,1747954499.518 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:55:00.128Z,1747954500.128 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-05-22T22:55:08.258Z,1747954508.258 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:55:08.772Z,1747954508.772 [SBIT](FAULT): Elevator: EXPECTED:-15.000000 ACTUAL:-14.771283
2025-05-22T22:55:08.773Z,1747954508.773 [SBIT](FAULT): Control surface position failure.
2025-05-22T22:55:12.655Z,1747954512.655 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2025-05-22T22:55:17.082Z,1747954517.082 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:55:22.788Z,1747954522.788 [SBIT](CRITICAL): SBIT FAILED
2025-05-22T22:55:22.788Z,1747954522.788 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-05-22T22:55:22.790Z,1747954522.790 [SBIT](IMPORTANT): CBIT.gf24Offset=173 microampere;

2025-05-22T22:55:22.790Z,1747954522.790 [SBIT](IMPORTANT): CBIT.runNavErrorCritical=0 bool;

2025-05-22T22:55:22.790Z,1747954522.790 [SBIT](IMPORTANT): DAT.convertPhaseDataToDirection=0 bool;

2025-05-22T22:55:22.793Z,1747954522.793 [SBIT](IMPORTANT): DockingStepper.closedValue=16450 count;

2025-05-22T22:55:22.793Z,1747954522.793 [SBIT](IMPORTANT): DockingStepper.slideValue=10700 count;

2025-05-22T22:55:22.793Z,1747954522.793 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2025-05-22T22:55:22.793Z,1747954522.793 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=20 ampere_hour;

2025-05-22T22:55:22.793Z,1747954522.793 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=12 volt;

2025-05-22T22:55:22.793Z,1747954522.793 [SBIT](IMPORTANT): RudderServo.offsetAngle=0.2 degree;

2025-05-22T22:55:22.793Z,1747954522.793 [SBIT](IMPORTANT): TrnDataBridge.loadAtStartup=1 bool;

2025-05-22T22:55:22.793Z,1747954522.793 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=200 cubic_centimeter;

2025-05-22T22:55:22.793Z,1747954522.793 [SBIT](IMPORTANT): VerticalControl.massDefault=20.377216 millimeter;

2025-05-22T22:55:22.794Z,1747954522.794 [SBIT](IMPORTANT): VerticalControl.surfaceThreshold=0.5 meter;

2025-05-22T22:55:22.798Z,1747954522.798 [CommandExec](FAULT): Scheduling is paused
2025-05-22T22:55:22.799Z,1747954522.799 [CBIT](INFO): Critical error at 20250522T225522
2025-05-22T22:55:22.799Z,1747954522.799 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2025-05-22T22:55:23.189Z,1747954523.189 [MissionManager](IMPORTANT): Started mission DefaultTankUndock
2025-05-22T22:55:23.206Z,1747954523.206 [DefaultTankUndock] Running Loop=1
2025-05-22T22:55:23.206Z,1747954523.206 [DefaultTankUndock](DEBUG): Aggregate::initialize DefaultTankUndock
2025-05-22T22:55:23.206Z,1747954523.206 [DefaultTankUndock:B.GoToSurface] Running Loop=1
2025-05-22T22:55:23.206Z,1747954523.206 [DefaultTankUndock:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-05-22T22:55:23.207Z,1747954523.207 [DefaultTankUndock:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-05-22T22:55:23.207Z,1747954523.207 [DefaultTankUndock:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-05-22T22:55:23.207Z,1747954523.207 [DefaultTankUndock:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-05-22T22:55:23.208Z,1747954523.208 [DefaultTankUndock:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-05-22T22:55:23.208Z,1747954523.208 [DefaultTankUndock:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-05-22T22:55:23.208Z,1747954523.208 [DefaultTankUndock:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-05-22T22:55:23.209Z,1747954523.209 [DefaultTankUndock:A.Wait] Running Loop=1
2025-05-22T22:55:23.209Z,1747954523.209 [DefaultTankUndock:A.Wait](DEBUG): Initialize Wait Component.
2025-05-22T22:55:25.562Z,1747954525.562 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:55:34.022Z,1747954534.022 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:55:36.528Z,1747954536.528 [DefaultTankUndock:A.Wait](INFO): Done Waiting.
2025-05-22T22:55:36.528Z,1747954536.528 [DefaultTankUndock:A.Wait] Stopped
2025-05-22T22:55:36.528Z,1747954536.528 [DefaultTankUndock:A.Wait](DEBUG): Uninitialize Wait Component.
2025-05-22T22:55:36.937Z,1747954536.937 [DefaultTankUndock:C.Execute] Running Loop=1
2025-05-22T22:55:37.351Z,1747954537.351 [DefaultTankUndock:C.Execute](INFO): Executing command dock arm
2025-05-22T22:55:37.351Z,1747954537.351 [DefaultTankUndock:C.Execute] Stopped
2025-05-22T22:55:37.351Z,1747954537.351 [DefaultTankUndock:CheckIn] Running Loop=1
2025-05-22T22:55:37.351Z,1747954537.351 [DefaultTankUndock:CheckIn](DEBUG): Aggregate::initialize DefaultTankUndock:CheckIn
2025-05-22T22:55:37.351Z,1747954537.351 [DefaultTankUndock:CheckIn:Read_GPS] Running Loop=1
2025-05-22T22:55:37.383Z,1747954537.383 [CommandExec](IMPORTANT): got command docking arm
2025-05-22T22:55:37.383Z,1747954537.383 [CommandExec](IMPORTANT): Switching docking module to ARM.
2025-05-22T22:55:37.787Z,1747954537.787 [DefaultTankUndock:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-05-22T22:55:38.155Z,1747954538.155 [DockingStepper](INFO): Resume
2025-05-22T22:55:38.560Z,1747954538.560 [DockingStepper](INFO): Resuming
2025-05-22T22:55:38.974Z,1747954538.974 [DockingStepper](INFO): Start
2025-05-22T22:55:38.974Z,1747954538.974 [DockingStepper](DEBUG): Initializing EZServoServo.
2025-05-22T22:55:38.974Z,1747954538.974 [DockingStepper](DEBUG): Initializing DockingStepper.
2025-05-22T22:55:39.465Z,1747954539.465 [DockingStepper](INFO): Starting
2025-05-22T22:55:39.793Z,1747954539.793 [DockingStepper](INFO): Starting
2025-05-22T22:55:40.173Z,1747954540.173 [DockingStepper](INFO): Starting
2025-05-22T22:55:40.235Z,1747954540.235 [DockingStepper](INFO): Init string: /1z16368aE13561N3m50
2025-05-22T22:55:40.565Z,1747954540.565 [DockingStepper](INFO): Starting
2025-05-22T22:55:40.960Z,1747954540.960 [DockingStepper](INFO): Starting
2025-05-22T22:55:41.375Z,1747954541.375 [DockingStepper](INFO): Starting
2025-05-22T22:55:41.804Z,1747954541.804 [DockingStepper](INFO): Starting
2025-05-22T22:55:41.842Z,1747954541.842 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@
2025-05-22T22:55:42.182Z,1747954542.182 [DockingStepper](INFO): Starting
2025-05-22T22:55:42.597Z,1747954542.597 [DockingStepper](INFO): Changing to mode: 2
2025-05-22T22:55:42.597Z,1747954542.597 [DockingStepper](INFO): Armed mode.
2025-05-22T22:55:42.635Z,1747954542.635 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:55:42.666Z,1747954542.666 [DockingStepper](INFO): Commanding negative:54599
2025-05-22T22:55:42.700Z,1747954542.700 [Reporter](INFO): Onboard.platform_average_current 0.120210 mA
2025-05-22T22:55:43.627Z,1747954543.627 [CommandExec](IMPORTANT): got command strobe off
2025-05-22T22:55:43.627Z,1747954543.627 [CommandExec](IMPORTANT): Deactivating strobe
2025-05-22T22:55:47.459Z,1747954547.459 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-05-22T22:55:47.459Z,1747954547.459 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-22T22:55:47.531Z,1747954547.531 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-22T22:55:47.939Z,1747954547.939 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-22T22:55:47.939Z,1747954547.939 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-05-22T22:55:52.282Z,1747954552.282 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:56:00.846Z,1747954560.846 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:56:09.550Z,1747954569.550 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:56:18.198Z,1747954578.198 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:56:26.746Z,1747954586.746 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:56:35.350Z,1747954595.350 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:56:42.827Z,1747954602.827 [Reporter](INFO): Onboard.platform_average_current 336.319153 mA
2025-05-22T22:56:44.026Z,1747954604.026 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:56:52.602Z,1747954612.602 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:57:01.254Z,1747954621.254 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:57:09.858Z,1747954629.858 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:57:18.542Z,1747954638.542 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:57:27.166Z,1747954647.166 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:57:35.798Z,1747954655.798 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:57:42.668Z,1747954662.668 [Reporter](INFO): Onboard.platform_average_current 931.817261 mA
2025-05-22T22:57:44.390Z,1747954664.390 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:57:53.050Z,1747954673.050 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:58:02.830Z,1747954682.830 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:58:11.640Z,1747954691.640 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:58:20.350Z,1747954700.350 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:58:29.010Z,1747954709.010 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:58:31.051Z,1747954711.051 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-05-22T22:58:37.810Z,1747954717.810 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:58:42.844Z,1747954722.844 [Reporter](INFO): Onboard.platform_average_current 852.158386 mA
2025-05-22T22:58:46.443Z,1747954726.443 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:58:48.509Z,1747954728.509 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2025-05-22T22:58:48.509Z,1747954728.509 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-22T22:58:48.589Z,1747954728.589 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-22T22:58:48.923Z,1747954728.923 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-22T22:58:48.923Z,1747954728.923 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2025-05-22T22:58:55.130Z,1747954735.130 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:59:03.818Z,1747954743.818 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:59:07.808Z,1747954747.808 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,25052215002202,35.0, +22.1,   0.0,10
2025-05-22T22:59:12.338Z,1747954752.338 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:59:21.118Z,1747954761.118 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:59:28.048Z,1747954768.048 [RDI_Pathfinder](ERROR): only read 3 of 4 data items for beam range. Device response is::RA,  0.00,   0.00,   0.00,   0.00, 

2025-05-22T22:59:29.710Z,1747954769.710 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:59:38.266Z,1747954778.266 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:59:42.804Z,1747954782.804 [Reporter](INFO): Onboard.platform_average_current 849.072876 mA
2025-05-22T22:59:47.070Z,1747954787.070 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T22:59:55.722Z,1747954795.722 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T23:00:04.230Z,1747954804.230 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T23:00:08.063Z,1747954808.063 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. Device response is::WD,        +0.00,        +0.   +0.00,  20.00,430.00
2025-05-22T23:00:12.970Z,1747954812.970 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T23:00:21.636Z,1747954821.636 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T23:00:30.234Z,1747954830.234 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T23:00:37.532Z,1747954837.532 [DefaultTankUndock:CheckIn:Read_GPS](INFO): Timed out from 2025-05-22T22:55:37.4Z
2025-05-22T23:00:37.532Z,1747954837.532 [DefaultTankUndock:CheckIn:Read_GPS] Stopped
2025-05-22T23:00:37.532Z,1747954837.532 [DefaultTankUndock:CheckIn:Read_Iridium] Running Loop=1
2025-05-22T23:00:37.952Z,1747954837.952 [DefaultTankUndock:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-05-22T23:00:42.844Z,1747954842.844 [Reporter](INFO): Onboard.platform_average_current 851.813110 mA
2025-05-22T23:00:47.967Z,1747954847.967 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250515T173521/Courier0004.lzma
2025-05-22T23:00:48.968Z,1747954848.968 [DataOverHttps](INFO): Moved sent file to Logs/20250515T173521/Courier0004.lzma.bak
2025-05-22T23:00:48.968Z,1747954848.968 [DataOverHttps](INFO): SBD MOMSN=24958098
2025-05-22T23:01:07.898Z,1747954867.898 [DataOverHttps](INFO): Sending 186 bytes from file Logs/20250522T225235/Courier0000.lzma
2025-05-22T23:01:08.900Z,1747954868.900 [DataOverHttps](INFO): Moved sent file to Logs/20250522T225235/Courier0000.lzma.bak
2025-05-22T23:01:08.900Z,1747954868.900 [DataOverHttps](INFO): SBD MOMSN=24958104
2025-05-22T23:01:37.512Z,1747954897.512 [DataOverHttps](INFO): Sending 278 bytes from file Logs/20250515T173521/Express0005.lzma
2025-05-22T23:01:38.512Z,1747954898.512 [DataOverHttps](INFO): Moved sent file to Logs/20250515T173521/Express0005.lzma.bak
2025-05-22T23:01:38.512Z,1747954898.512 [DataOverHttps](INFO): SBD MOMSN=24958108
2025-05-22T23:01:42.881Z,1747954902.881 [Reporter](INFO): Onboard.platform_average_current 852.622925 mA
2025-05-22T23:01:49.542Z,1747954909.542 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2025-05-22T23:01:49.542Z,1747954909.542 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-22T23:01:49.622Z,1747954909.622 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-22T23:01:50.075Z,1747954910.075 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-22T23:01:50.075Z,1747954910.075 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2025-05-22T23:02:13.951Z,1747954933.951 [DataOverHttps](INFO): Sending 1356 bytes from file Logs/20250522T225235/Express0001.lzma
2025-05-22T23:02:14.952Z,1747954934.952 [DataOverHttps](INFO): Moved sent file to Logs/20250522T225235/Express0001.lzma.bak
2025-05-22T23:02:14.952Z,1747954934.952 [DataOverHttps](INFO): SBD MOMSN=24958121
2025-05-22T23:02:22.303Z,1747954942.303 [DefaultTankUndock:CheckIn:Read_Iridium] Stopped
2025-05-22T23:02:22.303Z,1747954942.303 [DefaultTankUndock:CheckIn:C.Wait] Running Loop=1
2025-05-22T23:02:22.303Z,1747954942.303 [DefaultTankUndock:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-05-22T23:02:43.012Z,1747954963.012 [Reporter](INFO): Onboard.platform_average_current 869.078735 mA
2025-05-22T23:02:50.522Z,1747954970.522 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T23:02:59.170Z,1747954979.170 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T23:03:07.898Z,1747954987.898 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T23:03:18.494Z,1747954998.494 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T23:03:24.080Z,1747955004.080 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for water mass range. Device response is::WD,        +0.00,        +0.00,    23.99
2025-05-22T23:03:27.026Z,1747955007.026 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T23:03:28.500Z,1747955008.500 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-05-22T23:03:28.500Z,1747955008.500 [NAL9602] Data Fault, FailCount= 1
2025-05-22T23:03:28.500Z,1747955008.500 [NAL9602](ERROR): Data Fault
2025-05-22T23:03:28.583Z,1747955008.583 [CBIT](ERROR): Data Fault in component: NAL9602
2025-05-22T23:03:28.908Z,1747955008.908 [NAL9602](INFO): Powering down
2025-05-22T23:03:29.859Z,1747955009.859 [CBIT](INFO): Clearing failed state for component NAL9602
2025-05-22T23:03:29.859Z,1747955009.859 [NAL9602] No Fault, FailCount= 1
2025-05-22T23:03:35.718Z,1747955015.718 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T23:03:43.117Z,1747955023.117 [Reporter](INFO): Onboard.platform_average_current 841.513000 mA
2025-05-22T23:03:44.312Z,1747955024.312 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T23:03:52.886Z,1747955032.886 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T23:03:59.216Z,1747955039.216 [NAL9602](INFO): Powering up NAL9602
2025-05-22T23:04:01.598Z,1747955041.598 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T23:04:10.112Z,1747955050.112 [NAL9602](INFO): NAL9602 initialized
2025-05-22T23:04:10.210Z,1747955050.210 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T23:04:18.818Z,1747955058.818 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T23:04:27.462Z,1747955067.462 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T23:04:37.106Z,1747955077.106 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T23:04:43.316Z,1747955083.316 [Reporter](INFO): Onboard.platform_average_current 871.958313 mA
2025-05-22T23:04:45.734Z,1747955085.734 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T23:04:50.542Z,1747955090.542 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2025-05-22T23:04:50.542Z,1747955090.542 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-05-22T23:04:50.607Z,1747955090.607 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-05-22T23:04:51.015Z,1747955091.015 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-05-22T23:04:51.015Z,1747955091.015 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2025-05-22T23:04:54.394Z,1747955094.394 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T23:05:03.022Z,1747955103.022 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T23:05:11.648Z,1747955111.648 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T23:05:20.190Z,1747955120.190 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T23:05:28.894Z,1747955128.894 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T23:05:37.518Z,1747955137.518 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T23:05:43.119Z,1747955143.119 [Reporter](INFO): Onboard.platform_average_current 621.321960 mA
2025-05-22T23:05:46.210Z,1747955146.210 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T23:05:54.854Z,1747955154.854 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T23:06:03.494Z,1747955163.494 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T23:06:12.147Z,1747955172.147 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T23:06:20.726Z,1747955180.726 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T23:06:29.346Z,1747955189.346 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T23:06:38.058Z,1747955198.058 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T23:06:43.327Z,1747955203.327 [Reporter](INFO): Onboard.platform_average_current 849.865967 mA
2025-05-22T23:06:46.618Z,1747955206.618 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T23:06:53.746Z,1747955213.746 [CommandExec](IMPORTANT): got command quit
2025-05-22T23:06:54.750Z,1747955214.750 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-22T23:06:54.750Z,1747955214.750 [CommandExec](INFO): Uninitializing the command executive.
2025-05-22T23:06:54.750Z,1747955214.750 [CommandExec](INFO): Uninitializing the command scheduler.
2025-05-22T23:06:54.750Z,1747955214.750 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-05-22T23:06:54.823Z,1747955214.823 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2025-05-22T23:06:54.823Z,1747955214.823 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-05-22T23:06:54.823Z,1747955214.823 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-05-22T23:06:54.824Z,1747955214.824 [NavChartDb](INFO): Join timeout helper Thread ID is 2268
2025-05-22T23:06:55.202Z,1747955215.202 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-22T23:06:55.202Z,1747955215.202 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-05-22T23:06:55.203Z,1747955215.203 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2025-05-22T23:06:55.203Z,1747955215.203 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2025-05-22T23:06:55.203Z,1747955215.203 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 2269
2025-05-22T23:06:55.242Z,1747955215.242 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-05-22T23:06:55.526Z,1747955215.526 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-22T23:06:55.526Z,1747955215.526 [WetLabsUBAT](INFO): Powering down
2025-05-22T23:06:55.527Z,1747955215.527 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2025-05-22T23:06:55.546Z,1747955215.546 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-05-22T23:06:55.546Z,1747955215.546 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-05-22T23:06:55.546Z,1747955215.546 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 2270
2025-05-22T23:06:55.562Z,1747955215.562 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-22T23:06:55.562Z,1747955215.562 [WetLabsBB2FL](INFO): Powering down
2025-05-22T23:06:55.563Z,1747955215.563 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-05-22T23:06:55.566Z,1747955215.566 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-05-22T23:06:55.566Z,1747955215.566 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-05-22T23:06:55.566Z,1747955215.566 [CTD_Seabird](INFO): Join timeout helper Thread ID is 2271
2025-05-22T23:06:55.982Z,1747955215.982 [CTD_Seabird](INFO): Powering down
2025-05-22T23:06:55.998Z,1747955215.998 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-22T23:06:55.998Z,1747955215.998 [CTD_Seabird](INFO): Powering down
2025-05-22T23:06:56.010Z,1747955216.010 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-05-22T23:06:56.018Z,1747955216.018 [ComponentRegistry](INFO): Shutting down TrnDataBridge ThreadHandler
2025-05-22T23:06:56.018Z,1747955216.018 [TrnDataBridge ThreadHandler](INFO): Thread cancelled.
2025-05-22T23:06:56.018Z,1747955216.018 [TrnDataBridge](INFO): Join timeout helper Thread ID is 2272
2025-05-22T23:06:56.311Z,1747955216.311 [TrnDataBridge ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-22T23:06:56.311Z,1747955216.311 [TrnDataBridge ThreadHandler](INFO): Thread cancelled.
2025-05-22T23:06:56.319Z,1747955216.319 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-05-22T23:06:56.319Z,1747955216.319 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-05-22T23:06:56.319Z,1747955216.319 [Radio_Surface](INFO): Join timeout helper Thread ID is 2273
2025-05-22T23:06:56.326Z,1747955216.326 [Radio_Surface](INFO): Powering down
2025-05-22T23:06:56.327Z,1747955216.327 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-22T23:06:56.327Z,1747955216.327 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-05-22T23:06:56.338Z,1747955216.338 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-05-22T23:06:56.339Z,1747955216.339 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-05-22T23:06:56.339Z,1747955216.339 [Onboard](INFO): Join timeout helper Thread ID is 2274
2025-05-22T23:06:57.994Z,1747955217.994 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-22T23:06:57.994Z,1747955217.994 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-05-22T23:06:58.006Z,1747955218.006 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-05-22T23:06:58.007Z,1747955218.007 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-05-22T23:06:58.007Z,1747955218.007 [DataOverHttps](INFO): Join timeout helper Thread ID is 2275
2025-05-22T23:06:58.260Z,1747955218.260 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-22T23:06:58.265Z,1747955218.265 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-05-22T23:06:58.282Z,1747955218.282 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2025-05-22T23:06:58.283Z,1747955218.283 [DAT ThreadHandler](INFO): Thread cancelled.
2025-05-22T23:06:58.283Z,1747955218.283 [DAT](INFO): Join timeout helper Thread ID is 2276
2025-05-22T23:06:58.514Z,1747955218.514 [DAT](INFO): Powering down
2025-05-22T23:06:58.515Z,1747955218.515 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-22T23:06:58.515Z,1747955218.515 [DAT](INFO): Powering down
2025-05-22T23:06:58.516Z,1747955218.516 [DAT ThreadHandler](INFO): Thread cancelled.
2025-05-22T23:06:58.526Z,1747955218.526 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2025-05-22T23:06:58.527Z,1747955218.527 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-05-22T23:06:58.527Z,1747955218.527 [BackseatComponent](INFO): Join timeout helper Thread ID is 2277
2025-05-22T23:06:58.671Z,1747955218.671 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-22T23:06:58.672Z,1747955218.672 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-05-22T23:06:58.682Z,1747955218.682 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-05-22T23:06:58.683Z,1747955218.683 [logger ThreadHandler](INFO): Thread cancelled.
2025-05-22T23:06:58.683Z,1747955218.683 [logger](INFO): Join timeout helper Thread ID is 2278
2025-05-22T23:06:58.698Z,1747955218.698 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-22T23:06:58.698Z,1747955218.698 [logger ThreadHandler](INFO): Thread cancelled.
2025-05-22T23:06:58.702Z,1747955218.702 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-05-22T23:06:58.703Z,1747955218.703 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-05-22T23:06:58.703Z,1747955218.703 [CommandLine](INFO): Join timeout helper Thread ID is 2279
2025-05-22T23:06:58.790Z,1747955218.790 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-22T23:06:58.790Z,1747955218.790 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-05-22T23:06:58.803Z,1747955218.803 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-05-22T23:06:58.803Z,1747955218.803 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-05-22T23:06:58.803Z,1747955218.803 [CommandExec](INFO): Join timeout helper Thread ID is 2280
2025-05-22T23:06:58.804Z,1747955218.804 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-05-22T23:06:58.804Z,1747955218.804 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-05-22T23:06:58.805Z,1747955218.805 [controlThread](INFO): Join timeout helper Thread ID is 2281
2025-05-22T23:06:58.970Z,1747955218.970 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-05-22T23:06:58.970Z,1747955218.970 [controlThread](DEBUG): Uninitializing ControlThread
2025-05-22T23:06:58.971Z,1747955218.971 [AHRS_M2](INFO): Powering down
2025-05-22T23:06:58.973Z,1747955218.973 [NAL9602](INFO): Powering down
2025-05-22T23:06:58.974Z,1747955218.974 [RDI_Pathfinder](INFO): Powering down
2025-05-22T23:06:58.975Z,1747955218.975 [Sonardyne_Nano](INFO): Powering down
2025-05-22T23:06:58.976Z,1747955218.976 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-05-22T23:06:58.976Z,1747955218.976 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-05-22T23:06:58.977Z,1747955218.977 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-05-22T23:06:58.977Z,1747955218.977 [MissionManager](INFO): Uninitializing Mission DefaultTankUndock
2025-05-22T23:06:58.977Z,1747955218.977 [DefaultTankUndock] Stopped
2025-05-22T23:06:58.977Z,1747955218.977 [DefaultTankUndock](DEBUG): Aggregate::uninitialize DefaultTankUndock
2025-05-22T23:06:58.977Z,1747955218.977 [DefaultTankUndock:B.GoToSurface] Stopped
2025-05-22T23:06:58.977Z,1747955218.977 [DefaultTankUndock:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-05-22T23:06:58.978Z,1747955218.978 [DefaultTankUndock:CheckIn] Stopped
2025-05-22T23:06:58.978Z,1747955218.978 [DefaultTankUndock:CheckIn](DEBUG): Aggregate::uninitialize DefaultTankUndock:CheckIn
2025-05-22T23:06:58.978Z,1747955218.978 [DefaultTankUndock:CheckIn:C.Wait] Stopped
2025-05-22T23:06:58.978Z,1747955218.978 [DefaultTankUndock:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-05-22T23:06:58.980Z,1747955218.980 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-05-22T23:06:58.981Z,1747955218.981 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-05-22T23:06:58.981Z,1747955218.981 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-05-22T23:06:58.981Z,1747955218.981 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-05-22T23:06:58.981Z,1747955218.981 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-05-22T23:06:58.982Z,1747955218.982 [BuoyancyServo](INFO): Powering down
2025-05-22T23:06:58.994Z,1747955218.994 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-05-22T23:06:58.994Z,1747955218.994 [ElevatorServo](INFO): Powering down
2025-05-22T23:06:58.995Z,1747955218.995 [DockingStepper](DEBUG): Uninitialize Docking Stepper.
2025-05-22T23:06:58.995Z,1747955218.995 [DockingStepper](INFO): Powering down
2025-05-22T23:06:58.996Z,1747955218.996 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-05-22T23:06:58.996Z,1747955218.996 [MassServo](INFO): Powering down
2025-05-22T23:06:58.997Z,1747955218.997 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-05-22T23:06:58.997Z,1747955218.997 [RudderServo](INFO): Powering down
2025-05-22T23:06:58.998Z,1747955218.998 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2025-05-22T23:06:58.998Z,1747955218.998 [ThrusterServo](INFO): Powering down
2025-05-22T23:06:58.999Z,1747955218.999 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-05-22T23:06:58.999Z,1747955218.999 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-05-22T23:06:58.999Z,1747955218.999 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-05-22T23:06:58.999Z,1747955218.999 [CBIT](DEBUG): Powering off loads.
2025-05-22T23:06:59.010Z,1747955219.010 [CBIT](DEBUG): Disabling WDT.
2025-05-22T23:06:59.022Z,1747955219.022 [CBIT](DEBUG): Opening all GF detection circuits.
2025-05-22T23:06:59.023Z,1747955219.023 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-05-22T23:06:59.100Z,1747955219.100 [TrnDataBridge ThreadHandler](INFO): Thread cancelled.
2025-05-22T23:06:59.109Z,1747955219.109 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-05-22T23:06:59.111Z,1747955219.111 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-05-22T23:06:59.117Z,1747955219.117 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-05-22T23:06:59.123Z,1747955219.123 [DAT ThreadHandler](INFO): Thread cancelled.
2025-05-22T23:06:59.164Z,1747955219.164 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-05-22T23:06:59.225Z,1747955219.225 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2025-05-22T23:06:59.227Z,1747955219.227 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-05-22T23:06:59.232Z,1747955219.232 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-05-22T23:06:59.240Z,1747955219.240 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-05-22T23:06:59.303Z,1747955219.303 [logger ThreadHandler](INFO): Thread cancelled.