2020-11-10T15:16:00.129Z,1605021360.129 [Supervisor](DEBUG): Initializing supervisor. 2020-11-10T15:16:00.132Z,1605021360.132 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2020-11-10T15:16:00.132Z,1605021360.132 [SyncHandler](INFO): Protected caller Thread ID is 862 2020-11-10T15:16:00.133Z,1605021360.133 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2020-11-10T15:16:00.133Z,1605021360.133 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2020-11-10T15:16:00.134Z,1605021360.134 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 863 2020-11-10T15:16:00.137Z,1605021360.137 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2020-11-10T15:16:00.155Z,1605021360.155 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2020-11-10T15:16:00.156Z,1605021360.156 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2020-11-10T15:16:00.156Z,1605021360.156 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 864 2020-11-10T15:16:00.157Z,1605021360.157 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2020-11-10T15:16:00.158Z,1605021360.158 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2020-11-10T15:16:00.158Z,1605021360.158 [logger ThreadHandler](INFO): Protected caller Thread ID is 865 2020-11-10T15:16:00.160Z,1605021360.160 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2020-11-10T15:16:00.161Z,1605021360.161 [Supervisor](INFO): Looking for Config files in directory: Config/ 2020-11-10T15:16:00.162Z,1605021360.162 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2020-11-10T15:16:00.257Z,1605021360.257 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2020-11-10T15:16:00.257Z,1605021360.257 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2020-11-10T15:16:00.868Z,1605021360.868 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2020-11-10T15:16:00.869Z,1605021360.869 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2020-11-10T15:16:01.071Z,1605021361.071 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2020-11-10T15:16:01.072Z,1605021361.072 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2020-11-10T15:16:01.170Z,1605021361.170 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2020-11-10T15:16:01.170Z,1605021361.170 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2020-11-10T15:16:01.275Z,1605021361.275 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2020-11-10T15:16:01.275Z,1605021361.275 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2020-11-10T15:16:01.355Z,1605021361.355 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2020-11-10T15:16:01.496Z,1605021361.496 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2020-11-10T15:16:01.496Z,1605021361.496 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2020-11-10T15:16:01.797Z,1605021361.797 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2020-11-10T15:16:01.798Z,1605021361.798 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2020-11-10T15:16:02.314Z,1605021362.314 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2020-11-10T15:16:02.315Z,1605021362.315 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2020-11-10T15:16:02.460Z,1605021362.460 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2020-11-10T15:16:02.461Z,1605021362.461 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2020-11-10T15:16:02.655Z,1605021362.655 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2020-11-10T15:16:02.656Z,1605021362.656 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2020-11-10T15:16:03.156Z,1605021363.156 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2020-11-10T15:16:03.156Z,1605021363.156 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2020-11-10T15:16:03.376Z,1605021363.376 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2020-11-10T15:16:03.377Z,1605021363.377 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2020-11-10T15:16:03.580Z,1605021363.580 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2020-11-10T15:16:03.581Z,1605021363.581 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2020-11-10T15:16:04.075Z,1605021364.075 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2020-11-10T15:16:04.076Z,1605021364.076 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2020-11-10T15:16:04.411Z,1605021364.411 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2020-11-10T15:16:04.413Z,1605021364.413 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/ 2020-11-10T15:16:04.414Z,1605021364.414 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg 2020-11-10T15:16:04.497Z,1605021364.497 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg 2020-11-10T15:16:04.654Z,1605021364.654 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg 2020-11-10T15:16:04.761Z,1605021364.761 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg 2020-11-10T15:16:04.927Z,1605021364.927 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg 2020-11-10T15:16:05.021Z,1605021365.021 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg 2020-11-10T15:16:05.503Z,1605021365.503 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg 2020-11-10T15:16:05.926Z,1605021365.926 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2020-11-10T15:16:05.926Z,1605021365.926 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg 2020-11-10T15:16:06.019Z,1605021366.019 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg 2020-11-10T15:16:06.116Z,1605021366.116 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg 2020-11-10T15:16:06.249Z,1605021366.249 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg 2020-11-10T15:16:06.348Z,1605021366.348 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/ 2020-11-10T15:16:06.349Z,1605021366.349 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2020-11-10T15:16:06.362Z,1605021366.362 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2020-11-10T15:16:06.440Z,1605021366.440 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2020-11-10T15:16:06.440Z,1605021366.440 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2020-11-10T15:16:06.488Z,1605021366.488 [VerticalControl](DEBUG): Construct VerticalControl. 2020-11-10T15:16:06.547Z,1605021366.547 [VerticalControl] Loaded 2020-11-10T15:16:06.547Z,1605021366.547 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2020-11-10T15:16:06.548Z,1605021366.548 [HorizontalControl](DEBUG): Construct HorizontalControl. 2020-11-10T15:16:06.585Z,1605021366.585 [HorizontalControl] Loaded 2020-11-10T15:16:06.586Z,1605021366.586 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2020-11-10T15:16:06.586Z,1605021366.586 [SpeedControl](DEBUG): Construct SpeedControl. 2020-11-10T15:16:06.589Z,1605021366.589 [SpeedControl] Loaded 2020-11-10T15:16:06.590Z,1605021366.590 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2020-11-10T15:16:06.590Z,1605021366.590 [LoopControl](DEBUG): Construct LoopControl. 2020-11-10T15:16:06.591Z,1605021366.591 [LoopControl] Loaded 2020-11-10T15:16:06.591Z,1605021366.591 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2020-11-10T15:16:06.591Z,1605021366.591 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2020-11-10T15:16:06.592Z,1605021366.592 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2020-11-10T15:16:06.631Z,1605021366.631 [DepthRateCalculator] Loaded 2020-11-10T15:16:06.632Z,1605021366.632 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2020-11-10T15:16:06.635Z,1605021366.635 [PitchRateCalculator] Loaded 2020-11-10T15:16:06.635Z,1605021366.635 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2020-11-10T15:16:06.643Z,1605021366.643 [SpeedCalculator] Loaded 2020-11-10T15:16:06.643Z,1605021366.643 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2020-11-10T15:16:06.654Z,1605021366.654 [TempGradientCalculator] Loaded 2020-11-10T15:16:06.655Z,1605021366.655 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2020-11-10T15:16:06.657Z,1605021366.657 [YawRateCalculator] Loaded 2020-11-10T15:16:06.658Z,1605021366.658 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2020-11-10T15:16:06.673Z,1605021366.673 [ElevatorOffsetCalculator] Loaded 2020-11-10T15:16:06.673Z,1605021366.673 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2020-11-10T15:16:06.673Z,1605021366.673 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2020-11-10T15:16:06.674Z,1605021366.674 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2020-11-10T15:16:06.700Z,1605021366.700 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2020-11-10T15:16:06.701Z,1605021366.701 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2020-11-10T15:16:06.785Z,1605021366.785 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2020-11-10T15:16:06.786Z,1605021366.786 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2020-11-10T15:16:07.073Z,1605021367.073 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2020-11-10T15:16:07.073Z,1605021367.073 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2020-11-10T15:16:07.171Z,1605021367.171 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2020-11-10T15:16:07.172Z,1605021367.172 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2020-11-10T15:16:07.474Z,1605021367.474 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation 2020-11-10T15:16:07.477Z,1605021367.477 [AHRS_M2](INFO): created writer for : platform_orientation 2020-11-10T15:16:07.478Z,1605021367.478 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation 2020-11-10T15:16:07.481Z,1605021367.481 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation 2020-11-10T15:16:07.482Z,1605021367.482 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle 2020-11-10T15:16:07.484Z,1605021367.484 [AHRS_M2](INFO): created writer for : platform_pitch_angle 2020-11-10T15:16:07.485Z,1605021367.485 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle 2020-11-10T15:16:07.488Z,1605021367.488 [AHRS_M2](INFO): created writer for : platform_roll_angle 2020-11-10T15:16:07.525Z,1605021367.525 [AHRS_M2] Loaded 2020-11-10T15:16:07.525Z,1605021367.525 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2020-11-10T15:16:07.596Z,1605021367.596 [DataOverHttps] Loaded 2020-11-10T15:16:07.596Z,1605021367.596 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2020-11-10T15:16:07.597Z,1605021367.597 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4090E4E0 2020-11-10T15:16:07.598Z,1605021367.598 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 948 2020-11-10T15:16:07.615Z,1605021367.615 [Depth_Keller] Loaded 2020-11-10T15:16:07.615Z,1605021367.615 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2020-11-10T15:16:07.617Z,1605021367.617 [DropWeight] Loaded 2020-11-10T15:16:07.618Z,1605021367.618 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread. 2020-11-10T15:16:07.673Z,1605021367.673 [NAL9602] Loaded 2020-11-10T15:16:07.674Z,1605021367.674 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2020-11-10T15:16:07.700Z,1605021367.700 [Onboard] Loaded 2020-11-10T15:16:07.700Z,1605021367.700 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2020-11-10T15:16:07.701Z,1605021367.701 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4093E4E0 2020-11-10T15:16:07.701Z,1605021367.701 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 949 2020-11-10T15:16:07.712Z,1605021367.712 [Radio_Surface] Loaded 2020-11-10T15:16:07.712Z,1605021367.712 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2020-11-10T15:16:07.713Z,1605021367.713 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4096E4E0 2020-11-10T15:16:07.713Z,1605021367.713 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 950 2020-11-10T15:16:07.741Z,1605021367.741 [RDI_Pathfinder] Loaded 2020-11-10T15:16:07.741Z,1605021367.741 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread. 2020-11-10T15:16:07.815Z,1605021367.815 [DAT] Loaded 2020-11-10T15:16:07.815Z,1605021367.815 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2020-11-10T15:16:08.600Z,1605021368.600 [BPC1] Loaded 2020-11-10T15:16:08.601Z,1605021368.601 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2020-11-10T15:16:08.601Z,1605021368.601 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2020-11-10T15:16:08.602Z,1605021368.602 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2020-11-10T15:16:08.681Z,1605021368.681 [DeadReckonUsingMultipleVelocitySources] Loaded 2020-11-10T15:16:08.681Z,1605021368.681 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2020-11-10T15:16:08.692Z,1605021368.692 [NavChart] Loaded 2020-11-10T15:16:08.692Z,1605021368.692 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2020-11-10T15:16:08.696Z,1605021368.696 [UniversalFixResidualReporter] Loaded 2020-11-10T15:16:08.696Z,1605021368.696 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2020-11-10T15:16:08.697Z,1605021368.697 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2020-11-10T15:16:08.697Z,1605021368.697 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2020-11-10T15:16:08.830Z,1605021368.830 [SBIT](DEBUG): Construct Startup Built In Test. 2020-11-10T15:16:08.838Z,1605021368.838 [SBIT] Loaded 2020-11-10T15:16:08.839Z,1605021368.839 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2020-11-10T15:16:08.839Z,1605021368.839 [IBIT](DEBUG): Construct Initiated Built In Test. 2020-11-10T15:16:08.852Z,1605021368.852 [IBIT] Loaded 2020-11-10T15:16:08.852Z,1605021368.852 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2020-11-10T15:16:08.855Z,1605021368.855 [CBIT](DEBUG): Construct Continuous Built In Test. 2020-11-10T15:16:08.953Z,1605021368.953 [CBIT] Loaded 2020-11-10T15:16:08.953Z,1605021368.953 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2020-11-10T15:16:08.953Z,1605021368.953 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2020-11-10T15:16:08.954Z,1605021368.954 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2020-11-10T15:16:09.064Z,1605021369.064 [BuoyancyServo] Loaded 2020-11-10T15:16:09.064Z,1605021369.064 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2020-11-10T15:16:09.081Z,1605021369.081 [ElevatorServo] Loaded 2020-11-10T15:16:09.082Z,1605021369.082 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2020-11-10T15:16:09.098Z,1605021369.098 [MassServo] Loaded 2020-11-10T15:16:09.098Z,1605021369.098 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2020-11-10T15:16:09.114Z,1605021369.114 [RudderServo] Loaded 2020-11-10T15:16:09.114Z,1605021369.114 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2020-11-10T15:16:09.130Z,1605021369.130 [ThrusterServo] Loaded 2020-11-10T15:16:09.130Z,1605021369.130 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2020-11-10T15:16:09.130Z,1605021369.130 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2020-11-10T15:16:09.131Z,1605021369.131 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2020-11-10T15:16:09.149Z,1605021369.149 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2020-11-10T15:16:09.150Z,1605021369.150 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2020-11-10T15:16:09.359Z,1605021369.359 [BackSeatDriver] Loaded 2020-11-10T15:16:09.359Z,1605021369.359 [ComponentRegistry](DEBUG): Component "BackSeatDriver" handled in its own thread. 2020-11-10T15:16:09.360Z,1605021369.360 [BackSeatDriver ThreadHandler](DEBUG): Created PCaller Thread at 40B064E0 2020-11-10T15:16:09.361Z,1605021369.361 [BackSeatDriver ThreadHandler](INFO): Protected caller Thread ID is 951 2020-11-10T15:16:09.381Z,1605021369.381 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2020-11-10T15:16:09.384Z,1605021369.384 [CTD_Seabird](INFO): created writer for : sea_water_density 2020-11-10T15:16:09.384Z,1605021369.384 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2020-11-10T15:16:09.387Z,1605021369.387 [CTD_Seabird](INFO): created writer for : depth 2020-11-10T15:16:09.387Z,1605021369.387 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2020-11-10T15:16:09.390Z,1605021369.390 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2020-11-10T15:16:09.390Z,1605021369.390 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2020-11-10T15:16:09.394Z,1605021369.394 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2020-11-10T15:16:09.394Z,1605021369.394 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2020-11-10T15:16:09.397Z,1605021369.397 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2020-11-10T15:16:09.398Z,1605021369.398 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2020-11-10T15:16:09.401Z,1605021369.401 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2020-11-10T15:16:09.401Z,1605021369.401 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2020-11-10T15:16:09.404Z,1605021369.404 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2020-11-10T15:16:09.417Z,1605021369.417 [CTD_Seabird] Loaded 2020-11-10T15:16:09.418Z,1605021369.418 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2020-11-10T15:16:09.419Z,1605021369.419 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B364E0 2020-11-10T15:16:09.419Z,1605021369.419 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 952 2020-11-10T15:16:09.437Z,1605021369.437 [PAR_Licor] Loaded 2020-11-10T15:16:09.437Z,1605021369.437 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread. 2020-11-10T15:16:09.450Z,1605021369.450 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470 2020-11-10T15:16:09.450Z,1605021369.450 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470 2020-11-10T15:16:09.452Z,1605021369.452 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650 2020-11-10T15:16:09.453Z,1605021369.453 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650 2020-11-10T15:16:09.455Z,1605021369.455 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl 2020-11-10T15:16:09.455Z,1605021369.455 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl 2020-11-10T15:16:09.457Z,1605021369.457 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm 2020-11-10T15:16:09.457Z,1605021369.457 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm 2020-11-10T15:16:09.459Z,1605021369.459 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm 2020-11-10T15:16:09.459Z,1605021369.459 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm 2020-11-10T15:16:09.461Z,1605021369.461 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm 2020-11-10T15:16:09.462Z,1605021369.462 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm 2020-11-10T15:16:09.463Z,1605021369.463 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm 2020-11-10T15:16:09.464Z,1605021369.464 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm 2020-11-10T15:16:09.466Z,1605021369.466 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water 2020-11-10T15:16:09.468Z,1605021369.468 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water 2020-11-10T15:16:09.469Z,1605021369.469 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-11-10T15:16:09.469Z,1605021369.469 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-11-10T15:16:09.471Z,1605021369.471 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-11-10T15:16:09.471Z,1605021369.471 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-11-10T15:16:09.473Z,1605021369.473 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-11-10T15:16:09.473Z,1605021369.473 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-11-10T15:16:09.475Z,1605021369.475 [WetLabsBB2FL] Loaded 2020-11-10T15:16:09.475Z,1605021369.475 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2020-11-10T15:16:09.476Z,1605021369.476 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B664E0 2020-11-10T15:16:09.477Z,1605021369.477 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 953 2020-11-10T15:16:09.497Z,1605021369.497 [WetLabsUBAT..SerialDriver](INFO): Created logger 2020-11-10T15:16:09.498Z,1605021369.498 [WetLabsUBAT..SerialDriver](INFO): publishing on LCM channel WetLabsUBAT 2020-11-10T15:16:09.498Z,1605021369.498 [WetLabsUBAT..StreamSerialDriver](INFO): Created logger 2020-11-10T15:16:09.503Z,1605021369.503 [WetLabsUBAT](INFO): readConfig(): serialNo_: UBAT0051, uartName_: /dev/ttyC1, optionalArgs_: 2020-11-10T15:16:09.504Z,1605021369.504 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.biolum_potential 2020-11-10T15:16:09.504Z,1605021369.504 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: biolum_potential 2020-11-10T15:16:09.506Z,1605021369.506 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsUBAT.flow_rate 2020-11-10T15:16:09.506Z,1605021369.506 [WetLabsUBAT](DEBUG): LcmSlateWriter::add(): dataName: flow_rate 2020-11-10T15:16:09.509Z,1605021369.509 [WetLabsUBAT] Loaded 2020-11-10T15:16:09.509Z,1605021369.509 [ComponentRegistry](DEBUG): Component "WetLabsUBAT" handled in its own thread. 2020-11-10T15:16:09.510Z,1605021369.510 [WetLabsUBAT ThreadHandler](DEBUG): Created PCaller Thread at 40B964E0 2020-11-10T15:16:09.511Z,1605021369.511 [WetLabsUBAT ThreadHandler](INFO): Protected caller Thread ID is 954 2020-11-10T15:16:09.511Z,1605021369.511 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2020-11-10T15:16:09.515Z,1605021369.515 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2020-11-10T15:16:09.516Z,1605021369.516 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2020-11-10T15:16:09.525Z,1605021369.525 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2020-11-10T15:16:09.526Z,1605021369.526 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40BC64E0 2020-11-10T15:16:09.526Z,1605021369.526 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 955 2020-11-10T15:16:09.531Z,1605021369.531 [Supervisor](INFO): Main Thread ID is 861 2020-11-10T15:16:09.531Z,1605021369.531 [Supervisor](DEBUG): Running supervisor. 2020-11-10T15:16:09.532Z,1605021369.532 [CommandLine ThreadHandler](INFO): Handler Thread ID is 956 2020-11-10T15:16:09.535Z,1605021369.535 [controlThread ThreadHandler](INFO): Handler Thread ID is 957 2020-11-10T15:16:09.535Z,1605021369.535 [controlThread](DEBUG): Initializing ControlThread 2020-11-10T15:16:09.536Z,1605021369.536 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2020-11-10T15:16:09.538Z,1605021369.538 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2020-11-10T15:16:09.539Z,1605021369.539 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2020-11-10T15:16:09.539Z,1605021369.539 [LoopControl](DEBUG): Initialize LoopControlComponent. 2020-11-10T15:16:09.539Z,1605021369.539 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2020-11-10T15:16:09.540Z,1605021369.540 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2020-11-10T15:16:09.540Z,1605021369.540 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2020-11-10T15:16:09.540Z,1605021369.540 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2020-11-10T15:16:09.541Z,1605021369.541 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2020-11-10T15:16:09.541Z,1605021369.541 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2020-11-10T15:16:09.547Z,1605021369.547 [NavChart](DEBUG): Initialize NavChart Navigation. 2020-11-10T15:16:09.547Z,1605021369.547 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2020-11-10T15:16:09.548Z,1605021369.548 [SBIT](INFO): Initialize SBIT Component. 2020-11-10T15:16:09.548Z,1605021369.548 [SBIT](IMPORTANT): git: 2020-11-09 2020-11-10T15:16:09.549Z,1605021369.549 [SBIT](INFO): git hash: f81f2d5d64572d3a213bb5cdbf8311bdbfca79ef 2020-11-10T15:16:09.549Z,1605021369.549 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2020-11-10T15:16:09.550Z,1605021369.550 [SBIT](INFO): Kernel Reporting Different Version From Configuration. Kernel Expected: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019 Kernel Reported: #1 PREEMPT Thu Aug 6 12:04:53 PDT 2020 2020-11-10T15:16:09.551Z,1605021369.551 [SBIT](INFO): Beginning SBIT in 56.000000 seconds. 2020-11-10T15:16:09.552Z,1605021369.552 [IBIT](INFO): Initialize IBIT Component. 2020-11-10T15:16:09.553Z,1605021369.553 [CBIT](DEBUG): Initialize CBIT Component. 2020-11-10T15:16:09.553Z,1605021369.553 [logger ThreadHandler](INFO): Handler Thread ID is 958 2020-11-10T15:16:09.564Z,1605021369.564 [CBIT](DEBUG): Initialized mux pins. 2020-11-10T15:16:09.564Z,1605021369.564 [CBIT](DEBUG): Initializing the watchdog timer. 2020-11-10T15:16:09.572Z,1605021369.572 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 959 2020-11-10T15:16:09.573Z,1605021369.573 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2020-11-10T15:16:09.584Z,1605021369.584 [Onboard ThreadHandler](INFO): Handler Thread ID is 960 2020-11-10T15:16:09.588Z,1605021369.588 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2020-11-10T15:16:09.589Z,1605021369.589 [CBIT](DEBUG): Initializing heartbeat. 2020-11-10T15:16:09.617Z,1605021369.617 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 961 2020-11-10T15:16:09.620Z,1605021369.620 [BackSeatDriver ThreadHandler](INFO): Handler Thread ID is 962 2020-11-10T15:16:09.632Z,1605021369.632 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 963 2020-11-10T15:16:09.633Z,1605021369.633 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2020-11-10T15:16:09.637Z,1605021369.637 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 965 2020-11-10T15:16:09.638Z,1605021369.638 [WetLabsBB2FL](INFO): Powering down 2020-11-10T15:16:09.649Z,1605021369.649 [WetLabsUBAT ThreadHandler](INFO): Handler Thread ID is 966 2020-11-10T15:16:09.656Z,1605021369.656 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 967 2020-11-10T15:16:09.659Z,1605021369.659 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2020-11-10T15:16:09.659Z,1605021369.659 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2020-11-10T15:16:09.659Z,1605021369.659 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2020-11-10T15:16:09.660Z,1605021369.660 [CBIT](DEBUG): Deactivating GF circuits. 2020-11-10T15:16:09.660Z,1605021369.660 [CBIT](DEBUG): Deactivating emergency mode. 2020-11-10T15:16:09.660Z,1605021369.660 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2020-11-10T15:16:09.660Z,1605021369.660 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2020-11-10T15:16:09.660Z,1605021369.660 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2020-11-10T15:16:09.661Z,1605021369.661 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2020-11-10T15:16:09.661Z,1605021369.661 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2020-11-10T15:16:09.661Z,1605021369.661 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2020-11-10T15:16:09.661Z,1605021369.661 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2020-11-10T15:16:09.661Z,1605021369.661 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2020-11-10T15:16:09.661Z,1605021369.661 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2020-11-10T15:16:09.661Z,1605021369.661 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2020-11-10T15:16:09.662Z,1605021369.662 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2020-11-10T15:16:09.662Z,1605021369.662 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2020-11-10T15:16:09.662Z,1605021369.662 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2020-11-10T15:16:09.696Z,1605021369.696 [CBIT](DEBUG): Backplane powered. 2020-11-10T15:16:09.701Z,1605021369.701 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2020-11-10T15:16:09.711Z,1605021369.711 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2020-11-10T15:16:09.750Z,1605021369.750 [MissionManager](DEBUG): 2020-11-10T15:16:09.750Z,1605021369.750 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2020-11-10T15:16:09.818Z,1605021369.818 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2020-11-10T15:16:09.819Z,1605021369.819 [Default:A.Wait](DEBUG): Construct Wait. 2020-11-10T15:16:09.833Z,1605021369.833 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2020-11-10T15:16:09.866Z,1605021369.866 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2020-11-10T15:16:09.884Z,1605021369.884 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2020-11-10T15:16:09.895Z,1605021369.895 [Default:E.Execute](DEBUG): Construct Execute. 2020-11-10T15:16:09.899Z,1605021369.899 [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-11-10T15:16:09.907Z,1605021369.907 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,NAL9602,RDI_Pathfinder,DAT,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2020-11-10T15:16:09.913Z,1605021369.913 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2020-11-10T15:16:10.006Z,1605021370.006 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar 2020-11-10T15:16:10.015Z,1605021370.015 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2020-11-10T15:16:10.015Z,1605021370.015 [DAT](INFO): Powering up 2020-11-10T15:16:10.015Z,1605021370.015 [DAT](DEBUG): Initializing DAT. 2020-11-10T15:16:10.020Z,1605021370.020 [Radio_Surface](INFO): Powering up 2020-11-10T15:16:10.032Z,1605021370.032 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2020-11-10T15:16:10.224Z,1605021370.224 [DepthRateCalculator](ERROR): Depth measurement is not active 2020-11-10T15:16:10.327Z,1605021370.327 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2020-11-10T15:16:10.338Z,1605021370.338 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2020-11-10T15:16:10.339Z,1605021370.339 [ElevatorServo](DEBUG): Initializing EZServoServo. 2020-11-10T15:16:10.348Z,1605021370.348 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2020-11-10T15:16:10.350Z,1605021370.350 [MassServo](DEBUG): Initializing EZServoServo. 2020-11-10T15:16:10.360Z,1605021370.360 [MassServo](DEBUG): Initializing MassServo. 2020-11-10T15:16:10.361Z,1605021370.361 [RudderServo](DEBUG): Initializing EZServoServo. 2020-11-10T15:16:10.368Z,1605021370.368 [RudderServo](DEBUG): Initializing RudderServo. 2020-11-10T15:16:10.369Z,1605021370.369 [ThrusterServo](DEBUG): Initializing EZServoServo. 2020-11-10T15:16:10.376Z,1605021370.376 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2020-11-10T15:16:10.484Z,1605021370.484 [DropWeight](CRITICAL): DROP WEIGHT MISSING. 2020-11-10T15:16:10.484Z,1605021370.484 [DropWeight] Hardware Fault, FailCount= 1 2020-11-10T15:16:10.485Z,1605021370.485 [DropWeight](ERROR): Hardware Fault 2020-11-10T15:16:10.544Z,1605021370.544 [DepthRateCalculator](ERROR): Depth measurement is not active 2020-11-10T15:16:10.559Z,1605021370.559 [CommandLine](FAULT): Scheduling is paused 2020-11-10T15:16:10.559Z,1605021370.559 [CBIT](INFO): Critical error at 20201110T151610 2020-11-10T15:16:10.560Z,1605021370.560 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals 2020-11-10T15:16:10.562Z,1605021370.562 [CBIT](ERROR): Hardware Fault in component: DropWeight 2020-11-10T15:16:10.562Z,1605021370.562 [CBIT](CRITICAL): Hardware Fault in component: DropWeight 2020-11-10T15:16:10.859Z,1605021370.859 [CBIT](INFO): Critical error at 20201110T151610 2020-11-10T15:16:23.186Z,1605021383.186 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005097 2020-11-10T15:16:24.135Z,1605021384.135 [DAT](INFO): commRate: 800 2020-11-10T15:16:26.165Z,1605021386.165 [DAT](INFO): entering command mode 2020-11-10T15:16:26.558Z,1605021386.558 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-10T15:16:26.962Z,1605021386.962 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-10T15:16:27.376Z,1605021387.376 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-10T15:16:27.780Z,1605021387.780 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-10T15:16:28.174Z,1605021388.174 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-10T15:16:28.588Z,1605021388.588 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-10T15:16:28.981Z,1605021388.981 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-10T15:16:29.386Z,1605021389.386 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-10T15:16:29.805Z,1605021389.805 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-10T15:16:30.194Z,1605021390.194 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-10T15:16:30.612Z,1605021390.612 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-10T15:16:31.002Z,1605021391.002 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-10T15:16:31.405Z,1605021391.405 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-10T15:16:31.829Z,1605021391.829 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-10T15:16:32.222Z,1605021392.222 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-10T15:16:32.624Z,1605021392.624 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-10T15:16:33.022Z,1605021393.022 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-10T15:16:33.422Z,1605021393.422 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-10T15:16:33.839Z,1605021393.839 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-10T15:16:34.230Z,1605021394.230 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-10T15:16:34.644Z,1605021394.644 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-10T15:16:35.073Z,1605021395.073 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-10T15:16:35.442Z,1605021395.442 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-10T15:16:35.854Z,1605021395.854 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-10T15:16:36.264Z,1605021396.264 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-10T15:16:36.672Z,1605021396.672 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-10T15:16:37.058Z,1605021397.058 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-10T15:16:37.468Z,1605021397.468 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-10T15:16:37.869Z,1605021397.869 [NAL9602](INFO): Powering up NAL9602 2020-11-10T15:16:37.887Z,1605021397.887 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-10T15:16:38.270Z,1605021398.270 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-10T15:16:38.684Z,1605021398.684 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-10T15:16:39.078Z,1605021399.078 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-10T15:16:39.502Z,1605021399.502 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-10T15:16:39.904Z,1605021399.904 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-10T15:16:40.294Z,1605021400.294 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-10T15:16:40.704Z,1605021400.704 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-10T15:16:41.098Z,1605021401.098 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-10T15:16:41.507Z,1605021401.507 [DAT](DEBUG): checking for command mode acknowledgment 2020-11-10T15:16:41.507Z,1605021401.507 [DAT](FAULT): failed to enter command mode 2020-11-10T15:16:41.933Z,1605021401.933 [DAT](INFO): entering command mode 2020-11-10T15:16:42.317Z,1605021402.317 [DAT](INFO): setting verbose to 3 2020-11-10T15:16:42.733Z,1605021402.733 [DAT](INFO): set verbose to 3 2020-11-10T15:16:42.734Z,1605021402.734 [DAT](INFO): setting DatVerbose to 27440 2020-11-10T15:16:43.128Z,1605021403.128 [DAT](INFO): set DatVerbose to 27440 2020-11-10T15:16:43.128Z,1605021403.128 [DAT](INFO): setting transmit power to 8 2020-11-10T15:16:43.527Z,1605021403.527 [DAT](INFO): set transmit power to 8 2020-11-10T15:16:43.527Z,1605021403.527 [DAT](INFO): setting local address to 9 2020-11-10T15:16:43.935Z,1605021403.935 [DAT](INFO): set local address to 9 2020-11-10T15:16:48.777Z,1605021408.777 [NAL9602](INFO): NAL9602 initialized 2020-11-10T15:17:06.175Z,1605021426.175 [SBIT](IMPORTANT): Beginning Startup BIT 2020-11-10T15:17:06.180Z,1605021426.180 [CBIT](IMPORTANT): Beginning ground fault scan 2020-11-10T15:17:17.111Z,1605021437.111 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): -0.011273 CHAN A1 (24V): -0.002303 CHAN A2 (12V): -0.007385 CHAN A3 (5V): -0.002601 CHAN B0 (3.3V): 0.000307 CHAN B1 (3.15aV): -0.000314 CHAN B2 (3.15bV): -0.000158 CHAN B3 (GND): 0.001912 OPEN: 0.005161 Full Scale Calc: 4.765 mA, -1.589 mA 2020-11-10T15:17:45.858Z,1605021465.858 [SBIT](FAULT): Rudder: EXPECTED:-15.000000 ACTUAL:-14.762486 2020-11-10T15:17:45.858Z,1605021465.858 [SBIT](FAULT): Control surface position failure. 2020-11-10T15:17:59.955Z,1605021479.955 [SBIT](CRITICAL): SBIT FAILED 2020-11-10T15:17:59.955Z,1605021479.955 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2020-11-10T15:17:59.956Z,1605021479.956 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count; 2020-11-10T15:17:59.956Z,1605021479.956 [SBIT](IMPORTANT): CBIT.gf24Offset=145 microampere; 2020-11-10T15:17:59.956Z,1605021479.956 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool; 2020-11-10T15:17:59.957Z,1605021479.957 [SBIT](IMPORTANT): DAT.sbdAddress=-1 enum; 2020-11-10T15:17:59.957Z,1605021479.957 [SBIT](IMPORTANT): DAT.surfaceThreshold=100 meter; 2020-11-10T15:17:59.957Z,1605021479.957 [SBIT](IMPORTANT): DAT.verbosity=0 count; 2020-11-10T15:17:59.957Z,1605021479.957 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 1.000000 liter_per_second; 2020-11-10T15:17:59.957Z,1605021479.957 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter; 2020-11-10T15:17:59.957Z,1605021479.957 [SBIT](IMPORTANT): PAR_Licor.loadAtStartup=1 bool; 2020-11-10T15:17:59.957Z,1605021479.957 [SBIT](IMPORTANT): PAR_Licor.parCal=1.01146 none; 2020-11-10T15:17:59.957Z,1605021479.957 [SBIT](IMPORTANT): RDI_Pathfinder.loadAtStartup=1 bool; 2020-11-10T15:17:59.957Z,1605021479.957 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=203.794028 cubic_centimeter; 2020-11-10T15:17:59.958Z,1605021479.958 [SBIT](IMPORTANT): VerticalControl.massDefault=9.170314 millimeter; 2020-11-10T15:17:59.958Z,1605021479.958 [SBIT](IMPORTANT): WetLabsUBAT.loadAtStartup=1 bool; 2020-11-10T15:17:59.969Z,1605021479.969 [CBIT](INFO): Critical error at 20201110T151759 2020-11-10T15:18:00.342Z,1605021480.342 [MissionManager](IMPORTANT): Started mission Startup 2020-11-10T15:18:00.342Z,1605021480.342 [Startup] Running Loop=1 2020-11-10T15:18:00.342Z,1605021480.342 [Startup](DEBUG): Aggregate::initialize Startup 2020-11-10T15:18:00.342Z,1605021480.342 [Startup:A.GoToSurface] Running Loop=1 2020-11-10T15:18:00.343Z,1605021480.343 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-11-10T15:18:00.343Z,1605021480.343 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-11-10T15:18:00.348Z,1605021480.348 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-11-10T15:18:00.348Z,1605021480.348 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-11-10T15:18:00.349Z,1605021480.349 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-11-10T15:18:00.349Z,1605021480.349 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-11-10T15:18:00.350Z,1605021480.350 [Startup:StartupSatComms] Running Loop=1 2020-11-10T15:18:00.350Z,1605021480.350 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2020-11-10T15:18:00.350Z,1605021480.350 [Startup:StartupSatComms:A] Running Loop=1 2020-11-10T15:18:00.782Z,1605021480.782 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2020-11-10T15:18:03.254Z,1605021483.254 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2020-11-10T15:18:05.163Z,1605021485.163 [CommandLine](IMPORTANT): got command failComponent 2020-11-10T15:18:05.163Z,1605021485.163 [CommandLine](IMPORTANT): Failed components: 2020-11-10T15:18:05.163Z,1605021485.163 [CommandLine](IMPORTANT): DropWeight: Hardware Fault 2020-11-10T15:18:48.457Z,1605021528.457 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2020-11-10T15:19:00.549Z,1605021540.549 [Startup:StartupSatComms:A](INFO): Timed out from 2020-11-10T15:18:00.4Z 2020-11-10T15:19:00.549Z,1605021540.549 [Startup:StartupSatComms:A] Stopped 2020-11-10T15:19:00.550Z,1605021540.550 [Startup:StartupSatComms:B] Running Loop=1 2020-11-10T15:19:00.929Z,1605021540.929 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2020-11-10T15:19:07.767Z,1605021547.767 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201109T211635/Courier0037.lzma 2020-11-10T15:19:08.770Z,1605021548.770 [DataOverHttps](INFO): Moved sent file to Logs/20201109T211635/Courier0037.lzma.bak 2020-11-10T15:19:08.770Z,1605021548.770 [DataOverHttps](INFO): SBD MOMSN=13164106 2020-11-10T15:19:09.834Z,1605021549.834 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2020-11-10T15:19:09.834Z,1605021549.834 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-11-10T15:19:09.844Z,1605021549.844 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-11-10T15:19:10.253Z,1605021550.253 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-11-10T15:19:10.254Z,1605021550.254 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2020-11-10T15:19:24.734Z,1605021564.734 [DataOverHttps](INFO): Sending 263 bytes from file Logs/20201110T151600/Courier0000.lzma 2020-11-10T15:19:25.734Z,1605021565.734 [DataOverHttps](INFO): Moved sent file to Logs/20201110T151600/Courier0000.lzma.bak 2020-11-10T15:19:25.734Z,1605021565.734 [DataOverHttps](INFO): SBD MOMSN=13164108 2020-11-10T15:19:28.634Z,1605021568.634 [CommandLine](IMPORTANT): got command failComponent none DropWeight 2020-11-10T15:19:28.634Z,1605021568.634 [DropWeight] No Fault, FailCount= 1 2020-11-10T15:19:28.634Z,1605021568.634 [CommandLine](IMPORTANT): DropWeight failureMode is No Fault 2020-11-10T15:19:31.642Z,1605021571.642 [CommandLine](IMPORTANT): got command burn on 2020-11-10T15:19:31.642Z,1605021571.642 [CommandLine](IMPORTANT): Activating dropweight wire 2020-11-10T15:19:37.961Z,1605021577.961 [CommandLine](IMPORTANT): got command burn off 2020-11-10T15:19:37.962Z,1605021577.962 [CommandLine](IMPORTANT): Deactivating dropweight wire 2020-11-10T15:19:50.723Z,1605021590.723 [DataOverHttps](INFO): Sending 120 bytes from file Logs/20201109T211635/Express0038.lzma 2020-11-10T15:20:00.750Z,1605021600.750 [Startup:StartupSatComms:B](INFO): Timed out from 2020-11-10T15:19:00.5Z 2020-11-10T15:20:00.750Z,1605021600.750 [Startup:StartupSatComms:B] Stopped 2020-11-10T15:20:00.750Z,1605021600.750 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2020-11-10T15:20:00.751Z,1605021600.751 [Startup:StartupSatComms] Stopped 2020-11-10T15:20:00.751Z,1605021600.751 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2020-11-10T15:20:00.751Z,1605021600.751 [Startup](INFO): Completed Startup 2020-11-10T15:20:00.751Z,1605021600.751 [MissionManager](INFO): Startup is completed. 2020-11-10T15:20:00.752Z,1605021600.752 [MissionManager](INFO): Uninitializing Mission Startup 2020-11-10T15:20:00.752Z,1605021600.752 [Startup] Stopped 2020-11-10T15:20:00.752Z,1605021600.752 [Startup](DEBUG): Aggregate::uninitialize Startup 2020-11-10T15:20:00.752Z,1605021600.752 [Startup:A.GoToSurface] Stopped 2020-11-10T15:20:00.752Z,1605021600.752 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-11-10T15:20:01.138Z,1605021601.138 [MissionManager](IMPORTANT): Started mission Default 2020-11-10T15:20:01.138Z,1605021601.138 [Default] Running Loop=1 2020-11-10T15:20:01.138Z,1605021601.138 [Default](DEBUG): Aggregate::initialize Default 2020-11-10T15:20:01.138Z,1605021601.138 [Default:B.GoToSurface] Running Loop=1 2020-11-10T15:20:01.138Z,1605021601.138 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-11-10T15:20:01.138Z,1605021601.138 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-11-10T15:20:01.139Z,1605021601.139 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-11-10T15:20:01.139Z,1605021601.139 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-11-10T15:20:01.139Z,1605021601.139 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-11-10T15:20:01.140Z,1605021601.140 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-11-10T15:20:01.140Z,1605021601.140 [Default:A.Wait] Running Loop=1 2020-11-10T15:20:01.140Z,1605021601.140 [Default:A.Wait](DEBUG): Initialize Wait Component. 2020-11-10T15:20:06.833Z,1605021606.833 [DataOverHttps](INFO): Moved sent file to Logs/20201109T211635/Express0038.lzma.bak 2020-11-10T15:20:06.833Z,1605021606.833 [DataOverHttps](INFO): SBD MOMSN=13164115 2020-11-10T15:20:14.461Z,1605021614.461 [Default:A.Wait](INFO): Done Waiting. 2020-11-10T15:20:14.462Z,1605021614.462 [Default:A.Wait] Stopped 2020-11-10T15:20:14.462Z,1605021614.462 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2020-11-10T15:20:14.873Z,1605021614.873 [Default:CheckIn] Running Loop=1 2020-11-10T15:20:14.873Z,1605021614.873 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-11-10T15:20:14.873Z,1605021614.873 [Default:CheckIn:Read_GPS] Running Loop=1 2020-11-10T15:20:15.259Z,1605021615.259 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2020-11-10T15:20:30.683Z,1605021630.683 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size. 2020-11-10T15:20:53.294Z,1605021653.294 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size. 2020-11-10T15:21:12.945Z,1605021672.945 [CommandLine](IMPORTANT): got command restart system 2020-11-10T15:21:15.092Z,1605021675.092 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2020-11-10T15:21:15.092Z,1605021675.092 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-11-10T15:21:15.152Z,1605021675.152 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2020-11-10T15:21:15.152Z,1605021675.152 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-11-10T15:21:15.153Z,1605021675.153 [CommandLine](INFO): Join timeout helper Thread ID is 1005 2020-11-10T15:21:15.153Z,1605021675.153 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2020-11-10T15:21:15.153Z,1605021675.153 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-11-10T15:21:15.154Z,1605021675.154 [NavChartDb](INFO): Join timeout helper Thread ID is 1006 2020-11-10T15:21:15.292Z,1605021675.292 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2020-11-10T15:21:15.292Z,1605021675.292 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-11-10T15:21:15.312Z,1605021675.312 [ComponentRegistry](INFO): Shutting down WetLabsUBAT ThreadHandler 2020-11-10T15:21:15.312Z,1605021675.312 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2020-11-10T15:21:15.312Z,1605021675.312 [WetLabsUBAT](INFO): Join timeout helper Thread ID is 1007 2020-11-10T15:21:15.552Z,1605021675.552 [WetLabsUBAT ThreadHandler](INFO): Uninitializing protected caller thread. 2020-11-10T15:21:15.552Z,1605021675.552 [WetLabsUBAT](INFO): Powering down 2020-11-10T15:21:15.553Z,1605021675.553 [WetLabsUBAT ThreadHandler](INFO): Thread cancelled. 2020-11-10T15:21:15.560Z,1605021675.560 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2020-11-10T15:21:15.560Z,1605021675.560 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-11-10T15:21:15.560Z,1605021675.560 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 1008 2020-11-10T15:21:15.752Z,1605021675.752 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2020-11-10T15:21:15.752Z,1605021675.752 [WetLabsBB2FL](INFO): Powering down 2020-11-10T15:21:15.753Z,1605021675.753 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-11-10T15:21:15.764Z,1605021675.764 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2020-11-10T15:21:15.764Z,1605021675.764 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-11-10T15:21:15.764Z,1605021675.764 [CTD_Seabird](INFO): Join timeout helper Thread ID is 1009 2020-11-10T15:21:15.912Z,1605021675.912 [CTD_Seabird](INFO): Powering down 2020-11-10T15:21:15.92