2025-11-07T02:12:20.961Z,1762481540.961 [Supervisor](DEBUG): Initializing supervisor.
2025-11-07T02:12:20.965Z,1762481540.965 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-11-07T02:12:20.965Z,1762481540.965 [SyncHandler](INFO): Protected caller Thread ID is 9717
2025-11-07T02:12:20.966Z,1762481540.966 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-11-07T02:12:20.967Z,1762481540.967 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-11-07T02:12:20.967Z,1762481540.967 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 9718
2025-11-07T02:12:20.971Z,1762481540.971 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-11-07T02:12:20.990Z,1762481540.990 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-11-07T02:12:20.991Z,1762481540.991 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-11-07T02:12:20.991Z,1762481540.991 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 9719
2025-11-07T02:12:20.995Z,1762481540.995 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-11-07T02:12:20.996Z,1762481540.996 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-11-07T02:12:20.997Z,1762481540.997 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 9720
2025-11-07T02:12:20.999Z,1762481540.999 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-11-07T02:12:20.000Z,1762481541.000 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-11-07T02:12:20.000Z,1762481541.000 [logger ThreadHandler](INFO): Protected caller Thread ID is 9721
2025-11-07T02:12:21.003Z,1762481541.003 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-11-07T02:12:21.004Z,1762481541.004 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-11-07T02:12:21.005Z,1762481541.005 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-11-07T02:12:21.110Z,1762481541.110 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-11-07T02:12:21.111Z,1762481541.111 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-11-07T02:12:21.837Z,1762481541.837 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-11-07T02:12:21.837Z,1762481541.837 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-11-07T02:12:22.083Z,1762481542.083 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-11-07T02:12:22.084Z,1762481542.084 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-11-07T02:12:22.173Z,1762481542.173 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-11-07T02:12:22.313Z,1762481542.313 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-11-07T02:12:22.313Z,1762481542.313 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-11-07T02:12:22.414Z,1762481542.414 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance
2025-11-07T02:12:22.414Z,1762481542.414 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-11-07T02:12:22.530Z,1762481542.530 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-11-07T02:12:22.531Z,1762481542.531 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-11-07T02:12:22.928Z,1762481542.928 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-11-07T02:12:22.929Z,1762481542.929 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-11-07T02:12:23.511Z,1762481543.511 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-11-07T02:12:23.512Z,1762481543.512 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-11-07T02:12:23.781Z,1762481543.781 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-11-07T02:12:23.782Z,1762481543.782 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-11-07T02:12:23.976Z,1762481543.976 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-11-07T02:12:23.976Z,1762481543.976 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-11-07T02:12:24.517Z,1762481544.517 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-11-07T02:12:24.518Z,1762481544.518 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-11-07T02:12:24.810Z,1762481544.810 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-11-07T02:12:24.811Z,1762481544.811 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-11-07T02:12:25.055Z,1762481545.055 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-11-07T02:12:25.056Z,1762481545.056 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-11-07T02:12:25.443Z,1762481545.443 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-11-07T02:12:25.444Z,1762481545.444 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-11-07T02:12:25.815Z,1762481545.815 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-11-07T02:12:25.817Z,1762481545.817 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2025-11-07T02:12:25.818Z,1762481545.818 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2025-11-07T02:12:25.957Z,1762481545.957 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2025-11-07T02:12:26.174Z,1762481546.174 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Dock.cfg
2025-11-07T02:12:26.476Z,1762481546.476 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/workSite.cfg
2025-11-07T02:12:26.581Z,1762481546.581 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2025-11-07T02:12:26.697Z,1762481546.697 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2025-11-07T02:12:26.793Z,1762481546.793 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2025-11-07T02:12:26.898Z,1762481546.898 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2025-11-07T02:12:27.018Z,1762481547.018 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2025-11-07T02:12:27.288Z,1762481547.288 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-11-07T02:12:27.288Z,1762481547.288 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2025-11-07T02:12:27.382Z,1762481547.382 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2025-11-07T02:12:27.485Z,1762481547.485 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2025-11-07T02:12:27.634Z,1762481547.634 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2025-11-07T02:12:27.741Z,1762481547.741 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2025-11-07T02:12:27.741Z,1762481547.741 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-11-07T02:12:27.756Z,1762481547.756 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-11-07T02:12:27.885Z,1762481547.885 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-11-07T02:12:27.886Z,1762481547.886 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-11-07T02:12:27.937Z,1762481547.937 [VerticalControl](DEBUG): Construct VerticalControl.
2025-11-07T02:12:27.996Z,1762481547.996 [VerticalControl] Loaded
2025-11-07T02:12:27.996Z,1762481547.996 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-11-07T02:12:27.999Z,1762481547.999 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-11-07T02:12:28.045Z,1762481548.045 [HorizontalControl] Loaded
2025-11-07T02:12:28.045Z,1762481548.045 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-11-07T02:12:28.048Z,1762481548.048 [SpeedControl](DEBUG): Construct SpeedControl.
2025-11-07T02:12:28.050Z,1762481548.050 [SpeedControl] Loaded
2025-11-07T02:12:28.051Z,1762481548.051 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-11-07T02:12:28.053Z,1762481548.053 [LoopControl](DEBUG): Construct LoopControl.
2025-11-07T02:12:28.054Z,1762481548.054 [LoopControl] Loaded
2025-11-07T02:12:28.054Z,1762481548.054 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-11-07T02:12:28.055Z,1762481548.055 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-11-07T02:12:28.055Z,1762481548.055 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-11-07T02:12:28.104Z,1762481548.104 [DepthRateCalculator] Loaded
2025-11-07T02:12:28.104Z,1762481548.104 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-11-07T02:12:28.109Z,1762481548.109 [PitchRateCalculator] Loaded
2025-11-07T02:12:28.109Z,1762481548.109 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-11-07T02:12:28.119Z,1762481548.119 [SpeedCalculator] Loaded
2025-11-07T02:12:28.119Z,1762481548.119 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-11-07T02:12:28.123Z,1762481548.123 [YawRateCalculator] Loaded
2025-11-07T02:12:28.123Z,1762481548.123 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-11-07T02:12:28.141Z,1762481548.141 [ElevatorOffsetCalculator] Loaded
2025-11-07T02:12:28.141Z,1762481548.141 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-11-07T02:12:28.142Z,1762481548.142 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-11-07T02:12:28.142Z,1762481548.142 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-11-07T02:12:28.172Z,1762481548.172 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-11-07T02:12:28.172Z,1762481548.172 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-11-07T02:12:28.230Z,1762481548.230 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-11-07T02:12:28.231Z,1762481548.231 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-11-07T02:12:28.563Z,1762481548.563 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-11-07T02:12:28.564Z,1762481548.564 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-11-07T02:12:28.736Z,1762481548.736 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-11-07T02:12:28.736Z,1762481548.736 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-11-07T02:12:29.199Z,1762481549.199 [AHRS_M2] Loaded
2025-11-07T02:12:29.199Z,1762481549.199 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-11-07T02:12:29.237Z,1762481549.237 [BackseatComponent] Loaded
2025-11-07T02:12:29.238Z,1762481549.238 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-11-07T02:12:29.239Z,1762481549.239 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 409EA4E0
2025-11-07T02:12:29.239Z,1762481549.239 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 9810
2025-11-07T02:12:29.242Z,1762481549.242 [LcmPublisher](INFO): Full slate access is ENABLED. All requested slate values (including non-universals) will be published on LCM.
2025-11-07T02:12:29.242Z,1762481549.242 [LcmPublisher] Loaded
2025-11-07T02:12:29.243Z,1762481549.243 [ComponentRegistry](DEBUG): SyncComponent "LcmPublisher" handled in the control thread.
2025-11-07T02:12:30.040Z,1762481550.040 [BPC1] Loaded
2025-11-07T02:12:30.040Z,1762481550.040 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-11-07T02:12:30.135Z,1762481550.135 [DAT] Loaded
2025-11-07T02:12:30.135Z,1762481550.135 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-11-07T02:12:30.136Z,1762481550.136 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40A1A4E0
2025-11-07T02:12:30.137Z,1762481550.137 [DAT ThreadHandler](INFO): Protected caller Thread ID is 9811
2025-11-07T02:12:30.208Z,1762481550.208 [DataOverHttps] Loaded
2025-11-07T02:12:30.208Z,1762481550.208 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-11-07T02:12:30.209Z,1762481550.209 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40A4A4E0
2025-11-07T02:12:30.209Z,1762481550.209 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 9812
2025-11-07T02:12:30.232Z,1762481550.232 [Depth_Keller] Loaded
2025-11-07T02:12:30.232Z,1762481550.232 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-11-07T02:12:30.237Z,1762481550.237 [DropWeight] Loaded
2025-11-07T02:12:30.237Z,1762481550.237 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-11-07T02:12:30.301Z,1762481550.301 [NAL9602] Loaded
2025-11-07T02:12:30.301Z,1762481550.301 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-11-07T02:12:30.330Z,1762481550.330 [Onboard] Loaded
2025-11-07T02:12:30.330Z,1762481550.330 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-11-07T02:12:30.331Z,1762481550.331 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40A7A4E0
2025-11-07T02:12:30.331Z,1762481550.331 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 9813
2025-11-07T02:12:30.345Z,1762481550.345 [Power24vConverter] Loaded
2025-11-07T02:12:30.345Z,1762481550.345 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-11-07T02:12:30.361Z,1762481550.361 [Radio_Surface] Loaded
2025-11-07T02:12:30.361Z,1762481550.361 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-11-07T02:12:30.362Z,1762481550.362 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40AAA4E0
2025-11-07T02:12:30.362Z,1762481550.362 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 9814
2025-11-07T02:12:30.410Z,1762481550.410 [RDI_Pathfinder] Loaded
2025-11-07T02:12:30.410Z,1762481550.410 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2025-11-07T02:12:30.425Z,1762481550.425 [Sonardyne_Nano] Loaded
2025-11-07T02:12:30.425Z,1762481550.425 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-11-07T02:12:30.425Z,1762481550.425 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-11-07T02:12:30.426Z,1762481550.426 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-11-07T02:12:30.536Z,1762481550.536 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-11-07T02:12:30.536Z,1762481550.536 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-11-07T02:12:30.549Z,1762481550.549 [NavChart] Loaded
2025-11-07T02:12:30.550Z,1762481550.550 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-11-07T02:12:30.555Z,1762481550.555 [UniversalFixResidualReporter] Loaded
2025-11-07T02:12:30.555Z,1762481550.555 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-11-07T02:12:30.556Z,1762481550.556 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-11-07T02:12:30.556Z,1762481550.556 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-11-07T02:12:30.711Z,1762481550.711 [SBIT](DEBUG): Construct Startup Built In Test.
2025-11-07T02:12:30.719Z,1762481550.719 [SBIT] Loaded
2025-11-07T02:12:30.720Z,1762481550.720 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-11-07T02:12:30.722Z,1762481550.722 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-11-07T02:12:30.735Z,1762481550.735 [IBIT] Loaded
2025-11-07T02:12:30.735Z,1762481550.735 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-11-07T02:12:30.740Z,1762481550.740 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-11-07T02:12:30.819Z,1762481550.819 [CBIT] Loaded
2025-11-07T02:12:30.819Z,1762481550.819 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-11-07T02:12:30.852Z,1762481550.852 [GFScanner] Loaded
2025-11-07T02:12:30.852Z,1762481550.852 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-11-07T02:12:30.853Z,1762481550.853 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-11-07T02:12:30.853Z,1762481550.853 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-11-07T02:12:31.301Z,1762481551.301 [BuoyancyServo] Loaded
2025-11-07T02:12:31.302Z,1762481551.302 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-11-07T02:12:31.324Z,1762481551.324 [ElevatorServo] Loaded
2025-11-07T02:12:31.325Z,1762481551.325 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-11-07T02:12:31.353Z,1762481551.353 [DockingStepper] Loaded
2025-11-07T02:12:31.353Z,1762481551.353 [ComponentRegistry](DEBUG): SyncComponent "DockingStepper" handled in the control thread.
2025-11-07T02:12:31.379Z,1762481551.379 [MassServo] Loaded
2025-11-07T02:12:31.379Z,1762481551.379 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-11-07T02:12:31.399Z,1762481551.399 [RudderServo] Loaded
2025-11-07T02:12:31.400Z,1762481551.400 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-11-07T02:12:31.418Z,1762481551.418 [ThrusterServo] Loaded
2025-11-07T02:12:31.418Z,1762481551.418 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2025-11-07T02:12:31.419Z,1762481551.419 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-11-07T02:12:31.419Z,1762481551.419 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-11-07T02:12:31.430Z,1762481551.430 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-11-07T02:12:31.430Z,1762481551.430 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-11-07T02:12:31.600Z,1762481551.600 [CTD_Seabird] Loaded
2025-11-07T02:12:31.600Z,1762481551.600 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-11-07T02:12:31.601Z,1762481551.601 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40C0D4E0
2025-11-07T02:12:31.602Z,1762481551.602 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 9815
2025-11-07T02:12:31.624Z,1762481551.624 [PAR_Licor] Loaded
2025-11-07T02:12:31.624Z,1762481551.624 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-11-07T02:12:31.669Z,1762481551.669 [WetLabsBB2FL] Loaded
2025-11-07T02:12:31.669Z,1762481551.669 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-11-07T02:12:31.670Z,1762481551.670 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40C3D4E0
2025-11-07T02:12:31.670Z,1762481551.670 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 9816
2025-11-07T02:12:31.691Z,1762481551.691 [WetLabsUBAT] Loaded
2025-11-07T02:12:31.691Z,1762481551.691 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2025-11-07T02:12:31.692Z,1762481551.692 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40C6D4E0
2025-11-07T02:12:31.692Z,1762481551.692 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 9817
2025-11-07T02:12:31.693Z,1762481551.693 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-11-07T02:12:31.699Z,1762481551.699 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-11-07T02:12:31.702Z,1762481551.702 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-11-07T02:12:31.713Z,1762481551.713 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-11-07T02:12:31.714Z,1762481551.714 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C9D4E0
2025-11-07T02:12:31.714Z,1762481551.714 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 9818
2025-11-07T02:12:31.719Z,1762481551.719 [Supervisor](INFO): Main Thread ID is 9716
2025-11-07T02:12:31.719Z,1762481551.719 [Supervisor](DEBUG): Running supervisor.
2025-11-07T02:12:31.720Z,1762481551.720 [CommandExec ThreadHandler](INFO): Handler Thread ID is 9819
2025-11-07T02:12:31.720Z,1762481551.720 [CommandExec](INFO): Initializing the command executive.
2025-11-07T02:12:31.722Z,1762481551.722 [CommandLine ThreadHandler](INFO): Handler Thread ID is 9820
2025-11-07T02:12:31.724Z,1762481551.724 [controlThread ThreadHandler](INFO): Handler Thread ID is 9821
2025-11-07T02:12:31.724Z,1762481551.724 [controlThread](DEBUG): Initializing ControlThread
2025-11-07T02:12:31.725Z,1762481551.725 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-11-07T02:12:31.727Z,1762481551.727 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-11-07T02:12:31.728Z,1762481551.728 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-11-07T02:12:31.728Z,1762481551.728 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-11-07T02:12:31.728Z,1762481551.728 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-11-07T02:12:31.729Z,1762481551.729 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-11-07T02:12:31.729Z,1762481551.729 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-11-07T02:12:31.729Z,1762481551.729 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-11-07T02:12:31.730Z,1762481551.730 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-11-07T02:12:31.734Z,1762481551.734 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-11-07T02:12:31.734Z,1762481551.734 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-11-07T02:12:31.735Z,1762481551.735 [SBIT](INFO): Initialize SBIT Component.
2025-11-07T02:12:31.735Z,1762481551.735 [SBIT](IMPORTANT): git: 2025-10-23
2025-11-07T02:12:31.736Z,1762481551.736 [SBIT](INFO): git hash: 8a36ff280500b42a57cbb356490d4e9b9ffd6cfe
2025-11-07T02:12:31.736Z,1762481551.736 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-11-07T02:12:31.737Z,1762481551.737 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2025-11-07T02:12:31.738Z,1762481551.738 [SBIT](INFO): Beginning SBIT in 75.000000 seconds.
2025-11-07T02:12:31.738Z,1762481551.738 [IBIT](INFO): Initialize IBIT Component.
2025-11-07T02:12:31.739Z,1762481551.739 [CBIT](DEBUG): Initialize CBIT Component.
2025-11-07T02:12:31.740Z,1762481551.740 [logger ThreadHandler](INFO): Handler Thread ID is 9822
2025-11-07T02:12:31.752Z,1762481551.752 [CBIT](DEBUG): Initialized mux pins.
2025-11-07T02:12:31.752Z,1762481551.752 [CBIT](DEBUG): Initializing the watchdog timer.
2025-11-07T02:12:31.760Z,1762481551.760 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 9823
2025-11-07T02:12:31.776Z,1762481551.776 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-11-07T02:12:31.776Z,1762481551.776 [CBIT](DEBUG): Initializing heartbeat.
2025-11-07T02:12:31.777Z,1762481551.777 [DAT ThreadHandler](INFO): Handler Thread ID is 9824
2025-11-07T02:12:31.777Z,1762481551.777 [DAT](INFO): Powering up
2025-11-07T02:12:31.778Z,1762481551.778 [DAT](DEBUG): Initializing DAT.
2025-11-07T02:12:31.781Z,1762481551.781 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 9826
2025-11-07T02:12:31.783Z,1762481551.783 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-11-07T02:12:31.800Z,1762481551.800 [Onboard ThreadHandler](INFO): Handler Thread ID is 9827
2025-11-07T02:12:31.817Z,1762481551.817 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 9828
2025-11-07T02:12:31.840Z,1762481551.840 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 9829
2025-11-07T02:12:31.841Z,1762481551.841 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-11-07T02:12:31.845Z,1762481551.845 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 9831
2025-11-07T02:12:31.848Z,1762481551.848 [CBIT](DEBUG): Deactivating emergency mode.
2025-11-07T02:12:31.848Z,1762481551.848 [WetLabsBB2FL](INFO): Powering up
2025-11-07T02:12:31.850Z,1762481551.850 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 9833
2025-11-07T02:12:31.852Z,1762481551.852 [WetLabsUBAT](INFO): Powering up
2025-11-07T02:12:31.854Z,1762481551.854 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 9835
2025-11-07T02:12:31.857Z,1762481551.857 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-11-07T02:12:31.857Z,1762481551.857 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-11-07T02:12:31.857Z,1762481551.857 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-11-07T02:12:31.857Z,1762481551.857 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-11-07T02:12:31.858Z,1762481551.858 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-11-07T02:12:31.858Z,1762481551.858 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-11-07T02:12:31.858Z,1762481551.858 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-11-07T02:12:31.858Z,1762481551.858 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-11-07T02:12:31.888Z,1762481551.888 [CBIT](DEBUG): Backplane powered.
2025-11-07T02:12:31.888Z,1762481551.888 [GFScanner](DEBUG): Initializing GFScanner
2025-11-07T02:12:31.888Z,1762481551.888 [GFScanner](DEBUG): Deactivating GF circuits.
2025-11-07T02:12:31.896Z,1762481551.896 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-11-07T02:12:31.897Z,1762481551.897 [MissionManager](INFO): Loading Mission from file: Missions/underIce/StartupUnder.tl
2025-11-07T02:12:31.897Z,1762481551.897 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/StartupUnder.tl
2025-11-07T02:12:31.898Z,1762481551.898 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/StartupUnder.tl
2025-11-07T02:12:31.916Z,1762481551.916 [MissionManager](DEBUG): TethyslAPI: Missions/underIce/StartupUnder.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="StartupUnder" />

2025-11-07T02:12:31.916Z,1762481551.916 [MissionManager](ERROR): Top node has no child nodes: Mission
2025-11-07T02:12:31.916Z,1762481551.916 [MissionManager](ERROR): Could not load startup mission.
2025-11-07T02:12:31.917Z,1762481551.917 [MissionManager](INFO): Loading Mission from file: Missions/underIce/DefaultWithUndock.tl
2025-11-07T02:12:31.917Z,1762481551.917 [MissionManager](DEBUG): TethyslAPI: loading: Missions/underIce/DefaultWithUndock.tl
2025-11-07T02:12:31.918Z,1762481551.918 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/underIce/DefaultWithUndock.tl
2025-11-07T02:12:32.228Z,1762481552.228 [Radio_Surface](INFO): Powering up
2025-11-07T02:12:32.484Z,1762481552.484 [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-11-07T02:12:32.507Z,1762481552.507 [MissionManager](INFO): DefineArg DefaultWithUndock.ElapsedSinceDefaultStarted = 0.000000 min
2025-11-07T02:12:32.511Z,1762481552.511 [MissionManager](INFO): DefineArg DefaultWithUndock.DetachTimeout = 6.000000 h
2025-11-07T02:12:32.531Z,1762481552.531 [MissionManager](INFO): DefineArg DefaultWithUndock.TransponderCode = 50 count
2025-11-07T02:12:32.543Z,1762481552.543 [MissionManager](INFO): DefineArg DefaultWithUndock.TrackingUpdatePeriod = 10.000000 s
2025-11-07T02:12:32.563Z,1762481552.563 [MissionManager](INFO): DefineArg DefaultWithUndock.NumberOfPings = 1.000000 count
2025-11-07T02:12:32.567Z,1762481552.567 [MissionManager](INFO): DefineArg DefaultWithUndock.DriftTimeout = 3.000000 min
2025-11-07T02:12:32.584Z,1762481552.584 [DefaultWithUndock:A.Wait](DEBUG): Construct Wait.
2025-11-07T02:12:32.748Z,1762481552.748 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Construct.
2025-11-07T02:12:32.748Z,1762481552.748 [DefaultWithUndock:LeaveDock:B.Undock](DEBUG): Initializing internal variables to default values.
2025-11-07T02:12:32.814Z,1762481552.814 [DefaultWithUndock:SurfaceDefault:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-11-07T02:12:32.837Z,1762481552.837 [DefaultWithUndock:SurfaceDefault:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-11-07T02:12:32.864Z,1762481552.864 [DefaultWithUndock:SurfaceDefault:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-11-07T02:12:32.870Z,1762481552.870 [DefaultWithUndock:E.Execute](DEBUG): Construct Execute.
2025-11-07T02:12:32.891Z,1762481552.891 [MissionManager](DEBUG): 
#   Copyright (c) 2024 MBARI
#   MBARI Proprietary Information. Confidential. All Rights Reserved
#   Unauthorized copying or distribution of this file via any medium is strictly
#   prohibited.
#
#   WARNING - This file contains information whose export is restricted by the
#   Export Administration Act of 1979 (Title 50, U.S.C., App. 2401 et seq.), as
#   amended. Violations of these export laws are subject to severe civil and/or
#   criminal penalties.

mission DefaultWithUndock {
  arguments {
    ElapsedSinceDefaultStarted = 0 minute

    DetachTimeout = 6 hour
      """
      Maximum time duration for the vehicle to detach form the dock.
      """

    TransponderCode = Dock:Dock.transponderCode
      """
      Dock transponder address.
      """

    TrackingUpdatePeriod = 10 second
      """
      Time duration between acoustic range queries while the mission is
      detaching from the dock.
      """

    NumberOfPings = 1 count
      """
      Number of pings requested each time.
      """

    DriftTimeout = 3 minute
      """
      How long to allow vehicle to drift away from dock before retrying reverse
      """
  }

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

    run in sequence

    set duration = 30 second
  }

  aggregate LeaveDock {
    run in sequence

    timeout duration=DetachTimeout {
      syslog important "Timed out while trying to detach from dock at current depth of "
           + Universal:depth~meter + "."
    }

    # Acoustic tracking directives

    behavior Estimation:TrackAcousticContact {
      run while (
        Universal:depth > Control:VerticalControl.surfaceThreshold
      )

      set contactLabelSetting = TransponderCode
      set numberOfSamplesSetting = NumberOfPings
      set updatePeriodSetting = TrackingUpdatePeriod
    }

    # Leave the dock

    behavior Dock:Undock {
      run in sequence

      set driftTimeout = DriftTimeout
    }
  }

  aggregate SurfaceDefault {
    run in sequence

    # This mission assumes open water so from here on it's the same old default that's always used

    behavior Guidance:GoToSurface {
      run in progression
    }

    aggregate CheckIn {
      run in sequence repeat=288

      readDatum id="Read_GPS" {
        timeout duration=P5M

        Universal:time_fix
      }

      readDatum id="Read_Iridium" {
        timeout duration=P2H {
          behavior Guidance:Execute {
            run in sequence

            set command = "Burn on"
          }

          syslog critical "Dropped weight due to communications timeout."
        }

        Universal:platform_communications
      }

      behavior Guidance:Wait {
        run in sequence

        set duration = 5 minute
      }

      assign in sequence ElapsedSinceDefaultStarted = elapsed ( Universal:mission_started )

      syslog important "Default mission has been running for " + ElapsedSinceDefaultStarted~minute
    }
  }

  syslog important "Restarting logs and Default mission."

  behavior Guidance:Execute {
    run in sequence

    set command = "restart logs"
  }
}


2025-11-07T02:12:32.895Z,1762481552.895 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,RDI_Pathfinder,Sonardyne_Nano,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,DockingStepper,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,GFScanner,LcmPublisher,Reporter,LogSplitter,
2025-11-07T02:12:32.926Z,1762481552.926 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-11-07T02:12:32.988Z,1762481552.988 [Depth_Keller](INFO): Initializing.
2025-11-07T02:12:32.989Z,1762481552.989 [Power24vConverter](INFO): Powering up.
2025-11-07T02:12:32.990Z,1762481552.990 [RDI_Pathfinder](IMPORTANT): Expecting PD13 message format
2025-11-07T02:12:32.996Z,1762481552.996 [Sonardyne_Nano](INFO): Initializing.
2025-11-07T02:12:33.040Z,1762481553.040 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-11-07T02:12:33.067Z,1762481553.067 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-11-07T02:12:33.076Z,1762481553.076 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-11-07T02:12:33.077Z,1762481553.077 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-11-07T02:12:33.084Z,1762481553.084 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-11-07T02:12:33.085Z,1762481553.085 [DockingStepper](DEBUG): Initializing EZServoServo.
2025-11-07T02:12:33.092Z,1762481553.092 [DockingStepper](DEBUG): Initializing DockingStepper.
2025-11-07T02:12:33.093Z,1762481553.093 [MassServo](DEBUG): Initializing EZServoServo.
2025-11-07T02:12:33.100Z,1762481553.100 [MassServo](DEBUG): Initializing MassServo.
2025-11-07T02:12:33.101Z,1762481553.101 [RudderServo](DEBUG): Initializing EZServoServo.
2025-11-07T02:12:33.108Z,1762481553.108 [RudderServo](DEBUG): Initializing RudderServo.
2025-11-07T02:12:33.109Z,1762481553.109 [ThrusterServo](DEBUG): Initializing EZServoServo.
2025-11-07T02:12:33.116Z,1762481553.116 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2025-11-07T02:12:33.342Z,1762481553.342 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2025-11-07T02:12:33.342Z,1762481553.342 [DropWeight] Hardware Fault, FailCount= 1
2025-11-07T02:12:33.342Z,1762481553.342 [DropWeight](ERROR): Hardware Fault
2025-11-07T02:12:33.366Z,1762481553.366 [CommandExec](FAULT): Scheduling is paused
2025-11-07T02:12:33.366Z,1762481553.366 [CBIT](INFO): Critical error at 20251107T021233
2025-11-07T02:12:33.367Z,1762481553.367 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2025-11-07T02:12:33.377Z,1762481553.377 [CBIT](ERROR): Hardware Fault in component: DropWeight
2025-11-07T02:12:33.378Z,1762481553.378 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2025-11-07T02:12:33.733Z,1762481553.733 [CBIT](INFO): Critical error at 20251107T021233
2025-11-07T02:12:34.460Z,1762481554.460 [WetLabsBB2FL](INFO): Powering down
2025-11-07T02:12:34.950Z,1762481554.950 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-11-07T02:12:35.985Z,1762481555.985 [CommandExec](IMPORTANT): got command docking arm
2025-11-07T02:12:35.985Z,1762481555.985 [CommandExec](IMPORTANT): Switching docking module to ARM.
2025-11-07T02:12:36.604Z,1762481556.604 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2103
2025-11-07T02:12:36.864Z,1762481556.864 [DockingStepper](INFO): Changing to mode: 2
2025-11-07T02:12:37.480Z,1762481557.480 [Radio_Surface](INFO): Checking local IP, expecting subnet 10.89.10
2025-11-07T02:12:37.481Z,1762481557.481 [Radio_Surface](INFO): Found local IP address 10.89.10.2 for eth0
2025-11-07T02:12:41.100Z,1762481561.100 [WetLabsUBAT](INFO): Powering down
2025-11-07T02:12:41.573Z,1762481561.573 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.322982,0.943335,-0.076170],[-0.946369,0.322627,-0.017265],[0.008288,0.077661,0.996945]]
2025-11-07T02:12:41.965Z,1762481561.965 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.321162,0.943927,-0.076535],[-0.946989,0.320798,-0.017335],[0.008189,0.078045,0.996916]]
2025-11-07T02:12:42.366Z,1762481562.366 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.313187,0.946567,-0.076973],[-0.949654,0.312864,-0.016540],[0.008425,0.078277,0.996896]]
2025-11-07T02:12:42.768Z,1762481562.768 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.308908,0.947971,-0.076982],[-0.951053,0.308614,-0.015982],[0.008607,0.078151,0.996904]]
2025-11-07T02:12:43.173Z,1762481563.173 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.306052,0.948889,-0.077088],[-0.951974,0.305786,-0.015531],[0.008836,0.078139,0.996903]]
2025-11-07T02:12:43.577Z,1762481563.577 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.305242,0.949137,-0.077240],[-0.952233,0.304981,-0.015437],[0.008905,0.078262,0.996893]]
2025-11-07T02:12:43.980Z,1762481563.980 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.305434,0.949089,-0.077073],[-0.952173,0.305159,-0.015602],[0.008711,0.078152,0.996903]]
2025-11-07T02:12:44.376Z,1762481564.376 [DAT](INFO): DAT read: 

2025-11-07T02:12:44.378Z,1762481564.378 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2025-11-07T02:12:44.789Z,1762481564.789 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.304153,0.949522,-0.076804],[-0.952583,0.303893,-0.015339],[0.008775,0.077828,0.996928]]
2025-11-07T02:12:45.193Z,1762481565.193 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.303596,0.949674,-0.077133],[-0.952760,0.303337,-0.015334],[0.008835,0.078144,0.996903]]
2025-11-07T02:12:45.597Z,1762481565.597 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.303800,0.949600,-0.077233],[-0.952696,0.303532,-0.015472],[0.008750,0.078280,0.996893]]
2025-11-07T02:12:45.854Z,1762481565.854 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005152
2025-11-07T02:12:46.001Z,1762481566.001 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.303783,0.949605,-0.077244],[-0.952701,0.303514,-0.015487],[0.008738,0.078295,0.996892]]
2025-11-07T02:12:46.404Z,1762481566.404 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.303995,0.949543,-0.077170],[-0.952634,0.303719,-0.015570],[0.008653,0.078248,0.996896]]
2025-11-07T02:12:46.809Z,1762481566.809 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.304275,0.949446,-0.077257],[-0.952546,0.303989,-0.015726],[0.008554,0.078376,0.996887]]
2025-11-07T02:12:46.852Z,1762481566.852 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-11-07T02:12:47.213Z,1762481567.213 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.304226,0.949473,-0.077119],[-0.952561,0.303942,-0.015679],[0.008553,0.078231,0.996899]]
2025-11-07T02:12:47.617Z,1762481567.617 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.304268,0.949456,-0.077163],[-0.952548,0.303982,-0.015713],[0.008538,0.078282,0.996895]]
2025-11-07T02:12:48.021Z,1762481568.021 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.304135,0.949498,-0.077173],[-0.952590,0.303853,-0.015654],[0.008585,0.078275,0.996895]]
2025-11-07T02:12:48.425Z,1762481568.425 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.304275,0.949457,-0.077127],[-0.952546,0.303990,-0.015696],[0.008543,0.078243,0.996898]]
2025-11-07T02:12:48.829Z,1762481568.829 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.304051,0.949524,-0.077177],[-0.952617,0.303772,-0.015620],[0.008613,0.078270,0.996895]]
2025-11-07T02:12:49.233Z,1762481569.233 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.304124,0.949506,-0.077113],[-0.952594,0.303842,-0.015645],[0.008576,0.078215,0.996900]]
2025-11-07T02:12:49.637Z,1762481569.637 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.303972,0.949554,-0.077119],[-0.952642,0.303697,-0.015557],[0.008648,0.078195,0.996901]]
2025-11-07T02:12:49.921Z,1762481569.921 [DAT](INFO): DAT read: MF Frequency Band

2025-11-07T02:12:49.922Z,1762481569.922 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.17.2

2025-11-07T02:12:49.922Z,1762481569.922 [DAT](INFO): DAT read: Nov  7 2025 02:10:17 

2025-11-07T02:12:50.041Z,1762481570.041 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.304003,0.949546,-0.077103],[-0.952632,0.303726,-0.015576],[0.008628,0.078186,0.996901]]
2025-11-07T02:12:50.444Z,1762481570.444 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.303994,0.949553,-0.077050],[-0.952635,0.303719,-0.015558],[0.008629,0.078130,0.996906]]
2025-11-07T02:12:50.849Z,1762481570.849 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.304148,0.949497,-0.077139],[-0.952586,0.303867,-0.015644],[0.008586,0.078240,0.996898]]
2025-11-07T02:12:50.929Z,1762481570.929 [DAT](INFO): DAT read: Features enabled [Bearing]

2025-11-07T02:12:50.930Z,1762481570.930 [DAT](INFO): DAT read: CONNECT 00600 bits/sec 1 of 4, Rate 1/2 CC 25.00ms MGP

2025-11-07T02:12:50.931Z,1762481570.931 [DAT](INFO): commRate: 600
2025-11-07T02:12:51.253Z,1762481571.253 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.304242,0.949466,-0.077149],[-0.952557,0.303956,-0.015702],[0.008542,0.078266,0.996896]]
2025-11-07T02:12:51.388Z,1762481571.388 [DockingStepper](FAULT): Timed out moving from: 3334 to: 3000 response: ÿ
2025-11-07T02:12:51.388Z,1762481571.388 [DockingStepper] Hardware Fault, FailCount= 1
2025-11-07T02:12:51.388Z,1762481571.388 [DockingStepper](ERROR): Hardware Fault
2025-11-07T02:12:51.428Z,1762481571.428 [CBIT](ERROR): Hardware Fault in component: DockingStepper
2025-11-07T02:12:51.656Z,1762481571.656 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.304048,0.949540,-0.076996],[-0.952618,0.303770,-0.015583],[0.008592,0.078086,0.996910]]
2025-11-07T02:12:51.703Z,1762481571.703 [DockingStepper](DEBUG): Uninitialize Docking Stepper.
2025-11-07T02:12:51.703Z,1762481571.703 [DockingStepper](INFO): Powering down
2025-11-07T02:12:52.465Z,1762481572.465 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.304345,0.949428,-0.077200],[-0.952523,0.304067,-0.015622],[0.008642,0.078289,0.996893]]
2025-11-07T02:12:52.869Z,1762481572.869 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.304594,0.949342,-0.077283],[-0.952443,0.304312,-0.015688],[0.008625,0.078386,0.996886]]
2025-11-07T02:12:52.996Z,1762481572.996 [DAT](INFO): entering command mode
2025-11-07T02:12:53.196Z,1762481573.196 [DAT](INFO): DAT read: 

2025-11-07T02:12:53.197Z,1762481573.197 [DAT](INFO): DAT read: user:1

2025-11-07T02:12:53.197Z,1762481573.197 [DAT](INFO): setting verbose to 3
2025-11-07T02:12:53.273Z,1762481573.273 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.304933,0.949238,-0.077220],[-0.952336,0.304643,-0.015790],[0.008537,0.078354,0.996889]]
2025-11-07T02:12:53.448Z,1762481573.448 [DAT](INFO): DAT read: user:1>

2025-11-07T02:12:53.449Z,1762481573.449 [DAT](INFO): DAT read: Verbose         | 3           

2025-11-07T02:12:53.450Z,1762481573.450 [DAT](INFO): set verbose to 3
2025-11-07T02:12:53.450Z,1762481573.450 [DAT](INFO): setting DatVerbose to 27440
2025-11-07T02:12:53.677Z,1762481573.677 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.305074,0.949198,-0.077158],[-0.952291,0.304783,-0.015815],[0.008505,0.078302,0.996893]]
2025-11-07T02:12:53.701Z,1762481573.701 [DAT](INFO): DAT read: user:2>

2025-11-07T02:12:53.702Z,1762481573.702 [DAT](INFO): DAT read: DatVerbose      | 27440       

2025-11-07T02:12:53.702Z,1762481573.702 [DAT](INFO): set DatVerbose to 27440
2025-11-07T02:12:53.702Z,1762481573.702 [DAT](INFO): setting transmit power to 8
2025-11-07T02:12:53.953Z,1762481573.953 [DAT](INFO): DAT read: user:3>

2025-11-07T02:12:53.953Z,1762481573.953 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2025-11-07T02:12:53.954Z,1762481573.954 [DAT](INFO): set transmit power to 8
2025-11-07T02:12:53.954Z,1762481573.954 [DAT](INFO): setting local address to 10
2025-11-07T02:12:54.081Z,1762481574.081 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.305148,0.949186,-0.077010],[-0.952267,0.304856,-0.015812],[0.008469,0.078159,0.996905]]
2025-11-07T02:12:54.205Z,1762481574.205 [DAT](INFO): DAT read: user:4>

2025-11-07T02:12:54.206Z,1762481574.206 [DAT](INFO): DAT read: LocalAddr       | 10          

2025-11-07T02:12:54.206Z,1762481574.206 [DAT](INFO): set local address to 10
2025-11-07T02:12:54.212Z,1762481574.212 [DAT](INFO): Setting time to: 2:12:54 And date to:11/7/2025
2025-11-07T02:12:54.456Z,1762481574.456 [DAT](INFO): DAT read: user:5>

2025-11-07T02:12:54.457Z,1762481574.457 [DAT](INFO): DAT read: Fri Nov 7, 2025  02:12:54  

2025-11-07T02:12:54.458Z,1762481574.458 [DAT](INFO): Local DAT time set to Fri Nov 7, 2025  02:12:54  

2025-11-07T02:12:54.490Z,1762481574.490 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.305182,0.949168,-0.077099],[-0.952256,0.304888,-0.015849],[0.008463,0.078254,0.996898]]
2025-11-07T02:12:54.889Z,1762481574.889 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.305187,0.949177,-0.076967],[-0.952255,0.304893,-0.015825],[0.008446,0.078122,0.996908]]
2025-11-07T02:12:55.293Z,1762481575.293 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.305131,0.949189,-0.077037],[-0.952273,0.304839,-0.015818],[0.008470,0.078187,0.996903]]
2025-11-07T02:12:55.697Z,1762481575.697 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.305201,0.949155,-0.077182],[-0.952250,0.304905,-0.015878],[0.008463,0.078343,0.996891]]
2025-11-07T02:12:56.100Z,1762481576.100 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.305225,0.949149,-0.077167],[-0.952243,0.304929,-0.015873],[0.008465,0.078327,0.996892]]
2025-11-07T02:12:56.909Z,1762481576.909 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.305143,0.949172,-0.077207],[-0.952268,0.304851,-0.015838],[0.008503,0.078355,0.996889]]
2025-11-07T02:12:57.312Z,1762481577.312 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.305462,0.949059,-0.077333],[-0.952167,0.305160,-0.015984],[0.008429,0.078517,0.996877]]
2025-11-07T02:12:57.716Z,1762481577.716 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.305384,0.949091,-0.077249],[-0.952192,0.305085,-0.015925],[0.008453,0.078419,0.996885]]
2025-11-07T02:12:58.121Z,1762481578.121 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.305327,0.949111,-0.077232],[-0.952210,0.305029,-0.015912],[0.008455,0.078399,0.996886]]
2025-11-07T02:12:58.559Z,1762481578.559 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.305256,0.949147,-0.077060],[-0.952233,0.304960,-0.015859],[0.008448,0.078220,0.996900]]
2025-11-07T02:12:59.339Z,1762481579.339 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.305431,0.949086,-0.077118],[-0.952177,0.305128,-0.015967],[0.008376,0.078307,0.996894]]
2025-11-07T02:12:59.737Z,1762481579.737 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.305313,0.949121,-0.077156],[-0.952215,0.305012,-0.015947],[0.008398,0.078338,0.996891]]
2025-11-07T02:13:00.141Z,1762481580.141 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.305031,0.949214,-0.077133],[-0.952305,0.304735,-0.015868],[0.008443,0.078294,0.996895]]
2025-11-07T02:13:00.557Z,1762481580.557 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.305065,0.949201,-0.077155],[-0.952294,0.304766,-0.015901],[0.008421,0.078325,0.996892]]
2025-11-07T02:13:00.561Z,1762481580.561 [NAL9602](INFO): Powering up NAL9602
2025-11-07T02:13:00.948Z,1762481580.948 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.305060,0.949205,-0.077131],[-0.952296,0.304763,-0.015883],[0.008431,0.078297,0.996894]]
2025-11-07T02:13:01.353Z,1762481581.353 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.305275,0.949145,-0.077006],[-0.952228,0.304969,-0.015990],[0.008308,0.078209,0.996902]]
2025-11-07T02:13:01.757Z,1762481581.757 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.304792,0.949296,-0.077061],[-0.952382,0.304494,-0.015874],[0.008395,0.078230,0.996900]]
2025-11-07T02:13:02.036Z,1762481582.036 [CommandExec](IMPORTANT): got command quit
2025-11-07T02:13:02.161Z,1762481582.161 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.303948,0.949576,-0.076951],[-0.952652,0.303649,-0.015841],[0.008324,0.078123,0.996909]]
2025-11-07T02:13:02.565Z,1762481582.565 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.302461,0.950042,-0.077057],[-0.953125,0.302167,-0.015730],[0.008340,0.078202,0.996903]]
2025-11-07T02:13:02.652Z,1762481582.652 [DataOverHttps](DEBUG): Managing dock network, ignoring radio surface power off
2025-11-07T02:13:02.969Z,1762481582.969 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.301231,0.950427,-0.077130],[-0.953515,0.300940,-0.015636],[0.008351,0.078254,0.996898]]
2025-11-07T02:13:03.040Z,1762481583.040 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-11-07T02:13:03.040Z,1762481583.040 [CommandExec](INFO): Uninitializing the command executive.
2025-11-07T02:13:03.040Z,1762481583.040 [CommandExec](INFO): Uninitializing the command scheduler.
2025-11-07T02:13:03.040Z,1762481583.040 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-11-07T02:13:03.096Z,1762481583.096 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2025-11-07T02:13:03.096Z,1762481583.096 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-11-07T02:13:03.096Z,1762481583.096 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-11-07T02:13:03.097Z,1762481583.097 [NavChartDb](INFO): Join timeout helper Thread ID is 9845
2025-11-07T02:13:03.373Z,1762481583.373 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.299742,0.950893,-0.077178],[-0.953983,0.299463,-0.015429],[0.008440,0.078252,0.996898]]
2025-11-07T02:13:03.551Z,1762481583.551 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-11-07T02:13:03.551Z,1762481583.551 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-11-07T02:13:03.564Z,1762481583.564 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2025-11-07T02:13:03.564Z,1762481583.564 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2025-11-07T02:13:03.564Z,1762481583.564 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 9846
2025-11-07T02:13:03.632Z,1762481583.632 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-11-07T02:13:03.632Z,1762481583.632 [WetLabsUBAT](INFO): Powering down
2025-11-07T02:13:03.633Z,1762481583.633 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2025-11-07T02:13:03.648Z,1762481583.648 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-11-07T02:13:03.648Z,1762481583.648 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-11-07T02:13:03.648Z,1762481583.648 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 9847
2025-11-07T02:13:03.777Z,1762481583.777 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.298188,0.951371,-0.077311],[-0.954470,0.297908,-0.015401],[0.008380,0.078384,0.996888]]
2025-11-07T02:13:04.100Z,1762481584.100 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-11-07T02:13:04.100Z,1762481584.100 [WetLabsBB2FL](INFO): Powering down
2025-11-07T02:13:04.101Z,1762481584.101 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-11-07T02:13:04.111Z,1762481584.111 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-11-07T02:13:04.111Z,1762481584.111 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-11-07T02:13:04.117Z,1762481584.117 [CTD_Seabird](INFO): Join timeout helper Thread ID is 9848
2025-11-07T02:13:04.181Z,1762481584.181 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.295798,0.952115,-0.077332],[-0.955213,0.295529,-0.015161],[0.008419,0.078353,0.996890]]
2025-11-07T02:13:04.585Z,1762481584.585 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.295255,0.952300,-0.077125],[-0.955383,0.294981,-0.015185],[0.008290,0.078167,0.996906]]
2025-11-07T02:13:04.972Z,1762481584.972 [CTD_Seabird](INFO): Powering down
2025-11-07T02:13:04.997Z,1762481584.997 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.297376,0.951640,-0.077127],[-0.954725,0.297087,-0.015461],[0.008200,0.078233,0.996901]]
2025-11-07T02:13:05.008Z,1762481585.008 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-11-07T02:13:05.008Z,1762481585.008 [CTD_Seabird](INFO): Powering down
2025-11-07T02:13:05.020Z,1762481585.020 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-11-07T02:13:05.032Z,1762481585.032 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-11-07T02:13:05.032Z,1762481585.032 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-11-07T02:13:05.032Z,1762481585.032 [Radio_Surface](INFO): Join timeout helper Thread ID is 9849
2025-11-07T02:13:05.360Z,1762481585.360 [Radio_Surface](INFO): Powering down
2025-11-07T02:13:05.361Z,1762481585.361 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-11-07T02:13:05.361Z,1762481585.361 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-11-07T02:13:05.373Z,1762481585.373 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-11-07T02:13:05.373Z,1762481585.373 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-11-07T02:13:05.373Z,1762481585.373 [Onboard](INFO): Join timeout helper Thread ID is 9850
2025-11-07T02:13:05.393Z,1762481585.393 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.298513,0.951296,-0.076980],[-0.954369,0.298233,-0.015386],[0.008322,0.078060,0.996914]]
2025-11-07T02:13:05.797Z,1762481585.797 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.299722,0.950913,-0.077017],[-0.953989,0.299448,-0.015364],[0.008453,0.078079,0.996911]]
2025-11-07T02:13:06.201Z,1762481586.201 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.300984,0.950507,-0.077106],[-0.953593,0.300696,-0.015597],[0.008360,0.078222,0.996901]]
2025-11-07T02:13:06.605Z,1762481586.605 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.302028,0.950172,-0.077154],[-0.953264,0.301725,-0.015829],[0.008239,0.078329,0.996894]]
2025-11-07T02:13:06.829Z,1762481586.829 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-11-07T02:13:06.830Z,1762481586.830 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-11-07T02:13:06.840Z,1762481586.840 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-11-07T02:13:06.841Z,1762481586.841 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-11-07T02:13:06.841Z,1762481586.841 [DataOverHttps](INFO): Join timeout helper Thread ID is 9851
2025-11-07T02:13:07.009Z,1762481587.009 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.301956,0.950192,-0.077184],[-0.953285,0.301665,-0.015678],[0.008386,0.078312,0.996894]]
2025-11-07T02:13:07.413Z,1762481587.413 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.302132,0.950150,-0.077021],[-0.953229,0.301843,-0.015636],[0.008392,0.078143,0.996907]]
2025-11-07T02:13:07.818Z,1762481587.818 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.302283,0.950094,-0.077117],[-0.953181,0.301993,-0.015680],[0.008392,0.078246,0.996899]]
2025-11-07T02:13:08.179Z,1762481588.179 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-11-07T02:13:08.182Z,1762481588.182 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-11-07T02:13:08.188Z,1762481588.188 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2025-11-07T02:13:08.188Z,1762481588.188 [DAT ThreadHandler](INFO): Thread cancelled.
2025-11-07T02:13:08.189Z,1762481588.189 [DAT](INFO): Join timeout helper Thread ID is 9852
2025-11-07T02:13:08.249Z,1762481588.249 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.302573,0.950005,-0.077074],[-0.953090,0.302276,-0.015773],[0.008313,0.078231,0.996901]]
2025-11-07T02:13:08.320Z,1762481588.320 [DAT](INFO): Powering down
2025-11-07T02:13:08.392Z,1762481588.392 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-11-07T02:13:08.393Z,1762481588.393 [DAT](INFO): Powering down
2025-11-07T02:13:08.393Z,1762481588.393 [DAT ThreadHandler](INFO): Thread cancelled.
2025-11-07T02:13:08.404Z,1762481588.404 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2025-11-07T02:13:08.405Z,1762481588.405 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-11-07T02:13:08.405Z,1762481588.405 [BackseatComponent](INFO): Join timeout helper Thread ID is 9853
2025-11-07T02:13:08.509Z,1762481588.509 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2025-11-07T02:13:08.510Z,1762481588.510 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-11-07T02:13:08.530Z,1762481588.530 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-11-07T02:13:08.530Z,1762481588.530 [logger ThreadHandler](INFO): Thread cancelled.
2025-11-07T02:13:08.531Z,1762481588.531 [logger](INFO): Join timeout helper Thread ID is 9854
2025-11-07T02:13:08.537Z,1762481588.537 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-11-07T02:13:08.538Z,1762481588.538 [logger ThreadHandler](INFO): Thread cancelled.
2025-11-07T02:13:08.554Z,1762481588.554 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-11-07T02:13:08.554Z,1762481588.554 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-11-07T02:13:08.554Z,1762481588.554 [CommandLine](INFO): Join timeout helper Thread ID is 9855
2025-11-07T02:13:08.622Z,1762481588.622 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-11-07T02:13:08.622Z,1762481588.622 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-11-07T02:13:08.626Z,1762481588.626 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.303077,0.949847,-0.077036],[-0.952931,0.302768,-0.015943],[0.008181,0.078242,0.996901]]
2025-11-07T02:13:08.641Z,1762481588.641 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-11-07T02:13:08.641Z,1762481588.641 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-11-07T02:13:08.641Z,1762481588.641 [CommandExec](INFO): Join timeout helper Thread ID is 9856
2025-11-07T02:13:08.642Z,1762481588.642 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-11-07T02:13:08.643Z,1762481588.643 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-11-07T02:13:08.643Z,1762481588.643 [controlThread](INFO): Join timeout helper Thread ID is 9857
2025-11-07T02:13:08.702Z,1762481588.702 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-11-07T02:13:08.702Z,1762481588.702 [controlThread](DEBUG): Uninitializing ControlThread
2025-11-07T02:13:08.703Z,1762481588.703 [AHRS_M2](INFO): Powering down
2025-11-07T02:13:08.780Z,1762481588.780 [NAL9602](INFO): Powering down
2025-11-07T02:13:08.852Z,1762481588.852 [RDI_Pathfinder](INFO): Powering down
2025-11-07T02:13:08.853Z,1762481588.853 [Sonardyne_Nano](INFO): Powering down
2025-11-07T02:13:08.980Z,1762481588.980 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-11-07T02:13:08.981Z,1762481588.981 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-11-07T02:13:08.981Z,1762481588.981 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-11-07T02:13:08.982Z,1762481588.982 [MissionManager](INFO): Uninitializing Mission DefaultWithUndock
2025-11-07T02:13:08.990Z,1762481588.990 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-11-07T02:13:08.991Z,1762481588.991 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-11-07T02:13:08.991Z,1762481588.991 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-11-07T02:13:08.991Z,1762481588.991 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-11-07T02:13:08.992Z,1762481588.992 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-11-07T02:13:08.992Z,1762481588.992 [BuoyancyServo](INFO): Powering down
2025-11-07T02:13:09.004Z,1762481589.004 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-11-07T02:13:09.004Z,1762481589.004 [ElevatorServo](INFO): Powering down
2025-11-07T02:13:09.005Z,1762481589.005 [DockingStepper](DEBUG): Uninitialize Docking Stepper.
2025-11-07T02:13:09.005Z,1762481589.005 [DockingStepper](INFO): Powering down
2025-11-07T02:13:09.006Z,1762481589.006 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-11-07T02:13:09.006Z,1762481589.006 [MassServo](INFO): Powering down
2025-11-07T02:13:09.007Z,1762481589.007 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-11-07T02:13:09.007Z,1762481589.007 [RudderServo](INFO): Powering down
2025-11-07T02:13:09.008Z,1762481589.008 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2025-11-07T02:13:09.008Z,1762481589.008 [ThrusterServo](INFO): Powering down
2025-11-07T02:13:09.009Z,1762481589.009 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-11-07T02:13:09.009Z,1762481589.009 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-11-07T02:13:09.009Z,1762481589.009 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-11-07T02:13:09.009Z,1762481589.009 [CBIT](DEBUG): Powering off loads.
2025-11-07T02:13:09.024Z,1762481589.024 [CBIT](DEBUG): Disabling WDT.
2025-11-07T02:13:09.036Z,1762481589.036 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-11-07T02:13:09.036Z,1762481589.036 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-11-07T02:13:09.037Z,1762481589.037 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-11-07T02:13:09.091Z,1762481589.091 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-11-07T02:13:09.094Z,1762481589.094 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-11-07T02:13:09.100Z,1762481589.100 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-11-07T02:13:09.106Z,1762481589.106 [DAT ThreadHandler](INFO): Thread cancelled.
2025-11-07T02:13:09.148Z,1762481589.148 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-11-07T02:13:09.183Z,1762481589.183 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-11-07T02:13:09.183Z,1762481589.183 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-11-07T02:13:09.569Z,1762481589.569 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2025-11-07T02:13:09.571Z,1762481589.571 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-11-07T02:13:09.576Z,1762481589.576 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-11-07T02:13:09.584Z,1762481589.584 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-11-07T02:13:09.650Z,1762481589.650 [logger ThreadHandler](INFO): Thread cancelled.