2019-04-16T21:36:35.798Z,1555450595.798 [Supervisor](DEBUG): Initializing supervisor.
2019-04-16T21:36:35.801Z,1555450595.801 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-04-16T21:36:35.802Z,1555450595.802 [SyncHandler](INFO): Protected caller Thread ID is 6865
2019-04-16T21:36:35.802Z,1555450595.802 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-04-16T21:36:35.803Z,1555450595.803 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-04-16T21:36:35.804Z,1555450595.804 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6866
2019-04-16T21:36:35.807Z,1555450595.807 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-04-16T21:36:35.820Z,1555450595.820 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-04-16T21:36:35.821Z,1555450595.821 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-04-16T21:36:35.821Z,1555450595.821 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6867
2019-04-16T21:36:35.822Z,1555450595.822 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-04-16T21:36:35.823Z,1555450595.823 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-04-16T21:36:35.824Z,1555450595.824 [logger ThreadHandler](INFO): Protected caller Thread ID is 6868
2019-04-16T21:36:35.826Z,1555450595.826 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-04-16T21:36:35.826Z,1555450595.826 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-04-16T21:36:35.828Z,1555450595.828 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-04-16T21:36:36.314Z,1555450596.314 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-04-16T21:36:36.315Z,1555450596.315 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-04-16T21:36:36.459Z,1555450596.459 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-04-16T21:36:36.460Z,1555450596.460 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-04-16T21:36:36.541Z,1555450596.541 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-04-16T21:36:36.640Z,1555450596.640 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-04-16T21:36:36.641Z,1555450596.641 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-04-16T21:36:36.737Z,1555450596.737 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-04-16T21:36:36.737Z,1555450596.737 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-04-16T21:36:37.124Z,1555450597.124 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-04-16T21:36:37.124Z,1555450597.124 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-04-16T21:36:37.228Z,1555450597.228 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-04-16T21:36:37.229Z,1555450597.229 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-04-16T21:36:37.687Z,1555450597.687 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-04-16T21:36:37.687Z,1555450597.687 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-04-16T21:36:37.891Z,1555450597.891 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-04-16T21:36:37.892Z,1555450597.892 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-04-16T21:36:38.196Z,1555450598.196 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-04-16T21:36:38.197Z,1555450598.197 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-04-16T21:36:38.410Z,1555450598.410 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-04-16T21:36:38.410Z,1555450598.410 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-04-16T21:36:38.553Z,1555450598.553 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-04-16T21:36:38.554Z,1555450598.554 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-04-16T21:36:39.084Z,1555450599.084 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-04-16T21:36:39.084Z,1555450599.084 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-04-16T21:36:39.418Z,1555450599.418 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-04-16T21:36:39.419Z,1555450599.419 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-04-16T21:36:39.626Z,1555450599.626 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-04-16T21:36:39.628Z,1555450599.628 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2019-04-16T21:36:39.629Z,1555450599.629 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2019-04-16T21:36:39.891Z,1555450599.891 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2019-04-16T21:36:40.018Z,1555450600.018 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2019-04-16T21:36:40.237Z,1555450600.237 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2019-04-16T21:36:40.390Z,1555450600.390 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2019-04-16T21:36:40.714Z,1555450600.714 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-04-16T21:36:40.715Z,1555450600.715 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2019-04-16T21:36:40.810Z,1555450600.810 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2019-04-16T21:36:40.920Z,1555450600.920 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2019-04-16T21:36:41.015Z,1555450601.015 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2019-04-16T21:36:41.163Z,1555450601.163 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2019-04-16T21:36:41.264Z,1555450601.264 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2019-04-16T21:36:41.364Z,1555450601.364 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2019-04-16T21:36:41.364Z,1555450601.364 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-04-16T21:36:41.371Z,1555450601.371 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-04-16T21:36:41.385Z,1555450601.385 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-04-16T21:36:41.386Z,1555450601.386 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-04-16T21:36:41.453Z,1555450601.453 [VerticalControl](DEBUG): Construct VerticalControl.
2019-04-16T21:36:41.568Z,1555450601.568 [VerticalControl] Loaded
2019-04-16T21:36:41.569Z,1555450601.569 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-04-16T21:36:41.569Z,1555450601.569 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-04-16T21:36:41.640Z,1555450601.640 [HorizontalControl] Loaded
2019-04-16T21:36:41.640Z,1555450601.640 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-04-16T21:36:41.641Z,1555450601.641 [SpeedControl](DEBUG): Construct SpeedControl.
2019-04-16T21:36:41.647Z,1555450601.647 [SpeedControl] Loaded
2019-04-16T21:36:41.647Z,1555450601.647 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-04-16T21:36:41.648Z,1555450601.648 [LoopControl](DEBUG): Construct LoopControl.
2019-04-16T21:36:41.648Z,1555450601.648 [LoopControl] Loaded
2019-04-16T21:36:41.648Z,1555450601.648 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-04-16T21:36:41.649Z,1555450601.649 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-04-16T21:36:41.649Z,1555450601.649 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-04-16T21:36:41.773Z,1555450601.773 [BuoyancyServo] Loaded
2019-04-16T21:36:41.774Z,1555450601.774 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-04-16T21:36:41.785Z,1555450601.785 [ElevatorServo] Loaded
2019-04-16T21:36:41.785Z,1555450601.785 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-04-16T21:36:41.796Z,1555450601.796 [MassServo] Loaded
2019-04-16T21:36:41.796Z,1555450601.796 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-04-16T21:36:41.807Z,1555450601.807 [RudderServo] Loaded
2019-04-16T21:36:41.808Z,1555450601.808 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-04-16T21:36:41.819Z,1555450601.819 [ThrusterServo] Loaded
2019-04-16T21:36:41.819Z,1555450601.819 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-04-16T21:36:41.819Z,1555450601.819 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-04-16T21:36:41.820Z,1555450601.820 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-04-16T21:36:41.861Z,1555450601.861 [DepthRateCalculator] Loaded
2019-04-16T21:36:41.861Z,1555450601.861 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-04-16T21:36:41.866Z,1555450601.866 [PitchRateCalculator] Loaded
2019-04-16T21:36:41.866Z,1555450601.866 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-04-16T21:36:41.882Z,1555450601.882 [SpeedCalculator] Loaded
2019-04-16T21:36:41.882Z,1555450601.882 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-04-16T21:36:41.904Z,1555450601.904 [TempGradientCalculator] Loaded
2019-04-16T21:36:41.904Z,1555450601.904 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-04-16T21:36:41.909Z,1555450601.909 [YawRateCalculator] Loaded
2019-04-16T21:36:41.909Z,1555450601.909 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-04-16T21:36:41.939Z,1555450601.939 [ElevatorOffsetCalculator] Loaded
2019-04-16T21:36:41.940Z,1555450601.940 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-04-16T21:36:41.940Z,1555450601.940 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-04-16T21:36:41.941Z,1555450601.941 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-04-16T21:36:42.048Z,1555450602.048 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-04-16T21:36:42.048Z,1555450602.048 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-04-16T21:36:42.069Z,1555450602.069 [NavChart] Loaded
2019-04-16T21:36:42.069Z,1555450602.069 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-04-16T21:36:42.073Z,1555450602.073 [UniversalFixResidualReporter] Loaded
2019-04-16T21:36:42.073Z,1555450602.073 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-04-16T21:36:42.074Z,1555450602.074 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-04-16T21:36:42.074Z,1555450602.074 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-04-16T21:36:42.412Z,1555450602.412 [DataOverHttps] Loaded
2019-04-16T21:36:42.412Z,1555450602.412 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-04-16T21:36:42.413Z,1555450602.413 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408044E0
2019-04-16T21:36:42.413Z,1555450602.413 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 6947
2019-04-16T21:36:42.427Z,1555450602.427 [Depth_Keller] Loaded
2019-04-16T21:36:42.427Z,1555450602.427 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-04-16T21:36:42.526Z,1555450602.526 [NAL9602] Loaded
2019-04-16T21:36:42.526Z,1555450602.526 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-04-16T21:36:42.542Z,1555450602.542 [Onboard] Loaded
2019-04-16T21:36:42.542Z,1555450602.542 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-04-16T21:36:42.546Z,1555450602.546 [Radio_Surface] Loaded
2019-04-16T21:36:42.546Z,1555450602.546 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-04-16T21:36:42.547Z,1555450602.547 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408344E0
2019-04-16T21:36:42.548Z,1555450602.548 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6948
2019-04-16T21:36:44.031Z,1555450604.031 [BPC1] Loaded
2019-04-16T21:36:44.031Z,1555450604.031 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-04-16T21:36:44.032Z,1555450604.032 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-04-16T21:36:44.032Z,1555450604.032 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-04-16T21:36:44.106Z,1555450604.106 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-04-16T21:36:44.106Z,1555450604.106 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-04-16T21:36:44.205Z,1555450604.205 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-04-16T21:36:44.205Z,1555450604.205 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-04-16T21:36:44.234Z,1555450604.234 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-04-16T21:36:44.234Z,1555450604.234 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-04-16T21:36:44.487Z,1555450604.487 [CTD_NeilBrown] Loaded
2019-04-16T21:36:44.488Z,1555450604.488 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-04-16T21:36:44.489Z,1555450604.489 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 409C04E0
2019-04-16T21:36:44.489Z,1555450604.489 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 6949
2019-04-16T21:36:44.499Z,1555450604.499 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-04-16T21:36:44.504Z,1555450604.504 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-04-16T21:36:44.506Z,1555450604.506 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-04-16T21:36:44.511Z,1555450604.511 [CTD_Seabird](INFO): created writer for : depth
2019-04-16T21:36:44.511Z,1555450604.511 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-04-16T21:36:44.516Z,1555450604.516 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-04-16T21:36:44.517Z,1555450604.517 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-04-16T21:36:44.522Z,1555450604.522 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-04-16T21:36:44.522Z,1555450604.522 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-04-16T21:36:44.527Z,1555450604.527 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-04-16T21:36:44.528Z,1555450604.528 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-04-16T21:36:44.533Z,1555450604.533 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-04-16T21:36:44.533Z,1555450604.533 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-04-16T21:36:44.538Z,1555450604.538 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-04-16T21:36:44.566Z,1555450604.566 [CTD_Seabird] Loaded
2019-04-16T21:36:44.566Z,1555450604.566 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-04-16T21:36:44.567Z,1555450604.567 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409F04E0
2019-04-16T21:36:44.568Z,1555450604.568 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 6950
2019-04-16T21:36:44.616Z,1555450604.616 [WetLabsBB2FL] Loaded
2019-04-16T21:36:44.617Z,1555450604.617 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-04-16T21:36:44.618Z,1555450604.618 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A204E0
2019-04-16T21:36:44.618Z,1555450604.618 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6951
2019-04-16T21:36:44.619Z,1555450604.619 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-04-16T21:36:44.619Z,1555450604.619 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-04-16T21:36:44.889Z,1555450604.889 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-04-16T21:36:44.890Z,1555450604.890 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-04-16T21:36:45.162Z,1555450605.162 [SBIT](DEBUG): Construct Startup Built In Test.
2019-04-16T21:36:45.173Z,1555450605.173 [SBIT] Loaded
2019-04-16T21:36:45.173Z,1555450605.173 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-04-16T21:36:45.174Z,1555450605.174 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-04-16T21:36:45.186Z,1555450605.186 [IBIT] Loaded
2019-04-16T21:36:45.186Z,1555450605.186 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-04-16T21:36:45.189Z,1555450605.189 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-04-16T21:36:45.432Z,1555450605.432 [CBIT] Loaded
2019-04-16T21:36:45.432Z,1555450605.432 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-04-16T21:36:45.433Z,1555450605.433 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-04-16T21:36:45.436Z,1555450605.436 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-04-16T21:36:45.437Z,1555450605.437 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-04-16T21:36:45.444Z,1555450605.444 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-04-16T21:36:45.445Z,1555450605.445 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AF84E0
2019-04-16T21:36:45.446Z,1555450605.446 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6952
2019-04-16T21:36:45.451Z,1555450605.451 [Supervisor](INFO): Main Thread ID is 6048
2019-04-16T21:36:45.451Z,1555450605.451 [Supervisor](DEBUG): Running supervisor.
2019-04-16T21:36:45.452Z,1555450605.452 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6953
2019-04-16T21:36:45.454Z,1555450605.454 [controlThread ThreadHandler](INFO): Handler Thread ID is 6954
2019-04-16T21:36:45.455Z,1555450605.455 [controlThread](DEBUG): Initializing ControlThread
2019-04-16T21:36:45.455Z,1555450605.455 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-04-16T21:36:45.457Z,1555450605.457 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-04-16T21:36:45.458Z,1555450605.458 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-04-16T21:36:45.458Z,1555450605.458 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-04-16T21:36:45.459Z,1555450605.459 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-04-16T21:36:45.460Z,1555450605.460 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-04-16T21:36:45.460Z,1555450605.460 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-04-16T21:36:45.460Z,1555450605.460 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-04-16T21:36:45.461Z,1555450605.461 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-04-16T21:36:45.461Z,1555450605.461 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-04-16T21:36:45.462Z,1555450605.462 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-04-16T21:36:45.462Z,1555450605.462 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-04-16T21:36:45.464Z,1555450605.464 [SBIT](INFO): Initialize SBIT Component.
2019-04-16T21:36:45.465Z,1555450605.465 [SBIT](IMPORTANT): git: 2019-04-10-1-g18bae04
2019-04-16T21:36:45.465Z,1555450605.465 [SBIT](INFO): git hash: 18bae04951e434e73e54670b57def3d69c769fdd
2019-04-16T21:36:45.465Z,1555450605.465 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-04-16T21:36:45.466Z,1555450605.466 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Tue Dec 18 21:19:28 PST 2018
2019-04-16T21:36:45.467Z,1555450605.467 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-04-16T21:36:45.467Z,1555450605.467 [IBIT](INFO): Initialize IBIT Component.
2019-04-16T21:36:45.468Z,1555450605.468 [CBIT](DEBUG): Initialize CBIT Component.
2019-04-16T21:36:45.469Z,1555450605.469 [logger ThreadHandler](INFO): Handler Thread ID is 6955
2019-04-16T21:36:45.479Z,1555450605.479 [CBIT](DEBUG): Initialized mux pins.
2019-04-16T21:36:45.479Z,1555450605.479 [CBIT](DEBUG): Initializing the watchdog timer.
2019-04-16T21:36:45.488Z,1555450605.488 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 6956
2019-04-16T21:36:45.489Z,1555450605.489 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-04-16T21:36:45.500Z,1555450605.500 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6957
2019-04-16T21:36:45.503Z,1555450605.503 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-04-16T21:36:45.503Z,1555450605.503 [CBIT](DEBUG): Initializing heartbeat.
2019-04-16T21:36:45.512Z,1555450605.512 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 6958
2019-04-16T21:36:45.520Z,1555450605.520 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 6959
2019-04-16T21:36:45.520Z,1555450605.520 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-16T21:36:45.524Z,1555450605.524 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-16T21:36:45.525Z,1555450605.525 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6961
2019-04-16T21:36:45.527Z,1555450605.527 [WetLabsBB2FL](INFO): Powering down
2019-04-16T21:36:45.564Z,1555450605.564 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6962
2019-04-16T21:36:45.570Z,1555450605.570 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-04-16T21:36:45.570Z,1555450605.570 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-04-16T21:36:45.570Z,1555450605.570 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-04-16T21:36:45.570Z,1555450605.570 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-04-16T21:36:45.570Z,1555450605.570 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-04-16T21:36:45.570Z,1555450605.570 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-04-16T21:36:45.571Z,1555450605.571 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-04-16T21:36:45.571Z,1555450605.571 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-04-16T21:36:45.571Z,1555450605.571 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-04-16T21:36:45.571Z,1555450605.571 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-04-16T21:36:45.571Z,1555450605.571 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-04-16T21:36:45.572Z,1555450605.572 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-04-16T21:36:45.572Z,1555450605.572 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-04-16T21:36:45.572Z,1555450605.572 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-04-16T21:36:45.572Z,1555450605.572 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-04-16T21:36:45.572Z,1555450605.572 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-04-16T21:36:45.575Z,1555450605.575 [CBIT](DEBUG): Deactivating GF circuits.
2019-04-16T21:36:45.575Z,1555450605.575 [CBIT](DEBUG): Deactivating emergency mode.
2019-04-16T21:36:45.611Z,1555450605.611 [CBIT](DEBUG): Backplane powered.
2019-04-16T21:36:45.612Z,1555450605.612 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-04-16T21:36:45.623Z,1555450605.623 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-16T21:36:45.634Z,1555450605.634 [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>


2019-04-16T21:36:45.652Z,1555450605.652 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-04-16T21:36:45.723Z,1555450605.723 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-04-16T21:36:45.724Z,1555450605.724 [Default:A.Wait](DEBUG): Construct Wait.
2019-04-16T21:36:45.726Z,1555450605.726 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-04-16T21:36:45.745Z,1555450605.745 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-04-16T21:36:45.768Z,1555450605.768 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-04-16T21:36:45.774Z,1555450605.774 [Default:E.Execute](DEBUG): Construct Execute.
2019-04-16T21:36:45.805Z,1555450605.805 [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>


2019-04-16T21:36:45.809Z,1555450605.809 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,NAL9602,Onboard,BPC1,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,
2019-04-16T21:36:45.903Z,1555450605.903 [Radio_Surface](INFO): Powering up
2019-04-16T21:36:45.904Z,1555450605.904 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-04-16T21:36:45.916Z,1555450605.916 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-04-16T21:36:45.917Z,1555450605.917 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-04-16T21:36:45.924Z,1555450605.924 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-04-16T21:36:45.924Z,1555450605.924 [MassServo](DEBUG): Initializing EZServoServo.
2019-04-16T21:36:45.932Z,1555450605.932 [MassServo](DEBUG): Initializing MassServo.
2019-04-16T21:36:45.932Z,1555450605.932 [RudderServo](DEBUG): Initializing EZServoServo.
2019-04-16T21:36:45.940Z,1555450605.940 [RudderServo](DEBUG): Initializing RudderServo.
2019-04-16T21:36:45.941Z,1555450605.941 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-04-16T21:36:45.952Z,1555450605.952 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-04-16T21:36:58.571Z,1555450618.571 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-16T21:36:58.572Z,1555450618.572 [CTD_Seabird] Communications Fault, FailCount= 1
2019-04-16T21:36:58.572Z,1555450618.572 [CTD_Seabird](ERROR): Communications Fault
2019-04-16T21:36:58.771Z,1555450618.771 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-16T21:36:58.976Z,1555450618.976 [CTD_Seabird](INFO): Powering down
2019-04-16T21:37:00.003Z,1555450620.003 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-16T21:37:00.004Z,1555450620.004 [CTD_Seabird] No Fault, FailCount= 1
2019-04-16T21:37:00.343Z,1555450620.343 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-16T21:37:00.345Z,1555450620.345 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-16T21:37:12.885Z,1555450632.885 [NAL9602](INFO): Powering up NAL9602
2019-04-16T21:37:13.396Z,1555450633.396 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-16T21:37:13.396Z,1555450633.396 [CTD_Seabird] Communications Fault, FailCount= 2
2019-04-16T21:37:13.396Z,1555450633.396 [CTD_Seabird](ERROR): Communications Fault
2019-04-16T21:37:13.719Z,1555450633.719 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-16T21:37:13.799Z,1555450633.799 [CTD_Seabird](INFO): Powering down
2019-04-16T21:37:14.122Z,1555450634.122 [SBIT](IMPORTANT): Beginning Startup BIT
2019-04-16T21:37:14.126Z,1555450634.126 [CBIT](IMPORTANT): Beginning ground fault scan
2019-04-16T21:37:14.992Z,1555450634.992 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-04-16T21:37:14.992Z,1555450634.992 [CTD_Seabird] No Fault, FailCount= 2
2019-04-16T21:37:15.143Z,1555450635.143 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-04-16T21:37:15.152Z,1555450635.152 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-04-16T21:37:23.971Z,1555450643.971 [NAL9602](INFO): NAL9602 initialized
2019-04-16T21:37:24.787Z,1555450644.787 [NAL9602](DEBUG): Fix Requested
2019-04-16T21:37:24.861Z,1555450644.861 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.007980
CHAN A1 (24V): 0.001033
CHAN A2 (12V): -0.068480
CHAN A3 (5V): -0.002808
CHAN B0 (3.3V): -0.001358
CHAN B1 (3.15aV): -0.001285
CHAN B2 (3.15bV): -0.001014
CHAN B3 (GND): -0.000079
OPEN: -0.001189
Full Scale Calc: 4.765 mA, -1.589 mA
2019-04-16T21:37:28.203Z,1555450648.203 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-04-16T21:37:28.204Z,1555450648.204 [CTD_Seabird] Communications Fault, FailCount= 3
2019-04-16T21:37:28.204Z,1555450648.204 [CTD_Seabird](ERROR): Communications Fault
2019-04-16T21:37:28.472Z,1555450648.472 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-04-16T21:37:28.472Z,1555450648.472 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-04-16T21:37:28.608Z,1555450648.608 [CTD_Seabird](INFO): Powering down
2019-04-16T21:37:31.358Z,1555450651.358 [CommandLine](IMPORTANT): got command show variable durationOfLastRun
2019-04-16T21:37:31.700Z,1555450651.700 [CommandLine](IMPORTANT): controlThread.durationOfLastRun (second)
2019-04-16T21:37:42.676Z,1555450662.676 [CommandLine](IMPORTANT): got command report touch controlThread.durationOfLastRun
2019-04-16T21:37:43.129Z,1555450663.129 [Reporter](INFO): controlThread.durationOfLastRun 0.105385 s
2019-04-16T21:37:43.518Z,1555450663.518 [Reporter](INFO): controlThread.durationOfLastRun 0.110637 s
2019-04-16T21:37:43.918Z,1555450663.918 [Reporter](INFO): controlThread.durationOfLastRun 0.095367 s
2019-04-16T21:37:44.317Z,1555450664.317 [Reporter](INFO): controlThread.durationOfLastRun 0.090730 s
2019-04-16T21:37:44.722Z,1555450664.722 [Reporter](INFO): controlThread.durationOfLastRun 0.086677 s
2019-04-16T21:37:45.185Z,1555450665.185 [Reporter](INFO): controlThread.durationOfLastRun 0.086774 s
2019-04-16T21:37:45.530Z,1555450665.530 [Reporter](INFO): controlThread.durationOfLastRun 0.146401 s
2019-04-16T21:37:45.925Z,1555450665.925 [Reporter](INFO): controlThread.durationOfLastRun 0.087307 s
2019-04-16T21:37:46.417Z,1555450666.417 [Reporter](INFO): controlThread.durationOfLastRun 0.078675 s
2019-04-16T21:37:46.729Z,1555450666.729 [Reporter](INFO): controlThread.durationOfLastRun 0.166088 s
2019-04-16T21:37:47.130Z,1555450667.130 [Reporter](INFO): controlThread.durationOfLastRun 0.074624 s
2019-04-16T21:37:47.533Z,1555450667.533 [Reporter](INFO): controlThread.durationOfLastRun 0.070936 s
2019-04-16T21:37:47.925Z,1555450667.925 [Reporter](INFO): controlThread.durationOfLastRun 0.069986 s
2019-04-16T21:37:48.340Z,1555450668.340 [Reporter](INFO): controlThread.durationOfLastRun 0.058594 s
2019-04-16T21:37:48.733Z,1555450668.733 [Reporter](INFO): controlThread.durationOfLastRun 0.069513 s
2019-04-16T21:37:49.125Z,1555450669.125 [Reporter](INFO): controlThread.durationOfLastRun 0.058238 s
2019-04-16T21:37:49.529Z,1555450669.529 [Reporter](INFO): controlThread.durationOfLastRun 0.046127 s
2019-04-16T21:37:49.930Z,1555450669.930 [Reporter](INFO): controlThread.durationOfLastRun 0.045920 s
2019-04-16T21:37:50.337Z,1555450670.337 [Reporter](INFO): controlThread.durationOfLastRun 0.043070 s
2019-04-16T21:37:50.741Z,1555450670.741 [Reporter](INFO): controlThread.durationOfLastRun 0.046113 s
2019-04-16T21:37:51.151Z,1555450671.151 [Reporter](INFO): controlThread.durationOfLastRun 0.045845 s
2019-04-16T21:37:51.546Z,1555450671.546 [Reporter](INFO): controlThread.durationOfLastRun 0.056235 s
2019-04-16T21:37:51.953Z,1555450671.953 [Reporter](INFO): controlThread.durationOfLastRun 0.043227 s
2019-04-16T21:37:52.354Z,1555450672.354 [Reporter](INFO): controlThread.durationOfLastRun 0.046033 s
2019-04-16T21:37:52.769Z,1555450672.769 [Reporter](INFO): controlThread.durationOfLastRun 0.043159 s
2019-04-16T21:37:53.199Z,1555450673.199 [Reporter](INFO): controlThread.durationOfLastRun 0.054210 s
2019-04-16T21:37:53.591Z,1555450673.591 [Reporter](INFO): controlThread.durationOfLastRun 0.080646 s
2019-04-16T21:37:54.009Z,1555450674.009 [Reporter](INFO): controlThread.durationOfLastRun 0.068501 s
2019-04-16T21:37:54.400Z,1555450674.400 [Reporter](INFO): controlThread.durationOfLastRun 0.082570 s
2019-04-16T21:37:54.790Z,1555450674.790 [Reporter](INFO): controlThread.durationOfLastRun 0.069670 s
2019-04-16T21:37:55.185Z,1555450675.185 [Reporter](INFO): controlThread.durationOfLastRun 0.054938 s
2019-04-16T21:37:55.588Z,1555450675.588 [Reporter](INFO): controlThread.durationOfLastRun 0.045628 s
2019-04-16T21:37:56.161Z,1555450676.161 [Reporter](INFO): controlThread.durationOfLastRun 0.045615 s
2019-04-16T21:37:56.829Z,1555450676.829 [Reporter](INFO): controlThread.durationOfLastRun 0.214691 s
2019-04-16T21:37:56.981Z,1555450676.981 [Reporter](INFO): controlThread.durationOfLastRun 0.478123 s
2019-04-16T21:37:57.384Z,1555450677.384 [Reporter](INFO): controlThread.durationOfLastRun 0.151709 s
2019-04-16T21:37:57.769Z,1555450677.769 [Reporter](INFO): controlThread.durationOfLastRun 0.152992 s
2019-04-16T21:37:58.193Z,1555450678.193 [Reporter](INFO): controlThread.durationOfLastRun 0.134135 s
2019-04-16T21:37:58.597Z,1555450678.597 [Reporter](INFO): controlThread.durationOfLastRun 0.153966 s
2019-04-16T21:37:59.061Z,1555450679.061 [Reporter](INFO): controlThread.durationOfLastRun 0.154050 s
2019-04-16T21:37:59.521Z,1555450679.521 [Reporter](INFO): controlThread.durationOfLastRun 0.214141 s
2019-04-16T21:37:59.861Z,1555450679.861 [Reporter](INFO): controlThread.durationOfLastRun 0.270044 s
2019-04-16T21:38:00.142Z,1555450680.142 [Reporter](INFO): controlThread.durationOfLastRun 0.205803 s
2019-04-16T21:38:00.545Z,1555450680.545 [Reporter](INFO): controlThread.durationOfLastRun 0.082828 s
2019-04-16T21:38:00.961Z,1555450680.961 [Reporter](INFO): controlThread.durationOfLastRun 0.082720 s
2019-04-16T21:38:01.353Z,1555450681.353 [Reporter](INFO): controlThread.durationOfLastRun 0.094254 s
2019-04-16T21:38:01.759Z,1555450681.759 [Reporter](INFO): controlThread.durationOfLastRun 0.082371 s
2019-04-16T21:38:02.153Z,1555450682.153 [Reporter](INFO): controlThread.durationOfLastRun 0.084320 s
2019-04-16T21:38:02.565Z,1555450682.565 [Reporter](INFO): controlThread.durationOfLastRun 0.074012 s
2019-04-16T21:38:02.961Z,1555450682.961 [Reporter](INFO): controlThread.durationOfLastRun 0.082420 s
2019-04-16T21:38:03.385Z,1555450683.385 [Reporter](INFO): controlThread.durationOfLastRun 0.074319 s
2019-04-16T21:38:03.789Z,1555450683.789 [Reporter](INFO): controlThread.durationOfLastRun 0.093997 s
2019-04-16T21:38:04.197Z,1555450684.197 [Reporter](INFO): controlThread.durationOfLastRun 0.094642 s
2019-04-16T21:38:04.601Z,1555450684.601 [Reporter](INFO): controlThread.durationOfLastRun 0.098542 s
2019-04-16T21:38:04.993Z,1555450684.993 [Reporter](INFO): controlThread.durationOfLastRun 0.097946 s
2019-04-16T21:38:05.477Z,1555450685.477 [Reporter](INFO): controlThread.durationOfLastRun 0.086511 s
2019-04-16T21:38:05.759Z,1555450685.759 [Reporter](INFO): controlThread.durationOfLastRun 0.166126 s
2019-04-16T21:38:06.163Z,1555450686.163 [Reporter](INFO): controlThread.durationOfLastRun 0.043924 s
2019-04-16T21:38:06.687Z,1555450686.687 [Reporter](INFO): controlThread.durationOfLastRun 0.044294 s
2019-04-16T21:38:06.985Z,1555450686.985 [Reporter](INFO): controlThread.durationOfLastRun 0.164279 s
2019-04-16T21:38:07.397Z,1555450687.397 [Reporter](INFO): controlThread.durationOfLastRun 0.057675 s
2019-04-16T21:38:07.801Z,1555450687.801 [Reporter](INFO): controlThread.durationOfLastRun 0.066081 s
2019-04-16T21:38:08.177Z,1555450688.177 [SBIT](IMPORTANT): SBIT PASSED
2019-04-16T21:38:08.205Z,1555450688.205 [CommandLine](IMPORTANT): got command configSet list
2019-04-16T21:38:08.206Z,1555450688.206 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-04-16T21:38:08.206Z,1555450688.206 [CommandLine](IMPORTANT): AHRS_M2.loadAtStartup=0 bool;

2019-04-16T21:38:08.207Z,1555450688.207 [CommandLine](IMPORTANT): BPC1.batterySamplingInterval=0 second;

2019-04-16T21:38:08.207Z,1555450688.207 [CommandLine](IMPORTANT): CBIT.vehicleOpen=1 bool;

2019-04-16T21:38:08.207Z,1555450688.207 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-04-16T21:38:08.227Z,1555450688.227 [Reporter](INFO): controlThread.durationOfLastRun 0.065976 s
2019-04-16T21:38:08.587Z,1555450688.587 [MissionManager](IMPORTANT): Started mission Startup
2019-04-16T21:38:08.588Z,1555450688.588 [Startup] Running Loop=1
2019-04-16T21:38:08.588Z,1555450688.588 [Startup](DEBUG): Aggregate::initialize Startup
2019-04-16T21:38:08.588Z,1555450688.588 [Startup:A.GoToSurface] Running Loop=1
2019-04-16T21:38:08.588Z,1555450688.588 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-04-16T21:38:08.589Z,1555450688.589 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-04-16T21:38:08.589Z,1555450688.589 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-04-16T21:38:08.589Z,1555450688.589 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-04-16T21:38:08.590Z,1555450688.590 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-04-16T21:38:08.590Z,1555450688.590 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-04-16T21:38:08.592Z,1555450688.592 [Startup:StartupSatComms] Running Loop=1
2019-04-16T21:38:08.592Z,1555450688.592 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-04-16T21:38:08.592Z,1555450688.592 [Startup:StartupSatComms:A] Running Loop=1
2019-04-16T21:38:08.602Z,1555450688.602 [Reporter](INFO): controlThread.durationOfLastRun 0.104351 s
2019-04-16T21:38:08.992Z,1555450688.992 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-04-16T21:38:09.001Z,1555450689.001 [Reporter](INFO): controlThread.durationOfLastRun 0.059567 s
2019-04-16T21:38:09.407Z,1555450689.407 [Reporter](INFO): controlThread.durationOfLastRun 0.054621 s
2019-04-16T21:38:09.813Z,1555450689.813 [Reporter](INFO): controlThread.durationOfLastRun 0.056578 s
2019-04-16T21:38:10.205Z,1555450690.205 [Reporter](INFO): controlThread.durationOfLastRun 0.058199 s
2019-04-16T21:38:10.611Z,1555450690.611 [Reporter](INFO): controlThread.durationOfLastRun 0.045789 s
2019-04-16T21:38:11.023Z,1555450691.023 [Reporter](INFO): controlThread.durationOfLastRun 0.048322 s
2019-04-16T21:38:11.413Z,1555450691.413 [Reporter](INFO): controlThread.durationOfLastRun 0.056240 s
2019-04-16T21:38:11.817Z,1555450691.817 [Reporter](INFO): controlThread.durationOfLastRun 0.042518 s
2019-04-16T21:38:12.222Z,1555450692.222 [Reporter](INFO): controlThread.durationOfLastRun 0.042498 s
2019-04-16T21:38:12.629Z,1555450692.629 [Reporter](INFO): controlThread.durationOfLastRun 0.043397 s
2019-04-16T21:38:13.030Z,1555450693.030 [Reporter](INFO): controlThread.durationOfLastRun 0.045993 s
2019-04-16T21:38:13.441Z,1555450693.441 [Reporter](INFO): controlThread.durationOfLastRun 0.042989 s
2019-04-16T21:38:13.853Z,1555450693.853 [Reporter](INFO): controlThread.durationOfLastRun 0.050142 s
2019-04-16T21:38:14.260Z,1555450694.260 [Reporter](INFO): controlThread.durationOfLastRun 0.057807 s
2019-04-16T21:38:14.672Z,1555450694.672 [Reporter](INFO): controlThread.durationOfLastRun 0.061459 s
2019-04-16T21:38:15.060Z,1555450695.060 [Reporter](INFO): controlThread.durationOfLastRun 0.069102 s
2019-04-16T21:38:15.454Z,1555450695.454 [Reporter](INFO): controlThread.durationOfLastRun 0.052914 s
2019-04-16T21:38:15.861Z,1555450695.861 [Reporter](INFO): controlThread.durationOfLastRun 0.043300 s
2019-04-16T21:38:16.269Z,1555450696.269 [Reporter](INFO): controlThread.durationOfLastRun 0.046169 s
2019-04-16T21:38:16.666Z,1555450696.666 [Reporter](INFO): controlThread.durationOfLastRun 0.050068 s
2019-04-16T21:38:17.070Z,1555450697.070 [Reporter](INFO): controlThread.durationOfLastRun 0.043100 s
2019-04-16T21:38:17.476Z,1555450697.476 [Reporter](INFO): controlThread.durationOfLastRun 0.042804 s
2019-04-16T21:38:17.893Z,1555450697.893 [Reporter](INFO): controlThread.durationOfLastRun 0.045396 s
2019-04-16T21:38:18.282Z,1555450698.282 [Reporter](INFO): controlThread.durationOfLastRun 0.058602 s
2019-04-16T21:38:18.682Z,1555450698.682 [Reporter](INFO): controlThread.durationOfLastRun 0.043126 s
2019-04-16T21:38:19.105Z,1555450699.105 [Reporter](INFO): controlThread.durationOfLastRun 0.039159 s
2019-04-16T21:38:19.505Z,1555450699.505 [Reporter](INFO): controlThread.durationOfLastRun 0.058088 s
2019-04-16T21:38:19.909Z,1555450699.909 [Reporter](INFO): controlThread.durationOfLastRun 0.054144 s
2019-04-16T21:38:20.435Z,1555450700.435 [Reporter](INFO): controlThread.durationOfLastRun 0.053910 s
2019-04-16T21:38:20.742Z,1555450700.742 [Reporter](INFO): controlThread.durationOfLastRun 0.176004 s
2019-04-16T21:38:21.141Z,1555450701.141 [Reporter](INFO): controlThread.durationOfLastRun 0.078902 s
2019-04-16T21:38:21.537Z,1555450701.537 [Reporter](INFO): controlThread.durationOfLastRun 0.074017 s
2019-04-16T21:38:21.952Z,1555450701.952 [Reporter](INFO): controlThread.durationOfLastRun 0.066218 s
2019-04-16T21:38:22.322Z,1555450702.322 [Reporter](INFO): controlThread.durationOfLastRun 0.077302 s
2019-04-16T21:38:22.726Z,1555450702.726 [Reporter](INFO): controlThread.durationOfLastRun 0.043220 s
2019-04-16T21:38:23.130Z,1555450703.130 [Reporter](INFO): controlThread.durationOfLastRun 0.043038 s
2019-04-16T21:38:23.537Z,1555450703.537 [Reporter](INFO): controlThread.durationOfLastRun 0.043143 s
2019-04-16T21:38:23.938Z,1555450703.938 [Reporter](INFO): controlThread.durationOfLastRun 0.045912 s
2019-04-16T21:38:24.369Z,1555450704.369 [Reporter](INFO): controlThread.durationOfLastRun 0.042832 s
2019-04-16T21:38:24.763Z,1555450704.763 [Reporter](INFO): controlThread.durationOfLastRun 0.070123 s
2019-04-16T21:38:25.180Z,1555450705.180 [Reporter](INFO): controlThread.durationOfLastRun 0.060715 s
2019-04-16T21:38:25.565Z,1555450705.565 [Reporter](INFO): controlThread.durationOfLastRun 0.073307 s
2019-04-16T21:38:25.958Z,1555450705.958 [Reporter](INFO): controlThread.durationOfLastRun 0.054153 s
2019-04-16T21:38:26.363Z,1555450706.363 [Reporter](INFO): controlThread.durationOfLastRun 0.043184 s
2019-04-16T21:38:26.765Z,1555450706.765 [Reporter](INFO): controlThread.durationOfLastRun 0.043905 s
2019-04-16T21:38:27.172Z,1555450707.172 [Reporter](INFO): controlThread.durationOfLastRun 0.042657 s
2019-04-16T21:38:27.579Z,1555450707.579 [Reporter](INFO): controlThread.durationOfLastRun 0.045673 s
2019-04-16T21:38:27.991Z,1555450707.991 [Reporter](INFO): controlThread.durationOfLastRun 0.048425 s
2019-04-16T21:38:28.381Z,1555450708.381 [Reporter](INFO): controlThread.durationOfLastRun 0.056679 s
2019-04-16T21:38:28.786Z,1555450708.786 [Reporter](INFO): controlThread.durationOfLastRun 0.042356 s
2019-04-16T21:38:29.191Z,1555450709.191 [Reporter](INFO): controlThread.durationOfLastRun 0.042606 s
2019-04-16T21:38:29.605Z,1555450709.605 [Reporter](INFO): controlThread.durationOfLastRun 0.044217 s
2019-04-16T21:38:30.017Z,1555450710.017 [Reporter](INFO): controlThread.durationOfLastRun 0.053811 s
2019-04-16T21:38:30.421Z,1555450710.421 [Reporter](INFO): controlThread.durationOfLastRun 0.061891 s
2019-04-16T21:38:30.819Z,1555450710.819 [Reporter](INFO): controlThread.durationOfLastRun 0.062116 s
2019-04-16T21:38:31.223Z,1555450711.223 [Reporter](INFO): controlThread.durationOfLastRun 0.056072 s
2019-04-16T21:38:31.615Z,1555450711.615 [Reporter](INFO): controlThread.durationOfLastRun 0.056468 s
2019-04-16T21:38:32.021Z,1555450712.021 [Reporter](INFO): controlThread.durationOfLastRun 0.044600 s
2019-04-16T21:38:32.423Z,1555450712.423 [Reporter](INFO): controlThread.durationOfLastRun 0.045820 s
2019-04-16T21:38:32.827Z,1555450712.827 [Reporter](INFO): controlThread.durationOfLastRun 0.044563 s
2019-04-16T21:38:33.233Z,1555450713.233 [Reporter](INFO): controlThread.durationOfLastRun 0.044144 s
2019-04-16T21:38:33.691Z,1555450713.691 [Reporter](INFO): controlThread.durationOfLastRun 0.045774 s
2019-04-16T21:38:34.108Z,1555450714.108 [Reporter](INFO): controlThread.durationOfLastRun 0.100728 s
2019-04-16T21:38:34.472Z,1555450714.472 [Reporter](INFO): controlThread.durationOfLastRun 0.112870 s
2019-04-16T21:38:34.889Z,1555450714.889 [Reporter](INFO): controlThread.durationOfLastRun 0.072855 s
2019-04-16T21:38:35.281Z,1555450715.281 [Reporter](INFO): controlThread.durationOfLastRun 0.086708 s
2019-04-16T21:38:35.668Z,1555450715.668 [Reporter](INFO): controlThread.durationOfLastRun 0.074281 s
2019-04-16T21:38:36.073Z,1555450716.073 [Reporter](INFO): controlThread.durationOfLastRun 0.057458 s
2019-04-16T21:38:36.463Z,1555450716.463 [Reporter](INFO): controlThread.durationOfLastRun 0.058048 s
2019-04-16T21:38:36.869Z,1555450716.869 [Reporter](INFO): controlThread.durationOfLastRun 0.044634 s
2019-04-16T21:38:37.273Z,1555450717.273 [Reporter](INFO): controlThread.durationOfLastRun 0.045802 s
2019-04-16T21:38:37.681Z,1555450717.681 [Reporter](INFO): controlThread.durationOfLastRun 0.044534 s
2019-04-16T21:38:38.085Z,1555450718.085 [Reporter](INFO): controlThread.durationOfLastRun 0.050153 s
2019-04-16T21:38:38.499Z,1555450718.499 [Reporter](INFO): controlThread.durationOfLastRun 0.050097 s
2019-04-16T21:38:38.879Z,1555450718.879 [Reporter](INFO): controlThread.durationOfLastRun 0.060385 s
2019-04-16T21:38:39.294Z,1555450719.294 [Reporter](INFO): controlThread.durationOfLastRun 0.036702 s
2019-04-16T21:38:39.694Z,1555450719.694 [Reporter](INFO): controlThread.durationOfLastRun 0.047521 s
2019-04-16T21:38:40.121Z,1555450720.121 [Reporter](INFO): controlThread.durationOfLastRun 0.043003 s
2019-04-16T21:38:40.513Z,1555450720.513 [Reporter](INFO): controlThread.durationOfLastRun 0.066109 s
2019-04-16T21:38:40.919Z,1555450720.919 [Reporter](INFO): controlThread.durationOfLastRun 0.053544 s
2019-04-16T21:38:41.240Z,1555450721.240 [CommandLine](IMPORTANT): got command report clear
2019-04-16T21:38:44.597Z,1555450724.597 [CommandLine](IMPORTANT): got command quit
2019-04-16T21:38:45.603Z,1555450725.603 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2019-04-16T21:38:45.604Z,1555450725.604 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-16T21:38:45.604Z,1555450725.604 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-16T21:38:45.758Z,1555450725.758 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2019-04-16T21:38:45.758Z,1555450725.758 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-04-16T21:38:45.758Z,1555450725.758 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-04-16T21:38:45.767Z,1555450725.767 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-04-16T21:38:45.767Z,1555450725.767 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-16T21:38:45.768Z,1555450725.768 [CommandLine](INFO): Join timeout helper Thread ID is 6979
2019-04-16T21:38:45.775Z,1555450725.775 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-04-16T21:38:45.775Z,1555450725.775 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-16T21:38:45.776Z,1555450725.776 [NavChartDb](INFO): Join timeout helper Thread ID is 6980
2019-04-16T21:38:45.782Z,1555450725.782 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-04-16T21:38:46.128Z,1555450726.128 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-16T21:38:46.128Z,1555450726.128 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-16T21:38:46.131Z,1555450726.131 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-04-16T21:38:46.131Z,1555450726.131 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-16T21:38:46.132Z,1555450726.132 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 6981
2019-04-16T21:38:46.168Z,1555450726.168 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-04-16T21:38:46.168Z,1555450726.168 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-04-16T21:38:46.512Z,1555450726.512 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-16T21:38:46.512Z,1555450726.512 [WetLabsBB2FL](INFO): Powering down
2019-04-16T21:38:46.513Z,1555450726.513 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-16T21:38:46.515Z,1555450726.515 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-04-16T21:38:46.515Z,1555450726.515 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-16T21:38:46.516Z,1555450726.516 [CTD_Seabird](INFO): Join timeout helper Thread ID is 6982
2019-04-16T21:38:46.711Z,1555450726.711 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-16T21:38:46.712Z,1555450726.712 [CTD_Seabird](INFO): Powering down
2019-04-16T21:38:46.723Z,1555450726.723 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-16T21:38:46.731Z,1555450726.731 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2019-04-16T21:38:46.731Z,1555450726.731 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-04-16T21:38:46.732Z,1555450726.732 [CTD_NeilBrown](INFO): Join timeout helper Thread ID is 6983
2019-04-16T21:38:46.780Z,1555450726.780 [CTD_NeilBrown ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-16T21:38:46.780Z,1555450726.780 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-04-16T21:38:46.792Z,1555450726.792 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-04-16T21:38:46.792Z,1555450726.792 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-16T21:38:46.793Z,1555450726.793 [Radio_Surface](INFO): Join timeout helper Thread ID is 6984
2019-04-16T21:38:47.107Z,1555450727.107 [Radio_Surface](INFO): Powering down
2019-04-16T21:38:47.108Z,1555450727.108 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-16T21:38:47.109Z,1555450727.109 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-16T21:38:47.116Z,1555450727.116 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-04-16T21:38:47.116Z,1555450727.116 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-16T21:38:47.117Z,1555450727.117 [DataOverHttps](INFO): Join timeout helper Thread ID is 6985
2019-04-16T21:38:47.507Z,1555450727.507 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-16T21:38:47.508Z,1555450727.508 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-16T21:38:47.520Z,1555450727.520 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-04-16T21:38:47.520Z,1555450727.520 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-16T21:38:47.521Z,1555450727.521 [logger](INFO): Join timeout helper Thread ID is 6986
2019-04-16T21:38:47.531Z,1555450727.531 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-16T21:38:47.532Z,1555450727.532 [logger ThreadHandler](INFO): Thread cancelled.
2019-04-16T21:38:47.540Z,1555450727.540 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-04-16T21:38:47.540Z,1555450727.540 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-04-16T21:38:47.540Z,1555450727.540 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-04-16T21:38:47.540Z,1555450727.540 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-16T21:38:47.541Z,1555450727.541 [controlThread](INFO): Join timeout helper Thread ID is 6987
2019-04-16T21:38:47.731Z,1555450727.731 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-04-16T21:38:47.732Z,1555450727.732 [controlThread](DEBUG): Uninitializing ControlThread
2019-04-16T21:38:47.732Z,1555450727.732 [NAL9602](INFO): Powering down
2019-04-16T21:38:47.734Z,1555450727.734 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-04-16T21:38:47.736Z,1555450727.736 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-04-16T21:38:47.736Z,1555450727.736 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-04-16T21:38:47.737Z,1555450727.737 [MissionManager](INFO): Uninitializing Mission Default
2019-04-16T21:38:47.739Z,1555450727.739 [MissionManager](INFO): Uninitializing Mission Startup
2019-04-16T21:38:47.739Z,1555450727.739 [Startup] Stopped
2019-04-16T21:38:47.739Z,1555450727.739 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-04-16T21:38:47.739Z,1555450727.739 [Startup:A.GoToSurface] Stopped
2019-04-16T21:38:47.739Z,1555450727.739 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-04-16T21:38:47.739Z,1555450727.739 [Startup:StartupSatComms] Stopped
2019-04-16T21:38:47.739Z,1555450727.739 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-04-16T21:38:47.739Z,1555450727.739 [Startup:StartupSatComms:A] Stopped
2019-04-16T21:38:47.741Z,1555450727.741 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-04-16T21:38:47.741Z,1555450727.741 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-04-16T21:38:47.741Z,1555450727.741 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-04-16T21:38:47.741Z,1555450727.741 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-04-16T21:38:47.741Z,1555450727.741 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-04-16T21:38:47.742Z,1555450727.742 [BuoyancyServo](INFO): Powering down
2019-04-16T21:38:47.755Z,1555450727.755 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-04-16T21:38:47.756Z,1555450727.756 [ElevatorServo](INFO): Powering down
2019-04-16T21:38:47.756Z,1555450727.756 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-04-16T21:38:47.756Z,1555450727.756 [MassServo](INFO): Powering down
2019-04-16T21:38:47.757Z,1555450727.757 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-04-16T21:38:47.757Z,1555450727.757 [RudderServo](INFO): Powering down
2019-04-16T21:38:47.758Z,1555450727.758 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-04-16T21:38:47.758Z,1555450727.758 [ThrusterServo](INFO): Powering down
2019-04-16T21:38:47.759Z,1555450727.759 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-04-16T21:38:47.760Z,1555450727.760 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-04-16T21:38:47.760Z,1555450727.760 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-04-16T21:38:47.760Z,1555450727.760 [CBIT](DEBUG): Powering off loads.
2019-04-16T21:38:47.771Z,1555450727.771 [CBIT](DEBUG): Disabling WDT.
2019-04-16T21:38:47.783Z,1555450727.783 [CBIT](DEBUG): Opening all GF detection circuits.
2019-04-16T21:38:47.784Z,1555450727.784 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-04-16T21:38:47.864Z,1555450727.864 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-04-16T21:38:47.870Z,1555450727.870 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-04-16T21:38:47.901Z,1555450727.901 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-04-16T21:38:47.903Z,1555450727.903 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-04-16T21:38:47.906Z,1555450727.906 [CTD_NeilBrown ThreadHandler](INFO): Thread cancelled.
2019-04-16T21:38:47.936Z,1555450727.936 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-04-16T21:38:47.993Z,1555450727.993 [logger ThreadHandler](INFO): Thread cancelled.