2020-05-15T19:31:15.221Z,1589571075.221 [Supervisor](DEBUG): Initializing supervisor. 2020-05-15T19:31:15.224Z,1589571075.224 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2020-05-15T19:31:15.224Z,1589571075.224 [SyncHandler](INFO): Protected caller Thread ID is 4434 2020-05-15T19:31:15.225Z,1589571075.225 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2020-05-15T19:31:15.226Z,1589571075.226 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2020-05-15T19:31:15.226Z,1589571075.226 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4435 2020-05-15T19:31:15.229Z,1589571075.229 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2020-05-15T19:31:15.242Z,1589571075.242 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2020-05-15T19:31:15.243Z,1589571075.243 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2020-05-15T19:31:15.243Z,1589571075.243 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4436 2020-05-15T19:31:15.244Z,1589571075.244 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2020-05-15T19:31:15.245Z,1589571075.245 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2020-05-15T19:31:15.245Z,1589571075.245 [logger ThreadHandler](INFO): Protected caller Thread ID is 4437 2020-05-15T19:31:15.248Z,1589571075.248 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2020-05-15T19:31:15.248Z,1589571075.248 [Supervisor](INFO): Looking for Config files in directory: Config/ 2020-05-15T19:31:15.249Z,1589571075.249 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2020-05-15T19:31:15.580Z,1589571075.580 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2020-05-15T19:31:15.581Z,1589571075.581 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2020-05-15T19:31:16.194Z,1589571076.194 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2020-05-15T19:31:16.195Z,1589571076.195 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2020-05-15T19:31:16.344Z,1589571076.344 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2020-05-15T19:31:16.345Z,1589571076.345 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2020-05-15T19:31:16.905Z,1589571076.905 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2020-05-15T19:31:16.905Z,1589571076.905 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2020-05-15T19:31:17.002Z,1589571077.002 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2020-05-15T19:31:17.002Z,1589571077.002 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2020-05-15T19:31:17.084Z,1589571077.084 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2020-05-15T19:31:17.396Z,1589571077.396 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2020-05-15T19:31:17.397Z,1589571077.397 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2020-05-15T19:31:17.544Z,1589571077.544 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2020-05-15T19:31:17.545Z,1589571077.545 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2020-05-15T19:31:17.649Z,1589571077.649 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2020-05-15T19:31:17.649Z,1589571077.649 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2020-05-15T19:31:18.146Z,1589571078.146 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2020-05-15T19:31:18.147Z,1589571078.147 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2020-05-15T19:31:18.566Z,1589571078.566 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2020-05-15T19:31:18.566Z,1589571078.566 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2020-05-15T19:31:18.669Z,1589571078.669 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2020-05-15T19:31:18.669Z,1589571078.669 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2020-05-15T19:31:18.867Z,1589571078.867 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2020-05-15T19:31:18.867Z,1589571078.867 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2020-05-15T19:31:19.075Z,1589571079.075 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2020-05-15T19:31:19.076Z,1589571079.076 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2020-05-15T19:31:19.307Z,1589571079.307 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2020-05-15T19:31:19.309Z,1589571079.309 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/ 2020-05-15T19:31:19.310Z,1589571079.310 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Control.cfg 2020-05-15T19:31:19.411Z,1589571079.411 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Sensor.cfg 2020-05-15T19:31:19.553Z,1589571079.553 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Simulator.cfg 2020-05-15T19:31:19.639Z,1589571079.639 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/secure.cfg 2020-05-15T19:31:19.722Z,1589571079.722 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Servo.cfg 2020-05-15T19:31:19.829Z,1589571079.829 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/vehicle.cfg 2020-05-15T19:31:20.017Z,1589571080.017 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Battery.cfg 2020-05-15T19:31:20.247Z,1589571080.247 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2020-05-15T19:31:20.248Z,1589571080.248 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Science.cfg 2020-05-15T19:31:20.360Z,1589571080.360 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/logger.cfg 2020-05-15T19:31:20.455Z,1589571080.455 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/BIT.cfg 2020-05-15T19:31:20.553Z,1589571080.553 [Supervisor](INFO): Opening Config file at: Config/lrauv-brizo/Navigation.cfg 2020-05-15T19:31:20.648Z,1589571080.648 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-brizo/root/ 2020-05-15T19:31:20.649Z,1589571080.649 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2020-05-15T19:31:20.655Z,1589571080.655 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2020-05-15T19:31:20.676Z,1589571080.676 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2020-05-15T19:31:20.676Z,1589571080.676 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2020-05-15T19:31:20.718Z,1589571080.718 [DepthRateCalculator] Loaded 2020-05-15T19:31:20.718Z,1589571080.718 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2020-05-15T19:31:20.725Z,1589571080.725 [PitchRateCalculator] Loaded 2020-05-15T19:31:20.725Z,1589571080.725 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2020-05-15T19:31:20.742Z,1589571080.742 [SpeedCalculator] Loaded 2020-05-15T19:31:20.742Z,1589571080.742 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2020-05-15T19:31:20.764Z,1589571080.764 [TempGradientCalculator] Loaded 2020-05-15T19:31:20.764Z,1589571080.764 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2020-05-15T19:31:20.770Z,1589571080.770 [YawRateCalculator] Loaded 2020-05-15T19:31:20.770Z,1589571080.770 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2020-05-15T19:31:20.812Z,1589571080.812 [ElevatorOffsetCalculator] Loaded 2020-05-15T19:31:20.812Z,1589571080.812 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2020-05-15T19:31:20.813Z,1589571080.813 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2020-05-15T19:31:20.813Z,1589571080.813 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2020-05-15T19:31:20.863Z,1589571080.863 [VerticalControl](DEBUG): Construct VerticalControl. 2020-05-15T19:31:20.969Z,1589571080.969 [VerticalControl] Loaded 2020-05-15T19:31:20.969Z,1589571080.969 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2020-05-15T19:31:20.970Z,1589571080.970 [HorizontalControl](DEBUG): Construct HorizontalControl. 2020-05-15T19:31:21.054Z,1589571081.054 [HorizontalControl] Loaded 2020-05-15T19:31:21.055Z,1589571081.055 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2020-05-15T19:31:21.055Z,1589571081.055 [SpeedControl](DEBUG): Construct SpeedControl. 2020-05-15T19:31:21.061Z,1589571081.061 [SpeedControl] Loaded 2020-05-15T19:31:21.061Z,1589571081.061 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2020-05-15T19:31:21.062Z,1589571081.062 [LoopControl](DEBUG): Construct LoopControl. 2020-05-15T19:31:21.062Z,1589571081.062 [LoopControl] Loaded 2020-05-15T19:31:21.062Z,1589571081.062 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2020-05-15T19:31:21.063Z,1589571081.063 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2020-05-15T19:31:21.064Z,1589571081.064 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2020-05-15T19:31:21.171Z,1589571081.171 [DeadReckonUsingMultipleVelocitySources] Loaded 2020-05-15T19:31:21.171Z,1589571081.171 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2020-05-15T19:31:21.193Z,1589571081.193 [NavChart] Loaded 2020-05-15T19:31:21.193Z,1589571081.193 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2020-05-15T19:31:21.197Z,1589571081.197 [UniversalFixResidualReporter] Loaded 2020-05-15T19:31:21.197Z,1589571081.197 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2020-05-15T19:31:21.198Z,1589571081.198 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2020-05-15T19:31:21.198Z,1589571081.198 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2020-05-15T19:31:21.301Z,1589571081.301 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2020-05-15T19:31:21.302Z,1589571081.302 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2020-05-15T19:31:21.685Z,1589571081.685 [DataOverHttps] Loaded 2020-05-15T19:31:21.685Z,1589571081.685 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2020-05-15T19:31:21.686Z,1589571081.686 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408244E0 2020-05-15T19:31:21.687Z,1589571081.687 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4516 2020-05-15T19:31:21.727Z,1589571081.727 [Depth_Keller] Loaded 2020-05-15T19:31:21.727Z,1589571081.727 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2020-05-15T19:31:21.732Z,1589571081.732 [DropWeight] Loaded 2020-05-15T19:31:21.732Z,1589571081.732 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2020-05-15T19:31:21.839Z,1589571081.839 [NAL9602] Loaded 2020-05-15T19:31:21.840Z,1589571081.840 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2020-05-15T19:31:21.891Z,1589571081.891 [Onboard] Loaded 2020-05-15T19:31:21.891Z,1589571081.891 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2020-05-15T19:31:21.894Z,1589571081.894 [Radio_Surface] Loaded 2020-05-15T19:31:21.895Z,1589571081.895 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2020-05-15T19:31:21.896Z,1589571081.896 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408544E0 2020-05-15T19:31:21.896Z,1589571081.896 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4517 2020-05-15T19:31:22.037Z,1589571082.037 [DAT] Loaded 2020-05-15T19:31:22.037Z,1589571082.037 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2020-05-15T19:31:23.953Z,1589571083.953 [BPC1] Loaded 2020-05-15T19:31:23.953Z,1589571083.953 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2020-05-15T19:31:23.954Z,1589571083.954 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2020-05-15T19:31:23.954Z,1589571083.954 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2020-05-15T19:31:24.061Z,1589571084.061 [BuoyancyServo] Loaded 2020-05-15T19:31:24.061Z,1589571084.061 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2020-05-15T19:31:24.072Z,1589571084.072 [ElevatorServo] Loaded 2020-05-15T19:31:24.073Z,1589571084.073 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2020-05-15T19:31:24.084Z,1589571084.084 [MassServo] Loaded 2020-05-15T19:31:24.085Z,1589571084.085 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2020-05-15T19:31:24.096Z,1589571084.096 [RudderServo] Loaded 2020-05-15T19:31:24.096Z,1589571084.096 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2020-05-15T19:31:24.107Z,1589571084.107 [ThrusterServo] Loaded 2020-05-15T19:31:24.108Z,1589571084.108 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2020-05-15T19:31:24.108Z,1589571084.108 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2020-05-15T19:31:24.109Z,1589571084.109 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2020-05-15T19:31:24.192Z,1589571084.192 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2020-05-15T19:31:24.192Z,1589571084.192 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2020-05-15T19:31:24.219Z,1589571084.219 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2020-05-15T19:31:24.220Z,1589571084.220 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2020-05-15T19:31:24.540Z,1589571084.540 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2020-05-15T19:31:24.540Z,1589571084.540 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2020-05-15T19:31:24.755Z,1589571084.755 [ESPComponent] Loaded 2020-05-15T19:31:24.755Z,1589571084.755 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2020-05-15T19:31:24.770Z,1589571084.770 [PAR_Licor] Loaded 2020-05-15T19:31:24.770Z,1589571084.770 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2020-05-15T19:31:24.777Z,1589571084.777 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470 2020-05-15T19:31:24.778Z,1589571084.778 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470 2020-05-15T19:31:24.784Z,1589571084.784 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650 2020-05-15T19:31:24.784Z,1589571084.784 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650 2020-05-15T19:31:24.789Z,1589571084.789 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl 2020-05-15T19:31:24.789Z,1589571084.789 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl 2020-05-15T19:31:24.794Z,1589571084.794 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm 2020-05-15T19:31:24.794Z,1589571084.794 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm 2020-05-15T19:31:24.798Z,1589571084.798 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm 2020-05-15T19:31:24.798Z,1589571084.798 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm 2020-05-15T19:31:24.803Z,1589571084.803 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm 2020-05-15T19:31:24.803Z,1589571084.803 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm 2020-05-15T19:31:24.808Z,1589571084.808 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm 2020-05-15T19:31:24.808Z,1589571084.808 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm 2020-05-15T19:31:24.813Z,1589571084.813 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water 2020-05-15T19:31:24.818Z,1589571084.818 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water 2020-05-15T19:31:24.818Z,1589571084.818 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-05-15T19:31:24.818Z,1589571084.818 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-05-15T19:31:24.823Z,1589571084.823 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-05-15T19:31:24.823Z,1589571084.823 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-05-15T19:31:24.828Z,1589571084.828 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-05-15T19:31:24.828Z,1589571084.828 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-05-15T19:31:24.832Z,1589571084.832 [WetLabsBB2FL] Loaded 2020-05-15T19:31:24.832Z,1589571084.832 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2020-05-15T19:31:24.834Z,1589571084.834 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A6A4E0 2020-05-15T19:31:24.834Z,1589571084.834 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4518 2020-05-15T19:31:24.835Z,1589571084.835 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2020-05-15T19:31:24.835Z,1589571084.835 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2020-05-15T19:31:24.974Z,1589571084.974 [SBIT](DEBUG): Construct Startup Built In Test. 2020-05-15T19:31:24.986Z,1589571084.986 [SBIT] Loaded 2020-05-15T19:31:24.986Z,1589571084.986 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2020-05-15T19:31:24.987Z,1589571084.987 [IBIT](DEBUG): Construct Initiated Built In Test. 2020-05-15T19:31:24.000Z,1589571085.000 [IBIT] Loaded 2020-05-15T19:31:24.000Z,1589571085.000 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2020-05-15T19:31:25.003Z,1589571085.003 [CBIT](DEBUG): Construct Continuous Built In Test. 2020-05-15T19:31:25.149Z,1589571085.149 [CBIT] Loaded 2020-05-15T19:31:25.150Z,1589571085.150 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2020-05-15T19:31:25.150Z,1589571085.150 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2020-05-15T19:31:25.154Z,1589571085.154 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2020-05-15T19:31:25.155Z,1589571085.155 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2020-05-15T19:31:25.162Z,1589571085.162 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2020-05-15T19:31:25.163Z,1589571085.163 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AD54E0 2020-05-15T19:31:25.163Z,1589571085.163 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4519 2020-05-15T19:31:25.168Z,1589571085.168 [Supervisor](INFO): Main Thread ID is 3443 2020-05-15T19:31:25.168Z,1589571085.168 [Supervisor](DEBUG): Running supervisor. 2020-05-15T19:31:25.169Z,1589571085.169 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4520 2020-05-15T19:31:25.172Z,1589571085.172 [controlThread ThreadHandler](INFO): Handler Thread ID is 4521 2020-05-15T19:31:25.173Z,1589571085.173 [controlThread](DEBUG): Initializing ControlThread 2020-05-15T19:31:25.173Z,1589571085.173 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2020-05-15T19:31:25.174Z,1589571085.174 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2020-05-15T19:31:25.174Z,1589571085.174 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2020-05-15T19:31:25.174Z,1589571085.174 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2020-05-15T19:31:25.175Z,1589571085.175 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2020-05-15T19:31:25.175Z,1589571085.175 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2020-05-15T19:31:25.176Z,1589571085.176 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2020-05-15T19:31:25.178Z,1589571085.178 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2020-05-15T19:31:25.178Z,1589571085.178 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2020-05-15T19:31:25.179Z,1589571085.179 [LoopControl](DEBUG): Initialize LoopControlComponent. 2020-05-15T19:31:25.180Z,1589571085.180 [NavChart](DEBUG): Initialize NavChart Navigation. 2020-05-15T19:31:25.180Z,1589571085.180 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2020-05-15T19:31:25.188Z,1589571085.188 [SBIT](INFO): Initialize SBIT Component. 2020-05-15T19:31:25.188Z,1589571085.188 [SBIT](IMPORTANT): git: 2020-02-26-39-g5ba86ef19 2020-05-15T19:31:25.188Z,1589571085.188 [SBIT](INFO): git hash: 5ba86ef19446a3a7971077fddc96013a7a660a2f 2020-05-15T19:31:25.189Z,1589571085.189 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2020-05-15T19:31:25.189Z,1589571085.189 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Tue Sep 24 11:12:27 PDT 2019 2020-05-15T19:31:25.190Z,1589571085.190 [SBIT](INFO): Beginning SBIT in 20.000000 seconds. 2020-05-15T19:31:25.191Z,1589571085.191 [IBIT](INFO): Initialize IBIT Component. 2020-05-15T19:31:25.192Z,1589571085.192 [CBIT](DEBUG): Initialize CBIT Component. 2020-05-15T19:31:25.193Z,1589571085.193 [logger ThreadHandler](INFO): Handler Thread ID is 4522 2020-05-15T19:31:25.203Z,1589571085.203 [CBIT](DEBUG): Initialized mux pins. 2020-05-15T19:31:25.204Z,1589571085.204 [CBIT](DEBUG): Initializing the watchdog timer. 2020-05-15T19:31:25.212Z,1589571085.212 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4523 2020-05-15T19:31:25.213Z,1589571085.213 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2020-05-15T19:31:25.224Z,1589571085.224 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4524 2020-05-15T19:31:25.228Z,1589571085.227 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2020-05-15T19:31:25.228Z,1589571085.228 [CBIT](DEBUG): Initializing heartbeat. 2020-05-15T19:31:25.236Z,1589571085.236 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4525 2020-05-15T19:31:25.237Z,1589571085.237 [WetLabsBB2FL](INFO): Powering down 2020-05-15T19:31:25.265Z,1589571085.265 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4526 2020-05-15T19:31:25.268Z,1589571085.268 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2020-05-15T19:31:25.268Z,1589571085.268 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2020-05-15T19:31:25.268Z,1589571085.268 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2020-05-15T19:31:25.268Z,1589571085.268 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2020-05-15T19:31:25.269Z,1589571085.269 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2020-05-15T19:31:25.269Z,1589571085.269 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2020-05-15T19:31:25.269Z,1589571085.269 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2020-05-15T19:31:25.269Z,1589571085.269 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2020-05-15T19:31:25.269Z,1589571085.269 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2020-05-15T19:31:25.270Z,1589571085.270 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2020-05-15T19:31:25.270Z,1589571085.270 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2020-05-15T19:31:25.270Z,1589571085.270 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2020-05-15T19:31:25.270Z,1589571085.270 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2020-05-15T19:31:25.270Z,1589571085.270 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2020-05-15T19:31:25.271Z,1589571085.271 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2020-05-15T19:31:25.271Z,1589571085.271 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2020-05-15T19:31:25.300Z,1589571085.300 [CBIT](DEBUG): Deactivating GF circuits. 2020-05-15T19:31:25.300Z,1589571085.300 [CBIT](DEBUG): Deactivating emergency mode. 2020-05-15T19:31:25.335Z,1589571085.335 [CBIT](DEBUG): Backplane powered. 2020-05-15T19:31:25.336Z,1589571085.336 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2020-05-15T19:31:25.346Z,1589571085.346 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2020-05-15T19:31:25.365Z,1589571085.365 [MissionManager](DEBUG): 2020-05-15T19:31:25.365Z,1589571085.365 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2020-05-15T19:31:25.434Z,1589571085.434 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2020-05-15T19:31:25.435Z,1589571085.435 [Default:A.Wait](DEBUG): Construct Wait. 2020-05-15T19:31:25.436Z,1589571085.436 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2020-05-15T19:31:25.480Z,1589571085.480 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2020-05-15T19:31:25.482Z,1589571085.482 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2020-05-15T19:31:25.540Z,1589571085.540 [Default:E.Execute](DEBUG): Construct Execute. 2020-05-15T19:31:25.543Z,1589571085.543 [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-05-15T19:31:25.555Z,1589571085.555 [controlThread](DEBUG): Component order: CycleStarter,Depth_Keller,DropWeight,NAL9602,Onboard,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-05-15T19:31:25.628Z,1589571085.628 [Radio_Surface](INFO): Powering up 2020-05-15T19:31:25.687Z,1589571085.687 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2020-05-15T19:31:25.687Z,1589571085.687 [DAT](INFO): Powering up 2020-05-15T19:31:25.687Z,1589571085.687 [DAT](DEBUG): Initializing DAT. 2020-05-15T19:31:25.772Z,1589571085.772 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2020-05-15T19:31:25.914Z,1589571085.914 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2020-05-15T19:31:25.928Z,1589571085.928 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2020-05-15T19:31:25.929Z,1589571085.929 [ElevatorServo](DEBUG): Initializing EZServoServo. 2020-05-15T19:31:25.936Z,1589571085.936 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2020-05-15T19:31:25.937Z,1589571085.937 [MassServo](DEBUG): Initializing EZServoServo. 2020-05-15T19:31:25.944Z,1589571085.944 [MassServo](DEBUG): Initializing MassServo. 2020-05-15T19:31:25.945Z,1589571085.945 [RudderServo](DEBUG): Initializing EZServoServo. 2020-05-15T19:31:25.952Z,1589571085.952 [RudderServo](DEBUG): Initializing RudderServo. 2020-05-15T19:31:25.953Z,1589571085.953 [ThrusterServo](DEBUG): Initializing EZServoServo. 2020-05-15T19:31:25.960Z,1589571085.960 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2020-05-15T19:31:26.779Z,1589571086.779 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2020-05-15T19:31:26.780Z,1589571086.780 [RudderServo](FAULT): Rudder failed to initialize 2020-05-15T19:31:26.780Z,1589571086.780 [RudderServo] Communications Fault, FailCount= 1 2020-05-15T19:31:26.780Z,1589571086.780 [RudderServo](ERROR): Communications Fault 2020-05-15T19:31:26.927Z,1589571086.927 [CBIT](ERROR): Communications Fault in component: RudderServo 2020-05-15T19:31:27.128Z,1589571087.128 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-05-15T19:31:27.129Z,1589571087.129 [RudderServo](INFO): Powering down 2020-05-15T19:31:27.802Z,1589571087.802 [RudderServo](DEBUG): Initializing EZServoServo. 2020-05-15T19:31:27.920Z,1589571087.920 [RudderServo](DEBUG): Initializing RudderServo. 2020-05-15T19:31:27.924Z,1589571087.924 [CBIT](INFO): Clearing failed state for component RudderServo 2020-05-15T19:31:27.924Z,1589571087.924 [RudderServo] No Fault, FailCount= 1 2020-05-15T19:31:35.438Z,1589571095.438 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V 2020-05-15T19:31:35.442Z,1589571095.442 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V 2020-05-15T19:31:35.448Z,1589571095.448 [Onboard](IMPORTANT): Battery Current:7.815000 mA 2020-05-15T19:31:35.449Z,1589571095.449 [Onboard](IMPORTANT): using main battery for universal 2020-05-15T19:31:35.449Z,1589571095.449 [Onboard](IMPORTANT): average counter: 0 2020-05-15T19:31:35.450Z,1589571095.450 [Onboard](IMPORTANT): average volt scaler: 1 2020-05-15T19:31:35.450Z,1589571095.450 [Onboard](IMPORTANT): average fg timeout scaler: 6 2020-05-15T19:31:37.051Z,1589571097.051 [DAT](DEBUG): 2020-05-15T19:31:37.052Z,1589571097.052 [DAT](DEBUG): Teledyne Benthos DAT-900 Series 2020-05-15T19:31:38.668Z,1589571098.668 [DAT](DEBUG): MF Frequency Band 2020-05-15T19:31:38.669Z,1589571098.669 [DAT](DEBUG): Directional Acoustic Transponder version 8.14.0 2020-05-15T19:31:38.670Z,1589571098.670 [DAT](DEBUG): May 15 2020 19:31:25 2020-05-15T19:31:39.476Z,1589571099.476 [DAT](DEBUG): Features enabled [Bearing] 2020-05-15T19:31:39.477Z,1589571099.477 [DAT](DEBUG): CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP 2020-05-15T19:31:39.477Z,1589571099.477 [DAT](INFO): commRate: 800 2020-05-15T19:31:39.881Z,1589571099.881 [DAT](INFO): entering command mode 2020-05-15T19:31:40.284Z,1589571100.284 [DAT](DEBUG): checking for command mode acknowledgment 2020-05-15T19:31:40.687Z,1589571100.687 [DAT](DEBUG): checking for command mode acknowledgment 2020-05-15T19:31:41.099Z,1589571101.099 [DAT](DEBUG): checking for command mode acknowledgment 2020-05-15T19:31:41.099Z,1589571101.099 [DAT](INFO): command mode acknowledged 2020-05-15T19:31:41.100Z,1589571101.100 [DAT](INFO): setting verbose to 3 2020-05-15T19:31:41.495Z,1589571101.495 [DAT](DEBUG): checking for verbose setting acknowledgment 2020-05-15T19:31:41.496Z,1589571101.496 [DAT](INFO): set verbose to 3 2020-05-15T19:31:41.899Z,1589571101.899 [DAT](INFO): setting transmit power to 8 2020-05-15T19:31:42.322Z,1589571102.322 [DAT](DEBUG): checking for transmit power setting acknowledgment 2020-05-15T19:31:42.724Z,1589571102.724 [DAT](DEBUG): checking for transmit power setting acknowledgment 2020-05-15T19:31:42.725Z,1589571102.725 [DAT](INFO): set transmit power to 8 2020-05-15T19:31:43.116Z,1589571103.116 [DAT](INFO): setting local address to 8 2020-05-15T19:31:43.516Z,1589571103.516 [DAT](DEBUG): checking for local address setting acknowledgment 2020-05-15T19:31:43.915Z,1589571103.915 [DAT](DEBUG): checking for local address setting acknowledgment 2020-05-15T19:31:43.916Z,1589571103.916 [DAT](INFO): set local address to 8 2020-05-15T19:31:45.538Z,1589571105.538 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V 2020-05-15T19:31:45.541Z,1589571105.541 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V 2020-05-15T19:31:45.547Z,1589571105.547 [Onboard](IMPORTANT): Battery Current:7.815000 mA 2020-05-15T19:31:45.547Z,1589571105.547 [Onboard](IMPORTANT): using main battery for universal 2020-05-15T19:31:45.547Z,1589571105.547 [Onboard](IMPORTANT): average counter: 1 2020-05-15T19:31:45.548Z,1589571105.548 [Onboard](IMPORTANT): average volt scaler: 1 2020-05-15T19:31:45.548Z,1589571105.548 [Onboard](IMPORTANT): average fg timeout scaler: 6 2020-05-15T19:31:45.969Z,1589571105.969 [SBIT](IMPORTANT): Beginning Startup BIT 2020-05-15T19:31:45.976Z,1589571105.976 [CBIT](IMPORTANT): Beginning ground fault scan 2020-05-15T19:31:52.073Z,1589571112.073 [NAL9602](INFO): Powering up NAL9602 2020-05-15T19:31:55.736Z,1589571115.736 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V 2020-05-15T19:31:55.740Z,1589571115.740 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V 2020-05-15T19:31:55.746Z,1589571115.746 [Onboard](IMPORTANT): Battery Current:8.335999 mA 2020-05-15T19:31:55.746Z,1589571115.746 [Onboard](IMPORTANT): using main battery for universal 2020-05-15T19:31:55.746Z,1589571115.746 [Onboard](IMPORTANT): average counter: 2 2020-05-15T19:31:55.746Z,1589571115.746 [Onboard](IMPORTANT): average volt scaler: 1 2020-05-15T19:31:55.747Z,1589571115.747 [Onboard](IMPORTANT): average fg timeout scaler: 6 2020-05-15T19:31:57.027Z,1589571117.027 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.023429 CHAN A1 (24V): 0.002907 CHAN A2 (12V): -0.003727 CHAN A3 (5V): 0.000591 CHAN B0 (3.3V): -0.001181 CHAN B1 (3.15aV): -0.001122 CHAN B2 (3.15bV): -0.001762 CHAN B3 (GND): -0.001046 OPEN: 0.003522 Full Scale Calc: 4.765 mA, -1.589 mA 2020-05-15T19:32:03.129Z,1589571123.129 [NAL9602](INFO): NAL9602 initialized 2020-05-15T19:32:05.997Z,1589571125.997 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V 2020-05-15T19:32:06.000Z,1589571126.000 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V 2020-05-15T19:32:06.006Z,1589571126.006 [Onboard](IMPORTANT): Battery Current:9.899000 mA 2020-05-15T19:32:06.007Z,1589571126.007 [Onboard](IMPORTANT): using main battery for universal 2020-05-15T19:32:06.007Z,1589571126.007 [Onboard](IMPORTANT): average counter: 3 2020-05-15T19:32:06.007Z,1589571126.007 [Onboard](IMPORTANT): average volt scaler: 1 2020-05-15T19:32:06.007Z,1589571126.007 [Onboard](IMPORTANT): average fg timeout scaler: 6 2020-05-15T19:32:16.084Z,1589571136.084 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V 2020-05-15T19:32:16.087Z,1589571136.087 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V 2020-05-15T19:32:16.093Z,1589571136.093 [Onboard](IMPORTANT): Battery Current:7.815000 mA 2020-05-15T19:32:16.094Z,1589571136.094 [Onboard](IMPORTANT): using main battery for universal 2020-05-15T19:32:16.094Z,1589571136.094 [Onboard](IMPORTANT): average counter: 4 2020-05-15T19:32:16.094Z,1589571136.094 [Onboard](IMPORTANT): average volt scaler: 1 2020-05-15T19:32:16.094Z,1589571136.094 [Onboard](IMPORTANT): average fg timeout scaler: 6 2020-05-15T19:32:26.189Z,1589571146.189 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V 2020-05-15T19:32:26.192Z,1589571146.192 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V 2020-05-15T19:32:26.198Z,1589571146.198 [Onboard](IMPORTANT): Battery Current:7.815000 mA 2020-05-15T19:32:26.198Z,1589571146.198 [Onboard](IMPORTANT): using main battery for universal 2020-05-15T19:32:26.198Z,1589571146.198 [Onboard](IMPORTANT): average counter: 5 2020-05-15T19:32:26.198Z,1589571146.198 [Onboard](IMPORTANT): average volt scaler: 1 2020-05-15T19:32:26.199Z,1589571146.199 [Onboard](IMPORTANT): average fg timeout scaler: 6 2020-05-15T19:32:26.199Z,1589571146.199 [Onboard](IMPORTANT): doing average stuff 2020-05-15T19:32:26.205Z,1589571146.205 [Onboard](IMPORTANT): main buf100000000 2020-05-15T19:32:26.210Z,1589571146.210 [Onboard](IMPORTANT): backup buf99999995 2020-05-15T19:32:26.210Z,1589571146.210 [Onboard](IMPORTANT): lastCharge-1.000000 2020-05-15T19:32:26.211Z,1589571146.211 [Onboard](IMPORTANT): charge200000000.000000 2020-05-15T19:32:26.211Z,1589571146.211 [Onboard](IMPORTANT): avgVoltageIdx:6 2020-05-15T19:32:26.211Z,1589571146.211 [Onboard](IMPORTANT): avgCurrent:52546.871094 2020-05-15T19:32:26.212Z,1589571146.212 [Onboard](IMPORTANT): Average Battery Power:808.276001 W 2020-05-15T19:32:36.365Z,1589571156.365 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V 2020-05-15T19:32:36.368Z,1589571156.368 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V 2020-05-15T19:32:36.374Z,1589571156.374 [Onboard](IMPORTANT): Battery Current:8.857000 mA 2020-05-15T19:32:36.374Z,1589571156.374 [Onboard](IMPORTANT): using main battery for universal 2020-05-15T19:32:36.375Z,1589571156.375 [Onboard](IMPORTANT): average counter: 0 2020-05-15T19:32:36.375Z,1589571156.375 [Onboard](IMPORTANT): average volt scaler: 1 2020-05-15T19:32:36.375Z,1589571156.375 [Onboard](IMPORTANT): average fg timeout scaler: 6 2020-05-15T19:32:40.024Z,1589571160.024 [SBIT](IMPORTANT): SBIT PASSED 2020-05-15T19:32:40.024Z,1589571160.024 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2020-05-15T19:32:40.025Z,1589571160.025 [SBIT](IMPORTANT): 2020-05-15T19:32:40.025Z,1589571160.025 [SBIT](IMPORTANT): AHRS_M2.loadAtStartup=0 bool; 2020-05-15T19:32:40.025Z,1589571160.025 [SBIT](IMPORTANT): CTD_Seabird.loadAtStartup=0 bool; 2020-05-15T19:32:40.027Z,1589571160.027 [SBIT](IMPORTANT): DVL_micro.loadAtStartup=0 bool; 2020-05-15T19:32:40.027Z,1589571160.027 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool; 2020-05-15T19:32:40.027Z,1589571160.027 [SBIT](IMPORTANT): ESPComponent.simulateHardware=0 bool; 2020-05-15T19:32:40.411Z,1589571160.411 [MissionManager](IMPORTANT): Started mission Startup 2020-05-15T19:32:40.411Z,1589571160.411 [Startup] Running Loop=1 2020-05-15T19:32:40.411Z,1589571160.411 [Startup](DEBUG): Aggregate::initialize Startup 2020-05-15T19:32:40.411Z,1589571160.411 [Startup:A.GoToSurface] Running Loop=1 2020-05-15T19:32:40.411Z,1589571160.411 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-05-15T19:32:40.412Z,1589571160.412 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-05-15T19:32:40.412Z,1589571160.412 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-05-15T19:32:40.413Z,1589571160.413 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-05-15T19:32:40.413Z,1589571160.413 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-05-15T19:32:40.413Z,1589571160.413 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-05-15T19:32:40.415Z,1589571160.415 [Startup:StartupSatComms] Running Loop=1 2020-05-15T19:32:40.415Z,1589571160.415 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2020-05-15T19:32:40.415Z,1589571160.415 [Startup:StartupSatComms:A] Running Loop=1 2020-05-15T19:32:40.814Z,1589571160.814 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2020-05-15T19:32:46.446Z,1589571166.446 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V 2020-05-15T19:32:46.449Z,1589571166.449 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V 2020-05-15T19:32:46.455Z,1589571166.455 [Onboard](IMPORTANT): Battery Current:7.294000 mA 2020-05-15T19:32:46.456Z,1589571166.456 [Onboard](IMPORTANT): using main battery for universal 2020-05-15T19:32:46.456Z,1589571166.456 [Onboard](IMPORTANT): average counter: 1 2020-05-15T19:32:46.456Z,1589571166.456 [Onboard](IMPORTANT): average volt scaler: 1 2020-05-15T19:32:46.456Z,1589571166.456 [Onboard](IMPORTANT): average fg timeout scaler: 6 2020-05-15T19:32:56.552Z,1589571176.552 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V 2020-05-15T19:32:56.555Z,1589571176.555 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V 2020-05-15T19:32:56.560Z,1589571176.560 [Onboard](IMPORTANT): Battery Current:7.294000 mA 2020-05-15T19:32:56.560Z,1589571176.560 [Onboard](IMPORTANT): using main battery for universal 2020-05-15T19:32:56.560Z,1589571176.560 [Onboard](IMPORTANT): average counter: 2 2020-05-15T19:32:56.561Z,1589571176.561 [Onboard](IMPORTANT): average volt scaler: 1 2020-05-15T19:32:56.561Z,1589571176.561 [Onboard](IMPORTANT): average fg timeout scaler: 6 2020-05-15T19:33:06.658Z,1589571186.658 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V 2020-05-15T19:33:06.661Z,1589571186.661 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V 2020-05-15T19:33:06.668Z,1589571186.668 [Onboard](IMPORTANT): Battery Current:7.815000 mA 2020-05-15T19:33:06.669Z,1589571186.669 [Onboard](IMPORTANT): using main battery for universal 2020-05-15T19:33:06.669Z,1589571186.669 [Onboard](IMPORTANT): average counter: 3 2020-05-15T19:33:06.669Z,1589571186.669 [Onboard](IMPORTANT): average volt scaler: 1 2020-05-15T19:33:06.669Z,1589571186.669 [Onboard](IMPORTANT): average fg timeout scaler: 6 2020-05-15T19:33:16.751Z,1589571196.751 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V 2020-05-15T19:33:16.754Z,1589571196.754 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V 2020-05-15T19:33:16.760Z,1589571196.760 [Onboard](IMPORTANT): Battery Current:7.294000 mA 2020-05-15T19:33:16.760Z,1589571196.760 [Onboard](IMPORTANT): using main battery for universal 2020-05-15T19:33:16.760Z,1589571196.760 [Onboard](IMPORTANT): average counter: 4 2020-05-15T19:33:16.761Z,1589571196.761 [Onboard](IMPORTANT): average volt scaler: 1 2020-05-15T19:33:16.761Z,1589571196.761 [Onboard](IMPORTANT): average fg timeout scaler: 6 2020-05-15T19:33:25.242Z,1589571205.242 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2020-05-15T19:33:25.242Z,1589571205.242 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2020-05-15T19:33:25.242Z,1589571205.242 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-05-15T19:33:25.252Z,1589571205.252 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-05-15T19:33:25.657Z,1589571205.657 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-05-15T19:33:25.657Z,1589571205.657 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2020-05-15T19:33:26.850Z,1589571206.850 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V 2020-05-15T19:33:26.854Z,1589571206.854 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V 2020-05-15T19:33:26.892Z,1589571206.892 [Onboard](IMPORTANT): Battery Current:7.815000 mA 2020-05-15T19:33:26.892Z,1589571206.892 [Onboard](IMPORTANT): using main battery for universal 2020-05-15T19:33:26.892Z,1589571206.892 [Onboard](IMPORTANT): average counter: 5 2020-05-15T19:33:26.892Z,1589571206.892 [Onboard](IMPORTANT): average volt scaler: 1 2020-05-15T19:33:26.893Z,1589571206.893 [Onboard](IMPORTANT): average fg timeout scaler: 6 2020-05-15T19:33:26.893Z,1589571206.893 [Onboard](IMPORTANT): doing average stuff 2020-05-15T19:33:26.913Z,1589571206.913 [Onboard](IMPORTANT): main buf100000000 2020-05-15T19:33:26.932Z,1589571206.932 [Onboard](IMPORTANT): backup buf99999995 2020-05-15T19:33:26.933Z,1589571206.933 [Onboard](IMPORTANT): lastCharge200000000.000000 2020-05-15T19:33:26.933Z,1589571206.933 [Onboard](IMPORTANT): charge200000000.000000 2020-05-15T19:33:26.934Z,1589571206.934 [Onboard](IMPORTANT): Average Battery Current:0.000000 mA 2020-05-15T19:33:26.934Z,1589571206.934 [Onboard](IMPORTANT): avgVoltageIdx:6 2020-05-15T19:33:26.934Z,1589571206.934 [Onboard](IMPORTANT): avgCurrent:0.000000 2020-05-15T19:33:26.934Z,1589571206.934 [Onboard](IMPORTANT): Average Battery Power:0.000000 W 2020-05-15T19:33:36.952Z,1589571216.952 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V 2020-05-15T19:33:36.955Z,1589571216.955 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V 2020-05-15T19:33:36.961Z,1589571216.961 [Onboard](IMPORTANT): Battery Current:7.815000 mA 2020-05-15T19:33:36.961Z,1589571216.961 [Onboard](IMPORTANT): using main battery for universal 2020-05-15T19:33:36.961Z,1589571216.961 [Onboard](IMPORTANT): average counter: 0 2020-05-15T19:33:36.962Z,1589571216.962 [Onboard](IMPORTANT): average volt scaler: 1 2020-05-15T19:33:36.962Z,1589571216.962 [Onboard](IMPORTANT): average fg timeout scaler: 6 2020-05-15T19:33:40.613Z,1589571220.613 [Startup:StartupSatComms:A](INFO): Timed out from 2020-05-15T19:32:40.4Z 2020-05-15T19:33:40.613Z,1589571220.613 [Startup:StartupSatComms:A] Stopped 2020-05-15T19:33:40.613Z,1589571220.613 [Startup:StartupSatComms:B] Running Loop=1 2020-05-15T19:33:41.005Z,1589571221.005 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2020-05-15T19:33:47.053Z,1589571227.053 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V 2020-05-15T19:33:47.056Z,1589571227.056 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V 2020-05-15T19:33:47.062Z,1589571227.062 [Onboard](IMPORTANT): Battery Current:7.815000 mA 2020-05-15T19:33:47.062Z,1589571227.062 [Onboard](IMPORTANT): using main battery for universal 2020-05-15T19:33:47.063Z,1589571227.063 [Onboard](IMPORTANT): average counter: 1 2020-05-15T19:33:47.063Z,1589571227.063 [Onboard](IMPORTANT): average volt scaler: 1 2020-05-15T19:33:47.063Z,1589571227.063 [Onboard](IMPORTANT): average fg timeout scaler: 6 2020-05-15T19:33:52.943Z,1589571232.943 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004755 2020-05-15T19:33:57.153Z,1589571237.153 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V 2020-05-15T19:33:57.157Z,1589571237.157 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V 2020-05-15T19:33:57.163Z,1589571237.163 [Onboard](IMPORTANT): Battery Current:7.815000 mA 2020-05-15T19:33:57.163Z,1589571237.163 [Onboard](IMPORTANT): using main battery for universal 2020-05-15T19:33:57.163Z,1589571237.163 [Onboard](IMPORTANT): average counter: 2 2020-05-15T19:33:57.163Z,1589571237.163 [Onboard](IMPORTANT): average volt scaler: 1 2020-05-15T19:33:57.164Z,1589571237.164 [Onboard](IMPORTANT): average fg timeout scaler: 6 2020-05-15T19:34:07.247Z,1589571247.247 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V 2020-05-15T19:34:07.250Z,1589571247.250 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V 2020-05-15T19:34:07.256Z,1589571247.256 [Onboard](IMPORTANT): Battery Current:7.815000 mA 2020-05-15T19:34:07.256Z,1589571247.256 [Onboard](IMPORTANT): using main battery for universal 2020-05-15T19:34:07.257Z,1589571247.257 [Onboard](IMPORTANT): average counter: 3 2020-05-15T19:34:07.257Z,1589571247.257 [Onboard](IMPORTANT): average volt scaler: 1 2020-05-15T19:34:07.257Z,1589571247.257 [Onboard](IMPORTANT): average fg timeout scaler: 6 2020-05-15T19:34:17.360Z,1589571257.360 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V 2020-05-15T19:34:17.364Z,1589571257.364 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V 2020-05-15T19:34:17.370Z,1589571257.370 [Onboard](IMPORTANT): Battery Current:7.815000 mA 2020-05-15T19:34:17.370Z,1589571257.370 [Onboard](IMPORTANT): using main battery for universal 2020-05-15T19:34:17.370Z,1589571257.370 [Onboard](IMPORTANT): average counter: 4 2020-05-15T19:34:17.370Z,1589571257.370 [Onboard](IMPORTANT): average volt scaler: 1 2020-05-15T19:34:17.371Z,1589571257.371 [Onboard](IMPORTANT): average fg timeout scaler: 6 2020-05-15T19:34:27.464Z,1589571267.464 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V 2020-05-15T19:34:27.467Z,1589571267.467 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V 2020-05-15T19:34:27.473Z,1589571267.473 [Onboard](IMPORTANT): Battery Current:8.336000 mA 2020-05-15T19:34:27.474Z,1589571267.474 [Onboard](IMPORTANT): using main battery for universal 2020-05-15T19:34:27.474Z,1589571267.474 [Onboard](IMPORTANT): average counter: 5 2020-05-15T19:34:27.474Z,1589571267.474 [Onboard](IMPORTANT): average volt scaler: 1 2020-05-15T19:34:27.474Z,1589571267.474 [Onboard](IMPORTANT): average fg timeout scaler: 6 2020-05-15T19:34:27.474Z,1589571267.474 [Onboard](IMPORTANT): doing average stuff 2020-05-15T19:34:27.480Z,1589571267.480 [Onboard](IMPORTANT): main buf100000000 2020-05-15T19:34:27.485Z,1589571267.485 [Onboard](IMPORTANT): backup buf99999995 2020-05-15T19:34:27.485Z,1589571267.485 [Onboard](IMPORTANT): lastCharge200000000.000000 2020-05-15T19:34:27.486Z,1589571267.486 [Onboard](IMPORTANT): charge200000000.000000 2020-05-15T19:34:27.486Z,1589571267.486 [Onboard](IMPORTANT): Average Battery Current:0.000000 mA 2020-05-15T19:34:27.486Z,1589571267.486 [Onboard](IMPORTANT): avgVoltageIdx:6 2020-05-15T19:34:27.486Z,1589571267.486 [Onboard](IMPORTANT): avgCurrent:0.000000 2020-05-15T19:34:27.487Z,1589571267.487 [Onboard](IMPORTANT): Average Battery Power:0.000000 W 2020-05-15T19:34:37.564Z,1589571277.564 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V 2020-05-15T19:34:37.568Z,1589571277.568 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V 2020-05-15T19:34:37.573Z,1589571277.573 [Onboard](IMPORTANT): Battery Current:7.815000 mA 2020-05-15T19:34:37.574Z,1589571277.574 [Onboard](IMPORTANT): using main battery for universal 2020-05-15T19:34:37.574Z,1589571277.574 [Onboard](IMPORTANT): average counter: 0 2020-05-15T19:34:37.574Z,1589571277.574 [Onboard](IMPORTANT): average volt scaler: 1 2020-05-15T19:34:37.574Z,1589571277.574 [Onboard](IMPORTANT): average fg timeout scaler: 6 2020-05-15T19:34:40.811Z,1589571280.811 [Startup:StartupSatComms:B](INFO): Timed out from 2020-05-15T19:33:40.6Z 2020-05-15T19:34:40.811Z,1589571280.811 [Startup:StartupSatComms:B] Stopped 2020-05-15T19:34:40.815Z,1589571280.815 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2020-05-15T19:34:40.816Z,1589571280.816 [Startup:StartupSatComms] Stopped 2020-05-15T19:34:40.816Z,1589571280.816 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2020-05-15T19:34:40.816Z,1589571280.816 [Startup](INFO): Completed Startup 2020-05-15T19:34:40.817Z,1589571280.817 [MissionManager](INFO): Startup is completed. 2020-05-15T19:34:40.817Z,1589571280.817 [MissionManager](INFO): Uninitializing Mission Startup 2020-05-15T19:34:40.817Z,1589571280.817 [Startup] Stopped 2020-05-15T19:34:40.817Z,1589571280.817 [Startup](DEBUG): Aggregate::uninitialize Startup 2020-05-15T19:34:40.817Z,1589571280.817 [Startup:A.GoToSurface] Stopped 2020-05-15T19:34:40.817Z,1589571280.817 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-05-15T19:34:41.319Z,1589571281.319 [MissionManager](IMPORTANT): Started mission Default 2020-05-15T19:34:41.319Z,1589571281.319 [Default] Running Loop=1 2020-05-15T19:34:41.320Z,1589571281.320 [Default](DEBUG): Aggregate::initialize Default 2020-05-15T19:34:41.320Z,1589571281.320 [Default:B.GoToSurface] Running Loop=1 2020-05-15T19:34:41.320Z,1589571281.320 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-05-15T19:34:41.320Z,1589571281.320 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-05-15T19:34:41.320Z,1589571281.320 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-05-15T19:34:41.321Z,1589571281.321 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-05-15T19:34:41.321Z,1589571281.321 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-05-15T19:34:41.321Z,1589571281.321 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-05-15T19:34:41.321Z,1589571281.321 [Default:A.Wait] Running Loop=1 2020-05-15T19:34:41.321Z,1589571281.321 [Default:A.Wait](DEBUG): Initialize Wait Component. 2020-05-15T19:34:43.221Z,1589571283.221 [DAT](INFO): unknown deviceResponse_: Lowpower 2020-05-15T19:34:47.665Z,1589571287.665 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V 2020-05-15T19:34:47.669Z,1589571287.669 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V 2020-05-15T19:34:47.675Z,1589571287.675 [Onboard](IMPORTANT): Battery Current:7.294000 mA 2020-05-15T19:34:47.675Z,1589571287.675 [Onboard](IMPORTANT): using main battery for universal 2020-05-15T19:34:47.675Z,1589571287.675 [Onboard](IMPORTANT): average counter: 1 2020-05-15T19:34:47.675Z,1589571287.675 [Onboard](IMPORTANT): average volt scaler: 1 2020-05-15T19:34:47.676Z,1589571287.676 [Onboard](IMPORTANT): average fg timeout scaler: 6 2020-05-15T19:34:54.522Z,1589571294.522 [Default:A.Wait](INFO): Done Waiting. 2020-05-15T19:34:54.523Z,1589571294.523 [Default:A.Wait] Stopped 2020-05-15T19:34:54.523Z,1589571294.523 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2020-05-15T19:34:54.928Z,1589571294.928 [Default:CheckIn] Running Loop=1 2020-05-15T19:34:54.928Z,1589571294.928 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-05-15T19:34:54.928Z,1589571294.928 [Default:CheckIn:Read_GPS] Running Loop=1 2020-05-15T19:34:55.388Z,1589571295.388 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2020-05-15T19:34:57.753Z,1589571297.753 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V 2020-05-15T19:34:57.756Z,1589571297.756 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V 2020-05-15T19:34:57.762Z,1589571297.762 [Onboard](IMPORTANT): Battery Current:7.294000 mA 2020-05-15T19:34:57.762Z,1589571297.762 [Onboard](IMPORTANT): using main battery for universal 2020-05-15T19:34:57.762Z,1589571297.762 [Onboard](IMPORTANT): average counter: 2 2020-05-15T19:34:57.762Z,1589571297.762 [Onboard](IMPORTANT): average volt scaler: 1 2020-05-15T19:34:57.763Z,1589571297.763 [Onboard](IMPORTANT): average fg timeout scaler: 6 2020-05-15T19:35:07.850Z,1589571307.850 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V 2020-05-15T19:35:07.853Z,1589571307.853 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V 2020-05-15T19:35:07.894Z,1589571307.894 [Onboard](IMPORTANT): Battery Current:7.294000 mA 2020-05-15T19:35:07.894Z,1589571307.894 [Onboard](IMPORTANT): using main battery for universal 2020-05-15T19:35:07.894Z,1589571307.894 [Onboard](IMPORTANT): average counter: 3 2020-05-15T19:35:07.894Z,1589571307.894 [Onboard](IMPORTANT): average volt scaler: 1 2020-05-15T19:35:07.895Z,1589571307.895 [Onboard](IMPORTANT): average fg timeout scaler: 6 2020-05-15T19:35:17.951Z,1589571317.951 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V 2020-05-15T19:35:17.955Z,1589571317.955 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V 2020-05-15T19:35:17.960Z,1589571317.960 [Onboard](IMPORTANT): Battery Current:7.294000 mA 2020-05-15T19:35:17.961Z,1589571317.961 [Onboard](IMPORTANT): using main battery for universal 2020-05-15T19:35:17.961Z,1589571317.961 [Onboard](IMPORTANT): average counter: 4 2020-05-15T19:35:17.961Z,1589571317.961 [Onboard](IMPORTANT): average volt scaler: 1 2020-05-15T19:35:17.961Z,1589571317.961 [Onboard](IMPORTANT): average fg timeout scaler: 6 2020-05-15T19:35:26.438Z,1589571326.438 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2020-05-15T19:35:26.438Z,1589571326.438 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2020-05-15T19:35:26.438Z,1589571326.438 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-05-15T19:35:26.449Z,1589571326.449 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-05-15T19:35:26.864Z,1589571326.864 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-05-15T19:35:26.864Z,1589571326.864 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2020-05-15T19:35:28.050Z,1589571328.050 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V 2020-05-15T19:35:28.054Z,1589571328.054 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V 2020-05-15T19:35:28.060Z,1589571328.060 [Onboard](IMPORTANT): Battery Current:7.815000 mA 2020-05-15T19:35:28.061Z,1589571328.061 [Onboard](IMPORTANT): using main battery for universal 2020-05-15T19:35:28.061Z,1589571328.061 [Onboard](IMPORTANT): average counter: 5 2020-05-15T19:35:28.061Z,1589571328.061 [Onboard](IMPORTANT): average volt scaler: 1 2020-05-15T19:35:28.061Z,1589571328.061 [Onboard](IMPORTANT): average fg timeout scaler: 6 2020-05-15T19:35:28.061Z,1589571328.061 [Onboard](IMPORTANT): doing average stuff 2020-05-15T19:35:28.067Z,1589571328.067 [Onboard](IMPORTANT): main buf100000000 2020-05-15T19:35:28.072Z,1589571328.072 [Onboard](IMPORTANT): backup buf99999995 2020-05-15T19:35:28.073Z,1589571328.073 [Onboard](IMPORTANT): lastCharge200000000.000000 2020-05-15T19:35:28.073Z,1589571328.073 [Onboard](IMPORTANT): charge200000000.000000 2020-05-15T19:35:28.073Z,1589571328.073 [Onboard](IMPORTANT): Average Battery Current:0.000000 mA 2020-05-15T19:35:28.073Z,1589571328.073 [Onboard](IMPORTANT): avgVoltageIdx:6 2020-05-15T19:35:28.074Z,1589571328.074 [Onboard](IMPORTANT): avgCurrent:0.000000 2020-05-15T19:35:28.074Z,1589571328.074 [Onboard](IMPORTANT): Average Battery Power:0.000000 W 2020-05-15T19:35:38.151Z,1589571338.151 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V 2020-05-15T19:35:38.154Z,1589571338.154 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V 2020-05-15T19:35:38.160Z,1589571338.160 [Onboard](IMPORTANT): Battery Current:7.294000 mA 2020-05-15T19:35:38.160Z,1589571338.160 [Onboard](IMPORTANT): using main battery for universal 2020-05-15T19:35:38.160Z,1589571338.160 [Onboard](IMPORTANT): average counter: 0 2020-05-15T19:35:38.161Z,1589571338.161 [Onboard](IMPORTANT): average volt scaler: 1 2020-05-15T19:35:38.161Z,1589571338.161 [Onboard](IMPORTANT): average fg timeout scaler: 6 2020-05-15T19:35:48.250Z,1589571348.250 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V 2020-05-15T19:35:48.274Z,1589571348.274 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V 2020-05-15T19:35:48.280Z,1589571348.280 [Onboard](IMPORTANT): Battery Current:7.294000 mA 2020-05-15T19:35:48.280Z,1589571348.280 [Onboard](IMPORTANT): using main battery for universal 2020-05-15T19:35:48.281Z,1589571348.281 [Onboard](IMPORTANT): average counter: 1 2020-05-15T19:35:48.281Z,1589571348.281 [Onboard](IMPORTANT): average volt scaler: 1 2020-05-15T19:35:48.281Z,1589571348.281 [Onboard](IMPORTANT): average fg timeout scaler: 6 2020-05-15T19:35:58.346Z,1589571358.346 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V 2020-05-15T19:35:58.350Z,1589571358.350 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V 2020-05-15T19:35:58.356Z,1589571358.356 [Onboard](IMPORTANT): Battery Current:7.815000 mA 2020-05-15T19:35:58.356Z,1589571358.356 [Onboard](IMPORTANT): using main battery for universal 2020-05-15T19:35:58.356Z,1589571358.356 [Onboard](IMPORTANT): average counter: 2 2020-05-15T19:35:58.357Z,1589571358.357 [Onboard](IMPORTANT): average volt scaler: 1 2020-05-15T19:35:58.357Z,1589571358.357 [Onboard](IMPORTANT): average fg timeout scaler: 6 2020-05-15T19:36:08.450Z,1589571368.450 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V 2020-05-15T19:36:08.453Z,1589571368.453 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V 2020-05-15T19:36:08.459Z,1589571368.459 [Onboard](IMPORTANT): Battery Current:7.294000 mA 2020-05-15T19:36:08.459Z,1589571368.459 [Onboard](IMPORTANT): using main battery for universal 2020-05-15T19:36:08.459Z,1589571368.459 [Onboard](IMPORTANT): average counter: 3 2020-05-15T19:36:08.460Z,1589571368.460 [Onboard](IMPORTANT): average volt scaler: 1 2020-05-15T19:36:08.460Z,1589571368.460 [Onboard](IMPORTANT): average fg timeout scaler: 6 2020-05-15T19:36:18.552Z,1589571378.552 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V 2020-05-15T19:36:18.555Z,1589571378.555 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V 2020-05-15T19:36:18.561Z,1589571378.561 [Onboard](IMPORTANT): Battery Current:7.294000 mA 2020-05-15T19:36:18.562Z,1589571378.562 [Onboard](IMPORTANT): using main battery for universal 2020-05-15T19:36:18.562Z,1589571378.562 [Onboard](IMPORTANT): average counter: 4 2020-05-15T19:36:18.562Z,1589571378.562 [Onboard](IMPORTANT): average volt scaler: 1 2020-05-15T19:36:18.562Z,1589571378.562 [Onboard](IMPORTANT): average fg timeout scaler: 6 2020-05-15T19:36:28.665Z,1589571388.665 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V 2020-05-15T19:36:28.668Z,1589571388.668 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V 2020-05-15T19:36:28.674Z,1589571388.674 [Onboard](IMPORTANT): Battery Current:7.294000 mA 2020-05-15T19:36:28.674Z,1589571388.674 [Onboard](IMPORTANT): using main battery for universal 2020-05-15T19:36:28.675Z,1589571388.675 [Onboard](IMPORTANT): average counter: 5 2020-05-15T19:36:28.675Z,1589571388.675 [Onboard](IMPORTANT): average volt scaler: 1 2020-05-15T19:36:28.675Z,1589571388.675 [Onboard](IMPORTANT): average fg timeout scaler: 6 2020-05-15T19:36:28.675Z,1589571388.675 [Onboard](IMPORTANT): doing average stuff 2020-05-15T19:36:28.680Z,1589571388.680 [Onboard](IMPORTANT): main buf100000000 2020-05-15T19:36:28.686Z,1589571388.686 [Onboard](IMPORTANT): backup buf99999995 2020-05-15T19:36:28.686Z,1589571388.686 [Onboard](IMPORTANT): lastCharge200000000.000000 2020-05-15T19:36:28.686Z,1589571388.686 [Onboard](IMPORTANT): charge200000000.000000 2020-05-15T19:36:28.687Z,1589571388.687 [Onboard](IMPORTANT): Average Battery Current:0.000000 mA 2020-05-15T19:36:28.687Z,1589571388.687 [Onboard](IMPORTANT): avgVoltageIdx:6 2020-05-15T19:36:28.687Z,1589571388.687 [Onboard](IMPORTANT): avgCurrent:0.000000 2020-05-15T19:36:28.688Z,1589571388.688 [Onboard](IMPORTANT): Average Battery Power:0.000000 W 2020-05-15T19:36:38.768Z,1589571398.768 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V 2020-05-15T19:36:38.771Z,1589571398.771 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V 2020-05-15T19:36:38.777Z,1589571398.777 [Onboard](IMPORTANT): Battery Current:7.294000 mA 2020-05-15T19:36:38.778Z,1589571398.778 [Onboard](IMPORTANT): using main battery for universal 2020-05-15T19:36:38.778Z,1589571398.778 [Onboard](IMPORTANT): average counter: 0 2020-05-15T19:36:38.778Z,1589571398.778 [Onboard](IMPORTANT): average volt scaler: 1 2020-05-15T19:36:38.778Z,1589571398.778 [Onboard](IMPORTANT): average fg timeout scaler: 6 2020-05-15T19:36:48.860Z,1589571408.860 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V 2020-05-15T19:36:48.864Z,1589571408.864 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V 2020-05-15T19:36:48.901Z,1589571408.901 [Onboard](IMPORTANT): Battery Current:7.294000 mA 2020-05-15T19:36:48.901Z,1589571408.901 [Onboard](IMPORTANT): using main battery for universal 2020-05-15T19:36:48.901Z,1589571408.901 [Onboard](IMPORTANT): average counter: 1 2020-05-15T19:36:48.902Z,1589571408.902 [Onboard](IMPORTANT): average volt scaler: 1 2020-05-15T19:36:48.902Z,1589571408.902 [Onboard](IMPORTANT): average fg timeout scaler: 6 2020-05-15T19:36:58.964Z,1589571418.964 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V 2020-05-15T19:36:58.967Z,1589571418.967 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V 2020-05-15T19:36:58.973Z,1589571418.973 [Onboard](IMPORTANT): Battery Current:7.294000 mA 2020-05-15T19:36:58.973Z,1589571418.973 [Onboard](IMPORTANT): using main battery for universal 2020-05-15T19:36:58.974Z,1589571418.974 [Onboard](IMPORTANT): average counter: 2 2020-05-15T19:36:58.974Z,1589571418.974 [Onboard](IMPORTANT): average volt scaler: 1 2020-05-15T19:36:58.974Z,1589571418.974 [Onboard](IMPORTANT): average fg timeout scaler: 6 2020-05-15T19:37:05.809Z,1589571425.809 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-05-15T19:37:09.062Z,1589571429.062 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V 2020-05-15T19:37:09.066Z,1589571429.066 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V 2020-05-15T19:37:09.072Z,1589571429.072 [Onboard](IMPORTANT): Battery Current:7.815000 mA 2020-05-15T19:37:09.072Z,1589571429.072 [Onboard](IMPORTANT): using main battery for universal 2020-05-15T19:37:09.072Z,1589571429.072 [Onboard](IMPORTANT): average counter: 3 2020-05-15T19:37:09.072Z,1589571429.072 [Onboard](IMPORTANT): average volt scaler: 1 2020-05-15T19:37:09.072Z,1589571429.072 [Onboard](IMPORTANT): average fg timeout scaler: 6 2020-05-15T19:37:19.151Z,1589571439.151 [Onboard](IMPORTANT): Main Battery Voltage:15.382000 V 2020-05-15T19:37:19.172Z,1589571439.172 [Onboard](IMPORTANT): Backup Battery Voltage:15.382000 V 2020-05-15T19:37:19.178Z,1589571439.178 [Onboard](IMPORTANT): Battery Current:7.294000 mA 2020-05-15T19:37:19.178Z,1589571439.178 [Onboard](IMPORTANT): using main battery for universal 2020-05-15T19:37:19.179Z,1589571439.179 [Onboard](IMPORTANT): average counter: 4 2020-05-15T19:37:19.179Z,1589571439.179 [Onboard](IMPORTANT): average volt scaler: 1 2020-05-15T19:37:19.179Z,1589571439.179 [Onboard](IMPORTANT): average fg timeout scaler: 6 2020-05-15T19:37:25.600Z,1589571445.600 [CommandLine](IMPORTANT): got command restart application 2020-05-15T19:37:26.611Z,1589571446.611 [Supervisor](INFO): Stop Mission called by Supervisor::terminate 2020-05-15T19:37:26.612Z,1589571446.612 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2020-05-15T19:37:26.612Z,1589571446.612 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-05-15T19:37:26.655Z,1589571446.655 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2020-05-15T19:37:26.656Z,1589571446.656 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-05-15T19:37:26.656Z,1589571446.656 [CommandLine](INFO): Join timeout helper Thread ID is 4549 2020-05-15T19:37:26.657Z,1589571446.657 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2020-05-15T19:37:26.657Z,1589571446.657 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-05-15T19:37:26.657Z,1589571446.657 [NavChartDb](INFO): Join timeout helper Thread ID is 4550 2020-05-15T19:37:27.036Z,1589571447.036 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2020-05-15T19:37:27.036Z,1589571447.036 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-05-15T19:37:27.055Z,1589571447.055 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2020-05-15T19:37:27.055Z,1589571447.055 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-05-15T19:37:27.056Z,1589571447.056 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4551 2020-05-15T19:37:27.288Z,1589571447.288 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2020-05-15T19:37:27.288Z,1589571447.288 [WetLabsBB2FL](INFO): Powering down 2020-05-15T19:37:27.289Z,1589571447.289 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-05-15T19:37:27.296Z,1589571447.296 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2020-05-15T19:37:27.296Z,1589571447.296 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-05-15T19:37:27.296Z,1589571447.296 [Radio_Surface](INFO): Join timeout helper Thread ID is 4552 2020-05-15T19:37:27.307Z,1589571447.307 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2020-05-15T19:37:27.307Z,1589571447.307 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2020-05-15T19:37:27.307Z,1589571447.307 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-05-15T19:37:27.317Z,1589571447.317 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-05-15T19:37:27.361Z,1589571447.361 [Radio_Surface](INFO): Powering down 2020-05-15T19:37:27.361Z,1589571447.361 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2020-05-15T19:37:27.362Z,1589571447.362 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-05-15T19:37:27.379Z,1589571447.379 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2020-05-15T19:37:27.380Z,1589571447.380 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-05-15T19:37:27.380Z,1589571447.380 [DataOverHttps](INFO): Join timeout helper Thread ID is 4553 2020-05-15T19:37:27.654Z,1589571447.654 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-05-15T19:37:27.654Z,1589571447.654 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2020-05-15T19:37:27.768Z,1589571447.768 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2020-05-15T19:37:27.768Z,1589571447.768 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-05-15T19:37:27.784Z,1589571447.784 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2020-05-15T19:37:27.784Z,1589571447.784 [logger ThreadHandler](INFO): Thread cancelled. 2020-05-15T19:37:27.785Z,1589571447.785 [logger](INFO): Join timeout helper Thread ID is 4554 2020-05-15T19:37:27.812Z,1589571447.812 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2020-05-15T19:37:27.812Z,1589571447.812 [logger ThreadHandler](INFO): Thread cancelled. 2020-05-15T19:37:27.824Z,1589571447.824 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2020-05-15T19:37:27.824Z,1589571447.824 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-05-15T19:37:27.824Z,1589571447.824 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2020-05-15T19:37:27.825Z,1589571447.825 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-05-15T19:37:27.825Z,1589571447.825 [controlThread](INFO): Join timeout helper Thread ID is 4555 2020-05-15T19:37:28.016Z,1589571448.016 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2020-05-15T19:37:28.016Z,1589571448.016 [controlThread](DEBUG): Uninitializing ControlThread 2020-05-15T19:37:28.017Z,1589571448.017 [NAL9602](INFO): Powering down 2020-05-15T19:37:28.018Z,1589571448.018 [DAT](INFO): Powering down 2020-05-15T19:37:28.156Z,1589571448.156 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2020-05-15T19:37:28.157Z,1589571448.157 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2020-05-15T19:37:28.157Z,1589571448.157 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2020-05-15T19:37:28.158Z,1589571448.158 [MissionManager](INFO): Uninitializing Mission Default 2020-05-15T19:37:28.158Z,1589571448.158 [Default] Stopped 2020-05-15T19:37:28.158Z,1589571448.158 [Default](DEBUG): Aggregate::uninitialize Default 2020-05-15T19:37:28.158Z,1589571448.158 [Default:B.GoToSurface] Stopped 2020-05-15T19:37:28.158Z,1589571448.158 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-05-15T19:37:28.158Z,1589571448.158 [Default:CheckIn] Stopped 2020-05-15T19:37:28.158Z,1589571448.158 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-05-15T19:37:28.159Z,1589571448.159 [Default:CheckIn:Read_GPS] Stopped 2020-05-15T19:37:28.161Z,1589571448.161 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2020-05-15T19:37:28.161Z,1589571448.161 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2020-05-15T19:37:28.162Z,1589571448.162 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2020-05-15T19:37:28.162Z,1589571448.162 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2020-05-15T19:37:28.162Z,1589571448.162 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2020-05-15T19:37:28.162Z,1589571448.162 [BuoyancyServo](INFO): Powering down 2020-05-15T19:37:28.175Z,1589571448.175 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2020-05-15T19:37:28.176Z,1589571448.176 [ElevatorServo](INFO): Powering down 2020-05-15T19:37:28.176Z,1589571448.176 [MassServo](DEBUG): Uninitialize Mass Servo. 2020-05-15T19:37:28.176Z,1589571448.176 [MassServo](INFO): Powering down 2020-05-15T19:37:28.177Z,1589571448.177 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-05-15T19:37:28.177Z,1589571448.177 [RudderServo](INFO): Powering down 2020-05-15T19:37:28.178Z,1589571448.178 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2020-05-15T19:37:28.178Z,1589571448.178 [ThrusterServo](INFO): Powering down 2020-05-15T19:37:28.179Z,1589571448.179 [SBIT](DEBUG): Uninitialize SBIT Component. 2020-05-15T19:37:28.179Z,1589571448.179 [IBIT](DEBUG): Uninitialize IBIT Component. 2020-05-15T19:37:28.180Z,1589571448.180 [CBIT](DEBUG): Uninitialize CBIT Component. 2020-05-15T19:37:28.180Z,1589571448.180 [CBIT](DEBUG): Powering off loads. 2020-05-15T19:37:28.191Z,1589571448.191 [CBIT](DEBUG): Disabling WDT. 2020-05-15T19:37:28.203Z,1589571448.203 [CBIT](DEBUG): Opening all GF detection circuits. 2020-05-15T19:37:28.204Z,1589571448.204 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-05-15T19:37:28.290Z,1589571448.290 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-05-15T19:37:28.297Z,1589571448.297 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-05-15T19:37:28.353Z,1589571448.353 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-05-15T19:37:28.379Z,1589571448.379 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-05-15T19:37:28.435Z,1589571448.435 [logger ThreadHandler](INFO): Thread cancelled.