2019-10-15T22:23:51.847Z,1571178231.847 [Supervisor](DEBUG): Initializing supervisor.
2019-10-15T22:23:51.849Z,1571178231.849 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-10-15T22:23:51.850Z,1571178231.850 [SyncHandler](INFO): Protected caller Thread ID is 806
2019-10-15T22:23:51.850Z,1571178231.850 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-10-15T22:23:51.851Z,1571178231.851 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-10-15T22:23:51.852Z,1571178231.852 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 807
2019-10-15T22:23:51.854Z,1571178231.854 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-10-15T22:23:51.865Z,1571178231.865 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-10-15T22:23:51.866Z,1571178231.866 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-10-15T22:23:51.866Z,1571178231.866 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 808
2019-10-15T22:23:51.867Z,1571178231.867 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-10-15T22:23:51.868Z,1571178231.868 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-10-15T22:23:51.868Z,1571178231.868 [logger ThreadHandler](INFO): Protected caller Thread ID is 809
2019-10-15T22:23:51.870Z,1571178231.870 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-10-15T22:23:51.871Z,1571178231.871 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-10-15T22:23:51.875Z,1571178231.875 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-10-15T22:23:52.328Z,1571178232.328 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-10-15T22:23:52.330Z,1571178232.330 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-10-15T22:23:52.471Z,1571178232.471 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-10-15T22:23:52.473Z,1571178232.473 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-10-15T22:23:52.555Z,1571178232.555 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-10-15T22:23:52.652Z,1571178232.652 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-10-15T22:23:52.654Z,1571178232.654 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-10-15T22:23:52.749Z,1571178232.749 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-10-15T22:23:52.750Z,1571178232.750 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-10-15T22:23:53.119Z,1571178233.119 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-10-15T22:23:53.120Z,1571178233.120 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-10-15T22:23:53.221Z,1571178233.221 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-10-15T22:23:53.223Z,1571178233.223 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-10-15T22:23:53.674Z,1571178233.674 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-10-15T22:23:53.675Z,1571178233.675 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-10-15T22:23:53.869Z,1571178233.869 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-10-15T22:23:53.871Z,1571178233.871 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-10-15T22:23:54.178Z,1571178234.178 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-10-15T22:23:54.180Z,1571178234.180 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-10-15T22:23:54.387Z,1571178234.387 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-10-15T22:23:54.388Z,1571178234.388 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-10-15T22:23:54.527Z,1571178234.527 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-10-15T22:23:54.528Z,1571178234.528 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-10-15T22:23:55.154Z,1571178235.154 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-10-15T22:23:55.155Z,1571178235.155 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-10-15T22:23:55.521Z,1571178235.521 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-10-15T22:23:55.830Z,1571178235.830 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-10-15T22:23:56.451Z,1571178236.451 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-10-15T22:23:56.453Z,1571178236.453 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2019-10-15T22:23:56.455Z,1571178236.455 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2019-10-15T22:23:56.687Z,1571178236.687 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2019-10-15T22:23:56.770Z,1571178236.770 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2019-10-15T22:23:56.904Z,1571178236.904 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2019-10-15T22:23:56.990Z,1571178236.990 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2019-10-15T22:23:57.213Z,1571178237.213 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-10-15T22:23:57.214Z,1571178237.214 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2019-10-15T22:23:57.308Z,1571178237.308 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2019-10-15T22:23:57.415Z,1571178237.415 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2019-10-15T22:23:57.507Z,1571178237.507 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2019-10-15T22:23:57.663Z,1571178237.663 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2019-10-15T22:23:57.864Z,1571178237.864 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2019-10-15T22:23:57.961Z,1571178237.961 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2019-10-15T22:23:57.965Z,1571178237.965 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-10-15T22:23:57.990Z,1571178237.990 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-10-15T22:23:57.992Z,1571178237.992 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-10-15T22:23:58.082Z,1571178238.082 [VerticalControl](DEBUG): Construct VerticalControl.
2019-10-15T22:23:58.193Z,1571178238.193 [VerticalControl] Loaded
2019-10-15T22:23:58.193Z,1571178238.193 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-10-15T22:23:58.194Z,1571178238.194 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-10-15T22:23:58.261Z,1571178238.261 [HorizontalControl] Loaded
2019-10-15T22:23:58.262Z,1571178238.262 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-10-15T22:23:58.262Z,1571178238.262 [SpeedControl](DEBUG): Construct SpeedControl.
2019-10-15T22:23:58.268Z,1571178238.268 [SpeedControl] Loaded
2019-10-15T22:23:58.268Z,1571178238.268 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-10-15T22:23:58.269Z,1571178238.269 [LoopControl](DEBUG): Construct LoopControl.
2019-10-15T22:23:58.269Z,1571178238.269 [LoopControl] Loaded
2019-10-15T22:23:58.269Z,1571178238.269 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-10-15T22:23:58.270Z,1571178238.270 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-10-15T22:23:58.272Z,1571178238.272 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-10-15T22:23:58.422Z,1571178238.422 [BuoyancyServo] Loaded
2019-10-15T22:23:58.423Z,1571178238.423 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-10-15T22:23:58.434Z,1571178238.434 [ElevatorServo] Loaded
2019-10-15T22:23:58.434Z,1571178238.434 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-10-15T22:23:58.444Z,1571178238.444 [MassServo] Loaded
2019-10-15T22:23:58.445Z,1571178238.445 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-10-15T22:23:58.455Z,1571178238.455 [RudderServo] Loaded
2019-10-15T22:23:58.456Z,1571178238.456 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-10-15T22:23:58.466Z,1571178238.466 [ThrusterServo] Loaded
2019-10-15T22:23:58.467Z,1571178238.467 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-10-15T22:23:58.467Z,1571178238.467 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-10-15T22:23:58.468Z,1571178238.468 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-10-15T22:23:58.523Z,1571178238.523 [DepthRateCalculator] Loaded
2019-10-15T22:23:58.523Z,1571178238.523 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-10-15T22:23:58.528Z,1571178238.528 [PitchRateCalculator] Loaded
2019-10-15T22:23:58.528Z,1571178238.528 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-10-15T22:23:58.543Z,1571178238.543 [SpeedCalculator] Loaded
2019-10-15T22:23:58.543Z,1571178238.543 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-10-15T22:23:58.564Z,1571178238.564 [TempGradientCalculator] Loaded
2019-10-15T22:23:58.564Z,1571178238.564 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-10-15T22:23:58.569Z,1571178238.569 [YawRateCalculator] Loaded
2019-10-15T22:23:58.570Z,1571178238.570 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-10-15T22:23:58.599Z,1571178238.599 [ElevatorOffsetCalculator] Loaded
2019-10-15T22:23:58.599Z,1571178238.599 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-10-15T22:23:58.599Z,1571178238.599 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-10-15T22:23:58.600Z,1571178238.600 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-10-15T22:23:58.717Z,1571178238.717 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-10-15T22:23:58.718Z,1571178238.718 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-10-15T22:23:58.738Z,1571178238.738 [NavChart] Loaded
2019-10-15T22:23:58.738Z,1571178238.738 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-10-15T22:23:58.742Z,1571178238.742 [UniversalFixResidualReporter] Loaded
2019-10-15T22:23:58.742Z,1571178238.742 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-10-15T22:23:58.743Z,1571178238.743 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-10-15T22:23:58.743Z,1571178238.743 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-10-15T22:23:59.408Z,1571178239.408 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2019-10-15T22:23:59.413Z,1571178239.413 [AHRS_M2](INFO): created writer for : platform_orientation
2019-10-15T22:23:59.414Z,1571178239.414 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2019-10-15T22:23:59.419Z,1571178239.419 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2019-10-15T22:23:59.420Z,1571178239.420 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2019-10-15T22:23:59.425Z,1571178239.425 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2019-10-15T22:23:59.425Z,1571178239.425 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2019-10-15T22:23:59.431Z,1571178239.431 [AHRS_M2](INFO): created writer for : platform_roll_angle
2019-10-15T22:23:59.606Z,1571178239.606 [AHRS_M2] Loaded
2019-10-15T22:23:59.607Z,1571178239.607 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-10-15T22:23:59.750Z,1571178239.750 [DataOverHttps] Loaded
2019-10-15T22:23:59.750Z,1571178239.750 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-10-15T22:23:59.751Z,1571178239.751 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407F84E0
2019-10-15T22:23:59.751Z,1571178239.751 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 890
2019-10-15T22:23:59.765Z,1571178239.765 [Depth_Keller] Loaded
2019-10-15T22:23:59.765Z,1571178239.765 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-10-15T22:23:59.770Z,1571178239.770 [DropWeight] Loaded
2019-10-15T22:23:59.770Z,1571178239.770 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-10-15T22:23:59.815Z,1571178239.815 [DVL_micro] Loaded
2019-10-15T22:23:59.815Z,1571178239.815 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2019-10-15T22:23:59.910Z,1571178239.910 [NAL9602] Loaded
2019-10-15T22:23:59.911Z,1571178239.911 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-10-15T22:23:59.926Z,1571178239.926 [Onboard] Loaded
2019-10-15T22:23:59.926Z,1571178239.926 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-10-15T22:23:59.930Z,1571178239.930 [Radio_Surface] Loaded
2019-10-15T22:23:59.930Z,1571178239.930 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-10-15T22:23:59.931Z,1571178239.931 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408284E0
2019-10-15T22:23:59.931Z,1571178239.931 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 891
2019-10-15T22:24:00.056Z,1571178240.056 [DAT] Loaded
2019-10-15T22:24:00.056Z,1571178240.056 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2019-10-15T22:24:01.531Z,1571178241.531 [BPC1] Loaded
2019-10-15T22:24:01.531Z,1571178241.531 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-10-15T22:24:01.532Z,1571178241.532 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-10-15T22:24:01.532Z,1571178241.532 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-10-15T22:24:01.944Z,1571178241.944 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-10-15T22:24:01.945Z,1571178241.945 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-10-15T22:24:02.098Z,1571178242.098 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-10-15T22:24:02.099Z,1571178242.099 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-10-15T22:24:02.147Z,1571178242.147 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-10-15T22:24:02.148Z,1571178242.148 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-10-15T22:24:02.395Z,1571178242.395 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-10-15T22:24:02.400Z,1571178242.400 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-10-15T22:24:02.400Z,1571178242.400 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-10-15T22:24:02.405Z,1571178242.405 [CTD_Seabird](INFO): created writer for : depth
2019-10-15T22:24:02.406Z,1571178242.406 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-10-15T22:24:02.410Z,1571178242.410 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-10-15T22:24:02.411Z,1571178242.411 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-10-15T22:24:02.416Z,1571178242.416 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-10-15T22:24:02.417Z,1571178242.417 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-10-15T22:24:02.422Z,1571178242.422 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-10-15T22:24:02.422Z,1571178242.422 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-10-15T22:24:02.428Z,1571178242.428 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-10-15T22:24:02.428Z,1571178242.428 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-10-15T22:24:02.434Z,1571178242.434 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-10-15T22:24:02.434Z,1571178242.434 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): mass_concentration_of_oxygen_in_sea_water
2019-10-15T22:24:02.439Z,1571178242.439 [CTD_Seabird](INFO): created writer for : mass_concentration_of_oxygen_in_sea_water
2019-10-15T22:24:02.440Z,1571178242.440 [CTD_Seabird](DEBUG): LcmSlateWriter::add(DAtaURI): CTD_Seabird.sea_water_dissolved_oxygen_freq
2019-10-15T22:24:02.440Z,1571178242.440 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): dataName: sea_water_dissolved_oxygen_freq
2019-10-15T22:24:02.472Z,1571178242.472 [CTD_Seabird] Loaded
2019-10-15T22:24:02.473Z,1571178242.473 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-10-15T22:24:02.474Z,1571178242.474 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409BF4E0
2019-10-15T22:24:02.474Z,1571178242.474 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 892
2019-10-15T22:24:02.504Z,1571178242.504 [ESPComponent] Loaded
2019-10-15T22:24:02.505Z,1571178242.505 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-10-15T22:24:02.519Z,1571178242.519 [PAR_Licor] Loaded
2019-10-15T22:24:02.519Z,1571178242.519 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-10-15T22:24:02.565Z,1571178242.565 [WetLabsBB2FL] Loaded
2019-10-15T22:24:02.566Z,1571178242.566 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-10-15T22:24:02.567Z,1571178242.567 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409EF4E0
2019-10-15T22:24:02.567Z,1571178242.567 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 893
2019-10-15T22:24:02.568Z,1571178242.568 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-10-15T22:24:02.569Z,1571178242.569 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-10-15T22:24:02.911Z,1571178242.911 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-10-15T22:24:02.911Z,1571178242.911 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-10-15T22:24:03.069Z,1571178243.069 [SBIT](DEBUG): Construct Startup Built In Test.
2019-10-15T22:24:03.080Z,1571178243.080 [SBIT] Loaded
2019-10-15T22:24:03.080Z,1571178243.080 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-10-15T22:24:03.081Z,1571178243.081 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-10-15T22:24:03.092Z,1571178243.092 [IBIT] Loaded
2019-10-15T22:24:03.092Z,1571178243.092 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-10-15T22:24:03.096Z,1571178243.096 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-10-15T22:24:03.233Z,1571178243.233 [CBIT] Loaded
2019-10-15T22:24:03.233Z,1571178243.233 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-10-15T22:24:03.234Z,1571178243.234 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-10-15T22:24:03.238Z,1571178243.238 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-10-15T22:24:03.239Z,1571178243.239 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-10-15T22:24:03.245Z,1571178243.245 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-10-15T22:24:03.246Z,1571178243.246 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACE4E0
2019-10-15T22:24:03.247Z,1571178243.247 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 894
2019-10-15T22:24:03.251Z,1571178243.251 [Supervisor](INFO): Main Thread ID is 802
2019-10-15T22:24:03.251Z,1571178243.251 [Supervisor](DEBUG): Running supervisor.
2019-10-15T22:24:03.252Z,1571178243.252 [CommandLine ThreadHandler](INFO): Handler Thread ID is 895
2019-10-15T22:24:03.254Z,1571178243.254 [controlThread ThreadHandler](INFO): Handler Thread ID is 896
2019-10-15T22:24:03.255Z,1571178243.255 [controlThread](DEBUG): Initializing ControlThread
2019-10-15T22:24:03.255Z,1571178243.255 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-10-15T22:24:03.257Z,1571178243.257 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-10-15T22:24:03.258Z,1571178243.258 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-10-15T22:24:03.258Z,1571178243.258 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-10-15T22:24:03.260Z,1571178243.260 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-10-15T22:24:03.260Z,1571178243.260 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-10-15T22:24:03.260Z,1571178243.260 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-10-15T22:24:03.261Z,1571178243.261 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-10-15T22:24:03.261Z,1571178243.261 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-10-15T22:24:03.261Z,1571178243.261 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-10-15T22:24:03.262Z,1571178243.262 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-10-15T22:24:03.263Z,1571178243.263 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-10-15T22:24:03.269Z,1571178243.269 [SBIT](INFO): Initialize SBIT Component.
2019-10-15T22:24:03.270Z,1571178243.270 [SBIT](IMPORTANT): git: 2019-09-23_TRN-5-g307b34b
2019-10-15T22:24:03.270Z,1571178243.270 [SBIT](INFO): git hash: 307b34b03e5aa7dd6ba91a7832117416aca74dd3
2019-10-15T22:24:03.270Z,1571178243.270 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-10-15T22:24:03.271Z,1571178243.271 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2019-10-15T22:24:03.272Z,1571178243.272 [SBIT](INFO): Beginning SBIT in 38.000000 seconds.
2019-10-15T22:24:03.273Z,1571178243.273 [IBIT](INFO): Initialize IBIT Component.
2019-10-15T22:24:03.274Z,1571178243.274 [CBIT](DEBUG): Initialize CBIT Component.
2019-10-15T22:24:03.275Z,1571178243.275 [logger ThreadHandler](INFO): Handler Thread ID is 897
2019-10-15T22:24:03.285Z,1571178243.285 [CBIT](DEBUG): Initialized mux pins.
2019-10-15T22:24:03.285Z,1571178243.285 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2019-10-15T22:24:03.285Z,1571178243.285 [CBIT](DEBUG): Initializing the watchdog timer.
2019-10-15T22:24:03.293Z,1571178243.293 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 898
2019-10-15T22:24:03.294Z,1571178243.294 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-10-15T22:24:03.305Z,1571178243.305 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 899
2019-10-15T22:24:03.309Z,1571178243.309 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-10-15T22:24:03.309Z,1571178243.309 [CBIT](DEBUG): Initializing heartbeat.
2019-10-15T22:24:03.317Z,1571178243.317 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 900
2019-10-15T22:24:03.318Z,1571178243.318 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-15T22:24:03.321Z,1571178243.321 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-15T22:24:03.322Z,1571178243.322 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 902
2019-10-15T22:24:03.324Z,1571178243.324 [WetLabsBB2FL](INFO): Powering down
2019-10-15T22:24:03.350Z,1571178243.350 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 903
2019-10-15T22:24:03.357Z,1571178243.357 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-10-15T22:24:03.357Z,1571178243.357 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-10-15T22:24:03.357Z,1571178243.357 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-10-15T22:24:03.358Z,1571178243.358 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-10-15T22:24:03.358Z,1571178243.358 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-10-15T22:24:03.358Z,1571178243.358 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-10-15T22:24:03.358Z,1571178243.358 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-10-15T22:24:03.358Z,1571178243.358 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-10-15T22:24:03.358Z,1571178243.358 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-10-15T22:24:03.359Z,1571178243.359 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-10-15T22:24:03.359Z,1571178243.359 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-10-15T22:24:03.359Z,1571178243.359 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-10-15T22:24:03.359Z,1571178243.359 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-10-15T22:24:03.359Z,1571178243.359 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-10-15T22:24:03.360Z,1571178243.360 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-10-15T22:24:03.360Z,1571178243.360 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-10-15T22:24:03.381Z,1571178243.381 [CBIT](DEBUG): Deactivating GF circuits.
2019-10-15T22:24:03.381Z,1571178243.381 [CBIT](DEBUG): Deactivating emergency mode.
2019-10-15T22:24:03.417Z,1571178243.417 [CBIT](DEBUG): Backplane powered.
2019-10-15T22:24:03.418Z,1571178243.418 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-10-15T22:24:03.450Z,1571178243.450 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-10-15T22:24:03.461Z,1571178243.461 [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-10-15T22:24:03.461Z,1571178243.461 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-10-15T22:24:03.520Z,1571178243.520 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-10-15T22:24:03.537Z,1571178243.537 [Default:A.Wait](DEBUG): Construct Wait.
2019-10-15T22:24:03.539Z,1571178243.539 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-10-15T22:24:03.568Z,1571178243.568 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-10-15T22:24:03.570Z,1571178243.570 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-10-15T22:24:03.584Z,1571178243.584 [Default:E.Execute](DEBUG): Construct Execute.
2019-10-15T22:24:03.587Z,1571178243.587 [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-10-15T22:24:03.612Z,1571178243.612 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,DAT,BPC1,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-10-15T22:24:03.633Z,1571178243.633 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-10-15T22:24:03.709Z,1571178243.709 [Radio_Surface](INFO): Powering up
2019-10-15T22:24:03.716Z,1571178243.716 [Depth_Keller](ERROR): Pressure reading out of range: 1925.893799 decibar
2019-10-15T22:24:03.743Z,1571178243.743 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2019-10-15T22:24:03.743Z,1571178243.743 [DAT](INFO): Powering up
2019-10-15T22:24:03.743Z,1571178243.743 [DAT](DEBUG): Initializing DAT.
2019-10-15T22:24:03.753Z,1571178243.753 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2019-10-15T22:24:03.805Z,1571178243.805 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-10-15T22:24:03.891Z,1571178243.891 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-10-15T22:24:03.901Z,1571178243.901 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-10-15T22:24:03.902Z,1571178243.902 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-10-15T22:24:03.909Z,1571178243.909 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-10-15T22:24:03.910Z,1571178243.910 [MassServo](DEBUG): Initializing EZServoServo.
2019-10-15T22:24:03.917Z,1571178243.917 [MassServo](DEBUG): Initializing MassServo.
2019-10-15T22:24:03.918Z,1571178243.918 [RudderServo](DEBUG): Initializing EZServoServo.
2019-10-15T22:24:03.925Z,1571178243.925 [RudderServo](DEBUG): Initializing RudderServo.
2019-10-15T22:24:03.926Z,1571178243.926 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-10-15T22:24:03.933Z,1571178243.933 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-10-15T22:24:04.135Z,1571178244.135 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2019-10-15T22:24:04.135Z,1571178244.135 [DropWeight] Hardware Fault, FailCount= 1
2019-10-15T22:24:04.135Z,1571178244.135 [DropWeight](ERROR): Hardware Fault
2019-10-15T22:24:04.178Z,1571178244.178 [CommandLine](FAULT): Scheduling is paused
2019-10-15T22:24:04.179Z,1571178244.179 [CBIT](INFO): Critical error at 20191015T222404
2019-10-15T22:24:04.179Z,1571178244.179 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-10-15T22:24:04.182Z,1571178244.182 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-10-15T22:24:04.182Z,1571178244.182 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-10-15T22:24:04.809Z,1571178244.809 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-10-15T22:24:04.810Z,1571178244.810 [RudderServo](FAULT): Rudder failed to initialize
2019-10-15T22:24:04.810Z,1571178244.810 [RudderServo] Communications Fault, FailCount= 1
2019-10-15T22:24:04.810Z,1571178244.810 [RudderServo](ERROR): Communications Fault
2019-10-15T22:24:04.954Z,1571178244.954 [CBIT](INFO): Critical error at 20191015T222404
2019-10-15T22:24:04.956Z,1571178244.956 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-10-15T22:24:05.158Z,1571178245.158 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-10-15T22:24:05.158Z,1571178245.158 [RudderServo](INFO): Powering down
2019-10-15T22:24:05.823Z,1571178245.823 [RudderServo](DEBUG): Initializing EZServoServo.
2019-10-15T22:24:05.942Z,1571178245.942 [RudderServo](DEBUG): Initializing RudderServo.
2019-10-15T22:24:05.946Z,1571178245.946 [CBIT](INFO): Clearing failed state for component RudderServo
2019-10-15T22:24:05.946Z,1571178245.946 [RudderServo] No Fault, FailCount= 1
2019-10-15T22:24:07.305Z,1571178247.305 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:24:07.717Z,1571178247.717 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:24:08.217Z,1571178248.217 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:24:08.793Z,1571178248.793 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:24:09.245Z,1571178249.245 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:24:09.737Z,1571178249.737 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:24:10.473Z,1571178250.473 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:24:10.921Z,1571178250.921 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:24:11.357Z,1571178251.357 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:24:11.805Z,1571178251.805 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:24:12.225Z,1571178252.225 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:24:12.681Z,1571178252.681 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:24:13.105Z,1571178253.105 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:24:13.589Z,1571178253.589 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:24:14.077Z,1571178254.077 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:24:14.513Z,1571178254.513 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:24:14.945Z,1571178254.945 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:24:15.433Z,1571178255.433 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:24:15.865Z,1571178255.865 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:24:16.285Z,1571178256.285 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:24:16.369Z,1571178256.369 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-10-15T22:24:16.369Z,1571178256.369 [CTD_Seabird] Communications Fault, FailCount= 1
2019-10-15T22:24:16.369Z,1571178256.369 [CTD_Seabird](ERROR): Communications Fault
2019-10-15T22:24:16.397Z,1571178256.397 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-10-15T22:24:16.717Z,1571178256.717 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:24:16.717Z,1571178256.717 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-10-15T22:24:16.717Z,1571178256.717 [AHRS_M2] Communications Fault, FailCount= 1
2019-10-15T22:24:16.717Z,1571178256.717 [AHRS_M2](ERROR): Communications Fault
2019-10-15T22:24:16.773Z,1571178256.773 [CTD_Seabird](INFO): Powering down
2019-10-15T22:24:16.825Z,1571178256.825 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-10-15T22:24:16.830Z,1571178256.830 [AHRS_M2](INFO): Powering down
2019-10-15T22:24:17.741Z,1571178257.741 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-10-15T22:24:17.741Z,1571178257.741 [AHRS_M2] No Fault, FailCount= 1
2019-10-15T22:24:17.742Z,1571178257.742 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-10-15T22:24:17.742Z,1571178257.742 [CTD_Seabird] No Fault, FailCount= 1
2019-10-15T22:24:18.040Z,1571178258.040 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-10-15T22:24:18.133Z,1571178258.133 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-15T22:24:18.135Z,1571178258.135 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-15T22:24:21.585Z,1571178261.585 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:24:21.590Z,1571178261.590 [DVL_micro](ERROR): No DVL communication! Re-initializing
2019-10-15T22:24:21.590Z,1571178261.590 [DVL_micro] Communications Fault, FailCount= 1
2019-10-15T22:24:21.590Z,1571178261.590 [DVL_micro](ERROR): Communications Fault
2019-10-15T22:24:21.674Z,1571178261.674 [CBIT](ERROR): Communications Fault in component: DVL_micro
2019-10-15T22:24:22.017Z,1571178262.017 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:24:22.093Z,1571178262.093 [DVL_micro](INFO): Powering down
2019-10-15T22:24:22.261Z,1571178262.261 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:24:22.669Z,1571178262.669 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:24:22.733Z,1571178262.733 [CBIT](INFO): Clearing failed state for component DVL_micro
2019-10-15T22:24:22.733Z,1571178262.733 [DVL_micro] No Fault, FailCount= 1
2019-10-15T22:24:24.834Z,1571178264.834 [CommandLine](IMPORTANT): got command restart application
2019-10-15T22:24:25.838Z,1571178265.838 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-15T22:24:25.838Z,1571178265.838 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:24:26.005Z,1571178266.005 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-10-15T22:24:26.005Z,1571178266.005 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:24:26.006Z,1571178266.006 [CommandLine](INFO): Join timeout helper Thread ID is 920
2019-10-15T22:24:26.017Z,1571178266.017 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-10-15T22:24:26.017Z,1571178266.017 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:24:26.017Z,1571178266.017 [NavChartDb](INFO): Join timeout helper Thread ID is 921
2019-10-15T22:24:26.330Z,1571178266.330 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-15T22:24:26.330Z,1571178266.330 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:24:26.345Z,1571178266.345 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-10-15T22:24:26.345Z,1571178266.345 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:24:26.345Z,1571178266.345 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 922
2019-10-15T22:24:26.505Z,1571178266.505 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-15T22:24:26.505Z,1571178266.505 [WetLabsBB2FL](INFO): Powering down
2019-10-15T22:24:26.506Z,1571178266.506 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:24:26.525Z,1571178266.525 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-10-15T22:24:26.525Z,1571178266.525 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:24:26.525Z,1571178266.525 [CTD_Seabird](INFO): Join timeout helper Thread ID is 923
2019-10-15T22:24:27.169Z,1571178267.169 [CTD_Seabird](INFO): Powering down
2019-10-15T22:24:27.301Z,1571178267.301 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-15T22:24:27.301Z,1571178267.301 [CTD_Seabird](INFO): Powering down
2019-10-15T22:24:27.313Z,1571178267.313 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:24:27.313Z,1571178267.313 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-10-15T22:24:27.313Z,1571178267.313 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:24:27.314Z,1571178267.314 [Radio_Surface](INFO): Join timeout helper Thread ID is 924
2019-10-15T22:24:27.553Z,1571178267.553 [Radio_Surface](INFO): Powering down
2019-10-15T22:24:27.554Z,1571178267.554 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-15T22:24:27.554Z,1571178267.554 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:24:27.574Z,1571178267.574 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-10-15T22:24:27.574Z,1571178267.574 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:24:27.574Z,1571178267.574 [DataOverHttps](INFO): Join timeout helper Thread ID is 925
2019-10-15T22:24:28.393Z,1571178268.393 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-15T22:24:28.394Z,1571178268.394 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:24:28.413Z,1571178268.413 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-10-15T22:24:28.414Z,1571178268.414 [logger ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:24:28.414Z,1571178268.414 [logger](INFO): Join timeout helper Thread ID is 926
2019-10-15T22:24:28.449Z,1571178268.449 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-15T22:24:28.449Z,1571178268.449 [logger ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:24:28.453Z,1571178268.453 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-10-15T22:24:28.454Z,1571178268.454 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:24:28.454Z,1571178268.454 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-10-15T22:24:28.454Z,1571178268.454 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:24:28.454Z,1571178268.454 [controlThread](INFO): Join timeout helper Thread ID is 927
2019-10-15T22:24:28.621Z,1571178268.621 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-15T22:24:28.621Z,1571178268.621 [controlThread](DEBUG): Uninitializing ControlThread
2019-10-15T22:24:28.621Z,1571178268.621 [AHRS_M2](INFO): Powering down
2019-10-15T22:24:28.765Z,1571178268.765 [DVL_micro](INFO): Powering down
2019-10-15T22:24:28.766Z,1571178268.766 [NAL9602](INFO): Powering down
2019-10-15T22:24:28.785Z,1571178268.785 [DAT](INFO): Powering down
2019-10-15T22:24:28.943Z,1571178268.943 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-10-15T22:24:28.944Z,1571178268.944 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-10-15T22:24:28.944Z,1571178268.944 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-10-15T22:24:28.945Z,1571178268.945 [MissionManager](INFO): Uninitializing Mission Default
2019-10-15T22:24:28.947Z,1571178268.947 [MissionManager](INFO): Uninitializing Mission Startup
2019-10-15T22:24:28.948Z,1571178268.948 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-10-15T22:24:28.948Z,1571178268.948 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-10-15T22:24:28.949Z,1571178268.949 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-10-15T22:24:28.949Z,1571178268.949 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-10-15T22:24:28.949Z,1571178268.949 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-10-15T22:24:28.950Z,1571178268.950 [BuoyancyServo](INFO): Powering down
2019-10-15T22:24:28.961Z,1571178268.961 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-10-15T22:24:28.961Z,1571178268.961 [ElevatorServo](INFO): Powering down
2019-10-15T22:24:28.980Z,1571178268.980 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-10-15T22:24:28.980Z,1571178268.980 [MassServo](INFO): Powering down
2019-10-15T22:24:28.981Z,1571178268.981 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-10-15T22:24:28.981Z,1571178268.981 [RudderServo](INFO): Powering down
2019-10-15T22:24:28.982Z,1571178268.982 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-10-15T22:24:28.982Z,1571178268.982 [ThrusterServo](INFO): Powering down
2019-10-15T22:24:28.983Z,1571178268.983 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-10-15T22:24:28.983Z,1571178268.983 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-10-15T22:24:28.983Z,1571178268.983 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-10-15T22:24:28.984Z,1571178268.984 [CBIT](DEBUG): Powering off loads.
2019-10-15T22:24:28.997Z,1571178268.997 [CBIT](DEBUG): Disabling WDT.
2019-10-15T22:24:29.009Z,1571178269.009 [CBIT](DEBUG): Opening all GF detection circuits.
2019-10-15T22:24:29.011Z,1571178269.011 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:24:29.203Z,1571178269.203 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:24:29.210Z,1571178269.210 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:24:29.244Z,1571178269.244 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:24:29.248Z,1571178269.248 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:24:29.282Z,1571178269.282 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-10-15T22:24:29.336Z,1571178269.336 [logger ThreadHandler](INFO): Thread cancelled.