2025-04-10T22:46:20.400Z,1744325180.400 [Supervisor](DEBUG): Initializing supervisor.
2025-04-10T22:46:20.405Z,1744325180.405 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-04-10T22:46:20.405Z,1744325180.405 [SyncHandler](INFO): Protected caller Thread ID is 5663
2025-04-10T22:46:20.406Z,1744325180.406 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-04-10T22:46:20.407Z,1744325180.407 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-04-10T22:46:20.407Z,1744325180.407 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5664
2025-04-10T22:46:20.411Z,1744325180.411 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-04-10T22:46:20.428Z,1744325180.428 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-04-10T22:46:20.429Z,1744325180.429 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-04-10T22:46:20.429Z,1744325180.429 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 5665
2025-04-10T22:46:20.433Z,1744325180.433 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-04-10T22:46:20.434Z,1744325180.434 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-04-10T22:46:20.435Z,1744325180.435 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5666
2025-04-10T22:46:20.437Z,1744325180.437 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-04-10T22:46:20.438Z,1744325180.438 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-04-10T22:46:20.438Z,1744325180.438 [logger ThreadHandler](INFO): Protected caller Thread ID is 5667
2025-04-10T22:46:20.441Z,1744325180.441 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-04-10T22:46:20.442Z,1744325180.442 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-04-10T22:46:20.443Z,1744325180.443 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-04-10T22:46:20.551Z,1744325180.551 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-04-10T22:46:20.551Z,1744325180.551 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-04-10T22:46:21.312Z,1744325181.312 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-04-10T22:46:21.313Z,1744325181.313 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-04-10T22:46:21.614Z,1744325181.614 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-04-10T22:46:21.614Z,1744325181.614 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-04-10T22:46:21.713Z,1744325181.713 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-04-10T22:46:21.853Z,1744325181.853 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-04-10T22:46:21.853Z,1744325181.853 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-04-10T22:46:21.945Z,1744325181.945 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-04-10T22:46:22.062Z,1744325182.062 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-04-10T22:46:22.062Z,1744325182.062 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-04-10T22:46:22.461Z,1744325182.461 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-04-10T22:46:22.462Z,1744325182.462 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-04-10T22:46:22.992Z,1744325182.992 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-04-10T22:46:22.993Z,1744325182.993 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-04-10T22:46:23.218Z,1744325183.218 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-04-10T22:46:23.219Z,1744325183.219 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-04-10T22:46:23.409Z,1744325183.409 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-04-10T22:46:23.410Z,1744325183.410 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-04-10T22:46:23.954Z,1744325183.954 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-04-10T22:46:23.955Z,1744325183.955 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-04-10T22:46:24.256Z,1744325184.256 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-04-10T22:46:24.256Z,1744325184.256 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-04-10T22:46:24.497Z,1744325184.497 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-04-10T22:46:24.497Z,1744325184.497 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-04-10T22:46:24.878Z,1744325184.878 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-04-10T22:46:24.878Z,1744325184.878 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-04-10T22:46:25.255Z,1744325185.255 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-04-10T22:46:25.257Z,1744325185.257 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2025-04-10T22:46:25.258Z,1744325185.258 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2025-04-10T22:46:25.564Z,1744325185.564 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2025-04-10T22:46:25.968Z,1744325185.968 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Dock.cfg
2025-04-10T22:46:26.083Z,1744325186.083 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/workSite.cfg
2025-04-10T22:46:26.188Z,1744325186.188 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2025-04-10T22:46:26.306Z,1744325186.306 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2025-04-10T22:46:26.402Z,1744325186.402 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2025-04-10T22:46:26.507Z,1744325186.507 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2025-04-10T22:46:26.628Z,1744325186.628 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2025-04-10T22:46:26.900Z,1744325186.900 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-04-10T22:46:26.900Z,1744325186.900 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2025-04-10T22:46:26.996Z,1744325186.996 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2025-04-10T22:46:27.099Z,1744325187.099 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2025-04-10T22:46:27.252Z,1744325187.252 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2025-04-10T22:46:27.364Z,1744325187.364 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2025-04-10T22:46:27.364Z,1744325187.364 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-04-10T22:46:27.374Z,1744325187.374 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-04-10T22:46:27.507Z,1744325187.507 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-04-10T22:46:27.507Z,1744325187.507 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-04-10T22:46:27.559Z,1744325187.559 [VerticalControl](DEBUG): Construct VerticalControl.
2025-04-10T22:46:27.616Z,1744325187.616 [VerticalControl] Loaded
2025-04-10T22:46:27.617Z,1744325187.617 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-04-10T22:46:27.619Z,1744325187.619 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-04-10T22:46:27.666Z,1744325187.666 [HorizontalControl] Loaded
2025-04-10T22:46:27.667Z,1744325187.667 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-04-10T22:46:27.669Z,1744325187.669 [SpeedControl](DEBUG): Construct SpeedControl.
2025-04-10T22:46:27.672Z,1744325187.672 [SpeedControl] Loaded
2025-04-10T22:46:27.672Z,1744325187.672 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-04-10T22:46:27.675Z,1744325187.675 [LoopControl](DEBUG): Construct LoopControl.
2025-04-10T22:46:27.675Z,1744325187.675 [LoopControl] Loaded
2025-04-10T22:46:27.675Z,1744325187.675 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-04-10T22:46:27.676Z,1744325187.676 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-04-10T22:46:27.676Z,1744325187.676 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-04-10T22:46:27.726Z,1744325187.726 [DepthRateCalculator] Loaded
2025-04-10T22:46:27.726Z,1744325187.726 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-04-10T22:46:27.731Z,1744325187.731 [PitchRateCalculator] Loaded
2025-04-10T22:46:27.731Z,1744325187.731 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-04-10T22:46:27.740Z,1744325187.740 [SpeedCalculator] Loaded
2025-04-10T22:46:27.741Z,1744325187.741 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-04-10T22:46:27.745Z,1744325187.745 [YawRateCalculator] Loaded
2025-04-10T22:46:27.745Z,1744325187.745 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-04-10T22:46:27.763Z,1744325187.763 [ElevatorOffsetCalculator] Loaded
2025-04-10T22:46:27.763Z,1744325187.763 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-04-10T22:46:27.764Z,1744325187.764 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-04-10T22:46:27.764Z,1744325187.764 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-04-10T22:46:27.790Z,1744325187.790 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-04-10T22:46:27.791Z,1744325187.791 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-04-10T22:46:27.847Z,1744325187.847 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-04-10T22:46:27.847Z,1744325187.847 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-04-10T22:46:28.184Z,1744325188.184 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-04-10T22:46:28.185Z,1744325188.185 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-04-10T22:46:28.355Z,1744325188.355 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-04-10T22:46:28.355Z,1744325188.355 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-04-10T22:46:28.812Z,1744325188.812 [AHRS_M2] Loaded
2025-04-10T22:46:28.812Z,1744325188.812 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-04-10T22:46:28.851Z,1744325188.851 [BackseatComponent] Loaded
2025-04-10T22:46:28.851Z,1744325188.851 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-04-10T22:46:28.895Z,1744325188.895 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409E24E0
2025-04-10T22:46:28.896Z,1744325188.896 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 5756
2025-04-10T22:46:28.899Z,1744325188.899 [LcmUniversalReporter] Loaded
2025-04-10T22:46:28.899Z,1744325188.899 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-04-10T22:46:29.714Z,1744325189.714 [BPC1] Loaded
2025-04-10T22:46:29.715Z,1744325189.715 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-04-10T22:46:29.810Z,1744325189.810 [DAT] Loaded
2025-04-10T22:46:29.810Z,1744325189.810 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-04-10T22:46:29.811Z,1744325189.811 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A124E0
2025-04-10T22:46:29.811Z,1744325189.811 [DAT ThreadHandler](INFO): Protected caller Thread ID is 5757
2025-04-10T22:46:29.886Z,1744325189.886 [DataOverHttps] Loaded
2025-04-10T22:46:29.886Z,1744325189.886 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-04-10T22:46:29.887Z,1744325189.887 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A424E0
2025-04-10T22:46:29.887Z,1744325189.887 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5758
2025-04-10T22:46:29.910Z,1744325189.910 [Depth_Keller] Loaded
2025-04-10T22:46:29.910Z,1744325189.910 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-04-10T22:46:29.915Z,1744325189.915 [DropWeight] Loaded
2025-04-10T22:46:29.915Z,1744325189.915 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-04-10T22:46:29.979Z,1744325189.979 [NAL9602] Loaded
2025-04-10T22:46:29.979Z,1744325189.979 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-04-10T22:46:30.008Z,1744325190.008 [Onboard] Loaded
2025-04-10T22:46:30.008Z,1744325190.008 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-04-10T22:46:30.009Z,1744325190.009 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A724E0
2025-04-10T22:46:30.009Z,1744325190.009 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 5759
2025-04-10T22:46:30.023Z,1744325190.023 [Power24vConverter] Loaded
2025-04-10T22:46:30.023Z,1744325190.023 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-04-10T22:46:30.039Z,1744325190.039 [Radio_Surface] Loaded
2025-04-10T22:46:30.039Z,1744325190.039 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-04-10T22:46:30.040Z,1744325190.040 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40AA24E0
2025-04-10T22:46:30.040Z,1744325190.040 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5760
2025-04-10T22:46:30.087Z,1744325190.087 [RDI_Pathfinder] Loaded
2025-04-10T22:46:30.087Z,1744325190.087 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2025-04-10T22:46:30.102Z,1744325190.102 [Sonardyne_Nano] Loaded
2025-04-10T22:46:30.102Z,1744325190.102 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-04-10T22:46:30.165Z,1744325190.165 [TrnDataBridge] Loaded
2025-04-10T22:46:30.165Z,1744325190.165 [ComponentRegistry](DEBUG): Component "TrnDataBridge" handled in its own thread.
2025-04-10T22:46:30.166Z,1744325190.166 [TrnDataBridge ThreadHandler](DEBUG): Created PCaller Thread at 40AD24E0
2025-04-10T22:46:30.166Z,1744325190.166 [TrnDataBridge ThreadHandler](INFO): Protected caller Thread ID is 5761
2025-04-10T22:46:30.167Z,1744325190.167 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-04-10T22:46:30.167Z,1744325190.167 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-04-10T22:46:30.274Z,1744325190.274 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-04-10T22:46:30.274Z,1744325190.274 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-04-10T22:46:30.287Z,1744325190.287 [NavChart] Loaded
2025-04-10T22:46:30.288Z,1744325190.288 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-04-10T22:46:30.293Z,1744325190.293 [UniversalFixResidualReporter] Loaded
2025-04-10T22:46:30.294Z,1744325190.294 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-04-10T22:46:30.294Z,1744325190.294 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-04-10T22:46:30.295Z,1744325190.295 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-04-10T22:46:30.619Z,1744325190.619 [SBIT](DEBUG): Construct Startup Built In Test.
2025-04-10T22:46:30.628Z,1744325190.628 [SBIT] Loaded
2025-04-10T22:46:30.628Z,1744325190.628 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-04-10T22:46:30.631Z,1744325190.631 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-04-10T22:46:30.644Z,1744325190.644 [IBIT] Loaded
2025-04-10T22:46:30.645Z,1744325190.645 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-04-10T22:46:30.650Z,1744325190.650 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-04-10T22:46:30.752Z,1744325190.752 [CBIT] Loaded
2025-04-10T22:46:30.752Z,1744325190.752 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-04-10T22:46:30.752Z,1744325190.752 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-04-10T22:46:30.753Z,1744325190.753 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-04-10T22:46:30.883Z,1744325190.883 [BuoyancyServo] Loaded
2025-04-10T22:46:30.883Z,1744325190.883 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-04-10T22:46:30.906Z,1744325190.906 [ElevatorServo] Loaded
2025-04-10T22:46:30.907Z,1744325190.907 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-04-10T22:46:30.937Z,1744325190.937 [DockingStepper] Loaded
2025-04-10T22:46:30.937Z,1744325190.937 [ComponentRegistry](DEBUG): SyncComponent "DockingStepper" handled in the control thread.
2025-04-10T22:46:30.959Z,1744325190.959 [MassServo] Loaded
2025-04-10T22:46:30.959Z,1744325190.959 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-04-10T22:46:30.979Z,1744325190.979 [RudderServo] Loaded
2025-04-10T22:46:30.979Z,1744325190.979 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-04-10T22:46:30.998Z,1744325190.998 [ThrusterServo] Loaded
2025-04-10T22:46:30.998Z,1744325190.998 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2025-04-10T22:46:30.999Z,1744325190.999 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-04-10T22:46:30.999Z,1744325190.999 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-04-10T22:46:31.009Z,1744325191.009 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-04-10T22:46:31.010Z,1744325191.010 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-04-10T22:46:31.191Z,1744325191.191 [CTD_Seabird] Loaded
2025-04-10T22:46:31.191Z,1744325191.191 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-04-10T22:46:31.215Z,1744325191.215 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40C1F4E0
2025-04-10T22:46:31.215Z,1744325191.215 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 5762
2025-04-10T22:46:31.238Z,1744325191.238 [PAR_Licor] Loaded
2025-04-10T22:46:31.238Z,1744325191.238 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-04-10T22:46:31.282Z,1744325191.282 [WetLabsBB2FL] Loaded
2025-04-10T22:46:31.283Z,1744325191.283 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-04-10T22:46:31.283Z,1744325191.283 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C4F4E0
2025-04-10T22:46:31.284Z,1744325191.284 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5763
2025-04-10T22:46:31.306Z,1744325191.306 [WetLabsUBAT] Loaded
2025-04-10T22:46:31.306Z,1744325191.306 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2025-04-10T22:46:31.307Z,1744325191.307 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40C7F4E0
2025-04-10T22:46:31.307Z,1744325191.307 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 5764
2025-04-10T22:46:31.308Z,1744325191.308 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-04-10T22:46:31.314Z,1744325191.314 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-04-10T22:46:31.317Z,1744325191.317 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-04-10T22:46:31.328Z,1744325191.328 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-04-10T22:46:31.329Z,1744325191.329 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40CAF4E0
2025-04-10T22:46:31.330Z,1744325191.330 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5765
2025-04-10T22:46:31.334Z,1744325191.334 [Supervisor](INFO): Main Thread ID is 5662
2025-04-10T22:46:31.334Z,1744325191.334 [Supervisor](DEBUG): Running supervisor.
2025-04-10T22:46:31.335Z,1744325191.335 [CommandExec ThreadHandler](INFO): Handler Thread ID is 5766
2025-04-10T22:46:31.335Z,1744325191.335 [CommandExec](INFO): Initializing the command executive.
2025-04-10T22:46:31.337Z,1744325191.337 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5767
2025-04-10T22:46:31.339Z,1744325191.339 [controlThread ThreadHandler](INFO): Handler Thread ID is 5768
2025-04-10T22:46:31.340Z,1744325191.340 [controlThread](DEBUG): Initializing ControlThread
2025-04-10T22:46:31.340Z,1744325191.340 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-04-10T22:46:31.342Z,1744325191.342 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-04-10T22:46:31.343Z,1744325191.343 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-04-10T22:46:31.343Z,1744325191.343 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-04-10T22:46:31.344Z,1744325191.344 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-04-10T22:46:31.344Z,1744325191.344 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-04-10T22:46:31.344Z,1744325191.344 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-04-10T22:46:31.345Z,1744325191.345 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-04-10T22:46:31.345Z,1744325191.345 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-04-10T22:46:31.352Z,1744325191.352 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-04-10T22:46:31.353Z,1744325191.353 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-04-10T22:46:31.353Z,1744325191.353 [SBIT](INFO): Initialize SBIT Component.
2025-04-10T22:46:31.353Z,1744325191.353 [SBIT](IMPORTANT): git: 2025-01-23
2025-04-10T22:46:31.354Z,1744325191.354 [SBIT](INFO): git hash: e941ed7ca4296489cfe1bf7015177657c0080d4b
2025-04-10T22:46:31.354Z,1744325191.354 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-04-10T22:46:31.355Z,1744325191.355 [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-04-10T22:46:31.356Z,1744325191.356 [SBIT](INFO): Beginning SBIT in 101.000000 seconds.
2025-04-10T22:46:31.357Z,1744325191.357 [IBIT](INFO): Initialize IBIT Component.
2025-04-10T22:46:31.358Z,1744325191.358 [CBIT](DEBUG): Initialize CBIT Component.
2025-04-10T22:46:31.359Z,1744325191.359 [logger ThreadHandler](INFO): Handler Thread ID is 5769
2025-04-10T22:46:31.369Z,1744325191.369 [CBIT](DEBUG): Initialized mux pins.
2025-04-10T22:46:31.370Z,1744325191.370 [CBIT](DEBUG): Initializing the watchdog timer.
2025-04-10T22:46:31.378Z,1744325191.378 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 5770
2025-04-10T22:46:31.391Z,1744325191.391 [DAT ThreadHandler](INFO): Handler Thread ID is 5771
2025-04-10T22:46:31.392Z,1744325191.392 [DAT](INFO): Powering up
2025-04-10T22:46:31.392Z,1744325191.392 [DAT](DEBUG): Initializing DAT.
2025-04-10T22:46:31.394Z,1744325191.394 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-04-10T22:46:31.394Z,1744325191.394 [CBIT](DEBUG): Initializing heartbeat.
2025-04-10T22:46:31.396Z,1744325191.396 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5773
2025-04-10T22:46:31.398Z,1744325191.398 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-04-10T22:46:31.406Z,1744325191.406 [Onboard ThreadHandler](INFO): Handler Thread ID is 5774
2025-04-10T22:46:31.431Z,1744325191.431 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5775
2025-04-10T22:46:31.450Z,1744325191.450 [TrnDataBridge ThreadHandler](INFO): Handler Thread ID is 5776
2025-04-10T22:46:31.450Z,1744325191.450 [TrnDataBridge](INFO): Start
2025-04-10T22:46:31.451Z,1744325191.451 [TrnDataBridge](IMPORTANT): LCM Instance1
2025-04-10T22:46:31.465Z,1744325191.465 [CBIT](DEBUG): Deactivating GF circuits.
2025-04-10T22:46:31.466Z,1744325191.466 [CBIT](DEBUG): Deactivating emergency mode.
2025-04-10T22:46:31.486Z,1744325191.486 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 5778
2025-04-10T22:46:31.491Z,1744325191.491 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-04-10T22:46:31.498Z,1744325191.498 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5780
2025-04-10T22:46:31.505Z,1744325191.505 [CBIT](DEBUG): Backplane powered.
2025-04-10T22:46:31.510Z,1744325191.510 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-04-10T22:46:31.511Z,1744325191.511 [MissionManager](INFO): Loading Mission from file: Missions/underIce/StartupUnder.tl
2025-04-10T22:46:31.511Z,1744325191.511 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/StartupUnder.tl
2025-04-10T22:46:31.512Z,1744325191.512 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/StartupUnder.tl
2025-04-10T22:46:31.518Z,1744325191.518 [MissionManager](DEBUG): TethyslAPI: Missions/underIce/StartupUnder.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="StartupUnder" />

2025-04-10T22:46:31.519Z,1744325191.519 [MissionManager](ERROR): Top node has no child nodes: Mission
2025-04-10T22:46:31.519Z,1744325191.519 [MissionManager](ERROR): Could not load startup mission.
2025-04-10T22:46:31.519Z,1744325191.519 [MissionManager](INFO): Loading Mission from file: Missions/underIce/DefaultWithUndock.tl
2025-04-10T22:46:31.519Z,1744325191.519 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/DefaultWithUndock.tl
2025-04-10T22:46:31.521Z,1744325191.521 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/DefaultWithUndock.tl
2025-04-10T22:46:31.522Z,1744325191.522 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 5782
2025-04-10T22:46:31.524Z,1744325191.524 [WetLabsBB2FL](INFO): Powering up
2025-04-10T22:46:31.530Z,1744325191.530 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5784
2025-04-10T22:46:31.533Z,1744325191.533 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-04-10T22:46:31.533Z,1744325191.533 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-04-10T22:46:31.533Z,1744325191.533 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-04-10T22:46:31.534Z,1744325191.534 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-04-10T22:46:31.534Z,1744325191.534 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-04-10T22:46:31.534Z,1744325191.534 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-04-10T22:46:31.534Z,1744325191.534 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-04-10T22:46:31.534Z,1744325191.534 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-04-10T22:46:31.554Z,1744325191.554 [WetLabsUBAT](INFO): Powering up
2025-04-10T22:46:31.838Z,1744325191.838 [Radio_Surface](INFO): Powering up
2025-04-10T22:46:32.126Z,1744325192.126 [MissionManager](DEBUG): TethyslAPI: Missions/underIce/DefaultWithUndock.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="DefaultWithUndock">
    <DefineArg Name="ElapsedSinceDefaultStarted">
        <Units:minute />
        <Value>0</Value>
    </DefineArg>
    <DefineArg Name="DetachTimeout">
        <Units:hour />
        <Value>6</Value>
    </DefineArg>
    <DefineArg Name="TransponderCode">
        <Dock:Dock.transponderCode />
    </DefineArg>
    <DefineArg Name="TrackingUpdatePeriod">
        <Units:second />
        <Value>10</Value>
    </DefineArg>
    <DefineArg Name="NumberOfPings">
        <Units:count />
        <Value>1</Value>
    </DefineArg>
    <DefineArg Name="DriftTimeout">
        <Units:minute />
        <Value>3</Value>
    </DefineArg>
    <Guidance:Wait>
        <Sequence />
        <Setting>
            <Guidance:Wait.duration />
            <Units:second />
            <Value>30</Value>
        </Setting>
    </Guidance:Wait>
    <Aggregate Id="LeaveDock">
        <Sequence />
        <Timeout Duration="DetachTimeout">
            <Syslog Severity="Important">
                Timed out while trying to detach from dock at current depth of 
                <Universal:depth />
                <Units:meter />
                .
            </Syslog>
        </Timeout>
        <Estimation:TrackAcousticContact>
            <While>
                <Universal:depth />
                <Gt>
                    <Control:VerticalControl.surfaceThreshold />
                </Gt>
            </While>
            <Setting>
                <Estimation:TrackAcousticContact.contactLabelSetting />
                <Arg Name="TransponderCode" />
            </Setting>
            <Setting>
                <Estimation:TrackAcousticContact.numberOfSamplesSetting />
                <Arg Name="NumberOfPings" />
            </Setting>
            <Setting>
                <Estimation:TrackAcousticContact.updatePeriodSetting />
                <Arg Name="TrackingUpdatePeriod" />
            </Setting>
        </Estimation:TrackAcousticContact>
        <Dock:Undock>
            <Sequence />
            <Setting>
                <Dock:Undock.driftTimeout />
                <Arg Name="DriftTimeout" />
            </Setting>
        </Dock:Undock>
    </Aggregate>
    <Aggregate Id="SurfaceDefault">
        <Sequence />
        <Guidance:GoToSurface>
            <Progression />
        </Guidance:GoToSurface>
        <Aggregate Id="CheckIn">
            <Sequence Repeat="288" />
            <ReadDatum Id="Read_GPS">
                <Timeout Duration="P5M" />
                <Universal:time_fix />
            </ReadDatum>
            <ReadDatum Id="Read_Iridium">
                <Timeout Duration="P2H">
                    <Guidance:Execute>
                        <Sequence />
                        <Setting>
                            <Guidance:Execute.command />
                            <String>Burn on</String>
                        </Setting>
                    </Guidance:Execute>
                    <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
                </Timeout>
                <Universal:platform_communications />
            </ReadDatum>
            <Guidance:Wait>
                <Sequence />
                <Setting>
                    <Guidance:Wait.duration />
                    <Units:minute />
                    <Value>5</Value>
                </Setting>
            </Guidance:Wait>
            <Assign>
                <Sequence />
                <Arg Name="ElapsedSinceDefaultStarted" />
                <Elapsed>
                    <Universal:mission_started />
                </Elapsed>
            </Assign>
            <Syslog Severity="Important">
                Default mission has been running for 
                <Arg Name="ElapsedSinceDefaultStarted" />
                <Units:minute />
            </Syslog>
        </Aggregate>
    </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-04-10T22:46:32.146Z,1744325192.146 [MissionManager](INFO): DefineArg DefaultWithUndock.ElapsedSinceDefaultStarted = 0.000000 min
2025-04-10T22:46:32.151Z,1744325192.151 [MissionManager](INFO): DefineArg DefaultWithUndock.DetachTimeout = 6.000000 h
2025-04-10T22:46:32.180Z,1744325192.180 [MissionManager](INFO): DefineArg DefaultWithUndock.TransponderCode = 50 count
2025-04-10T22:46:32.184Z,1744325192.184 [MissionManager](INFO): DefineArg DefaultWithUndock.TrackingUpdatePeriod = 10.000000 s
2025-04-10T22:46:32.187Z,1744325192.187 [MissionManager](INFO): DefineArg DefaultWithUndock.NumberOfPings = 1.000000 count
2025-04-10T22:46:32.207Z,1744325192.207 [MissionManager](INFO): DefineArg DefaultWithUndock.DriftTimeout = 3.000000 min
2025-04-10T22:46:32.209Z,1744325192.209 [DefaultWithUndock:A.Wait](DEBUG): Construct Wait.
2025-04-10T22:46:32.360Z,1744325192.360 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Construct.
2025-04-10T22:46:32.361Z,1744325192.361 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2025-04-10T22:46:32.433Z,1744325192.433 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-04-10T22:46:32.482Z,1744325192.482 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-04-10T22:46:32.485Z,1744325192.485 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-04-10T22:46:32.511Z,1744325192.511 [DefaultWithUndock:E.Execute](DEBUG): Construct Execute.
2025-04-10T22:46:32.543Z,1744325192.543 [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-04-10T22:46:32.548Z,1744325192.548 [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,LcmUniversalReporter,Reporter,LogSplitter,
2025-04-10T22:46:32.555Z,1744325192.555 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-04-10T22:46:32.616Z,1744325192.616 [Depth_Keller](ERROR): Pressure or depth reading out of range: -1.000394 decibar, -0.993022 m
2025-04-10T22:46:32.617Z,1744325192.617 [Power24vConverter](INFO): Powering up.
2025-04-10T22:46:32.618Z,1744325192.618 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2025-04-10T22:46:32.626Z,1744325192.626 [Sonardyne_Nano](INFO): Initializing.
2025-04-10T22:46:32.667Z,1744325192.667 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-04-10T22:46:32.695Z,1744325192.695 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-04-10T22:46:32.702Z,1744325192.702 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-04-10T22:46:32.703Z,1744325192.703 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-04-10T22:46:32.710Z,1744325192.710 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-04-10T22:46:32.711Z,1744325192.711 [DockingStepper](INFO): Start
2025-04-10T22:46:32.711Z,1744325192.711 [DockingStepper](DEBUG): Initializing EZServoServo.
2025-04-10T22:46:32.718Z,1744325192.718 [DockingStepper](DEBUG): Initializing DockingStepper.
2025-04-10T22:46:32.719Z,1744325192.719 [MassServo](DEBUG): Initializing EZServoServo.
2025-04-10T22:46:32.726Z,1744325192.726 [MassServo](DEBUG): Initializing MassServo.
2025-04-10T22:46:32.727Z,1744325192.727 [RudderServo](DEBUG): Initializing EZServoServo.
2025-04-10T22:46:32.734Z,1744325192.734 [RudderServo](DEBUG): Initializing RudderServo.
2025-04-10T22:46:32.735Z,1744325192.735 [ThrusterServo](DEBUG): Initializing EZServoServo.
2025-04-10T22:46:32.742Z,1744325192.742 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2025-04-10T22:46:32.999Z,1744325192.999 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2025-04-10T22:46:32.000Z,1744325193.000 [DropWeight] Hardware Fault, FailCount= 1
2025-04-10T22:46:32.000Z,1744325193.000 [DropWeight](ERROR): Hardware Fault
2025-04-10T22:46:33.015Z,1744325193.015 [DockingStepper](INFO): Starting
2025-04-10T22:46:33.017Z,1744325193.017 [CommandExec](FAULT): Scheduling is paused
2025-04-10T22:46:33.017Z,1744325193.017 [CBIT](INFO): Critical error at 20250410T224632
2025-04-10T22:46:33.026Z,1744325193.026 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2025-04-10T22:46:33.028Z,1744325193.028 [CBIT](ERROR): Hardware Fault in component: DropWeight
2025-04-10T22:46:33.029Z,1744325193.029 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2025-04-10T22:46:33.381Z,1744325193.381 [DockingStepper](INFO): Starting
2025-04-10T22:46:33.383Z,1744325193.383 [CBIT](INFO): Critical error at 20250410T224633
2025-04-10T22:46:33.914Z,1744325193.914 [DockingStepper](INFO): Starting
2025-04-10T22:46:33.967Z,1744325193.967 [DockingStepper](INFO): Init string: /1z16127aE13561N3m50
2025-04-10T22:46:34.204Z,1744325194.204 [DockingStepper](INFO): Starting
2025-04-10T22:46:34.618Z,1744325194.618 [DockingStepper](INFO): Starting
2025-04-10T22:46:35.023Z,1744325195.023 [DockingStepper](INFO): Starting
2025-04-10T22:46:35.458Z,1744325195.458 [DockingStepper](INFO): Starting
2025-04-10T22:46:35.482Z,1744325195.482 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@
2025-04-10T22:46:36.114Z,1744325196.114 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:36.169Z,1744325196.169 [DockingStepper](INFO): Starting
2025-04-10T22:46:36.550Z,1744325196.550 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:36.790Z,1744325196.790 [WetLabsBB2FL] Communications Fault, FailCount= 1
2025-04-10T22:46:36.790Z,1744325196.790 [WetLabsBB2FL](ERROR): Communications Fault
2025-04-10T22:46:37.090Z,1744325197.090 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.10
2025-04-10T22:46:37.091Z,1744325197.091 [Radio_Surface](INFO): Found local IP address 10.89.10.2 for eth0
2025-04-10T22:46:37.118Z,1744325197.118 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:37.196Z,1744325197.196 [DockingStepper](INFO): Pause
2025-04-10T22:46:37.290Z,1744325197.290 [WetLabsBB2FL](INFO): Powering down
2025-04-10T22:46:37.546Z,1744325197.546 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2025-04-10T22:46:37.878Z,1744325197.878 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:38.222Z,1744325198.222 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2025-04-10T22:46:38.223Z,1744325198.223 [WetLabsBB2FL] No Fault, FailCount= 1
2025-04-10T22:46:38.504Z,1744325198.504 [WetLabsBB2FL](INFO): Powering up
2025-04-10T22:46:38.553Z,1744325198.553 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:39.126Z,1744325199.126 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:39.667Z,1744325199.667 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:40.090Z,1744325200.090 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:40.514Z,1744325200.514 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:40.946Z,1744325200.946 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:41.439Z,1744325201.439 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:41.527Z,1744325201.527 [CBIT](CRITICAL): Environmental Failure. Press:14.519491 PSI. Humidity:43%. Temp:23 C. ABORTING MISSION
2025-04-10T22:46:41.869Z,1744325201.869 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:41.960Z,1744325201.960 [CBIT](INFO): Critical error at 20250410T224641
2025-04-10T22:46:42.282Z,1744325202.282 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:42.721Z,1744325202.721 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:43.122Z,1744325203.122 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:43.566Z,1744325203.566 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:43.793Z,1744325203.793 [WetLabsBB2FL] Communications Fault, FailCount= 2
2025-04-10T22:46:43.793Z,1744325203.793 [WetLabsBB2FL](ERROR): Communications Fault
2025-04-10T22:46:44.001Z,1744325204.001 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:44.091Z,1744325204.091 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2025-04-10T22:46:44.294Z,1744325204.294 [WetLabsBB2FL](INFO): Powering down
2025-04-10T22:46:44.426Z,1744325204.426 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:44.547Z,1744325204.547 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2025-04-10T22:46:44.548Z,1744325204.548 [CTD_Seabird] Communications Fault, FailCount= 1
2025-04-10T22:46:44.548Z,1744325204.548 [CTD_Seabird](ERROR): Communications Fault
2025-04-10T22:46:44.774Z,1744325204.774 [DAT](INFO): DAT read: 

2025-04-10T22:46:44.776Z,1744325204.776 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2025-04-10T22:46:44.838Z,1744325204.838 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:44.910Z,1744325204.910 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2025-04-10T22:46:44.960Z,1744325204.960 [CTD_Seabird](INFO): Powering down
2025-04-10T22:46:45.249Z,1744325205.249 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:45.362Z,1744325205.362 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2025-04-10T22:46:45.362Z,1744325205.362 [WetLabsBB2FL] No Fault, FailCount= 2
2025-04-10T22:46:45.508Z,1744325205.508 [WetLabsBB2FL](INFO): Powering up
2025-04-10T22:46:45.694Z,1744325205.694 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:45.694Z,1744325205.694 [AHRS_M2](FAULT): Failed to initialize within timeout.
2025-04-10T22:46:45.694Z,1744325205.694 [AHRS_M2] Communications Fault, FailCount= 1
2025-04-10T22:46:45.694Z,1744325205.694 [AHRS_M2](ERROR): Communications Fault
2025-04-10T22:46:45.766Z,1744325205.766 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2025-04-10T22:46:45.773Z,1744325205.773 [AHRS_M2](INFO): Powering down
2025-04-10T22:46:45.895Z,1744325205.895 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004237
2025-04-10T22:46:45.946Z,1744325205.946 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2025-04-10T22:46:45.946Z,1744325205.946 [CTD_Seabird] No Fault, FailCount= 1
2025-04-10T22:46:46.306Z,1744325206.306 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-04-10T22:46:46.662Z,1744325206.662 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-04-10T22:46:46.662Z,1744325206.662 [AHRS_M2] No Fault, FailCount= 1
2025-04-10T22:46:46.894Z,1744325206.894 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-04-10T22:46:46.983Z,1744325206.983 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-04-10T22:46:50.541Z,1744325210.541 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:50.797Z,1744325210.797 [WetLabsBB2FL] Communications Fault, FailCount= 3
2025-04-10T22:46:50.797Z,1744325210.797 [WetLabsBB2FL](ERROR): Communications Fault
2025-04-10T22:46:50.966Z,1744325210.966 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:51.034Z,1744325211.034 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2025-04-10T22:46:51.298Z,1744325211.298 [WetLabsBB2FL](INFO): Powering down
2025-04-10T22:46:51.390Z,1744325211.390 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:51.830Z,1744325211.830 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:52.245Z,1744325212.245 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:52.322Z,1744325212.322 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2025-04-10T22:46:52.323Z,1744325212.323 [WetLabsBB2FL] No Fault, FailCount= 3
2025-04-10T22:46:52.511Z,1744325212.511 [WetLabsBB2FL](INFO): Powering up
2025-04-10T22:46:52.678Z,1744325212.678 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:53.090Z,1744325213.090 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:53.514Z,1744325213.514 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:53.846Z,1744325213.846 [DAT](INFO): DAT read: MF Frequency Band

2025-04-10T22:46:53.848Z,1744325213.848 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.17.2

2025-04-10T22:46:53.848Z,1744325213.848 [DAT](INFO): DAT read: Apr 10 2025 18:48:50 

2025-04-10T22:46:53.946Z,1744325213.946 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:54.382Z,1744325214.382 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:54.762Z,1744325214.762 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:54.855Z,1744325214.855 [DAT](INFO): DAT read: Features enabled [Bearing]

2025-04-10T22:46:54.856Z,1744325214.856 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP

2025-04-10T22:46:54.856Z,1744325214.856 [DAT](INFO): commRate: 600
2025-04-10T22:46:55.174Z,1744325215.174 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:55.184Z,1744325215.184 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2025-04-10T22:46:55.184Z,1744325215.184 [RDI_Pathfinder] Communications Fault, FailCount= 1
2025-04-10T22:46:55.184Z,1744325215.184 [RDI_Pathfinder](ERROR): Communications Fault
2025-04-10T22:46:55.299Z,1744325215.299 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2025-04-10T22:46:55.658Z,1744325215.658 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:55.746Z,1744325215.746 [RDI_Pathfinder](INFO): Powering down
2025-04-10T22:46:56.194Z,1744325216.194 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:56.617Z,1744325216.617 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:56.714Z,1744325216.714 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2025-04-10T22:46:56.714Z,1744325216.714 [RDI_Pathfinder] No Fault, FailCount= 1
2025-04-10T22:46:56.946Z,1744325216.946 [DAT](INFO): entering command mode
2025-04-10T22:46:57.054Z,1744325217.054 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:57.064Z,1744325217.064 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2025-04-10T22:46:57.146Z,1744325217.146 [DAT](INFO): DAT read: 

2025-04-10T22:46:57.147Z,1744325217.147 [DAT](INFO): DAT read: user:1>

2025-04-10T22:46:57.147Z,1744325217.147 [DAT](INFO): setting verbose to 3
2025-04-10T22:46:57.398Z,1744325217.398 [DAT](INFO): DAT read: user:1>

2025-04-10T22:46:57.399Z,1744325217.399 [DAT](INFO): DAT read: Verbose         | 3           

2025-04-10T22:46:57.400Z,1744325217.400 [DAT](INFO): set verbose to 3
2025-04-10T22:46:57.400Z,1744325217.400 [DAT](INFO): setting DatVerbose to 27440
2025-04-10T22:46:57.526Z,1744325217.526 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:57.663Z,1744325217.663 [DAT](INFO): DAT read: user:2>

2025-04-10T22:46:57.664Z,1744325217.664 [DAT](INFO): DAT read: DatVerbose      | 27440       

2025-04-10T22:46:57.664Z,1744325217.664 [DAT](INFO): set DatVerbose to 27440
2025-04-10T22:46:57.664Z,1744325217.664 [DAT](INFO): setting transmit power to 8
2025-04-10T22:46:57.809Z,1744325217.809 [WetLabsBB2FL] Communications Fault, FailCount= 4
2025-04-10T22:46:57.810Z,1744325217.810 [WetLabsBB2FL](ERROR): Communications Fault
2025-04-10T22:46:57.914Z,1744325217.914 [DAT](INFO): DAT read: user:3>

2025-04-10T22:46:57.915Z,1744325217.915 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2025-04-10T22:46:57.915Z,1744325217.915 [DAT](INFO): set transmit power to 8
2025-04-10T22:46:57.916Z,1744325217.916 [DAT](INFO): setting local address to 10
2025-04-10T22:46:57.942Z,1744325217.942 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:58.038Z,1744325218.038 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2025-04-10T22:46:58.166Z,1744325218.166 [DAT](INFO): DAT read: user:4>

2025-04-10T22:46:58.167Z,1744325218.167 [DAT](INFO): DAT read: LocalAddr       | 10          

2025-04-10T22:46:58.168Z,1744325218.168 [DAT](INFO): set local address to 10
2025-04-10T22:46:58.169Z,1744325218.169 [DAT](INFO): Setting time to: 22:46:58 And date to:4/10/2025
2025-04-10T22:46:58.310Z,1744325218.310 [WetLabsBB2FL](INFO): Powering down
2025-04-10T22:46:58.373Z,1744325218.373 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:58.418Z,1744325218.418 [DAT](INFO): DAT read: user:5>

2025-04-10T22:46:58.419Z,1744325218.419 [DAT](INFO): DAT read: Thu Apr 10, 2025  22:46:58  

2025-04-10T22:46:58.419Z,1744325218.419 [DAT](INFO): Local DAT time set to Thu Apr 10, 2025  22:46:58  

2025-04-10T22:46:58.870Z,1744325218.870 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:59.278Z,1744325219.278 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:59.353Z,1744325219.353 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2025-04-10T22:46:59.353Z,1744325219.353 [WetLabsBB2FL] No Fault, FailCount= 4
2025-04-10T22:46:59.358Z,1744325219.358 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2025-04-10T22:46:59.358Z,1744325219.358 [CTD_Seabird] Communications Fault, FailCount= 2
2025-04-10T22:46:59.358Z,1744325219.358 [CTD_Seabird](ERROR): Communications Fault
2025-04-10T22:46:59.534Z,1744325219.534 [WetLabsBB2FL](INFO): Powering up
2025-04-10T22:46:59.718Z,1744325219.718 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:46:59.762Z,1744325219.762 [CTD_Seabird](INFO): Powering down
2025-04-10T22:46:59.813Z,1744325219.813 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2025-04-10T22:47:00.138Z,1744325220.138 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:00.138Z,1744325220.138 [AHRS_M2](FAULT): Failed to initialize within timeout.
2025-04-10T22:47:00.138Z,1744325220.138 [AHRS_M2] Communications Fault, FailCount= 2
2025-04-10T22:47:00.138Z,1744325220.138 [AHRS_M2](ERROR): Communications Fault
2025-04-10T22:47:00.143Z,1744325220.143 [NAL9602](INFO): Powering up NAL9602
2025-04-10T22:47:00.217Z,1744325220.217 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2025-04-10T22:47:00.228Z,1744325220.228 [AHRS_M2](INFO): Powering down
2025-04-10T22:47:00.706Z,1744325220.706 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2025-04-10T22:47:00.706Z,1744325220.706 [CTD_Seabird] No Fault, FailCount= 2
2025-04-10T22:47:01.110Z,1744325221.110 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-04-10T22:47:01.117Z,1744325221.117 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-04-10T22:47:01.117Z,1744325221.117 [AHRS_M2] No Fault, FailCount= 2
2025-04-10T22:47:01.459Z,1744325221.459 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-04-10T22:47:01.694Z,1744325221.694 [WetLabsUBAT](FAULT): Failed to acquire valid data within specified timeout upon startup.
2025-04-10T22:47:01.694Z,1744325221.694 [WetLabsUBAT] Communications Fault, FailCount= 1
2025-04-10T22:47:01.694Z,1744325221.694 [WetLabsUBAT](ERROR): Communications Fault
2025-04-10T22:47:01.926Z,1744325221.926 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-04-10T22:47:02.043Z,1744325222.043 [CBIT](ERROR): Communications Fault in component: WetLabsUBAT
2025-04-10T22:47:02.194Z,1744325222.194 [WetLabsUBAT](INFO): Powering down
2025-04-10T22:47:03.122Z,1744325223.122 [CBIT](INFO): Clearing failed state for component WetLabsUBAT
2025-04-10T22:47:03.122Z,1744325223.122 [WetLabsUBAT] No Fault, FailCount= 1
2025-04-10T22:47:03.407Z,1744325223.407 [WetLabsUBAT](INFO): Powering up
2025-04-10T22:47:04.813Z,1744325224.813 [WetLabsBB2FL] Communications Fault, FailCount= 5
2025-04-10T22:47:04.814Z,1744325224.814 [WetLabsBB2FL](ERROR): Communications Fault
2025-04-10T22:47:04.993Z,1744325224.993 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:05.082Z,1744325225.082 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2025-04-10T22:47:05.082Z,1744325225.082 [CBIT](FAULT): Communications Fault in component: WetLabsBB2FL
2025-04-10T22:47:05.314Z,1744325225.314 [WetLabsBB2FL](INFO): Powering down
2025-04-10T22:47:05.414Z,1744325225.414 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:05.834Z,1744325225.834 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:06.258Z,1744325226.258 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:06.547Z,1744325226.547 [CommandExec](IMPORTANT): got command docking arm
2025-04-10T22:47:06.547Z,1744325226.547 [CommandExec](IMPORTANT): Switching docking module to ARM.
2025-04-10T22:47:06.682Z,1744325226.682 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:07.126Z,1744325227.126 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:07.182Z,1744325227.182 [DockingStepper](INFO): Resume
2025-04-10T22:47:07.558Z,1744325227.558 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:07.619Z,1744325227.619 [DockingStepper](INFO): Resuming
2025-04-10T22:47:07.997Z,1744325227.997 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:08.039Z,1744325228.039 [DockingStepper](INFO): Start
2025-04-10T22:47:08.039Z,1744325228.039 [DockingStepper](DEBUG): Initializing EZServoServo.
2025-04-10T22:47:08.158Z,1744325228.158 [DockingStepper](DEBUG): Initializing DockingStepper.
2025-04-10T22:47:08.518Z,1744325228.518 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:08.551Z,1744325228.551 [DockingStepper](INFO): Starting
2025-04-10T22:47:08.942Z,1744325228.942 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:08.999Z,1744325228.999 [DockingStepper](INFO): Starting
2025-04-10T22:47:09.382Z,1744325229.382 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:09.431Z,1744325229.431 [DockingStepper](INFO): Starting
2025-04-10T22:47:09.478Z,1744325229.478 [DockingStepper](INFO): Init string: /1z16129aE13561N3m50
2025-04-10T22:47:09.882Z,1744325229.882 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:09.934Z,1744325229.934 [DockingStepper](INFO): Starting
2025-04-10T22:47:10.314Z,1744325230.314 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:10.391Z,1744325230.391 [DockingStepper](INFO): Starting
2025-04-10T22:47:10.758Z,1744325230.758 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:10.819Z,1744325230.819 [DockingStepper](INFO): Starting
2025-04-10T22:47:11.198Z,1744325231.198 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:11.203Z,1744325231.203 [NAL9602](INFO): NAL9602 initialized
2025-04-10T22:47:11.252Z,1744325231.252 [DockingStepper](INFO): Starting
2025-04-10T22:47:11.282Z,1744325231.282 [DockingStepper](INFO): Sent speed cmd:/1V10000 Response:ÿ/0@
2025-04-10T22:47:11.658Z,1744325231.658 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:11.707Z,1744325231.707 [DockingStepper](INFO): Starting
2025-04-10T22:47:12.082Z,1744325232.082 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:12.139Z,1744325232.139 [DockingStepper](INFO): Changing to mode: 2
2025-04-10T22:47:12.139Z,1744325232.139 [DockingStepper](INFO): Armed mode.
2025-04-10T22:47:12.201Z,1744325232.201 [DockingStepper](INFO): Commanding negative:53127
2025-04-10T22:47:12.602Z,1744325232.602 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:13.074Z,1744325233.074 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:13.546Z,1744325233.546 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:14.006Z,1744325234.006 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:14.179Z,1744325234.179 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2025-04-10T22:47:14.179Z,1744325234.179 [CTD_Seabird] Communications Fault, FailCount= 3
2025-04-10T22:47:14.179Z,1744325234.179 [CTD_Seabird](ERROR): Communications Fault
2025-04-10T22:47:14.182Z,1744325234.182 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-04-10T22:47:14.442Z,1744325234.442 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:14.442Z,1744325234.442 [AHRS_M2](FAULT): Failed to initialize within timeout.
2025-04-10T22:47:14.442Z,1744325234.442 [AHRS_M2] Communications Fault, FailCount= 3
2025-04-10T22:47:14.442Z,1744325234.442 [AHRS_M2](ERROR): Communications Fault
2025-04-10T22:47:14.582Z,1744325234.582 [CTD_Seabird](INFO): Powering down
2025-04-10T22:47:14.614Z,1744325234.614 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2025-04-10T22:47:14.615Z,1744325234.615 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2025-04-10T22:47:14.615Z,1744325234.615 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2025-04-10T22:47:14.622Z,1744325234.622 [AHRS_M2](INFO): Powering down
2025-04-10T22:47:15.554Z,1744325235.554 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-04-10T22:47:15.554Z,1744325235.554 [AHRS_M2] No Fault, FailCount= 3
2025-04-10T22:47:15.831Z,1744325235.831 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-04-10T22:47:19.378Z,1744325239.378 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:19.402Z,1744325239.402 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2025-04-10T22:47:19.402Z,1744325239.402 [RDI_Pathfinder] Communications Fault, FailCount= 2
2025-04-10T22:47:19.402Z,1744325239.402 [RDI_Pathfinder](ERROR): Communications Fault
2025-04-10T22:47:19.550Z,1744325239.550 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2025-04-10T22:47:19.882Z,1744325239.882 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:19.970Z,1744325239.970 [RDI_Pathfinder](INFO): Powering down
2025-04-10T22:47:20.434Z,1744325240.434 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:20.914Z,1744325240.914 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:21.054Z,1744325241.054 [CBIT](INFO): Clearing failed state for component RDI_Pathfinder
2025-04-10T22:47:21.054Z,1744325241.054 [RDI_Pathfinder] No Fault, FailCount= 2
2025-04-10T22:47:21.382Z,1744325241.382 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:21.388Z,1744325241.388 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2025-04-10T22:47:21.894Z,1744325241.894 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:22.358Z,1744325242.358 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:22.862Z,1744325242.862 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:23.346Z,1744325243.346 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:23.814Z,1744325243.814 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:24.286Z,1744325244.286 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:24.734Z,1744325244.734 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:25.214Z,1744325245.214 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:25.338Z,1744325245.338 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-04-10T22:47:25.702Z,1744325245.702 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:26.170Z,1744325246.170 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:26.642Z,1744325246.642 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:27.102Z,1744325247.102 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:27.538Z,1744325247.538 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:28.006Z,1744325248.006 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:28.470Z,1744325248.470 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:28.942Z,1744325248.942 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:28.942Z,1744325248.942 [AHRS_M2](FAULT): Failed to initialize within timeout.
2025-04-10T22:47:28.942Z,1744325248.942 [AHRS_M2] Communications Fault, FailCount= 4
2025-04-10T22:47:28.942Z,1744325248.942 [AHRS_M2](ERROR): Communications Fault
2025-04-10T22:47:29.184Z,1744325249.184 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2025-04-10T22:47:29.191Z,1744325249.191 [AHRS_M2](INFO): Powering down
2025-04-10T22:47:30.119Z,1744325250.119 [CBIT](INFO): Clearing failed state for component AHRS_M2
2025-04-10T22:47:30.119Z,1744325250.119 [AHRS_M2] No Fault, FailCount= 4
2025-04-10T22:47:30.403Z,1744325250.403 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-04-10T22:47:33.525Z,1744325253.525 [WetLabsUBAT](FAULT): Failed to acquire valid data within specified timeout upon startup.
2025-04-10T22:47:33.526Z,1744325253.526 [WetLabsUBAT] Communications Fault, FailCount= 2
2025-04-10T22:47:33.526Z,1744325253.526 [WetLabsUBAT](ERROR): Communications Fault
2025-04-10T22:47:33.961Z,1744325253.961 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:34.026Z,1744325254.026 [WetLabsUBAT](INFO): Powering down
2025-04-10T22:47:34.087Z,1744325254.087 [CBIT](ERROR): Communications Fault in component: WetLabsUBAT
2025-04-10T22:47:34.426Z,1744325254.426 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:34.838Z,1744325254.838 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:34.908Z,1744325254.908 [CBIT](INFO): Clearing failed state for component WetLabsUBAT
2025-04-10T22:47:34.908Z,1744325254.908 [WetLabsUBAT] No Fault, FailCount= 2
2025-04-10T22:47:35.239Z,1744325255.239 [WetLabsUBAT](INFO): Powering up
2025-04-10T22:47:35.249Z,1744325255.249 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:35.702Z,1744325255.702 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:36.154Z,1744325256.154 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:36.626Z,1744325256.626 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:37.066Z,1744325257.066 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:37.254Z,1744325257.254 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-04-10T22:47:37.486Z,1744325257.486 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:37.918Z,1744325257.918 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:38.330Z,1744325258.330 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:38.746Z,1744325258.746 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:39.154Z,1744325259.154 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:39.590Z,1744325259.590 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:40.062Z,1744325260.062 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:40.550Z,1744325260.550 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:41.058Z,1744325261.058 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:41.538Z,1744325261.538 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:42.014Z,1744325262.014 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:42.478Z,1744325262.478 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:42.949Z,1744325262.949 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:43.394Z,1744325263.394 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2025-04-10T22:47:43.394Z,1744325263.394 [AHRS_M2](FAULT): Failed to initialize within timeout.
2025-04-10T22:47:43.394Z,1744325263.394 [AHRS_M2] Communications Fault, FailCount= 5
2025-04-10T22:47:43.394Z,1744325263.394 [AHRS_M2](ERROR): Communications Fault
2025-04-10T22:47:43.523Z,1744325263.523 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2025-04-10T22:47:43.524Z,1744325263.524 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2
2025-04-10T22:47:43.532Z,1744325263.532 [AHRS_M2](INFO): Powering down
2025-04-10T22:47:43.730Z,1744325263.730 [CBIT](INFO): Critical error at 20250410T224743
2025-04-10T22:47:44.343Z,1744325264.343 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing
2025-04-10T22:47:44.344Z,1744325264.344 [RDI_Pathfinder] Communications Fault, FailCount= 3
2025-04-10T22:47:44.344Z,1744325264.344 [RDI_Pathfinder](ERROR): Communications Fault
2025-04-10T22:47:44.459Z,1744325264.459 [CBIT](ERROR): Communications Fault in component: RDI_Pathfinder
2025-04-10T22:47:44.460Z,1744325264.460 [CBIT](CRITICAL): Communications Fault in component: RDI_Pathfinder
2025-04-10T22:47:44.818Z,1744325264.818 [RDI_Pathfinder](INFO): Powering down
2025-04-10T22:47:44.921Z,1744325264.921 [CBIT](INFO): Critical error at 20250410T224744
2025-04-10T22:47:49.442Z,1744325269.442 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-04-10T22:47:57.986Z,1744325277.986 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-04-10T22:48:05.429Z,1744325285.429 [WetLabsUBAT](FAULT): Failed to acquire valid data within specified timeout upon startup.
2025-04-10T22:48:05.429Z,1744325285.429 [WetLabsUBAT] Communications Fault, FailCount= 3
2025-04-10T22:48:05.430Z,1744325285.430 [WetLabsUBAT](ERROR): Communications Fault
2025-04-10T22:48:05.824Z,1744325285.824 [CBIT](ERROR): Communications Fault in component: WetLabsUBAT
2025-04-10T22:48:05.926Z,1744325285.926 [WetLabsUBAT](INFO): Powering down
2025-04-10T22:48:06.310Z,1744325286.310 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-04-10T22:48:06.775Z,1744325286.775 [CBIT](INFO): Clearing failed state for component WetLabsUBAT
2025-04-10T22:48:06.775Z,1744325286.775 [WetLabsUBAT] No Fault, FailCount= 3
2025-04-10T22:48:07.148Z,1744325287.148 [WetLabsUBAT](INFO): Powering up
2025-04-10T22:48:13.112Z,1744325293.112 [SBIT](IMPORTANT): Beginning Startup BIT
2025-04-10T22:48:13.117Z,1744325293.117 [CBIT](IMPORTANT): Beginning ground fault scan
2025-04-10T22:48:14.594Z,1744325294.594 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-04-10T22:48:23.462Z,1744325303.462 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-04-10T22:48:24.622Z,1744325304.622 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.010517
CHAN A1 (24V): -0.029443
CHAN A2 (12V): -0.007388
CHAN A3 (5V): -0.002087
CHAN B0 (3.3V): 0.000176
CHAN B1 (3.15aV): -0.000323
CHAN B2 (3.15bV): 0.000071
CHAN B3 (GND): 0.001878
OPEN: 0.005133
Full Scale: +/- 1 mA
2025-04-10T22:48:31.657Z,1744325311.657 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2025-04-10T22:48:31.657Z,1744325311.657 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-04-10T22:48:31.657Z,1744325311.657 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-04-10T22:48:31.811Z,1744325311.811 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-04-10T22:48:32.114Z,1744325312.114 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-04-10T22:48:32.313Z,1744325312.313 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-04-10T22:48:32.313Z,1744325312.313 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-04-10T22:48:37.234Z,1744325317.234 [WetLabsUBAT](FAULT): Failed to acquire valid data within specified timeout upon startup.
2025-04-10T22:48:37.234Z,1744325317.234 [WetLabsUBAT] Communications Fault, FailCount= 4
2025-04-10T22:48:37.234Z,1744325317.234 [WetLabsUBAT](ERROR): Communications Fault
2025-04-10T22:48:37.422Z,1744325317.422 [CBIT](ERROR): Communications Fault in component: WetLabsUBAT
2025-04-10T22:48:37.734Z,1744325317.734 [WetLabsUBAT](INFO): Powering down
2025-04-10T22:48:38.655Z,1744325318.655 [CBIT](INFO): Clearing failed state for component WetLabsUBAT
2025-04-10T22:48:38.655Z,1744325318.655 [WetLabsUBAT] No Fault, FailCount= 4
2025-04-10T22:48:38.947Z,1744325318.947 [WetLabsUBAT](INFO): Powering up
2025-04-10T22:48:41.014Z,1744325321.014 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-04-10T22:48:49.742Z,1744325329.742 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-04-10T22:48:58.398Z,1744325338.398 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-04-10T22:49:06.770Z,1744325346.770 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-04-10T22:49:06.819Z,1744325346.819 [SBIT](IMPORTANT): SBIT PASSED
2025-04-10T22:49:06.820Z,1744325346.820 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-04-10T22:49:06.821Z,1744325346.821 [SBIT](IMPORTANT): CBIT.gf24Offset=173 microampere;

2025-04-10T22:49:06.821Z,1744325346.821 [SBIT](IMPORTANT): CBIT.runNavErrorCritical=0 bool;

2025-04-10T22:49:06.821Z,1744325346.821 [SBIT](IMPORTANT): DAT.convertPhaseDataToDirection=0 bool;

2025-04-10T22:49:06.821Z,1744325346.821 [SBIT](IMPORTANT): DockingStepper.closedValue=16450 count;

2025-04-10T22:49:06.821Z,1744325346.821 [SBIT](IMPORTANT): DockingStepper.slideValue=10700 count;

2025-04-10T22:49:06.821Z,1744325346.821 [SBIT](IMPORTANT): IBIT.batteryCapacityThreshold=20 ampere_hour;

2025-04-10T22:49:06.821Z,1744325346.821 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=12 volt;

2025-04-10T22:49:06.822Z,1744325346.822 [SBIT](IMPORTANT): RudderServo.offsetAngle=0.2 degree;

2025-04-10T22:49:06.822Z,1744325346.822 [SBIT](IMPORTANT): TrnDataBridge.loadAtStartup=1 bool;

2025-04-10T22:49:06.822Z,1744325346.822 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=460.577328 cubic_centimeter;

2025-04-10T22:49:06.822Z,1744325346.822 [SBIT](IMPORTANT): VerticalControl.massDefault=20.377216 millimeter;

2025-04-10T22:49:07.177Z,1744325347.177 [MissionManager](IMPORTANT): Started mission DefaultWithUndock
2025-04-10T22:49:07.177Z,1744325347.177 [DefaultWithUndock] Running Loop=1
2025-04-10T22:49:07.177Z,1744325347.177 [DefaultWithUndock](DEBUG): Aggregate::initialize DefaultWithUndock
2025-04-10T22:49:07.178Z,1744325347.178 [DefaultWithUndock:A.Wait] Running Loop=1
2025-04-10T22:49:07.178Z,1744325347.178 [DefaultWithUndock:A.Wait](DEBUG): Initialize Wait Component.
2025-04-10T22:49:09.102Z,1744325349.102 [WetLabsUBAT](FAULT): Failed to acquire valid data within specified timeout upon startup.
2025-04-10T22:49:09.102Z,1744325349.102 [WetLabsUBAT] Communications Fault, FailCount= 5
2025-04-10T22:49:09.102Z,1744325349.102 [WetLabsUBAT](ERROR): Communications Fault
2025-04-10T22:49:09.276Z,1744325349.276 [CBIT](ERROR): Communications Fault in component: WetLabsUBAT
2025-04-10T22:49:09.276Z,1744325349.276 [CBIT](CRITICAL): Communications Fault in component: WetLabsUBAT
2025-04-10T22:49:09.606Z,1744325349.606 [WetLabsUBAT](INFO): Powering down
2025-04-10T22:49:09.680Z,1744325349.680 [CBIT](INFO): Critical error at 20250410T224909
2025-04-10T22:49:15.162Z,1744325355.162 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-04-10T22:49:23.414Z,1744325363.414 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-04-10T22:49:31.858Z,1744325371.858 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-04-10T22:49:35.119Z,1744325375.119 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2025-04-10T22:49:35.119Z,1744325375.119 [WetLabsBB2FL] No Fault, FailCount= 5
2025-04-10T22:49:35.243Z,1744325375.243 [WetLabsBB2FL](INFO): Powering up
2025-04-10T22:49:37.478Z,1744325377.478 [DefaultWithUndock:A.Wait](INFO): Done Waiting.
2025-04-10T22:49:37.478Z,1744325377.478 [DefaultWithUndock:A.Wait] Stopped
2025-04-10T22:49:37.478Z,1744325377.478 [DefaultWithUndock:A.Wait](DEBUG): Uninitialize Wait Component.
2025-04-10T22:49:37.479Z,1744325377.479 [DefaultWithUndock:LeaveDock] Running Loop=1
2025-04-10T22:49:37.479Z,1744325377.479 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::initialize DefaultWithUndock:LeaveDock
2025-04-10T22:49:37.479Z,1744325377.479 [DefaultWithUndock:LeaveDock:A.] Running Loop=1
2025-04-10T22:49:37.479Z,1744325377.479 [DefaultWithUndock:LeaveDock:A.](INFO): Initializing TrackAcousticContact.
2025-04-10T22:49:37.479Z,1744325377.479 [DefaultWithUndock:LeaveDock:B.Undock] Running Loop=1
2025-04-10T22:49:37.479Z,1744325377.479 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize.
2025-04-10T22:49:37.479Z,1744325377.479 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2025-04-10T22:49:37.879Z,1744325377.879 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initialize.
2025-04-10T22:49:37.879Z,1744325377.879 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2025-04-10T22:49:37.881Z,1744325377.881 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detached from dock at depth -0.168045 m.
2025-04-10T22:49:37.881Z,1744325377.881 [DefaultWithUndock:LeaveDock:A.] Stopped
2025-04-10T22:49:37.882Z,1744325377.882 [DefaultWithUndock:LeaveDock:A.](DEBUG): Uninitializing TrackAcousticContact.
2025-04-10T22:49:38.287Z,1744325378.287 [DefaultWithUndock:LeaveDock:B.Undock](INFO): Detected possible detachment. Starting timer.
2025-04-10T22:49:40.210Z,1744325380.210 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-04-10T22:49:40.533Z,1744325380.533 [WetLabsBB2FL] Communications Fault, FailCount= 1
2025-04-10T22:49:40.533Z,1744325380.533 [WetLabsBB2FL](ERROR): Communications Fault
2025-04-10T22:49:40.820Z,1744325380.820 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2025-04-10T22:49:41.034Z,1744325381.034 [WetLabsBB2FL](INFO): Powering down
2025-04-10T22:49:42.007Z,1744325382.007 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2025-04-10T22:49:42.007Z,1744325382.007 [WetLabsBB2FL] No Fault, FailCount= 1
2025-04-10T22:49:42.247Z,1744325382.247 [WetLabsBB2FL](INFO): Powering up
2025-04-10T22:49:42.979Z,1744325382.979 [CommandExec](IMPORTANT): got command docking standby
2025-04-10T22:49:42.979Z,1744325382.979 [CommandExec](IMPORTANT): Switching docking module to STANDBY.
2025-04-10T22:49:43.182Z,1744325383.182 [DockingStepper](INFO): Changing to mode: 1
2025-04-10T22:49:43.182Z,1744325383.182 [DockingStepper](INFO): Standby mode.
2025-04-10T22:49:43.254Z,1744325383.254 [DockingStepper](INFO): Commanding positive:54389
2025-04-10T22:49:44.443Z,1744325384.443 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2025-04-10T22:49:44.443Z,1744325384.443 [CTD_Seabird] No Fault, FailCount= 3
2025-04-10T22:49:44.646Z,1744325384.646 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-04-10T22:49:47.537Z,1744325387.537 [WetLabsBB2FL] Communications Fault, FailCount= 2
2025-04-10T22:49:47.537Z,1744325387.537 [WetLabsBB2FL](ERROR): Communications Fault
2025-04-10T22:49:47.679Z,1744325387.679 [CBIT](ERROR): Communications Fault in component: WetLabsBB2FL
2025-04-10T22:49:48.034Z,1744325388.034 [WetLabsBB2FL](INFO): Powering down
2025-04-10T22:49:48.738Z,1744325388.738 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-04-10T22:49:48.875Z,1744325388.875 [CBIT](INFO): Clearing failed state for component WetLabsBB2FL
2025-04-10T22:49:48.875Z,1744325388.875 [WetLabsBB2FL] No Fault, FailCount= 2
2025-04-10T22:49:49.247Z,1744325389.247 [WetLabsBB2FL](INFO): Powering up
2025-04-10T22:49:51.327Z,1744325391.327 [CommandExec](IMPORTANT): got command quit
2025-04-10T22:49:52.330Z,1744325392.330 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-10T22:49:52.330Z,1744325392.330 [CommandExec](INFO): Uninitializing the command executive.
2025-04-10T22:49:52.330Z,1744325392.330 [CommandExec](INFO): Uninitializing the command scheduler.
2025-04-10T22:49:52.330Z,1744325392.330 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-04-10T22:49:52.438Z,1744325392.438 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2025-04-10T22:49:52.439Z,1744325392.439 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-04-10T22:49:52.439Z,1744325392.439 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-04-10T22:49:52.441Z,1744325392.441 [NavChartDb](INFO): Join timeout helper Thread ID is 5822
2025-04-10T22:49:52.534Z,1744325392.534 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-10T22:49:52.534Z,1744325392.534 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-04-10T22:49:52.550Z,1744325392.550 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2025-04-10T22:49:52.550Z,1744325392.550 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2025-04-10T22:49:52.550Z,1744325392.550 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 5823
2025-04-10T22:49:52.898Z,1744325392.898 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-10T22:49:52.898Z,1744325392.898 [WetLabsUBAT](INFO): Powering down
2025-04-10T22:49:52.899Z,1744325392.899 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2025-04-10T22:49:52.914Z,1744325392.914 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-04-10T22:49:52.914Z,1744325392.914 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-04-10T22:49:52.914Z,1744325392.914 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5824
2025-04-10T22:49:53.026Z,1744325393.026 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-10T22:49:53.122Z,1744325393.122 [WetLabsBB2FL](INFO): Powering down
2025-04-10T22:49:53.123Z,1744325393.123 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-04-10T22:49:53.123Z,1744325393.123 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-04-10T22:49:53.123Z,1744325393.123 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-04-10T22:49:53.124Z,1744325393.124 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5825
2025-04-10T22:49:53.682Z,1744325393.682 [CTD_Seabird](INFO): Powering down
2025-04-10T22:49:53.810Z,1744325393.810 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-10T22:49:53.810Z,1744325393.810 [CTD_Seabird](INFO): Powering down
2025-04-10T22:49:53.822Z,1744325393.822 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-04-10T22:49:53.826Z,1744325393.826 [ComponentRegistry](INFO): Shutting down TrnDataBridge ThreadHandler
2025-04-10T22:49:53.826Z,1744325393.826 [TrnDataBridge ThreadHandler](INFO): Thread cancelled.
2025-04-10T22:49:53.826Z,1744325393.826 [TrnDataBridge](INFO): Join timeout helper Thread ID is 5826
2025-04-10T22:49:54.194Z,1744325394.194 [TrnDataBridge ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-10T22:49:54.195Z,1744325394.195 [TrnDataBridge ThreadHandler](INFO): Thread cancelled.
2025-04-10T22:49:54.198Z,1744325394.198 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-04-10T22:49:54.199Z,1744325394.199 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-04-10T22:49:54.199Z,1744325394.199 [Radio_Surface](INFO): Join timeout helper Thread ID is 5827
2025-04-10T22:49:54.294Z,1744325394.294 [Radio_Surface](INFO): Powering down
2025-04-10T22:49:54.295Z,1744325394.295 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-10T22:49:54.295Z,1744325394.295 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-04-10T22:49:54.314Z,1744325394.314 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-04-10T22:49:54.315Z,1744325394.315 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-04-10T22:49:54.315Z,1744325394.315 [Onboard](INFO): Join timeout helper Thread ID is 5828
2025-04-10T22:49:56.578Z,1744325396.578 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-10T22:49:56.578Z,1744325396.578 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-04-10T22:49:56.590Z,1744325396.590 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-04-10T22:49:56.590Z,1744325396.590 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-04-10T22:49:56.591Z,1744325396.591 [DataOverHttps](INFO): Join timeout helper Thread ID is 5829
2025-04-10T22:49:56.826Z,1744325396.826 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-10T22:49:56.829Z,1744325396.829 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-04-10T22:49:56.830Z,1744325396.830 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2025-04-10T22:49:56.831Z,1744325396.831 [DAT ThreadHandler](INFO): Thread cancelled.
2025-04-10T22:49:56.831Z,1744325396.831 [DAT](INFO): Join timeout helper Thread ID is 5830
2025-04-10T22:49:56.918Z,1744325396.918 [DAT](INFO): Powering down
2025-04-10T22:49:56.991Z,1744325396.991 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-10T22:49:56.991Z,1744325396.991 [DAT](INFO): Powering down
2025-04-10T22:49:56.992Z,1744325396.992 [DAT ThreadHandler](INFO): Thread cancelled.
2025-04-10T22:49:57.002Z,1744325397.002 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2025-04-10T22:49:57.003Z,1744325397.003 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-04-10T22:49:57.003Z,1744325397.003 [BackseatComponent](INFO): Join timeout helper Thread ID is 5831
2025-04-10T22:49:57.134Z,1744325397.134 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-10T22:49:57.134Z,1744325397.134 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-04-10T22:49:57.142Z,1744325397.142 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-04-10T22:49:57.142Z,1744325397.142 [logger ThreadHandler](INFO): Thread cancelled.
2025-04-10T22:49:57.143Z,1744325397.143 [logger](INFO): Join timeout helper Thread ID is 5832
2025-04-10T22:49:57.156Z,1744325397.156 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-10T22:49:57.157Z,1744325397.157 [logger ThreadHandler](INFO): Thread cancelled.
2025-04-10T22:49:57.166Z,1744325397.166 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-04-10T22:49:57.166Z,1744325397.166 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-04-10T22:49:57.167Z,1744325397.167 [CommandLine](INFO): Join timeout helper Thread ID is 5833
2025-04-10T22:49:57.226Z,1744325397.226 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-10T22:49:57.226Z,1744325397.226 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-04-10T22:49:57.234Z,1744325397.234 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-04-10T22:49:57.234Z,1744325397.234 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-04-10T22:49:57.235Z,1744325397.235 [CommandExec](INFO): Join timeout helper Thread ID is 5834
2025-04-10T22:49:57.236Z,1744325397.236 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-04-10T22:49:57.236Z,1744325397.236 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-04-10T22:49:57.236Z,1744325397.236 [controlThread](INFO): Join timeout helper Thread ID is 5835
2025-04-10T22:49:57.246Z,1744325397.246 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-04-10T22:49:57.246Z,1744325397.246 [controlThread](DEBUG): Uninitializing ControlThread
2025-04-10T22:49:57.246Z,1744325397.246 [AHRS_M2](INFO): Powering down
2025-04-10T22:49:57.249Z,1744325397.249 [NAL9602](INFO): Powering down
2025-04-10T22:49:57.250Z,1744325397.250 [RDI_Pathfinder](INFO): Powering down
2025-04-10T22:49:57.251Z,1744325397.251 [Sonardyne_Nano](INFO): Powering down
2025-04-10T22:49:57.370Z,1744325397.370 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-04-10T22:49:57.371Z,1744325397.371 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-04-10T22:49:57.372Z,1744325397.372 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-04-10T22:49:57.372Z,1744325397.372 [MissionManager](INFO): Uninitializing Mission DefaultWithUndock
2025-04-10T22:49:57.372Z,1744325397.372 [DefaultWithUndock] Stopped
2025-04-10T22:49:57.372Z,1744325397.372 [DefaultWithUndock](DEBUG): Aggregate::uninitialize DefaultWithUndock
2025-04-10T22:49:57.372Z,1744325397.372 [DefaultWithUndock:LeaveDock] Stopped
2025-04-10T22:49:57.373Z,1744325397.373 [DefaultWithUndock:LeaveDock](DEBUG): Aggregate::uninitialize DefaultWithUndock:LeaveDock
2025-04-10T22:49:57.373Z,1744325397.373 [DefaultWithUndock:LeaveDock:B.Undock] Stopped
2025-04-10T22:49:57.380Z,1744325397.380 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-04-10T22:49:57.381Z,1744325397.381 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-04-10T22:49:57.381Z,1744325397.381 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-04-10T22:49:57.382Z,1744325397.382 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-04-10T22:49:57.382Z,1744325397.382 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-04-10T22:49:57.382Z,1744325397.382 [BuoyancyServo](INFO): Powering down
2025-04-10T22:49:57.514Z,1744325397.514 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-04-10T22:49:57.514Z,1744325397.514 [ElevatorServo](INFO): Powering down
2025-04-10T22:49:57.515Z,1744325397.515 [DockingStepper](DEBUG): Uninitialize Docking Stepper.
2025-04-10T22:49:57.515Z,1744325397.515 [DockingStepper](INFO): Powering down
2025-04-10T22:49:57.516Z,1744325397.516 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-04-10T22:49:57.516Z,1744325397.516 [MassServo](INFO): Powering down
2025-04-10T22:49:57.517Z,1744325397.517 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-04-10T22:49:57.517Z,1744325397.517 [RudderServo](INFO): Powering down
2025-04-10T22:49:57.518Z,1744325397.518 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2025-04-10T22:49:57.518Z,1744325397.518 [ThrusterServo](INFO): Powering down
2025-04-10T22:49:57.519Z,1744325397.519 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-04-10T22:49:57.519Z,1744325397.519 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-04-10T22:49:57.519Z,1744325397.519 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-04-10T22:49:57.519Z,1744325397.519 [CBIT](DEBUG): Powering off loads.
2025-04-10T22:49:57.533Z,1744325397.533 [CBIT](DEBUG): Disabling WDT.
2025-04-10T22:49:57.545Z,1744325397.545 [CBIT](DEBUG): Opening all GF detection circuits.
2025-04-10T22:49:57.546Z,1744325397.546 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-04-10T22:49:57.613Z,1744325397.613 [TrnDataBridge ThreadHandler](INFO): Thread cancelled.
2025-04-10T22:49:57.622Z,1744325397.622 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-04-10T22:49:57.624Z,1744325397.624 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-04-10T22:49:57.630Z,1744325397.630 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-04-10T22:49:57.636Z,1744325397.636 [DAT ThreadHandler](INFO): Thread cancelled.
2025-04-10T22:49:57.677Z,1744325397.677 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-04-10T22:49:57.856Z,1744325397.856 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2025-04-10T22:49:57.858Z,1744325397.858 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-04-10T22:49:57.862Z,1744325397.862 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-04-10T22:49:57.870Z,1744325397.870 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-04-10T22:49:57.936Z,1744325397.936 [logger ThreadHandler](INFO): Thread cancelled.