2020-03-12T16:49:14.584Z,1584031754.584 [Supervisor](DEBUG): Initializing supervisor.
2020-03-12T16:49:14.587Z,1584031754.587 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-03-12T16:49:14.587Z,1584031754.587 [SyncHandler](INFO): Protected caller Thread ID is 822
2020-03-12T16:49:14.588Z,1584031754.588 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-03-12T16:49:14.589Z,1584031754.589 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-03-12T16:49:14.589Z,1584031754.589 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 823
2020-03-12T16:49:14.592Z,1584031754.592 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-03-12T16:49:14.604Z,1584031754.604 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-03-12T16:49:14.605Z,1584031754.605 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-03-12T16:49:14.606Z,1584031754.606 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 824
2020-03-12T16:49:14.606Z,1584031754.606 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-03-12T16:49:14.607Z,1584031754.607 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-03-12T16:49:14.608Z,1584031754.608 [logger ThreadHandler](INFO): Protected caller Thread ID is 825
2020-03-12T16:49:14.610Z,1584031754.610 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-03-12T16:49:14.610Z,1584031754.610 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-03-12T16:49:14.614Z,1584031754.614 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-03-12T16:49:14.761Z,1584031754.761 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-03-12T16:49:14.762Z,1584031754.762 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-03-12T16:49:14.961Z,1584031754.961 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-03-12T16:49:14.962Z,1584031754.962 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-03-12T16:49:15.098Z,1584031755.098 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-03-12T16:49:15.100Z,1584031755.100 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-03-12T16:49:15.680Z,1584031755.680 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-03-12T16:49:15.681Z,1584031755.681 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-03-12T16:49:16.159Z,1584031756.159 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-03-12T16:49:16.160Z,1584031756.160 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-03-12T16:49:16.619Z,1584031756.619 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-03-12T16:49:16.621Z,1584031756.621 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-03-12T16:49:16.915Z,1584031756.915 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-03-12T16:49:16.916Z,1584031756.916 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-03-12T16:49:17.232Z,1584031757.232 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-03-12T16:49:17.234Z,1584031757.234 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-03-12T16:49:17.670Z,1584031757.670 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-03-12T16:49:17.671Z,1584031757.671 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-03-12T16:49:18.161Z,1584031758.161 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-03-12T16:49:18.163Z,1584031758.163 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-03-12T16:49:18.268Z,1584031758.268 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-03-12T16:49:18.268Z,1584031758.268 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-03-12T16:49:18.349Z,1584031758.349 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-03-12T16:49:18.450Z,1584031758.450 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-03-12T16:49:18.452Z,1584031758.452 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-03-12T16:49:18.643Z,1584031758.643 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-03-12T16:49:18.645Z,1584031758.645 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-03-12T16:49:18.854Z,1584031758.854 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-03-12T16:49:18.856Z,1584031758.856 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2020-03-12T16:49:18.860Z,1584031758.860 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2020-03-12T16:49:18.942Z,1584031758.942 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2020-03-12T16:49:19.173Z,1584031759.173 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-03-12T16:49:19.173Z,1584031759.173 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2020-03-12T16:49:19.269Z,1584031759.269 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2020-03-12T16:49:19.435Z,1584031759.435 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2020-03-12T16:49:19.640Z,1584031759.640 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2020-03-12T16:49:19.726Z,1584031759.726 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2020-03-12T16:49:19.826Z,1584031759.826 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2020-03-12T16:49:19.918Z,1584031759.918 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2020-03-12T16:49:20.073Z,1584031760.073 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2020-03-12T16:49:20.178Z,1584031760.178 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2020-03-12T16:49:20.277Z,1584031760.277 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-03-12T16:49:20.293Z,1584031760.293 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-03-12T16:49:20.423Z,1584031760.423 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-03-12T16:49:20.423Z,1584031760.423 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-03-12T16:49:20.584Z,1584031760.584 [BuoyancyServo] Loaded
2020-03-12T16:49:20.584Z,1584031760.584 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-03-12T16:49:20.600Z,1584031760.600 [ElevatorServo] Loaded
2020-03-12T16:49:20.600Z,1584031760.600 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-03-12T16:49:20.617Z,1584031760.617 [MassServo] Loaded
2020-03-12T16:49:20.617Z,1584031760.617 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-03-12T16:49:20.632Z,1584031760.632 [RudderServo] Loaded
2020-03-12T16:49:20.633Z,1584031760.633 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-03-12T16:49:20.647Z,1584031760.647 [ThrusterServo] Loaded
2020-03-12T16:49:20.647Z,1584031760.647 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-03-12T16:49:20.648Z,1584031760.648 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-03-12T16:49:20.649Z,1584031760.649 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-03-12T16:49:20.815Z,1584031760.815 [SBIT](DEBUG): Construct Startup Built In Test.
2020-03-12T16:49:20.844Z,1584031760.844 [SBIT] Loaded
2020-03-12T16:49:20.844Z,1584031760.844 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-03-12T16:49:20.845Z,1584031760.845 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-03-12T16:49:20.875Z,1584031760.875 [IBIT] Loaded
2020-03-12T16:49:20.875Z,1584031760.875 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-03-12T16:49:20.878Z,1584031760.878 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-03-12T16:49:21.281Z,1584031761.281 [CBIT] Loaded
2020-03-12T16:49:21.281Z,1584031761.281 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-03-12T16:49:21.281Z,1584031761.281 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-03-12T16:49:21.282Z,1584031761.282 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-03-12T16:49:21.573Z,1584031761.573 [ESPComponent] Loaded
2020-03-12T16:49:21.574Z,1584031761.574 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2020-03-12T16:49:21.589Z,1584031761.589 [PAR_Licor] Loaded
2020-03-12T16:49:21.589Z,1584031761.589 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-03-12T16:49:21.595Z,1584031761.595 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-03-12T16:49:21.596Z,1584031761.596 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-03-12T16:49:21.601Z,1584031761.601 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-03-12T16:49:21.602Z,1584031761.602 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-03-12T16:49:21.606Z,1584031761.606 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-03-12T16:49:21.606Z,1584031761.606 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-03-12T16:49:21.611Z,1584031761.611 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-03-12T16:49:21.611Z,1584031761.611 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-03-12T16:49:21.615Z,1584031761.615 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-03-12T16:49:21.615Z,1584031761.615 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-03-12T16:49:21.620Z,1584031761.620 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-03-12T16:49:21.620Z,1584031761.620 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-03-12T16:49:21.625Z,1584031761.625 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-03-12T16:49:21.625Z,1584031761.625 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-03-12T16:49:21.629Z,1584031761.629 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-03-12T16:49:21.634Z,1584031761.634 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-03-12T16:49:21.635Z,1584031761.635 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-03-12T16:49:21.635Z,1584031761.635 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-03-12T16:49:21.639Z,1584031761.639 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-03-12T16:49:21.639Z,1584031761.639 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-03-12T16:49:21.644Z,1584031761.644 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-03-12T16:49:21.644Z,1584031761.644 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-03-12T16:49:21.649Z,1584031761.649 [WetLabsBB2FL] Loaded
2020-03-12T16:49:21.649Z,1584031761.649 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-03-12T16:49:21.650Z,1584031761.650 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4066C4E0
2020-03-12T16:49:21.650Z,1584031761.650 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 904
2020-03-12T16:49:21.651Z,1584031761.651 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-03-12T16:49:21.652Z,1584031761.652 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-03-12T16:49:21.708Z,1584031761.708 [DepthRateCalculator] Loaded
2020-03-12T16:49:21.708Z,1584031761.708 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-03-12T16:49:21.714Z,1584031761.714 [PitchRateCalculator] Loaded
2020-03-12T16:49:21.714Z,1584031761.714 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-03-12T16:49:21.730Z,1584031761.730 [SpeedCalculator] Loaded
2020-03-12T16:49:21.730Z,1584031761.730 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-03-12T16:49:21.752Z,1584031761.752 [TempGradientCalculator] Loaded
2020-03-12T16:49:21.752Z,1584031761.752 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-03-12T16:49:21.759Z,1584031761.759 [YawRateCalculator] Loaded
2020-03-12T16:49:21.759Z,1584031761.759 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-03-12T16:49:21.793Z,1584031761.793 [ElevatorOffsetCalculator] Loaded
2020-03-12T16:49:21.793Z,1584031761.793 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-03-12T16:49:21.793Z,1584031761.793 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-03-12T16:49:21.795Z,1584031761.795 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-03-12T16:49:21.844Z,1584031761.844 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-03-12T16:49:21.845Z,1584031761.845 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-03-12T16:49:21.968Z,1584031761.968 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-03-12T16:49:21.969Z,1584031761.969 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-03-12T16:49:21.991Z,1584031761.991 [NavChart] Loaded
2020-03-12T16:49:21.991Z,1584031761.991 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-03-12T16:49:21.995Z,1584031761.995 [UniversalFixResidualReporter] Loaded
2020-03-12T16:49:21.995Z,1584031761.995 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-03-12T16:49:21.996Z,1584031761.996 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-03-12T16:49:21.996Z,1584031761.996 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-03-12T16:49:22.989Z,1584031762.989 [DataOverHttps] Loaded
2020-03-12T16:49:22.989Z,1584031762.989 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-03-12T16:49:22.990Z,1584031762.990 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4095B4E0
2020-03-12T16:49:22.991Z,1584031762.991 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 905
2020-03-12T16:49:23.005Z,1584031763.005 [Depth_Keller] Loaded
2020-03-12T16:49:23.005Z,1584031763.005 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-03-12T16:49:23.010Z,1584031763.010 [DropWeight] Loaded
2020-03-12T16:49:23.010Z,1584031763.010 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-03-12T16:49:23.104Z,1584031763.104 [NAL9602] Loaded
2020-03-12T16:49:23.105Z,1584031763.105 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-03-12T16:49:23.135Z,1584031763.135 [Onboard] Loaded
2020-03-12T16:49:23.135Z,1584031763.135 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2020-03-12T16:49:23.138Z,1584031763.138 [Radio_Surface] Loaded
2020-03-12T16:49:23.139Z,1584031763.139 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-03-12T16:49:23.140Z,1584031763.140 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4098B4E0
2020-03-12T16:49:23.140Z,1584031763.140 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 906
2020-03-12T16:49:23.546Z,1584031763.546 [DAT] Loaded
2020-03-12T16:49:23.547Z,1584031763.547 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2020-03-12T16:49:25.276Z,1584031765.276 [BPC1] Loaded
2020-03-12T16:49:25.276Z,1584031765.276 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-03-12T16:49:25.277Z,1584031765.277 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-03-12T16:49:25.277Z,1584031765.277 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-03-12T16:49:25.404Z,1584031765.404 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-03-12T16:49:25.404Z,1584031765.404 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-03-12T16:49:25.470Z,1584031765.470 [VerticalControl](DEBUG): Construct VerticalControl.
2020-03-12T16:49:25.556Z,1584031765.556 [VerticalControl] Loaded
2020-03-12T16:49:25.556Z,1584031765.556 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-03-12T16:49:25.557Z,1584031765.557 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-03-12T16:49:25.630Z,1584031765.630 [HorizontalControl] Loaded
2020-03-12T16:49:25.630Z,1584031765.630 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-03-12T16:49:25.631Z,1584031765.631 [SpeedControl](DEBUG): Construct SpeedControl.
2020-03-12T16:49:25.632Z,1584031765.632 [SpeedControl] Loaded
2020-03-12T16:49:25.633Z,1584031765.633 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-03-12T16:49:25.633Z,1584031765.633 [LoopControl](DEBUG): Construct LoopControl.
2020-03-12T16:49:25.634Z,1584031765.634 [LoopControl] Loaded
2020-03-12T16:49:25.634Z,1584031765.634 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-03-12T16:49:25.634Z,1584031765.634 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-03-12T16:49:25.635Z,1584031765.635 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-03-12T16:49:25.659Z,1584031765.659 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-03-12T16:49:25.660Z,1584031765.660 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-03-12T16:49:26.033Z,1584031766.033 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-03-12T16:49:26.037Z,1584031766.037 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-03-12T16:49:26.038Z,1584031766.038 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-03-12T16:49:26.044Z,1584031766.044 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-03-12T16:49:26.045Z,1584031766.045 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACA4E0
2020-03-12T16:49:26.046Z,1584031766.046 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 907
2020-03-12T16:49:26.050Z,1584031766.050 [Supervisor](INFO): Main Thread ID is 818
2020-03-12T16:49:26.050Z,1584031766.050 [Supervisor](DEBUG): Running supervisor.
2020-03-12T16:49:26.051Z,1584031766.051 [CommandLine ThreadHandler](INFO): Handler Thread ID is 908
2020-03-12T16:49:26.053Z,1584031766.053 [controlThread ThreadHandler](INFO): Handler Thread ID is 909
2020-03-12T16:49:26.054Z,1584031766.054 [controlThread](DEBUG): Initializing ControlThread
2020-03-12T16:49:26.055Z,1584031766.055 [SBIT](INFO): Initialize SBIT Component.
2020-03-12T16:49:26.056Z,1584031766.056 [SBIT](IMPORTANT): git: 2020-02-26-4-ge87e28be4
2020-03-12T16:49:26.056Z,1584031766.056 [SBIT](INFO): git hash: e87e28be4f0b70038b19136bd27a833bbcc93acc
2020-03-12T16:49:26.057Z,1584031766.057 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-03-12T16:49:26.058Z,1584031766.058 [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
2020-03-12T16:49:26.059Z,1584031766.059 [SBIT](INFO): Beginning SBIT in 72.000000 seconds.
2020-03-12T16:49:26.060Z,1584031766.060 [IBIT](INFO): Initialize IBIT Component.
2020-03-12T16:49:26.060Z,1584031766.060 [CBIT](DEBUG): Initialize CBIT Component.
2020-03-12T16:49:26.061Z,1584031766.061 [logger ThreadHandler](INFO): Handler Thread ID is 910
2020-03-12T16:49:26.073Z,1584031766.073 [CBIT](DEBUG): Initialized mux pins.
2020-03-12T16:49:26.073Z,1584031766.073 [CBIT](DEBUG): Initializing the watchdog timer.
2020-03-12T16:49:26.081Z,1584031766.081 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 911
2020-03-12T16:49:26.082Z,1584031766.082 [WetLabsBB2FL](INFO): Powering down
2020-03-12T16:49:26.097Z,1584031766.097 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-03-12T16:49:26.097Z,1584031766.097 [CBIT](DEBUG): Initializing heartbeat.
2020-03-12T16:49:26.169Z,1584031766.169 [CBIT](DEBUG): Deactivating GF circuits.
2020-03-12T16:49:26.169Z,1584031766.169 [CBIT](DEBUG): Deactivating emergency mode.
2020-03-12T16:49:26.209Z,1584031766.209 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 912
2020-03-12T16:49:26.210Z,1584031766.210 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-03-12T16:49:26.230Z,1584031766.230 [CBIT](DEBUG): Backplane powered.
2020-03-12T16:49:26.238Z,1584031766.238 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-03-12T16:49:26.240Z,1584031766.240 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-03-12T16:49:26.241Z,1584031766.241 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-03-12T16:49:26.243Z,1584031766.243 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-03-12T16:49:26.245Z,1584031766.245 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-03-12T16:49:26.245Z,1584031766.245 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-03-12T16:49:26.246Z,1584031766.246 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-03-12T16:49:26.247Z,1584031766.247 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-03-12T16:49:26.261Z,1584031766.261 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-03-12T16:49:26.263Z,1584031766.263 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-03-12T16:49:26.264Z,1584031766.264 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-03-12T16:49:26.264Z,1584031766.264 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-03-12T16:49:26.265Z,1584031766.265 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-03-12T16:49:26.285Z,1584031766.285 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 913
2020-03-12T16:49:26.426Z,1584031766.426 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 915
2020-03-12T16:49:26.438Z,1584031766.438 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-03-12T16:49:26.438Z,1584031766.438 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-03-12T16:49:26.438Z,1584031766.438 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-03-12T16:49:26.438Z,1584031766.438 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-03-12T16:49:26.439Z,1584031766.439 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-03-12T16:49:26.439Z,1584031766.439 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-03-12T16:49:26.439Z,1584031766.439 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-03-12T16:49:26.439Z,1584031766.439 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-03-12T16:49:26.439Z,1584031766.439 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-03-12T16:49:26.440Z,1584031766.440 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-03-12T16:49:26.440Z,1584031766.440 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-03-12T16:49:26.440Z,1584031766.440 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-03-12T16:49:26.440Z,1584031766.440 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-03-12T16:49:26.440Z,1584031766.440 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-03-12T16:49:26.441Z,1584031766.441 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-03-12T16:49:26.441Z,1584031766.441 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-03-12T16:49:26.477Z,1584031766.477 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-03-12T16:49:26.583Z,1584031766.583 [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>


2020-03-12T16:49:26.593Z,1584031766.593 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-03-12T16:49:26.689Z,1584031766.689 [Radio_Surface](INFO): Powering up
2020-03-12T16:49:26.738Z,1584031766.738 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-03-12T16:49:26.768Z,1584031766.768 [Default:A.Wait](DEBUG): Construct Wait.
2020-03-12T16:49:26.770Z,1584031766.770 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-03-12T16:49:26.842Z,1584031766.842 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-03-12T16:49:26.845Z,1584031766.845 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-03-12T16:49:26.860Z,1584031766.860 [Default:E.Execute](DEBUG): Construct Execute.
2020-03-12T16:49:26.864Z,1584031766.864 [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>


2020-03-12T16:49:26.881Z,1584031766.881 [controlThread](DEBUG): Component order: CycleStarter,ESPComponent,PAR_Licor,Depth_Keller,DropWeight,NAL9602,Onboard,DAT,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-03-12T16:49:27.197Z,1584031767.197 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-03-12T16:49:27.197Z,1584031767.197 [DAT](INFO): Powering up
2020-03-12T16:49:27.197Z,1584031767.197 [DAT](DEBUG): Initializing DAT.
2020-03-12T16:49:27.257Z,1584031767.257 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-03-12T16:49:27.584Z,1584031767.584 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-03-12T16:49:27.589Z,1584031767.589 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-03-12T16:49:27.590Z,1584031767.590 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-03-12T16:49:27.597Z,1584031767.597 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-03-12T16:49:27.598Z,1584031767.598 [MassServo](DEBUG): Initializing EZServoServo.
2020-03-12T16:49:27.605Z,1584031767.605 [MassServo](DEBUG): Initializing MassServo.
2020-03-12T16:49:27.606Z,1584031767.606 [RudderServo](DEBUG): Initializing EZServoServo.
2020-03-12T16:49:27.617Z,1584031767.617 [RudderServo](DEBUG): Initializing RudderServo.
2020-03-12T16:49:27.618Z,1584031767.618 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-03-12T16:49:27.625Z,1584031767.625 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-03-12T16:49:27.720Z,1584031767.720 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-03-12T16:49:27.720Z,1584031767.720 [DropWeight] Hardware Fault, FailCount= 1
2020-03-12T16:49:27.720Z,1584031767.720 [DropWeight](ERROR): Hardware Fault
2020-03-12T16:49:27.779Z,1584031767.779 [CommandLine](FAULT): Scheduling is paused
2020-03-12T16:49:27.779Z,1584031767.779 [CBIT](INFO): Critical error at 20200312T164927
2020-03-12T16:49:27.779Z,1584031767.779 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-03-12T16:49:27.782Z,1584031767.782 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-03-12T16:49:27.782Z,1584031767.782 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-03-12T16:49:28.100Z,1584031768.100 [CBIT](INFO): Critical error at 20200312T164927
2020-03-12T16:49:33.997Z,1584031773.997 [CommandLine](IMPORTANT): got command quit
2020-03-12T16:49:35.001Z,1584031775.001 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-03-12T16:49:35.001Z,1584031775.001 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-03-12T16:49:35.037Z,1584031775.037 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-03-12T16:49:35.037Z,1584031775.037 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-03-12T16:49:35.060Z,1584031775.060 [CommandLine](INFO): Join timeout helper Thread ID is 924
2020-03-12T16:49:35.077Z,1584031775.077 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-03-12T16:49:35.077Z,1584031775.077 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-03-12T16:49:35.077Z,1584031775.077 [NavChartDb](INFO): Join timeout helper Thread ID is 925
2020-03-12T16:49:38.583Z,1584031778.583 [DAT](DEBUG): 
2020-03-12T16:49:38.584Z,1584031778.584 [DAT](DEBUG): Teledyne Benthos DAT-900 Series
2020-03-12T16:49:40.193Z,1584031780.193 [DAT](DEBUG): MF Frequency Band
2020-03-12T16:49:40.194Z,1584031780.194 [DAT](DEBUG): Directional Acoustic Transponder version 8.14.0
2020-03-12T16:49:40.194Z,1584031780.194 [DAT](DEBUG): Mar 12 2020 16:39:49 
2020-03-12T16:49:40.995Z,1584031780.995 [DAT](DEBUG): Features enabled [Bearing]
2020-03-12T16:49:41.397Z,1584031781.397 [DAT](DEBUG): CONNECT 00800 bits/sec
2020-03-12T16:49:41.398Z,1584031781.398 [DAT](INFO): commRate: 800
2020-03-12T16:49:41.801Z,1584031781.801 [DAT](INFO): entering command mode
2020-03-12T16:49:42.213Z,1584031782.213 [DAT](DEBUG): checking for command mode acknowledgment
2020-03-12T16:49:42.608Z,1584031782.608 [DAT](DEBUG): checking for command mode acknowledgment
2020-03-12T16:49:42.608Z,1584031782.608 [DAT](INFO): command mode acknowledged
2020-03-12T16:49:42.609Z,1584031782.609 [DAT](INFO): setting verbose to 3
2020-03-12T16:49:42.788Z,1584031782.788 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-03-12T16:49:42.788Z,1584031782.788 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-03-12T16:49:42.789Z,1584031782.789 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-03-12T16:49:42.789Z,1584031782.789 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-03-12T16:49:42.789Z,1584031782.789 [Radio_Surface](INFO): Join timeout helper Thread ID is 926
2020-03-12T16:49:42.853Z,1584031782.853 [Radio_Surface](INFO): Powering down
2020-03-12T16:49:42.854Z,1584031782.854 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-03-12T16:49:42.854Z,1584031782.854 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-03-12T16:49:42.869Z,1584031782.869 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-03-12T16:49:42.869Z,1584031782.869 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-03-12T16:49:42.869Z,1584031782.869 [DataOverHttps](INFO): Join timeout helper Thread ID is 927
2020-03-12T16:49:43.012Z,1584031783.012 [DAT](DEBUG): checking for verbose setting acknowledgment
2020-03-12T16:49:43.013Z,1584031783.013 [DAT](INFO): set verbose to 3
2020-03-12T16:49:43.277Z,1584031783.277 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-03-12T16:49:43.277Z,1584031783.277 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-03-12T16:49:43.281Z,1584031783.281 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-03-12T16:49:43.281Z,1584031783.281 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-03-12T16:49:43.281Z,1584031783.281 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 928
2020-03-12T16:49:43.345Z,1584031783.345 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-03-12T16:49:43.345Z,1584031783.345 [WetLabsBB2FL](INFO): Powering down
2020-03-12T16:49:43.346Z,1584031783.346 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-03-12T16:49:43.354Z,1584031783.354 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-03-12T16:49:43.354Z,1584031783.354 [logger ThreadHandler](INFO): Thread cancelled.
2020-03-12T16:49:43.354Z,1584031783.354 [logger](INFO): Join timeout helper Thread ID is 929
2020-03-12T16:49:43.359Z,1584031783.359 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-03-12T16:49:43.359Z,1584031783.359 [logger ThreadHandler](INFO): Thread cancelled.
2020-03-12T16:49:43.361Z,1584031783.361 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-03-12T16:49:43.361Z,1584031783.361 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-03-12T16:49:43.361Z,1584031783.361 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-03-12T16:49:43.361Z,1584031783.361 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-03-12T16:49:43.362Z,1584031783.362 [controlThread](INFO): Join timeout helper Thread ID is 930
2020-03-12T16:49:43.389Z,1584031783.389 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-03-12T16:49:43.389Z,1584031783.389 [controlThread](DEBUG): Uninitializing ControlThread
2020-03-12T16:49:43.411Z,1584031783.411 [NAL9602](INFO): Powering down
2020-03-12T16:49:43.431Z,1584031783.431 [DAT](INFO): Powering down
2020-03-12T16:49:43.570Z,1584031783.570 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-03-12T16:49:43.571Z,1584031783.571 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-03-12T16:49:43.571Z,1584031783.571 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-03-12T16:49:43.572Z,1584031783.572 [MissionManager](INFO): Uninitializing Mission Default
2020-03-12T16:49:43.574Z,1584031783.574 [MissionManager](INFO): Uninitializing Mission Startup
2020-03-12T16:49:43.575Z,1584031783.575 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-03-12T16:49:43.576Z,1584031783.576 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-03-12T16:49:43.576Z,1584031783.576 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-03-12T16:49:43.576Z,1584031783.576 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-03-12T16:49:43.576Z,1584031783.576 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-03-12T16:49:43.577Z,1584031783.577 [BuoyancyServo](INFO): Powering down
2020-03-12T16:49:43.589Z,1584031783.589 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-03-12T16:49:43.589Z,1584031783.589 [ElevatorServo](INFO): Powering down
2020-03-12T16:49:43.609Z,1584031783.609 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-03-12T16:49:43.609Z,1584031783.609 [MassServo](INFO): Powering down
2020-03-12T16:49:43.629Z,1584031783.629 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-03-12T16:49:43.629Z,1584031783.629 [RudderServo](INFO): Powering down
2020-03-12T16:49:43.630Z,1584031783.630 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-03-12T16:49:43.630Z,1584031783.630 [ThrusterServo](INFO): Powering down
2020-03-12T16:49:43.631Z,1584031783.631 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-03-12T16:49:43.631Z,1584031783.631 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-03-12T16:49:43.631Z,1584031783.631 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-03-12T16:49:43.631Z,1584031783.631 [CBIT](DEBUG): Powering off loads.
2020-03-12T16:49:43.645Z,1584031783.645 [CBIT](DEBUG): Disabling WDT.
2020-03-12T16:49:43.656Z,1584031783.656 [CBIT](DEBUG): Opening all GF detection circuits.
2020-03-12T16:49:43.657Z,1584031783.657 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-03-12T16:49:43.933Z,1584031783.933 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-03-12T16:49:43.000Z,1584031784.000 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-03-12T16:49:44.005Z,1584031784.005 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-03-12T16:49:44.053Z,1584031784.053 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-03-12T16:49:44.099Z,1584031784.099 [logger ThreadHandler](INFO): Thread cancelled.