2020-08-26T17:39:18.812Z,1598463558.812 [Supervisor](DEBUG): Initializing supervisor. 2020-08-26T17:39:18.815Z,1598463558.815 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2020-08-26T17:39:18.816Z,1598463558.816 [SyncHandler](INFO): Protected caller Thread ID is 7210 2020-08-26T17:39:18.816Z,1598463558.816 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2020-08-26T17:39:18.817Z,1598463558.817 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2020-08-26T17:39:18.818Z,1598463558.818 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7211 2020-08-26T17:39:18.822Z,1598463558.822 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2020-08-26T17:39:18.836Z,1598463558.836 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2020-08-26T17:39:18.837Z,1598463558.837 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2020-08-26T17:39:18.837Z,1598463558.837 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7212 2020-08-26T17:39:18.838Z,1598463558.838 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2020-08-26T17:39:18.838Z,1598463558.838 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2020-08-26T17:39:18.839Z,1598463558.839 [logger ThreadHandler](INFO): Protected caller Thread ID is 7213 2020-08-26T17:39:18.841Z,1598463558.841 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2020-08-26T17:39:18.841Z,1598463558.841 [Supervisor](INFO): Looking for Config files in directory: Config/ 2020-08-26T17:39:18.843Z,1598463558.843 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2020-08-26T17:39:19.268Z,1598463559.268 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2020-08-26T17:39:19.269Z,1598463559.269 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2020-08-26T17:39:19.408Z,1598463559.408 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2020-08-26T17:39:19.408Z,1598463559.408 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2020-08-26T17:39:19.487Z,1598463559.487 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2020-08-26T17:39:19.677Z,1598463559.677 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2020-08-26T17:39:19.678Z,1598463559.678 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2020-08-26T17:39:19.774Z,1598463559.774 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2020-08-26T17:39:19.774Z,1598463559.774 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2020-08-26T17:39:19.869Z,1598463559.869 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2020-08-26T17:39:19.869Z,1598463559.869 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2020-08-26T17:39:20.266Z,1598463560.266 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2020-08-26T17:39:20.266Z,1598463560.266 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2020-08-26T17:39:20.367Z,1598463560.367 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2020-08-26T17:39:20.367Z,1598463560.367 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2020-08-26T17:39:20.815Z,1598463560.815 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2020-08-26T17:39:20.816Z,1598463560.816 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2020-08-26T17:39:21.007Z,1598463561.007 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2020-08-26T17:39:21.008Z,1598463561.008 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2020-08-26T17:39:21.318Z,1598463561.318 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2020-08-26T17:39:21.319Z,1598463561.319 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2020-08-26T17:39:21.531Z,1598463561.531 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2020-08-26T17:39:21.532Z,1598463561.532 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2020-08-26T17:39:21.671Z,1598463561.671 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2020-08-26T17:39:21.671Z,1598463561.671 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2020-08-26T17:39:22.322Z,1598463562.322 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2020-08-26T17:39:22.323Z,1598463562.323 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2020-08-26T17:39:22.644Z,1598463562.644 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2020-08-26T17:39:22.645Z,1598463562.645 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2020-08-26T17:39:22.848Z,1598463562.848 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2020-08-26T17:39:22.850Z,1598463562.850 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/ 2020-08-26T17:39:22.850Z,1598463562.850 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg 2020-08-26T17:39:23.039Z,1598463563.039 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg 2020-08-26T17:39:23.120Z,1598463563.120 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg 2020-08-26T17:39:23.267Z,1598463563.267 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg 2020-08-26T17:39:23.382Z,1598463563.382 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg 2020-08-26T17:39:23.687Z,1598463563.687 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2020-08-26T17:39:23.687Z,1598463563.687 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg 2020-08-26T17:39:23.916Z,1598463563.916 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg 2020-08-26T17:39:24.160Z,1598463564.160 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg 2020-08-26T17:39:24.524Z,1598463564.524 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg 2020-08-26T17:39:24.666Z,1598463564.666 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg 2020-08-26T17:39:24.761Z,1598463564.761 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg 2020-08-26T17:39:24.858Z,1598463564.858 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/ 2020-08-26T17:39:24.859Z,1598463564.859 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2020-08-26T17:39:24.870Z,1598463564.870 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2020-08-26T17:39:24.890Z,1598463564.890 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2020-08-26T17:39:24.890Z,1598463564.890 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2020-08-26T17:39:24.968Z,1598463564.968 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2020-08-26T17:39:24.968Z,1598463564.968 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2020-08-26T17:39:25.017Z,1598463565.017 [VerticalControl](DEBUG): Construct VerticalControl. 2020-08-26T17:39:25.126Z,1598463565.126 [VerticalControl] Loaded 2020-08-26T17:39:25.126Z,1598463565.126 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2020-08-26T17:39:25.127Z,1598463565.127 [HorizontalControl](DEBUG): Construct HorizontalControl. 2020-08-26T17:39:25.204Z,1598463565.204 [HorizontalControl] Loaded 2020-08-26T17:39:25.205Z,1598463565.205 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2020-08-26T17:39:25.205Z,1598463565.205 [SpeedControl](DEBUG): Construct SpeedControl. 2020-08-26T17:39:25.211Z,1598463565.211 [SpeedControl] Loaded 2020-08-26T17:39:25.211Z,1598463565.211 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2020-08-26T17:39:25.211Z,1598463565.211 [LoopControl](DEBUG): Construct LoopControl. 2020-08-26T17:39:25.212Z,1598463565.212 [LoopControl] Loaded 2020-08-26T17:39:25.212Z,1598463565.212 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2020-08-26T17:39:25.213Z,1598463565.213 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2020-08-26T17:39:25.213Z,1598463565.213 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2020-08-26T17:39:25.359Z,1598463565.359 [BuoyancyServo] Loaded 2020-08-26T17:39:25.359Z,1598463565.359 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2020-08-26T17:39:25.387Z,1598463565.387 [ElevatorServo] Loaded 2020-08-26T17:39:25.387Z,1598463565.387 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2020-08-26T17:39:25.414Z,1598463565.414 [MassServo] Loaded 2020-08-26T17:39:25.414Z,1598463565.414 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2020-08-26T17:39:25.441Z,1598463565.441 [RudderServo] Loaded 2020-08-26T17:39:25.441Z,1598463565.441 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2020-08-26T17:39:25.467Z,1598463565.467 [ThrusterServo] Loaded 2020-08-26T17:39:25.467Z,1598463565.467 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2020-08-26T17:39:25.468Z,1598463565.468 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2020-08-26T17:39:25.468Z,1598463565.468 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2020-08-26T17:39:25.509Z,1598463565.509 [DepthRateCalculator] Loaded 2020-08-26T17:39:25.509Z,1598463565.509 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2020-08-26T17:39:25.514Z,1598463565.514 [PitchRateCalculator] Loaded 2020-08-26T17:39:25.515Z,1598463565.515 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2020-08-26T17:39:25.529Z,1598463565.529 [SpeedCalculator] Loaded 2020-08-26T17:39:25.529Z,1598463565.529 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2020-08-26T17:39:25.550Z,1598463565.550 [TempGradientCalculator] Loaded 2020-08-26T17:39:25.550Z,1598463565.550 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2020-08-26T17:39:25.555Z,1598463565.555 [YawRateCalculator] Loaded 2020-08-26T17:39:25.555Z,1598463565.555 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2020-08-26T17:39:25.584Z,1598463565.584 [ElevatorOffsetCalculator] Loaded 2020-08-26T17:39:25.584Z,1598463565.584 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2020-08-26T17:39:25.585Z,1598463565.585 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2020-08-26T17:39:25.585Z,1598463565.585 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2020-08-26T17:39:25.704Z,1598463565.704 [DeadReckonUsingMultipleVelocitySources] Loaded 2020-08-26T17:39:25.704Z,1598463565.704 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2020-08-26T17:39:25.724Z,1598463565.724 [NavChart] Loaded 2020-08-26T17:39:25.724Z,1598463565.724 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2020-08-26T17:39:25.728Z,1598463565.728 [UniversalFixResidualReporter] Loaded 2020-08-26T17:39:25.728Z,1598463565.728 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2020-08-26T17:39:25.729Z,1598463565.729 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2020-08-26T17:39:25.729Z,1598463565.729 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2020-08-26T17:39:26.052Z,1598463566.052 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation 2020-08-26T17:39:26.057Z,1598463566.057 [AHRS_M2](INFO): created writer for : platform_orientation 2020-08-26T17:39:26.059Z,1598463566.059 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation 2020-08-26T17:39:26.064Z,1598463566.064 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation 2020-08-26T17:39:26.065Z,1598463566.065 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle 2020-08-26T17:39:26.070Z,1598463566.070 [AHRS_M2](INFO): created writer for : platform_pitch_angle 2020-08-26T17:39:26.070Z,1598463566.070 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle 2020-08-26T17:39:26.076Z,1598463566.076 [AHRS_M2](INFO): created writer for : platform_roll_angle 2020-08-26T17:39:26.145Z,1598463566.145 [AHRS_M2] Loaded 2020-08-26T17:39:26.145Z,1598463566.145 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2020-08-26T17:39:26.220Z,1598463566.220 [DataOverHttps] Loaded 2020-08-26T17:39:26.220Z,1598463566.220 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2020-08-26T17:39:26.222Z,1598463566.222 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4084F4E0 2020-08-26T17:39:26.222Z,1598463566.222 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7295 2020-08-26T17:39:26.252Z,1598463566.252 [Depth_Keller] Loaded 2020-08-26T17:39:26.252Z,1598463566.252 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2020-08-26T17:39:26.362Z,1598463566.362 [NAL9602] Loaded 2020-08-26T17:39:26.363Z,1598463566.363 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2020-08-26T17:39:26.410Z,1598463566.410 [Onboard] Loaded 2020-08-26T17:39:26.410Z,1598463566.410 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2020-08-26T17:39:26.411Z,1598463566.411 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4087F4E0 2020-08-26T17:39:26.412Z,1598463566.412 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 7296 2020-08-26T17:39:26.431Z,1598463566.431 [Radio_Surface] Loaded 2020-08-26T17:39:26.431Z,1598463566.431 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2020-08-26T17:39:26.432Z,1598463566.432 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408AF4E0 2020-08-26T17:39:26.432Z,1598463566.432 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7297 2020-08-26T17:39:26.580Z,1598463566.580 [DAT] Loaded 2020-08-26T17:39:26.581Z,1598463566.581 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2020-08-26T17:39:28.059Z,1598463568.059 [BPC1] Loaded 2020-08-26T17:39:28.060Z,1598463568.060 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2020-08-26T17:39:28.060Z,1598463568.060 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2020-08-26T17:39:28.061Z,1598463568.061 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2020-08-26T17:39:28.149Z,1598463568.149 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2020-08-26T17:39:28.150Z,1598463568.150 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2020-08-26T17:39:28.249Z,1598463568.249 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2020-08-26T17:39:28.249Z,1598463568.249 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2020-08-26T17:39:28.276Z,1598463568.276 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2020-08-26T17:39:28.277Z,1598463568.277 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2020-08-26T17:39:28.494Z,1598463568.494 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2020-08-26T17:39:28.499Z,1598463568.499 [CTD_Seabird](INFO): created writer for : sea_water_density 2020-08-26T17:39:28.499Z,1598463568.499 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2020-08-26T17:39:28.504Z,1598463568.504 [CTD_Seabird](INFO): created writer for : depth 2020-08-26T17:39:28.505Z,1598463568.505 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2020-08-26T17:39:28.509Z,1598463568.509 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2020-08-26T17:39:28.510Z,1598463568.510 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2020-08-26T17:39:28.515Z,1598463568.515 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2020-08-26T17:39:28.516Z,1598463568.516 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2020-08-26T17:39:28.521Z,1598463568.521 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2020-08-26T17:39:28.521Z,1598463568.521 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2020-08-26T17:39:28.527Z,1598463568.527 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2020-08-26T17:39:28.527Z,1598463568.527 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2020-08-26T17:39:28.532Z,1598463568.532 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2020-08-26T17:39:28.559Z,1598463568.559 [CTD_Seabird] Loaded 2020-08-26T17:39:28.560Z,1598463568.560 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2020-08-26T17:39:28.561Z,1598463568.561 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A554E0 2020-08-26T17:39:28.561Z,1598463568.561 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7298 2020-08-26T17:39:28.610Z,1598463568.610 [ESPComponent] Loaded 2020-08-26T17:39:28.610Z,1598463568.610 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2020-08-26T17:39:28.640Z,1598463568.640 [PAR_Licor] Loaded 2020-08-26T17:39:28.640Z,1598463568.640 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2020-08-26T17:39:28.662Z,1598463568.662 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470 2020-08-26T17:39:28.662Z,1598463568.662 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470 2020-08-26T17:39:28.666Z,1598463568.666 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650 2020-08-26T17:39:28.666Z,1598463568.666 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650 2020-08-26T17:39:28.671Z,1598463568.671 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl 2020-08-26T17:39:28.671Z,1598463568.671 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl 2020-08-26T17:39:28.675Z,1598463568.675 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm 2020-08-26T17:39:28.675Z,1598463568.675 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm 2020-08-26T17:39:28.679Z,1598463568.679 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm 2020-08-26T17:39:28.679Z,1598463568.679 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm 2020-08-26T17:39:28.684Z,1598463568.684 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm 2020-08-26T17:39:28.684Z,1598463568.684 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm 2020-08-26T17:39:28.688Z,1598463568.688 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm 2020-08-26T17:39:28.688Z,1598463568.688 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm 2020-08-26T17:39:28.692Z,1598463568.692 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water 2020-08-26T17:39:28.696Z,1598463568.696 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water 2020-08-26T17:39:28.697Z,1598463568.697 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-08-26T17:39:28.697Z,1598463568.697 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-08-26T17:39:28.701Z,1598463568.701 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-08-26T17:39:28.701Z,1598463568.701 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-08-26T17:39:28.705Z,1598463568.705 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-08-26T17:39:28.705Z,1598463568.705 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-08-26T17:39:28.710Z,1598463568.710 [WetLabsBB2FL] Loaded 2020-08-26T17:39:28.710Z,1598463568.710 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2020-08-26T17:39:28.711Z,1598463568.711 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A854E0 2020-08-26T17:39:28.711Z,1598463568.711 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 7299 2020-08-26T17:39:28.712Z,1598463568.712 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2020-08-26T17:39:28.713Z,1598463568.713 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2020-08-26T17:39:29.315Z,1598463569.315 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2020-08-26T17:39:29.316Z,1598463569.316 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2020-08-26T17:39:29.449Z,1598463569.449 [SBIT](DEBUG): Construct Startup Built In Test. 2020-08-26T17:39:29.460Z,1598463569.460 [SBIT] Loaded 2020-08-26T17:39:29.460Z,1598463569.460 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2020-08-26T17:39:29.461Z,1598463569.461 [IBIT](DEBUG): Construct Initiated Built In Test. 2020-08-26T17:39:29.474Z,1598463569.474 [IBIT] Loaded 2020-08-26T17:39:29.474Z,1598463569.474 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2020-08-26T17:39:29.477Z,1598463569.477 [CBIT](DEBUG): Construct Continuous Built In Test. 2020-08-26T17:39:29.616Z,1598463569.616 [CBIT] Loaded 2020-08-26T17:39:29.616Z,1598463569.616 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2020-08-26T17:39:29.616Z,1598463569.616 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2020-08-26T17:39:29.620Z,1598463569.620 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2020-08-26T17:39:29.621Z,1598463569.621 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2020-08-26T17:39:29.628Z,1598463569.628 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2020-08-26T17:39:29.629Z,1598463569.629 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B634E0 2020-08-26T17:39:29.629Z,1598463569.629 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7300 2020-08-26T17:39:29.634Z,1598463569.634 [Supervisor](INFO): Main Thread ID is 6839 2020-08-26T17:39:29.634Z,1598463569.634 [Supervisor](DEBUG): Running supervisor. 2020-08-26T17:39:29.634Z,1598463569.634 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7301 2020-08-26T17:39:29.637Z,1598463569.637 [controlThread ThreadHandler](INFO): Handler Thread ID is 7302 2020-08-26T17:39:29.637Z,1598463569.637 [controlThread](DEBUG): Initializing ControlThread 2020-08-26T17:39:29.638Z,1598463569.638 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2020-08-26T17:39:29.640Z,1598463569.640 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2020-08-26T17:39:29.641Z,1598463569.641 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2020-08-26T17:39:29.641Z,1598463569.641 [LoopControl](DEBUG): Initialize LoopControlComponent. 2020-08-26T17:39:29.642Z,1598463569.642 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2020-08-26T17:39:29.643Z,1598463569.643 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2020-08-26T17:39:29.643Z,1598463569.643 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2020-08-26T17:39:29.643Z,1598463569.643 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2020-08-26T17:39:29.644Z,1598463569.644 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2020-08-26T17:39:29.644Z,1598463569.644 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2020-08-26T17:39:29.646Z,1598463569.646 [NavChart](DEBUG): Initialize NavChart Navigation. 2020-08-26T17:39:29.646Z,1598463569.646 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2020-08-26T17:39:29.653Z,1598463569.653 [SBIT](INFO): Initialize SBIT Component. 2020-08-26T17:39:29.654Z,1598463569.654 [SBIT](IMPORTANT): git: 2020-08-18a-30-g8cc0a1c87 2020-08-26T17:39:29.654Z,1598463569.654 [SBIT](INFO): git hash: 8cc0a1c87b994ada9a621eded1d43a5b5e56617e 2020-08-26T17:39:29.654Z,1598463569.654 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2020-08-26T17:39:29.655Z,1598463569.655 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018 Kernel Reported: #1 PREEMPT Tue May 26 08:17:09 PDT 2020 2020-08-26T17:39:29.656Z,1598463569.656 [SBIT](INFO): Beginning SBIT in 34.000000 seconds. 2020-08-26T17:39:29.657Z,1598463569.657 [IBIT](INFO): Initialize IBIT Component. 2020-08-26T17:39:29.658Z,1598463569.658 [CBIT](DEBUG): Initialize CBIT Component. 2020-08-26T17:39:29.659Z,1598463569.659 [logger ThreadHandler](INFO): Handler Thread ID is 7303 2020-08-26T17:39:29.669Z,1598463569.669 [CBIT](DEBUG): Initialized mux pins. 2020-08-26T17:39:29.669Z,1598463569.669 [CBIT](DEBUG): Initializing the watchdog timer. 2020-08-26T17:39:29.677Z,1598463569.677 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7304 2020-08-26T17:39:29.678Z,1598463569.678 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2020-08-26T17:39:29.689Z,1598463569.689 [Onboard ThreadHandler](INFO): Handler Thread ID is 7305 2020-08-26T17:39:29.693Z,1598463569.693 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2020-08-26T17:39:29.693Z,1598463569.693 [CBIT](DEBUG): Initializing heartbeat. 2020-08-26T17:39:29.707Z,1598463569.707 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7306 2020-08-26T17:39:29.725Z,1598463569.725 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7307 2020-08-26T17:39:29.726Z,1598463569.726 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2020-08-26T17:39:29.730Z,1598463569.730 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 7309 2020-08-26T17:39:29.731Z,1598463569.731 [WetLabsBB2FL](INFO): Powering down 2020-08-26T17:39:29.753Z,1598463569.753 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7310 2020-08-26T17:39:29.756Z,1598463569.756 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2020-08-26T17:39:29.757Z,1598463569.757 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2020-08-26T17:39:29.757Z,1598463569.757 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2020-08-26T17:39:29.757Z,1598463569.757 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2020-08-26T17:39:29.757Z,1598463569.757 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2020-08-26T17:39:29.757Z,1598463569.757 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2020-08-26T17:39:29.757Z,1598463569.757 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2020-08-26T17:39:29.757Z,1598463569.757 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2020-08-26T17:39:29.758Z,1598463569.758 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2020-08-26T17:39:29.758Z,1598463569.758 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2020-08-26T17:39:29.758Z,1598463569.758 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2020-08-26T17:39:29.758Z,1598463569.758 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2020-08-26T17:39:29.758Z,1598463569.758 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2020-08-26T17:39:29.758Z,1598463569.758 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2020-08-26T17:39:29.759Z,1598463569.759 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2020-08-26T17:39:29.759Z,1598463569.759 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2020-08-26T17:39:29.765Z,1598463569.765 [CBIT](DEBUG): Deactivating GF circuits. 2020-08-26T17:39:29.765Z,1598463569.765 [CBIT](DEBUG): Deactivating emergency mode. 2020-08-26T17:39:29.801Z,1598463569.801 [CBIT](DEBUG): Backplane powered. 2020-08-26T17:39:29.802Z,1598463569.802 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2020-08-26T17:39:29.812Z,1598463569.812 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2020-08-26T17:39:29.823Z,1598463569.823 [MissionManager](DEBUG): 2020-08-26T17:39:29.823Z,1598463569.823 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2020-08-26T17:39:29.891Z,1598463569.891 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2020-08-26T17:39:29.892Z,1598463569.892 [Default:A.Wait](DEBUG): Construct Wait. 2020-08-26T17:39:29.910Z,1598463569.910 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2020-08-26T17:39:29.936Z,1598463569.936 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2020-08-26T17:39:29.939Z,1598463569.939 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2020-08-26T17:39:29.964Z,1598463569.964 [Default:E.Execute](DEBUG): Construct Execute. 2020-08-26T17:39:29.968Z,1598463569.968 [MissionManager](DEBUG): 0 Wait a moment to see if the scheduler starts a new mission before starting to actually run Default. 13 Burn on Dropped weight due to communications timeout. 5 Default mission has been running for Restarting logs and Default mission. restart logs 2020-08-26T17:39:29.972Z,1598463569.972 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,NAL9602,DAT,BPC1,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2020-08-26T17:39:29.995Z,1598463569.995 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2020-08-26T17:39:30.034Z,1598463570.034 [Depth_Keller](ERROR): Pressure reading out of range: 1925.893799 decibar 2020-08-26T17:39:30.037Z,1598463570.037 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2020-08-26T17:39:30.037Z,1598463570.037 [DAT](INFO): Powering up 2020-08-26T17:39:30.037Z,1598463570.037 [DAT](DEBUG): Initializing DAT. 2020-08-26T17:39:30.113Z,1598463570.113 [Radio_Surface](INFO): Powering up 2020-08-26T17:39:30.125Z,1598463570.125 [DepthRateCalculator](ERROR): Depth measurement is not active 2020-08-26T17:39:30.133Z,1598463570.133 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2020-08-26T17:39:30.193Z,1598463570.193 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2020-08-26T17:39:30.201Z,1598463570.201 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2020-08-26T17:39:30.202Z,1598463570.202 [ElevatorServo](DEBUG): Initializing EZServoServo. 2020-08-26T17:39:30.213Z,1598463570.213 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2020-08-26T17:39:30.214Z,1598463570.214 [MassServo](DEBUG): Initializing EZServoServo. 2020-08-26T17:39:30.234Z,1598463570.234 [MassServo](DEBUG): Initializing MassServo. 2020-08-26T17:39:30.235Z,1598463570.235 [RudderServo](DEBUG): Initializing EZServoServo. 2020-08-26T17:39:30.249Z,1598463570.249 [RudderServo](DEBUG): Initializing RudderServo. 2020-08-26T17:39:30.250Z,1598463570.250 [ThrusterServo](DEBUG): Initializing EZServoServo. 2020-08-26T17:39:30.261Z,1598463570.261 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2020-08-26T17:39:31.113Z,1598463571.113 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2020-08-26T17:39:31.113Z,1598463571.113 [RudderServo](FAULT): Rudder failed to initialize 2020-08-26T17:39:31.113Z,1598463571.113 [RudderServo] Communications Fault, FailCount= 1 2020-08-26T17:39:31.113Z,1598463571.113 [RudderServo](ERROR): Communications Fault 2020-08-26T17:39:31.224Z,1598463571.224 [CBIT](ERROR): Communications Fault in component: RudderServo 2020-08-26T17:39:31.394Z,1598463571.394 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-08-26T17:39:31.394Z,1598463571.394 [RudderServo](INFO): Powering down 2020-08-26T17:39:32.059Z,1598463572.059 [RudderServo](DEBUG): Initializing EZServoServo. 2020-08-26T17:39:32.178Z,1598463572.178 [RudderServo](DEBUG): Initializing RudderServo. 2020-08-26T17:39:32.182Z,1598463572.182 [CBIT](INFO): Clearing failed state for component RudderServo 2020-08-26T17:39:32.182Z,1598463572.182 [RudderServo] No Fault, FailCount= 1 2020-08-26T17:39:33.573Z,1598463573.573 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:33.977Z,1598463573.977 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:34.473Z,1598463574.473 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:35.053Z,1598463575.053 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:35.473Z,1598463575.473 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:35.925Z,1598463575.925 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:36.377Z,1598463576.377 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:36.973Z,1598463576.973 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:37.529Z,1598463577.529 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:37.933Z,1598463577.933 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:38.433Z,1598463578.433 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:38.881Z,1598463578.881 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:39.269Z,1598463579.269 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:39.689Z,1598463579.689 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:40.121Z,1598463580.121 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:40.529Z,1598463580.529 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:40.965Z,1598463580.965 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:41.365Z,1598463581.365 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:41.805Z,1598463581.805 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:42.217Z,1598463582.217 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:42.617Z,1598463582.617 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:42.624Z,1598463582.624 [DAT](INFO): commRate: 800 2020-08-26T17:39:42.625Z,1598463582.625 [DAT](INFO): commRate: 800 2020-08-26T17:39:43.045Z,1598463583.045 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:43.045Z,1598463583.045 [AHRS_M2](FAULT): Failed to initialize within timeout. 2020-08-26T17:39:43.045Z,1598463583.045 [AHRS_M2] Communications Fault, FailCount= 1 2020-08-26T17:39:43.045Z,1598463583.045 [AHRS_M2](ERROR): Communications Fault 2020-08-26T17:39:43.050Z,1598463583.050 [DAT](INFO): entering command mode 2020-08-26T17:39:43.117Z,1598463583.117 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2020-08-26T17:39:43.122Z,1598463583.122 [AHRS_M2](INFO): Powering down 2020-08-26T17:39:43.199Z,1598463583.199 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-26T17:39:43.530Z,1598463583.530 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-26T17:39:43.934Z,1598463583.934 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-26T17:39:43.951Z,1598463583.951 [CBIT](INFO): Clearing failed state for component AHRS_M2 2020-08-26T17:39:43.952Z,1598463583.952 [AHRS_M2] No Fault, FailCount= 1 2020-08-26T17:39:44.333Z,1598463584.333 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2020-08-26T17:39:44.347Z,1598463584.347 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-26T17:39:44.742Z,1598463584.742 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-26T17:39:45.146Z,1598463585.146 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-26T17:39:45.562Z,1598463585.562 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-26T17:39:45.954Z,1598463585.954 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-26T17:39:46.370Z,1598463586.370 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-26T17:39:46.762Z,1598463586.762 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-26T17:39:47.166Z,1598463587.166 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-26T17:39:47.881Z,1598463587.881 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:47.886Z,1598463587.886 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-26T17:39:48.293Z,1598463588.293 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:48.295Z,1598463588.295 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-26T17:39:48.689Z,1598463588.689 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:48.707Z,1598463588.707 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-26T17:39:49.113Z,1598463589.113 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:49.118Z,1598463589.118 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-26T17:39:49.525Z,1598463589.525 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:49.530Z,1598463589.530 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-26T17:39:49.937Z,1598463589.937 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:49.951Z,1598463589.951 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-26T17:39:50.357Z,1598463590.357 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:50.370Z,1598463590.370 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-26T17:39:50.753Z,1598463590.753 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:50.758Z,1598463590.758 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-26T17:39:51.157Z,1598463591.157 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:51.162Z,1598463591.162 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-26T17:39:51.541Z,1598463591.541 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:51.546Z,1598463591.546 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-26T17:39:51.957Z,1598463591.957 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:51.962Z,1598463591.962 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-26T17:39:52.361Z,1598463592.361 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:52.366Z,1598463592.366 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-26T17:39:52.773Z,1598463592.773 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:52.778Z,1598463592.778 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-26T17:39:53.165Z,1598463593.165 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:53.177Z,1598463593.177 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-26T17:39:53.565Z,1598463593.565 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:53.570Z,1598463593.570 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-26T17:39:53.969Z,1598463593.969 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:53.974Z,1598463593.974 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-26T17:39:54.377Z,1598463594.377 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:54.382Z,1598463594.382 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-26T17:39:54.777Z,1598463594.777 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:54.783Z,1598463594.783 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-26T17:39:55.181Z,1598463595.181 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:55.186Z,1598463595.186 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-26T17:39:55.585Z,1598463595.585 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:55.590Z,1598463595.590 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-26T17:39:55.997Z,1598463595.997 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:56.002Z,1598463596.002 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-26T17:39:56.401Z,1598463596.401 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:56.414Z,1598463596.414 [NAL9602](INFO): Powering up NAL9602 2020-08-26T17:39:56.415Z,1598463596.415 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-26T17:39:56.805Z,1598463596.805 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:56.810Z,1598463596.810 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-26T17:39:57.209Z,1598463597.209 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:39:57.209Z,1598463597.209 [AHRS_M2](FAULT): Failed to initialize within timeout. 2020-08-26T17:39:57.209Z,1598463597.209 [AHRS_M2] Communications Fault, FailCount= 2 2020-08-26T17:39:57.209Z,1598463597.209 [AHRS_M2](ERROR): Communications Fault 2020-08-26T17:39:57.214Z,1598463597.214 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-26T17:39:57.299Z,1598463597.299 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2020-08-26T17:39:57.313Z,1598463597.313 [AHRS_M2](INFO): Powering down 2020-08-26T17:39:57.387Z,1598463597.387 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-26T17:39:57.710Z,1598463597.710 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-26T17:39:58.114Z,1598463598.114 [DAT](DEBUG): checking for command mode acknowledgment 2020-08-26T17:39:58.114Z,1598463598.114 [DAT](FAULT): failed to enter command mode 2020-08-26T17:39:58.155Z,1598463598.155 [CBIT](INFO): Clearing failed state for component AHRS_M2 2020-08-26T17:39:58.155Z,1598463598.155 [AHRS_M2] No Fault, FailCount= 2 2020-08-26T17:39:58.512Z,1598463598.512 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2020-08-26T17:39:58.531Z,1598463598.531 [DAT](INFO): entering command mode 2020-08-26T17:39:58.935Z,1598463598.935 [DAT](INFO): setting verbose to 3 2020-08-26T17:39:59.327Z,1598463599.327 [DAT](INFO): set verbose to 3 2020-08-26T17:39:59.328Z,1598463599.328 [DAT](INFO): setting DatVerbose to 27440 2020-08-26T17:39:59.731Z,1598463599.731 [DAT](INFO): set DatVerbose to 27440 2020-08-26T17:39:59.732Z,1598463599.732 [DAT](INFO): setting transmit power to 8 2020-08-26T17:40:00.147Z,1598463600.147 [DAT](INFO): set transmit power to 8 2020-08-26T17:40:00.148Z,1598463600.148 [DAT](INFO): setting local address to 5 2020-08-26T17:40:00.539Z,1598463600.539 [DAT](INFO): set local address to 5 2020-08-26T17:40:02.069Z,1598463602.069 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:40:02.461Z,1598463602.461 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:40:02.877Z,1598463602.877 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:40:03.273Z,1598463603.273 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:40:03.677Z,1598463603.677 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:40:04.089Z,1598463604.089 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-08-26T17:40:04.150Z,1598463604.150 [SBIT](IMPORTANT): Beginning Startup BIT 2020-08-26T17:40:04.154Z,1598463604.154 [CBIT](IMPORTANT): Beginning ground fault scan 2020-08-26T17:40:04.909Z,1598463604.909 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:05.329Z,1598463605.329 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:05.833Z,1598463605.833 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:06.329Z,1598463606.329 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:06.817Z,1598463606.817 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:07.137Z,1598463607.137 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:07.785Z,1598463607.785 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:07.798Z,1598463607.798 [NAL9602](INFO): NAL9602 initialized 2020-08-26T17:40:08.349Z,1598463608.349 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:08.881Z,1598463608.881 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:09.369Z,1598463609.369 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:10.017Z,1598463610.017 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:10.441Z,1598463610.441 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:10.861Z,1598463610.861 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:11.273Z,1598463611.273 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:11.785Z,1598463611.785 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:11.785Z,1598463611.785 [AHRS_M2](FAULT): Failed to initialize within timeout. 2020-08-26T17:40:11.785Z,1598463611.785 [AHRS_M2] Communications Fault, FailCount= 3 2020-08-26T17:40:11.785Z,1598463611.785 [AHRS_M2](ERROR): Communications Fault 2020-08-26T17:40:11.862Z,1598463611.862 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2020-08-26T17:40:11.876Z,1598463611.876 [AHRS_M2](INFO): Powering down 2020-08-26T17:40:12.733Z,1598463612.733 [CBIT](INFO): Clearing failed state for component AHRS_M2 2020-08-26T17:40:12.733Z,1598463612.733 [AHRS_M2] No Fault, FailCount= 3 2020-08-26T17:40:13.077Z,1598463613.077 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2020-08-26T17:40:15.545Z,1598463615.545 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.008580 CHAN A1 (24V): -0.007487 CHAN A2 (12V): -0.004938 CHAN A3 (5V): -0.002389 CHAN B0 (3.3V): -0.000995 CHAN B1 (3.15aV): -0.001182 CHAN B2 (3.15bV): -0.000919 CHAN B3 (GND): 0.000300 OPEN: 0.004512 Full Scale Calc: 4.765 mA, -1.589 mA 2020-08-26T17:40:16.621Z,1598463616.621 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:17.049Z,1598463617.049 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:17.469Z,1598463617.469 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:17.869Z,1598463617.869 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:18.257Z,1598463618.257 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:18.669Z,1598463618.669 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:19.073Z,1598463619.073 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:19.501Z,1598463619.501 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:20.077Z,1598463620.077 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:20.533Z,1598463620.533 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:20.969Z,1598463620.969 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:21.425Z,1598463621.425 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:21.893Z,1598463621.893 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:22.365Z,1598463622.365 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:22.829Z,1598463622.829 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:23.281Z,1598463623.281 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:23.717Z,1598463623.717 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:24.157Z,1598463624.157 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:24.605Z,1598463624.605 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:25.125Z,1598463625.125 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:25.745Z,1598463625.745 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:26.149Z,1598463626.149 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:26.149Z,1598463626.149 [AHRS_M2](FAULT): Failed to initialize within timeout. 2020-08-26T17:40:26.149Z,1598463626.149 [AHRS_M2] Communications Fault, FailCount= 4 2020-08-26T17:40:26.149Z,1598463626.149 [AHRS_M2](ERROR): Communications Fault 2020-08-26T17:40:26.225Z,1598463626.225 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2020-08-26T17:40:26.230Z,1598463626.230 [AHRS_M2](INFO): Powering down 2020-08-26T17:40:27.105Z,1598463627.105 [CBIT](INFO): Clearing failed state for component AHRS_M2 2020-08-26T17:40:27.106Z,1598463627.106 [AHRS_M2] No Fault, FailCount= 4 2020-08-26T17:40:27.440Z,1598463627.440 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2020-08-26T17:40:30.997Z,1598463630.997 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:31.421Z,1598463631.421 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:31.853Z,1598463631.853 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:32.257Z,1598463632.257 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:32.673Z,1598463632.673 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:33.077Z,1598463633.077 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:33.497Z,1598463633.497 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:33.893Z,1598463633.893 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:34.289Z,1598463634.289 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:34.693Z,1598463634.693 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:35.097Z,1598463635.097 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:35.497Z,1598463635.497 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:35.901Z,1598463635.901 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:36.389Z,1598463636.389 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:36.793Z,1598463636.793 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:37.197Z,1598463637.197 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:37.613Z,1598463637.613 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:38.017Z,1598463638.017 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:38.441Z,1598463638.441 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:38.833Z,1598463638.833 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:39.237Z,1598463639.237 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:39.641Z,1598463639.641 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:40.037Z,1598463640.037 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:40.449Z,1598463640.449 [AHRS_M2](ERROR): Format BitStream UART error: serial timeout 2020-08-26T17:40:40.449Z,1598463640.449 [AHRS_M2](FAULT): Failed to initialize within timeout. 2020-08-26T17:40:40.449Z,1598463640.449 [AHRS_M2] Communications Fault, FailCount= 5 2020-08-26T17:40:40.449Z,1598463640.449 [AHRS_M2](ERROR): Communications Fault 2020-08-26T17:40:40.474Z,1598463640.474 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2020-08-26T17:40:40.475Z,1598463640.475 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2 2020-08-26T17:40:40.529Z,1598463640.529 [AHRS_M2](INFO): Powering down 2020-08-26T17:40:40.624Z,1598463640.624 [CommandLine](FAULT): Scheduling is paused 2020-08-26T17:40:40.625Z,1598463640.625 [CBIT](INFO): Critical error at 20200826T174040 2020-08-26T17:40:40.625Z,1598463640.625 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2020-08-26T17:40:58.338Z,1598463658.338 [SBIT](IMPORTANT): SBIT PASSED 2020-08-26T17:40:58.339Z,1598463658.339 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2020-08-26T17:40:58.340Z,1598463658.340 [SBIT](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool; 2020-08-26T17:40:58.340Z,1598463658.340 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count; 2020-08-26T17:40:58.340Z,1598463658.340 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool; 2020-08-26T17:40:58.340Z,1598463658.340 [SBIT](IMPORTANT): DVL_micro.loadAtStartup=0 bool; 2020-08-26T17:40:58.340Z,1598463658.340 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2020-08-26T17:40:58.340Z,1598463658.340 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity; 2020-08-26T17:40:58.340Z,1598463658.340 [SBIT](IMPORTANT): Express linearApproximation WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 0.100000 microgram_per_liter; 2020-08-26T17:40:58.341Z,1598463658.341 [SBIT](IMPORTANT): Express linearApproximation depth 5.000000 meter; 2020-08-26T17:40:58.341Z,1598463658.341 [SBIT](IMPORTANT): Express linearApproximation latitude 0.005000 degree; 2020-08-26T17:40:58.341Z,1598463658.341 [SBIT](IMPORTANT): Express linearApproximation longitude 0.005000 degree; 2020-08-26T17:40:58.341Z,1598463658.341 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=168 cubic_centimeter; 2020-08-26T17:40:58.341Z,1598463658.341 [SBIT](IMPORTANT): VerticalControl.massDefault=3.7 millimeter; 2020-08-26T17:40:58.728Z,1598463658.728 [MissionManager](IMPORTANT): Started mission Startup 2020-08-26T17:40:58.728Z,1598463658.728 [Startup] Running Loop=1 2020-08-26T17:40:58.728Z,1598463658.728 [Startup](DEBUG): Aggregate::initialize Startup 2020-08-26T17:40:58.728Z,1598463658.728 [Startup:A.GoToSurface] Running Loop=1 2020-08-26T17:40:58.728Z,1598463658.728 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-08-26T17:40:58.729Z,1598463658.729 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-08-26T17:40:58.729Z,1598463658.729 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-08-26T17:40:58.730Z,1598463658.730 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-08-26T17:40:58.730Z,1598463658.730 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-08-26T17:40:58.730Z,1598463658.730 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-08-26T17:40:58.732Z,1598463658.732 [Startup:StartupSatComms] Running Loop=1 2020-08-26T17:40:58.732Z,1598463658.732 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2020-08-26T17:40:58.732Z,1598463658.732 [Startup:StartupSatComms:A] Running Loop=1 2020-08-26T17:40:59.140Z,1598463659.140 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2020-08-26T17:41:29.854Z,1598463689.854 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2020-08-26T17:41:29.854Z,1598463689.854 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2020-08-26T17:41:29.854Z,1598463689.854 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-08-26T17:41:29.865Z,1598463689.865 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-08-26T17:41:30.311Z,1598463690.311 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-08-26T17:41:30.311Z,1598463690.311 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2020-08-26T17:41:57.363Z,1598463717.363 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005340 2020-08-26T17:41:58.922Z,1598463718.922 [Startup:StartupSatComms:A](INFO): Timed out from 2020-08-26T17:40:58.7Z 2020-08-26T17:41:58.922Z,1598463718.922 [Startup:StartupSatComms:A] Stopped 2020-08-26T17:41:58.923Z,1598463718.923 [Startup:StartupSatComms:B] Running Loop=1 2020-08-26T17:41:59.323Z,1598463719.323 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2020-08-26T17:42:05.372Z,1598463725.372 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200826T161416/Courier0028.lzma 2020-08-26T17:42:06.375Z,1598463726.375 [DataOverHttps](INFO): Moved sent file to Logs/20200826T161416/Courier0028.lzma.bak 2020-08-26T17:42:06.375Z,1598463726.375 [DataOverHttps](INFO): SBD MOMSN=12620687 2020-08-26T17:42:21.556Z,1598463741.556 [DataOverHttps](INFO): Sending 211 bytes from file Logs/20200826T173918/Courier0000.lzma 2020-08-26T17:42:22.558Z,1598463742.558 [DataOverHttps](INFO): Moved sent file to Logs/20200826T173918/Courier0000.lzma.bak 2020-08-26T17:42:22.559Z,1598463742.559 [DataOverHttps](INFO): SBD MOMSN=12620689 2020-08-26T17:42:37.637Z,1598463757.637 [DataOverHttps](INFO): Sending 240 bytes from file Logs/20200826T161416/Express0029.lzma 2020-08-26T17:42:38.638Z,1598463758.638 [DataOverHttps](INFO): Moved sent file to Logs/20200826T161416/Express0029.lzma.bak 2020-08-26T17:42:38.639Z,1598463758.639 [DataOverHttps](INFO): SBD MOMSN=12620693 2020-08-26T17:42:53.822Z,1598463773.822 [DataOverHttps](INFO): Sending 1192 bytes from file Logs/20200826T173918/Express0001.lzma 2020-08-26T17:42:54.822Z,1598463774.822 [DataOverHttps](INFO): Moved sent file to Logs/20200826T173918/Express0001.lzma.bak 2020-08-26T17:42:54.822Z,1598463774.822 [DataOverHttps](INFO): SBD MOMSN=12620699 2020-08-26T17:42:56.737Z,1598463776.737 [Startup:StartupSatComms:B] Stopped 2020-08-26T17:42:56.737Z,1598463776.737 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2020-08-26T17:42:56.737Z,1598463776.737 [Startup:StartupSatComms] Stopped 2020-08-26T17:42:56.737Z,1598463776.737 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2020-08-26T17:42:56.738Z,1598463776.738 [Startup](INFO): Completed Startup 2020-08-26T17:42:56.738Z,1598463776.738 [MissionManager](INFO): Startup is completed. 2020-08-26T17:42:56.738Z,1598463776.738 [MissionManager](INFO): Uninitializing Mission Startup 2020-08-26T17:42:56.738Z,1598463776.738 [Startup] Stopped 2020-08-26T17:42:56.739Z,1598463776.739 [Startup](DEBUG): Aggregate::uninitialize Startup 2020-08-26T17:42:56.739Z,1598463776.739 [Startup:A.GoToSurface] Stopped 2020-08-26T17:42:56.739Z,1598463776.739 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-08-26T17:42:57.142Z,1598463777.142 [MissionManager](IMPORTANT): Started mission Default 2020-08-26T17:42:57.142Z,1598463777.142 [Default] Running Loop=1 2020-08-26T17:42:57.142Z,1598463777.142 [Default](DEBUG): Aggregate::initialize Default 2020-08-26T17:42:57.142Z,1598463777.142 [Default:B.GoToSurface] Running Loop=1 2020-08-26T17:42:57.142Z,1598463777.142 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-08-26T17:42:57.143Z,1598463777.143 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-08-26T17:42:57.143Z,1598463777.143 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-08-26T17:42:57.143Z,1598463777.143 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-08-26T17:42:57.144Z,1598463777.144 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-08-26T17:42:57.144Z,1598463777.144 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-08-26T17:42:57.144Z,1598463777.144 [Default:A.Wait] Running Loop=1 2020-08-26T17:42:57.144Z,1598463777.144 [Default:A.Wait](DEBUG): Initialize Wait Component. 2020-08-26T17:43:10.474Z,1598463790.474 [Default:A.Wait](INFO): Done Waiting. 2020-08-26T17:43:10.474Z,1598463790.474 [Default:A.Wait] Stopped 2020-08-26T17:43:10.474Z,1598463790.474 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2020-08-26T17:43:10.882Z,1598463790.882 [Default:CheckIn] Running Loop=1 2020-08-26T17:43:10.883Z,1598463790.883 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-08-26T17:43:10.883Z,1598463790.883 [Default:CheckIn:Read_GPS] Running Loop=1 2020-08-26T17:43:11.288Z,1598463791.288 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2020-08-26T17:43:30.685Z,1598463810.685 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2020-08-26T17:43:30.685Z,1598463810.685 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2020-08-26T17:43:30.685Z,1598463810.685 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-08-26T17:43:30.696Z,1598463810.696 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-08-26T17:43:31.102Z,1598463811.102 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-08-26T17:43:31.102Z,1598463811.102 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2020-08-26T17:45:11.666Z,1598463911.666 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-08-26T17:45:31.877Z,1598463931.877 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2020-08-26T17:45:31.877Z,1598463931.877 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2020-08-26T17:45:31.877Z,1598463931.877 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-08-26T17:45:31.887Z,1598463931.887 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-08-26T17:45:32.344Z,1598463932.344 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-08-26T17:45:32.344Z,1598463932.344 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2020-08-26T17:45:35.266Z,1598463935.266 [CommandLine](IMPORTANT): got command restart application 2020-08-26T17:45:36.273Z,1598463936.273 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2020-08-26T17:45:36.273Z,1598463936.273 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-08-26T17:45:36.317Z,1598463936.317 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2020-08-26T17:45:36.317Z,1598463936.317 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-08-26T17:45:36.318Z,1598463936.318 [CommandLine](INFO): Join timeout helper Thread ID is 7338 2020-08-26T17:45:36.326Z,1598463936.326 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2020-08-26T17:45:36.326Z,1598463936.326 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-08-26T17:45:36.326Z,1598463936.326 [NavChartDb](INFO): Join timeout helper Thread ID is 7339 2020-08-26T17:45:36.581Z,1598463936.581 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2020-08-26T17:45:36.581Z,1598463936.581 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-08-26T17:45:36.589Z,1598463936.589 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2020-08-26T17:45:36.589Z,1598463936.589 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-08-26T17:45:36.589Z,1598463936.589 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7340 2020-08-26T17:45:36.761Z,1598463936.761 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2020-08-26T17:45:36.761Z,1598463936.761 [WetLabsBB2FL](INFO): Powering down 2020-08-26T17:45:36.762Z,1598463936.762 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-08-26T17:45:36.777Z,1598463936.777 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2020-08-26T17:45:36.777Z,1598463936.777 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-08-26T17:45:36.777Z,1598463936.777 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7341 2020-08-26T17:45:37.225Z,1598463937.225 [CTD_Seabird](INFO): Powering down 2020-08-26T17:45:37.237Z,1598463937.237 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2020-08-26T17:45:37.237Z,1598463937.237 [CTD_Seabird](INFO): Powering down 2020-08-26T17:45:37.249Z,1598463937.249 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-08-26T17:45:37.257Z,1598463937.257 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2020-08-26T17:45:37.257Z,1598463937.257 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-08-26T17:45:37.257Z,1598463937.257 [Radio_Surface](INFO): Join timeout helper Thread ID is 7342 2020-08-26T17:45:37.413Z,1598463937.413 [Radio_Surface](INFO): Powering down 2020-08-26T17:45:37.414Z,1598463937.414 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2020-08-26T17:45:37.414Z,1598463937.414 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-08-26T17:45:37.422Z,1598463937.422 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2020-08-26T17:45:37.422Z,1598463937.422 [Onboard ThreadHandler](INFO): Thread cancelled. 2020-08-26T17:45:37.422Z,1598463937.422 [Onboard](INFO): Join timeout helper Thread ID is 7343 2020-08-26T17:45:37.541Z,1598463937.541 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2020-08-26T17:45:39.997Z,1598463939.997 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2020-08-26T17:45:39.997Z,1598463939.997 [Onboard ThreadHandler](INFO): Thread cancelled. 2020-08-26T17:45:40.001Z,1598463940.001 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2020-08-26T17:45:40.001Z,1598463940.001 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-08-26T17:45:40.002Z,1598463940.002 [DataOverHttps](INFO): Join timeout helper Thread ID is 7344 2020-08-26T17:45:40.377Z,1598463940.377 [CBIT](FAULT): Humidity exceeds 50% of running average: Humidity:2285304 %. Average:260285.359375 2020-08-26T17:45:40.553Z,1598463940.553 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2020-08-26T17:45:40.553Z,1598463940.553 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-08-26T17:45:40.569Z,1598463940.569 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2020-08-26T17:45:40.570Z,1598463940.570 [logger ThreadHandler](INFO): Thread cancelled. 2020-08-26T17:45:40.570Z,1598463940.570 [logger](INFO): Join timeout helper Thread ID is 7345 2020-08-26T17:45:40.605Z,1598463940.605 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2020-08-26T17:45:40.605Z,1598463940.605 [logger ThreadHandler](INFO): Thread cancelled. 2020-08-26T17:45:40.609Z,1598463940.609 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2020-08-26T17:45:40.609Z,1598463940.609 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-08-26T17:45:40.610Z,1598463940.610 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2020-08-26T17:45:40.610Z,1598463940.610 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-08-26T17:45:40.610Z,1598463940.610 [controlThread](INFO): Join timeout helper Thread ID is 7346 2020-08-26T17:45:40.741Z,1598463940.741 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2020-08-26T17:45:40.741Z,1598463940.741 [controlThread](DEBUG): Uninitializing ControlThread 2020-08-26T17:45:40.741Z,1598463940.741 [AHRS_M2](INFO): Powering down 2020-08-26T17:45:40.742Z,1598463940.742 [NAL9602](INFO): Powering down 2020-08-26T17:45:40.743Z,1598463940.743 [DAT](INFO): Powering down 2020-08-26T17:45:40.863Z,1598463940.863 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2020-08-26T17:45:40.864Z,1598463940.864 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2020-08-26T17:45:40.865Z,1598463940.865 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2020-08-26T17:45:40.865Z,1598463940.865 [MissionManager](INFO): Uninitializing Mission Default 2020-08-26T17:45:40.866Z,1598463940.866 [Default] Stopped 2020-08-26T17:45:40.866Z,1598463940.866 [Default](DEBUG): Aggregate::uninitialize Default 2020-08-26T17:45:40.866Z,1598463940.866 [Default:B.GoToSurface] Stopped 2020-08-26T17:45:40.866Z,1598463940.866 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-08-26T17:45:40.866Z,1598463940.866 [Default:CheckIn] Stopped 2020-08-26T17:45:40.866Z,1598463940.866 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-08-26T17:45:40.866Z,1598463940.866 [Default:CheckIn:Read_GPS] Stopped 2020-08-26T17:45:40.868Z,1598463940.868 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2020-08-26T17:45:40.869Z,1598463940.869 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2020-08-26T17:45:40.869Z,1598463940.869 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2020-08-26T17:45:40.870Z,1598463940.870 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2020-08-26T17:45:40.870Z,1598463940.870 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2020-08-26T17:45:40.870Z,1598463940.870 [BuoyancyServo](INFO): Powering down 2020-08-26T17:45:40.885Z,1598463940.885 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2020-08-26T17:45:40.885Z,1598463940.885 [ElevatorServo](INFO): Powering down 2020-08-26T17:45:40.886Z,1598463940.886 [MassServo](DEBUG): Uninitialize Mass Servo. 2020-08-26T17:45:40.886Z,1598463940.886 [MassServo](INFO): Powering down 2020-08-26T17:45:40.887Z,1598463940.887 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-08-26T17:45:40.887Z,1598463940.887 [RudderServo](INFO): Powering down 2020-08-26T17:45:40.887Z,1598463940.887 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2020-08-26T17:45:40.888Z,1598463940.888 [ThrusterServo](INFO): Powering down 2020-08-26T17:45:40.888Z,1598463940.888 [SBIT](DEBUG): Uninitialize SBIT Component. 2020-08-26T17:45:40.889Z,1598463940.889 [IBIT](DEBUG): Uninitialize IBIT Component. 2020-08-26T17:45:40.889Z,1598463940.889 [CBIT](DEBUG): Uninitialize CBIT Component. 2020-08-26T17:45:40.889Z,1598463940.889 [CBIT](DEBUG): Powering off loads. 2020-08-26T17:45:40.901Z,1598463940.901 [CBIT](DEBUG): Disabling WDT. 2020-08-26T17:45:40.912Z,1598463940.912 [CBIT](DEBUG): Opening all GF detection circuits. 2020-08-26T17:45:40.913Z,1598463940.913 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-08-26T17:45:41.011Z,1598463941.011 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-08-26T17:45:41.013Z,1598463941.013 [Onboard ThreadHandler](INFO): Thread cancelled. 2020-08-26T17:45:41.018Z,1598463941.018 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-08-26T17:45:41.060Z,1598463941.060 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-08-26T17:45:41.066Z,1598463941.066 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-08-26T17:45:41.104Z,1598463941.104 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-08-26T17:45:41.168Z,1598463941.168 [logger ThreadHandler](INFO): Thread cancelled.