2019-07-10T23:32:20.216Z,1562801540.216 [Supervisor](DEBUG): Initializing supervisor.
2019-07-10T23:32:20.219Z,1562801540.219 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-07-10T23:32:20.220Z,1562801540.220 [SyncHandler](INFO): Protected caller Thread ID is 959
2019-07-10T23:32:20.220Z,1562801540.220 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-07-10T23:32:20.221Z,1562801540.221 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-07-10T23:32:20.222Z,1562801540.222 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 960
2019-07-10T23:32:20.225Z,1562801540.225 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-07-10T23:32:20.236Z,1562801540.236 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-07-10T23:32:20.237Z,1562801540.237 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-07-10T23:32:20.237Z,1562801540.237 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 961
2019-07-10T23:32:20.238Z,1562801540.238 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-07-10T23:32:20.239Z,1562801540.239 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-07-10T23:32:20.239Z,1562801540.239 [logger ThreadHandler](INFO): Protected caller Thread ID is 962
2019-07-10T23:32:20.241Z,1562801540.241 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-07-10T23:32:20.242Z,1562801540.242 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-07-10T23:32:20.243Z,1562801540.243 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-07-10T23:32:20.654Z,1562801540.654 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-07-10T23:32:20.655Z,1562801540.655 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-07-10T23:32:20.751Z,1562801540.751 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-07-10T23:32:20.752Z,1562801540.752 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-07-10T23:32:21.059Z,1562801541.059 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-07-10T23:32:21.060Z,1562801541.060 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-07-10T23:32:21.197Z,1562801541.197 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-07-10T23:32:21.197Z,1562801541.197 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-07-10T23:32:21.399Z,1562801541.399 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-07-10T23:32:21.399Z,1562801541.399 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-07-10T23:32:22.010Z,1562801542.010 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-07-10T23:32:22.011Z,1562801542.011 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-07-10T23:32:22.212Z,1562801542.212 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-07-10T23:32:22.213Z,1562801542.213 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-07-10T23:32:22.355Z,1562801542.355 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-07-10T23:32:22.355Z,1562801542.355 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-07-10T23:32:22.545Z,1562801542.545 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-07-10T23:32:22.546Z,1562801542.546 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-07-10T23:32:22.640Z,1562801542.640 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-07-10T23:32:22.641Z,1562801542.641 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-07-10T23:32:22.934Z,1562801542.934 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-07-10T23:32:22.935Z,1562801542.935 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-07-10T23:32:23.014Z,1562801543.014 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-07-10T23:32:23.115Z,1562801543.115 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-07-10T23:32:23.116Z,1562801543.116 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-07-10T23:32:23.693Z,1562801543.693 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-07-10T23:32:23.694Z,1562801543.694 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-07-10T23:32:24.076Z,1562801544.076 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-07-10T23:32:24.078Z,1562801544.078 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2019-07-10T23:32:24.079Z,1562801544.079 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2019-07-10T23:32:24.282Z,1562801544.282 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2019-07-10T23:32:24.381Z,1562801544.381 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2019-07-10T23:32:24.477Z,1562801544.477 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2019-07-10T23:32:24.701Z,1562801544.701 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-07-10T23:32:24.701Z,1562801544.701 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2019-07-10T23:32:24.785Z,1562801544.785 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2019-07-10T23:32:24.876Z,1562801544.876 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2019-07-10T23:32:24.970Z,1562801544.970 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2019-07-10T23:32:25.052Z,1562801545.052 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2019-07-10T23:32:25.157Z,1562801545.157 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2019-07-10T23:32:25.331Z,1562801545.331 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2019-07-10T23:32:25.459Z,1562801545.459 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2019-07-10T23:32:25.459Z,1562801545.459 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-07-10T23:32:25.461Z,1562801545.461 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-07-10T23:32:25.859Z,1562801545.859 [AHRS_M2] Loaded
2019-07-10T23:32:25.859Z,1562801545.859 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-07-10T23:32:25.932Z,1562801545.932 [DataOverHttps] Loaded
2019-07-10T23:32:25.932Z,1562801545.932 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-07-10T23:32:25.934Z,1562801545.934 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407654E0
2019-07-10T23:32:25.934Z,1562801545.934 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1041
2019-07-10T23:32:25.947Z,1562801545.947 [Depth_Keller] Loaded
2019-07-10T23:32:25.947Z,1562801545.947 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-07-10T23:32:25.952Z,1562801545.952 [DropWeight] Loaded
2019-07-10T23:32:25.952Z,1562801545.952 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-07-10T23:32:26.008Z,1562801546.008 [DUSBL_Hydroid] Loaded
2019-07-10T23:32:26.008Z,1562801546.008 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2019-07-10T23:32:26.057Z,1562801546.057 [Micromodem] Loaded
2019-07-10T23:32:26.057Z,1562801546.057 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2019-07-10T23:32:26.154Z,1562801546.154 [NAL9602] Loaded
2019-07-10T23:32:26.155Z,1562801546.155 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-07-10T23:32:26.170Z,1562801546.170 [Onboard] Loaded
2019-07-10T23:32:26.170Z,1562801546.170 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-07-10T23:32:26.176Z,1562801546.176 [PowerOnly] Loaded
2019-07-10T23:32:26.176Z,1562801546.176 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2019-07-10T23:32:26.183Z,1562801546.183 [Radio_Surface] Loaded
2019-07-10T23:32:26.183Z,1562801546.183 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-07-10T23:32:26.184Z,1562801546.184 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407954E0
2019-07-10T23:32:26.185Z,1562801546.185 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1042
2019-07-10T23:32:26.228Z,1562801546.228 [RDI_Pathfinder] Loaded
2019-07-10T23:32:26.228Z,1562801546.228 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-07-10T23:32:28.220Z,1562801548.220 [BPC1] Loaded
2019-07-10T23:32:28.221Z,1562801548.221 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-07-10T23:32:28.221Z,1562801548.221 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-07-10T23:32:28.222Z,1562801548.222 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-07-10T23:32:28.235Z,1562801548.235 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-07-10T23:32:28.235Z,1562801548.235 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-07-10T23:32:28.341Z,1562801548.341 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-07-10T23:32:28.342Z,1562801548.342 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-07-10T23:32:28.362Z,1562801548.362 [NavChart] Loaded
2019-07-10T23:32:28.362Z,1562801548.362 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-07-10T23:32:28.366Z,1562801548.366 [UniversalFixResidualReporter] Loaded
2019-07-10T23:32:28.366Z,1562801548.366 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-07-10T23:32:28.367Z,1562801548.367 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-07-10T23:32:28.367Z,1562801548.367 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-07-10T23:32:28.474Z,1562801548.474 [BuoyancyServo] Loaded
2019-07-10T23:32:28.474Z,1562801548.474 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-07-10T23:32:28.489Z,1562801548.489 [ElevatorServo] Loaded
2019-07-10T23:32:28.489Z,1562801548.489 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-07-10T23:32:28.504Z,1562801548.504 [MassServo] Loaded
2019-07-10T23:32:28.504Z,1562801548.504 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-07-10T23:32:28.519Z,1562801548.519 [RudderServo] Loaded
2019-07-10T23:32:28.520Z,1562801548.520 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-07-10T23:32:28.534Z,1562801548.534 [ThrusterServo] Loaded
2019-07-10T23:32:28.534Z,1562801548.534 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-07-10T23:32:28.535Z,1562801548.535 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-07-10T23:32:28.535Z,1562801548.535 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-07-10T23:32:28.783Z,1562801548.783 [CTD_NeilBrown] Loaded
2019-07-10T23:32:28.783Z,1562801548.783 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-07-10T23:32:28.784Z,1562801548.784 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408E04E0
2019-07-10T23:32:28.785Z,1562801548.785 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 1043
2019-07-10T23:32:28.829Z,1562801548.829 [WetLabsSeaOWL_UV_A] Loaded
2019-07-10T23:32:28.829Z,1562801548.829 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2019-07-10T23:32:28.830Z,1562801548.830 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409104E0
2019-07-10T23:32:28.831Z,1562801548.831 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 1044
2019-07-10T23:32:28.831Z,1562801548.831 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-07-10T23:32:28.832Z,1562801548.832 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-07-10T23:32:29.125Z,1562801549.125 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-07-10T23:32:29.126Z,1562801549.126 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-07-10T23:32:29.167Z,1562801549.167 [DepthRateCalculator] Loaded
2019-07-10T23:32:29.167Z,1562801549.167 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-07-10T23:32:29.173Z,1562801549.173 [PitchRateCalculator] Loaded
2019-07-10T23:32:29.173Z,1562801549.173 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-07-10T23:32:29.185Z,1562801549.185 [SpeedCalculator] Loaded
2019-07-10T23:32:29.185Z,1562801549.185 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-07-10T23:32:29.206Z,1562801549.206 [TempGradientCalculator] Loaded
2019-07-10T23:32:29.206Z,1562801549.206 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-07-10T23:32:29.212Z,1562801549.212 [YawRateCalculator] Loaded
2019-07-10T23:32:29.212Z,1562801549.212 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-07-10T23:32:29.253Z,1562801549.253 [ElevatorOffsetCalculator] Loaded
2019-07-10T23:32:29.253Z,1562801549.253 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-07-10T23:32:29.253Z,1562801549.253 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-07-10T23:32:29.254Z,1562801549.254 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-07-10T23:32:29.392Z,1562801549.392 [SBIT](DEBUG): Construct Startup Built In Test.
2019-07-10T23:32:29.414Z,1562801549.414 [SBIT] Loaded
2019-07-10T23:32:29.414Z,1562801549.414 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-07-10T23:32:29.415Z,1562801549.415 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-07-10T23:32:29.426Z,1562801549.426 [IBIT] Loaded
2019-07-10T23:32:29.427Z,1562801549.427 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-07-10T23:32:29.430Z,1562801549.430 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-07-10T23:32:29.569Z,1562801549.569 [CBIT] Loaded
2019-07-10T23:32:29.570Z,1562801549.570 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-07-10T23:32:29.570Z,1562801549.570 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-07-10T23:32:29.571Z,1562801549.571 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-07-10T23:32:29.651Z,1562801549.651 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-07-10T23:32:29.651Z,1562801549.651 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-07-10T23:32:29.748Z,1562801549.748 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-07-10T23:32:29.749Z,1562801549.749 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-07-10T23:32:29.816Z,1562801549.816 [VerticalControl](DEBUG): Construct VerticalControl.
2019-07-10T23:32:29.899Z,1562801549.899 [VerticalControl] Loaded
2019-07-10T23:32:29.899Z,1562801549.899 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-07-10T23:32:29.900Z,1562801549.900 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-07-10T23:32:29.956Z,1562801549.956 [HorizontalControl] Loaded
2019-07-10T23:32:29.956Z,1562801549.956 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-07-10T23:32:29.957Z,1562801549.957 [SpeedControl](DEBUG): Construct SpeedControl.
2019-07-10T23:32:29.959Z,1562801549.959 [SpeedControl] Loaded
2019-07-10T23:32:29.959Z,1562801549.959 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-07-10T23:32:29.960Z,1562801549.960 [LoopControl](DEBUG): Construct LoopControl.
2019-07-10T23:32:29.960Z,1562801549.960 [LoopControl] Loaded
2019-07-10T23:32:29.960Z,1562801549.960 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-07-10T23:32:29.961Z,1562801549.961 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-07-10T23:32:29.961Z,1562801549.961 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-07-10T23:32:29.992Z,1562801549.992 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-07-10T23:32:29.996Z,1562801549.996 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-07-10T23:32:29.997Z,1562801549.997 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-07-10T23:32:30.004Z,1562801550.004 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-07-10T23:32:30.005Z,1562801550.005 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AEC4E0
2019-07-10T23:32:30.005Z,1562801550.005 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1045
2019-07-10T23:32:30.009Z,1562801550.009 [Supervisor](INFO): Main Thread ID is 802
2019-07-10T23:32:30.009Z,1562801550.009 [Supervisor](DEBUG): Running supervisor.
2019-07-10T23:32:30.010Z,1562801550.010 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1046
2019-07-10T23:32:30.012Z,1562801550.012 [controlThread ThreadHandler](INFO): Handler Thread ID is 1047
2019-07-10T23:32:30.013Z,1562801550.013 [controlThread](DEBUG): Initializing ControlThread
2019-07-10T23:32:30.022Z,1562801550.022 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-07-10T23:32:30.022Z,1562801550.022 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-07-10T23:32:30.024Z,1562801550.024 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-07-10T23:32:30.024Z,1562801550.024 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-07-10T23:32:30.024Z,1562801550.024 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-07-10T23:32:30.025Z,1562801550.025 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-07-10T23:32:30.025Z,1562801550.025 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-07-10T23:32:30.025Z,1562801550.025 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-07-10T23:32:30.026Z,1562801550.026 [SBIT](INFO): Initialize SBIT Component.
2019-07-10T23:32:30.027Z,1562801550.027 [SBIT](IMPORTANT): git: 2019-07-01-2-g8df750c
2019-07-10T23:32:30.027Z,1562801550.027 [SBIT](INFO): git hash: 8df750cfd7ab665d8785a0cb54650bf6fbbccb32
2019-07-10T23:32:30.027Z,1562801550.027 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-07-10T23:32:30.028Z,1562801550.028 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #1 PREEMPT Thu Feb 21 11:17:40 PST 2019
2019-07-10T23:32:30.030Z,1562801550.030 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-07-10T23:32:30.030Z,1562801550.030 [IBIT](INFO): Initialize IBIT Component.
2019-07-10T23:32:30.031Z,1562801550.031 [CBIT](DEBUG): Initialize CBIT Component.
2019-07-10T23:32:30.032Z,1562801550.032 [logger ThreadHandler](INFO): Handler Thread ID is 1048
2019-07-10T23:32:30.042Z,1562801550.042 [CBIT](DEBUG): Initialized mux pins.
2019-07-10T23:32:30.042Z,1562801550.042 [CBIT](DEBUG): Initializing the watchdog timer.
2019-07-10T23:32:30.051Z,1562801550.051 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1049
2019-07-10T23:32:30.052Z,1562801550.052 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-07-10T23:32:30.063Z,1562801550.063 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1050
2019-07-10T23:32:30.066Z,1562801550.066 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-07-10T23:32:30.066Z,1562801550.066 [CBIT](DEBUG): Initializing heartbeat.
2019-07-10T23:32:30.083Z,1562801550.083 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 1051
2019-07-10T23:32:30.084Z,1562801550.084 [CTD_NeilBrown](INFO): Powering down
2019-07-10T23:32:30.111Z,1562801550.111 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 1052
2019-07-10T23:32:30.112Z,1562801550.112 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-07-10T23:32:30.138Z,1562801550.138 [CBIT](DEBUG): Deactivating GF circuits.
2019-07-10T23:32:30.138Z,1562801550.138 [CBIT](DEBUG): Deactivating emergency mode.
2019-07-10T23:32:30.139Z,1562801550.139 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1053
2019-07-10T23:32:30.143Z,1562801550.143 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-07-10T23:32:30.143Z,1562801550.143 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-07-10T23:32:30.143Z,1562801550.143 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-07-10T23:32:30.143Z,1562801550.143 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-07-10T23:32:30.143Z,1562801550.143 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-07-10T23:32:30.143Z,1562801550.143 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-07-10T23:32:30.144Z,1562801550.144 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-07-10T23:32:30.144Z,1562801550.144 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-07-10T23:32:30.144Z,1562801550.144 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-07-10T23:32:30.144Z,1562801550.144 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-07-10T23:32:30.144Z,1562801550.144 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-07-10T23:32:30.144Z,1562801550.144 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-07-10T23:32:30.145Z,1562801550.145 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-07-10T23:32:30.145Z,1562801550.145 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-07-10T23:32:30.145Z,1562801550.145 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-07-10T23:32:30.145Z,1562801550.145 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-07-10T23:32:30.174Z,1562801550.174 [CBIT](DEBUG): Backplane powered.
2019-07-10T23:32:30.175Z,1562801550.175 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-07-10T23:32:30.176Z,1562801550.176 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-07-10T23:32:30.177Z,1562801550.177 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-07-10T23:32:30.177Z,1562801550.177 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-07-10T23:32:30.178Z,1562801550.178 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-07-10T23:32:30.189Z,1562801550.189 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-07-10T23:32:30.208Z,1562801550.208 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2019-07-10T23:32:30.209Z,1562801550.209 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-07-10T23:32:30.284Z,1562801550.284 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-07-10T23:32:30.285Z,1562801550.285 [Default:A.Wait](DEBUG): Construct Wait.
2019-07-10T23:32:30.288Z,1562801550.288 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-07-10T23:32:30.314Z,1562801550.314 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-07-10T23:32:30.317Z,1562801550.317 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-07-10T23:32:30.338Z,1562801550.338 [Default:E.Execute](DEBUG): Construct Execute.
2019-07-10T23:32:30.341Z,1562801550.341 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2019-07-10T23:32:30.350Z,1562801550.350 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DropWeight,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,PowerOnly,RDI_Pathfinder,BPC1,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-07-10T23:32:30.362Z,1562801550.362 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-07-10T23:32:30.392Z,1562801550.392 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2019-07-10T23:32:30.393Z,1562801550.393 [DUSBL_Hydroid](INFO): Powering up
2019-07-10T23:32:30.393Z,1562801550.393 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-07-10T23:32:30.471Z,1562801550.471 [Radio_Surface](INFO): Powering up
2019-07-10T23:32:30.472Z,1562801550.472 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-07-10T23:32:30.483Z,1562801550.483 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-07-10T23:32:30.491Z,1562801550.491 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-07-10T23:32:30.492Z,1562801550.492 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-07-10T23:32:30.499Z,1562801550.499 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-07-10T23:32:30.499Z,1562801550.499 [MassServo](DEBUG): Initializing EZServoServo.
2019-07-10T23:32:30.507Z,1562801550.507 [MassServo](DEBUG): Initializing MassServo.
2019-07-10T23:32:30.507Z,1562801550.507 [RudderServo](DEBUG): Initializing EZServoServo.
2019-07-10T23:32:30.515Z,1562801550.515 [RudderServo](DEBUG): Initializing RudderServo.
2019-07-10T23:32:30.515Z,1562801550.515 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-07-10T23:32:30.523Z,1562801550.523 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-07-10T23:32:30.776Z,1562801550.776 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2019-07-10T23:32:30.776Z,1562801550.776 [DropWeight] Hardware Fault, FailCount= 1
2019-07-10T23:32:30.776Z,1562801550.776 [DropWeight](ERROR): Hardware Fault
2019-07-10T23:32:30.778Z,1562801550.778 [Micromodem](INFO): Powering up
2019-07-10T23:32:30.778Z,1562801550.778 [Micromodem](DEBUG): Initializing Micromodem.
2019-07-10T23:32:30.807Z,1562801550.807 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-07-10T23:32:30.815Z,1562801550.815 [CommandLine](FAULT): Scheduling is paused
2019-07-10T23:32:30.815Z,1562801550.815 [CBIT](INFO): Critical error at 20190710T233230
2019-07-10T23:32:30.816Z,1562801550.816 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-07-10T23:32:30.818Z,1562801550.818 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-07-10T23:32:30.819Z,1562801550.819 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-07-10T23:32:31.464Z,1562801551.464 [CBIT](INFO): Critical error at 20190710T233230
2019-07-10T23:32:35.650Z,1562801555.650 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2019-07-10T23:32:36.053Z,1562801556.053 [Micromodem](INFO): Nmea in: $CATMG,2019-07-10T23:32:35Z,RTC,RTC*74

2019-07-10T23:32:36.053Z,1562801556.053 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2019-07-10T23:32:35Z,RTC,RTC*74

2019-07-10T23:32:36.456Z,1562801556.456 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2019-07-10T23:32:36.457Z,1562801556.457 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2019-07-10T23:32:36.848Z,1562801556.848 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2019-07-10T23:32:36.848Z,1562801556.848 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-07-10T23:32:37.265Z,1562801557.265 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2019-07-10T23:32:37.265Z,1562801557.265 [Micromodem](INFO): Nmea out: $CCCFG,nav.nst,1*4D

2019-07-10T23:32:37.668Z,1562801557.668 [Micromodem](INFO): Nmea in: $CACFG,nav.nst,1*4F

2019-07-10T23:32:37.669Z,1562801557.669 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2019-07-10T23:32:38.072Z,1562801558.072 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2019-07-10T23:32:38.073Z,1562801558.073 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2019-07-10T23:32:38.465Z,1562801558.465 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2019-07-10T23:32:38.465Z,1562801558.465 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46

2019-07-10T23:32:38.872Z,1562801558.872 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44

2019-07-10T23:32:38.873Z,1562801558.873 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2019-07-10T23:32:39.272Z,1562801559.272 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2019-07-10T23:32:39.273Z,1562801559.273 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2019-07-10T23:32:39.672Z,1562801559.672 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2019-07-10T23:32:39.673Z,1562801559.673 [Micromodem](INFO): Nmea out: $CCCLK,2019,07,10,23,32,40*4C

2019-07-10T23:32:40.069Z,1562801560.069 [Micromodem](INFO): Nmea in: $CACLK,2019,7,10,23,32,40*7E

2019-07-10T23:32:40.469Z,1562801560.469 [Micromodem](INFO): Nmea in: $CATMS,0,2019-07-10T23:32:41Z*7F

2019-07-10T23:32:40.470Z,1562801560.470 [Micromodem](INFO): Nmea in: $CATMG,2019-07-10T23:32:41Z,USER_CMD,RTC*36

2019-07-10T23:32:43.718Z,1562801563.718 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-07-10T23:32:48.546Z,1562801568.546 [DUSBL_Hydroid](INFO): DUSBL Version:O
2019-07-10T23:32:56.216Z,1562801576.216 [NAL9602](INFO): Powering up NAL9602
2019-07-10T23:32:58.692Z,1562801578.692 [SBIT](IMPORTANT): Beginning Startup BIT
2019-07-10T23:32:58.701Z,1562801578.701 [CBIT](IMPORTANT): Beginning ground fault scan
2019-07-10T23:33:00.503Z,1562801580.503 [CommandLine](IMPORTANT): got command configSet DropWeight.loadAtStartup 0.000000 bool persist
2019-07-10T23:33:00.504Z,1562801580.504 [CommandLine](IMPORTANT): configSet DropWeight.loadAtStartup requires a restart to take effect.
2019-07-10T23:33:07.233Z,1562801587.233 [NAL9602](INFO): NAL9602 initialized
2019-07-10T23:33:08.050Z,1562801588.050 [NAL9602](DEBUG): Fix Requested
2019-07-10T23:33:09.755Z,1562801589.755 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.022943
CHAN A1 (24V): -0.026881
CHAN A2 (12V): -0.006691
CHAN A3 (5V): -0.001977
CHAN B0 (3.3V): -0.000051
CHAN B1 (3.15aV): 0.000298
CHAN B2 (3.15bV): 0.000214
CHAN B3 (GND): 0.001556
OPEN: 0.006786
Full Scale Calc: 4.765 mA, -1.589 mA
2019-07-10T23:33:22.340Z,1562801602.340 [CommandLine](IMPORTANT): got command configSet DUSBL_Hydroid.verbosity 3.000000 count
2019-07-10T23:33:26.697Z,1562801606.697 [CommandLine](IMPORTANT): got command configSet DUSBL_Hydroid.verbosity 3.000000 count persist
2019-07-10T23:33:32.009Z,1562801612.009 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-07-10T23:33:32.009Z,1562801612.009 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  93.43,  94.92,   0.00
2019-07-10T23:33:42.563Z,1562801622.563 [CommandLine](IMPORTANT): got command run ./Missions/DUSBL_Tracking.xml
2019-07-10T23:33:42.563Z,1562801622.563 [MissionManager](INFO): Loading Mission: ./Missions/DUSBL_Tracking.xml
2019-07-10T23:33:42.684Z,1562801622.684 [MissionManager](INFO): DefineArg DUSBL.MissionTimeout = 10.000000 min
2019-07-10T23:33:42.692Z,1562801622.692 [MissionManager](INFO): DefineArg DUSBL.TransponderCode = 2.000000 count
2019-07-10T23:33:42.701Z,1562801622.701 [MissionManager](INFO): DefineArg DUSBL.TrackingUpdatePeriod = 8.000000 s
2019-07-10T23:33:42.706Z,1562801622.706 [MissionManager](INFO): DefineArg DUSBL.NumberOfPings = 1.000000 count
2019-07-10T23:33:42.718Z,1562801622.718 [MissionManager](INFO): DefineArg DUSBL.EnabledDUSBL = DUSBL_Hydroid.loadAtStartup
2019-07-10T23:33:42.724Z,1562801622.724 [DUSBL:A.Pitch](DEBUG): Construct.
2019-07-10T23:33:42.745Z,1562801622.745 [DUSBL:B.SetSpeed](DEBUG): Construct.
2019-07-10T23:33:42.965Z,1562801622.965 [DUSBL:RequestRepeater:A.Wait](DEBUG): Construct Wait.
2019-07-10T23:33:43.000Z,1562801623.000 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Control="Tethys/Control"
         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/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd"
       Id="DUSBL">

    <Description>
        This mission is requests a DUSBL arming.
    </Description>

    <DefineArg Name="MissionTimeout"><Description>
        How long to let the mission run.
    </Description><Units:minute/><Value>10</Value></DefineArg>

    <DefineArg Name="TransponderCode"><Description>
        Transponder Address.
    </Description><Units:count/><Value>2</Value></DefineArg>
    
    <DefineArg Name="TrackingUpdatePeriod"><Description>
        How long to wait between acoustic queries
    </Description><Units:second/><Value>8</Value></DefineArg>
    
    <DefineArg Name="NumberOfPings"><Description>
        Number of pings requested each time.
    </Description><Units:count/><Value>1</Value></DefineArg>
    
    <DefineArg Name="EnabledDUSBL"><Description>
        Automatically set to true if the DUSBL is enabled. Set to false to disable
        reading from the DUSBL.
    </Description><Sensor:DUSBL_Hydroid.loadAtStartup/></DefineArg>    

    <Timeout Duration="MissionTimeout"/>

    <Guidance:Pitch>
        <Parallel/>
        <Setting><Guidance:Pitch.massPosition/><Control:VerticalControl.massDefault/></Setting>
    </Guidance:Pitch>

    <Guidance:SetSpeed>
        <Parallel/>
        <Setting><Guidance:SetSpeed.speed/><Units:meter_per_second/><Value>0</Value></Setting>
    </Guidance:SetSpeed>

    
        <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>
        
      <Aggregate Id="RequestRepeater">

        <Sequence/>
        
          <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Arg Name="MissionTimeout"/></Setting>            
          </Guidance:Wait>
      </Aggregate>

</Mission>


2019-07-10T23:33:43.007Z,1562801623.007 [CommandLine](IMPORTANT): Running ./Missions/DUSBL_Tracking.xml
2019-07-10T23:33:52.311Z,1562801632.311 [SBIT](IMPORTANT): SBIT PASSED
2019-07-10T23:33:52.344Z,1562801632.344 [CommandLine](IMPORTANT): got command configSet list
2019-07-10T23:33:52.344Z,1562801632.344 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-07-10T23:33:52.345Z,1562801632.345 [CommandLine](IMPORTANT): DUSBL_Hydroid.verbosity=3 count;

2019-07-10T23:33:52.345Z,1562801632.345 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-07-10T23:33:52.689Z,1562801632.689 [MissionManager](IMPORTANT): Started mission Startup
2019-07-10T23:33:52.689Z,1562801632.689 [Startup] Running Loop=1
2019-07-10T23:33:52.689Z,1562801632.689 [Startup](DEBUG): Aggregate::initialize Startup
2019-07-10T23:33:52.689Z,1562801632.689 [Startup:A.GoToSurface] Running Loop=1
2019-07-10T23:33:52.689Z,1562801632.689 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-07-10T23:33:52.690Z,1562801632.690 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-07-10T23:33:52.691Z,1562801632.691 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-07-10T23:33:52.691Z,1562801632.691 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-07-10T23:33:52.691Z,1562801632.691 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-07-10T23:33:52.692Z,1562801632.692 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-07-10T23:33:52.693Z,1562801632.693 [Startup:StartupSatComms] Running Loop=1
2019-07-10T23:33:52.693Z,1562801632.693 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-07-10T23:33:52.693Z,1562801632.693 [Startup:StartupSatComms:A] Running Loop=1
2019-07-10T23:33:53.078Z,1562801633.078 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-07-10T23:34:01.300Z,1562801641.300 [CommandLine](IMPORTANT): got command stop
2019-07-10T23:34:01.300Z,1562801641.300 [CommandLine](IMPORTANT): Scheduling is paused
2019-07-10T23:34:01.300Z,1562801641.300 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2019-07-10T23:34:25.000Z,1562801665.000 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-07-10T23:34:25.000Z,1562801665.000 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  94.42,  93.93,   0.00
2019-07-10T23:34:52.880Z,1562801692.880 [Startup:StartupSatComms:A](INFO): Timed out from 2019-07-10T23:33:52.7Z
2019-07-10T23:34:52.881Z,1562801692.881 [Startup:StartupSatComms:A] Stopped
2019-07-10T23:34:52.881Z,1562801692.881 [Startup:StartupSatComms:B] Running Loop=1
2019-07-10T23:34:53.272Z,1562801693.272 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-07-10T23:34:55.269Z,1562801695.269 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 8475265D000000005D00000400900000000000000000151D80030C5669BF31555EAE1409A708CE2489B0D24F922C4E73F3619C1A3D5DFEC97D4617D39DECC9F9
2019-07-10T23:34:55.270Z,1562801695.270 [Micromodem](INFO): Outgoing frame #2, 64 bytes: D53BA890DBBB7A588CAAC4D47DF26C1074F87DFAEB477C2219A8B2B768894B18F2AD47F60DFD50EDE0E9DE1AAE366CF1095C97B80A97D64DDB97C9840AF82C6F
2019-07-10T23:34:55.303Z,1562801695.303 [Micromodem](INFO): Outgoing frame #3, 12 bytes: F4E57E006EF2524220766000
2019-07-10T23:34:55.304Z,1562801695.304 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,3*53

2019-07-10T23:34:56.276Z,1562801696.276 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,3*51

2019-07-10T23:34:56.656Z,1562801696.656 [Micromodem](INFO): Nmea in: $CADRQ,233456,1,9,0,64,1*4B

2019-07-10T23:34:56.657Z,1562801696.657 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,8475265D000000005D00000400900000000000000000151D80030C5669BF31555EAE1409A708CE2489B0D24F922C4E73F3619C1A3D5DFEC97D4617D39DECC9F9*72

2019-07-10T23:34:57.064Z,1562801697.064 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71

2019-07-10T23:34:57.225Z,1562801697.225 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005037
2019-07-10T23:34:57.475Z,1562801697.475 [Micromodem](INFO): Nmea in: $CADRQ,233457,1,9,0,64,2*49

2019-07-10T23:34:57.475Z,1562801697.475 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,D53BA890DBBB7A588CAAC4D47DF26C1074F87DFAEB477C2219A8B2B768894B18F2AD47F60DFD50EDE0E9DE1AAE366CF1095C97B80A97D64DDB97C9840AF82C6F*03

2019-07-10T23:34:57.873Z,1562801697.873 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71

2019-07-10T23:34:58.280Z,1562801698.280 [Micromodem](INFO): Nmea in: $CADRQ,233458,1,9,0,64,3*47

2019-07-10T23:34:58.281Z,1562801698.281 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,F4E57E006EF2524220766000*02

2019-07-10T23:34:58.684Z,1562801698.684 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,12*70

2019-07-10T23:34:59.092Z,1562801699.092 [Micromodem](INFO): Nmea in: $CATXP,140*47

2019-07-10T23:35:02.716Z,1562801702.716 [Micromodem](INFO): Nmea in: $CATXF,140*51

2019-07-10T23:35:03.121Z,1562801703.121 [Micromodem](INFO): Nmea in: $CAXST,6,20190710,233459.053351,3,0,200,4000,10000,1,1,9,1,3,3,3,140*48

2019-07-10T23:35:03.693Z,1562801703.693 [DataOverHttps](INFO): Sending 132 bytes from file Logs/20190710T233220/Courier0000.lzma
2019-07-10T23:35:04.480Z,1562801704.480 [DataOverHttps](INFO): Moved sent file to Logs/20190710T233220/Courier0000.lzma.bak
2019-07-10T23:35:04.480Z,1562801704.480 [DataOverHttps](INFO): SBD MOMSN=11438381
2019-07-10T23:35:08.792Z,1562801708.792 [CommandLine](IMPORTANT): got command run ./Missions/DUSBL_Tracking.xml
2019-07-10T23:35:08.793Z,1562801708.793 [MissionManager](INFO): Uninitializing Mission DUSBL
2019-07-10T23:35:08.797Z,1562801708.797 [MissionManager](INFO): Loading Mission: ./Missions/DUSBL_Tracking.xml
2019-07-10T23:35:08.921Z,1562801708.921 [MissionManager](INFO): DefineArg DUSBL.MissionTimeout = 10.000000 min
2019-07-10T23:35:08.931Z,1562801708.931 [MissionManager](INFO): DefineArg DUSBL.TransponderCode = 2.000000 count
2019-07-10T23:35:08.933Z,1562801708.933 [MissionManager](INFO): DefineArg DUSBL.TrackingUpdatePeriod = 8.000000 s
2019-07-10T23:35:08.945Z,1562801708.945 [MissionManager](INFO): DefineArg DUSBL.NumberOfPings = 1.000000 count
2019-07-10T23:35:08.959Z,1562801708.959 [MissionManager](INFO): DefineArg DUSBL.EnabledDUSBL = DUSBL_Hydroid.loadAtStartup
2019-07-10T23:35:08.960Z,1562801708.960 [DUSBL:A.Pitch](DEBUG): Construct.
2019-07-10T23:35:08.972Z,1562801708.972 [DUSBL:B.SetSpeed](DEBUG): Construct.
2019-07-10T23:35:09.017Z,1562801709.017 [DUSBL:RequestRepeater:A.Wait](DEBUG): Construct Wait.
2019-07-10T23:35:09.037Z,1562801709.037 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Control="Tethys/Control"
         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/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd"
       Id="DUSBL">

    <Description>
        This mission is requests a DUSBL arming.
    </Description>

    <DefineArg Name="MissionTimeout"><Description>
        How long to let the mission run.
    </Description><Units:minute/><Value>10</Value></DefineArg>

    <DefineArg Name="TransponderCode"><Description>
        Transponder Address.
    </Description><Units:count/><Value>2</Value></DefineArg>
    
    <DefineArg Name="TrackingUpdatePeriod"><Description>
        How long to wait between acoustic queries
    </Description><Units:second/><Value>8</Value></DefineArg>
    
    <DefineArg Name="NumberOfPings"><Description>
        Number of pings requested each time.
    </Description><Units:count/><Value>1</Value></DefineArg>
    
    <DefineArg Name="EnabledDUSBL"><Description>
        Automatically set to true if the DUSBL is enabled. Set to false to disable
        reading from the DUSBL.
    </Description><Sensor:DUSBL_Hydroid.loadAtStartup/></DefineArg>    

    <Timeout Duration="MissionTimeout"/>

    <Guidance:Pitch>
        <Parallel/>
        <Setting><Guidance:Pitch.massPosition/><Control:VerticalControl.massDefault/></Setting>
    </Guidance:Pitch>

    <Guidance:SetSpeed>
        <Parallel/>
        <Setting><Guidance:SetSpeed.speed/><Units:meter_per_second/><Value>0</Value></Setting>
    </Guidance:SetSpeed>

    
        <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>
        
      <Aggregate Id="RequestRepeater">

        <Sequence/>
        
          <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Arg Name="MissionTimeout"/></Setting>            
          </Guidance:Wait>
      </Aggregate>

</Mission>


2019-07-10T23:35:09.067Z,1562801709.067 [CommandLine](IMPORTANT): Running ./Missions/DUSBL_Tracking.xml
2019-07-10T23:35:14.360Z,1562801714.360 [CommandLine](IMPORTANT): got command stop
2019-07-10T23:35:14.360Z,1562801714.360 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2019-07-10T23:35:17.626Z,1562801717.626 [DataOverHttps](INFO): Sending 216 bytes from file Logs/20190710T231649/Express0011.lzma
2019-07-10T23:35:18.376Z,1562801718.376 [CommandLine](IMPORTANT): got command show stack
2019-07-10T23:35:18.376Z,1562801718.376 [CommandLine](IMPORTANT): Behavior Stack:

2019-07-10T23:35:18.376Z,1562801718.376 [Startup](IMPORTANT): Priority 0: Startup:A.GoToSurface
2019-07-10T23:35:18.377Z,1562801718.377 [Startup:StartupSatComms](IMPORTANT): Priority 1: Startup:StartupSatComms:B
2019-07-10T23:35:18.432Z,1562801718.432 [DataOverHttps](INFO): Moved sent file to Logs/20190710T231649/Express0011.lzma.bak
2019-07-10T23:35:18.432Z,1562801718.432 [DataOverHttps](INFO): SBD MOMSN=11438386
2019-07-10T23:35:30.223Z,1562801730.223 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-07-10T23:35:30.223Z,1562801730.223 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-10T23:35:30.246Z,1562801730.246 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-10T23:35:30.679Z,1562801730.679 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-10T23:35:30.679Z,1562801730.679 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-07-10T23:35:31.311Z,1562801731.311 [DataOverHttps](INFO): Sending 796 bytes from file Logs/20190710T233220/Express0001.lzma
2019-07-10T23:35:32.116Z,1562801732.116 [DataOverHttps](INFO): Moved sent file to Logs/20190710T233220/Express0001.lzma.bak
2019-07-10T23:35:32.116Z,1562801732.116 [DataOverHttps](INFO): SBD MOMSN=11438397
2019-07-10T23:35:34.696Z,1562801734.696 [Startup:StartupSatComms:B] Stopped
2019-07-10T23:35:34.697Z,1562801734.697 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-07-10T23:35:34.697Z,1562801734.697 [Startup:StartupSatComms] Stopped
2019-07-10T23:35:34.697Z,1562801734.697 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-07-10T23:35:34.707Z,1562801734.707 [Startup](INFO): Completed Startup
2019-07-10T23:35:34.707Z,1562801734.707 [MissionManager](INFO): Startup is completed.
2019-07-10T23:35:34.707Z,1562801734.707 [MissionManager](INFO): Uninitializing Mission Startup
2019-07-10T23:35:34.707Z,1562801734.707 [Startup] Stopped
2019-07-10T23:35:34.707Z,1562801734.707 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-07-10T23:35:34.707Z,1562801734.707 [Startup:A.GoToSurface] Stopped
2019-07-10T23:35:34.708Z,1562801734.708 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-07-10T23:35:35.067Z,1562801735.067 [MissionManager](IMPORTANT): Started mission DUSBL
2019-07-10T23:35:35.068Z,1562801735.068 [DUSBL] Running Loop=1
2019-07-10T23:35:35.068Z,1562801735.068 [DUSBL](DEBUG): Aggregate::initialize DUSBL
2019-07-10T23:35:35.068Z,1562801735.068 [DUSBL:A.Pitch] Running Loop=1
2019-07-10T23:35:35.068Z,1562801735.068 [DUSBL:A.Pitch](DEBUG): Initialize.
2019-07-10T23:35:35.068Z,1562801735.068 [DUSBL:B.SetSpeed] Running Loop=1
2019-07-10T23:35:35.068Z,1562801735.068 [DUSBL:B.SetSpeed](DEBUG): Initialize.
2019-07-10T23:35:35.068Z,1562801735.068 [DUSBL:C.] Running Loop=1
2019-07-10T23:35:35.069Z,1562801735.069 [DUSBL:C.](INFO): Initializing TrackAcousticContact.
2019-07-10T23:35:35.069Z,1562801735.069 [DUSBL:RequestRepeater] Running Loop=1
2019-07-10T23:35:35.069Z,1562801735.069 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-07-10T23:35:35.069Z,1562801735.069 [DUSBL:RequestRepeater:A.Wait] Running Loop=1
2019-07-10T23:35:35.069Z,1562801735.069 [DUSBL:RequestRepeater:A.Wait](DEBUG): Initialize Wait Component.
2019-07-10T23:35:35.070Z,1562801735.070 [DUSBL:C.] Running Loop=1
2019-07-10T23:35:35.075Z,1562801735.075 [DUSBL:B.SetSpeed] Running Loop=1
2019-07-10T23:35:35.075Z,1562801735.075 [DUSBL:A.Pitch] Running Loop=1
2019-07-10T23:35:35.075Z,1562801735.075 [MissionManager](INFO): MissionManager is completed.
2019-07-10T23:35:35.075Z,1562801735.075 [MissionManager](INFO): Uninitializing Mission DUSBL
2019-07-10T23:35:35.076Z,1562801735.076 [DUSBL] Stopped
2019-07-10T23:35:35.076Z,1562801735.076 [DUSBL](DEBUG): Aggregate::uninitialize DUSBL
2019-07-10T23:35:35.076Z,1562801735.076 [DUSBL:A.Pitch] Stopped
2019-07-10T23:35:35.076Z,1562801735.076 [DUSBL:B.SetSpeed] Stopped
2019-07-10T23:35:35.076Z,1562801735.076 [DUSBL:B.SetSpeed](DEBUG): Uninitialize.
2019-07-10T23:35:35.076Z,1562801735.076 [DUSBL:C.] Stopped
2019-07-10T23:35:35.076Z,1562801735.076 [DUSBL:RequestRepeater] Stopped
2019-07-10T23:35:35.076Z,1562801735.076 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-07-10T23:35:35.076Z,1562801735.076 [DUSBL:RequestRepeater:A.Wait] Stopped
2019-07-10T23:35:35.076Z,1562801735.076 [DUSBL:RequestRepeater:A.Wait](DEBUG): Uninitialize Wait Component.
2019-07-10T23:35:35.463Z,1562801735.463 [MissionManager](IMPORTANT): Started mission Default
2019-07-10T23:35:35.464Z,1562801735.464 [Default] Running Loop=1
2019-07-10T23:35:35.464Z,1562801735.464 [Default](DEBUG): Aggregate::initialize Default
2019-07-10T23:35:35.464Z,1562801735.464 [Default:B.GoToSurface] Running Loop=1
2019-07-10T23:35:35.464Z,1562801735.464 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-07-10T23:35:35.464Z,1562801735.464 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-07-10T23:35:35.464Z,1562801735.464 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-07-10T23:35:35.465Z,1562801735.465 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-07-10T23:35:35.465Z,1562801735.465 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-07-10T23:35:35.465Z,1562801735.465 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-07-10T23:35:35.466Z,1562801735.466 [Default:A.Wait] Running Loop=1
2019-07-10T23:35:35.466Z,1562801735.466 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-07-10T23:35:48.808Z,1562801748.808 [Default:A.Wait](INFO): Done Waiting.
2019-07-10T23:35:48.808Z,1562801748.808 [Default:A.Wait] Stopped
2019-07-10T23:35:48.808Z,1562801748.808 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-07-10T23:35:49.204Z,1562801749.204 [Default:CheckIn] Running Loop=1
2019-07-10T23:35:49.204Z,1562801749.204 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-10T23:35:49.204Z,1562801749.204 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-10T23:35:49.633Z,1562801749.633 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-07-10T23:36:30.004Z,1562801790.004 [CommandLine](IMPORTANT): got command run ./Missions/DUSBL_Tracking.xml
2019-07-10T23:36:30.004Z,1562801790.004 [MissionManager](INFO): Loading Mission: ./Missions/DUSBL_Tracking.xml
2019-07-10T23:36:30.068Z,1562801790.068 [MissionManager](INFO): DefineArg DUSBL.MissionTimeout = 10.000000 min
2019-07-10T23:36:30.070Z,1562801790.070 [MissionManager](INFO): DefineArg DUSBL.TransponderCode = 2.000000 count
2019-07-10T23:36:30.080Z,1562801790.080 [MissionManager](INFO): DefineArg DUSBL.TrackingUpdatePeriod = 8.000000 s
2019-07-10T23:36:30.087Z,1562801790.087 [MissionManager](INFO): DefineArg DUSBL.NumberOfPings = 1.000000 count
2019-07-10T23:36:30.089Z,1562801790.089 [MissionManager](INFO): DefineArg DUSBL.EnabledDUSBL = DUSBL_Hydroid.loadAtStartup
2019-07-10T23:36:30.094Z,1562801790.094 [DUSBL:A.Pitch](DEBUG): Construct.
2019-07-10T23:36:30.103Z,1562801790.103 [DUSBL:B.SetSpeed](DEBUG): Construct.
2019-07-10T23:36:30.124Z,1562801790.124 [DUSBL:RequestRepeater:A.Wait](DEBUG): Construct Wait.
2019-07-10T23:36:30.136Z,1562801790.136 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Control="Tethys/Control"
         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/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd"
       Id="DUSBL">

    <Description>
        This mission is requests a DUSBL arming.
    </Description>

    <DefineArg Name="MissionTimeout"><Description>
        How long to let the mission run.
    </Description><Units:minute/><Value>10</Value></DefineArg>

    <DefineArg Name="TransponderCode"><Description>
        Transponder Address.
    </Description><Units:count/><Value>2</Value></DefineArg>
    
    <DefineArg Name="TrackingUpdatePeriod"><Description>
        How long to wait between acoustic queries
    </Description><Units:second/><Value>8</Value></DefineArg>
    
    <DefineArg Name="NumberOfPings"><Description>
        Number of pings requested each time.
    </Description><Units:count/><Value>1</Value></DefineArg>
    
    <DefineArg Name="EnabledDUSBL"><Description>
        Automatically set to true if the DUSBL is enabled. Set to false to disable
        reading from the DUSBL.
    </Description><Sensor:DUSBL_Hydroid.loadAtStartup/></DefineArg>    

    <Timeout Duration="MissionTimeout"/>

    <Guidance:Pitch>
        <Parallel/>
        <Setting><Guidance:Pitch.massPosition/><Control:VerticalControl.massDefault/></Setting>
    </Guidance:Pitch>

    <Guidance:SetSpeed>
        <Parallel/>
        <Setting><Guidance:SetSpeed.speed/><Units:meter_per_second/><Value>0</Value></Setting>
    </Guidance:SetSpeed>

    
        <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>
        
      <Aggregate Id="RequestRepeater">

        <Sequence/>
        
          <Guidance:Wait>
            <Sequence/>
            <Setting><Guidance:Wait.duration/><Arg Name="MissionTimeout"/></Setting>            
          </Guidance:Wait>
      </Aggregate>

</Mission>


2019-07-10T23:36:30.138Z,1562801790.138 [CommandLine](IMPORTANT): Running ./Missions/DUSBL_Tracking.xml
2019-07-10T23:36:30.411Z,1562801790.411 [Default] Stopped
2019-07-10T23:36:30.411Z,1562801790.411 [Default](DEBUG): Aggregate::uninitialize Default
2019-07-10T23:36:30.411Z,1562801790.411 [Default:B.GoToSurface] Stopped
2019-07-10T23:36:30.411Z,1562801790.411 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-07-10T23:36:30.411Z,1562801790.411 [Default:CheckIn] Stopped
2019-07-10T23:36:30.412Z,1562801790.412 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-10T23:36:30.412Z,1562801790.412 [Default:CheckIn:Read_GPS] Stopped
2019-07-10T23:36:30.412Z,1562801790.412 [MissionManager](IMPORTANT): Started mission DUSBL
2019-07-10T23:36:30.412Z,1562801790.412 [DUSBL] Running Loop=1
2019-07-10T23:36:30.412Z,1562801790.412 [DUSBL](DEBUG): Aggregate::initialize DUSBL
2019-07-10T23:36:30.412Z,1562801790.412 [DUSBL:A.Pitch] Running Loop=1
2019-07-10T23:36:30.412Z,1562801790.412 [DUSBL:A.Pitch](DEBUG): Initialize.
2019-07-10T23:36:30.413Z,1562801790.413 [DUSBL:B.SetSpeed] Running Loop=1
2019-07-10T23:36:30.413Z,1562801790.413 [DUSBL:B.SetSpeed](DEBUG): Initialize.
2019-07-10T23:36:30.413Z,1562801790.413 [DUSBL:C.] Running Loop=1
2019-07-10T23:36:30.413Z,1562801790.413 [DUSBL:C.](INFO): Initializing TrackAcousticContact.
2019-07-10T23:36:30.413Z,1562801790.413 [DUSBL:RequestRepeater] Running Loop=1
2019-07-10T23:36:30.413Z,1562801790.413 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-07-10T23:36:30.413Z,1562801790.413 [DUSBL:RequestRepeater:A.Wait] Running Loop=1
2019-07-10T23:36:30.413Z,1562801790.413 [DUSBL:RequestRepeater:A.Wait](DEBUG): Initialize Wait Component.
2019-07-10T23:36:30.414Z,1562801790.414 [DUSBL:C.] Running Loop=1
2019-07-10T23:36:30.414Z,1562801790.414 [DUSBL:B.SetSpeed] Running Loop=1
2019-07-10T23:36:30.415Z,1562801790.415 [DUSBL:A.Pitch] Running Loop=1
2019-07-10T23:36:36.854Z,1562801796.854 [NAL9602](INFO): SBD MO Status=2, MOMSN=8107, MT Status=2, MTMSN=0
2019-07-10T23:36:36.855Z,1562801796.855 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-07-10T23:36:38.496Z,1562801798.496 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:36:38.867Z,1562801798.867 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:36:38.868Z,1562801798.868 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:36:38.868Z,1562801798.868 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:36:38.869Z,1562801798.869 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:36:39.272Z,1562801799.272 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
O
2019-07-10T23:36:39.272Z,1562801799.272 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:36:39.272Z,1562801799.272 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:36:39.274Z,1562801799.274 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:36:39.682Z,1562801799.682 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:36:42.512Z,1562801802.512 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,63,
2019-07-10T23:36:42.513Z,1562801802.513 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,63,
2019-07-10T23:36:42.513Z,1562801802.513 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-10T23:36:46.576Z,1562801806.576 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:36:46.947Z,1562801806.947 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:36:46.948Z,1562801806.948 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:36:46.948Z,1562801806.948 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:36:46.948Z,1562801806.948 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:36:47.356Z,1562801807.356 [DUSBL_Hydroid](INFO): USBL response received:F4
!U1,P
80,63,
2019-07-10T23:36:47.356Z,1562801807.356 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:36:47.356Z,1562801807.356 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:36:47.357Z,1562801807.357 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:36:47.762Z,1562801807.762 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:36:50.601Z,1562801810.601 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,63,F4
2019-07-10T23:36:50.601Z,1562801810.601 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,63,F4
2019-07-10T23:36:50.602Z,1562801810.602 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-10T23:36:54.669Z,1562801814.669 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:36:55.031Z,1562801815.031 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:36:55.031Z,1562801815.031 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:36:55.032Z,1562801815.032 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:36:55.032Z,1562801815.032 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:36:55.435Z,1562801815.435 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
80,80,63,F4
2019-07-10T23:36:55.436Z,1562801815.436 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:36:55.436Z,1562801815.436 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:36:55.437Z,1562801815.437 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:36:55.850Z,1562801815.850 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:36:58.668Z,1562801818.668 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,63,F4
2019-07-10T23:36:58.668Z,1562801818.668 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,63,F4
2019-07-10T23:36:58.668Z,1562801818.668 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-10T23:37:02.741Z,1562801822.741 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:37:03.107Z,1562801823.107 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:37:03.108Z,1562801823.108 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:37:03.108Z,1562801823.108 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:37:03.108Z,1562801823.108 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:37:03.516Z,1562801823.516 [DUSBL_Hydroid](INFO): USBL response received:80,63,F4
!U1,P
F4
2019-07-10T23:37:03.516Z,1562801823.516 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:37:03.516Z,1562801823.516 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:37:03.517Z,1562801823.517 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:37:03.918Z,1562801823.918 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:37:04.320Z,1562801824.320 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,049,000,003B5,185,80,80,0C,FF
2019-07-10T23:37:04.321Z,1562801824.321 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:37:12.440Z,1562801832.440 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:37:12.803Z,1562801832.803 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:37:12.804Z,1562801832.804 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:37:12.804Z,1562801832.804 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:37:12.804Z,1562801832.804 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:37:13.223Z,1562801833.223 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
049,000,003B5,185,80,80,0C,FF
2019-07-10T23:37:13.223Z,1562801833.223 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:37:13.223Z,1562801833.223 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:37:13.225Z,1562801833.225 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:37:13.622Z,1562801833.622 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:37:14.020Z,1562801834.020 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,09B,000,00347,186,80,80,14,FF
2019-07-10T23:37:14.020Z,1562801834.020 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:37:22.124Z,1562801842.124 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:37:22.499Z,1562801842.499 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:37:22.500Z,1562801842.500 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:37:22.500Z,1562801842.500 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:37:22.500Z,1562801842.500 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:37:22.908Z,1562801842.908 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
09B,000,00347,186,80,80,14,FF
2019-07-10T23:37:22.908Z,1562801842.908 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:37:22.908Z,1562801842.908 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:37:22.909Z,1562801842.909 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:37:23.320Z,1562801843.320 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:37:26.144Z,1562801846.144 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,6300347,186,80,80,14,FF
2019-07-10T23:37:26.144Z,1562801846.144 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,6300347,186,80,80,14,FF
2019-07-10T23:37:26.144Z,1562801846.144 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-10T23:37:28.996Z,1562801848.996 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-07-10T23:37:28.997Z,1562801848.997 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  94.92,  95.92,   0.00
2019-07-10T23:37:30.248Z,1562801850.248 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:37:30.614Z,1562801850.614 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:37:30.614Z,1562801850.614 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:37:30.614Z,1562801850.614 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:37:30.631Z,1562801850.631 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:37:30.997Z,1562801850.997 [DUSBL_Hydroid](INFO): USBL response received:,F4
!U1,P
0,6300347,186,80,80,14,FF
2019-07-10T23:37:30.997Z,1562801850.997 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:37:30.997Z,1562801850.997 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:37:31.007Z,1562801851.007 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:37:31.406Z,1562801851.406 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:37:31.800Z,1562801851.800 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,06D,000,0034D,186,80,80,1F,FF
2019-07-10T23:37:31.801Z,1562801851.801 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:37:39.921Z,1562801859.921 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:37:40.291Z,1562801860.291 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:37:40.292Z,1562801860.292 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:37:40.292Z,1562801860.292 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:37:40.293Z,1562801860.293 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:37:40.688Z,1562801860.688 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
06D,000,0034D,186,80,80,1F,FF
2019-07-10T23:37:40.688Z,1562801860.688 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:37:40.688Z,1562801860.688 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:37:40.689Z,1562801860.689 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:37:41.106Z,1562801861.106 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:37:41.496Z,1562801861.496 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,0B2,000,00426,186,80,80,E9,FF
2019-07-10T23:37:41.496Z,1562801861.496 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:37:47.315Z,1562801867.315 [CommandLine](IMPORTANT): got command report touch DUSBL_Hydroid.acoustic_contact_range
2019-07-10T23:37:49.653Z,1562801869.653 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:37:49.995Z,1562801869.995 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:37:49.995Z,1562801869.995 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:37:49.996Z,1562801869.996 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:37:49.996Z,1562801869.996 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:37:50.384Z,1562801870.384 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
0B2,000,00426,186,80,80,E9,FF
2019-07-10T23:37:50.384Z,1562801870.384 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:37:50.384Z,1562801870.384 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:37:50.385Z,1562801870.385 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:37:50.789Z,1562801870.789 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:37:53.636Z,1562801873.636 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,63,F4
6,186,80,80,E9,FF
2019-07-10T23:37:53.636Z,1562801873.636 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,63,F4
6,186,80,80,E9,FF
2019-07-10T23:37:53.636Z,1562801873.636 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-10T23:37:57.713Z,1562801877.713 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:37:58.063Z,1562801878.063 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:37:58.064Z,1562801878.064 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:37:58.064Z,1562801878.064 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:37:58.065Z,1562801878.065 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:37:58.464Z,1562801878.464 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
80,80,63,F4
6,186,80,80,E9,FF
2019-07-10T23:37:58.464Z,1562801878.464 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:37:58.464Z,1562801878.464 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:37:58.465Z,1562801878.465 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:37:58.870Z,1562801878.870 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:37:59.276Z,1562801879.276 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,0C0,000,0030B,185,80,80,F6,FF
2019-07-10T23:37:59.276Z,1562801879.276 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:37:59.319Z,1562801879.319 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 9.240001 m
2019-07-10T23:38:07.396Z,1562801887.396 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:38:07.756Z,1562801887.756 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:38:07.756Z,1562801887.756 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:38:07.756Z,1562801887.756 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:38:07.757Z,1562801887.757 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:38:08.164Z,1562801888.164 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
0C0,000,0030B,185,80,80,F6,FF
2019-07-10T23:38:08.164Z,1562801888.164 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:38:08.164Z,1562801888.164 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:38:08.165Z,1562801888.165 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:38:08.570Z,1562801888.570 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:38:08.968Z,1562801888.968 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,0C2,000,00426,186,80,80,E6,FF
2019-07-10T23:38:08.968Z,1562801888.968 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:38:09.023Z,1562801889.023 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 26.219999 m
2019-07-10T23:38:10.610Z,1562801890.610 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-07-10T23:38:11.410Z,1562801891.410 [NAL9602](DEBUG): Fix Requested
2019-07-10T23:38:14.638Z,1562801894.638 [NAL9602](DEBUG): Fix Requested
2019-07-10T23:38:15.031Z,1562801895.031 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,233701.00,A,3648.16758,N,12147.28491,W,0.253,328.19,100719,,,A*70
2019-07-10T23:38:15.034Z,1562801895.034 [NAL9602](INFO): GPS fix at 20190710T233701: (36.802793, -121.788082)
2019-07-10T23:38:17.085Z,1562801897.085 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:38:17.463Z,1562801897.463 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:38:17.463Z,1562801897.463 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:38:17.463Z,1562801897.463 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:38:17.464Z,1562801897.464 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:38:17.863Z,1562801897.863 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
0C2,000,00426,186,80,80,E6,FF
2019-07-10T23:38:17.863Z,1562801897.863 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:38:17.864Z,1562801897.864 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:38:17.865Z,1562801897.865 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:38:18.270Z,1562801898.270 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:38:18.671Z,1562801898.671 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,0A8,000,00428,186,80,80,D3,FF
2019-07-10T23:38:18.672Z,1562801898.672 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:38:18.921Z,1562801898.921 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 26.340000 m
2019-07-10T23:38:26.891Z,1562801906.891 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:38:27.155Z,1562801907.155 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:38:27.155Z,1562801907.155 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:38:27.156Z,1562801907.156 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:38:27.156Z,1562801907.156 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:38:27.563Z,1562801907.563 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
0A8,000,00428,186,80,80,D3,FF
2019-07-10T23:38:27.563Z,1562801907.563 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:38:27.564Z,1562801907.564 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:38:27.565Z,1562801907.565 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:38:27.977Z,1562801907.977 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:38:28.010Z,1562801908.010 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-07-10T23:38:28.010Z,1562801908.010 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19071016472078,35.0, -0.1,   0.0,1448.9,,  0.00,  94.92,  95.42,  99.42
2019-07-10T23:38:28.368Z,1562801908.368 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,055,000,0034E,184,80,80,21,FF
2019-07-10T23:38:28.368Z,1562801908.368 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:38:28.458Z,1562801908.458 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 13.260000 m
2019-07-10T23:38:36.481Z,1562801916.481 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:38:36.855Z,1562801916.855 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:38:36.855Z,1562801916.855 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:38:36.855Z,1562801916.855 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:38:36.856Z,1562801916.856 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:38:37.255Z,1562801917.255 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
055,000,0034E,184,80,80,21,FF
2019-07-10T23:38:37.255Z,1562801917.255 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:38:37.256Z,1562801917.256 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:38:37.257Z,1562801917.257 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:38:37.662Z,1562801917.662 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:38:38.067Z,1562801918.067 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,0BF,000,00426,186,80,80,BB,FF
2019-07-10T23:38:38.068Z,1562801918.068 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:38:38.106Z,1562801918.106 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 26.219999 m
2019-07-10T23:38:46.190Z,1562801926.190 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:38:46.551Z,1562801926.551 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:38:46.551Z,1562801926.551 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:38:46.551Z,1562801926.551 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:38:46.552Z,1562801926.552 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:38:46.955Z,1562801926.955 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
0BF,000,00426,186,80,80,BB,FF
2019-07-10T23:38:46.955Z,1562801926.955 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:38:46.955Z,1562801926.955 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:38:46.957Z,1562801926.957 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:38:47.361Z,1562801927.361 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:38:47.375Z,1562801927.375 [NAL9602](INFO): Powering down
2019-07-10T23:38:47.757Z,1562801927.757 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,0BB,000,00426,185,80,80,DF,FF
2019-07-10T23:38:47.758Z,1562801927.758 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:38:47.813Z,1562801927.813 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 26.219999 m
2019-07-10T23:38:55.883Z,1562801935.883 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:38:56.247Z,1562801936.247 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:38:56.247Z,1562801936.247 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:38:56.247Z,1562801936.247 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:38:56.248Z,1562801936.248 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:38:56.651Z,1562801936.651 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
0BB,000,00426,185,80,80,DF,FF
2019-07-10T23:38:56.651Z,1562801936.651 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:38:56.652Z,1562801936.652 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:38:56.653Z,1562801936.653 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:38:57.054Z,1562801937.054 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:38:57.453Z,1562801937.453 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,0AF,000,00426,186,80,80,DA,FF
2019-07-10T23:38:57.454Z,1562801937.454 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:38:57.492Z,1562801937.492 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 26.219999 m
2019-07-10T23:39:05.566Z,1562801945.566 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:39:05.950Z,1562801945.950 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:39:05.950Z,1562801945.950 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:39:05.950Z,1562801945.950 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:39:05.951Z,1562801945.951 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:39:06.347Z,1562801946.347 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
0AF,000,00426,186,80,80,DA,FF
2019-07-10T23:39:06.347Z,1562801946.347 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:39:06.348Z,1562801946.348 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:39:06.349Z,1562801946.349 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:39:06.759Z,1562801946.759 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:39:09.591Z,1562801949.591 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,6300426,186,80,80,DA,FF
2019-07-10T23:39:09.592Z,1562801949.592 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,6300426,186,80,80,DA,FF
2019-07-10T23:39:09.592Z,1562801949.592 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-10T23:39:13.665Z,1562801953.665 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:39:14.019Z,1562801954.019 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:39:14.019Z,1562801954.019 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:39:14.019Z,1562801954.019 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:39:14.020Z,1562801954.020 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:39:14.423Z,1562801954.423 [DUSBL_Hydroid](INFO): USBL response received:,F4
!U1,P
0,6300426,186,80,80,DA,FF
2019-07-10T23:39:14.424Z,1562801954.424 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:39:14.424Z,1562801954.424 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:39:14.425Z,1562801954.425 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:39:14.829Z,1562801954.829 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:39:15.232Z,1562801955.232 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,0B7,000,00427,186,80,80,F0,FF
2019-07-10T23:39:15.232Z,1562801955.232 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:39:15.302Z,1562801955.302 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 26.280003 m
2019-07-10T23:39:23.351Z,1562801963.351 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:39:23.719Z,1562801963.719 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:39:23.719Z,1562801963.719 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:39:23.719Z,1562801963.719 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:39:23.720Z,1562801963.720 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:39:24.123Z,1562801964.123 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
0B7,000,00427,186,80,80,F0,FF
2019-07-10T23:39:24.123Z,1562801964.123 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:39:24.124Z,1562801964.124 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:39:24.125Z,1562801964.125 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:39:24.529Z,1562801964.529 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:39:24.931Z,1562801964.931 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,0C2,000,00427,186,80,80,BB,FF
2019-07-10T23:39:24.932Z,1562801964.932 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:39:24.978Z,1562801964.978 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 26.280003 m
2019-07-10T23:39:33.042Z,1562801973.042 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:39:33.411Z,1562801973.411 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:39:33.411Z,1562801973.411 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:39:33.411Z,1562801973.411 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:39:33.412Z,1562801973.412 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:39:33.815Z,1562801973.815 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
0C2,000,00427,186,80,80,BB,FF
2019-07-10T23:39:33.815Z,1562801973.815 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:39:33.816Z,1562801973.816 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:39:33.817Z,1562801973.817 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:39:34.222Z,1562801974.222 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:39:34.624Z,1562801974.624 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,0BD,000,00426,185,80,80,D8,FF
2019-07-10T23:39:34.625Z,1562801974.625 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:39:34.671Z,1562801974.671 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 26.219999 m
2019-07-10T23:39:42.766Z,1562801982.766 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:39:43.105Z,1562801983.105 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:39:43.105Z,1562801983.105 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:39:43.105Z,1562801983.105 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:39:43.106Z,1562801983.106 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:39:43.515Z,1562801983.515 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
0BD,000,00426,185,80,80,D8,FF
2019-07-10T23:39:43.515Z,1562801983.515 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:39:43.516Z,1562801983.516 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:39:43.517Z,1562801983.517 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:39:43.917Z,1562801983.917 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:39:46.748Z,1562801986.748 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,0,00426,185,80,80,D8,FF
2019-07-10T23:39:46.748Z,1562801986.748 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,0,00426,185,80,80,D8,FF
2019-07-10T23:39:46.748Z,1562801986.748 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-10T23:39:50.826Z,1562801990.826 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:39:51.187Z,1562801991.187 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:39:51.187Z,1562801991.187 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:39:51.187Z,1562801991.187 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:39:51.188Z,1562801991.188 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:39:51.623Z,1562801991.623 [DUSBL_Hydroid](INFO): USBL response received:63,F4
!U1,P
0,00426,185,80,80,D8,FF
2019-07-10T23:39:51.623Z,1562801991.623 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:39:51.623Z,1562801991.623 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:39:51.624Z,1562801991.624 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:39:52.019Z,1562801992.019 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:39:52.059Z,1562801992.059 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2019-07-10T23:39:52.061Z,1562801992.061 [BPC1](INFO): Received data from all battery sticks.
2019-07-10T23:39:52.399Z,1562801992.399 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,0AF,000,00427,186,80,80,E5,FF
2019-07-10T23:39:52.400Z,1562801992.400 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:39:52.490Z,1562801992.490 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 26.280003 m
2019-07-10T23:40:00.513Z,1562802000.513 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:40:00.883Z,1562802000.883 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:40:00.883Z,1562802000.883 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:40:00.884Z,1562802000.884 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:40:00.884Z,1562802000.884 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:40:01.288Z,1562802001.288 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
0AF,000,00427,186,80,80,E5,FF
2019-07-10T23:40:01.288Z,1562802001.288 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:40:01.288Z,1562802001.288 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:40:01.289Z,1562802001.289 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:40:01.701Z,1562802001.701 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:40:04.523Z,1562802004.523 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,6300427,186,80,80,E5,FF
2019-07-10T23:40:04.524Z,1562802004.524 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,6300427,186,80,80,E5,FF
2019-07-10T23:40:04.524Z,1562802004.524 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-10T23:40:08.620Z,1562802008.620 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:40:08.967Z,1562802008.967 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:40:08.967Z,1562802008.967 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:40:08.967Z,1562802008.967 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:40:08.968Z,1562802008.968 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:40:09.367Z,1562802009.367 [DUSBL_Hydroid](INFO): USBL response received:,F4
!U1,P
0,6300427,186,80,80,E5,FF
2019-07-10T23:40:09.368Z,1562802009.368 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:40:09.368Z,1562802009.368 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:40:09.369Z,1562802009.369 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:40:09.774Z,1562802009.774 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:40:10.179Z,1562802010.179 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,0BF,000,00425,186,80,80,BC,FF
2019-07-10T23:40:10.180Z,1562802010.180 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:40:10.217Z,1562802010.217 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 26.160002 m
2019-07-10T23:40:18.293Z,1562802018.293 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:40:18.657Z,1562802018.657 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:40:18.657Z,1562802018.657 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:40:18.657Z,1562802018.657 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:40:18.658Z,1562802018.658 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:40:19.061Z,1562802019.061 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
0BF,000,00425,186,80,80,BC,FF
2019-07-10T23:40:19.061Z,1562802019.061 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:40:19.061Z,1562802019.061 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:40:19.063Z,1562802019.063 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:40:19.473Z,1562802019.473 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:40:19.875Z,1562802019.875 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,0C2,000,00427,186,80,80,F0,FF
2019-07-10T23:40:19.876Z,1562802019.876 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:40:19.967Z,1562802019.967 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 26.280003 m
2019-07-10T23:40:27.997Z,1562802027.997 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:40:28.362Z,1562802028.362 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:40:28.363Z,1562802028.363 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:40:28.363Z,1562802028.363 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:40:28.364Z,1562802028.364 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:40:28.759Z,1562802028.759 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
0C2,000,00427,186,80,80,F0,FF
2019-07-10T23:40:28.759Z,1562802028.759 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:40:28.760Z,1562802028.760 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:40:28.761Z,1562802028.761 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:40:29.169Z,1562802029.169 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:40:29.569Z,1562802029.569 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,0AA,000,00427,185,80,80,D0,FF
2019-07-10T23:40:29.569Z,1562802029.569 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:40:29.659Z,1562802029.659 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 26.280003 m
2019-07-10T23:40:37.690Z,1562802037.690 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:40:38.051Z,1562802038.051 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:40:38.051Z,1562802038.051 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:40:38.052Z,1562802038.052 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:40:38.052Z,1562802038.052 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:40:38.455Z,1562802038.455 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
0AA,000,00427,185,80,80,D0,FF
2019-07-10T23:40:38.456Z,1562802038.456 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:40:38.456Z,1562802038.456 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:40:38.457Z,1562802038.457 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:40:38.862Z,1562802038.862 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:40:39.263Z,1562802039.263 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,0B3,000,00426,186,80,80,EB,FF
2019-07-10T23:40:39.264Z,1562802039.264 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:40:39.350Z,1562802039.350 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 26.219999 m
2019-07-10T23:40:47.378Z,1562802047.378 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:40:47.745Z,1562802047.745 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:40:47.745Z,1562802047.745 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:40:47.745Z,1562802047.745 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:40:47.746Z,1562802047.746 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:40:48.151Z,1562802048.151 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
0B3,000,00426,186,80,80,EB,FF
2019-07-10T23:40:48.151Z,1562802048.151 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:40:48.152Z,1562802048.152 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:40:48.153Z,1562802048.153 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:40:48.562Z,1562802048.562 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:40:48.974Z,1562802048.974 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,0BC,000,00426,186,80,80,F3,FF
2019-07-10T23:40:48.974Z,1562802048.974 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:40:49.018Z,1562802049.018 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 26.219999 m
2019-07-10T23:40:50.619Z,1562802050.619 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,    -1,   +32,   -15,A
2019-07-10T23:40:57.073Z,1562802057.073 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:40:57.447Z,1562802057.447 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:40:57.447Z,1562802057.447 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:40:57.447Z,1562802057.447 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:40:57.448Z,1562802057.448 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:40:57.848Z,1562802057.848 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
0BC,000,00426,186,80,80,F3,FF
2019-07-10T23:40:57.848Z,1562802057.848 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:40:57.848Z,1562802057.848 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:40:57.849Z,1562802057.849 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:40:58.259Z,1562802058.259 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:40:58.659Z,1562802058.659 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,096,000,00347,186,80,80,15,FF
2019-07-10T23:40:58.660Z,1562802058.660 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:40:58.741Z,1562802058.741 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 12.840000 m
2019-07-10T23:41:06.778Z,1562802066.778 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:41:07.143Z,1562802067.143 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:41:07.143Z,1562802067.143 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:41:07.143Z,1562802067.143 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:41:07.144Z,1562802067.144 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:41:07.549Z,1562802067.549 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
096,000,00347,186,80,80,15,FF
2019-07-10T23:41:07.549Z,1562802067.549 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:41:07.549Z,1562802067.549 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:41:07.551Z,1562802067.551 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:41:07.950Z,1562802067.950 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:41:10.777Z,1562802070.777 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,,000,00347,186,80,80,15,FF
2019-07-10T23:41:10.778Z,1562802070.778 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,,000,00347,186,80,80,15,FF
2019-07-10T23:41:10.778Z,1562802070.778 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-10T23:41:14.860Z,1562802074.860 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:41:15.219Z,1562802075.219 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:41:15.219Z,1562802075.219 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:41:15.220Z,1562802075.220 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:41:15.220Z,1562802075.220 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:41:15.625Z,1562802075.625 [DUSBL_Hydroid](INFO): USBL response received:80,63,F4
!U1,P
0347,186,80,80,15,FF
2019-07-10T23:41:15.625Z,1562802075.625 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:41:15.625Z,1562802075.625 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:41:15.627Z,1562802075.627 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:41:16.029Z,1562802076.029 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:41:16.432Z,1562802076.432 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,0AB,000,00427,186,80,80,DC,FF
2019-07-10T23:41:16.432Z,1562802076.432 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:41:16.474Z,1562802076.474 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 26.280003 m
2019-07-10T23:41:24.558Z,1562802084.558 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:41:24.919Z,1562802084.919 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:41:24.919Z,1562802084.919 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:41:24.919Z,1562802084.919 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:41:24.920Z,1562802084.920 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:41:25.323Z,1562802085.323 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
0AB,000,00427,186,80,80,DC,FF
2019-07-10T23:41:25.323Z,1562802085.323 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:41:25.324Z,1562802085.324 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:41:25.325Z,1562802085.325 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:41:25.729Z,1562802085.729 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:41:26.148Z,1562802086.148 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,058,000,0034D,184,80,80,21,FF
2019-07-10T23:41:26.148Z,1562802086.148 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:41:26.209Z,1562802086.209 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 13.199997 m
2019-07-10T23:41:34.242Z,1562802094.242 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:41:34.620Z,1562802094.620 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:41:34.621Z,1562802094.621 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:41:34.621Z,1562802094.621 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:41:34.622Z,1562802094.622 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:41:35.015Z,1562802095.015 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
058,000,0034D,184,80,80,21,FF
2019-07-10T23:41:35.015Z,1562802095.015 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:41:35.016Z,1562802095.016 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:41:35.017Z,1562802095.017 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:41:35.421Z,1562802095.421 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:41:35.824Z,1562802095.824 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,042,000,0034E,184,80,80,22,FF
2019-07-10T23:41:35.824Z,1562802095.824 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:41:35.951Z,1562802095.951 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 13.260000 m
2019-07-10T23:41:43.935Z,1562802103.935 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:41:44.313Z,1562802104.313 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:41:44.313Z,1562802104.313 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:41:44.314Z,1562802104.314 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:41:44.314Z,1562802104.314 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:41:44.712Z,1562802104.712 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
042,000,0034E,184,80,80,22,FF
2019-07-10T23:41:44.712Z,1562802104.712 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:41:44.712Z,1562802104.712 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:41:44.713Z,1562802104.713 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:41:45.121Z,1562802105.121 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:41:45.523Z,1562802105.523 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,0BB,000,00425,186,80,80,D5,FF
2019-07-10T23:41:45.524Z,1562802105.524 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:41:45.635Z,1562802105.635 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 26.160002 m
2019-07-10T23:41:49.979Z,1562802109.979 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-07-10T23:41:49.979Z,1562802109.979 [RDI_Pathfinder](ERROR): Failed to parse:
:TS6504278,35.0, -0.1,   0.0,1448.9,  0
2019-07-10T23:41:53.654Z,1562802113.654 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:41:54.004Z,1562802114.004 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:41:54.004Z,1562802114.004 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:41:54.004Z,1562802114.004 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:41:54.005Z,1562802114.005 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:41:54.407Z,1562802114.407 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
0BB,000,00425,186,80,80,D5,FF
2019-07-10T23:41:54.407Z,1562802114.407 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:41:54.408Z,1562802114.408 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:41:54.409Z,1562802114.409 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:41:54.813Z,1562802114.813 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:41:55.219Z,1562802115.219 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,0AF,000,00426,185,80,80,E4,FF
2019-07-10T23:41:55.220Z,1562802115.220 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:41:55.303Z,1562802115.303 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 26.219999 m
2019-07-10T23:42:03.330Z,1562802123.330 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:42:03.711Z,1562802123.711 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:42:03.711Z,1562802123.711 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:42:03.712Z,1562802123.712 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:42:03.712Z,1562802123.712 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:42:04.111Z,1562802124.111 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
0AF,000,00426,185,80,80,E4,FF
2019-07-10T23:42:04.111Z,1562802124.111 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:42:04.112Z,1562802124.112 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:42:04.113Z,1562802124.113 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:42:04.513Z,1562802124.513 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:42:04.912Z,1562802124.912 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,05B,000,0034D,186,80,80,22,FF
2019-07-10T23:42:04.912Z,1562802124.912 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:42:04.987Z,1562802124.987 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 13.199997 m
2019-07-10T23:42:13.026Z,1562802133.026 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:42:13.399Z,1562802133.399 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:42:13.399Z,1562802133.399 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:42:13.399Z,1562802133.399 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:42:13.400Z,1562802133.400 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:42:13.807Z,1562802133.807 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
05B,000,0034D,186,80,80,22,FF
2019-07-10T23:42:13.807Z,1562802133.807 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:42:13.808Z,1562802133.808 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:42:13.809Z,1562802133.809 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:42:14.205Z,1562802134.205 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:42:14.620Z,1562802134.620 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,044,000,0034E,185,80,80,23,FF
2019-07-10T23:42:14.620Z,1562802134.620 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:42:14.698Z,1562802134.698 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 13.260000 m
2019-07-10T23:42:22.724Z,1562802142.724 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:42:23.095Z,1562802143.095 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:42:23.095Z,1562802143.095 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:42:23.095Z,1562802143.095 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:42:23.096Z,1562802143.096 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:42:23.499Z,1562802143.499 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
044,000,0034E,185,80,80,23,FF
2019-07-10T23:42:23.499Z,1562802143.499 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:42:23.500Z,1562802143.500 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:42:23.501Z,1562802143.501 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:42:23.901Z,1562802143.901 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:42:24.307Z,1562802144.307 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,0C0,000,00427,186,80,80,D6,FF
2019-07-10T23:42:24.308Z,1562802144.308 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:42:24.350Z,1562802144.350 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 26.280003 m
2019-07-10T23:42:32.418Z,1562802152.418 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:42:32.795Z,1562802152.795 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:42:32.795Z,1562802152.795 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:42:32.795Z,1562802152.795 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:42:32.796Z,1562802152.796 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:42:33.199Z,1562802153.199 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
0C0,000,00427,186,80,80,D6,FF
2019-07-10T23:42:33.199Z,1562802153.199 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:42:33.200Z,1562802153.200 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:42:33.201Z,1562802153.201 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:42:33.636Z,1562802153.636 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:42:34.012Z,1562802154.012 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,0B5,000,00427,185,80,80,ED,FF
2019-07-10T23:42:34.012Z,1562802154.012 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:42:34.124Z,1562802154.124 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 26.280003 m
2019-07-10T23:42:42.126Z,1562802162.126 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:42:42.499Z,1562802162.499 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:42:42.499Z,1562802162.499 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:42:42.500Z,1562802162.500 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:42:42.500Z,1562802162.500 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:42:42.895Z,1562802162.895 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
0B5,000,00427,185,80,80,ED,FF
2019-07-10T23:42:42.896Z,1562802162.896 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:42:42.896Z,1562802162.896 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:42:42.897Z,1562802162.897 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:42:43.301Z,1562802163.301 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:42:43.709Z,1562802163.709 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,0BA,000,00426,186,80,80,F6,FF
2019-07-10T23:42:43.710Z,1562802163.710 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:42:43.756Z,1562802163.756 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 26.219999 m
2019-07-10T23:42:51.820Z,1562802171.820 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:42:52.191Z,1562802172.191 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:42:52.191Z,1562802172.191 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:42:52.191Z,1562802172.191 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:42:52.192Z,1562802172.192 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:42:52.614Z,1562802172.614 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
0BA,000,00426,186,80,80,F6,FF
2019-07-10T23:42:52.615Z,1562802172.615 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:42:52.615Z,1562802172.615 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:42:52.616Z,1562802172.616 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:42:53.001Z,1562802173.001 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:42:53.403Z,1562802173.403 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,0C2,000,00425,186,80,80,F2,FF
2019-07-10T23:42:53.404Z,1562802173.404 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:42:53.486Z,1562802173.486 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 26.160002 m
2019-07-10T23:43:01.507Z,1562802181.507 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:43:01.881Z,1562802181.881 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:43:01.881Z,1562802181.881 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:43:01.881Z,1562802181.881 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:43:01.882Z,1562802181.882 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:43:02.288Z,1562802182.288 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
0C2,000,00425,186,80,80,F2,FF
2019-07-10T23:43:02.288Z,1562802182.288 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:43:02.288Z,1562802182.288 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:43:02.289Z,1562802182.289 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:43:02.697Z,1562802182.697 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:43:03.103Z,1562802183.103 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,0C2,000,00427,186,80,80,CD,FF
2019-07-10T23:43:03.104Z,1562802183.104 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:43:03.142Z,1562802183.142 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 26.280003 m
2019-07-10T23:43:11.207Z,1562802191.207 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:43:11.594Z,1562802191.594 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:43:11.595Z,1562802191.595 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:43:11.595Z,1562802191.595 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:43:11.596Z,1562802191.596 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:43:11.988Z,1562802191.988 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
0C2,000,00427,186,80,80,CD,FF
2019-07-10T23:43:11.988Z,1562802191.988 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:43:11.988Z,1562802191.988 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:43:11.990Z,1562802191.990 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:43:12.407Z,1562802192.407 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:43:12.792Z,1562802192.792 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,0B5,000,00427,186,80,80,EC,FF
2019-07-10T23:43:12.792Z,1562802192.792 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:43:12.841Z,1562802192.841 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 26.280003 m
2019-07-10T23:43:20.908Z,1562802200.908 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:43:21.275Z,1562802201.275 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:43:21.275Z,1562802201.275 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:43:21.275Z,1562802201.275 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:43:21.276Z,1562802201.276 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:43:21.679Z,1562802201.679 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
0B5,000,00427,186,80,80,EC,FF
2019-07-10T23:43:21.679Z,1562802201.679 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:43:21.680Z,1562802201.680 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:43:21.681Z,1562802201.681 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:43:22.085Z,1562802202.085 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:43:24.912Z,1562802204.912 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,8000,00427,186,80,80,EC,FF
2019-07-10T23:43:24.912Z,1562802204.912 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,8000,00427,186,80,80,EC,FF
2019-07-10T23:43:24.912Z,1562802204.912 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-10T23:43:28.992Z,1562802208.992 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:43:29.355Z,1562802209.355 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:43:29.355Z,1562802209.355 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:43:29.355Z,1562802209.355 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:43:29.356Z,1562802209.356 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:43:29.763Z,1562802209.763 [DUSBL_Hydroid](INFO): USBL response received:,63,F4
!U1,P
,00427,186,80,80,EC,FF
2019-07-10T23:43:29.764Z,1562802209.764 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:43:29.764Z,1562802209.764 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:43:29.765Z,1562802209.765 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:43:30.165Z,1562802210.165 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:43:30.570Z,1562802210.570 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,0BD,000,00425,185,80,80,BC,FF
2019-07-10T23:43:30.571Z,1562802210.571 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:43:30.681Z,1562802210.681 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 26.160002 m
2019-07-10T23:43:38.705Z,1562802218.705 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:43:39.051Z,1562802219.051 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:43:39.051Z,1562802219.051 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:43:39.051Z,1562802219.051 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:43:39.052Z,1562802219.052 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:43:39.453Z,1562802219.453 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
0BD,000,00425,185,80,80,BC,FF
2019-07-10T23:43:39.453Z,1562802219.453 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:43:39.454Z,1562802219.454 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:43:39.455Z,1562802219.455 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:43:39.861Z,1562802219.861 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:43:40.267Z,1562802220.267 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,0BB,000,00425,186,80,80,D7,FF
2019-07-10T23:43:40.268Z,1562802220.268 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:43:40.304Z,1562802220.304 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 26.160002 m
2019-07-10T23:43:48.383Z,1562802228.383 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:43:48.754Z,1562802228.754 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:43:48.754Z,1562802228.754 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:43:48.755Z,1562802228.755 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:43:48.756Z,1562802228.756 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:43:49.156Z,1562802229.156 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
0BB,000,00425,186,80,80,D7,FF
2019-07-10T23:43:49.156Z,1562802229.156 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:43:49.157Z,1562802229.157 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:43:49.158Z,1562802229.158 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:43:49.563Z,1562802229.563 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:43:52.387Z,1562802232.387 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,8000,00425,186,80,80,D7,FF
2019-07-10T23:43:52.388Z,1562802232.388 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,8000,00425,186,80,80,D7,FF
2019-07-10T23:43:52.388Z,1562802232.388 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-10T23:43:56.450Z,1562802236.450 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:43:56.827Z,1562802236.827 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:43:56.827Z,1562802236.827 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:43:56.828Z,1562802236.828 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:43:56.828Z,1562802236.828 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:43:57.231Z,1562802237.231 [DUSBL_Hydroid](INFO): USBL response received:,63,F4
!U1,P
,00425,186,80,80,D7,FF
2019-07-10T23:43:57.231Z,1562802237.231 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:43:57.232Z,1562802237.232 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:43:57.233Z,1562802237.233 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:43:57.638Z,1562802237.638 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:43:58.039Z,1562802238.039 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,0AF,000,00427,186,80,80,E3,FF
2019-07-10T23:43:58.040Z,1562802238.040 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:43:58.082Z,1562802238.082 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 26.280003 m
2019-07-10T23:44:06.154Z,1562802246.154 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:44:06.527Z,1562802246.527 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:44:06.527Z,1562802246.527 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:44:06.528Z,1562802246.528 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:44:06.528Z,1562802246.528 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:44:06.927Z,1562802246.927 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
0AF,000,00427,186,80,80,E3,FF
2019-07-10T23:44:06.928Z,1562802246.928 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:44:06.928Z,1562802246.928 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:44:06.929Z,1562802246.929 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:44:07.334Z,1562802247.334 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:44:07.743Z,1562802247.743 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,0BA,000,00426,186,80,80,BC,FF
2019-07-10T23:44:07.744Z,1562802247.744 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:44:07.791Z,1562802247.791 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 26.219999 m
2019-07-10T23:44:15.852Z,1562802255.852 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:44:16.223Z,1562802256.223 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:44:16.223Z,1562802256.223 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:44:16.223Z,1562802256.223 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:44:16.224Z,1562802256.224 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:44:16.627Z,1562802256.627 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
0BA,000,00426,186,80,80,BC,FF
2019-07-10T23:44:16.627Z,1562802256.627 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:44:16.628Z,1562802256.628 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:44:16.629Z,1562802256.629 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:44:17.030Z,1562802257.030 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:44:17.432Z,1562802257.432 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,0C0,000,00425,186,80,80,EE,FF
2019-07-10T23:44:17.432Z,1562802257.432 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:44:17.470Z,1562802257.470 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 26.160002 m
2019-07-10T23:44:25.537Z,1562802265.537 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:44:25.917Z,1562802265.917 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:44:25.917Z,1562802265.917 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:44:25.918Z,1562802265.918 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:44:25.918Z,1562802265.918 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:44:26.323Z,1562802266.323 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
0C0,000,00425,186,80,80,EE,FF
2019-07-10T23:44:26.323Z,1562802266.323 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:44:26.323Z,1562802266.323 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:44:26.325Z,1562802266.325 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:44:26.729Z,1562802266.729 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:44:27.131Z,1562802267.131 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,053,000,00324,183,80,80,23,FF
2019-07-10T23:44:27.132Z,1562802267.132 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:44:27.169Z,1562802267.169 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 10.739998 m
2019-07-10T23:44:35.246Z,1562802275.246 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:44:35.619Z,1562802275.619 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:44:35.619Z,1562802275.619 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:44:35.620Z,1562802275.620 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:44:35.620Z,1562802275.620 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:44:36.019Z,1562802276.019 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
053,000,00324,183,80,80,23,FF
2019-07-10T23:44:36.019Z,1562802276.019 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:44:36.019Z,1562802276.019 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:44:36.021Z,1562802276.021 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:44:36.425Z,1562802276.425 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:44:36.823Z,1562802276.823 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,0B2,000,00426,186,80,80,E9,FF
2019-07-10T23:44:36.824Z,1562802276.824 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:44:36.871Z,1562802276.871 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 26.219999 m
2019-07-10T23:44:44.965Z,1562802284.965 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:44:45.307Z,1562802285.307 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:44:45.307Z,1562802285.307 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:44:45.307Z,1562802285.307 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:44:45.308Z,1562802285.308 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:44:45.715Z,1562802285.715 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
0B2,000,00426,186,80,80,E9,FF
2019-07-10T23:44:45.715Z,1562802285.715 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:44:45.715Z,1562802285.715 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:44:45.717Z,1562802285.717 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:44:46.121Z,1562802286.121 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:44:46.523Z,1562802286.523 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,0BB,000,00425,186,80,80,BD,FF
2019-07-10T23:44:46.524Z,1562802286.524 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:44:46.565Z,1562802286.565 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 26.160002 m
2019-07-10T23:44:54.654Z,1562802294.654 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:44:55.001Z,1562802295.001 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:44:55.001Z,1562802295.001 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:44:55.001Z,1562802295.001 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:44:55.002Z,1562802295.002 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:44:55.411Z,1562802295.411 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
0BB,000,00425,186,80,80,BD,FF
2019-07-10T23:44:55.411Z,1562802295.411 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:44:55.411Z,1562802295.411 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:44:55.413Z,1562802295.413 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:44:55.817Z,1562802295.817 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:44:56.219Z,1562802296.219 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,0C2,000,00426,186,80,80,E8,FF
2019-07-10T23:44:56.220Z,1562802296.220 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:44:56.257Z,1562802296.257 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 26.219999 m
2019-07-10T23:45:04.329Z,1562802304.329 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:45:04.703Z,1562802304.703 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:45:04.703Z,1562802304.703 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:45:04.703Z,1562802304.703 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:45:04.704Z,1562802304.704 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:45:05.103Z,1562802305.103 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
0C2,000,00426,186,80,80,E8,FF
2019-07-10T23:45:05.103Z,1562802305.103 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:45:05.104Z,1562802305.104 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:45:05.105Z,1562802305.105 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:45:05.513Z,1562802305.513 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:45:05.909Z,1562802305.909 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,0AD,000,00425,186,80,80,D7,FF
2019-07-10T23:45:05.909Z,1562802305.909 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:45:05.988Z,1562802305.988 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 26.160002 m
2019-07-10T23:45:14.025Z,1562802314.025 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:45:14.393Z,1562802314.393 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:45:14.393Z,1562802314.393 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:45:14.393Z,1562802314.393 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:45:14.394Z,1562802314.394 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:45:14.803Z,1562802314.803 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
0AD,000,00425,186,80,80,D7,FF
2019-07-10T23:45:14.803Z,1562802314.803 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:45:14.804Z,1562802314.804 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:45:14.805Z,1562802314.805 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:45:15.209Z,1562802315.209 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:45:15.618Z,1562802315.618 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,0B7,000,00427,186,80,80,F1,FF
2019-07-10T23:45:15.619Z,1562802315.619 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:45:15.712Z,1562802315.712 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 26.280003 m
2019-07-10T23:45:23.730Z,1562802323.730 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:45:24.092Z,1562802324.092 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:45:24.092Z,1562802324.092 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:45:24.092Z,1562802324.092 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:45:24.093Z,1562802324.093 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:45:24.495Z,1562802324.495 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
0B7,000,00427,186,80,80,F1,FF
2019-07-10T23:45:24.496Z,1562802324.496 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:45:24.496Z,1562802324.496 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:45:24.497Z,1562802324.497 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:45:24.902Z,1562802324.902 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:45:25.301Z,1562802325.301 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,0BD,000,00427,186,80,80,F2,FF
2019-07-10T23:45:25.302Z,1562802325.302 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:45:25.360Z,1562802325.360 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 26.280003 m
2019-07-10T23:45:33.414Z,1562802333.414 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:45:33.788Z,1562802333.788 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:45:33.788Z,1562802333.788 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:45:33.788Z,1562802333.788 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:45:33.789Z,1562802333.789 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:45:34.192Z,1562802334.192 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
0BD,000,00427,186,80,80,F2,FF
2019-07-10T23:45:34.192Z,1562802334.192 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:45:34.192Z,1562802334.192 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:45:34.193Z,1562802334.193 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:45:34.633Z,1562802334.633 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:45:35.015Z,1562802335.015 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,0C2,000,00425,185,80,80,DF,FF
2019-07-10T23:45:35.016Z,1562802335.016 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:45:35.081Z,1562802335.081 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 26.160002 m
2019-07-10T23:45:43.106Z,1562802343.106 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:45:43.483Z,1562802343.483 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:45:43.483Z,1562802343.483 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:45:43.483Z,1562802343.483 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:45:43.484Z,1562802343.484 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:45:43.892Z,1562802343.892 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
0C2,000,00425,185,80,80,DF,FF
2019-07-10T23:45:43.892Z,1562802343.892 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:45:43.892Z,1562802343.892 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:45:43.894Z,1562802343.894 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:45:44.294Z,1562802344.294 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:45:47.121Z,1562802347.121 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,8000,00425,185,80,80,DF,FF
2019-07-10T23:45:47.122Z,1562802347.122 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,8000,00425,185,80,80,DF,FF
2019-07-10T23:45:47.122Z,1562802347.122 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-10T23:45:51.205Z,1562802351.205 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:45:51.567Z,1562802351.567 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:45:51.567Z,1562802351.567 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:45:51.568Z,1562802351.568 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:45:51.569Z,1562802351.569 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:45:51.971Z,1562802351.971 [DUSBL_Hydroid](INFO): USBL response received:,63,F4
!U1,P
,00425,185,80,80,DF,FF
2019-07-10T23:45:51.972Z,1562802351.972 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:45:51.972Z,1562802351.972 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:45:51.973Z,1562802351.973 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:45:52.373Z,1562802352.373 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:45:52.779Z,1562802352.779 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,0AF,000,00427,186,80,80,E5,FF
2019-07-10T23:45:52.780Z,1562802352.780 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:45:52.830Z,1562802352.830 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 26.280003 m
2019-07-10T23:46:00.896Z,1562802360.896 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:46:01.263Z,1562802361.263 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:46:01.264Z,1562802361.264 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:46:01.264Z,1562802361.264 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:46:01.265Z,1562802361.265 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:46:01.667Z,1562802361.667 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
0AF,000,00427,186,80,80,E5,FF
2019-07-10T23:46:01.667Z,1562802361.667 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:46:01.668Z,1562802361.668 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:46:01.669Z,1562802361.669 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:46:02.073Z,1562802362.073 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:46:02.475Z,1562802362.475 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,056,000,0034D,184,80,80,23,FF
2019-07-10T23:46:02.476Z,1562802362.476 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:46:02.514Z,1562802362.514 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 13.199997 m
2019-07-10T23:46:10.616Z,1562802370.616 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:46:10.961Z,1562802370.961 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:46:10.962Z,1562802370.962 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:46:10.963Z,1562802370.963 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:46:10.964Z,1562802370.964 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:46:11.367Z,1562802371.367 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
056,000,0034D,184,80,80,23,FF
2019-07-10T23:46:11.367Z,1562802371.367 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:46:11.367Z,1562802371.367 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:46:11.369Z,1562802371.369 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:46:11.769Z,1562802371.769 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:46:12.169Z,1562802372.169 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,04C,000,0034E,184,80,80,26,FF
2019-07-10T23:46:12.169Z,1562802372.169 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:46:12.207Z,1562802372.207 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 13.260000 m
2019-07-10T23:46:20.330Z,1562802380.330 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:46:20.655Z,1562802380.655 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:46:20.655Z,1562802380.655 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:46:20.655Z,1562802380.655 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:46:20.656Z,1562802380.656 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:46:21.059Z,1562802381.059 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
04C,000,0034E,184,80,80,26,FF
2019-07-10T23:46:21.059Z,1562802381.059 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:46:21.060Z,1562802381.060 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:46:21.061Z,1562802381.061 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:46:21.465Z,1562802381.465 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:46:21.872Z,1562802381.872 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,04C,000,0034D,184,80,80,28,FF
2019-07-10T23:46:21.872Z,1562802381.872 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-10T23:46:21.998Z,1562802381.998 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 13.199997 m
2019-07-10T23:46:29.998Z,1562802389.998 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-10T23:46:30.349Z,1562802390.349 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-10T23:46:30.349Z,1562802390.349 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-10T23:46:30.349Z,1562802390.349 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,20,00,00,5DC
2019-07-10T23:46:30.350Z,1562802390.350 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-10T23:46:30.759Z,1562802390.759 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
04C,000,0034D,184,80,80,28,FF
2019-07-10T23:46:30.759Z,1562802390.759 [DUSBL_Hydroid](INFO): Command Ack
2019-07-10T23:46:30.760Z,1562802390.760 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-10T23:46:30.761Z,1562802390.761 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-10T23:46:30.857Z,1562802390.857 [DUSBL](INFO): Timed out from 2019-07-10T23:36:30.4Z
2019-07-10T23:46:30.858Z,1562802390.858 [MissionManager](INFO): DUSBL is completed.
2019-07-10T23:46:30.858Z,1562802390.858 [MissionManager](INFO): Uninitializing Mission DUSBL
2019-07-10T23:46:30.858Z,1562802390.858 [DUSBL] Stopped
2019-07-10T23:46:30.858Z,1562802390.858 [DUSBL](DEBUG): Aggregate::uninitialize DUSBL
2019-07-10T23:46:30.858Z,1562802390.858 [DUSBL:A.Pitch] Stopped
2019-07-10T23:46:30.858Z,1562802390.858 [DUSBL:B.SetSpeed] Stopped
2019-07-10T23:46:30.858Z,1562802390.858 [DUSBL:B.SetSpeed](DEBUG): Uninitialize.
2019-07-10T23:46:30.858Z,1562802390.858 [DUSBL:C.] Stopped
2019-07-10T23:46:30.859Z,1562802390.859 [DUSBL:RequestRepeater] Stopped
2019-07-10T23:46:30.859Z,1562802390.859 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-07-10T23:46:30.859Z,1562802390.859 [DUSBL:RequestRepeater:A.Wait] Stopped
2019-07-10T23:46:30.859Z,1562802390.859 [DUSBL:RequestRepeater:A.Wait](DEBUG): Uninitialize Wait Component.
2019-07-10T23:46:31.161Z,1562802391.161 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-10T23:46:31.201Z,1562802391.201 [MissionManager](IMPORTANT): Started mission Default
2019-07-10T23:46:31.201Z,1562802391.201 [Default] Running Loop=1
2019-07-10T23:46:31.202Z,1562802391.202 [Default](DEBUG): Aggregate::initialize Default
2019-07-10T23:46:31.202Z,1562802391.202 [Default:B.GoToSurface] Running Loop=1
2019-07-10T23:46:31.202Z,1562802391.202 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-07-10T23:46:31.202Z,1562802391.202 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-07-10T23:46:31.203Z,1562802391.203 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-07-10T23:46:31.203Z,1562802391.203 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-07-10T23:46:31.203Z,1562802391.203 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-07-10T23:46:31.203Z,1562802391.203 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-07-10T23:46:31.204Z,1562802391.204 [Default:A.Wait] Running Loop=1
2019-07-10T23:46:31.204Z,1562802391.204 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-07-10T23:46:44.523Z,1562802404.523 [Default:A.Wait](INFO): Done Waiting.
2019-07-10T23:46:44.523Z,1562802404.523 [Default:A.Wait] Stopped
2019-07-10T23:46:44.523Z,1562802404.523 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-07-10T23:46:44.933Z,1562802404.933 [Default:CheckIn] Running Loop=1
2019-07-10T23:46:44.933Z,1562802404.933 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-10T23:46:44.933Z,1562802404.933 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-10T23:46:45.720Z,1562802405.720 [NAL9602](INFO): Powering up
2019-07-10T23:46:56.618Z,1562802416.618 [NAL9602](INFO): NAL9602 initialized
2019-07-10T23:46:57.438Z,1562802417.438 [NAL9602](DEBUG): Fix Requested
2019-07-10T23:47:28.528Z,1562802448.528 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2019-07-10T23:47:28.603Z,1562802448.603 [NAL9602](FAULT): received: 
+CSQ:0

OK07, 2, 0, 0, 0

OK
2019-07-10T23:47:28.603Z,1562802448.603 [NAL9602] Data Fault, FailCount= 1
2019-07-10T23:47:28.603Z,1562802448.603 [NAL9602](ERROR): Data Fault
2019-07-10T23:47:28.689Z,1562802448.689 [CBIT](ERROR): Data Fault in component: NAL9602
2019-07-10T23:47:28.936Z,1562802448.936 [NAL9602](INFO): Powering down
2019-07-10T23:47:29.826Z,1562802449.826 [CBIT](INFO): Clearing failed state for component NAL9602
2019-07-10T23:47:29.827Z,1562802449.827 [NAL9602] No Fault, FailCount= 1
2019-07-10T23:47:31.104Z,1562802451.104 [CBIT](INFO): Clearing failed state for component DropWeight
2019-07-10T23:47:31.104Z,1562802451.104 [DropWeight] No Fault, FailCount= 1
2019-07-10T23:47:59.237Z,1562802479.237 [NAL9602](INFO): Powering up NAL9602
2019-07-10T23:48:10.148Z,1562802490.148 [NAL9602](INFO): NAL9602 initialized
2019-07-10T23:48:10.970Z,1562802490.970 [NAL9602](DEBUG): Fix Requested
2019-07-10T23:48:31.000Z,1562802512.000 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-07-10T23:48:31.000Z,1562802512.000 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.92,  95.42,   0.00,  98.92
2019-07-10T23:50:10.961Z,1562802610.961 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-07-10T23:50:10.961Z,1562802610.961 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19071016590378,35.0, -0.1,   0.0, 0
2019-07-10T23:51:45.096Z,1562802705.096 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-07-10T23:46:44.9Z
2019-07-10T23:51:45.096Z,1562802705.096 [Default:CheckIn:Read_GPS] Stopped
2019-07-10T23:51:45.096Z,1562802705.096 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-07-10T23:51:45.522Z,1562802705.522 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-07-10T23:51:45.890Z,1562802705.890 [Micromodem](ERROR): Buffer fill timout failure.
2019-07-10T23:51:47.411Z,1562802707.411 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 8475265D040001005D00000400270300000000000000151D80030C5669BF3148E2FA22188B6A3173E6960F887FF8A99E669AC91D474B0DB47F940EC581E0D567
2019-07-10T23:51:47.412Z,1562802707.412 [Micromodem](INFO): Outgoing frame #2, 64 bytes: 266CC0E6F1A517638CB0C0B8BE3619E02B8F4A169EC22D30546A87042BC836DF836B942BC3C4A9EFC1B6C4BDD91F97B2782B4B03CB56EA92D77C6909485B9FDE
2019-07-10T23:51:47.412Z,1562802707.412 [Micromodem](INFO): Outgoing frame #3, 64 bytes: C9BA76D6E11C7243AB9F1CB76D4E14F7CBE1C6B0B40F2F0A19DC25B10B3C6279A2D80F52F03F1275F5BE973EA20943C61E76D185D02D3A531DAE1C60CC9420D9
2019-07-10T23:51:47.412Z,1562802707.412 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,3*53

2019-07-10T23:51:47.598Z,1562802707.598 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,3*51

2019-07-10T23:51:47.928Z,1562802707.928 [Micromodem](INFO): Nmea in: $CADRQ,235148,1,9,0,64,1*47

2019-07-10T23:51:47.929Z,1562802707.929 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,8475265D040001005D00000400270300000000000000151D80030C5669BF3148E2FA22188B6A3173E6960F887FF8A99E669AC91D474B0DB47F940EC581E0D567*73

2019-07-10T23:51:48.332Z,1562802708.332 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71

2019-07-10T23:51:48.736Z,1562802708.736 [Micromodem](INFO): Nmea in: $CADRQ,235148,1,9,0,64,2*44

2019-07-10T23:51:48.737Z,1562802708.737 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,266CC0E6F1A517638CB0C0B8BE3619E02B8F4A169EC22D30546A87042BC836DF836B942BC3C4A9EFC1B6C4BDD91F97B2782B4B03CB56EA92D77C6909485B9FDE*74

2019-07-10T23:51:49.140Z,1562802709.140 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71

2019-07-10T23:51:49.547Z,1562802709.547 [Micromodem](INFO): Nmea in: $CADRQ,235149,1,9,0,64,3*44

2019-07-10T23:51:49.548Z,1562802709.548 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,C9BA76D6E11C7243AB9F1CB76D4E14F7CBE1C6B0B40F2F0A19DC25B10B3C6279A2D80F52F03F1275F5BE973EA20943C61E76D185D02D3A531DAE1C60CC9420D9*03

2019-07-10T23:51:49.948Z,1562802709.948 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71

2019-07-10T23:51:50.352Z,1562802710.352 [Micromodem](INFO): Nmea in: $CATXP,192*48

2019-07-10T23:51:53.147Z,1562802713.147 [DataOverHttps](INFO): Sending 302 bytes from file Logs/20190710T233220/Courier0004.lzma
2019-07-10T23:51:53.953Z,1562802713.953 [DataOverHttps](INFO): Moved sent file to Logs/20190710T233220/Courier0004.lzma.bak
2019-07-10T23:51:53.953Z,1562802713.953 [DataOverHttps](INFO): SBD MOMSN=11438426
2019-07-10T23:51:54.013Z,1562802714.013 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2019-07-10T23:51:54.393Z,1562802714.393 [Micromodem](INFO): Nmea in: $CAXST,6,20190710,235150.377440,3,0,200,4000,10000,1,1,9,1,3,3,3,192*4F

2019-07-10T23:52:00.452Z,1562802720.452 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-07-10T23:52:07.436Z,1562802727.436 [DataOverHttps](INFO): Sending 595 bytes from file Logs/20190710T233220/Express0005.lzma
2019-07-10T23:52:08.248Z,1562802728.248 [DataOverHttps](INFO): Moved sent file to Logs/20190710T233220/Express0005.lzma.bak
2019-07-10T23:52:08.248Z,1562802728.248 [DataOverHttps](INFO): SBD MOMSN=11438429
2019-07-10T23:52:10.989Z,1562802730.989 [Default:CheckIn:Read_Iridium] Stopped
2019-07-10T23:52:10.989Z,1562802730.989 [Default:CheckIn:C.Wait] Running Loop=1
2019-07-10T23:52:10.989Z,1562802730.989 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-07-10T23:52:31.160Z,1562802751.160 [NAL9602](INFO): Powering down
2019-07-10T23:53:51.980Z,1562802831.980 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-07-10T23:53:51.981Z,1562802831.981 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  93.93,  95.42,  93.03,  BI,    -2,    +0,    +0,-32768,A
2019-07-10T23:54:31.976Z,1562802871.976 [RDI_Pathfinder](ERROR): Failed to parse:
:BI,    -3,   +18,    +2,-32768,A