2021-02-02T20:08:28.926Z,1612296508.926 [Supervisor](DEBUG): Initializing supervisor.
2021-02-02T20:08:28.929Z,1612296508.929 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2021-02-02T20:08:28.929Z,1612296508.929 [SyncHandler](INFO): Protected caller Thread ID is 7691
2021-02-02T20:08:28.929Z,1612296508.929 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2021-02-02T20:08:28.930Z,1612296508.930 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2021-02-02T20:08:28.931Z,1612296508.931 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7692
2021-02-02T20:08:28.934Z,1612296508.934 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2021-02-02T20:08:28.951Z,1612296508.951 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2021-02-02T20:08:28.952Z,1612296508.952 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2021-02-02T20:08:28.953Z,1612296508.953 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7693
2021-02-02T20:08:28.953Z,1612296508.953 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2021-02-02T20:08:28.954Z,1612296508.954 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2021-02-02T20:08:28.955Z,1612296508.955 [logger ThreadHandler](INFO): Protected caller Thread ID is 7694
2021-02-02T20:08:28.957Z,1612296508.957 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2021-02-02T20:08:28.957Z,1612296508.957 [Supervisor](INFO): Looking for Config files in directory: Config/
2021-02-02T20:08:28.959Z,1612296508.959 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2021-02-02T20:08:29.157Z,1612296509.157 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2021-02-02T20:08:29.158Z,1612296509.158 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2021-02-02T20:08:29.235Z,1612296509.235 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2021-02-02T20:08:29.701Z,1612296509.701 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2021-02-02T20:08:29.701Z,1612296509.701 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2021-02-02T20:08:30.012Z,1612296510.012 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2021-02-02T20:08:30.012Z,1612296510.012 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2021-02-02T20:08:30.107Z,1612296510.107 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2021-02-02T20:08:30.107Z,1612296510.107 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2021-02-02T20:08:30.396Z,1612296510.396 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2021-02-02T20:08:30.396Z,1612296510.396 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2021-02-02T20:08:30.584Z,1612296510.584 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2021-02-02T20:08:30.585Z,1612296510.585 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2021-02-02T20:08:31.060Z,1612296511.060 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2021-02-02T20:08:31.061Z,1612296511.061 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2021-02-02T20:08:31.164Z,1612296511.164 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2021-02-02T20:08:31.164Z,1612296511.164 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2021-02-02T20:08:31.262Z,1612296511.262 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2021-02-02T20:08:31.263Z,1612296511.263 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2021-02-02T20:08:31.982Z,1612296511.982 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2021-02-02T20:08:31.982Z,1612296511.982 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2021-02-02T20:08:32.402Z,1612296512.402 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2021-02-02T20:08:32.403Z,1612296512.403 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2021-02-02T20:08:32.589Z,1612296512.589 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2021-02-02T20:08:32.589Z,1612296512.589 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2021-02-02T20:08:32.730Z,1612296512.730 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2021-02-02T20:08:32.731Z,1612296512.731 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2021-02-02T20:08:32.876Z,1612296512.876 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2021-02-02T20:08:32.876Z,1612296512.876 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2021-02-02T20:08:33.083Z,1612296513.083 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2021-02-02T20:08:33.085Z,1612296513.085 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2021-02-02T20:08:33.086Z,1612296513.086 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2021-02-02T20:08:33.174Z,1612296513.174 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2021-02-02T20:08:33.257Z,1612296513.257 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2021-02-02T20:08:33.354Z,1612296513.354 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2021-02-02T20:08:33.435Z,1612296513.435 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2021-02-02T20:08:33.527Z,1612296513.527 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2021-02-02T20:08:33.625Z,1612296513.625 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2021-02-02T20:08:33.837Z,1612296513.837 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2021-02-02T20:08:34.006Z,1612296514.006 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2021-02-02T20:08:34.463Z,1612296514.463 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2021-02-02T20:08:34.879Z,1612296514.879 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2021-02-02T20:08:34.999Z,1612296514.999 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2021-02-02T20:08:35.247Z,1612296515.247 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2021-02-02T20:08:35.248Z,1612296515.248 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2021-02-02T20:08:35.249Z,1612296515.249 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2021-02-02T20:08:35.257Z,1612296515.257 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2021-02-02T20:08:35.306Z,1612296515.306 [VerticalControl](DEBUG): Construct VerticalControl.
2021-02-02T20:08:35.365Z,1612296515.365 [VerticalControl] Loaded
2021-02-02T20:08:35.365Z,1612296515.365 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2021-02-02T20:08:35.366Z,1612296515.366 [HorizontalControl](DEBUG): Construct HorizontalControl.
2021-02-02T20:08:35.403Z,1612296515.403 [HorizontalControl] Loaded
2021-02-02T20:08:35.403Z,1612296515.403 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2021-02-02T20:08:35.404Z,1612296515.404 [SpeedControl](DEBUG): Construct SpeedControl.
2021-02-02T20:08:35.407Z,1612296515.407 [SpeedControl] Loaded
2021-02-02T20:08:35.407Z,1612296515.407 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2021-02-02T20:08:35.408Z,1612296515.408 [LoopControl](DEBUG): Construct LoopControl.
2021-02-02T20:08:35.408Z,1612296515.408 [LoopControl] Loaded
2021-02-02T20:08:35.408Z,1612296515.408 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2021-02-02T20:08:35.409Z,1612296515.409 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2021-02-02T20:08:35.409Z,1612296515.409 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2021-02-02T20:08:35.427Z,1612296515.427 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2021-02-02T20:08:35.428Z,1612296515.428 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2021-02-02T20:08:35.523Z,1612296515.523 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2021-02-02T20:08:35.524Z,1612296515.524 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2021-02-02T20:08:35.629Z,1612296515.629 [BuoyancyServo] Loaded
2021-02-02T20:08:35.630Z,1612296515.630 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2021-02-02T20:08:35.646Z,1612296515.646 [ElevatorServo] Loaded
2021-02-02T20:08:35.646Z,1612296515.646 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2021-02-02T20:08:35.661Z,1612296515.661 [MassServo] Loaded
2021-02-02T20:08:35.662Z,1612296515.662 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2021-02-02T20:08:35.676Z,1612296515.676 [RudderServo] Loaded
2021-02-02T20:08:35.677Z,1612296515.677 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2021-02-02T20:08:35.692Z,1612296515.692 [ThrusterServo] Loaded
2021-02-02T20:08:35.692Z,1612296515.692 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2021-02-02T20:08:35.692Z,1612296515.692 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2021-02-02T20:08:35.693Z,1612296515.693 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2021-02-02T20:08:35.771Z,1612296515.771 [DeadReckonUsingMultipleVelocitySources] Loaded
2021-02-02T20:08:35.771Z,1612296515.771 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2021-02-02T20:08:35.782Z,1612296515.782 [NavChart] Loaded
2021-02-02T20:08:35.782Z,1612296515.782 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2021-02-02T20:08:35.786Z,1612296515.786 [UniversalFixResidualReporter] Loaded
2021-02-02T20:08:35.786Z,1612296515.786 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2021-02-02T20:08:35.787Z,1612296515.787 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2021-02-02T20:08:35.787Z,1612296515.787 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2021-02-02T20:08:35.870Z,1612296515.870 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2021-02-02T20:08:35.870Z,1612296515.870 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2021-02-02T20:08:36.165Z,1612296516.165 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2021-02-02T20:08:36.168Z,1612296516.168 [AHRS_M2](INFO): created writer for : platform_orientation
2021-02-02T20:08:36.170Z,1612296516.170 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2021-02-02T20:08:36.173Z,1612296516.173 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2021-02-02T20:08:36.173Z,1612296516.173 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2021-02-02T20:08:36.176Z,1612296516.176 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2021-02-02T20:08:36.177Z,1612296516.177 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2021-02-02T20:08:36.180Z,1612296516.180 [AHRS_M2](INFO): created writer for : platform_roll_angle
2021-02-02T20:08:36.216Z,1612296516.216 [AHRS_M2] Loaded
2021-02-02T20:08:36.216Z,1612296516.216 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2021-02-02T20:08:36.297Z,1612296516.297 [DataOverHttps] Loaded
2021-02-02T20:08:36.298Z,1612296516.298 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2021-02-02T20:08:36.299Z,1612296516.299 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408A34E0
2021-02-02T20:08:36.299Z,1612296516.299 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7780
2021-02-02T20:08:36.316Z,1612296516.316 [Depth_Keller] Loaded
2021-02-02T20:08:36.316Z,1612296516.316 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2021-02-02T20:08:36.318Z,1612296516.318 [DropWeight] Loaded
2021-02-02T20:08:36.319Z,1612296516.319 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2021-02-02T20:08:36.373Z,1612296516.373 [NAL9602] Loaded
2021-02-02T20:08:36.373Z,1612296516.373 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2021-02-02T20:08:36.399Z,1612296516.399 [Onboard] Loaded
2021-02-02T20:08:36.399Z,1612296516.399 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2021-02-02T20:08:36.400Z,1612296516.400 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408D34E0
2021-02-02T20:08:36.401Z,1612296516.401 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 7781
2021-02-02T20:08:36.411Z,1612296516.411 [Radio_Surface] Loaded
2021-02-02T20:08:36.411Z,1612296516.411 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2021-02-02T20:08:36.412Z,1612296516.412 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409034E0
2021-02-02T20:08:36.413Z,1612296516.413 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7782
2021-02-02T20:08:37.188Z,1612296517.188 [BPC1] Loaded
2021-02-02T20:08:37.188Z,1612296517.188 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2021-02-02T20:08:37.207Z,1612296517.207 [AMEcho] Loaded
2021-02-02T20:08:37.207Z,1612296517.207 [ComponentRegistry](DEBUG): SyncComponent "AMEcho" handled in the control thread.
2021-02-02T20:08:37.208Z,1612296517.208 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2021-02-02T20:08:37.208Z,1612296517.208 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2021-02-02T20:08:37.247Z,1612296517.247 [DepthRateCalculator] Loaded
2021-02-02T20:08:37.247Z,1612296517.247 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2021-02-02T20:08:37.250Z,1612296517.250 [PitchRateCalculator] Loaded
2021-02-02T20:08:37.250Z,1612296517.250 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2021-02-02T20:08:37.257Z,1612296517.257 [SpeedCalculator] Loaded
2021-02-02T20:08:37.257Z,1612296517.257 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2021-02-02T20:08:37.268Z,1612296517.268 [TempGradientCalculator] Loaded
2021-02-02T20:08:37.269Z,1612296517.269 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2021-02-02T20:08:37.271Z,1612296517.271 [YawRateCalculator] Loaded
2021-02-02T20:08:37.272Z,1612296517.272 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2021-02-02T20:08:37.286Z,1612296517.286 [ElevatorOffsetCalculator] Loaded
2021-02-02T20:08:37.287Z,1612296517.287 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2021-02-02T20:08:37.287Z,1612296517.287 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2021-02-02T20:08:37.288Z,1612296517.288 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2021-02-02T20:08:37.478Z,1612296517.478 [CANONSampler] Loaded
2021-02-02T20:08:37.479Z,1612296517.479 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2021-02-02T20:08:37.497Z,1612296517.497 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2021-02-02T20:08:37.500Z,1612296517.500 [CTD_Seabird](INFO): created writer for : sea_water_density
2021-02-02T20:08:37.500Z,1612296517.500 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2021-02-02T20:08:37.503Z,1612296517.503 [CTD_Seabird](INFO): created writer for : depth
2021-02-02T20:08:37.503Z,1612296517.503 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2021-02-02T20:08:37.506Z,1612296517.506 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2021-02-02T20:08:37.507Z,1612296517.507 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2021-02-02T20:08:37.510Z,1612296517.510 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2021-02-02T20:08:37.510Z,1612296517.510 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2021-02-02T20:08:37.513Z,1612296517.513 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2021-02-02T20:08:37.514Z,1612296517.514 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2021-02-02T20:08:37.517Z,1612296517.517 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2021-02-02T20:08:37.517Z,1612296517.517 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2021-02-02T20:08:37.520Z,1612296517.520 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2021-02-02T20:08:37.521Z,1612296517.521 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): mass_concentration_of_oxygen_in_sea_water
2021-02-02T20:08:37.524Z,1612296517.524 [CTD_Seabird](INFO): created writer for : mass_concentration_of_oxygen_in_sea_water
2021-02-02T20:08:37.524Z,1612296517.524 [CTD_Seabird](DEBUG): LcmSlateWriter::add(DAtaURI): CTD_Seabird.sea_water_dissolved_oxygen_freq
2021-02-02T20:08:37.525Z,1612296517.525 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): dataName: sea_water_dissolved_oxygen_freq
2021-02-02T20:08:37.541Z,1612296517.541 [CTD_Seabird] Loaded
2021-02-02T20:08:37.541Z,1612296517.541 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2021-02-02T20:08:37.542Z,1612296517.542 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A114E0
2021-02-02T20:08:37.543Z,1612296517.543 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7783
2021-02-02T20:08:37.560Z,1612296517.560 [PAR_Licor] Loaded
2021-02-02T20:08:37.560Z,1612296517.560 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2021-02-02T20:08:37.572Z,1612296517.572 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2021-02-02T20:08:37.573Z,1612296517.573 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2021-02-02T20:08:37.574Z,1612296517.574 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2021-02-02T20:08:37.575Z,1612296517.575 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2021-02-02T20:08:37.577Z,1612296517.577 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2021-02-02T20:08:37.577Z,1612296517.577 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2021-02-02T20:08:37.579Z,1612296517.579 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2021-02-02T20:08:37.579Z,1612296517.579 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2021-02-02T20:08:37.581Z,1612296517.581 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2021-02-02T20:08:37.581Z,1612296517.581 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2021-02-02T20:08:37.583Z,1612296517.583 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2021-02-02T20:08:37.583Z,1612296517.583 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2021-02-02T20:08:37.585Z,1612296517.585 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2021-02-02T20:08:37.585Z,1612296517.585 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2021-02-02T20:08:37.587Z,1612296517.587 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2021-02-02T20:08:37.590Z,1612296517.590 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2021-02-02T20:08:37.590Z,1612296517.590 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2021-02-02T20:08:37.590Z,1612296517.590 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2021-02-02T20:08:37.592Z,1612296517.592 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2021-02-02T20:08:37.592Z,1612296517.592 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2021-02-02T20:08:37.594Z,1612296517.594 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2021-02-02T20:08:37.594Z,1612296517.594 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2021-02-02T20:08:37.596Z,1612296517.596 [WetLabsBB2FL] Loaded
2021-02-02T20:08:37.597Z,1612296517.597 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2021-02-02T20:08:37.598Z,1612296517.598 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A414E0
2021-02-02T20:08:37.598Z,1612296517.598 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 7784
2021-02-02T20:08:37.599Z,1612296517.599 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2021-02-02T20:08:37.599Z,1612296517.599 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2021-02-02T20:08:37.626Z,1612296517.626 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2021-02-02T20:08:37.626Z,1612296517.626 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2021-02-02T20:08:37.907Z,1612296517.907 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2021-02-02T20:08:37.908Z,1612296517.908 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2021-02-02T20:08:38.036Z,1612296518.036 [SBIT](DEBUG): Construct Startup Built In Test.
2021-02-02T20:08:38.045Z,1612296518.045 [SBIT] Loaded
2021-02-02T20:08:38.045Z,1612296518.045 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2021-02-02T20:08:38.046Z,1612296518.046 [IBIT](DEBUG): Construct Initiated Built In Test.
2021-02-02T20:08:38.058Z,1612296518.058 [IBIT] Loaded
2021-02-02T20:08:38.059Z,1612296518.059 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2021-02-02T20:08:38.062Z,1612296518.062 [CBIT](DEBUG): Construct Continuous Built In Test.
2021-02-02T20:08:38.158Z,1612296518.158 [CBIT] Loaded
2021-02-02T20:08:38.159Z,1612296518.159 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2021-02-02T20:08:38.159Z,1612296518.159 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2021-02-02T20:08:38.160Z,1612296518.160 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2021-02-02T20:08:38.236Z,1612296518.236 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2021-02-02T20:08:38.240Z,1612296518.240 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2021-02-02T20:08:38.240Z,1612296518.240 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2021-02-02T20:08:38.249Z,1612296518.249 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2021-02-02T20:08:38.250Z,1612296518.250 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B684E0
2021-02-02T20:08:38.250Z,1612296518.250 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7785
2021-02-02T20:08:38.255Z,1612296518.255 [Supervisor](INFO): Main Thread ID is 7690
2021-02-02T20:08:38.255Z,1612296518.255 [Supervisor](DEBUG): Running supervisor.
2021-02-02T20:08:38.255Z,1612296518.255 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7786
2021-02-02T20:08:38.258Z,1612296518.258 [controlThread ThreadHandler](INFO): Handler Thread ID is 7787
2021-02-02T20:08:38.258Z,1612296518.258 [controlThread](DEBUG): Initializing ControlThread
2021-02-02T20:08:38.259Z,1612296518.259 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2021-02-02T20:08:38.261Z,1612296518.261 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2021-02-02T20:08:38.261Z,1612296518.261 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2021-02-02T20:08:38.262Z,1612296518.262 [LoopControl](DEBUG): Initialize LoopControlComponent.
2021-02-02T20:08:38.264Z,1612296518.264 [NavChart](DEBUG): Initialize NavChart Navigation.
2021-02-02T20:08:38.264Z,1612296518.264 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2021-02-02T20:08:38.270Z,1612296518.270 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2021-02-02T20:08:38.270Z,1612296518.270 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2021-02-02T20:08:38.270Z,1612296518.270 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2021-02-02T20:08:38.270Z,1612296518.270 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2021-02-02T20:08:38.271Z,1612296518.271 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2021-02-02T20:08:38.271Z,1612296518.271 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2021-02-02T20:08:38.276Z,1612296518.276 [SBIT](INFO): Initialize SBIT Component.
2021-02-02T20:08:38.276Z,1612296518.276 [SBIT](IMPORTANT): git: 2020-11-25
2021-02-02T20:08:38.277Z,1612296518.277 [SBIT](INFO): git hash: 5e13bf84858840c906f3eccd44caf880a68d7500
2021-02-02T20:08:38.277Z,1612296518.277 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2021-02-02T20:08:38.278Z,1612296518.278 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Nov 13 12:38:10 PST 2019
2021-02-02T20:08:38.279Z,1612296518.279 [SBIT](INFO): Beginning SBIT in 44.000000 seconds.
2021-02-02T20:08:38.280Z,1612296518.280 [IBIT](INFO): Initialize IBIT Component.
2021-02-02T20:08:38.281Z,1612296518.281 [CBIT](DEBUG): Initialize CBIT Component.
2021-02-02T20:08:38.281Z,1612296518.281 [logger ThreadHandler](INFO): Handler Thread ID is 7788
2021-02-02T20:08:38.292Z,1612296518.292 [CBIT](DEBUG): Initialized mux pins.
2021-02-02T20:08:38.292Z,1612296518.292 [CBIT](DEBUG): Initializing the watchdog timer.
2021-02-02T20:08:38.300Z,1612296518.300 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7789
2021-02-02T20:08:38.301Z,1612296518.301 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2021-02-02T20:08:38.312Z,1612296518.312 [Onboard ThreadHandler](INFO): Handler Thread ID is 7790
2021-02-02T20:08:38.316Z,1612296518.316 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2021-02-02T20:08:38.316Z,1612296518.316 [CBIT](DEBUG): Initializing heartbeat.
2021-02-02T20:08:38.330Z,1612296518.330 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7791
2021-02-02T20:08:38.344Z,1612296518.344 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7792
2021-02-02T20:08:38.345Z,1612296518.345 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-02-02T20:08:38.349Z,1612296518.349 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 7794
2021-02-02T20:08:38.350Z,1612296518.350 [WetLabsBB2FL](INFO): Powering down
2021-02-02T20:08:38.372Z,1612296518.372 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7795
2021-02-02T20:08:38.375Z,1612296518.375 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2021-02-02T20:08:38.375Z,1612296518.375 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2021-02-02T20:08:38.375Z,1612296518.375 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2021-02-02T20:08:38.375Z,1612296518.375 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2021-02-02T20:08:38.376Z,1612296518.376 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2021-02-02T20:08:38.376Z,1612296518.376 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2021-02-02T20:08:38.376Z,1612296518.376 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2021-02-02T20:08:38.376Z,1612296518.376 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2021-02-02T20:08:38.376Z,1612296518.376 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2021-02-02T20:08:38.376Z,1612296518.376 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2021-02-02T20:08:38.377Z,1612296518.377 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2021-02-02T20:08:38.377Z,1612296518.377 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2021-02-02T20:08:38.377Z,1612296518.377 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2021-02-02T20:08:38.377Z,1612296518.377 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2021-02-02T20:08:38.377Z,1612296518.377 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2021-02-02T20:08:38.377Z,1612296518.377 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2021-02-02T20:08:38.387Z,1612296518.388 [CBIT](DEBUG): Deactivating GF circuits.
2021-02-02T20:08:38.388Z,1612296518.388 [CBIT](DEBUG): Deactivating emergency mode.
2021-02-02T20:08:38.424Z,1612296518.424 [CBIT](DEBUG): Backplane powered.
2021-02-02T20:08:38.424Z,1612296518.424 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2021-02-02T20:08:38.434Z,1612296518.434 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2021-02-02T20:08:38.444Z,1612296518.444 [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>


2021-02-02T20:08:38.445Z,1612296518.445 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2021-02-02T20:08:38.513Z,1612296518.513 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2021-02-02T20:08:38.514Z,1612296518.514 [Default:A.Wait](DEBUG): Construct Wait.
2021-02-02T20:08:38.532Z,1612296518.532 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2021-02-02T20:08:38.558Z,1612296518.558 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2021-02-02T20:08:38.561Z,1612296518.561 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2021-02-02T20:08:38.582Z,1612296518.582 [Default:E.Execute](DEBUG): Construct Execute.
2021-02-02T20:08:38.585Z,1612296518.585 [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>


2021-02-02T20:08:38.604Z,1612296518.604 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,BPC1,AMEcho,CANONSampler,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,
2021-02-02T20:08:38.609Z,1612296518.609 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-02-02T20:08:38.649Z,1612296518.649 [Depth_Keller](ERROR): Pressure reading out of range: 1831.023193 decibar
2021-02-02T20:08:38.676Z,1612296518.676 [AMEcho](INFO): Powering up
2021-02-02T20:08:38.678Z,1612296518.678 [AMEcho](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2021-02-02T20:08:38.696Z,1612296518.696 [CANONSampler](INFO): Powering down
2021-02-02T20:08:38.716Z,1612296518.716 [DepthRateCalculator](ERROR): Depth measurement is not active
2021-02-02T20:08:38.736Z,1612296518.736 [Radio_Surface](INFO): Powering up
2021-02-02T20:08:38.737Z,1612296518.737 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2021-02-02T20:08:38.744Z,1612296518.744 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2021-02-02T20:08:38.745Z,1612296518.745 [ElevatorServo](DEBUG): Initializing EZServoServo.
2021-02-02T20:08:38.752Z,1612296518.752 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2021-02-02T20:08:38.753Z,1612296518.753 [MassServo](DEBUG): Initializing EZServoServo.
2021-02-02T20:08:38.756Z,1612296518.756 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2021-02-02T20:08:38.788Z,1612296518.788 [MassServo](DEBUG): Initializing MassServo.
2021-02-02T20:08:38.789Z,1612296518.789 [RudderServo](DEBUG): Initializing EZServoServo.
2021-02-02T20:08:38.796Z,1612296518.796 [RudderServo](DEBUG): Initializing RudderServo.
2021-02-02T20:08:38.797Z,1612296518.797 [ThrusterServo](DEBUG): Initializing EZServoServo.
2021-02-02T20:08:38.808Z,1612296518.808 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2021-02-02T20:08:39.045Z,1612296519.045 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2021-02-02T20:08:39.045Z,1612296519.045 [DropWeight] Hardware Fault, FailCount= 1
2021-02-02T20:08:39.045Z,1612296519.045 [DropWeight](ERROR): Hardware Fault
2021-02-02T20:08:39.212Z,1612296519.212 [AMEcho](INFO): Powering down
2021-02-02T20:08:39.245Z,1612296519.245 [CommandLine](FAULT): Scheduling is paused
2021-02-02T20:08:39.246Z,1612296519.246 [CBIT](INFO): Critical error at 20210202T200839
2021-02-02T20:08:39.246Z,1612296519.246 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2021-02-02T20:08:39.250Z,1612296519.250 [CBIT](ERROR): Hardware Fault in component: DropWeight
2021-02-02T20:08:39.251Z,1612296519.251 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2021-02-02T20:08:39.764Z,1612296519.764 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2021-02-02T20:08:39.764Z,1612296519.764 [RudderServo](FAULT): Rudder failed to initialize
2021-02-02T20:08:39.764Z,1612296519.764 [RudderServo] Communications Fault, FailCount= 1
2021-02-02T20:08:39.764Z,1612296519.764 [RudderServo](ERROR): Communications Fault
2021-02-02T20:08:39.861Z,1612296519.861 [CBIT](INFO): Critical error at 20210202T200839
2021-02-02T20:08:39.864Z,1612296519.864 [CBIT](ERROR): Communications Fault in component: RudderServo
2021-02-02T20:08:39.873Z,1612296519.873 [AMEcho](IMPORTANT): Setting 'enabled' to 0
2021-02-02T20:08:39.873Z,1612296519.873 [AMEcho](IMPORTANT): Setting 'depthThreshold' to 20.000000
2021-02-02T20:08:40.025Z,1612296520.025 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-02-02T20:08:40.025Z,1612296520.025 [RudderServo](INFO): Powering down
2021-02-02T20:08:40.729Z,1612296520.729 [RudderServo](DEBUG): Initializing EZServoServo.
2021-02-02T20:08:40.849Z,1612296520.849 [RudderServo](DEBUG): Initializing RudderServo.
2021-02-02T20:08:40.852Z,1612296520.852 [CBIT](INFO): Clearing failed state for component RudderServo
2021-02-02T20:08:40.853Z,1612296520.853 [RudderServo] No Fault, FailCount= 1
2021-02-02T20:08:42.228Z,1612296522.228 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:08:42.640Z,1612296522.640 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:08:43.136Z,1612296523.136 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:08:43.704Z,1612296523.704 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:08:44.108Z,1612296524.108 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:08:44.616Z,1612296524.616 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:08:45.076Z,1612296525.076 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:08:45.708Z,1612296525.708 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:08:46.176Z,1612296526.176 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:08:46.608Z,1612296526.608 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:08:47.108Z,1612296527.108 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:08:47.580Z,1612296527.580 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:08:48.060Z,1612296528.060 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:08:48.532Z,1612296528.532 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:08:48.664Z,1612296528.664 [CBIT](CRITICAL): Environmental Failure. Press:14.791468 PSI. Humidity:41%. Temp:21 C. ABORTING MISSION
2021-02-02T20:08:48.996Z,1612296528.996 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:08:49.134Z,1612296529.134 [CBIT](INFO): Critical error at 20210202T200848
2021-02-02T20:08:49.464Z,1612296529.464 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:08:49.936Z,1612296529.936 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:08:50.408Z,1612296530.408 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:08:50.868Z,1612296530.868 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:08:50.878Z,1612296530.878 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004689
2021-02-02T20:08:51.340Z,1612296531.340 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:08:51.396Z,1612296531.396 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2021-02-02T20:08:51.396Z,1612296531.396 [CTD_Seabird] Communications Fault, FailCount= 1
2021-02-02T20:08:51.396Z,1612296531.396 [CTD_Seabird](ERROR): Communications Fault
2021-02-02T20:08:51.465Z,1612296531.465 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2021-02-02T20:08:51.796Z,1612296531.796 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:08:51.796Z,1612296531.796 [AHRS_M2](FAULT): Failed to initialize within timeout.
2021-02-02T20:08:51.796Z,1612296531.796 [AHRS_M2] Communications Fault, FailCount= 1
2021-02-02T20:08:51.796Z,1612296531.796 [AHRS_M2](ERROR): Communications Fault
2021-02-02T20:08:51.800Z,1612296531.800 [CTD_Seabird](INFO): Powering down
2021-02-02T20:08:51.935Z,1612296531.935 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2021-02-02T20:08:51.941Z,1612296531.941 [AHRS_M2](INFO): Powering down
2021-02-02T20:08:52.867Z,1612296532.867 [CBIT](INFO): Clearing failed state for component AHRS_M2
2021-02-02T20:08:52.867Z,1612296532.867 [AHRS_M2] No Fault, FailCount= 1
2021-02-02T20:08:52.868Z,1612296532.868 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2021-02-02T20:08:52.868Z,1612296532.868 [CTD_Seabird] No Fault, FailCount= 1
2021-02-02T20:08:53.152Z,1612296533.152 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-02-02T20:08:53.168Z,1612296533.168 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-02-02T20:08:56.704Z,1612296536.704 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:08:57.188Z,1612296537.188 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:08:57.672Z,1612296537.672 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:08:58.156Z,1612296538.156 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:08:58.612Z,1612296538.612 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:08:59.108Z,1612296539.108 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:08:59.556Z,1612296539.556 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:00.084Z,1612296540.084 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:00.476Z,1612296540.476 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:00.892Z,1612296540.892 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:01.288Z,1612296541.288 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:01.692Z,1612296541.692 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:02.096Z,1612296542.096 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:02.508Z,1612296542.508 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:02.928Z,1612296542.928 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:03.344Z,1612296543.344 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:03.744Z,1612296543.744 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:04.140Z,1612296544.140 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:04.552Z,1612296544.552 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:04.952Z,1612296544.952 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:05.384Z,1612296545.384 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:05.800Z,1612296545.800 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:06.192Z,1612296546.192 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:06.192Z,1612296546.192 [AHRS_M2](FAULT): Failed to initialize within timeout.
2021-02-02T20:09:06.192Z,1612296546.192 [AHRS_M2] Communications Fault, FailCount= 2
2021-02-02T20:09:06.192Z,1612296546.192 [AHRS_M2](ERROR): Communications Fault
2021-02-02T20:09:06.220Z,1612296546.220 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2021-02-02T20:09:06.220Z,1612296546.220 [CTD_Seabird] Communications Fault, FailCount= 2
2021-02-02T20:09:06.220Z,1612296546.220 [CTD_Seabird](ERROR): Communications Fault
2021-02-02T20:09:06.297Z,1612296546.297 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2021-02-02T20:09:06.297Z,1612296546.297 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2021-02-02T20:09:06.302Z,1612296546.302 [AHRS_M2](INFO): Powering down
2021-02-02T20:09:06.377Z,1612296546.377 [NAL9602](INFO): Powering up NAL9602
2021-02-02T20:09:06.624Z,1612296546.624 [CTD_Seabird](INFO): Powering down
2021-02-02T20:09:07.155Z,1612296547.155 [CBIT](INFO): Clearing failed state for component AHRS_M2
2021-02-02T20:09:07.155Z,1612296547.155 [AHRS_M2] No Fault, FailCount= 2
2021-02-02T20:09:07.512Z,1612296547.512 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-02-02T20:09:07.608Z,1612296547.608 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2021-02-02T20:09:07.608Z,1612296547.608 [CTD_Seabird] No Fault, FailCount= 2
2021-02-02T20:09:07.968Z,1612296547.968 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2021-02-02T20:09:08.133Z,1612296548.133 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 10.000000 millimeter
2021-02-02T20:09:08.134Z,1612296548.134 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.massPositionAction" handled in the control thread.
2021-02-02T20:09:08.448Z,1612296548.448 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,BPC1,AMEcho,CANONSampler,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.massPositionAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2021-02-02T20:09:11.060Z,1612296551.060 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:11.492Z,1612296551.492 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:11.928Z,1612296551.928 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:12.344Z,1612296552.344 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:12.760Z,1612296552.760 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:13.224Z,1612296553.224 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:13.632Z,1612296553.632 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:13.692Z,1612296553.692 [CommandLine](IMPORTANT): got command get 
2021-02-02T20:09:13.692Z,1612296553.692 [CommandLine](FAULT): Incomplete syntax. Try: help get
2021-02-02T20:09:14.104Z,1612296554.104 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:14.524Z,1612296554.524 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:14.956Z,1612296554.956 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:15.356Z,1612296555.356 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:15.772Z,1612296555.772 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:16.216Z,1612296556.216 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:16.616Z,1612296556.616 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:17.060Z,1612296557.060 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:17.488Z,1612296557.488 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:17.493Z,1612296557.493 [NAL9602](INFO): NAL9602 initialized
2021-02-02T20:09:17.920Z,1612296557.920 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:18.324Z,1612296558.324 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:18.764Z,1612296558.764 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:19.176Z,1612296559.176 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:19.588Z,1612296559.588 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:19.988Z,1612296559.988 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:20.004Z,1612296560.004 [CommandLine](IMPORTANT): got command show variable fwd
2021-02-02T20:09:20.038Z,1612296560.038 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitFwd (millimeter)
2021-02-02T20:09:20.472Z,1612296560.472 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:20.472Z,1612296560.472 [AHRS_M2](FAULT): Failed to initialize within timeout.
2021-02-02T20:09:20.472Z,1612296560.472 [AHRS_M2] Communications Fault, FailCount= 3
2021-02-02T20:09:20.472Z,1612296560.472 [AHRS_M2](ERROR): Communications Fault
2021-02-02T20:09:20.580Z,1612296560.580 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2021-02-02T20:09:20.585Z,1612296560.585 [AHRS_M2](INFO): Powering down
2021-02-02T20:09:21.020Z,1612296561.020 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2021-02-02T20:09:21.020Z,1612296561.020 [CTD_Seabird] Communications Fault, FailCount= 3
2021-02-02T20:09:21.020Z,1612296561.020 [CTD_Seabird](ERROR): Communications Fault
2021-02-02T20:09:21.076Z,1612296561.076 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2021-02-02T20:09:21.076Z,1612296561.076 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2021-02-02T20:09:21.424Z,1612296561.424 [CTD_Seabird](INFO): Powering down
2021-02-02T20:09:21.487Z,1612296561.487 [CBIT](INFO): Clearing failed state for component AHRS_M2
2021-02-02T20:09:21.487Z,1612296561.487 [AHRS_M2] No Fault, FailCount= 3
2021-02-02T20:09:21.796Z,1612296561.796 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-02-02T20:09:23.073Z,1612296563.073 [SBIT](IMPORTANT): Beginning Startup BIT
2021-02-02T20:09:23.077Z,1612296563.077 [CBIT](IMPORTANT): Beginning ground fault scan
2021-02-02T20:09:25.352Z,1612296565.352 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:25.832Z,1612296565.832 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:26.392Z,1612296566.392 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:26.908Z,1612296566.908 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:27.596Z,1612296567.596 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:28.076Z,1612296568.076 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:28.720Z,1612296568.720 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:29.132Z,1612296569.132 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:29.536Z,1612296569.536 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:29.636Z,1612296569.636 [CommandLine](IMPORTANT): got command get VerticalControl.massPositionLimitFwd
2021-02-02T20:09:29.637Z,1612296569.637 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitFwd 25.000000 mm
2021-02-02T20:09:29.952Z,1612296569.952 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:30.432Z,1612296570.432 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:30.840Z,1612296570.840 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:31.268Z,1612296571.268 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:31.776Z,1612296571.776 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:32.196Z,1612296572.196 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:32.596Z,1612296572.596 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:33.168Z,1612296573.168 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:33.604Z,1612296573.604 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:34.024Z,1612296574.024 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:34.504Z,1612296574.504 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:34.587Z,1612296574.587 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.015880
CHAN A1 (24V): 0.000581
CHAN A2 (12V): -0.002223
CHAN A3 (5V): -0.001753
CHAN B0 (3.3V): 0.000246
CHAN B1 (3.15aV): -0.000322
CHAN B2 (3.15bV): -0.000237
CHAN B3 (GND): -0.000109
OPEN: -0.000426
Full Scale Calc: 4.765 mA, -1.589 mA
2021-02-02T20:09:34.932Z,1612296574.932 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:34.932Z,1612296574.932 [AHRS_M2](FAULT): Failed to initialize within timeout.
2021-02-02T20:09:34.932Z,1612296574.932 [AHRS_M2] Communications Fault, FailCount= 4
2021-02-02T20:09:34.932Z,1612296574.932 [AHRS_M2](ERROR): Communications Fault
2021-02-02T20:09:35.050Z,1612296575.050 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2021-02-02T20:09:35.083Z,1612296575.083 [AHRS_M2](INFO): Powering down
2021-02-02T20:09:35.966Z,1612296575.966 [CBIT](INFO): Clearing failed state for component AHRS_M2
2021-02-02T20:09:35.967Z,1612296575.967 [AHRS_M2] No Fault, FailCount= 4
2021-02-02T20:09:36.267Z,1612296576.267 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2021-02-02T20:09:36.386Z,1612296576.386 [SBIT](FAULT): Mass: EXPECTED:-0.001105 ACTUAL:0.009272
2021-02-02T20:09:39.453Z,1612296579.453 [CommandLine](INFO): End of History
2021-02-02T20:09:39.824Z,1612296579.824 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:40.156Z,1612296580.156 [CommandLine](INFO): End of History
2021-02-02T20:09:40.260Z,1612296580.260 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:40.680Z,1612296580.680 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:40.852Z,1612296580.852 [CommandLine](INFO): End of History
2021-02-02T20:09:41.092Z,1612296581.092 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:41.512Z,1612296581.512 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:41.964Z,1612296581.964 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:42.436Z,1612296582.436 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:42.773Z,1612296582.773 [CommandLine](INFO): End of History
2021-02-02T20:09:42.892Z,1612296582.892 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:43.332Z,1612296583.332 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:43.900Z,1612296583.900 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:44.492Z,1612296584.492 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:44.000Z,1612296585.000 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:45.476Z,1612296585.476 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:45.920Z,1612296585.920 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:46.344Z,1612296586.344 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:46.764Z,1612296586.764 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:47.152Z,1612296587.152 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:47.560Z,1612296587.560 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:47.976Z,1612296587.976 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:48.384Z,1612296588.384 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:48.776Z,1612296588.776 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:49.176Z,1612296589.176 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2021-02-02T20:09:49.176Z,1612296589.176 [AHRS_M2](FAULT): Failed to initialize within timeout.
2021-02-02T20:09:49.176Z,1612296589.176 [AHRS_M2] Communications Fault, FailCount= 5
2021-02-02T20:09:49.176Z,1612296589.176 [AHRS_M2](ERROR): Communications Fault
2021-02-02T20:09:49.233Z,1612296589.233 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2021-02-02T20:09:49.233Z,1612296589.233 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2
2021-02-02T20:09:49.256Z,1612296589.256 [AHRS_M2](INFO): Powering down
2021-02-02T20:09:49.373Z,1612296589.373 [CBIT](INFO): Critical error at 20210202T200949
2021-02-02T20:10:02.954Z,1612296602.954 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 25.000000 millimeter
2021-02-02T20:10:03.039Z,1612296603.039 [SBIT](FAULT): Mass: EXPECTED:-0.013105 ACTUAL:0.009999
2021-02-02T20:10:03.039Z,1612296603.039 [SBIT](FAULT): Control surface position failure.
2021-02-02T20:10:12.537Z,1612296612.537 [CommandLine](IMPORTANT): got command maintain control VerticalControl.massPositionAction 25.000000 millimeter
2021-02-02T20:10:16.810Z,1612296616.810 [SBIT](FAULT): Mass: EXPECTED:-0.006105 ACTUAL:0.019086
2021-02-02T20:10:16.810Z,1612296616.810 [SBIT](FAULT): Control surface position failure.
2021-02-02T20:10:17.189Z,1612296617.189 [SBIT](CRITICAL): SBIT FAILED
2021-02-02T20:10:17.189Z,1612296617.189 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2021-02-02T20:10:17.190Z,1612296617.190 [SBIT](IMPORTANT): AMEcho.enabled=0 bool;

2021-02-02T20:10:17.190Z,1612296617.190 [SBIT](IMPORTANT): ElevatorOffsetCalculator.loadAtStartup=1 bool;

2021-02-02T20:10:17.191Z,1612296617.191 [SBIT](IMPORTANT): Express linearApproximation AMEcho.altitude 1.000000 meter;

2021-02-02T20:10:17.191Z,1612296617.191 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 1.000000 meter;

2021-02-02T20:10:17.191Z,1612296617.191 [SBIT](IMPORTANT): PAR_Licor.parCal=1.0914 none;

2021-02-02T20:10:17.191Z,1612296617.191 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=229.775315 cubic_centimeter;

2021-02-02T20:10:17.191Z,1612296617.191 [SBIT](IMPORTANT): VerticalControl.massDefault=-6.105428 millimeter;

2021-02-02T20:10:17.193Z,1612296617.193 [CBIT](INFO): Critical error at 20210202T201017
2021-02-02T20:10:17.568Z,1612296617.568 [MissionManager](IMPORTANT): Started mission Startup
2021-02-02T20:10:17.568Z,1612296617.568 [Startup] Running Loop=1
2021-02-02T20:10:17.568Z,1612296617.568 [Startup](DEBUG): Aggregate::initialize Startup
2021-02-02T20:10:17.568Z,1612296617.568 [Startup:A.GoToSurface] Running Loop=1
2021-02-02T20:10:17.568Z,1612296617.568 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2021-02-02T20:10:17.569Z,1612296617.569 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2021-02-02T20:10:17.570Z,1612296617.570 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2021-02-02T20:10:17.570Z,1612296617.570 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2021-02-02T20:10:17.570Z,1612296617.570 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2021-02-02T20:10:17.571Z,1612296617.571 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2021-02-02T20:10:17.572Z,1612296617.572 [Startup:StartupSatComms] Running Loop=1
2021-02-02T20:10:17.572Z,1612296617.572 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2021-02-02T20:10:17.572Z,1612296617.572 [Startup:StartupSatComms:A] Running Loop=1
2021-02-02T20:10:17.993Z,1612296617.993 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2021-02-02T20:10:38.570Z,1612296638.570 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2021-02-02T20:10:38.570Z,1612296638.570 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2021-02-02T20:10:38.570Z,1612296638.570 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2021-02-02T20:10:38.581Z,1612296638.581 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2021-02-02T20:10:39.001Z,1612296639.001 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2021-02-02T20:10:39.001Z,1612296639.001 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2021-02-02T20:11:14.772Z,1612296674.772 [CommandLine](IMPORTANT): got command quit
2021-02-02T20:11:15.776Z,1612296675.776 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2021-02-02T20:11:15.776Z,1612296675.776 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-02-02T20:11:15.891Z,1612296675.891 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2021-02-02T20:11:15.892Z,1612296675.892 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-02-02T20:11:15.892Z,1612296675.892 [CommandLine](INFO): Join timeout helper Thread ID is 7822
2021-02-02T20:11:15.893Z,1612296675.893 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2021-02-02T20:11:15.893Z,1612296675.893 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-02-02T20:11:15.893Z,1612296675.893 [NavChartDb](INFO): Join timeout helper Thread ID is 7823
2021-02-02T20:11:16.076Z,1612296676.076 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2021-02-02T20:11:16.076Z,1612296676.076 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-02-02T20:11:16.092Z,1612296676.092 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2021-02-02T20:11:16.092Z,1612296676.092 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-02-02T20:11:16.092Z,1612296676.092 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7824
2021-02-02T20:11:16.164Z,1612296676.164 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2021-02-02T20:11:16.164Z,1612296676.164 [WetLabsBB2FL](INFO): Powering down
2021-02-02T20:11:16.165Z,1612296676.165 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-02-02T20:11:16.165Z,1612296676.165 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2021-02-02T20:11:16.166Z,1612296676.166 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-02-02T20:11:16.166Z,1612296676.166 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7825
2021-02-02T20:11:16.311Z,1612296676.311 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2021-02-02T20:11:16.312Z,1612296676.312 [CTD_Seabird](INFO): Powering down
2021-02-02T20:11:16.324Z,1612296676.324 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-02-02T20:11:16.324Z,1612296676.324 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2021-02-02T20:11:16.324Z,1612296676.324 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-02-02T20:11:16.324Z,1612296676.324 [Radio_Surface](INFO): Join timeout helper Thread ID is 7826
2021-02-02T20:11:16.436Z,1612296676.436 [Radio_Surface](INFO): Powering down
2021-02-02T20:11:16.436Z,1612296676.436 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2021-02-02T20:11:16.437Z,1612296676.437 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-02-02T20:11:16.444Z,1612296676.444 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2021-02-02T20:11:16.445Z,1612296676.445 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-02-02T20:11:16.445Z,1612296676.445 [Onboard](INFO): Join timeout helper Thread ID is 7827
2021-02-02T20:11:17.765Z,1612296677.765 [Startup:StartupSatComms:A](INFO): Timed out from 2021-02-02T20:10:17.6Z
2021-02-02T20:11:17.765Z,1612296677.765 [Startup:StartupSatComms:A] Stopped
2021-02-02T20:11:17.765Z,1612296677.765 [Startup:StartupSatComms:B] Running Loop=1
2021-02-02T20:11:17.916Z,1612296677.916 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2021-02-02T20:11:18.173Z,1612296678.173 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2021-02-02T20:11:18.452Z,1612296678.452 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2021-02-02T20:11:18.452Z,1612296678.452 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-02-02T20:11:18.464Z,1612296678.464 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2021-02-02T20:11:18.464Z,1612296678.464 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-02-02T20:11:18.465Z,1612296678.465 [DataOverHttps](INFO): Join timeout helper Thread ID is 7828
2021-02-02T20:11:18.920Z,1612296678.920 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2021-02-02T20:11:18.920Z,1612296678.920 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-02-02T20:11:18.924Z,1612296678.924 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2021-02-02T20:11:18.924Z,1612296678.924 [logger ThreadHandler](INFO): Thread cancelled.
2021-02-02T20:11:18.925Z,1612296678.925 [logger](INFO): Join timeout helper Thread ID is 7829
2021-02-02T20:11:18.944Z,1612296678.944 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2021-02-02T20:11:18.944Z,1612296678.944 [logger ThreadHandler](INFO): Thread cancelled.
2021-02-02T20:11:18.972Z,1612296678.972 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2021-02-02T20:11:18.972Z,1612296678.972 [CommandLine ThreadHandler](INFO): Thread cancelled.
2021-02-02T20:11:18.973Z,1612296678.973 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2021-02-02T20:11:18.973Z,1612296678.973 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-02-02T20:11:18.973Z,1612296678.973 [controlThread](INFO): Join timeout helper Thread ID is 7830
2021-02-02T20:11:19.029Z,1612296679.029 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2021-02-02T20:11:19.029Z,1612296679.029 [controlThread](DEBUG): Uninitializing ControlThread
2021-02-02T20:11:19.030Z,1612296679.030 [AHRS_M2](INFO): Powering down
2021-02-02T20:11:19.031Z,1612296679.031 [NAL9602](INFO): Powering down
2021-02-02T20:11:19.033Z,1612296679.033 [AMEcho](INFO): Powering down
2021-02-02T20:11:19.035Z,1612296679.035 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2021-02-02T20:11:19.036Z,1612296679.036 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2021-02-02T20:11:19.036Z,1612296679.036 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2021-02-02T20:11:19.037Z,1612296679.037 [MissionManager](INFO): Uninitializing Mission Default
2021-02-02T20:11:19.039Z,1612296679.039 [MissionManager](INFO): Uninitializing Mission Startup
2021-02-02T20:11:19.039Z,1612296679.039 [Startup] Stopped
2021-02-02T20:11:19.039Z,1612296679.039 [Startup](DEBUG): Aggregate::uninitialize Startup
2021-02-02T20:11:19.039Z,1612296679.039 [Startup:A.GoToSurface] Stopped
2021-02-02T20:11:19.039Z,1612296679.039 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2021-02-02T20:11:19.039Z,1612296679.039 [Startup:StartupSatComms] Stopped
2021-02-02T20:11:19.039Z,1612296679.039 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2021-02-02T20:11:19.039Z,1612296679.039 [Startup:StartupSatComms:B] Stopped
2021-02-02T20:11:19.040Z,1612296679.040 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2021-02-02T20:11:19.041Z,1612296679.041 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2021-02-02T20:11:19.041Z,1612296679.041 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2021-02-02T20:11:19.041Z,1612296679.041 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2021-02-02T20:11:19.042Z,1612296679.042 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2021-02-02T20:11:19.042Z,1612296679.042 [BuoyancyServo](INFO): Powering down
2021-02-02T20:11:19.056Z,1612296679.056 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2021-02-02T20:11:19.056Z,1612296679.056 [ElevatorServo](INFO): Powering down
2021-02-02T20:11:19.057Z,1612296679.057 [MassServo](DEBUG): Uninitialize Mass Servo.
2021-02-02T20:11:19.057Z,1612296679.057 [MassServo](INFO): Powering down
2021-02-02T20:11:19.057Z,1612296679.057 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2021-02-02T20:11:19.058Z,1612296679.058 [RudderServo](INFO): Powering down
2021-02-02T20:11:19.058Z,1612296679.058 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2021-02-02T20:11:19.058Z,1612296679.058 [ThrusterServo](INFO): Powering down
2021-02-02T20:11:19.059Z,1612296679.059 [SBIT](DEBUG): Uninitialize SBIT Component.
2021-02-02T20:11:19.059Z,1612296679.059 [IBIT](DEBUG): Uninitialize IBIT Component.
2021-02-02T20:11:19.060Z,1612296679.060 [CBIT](DEBUG): Uninitialize CBIT Component.
2021-02-02T20:11:19.060Z,1612296679.060 [CBIT](DEBUG): Powering off loads.
2021-02-02T20:11:19.071Z,1612296679.071 [CBIT](DEBUG): Disabling WDT.
2021-02-02T20:11:19.083Z,1612296679.083 [CBIT](DEBUG): Opening all GF detection circuits.
2021-02-02T20:11:19.084Z,1612296679.084 [controlThread ThreadHandler](INFO): Thread cancelled.
2021-02-02T20:11:19.177Z,1612296679.177 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2021-02-02T20:11:19.179Z,1612296679.179 [Onboard ThreadHandler](INFO): Thread cancelled.
2021-02-02T20:11:19.184Z,1612296679.184 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2021-02-02T20:11:19.217Z,1612296679.217 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2021-02-02T20:11:19.221Z,1612296679.221 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2021-02-02T20:11:19.264Z,1612296679.264 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2021-02-02T20:11:19.328Z,1612296679.328 [logger ThreadHandler](INFO): Thread cancelled.