2025-03-10T19:58:46.857Z,1741636726.857 [Supervisor](DEBUG): Initializing supervisor.
2025-03-10T19:58:46.862Z,1741636726.862 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2025-03-10T19:58:46.863Z,1741636726.863 [SyncHandler](INFO): Protected caller Thread ID is 4692
2025-03-10T19:58:46.863Z,1741636726.863 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2025-03-10T19:58:46.865Z,1741636726.865 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2025-03-10T19:58:46.865Z,1741636726.865 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4693
2025-03-10T19:58:46.869Z,1741636726.869 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2025-03-10T19:58:46.888Z,1741636726.888 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2025-03-10T19:58:46.889Z,1741636726.889 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2025-03-10T19:58:46.889Z,1741636726.889 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 4694
2025-03-10T19:58:46.893Z,1741636726.893 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2025-03-10T19:58:46.894Z,1741636726.894 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2025-03-10T19:58:46.895Z,1741636726.895 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4695
2025-03-10T19:58:46.897Z,1741636726.897 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2025-03-10T19:58:46.898Z,1741636726.898 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2025-03-10T19:58:46.898Z,1741636726.898 [logger ThreadHandler](INFO): Protected caller Thread ID is 4696
2025-03-10T19:58:46.902Z,1741636726.902 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2025-03-10T19:58:46.902Z,1741636726.902 [Supervisor](INFO): Looking for Config files in directory: Config/
2025-03-10T19:58:46.904Z,1741636726.904 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2025-03-10T19:58:47.177Z,1741636727.177 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2025-03-10T19:58:47.177Z,1741636727.177 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2025-03-10T19:58:47.268Z,1741636727.268 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2025-03-10T19:58:47.799Z,1741636727.799 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2025-03-10T19:58:47.800Z,1741636727.800 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2025-03-10T19:58:48.150Z,1741636728.150 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2025-03-10T19:58:48.150Z,1741636728.150 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2025-03-10T19:58:48.258Z,1741636728.258 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2025-03-10T19:58:48.259Z,1741636728.259 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2025-03-10T19:58:48.685Z,1741636728.685 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2025-03-10T19:58:48.685Z,1741636728.685 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2025-03-10T19:58:48.924Z,1741636728.924 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2025-03-10T19:58:48.924Z,1741636728.924 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2025-03-10T19:58:49.459Z,1741636729.459 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2025-03-10T19:58:49.460Z,1741636729.460 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2025-03-10T19:58:49.607Z,1741636729.607 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2025-03-10T19:58:49.608Z,1741636729.608 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2025-03-10T19:58:49.699Z,1741636729.699 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2025-03-10T19:58:50.541Z,1741636730.541 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2025-03-10T19:58:50.542Z,1741636730.542 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2025-03-10T19:58:50.929Z,1741636730.929 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2025-03-10T19:58:50.929Z,1741636730.929 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2025-03-10T19:58:51.319Z,1741636731.319 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2025-03-10T19:58:51.319Z,1741636731.319 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2025-03-10T19:58:51.548Z,1741636731.548 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2025-03-10T19:58:51.548Z,1741636731.548 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2025-03-10T19:58:51.977Z,1741636731.977 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2025-03-10T19:58:51.977Z,1741636731.977 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2025-03-10T19:58:52.236Z,1741636732.236 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2025-03-10T19:58:52.237Z,1741636732.237 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2025-03-10T19:58:52.238Z,1741636732.238 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2025-03-10T19:58:52.336Z,1741636732.336 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2025-03-10T19:58:52.448Z,1741636732.448 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2025-03-10T19:58:52.543Z,1741636732.543 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2025-03-10T19:58:52.657Z,1741636732.657 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2025-03-10T19:58:52.762Z,1741636732.762 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2025-03-10T19:58:52.889Z,1741636732.889 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2025-03-10T19:58:53.053Z,1741636733.053 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2025-03-10T19:58:53.214Z,1741636733.214 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2025-03-10T19:58:53.336Z,1741636733.336 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2025-03-10T19:58:53.610Z,1741636733.610 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2025-03-10T19:58:53.611Z,1741636733.611 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2025-03-10T19:58:53.611Z,1741636733.611 [Supervisor](IMPORTANT): Reloading configuration overrides from Data/persisted.cfg
2025-03-10T19:58:53.620Z,1741636733.620 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2025-03-10T19:58:53.676Z,1741636733.676 [VerticalControl](DEBUG): Construct VerticalControl.
2025-03-10T19:58:53.736Z,1741636733.736 [VerticalControl] Loaded
2025-03-10T19:58:53.736Z,1741636733.736 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2025-03-10T19:58:53.739Z,1741636733.739 [HorizontalControl](DEBUG): Construct HorizontalControl.
2025-03-10T19:58:53.785Z,1741636733.785 [HorizontalControl] Loaded
2025-03-10T19:58:53.785Z,1741636733.785 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2025-03-10T19:58:53.788Z,1741636733.788 [SpeedControl](DEBUG): Construct SpeedControl.
2025-03-10T19:58:53.791Z,1741636733.791 [SpeedControl] Loaded
2025-03-10T19:58:53.791Z,1741636733.791 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2025-03-10T19:58:53.794Z,1741636733.794 [LoopControl](DEBUG): Construct LoopControl.
2025-03-10T19:58:53.794Z,1741636733.794 [LoopControl] Loaded
2025-03-10T19:58:53.794Z,1741636733.794 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2025-03-10T19:58:53.795Z,1741636733.795 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2025-03-10T19:58:53.796Z,1741636733.796 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2025-03-10T19:58:53.806Z,1741636733.806 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2025-03-10T19:58:53.806Z,1741636733.806 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2025-03-10T19:58:53.983Z,1741636733.983 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2025-03-10T19:58:53.984Z,1741636733.984 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2025-03-10T19:58:54.143Z,1741636734.143 [BuoyancyServo] Loaded
2025-03-10T19:58:54.144Z,1741636734.144 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2025-03-10T19:58:54.165Z,1741636734.165 [ElevatorServo] Loaded
2025-03-10T19:58:54.166Z,1741636734.166 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2025-03-10T19:58:54.187Z,1741636734.187 [MassServo] Loaded
2025-03-10T19:58:54.187Z,1741636734.187 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2025-03-10T19:58:54.207Z,1741636734.207 [RudderServo] Loaded
2025-03-10T19:58:54.207Z,1741636734.207 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2025-03-10T19:58:54.224Z,1741636734.224 [ThrusterHE] Loaded
2025-03-10T19:58:54.224Z,1741636734.224 [ComponentRegistry](DEBUG): SyncComponent "ThrusterHE" handled in the control thread.
2025-03-10T19:58:54.224Z,1741636734.224 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2025-03-10T19:58:54.225Z,1741636734.225 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2025-03-10T19:58:54.338Z,1741636734.338 [DeadReckonUsingMultipleVelocitySources] Loaded
2025-03-10T19:58:54.338Z,1741636734.338 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2025-03-10T19:58:54.352Z,1741636734.352 [NavChart] Loaded
2025-03-10T19:58:54.352Z,1741636734.352 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2025-03-10T19:58:54.357Z,1741636734.357 [UniversalFixResidualReporter] Loaded
2025-03-10T19:58:54.358Z,1741636734.358 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2025-03-10T19:58:54.358Z,1741636734.358 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2025-03-10T19:58:54.359Z,1741636734.359 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2025-03-10T19:58:54.418Z,1741636734.418 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2025-03-10T19:58:54.418Z,1741636734.418 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2025-03-10T19:58:54.895Z,1741636734.895 [AHRS_M2] Loaded
2025-03-10T19:58:54.896Z,1741636734.896 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2025-03-10T19:58:55.699Z,1741636735.699 [BPC1] Loaded
2025-03-10T19:58:55.699Z,1741636735.699 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2025-03-10T19:58:55.798Z,1741636735.798 [DAT] Loaded
2025-03-10T19:58:55.798Z,1741636735.798 [ComponentRegistry](DEBUG): Component "DAT" handled in its own thread.
2025-03-10T19:58:55.800Z,1741636735.800 [DAT ThreadHandler](DEBUG): Created PCaller Thread at 4094B4E0
2025-03-10T19:58:55.800Z,1741636735.800 [DAT ThreadHandler](INFO): Protected caller Thread ID is 4775
2025-03-10T19:58:55.874Z,1741636735.874 [DataOverHttps] Loaded
2025-03-10T19:58:55.874Z,1741636735.874 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2025-03-10T19:58:55.875Z,1741636735.875 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4097B4E0
2025-03-10T19:58:55.876Z,1741636735.876 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4776
2025-03-10T19:58:55.898Z,1741636735.898 [Depth_Keller] Loaded
2025-03-10T19:58:55.899Z,1741636735.899 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2025-03-10T19:58:55.904Z,1741636735.904 [DropWeight] Loaded
2025-03-10T19:58:55.904Z,1741636735.904 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2025-03-10T19:58:55.968Z,1741636735.968 [NAL9602] Loaded
2025-03-10T19:58:55.968Z,1741636735.968 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2025-03-10T19:58:55.997Z,1741636735.997 [Onboard] Loaded
2025-03-10T19:58:55.998Z,1741636735.998 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2025-03-10T19:58:55.999Z,1741636735.999 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409AB4E0
2025-03-10T19:58:55.999Z,1741636735.999 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 4777
2025-03-10T19:58:56.013Z,1741636736.013 [Power24vConverter] Loaded
2025-03-10T19:58:56.013Z,1741636736.013 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2025-03-10T19:58:56.029Z,1741636736.029 [Radio_Surface] Loaded
2025-03-10T19:58:56.029Z,1741636736.029 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2025-03-10T19:58:56.030Z,1741636736.030 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409DB4E0
2025-03-10T19:58:56.031Z,1741636736.031 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4778
2025-03-10T19:58:56.046Z,1741636736.046 [Sonardyne_Nano] Loaded
2025-03-10T19:58:56.046Z,1741636736.046 [ComponentRegistry](DEBUG): SyncComponent "Sonardyne_Nano" handled in the control thread.
2025-03-10T19:58:56.047Z,1741636736.047 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2025-03-10T19:58:56.047Z,1741636736.047 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2025-03-10T19:58:56.100Z,1741636736.100 [DepthRateCalculator] Loaded
2025-03-10T19:58:56.100Z,1741636736.100 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2025-03-10T19:58:56.105Z,1741636736.105 [PitchRateCalculator] Loaded
2025-03-10T19:58:56.105Z,1741636736.105 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2025-03-10T19:58:56.114Z,1741636736.114 [SpeedCalculator] Loaded
2025-03-10T19:58:56.114Z,1741636736.114 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2025-03-10T19:58:56.118Z,1741636736.118 [YawRateCalculator] Loaded
2025-03-10T19:58:56.118Z,1741636736.118 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2025-03-10T19:58:56.137Z,1741636736.137 [ElevatorOffsetCalculator] Loaded
2025-03-10T19:58:56.137Z,1741636736.137 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2025-03-10T19:58:56.138Z,1741636736.138 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2025-03-10T19:58:56.138Z,1741636736.138 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2025-03-10T19:58:56.266Z,1741636736.266 [CANONSampler] Loaded
2025-03-10T19:58:56.266Z,1741636736.266 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2025-03-10T19:58:56.330Z,1741636736.330 [CTD_Seabird] Loaded
2025-03-10T19:58:56.330Z,1741636736.330 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2025-03-10T19:58:56.332Z,1741636736.332 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A964E0
2025-03-10T19:58:56.332Z,1741636736.332 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4779
2025-03-10T19:58:56.354Z,1741636736.354 [PAR_Licor] Loaded
2025-03-10T19:58:56.354Z,1741636736.354 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2025-03-10T19:58:56.399Z,1741636736.399 [WetLabsBB2FL] Loaded
2025-03-10T19:58:56.399Z,1741636736.399 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2025-03-10T19:58:56.400Z,1741636736.400 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AC64E0
2025-03-10T19:58:56.401Z,1741636736.401 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4780
2025-03-10T19:58:56.401Z,1741636736.401 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2025-03-10T19:58:56.402Z,1741636736.402 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2025-03-10T19:58:56.482Z,1741636736.482 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2025-03-10T19:58:56.482Z,1741636736.482 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2025-03-10T19:58:57.213Z,1741636737.213 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2025-03-10T19:58:57.213Z,1741636737.213 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2025-03-10T19:58:57.375Z,1741636737.375 [SBIT](DEBUG): Construct Startup Built In Test.
2025-03-10T19:58:57.384Z,1741636737.384 [SBIT] Loaded
2025-03-10T19:58:57.384Z,1741636737.384 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2025-03-10T19:58:57.387Z,1741636737.387 [IBIT](DEBUG): Construct Initiated Built In Test.
2025-03-10T19:58:57.400Z,1741636737.400 [IBIT] Loaded
2025-03-10T19:58:57.400Z,1741636737.400 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2025-03-10T19:58:57.405Z,1741636737.405 [CBIT](DEBUG): Construct Continuous Built In Test.
2025-03-10T19:58:57.485Z,1741636737.485 [CBIT] Loaded
2025-03-10T19:58:57.486Z,1741636737.486 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2025-03-10T19:58:57.517Z,1741636737.517 [GFScanner] Loaded
2025-03-10T19:58:57.518Z,1741636737.518 [ComponentRegistry](DEBUG): SyncComponent "GFScanner" handled in the control thread.
2025-03-10T19:58:57.518Z,1741636737.518 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2025-03-10T19:58:57.519Z,1741636737.519 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2025-03-10T19:58:57.651Z,1741636737.651 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2025-03-10T19:58:57.658Z,1741636737.658 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2025-03-10T19:58:57.660Z,1741636737.660 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2025-03-10T19:58:57.671Z,1741636737.671 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2025-03-10T19:58:57.672Z,1741636737.672 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40C354E0
2025-03-10T19:58:57.673Z,1741636737.673 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4781
2025-03-10T19:58:57.677Z,1741636737.677 [Supervisor](INFO): Main Thread ID is 3600
2025-03-10T19:58:57.677Z,1741636737.677 [Supervisor](DEBUG): Running supervisor.
2025-03-10T19:58:57.678Z,1741636737.678 [CommandExec ThreadHandler](INFO): Handler Thread ID is 4782
2025-03-10T19:58:57.678Z,1741636737.678 [CommandExec](INFO): Initializing the command executive.
2025-03-10T19:58:57.680Z,1741636737.680 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4783
2025-03-10T19:58:57.682Z,1741636737.682 [controlThread ThreadHandler](INFO): Handler Thread ID is 4784
2025-03-10T19:58:57.683Z,1741636737.683 [controlThread](DEBUG): Initializing ControlThread
2025-03-10T19:58:57.684Z,1741636737.684 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2025-03-10T19:58:57.685Z,1741636737.685 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2025-03-10T19:58:57.686Z,1741636737.686 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2025-03-10T19:58:57.686Z,1741636737.686 [LoopControl](DEBUG): Initialize LoopControlComponent.
2025-03-10T19:58:57.688Z,1741636737.688 [NavChart](DEBUG): Initialize NavChart Navigation.
2025-03-10T19:58:57.689Z,1741636737.689 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2025-03-10T19:58:57.694Z,1741636737.694 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2025-03-10T19:58:57.694Z,1741636737.694 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2025-03-10T19:58:57.694Z,1741636737.694 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2025-03-10T19:58:57.695Z,1741636737.695 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2025-03-10T19:58:57.695Z,1741636737.695 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2025-03-10T19:58:57.700Z,1741636737.700 [SBIT](INFO): Initialize SBIT Component.
2025-03-10T19:58:57.700Z,1741636737.700 [SBIT](IMPORTANT): git: 2025-03-10
2025-03-10T19:58:57.700Z,1741636737.700 [SBIT](INFO): git hash: ed984fb97dc40cbcfe09dec37ea56a9b8070d839
2025-03-10T19:58:57.701Z,1741636737.701 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2025-03-10T19:58:57.702Z,1741636737.702 [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-10T19:58:57.703Z,1741636737.703 [SBIT](INFO): Beginning SBIT in 53.000000 seconds.
2025-03-10T19:58:57.704Z,1741636737.704 [IBIT](INFO): Initialize IBIT Component.
2025-03-10T19:58:57.704Z,1741636737.704 [CBIT](DEBUG): Initialize CBIT Component.
2025-03-10T19:58:57.705Z,1741636737.705 [logger ThreadHandler](INFO): Handler Thread ID is 4785
2025-03-10T19:58:57.715Z,1741636737.715 [CBIT](DEBUG): Initialized mux pins.
2025-03-10T19:58:57.716Z,1741636737.716 [CBIT](DEBUG): Initializing the watchdog timer.
2025-03-10T19:58:57.724Z,1741636737.724 [DAT ThreadHandler](INFO): Handler Thread ID is 4786
2025-03-10T19:58:57.725Z,1741636737.725 [DAT](INFO): Powering up
2025-03-10T19:58:57.725Z,1741636737.725 [DAT](DEBUG): Initializing DAT.
2025-03-10T19:58:57.729Z,1741636737.729 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4788
2025-03-10T19:58:57.731Z,1741636737.731 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2025-03-10T19:58:57.740Z,1741636737.740 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2025-03-10T19:58:57.740Z,1741636737.740 [CBIT](DEBUG): Initializing heartbeat.
2025-03-10T19:58:57.741Z,1741636737.741 [Onboard ThreadHandler](INFO): Handler Thread ID is 4789
2025-03-10T19:58:57.761Z,1741636737.761 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4790
2025-03-10T19:58:57.772Z,1741636737.772 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4791
2025-03-10T19:58:57.773Z,1741636737.773 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2025-03-10T19:58:57.777Z,1741636737.777 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4793
2025-03-10T19:58:57.781Z,1741636737.781 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4795
2025-03-10T19:58:57.784Z,1741636737.784 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2025-03-10T19:58:57.784Z,1741636737.784 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2025-03-10T19:58:57.784Z,1741636737.784 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2025-03-10T19:58:57.785Z,1741636737.785 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2025-03-10T19:58:57.785Z,1741636737.785 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2025-03-10T19:58:57.785Z,1741636737.785 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2025-03-10T19:58:57.785Z,1741636737.785 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2025-03-10T19:58:57.785Z,1741636737.785 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2025-03-10T19:58:57.800Z,1741636737.800 [WetLabsBB2FL](INFO): Powering up
2025-03-10T19:58:57.811Z,1741636737.811 [CBIT](DEBUG): Deactivating emergency mode.
2025-03-10T19:58:57.851Z,1741636737.851 [CBIT](DEBUG): Backplane powered.
2025-03-10T19:58:57.852Z,1741636737.852 [GFScanner](DEBUG): Initializing GFScanner
2025-03-10T19:58:57.852Z,1741636737.852 [GFScanner](DEBUG): Deactivating GF circuits.
2025-03-10T19:58:57.856Z,1741636737.856 [MissionManager](IMPORTANT): Using TethysL tl-to-tx parser
2025-03-10T19:58:57.857Z,1741636737.857 [MissionManager](INFO): Loading Mission from file: Missions/Startup.tl
2025-03-10T19:58:57.857Z,1741636737.857 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Startup.tl
2025-03-10T19:58:57.859Z,1741636737.859 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Startup.tl
2025-03-10T19:58:57.920Z,1741636737.920 [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-10T19:58:57.940Z,1741636737.940 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2025-03-10T19:58:57.964Z,1741636737.964 [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-10T19:58:57.965Z,1741636737.965 [MissionManager](INFO): Loading Mission from file: Missions/Default.tl
2025-03-10T19:58:57.965Z,1741636737.965 [MissionManager](DEBUG): TethyslAPI: loading: Missions/Default.tl
2025-03-10T19:58:57.966Z,1741636737.966 [MissionManager](DEBUG): TethyslAPI: mission file loaded: Missions/Default.tl
2025-03-10T19:58:58.164Z,1741636738.164 [Radio_Surface](INFO): Powering up
2025-03-10T19:58:58.318Z,1741636738.318 [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-10T19:58:58.352Z,1741636738.352 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2025-03-10T19:58:58.353Z,1741636738.353 [Default:A.Wait](DEBUG): Construct Wait.
2025-03-10T19:58:58.355Z,1741636738.355 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2025-03-10T19:58:58.404Z,1741636738.404 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2025-03-10T19:58:58.407Z,1741636738.407 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2025-03-10T19:58:58.429Z,1741636738.429 [Default:E.Execute](DEBUG): Construct Execute.
2025-03-10T19:58:58.432Z,1741636738.432 [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-10T19:58:58.444Z,1741636738.444 [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-03-10T19:58:58.455Z,1741636738.455 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2025-03-10T19:58:58.522Z,1741636738.522 [Power24vConverter](INFO): Powering up.
2025-03-10T19:58:58.523Z,1741636738.523 [Sonardyne_Nano](INFO): Initializing.
2025-03-10T19:58:58.540Z,1741636738.540 [CANONSampler](INFO): Powering down
2025-03-10T19:58:58.582Z,1741636738.582 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2025-03-10T19:58:58.588Z,1741636738.588 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2025-03-10T19:58:58.589Z,1741636738.589 [ElevatorServo](DEBUG): Initializing EZServoServo.
2025-03-10T19:58:58.596Z,1741636738.596 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2025-03-10T19:58:58.597Z,1741636738.597 [MassServo](DEBUG): Initializing EZServoServo.
2025-03-10T19:58:58.604Z,1741636738.604 [MassServo](DEBUG): Initializing MassServo.
2025-03-10T19:58:58.605Z,1741636738.605 [RudderServo](DEBUG): Initializing EZServoServo.
2025-03-10T19:58:58.612Z,1741636738.612 [RudderServo](DEBUG): Initializing RudderServo.
2025-03-10T19:58:58.613Z,1741636738.613 [ThrusterHE](DEBUG): Initializing EZServoServo.
2025-03-10T19:58:58.620Z,1741636738.620 [ThrusterHE](DEBUG): Initializing ThrusterHE.
2025-03-10T19:58:58.895Z,1741636738.895 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2025-03-10T19:58:58.895Z,1741636738.895 [DropWeight] Hardware Fault, FailCount= 1
2025-03-10T19:58:58.895Z,1741636738.895 [DropWeight](ERROR): Hardware Fault
2025-03-10T19:58:58.925Z,1741636738.925 [CommandExec](FAULT): Scheduling is paused
2025-03-10T19:58:58.926Z,1741636738.926 [CBIT](INFO): Critical error at 20250310T195858
2025-03-10T19:58:58.926Z,1741636738.926 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2025-03-10T19:58:58.937Z,1741636738.937 [CBIT](ERROR): Hardware Fault in component: DropWeight
2025-03-10T19:58:58.937Z,1741636738.937 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2025-03-10T19:58:59.310Z,1741636739.310 [CBIT](INFO): Critical error at 20250310T195858
2025-03-10T19:58:59.892Z,1741636739.892 [WetLabsBB2FL](INFO): Powering down
2025-03-10T19:59:00.474Z,1741636740.474 [Sonardyne_Nano](ERROR): Failed to get valid response or reached max queue size, flushing UART
2025-03-10T19:59:01.764Z,1741636741.764 [Sonardyne_Nano](IMPORTANT): Found beacon with acoustic ID 2109
2025-03-10T19:59:09.316Z,1741636749.316 [DAT](INFO): DAT read: 

2025-03-10T19:59:09.317Z,1741636749.317 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2025-03-10T19:59:10.829Z,1741636750.829 [DAT](INFO): DAT read: MF Frequency Band

2025-03-10T19:59:10.830Z,1741636750.830 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.15.0

2025-03-10T19:59:10.830Z,1741636750.830 [DAT](INFO): DAT read: Mar 10 2025 19:59:05 

2025-03-10T19:59:11.838Z,1741636751.838 [DAT](INFO): DAT read: Features enabled [Bearing]

2025-03-10T19:59:11.839Z,1741636751.839 [DAT](INFO): DAT read: CONNECT 01200 bits/sec 1 of 4, Rate 1/2 CC

2025-03-10T19:59:11.840Z,1741636751.840 [DAT](INFO): commRate: 1200
2025-03-10T19:59:13.908Z,1741636753.908 [DAT](INFO): entering command mode
2025-03-10T19:59:14.104Z,1741636754.104 [DAT](INFO): DAT read: 

2025-03-10T19:59:14.105Z,1741636754.105 [DAT](INFO): DAT read: user:1>

2025-03-10T19:59:14.105Z,1741636754.105 [DAT](INFO): setting verbose to 3
2025-03-10T19:59:14.357Z,1741636754.357 [DAT](INFO): DAT read: user:1>

2025-03-10T19:59:14.358Z,1741636754.358 [DAT](INFO): DAT read: Verbose         | 3           

2025-03-10T19:59:14.358Z,1741636754.358 [DAT](INFO): set verbose to 3
2025-03-10T19:59:14.359Z,1741636754.359 [DAT](INFO): setting DatVerbose to 27440
2025-03-10T19:59:14.608Z,1741636754.608 [DAT](INFO): DAT read: user:2>

2025-03-10T19:59:14.609Z,1741636754.609 [DAT](INFO): DAT read: DatVerbose      | 27440       

2025-03-10T19:59:14.610Z,1741636754.610 [DAT](INFO): set DatVerbose to 27440
2025-03-10T19:59:14.610Z,1741636754.610 [DAT](INFO): setting transmit power to 8
2025-03-10T19:59:14.860Z,1741636754.860 [DAT](INFO): DAT read: user:3>

2025-03-10T19:59:14.861Z,1741636754.861 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2025-03-10T19:59:14.862Z,1741636754.862 [DAT](INFO): set transmit power to 8
2025-03-10T19:59:14.862Z,1741636754.862 [DAT](INFO): setting local address to 8
2025-03-10T19:59:15.112Z,1741636755.112 [DAT](INFO): DAT read: user:4>

2025-03-10T19:59:15.113Z,1741636755.113 [DAT](INFO): DAT read: LocalAddr       | 8           

2025-03-10T19:59:15.114Z,1741636755.114 [DAT](INFO): set local address to 8
2025-03-10T19:59:15.115Z,1741636755.115 [DAT](INFO): Setting time to: 19:59:15 And date to:3/10/2025
2025-03-10T19:59:15.364Z,1741636755.364 [DAT](INFO): DAT read: user:5>

2025-03-10T19:59:15.365Z,1741636755.365 [DAT](INFO): DAT read: Mon Mar 10, 2025  19:59:15  

2025-03-10T19:59:15.366Z,1741636755.366 [DAT](INFO): Local DAT time set to Mon Mar 10, 2025  19:59:15  

2025-03-10T19:59:26.005Z,1741636766.005 [NAL9602](INFO): Powering up NAL9602
2025-03-10T19:59:36.919Z,1741636776.919 [NAL9602](INFO): NAL9602 initialized
2025-03-10T19:59:51.491Z,1741636791.491 [SBIT](IMPORTANT): Beginning Startup BIT
2025-03-10T19:59:51.496Z,1741636791.496 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-03-10T19:59:56.016Z,1741636796.016 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.002045
CHAN A1 (24V): 0.000818
CHAN A2 (12V): -0.002530
CHAN A3 (5V): -0.001848
CHAN B0 (3.3V): -0.000499
CHAN B1 (3.15aV): -0.000137
CHAN B2 (3.15bV): -0.000343
CHAN B3 (GND): -0.000406
OPEN: -0.000401
Full Scale: +/- 1 mA
2025-03-10T20:00:04.534Z,1741636804.534 [SBIT](FAULT): Rudder: EXPECTED:15.000000 ACTUAL:14.706036
2025-03-10T20:00:25.453Z,1741636825.453 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-03-10T20:00:44.842Z,1741636844.842 [SBIT](CRITICAL): SBIT FAILED
2025-03-10T20:00:44.842Z,1741636844.842 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2025-03-10T20:00:44.843Z,1741636844.843 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=11 count;

2025-03-10T20:00:44.843Z,1741636844.843 [SBIT](IMPORTANT): DATMMP.surfaceThreshold=2 meter;

2025-03-10T20:00:44.844Z,1741636844.844 [SBIT](IMPORTANT): Express linearApproximation DATMMP.acoustic_contact_range 15.000000 meter;

2025-03-10T20:00:44.844Z,1741636844.844 [SBIT](IMPORTANT): GFScanner.loadAtStartup=1 bool;

2025-03-10T20:00:44.844Z,1741636844.844 [SBIT](IMPORTANT): GFScanner.simulateHardware=0 bool;

2025-03-10T20:00:44.844Z,1741636844.844 [SBIT](IMPORTANT): RudderServo.offsetAngle=1 degree;

2025-03-10T20:00:44.844Z,1741636844.844 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=355.541360 cubic_centimeter;

2025-03-10T20:00:44.844Z,1741636844.844 [SBIT](IMPORTANT): VerticalControl.massDefault=8.472520 millimeter;

2025-03-10T20:00:44.846Z,1741636844.846 [CBIT](INFO): Critical error at 20250310T200044
2025-03-10T20:00:45.249Z,1741636845.249 [MissionManager](IMPORTANT): Started mission Startup
2025-03-10T20:00:45.249Z,1741636845.249 [Startup] Running Loop=1
2025-03-10T20:00:45.249Z,1741636845.249 [Startup](DEBUG): Aggregate::initialize Startup
2025-03-10T20:00:45.249Z,1741636845.249 [Startup:A.GoToSurface] Running Loop=1
2025-03-10T20:00:45.250Z,1741636845.250 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-03-10T20:00:45.250Z,1741636845.250 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-03-10T20:00:45.250Z,1741636845.250 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-03-10T20:00:45.251Z,1741636845.251 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-03-10T20:00:45.251Z,1741636845.251 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-03-10T20:00:45.252Z,1741636845.252 [Startup:A.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-03-10T20:00:45.252Z,1741636845.252 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-03-10T20:00:45.254Z,1741636845.254 [Startup:StartupSatComms] Running Loop=1
2025-03-10T20:00:45.254Z,1741636845.254 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2025-03-10T20:00:45.254Z,1741636845.254 [Startup:StartupSatComms:A] Running Loop=1
2025-03-10T20:00:45.640Z,1741636845.640 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2025-03-10T20:01:40.705Z,1741636900.705 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004093
2025-03-10T20:01:45.433Z,1741636905.433 [Startup:StartupSatComms:A](INFO): Timed out from 2025-03-10T20:00:45.3Z
2025-03-10T20:01:45.433Z,1741636905.433 [Startup:StartupSatComms:A] Stopped
2025-03-10T20:01:45.433Z,1741636905.433 [Startup:StartupSatComms:B] Running Loop=1
2025-03-10T20:01:45.842Z,1741636905.842 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-03-10T20:01:53.060Z,1741636913.060 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20250310T194453/Courier0007.lzma
2025-03-10T20:01:54.061Z,1741636914.061 [DataOverHttps](INFO): Moved sent file to Logs/20250310T194453/Courier0007.lzma.bak
2025-03-10T20:01:54.062Z,1741636914.062 [DataOverHttps](INFO): SBD MOMSN=24150364
2025-03-10T20:01:57.990Z,1741636917.990 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2025-03-10T20:01:57.990Z,1741636917.990 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T20:01:58.009Z,1741636918.009 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T20:01:58.409Z,1741636918.409 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T20:01:58.409Z,1741636918.409 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2025-03-10T20:02:09.912Z,1741636929.912 [DataOverHttps](INFO): Sending 265 bytes from file Logs/20250310T195846/Courier0000.lzma
2025-03-10T20:02:10.913Z,1741636930.913 [DataOverHttps](INFO): Moved sent file to Logs/20250310T195846/Courier0000.lzma.bak
2025-03-10T20:02:10.914Z,1741636930.914 [DataOverHttps](INFO): SBD MOMSN=24150366
2025-03-10T20:02:26.620Z,1741636946.620 [DataOverHttps](INFO): Sending 217 bytes from file Logs/20250310T194453/Express0008.lzma
2025-03-10T20:02:27.621Z,1741636947.621 [DataOverHttps](INFO): Moved sent file to Logs/20250310T194453/Express0008.lzma.bak
2025-03-10T20:02:27.621Z,1741636947.621 [DataOverHttps](INFO): SBD MOMSN=24150372
2025-03-10T20:02:35.578Z,1741636955.578 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-10T20:02:43.781Z,1741636963.781 [DataOverHttps](INFO): Sending 936 bytes from file Logs/20250310T195846/Express0001.lzma
2025-03-10T20:02:44.782Z,1741636964.782 [DataOverHttps](INFO): Moved sent file to Logs/20250310T195846/Express0001.lzma.bak
2025-03-10T20:02:44.782Z,1741636964.782 [DataOverHttps](INFO): SBD MOMSN=24150380
2025-03-10T20:02:45.640Z,1741636965.640 [Startup:StartupSatComms:B](INFO): Timed out from 2025-03-10T20:01:45.4Z
2025-03-10T20:02:45.640Z,1741636965.640 [Startup:StartupSatComms:B] Stopped
2025-03-10T20:02:45.640Z,1741636965.640 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2025-03-10T20:02:45.640Z,1741636965.640 [Startup:StartupSatComms] Stopped
2025-03-10T20:02:45.641Z,1741636965.641 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2025-03-10T20:02:45.641Z,1741636965.641 [Startup](INFO): Completed Startup
2025-03-10T20:02:45.642Z,1741636965.642 [MissionManager](INFO): Startup is completed.
2025-03-10T20:02:45.642Z,1741636965.642 [MissionManager](INFO): Uninitializing Mission Startup
2025-03-10T20:02:45.642Z,1741636965.642 [Startup] Stopped
2025-03-10T20:02:45.642Z,1741636965.642 [Startup](DEBUG): Aggregate::uninitialize Startup
2025-03-10T20:02:45.642Z,1741636965.642 [Startup:A.GoToSurface] Stopped
2025-03-10T20:02:45.642Z,1741636965.642 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-03-10T20:02:46.049Z,1741636966.049 [MissionManager](IMPORTANT): Started mission Default
2025-03-10T20:02:46.050Z,1741636966.050 [Default] Running Loop=1
2025-03-10T20:02:46.050Z,1741636966.050 [Default](DEBUG): Aggregate::initialize Default
2025-03-10T20:02:46.050Z,1741636966.050 [Default:B.GoToSurface] Running Loop=1
2025-03-10T20:02:46.050Z,1741636966.050 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2025-03-10T20:02:46.050Z,1741636966.050 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2025-03-10T20:02:46.050Z,1741636966.050 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2025-03-10T20:02:46.051Z,1741636966.051 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2025-03-10T20:02:46.051Z,1741636966.051 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2025-03-10T20:02:46.052Z,1741636966.052 [Default:B.GoToSurface](DEBUG): No altitude timeout specified. Using default value of 600.000000 seconds.
2025-03-10T20:02:46.052Z,1741636966.052 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2025-03-10T20:02:46.052Z,1741636966.052 [Default:A.Wait] Running Loop=1
2025-03-10T20:02:46.052Z,1741636966.052 [Default:A.Wait](DEBUG): Initialize Wait Component.
2025-03-10T20:02:59.386Z,1741636979.386 [Default:A.Wait](INFO): Done Waiting.
2025-03-10T20:02:59.386Z,1741636979.386 [Default:A.Wait] Stopped
2025-03-10T20:02:59.387Z,1741636979.387 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2025-03-10T20:02:59.794Z,1741636979.794 [Default:CheckIn] Running Loop=1
2025-03-10T20:02:59.794Z,1741636979.794 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2025-03-10T20:02:59.794Z,1741636979.794 [Default:CheckIn:Read_GPS] Running Loop=1
2025-03-10T20:03:00.203Z,1741636980.203 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2025-03-10T20:03:01.841Z,1741636981.841 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-10T20:03:25.502Z,1741637005.502 [CommandExec](IMPORTANT): got command configSet RudderServo.offsetAngle 0 degree persist
2025-03-10T20:03:45.813Z,1741637025.813 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-03-10T20:03:53.096Z,1741637033.096 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-03-10T20:03:54.060Z,1741637034.060 [CommandExec](IMPORTANT): got command maintain control HorizontalControl.rudderAngleAction 0 degree
2025-03-10T20:03:54.064Z,1741637034.064 [ComponentRegistry](DEBUG): SyncComponent "Maintain_HorizontalControl.rudderAngleAction" handled in the control thread.
2025-03-10T20:03:54.353Z,1741637034.353 [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,Maintain_HorizontalControl.rudderAngleAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterHE,SBIT,IBIT,CBIT,GFScanner,Reporter,LogSplitter,
2025-03-10T20:04:12.477Z,1741637052.477 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-03-10T20:04:18.197Z,1741637058.197 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2025-03-10T20:04:31.869Z,1741637071.869 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-03-10T20:04:40.765Z,1741637080.765 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2025-03-10T20:04:51.667Z,1741637091.667 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-03-10T20:04:58.948Z,1741637098.948 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2025-03-10T20:04:58.948Z,1741637098.948 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T20:04:58.976Z,1741637098.976 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T20:04:59.363Z,1741637099.363 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T20:04:59.363Z,1741637099.363 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2025-03-10T20:05:11.057Z,1741637111.057 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-03-10T20:05:26.116Z,1741637126.116 [CommandExec](IMPORTANT): got command ibit
2025-03-10T20:05:26.460Z,1741637126.460 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-03-10T20:05:26.460Z,1741637126.460 [IBIT](IMPORTANT): Beginning control surface checks.
2025-03-10T20:05:26.467Z,1741637126.467 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-03-10T20:05:30.453Z,1741637130.453 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7).
2025-03-10T20:05:30.967Z,1741637130.967 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.003663
CHAN A1 (24V): 0.000225
CHAN A2 (12V): -0.001985
CHAN A3 (5V): -0.001630
CHAN B0 (3.3V): -0.000148
CHAN B1 (3.15aV): 0.000055
CHAN B2 (3.15bV): -0.000269
CHAN B3 (GND): 0.000176
OPEN: -0.000238
Full Scale: +/- 1 mA
2025-03-10T20:05:41.804Z,1741637141.804 [IBIT](FAULT): Rudder: EXPECTED:15.000000 ACTUAL:-0.019795
2025-03-10T20:05:41.804Z,1741637141.804 [IBIT](FAULT): Control surface position failure.
2025-03-10T20:05:49.474Z,1741637149.474 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2025-03-10T20:05:57.140Z,1741637157.140 [IBIT](FAULT): Rudder: EXPECTED:-15.000000 ACTUAL:-0.019795
2025-03-10T20:05:57.140Z,1741637157.140 [IBIT](FAULT): Control surface position failure.
2025-03-10T20:06:02.893Z,1741637162.893 [CommandExec](IMPORTANT): got command maintain clear
2025-03-10T20:06:03.224Z,1741637163.224 [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-03-10T20:06:10.824Z,1741637170.824 [CommandExec](IMPORTANT): got command ibit
2025-03-10T20:06:10.897Z,1741637170.897 [IBIT](IMPORTANT): Beginning Initiated BIT
2025-03-10T20:06:10.897Z,1741637170.897 [IBIT](IMPORTANT): Beginning control surface checks.
2025-03-10T20:06:10.900Z,1741637170.900 [GFScanner](IMPORTANT): Beginning ground fault scan
2025-03-10T20:06:15.412Z,1741637175.412 [GFScanner](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.003319
CHAN A1 (24V): 0.000459
CHAN A2 (12V): -0.002701
CHAN A3 (5V): -0.001553
CHAN B0 (3.3V): 0.000403
CHAN B1 (3.15aV): 0.000131
CHAN B2 (3.15bV): -0.000491
CHAN B3 (GND): -0.000116
OPEN: -0.000510
Full Scale: +/- 1 mA
2025-03-10T20:07:59.954Z,1741637279.954 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2025-03-10T20:07:59.954Z,1741637279.954 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2025-03-10T20:07:59.964Z,1741637279.964 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2025-03-10T20:08:00.380Z,1741637280.380 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2025-03-10T20:08:00.380Z,1741637280.380 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2025-03-10T20:08:01.574Z,1741637281.574 [IBIT](FAULT): Error acquiring IBIT communications status. Timeout expired.
2025-03-10T20:08:01.988Z,1741637281.988 [IBIT](FAULT): Warning: Battery Data not active. Expected only when running primaries. Threshold checking not active.
2025-03-10T20:08:02.411Z,1741637282.411 [IBIT](IMPORTANT): bitHumidityThreshold: 55.000000 %
2025-03-10T20:08:02.412Z,1741637282.412 [IBIT](IMPORTANT): bitPressureThreshold: 0.750000 psi
2025-03-10T20:08:02.412Z,1741637282.412 [IBIT](IMPORTANT): Pressure:9.460482 PSI
2025-03-10T20:08:02.412Z,1741637282.412 [IBIT](IMPORTANT): Humidity:17.244537 %
2025-03-10T20:08:02.820Z,1741637282.820 [IBIT](IMPORTANT): Vehicle Pitch:-0.620449 degrees
2025-03-10T20:08:02.821Z,1741637282.821 [IBIT](IMPORTANT): Vehicle  Roll:-5.749315 degrees
2025-03-10T20:08:02.821Z,1741637282.821 [IBIT](IMPORTANT): Vehicle  Heading:108.458946 degrees
2025-03-10T20:08:03.214Z,1741637283.214 [IBIT](IMPORTANT): surfaceThreshold: 1.000000 m
2025-03-10T20:08:03.214Z,1741637283.214 [IBIT](IMPORTANT): buoyancyNeutral: 355.541351 cc
2025-03-10T20:08:03.214Z,1741637283.214 [IBIT](IMPORTANT): massDefault: 0.847252 cm
2025-03-10T20:08:03.215Z,1741637283.215 [IBIT](IMPORTANT): stopDepth: 250.000000 m
2025-03-10T20:08:03.215Z,1741637283.215 [IBIT](IMPORTANT): abortDepth: 270.000000 m
2025-03-10T20:08:03.215Z,1741637283.215 [IBIT](IMPORTANT): IBIT FAILED
2025-03-10T20:08:03.601Z,1741637283.601 [Default:CheckIn:Read_GPS](INFO): Timed out from 2025-03-10T20:02:59.8Z
2025-03-10T20:08:03.601Z,1741637283.601 [Default:CheckIn:Read_GPS] Stopped
2025-03-10T20:08:03.601Z,1741637283.601 [Default:CheckIn:Read_Iridium] Running Loop=1
2025-03-10T20:08:03.994Z,1741637283.994 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2025-03-10T20:08:11.287Z,1741637291.287 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20250310T195846/Courier0004.lzma
2025-03-10T20:08:12.289Z,1741637292.289 [DataOverHttps](INFO): Moved sent file to Logs/20250310T195846/Courier0004.lzma.bak
2025-03-10T20:08:12.289Z,1741637292.289 [DataOverHttps](INFO): SBD MOMSN=24150408
2025-03-10T20:08:28.378Z,1741637308.378 [DataOverHttps](INFO): Sending 990 bytes from file Logs/20250310T195846/Express0005.lzma
2025-03-10T20:08:29.377Z,1741637309.377 [DataOverHttps](INFO): Moved sent file to Logs/20250310T195846/Express0005.lzma.bak
2025-03-10T20:08:29.378Z,1741637309.378 [DataOverHttps](INFO): SBD MOMSN=24150410
2025-03-10T20:08:30.706Z,1741637310.706 [Default:CheckIn:Read_Iridium] Stopped
2025-03-10T20:08:30.706Z,1741637310.706 [Default:CheckIn:C.Wait] Running Loop=1
2025-03-10T20:08:30.706Z,1741637310.706 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2025-03-10T20:08:45.505Z,1741637325.505 [CommandExec](IMPORTANT): got command restart application
2025-03-10T20:08:46.508Z,1741637326.508 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-10T20:08:46.508Z,1741637326.508 [CommandExec](INFO): Uninitializing the command executive.
2025-03-10T20:08:46.508Z,1741637326.508 [CommandExec](INFO): Uninitializing the command scheduler.
2025-03-10T20:08:46.508Z,1741637326.508 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-03-10T20:08:46.603Z,1741637326.603 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2025-03-10T20:08:46.604Z,1741637326.604 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2025-03-10T20:08:46.604Z,1741637326.604 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-03-10T20:08:46.604Z,1741637326.604 [NavChartDb](INFO): Join timeout helper Thread ID is 4821
2025-03-10T20:08:46.613Z,1741637326.613 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-10T20:08:46.614Z,1741637326.614 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-03-10T20:08:46.624Z,1741637326.624 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2025-03-10T20:08:46.624Z,1741637326.624 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-03-10T20:08:46.624Z,1741637326.624 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4822
2025-03-10T20:08:46.640Z,1741637326.640 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-10T20:08:46.640Z,1741637326.640 [WetLabsBB2FL](INFO): Powering down
2025-03-10T20:08:46.641Z,1741637326.641 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-03-10T20:08:46.641Z,1741637326.641 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2025-03-10T20:08:46.641Z,1741637326.641 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-03-10T20:08:46.642Z,1741637326.642 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4823
2025-03-10T20:08:47.212Z,1741637327.212 [CTD_Seabird](INFO): Powering down
2025-03-10T20:08:47.224Z,1741637327.224 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-10T20:08:47.224Z,1741637327.224 [CTD_Seabird](INFO): Powering down
2025-03-10T20:08:47.236Z,1741637327.236 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-03-10T20:08:47.259Z,1741637327.259 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2025-03-10T20:08:47.260Z,1741637327.260 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-03-10T20:08:47.260Z,1741637327.260 [Radio_Surface](INFO): Join timeout helper Thread ID is 4824
2025-03-10T20:08:47.317Z,1741637327.317 [Radio_Surface](INFO): Powering down
2025-03-10T20:08:47.318Z,1741637327.318 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-10T20:08:47.319Z,1741637327.319 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-03-10T20:08:47.328Z,1741637327.328 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2025-03-10T20:08:47.328Z,1741637327.328 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-03-10T20:08:47.328Z,1741637327.328 [Onboard](INFO): Join timeout helper Thread ID is 4825
2025-03-10T20:08:47.896Z,1741637327.896 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2025-03-10T20:08:48.228Z,1741637328.228 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-10T20:08:48.228Z,1741637328.228 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-03-10T20:08:48.241Z,1741637328.241 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2025-03-10T20:08:48.241Z,1741637328.241 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-03-10T20:08:48.241Z,1741637328.241 [DataOverHttps](INFO): Join timeout helper Thread ID is 4826
2025-03-10T20:08:48.900Z,1741637328.900 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-10T20:08:48.900Z,1741637328.900 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-03-10T20:08:48.920Z,1741637328.920 [ComponentRegistry](INFO): Shutting down DAT ThreadHandler
2025-03-10T20:08:48.920Z,1741637328.920 [DAT ThreadHandler](INFO): Thread cancelled.
2025-03-10T20:08:48.921Z,1741637328.921 [DAT](INFO): Join timeout helper Thread ID is 4827
2025-03-10T20:08:49.056Z,1741637329.056 [DAT](INFO): Powering down
2025-03-10T20:08:49.128Z,1741637329.128 [DAT ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-10T20:08:49.129Z,1741637329.129 [DAT](INFO): Powering down
2025-03-10T20:08:49.130Z,1741637329.130 [DAT ThreadHandler](INFO): Thread cancelled.
2025-03-10T20:08:49.144Z,1741637329.144 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2025-03-10T20:08:49.144Z,1741637329.144 [logger ThreadHandler](INFO): Thread cancelled.
2025-03-10T20:08:49.145Z,1741637329.145 [logger](INFO): Join timeout helper Thread ID is 4828
2025-03-10T20:08:49.172Z,1741637329.172 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-10T20:08:49.172Z,1741637329.172 [logger ThreadHandler](INFO): Thread cancelled.
2025-03-10T20:08:49.184Z,1741637329.184 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2025-03-10T20:08:49.184Z,1741637329.184 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-03-10T20:08:49.185Z,1741637329.185 [CommandLine](INFO): Join timeout helper Thread ID is 4829
2025-03-10T20:08:49.240Z,1741637329.240 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-10T20:08:49.240Z,1741637329.240 [CommandLine ThreadHandler](INFO): Thread cancelled.
2025-03-10T20:08:49.244Z,1741637329.244 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2025-03-10T20:08:49.244Z,1741637329.244 [CommandExec ThreadHandler](INFO): Thread cancelled.
2025-03-10T20:08:49.245Z,1741637329.245 [CommandExec](INFO): Join timeout helper Thread ID is 4830
2025-03-10T20:08:49.246Z,1741637329.246 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2025-03-10T20:08:49.246Z,1741637329.246 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-03-10T20:08:49.246Z,1741637329.246 [controlThread](INFO): Join timeout helper Thread ID is 4831
2025-03-10T20:08:49.256Z,1741637329.256 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2025-03-10T20:08:49.256Z,1741637329.256 [controlThread](DEBUG): Uninitializing ControlThread
2025-03-10T20:08:49.256Z,1741637329.256 [AHRS_M2](INFO): Powering down
2025-03-10T20:08:49.329Z,1741637329.329 [NAL9602](INFO): Powering down
2025-03-10T20:08:49.330Z,1741637329.330 [Sonardyne_Nano](INFO): Powering down
2025-03-10T20:08:49.449Z,1741637329.449 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2025-03-10T20:08:49.450Z,1741637329.450 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2025-03-10T20:08:49.450Z,1741637329.450 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2025-03-10T20:08:49.451Z,1741637329.451 [MissionManager](INFO): Uninitializing Mission Default
2025-03-10T20:08:49.451Z,1741637329.451 [Default] Stopped
2025-03-10T20:08:49.451Z,1741637329.451 [Default](DEBUG): Aggregate::uninitialize Default
2025-03-10T20:08:49.451Z,1741637329.451 [Default:B.GoToSurface] Stopped
2025-03-10T20:08:49.451Z,1741637329.451 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2025-03-10T20:08:49.451Z,1741637329.451 [Default:CheckIn] Stopped
2025-03-10T20:08:49.451Z,1741637329.451 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2025-03-10T20:08:49.452Z,1741637329.452 [Default:CheckIn:C.Wait] Stopped
2025-03-10T20:08:49.452Z,1741637329.452 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2025-03-10T20:08:49.455Z,1741637329.455 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2025-03-10T20:08:49.455Z,1741637329.455 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2025-03-10T20:08:49.455Z,1741637329.455 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2025-03-10T20:08:49.456Z,1741637329.456 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2025-03-10T20:08:49.456Z,1741637329.456 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2025-03-10T20:08:49.456Z,1741637329.456 [BuoyancyServo](INFO): Powering down
2025-03-10T20:08:49.468Z,1741637329.468 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2025-03-10T20:08:49.468Z,1741637329.468 [ElevatorServo](INFO): Powering down
2025-03-10T20:08:49.469Z,1741637329.469 [MassServo](DEBUG): Uninitialize Mass Servo.
2025-03-10T20:08:49.469Z,1741637329.469 [MassServo](INFO): Powering down
2025-03-10T20:08:49.469Z,1741637329.469 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2025-03-10T20:08:49.470Z,1741637329.470 [RudderServo](INFO): Powering down
2025-03-10T20:08:49.470Z,1741637329.470 [ThrusterHE](DEBUG): Uninitialize Thruster Servo.
2025-03-10T20:08:49.470Z,1741637329.470 [ThrusterHE](INFO): Powering down
2025-03-10T20:08:49.471Z,1741637329.471 [SBIT](DEBUG): Uninitialize SBIT Component.
2025-03-10T20:08:49.472Z,1741637329.472 [IBIT](DEBUG): Uninitialize IBIT Component.
2025-03-10T20:08:49.472Z,1741637329.472 [CBIT](DEBUG): Uninitialize CBIT Component.
2025-03-10T20:08:49.472Z,1741637329.472 [CBIT](DEBUG): Powering off loads.
2025-03-10T20:08:49.483Z,1741637329.483 [CBIT](DEBUG): Disabling WDT.
2025-03-10T20:08:49.496Z,1741637329.496 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-03-10T20:08:49.496Z,1741637329.496 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-03-10T20:08:49.497Z,1741637329.497 [controlThread ThreadHandler](INFO): Thread cancelled.
2025-03-10T20:08:49.665Z,1741637329.665 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2025-03-10T20:08:49.668Z,1741637329.668 [Onboard ThreadHandler](INFO): Thread cancelled.
2025-03-10T20:08:49.673Z,1741637329.673 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2025-03-10T20:08:49.680Z,1741637329.680 [DAT ThreadHandler](INFO): Thread cancelled.
2025-03-10T20:08:49.752Z,1741637329.752 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2025-03-10T20:08:49.757Z,1741637329.757 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2025-03-10T20:08:49.781Z,1741637329.781 [GFScanner](DEBUG): Uninitialize GFScanner component.
2025-03-10T20:08:49.781Z,1741637329.781 [GFScanner](DEBUG): Opening all GF detection circuits.
2025-03-10T20:08:49.801Z,1741637329.801 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2025-03-10T20:08:49.867Z,1741637329.867 [logger ThreadHandler](INFO): Thread cancelled.