2021-04-27T16:53:08.475Z,1619542388.475 [Supervisor](DEBUG): Initializing supervisor. 2021-04-27T16:53:08.477Z,1619542388.477 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2021-04-27T16:53:08.478Z,1619542388.478 [SyncHandler](INFO): Protected caller Thread ID is 6308 2021-04-27T16:53:08.478Z,1619542388.478 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2021-04-27T16:53:08.479Z,1619542388.479 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2021-04-27T16:53:08.479Z,1619542388.479 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 6309 2021-04-27T16:53:08.482Z,1619542388.482 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2021-04-27T16:53:08.499Z,1619542388.499 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2021-04-27T16:53:08.500Z,1619542388.500 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2021-04-27T16:53:08.500Z,1619542388.500 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 6310 2021-04-27T16:53:08.501Z,1619542388.501 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2021-04-27T16:53:08.501Z,1619542388.501 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2021-04-27T16:53:08.502Z,1619542388.502 [logger ThreadHandler](INFO): Protected caller Thread ID is 6311 2021-04-27T16:53:08.504Z,1619542388.504 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2021-04-27T16:53:08.504Z,1619542388.504 [Supervisor](INFO): Looking for Config files in directory: Config/ 2021-04-27T16:53:08.506Z,1619542388.506 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2021-04-27T16:53:08.939Z,1619542388.939 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2021-04-27T16:53:08.939Z,1619542388.939 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2021-04-27T16:53:09.091Z,1619542389.091 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2021-04-27T16:53:09.091Z,1619542389.091 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2021-04-27T16:53:09.180Z,1619542389.180 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2021-04-27T16:53:09.389Z,1619542389.389 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2021-04-27T16:53:09.389Z,1619542389.389 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2021-04-27T16:53:09.497Z,1619542389.497 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2021-04-27T16:53:09.498Z,1619542389.498 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2021-04-27T16:53:09.601Z,1619542389.601 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2021-04-27T16:53:09.601Z,1619542389.601 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2021-04-27T16:53:09.976Z,1619542389.976 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2021-04-27T16:53:09.976Z,1619542389.976 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2021-04-27T16:53:10.083Z,1619542390.083 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2021-04-27T16:53:10.084Z,1619542390.084 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2021-04-27T16:53:10.596Z,1619542390.596 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2021-04-27T16:53:10.597Z,1619542390.597 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2021-04-27T16:53:10.787Z,1619542390.787 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2021-04-27T16:53:10.787Z,1619542390.787 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2021-04-27T16:53:11.085Z,1619542391.085 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2021-04-27T16:53:11.086Z,1619542391.086 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2021-04-27T16:53:11.330Z,1619542391.330 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2021-04-27T16:53:11.330Z,1619542391.330 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2021-04-27T16:53:11.866Z,1619542391.866 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2021-04-27T16:53:11.867Z,1619542391.867 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2021-04-27T16:53:12.556Z,1619542392.556 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2021-04-27T16:53:12.556Z,1619542392.556 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2021-04-27T16:53:12.873Z,1619542392.873 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2021-04-27T16:53:12.873Z,1619542392.873 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2021-04-27T16:53:13.075Z,1619542393.075 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2021-04-27T16:53:13.077Z,1619542393.077 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/ 2021-04-27T16:53:13.078Z,1619542393.078 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg 2021-04-27T16:53:13.264Z,1619542393.264 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg 2021-04-27T16:53:13.350Z,1619542393.350 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg 2021-04-27T16:53:13.497Z,1619542393.497 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg 2021-04-27T16:53:13.584Z,1619542393.584 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg 2021-04-27T16:53:13.833Z,1619542393.833 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2021-04-27T16:53:13.833Z,1619542393.833 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg 2021-04-27T16:53:13.938Z,1619542393.938 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg 2021-04-27T16:53:14.047Z,1619542394.047 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg 2021-04-27T16:53:14.143Z,1619542394.143 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg 2021-04-27T16:53:14.286Z,1619542394.286 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg 2021-04-27T16:53:14.385Z,1619542394.385 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg 2021-04-27T16:53:14.487Z,1619542394.487 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/ 2021-04-27T16:53:14.487Z,1619542394.487 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2021-04-27T16:53:14.489Z,1619542394.489 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2021-04-27T16:53:14.507Z,1619542394.507 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2021-04-27T16:53:14.508Z,1619542394.508 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2021-04-27T16:53:14.579Z,1619542394.579 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2021-04-27T16:53:14.580Z,1619542394.580 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2021-04-27T16:53:14.625Z,1619542394.625 [VerticalControl](DEBUG): Construct VerticalControl. 2021-04-27T16:53:14.681Z,1619542394.681 [VerticalControl] Loaded 2021-04-27T16:53:14.681Z,1619542394.681 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2021-04-27T16:53:14.682Z,1619542394.682 [HorizontalControl](DEBUG): Construct HorizontalControl. 2021-04-27T16:53:14.718Z,1619542394.718 [HorizontalControl] Loaded 2021-04-27T16:53:14.718Z,1619542394.718 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2021-04-27T16:53:14.719Z,1619542394.719 [SpeedControl](DEBUG): Construct SpeedControl. 2021-04-27T16:53:14.722Z,1619542394.722 [SpeedControl] Loaded 2021-04-27T16:53:14.722Z,1619542394.722 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2021-04-27T16:53:14.723Z,1619542394.723 [LoopControl](DEBUG): Construct LoopControl. 2021-04-27T16:53:14.723Z,1619542394.723 [LoopControl] Loaded 2021-04-27T16:53:14.723Z,1619542394.723 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2021-04-27T16:53:14.724Z,1619542394.724 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2021-04-27T16:53:14.724Z,1619542394.724 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2021-04-27T16:53:14.826Z,1619542394.826 [BuoyancyServo] Loaded 2021-04-27T16:53:14.826Z,1619542394.826 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2021-04-27T16:53:14.842Z,1619542394.842 [ElevatorServo] Loaded 2021-04-27T16:53:14.842Z,1619542394.842 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2021-04-27T16:53:14.857Z,1619542394.857 [MassServo] Loaded 2021-04-27T16:53:14.857Z,1619542394.857 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2021-04-27T16:53:14.872Z,1619542394.872 [RudderServo] Loaded 2021-04-27T16:53:14.872Z,1619542394.872 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2021-04-27T16:53:14.887Z,1619542394.887 [ThrusterServo] Loaded 2021-04-27T16:53:14.887Z,1619542394.887 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2021-04-27T16:53:14.887Z,1619542394.887 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2021-04-27T16:53:14.888Z,1619542394.888 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2021-04-27T16:53:14.923Z,1619542394.923 [DepthRateCalculator] Loaded 2021-04-27T16:53:14.923Z,1619542394.923 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2021-04-27T16:53:14.926Z,1619542394.926 [PitchRateCalculator] Loaded 2021-04-27T16:53:14.926Z,1619542394.926 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2021-04-27T16:53:14.933Z,1619542394.933 [SpeedCalculator] Loaded 2021-04-27T16:53:14.934Z,1619542394.934 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2021-04-27T16:53:14.944Z,1619542394.944 [TempGradientCalculator] Loaded 2021-04-27T16:53:14.944Z,1619542394.944 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2021-04-27T16:53:14.947Z,1619542394.947 [YawRateCalculator] Loaded 2021-04-27T16:53:14.947Z,1619542394.947 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2021-04-27T16:53:14.961Z,1619542394.961 [ElevatorOffsetCalculator] Loaded 2021-04-27T16:53:14.962Z,1619542394.962 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2021-04-27T16:53:14.962Z,1619542394.962 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2021-04-27T16:53:14.963Z,1619542394.963 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2021-04-27T16:53:15.037Z,1619542395.037 [DeadReckonUsingMultipleVelocitySources] Loaded 2021-04-27T16:53:15.037Z,1619542395.037 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2021-04-27T16:53:15.048Z,1619542395.048 [NavChart] Loaded 2021-04-27T16:53:15.048Z,1619542395.048 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2021-04-27T16:53:15.051Z,1619542395.051 [UniversalFixResidualReporter] Loaded 2021-04-27T16:53:15.052Z,1619542395.052 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2021-04-27T16:53:15.052Z,1619542395.052 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2021-04-27T16:53:15.053Z,1619542395.053 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2021-04-27T16:53:15.330Z,1619542395.330 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation 2021-04-27T16:53:15.333Z,1619542395.333 [AHRS_M2](INFO): created writer for : platform_orientation 2021-04-27T16:53:15.335Z,1619542395.335 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation 2021-04-27T16:53:15.338Z,1619542395.338 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation 2021-04-27T16:53:15.338Z,1619542395.338 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle 2021-04-27T16:53:15.341Z,1619542395.341 [AHRS_M2](INFO): created writer for : platform_pitch_angle 2021-04-27T16:53:15.341Z,1619542395.341 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle 2021-04-27T16:53:15.344Z,1619542395.344 [AHRS_M2](INFO): created writer for : platform_roll_angle 2021-04-27T16:53:15.381Z,1619542395.381 [AHRS_M2] Loaded 2021-04-27T16:53:15.381Z,1619542395.381 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2021-04-27T16:53:15.452Z,1619542395.452 [DataOverHttps] Loaded 2021-04-27T16:53:15.452Z,1619542395.452 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2021-04-27T16:53:15.453Z,1619542395.453 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408514E0 2021-04-27T16:53:15.454Z,1619542395.454 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 6394 2021-04-27T16:53:15.470Z,1619542395.470 [Depth_Keller] Loaded 2021-04-27T16:53:15.470Z,1619542395.470 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2021-04-27T16:53:15.472Z,1619542395.472 [DropWeight] Loaded 2021-04-27T16:53:15.473Z,1619542395.473 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2021-04-27T16:53:15.503Z,1619542395.503 [DVL_micro] Loaded 2021-04-27T16:53:15.504Z,1619542395.504 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread. 2021-04-27T16:53:15.555Z,1619542395.555 [NAL9602] Loaded 2021-04-27T16:53:15.555Z,1619542395.555 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2021-04-27T16:53:15.581Z,1619542395.581 [Onboard] Loaded 2021-04-27T16:53:15.581Z,1619542395.581 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2021-04-27T16:53:15.582Z,1619542395.582 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408814E0 2021-04-27T16:53:15.582Z,1619542395.582 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 6395 2021-04-27T16:53:15.593Z,1619542395.593 [Radio_Surface] Loaded 2021-04-27T16:53:15.593Z,1619542395.593 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2021-04-27T16:53:15.594Z,1619542395.594 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408B14E0 2021-04-27T16:53:15.594Z,1619542395.594 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 6396 2021-04-27T16:53:15.668Z,1619542395.668 [DAT] Loaded 2021-04-27T16:53:15.668Z,1619542395.668 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2021-04-27T16:53:17.140Z,1619542397.140 [BPC1] Loaded 2021-04-27T16:53:17.140Z,1619542397.140 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2021-04-27T16:53:17.141Z,1619542397.141 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2021-04-27T16:53:17.141Z,1619542397.141 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2021-04-27T16:53:17.296Z,1619542397.296 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2021-04-27T16:53:17.297Z,1619542397.297 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2021-04-27T16:53:17.467Z,1619542397.467 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2021-04-27T16:53:17.468Z,1619542397.468 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2021-04-27T16:53:17.501Z,1619542397.501 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2021-04-27T16:53:17.502Z,1619542397.502 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2021-04-27T16:53:18.226Z,1619542398.226 [Aanderaa_O2] Loaded 2021-04-27T16:53:18.227Z,1619542398.227 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread. 2021-04-27T16:53:18.284Z,1619542398.284 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2021-04-27T16:53:18.290Z,1619542398.290 [CTD_Seabird](INFO): created writer for : sea_water_density 2021-04-27T16:53:18.290Z,1619542398.290 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2021-04-27T16:53:18.292Z,1619542398.292 [CTD_Seabird](INFO): created writer for : depth 2021-04-27T16:53:18.293Z,1619542398.293 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2021-04-27T16:53:18.295Z,1619542398.295 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2021-04-27T16:53:18.296Z,1619542398.296 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2021-04-27T16:53:18.302Z,1619542398.302 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2021-04-27T16:53:18.313Z,1619542398.313 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2021-04-27T16:53:18.319Z,1619542398.319 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2021-04-27T16:53:18.321Z,1619542398.321 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2021-04-27T16:53:18.330Z,1619542398.330 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2021-04-27T16:53:18.331Z,1619542398.331 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2021-04-27T16:53:18.378Z,1619542398.378 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2021-04-27T16:53:18.411Z,1619542398.411 [CTD_Seabird] Loaded 2021-04-27T16:53:18.411Z,1619542398.411 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2021-04-27T16:53:18.412Z,1619542398.412 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A594E0 2021-04-27T16:53:18.413Z,1619542398.413 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 6397 2021-04-27T16:53:18.499Z,1619542398.499 [ESPComponent] Loaded 2021-04-27T16:53:18.499Z,1619542398.499 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2021-04-27T16:53:18.581Z,1619542398.581 [PAR_Licor] Loaded 2021-04-27T16:53:18.582Z,1619542398.582 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2021-04-27T16:53:18.601Z,1619542398.601 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470 2021-04-27T16:53:18.602Z,1619542398.602 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470 2021-04-27T16:53:18.603Z,1619542398.603 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650 2021-04-27T16:53:18.604Z,1619542398.604 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650 2021-04-27T16:53:18.617Z,1619542398.617 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl 2021-04-27T16:53:18.618Z,1619542398.618 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl 2021-04-27T16:53:18.620Z,1619542398.620 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm 2021-04-27T16:53:18.620Z,1619542398.620 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm 2021-04-27T16:53:18.622Z,1619542398.622 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm 2021-04-27T16:53:18.622Z,1619542398.622 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm 2021-04-27T16:53:18.624Z,1619542398.624 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm 2021-04-27T16:53:18.624Z,1619542398.624 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm 2021-04-27T16:53:18.668Z,1619542398.668 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm 2021-04-27T16:53:18.668Z,1619542398.668 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm 2021-04-27T16:53:18.670Z,1619542398.670 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water 2021-04-27T16:53:18.672Z,1619542398.672 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water 2021-04-27T16:53:18.673Z,1619542398.673 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water 2021-04-27T16:53:18.673Z,1619542398.673 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water 2021-04-27T16:53:18.675Z,1619542398.675 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2021-04-27T16:53:18.675Z,1619542398.675 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2021-04-27T16:53:18.677Z,1619542398.677 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2021-04-27T16:53:18.677Z,1619542398.677 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2021-04-27T16:53:18.679Z,1619542398.679 [WetLabsBB2FL] Loaded 2021-04-27T16:53:18.679Z,1619542398.679 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2021-04-27T16:53:18.680Z,1619542398.680 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A894E0 2021-04-27T16:53:18.680Z,1619542398.680 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 6398 2021-04-27T16:53:18.681Z,1619542398.681 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2021-04-27T16:53:18.682Z,1619542398.682 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2021-04-27T16:53:19.272Z,1619542399.272 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2021-04-27T16:53:19.273Z,1619542399.273 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2021-04-27T16:53:19.556Z,1619542399.556 [SBIT](DEBUG): Construct Startup Built In Test. 2021-04-27T16:53:19.564Z,1619542399.564 [SBIT] Loaded 2021-04-27T16:53:19.565Z,1619542399.565 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2021-04-27T16:53:19.565Z,1619542399.565 [IBIT](DEBUG): Construct Initiated Built In Test. 2021-04-27T16:53:19.626Z,1619542399.626 [IBIT] Loaded 2021-04-27T16:53:19.626Z,1619542399.626 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2021-04-27T16:53:19.629Z,1619542399.629 [CBIT](DEBUG): Construct Continuous Built In Test. 2021-04-27T16:53:19.866Z,1619542399.866 [CBIT] Loaded 2021-04-27T16:53:19.866Z,1619542399.866 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2021-04-27T16:53:19.866Z,1619542399.866 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2021-04-27T16:53:19.870Z,1619542399.870 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2021-04-27T16:53:19.871Z,1619542399.871 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2021-04-27T16:53:19.879Z,1619542399.879 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2021-04-27T16:53:19.880Z,1619542399.880 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B674E0 2021-04-27T16:53:19.880Z,1619542399.880 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 6399 2021-04-27T16:53:19.885Z,1619542399.885 [Supervisor](INFO): Main Thread ID is 6307 2021-04-27T16:53:19.885Z,1619542399.885 [Supervisor](DEBUG): Running supervisor. 2021-04-27T16:53:19.886Z,1619542399.886 [CommandLine ThreadHandler](INFO): Handler Thread ID is 6400 2021-04-27T16:53:19.889Z,1619542399.889 [controlThread ThreadHandler](INFO): Handler Thread ID is 6401 2021-04-27T16:53:19.890Z,1619542399.890 [controlThread](DEBUG): Initializing ControlThread 2021-04-27T16:53:19.890Z,1619542399.890 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2021-04-27T16:53:19.892Z,1619542399.892 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2021-04-27T16:53:19.893Z,1619542399.893 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2021-04-27T16:53:19.893Z,1619542399.893 [LoopControl](DEBUG): Initialize LoopControlComponent. 2021-04-27T16:53:19.894Z,1619542399.894 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2021-04-27T16:53:19.895Z,1619542399.895 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2021-04-27T16:53:19.895Z,1619542399.895 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2021-04-27T16:53:19.895Z,1619542399.895 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2021-04-27T16:53:19.896Z,1619542399.896 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2021-04-27T16:53:19.896Z,1619542399.896 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2021-04-27T16:53:19.898Z,1619542399.898 [NavChart](DEBUG): Initialize NavChart Navigation. 2021-04-27T16:53:19.898Z,1619542399.898 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2021-04-27T16:53:19.905Z,1619542399.905 [SBIT](INFO): Initialize SBIT Component. 2021-04-27T16:53:19.906Z,1619542399.906 [SBIT](IMPORTANT): git: 2020-11-24-6-gcb638d650 2021-04-27T16:53:19.906Z,1619542399.906 [SBIT](INFO): git hash: cb638d650ac65501896d406bf5988c2fe747cbc6 2021-04-27T16:53:19.906Z,1619542399.906 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2021-04-27T16:53:19.907Z,1619542399.907 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018 Kernel Reported: #1 PREEMPT Tue May 26 08:17:09 PDT 2020 2021-04-27T16:53:19.908Z,1619542399.908 [SBIT](INFO): Beginning SBIT in 20.000000 seconds. 2021-04-27T16:53:19.909Z,1619542399.909 [IBIT](INFO): Initialize IBIT Component. 2021-04-27T16:53:19.910Z,1619542399.910 [CBIT](DEBUG): Initialize CBIT Component. 2021-04-27T16:53:19.911Z,1619542399.911 [logger ThreadHandler](INFO): Handler Thread ID is 6402 2021-04-27T16:53:19.921Z,1619542399.921 [CBIT](DEBUG): Initialized mux pins. 2021-04-27T16:53:19.921Z,1619542399.921 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2021-04-27T16:53:19.921Z,1619542399.921 [CBIT](DEBUG): Initializing the watchdog timer. 2021-04-27T16:53:19.929Z,1619542399.929 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 6403 2021-04-27T16:53:19.930Z,1619542399.930 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2021-04-27T16:53:19.941Z,1619542399.941 [Onboard ThreadHandler](INFO): Handler Thread ID is 6404 2021-04-27T16:53:19.946Z,1619542399.946 [CBIT](CRITICAL): Watchdog Timer failed to initialize. 2021-04-27T16:53:19.946Z,1619542399.946 [CBIT] Hardware Fault, FailCount= 1 2021-04-27T16:53:19.946Z,1619542399.946 [CBIT](ERROR): Hardware Fault 2021-04-27T16:53:19.946Z,1619542399.946 [CBIT](DEBUG): Initializing heartbeat. 2021-04-27T16:53:19.962Z,1619542399.962 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 6405 2021-04-27T16:53:19.973Z,1619542399.973 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 6406 2021-04-27T16:53:19.974Z,1619542399.974 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2021-04-27T16:53:19.978Z,1619542399.978 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 6408 2021-04-27T16:53:19.979Z,1619542399.979 [WetLabsBB2FL](INFO): Powering down 2021-04-27T16:53:20.010Z,1619542400.010 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 6409 2021-04-27T16:53:20.013Z,1619542400.013 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2021-04-27T16:53:20.014Z,1619542400.014 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2021-04-27T16:53:20.014Z,1619542400.014 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2021-04-27T16:53:20.014Z,1619542400.014 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2021-04-27T16:53:20.014Z,1619542400.014 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2021-04-27T16:53:20.014Z,1619542400.014 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2021-04-27T16:53:20.014Z,1619542400.014 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2021-04-27T16:53:20.015Z,1619542400.015 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2021-04-27T16:53:20.015Z,1619542400.015 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2021-04-27T16:53:20.015Z,1619542400.015 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2021-04-27T16:53:20.015Z,1619542400.015 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2021-04-27T16:53:20.015Z,1619542400.015 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2021-04-27T16:53:20.015Z,1619542400.015 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2021-04-27T16:53:20.016Z,1619542400.016 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2021-04-27T16:53:20.016Z,1619542400.016 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2021-04-27T16:53:20.016Z,1619542400.016 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2021-04-27T16:53:20.017Z,1619542400.017 [CBIT](DEBUG): Deactivating GF circuits. 2021-04-27T16:53:20.017Z,1619542400.017 [CBIT](DEBUG): Deactivating emergency mode. 2021-04-27T16:53:20.053Z,1619542400.053 [CBIT](DEBUG): Backplane powered. 2021-04-27T16:53:20.054Z,1619542400.054 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2021-04-27T16:53:20.063Z,1619542400.063 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2021-04-27T16:53:20.074Z,1619542400.074 [MissionManager](DEBUG): 2021-04-27T16:53:20.074Z,1619542400.074 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2021-04-27T16:53:20.171Z,1619542400.171 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2021-04-27T16:53:20.172Z,1619542400.172 [Default:A.Wait](DEBUG): Construct Wait. 2021-04-27T16:53:20.173Z,1619542400.173 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2021-04-27T16:53:20.183Z,1619542400.183 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2021-04-27T16:53:20.212Z,1619542400.212 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2021-04-27T16:53:20.233Z,1619542400.233 [Default:E.Execute](DEBUG): Construct Execute. 2021-04-27T16:53:20.236Z,1619542400.236 [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 2021-04-27T16:53:20.250Z,1619542400.250 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,DAT,BPC1,Aanderaa_O2,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, 2021-04-27T16:53:20.269Z,1619542400.269 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2021-04-27T16:53:20.316Z,1619542400.316 [Depth_Keller](ERROR): Pressure reading out of range: 1925.893799 decibar 2021-04-27T16:53:20.332Z,1619542400.332 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2021-04-27T16:53:20.332Z,1619542400.332 [DAT](INFO): Powering up 2021-04-27T16:53:20.333Z,1619542400.333 [DAT](DEBUG): Initializing DAT. 2021-04-27T16:53:20.365Z,1619542400.365 [Radio_Surface](INFO): Powering up 2021-04-27T16:53:20.391Z,1619542400.391 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2021-04-27T16:53:20.445Z,1619542400.445 [DepthRateCalculator](ERROR): Depth measurement is not active 2021-04-27T16:53:20.484Z,1619542400.484 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2021-04-27T16:53:20.490Z,1619542400.490 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2021-04-27T16:53:20.491Z,1619542400.491 [ElevatorServo](DEBUG): Initializing EZServoServo. 2021-04-27T16:53:20.509Z,1619542400.509 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2021-04-27T16:53:20.510Z,1619542400.510 [MassServo](DEBUG): Initializing EZServoServo. 2021-04-27T16:53:20.533Z,1619542400.533 [MassServo](DEBUG): Initializing MassServo. 2021-04-27T16:53:20.534Z,1619542400.534 [RudderServo](DEBUG): Initializing EZServoServo. 2021-04-27T16:53:20.545Z,1619542400.545 [RudderServo](DEBUG): Initializing RudderServo. 2021-04-27T16:53:20.546Z,1619542400.546 [ThrusterServo](DEBUG): Initializing EZServoServo. 2021-04-27T16:53:20.557Z,1619542400.557 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2021-04-27T16:53:20.559Z,1619542400.559 [CBIT](DEBUG): Uninitialize CBIT Component. 2021-04-27T16:53:20.559Z,1619542400.559 [CBIT](DEBUG): Powering off loads. 2021-04-27T16:53:20.573Z,1619542400.573 [CBIT](DEBUG): Disabling WDT. 2021-04-27T16:53:20.585Z,1619542400.585 [CBIT](DEBUG): Opening all GF detection circuits. 2021-04-27T16:53:21.425Z,1619542401.425 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2021-04-27T16:53:21.425Z,1619542401.425 [RudderServo](FAULT): Rudder failed to initialize 2021-04-27T16:53:21.425Z,1619542401.425 [RudderServo] Communications Fault, FailCount= 1 2021-04-27T16:53:21.425Z,1619542401.425 [RudderServo](ERROR): Communications Fault 2021-04-27T16:53:21.749Z,1619542401.749 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2021-04-27T16:53:22.073Z,1619542402.073 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout 2021-04-27T16:53:22.073Z,1619542402.073 [ThrusterServo](FAULT): Thruster failed to initialize 2021-04-27T16:53:22.073Z,1619542402.073 [ThrusterServo] Communications Fault, FailCount= 1 2021-04-27T16:53:22.073Z,1619542402.073 [ThrusterServo](ERROR): Communications Fault 2021-04-27T16:53:22.469Z,1619542402.469 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2021-04-27T16:53:22.469Z,1619542402.469 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2021-04-27T16:53:22.469Z,1619542402.469 [BuoyancyServo] Communications Fault, FailCount= 1 2021-04-27T16:53:22.469Z,1619542402.469 [BuoyancyServo](ERROR): Communications Fault 2021-04-27T16:53:22.781Z,1619542402.781 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout 2021-04-27T16:53:22.781Z,1619542402.781 [ElevatorServo](FAULT): Elevator failed to initialize 2021-04-27T16:53:22.781Z,1619542402.781 [ElevatorServo] Communications Fault, FailCount= 1 2021-04-27T16:53:22.781Z,1619542402.781 [ElevatorServo](ERROR): Communications Fault 2021-04-27T16:53:22.782Z,1619542402.782 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2021-04-27T16:53:22.783Z,1619542402.783 [RudderServo](INFO): Powering down 2021-04-27T16:53:22.784Z,1619542402.784 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2021-04-27T16:53:22.784Z,1619542402.784 [ThrusterServo](INFO): Powering down 2021-04-27T16:53:22.889Z,1619542402.889 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2021-04-27T16:53:22.890Z,1619542402.890 [ElevatorServo](INFO): Powering down 2021-04-27T16:53:22.898Z,1619542402.898 [RudderServo](FAULT): LCB fault: Current Limiter Activated. 2021-04-27T16:53:22.898Z,1619542402.898 [RudderServo] Hardware Fault, FailCount= 1 2021-04-27T16:53:22.898Z,1619542402.898 [RudderServo](ERROR): Hardware Fault 2021-04-27T16:53:23.216Z,1619542403.216 [ElevatorServo](FAULT): LCB fault: Current Limiter Activated. 2021-04-27T16:53:23.216Z,1619542403.216 [ElevatorServo] Hardware Fault, FailCount= 1 2021-04-27T16:53:23.216Z,1619542403.216 [ElevatorServo](ERROR): Hardware Fault 2021-04-27T16:53:23.217Z,1619542403.217 [RudderServo](DEBUG): Initializing EZServoServo. 2021-04-27T16:53:23.338Z,1619542403.338 [RudderServo](DEBUG): Initializing RudderServo. 2021-04-27T16:53:23.689Z,1619542403.689 [ElevatorServo](DEBUG): Initializing EZServoServo. 2021-04-27T16:53:23.810Z,1619542403.810 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2021-04-27T16:53:24.113Z,1619542404.113 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 2021-04-27T16:53:24.113Z,1619542404.113 [MassServo] Communications Fault, FailCount= 1 2021-04-27T16:53:24.113Z,1619542404.113 [MassServo](ERROR): Communications Fault 2021-04-27T16:53:24.206Z,1619542404.206 [MassServo](DEBUG): Uninitialize Mass Servo. 2021-04-27T16:53:24.206Z,1619542404.206 [MassServo](INFO): Powering down 2021-04-27T16:53:25.077Z,1619542405.077 [AHRS_M2](ERROR): Subscribe Pitch UART error: serial timeout 2021-04-27T16:53:25.425Z,1619542405.425 [AHRS_M2](ERROR): Subscribe Pitch UART error: serial timeout 2021-04-27T16:53:25.829Z,1619542405.829 [AHRS_M2](ERROR): Subscribe Pitch UART error: serial timeout 2021-04-27T16:53:26.233Z,1619542406.233 [AHRS_M2](ERROR): Subscribe Pitch UART error: serial timeout 2021-04-27T16:53:28.253Z,1619542408.253 [AHRS_M2](ERROR): Subscribe Accelerations UART error: serial timeout 2021-04-27T16:53:30.677Z,1619542410.677 [AHRS_M2](ERROR): Start data-stream UART error: serial timeout 2021-04-27T16:53:30.683Z,1619542410.683 [Aanderaa_O2](FAULT): Failed to initialize within timeout. 2021-04-27T16:53:30.683Z,1619542410.683 [Aanderaa_O2] Communications Fault, FailCount= 1 2021-04-27T16:53:30.683Z,1619542410.683 [Aanderaa_O2](ERROR): Communications Fault 2021-04-27T16:53:31.133Z,1619542411.133 [Aanderaa_O2](INFO): Powering down 2021-04-27T16:53:33.025Z,1619542413.025 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2021-04-27T16:53:33.025Z,1619542413.025 [CTD_Seabird] Communications Fault, FailCount= 1 2021-04-27T16:53:33.025Z,1619542413.025 [CTD_Seabird](ERROR): Communications Fault 2021-04-27T16:53:33.429Z,1619542413.429 [CTD_Seabird](INFO): Powering down 2021-04-27T16:53:33.965Z,1619542413.965 [CTD_Seabird](FAULT): LCB fault: Hardware Overcurrent Shutdown. Current Limiter Activated. 2021-04-27T16:53:33.966Z,1619542413.966 [CTD_Seabird] Hardware Fault, FailCount= 1 2021-04-27T16:53:33.966Z,1619542413.966 [CTD_Seabird](ERROR): Hardware Fault 2021-04-27T16:53:37.538Z,1619542417.538 [DVL_micro](FAULT): Failed to initialize within timeout. 2021-04-27T16:53:37.538Z,1619542417.538 [DVL_micro] Communications Fault, FailCount= 1 2021-04-27T16:53:37.538Z,1619542417.538 [DVL_micro](ERROR): Communications Fault 2021-04-27T16:53:38.013Z,1619542418.013 [DVL_micro](INFO): Powering down 2021-04-27T16:53:40.410Z,1619542420.410 [SBIT](IMPORTANT): Beginning Startup BIT 2021-04-27T16:53:41.193Z,1619542421.193 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2021-04-27T16:53:41.193Z,1619542421.193 [AHRS_M2] Data Fault, FailCount= 1 2021-04-27T16:53:41.193Z,1619542421.193 [AHRS_M2](ERROR): Data Fault 2021-04-27T16:53:41.489Z,1619542421.489 [AHRS_M2](INFO): Powering down 2021-04-27T16:53:41.893Z,1619542421.893 [AHRS_M2](FAULT): LCB fault: Hardware Overcurrent Shutdown. Current Limiter Activated. 2021-04-27T16:53:41.893Z,1619542421.893 [AHRS_M2] Hardware Fault, FailCount= 1 2021-04-27T16:53:41.893Z,1619542421.893 [AHRS_M2](ERROR): Hardware Fault 2021-04-27T16:53:45.935Z,1619542425.935 [NAL9602](INFO): Powering up NAL9602 2021-04-27T16:53:53.655Z,1619542433.655 [SBIT](ERROR): Could not read elevatorAngleReader_. 2021-04-27T16:53:53.655Z,1619542433.655 [SBIT](ERROR): Could not read rudderAngleReader_. 2021-04-27T16:53:53.655Z,1619542433.655 [SBIT](ERROR): Could not read massPosReader_. 2021-04-27T16:54:06.540Z,1619542446.540 [NAL9602](ERROR): NAL9602 initialization error. 2021-04-27T16:54:06.540Z,1619542446.540 [NAL9602] Communications Fault, FailCount= 1 2021-04-27T16:54:06.540Z,1619542446.540 [NAL9602](ERROR): Communications Fault 2021-04-27T16:54:06.946Z,1619542446.946 [NAL9602](INFO): Powering down 2021-04-27T16:54:20.288Z,1619542460.288 [SBIT](ERROR): Could not read elevatorAngleReader_. 2021-04-27T16:54:20.288Z,1619542460.288 [SBIT](ERROR): Could not read rudderAngleReader_. 2021-04-27T16:54:20.288Z,1619542460.288 [SBIT](ERROR): Could not read massPosReader_. 2021-04-27T16:54:20.288Z,1619542460.288 [SBIT](FAULT): Control surface position failure. 2021-04-27T16:54:20.684Z,1619542460.684 [DAT](FAULT): failed to initialize, no bytes available on serial interface 2021-04-27T16:54:20.684Z,1619542460.684 [DAT] Communications Fault, FailCount= 1 2021-04-27T16:54:20.684Z,1619542460.684 [DAT](ERROR): Communications Fault 2021-04-27T16:54:21.086Z,1619542461.086 [DAT](INFO): Powering down 2021-04-27T16:54:21.490Z,1619542461.490 [DAT](FAULT): LCB fault: Hardware Overcurrent Shutdown. Current Limiter Activated. 2021-04-27T16:54:21.490Z,1619542461.490 [DAT] Hardware Fault, FailCount= 1 2021-04-27T16:54:21.490Z,1619542461.490 [DAT](ERROR): Hardware Fault 2021-04-27T16:54:33.738Z,1619542473.738 [SBIT](ERROR): Could not read elevatorAngleReader_. 2021-04-27T16:54:33.738Z,1619542473.738 [SBIT](ERROR): Could not read rudderAngleReader_. 2021-04-27T16:54:33.738Z,1619542473.738 [SBIT](ERROR): Could not read massPosReader_. 2021-04-27T16:54:33.738Z,1619542473.738 [SBIT](FAULT): Control surface position failure. 2021-04-27T16:54:34.071Z,1619542474.071 [SBIT](CRITICAL): SBIT FAILED 2021-04-27T16:54:34.071Z,1619542474.071 [SBIT](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2021-04-27T16:54:34.453Z,1619542474.453 [MissionManager](IMPORTANT): Started mission Startup 2021-04-27T16:54:34.454Z,1619542474.454 [Startup] Running Loop=1 2021-04-27T16:54:34.454Z,1619542474.454 [Startup](DEBUG): Aggregate::initialize Startup 2021-04-27T16:54:34.454Z,1619542474.454 [Startup:A.GoToSurface] Running Loop=1 2021-04-27T16:54:34.454Z,1619542474.454 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2021-04-27T16:54:34.455Z,1619542474.455 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2021-04-27T16:54:34.455Z,1619542474.455 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2021-04-27T16:54:34.455Z,1619542474.455 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2021-04-27T16:54:34.456Z,1619542474.456 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2021-04-27T16:54:34.456Z,1619542474.456 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2021-04-27T16:54:34.457Z,1619542474.457 [Startup:StartupSatComms] Running Loop=1 2021-04-27T16:54:34.458Z,1619542474.458 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2021-04-27T16:54:34.458Z,1619542474.458 [Startup:StartupSatComms:A] Running Loop=1 2021-04-27T16:54:34.863Z,1619542474.863 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2021-04-27T16:55:14.414Z,1619542514.414 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file). 2021-04-27T16:55:20.092Z,1619542520.092 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle frame to navigation frame for more than 120 seconds. 2021-04-27T16:55:20.092Z,1619542520.092 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2021-04-27T16:55:20.093Z,1619542520.093 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2021-04-27T16:55:22.090Z,1619542522.090 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-04-27T16:55:34.624Z,1619542534.624 [Startup:StartupSatComms:A](INFO): Timed out from 2021-04-27T16:54:34.5Z 2021-04-27T16:55:34.624Z,1619542534.624 [Startup:StartupSatComms:A] Stopped 2021-04-27T16:55:34.624Z,1619542534.624 [Startup:StartupSatComms:B] Running Loop=1 2021-04-27T16:55:35.028Z,1619542535.028 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2021-04-27T16:55:37.846Z,1619542537.846 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-04-27T16:55:53.603Z,1619542553.603 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-04-27T16:56:09.362Z,1619542569.362 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-04-27T16:56:25.114Z,1619542585.114 [BPC1](ERROR): BPC1B got IPBS message with 4 sticks (min is 7). 2021-04-27T16:56:34.822Z,1619542594.822 [Startup:StartupSatComms:B](I