2019-07-11T18:21:20.817Z,1562869280.817 [Supervisor](DEBUG): Initializing supervisor.
2019-07-11T18:21:20.820Z,1562869280.820 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-07-11T18:21:20.820Z,1562869280.820 [SyncHandler](INFO): Protected caller Thread ID is 2870
2019-07-11T18:21:20.821Z,1562869280.821 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-07-11T18:21:20.822Z,1562869280.822 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-07-11T18:21:20.822Z,1562869280.822 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 2871
2019-07-11T18:21:20.825Z,1562869280.825 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-07-11T18:21:20.837Z,1562869280.837 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-07-11T18:21:20.838Z,1562869280.838 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-07-11T18:21:20.839Z,1562869280.839 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 2872
2019-07-11T18:21:20.839Z,1562869280.839 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-07-11T18:21:20.840Z,1562869280.840 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-07-11T18:21:20.841Z,1562869280.841 [logger ThreadHandler](INFO): Protected caller Thread ID is 2873
2019-07-11T18:21:20.843Z,1562869280.843 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-07-11T18:21:20.843Z,1562869280.843 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-07-11T18:21:20.845Z,1562869280.845 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-07-11T18:21:21.255Z,1562869281.255 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-07-11T18:21:21.256Z,1562869281.256 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-07-11T18:21:21.353Z,1562869281.353 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-07-11T18:21:21.354Z,1562869281.354 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-07-11T18:21:21.661Z,1562869281.661 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-07-11T18:21:21.662Z,1562869281.662 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-07-11T18:21:21.800Z,1562869281.800 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-07-11T18:21:21.800Z,1562869281.800 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-07-11T18:21:21.993Z,1562869281.993 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-07-11T18:21:21.993Z,1562869281.993 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-07-11T18:21:22.431Z,1562869282.431 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-07-11T18:21:22.432Z,1562869282.432 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-07-11T18:21:22.656Z,1562869282.656 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-07-11T18:21:22.657Z,1562869282.657 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-07-11T18:21:22.799Z,1562869282.799 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-07-11T18:21:22.800Z,1562869282.800 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-07-11T18:21:22.988Z,1562869282.988 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-07-11T18:21:22.989Z,1562869282.989 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-07-11T18:21:23.084Z,1562869283.084 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-07-11T18:21:23.085Z,1562869283.085 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-07-11T18:21:23.377Z,1562869283.377 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-07-11T18:21:23.378Z,1562869283.378 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-07-11T18:21:23.458Z,1562869283.458 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-07-11T18:21:23.559Z,1562869283.559 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-07-11T18:21:23.560Z,1562869283.560 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-07-11T18:21:24.136Z,1562869284.136 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-07-11T18:21:24.137Z,1562869284.137 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-07-11T18:21:24.567Z,1562869284.567 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-07-11T18:21:24.569Z,1562869284.569 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2019-07-11T18:21:24.570Z,1562869284.570 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2019-07-11T18:21:25.101Z,1562869285.101 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2019-07-11T18:21:25.297Z,1562869285.297 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2019-07-11T18:21:25.530Z,1562869285.530 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2019-07-11T18:21:25.765Z,1562869285.765 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-07-11T18:21:25.766Z,1562869285.766 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2019-07-11T18:21:25.851Z,1562869285.851 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2019-07-11T18:21:25.946Z,1562869285.946 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2019-07-11T18:21:26.042Z,1562869286.042 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2019-07-11T18:21:26.129Z,1562869286.129 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2019-07-11T18:21:26.236Z,1562869286.236 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2019-07-11T18:21:26.410Z,1562869286.410 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2019-07-11T18:21:26.539Z,1562869286.539 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs//
2019-07-11T18:21:26.539Z,1562869286.539 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs//vehicle.cfg
2019-07-11T18:21:26.723Z,1562869286.723 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs//Control.cfg
2019-07-11T18:21:26.821Z,1562869286.821 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs//BIT.cfg
2019-07-11T18:21:26.920Z,1562869286.920 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs//Battery.cfg
2019-07-11T18:21:27.064Z,1562869287.064 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs//Simulator.cfg
2019-07-11T18:21:27.149Z,1562869287.149 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs//Navigation.cfg
2019-07-11T18:21:27.244Z,1562869287.244 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs//logger.cfg
2019-07-11T18:21:27.341Z,1562869287.341 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs//secure.cfg
2019-07-11T18:21:27.424Z,1562869287.424 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs//Servo.cfg
2019-07-11T18:21:27.533Z,1562869287.533 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs//Sensor.cfg
2019-07-11T18:21:27.719Z,1562869287.719 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs//Science.cfg
2019-07-11T18:21:27.852Z,1562869287.852 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-07-11T18:21:27.853Z,1562869287.853 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-07-11T18:21:28.257Z,1562869288.257 [AHRS_M2] Loaded
2019-07-11T18:21:28.257Z,1562869288.257 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-07-11T18:21:28.331Z,1562869288.331 [DataOverHttps] Loaded
2019-07-11T18:21:28.331Z,1562869288.331 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-07-11T18:21:28.332Z,1562869288.332 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 407654E0
2019-07-11T18:21:28.333Z,1562869288.333 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 2985
2019-07-11T18:21:28.346Z,1562869288.346 [Depth_Keller] Loaded
2019-07-11T18:21:28.346Z,1562869288.346 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-07-11T18:21:28.351Z,1562869288.351 [DropWeight] Loaded
2019-07-11T18:21:28.351Z,1562869288.351 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2019-07-11T18:21:28.409Z,1562869288.409 [DUSBL_Hydroid] Loaded
2019-07-11T18:21:28.409Z,1562869288.409 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2019-07-11T18:21:28.458Z,1562869288.458 [Micromodem] Loaded
2019-07-11T18:21:28.458Z,1562869288.458 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2019-07-11T18:21:28.556Z,1562869288.556 [NAL9602] Loaded
2019-07-11T18:21:28.556Z,1562869288.556 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-07-11T18:21:28.572Z,1562869288.572 [Onboard] Loaded
2019-07-11T18:21:28.573Z,1562869288.573 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-07-11T18:21:28.578Z,1562869288.578 [PowerOnly] Loaded
2019-07-11T18:21:28.579Z,1562869288.579 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2019-07-11T18:21:28.585Z,1562869288.585 [Radio_Surface] Loaded
2019-07-11T18:21:28.585Z,1562869288.585 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-07-11T18:21:28.587Z,1562869288.587 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 407954E0
2019-07-11T18:21:28.587Z,1562869288.587 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 2986
2019-07-11T18:21:28.631Z,1562869288.631 [RDI_Pathfinder] Loaded
2019-07-11T18:21:28.631Z,1562869288.631 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-07-11T18:21:30.102Z,1562869290.102 [BPC1] Loaded
2019-07-11T18:21:30.102Z,1562869290.102 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-07-11T18:21:30.103Z,1562869290.103 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-07-11T18:21:30.103Z,1562869290.103 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-07-11T18:21:30.163Z,1562869290.163 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-07-11T18:21:30.164Z,1562869290.164 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-07-11T18:21:30.402Z,1562869290.402 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-07-11T18:21:30.402Z,1562869290.402 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-07-11T18:21:30.486Z,1562869290.486 [NavChart] Loaded
2019-07-11T18:21:30.486Z,1562869290.486 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-07-11T18:21:30.490Z,1562869290.490 [UniversalFixResidualReporter] Loaded
2019-07-11T18:21:30.491Z,1562869290.491 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-07-11T18:21:30.491Z,1562869290.491 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-07-11T18:21:30.492Z,1562869290.492 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-07-11T18:21:30.694Z,1562869290.694 [BuoyancyServo] Loaded
2019-07-11T18:21:30.695Z,1562869290.695 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-07-11T18:21:30.710Z,1562869290.710 [ElevatorServo] Loaded
2019-07-11T18:21:30.710Z,1562869290.710 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-07-11T18:21:30.725Z,1562869290.725 [MassServo] Loaded
2019-07-11T18:21:30.725Z,1562869290.725 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-07-11T18:21:30.741Z,1562869290.741 [RudderServo] Loaded
2019-07-11T18:21:30.741Z,1562869290.741 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-07-11T18:21:30.756Z,1562869290.756 [ThrusterServo] Loaded
2019-07-11T18:21:30.756Z,1562869290.756 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-07-11T18:21:30.757Z,1562869290.757 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-07-11T18:21:30.757Z,1562869290.757 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-07-11T18:21:31.012Z,1562869291.012 [CTD_NeilBrown] Loaded
2019-07-11T18:21:31.013Z,1562869291.013 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2019-07-11T18:21:31.014Z,1562869291.014 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 408E04E0
2019-07-11T18:21:31.014Z,1562869291.014 [CTD_NeilBrown ThreadHandler](INFO): Protected caller Thread ID is 2987
2019-07-11T18:21:31.059Z,1562869291.059 [WetLabsSeaOWL_UV_A] Loaded
2019-07-11T18:21:31.059Z,1562869291.059 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2019-07-11T18:21:31.060Z,1562869291.060 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 409104E0
2019-07-11T18:21:31.061Z,1562869291.061 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 2988
2019-07-11T18:21:31.061Z,1562869291.061 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-07-11T18:21:31.062Z,1562869291.062 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-07-11T18:21:31.354Z,1562869291.354 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-07-11T18:21:31.355Z,1562869291.355 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-07-11T18:21:31.397Z,1562869291.397 [DepthRateCalculator] Loaded
2019-07-11T18:21:31.397Z,1562869291.397 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-07-11T18:21:31.403Z,1562869291.403 [PitchRateCalculator] Loaded
2019-07-11T18:21:31.404Z,1562869291.404 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-07-11T18:21:31.416Z,1562869291.416 [SpeedCalculator] Loaded
2019-07-11T18:21:31.417Z,1562869291.417 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-07-11T18:21:31.439Z,1562869291.439 [TempGradientCalculator] Loaded
2019-07-11T18:21:31.439Z,1562869291.439 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-07-11T18:21:31.445Z,1562869291.445 [YawRateCalculator] Loaded
2019-07-11T18:21:31.445Z,1562869291.445 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-07-11T18:21:31.486Z,1562869291.486 [ElevatorOffsetCalculator] Loaded
2019-07-11T18:21:31.486Z,1562869291.486 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-07-11T18:21:31.487Z,1562869291.487 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-07-11T18:21:31.487Z,1562869291.487 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-07-11T18:21:31.623Z,1562869291.623 [SBIT](DEBUG): Construct Startup Built In Test.
2019-07-11T18:21:31.645Z,1562869291.645 [SBIT] Loaded
2019-07-11T18:21:31.646Z,1562869291.646 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-07-11T18:21:31.647Z,1562869291.647 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-07-11T18:21:31.658Z,1562869291.658 [IBIT] Loaded
2019-07-11T18:21:31.658Z,1562869291.658 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-07-11T18:21:31.661Z,1562869291.661 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-07-11T18:21:31.805Z,1562869291.805 [CBIT] Loaded
2019-07-11T18:21:31.805Z,1562869291.805 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-07-11T18:21:31.805Z,1562869291.805 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-07-11T18:21:31.806Z,1562869291.806 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-07-11T18:21:31.890Z,1562869291.890 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-07-11T18:21:31.891Z,1562869291.891 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-07-11T18:21:31.990Z,1562869291.990 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-07-11T18:21:31.991Z,1562869291.991 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-07-11T18:21:32.059Z,1562869292.059 [VerticalControl](DEBUG): Construct VerticalControl.
2019-07-11T18:21:32.145Z,1562869292.145 [VerticalControl] Loaded
2019-07-11T18:21:32.145Z,1562869292.145 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-07-11T18:21:32.146Z,1562869292.146 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-07-11T18:21:32.203Z,1562869292.203 [HorizontalControl] Loaded
2019-07-11T18:21:32.203Z,1562869292.203 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-07-11T18:21:32.204Z,1562869292.204 [SpeedControl](DEBUG): Construct SpeedControl.
2019-07-11T18:21:32.206Z,1562869292.206 [SpeedControl] Loaded
2019-07-11T18:21:32.206Z,1562869292.206 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-07-11T18:21:32.207Z,1562869292.207 [LoopControl](DEBUG): Construct LoopControl.
2019-07-11T18:21:32.207Z,1562869292.207 [LoopControl] Loaded
2019-07-11T18:21:32.208Z,1562869292.208 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-07-11T18:21:32.208Z,1562869292.208 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-07-11T18:21:32.209Z,1562869292.209 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-07-11T18:21:32.242Z,1562869292.242 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-07-11T18:21:32.245Z,1562869292.245 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-07-11T18:21:32.246Z,1562869292.246 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-07-11T18:21:32.253Z,1562869292.253 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-07-11T18:21:32.255Z,1562869292.255 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AEC4E0
2019-07-11T18:21:32.255Z,1562869292.255 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 2989
2019-07-11T18:21:32.260Z,1562869292.260 [Supervisor](INFO): Main Thread ID is 2484
2019-07-11T18:21:32.260Z,1562869292.260 [Supervisor](DEBUG): Running supervisor.
2019-07-11T18:21:32.260Z,1562869292.260 [CommandLine ThreadHandler](INFO): Handler Thread ID is 2990
2019-07-11T18:21:32.263Z,1562869292.263 [controlThread ThreadHandler](INFO): Handler Thread ID is 2991
2019-07-11T18:21:32.263Z,1562869292.263 [controlThread](DEBUG): Initializing ControlThread
2019-07-11T18:21:32.270Z,1562869292.270 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-07-11T18:21:32.270Z,1562869292.270 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-07-11T18:21:32.271Z,1562869292.271 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-07-11T18:21:32.272Z,1562869292.272 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-07-11T18:21:32.272Z,1562869292.272 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-07-11T18:21:32.272Z,1562869292.272 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-07-11T18:21:32.273Z,1562869292.273 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-07-11T18:21:32.273Z,1562869292.273 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-07-11T18:21:32.273Z,1562869292.273 [SBIT](INFO): Initialize SBIT Component.
2019-07-11T18:21:32.274Z,1562869292.274 [SBIT](IMPORTANT): git: 2019-07-01-2-g8df750c
2019-07-11T18:21:32.274Z,1562869292.274 [SBIT](INFO): git hash: 8df750cfd7ab665d8785a0cb54650bf6fbbccb32
2019-07-11T18:21:32.275Z,1562869292.275 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-07-11T18:21:32.276Z,1562869292.276 [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-11T18:21:32.277Z,1562869292.277 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-07-11T18:21:32.277Z,1562869292.277 [IBIT](INFO): Initialize IBIT Component.
2019-07-11T18:21:32.279Z,1562869292.279 [CBIT](DEBUG): Initialize CBIT Component.
2019-07-11T18:21:32.279Z,1562869292.279 [logger ThreadHandler](INFO): Handler Thread ID is 2992
2019-07-11T18:21:32.290Z,1562869292.290 [CBIT](DEBUG): Initialized mux pins.
2019-07-11T18:21:32.290Z,1562869292.290 [CBIT](DEBUG): Initializing the watchdog timer.
2019-07-11T18:21:32.299Z,1562869292.299 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 2993
2019-07-11T18:21:32.300Z,1562869292.300 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-07-11T18:21:32.311Z,1562869292.311 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 2994
2019-07-11T18:21:32.314Z,1562869292.314 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-07-11T18:21:32.314Z,1562869292.314 [CBIT](DEBUG): Initializing heartbeat.
2019-07-11T18:21:32.323Z,1562869292.323 [CTD_NeilBrown ThreadHandler](INFO): Handler Thread ID is 2995
2019-07-11T18:21:32.324Z,1562869292.324 [CTD_NeilBrown](INFO): Powering down
2019-07-11T18:21:32.363Z,1562869292.363 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 2996
2019-07-11T18:21:32.364Z,1562869292.364 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-07-11T18:21:32.365Z,1562869292.365 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 2997
2019-07-11T18:21:32.369Z,1562869292.369 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-07-11T18:21:32.369Z,1562869292.369 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-07-11T18:21:32.369Z,1562869292.369 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-07-11T18:21:32.369Z,1562869292.369 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-07-11T18:21:32.369Z,1562869292.369 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-07-11T18:21:32.369Z,1562869292.369 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-07-11T18:21:32.370Z,1562869292.370 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-07-11T18:21:32.370Z,1562869292.370 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-07-11T18:21:32.370Z,1562869292.370 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-07-11T18:21:32.370Z,1562869292.370 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-07-11T18:21:32.371Z,1562869292.371 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-07-11T18:21:32.371Z,1562869292.371 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-07-11T18:21:32.371Z,1562869292.371 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-07-11T18:21:32.371Z,1562869292.371 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-07-11T18:21:32.371Z,1562869292.371 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-07-11T18:21:32.371Z,1562869292.371 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-07-11T18:21:32.386Z,1562869292.386 [CBIT](DEBUG): Deactivating GF circuits.
2019-07-11T18:21:32.386Z,1562869292.386 [CBIT](DEBUG): Deactivating emergency mode.
2019-07-11T18:21:32.422Z,1562869292.422 [CBIT](DEBUG): Backplane powered.
2019-07-11T18:21:32.423Z,1562869292.423 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-07-11T18:21:32.424Z,1562869292.424 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-07-11T18:21:32.425Z,1562869292.425 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-07-11T18:21:32.426Z,1562869292.426 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-07-11T18:21:32.427Z,1562869292.427 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-07-11T18:21:32.438Z,1562869292.438 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-07-11T18:21:32.465Z,1562869292.465 [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-11T18:21:32.466Z,1562869292.466 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-07-11T18:21:32.539Z,1562869292.539 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-07-11T18:21:32.540Z,1562869292.540 [Default:A.Wait](DEBUG): Construct Wait.
2019-07-11T18:21:32.542Z,1562869292.542 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-07-11T18:21:32.569Z,1562869292.569 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-07-11T18:21:32.596Z,1562869292.596 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-07-11T18:21:32.601Z,1562869292.601 [Default:E.Execute](DEBUG): Construct Execute.
2019-07-11T18:21:32.631Z,1562869292.631 [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-11T18:21:32.642Z,1562869292.642 [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-11T18:21:32.648Z,1562869292.648 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-07-11T18:21:32.684Z,1562869292.684 [Depth_Keller](ERROR): Pressure reading out of range: 1913.424561 decibar
2019-07-11T18:21:32.684Z,1562869292.684 [DUSBL_Hydroid](INFO): Powering up
2019-07-11T18:21:32.685Z,1562869292.685 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-07-11T18:21:32.715Z,1562869292.715 [Radio_Surface](INFO): Powering up
2019-07-11T18:21:32.751Z,1562869292.751 [DepthRateCalculator](ERROR): Depth measurement is not active
2019-07-11T18:21:32.782Z,1562869292.782 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-07-11T18:21:32.791Z,1562869292.791 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-07-11T18:21:32.792Z,1562869292.792 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-07-11T18:21:32.799Z,1562869292.799 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-07-11T18:21:32.800Z,1562869292.800 [MassServo](DEBUG): Initializing EZServoServo.
2019-07-11T18:21:32.810Z,1562869292.810 [MassServo](DEBUG): Initializing MassServo.
2019-07-11T18:21:32.811Z,1562869292.811 [RudderServo](DEBUG): Initializing EZServoServo.
2019-07-11T18:21:32.819Z,1562869292.819 [RudderServo](DEBUG): Initializing RudderServo.
2019-07-11T18:21:32.819Z,1562869292.819 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-07-11T18:21:32.827Z,1562869292.827 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-07-11T18:21:33.072Z,1562869293.072 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2019-07-11T18:21:33.072Z,1562869293.072 [DropWeight] Hardware Fault, FailCount= 1
2019-07-11T18:21:33.073Z,1562869293.073 [DropWeight](ERROR): Hardware Fault
2019-07-11T18:21:33.074Z,1562869293.074 [Micromodem](INFO): Powering up
2019-07-11T18:21:33.075Z,1562869293.075 [Micromodem](DEBUG): Initializing Micromodem.
2019-07-11T18:21:33.119Z,1562869293.119 [CommandLine](FAULT): Scheduling is paused
2019-07-11T18:21:33.120Z,1562869293.120 [CBIT](INFO): Critical error at 20190711T182133
2019-07-11T18:21:33.120Z,1562869293.120 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2019-07-11T18:21:33.123Z,1562869293.123 [CBIT](ERROR): Hardware Fault in component: DropWeight
2019-07-11T18:21:33.123Z,1562869293.123 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2019-07-11T18:21:33.768Z,1562869293.768 [CBIT](INFO): Critical error at 20190711T182133
2019-07-11T18:21:37.942Z,1562869297.942 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2019-07-11T18:21:38.345Z,1562869298.345 [Micromodem](INFO): Nmea in: $CATMG,2019-07-11T18:21:38Z,RTC,RTC*72

2019-07-11T18:21:38.345Z,1562869298.345 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2019-07-11T18:21:38Z,RTC,RTC*72

2019-07-11T18:21:38.765Z,1562869298.765 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2019-07-11T18:21:38.765Z,1562869298.765 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2019-07-11T18:21:39.176Z,1562869299.176 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2019-07-11T18:21:39.176Z,1562869299.176 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-07-11T18:21:39.581Z,1562869299.581 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2019-07-11T18:21:39.581Z,1562869299.581 [Micromodem](INFO): Nmea out: $CCCFG,BND,1*3B

2019-07-11T18:21:39.984Z,1562869299.984 [Micromodem](INFO): Nmea in: $CACFG,BND,1*39

2019-07-11T18:21:39.985Z,1562869299.985 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2019-07-11T18:21:40.388Z,1562869300.388 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2019-07-11T18:21:40.389Z,1562869300.389 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2019-07-11T18:21:40.777Z,1562869300.777 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2019-07-11T18:21:40.778Z,1562869300.778 [Micromodem](INFO): Nmea out: $CCCLK,2019,07,11,18,21,40*47

2019-07-11T18:21:41.189Z,1562869301.189 [Micromodem](INFO): Nmea in: $CACLK,2019,7,11,18,21,40*75

2019-07-11T18:21:41.578Z,1562869301.578 [Micromodem](INFO): Nmea in: $CATMS,0,2019-07-11T18:21:41Z*74

2019-07-11T18:21:41.579Z,1562869301.579 [Micromodem](INFO): Nmea in: $CATMG,2019-07-11T18:21:41Z,USER_CMD,RTC*3D

2019-07-11T18:21:46.035Z,1562869306.035 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-07-11T18:21:50.860Z,1562869310.860 [DUSBL_Hydroid](INFO): DUSBL Version:O
2019-07-11T18:21:50.915Z,1562869310.915 [RDI_Pathfinder](ERROR): Failed to parse:
23,   +13,    +9,A
2019-07-11T18:21:58.937Z,1562869318.937 [NAL9602](INFO): Powering up NAL9602
2019-07-11T18:22:01.004Z,1562869321.004 [SBIT](IMPORTANT): Beginning Startup BIT
2019-07-11T18:22:01.012Z,1562869321.012 [CBIT](IMPORTANT): Beginning ground fault scan
2019-07-11T18:22:09.964Z,1562869329.964 [NAL9602](INFO): NAL9602 initialized
2019-07-11T18:22:10.798Z,1562869330.798 [NAL9602](DEBUG): Fix Requested
2019-07-11T18:22:12.107Z,1562869332.107 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.023199
CHAN A1 (24V): -0.026831
CHAN A2 (12V): -0.006665
CHAN A3 (5V): -0.002263
CHAN B0 (3.3V): 0.000155
CHAN B1 (3.15aV): 0.000234
CHAN B2 (3.15bV): 0.000236
CHAN B3 (GND): 0.001822
OPEN: 0.007083
Full Scale Calc: 4.765 mA, -1.589 mA
2019-07-11T18:22:29.512Z,1562869349.512 [CommandLine](IMPORTANT): got command failComponent
2019-07-11T18:22:29.512Z,1562869349.512 [CommandLine](IMPORTANT): Failed components:
2019-07-11T18:22:29.512Z,1562869349.512 [CommandLine](IMPORTANT): DropWeight: Hardware Fault
2019-07-11T18:22:50.298Z,1562869370.298 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-07-11T18:22:50.298Z,1562869370.298 [RDI_Pathfinder](ERROR): Failed to parse:
:RA, 93.43,  94.92,  93.43,   0.00
2019-07-11T18:22:53.909Z,1562869373.909 [CommandLine](IMPORTANT): got command configSet DUSBL_Hydroid.verbosity 3.000000 count
2019-07-11T18:22:54.340Z,1562869374.340 [SBIT](IMPORTANT): SBIT PASSED
2019-07-11T18:22:54.415Z,1562869374.415 [CommandLine](IMPORTANT): got command configSet list
2019-07-11T18:22:54.415Z,1562869374.415 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-07-11T18:22:54.416Z,1562869374.416 [CommandLine](IMPORTANT): No configSet variables persisted
2019-07-11T18:22:54.735Z,1562869374.735 [MissionManager](IMPORTANT): Started mission Startup
2019-07-11T18:22:54.736Z,1562869374.736 [Startup] Running Loop=1
2019-07-11T18:22:54.736Z,1562869374.736 [Startup](DEBUG): Aggregate::initialize Startup
2019-07-11T18:22:54.736Z,1562869374.736 [Startup:A.GoToSurface] Running Loop=1
2019-07-11T18:22:54.736Z,1562869374.736 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-07-11T18:22:54.737Z,1562869374.737 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-07-11T18:22:54.737Z,1562869374.737 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-07-11T18:22:54.737Z,1562869374.737 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-07-11T18:22:54.738Z,1562869374.738 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-07-11T18:22:54.738Z,1562869374.738 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-07-11T18:22:54.740Z,1562869374.740 [Startup:StartupSatComms] Running Loop=1
2019-07-11T18:22:54.740Z,1562869374.740 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-07-11T18:22:54.740Z,1562869374.740 [Startup:StartupSatComms:A] Running Loop=1
2019-07-11T18:22:55.144Z,1562869375.144 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-07-11T18:23:20.219Z,1562869400.219 [CommandLine](IMPORTANT): got command configSet DUSBL_Hydroid.detectionThreshold 25.000000 count
2019-07-11T18:23:28.653Z,1562869408.653 [NAL9602](INFO): SBD MO Status=2, MOMSN=8119, MT Status=2, MTMSN=0
2019-07-11T18:23:28.654Z,1562869408.654 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-07-11T18:23:44.002Z,1562869424.002 [NAL9602](INFO): SBD MO Status=2, MOMSN=8119, MT Status=2, MTMSN=0
2019-07-11T18:23:44.003Z,1562869424.003 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-07-11T18:23:52.116Z,1562869432.116 [RDI_Pathfinder](ERROR): Failed to parse:
:SA,   +0.00,   +0.00,  0.00
2019-07-11T18:23:54.969Z,1562869434.969 [Startup:StartupSatComms:A](INFO): Timed out from 2019-07-11T18:22:54.7Z
2019-07-11T18:23:54.969Z,1562869434.969 [Startup:StartupSatComms:A] Stopped
2019-07-11T18:23:54.969Z,1562869434.969 [Startup:StartupSatComms:B] Running Loop=1
2019-07-11T18:23:55.344Z,1562869435.344 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-07-11T18:23:57.524Z,1562869437.524 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 207E275D000000005D00000400900000000000000000151D80030C5669BF31555EAE1409A708CE2489B0D24F922C4E73F3619C1A3D5DFEC97D4617D39DECC9F9
2019-07-11T18:23:57.524Z,1562869437.524 [Micromodem](INFO): Outgoing frame #2, 64 bytes: D5208A2D3747F6DFCFC92B43B7871EEBC9D0AD4DFB1DF0EEEBFF1AFD0E2B7A7A22F88AE563FB62FA2B0555EC87BCDAC154CAFC30FDF20B84BD211B6993A3FC4C
2019-07-11T18:23:57.525Z,1562869437.525 [Micromodem](INFO): Outgoing frame #3, 11 bytes: 459BD999C787E1A04A8000
2019-07-11T18:23:57.525Z,1562869437.525 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,3*53

2019-07-11T18:23:59.580Z,1562869439.580 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.004876
2019-07-11T18:24:05.930Z,1562869445.930 [DataOverHttps](INFO): Sending 131 bytes from file Logs/20190711T182120/Courier0000.lzma
2019-07-11T18:24:06.736Z,1562869446.736 [DataOverHttps](INFO): Moved sent file to Logs/20190711T182120/Courier0000.lzma.bak
2019-07-11T18:24:06.736Z,1562869446.736 [DataOverHttps](INFO): SBD MOMSN=11439308
2019-07-11T18:24:08.588Z,1562869448.588 [Micromodem](ERROR): Nmea resend: $CCCYC,0,1,9,1,0,3*53

2019-07-11T18:24:17.193Z,1562869457.193 [Micromodem](INFO): Nmea in: ,A$CAREV,182416,AUV,2.0.27690*1D

2019-07-11T18:24:17.193Z,1562869457.193 [Micromodem](ERROR): Response from modem unexpected: ,A$CAREV,182416,AUV,2.0.27690*1D

2019-07-11T18:24:19.608Z,1562869459.608 [Micromodem](ERROR): Nmea resend: $CCCYC,0,1,9,1,0,3*53

2019-07-11T18:24:20.020Z,1562869460.020 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,3*51

2019-07-11T18:24:20.420Z,1562869460.420 [Micromodem](INFO): Nmea in: $CADRQ,182419,1,9,0,64,1*49

2019-07-11T18:24:20.421Z,1562869460.421 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,207E275D000000005D00000400900000000000000000151D80030C5669BF31555EAE1409A708CE2489B0D24F922C4E73F3619C1A3D5DFEC97D4617D39DECC9F9*0D

2019-07-11T18:24:20.821Z,1562869460.821 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71

2019-07-11T18:24:21.232Z,1562869461.232 [Micromodem](INFO): Nmea in: $CADRQ,182420,1,9,0,64,2*40

2019-07-11T18:24:21.233Z,1562869461.233 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,D5208A2D3747F6DFCFC92B43B7871EEBC9D0AD4DFB1DF0EEEBFF1AFD0E2B7A7A22F88AE563FB62FA2B0555EC87BCDAC154CAFC30FDF20B84BD211B6993A3FC4C*74

2019-07-11T18:24:21.632Z,1562869461.632 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71

2019-07-11T18:24:22.033Z,1562869462.033 [Micromodem](INFO): Nmea in: $CADRQ,182420,1,9,0,64,3*41

2019-07-11T18:24:22.033Z,1562869462.033 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,459BD999C787E1A04A8000*75

2019-07-11T18:24:22.142Z,1562869462.142 [DataOverHttps](INFO): Sending 244 bytes from file Logs/20190711T171402/Express0019.lzma
2019-07-11T18:24:22.440Z,1562869462.440 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,11*73

2019-07-11T18:24:22.844Z,1562869462.844 [Micromodem](INFO): Nmea in: $CATXP,139*49

2019-07-11T18:24:22.948Z,1562869462.948 [DataOverHttps](INFO): Moved sent file to Logs/20190711T171402/Express0019.lzma.bak
2019-07-11T18:24:22.948Z,1562869462.948 [DataOverHttps](INFO): SBD MOMSN=11439313
2019-07-11T18:24:25.669Z,1562869465.669 [Micromodem](INFO): Nmea in: $CATXF,139*5F

2019-07-11T18:24:26.081Z,1562869466.081 [Micromodem](INFO): Nmea in: $CAXST,6,20190711,182421.777263,3,0,200,4000,9760,1,1,9,1,3,3,3,139*79

2019-07-11T18:24:32.566Z,1562869472.566 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-07-11T18:24:32.566Z,1562869472.566 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-07-11T18:24:32.588Z,1562869472.588 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-07-11T18:24:33.007Z,1562869473.007 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-07-11T18:24:33.007Z,1562869473.007 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-07-11T18:24:35.999Z,1562869475.999 [DataOverHttps](INFO): Sending 731 bytes from file Logs/20190711T182120/Express0001.lzma
2019-07-11T18:24:36.804Z,1562869476.804 [DataOverHttps](INFO): Moved sent file to Logs/20190711T182120/Express0001.lzma.bak
2019-07-11T18:24:36.804Z,1562869476.804 [DataOverHttps](INFO): SBD MOMSN=11439319
2019-07-11T18:24:39.036Z,1562869479.036 [Startup:StartupSatComms:B] Stopped
2019-07-11T18:24:39.036Z,1562869479.036 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-07-11T18:24:39.036Z,1562869479.036 [Startup:StartupSatComms] Stopped
2019-07-11T18:24:39.036Z,1562869479.036 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-07-11T18:24:39.037Z,1562869479.037 [Startup](INFO): Completed Startup
2019-07-11T18:24:39.037Z,1562869479.037 [MissionManager](INFO): Startup is completed.
2019-07-11T18:24:39.037Z,1562869479.037 [MissionManager](INFO): Uninitializing Mission Startup
2019-07-11T18:24:39.038Z,1562869479.038 [Startup] Stopped
2019-07-11T18:24:39.038Z,1562869479.038 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-07-11T18:24:39.038Z,1562869479.038 [Startup:A.GoToSurface] Stopped
2019-07-11T18:24:39.038Z,1562869479.038 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-07-11T18:24:39.436Z,1562869479.436 [MissionManager](IMPORTANT): Started mission Default
2019-07-11T18:24:39.436Z,1562869479.436 [Default] Running Loop=1
2019-07-11T18:24:39.436Z,1562869479.436 [Default](DEBUG): Aggregate::initialize Default
2019-07-11T18:24:39.436Z,1562869479.436 [Default:B.GoToSurface] Running Loop=1
2019-07-11T18:24:39.436Z,1562869479.436 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-07-11T18:24:39.436Z,1562869479.436 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-07-11T18:24:39.437Z,1562869479.437 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-07-11T18:24:39.437Z,1562869479.437 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-07-11T18:24:39.437Z,1562869479.437 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-07-11T18:24:39.438Z,1562869479.438 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-07-11T18:24:39.438Z,1562869479.438 [Default:A.Wait] Running Loop=1
2019-07-11T18:24:39.438Z,1562869479.438 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-07-11T18:24:51.944Z,1562869491.944 [CommandLine](IMPORTANT): got command show variable Micromiodem
2019-07-11T18:24:52.759Z,1562869492.759 [Default:A.Wait](INFO): Done Waiting.
2019-07-11T18:24:52.759Z,1562869492.759 [Default:A.Wait] Stopped
2019-07-11T18:24:52.759Z,1562869492.759 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-07-11T18:24:53.337Z,1562869493.337 [Default:CheckIn] Running Loop=1
2019-07-11T18:24:53.337Z,1562869493.337 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-11T18:24:53.337Z,1562869493.337 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-11T18:24:53.575Z,1562869493.575 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-07-11T18:24:57.780Z,1562869497.780 [CommandLine](IMPORTANT): got command show variable Micromodem
2019-07-11T18:24:57.837Z,1562869497.837 [CommandLine](IMPORTANT): Micromodem.loadAtStartup (bool)
2019-07-11T18:24:57.837Z,1562869497.837 [CommandLine](IMPORTANT): Micromodem.simulateHardware (bool)
2019-07-11T18:24:57.838Z,1562869497.838 [CommandLine](IMPORTANT): Micromodem.localAddress (enum)
2019-07-11T18:24:57.838Z,1562869497.838 [CommandLine](IMPORTANT): Micromodem.destinationAddress (enum)
2019-07-11T18:24:57.839Z,1562869497.839 [CommandLine](IMPORTANT): Micromodem.dataRate (enum)
2019-07-11T18:24:57.839Z,1562869497.839 [CommandLine](IMPORTANT): Micromodem.sendExpress (bool)
2019-07-11T18:24:57.839Z,1562869497.839 [CommandLine](IMPORTANT): Micromodem.surfaceThreshold (meter)
2019-07-11T18:24:57.840Z,1562869497.840 [CommandLine](IMPORTANT): Micromodem.pwrampTXLevel (enum)
2019-07-11T18:24:57.840Z,1562869497.840 [CommandLine](IMPORTANT): Micromodem.dusblPingCode (none)
2019-07-11T18:24:57.857Z,1562869497.857 [CommandLine](IMPORTANT): Micromodem.loadControl (none)
2019-07-11T18:24:57.857Z,1562869497.857 [CommandLine](IMPORTANT): Micromodem.loadControl2 (none)
2019-07-11T18:24:57.857Z,1562869497.857 [CommandLine](IMPORTANT): Micromodem.uart (none)
2019-07-11T18:24:57.858Z,1562869497.858 [CommandLine](IMPORTANT): Micromodem.baud (bit_per_second)
2019-07-11T18:24:57.864Z,1562869497.864 [CommandLine](IMPORTANT): Micromodem.dusblPingCodeRequested (enum)
2019-07-11T18:24:57.868Z,1562869497.868 [CommandLine](IMPORTANT): Micromodem.acoustic_wakeup (count)
2019-07-11T18:24:57.869Z,1562869497.869 [CommandLine](IMPORTANT): Micromodem.acoustic_receive_time (epoch_second)
2019-07-11T18:24:57.871Z,1562869497.871 [CommandLine](IMPORTANT): Micromodem.acoustic_transmit_time (epoch_second)
2019-07-11T18:24:57.872Z,1562869497.872 [CommandLine](IMPORTANT): Micromodem.range_request (count)
2019-07-11T18:24:57.874Z,1562869497.874 [CommandLine](IMPORTANT): Micromodem.remote_address (count)
2019-07-11T18:24:57.875Z,1562869497.875 [CommandLine](IMPORTANT): Micromodem.local_address (count)
2019-07-11T18:24:57.877Z,1562869497.877 [CommandLine](IMPORTANT): Micromodem.range (meter)
2019-07-11T18:24:57.878Z,1562869497.878 [CommandLine](IMPORTANT): Micromodem.platform_communications (bool)
2019-07-11T18:24:57.950Z,1562869497.950 [CommandLine](IMPORTANT): Micromodem.component_voltage (volt)
2019-07-11T18:24:57.954Z,1562869497.954 [CommandLine](IMPORTANT): Micromodem.component_avgVoltage (volt)
2019-07-11T18:24:57.955Z,1562869497.955 [CommandLine](IMPORTANT): Micromodem.component_current (milliampere)
2019-07-11T18:24:57.956Z,1562869497.956 [CommandLine](IMPORTANT): Micromodem.component_avgCurrent (milliampere)
2019-07-11T18:25:12.080Z,1562869512.080 [CommandLine](IMPORTANT): got command get Micromodem.surfaceThreshold
2019-07-11T18:25:12.081Z,1562869512.081 [CommandLine](IMPORTANT): Micromodem.surfaceThreshold 0.200000 m
2019-07-11T18:25:29.800Z,1562869529.800 [CommandLine](IMPORTANT): got command configSet Micromodem.surfaceThreshold 5.000000 meter persist
2019-07-11T18:26:57.184Z,1562869617.184 [Micromodem](INFO): Nmea in: *1$CAREV,182656,AUV,2.0.27690*1B

2019-07-11T18:26:57.185Z,1562869617.185 [Micromodem](ERROR): Response from modem failed NMEA checksum: *1$CAREV,182656,AUV,2.0.27690*1B
 expected 0
2019-07-11T18:27:01.568Z,1562869621.568 [CommandLine](IMPORTANT): got command show stack
2019-07-11T18:27:01.568Z,1562869621.568 [CommandLine](IMPORTANT): Behavior Stack:

2019-07-11T18:27:01.569Z,1562869621.569 [Default](IMPORTANT): Priority 0: Default:B.GoToSurface
2019-07-11T18:27:01.569Z,1562869621.569 [Default:CheckIn](IMPORTANT): Priority 1: Default:CheckIn:Read_GPS
2019-07-11T18:27:10.351Z,1562869630.351 [CommandLine](IMPORTANT): got command run ./Missions/DUSBL_Tracking.xml
2019-07-11T18:27:10.351Z,1562869630.351 [MissionManager](INFO): Loading Mission: ./Missions/DUSBL_Tracking.xml
2019-07-11T18:27:10.391Z,1562869630.391 [MissionManager](INFO): DefineArg DUSBL.MissionTimeout = 5.000000 min
2019-07-11T18:27:10.395Z,1562869630.395 [MissionManager](INFO): DefineArg DUSBL.TransponderCode = 2.000000 count
2019-07-11T18:27:10.399Z,1562869630.399 [MissionManager](INFO): DefineArg DUSBL.TrackingUpdatePeriod = 4.000000 s
2019-07-11T18:27:10.408Z,1562869630.408 [MissionManager](INFO): DefineArg DUSBL.NumberOfPings = 1.000000 count
2019-07-11T18:27:10.411Z,1562869630.411 [MissionManager](INFO): DefineArg DUSBL.EnabledDUSBL = DUSBL_Hydroid.loadAtStartup
2019-07-11T18:27:10.416Z,1562869630.416 [DUSBL:A.Pitch](DEBUG): Construct.
2019-07-11T18:27:10.432Z,1562869630.432 [DUSBL:B.SetSpeed](DEBUG): Construct.
2019-07-11T18:27:10.744Z,1562869630.744 [DUSBL:RequestRepeater:A.Wait](DEBUG): Construct Wait.
2019-07-11T18:27:10.756Z,1562869630.756 [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>5</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>4</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-11T18:27:10.767Z,1562869630.767 [CommandLine](IMPORTANT): Running ./Missions/DUSBL_Tracking.xml
2019-07-11T18:27:10.979Z,1562869630.979 [Default] Stopped
2019-07-11T18:27:10.980Z,1562869630.980 [Default](DEBUG): Aggregate::uninitialize Default
2019-07-11T18:27:10.980Z,1562869630.980 [Default:B.GoToSurface] Stopped
2019-07-11T18:27:10.980Z,1562869630.980 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-07-11T18:27:10.980Z,1562869630.980 [Default:CheckIn] Stopped
2019-07-11T18:27:10.980Z,1562869630.980 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-11T18:27:10.980Z,1562869630.980 [Default:CheckIn:Read_GPS] Stopped
2019-07-11T18:27:10.980Z,1562869630.980 [MissionManager](IMPORTANT): Started mission DUSBL
2019-07-11T18:27:10.980Z,1562869630.980 [DUSBL] Running Loop=1
2019-07-11T18:27:10.981Z,1562869630.981 [DUSBL](DEBUG): Aggregate::initialize DUSBL
2019-07-11T18:27:10.981Z,1562869630.981 [DUSBL:A.Pitch] Running Loop=1
2019-07-11T18:27:10.981Z,1562869630.981 [DUSBL:A.Pitch](DEBUG): Initialize.
2019-07-11T18:27:10.981Z,1562869630.981 [DUSBL:B.SetSpeed] Running Loop=1
2019-07-11T18:27:10.981Z,1562869630.981 [DUSBL:B.SetSpeed](DEBUG): Initialize.
2019-07-11T18:27:10.981Z,1562869630.981 [DUSBL:C.] Running Loop=1
2019-07-11T18:27:10.981Z,1562869630.981 [DUSBL:C.](INFO): Initializing TrackAcousticContact.
2019-07-11T18:27:10.982Z,1562869630.982 [DUSBL:RequestRepeater] Running Loop=1
2019-07-11T18:27:10.982Z,1562869630.982 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-07-11T18:27:10.982Z,1562869630.982 [DUSBL:RequestRepeater:A.Wait] Running Loop=1
2019-07-11T18:27:10.982Z,1562869630.982 [DUSBL:RequestRepeater:A.Wait](DEBUG): Initialize Wait Component.
2019-07-11T18:27:10.990Z,1562869630.990 [DUSBL:C.] Running Loop=1
2019-07-11T18:27:10.991Z,1562869630.991 [DUSBL:B.SetSpeed] Running Loop=1
2019-07-11T18:27:10.991Z,1562869630.991 [DUSBL:A.Pitch] Running Loop=1
2019-07-11T18:27:12.539Z,1562869632.539 [NAL9602](INFO): SBD MO Status=2, MOMSN=8119, MT Status=2, MTMSN=0
2019-07-11T18:27:12.539Z,1562869632.539 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-07-11T18:27:13.355Z,1562869633.355 [NAL9602](FAULT): MT Queue status failed to be acquired within timeout. Will not retry this session.
2019-07-11T18:27:14.166Z,1562869634.166 [NAL9602](DEBUG): Fix Requested
2019-07-11T18:27:15.004Z,1562869635.004 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:27:15.368Z,1562869635.368 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:27:15.368Z,1562869635.368 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:27:15.368Z,1562869635.368 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:27:15.368Z,1562869635.368 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:27:15.768Z,1562869635.768 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
O
2019-07-11T18:27:15.768Z,1562869635.768 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:27:15.768Z,1562869635.768 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:27:15.770Z,1562869635.770 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:27:16.173Z,1562869636.173 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:27:19.008Z,1562869639.008 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,63,E
2019-07-11T18:27:19.008Z,1562869639.008 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,63,E
2019-07-11T18:27:19.008Z,1562869639.008 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-11T18:27:19.074Z,1562869639.074 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:27:19.404Z,1562869639.404 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:27:19.404Z,1562869639.404 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:27:19.404Z,1562869639.404 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:27:19.405Z,1562869639.405 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:27:19.422Z,1562869639.422 [NAL9602](DEBUG): Fix Requested
2019-07-11T18:27:19.808Z,1562869639.808 [DUSBL_Hydroid](INFO): USBL response received:4
!U1,P
,80,63,E
2019-07-11T18:27:19.808Z,1562869639.808 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:27:19.808Z,1562869639.808 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:27:19.810Z,1562869639.810 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:27:19.822Z,1562869639.822 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,182605.00,A,3648.16414,N,12147.28853,W,0.097,282.21,110719,,,A*74
2019-07-11T18:27:19.837Z,1562869639.837 [NAL9602](INFO): GPS fix at 20190711T182605: (36.802736, -121.788142)
2019-07-11T18:27:20.222Z,1562869640.222 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:27:23.048Z,1562869643.048 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,63,E4
2019-07-11T18:27:23.048Z,1562869643.048 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,63,E4
2019-07-11T18:27:23.048Z,1562869643.048 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-11T18:27:23.259Z,1562869643.259 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:27:23.455Z,1562869643.455 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:27:23.456Z,1562869643.456 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:27:23.456Z,1562869643.456 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:27:23.456Z,1562869643.456 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:27:23.859Z,1562869643.859 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
80,80,63,E4
2019-07-11T18:27:23.859Z,1562869643.859 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:27:23.860Z,1562869643.860 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:27:23.864Z,1562869643.864 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:27:23.947Z,1562869643.947 [RDI_Pathfinder](ERROR): Failed to parse:
:BE,    -3,   -14,   +13,A
2019-07-11T18:27:24.261Z,1562869644.261 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:27:24.663Z,1562869644.663 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,DD5,000,00B37,189,80,80,10,FF
2019-07-11T18:27:24.664Z,1562869644.664 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-11T18:27:28.751Z,1562869648.751 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:27:29.117Z,1562869649.117 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:27:29.118Z,1562869649.118 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:27:29.118Z,1562869649.118 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:27:29.119Z,1562869649.119 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:27:29.515Z,1562869649.515 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
DD5,000,00B37,189,80,80,10,FF
2019-07-11T18:27:29.515Z,1562869649.515 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:27:29.516Z,1562869649.516 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:27:29.517Z,1562869649.517 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:27:29.929Z,1562869649.929 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:27:32.744Z,1562869652.744 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,8000,00B37,189,80,80,10,FF
2019-07-11T18:27:32.744Z,1562869652.744 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,8000,00B37,189,80,80,10,FF
2019-07-11T18:27:32.744Z,1562869652.744 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-11T18:27:32.817Z,1562869652.817 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:27:33.151Z,1562869653.151 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:27:33.151Z,1562869653.151 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:27:33.151Z,1562869653.151 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:27:33.152Z,1562869653.152 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:27:33.557Z,1562869653.557 [DUSBL_Hydroid](INFO): USBL response received:0,63,E4
!U1,P
00B37,189,80,80,10,FF
2019-07-11T18:27:33.557Z,1562869653.557 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:27:33.557Z,1562869653.557 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:27:33.559Z,1562869653.559 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:27:33.962Z,1562869653.962 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:27:36.784Z,1562869656.784 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,U1,P
00B37,189,80,80,10,FF
2019-07-11T18:27:36.784Z,1562869656.784 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,U1,P
00B37,189,80,80,10,FF
2019-07-11T18:27:36.784Z,1562869656.784 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-11T18:27:36.827Z,1562869656.827 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:27:37.187Z,1562869657.187 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:27:37.187Z,1562869657.187 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:27:37.187Z,1562869657.187 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:27:37.188Z,1562869657.188 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:27:37.592Z,1562869657.592 [DUSBL_Hydroid](INFO): USBL response received:80,63,E4
!U1,P
0B37,189,80,80,10,FF
2019-07-11T18:27:37.592Z,1562869657.592 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:27:37.592Z,1562869657.592 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:27:37.593Z,1562869657.593 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:27:38.017Z,1562869658.017 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:27:38.399Z,1562869658.399 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,1B3,000,00DEF,186,80,80,10,FF
2019-07-11T18:27:38.400Z,1562869658.400 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-11T18:27:42.488Z,1562869662.488 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:27:42.845Z,1562869662.845 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:27:42.846Z,1562869662.846 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:27:42.847Z,1562869662.847 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:27:42.848Z,1562869662.848 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:27:43.247Z,1562869663.247 [DUSBL_Hydroid](INFO): USBL response received:80,08,FF
!U1,P
0DEF,186,80,80,10,FF
2019-07-11T18:27:43.247Z,1562869663.247 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:27:43.248Z,1562869663.248 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:27:43.249Z,1562869663.249 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:27:43.657Z,1562869663.657 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:27:44.059Z,1562869664.059 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,143,000,00901,187,80,80,0A,FF
2019-07-11T18:27:44.060Z,1562869664.060 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-11T18:27:48.148Z,1562869668.148 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:27:48.499Z,1562869668.499 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:27:48.499Z,1562869668.499 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:27:48.499Z,1562869668.499 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:27:48.500Z,1562869668.500 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:27:48.915Z,1562869668.915 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
143,000,00901,187,80,80,0A,FF
2019-07-11T18:27:48.915Z,1562869668.915 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:27:48.916Z,1562869668.916 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:27:48.917Z,1562869668.917 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:27:49.309Z,1562869669.309 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:27:52.140Z,1562869672.140 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,843,000,00901,187,80,80,0A,FF
2019-07-11T18:27:52.140Z,1562869672.140 [DUSBL_Hydroid](INFO): No reply:!U1,N,843,000,00901,187,80,80,0A,FF
2019-07-11T18:27:52.140Z,1562869672.140 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-11T18:27:52.216Z,1562869672.216 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:27:52.555Z,1562869672.555 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:27:52.555Z,1562869672.555 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:27:52.555Z,1562869672.555 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:27:52.556Z,1562869672.556 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:27:52.564Z,1562869672.564 [NAL9602](INFO): Powering down
2019-07-11T18:27:52.947Z,1562869672.947 [DUSBL_Hydroid](INFO): USBL response received:0,80,63,E4
!U1,P
01,187,80,80,0A,FF
2019-07-11T18:27:52.947Z,1562869672.947 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:27:52.948Z,1562869672.948 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:27:52.949Z,1562869672.949 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:27:53.358Z,1562869673.358 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:27:54.882Z,1562869674.882 [CommandLine](IMPORTANT): got command report touch DUSBL_Hydroid.acoustic_contact_range
2019-07-11T18:27:56.180Z,1562869676.180 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,U1,P
01,187,80,80,0A,FF
2019-07-11T18:27:56.180Z,1562869676.180 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,U1,P
01,187,80,80,0A,FF
2019-07-11T18:27:56.180Z,1562869676.180 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-11T18:27:56.303Z,1562869676.303 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:27:56.583Z,1562869676.583 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:27:56.583Z,1562869676.583 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:27:56.584Z,1562869676.584 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:27:56.584Z,1562869676.584 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:27:57.035Z,1562869677.035 [DUSBL_Hydroid](INFO): USBL response received:63,E4
!U1,P
U1,P
01,187,80,80,0A,FF
2019-07-11T18:27:57.036Z,1562869677.036 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:27:57.036Z,1562869677.036 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:27:57.037Z,1562869677.037 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:27:57.398Z,1562869677.398 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:27:57.803Z,1562869677.803 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,!U1,P
U1,P
01,187,80,80,0A,FF
2019-07-11T18:27:57.804Z,1562869677.804 [DUSBL_Hydroid](ERROR): unknown deviceResponse_: !U1,R,!U1,P
U1,P
01,187,80,80,0A,FF
2019-07-11T18:28:00.658Z,1562869680.658 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:28:02.663Z,1562869682.663 [DUSBL_Hydroid](INFO): Setting waiting for range response to false due to timeout. Elapsed:6.078630
2019-07-11T18:28:04.722Z,1562869684.722 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:28:05.071Z,1562869685.071 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:28:05.071Z,1562869685.071 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:28:05.071Z,1562869685.071 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:28:05.072Z,1562869685.072 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:28:05.476Z,1562869685.476 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
!U1,P
U1,P
01,187,80,80,0A,FF
2019-07-11T18:28:05.476Z,1562869685.476 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:28:05.476Z,1562869685.476 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:28:05.477Z,1562869685.477 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:28:05.914Z,1562869685.914 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:28:06.279Z,1562869686.279 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,1CB,000,00DEF,187,80,80,0A,FF
2019-07-11T18:28:06.280Z,1562869686.280 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-11T18:28:06.381Z,1562869686.381 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 176.520004 m
2019-07-11T18:28:10.374Z,1562869690.374 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:28:10.727Z,1562869690.727 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:28:10.727Z,1562869690.727 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:28:10.727Z,1562869690.727 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:28:10.728Z,1562869690.728 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:28:11.131Z,1562869691.131 [DUSBL_Hydroid](INFO): USBL response received:,80,09,FF
!U1,P
DEF,187,80,80,0A,FF
2019-07-11T18:28:11.131Z,1562869691.131 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:28:11.131Z,1562869691.131 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:28:11.133Z,1562869691.133 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:28:11.533Z,1562869691.533 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:28:14.363Z,1562869694.363 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,6,P
DEF,187,80,80,0A,FF
2019-07-11T18:28:14.364Z,1562869694.364 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,6,P
DEF,187,80,80,0A,FF
2019-07-11T18:28:14.364Z,1562869694.364 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-11T18:28:14.442Z,1562869694.442 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:28:14.761Z,1562869694.761 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:28:14.761Z,1562869694.761 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:28:14.761Z,1562869694.761 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:28:14.762Z,1562869694.762 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:28:15.171Z,1562869695.171 [DUSBL_Hydroid](INFO): USBL response received:3,E4
!U1,P
,6,P
DEF,187,80,80,0A,FF
2019-07-11T18:28:15.172Z,1562869695.172 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:28:15.172Z,1562869695.172 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:28:15.173Z,1562869695.173 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:28:15.577Z,1562869695.577 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:28:18.403Z,1562869698.403 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,6,P
DEF,187,80,80,0A,FF
2019-07-11T18:28:18.404Z,1562869698.404 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,6,P
DEF,187,80,80,0A,FF
2019-07-11T18:28:18.404Z,1562869698.404 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-11T18:28:18.486Z,1562869698.486 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:28:18.803Z,1562869698.803 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:28:18.803Z,1562869698.803 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:28:18.803Z,1562869698.803 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:28:18.804Z,1562869698.804 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:28:19.207Z,1562869699.207 [DUSBL_Hydroid](INFO): USBL response received:,63,E4
!U1,P
,P
DEF,187,80,80,0A,FF
2019-07-11T18:28:19.208Z,1562869699.208 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:28:19.208Z,1562869699.208 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:28:19.209Z,1562869699.209 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:28:19.613Z,1562869699.613 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:28:22.444Z,1562869702.444 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,6,P
DEF,187,80,80,0A,FF
2019-07-11T18:28:22.444Z,1562869702.444 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,6,P
DEF,187,80,80,0A,FF
2019-07-11T18:28:22.444Z,1562869702.444 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-11T18:28:22.899Z,1562869702.899 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:28:23.245Z,1562869703.245 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:28:23.245Z,1562869703.245 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:28:23.246Z,1562869703.246 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:28:23.247Z,1562869703.247 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:28:23.655Z,1562869703.655 [DUSBL_Hydroid](INFO): USBL response received:3,E4
!U1,P
,6,P
DEF,187,80,80,0A,FF
2019-07-11T18:28:23.656Z,1562869703.656 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:28:23.656Z,1562869703.656 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:28:23.657Z,1562869703.657 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:28:24.057Z,1562869704.057 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:28:26.914Z,1562869706.914 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,63,E4
F,187,80,80,0A,FF
2019-07-11T18:28:26.914Z,1562869706.914 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,63,E4
F,187,80,80,0A,FF
2019-07-11T18:28:26.915Z,1562869706.915 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-11T18:28:26.949Z,1562869706.949 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:28:27.288Z,1562869707.288 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:28:27.288Z,1562869707.288 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:28:27.288Z,1562869707.288 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:28:27.289Z,1562869707.289 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:28:27.692Z,1562869707.692 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
80,80,63,E4
F,187,80,80,0A,FF
2019-07-11T18:28:27.692Z,1562869707.692 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:28:27.692Z,1562869707.692 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:28:27.694Z,1562869707.694 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:28:28.101Z,1562869708.101 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:28:28.637Z,1562869708.637 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,255,000,00DF0,189,80,80,08,FF
2019-07-11T18:28:28.637Z,1562869708.637 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-11T18:28:28.744Z,1562869708.744 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 176.580002 m
2019-07-11T18:28:33.060Z,1562869713.060 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:28:33.427Z,1562869713.427 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:28:33.427Z,1562869713.427 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:28:33.428Z,1562869713.428 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:28:33.428Z,1562869713.428 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:28:33.832Z,1562869713.832 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
255,000,00DF0,189,80,80,08,FF
2019-07-11T18:28:33.832Z,1562869713.832 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:28:33.832Z,1562869713.832 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:28:33.833Z,1562869713.833 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:28:34.245Z,1562869714.245 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:28:34.278Z,1562869714.278 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-07-11T18:28:34.278Z,1562869714.278 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  95.26,  94.59,   0.00
2019-07-11T18:28:37.064Z,1562869717.064 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,6,00DF0,189,80,80,08,FF
2019-07-11T18:28:37.064Z,1562869717.064 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,6,00DF0,189,80,80,08,FF
2019-07-11T18:28:37.064Z,1562869717.064 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-11T18:28:37.102Z,1562869717.102 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:28:37.471Z,1562869717.471 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:28:37.472Z,1562869717.472 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:28:37.472Z,1562869717.472 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:28:37.473Z,1562869717.473 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:28:37.902Z,1562869717.902 [DUSBL_Hydroid](INFO): USBL response received:3,E4
!U1,P
,6,00DF0,189,80,80,08,FF
2019-07-11T18:28:37.903Z,1562869717.903 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:28:37.903Z,1562869717.903 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:28:37.904Z,1562869717.904 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:28:38.278Z,1562869718.278 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:28:38.675Z,1562869718.675 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,263,000,00B37,189,80,80,0E,FF
2019-07-11T18:28:38.676Z,1562869718.676 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-11T18:28:38.756Z,1562869718.756 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 134.760010 m
2019-07-11T18:28:42.770Z,1562869722.770 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:28:43.119Z,1562869723.119 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:28:43.119Z,1562869723.119 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:28:43.120Z,1562869723.120 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:28:43.120Z,1562869723.120 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:28:43.528Z,1562869723.528 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
263,000,00B37,189,80,80,0E,FF
2019-07-11T18:28:43.528Z,1562869723.528 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:28:43.528Z,1562869723.528 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:28:43.529Z,1562869723.529 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:28:43.929Z,1562869723.929 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:28:44.332Z,1562869724.332 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,FDD,000,002B7,184,80,80,13,FF
2019-07-11T18:28:44.332Z,1562869724.332 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-11T18:28:44.477Z,1562869724.477 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 4.199998 m
2019-07-11T18:28:48.804Z,1562869728.804 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:28:49.183Z,1562869729.183 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:28:49.183Z,1562869729.183 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:28:49.184Z,1562869729.184 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:28:49.184Z,1562869729.184 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:28:49.583Z,1562869729.583 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
FDD,000,002B7,184,80,80,13,FF
2019-07-11T18:28:49.583Z,1562869729.583 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:28:49.584Z,1562869729.584 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:28:49.585Z,1562869729.585 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:28:49.989Z,1562869729.989 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:28:50.396Z,1562869730.396 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,15C,000,00901,187,80,80,0A,FF
2019-07-11T18:28:50.396Z,1562869730.396 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-11T18:28:50.436Z,1562869730.436 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 100.800011 m
2019-07-11T18:28:54.482Z,1562869734.482 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:28:54.835Z,1562869734.835 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:28:54.835Z,1562869734.835 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:28:54.835Z,1562869734.835 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:28:54.836Z,1562869734.836 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:28:55.239Z,1562869735.239 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
15C,000,00901,187,80,80,0A,FF
2019-07-11T18:28:55.240Z,1562869735.240 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:28:55.240Z,1562869735.240 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:28:55.241Z,1562869735.241 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:28:55.645Z,1562869735.645 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:28:56.047Z,1562869736.047 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,FFE,000,002B8,183,80,80,12,FF
2019-07-11T18:28:56.048Z,1562869736.048 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-11T18:28:56.159Z,1562869736.159 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 4.259999 m
2019-07-11T18:28:57.299Z,1562869737.299 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2019-07-11T18:28:57.302Z,1562869737.302 [BPC1](INFO): Received data from all battery sticks.
2019-07-11T18:29:00.130Z,1562869740.130 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:29:00.491Z,1562869740.491 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:29:00.491Z,1562869740.491 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:29:00.491Z,1562869740.491 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:29:00.492Z,1562869740.492 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:29:00.908Z,1562869740.908 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
FFE,000,002B8,183,80,80,12,FF
2019-07-11T18:29:00.908Z,1562869740.908 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:29:00.909Z,1562869740.909 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:29:00.910Z,1562869740.910 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:29:01.306Z,1562869741.306 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:29:01.704Z,1562869741.704 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,208,000,00DF0,183,80,80,12,FF
2019-07-11T18:29:01.704Z,1562869741.704 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-11T18:29:01.781Z,1562869741.781 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 176.580002 m
2019-07-11T18:29:05.780Z,1562869745.780 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:29:06.151Z,1562869746.151 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:29:06.151Z,1562869746.151 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:29:06.152Z,1562869746.152 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:29:06.152Z,1562869746.152 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:29:06.556Z,1562869746.556 [DUSBL_Hydroid](INFO): USBL response received:,188,80,80,08,FF
!U1,P
,80,80,12,FF
2019-07-11T18:29:06.556Z,1562869746.556 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:29:06.556Z,1562869746.556 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:29:06.557Z,1562869746.557 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:29:06.975Z,1562869746.975 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:29:09.789Z,1562869749.789 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,6,FF
!U1,P
,80,80,12,FF
2019-07-11T18:29:09.789Z,1562869749.789 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,6,FF
!U1,P
,80,80,12,FF
2019-07-11T18:29:09.789Z,1562869749.789 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-11T18:29:09.817Z,1562869749.817 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:29:10.188Z,1562869750.188 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:29:10.188Z,1562869750.188 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:29:10.188Z,1562869750.188 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:29:10.189Z,1562869750.189 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:29:10.596Z,1562869750.596 [DUSBL_Hydroid](INFO): USBL response received:3,E4
!U1,P
,6,FF
!U1,P
,80,80,12,FF
2019-07-11T18:29:10.596Z,1562869750.596 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:29:10.596Z,1562869750.596 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:29:10.597Z,1562869750.597 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:29:10.998Z,1562869750.998 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:29:11.400Z,1562869751.400 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,1EE,000,00DF0,187,80,80,12,FF
2019-07-11T18:29:11.400Z,1562869751.400 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-11T18:29:11.476Z,1562869751.476 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 176.580002 m
2019-07-11T18:29:15.471Z,1562869755.471 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:29:15.847Z,1562869755.847 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:29:15.847Z,1562869755.847 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:29:15.848Z,1562869755.848 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:29:15.849Z,1562869755.849 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:29:16.247Z,1562869756.247 [DUSBL_Hydroid](INFO): USBL response received:,80,0A,FF
!U1,P
DF0,187,80,80,12,FF
2019-07-11T18:29:16.247Z,1562869756.247 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:29:16.248Z,1562869756.248 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:29:16.249Z,1562869756.249 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:29:16.657Z,1562869756.657 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:29:19.484Z,1562869759.484 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,6,P
DF0,187,80,80,12,FF
2019-07-11T18:29:19.484Z,1562869759.484 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,6,P
DF0,187,80,80,12,FF
2019-07-11T18:29:19.484Z,1562869759.484 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-11T18:29:19.520Z,1562869759.520 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:29:19.955Z,1562869759.955 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:29:19.955Z,1562869759.955 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:29:19.956Z,1562869759.956 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:29:19.956Z,1562869759.956 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:29:20.292Z,1562869760.292 [DUSBL_Hydroid](INFO): USBL response received:3,E4
!U1,P
,6,P
DF0,187,80,80,12,FF
2019-07-11T18:29:20.292Z,1562869760.292 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:29:20.292Z,1562869760.292 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:29:20.293Z,1562869760.293 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:29:20.698Z,1562869760.698 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:29:23.519Z,1562869763.519 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,8
,6,P
DF0,187,80,80,12,FF
2019-07-11T18:29:23.520Z,1562869763.520 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,8
,6,P
DF0,187,80,80,12,FF
2019-07-11T18:29:23.520Z,1562869763.520 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-11T18:29:23.562Z,1562869763.562 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:29:23.927Z,1562869763.927 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:29:23.927Z,1562869763.927 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:29:23.928Z,1562869763.928 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:29:23.928Z,1562869763.928 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:29:24.332Z,1562869764.332 [DUSBL_Hydroid](INFO): USBL response received:0,63,E4
!U1,P
P
DF0,187,80,80,12,FF
2019-07-11T18:29:24.332Z,1562869764.332 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:29:24.332Z,1562869764.332 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:29:24.334Z,1562869764.334 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:29:24.733Z,1562869764.733 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:29:27.560Z,1562869767.560 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,U1,P
P
DF0,187,80,80,12,FF
2019-07-11T18:29:27.560Z,1562869767.560 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,U1,P
P
DF0,187,80,80,12,FF
2019-07-11T18:29:27.560Z,1562869767.560 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-11T18:29:27.587Z,1562869767.587 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:29:27.963Z,1562869767.963 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:29:27.963Z,1562869767.963 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:29:27.964Z,1562869767.964 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:29:27.964Z,1562869767.964 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:29:28.367Z,1562869768.367 [DUSBL_Hydroid](INFO): USBL response received:80,63,E4
!U1,P

DF0,187,80,80,12,FF
2019-07-11T18:29:28.368Z,1562869768.368 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:29:28.368Z,1562869768.368 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:29:28.369Z,1562869768.369 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:29:28.777Z,1562869768.777 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:29:29.175Z,1562869769.175 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,FE0,000,002B7,184,80,80,12,FF
2019-07-11T18:29:29.176Z,1562869769.176 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-11T18:29:29.240Z,1562869769.240 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 4.199998 m
2019-07-11T18:29:33.261Z,1562869773.261 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:29:33.623Z,1562869773.623 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:29:33.624Z,1562869773.624 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:29:33.624Z,1562869773.624 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:29:33.625Z,1562869773.625 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:29:34.028Z,1562869774.028 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
FE0,000,002B7,184,80,80,12,FF
2019-07-11T18:29:34.028Z,1562869774.028 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:29:34.028Z,1562869774.028 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:29:34.030Z,1562869774.030 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:29:34.429Z,1562869774.429 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:29:36.919Z,1562869776.919 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-07-11T18:29:36.920Z,1562869776.920 [RDI_Pathfinder](ERROR): Failed to parse:
:TS69,    -5,A
2019-07-11T18:29:37.260Z,1562869777.260 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,8000,002B7,184,80,80,12,FF
2019-07-11T18:29:37.260Z,1562869777.260 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,8000,002B7,184,80,80,12,FF
2019-07-11T18:29:37.260Z,1562869777.260 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-11T18:29:37.316Z,1562869777.316 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:29:37.663Z,1562869777.663 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:29:37.663Z,1562869777.663 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:29:37.664Z,1562869777.664 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:29:37.664Z,1562869777.664 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:29:38.068Z,1562869778.068 [DUSBL_Hydroid](INFO): USBL response received:,63,E4
!U1,P
,002B7,184,80,80,12,FF
2019-07-11T18:29:38.068Z,1562869778.068 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:29:38.068Z,1562869778.068 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:29:38.069Z,1562869778.069 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:29:38.473Z,1562869778.473 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:29:41.312Z,1562869781.312 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,63,E4
7,184,80,80,12,FF
2019-07-11T18:29:41.312Z,1562869781.312 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,63,E4
7,184,80,80,12,FF
2019-07-11T18:29:41.312Z,1562869781.312 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-11T18:29:41.345Z,1562869781.345 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:29:41.715Z,1562869781.715 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:29:41.715Z,1562869781.715 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:29:41.715Z,1562869781.715 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:29:41.716Z,1562869781.716 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:29:42.119Z,1562869782.119 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
80,80,63,E4
7,184,80,80,12,FF
2019-07-11T18:29:42.119Z,1562869782.119 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:29:42.120Z,1562869782.120 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:29:42.121Z,1562869782.121 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:29:42.529Z,1562869782.529 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:29:45.368Z,1562869785.368 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:29:45.744Z,1562869785.744 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,63,E4
7,184,80,80,12,FF
2019-07-11T18:29:45.744Z,1562869785.744 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,63,E4
7,184,80,80,12,FF
2019-07-11T18:29:45.744Z,1562869785.744 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-11T18:29:49.417Z,1562869789.417 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:29:49.783Z,1562869789.783 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:29:49.783Z,1562869789.783 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:29:49.784Z,1562869789.784 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:29:49.784Z,1562869789.784 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:29:50.188Z,1562869790.188 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
80,80,63,E4
7,184,80,80,12,FF
2019-07-11T18:29:50.188Z,1562869790.188 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:29:50.188Z,1562869790.188 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:29:50.189Z,1562869790.189 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:29:50.594Z,1562869790.594 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:29:50.991Z,1562869790.991 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,212,000,00DF0,188,80,80,12,FF
2019-07-11T18:29:50.991Z,1562869790.991 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-11T18:29:51.043Z,1562869791.043 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 176.580002 m
2019-07-11T18:29:55.067Z,1562869795.067 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:29:55.439Z,1562869795.439 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:29:55.439Z,1562869795.439 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:29:55.440Z,1562869795.440 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:29:55.440Z,1562869795.440 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:29:55.844Z,1562869795.844 [DUSBL_Hydroid](INFO): USBL response received:80,80,08,FF
!U1,P
0,188,80,80,12,FF
2019-07-11T18:29:55.844Z,1562869795.844 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:29:55.844Z,1562869795.844 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:29:55.846Z,1562869795.846 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:29:56.245Z,1562869796.245 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:29:56.645Z,1562869796.645 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,E89,000,005A6,187,80,80,0A,FF
2019-07-11T18:29:56.645Z,1562869796.645 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-11T18:29:56.720Z,1562869796.720 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 49.259998 m
2019-07-11T18:30:00.709Z,1562869800.709 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:30:01.091Z,1562869801.091 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:30:01.091Z,1562869801.091 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:30:01.092Z,1562869801.092 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:30:01.092Z,1562869801.092 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:30:01.500Z,1562869801.500 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
E89,000,005A6,187,80,80,0A,FF
2019-07-11T18:30:01.500Z,1562869801.500 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:30:01.500Z,1562869801.500 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:30:01.501Z,1562869801.501 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:30:01.916Z,1562869801.916 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:30:04.727Z,1562869804.727 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,809,000,005A6,187,80,80,0A,FF
2019-07-11T18:30:04.728Z,1562869804.728 [DUSBL_Hydroid](INFO): No reply:!U1,N,809,000,005A6,187,80,80,0A,FF
2019-07-11T18:30:04.728Z,1562869804.728 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-11T18:30:04.768Z,1562869804.768 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:30:05.131Z,1562869805.131 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:30:05.131Z,1562869805.131 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:30:05.131Z,1562869805.131 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:30:05.132Z,1562869805.132 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:30:05.535Z,1562869805.535 [DUSBL_Hydroid](INFO): USBL response received:,80,63,E4
!U1,P
5A6,187,80,80,0A,FF
2019-07-11T18:30:05.536Z,1562869805.536 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:30:05.536Z,1562869805.536 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:30:05.537Z,1562869805.537 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:30:05.941Z,1562869805.941 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:30:08.772Z,1562869808.772 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,63,
5A6,187,80,80,0A,FF
2019-07-11T18:30:08.772Z,1562869808.772 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,63,
5A6,187,80,80,0A,FF
2019-07-11T18:30:08.772Z,1562869808.772 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-11T18:30:08.802Z,1562869808.802 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:30:09.178Z,1562869809.178 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:30:09.178Z,1562869809.178 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:30:09.178Z,1562869809.178 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:30:09.179Z,1562869809.179 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:30:09.575Z,1562869809.575 [DUSBL_Hydroid](INFO): USBL response received:E4
!U1,P
80,63,
5A6,187,80,80,0A,FF
2019-07-11T18:30:09.575Z,1562869809.575 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:30:09.576Z,1562869809.576 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:30:09.577Z,1562869809.577 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:30:09.985Z,1562869809.985 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:30:10.388Z,1562869810.388 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,262,000,00DF0,189,80,80,0A,FF
2019-07-11T18:30:10.388Z,1562869810.388 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-11T18:30:10.475Z,1562869810.475 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 176.580002 m
2019-07-11T18:30:14.458Z,1562869814.458 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:30:14.827Z,1562869814.827 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:30:14.827Z,1562869814.827 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:30:14.828Z,1562869814.828 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:30:14.828Z,1562869814.828 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:30:15.232Z,1562869815.232 [DUSBL_Hydroid](INFO): USBL response received:,09,FF
!U1,P
,00DF0,189,80,80,0A,FF
2019-07-11T18:30:15.232Z,1562869815.232 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:30:15.232Z,1562869815.232 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:30:15.234Z,1562869815.234 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:30:15.645Z,1562869815.645 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:30:18.467Z,1562869818.467 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,6300DF0,189,80,80,0A,FF
2019-07-11T18:30:18.468Z,1562869818.468 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,6300DF0,189,80,80,0A,FF
2019-07-11T18:30:18.468Z,1562869818.468 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-11T18:30:18.495Z,1562869818.495 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:30:18.885Z,1562869818.885 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:30:18.885Z,1562869818.885 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:30:18.886Z,1562869818.886 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:30:18.887Z,1562869818.887 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:30:19.272Z,1562869819.272 [DUSBL_Hydroid](INFO): USBL response received:,E4
!U1,P
0,6300DF0,189,80,80,0A,FF
2019-07-11T18:30:19.273Z,1562869819.273 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:30:19.273Z,1562869819.273 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:30:19.279Z,1562869819.279 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:30:19.681Z,1562869819.681 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:30:20.083Z,1562869820.083 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,DC9,000,00AEC,189,80,80,10,FF
2019-07-11T18:30:20.083Z,1562869820.083 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-11T18:30:20.180Z,1562869820.180 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 130.260010 m
2019-07-11T18:30:24.558Z,1562869824.558 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:30:24.931Z,1562869824.931 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:30:24.931Z,1562869824.931 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:30:24.931Z,1562869824.931 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:30:24.932Z,1562869824.932 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:30:25.336Z,1562869825.336 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
DC9,000,00AEC,189,80,80,10,FF
2019-07-11T18:30:25.336Z,1562869825.336 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:30:25.336Z,1562869825.336 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:30:25.337Z,1562869825.337 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:30:25.737Z,1562869825.737 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:30:26.140Z,1562869826.140 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,1BA,000,00DF0,187,80,80,10,FF
2019-07-11T18:30:26.140Z,1562869826.140 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-11T18:30:26.233Z,1562869826.233 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 176.580002 m
2019-07-11T18:30:30.224Z,1562869830.224 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:30:30.587Z,1562869830.587 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:30:30.587Z,1562869830.587 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:30:30.587Z,1562869830.587 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:30:30.588Z,1562869830.588 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:30:30.985Z,1562869830.985 [DUSBL_Hydroid](INFO): USBL response received:,80,09,FF
!U1,P
DF0,187,80,80,10,FF
2019-07-11T18:30:30.985Z,1562869830.985 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:30:30.985Z,1562869830.985 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:30:30.987Z,1562869830.987 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:30:31.393Z,1562869831.393 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:30:34.223Z,1562869834.223 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,63,
DF0,187,80,80,10,FF
2019-07-11T18:30:34.224Z,1562869834.224 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,63,
DF0,187,80,80,10,FF
2019-07-11T18:30:34.224Z,1562869834.224 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-11T18:30:34.311Z,1562869834.311 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:30:34.627Z,1562869834.627 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:30:34.627Z,1562869834.627 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:30:34.627Z,1562869834.627 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:30:34.628Z,1562869834.628 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:30:35.031Z,1562869835.031 [DUSBL_Hydroid](INFO): USBL response received:E4
!U1,P
80,63,
DF0,187,80,80,10,FF
2019-07-11T18:30:35.031Z,1562869835.031 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:30:35.032Z,1562869835.032 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:30:35.033Z,1562869835.033 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:30:35.437Z,1562869835.437 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:30:35.840Z,1562869835.840 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,208,000,00DF0,187,80,80,10,FF
2019-07-11T18:30:35.840Z,1562869835.840 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-11T18:30:35.910Z,1562869835.910 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 176.580002 m
2019-07-11T18:30:39.957Z,1562869839.957 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:30:40.279Z,1562869840.279 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:30:40.279Z,1562869840.279 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:30:40.280Z,1562869840.280 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:30:40.280Z,1562869840.280 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:30:40.684Z,1562869840.684 [DUSBL_Hydroid](INFO): USBL response received:80,0A,FF
!U1,P
0DF0,187,80,80,10,FF
2019-07-11T18:30:40.684Z,1562869840.684 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:30:40.684Z,1562869840.684 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:30:40.686Z,1562869840.686 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:30:41.089Z,1562869841.089 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:30:43.919Z,1562869843.919 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,63,0DF0,187,80,80,10,FF
2019-07-11T18:30:43.920Z,1562869843.920 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,63,0DF0,187,80,80,10,FF
2019-07-11T18:30:43.920Z,1562869843.920 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-11T18:30:44.010Z,1562869844.010 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:30:44.317Z,1562869844.317 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:30:44.317Z,1562869844.317 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:30:44.317Z,1562869844.317 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:30:44.318Z,1562869844.318 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:30:44.724Z,1562869844.724 [DUSBL_Hydroid](INFO): USBL response received:E4
!U1,P
80,63,0DF0,187,80,80,10,FF
2019-07-11T18:30:44.724Z,1562869844.724 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:30:44.724Z,1562869844.724 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:30:44.726Z,1562869844.726 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:30:45.129Z,1562869845.129 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:30:45.535Z,1562869845.535 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,E55,000,005A6,187,80,80,0A,FF
2019-07-11T18:30:45.536Z,1562869845.536 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-11T18:30:45.575Z,1562869845.575 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 49.259998 m
2019-07-11T18:30:49.603Z,1562869849.603 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:30:49.975Z,1562869849.975 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:30:49.975Z,1562869849.975 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:30:49.976Z,1562869849.976 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:30:49.976Z,1562869849.976 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:30:50.383Z,1562869850.383 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
E55,000,005A6,187,80,80,0A,FF
2019-07-11T18:30:50.384Z,1562869850.384 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:30:50.384Z,1562869850.384 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:30:50.385Z,1562869850.385 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:30:50.785Z,1562869850.785 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:30:53.632Z,1562869853.632 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,63,E4
6,187,80,80,0A,FF
2019-07-11T18:30:53.632Z,1562869853.632 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,63,E4
6,187,80,80,0A,FF
2019-07-11T18:30:53.632Z,1562869853.632 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-11T18:30:53.665Z,1562869853.665 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:30:54.015Z,1562869854.015 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:30:54.015Z,1562869854.015 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:30:54.016Z,1562869854.016 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:30:54.016Z,1562869854.016 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:30:54.435Z,1562869854.435 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
80,80,63,E4
6,187,80,80,0A,FF
2019-07-11T18:30:54.435Z,1562869854.435 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:30:54.435Z,1562869854.435 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:30:54.437Z,1562869854.437 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:30:54.831Z,1562869854.831 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:30:57.683Z,1562869857.683 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:30:58.059Z,1562869858.059 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,63,E4
6,187,80,80,0A,FF
2019-07-11T18:30:58.059Z,1562869858.059 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,63,E4
6,187,80,80,0A,FF
2019-07-11T18:30:58.060Z,1562869858.060 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-11T18:31:01.719Z,1562869861.719 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:31:02.099Z,1562869862.099 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:31:02.099Z,1562869862.099 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:31:02.099Z,1562869862.099 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:31:02.100Z,1562869862.100 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:31:02.500Z,1562869862.500 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
80,80,63,E4
6,187,80,80,0A,FF
2019-07-11T18:31:02.500Z,1562869862.500 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:31:02.500Z,1562869862.500 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:31:02.502Z,1562869862.502 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:31:02.920Z,1562869862.920 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:31:03.311Z,1562869863.311 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,1EC,000,00DEF,187,80,80,0A,FF
2019-07-11T18:31:03.312Z,1562869863.312 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-11T18:31:03.385Z,1562869863.385 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 176.520004 m
2019-07-11T18:31:07.376Z,1562869867.376 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:31:07.751Z,1562869867.751 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:31:07.751Z,1562869867.751 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:31:07.752Z,1562869867.752 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:31:07.752Z,1562869867.752 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:31:08.155Z,1562869868.155 [DUSBL_Hydroid](INFO): USBL response received:0A,FF
!U1,P
0,00DEF,187,80,80,0A,FF
2019-07-11T18:31:08.156Z,1562869868.156 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:31:08.156Z,1562869868.156 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:31:08.157Z,1562869868.157 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:31:08.562Z,1562869868.562 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:31:08.964Z,1562869868.964 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,279,000,00DF0,18A,80,80,0A,FF
2019-07-11T18:31:08.964Z,1562869868.964 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-11T18:31:09.014Z,1562869869.014 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 176.580002 m
2019-07-11T18:31:13.053Z,1562869873.053 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:31:13.411Z,1562869873.411 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:31:13.411Z,1562869873.411 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:31:13.411Z,1562869873.411 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:31:13.412Z,1562869873.412 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:31:13.815Z,1562869873.815 [DUSBL_Hydroid](INFO): USBL response received:,80,09,FF
!U1,P
DF0,18A,80,80,0A,FF
2019-07-11T18:31:13.815Z,1562869873.815 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:31:13.816Z,1562869873.816 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:31:13.817Z,1562869873.817 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:31:14.217Z,1562869874.217 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:31:17.044Z,1562869877.044 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80U1,P
DF0,18A,80,80,0A,FF
2019-07-11T18:31:17.044Z,1562869877.044 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80U1,P
DF0,18A,80,80,0A,FF
2019-07-11T18:31:17.044Z,1562869877.044 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-11T18:31:17.124Z,1562869877.124 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:31:17.447Z,1562869877.447 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:31:17.447Z,1562869877.447 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:31:17.447Z,1562869877.447 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:31:17.448Z,1562869877.448 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:31:17.855Z,1562869877.855 [DUSBL_Hydroid](INFO): USBL response received:,63,E4
!U1,P
,P
DF0,18A,80,80,0A,FF
2019-07-11T18:31:17.855Z,1562869877.855 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:31:17.856Z,1562869877.856 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:31:17.857Z,1562869877.857 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:31:18.257Z,1562869878.257 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:31:21.087Z,1562869881.087 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,8,P
,P
DF0,18A,80,80,0A,FF
2019-07-11T18:31:21.088Z,1562869881.088 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,8,P
,P
DF0,18A,80,80,0A,FF
2019-07-11T18:31:21.088Z,1562869881.088 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-11T18:31:21.518Z,1562869881.518 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:31:21.906Z,1562869881.906 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:31:21.906Z,1562869881.906 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:31:21.906Z,1562869881.906 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:31:21.907Z,1562869881.907 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:31:22.295Z,1562869882.295 [DUSBL_Hydroid](INFO): USBL response received:0,63,E4
!U1,P
P
DF0,18A,80,80,0A,FF
2019-07-11T18:31:22.295Z,1562869882.295 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:31:22.296Z,1562869882.296 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:31:22.297Z,1562869882.297 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:31:22.702Z,1562869882.702 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:31:25.529Z,1562869885.529 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,P
P
DF0,18A,80,80,0A,FF
2019-07-11T18:31:25.529Z,1562869885.529 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,P
P
DF0,18A,80,80,0A,FF
2019-07-11T18:31:25.529Z,1562869885.529 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-11T18:31:25.555Z,1562869885.555 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:31:25.931Z,1562869885.931 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:31:25.931Z,1562869885.931 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:31:25.932Z,1562869885.932 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:31:25.932Z,1562869885.932 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:31:26.335Z,1562869886.335 [DUSBL_Hydroid](INFO): USBL response received:63,E4
!U1,P
P
P
DF0,18A,80,80,0A,FF
2019-07-11T18:31:26.336Z,1562869886.336 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:31:26.336Z,1562869886.336 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:31:26.337Z,1562869886.337 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:31:26.742Z,1562869886.742 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:31:27.147Z,1562869887.147 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,256,000,00DF0,189,80,80,0A,FF
2019-07-11T18:31:27.148Z,1562869887.148 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-11T18:31:27.242Z,1562869887.242 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 176.580002 m
2019-07-11T18:31:31.225Z,1562869891.225 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:31:31.591Z,1562869891.591 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:31:31.591Z,1562869891.591 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:31:31.591Z,1562869891.591 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:31:31.592Z,1562869891.592 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:31:31.999Z,1562869891.999 [DUSBL_Hydroid](INFO): USBL response received:0,09,FF
!U1,P
00DF0,189,80,80,0A,FF
2019-07-11T18:31:31.999Z,1562869891.999 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:31:31.000Z,1562869892.000 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:31:32.001Z,1562869892.001 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:31:32.397Z,1562869892.397 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:31:32.800Z,1562869892.800 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,1DE,000,00DF0,187,80,80,0A,FF
2019-07-11T18:31:32.800Z,1562869892.800 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-11T18:31:32.916Z,1562869892.916 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 176.580002 m
2019-07-11T18:31:36.900Z,1562869896.900 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:31:37.243Z,1562869897.243 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:31:37.243Z,1562869897.243 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:31:37.244Z,1562869897.244 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:31:37.244Z,1562869897.244 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:31:37.648Z,1562869897.648 [DUSBL_Hydroid](INFO): USBL response received:,80,80,0A,FF
!U1,P
,187,80,80,0A,FF
2019-07-11T18:31:37.648Z,1562869897.648 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:31:37.648Z,1562869897.648 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:31:37.650Z,1562869897.650 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:31:38.053Z,1562869898.053 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:31:38.457Z,1562869898.457 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,24B,000,00DF1,189,80,80,0A,FF
2019-07-11T18:31:38.457Z,1562869898.457 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-11T18:31:38.502Z,1562869898.502 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 176.639999 m
2019-07-11T18:31:42.538Z,1562869902.538 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:31:42.913Z,1562869902.913 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:31:42.913Z,1562869902.913 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:31:42.913Z,1562869902.913 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:31:42.914Z,1562869902.914 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:31:43.303Z,1562869903.303 [DUSBL_Hydroid](INFO): USBL response received:80,09,FF
!U1,P
0DF1,189,80,80,0A,FF
2019-07-11T18:31:43.304Z,1562869903.304 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:31:43.304Z,1562869903.304 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:31:43.305Z,1562869903.305 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:31:43.709Z,1562869903.709 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:31:44.112Z,1562869904.112 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,27C,000,00DEE,18A,80,80,0A,FF
2019-07-11T18:31:44.112Z,1562869904.112 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-11T18:31:44.235Z,1562869904.235 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 176.459991 m
2019-07-11T18:31:48.584Z,1562869908.584 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:31:48.959Z,1562869908.959 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:31:48.959Z,1562869908.959 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:31:48.959Z,1562869908.959 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:31:48.960Z,1562869908.960 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:31:49.367Z,1562869909.367 [DUSBL_Hydroid](INFO): USBL response received:,80,07,FF
!U1,P
DEE,18A,80,80,0A,FF
2019-07-11T18:31:49.367Z,1562869909.367 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:31:49.367Z,1562869909.367 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:31:49.369Z,1562869909.369 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:31:49.769Z,1562869909.769 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:31:50.171Z,1562869910.171 [DUSBL_Hydroid](INFO): USBL response received:!U1,R,D00,000,0035F,18A,80,80,0A,FF
2019-07-11T18:31:50.172Z,1562869910.172 [DUSBL_Hydroid](INFO): publishing direction and range info
2019-07-11T18:31:50.290Z,1562869910.290 [Reporter](INFO): DUSBL_Hydroid.acoustic_contact_range 14.280000 m
2019-07-11T18:31:54.640Z,1562869914.640 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:31:55.019Z,1562869915.019 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:31:55.019Z,1562869915.019 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:31:55.020Z,1562869915.020 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:31:55.020Z,1562869915.020 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:31:55.429Z,1562869915.429 [DUSBL_Hydroid](INFO): USBL response received:!U1,P
D00,000,0035F,18A,80,80,0A,FF
2019-07-11T18:31:55.429Z,1562869915.429 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:31:55.430Z,1562869915.430 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:31:55.431Z,1562869915.431 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:31:55.830Z,1562869915.830 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:31:58.664Z,1562869918.664 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,6,0035F,18A,80,80,0A,FF
2019-07-11T18:31:58.664Z,1562869918.664 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,6,0035F,18A,80,80,0A,FF
2019-07-11T18:31:58.664Z,1562869918.664 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-11T18:31:58.698Z,1562869918.698 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:31:59.063Z,1562869919.063 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:31:59.063Z,1562869919.063 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:31:59.063Z,1562869919.063 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:31:59.064Z,1562869919.064 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:31:59.467Z,1562869919.467 [DUSBL_Hydroid](INFO): USBL response received:3,E4
!U1,P
,6,0035F,18A,80,80,0A,FF
2019-07-11T18:31:59.468Z,1562869919.468 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:31:59.468Z,1562869919.468 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:31:59.469Z,1562869919.469 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:31:59.879Z,1562869919.879 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:32:02.699Z,1562869922.699 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,6,0035F,18A,80,80,0A,FF
2019-07-11T18:32:02.700Z,1562869922.700 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,6,0035F,18A,80,80,0A,FF
2019-07-11T18:32:02.700Z,1562869922.700 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-11T18:32:02.768Z,1562869922.768 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:32:03.103Z,1562869923.103 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:32:03.103Z,1562869923.103 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:32:03.103Z,1562869923.103 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:32:03.104Z,1562869923.104 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:32:03.507Z,1562869923.507 [DUSBL_Hydroid](INFO): USBL response received:63,E4
!U1,P
6,0035F,18A,80,80,0A,FF
2019-07-11T18:32:03.507Z,1562869923.507 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:32:03.508Z,1562869923.508 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:32:03.509Z,1562869923.509 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:32:03.922Z,1562869923.922 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:32:06.740Z,1562869926.740 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,6,0035F,18A,80,80,0A,FF
2019-07-11T18:32:06.741Z,1562869926.741 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,6,0035F,18A,80,80,0A,FF
2019-07-11T18:32:06.741Z,1562869926.741 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-11T18:32:06.787Z,1562869926.787 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:32:07.143Z,1562869927.143 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:32:07.143Z,1562869927.143 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:32:07.144Z,1562869927.144 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:32:07.144Z,1562869927.144 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:32:07.544Z,1562869927.544 [DUSBL_Hydroid](INFO): USBL response received:63,E4
!U1,P
6,0035F,18A,80,80,0A,FF
2019-07-11T18:32:07.544Z,1562869927.544 [DUSBL_Hydroid](INFO): Command Ack
2019-07-11T18:32:07.545Z,1562869927.545 [DUSBL_Hydroid](INFO): Requesting micromodem ping code 2
2019-07-11T18:32:07.546Z,1562869927.546 [Micromodem](INFO): Nmea out: $CCPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*53

2019-07-11T18:32:07.949Z,1562869927.949 [Micromodem](INFO): Nmea in: $SNPGT,14500,28,4A1C0370,1000,24000,0,0,0,0,2000,0*4E

2019-07-11T18:32:10.779Z,1562869930.779 [DUSBL_Hydroid](INFO): USBL response received:!U1,N,80,80,6,0035F,18A,80,80,0A,FF
2019-07-11T18:32:10.780Z,1562869930.780 [DUSBL_Hydroid](INFO): No reply:!U1,N,80,80,6,0035F,18A,80,80,0A,FF
2019-07-11T18:32:10.780Z,1562869930.780 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-07-11T18:32:10.810Z,1562869930.810 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-07-11T18:32:11.179Z,1562869931.179 [DUSBL_Hydroid](INFO): ****** received transponder code query ******
2019-07-11T18:32:11.179Z,1562869931.179 [DUSBL_Hydroid](INFO): ****** received ping request ******
2019-07-11T18:32:11.180Z,1562869931.180 [DUSBL_Hydroid](INFO): Arming DUSBL. rangeReq: #U1,P,02,014,800,19,00,00,5DC
2019-07-11T18:32:11.180Z,1562869931.180 [DUSBL_Hydroid](INFO): ****** ping requested ******
2019-07-11T18:32:11.219Z,1562869931.219 [DUSBL](INFO): Timed out from 2019-07-11T18:27:10.0Z
2019-07-11T18:32:11.219Z,1562869931.219 [MissionManager](INFO): DUSBL is completed.
2019-07-11T18:32:11.220Z,1562869931.220 [MissionManager](INFO): Uninitializing Mission DUSBL
2019-07-11T18:32:11.220Z,1562869931.220 [DUSBL] Stopped
2019-07-11T18:32:11.220Z,1562869931.220 [DUSBL](DEBUG): Aggregate::uninitialize DUSBL
2019-07-11T18:32:11.220Z,1562869931.220 [DUSBL:A.Pitch] Stopped
2019-07-11T18:32:11.220Z,1562869931.220 [DUSBL:B.SetSpeed] Stopped
2019-07-11T18:32:11.220Z,1562869931.220 [DUSBL:B.SetSpeed](DEBUG): Uninitialize.
2019-07-11T18:32:11.220Z,1562869931.220 [DUSBL:C.] Stopped
2019-07-11T18:32:11.220Z,1562869931.220 [DUSBL:RequestRepeater] Stopped
2019-07-11T18:32:11.220Z,1562869931.220 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-07-11T18:32:11.220Z,1562869931.220 [DUSBL:RequestRepeater:A.Wait] Stopped
2019-07-11T18:32:11.220Z,1562869931.220 [DUSBL:RequestRepeater:A.Wait](DEBUG): Uninitialize Wait Component.
2019-07-11T18:32:11.613Z,1562869931.613 [MissionManager](IMPORTANT): Started mission Default
2019-07-11T18:32:11.613Z,1562869931.613 [Default] Running Loop=1
2019-07-11T18:32:11.613Z,1562869931.613 [Default](DEBUG): Aggregate::initialize Default
2019-07-11T18:32:11.614Z,1562869931.614 [Default:B.GoToSurface] Running Loop=1
2019-07-11T18:32:11.614Z,1562869931.614 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-07-11T18:32:11.614Z,1562869931.614 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-07-11T18:32:11.615Z,1562869931.615 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-07-11T18:32:11.615Z,1562869931.615 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-07-11T18:32:11.616Z,1562869931.616 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-07-11T18:32:11.616Z,1562869931.616 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-07-11T18:32:11.616Z,1562869931.616 [Default:A.Wait] Running Loop=1
2019-07-11T18:32:11.616Z,1562869931.616 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-07-11T18:32:24.959Z,1562869944.959 [Default:A.Wait](INFO): Done Waiting.
2019-07-11T18:32:24.959Z,1562869944.959 [Default:A.Wait] Stopped
2019-07-11T18:32:24.959Z,1562869944.959 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-07-11T18:32:25.358Z,1562869945.358 [Default:CheckIn] Running Loop=1
2019-07-11T18:32:25.358Z,1562869945.358 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-11T18:32:25.359Z,1562869945.359 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-11T18:32:26.136Z,1562869946.136 [NAL9602](INFO): Powering up
2019-07-11T18:32:37.041Z,1562869957.041 [NAL9602](INFO): NAL9602 initialized
2019-07-11T18:32:37.866Z,1562869957.866 [NAL9602](DEBUG): Fix Requested
2019-07-11T18:32:38.300Z,1562869958.300 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-07-11T18:32:38.300Z,1562869958.300 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  93.93,  94.92,  93.43,

2019-07-11T18:33:08.952Z,1562869988.952 [NAL9602](ERROR): Failed to receive proper response when querying signal strength for MT queue check.
2019-07-11T18:33:09.027Z,1562869989.027 [NAL9602](FAULT): received: 
+SBDI: 2, 8119, 2, 0, 0, 0

OK
2019-07-11T18:33:09.027Z,1562869989.027 [NAL9602] Data Fault, FailCount= 1
2019-07-11T18:33:09.027Z,1562869989.027 [NAL9602](ERROR): Data Fault
2019-07-11T18:33:09.171Z,1562869989.171 [CBIT](ERROR): Data Fault in component: NAL9602
2019-07-11T18:33:09.368Z,1562869989.368 [NAL9602](INFO): Powering down
2019-07-11T18:33:10.277Z,1562869990.277 [CBIT](INFO): Clearing failed state for component NAL9602
2019-07-11T18:33:10.277Z,1562869990.277 [NAL9602] No Fault, FailCount= 1
2019-07-11T18:33:39.660Z,1562870019.660 [NAL9602](INFO): Powering up NAL9602
2019-07-11T18:33:50.580Z,1562870030.580 [NAL9602](INFO): NAL9602 initialized
2019-07-11T18:33:51.387Z,1562870031.387 [NAL9602](DEBUG): Fix Requested
2019-07-11T18:36:33.414Z,1562870193.414 [CBIT](INFO): Clearing failed state for component DropWeight
2019-07-11T18:36:33.414Z,1562870193.414 [DropWeight] No Fault, FailCount= 1
2019-07-11T18:37:25.517Z,1562870245.517 [Default:CheckIn:Read_GPS](INFO): Timed out from 2019-07-11T18:32:25.4Z
2019-07-11T18:37:25.517Z,1562870245.517 [Default:CheckIn:Read_GPS] Stopped
2019-07-11T18:37:25.517Z,1562870245.517 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-07-11T18:37:25.945Z,1562870245.945 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-07-11T18:37:26.312Z,1562870246.312 [Micromodem](ERROR): Buffer fill timout failure.
2019-07-11T18:37:32.526Z,1562870252.526 [DataOverHttps](INFO): Sending 301 bytes from file Logs/20190711T182120/Courier0004.lzma
2019-07-11T18:37:33.332Z,1562870253.332 [DataOverHttps](INFO): Moved sent file to Logs/20190711T182120/Courier0004.lzma.bak
2019-07-11T18:37:33.332Z,1562870253.332 [DataOverHttps](INFO): SBD MOMSN=11439400
2019-07-11T18:37:39.238Z,1562870259.238 [NAL9602](INFO): SBD MO Status=0, MOMSN=8119, MT Status=0, MTMSN=0
2019-07-11T18:37:39.238Z,1562870259.238 [NAL9602](INFO): No messages in MT queue
2019-07-11T18:37:55.048Z,1562870275.048 [DataOverHttps](INFO): Sending 1038 bytes from file Logs/20190711T182120/Express0005.lzma
2019-07-11T18:37:55.848Z,1562870275.848 [DataOverHttps](INFO): Moved sent file to Logs/20190711T182120/Express0005.lzma.bak
2019-07-11T18:37:55.848Z,1562870275.848 [DataOverHttps](INFO): SBD MOMSN=11439403
2019-07-11T18:37:58.665Z,1562870278.665 [Default:CheckIn:Read_Iridium] Stopped
2019-07-11T18:37:58.666Z,1562870278.666 [Default:CheckIn:C.Wait] Running Loop=1
2019-07-11T18:37:58.666Z,1562870278.666 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-07-11T18:38:09.940Z,1562870289.940 [NAL9602](INFO): Powering down
2019-07-11T18:39:12.188Z,1562870352.188 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  52.34,  95.26,  94.26,   0.00
2019-07-11T18:39:19.967Z,1562870359.967 [CommandLine](IMPORTANT): got command show stack
2019-07-11T18:39:19.967Z,1562870359.967 [CommandLine](IMPORTANT): Behavior Stack:

2019-07-11T18:39:19.967Z,1562870359.967 [Default](IMPORTANT): Priority 0: Default:B.GoToSurface
2019-07-11T18:39:19.968Z,1562870359.968 [Default:CheckIn](IMPORTANT): Priority 1: Default:CheckIn:C.Wait
2019-07-11T18:40:21.284Z,1562870421.284 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-07-11T18:40:21.284Z,1562870421.284 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,  0.00,  52.34,  95.26,  90.00
2019-07-11T18:42:59.294Z,1562870579.294 [Default:CheckIn:C.Wait](INFO): Done Waiting.
2019-07-11T18:42:59.294Z,1562870579.294 [Default:CheckIn:C.Wait] Stopped
2019-07-11T18:42:59.295Z,1562870579.295 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-07-11T18:42:59.295Z,1562870579.295 [Default:CheckIn:D] Running Loop=1
2019-07-11T18:42:59.650Z,1562870579.650 [Default:CheckIn:D] Stopped
2019-07-11T18:42:59.654Z,1562870579.654 [Default:CheckIn:E] Running Loop=1
2019-07-11T18:43:00.110Z,1562870580.110 [Default:CheckIn:E](IMPORTANT): Default mission has been running for 10.800607 min 
2019-07-11T18:43:00.110Z,1562870580.110 [Default:CheckIn:E] Stopped
2019-07-11T18:43:00.111Z,1562870580.111 [Default:CheckIn](INFO): Completed Default:CheckIn
2019-07-11T18:43:00.111Z,1562870580.111 [Default:CheckIn] Stopped
2019-07-11T18:43:00.111Z,1562870580.111 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-07-11T18:43:00.111Z,1562870580.111 [Default:CheckIn](INFO): Running loop #2
2019-07-11T18:43:00.111Z,1562870580.111 [Default:CheckIn] Running Loop=2
2019-07-11T18:43:00.111Z,1562870580.111 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-07-11T18:43:00.111Z,1562870580.111 [Default:CheckIn:Read_GPS] Running Loop=1
2019-07-11T18:43:00.840Z,1562870580.840 [NAL9602](INFO): Powering up