2020-09-30T19:26:01.900Z,1601493961.900 [Supervisor](DEBUG): Initializing supervisor. 2020-09-30T19:26:01.903Z,1601493961.903 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0 2020-09-30T19:26:01.904Z,1601493961.904 [SyncHandler](INFO): Protected caller Thread ID is 5579 2020-09-30T19:26:01.904Z,1601493961.904 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread. 2020-09-30T19:26:01.905Z,1601493961.905 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0 2020-09-30T19:26:01.905Z,1601493961.905 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 5580 2020-09-30T19:26:01.909Z,1601493961.909 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread. 2020-09-30T19:26:01.923Z,1601493961.923 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread. 2020-09-30T19:26:01.924Z,1601493961.924 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0 2020-09-30T19:26:01.925Z,1601493961.925 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 5581 2020-09-30T19:26:01.925Z,1601493961.925 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread. 2020-09-30T19:26:01.926Z,1601493961.926 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0 2020-09-30T19:26:01.927Z,1601493961.927 [logger ThreadHandler](INFO): Protected caller Thread ID is 5582 2020-09-30T19:26:01.928Z,1601493961.928 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread. 2020-09-30T19:26:01.929Z,1601493961.929 [Supervisor](INFO): Looking for Config files in directory: Config/ 2020-09-30T19:26:01.930Z,1601493961.930 [Supervisor](INFO): Opening Config file at: Config/secure.cfg 2020-09-30T19:26:02.024Z,1601493962.024 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure 2020-09-30T19:26:02.024Z,1601493962.024 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg 2020-09-30T19:26:02.585Z,1601493962.585 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor 2020-09-30T19:26:02.585Z,1601493962.585 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg 2020-09-30T19:26:02.838Z,1601493962.838 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock 2020-09-30T19:26:02.839Z,1601493962.839 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg 2020-09-30T19:26:02.989Z,1601493962.989 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample 2020-09-30T19:26:02.990Z,1601493962.990 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg 2020-09-30T19:26:03.090Z,1601493963.090 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite 2020-09-30T19:26:03.090Z,1601493963.090 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg 2020-09-30T19:26:03.170Z,1601493963.170 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg 2020-09-30T19:26:03.305Z,1601493963.305 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation 2020-09-30T19:26:03.305Z,1601493963.305 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg 2020-09-30T19:26:03.581Z,1601493963.581 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo 2020-09-30T19:26:03.582Z,1601493963.582 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg 2020-09-30T19:26:04.020Z,1601493964.020 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator 2020-09-30T19:26:04.020Z,1601493964.020 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg 2020-09-30T19:26:04.161Z,1601493964.161 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation 2020-09-30T19:26:04.161Z,1601493964.161 [Supervisor](INFO): Opening Config file at: Config/logger.cfg 2020-09-30T19:26:04.339Z,1601493964.339 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger 2020-09-30T19:26:04.339Z,1601493964.339 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg 2020-09-30T19:26:04.815Z,1601493964.815 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle 2020-09-30T19:26:04.816Z,1601493964.816 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg 2020-09-30T19:26:05.024Z,1601493965.024 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation 2020-09-30T19:26:05.025Z,1601493965.025 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg 2020-09-30T19:26:05.218Z,1601493965.218 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT 2020-09-30T19:26:05.218Z,1601493965.218 [Supervisor](INFO): Opening Config file at: Config/Science.cfg 2020-09-30T19:26:05.612Z,1601493965.612 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science 2020-09-30T19:26:05.612Z,1601493965.612 [Supervisor](INFO): Opening Config file at: Config/Control.cfg 2020-09-30T19:26:05.921Z,1601493965.921 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control 2020-09-30T19:26:05.923Z,1601493965.923 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/ 2020-09-30T19:26:05.923Z,1601493965.923 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/secure.cfg 2020-09-30T19:26:06.005Z,1601493966.005 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Sensor.cfg 2020-09-30T19:26:06.153Z,1601493966.153 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Servo.cfg 2020-09-30T19:26:06.257Z,1601493966.257 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Simulator.cfg 2020-09-30T19:26:06.341Z,1601493966.341 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/logger.cfg 2020-09-30T19:26:06.434Z,1601493966.434 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/vehicle.cfg 2020-09-30T19:26:06.618Z,1601493966.618 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Battery.cfg 2020-09-30T19:26:06.844Z,1601493966.844 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery 2020-09-30T19:26:06.845Z,1601493966.845 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Navigation.cfg 2020-09-30T19:26:06.937Z,1601493966.937 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/BIT.cfg 2020-09-30T19:26:07.032Z,1601493967.032 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Science.cfg 2020-09-30T19:26:07.156Z,1601493967.156 [Supervisor](INFO): Opening Config file at: Config/lrauv-pontus/Control.cfg 2020-09-30T19:26:07.253Z,1601493967.253 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-pontus/root/ 2020-09-30T19:26:07.254Z,1601493967.254 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg 2020-09-30T19:26:07.270Z,1601493967.270 [Module Loader](DEBUG): Loading Module at Modules/Dock.so 2020-09-30T19:26:07.351Z,1601493967.351 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking) 2020-09-30T19:26:07.352Z,1601493967.352 [Module Loader](DEBUG): Loading Module at Modules/Control.so 2020-09-30T19:26:07.400Z,1601493967.400 [VerticalControl](DEBUG): Construct VerticalControl. 2020-09-30T19:26:07.513Z,1601493967.513 [VerticalControl] Loaded 2020-09-30T19:26:07.513Z,1601493967.513 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread. 2020-09-30T19:26:07.514Z,1601493967.514 [HorizontalControl](DEBUG): Construct HorizontalControl. 2020-09-30T19:26:07.594Z,1601493967.594 [HorizontalControl] Loaded 2020-09-30T19:26:07.595Z,1601493967.595 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread. 2020-09-30T19:26:07.595Z,1601493967.595 [SpeedControl](DEBUG): Construct SpeedControl. 2020-09-30T19:26:07.601Z,1601493967.601 [SpeedControl] Loaded 2020-09-30T19:26:07.601Z,1601493967.601 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread. 2020-09-30T19:26:07.602Z,1601493967.602 [LoopControl](DEBUG): Construct LoopControl. 2020-09-30T19:26:07.602Z,1601493967.602 [LoopControl] Loaded 2020-09-30T19:26:07.603Z,1601493967.603 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread. 2020-09-30T19:26:07.603Z,1601493967.603 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control) 2020-09-30T19:26:07.604Z,1601493967.604 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so 2020-09-30T19:26:07.646Z,1601493967.646 [DepthRateCalculator] Loaded 2020-09-30T19:26:07.646Z,1601493967.646 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread. 2020-09-30T19:26:07.651Z,1601493967.651 [PitchRateCalculator] Loaded 2020-09-30T19:26:07.651Z,1601493967.651 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread. 2020-09-30T19:26:07.667Z,1601493967.667 [SpeedCalculator] Loaded 2020-09-30T19:26:07.667Z,1601493967.667 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread. 2020-09-30T19:26:07.688Z,1601493967.688 [TempGradientCalculator] Loaded 2020-09-30T19:26:07.688Z,1601493967.688 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread. 2020-09-30T19:26:07.693Z,1601493967.693 [YawRateCalculator] Loaded 2020-09-30T19:26:07.694Z,1601493967.694 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread. 2020-09-30T19:26:07.723Z,1601493967.723 [ElevatorOffsetCalculator] Loaded 2020-09-30T19:26:07.723Z,1601493967.723 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread. 2020-09-30T19:26:07.724Z,1601493967.724 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components) 2020-09-30T19:26:07.724Z,1601493967.724 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so 2020-09-30T19:26:07.751Z,1601493967.751 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions) 2020-09-30T19:26:07.752Z,1601493967.752 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so 2020-09-30T19:26:07.840Z,1601493967.840 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components) 2020-09-30T19:26:07.840Z,1601493967.840 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so 2020-09-30T19:26:08.375Z,1601493968.375 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands) 2020-09-30T19:26:08.375Z,1601493968.375 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so 2020-09-30T19:26:08.644Z,1601493968.644 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator) 2020-09-30T19:26:08.644Z,1601493968.644 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so 2020-09-30T19:26:08.988Z,1601493968.988 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation 2020-09-30T19:26:08.993Z,1601493968.993 [AHRS_M2](INFO): created writer for : platform_orientation 2020-09-30T19:26:08.995Z,1601493968.995 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation 2020-09-30T19:26:09.000Z,1601493969.000 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation 2020-09-30T19:26:09.001Z,1601493969.001 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle 2020-09-30T19:26:09.006Z,1601493969.006 [AHRS_M2](INFO): created writer for : platform_pitch_angle 2020-09-30T19:26:09.007Z,1601493969.007 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle 2020-09-30T19:26:09.012Z,1601493969.012 [AHRS_M2](INFO): created writer for : platform_roll_angle 2020-09-30T19:26:09.084Z,1601493969.084 [AHRS_M2] Loaded 2020-09-30T19:26:09.084Z,1601493969.084 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread. 2020-09-30T19:26:09.170Z,1601493969.170 [DataOverHttps] Loaded 2020-09-30T19:26:09.170Z,1601493969.170 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread. 2020-09-30T19:26:09.172Z,1601493969.172 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4090E4E0 2020-09-30T19:26:09.172Z,1601493969.172 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 5664 2020-09-30T19:26:09.202Z,1601493969.202 [Depth_Keller] Loaded 2020-09-30T19:26:09.203Z,1601493969.203 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread. 2020-09-30T19:26:09.318Z,1601493969.318 [NAL9602] Loaded 2020-09-30T19:26:09.318Z,1601493969.318 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread. 2020-09-30T19:26:09.368Z,1601493969.368 [Onboard] Loaded 2020-09-30T19:26:09.368Z,1601493969.368 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread. 2020-09-30T19:26:09.369Z,1601493969.369 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 4093E4E0 2020-09-30T19:26:09.369Z,1601493969.369 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 5665 2020-09-30T19:26:09.389Z,1601493969.389 [Radio_Surface] Loaded 2020-09-30T19:26:09.389Z,1601493969.389 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread. 2020-09-30T19:26:09.390Z,1601493969.390 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4096E4E0 2020-09-30T19:26:09.390Z,1601493969.390 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 5666 2020-09-30T19:26:09.544Z,1601493969.544 [DAT] Loaded 2020-09-30T19:26:09.544Z,1601493969.544 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread. 2020-09-30T19:26:11.064Z,1601493971.064 [BPC1] Loaded 2020-09-30T19:26:11.064Z,1601493971.064 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread. 2020-09-30T19:26:11.065Z,1601493971.065 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components) 2020-09-30T19:26:11.065Z,1601493971.065 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so 2020-09-30T19:26:11.184Z,1601493971.184 [DeadReckonUsingMultipleVelocitySources] Loaded 2020-09-30T19:26:11.184Z,1601493971.184 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread. 2020-09-30T19:26:11.205Z,1601493971.205 [NavChart] Loaded 2020-09-30T19:26:11.205Z,1601493971.205 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread. 2020-09-30T19:26:11.209Z,1601493971.209 [UniversalFixResidualReporter] Loaded 2020-09-30T19:26:11.209Z,1601493971.209 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread. 2020-09-30T19:26:11.209Z,1601493971.209 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components) 2020-09-30T19:26:11.210Z,1601493971.210 [Module Loader](DEBUG): Loading Module at Modules/BIT.so 2020-09-30T19:26:11.354Z,1601493971.354 [SBIT](DEBUG): Construct Startup Built In Test. 2020-09-30T19:26:11.365Z,1601493971.365 [SBIT] Loaded 2020-09-30T19:26:11.365Z,1601493971.365 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread. 2020-09-30T19:26:11.366Z,1601493971.366 [IBIT](DEBUG): Construct Initiated Built In Test. 2020-09-30T19:26:11.378Z,1601493971.378 [IBIT] Loaded 2020-09-30T19:26:11.379Z,1601493971.379 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread. 2020-09-30T19:26:11.382Z,1601493971.382 [CBIT](DEBUG): Construct Continuous Built In Test. 2020-09-30T19:26:11.522Z,1601493971.522 [CBIT] Loaded 2020-09-30T19:26:11.522Z,1601493971.522 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread. 2020-09-30T19:26:11.522Z,1601493971.522 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test) 2020-09-30T19:26:11.523Z,1601493971.523 [Module Loader](DEBUG): Loading Module at Modules/Servo.so 2020-09-30T19:26:11.652Z,1601493971.652 [BuoyancyServo] Loaded 2020-09-30T19:26:11.652Z,1601493971.652 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread. 2020-09-30T19:26:11.681Z,1601493971.681 [ElevatorServo] Loaded 2020-09-30T19:26:11.681Z,1601493971.681 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread. 2020-09-30T19:26:11.709Z,1601493971.709 [MassServo] Loaded 2020-09-30T19:26:11.709Z,1601493971.709 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread. 2020-09-30T19:26:11.737Z,1601493971.737 [RudderServo] Loaded 2020-09-30T19:26:11.737Z,1601493971.737 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread. 2020-09-30T19:26:11.765Z,1601493971.765 [ThrusterServo] Loaded 2020-09-30T19:26:11.765Z,1601493971.765 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread. 2020-09-30T19:26:11.766Z,1601493971.766 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers) 2020-09-30T19:26:11.766Z,1601493971.766 [Module Loader](DEBUG): Loading Module at Modules/Sample.so 2020-09-30T19:26:11.785Z,1601493971.785 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components) 2020-09-30T19:26:11.786Z,1601493971.786 [Module Loader](DEBUG): Loading Module at Modules/Science.so 2020-09-30T19:26:12.027Z,1601493972.027 [BackSeatDriver] Loaded 2020-09-30T19:26:12.028Z,1601493972.028 [ComponentRegistry](DEBUG): Component "BackSeatDriver" handled in its own thread. 2020-09-30T19:26:12.029Z,1601493972.029 [BackSeatDriver ThreadHandler](DEBUG): Created PCaller Thread at 40B054E0 2020-09-30T19:26:12.029Z,1601493972.029 [BackSeatDriver ThreadHandler](INFO): Protected caller Thread ID is 5667 2020-09-30T19:26:12.057Z,1601493972.057 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density 2020-09-30T19:26:12.063Z,1601493972.063 [CTD_Seabird](INFO): created writer for : sea_water_density 2020-09-30T19:26:12.063Z,1601493972.063 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth 2020-09-30T19:26:12.068Z,1601493972.068 [CTD_Seabird](INFO): created writer for : depth 2020-09-30T19:26:12.068Z,1601493972.068 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure 2020-09-30T19:26:12.073Z,1601493972.073 [CTD_Seabird](INFO): created writer for : sea_water_pressure 2020-09-30T19:26:12.074Z,1601493972.074 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity 2020-09-30T19:26:12.080Z,1601493972.080 [CTD_Seabird](INFO): created writer for : sea_water_salinity 2020-09-30T19:26:12.080Z,1601493972.080 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature 2020-09-30T19:26:12.085Z,1601493972.085 [CTD_Seabird](INFO): created writer for : sea_water_temperature 2020-09-30T19:26:12.086Z,1601493972.086 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity 2020-09-30T19:26:12.092Z,1601493972.092 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity 2020-09-30T19:26:12.093Z,1601493972.093 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water 2020-09-30T19:26:12.098Z,1601493972.098 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water 2020-09-30T19:26:12.126Z,1601493972.126 [CTD_Seabird] Loaded 2020-09-30T19:26:12.126Z,1601493972.126 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread. 2020-09-30T19:26:12.127Z,1601493972.127 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40B354E0 2020-09-30T19:26:12.128Z,1601493972.128 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 5668 2020-09-30T19:26:12.178Z,1601493972.178 [ESPComponent] Loaded 2020-09-30T19:26:12.178Z,1601493972.178 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread. 2020-09-30T19:26:12.200Z,1601493972.200 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470 2020-09-30T19:26:12.201Z,1601493972.201 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470 2020-09-30T19:26:12.205Z,1601493972.205 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650 2020-09-30T19:26:12.205Z,1601493972.205 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650 2020-09-30T19:26:12.209Z,1601493972.209 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl 2020-09-30T19:26:12.209Z,1601493972.209 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl 2020-09-30T19:26:12.214Z,1601493972.214 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm 2020-09-30T19:26:12.214Z,1601493972.214 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm 2020-09-30T19:26:12.218Z,1601493972.218 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm 2020-09-30T19:26:12.218Z,1601493972.218 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm 2020-09-30T19:26:12.223Z,1601493972.223 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm 2020-09-30T19:26:12.223Z,1601493972.223 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm 2020-09-30T19:26:12.227Z,1601493972.227 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm 2020-09-30T19:26:12.227Z,1601493972.227 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm 2020-09-30T19:26:12.231Z,1601493972.231 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water 2020-09-30T19:26:12.236Z,1601493972.236 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water 2020-09-30T19:26:12.236Z,1601493972.236 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-09-30T19:26:12.236Z,1601493972.236 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water 2020-09-30T19:26:12.241Z,1601493972.241 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-09-30T19:26:12.241Z,1601493972.241 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water 2020-09-30T19:26:12.245Z,1601493972.245 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-09-30T19:26:12.245Z,1601493972.245 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water 2020-09-30T19:26:12.250Z,1601493972.250 [WetLabsBB2FL] Loaded 2020-09-30T19:26:12.250Z,1601493972.250 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread. 2020-09-30T19:26:12.251Z,1601493972.251 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40B654E0 2020-09-30T19:26:12.251Z,1601493972.251 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 5669 2020-09-30T19:26:12.252Z,1601493972.252 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components) 2020-09-30T19:26:12.256Z,1601493972.256 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread. 2020-09-30T19:26:12.257Z,1601493972.257 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread. 2020-09-30T19:26:12.263Z,1601493972.263 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread. 2020-09-30T19:26:12.264Z,1601493972.264 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B954E0 2020-09-30T19:26:12.265Z,1601493972.265 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 5670 2020-09-30T19:26:12.269Z,1601493972.269 [Supervisor](INFO): Main Thread ID is 4201 2020-09-30T19:26:12.270Z,1601493972.270 [Supervisor](DEBUG): Running supervisor. 2020-09-30T19:26:12.270Z,1601493972.270 [CommandLine ThreadHandler](INFO): Handler Thread ID is 5671 2020-09-30T19:26:12.273Z,1601493972.273 [controlThread ThreadHandler](INFO): Handler Thread ID is 5672 2020-09-30T19:26:12.273Z,1601493972.273 [controlThread](DEBUG): Initializing ControlThread 2020-09-30T19:26:12.274Z,1601493972.274 [VerticalControl](DEBUG): Initialize VerticalControlComponent. 2020-09-30T19:26:12.276Z,1601493972.276 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent. 2020-09-30T19:26:12.276Z,1601493972.276 [SpeedControl](DEBUG): Initialize SpeedControlComponent. 2020-09-30T19:26:12.277Z,1601493972.277 [LoopControl](DEBUG): Initialize LoopControlComponent. 2020-09-30T19:26:12.277Z,1601493972.277 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator. 2020-09-30T19:26:12.278Z,1601493972.278 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator. 2020-09-30T19:26:12.278Z,1601493972.278 [SpeedCalculator](DEBUG): Initializing SpeedCalculator. 2020-09-30T19:26:12.278Z,1601493972.278 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator. 2020-09-30T19:26:12.279Z,1601493972.279 [YawRateCalculator](DEBUG): Initializing YawRateCalculator. 2020-09-30T19:26:12.279Z,1601493972.279 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator. 2020-09-30T19:26:12.284Z,1601493972.284 [NavChart](DEBUG): Initialize NavChart Navigation. 2020-09-30T19:26:12.284Z,1601493972.284 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component. 2020-09-30T19:26:12.285Z,1601493972.285 [SBIT](INFO): Initialize SBIT Component. 2020-09-30T19:26:12.285Z,1601493972.285 [SBIT](IMPORTANT): git: 2020-09-25 2020-09-30T19:26:12.285Z,1601493972.285 [SBIT](INFO): git hash: 37975fc996eaab47e1849088435330a6c93ea7b4 2020-09-30T19:26:12.285Z,1601493972.285 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8 2020-09-30T19:26:12.287Z,1601493972.287 [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:26:12.288Z,1601493972.288 [SBIT](INFO): Beginning SBIT in 99.000000 seconds. 2020-09-30T19:26:12.288Z,1601493972.288 [IBIT](INFO): Initialize IBIT Component. 2020-09-30T19:26:12.289Z,1601493972.289 [CBIT](DEBUG): Initialize CBIT Component. 2020-09-30T19:26:12.290Z,1601493972.290 [logger ThreadHandler](INFO): Handler Thread ID is 5673 2020-09-30T19:26:12.303Z,1601493972.303 [CBIT](DEBUG): Initialized mux pins. 2020-09-30T19:26:12.303Z,1601493972.303 [CBIT](DEBUG): Initializing the watchdog timer. 2020-09-30T19:26:12.307Z,1601493972.307 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 5674 2020-09-30T19:26:12.308Z,1601493972.308 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP 2020-09-30T19:26:12.315Z,1601493972.315 [Onboard ThreadHandler](INFO): Handler Thread ID is 5675 2020-09-30T19:26:12.327Z,1601493972.327 [CBIT](INFO): Last reboot was NOT due to watchdog timer. 2020-09-30T19:26:12.327Z,1601493972.327 [CBIT](DEBUG): Initializing heartbeat. 2020-09-30T19:26:12.332Z,1601493972.332 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 5676 2020-09-30T19:26:12.355Z,1601493972.355 [BackSeatDriver ThreadHandler](INFO): Handler Thread ID is 5677 2020-09-30T19:26:12.371Z,1601493972.371 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 5678 2020-09-30T19:26:12.372Z,1601493972.372 [CTD_Seabird](DEBUG): Initializing CTD_Seabird. 2020-09-30T19:26:12.376Z,1601493972.376 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 5680 2020-09-30T19:26:12.377Z,1601493972.377 [WetLabsBB2FL](INFO): Powering down 2020-09-30T19:26:12.398Z,1601493972.398 [CBIT](DEBUG): Deactivating GF circuits. 2020-09-30T19:26:12.399Z,1601493972.399 [CBIT](DEBUG): Deactivating emergency mode. 2020-09-30T19:26:12.411Z,1601493972.411 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 5681 2020-09-30T19:26:12.414Z,1601493972.414 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000 2020-09-30T19:26:12.414Z,1601493972.414 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000 2020-09-30T19:26:12.414Z,1601493972.414 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000 2020-09-30T19:26:12.414Z,1601493972.414 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000 2020-09-30T19:26:12.415Z,1601493972.415 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000 2020-09-30T19:26:12.415Z,1601493972.415 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000 2020-09-30T19:26:12.415Z,1601493972.415 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000 2020-09-30T19:26:12.415Z,1601493972.415 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000 2020-09-30T19:26:12.415Z,1601493972.415 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000 2020-09-30T19:26:12.415Z,1601493972.415 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000 2020-09-30T19:26:12.416Z,1601493972.416 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000 2020-09-30T19:26:12.416Z,1601493972.416 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000 2020-09-30T19:26:12.416Z,1601493972.416 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000 2020-09-30T19:26:12.416Z,1601493972.416 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000 2020-09-30T19:26:12.416Z,1601493972.416 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000 2020-09-30T19:26:12.416Z,1601493972.416 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000 2020-09-30T19:26:12.434Z,1601493972.434 [CBIT](DEBUG): Backplane powered. 2020-09-30T19:26:12.436Z,1601493972.436 [MissionManager](INFO): Loading Mission: Missions/Startup.xml 2020-09-30T19:26:12.446Z,1601493972.446 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface. 2020-09-30T19:26:12.457Z,1601493972.457 [MissionManager](DEBUG): 2020-09-30T19:26:12.457Z,1601493972.457 [MissionManager](INFO): Loading Mission: Missions/Default.xml 2020-09-30T19:26:12.530Z,1601493972.530 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min 2020-09-30T19:26:12.547Z,1601493972.547 [Default:A.Wait](DEBUG): Construct Wait. 2020-09-30T19:26:12.549Z,1601493972.549 [Default:B.GoToSurface](DEBUG): Construct GoToSurface. 2020-09-30T19:26:12.583Z,1601493972.583 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute. 2020-09-30T19:26:12.585Z,1601493972.585 [Default:CheckIn:C.Wait](DEBUG): Construct Wait. 2020-09-30T19:26:12.608Z,1601493972.608 [Default:E.Execute](DEBUG): Construct Execute. 2020-09-30T19:26:12.625Z,1601493972.625 [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:26:12.629Z,1601493972.629 [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:26:12.635Z,1601493972.635 [AHRS_M2](DEBUG): Initializing AHRS_M2. 2020-09-30T19:26:12.739Z,1601493972.739 [Radio_Surface](INFO): Powering up 2020-09-30T19:26:12.787Z,1601493972.787 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2 2020-09-30T19:26:12.807Z,1601493972.807 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2 2020-09-30T19:26:12.807Z,1601493972.807 [DAT](INFO): Powering up 2020-09-30T19:26:12.808Z,1601493972.808 [DAT](DEBUG): Initializing DAT. 2020-09-30T19:26:12.881Z,1601493972.881 [BuoyancyServo](DEBUG): Initializing EZServoServo. 2020-09-30T19:26:12.891Z,1601493972.891 [BuoyancyServo](DEBUG): Initializing BuoyancyServo. 2020-09-30T19:26:12.892Z,1601493972.892 [ElevatorServo](DEBUG): Initializing EZServoServo. 2020-09-30T19:26:12.903Z,1601493972.903 [ElevatorServo](DEBUG): Initializing ElevatorServo. 2020-09-30T19:26:12.904Z,1601493972.904 [MassServo](DEBUG): Initializing EZServoServo. 2020-09-30T19:26:12.915Z,1601493972.915 [MassServo](DEBUG): Initializing MassServo. 2020-09-30T19:26:12.916Z,1601493972.916 [RudderServo](DEBUG): Initializing EZServoServo. 2020-09-30T19:26:12.927Z,1601493972.927 [RudderServo](DEBUG): Initializing RudderServo. 2020-09-30T19:26:12.928Z,1601493972.928 [ThrusterServo](DEBUG): Initializing EZServoServo. 2020-09-30T19:26:12.939Z,1601493972.939 [ThrusterServo](DEBUG): Initializing ThrusterServo. 2020-09-30T19:26:13.783Z,1601493973.783 [RudderServo](ERROR): Rudder initialization uart error serial timeout 2020-09-30T19:26:13.783Z,1601493973.783 [RudderServo](FAULT): Rudder failed to initialize 2020-09-30T19:26:13.783Z,1601493973.783 [RudderServo] Communications Fault, FailCount= 1 2020-09-30T19:26:13.783Z,1601493973.783 [RudderServo](ERROR): Communications Fault 2020-09-30T19:26:13.894Z,1601493973.894 [CBIT](ERROR): Communications Fault in component: RudderServo 2020-09-30T19:26:14.052Z,1601493974.052 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-09-30T19:26:14.052Z,1601493974.052 [RudderServo](INFO): Powering down 2020-09-30T19:26:14.722Z,1601493974.722 [RudderServo](DEBUG): Initializing EZServoServo. 2020-09-30T19:26:14.839Z,1601493974.839 [RudderServo](DEBUG): Initializing RudderServo. 2020-09-30T19:26:14.844Z,1601493974.844 [CBIT](INFO): Clearing failed state for component RudderServo 2020-09-30T19:26:14.844Z,1601493974.844 [RudderServo] No Fault, FailCount= 1 2020-09-30T19:26:16.439Z,1601493976.439 [BuoyancyServo](INFO): is needed 2020-09-30T19:26:16.807Z,1601493976.807 [BuoyancyServo](INFO): is needed 2020-09-30T19:26:17.271Z,1601493977.271 [BuoyancyServo](INFO): is needed 2020-09-30T19:26:22.436Z,1601493982.436 [CBIT](CRITICAL): Environmental Failure. Press:14.433263 PSI. Humidity:57%. Temp:22 C. ABORTING MISSION 2020-09-30T19:26:22.436Z,1601493982.436 [Supervisor](INFO): Stop Mission called by CBIT::monitorEnvironmentals 2020-09-30T19:26:22.833Z,1601493982.833 [CommandLine](FAULT): Scheduling is paused 2020-09-30T19:26:22.833Z,1601493982.833 [CBIT](INFO): Critical error at 20200930T192622 2020-09-30T19:26:26.838Z,1601493986.838 [DAT](INFO): commRate: 800 2020-09-30T19:26:28.856Z,1601493988.856 [DAT](INFO): entering command mode 2020-09-30T19:26:29.260Z,1601493989.260 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:26:29.664Z,1601493989.664 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:26:30.068Z,1601493990.068 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:26:30.472Z,1601493990.472 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:26:30.876Z,1601493990.876 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:26:31.280Z,1601493991.280 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:26:31.684Z,1601493991.684 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:26:32.088Z,1601493992.088 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:26:32.492Z,1601493992.492 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:26:32.896Z,1601493992.896 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:26:33.300Z,1601493993.300 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:26:33.704Z,1601493993.704 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:26:34.108Z,1601493994.108 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:26:34.508Z,1601493994.508 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:26:34.916Z,1601493994.916 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:26:35.320Z,1601493995.320 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:26:35.730Z,1601493995.730 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:26:36.124Z,1601493996.124 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:26:36.528Z,1601493996.528 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:26:36.936Z,1601493996.936 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:26:37.337Z,1601493997.337 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:26:37.752Z,1601493997.752 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:26:38.148Z,1601493998.148 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:26:38.554Z,1601493998.554 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:26:38.960Z,1601493998.960 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:26:39.360Z,1601493999.360 [NAL9602](INFO): Powering up NAL9602 2020-09-30T19:26:39.361Z,1601493999.361 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:26:39.764Z,1601493999.764 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:26:40.175Z,1601494000.175 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:26:40.568Z,1601494000.568 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:26:40.972Z,1601494000.972 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:26:41.380Z,1601494001.380 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:26:41.788Z,1601494001.788 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:26:42.184Z,1601494002.184 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:26:42.592Z,1601494002.592 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:26:42.996Z,1601494002.996 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:26:43.050Z,1601494003.050 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:26:43.396Z,1601494003.396 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:26:43.417Z,1601494003.417 [BuoyancyServo](INFO): is needed 2020-09-30T19:26:43.462Z,1601494003.462 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:26:43.804Z,1601494003.804 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:26:43.867Z,1601494003.867 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:26:44.208Z,1601494004.208 [DAT](DEBUG): checking for command mode acknowledgment 2020-09-30T19:26:44.208Z,1601494004.208 [DAT](FAULT): failed to enter command mode 2020-09-30T19:26:44.263Z,1601494004.263 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:26:44.609Z,1601494004.609 [DAT](INFO): entering command mode 2020-09-30T19:26:44.671Z,1601494004.671 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:26:45.017Z,1601494005.017 [DAT](INFO): setting verbose to 3 2020-09-30T19:26:45.091Z,1601494005.091 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:26:45.421Z,1601494005.421 [DAT](INFO): set verbose to 3 2020-09-30T19:26:45.422Z,1601494005.422 [DAT](INFO): setting DatVerbose to 27440 2020-09-30T19:26:45.535Z,1601494005.535 [BuoyancyServo](INFO): is needed 2020-09-30T19:26:45.567Z,1601494005.567 [CBIT](INFO): got buoyancy position: 971.096069 2020-09-30T19:26:45.821Z,1601494005.821 [DAT](INFO): set DatVerbose to 27440 2020-09-30T19:26:45.821Z,1601494005.821 [DAT](INFO): setting transmit power to 8 2020-09-30T19:26:45.919Z,1601494005.919 [BuoyancyServo](INFO): is needed 2020-09-30T19:26:46.232Z,1601494006.232 [DAT](INFO): set transmit power to 8 2020-09-30T19:26:46.232Z,1601494006.232 [DAT](INFO): setting local address to 9 2020-09-30T19:26:46.303Z,1601494006.303 [BuoyancyServo](INFO): is needed 2020-09-30T19:26:46.633Z,1601494006.633 [DAT](INFO): set local address to 9 2020-09-30T19:26:50.268Z,1601494010.268 [NAL9602](INFO): NAL9602 initialized 2020-09-30T19:27:15.788Z,1601494035.788 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:27:16.179Z,1601494036.179 [BuoyancyServo](INFO): is needed 2020-09-30T19:27:16.184Z,1601494036.184 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:27:16.592Z,1601494036.592 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:27:16.997Z,1601494036.997 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:27:17.431Z,1601494037.431 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:27:17.859Z,1601494037.859 [BuoyancyServo](INFO): is needed 2020-09-30T19:27:17.872Z,1601494037.872 [CBIT](INFO): got buoyancy position: 971.096069 2020-09-30T19:27:18.227Z,1601494038.227 [BuoyancyServo](INFO): is needed 2020-09-30T19:27:18.643Z,1601494038.643 [BuoyancyServo](INFO): is needed 2020-09-30T19:27:48.108Z,1601494068.108 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:27:48.506Z,1601494068.506 [BuoyancyServo](INFO): is needed 2020-09-30T19:27:48.510Z,1601494068.510 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:27:48.905Z,1601494068.905 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:27:49.307Z,1601494069.307 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:27:49.717Z,1601494069.717 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:27:50.191Z,1601494070.191 [BuoyancyServo](INFO): is needed 2020-09-30T19:27:50.195Z,1601494070.195 [CBIT](INFO): got buoyancy position: 972.842041 2020-09-30T19:27:50.571Z,1601494070.571 [BuoyancyServo](INFO): is needed 2020-09-30T19:27:50.971Z,1601494070.971 [BuoyancyServo](INFO): is needed 2020-09-30T19:27:51.756Z,1601494071.756 [SBIT](IMPORTANT): Beginning Startup BIT 2020-09-30T19:27:51.760Z,1601494071.760 [CBIT](IMPORTANT): Beginning ground fault scan 2020-09-30T19:28:03.115Z,1601494083.115 [CBIT](IMPORTANT): No ground fault detected mA: CHAN A0 (Batt): 0.011327 CHAN A1 (24V): -0.001084 CHAN A2 (12V): -0.007229 CHAN A3 (5V): -0.002742 CHAN B0 (3.3V): 0.000166 CHAN B1 (3.15aV): -0.000142 CHAN B2 (3.15bV): -0.000388 CHAN B3 (GND): 0.001518 OPEN: 0.004758 Full Scale Calc: 4.765 mA, -1.589 mA 2020-09-30T19:28:20.507Z,1601494100.507 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:28:20.842Z,1601494100.842 [BuoyancyServo](INFO): is needed 2020-09-30T19:28:20.875Z,1601494100.875 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:28:21.291Z,1601494101.291 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:28:21.683Z,1601494101.683 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:28:22.103Z,1601494102.103 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:28:22.555Z,1601494102.555 [BuoyancyServo](INFO): is needed 2020-09-30T19:28:22.591Z,1601494102.591 [CBIT](INFO): got buoyancy position: 973.379150 2020-09-30T19:28:22.903Z,1601494102.903 [BuoyancyServo](INFO): is needed 2020-09-30T19:28:23.303Z,1601494103.303 [BuoyancyServo](INFO): is needed 2020-09-30T19:28:23.699Z,1601494103.699 [BuoyancyServo](INFO): is needed 2020-09-30T19:28:24.103Z,1601494104.103 [BuoyancyServo](INFO): is needed 2020-09-30T19:28:24.511Z,1601494104.511 [BuoyancyServo](INFO): is needed 2020-09-30T19:28:24.919Z,1601494104.919 [BuoyancyServo](INFO): is needed 2020-09-30T19:28:40.297Z,1601494120.297 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005199 2020-09-30T19:28:45.485Z,1601494125.485 [SBIT](IMPORTANT): SBIT PASSED 2020-09-30T19:28:45.485Z,1601494125.485 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg 2020-09-30T19:28:45.486Z,1601494125.486 [SBIT](IMPORTANT): AMEcho.loadAtStartup=0 bool; 2020-09-30T19:28:45.486Z,1601494125.486 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=6 count; 2020-09-30T19:28:45.486Z,1601494125.486 [SBIT](IMPORTANT): CBIT.gf24Offset=145 microampere; 2020-09-30T19:28:45.487Z,1601494125.487 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool; 2020-09-30T19:28:45.487Z,1601494125.487 [SBIT](IMPORTANT): DAT.sbdAddress=-1 enum; 2020-09-30T19:28:45.487Z,1601494125.487 [SBIT](IMPORTANT): DAT.surfaceThreshold=100 meter; 2020-09-30T19:28:45.487Z,1601494125.487 [SBIT](IMPORTANT): DAT.verbosity=0 count; 2020-09-30T19:28:45.487Z,1601494125.487 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool; 2020-09-30T19:28:45.487Z,1601494125.487 [SBIT](IMPORTANT): ESPComponent.loadAtStartup=1 bool; 2020-09-30T19:28:45.487Z,1601494125.487 [SBIT](IMPORTANT): Express linearApproximation AMEcho.altitude 1.000000 meter; 2020-09-30T19:28:45.487Z,1601494125.487 [SBIT](IMPORTANT): Express none ThrusterServo.component_avgCurrent; 2020-09-30T19:28:45.487Z,1601494125.487 [SBIT](IMPORTANT): Express linearApproximation WetLabsUBAT.flow_rate 1.000000 liter_per_second; 2020-09-30T19:28:45.488Z,1601494125.488 [SBIT](IMPORTANT): Express linearApproximation height_above_sea_floor 5.000000 meter; 2020-09-30T19:28:45.488Z,1601494125.488 [SBIT](IMPORTANT): IBIT.batteryVoltageThreshold=13 volt; 2020-09-30T19:28:45.488Z,1601494125.488 [SBIT](IMPORTANT): RDI_Pathfinder.loadAtStartup=0 bool; 2020-09-30T19:28:45.488Z,1601494125.488 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=170 cubic_centimeter; 2020-09-30T19:28:45.488Z,1601494125.488 [SBIT](IMPORTANT): VerticalControl.massDefault=20 millimeter; 2020-09-30T19:28:45.488Z,1601494125.488 [SBIT](IMPORTANT): WetLabsUBAT.loadAtStartup=0 bool; 2020-09-30T19:28:46.021Z,1601494126.021 [MissionManager](IMPORTANT): Started mission Startup 2020-09-30T19:28:46.021Z,1601494126.021 [Startup] Running Loop=1 2020-09-30T19:28:46.021Z,1601494126.021 [Startup](DEBUG): Aggregate::initialize Startup 2020-09-30T19:28:46.021Z,1601494126.021 [Startup:A.GoToSurface] Running Loop=1 2020-09-30T19:28:46.021Z,1601494126.021 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-09-30T19:28:46.022Z,1601494126.022 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-09-30T19:28:46.023Z,1601494126.023 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-09-30T19:28:46.023Z,1601494126.023 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-09-30T19:28:46.024Z,1601494126.024 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-09-30T19:28:46.024Z,1601494126.024 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-09-30T19:28:46.026Z,1601494126.026 [Startup:StartupSatComms] Running Loop=1 2020-09-30T19:28:46.026Z,1601494126.026 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms 2020-09-30T19:28:46.026Z,1601494126.026 [Startup:StartupSatComms:A] Running Loop=1 2020-09-30T19:28:46.304Z,1601494126.304 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix 2020-09-30T19:28:52.760Z,1601494132.760 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:28:53.168Z,1601494133.168 [BuoyancyServo](INFO): is needed 2020-09-30T19:28:53.172Z,1601494133.172 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:28:53.589Z,1601494133.589 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:28:53.999Z,1601494133.999 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:28:54.415Z,1601494134.415 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:28:54.859Z,1601494134.859 [BuoyancyServo](INFO): is needed 2020-09-30T19:28:54.864Z,1601494134.864 [CBIT](INFO): got buoyancy position: 973.244873 2020-09-30T19:28:55.219Z,1601494135.219 [BuoyancyServo](INFO): is needed 2020-09-30T19:28:55.635Z,1601494135.635 [BuoyancyServo](INFO): is needed 2020-09-30T19:28:56.015Z,1601494136.015 [BuoyancyServo](INFO): is needed 2020-09-30T19:28:56.419Z,1601494136.419 [BuoyancyServo](INFO): is needed 2020-09-30T19:28:56.839Z,1601494136.839 [BuoyancyServo](INFO): is needed 2020-09-30T19:28:57.227Z,1601494137.227 [BuoyancyServo](INFO): is needed 2020-09-30T19:28:57.635Z,1601494137.635 [BuoyancyServo](INFO): is needed 2020-09-30T19:29:12.670Z,1601494152.670 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1 2020-09-30T19:29:12.670Z,1601494152.670 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-09-30T19:29:12.704Z,1601494152.704 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-09-30T19:29:13.147Z,1601494153.147 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-09-30T19:29:13.147Z,1601494153.147 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1 2020-09-30T19:29:25.105Z,1601494165.105 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:29:25.492Z,1601494165.492 [BuoyancyServo](INFO): is needed 2020-09-30T19:29:25.496Z,1601494165.496 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:29:25.909Z,1601494165.909 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:29:26.433Z,1601494166.433 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:29:26.742Z,1601494166.742 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:29:27.203Z,1601494167.203 [BuoyancyServo](INFO): is needed 2020-09-30T19:29:27.207Z,1601494167.207 [CBIT](INFO): got buoyancy position: 970.961792 2020-09-30T19:29:27.595Z,1601494167.595 [BuoyancyServo](INFO): is needed 2020-09-30T19:29:27.939Z,1601494167.939 [BuoyancyServo](INFO): is needed 2020-09-30T19:29:46.088Z,1601494186.088 [Startup:StartupSatComms:A](INFO): Timed out from 2020-09-30T19:28:46.0Z 2020-09-30T19:29:46.088Z,1601494186.088 [Startup:StartupSatComms:A] Stopped 2020-09-30T19:29:46.088Z,1601494186.088 [Startup:StartupSatComms:B] Running Loop=1 2020-09-30T19:29:46.492Z,1601494186.492 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications 2020-09-30T19:29:50.075Z,1601494190.075 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200930T190748/Courier0007.lzma 2020-09-30T19:29:50.476Z,1601494190.476 [DataOverHttps](INFO): Moved sent file to Logs/20200930T190748/Courier0007.lzma.bak 2020-09-30T19:29:50.477Z,1601494190.477 [DataOverHttps](INFO): SBD MOMSN=12691074 2020-09-30T19:29:57.543Z,1601494197.543 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:29:57.880Z,1601494197.880 [BuoyancyServo](INFO): is needed 2020-09-30T19:29:57.885Z,1601494197.885 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:29:58.293Z,1601494198.293 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:29:58.712Z,1601494198.712 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:29:59.092Z,1601494199.092 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:29:59.504Z,1601494199.504 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:29:59.971Z,1601494199.971 [BuoyancyServo](INFO): is needed 2020-09-30T19:29:59.975Z,1601494199.975 [CBIT](INFO): got buoyancy position: 971.096069 2020-09-30T19:30:00.347Z,1601494200.347 [BuoyancyServo](INFO): is needed 2020-09-30T19:30:00.735Z,1601494200.735 [BuoyancyServo](INFO): is needed 2020-09-30T19:30:05.899Z,1601494205.899 [DataOverHttps](INFO): Sending 250 bytes from file Logs/20200930T192226/Courier0000.lzma 2020-09-30T19:30:06.901Z,1601494206.901 [DataOverHttps](INFO): Moved sent file to Logs/20200930T192226/Courier0000.lzma.bak 2020-09-30T19:30:06.901Z,1601494206.901 [DataOverHttps](INFO): SBD MOMSN=12691076 2020-09-30T19:30:22.452Z,1601494222.452 [DataOverHttps](INFO): Sending 250 bytes from file Logs/20200930T192601/Courier0000.lzma 2020-09-30T19:30:23.452Z,1601494223.452 [DataOverHttps](INFO): Moved sent file to Logs/20200930T192601/Courier0000.lzma.bak 2020-09-30T19:30:23.452Z,1601494223.452 [DataOverHttps](INFO): SBD MOMSN=12691080 2020-09-30T19:30:30.200Z,1601494230.200 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:30:30.600Z,1601494230.600 [BuoyancyServo](INFO): is needed 2020-09-30T19:30:30.604Z,1601494230.604 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:30:31.035Z,1601494231.035 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:30:31.423Z,1601494231.423 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:30:31.815Z,1601494231.815 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:30:32.219Z,1601494232.219 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:30:32.695Z,1601494232.695 [BuoyancyServo](INFO): is needed 2020-09-30T19:30:32.700Z,1601494232.700 [CBIT](INFO): got buoyancy position: 972.976318 2020-09-30T19:30:33.093Z,1601494233.093 [BuoyancyServo](INFO): is needed 2020-09-30T19:30:33.647Z,1601494233.647 [BuoyancyServo](INFO): is needed 2020-09-30T19:30:34.347Z,1601494234.347 [BuoyancyServo](INFO): is needed 2020-09-30T19:30:34.627Z,1601494234.627 [BuoyancyServo](INFO): is needed 2020-09-30T19:30:34.854Z,1601494234.854 [BuoyancyServo](INFO): is needed 2020-09-30T19:30:35.251Z,1601494235.251 [BuoyancyServo](INFO): is needed 2020-09-30T19:30:46.508Z,1601494246.508 [Startup:StartupSatComms:B](INFO): Timed out from 2020-09-30T19:29:46.1Z 2020-09-30T19:30:46.508Z,1601494246.508 [Startup:StartupSatComms:B] Stopped 2020-09-30T19:30:46.508Z,1601494246.508 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms 2020-09-30T19:30:46.508Z,1601494246.508 [Startup:StartupSatComms] Stopped 2020-09-30T19:30:46.508Z,1601494246.508 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms 2020-09-30T19:30:46.509Z,1601494246.509 [Startup](INFO): Completed Startup 2020-09-30T19:30:46.509Z,1601494246.509 [MissionManager](INFO): Startup is completed. 2020-09-30T19:30:46.509Z,1601494246.509 [MissionManager](INFO): Uninitializing Mission Startup 2020-09-30T19:30:46.509Z,1601494246.509 [Startup] Stopped 2020-09-30T19:30:46.510Z,1601494246.510 [Startup](DEBUG): Aggregate::uninitialize Startup 2020-09-30T19:30:46.510Z,1601494246.510 [Startup:A.GoToSurface] Stopped 2020-09-30T19:30:46.510Z,1601494246.510 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-09-30T19:30:46.958Z,1601494246.958 [MissionManager](IMPORTANT): Started mission Default 2020-09-30T19:30:46.972Z,1601494246.972 [Default] Running Loop=1 2020-09-30T19:30:46.972Z,1601494246.972 [Default](DEBUG): Aggregate::initialize Default 2020-09-30T19:30:46.972Z,1601494246.972 [Default:B.GoToSurface] Running Loop=1 2020-09-30T19:30:46.972Z,1601494246.972 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent. 2020-09-30T19:30:46.972Z,1601494246.972 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s. 2020-09-30T19:30:46.973Z,1601494246.973 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees. 2020-09-30T19:30:46.976Z,1601494246.976 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s. 2020-09-30T19:30:46.976Z,1601494246.976 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds. 2020-09-30T19:30:46.976Z,1601494246.976 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds. 2020-09-30T19:30:46.977Z,1601494246.977 [Default:A.Wait] Running Loop=1 2020-09-30T19:30:46.977Z,1601494246.977 [Default:A.Wait](DEBUG): Initialize Wait Component. 2020-09-30T19:30:47.466Z,1601494247.466 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20200930T190748/Express0008.lzma 2020-09-30T19:30:48.470Z,1601494248.470 [DataOverHttps](INFO): Moved sent file to Logs/20200930T190748/Express0008.lzma.bak 2020-09-30T19:30:48.470Z,1601494248.470 [DataOverHttps](INFO): SBD MOMSN=12691084 2020-09-30T19:31:00.248Z,1601494260.248 [Default:A.Wait](INFO): Done Waiting. 2020-09-30T19:31:00.248Z,1601494260.248 [Default:A.Wait] Stopped 2020-09-30T19:31:00.248Z,1601494260.248 [Default:A.Wait](DEBUG): Uninitialize Wait Component. 2020-09-30T19:31:00.762Z,1601494260.762 [Default:CheckIn] Running Loop=1 2020-09-30T19:31:00.762Z,1601494260.762 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-09-30T19:31:00.762Z,1601494260.762 [Default:CheckIn:Read_GPS] Running Loop=1 2020-09-30T19:31:01.071Z,1601494261.071 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix 2020-09-30T19:31:03.064Z,1601494263.064 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:31:03.468Z,1601494263.468 [BuoyancyServo](INFO): is needed 2020-09-30T19:31:03.472Z,1601494263.472 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:31:03.881Z,1601494263.881 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:31:04.303Z,1601494264.303 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:31:04.717Z,1601494264.717 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:31:05.183Z,1601494265.183 [BuoyancyServo](INFO): is needed 2020-09-30T19:31:05.187Z,1601494265.187 [CBIT](INFO): got buoyancy position: 973.244873 2020-09-30T19:31:05.535Z,1601494265.535 [BuoyancyServo](INFO): is needed 2020-09-30T19:31:05.931Z,1601494265.931 [BuoyancyServo](INFO): is needed 2020-09-30T19:31:35.432Z,1601494295.432 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:31:35.827Z,1601494295.827 [BuoyancyServo](INFO): is needed 2020-09-30T19:31:35.831Z,1601494295.831 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:31:36.253Z,1601494296.253 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:31:36.640Z,1601494296.640 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:31:37.031Z,1601494297.031 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:31:37.452Z,1601494297.452 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:31:37.903Z,1601494297.903 [BuoyancyServo](INFO): is needed 2020-09-30T19:31:37.908Z,1601494297.908 [CBIT](INFO): got buoyancy position: 973.244873 2020-09-30T19:31:38.263Z,1601494298.263 [BuoyancyServo](INFO): is needed 2020-09-30T19:31:38.671Z,1601494298.671 [BuoyancyServo](INFO): is needed 2020-09-30T19:31:39.087Z,1601494299.087 [BuoyancyServo](INFO): is needed 2020-09-30T19:31:39.475Z,1601494299.475 [BuoyancyServo](INFO): is needed 2020-09-30T19:31:39.887Z,1601494299.887 [BuoyancyServo](INFO): is needed 2020-09-30T19:31:40.283Z,1601494300.283 [BuoyancyServo](INFO): is needed 2020-09-30T19:31:53.175Z,1601494313.175 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session. 2020-09-30T19:32:08.160Z,1601494328.160 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:32:08.567Z,1601494328.567 [BuoyancyServo](INFO): is needed 2020-09-30T19:32:08.571Z,1601494328.571 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:32:08.977Z,1601494328.977 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:32:09.368Z,1601494329.368 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:32:09.767Z,1601494329.767 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:32:10.215Z,1601494330.215 [BuoyancyServo](INFO): is needed 2020-09-30T19:32:10.220Z,1601494330.220 [CBIT](INFO): got buoyancy position: 973.244873 2020-09-30T19:32:10.591Z,1601494330.591 [BuoyancyServo](INFO): is needed 2020-09-30T19:32:10.995Z,1601494330.995 [BuoyancyServo](INFO): is needed 2020-09-30T19:32:13.412Z,1601494333.412 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2 2020-09-30T19:32:13.412Z,1601494333.412 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-09-30T19:32:13.547Z,1601494333.547 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-09-30T19:32:13.803Z,1601494333.803 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-09-30T19:32:13.803Z,1601494333.803 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2 2020-09-30T19:32:40.456Z,1601494360.456 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:32:40.865Z,1601494360.865 [BuoyancyServo](INFO): is needed 2020-09-30T19:32:40.869Z,1601494360.869 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:32:41.280Z,1601494361.280 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:32:41.697Z,1601494361.697 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:32:42.084Z,1601494362.084 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:32:42.539Z,1601494362.539 [BuoyancyServo](INFO): is needed 2020-09-30T19:32:42.543Z,1601494362.543 [CBIT](INFO): got buoyancy position: 975.259399 2020-09-30T19:32:42.915Z,1601494362.915 [BuoyancyServo](INFO): is needed 2020-09-30T19:32:43.331Z,1601494363.331 [BuoyancyServo](INFO): is needed 2020-09-30T19:32:43.715Z,1601494363.715 [BuoyancyServo](INFO): is needed 2020-09-30T19:32:44.139Z,1601494364.139 [BuoyancyServo](INFO): is needed 2020-09-30T19:32:44.531Z,1601494364.531 [BuoyancyServo](INFO): is needed 2020-09-30T19:32:44.919Z,1601494364.919 [BuoyancyServo](INFO): is needed 2020-09-30T19:32:45.335Z,1601494365.335 [BuoyancyServo](INFO): is needed 2020-09-30T19:32:45.751Z,1601494365.751 [BuoyancyServo](INFO): is needed 2020-09-30T19:32:46.151Z,1601494366.151 [BuoyancyServo](INFO): is needed 2020-09-30T19:33:12.796Z,1601494392.796 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:33:13.200Z,1601494393.200 [BuoyancyServo](INFO): is needed 2020-09-30T19:33:13.213Z,1601494393.213 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:33:13.595Z,1601494393.595 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:33:14.115Z,1601494394.115 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:33:14.455Z,1601494394.455 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:33:14.900Z,1601494394.900 [BuoyancyServo](INFO): is needed 2020-09-30T19:33:14.904Z,1601494394.904 [CBIT](INFO): got buoyancy position: 970.827515 2020-09-30T19:33:15.336Z,1601494395.336 [BuoyancyServo](INFO): is needed 2020-09-30T19:33:15.675Z,1601494395.675 [BuoyancyServo](INFO): is needed 2020-09-30T19:33:45.116Z,1601494425.116 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:33:45.496Z,1601494425.496 [BuoyancyServo](INFO): is needed 2020-09-30T19:33:45.500Z,1601494425.500 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:33:45.905Z,1601494425.905 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:33:46.328Z,1601494426.328 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:33:46.730Z,1601494426.730 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:33:47.203Z,1601494427.203 [BuoyancyServo](INFO): is needed 2020-09-30T19:33:47.207Z,1601494427.207 [CBIT](INFO): got buoyancy position: 971.096069 2020-09-30T19:33:47.543Z,1601494427.543 [BuoyancyServo](INFO): is needed 2020-09-30T19:33:47.955Z,1601494427.955 [BuoyancyServo](INFO): is needed 2020-09-30T19:34:17.436Z,1601494457.436 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:34:17.816Z,1601494457.816 [BuoyancyServo](INFO): is needed 2020-09-30T19:34:17.820Z,1601494457.820 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:34:18.225Z,1601494458.225 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:34:18.632Z,1601494458.632 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:34:19.040Z,1601494459.040 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:34:19.523Z,1601494459.523 [BuoyancyServo](INFO): is needed 2020-09-30T19:34:19.527Z,1601494459.527 [CBIT](INFO): got buoyancy position: 972.170532 2020-09-30T19:34:19.895Z,1601494459.895 [BuoyancyServo](INFO): is needed 2020-09-30T19:34:20.279Z,1601494460.279 [BuoyancyServo](INFO): is needed 2020-09-30T19:34:41.249Z,1601494481.249 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6. 2020-09-30T19:34:41.253Z,1601494481.253 [BPC1](INFO): Received data from all battery sticks. 2020-09-30T19:34:49.739Z,1601494489.739 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:34:50.152Z,1601494490.152 [BuoyancyServo](INFO): is needed 2020-09-30T19:34:50.160Z,1601494490.160 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:34:50.579Z,1601494490.579 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:34:50.980Z,1601494490.980 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:34:51.362Z,1601494491.362 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:34:51.768Z,1601494491.768 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:34:52.227Z,1601494492.227 [BuoyancyServo](INFO): is needed 2020-09-30T19:34:52.231Z,1601494492.231 [CBIT](INFO): got buoyancy position: 971.230347 2020-09-30T19:34:52.591Z,1601494492.591 [BuoyancyServo](INFO): is needed 2020-09-30T19:34:52.995Z,1601494492.995 [BuoyancyServo](INFO): is needed 2020-09-30T19:35:14.395Z,1601494514.395 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3 2020-09-30T19:35:14.395Z,1601494514.395 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-09-30T19:35:14.405Z,1601494514.405 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-09-30T19:35:14.808Z,1601494514.808 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-09-30T19:35:14.808Z,1601494514.808 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3 2020-09-30T19:35:22.460Z,1601494522.460 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:35:22.876Z,1601494522.876 [BuoyancyServo](INFO): is needed 2020-09-30T19:35:22.880Z,1601494522.880 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:35:23.269Z,1601494523.269 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:35:23.677Z,1601494523.677 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:35:24.096Z,1601494524.096 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:35:24.559Z,1601494524.559 [BuoyancyServo](INFO): is needed 2020-09-30T19:35:24.563Z,1601494524.563 [CBIT](INFO): got buoyancy position: 971.364746 2020-09-30T19:35:24.900Z,1601494524.900 [BuoyancyServo](INFO): is needed 2020-09-30T19:35:25.315Z,1601494525.315 [BuoyancyServo](INFO): is needed 2020-09-30T19:35:54.796Z,1601494554.796 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:35:55.200Z,1601494555.200 [BuoyancyServo](INFO): is needed 2020-09-30T19:35:55.204Z,1601494555.204 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:35:55.588Z,1601494555.588 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:35:55.000Z,1601494556.000 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:35:56.410Z,1601494556.410 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:35:56.855Z,1601494556.855 [BuoyancyServo](INFO): is needed 2020-09-30T19:35:56.860Z,1601494556.860 [CBIT](INFO): got buoyancy position: 973.110596 2020-09-30T19:35:57.231Z,1601494557.231 [BuoyancyServo](INFO): is needed 2020-09-30T19:35:57.651Z,1601494557.651 [BuoyancyServo](INFO): is needed 2020-09-30T19:36:00.852Z,1601494560.852 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-09-30T19:31:00.8Z 2020-09-30T19:36:00.852Z,1601494560.852 [Default:CheckIn:Read_GPS] Stopped 2020-09-30T19:36:00.852Z,1601494560.852 [Default:CheckIn:Read_Iridium] Running Loop=1 2020-09-30T19:36:01.249Z,1601494561.249 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications 2020-09-30T19:36:09.058Z,1601494569.058 [DataOverHttps](INFO): Sending 169 bytes from file Logs/20200930T192601/Courier0004.lzma 2020-09-30T19:36:10.060Z,1601494570.060 [DataOverHttps](INFO): Moved sent file to Logs/20200930T192601/Courier0004.lzma.bak 2020-09-30T19:36:10.060Z,1601494570.060 [DataOverHttps](INFO): SBD MOMSN=12691145 2020-09-30T19:36:26.693Z,1601494586.693 [DataOverHttps](INFO): Sending 1133 bytes from file Logs/20200930T192226/Express0001.lzma 2020-09-30T19:36:27.116Z,1601494587.116 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:36:27.512Z,1601494587.512 [BuoyancyServo](INFO): is needed 2020-09-30T19:36:27.516Z,1601494587.516 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:36:27.692Z,1601494587.692 [DataOverHttps](INFO): Moved sent file to Logs/20200930T192226/Express0001.lzma.bak 2020-09-30T19:36:27.692Z,1601494587.692 [DataOverHttps](INFO): SBD MOMSN=12691147 2020-09-30T19:36:27.932Z,1601494587.932 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:36:28.324Z,1601494588.324 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:36:28.760Z,1601494588.760 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:36:29.191Z,1601494589.191 [BuoyancyServo](INFO): is needed 2020-09-30T19:36:29.195Z,1601494589.195 [CBIT](INFO): got buoyancy position: 973.513428 2020-09-30T19:36:29.563Z,1601494589.563 [BuoyancyServo](INFO): is needed 2020-09-30T19:36:29.963Z,1601494589.963 [BuoyancyServo](INFO): is needed 2020-09-30T19:36:30.383Z,1601494590.383 [BuoyancyServo](INFO): is needed 2020-09-30T19:36:30.791Z,1601494590.791 [BuoyancyServo](INFO): is needed 2020-09-30T19:36:31.179Z,1601494591.179 [BuoyancyServo](INFO): is needed 2020-09-30T19:36:31.571Z,1601494591.571 [BuoyancyServo](INFO): is needed 2020-09-30T19:36:31.987Z,1601494591.987 [BuoyancyServo](INFO): is needed 2020-09-30T19:36:45.377Z,1601494605.377 [DataOverHttps](INFO): Sending 1118 bytes from file Logs/20200930T192601/Express0001.lzma 2020-09-30T19:36:46.376Z,1601494606.376 [DataOverHttps](INFO): Moved sent file to Logs/20200930T192601/Express0001.lzma.bak 2020-09-30T19:36:46.376Z,1601494606.376 [DataOverHttps](INFO): SBD MOMSN=12691181 2020-09-30T19:36:52.540Z,1601494612.540 [NAL9602](FAULT): GPS failed to acquire within timeout. 2020-09-30T19:36:52.540Z,1601494612.540 [NAL9602] Data Fault, FailCount= 1 2020-09-30T19:36:52.540Z,1601494612.540 [NAL9602](ERROR): Data Fault 2020-09-30T19:36:52.617Z,1601494612.617 [CBIT](ERROR): Data Fault in component: NAL9602 2020-09-30T19:36:52.936Z,1601494612.936 [NAL9602](INFO): Powering down 2020-09-30T19:36:53.785Z,1601494613.785 [CBIT](INFO): Clearing failed state for component NAL9602 2020-09-30T19:36:53.785Z,1601494613.785 [NAL9602] No Fault, FailCount= 1 2020-09-30T19:36:59.441Z,1601494619.441 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:36:59.852Z,1601494619.852 [BuoyancyServo](INFO): is needed 2020-09-30T19:36:59.864Z,1601494619.864 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:37:00.243Z,1601494620.243 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:37:00.653Z,1601494620.653 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:37:01.068Z,1601494621.068 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:37:01.503Z,1601494621.503 [BuoyancyServo](INFO): is needed 2020-09-30T19:37:01.507Z,1601494621.507 [CBIT](INFO): got buoyancy position: 970.961792 2020-09-30T19:37:01.879Z,1601494621.879 [BuoyancyServo](INFO): is needed 2020-09-30T19:37:02.279Z,1601494622.279 [BuoyancyServo](INFO): is needed 2020-09-30T19:37:03.158Z,1601494623.158 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20200930T192601/Express0005.lzma 2020-09-30T19:37:04.161Z,1601494624.161 [DataOverHttps](INFO): Moved sent file to Logs/20200930T192601/Express0005.lzma.bak 2020-09-30T19:37:04.161Z,1601494624.161 [DataOverHttps](INFO): SBD MOMSN=12691214 2020-09-30T19:37:06.699Z,1601494626.699 [Default:CheckIn:Read_Iridium] Stopped 2020-09-30T19:37:06.699Z,1601494626.699 [Default:CheckIn:C.Wait] Running Loop=1 2020-09-30T19:37:06.700Z,1601494626.700 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component. 2020-09-30T19:37:23.240Z,1601494643.240 [NAL9602](INFO): Powering up NAL9602 2020-09-30T19:37:31.739Z,1601494651.739 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:37:32.140Z,1601494652.140 [BuoyancyServo](INFO): is needed 2020-09-30T19:37:32.144Z,1601494652.144 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:37:32.551Z,1601494652.551 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:37:32.975Z,1601494652.975 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:37:33.355Z,1601494653.355 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:37:33.835Z,1601494653.835 [BuoyancyServo](INFO): is needed 2020-09-30T19:37:33.839Z,1601494653.839 [CBIT](INFO): got buoyancy position: 971.096069 2020-09-30T19:37:34.144Z,1601494654.144 [NAL9602](INFO): NAL9602 initialized 2020-09-30T19:37:34.191Z,1601494654.191 [BuoyancyServo](INFO): is needed 2020-09-30T19:37:34.607Z,1601494654.607 [BuoyancyServo](INFO): is needed 2020-09-30T19:38:04.060Z,1601494684.060 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:38:04.472Z,1601494684.472 [BuoyancyServo](INFO): is needed 2020-09-30T19:38:04.476Z,1601494684.476 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:38:04.889Z,1601494684.889 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:38:05.290Z,1601494685.290 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:38:05.671Z,1601494685.671 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:38:06.080Z,1601494686.080 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:38:06.555Z,1601494686.555 [BuoyancyServo](INFO): is needed 2020-09-30T19:38:06.559Z,1601494686.559 [CBIT](INFO): got buoyancy position: 973.244873 2020-09-30T19:38:06.915Z,1601494686.915 [BuoyancyServo](INFO): is needed 2020-09-30T19:38:07.339Z,1601494687.339 [BuoyancyServo](INFO): is needed 2020-09-30T19:38:15.387Z,1601494695.387 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4 2020-09-30T19:38:15.387Z,1601494695.387 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-09-30T19:38:15.397Z,1601494695.397 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-09-30T19:38:15.808Z,1601494695.808 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-09-30T19:38:15.808Z,1601494695.808 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4 2020-09-30T19:38:36.772Z,1601494716.772 [CommandLine](IMPORTANT): got command show variable limit 2020-09-30T19:38:36.826Z,1601494716.826 [CommandLine](IMPORTANT): DDM.currentLimit (count) 2020-09-30T19:38:36.826Z,1601494716.826 [CommandLine](IMPORTANT): DDM.PWMLimit (count) 2020-09-30T19:38:36.822Z,1601494716.822 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:38:36.860Z,1601494716.860 [CommandLine](IMPORTANT): BuoyancyServo.currLimit (percent) 2020-09-30T19:38:36.861Z,1601494716.861 [CommandLine](IMPORTANT): BuoyancyServo.limitHi (count) 2020-09-30T19:38:36.861Z,1601494716.861 [CommandLine](IMPORTANT): BuoyancyServo.limitLo (count) 2020-09-30T19:38:36.862Z,1601494716.862 [CommandLine](IMPORTANT): ElevatorServo.currLimit (percent) 2020-09-30T19:38:36.871Z,1601494716.871 [CommandLine](IMPORTANT): ElevatorServo.limitHi (count) 2020-09-30T19:38:36.871Z,1601494716.871 [CommandLine](IMPORTANT): ElevatorServo.limitLo (count) 2020-09-30T19:38:36.872Z,1601494716.872 [CommandLine](IMPORTANT): MassServo.currLimit (percent) 2020-09-30T19:38:36.872Z,1601494716.872 [CommandLine](IMPORTANT): MassServo.limitHi (count) 2020-09-30T19:38:36.873Z,1601494716.873 [CommandLine](IMPORTANT): MassServo.limitLo (count) 2020-09-30T19:38:36.873Z,1601494716.873 [CommandLine](IMPORTANT): RudderServo.currLimit (percent) 2020-09-30T19:38:36.874Z,1601494716.874 [CommandLine](IMPORTANT): RudderServo.limitHi (count) 2020-09-30T19:38:36.874Z,1601494716.874 [CommandLine](IMPORTANT): RudderServo.limitLo (count) 2020-09-30T19:38:36.883Z,1601494716.883 [CommandLine](IMPORTANT): ThrusterServo.currLimit (percent) 2020-09-30T19:38:36.948Z,1601494716.948 [CommandLine](IMPORTANT): HorizontalControl.rudLimit (angular_degree) 2020-09-30T19:38:36.949Z,1601494716.949 [CommandLine](IMPORTANT): VerticalControl.buoyancyLimitHiCC (cubic_centimeter) 2020-09-30T19:38:36.949Z,1601494716.949 [CommandLine](IMPORTANT): VerticalControl.buoyancyLimitLoCC (cubic_centimeter) 2020-09-30T19:38:36.950Z,1601494716.950 [CommandLine](IMPORTANT): VerticalControl.elevLimit (angular_degree) 2020-09-30T19:38:36.991Z,1601494716.991 [CommandLine](IMPORTANT): VerticalControl.limitDepthTrajectory (bool) 2020-09-30T19:38:36.992Z,1601494716.992 [CommandLine](IMPORTANT): VerticalControl.massFilterLimit (degree) 2020-09-30T19:38:36.993Z,1601494716.993 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitAft (millimeter) 2020-09-30T19:38:36.993Z,1601494716.993 [CommandLine](IMPORTANT): VerticalControl.massPositionLimitFwd (millimeter) 2020-09-30T19:38:37.011Z,1601494717.011 [CommandLine](IMPORTANT): VerticalControl.pitchLimit (angular_degree) 2020-09-30T19:38:37.014Z,1601494717.014 [CommandLine](IMPORTANT): VeritcalControl.buoyancyLimitHiCC (cubic_centimeter) 2020-09-30T19:38:37.200Z,1601494717.200 [BuoyancyServo](INFO): is needed 2020-09-30T19:38:37.204Z,1601494717.204 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:38:37.609Z,1601494717.609 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:38:38.034Z,1601494718.034 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:38:38.399Z,1601494718.399 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:38:38.803Z,1601494718.803 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:38:39.283Z,1601494719.283 [BuoyancyServo](INFO): is needed 2020-09-30T19:38:39.288Z,1601494719.288 [CBIT](INFO): got buoyancy position: 973.110596 2020-09-30T19:38:39.655Z,1601494719.655 [BuoyancyServo](INFO): is needed 2020-09-30T19:38:40.063Z,1601494720.063 [BuoyancyServo](INFO): is needed 2020-09-30T19:38:40.447Z,1601494720.447 [BuoyancyServo](INFO): is needed 2020-09-30T19:38:40.867Z,1601494720.867 [BuoyancyServo](INFO): is needed 2020-09-30T19:38:41.263Z,1601494721.263 [BuoyancyServo](INFO): is needed 2020-09-30T19:38:41.655Z,1601494721.655 [BuoyancyServo](INFO): is needed 2020-09-30T19:38:42.063Z,1601494722.063 [BuoyancyServo](INFO): is needed 2020-09-30T19:38:59.845Z,1601494739.845 [CommandLine](IMPORTANT): got command configSet VerticalControl.buoyancyLimitLoCC 1100.000000 cubic_centimeter 2020-09-30T19:39:09.533Z,1601494749.533 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:39:09.920Z,1601494749.920 [BuoyancyServo](INFO): is needed 2020-09-30T19:39:09.924Z,1601494749.924 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:39:10.325Z,1601494750.325 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:39:10.735Z,1601494750.735 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:39:11.155Z,1601494751.155 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:39:11.623Z,1601494751.623 [BuoyancyServo](INFO): is needed 2020-09-30T19:39:11.627Z,1601494751.627 [CBIT](INFO): got buoyancy position: 971.096069 2020-09-30T19:39:11.627Z,1601494751.627 [CBIT](INFO): Buoyancy position below lower volume limit. VB valve could be leaking! 2020-09-30T19:39:11.971Z,1601494751.971 [BuoyancyServo](INFO): is needed 2020-09-30T19:39:11.976Z,1601494751.976 [CBIT](INFO): got buoyancy position: 971.096069 2020-09-30T19:39:11.976Z,1601494751.976 [CBIT](INFO): Buoyancy position below lower volume limit. VB valve could be leaking! 2020-09-30T19:39:12.379Z,1601494752.379 [BuoyancyServo](INFO): is needed 2020-09-30T19:39:12.384Z,1601494752.384 [CBIT](INFO): got buoyancy position: 971.096069 2020-09-30T19:39:12.384Z,1601494752.384 [CBIT](FAULT): Buoyancy position below lower volume limit. VB valve could be leaking! 2020-09-30T19:39:12.384Z,1601494752.384 [CBIT](INFO): Current buoyancy position: 971.096069 2020-09-30T19:39:42.644Z,1601494782.644 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:39:43.066Z,1601494783.066 [BuoyancyServo](INFO): is needed 2020-09-30T19:39:43.074Z,1601494783.074 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:39:43.462Z,1601494783.462 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:39:43.871Z,1601494783.871 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:39:44.261Z,1601494784.261 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:39:44.659Z,1601494784.659 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:39:45.135Z,1601494785.135 [BuoyancyServo](INFO): is needed 2020-09-30T19:39:45.139Z,1601494785.139 [CBIT](INFO): got buoyancy position: 972.707642 2020-09-30T19:39:45.487Z,1601494785.487 [BuoyancyServo](INFO): is needed 2020-09-30T19:39:45.899Z,1601494785.899 [BuoyancyServo](INFO): is needed 2020-09-30T19:40:05.768Z,1601494805.768 [CommandLine](IMPORTANT): got command configSet VerticalControl.buoyancyLimitLoCC 800.000000 cubic_centimeter 2020-09-30T19:40:15.360Z,1601494815.360 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:40:15.772Z,1601494815.772 [BuoyancyServo](INFO): is needed 2020-09-30T19:40:15.776Z,1601494815.776 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:40:16.203Z,1601494816.203 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:40:16.588Z,1601494816.588 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:40:16.972Z,1601494816.972 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:40:17.400Z,1601494817.400 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:40:17.863Z,1601494817.863 [BuoyancyServo](INFO): is needed 2020-09-30T19:40:17.867Z,1601494817.867 [CBIT](INFO): got buoyancy position: 973.513428 2020-09-30T19:40:18.235Z,1601494818.235 [BuoyancyServo](INFO): is needed 2020-09-30T19:40:18.615Z,1601494818.615 [BuoyancyServo](INFO): is needed 2020-09-30T19:40:19.039Z,1601494819.039 [BuoyancyServo](INFO): is needed 2020-09-30T19:40:19.435Z,1601494819.435 [BuoyancyServo](INFO): is needed 2020-09-30T19:40:19.831Z,1601494819.831 [BuoyancyServo](INFO): is needed 2020-09-30T19:40:20.247Z,1601494820.247 [BuoyancyServo](INFO): is needed 2020-09-30T19:40:20.655Z,1601494820.655 [BuoyancyServo](INFO): is needed 2020-09-30T19:40:21.073Z,1601494821.073 [BuoyancyServo](INFO): is needed 2020-09-30T19:40:48.122Z,1601494848.122 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:40:48.504Z,1601494848.504 [BuoyancyServo](INFO): is needed 2020-09-30T19:40:48.508Z,1601494848.508 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:40:48.923Z,1601494848.923 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:40:49.332Z,1601494849.332 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:40:49.704Z,1601494849.704 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:40:50.124Z,1601494850.124 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:40:50.595Z,1601494850.595 [BuoyancyServo](INFO): is needed 2020-09-30T19:40:50.608Z,1601494850.608 [CBIT](INFO): got buoyancy position: 970.021729 2020-09-30T19:40:50.931Z,1601494850.931 [BuoyancyServo](INFO): is needed 2020-09-30T19:40:51.347Z,1601494851.347 [BuoyancyServo](INFO): is needed 2020-09-30T19:41:16.361Z,1601494876.361 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5 2020-09-30T19:41:16.361Z,1601494876.361 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-09-30T19:41:16.372Z,1601494876.372 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-09-30T19:41:16.783Z,1601494876.783 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-09-30T19:41:16.783Z,1601494876.783 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5 2020-09-30T19:41:20.811Z,1601494880.811 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:41:21.228Z,1601494881.228 [BuoyancyServo](INFO): is needed 2020-09-30T19:41:21.232Z,1601494881.232 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:41:21.643Z,1601494881.643 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:41:22.032Z,1601494882.032 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:41:22.426Z,1601494882.426 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:41:22.844Z,1601494882.844 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:41:23.295Z,1601494883.295 [BuoyancyServo](INFO): is needed 2020-09-30T19:41:23.299Z,1601494883.299 [CBIT](INFO): got buoyancy position: 971.230347 2020-09-30T19:41:23.663Z,1601494883.663 [BuoyancyServo](INFO): is needed 2020-09-30T19:41:24.075Z,1601494884.075 [BuoyancyServo](INFO): is needed 2020-09-30T19:41:53.552Z,1601494913.552 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:41:53.955Z,1601494913.955 [BuoyancyServo](INFO): is needed 2020-09-30T19:41:53.959Z,1601494913.959 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:41:54.345Z,1601494914.345 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:41:54.752Z,1601494914.752 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:41:55.163Z,1601494915.163 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:41:55.639Z,1601494915.639 [BuoyancyServo](INFO): is needed 2020-09-30T19:41:55.643Z,1601494915.643 [CBIT](INFO): got buoyancy position: 973.244873 2020-09-30T19:41:55.999Z,1601494915.999 [BuoyancyServo](INFO): is needed 2020-09-30T19:41:56.387Z,1601494916.387 [BuoyancyServo](INFO): is needed 2020-09-30T19:42:07.276Z,1601494927.276 [Default:CheckIn:C.Wait](INFO): Done Waiting. 2020-09-30T19:42:07.276Z,1601494927.276 [Default:CheckIn:C.Wait] Stopped 2020-09-30T19:42:07.276Z,1601494927.276 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component. 2020-09-30T19:42:07.276Z,1601494927.276 [Default:CheckIn:D] Running Loop=1 2020-09-30T19:42:07.663Z,1601494927.663 [Default:CheckIn:D] Stopped 2020-09-30T19:42:07.663Z,1601494927.663 [Default:CheckIn:E] Running Loop=1 2020-09-30T19:42:08.076Z,1601494928.076 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 11.345066 min 2020-09-30T19:42:08.076Z,1601494928.076 [Default:CheckIn:E] Stopped 2020-09-30T19:42:08.076Z,1601494928.076 [Default:CheckIn](INFO): Completed Default:CheckIn 2020-09-30T19:42:08.076Z,1601494928.076 [Default:CheckIn] Stopped 2020-09-30T19:42:08.076Z,1601494928.076 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-09-30T19:42:08.076Z,1601494928.076 [Default:CheckIn](INFO): Running loop #2 2020-09-30T19:42:08.076Z,1601494928.076 [Default:CheckIn] Running Loop=2 2020-09-30T19:42:08.076Z,1601494928.076 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn 2020-09-30T19:42:08.077Z,1601494928.077 [Default:CheckIn:Read_GPS] Running Loop=1 2020-09-30T19:42:25.872Z,1601494945.872 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:42:26.276Z,1601494946.276 [BuoyancyServo](INFO): is needed 2020-09-30T19:42:26.280Z,1601494946.280 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:42:26.661Z,1601494946.661 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:42:27.069Z,1601494947.069 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:42:27.480Z,1601494947.480 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:42:27.927Z,1601494947.927 [BuoyancyServo](INFO): is needed 2020-09-30T19:42:27.932Z,1601494947.932 [CBIT](INFO): got buoyancy position: 975.259399 2020-09-30T19:42:28.315Z,1601494948.315 [BuoyancyServo](INFO): is needed 2020-09-30T19:42:28.723Z,1601494948.723 [BuoyancyServo](INFO): is needed 2020-09-30T19:42:29.123Z,1601494949.123 [BuoyancyServo](INFO): is needed 2020-09-30T19:42:29.507Z,1601494949.507 [BuoyancyServo](INFO): is needed 2020-09-30T19:42:29.919Z,1601494949.919 [BuoyancyServo](INFO): is needed 2020-09-30T19:42:30.331Z,1601494950.331 [BuoyancyServo](INFO): is needed 2020-09-30T19:42:30.739Z,1601494950.739 [BuoyancyServo](INFO): is needed 2020-09-30T19:42:31.127Z,1601494951.127 [BuoyancyServo](INFO): is needed 2020-09-30T19:42:31.547Z,1601494951.547 [BuoyancyServo](INFO): is needed 2020-09-30T19:42:58.226Z,1601494978.226 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:42:58.608Z,1601494978.608 [BuoyancyServo](INFO): is needed 2020-09-30T19:42:58.612Z,1601494978.612 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:42:59.009Z,1601494979.009 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:42:59.384Z,1601494979.384 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:42:59.788Z,1601494979.788 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:43:00.204Z,1601494980.204 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:43:00.655Z,1601494980.655 [BuoyancyServo](INFO): is needed 2020-09-30T19:43:00.659Z,1601494980.659 [CBIT](INFO): got buoyancy position: 970.961792 2020-09-30T19:43:01.027Z,1601494981.027 [BuoyancyServo](INFO): is needed 2020-09-30T19:43:01.451Z,1601494981.451 [BuoyancyServo](INFO): is needed 2020-09-30T19:43:21.865Z,1601495001.865 [CommandLine](IMPORTANT): got command configSet VerticalControl.buoyancyLimitLoCC 1100.000000 cubic_centimeter 2020-09-30T19:43:30.891Z,1601495010.891 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:43:31.300Z,1601495011.300 [BuoyancyServo](INFO): is needed 2020-09-30T19:43:31.304Z,1601495011.304 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:43:31.719Z,1601495011.719 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:43:32.134Z,1601495012.134 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:43:32.544Z,1601495012.544 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:43:32.999Z,1601495012.999 [BuoyancyServo](INFO): is needed 2020-09-30T19:43:33.004Z,1601495013.004 [CBIT](INFO): got buoyancy position: 971.230347 2020-09-30T19:43:33.004Z,1601495013.004 [CBIT](INFO): Buoyancy position below lower volume limit. VB valve could be leaking! 2020-09-30T19:43:33.359Z,1601495013.359 [BuoyancyServo](INFO): is needed 2020-09-30T19:43:33.383Z,1601495013.383 [CBIT](INFO): got buoyancy position: 971.096069 2020-09-30T19:43:33.383Z,1601495013.383 [CBIT](INFO): Buoyancy position below lower volume limit. VB valve could be leaking! 2020-09-30T19:43:33.755Z,1601495013.755 [BuoyancyServo](INFO): is needed 2020-09-30T19:43:33.759Z,1601495013.759 [CBIT](INFO): got buoyancy position: 970.961792 2020-09-30T19:43:33.759Z,1601495013.759 [CBIT](FAULT): Buoyancy position below lower volume limit. VB valve could be leaking! 2020-09-30T19:43:33.760Z,1601495013.760 [CBIT](INFO): Current buoyancy position: 970.961792 2020-09-30T19:44:04.040Z,1601495044.040 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:44:04.424Z,1601495044.424 [BuoyancyServo](INFO): is needed 2020-09-30T19:44:04.428Z,1601495044.428 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:44:04.833Z,1601495044.833 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:44:05.248Z,1601495045.248 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:44:05.660Z,1601495045.660 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:44:06.135Z,1601495046.135 [BuoyancyServo](INFO): is needed 2020-09-30T19:44:06.139Z,1601495046.139 [CBIT](INFO): got buoyancy position: 972.170532 2020-09-30T19:44:06.475Z,1601495046.475 [BuoyancyServo](INFO): is needed 2020-09-30T19:44:06.895Z,1601495046.895 [BuoyancyServo](INFO): is needed 2020-09-30T19:44:09.437Z,1601495049.437 [CommandLine](IMPORTANT): got command configSet BuoyancyServo.loadAtStartup 0.000000 bool persist 2020-09-30T19:44:09.437Z,1601495049.437 [CommandLine](IMPORTANT): configSet BuoyancyServo.loadAtStartup requires a restart to take effect. 2020-09-30T19:44:17.347Z,1601495057.347 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6 2020-09-30T19:44:17.347Z,1601495057.347 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault 2020-09-30T19:44:17.357Z,1601495057.357 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources 2020-09-30T19:44:17.768Z,1601495057.768 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources 2020-09-30T19:44:17.768Z,1601495057.768 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6 2020-09-30T19:44:36.356Z,1601495076.356 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:44:36.766Z,1601495076.766 [BuoyancyServo](INFO): is needed 2020-09-30T19:44:36.770Z,1601495076.770 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:44:37.184Z,1601495077.184 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:44:37.555Z,1601495077.555 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:44:38.009Z,1601495078.009 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:44:38.435Z,1601495078.435 [BuoyancyServo](INFO): is needed 2020-09-30T19:44:38.439Z,1601495078.439 [CBIT](INFO): got buoyancy position: 974.184937 2020-09-30T19:44:38.799Z,1601495078.799 [BuoyancyServo](INFO): is needed 2020-09-30T19:44:39.211Z,1601495079.211 [BuoyancyServo](INFO): is needed 2020-09-30T19:44:39.615Z,1601495079.615 [BuoyancyServo](INFO): is needed 2020-09-30T19:44:40.023Z,1601495080.023 [BuoyancyServo](INFO): is needed 2020-09-30T19:44:40.411Z,1601495080.411 [BuoyancyServo](INFO): is needed 2020-09-30T19:44:40.811Z,1601495080.811 [BuoyancyServo](INFO): is needed 2020-09-30T19:45:08.368Z,1601495108.368 [CommandLine](IMPORTANT): got command restart application 2020-09-30T19:45:08.676Z,1601495108.676 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:45:09.059Z,1601495109.059 [BuoyancyServo](INFO): is needed 2020-09-30T19:45:09.063Z,1601495109.063 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:45:09.375Z,1601495109.375 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread. 2020-09-30T19:45:09.375Z,1601495109.375 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:45:09.418Z,1601495109.418 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup. Bye! 2020-09-30T19:45:09.419Z,1601495109.419 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:45:09.419Z,1601495109.419 [CommandLine](INFO): Join timeout helper Thread ID is 5760 2020-09-30T19:45:09.420Z,1601495109.420 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler 2020-09-30T19:45:09.420Z,1601495109.420 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:45:09.420Z,1601495109.420 [NavChartDb](INFO): Join timeout helper Thread ID is 5761 2020-09-30T19:45:09.504Z,1601495109.504 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:45:09.795Z,1601495109.795 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread. 2020-09-30T19:45:09.795Z,1601495109.795 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:45:09.799Z,1601495109.799 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler 2020-09-30T19:45:09.799Z,1601495109.799 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:45:09.799Z,1601495109.799 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 5763 2020-09-30T19:45:09.896Z,1601495109.896 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:45:10.223Z,1601495110.223 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread. 2020-09-30T19:45:10.223Z,1601495110.223 [WetLabsBB2FL](INFO): Powering down 2020-09-30T19:45:10.224Z,1601495110.224 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:45:10.242Z,1601495110.242 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler 2020-09-30T19:45:10.243Z,1601495110.243 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:45:10.243Z,1601495110.243 [CTD_Seabird](INFO): Join timeout helper Thread ID is 5764 2020-09-30T19:45:10.312Z,1601495110.312 [CBIT](INFO): No valid buoyancy position, requesting data. 2020-09-30T19:45:10.747Z,1601495110.747 [BuoyancyServo](INFO): is needed 2020-09-30T19:45:10.751Z,1601495110.751 [CBIT](INFO): got buoyancy position: 973.379150 2020-09-30T19:45:11.091Z,1601495111.091 [CTD_Seabird](INFO): Powering down 2020-09-30T19:45:11.107Z,1601495111.107 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread. 2020-09-30T19:45:11.115Z,1601495111.115 [BuoyancyServo](INFO): is needed 2020-09-30T19:45:11.115Z,1601495111.115 [CTD_Seabird](INFO): Powering down 2020-09-30T19:45:11.127Z,1601495111.127 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:45:11.143Z,1601495111.143 [ComponentRegistry](INFO): Shutting down BackSeatDriver ThreadHandler 2020-09-30T19:45:11.143Z,1601495111.143 [BackSeatDriver ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:45:11.143Z,1601495111.143 [BackSeatDriver](INFO): Join timeout helper Thread ID is 5765 2020-09-30T19:45:11.412Z,1601495111.412 [BackSeatDriver ThreadHandler](INFO): Uninitializing protected caller thread. 2020-09-30T19:45:11.412Z,1601495111.412 [BackSeatDriver ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:45:11.423Z,1601495111.423 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler 2020-09-30T19:45:11.424Z,1601495111.424 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:45:11.424Z,1601495111.424 [Radio_Surface](INFO): Join timeout helper Thread ID is 5766 2020-09-30T19:45:11.531Z,1601495111.531 [BuoyancyServo](INFO): is needed 2020-09-30T19:45:11.795Z,1601495111.795 [Radio_Surface](INFO): Powering down 2020-09-30T19:45:11.795Z,1601495111.795 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread. 2020-09-30T19:45:11.796Z,1601495111.796 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:45:11.799Z,1601495111.799 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler 2020-09-30T19:45:11.799Z,1601495111.799 [Onboard ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:45:11.800Z,1601495111.800 [Onboard](INFO): Join timeout helper Thread ID is 5767 2020-09-30T19:45:12.519Z,1601495112.519 [DataOverHttps](INFO): Radio surface powered OFF, will not connect. 2020-09-30T19:45:13.299Z,1601495113.299 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread. 2020-09-30T19:45:13.299Z,1601495113.299 [Onboard ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:45:13.311Z,1601495113.311 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler 2020-09-30T19:45:13.311Z,1601495113.311 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:45:13.312Z,1601495113.312 [DataOverHttps](INFO): Join timeout helper Thread ID is 5768 2020-09-30T19:45:13.523Z,1601495113.523 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread. 2020-09-30T19:45:13.523Z,1601495113.523 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:45:13.535Z,1601495113.535 [ComponentRegistry](INFO): Shutting down logger ThreadHandler 2020-09-30T19:45:13.535Z,1601495113.535 [logger ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:45:13.536Z,1601495113.536 [logger](INFO): Join timeout helper Thread ID is 5769 2020-09-30T19:45:13.551Z,1601495113.551 [logger ThreadHandler](INFO): Uninitializing protected caller thread. 2020-09-30T19:45:13.551Z,1601495113.551 [logger ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:45:13.555Z,1601495113.555 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler 2020-09-30T19:45:13.555Z,1601495113.555 [CommandLine ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:45:13.556Z,1601495113.556 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler 2020-09-30T19:45:13.556Z,1601495113.556 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:45:13.556Z,1601495113.556 [controlThread](INFO): Join timeout helper Thread ID is 5770 2020-09-30T19:45:13.883Z,1601495113.883 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread. 2020-09-30T19:45:13.883Z,1601495113.883 [controlThread](DEBUG): Uninitializing ControlThread 2020-09-30T19:45:13.883Z,1601495113.883 [AHRS_M2](INFO): Powering down 2020-09-30T19:45:13.955Z,1601495113.955 [NAL9602](INFO): Powering down 2020-09-30T19:45:13.956Z,1601495113.956 [DAT](INFO): Powering down 2020-09-30T19:45:14.076Z,1601495114.076 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator. 2020-09-30T19:45:14.077Z,1601495114.077 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator. 2020-09-30T19:45:14.078Z,1601495114.078 [NavChart](DEBUG): Uninitialize NavChart Navigation. 2020-09-30T19:45:14.079Z,1601495114.079 [MissionManager](INFO): Uninitializing Mission Default 2020-09-30T19:45:14.079Z,1601495114.079 [Default] Stopped 2020-09-30T19:45:14.079Z,1601495114.079 [Default](DEBUG): Aggregate::uninitialize Default 2020-09-30T19:45:14.079Z,1601495114.079 [Default:B.GoToSurface] Stopped 2020-09-30T19:45:14.079Z,1601495114.079 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent. 2020-09-30T19:45:14.079Z,1601495114.079 [Default:CheckIn] Stopped 2020-09-30T19:45:14.079Z,1601495114.079 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn 2020-09-30T19:45:14.079Z,1601495114.079 [Default:CheckIn:Read_GPS] Stopped 2020-09-30T19:45:14.081Z,1601495114.081 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent. 2020-09-30T19:45:14.082Z,1601495114.082 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent. 2020-09-30T19:45:14.082Z,1601495114.082 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent. 2020-09-30T19:45:14.082Z,1601495114.082 [LoopControl](DEBUG): Uninitialize LoopControlComponent. 2020-09-30T19:45:14.083Z,1601495114.083 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo. 2020-09-30T19:45:14.083Z,1601495114.083 [BuoyancyServo](INFO): Powering down 2020-09-30T19:45:14.215Z,1601495114.215 [ElevatorServo](DEBUG): Uninitialize Elevator Servo. 2020-09-30T19:45:14.215Z,1601495114.215 [ElevatorServo](INFO): Powering down 2020-09-30T19:45:14.216Z,1601495114.216 [MassServo](DEBUG): Uninitialize Mass Servo. 2020-09-30T19:45:14.216Z,1601495114.216 [MassServo](INFO): Powering down 2020-09-30T19:45:14.217Z,1601495114.217 [RudderServo](DEBUG): Uninitialize Rudder Servo. 2020-09-30T19:45:14.217Z,1601495114.217 [RudderServo](INFO): Powering down 2020-09-30T19:45:14.217Z,1601495114.217 [ThrusterServo](DEBUG): Uninitialize Thruster Servo. 2020-09-30T19:45:14.218Z,1601495114.218 [ThrusterServo](INFO): Powering down 2020-09-30T19:45:14.219Z,1601495114.219 [SBIT](DEBUG): Uninitialize SBIT Component. 2020-09-30T19:45:14.219Z,1601495114.219 [IBIT](DEBUG): Uninitialize IBIT Component. 2020-09-30T19:45:14.219Z,1601495114.219 [CBIT](DEBUG): Uninitialize CBIT Component. 2020-09-30T19:45:14.219Z,1601495114.219 [CBIT](DEBUG): Powering off loads. 2020-09-30T19:45:14.230Z,1601495114.230 [CBIT](DEBUG): Disabling WDT. 2020-09-30T19:45:14.242Z,1601495114.242 [CBIT](DEBUG): Opening all GF detection circuits. 2020-09-30T19:45:14.243Z,1601495114.243 [controlThread ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:45:14.338Z,1601495114.338 [Radio_Surface ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:45:14.340Z,1601495114.340 [Onboard ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:45:14.345Z,1601495114.345 [DataOverHttps ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:45:14.441Z,1601495114.441 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:45:14.446Z,1601495114.446 [CTD_Seabird ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:45:14.481Z,1601495114.481 [BackSeatDriver ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:45:14.491Z,1601495114.491 [NavChartDb ThreadHandler](INFO): Thread cancelled. 2020-09-30T19:45:14.558Z,1601495114.558 [logger ThreadHandler](INFO): Thread cancelled.