2021-06-01T14:38:19.832Z,1622558299.832 [Supervisor](DEBUG): Initializing supervisor.
2021-06-01T14:38:19.837Z,1622558299.837 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2021-06-01T14:38:19.838Z,1622558299.838 [SyncHandler](INFO): Protected caller Thread ID is 6953
2021-06-01T14:38:19.838Z,1622558299.838 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2021-06-01T14:38:19.839Z,1622558299.839 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2021-06-01T14:38:19.840Z,1622558299.840 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6954
2021-06-01T14:38:19.844Z,1622558299.844 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2021-06-01T14:38:19.865Z,1622558299.865 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2021-06-01T14:38:19.866Z,1622558299.866 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2021-06-01T14:38:19.866Z,1622558299.866 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6955
2021-06-01T14:38:19.868Z,1622558299.868 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2021-06-01T14:38:19.869Z,1622558299.869 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2021-06-01T14:38:19.870Z,1622558299.870 [logger ThreadHandler](INFO): Protected caller Thread ID is 6956
2021-06-01T14:38:19.874Z,1622558299.874 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2021-06-01T14:38:19.874Z,1622558299.874 [Supervisor](INFO): Looking for Config files in directory: Config/
2021-06-01T14:38:19.876Z,1622558299.876 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2021-06-01T14:38:20.104Z,1622558300.104 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2021-06-01T14:38:20.105Z,1622558300.105 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2021-06-01T14:38:20.183Z,1622558300.183 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2021-06-01T14:38:20.692Z,1622558300.692 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2021-06-01T14:38:20.693Z,1622558300.693 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2021-06-01T14:38:21.025Z,1622558301.025 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2021-06-01T14:38:21.025Z,1622558301.025 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2021-06-01T14:38:21.121Z,1622558301.121 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2021-06-01T14:38:21.121Z,1622558301.121 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2021-06-01T14:38:21.494Z,1622558301.494 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2021-06-01T14:38:21.495Z,1622558301.495 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2021-06-01T14:38:21.696Z,1622558301.696 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2021-06-01T14:38:21.696Z,1622558301.696 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2021-06-01T14:38:22.165Z,1622558302.165 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2021-06-01T14:38:22.166Z,1622558302.166 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2021-06-01T14:38:22.292Z,1622558302.292 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2021-06-01T14:38:22.292Z,1622558302.292 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2021-06-01T14:38:22.371Z,1622558302.371 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2021-06-01T14:38:23.062Z,1622558303.062 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2021-06-01T14:38:23.063Z,1622558303.063 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2021-06-01T14:38:23.673Z,1622558303.673 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2021-06-01T14:38:23.674Z,1622558303.674 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2021-06-01T14:38:23.867Z,1622558303.867 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2021-06-01T14:38:23.868Z,1622558303.868 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2021-06-01T14:38:23.969Z,1622558303.969 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2021-06-01T14:38:23.970Z,1622558303.970 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2021-06-01T14:38:24.121Z,1622558304.121 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2021-06-01T14:38:24.121Z,1622558304.121 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2021-06-01T14:38:24.429Z,1622558304.429 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2021-06-01T14:38:24.431Z,1622558304.431 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2021-06-01T14:38:24.432Z,1622558304.432 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2021-06-01T14:38:24.522Z,1622558304.522 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2021-06-01T14:38:24.607Z,1622558304.607 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2021-06-01T14:38:24.707Z,1622558304.707 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2021-06-01T14:38:24.789Z,1622558304.789 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2021-06-01T14:38:24.884Z,1622558304.884 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2021-06-01T14:38:24.985Z,1622558304.985 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2021-06-01T14:38:25.172Z,1622558305.172 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2021-06-01T14:38:25.247Z,1622558305.247 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2021-06-01T14:38:25.395Z,1622558305.395 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2021-06-01T14:38:25.533Z,1622558305.533 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2021-06-01T14:38:25.652Z,1622558305.652 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2021-06-01T14:38:25.919Z,1622558305.919 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2021-06-01T14:38:25.921Z,1622558305.921 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2021-06-01T14:38:25.921Z,1622558305.921 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2021-06-01T14:38:25.927Z,1622558305.927 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2021-06-01T14:38:25.986Z,1622558305.986 [VerticalControl](DEBUG): Construct VerticalControl.
2021-06-01T14:38:26.049Z,1622558306.049 [VerticalControl] Loaded
2021-06-01T14:38:26.050Z,1622558306.050 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2021-06-01T14:38:26.052Z,1622558306.052 [HorizontalControl](DEBUG): Construct HorizontalControl.
2021-06-01T14:38:26.093Z,1622558306.093 [HorizontalControl] Loaded
2021-06-01T14:38:26.093Z,1622558306.093 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2021-06-01T14:38:26.095Z,1622558306.095 [SpeedControl](DEBUG): Construct SpeedControl.
2021-06-01T14:38:26.099Z,1622558306.099 [SpeedControl] Loaded
2021-06-01T14:38:26.099Z,1622558306.099 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2021-06-01T14:38:26.101Z,1622558306.101 [LoopControl](DEBUG): Construct LoopControl.
2021-06-01T14:38:26.102Z,1622558306.102 [LoopControl] Loaded
2021-06-01T14:38:26.102Z,1622558306.102 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2021-06-01T14:38:26.103Z,1622558306.103 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2021-06-01T14:38:26.103Z,1622558306.103 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2021-06-01T14:38:26.115Z,1622558306.115 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2021-06-01T14:38:26.115Z,1622558306.115 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2021-06-01T14:38:26.230Z,1622558306.230 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2021-06-01T14:38:26.230Z,1622558306.230 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2021-06-01T14:38:26.364Z,1622558306.364 [BuoyancyServo] Loaded
2021-06-01T14:38:26.364Z,1622558306.364 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2021-06-01T14:38:26.384Z,1622558306.384 [ElevatorServo] Loaded
2021-06-01T14:38:26.384Z,1622558306.384 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2021-06-01T14:38:26.403Z,1622558306.403 [MassServo] Loaded
2021-06-01T14:38:26.403Z,1622558306.403 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2021-06-01T14:38:26.421Z,1622558306.421 [RudderServo] Loaded
2021-06-01T14:38:26.422Z,1622558306.422 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2021-06-01T14:38:26.440Z,1622558306.440 [ThrusterServo] Loaded
2021-06-01T14:38:26.440Z,1622558306.440 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2021-06-01T14:38:26.441Z,1622558306.441 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2021-06-01T14:38:26.441Z,1622558306.441 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2021-06-01T14:38:26.527Z,1622558306.527 [DeadReckonUsingMultipleVelocitySources] Loaded
2021-06-01T14:38:26.527Z,1622558306.527 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2021-06-01T14:38:26.541Z,1622558306.541 [NavChart] Loaded
2021-06-01T14:38:26.541Z,1622558306.541 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2021-06-01T14:38:26.547Z,1622558306.547 [UniversalFixResidualReporter] Loaded
2021-06-01T14:38:26.547Z,1622558306.547 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2021-06-01T14:38:26.548Z,1622558306.548 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2021-06-01T14:38:26.548Z,1622558306.548 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2021-06-01T14:38:26.610Z,1622558306.610 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2021-06-01T14:38:26.610Z,1622558306.610 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2021-06-01T14:38:27.018Z,1622558307.018 [AHRS_M2] Loaded
2021-06-01T14:38:27.019Z,1622558307.019 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2021-06-01T14:38:27.848Z,1622558307.848 [BPC1] Loaded
2021-06-01T14:38:27.849Z,1622558307.849 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2021-06-01T14:38:27.922Z,1622558307.922 [DataOverHttps] Loaded
2021-06-01T14:38:27.922Z,1622558307.922 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2021-06-01T14:38:27.923Z,1622558307.923 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408844E0
2021-06-01T14:38:27.924Z,1622558307.924 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7042
2021-06-01T14:38:27.944Z,1622558307.944 [Depth_Keller] Loaded
2021-06-01T14:38:27.944Z,1622558307.944 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2021-06-01T14:38:28.005Z,1622558308.005 [NAL9602] Loaded
2021-06-01T14:38:28.005Z,1622558308.005 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2021-06-01T14:38:28.035Z,1622558308.035 [Onboard] Loaded
2021-06-01T14:38:28.035Z,1622558308.035 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2021-06-01T14:38:28.036Z,1622558308.036 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408B44E0
2021-06-01T14:38:28.037Z,1622558308.037 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 7043
2021-06-01T14:38:28.050Z,1622558308.050 [Power24vConverter] Loaded
2021-06-01T14:38:28.050Z,1622558308.050 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2021-06-01T14:38:28.063Z,1622558308.063 [Radio_Surface] Loaded
2021-06-01T14:38:28.064Z,1622558308.064 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2021-06-01T14:38:28.065Z,1622558308.065 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408E44E0
2021-06-01T14:38:28.065Z,1622558308.065 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7044
2021-06-01T14:38:28.086Z,1622558308.086 [AMEcho] Loaded
2021-06-01T14:38:28.086Z,1622558308.086 [ComponentRegistry](DEBUG): SyncComponent "AMEcho" handled in the control thread.
2021-06-01T14:38:28.169Z,1622558308.169 [DAT] Loaded
2021-06-01T14:38:28.170Z,1622558308.170 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2021-06-01T14:38:28.170Z,1622558308.170 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2021-06-01T14:38:28.171Z,1622558308.171 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2021-06-01T14:38:28.258Z,1622558308.258 [DepthRateCalculator] Loaded
2021-06-01T14:38:28.259Z,1622558308.259 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2021-06-01T14:38:28.263Z,1622558308.263 [PitchRateCalculator] Loaded
2021-06-01T14:38:28.264Z,1622558308.264 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2021-06-01T14:38:28.273Z,1622558308.273 [SpeedCalculator] Loaded
2021-06-01T14:38:28.273Z,1622558308.273 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2021-06-01T14:38:28.319Z,1622558308.319 [TempGradientCalculator] Loaded
2021-06-01T14:38:28.320Z,1622558308.320 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2021-06-01T14:38:28.344Z,1622558308.344 [YawRateCalculator] Loaded
2021-06-01T14:38:28.345Z,1622558308.345 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2021-06-01T14:38:28.379Z,1622558308.379 [ElevatorOffsetCalculator] Loaded
2021-06-01T14:38:28.379Z,1622558308.379 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2021-06-01T14:38:28.379Z,1622558308.379 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2021-06-01T14:38:28.380Z,1622558308.380 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2021-06-01T14:38:28.895Z,1622558308.895 [CTD_Seabird] Loaded
2021-06-01T14:38:28.896Z,1622558308.896 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2021-06-01T14:38:28.898Z,1622558308.898 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409974E0
2021-06-01T14:38:28.898Z,1622558308.898 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7045
2021-06-01T14:38:28.921Z,1622558308.921 [PAR_Licor] Loaded
2021-06-01T14:38:28.921Z,1622558308.921 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2021-06-01T14:38:28.954Z,1622558308.954 [WetLabsBB2FL] Loaded
2021-06-01T14:38:28.955Z,1622558308.955 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2021-06-01T14:38:28.956Z,1622558308.956 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409C74E0
2021-06-01T14:38:28.956Z,1622558308.956 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 7046
2021-06-01T14:38:28.957Z,1622558308.957 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2021-06-01T14:38:28.957Z,1622558308.957 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2021-06-01T14:38:28.988Z,1622558308.988 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2021-06-01T14:38:28.989Z,1622558308.989 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2021-06-01T14:38:29.345Z,1622558309.345 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2021-06-01T14:38:29.346Z,1622558309.346 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2021-06-01T14:38:29.511Z,1622558309.511 [SBIT](DEBUG): Construct Startup Built In Test.
2021-06-01T14:38:29.520Z,1622558309.520 [SBIT] Loaded
2021-06-01T14:38:29.520Z,1622558309.520 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2021-06-01T14:38:29.523Z,1622558309.523 [IBIT](DEBUG): Construct Initiated Built In Test.
2021-06-01T14:38:29.536Z,1622558309.536 [IBIT] Loaded
2021-06-01T14:38:29.537Z,1622558309.537 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2021-06-01T14:38:29.543Z,1622558309.543 [CBIT](DEBUG): Construct Continuous Built In Test.
2021-06-01T14:38:29.647Z,1622558309.647 [CBIT] Loaded
2021-06-01T14:38:29.647Z,1622558309.647 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2021-06-01T14:38:29.648Z,1622558309.648 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2021-06-01T14:38:29.648Z,1622558309.648 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2021-06-01T14:38:29.755Z,1622558309.755 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2021-06-01T14:38:29.761Z,1622558309.761 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2021-06-01T14:38:29.764Z,1622558309.764 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2021-06-01T14:38:29.775Z,1622558309.775 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2021-06-01T14:38:29.776Z,1622558309.776 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AF94E0
2021-06-01T14:38:29.776Z,1622558309.776 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7047
2021-06-01T14:38:29.781Z,1622558309.781 [Supervisor](INFO): Main Thread ID is 6952
2021-06-01T14:38:29.781Z,1622558309.781 [Supervisor](DEBUG): Running supervisor.
2021-06-01T14:38:29.782Z,1622558309.782 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7048
2021-06-01T14:38:29.784Z,1622558309.784 [controlThread ThreadHandler](INFO): Handler Thread ID is 7049
2021-06-01T14:38:29.785Z,1622558309.785 [controlThread](DEBUG): Initializing ControlThread
2021-06-01T14:38:29.786Z,1622558309.786 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2021-06-01T14:38:29.788Z,1622558309.788 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2021-06-01T14:38:29.789Z,1622558309.789 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2021-06-01T14:38:29.789Z,1622558309.789 [LoopControl](DEBUG): Initialize LoopControlComponent.
2021-06-01T14:38:29.791Z,1622558309.791 [NavChart](DEBUG): Initialize NavChart Navigation.
2021-06-01T14:38:29.792Z,1622558309.792 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2021-06-01T14:38:29.798Z,1622558309.798 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2021-06-01T14:38:29.798Z,1622558309.798 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2021-06-01T14:38:29.798Z,1622558309.798 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2021-06-01T14:38:29.799Z,1622558309.799 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2021-06-01T14:38:29.799Z,1622558309.799 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2021-06-01T14:38:29.800Z,1622558309.800 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2021-06-01T14:38:29.805Z,1622558309.805 [SBIT](INFO): Initialize SBIT Component.
2021-06-01T14:38:29.805Z,1622558309.805 [SBIT](IMPORTANT): git: 2021-05-27-49-gcf38c2cb2
2021-06-01T14:38:29.805Z,1622558309.805 [SBIT](INFO): git hash: cf38c2cb22e5f1a07f3566d8bf81cf524e6bff3d
2021-06-01T14:38:29.806Z,1622558309.806 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2021-06-01T14:38:29.807Z,1622558309.807 [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 Nov 13 12:38:10 PST 2019
2021-06-01T14:38:29.809Z,1622558309.809 [logger ThreadHandler](INFO): Handler Thread ID is 7050
2021-06-01T14:38:29.824Z,1622558309.824 [SBIT](INFO): Beginning SBIT in 57.000000 seconds.
2021-06-01T14:38:29.825Z,1622558309.825 [IBIT](INFO): Initialize IBIT Component.
2021-06-01T14:38:29.826Z,1622558309.826 [CBIT](DEBUG): Initialize CBIT Component.
2021-06-01T14:38:29.833Z,1622558309.833 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7051
2021-06-01T14:38:29.834Z,1622558309.834 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2021-06-01T14:38:29.840Z,1622558309.840 [CBIT](DEBUG): Initialized mux pins.
2021-06-01T14:38:29.840Z,1622558309.840 [CBIT](DEBUG): Initializing the watchdog timer.
2021-06-01T14:38:29.841Z,1622558309.841 [Onboard ThreadHandler](INFO): Handler Thread ID is 7052
2021-06-01T14:38:29.859Z,1622558309.859 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7053
2021-06-01T14:38:29.864Z,1622558309.864 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2021-06-01T14:38:29.865Z,1622558309.865 [CBIT](DEBUG): Initializing heartbeat.
2021-06-01T14:38:29.877Z,1622558309.877 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7054
2021-06-01T14:38:29.877Z,1622558309.877 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-06-01T14:38:29.882Z,1622558309.882 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 7056
2021-06-01T14:38:29.885Z,1622558309.885 [WetLabsBB2FL](INFO): Powering up
2021-06-01T14:38:29.886Z,1622558309.886 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7058
2021-06-01T14:38:29.890Z,1622558309.890 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2021-06-01T14:38:29.890Z,1622558309.890 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2021-06-01T14:38:29.890Z,1622558309.890 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2021-06-01T14:38:29.890Z,1622558309.890 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2021-06-01T14:38:29.890Z,1622558309.890 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2021-06-01T14:38:29.891Z,1622558309.891 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2021-06-01T14:38:29.891Z,1622558309.891 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2021-06-01T14:38:29.891Z,1622558309.891 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2021-06-01T14:38:29.891Z,1622558309.891 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2021-06-01T14:38:29.891Z,1622558309.891 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2021-06-01T14:38:29.892Z,1622558309.892 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2021-06-01T14:38:29.892Z,1622558309.892 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2021-06-01T14:38:29.892Z,1622558309.892 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2021-06-01T14:38:29.893Z,1622558309.893 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2021-06-01T14:38:29.893Z,1622558309.893 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2021-06-01T14:38:29.893Z,1622558309.893 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2021-06-01T14:38:29.936Z,1622558309.936 [CBIT](DEBUG): Deactivating GF circuits.
2021-06-01T14:38:29.937Z,1622558309.937 [CBIT](DEBUG): Deactivating emergency mode.
2021-06-01T14:38:29.972Z,1622558309.972 [CBIT](DEBUG): Backplane powered.
2021-06-01T14:38:29.973Z,1622558309.973 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2021-06-01T14:38:29.987Z,1622558309.987 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2021-06-01T14:38:30.007Z,1622558310.007 [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>


2021-06-01T14:38:30.008Z,1622558310.008 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2021-06-01T14:38:30.086Z,1622558310.086 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2021-06-01T14:38:30.088Z,1622558310.088 [Default:A.Wait](DEBUG): Construct Wait.
2021-06-01T14:38:30.102Z,1622558310.102 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-06-01T14:38:30.123Z,1622558310.123 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2021-06-01T14:38:30.150Z,1622558310.150 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2021-06-01T14:38:30.172Z,1622558310.172 [Default:E.Execute](DEBUG): Construct Execute.
2021-06-01T14:38:30.176Z,1622558310.176 [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="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2021-06-01T14:38:30.181Z,1622558310.181 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,AMEcho,DAT,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2021-06-01T14:38:30.205Z,1622558310.205 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-06-01T14:38:30.264Z,1622558310.264 [Radio_Surface](INFO): Powering up
2021-06-01T14:38:30.275Z,1622558310.275 [Power24vConverter](INFO): Powering up.
2021-06-01T14:38:30.275Z,1622558310.275 [AMEcho](INFO): Powering up.
2021-06-01T14:38:30.297Z,1622558310.297 [DAT](INFO): Powering up
2021-06-01T14:38:30.297Z,1622558310.297 [DAT](DEBUG): Initializing DAT.
2021-06-01T14:38:30.466Z,1622558310.466 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2021-06-01T14:38:30.473Z,1622558310.473 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2021-06-01T14:38:30.474Z,1622558310.474 [ElevatorServo](DEBUG): Initializing EZServoServo.
2021-06-01T14:38:30.481Z,1622558310.481 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2021-06-01T14:38:30.482Z,1622558310.482 [MassServo](DEBUG): Initializing EZServoServo.
2021-06-01T14:38:30.489Z,1622558310.489 [MassServo](DEBUG): Initializing MassServo.
2021-06-01T14:38:30.490Z,1622558310.490 [RudderServo](DEBUG): Initializing EZServoServo.
2021-06-01T14:38:30.497Z,1622558310.497 [RudderServo](DEBUG): Initializing RudderServo.
2021-06-01T14:38:30.498Z,1622558310.498 [ThrusterServo](DEBUG): Initializing EZServoServo.
2021-06-01T14:38:30.505Z,1622558310.505 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2021-06-01T14:38:30.885Z,1622558310.885 [AMEcho](INFO): Powering down.
2021-06-01T14:38:31.328Z,1622558311.328 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2021-06-01T14:38:31.329Z,1622558311.329 [RudderServo](FAULT): Rudder failed to initialize
2021-06-01T14:38:31.329Z,1622558311.329 [RudderServo] Communications Fault, FailCount= 1
2021-06-01T14:38:31.329Z,1622558311.329 [RudderServo](ERROR): Communications Fault
2021-06-01T14:38:31.429Z,1622558311.429 [CBIT](ERROR): Communications Fault in component: RudderServo
2021-06-01T14:38:31.438Z,1622558311.438 [AMEcho](IMPORTANT): Setting 'depthThreshold' to 20.000000
2021-06-01T14:38:31.625Z,1622558311.625 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-06-01T14:38:31.626Z,1622558311.626 [RudderServo](INFO): Powering down
2021-06-01T14:38:31.992Z,1622558311.992 [WetLabsBB2FL](INFO): Powering down
2021-06-01T14:38:32.306Z,1622558312.306 [RudderServo](DEBUG): Initializing EZServoServo.
2021-06-01T14:38:32.425Z,1622558312.425 [RudderServo](DEBUG): Initializing RudderServo.
2021-06-01T14:38:32.430Z,1622558312.430 [CBIT](INFO): Clearing failed state for component RudderServo
2021-06-01T14:38:32.430Z,1622558312.430 [RudderServo] No Fault, FailCount= 1
2021-06-01T14:38:36.945Z,1622558316.945 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2021-06-01T14:38:41.946Z,1622558321.946 [DAT](INFO): DAT read: 

2021-06-01T14:38:41.948Z,1622558321.948 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2021-06-01T14:38:42.587Z,1622558322.587 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005059
2021-06-01T14:38:43.563Z,1622558323.563 [DAT](INFO): DAT read: MF Frequency Band

2021-06-01T14:38:43.565Z,1622558323.565 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.14.2

2021-06-01T14:38:43.565Z,1622558323.565 [DAT](INFO): DAT read: Jun  1 2021 14:38:12 

2021-06-01T14:38:44.371Z,1622558324.371 [DAT](INFO): DAT read: Features enabled [Bearing]

2021-06-01T14:38:44.372Z,1622558324.372 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2021-06-01T14:38:44.373Z,1622558324.373 [DAT](INFO): commRate: 800
2021-06-01T14:38:46.395Z,1622558326.395 [DAT](INFO): entering command mode
2021-06-01T14:38:46.794Z,1622558326.794 [DAT](INFO): DAT read: 

2021-06-01T14:38:46.795Z,1622558326.795 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:38:47.198Z,1622558327.198 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:38:47.607Z,1622558327.607 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:38:48.006Z,1622558328.006 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:38:48.410Z,1622558328.410 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:38:48.814Z,1622558328.814 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:38:49.218Z,1622558329.218 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:38:49.631Z,1622558329.631 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:38:50.026Z,1622558330.026 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:38:50.430Z,1622558330.430 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:38:50.834Z,1622558330.834 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:38:51.238Z,1622558331.238 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:38:51.642Z,1622558331.642 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:38:52.046Z,1622558332.046 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:38:52.450Z,1622558332.450 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:38:52.854Z,1622558332.854 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:38:53.258Z,1622558333.258 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:38:53.662Z,1622558333.662 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:38:54.066Z,1622558334.066 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:38:54.470Z,1622558334.470 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:38:54.895Z,1622558334.895 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:38:55.278Z,1622558335.278 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:38:55.682Z,1622558335.682 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:38:56.087Z,1622558336.087 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:38:56.499Z,1622558336.499 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:38:56.895Z,1622558336.895 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:38:57.299Z,1622558337.299 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:38:57.703Z,1622558337.703 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:38:58.110Z,1622558338.110 [NAL9602](INFO): Powering up NAL9602
2021-06-01T14:38:58.112Z,1622558338.112 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:38:58.511Z,1622558338.511 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:38:58.915Z,1622558338.915 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:38:59.318Z,1622558339.318 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:38:59.722Z,1622558339.722 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:39:00.127Z,1622558340.127 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:39:00.531Z,1622558340.531 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:39:00.935Z,1622558340.935 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:39:01.339Z,1622558341.339 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:39:01.749Z,1622558341.749 [DAT](DEBUG): checking for command mode acknowledgment
2021-06-01T14:39:01.749Z,1622558341.749 [DAT](FAULT): failed to enter command mode
2021-06-01T14:39:02.147Z,1622558342.147 [DAT](INFO): entering command mode
2021-06-01T14:39:02.551Z,1622558342.551 [DAT](INFO): DAT read: user:1>

2021-06-01T14:39:02.552Z,1622558342.552 [DAT](INFO): DAT read: Command '+++' not found

2021-06-01T14:39:02.552Z,1622558342.552 [DAT](INFO): DAT read: Error

2021-06-01T14:39:02.553Z,1622558342.553 [DAT](INFO): setting verbose to 3
2021-06-01T14:39:02.955Z,1622558342.955 [DAT](INFO): DAT read: user:2>

2021-06-01T14:39:02.956Z,1622558342.956 [DAT](INFO): DAT read: Verbose         | 3           

2021-06-01T14:39:02.956Z,1622558342.956 [DAT](INFO): set verbose to 3
2021-06-01T14:39:02.957Z,1622558342.957 [DAT](INFO): setting DatVerbose to 27440
2021-06-01T14:39:03.359Z,1622558343.359 [DAT](INFO): DAT read: user:3>

2021-06-01T14:39:03.360Z,1622558343.360 [DAT](INFO): DAT read: DatVerbose      | 27440       

2021-06-01T14:39:03.360Z,1622558343.360 [DAT](INFO): set DatVerbose to 27440
2021-06-01T14:39:03.360Z,1622558343.360 [DAT](INFO): setting transmit power to 8
2021-06-01T14:39:03.763Z,1622558343.763 [DAT](INFO): DAT read: user:4>

2021-06-01T14:39:03.764Z,1622558343.764 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2021-06-01T14:39:03.764Z,1622558343.764 [DAT](INFO): set transmit power to 8
2021-06-01T14:39:03.764Z,1622558343.764 [DAT](INFO): setting local address to 7
2021-06-01T14:39:04.167Z,1622558344.167 [DAT](INFO): DAT read: user:5>

2021-06-01T14:39:04.168Z,1622558344.168 [DAT](INFO): DAT read: LocalAddr       | 7           

2021-06-01T14:39:04.168Z,1622558344.168 [DAT](INFO): set local address to 7
2021-06-01T14:39:09.014Z,1622558349.014 [NAL9602](INFO): NAL9602 initialized
2021-06-01T14:39:27.228Z,1622558367.228 [SBIT](IMPORTANT): Beginning Startup BIT
2021-06-01T14:39:27.241Z,1622558367.241 [CBIT](IMPORTANT): Beginning ground fault scan
2021-06-01T14:39:37.910Z,1622558377.910 [CommandLine](IMPORTANT): got command failComponent
2021-06-01T14:39:37.910Z,1622558377.910 [CommandLine](IMPORTANT): Failed components:
2021-06-01T14:39:37.911Z,1622558377.911 [CommandLine](IMPORTANT): No failed Components.
2021-06-01T14:39:38.179Z,1622558378.179 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.016652
CHAN A1 (24V): 0.000530
CHAN A2 (12V): -0.002313
CHAN A3 (5V): 4.753929
CHAN B0 (3.3V): 0.001440
CHAN B1 (3.15aV): 0.000476
CHAN B2 (3.15bV): -0.000011
CHAN B3 (GND): 0.000261
OPEN: -0.000839
Full Scale Calc: 4.765 mA, -1.589 mA
2021-06-01T14:40:21.121Z,1622558421.121 [SBIT](IMPORTANT): SBIT PASSED
2021-06-01T14:40:21.122Z,1622558421.122 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2021-06-01T14:40:21.123Z,1622558421.123 [SBIT](IMPORTANT): DAT.verbosity=3 count;

2021-06-01T14:40:21.123Z,1622558421.123 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2021-06-01T14:40:21.123Z,1622558421.123 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=282 cubic_centimeter;

2021-06-01T14:40:21.123Z,1622558421.123 [SBIT](IMPORTANT): VerticalControl.massDefault=9.25 millimeter;

2021-06-01T14:40:21.367Z,1622558421.367 [MissionManager](IMPORTANT): Started mission Startup
2021-06-01T14:40:21.367Z,1622558421.367 [Startup] Running Loop=1
2021-06-01T14:40:21.367Z,1622558421.367 [Startup](DEBUG): Aggregate::initialize Startup
2021-06-01T14:40:21.368Z,1622558421.368 [Startup:A.GoToSurface] Running Loop=1
2021-06-01T14:40:21.368Z,1622558421.368 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-06-01T14:40:21.405Z,1622558421.405 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-06-01T14:40:21.405Z,1622558421.405 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-06-01T14:40:21.406Z,1622558421.406 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-06-01T14:40:21.406Z,1622558421.406 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-06-01T14:40:21.406Z,1622558421.406 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-06-01T14:40:21.417Z,1622558421.417 [Startup:StartupSatComms] Running Loop=1
2021-06-01T14:40:21.417Z,1622558421.417 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2021-06-01T14:40:21.417Z,1622558421.417 [Startup:StartupSatComms:A] Running Loop=1
2021-06-01T14:40:21.744Z,1622558421.744 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2021-06-01T14:40:22.139Z,1622558422.139 [AMEcho](INFO): Powering up.
2021-06-01T14:40:23.698Z,1622558423.698 [CommandLine](IMPORTANT): got command report touch AMEcho.height_above_sea_floor
2021-06-01T14:40:33.970Z,1622558433.970 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T14:40:34.797Z,1622558434.797 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T14:40:35.998Z,1622558435.998 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T14:40:36.817Z,1622558436.817 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T14:40:38.026Z,1622558438.026 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T14:40:38.809Z,1622558438.809 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T14:40:40.068Z,1622558440.068 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T14:40:40.854Z,1622558440.854 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T14:40:42.046Z,1622558442.046 [Reporter](INFO): AMEcho.height_above_sea_floor -1.000000 m
2021-06-01T14:40:42.786Z,1622558442.786 [CommandLine](IMPORTANT): got command report clear
2021-06-01T14:40:47.988Z,1622558447.988 [CommandLine](IMPORTANT): got command strobe off
2021-06-01T14:40:47.988Z,1622558447.988 [CommandLine](IMPORTANT): Deactivating strobe
2021-06-01T14:41:21.540Z,1622558481.540 [Startup:StartupSatComms:A](INFO): Timed out from 2021-06-01T14:40:21.4Z
2021-06-01T14:41:21.540Z,1622558481.540 [Startup:StartupSatComms:A] Stopped
2021-06-01T14:41:21.541Z,1622558481.541 [Startup:StartupSatComms:B] Running Loop=1
2021-06-01T14:41:21.948Z,1622558481.948 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-06-01T14:41:28.949Z,1622558488.949 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20210528T202225/Courier0043.lzma
2021-06-01T14:41:29.950Z,1622558489.950 [DataOverHttps](INFO): Moved sent file to Logs/20210528T202225/Courier0043.lzma.bak
2021-06-01T14:41:29.951Z,1622558489.951 [DataOverHttps](INFO): SBD MOMSN=15681072
2021-06-01T14:41:30.047Z,1622558490.047 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2021-06-01T14:41:30.047Z,1622558490.047 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T14:41:30.059Z,1622558490.059 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T14:41:30.437Z,1622558490.437 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T14:41:30.437Z,1622558490.437 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2021-06-01T14:41:45.516Z,1622558505.516 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20210601T143819/Courier0000.lzma
2021-06-01T14:41:46.518Z,1622558506.518 [DataOverHttps](INFO): Moved sent file to Logs/20210601T143819/Courier0000.lzma.bak
2021-06-01T14:41:46.518Z,1622558506.518 [DataOverHttps](INFO): SBD MOMSN=15681074
2021-06-01T14:42:03.844Z,1622558523.844 [DataOverHttps](INFO): Sending 188 bytes from file Logs/20210528T202225/Express0044.lzma
2021-06-01T14:42:03.963Z,1622558523.963 [DAT](INFO): DAT read: user:6>Lowpower

2021-06-01T14:42:03.963Z,1622558523.963 [DAT](DEBUG): Re-entering command mode due to deviceResponse_: user:6>Lowpower

2021-06-01T14:42:04.846Z,1622558524.846 [DataOverHttps](INFO): Moved sent file to Logs/20210528T202225/Express0044.lzma.bak
2021-06-01T14:42:04.847Z,1622558524.847 [DataOverHttps](INFO): SBD MOMSN=15681077
2021-06-01T14:42:21.749Z,1622558541.749 [Startup:StartupSatComms:B](INFO): Timed out from 2021-06-01T14:41:21.5Z
2021-06-01T14:42:21.749Z,1622558541.749 [Startup:StartupSatComms:B] Stopped
2021-06-01T14:42:21.749Z,1622558541.749 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2021-06-01T14:42:21.749Z,1622558541.749 [Startup:StartupSatComms] Stopped
2021-06-01T14:42:21.749Z,1622558541.749 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2021-06-01T14:42:21.750Z,1622558541.750 [Startup](INFO): Completed Startup
2021-06-01T14:42:21.750Z,1622558541.750 [MissionManager](INFO): Startup is completed.
2021-06-01T14:42:21.751Z,1622558541.751 [MissionManager](INFO): Uninitializing Mission Startup
2021-06-01T14:42:21.751Z,1622558541.751 [Startup] Stopped
2021-06-01T14:42:21.751Z,1622558541.751 [Startup](DEBUG): Aggregate::uninitialize Startup
2021-06-01T14:42:21.751Z,1622558541.751 [Startup:A.GoToSurface] Stopped
2021-06-01T14:42:21.751Z,1622558541.751 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-06-01T14:42:22.143Z,1622558542.143 [MissionManager](IMPORTANT): Started mission Default
2021-06-01T14:42:22.143Z,1622558542.143 [Default] Running Loop=1
2021-06-01T14:42:22.143Z,1622558542.143 [Default](DEBUG): Aggregate::initialize Default
2021-06-01T14:42:22.143Z,1622558542.143 [Default:B.GoToSurface] Running Loop=1
2021-06-01T14:42:22.144Z,1622558542.144 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-06-01T14:42:22.144Z,1622558542.144 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-06-01T14:42:22.144Z,1622558542.144 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-06-01T14:42:22.145Z,1622558542.145 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-06-01T14:42:22.145Z,1622558542.145 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-06-01T14:42:22.145Z,1622558542.145 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-06-01T14:42:22.146Z,1622558542.146 [Default:A.Wait] Running Loop=1
2021-06-01T14:42:22.146Z,1622558542.146 [Default:A.Wait](DEBUG): Initialize Wait Component.
2021-06-01T14:42:22.173Z,1622558542.173 [DataOverHttps](INFO): Sending 946 bytes from file Logs/20210601T143819/Express0001.lzma
2021-06-01T14:42:22.705Z,1622558542.705 [AMEcho](INFO): Powering down.
2021-06-01T14:42:23.174Z,1622558543.174 [DataOverHttps](INFO): Moved sent file to Logs/20210601T143819/Express0001.lzma.bak
2021-06-01T14:42:23.174Z,1622558543.174 [DataOverHttps](INFO): SBD MOMSN=15681082
2021-06-01T14:42:23.750Z,1622558543.750 [AMEcho](INFO): Powering up.
2021-06-01T14:42:35.488Z,1622558555.488 [Default:A.Wait](INFO): Done Waiting.
2021-06-01T14:42:35.488Z,1622558555.488 [Default:A.Wait] Stopped
2021-06-01T14:42:35.488Z,1622558555.488 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2021-06-01T14:42:35.885Z,1622558555.885 [AMEcho](ERROR): No EchoSounder communication! Re-initializing
2021-06-01T14:42:35.885Z,1622558555.885 [AMEcho] Communications Fault, FailCount= 1
2021-06-01T14:42:35.885Z,1622558555.885 [AMEcho](ERROR): Communications Fault
2021-06-01T14:42:35.895Z,1622558555.895 [Default:CheckIn] Running Loop=1
2021-06-01T14:42:35.895Z,1622558555.895 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2021-06-01T14:42:35.895Z,1622558555.895 [Default:CheckIn:Read_GPS] Running Loop=1
2021-06-01T14:42:35.904Z,1622558555.904 [CBIT](ERROR): Communications Fault in component: AMEcho
2021-06-01T14:42:36.445Z,1622558556.445 [AMEcho](INFO): Powering down.
2021-06-01T14:42:36.460Z,1622558556.460 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2021-06-01T14:42:37.097Z,1622558557.097 [CBIT](INFO): Clearing failed state for component AMEcho
2021-06-01T14:42:37.097Z,1622558557.097 [AMEcho] No Fault, FailCount= 1
2021-06-01T14:42:37.489Z,1622558557.489 [AMEcho](INFO): Powering up.
2021-06-01T14:42:49.605Z,1622558569.605 [AMEcho](ERROR): No EchoSounder communication! Re-initializing
2021-06-01T14:42:49.605Z,1622558569.605 [AMEcho] Communications Fault, FailCount= 2
2021-06-01T14:42:49.605Z,1622558569.605 [AMEcho](ERROR): Communications Fault
2021-06-01T14:42:49.661Z,1622558569.661 [CBIT](ERROR): Communications Fault in component: AMEcho
2021-06-01T14:42:50.173Z,1622558570.173 [AMEcho](INFO): Powering down.
2021-06-01T14:42:50.857Z,1622558570.857 [CBIT](INFO): Clearing failed state for component AMEcho
2021-06-01T14:42:50.857Z,1622558570.857 [AMEcho] No Fault, FailCount= 2
2021-06-01T14:42:51.225Z,1622558571.225 [AMEcho](INFO): Powering up.
2021-06-01T14:43:03.339Z,1622558583.339 [AMEcho](ERROR): No EchoSounder communication! Re-initializing
2021-06-01T14:43:03.339Z,1622558583.339 [AMEcho] Communications Fault, FailCount= 3
2021-06-01T14:43:03.339Z,1622558583.339 [AMEcho](ERROR): Communications Fault
2021-06-01T14:43:03.357Z,1622558583.357 [CBIT](ERROR): Communications Fault in component: AMEcho
2021-06-01T14:43:03.358Z,1622558583.358 [CBIT](CRITICAL): Communications Fault in component: AMEcho
2021-06-01T14:43:03.913Z,1622558583.913 [AMEcho](INFO): Powering down.
2021-06-01T14:43:03.935Z,1622558583.935 [CommandLine](FAULT): Scheduling is paused
2021-06-01T14:43:03.935Z,1622558583.935 [CBIT](INFO): Critical error at 20210601T144303
2021-06-01T14:43:03.936Z,1622558583.936 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2021-06-01T14:44:09.430Z,1622558649.430 [CommandLine](IMPORTANT): got command failComponent
2021-06-01T14:44:09.430Z,1622558649.430 [CommandLine](IMPORTANT): Failed components:
2021-06-01T14:44:09.431Z,1622558649.431 [CommandLine](IMPORTANT): AMEcho: Communications Fault
2021-06-01T14:44:12.418Z,1622558652.418 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2021-06-01T14:44:24.614Z,1622558664.614 [CommandLine](IMPORTANT): got command failComponent none AMEcho
2021-06-01T14:44:24.615Z,1622558664.615 [AMEcho] No Fault, FailCount= 3
2021-06-01T14:44:24.615Z,1622558664.615 [CommandLine](IMPORTANT): AMEcho failureMode is No Fault
2021-06-01T14:44:24.942Z,1622558664.942 [AMEcho](INFO): Powering up.
2021-06-01T14:44:31.033Z,1622558671.033 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2021-06-01T14:44:31.033Z,1622558671.033 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-06-01T14:44:31.057Z,1622558671.057 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-06-01T14:44:31.459Z,1622558671.459 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-06-01T14:44:31.460Z,1622558671.460 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2021-06-01T14:44:55.433Z,1622558695.433 [CommandLine](IMPORTANT): got command failComponent
2021-06-01T14:44:55.433Z,1622558695.433 [CommandLine](IMPORTANT): Failed components:
2021-06-01T14:44:55.433Z,1622558695.433 [CommandLine](IMPORTANT): No failed Components.
2021-06-01T14:45:10.462Z,1622558710.462 [CommandLine](IMPORTANT): got command restart system
2021-06-01T14:45:12.549Z,1622558712.549 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2021-06-01T14:45:12.549Z,1622558712.549 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-06-01T14:45:12.576Z,1622558712.576 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2021-06-01T14:45:12.577Z,1622558712.577 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-06-01T14:45:12.577Z,1622558712.577 [CommandLine](INFO): Join timeout helper Thread ID is 7099
2021-06-01T14:45:12.578Z,1622558712.578 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2021-06-01T14:45:12.578Z,1622558712.578 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-06-01T14:45:12.579Z,1622558712.579 [NavChartDb](INFO): Join timeout helper Thread ID is 7100
2021-06-01T14:45:12.849Z,1622558712.849 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2021-06-01T14:45:12.849Z,1622558712.849 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-06-01T14:45:12.856Z,1622558712.856 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2021-06-01T14:45:12.857Z,1622558712.857 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-06-01T14:45:12.857Z,1622558712.857 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7101
2021-06-01T14:45:13.356Z,1622558713.356 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2021-06-01T14:45:13.357Z,1622558713.357 [WetLabsBB2FL](INFO): Powering down
2021-06-01T14:45:13.358Z,1622558713.358 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-06-01T14:45:13.364Z,1622558713.364 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2021-06-01T14:45:13.364Z,1622558713.364 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-06-01T14:45:13.365Z,1622558713.365 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7102
2021-06-01T14:45:13.533Z,1622558713.533 [CTD_Seabird](INFO): Powering down
2021-06-01T14:45:13.544Z,1622558713.544 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2021-06-01T14:45:13.545Z,1622558713.545 [CTD_Seabird](INFO): Powering down
2021-06-01T14:45:13.556Z,1622558713.556 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-06-01T14:45:13.568Z,1622558713.568 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2021-06-01T14:45:13.569Z,1622558713.569 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-06-01T14:45:13.569Z,1622558713.569 [Radio_Surface](INFO): Join timeout helper Thread ID is 7103
2021-06-01T14:45:13.569Z,1622558713.569 [Radio_Surface](INFO): Powering down
2021-06-01T14:45:13.570Z,1622558713.570 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2021-06-01T14:45:13.571Z,1622558713.571 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-06-01T14:45:13.572Z,1622558713.572 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2021-06-01T14:45:13.572Z,1622558713.572 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-06-01T14:45:13.573Z,1622558713.573 [Onboard](INFO): Join timeout helper Thread ID is 7104
2021-06-01T14:45:14.549Z,1622558714.549 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2021-06-01T14:45:15.181Z,1622558715.181 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2021-06-01T14:45:15.181Z,1622558715.181 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-06-01T14:45:15.189Z,1622558715.189 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2021-06-01T14:45:15.189Z,1622558715.189 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-06-01T14:45:15.190Z,1622558715.190 [DataOverHttps](INFO): Join timeout helper Thread ID is 7105
2021-06-01T14:45:15.552Z,1622558715.552 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2021-06-01T14:45:15.553Z,1622558715.553 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-06-01T14:45:15.573Z,1622558715.573 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2021-06-01T14:45:15.573Z,1622558715.573 [logger ThreadHandler](INFO): Thread cancelled.
2021-06-01T14:45:15.574Z,1622558715.574 [logger](INFO): Join timeout helper Thread ID is 7106
2021-06-01T14:45:15.616Z,1622558715.616 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2021-06-01T14:45:15.617Z,1622558715.617 [logger ThreadHandler](INFO): Thread cancelled.
2021-06-01T14:45:15.633Z,1622558715.633 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2021-06-01T14:45:15.633Z,1622558715.633 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-06-01T14:45:15.633Z,1622558715.633 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2021-06-01T14:45:15.633Z,1622558715.633 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-06-01T14:45:15.634Z,1622558715.634 [controlThread](INFO): Join timeout helper Thread ID is 7107
2021-06-01T14:45:15.853Z,1622558715.853 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2021-06-01T14:45:15.853Z,1622558715.853 [controlThread](DEBUG): Uninitializing ControlThread
2021-06-01T14:45:15.853Z,1622558715.853 [AHRS_M2](INFO): Powering down
2021-06-01T14:45:15.926Z,1622558715.926 [NAL9602](INFO): Powering down
2021-06-01T14:45:16.092Z,1622558716.092 [AMEcho](INFO): Powering down.
2021-06-01T14:45:16.094Z,1622558716.094 [DAT](INFO): Powering down
2021-06-01T14:45:16.165Z,1622558716.165 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2021-06-01T14:45:16.166Z,1622558716.166 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2021-06-01T14:45:16.167Z,1622558716.167 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2021-06-01T14:45:16.167Z,1622558716.167 [MissionManager](INFO): Uninitializing Mission Default
2021-06-01T14:45:16.167Z,1622558716.167 [Default] Stopped
2021-06-01T14:45:16.167Z,1622558716.167 [Default](DEBUG): Aggregate::uninitialize Default
2021-06-01T14:45:16.168Z,1622558716.168 [Default:B.GoToSurface] Stopped
2021-06-01T14:45:16.168Z,1622558716.168 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-06-01T14:45:16.168Z,1622558716.168 [Default:CheckIn] Stopped
2021-06-01T14:45:16.168Z,1622558716.168 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2021-06-01T14:45:16.168Z,1622558716.168 [Default:CheckIn:Read_GPS] Stopped
2021-06-01T14:45:16.171Z,1622558716.171 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2021-06-01T14:45:16.172Z,1622558716.172 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2021-06-01T14:45:16.172Z,1622558716.172 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2021-06-01T14:45:16.173Z,1622558716.173 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2021-06-01T14:45:16.173Z,1622558716.173 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2021-06-01T14:45:16.173Z,1622558716.173 [BuoyancyServo](INFO): Powering down
2021-06-01T14:45:16.184Z,1622558716.184 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2021-06-01T14:45:16.185Z,1622558716.185 [ElevatorServo](INFO): Powering down
2021-06-01T14:45:16.186Z,1622558716.186 [MassServo](DEBUG): Uninitialize Mass Servo.
2021-06-01T14:45:16.186Z,1622558716.186 [MassServo](INFO): Powering down
2021-06-01T14:45:16.187Z,1622558716.187 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-06-01T14:45:16.187Z,1622558716.187 [RudderServo](INFO): Powering down
2021-06-01T14:45:16.188Z,1622558716.188 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2021-06-01T14:45:16.188Z,1622558716.188 [ThrusterServo](INFO): Powering down
2021-06-01T14:45:16.189Z,1622558716.189 [SBIT](DEBUG): Uninitialize SBIT Component.
2021-06-01T14:45:16.189Z,1622558716.189 [IBIT](DEBUG): Uninitialize IBIT Component.
2021-06-01T14:45:16.190Z,1622558716.190 [CBIT](DEBUG): Uninitialize CBIT Component.
2021-06-01T14:45:16.190Z,1622558716.190 [CBIT](DEBUG): Powering off loads.
2021-06-01T14:45:16.200Z,1622558716.200 [CBIT](DEBUG): Disabling WDT.
2021-06-01T14:45:16.212Z,1622558716.212 [CBIT](DEBUG): Opening all GF detection circuits.
2021-06-01T14:45:16.213Z,1622558716.213 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-06-01T14:45:16.282Z,1622558716.282 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-06-01T14:45:16.285Z,1622558716.285 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-06-01T14:45:16.291Z,1622558716.291 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-06-01T14:45:16.373Z,1622558716.373 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-06-01T14:45:16.377Z,1622558716.377 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-06-01T14:45:16.428Z,1622558716.428 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-06-01T14:45:16.493Z,1622558716.493 [logger ThreadHandler](INFO): Thread cancelled.