2025-03-26T20:00:38.053Z,1743019238.053 [Supervisor](DEBUG): Initializing supervisor.
2025-03-26T20:00:38.057Z,1743019238.057 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-03-26T20:00:38.058Z,1743019238.058 [SyncHandler](INFO): Protected caller Thread ID is 6494
2025-03-26T20:00:38.058Z,1743019238.058 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-03-26T20:00:38.059Z,1743019238.059 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-03-26T20:00:38.059Z,1743019238.059 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6495
2025-03-26T20:00:38.063Z,1743019238.063 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-03-26T20:00:38.083Z,1743019238.083 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-03-26T20:00:38.084Z,1743019238.084 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-03-26T20:00:38.085Z,1743019238.085 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 6496
2025-03-26T20:00:38.089Z,1743019238.089 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-03-26T20:00:38.090Z,1743019238.090 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-03-26T20:00:38.090Z,1743019238.090 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6497
2025-03-26T20:00:38.093Z,1743019238.093 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-03-26T20:00:38.093Z,1743019238.093 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-03-26T20:00:38.094Z,1743019238.094 [logger ThreadHandler](INFO): Protected caller Thread ID is 6498
2025-03-26T20:00:38.098Z,1743019238.098 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-03-26T20:00:38.098Z,1743019238.098 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-03-26T20:00:38.100Z,1743019238.100 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-03-26T20:00:38.211Z,1743019238.211 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-03-26T20:00:38.212Z,1743019238.212 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-03-26T20:00:38.463Z,1743019238.463 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-03-26T20:00:38.463Z,1743019238.463 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-03-26T20:00:38.756Z,1743019238.756 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-03-26T20:00:38.757Z,1743019238.757 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-03-26T20:00:38.880Z,1743019238.880 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-03-26T20:00:38.880Z,1743019238.880 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-03-26T20:00:39.707Z,1743019239.707 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-03-26T20:00:39.708Z,1743019239.708 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-03-26T20:00:40.260Z,1743019240.260 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-03-26T20:00:40.261Z,1743019240.261 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-03-26T20:00:40.863Z,1743019240.863 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-03-26T20:00:40.864Z,1743019240.864 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-03-26T20:00:41.323Z,1743019241.323 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-03-26T20:00:41.323Z,1743019241.323 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-03-26T20:00:41.706Z,1743019241.706 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-03-26T20:00:41.707Z,1743019241.707 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-03-26T20:00:42.116Z,1743019242.116 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-03-26T20:00:42.117Z,1743019242.117 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-03-26T20:00:42.368Z,1743019242.368 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-03-26T20:00:42.368Z,1743019242.368 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-03-26T20:00:42.537Z,1743019242.537 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-03-26T20:00:42.538Z,1743019242.538 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-03-26T20:00:42.635Z,1743019242.635 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-03-26T20:00:42.730Z,1743019242.730 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-03-26T20:00:42.947Z,1743019242.947 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-03-26T20:00:42.948Z,1743019242.948 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-03-26T20:00:43.664Z,1743019243.664 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-03-26T20:00:43.666Z,1743019243.666 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2025-03-26T20:00:43.667Z,1743019243.667 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2025-03-26T20:00:43.833Z,1743019243.833 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2025-03-26T20:00:44.174Z,1743019244.174 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-03-26T20:00:44.175Z,1743019244.175 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2025-03-26T20:00:44.275Z,1743019244.275 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2025-03-26T20:00:44.472Z,1743019244.472 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2025-03-26T20:00:44.590Z,1743019244.590 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2025-03-26T20:00:44.722Z,1743019244.722 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2025-03-26T20:00:44.855Z,1743019244.855 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2025-03-26T20:00:44.967Z,1743019244.967 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2025-03-26T20:00:45.154Z,1743019245.154 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Derivation.cfg
2025-03-26T20:00:45.269Z,1743019245.269 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/workSite.cfg
2025-03-26T20:00:45.370Z,1743019245.370 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2025-03-26T20:00:45.486Z,1743019245.486 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2025-03-26T20:00:45.590Z,1743019245.590 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2025-03-26T20:00:45.591Z,1743019245.591 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-03-26T20:00:45.794Z,1743019245.794 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-03-26T20:00:45.795Z,1743019245.795 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-03-26T20:00:45.947Z,1743019245.947 [BuoyancyServo] Loaded
2025-03-26T20:00:45.947Z,1743019245.947 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-03-26T20:00:45.972Z,1743019245.972 [ElevatorServo] Loaded
2025-03-26T20:00:45.972Z,1743019245.972 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-03-26T20:00:45.995Z,1743019245.995 [MassServo] Loaded
2025-03-26T20:00:45.995Z,1743019245.995 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-03-26T20:00:46.018Z,1743019246.018 [RudderServo] Loaded
2025-03-26T20:00:46.018Z,1743019246.018 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-03-26T20:00:46.037Z,1743019246.037 [ThrusterHE] Loaded
2025-03-26T20:00:46.037Z,1743019246.037 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-03-26T20:00:46.037Z,1743019246.037 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-03-26T20:00:46.038Z,1743019246.038 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-03-26T20:00:46.222Z,1743019246.222 [SBIT](DEBUG): Construct Startup Built In Test.
2025-03-26T20:00:46.237Z,1743019246.237 [SBIT] Loaded
2025-03-26T20:00:46.238Z,1743019246.238 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-03-26T20:00:46.240Z,1743019246.240 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-03-26T20:00:46.261Z,1743019246.261 [IBIT] Loaded
2025-03-26T20:00:46.262Z,1743019246.262 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-03-26T20:00:46.267Z,1743019246.267 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-03-26T20:00:46.463Z,1743019246.463 [CBIT] Loaded
2025-03-26T20:00:46.463Z,1743019246.463 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-03-26T20:00:46.494Z,1743019246.494 [GFScanner] Loaded
2025-03-26T20:00:46.494Z,1743019246.494 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-03-26T20:00:46.494Z,1743019246.494 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-03-26T20:00:46.495Z,1743019246.495 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-03-26T20:00:46.673Z,1743019246.673 [CTD_Seabird] Loaded
2025-03-26T20:00:46.673Z,1743019246.673 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-03-26T20:00:46.674Z,1743019246.674 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406834E0
2025-03-26T20:00:46.674Z,1743019246.674 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 6586
2025-03-26T20:00:46.710Z,1743019246.710 [ESPComponent] Loaded
2025-03-26T20:00:46.710Z,1743019246.710 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2025-03-26T20:00:46.732Z,1743019246.732 [PAR_Licor] Loaded
2025-03-26T20:00:46.733Z,1743019246.733 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-03-26T20:00:46.779Z,1743019246.779 [WetLabsBB2FL] Loaded
2025-03-26T20:00:46.780Z,1743019246.780 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-03-26T20:00:46.781Z,1743019246.781 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406B34E0
2025-03-26T20:00:46.781Z,1743019246.781 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6587
2025-03-26T20:00:46.782Z,1743019246.782 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-03-26T20:00:46.782Z,1743019246.782 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-03-26T20:00:46.839Z,1743019246.839 [DepthRateCalculator] Loaded
2025-03-26T20:00:46.839Z,1743019246.839 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-03-26T20:00:46.844Z,1743019246.844 [PitchRateCalculator] Loaded
2025-03-26T20:00:46.845Z,1743019246.845 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-03-26T20:00:46.855Z,1743019246.855 [SpeedCalculator] Loaded
2025-03-26T20:00:46.855Z,1743019246.855 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-03-26T20:00:46.860Z,1743019246.860 [YawRateCalculator] Loaded
2025-03-26T20:00:46.860Z,1743019246.860 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-03-26T20:00:46.880Z,1743019246.880 [ElevatorOffsetCalculator] Loaded
2025-03-26T20:00:46.880Z,1743019246.880 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-03-26T20:00:46.880Z,1743019246.880 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-03-26T20:00:46.881Z,1743019246.881 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-03-26T20:00:47.033Z,1743019247.033 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-03-26T20:00:47.034Z,1743019247.034 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-03-26T20:00:47.065Z,1743019247.065 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-03-26T20:00:47.066Z,1743019247.066 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-03-26T20:00:47.186Z,1743019247.186 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-03-26T20:00:47.186Z,1743019247.186 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-03-26T20:00:47.200Z,1743019247.200 [NavChart] Loaded
2025-03-26T20:00:47.201Z,1743019247.201 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-03-26T20:00:47.206Z,1743019247.206 [UniversalFixResidualReporter] Loaded
2025-03-26T20:00:47.207Z,1743019247.207 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-03-26T20:00:47.207Z,1743019247.207 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-03-26T20:00:47.208Z,1743019247.208 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-03-26T20:00:47.735Z,1743019247.735 [AHRS_M2] Loaded
2025-03-26T20:00:47.736Z,1743019247.736 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-03-26T20:00:47.775Z,1743019247.775 [BackseatComponent] Loaded
2025-03-26T20:00:47.775Z,1743019247.775 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2025-03-26T20:00:47.777Z,1743019247.777 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 40A7F4E0
2025-03-26T20:00:47.777Z,1743019247.777 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 6588
2025-03-26T20:00:47.780Z,1743019247.780 [LcmUniversalReporter] Loaded
2025-03-26T20:00:47.780Z,1743019247.780 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2025-03-26T20:00:49.418Z,1743019249.418 [BPC1] Loaded
2025-03-26T20:00:49.418Z,1743019249.418 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-03-26T20:00:49.533Z,1743019249.533 [DAT] Loaded
2025-03-26T20:00:49.533Z,1743019249.533 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-03-26T20:00:49.534Z,1743019249.534 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 40AAF4E0
2025-03-26T20:00:49.535Z,1743019249.535 [DAT ThreadHandler](INFO): Protected caller Thread ID is 6589
2025-03-26T20:00:49.701Z,1743019249.701 [DataOverHttps] Loaded
2025-03-26T20:00:49.702Z,1743019249.702 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-03-26T20:00:49.703Z,1743019249.703 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 40ADF4E0
2025-03-26T20:00:49.703Z,1743019249.703 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 6590
2025-03-26T20:00:49.727Z,1743019249.727 [Depth_Keller] Loaded
2025-03-26T20:00:49.727Z,1743019249.727 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-03-26T20:00:49.733Z,1743019249.733 [DropWeight] Loaded
2025-03-26T20:00:49.733Z,1743019249.733 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-03-26T20:00:49.775Z,1743019249.775 [DVL_micro] Loaded
2025-03-26T20:00:49.775Z,1743019249.775 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2025-03-26T20:00:49.840Z,1743019249.840 [NAL9602] Loaded
2025-03-26T20:00:49.841Z,1743019249.841 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-03-26T20:00:49.868Z,1743019249.868 [Onboard] Loaded
2025-03-26T20:00:49.868Z,1743019249.868 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-03-26T20:00:49.869Z,1743019249.869 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 40B0F4E0
2025-03-26T20:00:49.870Z,1743019249.870 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 6591
2025-03-26T20:00:49.876Z,1743019249.876 [PowerOnly](INFO): Adding load control power supply at /dev/loadB1
2025-03-26T20:00:49.890Z,1743019249.890 [PowerOnly] Loaded
2025-03-26T20:00:49.890Z,1743019249.890 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2025-03-26T20:00:49.905Z,1743019249.905 [Power24vConverter] Loaded
2025-03-26T20:00:49.906Z,1743019249.906 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-03-26T20:00:49.922Z,1743019249.922 [Radio_Surface] Loaded
2025-03-26T20:00:49.923Z,1743019249.923 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-03-26T20:00:49.924Z,1743019249.924 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40B3F4E0
2025-03-26T20:00:49.924Z,1743019249.924 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6592
2025-03-26T20:00:49.941Z,1743019249.941 [Sonardyne_Nano] Loaded
2025-03-26T20:00:49.941Z,1743019249.941 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-03-26T20:00:49.941Z,1743019249.941 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-03-26T20:00:49.942Z,1743019249.942 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-03-26T20:00:50.008Z,1743019250.008 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-03-26T20:00:50.008Z,1743019250.008 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-03-26T20:00:50.070Z,1743019250.070 [VerticalControl](DEBUG): Construct VerticalControl.
2025-03-26T20:00:50.122Z,1743019250.122 [VerticalControl] Loaded
2025-03-26T20:00:50.123Z,1743019250.123 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-03-26T20:00:50.126Z,1743019250.126 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-03-26T20:00:50.169Z,1743019250.169 [HorizontalControl] Loaded
2025-03-26T20:00:50.170Z,1743019250.170 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-03-26T20:00:50.172Z,1743019250.172 [SpeedControl](DEBUG): Construct SpeedControl.
2025-03-26T20:00:50.174Z,1743019250.174 [SpeedControl] Loaded
2025-03-26T20:00:50.174Z,1743019250.174 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-03-26T20:00:50.177Z,1743019250.177 [LoopControl](DEBUG): Construct LoopControl.
2025-03-26T20:00:50.178Z,1743019250.178 [LoopControl] Loaded
2025-03-26T20:00:50.178Z,1743019250.178 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-03-26T20:00:50.178Z,1743019250.178 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-03-26T20:00:50.179Z,1743019250.179 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-03-26T20:00:50.190Z,1743019250.190 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-03-26T20:00:50.190Z,1743019250.190 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-03-26T20:00:50.582Z,1743019250.582 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-03-26T20:00:50.589Z,1743019250.589 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-03-26T20:00:50.592Z,1743019250.592 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-03-26T20:00:50.603Z,1743019250.603 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-03-26T20:00:50.604Z,1743019250.604 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C654E0
2025-03-26T20:00:50.604Z,1743019250.604 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6593
2025-03-26T20:00:50.609Z,1743019250.609 [Supervisor](INFO): Main Thread ID is 6493
2025-03-26T20:00:50.609Z,1743019250.609 [Supervisor](DEBUG): Running supervisor.
2025-03-26T20:00:50.610Z,1743019250.610 [CommandExec ThreadHandler](INFO): Handler Thread ID is 6594
2025-03-26T20:00:50.610Z,1743019250.610 [CommandExec](INFO): Initializing the command executive.
2025-03-26T20:00:50.612Z,1743019250.612 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6595
2025-03-26T20:00:50.614Z,1743019250.614 [controlThread ThreadHandler](INFO): Handler Thread ID is 6596
2025-03-26T20:00:50.614Z,1743019250.614 [controlThread](DEBUG): Initializing ControlThread
2025-03-26T20:00:50.616Z,1743019250.616 [SBIT](INFO): Initialize SBIT Component.
2025-03-26T20:00:50.617Z,1743019250.617 [SBIT](IMPORTANT): git: 2025-03-26-1-g1815535cc
2025-03-26T20:00:50.617Z,1743019250.617 [SBIT](INFO): git hash: 1815535cca105e464bdb7831f6233b5043f0283f
2025-03-26T20:00:50.617Z,1743019250.617 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-03-26T20:00:50.618Z,1743019250.618 [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-03-26T20:00:50.620Z,1743019250.620 [SBIT](INFO): Beginning SBIT in 107.000000 seconds.
2025-03-26T20:00:50.620Z,1743019250.620 [IBIT](INFO): Initialize IBIT Component.
2025-03-26T20:00:50.621Z,1743019250.621 [CBIT](DEBUG): Initialize CBIT Component.
2025-03-26T20:00:50.622Z,1743019250.622 [logger ThreadHandler](INFO): Handler Thread ID is 6597
2025-03-26T20:00:50.632Z,1743019250.632 [CBIT](DEBUG): Initialized mux pins.
2025-03-26T20:00:50.632Z,1743019250.632 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2025-03-26T20:00:50.632Z,1743019250.632 [CBIT](DEBUG): Initializing the watchdog timer.
2025-03-26T20:00:50.640Z,1743019250.640 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 6598
2025-03-26T20:00:50.641Z,1743019250.641 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-03-26T20:00:50.646Z,1743019250.646 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6600
2025-03-26T20:00:50.648Z,1743019250.648 [WetLabsBB2FL](INFO): Powering up
2025-03-26T20:00:50.650Z,1743019250.650 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 6602
2025-03-26T20:00:50.656Z,1743019250.656 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-03-26T20:00:50.656Z,1743019250.656 [CBIT](DEBUG): Initializing heartbeat.
2025-03-26T20:00:50.657Z,1743019250.657 [DAT ThreadHandler](INFO): Handler Thread ID is 6603
2025-03-26T20:00:50.658Z,1743019250.658 [DAT](INFO): Powering up
2025-03-26T20:00:50.658Z,1743019250.658 [DAT](DEBUG): Initializing DAT.
2025-03-26T20:00:50.661Z,1743019250.661 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 6605
2025-03-26T20:00:50.663Z,1743019250.663 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-03-26T20:00:50.673Z,1743019250.673 [Onboard ThreadHandler](INFO): Handler Thread ID is 6606
2025-03-26T20:00:50.690Z,1743019250.690 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6607
2025-03-26T20:00:50.708Z,1743019250.708 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6608
2025-03-26T20:00:50.711Z,1743019250.711 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-03-26T20:00:50.711Z,1743019250.711 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-03-26T20:00:50.711Z,1743019250.711 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-03-26T20:00:50.711Z,1743019250.711 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-03-26T20:00:50.711Z,1743019250.711 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-03-26T20:00:50.711Z,1743019250.711 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-03-26T20:00:50.713Z,1743019250.713 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-03-26T20:00:50.713Z,1743019250.713 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-03-26T20:00:50.728Z,1743019250.728 [CBIT](DEBUG): Deactivating emergency mode.
2025-03-26T20:00:50.768Z,1743019250.768 [CBIT](DEBUG): Backplane powered.
2025-03-26T20:00:50.768Z,1743019250.768 [GFScanner](DEBUG): Initializing GFScanner
2025-03-26T20:00:50.772Z,1743019250.772 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-03-26T20:00:50.773Z,1743019250.773 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-03-26T20:00:50.773Z,1743019250.773 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-03-26T20:00:50.773Z,1743019250.773 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-03-26T20:00:50.774Z,1743019250.774 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-03-26T20:00:50.775Z,1743019250.775 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-03-26T20:00:50.775Z,1743019250.775 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-03-26T20:00:50.779Z,1743019250.779 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-03-26T20:00:50.781Z,1743019250.781 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-03-26T20:00:50.781Z,1743019250.781 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-03-26T20:00:50.782Z,1743019250.782 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-03-26T20:00:50.783Z,1743019250.783 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-03-26T20:00:50.783Z,1743019250.783 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-03-26T20:00:50.783Z,1743019250.783 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-03-26T20:00:50.789Z,1743019250.789 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-03-26T20:00:50.859Z,1743019250.859 [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-03-26T20:00:50.880Z,1743019250.880 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-03-26T20:00:50.913Z,1743019250.913 [DAT](INFO): Using elevation to construct direction to contact in vehicle frame.
2025-03-26T20:00:50.918Z,1743019250.918 [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-03-26T20:00:50.918Z,1743019250.918 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-03-26T20:00:50.918Z,1743019250.918 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-03-26T20:00:50.936Z,1743019250.936 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-03-26T20:00:51.096Z,1743019251.096 [Radio_Surface](INFO): Powering up
2025-03-26T20:00:51.263Z,1743019251.263 [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-03-26T20:00:51.277Z,1743019251.277 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-03-26T20:00:51.278Z,1743019251.278 [Default:A.Wait](DEBUG): Construct Wait.
2025-03-26T20:00:51.280Z,1743019251.280 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-03-26T20:00:51.338Z,1743019251.338 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-03-26T20:00:51.340Z,1743019251.340 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-03-26T20:00:51.362Z,1743019251.362 [Default:E.Execute](DEBUG): Construct Execute.
2025-03-26T20:00:51.366Z,1743019251.365 [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-03-26T20:00:51.380Z,1743019251.380 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,AHRS_M2,BPC1,Depth_Keller,DropWeight,DVL_micro,NAL9602,PowerOnly,Power24vConverter,Sonardyne_Nano,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,LcmUniversalReporter,Reporter,LogSplitter,
2025-03-26T20:00:51.413Z,1743019251.413 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-03-26T20:00:51.468Z,1743019251.468 [Depth_Keller](INFO): Initializing.
2025-03-26T20:00:51.477Z,1743019251.477 [Power24vConverter](INFO): Powering up.
2025-03-26T20:00:51.478Z,1743019251.478 [Sonardyne_Nano](INFO): Initializing.
2025-03-26T20:00:51.504Z,1743019251.504 [DepthRateCalculator](ERROR): Depth measurement is not active
2025-03-26T20:00:51.527Z,1743019251.527 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-03-26T20:00:51.532Z,1743019251.532 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-03-26T20:00:51.533Z,1743019251.533 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-03-26T20:00:51.540Z,1743019251.540 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-03-26T20:00:51.541Z,1743019251.541 [MassServo](DEBUG): Initializing EZServoServo.
2025-03-26T20:00:51.548Z,1743019251.548 [MassServo](DEBUG): Initializing MassServo.
2025-03-26T20:00:51.549Z,1743019251.549 [RudderServo](DEBUG): Initializing EZServoServo.
2025-03-26T20:00:51.556Z,1743019251.556 [RudderServo](DEBUG): Initializing RudderServo.
2025-03-26T20:00:51.557Z,1743019251.557 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-03-26T20:00:51.564Z,1743019251.564 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-03-26T20:00:51.827Z,1743019251.827 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2025-03-26T20:00:51.827Z,1743019251.827 [DropWeight] Hardware Fault, FailCount= 1
2025-03-26T20:00:51.827Z,1743019251.827 [DropWeight](ERROR): Hardware Fault
2025-03-26T20:00:51.847Z,1743019251.847 [CommandExec](FAULT): Scheduling is paused
2025-03-26T20:00:51.847Z,1743019251.847 [CBIT](INFO): Critical error at 20250326T200051
2025-03-26T20:00:51.847Z,1743019251.847 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2025-03-26T20:00:51.868Z,1743019251.868 [CBIT](ERROR): Hardware Fault in component: DropWeight
2025-03-26T20:00:51.868Z,1743019251.868 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2025-03-26T20:00:52.222Z,1743019252.222 [CBIT](INFO): Critical error at 20250326T200051
2025-03-26T20:00:53.260Z,1743019253.260 [WetLabsBB2FL](INFO): Powering down
2025-03-26T20:00:53.427Z,1743019253.427 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-03-26T20:00:54.705Z,1743019254.705 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2213
2025-03-26T20:01:05.698Z,1743019265.698 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004558
2025-03-26T20:01:06.466Z,1743019266.466 [DVL_micro](ERROR): Failed to parse:
:WI,-00250,+00213,-00038,+00000,A
2025-03-26T20:01:20.180Z,1743019280.180 [NAL9602](INFO): Powering up NAL9602
2025-03-26T20:01:40.753Z,1743019300.753 [NAL9602](ERROR): NAL9602 initialization error.
2025-03-26T20:01:40.754Z,1743019300.754 [NAL9602] Communications Fault, FailCount= 1
2025-03-26T20:01:40.754Z,1743019300.754 [NAL9602](ERROR): Communications Fault
2025-03-26T20:01:40.821Z,1743019300.821 [CBIT](ERROR): Communications Fault in component: NAL9602
2025-03-26T20:01:41.162Z,1743019301.162 [NAL9602](INFO): Powering down
2025-03-26T20:01:42.032Z,1743019302.032 [CBIT](INFO): Clearing failed state for component NAL9602
2025-03-26T20:01:42.032Z,1743019302.032 [NAL9602] No Fault, FailCount= 1
2025-03-26T20:01:50.660Z,1743019310.660 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2025-03-26T20:01:50.660Z,1743019310.660 [DAT] Communications Fault, FailCount= 1
2025-03-26T20:01:50.660Z,1743019310.660 [DAT](ERROR): Communications Fault
2025-03-26T20:01:50.895Z,1743019310.895 [CBIT](ERROR): Communications Fault in component: DAT
2025-03-26T20:01:51.064Z,1743019311.064 [DAT](INFO): Powering down
2025-03-26T20:01:52.148Z,1743019312.148 [CBIT](INFO): Clearing failed state for component DAT
2025-03-26T20:01:52.148Z,1743019312.148 [DAT] No Fault, FailCount= 1
2025-03-26T20:01:54.116Z,1743019314.116 [DAT](INFO): Powering up
2025-03-26T20:01:54.117Z,1743019314.117 [DAT](DEBUG): Initializing DAT.
2025-03-26T20:02:06.213Z,1743019326.213 [DAT](INFO): DAT read: 

2025-03-26T20:02:06.214Z,1743019326.214 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2025-03-26T20:02:07.977Z,1743019327.977 [DAT](INFO): DAT read: MF Frequency Band

2025-03-26T20:02:07.978Z,1743019327.978 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2025-03-26T20:02:07.979Z,1743019327.979 [DAT](INFO): DAT read: Mar 26 2025 20:02:02 

2025-03-26T20:02:08.985Z,1743019328.985 [DAT](INFO): DAT read: Features enabled [Bearing]

2025-03-26T20:02:08.986Z,1743019328.986 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC

2025-03-26T20:02:08.986Z,1743019328.986 [DAT](INFO): commRate: 1200
2025-03-26T20:02:11.052Z,1743019331.052 [DAT](INFO): entering command mode
2025-03-26T20:02:11.252Z,1743019331.252 [DAT](INFO): DAT read: 

2025-03-26T20:02:11.253Z,1743019331.253 [DAT](INFO): DAT read: user:1>

2025-03-26T20:02:11.253Z,1743019331.253 [DAT](INFO): setting verbose to 3
2025-03-26T20:02:11.505Z,1743019331.505 [DAT](INFO): DAT read: user:1>

2025-03-26T20:02:11.506Z,1743019331.506 [DAT](INFO): DAT read: Verbose         | 3           

2025-03-26T20:02:11.506Z,1743019331.506 [DAT](INFO): set verbose to 3
2025-03-26T20:02:11.506Z,1743019331.506 [DAT](INFO): setting DatVerbose to 27440
2025-03-26T20:02:11.574Z,1743019331.574 [NAL9602](INFO): Powering up NAL9602
2025-03-26T20:02:11.756Z,1743019331.756 [DAT](INFO): DAT read: user:2>

2025-03-26T20:02:11.757Z,1743019331.757 [DAT](INFO): DAT read: DatVerbose      | 27440       

2025-03-26T20:02:11.758Z,1743019331.758 [DAT](INFO): set DatVerbose to 27440
2025-03-26T20:02:11.758Z,1743019331.758 [DAT](INFO): setting transmit power to 8
2025-03-26T20:02:12.009Z,1743019332.009 [DAT](INFO): DAT read: user:3>

2025-03-26T20:02:12.010Z,1743019332.010 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2025-03-26T20:02:12.010Z,1743019332.010 [DAT](INFO): set transmit power to 8
2025-03-26T20:02:12.010Z,1743019332.010 [DAT](INFO): setting local address to 5
2025-03-26T20:02:12.261Z,1743019332.261 [DAT](INFO): DAT read: user:4>

2025-03-26T20:02:12.262Z,1743019332.262 [DAT](INFO): DAT read: LocalAddr       | 5           

2025-03-26T20:02:12.262Z,1743019332.262 [DAT](INFO): set local address to 5
2025-03-26T20:02:12.263Z,1743019332.263 [DAT](INFO): Setting time to: 20:2:12 And date to:3/26/2025
2025-03-26T20:02:12.513Z,1743019332.513 [DAT](INFO): DAT read: user:5>

2025-03-26T20:02:12.514Z,1743019332.514 [DAT](INFO): DAT read: Wed Mar 26, 2025  20:02:12  

2025-03-26T20:02:12.514Z,1743019332.514 [DAT](INFO): Local DAT time set to Wed Mar 26, 2025  20:02:12  

2025-03-26T20:02:22.789Z,1743019342.789 [NAL9602](INFO): NAL9602 initialized
2025-03-26T20:02:38.215Z,1743019358.215 [SBIT](IMPORTANT): Beginning Startup BIT
2025-03-26T20:03:11.412Z,1743019391.412 [NAL9602](INFO): SBD MO Status=2, MOMSN=19207, MT Status=2, MTMSN=0
2025-03-26T20:03:11.412Z,1743019391.412 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2025-03-26T20:03:32.046Z,1743019412.046 [SBIT](IMPORTANT): SBIT PASSED
2025-03-26T20:03:32.046Z,1743019412.046 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2025-03-26T20:03:32.442Z,1743019412.442 [MissionManager](IMPORTANT): Started mission Startup
2025-03-26T20:03:32.442Z,1743019412.442 [Startup] Running Loop=1
2025-03-26T20:03:32.442Z,1743019412.442 [Startup](DEBUG): Aggregate::initialize Startup
2025-03-26T20:03:32.442Z,1743019412.442 [Startup:A.GoToSurface] Running Loop=1
2025-03-26T20:03:32.442Z,1743019412.442 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-03-26T20:03:32.443Z,1743019412.443 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-03-26T20:03:32.443Z,1743019412.443 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-03-26T20:03:32.443Z,1743019412.443 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-03-26T20:03:32.444Z,1743019412.444 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-03-26T20:03:32.444Z,1743019412.444 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-03-26T20:03:32.445Z,1743019412.445 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-03-26T20:03:32.446Z,1743019412.446 [Startup:StartupSatComms] Running Loop=1
2025-03-26T20:03:32.447Z,1743019412.447 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-03-26T20:03:32.447Z,1743019412.447 [Startup:StartupSatComms:A] Running Loop=1
2025-03-26T20:03:32.868Z,1743019412.868 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-03-26T20:03:51.087Z,1743019431.087 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-03-26T20:03:51.087Z,1743019431.087 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-26T20:03:51.098Z,1743019431.098 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-26T20:03:51.477Z,1743019431.477 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-26T20:03:51.477Z,1743019431.477 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-03-26T20:04:32.661Z,1743019472.661 [Startup:StartupSatComms:A](INFO): Timed out from 2025-03-26T20:03:32.4Z
2025-03-26T20:04:32.662Z,1743019472.662 [Startup:StartupSatComms:A] Stopped
2025-03-26T20:04:32.662Z,1743019472.662 [Startup:StartupSatComms:B] Running Loop=1
2025-03-26T20:04:33.045Z,1743019473.045 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-03-26T20:04:42.185Z,1743019482.185 [DataOverHttps](INFO): Sending 40 bytes from file Logs/20250314T173554/Courier0045.lzma
2025-03-26T20:04:43.186Z,1743019483.186 [DataOverHttps](INFO): Moved sent file to Logs/20250314T173554/Courier0045.lzma.bak
2025-03-26T20:04:43.186Z,1743019483.186 [DataOverHttps](INFO): SBD MOMSN=24461167
2025-03-26T20:04:59.697Z,1743019499.697 [DataOverHttps](INFO): Sending 265 bytes from file Logs/20250326T165304/Courier0000.lzma
2025-03-26T20:05:00.695Z,1743019500.695 [DataOverHttps](INFO): Moved sent file to Logs/20250326T165304/Courier0000.lzma.bak
2025-03-26T20:05:00.695Z,1743019500.695 [DataOverHttps](INFO): SBD MOMSN=24461169
2025-03-26T20:05:16.836Z,1743019516.836 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20250326T165304/Courier0004.lzma
2025-03-26T20:05:17.842Z,1743019517.842 [DataOverHttps](INFO): Moved sent file to Logs/20250326T165304/Courier0004.lzma.bak
2025-03-26T20:05:17.842Z,1743019517.842 [DataOverHttps](INFO): SBD MOMSN=24461175
2025-03-26T20:05:32.881Z,1743019532.881 [Startup:StartupSatComms:B](INFO): Timed out from 2025-03-26T20:04:32.7Z
2025-03-26T20:05:32.881Z,1743019532.881 [Startup:StartupSatComms:B] Stopped
2025-03-26T20:05:32.882Z,1743019532.882 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-03-26T20:05:32.882Z,1743019532.882 [Startup:StartupSatComms] Stopped
2025-03-26T20:05:32.882Z,1743019532.882 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-03-26T20:05:32.883Z,1743019532.883 [Startup](INFO): Completed Startup
2025-03-26T20:05:32.883Z,1743019532.883 [MissionManager](INFO): Startup is completed.
2025-03-26T20:05:32.883Z,1743019532.883 [MissionManager](INFO): Uninitializing Mission Startup
2025-03-26T20:05:32.883Z,1743019532.883 [Startup] Stopped
2025-03-26T20:05:32.883Z,1743019532.883 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-03-26T20:05:32.883Z,1743019532.883 [Startup:A.GoToSurface] Stopped
2025-03-26T20:05:32.883Z,1743019532.883 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-03-26T20:05:33.412Z,1743019533.412 [MissionManager](IMPORTANT): Started mission Default
2025-03-26T20:05:33.412Z,1743019533.412 [Default] Running Loop=1
2025-03-26T20:05:33.412Z,1743019533.412 [Default](DEBUG): Aggregate::initialize Default
2025-03-26T20:05:33.412Z,1743019533.412 [Default:B.GoToSurface] Running Loop=1
2025-03-26T20:05:33.412Z,1743019533.412 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-03-26T20:05:33.413Z,1743019533.413 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-03-26T20:05:33.413Z,1743019533.413 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-03-26T20:05:33.413Z,1743019533.413 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-03-26T20:05:33.414Z,1743019533.414 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-03-26T20:05:33.414Z,1743019533.414 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-03-26T20:05:33.414Z,1743019533.414 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-03-26T20:05:33.414Z,1743019533.414 [Default:A.Wait] Running Loop=1
2025-03-26T20:05:33.415Z,1743019533.415 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-03-26T20:05:33.979Z,1743019533.979 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250326T165304/Courier0007.lzma
2025-03-26T20:05:34.982Z,1743019534.982 [DataOverHttps](INFO): Moved sent file to Logs/20250326T165304/Courier0007.lzma.bak
2025-03-26T20:05:34.982Z,1743019534.982 [DataOverHttps](INFO): SBD MOMSN=24461177
2025-03-26T20:05:46.594Z,1743019546.594 [Default:A.Wait](INFO): Done Waiting.
2025-03-26T20:05:46.595Z,1743019546.595 [Default:A.Wait] Stopped
2025-03-26T20:05:46.595Z,1743019546.595 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-03-26T20:05:47.013Z,1743019547.013 [Default:CheckIn] Running Loop=1
2025-03-26T20:05:47.013Z,1743019547.013 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-03-26T20:05:47.013Z,1743019547.013 [Default:CheckIn:Read_GPS] Running Loop=1
2025-03-26T20:05:47.403Z,1743019547.403 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-03-26T20:06:52.059Z,1743019612.059 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2025-03-26T20:06:52.059Z,1743019612.059 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-26T20:06:52.090Z,1743019612.090 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-26T20:06:52.501Z,1743019612.501 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-26T20:06:52.501Z,1743019612.501 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2025-03-26T20:07:25.555Z,1743019645.555 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-03-26T20:08:24.952Z,1743019704.952 [BPC1](INFO): Calculating totals. Valid battery stick count: 52. Valid reserve battery stick count: 6.
2025-03-26T20:08:24.958Z,1743019704.958 [BPC1](INFO): Received data from all battery sticks.
2025-03-26T20:09:53.023Z,1743019793.023 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2025-03-26T20:09:53.023Z,1743019793.023 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-26T20:09:53.034Z,1743019793.034 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-26T20:09:53.445Z,1743019793.445 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-26T20:09:53.446Z,1743019793.446 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2025-03-26T20:10:47.195Z,1743019847.195 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-03-26T20:05:47.0Z
2025-03-26T20:10:47.195Z,1743019847.195 [Default:CheckIn:Read_GPS] Stopped
2025-03-26T20:10:47.195Z,1743019847.195 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-03-26T20:10:47.566Z,1743019847.566 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-03-26T20:10:48.573Z,1743019848.573 [DataOverHttps](INFO): Sending 242 bytes from file Logs/20250326T200038/Courier0000.lzma
2025-03-26T20:10:49.406Z,1743019849.406 [DataOverHttps](INFO): Moved sent file to Logs/20250326T200038/Courier0000.lzma.bak
2025-03-26T20:10:49.406Z,1743019849.406 [DataOverHttps](INFO): SBD MOMSN=24461217
2025-03-26T20:11:05.266Z,1743019865.266 [DataOverHttps](INFO): Sending 170 bytes from file Logs/20250326T200038/Courier0004.lzma
2025-03-26T20:11:06.266Z,1743019866.266 [DataOverHttps](INFO): Moved sent file to Logs/20250326T200038/Courier0004.lzma.bak
2025-03-26T20:11:06.266Z,1743019866.266 [DataOverHttps](INFO): SBD MOMSN=24461222
2025-03-26T20:11:22.041Z,1743019882.041 [DataOverHttps](INFO): Sending 101 bytes from file Logs/20250314T173554/Express0046.lzma
2025-03-26T20:11:23.042Z,1743019883.042 [DataOverHttps](INFO): Moved sent file to Logs/20250314T173554/Express0046.lzma.bak
2025-03-26T20:11:23.042Z,1743019883.042 [DataOverHttps](INFO): SBD MOMSN=24461224
2025-03-26T20:11:26.921Z,1743019886.921 [CommandExec](IMPORTANT): got command gfscan
2025-03-26T20:11:34.823Z,1743019894.823 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error. Device response is::TS,0000000001611,+00000,A
2025-03-26T20:11:34.824Z,1743019894.824 [DVL_micro](ERROR): Failed to parse:
:TS,0000000001611,+00000,A
2025-03-26T20:11:41.738Z,1743019901.738 [DataOverHttps](INFO): Sending 1124 bytes from file Logs/20250326T165304/Express0001.lzma
2025-03-26T20:11:42.738Z,1743019902.738 [DataOverHttps](INFO): Moved sent file to Logs/20250326T165304/Express0001.lzma.bak
2025-03-26T20:11:42.738Z,1743019902.738 [DataOverHttps](INFO): SBD MOMSN=24461227
2025-03-26T20:11:58.633Z,1743019918.633 [DataOverHttps](INFO): Sending 190 bytes from file Logs/20250326T165304/Express0005.lzma
2025-03-26T20:11:59.634Z,1743019919.634 [DataOverHttps](INFO): Moved sent file to Logs/20250326T165304/Express0005.lzma.bak
2025-03-26T20:11:59.634Z,1743019919.634 [DataOverHttps](INFO): SBD MOMSN=24461259
2025-03-26T20:12:15.719Z,1743019935.719 [DataOverHttps](INFO): Sending 18 bytes from file Logs/20250326T165304/Express0008.lzma
2025-03-26T20:12:16.722Z,1743019936.722 [DataOverHttps](INFO): Moved sent file to Logs/20250326T165304/Express0008.lzma.bak
2025-03-26T20:12:16.722Z,1743019936.722 [DataOverHttps](INFO): SBD MOMSN=24461263
2025-03-26T20:12:24.927Z,1743019944.927 [NAL9602](FAULT): GPS failed to acquire within timeout.
2025-03-26T20:12:24.927Z,1743019944.927 [NAL9602] Data Fault, FailCount= 2
2025-03-26T20:12:24.927Z,1743019944.927 [NAL9602](ERROR): Data Fault
2025-03-26T20:12:24.988Z,1743019944.988 [CBIT](ERROR): Data Fault in component: NAL9602
2025-03-26T20:12:25.325Z,1743019945.325 [NAL9602](INFO): Powering down
2025-03-26T20:12:26.170Z,1743019946.170 [CBIT](INFO): Clearing failed state for component NAL9602
2025-03-26T20:12:26.170Z,1743019946.170 [NAL9602] No Fault, FailCount= 2
2025-03-26T20:12:33.166Z,1743019953.166 [DataOverHttps](INFO): Sending 656 bytes from file Logs/20250326T200038/Express0001.lzma
2025-03-26T20:12:34.166Z,1743019954.166 [DataOverHttps](INFO): Moved sent file to Logs/20250326T200038/Express0001.lzma.bak
2025-03-26T20:12:34.167Z,1743019954.167 [DataOverHttps](INFO): SBD MOMSN=24461270
2025-03-26T20:12:51.615Z,1743019971.615 [CommandExec](IMPORTANT): got command get GFScanner.loadAtStartup
2025-03-26T20:12:51.615Z,1743019971.615 [CommandExec](IMPORTANT): GFScanner.loadAtStartup 1 bool
2025-03-26T20:12:54.036Z,1743019974.036 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2025-03-26T20:12:54.036Z,1743019974.036 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-26T20:12:54.050Z,1743019974.050 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-26T20:12:54.341Z,1743019974.341 [CommandExec](IMPORTANT): got command get GFScanner.simulateHardware
2025-03-26T20:12:54.342Z,1743019974.342 [CommandExec](IMPORTANT): GFScanner.simulateHardware 1 bool
2025-03-26T20:12:54.463Z,1743019974.463 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-26T20:12:54.463Z,1743019974.463 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2025-03-26T20:12:54.664Z,1743019974.664 [DataOverHttps](INFO): Sending 189 bytes from file Logs/20250326T200038/Express0005.lzma
2025-03-26T20:12:55.631Z,1743019975.631 [NAL9602](INFO): Powering up NAL9602
2025-03-26T20:12:55.666Z,1743019975.666 [DataOverHttps](INFO): Moved sent file to Logs/20250326T200038/Express0005.lzma.bak
2025-03-26T20:12:55.666Z,1743019975.666 [DataOverHttps](INFO): SBD MOMSN=24461287
2025-03-26T20:12:57.250Z,1743019977.250 [Default:CheckIn:Read_Iridium] Stopped
2025-03-26T20:12:57.250Z,1743019977.250 [Default:CheckIn:C.Wait] Running Loop=1
2025-03-26T20:12:57.250Z,1743019977.250 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-03-26T20:13:04.951Z,1743019984.951 [CommandExec](IMPORTANT): got command configSet GFScanner.simulateHardware 0 bool persist
2025-03-26T20:13:04.957Z,1743019984.957 [CommandExec](IMPORTANT): configSet GFScanner.simulateHardware requires a restart to take effect.
2025-03-26T20:13:04.968Z,1743019984.968 [CommandExec](FAULT): Not allowing to overwrite existing Data/persisted.cfg after unclean shutdown.
2025-03-26T20:13:04.968Z,1743019984.968 [CommandExec](IMPORTANT): got command restart application
2025-03-26T20:13:05.972Z,1743019985.972 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-26T20:13:05.972Z,1743019985.972 [CommandExec](INFO): Uninitializing the command executive.
2025-03-26T20:13:05.972Z,1743019985.972 [CommandExec](INFO): Uninitializing the command scheduler.
2025-03-26T20:13:05.972Z,1743019985.972 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-03-26T20:13:06.048Z,1743019986.048 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2025-03-26T20:13:06.048Z,1743019986.048 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-03-26T20:13:06.048Z,1743019986.048 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-03-26T20:13:06.049Z,1743019986.049 [NavChartDb](INFO): Join timeout helper Thread ID is 6689
2025-03-26T20:13:06.344Z,1743019986.344 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-26T20:13:06.344Z,1743019986.344 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-03-26T20:13:06.356Z,1743019986.356 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-03-26T20:13:06.356Z,1743019986.356 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-03-26T20:13:06.356Z,1743019986.356 [Radio_Surface](INFO): Join timeout helper Thread ID is 6690
2025-03-26T20:13:06.416Z,1743019986.416 [Radio_Surface](INFO): Powering down
2025-03-26T20:13:06.418Z,1743019986.418 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-26T20:13:06.418Z,1743019986.418 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-03-26T20:13:06.428Z,1743019986.428 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-03-26T20:13:06.428Z,1743019986.428 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-03-26T20:13:06.429Z,1743019986.429 [Onboard](INFO): Join timeout helper Thread ID is 6691
2025-03-26T20:13:06.572Z,1743019986.572 [NAL9602](INFO): NAL9602 initialized
2025-03-26T20:13:06.676Z,1743019986.676 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-03-26T20:13:11.320Z,1743019991.320 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-26T20:13:11.320Z,1743019991.320 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-03-26T20:13:11.328Z,1743019991.328 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-03-26T20:13:11.328Z,1743019991.328 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-03-26T20:13:11.328Z,1743019991.328 [DataOverHttps](INFO): Join timeout helper Thread ID is 6692
2025-03-26T20:13:11.700Z,1743019991.700 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-26T20:13:11.700Z,1743019991.700 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-03-26T20:13:11.725Z,1743019991.725 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2025-03-26T20:13:11.726Z,1743019991.726 [DAT ThreadHandler](INFO): Thread cancelled.
2025-03-26T20:13:11.726Z,1743019991.726 [DAT](INFO): Join timeout helper Thread ID is 6693
2025-03-26T20:13:11.732Z,1743019991.732 [DAT](INFO): Powering down
2025-03-26T20:13:11.804Z,1743019991.804 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-26T20:13:11.805Z,1743019991.805 [DAT](INFO): Powering down
2025-03-26T20:13:11.805Z,1743019991.805 [DAT ThreadHandler](INFO): Thread cancelled.
2025-03-26T20:13:11.817Z,1743019991.817 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2025-03-26T20:13:11.817Z,1743019991.817 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-03-26T20:13:11.817Z,1743019991.817 [BackseatComponent](INFO): Join timeout helper Thread ID is 6694
2025-03-26T20:13:11.948Z,1743019991.948 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-26T20:13:11.948Z,1743019991.948 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-03-26T20:13:11.968Z,1743019991.968 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-03-26T20:13:11.969Z,1743019991.969 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-03-26T20:13:11.969Z,1743019991.969 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6695
2025-03-26T20:13:12.312Z,1743019992.312 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-26T20:13:12.312Z,1743019992.312 [WetLabsBB2FL](INFO): Powering down
2025-03-26T20:13:12.313Z,1743019992.313 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-03-26T20:13:12.328Z,1743019992.328 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-03-26T20:13:12.329Z,1743019992.329 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-03-26T20:13:12.329Z,1743019992.329 [CTD_Seabird](INFO): Join timeout helper Thread ID is 6696
2025-03-26T20:13:12.716Z,1743019992.716 [CTD_Seabird](INFO): Powering down
2025-03-26T20:13:12.734Z,1743019992.734 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-26T20:13:12.734Z,1743019992.734 [CTD_Seabird](INFO): Powering down
2025-03-26T20:13:12.748Z,1743019992.748 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-03-26T20:13:12.753Z,1743019992.753 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-03-26T20:13:12.753Z,1743019992.753 [logger ThreadHandler](INFO): Thread cancelled.
2025-03-26T20:13:12.753Z,1743019992.753 [logger](INFO): Join timeout helper Thread ID is 6697
2025-03-26T20:13:12.779Z,1743019992.779 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-26T20:13:12.780Z,1743019992.780 [logger ThreadHandler](INFO): Thread cancelled.
2025-03-26T20:13:12.783Z,1743019992.783 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-03-26T20:13:12.783Z,1743019992.783 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-03-26T20:13:12.783Z,1743019992.783 [CommandLine](INFO): Join timeout helper Thread ID is 6698
2025-03-26T20:13:12.840Z,1743019992.840 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-26T20:13:12.842Z,1743019992.842 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-03-26T20:13:12.862Z,1743019992.862 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-03-26T20:13:12.863Z,1743019992.863 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-03-26T20:13:12.863Z,1743019992.863 [CommandExec](INFO): Join timeout helper Thread ID is 6699
2025-03-26T20:13:12.867Z,1743019992.867 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-03-26T20:13:12.871Z,1743019992.871 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-03-26T20:13:12.872Z,1743019992.872 [controlThread](INFO): Join timeout helper Thread ID is 6700
2025-03-26T20:13:12.976Z,1743019992.976 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-26T20:13:12.976Z,1743019992.976 [controlThread](DEBUG): Uninitializing ControlThread
2025-03-26T20:13:12.978Z,1743019992.978 [AHRS_M2](INFO): Powering down
2025-03-26T20:13:13.164Z,1743019993.164 [DVL_micro](INFO): Powering down
2025-03-26T20:13:13.165Z,1743019993.165 [NAL9602](INFO): Powering down
2025-03-26T20:13:13.166Z,1743019993.166 [Sonardyne_Nano](INFO): Powering down
2025-03-26T20:13:13.372Z,1743019993.372 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-03-26T20:13:13.373Z,1743019993.373 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-03-26T20:13:13.373Z,1743019993.373 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-03-26T20:13:13.374Z,1743019993.374 [MissionManager](INFO): Uninitializing Mission Default
2025-03-26T20:13:13.374Z,1743019993.374 [Default] Stopped
2025-03-26T20:13:13.374Z,1743019993.374 [Default](DEBUG): Aggregate::uninitialize Default
2025-03-26T20:13:13.374Z,1743019993.374 [Default:B.GoToSurface] Stopped
2025-03-26T20:13:13.374Z,1743019993.374 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-03-26T20:13:13.374Z,1743019993.374 [Default:CheckIn] Stopped
2025-03-26T20:13:13.374Z,1743019993.374 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-03-26T20:13:13.374Z,1743019993.374 [Default:CheckIn:C.Wait] Stopped
2025-03-26T20:13:13.374Z,1743019993.374 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-03-26T20:13:13.378Z,1743019993.378 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-03-26T20:13:13.378Z,1743019993.378 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-03-26T20:13:13.378Z,1743019993.378 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-03-26T20:13:13.378Z,1743019993.378 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-03-26T20:13:13.379Z,1743019993.379 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-03-26T20:13:13.379Z,1743019993.379 [BuoyancyServo](INFO): Powering down
2025-03-26T20:13:13.392Z,1743019993.392 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-03-26T20:13:13.392Z,1743019993.392 [ElevatorServo](INFO): Powering down
2025-03-26T20:13:13.393Z,1743019993.393 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-03-26T20:13:13.393Z,1743019993.393 [MassServo](INFO): Powering down
2025-03-26T20:13:13.394Z,1743019993.394 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-03-26T20:13:13.394Z,1743019993.394 [RudderServo](INFO): Powering down
2025-03-26T20:13:13.395Z,1743019993.395 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-03-26T20:13:13.395Z,1743019993.395 [ThrusterHE](INFO): Powering down
2025-03-26T20:13:13.396Z,1743019993.396 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-03-26T20:13:13.396Z,1743019993.396 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-03-26T20:13:13.396Z,1743019993.396 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-03-26T20:13:13.396Z,1743019993.396 [CBIT](DEBUG): Powering off loads.
2025-03-26T20:13:13.408Z,1743019993.408 [CBIT](DEBUG): Disabling WDT.
2025-03-26T20:13:13.420Z,1743019993.420 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-03-26T20:13:13.421Z,1743019993.421 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-03-26T20:13:13.458Z,1743019993.458 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-03-26T20:13:13.473Z,1743019993.473 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-03-26T20:13:13.480Z,1743019993.480 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-03-26T20:13:13.511Z,1743019993.511 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-03-26T20:13:13.514Z,1743019993.514 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-03-26T20:13:13.522Z,1743019993.522 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-03-26T20:13:13.528Z,1743019993.528 [DAT ThreadHandler](INFO): Thread cancelled.
2025-03-26T20:13:13.570Z,1743019993.570 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2025-03-26T20:13:13.624Z,1743019993.624 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-03-26T20:13:13.690Z,1743019993.690 [logger ThreadHandler](INFO): Thread cancelled.