2020-07-08T17:50:25.214Z,1594230625.214 [Supervisor](DEBUG): Initializing supervisor.
2020-07-08T17:50:25.217Z,1594230625.217 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-07-08T17:50:25.218Z,1594230625.218 [SyncHandler](INFO): Protected caller Thread ID is 3261
2020-07-08T17:50:25.218Z,1594230625.218 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-07-08T17:50:25.219Z,1594230625.219 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-07-08T17:50:25.220Z,1594230625.220 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 3262
2020-07-08T17:50:25.223Z,1594230625.223 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-07-08T17:50:25.236Z,1594230625.236 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-07-08T17:50:25.237Z,1594230625.237 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-07-08T17:50:25.238Z,1594230625.238 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 3263
2020-07-08T17:50:25.238Z,1594230625.238 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-07-08T17:50:25.239Z,1594230625.239 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-07-08T17:50:25.240Z,1594230625.240 [logger ThreadHandler](INFO): Protected caller Thread ID is 3264
2020-07-08T17:50:25.242Z,1594230625.242 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-07-08T17:50:25.242Z,1594230625.242 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-07-08T17:50:25.243Z,1594230625.243 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-07-08T17:50:25.336Z,1594230625.336 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-07-08T17:50:25.337Z,1594230625.337 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-07-08T17:50:25.525Z,1594230625.525 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-07-08T17:50:25.526Z,1594230625.526 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-07-08T17:50:25.724Z,1594230625.724 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-07-08T17:50:25.725Z,1594230625.725 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-07-08T17:50:25.860Z,1594230625.860 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-07-08T17:50:25.861Z,1594230625.861 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-07-08T17:50:26.437Z,1594230626.437 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-07-08T17:50:26.438Z,1594230626.438 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-07-08T17:50:26.886Z,1594230626.886 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-07-08T17:50:26.886Z,1594230626.886 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-07-08T17:50:27.339Z,1594230627.339 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-07-08T17:50:27.340Z,1594230627.340 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-07-08T17:50:27.632Z,1594230627.632 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-07-08T17:50:27.633Z,1594230627.633 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-07-08T17:50:27.952Z,1594230627.952 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-07-08T17:50:27.952Z,1594230627.952 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-07-08T17:50:28.356Z,1594230628.356 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-07-08T17:50:28.357Z,1594230628.357 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-07-08T17:50:28.516Z,1594230628.516 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-07-08T17:50:28.516Z,1594230628.516 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-07-08T17:50:29.087Z,1594230629.087 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-07-08T17:50:29.088Z,1594230629.088 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-07-08T17:50:29.168Z,1594230629.168 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-07-08T17:50:29.267Z,1594230629.267 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-07-08T17:50:29.268Z,1594230629.268 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-07-08T17:50:29.450Z,1594230629.450 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-07-08T17:50:29.450Z,1594230629.450 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-07-08T17:50:29.653Z,1594230629.653 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-07-08T17:50:29.655Z,1594230629.655 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/
2020-07-08T17:50:29.656Z,1594230629.656 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/secure.cfg
2020-07-08T17:50:29.738Z,1594230629.738 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Battery.cfg
2020-07-08T17:50:29.968Z,1594230629.968 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-07-08T17:50:29.969Z,1594230629.969 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Navigation.cfg
2020-07-08T17:50:30.061Z,1594230630.061 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Sensor.cfg
2020-07-08T17:50:30.219Z,1594230630.219 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/vehicle.cfg
2020-07-08T17:50:30.413Z,1594230630.413 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Simulator.cfg
2020-07-08T17:50:30.496Z,1594230630.496 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Servo.cfg
2020-07-08T17:50:30.594Z,1594230630.594 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Control.cfg
2020-07-08T17:50:30.683Z,1594230630.683 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/Science.cfg
2020-07-08T17:50:30.829Z,1594230630.829 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/logger.cfg
2020-07-08T17:50:30.930Z,1594230630.930 [Supervisor](INFO): Opening Config file at: Config/lrauv-makai/BIT.cfg
2020-07-08T17:50:31.027Z,1594230631.027 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-makai/root/
2020-07-08T17:50:31.027Z,1594230631.027 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-07-08T17:50:31.032Z,1594230631.032 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-07-08T17:50:31.127Z,1594230631.127 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-07-08T17:50:31.128Z,1594230631.128 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-07-08T17:50:31.275Z,1594230631.275 [BuoyancyServo] Loaded
2020-07-08T17:50:31.275Z,1594230631.275 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-07-08T17:50:31.307Z,1594230631.307 [ElevatorServo] Loaded
2020-07-08T17:50:31.307Z,1594230631.307 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-07-08T17:50:31.337Z,1594230631.337 [MassServo] Loaded
2020-07-08T17:50:31.337Z,1594230631.337 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-07-08T17:50:31.368Z,1594230631.368 [RudderServo] Loaded
2020-07-08T17:50:31.368Z,1594230631.368 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-07-08T17:50:31.398Z,1594230631.398 [ThrusterServo] Loaded
2020-07-08T17:50:31.398Z,1594230631.398 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-07-08T17:50:31.399Z,1594230631.399 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-07-08T17:50:31.399Z,1594230631.399 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-07-08T17:50:31.533Z,1594230631.533 [SBIT](DEBUG): Construct Startup Built In Test.
2020-07-08T17:50:31.560Z,1594230631.560 [SBIT] Loaded
2020-07-08T17:50:31.561Z,1594230631.561 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-07-08T17:50:31.561Z,1594230631.561 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-07-08T17:50:31.590Z,1594230631.590 [IBIT] Loaded
2020-07-08T17:50:31.590Z,1594230631.590 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-07-08T17:50:31.593Z,1594230631.593 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-07-08T17:50:31.973Z,1594230631.973 [CBIT] Loaded
2020-07-08T17:50:31.974Z,1594230631.974 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-07-08T17:50:31.974Z,1594230631.974 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-07-08T17:50:31.975Z,1594230631.975 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-07-08T17:50:32.186Z,1594230632.186 [Aanderaa_O2] Loaded
2020-07-08T17:50:32.186Z,1594230632.186 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2020-07-08T17:50:32.214Z,1594230632.214 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-07-08T17:50:32.219Z,1594230632.219 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-07-08T17:50:32.220Z,1594230632.220 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-07-08T17:50:32.225Z,1594230632.225 [CTD_Seabird](INFO): created writer for : depth
2020-07-08T17:50:32.225Z,1594230632.225 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-07-08T17:50:32.230Z,1594230632.230 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-07-08T17:50:32.231Z,1594230632.231 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-07-08T17:50:32.236Z,1594230632.236 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-07-08T17:50:32.237Z,1594230632.237 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-07-08T17:50:32.242Z,1594230632.242 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-07-08T17:50:32.242Z,1594230632.242 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-07-08T17:50:32.248Z,1594230632.248 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-07-08T17:50:32.248Z,1594230632.248 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-07-08T17:50:32.253Z,1594230632.253 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-07-08T17:50:32.279Z,1594230632.279 [CTD_Seabird] Loaded
2020-07-08T17:50:32.280Z,1594230632.280 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-07-08T17:50:32.281Z,1594230632.281 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 406764E0
2020-07-08T17:50:32.281Z,1594230632.281 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 3346
2020-07-08T17:50:32.330Z,1594230632.330 [ESPComponent] Loaded
2020-07-08T17:50:32.330Z,1594230632.330 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2020-07-08T17:50:32.360Z,1594230632.360 [PAR_Licor] Loaded
2020-07-08T17:50:32.360Z,1594230632.360 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-07-08T17:50:32.381Z,1594230632.381 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-07-08T17:50:32.382Z,1594230632.382 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-07-08T17:50:32.386Z,1594230632.386 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-07-08T17:50:32.386Z,1594230632.386 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-07-08T17:50:32.390Z,1594230632.390 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-07-08T17:50:32.390Z,1594230632.390 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-07-08T17:50:32.394Z,1594230632.395 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-07-08T17:50:32.395Z,1594230632.395 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-07-08T17:50:32.399Z,1594230632.399 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-07-08T17:50:32.399Z,1594230632.399 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-07-08T17:50:32.403Z,1594230632.403 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-07-08T17:50:32.403Z,1594230632.403 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-07-08T17:50:32.407Z,1594230632.407 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-07-08T17:50:32.407Z,1594230632.407 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-07-08T17:50:32.411Z,1594230632.411 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-07-08T17:50:32.416Z,1594230632.416 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-07-08T17:50:32.416Z,1594230632.416 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-07-08T17:50:32.417Z,1594230632.417 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-07-08T17:50:32.421Z,1594230632.421 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-07-08T17:50:32.421Z,1594230632.421 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-07-08T17:50:32.425Z,1594230632.425 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-07-08T17:50:32.425Z,1594230632.425 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-07-08T17:50:32.429Z,1594230632.429 [WetLabsBB2FL] Loaded
2020-07-08T17:50:32.430Z,1594230632.430 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-07-08T17:50:32.431Z,1594230632.431 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 406A64E0
2020-07-08T17:50:32.431Z,1594230632.431 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 3347
2020-07-08T17:50:32.432Z,1594230632.432 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-07-08T17:50:32.432Z,1594230632.432 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-07-08T17:50:32.472Z,1594230632.472 [DepthRateCalculator] Loaded
2020-07-08T17:50:32.472Z,1594230632.472 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-07-08T17:50:32.477Z,1594230632.477 [PitchRateCalculator] Loaded
2020-07-08T17:50:32.478Z,1594230632.478 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-07-08T17:50:32.493Z,1594230632.493 [SpeedCalculator] Loaded
2020-07-08T17:50:32.493Z,1594230632.493 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-07-08T17:50:32.514Z,1594230632.514 [TempGradientCalculator] Loaded
2020-07-08T17:50:32.514Z,1594230632.514 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-07-08T17:50:32.519Z,1594230632.519 [YawRateCalculator] Loaded
2020-07-08T17:50:32.520Z,1594230632.520 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-07-08T17:50:32.552Z,1594230632.552 [ElevatorOffsetCalculator] Loaded
2020-07-08T17:50:32.552Z,1594230632.552 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-07-08T17:50:32.553Z,1594230632.553 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-07-08T17:50:32.553Z,1594230632.553 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-07-08T17:50:32.630Z,1594230632.630 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2020-07-08T17:50:32.631Z,1594230632.631 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-07-08T17:50:32.657Z,1594230632.657 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-07-08T17:50:32.658Z,1594230632.658 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-07-08T17:50:32.772Z,1594230632.772 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-07-08T17:50:32.772Z,1594230632.772 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-07-08T17:50:32.793Z,1594230632.793 [NavChart] Loaded
2020-07-08T17:50:32.793Z,1594230632.793 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-07-08T17:50:32.797Z,1594230632.797 [UniversalFixResidualReporter] Loaded
2020-07-08T17:50:32.797Z,1594230632.797 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-07-08T17:50:32.798Z,1594230632.798 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-07-08T17:50:32.798Z,1594230632.798 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-07-08T17:50:33.102Z,1594230633.102 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-07-08T17:50:33.107Z,1594230633.107 [AHRS_M2](INFO): created writer for : platform_orientation
2020-07-08T17:50:33.108Z,1594230633.108 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-07-08T17:50:33.113Z,1594230633.113 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-07-08T17:50:33.113Z,1594230633.113 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-07-08T17:50:33.118Z,1594230633.118 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-07-08T17:50:33.119Z,1594230633.119 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-07-08T17:50:33.124Z,1594230633.124 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-07-08T17:50:33.197Z,1594230633.197 [AHRS_M2] Loaded
2020-07-08T17:50:33.197Z,1594230633.197 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-07-08T17:50:33.274Z,1594230633.274 [DataOverHttps] Loaded
2020-07-08T17:50:33.274Z,1594230633.274 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-07-08T17:50:33.275Z,1594230633.275 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 409C64E0
2020-07-08T17:50:33.276Z,1594230633.276 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 3348
2020-07-08T17:50:33.305Z,1594230633.305 [Depth_Keller] Loaded
2020-07-08T17:50:33.305Z,1594230633.305 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-07-08T17:50:33.310Z,1594230633.310 [DropWeight] Loaded
2020-07-08T17:50:33.310Z,1594230633.310 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2020-07-08T17:50:33.373Z,1594230633.373 [DVL_micro] Loaded
2020-07-08T17:50:33.373Z,1594230633.373 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2020-07-08T17:50:33.478Z,1594230633.478 [NAL9602] Loaded
2020-07-08T17:50:33.479Z,1594230633.479 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-07-08T17:50:33.516Z,1594230633.516 [Onboard] Loaded
2020-07-08T17:50:33.516Z,1594230633.516 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2020-07-08T17:50:33.517Z,1594230633.517 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 409F64E0
2020-07-08T17:50:33.517Z,1594230633.517 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 3349
2020-07-08T17:50:33.537Z,1594230633.537 [Radio_Surface] Loaded
2020-07-08T17:50:33.537Z,1594230633.537 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-07-08T17:50:33.538Z,1594230633.538 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 40A264E0
2020-07-08T17:50:33.538Z,1594230633.538 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 3350
2020-07-08T17:50:33.767Z,1594230633.767 [DAT] Loaded
2020-07-08T17:50:33.769Z,1594230633.769 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2020-07-08T17:50:35.172Z,1594230635.172 [BPC1] Loaded
2020-07-08T17:50:35.172Z,1594230635.172 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-07-08T17:50:35.173Z,1594230635.173 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-07-08T17:50:35.173Z,1594230635.173 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-07-08T17:50:35.253Z,1594230635.253 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-07-08T17:50:35.254Z,1594230635.254 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-07-08T17:50:35.303Z,1594230635.303 [VerticalControl](DEBUG): Construct VerticalControl.
2020-07-08T17:50:35.386Z,1594230635.386 [VerticalControl] Loaded
2020-07-08T17:50:35.386Z,1594230635.386 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-07-08T17:50:35.387Z,1594230635.387 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-07-08T17:50:35.455Z,1594230635.455 [HorizontalControl] Loaded
2020-07-08T17:50:35.456Z,1594230635.456 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-07-08T17:50:35.456Z,1594230635.456 [SpeedControl](DEBUG): Construct SpeedControl.
2020-07-08T17:50:35.458Z,1594230635.458 [SpeedControl] Loaded
2020-07-08T17:50:35.458Z,1594230635.458 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-07-08T17:50:35.459Z,1594230635.459 [LoopControl](DEBUG): Construct LoopControl.
2020-07-08T17:50:35.459Z,1594230635.459 [LoopControl] Loaded
2020-07-08T17:50:35.460Z,1594230635.460 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-07-08T17:50:35.460Z,1594230635.460 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-07-08T17:50:35.461Z,1594230635.461 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-07-08T17:50:35.479Z,1594230635.479 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-07-08T17:50:35.480Z,1594230635.480 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-07-08T17:50:35.760Z,1594230635.760 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-07-08T17:50:35.763Z,1594230635.763 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-07-08T17:50:35.764Z,1594230635.764 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-07-08T17:50:35.771Z,1594230635.771 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-07-08T17:50:35.772Z,1594230635.772 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B5D4E0
2020-07-08T17:50:35.772Z,1594230635.772 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 3351
2020-07-08T17:50:35.777Z,1594230635.777 [Supervisor](INFO): Main Thread ID is 3123
2020-07-08T17:50:35.777Z,1594230635.777 [Supervisor](DEBUG): Running supervisor.
2020-07-08T17:50:35.778Z,1594230635.778 [CommandLine ThreadHandler](INFO): Handler Thread ID is 3352
2020-07-08T17:50:35.780Z,1594230635.780 [controlThread ThreadHandler](INFO): Handler Thread ID is 3353
2020-07-08T17:50:35.780Z,1594230635.780 [controlThread](DEBUG): Initializing ControlThread
2020-07-08T17:50:35.782Z,1594230635.782 [SBIT](INFO): Initialize SBIT Component.
2020-07-08T17:50:35.783Z,1594230635.783 [SBIT](IMPORTANT): git: 2020-07-06
2020-07-08T17:50:35.783Z,1594230635.783 [SBIT](INFO): git hash: 27d6d613a5802cbc38609e1e9bc8b2bacbda1d86
2020-07-08T17:50:35.783Z,1594230635.783 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-07-08T17:50:35.785Z,1594230635.785 [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-08T17:50:35.786Z,1594230635.786 [SBIT](INFO): Beginning SBIT in 72.000000 seconds.
2020-07-08T17:50:35.786Z,1594230635.786 [IBIT](INFO): Initialize IBIT Component.
2020-07-08T17:50:35.787Z,1594230635.787 [CBIT](DEBUG): Initialize CBIT Component.
2020-07-08T17:50:35.788Z,1594230635.788 [logger ThreadHandler](INFO): Handler Thread ID is 3354
2020-07-08T17:50:35.801Z,1594230635.801 [CBIT](DEBUG): Initialized mux pins.
2020-07-08T17:50:35.801Z,1594230635.801 [CBIT](DEBUG): Initializing the watchdog timer.
2020-07-08T17:50:35.809Z,1594230635.809 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 3355
2020-07-08T17:50:35.810Z,1594230635.810 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-07-08T17:50:35.814Z,1594230635.814 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 3357
2020-07-08T17:50:35.815Z,1594230635.815 [WetLabsBB2FL](INFO): Powering down
2020-07-08T17:50:35.825Z,1594230635.825 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-07-08T17:50:35.825Z,1594230635.825 [CBIT](DEBUG): Initializing heartbeat.
2020-07-08T17:50:35.849Z,1594230635.849 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 3358
2020-07-08T17:50:35.850Z,1594230635.850 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-07-08T17:50:35.861Z,1594230635.861 [Onboard ThreadHandler](INFO): Handler Thread ID is 3359
2020-07-08T17:50:35.878Z,1594230635.878 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 3360
2020-07-08T17:50:35.897Z,1594230635.897 [CBIT](DEBUG): Deactivating GF circuits.
2020-07-08T17:50:35.897Z,1594230635.897 [CBIT](DEBUG): Deactivating emergency mode.
2020-07-08T17:50:35.898Z,1594230635.898 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 3361
2020-07-08T17:50:35.900Z,1594230635.900 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-07-08T17:50:35.901Z,1594230635.901 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-07-08T17:50:35.901Z,1594230635.901 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-07-08T17:50:35.901Z,1594230635.901 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-07-08T17:50:35.901Z,1594230635.901 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-07-08T17:50:35.901Z,1594230635.901 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-07-08T17:50:35.902Z,1594230635.902 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-07-08T17:50:35.902Z,1594230635.902 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-07-08T17:50:35.902Z,1594230635.902 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-07-08T17:50:35.902Z,1594230635.902 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-07-08T17:50:35.902Z,1594230635.902 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-07-08T17:50:35.902Z,1594230635.902 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-07-08T17:50:35.903Z,1594230635.903 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-07-08T17:50:35.903Z,1594230635.903 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-07-08T17:50:35.903Z,1594230635.903 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-07-08T17:50:35.903Z,1594230635.903 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-07-08T17:50:35.933Z,1594230635.933 [CBIT](DEBUG): Backplane powered.
2020-07-08T17:50:35.937Z,1594230635.937 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-07-08T17:50:35.938Z,1594230635.938 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-07-08T17:50:35.938Z,1594230635.938 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-07-08T17:50:35.938Z,1594230635.938 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-07-08T17:50:35.939Z,1594230635.939 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-07-08T17:50:35.939Z,1594230635.939 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-07-08T17:50:35.940Z,1594230635.940 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-07-08T17:50:35.941Z,1594230635.941 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-07-08T17:50:35.947Z,1594230635.947 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-07-08T17:50:35.949Z,1594230635.949 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-07-08T17:50:35.950Z,1594230635.950 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-07-08T17:50:35.950Z,1594230635.950 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-07-08T17:50:35.951Z,1594230635.951 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-07-08T17:50:35.985Z,1594230635.985 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-07-08T17:50:36.012Z,1594230636.012 [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-08T17:50:36.021Z,1594230636.021 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-07-08T17:50:36.107Z,1594230636.107 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-07-08T17:50:36.108Z,1594230636.108 [Default:A.Wait](DEBUG): Construct Wait.
2020-07-08T17:50:36.110Z,1594230636.110 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-07-08T17:50:36.163Z,1594230636.163 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-07-08T17:50:36.166Z,1594230636.166 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-07-08T17:50:36.176Z,1594230636.176 [Default:E.Execute](DEBUG): Construct Execute.
2020-07-08T17:50:36.180Z,1594230636.180 [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-08T17:50:36.185Z,1594230636.185 [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-08T17:50:36.242Z,1594230636.242 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-07-08T17:50:36.293Z,1594230636.293 [Radio_Surface](INFO): Powering up
2020-07-08T17:50:36.376Z,1594230636.376 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-07-08T17:50:36.376Z,1594230636.376 [DAT](INFO): Powering up
2020-07-08T17:50:36.376Z,1594230636.376 [DAT](DEBUG): Initializing DAT.
2020-07-08T17:50:36.385Z,1594230636.385 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-07-08T17:50:36.495Z,1594230636.495 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-07-08T17:50:36.505Z,1594230636.505 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-07-08T17:50:36.506Z,1594230636.506 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-07-08T17:50:36.520Z,1594230636.520 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-07-08T17:50:36.521Z,1594230636.521 [MassServo](DEBUG): Initializing EZServoServo.
2020-07-08T17:50:36.529Z,1594230636.529 [MassServo](DEBUG): Initializing MassServo.
2020-07-08T17:50:36.530Z,1594230636.530 [RudderServo](DEBUG): Initializing EZServoServo.
2020-07-08T17:50:36.537Z,1594230636.537 [RudderServo](DEBUG): Initializing RudderServo.
2020-07-08T17:50:36.538Z,1594230636.538 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-07-08T17:50:36.545Z,1594230636.545 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-07-08T17:50:36.651Z,1594230636.651 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2020-07-08T17:50:36.651Z,1594230636.651 [DropWeight] Hardware Fault, FailCount= 1
2020-07-08T17:50:36.651Z,1594230636.651 [DropWeight](ERROR): Hardware Fault
2020-07-08T17:50:36.722Z,1594230636.722 [CommandLine](FAULT): Scheduling is paused
2020-07-08T17:50:36.723Z,1594230636.723 [CBIT](INFO): Critical error at 20200708T175036
2020-07-08T17:50:36.723Z,1594230636.723 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-07-08T17:50:36.737Z,1594230636.737 [CBIT](ERROR): Hardware Fault in component: DropWeight
2020-07-08T17:50:36.738Z,1594230636.738 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2020-07-08T17:50:37.038Z,1594230637.038 [CBIT](INFO): Critical error at 20200708T175036
2020-07-08T17:50:39.541Z,1594230639.541 [Aanderaa_O2](INFO): Powering down
2020-07-08T17:51:02.814Z,1594230662.814 [CommandLine](IMPORTANT): got command get platform_pitch_angle degree
2020-07-08T17:51:02.814Z,1594230662.814 [CommandLine](IMPORTANT): platform_pitch_angle -2.477635 arcdeg
2020-07-08T17:51:03.681Z,1594230663.681 [NAL9602](INFO): Powering up NAL9602
2020-07-08T17:51:10.543Z,1594230670.543 [CommandLine](IMPORTANT): got command get platform_orientation degree
2020-07-08T17:51:10.543Z,1594230670.543 [CommandLine](IMPORTANT): platform_orientation 260.220809 arcdeg
2020-07-08T17:51:14.583Z,1594230674.583 [NAL9602](INFO): NAL9602 initialized
2020-07-08T17:51:36.820Z,1594230696.820 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2020-07-08T17:51:36.821Z,1594230696.821 [DAT] Communications Fault, FailCount= 1
2020-07-08T17:51:36.821Z,1594230696.821 [DAT](ERROR): Communications Fault
2020-07-08T17:51:36.837Z,1594230696.837 [CBIT](ERROR): Communications Fault in component: DAT
2020-07-08T17:51:37.207Z,1594230697.207 [DAT](INFO): Powering down
2020-07-08T17:51:38.054Z,1594230698.054 [CBIT](INFO): Clearing failed state for component DAT
2020-07-08T17:51:38.101Z,1594230698.101 [DAT] No Fault, FailCount= 1
2020-07-08T17:51:40.442Z,1594230700.442 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-07-08T17:51:40.442Z,1594230700.442 [DAT](INFO): Powering up
2020-07-08T17:51:40.442Z,1594230700.442 [DAT](DEBUG): Initializing DAT.
2020-07-08T17:51:40.454Z,1594230700.454 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-07-08T17:51:48.548Z,1594230708.548 [SBIT](IMPORTANT): Beginning Startup BIT
2020-07-08T17:51:48.552Z,1594230708.552 [CBIT](IMPORTANT): Beginning ground fault scan
2020-07-08T17:51:59.514Z,1594230719.514 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.009660
CHAN A1 (24V): 0.001272
CHAN A2 (12V): -0.004672
CHAN A3 (5V): -0.002628
CHAN B0 (3.3V): -0.001235
CHAN B1 (3.15aV): -0.001437
CHAN B2 (3.15bV): -0.000828
CHAN B3 (GND): 0.000108
OPEN: 0.007109
Full Scale Calc: 4.765 mA, -1.589 mA
2020-07-08T17:52:34.934Z,1594230754.934 [CommandLine](IMPORTANT): got command run 
2020-07-08T17:52:34.935Z,1594230754.935 [CommandLine](FAULT): Incomplete syntax. Try: help run
2020-07-08T17:52:36.838Z,1594230756.838 [CommandLine](IMPORTANT): got command run ./Missions/Insert/Science.xml
2020-07-08T17:52:36.839Z,1594230756.839 [MissionManager](INFO): Loading Mission: ./Missions/Insert/Science.xml
2020-07-08T17:52:37.044Z,1594230757.044 [MissionManager](INFO): DefineArg Science.SampleISUS = 0 bool
2020-07-08T17:52:37.047Z,1594230757.047 [MissionManager](INFO): DefineArg Science.SampleISUSmaxPitch = 60.000000 arcdeg
2020-07-08T17:52:37.058Z,1594230757.058 [MissionManager](INFO): DefineArg Science.SampleISUSminPitch = -60.000000 arcdeg
2020-07-08T17:52:37.094Z,1594230757.094 [MissionManager](INFO): DefineArg Science.PeakDetectChlActive = 0 bool
2020-07-08T17:52:37.098Z,1594230757.098 [MissionManager](INFO): DefineArg Science.TimeWindowPeakReport = nan min
2020-07-08T17:52:37.100Z,1594230757.100 [MissionManager](INFO): DefineArg Science.HighestChlPeakReportActive = 0 bool
2020-07-08T17:52:37.108Z,1594230757.108 [MissionManager](INFO): DefineArg Science.PatchTracking = 0 bool
2020-07-08T17:52:37.112Z,1594230757.112 [MissionManager](INFO): DefineArg Science.FilterWidthHorizontal = 3.000000 count
2020-07-08T17:52:37.123Z,1594230757.123 [MissionManager](INFO): DefineArg Science.NumProfilesSlidingwindow = 100.000000 count
2020-07-08T17:52:37.131Z,1594230757.131 [MissionManager](INFO): DefineArg Science.OffPeakFractionHorizontal = 80.000000 %
2020-07-08T17:52:37.140Z,1594230757.140 [MissionManager](INFO): DefineArg Science.PeakDetectNO3Active = 0 bool
2020-07-08T17:52:37.151Z,1594230757.151 [MissionManager](INFO): DefineArg Science.PeakDetectOilActive = 0 bool
2020-07-08T17:52:37.154Z,1594230757.154 [MissionManager](INFO): DefineArg Science.PeakDetectFDOMActive = 0 bool
2020-07-08T17:52:37.161Z,1594230757.161 [MissionManager](INFO): DefineArg Science.UpwardDerivativeOfTemperatureActive = 0 bool
2020-07-08T17:52:37.167Z,1594230757.167 [MissionManager](INFO): DefineArg Science.EnabledAanderaaO2 = 1.000000 bool
2020-07-08T17:52:37.182Z,1594230757.182 [MissionManager](INFO): DefineArg Science.EnabledNeilBrown = 0.000000 bool
2020-07-08T17:52:37.186Z,1594230757.186 [MissionManager](INFO): DefineArg Science.EnabledSeabird = 1.000000 bool
2020-07-08T17:52:37.202Z,1594230757.202 [MissionManager](INFO): DefineArg Science.EnabledTurbulence_NPS = 0.000000 bool
2020-07-08T17:52:37.207Z,1594230757.207 [MissionManager](INFO): DefineArg Science.EnabledWetLabsBB2FL = 1.000000 bool
2020-07-08T17:52:37.223Z,1594230757.223 [MissionManager](INFO): DefineArg Science.EnabledWetLabsSeaOWL_UV_A = 0.000000 bool
2020-07-08T17:52:37.231Z,1594230757.231 [MissionManager](INFO): DefineArg Science.EnabledWetLabsUBAT = 0.000000 bool
2020-07-08T17:52:37.235Z,1594230757.235 [MissionManager](INFO): DefineArg Science.EnabledISUS = 0.000000 bool
2020-07-08T17:52:37.246Z,1594230757.246 [MissionManager](INFO): DefineArg Science.LowPassWindowLength = 20.000000 count
2020-07-08T17:52:37.250Z,1594230757.250 [MissionManager](INFO): DefineArg Science.MedianFilterLen = 5.000000 count
2020-07-08T17:52:37.262Z,1594230757.262 [MissionManager](INFO): DefineArg Science.PeakChlShallowBound = nan m
2020-07-08T17:52:37.264Z,1594230757.264 [MissionManager](INFO): DefineArg Science.PeakChlDeepBound = nan m
2020-07-08T17:52:37.272Z,1594230757.272 [MissionManager](INFO): DefineArg Science.PeakShallowBound = nan m
2020-07-08T17:52:37.276Z,1594230757.276 [MissionManager](INFO): DefineArg Science.PeakDeepBound = nan m
2020-07-08T17:52:37.288Z,1594230757.288 [MissionManager](INFO): DefineOutput Science.PeakChl = 0.000000 ug/l
2020-07-08T17:52:37.304Z,1594230757.304 [MissionManager](INFO): DefineOutput Science.PeakChlDepth = 0.000000 m
2020-07-08T17:52:37.307Z,1594230757.307 [MissionManager](INFO): DefineOutput Science.PeakChlTemperature = 0.000000 degC
2020-07-08T17:52:37.310Z,1594230757.310 [MissionManager](INFO): DefineOutput Science.PeakChlLatitude = nan arcdeg
2020-07-08T17:52:37.337Z,1594230757.337 [MissionManager](INFO): DefineOutput Science.PeakChlLongitude = nan arcdeg
2020-07-08T17:52:37.340Z,1594230757.340 [MissionManager](INFO): DefineOutput Science.PeakNO3 = 0.000000 umol/l
2020-07-08T17:52:37.343Z,1594230757.343 [MissionManager](INFO): DefineOutput Science.PeakNO3Depth = 0.000000 m
2020-07-08T17:52:37.359Z,1594230757.359 [MissionManager](INFO): DefineOutput Science.PeakNO3Latitude = nan arcdeg
2020-07-08T17:52:37.362Z,1594230757.362 [MissionManager](INFO): DefineOutput Science.PeakNO3Longitude = nan arcdeg
2020-07-08T17:52:37.366Z,1594230757.366 [MissionManager](INFO): DefineOutput Science.PatchChl = 0.000000 m
2020-07-08T17:52:37.413Z,1594230757.413 [MissionManager](INFO): DefineOutput Science.PatchChlDepth = 0.000000 m
2020-07-08T17:52:37.443Z,1594230757.443 [MissionManager](INFO): DefineOutput Science.PatchChlLatitude = nan arcdeg
2020-07-08T17:52:37.465Z,1594230757.465 [MissionManager](INFO): DefineOutput Science.PatchChlLongitude = nan arcdeg
2020-07-08T17:52:37.468Z,1594230757.468 [MissionManager](INFO): DefineOutput Science.PatchChlDistance = nan m
2020-07-08T17:52:37.480Z,1594230757.480 [MissionManager](INFO): DefineOutput Science.PeakOil = 0.000000 kg/m3
2020-07-08T17:52:37.487Z,1594230757.487 [MissionManager](INFO): DefineOutput Science.PeakOilDepth = 0.000000 m
2020-07-08T17:52:37.499Z,1594230757.499 [MissionManager](INFO): DefineOutput Science.PeakOilLatitude = nan arcdeg
2020-07-08T17:52:37.502Z,1594230757.502 [MissionManager](INFO): DefineOutput Science.PeakOilLongitude = nan arcdeg
2020-07-08T17:52:37.510Z,1594230757.510 [MissionManager](INFO): DefineOutput Science.PeakFDOM = 0.000000 ppb
2020-07-08T17:52:37.517Z,1594230757.517 [MissionManager](INFO): DefineOutput Science.PeakFDOMDepth = 0.000000 m
2020-07-08T17:52:37.525Z,1594230757.525 [MissionManager](INFO): DefineOutput Science.PeakFDOMLatitude = nan arcdeg
2020-07-08T17:52:37.528Z,1594230757.528 [MissionManager](INFO): DefineOutput Science.PeakFDOMLongitude = nan arcdeg
2020-07-08T17:52:37.544Z,1594230757.544 [MissionManager](INFO): DefineOutput Science.PatchFDOMDistance = nan m
2020-07-08T17:52:37.592Z,1594230757.592 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-07-08T17:52:37.616Z,1594230757.616 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2020-07-08T17:52:37.654Z,1594230757.654 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-07-08T17:52:37.686Z,1594230757.686 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-07-08T17:52:37.710Z,1594230757.710 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-07-08T17:52:37.835Z,1594230757.835 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Aggregate xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
           xmlns="Tethys"
           xmlns:Units="Tethys/Units"
           xmlns:Science="Tethys/Science"
           xmlns:Universal="Tethys/Universal"
           xmlns:Trigger="Tethys/Trigger"
           xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                               Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                               Tethys/Science http://okeanids.mbari.org/tethys/Xml/Science.xsd
                               Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd
                               Tethys/Trigger http://okeanids.mbari.org/tethys/Xml/Trigger.xsd"
       Id="Science">

    <DefineArg Name="SampleISUS"><Description>
        Set either this or PeakDetectNO3Active to True in order to sample
        Nitrate. EnabledISUS must also be true.
    </Description><False/></DefineArg>

    <DefineArg Name="SampleISUSmaxPitch"><Description>
        Maximum pitch to sample ISUS. (EnabledISUS must also be true.)
    </Description><Units:degree/><Value>60.0</Value></DefineArg>

    <DefineArg Name="SampleISUSminPitch"><Description>
        Minimum pitch to sample ISUS. (EnabledISUS must also be true.)
    </Description><Units:degree/><Value>-60.0</Value></DefineArg>

    <DefineArg Name="PeakDetectChlActive"><Description>
        Turns on peak detection of Cholorphyll.
    </Description><False/></DefineArg>

    <DefineArg Name="TimeWindowPeakReport"><Description>
        If greater than zero, report a peak every window. If NaN or zero, this
        variable is ignored.
    </Description><Units:minute/><Value>NaN</Value></DefineArg>

    <DefineArg Name="HighestChlPeakReportActive"><Description>
        Turns on reporting of the highest peak value on yo-yo profiles in a
        horizontal sliding window (of length numProfilesSlidingwindow)
    </Description><False/></DefineArg>

    <DefineArg Name="PatchTracking"><Description>
        If tracking a patch
    </Description><False/></DefineArg>

<!--debug
    </Description><True/></DefineArg>
-->

    <DefineArg Name="FilterWidthHorizontal"><Description>
        Width of boxcar filter applied to yoyo-wise chl peaks to pick out the
        highest peak.
    </Description><Units:count/><Value>3</Value></DefineArg>

    <DefineArg Name="NumProfilesSlidingwindow"><Description>
        Length of horizontal sliding window. The highest yoyo-wise chl peak
        (after low-pass filtering by a filter of length FilterWidthHorizontal)
        within this window.
    </Description><Units:count/><Value>100</Value></DefineArg>

    <DefineArg Name="OffPeakFractionHorizontal"><Description>
        When filtered horizontal value is this fraction of the peak, consider it
        outside the patch.
    </Description><Units:percent/><Value>80</Value></DefineArg>

    <DefineArg Name="PeakDetectNO3Active"><Description>
        Turns on peak detection of Nitrate (and turns on ISUS).
    </Description><False/></DefineArg>

    <DefineArg Name="PeakDetectOilActive"><Description>
        Turns on peak detection of Dissolved Oil.
    </Description><False/></DefineArg>

    <DefineArg Name="PeakDetectFDOMActive"><Description>
        Turns on peak detection of concentration_of_chromophoric_dissolved_organic_matter_in_sea_water from FDOM sensor.
    </Description><False/></DefineArg>
    
    <DefineArg Name="UpwardDerivativeOfTemperatureActive"><Description>
        Turns on seawater temperature derivative.
    </Description><False/></DefineArg>

    <DefineArg Name="EnabledAanderaaO2"><Description>
        Automatically set to True if the Aanderaa O2 sensor is installed. Set to
        false to disable reading Aandera O2.
    </Description><Science:Aanderaa_O2.loadAtStartup/></DefineArg>

    <DefineArg Name="EnabledNeilBrown"><Description>
        Automatically set to true if the Neil Brown CTD is enabled. Set to false
        to disable reading from the Neil Brown.
    </Description><Science:CTD_NeilBrown.loadAtStartup/></DefineArg>

    <DefineArg Name="EnabledSeabird"><Description>
        Automatically set to true if the CTD is enabled. Set to false to disable
        reading from the CTD.
    </Description><Science:CTD_Seabird.loadAtStartup/></DefineArg>

    <DefineArg Name="EnabledTurbulence_NPS"><Description>
        Automatically set to true if the NPS Turbulence sensor is enabled. Set
        to false to disable reading from the NPS Turbulence sensor.
    </Description><Science:Turbulence_NPS.loadAtStartup/></DefineArg>

    <DefineArg Name="EnabledWetLabsBB2FL"><Description>
        Automatically set to true if the the WetLabs BB2FL is enabled. Set to
        false to disable the WetLabs BB2FL.
    </Description><Science:WetLabsBB2FL.loadAtStartup/></DefineArg>

    <DefineArg Name="EnabledWetLabsSeaOWL_UV_A"><Description>
        Automatically set to true if the the WetLabs SeaOWL UV-A is enabled. Set
        to false to disable the WetLabs SeaOWL UV-A.
    </Description><Science:WetLabsSeaOWL_UV_A.loadAtStartup/></DefineArg>

    <DefineArg Name="EnabledWetLabsUBAT"><Description>
        Automatically set to true if the the WetLabs UBAT is enabled. Set
        to false to disable the WetLabs UBAT.
    </Description><Science:WetLabsUBAT.loadAtStartup/></DefineArg>    

    <DefineArg Name="EnabledISUS"><Description>
        Automatically set to True if ISUS is enabled. Set to false to disable
        ISUS.
    </Description><Science:ISUS.loadAtStartup/></DefineArg>

    <DefineArg Name="LowPassWindowLength"><Description>
        Low-pass window length (based on depth sensor sampling interval 0.4
        second) for low-pass filtering.
    </Description><Units:count/><Value>20</Value></DefineArg>

    <DefineArg Name="MedianFilterLen"><Description>
        Median filter length (only for chlorophyll fluorescence which tends to have spikes)
    </Description><Units:count/><Value>5</Value></DefineArg>

    <DefineArg Name="PeakChlShallowBound"><Description>
        Shallow depth bound for detecting chl peak on each descent or ascent
        profile.
    </Description><Units:meter/><Value>NaN</Value></DefineArg>

<!--debug
    </Description><Units:meter/><Value>10</Value></DefineArg>
-->

    <DefineArg Name="PeakChlDeepBound"><Description>
        Deep depth bound for detecting chl peak on each descent or ascent
        profile.
    </Description><Units:meter/><Value>NaN</Value></DefineArg>

<!--debug
    </Description><Units:meter/><Value>20</Value></DefineArg>
-->

    <DefineArg Name="PeakShallowBound"><Description>
        Shallow depth bound for detecting any peak on each descent or ascent
        profile.
    </Description><Units:meter/><Value>NaN</Value></DefineArg>

    <DefineArg Name="PeakDeepBound"><Description>
        Deep depth bound for detecting any peak on each descent or ascent
        profile.
    </Description><Units:meter/><Value>NaN</Value></DefineArg>

    <DefineOutput Name="PeakChl"><Units:microgram_per_liter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakChlDepth"><Units:meter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakChlTemperature"><Units:celsius/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakChlLatitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PeakChlLongitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PeakNO3"><Units:micromole_per_liter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakNO3Depth"><Units:meter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakNO3Latitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PeakNO3Longitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PatchChl"><Units:meter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PatchChlDepth"><Units:meter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PatchChlLatitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PatchChlLongitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PatchChlDistance"><Units:meter/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PeakOil"><Units:kilogram_per_cubic_meter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakOilDepth"><Units:meter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakOilLatitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PeakOilLongitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PeakFDOM"><Units:part_per_billion/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakFDOMDepth"><Units:meter/><Value>0</Value></DefineOutput>

    <DefineOutput Name="PeakFDOMLatitude"><Units:degree/><Value>NaN</Value></DefineOutput>

    <DefineOutput Name="PeakFDOMLongitude"><Units:degree/><Value>NaN</Value></DefineOutput>
    
    <DefineOutput Name="PatchFDOMDistance"><Units:meter/><Value>NaN</Value></DefineOutput>    
    
    <Parallel/>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledAanderaaO2"/>
        </While>
        <Universal:mass_concentration_of_oxygen_in_sea_water/>
    </ReadData>

    <ReadData>
        <While>
            <Arg Name="EnabledISUS"/>
            <And><Universal:platform_pitch_angle/><Lt><Arg Name="SampleISUSmaxPitch"/></Lt><And><Universal:platform_pitch_angle/><Gt><Arg Name="SampleISUSminPitch"/></Gt><And><Arg Name="SampleISUS"/><Or><Arg Name="PeakDetectNO3Active"/></Or></And></And></And>
        </While>
        <Universal:mole_concentration_of_nitrate_in_sea_water/>
    </ReadData>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledNeilBrown"/>
            <Or><Arg Name="EnabledSeabird"/></Or>
        </While>
        <Universal:sea_water_temperature/>
        <Universal:sea_water_salinity/>
    </ReadData>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledSeabird"/>
        </While>
        <Science:CTD_Seabird.sea_water_temperature/>
        <Science:CTD_Seabird.sea_water_salinity/>
    </ReadData>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledNeilBrown"/>
            <Or><Arg Name="EnabledSeabird"/></Or>
            <And><Arg Name="UpwardDerivativeOfTemperatureActive"/></And>
        </While>
        <Universal:upward_derivative_of_sea_water_temperature/>
    </ReadData>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledTurbulence_NPS"/>
        </While>
        <Science:Turbulence_NPS.MicroCond/>
    </ReadData>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledWetLabsBB2FL"/>
            <Or><Arg Name="PeakDetectChlActive"/></Or>
        </While>
        <Universal:mass_concentration_of_chlorophyll_in_sea_water/>
    </ReadData>

    <ReadData Strategy="MinError">
        <While>
            <Arg Name="EnabledWetLabsUBAT"/>
        </While>
        <Science:WetLabsUBAT.biolum_potential/>
    </ReadData>

<!--debug-->

    <ReadData Id="Read_Oil" Strategy="MinError">
        <While>
            <Arg Name="EnabledWetLabsSeaOWL_UV_A"/>
            <Or><Arg Name="PeakDetectOilActive"/></Or>
        </While>
        <Universal:mass_concentration_of_chlorophyll_in_sea_water/>
    </ReadData>

    <Aggregate Id="PeakDetectChl">

        <While>
            <Arg Name="PeakDetectChlActive"/>
        </While>

        <Trigger:PeakDetectVsDepth>
            <Sequence/>
            <Setting><Trigger:PeakDetectVsDepth.detect/><Universal:mass_concentration_of_chlorophyll_in_sea_water/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.timeWindowPeakReport/><Arg Name="TimeWindowPeakReport"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.windowLength/><Arg Name="LowPassWindowLength"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.medianFilterLength/><Arg Name="MedianFilterLen"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.shallowBound/><Arg Name="PeakChlShallowBound"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.deepBound/><Arg Name="PeakChlDeepBound"/></Setting>
            <OutputArg Name="PeakChl"><Trigger:PeakDetectVsDepth.peakDetect/></OutputArg>
            <OutputArg Name="PeakChlDepth"><Trigger:PeakDetectVsDepth.peakDepth/></OutputArg>
            <OutputArg Name="PeakChlTemperature"><Trigger:PeakDetectVsDepth.peakTemperature/></OutputArg>
            <OutputArg Name="PeakChlLatitude"><Trigger:PeakDetectVsDepth.peakLatitude/></OutputArg>
            <OutputArg Name="PeakChlLongitude"><Trigger:PeakDetectVsDepth.peakLongitude/></OutputArg>
        </Trigger:PeakDetectVsDepth>

<!--debug
        <Syslog Severity="Important">PeakChl, PeakChlLatitude, PeakChlLongitude = <Arg Name="PeakChl"/><Units:microgram_per_liter/>, <Arg Name="PeakChlLatitude"/><Units:degree/>, <Arg Name="PeakChlLongitude"/><Units:degree/></Syslog>
-->

        <SendData Service="Express"><Arg Name="PeakChl"/><Arg Name="PeakChlDepth"/>
        </SendData>

    </Aggregate>

    <Aggregate Id="HighestChlPeakReport">

        <While>
            <Arg Name="HighestChlPeakReportActive"/>
        </While>

        <Trigger:PeakDetectHorizontal>
            <Sequence/>
            <Setting><Trigger:PeakDetectHorizontal.detectFrom/><Arg Name="PeakChl"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.depthFrom/><Arg Name="PeakChlDepth"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.latitudeFrom/><Arg Name="PeakChlLatitude"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.longitudeFrom/><Arg Name="PeakChlLongitude"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.patchTracking/><Arg Name="PatchTracking"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.filterWidth/><Arg Name="FilterWidthHorizontal"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.numProfilesSlidingwindow/><Arg Name="NumProfilesSlidingwindow"/></Setting>
            <Setting><Trigger:PeakDetectHorizontal.offPeakFraction/><Arg Name="OffPeakFractionHorizontal"/></Setting>
            <OutputArg Name="PatchChl"><Trigger:PeakDetectHorizontal.peakDetect/></OutputArg>
            <OutputArg Name="PatchChlDepth"><Trigger:PeakDetectHorizontal.peakDepth/></OutputArg>
            <OutputArg Name="PatchChlLatitude"><Trigger:PeakDetectHorizontal.peakLatitude/></OutputArg>
            <OutputArg Name="PatchChlLongitude"><Trigger:PeakDetectHorizontal.peakLongitude/></OutputArg>
            <OutputArg Name="PatchChlDistance"><Trigger:PeakDetectHorizontal.peakDistance/></OutputArg>
        </Trigger:PeakDetectHorizontal>

    </Aggregate>

    <Aggregate Id="PeakDetectNO3">

        <While>
            <Arg Name="PeakDetectNO3Active"/>
        </While>

        <Trigger:PeakDetectVsDepth>
            <Sequence/>
            <Setting><Trigger:PeakDetectVsDepth.detect/><Universal:mole_concentration_of_nitrate_in_sea_water/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.timeWindowPeakReport/><Arg Name="TimeWindowPeakReport"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.windowLength/><Arg Name="LowPassWindowLength"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.shallowBound/><Arg Name="PeakShallowBound"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.deepBound/><Arg Name="PeakDeepBound"/></Setting>
            <OutputArg Name="PeakNO3"><Trigger:PeakDetectVsDepth.peakDetect/></OutputArg>
            <OutputArg Name="PeakNO3Depth"><Trigger:PeakDetectVsDepth.peakDepth/></OutputArg>
            <OutputArg Name="PeakNO3Latitude"><Trigger:PeakDetectVsDepth.peakLatitude/></OutputArg>
            <OutputArg Name="PeakNO3Longitude"><Trigger:PeakDetectVsDepth.peakLongitude/></OutputArg>
        </Trigger:PeakDetectVsDepth>

        <SendData Service="Express"><Arg Name="PeakNO3"/><Arg Name="PeakNO3Depth"/>
        </SendData>

    </Aggregate>

    <Aggregate Id="PeakDetectOil">

        <While>
            <Arg Name="PeakDetectOilActive"/>
        </While>

        <Trigger:PeakDetectVsDepth>
            <Sequence/>
            <Setting><Trigger:PeakDetectVsDepth.detect/><Universal:mass_concentration_of_petroleum_hydrocarbons_in_sea_water/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.timeWindowPeakReport/><Arg Name="TimeWindowPeakReport"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.windowLength/><Arg Name="LowPassWindowLength"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.shallowBound/><Arg Name="PeakShallowBound"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.deepBound/><Arg Name="PeakDeepBound"/></Setting>
            <OutputArg Name="PeakOil"><Trigger:PeakDetectVsDepth.peakDetect/></OutputArg>
            <OutputArg Name="PeakOilDepth"><Trigger:PeakDetectVsDepth.peakDepth/></OutputArg>
            <OutputArg Name="PeakOilLatitude"><Trigger:PeakDetectVsDepth.peakLatitude/></OutputArg>
            <OutputArg Name="PeakOilLongitude"><Trigger:PeakDetectVsDepth.peakLongitude/></OutputArg>
        </Trigger:PeakDetectVsDepth>

        <SendData Service="Express"><Arg Name="PeakOil"/><Arg Name="PeakOilDepth"/>
        </SendData>

    </Aggregate>

    <Aggregate Id="PeakDetectFDOM">

        <While>
            <Arg Name="PeakDetectFDOMActive"/>
        </While>

        <Trigger:PeakDetectVsDepth>
            <Sequence/>
            <Setting><Trigger:PeakDetectVsDepth.detect/><Universal:concentration_of_chromophoric_dissolved_organic_matter_in_sea_water/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.timeWindowPeakReport/><Arg Name="TimeWindowPeakReport"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.windowLength/><Arg Name="LowPassWindowLength"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.shallowBound/><Arg Name="PeakShallowBound"/></Setting>
            <Setting><Trigger:PeakDetectVsDepth.deepBound/><Arg Name="PeakDeepBound"/></Setting>
            <OutputArg Name="PeakFDOM"><Trigger:PeakDetectVsDepth.peakDetect/></OutputArg>
            <OutputArg Name="PeakFDOMDepth"><Trigger:PeakDetectVsDepth.peakDepth/></OutputArg>
            <OutputArg Name="PeakFDOMTemperature"><Trigger:PeakDetectVsDepth.peakTemperature/></OutputArg>
            <OutputArg Name="PeakFDOMLatitude"><Trigger:PeakDetectVsDepth.peakLatitude/></OutputArg>
            <OutputArg Name="PeakFDOMLongitude"><Trigger:PeakDetectVsDepth.peakLongitude/></OutputArg>
        </Trigger:PeakDetectVsDepth>

        <SendData Service="Express"><Arg Name="PeakFDOM"/><Arg Name="PeakFDOMDepth"/>
        </SendData>

    </Aggregate>

</Aggregate>


2020-07-08T17:52:37.845Z,1594230757.845 [CommandLine](IMPORTANT): Running ./Missions/Insert/Science.xml
2020-07-08T17:52:40.639Z,1594230760.639 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2020-07-08T17:52:40.639Z,1594230760.639 [DAT] Communications Fault, FailCount= 2
2020-07-08T17:52:40.639Z,1594230760.639 [DAT](ERROR): Communications Fault
2020-07-08T17:52:40.656Z,1594230760.656 [CBIT](ERROR): Communications Fault in component: DAT
2020-07-08T17:52:41.038Z,1594230761.038 [DAT](INFO): Powering down
2020-07-08T17:52:41.884Z,1594230761.884 [CBIT](INFO): Clearing failed state for component DAT
2020-07-08T17:52:41.884Z,1594230761.884 [DAT] No Fault, FailCount= 2
2020-07-08T17:52:42.736Z,1594230762.736 [SBIT](IMPORTANT): SBIT PASSED
2020-07-08T17:52:42.736Z,1594230762.736 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-07-08T17:52:42.737Z,1594230762.737 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2020-07-08T17:52:42.737Z,1594230762.737 [SBIT](IMPORTANT): BuoyancyServo.checkingTimeout=30 second;

2020-07-08T17:52:42.737Z,1594230762.737 [SBIT](IMPORTANT): CBIT.gf24Offset=148.1 microampere;

2020-07-08T17:52:43.076Z,1594230763.076 [MissionManager](IMPORTANT): Started mission Startup
2020-07-08T17:52:43.076Z,1594230763.076 [Startup] Running Loop=1
2020-07-08T17:52:43.076Z,1594230763.076 [Startup](DEBUG): Aggregate::initialize Startup
2020-07-08T17:52:43.076Z,1594230763.076 [Startup:A.GoToSurface] Running Loop=1
2020-07-08T17:52:43.076Z,1594230763.076 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-07-08T17:52:43.077Z,1594230763.077 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-07-08T17:52:43.077Z,1594230763.077 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-07-08T17:52:43.078Z,1594230763.078 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-07-08T17:52:43.078Z,1594230763.078 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-07-08T17:52:43.078Z,1594230763.078 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-07-08T17:52:43.080Z,1594230763.080 [Startup:StartupSatComms] Running Loop=1
2020-07-08T17:52:43.080Z,1594230763.080 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-07-08T17:52:43.080Z,1594230763.080 [Startup:StartupSatComms:A] Running Loop=1
2020-07-08T17:52:43.474Z,1594230763.474 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-07-08T17:52:44.270Z,1594230764.270 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-07-08T17:52:44.270Z,1594230764.270 [DAT](INFO): Powering up
2020-07-08T17:52:44.270Z,1594230764.270 [DAT](DEBUG): Initializing DAT.
2020-07-08T17:52:44.345Z,1594230764.345 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-07-08T17:52:52.238Z,1594230772.238 [CommandLine](IMPORTANT): got command failComponent
2020-07-08T17:52:52.239Z,1594230772.239 [CommandLine](IMPORTANT): Failed components:
2020-07-08T17:52:52.239Z,1594230772.239 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2020-07-08T17:52:53.955Z,1594230773.955 [CommandLine](IMPORTANT): got command show stack
2020-07-08T17:52:53.955Z,1594230773.955 [CommandLine](IMPORTANT): Behavior Stack:

2020-07-08T17:52:53.955Z,1594230773.955 [Startup](IMPORTANT): Priority 0: Startup:A.GoToSurface
2020-07-08T17:52:53.955Z,1594230773.955 [Startup:StartupSatComms](IMPORTANT): Priority 1: Startup:StartupSatComms:A
2020-07-08T17:53:00.535Z,1594230780.535 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2020-07-08T17:53:10.495Z,1594230790.495 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.799999 degree
2020-07-08T17:53:10.496Z,1594230790.496 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread.
2020-07-08T17:53:10.497Z,1594230790.497 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121.900002 degree
2020-07-08T17:53:10.498Z,1594230790.498 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread.
2020-07-08T17:53:10.613Z,1594230790.613 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,ESPComponent,PAR_Licor,AHRS_M2,Depth_Keller,DropWeight,DVL_micro,NAL9602,DAT,BPC1,PAR_Licor,Depth_Keller,Maintain_NAL9602.latitude_fix,Maintain_NAL9602.longitude_fix,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-08T17:53:10.955Z,1594230790.955 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-07-08T17:53:10.956Z,1594230790.956 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-07-08T17:53:10.956Z,1594230790.956 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-08T17:53:10.957Z,1594230790.957 [Startup:StartupSatComms:A] Stopped
2020-07-08T17:53:10.957Z,1594230790.957 [Startup:StartupSatComms:B] Running Loop=1
2020-07-08T17:53:10.966Z,1594230790.966 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-08T17:53:11.369Z,1594230791.369 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-07-08T17:53:11.378Z,1594230791.378 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-08T17:53:11.378Z,1594230791.378 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-07-08T17:53:12.226Z,1594230792.226 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-07-08T17:53:12.226Z,1594230792.226 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-07-08T17:53:12.226Z,1594230792.226 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-08T17:53:12.276Z,1594230792.276 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-08T17:53:12.618Z,1594230792.618 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-08T17:53:12.618Z,1594230792.618 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-07-08T17:53:13.414Z,1594230793.414 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-07-08T17:53:13.414Z,1594230793.414 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2020-07-08T17:53:13.414Z,1594230793.414 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-08T17:53:13.424Z,1594230793.424 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-08T17:53:13.851Z,1594230793.851 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-08T17:53:13.852Z,1594230793.852 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2020-07-08T17:53:14.786Z,1594230794.786 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-07-08T17:53:14.786Z,1594230794.786 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2020-07-08T17:53:14.786Z,1594230794.786 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-08T17:53:14.840Z,1594230794.840 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-08T17:53:15.076Z,1594230795.076 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-08T17:53:15.076Z,1594230795.076 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2020-07-08T17:53:15.853Z,1594230795.853 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-07-08T17:53:15.854Z,1594230795.854 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2020-07-08T17:53:15.854Z,1594230795.854 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-08T17:53:15.874Z,1594230795.874 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-08T17:53:16.245Z,1594230796.245 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-08T17:53:16.245Z,1594230796.245 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2020-07-08T17:53:17.034Z,1594230797.034 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-07-08T17:53:17.034Z,1594230797.034 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2020-07-08T17:53:17.035Z,1594230797.035 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-08T17:53:17.087Z,1594230797.087 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-08T17:53:17.449Z,1594230797.449 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-08T17:53:17.449Z,1594230797.449 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2020-07-08T17:53:18.282Z,1594230798.282 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-07-08T17:53:18.282Z,1594230798.282 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2020-07-08T17:53:18.282Z,1594230798.282 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-08T17:53:18.322Z,1594230798.322 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-08T17:53:18.675Z,1594230798.675 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-08T17:53:18.675Z,1594230798.675 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2020-07-08T17:53:19.466Z,1594230799.466 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-07-08T17:53:19.466Z,1594230799.466 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2020-07-08T17:53:19.466Z,1594230799.466 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-08T17:53:19.476Z,1594230799.476 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-08T17:53:19.872Z,1594230799.872 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-08T17:53:19.872Z,1594230799.872 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2020-07-08T17:53:19.968Z,1594230799.968 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005241
2020-07-08T17:53:20.730Z,1594230800.730 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-07-08T17:53:20.730Z,1594230800.730 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2020-07-08T17:53:20.730Z,1594230800.730 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-08T17:53:20.740Z,1594230800.740 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-08T17:53:21.134Z,1594230801.134 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-08T17:53:21.134Z,1594230801.134 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2020-07-08T17:53:21.894Z,1594230801.894 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-07-08T17:53:21.894Z,1594230801.894 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2020-07-08T17:53:21.894Z,1594230801.894 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-08T17:53:21.904Z,1594230801.904 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-08T17:53:22.298Z,1594230802.298 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-08T17:53:22.298Z,1594230802.298 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2020-07-08T17:53:23.146Z,1594230803.146 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-07-08T17:53:23.146Z,1594230803.146 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2020-07-08T17:53:23.146Z,1594230803.146 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-07-08T17:53:23.201Z,1594230803.201 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-07-08T17:53:23.520Z,1594230803.520 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-07-08T17:53:23.520Z,1594230803.520 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2020-07-08T17:53:23.670Z,1594230803.670 [CommandLine](IMPORTANT): got command set NAL9602.time_fix 1594230784.000000 second
2020-07-08T17:53:28.436Z,1594230808.436 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20200708T174357/Courier0004.lzma
2020-07-08T17:53:29.439Z,1594230809.439 [DataOverHttps](INFO): Moved sent file to Logs/20200708T174357/Courier0004.lzma.bak
2020-07-08T17:53:29.439Z,1594230809.439 [DataOverHttps](INFO): SBD MOMSN=12425712
2020-07-08T17:53:32.591Z,1594230812.591 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2020-07-08T17:53:32.819Z,1594230812.819 [Startup:StartupSatComms:B] Stopped
2020-07-08T17:53:32.819Z,1594230812.819 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-07-08T17:53:32.819Z,1594230812.819 [Startup:StartupSatComms] Stopped
2020-07-08T17:53:32.819Z,1594230812.819 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-07-08T17:53:32.820Z,1594230812.820 [Startup](INFO): Completed Startup
2020-07-08T17:53:32.820Z,1594230812.820 [MissionManager](INFO): Startup is completed.
2020-07-08T17:53:32.820Z,1594230812.820 [MissionManager](INFO): Uninitializing Mission Startup
2020-07-08T17:53:32.820Z,1594230812.820 [Startup] Stopped
2020-07-08T17:53:32.821Z,1594230812.821 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-07-08T17:53:32.821Z,1594230812.821 [Startup:A.GoToSurface] Stopped
2020-07-08T17:53:32.821Z,1594230812.821 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-07-08T17:53:33.352Z,1594230813.352 [MissionManager](IMPORTANT): Started mission Science
2020-07-08T17:53:33.352Z,1594230813.352 [Science] Running Loop=1
2020-07-08T17:53:33.352Z,1594230813.352 [Science](DEBUG): Aggregate::initialize Science
2020-07-08T17:53:33.352Z,1594230813.352 [Science:A] Running Loop=1
2020-07-08T17:53:33.352Z,1594230813.352 [Science:B] Running Loop=1
2020-07-08T17:53:33.352Z,1594230813.352 [Science:C] Running Loop=1
2020-07-08T17:53:33.352Z,1594230813.352 [Science:D] Running Loop=1
2020-07-08T17:53:33.352Z,1594230813.352 [Science:E] Running Loop=1
2020-07-08T17:53:33.357Z,1594230813.357 [Science:F] Running Loop=1
2020-07-08T17:53:33.357Z,1594230813.357 [Science:G] Running Loop=1
2020-07-08T17:53:33.357Z,1594230813.357 [Science:H] Running Loop=1
2020-07-08T17:53:33.357Z,1594230813.357 [Science:Read_Oil] Running Loop=1
2020-07-08T17:53:33.357Z,1594230813.357 [Science:PeakDetectChl] Running Loop=1
2020-07-08T17:53:33.357Z,1594230813.357 [Science:PeakDetectChl](DEBUG): Aggregate::initialize Science:PeakDetectChl
2020-07-08T17:53:33.357Z,1594230813.357 [Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2020-07-08T17:53:33.357Z,1594230813.357 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-07-08T17:53:33.359Z,1594230813.359 [Science:HighestChlPeakReport] Running Loop=1
2020-07-08T17:53:33.359Z,1594230813.359 [Science:HighestChlPeakReport](DEBUG): Aggregate::initialize Science:HighestChlPeakReport
2020-07-08T17:53:33.359Z,1594230813.359 [Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2020-07-08T17:53:33.360Z,1594230813.360 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2020-07-08T17:53:33.360Z,1594230813.360 [Science:PeakDetectNO3] Running Loop=1
2020-07-08T17:53:33.360Z,1594230813.360 [Science:PeakDetectNO3](DEBUG): Aggregate::initialize Science:PeakDetectNO3
2020-07-08T17:53:33.393Z,1594230813.393 [Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2020-07-08T17:53:33.393Z,1594230813.393 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-07-08T17:53:33.395Z,1594230813.395 [Science:PeakDetectOil] Running Loop=1
2020-07-08T17:53:33.395Z,1594230813.395 [Science:PeakDetectOil](DEBUG): Aggregate::initialize Science:PeakDetectOil
2020-07-08T17:53:33.395Z,1594230813.395 [Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2020-07-08T17:53:33.395Z,1594230813.395 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-07-08T17:53:33.397Z,1594230813.397 [Science:PeakDetectFDOM] Running Loop=1
2020-07-08T17:53:33.397Z,1594230813.397 [Science:PeakDetectFDOM](DEBUG): Aggregate::initialize Science:PeakDetectFDOM
2020-07-08T17:53:33.397Z,1594230813.397 [Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2020-07-08T17:53:33.398Z,1594230813.398 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-07-08T17:53:33.400Z,1594230813.400 [Science:PeakDetectFDOM] Stopped
2020-07-08T17:53:33.400Z,1594230813.400 [Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize Science:PeakDetectFDOM
2020-07-08T17:53:33.400Z,1594230813.400 [Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2020-07-08T17:53:33.400Z,1594230813.400 [Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-07-08T17:53:33.400Z,1594230813.400 [Science:PeakDetectOil] Stopped
2020-07-08T17:53:33.417Z,1594230813.417 [Science:PeakDetectOil](DEBUG): Aggregate::uninitialize Science:PeakDetectOil
2020-07-08T17:53:33.417Z,1594230813.417 [Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2020-07-08T17:53:33.417Z,1594230813.417 [Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-07-08T17:53:33.417Z,1594230813.417 [Science:PeakDetectNO3] Stopped
2020-07-08T17:53:33.418Z,1594230813.418 [Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize Science:PeakDetectNO3
2020-07-08T17:53:33.418Z,1594230813.418 [Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2020-07-08T17:53:33.418Z,1594230813.418 [Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-07-08T17:53:33.418Z,1594230813.418 [Science:HighestChlPeakReport] Stopped
2020-07-08T17:53:33.418Z,1594230813.418 [Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize Science:HighestChlPeakReport
2020-07-08T17:53:33.418Z,1594230813.418 [Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2020-07-08T17:53:33.418Z,1594230813.418 [Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2020-07-08T17:53:33.418Z,1594230813.418 [Science:PeakDetectChl] Stopped
2020-07-08T17:53:33.418Z,1594230813.418 [Science:PeakDetectChl](DEBUG): Aggregate::uninitialize Science:PeakDetectChl
2020-07-08T17:53:33.418Z,1594230813.418 [Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2020-07-08T17:53:33.418Z,1594230813.418 [Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-07-08T17:53:33.418Z,1594230813.418 [Science:Read_Oil] Stopped
2020-07-08T17:53:33.418Z,1594230813.418 [Science:H] Stopped
2020-07-08T17:53:33.418Z,1594230813.418 [Science:G] Running Loop=1
2020-07-08T17:53:33.419Z,1594230813.419 [Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2020-07-08T17:53:33.419Z,1594230813.419 [Science:F] Stopped
2020-07-08T17:53:33.419Z,1594230813.419 [Science:E] Stopped
2020-07-08T17:53:33.419Z,1594230813.419 [Science:D] Running Loop=1
2020-07-08T17:53:33.420Z,1594230813.420 [Science:D](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2020-07-08T17:53:33.421Z,1594230813.421 [Science:D](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2020-07-08T17:53:33.421Z,1594230813.421 [Science:C] Running Loop=1
2020-07-08T17:53:33.422Z,1594230813.422 [Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2020-07-08T17:53:33.422Z,1594230813.422 [Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2020-07-08T17:53:33.422Z,1594230813.422 [Science:B] Stopped
2020-07-08T17:53:33.422Z,1594230813.422 [Science:A] Running Loop=1
2020-07-08T17:53:33.423Z,1594230813.423 [Science:A](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_oxygen_in_sea_water
2020-07-08T17:53:33.794Z,1594230813.794 [WetLabsBB2FL](INFO): Powering up
2020-07-08T17:53:36.546Z,1594230816.546 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2020-07-08T17:53:44.286Z,1594230824.286 [CommandLine](IMPORTANT): got command gfscan
2020-07-08T17:53:44.341Z,1594230824.341 [DAT](FAULT): failed to initialize, no bytes available on serial interface
2020-07-08T17:53:44.341Z,1594230824.341 [DAT] Communications Fault, FailCount= 3
2020-07-08T17:53:44.341Z,1594230824.341 [DAT](ERROR): Communications Fault
2020-07-08T17:53:44.427Z,1594230824.427 [CBIT](ERROR): Communications Fault in component: DAT
2020-07-08T17:53:44.428Z,1594230824.428 [CBIT](IMPORTANT): Beginning ground fault scan
2020-07-08T17:53:44.591Z,1594230824.591 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2020-07-08T17:53:44.731Z,1594230824.731 [DAT](INFO): Powering down
2020-07-08T17:53:45.576Z,1594230825.576 [CBIT](INFO): Clearing failed state for component DAT
2020-07-08T17:53:45.576Z,1594230825.576 [DAT] No Fault, FailCount= 3
2020-07-08T17:53:45.595Z,1594230825.595 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2020-07-08T17:53:45.942Z,1594230825.942 [DVL_micro](ERROR): only read 3 of 4 data items
2020-07-08T17:53:45.942Z,1594230825.942 [DVL_micro](ERROR): Failed to parse:
:BI,+0217,-00992,+00000,I
2020-07-08T17:53:46.413Z,1594230826.413 [DataOverHttps](INFO): Sending 244 bytes from file Logs/20200708T175025/Courier0000.lzma
2020-07-08T17:53:46.599Z,1594230826.599 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2020-07-08T17:53:47.415Z,1594230827.415 [DataOverHttps](INFO): Moved sent file to Logs/20200708T175025/Courier0000.lzma.bak
2020-07-08T17:53:47.415Z,1594230827.415 [DataOverHttps](INFO): SBD MOMSN=12425714
2020-07-08T17:53:47.602Z,1594230827.602 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2020-07-08T17:53:47.964Z,1594230827.964 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-07-08T17:53:47.964Z,1594230827.964 [DAT](INFO): Powering up
2020-07-08T17:53:47.965Z,1594230827.965 [DAT](DEBUG): Initializing DAT.
2020-07-08T17:53:47.994Z,1594230827.994 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-07-08T17:53:48.607Z,1594230828.607 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2020-07-08T17:53:49.611Z,1594230829.611 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2020-07-08T17:53:50.615Z,1594230830.615 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2020-07-08T17:53:51.619Z,1594230831.619 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2020-07-08T17:53:52.623Z,1594230832.623 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2020-07-08T17:53:53.627Z,1594230833.627 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2020-07-08T17:53:54.631Z,1594230834.631 [CTD_Seabird](INFO): Ground Fault scan is active; will mark data as invalid.
2020-07-08T17:53:55.363Z,1594230835.363 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.009068
CHAN A1 (24V): 0.001349
CHAN A2 (12V): -0.004976
CHAN A3 (5V): -0.002808
CHAN B0 (3.3V): -0.001326
CHAN B1 (3.15aV): -0.000608
CHAN B2 (3.15bV): -0.000434
CHAN B3 (GND): -0.000201
OPEN: 0.007057
Full Scale Calc: 4.765 mA, -1.589 mA
2020-07-08T17:53:58.402Z,1594230838.402 [CommandLine](IMPORTANT): got command stop
2020-07-08T17:53:58.403Z,1594230838.403 [CommandLine](IMPORTANT): Scheduling is paused
2020-07-08T17:53:58.403Z,1594230838.403 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2020-07-08T17:53:58.556Z,1594230838.556 [MissionManager](INFO): MissionManager is completed.
2020-07-08T17:53:58.556Z,1594230838.556 [MissionManager](INFO): Uninitializing Mission Science
2020-07-08T17:53:58.556Z,1594230838.556 [Science] Stopped
2020-07-08T17:53:58.556Z,1594230838.556 [Science](DEBUG): Aggregate::uninitialize Science
2020-07-08T17:53:58.556Z,1594230838.556 [Science:A] Stopped
2020-07-08T17:53:58.556Z,1594230838.556 [Science:C] Stopped
2020-07-08T17:53:58.556Z,1594230838.556 [Science:D] Stopped
2020-07-08T17:53:58.556Z,1594230838.556 [Science:G] Stopped
2020-07-08T17:53:58.870Z,1594230838.870 [MissionManager](IMPORTANT): Started mission Default
2020-07-08T17:53:58.871Z,1594230838.871 [Default] Running Loop=1
2020-07-08T17:53:58.871Z,1594230838.871 [Default](DEBUG): Aggregate::initialize Default
2020-07-08T17:53:58.871Z,1594230838.871 [Default:B.GoToSurface] Running Loop=1
2020-07-08T17:53:58.871Z,1594230838.871 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-07-08T17:53:58.871Z,1594230838.871 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-07-08T17:53:58.872Z,1594230838.872 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-07-08T17:53:58.872Z,1594230838.872 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-07-08T17:53:58.872Z,1594230838.872 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-07-08T17:53:58.872Z,1594230838.872 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-07-08T17:53:58.873Z,1594230838.873 [Default:A.Wait] Running Loop=1
2020-07-08T17:53:58.873Z,1594230838.873 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-07-08T17:53:59.097Z,1594230839.097 [WetLabsBB2FL](INFO): Powering down
2020-07-08T17:53:59.365Z,1594230839.365 [Aanderaa_O2](INFO): Powering down
2020-07-08T17:53:59.966Z,1594230839.966 [CommandLine](IMPORTANT): got command failComponent
2020-07-08T17:53:59.967Z,1594230839.967 [CommandLine](IMPORTANT): Failed components:
2020-07-08T17:53:59.967Z,1594230839.967 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2020-07-08T17:54:06.558Z,1594230846.558 [CommandLine](IMPORTANT): got command get DAT.uart
2020-07-08T17:54:06.558Z,1594230846.558 [CommandLine](IMPORTANT): DAT.uart /dev/ttyB3
2020-07-08T17:54:11.388Z,1594230851.388 [DVL_micro](ERROR): Failed to parse:
:WI,+00090,-00419,-00903,+00000,A
2020-07-08T17:54:12.233Z,1594230852.233 [Default:A.Wait](INFO): Done Waiting.
2020-07-08T17:54:12.234Z,1594230852.234 [Default:A.Wait] Stopped
2020-07-08T17:54:12.234Z,1594230852.234 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-07-08T17:54:12.646Z,1594230852.646 [Default:CheckIn] Running Loop=1
2020-07-08T17:54:12.646Z,1594230852.646 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-07-08T17:54:12.646Z,1594230852.646 [Default:CheckIn:Read_GPS] Running Loop=1
2020-07-08T17:54:13.049Z,1594230853.049 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-07-08T17:54:25.453Z,1594230865.453 [CommandLine](IMPORTANT): got command failComponent
2020-07-08T17:54:25.454Z,1594230865.454 [CommandLine](IMPORTANT): Failed components:
2020-07-08T17:54:25.454Z,1594230865.454 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2020-07-08T17:54:27.903Z,1594230867.903 [CommandLine](IMPORTANT): got command failComponent hardware DAT
2020-07-08T17:54:27.903Z,1594230867.903 [DAT] Hardware Fault, FailCount= 4
2020-07-08T17:54:27.903Z,1594230867.903 [DAT](ERROR): Hardware Fault
2020-07-08T17:54:27.903Z,1594230867.903 [CommandLine](IMPORTANT): DAT failureMode is Hardware Fault
2020-07-08T17:54:27.960Z,1594230867.960 [DAT](INFO): Powering down
2020-07-08T17:54:28.193Z,1594230868.193 [CBIT](ERROR): Hardware Fault in component: DAT
2020-07-08T17:54:28.809Z,1594230868.809 [CBIT](INFO): Clearing failed state for component DAT
2020-07-08T17:54:28.810Z,1594230868.810 [DAT] No Fault, FailCount= 4
2020-07-08T17:54:31.199Z,1594230871.199 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-07-08T17:54:31.199Z,1594230871.199 [DAT](INFO): Powering up
2020-07-08T17:54:31.200Z,1594230871.200 [DAT](DEBUG): Initializing DAT.
2020-07-08T17:54:31.206Z,1594230871.206 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-07-08T17:54:45.320Z,1594230885.320 [DAT](INFO): commRate: 800
2020-07-08T17:54:45.320Z,1594230885.320 [DAT](INFO): commRate: 800
2020-07-08T17:54:45.747Z,1594230885.747 [DAT](INFO): entering command mode
2020-07-08T17:54:46.130Z,1594230886.130 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-08T17:54:46.562Z,1594230886.562 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-08T17:54:46.949Z,1594230886.949 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-08T17:54:47.353Z,1594230887.353 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-08T17:54:47.753Z,1594230887.753 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-08T17:54:48.146Z,1594230888.146 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-08T17:54:48.554Z,1594230888.554 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-08T17:54:48.960Z,1594230888.960 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-08T17:54:49.374Z,1594230889.374 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-08T17:54:49.766Z,1594230889.766 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-08T17:54:50.162Z,1594230890.162 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-08T17:54:50.575Z,1594230890.575 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-08T17:54:50.974Z,1594230890.974 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-08T17:54:51.378Z,1594230891.378 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-08T17:54:51.805Z,1594230891.805 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-08T17:54:52.201Z,1594230892.201 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-08T17:54:52.613Z,1594230892.613 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-08T17:54:53.005Z,1594230893.005 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-08T17:54:53.398Z,1594230893.398 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-08T17:54:53.807Z,1594230893.807 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-08T17:54:54.222Z,1594230894.222 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-08T17:54:54.610Z,1594230894.610 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-08T17:54:55.019Z,1594230895.019 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-08T17:54:55.419Z,1594230895.419 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-08T17:54:55.828Z,1594230895.828 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-08T17:54:56.226Z,1594230896.226 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-08T17:54:56.639Z,1594230896.639 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-08T17:54:57.045Z,1594230897.045 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-08T17:54:57.457Z,1594230897.457 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-08T17:54:57.860Z,1594230897.860 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-08T17:54:58.257Z,1594230898.257 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-08T17:54:58.651Z,1594230898.651 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-08T17:54:59.060Z,1594230899.060 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-08T17:54:59.467Z,1594230899.467 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-08T17:54:59.867Z,1594230899.867 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-08T17:55:00.267Z,1594230900.267 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-08T17:55:00.676Z,1594230900.676 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-08T17:55:01.082Z,1594230901.082 [DAT](DEBUG): checking for command mode acknowledgment
2020-07-08T17:55:01.083Z,1594230901.083 [DAT](FAULT): failed to enter command mode
2020-07-08T17:55:01.478Z,1594230901.478 [DAT](INFO): entering command mode
2020-07-08T17:55:01.898Z,1594230901.898 [DAT](INFO): setting verbose to 3
2020-07-08T17:55:02.310Z,1594230902.310 [DAT](INFO): set verbose to 3
2020-07-08T17:55:02.311Z,1594230902.311 [DAT](INFO): setting DatVerbose to 27440
2020-07-08T17:55:02.713Z,1594230902.713 [DAT](INFO): set DatVerbose to 27440
2020-07-08T17:55:02.713Z,1594230902.713 [DAT](INFO): setting transmit power to 8
2020-07-08T17:55:03.106Z,1594230903.106 [DAT](INFO): set transmit power to 8
2020-07-08T17:55:03.107Z,1594230903.107 [DAT](INFO): setting local address to 4
2020-07-08T17:55:03.522Z,1594230903.522 [DAT](INFO): set local address to 4
2020-07-08T17:55:29.372Z,1594230929.372 [DAT](INFO): unknown deviceResponse_: WARNING: Modem reset during xmit; lowering xmit power level by 3 dB.

2020-07-08T17:55:29.374Z,1594230929.374 [DAT](INFO): unknown deviceResponse_: WARNING: Reduced xmit power level to 7 due to weak battery.

2020-07-08T17:55:29.375Z,1594230929.375 [DAT](INFO): commRate: 800
2020-07-08T17:55:59.830Z,1594230959.830 [CommandLine](IMPORTANT): got command quit
2020-07-08T17:56:00.837Z,1594230960.837 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2020-07-08T17:56:00.837Z,1594230960.837 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-07-08T17:56:01.005Z,1594230961.005 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2020-07-08T17:56:01.005Z,1594230961.005 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-07-08T17:56:01.006Z,1594230961.006 [CommandLine](INFO): Join timeout helper Thread ID is 3404
2020-07-08T17:56:01.006Z,1594230961.006 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2020-07-08T17:56:01.006Z,1594230961.006 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-07-08T17:56:01.007Z,1594230961.007 [NavChartDb](INFO): Join timeout helper Thread ID is 3406
2020-07-08T17:56:01.150Z,1594230961.150 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2020-07-08T17:56:01.150Z,1594230961.150 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-07-08T17:56:01.165Z,1594230961.165 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2020-07-08T17:56:01.165Z,1594230961.165 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-07-08T17:56:01.165Z,1594230961.165 [Radio_Surface](INFO): Join timeout helper Thread ID is 3407
2020-07-08T17:56:01.565Z,1594230961.565 [Radio_Surface](INFO): Powering down
2020-07-08T17:56:01.566Z,1594230961.566 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2020-07-08T17:56:01.566Z,1594230961.566 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-07-08T17:56:01.567Z,1594230961.567 [ComponentRegistry](INFO): Shutting down Onboard ThreadHandler
2020-07-08T17:56:01.567Z,1594230961.567 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-07-08T17:56:01.567Z,1594230961.567 [Onboard](INFO): Join timeout helper Thread ID is 3408
2020-07-08T17:56:02.141Z,1594230962.141 [DataOverHttps](INFO): Radio surface powered OFF, will not connect.
2020-07-08T17:56:06.145Z,1594230966.145 [Onboard ThreadHandler](INFO): Uninitializing protected caller thread.
2020-07-08T17:56:06.145Z,1594230966.145 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-07-08T17:56:06.157Z,1594230966.157 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2020-07-08T17:56:06.157Z,1594230966.157 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-07-08T17:56:06.157Z,1594230966.157 [DataOverHttps](INFO): Join timeout helper Thread ID is 3410
2020-07-08T17:56:06.158Z,1594230966.158 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2020-07-08T17:56:06.158Z,1594230966.158 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-07-08T17:56:06.160Z,1594230966.160 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2020-07-08T17:56:06.160Z,1594230966.160 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-07-08T17:56:06.160Z,1594230966.160 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 3411
2020-07-08T17:56:06.191Z,1594230966.191 [CBIT](FAULT): Humidity exceeds 50% of running average: Humidity:2285304 %. Average:267127.593750
2020-07-08T17:56:06.605Z,1594230966.605 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2020-07-08T17:56:06.605Z,1594230966.605 [WetLabsBB2FL](INFO): Powering down
2020-07-08T17:56:06.606Z,1594230966.606 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-07-08T17:56:06.609Z,1594230966.609 [ComponentRegistry](INFO): Shutting down CTD_Seabird ThreadHandler
2020-07-08T17:56:06.610Z,1594230966.610 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-07-08T17:56:06.610Z,1594230966.610 [CTD_Seabird](INFO): Join timeout helper Thread ID is 3412
2020-07-08T17:56:07.173Z,1594230967.173 [CTD_Seabird](INFO): Powering down
2020-07-08T17:56:07.185Z,1594230967.185 [CTD_Seabird ThreadHandler](INFO): Uninitializing protected caller thread.
2020-07-08T17:56:07.185Z,1594230967.185 [CTD_Seabird](INFO): Powering down
2020-07-08T17:56:07.197Z,1594230967.197 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-07-08T17:56:07.198Z,1594230967.198 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2020-07-08T17:56:07.198Z,1594230967.198 [logger ThreadHandler](INFO): Thread cancelled.
2020-07-08T17:56:07.199Z,1594230967.199 [logger](INFO): Join timeout helper Thread ID is 3413
2020-07-08T17:56:07.201Z,1594230967.201 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2020-07-08T17:56:07.201Z,1594230967.201 [logger ThreadHandler](INFO): Thread cancelled.
2020-07-08T17:56:07.217Z,1594230967.217 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2020-07-08T17:56:07.218Z,1594230967.218 [CommandLine ThreadHandler](INFO): Thread cancelled.
2020-07-08T17:56:07.218Z,1594230967.218 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2020-07-08T17:56:07.218Z,1594230967.218 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-07-08T17:56:07.218Z,1594230967.218 [controlThread](INFO): Join timeout helper Thread ID is 3414
2020-07-08T17:56:07.309Z,1594230967.309 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2020-07-08T17:56:07.309Z,1594230967.309 [controlThread](DEBUG): Uninitializing ControlThread
2020-07-08T17:56:07.309Z,1594230967.309 [Aanderaa_O2](INFO): Powering down
2020-07-08T17:56:07.311Z,1594230967.311 [AHRS_M2](INFO): Powering down
2020-07-08T17:56:07.453Z,1594230967.453 [DVL_micro](INFO): Powering down
2020-07-08T17:56:07.454Z,1594230967.454 [NAL9602](INFO): Powering down
2020-07-08T17:56:07.455Z,1594230967.455 [DAT](INFO): Powering down
2020-07-08T17:56:07.574Z,1594230967.574 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2020-07-08T17:56:07.575Z,1594230967.575 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2020-07-08T17:56:07.576Z,1594230967.576 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2020-07-08T17:56:07.576Z,1594230967.576 [MissionManager](INFO): Uninitializing Mission Default
2020-07-08T17:56:07.576Z,1594230967.576 [Default] Stopped
2020-07-08T17:56:07.576Z,1594230967.576 [Default](DEBUG): Aggregate::uninitialize Default
2020-07-08T17:56:07.576Z,1594230967.576 [Default:B.GoToSurface] Stopped
2020-07-08T17:56:07.577Z,1594230967.577 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-07-08T17:56:07.577Z,1594230967.577 [Default:CheckIn] Stopped
2020-07-08T17:56:07.577Z,1594230967.577 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-07-08T17:56:07.577Z,1594230967.577 [Default:CheckIn:Read_GPS] Stopped
2020-07-08T17:56:07.579Z,1594230967.579 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2020-07-08T17:56:07.579Z,1594230967.579 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2020-07-08T17:56:07.580Z,1594230967.580 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2020-07-08T17:56:07.580Z,1594230967.580 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2020-07-08T17:56:07.580Z,1594230967.580 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2020-07-08T17:56:07.581Z,1594230967.581 [BuoyancyServo](INFO): Powering down
2020-07-08T17:56:07.713Z,1594230967.713 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2020-07-08T17:56:07.713Z,1594230967.713 [ElevatorServo](INFO): Powering down
2020-07-08T17:56:07.714Z,1594230967.714 [MassServo](DEBUG): Uninitialize Mass Servo.
2020-07-08T17:56:07.714Z,1594230967.714 [MassServo](INFO): Powering down
2020-07-08T17:56:07.715Z,1594230967.715 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-07-08T17:56:07.715Z,1594230967.715 [RudderServo](INFO): Powering down
2020-07-08T17:56:07.716Z,1594230967.716 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2020-07-08T17:56:07.716Z,1594230967.716 [ThrusterServo](INFO): Powering down
2020-07-08T17:56:07.717Z,1594230967.717 [SBIT](DEBUG): Uninitialize SBIT Component.
2020-07-08T17:56:07.717Z,1594230967.717 [IBIT](DEBUG): Uninitialize IBIT Component.
2020-07-08T17:56:07.717Z,1594230967.717 [CBIT](DEBUG): Uninitialize CBIT Component.
2020-07-08T17:56:07.718Z,1594230967.718 [CBIT](DEBUG): Powering off loads.
2020-07-08T17:56:07.729Z,1594230967.729 [CBIT](DEBUG): Disabling WDT.
2020-07-08T17:56:07.741Z,1594230967.741 [CBIT](DEBUG): Opening all GF detection circuits.
2020-07-08T17:56:07.742Z,1594230967.742 [controlThread ThreadHandler](INFO): Thread cancelled.
2020-07-08T17:56:07.788Z,1594230967.788 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2020-07-08T17:56:07.794Z,1594230967.794 [CTD_Seabird ThreadHandler](INFO): Thread cancelled.
2020-07-08T17:56:07.867Z,1594230967.867 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2020-07-08T17:56:07.868Z,1594230967.868 [Onboard ThreadHandler](INFO): Thread cancelled.
2020-07-08T17:56:07.875Z,1594230967.875 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2020-07-08T17:56:07.931Z,1594230967.931 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2020-07-08T17:56:07.996Z,1594230967.996 [logger ThreadHandler](INFO): Thread cancelled.