2025-12-10T18:52:45.099Z,1765392765.099 [Supervisor](DEBUG): Initializing supervisor.
2025-12-10T18:52:45.103Z,1765392765.103 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-12-10T18:52:45.104Z,1765392765.104 [SyncHandler](INFO): Protected caller Thread ID is 1737
2025-12-10T18:52:45.104Z,1765392765.104 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-12-10T18:52:45.105Z,1765392765.105 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-12-10T18:52:45.106Z,1765392765.106 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1738
2025-12-10T18:52:45.110Z,1765392765.110 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-12-10T18:52:45.128Z,1765392765.128 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-12-10T18:52:45.129Z,1765392765.129 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-12-10T18:52:45.130Z,1765392765.130 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 1739
2025-12-10T18:52:45.134Z,1765392765.134 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-12-10T18:52:45.135Z,1765392765.135 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-12-10T18:52:45.135Z,1765392765.135 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1740
2025-12-10T18:52:45.137Z,1765392765.137 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-12-10T18:52:45.139Z,1765392765.139 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-12-10T18:52:45.140Z,1765392765.140 [logger ThreadHandler](INFO): Protected caller Thread ID is 1741
2025-12-10T18:52:45.143Z,1765392765.143 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-12-10T18:52:45.144Z,1765392765.144 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-12-10T18:52:45.149Z,1765392765.149 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-12-10T18:52:45.590Z,1765392765.590 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-12-10T18:52:45.592Z,1765392765.592 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-12-10T18:52:45.697Z,1765392765.697 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Guidance
2025-12-10T18:52:45.697Z,1765392765.697 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-12-10T18:52:46.285Z,1765392766.285 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-12-10T18:52:46.286Z,1765392766.286 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-12-10T18:52:46.630Z,1765392766.630 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-12-10T18:52:46.631Z,1765392766.631 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-12-10T18:52:46.739Z,1765392766.739 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-12-10T18:52:46.741Z,1765392766.741 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-12-10T18:52:47.140Z,1765392767.140 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-12-10T18:52:47.141Z,1765392767.141 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-12-10T18:52:47.378Z,1765392767.378 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-12-10T18:52:47.380Z,1765392767.380 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-12-10T18:52:47.905Z,1765392767.905 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-12-10T18:52:47.907Z,1765392767.907 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-12-10T18:52:48.052Z,1765392768.052 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-12-10T18:52:48.054Z,1765392768.054 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-12-10T18:52:48.145Z,1765392768.145 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-12-10T18:52:48.963Z,1765392768.963 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-12-10T18:52:48.963Z,1765392768.963 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-12-10T18:52:49.347Z,1765392769.347 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-12-10T18:52:49.348Z,1765392769.348 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-12-10T18:52:49.535Z,1765392769.535 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-12-10T18:52:49.536Z,1765392769.536 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-12-10T18:52:49.660Z,1765392769.660 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-12-10T18:52:49.661Z,1765392769.661 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-12-10T18:52:49.891Z,1765392769.891 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-12-10T18:52:49.893Z,1765392769.893 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-12-10T18:52:50.188Z,1765392770.188 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-12-10T18:52:50.190Z,1765392770.190 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2025-12-10T18:52:50.601Z,1765392770.601 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2025-12-10T18:52:50.870Z,1765392770.870 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2025-12-10T18:52:51.525Z,1765392771.525 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2025-12-10T18:52:51.620Z,1765392771.620 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2025-12-10T18:52:51.732Z,1765392771.732 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2025-12-10T18:52:51.837Z,1765392771.837 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2025-12-10T18:52:51.963Z,1765392771.963 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2025-12-10T18:52:52.121Z,1765392772.121 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2025-12-10T18:52:52.275Z,1765392772.275 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2025-12-10T18:52:52.395Z,1765392772.395 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2025-12-10T18:52:52.664Z,1765392772.664 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-12-10T18:52:52.665Z,1765392772.665 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2025-12-10T18:52:52.665Z,1765392772.665 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-12-10T18:52:52.678Z,1765392772.678 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-12-10T18:52:52.751Z,1765392772.751 [VerticalControl](DEBUG): Construct VerticalControl.
2025-12-10T18:52:52.810Z,1765392772.810 [VerticalControl] Loaded
2025-12-10T18:52:52.811Z,1765392772.811 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-12-10T18:52:52.813Z,1765392772.813 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-12-10T18:52:52.860Z,1765392772.860 [HorizontalControl] Loaded
2025-12-10T18:52:52.860Z,1765392772.860 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-12-10T18:52:52.863Z,1765392772.863 [SpeedControl](DEBUG): Construct SpeedControl.
2025-12-10T18:52:52.866Z,1765392772.866 [SpeedControl] Loaded
2025-12-10T18:52:52.866Z,1765392772.866 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-12-10T18:52:52.869Z,1765392772.869 [LoopControl](DEBUG): Construct LoopControl.
2025-12-10T18:52:52.869Z,1765392772.869 [LoopControl] Loaded
2025-12-10T18:52:52.869Z,1765392772.869 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-12-10T18:52:52.870Z,1765392772.870 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-12-10T18:52:52.872Z,1765392772.872 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-12-10T18:52:52.887Z,1765392772.887 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-12-10T18:52:52.888Z,1765392772.888 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-12-10T18:52:53.097Z,1765392773.097 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-12-10T18:52:53.098Z,1765392773.098 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-12-10T18:52:53.260Z,1765392773.260 [BuoyancyServo] Loaded
2025-12-10T18:52:53.260Z,1765392773.260 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-12-10T18:52:53.282Z,1765392773.282 [ElevatorServo] Loaded
2025-12-10T18:52:53.282Z,1765392773.282 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-12-10T18:52:53.303Z,1765392773.303 [MassServo] Loaded
2025-12-10T18:52:53.304Z,1765392773.304 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-12-10T18:52:53.323Z,1765392773.323 [RudderServo] Loaded
2025-12-10T18:52:53.324Z,1765392773.324 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-12-10T18:52:53.342Z,1765392773.342 [ThrusterHE] Loaded
2025-12-10T18:52:53.342Z,1765392773.342 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-12-10T18:52:53.343Z,1765392773.343 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-12-10T18:52:53.343Z,1765392773.343 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-12-10T18:52:53.484Z,1765392773.484 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-12-10T18:52:53.485Z,1765392773.485 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-12-10T18:52:53.498Z,1765392773.498 [NavChart] Loaded
2025-12-10T18:52:53.498Z,1765392773.498 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-12-10T18:52:53.504Z,1765392773.504 [UniversalFixResidualReporter] Loaded
2025-12-10T18:52:53.504Z,1765392773.504 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-12-10T18:52:53.504Z,1765392773.504 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-12-10T18:52:53.505Z,1765392773.505 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-12-10T18:52:53.599Z,1765392773.599 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-12-10T18:52:53.600Z,1765392773.600 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-12-10T18:52:54.454Z,1765392774.454 [AHRS_M2] Loaded
2025-12-10T18:52:54.455Z,1765392774.455 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-12-10T18:52:55.260Z,1765392775.260 [BPC1] Loaded
2025-12-10T18:52:55.260Z,1765392775.260 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-12-10T18:52:55.361Z,1765392775.361 [DAT] Loaded
2025-12-10T18:52:55.361Z,1765392775.361 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-12-10T18:52:55.362Z,1765392775.362 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4094D4E0
2025-12-10T18:52:55.363Z,1765392775.363 [DAT ThreadHandler](INFO): Protected caller Thread ID is 1821
2025-12-10T18:52:55.747Z,1765392775.747 [DataOverHttps] Loaded
2025-12-10T18:52:55.747Z,1765392775.747 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-12-10T18:52:55.762Z,1765392775.762 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4097D4E0
2025-12-10T18:52:55.790Z,1765392775.790 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1822
2025-12-10T18:52:55.840Z,1765392775.840 [Depth_Keller] Loaded
2025-12-10T18:52:55.840Z,1765392775.840 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-12-10T18:52:55.877Z,1765392775.877 [DropWeight] Loaded
2025-12-10T18:52:55.877Z,1765392775.877 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-12-10T18:52:56.035Z,1765392776.035 [NAL9602] Loaded
2025-12-10T18:52:56.035Z,1765392776.035 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-12-10T18:52:56.104Z,1765392776.104 [Onboard] Loaded
2025-12-10T18:52:56.105Z,1765392776.105 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-12-10T18:52:56.110Z,1765392776.110 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409AD4E0
2025-12-10T18:52:56.110Z,1765392776.110 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 1823
2025-12-10T18:52:56.132Z,1765392776.132 [Power24vConverter] Loaded
2025-12-10T18:52:56.132Z,1765392776.132 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-12-10T18:52:56.150Z,1765392776.150 [Radio_Surface] Loaded
2025-12-10T18:52:56.150Z,1765392776.150 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-12-10T18:52:56.151Z,1765392776.151 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409DD4E0
2025-12-10T18:52:56.152Z,1765392776.152 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1824
2025-12-10T18:52:56.167Z,1765392776.167 [Sonardyne_Nano] Loaded
2025-12-10T18:52:56.167Z,1765392776.167 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-12-10T18:52:56.168Z,1765392776.168 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-12-10T18:52:56.168Z,1765392776.168 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-12-10T18:52:56.299Z,1765392776.299 [DepthRateCalculator] Loaded
2025-12-10T18:52:56.300Z,1765392776.300 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-12-10T18:52:56.305Z,1765392776.305 [PitchRateCalculator] Loaded
2025-12-10T18:52:56.305Z,1765392776.305 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-12-10T18:52:56.313Z,1765392776.313 [SpeedCalculator] Loaded
2025-12-10T18:52:56.314Z,1765392776.314 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-12-10T18:52:56.318Z,1765392776.318 [YawRateCalculator] Loaded
2025-12-10T18:52:56.318Z,1765392776.318 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-12-10T18:52:56.337Z,1765392776.337 [ElevatorOffsetCalculator] Loaded
2025-12-10T18:52:56.337Z,1765392776.337 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-12-10T18:52:56.337Z,1765392776.337 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-12-10T18:52:56.339Z,1765392776.339 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-12-10T18:52:56.520Z,1765392776.520 [CANONSampler] Loaded
2025-12-10T18:52:56.520Z,1765392776.520 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2025-12-10T18:52:56.583Z,1765392776.583 [CTD_Seabird] Loaded
2025-12-10T18:52:56.584Z,1765392776.584 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-12-10T18:52:56.585Z,1765392776.585 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A9B4E0
2025-12-10T18:52:56.585Z,1765392776.585 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1825
2025-12-10T18:52:56.607Z,1765392776.607 [PAR_Licor] Loaded
2025-12-10T18:52:56.607Z,1765392776.607 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-12-10T18:52:56.653Z,1765392776.653 [WetLabsBB2FL] Loaded
2025-12-10T18:52:56.653Z,1765392776.653 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-12-10T18:52:56.654Z,1765392776.654 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40ACB4E0
2025-12-10T18:52:56.654Z,1765392776.654 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1826
2025-12-10T18:52:56.655Z,1765392776.655 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-12-10T18:52:56.656Z,1765392776.656 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-12-10T18:52:56.703Z,1765392776.703 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-12-10T18:52:56.703Z,1765392776.703 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-12-10T18:52:57.105Z,1765392777.105 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-12-10T18:52:57.106Z,1765392777.106 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-12-10T18:52:57.296Z,1765392777.296 [SBIT](DEBUG): Construct Startup Built In Test.
2025-12-10T18:52:57.305Z,1765392777.305 [SBIT] Loaded
2025-12-10T18:52:57.305Z,1765392777.305 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-12-10T18:52:57.308Z,1765392777.308 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-12-10T18:52:57.321Z,1765392777.321 [IBIT] Loaded
2025-12-10T18:52:57.321Z,1765392777.321 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-12-10T18:52:57.326Z,1765392777.326 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-12-10T18:52:57.407Z,1765392777.407 [CBIT] Loaded
2025-12-10T18:52:57.407Z,1765392777.407 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-12-10T18:52:57.439Z,1765392777.439 [GFScanner] Loaded
2025-12-10T18:52:57.440Z,1765392777.440 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-12-10T18:52:57.440Z,1765392777.440 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-12-10T18:52:57.441Z,1765392777.441 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-12-10T18:52:57.612Z,1765392777.612 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-12-10T18:52:57.618Z,1765392777.618 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-12-10T18:52:57.621Z,1765392777.621 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-12-10T18:52:57.632Z,1765392777.632 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-12-10T18:52:57.633Z,1765392777.633 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C3D4E0
2025-12-10T18:52:57.633Z,1765392777.633 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1827
2025-12-10T18:52:57.639Z,1765392777.639 [Supervisor](INFO): Main Thread ID is 1736
2025-12-10T18:52:57.639Z,1765392777.639 [Supervisor](DEBUG): Running supervisor.
2025-12-10T18:52:57.640Z,1765392777.640 [CommandExec ThreadHandler](INFO): Handler Thread ID is 1828
2025-12-10T18:52:57.640Z,1765392777.640 [CommandExec](INFO): Initializing the command executive.
2025-12-10T18:52:57.642Z,1765392777.642 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1829
2025-12-10T18:52:57.646Z,1765392777.646 [controlThread ThreadHandler](INFO): Handler Thread ID is 1830
2025-12-10T18:52:57.646Z,1765392777.646 [controlThread](DEBUG): Initializing ControlThread
2025-12-10T18:52:57.647Z,1765392777.647 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-12-10T18:52:57.649Z,1765392777.649 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-12-10T18:52:57.649Z,1765392777.649 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-12-10T18:52:57.650Z,1765392777.650 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-12-10T18:52:57.652Z,1765392777.652 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-12-10T18:52:57.652Z,1765392777.652 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-12-10T18:52:57.655Z,1765392777.655 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-12-10T18:52:57.656Z,1765392777.656 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-12-10T18:52:57.656Z,1765392777.656 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-12-10T18:52:57.656Z,1765392777.656 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-12-10T18:52:57.657Z,1765392777.657 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-12-10T18:52:57.658Z,1765392777.658 [SBIT](INFO): Initialize SBIT Component.
2025-12-10T18:52:57.659Z,1765392777.659 [SBIT](IMPORTANT): git: 2025-11-03_B
2025-12-10T18:52:57.659Z,1765392777.659 [SBIT](INFO): git hash: 2a90a5d137110d076944ce9cd5c4234179c797f8
2025-12-10T18:52:57.659Z,1765392777.659 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-12-10T18:52:57.661Z,1765392777.661 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2025-12-10T18:52:57.681Z,1765392777.681 [logger ThreadHandler](INFO): Handler Thread ID is 1831
2025-12-10T18:52:57.694Z,1765392777.694 [SBIT](INFO): Beginning SBIT in 59.000000 seconds.
2025-12-10T18:52:57.695Z,1765392777.695 [IBIT](INFO): Initialize IBIT Component.
2025-12-10T18:52:57.696Z,1765392777.696 [CBIT](DEBUG): Initialize CBIT Component.
2025-12-10T18:52:57.703Z,1765392777.703 [DAT ThreadHandler](INFO): Handler Thread ID is 1832
2025-12-10T18:52:57.703Z,1765392777.703 [DAT](INFO): Powering up
2025-12-10T18:52:57.704Z,1765392777.704 [DAT](DEBUG): Initializing DAT.
2025-12-10T18:52:57.708Z,1765392777.708 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1834
2025-12-10T18:52:57.709Z,1765392777.709 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-12-10T18:52:57.711Z,1765392777.711 [CBIT](DEBUG): Initialized mux pins.
2025-12-10T18:52:57.711Z,1765392777.711 [CBIT](DEBUG): Initializing the watchdog timer.
2025-12-10T18:52:57.719Z,1765392777.719 [Onboard ThreadHandler](INFO): Handler Thread ID is 1835
2025-12-10T18:52:57.734Z,1765392777.734 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-12-10T18:52:57.735Z,1765392777.735 [CBIT](DEBUG): Initializing heartbeat.
2025-12-10T18:52:57.737Z,1765392777.737 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1836
2025-12-10T18:52:57.763Z,1765392777.763 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1837
2025-12-10T18:52:57.763Z,1765392777.763 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-12-10T18:52:57.767Z,1765392777.767 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1839
2025-12-10T18:52:57.771Z,1765392777.771 [WetLabsBB2FL](INFO): Powering up
2025-12-10T18:52:57.772Z,1765392777.772 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1841
2025-12-10T18:52:57.780Z,1765392777.780 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-12-10T18:52:57.780Z,1765392777.780 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-12-10T18:52:57.780Z,1765392777.780 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-12-10T18:52:57.780Z,1765392777.780 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-12-10T18:52:57.780Z,1765392777.780 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-12-10T18:52:57.780Z,1765392777.780 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-12-10T18:52:57.781Z,1765392777.781 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-12-10T18:52:57.781Z,1765392777.781 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-12-10T18:52:57.806Z,1765392777.806 [CBIT](DEBUG): Deactivating emergency mode.
2025-12-10T18:52:57.847Z,1765392777.847 [CBIT](DEBUG): Backplane powered.
2025-12-10T18:52:57.847Z,1765392777.847 [GFScanner](DEBUG): Initializing GFScanner
2025-12-10T18:52:57.847Z,1765392777.847 [GFScanner](DEBUG): Deactivating GF circuits.
2025-12-10T18:52:57.851Z,1765392777.851 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-12-10T18:52:57.852Z,1765392777.852 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-12-10T18:52:57.852Z,1765392777.852 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-12-10T18:52:57.857Z,1765392777.857 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-12-10T18:52:57.909Z,1765392777.909 [MissionManager](DEBUG): TethyslAPI: Missions/Startup.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="Startup">
    <Guidance:GoToSurface>
        <Progression />
    </Guidance:GoToSurface>
    <Aggregate Id="StartupSatComms">
        <Sequence />
        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix />
        </ReadDatum>
        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications />
        </ReadDatum>
    </Aggregate>
</Mission>

2025-12-10T18:52:57.922Z,1765392777.922 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-12-10T18:52:58.010Z,1765392778.010 [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 Startup {
  behavior Guidance:GoToSurface {
    run in progression
  }

  aggregate StartupSatComms {
    run in sequence

    readDatum {
      timeout duration=P1M

      Universal:latitude_fix
    }

    readDatum {
      timeout duration=P1M

      Universal:platform_communications
    }
  }
}


2025-12-10T18:52:58.010Z,1765392778.010 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-12-10T18:52:58.011Z,1765392778.011 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-12-10T18:52:58.019Z,1765392778.019 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-12-10T18:52:58.146Z,1765392778.146 [Radio_Surface](INFO): Powering up
2025-12-10T18:52:58.340Z,1765392778.340 [MissionManager](DEBUG): TethyslAPI: Missions/Default.tl translated into:
<?xml version="1.0" encoding="UTF-8" ?>
<!--generated by tl2xml-->
<Mission Id="Default">
    <DefineArg Name="ElapsedSinceDefaultStarted">
        <Units:minute />
        <Value>0</Value>
    </DefineArg>
    <Guidance:Wait>
        <Sequence />
        <Setting>
            <Guidance:Wait.duration />
            <Units:second />
            <Value>13</Value>
        </Setting>
    </Guidance:Wait>
    <Guidance:GoToSurface>
        <Progression />
    </Guidance:GoToSurface>
    <Aggregate Id="CheckIn">
        <Sequence Repeat="288" />
        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M" />
            <Universal:time_fix />
        </ReadDatum>
        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P4H">
                <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>
    <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-12-10T18:52:58.354Z,1765392778.354 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-12-10T18:52:58.355Z,1765392778.355 [Default:A.Wait](DEBUG): Construct Wait.
2025-12-10T18:52:58.361Z,1765392778.361 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-12-10T18:52:58.399Z,1765392778.399 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-12-10T18:52:58.402Z,1765392778.402 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-12-10T18:52:58.423Z,1765392778.423 [Default:E.Execute](DEBUG): Construct Execute.
2025-12-10T18:52:58.442Z,1765392778.442 [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 Default {
  arguments {
    ElapsedSinceDefaultStarted = 0 minute
  }

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

    run in sequence

    set duration = 13 second
  }

  behavior Guidance:GoToSurface {
    run in progression
  }

  aggregate CheckIn {
    run in sequence repeat=288

    readDatum id="Read_GPS" {
      timeout duration=P5M

      Universal:time_fix
    }

    readDatum id="Read_Iridium" {
      timeout duration=P4H {
        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-12-10T18:52:58.447Z,1765392778.447 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,Sonardyne_Nano,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,Reporter,LogSplitter,
2025-12-10T18:52:58.466Z,1765392778.466 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-12-10T18:52:58.519Z,1765392778.519 [Depth_Keller](INFO): Initializing.
2025-12-10T18:52:58.520Z,1765392778.520 [Power24vConverter](INFO): Powering up.
2025-12-10T18:52:58.521Z,1765392778.521 [Sonardyne_Nano](INFO): Initializing.
2025-12-10T18:52:58.539Z,1765392778.539 [CANONSampler](INFO): Powering down
2025-12-10T18:52:58.563Z,1765392778.563 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-12-10T18:52:58.599Z,1765392778.599 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-12-10T18:52:58.606Z,1765392778.606 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-12-10T18:52:58.607Z,1765392778.607 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-12-10T18:52:58.614Z,1765392778.614 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-12-10T18:52:58.615Z,1765392778.615 [MassServo](DEBUG): Initializing EZServoServo.
2025-12-10T18:52:58.622Z,1765392778.622 [MassServo](DEBUG): Initializing MassServo.
2025-12-10T18:52:58.623Z,1765392778.623 [RudderServo](DEBUG): Initializing EZServoServo.
2025-12-10T18:52:58.631Z,1765392778.631 [RudderServo](DEBUG): Initializing RudderServo.
2025-12-10T18:52:58.631Z,1765392778.631 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-12-10T18:52:58.638Z,1765392778.638 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-12-10T18:52:59.878Z,1765392779.878 [WetLabsBB2FL](INFO): Powering down
2025-12-10T18:53:00.481Z,1765392780.481 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-12-10T18:53:01.763Z,1765392781.763 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2109
2025-12-10T18:53:04.223Z,1765392784.223 [ThrusterHE](ERROR): Zero Speed Commanded.
2025-12-10T18:53:06.967Z,1765392786.967 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985144,-0.171377,-0.010985],[0.171527,0.985071,0.014604],[0.008319,-0.016271,0.999833]]
2025-12-10T18:53:07.749Z,1765392787.749 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985139,-0.171417,-0.010874],[0.171565,0.985065,0.014594],[0.008210,-0.016243,0.999834]]
2025-12-10T18:53:08.151Z,1765392788.151 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985135,-0.171438,-0.010861],[0.171586,0.985061,0.014579],[0.008200,-0.016226,0.999835]]
2025-12-10T18:53:08.555Z,1765392788.555 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985143,-0.171393,-0.010843],[0.171542,0.985068,0.014633],[0.008173,-0.016276,0.999834]]
2025-12-10T18:53:08.964Z,1765392788.964 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985138,-0.171417,-0.010914],[0.171566,0.985064,0.014596],[0.008249,-0.016252,0.999834]]
2025-12-10T18:53:09.295Z,1765392789.295 [DAT](INFO): DAT read: 

2025-12-10T18:53:09.296Z,1765392789.296 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2025-12-10T18:53:09.370Z,1765392789.370 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985152,-0.171334,-0.010973],[0.171484,0.985079,0.014613],[0.008306,-0.016278,0.999833]]
2025-12-10T18:53:09.771Z,1765392789.771 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985167,-0.171248,-0.010946],[0.171398,0.985092,0.014709],[0.008264,-0.016368,0.999832]]
2025-12-10T18:53:10.175Z,1765392790.175 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985130,-0.171461,-0.010916],[0.171610,0.985057,0.014577],[0.008253,-0.016234,0.999834]]
2025-12-10T18:53:10.576Z,1765392790.576 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985117,-0.171538,-0.010913],[0.171687,0.985045,0.014515],[0.008260,-0.016172,0.999835]]
2025-12-10T18:53:10.807Z,1765392790.807 [DAT](INFO): DAT read: MF Frequency Band

2025-12-10T18:53:10.808Z,1765392790.808 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2025-12-10T18:53:10.809Z,1765392790.809 [DAT](INFO): DAT read: Jan  1 2000 00:00:07 

2025-12-10T18:53:10.979Z,1765392790.979 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985120,-0.171518,-0.010948],[0.171666,0.985048,0.014504],[0.008297,-0.016168,0.999835]]
2025-12-10T18:53:11.384Z,1765392791.384 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985124,-0.171496,-0.010934],[0.171645,0.985052,0.014486],[0.008287,-0.016148,0.999835]]
2025-12-10T18:53:11.787Z,1765392791.787 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985129,-0.171467,-0.010993],[0.171616,0.985057,0.014492],[0.008343,-0.016163,0.999835]]
2025-12-10T18:53:11.837Z,1765392791.837 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005244
2025-12-10T18:53:12.067Z,1765392792.067 [DAT](INFO): DAT read: Features enabled [Bearing]

2025-12-10T18:53:12.068Z,1765392792.068 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC

2025-12-10T18:53:12.069Z,1765392792.069 [DAT](INFO): commRate: 1200
2025-12-10T18:53:12.192Z,1765392792.192 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985133,-0.171454,-0.010816],[0.171601,0.985059,0.014570],[0.008156,-0.016210,0.999835]]
2025-12-10T18:53:12.595Z,1765392792.595 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985134,-0.171442,-0.010917],[0.171591,0.985060,0.014572],[0.008256,-0.016229,0.999834]]
2025-12-10T18:53:12.999Z,1765392792.999 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985147,-0.171358,-0.010988],[0.171509,0.985074,0.014646],[0.008314,-0.016313,0.999832]]
2025-12-10T18:53:13.403Z,1765392793.403 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985163,-0.171273,-0.010947],[0.171423,0.985088,0.014691],[0.008268,-0.016350,0.999832]]
2025-12-10T18:53:13.807Z,1765392793.807 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985148,-0.171359,-0.010961],[0.171509,0.985073,0.014674],[0.008283,-0.016336,0.999832]]
2025-12-10T18:53:14.134Z,1765392794.134 [DAT](INFO): entering command mode
2025-12-10T18:53:14.211Z,1765392794.211 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985107,-0.171591,-0.010961],[0.171740,0.985035,0.014527],[0.008304,-0.016193,0.999834]]
2025-12-10T18:53:14.335Z,1765392794.335 [DAT](INFO): DAT read: 

2025-12-10T18:53:14.335Z,1765392794.335 [DAT](INFO): DAT read: user:1>

2025-12-10T18:53:14.336Z,1765392794.336 [DAT](INFO): setting verbose to 3
2025-12-10T18:53:14.587Z,1765392794.587 [DAT](INFO): DAT read: user:1>

2025-12-10T18:53:14.588Z,1765392794.588 [DAT](INFO): DAT read: Verbose         | 3           

2025-12-10T18:53:14.588Z,1765392794.588 [DAT](INFO): set verbose to 3
2025-12-10T18:53:14.589Z,1765392794.589 [DAT](INFO): setting DatVerbose to 27440
2025-12-10T18:53:14.616Z,1765392794.616 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985111,-0.171578,-0.010814],[0.171725,0.985038,0.014537],[0.008158,-0.016178,0.999836]]
2025-12-10T18:53:14.839Z,1765392794.839 [DAT](INFO): DAT read: user:2>

2025-12-10T18:53:14.840Z,1765392794.840 [DAT](INFO): DAT read: DatVerbose      | 27440       

2025-12-10T18:53:14.840Z,1765392794.840 [DAT](INFO): set DatVerbose to 27440
2025-12-10T18:53:14.841Z,1765392794.841 [DAT](INFO): setting transmit power to 8
2025-12-10T18:53:15.019Z,1765392795.019 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985150,-0.171361,-0.010687],[0.171507,0.985073,0.014689],[0.008010,-0.016304,0.999835]]
2025-12-10T18:53:15.091Z,1765392795.091 [DAT](INFO): DAT read: user:3>

2025-12-10T18:53:15.092Z,1765392795.092 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2025-12-10T18:53:15.092Z,1765392795.092 [DAT](INFO): set transmit power to 8
2025-12-10T18:53:15.093Z,1765392795.093 [DAT](INFO): setting local address to 8
2025-12-10T18:53:15.343Z,1765392795.343 [DAT](INFO): DAT read: user:4>

2025-12-10T18:53:15.344Z,1765392795.344 [DAT](INFO): DAT read: LocalAddr       | 8           

2025-12-10T18:53:15.344Z,1765392795.344 [DAT](INFO): set local address to 8
2025-12-10T18:53:15.345Z,1765392795.345 [DAT](INFO): Setting time to: 18:53:15 And date to:12/10/2025
2025-12-10T18:53:15.423Z,1765392795.423 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985150,-0.171361,-0.010685],[0.171507,0.985073,0.014705],[0.008006,-0.016319,0.999835]]
2025-12-10T18:53:15.595Z,1765392795.595 [DAT](INFO): DAT read: user:5>

2025-12-10T18:53:15.596Z,1765392795.596 [DAT](INFO): DAT read: Wed Dec 10, 2025  18:53:15  

2025-12-10T18:53:15.596Z,1765392795.596 [DAT](INFO): Local DAT time set to Wed Dec 10, 2025  18:53:15  

2025-12-10T18:53:15.827Z,1765392795.827 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985127,-0.171496,-0.010710],[0.171642,0.985051,0.014621],[0.008042,-0.016242,0.999836]]
2025-12-10T18:53:16.232Z,1765392796.232 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985134,-0.171441,-0.010892],[0.171590,0.985060,0.014596],[0.008227,-0.016248,0.999834]]
2025-12-10T18:53:16.636Z,1765392796.636 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985130,-0.171463,-0.010973],[0.171612,0.985057,0.014577],[0.008310,-0.016243,0.999834]]
2025-12-10T18:53:17.039Z,1765392797.039 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985118,-0.171538,-0.010817],[0.171685,0.985044,0.014551],[0.008159,-0.016192,0.999836]]
2025-12-10T18:53:17.444Z,1765392797.444 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985120,-0.171530,-0.010731],[0.171676,0.985045,0.014611],[0.008064,-0.016236,0.999836]]
2025-12-10T18:53:17.848Z,1765392797.848 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985101,-0.171649,-0.010654],[0.171794,0.985025,0.014564],[0.007995,-0.016177,0.999837]]
2025-12-10T18:53:18.252Z,1765392798.252 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985112,-0.171578,-0.010736],[0.171724,0.985037,0.014588],[0.008072,-0.016215,0.999836]]
2025-12-10T18:53:18.656Z,1765392798.656 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985111,-0.171586,-0.010666],[0.171731,0.985036,0.014604],[0.008001,-0.016218,0.999836]]
2025-12-10T18:53:19.060Z,1765392799.060 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985111,-0.171591,-0.010624],[0.171735,0.985034,0.014638],[0.007954,-0.016245,0.999836]]
2025-12-10T18:53:19.464Z,1765392799.464 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985121,-0.171537,-0.010567],[0.171681,0.985044,0.014661],[0.007894,-0.016257,0.999837]]
2025-12-10T18:53:19.868Z,1765392799.868 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985114,-0.171575,-0.010629],[0.171719,0.985037,0.014638],[0.007959,-0.016246,0.999836]]
2025-12-10T18:53:20.271Z,1765392800.271 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985103,-0.171633,-0.010690],[0.171778,0.985028,0.014584],[0.008027,-0.016203,0.999837]]
2025-12-10T18:53:20.675Z,1765392800.675 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985113,-0.171573,-0.010691],[0.171718,0.985038,0.014591],[0.008028,-0.016209,0.999836]]
2025-12-10T18:53:21.081Z,1765392801.081 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985138,-0.171432,-0.010679],[0.171577,0.985061,0.014682],[0.008003,-0.016296,0.999835]]
2025-12-10T18:53:21.484Z,1765392801.484 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985152,-0.171349,-0.010683],[0.171495,0.985075,0.014724],[0.008001,-0.016338,0.999835]]
2025-12-10T18:53:21.888Z,1765392801.888 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985145,-0.171393,-0.010655],[0.171538,0.985068,0.014702],[0.007976,-0.016311,0.999835]]
2025-12-10T18:53:22.292Z,1765392802.292 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985136,-0.171452,-0.010527],[0.171596,0.985058,0.014686],[0.007852,-0.016274,0.999837]]
2025-12-10T18:53:22.696Z,1765392802.696 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985148,-0.171374,-0.010697],[0.171520,0.985072,0.014666],[0.008024,-0.016283,0.999835]]
2025-12-10T18:53:23.100Z,1765392803.100 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985159,-0.171308,-0.010730],[0.171455,0.985082,0.014694],[0.008053,-0.016316,0.999834]]
2025-12-10T18:53:23.448Z,1765392803.448 [CommandExec](IMPORTANT): got command restart system
2025-12-10T18:53:23.503Z,1765392803.503 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985137,-0.171441,-0.010598],[0.171586,0.985060,0.014681],[0.007923,-0.016281,0.999836]]
2025-12-10T18:53:23.907Z,1765392803.907 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985135,-0.171455,-0.010575],[0.171599,0.985057,0.014683],[0.007900,-0.016280,0.999836]]
2025-12-10T18:53:24.311Z,1765392804.311 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985136,-0.171442,-0.010676],[0.171587,0.985060,0.014643],[0.008006,-0.016258,0.999836]]
2025-12-10T18:53:24.719Z,1765392804.719 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985112,-0.171584,-0.010662],[0.171729,0.985037,0.014570],[0.008002,-0.016184,0.999837]]
2025-12-10T18:53:25.119Z,1765392805.119 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985149,-0.171374,-0.010635],[0.171520,0.985070,0.014735],[0.007951,-0.016340,0.999835]]
2025-12-10T18:53:25.523Z,1765392805.523 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985146,-0.171395,-0.010559],[0.171539,0.985068,0.014691],[0.007884,-0.016284,0.999836]]
2025-12-10T18:53:25.550Z,1765392805.550 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2025-12-10T18:53:25.551Z,1765392805.551 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-12-10T18:53:25.551Z,1765392805.551 [CommandExec](INFO): Uninitializing the command executive.
2025-12-10T18:53:25.551Z,1765392805.551 [CommandExec](INFO): Uninitializing the command scheduler.
2025-12-10T18:53:25.551Z,1765392805.551 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-12-10T18:53:25.730Z,1765392805.730 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2025-12-10T18:53:25.730Z,1765392805.730 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-12-10T18:53:25.731Z,1765392805.731 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-12-10T18:53:25.743Z,1765392805.743 [NavChartDb](INFO): Join timeout helper Thread ID is 1852
2025-12-10T18:53:25.943Z,1765392805.943 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985149,-0.171378,-0.010547],[0.171523,0.985069,0.014769],[0.007859,-0.016359,0.999835]]
2025-12-10T18:53:26.331Z,1765392806.331 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985155,-0.171345,-0.010555],[0.171489,0.985075,0.014782],[0.007864,-0.016372,0.999835]]
2025-12-10T18:53:26.348Z,1765392806.348 [NAL9602](INFO): Powering up NAL9602
2025-12-10T18:53:26.410Z,1765392806.410 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-12-10T18:53:26.411Z,1765392806.411 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-12-10T18:53:26.426Z,1765392806.426 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-12-10T18:53:26.426Z,1765392806.426 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-12-10T18:53:26.427Z,1765392806.427 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1853
2025-12-10T18:53:26.514Z,1765392806.514 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-12-10T18:53:26.515Z,1765392806.515 [WetLabsBB2FL](INFO): Powering down
2025-12-10T18:53:26.516Z,1765392806.516 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-12-10T18:53:26.534Z,1765392806.534 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-12-10T18:53:26.534Z,1765392806.534 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-12-10T18:53:26.535Z,1765392806.535 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1854
2025-12-10T18:53:26.735Z,1765392806.735 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985135,-0.171454,-0.010616],[0.171599,0.985057,0.014684],[0.007940,-0.016288,0.999836]]
2025-12-10T18:53:26.878Z,1765392806.878 [CTD_Seabird](INFO): Powering down
2025-12-10T18:53:26.890Z,1765392806.890 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-12-10T18:53:26.891Z,1765392806.891 [CTD_Seabird](INFO): Powering down
2025-12-10T18:53:26.902Z,1765392806.902 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-12-10T18:53:26.906Z,1765392806.906 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-12-10T18:53:26.906Z,1765392806.906 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-12-10T18:53:26.907Z,1765392806.907 [Radio_Surface](INFO): Join timeout helper Thread ID is 1855
2025-12-10T18:53:27.139Z,1765392807.139 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985120,-0.171537,-0.010682],[0.171682,0.985044,0.014618],[0.008015,-0.016234,0.999836]]
2025-12-10T18:53:27.234Z,1765392807.234 [Radio_Surface](INFO): Powering down
2025-12-10T18:53:27.235Z,1765392807.235 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-12-10T18:53:27.236Z,1765392807.236 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-12-10T18:53:27.254Z,1765392807.254 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-12-10T18:53:27.254Z,1765392807.254 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-12-10T18:53:27.255Z,1765392807.255 [Onboard](INFO): Join timeout helper Thread ID is 1856
2025-12-10T18:53:27.543Z,1765392807.543 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985087,-0.171726,-0.010628],[0.171870,0.985012,0.014540],[0.007972,-0.016150,0.999838]]
2025-12-10T18:53:27.759Z,1765392807.759 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-12-10T18:53:27.759Z,1765392807.759 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-12-10T18:53:27.771Z,1765392807.771 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-12-10T18:53:27.771Z,1765392807.771 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-12-10T18:53:27.772Z,1765392807.772 [DataOverHttps](INFO): Join timeout helper Thread ID is 1857
2025-12-10T18:53:27.894Z,1765392807.894 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-12-10T18:53:27.895Z,1765392807.895 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-12-10T18:53:27.899Z,1765392807.899 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2025-12-10T18:53:27.899Z,1765392807.899 [DAT ThreadHandler](INFO): Thread cancelled.
2025-12-10T18:53:27.900Z,1765392807.900 [DAT](INFO): Join timeout helper Thread ID is 1858
2025-12-10T18:53:27.944Z,1765392807.944 [DAT](INFO): Powering down
2025-12-10T18:53:27.949Z,1765392807.949 [AHRS_M2](DEBUG): AHRS rotation from veh to nav: [[0.985120,-0.171537,-0.010673],[0.171682,0.985044,0.014631],[0.008004,-0.016246,0.999836]]
2025-12-10T18:53:28.015Z,1765392808.015 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-12-10T18:53:28.015Z,1765392808.015 [DAT](INFO): Powering down
2025-12-10T18:53:28.016Z,1765392808.016 [DAT ThreadHandler](INFO): Thread cancelled.
2025-12-10T18:53:28.027Z,1765392808.027 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-12-10T18:53:28.027Z,1765392808.027 [logger ThreadHandler](INFO): Thread cancelled.
2025-12-10T18:53:28.028Z,1765392808.028 [logger](INFO): Join timeout helper Thread ID is 1859
2025-12-10T18:53:28.070Z,1765392808.070 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-12-10T18:53:28.070Z,1765392808.070 [logger ThreadHandler](INFO): Thread cancelled.
2025-12-10T18:53:28.087Z,1765392808.087 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-12-10T18:53:28.087Z,1765392808.087 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-12-10T18:53:28.087Z,1765392808.087 [CommandLine](INFO): Join timeout helper Thread ID is 1860
2025-12-10T18:53:28.158Z,1765392808.158 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-12-10T18:53:28.159Z,1765392808.159 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-12-10T18:53:28.167Z,1765392808.167 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-12-10T18:53:28.167Z,1765392808.167 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-12-10T18:53:28.167Z,1765392808.167 [CommandExec](INFO): Join timeout helper Thread ID is 1861
2025-12-10T18:53:28.169Z,1765392808.169 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-12-10T18:53:28.169Z,1765392808.169 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-12-10T18:53:28.169Z,1765392808.169 [controlThread](INFO): Join timeout helper Thread ID is 1862
2025-12-10T18:53:28.342Z,1765392808.342 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-12-10T18:53:28.343Z,1765392808.343 [controlThread](DEBUG): Uninitializing ControlThread
2025-12-10T18:53:28.343Z,1765392808.343 [AHRS_M2](INFO): Powering down
2025-12-10T18:53:28.416Z,1765392808.416 [NAL9602](INFO): Powering down
2025-12-10T18:53:28.417Z,1765392808.417 [Sonardyne_Nano](INFO): Powering down
2025-12-10T18:53:28.627Z,1765392808.627 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-12-10T18:53:28.627Z,1765392808.627 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-12-10T18:53:28.628Z,1765392808.628 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-12-10T18:53:28.628Z,1765392808.628 [MissionManager](INFO): Uninitializing Mission Default
2025-12-10T18:53:28.632Z,1765392808.632 [MissionManager](INFO): Uninitializing Mission Startup
2025-12-10T18:53:28.634Z,1765392808.634 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-12-10T18:53:28.634Z,1765392808.634 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-12-10T18:53:28.634Z,1765392808.634 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-12-10T18:53:28.635Z,1765392808.635 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-12-10T18:53:28.635Z,1765392808.635 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-12-10T18:53:28.635Z,1765392808.635 [BuoyancyServo](INFO): Powering down
2025-12-10T18:53:28.646Z,1765392808.646 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-12-10T18:53:28.647Z,1765392808.647 [ElevatorServo](INFO): Powering down
2025-12-10T18:53:28.648Z,1765392808.648 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-12-10T18:53:28.648Z,1765392808.648 [MassServo](INFO): Powering down
2025-12-10T18:53:28.648Z,1765392808.648 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-12-10T18:53:28.649Z,1765392808.649 [RudderServo](INFO): Powering down
2025-12-10T18:53:28.649Z,1765392808.649 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-12-10T18:53:28.650Z,1765392808.650 [ThrusterHE](INFO): Powering down
2025-12-10T18:53:28.651Z,1765392808.651 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-12-10T18:53:28.651Z,1765392808.651 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-12-10T18:53:28.651Z,1765392808.651 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-12-10T18:53:28.651Z,1765392808.651 [CBIT](DEBUG): Powering off loads.
2025-12-10T18:53:28.662Z,1765392808.662 [CBIT](DEBUG): Disabling WDT.
2025-12-10T18:53:28.674Z,1765392808.674 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-12-10T18:53:28.674Z,1765392808.674 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-12-10T18:53:28.676Z,1765392808.676 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-12-10T18:53:28.851Z,1765392808.851 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-12-10T18:53:28.854Z,1765392808.854 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-12-10T18:53:28.860Z,1765392808.860 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-12-10T18:53:28.867Z,1765392808.867 [DAT ThreadHandler](INFO): Thread cancelled.
2025-12-10T18:53:28.946Z,1765392808.946 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-12-10T18:53:28.951Z,1765392808.951 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-12-10T18:53:28.977Z,1765392808.977 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-12-10T18:53:28.977Z,1765392808.977 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-12-10T18:53:28.999Z,1765392808.999 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-12-10T18:53:29.066Z,1765392809.066 [logger ThreadHandler](INFO): Thread cancelled.