2019-10-15T22:18:59.977Z,1571177939.977 [Supervisor](DEBUG): Initializing supervisor.
2019-10-15T22:18:59.979Z,1571177939.979 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-10-15T22:18:59.980Z,1571177939.980 [SyncHandler](INFO): Protected caller Thread ID is 807
2019-10-15T22:18:59.980Z,1571177939.980 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-10-15T22:18:59.981Z,1571177939.981 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-10-15T22:18:59.982Z,1571177939.982 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 808
2019-10-15T22:18:59.984Z,1571177939.984 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-10-15T22:18:59.995Z,1571177939.995 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-10-15T22:18:59.996Z,1571177939.996 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-10-15T22:18:59.996Z,1571177939.996 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 809
2019-10-15T22:18:59.997Z,1571177939.997 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-10-15T22:18:59.998Z,1571177939.998 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-10-15T22:18:59.999Z,1571177939.999 [logger ThreadHandler](INFO): Protected caller Thread ID is 810
2019-10-15T22:19:00.001Z,1571177940.001 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-10-15T22:19:00.001Z,1571177940.001 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-10-15T22:19:00.005Z,1571177940.005 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-10-15T22:19:00.415Z,1571177940.415 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-10-15T22:19:00.417Z,1571177940.417 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-10-15T22:19:00.559Z,1571177940.559 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-10-15T22:19:00.560Z,1571177940.560 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-10-15T22:19:00.643Z,1571177940.643 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-10-15T22:19:00.741Z,1571177940.741 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-10-15T22:19:00.743Z,1571177940.743 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-10-15T22:19:00.838Z,1571177940.838 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-10-15T22:19:00.839Z,1571177940.839 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-10-15T22:19:01.209Z,1571177941.209 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-10-15T22:19:01.210Z,1571177941.210 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-10-15T22:19:01.312Z,1571177941.312 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-10-15T22:19:01.314Z,1571177941.314 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-10-15T22:19:01.762Z,1571177941.762 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-10-15T22:19:01.763Z,1571177941.763 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-10-15T22:19:01.959Z,1571177941.959 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-10-15T22:19:01.961Z,1571177941.961 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-10-15T22:19:02.258Z,1571177942.258 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-10-15T22:19:02.260Z,1571177942.260 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-10-15T22:19:02.470Z,1571177942.470 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-10-15T22:19:02.471Z,1571177942.471 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-10-15T22:19:02.611Z,1571177942.611 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-10-15T22:19:02.612Z,1571177942.612 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-10-15T22:19:03.237Z,1571177943.237 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-10-15T22:19:03.238Z,1571177943.238 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-10-15T22:19:03.651Z,1571177943.651 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-10-15T22:19:03.797Z,1571177943.797 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-10-15T22:19:04.264Z,1571177944.264 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-10-15T22:19:04.266Z,1571177944.266 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2019-10-15T22:19:04.269Z,1571177944.269 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2019-10-15T22:19:04.463Z,1571177944.463 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2019-10-15T22:19:04.546Z,1571177944.546 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2019-10-15T22:19:04.681Z,1571177944.681 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2019-10-15T22:19:04.767Z,1571177944.767 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2019-10-15T22:19:04.990Z,1571177944.990 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-10-15T22:19:04.991Z,1571177944.991 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2019-10-15T22:19:05.085Z,1571177945.085 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2019-10-15T22:19:05.192Z,1571177945.192 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2019-10-15T22:19:05.285Z,1571177945.286 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2019-10-15T22:19:05.432Z,1571177945.432 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2019-10-15T22:19:05.530Z,1571177945.530 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2019-10-15T22:19:05.630Z,1571177945.630 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2019-10-15T22:19:05.631Z,1571177945.631 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-10-15T22:19:05.640Z,1571177945.640 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-10-15T22:19:05.665Z,1571177945.665 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-10-15T22:19:05.667Z,1571177945.667 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-10-15T22:19:05.755Z,1571177945.755 [VerticalControl](DEBUG): Construct VerticalControl.
2019-10-15T22:19:05.866Z,1571177945.866 [VerticalControl] Loaded
2019-10-15T22:19:05.866Z,1571177945.866 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-10-15T22:19:05.867Z,1571177945.867 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-10-15T22:19:05.935Z,1571177945.935 [HorizontalControl] Loaded
2019-10-15T22:19:05.935Z,1571177945.935 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-10-15T22:19:05.936Z,1571177945.936 [SpeedControl](DEBUG): Construct SpeedControl.
2019-10-15T22:19:05.941Z,1571177945.941 [SpeedControl] Loaded
2019-10-15T22:19:05.942Z,1571177945.942 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-10-15T22:19:05.942Z,1571177945.942 [LoopControl](DEBUG): Construct LoopControl.
2019-10-15T22:19:05.943Z,1571177945.943 [LoopControl] Loaded
2019-10-15T22:19:05.943Z,1571177945.943 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-10-15T22:19:05.944Z,1571177945.944 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-10-15T22:19:05.946Z,1571177945.946 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-10-15T22:19:06.094Z,1571177946.094 [BuoyancyServo] Loaded
2019-10-15T22:19:06.095Z,1571177946.095 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-10-15T22:19:06.106Z,1571177946.106 [ElevatorServo] Loaded
2019-10-15T22:19:06.106Z,1571177946.106 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-10-15T22:19:06.117Z,1571177946.117 [MassServo] Loaded
2019-10-15T22:19:06.117Z,1571177946.117 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-10-15T22:19:06.128Z,1571177946.128 [RudderServo] Loaded
2019-10-15T22:19:06.128Z,1571177946.128 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-10-15T22:19:06.139Z,1571177946.139 [ThrusterServo] Loaded
2019-10-15T22:19:06.139Z,1571177946.139 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-10-15T22:19:06.140Z,1571177946.140 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-10-15T22:19:06.141Z,1571177946.141 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-10-15T22:19:06.196Z,1571177946.196 [DepthRateCalculator] Loaded
2019-10-15T22:19:06.197Z,1571177946.197 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-10-15T22:19:06.202Z,1571177946.202 [PitchRateCalculator] Loaded
2019-10-15T22:19:06.202Z,1571177946.202 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-10-15T22:19:06.217Z,1571177946.217 [SpeedCalculator] Loaded
2019-10-15T22:19:06.217Z,1571177946.217 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-10-15T22:19:06.238Z,1571177946.238 [TempGradientCalculator] Loaded
2019-10-15T22:19:06.238Z,1571177946.238 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-10-15T22:19:06.243Z,1571177946.243 [YawRateCalculator] Loaded
2019-10-15T22:19:06.244Z,1571177946.244 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-10-15T22:19:06.273Z,1571177946.273 [ElevatorOffsetCalculator] Loaded
2019-10-15T22:19:06.273Z,1571177946.273 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-10-15T22:19:06.273Z,1571177946.273 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-10-15T22:19:06.274Z,1571177946.274 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-10-15T22:19:06.392Z,1571177946.392 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-10-15T22:19:06.393Z,1571177946.393 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-10-15T22:19:06.414Z,1571177946.414 [NavChart] Loaded
2019-10-15T22:19:06.414Z,1571177946.414 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-10-15T22:19:06.418Z,1571177946.418 [UniversalFixResidualReporter] Loaded
2019-10-15T22:19:06.418Z,1571177946.418 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-10-15T22:19:06.418Z,1571177946.418 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-10-15T22:19:06.419Z,1571177946.419 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-10-15T22:19:06.875Z,1571177946.875 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2019-10-15T22:19:06.880Z,1571177946.880 [AHRS_M2](INFO): created writer for : platform_orientation
2019-10-15T22:19:06.882Z,1571177946.882 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2019-10-15T22:19:06.887Z,1571177946.887 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2019-10-15T22:19:06.888Z,1571177946.888 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2019-10-15T22:19:06.892Z,1571177946.892 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2019-10-15T22:19:06.893Z,1571177946.893 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2019-10-15T22:19:06.898Z,1571177946.898 [AHRS_M2](INFO): created writer for : platform_roll_angle
2019-10-15T22:19:07.074Z,1571177947.074 [AHRS_M2] Loaded
2019-10-15T22:19:07.074Z,1571177947.074 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-10-15T22:19:07.228Z,1571177947.228 [DataOverHttps] Loaded
2019-10-15T22:19:07.228Z,1571177947.228 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-10-15T22:19:07.230Z,1571177947.230 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407F84E0
2019-10-15T22:19:07.230Z,1571177947.230 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 890
2019-10-15T22:19:07.243Z,1571177947.243 [Depth_Keller] Loaded
2019-10-15T22:19:07.244Z,1571177947.244 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-10-15T22:19:07.248Z,1571177947.248 [DropWeight] Loaded
2019-10-15T22:19:07.249Z,1571177947.249 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-10-15T22:19:07.344Z,1571177947.344 [NAL9602] Loaded
2019-10-15T22:19:07.345Z,1571177947.345 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-10-15T22:19:07.360Z,1571177947.360 [Onboard] Loaded
2019-10-15T22:19:07.360Z,1571177947.360 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-10-15T22:19:07.364Z,1571177947.364 [Radio_Surface] Loaded
2019-10-15T22:19:07.364Z,1571177947.364 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-10-15T22:19:07.365Z,1571177947.365 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408284E0
2019-10-15T22:19:07.366Z,1571177947.366 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 891
2019-10-15T22:19:09.134Z,1571177949.134 [BPC1] Loaded
2019-10-15T22:19:09.134Z,1571177949.134 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-10-15T22:19:09.135Z,1571177949.135 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-10-15T22:19:09.135Z,1571177949.135 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-10-15T22:19:09.547Z,1571177949.547 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-10-15T22:19:09.548Z,1571177949.548 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-10-15T22:19:09.674Z,1571177949.674 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-10-15T22:19:09.674Z,1571177949.674 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-10-15T22:19:09.723Z,1571177949.723 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-10-15T22:19:09.723Z,1571177949.723 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-10-15T22:19:09.971Z,1571177949.971 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-10-15T22:19:09.976Z,1571177949.976 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-10-15T22:19:09.977Z,1571177949.977 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-10-15T22:19:09.982Z,1571177949.982 [CTD_Seabird](INFO): created writer for : depth
2019-10-15T22:19:09.982Z,1571177949.982 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-10-15T22:19:09.987Z,1571177949.987 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-10-15T22:19:09.988Z,1571177949.988 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-10-15T22:19:09.993Z,1571177949.993 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-10-15T22:19:09.994Z,1571177949.994 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-10-15T22:19:09.999Z,1571177949.999 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-10-15T22:19:09.999Z,1571177949.999 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-10-15T22:19:10.005Z,1571177950.005 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-10-15T22:19:10.005Z,1571177950.005 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-10-15T22:19:10.010Z,1571177950.010 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-10-15T22:19:10.011Z,1571177950.011 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): mass_concentration_of_oxygen_in_sea_water
2019-10-15T22:19:10.016Z,1571177950.016 [CTD_Seabird](INFO): created writer for : mass_concentration_of_oxygen_in_sea_water
2019-10-15T22:19:10.017Z,1571177950.017 [CTD_Seabird](DEBUG): LcmSlateWriter::add(DAtaURI): CTD_Seabird.sea_water_dissolved_oxygen_freq
2019-10-15T22:19:10.017Z,1571177950.017 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): dataName: sea_water_dissolved_oxygen_freq
2019-10-15T22:19:10.049Z,1571177950.049 [CTD_Seabird] Loaded
2019-10-15T22:19:10.049Z,1571177950.049 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-10-15T22:19:10.050Z,1571177950.050 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409BF4E0
2019-10-15T22:19:10.050Z,1571177950.050 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 892
2019-10-15T22:19:10.081Z,1571177950.081 [ESPComponent] Loaded
2019-10-15T22:19:10.081Z,1571177950.081 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-10-15T22:19:10.095Z,1571177950.095 [PAR_Licor] Loaded
2019-10-15T22:19:10.095Z,1571177950.095 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-10-15T22:19:10.143Z,1571177950.143 [WetLabsBB2FL] Loaded
2019-10-15T22:19:10.144Z,1571177950.144 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-10-15T22:19:10.145Z,1571177950.145 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 409EF4E0
2019-10-15T22:19:10.145Z,1571177950.145 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 893
2019-10-15T22:19:10.146Z,1571177950.146 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-10-15T22:19:10.146Z,1571177950.146 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-10-15T22:19:10.490Z,1571177950.490 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-10-15T22:19:10.490Z,1571177950.490 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-10-15T22:19:10.649Z,1571177950.649 [SBIT](DEBUG): Construct Startup Built In Test.
2019-10-15T22:19:10.660Z,1571177950.660 [SBIT] Loaded
2019-10-15T22:19:10.661Z,1571177950.661 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-10-15T22:19:10.661Z,1571177950.661 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-10-15T22:19:10.674Z,1571177950.674 [IBIT] Loaded
2019-10-15T22:19:10.674Z,1571177950.674 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-10-15T22:19:10.677Z,1571177950.677 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-10-15T22:19:10.813Z,1571177950.813 [CBIT] Loaded
2019-10-15T22:19:10.814Z,1571177950.814 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-10-15T22:19:10.814Z,1571177950.814 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-10-15T22:19:10.818Z,1571177950.818 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-10-15T22:19:10.819Z,1571177950.819 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-10-15T22:19:10.825Z,1571177950.825 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-10-15T22:19:10.826Z,1571177950.826 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACE4E0
2019-10-15T22:19:10.827Z,1571177950.827 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 894
2019-10-15T22:19:10.831Z,1571177950.831 [Supervisor](INFO): Main Thread ID is 803
2019-10-15T22:19:10.831Z,1571177950.831 [Supervisor](DEBUG): Running supervisor.
2019-10-15T22:19:10.832Z,1571177950.832 [CommandLine ThreadHandler](INFO): Handler Thread ID is 895
2019-10-15T22:19:10.835Z,1571177950.835 [controlThread ThreadHandler](INFO): Handler Thread ID is 896
2019-10-15T22:19:10.835Z,1571177950.835 [controlThread](DEBUG): Initializing ControlThread
2019-10-15T22:19:10.836Z,1571177950.836 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-10-15T22:19:10.837Z,1571177950.837 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-10-15T22:19:10.838Z,1571177950.838 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-10-15T22:19:10.838Z,1571177950.838 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-10-15T22:19:10.840Z,1571177950.840 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-10-15T22:19:10.840Z,1571177950.840 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-10-15T22:19:10.840Z,1571177950.840 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-10-15T22:19:10.841Z,1571177950.841 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-10-15T22:19:10.842Z,1571177950.842 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-10-15T22:19:10.842Z,1571177950.842 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-10-15T22:19:10.843Z,1571177950.843 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-10-15T22:19:10.843Z,1571177950.843 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-10-15T22:19:10.849Z,1571177950.849 [SBIT](INFO): Initialize SBIT Component.
2019-10-15T22:19:10.850Z,1571177950.850 [SBIT](IMPORTANT): git: 2019-09-23_TRN-5-g307b34b
2019-10-15T22:19:10.850Z,1571177950.850 [SBIT](INFO): git hash: 307b34b03e5aa7dd6ba91a7832117416aca74dd3
2019-10-15T22:19:10.850Z,1571177950.850 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-10-15T22:19:10.851Z,1571177950.851 [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:19:10.852Z,1571177950.852 [SBIT](INFO): Beginning SBIT in 38.000000 seconds.
2019-10-15T22:19:10.853Z,1571177950.853 [IBIT](INFO): Initialize IBIT Component.
2019-10-15T22:19:10.854Z,1571177950.854 [CBIT](DEBUG): Initialize CBIT Component.
2019-10-15T22:19:10.855Z,1571177950.855 [logger ThreadHandler](INFO): Handler Thread ID is 897
2019-10-15T22:19:10.865Z,1571177950.865 [CBIT](DEBUG): Initialized mux pins.
2019-10-15T22:19:10.865Z,1571177950.865 [CBIT](DEBUG): Initializing the watchdog timer.
2019-10-15T22:19:10.873Z,1571177950.873 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 898
2019-10-15T22:19:10.874Z,1571177950.874 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-10-15T22:19:10.885Z,1571177950.885 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 899
2019-10-15T22:19:10.889Z,1571177950.889 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-10-15T22:19:10.889Z,1571177950.889 [CBIT](DEBUG): Initializing heartbeat.
2019-10-15T22:19:10.897Z,1571177950.897 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 900
2019-10-15T22:19:10.898Z,1571177950.898 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-15T22:19:10.901Z,1571177950.901 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-15T22:19:10.903Z,1571177950.903 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 902
2019-10-15T22:19:10.904Z,1571177950.904 [WetLabsBB2FL](INFO): Powering down
2019-10-15T22:19:10.930Z,1571177950.930 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 903
2019-10-15T22:19:10.938Z,1571177950.938 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-10-15T22:19:10.939Z,1571177950.939 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-10-15T22:19:10.939Z,1571177950.939 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-10-15T22:19:10.939Z,1571177950.939 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-10-15T22:19:10.939Z,1571177950.939 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-10-15T22:19:10.939Z,1571177950.939 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-10-15T22:19:10.939Z,1571177950.939 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-10-15T22:19:10.940Z,1571177950.940 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-10-15T22:19:10.940Z,1571177950.940 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-10-15T22:19:10.940Z,1571177950.940 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-10-15T22:19:10.940Z,1571177950.940 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-10-15T22:19:10.940Z,1571177950.940 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-10-15T22:19:10.940Z,1571177950.940 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-10-15T22:19:10.941Z,1571177950.941 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-10-15T22:19:10.941Z,1571177950.941 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-10-15T22:19:10.941Z,1571177950.941 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-10-15T22:19:10.961Z,1571177950.961 [CBIT](DEBUG): Deactivating GF circuits.
2019-10-15T22:19:10.961Z,1571177950.961 [CBIT](DEBUG): Deactivating emergency mode.
2019-10-15T22:19:10.997Z,1571177950.997 [CBIT](DEBUG): Backplane powered.
2019-10-15T22:19:10.998Z,1571177950.998 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-10-15T22:19:11.022Z,1571177951.022 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-10-15T22:19:11.032Z,1571177951.032 [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:19:11.049Z,1571177951.049 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-10-15T22:19:11.118Z,1571177951.118 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-10-15T22:19:11.119Z,1571177951.119 [Default:A.Wait](DEBUG): Construct Wait.
2019-10-15T22:19:11.120Z,1571177951.120 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-10-15T22:19:11.164Z,1571177951.164 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-10-15T22:19:11.167Z,1571177951.167 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-10-15T22:19:11.185Z,1571177951.185 [Default:E.Execute](DEBUG): Construct Execute.
2019-10-15T22:19:11.195Z,1571177951.195 [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:19:11.199Z,1571177951.199 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,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:19:11.209Z,1571177951.209 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-10-15T22:19:11.289Z,1571177951.289 [Radio_Surface](INFO): Powering up
2019-10-15T22:19:11.515Z,1571177951.515 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-10-15T22:19:11.521Z,1571177951.521 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-10-15T22:19:11.522Z,1571177951.522 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-10-15T22:19:11.529Z,1571177951.529 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-10-15T22:19:11.530Z,1571177951.530 [MassServo](DEBUG): Initializing EZServoServo.
2019-10-15T22:19:11.537Z,1571177951.537 [MassServo](DEBUG): Initializing MassServo.
2019-10-15T22:19:11.538Z,1571177951.538 [RudderServo](DEBUG): Initializing EZServoServo.
2019-10-15T22:19:11.545Z,1571177951.545 [RudderServo](DEBUG): Initializing RudderServo.
2019-10-15T22:19:11.546Z,1571177951.546 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-10-15T22:19:11.553Z,1571177951.553 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-10-15T22:19:11.622Z,1571177951.622 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2019-10-15T22:19:11.622Z,1571177951.622 [DropWeight] Hardware Fault, FailCount= 1
2019-10-15T22:19:11.622Z,1571177951.622 [DropWeight](ERROR): Hardware Fault
2019-10-15T22:19:11.716Z,1571177951.716 [CommandLine](FAULT): Scheduling is paused
2019-10-15T22:19:11.717Z,1571177951.717 [CBIT](INFO): Critical error at 20191015T221911
2019-10-15T22:19:11.717Z,1571177951.717 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-10-15T22:19:11.719Z,1571177951.719 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-10-15T22:19:11.720Z,1571177951.720 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-10-15T22:19:12.365Z,1571177952.365 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-10-15T22:19:12.365Z,1571177952.365 [RudderServo](FAULT): Rudder failed to initialize
2019-10-15T22:19:12.365Z,1571177952.365 [RudderServo] Communications Fault, FailCount= 1
2019-10-15T22:19:12.365Z,1571177952.365 [RudderServo](ERROR): Communications Fault
2019-10-15T22:19:12.570Z,1571177952.570 [CBIT](INFO): Critical error at 20191015T221911
2019-10-15T22:19:12.573Z,1571177952.573 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-10-15T22:19:12.774Z,1571177952.774 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-10-15T22:19:12.774Z,1571177952.774 [RudderServo](INFO): Powering down
2019-10-15T22:19:13.457Z,1571177953.457 [RudderServo](DEBUG): Initializing EZServoServo.
2019-10-15T22:19:13.578Z,1571177953.578 [RudderServo](DEBUG): Initializing RudderServo.
2019-10-15T22:19:13.582Z,1571177953.582 [CBIT](INFO): Clearing failed state for component RudderServo
2019-10-15T22:19:13.582Z,1571177953.582 [RudderServo] No Fault, FailCount= 1
2019-10-15T22:19:14.921Z,1571177954.921 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:15.329Z,1571177955.329 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:15.949Z,1571177955.949 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:16.409Z,1571177956.409 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:16.845Z,1571177956.845 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:17.345Z,1571177957.345 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:17.885Z,1571177957.885 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:18.489Z,1571177958.489 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:18.933Z,1571177958.933 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:19.357Z,1571177959.357 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:19.761Z,1571177959.761 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:20.201Z,1571177960.201 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:20.641Z,1571177960.641 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:21.137Z,1571177961.137 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:21.553Z,1571177961.553 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:22.041Z,1571177962.041 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:22.469Z,1571177962.469 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:22.889Z,1571177962.889 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:23.333Z,1571177963.333 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:23.753Z,1571177963.753 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:23.949Z,1571177963.949 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-10-15T22:19:23.949Z,1571177963.949 [CTD_Seabird] Communications Fault, FailCount= 1
2019-10-15T22:19:23.949Z,1571177963.949 [CTD_Seabird](ERROR): Communications Fault
2019-10-15T22:19:24.173Z,1571177964.173 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:24.270Z,1571177964.270 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-10-15T22:19:24.353Z,1571177964.353 [CTD_Seabird](INFO): Powering down
2019-10-15T22:19:24.601Z,1571177964.601 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:24.601Z,1571177964.601 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-10-15T22:19:24.601Z,1571177964.601 [AHRS_M2] Communications Fault, FailCount= 1
2019-10-15T22:19:24.601Z,1571177964.601 [AHRS_M2](ERROR): Communications Fault
2019-10-15T22:19:24.701Z,1571177964.701 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-10-15T22:19:24.706Z,1571177964.706 [AHRS_M2](INFO): Powering down
2019-10-15T22:19:25.613Z,1571177965.613 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-10-15T22:19:25.613Z,1571177965.613 [AHRS_M2] No Fault, FailCount= 1
2019-10-15T22:19:25.614Z,1571177965.614 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-10-15T22:19:25.614Z,1571177965.614 [CTD_Seabird] No Fault, FailCount= 1
2019-10-15T22:19:25.717Z,1571177965.717 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-15T22:19:25.719Z,1571177965.719 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-15T22:19:25.916Z,1571177965.916 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-10-15T22:19:29.473Z,1571177969.473 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:29.869Z,1571177969.869 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:30.277Z,1571177970.277 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:30.681Z,1571177970.681 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:31.109Z,1571177971.109 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:31.509Z,1571177971.509 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:31.925Z,1571177971.925 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:32.353Z,1571177972.353 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:32.765Z,1571177972.765 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:33.177Z,1571177973.177 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:33.577Z,1571177973.577 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:33.981Z,1571177973.981 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:34.377Z,1571177974.377 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:34.789Z,1571177974.789 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:35.189Z,1571177975.189 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:35.589Z,1571177975.589 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:35.993Z,1571177975.993 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:36.389Z,1571177976.389 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:36.809Z,1571177976.809 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:37.213Z,1571177977.213 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:37.609Z,1571177977.609 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:37.614Z,1571177977.614 [NAL9602](INFO): Powering up NAL9602
2019-10-15T22:19:38.017Z,1571177978.017 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:38.417Z,1571177978.417 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:38.770Z,1571177978.770 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-10-15T22:19:38.770Z,1571177978.770 [CTD_Seabird] Communications Fault, FailCount= 2
2019-10-15T22:19:38.770Z,1571177978.770 [CTD_Seabird](ERROR): Communications Fault
2019-10-15T22:19:38.849Z,1571177978.849 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:38.849Z,1571177978.849 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-10-15T22:19:38.849Z,1571177978.849 [AHRS_M2] Communications Fault, FailCount= 2
2019-10-15T22:19:38.849Z,1571177978.849 [AHRS_M2](ERROR): Communications Fault
2019-10-15T22:19:38.922Z,1571177978.922 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-10-15T22:19:38.922Z,1571177978.922 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-10-15T22:19:38.943Z,1571177978.943 [AHRS_M2](INFO): Powering down
2019-10-15T22:19:39.173Z,1571177979.173 [CTD_Seabird](INFO): Powering down
2019-10-15T22:19:39.800Z,1571177979.800 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-10-15T22:19:39.800Z,1571177979.800 [AHRS_M2] No Fault, FailCount= 2
2019-10-15T22:19:40.136Z,1571177980.136 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-10-15T22:19:40.203Z,1571177980.203 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2019-10-15T22:19:40.203Z,1571177980.203 [CTD_Seabird] No Fault, FailCount= 2
2019-10-15T22:19:40.517Z,1571177980.517 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-15T22:19:40.525Z,1571177980.525 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-15T22:19:43.693Z,1571177983.693 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:44.105Z,1571177984.105 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:44.513Z,1571177984.513 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:44.917Z,1571177984.917 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:45.309Z,1571177985.309 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:45.717Z,1571177985.717 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:46.113Z,1571177986.113 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:46.533Z,1571177986.533 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:46.945Z,1571177986.945 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:47.349Z,1571177987.349 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:47.741Z,1571177987.741 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:48.153Z,1571177988.153 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:48.573Z,1571177988.573 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:48.578Z,1571177988.578 [NAL9602](INFO): NAL9602 initialized
2019-10-15T22:19:48.985Z,1571177988.985 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:49.417Z,1571177989.417 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:49.439Z,1571177989.439 [NAL9602](DEBUG): Fix Requested
2019-10-15T22:19:49.475Z,1571177989.475 [SBIT](IMPORTANT): Beginning Startup BIT
2019-10-15T22:19:49.503Z,1571177989.503 [CBIT](IMPORTANT): Beginning ground fault scan
2019-10-15T22:19:49.853Z,1571177989.853 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:50.433Z,1571177990.433 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:50.925Z,1571177990.925 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:51.341Z,1571177991.341 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:51.729Z,1571177991.729 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:52.593Z,1571177992.593 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:53.173Z,1571177993.173 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:53.173Z,1571177993.173 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-10-15T22:19:53.173Z,1571177993.173 [AHRS_M2] Communications Fault, FailCount= 3
2019-10-15T22:19:53.173Z,1571177993.173 [AHRS_M2](ERROR): Communications Fault
2019-10-15T22:19:53.459Z,1571177993.459 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-10-15T22:19:53.464Z,1571177993.464 [AHRS_M2](INFO): Powering down
2019-10-15T22:19:54.413Z,1571177994.413 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-10-15T22:19:54.413Z,1571177994.413 [AHRS_M2] No Fault, FailCount= 3
2019-10-15T22:19:54.573Z,1571177994.573 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2019-10-15T22:19:54.573Z,1571177994.573 [CTD_Seabird] Communications Fault, FailCount= 3
2019-10-15T22:19:54.573Z,1571177994.573 [CTD_Seabird](ERROR): Communications Fault
2019-10-15T22:19:54.672Z,1571177994.672 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-10-15T22:19:54.898Z,1571177994.898 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2019-10-15T22:19:54.898Z,1571177994.898 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2019-10-15T22:19:54.977Z,1571177994.977 [CTD_Seabird](INFO): Powering down
2019-10-15T22:19:58.229Z,1571177998.229 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:58.749Z,1571177998.749 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:59.169Z,1571177999.169 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:19:59.597Z,1571177999.597 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:20:00.153Z,1571178000.153 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:20:00.557Z,1571178000.557 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:20:01.053Z,1571178001.053 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:20:01.497Z,1571178001.497 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:20:01.558Z,1571178001.558 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.005956
CHAN A1 (24V): 0.008783
CHAN A2 (12V): -0.005058
CHAN A3 (5V): -0.002540
CHAN B0 (3.3V): -0.000287
CHAN B1 (3.15aV): -0.001335
CHAN B2 (3.15bV): -0.001314
CHAN B3 (GND): -0.000480
OPEN: -0.004490
Full Scale Calc: 4.765 mA, -1.589 mA
2019-10-15T22:20:01.893Z,1571178001.893 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2019-10-15T22:20:17.113Z,1571178017.113 [AHRS_M2](FAULT): Failed to acquire valid data within timeout.
2019-10-15T22:20:17.113Z,1571178017.113 [AHRS_M2] Data Fault, FailCount= 4
2019-10-15T22:20:17.113Z,1571178017.113 [AHRS_M2](ERROR): Data Fault
2019-10-15T22:20:17.243Z,1571178017.243 [CBIT](ERROR): Data Fault in component: AHRS_M2
2019-10-15T22:20:17.504Z,1571178017.504 [AHRS_M2](INFO): Powering down
2019-10-15T22:20:18.402Z,1571178018.402 [CBIT](INFO): Clearing failed state for component AHRS_M2
2019-10-15T22:20:18.402Z,1571178018.402 [AHRS_M2] No Fault, FailCount= 4
2019-10-15T22:20:18.716Z,1571178018.716 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-10-15T22:20:22.273Z,1571178022.273 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-15T22:20:22.681Z,1571178022.681 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-15T22:20:23.093Z,1571178023.093 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-15T22:20:23.489Z,1571178023.489 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-15T22:20:23.881Z,1571178023.881 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-15T22:20:24.321Z,1571178024.321 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-15T22:20:24.717Z,1571178024.717 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-15T22:20:25.125Z,1571178025.125 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-15T22:20:25.533Z,1571178025.533 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-15T22:20:25.925Z,1571178025.925 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-15T22:20:26.333Z,1571178026.333 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-15T22:20:26.737Z,1571178026.737 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-15T22:20:27.141Z,1571178027.141 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-15T22:20:27.545Z,1571178027.545 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-15T22:20:27.941Z,1571178027.941 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-15T22:20:28.353Z,1571178028.353 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-15T22:20:28.753Z,1571178028.753 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-15T22:20:29.169Z,1571178029.169 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-15T22:20:29.561Z,1571178029.561 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-15T22:20:29.965Z,1571178029.965 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-15T22:20:30.393Z,1571178030.393 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-15T22:20:30.801Z,1571178030.801 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-15T22:20:31.213Z,1571178031.213 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-15T22:20:31.629Z,1571178031.629 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout
2019-10-15T22:20:31.629Z,1571178031.629 [AHRS_M2](FAULT): Failed to initialize within timeout.
2019-10-15T22:20:31.629Z,1571178031.629 [AHRS_M2] Communications Fault, FailCount= 5
2019-10-15T22:20:31.629Z,1571178031.629 [AHRS_M2](ERROR): Communications Fault
2019-10-15T22:20:31.849Z,1571178031.849 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2019-10-15T22:20:31.850Z,1571178031.850 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2
2019-10-15T22:20:31.856Z,1571178031.856 [AHRS_M2](INFO): Powering down
2019-10-15T22:20:32.415Z,1571178032.415 [CBIT](INFO): Critical error at 20191015T222031
2019-10-15T22:20:43.783Z,1571178043.783 [SBIT](IMPORTANT): SBIT PASSED
2019-10-15T22:20:43.866Z,1571178043.866 [CommandLine](IMPORTANT): got command configSet list
2019-10-15T22:20:43.867Z,1571178043.867 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-10-15T22:20:43.868Z,1571178043.868 [CommandLine](IMPORTANT): DAT.loadAtStartup=0 bool;

2019-10-15T22:20:43.868Z,1571178043.868 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup=0 bool;

2019-10-15T22:20:44.183Z,1571178044.183 [MissionManager](IMPORTANT): Started mission Startup
2019-10-15T22:20:44.183Z,1571178044.183 [Startup] Running Loop=1
2019-10-15T22:20:44.183Z,1571178044.183 [Startup](DEBUG): Aggregate::initialize Startup
2019-10-15T22:20:44.183Z,1571178044.183 [Startup:A.GoToSurface] Running Loop=1
2019-10-15T22:20:44.183Z,1571178044.183 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-10-15T22:20:44.184Z,1571178044.184 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-10-15T22:20:44.184Z,1571178044.184 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-10-15T22:20:44.185Z,1571178044.185 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-10-15T22:20:44.185Z,1571178044.185 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-10-15T22:20:44.185Z,1571178044.185 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-10-15T22:20:44.187Z,1571178044.187 [Startup:StartupSatComms] Running Loop=1
2019-10-15T22:2