2020-09-30T19:07:48.712Z,1601492868.712 [Supervisor](DEBUG): Initializing supervisor. 2020-09-30T19:07:48.715Z,1601492868.715 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2020-09-30T19:07:48.716Z,1601492868.716 [SyncHandler](INFO): Protected caller Thread ID is 5262 2020-09-30T19:07:48.717Z,1601492868.717 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2020-09-30T19:07:48.718Z,1601492868.718 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2020-09-30T19:07:48.718Z,1601492868.718 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5263 2020-09-30T19:07:48.722Z,1601492868.722 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2020-09-30T19:07:48.737Z,1601492868.737 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2020-09-30T19:07:48.738Z,1601492868.738 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2020-09-30T19:07:48.738Z,1601492868.738 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5264 2020-09-30T19:07:48.739Z,1601492868.739 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2020-09-30T19:07:48.740Z,1601492868.740 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2020-09-30T19:07:48.740Z,1601492868.740 [logger ThreadHandler](INFO): Protected caller Thread ID is 5265 2020-09-30T19:07:48.742Z,1601492868.742 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2020-09-30T19:07:48.743Z,1601492868.743 [Supervisor](INFO): Looking for Config files in directory: Config/ 2020-09-30T19:07:48.744Z,1601492868.744 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2020-09-30T19:07:48.837Z,1601492868.837 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2020-09-30T19:07:48.838Z,1601492868.838 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2020-09-30T19:07:49.412Z,1601492869.412 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2020-09-30T19:07:49.412Z,1601492869.412 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2020-09-30T19:07:49.604Z,1601492869.604 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2020-09-30T19:07:49.604Z,1601492869.604 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2020-09-30T19:07:49.701Z,1601492869.701 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2020-09-30T19:07:49.702Z,1601492869.702 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2020-09-30T19:07:49.801Z,1601492869.801 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2020-09-30T19:07:49.801Z,1601492869.801 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2020-09-30T19:07:49.881Z,1601492869.881 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2020-09-30T19:07:50.017Z,1601492870.017 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2020-09-30T19:07:50.018Z,1601492870.018 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2020-09-30T19:07:50.300Z,1601492870.300 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2020-09-30T19:07:50.301Z,1601492870.301 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2020-09-30T19:07:50.742Z,1601492870.742 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2020-09-30T19:07:50.742Z,1601492870.742 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2020-09-30T19:07:50.883Z,1601492870.883 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2020-09-30T19:07:50.883Z,1601492870.883 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2020-09-30T19:07:51.066Z,1601492871.066 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2020-09-30T19:07:51.066Z,1601492871.066 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2020-09-30T19:07:51.548Z,1601492871.548 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2020-09-30T19:07:51.548Z,1601492871.548 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2020-09-30T19:07:51.759Z,1601492871.759 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2020-09-30T19:07:51.760Z,1601492871.760 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2020-09-30T19:07:51.955Z,1601492871.955 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2020-09-30T19:07:51.955Z,1601492871.955 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2020-09-30T19:07:52.355Z,1601492872.355 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2020-09-30T19:07:52.355Z,1601492872.355 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2020-09-30T19:07:52.669Z,1601492872.669 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2020-09-30T19:07:52.671Z,1601492872.671 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/ 2020-09-30T19:07:52.672Z,1601492872.672 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg 2020-09-30T19:07:52.754Z,1601492872.754 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg 2020-09-30T19:07:52.905Z,1601492872.905 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg 2020-09-30T19:07:53.010Z,1601492873.010 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg 2020-09-30T19:07:53.093Z,1601492873.093 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg 2020-09-30T19:07:53.186Z,1601492873.186 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg 2020-09-30T19:07:53.387Z,1601492873.387 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg 2020-09-30T19:07:53.747Z,1601492873.747 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2020-09-30T19:07:53.747Z,1601492873.747 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg 2020-09-30T19:07:53.903Z,1601492873.903 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg 2020-09-30T19:07:54.107Z,1601492874.107 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg 2020-09-30T19:07:54.341Z,1601492874.341 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg 2020-09-30T19:07:54.442Z,1601492874.442 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/ 2020-09-30T19:07:54.442Z,1601492874.442 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2020-09-30T19:07:54.459Z,1601492874.459 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2020-09-30T19:07:54.536Z,1601492874.536 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2020-09-30T19:07:54.536Z,1601492874.536 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2020-09-30T19:07:54.582Z,1601492874.582 [VerticalControl](DEBUG): Construct VerticalControl. 2020-09-30T19:07:54.693Z,1601492874.693 [VerticalControl] Loaded 2020-09-30T19:07:54.694Z,1601492874.694 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2020-09-30T19:07:54.694Z,1601492874.694 [HorizontalControl](DEBUG): Construct HorizontalControl. 2020-09-30T19:07:54.774Z,1601492874.774 [HorizontalControl] Loaded 2020-09-30T19:07:54.775Z,1601492874.775 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2020-09-30T19:07:54.775Z,1601492874.775 [SpeedControl](DEBUG): Construct SpeedControl. 2020-09-30T19:07:54.781Z,1601492874.781 [SpeedControl] Loaded 2020-09-30T19:07:54.781Z,1601492874.781 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2020-09-30T19:07:54.782Z,1601492874.782 [LoopControl](DEBUG): Construct LoopControl. 2020-09-30T19:07:54.782Z,1601492874.782 [LoopControl] Loaded 2020-09-30T19:07:54.783Z,1601492874.783 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2020-09-30T19:07:54.783Z,1601492874.783 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2020-09-30T19:07:54.784Z,1601492874.784 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2020-09-30T19:07:54.824Z,1601492874.824 [DepthRateCalculator] Loaded 2020-09-30T19:07:54.824Z,1601492874.824 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2020-09-30T19:07:54.829Z,1601492874.829 [PitchRateCalculator] Loaded 2020-09-30T19:07:54.829Z,1601492874.829 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2020-09-30T19:07:54.845Z,1601492874.845 [SpeedCalculator] Loaded 2020-09-30T19:07:54.845Z,1601492874.845 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2020-09-30T19:07:54.866Z,1601492874.866 [TempGradientCalculator] Loaded 2020-09-30T19:07:54.866Z,1601492874.866 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2020-09-30T19:07:54.871Z,1601492874.871 [YawRateCalculator] Loaded 2020-09-30T19:07:54.871Z,1601492874.871 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2020-09-30T19:07:54.900Z,1601492874.900 [ElevatorOffsetCalculator] Loaded 2020-09-30T19:07:54.900Z,1601492874.900 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2020-09-30T19:07:54.901Z,1601492874.901 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2020-09-30T19:07:54.901Z,1601492874.901 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2020-09-30T19:07:54.928Z,1601492874.928 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2020-09-30T19:07:54.928Z,1601492874.928 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2020-09-30T19:07:55.013Z,1601492875.013 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2020-09-30T19:07:55.014Z,1601492875.014 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2020-09-30T19:07:55.297Z,1601492875.297 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2020-09-30T19:07:55.297Z,1601492875.297 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2020-09-30T19:07:55.396Z,1601492875.396 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2020-09-30T19:07:55.397Z,1601492875.397 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2020-09-30T19:07:55.722Z,1601492875.722 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation 2020-09-30T19:07:55.727Z,1601492875.727 [AHRS_M2](INFO): created writer for : platform_orientation 2020-09-30T19:07:55.729Z,1601492875.729 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation 2020-09-30T19:07:55.734Z,1601492875.734 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation 2020-09-30T19:07:55.734Z,1601492875.734 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle 2020-09-30T19:07:55.739Z,1601492875.739 [AHRS_M2](INFO): created writer for : platform_pitch_angle 2020-09-30T19:07:55.740Z,1601492875.740 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle 2020-09-30T19:07:55.746Z,1601492875.746 [AHRS_M2](INFO): created writer for : platform_roll_angle 2020-09-30T19:07:55.816Z,1601492875.816 [AHRS_M2] Loaded 2020-09-30T19:07:55.816Z,1601492875.816 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2020-09-30T19:07:55.892Z,1601492875.892 [DataOverHttps] Loaded 2020-09-30T19:07:55.893Z,1601492875.893 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2020-09-30T19:07:55.894Z,1601492875.894 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4090E4E0 2020-09-30T19:07:55.894Z,1601492875.894 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5347 2020-09-30T19:07:55.924Z,1601492875.924 [Depth_Keller] Loaded 2020-09-30T19:07:55.924Z,1601492875.924 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2020-09-30T19:07:56.037Z,1601492876.037 [NAL9602] Loaded 2020-09-30T19:07:56.037Z,1601492876.037 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2020-09-30T19:07:56.085Z,1601492876.085 [Onboard] Loaded 2020-09-30T19:07:56.085Z,1601492876.085 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2020-09-30T19:07:56.086Z,1601492876.086 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4093E4E0 2020-09-30T19:07:56.086Z,1601492876.086 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 5348 2020-09-30T19:07:56.105Z,1601492876.105 [Radio_Surface] Loaded 2020-09-30T19:07:56.106Z,1601492876.106 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2020-09-30T19:07:56.107Z,1601492876.107 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4096E4E0 2020-09-30T19:07:56.107Z,1601492876.107 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5349 2020-09-30T19:07:56.257Z,1601492876.257 [DAT] Loaded 2020-09-30T19:07:56.258Z,1601492876.258 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2020-09-30T19:07:57.725Z,1601492877.725 [BPC1] Loaded 2020-09-30T19:07:57.726Z,1601492877.726 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2020-09-30T19:07:57.726Z,1601492877.726 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2020-09-30T19:07:57.726Z,1601492877.726 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2020-09-30T19:07:57.842Z,1601492877.842 [DeadReckonUsingMultipleVelocitySources] Loaded 2020-09-30T19:07:57.842Z,1601492877.842 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2020-09-30T19:07:57.863Z,1601492877.863 [NavChart] Loaded 2020-09-30T19:07:57.863Z,1601492877.863 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2020-09-30T19:07:57.867Z,1601492877.867 [UniversalFixResidualReporter] Loaded 2020-09-30T19:07:57.867Z,1601492877.867 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2020-09-30T19:07:57.868Z,1601492877.868 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2020-09-30T19:07:57.868Z,1601492877.868 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2020-09-30T19:07:58.006Z,1601492878.006 [SBIT](DEBUG): Construct Startup Built In Test. 2020-09-30T19:07:58.017Z,1601492878.017 [SBIT] Loaded 2020-09-30T19:07:58.017Z,1601492878.017 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2020-09-30T19:07:58.018Z,1601492878.018 [IBIT](DEBUG): Construct Initiated Built In Test. 2020-09-30T19:07:58.031Z,1601492878.031 [IBIT] Loaded 2020-09-30T19:07:58.031Z,1601492878.031 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2020-09-30T19:07:58.034Z,1601492878.034 [CBIT](DEBUG): Construct Continuous Built In Test. 2020-09-30T19:07:58.174Z,1601492878.174 [CBIT] Loaded 2020-09-30T19:07:58.174Z,1601492878.174 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2020-09-30T19:07:58.175Z,1601492878.175 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2020-09-30T19:07:58.175Z,1601492878.175 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2020-09-30T19:07:58.296Z,1601492878.296 [BuoyancyServo] Loaded 2020-09-30T19:07:58.297Z,1601492878.297 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2020-09-30T19:07:58.325Z,1601492878.325 [ElevatorServo] Loaded 2020-09-30T19:07:58.325Z,1601492878.325 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2020-09-30T19:07:58.353Z,1601492878.353 [MassServo] Loaded 2020-09-30T19:07:58.353Z,1601492878.353 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2020-09-30T19:07:58.380Z,1601492878.380 [RudderServo] Loaded 2020-09-30T19:07:58.381Z,1601492878.381 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2020-09-30T19:07:58.408Z,1601492878.408 [ThrusterServo] Loaded 2020-09-30T19:07:58.408Z,1601492878.408 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2020-09-30T19:07:58.408Z,1601492878.408 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2020-09-30T19:07:58.409Z,1601492878.409 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2020-09-30T19:07:58.459Z,1601492878.459 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2020-09-30T19:07:58.460Z,1601492878.460 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2020-09-30T19:07:58.701Z,1601492878.701 [BackSeatDriver] Loaded 2020-09-30T19:07:58.701Z,1601492878.701 [ComponentRegistry](DEBUG): Component "BackSeatDriver" handled in its own thread. 2020-09-30T19:07:58.702Z,1601492878.702 [BackSeatDriver ThreadHandler](DEBUG): Created PCaller Thread at 40B064E0 2020-09-30T19:07:58.702Z,1601492878.702 [BackSeatDriver ThreadHandler](INFO): Protected caller Thread ID is 5350 2020-09-30T19:07:58.730Z,1601492878.730 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2020-09-30T19:07:58.735Z,1601492878.735 [CTD_Seabird](INFO): created writer for : sea_water_density 2020-09-30T19:07:58.736Z,1601492878.736 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2020-09-30T19:07:58.741Z,1601492878.741 [CTD_Seabird](INFO): created writer for : depth 2020-09-30T19:07:58.741Z,1601492878.741 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2020-09-30T19:07:58.746Z,1601492878.746 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2020-09-30T19:07:58.747Z,1601492878.747 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2020-09-30T19:07:58.752Z,1601492878.752 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2020-09-30T19:07:58.753Z,1601492878.753 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2020-09-30T19:07:58.759Z,1601492878.759 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2020-09-30T19:07:58.760Z,1601492878.760 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2020-09-30T19:07:58.765Z,1601492878.765 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2020-09-30T19:07:58.765Z,1601492878.765 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2020-09-30T19:07:58.770Z,1601492878.770 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2020-09-30T19:07:58.798Z,1601492878.798 [CTD_Seabird] Loaded 2020-09-30T19:07:58.798Z,1601492878.798 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2020-09-30T19:07:58.799Z,1601492878.799 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B364E0 2020-09-30T19:07:58.799Z,1601492878.799 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 5351 2020-09-30T19:07:58.849Z,1601492878.849 [ESPComponent] Loaded 2020-09-30T19:07:58.849Z,1601492878.849 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2020-09-30T19:07:58.870Z,1601492878.870 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470 2020-09-30T19:07:58.871Z,1601492878.871 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470 2020-09-30T19:07:58.875Z,1601492878.875 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650 2020-09-30T19:07:58.875Z,1601492878.875 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650 2020-09-30T19:07:58.879Z,1601492878.879 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl 2020-09-30T19:07:58.879Z,1601492878.879 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl 2020-09-30T19:07:58.883Z,1601492878.883 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm 2020-09-30T19:07:58.884Z,1601492878.884 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm 2020-09-30T19:07:58.888Z,1601492878.888 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm 2020-09-30T19:07:58.888Z,1601492878.888 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm 2020-09-30T19:07:58.892Z,1601492878.892 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm 2020-09-30T19:07:58.892Z,1601492878.892 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm 2020-09-30T19:07:58.896Z,1601492878.896 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm 2020-09-30T19:07:58.897Z,1601492878.897 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm 2020-09-30T19:07:58.901Z,1601492878.901 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water 2020-09-30T19:07:58.905Z,1601492878.905 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water 2020-09-30T19:07:58.906Z,1601492878.906 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-09-30T19:07:58.906Z,1601492878.906 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-09-30T19:07:58.910Z,1601492878.910 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-09-30T19:07:58.910Z,1601492878.910 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-09-30T19:07:58.914Z,1601492878.914 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-09-30T19:07:58.914Z,1601492878.914 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-09-30T19:07:58.919Z,1601492878.919 [WetLabsBB2FL] Loaded 2020-09-30T19:07:58.919Z,1601492878.919 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2020-09-30T19:07:58.920Z,1601492878.920 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B664E0 2020-09-30T19:07:58.920Z,1601492878.920 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5352 2020-09-30T19:07:58.921Z,1601492878.921 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2020-09-30T19:07:58.925Z,1601492878.925 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2020-09-30T19:07:58.926Z,1601492878.926 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2020-09-30T19:07:58.933Z,1601492878.933 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2020-09-30T19:07:58.934Z,1601492878.934 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B964E0 2020-09-30T19:07:58.934Z,1601492878.934 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5353 2020-09-30T19:07:58.939Z,1601492878.939 [Supervisor](INFO): Main Thread ID is 4201 2020-09-30T19:07:58.939Z,1601492878.939 [Supervisor](DEBUG): Running supervisor. 2020-09-30T19:07:58.939Z,1601492878.939 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5354 2020-09-30T19:07:58.942Z,1601492878.942 [controlThread ThreadHandler](INFO): Handler Thread ID is 5355 2020-09-30T19:07:58.942Z,1601492878.942 [controlThread](DEBUG): Initializing ControlThread 2020-09-30T19:07:58.943Z,1601492878.943 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2020-09-30T19:07:58.945Z,1601492878.945 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2020-09-30T19:07:58.946Z,1601492878.946 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2020-09-30T19:07:58.946Z,1601492878.946 [LoopControl](DEBUG): Initialize LoopControlComponent. 2020-09-30T19:07:58.946Z,1601492878.946 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2020-09-30T19:07:58.947Z,1601492878.947 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2020-09-30T19:07:58.947Z,1601492878.947 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2020-09-30T19:07:58.947Z,1601492878.947 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2020-09-30T19:07:58.948Z,1601492878.948 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2020-09-30T19:07:58.948Z,1601492878.948 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2020-09-30T19:07:58.953Z,1601492878.953 [NavChart](DEBUG): Initialize NavChart Navigation. 2020-09-30T19:07:58.954Z,1601492878.954 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2020-09-30T19:07:58.954Z,1601492878.954 [SBIT](INFO): Initialize SBIT Component. 2020-09-30T19:07:58.954Z,1601492878.954 [SBIT](IMPORTANT): git: 2020-09-25 2020-09-30T19:07:58.955Z,1601492878.955 [SBIT](INFO): git hash: 37975fc996eaab47e1849088435330a6c93ea7b4 2020-09-30T19:07:58.955Z,1601492878.955 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2020-09-30T19:07:58.956Z,1601492878.956 [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-09-30T19:07:58.957Z,1601492878.957 [SBIT](INFO): Beginning SBIT in 99.000000 seconds. 2020-09-30T19:07:58.958Z,1601492878.958 [IBIT](INFO): Initialize IBIT Component. 2020-09-30T19:07:58.958Z,1601492878.958 [CBIT](DEBUG): Initialize CBIT Component. 2020-09-30T19:07:58.959Z,1601492878.959 [logger ThreadHandler](INFO): Handler Thread ID is 5356 2020-09-30T19:07:58.972Z,1601492878.972 [CBIT](DEBUG): Initialized mux pins. 2020-09-30T19:07:58.972Z,1601492878.972 [CBIT](DEBUG): Initializing the watchdog timer. 2020-09-30T19:07:58.981Z,1601492878.981 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5357 2020-09-30T19:07:58.982Z,1601492878.982 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2020-09-30T19:07:58.993Z,1601492878.993 [Onboard ThreadHandler](INFO): Handler Thread ID is 5358 2020-09-30T19:07:58.997Z,1601492878.997 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2020-09-30T19:07:58.997Z,1601492878.997 [CBIT](DEBUG): Initializing heartbeat. 2020-09-30T19:07:59.010Z,1601492879.010 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5359 2020-09-30T19:07:59.033Z,1601492879.033 [BackSeatDriver ThreadHandler](INFO): Handler Thread ID is 5360 2020-09-30T19:07:59.052Z,1601492879.052 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 5361 2020-09-30T19:07:59.053Z,1601492879.053 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2020-09-30T19:07:59.058Z,1601492879.058 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5363 2020-09-30T19:07:59.059Z,1601492879.059 [WetLabsBB2FL](INFO): Powering down 2020-09-30T19:07:59.068Z,1601492879.068 [CBIT](DEBUG): Deactivating GF circuits. 2020-09-30T19:07:59.069Z,1601492879.069 [CBIT](DEBUG): Deactivating emergency mode. 2020-09-30T19:07:59.092Z,1601492879.092 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5364 2020-09-30T19:07:59.095Z,1601492879.095 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2020-09-30T19:07:59.096Z,1601492879.096 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2020-09-30T19:07:59.096Z,1601492879.096 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2020-09-30T19:07:59.096Z,1601492879.096 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2020-09-30T19:07:59.096Z,1601492879.096 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2020-09-30T19:07:59.096Z,1601492879.096 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2020-09-30T19:07:59.097Z,1601492879.097 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2020-09-30T19:07:59.097Z,1601492879.097 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2020-09-30T19:07:59.097Z,1601492879.097 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2020-09-30T19:07:59.097Z,1601492879.097 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2020-09-30T19:07:59.097Z,1601492879.097 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2020-09-30T19:07:59.097Z,1601492879.097 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2020-09-30T19:07:59.097Z,1601492879.097 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2020-09-30T19:07:59.098Z,1601492879.098 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2020-09-30T19:07:59.098Z,1601492879.098 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2020-09-30T19:07:59.098Z,1601492879.098 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2020-09-30T19:07:59.104Z,1601492879.104 [CBIT](DEBUG): Backplane powered. 2020-09-30T19:07:59.106Z,1601492879.106 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2020-09-30T19:07:59.121Z,1601492879.121 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2020-09-30T19:07:59.153Z,1601492879.153 [MissionManager](DEBUG): 2020-09-30T19:07:59.160Z,1601492879.160 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2020-09-30T19:07:59.232Z,1601492879.232 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2020-09-30T19:07:59.233Z,1601492879.233 [Default:A.Wait](DEBUG): Construct Wait. 2020-09-30T19:07:59.235Z,1601492879.235 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2020-09-30T19:07:59.284Z,1601492879.284 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2020-09-30T19:07:59.287Z,1601492879.287 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2020-09-30T19:07:59.308Z,1601492879.308 [Default:E.Execute](DEBUG): Construct Execute. 2020-09-30T19:07:59.311Z,1601492879.311 [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-09-30T19:07:59.315Z,1601492879.315 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,NAL9602,DAT,BPC1,ESPComponent,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter, 2020-09-30T19:07:59.333Z,1601492879.333 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2020-09-30T19:07:59.367Z,1601492879.367 [Depth_Keller](ERROR): Pressure reading out of range: 1650.297119 decibar 2020-09-30T19:07:59.386Z,1601492879.386 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2020-09-30T19:07:59.386Z,1601492879.386 [DAT](INFO): Powering up 2020-09-30T19:07:59.387Z,1601492879.387 [DAT](DEBUG): Initializing DAT. 2020-09-30T19:07:59.416Z,1601492879.416 [Radio_Surface](INFO): Powering up 2020-09-30T19:07:59.437Z,1601492879.437 [DepthRateCalculator](ERROR): Depth measurement is not active 2020-09-30T19:07:59.440Z,1601492879.440 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2020-09-30T19:07:59.508Z,1601492879.508 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2020-09-30T19:07:59.520Z,1601492879.520 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2020-09-30T19:07:59.522Z,1601492879.522 [ElevatorServo](DEBUG): Initializing EZServoServo. 2020-09-30T19:07:59.532Z,1601492879.532 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2020-09-30T19:07:59.533Z,1601492879.533 [MassServo](DEBUG): Initializing EZServoServo. 2020-09-30T19:07:59.548Z,1601492879.548 [MassServo](DEBUG): Initializing MassServo. 2020-09-30T19:07:59.549Z,1601492879.549 [RudderServo](DEBUG): Initializing EZServoServo. 2020-09-30T19:07:59.561Z,1601492879.561 [RudderServo](DEBUG): Initializing RudderServo. 2020-09-30T19:07:59.562Z,1601492879.562 [ThrusterServo](DEBUG): Initializing EZServoServo. 2020-09-30T19:07:59.572Z,1601492879.572 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2020-09-30T19:08:00.452Z,1601492880.452 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2020-09-30T19:08:00.452Z,1601492880.452 [RudderServo](FAULT): Rudder failed to initialize 2020-09-30T19:08:00.452Z,1601492880.452 [RudderServo] Communications Fault, FailCount= 1 2020-09-30T19:08:00.452Z,1601492880.452 [RudderServo](ERROR): Communications Fault 2020-09-30T19:08:00.563Z,1601492880.563 [CBIT](ERROR): Communications Fault in component: RudderServo 2020-09-30T19:08:00.729Z,1601492880.729 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-09-30T19:08:00.729Z,1601492880.729 [RudderServo](INFO): Powering down 2020-09-30T19:08:01.391Z,1601492881.391 [RudderServo](DEBUG): Initializing EZServoServo. 2020-09-30T19:08:01.509Z,1601492881.509 [RudderServo](DEBUG): Initializing RudderServo. 2020-09-30T19:08:01.513Z,1601492881.513 [CBIT](INFO): Clearing failed state for component RudderServo 2020-09-30T19:08:01.513Z,1601492881.513 [RudderServo] No Fault, FailCount= 1 2020-09-30T19:08:03.101Z,1601492883.101 [BuoyancyServo](INFO): is needed 2020-09-30T19:08:03.468Z,1601492883.468 [BuoyancyServo](INFO): is needed 2020-09-30T19:08:03.949Z,1601492883.949 [BuoyancyServo](INFO): is needed 2020-09-30T19:08:04.659Z,1601492884.659 [BuoyancyServo](INFO): Pause 2020-09-30T19:08:06.085Z,1601492886.085 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit. 2020-09-30T19:08:09.138Z,1601492889.138 [CBIT](CRITICAL): Environmental Failure. Press:14.439201 PSI. Humidity:57%. Temp:22 C. ABORTING MISSION 2020-09-30T19:08:09.138Z,1601492889.138 [Supervisor](INFO): Stop Mission called by CBIT::monitorEnvironmentals 2020-09-30T19:08:09.510Z,1601492889.510 [CommandLine](FAULT): Scheduling is paused 2020-09-30T19:08:09.510Z,1601492889.510 [CBIT](INFO): Critical error at 20200930T190809 2020-09-30T19:08:13.507Z,1601492893.507 [DAT](INFO): commRate: 800 2020-09-30T19:08:15.526Z,1601492895.526 [DAT](INFO): entering command mode 2020-09-30T19:08:15.930Z,1601492895.930 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:08:16.334Z,1601492896.334 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:08:16.737Z,1601492896.737 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:08:17.141Z,1601492897.141 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:08:17.546Z,1601492897.546 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:08:17.950Z,1601492897.950 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:08:18.354Z,1601492898.354 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:08:18.758Z,1601492898.758 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:08:19.162Z,1601492899.162 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:08:19.566Z,1601492899.566 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:08:19.969Z,1601492899.969 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:08:20.374Z,1601492900.374 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:08:20.778Z,1601492900.778 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:08:21.178Z,1601492901.178 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:08:21.582Z,1601492901.582 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:08:21.992Z,1601492901.992 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:08:22.390Z,1601492902.390 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:08:22.794Z,1601492902.794 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:08:23.202Z,1601492903.202 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:08:23.607Z,1601492903.607 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:08:24.006Z,1601492904.006 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:08:24.422Z,1601492904.422 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:08:24.814Z,1601492904.814 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:08:25.217Z,1601492905.217 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:08:25.630Z,1601492905.630 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:08:26.030Z,1601492906.030 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:08:26.430Z,1601492906.430 [NAL9602](INFO): Powering up NAL9602 2020-09-30T19:08:26.431Z,1601492906.431 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:08:26.841Z,1601492906.841 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:08:27.242Z,1601492907.242 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:08:27.641Z,1601492907.641 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:08:28.050Z,1601492908.050 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:08:28.453Z,1601492908.453 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:08:28.858Z,1601492908.858 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:08:29.262Z,1601492909.262 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:08:29.674Z,1601492909.674 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:08:29.746Z,1601492909.746 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:08:30.070Z,1601492910.070 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:08:30.080Z,1601492910.080 [BuoyancyServo](INFO): Data requested 2020-09-30T19:08:30.133Z,1601492910.133 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:08:30.474Z,1601492910.474 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:08:30.545Z,1601492910.545 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:08:30.878Z,1601492910.878 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:08:30.878Z,1601492910.878 [DAT](FAULT): failed to enter command mode 2020-09-30T19:08:30.949Z,1601492910.949 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:08:31.278Z,1601492911.278 [DAT](INFO): entering command mode 2020-09-30T19:08:31.342Z,1601492911.342 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:08:31.687Z,1601492911.687 [DAT](INFO): setting verbose to 3 2020-09-30T19:08:31.758Z,1601492911.758 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:08:32.103Z,1601492912.103 [DAT](INFO): set verbose to 3 2020-09-30T19:08:32.103Z,1601492912.103 [DAT](INFO): setting DatVerbose to 27440 2020-09-30T19:08:32.224Z,1601492912.224 [BuoyancyServo](INFO): Data requested 2020-09-30T19:08:32.260Z,1601492912.260 [CBIT](INFO): got buoyancy position: 966.798462 2020-09-30T19:08:32.491Z,1601492912.491 [DAT](INFO): set DatVerbose to 27440 2020-09-30T19:08:32.491Z,1601492912.491 [DAT](INFO): setting transmit power to 8 2020-09-30T19:08:32.605Z,1601492912.605 [BuoyancyServo](INFO): is needed 2020-09-30T19:08:32.907Z,1601492912.907 [DAT](INFO): set transmit power to 8 2020-09-30T19:08:32.907Z,1601492912.907 [DAT](INFO): setting local address to 9 2020-09-30T19:08:33.032Z,1601492913.032 [BuoyancyServo](INFO): is needed 2020-09-30T19:08:33.303Z,1601492913.303 [DAT](INFO): set local address to 9 2020-09-30T19:08:33.729Z,1601492913.729 [BuoyancyServo](INFO): Pause 2020-09-30T19:08:37.342Z,1601492917.342 [NAL9602](INFO): NAL9602 initialized 2020-09-30T19:09:02.409Z,1601492942.409 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:09:02.808Z,1601492942.808 [BuoyancyServo](INFO): Data requested 2020-09-30T19:09:02.813Z,1601492942.813 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:09:03.226Z,1601492943.226 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:09:03.636Z,1601492943.636 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:09:04.020Z,1601492944.020 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:09:04.436Z,1601492944.436 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:09:04.864Z,1601492944.864 [BuoyancyServo](INFO): Data requested 2020-09-30T19:09:04.869Z,1601492944.869 [CBIT](INFO): got buoyancy position: 962.500854 2020-09-30T19:09:05.636Z,1601492945.636 [BuoyancyServo](INFO): Pause 2020-09-30T19:09:12.454Z,1601492952.454 [CommandLine](IMPORTANT): got command show variable limitlo 2020-09-30T19:09:12.502Z,1601492952.502 [CommandLine](IMPORTANT): BuoyancyServo.limitLo (count) 2020-09-30T19:09:12.503Z,1601492952.503 [CommandLine](IMPORTANT): ElevatorServo.limitLo (count) 2020-09-30T19:09:12.505Z,1601492952.505 [CommandLine](IMPORTANT): MassServo.limitLo (count) 2020-09-30T19:09:12.505Z,1601492952.505 [CommandLine](IMPORTANT): RudderServo.limitLo (count) 2020-09-30T19:09:12.627Z,1601492952.627 [CommandLine](IMPORTANT): VerticalControl.buoyancyLimitLoCC (cubic_centimeter) 2020-09-30T19:09:21.862Z,1601492961.862 [CommandLine](IMPORTANT): got command configSet VerticalControl.buoyancyLimitLoCC 1000.000000 cubic_centimeter 2020-09-30T19:09:22.216Z,1601492962.216 [BuoyancyServo](INFO): is needed 2020-09-30T19:09:24.296Z,1601492964.296 [BuoyancyServo](INFO): is needed 2020-09-30T19:09:24.664Z,1601492964.664 [BuoyancyServo](INFO): is needed 2020-09-30T19:09:25.057Z,1601492965.057 [BuoyancyServo](INFO): is needed 2020-09-30T19:09:25.469Z,1601492965.469 [BuoyancyServo](INFO): is needed 2020-09-30T19:09:25.939Z,1601492965.939 [BuoyancyServo](INFO): is needed 2020-09-30T19:09:26.345Z,1601492966.345 [BuoyancyServo](INFO): is needed 2020-09-30T19:09:26.713Z,1601492966.713 [BuoyancyServo](INFO): is needed 2020-09-30T19:09:27.117Z,1601492967.117 [BuoyancyServo](INFO): is needed 2020-09-30T19:09:27.506Z,1601492967.506 [BuoyancyServo](INFO): is needed 2020-09-30T19:09:28.289Z,1601492968.289 [BuoyancyServo](INFO): Pause 2020-09-30T19:09:35.140Z,1601492975.140 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:09:35.536Z,1601492975.536 [BuoyancyServo](INFO): Data requested 2020-09-30T19:09:35.541Z,1601492975.541 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:09:35.955Z,1601492975.955 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:09:36.362Z,1601492976.362 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:09:36.749Z,1601492976.749 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:09:37.160Z,1601492977.160 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:09:37.601Z,1601492977.601 [BuoyancyServo](INFO): Data requested 2020-09-30T19:09:37.608Z,1601492977.608 [CBIT](INFO): got buoyancy position: 981.974426 2020-09-30T19:09:38.390Z,1601492978.390 [BuoyancyServo](INFO): Pause 2020-09-30T19:09:38.396Z,1601492978.396 [SBIT](IMPORTANT): Beginning Startup BIT 2020-09-30T19:09:38.401Z,1601492978.401 [CBIT](IMPORTANT): Beginning ground fault scan 2020-09-30T19:09:49.789Z,1601492989.789 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.011639 CHAN A1 (24V): -0.001114 CHAN A2 (12V): -0.007371 CHAN A3 (5V): -0.002758 CHAN B0 (3.3V): -0.000146 CHAN B1 (3.15aV): -0.000437 CHAN B2 (3.15bV): 0.000678 CHAN B3 (GND): 0.001966 OPEN: 0.004511 Full Scale Calc: 4.765 mA, -1.589 mA 2020-09-30T19:10:07.969Z,1601493007.969 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:10:08.308Z,1601493008.308 [BuoyancyServo](INFO): Data requested 2020-09-30T19:10:08.345Z,1601493008.345 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:10:08.782Z,1601493008.782 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:10:09.197Z,1601493009.197 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:10:09.652Z,1601493009.652 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:10:09.957Z,1601493009.957 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:10:10.356Z,1601493010.356 [BuoyancyServo](INFO): Data requested 2020-09-30T19:10:10.362Z,1601493010.362 [CBIT](INFO): got buoyancy position: 983.720276 2020-09-30T19:10:11.149Z,1601493011.149 [BuoyancyServo](INFO): Pause 2020-09-30T19:10:17.323Z,1601493017.323 [CommandLine](IMPORTANT): got command configSet VerticalControl.buoyancyLimitLoCC 1100.000000 cubic_centimeter 2020-09-30T19:10:26.983Z,1601493026.983 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005249 2020-09-30T19:10:32.147Z,1601493032.147 [SBIT](IMPORTANT): SBIT PASSED 2020-09-30T19:10:32.147Z,1601493032.147 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2020-09-30T19:10:32.148Z,1601493032.148 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool; 2020-09-30T19:10:32.148Z,1601493032.148 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count; 2020-09-30T19:10:32.148Z,1601493032.148 [SBIT](IMPORTANT): CBIT.gf24Offset=145 microampere; 2020-09-30T19:10:32.148Z,1601493032.148 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool; 2020-09-30T19:10:32.148Z,1601493032.148 [SBIT](IMPORTANT): DAT.sbdAddress=-1 enum; 2020-09-30T19:10:32.148Z,1601493032.148 [SBIT](IMPORTANT): DAT.surfaceThreshold=100 meter; 2020-09-30T19:10:32.149Z,1601493032.149 [SBIT](IMPORTANT): DAT.verbosity=0 count; 2020-09-30T19:10:32.149Z,1601493032.149 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2020-09-30T19:10:32.149Z,1601493032.149 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool; 2020-09-30T19:10:32.149Z,1601493032.149 [SBIT](IMPORTANT): Express linearApproximation AMEcho.altitude 1.000000 meter; 2020-09-30T19:10:32.149Z,1601493032.149 [SBIT](IMPORTANT): Express none ThrusterServo.component_avgCurrent; 2020-09-30T19:10:32.149Z,1601493032.149 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 1.000000 liter_per_second; 2020-09-30T19:10:32.149Z,1601493032.149 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter; 2020-09-30T19:10:32.149Z,1601493032.149 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=13 volt; 2020-09-30T19:10:32.149Z,1601493032.149 [SBIT](IMPORTANT): RDI_Pathfinder.loadAtStartup=0 bool; 2020-09-30T19:10:32.150Z,1601493032.150 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=170 cubic_centimeter; 2020-09-30T19:10:32.150Z,1601493032.150 [SBIT](IMPORTANT): VerticalControl.massDefault=20 millimeter; 2020-09-30T19:10:32.150Z,1601493032.150 [SBIT](IMPORTANT): WetLabsUBAT.loadAtStartup=0 bool; 2020-09-30T19:10:32.569Z,1601493032.569 [MissionManager](IMPORTANT): Started mission Startup 2020-09-30T19:10:32.570Z,1601493032.570 [Startup] Running Loop=1 2020-09-30T19:10:32.570Z,1601493032.570 [Startup](DEBUG): Aggregate::initialize Startup 2020-09-30T19:10:32.570Z,1601493032.570 [Startup:A.GoToSurface] Running Loop=1 2020-09-30T19:10:32.570Z,1601493032.570 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-09-30T19:10:32.571Z,1601493032.571 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-09-30T19:10:32.571Z,1601493032.571 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-09-30T19:10:32.571Z,1601493032.571 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-09-30T19:10:32.572Z,1601493032.572 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-09-30T19:10:32.572Z,1601493032.572 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-09-30T19:10:32.573Z,1601493032.573 [Startup:StartupSatComms] Running Loop=1 2020-09-30T19:10:32.574Z,1601493032.574 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2020-09-30T19:10:32.574Z,1601493032.574 [Startup:StartupSatComms:A] Running Loop=1 2020-09-30T19:10:32.578Z,1601493032.578 [BuoyancyServo](INFO): is needed 2020-09-30T19:10:33.110Z,1601493033.110 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2020-09-30T19:10:34.677Z,1601493034.677 [BuoyancyServo](INFO): is needed 2020-09-30T19:10:35.057Z,1601493035.057 [BuoyancyServo](INFO): is needed 2020-09-30T19:10:35.425Z,1601493035.425 [BuoyancyServo](INFO): is needed 2020-09-30T19:10:35.825Z,1601493035.825 [BuoyancyServo](INFO): is needed 2020-09-30T19:10:36.242Z,1601493036.242 [BuoyancyServo](INFO): is needed 2020-09-30T19:10:36.621Z,1601493036.621 [BuoyancyServo](INFO): is needed 2020-09-30T19:10:37.045Z,1601493037.045 [BuoyancyServo](INFO): is needed 2020-09-30T19:10:37.429Z,1601493037.429 [BuoyancyServo](INFO): is needed 2020-09-30T19:10:37.849Z,1601493037.849 [BuoyancyServo](INFO): is needed 2020-09-30T19:10:38.260Z,1601493038.260 [BuoyancyServo](INFO): is needed 2020-09-30T19:10:38.663Z,1601493038.663 [BuoyancyServo](INFO): is needed 2020-09-30T19:10:39.079Z,1601493039.079 [BuoyancyServo](INFO): is needed 2020-09-30T19:10:39.476Z,1601493039.476 [BuoyancyServo](INFO): is needed 2020-09-30T19:10:39.873Z,1601493039.873 [BuoyancyServo](INFO): is needed 2020-09-30T19:10:40.277Z,1601493040.277 [BuoyancyServo](INFO): is needed 2020-09-30T19:10:40.672Z,1601493040.672 [BuoyancyServo](INFO): is needed 2020-09-30T19:10:40.684Z,1601493040.684 [CBIT](INFO): got buoyancy position: 966.932739 2020-09-30T19:10:40.685Z,1601493040.685 [CBIT](INFO): Buoyancy position below lower volume limit. VB valve could be leaking! 2020-09-30T19:10:41.101Z,1601493041.101 [BuoyancyServo](INFO): Data requested 2020-09-30T19:10:41.106Z,1601493041.106 [CBIT](INFO): got buoyancy position: 966.798462 2020-09-30T19:10:41.106Z,1601493041.106 [CBIT](INFO): Buoyancy position below lower volume limit. VB valve could be leaking! 2020-09-30T19:10:41.501Z,1601493041.501 [BuoyancyServo](INFO): Data requested 2020-09-30T19:10:41.505Z,1601493041.505 [CBIT](INFO): got buoyancy position: 964.649658 2020-09-30T19:10:41.505Z,1601493041.505 [CBIT](FAULT): Buoyancy position below lower volume limit. VB valve could be leaking! 2020-09-30T19:10:41.505Z,1601493041.505 [CBIT](INFO): Current buoyancy position: 964.649658 2020-09-30T19:10:42.298Z,1601493042.298 [BuoyancyServo](INFO): Pause 2020-09-30T19:10:59.234Z,1601493059.234 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2020-09-30T19:10:59.234Z,1601493059.234 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-09-30T19:10:59.252Z,1601493059.252 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-09-30T19:10:59.662Z,1601493059.662 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-09-30T19:10:59.662Z,1601493059.662 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2020-09-30T19:11:11.761Z,1601493071.761 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:11:12.166Z,1601493072.165 [BuoyancyServo](INFO): Data requested 2020-09-30T19:11:12.170Z,1601493072.170 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:11:12.574Z,1601493072.574 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:11:12.984Z,1601493072.984 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:11:13.423Z,1601493073.423 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:11:13.841Z,1601493073.841 [BuoyancyServo](INFO): Data requested 2020-09-30T19:11:13.851Z,1601493073.851 [CBIT](INFO): got buoyancy position: 968.678711 2020-09-30T19:11:14.308Z,1601493074.308 [BuoyancyServo](INFO): is needed 2020-09-30T19:11:14.633Z,1601493074.633 [BuoyancyServo](INFO): is needed 2020-09-30T19:11:15.397Z,1601493075.397 [BuoyancyServo](INFO): Pause 2020-09-30T19:11:32.766Z,1601493092.766 [Startup:StartupSatComms:A](INFO): Timed out from 2020-09-30T19:10:32.6Z 2020-09-30T19:11:32.766Z,1601493092.766 [Startup:StartupSatComms:A] Stopped 2020-09-30T19:11:32.766Z,1601493092.766 [Startup:StartupSatComms:B] Running Loop=1 2020-09-30T19:11:33.166Z,1601493093.166 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2020-09-30T19:11:36.610Z,1601493096.610 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200930T184845/Courier0007.lzma 2020-09-30T19:11:36.974Z,1601493096.974 [DataOverHttps](INFO): Moved sent file to Logs/20200930T184845/Courier0007.lzma.bak 2020-09-30T19:11:36.974Z,1601493096.974 [DataOverHttps](INFO): SBD MOMSN=12690880 2020-09-30T19:11:44.214Z,1601493104.214 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:11:44.566Z,1601493104.566 [BuoyancyServo](INFO): Data requested 2020-09-30T19:11:44.570Z,1601493104.570 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:11:44.986Z,1601493104.986 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:11:45.394Z,1601493105.394 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:11:45.782Z,1601493105.782 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:11:46.193Z,1601493106.193 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:11:46.668Z,1601493106.668 [BuoyancyServo](INFO): Data requested 2020-09-30T19:11:46.673Z,1601493106.673 [CBIT](INFO): got buoyancy position: 968.812988 2020-09-30T19:11:47.032Z,1601493107.032 [BuoyancyServo](INFO): is needed 2020-09-30T19:11:47.425Z,1601493107.425 [BuoyancyServo](INFO): is needed 2020-09-30T19:11:48.233Z,1601493108.233 [BuoyancyServo](INFO): Pause 2020-09-30T19:11:52.516Z,1601493112.516 [DataOverHttps](INFO): Sending 250 bytes from file Logs/20200930T190505/Courier0000.lzma 2020-09-30T19:11:53.518Z,1601493113.518 [DataOverHttps](INFO): Moved sent file to Logs/20200930T190505/Courier0000.lzma.bak 2020-09-30T19:11:53.518Z,1601493113.518 [DataOverHttps](INFO): SBD MOMSN=12690882 2020-09-30T19:12:09.340Z,1601493129.340 [DataOverHttps](INFO): Sending 250 bytes from file Logs/20200930T190748/Courier0000.lzma 2020-09-30T19:12:10.342Z,1601493130.342 [DataOverHttps](INFO): Moved sent file to Logs/20200930T190748/Courier0000.lzma.bak 2020-09-30T19:12:10.342Z,1601493130.342 [DataOverHttps](INFO): SBD MOMSN=12690886 2020-09-30T19:12:11.274Z,1601493131.274 [CommandLine](IMPORTANT): got command configSet VerticalControl.buoyancyLimitLoCC 900.000000 cubic_centimeter 2020-09-30T19:12:16.898Z,1601493136.898 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:12:17.311Z,1601493137.311 [BuoyancyServo](INFO): Data requested 2020-09-30T19:12:17.319Z,1601493137.319 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:12:17.740Z,1601493137.740 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:12:18.106Z,1601493138.106 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:12:18.521Z,1601493138.521 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:12:18.921Z,1601493138.921 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:12:19.373Z,1601493139.373 [BuoyancyServo](INFO): Data requested 2020-09-30T19:12:19.377Z,1601493139.377 [CBIT](INFO): got buoyancy position: 970.961792 2020-09-30T19:12:19.776Z,1601493139.776 [BuoyancyServo](INFO): is needed 2020-09-30T19:12:20.165Z,1601493140.165 [BuoyancyServo](INFO): is needed 2020-09-30T19:12:21.014Z,1601493141.014 [BuoyancyServo](INFO): Pause 2020-09-30T19:12:27.036Z,1601493147.036 [DataOverHttps](INFO): Sending 213 bytes from file Logs/20200930T184845/Express0008.lzma 2020-09-30T19:12:28.038Z,1601493148.038 [DataOverHttps](INFO): Moved sent file to Logs/20200930T184845/Express0008.lzma.bak 2020-09-30T19:12:28.038Z,1601493148.038 [DataOverHttps](INFO): SBD MOMSN=12690896 2020-09-30T19:12:33.050Z,1601493153.050 [Startup:StartupSatComms:B](INFO): Timed out from 2020-09-30T19:11:32.8Z 2020-09-30T19:12:33.050Z,1601493153.050 [Startup:StartupSatComms:B] Stopped 2020-09-30T19:12:33.050Z,1601493153.050 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2020-09-30T19:12:33.050Z,1601493153.050 [Startup:StartupSatComms] Stopped 2020-09-30T19:12:33.050Z,1601493153.050 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2020-09-30T19:12:33.051Z,1601493153.051 [Startup](INFO): Completed Startup 2020-09-30T19:12:33.051Z,1601493153.051 [MissionManager](INFO): Startup is completed. 2020-09-30T19:12:33.051Z,1601493153.051 [MissionManager](INFO): Uninitializing Mission Startup 2020-09-30T19:12:33.051Z,1601493153.051 [Startup] Stopped 2020-09-30T19:12:33.051Z,1601493153.051 [Startup](DEBUG): Aggregate::uninitialize Startup 2020-09-30T19:12:33.051Z,1601493153.051 [Startup:A.GoToSurface] Stopped 2020-09-30T19:12:33.051Z,1601493153.051 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-09-30T19:12:33.445Z,1601493153.445 [MissionManager](IMPORTANT): Started mission Default 2020-09-30T19:12:33.445Z,1601493153.445 [Default] Running Loop=1 2020-09-30T19:12:33.445Z,1601493153.445 [Default](DEBUG): Aggregate::initialize Default 2020-09-30T19:12:33.446Z,1601493153.446 [Default:B.GoToSurface] Running Loop=1 2020-09-30T19:12:33.446Z,1601493153.446 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-09-30T19:12:33.446Z,1601493153.446 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-09-30T19:12:33.446Z,1601493153.446 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-09-30T19:12:33.446Z,1601493153.446 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-09-30T19:12:33.447Z,1601493153.447 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-09-30T19:12:33.447Z,1601493153.447 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-09-30T19:12:33.447Z,1601493153.447 [Default:A.Wait] Running Loop=1 2020-09-30T19:12:33.447Z,1601493153.447 [Default:A.Wait](DEBUG): Initialize Wait Component. 2020-09-30T19:12:45.117Z,1601493165.117 [DataOverHttps](INFO): Sending 683 bytes from file Logs/20200930T190505/Express0001.lzma 2020-09-30T19:12:46.118Z,1601493166.118 [DataOverHttps](INFO): Moved sent file to Logs/20200930T190505/Express0001.lzma.bak 2020-09-30T19:12:46.118Z,1601493166.118 [DataOverHttps](INFO): SBD MOMSN=12690941 2020-09-30T19:12:46.810Z,1601493166.810 [Default:A.Wait](INFO): Done Waiting. 2020-09-30T19:12:46.810Z,1601493166.810 [Default:A.Wait] Stopped 2020-09-30T19:12:46.811Z,1601493166.811 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2020-09-30T19:12:47.197Z,1601493167.197 [Default:CheckIn] Running Loop=1 2020-09-30T19:12:47.197Z,1601493167.197 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-09-30T19:12:47.198Z,1601493167.198 [Default:CheckIn:Read_GPS] Running Loop=1 2020-09-30T19:12:47.590Z,1601493167.590 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2020-09-30T19:12:49.630Z,1601493169.630 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:12:50.014Z,1601493170.014 [BuoyancyServo](INFO): Data requested 2020-09-30T19:12:50.018Z,1601493170.018 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:12:50.423Z,1601493170.423 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:12:50.837Z,1601493170.837 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:12:51.256Z,1601493171.256 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:12:51.724Z,1601493171.724 [BuoyancyServo](INFO): Data requested 2020-09-30T19:12:51.729Z,1601493171.729 [CBIT](INFO): got buoyancy position: 973.244873 2020-09-30T19:12:52.084Z,1601493172.084 [BuoyancyServo](INFO): is needed 2020-09-30T19:12:52.489Z,1601493172.489 [BuoyancyServo](INFO): is needed 2020-09-30T19:12:53.305Z,1601493173.305 [BuoyancyServo](INFO): Pause 2020-09-30T19:12:57.706Z,1601493177.706 [CommandLine](IMPORTANT): got command configSet VerticalControl.buoyancyLimitLoCC 1100.000000 cubic_centimeter 2020-09-30T19:13:21.946Z,1601493201.946 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:13:22.353Z,1601493202.353 [BuoyancyServo](INFO): Data requested 2020-09-30T19:13:22.357Z,1601493202.357 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:13:22.773Z,1601493202.773 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:13:23.162Z,1601493203.162 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:13:23.564Z,1601493203.564 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:13:23.969Z,1601493203.969 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:13:24.420Z,1601493204.420 [BuoyancyServo](INFO): Data requested 2020-09-30T19:13:24.425Z,1601493204.425 [CBIT](INFO): got buoyancy position: 973.244873 2020-09-30T19:13:24.425Z,1601493204.425 [CBIT](INFO): Buoyancy position below lower volume limit. VB valve could be leaking! 2020-09-30T19:13:24.785Z,1601493204.785 [BuoyancyServo](INFO): Data requested 2020-09-30T19:13:24.790Z,1601493204.790 [CBIT](INFO): got buoyancy position: 973.244873 2020-09-30T19:13:24.790Z,1601493204.790 [CBIT](INFO): Buoyancy position below lower volume limit. VB valve could be leaking! 2020-09-30T19:13:25.189Z,1601493205.189 [BuoyancyServo](INFO): Data requested 2020-09-30T19:13:25.205Z,1601493205.205 [CBIT](INFO): got buoyancy position: 973.244873 2020-09-30T19:13:25.205Z,1601493205.205 [CBIT](FAULT): Buoyancy position below lower volume limit. VB valve could be leaking! 2020-09-30T19:13:25.206Z,1601493205.206 [CBIT](INFO): Current buoyancy position: 973.244873 2020-09-30T19:13:25.596Z,1601493205.596 [BuoyancyServo](INFO): is needed 2020-09-30T19:13:26.012Z,1601493206.012 [BuoyancyServo](INFO): is needed 2020-09-30T19:13:26.401Z,1601493206.401 [BuoyancyServo](INFO): is needed 2020-09-30T19:13:26.817Z,1601493206.817 [BuoyancyServo](INFO): is needed 2020-09-30T19:13:27.602Z,1601493207.602 [BuoyancyServo](INFO): Pause 2020-09-30T19:13:40.498Z,1601493220.498 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-09-30T19:13:55.502Z,1601493235.502 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:13:55.940Z,1601493235.940 [BuoyancyServo](INFO): Data requested 2020-09-30T19:13:55.944Z,1601493235.944 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:13:56.288Z,1601493236.288 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:13:56.705Z,1601493236.705 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:13:57.122Z,1601493237.122 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:13:57.572Z,1601493237.572 [BuoyancyServo](INFO): Data requested 2020-09-30T19:13:57.577Z,1601493237.577 [CBIT](INFO): got buoyancy position: 973.110596 2020-09-30T19:13:57.953Z,1601493237.953 [BuoyancyServo](INFO): is needed 2020-09-30T19:13:58.348Z,1601493238.348 [BuoyancyServo](INFO): is needed 2020-09-30T19:13:59.125Z,1601493239.125 [BuoyancyServo](INFO): Pause 2020-09-30T19:14:00.330Z,1601493240.330 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2020-09-30T19:14:00.330Z,1601493240.330 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-09-30T19:14:00.340Z,1601493240.340 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-09-30T19:14:00.881Z,1601493240.881 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-09-30T19:14:00.881Z,1601493240.881 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2020-09-30T19:14:27.826Z,1601493267.826 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:14:28.193Z,1601493268.193 [BuoyancyServo](INFO): Data requested 2020-09-30T19:14:28.196Z,1601493268.196 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:14:28.629Z,1601493268.629 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:14:29.042Z,1601493269.042 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:14:29.441Z,1601493269.441 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:14:29.985Z,1601493269.985 [BuoyancyServo](INFO): Data requested 2020-09-30T19:14:29.989Z,1601493269.989 [CBIT](INFO): got buoyancy position: 975.259399 2020-09-30T19:14:30.269Z,1601493270.269 [BuoyancyServo](INFO): is needed 2020-09-30T19:14:30.661Z,1601493270.661 [BuoyancyServo](INFO): is needed 2020-09-30T19:14:31.072Z,1601493271.072 [BuoyancyServo](INFO): is needed 2020-09-30T19:14:31.472Z,1601493271.472 [BuoyancyServo](INFO): is needed 2020-09-30T19:14:31.933Z,1601493271.933 [BuoyancyServo](INFO): is needed 2020-09-30T19:14:32.313Z,1601493272.313 [BuoyancyServo](INFO): is needed 2020-09-30T19:14:32.692Z,1601493272.692 [BuoyancyServo](INFO): is needed 2020-09-30T19:14:33.085Z,1601493273.085 [BuoyancyServo](INFO): is needed 2020-09-30T19:14:33.476Z,1601493273.476 [BuoyancyServo](INFO): is needed 2020-09-30T19:14:33.888Z,1601493273.888 [BuoyancyServo](INFO): is needed 2020-09-30T19:14:34.674Z,1601493274.674 [BuoyancyServo](INFO): Pause 2020-09-30T19:15:00.120Z,1601493300.120 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:15:00.530Z,1601493300.530 [BuoyancyServo](INFO): Data requested 2020-09-30T19:15:00.534Z,1601493300.534 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:15:00.926Z,1601493300.926 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:15:01.342Z,1601493301.342 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:15:01.758Z,1601493301.758 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:15:02.220Z,1601493302.220 [BuoyancyServo](INFO): Data requested 2020-09-30T19:15:02.225Z,1601493302.225 [CBIT](INFO): got buoyancy position: 969.081543 2020-09-30T19:15:02.572Z,1601493302.572 [BuoyancyServo](INFO): is needed 2020-09-30T19:15:02.981Z,1601493302.981 [BuoyancyServo](INFO): is needed 2020-09-30T19:15:03.778Z,1601493303.778 [BuoyancyServo](INFO): Pause 2020-09-30T19:15:09.413Z,1601493309.413 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2020-09-30T19:15:09.417Z,1601493309.417 [BPC1](INFO): Received data from all battery sticks. 2020-09-30T19:15:32.447Z,1601493332.447 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:15:32.854Z,1601493332.854 [BuoyancyServo](INFO): Data requested 2020-09-30T19:15:32.859Z,1601493332.859 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:15:33.267Z,1601493333.267 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:15:33.653Z,1601493333.653 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:15:34.056Z,1601493334.056 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:15:34.464Z,1601493334.464 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:15:34.916Z,1601493334.916 [BuoyancyServo](INFO): Data requested 2020-09-30T19:15:34.921Z,1601493334.921 [CBIT](INFO): got buoyancy position: 970.693237 2020-09-30T19:15:35.293Z,1601493335.293 [BuoyancyServo](INFO): is needed 2020-09-30T19:15:35.717Z,1601493335.717 [BuoyancyServo](INFO): is needed 2020-09-30T19:15:36.499Z,1601493336.499 [BuoyancyServo](INFO): Pause 2020-09-30T19:16:05.171Z,1601493365.171 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:16:05.557Z,1601493365.557 [BuoyancyServo](INFO): Data requested 2020-09-30T19:16:05.562Z,1601493365.562 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:16:05.966Z,1601493365.966 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:16:06.389Z,1601493366.389 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:16:06.793Z,1601493366.793 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:16:07.256Z,1601493367.256 [BuoyancyServo](INFO): Data requested 2020-09-30T19:16:07.261Z,1601493367.261 [CBIT](INFO): got buoyancy position: 971.096069 2020-09-30T19:16:07.609Z,1601493367.609 [BuoyancyServo](INFO): is needed 2020-09-30T19:16:08.025Z,1601493368.025 [BuoyancyServo](INFO): is needed 2020-09-30T19:16:08.807Z,1601493368.807 [BuoyancyServo](INFO): Pause 2020-09-30T19:16:37.491Z,1601493397.491 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:16:37.887Z,1601493397.887 [BuoyancyServo](INFO): Data requested 2020-09-30T19:16:37.891Z,1601493397.891 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:16:38.307Z,1601493398.307 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:16:38.693Z,1601493398.693 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:16:39.097Z,1601493399.097 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:16:39.517Z,1601493399.517 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:16:39.988Z,1601493399.988 [BuoyancyServo](INFO): Data requested 2020-09-30T19:16:39.992Z,1601493399.992 [CBIT](INFO): got buoyancy position: 971.230347 2020-09-30T19:16:40.356Z,1601493400.356 [BuoyancyServo](INFO): is needed 2020-09-30T19:16:40.741Z,1601493400.741 [BuoyancyServo](INFO): is needed 2020-09-30T19:16:41.530Z,1601493401.530 [BuoyancyServo](INFO): Pause 2020-09-30T19:17:01.318Z,1601493421.318 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2020-09-30T19:17:01.318Z,1601493421.318 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-09-30T19:17:01.328Z,1601493421.328 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-09-30T19:17:01.718Z,1601493421.718 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-09-30T19:17:01.718Z,1601493421.718 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2020-09-30T19:17:10.028Z,1601493430.028 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:17:10.400Z,1601493430.400 [BuoyancyServo](INFO): Data requested 2020-09-30T19:17:10.404Z,1601493430.404 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:17:10.819Z,1601493430.819 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:17:11.203Z,1601493431.203 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:17:11.611Z,1601493431.611 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:17:12.015Z,1601493432.015 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:17:12.495Z,1601493432.495 [BuoyancyServo](INFO): Data requested 2020-09-30T19:17:12.500Z,1601493432.500 [CBIT](INFO): got buoyancy position: 973.379150 2020-09-30T19:17:12.867Z,1601493432.867 [BuoyancyServo](INFO): is needed 2020-09-30T19:17:13.279Z,1601493433.279 [BuoyancyServo](INFO): is needed 2020-09-30T19:17:13.667Z,1601493433.667 [BuoyancyServo](INFO): is needed 2020-09-30T19:17:14.063Z,1601493434.063 [BuoyancyServo](INFO): is needed 2020-09-30T19:17:14.475Z,1601493434.475 [BuoyancyServo](INFO): is needed 2020-09-30T19:17:14.871Z,1601493434.871 [BuoyancyServo](INFO): is needed 2020-09-30T19:17:15.664Z,1601493435.664 [BuoyancyServo](INFO): Pause 2020-09-30T19:17:42.761Z,1601493462.761 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:17:43.143Z,1601493463.143 [BuoyancyServo](INFO): Data requested 2020-09-30T19:17:43.147Z,1601493463.147 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:17:43.553Z,1601493463.553 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:17:43.968Z,1601493463.968 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:17:44.378Z,1601493464.378 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:17:44.855Z,1601493464.855 [BuoyancyServo](INFO): Data requested 2020-09-30T19:17:44.859Z,1601493464.859 [CBIT](INFO): got buoyancy position: 973.110596 2020-09-30T19:17:45.207Z,1601493465.207 [BuoyancyServo](INFO): is needed 2020-09-30T19:17:45.599Z,1601493465.599 [BuoyancyServo](INFO): is needed 2020-09-30T19:17:46.376Z,1601493466.376 [BuoyancyServo](INFO): Pause 2020-09-30T19:17:47.600Z,1601493467.600 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-09-30T19:12:47.2Z 2020-09-30T19:17:47.600Z,1601493467.600 [Default:CheckIn:Read_GPS] Stopped 2020-09-30T19:17:47.600Z,1601493467.600 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-09-30T19:17:48.009Z,1601493468.009 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2020-09-30T19:17:55.134Z,1601493475.134 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20200930T190748/Courier0004.lzma 2020-09-30T19:17:56.136Z,1601493476.136 [DataOverHttps](INFO): Moved sent file to Logs/20200930T190748/Courier0004.lzma.bak 2020-09-30T19:17:56.136Z,1601493476.136 [DataOverHttps](INFO): SBD MOMSN=12690965 2020-09-30T19:18:12.732Z,1601493492.732 [DataOverHttps](INFO): Sending 1287 bytes from file Logs/20200930T190748/Express0001.lzma 2020-09-30T19:18:13.732Z,1601493493.732 [DataOverHttps](INFO): Moved sent file to Logs/20200930T190748/Express0001.lzma.bak 2020-09-30T19:18:13.732Z,1601493493.732 [DataOverHttps](INFO): SBD MOMSN=12690967 2020-09-30T19:18:15.094Z,1601493495.094 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:18:15.468Z,1601493495.468 [BuoyancyServo](INFO): Data requested 2020-09-30T19:18:15.477Z,1601493495.477 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:18:15.895Z,1601493495.895 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:18:16.292Z,1601493496.292 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:18:16.675Z,1601493496.675 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:18:17.083Z,1601493497.083 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:18:17.555Z,1601493497.555 [BuoyancyServo](INFO): Data requested 2020-09-30T19:18:17.559Z,1601493497.559 [CBIT](INFO): got buoyancy position: 975.259399 2020-09-30T19:18:17.923Z,1601493497.923 [BuoyancyServo](INFO): is needed 2020-09-30T19:18:18.355Z,1601493498.355 [BuoyancyServo](INFO): is needed 2020-09-30T19:18:18.731Z,1601493498.731 [BuoyancyServo](INFO): is needed 2020-09-30T19:18:19.135Z,1601493499.135 [BuoyancyServo](INFO): is needed 2020-09-30T19:18:19.556Z,1601493499.556 [BuoyancyServo](INFO): is needed 2020-09-30T19:18:19.927Z,1601493499.927 [BuoyancyServo](INFO): is needed 2020-09-30T19:18:20.371Z,1601493500.371 [BuoyancyServo](INFO): is needed 2020-09-30T19:18:20.762Z,1601493500.762 [BuoyancyServo](INFO): is needed 2020-09-30T19:18:21.167Z,1601493501.167 [BuoyancyServo](INFO): is needed 2020-09-30T19:18:21.567Z,1601493501.567 [BuoyancyServo](INFO): is needed 2020-09-30T19:18:22.348Z,1601493502.348 [BuoyancyServo](INFO): Pause 2020-09-30T19:18:30.491Z,1601493510.491 [DataOverHttps](INFO): Sending 329 bytes from file Logs/20200930T190748/Express0005.lzma 2020-09-30T19:18:31.492Z,1601493511.492 [DataOverHttps](INFO): Moved sent file to Logs/20200930T190748/Express0005.lzma.bak 2020-09-30T19:18:31.492Z,1601493511.492 [DataOverHttps](INFO): SBD MOMSN=12691010 2020-09-30T19:18:34.056Z,1601493514.056 [Default:CheckIn:Read_Iridium] Stopped 2020-09-30T19:18:34.056Z,1601493514.056 [Default:CheckIn:C.Wait] Running Loop=1 2020-09-30T19:18:34.056Z,1601493514.056 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-09-30T19:18:39.284Z,1601493519.284 [NAL9602](FAULT): GPS failed to acquire within timeout. 2020-09-30T19:18:39.284Z,1601493519.284 [NAL9602] Data Fault, FailCount= 1 2020-09-30T19:18:39.284Z,1601493519.284 [NAL9602](ERROR): Data Fault 2020-09-30T19:18:39.339Z,1601493519.339 [CBIT](ERROR): Data Fault in component: NAL9602 2020-09-30T19:18:39.684Z,1601493519.684 [NAL9602](INFO): Powering down 2020-09-30T19:18:40.531Z,1601493520.531 [CBIT](INFO): Clearing failed state for component NAL9602 2020-09-30T19:18:40.531Z,1601493520.531 [NAL9602] No Fault, FailCount= 1 2020-09-30T19:18:47.783Z,1601493527.783 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:18:48.188Z,1601493528.188 [BuoyancyServo](INFO): Data requested 2020-09-30T19:18:48.192Z,1601493528.192 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:18:48.598Z,1601493528.598 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:18:49.015Z,1601493529.015 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:18:49.402Z,1601493529.402 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:18:49.875Z,1601493529.875 [BuoyancyServo](INFO): Data requested 2020-09-30T19:18:49.880Z,1601493529.880 [CBIT](INFO): got buoyancy position: 968.812988 2020-09-30T19:18:50.239Z,1601493530.239 [BuoyancyServo](INFO): is needed 2020-09-30T19:18:50.655Z,1601493530.655 [BuoyancyServo](INFO): is needed 2020-09-30T19:18:51.420Z,1601493531.420 [BuoyancyServo](INFO): Pause 2020-09-30T19:19:09.993Z,1601493549.993 [NAL9602](INFO): Powering up NAL9602 2020-09-30T19:19:20.132Z,1601493560.132 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:19:20.532Z,1601493560.532 [BuoyancyServo](INFO): Data requested 2020-09-30T19:19:20.540Z,1601493560.540 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:19:20.905Z,1601493560.905 [NAL9602](INFO): NAL9602 initialized 2020-09-30T19:19:20.962Z,1601493560.962 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:19:21.331Z,1601493561.331 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:19:21.744Z,1601493561.744 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:19:22.158Z,1601493562.158 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:19:22.599Z,1601493562.599 [BuoyancyServo](INFO): Data requested 2020-09-30T19:19:22.603Z,1601493562.603 [CBIT](INFO): got buoyancy position: 970.961792 2020-09-30T19:19:22.973Z,1601493562.973 [BuoyancyServo](INFO): is needed 2020-09-30T19:19:23.367Z,1601493563.367 [BuoyancyServo](INFO): is needed 2020-09-30T19:19:24.172Z,1601493564.172 [BuoyancyServo](INFO): Pause 2020-09-30T19:19:52.844Z,1601493592.844 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:19:53.228Z,1601493593.228 [BuoyancyServo](INFO): Data requested 2020-09-30T19:19:53.232Z,1601493593.232 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:19:53.633Z,1601493593.633 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:19:54.055Z,1601493594.055 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:19:54.464Z,1601493594.464 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:19:54.939Z,1601493594.939 [BuoyancyServo](INFO): Data requested 2020-09-30T19:19:54.943Z,1601493594.943 [CBIT](INFO): got buoyancy position: 972.976318 2020-09-30T19:19:55.295Z,1601493595.295 [BuoyancyServo](INFO): is needed 2020-09-30T19:19:55.708Z,1601493595.708 [BuoyancyServo](INFO): is needed 2020-09-30T19:19:56.460Z,1601493596.460 [BuoyancyServo](INFO): Pause 2020-09-30T19:20:02.115Z,1601493602.115 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2020-09-30T19:20:02.115Z,1601493602.115 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-09-30T19:20:02.125Z,1601493602.125 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-09-30T19:20:02.543Z,1601493602.543 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-09-30T19:20:02.543Z,1601493602.543 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2020-09-30T19:20:25.148Z,1601493625.148 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:20:25.552Z,1601493625.552 [BuoyancyServo](INFO): Data requested 2020-09-30T19:20:25.556Z,1601493625.556 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:20:25.982Z,1601493625.982 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:20:26.379Z,1601493626.379 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:20:26.794Z,1601493626.794 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:20:27.239Z,1601493627.239 [BuoyancyServo](INFO): Data requested 2020-09-30T19:20:27.244Z,1601493627.244 [CBIT](INFO): got buoyancy position: 973.244873 2020-09-30T19:20:27.599Z,1601493627.599 [BuoyancyServo](INFO): is needed 2020-09-30T19:20:28.011Z,1601493628.011 [BuoyancyServo](INFO): is needed 2020-09-30T19:20:28.403Z,1601493628.403 [BuoyancyServo](INFO): is needed 2020-09-30T19:20:28.811Z,1601493628.811 [BuoyancyServo](INFO): is needed 2020-09-30T19:20:29.231Z,1601493629.231 [BuoyancyServo](INFO): is needed 2020-09-30T19:20:29.639Z,1601493629.639 [BuoyancyServo](INFO): is needed 2020-09-30T19:20:30.047Z,1601493630.047 [BuoyancyServo](INFO): is needed 2020-09-30T19:20:30.427Z,1601493630.427 [BuoyancyServo](INFO): is needed 2020-09-30T19:20:31.227Z,1601493631.227 [BuoyancyServo](INFO): Pause 2020-09-30T19:20:57.471Z,1601493657.471 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:20:57.876Z,1601493657.876 [BuoyancyServo](INFO): Data requested 2020-09-30T19:20:57.880Z,1601493657.880 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:20:58.295Z,1601493658.295 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:20:58.684Z,1601493658.684 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:20:59.095Z,1601493659.095 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:20:59.579Z,1601493659.579 [BuoyancyServo](INFO): Data requested 2020-09-30T19:20:59.584Z,1601493659.584 [CBIT](INFO): got buoyancy position: 970.961792 2020-09-30T19:20:59.935Z,1601493659.935 [BuoyancyServo](INFO): is needed 2020-09-30T19:21:00.327Z,1601493660.327 [BuoyancyServo](INFO): is needed 2020-09-30T19:21:01.106Z,1601493661.106 [BuoyancyServo](INFO): Pause 2020-09-30T19:21:29.795Z,1601493689.795 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:21:30.208Z,1601493690.208 [BuoyancyServo](INFO): Data requested 2020-09-30T19:21:30.212Z,1601493690.212 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:21:30.620Z,1601493690.620 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:21:31.037Z,1601493691.037 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:21:31.407Z,1601493691.407 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:21:31.817Z,1601493691.817 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:21:32.267Z,1601493692.267 [BuoyancyServo](INFO): Data requested 2020-09-30T19:21:32.271Z,1601493692.271 [CBIT](INFO): got buoyancy position: 971.499023 2020-09-30T19:21:32.639Z,1601493692.639 [BuoyancyServo](INFO): is needed 2020-09-30T19:21:33.059Z,1601493693.059 [BuoyancyServo](INFO): is needed 2020-09-30T19:21:33.843Z,1601493693.843 [BuoyancyServo](INFO): Pause 2020-09-30T19:22:02.536Z,1601493722.536 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:22:02.948Z,1601493722.948 [BuoyancyServo](INFO): Data requested 2020-09-30T19:22:02.956Z,1601493722.956 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:22:03.345Z,1601493723.345 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:22:03.742Z,1601493723.742 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:22:04.127Z,1601493724.127 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:22:04.536Z,1601493724.536 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:22:05.019Z,1601493725.019 [BuoyancyServo](INFO): Data requested 2020-09-30T19:22:05.032Z,1601493725.032 [CBIT](INFO): got buoyancy position: 972.707642 2020-09-30T19:22:05.375Z,1601493725.375 [BuoyancyServo](INFO): is needed 2020-09-30T19:22:05.771Z,1601493725.771 [BuoyancyServo](INFO): is needed 2020-09-30T19:22:06.576Z,1601493726.576 [BuoyancyServo](INFO): Pause 2020-09-30T19:22:18.427Z,1601493738.427 [CommandLine](IMPORTANT): got command restart application 2020-09-30T19:22:19.431Z,1601493739.431 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2020-09-30T19:22:19.431Z,1601493739.431 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:22:19.553Z,1601493739.553 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2020-09-30T19:22:19.553Z,1601493739.553 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:22:19.554Z,1601493739.554 [CommandLine](INFO): Join timeout helper Thread ID is 5430 2020-09-30T19:22:19.554Z,1601493739.554 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2020-09-30T19:22:19.555Z,1601493739.555 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:22:19.555Z,1601493739.555 [NavChartDb](INFO): Join timeout helper Thread ID is 5431 2020-09-30T19:22:19.643Z,1601493739.643 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2020-09-30T19:22:19.643Z,1601493739.643 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:22:19.658Z,1601493739.658 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2020-09-30T19:22:19.658Z,1601493739.658 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:22:19.658Z,1601493739.658 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5432 2020-09-30T19:22:19.915Z,1601493739.915 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2020-09-30T19:22:19.915Z,1601493739.915 [WetLabsBB2FL](INFO): Powering down 2020-09-30T19:22:19.916Z,1601493739.916 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:22:19.935Z,1601493739.935 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2020-09-30T19:22:19.935Z,1601493739.935 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:22:19.935Z,1601493739.935 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5433 2020-09-30T19:22:20.391Z,1601493740.391 [CTD_Seabird](INFO): Powering down 2020-09-30T19:22:20.403Z,1601493740.403 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2020-09-30T19:22:20.403Z,1601493740.403 [CTD_Seabird](INFO): Powering down 2020-09-30T19:22:20.414Z,1601493740.414 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:22:20.430Z,1601493740.430 [ComponentRegistry](INFO): Shutting down BackSeatDriver ThreadHandler 2020-09-30T19:22:20.431Z,1601493740.431 [BackSeatDriver ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:22:20.431Z,1601493740.431 [BackSeatDriver](INFO): Join timeout helper Thread ID is 5434 2020-09-30T19:22:20.799Z,1601493740.799 [BackSeatDriver ThreadHandler](INFO): Uninitializing protected caller thread. 2020-09-30T19:22:20.800Z,1601493740.800 [BackSeatDriver ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:22:20.815Z,1601493740.815 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2020-09-30T19:22:20.816Z,1601493740.816 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:22:20.816Z,1601493740.816 [Radio_Surface](INFO): Join timeout helper Thread ID is 5435 2020-09-30T19:22:21.203Z,1601493741.203 [Radio_Surface](INFO): Powering down 2020-09-30T19:22:21.204Z,1601493741.204 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2020-09-30T19:22:21.204Z,1601493741.204 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:22:21.226Z,1601493741.226 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2020-09-30T19:22:21.227Z,1601493741.227 [Onboard ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:22:21.227Z,1601493741.227 [Onboard](INFO): Join timeout helper Thread ID is 5436 2020-09-30T19:22:22.023Z,1601493742.023 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2020-09-30T19:22:24.487Z,1601493744.487 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2020-09-30T19:22:24.488Z,1601493744.488 [Onboard ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:22:24.499Z,1601493744.499 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2020-09-30T19:22:24.499Z,1601493744.499 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:22:24.500Z,1601493744.500 [DataOverHttps](INFO): Join timeout helper Thread ID is 5437 2020-09-30T19:22:25.035Z,1601493745.035 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2020-09-30T19:22:25.035Z,1601493745.035 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:22:25.039Z,1601493745.039 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2020-09-30T19:22:25.039Z,1601493745.039 [logger ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:22:25.040Z,1601493745.040 [logger](INFO): Join timeout helper Thread ID is 5438 2020-09-30T19:22:25.075Z,1601493745.075 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2020-09-30T19:22:25.075Z,1601493745.075 [logger ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:22:25.079Z,1601493745.079 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2020-09-30T19:22:25.079Z,1601493745.079 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:22:25.080Z,1601493745.080 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2020-09-30T19:22:25.080Z,1601493745.080 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:22:25.080Z,1601493745.080 [controlThread](INFO): Join timeout helper Thread ID is 5439 2020-09-30T19:22:25.103Z,1601493745.103 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2020-09-30T19:22:25.103Z,1601493745.103 [controlThread](DEBUG): Uninitializing ControlThread 2020-09-30T19:22:25.103Z,1601493745.103 [AHRS_M2](INFO): Powering down 2020-09-30T19:22:25.175Z,1601493745.175 [NAL9602](INFO): Powering down 2020-09-30T19:22:25.176Z,1601493745.176 [DAT](INFO): Powering down 2020-09-30T19:22:25.296Z,1601493745.296 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2020-09-30T19:22:25.297Z,1601493745.297 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2020-09-30T19:22:25.298Z,1601493745.298 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2020-09-30T19:22:25.298Z,1601493745.298 [MissionManager](INFO): Uninitializing Mission Default 2020-09-30T19:22:25.299Z,1601493745.299 [Default] Stopped 2020-09-30T19:22:25.299Z,1601493745.299 [Default](DEBUG): Aggregate::uninitialize Default 2020-09-30T19:22:25.299Z,1601493745.299 [Default:B.GoToSurface] Stopped 2020-09-30T19:22:25.299Z,1601493745.299 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-09-30T19:22:25.299Z,1601493745.299 [Default:CheckIn] Stopped 2020-09-30T19:22:25.299Z,1601493745.299 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-09-30T19:22:25.299Z,1601493745.299 [Default:CheckIn:C.Wait] Stopped 2020-09-30T19:22:25.299Z,1601493745.299 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-09-30T19:22:25.302Z,1601493745.302 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2020-09-30T19:22:25.302Z,1601493745.302 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2020-09-30T19:22:25.302Z,1601493745.302 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2020-09-30T19:22:25.303Z,1601493745.303 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2020-09-30T19:22:25.303Z,1601493745.303 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2020-09-30T19:22:25.303Z,1601493745.303 [BuoyancyServo](INFO): Powering down 2020-09-30T19:22:25.315Z,1601493745.315 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2020-09-30T19:22:25.315Z,1601493745.315 [ElevatorServo](INFO): Powering down 2020-09-30T19:22:25.316Z,1601493745.316 [MassServo](DEBUG): Uninitialize Mass Servo. 2020-09-30T19:22:25.316Z,1601493745.316 [MassServo](INFO): Powering down 2020-09-30T19:22:25.316Z,1601493745.316 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-09-30T19:22:25.317Z,1601493745.317 [RudderServo](INFO): Powering down 2020-09-30T19:22:25.317Z,1601493745.317 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2020-09-30T19:22:25.317Z,1601493745.317 [ThrusterServo](INFO): Powering down 2020-09-30T19:22:25.318Z,1601493745.318 [SBIT](DEBUG): Uninitialize SBIT Component. 2020-09-30T19:22:25.319Z,1601493745.319 [IBIT](DEBUG): Uninitialize IBIT Component. 2020-09-30T19:22:25.319Z,1601493745.319 [CBIT](DEBUG): Uninitialize CBIT Component. 2020-09-30T19:22:25.319Z,1601493745.319 [CBIT](DEBUG): Powering off loads. 2020-09-30T19:22:25.330Z,1601493745.330 [CBIT](DEBUG): Disabling WDT. 2020-09-30T19:22:25.342Z,1601493745.342 [CBIT](DEBUG): Opening all GF detection circuits. 2020-09-30T19:22:25.343Z,1601493745.343 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:22:25.443Z,1601493745.443 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:22:25.445Z,1601493745.445 [Onboard ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:22:25.450Z,1601493745.450 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:22:25.512Z,1601493745.512 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:22:25.517Z,1601493745.517 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:22:25.520Z,1601493745.520 [BackSeatDriver ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:22:25.529Z,1601493745.529 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:22:25.593Z,1601493745.593 [logger ThreadHandler](INFO): Thread cancelled.