2019-10-01T14:28:23.285Z,1569940103.285 [Supervisor](DEBUG): Initializing supervisor.
2019-10-01T14:28:23.288Z,1569940103.288 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-10-01T14:28:23.288Z,1569940103.288 [SyncHandler](INFO): Protected caller Thread ID is 806
2019-10-01T14:28:23.289Z,1569940103.289 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-10-01T14:28:23.290Z,1569940103.290 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-10-01T14:28:23.290Z,1569940103.290 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 807
2019-10-01T14:28:23.292Z,1569940103.292 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-10-01T14:28:23.303Z,1569940103.303 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-10-01T14:28:23.304Z,1569940103.304 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-10-01T14:28:23.305Z,1569940103.305 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 808
2019-10-01T14:28:23.305Z,1569940103.305 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-10-01T14:28:23.306Z,1569940103.306 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-10-01T14:28:23.307Z,1569940103.307 [logger ThreadHandler](INFO): Protected caller Thread ID is 809
2019-10-01T14:28:23.309Z,1569940103.309 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-10-01T14:28:23.309Z,1569940103.309 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-10-01T14:28:23.313Z,1569940103.313 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-10-01T14:28:23.409Z,1569940103.409 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-10-01T14:28:23.410Z,1569940103.410 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-10-01T14:28:23.611Z,1569940103.611 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-10-01T14:28:23.613Z,1569940103.613 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-10-01T14:28:23.746Z,1569940103.746 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-10-01T14:28:23.748Z,1569940103.748 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-10-01T14:28:24.306Z,1569940104.306 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-10-01T14:28:24.306Z,1569940104.306 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-10-01T14:28:24.736Z,1569940104.736 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-10-01T14:28:24.736Z,1569940104.736 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-10-01T14:28:25.202Z,1569940105.202 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-10-01T14:28:25.204Z,1569940105.204 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-10-01T14:28:25.501Z,1569940105.501 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-10-01T14:28:25.502Z,1569940105.502 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-10-01T14:28:25.824Z,1569940105.824 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-10-01T14:28:25.826Z,1569940105.826 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-10-01T14:28:26.407Z,1569940106.407 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-10-01T14:28:26.407Z,1569940106.407 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-10-01T14:28:26.968Z,1569940106.968 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-10-01T14:28:26.969Z,1569940106.969 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-10-01T14:28:27.073Z,1569940107.073 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-10-01T14:28:27.074Z,1569940107.074 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-10-01T14:28:27.157Z,1569940107.157 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-10-01T14:28:27.258Z,1569940107.258 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-10-01T14:28:27.260Z,1569940107.260 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-10-01T14:28:27.450Z,1569940107.450 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-10-01T14:28:27.452Z,1569940107.452 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-10-01T14:28:27.655Z,1569940107.655 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-10-01T14:28:27.657Z,1569940107.657 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2019-10-01T14:28:27.660Z,1569940107.660 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2019-10-01T14:28:27.744Z,1569940107.744 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2019-10-01T14:28:27.968Z,1569940107.968 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-10-01T14:28:27.969Z,1569940107.969 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2019-10-01T14:28:28.063Z,1569940108.063 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2019-10-01T14:28:28.224Z,1569940108.224 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2019-10-01T14:28:28.423Z,1569940108.423 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2019-10-01T14:28:28.508Z,1569940108.508 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2019-10-01T14:28:28.607Z,1569940108.607 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2019-10-01T14:28:28.700Z,1569940108.700 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2019-10-01T14:28:28.851Z,1569940108.851 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2019-10-01T14:28:28.953Z,1569940108.953 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2019-10-01T14:28:29.051Z,1569940109.051 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-10-01T14:28:29.054Z,1569940109.054 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-10-01T14:28:29.185Z,1569940109.185 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-10-01T14:28:29.185Z,1569940109.185 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-10-01T14:28:29.341Z,1569940109.341 [BuoyancyServo] Loaded
2019-10-01T14:28:29.341Z,1569940109.341 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-10-01T14:28:29.356Z,1569940109.356 [ElevatorServo] Loaded
2019-10-01T14:28:29.356Z,1569940109.356 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-10-01T14:28:29.371Z,1569940109.371 [MassServo] Loaded
2019-10-01T14:28:29.371Z,1569940109.371 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-10-01T14:28:29.386Z,1569940109.386 [RudderServo] Loaded
2019-10-01T14:28:29.386Z,1569940109.386 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-10-01T14:28:29.401Z,1569940109.401 [ThrusterServo] Loaded
2019-10-01T14:28:29.401Z,1569940109.401 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-10-01T14:28:29.402Z,1569940109.402 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-10-01T14:28:29.402Z,1569940109.402 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-10-01T14:28:29.561Z,1569940109.561 [SBIT](DEBUG): Construct Startup Built In Test.
2019-10-01T14:28:29.590Z,1569940109.590 [SBIT] Loaded
2019-10-01T14:28:29.590Z,1569940109.590 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-10-01T14:28:29.591Z,1569940109.591 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-10-01T14:28:29.618Z,1569940109.618 [IBIT] Loaded
2019-10-01T14:28:29.618Z,1569940109.618 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-10-01T14:28:29.621Z,1569940109.621 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-10-01T14:28:30.008Z,1569940110.008 [CBIT] Loaded
2019-10-01T14:28:30.008Z,1569940110.008 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-10-01T14:28:30.008Z,1569940110.008 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-10-01T14:28:30.009Z,1569940110.009 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-10-01T14:28:30.267Z,1569940110.267 [Aanderaa_O2] Loaded
2019-10-01T14:28:30.267Z,1569940110.267 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2019-10-01T14:28:30.277Z,1569940110.277 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2019-10-01T14:28:30.282Z,1569940110.282 [CTD_Seabird](INFO): created writer for : sea_water_density
2019-10-01T14:28:30.283Z,1569940110.283 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2019-10-01T14:28:30.288Z,1569940110.288 [CTD_Seabird](INFO): created writer for : depth
2019-10-01T14:28:30.289Z,1569940110.289 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2019-10-01T14:28:30.293Z,1569940110.293 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2019-10-01T14:28:30.294Z,1569940110.294 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2019-10-01T14:28:30.299Z,1569940110.299 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2019-10-01T14:28:30.300Z,1569940110.300 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2019-10-01T14:28:30.305Z,1569940110.305 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2019-10-01T14:28:30.306Z,1569940110.306 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2019-10-01T14:28:30.311Z,1569940110.311 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2019-10-01T14:28:30.311Z,1569940110.311 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2019-10-01T14:28:30.316Z,1569940110.316 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2019-10-01T14:28:30.342Z,1569940110.342 [CTD_Seabird] Loaded
2019-10-01T14:28:30.343Z,1569940110.343 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2019-10-01T14:28:30.344Z,1569940110.344 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 4066B4E0
2019-10-01T14:28:30.344Z,1569940110.344 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 888
2019-10-01T14:28:30.374Z,1569940110.374 [ESPComponent] Loaded
2019-10-01T14:28:30.375Z,1569940110.375 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2019-10-01T14:28:30.389Z,1569940110.389 [PAR_Licor] Loaded
2019-10-01T14:28:30.389Z,1569940110.389 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-10-01T14:28:30.395Z,1569940110.395 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2019-10-01T14:28:30.395Z,1569940110.395 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2019-10-01T14:28:30.399Z,1569940110.399 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2019-10-01T14:28:30.400Z,1569940110.400 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2019-10-01T14:28:30.404Z,1569940110.404 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2019-10-01T14:28:30.404Z,1569940110.404 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2019-10-01T14:28:30.408Z,1569940110.408 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2019-10-01T14:28:30.408Z,1569940110.408 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2019-10-01T14:28:30.412Z,1569940110.412 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2019-10-01T14:28:30.413Z,1569940110.413 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2019-10-01T14:28:30.417Z,1569940110.417 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2019-10-01T14:28:30.417Z,1569940110.417 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2019-10-01T14:28:30.421Z,1569940110.421 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2019-10-01T14:28:30.422Z,1569940110.422 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2019-10-01T14:28:30.426Z,1569940110.426 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2019-10-01T14:28:30.430Z,1569940110.430 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2019-10-01T14:28:30.431Z,1569940110.431 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2019-10-01T14:28:30.431Z,1569940110.431 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2019-10-01T14:28:30.435Z,1569940110.435 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2019-10-01T14:28:30.436Z,1569940110.436 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2019-10-01T14:28:30.440Z,1569940110.440 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2019-10-01T14:28:30.440Z,1569940110.440 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2019-10-01T14:28:30.444Z,1569940110.444 [WetLabsBB2FL] Loaded
2019-10-01T14:28:30.445Z,1569940110.445 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2019-10-01T14:28:30.446Z,1569940110.446 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4069B4E0
2019-10-01T14:28:30.446Z,1569940110.446 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 889
2019-10-01T14:28:30.447Z,1569940110.447 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-10-01T14:28:30.448Z,1569940110.448 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-10-01T14:28:30.502Z,1569940110.502 [DepthRateCalculator] Loaded
2019-10-01T14:28:30.503Z,1569940110.503 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-10-01T14:28:30.508Z,1569940110.508 [PitchRateCalculator] Loaded
2019-10-01T14:28:30.509Z,1569940110.509 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-10-01T14:28:30.524Z,1569940110.524 [SpeedCalculator] Loaded
2019-10-01T14:28:30.524Z,1569940110.524 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-10-01T14:28:30.544Z,1569940110.544 [TempGradientCalculator] Loaded
2019-10-01T14:28:30.545Z,1569940110.545 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-10-01T14:28:30.550Z,1569940110.550 [YawRateCalculator] Loaded
2019-10-01T14:28:30.551Z,1569940110.551 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-10-01T14:28:30.583Z,1569940110.583 [ElevatorOffsetCalculator] Loaded
2019-10-01T14:28:30.583Z,1569940110.583 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-10-01T14:28:30.584Z,1569940110.584 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-10-01T14:28:30.586Z,1569940110.586 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-10-01T14:28:30.634Z,1569940110.634 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-10-01T14:28:30.635Z,1569940110.635 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-10-01T14:28:30.753Z,1569940110.753 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-10-01T14:28:30.753Z,1569940110.753 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-10-01T14:28:30.773Z,1569940110.773 [NavChart] Loaded
2019-10-01T14:28:30.774Z,1569940110.774 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-10-01T14:28:30.778Z,1569940110.778 [UniversalFixResidualReporter] Loaded
2019-10-01T14:28:30.778Z,1569940110.778 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-10-01T14:28:30.778Z,1569940110.778 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-10-01T14:28:30.779Z,1569940110.779 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-10-01T14:28:31.234Z,1569940111.234 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2019-10-01T14:28:31.239Z,1569940111.239 [AHRS_M2](INFO): created writer for : platform_orientation
2019-10-01T14:28:31.240Z,1569940111.240 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2019-10-01T14:28:31.245Z,1569940111.245 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2019-10-01T14:28:31.246Z,1569940111.246 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2019-10-01T14:28:31.250Z,1569940111.250 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2019-10-01T14:28:31.251Z,1569940111.251 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2019-10-01T14:28:31.256Z,1569940111.256 [AHRS_M2](INFO): created writer for : platform_roll_angle
2019-10-01T14:28:31.435Z,1569940111.435 [AHRS_M2] Loaded
2019-10-01T14:28:31.435Z,1569940111.435 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-10-01T14:28:31.579Z,1569940111.579 [DataOverHttps] Loaded
2019-10-01T14:28:31.580Z,1569940111.580 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-10-01T14:28:31.581Z,1569940111.581 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409634E0
2019-10-01T14:28:31.581Z,1569940111.581 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 890
2019-10-01T14:28:31.594Z,1569940111.594 [Depth_Keller] Loaded
2019-10-01T14:28:31.595Z,1569940111.595 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-10-01T14:28:31.600Z,1569940111.600 [DropWeight] Loaded
2019-10-01T14:28:31.600Z,1569940111.600 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-10-01T14:28:31.645Z,1569940111.645 [DVL_micro] Loaded
2019-10-01T14:28:31.645Z,1569940111.645 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2019-10-01T14:28:31.735Z,1569940111.735 [NAL9602] Loaded
2019-10-01T14:28:31.735Z,1569940111.735 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-10-01T14:28:31.740Z,1569940111.740 [Onboard] Loaded
2019-10-01T14:28:31.740Z,1569940111.740 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-10-01T14:28:31.744Z,1569940111.744 [Radio_Surface] Loaded
2019-10-01T14:28:31.744Z,1569940111.744 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-10-01T14:28:31.745Z,1569940111.745 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409934E0
2019-10-01T14:28:31.745Z,1569940111.745 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 891
2019-10-01T14:28:31.867Z,1569940111.867 [DAT] Loaded
2019-10-01T14:28:31.867Z,1569940111.867 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2019-10-01T14:28:33.130Z,1569940113.130 [BPC1] Loaded
2019-10-01T14:28:33.130Z,1569940113.130 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-10-01T14:28:33.130Z,1569940113.130 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-10-01T14:28:33.131Z,1569940113.131 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-10-01T14:28:33.391Z,1569940113.391 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-10-01T14:28:33.392Z,1569940113.392 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-10-01T14:28:34.324Z,1569940114.324 [VerticalControl](DEBUG): Construct VerticalControl.
2019-10-01T14:28:34.407Z,1569940114.407 [VerticalControl] Loaded
2019-10-01T14:28:34.407Z,1569940114.407 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-10-01T14:28:34.408Z,1569940114.408 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-10-01T14:28:34.465Z,1569940114.465 [HorizontalControl] Loaded
2019-10-01T14:28:34.465Z,1569940114.465 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-10-01T14:28:34.466Z,1569940114.466 [SpeedControl](DEBUG): Construct SpeedControl.
2019-10-01T14:28:34.467Z,1569940114.467 [SpeedControl] Loaded
2019-10-01T14:28:34.468Z,1569940114.468 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-10-01T14:28:34.468Z,1569940114.468 [LoopControl](DEBUG): Construct LoopControl.
2019-10-01T14:28:34.469Z,1569940114.469 [LoopControl] Loaded
2019-10-01T14:28:34.469Z,1569940114.469 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-10-01T14:28:34.469Z,1569940114.469 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-10-01T14:28:34.470Z,1569940114.470 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-10-01T14:28:34.494Z,1569940114.494 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-10-01T14:28:34.495Z,1569940114.495 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-10-01T14:28:34.832Z,1569940114.832 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-10-01T14:28:34.836Z,1569940114.836 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-10-01T14:28:34.837Z,1569940114.837 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-10-01T14:28:34.843Z,1569940114.843 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-10-01T14:28:34.844Z,1569940114.844 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40ACE4E0
2019-10-01T14:28:34.845Z,1569940114.845 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 892
2019-10-01T14:28:34.849Z,1569940114.849 [Supervisor](INFO): Main Thread ID is 802
2019-10-01T14:28:34.850Z,1569940114.850 [Supervisor](DEBUG): Running supervisor.
2019-10-01T14:28:34.850Z,1569940114.850 [CommandLine ThreadHandler](INFO): Handler Thread ID is 893
2019-10-01T14:28:34.852Z,1569940114.852 [controlThread ThreadHandler](INFO): Handler Thread ID is 894
2019-10-01T14:28:34.853Z,1569940114.853 [controlThread](DEBUG): Initializing ControlThread
2019-10-01T14:28:34.855Z,1569940114.855 [SBIT](INFO): Initialize SBIT Component.
2019-10-01T14:28:34.855Z,1569940114.855 [SBIT](IMPORTANT): git: 2019-10-01_A
2019-10-01T14:28:34.855Z,1569940114.855 [SBIT](INFO): git hash: 45f1fece079aa3565d4a3aba33a016ea6943a305
2019-10-01T14:28:34.856Z,1569940114.856 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-10-01T14:28:34.857Z,1569940114.857 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2019-10-01T14:28:34.858Z,1569940114.858 [SBIT](INFO): Beginning SBIT in 52.000000 seconds.
2019-10-01T14:28:34.859Z,1569940114.859 [IBIT](INFO): Initialize IBIT Component.
2019-10-01T14:28:34.859Z,1569940114.859 [CBIT](DEBUG): Initialize CBIT Component.
2019-10-01T14:28:34.860Z,1569940114.860 [logger ThreadHandler](INFO): Handler Thread ID is 895
2019-10-01T14:28:34.871Z,1569940114.871 [CBIT](DEBUG): Initialized mux pins.
2019-10-01T14:28:34.871Z,1569940114.871 [CBIT](DEBUG): Initializing the watchdog timer.
2019-10-01T14:28:34.879Z,1569940114.879 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 896
2019-10-01T14:28:34.880Z,1569940114.880 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2019-10-01T14:28:34.884Z,1569940114.884 [CTD_Seabird](INFO): Opening uart, block timeout 10ths=20
2019-10-01T14:28:34.885Z,1569940114.885 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 898
2019-10-01T14:28:34.886Z,1569940114.886 [WetLabsBB2FL](INFO): Powering down
2019-10-01T14:28:34.895Z,1569940114.895 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-10-01T14:28:34.895Z,1569940114.895 [CBIT](DEBUG): Initializing heartbeat.
2019-10-01T14:28:34.933Z,1569940114.933 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 899
2019-10-01T14:28:34.934Z,1569940114.934 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-10-01T14:28:34.967Z,1569940114.967 [CBIT](DEBUG): Deactivating GF circuits.
2019-10-01T14:28:34.967Z,1569940114.967 [CBIT](DEBUG): Deactivating emergency mode.
2019-10-01T14:28:35.003Z,1569940115.003 [CBIT](DEBUG): Backplane powered.
2019-10-01T14:28:35.007Z,1569940115.007 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-10-01T14:28:35.008Z,1569940115.008 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-10-01T14:28:35.008Z,1569940115.008 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-10-01T14:28:35.008Z,1569940115.008 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-10-01T14:28:35.009Z,1569940115.009 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-10-01T14:28:35.009Z,1569940115.009 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-10-01T14:28:35.010Z,1569940115.010 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-10-01T14:28:35.011Z,1569940115.011 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-10-01T14:28:35.025Z,1569940115.025 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 900
2019-10-01T14:28:35.048Z,1569940115.048 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-10-01T14:28:35.049Z,1569940115.049 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-10-01T14:28:35.050Z,1569940115.050 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-10-01T14:28:35.050Z,1569940115.050 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-10-01T14:28:35.052Z,1569940115.052 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-10-01T14:28:35.155Z,1569940115.155 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 901
2019-10-01T14:28:35.166Z,1569940115.166 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-10-01T14:28:35.172Z,1569940115.172 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-10-01T14:28:35.172Z,1569940115.172 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-10-01T14:28:35.173Z,1569940115.173 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-10-01T14:28:35.173Z,1569940115.173 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-10-01T14:28:35.173Z,1569940115.173 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-10-01T14:28:35.173Z,1569940115.173 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-10-01T14:28:35.173Z,1569940115.173 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-10-01T14:28:35.173Z,1569940115.173 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-10-01T14:28:35.174Z,1569940115.174 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-10-01T14:28:35.174Z,1569940115.174 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-10-01T14:28:35.174Z,1569940115.174 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-10-01T14:28:35.174Z,1569940115.174 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-10-01T14:28:35.174Z,1569940115.174 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-10-01T14:28:35.174Z,1569940115.174 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-10-01T14:28:35.175Z,1569940115.175 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-10-01T14:28:35.175Z,1569940115.175 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-10-01T14:28:35.240Z,1569940115.240 [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>


2019-10-01T14:28:35.240Z,1569940115.240 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-10-01T14:28:35.386Z,1569940115.386 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-10-01T14:28:35.388Z,1569940115.388 [Default:A.Wait](DEBUG): Construct Wait.
2019-10-01T14:28:35.390Z,1569940115.390 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-10-01T14:28:35.427Z,1569940115.427 [Radio_Surface](INFO): Powering up
2019-10-01T14:28:35.443Z,1569940115.443 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-10-01T14:28:35.445Z,1569940115.445 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-10-01T14:28:35.468Z,1569940115.468 [Default:E.Execute](DEBUG): Construct Execute.
2019-10-01T14:28:35.471Z,1569940115.471 [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>


2019-10-01T14:28:35.484Z,1569940115.484 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,Onboard,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,
2019-10-01T14:28:35.520Z,1569940115.520 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-10-01T14:28:35.594Z,1569940115.594 [Depth_Keller](ERROR): Pressure reading out of range: 1895.684326 decibar
2019-10-01T14:28:35.629Z,1569940115.629 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2019-10-01T14:28:35.629Z,1569940115.629 [DAT](INFO): Powering up
2019-10-01T14:28:35.629Z,1569940115.629 [DAT](DEBUG): Initializing DAT.
2019-10-01T14:28:35.656Z,1569940115.656 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2019-10-01T14:28:35.707Z,1569940115.707 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-10-01T14:28:35.769Z,1569940115.769 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-10-01T14:28:35.780Z,1569940115.780 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-10-01T14:28:35.781Z,1569940115.781 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-10-01T14:28:35.787Z,1569940115.787 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-10-01T14:28:35.788Z,1569940115.788 [MassServo](DEBUG): Initializing EZServoServo.
2019-10-01T14:28:35.795Z,1569940115.795 [MassServo](DEBUG): Initializing MassServo.
2019-10-01T14:28:35.796Z,1569940115.796 [RudderServo](DEBUG): Initializing EZServoServo.
2019-10-01T14:28:35.803Z,1569940115.803 [RudderServo](DEBUG): Initializing RudderServo.
2019-10-01T14:28:35.804Z,1569940115.804 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-10-01T14:28:35.811Z,1569940115.811 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-10-01T14:28:36.679Z,1569940116.679 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-10-01T14:28:36.679Z,1569940116.679 [RudderServo](FAULT): Rudder failed to initialize
2019-10-01T14:28:36.679Z,1569940116.679 [RudderServo] Communications Fault, FailCount= 1
2019-10-01T14:28:36.679Z,1569940116.679 [RudderServo](ERROR): Communications Fault
2019-10-01T14:28:36.827Z,1569940116.827 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-10-01T14:28:37.040Z,1569940117.040 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-10-01T14:28:37.040Z,1569940117.040 [RudderServo](INFO): Powering down
2019-10-01T14:28:37.701Z,1569940117.701 [RudderServo](DEBUG): Initializing EZServoServo.
2019-10-01T14:28:37.820Z,1569940117.820 [RudderServo](DEBUG): Initializing RudderServo.
2019-10-01T14:28:37.824Z,1569940117.824 [CBIT](INFO): Clearing failed state for component RudderServo
2019-10-01T14:28:37.824Z,1569940117.824 [RudderServo] No Fault, FailCount= 1
2019-10-01T14:28:38.971Z,1569940118.971 [Aanderaa_O2](INFO): Powering down
2019-10-01T14:28:41.911Z,1569940121.911 [CTD_Seabird](ERROR): Device message queue exceeded the allowed limit.
2019-10-01T14:28:51.022Z,1569940131.022 [DAT](INFO): setting local address to 3
2019-10-01T14:28:51.405Z,1569940131.405 [DAT](INFO): set local address to 3
2019-10-01T14:29:01.893Z,1569940141.893 [NAL9602](INFO): Powering up NAL9602
2019-10-01T14:29:12.813Z,1569940152.813 [NAL9602](INFO): NAL9602 initialized
2019-10-01T14:29:13.635Z,1569940153.635 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:29:27.403Z,1569940167.403 [SBIT](IMPORTANT): Beginning Startup BIT
2019-10-01T14:29:27.407Z,1569940167.407 [CBIT](IMPORTANT): Beginning ground fault scan
2019-10-01T14:29:38.423Z,1569940178.423 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.011040
CHAN A1 (24V): -0.013938
CHAN A2 (12V): -0.002010
CHAN A3 (5V): -0.001642
CHAN B0 (3.3V): -0.001193
CHAN B1 (3.15aV): -0.001528
CHAN B2 (3.15bV): -0.001296
CHAN B3 (GND): -0.000993
OPEN: 0.007283
Full Scale Calc: 4.765 mA, -1.589 mA
2019-10-01T14:30:20.707Z,1569940220.707 [SBIT](IMPORTANT): SBIT PASSED
2019-10-01T14:30:20.799Z,1569940220.799 [CommandLine](IMPORTANT): got command configSet list
2019-10-01T14:30:20.800Z,1569940220.800 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-10-01T14:30:20.800Z,1569940220.800 [CommandLine](IMPORTANT): No configSet variables persisted
2019-10-01T14:30:21.112Z,1569940221.112 [MissionManager](IMPORTANT): Started mission Startup
2019-10-01T14:30:21.113Z,1569940221.113 [Startup] Running Loop=1
2019-10-01T14:30:21.113Z,1569940221.113 [Startup](DEBUG): Aggregate::initialize Startup
2019-10-01T14:30:21.113Z,1569940221.113 [Startup:A.GoToSurface] Running Loop=1
2019-10-01T14:30:21.113Z,1569940221.113 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-10-01T14:30:21.114Z,1569940221.114 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-10-01T14:30:21.114Z,1569940221.114 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-10-01T14:30:21.114Z,1569940221.114 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-10-01T14:30:21.115Z,1569940221.115 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-10-01T14:30:21.115Z,1569940221.115 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-10-01T14:30:21.117Z,1569940221.117 [Startup:StartupSatComms] Running Loop=1
2019-10-01T14:30:21.117Z,1569940221.117 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-10-01T14:30:21.117Z,1569940221.117 [Startup:StartupSatComms:A] Running Loop=1
2019-10-01T14:30:21.506Z,1569940221.506 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-10-01T14:30:29.559Z,1569940229.559 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2019-10-01T14:30:29.560Z,1569940229.560 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+22.0,000.0,000
2019-10-01T14:30:39.661Z,1569940239.661 [DVL_micro](ERROR): Failed to parse:
:BI,-00016,-00072,-00146,+00000,I
2019-10-01T14:30:40.164Z,1569940240.164 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-10-01T14:31:05.925Z,1569940265.925 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004977
2019-10-01T14:31:21.292Z,1569940281.292 [Startup:StartupSatComms:A](INFO): Timed out from 2019-10-01T14:30:21.1Z
2019-10-01T14:31:21.292Z,1569940281.292 [Startup:StartupSatComms:A] Stopped
2019-10-01T14:31:21.292Z,1569940281.292 [Startup:StartupSatComms:B] Running Loop=1
2019-10-01T14:31:21.712Z,1569940281.712 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-10-01T14:31:35.087Z,1569940295.087 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-10-01T14:31:35.087Z,1569940295.087 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-01T14:31:35.102Z,1569940295.102 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-01T14:31:35.477Z,1569940295.477 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-01T14:31:35.477Z,1569940295.477 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-10-01T14:31:37.416Z,1569940297.416 [DataOverHttps](INFO): Sending 658 bytes from file Logs/20191001T142823/Express0001.lzma
2019-10-01T14:31:39.417Z,1569940299.417 [DataOverHttps](INFO): Moved sent file to Logs/20191001T142823/Express0001.lzma.bak
2019-10-01T14:31:39.417Z,1569940299.417 [DataOverHttps](INFO): SBD MOMSN=11835728
2019-10-01T14:31:40.691Z,1569940300.691 [Startup:StartupSatComms:B] Stopped
2019-10-01T14:31:40.691Z,1569940300.691 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-10-01T14:31:40.691Z,1569940300.691 [Startup:StartupSatComms] Stopped
2019-10-01T14:31:40.691Z,1569940300.691 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-10-01T14:31:40.692Z,1569940300.692 [Startup](INFO): Completed Startup
2019-10-01T14:31:40.692Z,1569940300.692 [MissionManager](INFO): Startup is completed.
2019-10-01T14:31:40.692Z,1569940300.692 [MissionManager](INFO): Uninitializing Mission Startup
2019-10-01T14:31:40.692Z,1569940300.692 [Startup] Stopped
2019-10-01T14:31:40.692Z,1569940300.692 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-10-01T14:31:40.693Z,1569940300.693 [Startup:A.GoToSurface] Stopped
2019-10-01T14:31:40.693Z,1569940300.693 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-10-01T14:31:41.172Z,1569940301.172 [MissionManager](IMPORTANT): Started mission Default
2019-10-01T14:31:41.172Z,1569940301.172 [Default] Running Loop=1
2019-10-01T14:31:41.172Z,1569940301.172 [Default](DEBUG): Aggregate::initialize Default
2019-10-01T14:31:41.173Z,1569940301.173 [Default:B.GoToSurface] Running Loop=1
2019-10-01T14:31:41.173Z,1569940301.173 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-10-01T14:31:41.173Z,1569940301.173 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-10-01T14:31:41.173Z,1569940301.173 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-10-01T14:31:41.173Z,1569940301.173 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-10-01T14:31:41.174Z,1569940301.174 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-10-01T14:31:41.174Z,1569940301.174 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-10-01T14:31:41.174Z,1569940301.174 [Default:A.Wait] Running Loop=1
2019-10-01T14:31:41.174Z,1569940301.174 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-10-01T14:31:54.473Z,1569940314.473 [Default:A.Wait](INFO): Done Waiting.
2019-10-01T14:31:54.473Z,1569940314.473 [Default:A.Wait] Stopped
2019-10-01T14:31:54.473Z,1569940314.473 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-10-01T14:31:54.853Z,1569940314.853 [Default:CheckIn] Running Loop=1
2019-10-01T14:31:54.853Z,1569940314.853 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-01T14:31:54.853Z,1569940314.853 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-01T14:31:55.252Z,1569940315.252 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-10-01T14:32:59.121Z,1569940379.121 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-10-01T14:33:51.155Z,1569940431.155 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2019-10-01T14:33:51.156Z,1569940431.156 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000000,35.0,+22.0,0000.0,14
2019-10-01T14:34:06.589Z,1569940446.589 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2019-10-01T14:34:16.214Z,1569940456.214 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-10-01T14:34:17.019Z,1569940457.019 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:34:19.851Z,1569940459.851 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:34:20.392Z,1569940460.392 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-10-01T14:34:22.675Z,1569940462.675 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:34:23.880Z,1569940463.880 [DVL_micro](ERROR): Failed to parse:
:WI,+00065,-00001,-00158,+00000,A
2019-10-01T14:34:25.911Z,1569940465.911 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:34:28.755Z,1569940468.755 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:34:31.567Z,1569940471.567 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:34:34.819Z,1569940474.819 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:34:36.039Z,1569940476.039 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2019-10-01T14:34:36.039Z,1569940476.039 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-01T14:34:36.049Z,1569940476.049 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-01T14:34:36.458Z,1569940476.458 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-01T14:34:36.458Z,1569940476.458 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2019-10-01T14:34:37.639Z,1569940477.639 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:34:40.859Z,1569940480.859 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:34:43.711Z,1569940483.711 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:34:46.915Z,1569940486.915 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:34:49.751Z,1569940489.751 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:34:52.578Z,1569940492.578 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:34:55.811Z,1569940495.811 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:34:58.635Z,1569940498.635 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:35:00.724Z,1569940500.724 [BPC1](ERROR): BPC1A failed to parse battery data due to unrecognized msg size.
2019-10-01T14:35:01.871Z,1569940501.871 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:35:04.695Z,1569940504.695 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:35:07.931Z,1569940507.931 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:35:10.775Z,1569940510.775 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:35:14.003Z,1569940514.003 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:35:14.431Z,1569940514.431 [BPC1](ERROR): Battery stick #38 (s/n: 0139) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2019-10-01T14:35:14.432Z,1569940514.432 [BPC1](ERROR): Battery stick #39 (s/n: 00F0) reported TERMINATE_DISCHARGE_ALARM. Status code: 0x48C0.
2019-10-01T14:35:14.432Z,1569940514.432 [BPC1](INFO): Calculating totals. Valid battery stick count: 55. Valid reserve battery stick count: 5.
2019-10-01T14:35:14.436Z,1569940514.436 [BPC1](INFO): Received data from all battery sticks.
2019-10-01T14:35:16.831Z,1569940516.831 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:35:19.647Z,1569940519.647 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:35:22.875Z,1569940522.875 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:35:25.707Z,1569940525.707 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:35:26.905Z,1569940526.905 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2019-10-01T14:35:26.905Z,1569940526.905 [DVL_micro](ERROR): Failed to parse:
:TS,00000000000,+22.1,0000.0,1489.0,000
2019-10-01T14:35:28.934Z,1569940528.934 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:35:31.775Z,1569940531.775 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:35:34.991Z,1569940534.991 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:35:37.823Z,1569940537.823 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:35:40.659Z,1569940540.659 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:35:42.674Z,1569940542.674 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:35:45.905Z,1569940545.905 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:35:48.735Z,1569940548.735 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:35:52.002Z,1569940552.002 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:35:54.802Z,1569940554.802 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:35:58.023Z,1569940558.023 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:36:00.859Z,1569940560.859 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:36:04.083Z,1569940564.083 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:36:06.919Z,1569940566.919 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:36:09.739Z,1569940569.739 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:36:12.155Z,1569940572.155 [DVL_micro](ERROR): Failed to parse:
:4,+00134+00000,A
2019-10-01T14:36:12.967Z,1569940572.967 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:36:15.799Z,1569940575.799 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:36:19.035Z,1569940579.035 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:36:21.863Z,1569940581.863 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:36:25.098Z,1569940585.098 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:36:27.927Z,1569940587.927 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:36:30.747Z,1569940590.747 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:36:33.991Z,1569940593.991 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:36:36.811Z,1569940596.811 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:36:40.047Z,1569940600.047 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:36:42.867Z,1569940602.867 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:36:46.099Z,1569940606.099 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:36:48.931Z,1569940608.931 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:36:52.179Z,1569940612.179 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:36:54.991Z,1569940614.991 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:36:55.044Z,1569940615.044 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-10-01T14:31:54.9Z
2019-10-01T14:36:55.044Z,1569940615.044 [Default:CheckIn:Read_GPS] Stopped
2019-10-01T14:36:55.045Z,1569940615.045 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-10-01T14:36:55.441Z,1569940615.441 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-10-01T14:36:57.826Z,1569940617.826 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:37:01.054Z,1569940621.054 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:37:03.883Z,1569940623.883 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:37:06.323Z,1569940626.323 [DataOverHttps](INFO): Sending 187 bytes from file Logs/20191001T142823/Courier0004.lzma
2019-10-01T14:37:07.107Z,1569940627.107 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:37:08.328Z,1569940628.328 [DataOverHttps](INFO): Moved sent file to Logs/20191001T142823/Courier0004.lzma.bak
2019-10-01T14:37:08.329Z,1569940628.329 [DataOverHttps](INFO): SBD MOMSN=11835752
2019-10-01T14:37:09.943Z,1569940629.943 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:37:13.167Z,1569940633.167 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:37:15.995Z,1569940635.995 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:37:18.827Z,1569940638.827 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:37:22.055Z,1569940642.055 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:37:24.891Z,1569940644.891 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:37:25.275Z,1569940645.275 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2019-10-01T14:37:25.275Z,1569940645.275 [DVL_micro](ERROR): Failed to parse:
:TS,000000,35.0000.0,14
2019-10-01T14:37:28.067Z,1569940648.067 [DataOverHttps](INFO): Sending 177 bytes from file Logs/20191001T142823/Express0005.lzma
2019-10-01T14:37:28.115Z,1569940648.115 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:37:30.072Z,1569940650.072 [DataOverHttps](INFO): Moved sent file to Logs/20191001T142823/Express0005.lzma.bak
2019-10-01T14:37:30.072Z,1569940650.072 [DataOverHttps](INFO): SBD MOMSN=11835754
2019-10-01T14:37:30.963Z,1569940650.963 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:37:31.380Z,1569940651.380 [Default:CheckIn:Read_Iridium] Stopped
2019-10-01T14:37:31.380Z,1569940651.380 [Default:CheckIn:C.Wait] Running Loop=1
2019-10-01T14:37:31.380Z,1569940651.380 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-10-01T14:37:34.175Z,1569940654.175 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:37:37.035Z,1569940657.035 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:37:37.077Z,1569940657.077 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2019-10-01T14:37:37.077Z,1569940657.077 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-01T14:37:37.099Z,1569940657.099 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-01T14:37:37.460Z,1569940657.460 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-01T14:37:37.460Z,1569940657.460 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2019-10-01T14:37:40.255Z,1569940660.255 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:37:43.067Z,1569940663.067 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:37:45.887Z,1569940665.887 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:37:49.123Z,1569940669.123 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:37:51.947Z,1569940671.947 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:37:55.188Z,1569940675.188 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:37:58.019Z,1569940678.019 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:38:01.243Z,1569940681.243 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:38:04.071Z,1569940684.071 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:38:06.919Z,1569940686.919 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:38:10.135Z,1569940690.135 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:38:12.967Z,1569940692.967 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:38:16.191Z,1569940696.191 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:38:19.018Z,1569940699.018 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:38:22.255Z,1569940702.255 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:38:25.079Z,1569940705.079 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:38:28.319Z,1569940708.319 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:38:31.143Z,1569940711.143 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:38:33.967Z,1569940713.967 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:38:37.203Z,1569940717.203 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:38:40.027Z,1569940720.027 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:38:42.867Z,1569940722.867 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:38:46.091Z,1569940726.091 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:38:48.915Z,1569940728.915 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:38:52.147Z,1569940732.147 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:38:54.971Z,1569940734.971 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:38:58.211Z,1569940738.211 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:39:01.043Z,1569940741.043 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:39:03.867Z,1569940743.867 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:39:07.095Z,1569940747.095 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:39:09.923Z,1569940749.923 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:39:13.163Z,1569940753.163 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:39:15.982Z,1569940755.982 [NAL9602](FAULT): GPS failed to acquire within timeout.
2019-10-01T14:39:15.982Z,1569940755.982 [NAL9602] Data Fault, FailCount= 1
2019-10-01T14:39:15.982Z,1569940755.982 [NAL9602](ERROR): Data Fault
2019-10-01T14:39:16.061Z,1569940756.061 [CBIT](ERROR): Data Fault in component: NAL9602
2019-10-01T14:39:16.392Z,1569940756.392 [NAL9602](INFO): Powering down
2019-10-01T14:39:17.222Z,1569940757.222 [CBIT](INFO): Clearing failed state for component NAL9602
2019-10-01T14:39:17.222Z,1569940757.222 [NAL9602] No Fault, FailCount= 1
2019-10-01T14:39:46.674Z,1569940786.674 [NAL9602](INFO): Powering up NAL9602
2019-10-01T14:39:57.582Z,1569940797.582 [NAL9602](INFO): NAL9602 initialized
2019-10-01T14:39:58.403Z,1569940798.403 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:40:00.831Z,1569940800.831 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:40:02.858Z,1569940802.858 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:40:04.062Z,1569940804.062 [DVL_micro](ERROR): only read 3 of 4 data items
2019-10-01T14:40:04.062Z,1569940804.062 [DVL_micro](ERROR): Failed to parse:
:BI,+00037,+0009,+00000,I
2019-10-01T14:40:06.079Z,1569940806.079 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:40:08.923Z,1569940808.923 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:40:11.789Z,1569940811.789 [DVL_micro](ERROR): only read 2 of 4 data items
2019-10-01T14:40:11.790Z,1569940811.790 [DVL_micro](ERROR): Failed to parse:
:RD,+99999.99,+99999.99
2019-10-01T14:40:12.143Z,1569940812.143 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:40:14.979Z,1569940814.979 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:40:18.214Z,1569940818.214 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:40:21.039Z,1569940821.039 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:40:23.859Z,1569940823.859 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:40:27.099Z,1569940827.099 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:40:29.923Z,1569940829.923 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:40:33.162Z,1569940833.162 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:40:35.987Z,1569940835.987 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:40:38.020Z,1569940838.020 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2019-10-01T14:40:38.020Z,1569940838.020 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-01T14:40:38.030Z,1569940838.030 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-01T14:40:38.433Z,1569940838.433 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-01T14:40:38.433Z,1569940838.433 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2019-10-01T14:40:39.261Z,1569940839.261 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:40:42.043Z,1569940842.043 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:40:44.875Z,1569940844.875 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:40:46.911Z,1569940846.911 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:40:48.911Z,1569940848.911 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:40:52.143Z,1569940852.143 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:40:54.975Z,1569940854.975 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:40:58.207Z,1569940858.207 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:41:01.031Z,1569940861.031 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:41:04.263Z,1569940864.263 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:41:07.111Z,1569940867.111 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:41:09.919Z,1569940869.919 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:41:11.963Z,1569940871.963 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:41:15.171Z,1569940875.171 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:41:18.003Z,1569940878.003 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:41:21.231Z,1569940881.231 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:41:24.066Z,1569940884.066 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:41:24.857Z,1569940884.857 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2019-10-01T14:41:24.857Z,1569940884.857 [DVL_micro](ERROR): Failed to parse:
:TS,000000000000022.1,0000.,000
2019-10-01T14:41:27.307Z,1569940887.307 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:41:30.123Z,1569940890.123 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:41:32.947Z,1569940892.947 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:41:34.983Z,1569940894.983 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:41:38.203Z,1569940898.203 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:41:41.023Z,1569940901.023 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:41:44.259Z,1569940904.259 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:41:47.087Z,1569940907.087 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:41:50.330Z,1569940910.330 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:41:53.151Z,1569940913.151 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:41:55.971Z,1569940915.971 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:41:58.003Z,1569940918.003 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:42:00.031Z,1569940920.031 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:42:03.250Z,1569940923.250 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:42:06.075Z,1569940926.075 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:42:09.311Z,1569940929.311 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:42:12.155Z,1569940932.155 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:42:15.371Z,1569940935.371 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:42:17.788Z,1569940937.788 [DVL_micro](ERROR): only read 3 of 4 data items
2019-10-01T14:42:17.788Z,1569940937.788 [DVL_micro](ERROR): Failed to parse:
:BI,-00285,+00109,-0000,I
2019-10-01T14:42:18.195Z,1569940938.195 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:42:21.023Z,1569940941.023 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:42:23.043Z,1569940943.043 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:42:25.078Z,1569940945.078 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:42:28.299Z,1569940948.299 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:42:31.119Z,1569940951.119 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:42:31.960Z,1569940951.960 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-10-01T14:42:31.960Z,1569940951.960 [Default:CheckIn:C.Wait] Stopped
2019-10-01T14:42:31.960Z,1569940951.960 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-10-01T14:42:31.961Z,1569940951.961 [Default:CheckIn:D] Running Loop=1
2019-10-01T14:42:32.345Z,1569940952.345 [Default:CheckIn:D] Stopped
2019-10-01T14:42:32.346Z,1569940952.346 [Default:CheckIn:E] Running Loop=1
2019-10-01T14:42:32.757Z,1569940952.757 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.852885 min 
2019-10-01T14:42:32.757Z,1569940952.757 [Default:CheckIn:E] Stopped
2019-10-01T14:42:32.757Z,1569940952.757 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-10-01T14:42:32.757Z,1569940952.757 [Default:CheckIn] Stopped
2019-10-01T14:42:32.757Z,1569940952.757 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-01T14:42:32.757Z,1569940952.757 [Default:CheckIn](INFO): Running loop #2
2019-10-01T14:42:32.757Z,1569940952.757 [Default:CheckIn] Running Loop=2
2019-10-01T14:42:32.757Z,1569940952.757 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-01T14:42:32.758Z,1569940952.758 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-01T14:42:34.355Z,1569940954.355 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:42:37.183Z,1569940957.183 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:42:40.427Z,1569940960.427 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:42:43.243Z,1569940963.243 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:42:46.083Z,1569940966.083 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:42:49.311Z,1569940969.311 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:42:52.142Z,1569940972.142 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:42:55.383Z,1569940975.383 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:42:58.191Z,1569940978.191 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:43:01.427Z,1569940981.427 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:43:04.255Z,1569940984.255 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:43:07.087Z,1569940987.087 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:43:09.103Z,1569940989.103 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:43:11.119Z,1569940991.119 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:43:14.375Z,1569940994.375 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:43:17.187Z,1569940997.187 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:43:20.419Z,1569941000.419 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:43:23.259Z,1569941003.259 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:43:26.479Z,1569941006.479 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:43:29.303Z,1569941009.303 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:43:30.914Z,1569941010.914 [DVL_micro](ERROR): Failed to parse:
:BD,+00000000.00,+00000000.00,+00000000.00,9999.99,000.00
2019-10-01T14:43:32.135Z,1569941012.135 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:43:34.155Z,1569941014.155 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:43:36.186Z,1569941016.186 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:43:39.014Z,1569941019.014 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2019-10-01T14:43:39.014Z,1569941019.014 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-01T14:43:39.024Z,1569941019.024 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-01T14:43:39.410Z,1569941019.410 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:43:39.444Z,1569941019.444 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-01T14:43:39.444Z,1569941019.444 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2019-10-01T14:43:42.235Z,1569941022.235 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:43:45.463Z,1569941025.463 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:43:48.311Z,1569941028.311 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:43:51.534Z,1569941031.534 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:43:54.363Z,1569941034.363 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:43:57.195Z,1569941037.195 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:44:00.419Z,1569941040.419 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:44:03.247Z,1569941043.247 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:44:06.487Z,1569941046.487 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:44:09.307Z,1569941049.307 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:44:12.539Z,1569941052.539 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:44:15.367Z,1569941055.367 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:44:18.195Z,1569941058.195 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:44:20.227Z,1569941060.227 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:44:23.451Z,1569941063.451 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:44:24.404Z,1569941064.404 [CommandLine](IMPORTANT): got command failComponent
2019-10-01T14:44:24.405Z,1569941064.405 [CommandLine](IMPORTANT): Failed components:
2019-10-01T14:44:24.405Z,1569941064.405 [CommandLine](IMPORTANT): No failed Components.
2019-10-01T14:44:26.283Z,1569941066.283 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:44:29.429Z,1569941069.429 [CommandLine](IMPORTANT): got command burn on
2019-10-01T14:44:29.429Z,1569941069.429 [CommandLine](IMPORTANT): Activating dropweight wire
2019-10-01T14:44:29.511Z,1569941069.511 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:44:32.339Z,1569941072.339 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:44:35.571Z,1569941075.571 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:44:38.395Z,1569941078.395 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:44:41.627Z,1569941081.627 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:44:44.050Z,1569941084.050 [DVL_micro](ERROR): only read 0 of 1 data item for BIT error
2019-10-01T14:44:44.050Z,1569941084.050 [DVL_micro](ERROR): Failed to parse:
:TS,000000,32,0000.0,1489.WI,+00065,-00144,-00171,+00000,A
2019-10-01T14:44:44.455Z,1569941084.455 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:44:47.283Z,1569941087.283 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:44:50.523Z,1569941090.523 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:44:53.343Z,1569941093.343 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:44:54.140Z,1569941094.140 [DVL_micro](ERROR): Failed to parse:
:WI,+00032,+00100,-00167,+00000,A
2019-10-01T14:44:56.606Z,1569941096.606 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:44:59.407Z,1569941099.407 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:45:02.643Z,1569941102.643 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:45:05.463Z,1569941105.463 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:45:08.295Z,1569941108.295 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:45:11.535Z,1569941111.535 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:45:14.362Z,1569941114.362 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:45:17.587Z,1569941117.587 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:45:20.415Z,1569941120.415 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:45:23.647Z,1569941123.647 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:45:26.475Z,1569941126.475 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:45:29.323Z,1569941129.323 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:45:31.319Z,1569941131.319 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:45:33.343Z,1569941133.343 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:45:36.575Z,1569941136.575 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:45:39.424Z,1569941139.424 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:45:42.691Z,1569941142.691 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:45:45.467Z,1569941145.467 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:45:48.703Z,1569941148.703 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:45:50.792Z,1569941150.792 [CommandLine](IMPORTANT): got command burn off
2019-10-01T14:45:50.792Z,1569941150.792 [CommandLine](IMPORTANT): Deactivating dropweight wire
2019-10-01T14:45:51.523Z,1569941151.523 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:45:54.367Z,1569941154.367 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:45:56.371Z,1569941156.371 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:45:59.611Z,1569941159.611 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:46:02.427Z,1569941162.427 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:46:05.663Z,1569941165.663 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:46:08.491Z,1569941168.491 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:46:11.727Z,1569941171.727 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:46:14.603Z,1569941174.603 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:46:17.411Z,1569941177.411 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:46:19.399Z,1569941179.399 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:46:21.427Z,1569941181.427 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:46:24.663Z,1569941184.663 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:46:27.491Z,1569941187.491 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:46:30.711Z,1569941190.711 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:46:33.539Z,1569941193.539 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:46:36.778Z,1569941196.778 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:46:39.603Z,1569941199.603 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:46:40.057Z,1569941200.057 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2019-10-01T14:46:40.057Z,1569941200.057 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-01T14:46:40.067Z,1569941200.067 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-01T14:46:40.452Z,1569941200.452 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-01T14:46:40.452Z,1569941200.452 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2019-10-01T14:46:42.435Z,1569941202.435 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:46:43.536Z,1569941203.536 [CommandLine](IMPORTANT): got command quit
2019-10-01T14:46:44.447Z,1569941204.447 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:46:44.543Z,1569941204.543 [Supervisor](INFO): Stop Mission called by Supervisor::terminate
2019-10-01T14:46:44.543Z,1569941204.543 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-01T14:46:44.544Z,1569941204.544 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-10-01T14:46:44.603Z,1569941204.603 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-10-01T14:46:44.603Z,1569941204.603 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-10-01T14:46:44.604Z,1569941204.604 [CommandLine](INFO): Join timeout helper Thread ID is 949
2019-10-01T14:46:44.615Z,1569941204.615 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-10-01T14:46:44.615Z,1569941204.615 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-10-01T14:46:44.615Z,1569941204.615 [NavChartDb](INFO): Join timeout helper Thread ID is 950
2019-10-01T14:46:44.623Z,1569941204.623 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-01T14:46:44.623Z,1569941204.623 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-10-01T14:46:44.642Z,1569941204.642 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-10-01T14:46:44.642Z,1569941204.642 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-10-01T14:46:44.643Z,1569941204.643 [Radio_Surface](INFO): Join timeout helper Thread ID is 951
2019-10-01T14:46:44.651Z,1569941204.651 [Radio_Surface](INFO): Powering down
2019-10-01T14:46:44.652Z,1569941204.652 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-01T14:46:44.652Z,1569941204.652 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-10-01T14:46:44.655Z,1569941204.655 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-10-01T14:46:44.655Z,1569941204.655 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-10-01T14:46:44.655Z,1569941204.655 [DataOverHttps](INFO): Join timeout helper Thread ID is 952
2019-10-01T14:46:46.989Z,1569941206.989 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-01T14:46:46.992Z,1569941206.992 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-10-01T14:46:47.007Z,1569941207.007 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2019-10-01T14:46:47.007Z,1569941207.007 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-10-01T14:46:47.007Z,1569941207.007 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 953
2019-10-01T14:46:47.439Z,1569941207.439 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-01T14:46:47.440Z,1569941207.440 [WetLabsBB2FL](INFO): Powering down
2019-10-01T14:46:47.440Z,1569941207.440 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-10-01T14:46:47.452Z,1569941207.452 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2019-10-01T14:46:47.452Z,1569941207.452 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-10-01T14:46:47.452Z,1569941207.452 [CTD_Seabird](INFO): Join timeout helper Thread ID is 954
2019-10-01T14:46:47.687Z,1569941207.687 [NAL9602](DEBUG): Fix Requested
2019-10-01T14:46:48.399Z,1569941208.399 [CTD_Seabird](INFO): Powering down
2019-10-01T14:46:48.411Z,1569941208.411 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-01T14:46:48.411Z,1569941208.411 [CTD_Seabird](INFO): Powering down
2019-10-01T14:46:48.423Z,1569941208.423 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-10-01T14:46:48.436Z,1569941208.436 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-10-01T14:46:48.437Z,1569941208.437 [logger ThreadHandler](INFO): Thread cancelled.
2019-10-01T14:46:48.437Z,1569941208.437 [logger](INFO): Join timeout helper Thread ID is 955
2019-10-01T14:46:48.437Z,1569941208.437 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-01T14:46:48.438Z,1569941208.438 [logger ThreadHandler](INFO): Thread cancelled.
2019-10-01T14:46:48.439Z,1569941208.439 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-10-01T14:46:48.439Z,1569941208.439 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-10-01T14:46:48.439Z,1569941208.439 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-10-01T14:46:48.439Z,1569941208.439 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-10-01T14:46:48.440Z,1569941208.440 [controlThread](INFO): Join timeout helper Thread ID is 956
2019-10-01T14:46:48.455Z,1569941208.455 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-01T14:46:48.455Z,1569941208.455 [controlThread](DEBUG): Uninitializing ControlThread
2019-10-01T14:46:48.456Z,1569941208.456 [Aanderaa_O2](INFO): Powering down
2019-10-01T14:46:48.476Z,1569941208.476 [AHRS_M2](INFO): Powering down
2019-10-01T14:46:48.619Z,1569941208.619 [DVL_micro](INFO): Powering down
2019-10-01T14:46:48.620Z,1569941208.620 [NAL9602](INFO): Powering down
2019-10-01T14:46:48.621Z,1569941208.621 [DAT](INFO): Powering down
2019-10-01T14:46:48.740Z,1569941208.740 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-10-01T14:46:48.741Z,1569941208.741 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-10-01T14:46:48.741Z,1569941208.741 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-10-01T14:46:48.742Z,1569941208.742 [MissionManager](INFO): Uninitializing Mission Default
2019-10-01T14:46:48.742Z,1569941208.742 [Default] Stopped
2019-10-01T14:46:48.742Z,1569941208.742 [Default](DEBUG): Aggregate::uninitialize Default
2019-10-01T14:46:48.742Z,1569941208.742 [Default:B.GoToSurface] Stopped
2019-10-01T14:46:48.742Z,1569941208.742 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-10-01T14:46:48.742Z,1569941208.742 [Default:CheckIn] Stopped
2019-10-01T14:46:48.742Z,1569941208.742 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-01T14:46:48.742Z,1569941208.742 [Default:CheckIn:Read_GPS] Stopped
2019-10-01T14:46:48.744Z,1569941208.744 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-10-01T14:46:48.745Z,1569941208.745 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-10-01T14:46:48.745Z,1569941208.745 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-10-01T14:46:48.745Z,1569941208.745 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-10-01T14:46:48.746Z,1569941208.746 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-10-01T14:46:48.746Z,1569941208.746 [BuoyancyServo](INFO): Powering down
2019-10-01T14:46:48.759Z,1569941208.759 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-10-01T14:46:48.759Z,1569941208.759 [ElevatorServo](INFO): Powering down
2019-10-01T14:46:48.760Z,1569941208.760 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-10-01T14:46:48.760Z,1569941208.760 [MassServo](INFO): Powering down
2019-10-01T14:46:48.761Z,1569941208.761 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-10-01T14:46:48.761Z,1569941208.761 [RudderServo](INFO): Powering down
2019-10-01T14:46:48.762Z,1569941208.762 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-10-01T14:46:48.762Z,1569941208.762 [ThrusterServo](INFO): Powering down
2019-10-01T14:46:48.762Z,1569941208.762 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-10-01T14:46:48.763Z,1569941208.763 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-10-01T14:46:48.763Z,1569941208.763 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-10-01T14:46:48.763Z,1569941208.763 [CBIT](DEBUG): Powering off loads.
2019-10-01T14:46:48.775Z,1569941208.775 [CBIT](DEBUG): Disabling WDT.
2019-10-01T14:46:48.787Z,1569941208.787 [CBIT](DEBUG): Opening all GF detection circuits.
2019-10-01T14:46:48.788Z,1569941208.788 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-10-01T14:46:48.835Z,1569941208.835 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2019-10-01T14:46:48.841Z,1569941208.841 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2019-10-01T14:46:48.902Z,1569941208.902 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-10-01T14:46:48.908Z,1569941208.908 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-10-01T14:46:48.958Z,1569941208.958 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-10-01T14:46:49.013Z,1569941209.013 [logger ThreadHandler](INFO): Thread cancelled.