2019-10-10T22:32:42.906Z,1570746762.906 [Supervisor](DEBUG): Initializing supervisor. 2019-10-10T22:32:42.911Z,1570746762.911 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2019-10-10T22:32:42.912Z,1570746762.912 [SyncHandler](INFO): Protected caller Thread ID is 1043 2019-10-10T22:32:42.912Z,1570746762.912 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2019-10-10T22:32:42.913Z,1570746762.913 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2019-10-10T22:32:42.914Z,1570746762.914 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 1044 2019-10-10T22:32:42.929Z,1570746762.929 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2019-10-10T22:32:42.940Z,1570746762.940 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2019-10-10T22:32:42.941Z,1570746762.941 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2019-10-10T22:32:42.941Z,1570746762.941 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 1045 2019-10-10T22:32:42.942Z,1570746762.942 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2019-10-10T22:32:42.943Z,1570746762.943 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2019-10-10T22:32:42.943Z,1570746762.943 [logger ThreadHandler](INFO): Protected caller Thread ID is 1046 2019-10-10T22:32:42.945Z,1570746762.945 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2019-10-10T22:32:42.945Z,1570746762.945 [Supervisor](INFO): Looking for Config files in directory: Config/ 2019-10-10T22:32:42.951Z,1570746762.951 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2019-10-10T22:32:43.120Z,1570746763.120 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2019-10-10T22:32:43.122Z,1570746763.122 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2019-10-10T22:32:43.775Z,1570746763.775 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2019-10-10T22:32:43.776Z,1570746763.776 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2019-10-10T22:32:43.879Z,1570746763.879 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2019-10-10T22:32:43.881Z,1570746763.881 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2019-10-10T22:32:43.990Z,1570746763.990 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2019-10-10T22:32:43.991Z,1570746763.991 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2019-10-10T22:32:44.171Z,1570746764.171 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2019-10-10T22:32:44.322Z,1570746764.322 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2019-10-10T22:32:44.323Z,1570746764.323 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2019-10-10T22:32:44.654Z,1570746764.654 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2019-10-10T22:32:44.656Z,1570746764.656 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2019-10-10T22:32:45.151Z,1570746765.151 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2019-10-10T22:32:45.153Z,1570746765.153 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2019-10-10T22:32:45.310Z,1570746765.310 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2019-10-10T22:32:45.310Z,1570746765.310 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2019-10-10T22:32:45.529Z,1570746765.529 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2019-10-10T22:32:45.530Z,1570746765.530 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2019-10-10T22:32:46.032Z,1570746766.032 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2019-10-10T22:32:46.034Z,1570746766.034 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2019-10-10T22:32:46.325Z,1570746766.325 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2019-10-10T22:32:46.326Z,1570746766.326 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2019-10-10T22:32:46.782Z,1570746766.782 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2019-10-10T22:32:46.783Z,1570746766.783 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2019-10-10T22:32:47.220Z,1570746767.220 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2019-10-10T22:32:47.221Z,1570746767.221 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2019-10-10T22:32:47.598Z,1570746767.598 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2019-10-10T22:32:47.600Z,1570746767.600 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/ 2019-10-10T22:32:47.602Z,1570746767.602 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg 2019-10-10T22:32:47.691Z,1570746767.691 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg 2019-10-10T22:32:47.861Z,1570746767.861 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg 2019-10-10T22:32:47.977Z,1570746767.977 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg 2019-10-10T22:32:48.068Z,1570746768.068 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg 2019-10-10T22:32:48.169Z,1570746768.169 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg 2019-10-10T22:32:48.389Z,1570746768.389 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg 2019-10-10T22:32:48.625Z,1570746768.625 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2019-10-10T22:32:48.626Z,1570746768.626 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg 2019-10-10T22:32:48.726Z,1570746768.726 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg 2019-10-10T22:32:48.832Z,1570746768.832 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg 2019-10-10T22:32:48.987Z,1570746768.987 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg 2019-10-10T22:32:49.095Z,1570746769.095 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/ 2019-10-10T22:32:49.096Z,1570746769.096 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg 2019-10-10T22:32:49.100Z,1570746769.100 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2019-10-10T22:32:49.169Z,1570746769.169 [VerticalControl](DEBUG): Construct VerticalControl. 2019-10-10T22:32:49.284Z,1570746769.284 [VerticalControl] Loaded 2019-10-10T22:32:49.285Z,1570746769.285 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2019-10-10T22:32:49.285Z,1570746769.285 [HorizontalControl](DEBUG): Construct HorizontalControl. 2019-10-10T22:32:49.356Z,1570746769.356 [HorizontalControl] Loaded 2019-10-10T22:32:49.357Z,1570746769.357 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2019-10-10T22:32:49.357Z,1570746769.357 [SpeedControl](DEBUG): Construct SpeedControl. 2019-10-10T22:32:49.363Z,1570746769.363 [SpeedControl] Loaded 2019-10-10T22:32:49.363Z,1570746769.363 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2019-10-10T22:32:49.364Z,1570746769.364 [LoopControl](DEBUG): Construct LoopControl. 2019-10-10T22:32:49.364Z,1570746769.364 [LoopControl] Loaded 2019-10-10T22:32:49.365Z,1570746769.365 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2019-10-10T22:32:49.365Z,1570746769.365 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2019-10-10T22:32:49.367Z,1570746769.367 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2019-10-10T22:32:49.423Z,1570746769.423 [DepthRateCalculator] Loaded 2019-10-10T22:32:49.423Z,1570746769.423 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2019-10-10T22:32:49.428Z,1570746769.428 [PitchRateCalculator] Loaded 2019-10-10T22:32:49.429Z,1570746769.429 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2019-10-10T22:32:49.444Z,1570746769.444 [SpeedCalculator] Loaded 2019-10-10T22:32:49.445Z,1570746769.445 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2019-10-10T22:32:49.467Z,1570746769.467 [TempGradientCalculator] Loaded 2019-10-10T22:32:49.467Z,1570746769.467 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2019-10-10T22:32:49.472Z,1570746769.472 [YawRateCalculator] Loaded 2019-10-10T22:32:49.472Z,1570746769.472 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2019-10-10T22:32:49.503Z,1570746769.503 [ElevatorOffsetCalculator] Loaded 2019-10-10T22:32:49.503Z,1570746769.503 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2019-10-10T22:32:49.503Z,1570746769.503 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2019-10-10T22:32:49.505Z,1570746769.505 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2019-10-10T22:32:49.555Z,1570746769.555 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2019-10-10T22:32:49.557Z,1570746769.557 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2019-10-10T22:32:49.675Z,1570746769.675 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2019-10-10T22:32:49.676Z,1570746769.676 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2019-10-10T22:32:50.034Z,1570746770.034 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2019-10-10T22:32:50.035Z,1570746770.035 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2019-10-10T22:32:50.169Z,1570746770.169 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2019-10-10T22:32:50.169Z,1570746770.169 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2019-10-10T22:32:50.647Z,1570746770.647 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation 2019-10-10T22:32:50.652Z,1570746770.652 [AHRS_M2](INFO): created writer for : platform_orientation 2019-10-10T22:32:50.654Z,1570746770.654 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation 2019-10-10T22:32:50.660Z,1570746770.660 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation 2019-10-10T22:32:50.660Z,1570746770.660 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle 2019-10-10T22:32:50.665Z,1570746770.665 [AHRS_M2](INFO): created writer for : platform_pitch_angle 2019-10-10T22:32:50.666Z,1570746770.666 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle 2019-10-10T22:32:50.671Z,1570746770.671 [AHRS_M2](INFO): created writer for : platform_roll_angle 2019-10-10T22:32:50.853Z,1570746770.853 [AHRS_M2] Loaded 2019-10-10T22:32:50.854Z,1570746770.854 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2019-10-10T22:32:51.159Z,1570746771.159 [DataOverHttps] Loaded 2019-10-10T22:32:51.160Z,1570746771.160 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2019-10-10T22:32:51.161Z,1570746771.161 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408B14E0 2019-10-10T22:32:51.161Z,1570746771.161 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1126 2019-10-10T22:32:51.175Z,1570746771.175 [Depth_Keller] Loaded 2019-10-10T22:32:51.175Z,1570746771.175 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2019-10-10T22:32:51.180Z,1570746771.180 [DropWeight] Loaded 2019-10-10T22:32:51.180Z,1570746771.180 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2019-10-10T22:32:51.281Z,1570746771.281 [NAL9602] Loaded 2019-10-10T22:32:51.282Z,1570746771.282 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2019-10-10T22:32:51.298Z,1570746771.298 [Onboard] Loaded 2019-10-10T22:32:51.298Z,1570746771.298 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread. 2019-10-10T22:32:51.301Z,1570746771.301 [Radio_Surface] Loaded 2019-10-10T22:32:51.301Z,1570746771.301 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2019-10-10T22:32:51.302Z,1570746771.302 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408E14E0 2019-10-10T22:32:51.303Z,1570746771.303 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1127 2019-10-10T22:32:51.350Z,1570746771.350 [RDI_Pathfinder] Loaded 2019-10-10T22:32:51.350Z,1570746771.350 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2019-10-10T22:32:51.480Z,1570746771.480 [DAT] Loaded 2019-10-10T22:32:51.480Z,1570746771.480 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2019-10-10T22:32:53.456Z,1570746773.456 [BPC1] Loaded 2019-10-10T22:32:53.457Z,1570746773.457 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2019-10-10T22:32:53.457Z,1570746773.457 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2019-10-10T22:32:53.458Z,1570746773.458 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2019-10-10T22:32:53.580Z,1570746773.580 [DeadReckonUsingMultipleVelocitySources] Loaded 2019-10-10T22:32:53.581Z,1570746773.581 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2019-10-10T22:32:53.606Z,1570746773.606 [NavChart] Loaded 2019-10-10T22:32:53.606Z,1570746773.606 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2019-10-10T22:32:53.610Z,1570746773.610 [UniversalFixResidualReporter] Loaded 2019-10-10T22:32:53.610Z,1570746773.610 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2019-10-10T22:32:53.611Z,1570746773.611 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2019-10-10T22:32:53.611Z,1570746773.611 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2019-10-10T22:32:53.780Z,1570746773.780 [SBIT](DEBUG): Construct Startup Built In Test. 2019-10-10T22:32:53.791Z,1570746773.791 [SBIT] Loaded 2019-10-10T22:32:53.792Z,1570746773.792 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2019-10-10T22:32:53.792Z,1570746773.792 [IBIT](DEBUG): Construct Initiated Built In Test. 2019-10-10T22:32:53.804Z,1570746773.804 [IBIT] Loaded 2019-10-10T22:32:53.804Z,1570746773.804 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2019-10-10T22:32:53.807Z,1570746773.807 [CBIT](DEBUG): Construct Continuous Built In Test. 2019-10-10T22:32:53.946Z,1570746773.946 [CBIT] Loaded 2019-10-10T22:32:53.947Z,1570746773.947 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2019-10-10T22:32:53.947Z,1570746773.947 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2019-10-10T22:32:53.948Z,1570746773.948 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2019-10-10T22:32:54.082Z,1570746774.082 [BuoyancyServo] Loaded 2019-10-10T22:32:54.082Z,1570746774.082 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2019-10-10T22:32:54.094Z,1570746774.094 [ElevatorServo] Loaded 2019-10-10T22:32:54.094Z,1570746774.094 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2019-10-10T22:32:54.105Z,1570746774.105 [MassServo] Loaded 2019-10-10T22:32:54.106Z,1570746774.106 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2019-10-10T22:32:54.116Z,1570746774.116 [RudderServo] Loaded 2019-10-10T22:32:54.117Z,1570746774.117 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2019-10-10T22:32:54.127Z,1570746774.127 [ThrusterServo] Loaded 2019-10-10T22:32:54.128Z,1570746774.128 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2019-10-10T22:32:54.128Z,1570746774.128 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2019-10-10T22:32:54.129Z,1570746774.129 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2019-10-10T22:32:54.153Z,1570746774.153 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2019-10-10T22:32:54.154Z,1570746774.154 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2019-10-10T22:32:54.455Z,1570746774.455 [BackSeatDriver] Loaded 2019-10-10T22:32:54.455Z,1570746774.455 [ComponentRegistry](DEBUG): Component "BackSeatDriver" handled in its own thread. 2019-10-10T22:32:54.459Z,1570746774.459 [BackSeatDriver ThreadHandler](DEBUG): Created PCaller Thread at 40A654E0 2019-10-10T22:32:54.459Z,1570746774.459 [BackSeatDriver ThreadHandler](INFO): Protected caller Thread ID is 1128 2019-10-10T22:32:54.469Z,1570746774.469 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2019-10-10T22:32:54.475Z,1570746774.475 [CTD_Seabird](INFO): created writer for : sea_water_density 2019-10-10T22:32:54.475Z,1570746774.475 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2019-10-10T22:32:54.480Z,1570746774.480 [CTD_Seabird](INFO): created writer for : depth 2019-10-10T22:32:54.481Z,1570746774.481 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2019-10-10T22:32:54.486Z,1570746774.486 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2019-10-10T22:32:54.487Z,1570746774.487 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2019-10-10T22:32:54.492Z,1570746774.492 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2019-10-10T22:32:54.493Z,1570746774.493 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2019-10-10T22:32:54.498Z,1570746774.498 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2019-10-10T22:32:54.499Z,1570746774.499 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2019-10-10T22:32:54.505Z,1570746774.505 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2019-10-10T22:32:54.505Z,1570746774.505 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2019-10-10T22:32:54.511Z,1570746774.511 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2019-10-10T22:32:54.539Z,1570746774.539 [CTD_Seabird] Loaded 2019-10-10T22:32:54.539Z,1570746774.539 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2019-10-10T22:32:54.540Z,1570746774.540 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A954E0 2019-10-10T22:32:54.541Z,1570746774.541 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 1129 2019-10-10T22:32:54.547Z,1570746774.547 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470 2019-10-10T22:32:54.547Z,1570746774.547 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470 2019-10-10T22:32:54.552Z,1570746774.552 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650 2019-10-10T22:32:54.552Z,1570746774.552 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650 2019-10-10T22:32:54.557Z,1570746774.557 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl 2019-10-10T22:32:54.557Z,1570746774.557 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl 2019-10-10T22:32:54.561Z,1570746774.561 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm 2019-10-10T22:32:54.562Z,1570746774.562 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm 2019-10-10T22:32:54.566Z,1570746774.566 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm 2019-10-10T22:32:54.566Z,1570746774.566 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm 2019-10-10T22:32:54.571Z,1570746774.571 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm 2019-10-10T22:32:54.571Z,1570746774.571 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm 2019-10-10T22:32:54.576Z,1570746774.576 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm 2019-10-10T22:32:54.576Z,1570746774.576 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm 2019-10-10T22:32:54.580Z,1570746774.580 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water 2019-10-10T22:32:54.585Z,1570746774.585 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water 2019-10-10T22:32:54.585Z,1570746774.585 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water 2019-10-10T22:32:54.586Z,1570746774.586 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water 2019-10-10T22:32:54.590Z,1570746774.590 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2019-10-10T22:32:54.590Z,1570746774.590 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2019-10-10T22:32:54.595Z,1570746774.595 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2019-10-10T22:32:54.595Z,1570746774.595 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2019-10-10T22:32:54.599Z,1570746774.599 [WetLabsBB2FL] Loaded 2019-10-10T22:32:54.600Z,1570746774.600 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2019-10-10T22:32:54.601Z,1570746774.601 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40AC54E0 2019-10-10T22:32:54.601Z,1570746774.601 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 1130 2019-10-10T22:32:54.842Z,1570746774.842 [WetLabsUBAT..SerialDriver](INFO): Created logger 2019-10-10T22:32:54.842Z,1570746774.842 [WetLabsUBAT..SerialDriver](INFO): publishing on LCM channel WetLabsUBAT 2019-10-10T22:32:54.843Z,1570746774.843 [WetLabsUBAT..StreamSerialDriver](INFO): Created logger 2019-10-10T22:32:54.848Z,1570746774.848 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: -ldir /mnt/mmc/LRAUV/Logs/latest 2019-10-10T22:32:54.848Z,1570746774.848 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.biolum_potential 2019-10-10T22:32:54.848Z,1570746774.848 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: biolum_potential 2019-10-10T22:32:54.853Z,1570746774.853 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.flow_rate 2019-10-10T22:32:54.853Z,1570746774.853 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: flow_rate 2019-10-10T22:32:54.857Z,1570746774.857 [WetLabsUBAT] Loaded 2019-10-10T22:32:54.858Z,1570746774.858 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread. 2019-10-10T22:32:54.859Z,1570746774.859 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40AF54E0 2019-10-10T22:32:54.859Z,1570746774.859 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 1131 2019-10-10T22:32:54.860Z,1570746774.860 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2019-10-10T22:32:54.863Z,1570746774.863 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2019-10-10T22:32:54.864Z,1570746774.864 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2019-10-10T22:32:54.871Z,1570746774.871 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2019-10-10T22:32:54.872Z,1570746774.872 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B254E0 2019-10-10T22:32:54.872Z,1570746774.872 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1132 2019-10-10T22:32:54.877Z,1570746774.877 [Supervisor](INFO): Main Thread ID is 1042 2019-10-10T22:32:54.877Z,1570746774.877 [Supervisor](DEBUG): Running supervisor. 2019-10-10T22:32:54.878Z,1570746774.878 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1133 2019-10-10T22:32:54.884Z,1570746774.884 [controlThread ThreadHandler](INFO): Handler Thread ID is 1134 2019-10-10T22:32:54.884Z,1570746774.884 [controlThread](DEBUG): Initializing ControlThread 2019-10-10T22:32:54.885Z,1570746774.885 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2019-10-10T22:32:54.887Z,1570746774.887 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2019-10-10T22:32:54.887Z,1570746774.887 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2019-10-10T22:32:54.888Z,1570746774.888 [LoopControl](DEBUG): Initialize LoopControlComponent. 2019-10-10T22:32:54.888Z,1570746774.888 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2019-10-10T22:32:54.888Z,1570746774.888 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2019-10-10T22:32:54.889Z,1570746774.889 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2019-10-10T22:32:54.889Z,1570746774.889 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2019-10-10T22:32:54.890Z,1570746774.890 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2019-10-10T22:32:54.890Z,1570746774.890 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2019-10-10T22:32:54.896Z,1570746774.896 [NavChart](DEBUG): Initialize NavChart Navigation. 2019-10-10T22:32:54.896Z,1570746774.896 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2019-10-10T22:32:54.896Z,1570746774.896 [SBIT](INFO): Initialize SBIT Component. 2019-10-10T22:32:54.897Z,1570746774.897 [SBIT](IMPORTANT): git: 2019-10-03_bsd 2019-10-10T22:32:54.897Z,1570746774.897 [SBIT](INFO): git hash: c0737ec0000e1c9d4bf2eea0c11ce458bb27ad8a 2019-10-10T22:32:54.898Z,1570746774.898 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2019-10-10T22:32:54.898Z,1570746774.898 [SBIT](IMPORTANT): Kernel Version:#1 PREEMPT Thu Feb 21 11:17:40 PST 2019 2019-10-10T22:32:54.899Z,1570746774.899 [SBIT](INFO): Beginning SBIT in 44.000000 seconds. 2019-10-10T22:32:54.899Z,1570746774.899 [IBIT](INFO): Initialize IBIT Component. 2019-10-10T22:32:54.900Z,1570746774.900 [CBIT](DEBUG): Initialize CBIT Component. 2019-10-10T22:32:54.901Z,1570746774.901 [logger ThreadHandler](INFO): Handler Thread ID is 1135 2019-10-10T22:32:54.913Z,1570746774.913 [CBIT](DEBUG): Initialized mux pins. 2019-10-10T22:32:54.913Z,1570746774.913 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL. 2019-10-10T22:32:54.913Z,1570746774.913 [CBIT](DEBUG): Initializing the watchdog timer. 2019-10-10T22:32:54.921Z,1570746774.921 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1136 2019-10-10T22:32:54.922Z,1570746774.922 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2019-10-10T22:32:54.933Z,1570746774.933 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1137 2019-10-10T22:32:54.937Z,1570746774.937 [CBIT](CRITICAL): Watchdog Timer failed to initialize. 2019-10-10T22:32:54.937Z,1570746774.937 [CBIT] Hardware Fault, FailCount= 1 2019-10-10T22:32:54.937Z,1570746774.937 [CBIT](ERROR): Hardware Fault 2019-10-10T22:32:54.937Z,1570746774.937 [CBIT](DEBUG): Initializing heartbeat. 2019-10-10T22:32:54.945Z,1570746774.945 [BackSeatDriver ThreadHandler](INFO): Handler Thread ID is 1138 2019-10-10T22:32:54.965Z,1570746774.965 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 1139 2019-10-10T22:32:54.966Z,1570746774.966 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2019-10-10T22:32:54.969Z,1570746774.969 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20 2019-10-10T22:32:54.971Z,1570746774.971 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 1141 2019-10-10T22:32:54.972Z,1570746774.972 [WetLabsBB2FL](INFO): Powering down 2019-10-10T22:32:55.001Z,1570746775.001 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 1142 2019-10-10T22:32:55.009Z,1570746775.009 [CBIT](DEBUG): Deactivating GF circuits. 2019-10-10T22:32:55.009Z,1570746775.009 [CBIT](DEBUG): Deactivating emergency mode. 2019-10-10T22:32:55.019Z,1570746775.019 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1143 2019-10-10T22:32:55.030Z,1570746775.030 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2019-10-10T22:32:55.030Z,1570746775.030 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2019-10-10T22:32:55.030Z,1570746775.030 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2019-10-10T22:32:55.030Z,1570746775.030 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2019-10-10T22:32:55.031Z,1570746775.031 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2019-10-10T22:32:55.031Z,1570746775.031 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2019-10-10T22:32:55.031Z,1570746775.031 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2019-10-10T22:32:55.031Z,1570746775.031 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2019-10-10T22:32:55.031Z,1570746775.031 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2019-10-10T22:32:55.032Z,1570746775.032 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2019-10-10T22:32:55.032Z,1570746775.032 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2019-10-10T22:32:55.032Z,1570746775.032 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2019-10-10T22:32:55.032Z,1570746775.032 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2019-10-10T22:32:55.032Z,1570746775.032 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2019-10-10T22:32:55.033Z,1570746775.033 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2019-10-10T22:32:55.033Z,1570746775.033 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2019-10-10T22:32:55.045Z,1570746775.045 [CBIT](DEBUG): Backplane powered. 2019-10-10T22:32:55.047Z,1570746775.047 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2019-10-10T22:32:55.080Z,1570746775.080 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2019-10-10T22:32:55.123Z,1570746775.123 [MissionManager](DEBUG): 2019-10-10T22:32:55.124Z,1570746775.124 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2019-10-10T22:32:55.208Z,1570746775.208 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2019-10-10T22:32:55.226Z,1570746775.226 [Default:A.Wait](DEBUG): Construct Wait. 2019-10-10T22:32:55.227Z,1570746775.227 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2019-10-10T22:32:55.254Z,1570746775.254 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2019-10-10T22:32:55.256Z,1570746775.256 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2019-10-10T22:32:55.312Z,1570746775.312 [Default:E.Execute](DEBUG): Construct Execute. 2019-10-10T22:32:55.315Z,1570746775.315 [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 2019-10-10T22:32:55.329Z,1570746775.329 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,Onboard,RDI_Pathfinder,DAT,BPC1,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, 2019-10-10T22:32:55.337Z,1570746775.337 [Radio_Surface](INFO): Powering up 2019-10-10T22:32:55.341Z,1570746775.341 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2019-10-10T22:32:55.474Z,1570746775.474 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar 2019-10-10T22:32:55.504Z,1570746775.504 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2019-10-10T22:32:55.504Z,1570746775.504 [DAT](INFO): Powering up 2019-10-10T22:32:55.504Z,1570746775.504 [DAT](DEBUG): Initializing DAT. 2019-10-10T22:32:55.557Z,1570746775.557 [DepthRateCalculator](ERROR): Depth measurement is not active 2019-10-10T22:32:55.585Z,1570746775.585 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2019-10-10T22:32:55.655Z,1570746775.655 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2019-10-10T22:32:55.657Z,1570746775.657 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2019-10-10T22:32:55.659Z,1570746775.659 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-10-10T22:32:55.669Z,1570746775.669 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-10-10T22:32:55.670Z,1570746775.670 [MassServo](DEBUG): Initializing EZServoServo. 2019-10-10T22:32:55.681Z,1570746775.681 [MassServo](DEBUG): Initializing MassServo. 2019-10-10T22:32:55.682Z,1570746775.682 [RudderServo](DEBUG): Initializing EZServoServo. 2019-10-10T22:32:55.693Z,1570746775.693 [RudderServo](DEBUG): Initializing RudderServo. 2019-10-10T22:32:55.694Z,1570746775.694 [ThrusterServo](DEBUG): Initializing EZServoServo. 2019-10-10T22:32:55.705Z,1570746775.705 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2019-10-10T22:32:55.706Z,1570746775.706 [CBIT](DEBUG): Uninitialize CBIT Component. 2019-10-10T22:32:55.707Z,1570746775.707 [CBIT](DEBUG): Powering off loads. 2019-10-10T22:32:55.721Z,1570746775.721 [CBIT](DEBUG): Disabling WDT. 2019-10-10T22:32:55.733Z,1570746775.733 [CBIT](DEBUG): Opening all GF detection circuits. 2019-10-10T22:32:55.794Z,1570746775.794 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2019-10-10T22:32:55.794Z,1570746775.794 [DropWeight] Hardware Fault, FailCount= 1 2019-10-10T22:32:55.795Z,1570746775.795 [DropWeight](ERROR): Hardware Fault 2019-10-10T22:32:55.837Z,1570746775.837 [DepthRateCalculator](ERROR): Depth measurement is not active 2019-10-10T22:32:56.897Z,1570746776.897 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout 2019-10-10T22:32:56.897Z,1570746776.897 [BuoyancyServo](FAULT): Buoyancy failed to initialize 2019-10-10T22:32:56.897Z,1570746776.897 [BuoyancyServo] Communications Fault, FailCount= 1 2019-10-10T22:32:56.897Z,1570746776.897 [BuoyancyServo](ERROR): Communications Fault 2019-10-10T22:32:57.209Z,1570746777.209 [ElevatorServo](ERROR): Elevator initialization uart error I:serial timeout 2019-10-10T22:32:57.209Z,1570746777.209 [ElevatorServo](FAULT): Elevator failed to initialize 2019-10-10T22:32:57.209Z,1570746777.209 [ElevatorServo] Communications Fault, FailCount= 1 2019-10-10T22:32:57.209Z,1570746777.209 [ElevatorServo](ERROR): Communications Fault 2019-10-10T22:32:57.513Z,1570746777.513 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2019-10-10T22:32:57.513Z,1570746777.513 [RudderServo](FAULT): Rudder failed to initialize 2019-10-10T22:32:57.513Z,1570746777.513 [RudderServo] Communications Fault, FailCount= 1 2019-10-10T22:32:57.513Z,1570746777.513 [RudderServo](ERROR): Communications Fault 2019-10-10T22:32:57.837Z,1570746777.837 [ThrusterServo](ERROR): Thruster halt for initialization uart error serial timeout 2019-10-10T22:32:58.161Z,1570746778.161 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout 2019-10-10T22:32:58.161Z,1570746778.161 [ThrusterServo](FAULT): Thruster failed to initialize 2019-10-10T22:32:58.161Z,1570746778.161 [ThrusterServo] Communications Fault, FailCount= 1 2019-10-10T22:32:58.161Z,1570746778.161 [ThrusterServo](ERROR): Communications Fault 2019-10-10T22:32:58.211Z,1570746778.211 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2019-10-10T22:32:58.212Z,1570746778.212 [ElevatorServo](INFO): Powering down 2019-10-10T22:32:58.577Z,1570746778.577 [MassServo](FAULT): Mass shifter EEPROM initialization uart error serial timeout 2019-10-10T22:32:58.577Z,1570746778.577 [MassServo] Communications Fault, FailCount= 1 2019-10-10T22:32:58.577Z,1570746778.577 [MassServo](ERROR): Communications Fault 2019-10-10T22:32:58.577Z,1570746778.577 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2019-10-10T22:32:58.578Z,1570746778.578 [RudderServo](INFO): Powering down 2019-10-10T22:32:58.595Z,1570746778.595 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2019-10-10T22:32:58.595Z,1570746778.595 [ThrusterServo](INFO): Powering down 2019-10-10T22:32:58.751Z,1570746778.751 [MassServo](DEBUG): Uninitialize Mass Servo. 2019-10-10T22:32:58.751Z,1570746778.751 [MassServo](INFO): Powering down 2019-10-10T22:32:58.888Z,1570746778.888 [RudderServo](FAULT): LCB fault: Current Limiter Activated. 2019-10-10T22:32:58.888Z,1570746778.888 [RudderServo] Hardware Fault, FailCount= 1 2019-10-10T22:32:58.889Z,1570746778.889 [RudderServo](ERROR): Hardware Fault 2019-10-10T22:32:59.084Z,1570746779.084 [ElevatorServo](DEBUG): Initializing EZServoServo. 2019-10-10T22:32:59.204Z,1570746779.204 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2019-10-10T22:32:59.205Z,1570746779.205 [RudderServo](DEBUG): Initializing EZServoServo. 2019-10-10T22:32:59.326Z,1570746779.326 [RudderServo](DEBUG): Initializing RudderServo. 2019-10-10T22:32:59.508Z,1570746779.508 [MassServo](DEBUG): Initializing EZServoServo. 2019-10-10T22:32:59.626Z,1570746779.626 [MassServo](DEBUG): Initializing MassServo. 2019-10-10T22:33:08.017Z,1570746788.017 [CTD_Seabird](FAULT): Failed to initialize within timeout. 2019-10-10T22:33:08.017Z,1570746788.017 [CTD_Seabird] Communications Fault, FailCount= 1 2019-10-10T22:33:08.017Z,1570746788.017 [CTD_Seabird](ERROR): Communications Fault 2019-10-10T22:33:08.421Z,1570746788.421 [CTD_Seabird](INFO): Powering down 2019-10-10T22:33:13.673Z,1570746793.673 [AHRS_M2](FAULT): Failed to acquire valid data within timeout. 2019-10-10T22:33:13.673Z,1570746793.673 [AHRS_M2] Data Fault, FailCount= 1 2019-10-10T22:33:13.673Z,1570746793.673 [AHRS_M2](ERROR): Data Fault 2019-10-10T22:33:13.993Z,1570746793.993 [AHRS_M2](INFO): Powering down 2019-10-10T22:33:14.398Z,1570746794.398 [AHRS_M2](FAULT): LCB fault: Hardware Overcurrent Shutdown. Current Limiter Activated. 2019-10-10T22:33:14.398Z,1570746794.398 [AHRS_M2] Hardware Fault, FailCount= 1 2019-10-10T22:33:14.398Z,1570746794.398 [AHRS_M2](ERROR): Hardware Fault 2019-10-10T22:33:17.648Z,1570746797.648 [RDI_Pathfinder](ERROR): No DVL communication! Re-initializing 2019-10-10T22:33:17.648Z,1570746797.648 [RDI_Pathfinder] Communications Fault, FailCount= 1 2019-10-10T22:33:17.648Z,1570746797.648 [RDI_Pathfinder](ERROR): Communications Fault 2019-10-10T22:33:18.133Z,1570746798.133 [RDI_Pathfinder](INFO): Powering down 2019-10-10T22:33:21.674Z,1570746801.674 [NAL9602](INFO): Powering up NAL9602 2019-10-10T22:33:39.471Z,1570746819.471 [SBIT](IMPORTANT): Beginning Startup BIT 2019-10-10T22:33:42.275Z,1570746822.275 [NAL9602](ERROR): NAL9602 initialization error. 2019-10-10T22:33:42.275Z,1570746822.275 [NAL9602] Communications Fault, FailCount= 1 2019-10-10T22:33:42.275Z,1570746822.275 [NAL9602](ERROR): Communications Fault 2019-10-10T22:33:42.679Z,1570746822.679 [NAL9602](INFO): Powering down 2019-10-10T22:33:52.808Z,1570746832.808 [SBIT](ERROR): Could not read elevatorAngleReader_. 2019-10-10T22:33:52.808Z,1570746832.808 [SBIT](ERROR): Could not read rudderAngleReader_. 2019-10-10T22:33:52.808Z,1570746832.808 [SBIT](ERROR): Could not read massPosReader_. 2019-10-10T22:33:55.621Z,1570746835.621 [DAT](FAULT): failed to initialize; deviceResponse_ loaded: , available: 2019-10-10T22:33:55.624Z,1570746835.624 [DAT] Communications Fault, FailCount= 1 2019-10-10T22:33:55.624Z,1570746835.624 [DAT](ERROR): Communications Fault 2019-10-10T22:33:56.024Z,1570746836.024 [DAT](INFO): Powering down 2019-10-10T22:34:19.476Z,1570746859.476 [SBIT](ERROR): Could not read elevatorAngleReader_. 2019-10-10T22:34:19.476Z,1570746859.476 [SBIT](ERROR): Could not read rudderAngleReader_. 2019-10-10T22:34:19.476Z,1570746859.476 [SBIT](ERROR): Could not read massPosReader_. 2019-10-10T22:34:19.476Z,1570746859.476 [SBIT](FAULT): Control surface position failure. 2019-10-10T22:34:33.209Z,1570746873.209 [SBIT](ERROR): Could not read elevatorAngleReader_. 2019-10-10T22:34:33.209Z,1570746873.209 [SBIT](ERROR): Could not read rudderAngleReader_. 2019-10-10T22:34:33.209Z,1570746873.209 [SBIT](ERROR): Could not read massPosReader_. 2019-10-10T22:34:33.210Z,1570746873.210 [SBIT](FAULT): Control surface position failure. 2019-10-10T22:34:33.611Z,1570746873.611 [SBIT](CRITICAL): SBIT FAILED 2019-10-10T22:34:33.674Z,1570746873.674 [CommandLine](IMPORTANT): got command configSet list 2019-10-10T22:34:33.674Z,1570746873.674 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2019-10-10T22:34:33.678Z,1570746873.678 [CommandLine](IMPORTANT): CBIT.abortDepth=300 meter; 2019-10-10T22:34:33.678Z,1570746873.678 [CommandLine](IMPORTANT): CBIT.gf24Offset=143 microampere; 2019-10-10T22:34:33.678Z,1570746873.678 [CommandLine](IMPORTANT): CBIT.stopDepth=290 meter; 2019-10-10T22:34:33.678Z,1570746873.678 [CommandLine](IMPORTANT): DAT.loadAtStartup=0 bool; 2019-10-10T22:34:33.678Z,1570746873.678 [CommandLine](IMPORTANT): DVL_micro.loadAtStartup=0 bool; 2019-10-10T22:34:33.678Z,1570746873.678 [CommandLine](IMPORTANT): Express linearApproximation BackSeatDriver.biolum_dinoflagellate_proxy 1000000000.000000 none; 2019-10-10T22:34:33.679Z,1570746873.679 [CommandLine](IMPORTANT): Express none BackSeatDriver.biolum_jelly_proxy; 2019-10-10T22:34:33.679Z,1570746873.679 [CommandLine](IMPORTANT): Express none RDI_Pathfinder.height_above_sea_floor; 2019-10-10T22:34:33.679Z,1570746873.679 [CommandLine](IMPORTANT): Express linearApproximation Tracking.range_to_contact 10.000000 meter; 2019-10-10T22:34:33.679Z,1570746873.679 [CommandLine](IMPORTANT): Express none WetLabsUBAT.biolum_potential; 2019-10-10T22:34:33.679Z,1570746873.679 [CommandLine](IMPORTANT): Express none WetLabsUBAT.flow_rate; 2019-10-10T22:34:33.679Z,1570746873.679 [CommandLine](IMPORTANT): Express linearApproximation height_above_sea_floor 10.000000 meter; 2019-10-10T22:34:33.679Z,1570746873.679 [CommandLine](IMPORTANT): Normal linearApproximation BackSeatDriver.biolum_jelly_proxy 1000000.000000 none; 2019-10-10T22:34:33.679Z,1570746873.679 [CommandLine](IMPORTANT): Normal linearApproximation WetLabsUBAT.biolum_potential 1000000.000000 none; 2019-10-10T22:34:33.679Z,1570746873.679 [CommandLine](IMPORTANT): RDI_Pathfinder.loadAtStartup=1 bool; 2019-10-10T22:34:33.679Z,1570746873.679 [CommandLine](IMPORTANT): VerticalControl.buoyancyNeutral=191 cubic_centimeter; 2019-10-10T22:34:33.680Z,1570746873.680 [CommandLine](IMPORTANT): VerticalControl.massDefault=5.5 millimeter; 2019-10-10T22:34:34.016Z,1570746874.016 [MissionManager](IMPORTANT): Started mission Startup 2019-10-10T22:34:34.016Z,1570746874.016 [Startup] Running Loop=1 2019-10-10T22:34:34.017Z,1570746874.017 [Startup](DEBUG): Aggregate::initialize Startup 2019-10-10T22:34:34.017Z,1570746874.017 [Startup:A.GoToSurface] Running Loop=1 2019-10-10T22:34:34.017Z,1570746874.017 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2019-10-10T22:34:34.018Z,1570746874.018 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2019-10-10T22:34:34.018Z,1570746874.018 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2019-10-10T22:34:34.018Z,1570746874.018 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2019-10-10T22:34:34.019Z,1570746874.019 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2019-10-10T22:34:34.019Z,1570746874.019 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2019-10-10T22:34:34.020Z,1570746874.020 [Startup:StartupSatComms] Running Loop=1 2019-10-10T22:34:34.020Z,1570746874.020 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2019-10-10T22:34:34.051Z,1570746874.051 [Startup:StartupSatComms:A] Running Loop=1 2019-10-10T22:34:34.421Z,1570746874.421 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2019-10-10T22:34:55.020Z,1570746895.020 [DeadReckonUsingMultipleVelocitySources](FAULT): Unable to read the rotation from vehicle f