2020-03-06T19:35:54.180Z,1583523354.180 [Supervisor](DEBUG): Initializing supervisor. 2020-03-06T19:35:54.183Z,1583523354.183 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2020-03-06T19:35:54.183Z,1583523354.183 [SyncHandler](INFO): Protected caller Thread ID is 7176 2020-03-06T19:35:54.184Z,1583523354.184 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2020-03-06T19:35:54.185Z,1583523354.185 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2020-03-06T19:35:54.185Z,1583523354.185 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 7177 2020-03-06T19:35:54.188Z,1583523354.188 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2020-03-06T19:35:54.200Z,1583523354.200 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2020-03-06T19:35:54.201Z,1583523354.201 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2020-03-06T19:35:54.202Z,1583523354.202 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 7178 2020-03-06T19:35:54.202Z,1583523354.202 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2020-03-06T19:35:54.203Z,1583523354.203 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2020-03-06T19:35:54.204Z,1583523354.204 [logger ThreadHandler](INFO): Protected caller Thread ID is 7179 2020-03-06T19:35:54.206Z,1583523354.206 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2020-03-06T19:35:54.206Z,1583523354.206 [Supervisor](INFO): Looking for Config files in directory: Config/ 2020-03-06T19:35:54.208Z,1583523354.208 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2020-03-06T19:35:54.364Z,1583523354.364 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2020-03-06T19:35:54.365Z,1583523354.365 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2020-03-06T19:35:54.560Z,1583523354.560 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2020-03-06T19:35:54.560Z,1583523354.560 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2020-03-06T19:35:54.693Z,1583523354.693 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2020-03-06T19:35:54.694Z,1583523354.694 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2020-03-06T19:35:55.267Z,1583523355.267 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2020-03-06T19:35:55.267Z,1583523355.267 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2020-03-06T19:35:55.716Z,1583523355.716 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2020-03-06T19:35:55.717Z,1583523355.717 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2020-03-06T19:35:56.172Z,1583523356.172 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2020-03-06T19:35:56.172Z,1583523356.172 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2020-03-06T19:35:56.463Z,1583523356.463 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2020-03-06T19:35:56.464Z,1583523356.464 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2020-03-06T19:35:56.776Z,1583523356.776 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2020-03-06T19:35:56.776Z,1583523356.776 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2020-03-06T19:35:57.178Z,1583523357.178 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2020-03-06T19:35:57.179Z,1583523357.179 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2020-03-06T19:35:57.323Z,1583523357.323 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2020-03-06T19:35:57.323Z,1583523357.323 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2020-03-06T19:35:57.425Z,1583523357.425 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2020-03-06T19:35:57.426Z,1583523357.426 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2020-03-06T19:35:57.504Z,1583523357.504 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2020-03-06T19:35:57.603Z,1583523357.603 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2020-03-06T19:35:57.603Z,1583523357.603 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2020-03-06T19:35:57.862Z,1583523357.862 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2020-03-06T19:35:57.862Z,1583523357.862 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2020-03-06T19:35:58.072Z,1583523358.072 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2020-03-06T19:35:58.074Z,1583523358.074 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/ 2020-03-06T19:35:58.075Z,1583523358.075 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg 2020-03-06T19:35:58.156Z,1583523358.156 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg 2020-03-06T19:35:58.384Z,1583523358.384 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2020-03-06T19:35:58.384Z,1583523358.384 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg 2020-03-06T19:35:58.478Z,1583523358.478 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg 2020-03-06T19:35:58.640Z,1583523358.640 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg 2020-03-06T19:35:58.841Z,1583523358.841 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg 2020-03-06T19:35:58.942Z,1583523358.942 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg 2020-03-06T19:35:59.241Z,1583523359.241 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg 2020-03-06T19:35:59.481Z,1583523359.481 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg 2020-03-06T19:35:59.757Z,1583523359.757 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg 2020-03-06T19:36:00.002Z,1583523360.002 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg 2020-03-06T19:36:00.099Z,1583523360.099 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/ 2020-03-06T19:36:00.100Z,1583523360.100 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2020-03-06T19:36:00.105Z,1583523360.105 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2020-03-06T19:36:00.204Z,1583523360.204 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2020-03-06T19:36:00.205Z,1583523360.205 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2020-03-06T19:36:00.315Z,1583523360.315 [BuoyancyServo] Loaded 2020-03-06T19:36:00.315Z,1583523360.315 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2020-03-06T19:36:00.331Z,1583523360.331 [ElevatorServo] Loaded 2020-03-06T19:36:00.331Z,1583523360.331 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2020-03-06T19:36:00.347Z,1583523360.347 [MassServo] Loaded 2020-03-06T19:36:00.347Z,1583523360.347 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2020-03-06T19:36:00.362Z,1583523360.362 [RudderServo] Loaded 2020-03-06T19:36:00.362Z,1583523360.362 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2020-03-06T19:36:00.377Z,1583523360.377 [ThrusterServo] Loaded 2020-03-06T19:36:00.377Z,1583523360.377 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2020-03-06T19:36:00.377Z,1583523360.377 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2020-03-06T19:36:00.378Z,1583523360.378 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2020-03-06T19:36:00.516Z,1583523360.516 [SBIT](DEBUG): Construct Startup Built In Test. 2020-03-06T19:36:00.545Z,1583523360.545 [SBIT] Loaded 2020-03-06T19:36:00.545Z,1583523360.545 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2020-03-06T19:36:00.546Z,1583523360.546 [IBIT](DEBUG): Construct Initiated Built In Test. 2020-03-06T19:36:00.575Z,1583523360.575 [IBIT] Loaded 2020-03-06T19:36:00.576Z,1583523360.576 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2020-03-06T19:36:00.579Z,1583523360.579 [CBIT](DEBUG): Construct Continuous Built In Test. 2020-03-06T19:36:00.980Z,1583523360.980 [CBIT] Loaded 2020-03-06T19:36:00.980Z,1583523360.980 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2020-03-06T19:36:00.981Z,1583523360.981 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2020-03-06T19:36:00.981Z,1583523360.981 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2020-03-06T19:36:01.174Z,1583523361.174 [Aanderaa_O2] Loaded 2020-03-06T19:36:01.174Z,1583523361.174 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2020-03-06T19:36:01.184Z,1583523361.184 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2020-03-06T19:36:01.189Z,1583523361.189 [CTD_Seabird](INFO): created writer for : sea_water_density 2020-03-06T19:36:01.191Z,1583523361.191 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2020-03-06T19:36:01.196Z,1583523361.196 [CTD_Seabird](INFO): created writer for : depth 2020-03-06T19:36:01.196Z,1583523361.196 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2020-03-06T19:36:01.202Z,1583523361.202 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2020-03-06T19:36:01.202Z,1583523361.202 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2020-03-06T19:36:01.207Z,1583523361.207 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2020-03-06T19:36:01.208Z,1583523361.208 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2020-03-06T19:36:01.213Z,1583523361.213 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2020-03-06T19:36:01.214Z,1583523361.214 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2020-03-06T19:36:01.220Z,1583523361.220 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2020-03-06T19:36:01.220Z,1583523361.220 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2020-03-06T19:36:01.225Z,1583523361.225 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2020-03-06T19:36:01.253Z,1583523361.253 [CTD_Seabird] Loaded 2020-03-06T19:36:01.253Z,1583523361.253 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2020-03-06T19:36:01.254Z,1583523361.254 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4066C4E0 2020-03-06T19:36:01.255Z,1583523361.255 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 7259 2020-03-06T19:36:01.286Z,1583523361.286 [ESPComponent] Loaded 2020-03-06T19:36:01.286Z,1583523361.286 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2020-03-06T19:36:01.300Z,1583523361.300 [PAR_Licor] Loaded 2020-03-06T19:36:01.301Z,1583523361.301 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2020-03-06T19:36:01.307Z,1583523361.307 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470 2020-03-06T19:36:01.307Z,1583523361.307 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470 2020-03-06T19:36:01.311Z,1583523361.311 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650 2020-03-06T19:36:01.312Z,1583523361.312 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650 2020-03-06T19:36:01.316Z,1583523361.316 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl 2020-03-06T19:36:01.316Z,1583523361.316 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl 2020-03-06T19:36:01.321Z,1583523361.321 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm 2020-03-06T19:36:01.321Z,1583523361.321 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm 2020-03-06T19:36:01.325Z,1583523361.325 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm 2020-03-06T19:36:01.325Z,1583523361.325 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm 2020-03-06T19:36:01.330Z,1583523361.330 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm 2020-03-06T19:36:01.330Z,1583523361.330 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm 2020-03-06T19:36:01.334Z,1583523361.334 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm 2020-03-06T19:36:01.335Z,1583523361.335 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm 2020-03-06T19:36:01.339Z,1583523361.339 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water 2020-03-06T19:36:01.344Z,1583523361.344 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water 2020-03-06T19:36:01.344Z,1583523361.344 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-03-06T19:36:01.344Z,1583523361.344 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-03-06T19:36:01.349Z,1583523361.349 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-03-06T19:36:01.349Z,1583523361.349 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-03-06T19:36:01.353Z,1583523361.353 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-03-06T19:36:01.353Z,1583523361.353 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-03-06T19:36:01.358Z,1583523361.358 [WetLabsBB2FL] Loaded 2020-03-06T19:36:01.358Z,1583523361.358 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2020-03-06T19:36:01.359Z,1583523361.359 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4069C4E0 2020-03-06T19:36:01.359Z,1583523361.359 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 7260 2020-03-06T19:36:01.360Z,1583523361.360 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2020-03-06T19:36:01.361Z,1583523361.361 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2020-03-06T19:36:01.402Z,1583523361.402 [DepthRateCalculator] Loaded 2020-03-06T19:36:01.402Z,1583523361.402 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2020-03-06T19:36:01.408Z,1583523361.408 [PitchRateCalculator] Loaded 2020-03-06T19:36:01.408Z,1583523361.408 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2020-03-06T19:36:01.424Z,1583523361.424 [SpeedCalculator] Loaded 2020-03-06T19:36:01.425Z,1583523361.425 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2020-03-06T19:36:01.446Z,1583523361.446 [TempGradientCalculator] Loaded 2020-03-06T19:36:01.446Z,1583523361.446 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2020-03-06T19:36:01.452Z,1583523361.452 [YawRateCalculator] Loaded 2020-03-06T19:36:01.452Z,1583523361.452 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2020-03-06T19:36:01.486Z,1583523361.486 [ElevatorOffsetCalculator] Loaded 2020-03-06T19:36:01.486Z,1583523361.486 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2020-03-06T19:36:01.487Z,1583523361.487 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2020-03-06T19:36:01.487Z,1583523361.487 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2020-03-06T19:36:01.520Z,1583523361.520 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2020-03-06T19:36:01.520Z,1583523361.520 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2020-03-06T19:36:01.624Z,1583523361.624 [DeadReckonUsingMultipleVelocitySources] Loaded 2020-03-06T19:36:01.624Z,1583523361.624 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2020-03-06T19:36:01.645Z,1583523361.645 [NavChart] Loaded 2020-03-06T19:36:01.645Z,1583523361.645 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2020-03-06T19:36:01.649Z,1583523361.649 [UniversalFixResidualReporter] Loaded 2020-03-06T19:36:01.650Z,1583523361.650 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2020-03-06T19:36:01.650Z,1583523361.650 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2020-03-06T19:36:01.651Z,1583523361.651 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2020-03-06T19:36:01.942Z,1583523361.942 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation 2020-03-06T19:36:01.947Z,1583523361.947 [AHRS_M2](INFO): created writer for : platform_orientation 2020-03-06T19:36:01.947Z,1583523361.947 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation 2020-03-06T19:36:01.953Z,1583523361.953 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation 2020-03-06T19:36:01.953Z,1583523361.953 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle 2020-03-06T19:36:01.958Z,1583523361.958 [AHRS_M2](INFO): created writer for : platform_pitch_angle 2020-03-06T19:36:01.959Z,1583523361.959 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle 2020-03-06T19:36:01.964Z,1583523361.964 [AHRS_M2](INFO): created writer for : platform_roll_angle 2020-03-06T19:36:02.039Z,1583523362.039 [AHRS_M2] Loaded 2020-03-06T19:36:02.039Z,1583523362.039 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2020-03-06T19:36:02.118Z,1583523362.118 [DataOverHttps] Loaded 2020-03-06T19:36:02.118Z,1583523362.118 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2020-03-06T19:36:02.120Z,1583523362.120 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4098B4E0 2020-03-06T19:36:02.120Z,1583523362.120 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 7261 2020-03-06T19:36:02.133Z,1583523362.133 [Depth_Keller] Loaded 2020-03-06T19:36:02.134Z,1583523362.134 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2020-03-06T19:36:02.139Z,1583523362.139 [DropWeight] Loaded 2020-03-06T19:36:02.139Z,1583523362.139 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2020-03-06T19:36:02.187Z,1583523362.187 [DVL_micro] Loaded 2020-03-06T19:36:02.187Z,1583523362.187 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2020-03-06T19:36:02.281Z,1583523362.281 [NAL9602] Loaded 2020-03-06T19:36:02.281Z,1583523362.281 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2020-03-06T19:36:02.311Z,1583523362.311 [Onboard] Loaded 2020-03-06T19:36:02.311Z,1583523362.311 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2020-03-06T19:36:02.314Z,1583523362.314 [Radio_Surface] Loaded 2020-03-06T19:36:02.315Z,1583523362.315 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2020-03-06T19:36:02.316Z,1583523362.316 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409BB4E0 2020-03-06T19:36:02.316Z,1583523362.316 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 7262 2020-03-06T19:36:02.455Z,1583523362.455 [DAT] Loaded 2020-03-06T19:36:02.455Z,1583523362.455 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2020-03-06T19:36:03.787Z,1583523363.787 [BPC1] Loaded 2020-03-06T19:36:03.787Z,1583523363.787 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2020-03-06T19:36:03.788Z,1583523363.788 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2020-03-06T19:36:03.788Z,1583523363.788 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2020-03-06T19:36:03.871Z,1583523363.871 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2020-03-06T19:36:03.872Z,1583523363.872 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2020-03-06T19:36:03.920Z,1583523363.920 [VerticalControl](DEBUG): Construct VerticalControl. 2020-03-06T19:36:04.006Z,1583523364.006 [VerticalControl] Loaded 2020-03-06T19:36:04.007Z,1583523364.007 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2020-03-06T19:36:04.007Z,1583523364.007 [HorizontalControl](DEBUG): Construct HorizontalControl. 2020-03-06T19:36:04.080Z,1583523364.080 [HorizontalControl] Loaded 2020-03-06T19:36:04.081Z,1583523364.081 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2020-03-06T19:36:04.081Z,1583523364.081 [SpeedControl](DEBUG): Construct SpeedControl. 2020-03-06T19:36:04.083Z,1583523364.083 [SpeedControl] Loaded 2020-03-06T19:36:04.083Z,1583523364.083 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2020-03-06T19:36:04.084Z,1583523364.084 [LoopControl](DEBUG): Construct LoopControl. 2020-03-06T19:36:04.084Z,1583523364.084 [LoopControl] Loaded 2020-03-06T19:36:04.085Z,1583523364.085 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2020-03-06T19:36:04.085Z,1583523364.085 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2020-03-06T19:36:04.086Z,1583523364.086 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2020-03-06T19:36:04.121Z,1583523364.121 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2020-03-06T19:36:04.121Z,1583523364.121 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2020-03-06T19:36:04.627Z,1583523364.627 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2020-03-06T19:36:04.631Z,1583523364.631 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2020-03-06T19:36:04.632Z,1583523364.632 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2020-03-06T19:36:04.639Z,1583523364.639 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2020-03-06T19:36:04.640Z,1583523364.640 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AFA4E0 2020-03-06T19:36:04.640Z,1583523364.640 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 7263 2020-03-06T19:36:04.645Z,1583523364.645 [Supervisor](INFO): Main Thread ID is 7175 2020-03-06T19:36:04.645Z,1583523364.645 [Supervisor](DEBUG): Running supervisor. 2020-03-06T19:36:04.646Z,1583523364.646 [CommandLine ThreadHandler](INFO): Handler Thread ID is 7264 2020-03-06T19:36:04.648Z,1583523364.648 [controlThread ThreadHandler](INFO): Handler Thread ID is 7265 2020-03-06T19:36:04.649Z,1583523364.649 [controlThread](DEBUG): Initializing ControlThread 2020-03-06T19:36:04.650Z,1583523364.650 [SBIT](INFO): Initialize SBIT Component. 2020-03-06T19:36:04.651Z,1583523364.651 [SBIT](IMPORTANT): git: 2020-02-26-4-ge87e28be4 2020-03-06T19:36:04.651Z,1583523364.651 [SBIT](INFO): git hash: e87e28be4f0b70038b19136bd27a833bbcc93acc 2020-03-06T19:36:04.651Z,1583523364.651 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2020-03-06T19:36:04.653Z,1583523364.653 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018 Kernel Reported: #1 PREEMPT Wed Nov 13 12:38:10 PST 2019 2020-03-06T19:36:04.654Z,1583523364.654 [SBIT](INFO): Beginning SBIT in 72.000000 seconds. 2020-03-06T19:36:04.655Z,1583523364.655 [IBIT](INFO): Initialize IBIT Component. 2020-03-06T19:36:04.655Z,1583523364.655 [CBIT](DEBUG): Initialize CBIT Component. 2020-03-06T19:36:04.656Z,1583523364.656 [logger ThreadHandler](INFO): Handler Thread ID is 7266 2020-03-06T19:36:04.669Z,1583523364.669 [CBIT](DEBUG): Initialized mux pins. 2020-03-06T19:36:04.669Z,1583523364.669 [CBIT](DEBUG): Initializing the watchdog timer. 2020-03-06T19:36:04.681Z,1583523364.681 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 7267 2020-03-06T19:36:04.682Z,1583523364.682 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2020-03-06T19:36:04.685Z,1583523364.685 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2020-03-06T19:36:04.686Z,1583523364.686 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 7269 2020-03-06T19:36:04.687Z,1583523364.687 [WetLabsBB2FL](INFO): Powering down 2020-03-06T19:36:04.693Z,1583523364.693 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2020-03-06T19:36:04.693Z,1583523364.693 [CBIT](DEBUG): Initializing heartbeat. 2020-03-06T19:36:04.702Z,1583523364.702 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 7270 2020-03-06T19:36:04.714Z,1583523364.714 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2020-03-06T19:36:04.721Z,1583523364.721 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 7271 2020-03-06T19:36:04.739Z,1583523364.739 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 7272 2020-03-06T19:36:04.742Z,1583523364.742 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2020-03-06T19:36:04.743Z,1583523364.743 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2020-03-06T19:36:04.743Z,1583523364.743 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2020-03-06T19:36:04.743Z,1583523364.743 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2020-03-06T19:36:04.743Z,1583523364.743 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2020-03-06T19:36:04.743Z,1583523364.743 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2020-03-06T19:36:04.744Z,1583523364.744 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2020-03-06T19:36:04.744Z,1583523364.744 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2020-03-06T19:36:04.744Z,1583523364.744 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2020-03-06T19:36:04.744Z,1583523364.744 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2020-03-06T19:36:04.744Z,1583523364.744 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2020-03-06T19:36:04.745Z,1583523364.745 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2020-03-06T19:36:04.745Z,1583523364.745 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2020-03-06T19:36:04.745Z,1583523364.745 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2020-03-06T19:36:04.746Z,1583523364.746 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2020-03-06T19:36:04.746Z,1583523364.746 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2020-03-06T19:36:04.765Z,1583523364.765 [CBIT](DEBUG): Deactivating GF circuits. 2020-03-06T19:36:04.765Z,1583523364.765 [CBIT](DEBUG): Deactivating emergency mode. 2020-03-06T19:36:04.801Z,1583523364.801 [CBIT](DEBUG): Backplane powered. 2020-03-06T19:36:04.805Z,1583523364.805 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2020-03-06T19:36:04.805Z,1583523364.805 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2020-03-06T19:36:04.806Z,1583523364.806 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2020-03-06T19:36:04.806Z,1583523364.806 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2020-03-06T19:36:04.807Z,1583523364.807 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2020-03-06T19:36:04.807Z,1583523364.807 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2020-03-06T19:36:04.808Z,1583523364.808 [NavChart](DEBUG): Initialize NavChart Navigation. 2020-03-06T19:36:04.809Z,1583523364.809 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2020-03-06T19:36:04.816Z,1583523364.816 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2020-03-06T19:36:04.826Z,1583523364.826 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2020-03-06T19:36:04.827Z,1583523364.827 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2020-03-06T19:36:04.828Z,1583523364.828 [LoopControl](DEBUG): Initialize LoopControlComponent. 2020-03-06T19:36:04.828Z,1583523364.828 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2020-03-06T19:36:04.863Z,1583523364.863 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2020-03-06T19:36:04.887Z,1583523364.887 [MissionManager](DEBUG): 2020-03-06T19:36:04.888Z,1583523364.888 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2020-03-06T19:36:04.951Z,1583523364.951 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2020-03-06T19:36:04.952Z,1583523364.952 [Default:A.Wait](DEBUG): Construct Wait. 2020-03-06T19:36:04.978Z,1583523364.978 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2020-03-06T19:36:05.018Z,1583523365.018 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2020-03-06T19:36:05.021Z,1583523365.021 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2020-03-06T19:36:05.035Z,1583523365.035 [Default:E.Execute](DEBUG): Construct Execute. 2020-03-06T19:36:05.038Z,1583523365.038 [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-03-06T19:36:05.053Z,1583523365.053 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,DAT,BPC1,PAR_Licor,Depth_Keller,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-03-06T19:36:05.125Z,1583523365.125 [Radio_Surface](INFO): Powering up 2020-03-06T19:36:05.178Z,1583523365.178 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2020-03-06T19:36:05.351Z,1583523365.351 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2020-03-06T19:36:05.351Z,1583523365.351 [DAT](INFO): Powering up 2020-03-06T19:36:05.351Z,1583523365.351 [DAT](DEBUG): Initializing DAT. 2020-03-06T19:36:05.439Z,1583523365.439 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2020-03-06T19:36:05.445Z,1583523365.445 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2020-03-06T19:36:05.446Z,1583523365.446 [ElevatorServo](DEBUG): Initializing EZServoServo. 2020-03-06T19:36:05.449Z,1583523365.449 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2020-03-06T19:36:05.477Z,1583523365.477 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2020-03-06T19:36:05.478Z,1583523365.478 [MassServo](DEBUG): Initializing EZServoServo. 2020-03-06T19:36:05.489Z,1583523365.489 [MassServo](DEBUG): Initializing MassServo. 2020-03-06T19:36:05.490Z,1583523365.490 [RudderServo](DEBUG): Initializing EZServoServo. 2020-03-06T19:36:05.501Z,1583523365.501 [RudderServo](DEBUG): Initializing RudderServo. 2020-03-06T19:36:05.502Z,1583523365.502 [ThrusterServo](DEBUG): Initializing EZServoServo. 2020-03-06T19:36:05.513Z,1583523365.513 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2020-03-06T19:36:05.631Z,1583523365.631 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2020-03-06T19:36:05.631Z,1583523365.631 [DropWeight] Hardware Fault, FailCount= 1 2020-03-06T19:36:05.631Z,1583523365.631 [DropWeight](ERROR): Hardware Fault 2020-03-06T19:36:05.705Z,1583523365.705 [CommandLine](FAULT): Scheduling is paused 2020-03-06T19:36:05.706Z,1583523365.706 [CBIT](INFO): Critical error at 20200306T193605 2020-03-06T19:36:05.706Z,1583523365.706 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2020-03-06T19:36:05.709Z,1583523365.709 [CBIT](ERROR): Hardware Fault in component: DropWeight 2020-03-06T19:36:05.710Z,1583523365.710 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2020-03-06T19:36:05.992Z,1583523365.992 [CBIT](INFO): Critical error at 20200306T193605 2020-03-06T19:36:08.685Z,1583523368.685 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:09.149Z,1583523369.149 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:09.681Z,1583523369.681 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:10.165Z,1583523370.165 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:10.797Z,1583523370.797 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:11.237Z,1583523371.237 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:11.933Z,1583523371.933 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:12.381Z,1583523372.381 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:12.933Z,1583523372.933 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:13.369Z,1583523373.369 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:13.821Z,1583523373.821 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:14.297Z,1583523374.297 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:14.721Z,1583523374.721 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:15.161Z,1583523375.161 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:15.274Z,1583523375.274 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2020-03-06T19:36:15.274Z,1583523375.274 [Aanderaa_O2] Communications Fault, FailCount= 1 2020-03-06T19:36:15.274Z,1583523375.274 [Aanderaa_O2](ERROR): Communications Fault 2020-03-06T19:36:15.597Z,1583523375.597 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:15.697Z,1583523375.697 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2020-03-06T19:36:15.821Z,1583523375.821 [Aanderaa_O2](INFO): Powering down 2020-03-06T19:36:16.193Z,1583523376.193 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:16.625Z,1583523376.625 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:16.645Z,1583523376.645 [DAT](DEBUG): 2020-03-06T19:36:16.646Z,1583523376.646 [DAT](DEBUG): Teledyne Benthos DAT-900 Series 2020-03-06T19:36:17.061Z,1583523377.061 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:17.150Z,1583523377.150 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2020-03-06T19:36:17.150Z,1583523377.150 [Aanderaa_O2] No Fault, FailCount= 1 2020-03-06T19:36:17.521Z,1583523377.521 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:17.737Z,1583523377.737 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2020-03-06T19:36:17.737Z,1583523377.737 [CTD_Seabird] Communications Fault, FailCount= 1 2020-03-06T19:36:17.737Z,1583523377.737 [CTD_Seabird](ERROR): Communications Fault 2020-03-06T19:36:17.981Z,1583523377.981 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:17.981Z,1583523377.981 [AHRS_M2](FAULT): Failed to initialize within timeout. 2020-03-06T19:36:17.981Z,1583523377.981 [AHRS_M2] Communications Fault, FailCount= 1 2020-03-06T19:36:17.981Z,1583523377.981 [AHRS_M2](ERROR): Communications Fault 2020-03-06T19:36:18.006Z,1583523378.006 [DAT](DEBUG): MF Frequency Band 2020-03-06T19:36:18.085Z,1583523378.085 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2020-03-06T19:36:18.085Z,1583523378.085 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2020-03-06T19:36:18.100Z,1583523378.100 [AHRS_M2](INFO): Powering down 2020-03-06T19:36:18.141Z,1583523378.141 [CTD_Seabird](INFO): Powering down 2020-03-06T19:36:18.311Z,1583523378.311 [DAT](DEBUG): Directional Acoustic Transponder version 8.14.0 2020-03-06T19:36:18.312Z,1583523378.312 [DAT](DEBUG): Mar 6 2020 18:26:14 2020-03-06T19:36:18.973Z,1583523378.973 [CBIT](INFO): Clearing failed state for component AHRS_M2 2020-03-06T19:36:18.973Z,1583523378.973 [AHRS_M2] No Fault, FailCount= 1 2020-03-06T19:36:19.317Z,1583523379.317 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2020-03-06T19:36:19.377Z,1583523379.377 [DAT](DEBUG): Features enabled [Bearing] 2020-03-06T19:36:19.378Z,1583523379.378 [DAT](DEBUG): CONNECT 00800 bits/sec 2020-03-06T19:36:19.378Z,1583523379.378 [DAT](INFO): commRate: 800 2020-03-06T19:36:19.429Z,1583523379.429 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2020-03-06T19:36:19.429Z,1583523379.429 [CTD_Seabird] No Fault, FailCount= 1 2020-03-06T19:36:19.549Z,1583523379.549 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2020-03-06T19:36:19.551Z,1583523379.551 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2020-03-06T19:36:19.729Z,1583523379.729 [DAT](INFO): entering command mode 2020-03-06T19:36:20.133Z,1583523380.133 [DAT](DEBUG): checking for command mode acknowledgment 2020-03-06T19:36:20.537Z,1583523380.537 [DAT](DEBUG): checking for command mode acknowledgment 2020-03-06T19:36:20.537Z,1583523380.537 [DAT](INFO): command mode acknowledged 2020-03-06T19:36:20.537Z,1583523380.537 [DAT](INFO): setting verbose to 3 2020-03-06T19:36:20.941Z,1583523380.941 [DAT](DEBUG): checking for verbose setting acknowledgment 2020-03-06T19:36:20.942Z,1583523380.942 [DAT](INFO): set verbose to 3 2020-03-06T19:36:21.345Z,1583523381.345 [DAT](INFO): setting transmit power to 8 2020-03-06T19:36:21.749Z,1583523381.749 [DAT](DEBUG): checking for transmit power setting acknowledgment 2020-03-06T19:36:21.882Z,1583523381.882 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004601 2020-03-06T19:36:22.152Z,1583523382.152 [DAT](DEBUG): checking for transmit power setting acknowledgment 2020-03-06T19:36:22.153Z,1583523382.153 [DAT](INFO): set transmit power to 8 2020-03-06T19:36:22.861Z,1583523382.861 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:22.866Z,1583523382.866 [DVL_micro](ERROR): No DVL communication! Re-initializing 2020-03-06T19:36:22.866Z,1583523382.866 [DVL_micro] Communications Fault, FailCount= 1 2020-03-06T19:36:22.866Z,1583523382.866 [DVL_micro](ERROR): Communications Fault 2020-03-06T19:36:22.883Z,1583523382.883 [DAT](INFO): setting local address to 4 2020-03-06T19:36:22.946Z,1583523382.946 [CBIT](ERROR): Communications Fault in component: DVL_micro 2020-03-06T19:36:23.317Z,1583523383.317 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:23.393Z,1583523383.393 [DVL_micro](INFO): Powering down 2020-03-06T19:36:23.409Z,1583523383.409 [DAT](DEBUG): checking for local address setting acknowledgment 2020-03-06T19:36:23.893Z,1583523383.893 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:23.913Z,1583523383.913 [DAT](DEBUG): checking for local address setting acknowledgment 2020-03-06T19:36:23.914Z,1583523383.914 [DAT](INFO): set local address to 4 2020-03-06T19:36:24.337Z,1583523384.337 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:24.461Z,1583523384.461 [CBIT](INFO): Clearing failed state for component DVL_micro 2020-03-06T19:36:24.461Z,1583523384.461 [DVL_micro] No Fault, FailCount= 1 2020-03-06T19:36:24.797Z,1583523384.797 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:25.241Z,1583523385.241 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:25.725Z,1583523385.725 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:26.189Z,1583523386.189 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:26.617Z,1583523386.617 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:27.073Z,1583523387.073 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:27.184Z,1583523387.184 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2020-03-06T19:36:27.184Z,1583523387.184 [Aanderaa_O2] Communications Fault, FailCount= 2 2020-03-06T19:36:27.184Z,1583523387.184 [Aanderaa_O2](ERROR): Communications Fault 2020-03-06T19:36:27.509Z,1583523387.509 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:27.609Z,1583523387.609 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2020-03-06T19:36:27.745Z,1583523387.745 [Aanderaa_O2](INFO): Powering down 2020-03-06T19:36:28.077Z,1583523388.077 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:28.501Z,1583523388.501 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:28.945Z,1583523388.945 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:29.046Z,1583523389.046 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2020-03-06T19:36:29.046Z,1583523389.046 [Aanderaa_O2] No Fault, FailCount= 2 2020-03-06T19:36:29.385Z,1583523389.385 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:29.830Z,1583523389.830 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:30.273Z,1583523390.273 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:30.697Z,1583523390.697 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:31.117Z,1583523391.117 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:31.545Z,1583523391.545 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:31.997Z,1583523391.997 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:32.437Z,1583523392.437 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:32.437Z,1583523392.437 [AHRS_M2](FAULT): Failed to initialize within timeout. 2020-03-06T19:36:32.437Z,1583523392.437 [AHRS_M2] Communications Fault, FailCount= 2 2020-03-06T19:36:32.437Z,1583523392.437 [AHRS_M2](ERROR): Communications Fault 2020-03-06T19:36:32.443Z,1583523392.443 [NAL9602](INFO): Powering up NAL9602 2020-03-06T19:36:32.560Z,1583523392.560 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2020-03-06T19:36:32.577Z,1583523392.577 [AHRS_M2](INFO): Powering down 2020-03-06T19:36:32.609Z,1583523392.609 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2020-03-06T19:36:32.609Z,1583523392.609 [CTD_Seabird] Communications Fault, FailCount= 2 2020-03-06T19:36:32.609Z,1583523392.609 [CTD_Seabird](ERROR): Communications Fault 2020-03-06T19:36:32.746Z,1583523392.746 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2020-03-06T19:36:33.013Z,1583523393.013 [CTD_Seabird](INFO): Powering down 2020-03-06T19:36:33.481Z,1583523393.481 [CBIT](INFO): Clearing failed state for component AHRS_M2 2020-03-06T19:36:33.481Z,1583523393.481 [AHRS_M2] No Fault, FailCount= 2 2020-03-06T19:36:33.781Z,1583523393.781 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2020-03-06T19:36:34.277Z,1583523394.277 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2020-03-06T19:36:34.277Z,1583523394.277 [CTD_Seabird] No Fault, FailCount= 2 2020-03-06T19:36:34.357Z,1583523394.357 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2020-03-06T19:36:34.365Z,1583523394.365 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2020-03-06T19:36:37.337Z,1583523397.337 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:37.781Z,1583523397.781 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:38.221Z,1583523398.221 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:38.665Z,1583523398.665 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:39.101Z,1583523399.101 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:39.234Z,1583523399.234 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2020-03-06T19:36:39.235Z,1583523399.235 [Aanderaa_O2] Communications Fault, FailCount= 3 2020-03-06T19:36:39.235Z,1583523399.235 [Aanderaa_O2](ERROR): Communications Fault 2020-03-06T19:36:39.569Z,1583523399.569 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:39.673Z,1583523399.673 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2020-03-06T19:36:39.674Z,1583523399.674 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2020-03-06T19:36:39.797Z,1583523399.797 [Aanderaa_O2](INFO): Powering down 2020-03-06T19:36:40.117Z,1583523400.117 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:40.569Z,1583523400.569 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:41.013Z,1583523401.013 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:41.549Z,1583523401.549 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:42.009Z,1583523402.009 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:42.015Z,1583523402.015 [DVL_micro](ERROR): No DVL communication! Re-initializing 2020-03-06T19:36:42.015Z,1583523402.015 [DVL_micro] Communications Fault, FailCount= 2 2020-03-06T19:36:42.015Z,1583523402.015 [DVL_micro](ERROR): Communications Fault 2020-03-06T19:36:42.100Z,1583523402.100 [CBIT](ERROR): Communications Fault in component: DVL_micro 2020-03-06T19:36:42.481Z,1583523402.481 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:42.557Z,1583523402.557 [DVL_micro](INFO): Powering down 2020-03-06T19:36:43.013Z,1583523403.013 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:43.465Z,1583523403.465 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:43.470Z,1583523403.470 [NAL9602](INFO): NAL9602 initialized 2020-03-06T19:36:43.553Z,1583523403.553 [CBIT](INFO): Clearing failed state for component DVL_micro 2020-03-06T19:36:43.553Z,1583523403.553 [DVL_micro] No Fault, FailCount= 2 2020-03-06T19:36:43.941Z,1583523403.941 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:44.385Z,1583523404.385 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:44.869Z,1583523404.869 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:45.329Z,1583523405.329 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:45.781Z,1583523405.781 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:46.273Z,1583523406.273 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:46.741Z,1583523406.741 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:46.741Z,1583523406.741 [AHRS_M2](FAULT): Failed to initialize within timeout. 2020-03-06T19:36:46.741Z,1583523406.741 [AHRS_M2] Communications Fault, FailCount= 3 2020-03-06T19:36:46.741Z,1583523406.741 [AHRS_M2](ERROR): Communications Fault 2020-03-06T19:36:46.835Z,1583523406.835 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2020-03-06T19:36:46.886Z,1583523406.886 [AHRS_M2](INFO): Powering down 2020-03-06T19:36:47.409Z,1583523407.409 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2020-03-06T19:36:47.409Z,1583523407.409 [CTD_Seabird] Communications Fault, FailCount= 3 2020-03-06T19:36:47.409Z,1583523407.409 [CTD_Seabird](ERROR): Communications Fault 2020-03-06T19:36:47.723Z,1583523407.723 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2020-03-06T19:36:47.723Z,1583523407.723 [CBIT](FAULT): Communications Fault in component: CTD_Seabird 2020-03-06T19:36:47.723Z,1583523407.723 [CBIT](INFO): Clearing failed state for component AHRS_M2 2020-03-06T19:36:47.723Z,1583523407.723 [AHRS_M2] No Fault, FailCount= 3 2020-03-06T19:36:47.813Z,1583523407.813 [CTD_Seabird](INFO): Powering down 2020-03-06T19:36:48.062Z,1583523408.062 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2020-03-06T19:36:51.621Z,1583523411.621 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:52.065Z,1583523412.065 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:52.513Z,1583523412.513 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:52.961Z,1583523412.961 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:53.385Z,1583523413.385 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:53.821Z,1583523413.821 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:54.249Z,1583523414.249 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:54.685Z,1583523414.685 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:55.125Z,1583523415.125 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:55.573Z,1583523415.573 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:56.013Z,1583523416.013 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:56.477Z,1583523416.477 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:56.933Z,1583523416.933 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:57.381Z,1583523417.381 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:57.821Z,1583523417.821 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:58.269Z,1583523418.269 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:58.701Z,1583523418.701 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:59.133Z,1583523419.133 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:36:59.573Z,1583523419.573 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:37:00.021Z,1583523420.021 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:37:00.465Z,1583523420.465 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:37:00.897Z,1583523420.897 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:37:00.897Z,1583523420.897 [AHRS_M2](FAULT): Failed to initialize within timeout. 2020-03-06T19:37:00.897Z,1583523420.897 [AHRS_M2] Communications Fault, FailCount= 4 2020-03-06T19:37:00.897Z,1583523420.897 [AHRS_M2](ERROR): Communications Fault 2020-03-06T19:37:00.980Z,1583523420.980 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2020-03-06T19:37:01.045Z,1583523421.045 [AHRS_M2](INFO): Powering down 2020-03-06T19:37:01.818Z,1583523421.818 [DVL_micro](ERROR): No DVL communication! Re-initializing 2020-03-06T19:37:01.818Z,1583523421.818 [DVL_micro] Communications Fault, FailCount= 3 2020-03-06T19:37:01.818Z,1583523421.818 [DVL_micro](ERROR): Communications Fault 2020-03-06T19:37:01.901Z,1583523421.901 [CBIT](INFO): Clearing failed state for component AHRS_M2 2020-03-06T19:37:01.901Z,1583523421.901 [AHRS_M2] No Fault, FailCount= 4 2020-03-06T19:37:01.902Z,1583523421.902 [CBIT](ERROR): Communications Fault in component: DVL_micro 2020-03-06T19:37:01.902Z,1583523421.902 [CBIT](CRITICAL): Communications Fault in component: DVL_micro 2020-03-06T19:37:02.209Z,1583523422.209 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2020-03-06T19:37:02.289Z,1583523422.289 [DVL_micro](INFO): Powering down 2020-03-06T19:37:02.358Z,1583523422.358 [CBIT](INFO): Critical error at 20200306T193701 2020-03-06T19:37:05.765Z,1583523425.765 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:37:06.189Z,1583523426.189 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:37:06.677Z,1583523426.677 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:37:07.141Z,1583523427.141 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:37:07.597Z,1583523427.597 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:37:08.057Z,1583523428.057 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:37:08.513Z,1583523428.513 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:37:08.961Z,1583523428.961 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:37:09.401Z,1583523429.401 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:37:09.841Z,1583523429.841 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:37:10.281Z,1583523430.281 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:37:10.713Z,1583523430.713 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:37:11.149Z,1583523431.149 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:37:11.605Z,1583523431.605 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:37:12.029Z,1583523432.029 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:37:12.469Z,1583523432.469 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:37:12.981Z,1583523432.981 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:37:13.481Z,1583523433.481 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:37:13.949Z,1583523433.949 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:37:14.421Z,1583523434.421 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:37:14.853Z,1583523434.853 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:37:15.277Z,1583523435.277 [AHRS_M2](ERROR): setBoresightMatrix UART error: serial timeout 2020-03-06T19:37:15.277Z,1583523435.277 [AHRS_M2](FAULT): Failed to initialize within timeout. 2020-03-06T19:37:15.277Z,1583523435.277 [AHRS_M2] Communications Fault, FailCount= 5 2020-03-06T19:37:15.277Z,1583523435.277 [AHRS_M2](ERROR): Communications Fault 2020-03-06T19:37:15.315Z,1583523435.315 [CBIT](ERROR): Communications Fault in component: AHRS_M2 2020-03-06T19:37:15.315Z,1583523435.315 [CBIT](CRITICAL): Communications Fault in component: AHRS_M2 2020-03-06T19:37:15.381Z,1583523435.381 [AHRS_M2](INFO): Powering down 2020-03-06T19:37:15.499Z,1583523435.499 [CBIT](INFO): Critical error at 20200306T193715 2020-03-06T19:37:17.378Z,1583523437.378 [SBIT](IMPORTANT): Beginning Startup BIT 2020-03-06T19:37:17.382Z,1583523437.382 [CBIT](IMPORTANT): Beginning ground fault scan 2020-03-06T19:37:28.522Z,1583523448.522 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.002803 CHAN A1 (24V): -0.001725 CHAN A2 (12V): -0.005432 CHAN A3 (5V): -0.002699 CHAN B0 (3.3V): -0.001540 CHAN B1 (3.15aV): -0.001193 CHAN B2 (3.15bV): -0.000816 CHAN B3 (GND): 0.000470 OPEN: 0.007040 Full Scale Calc: 4.765 mA, -1.589 mA 2020-03-06T19:37:42.346Z,1583523462.346 [CommandLine](IMPORTANT): got command show variable offset 2020-03-06T19:37:42.378Z,1583523462.378 [CommandLine](IMPORTANT): AHRS_3DMGX3.pitchOffset (degree) 2020-03-06T19:37:42.379Z,1583523462.379 [CommandLine](IMPORTANT): AHRS_3DMGX3.rollOffset (degree) 2020-03-06T19:37:42.380Z,1583523462.380 [CommandLine](IMPORTANT): AHRS_sp3003D.pitchOffset (degree) 2020-03-06T19:37:42.380Z,1583523462.380 [CommandLine](IMPORTANT): AHRS_sp3003D.rollOffset (degree) 2020-03-06T19:37:42.390Z,1583523462.390 [CommandLine](IMPORTANT): Depth_Keller.offset (decibar) 2020-03-06T19:37:42.391Z,1583523462.391 [CommandLine](IMPORTANT): DVL_micro.pitchOffset (degree) 2020-03-06T19:37:42.391Z,1583523462.391 [CommandLine](IMPORTANT): DVL_micro.rollOffset (degree) 2020-03-06T19:37:42.400Z,1583523462.400 [CommandLine](IMPORTANT): PNI_TCM.pitchOffset (degree) 2020-03-06T19:37:42.433Z,1583523462.433 [CommandLine](IMPORTANT): PNI_TCM.rollOffset (degree) 2020-03-06T19:37:42.442Z,1583523462.442 [CommandLine](IMPORTANT): Rowe_600.headingOffset (degree) 2020-03-06T19:37:42.443Z,1583523462.443 [CommandLine](IMPORTANT): Rowe_600.pitchOffset (degree) 2020-03-06T19:37:42.443Z,1583523462.443 [CommandLine](IMPORTANT): Rowe_600.rollOffset (degree) 2020-03-06T19:37:42.510Z,1583523462.510 [CommandLine](IMPORTANT): Config/Simulator.buoyancyNeutralOffset (cubic_centimeter) 2020-03-06T19:37:42.510Z,1583523462.510 [CommandLine](IMPORTANT): Config/Simulator.massPositionOffset (millimeter) 2020-03-06T19:37:42.524Z,1583523462.524 [CommandLine](IMPORTANT): BuoyancyServo.offsetVolume (cubic_centimeter) 2020-03-06T19:37:42.530Z,1583523462.530 [CommandLine](IMPORTANT): ElevatorServo.offsetAngle (degree) 2020-03-06T19:37:42.532Z,1583523462.532 [CommandLine](IMPORTANT): RudderServo.offsetAngle (degree) 2020-03-06T19:37:42.555Z,1583523462.555 [CommandLine](IMPORTANT): CTD_NeilBrown.offset (decibar) 2020-03-06T19:37:42.556Z,1583523462.556 [CommandLine](IMPORTANT): CTD_Seabird.offset (decibar) 2020-03-06T19:37:42.556Z,1583523462.556 [CommandLine](IMPORTANT): CTD_Seabird.oxygenCalCoeffFOffset (none) 2020-03-06T19:37:42.595Z,1583523462.595 [CommandLine](IMPORTANT): ElevatorOffsetCalculator.loadAtStartup (bool) 2020-03-06T19:37:42.596Z,1583523462.596 [CommandLine](IMPORTANT): ElevatorOffsetCalculator.targetConfidenceLevel (percent) 2020-03-06T19:37:42.596Z,1583523462.596 [CommandLine](IMPORTANT): ElevatorOffsetCalculator.targetErrorBound (degree) 2020-03-06T19:37:42.596Z,1583523462.596 [CommandLine](IMPORTANT): ElevatorOffsetCalculator.verbosity (count) 2020-03-06T19:37:42.603Z,1583523462.603 [CommandLine](IMPORTANT): CBIT.runElevOffsetCalc (bool) 2020-03-06T19:37:42.604Z,1583523462.604 [CommandLine](IMPORTANT): CBIT.gfBattOffset (microampere) 2020-03-06T19:37:42.604Z,1583523462.604 [CommandLine](IMPORTANT): CBIT.gf24Offset (microampere) 2020-03-06T19:37:42.604Z,1583523462.604 [CommandLine](IMPORTANT): CBIT.gf12Offset (microampere) 2020-03-06T19:37:42.609Z,1583523462.609 [CommandLine](IMPORTANT): CBIT.gf5Offset (microampere) 2020-03-06T19:37:42.609Z,1583523462.609 [CommandLine](IMPORTANT): CBIT.gf3_3Offset (microampere) 2020-03-06T19:37:42.610Z,1583523462.610 [CommandLine](IMPORTANT): CBIT.gf3_15Offset (microampere) 2020-03-06T19:37:42.610Z,1583523462.610 [CommandLine](IMPORTANT): CBIT.gfCommOffset (microampere) 2020-03-06T19:37:42.697Z,1583523462.697 [CommandLine](IMPORTANT): CBIT.empiricalFaultElevOffset (radian) 2020-03-06T19:37:42.728Z,1583523462.728 [CommandLine](IMPORTANT): ElevatorOffsetCalculator.elevator_angle_average (radian) 2020-03-06T19:37:42.728Z,1583523462.728 [CommandLine](IMPORTANT): ElevatorOffsetCalculator.elevator_angle_variance (radian) 2020-03-06T19:37:42.761Z,1583523462.761 [CommandLine](IMPORTANT): ElevatorOffsetCalculator.elevator_angle_error_bound (radian) 2020-03-06T19:37:42.761Z,1583523462.761 [CommandLine](IMPORTANT): ElevatorOffsetCalculator.elevator_angle_cmd_speed_identifier (meter_per_second) 2020-03-06T19:37:42.762Z,1583523462.762 [CommandLine](IMPORTANT): ElevatorOffsetCalculator.elevator_angle_cmd_pitch_identifier (radian) 2020-03-06T19:37:42.762Z,1583523462.762 [CommandLine](IMPORTANT): ElevatorOffsetCalculator.elevator_angle_cmd_mass_position_identifier (meter) 2020-03-06T19:38:04.411Z,1583523484.411 [CommandLine](IMPORTANT): got command configSet CBIT.gfBattOffset 0.000000 microampere 2020-03-06T19:38:05.012Z,1583523485.012 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2020-03-06T19:38:05.012Z,1583523485.012 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2020-03-06T19:38:05.013Z,1583523485.013 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-03-06T19:38:05.023Z,1583523485.023 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-03-06T19:38:05.421Z,1583523485.421 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-03-06T19:38:05.422Z,1583523485.422 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2020-03-06T19:38:11.066Z,1583523491.066 [SBIT](IMPORTANT): SBIT PASSED 2020-03-06T19:38:11.067Z,1583523491.067 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2020-03-06T19:38:11.068Z,1583523491.068 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count; 2020-03-06T19:38:11.068Z,1583523491.068 [SBIT](IMPORTANT): CBIT.gf24Offset=150 microampere; 2020-03-06T19:38:11.068Z,1583523491.068 [SBIT](IMPORTANT): DAT.sbdAddress=-1 enum; 2020-03-06T19:38:11.485Z,1583523491.485 [MissionManager](IMPORTANT): Started mission Startup 2020-03-06T19:38:11.485Z,1583523491.485 [Startup] Running Loop=1 2020-03-06T19:38:11.486Z,1583523491.486 [Startup](DEBUG): Aggregate::initialize Startup 2020-03-06T19:38:11.486Z,1583523491.486 [Startup:A.GoToSurface] Running Loop=1 2020-03-06T19:38:11.486Z,1583523491.486 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-03-06T19:38:11.486Z,1583523491.486 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-03-06T19:38:11.487Z,1583523491.487 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-03-06T19:38:11.487Z,1583523491.487 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-03-06T19:38:11.488Z,1583523491.488 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-03-06T19:38:11.488Z,1583523491.488 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-03-06T19:38:11.493Z,1583523491.493 [Startup:StartupSatComms] Running Loop=1 2020-03-06T19:38:11.493Z,1583523491.493 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2020-03-06T19:38:11.494Z,1583523491.494 [Startup:StartupSatComms:A] Running Loop=1 2020-03-06T19:38:11.878Z,1583523491.878 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2020-03-06T19:38:17.719Z,1583523497.719 [CommandLine](IMPORTANT): got command configSet CBIT.gf24Offset 0.000000 microampere 2020-03-06T19:38:20.203Z,1583523500.203 [CommandLine](IMPORTANT): got command show variable offset 2020-03-06T19:38:20.231Z,1583523500.231 [CommandLine](IMPORTANT): AHRS_3DMGX3.pitchOffset (degree) 2020-03-06T19:38:20.232Z,1583523500.232 [CommandLine](IMPORTANT): AHRS_3DMGX3.rollOffset (degree) 2020-03-06T19:38:20.233Z,1583523500.233 [CommandLine](IMPORTANT): AHRS_sp3003D.pitchOffset (degree) 2020-03-06T19:38:20.233Z,1583523500.233 [CommandLine](IMPORTANT): AHRS_sp3003D.rollOffset (degree) 2020-03-06T19:38:20.239Z,1583523500.239 [CommandLine](IMPORTANT): Depth_Keller.offset (decibar) 2020-03-06T19:38:20.240Z,1583523500.240 [CommandLine](IMPORTANT): DVL_micro.pitchOffset (degree) 2020-03-06T19:38:20.240Z,1583523500.240 [CommandLine](IMPORTANT): DVL_micro.rollOffset (degree) 2020-03-06T19:38:20.244Z,1583523500.244 [CommandLine](IMPORTANT): PNI_TCM.pitchOffset (degree) 2020-03-06T19:38:20.245Z,1583523500.245 [CommandLine](IMPORTANT): PNI_TCM.rollOffset (degree) 2020-03-06T19:38:20.246Z,1583523500.246 [CommandLine](IMPORTANT): Rowe_600.headingOffset (degree) 2020-03-06T19:38:20.247Z,1583523500.247 [CommandLine](IMPORTANT): Rowe_600.pitchOffset (degree) 2020-03-06T19:38:20.247Z,1583523500.247 [CommandLine](IMPORTANT): Rowe_600.rollOffset (degree) 2020-03-06T19:38:20.370Z,1583523500.370 [CommandLine](IMPORTANT): Config/Simulator.buoyancyNeutralOffset (cubic_centimeter) 2020-03-06T19:38:20.370Z,1583523500.370 [CommandLine](IMPORTANT): Config/Simulator.massPositionOffset (millimeter) 2020-03-06T19:38:20.373Z,1583523500.373 [CommandLine](IMPORTANT): BuoyancyServo.offsetVolume (cubic_centimeter) 2020-03-06T19:38:20.375Z,1583523500.375 [CommandLine](IMPORTANT): ElevatorServo.offsetAngle (degree) 2020-03-06T19:38:20.385Z,1583523500.385 [CommandLine](IMPORTANT): RudderServo.offsetAngle (degree) 2020-03-06T19:38:20.439Z,1583523500.439 [CommandLine](IMPORTANT): CTD_NeilBrown.offset (decibar) 2020-03-06T19:38:20.439Z,1583523500.439 [CommandLine](IMPORTANT): CTD_Seabird.offset (decibar) 2020-03-06T19:38:20.440Z,1583523500.440 [CommandLine](IMPORTANT): CTD_Seabird.oxygenCalCoeffFOffset (none) 2020-03-06T19:38:20.467Z,1583523500.467 [CommandLine](IMPORTANT): ElevatorOffsetCalculator.loadAtStartup (bool) 2020-03-06T19:38:20.467Z,1583523500.467 [CommandLine](IMPORTANT): ElevatorOffsetCalculator.targetConfidenceLevel (percent) 2020-03-06T19:38:20.468Z,1583523500.468 [CommandLine](IMPORTANT): ElevatorOffsetCalculator.targetErrorBound (degree) 2020-03-06T19:38:20.468Z,1583523500.468 [CommandLine](IMPORTANT): ElevatorOffsetCalculator.verbosity (count) 2020-03-06T19:38:20.489Z,1583523500.489 [CommandLine](IMPORTANT): CBIT.runElevOffsetCalc (bool) 2020-03-06T19:38:20.490Z,1583523500.490 [CommandLine](IMPORTANT): CBIT.gfBattOffset (microampere) 2020-03-06T19:38:20.491Z,1583523500.491 [CommandLine](IMPORTANT): CBIT.gf24Offset (microampere) 2020-03-06T19:38:20.491Z,1583523500.491 [CommandLine](IMPORTANT): CBIT.gf12Offset (microampere) 2020-03-06T19:38:20.491Z,1583523500.491 [CommandLine](IMPORTANT): CBIT.gf5Offset (microampere) 2020-03-06T19:38:20.492Z,1583523500.492 [CommandLine](IMPORTANT): CBIT.gf3_3Offset (microampere) 2020-03-06T19:38:20.492Z,1583523500.492 [CommandLine](IMPORTANT): CBIT.gf3_15Offset (microampere) 2020-03-06T19:38:20.497Z,1583523500.497 [CommandLine](IMPORTANT): CBIT.gfCommOffset (microampere) 2020-03-06T19:38:20.513Z,1583523500.513 [CommandLine](IMPORTANT): CBIT.empiricalFaultElevOffset (radian) 2020-03-06T19:38:20.529Z,1583523500.529 [CommandLine](IMPORTANT): ElevatorOffsetCalculator.elevator_angle_average (radian) 2020-03-06T19:38:20.529Z,1583523500.529 [CommandLine](IMPORTANT): ElevatorOffsetCalculator.elevator_angle_variance (radian) 2020-03-06T19:38:20.530Z,1583523500.530 [CommandLine](IMPORTANT): ElevatorOffsetCalculator.elevator_angle_error_bound (radian) 2020-03-06T19:38:20.530Z,1583523500.530 [CommandLine](IMPORTANT): ElevatorOffsetCalculator.elevator_angle_cmd_speed_identifier (meter_per_second) 2020-03-06T19:38:20.531Z,1583523500.531 [CommandLine](IMPORTANT): ElevatorOffsetCalculator.elevator_angle_cmd_pitch_identifier (radian) 2020-03-06T19:38:20.531Z,1583523500.531 [CommandLine](IMPORTANT): ElevatorOffsetCalculator.elevator_angle_cmd_mass_position_identifier (meter) 2020-03-06T19:38:30.943Z,1583523510.943 [CommandLine](IMPORTANT): got command configSet CBIT.gf24Offset 0.000000 microampere 2020-03-06T19:38:39.315Z,1583523519.315 [CommandLine](IMPORTANT): got command configSet CBIT.gf12Offset 0.000000 microampere 2020-03-06T19:38:49.379Z,1583523529.379 [CommandLine](IMPORTANT): got command configSet CBIT.gf5Offset 0.000000 microampere 2020-03-06T19:38:57.479Z,1583523537.479 [CommandLine](IMPORTANT): got command configSet CBIT.gf3_3Offset 0.000000 microampere 2020-03-06T19:39:06.307Z,1583523546.307 [CommandLine](IMPORTANT): got command configSet CBIT.gf3_15Offset 0.000000 microampere 2020-03-06T19:39:09.249Z,1583523549.249 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2020-03-06T19:39:09.249Z,1583523549.249 [Aanderaa_O2] No Fault, FailCount= 3 2020-03-06T19:39:11.648Z,1583523551.648 [Startup:StartupSatComms:A](INFO): Timed out from 2020-03-06T19:38:11.5Z 2020-03-06T19:39:11.648Z,1583523551.648 [Startup:StartupSatComms:A] Stopped 2020-03-06T19:39:11.648Z,1583523551.648 [Startup:StartupSatComms:B] Running Loop=1 2020-03-06T19:39:12.070Z,1583523552.070 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2020-03-06T19:39:15.828Z,1583523555.828 [DataOverHttps](INFO): Sending 127 bytes from file Logs/20200304T183522/Courier0439.lzma 2020-03-06T19:39:17.003Z,1583523557.003 [CommandLine](IMPORTANT): got command configSet CBIT.gfCommOffset 0.000000 microampere 2020-03-06T19:39:17.530Z,1583523557.530 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2020-03-06T19:39:17.530Z,1583523557.530 [CTD_Seabird] No Fault, FailCount= 3 2020-03-06T19:39:17.631Z,1583523557.631 [DataOverHttps](INFO): Moved sent file to Logs/20200304T183522/Courier0439.lzma.bak 2020-03-06T19:39:17.631Z,1583523557.631 [DataOverHttps](INFO): SBD MOMSN=12300595 2020-03-06T19:39:17.838Z,1583523557.838 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2020-03-06T19:39:17.839Z,1583523557.839 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2020-03-06T19:39:19.438Z,1583523559.438 [CommandLine](IMPORTANT): got command gfscan 2020-03-06T19:39:19.559Z,1583523559.559 [CBIT](IMPORTANT): Beginning ground fault scan 2020-03-06T19:39:19.909Z,1583523559.909 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2020-03-06T19:39:19.909Z,1583523559.909 [Aanderaa_O2] Communications Fault, FailCount= 1 2020-03-06T19:39:19.909Z,1583523559.909 [Aanderaa_O2](ERROR): Communications Fault 2020-03-06T19:39:19.948Z,1583523559.948 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2020-03-06T19:39:20.433Z,1583523560.433 [Aanderaa_O2](INFO): Powering down 2020-03-06T19:39:21.167Z,1583523561.167 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2020-03-06T19:39:21.167Z,1583523561.167 [Aanderaa_O2] No Fault, FailCount= 1 2020-03-06T19:39:23.596Z,1583523563.596 [DAT](INFO): unknown deviceResponse_: Lowpower 2020-03-06T19:39:30.460Z,1583523570.460 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.003844 CHAN A1 (24V): 0.148144 CHAN A2 (12V): 0.061466 CHAN A3 (5V): 0.008263 CHAN B0 (3.3V): -0.002867 CHAN B1 (3.15aV): -0.004106 CHAN B2 (3.15bV): -0.003857 CHAN B3 (GND): -0.027839 OPEN: 0.006791 Full Scale Calc: 4.765 mA, -1.589 mA 2020-03-06T19:39:30.889Z,1583523570.889 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2020-03-06T19:39:30.889Z,1583523570.889 [CTD_Seabird] Communications Fault, FailCount= 1 2020-03-06T19:39:30.889Z,1583523570.889 [CTD_Seabird](ERROR): Communications Fault 2020-03-06T19:39:31.263Z,1583523571.263 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2020-03-06T19:39:31.293Z,1583523571.293 [CTD_Seabird](INFO): Powering down 2020-03-06T19:39:31.624Z,1583523571.624 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2020-03-06T19:39:31.625Z,1583523571.625 [Aanderaa_O2] Communications Fault, FailCount= 2 2020-03-06T19:39:31.625Z,1583523571.625 [Aanderaa_O2](ERROR): Communications Fault 2020-03-06T19:39:31.684Z,1583523571.684 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2020-03-06T19:39:32.149Z,1583523572.149 [Aanderaa_O2](INFO): Powering down 2020-03-06T19:39:32.486Z,1583523572.486 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2020-03-06T19:39:32.486Z,1583523572.486 [CTD_Seabird] No Fault, FailCount= 1 2020-03-06T19:39:32.637Z,1583523572.637 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2020-03-06T19:39:32.638Z,1583523572.638 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2020-03-06T19:39:32.871Z,1583523572.871 [CBIT](INFO): Clearing failed state for component Aanderaa_O2 2020-03-06T19:39:32.871Z,1583523572.871 [Aanderaa_O2] No Fault, FailCount= 2 2020-03-06T19:39:43.340Z,1583523583.340 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2020-03-06T19:39:43.341Z,1583523583.341 [Aanderaa_O2] Communications Fault, FailCount= 3 2020-03-06T19:39:43.341Z,1583523583.341 [Aanderaa_O2](ERROR): Communications Fault 2020-03-06T19:39:43.394Z,1583523583.394 [CBIT](ERROR): Communications Fault in component: Aanderaa_O2 2020-03-06T19:39:43.395Z,1583523583.395 [CBIT](FAULT): Communications Fault in component: Aanderaa_O2 2020-03-06T19:39:43.865Z,1583523583.865 [Aanderaa_O2](INFO): Powering down 2020-03-06T19:39:45.689Z,1583523585.689 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2020-03-06T19:39:45.689Z,1583523585.689 [CTD_Seabird] Communications Fault, FailCount= 2 2020-03-06T19:39:45.689Z,1583523585.689 [CTD_Seabird](ERROR): Communications Fault 2020-03-06T19:39:45.830Z,1583523585.830 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2020-03-06T19:39:46.093Z,1583523586.093 [CTD_Seabird](INFO): Powering down 2020-03-06T19:39:47.457Z,1583523587.457 [CBIT](INFO): Clearing failed state for component CTD_Seabird 2020-03-06T19:39:47.457Z,1583523587.457 [CTD_Seabird] No Fault, FailCount= 2 2020-03-06T19:39:47.845Z,1583523587.845 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2020-03-06T19:39:47.849Z,1583523587.849 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2020-03-06T19:39:51.357Z,1583523591.357 [DataOverHttps](INFO): Sending 284 bytes from file Logs/20200306T170426/Courier0000.lzma 2020-03-06T19:39:53.362Z,1583523593.362 [DataOverHttps](INFO): Moved sent file to Logs/20200306T170426/Courier0000.lzma.bak 2020-03-06T19:39:53.362Z,1583523593.362 [DataOverHttps](INFO): SBD MOMSN=12300600 2020-03-06T19:39:57.894Z,1583523597.894 [CommandLine](IMPORTANT): got command gfscan 2020-03-06T19:39:57.947Z,1583523597.947 [CBIT](IMPORTANT): Beginning ground fault scan 2020-03-06T19:40:00.933Z,1583523600.933 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2020-03-06T19:40:00.933Z,1583523600.933 [CTD_Seabird] Communications Fault, FailCount= 3 2020-03-06T19:40:00.933Z,1583523600.933 [CTD_Seabird](ERROR): Communications Fault 2020-03-06T19:40:01.235Z,1583523601.235 [CBIT](ERROR): Communications Fault in component: CTD_Seabird 2020-03-06T19:40:01.235Z,1583523601.235 [CBIT](FAULT): Communications Fault in component: CTD_Seabird 2020-03-06T19:40:01.337Z,1583523601.337 [CTD_Seabird](INFO): Powering down 2020-03-06T19:40:02.019Z,1583523602.019 [CBIT](INFO): Clearing failed state for component DVL_micro 2020-03-06T19:40:02.019Z,1583523602.019 [DVL_micro] No Fault, FailCount= 3 2020-03-06T19:40:06.024Z,1583523606.024 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2020-03-06T19:40:06.024Z,1583523606.024 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2020-03-06T19:40:06.024Z,1583523606.024 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-03-06T19:40:06.035Z,1583523606.035 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-03-06T19:40:06.440Z,1583523606.440 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-03-06T19:40:06.440Z,1583523606.440 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2020-03-06T19:40:08.902Z,1583523608.902 [CBIT](IMPORTANT): Ground fault detected mA: CHAN A0 (Batt): 0.004930 CHAN A1 (24V): 0.147873 CHAN A2 (12V): 0.061675 CHAN A3 (5V): 0.008366 CHAN B0 (3.3V): -0.003360 CHAN B1 (3.15aV): -0.004553 CHAN B2 (3.15bV): -0.004184 CHAN B3 (GND): -0.027657 OPEN: 0.006981 Full Scale Calc: 4.765 mA, -1.589 mA 2020-03-06T19:40:10.015Z,1583523610.015 [DVL_micro](FAULT): DVL failed to acquire valid data within timeout. 2020-03-06T19:40:10.015Z,1583523610.015 [DVL_micro] Data Fault, FailCount= 1 2020-03-06T19:40:10.015Z,1583523610.015 [DVL_micro](ERROR): Data Fault 2020-03-06T19:40:10.070Z,1583523610.070 [CBIT](ERROR): Data Fault in component: DVL_micro 2020-03-06T19:40:10.493Z,1583523610.493 [DVL_micro](INFO): Powering down 2020-03-06T19:40:11.303Z,1583523611.303 [CBIT](INFO): Clearing failed state for component DVL_micro 2020-03-06T19:40:11.303Z,1583523611.303 [DVL_micro] No Fault, FailCount= 1 2020-03-06T19:40:11.673Z,1583523611.673 [Startup:StartupSatComms:B](INFO): Timed out from 2020-03-06T19:39:11.6Z 2020-03-06T19:40:11.673Z,1583523611.673 [Startup:StartupSatComms:B] Stopped 2020-03-06T19:40:11.673Z,1583523611.673 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2020-03-06T19:40:11.673Z,1583523611.673 [Startup:StartupSatComms] Stopped 2020-03-06T19:40:11.674Z,1583523611.674 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2020-03-06T19:40:11.674Z,1583523611.674 [Startup](INFO): Completed Startup 2020-03-06T19:40:11.674Z,1583523611.674 [MissionManager](INFO): Startup is completed. 2020-03-06T19:40:11.675Z,1583523611.675 [MissionManager](INFO): Uninitializing Mission Startup 2020-03-06T19:40:11.675Z,1583523611.675 [Startup] Stopped 2020-03-06T19:40:11.675Z,1583523611.675 [Startup](DEBUG): Aggregate::uninitialize Startup 2020-03-06T19:40:11.675Z,1583523611.675 [Startup:A.GoToSurface] Stopped 2020-03-06T19:40:11.675Z,1583523611.675 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-03-06T19:40:12.089Z,1583523612.089 [MissionManager](IMPORTANT): Started mission Default 2020-03-06T19:40:12.090Z,1583523612.090 [Default] Running Loop=1 2020-03-06T19:40:12.090Z,1583523612.090 [Default](DEBUG): Aggregate::initialize Default 2020-03-06T19:40:12.090Z,1583523612.090 [Default:B.GoToSurface] Running Loop=1 2020-03-06T19:40:12.090Z,1583523612.090 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-03-06T19:40:12.090Z,1583523612.090 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-03-06T19:40:12.090Z,1583523612.090 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-03-06T19:40:12.091Z,1583523612.091 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-03-06T19:40:12.091Z,1583523612.091 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-03-06T19:40:12.091Z,1583523612.091 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-03-06T19:40:12.092Z,1583523612.092 [Default:A.Wait] Running Loop=1 2020-03-06T19:40:12.092Z,1583523612.092 [Default:A.Wait](DEBUG): Initialize Wait Component. 2020-03-06T19:40:16.033Z,1583523616.033 [DataOverHttps](INFO): Sending 285 bytes from file Logs/20200306T193554/Courier0000.lzma 2020-03-06T19:40:18.038Z,1583523618.038 [DataOverHttps](INFO): Moved sent file to Logs/20200306T193554/Courier0000.lzma.bak 2020-03-06T19:40:18.038Z,1583523618.038 [DataOverHttps](INFO): SBD MOMSN=12300607 2020-03-06T19:40:25.410Z,1583523625.410 [Default:A.Wait](INFO): Done Waiting. 2020-03-06T19:40:25.410Z,1583523625.410 [Default:A.Wait] Stopped 2020-03-06T19:40:25.410Z,1583523625.410 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2020-03-06T19:40:25.822Z,1583523625.822 [Default:CheckIn] Running Loop=1 2020-03-06T19:40:25.822Z,1583523625.822 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-03-06T19:40:25.822Z,1583523625.822 [Default:CheckIn:Read_GPS] Running Loop=1 2020-03-06T19:40:26.233Z,1583523626.233 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2020-03-06T19:40:29.003Z,1583523629.003 [DVL_micro](ERROR): No DVL communication! Re-initializing 2020-03-06T19:40:29.003Z,1583523629.003 [DVL_micro] Communications Fault, FailCount= 2 2020-03-06T19:40:29.003Z,1583523629.003 [DVL_micro](ERROR): Communications Fault 2020-03-06T19:40:29.091Z,1583523629.091 [CBIT](ERROR): Communications Fault in component: DVL_micro 2020-03-06T19:40:29.485Z,1583523629.485 [DVL_micro](INFO): Powering down 2020-03-06T19:40:30.253Z,1583523630.253 [CBIT](INFO): Clearing failed state for component DVL_micro 2020-03-06T19:40:30.254Z,1583523630.254 [DVL_micro] No Fault, FailCount= 2 2020-03-06T19:40:47.996Z,1583523647.996 [DVL_micro](ERROR): No DVL communication! Re-initializing 2020-03-06T19:40:47.996Z,1583523647.996 [DVL_micro] Communications Fault, FailCount= 3 2020-03-06T19:40:47.996Z,1583523647.996 [DVL_micro](ERROR): Communications Fault 2020-03-06T19:40:48.083Z,1583523648.083 [CBIT](ERROR): Communications Fault in component: DVL_micro 2020-03-06T19:40:48.083Z,1583523648.083 [CBIT](CRITICAL): Communications Fault in component: DVL_micro 2020-03-06T19:40:48.469Z,1583523648.469 [DVL_micro](INFO): Powering down 2020-03-06T19:40:48.522Z,1583523648.522 [CBIT](INFO): Critical error at 20200306T194048 2020-03-06T19:40:53.135Z,1583523653.135 [CommandLine](IMPORTANT): got command configSet AHRS_M2.loadAtStartup 0.000000 bool persist 2020-03-06T19:40:53.135Z,1583523653.135 [CommandLine](IMPORTANT): configSet AHRS_M2.loadAtStartup requires a restart to take effect. 2020-03-06T19:41:10.235Z,1583523670.235 [CommandLine](IMPORTANT): got command configSet DVL_micro.loadAtStartup 0.000000 bool persist 2020-03-06T19:41:10.235Z,1583523670.235 [CommandLine](IMPORTANT): configSet DVL_micro.loadAtStartup requires a restart to take effect. 2020-03-06T19:41:29.647Z,1583523689.647 [CommandLine](IMPORTANT): got command configSet CTD_Seabird.loadAtStartup 0.000000 bool persist 2020-03-06T19:41:29.648Z,1583523689.648 [CommandLine](IMPORTANT): configSet CTD_Seabird.loadAtStartup requires a restart to take effect. 2020-03-06T19:41:45.303Z,1583523705.303 [CommandLine](IMPORTANT): got command configSet Aanderaa_O2.loadAtStartup 0.000000 bool persist 2020-03-06T19:41:45.303Z,1583523705.303 [CommandLine](IMPORTANT): configSet Aanderaa_O2.loadAtStartup requires a restart to take effect. 2020-03-06T19:41:46.578Z,1583523706.578 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-03-06T19:41:48.099Z,1583523708.099 [CommandLine](IMPORTANT): got command restart application 2020-03-06T19:41:49.105Z,1583523709.105 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2020-03-06T19:41:49.105Z,1583523709.105 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-03-06T19:41:49.125Z,1583523709.125 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2020-03-06T19:41:49.125Z,1583523709.125 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-03-06T19:41:49.126Z,1583523709.126 [CommandLine](INFO): Join timeout helper Thread ID is 7315 2020-03-06T19:41:49.126Z,1583523709.126 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2020-03-06T19:41:49.126Z,1583523709.126 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-03-06T19:41:49.127Z,1583523709.127 [NavChartDb](INFO): Join timeout helper Thread ID is 7316 2020-03-06T19:41:49.185Z,1583523709.185 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2020-03-06T19:41:49.185Z,1583523709.185 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-03-06T19:41:49.205Z,1583523709.205 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2020-03-06T19:41:49.205Z,1583523709.205 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-03-06T19:41:49.205Z,1583523709.205 [Radio_Surface](INFO): Join timeout helper Thread ID is 7317 2020-03-06T19:41:49.345Z,1583523709.345 [Radio_Surface](INFO): Powering down 2020-03-06T19:41:49.350Z,1583523709.350 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2020-03-06T19:41:49.350Z,1583523709.350 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-03-06T19:41:49.365Z,1583523709.365 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2020-03-06T19:41:49.365Z,1583523709.365 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-03-06T19:41:49.365Z,1583523709.365 [DataOverHttps](INFO): Join timeout helper Thread ID is 7318 2020-03-06T19:41:50.149Z,1583523710.149 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2020-03-06T19:41:50.149Z,1583523710.149 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-03-06T19:41:50.165Z,1583523710.165 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2020-03-06T19:41:50.165Z,1583523710.165 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-03-06T19:41:50.165Z,1583523710.165 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 7319 2020-03-06T19:41:50.605Z,1583523710.605 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2020-03-06T19:41:50.609Z,1583523710.609 [WetLabsBB2FL](INFO): Powering down 2020-03-06T19:41:50.610Z,1583523710.610 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-03-06T19:41:50.626Z,1583523710.626 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2020-03-06T19:41:50.626Z,1583523710.626 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-03-06T19:41:50.626Z,1583523710.626 [CTD_Seabird](INFO): Join timeout helper Thread ID is 7320 2020-03-06T19:41:50.961Z,1583523710.961 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2020-03-06T19:41:50.963Z,1583523710.963 [CTD_Seabird](INFO): Powering down 2020-03-06T19:41:50.977Z,1583523710.977 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-03-06T19:41:50.982Z,1583523710.982 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2020-03-06T19:41:50.982Z,1583523710.982 [logger ThreadHandler](INFO): Thread cancelled. 2020-03-06T19:41:50.982Z,1583523710.982 [logger](INFO): Join timeout helper Thread ID is 7321 2020-03-06T19:41:51.013Z,1583523711.013 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2020-03-06T19:41:51.013Z,1583523711.013 [logger ThreadHandler](INFO): Thread cancelled. 2020-03-06T19:41:51.022Z,1583523711.022 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2020-03-06T19:41:51.023Z,1583523711.023 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-03-06T19:41:51.023Z,1583523711.023 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2020-03-06T19:41:51.023Z,1583523711.023 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-03-06T19:41:51.023Z,1583523711.023 [controlThread](INFO): Join timeout helper Thread ID is 7322 2020-03-06T19:41:51.048Z,1583523711.048 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2020-03-06T19:41:51.048Z,1583523711.048 [controlThread](DEBUG): Uninitializing ControlThread 2020-03-06T19:41:51.048Z,1583523711.048 [Aanderaa_O2](INFO): Powering down 2020-03-06T19:41:51.070Z,1583523711.070 [AHRS_M2](INFO): Powering down 2020-03-06T19:41:51.071Z,1583523711.071 [DVL_micro](INFO): Powering down 2020-03-06T19:41:51.072Z,1583523711.072 [NAL9602](INFO): Powering down 2020-03-06T19:41:51.073Z,1583523711.073 [DAT](INFO): Powering down 2020-03-06T19:41:51.194Z,1583523711.194 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2020-03-06T19:41:51.195Z,1583523711.195 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2020-03-06T19:41:51.195Z,1583523711.195 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2020-03-06T19:41:51.196Z,1583523711.196 [MissionManager](INFO): Uninitializing Mission Default 2020-03-06T19:41:51.196Z,1583523711.196 [Default] Stopped 2020-03-06T19:41:51.196Z,1583523711.196 [Default](DEBUG): Aggregate::uninitialize Default 2020-03-06T19:41:51.196Z,1583523711.196 [Default:B.GoToSurface] Stopped 2020-03-06T19:41:51.196Z,1583523711.196 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-03-06T19:41:51.196Z,1583523711.196 [Default:CheckIn] Stopped 2020-03-06T19:41:51.197Z,1583523711.197 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-03-06T19:41:51.197Z,1583523711.197 [Default:CheckIn:Read_GPS] Stopped 2020-03-06T19:41:51.199Z,1583523711.199 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2020-03-06T19:41:51.199Z,1583523711.199 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2020-03-06T19:41:51.199Z,1583523711.199 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2020-03-06T19:41:51.200Z,1583523711.200 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2020-03-06T19:41:51.200Z,1583523711.200 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2020-03-06T19:41:51.200Z,1583523711.200 [BuoyancyServo](INFO): Powering down 2020-03-06T19:41:51.213Z,1583523711.213 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2020-03-06T19:41:51.213Z,1583523711.213 [ElevatorServo](INFO): Powering down 2020-03-06T19:41:51.214Z,1583523711.214 [MassServo](DEBUG): Uninitialize Mass Servo. 2020-03-06T19:41:51.214Z,1583523711.214 [MassServo](INFO): Powering down 2020-03-06T19:41:51.215Z,1583523711.215 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-03-06T19:41:51.215Z,1583523711.215 [RudderServo](INFO): Powering down 2020-03-06T19:41:51.216Z,1583523711.216 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2020-03-06T19:41:51.216Z,1583523711.216 [ThrusterServo](INFO): Powering down 2020-03-06T19:41:51.216Z,1583523711.216 [SBIT](DEBUG): Uninitialize SBIT Component. 2020-03-06T19:41:51.217Z,1583523711.217 [IBIT](DEBUG): Uninitialize IBIT Component. 2020-03-06T19:41:51.217Z,1583523711.217 [CBIT](DEBUG): Uninitialize CBIT Component. 2020-03-06T19:41:51.217Z,1583523711.217 [CBIT](DEBUG): Powering off loads. 2020-03-06T19:41:51.229Z,1583523711.229 [CBIT](DEBUG): Disabling WDT. 2020-03-06T19:41:51.241Z,1583523711.241 [CBIT](DEBUG): Opening all GF detection circuits. 2020-03-06T19:41:51.242Z,1583523711.242 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-03-06T19:41:51.290Z,1583523711.290 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-03-06T19:41:51.296Z,1583523711.296 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-03-06T19:41:51.361Z,1583523711.361 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-03-06T19:41:51.368Z,1583523711.368 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-03-06T19:41:51.420Z,1583523711.420 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-03-06T19:41:51.479Z,1583523711.479 [logger ThreadHandler](INFO): Thread cancelled.