2020-12-03T18:59:07.207Z,1607021947.207 [Supervisor](DEBUG): Initializing supervisor.
2020-12-03T18:59:07.210Z,1607021947.210 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2020-12-03T18:59:07.211Z,1607021947.211 [SyncHandler](INFO): Protected caller Thread ID is 4759
2020-12-03T18:59:07.211Z,1607021947.211 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2020-12-03T18:59:07.212Z,1607021947.212 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2020-12-03T18:59:07.212Z,1607021947.212 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4760
2020-12-03T18:59:07.216Z,1607021947.216 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2020-12-03T18:59:07.233Z,1607021947.233 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2020-12-03T18:59:07.234Z,1607021947.234 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2020-12-03T18:59:07.234Z,1607021947.234 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4761
2020-12-03T18:59:07.235Z,1607021947.235 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2020-12-03T18:59:07.235Z,1607021947.235 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2020-12-03T18:59:07.236Z,1607021947.236 [logger ThreadHandler](INFO): Protected caller Thread ID is 4762
2020-12-03T18:59:07.238Z,1607021947.238 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2020-12-03T18:59:07.238Z,1607021947.238 [Supervisor](INFO): Looking for Config files in directory: Config/
2020-12-03T18:59:07.240Z,1607021947.240 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2020-12-03T18:59:07.823Z,1607021947.823 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2020-12-03T18:59:07.824Z,1607021947.824 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2020-12-03T18:59:07.960Z,1607021947.960 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2020-12-03T18:59:07.961Z,1607021947.961 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2020-12-03T18:59:08.039Z,1607021948.039 [Supervisor](INFO): Opening Config file at: Config/Dock.cfg
2020-12-03T18:59:08.235Z,1607021948.235 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Dock
2020-12-03T18:59:08.236Z,1607021948.236 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2020-12-03T18:59:08.331Z,1607021948.331 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2020-12-03T18:59:08.331Z,1607021948.331 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2020-12-03T18:59:08.423Z,1607021948.423 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2020-12-03T18:59:08.423Z,1607021948.423 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2020-12-03T18:59:08.795Z,1607021948.795 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2020-12-03T18:59:08.795Z,1607021948.795 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2020-12-03T18:59:08.897Z,1607021948.897 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2020-12-03T18:59:08.898Z,1607021948.898 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2020-12-03T18:59:09.384Z,1607021949.384 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2020-12-03T18:59:09.384Z,1607021949.384 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2020-12-03T18:59:09.564Z,1607021949.564 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2020-12-03T18:59:09.564Z,1607021949.564 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2020-12-03T18:59:09.852Z,1607021949.852 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2020-12-03T18:59:09.852Z,1607021949.852 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2020-12-03T18:59:10.059Z,1607021950.059 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2020-12-03T18:59:10.060Z,1607021950.060 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2020-12-03T18:59:10.196Z,1607021950.196 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2020-12-03T18:59:10.197Z,1607021950.197 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2020-12-03T18:59:10.831Z,1607021950.831 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2020-12-03T18:59:10.831Z,1607021950.831 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2020-12-03T18:59:11.144Z,1607021951.144 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2020-12-03T18:59:11.145Z,1607021951.145 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2020-12-03T18:59:11.343Z,1607021951.343 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2020-12-03T18:59:11.345Z,1607021951.345 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/
2020-12-03T18:59:11.346Z,1607021951.346 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/vehicle.cfg
2020-12-03T18:59:11.528Z,1607021951.528 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/secure.cfg
2020-12-03T18:59:11.609Z,1607021951.609 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Science.cfg
2020-12-03T18:59:11.751Z,1607021951.751 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Simulator.cfg
2020-12-03T18:59:11.834Z,1607021951.834 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Battery.cfg
2020-12-03T18:59:12.080Z,1607021952.080 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2020-12-03T18:59:12.081Z,1607021952.081 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/logger.cfg
2020-12-03T18:59:12.181Z,1607021952.181 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Servo.cfg
2020-12-03T18:59:12.589Z,1607021952.589 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Navigation.cfg
2020-12-03T18:59:12.701Z,1607021952.701 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Sensor.cfg
2020-12-03T18:59:13.037Z,1607021953.037 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/Control.cfg
2020-12-03T18:59:13.131Z,1607021953.131 [Supervisor](INFO): Opening Config file at: Config/lrauv-triton/BIT.cfg
2020-12-03T18:59:13.229Z,1607021953.229 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-triton/root/
2020-12-03T18:59:13.229Z,1607021953.229 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2020-12-03T18:59:13.242Z,1607021953.242 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2020-12-03T18:59:13.261Z,1607021953.261 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2020-12-03T18:59:13.262Z,1607021953.262 [Module Loader](DEBUG): Loading Module at Modules/Dock.so
2020-12-03T18:59:13.336Z,1607021953.336 [Module Loader](DEBUG): Loaded Module: Dock (Contains behaviors and commands for docking)
2020-12-03T18:59:13.337Z,1607021953.337 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2020-12-03T18:59:13.384Z,1607021953.384 [VerticalControl](DEBUG): Construct VerticalControl.
2020-12-03T18:59:13.441Z,1607021953.441 [VerticalControl] Loaded
2020-12-03T18:59:13.441Z,1607021953.441 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2020-12-03T18:59:13.441Z,1607021953.441 [HorizontalControl](DEBUG): Construct HorizontalControl.
2020-12-03T18:59:13.478Z,1607021953.478 [HorizontalControl] Loaded
2020-12-03T18:59:13.478Z,1607021953.478 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2020-12-03T18:59:13.479Z,1607021953.479 [SpeedControl](DEBUG): Construct SpeedControl.
2020-12-03T18:59:13.482Z,1607021953.482 [SpeedControl] Loaded
2020-12-03T18:59:13.482Z,1607021953.482 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2020-12-03T18:59:13.482Z,1607021953.482 [LoopControl](DEBUG): Construct LoopControl.
2020-12-03T18:59:13.483Z,1607021953.483 [LoopControl] Loaded
2020-12-03T18:59:13.483Z,1607021953.483 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2020-12-03T18:59:13.483Z,1607021953.483 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2020-12-03T18:59:13.484Z,1607021953.484 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2020-12-03T18:59:13.612Z,1607021953.612 [BuoyancyServo] Loaded
2020-12-03T18:59:13.612Z,1607021953.612 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2020-12-03T18:59:13.628Z,1607021953.628 [ElevatorServo] Loaded
2020-12-03T18:59:13.628Z,1607021953.628 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2020-12-03T18:59:13.643Z,1607021953.643 [MassServo] Loaded
2020-12-03T18:59:13.643Z,1607021953.643 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2020-12-03T18:59:13.658Z,1607021953.658 [RudderServo] Loaded
2020-12-03T18:59:13.658Z,1607021953.658 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2020-12-03T18:59:13.673Z,1607021953.673 [ThrusterServo] Loaded
2020-12-03T18:59:13.673Z,1607021953.673 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2020-12-03T18:59:13.673Z,1607021953.673 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2020-12-03T18:59:13.674Z,1607021953.674 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2020-12-03T18:59:13.710Z,1607021953.710 [DepthRateCalculator] Loaded
2020-12-03T18:59:13.710Z,1607021953.710 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2020-12-03T18:59:13.713Z,1607021953.713 [PitchRateCalculator] Loaded
2020-12-03T18:59:13.713Z,1607021953.713 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2020-12-03T18:59:13.721Z,1607021953.721 [SpeedCalculator] Loaded
2020-12-03T18:59:13.721Z,1607021953.721 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2020-12-03T18:59:13.732Z,1607021953.732 [TempGradientCalculator] Loaded
2020-12-03T18:59:13.732Z,1607021953.732 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2020-12-03T18:59:13.734Z,1607021953.734 [YawRateCalculator] Loaded
2020-12-03T18:59:13.735Z,1607021953.735 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2020-12-03T18:59:13.749Z,1607021953.749 [ElevatorOffsetCalculator] Loaded
2020-12-03T18:59:13.750Z,1607021953.750 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2020-12-03T18:59:13.750Z,1607021953.750 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2020-12-03T18:59:13.750Z,1607021953.750 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2020-12-03T18:59:13.826Z,1607021953.826 [DeadReckonUsingMultipleVelocitySources] Loaded
2020-12-03T18:59:13.827Z,1607021953.827 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2020-12-03T18:59:13.837Z,1607021953.837 [NavChart] Loaded
2020-12-03T18:59:13.838Z,1607021953.838 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2020-12-03T18:59:13.841Z,1607021953.841 [UniversalFixResidualReporter] Loaded
2020-12-03T18:59:13.841Z,1607021953.841 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2020-12-03T18:59:13.842Z,1607021953.842 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2020-12-03T18:59:13.842Z,1607021953.842 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2020-12-03T18:59:14.133Z,1607021954.133 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2020-12-03T18:59:14.136Z,1607021954.136 [AHRS_M2](INFO): created writer for : platform_orientation
2020-12-03T18:59:14.138Z,1607021954.138 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2020-12-03T18:59:14.141Z,1607021954.141 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2020-12-03T18:59:14.141Z,1607021954.141 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2020-12-03T18:59:14.144Z,1607021954.144 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2020-12-03T18:59:14.144Z,1607021954.144 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2020-12-03T18:59:14.147Z,1607021954.147 [AHRS_M2](INFO): created writer for : platform_roll_angle
2020-12-03T18:59:14.183Z,1607021954.183 [AHRS_M2] Loaded
2020-12-03T18:59:14.184Z,1607021954.184 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2020-12-03T18:59:14.254Z,1607021954.254 [DataOverHttps] Loaded
2020-12-03T18:59:14.255Z,1607021954.255 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2020-12-03T18:59:14.256Z,1607021954.256 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 408514E0
2020-12-03T18:59:14.256Z,1607021954.256 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 4844
2020-12-03T18:59:14.273Z,1607021954.273 [Depth_Keller] Loaded
2020-12-03T18:59:14.273Z,1607021954.273 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2020-12-03T18:59:14.326Z,1607021954.326 [NAL9602] Loaded
2020-12-03T18:59:14.326Z,1607021954.326 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2020-12-03T18:59:14.352Z,1607021954.352 [Onboard] Loaded
2020-12-03T18:59:14.352Z,1607021954.352 [ComponentRegistry](DEBUG): Component "Onboard" handled in its own thread.
2020-12-03T18:59:14.353Z,1607021954.353 [Onboard ThreadHandler](DEBUG): Created PCaller Thread at 408814E0
2020-12-03T18:59:14.353Z,1607021954.353 [Onboard ThreadHandler](INFO): Protected caller Thread ID is 4845
2020-12-03T18:59:14.363Z,1607021954.363 [Radio_Surface] Loaded
2020-12-03T18:59:14.364Z,1607021954.364 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2020-12-03T18:59:14.365Z,1607021954.365 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 408B14E0
2020-12-03T18:59:14.365Z,1607021954.365 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4846
2020-12-03T18:59:14.437Z,1607021954.437 [DAT] Loaded
2020-12-03T18:59:14.438Z,1607021954.438 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2020-12-03T18:59:15.224Z,1607021955.224 [BPC1] Loaded
2020-12-03T18:59:15.224Z,1607021955.224 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2020-12-03T18:59:15.225Z,1607021955.225 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2020-12-03T18:59:15.225Z,1607021955.225 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2020-12-03T18:59:15.311Z,1607021955.311 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2020-12-03T18:59:15.312Z,1607021955.312 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2020-12-03T18:59:15.405Z,1607021955.405 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2020-12-03T18:59:15.406Z,1607021955.406 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2020-12-03T18:59:15.431Z,1607021955.431 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2020-12-03T18:59:15.432Z,1607021955.432 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2020-12-03T18:59:15.625Z,1607021955.625 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_density
2020-12-03T18:59:15.627Z,1607021955.627 [CTD_Seabird](INFO): created writer for : sea_water_density
2020-12-03T18:59:15.628Z,1607021955.628 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): depth
2020-12-03T18:59:15.630Z,1607021955.630 [CTD_Seabird](INFO): created writer for : depth
2020-12-03T18:59:15.631Z,1607021955.631 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_pressure
2020-12-03T18:59:15.633Z,1607021955.633 [CTD_Seabird](INFO): created writer for : sea_water_pressure
2020-12-03T18:59:15.634Z,1607021955.634 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_salinity
2020-12-03T18:59:15.637Z,1607021955.637 [CTD_Seabird](INFO): created writer for : sea_water_salinity
2020-12-03T18:59:15.637Z,1607021955.637 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_temperature
2020-12-03T18:59:15.640Z,1607021955.640 [CTD_Seabird](INFO): created writer for : sea_water_temperature
2020-12-03T18:59:15.641Z,1607021955.641 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): sea_water_electrical_conductivity
2020-12-03T18:59:15.644Z,1607021955.644 [CTD_Seabird](INFO): created writer for : sea_water_electrical_conductivity
2020-12-03T18:59:15.644Z,1607021955.644 [CTD_Seabird](DEBUG): LcmSlateWriter::add(): speed_of_sound_in_sea_water
2020-12-03T18:59:15.647Z,1607021955.647 [CTD_Seabird](INFO): created writer for : speed_of_sound_in_sea_water
2020-12-03T18:59:15.660Z,1607021955.660 [CTD_Seabird] Loaded
2020-12-03T18:59:15.660Z,1607021955.660 [ComponentRegistry](DEBUG): Component "CTD_Seabird" handled in its own thread.
2020-12-03T18:59:15.661Z,1607021955.661 [CTD_Seabird ThreadHandler](DEBUG): Created PCaller Thread at 40A594E0
2020-12-03T18:59:15.661Z,1607021955.661 [CTD_Seabird ThreadHandler](INFO): Protected caller Thread ID is 4847
2020-12-03T18:59:15.690Z,1607021955.690 [ESPComponent] Loaded
2020-12-03T18:59:15.690Z,1607021955.690 [ComponentRegistry](DEBUG): SyncComponent "ESPComponent" handled in the control thread.
2020-12-03T18:59:15.706Z,1607021955.706 [PAR_Licor] Loaded
2020-12-03T18:59:15.706Z,1607021955.706 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2020-12-03T18:59:15.718Z,1607021955.718 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output470
2020-12-03T18:59:15.719Z,1607021955.719 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output470
2020-12-03T18:59:15.721Z,1607021955.721 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.Output650
2020-12-03T18:59:15.721Z,1607021955.721 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: Output650
2020-12-03T18:59:15.723Z,1607021955.723 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.OutputChl
2020-12-03T18:59:15.723Z,1607021955.723 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: OutputChl
2020-12-03T18:59:15.725Z,1607021955.725 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg470nm
2020-12-03T18:59:15.725Z,1607021955.725 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg470nm
2020-12-03T18:59:15.727Z,1607021955.727 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.VolumeScatCoeff117deg650nm
2020-12-03T18:59:15.727Z,1607021955.727 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: VolumeScatCoeff117deg650nm
2020-12-03T18:59:15.729Z,1607021955.729 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff470nm
2020-12-03T18:59:15.730Z,1607021955.730 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff470nm
2020-12-03T18:59:15.731Z,1607021955.731 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.BackscatteringCoeff650nm
2020-12-03T18:59:15.732Z,1607021955.732 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: BackscatteringCoeff650nm
2020-12-03T18:59:15.733Z,1607021955.733 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): mass_concentration_of_chlorophyll_in_sea_water
2020-12-03T18:59:15.736Z,1607021955.736 [WetLabsBB2FL](INFO): created writer for : mass_concentration_of_chlorophyll_in_sea_water
2020-12-03T18:59:15.736Z,1607021955.736 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-12-03T18:59:15.736Z,1607021955.736 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_median_mass_concentration_of_chlorophyll_in_sea_water
2020-12-03T18:59:15.738Z,1607021955.738 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-12-03T18:59:15.738Z,1607021955.738 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_mean_mass_concentration_of_chlorophyll_in_sea_water
2020-12-03T18:59:15.740Z,1607021955.740 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(DAtaURI): WetLabsBB2FL.bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-12-03T18:59:15.741Z,1607021955.741 [WetLabsBB2FL](DEBUG): LcmSlateWriter::add(): dataName: bin_variance_mass_concentration_of_chlorophyll_in_sea_water
2020-12-03T18:59:15.743Z,1607021955.743 [WetLabsBB2FL] Loaded
2020-12-03T18:59:15.743Z,1607021955.743 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2020-12-03T18:59:15.744Z,1607021955.744 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 40A894E0
2020-12-03T18:59:15.744Z,1607021955.744 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4848
2020-12-03T18:59:15.745Z,1607021955.745 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2020-12-03T18:59:15.746Z,1607021955.746 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2020-12-03T18:59:16.025Z,1607021956.025 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2020-12-03T18:59:16.025Z,1607021956.025 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2020-12-03T18:59:16.157Z,1607021956.157 [SBIT](DEBUG): Construct Startup Built In Test.
2020-12-03T18:59:16.165Z,1607021956.165 [SBIT] Loaded
2020-12-03T18:59:16.166Z,1607021956.166 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2020-12-03T18:59:16.166Z,1607021956.166 [IBIT](DEBUG): Construct Initiated Built In Test.
2020-12-03T18:59:16.178Z,1607021956.178 [IBIT] Loaded
2020-12-03T18:59:16.178Z,1607021956.178 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2020-12-03T18:59:16.181Z,1607021956.181 [CBIT](DEBUG): Construct Continuous Built In Test.
2020-12-03T18:59:16.276Z,1607021956.276 [CBIT] Loaded
2020-12-03T18:59:16.276Z,1607021956.276 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2020-12-03T18:59:16.277Z,1607021956.277 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2020-12-03T18:59:16.280Z,1607021956.280 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2020-12-03T18:59:16.281Z,1607021956.281 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2020-12-03T18:59:16.289Z,1607021956.289 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2020-12-03T18:59:16.290Z,1607021956.290 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40B674E0
2020-12-03T18:59:16.291Z,1607021956.291 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4849
2020-12-03T18:59:16.295Z,1607021956.295 [Supervisor](INFO): Main Thread ID is 4582
2020-12-03T18:59:16.295Z,1607021956.295 [Supervisor](DEBUG): Running supervisor.
2020-12-03T18:59:16.296Z,1607021956.296 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4850
2020-12-03T18:59:16.298Z,1607021956.298 [controlThread ThreadHandler](INFO): Handler Thread ID is 4851
2020-12-03T18:59:16.299Z,1607021956.299 [controlThread](DEBUG): Initializing ControlThread
2020-12-03T18:59:16.299Z,1607021956.299 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2020-12-03T18:59:16.301Z,1607021956.301 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2020-12-03T18:59:16.302Z,1607021956.302 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2020-12-03T18:59:16.302Z,1607021956.302 [LoopControl](DEBUG): Initialize LoopControlComponent.
2020-12-03T18:59:16.304Z,1607021956.304 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2020-12-03T18:59:16.304Z,1607021956.304 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2020-12-03T18:59:16.304Z,1607021956.304 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2020-12-03T18:59:16.305Z,1607021956.305 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2020-12-03T18:59:16.305Z,1607021956.305 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2020-12-03T18:59:16.305Z,1607021956.305 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2020-12-03T18:59:16.306Z,1607021956.306 [NavChart](DEBUG): Initialize NavChart Navigation.
2020-12-03T18:59:16.307Z,1607021956.307 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2020-12-03T18:59:16.313Z,1607021956.313 [SBIT](INFO): Initialize SBIT Component.
2020-12-03T18:59:16.313Z,1607021956.313 [SBIT](IMPORTANT): git: 2020-11-24-6-gcb638d650
2020-12-03T18:59:16.314Z,1607021956.314 [SBIT](INFO): git hash: cb638d650ac65501896d406bf5988c2fe747cbc6
2020-12-03T18:59:16.314Z,1607021956.314 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2020-12-03T18:59:16.315Z,1607021956.315 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #1 PREEMPT Tue Dec 18 21:19:28 PST 2018
Kernel Reported: #1 PREEMPT Tue May 26 08:17:09 PDT 2020
2020-12-03T18:59:16.316Z,1607021956.316 [SBIT](INFO): Beginning SBIT in 34.000000 seconds.
2020-12-03T18:59:16.317Z,1607021956.317 [IBIT](INFO): Initialize IBIT Component.
2020-12-03T18:59:16.318Z,1607021956.318 [CBIT](DEBUG): Initialize CBIT Component.
2020-12-03T18:59:16.318Z,1607021956.318 [logger ThreadHandler](INFO): Handler Thread ID is 4852
2020-12-03T18:59:16.329Z,1607021956.329 [CBIT](DEBUG): Initialized mux pins.
2020-12-03T18:59:16.329Z,1607021956.329 [CBIT](DEBUG): Initializing the watchdog timer.
2020-12-03T18:59:16.337Z,1607021956.337 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 4853
2020-12-03T18:59:16.338Z,1607021956.338 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2020-12-03T18:59:16.349Z,1607021956.349 [Onboard ThreadHandler](INFO): Handler Thread ID is 4854
2020-12-03T18:59:16.353Z,1607021956.353 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2020-12-03T18:59:16.353Z,1607021956.353 [CBIT](DEBUG): Initializing heartbeat.
2020-12-03T18:59:16.378Z,1607021956.378 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4855
2020-12-03T18:59:16.385Z,1607021956.385 [CTD_Seabird ThreadHandler](INFO): Handler Thread ID is 4856
2020-12-03T18:59:16.386Z,1607021956.386 [CTD_Seabird](DEBUG): Initializing CTD_Seabird.
2020-12-03T18:59:16.390Z,1607021956.390 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4858
2020-12-03T18:59:16.391Z,1607021956.391 [WetLabsBB2FL](INFO): Powering down
2020-12-03T18:59:16.413Z,1607021956.413 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4859
2020-12-03T18:59:16.416Z,1607021956.416 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2020-12-03T18:59:16.416Z,1607021956.416 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2020-12-03T18:59:16.416Z,1607021956.416 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2020-12-03T18:59:16.417Z,1607021956.417 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2020-12-03T18:59:16.417Z,1607021956.417 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2020-12-03T18:59:16.417Z,1607021956.417 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2020-12-03T18:59:16.417Z,1607021956.417 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2020-12-03T18:59:16.417Z,1607021956.417 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2020-12-03T18:59:16.417Z,1607021956.417 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2020-12-03T18:59:16.417Z,1607021956.417 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2020-12-03T18:59:16.418Z,1607021956.418 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2020-12-03T18:59:16.418Z,1607021956.418 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2020-12-03T18:59:16.418Z,1607021956.418 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2020-12-03T18:59:16.418Z,1607021956.418 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2020-12-03T18:59:16.418Z,1607021956.418 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2020-12-03T18:59:16.418Z,1607021956.418 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2020-12-03T18:59:16.425Z,1607021956.425 [CBIT](DEBUG): Deactivating GF circuits.
2020-12-03T18:59:16.425Z,1607021956.425 [CBIT](DEBUG): Deactivating emergency mode.
2020-12-03T18:59:16.461Z,1607021956.461 [CBIT](DEBUG): Backplane powered.
2020-12-03T18:59:16.462Z,1607021956.462 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2020-12-03T18:59:16.471Z,1607021956.471 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2020-12-03T18:59:16.482Z,1607021956.482 [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-12-03T18:59:16.483Z,1607021956.483 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2020-12-03T18:59:16.550Z,1607021956.550 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2020-12-03T18:59:16.551Z,1607021956.551 [Default:A.Wait](DEBUG): Construct Wait.
2020-12-03T18:59:16.569Z,1607021956.569 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-12-03T18:59:16.615Z,1607021956.615 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2020-12-03T18:59:16.618Z,1607021956.618 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2020-12-03T18:59:16.647Z,1607021956.647 [Default:E.Execute](DEBUG): Construct Execute.
2020-12-03T18:59:16.670Z,1607021956.670 [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-12-03T18:59:16.674Z,1607021956.674 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,NAL9602,DAT,BPC1,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,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-12-03T18:59:16.685Z,1607021956.685 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2020-12-03T18:59:16.716Z,1607021956.716 [Depth_Keller](ERROR): Pressure reading out of range: 1925.893799 decibar
2020-12-03T18:59:16.719Z,1607021956.719 [DAT](INFO): Powered 24V power converter LCB with command: ! echo 1 > /dev/loadB2
2020-12-03T18:59:16.719Z,1607021956.719 [DAT](INFO): Powering up
2020-12-03T18:59:16.719Z,1607021956.719 [DAT](DEBUG): Initializing DAT.
2020-12-03T18:59:16.775Z,1607021956.775 [DepthRateCalculator](ERROR): Depth measurement is not active
2020-12-03T18:59:16.781Z,1607021956.781 [Radio_Surface](INFO): Powering up
2020-12-03T18:59:16.797Z,1607021956.797 [CommandLine](IMPORTANT): got command ! echo 1 > /dev/loadB2
2020-12-03T18:59:16.820Z,1607021956.820 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2020-12-03T18:59:16.825Z,1607021956.825 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2020-12-03T18:59:16.826Z,1607021956.826 [ElevatorServo](DEBUG): Initializing EZServoServo.
2020-12-03T18:59:16.837Z,1607021956.837 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2020-12-03T18:59:16.838Z,1607021956.838 [MassServo](DEBUG): Initializing EZServoServo.
2020-12-03T18:59:16.849Z,1607021956.849 [MassServo](DEBUG): Initializing MassServo.
2020-12-03T18:59:16.850Z,1607021956.850 [RudderServo](DEBUG): Initializing EZServoServo.
2020-12-03T18:59:16.861Z,1607021956.861 [RudderServo](DEBUG): Initializing RudderServo.
2020-12-03T18:59:16.862Z,1607021956.862 [ThrusterServo](DEBUG): Initializing EZServoServo.
2020-12-03T18:59:16.873Z,1607021956.873 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2020-12-03T18:59:17.817Z,1607021957.817 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2020-12-03T18:59:17.817Z,1607021957.817 [RudderServo](FAULT): Rudder failed to initialize
2020-12-03T18:59:17.817Z,1607021957.817 [RudderServo] Communications Fault, FailCount= 1
2020-12-03T18:59:17.817Z,1607021957.817 [RudderServo](ERROR): Communications Fault
2020-12-03T18:59:17.916Z,1607021957.916 [CBIT](ERROR): Communications Fault in component: RudderServo
2020-12-03T18:59:18.094Z,1607021958.094 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2020-12-03T18:59:18.094Z,1607021958.094 [RudderServo](INFO): Powering down
2020-12-03T18:59:18.787Z,1607021958.787 [RudderServo](DEBUG): Initializing EZServoServo.
2020-12-03T18:59:18.906Z,1607021958.906 [RudderServo](DEBUG): Initializing RudderServo.
2020-12-03T18:59:18.910Z,1607021958.910 [CBIT](INFO): Clearing failed state for component RudderServo
2020-12-03T18:59:18.910Z,1607021958.910 [RudderServo] No Fault, FailCount= 1
2020-12-03T18:59:27.222Z,1607021967.222 [DAT](INFO): DAT read: 

2020-12-03T18:59:27.224Z,1607021967.224 [DAT](INFO): DAT read: Teledyne Benthos DAT-900 Series

2020-12-03T18:59:28.435Z,1607021968.435 [DAT](INFO): DAT read: MF Frequency Band

2020-12-03T18:59:28.436Z,1607021968.436 [DAT](INFO): DAT read: Directional Acoustic Transponder version 8.12.21

2020-12-03T18:59:28.437Z,1607021968.437 [DAT](INFO): DAT read: Dec  3 2020 18:57:40 

2020-12-03T18:59:29.239Z,1607021969.239 [DAT](INFO): DAT read: Features enabled [Bearing]

2020-12-03T18:59:29.240Z,1607021969.240 [DAT](INFO): DAT read: CONNECT 00800 bits/sec 1 of 4, Rate 1/2 CC 12.50ms MGP

2020-12-03T18:59:29.240Z,1607021969.240 [DAT](INFO): commRate: 800
2020-12-03T18:59:31.262Z,1607021971.262 [DAT](INFO): entering command mode
2020-12-03T18:59:31.666Z,1607021971.666 [DAT](INFO): DAT read: 

2020-12-03T18:59:31.666Z,1607021971.666 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T18:59:32.070Z,1607021972.070 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T18:59:32.474Z,1607021972.474 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T18:59:32.874Z,1607021972.874 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T18:59:33.278Z,1607021973.278 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T18:59:33.686Z,1607021973.686 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T18:59:34.086Z,1607021974.086 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T18:59:34.494Z,1607021974.494 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T18:59:34.894Z,1607021974.894 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T18:59:35.298Z,1607021975.298 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T18:59:35.708Z,1607021975.708 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T18:59:36.106Z,1607021976.106 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T18:59:36.510Z,1607021976.510 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T18:59:36.914Z,1607021976.914 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T18:59:37.318Z,1607021977.318 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T18:59:37.722Z,1607021977.722 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T18:59:38.126Z,1607021978.126 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T18:59:38.530Z,1607021978.530 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T18:59:38.938Z,1607021978.938 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T18:59:39.338Z,1607021979.338 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T18:59:39.742Z,1607021979.742 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T18:59:40.146Z,1607021980.146 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T18:59:40.550Z,1607021980.550 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T18:59:40.954Z,1607021980.954 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T18:59:41.358Z,1607021981.358 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T18:59:41.766Z,1607021981.766 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T18:59:42.170Z,1607021982.170 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T18:59:42.570Z,1607021982.570 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T18:59:42.974Z,1607021982.974 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T18:59:43.378Z,1607021983.378 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T18:59:43.782Z,1607021983.782 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T18:59:44.186Z,1607021984.186 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T18:59:44.590Z,1607021984.590 [NAL9602](INFO): Powering up NAL9602
2020-12-03T18:59:44.591Z,1607021984.591 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T18:59:44.994Z,1607021984.994 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T18:59:45.402Z,1607021985.402 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T18:59:45.807Z,1607021985.807 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T18:59:46.206Z,1607021986.206 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T18:59:46.610Z,1607021986.610 [DAT](DEBUG): checking for command mode acknowledgment
2020-12-03T18:59:46.610Z,1607021986.610 [DAT](FAULT): failed to enter command mode
2020-12-03T18:59:47.014Z,1607021987.014 [DAT](INFO): entering command mode
2020-12-03T18:59:47.418Z,1607021987.418 [DAT](INFO): DAT read: user:1>

2020-12-03T18:59:47.419Z,1607021987.419 [DAT](INFO): DAT read: Command '+++' not found

2020-12-03T18:59:47.419Z,1607021987.419 [DAT](INFO): DAT read: Error

2020-12-03T18:59:47.420Z,1607021987.420 [DAT](INFO): setting verbose to 3
2020-12-03T18:59:47.836Z,1607021987.836 [DAT](INFO): DAT read: user:2>

2020-12-03T18:59:47.837Z,1607021987.837 [DAT](INFO): DAT read: Verbose         | 3           

2020-12-03T18:59:47.837Z,1607021987.837 [DAT](INFO): set verbose to 3
2020-12-03T18:59:47.837Z,1607021987.837 [DAT](INFO): setting DatVerbose to 27440
2020-12-03T18:59:48.226Z,1607021988.226 [DAT](INFO): DAT read: user:3>

2020-12-03T18:59:48.227Z,1607021988.227 [DAT](INFO): DAT read: DatVerbose      | 27440       

2020-12-03T18:59:48.228Z,1607021988.228 [DAT](INFO): set DatVerbose to 27440
2020-12-03T18:59:48.228Z,1607021988.228 [DAT](INFO): setting transmit power to 8
2020-12-03T18:59:48.630Z,1607021988.630 [DAT](INFO): DAT read: user:4>

2020-12-03T18:59:48.631Z,1607021988.631 [DAT](INFO): DAT read: TxPower         | 8 (Max)     

2020-12-03T18:59:48.631Z,1607021988.631 [DAT](INFO): set transmit power to 8
2020-12-03T18:59:48.632Z,1607021988.632 [DAT](INFO): setting local address to 5
2020-12-03T18:59:49.038Z,1607021989.038 [DAT](INFO): DAT read: user:5>

2020-12-03T18:59:49.039Z,1607021989.039 [DAT](INFO): DAT read: LocalAddr       | 5           

2020-12-03T18:59:49.039Z,1607021989.039 [DAT](INFO): set local address to 5
2020-12-03T18:59:51.118Z,1607021991.118 [SBIT](IMPORTANT): Beginning Startup BIT
2020-12-03T18:59:51.122Z,1607021991.122 [CBIT](IMPORTANT): Beginning ground fault scan
2020-12-03T18:59:55.510Z,1607021995.510 [NAL9602](INFO): NAL9602 initialized
2020-12-03T19:00:01.023Z,1607022001.023 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.799999 degree
2020-12-03T19:00:01.024Z,1607022001.024 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread.
2020-12-03T19:00:01.025Z,1607022001.025 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -122.800003 degree
2020-12-03T19:00:01.026Z,1607022001.026 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread.
2020-12-03T19:00:01.318Z,1607022001.318 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,NAL9602,DAT,BPC1,ESPComponent,PAR_Licor,Depth_Keller,PAR_Licor,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-12-03T19:00:01.579Z,1607022001.579 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-12-03T19:00:01.579Z,1607022001.579 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2020-12-03T19:00:01.579Z,1607022001.579 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-03T19:00:01.602Z,1607022001.602 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-03T19:00:02.017Z,1607022002.017 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-03T19:00:02.018Z,1607022002.018 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2020-12-03T19:00:02.021Z,1607022002.021 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): 0.024442
CHAN A1 (24V): -0.007805
CHAN A2 (12V): -0.004143
CHAN A3 (5V): -0.002546
CHAN B0 (3.3V): -0.000878
CHAN B1 (3.15aV): -0.001334
CHAN B2 (3.15bV): -0.001132
CHAN B3 (GND): 0.000135
OPEN: 0.004635
Full Scale Calc: 4.765 mA, -1.589 mA
2020-12-03T19:00:02.810Z,1607022002.810 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-12-03T19:00:02.811Z,1607022002.811 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 2
2020-12-03T19:00:02.811Z,1607022002.811 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-03T19:00:02.821Z,1607022002.821 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-03T19:00:03.209Z,1607022003.209 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-03T19:00:03.209Z,1607022003.209 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 2
2020-12-03T19:00:04.006Z,1607022004.006 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-12-03T19:00:04.007Z,1607022004.007 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 3
2020-12-03T19:00:04.007Z,1607022004.007 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-03T19:00:04.017Z,1607022004.017 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-03T19:00:04.419Z,1607022004.419 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-03T19:00:04.419Z,1607022004.419 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 3
2020-12-03T19:00:05.213Z,1607022005.213 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-12-03T19:00:05.213Z,1607022005.213 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 4
2020-12-03T19:00:05.213Z,1607022005.213 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-03T19:00:05.263Z,1607022005.263 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-03T19:00:05.677Z,1607022005.677 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-03T19:00:05.677Z,1607022005.677 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 4
2020-12-03T19:00:06.439Z,1607022006.439 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-12-03T19:00:06.439Z,1607022006.439 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 5
2020-12-03T19:00:06.439Z,1607022006.439 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-03T19:00:06.645Z,1607022006.645 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-03T19:00:06.913Z,1607022006.913 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-03T19:00:06.913Z,1607022006.913 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 5
2020-12-03T19:00:07.661Z,1607022007.661 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-12-03T19:00:07.661Z,1607022007.661 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 6
2020-12-03T19:00:07.662Z,1607022007.662 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-03T19:00:07.766Z,1607022007.766 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-03T19:00:08.193Z,1607022008.193 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-03T19:00:08.194Z,1607022008.194 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 6
2020-12-03T19:00:08.866Z,1607022008.866 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-12-03T19:00:08.867Z,1607022008.867 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 7
2020-12-03T19:00:08.867Z,1607022008.867 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-03T19:00:08.997Z,1607022008.997 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-03T19:00:09.353Z,1607022009.353 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-03T19:00:09.353Z,1607022009.353 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 7
2020-12-03T19:00:10.054Z,1607022010.054 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-12-03T19:00:10.054Z,1607022010.054 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 8
2020-12-03T19:00:10.054Z,1607022010.054 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-03T19:00:10.149Z,1607022010.149 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-03T19:00:10.553Z,1607022010.553 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-03T19:00:10.553Z,1607022010.553 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 8
2020-12-03T19:00:11.266Z,1607022011.266 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-12-03T19:00:11.266Z,1607022011.266 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 9
2020-12-03T19:00:11.266Z,1607022011.266 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-03T19:00:11.373Z,1607022011.373 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-03T19:00:11.842Z,1607022011.842 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-03T19:00:11.842Z,1607022011.842 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 9
2020-12-03T19:00:12.482Z,1607022012.482 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-12-03T19:00:12.483Z,1607022012.483 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 10
2020-12-03T19:00:12.483Z,1607022012.483 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-03T19:00:12.517Z,1607022012.517 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-03T19:00:12.933Z,1607022012.933 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-03T19:00:12.933Z,1607022012.933 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 10
2020-12-03T19:00:13.710Z,1607022013.710 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-12-03T19:00:13.710Z,1607022013.710 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 11
2020-12-03T19:00:13.710Z,1607022013.710 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-03T19:00:13.749Z,1607022013.749 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-03T19:00:14.141Z,1607022014.141 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-03T19:00:14.141Z,1607022014.141 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 11
2020-12-03T19:00:14.906Z,1607022014.906 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-12-03T19:00:14.907Z,1607022014.907 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 12
2020-12-03T19:00:14.907Z,1607022014.907 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-03T19:00:14.941Z,1607022014.941 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-03T19:00:15.418Z,1607022015.418 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-03T19:00:15.418Z,1607022015.418 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 12
2020-12-03T19:00:16.123Z,1607022016.123 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-12-03T19:00:16.123Z,1607022016.123 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 13
2020-12-03T19:00:16.123Z,1607022016.123 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-03T19:00:16.166Z,1607022016.166 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-03T19:00:16.565Z,1607022016.565 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-03T19:00:16.565Z,1607022016.565 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 13
2020-12-03T19:00:17.366Z,1607022017.366 [DeadReckonUsingMultipleVelocitySources](ERROR): Caught NaN! Will not write estimated position: latitude_ = nan, longitude_ = nan, depth_ = nan, horizontalPathLengthSinceLastFix_ = nan, latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2020-12-03T19:00:17.366Z,1607022017.366 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 14
2020-12-03T19:00:17.366Z,1607022017.366 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2020-12-03T19:00:17.429Z,1607022017.429 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2020-12-03T19:00:17.790Z,1607022017.790 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2020-12-03T19:00:17.791Z,1607022017.791 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 14
2020-12-03T19:00:18.358Z,1607022018.358 [CommandLine](IMPORTANT): got command set NAL9602.time_fix 1607021952.000000 second
2020-12-03T19:00:45.238Z,1607022045.238 [SBIT](IMPORTANT): SBIT PASSED
2020-12-03T19:00:45.238Z,1607022045.238 [SBIT](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2020-12-03T19:00:45.239Z,1607022045.239 [SBIT](IMPORTANT): AHRS_M2.loadAtStartup=1 bool;

2020-12-03T19:00:45.239Z,1607022045.239 [SBIT](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool;

2020-12-03T19:00:45.239Z,1607022045.239 [SBIT](IMPORTANT): BPC1.batteryMissingStickThreshold=2 count;

2020-12-03T19:00:45.239Z,1607022045.239 [SBIT](IMPORTANT): DAT.loadAtStartup=1 bool;

2020-12-03T19:00:45.240Z,1607022045.240 [SBIT](IMPORTANT): DAT.verbosity=3 count;

2020-12-03T19:00:45.240Z,1607022045.240 [SBIT](IMPORTANT): DVL_micro.loadAtStartup=0 bool;

2020-12-03T19:00:45.240Z,1607022045.240 [SBIT](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2020-12-03T19:00:45.240Z,1607022045.240 [SBIT](IMPORTANT): Express none CTD_Seabird.bin_median_sea_water_salinity;

2020-12-03T19:00:45.240Z,1607022045.240 [SBIT](IMPORTANT): Express linearApproximation WetLabsBB2FL.bin_mean_mass_concentration_of_chlorophyll_in_sea_water 0.100000 microgram_per_liter;

2020-12-03T19:00:45.240Z,1607022045.240 [SBIT](IMPORTANT): Express linearApproximation depth 5.000000 meter;

2020-12-03T19:00:45.240Z,1607022045.240 [SBIT](IMPORTANT): Express linearApproximation latitude 0.005000 degree;

2020-12-03T19:00:45.241Z,1607022045.241 [SBIT](IMPORTANT): Express linearApproximation longitude 0.005000 degree;

2020-12-03T19:00:45.241Z,1607022045.241 [SBIT](IMPORTANT): VerticalControl.buoyancyNeutral=168 cubic_centimeter;

2020-12-03T19:00:45.241Z,1607022045.241 [SBIT](IMPORTANT): VerticalControl.massDefault=3.7 millimeter;

2020-12-03T19:00:45.647Z,1607022045.647 [MissionManager](IMPORTANT): Started mission Startup
2020-12-03T19:00:45.647Z,1607022045.647 [Startup] Running Loop=1
2020-12-03T19:00:45.647Z,1607022045.647 [Startup](DEBUG): Aggregate::initialize Startup
2020-12-03T19:00:45.647Z,1607022045.647 [Startup:A.GoToSurface] Running Loop=1
2020-12-03T19:00:45.647Z,1607022045.647 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-12-03T19:00:45.648Z,1607022045.648 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-12-03T19:00:45.649Z,1607022045.649 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-12-03T19:00:45.649Z,1607022045.649 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-12-03T19:00:45.649Z,1607022045.649 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-12-03T19:00:45.650Z,1607022045.650 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-12-03T19:00:45.651Z,1607022045.651 [Startup:StartupSatComms] Running Loop=1
2020-12-03T19:00:45.651Z,1607022045.651 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2020-12-03T19:00:45.651Z,1607022045.651 [Startup:StartupSatComms:A] Running Loop=1
2020-12-03T19:00:46.041Z,1607022046.041 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2020-12-03T19:00:46.042Z,1607022046.042 [Startup:StartupSatComms:A] Stopped
2020-12-03T19:00:46.042Z,1607022046.042 [Startup:StartupSatComms:B] Running Loop=1
2020-12-03T19:00:46.457Z,1607022046.457 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-12-03T19:01:44.735Z,1607022104.735 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005163
2020-12-03T19:01:46.217Z,1607022106.217 [Startup:StartupSatComms:B](INFO): Timed out from 2020-12-03T19:00:46.0Z
2020-12-03T19:01:46.218Z,1607022106.218 [Startup:StartupSatComms:B] Stopped
2020-12-03T19:01:46.218Z,1607022106.218 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2020-12-03T19:01:46.218Z,1607022106.218 [Startup:StartupSatComms] Stopped
2020-12-03T19:01:46.218Z,1607022106.218 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2020-12-03T19:01:46.219Z,1607022106.219 [Startup](INFO): Completed Startup
2020-12-03T19:01:46.219Z,1607022106.219 [MissionManager](INFO): Startup is completed.
2020-12-03T19:01:46.219Z,1607022106.219 [MissionManager](INFO): Uninitializing Mission Startup
2020-12-03T19:01:46.219Z,1607022106.219 [Startup] Stopped
2020-12-03T19:01:46.219Z,1607022106.219 [Startup](DEBUG): Aggregate::uninitialize Startup
2020-12-03T19:01:46.219Z,1607022106.219 [Startup:A.GoToSurface] Stopped
2020-12-03T19:01:46.219Z,1607022106.219 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-12-03T19:01:46.645Z,1607022106.645 [MissionManager](IMPORTANT): Started mission Default
2020-12-03T19:01:46.645Z,1607022106.645 [Default] Running Loop=1
2020-12-03T19:01:46.645Z,1607022106.645 [Default](DEBUG): Aggregate::initialize Default
2020-12-03T19:01:46.645Z,1607022106.645 [Default:B.GoToSurface] Running Loop=1
2020-12-03T19:01:46.645Z,1607022106.645 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-12-03T19:01:46.645Z,1607022106.645 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-12-03T19:01:46.646Z,1607022106.646 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-12-03T19:01:46.646Z,1607022106.646 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-12-03T19:01:46.646Z,1607022106.646 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-12-03T19:01:46.647Z,1607022106.647 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-12-03T19:01:46.647Z,1607022106.647 [Default:A.Wait] Running Loop=1
2020-12-03T19:01:46.647Z,1607022106.647 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-12-03T19:01:59.685Z,1607022119.685 [Default:A.Wait](INFO): Done Waiting.
2020-12-03T19:01:59.685Z,1607022119.685 [Default:A.Wait] Stopped
2020-12-03T19:01:59.685Z,1607022119.685 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-12-03T19:02:00.053Z,1607022120.053 [Default:CheckIn] Running Loop=1
2020-12-03T19:02:00.053Z,1607022120.053 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-03T19:02:00.053Z,1607022120.053 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-03T19:02:00.489Z,1607022120.489 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2020-12-03T19:04:58.230Z,1607022298.230 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-12-03T19:06:05.976Z,1607022365.976 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201203T174436/Courier0022.lzma
2020-12-03T19:06:06.979Z,1607022366.979 [DataOverHttps](INFO): Moved sent file to Logs/20201203T174436/Courier0022.lzma.bak
2020-12-03T19:06:06.979Z,1607022366.979 [DataOverHttps](INFO): SBD MOMSN=13388008
2020-12-03T19:07:00.248Z,1607022420.248 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-12-03T19:02:00.1Z
2020-12-03T19:07:00.249Z,1607022420.249 [Default:CheckIn:Read_GPS] Stopped
2020-12-03T19:07:00.249Z,1607022420.249 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-03T19:07:00.665Z,1607022420.665 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-12-03T19:07:10.641Z,1607022430.641 [DataOverHttps](INFO): Sending 203 bytes from file Logs/20201203T185907/Courier0000.lzma
2020-12-03T19:07:11.642Z,1607022431.642 [DataOverHttps](INFO): Moved sent file to Logs/20201203T185907/Courier0000.lzma.bak
2020-12-03T19:07:11.642Z,1607022431.642 [DataOverHttps](INFO): SBD MOMSN=13388010
2020-12-03T19:07:31.088Z,1607022451.088 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201203T185907/Courier0004.lzma
2020-12-03T19:07:32.090Z,1607022452.090 [DataOverHttps](INFO): Moved sent file to Logs/20201203T185907/Courier0004.lzma.bak
2020-12-03T19:07:32.090Z,1607022452.090 [DataOverHttps](INFO): SBD MOMSN=13388013
2020-12-03T19:07:33.365Z,1607022453.365 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-03T19:07:53.885Z,1607022473.885 [DataOverHttps](INFO): Sending 254 bytes from file Logs/20201203T174436/Express0023.lzma
2020-12-03T19:07:54.886Z,1607022474.886 [DataOverHttps](INFO): Moved sent file to Logs/20201203T174436/Express0023.lzma.bak
2020-12-03T19:07:54.887Z,1607022474.887 [DataOverHttps](INFO): SBD MOMSN=13388015
2020-12-03T19:08:16.647Z,1607022496.647 [DataOverHttps](INFO): Sending 1161 bytes from file Logs/20201203T185907/Express0001.lzma
2020-12-03T19:08:17.646Z,1607022497.646 [DataOverHttps](INFO): Moved sent file to Logs/20201203T185907/Express0001.lzma.bak
2020-12-03T19:08:17.646Z,1607022497.646 [DataOverHttps](INFO): SBD MOMSN=13388021
2020-12-03T19:08:39.326Z,1607022519.326 [DataOverHttps](INFO): Sending 317 bytes from file Logs/20201203T185907/Express0005.lzma
2020-12-03T19:08:40.327Z,1607022520.327 [DataOverHttps](INFO): Moved sent file to Logs/20201203T185907/Express0005.lzma.bak
2020-12-03T19:08:40.327Z,1607022520.327 [DataOverHttps](INFO): SBD MOMSN=13388052
2020-12-03T19:08:46.510Z,1607022526.510 [Default:CheckIn:Read_Iridium] Stopped
2020-12-03T19:08:46.510Z,1607022526.510 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-03T19:08:46.511Z,1607022526.511 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-03T19:11:09.938Z,1607022669.938 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2020-12-03T19:11:22.920Z,1607022682.920 [BPC1](ERROR): BPC1B failed to parse battery data due to unrecognized msg size.
2020-12-03T19:13:47.138Z,1607022827.138 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-03T19:13:47.139Z,1607022827.139 [Default:CheckIn:C.Wait] Stopped
2020-12-03T19:13:47.139Z,1607022827.139 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-03T19:13:47.139Z,1607022827.139 [Default:CheckIn:D] Running Loop=1
2020-12-03T19:13:47.541Z,1607022827.541 [Default:CheckIn:D] Stopped
2020-12-03T19:13:47.541Z,1607022827.541 [Default:CheckIn:E] Running Loop=1
2020-12-03T19:13:47.969Z,1607022827.969 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 12.014930 min 
2020-12-03T19:13:47.969Z,1607022827.969 [Default:CheckIn:E] Stopped
2020-12-03T19:13:47.969Z,1607022827.969 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-03T19:13:47.969Z,1607022827.969 [Default:CheckIn] Stopped
2020-12-03T19:13:47.970Z,1607022827.970 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-03T19:13:47.970Z,1607022827.970 [Default:CheckIn](INFO): Running loop #2
2020-12-03T19:13:47.970Z,1607022827.970 [Default:CheckIn] Running Loop=2
2020-12-03T19:13:47.970Z,1607022827.970 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-03T19:13:47.970Z,1607022827.970 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-03T19:13:49.546Z,1607022829.546 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-12-03T19:13:49.546Z,1607022829.546 [NAL9602] Data Fault, FailCount= 1
2020-12-03T19:13:49.546Z,1607022829.546 [NAL9602](ERROR): Data Fault
2020-12-03T19:13:49.608Z,1607022829.608 [CBIT](ERROR): Data Fault in component: NAL9602
2020-12-03T19:13:49.949Z,1607022829.949 [NAL9602](INFO): Powering down
2020-12-03T19:13:50.933Z,1607022830.933 [CBIT](INFO): Clearing failed state for component NAL9602
2020-12-03T19:13:50.933Z,1607022830.933 [NAL9602] No Fault, FailCount= 1
2020-12-03T19:14:20.250Z,1607022860.250 [NAL9602](INFO): Powering up NAL9602
2020-12-03T19:14:31.158Z,1607022871.158 [NAL9602](INFO): NAL9602 initialized
2020-12-03T19:18:48.135Z,1607023128.135 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-12-03T19:13:47.0Z
2020-12-03T19:18:48.136Z,1607023128.136 [Default:CheckIn:Read_GPS] Stopped
2020-12-03T19:18:48.136Z,1607023128.136 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-03T19:18:58.076Z,1607023138.076 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201203T185907/Courier0007.lzma
2020-12-03T19:18:59.079Z,1607023139.079 [DataOverHttps](INFO): Moved sent file to Logs/20201203T185907/Courier0007.lzma.bak
2020-12-03T19:18:59.079Z,1607023139.079 [DataOverHttps](INFO): SBD MOMSN=13388136
2020-12-03T19:19:21.609Z,1607023161.609 [DataOverHttps](INFO): Sending 200 bytes from file Logs/20201203T185907/Express0008.lzma
2020-12-03T19:19:22.610Z,1607023162.610 [DataOverHttps](INFO): Moved sent file to Logs/20201203T185907/Express0008.lzma.bak
2020-12-03T19:19:22.611Z,1607023162.611 [DataOverHttps](INFO): SBD MOMSN=13388141
2020-12-03T19:19:28.929Z,1607023168.929 [Default:CheckIn:Read_Iridium] Stopped
2020-12-03T19:19:28.929Z,1607023168.929 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-03T19:19:28.929Z,1607023168.929 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-03T19:19:33.762Z,1607023173.762 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-12-03T19:20:04.461Z,1607023204.461 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-03T19:24:29.532Z,1607023469.532 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-03T19:24:29.533Z,1607023469.533 [Default:CheckIn:C.Wait] Stopped
2020-12-03T19:24:29.533Z,1607023469.533 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-03T19:24:29.533Z,1607023469.533 [Default:CheckIn:D] Running Loop=1
2020-12-03T19:24:29.943Z,1607023469.943 [Default:CheckIn:D] Stopped
2020-12-03T19:24:29.943Z,1607023469.943 [Default:CheckIn:E] Running Loop=1
2020-12-03T19:24:30.335Z,1607023470.335 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 22.721637 min 
2020-12-03T19:24:30.336Z,1607023470.336 [Default:CheckIn:E] Stopped
2020-12-03T19:24:30.336Z,1607023470.336 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-03T19:24:30.336Z,1607023470.336 [Default:CheckIn] Stopped
2020-12-03T19:24:30.336Z,1607023470.336 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-03T19:24:30.336Z,1607023470.336 [Default:CheckIn](INFO): Running loop #3
2020-12-03T19:24:30.336Z,1607023470.336 [Default:CheckIn] Running Loop=3
2020-12-03T19:24:30.336Z,1607023470.336 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-03T19:24:30.336Z,1607023470.336 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-03T19:24:32.346Z,1607023472.346 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-12-03T19:24:32.346Z,1607023472.346 [NAL9602] Data Fault, FailCount= 2
2020-12-03T19:24:32.346Z,1607023472.346 [NAL9602](ERROR): Data Fault
2020-12-03T19:24:32.409Z,1607023472.409 [CBIT](ERROR): Data Fault in component: NAL9602
2020-12-03T19:24:32.750Z,1607023472.750 [NAL9602](INFO): Powering down
2020-12-03T19:24:33.608Z,1607023473.608 [CBIT](INFO): Clearing failed state for component NAL9602
2020-12-03T19:24:33.608Z,1607023473.608 [NAL9602] No Fault, FailCount= 2
2020-12-03T19:25:03.046Z,1607023503.046 [NAL9602](INFO): Powering up NAL9602
2020-12-03T19:25:13.959Z,1607023513.959 [NAL9602](INFO): NAL9602 initialized
2020-12-03T19:26:07.284Z,1607023567.284 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2020-12-03T19:29:30.519Z,1607023770.519 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-12-03T19:24:30.3Z
2020-12-03T19:29:30.520Z,1607023770.520 [Default:CheckIn:Read_GPS] Stopped
2020-12-03T19:29:30.520Z,1607023770.520 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-03T19:30:16.550Z,1607023816.550 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-12-03T19:30:16.955Z,1607023816.955 [BPC1](INFO): Calculating totals. Valid battery stick count: 49. Valid reserve battery stick count: 5.
2020-12-03T19:30:16.958Z,1607023816.958 [BPC1](ERROR): Failed to receive data from all sticks prior to timeout. Will not retry this scan cycle.
2020-12-03T19:30:47.258Z,1607023847.258 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-03T19:31:58.324Z,1607023918.324 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201203T185907/Courier0010.lzma
2020-12-03T19:31:59.326Z,1607023919.326 [DataOverHttps](INFO): Moved sent file to Logs/20201203T185907/Courier0010.lzma.bak
2020-12-03T19:31:59.327Z,1607023919.327 [DataOverHttps](INFO): SBD MOMSN=13388238
2020-12-03T19:32:31.273Z,1607023951.273 [DataOverHttps](INFO): Sending 222 bytes from file Logs/20201203T185907/Express0011.lzma
2020-12-03T19:32:32.274Z,1607023952.274 [DataOverHttps](INFO): Moved sent file to Logs/20201203T185907/Express0011.lzma.bak
2020-12-03T19:32:32.275Z,1607023952.275 [DataOverHttps](INFO): SBD MOMSN=13388246
2020-12-03T19:32:38.865Z,1607023958.865 [Default:CheckIn:Read_Iridium] Stopped
2020-12-03T19:32:38.865Z,1607023958.865 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-03T19:32:38.865Z,1607023958.865 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-03T19:37:39.443Z,1607024259.443 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-03T19:37:39.443Z,1607024259.443 [Default:CheckIn:C.Wait] Stopped
2020-12-03T19:37:39.443Z,1607024259.443 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-03T19:37:39.444Z,1607024259.444 [Default:CheckIn:D] Running Loop=1
2020-12-03T19:37:39.849Z,1607024259.849 [Default:CheckIn:D] Stopped
2020-12-03T19:37:39.849Z,1607024259.849 [Default:CheckIn:E] Running Loop=1
2020-12-03T19:37:40.244Z,1607024260.244 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 35.886731 min 
2020-12-03T19:37:40.244Z,1607024260.244 [Default:CheckIn:E] Stopped
2020-12-03T19:37:40.244Z,1607024260.244 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-03T19:37:40.244Z,1607024260.244 [Default:CheckIn] Stopped
2020-12-03T19:37:40.244Z,1607024260.244 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-03T19:37:40.245Z,1607024260.245 [Default:CheckIn](INFO): Running loop #4
2020-12-03T19:37:40.245Z,1607024260.245 [Default:CheckIn] Running Loop=4
2020-12-03T19:37:40.245Z,1607024260.245 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-03T19:37:40.245Z,1607024260.245 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-03T19:37:41.850Z,1607024261.850 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-12-03T19:37:41.850Z,1607024261.850 [NAL9602] Data Fault, FailCount= 3
2020-12-03T19:37:41.850Z,1607024261.850 [NAL9602](ERROR): Data Fault
2020-12-03T19:37:41.874Z,1607024261.874 [CBIT](ERROR): Data Fault in component: NAL9602
2020-12-03T19:37:42.258Z,1607024262.258 [NAL9602](INFO): Powering down
2020-12-03T19:37:43.088Z,1607024263.088 [CBIT](INFO): Clearing failed state for component NAL9602
2020-12-03T19:37:43.088Z,1607024263.088 [NAL9602] No Fault, FailCount= 3
2020-12-03T19:38:12.559Z,1607024292.559 [NAL9602](INFO): Powering up NAL9602
2020-12-03T19:38:23.462Z,1607024303.462 [NAL9602](INFO): NAL9602 initialized
2020-12-03T19:42:40.457Z,1607024560.457 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-12-03T19:37:40.2Z
2020-12-03T19:42:40.457Z,1607024560.457 [Default:CheckIn:Read_GPS] Stopped
2020-12-03T19:42:40.457Z,1607024560.457 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-03T19:43:26.106Z,1607024606.106 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-12-03T19:43:56.809Z,1607024636.809 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-03T19:45:12.100Z,1607024712.100 [DataOverHttps](INFO): Sending 168 bytes from file Logs/20201203T185907/Courier0013.lzma
2020-12-03T19:45:13.102Z,1607024713.102 [DataOverHttps](INFO): Moved sent file to Logs/20201203T185907/Courier0013.lzma.bak
2020-12-03T19:45:13.103Z,1607024713.103 [DataOverHttps](INFO): SBD MOMSN=13388343
2020-12-03T19:45:35.669Z,1607024735.669 [DataOverHttps](INFO): Sending 224 bytes from file Logs/20201203T185907/Express0014.lzma
2020-12-03T19:45:36.670Z,1607024736.670 [DataOverHttps](INFO): Moved sent file to Logs/20201203T185907/Express0014.lzma.bak
2020-12-03T19:45:36.671Z,1607024736.671 [DataOverHttps](INFO): SBD MOMSN=13388348
2020-12-03T19:45:42.689Z,1607024742.689 [Default:CheckIn:Read_Iridium] Stopped
2020-12-03T19:45:42.689Z,1607024742.689 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-03T19:45:42.689Z,1607024742.689 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-03T19:50:43.244Z,1607025043.244 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-03T19:50:43.244Z,1607025043.244 [Default:CheckIn:C.Wait] Stopped
2020-12-03T19:50:43.244Z,1607025043.244 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-03T19:50:43.244Z,1607025043.244 [Default:CheckIn:D] Running Loop=1
2020-12-03T19:50:43.653Z,1607025043.653 [Default:CheckIn:D] Stopped
2020-12-03T19:50:43.653Z,1607025043.653 [Default:CheckIn:E] Running Loop=1
2020-12-03T19:50:44.120Z,1607025044.120 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 48.950134 min 
2020-12-03T19:50:44.120Z,1607025044.120 [Default:CheckIn:E] Stopped
2020-12-03T19:50:44.120Z,1607025044.120 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-03T19:50:44.120Z,1607025044.120 [Default:CheckIn] Stopped
2020-12-03T19:50:44.120Z,1607025044.120 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-03T19:50:44.120Z,1607025044.120 [Default:CheckIn](INFO): Running loop #5
2020-12-03T19:50:44.124Z,1607025044.124 [Default:CheckIn] Running Loop=5
2020-12-03T19:50:44.125Z,1607025044.125 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-03T19:50:44.125Z,1607025044.125 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-03T19:50:45.657Z,1607025045.657 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-12-03T19:50:45.657Z,1607025045.657 [NAL9602] Data Fault, FailCount= 4
2020-12-03T19:50:45.658Z,1607025045.658 [NAL9602](ERROR): Data Fault
2020-12-03T19:50:45.725Z,1607025045.725 [CBIT](ERROR): Data Fault in component: NAL9602
2020-12-03T19:50:46.061Z,1607025046.061 [NAL9602](INFO): Powering down
2020-12-03T19:50:46.916Z,1607025046.916 [CBIT](INFO): Clearing failed state for component NAL9602
2020-12-03T19:50:46.945Z,1607025046.945 [NAL9602] No Fault, FailCount= 4
2020-12-03T19:51:16.365Z,1607025076.365 [NAL9602](INFO): Powering up NAL9602
2020-12-03T19:51:27.270Z,1607025087.270 [NAL9602](INFO): NAL9602 initialized
2020-12-03T19:55:44.224Z,1607025344.224 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-12-03T19:50:44.1Z
2020-12-03T19:55:44.224Z,1607025344.224 [Default:CheckIn:Read_GPS] Stopped
2020-12-03T19:55:44.224Z,1607025344.224 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-03T19:56:29.873Z,1607025389.873 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-12-03T19:57:00.569Z,1607025420.569 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-03T19:58:32.124Z,1607025512.124 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201203T185907/Courier0016.lzma
2020-12-03T19:58:33.126Z,1607025513.126 [DataOverHttps](INFO): Moved sent file to Logs/20201203T185907/Courier0016.lzma.bak
2020-12-03T19:58:33.127Z,1607025513.127 [DataOverHttps](INFO): SBD MOMSN=13388445
2020-12-03T19:58:54.521Z,1607025534.521 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20201203T185907/Express0017.lzma
2020-12-03T19:58:55.522Z,1607025535.522 [DataOverHttps](INFO): Moved sent file to Logs/20201203T185907/Express0017.lzma.bak
2020-12-03T19:58:55.523Z,1607025535.523 [DataOverHttps](INFO): SBD MOMSN=13388452
2020-12-03T19:59:01.389Z,1607025541.389 [Default:CheckIn:Read_Iridium] Stopped
2020-12-03T19:59:01.389Z,1607025541.389 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-03T19:59:01.389Z,1607025541.389 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-03T20:04:02.053Z,1607025842.053 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-03T20:04:02.053Z,1607025842.053 [Default:CheckIn:C.Wait] Stopped
2020-12-03T20:04:02.053Z,1607025842.053 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-03T20:04:02.053Z,1607025842.053 [Default:CheckIn:D] Running Loop=1
2020-12-03T20:04:02.481Z,1607025842.481 [Default:CheckIn:D] Stopped
2020-12-03T20:04:02.482Z,1607025842.482 [Default:CheckIn:E] Running Loop=1
2020-12-03T20:04:02.848Z,1607025842.848 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 62.263940 min 
2020-12-03T20:04:02.848Z,1607025842.848 [Default:CheckIn:E] Stopped
2020-12-03T20:04:02.848Z,1607025842.848 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-03T20:04:02.848Z,1607025842.848 [Default:CheckIn] Stopped
2020-12-03T20:04:02.848Z,1607025842.848 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-03T20:04:02.853Z,1607025842.853 [Default:CheckIn](INFO): Running loop #6
2020-12-03T20:04:02.853Z,1607025842.853 [Default:CheckIn] Running Loop=6
2020-12-03T20:04:02.853Z,1607025842.853 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-03T20:04:02.853Z,1607025842.853 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-03T20:04:04.446Z,1607025844.446 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-12-03T20:04:04.446Z,1607025844.446 [NAL9602] Data Fault, FailCount= 5
2020-12-03T20:04:04.446Z,1607025844.446 [NAL9602](ERROR): Data Fault
2020-12-03T20:04:04.514Z,1607025844.514 [CBIT](ERROR): Data Fault in component: NAL9602
2020-12-03T20:04:04.514Z,1607025844.514 [CBIT](CRITICAL): Data Fault in component: NAL9602
2020-12-03T20:04:04.849Z,1607025844.849 [NAL9602](INFO): Powering down
2020-12-03T20:04:04.918Z,1607025844.918 [CommandLine](FAULT): Scheduling is paused
2020-12-03T20:04:04.918Z,1607025844.918 [CBIT](INFO): Critical error at 20201203T200404
2020-12-03T20:04:04.918Z,1607025844.918 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2020-12-03T20:06:04.457Z,1607025964.457 [CBIT](INFO): Clearing failed state for component NAL9602
2020-12-03T20:06:04.457Z,1607025964.457 [NAL9602] No Fault, FailCount= 5
2020-12-03T20:06:04.837Z,1607025964.837 [NAL9602](INFO): Powering up NAL9602
2020-12-03T20:06:15.750Z,1607025975.750 [NAL9602](INFO): NAL9602 initialized
2020-12-03T20:09:03.016Z,1607026143.016 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-12-03T20:04:02.9Z
2020-12-03T20:09:03.016Z,1607026143.016 [Default:CheckIn:Read_GPS] Stopped
2020-12-03T20:09:03.016Z,1607026143.016 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-03T20:11:18.346Z,1607026278.346 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-12-03T20:11:42.500Z,1607026302.500 [DataOverHttps](INFO): Sending 104 bytes from file Logs/20201203T185907/Courier0019.lzma
2020-12-03T20:11:43.502Z,1607026303.502 [DataOverHttps](INFO): Moved sent file to Logs/20201203T185907/Courier0019.lzma.bak
2020-12-03T20:11:43.503Z,1607026303.503 [DataOverHttps](INFO): SBD MOMSN=13388536
2020-12-03T20:11:49.084Z,1607026309.084 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-03T20:12:15.958Z,1607026335.958 [DataOverHttps](INFO): Sending 247 bytes from file Logs/20201203T185907/Express0020.lzma
2020-12-03T20:12:16.958Z,1607026336.958 [DataOverHttps](INFO): Moved sent file to Logs/20201203T185907/Express0020.lzma.bak
2020-12-03T20:12:16.959Z,1607026336.959 [DataOverHttps](INFO): SBD MOMSN=13388543
2020-12-03T20:12:23.960Z,1607026343.960 [Default:CheckIn:Read_Iridium] Stopped
2020-12-03T20:12:23.960Z,1607026343.960 [Default:CheckIn:C.Wait] Running Loop=1
2020-12-03T20:12:23.960Z,1607026343.960 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2020-12-03T20:17:24.527Z,1607026644.527 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2020-12-03T20:17:24.527Z,1607026644.527 [Default:CheckIn:C.Wait] Stopped
2020-12-03T20:17:24.527Z,1607026644.527 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2020-12-03T20:17:24.527Z,1607026644.527 [Default:CheckIn:D] Running Loop=1
2020-12-03T20:17:24.923Z,1607026644.923 [Default:CheckIn:D] Stopped
2020-12-03T20:17:24.923Z,1607026644.923 [Default:CheckIn:E] Running Loop=1
2020-12-03T20:17:25.325Z,1607026645.325 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 75.637972 min 
2020-12-03T20:17:25.326Z,1607026645.326 [Default:CheckIn:E] Stopped
2020-12-03T20:17:25.326Z,1607026645.326 [Default:CheckIn](INFO): Completed Default:CheckIn
2020-12-03T20:17:25.326Z,1607026645.326 [Default:CheckIn] Stopped
2020-12-03T20:17:25.326Z,1607026645.326 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-03T20:17:25.326Z,1607026645.326 [Default:CheckIn](INFO): Running loop #7
2020-12-03T20:17:25.326Z,1607026645.326 [Default:CheckIn] Running Loop=7
2020-12-03T20:17:25.326Z,1607026645.326 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-03T20:17:25.326Z,1607026645.326 [Default:CheckIn:Read_GPS] Running Loop=1
2020-12-03T20:17:26.933Z,1607026646.933 [NAL9602](FAULT): GPS failed to acquire within timeout.
2020-12-03T20:17:26.934Z,1607026646.934 [NAL9602] Data Fault, FailCount= 1
2020-12-03T20:17:26.934Z,1607026646.934 [NAL9602](ERROR): Data Fault
2020-12-03T20:17:26.961Z,1607026646.961 [CBIT](ERROR): Data Fault in component: NAL9602
2020-12-03T20:17:27.343Z,1607026647.343 [NAL9602](INFO): Powering down
2020-12-03T20:17:28.188Z,1607026648.188 [CBIT](INFO): Clearing failed state for component NAL9602
2020-12-03T20:17:28.188Z,1607026648.188 [NAL9602] No Fault, FailCount= 1
2020-12-03T20:17:57.637Z,1607026677.637 [NAL9602](INFO): Powering up NAL9602
2020-12-03T20:18:08.546Z,1607026688.546 [NAL9602](INFO): NAL9602 initialized
2020-12-03T20:22:25.569Z,1607026945.569 [Default:CheckIn:Read_GPS](INFO): Timed out from 2020-12-03T20:17:25.3Z
2020-12-03T20:22:25.569Z,1607026945.569 [Default:CheckIn:Read_GPS] Stopped
2020-12-03T20:22:25.569Z,1607026945.569 [Default:CheckIn:Read_Iridium] Running Loop=1
2020-12-03T20:23:11.030Z,1607026991.030 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2020-12-03T20:23:41.727Z,1607027021.727 [NAL9602](INFO): Not Powering down - fast GPS
2020-12-03T20:25:00.828Z,1607027100.828 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201203T185907/Courier0022.lzma
2020-12-03T20:25:01.831Z,1607027101.831 [DataOverHttps](INFO): Moved sent file to Logs/20201203T185907/Courier0022.lzma.bak
2020-12-03T20:25:01.831Z,1607027101.831 [DataOverHttps](IMPORTANT): SBD MOMSN=13388603, MTMSN=20201203T202359
2020-12-03T20:25:14.671Z,1607027114.671 [DataOverHttps](INFO): Received command:run Engineering/lineCaptureDepthUSBLMode.xml
2020-12-03T20:25:14.804Z,1607027114.804 [CommandLine](IMPORTANT): got command run ./Missions/Engineering/lineCaptureDepthUSBLMode.xml
2020-12-03T20:25:14.804Z,1607027114.804 [MissionManager](INFO): Loading Mission: ./Missions/Engineering/lineCaptureDepthUSBLMode.xml
2020-12-03T20:25:14.883Z,1607027114.883 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode.MissionTimeout = 4.000000 h
2020-12-03T20:25:14.887Z,1607027114.887 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode.NeedCommsTime = 240.000000 min
2020-12-03T20:25:14.890Z,1607027114.890 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode.MicromodemCommsInterval = 5.000000 min
2020-12-03T20:25:14.893Z,1607027114.893 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode.Repeat = 1.000000 count
2020-12-03T20:25:14.896Z,1607027114.896 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode.WaitOnDockTimeout = 5.000000 min
2020-12-03T20:25:14.900Z,1607027114.900 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode.TransponderCode = 2.000000 count
2020-12-03T20:25:14.937Z,1607027114.937 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode.TrackingUpdatePeriod = 2.500000 s
2020-12-03T20:25:14.940Z,1607027114.940 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode.NumberOfPings = 1.000000 count
2020-12-03T20:25:14.943Z,1607027114.943 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode.NumberOfUSBLPings = 254.000000 count
2020-12-03T20:25:14.997Z,1607027114.997 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode.MinAltitude = 5.000000 m
2020-12-03T20:25:14.000Z,1607027115.000 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode.MaxDepth = 205.000000 m
2020-12-03T20:25:15.003Z,1607027115.003 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode.MinOffshore = 2.000000 km
2020-12-03T20:25:15.022Z,1607027115.022 [MissionManager](INFO): DefineOutput lineCaptureDepthUSBLMode.Mode = -1.000000 count
2020-12-03T20:25:15.022Z,1607027115.022 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2020-12-03T20:25:15.323Z,1607027115.323 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:Science.PeakDetectChlActive = 0 bool
2020-12-03T20:25:15.327Z,1607027115.327 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:Science.TimeWindowPeakReport = nan min
2020-12-03T20:25:15.329Z,1607027115.329 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:Science.HighestChlPeakReportActive = 0 bool
2020-12-03T20:25:15.332Z,1607027115.332 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:Science.PatchTracking = 0 bool
2020-12-03T20:25:15.335Z,1607027115.335 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:Science.FilterWidthHorizontal = 3.000000 count
2020-12-03T20:25:15.338Z,1607027115.338 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:Science.NumProfilesSlidingwindow = 100.000000 count
2020-12-03T20:25:15.341Z,1607027115.341 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:Science.OffPeakFractionHorizontal = 80.000000 %
2020-12-03T20:25:15.343Z,1607027115.343 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:Science.PeakDetectNO3Active = 0 bool
2020-12-03T20:25:15.346Z,1607027115.346 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:Science.PeakDetectOilActive = 0 bool
2020-12-03T20:25:15.348Z,1607027115.348 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:Science.PeakDetectFDOMActive = 0 bool
2020-12-03T20:25:15.351Z,1607027115.351 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:Science.UpwardDerivativeOfTemperatureActive = 0 bool
2020-12-03T20:25:15.354Z,1607027115.354 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:Science.EnabledAanderaaO2 = 0.000000 bool
2020-12-03T20:25:15.358Z,1607027115.358 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:Science.EnabledNeilBrown = 0.000000 bool
2020-12-03T20:25:15.421Z,1607027115.421 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:Science.EnabledSeabird = 1.000000 bool
2020-12-03T20:25:15.424Z,1607027115.424 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:Science.EnabledWetLabsBB2FL = 1.000000 bool
2020-12-03T20:25:15.439Z,1607027115.439 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:Science.EnabledWetLabsSeaOWL_UV_A = 0.000000 bool
2020-12-03T20:25:15.447Z,1607027115.447 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:Science.EnabledWetLabsUBAT = 0.000000 bool
2020-12-03T20:25:15.509Z,1607027115.509 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:Science.LowPassWindowLength = 20.000000 count
2020-12-03T20:25:15.512Z,1607027115.512 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:Science.MedianFilterLen = 5.000000 count
2020-12-03T20:25:15.531Z,1607027115.531 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:Science.PeakShallowBound = nan m
2020-12-03T20:25:15.540Z,1607027115.540 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:Science.PeakDeepBound = nan m
2020-12-03T20:25:15.543Z,1607027115.543 [MissionManager](INFO): DefineOutput lineCaptureDepthUSBLMode:Science.PeakChl = 0.000000 ug/l
2020-12-03T20:25:15.550Z,1607027115.550 [MissionManager](INFO): DefineOutput lineCaptureDepthUSBLMode:Science.PeakChlDepth = 0.000000 m
2020-12-03T20:25:15.559Z,1607027115.559 [MissionManager](INFO): DefineOutput lineCaptureDepthUSBLMode:Science.PeakChlTemperature = 0.000000 degC
2020-12-03T20:25:15.574Z,1607027115.574 [MissionManager](INFO): DefineOutput lineCaptureDepthUSBLMode:Science.PeakChlLatitude = nan arcdeg
2020-12-03T20:25:15.581Z,1607027115.581 [MissionManager](INFO): DefineOutput lineCaptureDepthUSBLMode:Science.PeakChlLongitude = nan arcdeg
2020-12-03T20:25:15.584Z,1607027115.584 [MissionManager](INFO): DefineOutput lineCaptureDepthUSBLMode:Science.PeakNO3 = 0.000000 umol/l
2020-12-03T20:25:15.592Z,1607027115.592 [MissionManager](INFO): DefineOutput lineCaptureDepthUSBLMode:Science.PeakNO3Depth = 0.000000 m
2020-12-03T20:25:15.603Z,1607027115.603 [MissionManager](INFO): DefineOutput lineCaptureDepthUSBLMode:Science.PeakNO3Latitude = nan arcdeg
2020-12-03T20:25:15.614Z,1607027115.614 [MissionManager](INFO): DefineOutput lineCaptureDepthUSBLMode:Science.PeakNO3Longitude = nan arcdeg
2020-12-03T20:25:15.617Z,1607027115.617 [MissionManager](INFO): DefineOutput lineCaptureDepthUSBLMode:Science.PatchChl = 0.000000 m
2020-12-03T20:25:15.620Z,1607027115.620 [MissionManager](INFO): DefineOutput lineCaptureDepthUSBLMode:Science.PatchChlDepth = 0.000000 m
2020-12-03T20:25:15.627Z,1607027115.627 [MissionManager](INFO): DefineOutput lineCaptureDepthUSBLMode:Science.PatchChlLatitude = nan arcdeg
2020-12-03T20:25:15.631Z,1607027115.631 [MissionManager](INFO): DefineOutput lineCaptureDepthUSBLMode:Science.PatchChlLongitude = nan arcdeg
2020-12-03T20:25:15.642Z,1607027115.642 [MissionManager](INFO): DefineOutput lineCaptureDepthUSBLMode:Science.PatchChlDistance = nan m
2020-12-03T20:25:15.649Z,1607027115.649 [MissionManager](INFO): DefineOutput lineCaptureDepthUSBLMode:Science.PeakOil = 0.000000 kg/m3
2020-12-03T20:25:15.652Z,1607027115.652 [MissionManager](INFO): DefineOutput lineCaptureDepthUSBLMode:Science.PeakOilDepth = 0.000000 m
2020-12-03T20:25:15.655Z,1607027115.655 [MissionManager](INFO): DefineOutput lineCaptureDepthUSBLMode:Science.PeakOilLatitude = nan arcdeg
2020-12-03T20:25:15.666Z,1607027115.666 [MissionManager](INFO): DefineOutput lineCaptureDepthUSBLMode:Science.PeakOilLongitude = nan arcdeg
2020-12-03T20:25:15.711Z,1607027115.711 [MissionManager](INFO): DefineOutput lineCaptureDepthUSBLMode:Science.PeakFDOM = 0.000000 ppb
2020-12-03T20:25:15.726Z,1607027115.726 [MissionManager](INFO): DefineOutput lineCaptureDepthUSBLMode:Science.PeakFDOMDepth = 0.000000 m
2020-12-03T20:25:15.730Z,1607027115.730 [MissionManager](INFO): DefineOutput lineCaptureDepthUSBLMode:Science.PeakFDOMLatitude = nan arcdeg
2020-12-03T20:25:15.762Z,1607027115.762 [MissionManager](INFO): DefineOutput lineCaptureDepthUSBLMode:Science.PeakFDOMLongitude = nan arcdeg
2020-12-03T20:25:15.765Z,1607027115.765 [MissionManager](INFO): DefineOutput lineCaptureDepthUSBLMode:Science.PatchFDOMDistance = nan m
2020-12-03T20:25:16.019Z,1607027116.019 [lineCaptureDepthUSBLMode:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-12-03T20:25:16.030Z,1607027116.030 [lineCaptureDepthUSBLMode:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Construct PeakDetectHorizontal.
2020-12-03T20:25:16.077Z,1607027116.077 [lineCaptureDepthUSBLMode:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-12-03T20:25:16.107Z,1607027116.107 [lineCaptureDepthUSBLMode:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-12-03T20:25:16.150Z,1607027116.150 [lineCaptureDepthUSBLMode:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2020-12-03T20:25:16.171Z,1607027116.171 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2020-12-03T20:25:16.236Z,1607027116.236 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:StandardEnvelopes.MinAltitude = 5.000000 m
2020-12-03T20:25:16.287Z,1607027116.287 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:StandardEnvelopes.MaxDepth = 200.000000 m
2020-12-03T20:25:16.304Z,1607027116.304 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:StandardEnvelopes.MinOffshore = 2000.000000 m
2020-12-03T20:25:16.330Z,1607027116.330 [lineCaptureDepthUSBLMode:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2020-12-03T20:25:16.383Z,1607027116.383 [lineCaptureDepthUSBLMode:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2020-12-03T20:25:16.412Z,1607027116.412 [lineCaptureDepthUSBLMode:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2020-12-03T20:25:16.455Z,1607027116.455 [MissionManager](INFO): Inserting Stack: Missions/Insert/NeedComms.xml
2020-12-03T20:25:16.553Z,1607027116.553 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:NeedComms.DiveInterval = 3.000000 h
2020-12-03T20:25:16.565Z,1607027116.565 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:NeedComms.WaitForPitchUp = 10.000000 min
2020-12-03T20:25:16.568Z,1607027116.568 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:NeedComms.SurfacePitch = 20.000000 arcdeg
2020-12-03T20:25:16.575Z,1607027116.575 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:NeedComms.SurfaceDepthRate = nan m/s
2020-12-03T20:25:16.582Z,1607027116.582 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:NeedComms.SurfaceSpeed = 1.000000 m/s
2020-12-03T20:25:16.593Z,1607027116.593 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:NeedComms.GPSTimeout = 7.000000 min
2020-12-03T20:25:16.596Z,1607027116.596 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:NeedComms.CommsTimeout = 30.000000 min
2020-12-03T20:25:16.611Z,1607027116.611 [lineCaptureDepthUSBLMode:NeedComms:B.GoToSurface](DEBUG): Construct GoToSurface.
2020-12-03T20:25:16.661Z,1607027116.661 [MissionManager](INFO): Inserting Stack: Missions/Insert/MicromodemComms.xml
2020-12-03T20:25:16.745Z,1607027116.745 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:MicromodemComms.Timeout = 10.000000 min
2020-12-03T20:25:16.754Z,1607027116.754 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:MicromodemComms.CommsInterval = 5.000000 min
2020-12-03T20:25:16.771Z,1607027116.771 [MissionManager](INFO): RedefineArg lineCaptureDepthUSBLMode:MicromodemComms.CommsInterval = reader:lineCaptureDepthUSBLMode.MicromodemCommsInterval
2020-12-03T20:25:16.847Z,1607027116.847 [MissionManager](INFO): Inserting Stack: Missions/Insert/LineCapture.xml
2020-12-03T20:25:17.238Z,1607027117.238 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:LineCapture.DockLat = 36.803400 arcdeg
2020-12-03T20:25:17.241Z,1607027117.241 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:LineCapture.DockLon = -121.822300 arcdeg
2020-12-03T20:25:17.244Z,1607027117.244 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:LineCapture.DetachTimeout = 30.000000 min
2020-12-03T20:25:17.248Z,1607027117.248 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:LineCapture.MidcourseDepth = 6.000000 m
2020-12-03T20:25:17.251Z,1607027117.251 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:LineCapture.MidcourseSpeed = 1.000000 m/s
2020-12-03T20:25:17.279Z,1607027117.279 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:LineCapture.MidcourseTimeout = 6.000000 h
2020-12-03T20:25:17.283Z,1607027117.283 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:LineCapture.TerminalRange = 650.000000 m
2020-12-03T20:25:17.286Z,1607027117.286 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:LineCapture.AcousticTimeout = 5.000000 min
2020-12-03T20:25:17.299Z,1607027117.299 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:LineCapture.SearchTimeout = 1.000000 h
2020-12-03T20:25:17.303Z,1607027117.303 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:LineCapture.CircleRadius = 25.000000 m
2020-12-03T20:25:17.318Z,1607027117.318 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:LineCapture.CircleMaxError = 1.000000 m
2020-12-03T20:25:17.321Z,1607027117.321 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:LineCapture.CircleTurnToPort = 0 bool
2020-12-03T20:25:17.324Z,1607027117.324 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:LineCapture.DepthDeadband = 0.010000 m
2020-12-03T20:25:17.327Z,1607027117.327 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:LineCapture.RudderDeadband = 0.001000 arcdeg
2020-12-03T20:25:17.340Z,1607027117.340 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:LineCapture.MinAltitude = 2.000000 m
2020-12-03T20:25:17.346Z,1607027117.346 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:LineCapture.MaxDepth = 8.000000 m
2020-12-03T20:25:17.355Z,1607027117.355 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:LineCapture.MinOffshore = 1.000000 m
2020-12-03T20:25:17.369Z,1607027117.369 [MissionManager](INFO): DefineOutput lineCaptureDepthUSBLMode:LineCapture.DoneTimeout = -1.000000 count
2020-12-03T20:25:17.372Z,1607027117.372 [MissionManager](INFO): DefineOutput lineCaptureDepthUSBLMode:LineCapture.Done = 0.000000 count
2020-12-03T20:25:17.375Z,1607027117.375 [MissionManager](INFO): DefineOutput lineCaptureDepthUSBLMode:LineCapture.DetachFromDock = 1.000000 count
2020-12-03T20:25:17.378Z,1607027117.378 [MissionManager](INFO): DefineOutput lineCaptureDepthUSBLMode:LineCapture.Midcourse = 2.000000 count
2020-12-03T20:25:17.383Z,1607027117.383 [MissionManager](INFO): DefineOutput lineCaptureDepthUSBLMode:LineCapture.Terminal = 3.000000 count
2020-12-03T20:25:17.386Z,1607027117.386 [MissionManager](INFO): DefineOutput lineCaptureDepthUSBLMode:LineCapture.GuidanceMode = 1.000000 n/a
2020-12-03T20:25:17.389Z,1607027117.389 [MissionManager](INFO): DefineOutput lineCaptureDepthUSBLMode:LineCapture.DockingDepth = 1.000000 m
2020-12-03T20:25:17.392Z,1607027117.392 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:LineCapture.ElapsedRange = 0.000000 min
2020-12-03T20:25:17.450Z,1607027117.450 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:LineCapture.Range = nan m
2020-12-03T20:25:17.455Z,1607027117.455 [MissionManager](INFO): Inserting Stack: Missions/Insert/StandardEnvelopes.xml
2020-12-03T20:25:17.565Z,1607027117.565 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:LineCapture:StandardEnvelopes.MinAltitude = 5.000000 m
2020-12-03T20:25:17.568Z,1607027117.568 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:LineCapture:StandardEnvelopes.MaxDepth = 200.000000 m
2020-12-03T20:25:17.583Z,1607027117.583 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:LineCapture:StandardEnvelopes.MinOffshore = 2000.000000 m
2020-12-03T20:25:17.597Z,1607027117.597 [lineCaptureDepthUSBLMode:LineCapture:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2020-12-03T20:25:17.616Z,1607027117.616 [lineCaptureDepthUSBLMode:LineCapture:StandardEnvelopes:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2020-12-03T20:25:17.648Z,1607027117.648 [lineCaptureDepthUSBLMode:LineCapture:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2020-12-03T20:25:17.709Z,1607027117.709 [lineCaptureDepthUSBLMode:LineCapture:HoldMassAndBuoyancy:B.Mass](DEBUG): Construct.
2020-12-03T20:25:17.713Z,1607027117.713 [lineCaptureDepthUSBLMode:LineCapture:HoldMassAndBuoyancy:C.Buoyancy](DEBUG): Construct Buoyancy.
2020-12-03T20:25:17.720Z,1607027117.720 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:DetachFromDock:guide:A.Undock](DEBUG): Construct.
2020-12-03T20:25:17.720Z,1607027117.720 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:DetachFromDock:guide:A.Undock](DEBUG): Initializing internal variables to default values.
2020-12-03T20:25:17.826Z,1607027117.826 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide:C.SetSpeed](DEBUG): Construct.
2020-12-03T20:25:17.837Z,1607027117.837 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide:D.Pitch](DEBUG): Construct.
2020-12-03T20:25:17.847Z,1607027117.847 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide:E.Waypoint](DEBUG): Construct Waypoint.
2020-12-03T20:25:17.901Z,1607027117.901 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide:G.Circle](DEBUG): Construct.
2020-12-03T20:25:17.966Z,1607027117.966 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:TerminalGuidance:guide:C.Pitch](DEBUG): Construct.
2020-12-03T20:25:17.989Z,1607027117.989 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:TerminalGuidance:guide:F.LineCapture](DEBUG): Construct.
2020-12-03T20:25:18.015Z,1607027118.015 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:TerminalGuidance:guide:F.LineCapture](DEBUG): Initializing internal variables to default values.
2020-12-03T20:25:18.015Z,1607027118.015 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:TerminalGuidance:guide:F.LineCapture](INFO): Transitioning guidance mode to: UNINITIALIZED
2020-12-03T20:25:18.020Z,1607027118.020 [lineCaptureDepthUSBLMode:LineCapture:K.Wait](DEBUG): Construct Wait.
2020-12-03T20:25:18.030Z,1607027118.030 [MissionManager](INFO): Inserting Stack: Missions/Insert/SetNavAcoustic.xml
2020-12-03T20:25:18.093Z,1607027118.093 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:SetNavAcoustic.DockLat = 36.803400 arcdeg
2020-12-03T20:25:18.096Z,1607027118.096 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:SetNavAcoustic.DockLon = -121.822300 arcdeg
2020-12-03T20:25:18.103Z,1607027118.103 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:SetNavAcoustic.DockRange = 5.000000 m
2020-12-03T20:25:18.111Z,1607027118.111 [MissionManager](INFO): DefineArg lineCaptureDepthUSBLMode:SetNavAcoustic.RangeTimeout = 5.000000 min
2020-12-03T20:25:18.126Z,1607027118.126 [lineCaptureDepthUSBLMode:SetNavAcoustic:UpdateNav:B.SetNav](DEBUG): Construct SetNav.
2020-12-03T20:25:18.162Z,1607027118.162 [lineCaptureDepthUSBLMode:Lap:Docked:B.Docked](DEBUG): Construct.
2020-12-03T20:25:18.162Z,1607027118.162 [lineCaptureDepthUSBLMode:Lap:Docked:B.Docked](DEBUG): Initializing internal variables to default values.
2020-12-03T20:25:18.182Z,1607027118.182 [lineCaptureDepthUSBLMode:Lap:Docked:Detach:B.Undock](DEBUG): Construct.
2020-12-03T20:25:18.183Z,1607027118.183 [lineCaptureDepthUSBLMode:Lap:Docked:Detach:B.Undock](DEBUG): Initializing internal variables to default values.
2020-12-03T20:25:18.353Z,1607027118.353 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
    xmlns:Estimation="Tethys/Estimation"
    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/Estimation http://okeanids.mbari.org/tethys/Xml/Estimation.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="lineCaptureDepthUSBLMode">

    <Description>
        Home to transponder while maintaining altitude. The mission sequence:
        (*) Start (1) Midcourse guidance: nav to target WP while querying
        transponder (2) Terminal guidance: home to target using DUSBL tracking
        data (4) Dock: final approach using fixed heading.
    </Description>

    <!-- Define mission arguments -->

    <DefineArg Name="MissionTimeout"><Description>
        Maximum length of mission
    </Description><Units:hour/><Value>4</Value></DefineArg>

    <DefineArg Name="NeedCommsTime"><Description>
        How often to surface for commumications
    </Description><Units:minute/><Value>240</Value></DefineArg>

    <DefineArg Name="MicromodemCommsInterval"><Description>
        How often to trigger Umodem communications
    </Description><Units:minute/><Value>5</Value></DefineArg>

    <DefineArg Name="Repeat"><Description>
        Number of times the vehicle should try to cycle through the homing
        sequence.
    </Description><Units:count/><Value>1</Value></DefineArg>

    <DefineArg Name="WaitOnDockTimeout"><Description>
        Time duration to wait on the dock after a successful line capture.
    </Description><Units:minute/><Value>5</Value></DefineArg>

    <DefineArg Name="TransponderCode"><Description>
        Transponder Address.
    </Description><Dock:Dock.transponderCode/></DefineArg>

    <DefineArg Name="TrackingUpdatePeriod"><Description>
        How long to wait between acoustic queries.
    </Description><Units:second/><Value>2.5</Value></DefineArg>

    <DefineArg Name="NumberOfPings"><Description>
        Number of pings requested before terminal guidance. Set this to 1.
    </Description><Units:count/><Value>1</Value></DefineArg>

    <DefineArg Name="NumberOfUSBLPings"><Description>
        Number of pings requested for Benthos USBL Mode. This should be 2x the distance but max is 255. It will request again after the 255 pings. 
    </Description><Units:count/><Value>254</Value></DefineArg>

    <DefineArg Name="MinAltitude"><Description>
        Minimum height above the sea floor for the entire mission.
    </Description><Units:meter/><Value>5</Value></DefineArg>

    <DefineArg Name="MaxDepth"><Description>
        Maximum depth for the entire mission.
    </Description><Units:meter/><Value>205</Value></DefineArg>

    <DefineArg Name="MinOffshore"><Description>
        Minimum offshore distance for the entire mission.
    </Description><Units:kilometer/><Value>2</Value></DefineArg>

    <!-- Mission Variables (don't change) -->

    <DefineOutput Name="Mode"><Description>
        Mission variable (don't change).
        The mission sets this variable to reflect the termination status of the LineCapture insert.
    </Description><Units:count/><Value>-1</Value></DefineOutput>

    <!-- Mission timeout -->

    <Timeout Duration="MissionTimeout"/>

    <Insert Filename="Insert/Science.xml">
        <Description>
            Most missions will run the science sensors. If you don't place this
            aggregate above NeedComms, science instruments get turned off on the
            last upcast and while floating on the surface.
        </Description> 
    </Insert>

    <!-- Safety nets and surfacing behaviors -->

    <Insert Filename="Insert/StandardEnvelopes.xml"/>

    <Assign><Sequence/><Arg Name="StandardEnvelopes:MinAltitude"/><Arg Name="MinAltitude"/></Assign>

    <Assign><Sequence/><Arg Name="StandardEnvelopes:MaxDepth"/><Arg Name="MaxDepth"/></Assign>

    <Assign><Sequence/><Arg Name="StandardEnvelopes:MinOffshore"/><Arg Name="MinOffshore"/></Assign>

    <Insert Filename="Insert/NeedComms.xml" Id="NeedComms"/>

    <Assign><Sequence/><Arg Name="NeedComms:DiveInterval"/><Arg Name="NeedCommsTime"/></Assign>

    <Assign><Sequence/><Arg Name="NeedComms:WaitForPitchUp"/><Units:minute/><Value>0</Value></Assign>

    <!-- Micromodem comms -->

    <Insert Filename="Insert/MicromodemComms.xml" Id="MicromodemComms">
        <RedefineArg Name="CommsInterval"><Arg Name="MicromodemCommsInterval"/></RedefineArg>
    </Insert>

    <!-- Add track acoustic contact directive high in the stack. -->

    <Estimation:TrackAcousticContact>
        <Parallel/>
        <Setting><Estimation:TrackAcousticContact.contactLabelSetting/><Arg Name="TransponderCode"/></Setting>
        <Setting><Estimation:TrackAcousticContact.numberOfSamplesSetting/><Arg Name="NumberOfPings"/></Setting>
        <Setting><Estimation:TrackAcousticContact.updatePeriodSetting/><Arg Name="TrackingUpdatePeriod"/></Setting>
    </Estimation:TrackAcousticContact>

    <!-- Add LineCapture directives. -->

    <Insert Filename="Insert/LineCapture.xml"/>
    
    <!-- Add SetNav directives. -->

    <Insert Filename="Insert/SetNavAcoustic.xml"/>

    <!-- Start mission sequence. -->

    <Aggregate Id="Lap">

        <Sequence Repeat="Repeat"/>

        <Assign><Sequence/><Arg Name="Mode"/><Units:count/><Value>-99</Value></Assign>

        <!-- Execute the LineCapture sequence. -->
        <Call RefId="LineCapture"/>

        <Assign><Sequence/><Arg Name="Mode"/><Arg Name="LineCapture:GuidanceMode"/></Assign>

        <Syslog Severity="Info">Mode<Arg Name="Mode"/><Units:count/></Syslog>

        <Aggregate Id="Terminal">
            <Sequence/>
                    <When>
                        <Arg Name="Mode"/><Eq><Units:count/><Value>3</Value></Eq>
                    </When>
            <Syslog Severity="Important">Terminal Mode. Requesting multiple pings if available. Range: <Estimation:TrackAcousticContact.range_to_contact/><Units:meter/>.</Syslog>
            <Assign><Sequence/><Arg Name="NumberOfPings"/><Arg Name="NumberOfUSBLPings"/></Assign>
        </Aggregate>


        <Aggregate Id="Docked">
            <Sequence/>

            <Break>
                <Arg Name="Mode"/><Lt><Units:count/><Value>0</Value></Lt>
            </Break>

            <Syslog Severity="Info">Waiting on dock for <Arg Name="WaitOnDockTimeout"/><Units:minute/></Syslog>

            <Guidance:Docked>
                <Sequence/>
                <Timeout Duration="WaitOnDockTimeout"/>
            </Guidance:Docked>

            <Aggregate Id="Detach">
                <Sequence/>

                <Syslog Severity="Important">Breaking off. Range: <Estimation:TrackAcousticContact.range_to_contact/><Units:meter/>.</Syslog>

                <Guidance:Undock>
                    <Sequence/>
                </Guidance:Undock>

            </Aggregate>
        </Aggregate>

    </Aggregate>

</Mission>


2020-12-03T20:25:18.359Z,1607027118.359 [Default] Stopped
2020-12-03T20:25:18.359Z,1607027118.359 [Default](DEBUG): Aggregate::uninitialize Default
2020-12-03T20:25:18.360Z,1607027118.360 [Default:B.GoToSurface] Stopped
2020-12-03T20:25:18.360Z,1607027118.360 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2020-12-03T20:25:18.360Z,1607027118.360 [Default:CheckIn] Stopped
2020-12-03T20:25:18.360Z,1607027118.360 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2020-12-03T20:25:18.360Z,1607027118.360 [Default:CheckIn:Read_Iridium] Stopped
2020-12-03T20:25:18.360Z,1607027118.360 [MissionManager](IMPORTANT): Started mission lineCaptureDepthUSBLMode
2020-12-03T20:25:18.360Z,1607027118.360 [lineCaptureDepthUSBLMode] Running Loop=1
2020-12-03T20:25:18.360Z,1607027118.360 [lineCaptureDepthUSBLMode](DEBUG): Aggregate::initialize lineCaptureDepthUSBLMode
2020-12-03T20:25:18.361Z,1607027118.361 [lineCaptureDepthUSBLMode:Science] Running Loop=1
2020-12-03T20:25:18.361Z,1607027118.361 [lineCaptureDepthUSBLMode:Science](DEBUG): Aggregate::initialize lineCaptureDepthUSBLMode:Science
2020-12-03T20:25:18.361Z,1607027118.361 [lineCaptureDepthUSBLMode:Science:A] Running Loop=1
2020-12-03T20:25:18.361Z,1607027118.361 [lineCaptureDepthUSBLMode:Science:B] Running Loop=1
2020-12-03T20:25:18.361Z,1607027118.361 [lineCaptureDepthUSBLMode:Science:C] Running Loop=1
2020-12-03T20:25:18.361Z,1607027118.361 [lineCaptureDepthUSBLMode:Science:D] Running Loop=1
2020-12-03T20:25:18.361Z,1607027118.361 [lineCaptureDepthUSBLMode:Science:E] Running Loop=1
2020-12-03T20:25:18.361Z,1607027118.361 [lineCaptureDepthUSBLMode:Science:F] Running Loop=1
2020-12-03T20:25:18.361Z,1607027118.361 [lineCaptureDepthUSBLMode:Science:Read_Oil] Running Loop=1
2020-12-03T20:25:18.361Z,1607027118.361 [lineCaptureDepthUSBLMode:Science:PeakDetectChl] Running Loop=1
2020-12-03T20:25:18.361Z,1607027118.361 [lineCaptureDepthUSBLMode:Science:PeakDetectChl](DEBUG): Aggregate::initialize lineCaptureDepthUSBLMode:Science:PeakDetectChl
2020-12-03T20:25:18.361Z,1607027118.361 [lineCaptureDepthUSBLMode:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2020-12-03T20:25:18.361Z,1607027118.361 [lineCaptureDepthUSBLMode:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-12-03T20:25:18.363Z,1607027118.363 [lineCaptureDepthUSBLMode:Science:HighestChlPeakReport] Running Loop=1
2020-12-03T20:25:18.364Z,1607027118.364 [lineCaptureDepthUSBLMode:Science:HighestChlPeakReport](DEBUG): Aggregate::initialize lineCaptureDepthUSBLMode:Science:HighestChlPeakReport
2020-12-03T20:25:18.364Z,1607027118.364 [lineCaptureDepthUSBLMode:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Running Loop=1
2020-12-03T20:25:18.364Z,1607027118.364 [lineCaptureDepthUSBLMode:Science:HighestChlPeakReport:A.PeakDetectHorizontal](INFO): Initialize.
2020-12-03T20:25:18.364Z,1607027118.364 [lineCaptureDepthUSBLMode:Science:PeakDetectNO3] Running Loop=1
2020-12-03T20:25:18.378Z,1607027118.378 [lineCaptureDepthUSBLMode:Science:PeakDetectNO3](DEBUG): Aggregate::initialize lineCaptureDepthUSBLMode:Science:PeakDetectNO3
2020-12-03T20:25:18.379Z,1607027118.379 [lineCaptureDepthUSBLMode:Science:PeakDetectNO3:A.PeakDetectVsDepth] Running Loop=1
2020-12-03T20:25:18.379Z,1607027118.379 [lineCaptureDepthUSBLMode:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-12-03T20:25:18.381Z,1607027118.381 [lineCaptureDepthUSBLMode:Science:PeakDetectOil] Running Loop=1
2020-12-03T20:25:18.381Z,1607027118.381 [lineCaptureDepthUSBLMode:Science:PeakDetectOil](DEBUG): Aggregate::initialize lineCaptureDepthUSBLMode:Science:PeakDetectOil
2020-12-03T20:25:18.381Z,1607027118.381 [lineCaptureDepthUSBLMode:Science:PeakDetectOil:A.PeakDetectVsDepth] Running Loop=1
2020-12-03T20:25:18.381Z,1607027118.381 [lineCaptureDepthUSBLMode:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-12-03T20:25:18.383Z,1607027118.383 [lineCaptureDepthUSBLMode:Science:PeakDetectFDOM] Running Loop=1
2020-12-03T20:25:18.383Z,1607027118.383 [lineCaptureDepthUSBLMode:Science:PeakDetectFDOM](DEBUG): Aggregate::initialize lineCaptureDepthUSBLMode:Science:PeakDetectFDOM
2020-12-03T20:25:18.383Z,1607027118.383 [lineCaptureDepthUSBLMode:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Running Loop=1
2020-12-03T20:25:18.383Z,1607027118.383 [lineCaptureDepthUSBLMode:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Initialize.
2020-12-03T20:25:18.385Z,1607027118.385 [CommandLine](IMPORTANT): Running ./Missions/Engineering/lineCaptureDepthUSBLMode.xml
2020-12-03T20:25:18.390Z,1607027118.390 [lineCaptureDepthUSBLMode:Science:OceanCurrent] Running Loop=1
2020-12-03T20:25:18.390Z,1607027118.390 [lineCaptureDepthUSBLMode:Science:OceanCurrent](DEBUG): Aggregate::initialize lineCaptureDepthUSBLMode:Science:OceanCurrent
2020-12-03T20:25:18.390Z,1607027118.390 [lineCaptureDepthUSBLMode:Science:OceanCurrent:A.] Running Loop=1
2020-12-03T20:25:18.391Z,1607027118.391 [lineCaptureDepthUSBLMode:Science:OceanCurrent:A.](INFO): Initializing CurrentEstimator.
2020-12-03T20:25:18.391Z,1607027118.391 [lineCaptureDepthUSBLMode:StandardEnvelopes] Running Loop=1
2020-12-03T20:25:18.391Z,1607027118.391 [lineCaptureDepthUSBLMode:StandardEnvelopes](DEBUG): Aggregate::initialize lineCaptureDepthUSBLMode:StandardEnvelopes
2020-12-03T20:25:18.391Z,1607027118.391 [lineCaptureDepthUSBLMode:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2020-12-03T20:25:18.391Z,1607027118.391 [lineCaptureDepthUSBLMode:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2020-12-03T20:25:18.391Z,1607027118.391 [lineCaptureDepthUSBLMode:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2020-12-03T20:25:18.391Z,1607027118.391 [lineCaptureDepthUSBLMode:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2020-12-03T20:25:18.391Z,1607027118.391 [lineCaptureDepthUSBLMode:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2020-12-03T20:25:18.391Z,1607027118.391 [lineCaptureDepthUSBLMode:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2020-12-03T20:25:18.392Z,1607027118.392 [lineCaptureDepthUSBLMode:MicromodemComms] Running Loop=1
2020-12-03T20:25:18.392Z,1607027118.392 [lineCaptureDepthUSBLMode:MicromodemComms](DEBUG): Aggregate::initialize lineCaptureDepthUSBLMode:MicromodemComms
2020-12-03T20:25:18.392Z,1607027118.392 [lineCaptureDepthUSBLMode:MicromodemComms:CheckIn] Running Loop=1
2020-12-03T20:25:18.392Z,1607027118.392 [lineCaptureDepthUSBLMode:MicromodemComms:CheckIn](DEBUG): Aggregate::initialize lineCaptureDepthUSBLMode:MicromodemComms:CheckIn
2020-12-03T20:25:18.392Z,1607027118.392 [lineCaptureDepthUSBLMode:MicromodemComms:CheckIn:A] Running Loop=1
2020-12-03T20:25:18.392Z,1607027118.392 [lineCaptureDepthUSBLMode:J.] Running Loop=1
2020-12-03T20:25:18.392Z,1607027118.392 [lineCaptureDepthUSBLMode:J.](INFO): Initializing TrackAcousticContact.
2020-12-03T20:25:18.393Z,1607027118.393 [lineCaptureDepthUSBLMode:C] Running Loop=1
2020-12-03T20:25:18.394Z,1607027118.394 [lineCaptureDepthUSBLMode:J.] Running Loop=1
2020-12-03T20:25:18.394Z,1607027118.394 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:18.394Z,1607027118.394 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:18.395Z,1607027118.395 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:18.395Z,1607027118.395 [lineCaptureDepthUSBLMode:MicromodemComms] Running Loop=1
2020-12-03T20:25:18.396Z,1607027118.396 [lineCaptureDepthUSBLMode:MicromodemComms:CheckIn:A](DEBUG): Initialize ReadDataComponent to sense platform_communications
2020-12-03T20:25:18.397Z,1607027118.397 [lineCaptureDepthUSBLMode:C] Stopped
2020-12-03T20:25:18.397Z,1607027118.397 [lineCaptureDepthUSBLMode:D] Running Loop=1
2020-12-03T20:25:18.397Z,1607027118.397 [lineCaptureDepthUSBLMode:StandardEnvelopes] Running Loop=1
2020-12-03T20:25:18.397Z,1607027118.397 [lineCaptureDepthUSBLMode:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2020-12-03T20:25:18.397Z,1607027118.397 [lineCaptureDepthUSBLMode:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2020-12-03T20:25:18.398Z,1607027118.398 [lineCaptureDepthUSBLMode:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2020-12-03T20:25:18.398Z,1607027118.398 [lineCaptureDepthUSBLMode:Science] Running Loop=1
2020-12-03T20:25:18.399Z,1607027118.399 [lineCaptureDepthUSBLMode:Science:PeakDetectFDOM] Stopped
2020-12-03T20:25:18.399Z,1607027118.399 [lineCaptureDepthUSBLMode:Science:PeakDetectFDOM](DEBUG): Aggregate::uninitialize lineCaptureDepthUSBLMode:Science:PeakDetectFDOM
2020-12-03T20:25:18.399Z,1607027118.399 [lineCaptureDepthUSBLMode:Science:PeakDetectFDOM:A.PeakDetectVsDepth] Stopped
2020-12-03T20:25:18.399Z,1607027118.399 [lineCaptureDepthUSBLMode:Science:PeakDetectFDOM:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-12-03T20:25:18.400Z,1607027118.400 [lineCaptureDepthUSBLMode:Science:PeakDetectOil] Stopped
2020-12-03T20:25:18.400Z,1607027118.400 [lineCaptureDepthUSBLMode:Science:PeakDetectOil](DEBUG): Aggregate::uninitialize lineCaptureDepthUSBLMode:Science:PeakDetectOil
2020-12-03T20:25:18.400Z,1607027118.400 [lineCaptureDepthUSBLMode:Science:PeakDetectOil:A.PeakDetectVsDepth] Stopped
2020-12-03T20:25:18.400Z,1607027118.400 [lineCaptureDepthUSBLMode:Science:PeakDetectOil:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-12-03T20:25:18.400Z,1607027118.400 [lineCaptureDepthUSBLMode:Science:PeakDetectNO3] Stopped
2020-12-03T20:25:18.400Z,1607027118.400 [lineCaptureDepthUSBLMode:Science:PeakDetectNO3](DEBUG): Aggregate::uninitialize lineCaptureDepthUSBLMode:Science:PeakDetectNO3
2020-12-03T20:25:18.400Z,1607027118.400 [lineCaptureDepthUSBLMode:Science:PeakDetectNO3:A.PeakDetectVsDepth] Stopped
2020-12-03T20:25:18.400Z,1607027118.400 [lineCaptureDepthUSBLMode:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-12-03T20:25:18.400Z,1607027118.400 [lineCaptureDepthUSBLMode:Science:HighestChlPeakReport] Stopped
2020-12-03T20:25:18.400Z,1607027118.400 [lineCaptureDepthUSBLMode:Science:HighestChlPeakReport](DEBUG): Aggregate::uninitialize lineCaptureDepthUSBLMode:Science:HighestChlPeakReport
2020-12-03T20:25:18.433Z,1607027118.433 [lineCaptureDepthUSBLMode:Science:HighestChlPeakReport:A.PeakDetectHorizontal] Stopped
2020-12-03T20:25:18.433Z,1607027118.433 [lineCaptureDepthUSBLMode:Science:HighestChlPeakReport:A.PeakDetectHorizontal](DEBUG): Uninitialize.
2020-12-03T20:25:18.433Z,1607027118.433 [lineCaptureDepthUSBLMode:Science:PeakDetectChl] Stopped
2020-12-03T20:25:18.433Z,1607027118.433 [lineCaptureDepthUSBLMode:Science:PeakDetectChl](DEBUG): Aggregate::uninitialize lineCaptureDepthUSBLMode:Science:PeakDetectChl
2020-12-03T20:25:18.433Z,1607027118.433 [lineCaptureDepthUSBLMode:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2020-12-03T20:25:18.433Z,1607027118.433 [lineCaptureDepthUSBLMode:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2020-12-03T20:25:18.434Z,1607027118.434 [lineCaptureDepthUSBLMode:Science:OceanCurrent:A.] Running Loop=1
2020-12-03T20:25:18.434Z,1607027118.434 [lineCaptureDepthUSBLMode:Science:Read_Oil] Stopped
2020-12-03T20:25:18.434Z,1607027118.434 [lineCaptureDepthUSBLMode:Science:F] Stopped
2020-12-03T20:25:18.434Z,1607027118.434 [lineCaptureDepthUSBLMode:Science:E] Running Loop=1
2020-12-03T20:25:18.435Z,1607027118.435 [lineCaptureDepthUSBLMode:Science:E](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2020-12-03T20:25:18.435Z,1607027118.435 [lineCaptureDepthUSBLMode:Science:D] Stopped
2020-12-03T20:25:18.435Z,1607027118.435 [lineCaptureDepthUSBLMode:Science:C] Running Loop=1
2020-12-03T20:25:18.436Z,1607027118.436 [lineCaptureDepthUSBLMode:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_temperature
2020-12-03T20:25:18.436Z,1607027118.436 [lineCaptureDepthUSBLMode:Science:C](DEBUG): Initialize ReadDataComponent to sense CTD_Seabird.sea_water_salinity
2020-12-03T20:25:18.436Z,1607027118.436 [lineCaptureDepthUSBLMode:Science:B] Running Loop=1
2020-12-03T20:25:18.437Z,1607027118.437 [lineCaptureDepthUSBLMode:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2020-12-03T20:25:18.438Z,1607027118.438 [lineCaptureDepthUSBLMode:Science:B](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2020-12-03T20:25:18.438Z,1607027118.438 [lineCaptureDepthUSBLMode:Science:A] Stopped
2020-12-03T20:25:18.513Z,1607027118.513 [WetLabsBB2FL](INFO): Powering up
2020-12-03T20:25:18.782Z,1607027118.782 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:18.783Z,1607027118.783 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:18.783Z,1607027118.783 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:18.783Z,1607027118.783 [lineCaptureDepthUSBLMode:D] Stopped
2020-12-03T20:25:18.784Z,1607027118.784 [lineCaptureDepthUSBLMode:E] Running Loop=1
2020-12-03T20:25:19.165Z,1607027119.165 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:19.166Z,1607027119.166 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:19.166Z,1607027119.166 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:19.167Z,1607027119.167 [lineCaptureDepthUSBLMode:E] Stopped
2020-12-03T20:25:19.167Z,1607027119.167 [lineCaptureDepthUSBLMode:G] Running Loop=1
2020-12-03T20:25:19.526Z,1607027119.526 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:19.526Z,1607027119.526 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:19.527Z,1607027119.527 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:19.527Z,1607027119.527 [lineCaptureDepthUSBLMode:G] Stopped
2020-12-03T20:25:19.527Z,1607027119.527 [lineCaptureDepthUSBLMode:H] Running Loop=1
2020-12-03T20:25:19.945Z,1607027119.945 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:19.946Z,1607027119.946 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:19.946Z,1607027119.946 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:19.947Z,1607027119.947 [lineCaptureDepthUSBLMode:H] Stopped
2020-12-03T20:25:19.947Z,1607027119.947 [lineCaptureDepthUSBLMode:Lap] Running Loop=1
2020-12-03T20:25:19.947Z,1607027119.947 [lineCaptureDepthUSBLMode:Lap](DEBUG): Aggregate::initialize lineCaptureDepthUSBLMode:Lap
2020-12-03T20:25:19.947Z,1607027119.947 [lineCaptureDepthUSBLMode:Lap:A] Running Loop=1
2020-12-03T20:25:19.948Z,1607027119.948 [lineCaptureDepthUSBLMode:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2020-12-03T20:25:20.363Z,1607027120.363 [lineCaptureDepthUSBLMode:Lap:A] Stopped
2020-12-03T20:25:20.363Z,1607027120.363 [lineCaptureDepthUSBLMode:Lap:B] Running Loop=1
2020-12-03T20:25:20.363Z,1607027120.363 [lineCaptureDepthUSBLMode:Lap:B](DEBUG): Aggregate::initialize lineCaptureDepthUSBLMode:Lap:B
2020-12-03T20:25:20.363Z,1607027120.363 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:20.363Z,1607027120.363 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:20.364Z,1607027120.364 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:20.770Z,1607027120.770 [lineCaptureDepthUSBLMode:LineCapture] Running Loop=1
2020-12-03T20:25:20.770Z,1607027120.770 [lineCaptureDepthUSBLMode:LineCapture](DEBUG): Aggregate::initialize lineCaptureDepthUSBLMode:LineCapture
2020-12-03T20:25:20.771Z,1607027120.771 [lineCaptureDepthUSBLMode:LineCapture:StandardEnvelopes] Running Loop=1
2020-12-03T20:25:20.771Z,1607027120.771 [lineCaptureDepthUSBLMode:LineCapture:StandardEnvelopes](DEBUG): Aggregate::initialize lineCaptureDepthUSBLMode:LineCapture:StandardEnvelopes
2020-12-03T20:25:20.771Z,1607027120.771 [lineCaptureDepthUSBLMode:LineCapture:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2020-12-03T20:25:20.771Z,1607027120.771 [lineCaptureDepthUSBLMode:LineCapture:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2020-12-03T20:25:20.771Z,1607027120.771 [lineCaptureDepthUSBLMode:LineCapture:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2020-12-03T20:25:20.771Z,1607027120.771 [lineCaptureDepthUSBLMode:LineCapture:StandardEnvelopes:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2020-12-03T20:25:20.771Z,1607027120.771 [lineCaptureDepthUSBLMode:LineCapture:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2020-12-03T20:25:20.771Z,1607027120.771 [lineCaptureDepthUSBLMode:LineCapture:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2020-12-03T20:25:20.771Z,1607027120.771 [lineCaptureDepthUSBLMode:LineCapture:G] Running Loop=1
2020-12-03T20:25:20.771Z,1607027120.771 [lineCaptureDepthUSBLMode:LineCapture:H] Running Loop=1
2020-12-03T20:25:20.771Z,1607027120.771 [lineCaptureDepthUSBLMode:LineCapture:HoldMassAndBuoyancy] Running Loop=1
2020-12-03T20:25:20.772Z,1607027120.772 [lineCaptureDepthUSBLMode:LineCapture:HoldMassAndBuoyancy](DEBUG): Aggregate::initialize lineCaptureDepthUSBLMode:LineCapture:HoldMassAndBuoyancy
2020-12-03T20:25:20.772Z,1607027120.772 [lineCaptureDepthUSBLMode:LineCapture:HoldMassAndBuoyancy:A] Running Loop=1
2020-12-03T20:25:20.772Z,1607027120.772 [lineCaptureDepthUSBLMode:LineCapture:HoldMassAndBuoyancy:B.Mass] Running Loop=1
2020-12-03T20:25:20.772Z,1607027120.772 [lineCaptureDepthUSBLMode:LineCapture:HoldMassAndBuoyancy:B.Mass](DEBUG): Initialize.
2020-12-03T20:25:20.772Z,1607027120.772 [lineCaptureDepthUSBLMode:LineCapture:HoldMassAndBuoyancy:C.Buoyancy] Running Loop=1
2020-12-03T20:25:20.772Z,1607027120.772 [lineCaptureDepthUSBLMode:LineCapture:HoldMassAndBuoyancy:C.Buoyancy](DEBUG): Initialize Buoyancy Component.
2020-12-03T20:25:20.772Z,1607027120.772 [lineCaptureDepthUSBLMode:LineCapture:A] Running Loop=1
2020-12-03T20:25:20.789Z,1607027120.789 [lineCaptureDepthUSBLMode:LineCapture:HoldMassAndBuoyancy] Stopped
2020-12-03T20:25:20.789Z,1607027120.789 [lineCaptureDepthUSBLMode:LineCapture:HoldMassAndBuoyancy](DEBUG): Aggregate::uninitialize lineCaptureDepthUSBLMode:LineCapture:HoldMassAndBuoyancy
2020-12-03T20:25:20.789Z,1607027120.789 [lineCaptureDepthUSBLMode:LineCapture:HoldMassAndBuoyancy:A] Stopped
2020-12-03T20:25:20.789Z,1607027120.789 [lineCaptureDepthUSBLMode:LineCapture:HoldMassAndBuoyancy:B.Mass] Stopped
2020-12-03T20:25:20.789Z,1607027120.789 [lineCaptureDepthUSBLMode:LineCapture:HoldMassAndBuoyancy:B.Mass](DEBUG): Uninitialize.
2020-12-03T20:25:20.789Z,1607027120.789 [lineCaptureDepthUSBLMode:LineCapture:HoldMassAndBuoyancy:C.Buoyancy] Stopped
2020-12-03T20:25:20.789Z,1607027120.789 [lineCaptureDepthUSBLMode:LineCapture:HoldMassAndBuoyancy:C.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2020-12-03T20:25:20.790Z,1607027120.790 [lineCaptureDepthUSBLMode:LineCapture:H] Running Loop=1
2020-12-03T20:25:20.790Z,1607027120.790 [lineCaptureDepthUSBLMode:LineCapture:G] Running Loop=1
2020-12-03T20:25:20.790Z,1607027120.790 [lineCaptureDepthUSBLMode:LineCapture:StandardEnvelopes] Running Loop=1
2020-12-03T20:25:20.790Z,1607027120.790 [lineCaptureDepthUSBLMode:LineCapture:StandardEnvelopes:C.OffshoreEnvelope] Running Loop=1
2020-12-03T20:25:20.791Z,1607027120.791 [lineCaptureDepthUSBLMode:LineCapture:StandardEnvelopes:B.DepthEnvelope] Running Loop=1
2020-12-03T20:25:20.791Z,1607027120.791 [lineCaptureDepthUSBLMode:LineCapture:StandardEnvelopes:A.AltitudeEnvelope] Running Loop=1
2020-12-03T20:25:20.791Z,1607027120.791 [lineCaptureDepthUSBLMode:LineCapture:A] Stopped
2020-12-03T20:25:20.791Z,1607027120.791 [lineCaptureDepthUSBLMode:LineCapture:B] Running Loop=1
2020-12-03T20:25:20.792Z,1607027120.792 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:20.792Z,1607027120.792 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:20.793Z,1607027120.793 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:21.171Z,1607027121.171 [lineCaptureDepthUSBLMode:LineCapture:B] Stopped
2020-12-03T20:25:21.171Z,1607027121.171 [lineCaptureDepthUSBLMode:LineCapture:D] Running Loop=1
2020-12-03T20:25:21.171Z,1607027121.171 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:21.171Z,1607027121.171 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:21.171Z,1607027121.171 [lineCaptureDepthUSBLMode:J.](INFO): update period (2.500000 s) has elapsed, querying...
2020-12-03T20:25:21.172Z,1607027121.172 [lineCaptureDepthUSBLMode:J.](INFO): *** querying acoustic contact ***
2020-12-03T20:25:21.562Z,1607027121.562 [DAT](INFO): ****** received valid address query ******
2020-12-03T20:25:21.562Z,1607027121.562 [DAT](INFO): ****** received valid ping request ******
2020-12-03T20:25:21.562Z,1607027121.562 [DAT](INFO): setting remote address to 0
2020-12-03T20:25:21.590Z,1607027121.590 [CTD_Seabird](ERROR): Failed to parse device response: <!--startlogging-->
2020-12-03T20:25:21.616Z,1607027121.616 [lineCaptureDepthUSBLMode:LineCapture:D] Stopped
2020-12-03T20:25:21.616Z,1607027121.616 [lineCaptureDepthUSBLMode:LineCapture:E] Running Loop=1
2020-12-03T20:25:21.629Z,1607027121.629 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:21.630Z,1607027121.630 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:21.630Z,1607027121.630 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:21.942Z,1607027121.942 [DAT](INFO): DAT read: user:6>

2020-12-03T20:25:21.943Z,1607027121.943 [DAT](INFO): DAT read: RemoteAddr      | 0           

2020-12-03T20:25:21.943Z,1607027121.943 [DAT](INFO): set remote address to 0
2020-12-03T20:25:21.944Z,1607027121.944 [DAT](INFO): ****** received valid address query ******
2020-12-03T20:25:21.944Z,1607027121.944 [DAT](INFO): ****** received valid ping request ******
2020-12-03T20:25:21.944Z,1607027121.944 [DAT](INFO): Querying Benthos address 2 with one ping in standard two-way mode.
2020-12-03T20:25:21.945Z,1607027121.945 [DAT](INFO): ****** ping requested ******
2020-12-03T20:25:22.050Z,1607027122.050 [lineCaptureDepthUSBLMode:LineCapture:E] Stopped
2020-12-03T20:25:22.050Z,1607027122.050 [lineCaptureDepthUSBLMode:LineCapture:F] Running Loop=1
2020-12-03T20:25:22.051Z,1607027122.051 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:22.051Z,1607027122.051 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:22.052Z,1607027122.052 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:22.346Z,1607027122.346 [DAT](INFO): DAT read: user:8>

2020-12-03T20:25:22.347Z,1607027122.347 [DAT](INFO): DAT read: Tx time:20:23:37.7992

2020-12-03T20:25:22.347Z,1607027122.347 [DAT](INFO): Ping request sent.
2020-12-03T20:25:22.347Z,1607027122.347 [DAT](INFO): transmitted an acoustic signal
2020-12-03T20:25:22.348Z,1607027122.348 [DAT](INFO): publishing transmit ping time
2020-12-03T20:25:22.384Z,1607027122.384 [lineCaptureDepthUSBLMode:LineCapture:F] Stopped
2020-12-03T20:25:22.384Z,1607027122.384 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence] Running Loop=1
2020-12-03T20:25:22.384Z,1607027122.384 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence](DEBUG): Aggregate::initialize lineCaptureDepthUSBLMode:LineCapture:HomingSequence
2020-12-03T20:25:22.384Z,1607027122.384 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:DetachFromDock] Running Loop=1
2020-12-03T20:25:22.384Z,1607027122.384 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:DetachFromDock](DEBUG): Aggregate::initialize lineCaptureDepthUSBLMode:LineCapture:HomingSequence:DetachFromDock
2020-12-03T20:25:22.384Z,1607027122.384 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:DetachFromDock:guide] Running Loop=1
2020-12-03T20:25:22.393Z,1607027122.393 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:DetachFromDock:guide](DEBUG): Aggregate::initialize lineCaptureDepthUSBLMode:LineCapture:HomingSequence:DetachFromDock:guide
2020-12-03T20:25:22.393Z,1607027122.393 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:DetachFromDock:guide:A.Undock] Running Loop=1
2020-12-03T20:25:22.393Z,1607027122.393 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:DetachFromDock:guide:A.Undock](DEBUG): Initialize.
2020-12-03T20:25:22.393Z,1607027122.393 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:DetachFromDock:guide:A.Undock](DEBUG): Initializing internal variables to default values.
2020-12-03T20:25:22.394Z,1607027122.394 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance] Running Loop=1
2020-12-03T20:25:22.394Z,1607027122.394 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance](DEBUG): Aggregate::initialize lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance
2020-12-03T20:25:22.394Z,1607027122.394 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide] Running Loop=1
2020-12-03T20:25:22.394Z,1607027122.394 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide](DEBUG): Aggregate::initialize lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide
2020-12-03T20:25:22.394Z,1607027122.394 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide:C.SetSpeed] Running Loop=1
2020-12-03T20:25:22.394Z,1607027122.394 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide:C.SetSpeed](DEBUG): Initialize.
2020-12-03T20:25:22.394Z,1607027122.394 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide:D.Pitch] Running Loop=1
2020-12-03T20:25:22.394Z,1607027122.394 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide:D.Pitch](DEBUG): Initialize.
2020-12-03T20:25:22.395Z,1607027122.395 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide:A] Running Loop=1
2020-12-03T20:25:22.395Z,1607027122.395 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:TerminalGuidance] Running Loop=1
2020-12-03T20:25:22.395Z,1607027122.395 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:TerminalGuidance](DEBUG): Aggregate::initialize lineCaptureDepthUSBLMode:LineCapture:HomingSequence:TerminalGuidance
2020-12-03T20:25:22.395Z,1607027122.395 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:TerminalGuidance:guide] Running Loop=1
2020-12-03T20:25:22.395Z,1607027122.395 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:TerminalGuidance:guide](DEBUG): Aggregate::initialize lineCaptureDepthUSBLMode:LineCapture:HomingSequence:TerminalGuidance:guide
2020-12-03T20:25:22.395Z,1607027122.395 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:TerminalGuidance:guide:C.Pitch] Running Loop=1
2020-12-03T20:25:22.395Z,1607027122.395 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:TerminalGuidance:guide:C.Pitch](DEBUG): Initialize.
2020-12-03T20:25:22.395Z,1607027122.395 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:TerminalGuidance:guide:D] Running Loop=1
2020-12-03T20:25:22.395Z,1607027122.395 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:TerminalGuidance:guide:E] Running Loop=1
2020-12-03T20:25:22.396Z,1607027122.396 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:TerminalGuidance:guide:A] Running Loop=1
2020-12-03T20:25:22.405Z,1607027122.405 [lineCaptureDepthUSBLMode:LineCapture:StandardEnvelopes:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2020-12-03T20:25:22.405Z,1607027122.405 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:22.406Z,1607027122.406 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:22.406Z,1607027122.406 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:22.612Z,1607027122.612 [CTD_Seabird](ERROR): Salinity reading out of range: 0.271908 psu
2020-12-03T20:25:22.802Z,1607027122.802 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:TerminalGuidance] Stopped
2020-12-03T20:25:22.802Z,1607027122.802 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:TerminalGuidance](DEBUG): Aggregate::uninitialize lineCaptureDepthUSBLMode:LineCapture:HomingSequence:TerminalGuidance
2020-12-03T20:25:22.802Z,1607027122.802 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:TerminalGuidance:guide] Stopped
2020-12-03T20:25:22.802Z,1607027122.802 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:TerminalGuidance:guide](DEBUG): Aggregate::uninitialize lineCaptureDepthUSBLMode:LineCapture:HomingSequence:TerminalGuidance:guide
2020-12-03T20:25:22.802Z,1607027122.802 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:TerminalGuidance:guide:A] Stopped
2020-12-03T20:25:22.802Z,1607027122.802 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:TerminalGuidance:guide:C.Pitch] Stopped
2020-12-03T20:25:22.802Z,1607027122.802 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:TerminalGuidance:guide:D] Stopped
2020-12-03T20:25:22.802Z,1607027122.802 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:TerminalGuidance:guide:E] Stopped
2020-12-03T20:25:22.802Z,1607027122.802 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance] Stopped
2020-12-03T20:25:22.803Z,1607027122.803 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance](DEBUG): Aggregate::uninitialize lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance
2020-12-03T20:25:22.803Z,1607027122.803 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide] Stopped
2020-12-03T20:25:22.803Z,1607027122.803 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide](DEBUG): Aggregate::uninitialize lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide
2020-12-03T20:25:22.803Z,1607027122.803 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide:A] Stopped
2020-12-03T20:25:22.803Z,1607027122.803 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide:C.SetSpeed] Stopped
2020-12-03T20:25:22.803Z,1607027122.803 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide:C.SetSpeed](DEBUG): Uninitialize.
2020-12-03T20:25:22.803Z,1607027122.803 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide:D.Pitch] Stopped
2020-12-03T20:25:22.803Z,1607027122.803 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:DetachFromDock] Running Loop=1
2020-12-03T20:25:22.803Z,1607027122.803 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:DetachFromDock:guide:A.Undock](INFO): DDM isn't loaded, so simply satisfying DDM request.
2020-12-03T20:25:22.804Z,1607027122.804 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:DetachFromDock:guide:A.Undock](INFO): Detaching from dock.
2020-12-03T20:25:22.813Z,1607027122.813 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:22.814Z,1607027122.814 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:22.814Z,1607027122.814 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:23.185Z,1607027123.185 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:DetachFromDock:guide:A.Undock](INFO): Detected possible detachment. Starting timer.
2020-12-03T20:25:23.187Z,1607027123.187 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:23.187Z,1607027123.187 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:23.188Z,1607027123.188 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:23.618Z,1607027123.618 [CTD_Seabird](ERROR): Salinity reading out of range: 0.271731 psu
2020-12-03T20:25:23.625Z,1607027123.625 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:23.626Z,1607027123.626 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:23.626Z,1607027123.626 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:24.015Z,1607027124.015 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:24.016Z,1607027124.016 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:24.016Z,1607027124.016 [lineCaptureDepthUSBLMode:J.](INFO): update period (2.500000 s) has elapsed, querying...
2020-12-03T20:25:24.016Z,1607027124.016 [lineCaptureDepthUSBLMode:J.](INFO): *** querying acoustic contact ***
2020-12-03T20:25:24.411Z,1607027124.411 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:24.411Z,1607027124.411 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:24.412Z,1607027124.412 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:24.608Z,1607027124.608 [CTD_Seabird](ERROR): Salinity reading out of range: 0.271613 psu
2020-12-03T20:25:24.800Z,1607027124.800 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:24.800Z,1607027124.800 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:24.809Z,1607027124.809 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:25.192Z,1607027125.192 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:25.192Z,1607027125.192 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:25.192Z,1607027125.192 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:25.600Z,1607027125.600 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:25.600Z,1607027125.600 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:25.601Z,1607027125.601 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:25.627Z,1607027125.627 [CTD_Seabird](ERROR): Salinity reading out of range: 0.271437 psu
2020-12-03T20:25:26.003Z,1607027126.003 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:26.003Z,1607027126.003 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:26.003Z,1607027126.003 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:26.401Z,1607027126.401 [DataOverHttps](INFO): Sending 223 bytes from file Logs/20201203T185907/Express0023.lzma
2020-12-03T20:25:26.424Z,1607027126.424 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:26.424Z,1607027126.424 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:26.433Z,1607027126.433 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:26.631Z,1607027126.631 [CTD_Seabird](ERROR): Salinity reading out of range: 0.271439 psu
2020-12-03T20:25:26.804Z,1607027126.804 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:26.804Z,1607027126.804 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:26.805Z,1607027126.805 [lineCaptureDepthUSBLMode:J.](INFO): update period (2.500000 s) has elapsed, querying...
2020-12-03T20:25:26.805Z,1607027126.805 [lineCaptureDepthUSBLMode:J.](INFO): *** querying acoustic contact ***
2020-12-03T20:25:27.203Z,1607027127.203 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:27.203Z,1607027127.203 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:27.203Z,1607027127.203 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:27.402Z,1607027127.402 [DataOverHttps](INFO): Moved sent file to Logs/20201203T185907/Express0023.lzma.bak
2020-12-03T20:25:27.403Z,1607027127.403 [DataOverHttps](INFO): SBD MOMSN=13388612
2020-12-03T20:25:27.624Z,1607027127.624 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:27.625Z,1607027127.625 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:27.625Z,1607027127.625 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:27.635Z,1607027127.635 [CTD_Seabird](ERROR): Salinity reading out of range: 0.271264 psu
2020-12-03T20:25:28.036Z,1607027128.036 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:28.036Z,1607027128.036 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:28.041Z,1607027128.041 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:28.498Z,1607027128.498 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:28.499Z,1607027128.499 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:28.499Z,1607027128.499 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:28.639Z,1607027128.639 [CTD_Seabird](ERROR): Salinity reading out of range: 0.271092 psu
2020-12-03T20:25:28.835Z,1607027128.835 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:28.835Z,1607027128.835 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:28.836Z,1607027128.836 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:29.255Z,1607027129.255 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:29.255Z,1607027129.255 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:29.256Z,1607027129.256 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:29.643Z,1607027129.643 [CTD_Seabird](ERROR): Salinity reading out of range: 0.270915 psu
2020-12-03T20:25:29.667Z,1607027129.667 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:29.667Z,1607027129.667 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:29.667Z,1607027129.667 [lineCaptureDepthUSBLMode:J.](INFO): update period (2.500000 s) has elapsed, querying...
2020-12-03T20:25:29.667Z,1607027129.667 [lineCaptureDepthUSBLMode:J.](INFO): *** querying acoustic contact ***
2020-12-03T20:25:30.072Z,1607027130.072 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:30.072Z,1607027130.072 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:30.077Z,1607027130.077 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:30.486Z,1607027130.486 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:30.486Z,1607027130.486 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:30.487Z,1607027130.487 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:30.647Z,1607027130.647 [CTD_Seabird](ERROR): Salinity reading out of range: 0.270794 psu
2020-12-03T20:25:30.876Z,1607027130.876 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:30.901Z,1607027130.901 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:30.904Z,1607027130.904 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:31.267Z,1607027131.267 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:31.268Z,1607027131.268 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:31.268Z,1607027131.268 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:31.650Z,1607027131.650 [CTD_Seabird](ERROR): Salinity reading out of range: 0.270620 psu
2020-12-03T20:25:31.699Z,1607027131.699 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:31.699Z,1607027131.699 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:31.700Z,1607027131.700 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:32.061Z,1607027132.061 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:32.062Z,1607027132.062 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:32.062Z,1607027132.062 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:32.454Z,1607027132.454 [DAT](INFO): ****** received valid address query ******
2020-12-03T20:25:32.455Z,1607027132.455 [DAT](INFO): ****** received valid ping request ******
2020-12-03T20:25:32.455Z,1607027132.455 [DAT](INFO): Querying Benthos address 2 with one ping in standard two-way mode.
2020-12-03T20:25:32.460Z,1607027132.460 [DAT](INFO): ****** ping requested ******
2020-12-03T20:25:32.484Z,1607027132.484 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:32.484Z,1607027132.484 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:32.489Z,1607027132.489 [lineCaptureDepthUSBLMode:J.](INFO): update period (2.500000 s) has elapsed, querying...
2020-12-03T20:25:32.489Z,1607027132.489 [lineCaptureDepthUSBLMode:J.](INFO): *** querying acoustic contact ***
2020-12-03T20:25:32.655Z,1607027132.655 [CTD_Seabird](ERROR): Salinity reading out of range: 0.270445 psu
2020-12-03T20:25:32.854Z,1607027132.854 [DAT](INFO): ****** received valid address query ******
2020-12-03T20:25:32.854Z,1607027132.854 [DAT](INFO): ****** received valid ping request ******
2020-12-03T20:25:32.902Z,1607027132.902 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:32.903Z,1607027132.903 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:32.903Z,1607027132.903 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:33.254Z,1607027133.254 [DAT](INFO): ****** received valid address query ******
2020-12-03T20:25:33.254Z,1607027133.254 [DAT](INFO): ****** received valid ping request ******
2020-12-03T20:25:33.272Z,1607027133.272 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:33.272Z,1607027133.272 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:33.273Z,1607027133.273 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:33.659Z,1607027133.659 [CTD_Seabird](ERROR): Salinity reading out of range: 0.270269 psu
2020-12-03T20:25:33.674Z,1607027133.674 [DAT](INFO): ****** received valid address query ******
2020-12-03T20:25:33.674Z,1607027133.674 [DAT](INFO): ****** received valid ping request ******
2020-12-03T20:25:33.692Z,1607027133.692 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:33.692Z,1607027133.692 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:33.693Z,1607027133.693 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:34.066Z,1607027134.066 [DAT](INFO): ****** received valid address query ******
2020-12-03T20:25:34.066Z,1607027134.066 [DAT](INFO): ****** received valid ping request ******
2020-12-03T20:25:34.112Z,1607027134.112 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:34.112Z,1607027134.112 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:34.121Z,1607027134.121 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:34.475Z,1607027134.475 [DAT](INFO): DAT read: Response Not Received

2020-12-03T20:25:34.475Z,1607027134.475 [DAT](INFO): response not received
2020-12-03T20:25:34.475Z,1607027134.475 [DAT](INFO): DAT read: user:9>

2020-12-03T20:25:34.476Z,1607027134.476 [DAT](INFO): DAT read: Tx time:20:23:50.2492

2020-12-03T20:25:34.476Z,1607027134.476 [DAT](INFO): Ping request sent.
2020-12-03T20:25:34.477Z,1607027134.477 [DAT](INFO): transmitted an acoustic signal
2020-12-03T20:25:34.477Z,1607027134.477 [DAT](INFO): publishing transmit ping time
2020-12-03T20:25:34.548Z,1607027134.548 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:34.548Z,1607027134.548 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:34.565Z,1607027134.565 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:34.663Z,1607027134.663 [CTD_Seabird](ERROR): Salinity reading out of range: 0.270091 psu
2020-12-03T20:25:34.931Z,1607027134.931 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:34.931Z,1607027134.931 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:34.932Z,1607027134.932 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:35.300Z,1607027135.300 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:35.300Z,1607027135.300 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:35.321Z,1607027135.321 [lineCaptureDepthUSBLMode:J.](INFO): update period (2.500000 s) has elapsed, querying...
2020-12-03T20:25:35.321Z,1607027135.321 [lineCaptureDepthUSBLMode:J.](INFO): *** querying acoustic contact ***
2020-12-03T20:25:35.667Z,1607027135.667 [CTD_Seabird](ERROR): Salinity reading out of range: 0.269920 psu
2020-12-03T20:25:35.751Z,1607027135.751 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:35.751Z,1607027135.751 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:35.752Z,1607027135.752 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:36.104Z,1607027136.104 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:36.104Z,1607027136.104 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:36.109Z,1607027136.109 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:36.534Z,1607027136.534 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:36.534Z,1607027136.534 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:36.534Z,1607027136.534 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:36.673Z,1607027136.673 [CTD_Seabird](ERROR): Salinity reading out of range: 0.269805 psu
2020-12-03T20:25:36.983Z,1607027136.983 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:36.983Z,1607027136.983 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:36.984Z,1607027136.984 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:37.346Z,1607027137.346 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:37.346Z,1607027137.346 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:37.347Z,1607027137.347 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:37.675Z,1607027137.675 [CTD_Seabird](ERROR): Salinity reading out of range: 0.269628 psu
2020-12-03T20:25:37.760Z,1607027137.760 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:37.760Z,1607027137.760 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:37.765Z,1607027137.765 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:38.167Z,1607027138.167 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:38.167Z,1607027138.167 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:38.167Z,1607027138.167 [lineCaptureDepthUSBLMode:J.](INFO): update period (2.500000 s) has elapsed, querying...
2020-12-03T20:25:38.167Z,1607027138.167 [lineCaptureDepthUSBLMode:J.](INFO): *** querying acoustic contact ***
2020-12-03T20:25:38.527Z,1607027138.527 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:38.527Z,1607027138.527 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:38.527Z,1607027138.527 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:38.679Z,1607027138.679 [CTD_Seabird](ERROR): Salinity reading out of range: 0.269509 psu
2020-12-03T20:25:38.974Z,1607027138.974 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:38.974Z,1607027138.974 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:38.975Z,1607027138.975 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:39.398Z,1607027139.398 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:39.398Z,1607027139.398 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:39.399Z,1607027139.399 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:39.683Z,1607027139.683 [CTD_Seabird](ERROR): Salinity reading out of range: 0.269394 psu
2020-12-03T20:25:39.789Z,1607027139.789 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:39.789Z,1607027139.789 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:39.790Z,1607027139.790 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:40.148Z,1607027140.148 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:40.148Z,1607027140.148 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:40.153Z,1607027140.153 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:40.567Z,1607027140.567 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:40.567Z,1607027140.567 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:40.567Z,1607027140.567 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:40.691Z,1607027140.691 [CTD_Seabird](ERROR): Salinity reading out of range: 0.269220 psu
2020-12-03T20:25:40.972Z,1607027140.972 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:40.973Z,1607027140.973 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:40.973Z,1607027140.973 [lineCaptureDepthUSBLMode:J.](INFO): update period (2.500000 s) has elapsed, querying...
2020-12-03T20:25:40.973Z,1607027140.973 [lineCaptureDepthUSBLMode:J.](INFO): *** querying acoustic contact ***
2020-12-03T20:25:41.352Z,1607027141.352 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:41.352Z,1607027141.352 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:41.353Z,1607027141.353 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:41.695Z,1607027141.695 [CTD_Seabird](ERROR): Salinity reading out of range: 0.269102 psu
2020-12-03T20:25:41.773Z,1607027141.773 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:41.774Z,1607027141.774 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:41.774Z,1607027141.774 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:42.178Z,1607027142.178 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:42.178Z,1607027142.178 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:42.178Z,1607027142.178 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:42.560Z,1607027142.560 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:42.560Z,1607027142.560 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:42.561Z,1607027142.561 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:42.699Z,1607027142.699 [CTD_Seabird](ERROR): Salinity reading out of range: 0.268931 psu
2020-12-03T20:25:42.976Z,1607027142.976 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:42.976Z,1607027142.976 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:42.981Z,1607027142.981 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:43.374Z,1607027143.374 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:DetachFromDock:guide:A.Undock](INFO): Detached at range: nan m. Transitioning DDM to standby.
2020-12-03T20:25:43.375Z,1607027143.375 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:43.376Z,1607027143.376 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:43.376Z,1607027143.376 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:43.703Z,1607027143.703 [CTD_Seabird](ERROR): Salinity reading out of range: 0.268811 psu
2020-12-03T20:25:43.771Z,1607027143.771 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:DetachFromDock:guide:A.Undock](INFO): DDM isn't loaded, so simply satisfying DDM request.
2020-12-03T20:25:43.771Z,1607027143.771 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:DetachFromDock:guide:A.Undock](INFO): DDM at standby.
2020-12-03T20:25:43.772Z,1607027143.772 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:43.773Z,1607027143.773 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:43.773Z,1607027143.773 [lineCaptureDepthUSBLMode:J.](INFO): update period (2.500000 s) has elapsed, querying...
2020-12-03T20:25:43.773Z,1607027143.773 [lineCaptureDepthUSBLMode:J.](INFO): *** querying acoustic contact ***
2020-12-03T20:25:44.178Z,1607027144.178 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:DetachFromDock:guide:A.Undock](IMPORTANT): Undocking sequence complete.
2020-12-03T20:25:44.179Z,1607027144.179 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:DetachFromDock:guide:A.Undock] Stopped
2020-12-03T20:25:44.179Z,1607027144.179 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:DetachFromDock:guide:B] Running Loop=1
2020-12-03T20:25:44.180Z,1607027144.180 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:44.180Z,1607027144.180 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:44.181Z,1607027144.181 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:44.564Z,1607027144.564 [DAT](INFO): ****** received valid address query ******
2020-12-03T20:25:44.564Z,1607027144.564 [DAT](INFO): ****** received valid ping request ******
2020-12-03T20:25:44.564Z,1607027144.564 [DAT](INFO): Querying Benthos address 2 with one ping in standard two-way mode.
2020-12-03T20:25:44.566Z,1607027144.566 [DAT](INFO): ****** ping requested ******
2020-12-03T20:25:44.641Z,1607027144.641 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:DetachFromDock:guide:B](INFO): Transitioning to midcourse guidance.
2020-12-03T20:25:44.641Z,1607027144.641 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:DetachFromDock:guide:B] Stopped
2020-12-03T20:25:44.642Z,1607027144.642 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:DetachFromDock:guide:C] Running Loop=1
2020-12-03T20:25:44.643Z,1607027144.643 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:44.643Z,1607027144.643 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:44.643Z,1607027144.643 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:44.707Z,1607027144.707 [CTD_Seabird](ERROR): Salinity reading out of range: 0.268632 psu
2020-12-03T20:25:44.974Z,1607027144.974 [DAT](INFO): ****** received valid address query ******
2020-12-03T20:25:44.974Z,1607027144.974 [DAT](INFO): ****** received valid ping request ******
2020-12-03T20:25:44.991Z,1607027144.991 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:DetachFromDock:guide:C] Stopped
2020-12-03T20:25:44.991Z,1607027144.991 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:DetachFromDock:guide](INFO): Completed lineCaptureDepthUSBLMode:LineCapture:HomingSequence:DetachFromDock:guide
2020-12-03T20:25:44.991Z,1607027144.991 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:DetachFromDock:guide] Stopped
2020-12-03T20:25:44.991Z,1607027144.991 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:DetachFromDock:guide](DEBUG): Aggregate::uninitialize lineCaptureDepthUSBLMode:LineCapture:HomingSequence:DetachFromDock:guide
2020-12-03T20:25:44.991Z,1607027144.991 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:DetachFromDock](INFO): Completed lineCaptureDepthUSBLMode:LineCapture:HomingSequence:DetachFromDock
2020-12-03T20:25:44.992Z,1607027144.992 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:44.993Z,1607027144.993 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:44.993Z,1607027144.993 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:45.380Z,1607027145.380 [DAT](INFO): ****** received valid address query ******
2020-12-03T20:25:45.380Z,1607027145.380 [DAT](INFO): ****** received valid ping request ******
2020-12-03T20:25:45.391Z,1607027145.391 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance] Running Loop=1
2020-12-03T20:25:45.391Z,1607027145.391 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance](DEBUG): Aggregate::initialize lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance
2020-12-03T20:25:45.391Z,1607027145.391 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide] Running Loop=1
2020-12-03T20:25:45.391Z,1607027145.391 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide](DEBUG): Aggregate::initialize lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide
2020-12-03T20:25:45.391Z,1607027145.391 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide:C.SetSpeed] Running Loop=1
2020-12-03T20:25:45.391Z,1607027145.391 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide:C.SetSpeed](DEBUG): Initialize.
2020-12-03T20:25:45.392Z,1607027145.392 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide:D.Pitch] Running Loop=1
2020-12-03T20:25:45.392Z,1607027145.392 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide:D.Pitch](DEBUG): Initialize.
2020-12-03T20:25:45.392Z,1607027145.392 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide:A] Running Loop=1
2020-12-03T20:25:45.392Z,1607027145.392 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance] Running Loop=1
2020-12-03T20:25:45.392Z,1607027145.392 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide:D.Pitch] Running Loop=1
2020-12-03T20:25:45.393Z,1607027145.393 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide:C.SetSpeed] Running Loop=1
2020-12-03T20:25:45.394Z,1607027145.394 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide:A](INFO): Navigating to dock waypoint ( 36.803400 arcdeg , -121.822300 arcdeg )
2020-12-03T20:25:45.394Z,1607027145.394 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide:A] Stopped
2020-12-03T20:25:45.394Z,1607027145.394 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide:E.Waypoint] Running Loop=1
2020-12-03T20:25:45.394Z,1607027145.394 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide:E.Waypoint](DEBUG): Initialize WaypointComponent.
2020-12-03T20:25:45.397Z,1607027145.397 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:45.397Z,1607027145.397 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:45.398Z,1607027145.398 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:45.711Z,1607027145.711 [CTD_Seabird](ERROR): Salinity reading out of range: 0.268521 psu
2020-12-03T20:25:45.778Z,1607027145.778 [DAT](INFO): ****** received valid address query ******
2020-12-03T20:25:45.778Z,1607027145.778 [DAT](INFO): ****** received valid ping request ******
2020-12-03T20:25:45.840Z,1607027145.840 [lineCaptureDepthUSBLMode:LineCapture:HoldMassAndBuoyancy] Running Loop=1
2020-12-03T20:25:45.840Z,1607027145.840 [lineCaptureDepthUSBLMode:LineCapture:HoldMassAndBuoyancy](DEBUG): Aggregate::initialize lineCaptureDepthUSBLMode:LineCapture:HoldMassAndBuoyancy
2020-12-03T20:25:45.840Z,1607027145.840 [lineCaptureDepthUSBLMode:LineCapture:HoldMassAndBuoyancy:A] Running Loop=1
2020-12-03T20:25:45.840Z,1607027145.840 [lineCaptureDepthUSBLMode:LineCapture:HoldMassAndBuoyancy:B.Mass] Running Loop=1
2020-12-03T20:25:45.840Z,1607027145.840 [lineCaptureDepthUSBLMode:LineCapture:HoldMassAndBuoyancy:B.Mass](DEBUG): Initialize.
2020-12-03T20:25:45.849Z,1607027145.849 [lineCaptureDepthUSBLMode:LineCapture:HoldMassAndBuoyancy:C.Buoyancy] Running Loop=1
2020-12-03T20:25:45.849Z,1607027145.849 [lineCaptureDepthUSBLMode:LineCapture:HoldMassAndBuoyancy:C.Buoyancy](DEBUG): Initialize Buoyancy Component.
2020-12-03T20:25:45.849Z,1607027145.849 [lineCaptureDepthUSBLMode:LineCapture:HoldMassAndBuoyancy] Running Loop=1
2020-12-03T20:25:45.849Z,1607027145.849 [lineCaptureDepthUSBLMode:LineCapture:HoldMassAndBuoyancy:C.Buoyancy] Running Loop=1
2020-12-03T20:25:45.849Z,1607027145.849 [lineCaptureDepthUSBLMode:LineCapture:HoldMassAndBuoyancy:B.Mass] Running Loop=1
2020-12-03T20:25:45.850Z,1607027145.850 [lineCaptureDepthUSBLMode:LineCapture:HoldMassAndBuoyancy:A] Running Loop=1
2020-12-03T20:25:45.851Z,1607027145.851 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:45.852Z,1607027145.852 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:45.852Z,1607027145.852 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:46.182Z,1607027146.182 [DAT](INFO): ****** received valid address query ******
2020-12-03T20:25:46.182Z,1607027146.182 [DAT](INFO): ****** received valid ping request ******
2020-12-03T20:25:46.252Z,1607027146.252 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:46.265Z,1607027146.265 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:46.265Z,1607027146.265 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:46.599Z,1607027146.599 [DAT](INFO): ****** received valid address query ******
2020-12-03T20:25:46.599Z,1607027146.599 [DAT](INFO): ****** received valid ping request ******
2020-12-03T20:25:46.631Z,1607027146.631 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:46.631Z,1607027146.631 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:46.632Z,1607027146.632 [lineCaptureDepthUSBLMode:J.](INFO): update period (2.500000 s) has elapsed, querying...
2020-12-03T20:25:46.632Z,1607027146.632 [lineCaptureDepthUSBLMode:J.](INFO): *** querying acoustic contact ***
2020-12-03T20:25:46.715Z,1607027146.715 [CTD_Seabird](ERROR): Salinity reading out of range: 0.268403 psu
2020-12-03T20:25:46.990Z,1607027146.990 [DAT](INFO): DAT read: Response Not Received

2020-12-03T20:25:46.991Z,1607027146.991 [DAT](INFO): response not received
2020-12-03T20:25:46.991Z,1607027146.991 [DAT](INFO): DAT read: user:10>

2020-12-03T20:25:46.992Z,1607027146.992 [DAT](INFO): DAT read: Tx time:20:24:02.6992

2020-12-03T20:25:46.992Z,1607027146.992 [DAT](INFO): Ping request sent.
2020-12-03T20:25:46.992Z,1607027146.992 [DAT](INFO): transmitted an acoustic signal
2020-12-03T20:25:46.992Z,1607027146.992 [DAT](INFO): publishing transmit ping time
2020-12-03T20:25:47.074Z,1607027147.074 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:47.074Z,1607027147.074 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:47.074Z,1607027147.074 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:47.529Z,1607027147.529 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:47.529Z,1607027147.529 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:47.529Z,1607027147.529 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:47.719Z,1607027147.719 [CTD_Seabird](ERROR): Salinity reading out of range: 0.268286 psu
2020-12-03T20:25:47.855Z,1607027147.855 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:47.855Z,1607027147.855 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:47.855Z,1607027147.855 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:48.274Z,1607027148.274 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:48.274Z,1607027148.274 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:48.274Z,1607027148.274 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:48.660Z,1607027148.660 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:48.681Z,1607027148.681 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:48.681Z,1607027148.681 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:48.723Z,1607027148.723 [CTD_Seabird](ERROR): Salinity reading out of range: 0.268110 psu
2020-12-03T20:25:49.073Z,1607027149.073 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:49.073Z,1607027149.073 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:49.074Z,1607027149.074 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:49.456Z,1607027149.456 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:49.456Z,1607027149.456 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:49.456Z,1607027149.456 [lineCaptureDepthUSBLMode:J.](INFO): update period (2.500000 s) has elapsed, querying...
2020-12-03T20:25:49.456Z,1607027149.456 [lineCaptureDepthUSBLMode:J.](INFO): *** querying acoustic contact ***
2020-12-03T20:25:49.727Z,1607027149.727 [CTD_Seabird](ERROR): Salinity reading out of range: 0.267992 psu
2020-12-03T20:25:49.917Z,1607027149.917 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:49.917Z,1607027149.917 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:49.917Z,1607027149.917 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:50.275Z,1607027150.275 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:50.275Z,1607027150.275 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:50.275Z,1607027150.275 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:50.686Z,1607027150.686 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:50.686Z,1607027150.686 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:50.687Z,1607027150.687 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:50.731Z,1607027150.731 [CTD_Seabird](ERROR): Salinity reading out of range: 0.267818 psu
2020-12-03T20:25:51.068Z,1607027151.068 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:51.068Z,1607027151.068 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:51.073Z,1607027151.073 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:51.485Z,1607027151.485 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:51.485Z,1607027151.485 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:51.486Z,1607027151.486 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:51.735Z,1607027151.735 [CTD_Seabird](ERROR): Salinity reading out of range: 0.267760 psu
2020-12-03T20:25:51.894Z,1607027151.894 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:51.894Z,1607027151.894 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:51.895Z,1607027151.895 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:52.261Z,1607027152.261 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:52.261Z,1607027152.261 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:52.262Z,1607027152.262 [lineCaptureDepthUSBLMode:J.](INFO): update period (2.500000 s) has elapsed, querying...
2020-12-03T20:25:52.262Z,1607027152.262 [lineCaptureDepthUSBLMode:J.](INFO): *** querying acoustic contact ***
2020-12-03T20:25:52.674Z,1607027152.674 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:52.674Z,1607027152.674 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:52.675Z,1607027152.675 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:52.739Z,1607027152.739 [CTD_Seabird](ERROR): Salinity reading out of range: 0.267582 psu
2020-12-03T20:25:52.976Z,1607027152.976 [DataOverHttps](INFO): Sending 39 bytes from file Logs/20201203T185907/Courier0025.lzma
2020-12-03T20:25:53.097Z,1607027153.097 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:53.098Z,1607027153.098 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:53.098Z,1607027153.098 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:53.483Z,1607027153.483 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:53.483Z,1607027153.483 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:53.484Z,1607027153.484 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:53.743Z,1607027153.743 [CTD_Seabird](ERROR): Salinity reading out of range: 0.267466 psu
2020-12-03T20:25:53.892Z,1607027153.892 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:53.893Z,1607027153.893 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:53.894Z,1607027153.894 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:53.979Z,1607027153.979 [DataOverHttps](INFO): Moved sent file to Logs/20201203T185907/Courier0025.lzma.bak
2020-12-03T20:25:53.979Z,1607027153.979 [DataOverHttps](INFO): SBD MOMSN=13388617
2020-12-03T20:25:54.293Z,1607027154.293 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:54.293Z,1607027154.293 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:54.294Z,1607027154.294 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:54.678Z,1607027154.678 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:54.678Z,1607027154.678 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:54.678Z,1607027154.678 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:54.749Z,1607027154.749 [CTD_Seabird](ERROR): Salinity reading out of range: 0.267352 psu
2020-12-03T20:25:55.135Z,1607027155.135 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:55.135Z,1607027155.135 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:55.136Z,1607027155.136 [lineCaptureDepthUSBLMode:J.](INFO): update period (2.500000 s) has elapsed, querying...
2020-12-03T20:25:55.136Z,1607027155.136 [lineCaptureDepthUSBLMode:J.](INFO): *** querying acoustic contact ***
2020-12-03T20:25:55.510Z,1607027155.510 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:55.510Z,1607027155.510 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:55.511Z,1607027155.511 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:55.751Z,1607027155.751 [CTD_Seabird](ERROR): Salinity reading out of range: 0.267232 psu
2020-12-03T20:25:55.961Z,1607027155.961 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:55.961Z,1607027155.961 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:55.962Z,1607027155.962 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:56.320Z,1607027156.320 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:56.320Z,1607027156.320 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:56.345Z,1607027156.345 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:56.717Z,1607027156.717 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:56.717Z,1607027156.717 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:56.718Z,1607027156.718 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:56.755Z,1607027156.755 [CTD_Seabird](ERROR): Salinity reading out of range: 0.267059 psu
2020-12-03T20:25:57.090Z,1607027157.090 [DAT](INFO): ****** received valid address query ******
2020-12-03T20:25:57.090Z,1607027157.090 [DAT](INFO): ****** received valid ping request ******
2020-12-03T20:25:57.090Z,1607027157.090 [DAT](INFO): Querying Benthos address 2 with one ping in standard two-way mode.
2020-12-03T20:25:57.091Z,1607027157.091 [DAT](INFO): ****** ping requested ******
2020-12-03T20:25:57.148Z,1607027157.148 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:57.161Z,1607027157.161 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:57.161Z,1607027157.161 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:57.494Z,1607027157.494 [DAT](INFO): ****** received valid address query ******
2020-12-03T20:25:57.494Z,1607027157.494 [DAT](INFO): ****** received valid ping request ******
2020-12-03T20:25:57.520Z,1607027157.520 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:57.553Z,1607027157.553 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:57.553Z,1607027157.553 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:57.759Z,1607027157.759 [CTD_Seabird](ERROR): Salinity reading out of range: 0.266943 psu
2020-12-03T20:25:57.910Z,1607027157.910 [DAT](INFO): ****** received valid address query ******
2020-12-03T20:25:57.910Z,1607027157.910 [DAT](INFO): ****** received valid ping request ******
2020-12-03T20:25:57.940Z,1607027157.940 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:57.969Z,1607027157.969 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:57.969Z,1607027157.969 [lineCaptureDepthUSBLMode:J.](INFO): update period (2.500000 s) has elapsed, querying...
2020-12-03T20:25:57.969Z,1607027157.969 [lineCaptureDepthUSBLMode:J.](INFO): *** querying acoustic contact ***
2020-12-03T20:25:58.302Z,1607027158.302 [DAT](INFO): ****** received valid address query ******
2020-12-03T20:25:58.302Z,1607027158.302 [DAT](INFO): ****** received valid ping request ******
2020-12-03T20:25:58.445Z,1607027158.445 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:58.445Z,1607027158.445 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:58.446Z,1607027158.446 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:58.706Z,1607027158.706 [DAT](INFO): ****** received valid address query ******
2020-12-03T20:25:58.706Z,1607027158.706 [DAT](INFO): ****** received valid ping request ******
2020-12-03T20:25:58.763Z,1607027158.763 [CTD_Seabird](ERROR): Salinity reading out of range: 0.266826 psu
2020-12-03T20:25:58.797Z,1607027158.797 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:58.797Z,1607027158.797 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:58.797Z,1607027158.797 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:59.110Z,1607027159.110 [DAT](INFO): ****** received valid address query ******
2020-12-03T20:25:59.110Z,1607027159.110 [DAT](INFO): ****** received valid ping request ******
2020-12-03T20:25:59.141Z,1607027159.141 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:59.141Z,1607027159.141 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:59.141Z,1607027159.141 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:59.526Z,1607027159.526 [DAT](INFO): DAT read: Response Not Received

2020-12-03T20:25:59.527Z,1607027159.527 [DAT](INFO): response not received
2020-12-03T20:25:59.527Z,1607027159.527 [DAT](INFO): DAT read: user:11>

2020-12-03T20:25:59.528Z,1607027159.528 [DAT](INFO): DAT read: Tx time:20:24:15.1492

2020-12-03T20:25:59.528Z,1607027159.528 [DAT](INFO): Ping request sent.
2020-12-03T20:25:59.528Z,1607027159.528 [DAT](INFO): transmitted an acoustic signal
2020-12-03T20:25:59.529Z,1607027159.529 [DAT](INFO): publishing transmit ping time
2020-12-03T20:25:59.561Z,1607027159.561 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:59.561Z,1607027159.561 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:59.562Z,1607027159.562 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:25:59.767Z,1607027159.767 [CTD_Seabird](ERROR): Salinity reading out of range: 0.266649 psu
2020-12-03T20:25:59.948Z,1607027159.948 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:25:59.953Z,1607027159.953 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:25:59.953Z,1607027159.953 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:26:00.389Z,1607027160.389 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:00.389Z,1607027160.389 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:00.390Z,1607027160.390 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:26:00.752Z,1607027160.752 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:00.757Z,1607027160.757 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:00.757Z,1607027160.757 [lineCaptureDepthUSBLMode:J.](INFO): update period (2.500000 s) has elapsed, querying...
2020-12-03T20:26:00.757Z,1607027160.757 [lineCaptureDepthUSBLMode:J.](INFO): *** querying acoustic contact ***
2020-12-03T20:26:00.771Z,1607027160.771 [CTD_Seabird](ERROR): Salinity reading out of range: 0.266534 psu
2020-12-03T20:26:01.172Z,1607027161.172 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:01.201Z,1607027161.201 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:01.201Z,1607027161.201 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:26:01.572Z,1607027161.572 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:01.572Z,1607027161.572 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:01.577Z,1607027161.577 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:26:01.775Z,1607027161.775 [CTD_Seabird](ERROR): Salinity reading out of range: 0.266358 psu
2020-12-03T20:26:02.005Z,1607027162.005 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:02.005Z,1607027162.005 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:02.006Z,1607027162.006 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:26:02.409Z,1607027162.409 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:02.409Z,1607027162.409 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:02.410Z,1607027162.410 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:26:02.769Z,1607027162.769 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:02.769Z,1607027162.769 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:02.770Z,1607027162.770 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:26:02.779Z,1607027162.779 [CTD_Seabird](ERROR): Salinity reading out of range: 0.266243 psu
2020-12-03T20:26:03.169Z,1607027163.169 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:03.169Z,1607027163.169 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:03.170Z,1607027163.170 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:26:03.580Z,1607027163.580 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:03.580Z,1607027163.580 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:03.581Z,1607027163.581 [lineCaptureDepthUSBLMode:J.](INFO): update period (2.500000 s) has elapsed, querying...
2020-12-03T20:26:03.582Z,1607027163.582 [lineCaptureDepthUSBLMode:J.](INFO): *** querying acoustic contact ***
2020-12-03T20:26:03.783Z,1607027163.783 [CTD_Seabird](ERROR): Salinity reading out of range: 0.266124 psu
2020-12-03T20:26:03.989Z,1607027163.989 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:03.990Z,1607027163.990 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:03.990Z,1607027163.990 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:26:04.417Z,1607027164.417 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:04.417Z,1607027164.417 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:04.418Z,1607027164.418 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:26:04.787Z,1607027164.787 [CTD_Seabird](ERROR): Salinity reading out of range: 0.266011 psu
2020-12-03T20:26:04.831Z,1607027164.831 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:04.831Z,1607027164.831 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:04.832Z,1607027164.832 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:26:05.197Z,1607027165.197 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:05.198Z,1607027165.198 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:05.198Z,1607027165.198 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:26:05.631Z,1607027165.631 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:05.631Z,1607027165.631 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:05.631Z,1607027165.631 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:26:05.791Z,1607027165.791 [CTD_Seabird](ERROR): Salinity reading out of range: 0.265833 psu
2020-12-03T20:26:06.045Z,1607027166.045 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:06.045Z,1607027166.045 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:06.045Z,1607027166.045 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:26:06.412Z,1607027166.412 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:06.417Z,1607027166.417 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:06.417Z,1607027166.417 [lineCaptureDepthUSBLMode:J.](INFO): update period (2.500000 s) has elapsed, querying...
2020-12-03T20:26:06.417Z,1607027166.417 [lineCaptureDepthUSBLMode:J.](INFO): *** querying acoustic contact ***
2020-12-03T20:26:06.795Z,1607027166.795 [CTD_Seabird](ERROR): Salinity reading out of range: 0.265716 psu
2020-12-03T20:26:06.880Z,1607027166.880 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:06.880Z,1607027166.880 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:06.885Z,1607027166.885 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:26:07.233Z,1607027167.233 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:07.233Z,1607027167.233 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:07.234Z,1607027167.234 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:26:07.645Z,1607027167.645 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:07.646Z,1607027167.646 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:07.646Z,1607027167.646 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:26:07.799Z,1607027167.799 [CTD_Seabird](ERROR): Salinity reading out of range: 0.265603 psu
2020-12-03T20:26:08.029Z,1607027168.029 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:08.029Z,1607027168.029 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:08.030Z,1607027168.030 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:26:08.449Z,1607027168.449 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:08.449Z,1607027168.449 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:08.450Z,1607027168.450 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:26:08.803Z,1607027168.803 [CTD_Seabird](ERROR): Salinity reading out of range: 0.265487 psu
2020-12-03T20:26:08.839Z,1607027168.839 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:08.839Z,1607027168.839 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:08.840Z,1607027168.840 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:26:09.247Z,1607027169.247 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:09.247Z,1607027169.247 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:09.248Z,1607027169.248 [lineCaptureDepthUSBLMode:J.](INFO): update period (2.500000 s) has elapsed, querying...
2020-12-03T20:26:09.248Z,1607027169.248 [lineCaptureDepthUSBLMode:J.](INFO): *** querying acoustic contact ***
2020-12-03T20:26:09.618Z,1607027169.618 [DAT](INFO): ****** received valid address query ******
2020-12-03T20:26:09.618Z,1607027169.618 [DAT](INFO): ****** received valid ping request ******
2020-12-03T20:26:09.619Z,1607027169.619 [DAT](INFO): Querying Benthos address 2 with one ping in standard two-way mode.
2020-12-03T20:26:09.619Z,1607027169.619 [DAT](INFO): ****** ping requested ******
2020-12-03T20:26:09.699Z,1607027169.699 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:09.700Z,1607027169.700 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:09.700Z,1607027169.700 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:26:09.807Z,1607027169.807 [CTD_Seabird](ERROR): Salinity reading out of range: 0.265310 psu
2020-12-03T20:26:10.018Z,1607027170.018 [DAT](INFO): ****** received valid address query ******
2020-12-03T20:26:10.018Z,1607027170.018 [DAT](INFO): ****** received valid ping request ******
2020-12-03T20:26:10.037Z,1607027170.037 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:10.037Z,1607027170.037 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:10.038Z,1607027170.038 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:26:10.421Z,1607027170.421 [DAT](INFO): ****** received valid address query ******
2020-12-03T20:26:10.422Z,1607027170.422 [DAT](INFO): ****** received valid ping request ******
2020-12-03T20:26:10.435Z,1607027170.435 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:10.435Z,1607027170.435 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:10.436Z,1607027170.436 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:26:10.811Z,1607027170.811 [CTD_Seabird](ERROR): Salinity reading out of range: 0.265196 psu
2020-12-03T20:26:10.835Z,1607027170.835 [DAT](INFO): ****** received valid address query ******
2020-12-03T20:26:10.835Z,1607027170.835 [DAT](INFO): ****** received valid ping request ******
2020-12-03T20:26:10.883Z,1607027170.883 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:10.883Z,1607027170.883 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:10.883Z,1607027170.883 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:26:11.238Z,1607027171.238 [DAT](INFO): ****** received valid address query ******
2020-12-03T20:26:11.238Z,1607027171.238 [DAT](INFO): ****** received valid ping request ******
2020-12-03T20:26:11.272Z,1607027171.272 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:11.272Z,1607027171.272 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:11.273Z,1607027171.273 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:26:11.638Z,1607027171.638 [DAT](INFO): ****** received valid address query ******
2020-12-03T20:26:11.638Z,1607027171.638 [DAT](INFO): ****** received valid ping request ******
2020-12-03T20:26:11.693Z,1607027171.693 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:11.693Z,1607027171.693 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:11.694Z,1607027171.694 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:26:11.819Z,1607027171.819 [CTD_Seabird](ERROR): Salinity reading out of range: 0.265075 psu
2020-12-03T20:26:12.042Z,1607027172.042 [DAT](INFO): DAT read: Response Not Received

2020-12-03T20:26:12.043Z,1607027172.043 [DAT](INFO): response not received
2020-12-03T20:26:12.043Z,1607027172.043 [DAT](INFO): DAT read: user:12>

2020-12-03T20:26:12.044Z,1607027172.044 [DAT](INFO): DAT read: Tx time:20:24:27.5992

2020-12-03T20:26:12.044Z,1607027172.044 [DAT](INFO): Ping request sent.
2020-12-03T20:26:12.044Z,1607027172.044 [DAT](INFO): transmitted an acoustic signal
2020-12-03T20:26:12.044Z,1607027172.044 [DAT](INFO): publishing transmit ping time
2020-12-03T20:26:12.154Z,1607027172.154 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:12.154Z,1607027172.154 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:12.154Z,1607027172.154 [lineCaptureDepthUSBLMode:J.](INFO): update period (2.500000 s) has elapsed, querying...
2020-12-03T20:26:12.155Z,1607027172.155 [lineCaptureDepthUSBLMode:J.](INFO): *** querying acoustic contact ***
2020-12-03T20:26:12.476Z,1607027172.476 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:12.476Z,1607027172.476 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:12.477Z,1607027172.477 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:26:12.820Z,1607027172.820 [CTD_Seabird](ERROR): Salinity reading out of range: 0.264960 psu
2020-12-03T20:26:12.950Z,1607027172.950 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:12.951Z,1607027172.951 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:12.951Z,1607027172.951 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:26:13.348Z,1607027173.348 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:13.348Z,1607027173.348 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:13.349Z,1607027173.349 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:26:13.758Z,1607027173.758 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:13.758Z,1607027173.758 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:13.758Z,1607027173.758 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:26:13.825Z,1607027173.825 [CTD_Seabird](ERROR): Salinity reading out of range: 0.264844 psu
2020-12-03T20:26:14.094Z,1607027174.094 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:14.094Z,1607027174.094 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:14.094Z,1607027174.094 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:26:14.505Z,1607027174.505 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:14.505Z,1607027174.505 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:14.506Z,1607027174.506 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:26:14.828Z,1607027174.828 [CTD_Seabird](ERROR): Salinity reading out of range: 0.264671 psu
2020-12-03T20:26:14.961Z,1607027174.961 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:14.961Z,1607027174.961 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:14.961Z,1607027174.961 [lineCaptureDepthUSBLMode:J.](INFO): update period (2.500000 s) has elapsed, querying...
2020-12-03T20:26:14.962Z,1607027174.962 [lineCaptureDepthUSBLMode:J.](INFO): *** querying acoustic contact ***
2020-12-03T20:26:15.297Z,1607027175.297 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:15.297Z,1607027175.297 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:15.298Z,1607027175.298 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:26:15.718Z,1607027175.718 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:15.718Z,1607027175.718 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:15.719Z,1607027175.719 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:26:15.832Z,1607027175.832 [CTD_Seabird](ERROR): Salinity reading out of range: 0.264552 psu
2020-12-03T20:26:15.955Z,1607027175.955 [CommandLine](IMPORTANT): got command stop
2020-12-03T20:26:15.955Z,1607027175.955 [CommandLine](IMPORTANT): Scheduling is paused
2020-12-03T20:26:15.955Z,1607027175.955 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2020-12-03T20:26:16.133Z,1607027176.133 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking...
2020-12-03T20:26:16.133Z,1607027176.133 [lineCaptureDepthUSBLMode:J.](DEBUG): Tracking contact label: 2
2020-12-03T20:26:16.134Z,1607027176.134 [lineCaptureDepthUSBLMode:J.](INFO): waiting for update period (2.500000 s) to elapse
2020-12-03T20:26:16.136Z,1607027176.136 [MissionManager](INFO): MissionManager is completed.
2020-12-03T20:26:16.136Z,1607027176.136 [MissionManager](INFO): Uninitializing Mission lineCaptureDepthUSBLMode
2020-12-03T20:26:16.136Z,1607027176.136 [lineCaptureDepthUSBLMode] Stopped
2020-12-03T20:26:16.136Z,1607027176.136 [lineCaptureDepthUSBLMode](DEBUG): Aggregate::uninitialize lineCaptureDepthUSBLMode
2020-12-03T20:26:16.136Z,1607027176.136 [lineCaptureDepthUSBLMode:Science] Stopped
2020-12-03T20:26:16.136Z,1607027176.136 [lineCaptureDepthUSBLMode:Science](DEBUG): Aggregate::uninitialize lineCaptureDepthUSBLMode:Science
2020-12-03T20:26:16.136Z,1607027176.136 [lineCaptureDepthUSBLMode:Science:B] Stopped
2020-12-03T20:26:16.136Z,1607027176.136 [lineCaptureDepthUSBLMode:Science:C] Stopped
2020-12-03T20:26:16.136Z,1607027176.136 [lineCaptureDepthUSBLMode:Science:E] Stopped
2020-12-03T20:26:16.136Z,1607027176.136 [lineCaptureDepthUSBLMode:Science:OceanCurrent] Stopped
2020-12-03T20:26:16.153Z,1607027176.153 [lineCaptureDepthUSBLMode:Science:OceanCurrent](DEBUG): Aggregate::uninitialize lineCaptureDepthUSBLMode:Science:OceanCurrent
2020-12-03T20:26:16.153Z,1607027176.153 [lineCaptureDepthUSBLMode:Science:OceanCurrent:A.] Stopped
2020-12-03T20:26:16.153Z,1607027176.153 [lineCaptureDepthUSBLMode:StandardEnvelopes] Stopped
2020-12-03T20:26:16.153Z,1607027176.153 [lineCaptureDepthUSBLMode:StandardEnvelopes](DEBUG): Aggregate::uninitialize lineCaptureDepthUSBLMode:StandardEnvelopes
2020-12-03T20:26:16.153Z,1607027176.153 [lineCaptureDepthUSBLMode:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2020-12-03T20:26:16.153Z,1607027176.153 [lineCaptureDepthUSBLMode:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2020-12-03T20:26:16.153Z,1607027176.153 [lineCaptureDepthUSBLMode:StandardEnvelopes:B.DepthEnvelope] Stopped
2020-12-03T20:26:16.153Z,1607027176.153 [lineCaptureDepthUSBLMode:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2020-12-03T20:26:16.153Z,1607027176.153 [lineCaptureDepthUSBLMode:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2020-12-03T20:26:16.153Z,1607027176.153 [lineCaptureDepthUSBLMode:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2020-12-03T20:26:16.153Z,1607027176.153 [lineCaptureDepthUSBLMode:MicromodemComms] Stopped
2020-12-03T20:26:16.154Z,1607027176.154 [lineCaptureDepthUSBLMode:MicromodemComms](DEBUG): Aggregate::uninitialize lineCaptureDepthUSBLMode:MicromodemComms
2020-12-03T20:26:16.154Z,1607027176.154 [lineCaptureDepthUSBLMode:MicromodemComms:CheckIn] Stopped
2020-12-03T20:26:16.154Z,1607027176.154 [lineCaptureDepthUSBLMode:MicromodemComms:CheckIn](DEBUG): Aggregate::uninitialize lineCaptureDepthUSBLMode:MicromodemComms:CheckIn
2020-12-03T20:26:16.154Z,1607027176.154 [lineCaptureDepthUSBLMode:MicromodemComms:CheckIn:A] Stopped
2020-12-03T20:26:16.154Z,1607027176.154 [lineCaptureDepthUSBLMode:J.] Stopped
2020-12-03T20:26:16.154Z,1607027176.154 [lineCaptureDepthUSBLMode:J.](DEBUG): Uninitializing TrackAcousticContact.
2020-12-03T20:26:16.154Z,1607027176.154 [lineCaptureDepthUSBLMode:LineCapture] Stopped
2020-12-03T20:26:16.154Z,1607027176.154 [lineCaptureDepthUSBLMode:LineCapture](DEBUG): Aggregate::uninitialize lineCaptureDepthUSBLMode:LineCapture
2020-12-03T20:26:16.154Z,1607027176.154 [lineCaptureDepthUSBLMode:LineCapture:StandardEnvelopes] Stopped
2020-12-03T20:26:16.154Z,1607027176.154 [lineCaptureDepthUSBLMode:LineCapture:StandardEnvelopes](DEBUG): Aggregate::uninitialize lineCaptureDepthUSBLMode:LineCapture:StandardEnvelopes
2020-12-03T20:26:16.154Z,1607027176.154 [lineCaptureDepthUSBLMode:LineCapture:StandardEnvelopes:A.AltitudeEnvelope] Stopped
2020-12-03T20:26:16.154Z,1607027176.154 [lineCaptureDepthUSBLMode:LineCapture:StandardEnvelopes:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2020-12-03T20:26:16.154Z,1607027176.154 [lineCaptureDepthUSBLMode:LineCapture:StandardEnvelopes:B.DepthEnvelope] Stopped
2020-12-03T20:26:16.154Z,1607027176.154 [lineCaptureDepthUSBLMode:LineCapture:StandardEnvelopes:B.DepthEnvelope](DEBUG): Uninitialize.
2020-12-03T20:26:16.155Z,1607027176.155 [lineCaptureDepthUSBLMode:LineCapture:StandardEnvelopes:C.OffshoreEnvelope] Stopped
2020-12-03T20:26:16.155Z,1607027176.155 [lineCaptureDepthUSBLMode:LineCapture:StandardEnvelopes:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2020-12-03T20:26:16.155Z,1607027176.155 [lineCaptureDepthUSBLMode:LineCapture:G] Stopped
2020-12-03T20:26:16.155Z,1607027176.155 [lineCaptureDepthUSBLMode:LineCapture:H] Stopped
2020-12-03T20:26:16.155Z,1607027176.155 [lineCaptureDepthUSBLMode:LineCapture:HoldMassAndBuoyancy] Stopped
2020-12-03T20:26:16.155Z,1607027176.155 [lineCaptureDepthUSBLMode:LineCapture:HoldMassAndBuoyancy](DEBUG): Aggregate::uninitialize lineCaptureDepthUSBLMode:LineCapture:HoldMassAndBuoyancy
2020-12-03T20:26:16.155Z,1607027176.155 [lineCaptureDepthUSBLMode:LineCapture:HoldMassAndBuoyancy:A] Stopped
2020-12-03T20:26:16.155Z,1607027176.155 [lineCaptureDepthUSBLMode:LineCapture:HoldMassAndBuoyancy:B.Mass] Stopped
2020-12-03T20:26:16.155Z,1607027176.155 [lineCaptureDepthUSBLMode:LineCapture:HoldMassAndBuoyancy:B.Mass](DEBUG): Uninitialize.
2020-12-03T20:26:16.155Z,1607027176.155 [lineCaptureDepthUSBLMode:LineCapture:HoldMassAndBuoyancy:C.Buoyancy] Stopped
2020-12-03T20:26:16.155Z,1607027176.155 [lineCaptureDepthUSBLMode:LineCapture:HoldMassAndBuoyancy:C.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2020-12-03T20:26:16.155Z,1607027176.155 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence] Stopped
2020-12-03T20:26:16.155Z,1607027176.155 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence](DEBUG): Aggregate::uninitialize lineCaptureDepthUSBLMode:LineCapture:HomingSequence
2020-12-03T20:26:16.155Z,1607027176.155 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:DetachFromDock] Stopped
2020-12-03T20:26:16.155Z,1607027176.155 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:DetachFromDock](DEBUG): Aggregate::uninitialize lineCaptureDepthUSBLMode:LineCapture:HomingSequence:DetachFromDock
2020-12-03T20:26:16.156Z,1607027176.156 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance] Stopped
2020-12-03T20:26:16.156Z,1607027176.156 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance](DEBUG): Aggregate::uninitialize lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance
2020-12-03T20:26:16.156Z,1607027176.156 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide] Stopped
2020-12-03T20:26:16.156Z,1607027176.156 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide](DEBUG): Aggregate::uninitialize lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide
2020-12-03T20:26:16.156Z,1607027176.156 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide:C.SetSpeed] Stopped
2020-12-03T20:26:16.156Z,1607027176.156 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide:C.SetSpeed](DEBUG): Uninitialize.
2020-12-03T20:26:16.156Z,1607027176.156 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide:D.Pitch] Stopped
2020-12-03T20:26:16.156Z,1607027176.156 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide:E.Waypoint] Stopped
2020-12-03T20:26:16.156Z,1607027176.156 [lineCaptureDepthUSBLMode:LineCapture:HomingSequence:MidcourseGuidance:guide:E.Waypoint](DEBUG): Uninitialize WaypointComponent.
2020-12-03T20:26:16.156Z,1607027176.156 [lineCaptureDepthUSBLMode:Lap] Stopped
2020-12-03T20:26:16.168Z,1607027176.168 [lineCaptureDepthUSBLMode:Lap](DEBUG): Aggregate::uninitialize lineCaptureDepthUSBLMode:Lap
2020-12-03T20:26:16.168Z,1607027176.168 [lineCaptureDepthUSBLMode:Lap:B] Stopped
2020-12-03T20:26:16.168Z,1607027176.168 [lineCaptureDepthUSBLMode:Lap:B](DEBUG): Aggregate::uninitialize lineCaptureDepthUSBLMode:Lap:B
2020-12-03T20:26:16.537Z,1607027176.537 [MissionManager](IMPORTANT): Started mission Default
2020-12-03T20:26:16.538Z,1607027176.538 [Default] Running Loop=1
2020-12-03T20:26:16.538Z,1607027176.538 [Default](DEBUG): Aggregate::initialize Default
2020-12-03T20:26:16.538Z,1607027176.538 [Default:B.GoToSurface] Running Loop=1
2020-12-03T20:26:16.538Z,1607027176.538 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2020-12-03T20:26:16.538Z,1607027176.538 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2020-12-03T20:26:16.538Z,1607027176.538 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2020-12-03T20:26:16.539Z,1607027176.539 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2020-12-03T20:26:16.539Z,1607027176.539 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2020-12-03T20:26:16.539Z,1607027176.539 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2020-12-03T20:26:16.540Z,1607027176.540 [Default:A.Wait] Running Loop=1
2020-12-03T20:26:16.540Z,1607027176.540 [Default:A.Wait](DEBUG): Initialize Wait Component.
2020-12-03T20:26:16.593Z,1607027176.593 [WetLabsBB2FL](INFO): Powering down
2020-12-03T20:26:21.002Z,1607027181.002 [DataOverHttps](INFO): Sending 291 bytes from file Logs/20201203T185907/Express0026.lzma
2020-12-03T20:26:22.002Z,1607027182.002 [DataOverHttps](INFO): Moved sent file to Logs/20201203T185907/Express0026.lzma.bak
2020-12-03T20:26:22.003Z,1607027182.003 [DataOverHttps](INFO): SBD MOMSN=13388619
2020-12-03T20:26:29.829Z,1607027189.829 [Default:A.Wait](INFO): Done Waiting.
2020-12-03T20:26:29.829Z,1607027189.829 [Default:A.Wait] Stopped
2020-12-03T20:26:29.829Z,1607027189.829 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2020-12-03T20:26:30.245Z,1607027190.245 [Default:CheckIn] Running Loop=1
2020-12-03T20:26:30.246Z,1607027190.246 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2020-12-03T20:26:30.246Z,1607027190.246 [Default:CheckIn:Read_GPS] Running Loop=1