2020-08-06T17:15:08.875Z,1596734108.875 [Supervisor](DEBUG): Initializing supervisor.
2020-08-06T17:15:08.878Z,1596734108.878 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-08-06T17:15:08.879Z,1596734108.879 [SyncHandler](INFO): Protected caller Thread ID is 827
2020-08-06T17:15:08.879Z,1596734108.879 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-08-06T17:15:08.880Z,1596734108.880 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-08-06T17:15:08.881Z,1596734108.881 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 828
2020-08-06T17:15:08.883Z,1596734108.883 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-08-06T17:15:08.896Z,1596734108.896 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-08-06T17:15:08.897Z,1596734108.897 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-08-06T17:15:08.897Z,1596734108.897 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 829
2020-08-06T17:15:08.898Z,1596734108.898 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-08-06T17:15:08.899Z,1596734108.899 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-08-06T17:15:08.899Z,1596734108.899 [logger ThreadHandler](INFO): Protected caller Thread ID is 830
2020-08-06T17:15:08.901Z,1596734108.901 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-08-06T17:15:08.902Z,1596734108.902 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-08-06T17:15:08.906Z,1596734108.906 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-08-06T17:15:09.327Z,1596734109.327 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-08-06T17:15:09.329Z,1596734109.329 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-08-06T17:15:09.469Z,1596734109.469 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-08-06T17:15:09.470Z,1596734109.470 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-08-06T17:15:09.550Z,1596734109.550 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-08-06T17:15:09.739Z,1596734109.739 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-08-06T17:15:09.741Z,1596734109.741 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-08-06T17:15:09.838Z,1596734109.838 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-08-06T17:15:09.840Z,1596734109.840 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-08-06T17:15:09.936Z,1596734109.936 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-08-06T17:15:09.938Z,1596734109.938 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-08-06T17:15:10.332Z,1596734110.332 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-08-06T17:15:10.333Z,1596734110.333 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-08-06T17:15:10.435Z,1596734110.435 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-08-06T17:15:10.436Z,1596734110.436 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-08-06T17:15:10.883Z,1596734110.883 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-08-06T17:15:10.885Z,1596734110.885 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-08-06T17:15:11.070Z,1596734111.070 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-08-06T17:15:11.071Z,1596734111.071 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-08-06T17:15:11.370Z,1596734111.370 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-08-06T17:15:11.372Z,1596734111.372 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-08-06T17:15:11.584Z,1596734111.584 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-08-06T17:15:11.584Z,1596734111.584 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-08-06T17:15:11.723Z,1596734111.723 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-08-06T17:15:11.724Z,1596734111.724 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-08-06T17:15:12.373Z,1596734112.373 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-08-06T17:15:12.374Z,1596734112.374 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-08-06T17:15:13.291Z,1596734113.291 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-08-06T17:15:13.293Z,1596734113.293 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-08-06T17:15:13.537Z,1596734113.537 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-08-06T17:15:13.539Z,1596734113.539 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2020-08-06T17:15:13.543Z,1596734113.543 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2020-08-06T17:15:13.729Z,1596734113.729 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2020-08-06T17:15:13.813Z,1596734113.813 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2020-08-06T17:15:13.959Z,1596734113.959 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2020-08-06T17:15:14.044Z,1596734114.044 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2020-08-06T17:15:14.271Z,1596734114.271 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-08-06T17:15:14.272Z,1596734114.272 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2020-08-06T17:15:14.376Z,1596734114.376 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2020-08-06T17:15:14.483Z,1596734114.483 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2020-08-06T17:15:14.575Z,1596734114.575 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2020-08-06T17:15:14.723Z,1596734114.723 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2020-08-06T17:15:14.819Z,1596734114.819 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2020-08-06T17:15:14.915Z,1596734114.915 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-08-06T17:15:14.932Z,1596734114.932 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-08-06T17:15:14.958Z,1596734114.958 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-08-06T17:15:14.960Z,1596734114.960 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-08-06T17:15:15.024Z,1596734115.024 [VerticalControl](DEBUG): Construct VerticalControl.
2020-08-06T17:15:15.139Z,1596734115.139 [VerticalControl] Loaded
2020-08-06T17:15:15.139Z,1596734115.139 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-08-06T17:15:15.140Z,1596734115.140 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-08-06T17:15:15.210Z,1596734115.210 [HorizontalControl] Loaded
2020-08-06T17:15:15.210Z,1596734115.210 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-08-06T17:15:15.211Z,1596734115.211 [SpeedControl](DEBUG): Construct SpeedControl.
2020-08-06T17:15:15.216Z,1596734115.216 [SpeedControl] Loaded
2020-08-06T17:15:15.217Z,1596734115.217 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-08-06T17:15:15.217Z,1596734115.217 [LoopControl](DEBUG): Construct LoopControl.
2020-08-06T17:15:15.218Z,1596734115.218 [LoopControl] Loaded
2020-08-06T17:15:15.218Z,1596734115.218 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-08-06T17:15:15.219Z,1596734115.219 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-08-06T17:15:15.219Z,1596734115.219 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-08-06T17:15:15.371Z,1596734115.371 [BuoyancyServo] Loaded
2020-08-06T17:15:15.372Z,1596734115.372 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-08-06T17:15:15.383Z,1596734115.383 [ElevatorServo] Loaded
2020-08-06T17:15:15.384Z,1596734115.384 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-08-06T17:15:15.395Z,1596734115.395 [MassServo] Loaded
2020-08-06T17:15:15.396Z,1596734115.396 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-08-06T17:15:15.407Z,1596734115.407 [RudderServo] Loaded
2020-08-06T17:15:15.407Z,1596734115.407 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-08-06T17:15:15.419Z,1596734115.419 [ThrusterServo] Loaded
2020-08-06T17:15:15.419Z,1596734115.419 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-08-06T17:15:15.419Z,1596734115.419 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-08-06T17:15:15.420Z,1596734115.420 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-08-06T17:15:15.475Z,1596734115.475 [DepthRateCalculator] Loaded
2020-08-06T17:15:15.475Z,1596734115.475 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-08-06T17:15:15.480Z,1596734115.480 [PitchRateCalculator] Loaded
2020-08-06T17:15:15.481Z,1596734115.481 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-08-06T17:15:15.496Z,1596734115.496 [SpeedCalculator] Loaded
2020-08-06T17:15:15.496Z,1596734115.496 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-08-06T17:15:15.518Z,1596734115.518 [TempGradientCalculator] Loaded
2020-08-06T17:15:15.518Z,1596734115.518 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-08-06T17:15:15.524Z,1596734115.524 [YawRateCalculator] Loaded
2020-08-06T17:15:15.524Z,1596734115.524 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-08-06T17:15:15.554Z,1596734115.554 [ElevatorOffsetCalculator] Loaded
2020-08-06T17:15:15.554Z,1596734115.554 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-08-06T17:15:15.555Z,1596734115.555 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-08-06T17:15:15.556Z,1596734115.556 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-08-06T17:15:15.679Z,1596734115.679 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-08-06T17:15:15.679Z,1596734115.679 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-08-06T17:15:15.700Z,1596734115.700 [NavChart] Loaded
2020-08-06T17:15:15.701Z,1596734115.701 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-08-06T17:15:15.705Z,1596734115.705 [UniversalFixResidualReporter] Loaded
2020-08-06T17:15:15.705Z,1596734115.705 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-08-06T17:15:15.705Z,1596734115.705 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-08-06T17:15:15.707Z,1596734115.707 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-08-06T17:15:16.188Z,1596734116.188 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-08-06T17:15:16.193Z,1596734116.193 [AHRS_M2](INFO): created writer for : platform_orientation
2020-08-06T17:15:16.195Z,1596734116.195 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-08-06T17:15:16.200Z,1596734116.200 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-08-06T17:15:16.201Z,1596734116.201 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-08-06T17:15:16.206Z,1596734116.206 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-08-06T17:15:16.207Z,1596734116.207 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-08-06T17:15:16.212Z,1596734116.212 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-08-06T17:15:16.392Z,1596734116.392 [AHRS_M2] Loaded
2020-08-06T17:15:16.393Z,1596734116.393 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-08-06T17:15:16.542Z,1596734116.542 [DataOverHttps] Loaded
2020-08-06T17:15:16.542Z,1596734116.542 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-08-06T17:15:16.543Z,1596734116.543 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407F94E0
2020-08-06T17:15:16.544Z,1596734116.544 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 912
2020-08-06T17:15:16.557Z,1596734116.557 [Depth_Keller] Loaded
2020-08-06T17:15:16.558Z,1596734116.558 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-08-06T17:15:16.657Z,1596734116.657 [NAL9602] Loaded
2020-08-06T17:15:16.657Z,1596734116.657 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-08-06T17:15:16.708Z,1596734116.708 [Onboard] Loaded
2020-08-06T17:15:16.708Z,1596734116.708 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2020-08-06T17:15:16.709Z,1596734116.709 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408294E0
2020-08-06T17:15:16.709Z,1596734116.709 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 913
2020-08-06T17:15:16.713Z,1596734116.713 [Radio_Surface] Loaded
2020-08-06T17:15:16.713Z,1596734116.713 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-08-06T17:15:16.714Z,1596734116.714 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408594E0
2020-08-06T17:15:16.715Z,1596734116.715 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 914
2020-08-06T17:15:16.844Z,1596734116.844 [DAT] Loaded
2020-08-06T17:15:16.844Z,1596734116.844 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2020-08-06T17:15:18.834Z,1596734118.834 [BPC1] Loaded
2020-08-06T17:15:18.834Z,1596734118.834 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-08-06T17:15:18.841Z,1596734118.841 [AMEcho] Loaded
2020-08-06T17:15:18.842Z,1596734118.842 [ComponentRegistry](DEBUG): SyncComponent "AMEcho" handled in the control thread.
2020-08-06T17:15:18.842Z,1596734118.842 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-08-06T17:15:18.844Z,1596734118.844 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-08-06T17:15:18.958Z,1596734118.958 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-08-06T17:15:18.959Z,1596734118.959 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-08-06T17:15:19.085Z,1596734119.085 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-08-06T17:15:19.086Z,1596734119.086 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-08-06T17:15:19.135Z,1596734119.135 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-08-06T17:15:19.136Z,1596734119.136 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-08-06T17:15:19.398Z,1596734119.398 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-08-06T17:15:19.404Z,1596734119.404 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-08-06T17:15:19.404Z,1596734119.404 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-08-06T17:15:19.409Z,1596734119.409 [CTD_Seabird](INFO): created writer for : depth
2020-08-06T17:15:19.410Z,1596734119.410 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-08-06T17:15:19.415Z,1596734119.415 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-08-06T17:15:19.415Z,1596734119.415 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-08-06T17:15:19.421Z,1596734119.421 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-08-06T17:15:19.421Z,1596734119.421 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-08-06T17:15:19.426Z,1596734119.426 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-08-06T17:15:19.427Z,1596734119.427 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-08-06T17:15:19.432Z,1596734119.432 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-08-06T17:15:19.433Z,1596734119.433 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-08-06T17:15:19.438Z,1596734119.438 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-08-06T17:15:19.466Z,1596734119.466 [CTD_Seabird] Loaded
2020-08-06T17:15:19.466Z,1596734119.466 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-08-06T17:15:19.467Z,1596734119.467 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 409EF4E0
2020-08-06T17:15:19.468Z,1596734119.468 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 915
2020-08-06T17:15:19.500Z,1596734119.500 [ESPComponent] Loaded
2020-08-06T17:15:19.500Z,1596734119.500 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2020-08-06T17:15:19.515Z,1596734119.515 [PAR_Licor] Loaded
2020-08-06T17:15:19.515Z,1596734119.515 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-08-06T17:15:19.521Z,1596734119.521 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-08-06T17:15:19.522Z,1596734119.522 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-08-06T17:15:19.526Z,1596734119.526 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-08-06T17:15:19.526Z,1596734119.526 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-08-06T17:15:19.530Z,1596734119.530 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-08-06T17:15:19.531Z,1596734119.531 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-08-06T17:15:19.535Z,1596734119.535 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-08-06T17:15:19.535Z,1596734119.535 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-08-06T17:15:19.539Z,1596734119.539 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-08-06T17:15:19.540Z,1596734119.540 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-08-06T17:15:19.544Z,1596734119.544 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-08-06T17:15:19.544Z,1596734119.544 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-08-06T17:15:19.548Z,1596734119.548 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-08-06T17:15:19.549Z,1596734119.549 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-08-06T17:15:19.553Z,1596734119.553 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-08-06T17:15:19.558Z,1596734119.558 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-08-06T17:15:19.558Z,1596734119.558 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-08-06T17:15:19.558Z,1596734119.558 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-08-06T17:15:19.563Z,1596734119.563 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-08-06T17:15:19.563Z,1596734119.563 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-08-06T17:15:19.568Z,1596734119.568 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-08-06T17:15:19.568Z,1596734119.568 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-08-06T17:15:19.572Z,1596734119.572 [WetLabsBB2FL] Loaded
2020-08-06T17:15:19.573Z,1596734119.573 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-08-06T17:15:19.574Z,1596734119.574 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A1F4E0
2020-08-06T17:15:19.574Z,1596734119.574 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 916
2020-08-06T17:15:19.575Z,1596734119.575 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-08-06T17:15:19.577Z,1596734119.577 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-08-06T17:15:19.917Z,1596734119.917 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-08-06T17:15:19.919Z,1596734119.919 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-08-06T17:15:20.082Z,1596734120.082 [SBIT](DEBUG): Construct Startup Built In Test.
2020-08-06T17:15:20.093Z,1596734120.093 [SBIT] Loaded
2020-08-06T17:15:20.093Z,1596734120.093 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-08-06T17:15:20.094Z,1596734120.094 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-08-06T17:15:20.107Z,1596734120.107 [IBIT] Loaded
2020-08-06T17:15:20.107Z,1596734120.107 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-08-06T17:15:20.111Z,1596734120.111 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-08-06T17:15:20.253Z,1596734120.253 [CBIT] Loaded
2020-08-06T17:15:20.253Z,1596734120.253 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-08-06T17:15:20.253Z,1596734120.253 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-08-06T17:15:20.257Z,1596734120.257 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-08-06T17:15:20.258Z,1596734120.258 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-08-06T17:15:20.265Z,1596734120.265 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-08-06T17:15:20.266Z,1596734120.266 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AFF4E0
2020-08-06T17:15:20.266Z,1596734120.266 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 917
2020-08-06T17:15:20.271Z,1596734120.271 [Supervisor](INFO): Main Thread ID is 823
2020-08-06T17:15:20.271Z,1596734120.271 [Supervisor](DEBUG): Running supervisor.
2020-08-06T17:15:20.272Z,1596734120.272 [CommandLine ThreadHandler](INFO): Handler Thread ID is 918
2020-08-06T17:15:20.274Z,1596734120.274 [controlThread ThreadHandler](INFO): Handler Thread ID is 919
2020-08-06T17:15:20.274Z,1596734120.274 [controlThread](DEBUG): Initializing ControlThread
2020-08-06T17:15:20.275Z,1596734120.275 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-08-06T17:15:20.277Z,1596734120.277 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-08-06T17:15:20.277Z,1596734120.277 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-08-06T17:15:20.278Z,1596734120.278 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-08-06T17:15:20.279Z,1596734120.279 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-08-06T17:15:20.280Z,1596734120.280 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-08-06T17:15:20.280Z,1596734120.280 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-08-06T17:15:20.280Z,1596734120.280 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-08-06T17:15:20.281Z,1596734120.281 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-08-06T17:15:20.281Z,1596734120.281 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-08-06T17:15:20.282Z,1596734120.282 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-08-06T17:15:20.283Z,1596734120.283 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-08-06T17:15:20.289Z,1596734120.289 [SBIT](INFO): Initialize SBIT Component.
2020-08-06T17:15:20.290Z,1596734120.290 [SBIT](IMPORTANT): git: 2020-01-30-43-g2288ba088
2020-08-06T17:15:20.290Z,1596734120.290 [SBIT](INFO): git hash: 2288ba0885cb5e0ead12bdc4915239f4cb3c2f06
2020-08-06T17:15:20.290Z,1596734120.290 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-08-06T17:15:20.291Z,1596734120.291 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Tue May 26 08:17:09 PDT 2020
2020-08-06T17:15:20.292Z,1596734120.292 [SBIT](INFO): Beginning SBIT in 34.000000 seconds.
2020-08-06T17:15:20.293Z,1596734120.293 [IBIT](INFO): Initialize IBIT Component.
2020-08-06T17:15:20.294Z,1596734120.294 [CBIT](DEBUG): Initialize CBIT Component.
2020-08-06T17:15:20.295Z,1596734120.295 [logger ThreadHandler](INFO): Handler Thread ID is 920
2020-08-06T17:15:20.305Z,1596734120.305 [CBIT](DEBUG): Initialized mux pins.
2020-08-06T17:15:20.305Z,1596734120.305 [CBIT](DEBUG): Initializing the watchdog timer.
2020-08-06T17:15:20.313Z,1596734120.313 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 921
2020-08-06T17:15:20.315Z,1596734120.315 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-08-06T17:15:20.325Z,1596734120.325 [Onboard ThreadHandler](INFO): Handler Thread ID is 922
2020-08-06T17:15:20.330Z,1596734120.330 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-08-06T17:15:20.330Z,1596734120.330 [CBIT](DEBUG): Initializing heartbeat.
2020-08-06T17:15:20.355Z,1596734120.355 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 923
2020-08-06T17:15:20.357Z,1596734120.357 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 924
2020-08-06T17:15:20.358Z,1596734120.358 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-08-06T17:15:20.362Z,1596734120.362 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2020-08-06T17:15:20.363Z,1596734120.363 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 926
2020-08-06T17:15:20.364Z,1596734120.364 [WetLabsBB2FL](INFO): Powering down
2020-08-06T17:15:20.401Z,1596734120.401 [CBIT](DEBUG): Deactivating GF circuits.
2020-08-06T17:15:20.401Z,1596734120.401 [CBIT](DEBUG): Deactivating emergency mode.
2020-08-06T17:15:20.413Z,1596734120.413 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 927
2020-08-06T17:15:20.437Z,1596734120.437 [CBIT](DEBUG): Backplane powered.
2020-08-06T17:15:20.516Z,1596734120.516 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-08-06T17:15:20.602Z,1596734120.602 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-08-06T17:15:20.603Z,1596734120.603 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-08-06T17:15:20.603Z,1596734120.603 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-08-06T17:15:20.603Z,1596734120.603 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-08-06T17:15:20.603Z,1596734120.603 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-08-06T17:15:20.603Z,1596734120.603 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-08-06T17:15:20.604Z,1596734120.604 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-08-06T17:15:20.604Z,1596734120.604 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-08-06T17:15:20.604Z,1596734120.604 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-08-06T17:15:20.604Z,1596734120.604 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-08-06T17:15:20.604Z,1596734120.604 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-08-06T17:15:20.604Z,1596734120.604 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-08-06T17:15:20.613Z,1596734120.613 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-08-06T17:15:20.613Z,1596734120.613 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-08-06T17:15:20.614Z,1596734120.614 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-08-06T17:15:20.614Z,1596734120.614 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-08-06T17:15:20.636Z,1596734120.636 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-08-06T17:15:20.724Z,1596734120.724 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2020-08-06T17:15:20.725Z,1596734120.725 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-08-06T17:15:20.757Z,1596734120.757 [Radio_Surface](INFO): Powering up
2020-08-06T17:15:20.823Z,1596734120.823 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-08-06T17:15:20.824Z,1596734120.824 [Default:A.Wait](DEBUG): Construct Wait.
2020-08-06T17:15:20.858Z,1596734120.858 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-08-06T17:15:20.897Z,1596734120.897 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-08-06T17:15:20.899Z,1596734120.899 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-08-06T17:15:20.921Z,1596734120.921 [Default:E.Execute](DEBUG): Construct Execute.
2020-08-06T17:15:20.924Z,1596734120.924 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2020-08-06T17:15:20.941Z,1596734120.941 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,NAL9602,DAT,BPC1,AMEcho,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,
2020-08-06T17:15:20.963Z,1596734120.963 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-08-06T17:15:21.110Z,1596734121.110 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-08-06T17:15:21.110Z,1596734121.110 [DAT](INFO): Powering up
2020-08-06T17:15:21.110Z,1596734121.110 [DAT](DEBUG): Initializing DAT.
2020-08-06T17:15:21.177Z,1596734121.177 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-08-06T17:15:21.267Z,1596734121.267 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-08-06T17:15:21.281Z,1596734121.281 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-08-06T17:15:21.283Z,1596734121.283 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-08-06T17:15:21.293Z,1596734121.293 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-08-06T17:15:21.294Z,1596734121.294 [MassServo](DEBUG): Initializing EZServoServo.
2020-08-06T17:15:21.305Z,1596734121.305 [MassServo](DEBUG): Initializing MassServo.
2020-08-06T17:15:21.306Z,1596734121.306 [RudderServo](DEBUG): Initializing EZServoServo.
2020-08-06T17:15:21.318Z,1596734121.318 [RudderServo](DEBUG): Initializing RudderServo.
2020-08-06T17:15:21.319Z,1596734121.319 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-08-06T17:15:21.329Z,1596734121.329 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-08-06T17:15:24.501Z,1596734124.501 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-06T17:15:24.925Z,1596734124.925 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-06T17:15:25.469Z,1596734125.469 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-06T17:15:26.089Z,1596734126.089 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-06T17:15:26.657Z,1596734126.657 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-06T17:15:26.946Z,1596734126.946 [CommandLine](IMPORTANT): got command quit
2020-08-06T17:15:27.133Z,1596734127.133 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-06T17:15:27.537Z,1596734127.537 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-06T17:15:27.949Z,1596734127.949 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2020-08-06T17:15:27.949Z,1596734127.949 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-08-06T17:15:27.950Z,1596734127.950 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-08-06T17:15:27.957Z,1596734127.957 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-06T17:15:28.065Z,1596734128.065 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-08-06T17:15:28.066Z,1596734128.066 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-08-06T17:15:28.069Z,1596734128.069 [CommandLine](INFO): Join timeout helper Thread ID is 939
2020-08-06T17:15:28.085Z,1596734128.085 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-08-06T17:15:28.085Z,1596734128.085 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-08-06T17:15:28.086Z,1596734128.086 [NavChartDb](INFO): Join timeout helper Thread ID is 940
2020-08-06T17:15:28.361Z,1596734128.361 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-06T17:15:28.793Z,1596734128.793 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-06T17:15:29.381Z,1596734129.381 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-06T17:15:29.785Z,1596734129.785 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-06T17:15:30.197Z,1596734130.197 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-06T17:15:30.613Z,1596734130.613 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-06T17:15:30.709Z,1596734130.709 [CBIT](CRITICAL): Environmental Failure. Press:14.911994 PSI. Humidity:54%. Temp:24 C. ABORTING MISSION
2020-08-06T17:15:31.041Z,1596734131.041 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-06T17:15:31.131Z,1596734131.131 [CommandLine](FAULT): Scheduling is paused
2020-08-06T17:15:31.131Z,1596734131.131 [CBIT](INFO): Critical error at 20200806T171530
2020-08-06T17:15:31.465Z,1596734131.465 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-06T17:15:31.901Z,1596734131.901 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-06T17:15:32.309Z,1596734132.309 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-06T17:15:32.741Z,1596734132.741 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-06T17:15:33.165Z,1596734133.165 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-06T17:15:33.409Z,1596734133.409 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2020-08-06T17:15:33.409Z,1596734133.409 [CTD_Seabird] Communications Fault, FailCount= 1
2020-08-06T17:15:33.409Z,1596734133.409 [CTD_Seabird](ERROR): Communications Fault
2020-08-06T17:15:33.593Z,1596734133.593 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-06T17:15:33.669Z,1596734133.669 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2020-08-06T17:15:33.813Z,1596734133.813 [CTD_Seabird](INFO): Powering down
2020-08-06T17:15:34.001Z,1596734134.001 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-06T17:15:34.001Z,1596734134.001 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-08-06T17:15:34.001Z,1596734134.001 [AHRS_M2] Communications Fault, FailCount= 1
2020-08-06T17:15:34.001Z,1596734134.001 [AHRS_M2](ERROR): Communications Fault
2020-08-06T17:15:34.074Z,1596734134.074 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-08-06T17:15:34.079Z,1596734134.079 [AHRS_M2](INFO): Powering down
2020-08-06T17:15:34.219Z,1596734134.219 [DAT](INFO): setting local address to 5
2020-08-06T17:15:34.487Z,1596734134.487 [DAT](INFO): set local address to 5
2020-08-06T17:15:34.909Z,1596734134.909 [CBIT](INFO): Clearing failed state for component AHRS_M2
2020-08-06T17:15:34.909Z,1596734134.909 [AHRS_M2] No Fault, FailCount= 1
2020-08-06T17:15:34.909Z,1596734134.909 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2020-08-06T17:15:34.909Z,1596734134.909 [CTD_Seabird] No Fault, FailCount= 1
2020-08-06T17:15:35.177Z,1596734135.177 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-08-06T17:15:35.179Z,1596734135.179 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2020-08-06T17:15:35.289Z,1596734135.289 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-08-06T17:15:36.049Z,1596734136.049 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-08-06T17:15:36.049Z,1596734136.049 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-08-06T17:15:36.069Z,1596734136.069 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-08-06T17:15:36.069Z,1596734136.069 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-08-06T17:15:36.069Z,1596734136.069 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 943
2020-08-06T17:15:36.489Z,1596734136.489 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-08-06T17:15:36.490Z,1596734136.490 [WetLabsBB2FL](INFO): Powering down
2020-08-06T17:15:36.490Z,1596734136.490 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-08-06T17:15:36.501Z,1596734136.501 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-08-06T17:15:36.501Z,1596734136.501 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-08-06T17:15:36.502Z,1596734136.502 [CTD_Seabird](INFO): Join timeout helper Thread ID is 944
2020-08-06T17:15:37.185Z,1596734137.185 [CTD_Seabird](INFO): Powering down
2020-08-06T17:15:37.317Z,1596734137.317 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-08-06T17:15:37.317Z,1596734137.317 [CTD_Seabird](INFO): Powering down
2020-08-06T17:15:37.329Z,1596734137.329 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-08-06T17:15:37.337Z,1596734137.337 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-08-06T17:15:37.337Z,1596734137.337 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-08-06T17:15:37.338Z,1596734137.338 [Radio_Surface](INFO): Join timeout helper Thread ID is 945
2020-08-06T17:15:37.725Z,1596734137.725 [Radio_Surface](INFO): Powering down
2020-08-06T17:15:37.726Z,1596734137.726 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-08-06T17:15:37.726Z,1596734137.726 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-08-06T17:15:37.738Z,1596734137.738 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2020-08-06T17:15:37.738Z,1596734137.738 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-08-06T17:15:37.738Z,1596734137.738 [Onboard](INFO): Join timeout helper Thread ID is 946
2020-08-06T17:15:38.845Z,1596734138.845 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-06T17:15:39.249Z,1596734139.249 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-06T17:15:39.645Z,1596734139.645 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-06T17:15:40.057Z,1596734140.057 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-06T17:15:40.341Z,1596734140.341 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2020-08-06T17:15:40.342Z,1596734140.342 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-08-06T17:15:40.358Z,1596734140.358 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-08-06T17:15:40.358Z,1596734140.358 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-08-06T17:15:40.358Z,1596734140.358 [DataOverHttps](INFO): Join timeout helper Thread ID is 947
2020-08-06T17:15:40.400Z,1596734140.400 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-08-06T17:15:40.400Z,1596734140.400 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-08-06T17:15:40.418Z,1596734140.418 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-08-06T17:15:40.418Z,1596734140.418 [logger ThreadHandler](INFO): Thread cancelled.
2020-08-06T17:15:40.418Z,1596734140.418 [logger](INFO): Join timeout helper Thread ID is 948
2020-08-06T17:15:40.445Z,1596734140.445 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-08-06T17:15:40.446Z,1596734140.446 [logger ThreadHandler](INFO): Thread cancelled.
2020-08-06T17:15:40.458Z,1596734140.458 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-08-06T17:15:40.458Z,1596734140.458 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-08-06T17:15:40.458Z,1596734140.458 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-08-06T17:15:40.458Z,1596734140.458 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-08-06T17:15:40.458Z,1596734140.458 [controlThread](INFO): Join timeout helper Thread ID is 949
2020-08-06T17:15:40.461Z,1596734140.461 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-08-06T17:15:40.479Z,1596734140.479 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-08-06T17:15:40.479Z,1596734140.479 [controlThread](DEBUG): Uninitializing ControlThread
2020-08-06T17:15:40.479Z,1596734140.479 [AHRS_M2](INFO): Powering down
2020-08-06T17:15:40.550Z,1596734140.550 [NAL9602](INFO): Powering down
2020-08-06T17:15:40.569Z,1596734140.569 [DAT](INFO): Powering down
2020-08-06T17:15:40.857Z,1596734140.857 [AMEcho](INFO): Powering down
2020-08-06T17:15:40.878Z,1596734140.878 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-08-06T17:15:40.879Z,1596734140.879 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-08-06T17:15:40.880Z,1596734140.880 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-08-06T17:15:40.880Z,1596734140.880 [MissionManager](INFO): Uninitializing Mission Default
2020-08-06T17:15:40.883Z,1596734140.883 [MissionManager](INFO): Uninitializing Mission Startup
2020-08-06T17:15:40.884Z,1596734140.884 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-08-06T17:15:40.884Z,1596734140.884 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-08-06T17:15:40.885Z,1596734140.885 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-08-06T17:15:40.885Z,1596734140.885 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-08-06T17:15:40.885Z,1596734140.885 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-08-06T17:15:40.886Z,1596734140.886 [BuoyancyServo](INFO): Powering down
2020-08-06T17:15:40.897Z,1596734140.897 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-08-06T17:15:40.897Z,1596734140.897 [ElevatorServo](INFO): Powering down
2020-08-06T17:15:40.917Z,1596734140.917 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-08-06T17:15:40.918Z,1596734140.918 [MassServo](INFO): Powering down
2020-08-06T17:15:40.918Z,1596734140.918 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-08-06T17:15:40.918Z,1596734140.918 [RudderServo](INFO): Powering down
2020-08-06T17:15:40.919Z,1596734140.919 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-08-06T17:15:40.919Z,1596734140.919 [ThrusterServo](INFO): Powering down
2020-08-06T17:15:40.920Z,1596734140.920 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-08-06T17:15:40.920Z,1596734140.920 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-08-06T17:15:40.921Z,1596734140.921 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-08-06T17:15:40.921Z,1596734140.921 [CBIT](DEBUG): Powering off loads.
2020-08-06T17:15:40.933Z,1596734140.933 [CBIT](DEBUG): Disabling WDT.
2020-08-06T17:15:40.945Z,1596734140.945 [CBIT](DEBUG): Opening all GF detection circuits.
2020-08-06T17:15:40.946Z,1596734140.946 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-08-06T17:15:41.144Z,1596734141.144 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-08-06T17:15:41.147Z,1596734141.147 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-08-06T17:15:41.151Z,1596734141.151 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-08-06T17:15:41.187Z,1596734141.187 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-08-06T17:15:41.192Z,1596734141.192 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-08-06T17:15:41.226Z,1596734141.226 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-08-06T17:15:41.283Z,1596734141.283 [logger ThreadHandler](INFO): Thread cancelled.