2020-07-09T21:08:51.856Z,1594328931.856 [Supervisor](DEBUG): Initializing supervisor.
2020-07-09T21:08:51.859Z,1594328931.859 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-07-09T21:08:51.859Z,1594328931.859 [SyncHandler](INFO): Protected caller Thread ID is 3326
2020-07-09T21:08:51.860Z,1594328931.860 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-07-09T21:08:51.861Z,1594328931.861 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-07-09T21:08:51.861Z,1594328931.861 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3327
2020-07-09T21:08:51.864Z,1594328931.864 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-07-09T21:08:51.877Z,1594328931.877 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-07-09T21:08:51.878Z,1594328931.878 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-07-09T21:08:51.878Z,1594328931.878 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3328
2020-07-09T21:08:51.879Z,1594328931.879 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-07-09T21:08:51.880Z,1594328931.880 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-07-09T21:08:51.880Z,1594328931.880 [logger ThreadHandler](INFO): Protected caller Thread ID is 3329
2020-07-09T21:08:51.882Z,1594328931.882 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-07-09T21:08:51.882Z,1594328931.882 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-07-09T21:08:51.884Z,1594328931.884 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-07-09T21:08:51.979Z,1594328931.979 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-07-09T21:08:51.979Z,1594328931.980 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-07-09T21:08:52.171Z,1594328932.171 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-07-09T21:08:52.171Z,1594328932.171 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-07-09T21:08:52.380Z,1594328932.380 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-07-09T21:08:52.381Z,1594328932.381 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-07-09T21:08:52.516Z,1594328932.516 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-07-09T21:08:52.516Z,1594328932.516 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-07-09T21:08:53.095Z,1594328933.095 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-07-09T21:08:53.096Z,1594328933.096 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-07-09T21:08:53.548Z,1594328933.548 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-07-09T21:08:53.548Z,1594328933.548 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-07-09T21:08:54.007Z,1594328934.007 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-07-09T21:08:54.010Z,1594328934.010 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-07-09T21:08:54.310Z,1594328934.310 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-07-09T21:08:54.311Z,1594328934.311 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-07-09T21:08:54.637Z,1594328934.637 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-07-09T21:08:54.637Z,1594328934.637 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-07-09T21:08:55.131Z,1594328935.131 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-07-09T21:08:55.131Z,1594328935.131 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-07-09T21:08:55.278Z,1594328935.278 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-07-09T21:08:55.278Z,1594328935.278 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-07-09T21:08:55.385Z,1594328935.385 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-07-09T21:08:55.386Z,1594328935.386 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-07-09T21:08:55.468Z,1594328935.468 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-07-09T21:08:55.571Z,1594328935.571 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-07-09T21:08:55.571Z,1594328935.571 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-07-09T21:08:55.757Z,1594328935.757 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-07-09T21:08:55.757Z,1594328935.757 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-07-09T21:08:55.962Z,1594328935.962 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-07-09T21:08:55.964Z,1594328935.964 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2020-07-09T21:08:55.965Z,1594328935.965 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2020-07-09T21:08:56.049Z,1594328936.049 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2020-07-09T21:08:56.279Z,1594328936.279 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-07-09T21:08:56.279Z,1594328936.279 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2020-07-09T21:08:56.377Z,1594328936.377 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2020-07-09T21:08:56.544Z,1594328936.544 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2020-07-09T21:08:56.927Z,1594328936.927 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2020-07-09T21:08:57.051Z,1594328937.051 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2020-07-09T21:08:57.269Z,1594328937.269 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2020-07-09T21:08:57.568Z,1594328937.568 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2020-07-09T21:08:57.723Z,1594328937.723 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2020-07-09T21:08:57.830Z,1594328937.830 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2020-07-09T21:08:57.932Z,1594328937.932 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2020-07-09T21:08:57.932Z,1594328937.932 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-07-09T21:08:57.937Z,1594328937.937 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-07-09T21:08:58.030Z,1594328938.030 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-07-09T21:08:58.030Z,1594328938.030 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-07-09T21:08:58.152Z,1594328938.152 [BuoyancyServo] Loaded
2020-07-09T21:08:58.153Z,1594328938.153 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-07-09T21:08:58.185Z,1594328938.185 [ElevatorServo] Loaded
2020-07-09T21:08:58.185Z,1594328938.185 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-07-09T21:08:58.215Z,1594328938.215 [MassServo] Loaded
2020-07-09T21:08:58.216Z,1594328938.216 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-07-09T21:08:58.247Z,1594328938.247 [RudderServo] Loaded
2020-07-09T21:08:58.247Z,1594328938.247 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-07-09T21:08:58.277Z,1594328938.277 [ThrusterServo] Loaded
2020-07-09T21:08:58.277Z,1594328938.277 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-07-09T21:08:58.277Z,1594328938.277 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-07-09T21:08:58.278Z,1594328938.278 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-07-09T21:08:58.408Z,1594328938.408 [SBIT](DEBUG): Construct Startup Built In Test.
2020-07-09T21:08:58.436Z,1594328938.436 [SBIT] Loaded
2020-07-09T21:08:58.436Z,1594328938.436 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-07-09T21:08:58.437Z,1594328938.437 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-07-09T21:08:58.466Z,1594328938.466 [IBIT] Loaded
2020-07-09T21:08:58.466Z,1594328938.466 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-07-09T21:08:58.470Z,1594328938.470 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-07-09T21:08:58.852Z,1594328938.852 [CBIT] Loaded
2020-07-09T21:08:58.852Z,1594328938.852 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-07-09T21:08:58.853Z,1594328938.853 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-07-09T21:08:58.853Z,1594328938.853 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-07-09T21:08:59.059Z,1594328939.059 [Aanderaa_O2] Loaded
2020-07-09T21:08:59.059Z,1594328939.059 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2020-07-09T21:08:59.087Z,1594328939.087 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-07-09T21:08:59.092Z,1594328939.092 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-07-09T21:08:59.094Z,1594328939.094 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-07-09T21:08:59.098Z,1594328939.098 [CTD_Seabird](INFO): created writer for : depth
2020-07-09T21:08:59.099Z,1594328939.099 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-07-09T21:08:59.104Z,1594328939.104 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-07-09T21:08:59.104Z,1594328939.104 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-07-09T21:08:59.110Z,1594328939.110 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-07-09T21:08:59.110Z,1594328939.110 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-07-09T21:08:59.115Z,1594328939.115 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-07-09T21:08:59.116Z,1594328939.116 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-07-09T21:08:59.121Z,1594328939.121 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-07-09T21:08:59.122Z,1594328939.122 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-07-09T21:08:59.127Z,1594328939.127 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-07-09T21:08:59.153Z,1594328939.153 [CTD_Seabird] Loaded
2020-07-09T21:08:59.153Z,1594328939.153 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-07-09T21:08:59.154Z,1594328939.154 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406764E0
2020-07-09T21:08:59.155Z,1594328939.155 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3413
2020-07-09T21:08:59.203Z,1594328939.203 [ESPComponent] Loaded
2020-07-09T21:08:59.203Z,1594328939.203 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2020-07-09T21:08:59.233Z,1594328939.233 [PAR_Licor] Loaded
2020-07-09T21:08:59.233Z,1594328939.233 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-07-09T21:08:59.255Z,1594328939.255 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-07-09T21:08:59.255Z,1594328939.255 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-07-09T21:08:59.259Z,1594328939.259 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-07-09T21:08:59.259Z,1594328939.259 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-07-09T21:08:59.263Z,1594328939.263 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-07-09T21:08:59.264Z,1594328939.264 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-07-09T21:08:59.268Z,1594328939.268 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-07-09T21:08:59.268Z,1594328939.268 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-07-09T21:08:59.272Z,1594328939.272 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-07-09T21:08:59.272Z,1594328939.272 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-07-09T21:08:59.276Z,1594328939.276 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-07-09T21:08:59.277Z,1594328939.277 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-07-09T21:08:59.281Z,1594328939.281 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-07-09T21:08:59.281Z,1594328939.281 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-07-09T21:08:59.285Z,1594328939.285 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-07-09T21:08:59.289Z,1594328939.289 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-07-09T21:08:59.290Z,1594328939.290 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-07-09T21:08:59.290Z,1594328939.290 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-07-09T21:08:59.294Z,1594328939.294 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-07-09T21:08:59.294Z,1594328939.294 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-07-09T21:08:59.299Z,1594328939.299 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-07-09T21:08:59.299Z,1594328939.299 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-07-09T21:08:59.303Z,1594328939.303 [WetLabsBB2FL] Loaded
2020-07-09T21:08:59.303Z,1594328939.303 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-07-09T21:08:59.304Z,1594328939.304 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406A64E0
2020-07-09T21:08:59.305Z,1594328939.305 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3414
2020-07-09T21:08:59.306Z,1594328939.306 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-07-09T21:08:59.306Z,1594328939.306 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-07-09T21:08:59.345Z,1594328939.345 [DepthRateCalculator] Loaded
2020-07-09T21:08:59.345Z,1594328939.345 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-07-09T21:08:59.350Z,1594328939.350 [PitchRateCalculator] Loaded
2020-07-09T21:08:59.351Z,1594328939.351 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-07-09T21:08:59.366Z,1594328939.366 [SpeedCalculator] Loaded
2020-07-09T21:08:59.366Z,1594328939.366 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-07-09T21:08:59.387Z,1594328939.387 [TempGradientCalculator] Loaded
2020-07-09T21:08:59.387Z,1594328939.387 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-07-09T21:08:59.392Z,1594328939.392 [YawRateCalculator] Loaded
2020-07-09T21:08:59.393Z,1594328939.393 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-07-09T21:08:59.425Z,1594328939.425 [ElevatorOffsetCalculator] Loaded
2020-07-09T21:08:59.425Z,1594328939.425 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-07-09T21:08:59.426Z,1594328939.426 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-07-09T21:08:59.426Z,1594328939.426 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-07-09T21:08:59.501Z,1594328939.501 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2020-07-09T21:08:59.502Z,1594328939.502 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-07-09T21:08:59.527Z,1594328939.527 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-07-09T21:08:59.528Z,1594328939.528 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-07-09T21:08:59.642Z,1594328939.642 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-07-09T21:08:59.642Z,1594328939.642 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-07-09T21:08:59.663Z,1594328939.663 [NavChart] Loaded
2020-07-09T21:08:59.663Z,1594328939.663 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-07-09T21:08:59.667Z,1594328939.667 [UniversalFixResidualReporter] Loaded
2020-07-09T21:08:59.668Z,1594328939.668 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-07-09T21:08:59.668Z,1594328939.668 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-07-09T21:08:59.669Z,1594328939.669 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-07-09T21:08:59.966Z,1594328939.966 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-07-09T21:08:59.971Z,1594328939.971 [AHRS_M2](INFO): created writer for : platform_orientation
2020-07-09T21:08:59.971Z,1594328939.971 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-07-09T21:08:59.977Z,1594328939.977 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-07-09T21:08:59.977Z,1594328939.977 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-07-09T21:08:59.982Z,1594328939.982 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-07-09T21:08:59.982Z,1594328939.982 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-07-09T21:08:59.987Z,1594328939.987 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-07-09T21:09:00.060Z,1594328940.060 [AHRS_M2] Loaded
2020-07-09T21:09:00.060Z,1594328940.060 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-07-09T21:09:00.137Z,1594328940.137 [DataOverHttps] Loaded
2020-07-09T21:09:00.138Z,1594328940.138 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-07-09T21:09:00.139Z,1594328940.139 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409C64E0
2020-07-09T21:09:00.139Z,1594328940.139 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3415
2020-07-09T21:09:00.169Z,1594328940.169 [Depth_Keller] Loaded
2020-07-09T21:09:00.169Z,1594328940.169 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-07-09T21:09:00.174Z,1594328940.174 [DropWeight] Loaded
2020-07-09T21:09:00.174Z,1594328940.174 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-07-09T21:09:00.237Z,1594328940.237 [DVL_micro] Loaded
2020-07-09T21:09:00.237Z,1594328940.237 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2020-07-09T21:09:00.341Z,1594328940.341 [NAL9602] Loaded
2020-07-09T21:09:00.342Z,1594328940.342 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-07-09T21:09:00.379Z,1594328940.379 [Onboard] Loaded
2020-07-09T21:09:00.380Z,1594328940.380 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2020-07-09T21:09:00.381Z,1594328940.381 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409F64E0
2020-07-09T21:09:00.381Z,1594328940.381 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 3416
2020-07-09T21:09:00.401Z,1594328940.401 [Radio_Surface] Loaded
2020-07-09T21:09:00.401Z,1594328940.401 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-07-09T21:09:00.402Z,1594328940.402 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A264E0
2020-07-09T21:09:00.402Z,1594328940.402 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3417
2020-07-09T21:09:00.552Z,1594328940.552 [DAT] Loaded
2020-07-09T21:09:00.552Z,1594328940.552 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2020-07-09T21:09:01.817Z,1594328941.817 [BPC1] Loaded
2020-07-09T21:09:01.817Z,1594328941.817 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-07-09T21:09:01.818Z,1594328941.818 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-07-09T21:09:01.818Z,1594328941.818 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-07-09T21:09:01.896Z,1594328941.896 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-07-09T21:09:01.897Z,1594328941.897 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-07-09T21:09:01.945Z,1594328941.945 [VerticalControl](DEBUG): Construct VerticalControl.
2020-07-09T21:09:02.029Z,1594328942.029 [VerticalControl] Loaded
2020-07-09T21:09:02.029Z,1594328942.029 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-07-09T21:09:02.030Z,1594328942.030 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-07-09T21:09:02.099Z,1594328942.099 [HorizontalControl] Loaded
2020-07-09T21:09:02.100Z,1594328942.100 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-07-09T21:09:02.100Z,1594328942.100 [SpeedControl](DEBUG): Construct SpeedControl.
2020-07-09T21:09:02.102Z,1594328942.102 [SpeedControl] Loaded
2020-07-09T21:09:02.102Z,1594328942.102 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-07-09T21:09:02.103Z,1594328942.103 [LoopControl](DEBUG): Construct LoopControl.
2020-07-09T21:09:02.103Z,1594328942.103 [LoopControl] Loaded
2020-07-09T21:09:02.104Z,1594328942.104 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-07-09T21:09:02.104Z,1594328942.104 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-07-09T21:09:02.105Z,1594328942.105 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-07-09T21:09:02.174Z,1594328942.174 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-07-09T21:09:02.175Z,1594328942.175 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-07-09T21:09:02.495Z,1594328942.495 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-07-09T21:09:02.499Z,1594328942.499 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-07-09T21:09:02.500Z,1594328942.500 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-07-09T21:09:02.507Z,1594328942.507 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-07-09T21:09:02.508Z,1594328942.508 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B5D4E0
2020-07-09T21:09:02.508Z,1594328942.508 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3418
2020-07-09T21:09:02.513Z,1594328942.513 [Supervisor](INFO): Main Thread ID is 3325
2020-07-09T21:09:02.513Z,1594328942.513 [Supervisor](DEBUG): Running supervisor.
2020-07-09T21:09:02.513Z,1594328942.513 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3419
2020-07-09T21:09:02.517Z,1594328942.517 [controlThread ThreadHandler](INFO): Handler Thread ID is 3420
2020-07-09T21:09:02.517Z,1594328942.517 [controlThread](DEBUG): Initializing ControlThread
2020-07-09T21:09:02.519Z,1594328942.519 [SBIT](INFO): Initialize SBIT Component.
2020-07-09T21:09:02.520Z,1594328942.520 [SBIT](IMPORTANT): git: 2020-07-06
2020-07-09T21:09:02.520Z,1594328942.520 [SBIT](INFO): git hash: 27d6d613a5802cbc38609e1e9bc8b2bacbda1d86
2020-07-09T21:09:02.520Z,1594328942.520 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-07-09T21:09:02.522Z,1594328942.522 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Wed Nov 13 12:38:10 PST 2019
2020-07-09T21:09:02.523Z,1594328942.523 [SBIT](INFO): Beginning SBIT in 72.000000 seconds.
2020-07-09T21:09:02.523Z,1594328942.523 [IBIT](INFO): Initialize IBIT Component.
2020-07-09T21:09:02.524Z,1594328942.524 [CBIT](DEBUG): Initialize CBIT Component.
2020-07-09T21:09:02.525Z,1594328942.525 [logger ThreadHandler](INFO): Handler Thread ID is 3421
2020-07-09T21:09:02.537Z,1594328942.537 [CBIT](DEBUG): Initialized mux pins.
2020-07-09T21:09:02.537Z,1594328942.537 [CBIT](DEBUG): Initializing the watchdog timer.
2020-07-09T21:09:02.545Z,1594328942.545 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3422
2020-07-09T21:09:02.546Z,1594328942.546 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-07-09T21:09:02.550Z,1594328942.550 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3424
2020-07-09T21:09:02.551Z,1594328942.551 [WetLabsBB2FL](INFO): Powering down
2020-07-09T21:09:02.561Z,1594328942.561 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-07-09T21:09:02.561Z,1594328942.561 [CBIT](DEBUG): Initializing heartbeat.
2020-07-09T21:09:02.585Z,1594328942.585 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3425
2020-07-09T21:09:02.586Z,1594328942.586 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-07-09T21:09:02.597Z,1594328942.597 [Onboard ThreadHandler](INFO): Handler Thread ID is 3426
2020-07-09T21:09:02.615Z,1594328942.615 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3427
2020-07-09T21:09:02.633Z,1594328942.633 [CBIT](DEBUG): Deactivating GF circuits.
2020-07-09T21:09:02.633Z,1594328942.633 [CBIT](DEBUG): Deactivating emergency mode.
2020-07-09T21:09:02.635Z,1594328942.635 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3428
2020-07-09T21:09:02.638Z,1594328942.638 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-07-09T21:09:02.638Z,1594328942.638 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-07-09T21:09:02.638Z,1594328942.638 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-07-09T21:09:02.638Z,1594328942.638 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-07-09T21:09:02.639Z,1594328942.639 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-07-09T21:09:02.639Z,1594328942.639 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-07-09T21:09:02.639Z,1594328942.639 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-07-09T21:09:02.639Z,1594328942.639 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-07-09T21:09:02.639Z,1594328942.639 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-07-09T21:09:02.639Z,1594328942.639 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-07-09T21:09:02.640Z,1594328942.640 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-07-09T21:09:02.640Z,1594328942.640 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-07-09T21:09:02.640Z,1594328942.640 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-07-09T21:09:02.640Z,1594328942.640 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-07-09T21:09:02.640Z,1594328942.640 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-07-09T21:09:02.640Z,1594328942.640 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-07-09T21:09:02.669Z,1594328942.669 [CBIT](DEBUG): Backplane powered.
2020-07-09T21:09:02.673Z,1594328942.673 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-07-09T21:09:02.674Z,1594328942.674 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-07-09T21:09:02.674Z,1594328942.674 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-07-09T21:09:02.674Z,1594328942.674 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-07-09T21:09:02.675Z,1594328942.675 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-07-09T21:09:02.675Z,1594328942.675 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-07-09T21:09:02.676Z,1594328942.676 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-07-09T21:09:02.677Z,1594328942.677 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-07-09T21:09:02.683Z,1594328942.683 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-07-09T21:09:02.693Z,1594328942.693 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-07-09T21:09:02.694Z,1594328942.694 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-07-09T21:09:02.694Z,1594328942.694 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-07-09T21:09:02.695Z,1594328942.695 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-07-09T21:09:02.729Z,1594328942.729 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-07-09T21:09:02.756Z,1594328942.756 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="StartupSatComms">

        <Sequence/>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:latitude_fix/>
        </ReadDatum>

        <ReadDatum>
            <Timeout Duration="P1M" />
            <Universal:platform_communications/>
        </ReadDatum>

    </Aggregate>

</Mission>


2020-07-09T21:09:02.765Z,1594328942.765 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-07-09T21:09:02.845Z,1594328942.845 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-07-09T21:09:02.846Z,1594328942.846 [Default:A.Wait](DEBUG): Construct Wait.
2020-07-09T21:09:02.848Z,1594328942.848 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-07-09T21:09:02.883Z,1594328942.883 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-07-09T21:09:02.905Z,1594328942.905 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-07-09T21:09:02.911Z,1594328942.911 [Default:E.Execute](DEBUG): Construct Execute.
2020-07-09T21:09:02.930Z,1594328942.930 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

    <DefineArg Name="ElapsedSinceDefaultStarted"><Units:minute/><Value>0</Value></DefineArg>

    <Guidance:Wait>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <Sequence/>
        <Setting><Guidance:Wait.duration/><Units:second/><Value>13</Value></Setting>
    </Guidance:Wait>

    <Guidance:GoToSurface>
        <Progression/>
    </Guidance:GoToSurface>

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

        <ReadDatum Id="Read_GPS">
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn on</String></Setting>
                </Guidance:Execute>
                <Syslog Severity="Critical">Dropped weight due to communications timeout.</Syslog>
            </Timeout>
            <Universal:platform_communications/>
        </ReadDatum>

        <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Units:minute/><Value>5</Value></Setting>
        </Guidance:Wait>

        <Assign><Sequence/><Arg Name="ElapsedSinceDefaultStarted"/><Elapsed><Universal:mission_started/></Elapsed></Assign>

        <Syslog Severity="Important">Default mission has been running for <Arg Name="ElapsedSinceDefaultStarted"/><Units:minute/></Syslog>

    </Aggregate>

    <Syslog Severity="Important">Restarting logs and Default mission.</Syslog>

    <Guidance:Execute>
        <Sequence/>
        <Setting><Guidance:Execute.command/><String>restart logs</String></Setting>
    </Guidance:Execute>

</Mission>


2020-07-09T21:09:02.935Z,1594328942.935 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,DAT,BPC1,PAR_Licor,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-07-09T21:09:03.001Z,1594328943.001 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-07-09T21:09:03.021Z,1594328943.021 [Radio_Surface](INFO): Powering up
2020-07-09T21:09:03.160Z,1594328943.160 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-07-09T21:09:03.160Z,1594328943.160 [DAT](INFO): Powering up
2020-07-09T21:09:03.160Z,1594328943.160 [DAT](DEBUG): Initializing DAT.
2020-07-09T21:09:03.213Z,1594328943.213 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-07-09T21:09:03.279Z,1594328943.279 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-07-09T21:09:03.285Z,1594328943.285 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-07-09T21:09:03.286Z,1594328943.286 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-07-09T21:09:03.293Z,1594328943.293 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-07-09T21:09:03.294Z,1594328943.294 [MassServo](DEBUG): Initializing EZServoServo.
2020-07-09T21:09:03.305Z,1594328943.305 [MassServo](DEBUG): Initializing MassServo.
2020-07-09T21:09:03.306Z,1594328943.306 [RudderServo](DEBUG): Initializing EZServoServo.
2020-07-09T21:09:03.317Z,1594328943.317 [RudderServo](DEBUG): Initializing RudderServo.
2020-07-09T21:09:03.318Z,1594328943.318 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-07-09T21:09:03.329Z,1594328943.329 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-07-09T21:09:03.454Z,1594328943.454 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-07-09T21:09:03.454Z,1594328943.454 [DropWeight] Hardware Fault, FailCount= 1
2020-07-09T21:09:03.454Z,1594328943.454 [DropWeight](ERROR): Hardware Fault
2020-07-09T21:09:03.523Z,1594328943.523 [CommandLine](FAULT): Scheduling is paused
2020-07-09T21:09:03.523Z,1594328943.523 [CBIT](INFO): Critical error at 20200709T210903
2020-07-09T21:09:03.523Z,1594328943.523 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-07-09T21:09:03.526Z,1594328943.526 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-07-09T21:09:03.526Z,1594328943.526 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-07-09T21:09:03.770Z,1594328943.770 [CBIT](INFO): Critical error at 20200709T210903
2020-07-09T21:09:06.289Z,1594328946.289 [Aanderaa_O2](INFO): Powering down
2020-07-09T21:09:16.131Z,1594328956.131 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004737
2020-07-09T21:09:17.092Z,1594328957.092 [DAT](INFO): commRate: 800
2020-07-09T21:09:17.092Z,1594328957.092 [DAT](INFO): commRate: 800
2020-07-09T21:09:17.496Z,1594328957.496 [DAT](INFO): entering command mode
2020-07-09T21:09:17.927Z,1594328957.927 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-09T21:09:18.303Z,1594328958.303 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-09T21:09:18.707Z,1594328958.707 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-09T21:09:19.111Z,1594328959.111 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-09T21:09:19.515Z,1594328959.515 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-09T21:09:19.919Z,1594328959.919 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-09T21:09:20.323Z,1594328960.323 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-09T21:09:20.736Z,1594328960.736 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-09T21:09:21.131Z,1594328961.131 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-09T21:09:21.535Z,1594328961.535 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-09T21:09:21.939Z,1594328961.939 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-09T21:09:22.343Z,1594328962.343 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-09T21:09:22.751Z,1594328962.751 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-09T21:09:23.160Z,1594328963.160 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-09T21:09:23.555Z,1594328963.555 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-09T21:09:23.959Z,1594328963.959 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-09T21:09:24.363Z,1594328964.363 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-09T21:09:24.767Z,1594328964.767 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-09T21:09:25.171Z,1594328965.171 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-09T21:09:25.584Z,1594328965.584 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-09T21:09:25.979Z,1594328965.979 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-09T21:09:26.383Z,1594328966.383 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-09T21:09:26.791Z,1594328966.791 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-09T21:09:27.191Z,1594328967.191 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-09T21:09:27.595Z,1594328967.595 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-09T21:09:28.033Z,1594328968.033 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-09T21:09:28.399Z,1594328968.399 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-09T21:09:28.807Z,1594328968.807 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-09T21:09:29.210Z,1594328969.210 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-09T21:09:29.615Z,1594328969.615 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-09T21:09:30.019Z,1594328970.019 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-09T21:09:30.423Z,1594328970.423 [NAL9602](INFO): Powering up NAL9602
2020-07-09T21:09:30.424Z,1594328970.424 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-09T21:09:30.844Z,1594328970.844 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-09T21:09:31.227Z,1594328971.227 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-09T21:09:31.643Z,1594328971.643 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-09T21:09:32.039Z,1594328972.039 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-09T21:09:32.443Z,1594328972.443 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-09T21:09:32.847Z,1594328972.847 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-09T21:09:32.847Z,1594328972.847 [DAT](FAULT): failed to enter command mode
2020-07-09T21:09:33.277Z,1594328973.277 [DAT](INFO): entering command mode
2020-07-09T21:09:33.656Z,1594328973.656 [DAT](INFO): setting verbose to 3
2020-07-09T21:09:34.086Z,1594328974.086 [DAT](INFO): set verbose to 3
2020-07-09T21:09:34.086Z,1594328974.086 [DAT](INFO): setting DatVerbose to 27440
2020-07-09T21:09:34.395Z,1594328974.395 [CommandLine](IMPORTANT): got command failComponent
2020-07-09T21:09:34.396Z,1594328974.396 [CommandLine](IMPORTANT): Failed components:
2020-07-09T21:09:34.396Z,1594328974.396 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2020-07-09T21:09:34.464Z,1594328974.464 [DAT](INFO): set DatVerbose to 27440
2020-07-09T21:09:34.464Z,1594328974.464 [DAT](INFO): setting transmit power to 8
2020-07-09T21:09:34.868Z,1594328974.868 [DAT](INFO): set transmit power to 8
2020-07-09T21:09:34.868Z,1594328974.868 [DAT](INFO): setting local address to 4
2020-07-09T21:09:35.271Z,1594328975.271 [DAT](INFO): set local address to 4
2020-07-09T21:09:41.331Z,1594328981.331 [NAL9602](INFO): NAL9602 initialized
2020-07-09T21:10:15.280Z,1594329015.280 [SBIT](IMPORTANT): Beginning Startup BIT
2020-07-09T21:10:15.285Z,1594329015.285 [CBIT](IMPORTANT): Beginning ground fault scan
2020-07-09T21:10:26.288Z,1594329026.288 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.006034
CHAN A1 (24V): 0.000189
CHAN A2 (12V): -0.005072
CHAN A3 (5V): -0.002739
CHAN B0 (3.3V): -0.000993
CHAN B1 (3.15aV): -0.001111
CHAN B2 (3.15bV): -0.001131
CHAN B3 (GND): -0.000119
OPEN: 0.006891
Full Scale Calc: 4.765 mA, -1.589 mA
2020-07-09T21:11:09.527Z,1594329069.527 [SBIT](IMPORTANT): SBIT PASSED
2020-07-09T21:11:09.527Z,1594329069.527 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-07-09T21:11:09.528Z,1594329069.528 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2020-07-09T21:11:09.528Z,1594329069.528 [SBIT](IMPORTANT): BuoyancyServo.checkingTimeout=30 second;

2020-07-09T21:11:09.528Z,1594329069.528 [SBIT](IMPORTANT): CBIT.gf24Offset=148.1 microampere;

2020-07-09T21:11:09.891Z,1594329069.891 [MissionManager](IMPORTANT): Started mission Startup
2020-07-09T21:11:09.891Z,1594329069.891 [Startup] Running Loop=1
2020-07-09T21:11:09.891Z,1594329069.891 [Startup](DEBUG): Aggregate::initialize Startup
2020-07-09T21:11:09.891Z,1594329069.891 [Startup:A.GoToSurface] Running Loop=1
2020-07-09T21:11:09.891Z,1594329069.891 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-07-09T21:11:09.892Z,1594329069.892 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-07-09T21:11:09.892Z,1594329069.892 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-07-09T21:11:09.893Z,1594329069.893 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-07-09T21:11:09.893Z,1594329069.893 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-07-09T21:11:09.894Z,1594329069.894 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-07-09T21:11:09.895Z,1594329069.895 [Startup:StartupSatComms] Running Loop=1
2020-07-09T21:11:09.896Z,1594329069.896 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-07-09T21:11:09.896Z,1594329069.896 [Startup:StartupSatComms:A] Running Loop=1
2020-07-09T21:11:10.303Z,1594329070.303 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-07-09T21:11:34.717Z,1594329094.717 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-07-09T21:12:02.830Z,1594329122.830 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-07-09T21:12:02.830Z,1594329122.830 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T21:12:02.849Z,1594329122.849 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T21:12:03.250Z,1594329123.250 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T21:12:03.250Z,1594329123.250 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-07-09T21:12:05.841Z,1594329125.841 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-07-09T21:12:10.088Z,1594329130.088 [Startup:StartupSatComms:A](INFO): Timed out from 2020-07-09T21:11:09.9Z
2020-07-09T21:12:10.088Z,1594329130.088 [Startup:StartupSatComms:A] Stopped
2020-07-09T21:12:10.088Z,1594329130.088 [Startup:StartupSatComms:B] Running Loop=1
2020-07-09T21:12:10.549Z,1594329130.549 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-07-09T21:12:36.965Z,1594329156.965 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-07-09T21:13:07.852Z,1594329187.852 [DVL_micro](ERROR): only read 3 of 4 data items
2020-07-09T21:13:07.852Z,1594329187.852 [DVL_micro](ERROR): Failed to parse:
:BI,+00004,-00088,-00757,+0:BD,+00000000.00,+00000000.00,+00000000.00,000.00
2020-07-09T21:13:08.090Z,1594329188.090 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-07-09T21:13:10.295Z,1594329190.295 [Startup:StartupSatComms:B](INFO): Timed out from 2020-07-09T21:12:10.1Z
2020-07-09T21:13:10.295Z,1594329190.295 [Startup:StartupSatComms:B] Stopped
2020-07-09T21:13:10.295Z,1594329190.295 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-07-09T21:13:10.295Z,1594329190.295 [Startup:StartupSatComms] Stopped
2020-07-09T21:13:10.295Z,1594329190.295 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-07-09T21:13:10.296Z,1594329190.296 [Startup](INFO): Completed Startup
2020-07-09T21:13:10.296Z,1594329190.296 [MissionManager](INFO): Startup is completed.
2020-07-09T21:13:10.296Z,1594329190.296 [MissionManager](INFO): Uninitializing Mission Startup
2020-07-09T21:13:10.296Z,1594329190.296 [Startup] Stopped
2020-07-09T21:13:10.296Z,1594329190.296 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-07-09T21:13:10.296Z,1594329190.296 [Startup:A.GoToSurface] Stopped
2020-07-09T21:13:10.296Z,1594329190.296 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-07-09T21:13:10.692Z,1594329190.692 [MissionManager](IMPORTANT): Started mission Default
2020-07-09T21:13:10.692Z,1594329190.692 [Default] Running Loop=1
2020-07-09T21:13:10.692Z,1594329190.692 [Default](DEBUG): Aggregate::initialize Default
2020-07-09T21:13:10.692Z,1594329190.692 [Default:B.GoToSurface] Running Loop=1
2020-07-09T21:13:10.692Z,1594329190.692 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-07-09T21:13:10.692Z,1594329190.692 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-07-09T21:13:10.693Z,1594329190.693 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-07-09T21:13:10.693Z,1594329190.693 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-07-09T21:13:10.693Z,1594329190.693 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-07-09T21:13:10.694Z,1594329190.694 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-07-09T21:13:10.694Z,1594329190.694 [Default:A.Wait] Running Loop=1
2020-07-09T21:13:10.694Z,1594329190.694 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-07-09T21:13:24.050Z,1594329204.050 [Default:A.Wait](INFO): Done Waiting.
2020-07-09T21:13:24.050Z,1594329204.050 [Default:A.Wait] Stopped
2020-07-09T21:13:24.051Z,1594329204.051 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-07-09T21:13:24.431Z,1594329204.431 [Default:CheckIn] Running Loop=1
2020-07-09T21:13:24.431Z,1594329204.431 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-09T21:13:24.431Z,1594329204.431 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-09T21:13:24.843Z,1594329204.843 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-07-09T21:13:39.213Z,1594329219.213 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-07-09T21:13:42.225Z,1594329222.225 [DataOverHttps](INFO): setting unavailable, lastComms_.elapsed()=180.719513
2020-07-09T21:14:40.458Z,1594329280.458 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-07-09T21:14:43.998Z,1594329283.998 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-07-09T21:14:43.998Z,1594329283.998 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+19.5,0000.0,1489:WI,+00162,-00101,-00846,+00000,A
2020-07-09T21:14:44.800Z,1594329284.800 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-07-09T21:15:03.794Z,1594329303.794 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-07-09T21:15:03.794Z,1594329303.794 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T21:15:03.830Z,1594329303.830 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T21:15:04.287Z,1594329304.287 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T21:15:04.287Z,1594329304.287 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-07-09T21:15:11.581Z,1594329311.581 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-07-09T21:15:42.709Z,1594329342.709 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-07-09T21:16:13.833Z,1594329373.833 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-07-09T21:16:43.989Z,1594329403.989 [BPC1](ERROR): Battery stick #39 (s/n: 00F0) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2020-07-09T21:16:43.990Z,1594329403.990 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5.
2020-07-09T21:16:43.993Z,1594329403.993 [BPC1](INFO): Received data from all battery sticks.
2020-07-09T21:16:44.965Z,1594329404.965 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-07-09T21:17:16.089Z,1594329436.089 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-07-09T21:17:47.213Z,1594329467.213 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-07-09T21:18:04.805Z,1594329484.805 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2020-07-09T21:18:04.805Z,1594329484.805 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T21:18:04.816Z,1594329484.816 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T21:18:05.241Z,1594329485.241 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T21:18:05.241Z,1594329485.241 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2020-07-09T21:18:18.337Z,1594329498.337 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-07-09T21:18:24.607Z,1594329504.607 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-07-09T21:13:24.4Z
2020-07-09T21:18:24.607Z,1594329504.607 [Default:CheckIn:Read_GPS] Stopped
2020-07-09T21:18:24.607Z,1594329504.607 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-07-09T21:18:24.993Z,1594329504.993 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-07-09T21:18:49.461Z,1594329529.461 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-07-09T21:18:54.882Z,1594329534.882 [NAL9602](ERROR): Queried for signal strength and failed to receive proper response. 
2020-07-09T21:18:54.961Z,1594329534.961 [NAL9602](ERROR): received: 
+CSQ:0

OK
2020-07-09T21:18:54.961Z,1594329534.961 [NAL9602] Data Fault, FailCount= 1
2020-07-09T21:18:54.961Z,1594329534.961 [NAL9602](ERROR): Data Fault
2020-07-09T21:18:54.983Z,1594329534.983 [CBIT](ERROR): Data Fault in component: NAL9602
2020-07-09T21:18:55.286Z,1594329535.286 [NAL9602](INFO): Powering down
2020-07-09T21:18:56.114Z,1594329536.114 [CBIT](INFO): Clearing failed state for component NAL9602
2020-07-09T21:18:56.114Z,1594329536.114 [NAL9602] No Fault, FailCount= 1
2020-07-09T21:19:20.585Z,1594329560.585 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-07-09T21:19:23.167Z,1594329563.167 [DVL_micro](ERROR): Failed to parse:240,+00182,-00814,+00000,A
2020-07-09T21:19:25.584Z,1594329565.584 [NAL9602](INFO): Powering up NAL9602
2020-07-09T21:19:36.487Z,1594329576.487 [NAL9602](INFO): NAL9602 initialized
2020-07-09T21:19:45.800Z,1594329585.800 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-07-09T21:19:45.800Z,1594329585.800 [NAL9602] Data Fault, FailCount= 2
2020-07-09T21:19:45.800Z,1594329585.800 [NAL9602](ERROR): Data Fault
2020-07-09T21:19:45.832Z,1594329585.832 [CBIT](ERROR): Data Fault in component: NAL9602
2020-07-09T21:19:46.195Z,1594329586.195 [NAL9602](INFO): Powering down
2020-07-09T21:19:47.015Z,1594329587.015 [CBIT](INFO): Clearing failed state for component NAL9602
2020-07-09T21:19:47.015Z,1594329587.015 [NAL9602] No Fault, FailCount= 2
2020-07-09T21:19:51.709Z,1594329591.709 [DataOverHttps](INFO): Exceeded connect timeout, disconnecting.
2020-07-09T21:19:59.986Z,1594329599.986 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.003533
2020-07-09T21:20:07.229Z,1594329607.229 [DataOverHttps](INFO): Sending 156 bytes from file Logs/20200708T175025/Courier0004.lzma
2020-07-09T21:20:08.231Z,1594329608.231 [DataOverHttps](INFO): Moved sent file to Logs/20200708T175025/Courier0004.lzma.bak
2020-07-09T21:20:08.231Z,1594329608.231 [DataOverHttps](INFO): SBD MOMSN=12431143
2020-07-09T21:20:16.494Z,1594329616.494 [NAL9602](INFO): Powering up NAL9602
2020-07-09T21:20:23.648Z,1594329623.648 [DataOverHttps](INFO): Sending 245 bytes from file Logs/20200709T210851/Courier0000.lzma
2020-07-09T21:20:24.651Z,1594329624.651 [DataOverHttps](INFO): Moved sent file to Logs/20200709T210851/Courier0000.lzma.bak
2020-07-09T21:20:24.651Z,1594329624.651 [DataOverHttps](INFO): SBD MOMSN=12431145
2020-07-09T21:20:27.425Z,1594329627.425 [NAL9602](INFO): NAL9602 initialized
2020-07-09T21:20:31.056Z,1594329631.056 [DVL_micro](ERROR): only read 3 of 4 data items
2020-07-09T21:20:31.056Z,1594329631.056 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.999,+9999.99
2020-07-09T21:20:36.298Z,1594329636.298 [DVL_micro](ERROR): Failed to parse:049,+00066,-00758,+00000,I
2020-07-09T21:20:41.146Z,1594329641.146 [DVL_micro](ERROR): only read 3 of 4 data items
2020-07-09T21:20:41.147Z,1594329641.147 [DVL_micro](ERROR): Failed to parse:
:BI,+0002,-00745,+00000,I
2020-07-09T21:20:47.549Z,1594329647.549 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20200709T210851/Courier0004.lzma
2020-07-09T21:20:48.550Z,1594329648.550 [DataOverHttps](INFO): Moved sent file to Logs/20200709T210851/Courier0004.lzma.bak
2020-07-09T21:20:48.551Z,1594329648.551 [DataOverHttps](INFO): SBD MOMSN=12431151
2020-07-09T21:21:05.870Z,1594329665.870 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2020-07-09T21:21:05.870Z,1594329665.870 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T21:21:05.923Z,1594329665.923 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T21:21:06.285Z,1594329666.285 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T21:21:06.285Z,1594329666.285 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2020-07-09T21:21:13.023Z,1594329673.023 [DataOverHttps](INFO): Sending 1086 bytes from file Logs/20200708T170848/Express0001.lzma
2020-07-09T21:21:14.023Z,1594329674.023 [DataOverHttps](INFO): Moved sent file to Logs/20200708T170848/Express0001.lzma.bak
2020-07-09T21:21:14.023Z,1594329674.023 [DataOverHttps](INFO): SBD MOMSN=12431154
2020-07-09T21:21:35.195Z,1594329695.195 [DataOverHttps](INFO): Sending 1041 bytes from file Logs/20200708T174357/Express0001.lzma
2020-07-09T21:21:36.190Z,1594329696.190 [DataOverHttps](INFO): Moved sent file to Logs/20200708T174357/Express0001.lzma.bak
2020-07-09T21:21:36.191Z,1594329696.191 [DataOverHttps](INFO): SBD MOMSN=12431191
2020-07-09T21:21:51.782Z,1594329711.782 [DataOverHttps](INFO): Sending 1215 bytes from file Logs/20200708T174357/Express0005.lzma
2020-07-09T21:21:52.782Z,1594329712.782 [DataOverHttps](INFO): Moved sent file to Logs/20200708T174357/Express0005.lzma.bak
2020-07-09T21:21:52.783Z,1594329712.783 [DataOverHttps](INFO): SBD MOMSN=12431220
2020-07-09T21:22:11.354Z,1594329731.354 [DataOverHttps](INFO): Sending 1212 bytes from file Logs/20200708T175025/Express0001.lzma
2020-07-09T21:22:12.354Z,1594329732.354 [DataOverHttps](INFO): Moved sent file to Logs/20200708T175025/Express0001.lzma.bak
2020-07-09T21:22:12.355Z,1594329732.355 [DataOverHttps](INFO): SBD MOMSN=12431259
2020-07-09T21:22:32.266Z,1594329752.266 [DataOverHttps](INFO): Sending 1062 bytes from file Logs/20200708T175025/Express0005.lzma
2020-07-09T21:22:33.266Z,1594329753.266 [DataOverHttps](INFO): Moved sent file to Logs/20200708T175025/Express0005.lzma.bak
2020-07-09T21:22:33.267Z,1594329753.267 [DataOverHttps](INFO): SBD MOMSN=12431298
2020-07-09T21:22:51.214Z,1594329771.214 [DataOverHttps](INFO): Sending 840 bytes from file Logs/20200709T210851/Express0001.lzma
2020-07-09T21:22:52.214Z,1594329772.214 [DataOverHttps](INFO): Moved sent file to Logs/20200709T210851/Express0001.lzma.bak
2020-07-09T21:22:52.215Z,1594329772.215 [DataOverHttps](INFO): SBD MOMSN=12431325
2020-07-09T21:23:07.705Z,1594329787.705 [DataOverHttps](INFO): Sending 188 bytes from file Logs/20200709T210851/Express0005.lzma
2020-07-09T21:23:08.706Z,1594329788.706 [DataOverHttps](INFO): Moved sent file to Logs/20200709T210851/Express0005.lzma.bak
2020-07-09T21:23:08.707Z,1594329788.707 [DataOverHttps](INFO): SBD MOMSN=12431346
2020-07-09T21:23:10.671Z,1594329790.671 [Default:CheckIn:Read_Iridium] Stopped
2020-07-09T21:23:10.671Z,1594329790.671 [Default:CheckIn:C.Wait] Running Loop=1
2020-07-09T21:23:10.671Z,1594329790.671 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-07-09T21:24:03.600Z,1594329843.600 [CBIT](INFO): Clearing failed state for component DropWeight
2020-07-09T21:24:03.600Z,1594329843.600 [DropWeight] No Fault, FailCount= 1
2020-07-09T21:24:06.805Z,1594329846.805 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2020-07-09T21:24:06.805Z,1594329846.805 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T21:24:06.841Z,1594329846.841 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T21:24:07.225Z,1594329847.225 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T21:24:07.225Z,1594329847.225 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2020-07-09T21:27:07.849Z,1594330027.849 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2020-07-09T21:27:07.849Z,1594330027.849 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T21:27:07.860Z,1594330027.860 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T21:27:08.262Z,1594330028.262 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T21:27:08.262Z,1594330028.262 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2020-07-09T21:28:11.286Z,1594330091.286 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-07-09T21:28:11.286Z,1594330091.286 [Default:CheckIn:C.Wait] Stopped
2020-07-09T21:28:11.286Z,1594330091.286 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-07-09T21:28:11.287Z,1594330091.287 [Default:CheckIn:D] Running Loop=1
2020-07-09T21:28:11.686Z,1594330091.686 [Default:CheckIn:D] Stopped
2020-07-09T21:28:11.686Z,1594330091.686 [Default:CheckIn:E] Running Loop=1
2020-07-09T21:28:12.067Z,1594330092.067 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 15.016574 min 
2020-07-09T21:28:12.067Z,1594330092.067 [Default:CheckIn:E] Stopped
2020-07-09T21:28:12.067Z,1594330092.067 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-07-09T21:28:12.068Z,1594330092.068 [Default:CheckIn] Stopped
2020-07-09T21:28:12.068Z,1594330092.068 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-07-09T21:28:12.068Z,1594330092.068 [Default:CheckIn](INFO): Running loop #2
2020-07-09T21:28:12.068Z,1594330092.068 [Default:CheckIn] Running Loop=2
2020-07-09T21:28:12.068Z,1594330092.068 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-09T21:28:12.068Z,1594330092.068 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-09T21:29:38.929Z,1594330178.929 [DVL_micro](ERROR): only read 2 of 4 data items
2020-07-09T21:29:38.929Z,1594330178.929 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,++9999.99
2020-07-09T21:29:46.200Z,1594330186.200 [DVL_micro](ERROR): Failed to parse:
:WI,+00006,-00096,-00646,+00000,A
2020-07-09T21:30:08.862Z,1594330208.862 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2020-07-09T21:30:08.862Z,1594330208.862 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T21:30:08.880Z,1594330208.880 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T21:30:09.244Z,1594330209.244 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T21:30:09.245Z,1594330209.245 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2020-07-09T21:30:29.035Z,1594330229.035 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-07-09T21:30:29.035Z,1594330229.035 [NAL9602] Data Fault, FailCount= 3
2020-07-09T21:30:29.035Z,1594330229.035 [NAL9602](ERROR): Data Fault
2020-07-09T21:30:29.060Z,1594330229.060 [CBIT](ERROR): Data Fault in component: NAL9602
2020-07-09T21:30:29.424Z,1594330229.424 [NAL9602](INFO): Powering down
2020-07-09T21:30:30.253Z,1594330230.253 [CBIT](INFO): Clearing failed state for component NAL9602
2020-07-09T21:30:30.253Z,1594330230.253 [NAL9602] No Fault, FailCount= 3
2020-07-09T21:30:59.724Z,1594330259.724 [NAL9602](INFO): Powering up NAL9602
2020-07-09T21:31:10.632Z,1594330270.632 [NAL9602](INFO): NAL9602 initialized
2020-07-09T21:33:09.843Z,1594330389.843 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2020-07-09T21:33:09.843Z,1594330389.843 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T21:33:09.853Z,1594330389.853 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T21:33:10.241Z,1594330390.241 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T21:33:10.242Z,1594330390.242 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2020-07-09T21:33:12.282Z,1594330392.282 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-07-09T21:28:12.1Z
2020-07-09T21:33:12.282Z,1594330392.282 [Default:CheckIn:Read_GPS] Stopped
2020-07-09T21:33:12.282Z,1594330392.282 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-07-09T21:33:19.440Z,1594330399.440 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200709T210851/Courier0007.lzma
2020-07-09T21:33:20.442Z,1594330400.442 [DataOverHttps](INFO): Moved sent file to Logs/20200709T210851/Courier0007.lzma.bak
2020-07-09T21:33:20.443Z,1594330400.443 [DataOverHttps](INFO): SBD MOMSN=12431460
2020-07-09T21:33:36.473Z,1594330416.473 [DataOverHttps](INFO): Sending 189 bytes from file Logs/20200709T210851/Express0008.lzma
2020-07-09T21:33:37.475Z,1594330417.475 [DataOverHttps](INFO): Moved sent file to Logs/20200709T210851/Express0008.lzma.bak
2020-07-09T21:33:37.475Z,1594330417.475 [DataOverHttps](INFO): SBD MOMSN=12431462
2020-07-09T21:33:39.362Z,1594330419.362 [Default:CheckIn:Read_Iridium] Stopped
2020-07-09T21:33:39.362Z,1594330419.362 [Default:CheckIn:C.Wait] Running Loop=1
2020-07-09T21:33:39.362Z,1594330419.362 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-07-09T21:36:10.838Z,1594330570.838 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2020-07-09T21:36:10.838Z,1594330570.838 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T21:36:10.868Z,1594330570.868 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T21:36:11.254Z,1594330571.254 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T21:36:11.254Z,1594330571.254 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2020-07-09T21:38:39.962Z,1594330719.962 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-07-09T21:38:39.962Z,1594330719.962 [Default:CheckIn:C.Wait] Stopped
2020-07-09T21:38:39.963Z,1594330719.963 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-07-09T21:38:39.963Z,1594330719.963 [Default:CheckIn:D] Running Loop=1
2020-07-09T21:38:40.362Z,1594330720.362 [Default:CheckIn:D] Stopped
2020-07-09T21:38:40.362Z,1594330720.362 [Default:CheckIn:E] Running Loop=1
2020-07-09T21:38:40.744Z,1594330720.744 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 25.494503 min 
2020-07-09T21:38:40.744Z,1594330720.744 [Default:CheckIn:E] Stopped
2020-07-09T21:38:40.744Z,1594330720.744 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-07-09T21:38:40.744Z,1594330720.744 [Default:CheckIn] Stopped
2020-07-09T21:38:40.745Z,1594330720.745 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-07-09T21:38:40.745Z,1594330720.745 [Default:CheckIn](INFO): Running loop #3
2020-07-09T21:38:40.745Z,1594330720.745 [Default:CheckIn] Running Loop=3
2020-07-09T21:38:40.745Z,1594330720.745 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-09T21:38:40.745Z,1594330720.745 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-09T21:39:11.863Z,1594330751.863 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2020-07-09T21:39:11.863Z,1594330751.863 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T21:39:11.873Z,1594330751.873 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T21:39:12.263Z,1594330752.263 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T21:39:12.264Z,1594330752.264 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2020-07-09T21:41:11.834Z,1594330871.834 [DVL_micro](ERROR): only read 1 of 4 data items
2020-07-09T21:41:11.834Z,1594330871.834 [DVL_micro](ERROR): Failed to parse:
:RD,+9999..99,+9999.99,+9999.99
2020-07-09T21:41:13.849Z,1594330873.849 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-07-09T21:41:13.849Z,1594330873.849 [NAL9602] Data Fault, FailCount= 4
2020-07-09T21:41:13.850Z,1594330873.850 [NAL9602](ERROR): Data Fault
2020-07-09T21:41:13.866Z,1594330873.866 [CBIT](ERROR): Data Fault in component: NAL9602
2020-07-09T21:41:14.273Z,1594330874.273 [NAL9602](INFO): Powering down
2020-07-09T21:41:15.080Z,1594330875.080 [CBIT](INFO): Clearing failed state for component NAL9602
2020-07-09T21:41:15.080Z,1594330875.080 [NAL9602] No Fault, FailCount= 4
2020-07-09T21:41:24.361Z,1594330884.361 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-07-09T21:41:24.361Z,1594330884.361 [DVL_micro](ERROR): Failed to parse:
:TS,000000000.0,+20.4,089.0,000
2020-07-09T21:41:44.568Z,1594330904.568 [NAL9602](INFO): Powering up NAL9602
2020-07-09T21:41:55.473Z,1594330915.473 [NAL9602](INFO): NAL9602 initialized
2020-07-09T21:42:12.870Z,1594330932.870 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2020-07-09T21:42:12.870Z,1594330932.870 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T21:42:12.884Z,1594330932.884 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T21:42:13.258Z,1594330933.258 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T21:42:13.258Z,1594330933.258 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2020-07-09T21:43:40.947Z,1594331020.947 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-07-09T21:38:40.7Z
2020-07-09T21:43:40.948Z,1594331020.948 [Default:CheckIn:Read_GPS] Stopped
2020-07-09T21:43:40.948Z,1594331020.948 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-07-09T21:43:48.304Z,1594331028.304 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200709T210851/Courier0010.lzma
2020-07-09T21:43:49.306Z,1594331029.306 [DataOverHttps](INFO): Moved sent file to Logs/20200709T210851/Courier0010.lzma.bak
2020-07-09T21:43:49.307Z,1594331029.307 [DataOverHttps](INFO): SBD MOMSN=12431558
2020-07-09T21:44:04.845Z,1594331044.845 [DataOverHttps](INFO): Sending 149 bytes from file Logs/20200709T210851/Express0011.lzma
2020-07-09T21:44:05.846Z,1594331045.846 [DataOverHttps](INFO): Moved sent file to Logs/20200709T210851/Express0011.lzma.bak
2020-07-09T21:44:05.847Z,1594331045.847 [DataOverHttps](INFO): SBD MOMSN=12431560
2020-07-09T21:44:07.630Z,1594331047.630 [Default:CheckIn:Read_Iridium] Stopped
2020-07-09T21:44:07.630Z,1594331047.630 [Default:CheckIn:C.Wait] Running Loop=1
2020-07-09T21:44:07.631Z,1594331047.631 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-07-09T21:45:13.897Z,1594331113.897 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2020-07-09T21:45:13.897Z,1594331113.897 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T21:45:13.907Z,1594331113.907 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T21:45:14.302Z,1594331114.302 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T21:45:14.302Z,1594331114.302 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2020-07-09T21:48:14.898Z,1594331294.898 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2020-07-09T21:48:14.898Z,1594331294.898 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T21:48:14.941Z,1594331294.941 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T21:48:15.326Z,1594331295.326 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T21:48:15.326Z,1594331295.326 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2020-07-09T21:48:39.926Z,1594331319.926 [DVL_micro](ERROR): only read 3 of 4 data items
2020-07-09T21:48:39.927Z,1594331319.927 [DVL_micro](ERROR): Failed to parse:
:RD,+99999.99,+9999.99,+9999.99
2020-07-09T21:48:47.213Z,1594331327.213 [DVL_micro](ERROR): only read 3 of 4 data items
2020-07-09T21:48:47.213Z,1594331327.213 [DVL_micro](ERROR): Failed to parse:
:BI,-00138,00511,+00000,I
2020-07-09T21:49:08.231Z,1594331348.231 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-07-09T21:49:08.231Z,1594331348.231 [Default:CheckIn:C.Wait] Stopped
2020-07-09T21:49:08.231Z,1594331348.231 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-07-09T21:49:08.231Z,1594331348.231 [Default:CheckIn:D] Running Loop=1
2020-07-09T21:49:08.638Z,1594331348.638 [Default:CheckIn:D] Stopped
2020-07-09T21:49:08.639Z,1594331348.639 [Default:CheckIn:E] Running Loop=1
2020-07-09T21:49:09.015Z,1594331349.015 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.965776 min 
2020-07-09T21:49:09.016Z,1594331349.016 [Default:CheckIn:E] Stopped
2020-07-09T21:49:09.016Z,1594331349.016 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-07-09T21:49:09.016Z,1594331349.016 [Default:CheckIn] Stopped
2020-07-09T21:49:09.016Z,1594331349.016 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-07-09T21:49:09.016Z,1594331349.016 [Default:CheckIn](INFO): Running loop #4
2020-07-09T21:49:09.016Z,1594331349.016 [Default:CheckIn] Running Loop=4
2020-07-09T21:49:09.016Z,1594331349.016 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-09T21:49:09.016Z,1594331349.016 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-09T21:51:15.948Z,1594331475.948 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2020-07-09T21:51:15.948Z,1594331475.948 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T21:51:15.992Z,1594331475.992 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T21:51:16.326Z,1594331476.326 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T21:51:16.326Z,1594331476.326 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2020-07-09T21:51:56.310Z,1594331516.310 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-07-09T21:51:56.310Z,1594331516.310 [NAL9602] Data Fault, FailCount= 5
2020-07-09T21:51:56.310Z,1594331516.310 [NAL9602](ERROR): Data Fault
2020-07-09T21:51:56.326Z,1594331516.326 [CBIT](ERROR): Data Fault in component: NAL9602
2020-07-09T21:51:56.327Z,1594331516.327 [CBIT](CRITICAL): Data Fault in component: NAL9602
2020-07-09T21:51:56.695Z,1594331516.695 [NAL9602](INFO): Powering down
2020-07-09T21:51:56.739Z,1594331516.739 [CBIT](INFO): Critical error at 20200709T215156
2020-07-09T21:51:57.102Z,1594331517.102 [NAL9602](FAULT): LCB fault:  Software Overcurrent.
2020-07-09T21:51:57.102Z,1594331517.102 [NAL9602] Hardware Fault, FailCount= 5
2020-07-09T21:51:57.103Z,1594331517.103 [NAL9602](ERROR): Hardware Fault
2020-07-09T21:53:56.312Z,1594331636.312 [CBIT](INFO): Clearing failed state for component NAL9602
2020-07-09T21:53:56.312Z,1594331636.312 [NAL9602] No Fault, FailCount= 5
2020-07-09T21:53:56.695Z,1594331636.695 [NAL9602](INFO): Powering up NAL9602
2020-07-09T21:54:05.174Z,1594331645.174 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-07-09T21:54:05.174Z,1594331645.174 [DropWeight] Hardware Fault, FailCount= 1
2020-07-09T21:54:05.174Z,1594331645.174 [DropWeight](ERROR): Hardware Fault
2020-07-09T21:54:05.214Z,1594331645.214 [CBIT](INFO): Critical error at 20200709T215405
2020-07-09T21:54:05.216Z,1594331645.216 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-07-09T21:54:05.216Z,1594331645.216 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-07-09T21:54:05.596Z,1594331645.596 [CBIT](INFO): Critical error at 20200709T215405
2020-07-09T21:54:07.599Z,1594331647.599 [NAL9602](INFO): NAL9602 initialized
2020-07-09T21:54:09.224Z,1594331649.224 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-07-09T21:49:09.0Z
2020-07-09T21:54:09.224Z,1594331649.224 [Default:CheckIn:Read_GPS] Stopped
2020-07-09T21:54:09.224Z,1594331649.224 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-07-09T21:54:16.640Z,1594331656.640 [DataOverHttps](INFO): Sending 134 bytes from file Logs/20200709T210851/Courier0013.lzma
2020-07-09T21:54:16.949Z,1594331656.949 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 15
2020-07-09T21:54:16.949Z,1594331656.949 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T21:54:16.960Z,1594331656.960 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T21:54:17.305Z,1594331657.305 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-07-09T21:54:17.305Z,1594331657.305 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,38,0000.0,1489.0,000
2020-07-09T21:54:17.357Z,1594331657.357 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T21:54:17.357Z,1594331657.357 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 15
2020-07-09T21:54:17.643Z,1594331657.643 [DataOverHttps](INFO): Moved sent file to Logs/20200709T210851/Courier0013.lzma.bak
2020-07-09T21:54:17.643Z,1594331657.643 [DataOverHttps](INFO): SBD MOMSN=12431593
2020-07-09T21:54:33.214Z,1594331673.214 [DataOverHttps](INFO): Sending 182 bytes from file Logs/20200709T210851/Express0014.lzma
2020-07-09T21:54:34.214Z,1594331674.214 [DataOverHttps](INFO): Moved sent file to Logs/20200709T210851/Express0014.lzma.bak
2020-07-09T21:54:34.215Z,1594331674.215 [DataOverHttps](INFO): SBD MOMSN=12431598
2020-07-09T21:54:36.299Z,1594331676.299 [Default:CheckIn:Read_Iridium] Stopped
2020-07-09T21:54:36.299Z,1594331676.299 [Default:CheckIn:C.Wait] Running Loop=1
2020-07-09T21:54:36.299Z,1594331676.299 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-07-09T21:55:27.598Z,1594331727.598 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-07-09T21:55:27.598Z,1594331727.598 [DVL_micro](ERROR): Failed to parse:
:TS,000000,35.0,+20.9,0000.,000
2020-07-09T21:57:17.908Z,1594331837.908 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 16
2020-07-09T21:57:17.908Z,1594331837.908 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T21:57:17.930Z,1594331837.930 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T21:57:18.378Z,1594331838.378 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T21:57:18.378Z,1594331838.378 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 16
2020-07-09T21:59:36.894Z,1594331976.894 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-07-09T21:59:36.894Z,1594331976.894 [Default:CheckIn:C.Wait] Stopped
2020-07-09T21:59:36.894Z,1594331976.894 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-07-09T21:59:36.894Z,1594331976.894 [Default:CheckIn:D] Running Loop=1
2020-07-09T21:59:37.306Z,1594331977.306 [Default:CheckIn:D] Stopped
2020-07-09T21:59:37.307Z,1594331977.307 [Default:CheckIn:E] Running Loop=1
2020-07-09T21:59:37.696Z,1594331977.696 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 46.443575 min 
2020-07-09T21:59:37.696Z,1594331977.696 [Default:CheckIn:E] Stopped
2020-07-09T21:59:37.696Z,1594331977.696 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-07-09T21:59:37.697Z,1594331977.697 [Default:CheckIn] Stopped
2020-07-09T21:59:37.697Z,1594331977.697 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-07-09T21:59:37.697Z,1594331977.697 [Default:CheckIn](INFO): Running loop #5
2020-07-09T21:59:37.697Z,1594331977.697 [Default:CheckIn] Running Loop=5
2020-07-09T21:59:37.697Z,1594331977.697 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-09T21:59:37.697Z,1594331977.697 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-09T22:00:18.893Z,1594332018.893 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 17
2020-07-09T22:00:18.893Z,1594332018.893 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T22:00:18.903Z,1594332018.903 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T22:00:19.309Z,1594332019.309 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T22:00:19.309Z,1594332019.309 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 17
2020-07-09T22:01:35.259Z,1594332095.259 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-07-09T22:01:35.259Z,1594332095.259 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+21.1,0000.0,10
2020-07-09T22:03:19.897Z,1594332199.897 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 18
2020-07-09T22:03:19.897Z,1594332199.897 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T22:03:19.907Z,1594332199.907 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T22:03:20.334Z,1594332200.334 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T22:03:20.334Z,1594332200.334 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 18
2020-07-09T22:04:09.987Z,1594332249.987 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-07-09T22:04:09.987Z,1594332249.987 [NAL9602] Data Fault, FailCount= 1
2020-07-09T22:04:09.987Z,1594332249.987 [NAL9602](ERROR): Data Fault
2020-07-09T22:04:10.003Z,1594332250.003 [CBIT](ERROR): Data Fault in component: NAL9602
2020-07-09T22:04:10.388Z,1594332250.388 [NAL9602](INFO): Powering down
2020-07-09T22:04:11.227Z,1594332251.227 [CBIT](INFO): Clearing failed state for component NAL9602
2020-07-09T22:04:11.227Z,1594332251.227 [NAL9602] No Fault, FailCount= 1
2020-07-09T22:04:37.886Z,1594332277.886 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-07-09T21:59:37.7Z
2020-07-09T22:04:37.886Z,1594332277.886 [Default:CheckIn:Read_GPS] Stopped
2020-07-09T22:04:37.886Z,1594332277.886 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-07-09T22:04:40.706Z,1594332280.706 [NAL9602](INFO): Powering up NAL9602
2020-07-09T22:04:45.132Z,1594332285.132 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200709T210851/Courier0016.lzma
2020-07-09T22:04:46.135Z,1594332286.135 [DataOverHttps](INFO): Moved sent file to Logs/20200709T210851/Courier0016.lzma.bak
2020-07-09T22:04:46.135Z,1594332286.135 [DataOverHttps](INFO): SBD MOMSN=12431641
2020-07-09T22:04:51.625Z,1594332291.625 [NAL9602](INFO): NAL9602 initialized
2020-07-09T22:05:02.248Z,1594332302.248 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20200709T210851/Express0017.lzma
2020-07-09T22:05:03.423Z,1594332303.423 [DataOverHttps](INFO): Moved sent file to Logs/20200709T210851/Express0017.lzma.bak
2020-07-09T22:05:03.424Z,1594332303.424 [DataOverHttps](INFO): SBD MOMSN=12431643
2020-07-09T22:05:05.383Z,1594332305.383 [Default:CheckIn:Read_Iridium] Stopped
2020-07-09T22:05:05.383Z,1594332305.383 [Default:CheckIn:C.Wait] Running Loop=1
2020-07-09T22:05:05.383Z,1594332305.383 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-07-09T22:06:20.909Z,1594332380.909 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 19
2020-07-09T22:06:20.909Z,1594332380.909 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T22:06:20.940Z,1594332380.940 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T22:06:21.322Z,1594332381.322 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T22:06:21.322Z,1594332381.322 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 19
2020-07-09T22:07:08.176Z,1594332428.176 [DVL_micro](ERROR): Failed to parse:
0000000,+21.3,009.0,000
2020-07-09T22:09:05.386Z,1594332545.386 [CBIT](INFO): Clearing failed state for component DropWeight
2020-07-09T22:09:05.386Z,1594332545.386 [DropWeight] No Fault, FailCount= 1
2020-07-09T22:09:21.912Z,1594332561.912 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 20
2020-07-09T22:09:21.912Z,1594332561.912 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T22:09:21.942Z,1594332561.942 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T22:09:22.323Z,1594332562.323 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T22:09:22.323Z,1594332562.323 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 20
2020-07-09T22:10:05.978Z,1594332605.978 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-07-09T22:10:05.978Z,1594332605.978 [Default:CheckIn:C.Wait] Stopped
2020-07-09T22:10:05.978Z,1594332605.978 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-07-09T22:10:05.978Z,1594332605.978 [Default:CheckIn:D] Running Loop=1
2020-07-09T22:10:06.381Z,1594332606.381 [Default:CheckIn:D] Stopped
2020-07-09T22:10:06.381Z,1594332606.381 [Default:CheckIn:E] Running Loop=1
2020-07-09T22:10:06.768Z,1594332606.768 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 56.928153 min 
2020-07-09T22:10:06.768Z,1594332606.768 [Default:CheckIn:E] Stopped
2020-07-09T22:10:06.769Z,1594332606.769 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-07-09T22:10:06.769Z,1594332606.769 [Default:CheckIn] Stopped
2020-07-09T22:10:06.769Z,1594332606.769 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-07-09T22:10:06.769Z,1594332606.769 [Default:CheckIn](INFO): Running loop #6
2020-07-09T22:10:06.769Z,1594332606.769 [Default:CheckIn] Running Loop=6
2020-07-09T22:10:06.769Z,1594332606.769 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-09T22:10:06.769Z,1594332606.769 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-09T22:12:22.929Z,1594332742.929 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 21
2020-07-09T22:12:22.929Z,1594332742.929 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T22:12:22.940Z,1594332742.940 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T22:12:23.331Z,1594332743.331 [DVL_micro](ERROR): Failed to parse:
:SA,-03.65,+03.09,246.5
2020-07-09T22:12:23.384Z,1594332743.384 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T22:12:23.384Z,1594332743.384 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 21
2020-07-09T22:14:54.815Z,1594332894.815 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-07-09T22:14:54.815Z,1594332894.815 [NAL9602] Data Fault, FailCount= 2
2020-07-09T22:14:54.815Z,1594332894.815 [NAL9602](ERROR): Data Fault
2020-07-09T22:14:54.864Z,1594332894.864 [CBIT](ERROR): Data Fault in component: NAL9602
2020-07-09T22:14:55.205Z,1594332895.205 [NAL9602](INFO): Powering down
2020-07-09T22:14:55.609Z,1594332895.609 [NAL9602](FAULT): LCB fault:  Software Overcurrent.
2020-07-09T22:14:55.610Z,1594332895.610 [NAL9602] Hardware Fault, FailCount= 2
2020-07-09T22:14:55.610Z,1594332895.610 [NAL9602](ERROR): Hardware Fault
2020-07-09T22:14:56.040Z,1594332896.040 [CBIT](INFO): Clearing failed state for component NAL9602
2020-07-09T22:14:56.040Z,1594332896.040 [NAL9602] No Fault, FailCount= 2
2020-07-09T22:15:06.927Z,1594332906.927 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-07-09T22:10:06.8Z
2020-07-09T22:15:06.927Z,1594332906.927 [Default:CheckIn:Read_GPS] Stopped
2020-07-09T22:15:06.927Z,1594332906.927 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-07-09T22:15:13.545Z,1594332913.545 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200709T210851/Courier0019.lzma
2020-07-09T22:15:14.542Z,1594332914.542 [DataOverHttps](INFO): Moved sent file to Logs/20200709T210851/Courier0019.lzma.bak
2020-07-09T22:15:14.543Z,1594332914.543 [DataOverHttps](INFO): SBD MOMSN=12431647
2020-07-09T22:15:23.953Z,1594332923.953 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 22
2020-07-09T22:15:23.953Z,1594332923.953 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T22:15:23.963Z,1594332923.963 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T22:15:24.369Z,1594332924.369 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T22:15:24.370Z,1594332924.370 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 22
2020-07-09T22:15:25.527Z,1594332925.527 [NAL9602](INFO): Powering up NAL9602
2020-07-09T22:15:30.112Z,1594332930.112 [DataOverHttps](INFO): Sending 180 bytes from file Logs/20200709T210851/Express0020.lzma
2020-07-09T22:15:31.114Z,1594332931.114 [DataOverHttps](INFO): Moved sent file to Logs/20200709T210851/Express0020.lzma.bak
2020-07-09T22:15:31.115Z,1594332931.115 [DataOverHttps](INFO): SBD MOMSN=12431649
2020-07-09T22:15:33.226Z,1594332933.226 [Default:CheckIn:Read_Iridium] Stopped
2020-07-09T22:15:33.226Z,1594332933.226 [Default:CheckIn:C.Wait] Running Loop=1
2020-07-09T22:15:33.226Z,1594332933.226 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-07-09T22:15:36.431Z,1594332936.431 [NAL9602](INFO): NAL9602 initialized
2020-07-09T22:17:21.084Z,1594333041.084 [DVL_micro](ERROR): only read 0 of 4 data items
2020-07-09T22:17:21.084Z,1594333041.084 [DVL_micro](ERROR): Failed to parse:
:RD9,+9999.99,+9999.99,+9999.99
2020-07-09T22:18:24.981Z,1594333104.981 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 23
2020-07-09T22:18:24.982Z,1594333104.982 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T22:18:24.996Z,1594333104.996 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T22:18:25.395Z,1594333105.395 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T22:18:25.395Z,1594333105.395 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 23
2020-07-09T22:19:34.819Z,1594333174.819 [DVL_micro](ERROR): only read 3 of 4 data items
2020-07-09T22:19:34.819Z,1594333174.819 [DVL_micro](ERROR): Failed to parse:
:BI,+00056,-00016,00000,I
2020-07-09T22:20:33.882Z,1594333233.882 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-07-09T22:20:33.882Z,1594333233.882 [Default:CheckIn:C.Wait] Stopped
2020-07-09T22:20:33.883Z,1594333233.883 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-07-09T22:20:33.883Z,1594333233.883 [Default:CheckIn:D] Running Loop=1
2020-07-09T22:20:34.226Z,1594333234.226 [Default:CheckIn:D] Stopped
2020-07-09T22:20:34.226Z,1594333234.226 [Default:CheckIn:E] Running Loop=1
2020-07-09T22:20:34.647Z,1594333234.647 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 67.392228 min 
2020-07-09T22:20:34.647Z,1594333234.647 [Default:CheckIn:E] Stopped
2020-07-09T22:20:34.647Z,1594333234.647 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-07-09T22:20:34.647Z,1594333234.647 [Default:CheckIn] Stopped
2020-07-09T22:20:34.647Z,1594333234.647 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-07-09T22:20:34.647Z,1594333234.647 [Default:CheckIn](INFO): Running loop #7
2020-07-09T22:20:34.647Z,1594333234.647 [Default:CheckIn] Running Loop=7
2020-07-09T22:20:34.647Z,1594333234.647 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-09T22:20:34.647Z,1594333234.647 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-09T22:21:25.969Z,1594333285.969 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 24
2020-07-09T22:21:25.969Z,1594333285.969 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T22:21:25.979Z,1594333285.979 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T22:21:26.351Z,1594333286.351 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T22:21:26.351Z,1594333286.351 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 24
2020-07-09T22:24:23.297Z,1594333463.297 [BPC1](ERROR): Battery stick #39 (s/n: 00F0) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x4AD0.
2020-07-09T22:24:23.298Z,1594333463.298 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5.
2020-07-09T22:24:23.300Z,1594333463.300 [BPC1](INFO): Received data from all battery sticks.
2020-07-09T22:24:26.926Z,1594333466.926 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 25
2020-07-09T22:24:26.926Z,1594333466.926 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T22:24:26.947Z,1594333466.947 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T22:24:27.365Z,1594333467.365 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T22:24:27.366Z,1594333467.366 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 25
2020-07-09T22:25:34.792Z,1594333534.792 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-07-09T22:20:34.6Z
2020-07-09T22:25:34.792Z,1594333534.792 [Default:CheckIn:Read_GPS] Stopped
2020-07-09T22:25:34.792Z,1594333534.792 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-07-09T22:25:38.823Z,1594333538.823 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-07-09T22:25:38.823Z,1594333538.823 [NAL9602] Data Fault, FailCount= 3
2020-07-09T22:25:38.823Z,1594333538.823 [NAL9602](ERROR): Data Fault
2020-07-09T22:25:38.860Z,1594333538.860 [CBIT](ERROR): Data Fault in component: NAL9602
2020-07-09T22:25:39.227Z,1594333539.227 [NAL9602](INFO): Powering down
2020-07-09T22:25:40.118Z,1594333540.118 [CBIT](INFO): Clearing failed state for component NAL9602
2020-07-09T22:25:40.118Z,1594333540.118 [NAL9602] No Fault, FailCount= 3
2020-07-09T22:25:42.328Z,1594333542.328 [DataOverHttps](INFO): Sending 50 bytes from file Logs/20200709T210851/Courier0022.lzma
2020-07-09T22:25:43.331Z,1594333543.331 [DataOverHttps](INFO): Moved sent file to Logs/20200709T210851/Courier0022.lzma.bak
2020-07-09T22:25:43.331Z,1594333543.331 [DataOverHttps](INFO): SBD MOMSN=12431655
2020-07-09T22:25:58.768Z,1594333558.768 [DataOverHttps](INFO): Sending 108 bytes from file Logs/20200709T210851/Express0023.lzma
2020-07-09T22:25:59.771Z,1594333559.771 [DataOverHttps](INFO): Moved sent file to Logs/20200709T210851/Express0023.lzma.bak
2020-07-09T22:25:59.771Z,1594333559.771 [DataOverHttps](INFO): SBD MOMSN=12431657
2020-07-09T22:26:01.866Z,1594333561.866 [Default:CheckIn:Read_Iridium] Stopped
2020-07-09T22:26:01.866Z,1594333561.866 [Default:CheckIn:C.Wait] Running Loop=1
2020-07-09T22:26:01.866Z,1594333561.866 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-07-09T22:26:09.536Z,1594333569.536 [NAL9602](INFO): Powering up NAL9602
2020-07-09T22:26:20.436Z,1594333580.436 [NAL9602](INFO): NAL9602 initialized
2020-07-09T22:27:01.653Z,1594333621.653 [DVL_micro](ERROR): Failed to parse:
:WI,-00020,-00017,-00261,+00000,A
2020-07-09T22:27:27.927Z,1594333647.927 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 26
2020-07-09T22:27:27.927Z,1594333647.927 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T22:27:27.937Z,1594333647.937 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T22:27:28.336Z,1594333648.336 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T22:27:28.336Z,1594333648.336 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 26
2020-07-09T22:28:17.221Z,1594333697.221 [CommandLine](IMPORTANT): got command failComponent
2020-07-09T22:28:17.221Z,1594333697.221 [CommandLine](IMPORTANT): Failed components:
2020-07-09T22:28:17.221Z,1594333697.221 [CommandLine](IMPORTANT): No failed Components.
2020-07-09T22:28:43.214Z,1594333723.214 [CommandLine](IMPORTANT): got command configSet list
2020-07-09T22:28:43.214Z,1594333723.214 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-07-09T22:28:43.216Z,1594333723.216 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2020-07-09T22:28:43.216Z,1594333723.216 [CommandLine](IMPORTANT): BuoyancyServo.checkingTimeout=30 second;

2020-07-09T22:28:43.216Z,1594333723.216 [CommandLine](IMPORTANT): CBIT.gf24Offset=148.1 microampere;

2020-07-09T22:28:52.161Z,1594333732.161 [CommandLine](IMPORTANT): got command failComponent
2020-07-09T22:28:52.161Z,1594333732.161 [CommandLine](IMPORTANT): Failed components:
2020-07-09T22:28:52.161Z,1594333732.161 [CommandLine](IMPORTANT): No failed Components.
2020-07-09T22:30:28.919Z,1594333828.919 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 27
2020-07-09T22:30:28.919Z,1594333828.919 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T22:30:28.929Z,1594333828.929 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T22:30:29.334Z,1594333829.334 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T22:30:29.334Z,1594333829.334 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 27
2020-07-09T22:31:02.451Z,1594333862.451 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-07-09T22:31:02.451Z,1594333862.451 [Default:CheckIn:C.Wait] Stopped
2020-07-09T22:31:02.451Z,1594333862.451 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-07-09T22:31:02.451Z,1594333862.451 [Default:CheckIn:D] Running Loop=1
2020-07-09T22:31:02.878Z,1594333862.878 [Default:CheckIn:D] Stopped
2020-07-09T22:31:02.879Z,1594333862.879 [Default:CheckIn:E] Running Loop=1
2020-07-09T22:31:03.314Z,1594333863.314 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 77.869775 min 
2020-07-09T22:31:03.314Z,1594333863.314 [Default:CheckIn:E] Stopped
2020-07-09T22:31:03.314Z,1594333863.314 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-07-09T22:31:03.315Z,1594333863.315 [Default:CheckIn] Stopped
2020-07-09T22:31:03.315Z,1594333863.315 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-07-09T22:31:03.315Z,1594333863.315 [Default:CheckIn](INFO): Running loop #8
2020-07-09T22:31:03.315Z,1594333863.315 [Default:CheckIn] Running Loop=8
2020-07-09T22:31:03.315Z,1594333863.315 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-09T22:31:03.315Z,1594333863.315 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-09T22:32:14.369Z,1594333934.369 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-07-09T22:32:14.370Z,1594333934.370 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+21.9,489.0,000
2020-07-09T22:32:16.791Z,1594333936.791 [DVL_micro](ERROR): Failed to parse:
:B,-00068,-00257,+00000,I
2020-07-09T22:32:19.610Z,1594333939.610 [DVL_micro](ERROR): No DVL communication! Re-initializing
2020-07-09T22:32:19.610Z,1594333939.610 [DVL_micro] Communications Fault, FailCount= 1
2020-07-09T22:32:19.610Z,1594333939.610 [DVL_micro](ERROR): Communications Fault
2020-07-09T22:32:19.652Z,1594333939.652 [CBIT](ERROR): Communications Fault in component: DVL_micro
2020-07-09T22:32:20.081Z,1594333940.081 [DVL_micro](INFO): Powering down
2020-07-09T22:32:20.852Z,1594333940.852 [CBIT](INFO): Clearing failed state for component DVL_micro
2020-07-09T22:32:20.852Z,1594333940.852 [DVL_micro] No Fault, FailCount= 1
2020-07-09T22:33:29.953Z,1594334009.953 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 28
2020-07-09T22:33:29.953Z,1594334009.953 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T22:33:29.964Z,1594334009.964 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T22:33:30.357Z,1594334010.357 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T22:33:30.357Z,1594334010.357 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 28
2020-07-09T22:34:07.095Z,1594334047.095 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-07-09T22:34:07.095Z,1594334047.095 [DVL_micro](ERROR): Failed to parse:
:TS0000000,35.0,+22.0,0000.0,1489.0,000
2020-07-09T22:36:03.467Z,1594334163.467 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-07-09T22:31:03.3Z
2020-07-09T22:36:03.467Z,1594334163.467 [Default:CheckIn:Read_GPS] Stopped
2020-07-09T22:36:03.467Z,1594334163.467 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-07-09T22:36:10.812Z,1594334170.812 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200709T210851/Courier0025.lzma
2020-07-09T22:36:11.815Z,1594334171.815 [DataOverHttps](INFO): Moved sent file to Logs/20200709T210851/Courier0025.lzma.bak
2020-07-09T22:36:11.815Z,1594334171.815 [DataOverHttps](INFO): SBD MOMSN=12431660
2020-07-09T22:36:24.047Z,1594334184.047 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-07-09T22:36:24.047Z,1594334184.047 [NAL9602] Data Fault, FailCount= 4
2020-07-09T22:36:24.047Z,1594334184.047 [NAL9602](ERROR): Data Fault
2020-07-09T22:36:24.104Z,1594334184.104 [CBIT](ERROR): Data Fault in component: NAL9602
2020-07-09T22:36:24.455Z,1594334184.455 [NAL9602](INFO): Powering down
2020-07-09T22:36:25.305Z,1594334185.305 [CBIT](INFO): Clearing failed state for component NAL9602
2020-07-09T22:36:25.305Z,1594334185.305 [NAL9602] No Fault, FailCount= 4
2020-07-09T22:36:27.347Z,1594334187.347 [DataOverHttps](INFO): Sending 378 bytes from file Logs/20200709T210851/Express0026.lzma
2020-07-09T22:36:28.346Z,1594334188.346 [DataOverHttps](INFO): Moved sent file to Logs/20200709T210851/Express0026.lzma.bak
2020-07-09T22:36:28.347Z,1594334188.347 [DataOverHttps](INFO): SBD MOMSN=12431662
2020-07-09T22:36:30.118Z,1594334190.118 [Default:CheckIn:Read_Iridium] Stopped
2020-07-09T22:36:30.118Z,1594334190.118 [Default:CheckIn:C.Wait] Running Loop=1
2020-07-09T22:36:30.118Z,1594334190.118 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-07-09T22:36:30.949Z,1594334190.949 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 29
2020-07-09T22:36:30.949Z,1594334190.949 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T22:36:30.959Z,1594334190.959 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T22:36:31.350Z,1594334191.350 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T22:36:31.350Z,1594334191.350 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 29
2020-07-09T22:36:54.752Z,1594334214.752 [NAL9602](INFO): Powering up NAL9602
2020-07-09T22:37:05.653Z,1594334225.653 [NAL9602](INFO): NAL9602 initialized
2020-07-09T22:39:06.863Z,1594334346.863 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-07-09T22:39:06.863Z,1594334346.863 [DropWeight] Hardware Fault, FailCount= 1
2020-07-09T22:39:06.863Z,1594334346.863 [DropWeight](ERROR): Hardware Fault
2020-07-09T22:39:06.966Z,1594334346.966 [CBIT](INFO): Critical error at 20200709T223906
2020-07-09T22:39:06.968Z,1594334346.968 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-07-09T22:39:06.968Z,1594334346.968 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-07-09T22:39:07.298Z,1594334347.298 [CBIT](INFO): Critical error at 20200709T223906
2020-07-09T22:39:31.941Z,1594334371.941 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 30
2020-07-09T22:39:31.942Z,1594334371.942 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T22:39:31.952Z,1594334371.952 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T22:39:32.324Z,1594334372.324 [DVL_micro](ERROR): only read 1 of 4 data items
2020-07-09T22:39:32.324Z,1594334372.324 [DVL_micro](ERROR): Failed to parse:
:RD,+99999.99,+
2020-07-09T22:39:32.364Z,1594334372.364 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T22:39:32.364Z,1594334372.364 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 30
2020-07-09T22:41:30.722Z,1594334490.722 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-07-09T22:41:30.722Z,1594334490.722 [Default:CheckIn:C.Wait] Stopped
2020-07-09T22:41:30.723Z,1594334490.723 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-07-09T22:41:30.723Z,1594334490.723 [Default:CheckIn:D] Running Loop=1
2020-07-09T22:41:31.122Z,1594334491.122 [Default:CheckIn:D] Stopped
2020-07-09T22:41:31.123Z,1594334491.123 [Default:CheckIn:E] Running Loop=1
2020-07-09T22:41:31.503Z,1594334491.503 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 88.340511 min 
2020-07-09T22:41:31.503Z,1594334491.503 [Default:CheckIn:E] Stopped
2020-07-09T22:41:31.504Z,1594334491.504 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-07-09T22:41:31.504Z,1594334491.504 [Default:CheckIn] Stopped
2020-07-09T22:41:31.504Z,1594334491.504 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-07-09T22:41:31.504Z,1594334491.504 [Default:CheckIn](INFO): Running loop #9
2020-07-09T22:41:31.504Z,1594334491.504 [Default:CheckIn] Running Loop=9
2020-07-09T22:41:31.504Z,1594334491.504 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-09T22:41:31.504Z,1594334491.504 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-09T22:42:32.937Z,1594334552.937 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 31
2020-07-09T22:42:32.937Z,1594334552.937 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T22:42:32.952Z,1594334552.952 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T22:42:33.326Z,1594334553.326 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T22:42:33.326Z,1594334553.326 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 31
2020-07-09T22:44:42.616Z,1594334682.616 [DVL_micro](ERROR): only read 3 of 4 data items
2020-07-09T22:44:42.616Z,1594334682.616 [DVL_micro](ERROR): Failed to parse:
:RD,+9999.99,+9999.99,+9999..99
2020-07-09T22:45:33.949Z,1594334733.949 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 32
2020-07-09T22:45:33.949Z,1594334733.949 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T22:45:33.959Z,1594334733.959 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T22:45:34.342Z,1594334734.342 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T22:45:34.342Z,1594334734.342 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 32
2020-07-09T22:46:31.702Z,1594334791.702 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-07-09T22:41:31.5Z
2020-07-09T22:46:31.702Z,1594334791.702 [Default:CheckIn:Read_GPS] Stopped
2020-07-09T22:46:31.702Z,1594334791.702 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-07-09T22:46:39.300Z,1594334799.300 [DataOverHttps](INFO): Sending 115 bytes from file Logs/20200709T210851/Courier0028.lzma
2020-07-09T22:46:40.303Z,1594334800.303 [DataOverHttps](INFO): Moved sent file to Logs/20200709T210851/Courier0028.lzma.bak
2020-07-09T22:46:40.303Z,1594334800.303 [DataOverHttps](INFO): SBD MOMSN=12431677
2020-07-09T22:46:55.880Z,1594334815.880 [DataOverHttps](INFO): Sending 150 bytes from file Logs/20200709T210851/Express0029.lzma
2020-07-09T22:46:56.883Z,1594334816.883 [DataOverHttps](INFO): Moved sent file to Logs/20200709T210851/Express0029.lzma.bak
2020-07-09T22:46:56.883Z,1594334816.883 [DataOverHttps](INFO): SBD MOMSN=12431681
2020-07-09T22:46:58.808Z,1594334818.808 [Default:CheckIn:Read_Iridium] Stopped
2020-07-09T22:46:58.808Z,1594334818.808 [Default:CheckIn:C.Wait] Running Loop=1
2020-07-09T22:46:58.809Z,1594334818.809 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-07-09T22:47:09.263Z,1594334829.263 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-07-09T22:47:09.264Z,1594334829.264 [NAL9602] Data Fault, FailCount= 5
2020-07-09T22:47:09.264Z,1594334829.264 [NAL9602](ERROR): Data Fault
2020-07-09T22:47:09.281Z,1594334829.281 [CBIT](ERROR): Data Fault in component: NAL9602
2020-07-09T22:47:09.282Z,1594334829.282 [CBIT](CRITICAL): Data Fault in component: NAL9602
2020-07-09T22:47:09.670Z,1594334829.670 [NAL9602](INFO): Powering down
2020-07-09T22:47:09.686Z,1594334829.686 [CBIT](INFO): Critical error at 20200709T224709
2020-07-09T22:48:34.941Z,1594334914.941 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 33
2020-07-09T22:48:34.941Z,1594334914.941 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T22:48:34.981Z,1594334914.981 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T22:48:35.340Z,1594334915.340 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T22:48:35.340Z,1594334915.340 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 33
2020-07-09T22:49:09.281Z,1594334949.281 [CBIT](INFO): Clearing failed state for component NAL9602
2020-07-09T22:49:09.281Z,1594334949.281 [NAL9602] No Fault, FailCount= 5
2020-07-09T22:49:09.654Z,1594334949.654 [NAL9602](INFO): Powering up NAL9602
2020-07-09T22:49:20.564Z,1594334960.564 [NAL9602](INFO): NAL9602 initialized
2020-07-09T22:50:07.839Z,1594335007.839 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-07-09T22:50:07.839Z,1594335007.839 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,.3,0000.0,1489.0,000
2020-07-09T22:51:35.953Z,1594335095.953 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 34
2020-07-09T22:51:35.953Z,1594335095.953 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T22:51:35.967Z,1594335095.967 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T22:51:36.365Z,1594335096.365 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T22:51:36.365Z,1594335096.365 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 34
2020-07-09T22:51:59.368Z,1594335119.368 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-07-09T22:51:59.368Z,1594335119.368 [Default:CheckIn:C.Wait] Stopped
2020-07-09T22:51:59.369Z,1594335119.369 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-07-09T22:51:59.369Z,1594335119.369 [Default:CheckIn:D] Running Loop=1
2020-07-09T22:51:59.778Z,1594335119.778 [Default:CheckIn:D] Stopped
2020-07-09T22:51:59.779Z,1594335119.779 [Default:CheckIn:E] Running Loop=1
2020-07-09T22:52:00.159Z,1594335120.159 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 98.818107 min 
2020-07-09T22:52:00.159Z,1594335120.159 [Default:CheckIn:E] Stopped
2020-07-09T22:52:00.159Z,1594335120.159 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-07-09T22:52:00.159Z,1594335120.159 [Default:CheckIn] Stopped
2020-07-09T22:52:00.160Z,1594335120.160 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-07-09T22:52:00.160Z,1594335120.160 [Default:CheckIn](INFO): Running loop #10
2020-07-09T22:52:00.160Z,1594335120.160 [Default:CheckIn] Running Loop=10
2020-07-09T22:52:00.160Z,1594335120.160 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-09T22:52:00.160Z,1594335120.160 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-09T22:54:07.064Z,1594335247.064 [CBIT](INFO): Clearing failed state for component DropWeight
2020-07-09T22:54:07.064Z,1594335247.064 [DropWeight] No Fault, FailCount= 1
2020-07-09T22:54:36.926Z,1594335276.926 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 35
2020-07-09T22:54:36.926Z,1594335276.926 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T22:54:36.969Z,1594335276.969 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T22:54:37.385Z,1594335277.385 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T22:54:37.385Z,1594335277.385 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 35
2020-07-09T22:55:43.179Z,1594335343.179 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-07-09T22:55:43.179Z,1594335343.179 [DVL_micro](ERROR): Failed to parse:
:TS,000000,35.0000.0,1489:WI,+0077,-00127,+00000,A
2020-07-09T22:57:00.351Z,1594335420.351 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-07-09T22:52:00.2Z
2020-07-09T22:57:00.352Z,1594335420.352 [Default:CheckIn:Read_GPS] Stopped
2020-07-09T22:57:00.352Z,1594335420.352 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-07-09T22:57:07.572Z,1594335427.572 [DataOverHttps](INFO): Sending 81 bytes from file Logs/20200709T210851/Courier0031.lzma
2020-07-09T22:57:08.575Z,1594335428.575 [DataOverHttps](INFO): Moved sent file to Logs/20200709T210851/Courier0031.lzma.bak
2020-07-09T22:57:08.575Z,1594335428.575 [DataOverHttps](INFO): SBD MOMSN=12431696
2020-07-09T22:57:23.788Z,1594335443.788 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2020-07-09T22:57:23.789Z,1594335443.789 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+22.5,0000.0,148

2020-07-09T22:57:24.060Z,1594335444.060 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20200709T210851/Express0032.lzma
2020-07-09T22:57:25.063Z,1594335445.063 [DataOverHttps](INFO): Moved sent file to Logs/20200709T210851/Express0032.lzma.bak
2020-07-09T22:57:25.063Z,1594335445.063 [DataOverHttps](INFO): SBD MOMSN=12431699
2020-07-09T22:57:27.035Z,1594335447.035 [Default:CheckIn:Read_Iridium] Stopped
2020-07-09T22:57:27.035Z,1594335447.035 [Default:CheckIn:C.Wait] Running Loop=1
2020-07-09T22:57:27.035Z,1594335447.035 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-07-09T22:57:37.922Z,1594335457.922 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 36
2020-07-09T22:57:37.922Z,1594335457.922 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T22:57:37.933Z,1594335457.933 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T22:57:38.340Z,1594335458.340 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T22:57:38.340Z,1594335458.340 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 36
2020-07-09T22:59:21.744Z,1594335561.744 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-07-09T22:59:21.744Z,1594335561.744 [NAL9602] Data Fault, FailCount= 1
2020-07-09T22:59:21.744Z,1594335561.744 [NAL9602](ERROR): Data Fault
2020-07-09T22:59:21.785Z,1594335561.785 [CBIT](ERROR): Data Fault in component: NAL9602
2020-07-09T22:59:22.160Z,1594335562.160 [NAL9602](INFO): Powering down
2020-07-09T22:59:22.981Z,1594335562.981 [CBIT](INFO): Clearing failed state for component NAL9602
2020-07-09T22:59:22.981Z,1594335562.981 [NAL9602] No Fault, FailCount= 1
2020-07-09T22:59:52.453Z,1594335592.453 [NAL9602](INFO): Powering up NAL9602
2020-07-09T23:00:03.379Z,1594335603.379 [NAL9602](INFO): NAL9602 initialized
2020-07-09T23:00:38.933Z,1594335638.933 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 37
2020-07-09T23:00:38.933Z,1594335638.933 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T23:00:38.944Z,1594335638.944 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T23:00:39.358Z,1594335639.358 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T23:00:39.358Z,1594335639.358 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 37
2020-07-09T23:02:27.614Z,1594335747.614 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-07-09T23:02:27.615Z,1594335747.615 [Default:CheckIn:C.Wait] Stopped
2020-07-09T23:02:27.615Z,1594335747.615 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-07-09T23:02:27.615Z,1594335747.615 [Default:CheckIn:D] Running Loop=1
2020-07-09T23:02:28.022Z,1594335748.022 [Default:CheckIn:D] Stopped
2020-07-09T23:02:28.023Z,1594335748.023 [Default:CheckIn:E] Running Loop=1
2020-07-09T23:02:28.394Z,1594335748.394 [DVL_micro](ERROR): only read 2 of 4 data items
2020-07-09T23:02:28.394Z,1594335748.394 [DVL_micro](ERROR): Failed to parse:
:BI,+0044,-00092I
2020-07-09T23:02:28.416Z,1594335748.416 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 109.288843 min 
2020-07-09T23:02:28.416Z,1594335748.416 [Default:CheckIn:E] Stopped
2020-07-09T23:02:28.417Z,1594335748.417 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-07-09T23:02:28.417Z,1594335748.417 [Default:CheckIn] Stopped
2020-07-09T23:02:28.417Z,1594335748.417 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-07-09T23:02:28.417Z,1594335748.417 [Default:CheckIn](INFO): Running loop #11
2020-07-09T23:02:28.417Z,1594335748.417 [Default:CheckIn] Running Loop=11
2020-07-09T23:02:28.417Z,1594335748.417 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-09T23:02:28.418Z,1594335748.418 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-09T23:03:39.908Z,1594335819.908 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 38
2020-07-09T23:03:39.909Z,1594335819.909 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T23:03:39.919Z,1594335819.919 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T23:03:40.449Z,1594335820.449 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T23:03:40.449Z,1594335820.449 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 38
2020-07-09T23:06:40.917Z,1594336000.917 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 39
2020-07-09T23:06:40.917Z,1594336000.917 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T23:06:40.951Z,1594336000.951 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T23:06:41.330Z,1594336001.330 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T23:06:41.330Z,1594336001.330 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 39
2020-07-09T23:07:28.578Z,1594336048.578 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-07-09T23:02:28.4Z
2020-07-09T23:07:28.578Z,1594336048.578 [Default:CheckIn:Read_GPS] Stopped
2020-07-09T23:07:28.579Z,1594336048.579 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-07-09T23:07:35.732Z,1594336055.732 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200709T210851/Courier0034.lzma
2020-07-09T23:07:36.735Z,1594336056.735 [DataOverHttps](INFO): Moved sent file to Logs/20200709T210851/Courier0034.lzma.bak
2020-07-09T23:07:36.735Z,1594336056.735 [DataOverHttps](INFO): SBD MOMSN=12431735
2020-07-09T23:07:54.308Z,1594336074.308 [DataOverHttps](INFO): Sending 151 bytes from file Logs/20200709T210851/Express0035.lzma
2020-07-09T23:07:55.311Z,1594336075.311 [DataOverHttps](INFO): Moved sent file to Logs/20200709T210851/Express0035.lzma.bak
2020-07-09T23:07:55.311Z,1594336075.311 [DataOverHttps](INFO): SBD MOMSN=12431737
2020-07-09T23:07:57.282Z,1594336077.282 [Default:CheckIn:Read_Iridium] Stopped
2020-07-09T23:07:57.282Z,1594336077.282 [Default:CheckIn:C.Wait] Running Loop=1
2020-07-09T23:07:57.282Z,1594336077.282 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-07-09T23:09:08.772Z,1594336148.772 [DVL_micro](ERROR): only read 0 of 4 data items
2020-07-09T23:09:08.772Z,1594336148.772 [DVL_micro](ERROR): Failed to parse:
:RD,,+9999.999,+9999.99
2020-07-09T23:09:13.624Z,1594336153.624 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.0000.00,+00000000.00,9999.99,000.00
2020-07-09T23:09:41.913Z,1594336181.913 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 40
2020-07-09T23:09:41.913Z,1594336181.913 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-09T23:09:41.923Z,1594336181.923 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-09T23:09:42.314Z,1594336182.314 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-09T23:09:42.314Z,1594336182.314 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 40
2020-07-09T23:10:04.923Z,1594336204.923 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-07-09T23:10:04.923Z,1594336204.923 [NAL9602] Data Fault, FailCount= 2
2020-07-09T23:10:04.923Z,1594336204.923 [NAL9602](ERROR): Data Fault
2020-07-09T23:10:04.944Z,1594336204.944 [CBIT](ERROR): Data Fault in component: NAL9602
2020-07-09T23:10:05.333Z,1594336205.333 [NAL9602](INFO): Powering down
2020-07-09T23:10:06.152Z,1594336206.152 [CBIT](INFO): Clearing failed state for component NAL9602
2020-07-09T23:10:06.152Z,1594336206.152 [NAL9602] No Fault, FailCount= 2
2020-07-09T23:10:35.623Z,1594336235.623 [NAL9602](INFO): Powering up NAL9602
2020-07-09T23:10:46.536Z,1594336246.536 [NAL9602](INFO): NAL9602 initialized
2020-07-09T23:11:12.022Z,1594336272.022 [CommandLine](IMPORTANT): got command quit
2020-07-09T23:11:13.029Z,1594336273.029 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-07-09T23:11:13.029Z,1594336273.029 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-07-09T23:11:13.121Z,1594336273.121 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-07-09T23:11:13.121Z,1594336273.121 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-07-09T23:11:13.122Z,1594336273.122 [CommandLine](INFO): Join timeout helper Thread ID is 4100
2020-07-09T23:11:13.122Z,1594336273.122 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-07-09T23:11:13.122Z,1594336273.122 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-07-09T23:11:13.123Z,1594336273.123 [NavChartDb](INFO): Join timeout helper Thread ID is 4101
2020-07-09T23:11:13.333Z,1594336273.333 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-07-09T23:11:13.333Z,1594336273.333 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-07-09T23:11:13.341Z,1594336273.341 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-07-09T23:11:13.341Z,1594336273.341 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-07-09T23:11:13.341Z,1594336273.341 [Radio_Surface](INFO): Join timeout helper Thread ID is 4102
2020-07-09T23:11:13.361Z,1594336273.361 [Radio_Surface](INFO): Powering down
2020-07-09T23:11:13.362Z,1594336273.362 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-07-09T23:11:13.362Z,1594336273.362 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-07-09T23:11:13.381Z,1594336273.381 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2020-07-09T23:11:13.381Z,1594336273.381 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-07-09T23:11:13.381Z,1594336273.381 [Onboard](INFO): Join timeout helper Thread ID is 4103
2020-07-09T23:11:13.649Z,1594336273.649 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2020-07-09T23:11:13.649Z,1594336273.649 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-07-09T23:11:13.661Z,1594336273.661 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-07-09T23:11:13.661Z,1594336273.661 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-07-09T23:11:13.661Z,1594336273.661 [DataOverHttps](INFO): Join timeout helper Thread ID is 4104
2020-07-09T23:11:14.028Z,1594336274.028 [CBIT](FAULT): Humidity exceeds 50% of running average: Humidity:2285304 %. Average:267127.593750
2020-07-09T23:11:14.361Z,1594336274.361 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-07-09T23:11:14.361Z,1594336274.361 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-07-09T23:11:14.382Z,1594336274.382 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-07-09T23:11:14.382Z,1594336274.382 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-07-09T23:11:14.382Z,1594336274.382 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4105
2020-07-09T23:11:14.685Z,1594336274.685 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-07-09T23:11:14.686Z,1594336274.686 [WetLabsBB2FL](INFO): Powering down
2020-07-09T23:11:14.686Z,1594336274.686 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-07-09T23:11:14.710Z,1594336274.710 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-07-09T23:11:14.710Z,1594336274.710 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-07-09T23:11:14.710Z,1594336274.710 [CTD_Seabird](INFO): Join timeout helper Thread ID is 4106
2020-07-09T23:11:14.953Z,1594336274.953 [CTD_Seabird](INFO): Powering down
2020-07-09T23:11:14.968Z,1594336274.968 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-07-09T23:11:14.968Z,1594336274.968 [CTD_Seabird](INFO): Powering down
2020-07-09T23:11:14.981Z,1594336274.981 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-07-09T23:11:14.989Z,1594336274.989 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-07-09T23:11:14.990Z,1594336274.990 [logger ThreadHandler](INFO): Thread cancelled.
2020-07-09T23:11:14.990Z,1594336274.990 [logger](INFO): Join timeout helper Thread ID is 4107
2020-07-09T23:11:15.017Z,1594336275.017 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-07-09T23:11:15.017Z,1594336275.017 [logger ThreadHandler](INFO): Thread cancelled.
2020-07-09T23:11:15.029Z,1594336275.029 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-07-09T23:11:15.030Z,1594336275.030 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-07-09T23:11:15.030Z,1594336275.030 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-07-09T23:11:15.030Z,1594336275.030 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-07-09T23:11:15.030Z,1594336275.030 [controlThread](INFO): Join timeout helper Thread ID is 4108
2020-07-09T23:11:15.213Z,1594336275.213 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-07-09T23:11:15.213Z,1594336275.213 [controlThread](DEBUG): Uninitializing ControlThread
2020-07-09T23:11:15.214Z,1594336275.214 [Aanderaa_O2](INFO): Powering down
2020-07-09T23:11:15.216Z,1594336275.216 [AHRS_M2](INFO): Powering down
2020-07-09T23:11:15.357Z,1594336275.357 [DVL_micro](INFO): Powering down
2020-07-09T23:11:15.358Z,1594336275.358 [NAL9602](INFO): Powering down
2020-07-09T23:11:15.359Z,1594336275.359 [DAT](INFO): Powering down
2020-07-09T23:11:15.478Z,1594336275.478 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-07-09T23:11:15.479Z,1594336275.479 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-07-09T23:11:15.479Z,1594336275.479 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-07-09T23:11:15.479Z,1594336275.479 [MissionManager](INFO): Uninitializing Mission Default
2020-07-09T23:11:15.480Z,1594336275.480 [Default] Stopped
2020-07-09T23:11:15.480Z,1594336275.480 [Default](DEBUG): Aggregate::uninitialize Default
2020-07-09T23:11:15.480Z,1594336275.480 [Default:B.GoToSurface] Stopped
2020-07-09T23:11:15.480Z,1594336275.480 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-07-09T23:11:15.480Z,1594336275.480 [Default:CheckIn] Stopped
2020-07-09T23:11:15.480Z,1594336275.480 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-07-09T23:11:15.480Z,1594336275.480 [Default:CheckIn:C.Wait] Stopped
2020-07-09T23:11:15.480Z,1594336275.480 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-07-09T23:11:15.482Z,1594336275.482 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-07-09T23:11:15.483Z,1594336275.483 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-07-09T23:11:15.483Z,1594336275.483 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-07-09T23:11:15.483Z,1594336275.483 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-07-09T23:11:15.484Z,1594336275.484 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-07-09T23:11:15.484Z,1594336275.484 [BuoyancyServo](INFO): Powering down
2020-07-09T23:11:15.497Z,1594336275.497 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-07-09T23:11:15.497Z,1594336275.497 [ElevatorServo](INFO): Powering down
2020-07-09T23:11:15.498Z,1594336275.498 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-07-09T23:11:15.498Z,1594336275.498 [MassServo](INFO): Powering down
2020-07-09T23:11:15.499Z,1594336275.499 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-07-09T23:11:15.499Z,1594336275.499 [RudderServo](INFO): Powering down
2020-07-09T23:11:15.500Z,1594336275.500 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-07-09T23:11:15.500Z,1594336275.500 [ThrusterServo](INFO): Powering down
2020-07-09T23:11:15.501Z,1594336275.501 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-07-09T23:11:15.501Z,1594336275.501 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-07-09T23:11:15.501Z,1594336275.501 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-07-09T23:11:15.501Z,1594336275.501 [CBIT](DEBUG): Powering off loads.
2020-07-09T23:11:15.513Z,1594336275.513 [CBIT](DEBUG): Disabling WDT.
2020-07-09T23:11:15.525Z,1594336275.525 [CBIT](DEBUG): Opening all GF detection circuits.
2020-07-09T23:11:15.526Z,1594336275.526 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-07-09T23:11:15.559Z,1594336275.559 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-07-09T23:11:15.566Z,1594336275.566 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-07-09T23:11:15.638Z,1594336275.638 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-07-09T23:11:15.639Z,1594336275.639 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-07-09T23:11:15.646Z,1594336275.646 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-07-09T23:11:15.701Z,1594336275.701 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-07-09T23:11:15.763Z,1594336275.763 [logger ThreadHandler](INFO): Thread cancelled.