2023-06-28T16:22:30.522Z,1687969350.522 [Supervisor](DEBUG): Initializing supervisor.
2023-06-28T16:22:30.526Z,1687969350.526 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2023-06-28T16:22:30.527Z,1687969350.527 [SyncHandler](INFO): Protected caller Thread ID is 661
2023-06-28T16:22:30.528Z,1687969350.528 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2023-06-28T16:22:30.529Z,1687969350.529 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2023-06-28T16:22:30.529Z,1687969350.529 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 662
2023-06-28T16:22:30.533Z,1687969350.533 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2023-06-28T16:22:30.552Z,1687969350.552 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2023-06-28T16:22:30.553Z,1687969350.553 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2023-06-28T16:22:30.554Z,1687969350.554 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 663
2023-06-28T16:22:30.558Z,1687969350.558 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2023-06-28T16:22:30.559Z,1687969350.559 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2023-06-28T16:22:30.559Z,1687969350.559 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 664
2023-06-28T16:22:30.561Z,1687969350.561 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2023-06-28T16:22:30.562Z,1687969350.562 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2023-06-28T16:22:30.563Z,1687969350.563 [logger ThreadHandler](INFO): Protected caller Thread ID is 665
2023-06-28T16:22:30.566Z,1687969350.566 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2023-06-28T16:22:30.567Z,1687969350.567 [Supervisor](INFO): Looking for Config files in directory: Config/
2023-06-28T16:22:30.572Z,1687969350.572 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2023-06-28T16:22:30.898Z,1687969350.898 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2023-06-28T16:22:30.898Z,1687969350.898 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2023-06-28T16:22:30.984Z,1687969350.984 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2023-06-28T16:22:31.499Z,1687969351.499 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2023-06-28T16:22:31.501Z,1687969351.501 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2023-06-28T16:22:31.837Z,1687969351.837 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2023-06-28T16:22:31.837Z,1687969351.837 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2023-06-28T16:22:31.937Z,1687969351.937 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2023-06-28T16:22:31.939Z,1687969351.939 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2023-06-28T16:22:32.316Z,1687969352.316 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2023-06-28T16:22:32.318Z,1687969352.318 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2023-06-28T16:22:32.521Z,1687969352.521 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2023-06-28T16:22:32.521Z,1687969352.521 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2023-06-28T16:22:33.013Z,1687969353.013 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2023-06-28T16:22:33.015Z,1687969353.015 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2023-06-28T16:22:33.153Z,1687969353.153 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2023-06-28T16:22:33.155Z,1687969353.155 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2023-06-28T16:22:33.237Z,1687969353.237 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2023-06-28T16:22:33.941Z,1687969353.941 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2023-06-28T16:22:33.942Z,1687969353.942 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2023-06-28T16:22:34.330Z,1687969354.330 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2023-06-28T16:22:34.465Z,1687969354.465 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2023-06-28T16:22:35.097Z,1687969355.097 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2023-06-28T16:22:35.098Z,1687969355.098 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2023-06-28T16:22:35.211Z,1687969355.211 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2023-06-28T16:22:35.213Z,1687969355.213 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2023-06-28T16:22:35.428Z,1687969355.428 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2023-06-28T16:22:35.429Z,1687969355.429 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2023-06-28T16:22:35.658Z,1687969355.658 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2023-06-28T16:22:35.661Z,1687969355.661 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2023-06-28T16:22:35.664Z,1687969355.664 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2023-06-28T16:22:35.757Z,1687969355.757 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2023-06-28T16:22:35.846Z,1687969355.846 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2023-06-28T16:22:35.948Z,1687969355.948 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2023-06-28T16:22:36.035Z,1687969356.035 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2023-06-28T16:22:36.136Z,1687969356.136 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2023-06-28T16:22:36.237Z,1687969356.237 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2023-06-28T16:22:36.411Z,1687969356.411 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2023-06-28T16:22:36.489Z,1687969356.489 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2023-06-28T16:22:36.640Z,1687969356.640 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2023-06-28T16:22:36.778Z,1687969356.778 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2023-06-28T16:22:36.889Z,1687969356.889 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2023-06-28T16:22:37.152Z,1687969357.152 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2023-06-28T16:22:37.153Z,1687969357.153 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2023-06-28T16:22:37.153Z,1687969357.153 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2023-06-28T16:22:37.168Z,1687969357.168 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2023-06-28T16:22:37.236Z,1687969357.236 [VerticalControl](DEBUG): Construct VerticalControl.
2023-06-28T16:22:37.295Z,1687969357.295 [VerticalControl] Loaded
2023-06-28T16:22:37.295Z,1687969357.295 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2023-06-28T16:22:37.298Z,1687969357.298 [HorizontalControl](DEBUG): Construct HorizontalControl.
2023-06-28T16:22:37.338Z,1687969357.338 [HorizontalControl] Loaded
2023-06-28T16:22:37.339Z,1687969357.339 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2023-06-28T16:22:37.341Z,1687969357.341 [SpeedControl](DEBUG): Construct SpeedControl.
2023-06-28T16:22:37.344Z,1687969357.344 [SpeedControl] Loaded
2023-06-28T16:22:37.344Z,1687969357.344 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2023-06-28T16:22:37.347Z,1687969357.347 [LoopControl](DEBUG): Construct LoopControl.
2023-06-28T16:22:37.347Z,1687969357.347 [LoopControl] Loaded
2023-06-28T16:22:37.348Z,1687969357.348 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2023-06-28T16:22:37.348Z,1687969357.348 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2023-06-28T16:22:37.349Z,1687969357.349 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2023-06-28T16:22:37.365Z,1687969357.365 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2023-06-28T16:22:37.366Z,1687969357.366 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2023-06-28T16:22:37.542Z,1687969357.542 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2023-06-28T16:22:37.542Z,1687969357.542 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2023-06-28T16:22:37.698Z,1687969357.698 [BuoyancyServo] Loaded
2023-06-28T16:22:37.699Z,1687969357.699 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2023-06-28T16:22:37.718Z,1687969357.718 [ElevatorServo] Loaded
2023-06-28T16:22:37.719Z,1687969357.719 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2023-06-28T16:22:37.737Z,1687969357.737 [MassServo] Loaded
2023-06-28T16:22:37.738Z,1687969357.738 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2023-06-28T16:22:37.756Z,1687969357.756 [RudderServo] Loaded
2023-06-28T16:22:37.756Z,1687969357.756 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2023-06-28T16:22:37.773Z,1687969357.773 [ThrusterServo] Loaded
2023-06-28T16:22:37.773Z,1687969357.773 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2023-06-28T16:22:37.774Z,1687969357.774 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2023-06-28T16:22:37.774Z,1687969357.774 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2023-06-28T16:22:37.909Z,1687969357.909 [DeadReckonUsingMultipleVelocitySources] Loaded
2023-06-28T16:22:37.909Z,1687969357.909 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2023-06-28T16:22:37.923Z,1687969357.923 [NavChart] Loaded
2023-06-28T16:22:37.923Z,1687969357.923 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2023-06-28T16:22:37.929Z,1687969357.929 [UniversalFixResidualReporter] Loaded
2023-06-28T16:22:37.929Z,1687969357.929 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2023-06-28T16:22:37.929Z,1687969357.929 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2023-06-28T16:22:37.930Z,1687969357.930 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2023-06-28T16:22:38.009Z,1687969358.009 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2023-06-28T16:22:38.010Z,1687969358.010 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2023-06-28T16:22:38.686Z,1687969358.686 [AHRS_M2] Loaded
2023-06-28T16:22:38.687Z,1687969358.687 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2023-06-28T16:22:39.557Z,1687969359.557 [BPC1] Loaded
2023-06-28T16:22:39.557Z,1687969359.557 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2023-06-28T16:22:39.828Z,1687969359.828 [DataOverHttps] Loaded
2023-06-28T16:22:39.828Z,1687969359.828 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2023-06-28T16:22:39.835Z,1687969359.835 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409004E0
2023-06-28T16:22:39.835Z,1687969359.835 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 751
2023-06-28T16:22:39.909Z,1687969359.909 [Depth_Keller] Loaded
2023-06-28T16:22:39.910Z,1687969359.910 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2023-06-28T16:22:39.914Z,1687969359.914 [DropWeight] Loaded
2023-06-28T16:22:39.915Z,1687969359.915 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2023-06-28T16:22:40.047Z,1687969360.047 [NAL9602] Loaded
2023-06-28T16:22:40.048Z,1687969360.048 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2023-06-28T16:22:40.221Z,1687969360.221 [Onboard] Loaded
2023-06-28T16:22:40.222Z,1687969360.222 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2023-06-28T16:22:40.223Z,1687969360.223 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409304E0
2023-06-28T16:22:40.223Z,1687969360.223 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 752
2023-06-28T16:22:40.236Z,1687969360.236 [Power24vConverter] Loaded
2023-06-28T16:22:40.236Z,1687969360.236 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2023-06-28T16:22:40.260Z,1687969360.260 [Radio_Surface] Loaded
2023-06-28T16:22:40.261Z,1687969360.261 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2023-06-28T16:22:40.262Z,1687969360.262 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409604E0
2023-06-28T16:22:40.262Z,1687969360.262 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 753
2023-06-28T16:22:40.263Z,1687969360.263 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2023-06-28T16:22:40.264Z,1687969360.264 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2023-06-28T16:22:40.634Z,1687969360.634 [DepthRateCalculator] Loaded
2023-06-28T16:22:40.634Z,1687969360.634 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2023-06-28T16:22:40.639Z,1687969360.639 [PitchRateCalculator] Loaded
2023-06-28T16:22:40.639Z,1687969360.639 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2023-06-28T16:22:40.648Z,1687969360.648 [SpeedCalculator] Loaded
2023-06-28T16:22:40.648Z,1687969360.648 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2023-06-28T16:22:40.652Z,1687969360.652 [YawRateCalculator] Loaded
2023-06-28T16:22:40.653Z,1687969360.653 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2023-06-28T16:22:40.671Z,1687969360.671 [ElevatorOffsetCalculator] Loaded
2023-06-28T16:22:40.672Z,1687969360.672 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2023-06-28T16:22:40.672Z,1687969360.672 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2023-06-28T16:22:40.673Z,1687969360.673 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2023-06-28T16:22:40.954Z,1687969360.954 [CANONSampler] Loaded
2023-06-28T16:22:40.955Z,1687969360.955 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2023-06-28T16:22:41.014Z,1687969361.014 [CTD_Seabird] Loaded
2023-06-28T16:22:41.014Z,1687969361.014 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2023-06-28T16:22:41.016Z,1687969361.016 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A194E0
2023-06-28T16:22:41.016Z,1687969361.016 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 754
2023-06-28T16:22:41.036Z,1687969361.036 [PAR_Licor] Loaded
2023-06-28T16:22:41.036Z,1687969361.036 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2023-06-28T16:22:41.067Z,1687969361.067 [WetLabsBB2FL] Loaded
2023-06-28T16:22:41.067Z,1687969361.067 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2023-06-28T16:22:41.068Z,1687969361.068 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A494E0
2023-06-28T16:22:41.069Z,1687969361.069 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 755
2023-06-28T16:22:41.069Z,1687969361.069 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2023-06-28T16:22:41.070Z,1687969361.070 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2023-06-28T16:22:41.111Z,1687969361.111 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2023-06-28T16:22:41.112Z,1687969361.112 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2023-06-28T16:22:41.467Z,1687969361.467 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2023-06-28T16:22:41.467Z,1687969361.467 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2023-06-28T16:22:41.630Z,1687969361.630 [SBIT](DEBUG): Construct Startup Built In Test.
2023-06-28T16:22:41.639Z,1687969361.639 [SBIT] Loaded
2023-06-28T16:22:41.639Z,1687969361.639 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2023-06-28T16:22:41.642Z,1687969361.642 [IBIT](DEBUG): Construct Initiated Built In Test.
2023-06-28T16:22:41.655Z,1687969361.655 [IBIT] Loaded
2023-06-28T16:22:41.655Z,1687969361.655 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2023-06-28T16:22:41.661Z,1687969361.661 [CBIT](DEBUG): Construct Continuous Built In Test.
2023-06-28T16:22:41.766Z,1687969361.766 [CBIT] Loaded
2023-06-28T16:22:41.766Z,1687969361.766 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2023-06-28T16:22:41.766Z,1687969361.766 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2023-06-28T16:22:41.768Z,1687969361.768 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2023-06-28T16:22:41.887Z,1687969361.887 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2023-06-28T16:22:41.894Z,1687969361.894 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2023-06-28T16:22:41.896Z,1687969361.896 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2023-06-28T16:22:41.908Z,1687969361.908 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2023-06-28T16:22:41.909Z,1687969361.909 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B814E0
2023-06-28T16:22:41.909Z,1687969361.909 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 756
2023-06-28T16:22:41.915Z,1687969361.915 [Supervisor](INFO): Main Thread ID is 660
2023-06-28T16:22:41.915Z,1687969361.915 [Supervisor](DEBUG): Running supervisor.
2023-06-28T16:22:41.916Z,1687969361.916 [CommandExec ThreadHandler](INFO): Handler Thread ID is 757
2023-06-28T16:22:41.916Z,1687969361.916 [CommandExec](INFO): Initializing the command executive.
2023-06-28T16:22:41.918Z,1687969361.918 [CommandLine ThreadHandler](INFO): Handler Thread ID is 758
2023-06-28T16:22:41.921Z,1687969361.921 [controlThread ThreadHandler](INFO): Handler Thread ID is 759
2023-06-28T16:22:41.921Z,1687969361.921 [controlThread](DEBUG): Initializing ControlThread
2023-06-28T16:22:41.922Z,1687969361.922 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2023-06-28T16:22:41.924Z,1687969361.924 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2023-06-28T16:22:41.925Z,1687969361.925 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2023-06-28T16:22:41.925Z,1687969361.925 [LoopControl](DEBUG): Initialize LoopControlComponent.
2023-06-28T16:22:41.927Z,1687969361.927 [NavChart](DEBUG): Initialize NavChart Navigation.
2023-06-28T16:22:41.927Z,1687969361.927 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2023-06-28T16:22:41.933Z,1687969361.933 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2023-06-28T16:22:41.933Z,1687969361.933 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2023-06-28T16:22:41.933Z,1687969361.933 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2023-06-28T16:22:41.934Z,1687969361.934 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2023-06-28T16:22:41.934Z,1687969361.934 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2023-06-28T16:22:41.939Z,1687969361.939 [SBIT](INFO): Initialize SBIT Component.
2023-06-28T16:22:41.940Z,1687969361.940 [SBIT](IMPORTANT): git: 2023-03-30-8-g9f4e94ede
2023-06-28T16:22:41.940Z,1687969361.940 [SBIT](INFO): git hash: 9f4e94ede96dd26b46ac6117d56fbd84feccae72
2023-06-28T16:22:41.940Z,1687969361.940 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2023-06-28T16:22:41.941Z,1687969361.941 [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
2023-06-28T16:22:41.943Z,1687969361.943 [SBIT](INFO): Beginning SBIT in 67.000000 seconds.
2023-06-28T16:22:41.943Z,1687969361.943 [IBIT](INFO): Initialize IBIT Component.
2023-06-28T16:22:41.944Z,1687969361.944 [CBIT](DEBUG): Initialize CBIT Component.
2023-06-28T16:22:41.945Z,1687969361.945 [logger ThreadHandler](INFO): Handler Thread ID is 760
2023-06-28T16:22:41.955Z,1687969361.955 [CBIT](DEBUG): Initialized mux pins.
2023-06-28T16:22:41.955Z,1687969361.955 [CBIT](DEBUG): Initializing the watchdog timer.
2023-06-28T16:22:41.963Z,1687969361.963 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 762
2023-06-28T16:22:41.965Z,1687969361.965 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2023-06-28T16:22:41.975Z,1687969361.975 [Onboard ThreadHandler](INFO): Handler Thread ID is 763
2023-06-28T16:22:41.980Z,1687969361.980 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2023-06-28T16:22:41.980Z,1687969361.980 [CBIT](DEBUG): Initializing heartbeat.
2023-06-28T16:22:42.007Z,1687969362.007 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 764
2023-06-28T16:22:42.016Z,1687969362.016 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 765
2023-06-28T16:22:42.016Z,1687969362.016 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2023-06-28T16:22:42.022Z,1687969362.022 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 767
2023-06-28T16:22:42.024Z,1687969362.024 [WetLabsBB2FL](INFO): Powering up
2023-06-28T16:22:42.031Z,1687969362.031 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 769
2023-06-28T16:22:42.042Z,1687969362.042 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2023-06-28T16:22:42.042Z,1687969362.042 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2023-06-28T16:22:42.043Z,1687969362.043 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2023-06-28T16:22:42.043Z,1687969362.043 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2023-06-28T16:22:42.043Z,1687969362.043 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2023-06-28T16:22:42.043Z,1687969362.043 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2023-06-28T16:22:42.044Z,1687969362.044 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2023-06-28T16:22:42.044Z,1687969362.044 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2023-06-28T16:22:42.051Z,1687969362.051 [CBIT](DEBUG): Deactivating GF circuits.
2023-06-28T16:22:42.051Z,1687969362.051 [CBIT](DEBUG): Deactivating emergency mode.
2023-06-28T16:22:42.091Z,1687969362.091 [CBIT](DEBUG): Backplane powered.
2023-06-28T16:22:42.092Z,1687969362.092 [MissionManager](INFO): Loading Mission from file: Missions/Startup.xml
2023-06-28T16:22:42.141Z,1687969362.141 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2023-06-28T16:22:42.168Z,1687969362.168 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       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>


2023-06-28T16:22:42.169Z,1687969362.169 [MissionManager](INFO): Loading Mission from file: Missions/Default.xml
2023-06-28T16:22:42.277Z,1687969362.277 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2023-06-28T16:22:42.278Z,1687969362.278 [Default:A.Wait](DEBUG): Construct Wait.
2023-06-28T16:22:42.280Z,1687969362.280 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2023-06-28T16:22:42.330Z,1687969362.330 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2023-06-28T16:22:42.348Z,1687969362.348 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2023-06-28T16:22:42.354Z,1687969362.354 [Default:E.Execute](DEBUG): Construct Execute.
2023-06-28T16:22:42.373Z,1687969362.373 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <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>


2023-06-28T16:22:42.378Z,1687969362.378 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,DropWeight,NAL9602,Power24vConverter,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2023-06-28T16:22:42.400Z,1687969362.400 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2023-06-28T16:22:42.411Z,1687969362.411 [Radio_Surface](INFO): Powering up
2023-06-28T16:22:42.672Z,1687969362.672 [Power24vConverter](INFO): Powering up.
2023-06-28T16:22:42.672Z,1687969362.672 [CANONSampler](INFO): Powering down
2023-06-28T16:22:42.733Z,1687969362.733 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2023-06-28T16:22:42.739Z,1687969362.739 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2023-06-28T16:22:42.740Z,1687969362.740 [ElevatorServo](DEBUG): Initializing EZServoServo.
2023-06-28T16:22:42.748Z,1687969362.748 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2023-06-28T16:22:42.749Z,1687969362.749 [MassServo](DEBUG): Initializing EZServoServo.
2023-06-28T16:22:42.755Z,1687969362.755 [MassServo](DEBUG): Initializing MassServo.
2023-06-28T16:22:42.756Z,1687969362.756 [RudderServo](DEBUG): Initializing EZServoServo.
2023-06-28T16:22:42.763Z,1687969362.763 [RudderServo](DEBUG): Initializing RudderServo.
2023-06-28T16:22:42.764Z,1687969362.764 [ThrusterServo](DEBUG): Initializing EZServoServo.
2023-06-28T16:22:42.771Z,1687969362.771 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2023-06-28T16:22:42.988Z,1687969362.988 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2023-06-28T16:22:42.988Z,1687969362.988 [DropWeight] Hardware Fault, FailCount= 1
2023-06-28T16:22:42.988Z,1687969362.988 [DropWeight](ERROR): Hardware Fault
2023-06-28T16:22:43.001Z,1687969363.001 [CommandExec](FAULT): Scheduling is paused
2023-06-28T16:22:43.002Z,1687969363.002 [CBIT](INFO): Critical error at 20230628T162242
2023-06-28T16:22:43.002Z,1687969363.002 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2023-06-28T16:22:43.009Z,1687969363.009 [CBIT](ERROR): Hardware Fault in component: DropWeight
2023-06-28T16:22:43.009Z,1687969363.009 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2023-06-28T16:22:43.221Z,1687969363.221 [CBIT](INFO): Critical error at 20230628T162243
2023-06-28T16:22:44.635Z,1687969364.635 [WetLabsBB2FL](INFO): Powering down
2023-06-28T16:22:49.047Z,1687969369.047 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2023-06-28T16:22:55.081Z,1687969375.081 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004861
2023-06-28T16:23:10.324Z,1687969390.324 [NAL9602](INFO): Powering up NAL9602
2023-06-28T16:23:21.232Z,1687969401.232 [NAL9602](INFO): NAL9602 initialized
2023-06-28T16:23:49.537Z,1687969429.537 [SBIT](IMPORTANT): Beginning Startup BIT
2023-06-28T16:23:49.542Z,1687969429.542 [CBIT](IMPORTANT): Beginning ground fault scan
2023-06-28T16:24:00.448Z,1687969440.448 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-06-28T16:24:00.867Z,1687969440.867 [CBIT](IMPORTANT): Beginning ground fault scan
2023-06-28T16:24:11.904Z,1687969451.904 [CBIT](ERROR): Ground fault scan returned out of bounds value. Re-running scan.
2023-06-28T16:24:12.296Z,1687969452.296 [CBIT](IMPORTANT): Beginning ground fault scan
2023-06-28T16:24:23.603Z,1687969463.603 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.004655
CHAN A1 (24V): 0.000492
CHAN A2 (12V): -0.002718
CHAN A3 (5V): -0.001821
CHAN B0 (3.3V): -0.000086
CHAN B1 (3.15aV): -0.000345
CHAN B2 (3.15bV): -0.000252
CHAN B3 (GND): -0.000191
OPEN: -0.000579
Full Scale: +/- 1 mA
2023-06-28T16:24:43.394Z,1687969483.394 [SBIT](IMPORTANT): SBIT PASSED
2023-06-28T16:24:43.395Z,1687969483.395 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2023-06-28T16:24:43.396Z,1687969483.396 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool;

2023-06-28T16:24:43.396Z,1687969483.396 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=20 count;

2023-06-28T16:24:43.396Z,1687969483.396 [SBIT](IMPORTANT): CANONSampler.loadAtStartup=1 bool;

2023-06-28T16:24:43.396Z,1687969483.396 [SBIT](IMPORTANT): CBIT.runElevOffsetCalc=1 bool;

2023-06-28T16:24:43.396Z,1687969483.396 [SBIT](IMPORTANT): DAT.loadAtStartup=0 bool;

2023-06-28T16:24:43.396Z,1687969483.396 [SBIT](IMPORTANT): RudderServo.offsetAngle=0 degree;

2023-06-28T16:24:43.396Z,1687969483.396 [SBIT](IMPORTANT): Sonardyne_Nano.loadAtStartup=0 bool;

2023-06-28T16:24:43.397Z,1687969483.397 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=234.842285 cubic_centimeter;

2023-06-28T16:24:43.397Z,1687969483.397 [SBIT](IMPORTANT): VerticalControl.massDefault=-11.931131 millimeter;

2023-06-28T16:24:43.748Z,1687969483.748 [MissionManager](IMPORTANT): Started mission Startup
2023-06-28T16:24:43.748Z,1687969483.748 [Startup] Running Loop=1
2023-06-28T16:24:43.748Z,1687969483.748 [Startup](DEBUG): Aggregate::initialize Startup
2023-06-28T16:24:43.749Z,1687969483.749 [Startup:A.GoToSurface] Running Loop=1
2023-06-28T16:24:43.749Z,1687969483.749 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-06-28T16:24:43.749Z,1687969483.749 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-06-28T16:24:43.750Z,1687969483.750 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-06-28T16:24:43.750Z,1687969483.750 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-06-28T16:24:43.750Z,1687969483.750 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-06-28T16:24:43.751Z,1687969483.751 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-06-28T16:24:43.752Z,1687969483.752 [Startup:StartupSatComms] Running Loop=1
2023-06-28T16:24:43.752Z,1687969483.752 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2023-06-28T16:24:43.752Z,1687969483.752 [Startup:StartupSatComms:A] Running Loop=1
2023-06-28T16:24:44.137Z,1687969484.137 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2023-06-28T16:25:42.315Z,1687969542.315 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2023-06-28T16:25:42.315Z,1687969542.315 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-06-28T16:25:42.326Z,1687969542.326 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-06-28T16:25:42.737Z,1687969542.737 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-06-28T16:25:42.737Z,1687969542.737 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2023-06-28T16:25:43.939Z,1687969543.939 [Startup:StartupSatComms:A](INFO): Timed out from 2023-06-28T16:24:43.8Z
2023-06-28T16:25:43.939Z,1687969543.939 [Startup:StartupSatComms:A] Stopped
2023-06-28T16:25:43.939Z,1687969543.939 [Startup:StartupSatComms:B] Running Loop=1
2023-06-28T16:25:44.328Z,1687969544.328 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-06-28T16:25:50.282Z,1687969550.282 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230609T154002/Courier0111.lzma
2023-06-28T16:25:51.281Z,1687969551.281 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Courier0111.lzma.bak
2023-06-28T16:25:51.282Z,1687969551.282 [DataOverHttps](INFO): SBD MOMSN=18438694
2023-06-28T16:26:06.803Z,1687969566.803 [DataOverHttps](INFO): Sending 242 bytes from file Logs/20230628T162230/Courier0000.lzma
2023-06-28T16:26:07.805Z,1687969567.805 [DataOverHttps](INFO): Moved sent file to Logs/20230628T162230/Courier0000.lzma.bak
2023-06-28T16:26:07.805Z,1687969567.805 [DataOverHttps](INFO): SBD MOMSN=18438696
2023-06-28T16:26:23.244Z,1687969583.244 [DataOverHttps](INFO): Sending 243 bytes from file Logs/20230609T154002/Express0112.lzma
2023-06-28T16:26:24.245Z,1687969584.245 [DataOverHttps](INFO): Moved sent file to Logs/20230609T154002/Express0112.lzma.bak
2023-06-28T16:26:24.245Z,1687969584.245 [DataOverHttps](INFO): SBD MOMSN=18438701
2023-06-28T16:26:39.808Z,1687969599.808 [DataOverHttps](INFO): Sending 855 bytes from file Logs/20230628T162230/Express0001.lzma
2023-06-28T16:26:40.809Z,1687969600.809 [DataOverHttps](INFO): Moved sent file to Logs/20230628T162230/Express0001.lzma.bak
2023-06-28T16:26:40.809Z,1687969600.809 [DataOverHttps](INFO): SBD MOMSN=18438707
2023-06-28T16:26:42.161Z,1687969602.161 [Startup:StartupSatComms:B] Stopped
2023-06-28T16:26:42.162Z,1687969602.162 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2023-06-28T16:26:42.162Z,1687969602.162 [Startup:StartupSatComms] Stopped
2023-06-28T16:26:42.162Z,1687969602.162 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2023-06-28T16:26:42.162Z,1687969602.162 [Startup](INFO): Completed Startup
2023-06-28T16:26:42.163Z,1687969602.163 [MissionManager](INFO): Startup is completed.
2023-06-28T16:26:42.163Z,1687969602.163 [MissionManager](INFO): Uninitializing Mission Startup
2023-06-28T16:26:42.163Z,1687969602.163 [Startup] Stopped
2023-06-28T16:26:42.163Z,1687969602.163 [Startup](DEBUG): Aggregate::uninitialize Startup
2023-06-28T16:26:42.163Z,1687969602.163 [Startup:A.GoToSurface] Stopped
2023-06-28T16:26:42.163Z,1687969602.163 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-06-28T16:26:42.512Z,1687969602.512 [MissionManager](IMPORTANT): Started mission Default
2023-06-28T16:26:42.512Z,1687969602.512 [Default] Running Loop=1
2023-06-28T16:26:42.512Z,1687969602.512 [Default](DEBUG): Aggregate::initialize Default
2023-06-28T16:26:42.513Z,1687969602.513 [Default:B.GoToSurface] Running Loop=1
2023-06-28T16:26:42.513Z,1687969602.513 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2023-06-28T16:26:42.513Z,1687969602.513 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2023-06-28T16:26:42.513Z,1687969602.513 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2023-06-28T16:26:42.514Z,1687969602.514 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2023-06-28T16:26:42.514Z,1687969602.514 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2023-06-28T16:26:42.514Z,1687969602.514 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2023-06-28T16:26:42.514Z,1687969602.514 [Default:A.Wait] Running Loop=1
2023-06-28T16:26:42.515Z,1687969602.515 [Default:A.Wait](DEBUG): Initialize Wait Component.
2023-06-28T16:26:55.837Z,1687969615.837 [Default:A.Wait](INFO): Done Waiting.
2023-06-28T16:26:55.837Z,1687969615.837 [Default:A.Wait] Stopped
2023-06-28T16:26:55.837Z,1687969615.837 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2023-06-28T16:26:56.252Z,1687969616.252 [Default:CheckIn] Running Loop=1
2023-06-28T16:26:56.253Z,1687969616.253 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-06-28T16:26:56.253Z,1687969616.253 [Default:CheckIn:Read_GPS] Running Loop=1
2023-06-28T16:26:56.665Z,1687969616.665 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2023-06-28T16:27:37.431Z,1687969657.431 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-28T16:27:43.896Z,1687969663.896 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-28T16:27:59.247Z,1687969679.247 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-28T16:28:14.599Z,1687969694.599 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-28T16:28:24.722Z,1687969704.722 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2023-06-28T16:28:29.960Z,1687969709.960 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-28T16:28:43.335Z,1687969723.335 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2023-06-28T16:28:43.335Z,1687969723.335 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-06-28T16:28:43.366Z,1687969723.366 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-06-28T16:28:43.732Z,1687969723.732 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-06-28T16:28:43.732Z,1687969723.732 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2023-06-28T16:28:45.716Z,1687969725.716 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-28T16:29:00.664Z,1687969740.664 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-28T16:29:15.612Z,1687969755.612 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-28T16:29:30.964Z,1687969770.964 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-28T16:29:45.911Z,1687969785.911 [BPC1](ERROR): BPC1A got IPBS message with 6 sticks (min is 7).
2023-06-28T16:30:01.675Z,1687969801.675 [BPC1](INFO): Calculating totals. Valid battery stick count: 40. Valid reserve battery stick count: 5.
2023-06-28T16:30:01.678Z,1687969801.678 [BPC1](INFO): Received data from all battery sticks.
2023-06-28T16:31:44.314Z,1687969904.314 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2023-06-28T16:31:44.314Z,1687969904.314 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-06-28T16:31:44.325Z,1687969904.325 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-06-28T16:31:44.738Z,1687969904.738 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-06-28T16:31:44.738Z,1687969904.738 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2023-06-28T16:31:56.445Z,1687969916.445 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-06-28T16:26:56.3Z
2023-06-28T16:31:56.445Z,1687969916.445 [Default:CheckIn:Read_GPS] Stopped
2023-06-28T16:31:56.445Z,1687969916.445 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-06-28T16:31:56.835Z,1687969916.835 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2023-06-28T16:32:03.419Z,1687969923.419 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20230628T162230/Courier0004.lzma
2023-06-28T16:32:04.423Z,1687969924.423 [DataOverHttps](INFO): Moved sent file to Logs/20230628T162230/Courier0004.lzma.bak
2023-06-28T16:32:04.423Z,1687969924.423 [DataOverHttps](INFO): SBD MOMSN=18438774
2023-06-28T16:32:19.843Z,1687969939.843 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20230628T162230/Express0005.lzma
2023-06-28T16:32:20.845Z,1687969940.845 [DataOverHttps](INFO): Moved sent file to Logs/20230628T162230/Express0005.lzma.bak
2023-06-28T16:32:20.845Z,1687969940.845 [DataOverHttps](INFO): SBD MOMSN=18438778
2023-06-28T16:32:22.281Z,1687969942.281 [Default:CheckIn:Read_Iridium] Stopped
2023-06-28T16:32:22.281Z,1687969942.281 [Default:CheckIn:C.Wait] Running Loop=1
2023-06-28T16:32:22.282Z,1687969942.282 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-06-28T16:32:43.695Z,1687969963.695 [Power24vConverter](INFO): Powering down.
2023-06-28T16:33:22.057Z,1687970002.057 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-06-28T16:33:22.057Z,1687970002.057 [NAL9602] Data Fault, FailCount= 1
2023-06-28T16:33:22.057Z,1687970002.057 [NAL9602](ERROR): Data Fault
2023-06-28T16:33:22.074Z,1687970002.074 [CBIT](ERROR): Data Fault in component: NAL9602
2023-06-28T16:33:22.460Z,1687970002.460 [NAL9602](INFO): Powering down
2023-06-28T16:33:23.307Z,1687970003.307 [CBIT](INFO): Clearing failed state for component NAL9602
2023-06-28T16:33:23.307Z,1687970003.307 [NAL9602] No Fault, FailCount= 1
2023-06-28T16:33:52.764Z,1687970032.764 [NAL9602](INFO): Powering up NAL9602
2023-06-28T16:34:03.668Z,1687970043.668 [NAL9602](INFO): NAL9602 initialized
2023-06-28T16:34:45.310Z,1687970085.310 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2023-06-28T16:34:45.310Z,1687970085.310 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-06-28T16:34:45.321Z,1687970085.321 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-06-28T16:34:45.740Z,1687970085.740 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-06-28T16:34:45.740Z,1687970085.740 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2023-06-28T16:37:22.851Z,1687970242.851 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-06-28T16:37:22.852Z,1687970242.852 [Default:CheckIn:C.Wait] Stopped
2023-06-28T16:37:22.852Z,1687970242.852 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-06-28T16:37:22.852Z,1687970242.852 [Default:CheckIn:D] Running Loop=1
2023-06-28T16:37:23.252Z,1687970243.252 [Default:CheckIn:D] Stopped
2023-06-28T16:37:23.252Z,1687970243.252 [Default:CheckIn:E] Running Loop=1
2023-06-28T16:37:23.664Z,1687970243.664 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.678998 min 
2023-06-28T16:37:23.664Z,1687970243.664 [Default:CheckIn:E] Stopped
2023-06-28T16:37:23.664Z,1687970243.664 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-06-28T16:37:23.665Z,1687970243.665 [Default:CheckIn] Stopped
2023-06-28T16:37:23.665Z,1687970243.665 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-06-28T16:37:23.665Z,1687970243.665 [Default:CheckIn](INFO): Running loop #2
2023-06-28T16:37:23.665Z,1687970243.665 [Default:CheckIn] Running Loop=2
2023-06-28T16:37:23.665Z,1687970243.665 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-06-28T16:37:23.665Z,1687970243.665 [Default:CheckIn:Read_GPS] Running Loop=1
2023-06-28T16:37:43.080Z,1687970263.080 [CBIT](INFO): Clearing failed state for component DropWeight
2023-06-28T16:37:43.080Z,1687970263.080 [DropWeight] No Fault, FailCount= 1
2023-06-28T16:37:46.315Z,1687970266.315 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2023-06-28T16:37:46.316Z,1687970266.316 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-06-28T16:37:46.326Z,1687970266.326 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-06-28T16:37:46.732Z,1687970266.732 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-06-28T16:37:46.732Z,1687970266.732 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2023-06-28T16:40:47.364Z,1687970447.364 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2023-06-28T16:40:47.364Z,1687970447.364 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-06-28T16:40:47.400Z,1687970447.400 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-06-28T16:40:47.774Z,1687970447.774 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-06-28T16:40:47.774Z,1687970447.774 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2023-06-28T16:42:23.909Z,1687970543.909 [Default:CheckIn:Read_GPS](INFO): Timed out from 2023-06-28T16:37:23.7Z
2023-06-28T16:42:23.909Z,1687970543.909 [Default:CheckIn:Read_GPS] Stopped
2023-06-28T16:42:23.909Z,1687970543.909 [Default:CheckIn:Read_Iridium] Running Loop=1
2023-06-28T16:42:30.920Z,1687970550.920 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20230628T162230/Courier0007.lzma
2023-06-28T16:42:31.921Z,1687970551.921 [DataOverHttps](INFO): Moved sent file to Logs/20230628T162230/Courier0007.lzma.bak
2023-06-28T16:42:31.921Z,1687970551.921 [DataOverHttps](INFO): SBD MOMSN=18439146
2023-06-28T16:42:46.239Z,1687970566.239 [DataOverHttps](INFO): Sending 181 bytes from file Logs/20230628T162230/Express0008.lzma
2023-06-28T16:42:47.241Z,1687970567.241 [DataOverHttps](INFO): Moved sent file to Logs/20230628T162230/Express0008.lzma.bak
2023-06-28T16:42:47.241Z,1687970567.241 [DataOverHttps](INFO): SBD MOMSN=18439148
2023-06-28T16:42:48.564Z,1687970568.564 [Default:CheckIn:Read_Iridium] Stopped
2023-06-28T16:42:48.564Z,1687970568.564 [Default:CheckIn:C.Wait] Running Loop=1
2023-06-28T16:42:48.565Z,1687970568.565 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2023-06-28T16:43:48.357Z,1687970628.357 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2023-06-28T16:43:48.357Z,1687970628.357 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-06-28T16:43:48.367Z,1687970628.367 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-06-28T16:43:48.757Z,1687970628.757 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-06-28T16:43:48.757Z,1687970628.757 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2023-06-28T16:44:04.497Z,1687970644.497 [NAL9602](FAULT): GPS failed to acquire within timeout.
2023-06-28T16:44:04.497Z,1687970644.497 [NAL9602] Data Fault, FailCount= 2
2023-06-28T16:44:04.497Z,1687970644.497 [NAL9602](ERROR): Data Fault
2023-06-28T16:44:04.514Z,1687970644.514 [CBIT](ERROR): Data Fault in component: NAL9602
2023-06-28T16:44:04.897Z,1687970644.897 [NAL9602](INFO): Powering down
2023-06-28T16:44:05.739Z,1687970645.739 [CBIT](INFO): Clearing failed state for component NAL9602
2023-06-28T16:44:05.739Z,1687970645.739 [NAL9602] No Fault, FailCount= 2
2023-06-28T16:44:35.201Z,1687970675.201 [NAL9602](INFO): Powering up NAL9602
2023-06-28T16:44:46.105Z,1687970686.105 [NAL9602](INFO): NAL9602 initialized
2023-06-28T16:46:49.343Z,1687970809.343 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2023-06-28T16:46:49.343Z,1687970809.343 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-06-28T16:46:49.354Z,1687970809.354 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-06-28T16:46:49.777Z,1687970809.777 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-06-28T16:46:49.777Z,1687970809.777 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2023-06-28T16:47:49.150Z,1687970869.150 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2023-06-28T16:47:49.150Z,1687970869.150 [Default:CheckIn:C.Wait] Stopped
2023-06-28T16:47:49.150Z,1687970869.150 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2023-06-28T16:47:49.150Z,1687970869.150 [Default:CheckIn:D] Running Loop=1
2023-06-28T16:47:49.557Z,1687970869.557 [Default:CheckIn:D] Stopped
2023-06-28T16:47:49.557Z,1687970869.557 [Default:CheckIn:E] Running Loop=1
2023-06-28T16:47:49.937Z,1687970869.937 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 21.117401 min 
2023-06-28T16:47:49.937Z,1687970869.937 [Default:CheckIn:E] Stopped
2023-06-28T16:47:49.937Z,1687970869.937 [Default:CheckIn](INFO): Completed Default:CheckIn
2023-06-28T16:47:49.937Z,1687970869.937 [Default:CheckIn] Stopped
2023-06-28T16:47:49.937Z,1687970869.937 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-06-28T16:47:49.938Z,1687970869.938 [Default:CheckIn](INFO): Running loop #3
2023-06-28T16:47:49.938Z,1687970869.938 [Default:CheckIn] Running Loop=3
2023-06-28T16:47:49.938Z,1687970869.938 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2023-06-28T16:47:49.938Z,1687970869.938 [Default:CheckIn:Read_GPS] Running Loop=1
2023-06-28T16:49:50.361Z,1687970990.361 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2023-06-28T16:49:50.361Z,1687970990.361 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2023-06-28T16:49:50.372Z,1687970990.372 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2023-06-28T16:49:50.766Z,1687970990.766 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2023-06-28T16:49:50.766Z,1687970990.766 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2023-06-28T16:51:21.684Z,1687971081.684 [CommandExec](IMPORTANT): got command quit
2023-06-28T16:51:22.687Z,1687971082.687 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2023-06-28T16:51:22.687Z,1687971082.687 [CommandExec](INFO): Uninitializing the command executive.
2023-06-28T16:51:22.687Z,1687971082.687 [CommandExec](INFO): Uninitializing the command scheduler.
2023-06-28T16:51:22.688Z,1687971082.688 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-06-28T16:51:22.775Z,1687971082.775 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2023-06-28T16:51:22.775Z,1687971082.775 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2023-06-28T16:51:22.775Z,1687971082.775 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-06-28T16:51:22.776Z,1687971082.776 [NavChartDb](INFO): Join timeout helper Thread ID is 811
2023-06-28T16:51:23.015Z,1687971083.015 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2023-06-28T16:51:23.015Z,1687971083.015 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-06-28T16:51:23.032Z,1687971083.032 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2023-06-28T16:51:23.032Z,1687971083.032 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-06-28T16:51:23.033Z,1687971083.033 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 812
2023-06-28T16:51:23.443Z,1687971083.443 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2023-06-28T16:51:23.444Z,1687971083.444 [WetLabsBB2FL](INFO): Powering down
2023-06-28T16:51:23.445Z,1687971083.445 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-06-28T16:51:23.448Z,1687971083.448 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2023-06-28T16:51:23.449Z,1687971083.449 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-06-28T16:51:23.450Z,1687971083.450 [CTD_Seabird](INFO): Join timeout helper Thread ID is 813
2023-06-28T16:51:24.251Z,1687971084.251 [CTD_Seabird](INFO): Powering down
2023-06-28T16:51:24.263Z,1687971084.263 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2023-06-28T16:51:24.263Z,1687971084.263 [CTD_Seabird](INFO): Powering down
2023-06-28T16:51:24.284Z,1687971084.284 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-06-28T16:51:24.291Z,1687971084.291 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2023-06-28T16:51:24.291Z,1687971084.291 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-06-28T16:51:24.291Z,1687971084.291 [Radio_Surface](INFO): Join timeout helper Thread ID is 814
2023-06-28T16:51:24.347Z,1687971084.347 [Radio_Surface](INFO): Powering down
2023-06-28T16:51:24.348Z,1687971084.348 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2023-06-28T16:51:24.348Z,1687971084.348 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-06-28T16:51:24.367Z,1687971084.367 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2023-06-28T16:51:24.367Z,1687971084.367 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-06-28T16:51:24.367Z,1687971084.367 [Onboard](INFO): Join timeout helper Thread ID is 816
2023-06-28T16:51:28.435Z,1687971088.435 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2023-06-28T16:51:28.435Z,1687971088.435 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-06-28T16:51:28.456Z,1687971088.456 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2023-06-28T16:51:28.456Z,1687971088.456 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-06-28T16:51:28.456Z,1687971088.456 [DataOverHttps](INFO): Join timeout helper Thread ID is 817
2023-06-28T16:51:28.523Z,1687971088.523 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2023-06-28T16:51:28.527Z,1687971088.527 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-06-28T16:51:28.540Z,1687971088.540 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2023-06-28T16:51:28.540Z,1687971088.540 [logger ThreadHandler](INFO): Thread cancelled.
2023-06-28T16:51:28.540Z,1687971088.540 [logger](INFO): Join timeout helper Thread ID is 818
2023-06-28T16:51:28.543Z,1687971088.543 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2023-06-28T16:51:28.544Z,1687971088.544 [logger ThreadHandler](INFO): Thread cancelled.
2023-06-28T16:51:28.546Z,1687971088.546 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2023-06-28T16:51:28.546Z,1687971088.546 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-06-28T16:51:28.547Z,1687971088.547 [CommandLine](INFO): Join timeout helper Thread ID is 819
2023-06-28T16:51:28.607Z,1687971088.607 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2023-06-28T16:51:28.608Z,1687971088.608 [CommandLine ThreadHandler](INFO): Thread cancelled.
2023-06-28T16:51:28.628Z,1687971088.628 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2023-06-28T16:51:28.628Z,1687971088.628 [CommandExec ThreadHandler](INFO): Thread cancelled.
2023-06-28T16:51:28.628Z,1687971088.628 [CommandExec](INFO): Join timeout helper Thread ID is 820
2023-06-28T16:51:28.629Z,1687971088.629 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2023-06-28T16:51:28.629Z,1687971088.629 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-06-28T16:51:28.630Z,1687971088.630 [controlThread](INFO): Join timeout helper Thread ID is 822
2023-06-28T16:51:28.907Z,1687971088.907 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2023-06-28T16:51:28.907Z,1687971088.907 [controlThread](DEBUG): Uninitializing ControlThread
2023-06-28T16:51:28.908Z,1687971088.908 [AHRS_M2](INFO): Powering down
2023-06-28T16:51:28.980Z,1687971088.980 [NAL9602](INFO): Powering down
2023-06-28T16:51:28.982Z,1687971088.982 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2023-06-28T16:51:28.983Z,1687971088.983 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2023-06-28T16:51:28.984Z,1687971088.984 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2023-06-28T16:51:28.984Z,1687971088.984 [MissionManager](INFO): Uninitializing Mission Default
2023-06-28T16:51:28.984Z,1687971088.984 [Default] Stopped
2023-06-28T16:51:28.984Z,1687971088.984 [Default](DEBUG): Aggregate::uninitialize Default
2023-06-28T16:51:28.985Z,1687971088.985 [Default:B.GoToSurface] Stopped
2023-06-28T16:51:28.985Z,1687971088.985 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2023-06-28T16:51:28.985Z,1687971088.985 [Default:CheckIn] Stopped
2023-06-28T16:51:28.985Z,1687971088.985 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2023-06-28T16:51:28.985Z,1687971088.985 [Default:CheckIn:Read_GPS] Stopped
2023-06-28T16:51:28.988Z,1687971088.988 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2023-06-28T16:51:28.988Z,1687971088.988 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2023-06-28T16:51:28.988Z,1687971088.988 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2023-06-28T16:51:28.989Z,1687971088.989 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2023-06-28T16:51:28.989Z,1687971088.989 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2023-06-28T16:51:28.989Z,1687971088.989 [BuoyancyServo](INFO): Powering down
2023-06-28T16:51:29.004Z,1687971089.004 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2023-06-28T16:51:29.006Z,1687971089.006 [ElevatorServo](INFO): Powering down
2023-06-28T16:51:29.008Z,1687971089.008 [MassServo](DEBUG): Uninitialize Mass Servo.
2023-06-28T16:51:29.008Z,1687971089.008 [MassServo](INFO): Powering down
2023-06-28T16:51:29.008Z,1687971089.008 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2023-06-28T16:51:29.009Z,1687971089.009 [RudderServo](INFO): Powering down
2023-06-28T16:51:29.009Z,1687971089.009 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2023-06-28T16:51:29.009Z,1687971089.009 [ThrusterServo](INFO): Powering down
2023-06-28T16:51:29.010Z,1687971089.010 [SBIT](DEBUG): Uninitialize SBIT Component.
2023-06-28T16:51:29.010Z,1687971089.010 [IBIT](DEBUG): Uninitialize IBIT Component.
2023-06-28T16:51:29.011Z,1687971089.011 [CBIT](DEBUG): Uninitialize CBIT Component.
2023-06-28T16:51:29.011Z,1687971089.011 [CBIT](DEBUG): Powering off loads.
2023-06-28T16:51:29.023Z,1687971089.023 [CBIT](DEBUG): Disabling WDT.
2023-06-28T16:51:29.035Z,1687971089.035 [CBIT](DEBUG): Opening all GF detection circuits.
2023-06-28T16:51:29.036Z,1687971089.036 [controlThread ThreadHandler](INFO): Thread cancelled.
2023-06-28T16:51:29.085Z,1687971089.085 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2023-06-28T16:51:29.088Z,1687971089.088 [Onboard ThreadHandler](INFO): Thread cancelled.
2023-06-28T16:51:29.094Z,1687971089.094 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2023-06-28T16:51:29.166Z,1687971089.166 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2023-06-28T16:51:29.169Z,1687971089.169 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2023-06-28T16:51:29.210Z,1687971089.210 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2023-06-28T16:51:29.270Z,1687971089.270 [logger ThreadHandler](INFO): Thread cancelled.