2020-10-13T19:32:59.723Z,1602617579.723 [Supervisor](DEBUG): Initializing supervisor.
2020-10-13T19:32:59.725Z,1602617579.725 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-10-13T19:32:59.726Z,1602617579.726 [SyncHandler](INFO): Protected caller Thread ID is 3406
2020-10-13T19:32:59.726Z,1602617579.726 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-10-13T19:32:59.727Z,1602617579.727 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-10-13T19:32:59.728Z,1602617579.728 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3407
2020-10-13T19:32:59.730Z,1602617579.730 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-10-13T19:32:59.743Z,1602617579.743 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-10-13T19:32:59.744Z,1602617579.744 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-10-13T19:32:59.745Z,1602617579.745 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3408
2020-10-13T19:32:59.746Z,1602617579.746 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-10-13T19:32:59.746Z,1602617579.746 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-10-13T19:32:59.747Z,1602617579.747 [logger ThreadHandler](INFO): Protected caller Thread ID is 3409
2020-10-13T19:32:59.749Z,1602617579.749 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-10-13T19:32:59.749Z,1602617579.749 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-10-13T19:32:59.751Z,1602617579.751 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-10-13T19:32:59.950Z,1602617579.950 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-10-13T19:32:59.951Z,1602617579.951 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-10-13T19:33:00.028Z,1602617580.028 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-10-13T19:33:00.450Z,1602617580.450 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-10-13T19:33:00.450Z,1602617580.450 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-10-13T19:33:00.775Z,1602617580.775 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-10-13T19:33:00.775Z,1602617580.775 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-10-13T19:33:00.870Z,1602617580.870 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-10-13T19:33:00.870Z,1602617580.870 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-10-13T19:33:01.161Z,1602617581.161 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-10-13T19:33:01.162Z,1602617581.162 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-10-13T19:33:01.352Z,1602617581.352 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-10-13T19:33:01.353Z,1602617581.353 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-10-13T19:33:01.896Z,1602617581.896 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-10-13T19:33:01.896Z,1602617581.896 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-10-13T19:33:01.997Z,1602617581.997 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-10-13T19:33:01.998Z,1602617581.998 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-10-13T19:33:02.095Z,1602617582.095 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-10-13T19:33:02.096Z,1602617582.096 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-10-13T19:33:02.745Z,1602617582.745 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-10-13T19:33:02.745Z,1602617582.745 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-10-13T19:33:03.154Z,1602617583.154 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-10-13T19:33:03.154Z,1602617583.154 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-10-13T19:33:03.341Z,1602617583.341 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-10-13T19:33:03.342Z,1602617583.342 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-10-13T19:33:03.486Z,1602617583.486 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-10-13T19:33:03.487Z,1602617583.487 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-10-13T19:33:03.632Z,1602617583.632 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-10-13T19:33:03.633Z,1602617583.633 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-10-13T19:33:03.834Z,1602617583.834 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-10-13T19:33:03.836Z,1602617583.836 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2020-10-13T19:33:03.837Z,1602617583.837 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Navigation.cfg
2020-10-13T19:33:03.925Z,1602617583.925 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2020-10-13T19:33:04.008Z,1602617584.008 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2020-10-13T19:33:04.106Z,1602617584.106 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/secure.cfg
2020-10-13T19:33:04.187Z,1602617584.187 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2020-10-13T19:33:04.280Z,1602617584.280 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2020-10-13T19:33:04.378Z,1602617584.378 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2020-10-13T19:33:04.619Z,1602617584.619 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/workSite.cfg
2020-10-13T19:33:04.704Z,1602617584.704 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2020-10-13T19:33:05.011Z,1602617585.011 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2020-10-13T19:33:05.396Z,1602617585.396 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2020-10-13T19:33:05.636Z,1602617585.636 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Battery.cfg
2020-10-13T19:33:06.070Z,1602617586.070 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-10-13T19:33:06.071Z,1602617586.071 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/root/
2020-10-13T19:33:06.072Z,1602617586.072 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-10-13T19:33:06.079Z,1602617586.079 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-10-13T19:33:06.127Z,1602617586.127 [VerticalControl](DEBUG): Construct VerticalControl.
2020-10-13T19:33:06.238Z,1602617586.238 [VerticalControl] Loaded
2020-10-13T19:33:06.238Z,1602617586.238 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-10-13T19:33:06.239Z,1602617586.239 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-10-13T19:33:06.319Z,1602617586.319 [HorizontalControl] Loaded
2020-10-13T19:33:06.319Z,1602617586.319 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-10-13T19:33:06.320Z,1602617586.320 [SpeedControl](DEBUG): Construct SpeedControl.
2020-10-13T19:33:06.325Z,1602617586.325 [SpeedControl] Loaded
2020-10-13T19:33:06.325Z,1602617586.325 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-10-13T19:33:06.326Z,1602617586.326 [LoopControl](DEBUG): Construct LoopControl.
2020-10-13T19:33:06.326Z,1602617586.326 [LoopControl] Loaded
2020-10-13T19:33:06.327Z,1602617586.327 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-10-13T19:33:06.327Z,1602617586.327 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-10-13T19:33:06.328Z,1602617586.328 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-10-13T19:33:06.346Z,1602617586.346 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-10-13T19:33:06.347Z,1602617586.347 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-10-13T19:33:06.444Z,1602617586.444 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-10-13T19:33:06.444Z,1602617586.444 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-10-13T19:33:06.565Z,1602617586.565 [BuoyancyServo] Loaded
2020-10-13T19:33:06.566Z,1602617586.566 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-10-13T19:33:06.594Z,1602617586.594 [ElevatorServo] Loaded
2020-10-13T19:33:06.594Z,1602617586.594 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-10-13T19:33:06.621Z,1602617586.621 [MassServo] Loaded
2020-10-13T19:33:06.621Z,1602617586.621 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-10-13T19:33:06.648Z,1602617586.648 [RudderServo] Loaded
2020-10-13T19:33:06.648Z,1602617586.648 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-10-13T19:33:06.675Z,1602617586.675 [ThrusterServo] Loaded
2020-10-13T19:33:06.675Z,1602617586.675 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-10-13T19:33:06.676Z,1602617586.676 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-10-13T19:33:06.676Z,1602617586.676 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-10-13T19:33:06.796Z,1602617586.796 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-10-13T19:33:06.796Z,1602617586.796 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-10-13T19:33:06.816Z,1602617586.816 [NavChart] Loaded
2020-10-13T19:33:06.816Z,1602617586.816 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-10-13T19:33:06.820Z,1602617586.820 [UniversalFixResidualReporter] Loaded
2020-10-13T19:33:06.821Z,1602617586.821 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-10-13T19:33:06.821Z,1602617586.821 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-10-13T19:33:06.822Z,1602617586.822 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-10-13T19:33:06.906Z,1602617586.906 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-10-13T19:33:06.907Z,1602617586.907 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-10-13T19:33:07.212Z,1602617587.212 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-10-13T19:33:07.217Z,1602617587.217 [AHRS_M2](INFO): created writer for : platform_orientation
2020-10-13T19:33:07.219Z,1602617587.219 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-10-13T19:33:07.224Z,1602617587.224 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-10-13T19:33:07.225Z,1602617587.225 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-10-13T19:33:07.229Z,1602617587.229 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-10-13T19:33:07.230Z,1602617587.230 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-10-13T19:33:07.235Z,1602617587.235 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-10-13T19:33:07.307Z,1602617587.307 [AHRS_M2] Loaded
2020-10-13T19:33:07.307Z,1602617587.307 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-10-13T19:33:07.390Z,1602617587.390 [DataOverHttps] Loaded
2020-10-13T19:33:07.391Z,1602617587.391 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-10-13T19:33:07.392Z,1602617587.392 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408A14E0
2020-10-13T19:33:07.392Z,1602617587.392 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3495
2020-10-13T19:33:07.422Z,1602617587.422 [Depth_Keller] Loaded
2020-10-13T19:33:07.422Z,1602617587.422 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-10-13T19:33:07.427Z,1602617587.427 [DropWeight] Loaded
2020-10-13T19:33:07.427Z,1602617587.427 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-10-13T19:33:07.539Z,1602617587.539 [NAL9602] Loaded
2020-10-13T19:33:07.540Z,1602617587.540 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-10-13T19:33:07.588Z,1602617587.588 [Onboard] Loaded
2020-10-13T19:33:07.588Z,1602617587.588 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2020-10-13T19:33:07.589Z,1602617587.589 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408D14E0
2020-10-13T19:33:07.589Z,1602617587.589 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 3496
2020-10-13T19:33:07.609Z,1602617587.609 [Radio_Surface] Loaded
2020-10-13T19:33:07.609Z,1602617587.609 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-10-13T19:33:07.610Z,1602617587.610 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409014E0
2020-10-13T19:33:07.611Z,1602617587.611 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3497
2020-10-13T19:33:09.085Z,1602617589.085 [BPC1] Loaded
2020-10-13T19:33:09.085Z,1602617589.085 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-10-13T19:33:09.118Z,1602617589.118 [AMEcho] Loaded
2020-10-13T19:33:09.118Z,1602617589.118 [ComponentRegistry](DEBUG): SyncComponent "AMEcho" handled in the control thread.
2020-10-13T19:33:09.118Z,1602617589.118 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-10-13T19:33:09.119Z,1602617589.119 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-10-13T19:33:09.159Z,1602617589.159 [DepthRateCalculator] Loaded
2020-10-13T19:33:09.159Z,1602617589.159 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-10-13T19:33:09.164Z,1602617589.164 [PitchRateCalculator] Loaded
2020-10-13T19:33:09.165Z,1602617589.165 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-10-13T19:33:09.176Z,1602617589.176 [SpeedCalculator] Loaded
2020-10-13T19:33:09.177Z,1602617589.177 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-10-13T19:33:09.198Z,1602617589.198 [TempGradientCalculator] Loaded
2020-10-13T19:33:09.198Z,1602617589.198 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-10-13T19:33:09.203Z,1602617589.203 [YawRateCalculator] Loaded
2020-10-13T19:33:09.203Z,1602617589.203 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-10-13T19:33:09.233Z,1602617589.233 [ElevatorOffsetCalculator] Loaded
2020-10-13T19:33:09.233Z,1602617589.233 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-10-13T19:33:09.233Z,1602617589.233 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-10-13T19:33:09.234Z,1602617589.234 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-10-13T19:33:09.439Z,1602617589.439 [CANONSampler] Loaded
2020-10-13T19:33:09.440Z,1602617589.440 [ComponentRegistry](DEBUG): SyncComponent "CANONSampler" handled in the control thread.
2020-10-13T19:33:09.467Z,1602617589.467 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-10-13T19:33:09.472Z,1602617589.472 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-10-13T19:33:09.473Z,1602617589.473 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-10-13T19:33:09.477Z,1602617589.477 [CTD_Seabird](INFO): created writer for : depth
2020-10-13T19:33:09.478Z,1602617589.478 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-10-13T19:33:09.483Z,1602617589.483 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-10-13T19:33:09.483Z,1602617589.483 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-10-13T19:33:09.488Z,1602617589.488 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-10-13T19:33:09.489Z,1602617589.489 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-10-13T19:33:09.494Z,1602617589.494 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-10-13T19:33:09.495Z,1602617589.495 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-10-13T19:33:09.500Z,1602617589.500 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-10-13T19:33:09.500Z,1602617589.500 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-10-13T19:33:09.506Z,1602617589.506 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-10-13T19:33:09.506Z,1602617589.506 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): mass_concentration_of_oxygen_in_sea_water
2020-10-13T19:33:09.511Z,1602617589.511 [CTD_Seabird](INFO): created writer for : mass_concentration_of_oxygen_in_sea_water
2020-10-13T19:33:09.512Z,1602617589.512 [CTD_Seabird](DEBUG): LcmSlateWriter::add(DAtaURI): CTD_Seabird.sea_water_dissolved_oxygen_freq
2020-10-13T19:33:09.512Z,1602617589.512 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): dataName: sea_water_dissolved_oxygen_freq
2020-10-13T19:33:09.545Z,1602617589.545 [CTD_Seabird] Loaded
2020-10-13T19:33:09.545Z,1602617589.545 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-10-13T19:33:09.546Z,1602617589.546 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A0D4E0
2020-10-13T19:33:09.546Z,1602617589.546 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3498
2020-10-13T19:33:09.577Z,1602617589.577 [PAR_Licor] Loaded
2020-10-13T19:33:09.577Z,1602617589.577 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-10-13T19:33:09.599Z,1602617589.599 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-10-13T19:33:09.599Z,1602617589.599 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-10-13T19:33:09.603Z,1602617589.603 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-10-13T19:33:09.603Z,1602617589.603 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-10-13T19:33:09.607Z,1602617589.607 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-10-13T19:33:09.607Z,1602617589.607 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-10-13T19:33:09.611Z,1602617589.611 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-10-13T19:33:09.612Z,1602617589.612 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-10-13T19:33:09.616Z,1602617589.616 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-10-13T19:33:09.616Z,1602617589.616 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-10-13T19:33:09.620Z,1602617589.620 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-10-13T19:33:09.620Z,1602617589.620 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-10-13T19:33:09.625Z,1602617589.625 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-10-13T19:33:09.625Z,1602617589.625 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-10-13T19:33:09.629Z,1602617589.629 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-10-13T19:33:09.634Z,1602617589.634 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-10-13T19:33:09.634Z,1602617589.634 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-10-13T19:33:09.635Z,1602617589.635 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-10-13T19:33:09.639Z,1602617589.639 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-10-13T19:33:09.639Z,1602617589.639 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-10-13T19:33:09.643Z,1602617589.643 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-10-13T19:33:09.644Z,1602617589.644 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-10-13T19:33:09.648Z,1602617589.648 [WetLabsBB2FL] Loaded
2020-10-13T19:33:09.648Z,1602617589.648 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-10-13T19:33:09.649Z,1602617589.649 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A3D4E0
2020-10-13T19:33:09.650Z,1602617589.650 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3499
2020-10-13T19:33:09.650Z,1602617589.650 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-10-13T19:33:09.651Z,1602617589.651 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-10-13T19:33:09.740Z,1602617589.740 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-10-13T19:33:09.749Z,1602617589.749 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-10-13T19:33:10.124Z,1602617590.124 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-10-13T19:33:10.124Z,1602617590.124 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-10-13T19:33:10.258Z,1602617590.258 [SBIT](DEBUG): Construct Startup Built In Test.
2020-10-13T19:33:10.269Z,1602617590.269 [SBIT] Loaded
2020-10-13T19:33:10.270Z,1602617590.270 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-10-13T19:33:10.270Z,1602617590.270 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-10-13T19:33:10.283Z,1602617590.283 [IBIT] Loaded
2020-10-13T19:33:10.284Z,1602617590.284 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-10-13T19:33:10.287Z,1602617590.287 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-10-13T19:33:10.427Z,1602617590.427 [CBIT] Loaded
2020-10-13T19:33:10.427Z,1602617590.427 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-10-13T19:33:10.427Z,1602617590.427 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-10-13T19:33:10.428Z,1602617590.428 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-10-13T19:33:10.504Z,1602617590.504 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2020-10-13T19:33:10.508Z,1602617590.508 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-10-13T19:33:10.509Z,1602617590.509 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-10-13T19:33:10.517Z,1602617590.517 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-10-13T19:33:10.518Z,1602617590.518 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B634E0
2020-10-13T19:33:10.518Z,1602617590.518 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3500
2020-10-13T19:33:10.522Z,1602617590.522 [Supervisor](INFO): Main Thread ID is 3405
2020-10-13T19:33:10.523Z,1602617590.523 [Supervisor](DEBUG): Running supervisor.
2020-10-13T19:33:10.523Z,1602617590.523 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3501
2020-10-13T19:33:10.526Z,1602617590.526 [controlThread ThreadHandler](INFO): Handler Thread ID is 3502
2020-10-13T19:33:10.526Z,1602617590.526 [controlThread](DEBUG): Initializing ControlThread
2020-10-13T19:33:10.527Z,1602617590.527 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-10-13T19:33:10.529Z,1602617590.529 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-10-13T19:33:10.529Z,1602617590.529 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-10-13T19:33:10.530Z,1602617590.530 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-10-13T19:33:10.532Z,1602617590.532 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-10-13T19:33:10.532Z,1602617590.532 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-10-13T19:33:10.539Z,1602617590.539 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-10-13T19:33:10.539Z,1602617590.539 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-10-13T19:33:10.539Z,1602617590.539 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-10-13T19:33:10.540Z,1602617590.540 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-10-13T19:33:10.540Z,1602617590.540 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-10-13T19:33:10.541Z,1602617590.541 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-10-13T19:33:10.545Z,1602617590.545 [SBIT](INFO): Initialize SBIT Component.
2020-10-13T19:33:10.546Z,1602617590.546 [SBIT](IMPORTANT): git: 2020-08-31-8-g854e668
2020-10-13T19:33:10.546Z,1602617590.546 [SBIT](INFO): git hash: 854e6687b208db53e020f4ee03a001432cc94599
2020-10-13T19:33:10.546Z,1602617590.546 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-10-13T19:33:10.547Z,1602617590.547 [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
2020-10-13T19:33:10.548Z,1602617590.548 [SBIT](INFO): Beginning SBIT in 68.000000 seconds.
2020-10-13T19:33:10.549Z,1602617590.549 [IBIT](INFO): Initialize IBIT Component.
2020-10-13T19:33:10.550Z,1602617590.550 [CBIT](DEBUG): Initialize CBIT Component.
2020-10-13T19:33:10.551Z,1602617590.551 [logger ThreadHandler](INFO): Handler Thread ID is 3503
2020-10-13T19:33:10.561Z,1602617590.561 [CBIT](DEBUG): Initialized mux pins.
2020-10-13T19:33:10.561Z,1602617590.561 [CBIT](DEBUG): Initializing the watchdog timer.
2020-10-13T19:33:10.569Z,1602617590.569 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3504
2020-10-13T19:33:10.570Z,1602617590.570 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-10-13T19:33:10.581Z,1602617590.581 [Onboard ThreadHandler](INFO): Handler Thread ID is 3505
2020-10-13T19:33:10.585Z,1602617590.585 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-10-13T19:33:10.586Z,1602617590.586 [CBIT](DEBUG): Initializing heartbeat.
2020-10-13T19:33:10.606Z,1602617590.606 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3506
2020-10-13T19:33:10.617Z,1602617590.617 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3507
2020-10-13T19:33:10.618Z,1602617590.618 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-10-13T19:33:10.622Z,1602617590.622 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3509
2020-10-13T19:33:10.623Z,1602617590.623 [WetLabsBB2FL](INFO): Powering down
2020-10-13T19:33:10.645Z,1602617590.645 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3510
2020-10-13T19:33:10.648Z,1602617590.648 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-10-13T19:33:10.649Z,1602617590.649 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-10-13T19:33:10.649Z,1602617590.649 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-10-13T19:33:10.649Z,1602617590.649 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-10-13T19:33:10.649Z,1602617590.649 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-10-13T19:33:10.649Z,1602617590.649 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-10-13T19:33:10.649Z,1602617590.649 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-10-13T19:33:10.650Z,1602617590.650 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-10-13T19:33:10.650Z,1602617590.650 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-10-13T19:33:10.650Z,1602617590.650 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-10-13T19:33:10.650Z,1602617590.650 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-10-13T19:33:10.650Z,1602617590.650 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-10-13T19:33:10.650Z,1602617590.650 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-10-13T19:33:10.651Z,1602617590.651 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-10-13T19:33:10.651Z,1602617590.651 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-10-13T19:33:10.651Z,1602617590.651 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-10-13T19:33:10.657Z,1602617590.657 [CBIT](DEBUG): Deactivating GF circuits.
2020-10-13T19:33:10.657Z,1602617590.657 [CBIT](DEBUG): Deactivating emergency mode.
2020-10-13T19:33:10.693Z,1602617590.693 [CBIT](DEBUG): Backplane powered.
2020-10-13T19:33:10.694Z,1602617590.694 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-10-13T19:33:10.704Z,1602617590.704 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-10-13T19:33:10.714Z,1602617590.714 [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-10-13T19:33:10.715Z,1602617590.715 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-10-13T19:33:10.783Z,1602617590.783 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-10-13T19:33:10.784Z,1602617590.784 [Default:A.Wait](DEBUG): Construct Wait.
2020-10-13T19:33:10.802Z,1602617590.802 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-10-13T19:33:10.828Z,1602617590.828 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-10-13T19:33:10.831Z,1602617590.831 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-10-13T19:33:10.852Z,1602617590.852 [Default:E.Execute](DEBUG): Construct Execute.
2020-10-13T19:33:10.855Z,1602617590.855 [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-10-13T19:33:10.864Z,1602617590.864 [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,
2020-10-13T19:33:10.877Z,1602617590.877 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-10-13T19:33:11.013Z,1602617591.013 [Radio_Surface](INFO): Powering up
2020-10-13T19:33:11.033Z,1602617591.033 [AMEcho](INFO): Powering up
2020-10-13T19:33:11.053Z,1602617591.053 [CANONSampler](INFO): Powering down
2020-10-13T19:33:11.223Z,1602617591.223 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-10-13T19:33:11.229Z,1602617591.229 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-10-13T19:33:11.230Z,1602617591.230 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-10-13T19:33:11.241Z,1602617591.241 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-10-13T19:33:11.242Z,1602617591.242 [MassServo](DEBUG): Initializing EZServoServo.
2020-10-13T19:33:11.249Z,1602617591.249 [MassServo](DEBUG): Initializing MassServo.
2020-10-13T19:33:11.250Z,1602617591.250 [RudderServo](DEBUG): Initializing EZServoServo.
2020-10-13T19:33:11.257Z,1602617591.257 [RudderServo](DEBUG): Initializing RudderServo.
2020-10-13T19:33:11.258Z,1602617591.258 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-10-13T19:33:11.265Z,1602617591.265 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-10-13T19:33:11.489Z,1602617591.489 [AMEcho](INFO): Powering down
2020-10-13T19:33:14.217Z,1602617594.217 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:14.621Z,1602617594.621 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:15.033Z,1602617595.033 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:15.131Z,1602617595.131 [ThrusterServo](INFO): Sent FORWARD direction
2020-10-13T19:33:15.437Z,1602617595.437 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:16.045Z,1602617596.045 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:16.485Z,1602617596.485 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:16.949Z,1602617596.949 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:17.509Z,1602617597.509 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:17.973Z,1602617597.973 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:18.513Z,1602617598.513 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:18.933Z,1602617598.933 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:19.345Z,1602617599.345 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:19.769Z,1602617599.769 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:20.169Z,1602617600.169 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:20.593Z,1602617600.593 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:21.025Z,1602617601.025 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:21.449Z,1602617601.449 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:21.869Z,1602617601.869 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:22.297Z,1602617602.297 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:22.701Z,1602617602.701 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:23.087Z,1602617603.087 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004743
2020-10-13T19:33:23.105Z,1602617603.105 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:23.509Z,1602617603.509 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:23.669Z,1602617603.669 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2020-10-13T19:33:23.669Z,1602617603.669 [CTD_Seabird] Communications Fault, FailCount= 1
2020-10-13T19:33:23.669Z,1602617603.669 [CTD_Seabird](ERROR): Communications Fault
2020-10-13T19:33:23.937Z,1602617603.937 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:23.937Z,1602617603.937 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-10-13T19:33:23.937Z,1602617603.937 [AHRS_M2] Communications Fault, FailCount= 1
2020-10-13T19:33:23.937Z,1602617603.937 [AHRS_M2](ERROR): Communications Fault
2020-10-13T19:33:24.013Z,1602617604.013 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-10-13T19:33:24.013Z,1602617604.013 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2020-10-13T19:33:24.019Z,1602617604.019 [AHRS_M2](INFO): Powering down
2020-10-13T19:33:24.073Z,1602617604.073 [CTD_Seabird](INFO): Powering down
2020-10-13T19:33:24.873Z,1602617604.873 [CBIT](INFO): Clearing failed state for component AHRS_M2
2020-10-13T19:33:24.873Z,1602617604.873 [AHRS_M2] No Fault, FailCount= 1
2020-10-13T19:33:25.229Z,1602617605.229 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-10-13T19:33:25.449Z,1602617605.449 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2020-10-13T19:33:25.449Z,1602617605.449 [CTD_Seabird] No Fault, FailCount= 1
2020-10-13T19:33:25.817Z,1602617605.817 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-10-13T19:33:28.785Z,1602617608.785 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:29.189Z,1602617609.189 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:29.601Z,1602617609.601 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:30.005Z,1602617610.005 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:30.437Z,1602617610.437 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:30.853Z,1602617610.853 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:31.261Z,1602617611.261 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:31.657Z,1602617611.657 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:32.069Z,1602617612.069 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:32.485Z,1602617612.485 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:32.913Z,1602617612.913 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:33.321Z,1602617613.321 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:33.709Z,1602617613.709 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:34.125Z,1602617614.125 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:34.517Z,1602617614.517 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:34.921Z,1602617614.921 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:35.337Z,1602617615.337 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:35.737Z,1602617615.737 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:36.157Z,1602617616.157 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:36.561Z,1602617616.561 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:36.969Z,1602617616.969 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:37.365Z,1602617617.365 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:37.773Z,1602617617.773 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:37.778Z,1602617617.778 [NAL9602](INFO): Powering up NAL9602
2020-10-13T19:33:37.953Z,1602617617.953 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:38.585Z,1602617618.585 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:38.585Z,1602617618.585 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-10-13T19:33:38.585Z,1602617618.585 [AHRS_M2] Communications Fault, FailCount= 2
2020-10-13T19:33:38.585Z,1602617618.585 [AHRS_M2](ERROR): Communications Fault
2020-10-13T19:33:38.661Z,1602617618.661 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-10-13T19:33:38.674Z,1602617618.674 [AHRS_M2](INFO): Powering down
2020-10-13T19:33:38.877Z,1602617618.877 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2020-10-13T19:33:38.877Z,1602617618.877 [CTD_Seabird] Communications Fault, FailCount= 2
2020-10-13T19:33:38.877Z,1602617618.877 [CTD_Seabird](ERROR): Communications Fault
2020-10-13T19:33:39.161Z,1602617619.161 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2020-10-13T19:33:39.281Z,1602617619.281 [CTD_Seabird](INFO): Powering down
2020-10-13T19:33:39.541Z,1602617619.541 [CBIT](INFO): Clearing failed state for component AHRS_M2
2020-10-13T19:33:39.541Z,1602617619.541 [AHRS_M2] No Fault, FailCount= 2
2020-10-13T19:33:39.883Z,1602617619.883 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-10-13T19:33:40.373Z,1602617620.373 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2020-10-13T19:33:40.373Z,1602617620.373 [CTD_Seabird] No Fault, FailCount= 2
2020-10-13T19:33:40.625Z,1602617620.625 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-10-13T19:33:43.429Z,1602617623.429 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:43.937Z,1602617623.937 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:44.341Z,1602617624.341 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:44.749Z,1602617624.749 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:45.169Z,1602617625.169 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:45.593Z,1602617625.593 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:46.009Z,1602617626.009 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:46.441Z,1602617626.441 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:46.885Z,1602617626.885 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:47.269Z,1602617627.269 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:47.697Z,1602617627.697 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:48.133Z,1602617628.133 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:48.557Z,1602617628.557 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:48.981Z,1602617628.981 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:48.987Z,1602617628.987 [NAL9602](INFO): NAL9602 initialized
2020-10-13T19:33:49.389Z,1602617629.389 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:49.809Z,1602617629.809 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:50.225Z,1602617630.225 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:50.621Z,1602617630.621 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:51.025Z,1602617631.025 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:51.425Z,1602617631.425 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:51.829Z,1602617631.829 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:52.245Z,1602617632.245 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:52.637Z,1602617632.637 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:53.049Z,1602617633.049 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:53.049Z,1602617633.049 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-10-13T19:33:53.049Z,1602617633.049 [AHRS_M2] Communications Fault, FailCount= 3
2020-10-13T19:33:53.049Z,1602617633.049 [AHRS_M2](ERROR): Communications Fault
2020-10-13T19:33:53.104Z,1602617633.104 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-10-13T19:33:53.137Z,1602617633.137 [AHRS_M2](INFO): Powering down
2020-10-13T19:33:53.685Z,1602617633.685 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2020-10-13T19:33:53.685Z,1602617633.685 [CTD_Seabird] Communications Fault, FailCount= 3
2020-10-13T19:33:53.685Z,1602617633.685 [CTD_Seabird](ERROR): Communications Fault
2020-10-13T19:33:53.960Z,1602617633.960 [CBIT](INFO): Clearing failed state for component AHRS_M2
2020-10-13T19:33:53.961Z,1602617633.961 [AHRS_M2] No Fault, FailCount= 3
2020-10-13T19:33:53.961Z,1602617633.961 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2020-10-13T19:33:53.962Z,1602617633.962 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2020-10-13T19:33:54.089Z,1602617634.089 [CTD_Seabird](INFO): Powering down
2020-10-13T19:33:54.345Z,1602617634.345 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-10-13T19:33:57.901Z,1602617637.901 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:58.317Z,1602617638.317 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:58.717Z,1602617638.717 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:59.113Z,1602617639.113 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:59.529Z,1602617639.529 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:33:59.929Z,1602617639.929 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:34:00.333Z,1602617640.333 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:34:00.737Z,1602617640.737 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:34:01.145Z,1602617641.145 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:34:01.537Z,1602617641.537 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:34:01.937Z,1602617641.937 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:34:02.349Z,1602617642.349 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:34:02.749Z,1602617642.749 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:34:03.169Z,1602617643.169 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:34:03.561Z,1602617643.561 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:34:03.965Z,1602617643.965 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:34:04.377Z,1602617644.377 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:34:04.789Z,1602617644.789 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:34:05.189Z,1602617645.189 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:34:05.593Z,1602617645.593 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:34:06.021Z,1602617646.021 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:34:06.409Z,1602617646.409 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:34:06.825Z,1602617646.825 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:34:07.237Z,1602617647.237 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:34:07.237Z,1602617647.237 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-10-13T19:34:07.237Z,1602617647.237 [AHRS_M2] Communications Fault, FailCount= 4
2020-10-13T19:34:07.237Z,1602617647.237 [AHRS_M2](ERROR): Communications Fault
2020-10-13T19:34:07.332Z,1602617647.332 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-10-13T19:34:07.353Z,1602617647.353 [AHRS_M2](INFO): Powering down
2020-10-13T19:34:08.212Z,1602617648.212 [CBIT](INFO): Clearing failed state for component AHRS_M2
2020-10-13T19:34:08.212Z,1602617648.212 [AHRS_M2] No Fault, FailCount= 4
2020-10-13T19:34:08.552Z,1602617648.552 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-10-13T19:34:12.109Z,1602617652.109 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:34:12.505Z,1602617652.505 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:34:12.917Z,1602617652.917 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:34:13.329Z,1602617653.329 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:34:13.737Z,1602617653.737 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:34:14.137Z,1602617654.137 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:34:14.541Z,1602617654.541 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:34:14.933Z,1602617654.933 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:34:15.353Z,1602617655.353 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:34:15.749Z,1602617655.749 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:34:16.145Z,1602617656.145 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:34:16.561Z,1602617656.561 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:34:16.969Z,1602617656.969 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:34:17.361Z,1602617657.361 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:34:17.773Z,1602617657.773 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:34:18.181Z,1602617658.181 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:34:18.585Z,1602617658.585 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:34:18.997Z,1602617658.997 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:34:19.053Z,1602617659.053 [SBIT](IMPORTANT): Beginning Startup BIT
2020-10-13T19:34:19.057Z,1602617659.057 [CBIT](IMPORTANT): Beginning ground fault scan
2020-10-13T19:34:19.413Z,1602617659.413 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout
2020-10-13T19:34:20.853Z,1602617660.853 [AHRS_M2](ERROR): Clear channel UART error: serial timeout
2020-10-13T19:34:20.949Z,1602617660.949 [AHRS_M2](ERROR): Subscribe Pitch UART error: serial timeout
2020-10-13T19:34:21.257Z,1602617661.257 [AHRS_M2](ERROR): Clear channel UART error: serial timeout
2020-10-13T19:34:21.357Z,1602617661.357 [AHRS_M2](ERROR): Subscribe Pitch UART error: serial timeout
2020-10-13T19:34:21.661Z,1602617661.661 [AHRS_M2](ERROR): Clear channel UART error: serial timeout
2020-10-13T19:34:21.753Z,1602617661.753 [AHRS_M2](ERROR): Subscribe Pitch UART error: serial timeout
2020-10-13T19:34:21.753Z,1602617661.753 [AHRS_M2](FAULT): Failed to initialize within timeout.
2020-10-13T19:34:21.753Z,1602617661.753 [AHRS_M2] Communications Fault, FailCount= 5
2020-10-13T19:34:21.753Z,1602617661.753 [AHRS_M2](ERROR): Communications Fault
2020-10-13T19:34:21.845Z,1602617661.845 [CBIT](ERROR): Communications Fault in component: AHRS_M2
2020-10-13T19:34:21.846Z,1602617661.846 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2
2020-10-13T19:34:21.969Z,1602617661.969 [AHRS_M2](INFO): Powering down
2020-10-13T19:34:22.139Z,1602617662.139 [CommandLine](FAULT): Scheduling is paused
2020-10-13T19:34:22.140Z,1602617662.140 [CBIT](INFO): Critical error at 20201013T193421
2020-10-13T19:34:22.140Z,1602617662.140 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-10-13T19:34:30.090Z,1602617670.090 [CBIT](IMPORTANT): Ground fault detected
mA:
CHAN A0 (Batt): 0.018338
CHAN A1 (24V): -0.180615
CHAN A2 (12V): -0.002366
CHAN A3 (5V): -0.001159
CHAN B0 (3.3V): -0.000243
CHAN B1 (3.15aV): 0.000139
CHAN B2 (3.15bV): -0.000829
CHAN B3 (GND): 0.000238
OPEN: -0.000465
Full Scale Calc: 4.765 mA, -1.589 mA
2020-10-13T19:34:59.327Z,1602617699.327 [CommandLine](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 150.000000 revolution_per_minute
2020-10-13T19:34:59.328Z,1602617699.328 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.propOmegaAction" handled in the control thread.
2020-10-13T19:34:59.600Z,1602617699.600 [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_SpeedControl.propOmegaAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-10-13T19:35:01.329Z,1602617701.329 [ThrusterServo](INFO): CHECKING
2020-10-13T19:35:01.329Z,1602617701.329 [ThrusterServo](FAULT): Thruster reporting ready to receive command while a command should still be executing. Uninitializing and Re-Initializing.

2020-10-13T19:35:01.329Z,1602617701.329 [ThrusterServo] Hardware Fault, FailCount= 1
2020-10-13T19:35:01.329Z,1602617701.329 [ThrusterServo](ERROR): Hardware Fault
2020-10-13T19:35:01.333Z,1602617701.333 [CBIT](ERROR): Hardware Fault in component: ThrusterServo
2020-10-13T19:35:01.677Z,1602617701.677 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-10-13T19:35:01.678Z,1602617701.678 [ThrusterServo](INFO): Powering down
2020-10-13T19:35:02.485Z,1602617702.485 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-10-13T19:35:02.606Z,1602617702.606 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-10-13T19:35:02.610Z,1602617702.610 [CBIT](INFO): Clearing failed state for component ThrusterServo
2020-10-13T19:35:02.610Z,1602617702.610 [ThrusterServo] No Fault, FailCount= 1
2020-10-13T19:35:05.659Z,1602617705.659 [ThrusterServo](INFO): Sent FORWARD direction
2020-10-13T19:35:06.081Z,1602617706.081 [ThrusterServo](INFO): CHECKING
2020-10-13T19:35:06.082Z,1602617706.082 [ThrusterServo](INFO): Actual15.705328 controller:2621.000000 motor:2621.439697
2020-10-13T19:35:06.493Z,1602617706.493 [ThrusterServo](INFO): CHECKING
2020-10-13T19:35:06.494Z,1602617706.494 [ThrusterServo](INFO): Actual15.705328 controller:2621.000000 motor:2621.439697
2020-10-13T19:35:06.897Z,1602617706.897 [ThrusterServo](INFO): CHECKING
2020-10-13T19:35:06.898Z,1602617706.898 [ThrusterServo](INFO): Actual15.705328 controller:2621.000000 motor:2621.439697
2020-10-13T19:35:07.305Z,1602617707.305 [ThrusterServo](INFO): CHECKING
2020-10-13T19:35:07.306Z,1602617707.306 [ThrusterServo](INFO): Actual15.705328 controller:2621.000000 motor:2621.439697
2020-10-13T19:35:07.701Z,1602617707.701 [ThrusterServo](INFO): CHECKING
2020-10-13T19:35:07.702Z,1602617707.702 [ThrusterServo](INFO): Actual15.705328 controller:2621.000000 motor:2621.439697
2020-10-13T19:35:08.109Z,1602617708.109 [ThrusterServo](INFO): CHECKING
2020-10-13T19:35:08.110Z,1602617708.110 [ThrusterServo](INFO): Actual15.705328 controller:2621.000000 motor:2621.439697
2020-10-13T19:35:08.234Z,1602617708.234 [CommandLine](IMPORTANT): got command maintain clear
2020-10-13T19:35:08.517Z,1602617708.517 [ThrusterServo](INFO): CHECKING
2020-10-13T19:35:08.518Z,1602617708.518 [ThrusterServo](INFO): Actual15.705328 controller:2621.000000 motor:2621.439697
2020-10-13T19:35:08.527Z,1602617708.527 [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,
2020-10-13T19:35:08.921Z,1602617708.921 [ThrusterServo](INFO): CHECKING
2020-10-13T19:35:08.922Z,1602617708.922 [ThrusterServo](INFO): Actual15.705328 controller:2621.000000 motor:0.000000
2020-10-13T19:35:10.872Z,1602617710.872 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2020-10-13T19:35:10.872Z,1602617710.872 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-10-13T19:35:10.873Z,1602617710.873 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-13T19:35:11.013Z,1602617711.013 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-13T19:35:11.390Z,1602617711.390 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-13T19:35:11.390Z,1602617711.390 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-10-13T19:35:12.923Z,1602617712.923 [SBIT](IMPORTANT): SBIT PASSED
2020-10-13T19:35:12.923Z,1602617712.923 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-10-13T19:35:12.924Z,1602617712.924 [SBIT](IMPORTANT): AMEcho.enabled=1 bool;

2020-10-13T19:35:12.924Z,1602617712.924 [SBIT](IMPORTANT): ElevatorOffsetCalculator.loadAtStartup=1 bool;

2020-10-13T19:35:12.924Z,1602617712.924 [SBIT](IMPORTANT): Express linearApproximation AMEcho.altitude 1.000000 meter;

2020-10-13T19:35:12.924Z,1602617712.924 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 1.000000 meter;

2020-10-13T19:35:12.924Z,1602617712.924 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=315.128269 cubic_centimeter;

2020-10-13T19:35:12.925Z,1602617712.925 [SBIT](IMPORTANT): VerticalControl.massDefault=-12.025033 millimeter;

2020-10-13T19:35:13.301Z,1602617713.301 [MissionManager](IMPORTANT): Started mission Startup
2020-10-13T19:35:13.301Z,1602617713.301 [Startup] Running Loop=1
2020-10-13T19:35:13.301Z,1602617713.301 [Startup](DEBUG): Aggregate::initialize Startup
2020-10-13T19:35:13.302Z,1602617713.302 [Startup:A.GoToSurface] Running Loop=1
2020-10-13T19:35:13.302Z,1602617713.302 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-10-13T19:35:13.302Z,1602617713.302 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-10-13T19:35:13.303Z,1602617713.303 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-10-13T19:35:13.303Z,1602617713.303 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-10-13T19:35:13.303Z,1602617713.303 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-10-13T19:35:13.304Z,1602617713.304 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-10-13T19:35:13.305Z,1602617713.305 [Startup:StartupSatComms] Running Loop=1
2020-10-13T19:35:13.305Z,1602617713.305 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-10-13T19:35:13.305Z,1602617713.305 [Startup:StartupSatComms:A] Running Loop=1
2020-10-13T19:35:13.698Z,1602617713.698 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-10-13T19:35:14.096Z,1602617714.096 [AMEcho](INFO): Powering up
2020-10-13T19:35:17.031Z,1602617717.031 [CommandLine](IMPORTANT): got command maintain control SpeedControl.propOmegaAction -150.000000 revolution_per_minute
2020-10-13T19:35:17.032Z,1602617717.032 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.propOmegaAction" handled in the control thread.
2020-10-13T19:35:17.378Z,1602617717.378 [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_SpeedControl.propOmegaAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-10-13T19:35:18.949Z,1602617718.949 [ThrusterServo](INFO): POWER CYCLE
2020-10-13T19:35:20.594Z,1602617720.594 [ThrusterServo](INFO): Sent REVERSE direction2621
2020-10-13T19:35:21.013Z,1602617721.013 [ThrusterServo](INFO): CHECKING
2020-10-13T19:35:21.013Z,1602617721.013 [ThrusterServo](FAULT): Thruster reporting ready to receive command while a command should still be executing. Uninitializing and Re-Initializing.

2020-10-13T19:35:21.013Z,1602617721.013 [ThrusterServo] Hardware Fault, FailCount= 2
2020-10-13T19:35:21.013Z,1602617721.013 [ThrusterServo](ERROR): Hardware Fault
2020-10-13T19:35:21.017Z,1602617721.017 [CBIT](ERROR): Hardware Fault in component: ThrusterServo
2020-10-13T19:35:21.384Z,1602617721.384 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-10-13T19:35:21.384Z,1602617721.384 [ThrusterServo](INFO): Powering down
2020-10-13T19:35:22.184Z,1602617722.184 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-10-13T19:35:22.298Z,1602617722.298 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-10-13T19:35:22.302Z,1602617722.302 [CBIT](INFO): Clearing failed state for component ThrusterServo
2020-10-13T19:35:22.302Z,1602617722.302 [ThrusterServo] No Fault, FailCount= 2
2020-10-13T19:35:25.458Z,1602617725.458 [ThrusterServo](INFO): Sent REVERSE direction2621
2020-10-13T19:35:25.889Z,1602617725.889 [ThrusterServo](INFO): CHECKING
2020-10-13T19:35:25.889Z,1602617725.889 [ThrusterServo](FAULT): Thruster reporting ready to receive command while a command should still be executing. Uninitializing and Re-Initializing.

2020-10-13T19:35:25.889Z,1602617725.889 [ThrusterServo] Hardware Fault, FailCount= 3
2020-10-13T19:35:25.889Z,1602617725.889 [ThrusterServo](ERROR): Hardware Fault
2020-10-13T19:35:25.923Z,1602617725.923 [CBIT](ERROR): Hardware Fault in component: ThrusterServo
2020-10-13T19:35:26.256Z,1602617726.256 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-10-13T19:35:26.256Z,1602617726.256 [ThrusterServo](INFO): Powering down
2020-10-13T19:35:27.036Z,1602617727.036 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-10-13T19:35:27.150Z,1602617727.150 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-10-13T19:35:27.153Z,1602617727.153 [CBIT](INFO): Clearing failed state for component ThrusterServo
2020-10-13T19:35:27.153Z,1602617727.153 [ThrusterServo] No Fault, FailCount= 3
2020-10-13T19:35:27.424Z,1602617727.424 [AMEcho](ERROR): No EchoSounder communication! Re-initializing
2020-10-13T19:35:27.424Z,1602617727.424 [AMEcho] Communications Fault, FailCount= 1
2020-10-13T19:35:27.424Z,1602617727.424 [AMEcho](ERROR): Communications Fault
2020-10-13T19:35:27.440Z,1602617727.440 [CBIT](ERROR): Communications Fault in component: AMEcho
2020-10-13T19:35:27.993Z,1602617727.993 [AMEcho](INFO): Powering down
2020-10-13T19:35:28.655Z,1602617728.655 [CBIT](INFO): Clearing failed state for component AMEcho
2020-10-13T19:35:28.655Z,1602617728.655 [AMEcho] No Fault, FailCount= 1
2020-10-13T19:35:29.041Z,1602617729.041 [AMEcho](INFO): Powering up
2020-10-13T19:35:30.684Z,1602617730.684 [ThrusterServo](INFO): Sent REVERSE direction2621
2020-10-13T19:35:31.113Z,1602617731.113 [ThrusterServo](INFO): CHECKING
2020-10-13T19:35:31.113Z,1602617731.113 [ThrusterServo](FAULT): Thruster reporting ready to receive command while a command should still be executing. Uninitializing and Re-Initializing.

2020-10-13T19:35:31.113Z,1602617731.113 [ThrusterServo] Hardware Fault, FailCount= 4
2020-10-13T19:35:31.113Z,1602617731.113 [ThrusterServo](ERROR): Hardware Fault
2020-10-13T19:35:31.116Z,1602617731.116 [CBIT](ERROR): Hardware Fault in component: ThrusterServo
2020-10-13T19:35:31.508Z,1602617731.508 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-10-13T19:35:31.508Z,1602617731.508 [ThrusterServo](INFO): Powering down
2020-10-13T19:35:32.288Z,1602617732.288 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-10-13T19:35:32.406Z,1602617732.406 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-10-13T19:35:32.409Z,1602617732.409 [CBIT](INFO): Clearing failed state for component ThrusterServo
2020-10-13T19:35:32.409Z,1602617732.409 [ThrusterServo] No Fault, FailCount= 4
2020-10-13T19:35:35.533Z,1602617735.533 [ThrusterServo](INFO): Sent REVERSE direction2621
2020-10-13T19:35:35.669Z,1602617735.669 [CommandLine](IMPORTANT): got command maintain clear
2020-10-13T19:35:35.973Z,1602617735.973 [ThrusterServo](INFO): CHECKING
2020-10-13T19:35:35.973Z,1602617735.973 [ThrusterServo](FAULT): Thruster reporting ready to receive command while a command should still be executing. Uninitializing and Re-Initializing.

2020-10-13T19:35:35.973Z,1602617735.973 [ThrusterServo] Hardware Fault, FailCount= 5
2020-10-13T19:35:35.973Z,1602617735.973 [ThrusterServo](ERROR): Hardware Fault
2020-10-13T19:35:36.003Z,1602617736.003 [CBIT](ERROR): Hardware Fault in component: ThrusterServo
2020-10-13T19:35:36.003Z,1602617736.003 [CBIT](CRITICAL): Hardware Fault in component: ThrusterServo
2020-10-13T19:35:36.010Z,1602617736.010 [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,
2020-10-13T19:35:36.356Z,1602617736.356 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-10-13T19:35:36.356Z,1602617736.356 [ThrusterServo](INFO): Powering down
2020-10-13T19:35:36.358Z,1602617736.358 [CBIT](INFO): Critical error at 20201013T193536
2020-10-13T19:35:41.695Z,1602617741.695 [CommandLine](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 150.000000 revolution_per_minute
2020-10-13T19:35:41.696Z,1602617741.696 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.propOmegaAction" handled in the control thread.
2020-10-13T19:35:42.007Z,1602617742.007 [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_SpeedControl.propOmegaAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-10-13T19:35:42.376Z,1602617742.376 [AMEcho](ERROR): No EchoSounder communication! Re-initializing
2020-10-13T19:35:42.376Z,1602617742.376 [AMEcho] Communications Fault, FailCount= 2
2020-10-13T19:35:42.376Z,1602617742.376 [AMEcho](ERROR): Communications Fault
2020-10-13T19:35:42.419Z,1602617742.419 [CBIT](ERROR): Communications Fault in component: AMEcho
2020-10-13T19:35:42.525Z,1602617742.525 [CommandLine](IMPORTANT): got command failComponent
2020-10-13T19:35:42.526Z,1602617742.526 [CommandLine](IMPORTANT): Failed components:
2020-10-13T19:35:42.526Z,1602617742.526 [CommandLine](IMPORTANT): ThrusterServo: Hardware Fault
2020-10-13T19:35:42.526Z,1602617742.526 [CommandLine](IMPORTANT): AHRS_M2: Communications Fault
2020-10-13T19:35:42.526Z,1602617742.526 [CommandLine](IMPORTANT): AMEcho: Communications Fault
2020-10-13T19:35:42.527Z,1602617742.527 [CommandLine](IMPORTANT): CTD_Seabird: Communications Fault
2020-10-13T19:35:42.941Z,1602617742.941 [AMEcho](INFO): Powering down
2020-10-13T19:35:43.601Z,1602617743.601 [CBIT](INFO): Clearing failed state for component AMEcho
2020-10-13T19:35:43.601Z,1602617743.601 [AMEcho] No Fault, FailCount= 2
2020-10-13T19:35:43.987Z,1602617743.987 [AMEcho](INFO): Powering up
2020-10-13T19:35:45.790Z,1602617745.790 [CommandLine](IMPORTANT): got command failComponent none ThrusterServo
2020-10-13T19:35:45.790Z,1602617745.790 [ThrusterServo] No Fault, FailCount= 5
2020-10-13T19:35:45.790Z,1602617745.790 [CommandLine](IMPORTANT): ThrusterServo failureMode is No Fault
2020-10-13T19:35:46.060Z,1602617746.060 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-10-13T19:35:46.073Z,1602617746.073 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-10-13T19:35:49.256Z,1602617749.256 [ThrusterServo](INFO): POWER CYCLE
2020-10-13T19:35:50.943Z,1602617750.943 [ThrusterServo](INFO): Sent FORWARD direction
2020-10-13T19:35:51.349Z,1602617751.349 [ThrusterServo](INFO): CHECKING
2020-10-13T19:35:51.350Z,1602617751.350 [ThrusterServo](INFO): Actual15.705328 controller:2621.000000 motor:2621.439697
2020-10-13T19:35:51.781Z,1602617751.781 [ThrusterServo](INFO): CHECKING
2020-10-13T19:35:51.782Z,1602617751.782 [ThrusterServo](INFO): Actual15.705328 controller:2621.000000 motor:2621.439697
2020-10-13T19:35:52.141Z,1602617752.141 [ThrusterServo](INFO): CHECKING
2020-10-13T19:35:52.142Z,1602617752.142 [ThrusterServo](INFO): Actual15.705328 controller:2621.000000 motor:2621.439697
2020-10-13T19:35:52.541Z,1602617752.541 [ThrusterServo](INFO): CHECKING
2020-10-13T19:35:52.542Z,1602617752.542 [ThrusterServo](INFO): Actual15.705328 controller:2621.000000 motor:2621.439697
2020-10-13T19:35:52.949Z,1602617752.949 [ThrusterServo](INFO): CHECKING
2020-10-13T19:35:52.950Z,1602617752.950 [ThrusterServo](INFO): Actual15.705328 controller:2621.000000 motor:2621.439697
2020-10-13T19:35:53.321Z,1602617753.321 [ThrusterServo](INFO): CHECKING
2020-10-13T19:35:53.322Z,1602617753.322 [ThrusterServo](INFO): Actual15.705328 controller:2621.000000 motor:2621.439697
2020-10-13T19:35:53.733Z,1602617753.733 [ThrusterServo](INFO): CHECKING
2020-10-13T19:35:53.734Z,1602617753.734 [ThrusterServo](INFO): Actual15.705328 controller:2621.000000 motor:2621.439697
2020-10-13T19:35:54.125Z,1602617754.125 [ThrusterServo](INFO): CHECKING
2020-10-13T19:35:54.126Z,1602617754.126 [ThrusterServo](INFO): Actual15.705328 controller:2621.000000 motor:2621.439697
2020-10-13T19:35:54.537Z,1602617754.537 [ThrusterServo](INFO): CHECKING
2020-10-13T19:35:54.538Z,1602617754.538 [ThrusterServo](INFO): Actual15.705328 controller:2621.000000 motor:2621.439697
2020-10-13T19:35:54.937Z,1602617754.937 [ThrusterServo](INFO): CHECKING
2020-10-13T19:35:54.938Z,1602617754.938 [ThrusterServo](INFO): Actual15.705328 controller:2621.000000 motor:2621.439697
2020-10-13T19:35:55.353Z,1602617755.353 [ThrusterServo](INFO): CHECKING
2020-10-13T19:35:55.354Z,1602617755.354 [ThrusterServo](INFO): Actual15.705328 controller:2621.000000 motor:2621.439697
2020-10-13T19:35:55.785Z,1602617755.785 [ThrusterServo](INFO): CHECKING
2020-10-13T19:35:55.786Z,1602617755.786 [ThrusterServo](INFO): Actual15.705328 controller:2621.000000 motor:2621.439697
2020-10-13T19:35:56.122Z,1602617756.122 [CommandLine](IMPORTANT): got command maintain clear
2020-10-13T19:35:56.157Z,1602617756.157 [ThrusterServo](INFO): CHECKING
2020-10-13T19:35:56.158Z,1602617756.158 [ThrusterServo](INFO): Actual15.705328 controller:2621.000000 motor:2621.439697
2020-10-13T19:35:56.179Z,1602617756.179 [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,
2020-10-13T19:35:56.580Z,1602617756.580 [ThrusterServo](INFO): CHECKING
2020-10-13T19:35:56.580Z,1602617756.580 [ThrusterServo](INFO): Actual15.705328 controller:2621.000000 motor:0.000000
2020-10-13T19:35:57.334Z,1602617757.334 [AMEcho](ERROR): No EchoSounder communication! Re-initializing
2020-10-13T19:35:57.334Z,1602617757.334 [AMEcho] Communications Fault, FailCount= 3
2020-10-13T19:35:57.334Z,1602617757.334 [AMEcho](ERROR): Communications Fault
2020-10-13T19:35:57.378Z,1602617757.378 [CBIT](ERROR): Communications Fault in component: AMEcho
2020-10-13T19:35:57.378Z,1602617757.378 [CBIT](CRITICAL): Communications Fault in component: AMEcho
2020-10-13T19:35:57.909Z,1602617757.909 [AMEcho](INFO): Powering down
2020-10-13T19:35:57.939Z,1602617757.939 [CBIT](INFO): Critical error at 20201013T193557
2020-10-13T19:36:09.167Z,1602617769.167 [CommandLine](IMPORTANT): got command maintain control SpeedControl.propOmegaAction -150.000000 revolution_per_minute
2020-10-13T19:36:09.168Z,1602617769.168 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.propOmegaAction" handled in the control thread.
2020-10-13T19:36:09.455Z,1602617769.455 [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_SpeedControl.propOmegaAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-10-13T19:36:11.072Z,1602617771.072 [ThrusterServo](INFO): POWER CYCLE
2020-10-13T19:36:12.723Z,1602617772.723 [ThrusterServo](INFO): Sent REVERSE direction2621
2020-10-13T19:36:13.139Z,1602617773.139 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:13.139Z,1602617773.139 [ThrusterServo](FAULT): Thruster reporting ready to receive command while a command should still be executing. Uninitializing and Re-Initializing.

2020-10-13T19:36:13.139Z,1602617773.139 [ThrusterServo] Hardware Fault, FailCount= 6
2020-10-13T19:36:13.139Z,1602617773.139 [ThrusterServo](ERROR): Hardware Fault
2020-10-13T19:36:13.143Z,1602617773.143 [CBIT](ERROR): Hardware Fault in component: ThrusterServo
2020-10-13T19:36:13.143Z,1602617773.143 [CBIT](CRITICAL): Hardware Fault in component: ThrusterServo
2020-10-13T19:36:13.506Z,1602617773.506 [Startup:StartupSatComms:A](INFO): Timed out from 2020-10-13T19:35:13.3Z
2020-10-13T19:36:13.506Z,1602617773.506 [Startup:StartupSatComms:A] Stopped
2020-10-13T19:36:13.506Z,1602617773.506 [Startup:StartupSatComms:B] Running Loop=1
2020-10-13T19:36:13.512Z,1602617773.512 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-10-13T19:36:13.512Z,1602617773.512 [ThrusterServo](INFO): Powering down
2020-10-13T19:36:13.514Z,1602617773.514 [CBIT](INFO): Critical error at 20201013T193613
2020-10-13T19:36:13.890Z,1602617773.890 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-10-13T19:36:14.411Z,1602617774.411 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-10-13T19:36:14.526Z,1602617774.526 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-10-13T19:36:18.702Z,1602617778.702 [CommandLine](IMPORTANT): got command maintain clear
2020-10-13T19:36:18.850Z,1602617778.850 [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,
2020-10-13T19:36:19.212Z,1602617779.212 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201013T190434/Courier0004.lzma
2020-10-13T19:36:20.215Z,1602617780.215 [DataOverHttps](INFO): Moved sent file to Logs/20201013T190434/Courier0004.lzma.bak
2020-10-13T19:36:20.215Z,1602617780.215 [DataOverHttps](INFO): SBD MOMSN=12891302
2020-10-13T19:36:22.149Z,1602617782.149 [CommandLine](IMPORTANT): got command failComponent none ThrusterServo
2020-10-13T19:36:22.150Z,1602617782.150 [ThrusterServo] No Fault, FailCount= 6
2020-10-13T19:36:22.150Z,1602617782.150 [CommandLine](IMPORTANT): ThrusterServo failureMode is No Fault
2020-10-13T19:36:23.723Z,1602617783.723 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2020-10-13T19:36:23.724Z,1602617783.724 [CTD_Seabird] No Fault, FailCount= 3
2020-10-13T19:36:23.749Z,1602617783.749 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-10-13T19:36:24.923Z,1602617784.923 [ThrusterServo](INFO): POWER CYCLE
2020-10-13T19:36:32.975Z,1602617792.975 [CommandLine](IMPORTANT): got command maintain control SpeedControl.propOmegaAction 300.000000 revolution_per_minute
2020-10-13T19:36:32.976Z,1602617792.976 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.propOmegaAction" handled in the control thread.
2020-10-13T19:36:33.021Z,1602617793.021 [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_SpeedControl.propOmegaAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-10-13T19:36:34.606Z,1602617794.606 [ThrusterServo](INFO): Sent FORWARD direction
2020-10-13T19:36:35.025Z,1602617795.025 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:35.026Z,1602617795.026 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:35.325Z,1602617795.325 [DataOverHttps](INFO): Sending 233 bytes from file Logs/20201013T190846/Courier0000.lzma
2020-10-13T19:36:35.437Z,1602617795.437 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:35.438Z,1602617795.438 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:35.829Z,1602617795.829 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:35.830Z,1602617795.830 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:36.245Z,1602617796.245 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:36.246Z,1602617796.246 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:36.327Z,1602617796.327 [DataOverHttps](INFO): Moved sent file to Logs/20201013T190846/Courier0000.lzma.bak
2020-10-13T19:36:36.327Z,1602617796.327 [DataOverHttps](INFO): SBD MOMSN=12891304
2020-10-13T19:36:36.645Z,1602617796.645 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:36.646Z,1602617796.646 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:37.053Z,1602617797.053 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:37.054Z,1602617797.054 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:37.488Z,1602617797.488 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:37.489Z,1602617797.489 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:37.805Z,1602617797.805 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2020-10-13T19:36:37.805Z,1602617797.805 [CTD_Seabird] Communications Fault, FailCount= 1
2020-10-13T19:36:37.805Z,1602617797.805 [CTD_Seabird](ERROR): Communications Fault
2020-10-13T19:36:37.905Z,1602617797.905 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:37.906Z,1602617797.906 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:37.910Z,1602617797.910 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2020-10-13T19:36:38.209Z,1602617798.209 [CTD_Seabird](INFO): Powering down
2020-10-13T19:36:38.269Z,1602617798.269 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:38.270Z,1602617798.270 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:38.673Z,1602617798.673 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:38.674Z,1602617798.674 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:39.082Z,1602617799.082 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:39.083Z,1602617799.083 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:39.520Z,1602617799.520 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:39.521Z,1602617799.521 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:39.525Z,1602617799.525 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2020-10-13T19:36:39.525Z,1602617799.525 [CTD_Seabird] No Fault, FailCount= 1
2020-10-13T19:36:39.549Z,1602617799.549 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-10-13T19:36:39.891Z,1602617799.891 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:39.892Z,1602617799.892 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:40.285Z,1602617800.285 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:40.286Z,1602617800.286 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:40.717Z,1602617800.717 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:40.718Z,1602617800.718 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:41.097Z,1602617801.097 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:41.098Z,1602617801.098 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:41.508Z,1602617801.508 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:41.509Z,1602617801.509 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:41.921Z,1602617801.921 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:41.922Z,1602617801.922 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:42.317Z,1602617802.317 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:42.318Z,1602617802.318 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:42.721Z,1602617802.721 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:42.722Z,1602617802.722 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:43.113Z,1602617803.113 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:43.114Z,1602617803.114 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:43.529Z,1602617803.529 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:43.530Z,1602617803.530 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:43.937Z,1602617803.937 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:43.938Z,1602617803.938 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:44.401Z,1602617804.401 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:44.402Z,1602617804.402 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:44.742Z,1602617804.742 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:44.742Z,1602617804.742 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:45.141Z,1602617805.141 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:45.142Z,1602617805.142 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:45.549Z,1602617805.549 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:45.550Z,1602617805.550 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:46.013Z,1602617806.013 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:46.014Z,1602617806.014 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:46.341Z,1602617806.341 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:46.342Z,1602617806.342 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:46.761Z,1602617806.761 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:46.762Z,1602617806.762 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:47.149Z,1602617807.149 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:47.150Z,1602617807.150 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:47.572Z,1602617807.572 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:47.572Z,1602617807.572 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:47.993Z,1602617807.993 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:47.994Z,1602617807.994 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:48.381Z,1602617808.381 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:48.382Z,1602617808.382 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:48.790Z,1602617808.790 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:48.790Z,1602617808.790 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:49.193Z,1602617809.193 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:49.194Z,1602617809.194 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:49.602Z,1602617809.602 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:49.603Z,1602617809.603 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:50.001Z,1602617810.001 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:50.002Z,1602617810.002 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:50.414Z,1602617810.414 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:50.415Z,1602617810.415 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:50.590Z,1602617810.590 [CommandLine](IMPORTANT): got command maintain clear
2020-10-13T19:36:50.837Z,1602617810.837 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:50.838Z,1602617810.838 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:5242.879395
2020-10-13T19:36:50.863Z,1602617810.863 [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,
2020-10-13T19:36:51.218Z,1602617811.218 [ThrusterServo](INFO): CHECKING
2020-10-13T19:36:51.219Z,1602617811.219 [ThrusterServo](INFO): Actual31.410656 controller:5242.000000 motor:0.000000
2020-10-13T19:36:52.293Z,1602617812.293 [DataOverHttps](INFO): Sending 255 bytes from file Logs/20201013T193259/Courier0000.lzma
2020-10-13T19:36:53.294Z,1602617813.294 [DataOverHttps](INFO): Moved sent file to Logs/20201013T193259/Courier0000.lzma.bak
2020-10-13T19:36:53.295Z,1602617813.295 [DataOverHttps](INFO): SBD MOMSN=12891309
2020-10-13T19:36:53.617Z,1602617813.617 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2020-10-13T19:36:53.617Z,1602617813.617 [CTD_Seabird] Communications Fault, FailCount= 2
2020-10-13T19:36:53.617Z,1602617813.617 [CTD_Seabird](ERROR): Communications Fault
2020-10-13T19:36:54.007Z,1602617814.007 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2020-10-13T19:36:54.046Z,1602617814.046 [CTD_Seabird](INFO): Powering down
2020-10-13T19:36:55.246Z,1602617815.246 [CBIT](INFO): Clearing failed state for component CTD_Seabird
2020-10-13T19:36:55.246Z,1602617815.246 [CTD_Seabird] No Fault, FailCount= 2
2020-10-13T19:36:55.389Z,1602617815.389 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-10-13T19:37:00.822Z,1602617820.822 [CommandLine](IMPORTANT): got command maintain control SpeedControl.propOmegaAction -300.000000 revolution_per_minute
2020-10-13T19:37:00.823Z,1602617820.823 [ComponentRegistry](DEBUG): SyncComponent "Maintain_SpeedControl.propOmegaAction" handled in the control thread.
2020-10-13T19:37:00.897Z,1602617820.897 [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_SpeedControl.propOmegaAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2020-10-13T19:37:02.472Z,1602617822.472 [ThrusterServo](INFO): POWER CYCLE
2020-10-13T19:37:04.082Z,1602617824.082 [ThrusterServo](INFO): Sent REVERSE direction5242
2020-10-13T19:37:04.549Z,1602617824.549 [ThrusterServo](INFO): CHECKING
2020-10-13T19:37:04.549Z,1602617824.549 [ThrusterServo](FAULT): Thruster reporting ready to receive command while a command should still be executing. Uninitializing and Re-Initializing.

2020-10-13T19:37:04.549Z,1602617824.549 [ThrusterServo] Hardware Fault, FailCount= 1
2020-10-13T19:37:04.549Z,1602617824.549 [ThrusterServo](ERROR): Hardware Fault
2020-10-13T19:37:04.553Z,1602617824.553 [CBIT](ERROR): Hardware Fault in component: ThrusterServo
2020-10-13T19:37:04.912Z,1602617824.912 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-10-13T19:37:04.912Z,1602617824.912 [ThrusterServo](INFO): Powering down
2020-10-13T19:37:05.724Z,1602617825.724 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-10-13T19:37:05.842Z,1602617825.842 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-10-13T19:37:05.845Z,1602617825.845 [CBIT](INFO): Clearing failed state for component ThrusterServo
2020-10-13T19:37:05.846Z,1602617825.846 [ThrusterServo] No Fault, FailCount= 1
2020-10-13T19:37:08.441Z,1602617828.441 [CTD_Seabird](FAULT): Failed to initialize within timeout.
2020-10-13T19:37:08.441Z,1602617828.441 [CTD_Seabird] Communications Fault, FailCount= 3
2020-10-13T19:37:08.441Z,1602617828.441 [CTD_Seabird](ERROR): Communications Fault
2020-10-13T19:37:08.555Z,1602617828.555 [CBIT](ERROR): Communications Fault in component: CTD_Seabird
2020-10-13T19:37:08.556Z,1602617828.556 [CBIT](FAULT): Communications Fault in component: CTD_Seabird
2020-10-13T19:37:08.845Z,1602617828.845 [CTD_Seabird](INFO): Powering down
2020-10-13T19:37:08.952Z,1602617828.952 [ThrusterServo](INFO): Sent REVERSE direction5242
2020-10-13T19:37:09.369Z,1602617829.369 [ThrusterServo](INFO): CHECKING
2020-10-13T19:37:09.369Z,1602617829.369 [ThrusterServo](FAULT): Thruster reporting ready to receive command while a command should still be executing. Uninitializing and Re-Initializing.

2020-10-13T19:37:09.369Z,1602617829.369 [ThrusterServo] Hardware Fault, FailCount= 2
2020-10-13T19:37:09.369Z,1602617829.369 [ThrusterServo](ERROR): Hardware Fault
2020-10-13T19:37:09.373Z,1602617829.373 [CBIT](ERROR): Hardware Fault in component: ThrusterServo
2020-10-13T19:37:09.766Z,1602617829.766 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-10-13T19:37:09.766Z,1602617829.766 [ThrusterServo](INFO): Powering down
2020-10-13T19:37:10.011Z,1602617830.011 [DataOverHttps](INFO): Sending 1576 bytes from file Logs/20201013T185158/Express0001.lzma
2020-10-13T19:37:10.588Z,1602617830.588 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-10-13T19:37:10.706Z,1602617830.706 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-10-13T19:37:10.709Z,1602617830.709 [CBIT](INFO): Clearing failed state for component ThrusterServo
2020-10-13T19:37:10.710Z,1602617830.710 [ThrusterServo] No Fault, FailCount= 2
2020-10-13T19:37:10.765Z,1602617830.765 [CommandLine](IMPORTANT): got command maintain clear
2020-10-13T19:37:11.010Z,1602617831.010 [DataOverHttps](INFO): Moved sent file to Logs/20201013T185158/Express0001.lzma.bak
2020-10-13T19:37:11.011Z,1602617831.011 [DataOverHttps](INFO): SBD MOMSN=12891316
2020-10-13T19:37:11.065Z,1602617831.065 [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,
2020-10-13T19:37:12.176Z,1602617832.176 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds.
2020-10-13T19:37:12.176Z,1602617832.176 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-10-13T19:37:12.176Z,1602617832.176 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-10-13T19:37:12.225Z,1602617832.225 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-10-13T19:37:12.586Z,1602617832.586 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-10-13T19:37:12.586Z,1602617832.586 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-10-13T19:37:13.170Z,1602617833.170 [CommandLine](IMPORTANT): got command quit
2020-10-13T19:37:13.781Z,1602617833.781 [Startup:StartupSatComms:B](INFO): Timed out from 2020-10-13T19:36:13.5Z
2020-10-13T19:37:13.781Z,1602617833.781 [Startup:StartupSatComms:B] Stopped
2020-10-13T19:37:13.781Z,1602617833.781 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-10-13T19:37:13.781Z,1602617833.781 [Startup:StartupSatComms] Stopped
2020-10-13T19:37:13.781Z,1602617833.781 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-10-13T19:37:13.782Z,1602617833.782 [Startup](INFO): Completed Startup
2020-10-13T19:37:13.782Z,1602617833.782 [MissionManager](INFO): Startup is completed.
2020-10-13T19:37:13.782Z,1602617833.782 [MissionManager](INFO): Uninitializing Mission Startup
2020-10-13T19:37:13.782Z,1602617833.782 [Startup] Stopped
2020-10-13T19:37:13.782Z,1602617833.782 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-10-13T19:37:13.782Z,1602617833.782 [Startup:A.GoToSurface] Stopped
2020-10-13T19:37:13.782Z,1602617833.782 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-10-13T19:37:13.789Z,1602617833.789 [ThrusterServo](INFO): POWER CYCLE
2020-10-13T19:37:14.177Z,1602617834.177 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-13T19:37:14.177Z,1602617834.177 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-10-13T19:37:14.194Z,1602617834.194 [MissionManager](IMPORTANT): Started mission Default
2020-10-13T19:37:14.194Z,1602617834.194 [Default] Running Loop=1
2020-10-13T19:37:14.195Z,1602617834.195 [Default](DEBUG): Aggregate::initialize Default
2020-10-13T19:37:14.195Z,1602617834.195 [Default:B.GoToSurface] Running Loop=1
2020-10-13T19:37:14.195Z,1602617834.195 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-10-13T19:37:14.195Z,1602617834.195 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-10-13T19:37:14.195Z,1602617834.195 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-10-13T19:37:14.195Z,1602617834.195 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-10-13T19:37:14.196Z,1602617834.196 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-10-13T19:37:14.196Z,1602617834.196 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-10-13T19:37:14.196Z,1602617834.196 [Default:A.Wait] Running Loop=1
2020-10-13T19:37:14.196Z,1602617834.196 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-10-13T19:37:14.369Z,1602617834.369 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-10-13T19:37:14.369Z,1602617834.369 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-10-13T19:37:14.370Z,1602617834.370 [CommandLine](INFO): Join timeout helper Thread ID is 3588
2020-10-13T19:37:14.370Z,1602617834.370 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-10-13T19:37:14.370Z,1602617834.370 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-10-13T19:37:14.371Z,1602617834.371 [NavChartDb](INFO): Join timeout helper Thread ID is 3589
2020-10-13T19:37:14.673Z,1602617834.673 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-13T19:37:14.673Z,1602617834.673 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-10-13T19:37:14.693Z,1602617834.693 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-10-13T19:37:14.693Z,1602617834.693 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-10-13T19:37:14.693Z,1602617834.693 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3590
2020-10-13T19:37:15.105Z,1602617835.105 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-13T19:37:15.105Z,1602617835.105 [WetLabsBB2FL](INFO): Powering down
2020-10-13T19:37:15.106Z,1602617835.106 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-10-13T19:37:15.121Z,1602617835.121 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-10-13T19:37:15.121Z,1602617835.121 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-10-13T19:37:15.121Z,1602617835.121 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3591
2020-10-13T19:37:15.441Z,1602617835.441 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-13T19:37:15.441Z,1602617835.441 [CTD_Seabird](INFO): Powering down
2020-10-13T19:37:15.453Z,1602617835.453 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-10-13T19:37:15.473Z,1602617835.473 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-10-13T19:37:15.473Z,1602617835.473 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-10-13T19:37:15.473Z,1602617835.473 [Radio_Surface](INFO): Join timeout helper Thread ID is 3592
2020-10-13T19:37:15.521Z,1602617835.521 [Radio_Surface](INFO): Powering down
2020-10-13T19:37:15.522Z,1602617835.522 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-13T19:37:15.522Z,1602617835.522 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-10-13T19:37:15.538Z,1602617835.538 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2020-10-13T19:37:15.538Z,1602617835.538 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-10-13T19:37:15.538Z,1602617835.538 [Onboard](INFO): Join timeout helper Thread ID is 3593
2020-10-13T19:37:15.777Z,1602617835.777 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-13T19:37:15.778Z,1602617835.778 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-10-13T19:37:15.786Z,1602617835.786 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-10-13T19:37:15.786Z,1602617835.786 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-10-13T19:37:15.786Z,1602617835.786 [DataOverHttps](INFO): Join timeout helper Thread ID is 3594
2020-10-13T19:37:18.291Z,1602617838.291 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-13T19:37:18.294Z,1602617838.294 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-10-13T19:37:18.310Z,1602617838.310 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-10-13T19:37:18.310Z,1602617838.310 [logger ThreadHandler](INFO): Thread cancelled.
2020-10-13T19:37:18.310Z,1602617838.310 [logger](INFO): Join timeout helper Thread ID is 3595
2020-10-13T19:37:18.325Z,1602617838.325 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-13T19:37:18.325Z,1602617838.325 [logger ThreadHandler](INFO): Thread cancelled.
2020-10-13T19:37:18.329Z,1602617838.329 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-10-13T19:37:18.330Z,1602617838.330 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-10-13T19:37:18.330Z,1602617838.330 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-10-13T19:37:18.330Z,1602617838.330 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-10-13T19:37:18.330Z,1602617838.330 [controlThread](INFO): Join timeout helper Thread ID is 3596
2020-10-13T19:37:18.605Z,1602617838.605 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-10-13T19:37:18.605Z,1602617838.605 [controlThread](DEBUG): Uninitializing ControlThread
2020-10-13T19:37:18.606Z,1602617838.606 [AHRS_M2](INFO): Powering down
2020-10-13T19:37:18.607Z,1602617838.607 [NAL9602](INFO): Powering down
2020-10-13T19:37:18.609Z,1602617838.609 [AMEcho](INFO): Powering down
2020-10-13T19:37:18.610Z,1602617838.610 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-10-13T19:37:18.611Z,1602617838.611 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-10-13T19:37:18.611Z,1602617838.611 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-10-13T19:37:18.612Z,1602617838.612 [MissionManager](INFO): Uninitializing Mission Default
2020-10-13T19:37:18.612Z,1602617838.612 [Default] Stopped
2020-10-13T19:37:18.612Z,1602617838.612 [Default](DEBUG): Aggregate::uninitialize Default
2020-10-13T19:37:18.612Z,1602617838.612 [Default:A.Wait] Stopped
2020-10-13T19:37:18.612Z,1602617838.612 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-10-13T19:37:18.612Z,1602617838.612 [Default:B.GoToSurface] Stopped
2020-10-13T19:37:18.612Z,1602617838.612 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-10-13T19:37:18.615Z,1602617838.615 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-10-13T19:37:18.615Z,1602617838.615 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-10-13T19:37:18.615Z,1602617838.615 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-10-13T19:37:18.615Z,1602617838.615 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-10-13T19:37:18.615Z,1602617838.615 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-10-13T19:37:18.615Z,1602617838.615 [BuoyancyServo](INFO): Powering down
2020-10-13T19:37:18.629Z,1602617838.629 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-10-13T19:37:18.629Z,1602617838.629 [ElevatorServo](INFO): Powering down
2020-10-13T19:37:18.630Z,1602617838.630 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-10-13T19:37:18.630Z,1602617838.630 [MassServo](INFO): Powering down
2020-10-13T19:37:18.631Z,1602617838.631 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-10-13T19:37:18.631Z,1602617838.631 [RudderServo](INFO): Powering down
2020-10-13T19:37:18.632Z,1602617838.632 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-10-13T19:37:18.632Z,1602617838.632 [ThrusterServo](INFO): Powering down
2020-10-13T19:37:18.632Z,1602617838.632 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-10-13T19:37:18.633Z,1602617838.633 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-10-13T19:37:18.633Z,1602617838.633 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-10-13T19:37:18.633Z,1602617838.633 [CBIT](DEBUG): Powering off loads.
2020-10-13T19:37:18.645Z,1602617838.645 [CBIT](DEBUG): Disabling WDT.
2020-10-13T19:37:18.657Z,1602617838.657 [CBIT](DEBUG): Opening all GF detection circuits.
2020-10-13T19:37:18.658Z,1602617838.658 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-10-13T19:37:18.749Z,1602617838.749 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-10-13T19:37:18.752Z,1602617838.752 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-10-13T19:37:18.757Z,1602617838.757 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-10-13T19:37:18.790Z,1602617838.790 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-10-13T19:37:18.794Z,1602617838.794 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-10-13T19:37:18.836Z,1602617838.836 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-10-13T19:37:18.887Z,1602617838.887 [logger ThreadHandler](INFO): Thread cancelled.