2021-09-20T18:24:47.482Z,1632162287.482 [Supervisor](DEBUG): Initializing supervisor.
2021-09-20T18:24:47.487Z,1632162287.487 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2021-09-20T18:24:47.488Z,1632162287.488 [SyncHandler](INFO): Protected caller Thread ID is 3106
2021-09-20T18:24:47.488Z,1632162287.488 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2021-09-20T18:24:47.489Z,1632162287.489 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2021-09-20T18:24:47.490Z,1632162287.490 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3107
2021-09-20T18:24:47.494Z,1632162287.494 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2021-09-20T18:24:47.513Z,1632162287.513 [ComponentRegistry](DEBUG): Component "CommandExec" handled in its own thread.
2021-09-20T18:24:47.514Z,1632162287.514 [CommandExec ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2021-09-20T18:24:47.514Z,1632162287.514 [CommandExec ThreadHandler](INFO): Protected caller Thread ID is 3108
2021-09-20T18:24:47.518Z,1632162287.518 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2021-09-20T18:24:47.519Z,1632162287.519 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2021-09-20T18:24:47.520Z,1632162287.520 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3109
2021-09-20T18:24:47.522Z,1632162287.522 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2021-09-20T18:24:47.523Z,1632162287.523 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405114E0
2021-09-20T18:24:47.523Z,1632162287.523 [logger ThreadHandler](INFO): Protected caller Thread ID is 3110
2021-09-20T18:24:47.527Z,1632162287.527 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2021-09-20T18:24:47.528Z,1632162287.528 [Supervisor](INFO): Looking for Config files in directory: Config/
2021-09-20T18:24:47.529Z,1632162287.529 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2021-09-20T18:24:47.688Z,1632162287.688 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2021-09-20T18:24:47.689Z,1632162287.689 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2021-09-20T18:24:48.258Z,1632162288.258 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2021-09-20T18:24:48.258Z,1632162288.258 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2021-09-20T18:24:48.463Z,1632162288.463 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2021-09-20T18:24:48.464Z,1632162288.464 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2021-09-20T18:24:48.542Z,1632162288.542 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2021-09-20T18:24:48.664Z,1632162288.664 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2021-09-20T18:24:48.664Z,1632162288.664 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2021-09-20T18:24:48.743Z,1632162288.743 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2021-09-20T18:24:48.849Z,1632162288.849 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2021-09-20T18:24:48.849Z,1632162288.849 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2021-09-20T18:24:49.236Z,1632162289.236 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2021-09-20T18:24:49.237Z,1632162289.237 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2021-09-20T18:24:49.788Z,1632162289.788 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2021-09-20T18:24:49.788Z,1632162289.788 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2021-09-20T18:24:49.933Z,1632162289.933 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2021-09-20T18:24:49.933Z,1632162289.933 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2021-09-20T18:24:50.116Z,1632162290.116 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2021-09-20T18:24:50.117Z,1632162290.117 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2021-09-20T18:24:50.550Z,1632162290.550 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2021-09-20T18:24:50.550Z,1632162290.550 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2021-09-20T18:24:50.831Z,1632162290.831 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2021-09-20T18:24:50.831Z,1632162290.831 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2021-09-20T18:24:54.305Z,1632162294.305 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2021-09-20T18:24:54.306Z,1632162294.306 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2021-09-20T18:24:54.652Z,1632162294.652 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2021-09-20T18:24:54.652Z,1632162294.652 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2021-09-20T18:24:54.977Z,1632162294.977 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2021-09-20T18:24:54.979Z,1632162294.979 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/
2021-09-20T18:24:54.980Z,1632162294.980 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg
2021-09-20T18:24:55.062Z,1632162295.062 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg
2021-09-20T18:24:55.215Z,1632162295.215 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg
2021-09-20T18:24:55.324Z,1632162295.324 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg
2021-09-20T18:24:55.409Z,1632162295.409 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg
2021-09-20T18:24:55.501Z,1632162295.501 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg
2021-09-20T18:24:55.686Z,1632162295.686 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg
2021-09-20T18:24:56.292Z,1632162296.292 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2021-09-20T18:24:56.293Z,1632162296.293 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg
2021-09-20T18:24:56.474Z,1632162296.474 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg
2021-09-20T18:24:56.794Z,1632162296.794 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg
2021-09-20T18:24:57.163Z,1632162297.163 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg
2021-09-20T18:24:57.344Z,1632162297.344 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/
2021-09-20T18:24:57.344Z,1632162297.344 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2021-09-20T18:24:57.354Z,1632162297.354 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2021-09-20T18:24:57.454Z,1632162297.454 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2021-09-20T18:24:57.455Z,1632162297.455 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2021-09-20T18:24:57.509Z,1632162297.509 [VerticalControl](DEBUG): Construct VerticalControl.
2021-09-20T18:24:57.570Z,1632162297.570 [VerticalControl] Loaded
2021-09-20T18:24:57.571Z,1632162297.571 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2021-09-20T18:24:57.573Z,1632162297.573 [HorizontalControl](DEBUG): Construct HorizontalControl.
2021-09-20T18:24:57.612Z,1632162297.612 [HorizontalControl] Loaded
2021-09-20T18:24:57.612Z,1632162297.612 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2021-09-20T18:24:57.614Z,1632162297.614 [SpeedControl](DEBUG): Construct SpeedControl.
2021-09-20T18:24:57.617Z,1632162297.617 [SpeedControl] Loaded
2021-09-20T18:24:57.618Z,1632162297.618 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2021-09-20T18:24:57.620Z,1632162297.620 [LoopControl](DEBUG): Construct LoopControl.
2021-09-20T18:24:57.621Z,1632162297.621 [LoopControl] Loaded
2021-09-20T18:24:57.621Z,1632162297.621 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2021-09-20T18:24:57.621Z,1632162297.621 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2021-09-20T18:24:57.622Z,1632162297.622 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2021-09-20T18:24:57.668Z,1632162297.668 [DepthRateCalculator] Loaded
2021-09-20T18:24:57.668Z,1632162297.668 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2021-09-20T18:24:57.673Z,1632162297.673 [PitchRateCalculator] Loaded
2021-09-20T18:24:57.673Z,1632162297.673 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2021-09-20T18:24:57.683Z,1632162297.683 [SpeedCalculator] Loaded
2021-09-20T18:24:57.683Z,1632162297.683 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2021-09-20T18:24:57.697Z,1632162297.697 [TempGradientCalculator] Loaded
2021-09-20T18:24:57.697Z,1632162297.697 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2021-09-20T18:24:57.701Z,1632162297.701 [YawRateCalculator] Loaded
2021-09-20T18:24:57.702Z,1632162297.702 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2021-09-20T18:24:57.718Z,1632162297.718 [ElevatorOffsetCalculator] Loaded
2021-09-20T18:24:57.719Z,1632162297.719 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2021-09-20T18:24:57.719Z,1632162297.719 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2021-09-20T18:24:57.720Z,1632162297.720 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2021-09-20T18:24:57.751Z,1632162297.751 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2021-09-20T18:24:57.752Z,1632162297.752 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2021-09-20T18:24:57.813Z,1632162297.813 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2021-09-20T18:24:57.813Z,1632162297.813 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2021-09-20T18:24:58.145Z,1632162298.145 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2021-09-20T18:24:58.146Z,1632162298.146 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2021-09-20T18:24:58.255Z,1632162298.255 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2021-09-20T18:24:58.256Z,1632162298.256 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2021-09-20T18:24:58.652Z,1632162298.652 [AHRS_M2] Loaded
2021-09-20T18:24:58.652Z,1632162298.652 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2021-09-20T18:24:58.687Z,1632162298.687 [BackseatComponent] Loaded
2021-09-20T18:24:58.688Z,1632162298.688 [ComponentRegistry](DEBUG): Component "BackseatComponent" handled in its own thread.
2021-09-20T18:24:58.689Z,1632162298.689 [BackseatComponent ThreadHandler](DEBUG): Created PCaller Thread at 4093B4E0
2021-09-20T18:24:58.689Z,1632162298.689 [BackseatComponent ThreadHandler](INFO): Protected caller Thread ID is 3193
2021-09-20T18:24:58.692Z,1632162298.692 [LcmUniversalReporter] Loaded
2021-09-20T18:24:58.692Z,1632162298.692 [ComponentRegistry](DEBUG): SyncComponent "LcmUniversalReporter" handled in the control thread.
2021-09-20T18:24:59.473Z,1632162299.473 [BPC1] Loaded
2021-09-20T18:24:59.473Z,1632162299.473 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2021-09-20T18:24:59.543Z,1632162299.543 [DataOverHttps] Loaded
2021-09-20T18:24:59.543Z,1632162299.543 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2021-09-20T18:24:59.544Z,1632162299.544 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4096B4E0
2021-09-20T18:24:59.545Z,1632162299.545 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3194
2021-09-20T18:24:59.564Z,1632162299.564 [Depth_Keller] Loaded
2021-09-20T18:24:59.565Z,1632162299.565 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2021-09-20T18:24:59.624Z,1632162299.624 [NAL9602] Loaded
2021-09-20T18:24:59.624Z,1632162299.624 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2021-09-20T18:24:59.653Z,1632162299.653 [Onboard] Loaded
2021-09-20T18:24:59.653Z,1632162299.653 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2021-09-20T18:24:59.654Z,1632162299.654 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4099B4E0
2021-09-20T18:24:59.655Z,1632162299.655 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 3195
2021-09-20T18:24:59.667Z,1632162299.667 [Power24vConverter] Loaded
2021-09-20T18:24:59.668Z,1632162299.668 [ComponentRegistry](DEBUG): SyncComponent "Power24vConverter" handled in the control thread.
2021-09-20T18:24:59.681Z,1632162299.681 [Radio_Surface] Loaded
2021-09-20T18:24:59.681Z,1632162299.681 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2021-09-20T18:24:59.682Z,1632162299.682 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409CB4E0
2021-09-20T18:24:59.682Z,1632162299.682 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3196
2021-09-20T18:24:59.683Z,1632162299.683 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2021-09-20T18:24:59.684Z,1632162299.684 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2021-09-20T18:24:59.868Z,1632162299.868 [DeadReckonUsingMultipleVelocitySources] Loaded
2021-09-20T18:24:59.869Z,1632162299.869 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2021-09-20T18:24:59.912Z,1632162299.912 [NavChart] Loaded
2021-09-20T18:24:59.913Z,1632162299.913 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2021-09-20T18:24:59.918Z,1632162299.918 [UniversalFixResidualReporter] Loaded
2021-09-20T18:24:59.919Z,1632162299.919 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2021-09-20T18:24:59.919Z,1632162299.919 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2021-09-20T18:24:59.920Z,1632162299.920 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2021-09-20T18:25:00.101Z,1632162300.101 [SBIT](DEBUG): Construct Startup Built In Test.
2021-09-20T18:25:00.140Z,1632162300.140 [SBIT] Loaded
2021-09-20T18:25:00.141Z,1632162300.141 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2021-09-20T18:25:00.145Z,1632162300.145 [IBIT](DEBUG): Construct Initiated Built In Test.
2021-09-20T18:25:00.159Z,1632162300.159 [IBIT] Loaded
2021-09-20T18:25:00.159Z,1632162300.159 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2021-09-20T18:25:00.165Z,1632162300.165 [CBIT](DEBUG): Construct Continuous Built In Test.
2021-09-20T18:25:00.271Z,1632162300.271 [CBIT] Loaded
2021-09-20T18:25:00.271Z,1632162300.271 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2021-09-20T18:25:00.272Z,1632162300.272 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2021-09-20T18:25:00.272Z,1632162300.272 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2021-09-20T18:25:00.761Z,1632162300.761 [BuoyancyServo] Loaded
2021-09-20T18:25:00.761Z,1632162300.761 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2021-09-20T18:25:00.788Z,1632162300.788 [ElevatorServo] Loaded
2021-09-20T18:25:00.788Z,1632162300.788 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2021-09-20T18:25:00.807Z,1632162300.807 [MassServo] Loaded
2021-09-20T18:25:00.807Z,1632162300.807 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2021-09-20T18:25:00.826Z,1632162300.826 [RudderServo] Loaded
2021-09-20T18:25:00.826Z,1632162300.826 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2021-09-20T18:25:00.843Z,1632162300.843 [ThrusterServo] Loaded
2021-09-20T18:25:00.844Z,1632162300.844 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2021-09-20T18:25:00.844Z,1632162300.844 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2021-09-20T18:25:00.845Z,1632162300.845 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2021-09-20T18:25:00.856Z,1632162300.856 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2021-09-20T18:25:00.856Z,1632162300.856 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2021-09-20T18:25:01.034Z,1632162301.034 [CTD_Seabird] Loaded
2021-09-20T18:25:01.034Z,1632162301.034 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2021-09-20T18:25:01.035Z,1632162301.035 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B034E0
2021-09-20T18:25:01.036Z,1632162301.036 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3197
2021-09-20T18:25:01.055Z,1632162301.055 [PAR_Licor] Loaded
2021-09-20T18:25:01.055Z,1632162301.055 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2021-09-20T18:25:01.087Z,1632162301.087 [WetLabsBB2FL] Loaded
2021-09-20T18:25:01.088Z,1632162301.088 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2021-09-20T18:25:01.089Z,1632162301.089 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B334E0
2021-09-20T18:25:01.089Z,1632162301.089 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3198
2021-09-20T18:25:01.110Z,1632162301.110 [WetLabsUBAT] Loaded
2021-09-20T18:25:01.111Z,1632162301.111 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread.
2021-09-20T18:25:01.112Z,1632162301.112 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40B634E0
2021-09-20T18:25:01.112Z,1632162301.112 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 3199
2021-09-20T18:25:01.113Z,1632162301.113 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2021-09-20T18:25:01.119Z,1632162301.119 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2021-09-20T18:25:01.122Z,1632162301.122 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2021-09-20T18:25:01.133Z,1632162301.133 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2021-09-20T18:25:01.134Z,1632162301.134 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B934E0
2021-09-20T18:25:01.134Z,1632162301.134 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3200
2021-09-20T18:25:01.139Z,1632162301.139 [Supervisor](INFO): Main Thread ID is 3105
2021-09-20T18:25:01.139Z,1632162301.139 [Supervisor](DEBUG): Running supervisor.
2021-09-20T18:25:01.140Z,1632162301.140 [CommandExec ThreadHandler](INFO): Handler Thread ID is 3201
2021-09-20T18:25:01.140Z,1632162301.140 [CommandExec](INFO): Initializing the command executive.
2021-09-20T18:25:01.142Z,1632162301.142 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3202
2021-09-20T18:25:01.144Z,1632162301.144 [controlThread ThreadHandler](INFO): Handler Thread ID is 3203
2021-09-20T18:25:01.145Z,1632162301.145 [controlThread](DEBUG): Initializing ControlThread
2021-09-20T18:25:01.145Z,1632162301.145 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2021-09-20T18:25:01.147Z,1632162301.147 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2021-09-20T18:25:01.148Z,1632162301.148 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2021-09-20T18:25:01.149Z,1632162301.149 [LoopControl](DEBUG): Initialize LoopControlComponent.
2021-09-20T18:25:01.149Z,1632162301.149 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2021-09-20T18:25:01.150Z,1632162301.150 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2021-09-20T18:25:01.150Z,1632162301.150 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2021-09-20T18:25:01.150Z,1632162301.150 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2021-09-20T18:25:01.151Z,1632162301.151 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2021-09-20T18:25:01.151Z,1632162301.151 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2021-09-20T18:25:01.156Z,1632162301.156 [NavChart](DEBUG): Initialize NavChart Navigation.
2021-09-20T18:25:01.156Z,1632162301.156 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2021-09-20T18:25:01.156Z,1632162301.156 [SBIT](INFO): Initialize SBIT Component.
2021-09-20T18:25:01.157Z,1632162301.157 [SBIT](IMPORTANT): git: 2021-09-01-36-g65ea572fe
2021-09-20T18:25:01.157Z,1632162301.157 [SBIT](INFO): git hash: 65ea572fe75264480c3c18f9734f4a40b1eecca1
2021-09-20T18:25:01.158Z,1632162301.158 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2021-09-20T18:25:01.159Z,1632162301.159 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
Kernel Reported: #1 PREEMPT Wed Mar 17 08:23:48 PDT 2021
2021-09-20T18:25:01.160Z,1632162301.160 [SBIT](INFO): Beginning SBIT in 50.000000 seconds.
2021-09-20T18:25:01.160Z,1632162301.160 [IBIT](INFO): Initialize IBIT Component.
2021-09-20T18:25:01.161Z,1632162301.161 [CBIT](DEBUG): Initialize CBIT Component.
2021-09-20T18:25:01.163Z,1632162301.163 [logger ThreadHandler](INFO): Handler Thread ID is 3204
2021-09-20T18:25:01.175Z,1632162301.175 [CBIT](DEBUG): Initialized mux pins.
2021-09-20T18:25:01.175Z,1632162301.175 [CBIT](DEBUG): Initializing the watchdog timer.
2021-09-20T18:25:01.183Z,1632162301.183 [BackseatComponent ThreadHandler](INFO): Handler Thread ID is 3205
2021-09-20T18:25:01.195Z,1632162301.195 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3206
2021-09-20T18:25:01.196Z,1632162301.196 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2021-09-20T18:25:01.199Z,1632162301.199 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2021-09-20T18:25:01.199Z,1632162301.199 [CBIT](DEBUG): Initializing heartbeat.
2021-09-20T18:25:01.207Z,1632162301.207 [Onboard ThreadHandler](INFO): Handler Thread ID is 3207
2021-09-20T18:25:01.225Z,1632162301.225 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3208
2021-09-20T18:25:01.247Z,1632162301.247 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3209
2021-09-20T18:25:01.248Z,1632162301.248 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-09-20T18:25:01.252Z,1632162301.252 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3211
2021-09-20T18:25:01.255Z,1632162301.255 [WetLabsBB2FL](INFO): Powering up
2021-09-20T18:25:01.256Z,1632162301.256 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 3213
2021-09-20T18:25:01.259Z,1632162301.259 [WetLabsUBAT](INFO): Powering up
2021-09-20T18:25:01.261Z,1632162301.261 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3215
2021-09-20T18:25:01.264Z,1632162301.264 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2021-09-20T18:25:01.265Z,1632162301.265 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2021-09-20T18:25:01.265Z,1632162301.265 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2021-09-20T18:25:01.265Z,1632162301.265 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2021-09-20T18:25:01.265Z,1632162301.265 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2021-09-20T18:25:01.265Z,1632162301.265 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2021-09-20T18:25:01.266Z,1632162301.266 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2021-09-20T18:25:01.266Z,1632162301.266 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2021-09-20T18:25:01.266Z,1632162301.266 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2021-09-20T18:25:01.266Z,1632162301.266 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2021-09-20T18:25:01.267Z,1632162301.267 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2021-09-20T18:25:01.267Z,1632162301.267 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2021-09-20T18:25:01.267Z,1632162301.267 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2021-09-20T18:25:01.267Z,1632162301.267 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2021-09-20T18:25:01.267Z,1632162301.267 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2021-09-20T18:25:01.267Z,1632162301.267 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2021-09-20T18:25:01.270Z,1632162301.270 [CBIT](DEBUG): Deactivating GF circuits.
2021-09-20T18:25:01.271Z,1632162301.271 [CBIT](DEBUG): Deactivating emergency mode.
2021-09-20T18:25:01.306Z,1632162301.306 [CBIT](DEBUG): Backplane powered.
2021-09-20T18:25:01.311Z,1632162301.311 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2021-09-20T18:25:01.334Z,1632162301.334 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2021-09-20T18:25:01.378Z,1632162301.378 [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-09-20T18:25:01.379Z,1632162301.379 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2021-09-20T18:25:01.450Z,1632162301.450 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2021-09-20T18:25:01.471Z,1632162301.471 [Default:A.Wait](DEBUG): Construct Wait.
2021-09-20T18:25:01.474Z,1632162301.474 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-09-20T18:25:01.498Z,1632162301.498 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2021-09-20T18:25:01.522Z,1632162301.522 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2021-09-20T18:25:01.541Z,1632162301.541 [Default:E.Execute](DEBUG): Construct Execute.
2021-09-20T18:25:01.561Z,1632162301.561 [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-09-20T18:25:01.565Z,1632162301.565 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,BPC1,Depth_Keller,NAL9602,Power24vConverter,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,LcmUniversalReporter,Reporter,LogSplitter,
2021-09-20T18:25:01.588Z,1632162301.588 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-09-20T18:25:01.631Z,1632162301.631 [Radio_Surface](INFO): Powering up
2021-09-20T18:25:01.661Z,1632162301.661 [Power24vConverter](INFO): Powering up.
2021-09-20T18:25:01.709Z,1632162301.709 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2021-09-20T18:25:01.715Z,1632162301.715 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2021-09-20T18:25:01.716Z,1632162301.716 [ElevatorServo](DEBUG): Initializing EZServoServo.
2021-09-20T18:25:01.723Z,1632162301.723 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2021-09-20T18:25:01.724Z,1632162301.724 [MassServo](DEBUG): Initializing EZServoServo.
2021-09-20T18:25:01.731Z,1632162301.731 [MassServo](DEBUG): Initializing MassServo.
2021-09-20T18:25:01.732Z,1632162301.732 [RudderServo](DEBUG): Initializing EZServoServo.
2021-09-20T18:25:01.743Z,1632162301.743 [RudderServo](DEBUG): Initializing RudderServo.
2021-09-20T18:25:01.744Z,1632162301.744 [ThrusterServo](DEBUG): Initializing EZServoServo.
2021-09-20T18:25:01.751Z,1632162301.751 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2021-09-20T18:25:02.719Z,1632162302.719 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2021-09-20T18:25:02.719Z,1632162302.719 [RudderServo](FAULT): Rudder failed to initialize
2021-09-20T18:25:02.719Z,1632162302.719 [RudderServo] Communications Fault, FailCount= 1
2021-09-20T18:25:02.719Z,1632162302.719 [RudderServo](ERROR): Communications Fault
2021-09-20T18:25:02.819Z,1632162302.819 [CBIT](ERROR): Communications Fault in component: RudderServo
2021-09-20T18:25:02.993Z,1632162302.993 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-09-20T18:25:02.994Z,1632162302.994 [RudderServo](INFO): Powering down
2021-09-20T18:25:03.682Z,1632162303.682 [RudderServo](DEBUG): Initializing EZServoServo.
2021-09-20T18:25:03.799Z,1632162303.799 [RudderServo](DEBUG): Initializing RudderServo.
2021-09-20T18:25:03.804Z,1632162303.804 [CBIT](INFO): Clearing failed state for component RudderServo
2021-09-20T18:25:03.804Z,1632162303.804 [RudderServo] No Fault, FailCount= 1
2021-09-20T18:25:03.867Z,1632162303.867 [WetLabsBB2FL](INFO): Powering down
2021-09-20T18:25:08.279Z,1632162308.279 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2021-09-20T18:25:10.535Z,1632162310.535 [WetLabsUBAT](INFO): Powering down
2021-09-20T18:25:14.213Z,1632162314.213 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004715
2021-09-20T18:25:29.900Z,1632162329.900 [NAL9602](INFO): Powering up NAL9602
2021-09-20T18:25:40.814Z,1632162340.814 [NAL9602](INFO): NAL9602 initialized
2021-09-20T18:25:48.155Z,1632162348.155 [CommandExec](IMPORTANT): got command failComponent 
2021-09-20T18:25:48.156Z,1632162348.156 [CommandExec](IMPORTANT): Failed components:
2021-09-20T18:25:48.156Z,1632162348.156 [CommandExec](IMPORTANT): No failed Components.
2021-09-20T18:25:50.339Z,1632162350.339 [CommandExec](IMPORTANT): got command quit
2021-09-20T18:25:51.343Z,1632162351.343 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2021-09-20T18:25:51.343Z,1632162351.343 [CommandExec ThreadHandler](INFO): Uninitializing protected caller thread.
2021-09-20T18:25:51.343Z,1632162351.343 [CommandExec](INFO): Uninitializing the command executive.
2021-09-20T18:25:51.343Z,1632162351.343 [CommandExec](INFO): Uninitializing the command scheduler.
2021-09-20T18:25:51.344Z,1632162351.344 [CommandExec ThreadHandler](INFO): Thread cancelled.
2021-09-20T18:25:51.347Z,1632162351.347 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2021-09-20T18:25:51.347Z,1632162351.347 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2021-09-20T18:25:51.347Z,1632162351.347 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-09-20T18:25:51.348Z,1632162351.348 [NavChartDb](INFO): Join timeout helper Thread ID is 3223
2021-09-20T18:25:51.769Z,1632162351.769 [SBIT](IMPORTANT): Beginning Startup BIT
2021-09-20T18:25:51.777Z,1632162351.777 [CBIT](IMPORTANT): Beginning ground fault scan
2021-09-20T18:25:52.060Z,1632162352.060 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2021-09-20T18:25:52.060Z,1632162352.060 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-09-20T18:25:52.070Z,1632162352.070 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler
2021-09-20T18:25:52.071Z,1632162352.071 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2021-09-20T18:25:52.071Z,1632162352.071 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 3224
2021-09-20T18:25:52.451Z,1632162352.451 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread.
2021-09-20T18:25:52.451Z,1632162352.451 [WetLabsUBAT](INFO): Powering down
2021-09-20T18:25:52.452Z,1632162352.452 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2021-09-20T18:25:52.462Z,1632162352.462 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2021-09-20T18:25:52.463Z,1632162352.463 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-09-20T18:25:52.463Z,1632162352.463 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3225
2021-09-20T18:25:52.719Z,1632162352.719 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2021-09-20T18:25:52.719Z,1632162352.719 [WetLabsBB2FL](INFO): Powering down
2021-09-20T18:25:52.720Z,1632162352.720 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-09-20T18:25:52.730Z,1632162352.730 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2021-09-20T18:25:52.731Z,1632162352.731 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-09-20T18:25:52.731Z,1632162352.731 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3228
2021-09-20T18:25:53.463Z,1632162353.463 [CTD_Seabird](INFO): Powering down
2021-09-20T18:25:53.478Z,1632162353.478 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2021-09-20T18:25:53.479Z,1632162353.479 [CTD_Seabird](INFO): Powering down
2021-09-20T18:25:53.491Z,1632162353.491 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-09-20T18:25:53.511Z,1632162353.511 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2021-09-20T18:25:53.511Z,1632162353.511 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-09-20T18:25:53.511Z,1632162353.511 [Radio_Surface](INFO): Join timeout helper Thread ID is 3229
2021-09-20T18:25:53.791Z,1632162353.791 [Radio_Surface](INFO): Powering down
2021-09-20T18:25:53.792Z,1632162353.792 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2021-09-20T18:25:53.792Z,1632162353.792 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-09-20T18:25:53.799Z,1632162353.799 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2021-09-20T18:25:53.800Z,1632162353.800 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-09-20T18:25:53.800Z,1632162353.800 [Onboard](INFO): Join timeout helper Thread ID is 3230
2021-09-20T18:25:54.791Z,1632162354.791 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2021-09-20T18:25:56.252Z,1632162356.252 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2021-09-20T18:25:56.253Z,1632162356.253 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-09-20T18:25:56.260Z,1632162356.260 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2021-09-20T18:25:56.260Z,1632162356.260 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-09-20T18:25:56.261Z,1632162356.261 [DataOverHttps](INFO): Join timeout helper Thread ID is 3231
2021-09-20T18:25:56.799Z,1632162356.799 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2021-09-20T18:25:56.799Z,1632162356.799 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-09-20T18:25:56.803Z,1632162356.803 [ComponentRegistry](INFO): Shutting down BackseatComponent ThreadHandler
2021-09-20T18:25:56.803Z,1632162356.803 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2021-09-20T18:25:56.804Z,1632162356.804 [BackseatComponent](INFO): Join timeout helper Thread ID is 3232
2021-09-20T18:25:56.931Z,1632162356.931 [BackseatComponent ThreadHandler](INFO): Uninitializing protected caller thread.
2021-09-20T18:25:56.931Z,1632162356.931 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2021-09-20T18:25:56.947Z,1632162356.947 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2021-09-20T18:25:56.948Z,1632162356.948 [logger ThreadHandler](INFO): Thread cancelled.
2021-09-20T18:25:56.948Z,1632162356.948 [logger](INFO): Join timeout helper Thread ID is 3233
2021-09-20T18:25:56.967Z,1632162356.967 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2021-09-20T18:25:56.967Z,1632162356.967 [logger ThreadHandler](INFO): Thread cancelled.
2021-09-20T18:25:56.988Z,1632162356.988 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2021-09-20T18:25:56.988Z,1632162356.988 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-09-20T18:25:56.989Z,1632162356.989 [CommandLine](INFO): Join timeout helper Thread ID is 3234
2021-09-20T18:25:57.051Z,1632162357.051 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2021-09-20T18:25:57.051Z,1632162357.051 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-09-20T18:25:57.067Z,1632162357.067 [ComponentRegistry](INFO): Shutting down CommandExec ThreadHandler
2021-09-20T18:25:57.067Z,1632162357.067 [CommandExec ThreadHandler](INFO): Thread cancelled.
2021-09-20T18:25:57.068Z,1632162357.068 [CommandExec](INFO): Join timeout helper Thread ID is 3235
2021-09-20T18:25:57.069Z,1632162357.069 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2021-09-20T18:25:57.069Z,1632162357.069 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-09-20T18:25:57.069Z,1632162357.069 [controlThread](INFO): Join timeout helper Thread ID is 3236
2021-09-20T18:25:57.104Z,1632162357.104 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2021-09-20T18:25:57.104Z,1632162357.104 [controlThread](DEBUG): Uninitializing ControlThread
2021-09-20T18:25:57.104Z,1632162357.104 [AHRS_M2](INFO): Powering down
2021-09-20T18:25:57.176Z,1632162357.176 [NAL9602](INFO): Powering down
2021-09-20T18:25:57.178Z,1632162357.178 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2021-09-20T18:25:57.179Z,1632162357.179 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2021-09-20T18:25:57.179Z,1632162357.179 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2021-09-20T18:25:57.180Z,1632162357.180 [MissionManager](INFO): Uninitializing Mission Default
2021-09-20T18:25:57.183Z,1632162357.183 [MissionManager](INFO): Uninitializing Mission Startup
2021-09-20T18:25:57.185Z,1632162357.185 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2021-09-20T18:25:57.185Z,1632162357.185 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2021-09-20T18:25:57.185Z,1632162357.185 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2021-09-20T18:25:57.186Z,1632162357.186 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2021-09-20T18:25:57.186Z,1632162357.186 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2021-09-20T18:25:57.186Z,1632162357.186 [BuoyancyServo](INFO): Powering down
2021-09-20T18:25:57.199Z,1632162357.199 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2021-09-20T18:25:57.199Z,1632162357.199 [ElevatorServo](INFO): Powering down
2021-09-20T18:25:57.200Z,1632162357.200 [MassServo](DEBUG): Uninitialize Mass Servo.
2021-09-20T18:25:57.200Z,1632162357.200 [MassServo](INFO): Powering down
2021-09-20T18:25:57.201Z,1632162357.201 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-09-20T18:25:57.201Z,1632162357.201 [RudderServo](INFO): Powering down
2021-09-20T18:25:57.202Z,1632162357.202 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2021-09-20T18:25:57.202Z,1632162357.202 [ThrusterServo](INFO): Powering down
2021-09-20T18:25:57.203Z,1632162357.203 [SBIT](DEBUG): Uninitialize SBIT Component.
2021-09-20T18:25:57.203Z,1632162357.203 [IBIT](DEBUG): Uninitialize IBIT Component.
2021-09-20T18:25:57.203Z,1632162357.203 [CBIT](DEBUG): Uninitialize CBIT Component.
2021-09-20T18:25:57.204Z,1632162357.204 [CBIT](DEBUG): Powering off loads.
2021-09-20T18:25:57.214Z,1632162357.214 [CBIT](DEBUG): Disabling WDT.
2021-09-20T18:25:57.226Z,1632162357.226 [CBIT](DEBUG): Opening all GF detection circuits.
2021-09-20T18:25:57.228Z,1632162357.228 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-09-20T18:25:57.289Z,1632162357.289 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-09-20T18:25:57.292Z,1632162357.292 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-09-20T18:25:57.297Z,1632162357.297 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-09-20T18:25:57.341Z,1632162357.341 [BackseatComponent ThreadHandler](INFO): Thread cancelled.
2021-09-20T18:25:57.512Z,1632162357.512 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled.
2021-09-20T18:25:57.514Z,1632162357.514 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-09-20T18:25:57.517Z,1632162357.517 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-09-20T18:25:57.525Z,1632162357.525 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-09-20T18:25:57.574Z,1632162357.574 [logger ThreadHandler](INFO): Thread cancelled.