2019-10-09T18:39:41.615Z,1570646381.615 [Supervisor](DEBUG): Initializing supervisor.
2019-10-09T18:39:41.618Z,1570646381.618 [SyncHandler](DEBUG): Created PCaller Thread at 404514E0
2019-10-09T18:39:41.619Z,1570646381.619 [SyncHandler](INFO): Protected caller Thread ID is 950
2019-10-09T18:39:41.619Z,1570646381.619 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2019-10-09T18:39:41.620Z,1570646381.620 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 404814E0
2019-10-09T18:39:41.621Z,1570646381.621 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 951
2019-10-09T18:39:41.624Z,1570646381.624 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2019-10-09T18:39:41.635Z,1570646381.635 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2019-10-09T18:39:41.636Z,1570646381.636 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 404B14E0
2019-10-09T18:39:41.636Z,1570646381.636 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 952
2019-10-09T18:39:41.637Z,1570646381.637 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2019-10-09T18:39:41.638Z,1570646381.638 [logger ThreadHandler](DEBUG): Created PCaller Thread at 404E14E0
2019-10-09T18:39:41.638Z,1570646381.638 [logger ThreadHandler](INFO): Protected caller Thread ID is 953
2019-10-09T18:39:41.640Z,1570646381.640 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2019-10-09T18:39:41.641Z,1570646381.641 [Supervisor](INFO): Looking for Config files in directory: Config/
2019-10-09T18:39:41.642Z,1570646381.642 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2019-10-09T18:39:42.044Z,1570646382.044 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2019-10-09T18:39:42.044Z,1570646382.044 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2019-10-09T18:39:42.140Z,1570646382.140 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2019-10-09T18:39:42.140Z,1570646382.140 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2019-10-09T18:39:42.438Z,1570646382.438 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2019-10-09T18:39:42.439Z,1570646382.439 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2019-10-09T18:39:42.574Z,1570646382.574 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2019-10-09T18:39:42.574Z,1570646382.574 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2019-10-09T18:39:42.758Z,1570646382.758 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2019-10-09T18:39:42.758Z,1570646382.758 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2019-10-09T18:39:43.186Z,1570646383.186 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2019-10-09T18:39:43.186Z,1570646383.186 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2019-10-09T18:39:43.428Z,1570646383.428 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2019-10-09T18:39:43.429Z,1570646383.429 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2019-10-09T18:39:43.683Z,1570646383.683 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2019-10-09T18:39:43.684Z,1570646383.684 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2019-10-09T18:39:43.867Z,1570646383.867 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2019-10-09T18:39:43.868Z,1570646383.868 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2019-10-09T18:39:43.962Z,1570646383.962 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2019-10-09T18:39:43.963Z,1570646383.963 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2019-10-09T18:39:44.248Z,1570646384.248 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2019-10-09T18:39:44.248Z,1570646384.248 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2019-10-09T18:39:44.327Z,1570646384.327 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2019-10-09T18:39:44.427Z,1570646384.427 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2019-10-09T18:39:44.428Z,1570646384.428 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2019-10-09T18:39:45.010Z,1570646385.010 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2019-10-09T18:39:45.010Z,1570646385.010 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2019-10-09T18:39:45.391Z,1570646385.391 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2019-10-09T18:39:45.393Z,1570646385.393 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/
2019-10-09T18:39:45.394Z,1570646385.394 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/vehicle.cfg
2019-10-09T18:39:45.588Z,1570646385.588 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Control.cfg
2019-10-09T18:39:45.684Z,1570646385.684 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/BIT.cfg
2019-10-09T18:39:45.779Z,1570646385.779 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Battery.cfg
2019-10-09T18:39:46.001Z,1570646386.001 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2019-10-09T18:39:46.002Z,1570646386.001 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Simulator.cfg
2019-10-09T18:39:46.085Z,1570646386.085 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Navigation.cfg
2019-10-09T18:39:46.176Z,1570646386.176 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/logger.cfg
2019-10-09T18:39:46.271Z,1570646386.271 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/secure.cfg
2019-10-09T18:39:46.353Z,1570646386.353 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Servo.cfg
2019-10-09T18:39:46.458Z,1570646386.458 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Sensor.cfg
2019-10-09T18:39:46.634Z,1570646386.634 [Supervisor](INFO): Opening Config file at: Config/lrauv-whoidhs/Science.cfg
2019-10-09T18:39:46.762Z,1570646386.762 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-whoidhs/root/
2019-10-09T18:39:46.762Z,1570646386.762 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2019-10-09T18:39:46.767Z,1570646386.767 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2019-10-09T18:39:47.056Z,1570646387.056 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_orientation
2019-10-09T18:39:47.061Z,1570646387.061 [AHRS_M2](INFO): created writer for : platform_orientation
2019-10-09T18:39:47.063Z,1570646387.063 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_magnetic_orientation
2019-10-09T18:39:47.068Z,1570646387.068 [AHRS_M2](INFO): created writer for : platform_magnetic_orientation
2019-10-09T18:39:47.069Z,1570646387.069 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_pitch_angle
2019-10-09T18:39:47.074Z,1570646387.074 [AHRS_M2](INFO): created writer for : platform_pitch_angle
2019-10-09T18:39:47.074Z,1570646387.074 [AHRS_M2](DEBUG): LcmSlateWriter::add(): platform_roll_angle
2019-10-09T18:39:47.080Z,1570646387.080 [AHRS_M2](INFO): created writer for : platform_roll_angle
2019-10-09T18:39:47.151Z,1570646387.151 [AHRS_M2] Loaded
2019-10-09T18:39:47.151Z,1570646387.151 [ComponentRegistry](DEBUG): SyncComponent "AHRS_M2" handled in the control thread.
2019-10-09T18:39:47.226Z,1570646387.226 [DataOverHttps] Loaded
2019-10-09T18:39:47.226Z,1570646387.226 [ComponentRegistry](DEBUG): Component "DataOverHttps" handled in its own thread.
2019-10-09T18:39:47.228Z,1570646387.228 [DataOverHttps ThreadHandler](DEBUG): Created PCaller Thread at 4075C4E0
2019-10-09T18:39:47.228Z,1570646387.228 [DataOverHttps ThreadHandler](INFO): Protected caller Thread ID is 1032
2019-10-09T18:39:47.241Z,1570646387.241 [Depth_Keller] Loaded
2019-10-09T18:39:47.241Z,1570646387.241 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2019-10-09T18:39:47.298Z,1570646387.298 [DUSBL_Hydroid] Loaded
2019-10-09T18:39:47.299Z,1570646387.299 [ComponentRegistry](DEBUG): SyncComponent "DUSBL_Hydroid" handled in the control thread.
2019-10-09T18:39:47.346Z,1570646387.346 [Micromodem] Loaded
2019-10-09T18:39:47.347Z,1570646387.347 [ComponentRegistry](DEBUG): SyncComponent "Micromodem" handled in the control thread.
2019-10-09T18:39:47.444Z,1570646387.444 [NAL9602] Loaded
2019-10-09T18:39:47.444Z,1570646387.444 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2019-10-09T18:39:47.459Z,1570646387.459 [Onboard] Loaded
2019-10-09T18:39:47.460Z,1570646387.460 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2019-10-09T18:39:47.466Z,1570646387.466 [PowerOnly] Loaded
2019-10-09T18:39:47.466Z,1570646387.466 [ComponentRegistry](DEBUG): SyncComponent "PowerOnly" handled in the control thread.
2019-10-09T18:39:47.473Z,1570646387.473 [Radio_Surface] Loaded
2019-10-09T18:39:47.473Z,1570646387.473 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2019-10-09T18:39:47.474Z,1570646387.474 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 4078C4E0
2019-10-09T18:39:47.474Z,1570646387.474 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 1033
2019-10-09T18:39:47.519Z,1570646387.519 [RDI_Pathfinder] Loaded
2019-10-09T18:39:47.519Z,1570646387.519 [ComponentRegistry](DEBUG): SyncComponent "RDI_Pathfinder" handled in the control thread.
2019-10-09T18:39:49.168Z,1570646389.168 [BPC1] Loaded
2019-10-09T18:39:49.168Z,1570646389.168 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2019-10-09T18:39:49.168Z,1570646389.168 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2019-10-09T18:39:49.169Z,1570646389.169 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2019-10-09T18:39:49.182Z,1570646389.182 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2019-10-09T18:39:49.182Z,1570646389.182 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2019-10-09T18:39:49.331Z,1570646389.331 [DeadReckonUsingMultipleVelocitySources] Loaded
2019-10-09T18:39:49.331Z,1570646389.331 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2019-10-09T18:39:49.470Z,1570646389.470 [NavChart] Loaded
2019-10-09T18:39:49.470Z,1570646389.470 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2019-10-09T18:39:49.506Z,1570646389.506 [UniversalFixResidualReporter] Loaded
2019-10-09T18:39:49.507Z,1570646389.507 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2019-10-09T18:39:49.507Z,1570646389.507 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2019-10-09T18:39:49.508Z,1570646389.508 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2019-10-09T18:39:49.738Z,1570646389.738 [BuoyancyServo] Loaded
2019-10-09T18:39:49.738Z,1570646389.738 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2019-10-09T18:39:49.768Z,1570646389.768 [ElevatorServo] Loaded
2019-10-09T18:39:49.769Z,1570646389.769 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2019-10-09T18:39:49.786Z,1570646389.786 [MassServo] Loaded
2019-10-09T18:39:49.786Z,1570646389.786 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2019-10-09T18:39:49.802Z,1570646389.802 [RudderServo] Loaded
2019-10-09T18:39:49.802Z,1570646389.802 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2019-10-09T18:39:49.816Z,1570646389.816 [ThrusterServo] Loaded
2019-10-09T18:39:49.817Z,1570646389.817 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2019-10-09T18:39:49.817Z,1570646389.817 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2019-10-09T18:39:49.818Z,1570646389.818 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2019-10-09T18:39:49.999Z,1570646389.999 [PAR_Licor] Loaded
2019-10-09T18:39:49.999Z,1570646389.999 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2019-10-09T18:39:50.043Z,1570646390.043 [WetLabsSeaOWL_UV_A] Loaded
2019-10-09T18:39:50.044Z,1570646390.044 [ComponentRegistry](DEBUG): Component "WetLabsSeaOWL_UV_A" handled in its own thread.
2019-10-09T18:39:50.045Z,1570646390.045 [WetLabsSeaOWL_UV_A ThreadHandler](DEBUG): Created PCaller Thread at 408D74E0
2019-10-09T18:39:50.045Z,1570646390.045 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Protected caller Thread ID is 1034
2019-10-09T18:39:50.046Z,1570646390.046 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2019-10-09T18:39:50.046Z,1570646390.046 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2019-10-09T18:39:50.337Z,1570646390.337 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2019-10-09T18:39:50.338Z,1570646390.338 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2019-10-09T18:39:50.379Z,1570646390.379 [DepthRateCalculator] Loaded
2019-10-09T18:39:50.379Z,1570646390.379 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2019-10-09T18:39:50.384Z,1570646390.384 [PitchRateCalculator] Loaded
2019-10-09T18:39:50.385Z,1570646390.385 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2019-10-09T18:39:50.397Z,1570646390.397 [SpeedCalculator] Loaded
2019-10-09T18:39:50.397Z,1570646390.397 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2019-10-09T18:39:50.418Z,1570646390.418 [TempGradientCalculator] Loaded
2019-10-09T18:39:50.418Z,1570646390.418 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2019-10-09T18:39:50.423Z,1570646390.423 [YawRateCalculator] Loaded
2019-10-09T18:39:50.424Z,1570646390.424 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2019-10-09T18:39:50.464Z,1570646390.464 [ElevatorOffsetCalculator] Loaded
2019-10-09T18:39:50.464Z,1570646390.464 [ComponentRegistry](DEBUG): SyncComponent "ElevatorOffsetCalculator" handled in the control thread.
2019-10-09T18:39:50.464Z,1570646390.464 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2019-10-09T18:39:50.465Z,1570646390.465 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2019-10-09T18:39:50.600Z,1570646390.600 [SBIT](DEBUG): Construct Startup Built In Test.
2019-10-09T18:39:50.622Z,1570646390.622 [SBIT] Loaded
2019-10-09T18:39:50.622Z,1570646390.622 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2019-10-09T18:39:50.623Z,1570646390.623 [IBIT](DEBUG): Construct Initiated Built In Test.
2019-10-09T18:39:50.634Z,1570646390.634 [IBIT] Loaded
2019-10-09T18:39:50.635Z,1570646390.635 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2019-10-09T18:39:50.638Z,1570646390.638 [CBIT](DEBUG): Construct Continuous Built In Test.
2019-10-09T18:39:50.774Z,1570646390.774 [CBIT] Loaded
2019-10-09T18:39:50.774Z,1570646390.774 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2019-10-09T18:39:50.775Z,1570646390.775 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2019-10-09T18:39:50.775Z,1570646390.775 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2019-10-09T18:39:50.857Z,1570646390.857 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2019-10-09T18:39:50.857Z,1570646390.857 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2019-10-09T18:39:50.956Z,1570646390.956 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2019-10-09T18:39:50.957Z,1570646390.957 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2019-10-09T18:39:51.023Z,1570646391.023 [VerticalControl](DEBUG): Construct VerticalControl.
2019-10-09T18:39:51.104Z,1570646391.104 [VerticalControl] Loaded
2019-10-09T18:39:51.105Z,1570646391.105 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2019-10-09T18:39:51.105Z,1570646391.105 [HorizontalControl](DEBUG): Construct HorizontalControl.
2019-10-09T18:39:51.162Z,1570646391.162 [HorizontalControl] Loaded
2019-10-09T18:39:51.163Z,1570646391.163 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2019-10-09T18:39:51.164Z,1570646391.164 [SpeedControl](DEBUG): Construct SpeedControl.
2019-10-09T18:39:51.165Z,1570646391.165 [SpeedControl] Loaded
2019-10-09T18:39:51.166Z,1570646391.166 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2019-10-09T18:39:51.166Z,1570646391.166 [LoopControl](DEBUG): Construct LoopControl.
2019-10-09T18:39:51.167Z,1570646391.167 [LoopControl] Loaded
2019-10-09T18:39:51.167Z,1570646391.167 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2019-10-09T18:39:51.167Z,1570646391.167 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2019-10-09T18:39:51.168Z,1570646391.168 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2019-10-09T18:39:51.200Z,1570646391.200 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2019-10-09T18:39:51.204Z,1570646391.204 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2019-10-09T18:39:51.204Z,1570646391.204 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2019-10-09T18:39:51.211Z,1570646391.211 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2019-10-09T18:39:51.212Z,1570646391.212 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40AB44E0
2019-10-09T18:39:51.212Z,1570646391.212 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 1035
2019-10-09T18:39:51.217Z,1570646391.217 [Supervisor](INFO): Main Thread ID is 802
2019-10-09T18:39:51.217Z,1570646391.217 [Supervisor](DEBUG): Running supervisor.
2019-10-09T18:39:51.218Z,1570646391.218 [CommandLine ThreadHandler](INFO): Handler Thread ID is 1036
2019-10-09T18:39:51.220Z,1570646391.220 [controlThread ThreadHandler](INFO): Handler Thread ID is 1037
2019-10-09T18:39:51.221Z,1570646391.221 [controlThread](DEBUG): Initializing ControlThread
2019-10-09T18:39:51.227Z,1570646391.227 [NavChart](DEBUG): Initialize NavChart Navigation.
2019-10-09T18:39:51.227Z,1570646391.227 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2019-10-09T18:39:51.229Z,1570646391.229 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2019-10-09T18:39:51.229Z,1570646391.229 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2019-10-09T18:39:51.230Z,1570646391.230 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2019-10-09T18:39:51.230Z,1570646391.230 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2019-10-09T18:39:51.231Z,1570646391.231 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2019-10-09T18:39:51.231Z,1570646391.231 [ElevatorOffsetCalculator](DEBUG): Initializing ElevatorOffsetCalculator.
2019-10-09T18:39:51.232Z,1570646391.232 [SBIT](INFO): Initialize SBIT Component.
2019-10-09T18:39:51.232Z,1570646391.232 [SBIT](IMPORTANT): git: 2019-09-23_TRN-90-g217e710
2019-10-09T18:39:51.232Z,1570646391.232 [SBIT](INFO): git hash: 217e710eb7ee4d50c735837a6a63058c2a331c1b
2019-10-09T18:39:51.233Z,1570646391.233 [SBIT](IMPORTANT): Kernel Release: 2.6.27.8
2019-10-09T18:39:51.234Z,1570646391.234 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #2 PREEMPT Thu Jan 11 20:13:48 PST 2018
Kernel Reported: #2 PREEMPT Wed May 15 08:34:03 PDT 2019
2019-10-09T18:39:51.235Z,1570646391.235 [SBIT](INFO): Beginning SBIT in 28.000000 seconds.
2019-10-09T18:39:51.236Z,1570646391.236 [IBIT](INFO): Initialize IBIT Component.
2019-10-09T18:39:51.236Z,1570646391.236 [CBIT](DEBUG): Initialize CBIT Component.
2019-10-09T18:39:51.237Z,1570646391.237 [logger ThreadHandler](INFO): Handler Thread ID is 1038
2019-10-09T18:39:51.249Z,1570646391.249 [CBIT](DEBUG): Initialized mux pins.
2019-10-09T18:39:51.249Z,1570646391.249 [CBIT](DEBUG): Initializing the watchdog timer.
2019-10-09T18:39:51.257Z,1570646391.257 [DataOverHttps ThreadHandler](INFO): Handler Thread ID is 1039
2019-10-09T18:39:51.258Z,1570646391.258 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2019-10-09T18:39:51.269Z,1570646391.269 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 1040
2019-10-09T18:39:51.273Z,1570646391.273 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2019-10-09T18:39:51.273Z,1570646391.273 [CBIT](DEBUG): Initializing heartbeat.
2019-10-09T18:39:51.281Z,1570646391.281 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Handler Thread ID is 1041
2019-10-09T18:39:51.282Z,1570646391.282 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-10-09T18:39:51.309Z,1570646391.309 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 1042
2019-10-09T18:39:51.312Z,1570646391.312 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2019-10-09T18:39:51.312Z,1570646391.312 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2019-10-09T18:39:51.313Z,1570646391.313 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2019-10-09T18:39:51.313Z,1570646391.313 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2019-10-09T18:39:51.313Z,1570646391.313 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2019-10-09T18:39:51.313Z,1570646391.313 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2019-10-09T18:39:51.313Z,1570646391.313 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2019-10-09T18:39:51.314Z,1570646391.314 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2019-10-09T18:39:51.314Z,1570646391.314 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2019-10-09T18:39:51.314Z,1570646391.314 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2019-10-09T18:39:51.314Z,1570646391.314 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2019-10-09T18:39:51.314Z,1570646391.314 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2019-10-09T18:39:51.315Z,1570646391.315 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2019-10-09T18:39:51.315Z,1570646391.315 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2019-10-09T18:39:51.315Z,1570646391.315 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2019-10-09T18:39:51.315Z,1570646391.315 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2019-10-09T18:39:51.345Z,1570646391.345 [CBIT](DEBUG): Deactivating GF circuits.
2019-10-09T18:39:51.345Z,1570646391.345 [CBIT](DEBUG): Deactivating emergency mode.
2019-10-09T18:39:51.381Z,1570646391.381 [CBIT](DEBUG): Backplane powered.
2019-10-09T18:39:51.381Z,1570646391.381 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2019-10-09T18:39:51.383Z,1570646391.383 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2019-10-09T18:39:51.383Z,1570646391.383 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2019-10-09T18:39:51.384Z,1570646391.384 [LoopControl](DEBUG): Initialize LoopControlComponent.
2019-10-09T18:39:51.385Z,1570646391.385 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2019-10-09T18:39:51.396Z,1570646391.396 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2019-10-09T18:39:51.418Z,1570646391.418 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

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

    <Aggregate Id="StartupSatComms">

        <Sequence/>

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

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

    </Aggregate>

</Mission>


2019-10-09T18:39:51.419Z,1570646391.419 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2019-10-09T18:39:51.492Z,1570646391.492 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2019-10-09T18:39:51.493Z,1570646391.493 [Default:A.Wait](DEBUG): Construct Wait.
2019-10-09T18:39:51.495Z,1570646391.495 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2019-10-09T18:39:51.542Z,1570646391.542 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2019-10-09T18:39:51.545Z,1570646391.545 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2019-10-09T18:39:51.562Z,1570646391.562 [Default:E.Execute](DEBUG): Construct Execute.
2019-10-09T18:39:51.581Z,1570646391.581 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Universal="Tethys/Universal"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Universal http://okeanids.mbari.org/tethys/Xml/Universal.xsd"
         Id="Default">

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

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

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

    <Aggregate Id="CheckIn">

        <Sequence Repeat="288"/>

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

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

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

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

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

    </Aggregate>

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

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

</Mission>


2019-10-09T18:39:51.586Z,1570646391.586 [controlThread](DEBUG): Component order: CycleStarter,AHRS_M2,Depth_Keller,DUSBL_Hydroid,Micromodem,NAL9602,Onboard,PowerOnly,RDI_Pathfinder,BPC1,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,ElevatorOffsetCalculator,DeadReckonUsingMultipleVelocitySources,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2019-10-09T18:39:51.599Z,1570646391.599 [AHRS_M2](DEBUG): Initializing AHRS_M2.
2019-10-09T18:39:51.637Z,1570646391.637 [DUSBL_Hydroid](INFO): Powering up
2019-10-09T18:39:51.637Z,1570646391.637 [DUSBL_Hydroid](DEBUG): Initializing DUSBL_Hydroid.
2019-10-09T18:39:51.673Z,1570646391.673 [Radio_Surface](INFO): Powering up
2019-10-09T18:39:51.843Z,1570646391.843 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2019-10-09T18:39:51.849Z,1570646391.849 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2019-10-09T18:39:51.850Z,1570646391.850 [ElevatorServo](DEBUG): Initializing EZServoServo.
2019-10-09T18:39:51.857Z,1570646391.857 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2019-10-09T18:39:51.858Z,1570646391.858 [MassServo](DEBUG): Initializing EZServoServo.
2019-10-09T18:39:51.865Z,1570646391.865 [MassServo](DEBUG): Initializing MassServo.
2019-10-09T18:39:51.866Z,1570646391.866 [RudderServo](DEBUG): Initializing EZServoServo.
2019-10-09T18:39:51.873Z,1570646391.873 [RudderServo](DEBUG): Initializing RudderServo.
2019-10-09T18:39:51.874Z,1570646391.874 [ThrusterServo](DEBUG): Initializing EZServoServo.
2019-10-09T18:39:51.881Z,1570646391.881 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2019-10-09T18:39:52.011Z,1570646392.011 [Micromodem](INFO): Powering up
2019-10-09T18:39:52.011Z,1570646392.011 [Micromodem](DEBUG): Initializing Micromodem.
2019-10-09T18:39:52.793Z,1570646392.793 [RudderServo](ERROR): Rudder initialization uart error serial timeout
2019-10-09T18:39:52.793Z,1570646392.793 [RudderServo](FAULT): Rudder failed to initialize
2019-10-09T18:39:52.794Z,1570646392.794 [RudderServo] Communications Fault, FailCount= 1
2019-10-09T18:39:52.794Z,1570646392.794 [RudderServo](ERROR): Communications Fault
2019-10-09T18:39:52.904Z,1570646392.904 [CBIT](ERROR): Communications Fault in component: RudderServo
2019-10-09T18:39:53.082Z,1570646393.082 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-10-09T18:39:53.082Z,1570646393.082 [RudderServo](INFO): Powering down
2019-10-09T18:39:53.800Z,1570646393.800 [RudderServo](DEBUG): Initializing EZServoServo.
2019-10-09T18:39:53.918Z,1570646393.918 [RudderServo](DEBUG): Initializing RudderServo.
2019-10-09T18:39:53.922Z,1570646393.922 [CBIT](INFO): Clearing failed state for component RudderServo
2019-10-09T18:39:53.922Z,1570646393.922 [RudderServo] No Fault, FailCount= 1
2019-10-09T18:39:56.993Z,1570646396.993 [Micromodem](INFO): Nmea out: $CCCFG,ALL,0*33

2019-10-09T18:39:57.399Z,1570646397.399 [Micromodem](INFO): Nmea in: $CATMG,2019-10-09T18:39:56.183105Z,RTC,RTC*5C

2019-10-09T18:39:57.400Z,1570646397.400 [Micromodem](ERROR): CFG response from modem unexpected: $CATMG,2019-10-09T18:39:56.183105Z,RTC,RTC*5C

2019-10-09T18:39:57.907Z,1570646397.907 [Micromodem](INFO): Nmea in: $CACFG,ALL,0*31

2019-10-09T18:39:57.907Z,1570646397.907 [Micromodem](INFO): Nmea out: $CCCFG,SRC,1*31

2019-10-09T18:39:58.311Z,1570646398.311 [Micromodem](INFO): Nmea in: $CACFG,SRC,1*33

2019-10-09T18:39:58.311Z,1570646398.311 [Micromodem](INFO): Nmea out: $CCCFG,nav.dt.txtrig_gpio4,1*64

2019-10-09T18:39:58.715Z,1570646398.715 [Micromodem](INFO): Nmea in: $CACFG,nav.dt.txtrig_gpio4,1*66

2019-10-09T18:39:58.715Z,1570646398.715 [Micromodem](INFO): Nmea out: $CCCFG,pwramp.txlevel,0*2F

2019-10-09T18:39:59.119Z,1570646399.119 [Micromodem](INFO): Nmea in: $CACFG,pwramp.txlevel,0*2D

2019-10-09T18:39:59.119Z,1570646399.119 [Micromodem](INFO): Nmea out: $CCCFG,DTO,30*1E

2019-10-09T18:39:59.527Z,1570646399.527 [Micromodem](INFO): Nmea in: $CACFG,DTO,30*1C

2019-10-09T18:39:59.527Z,1570646399.527 [Micromodem](INFO): Nmea out: $CCCFG,FC0,10000*46

2019-10-09T18:39:59.915Z,1570646399.915 [Micromodem](INFO): Nmea in: $CACFG,FC0,10000*44

2019-10-09T18:39:59.915Z,1570646399.915 [Micromodem](INFO): Nmea out: $CCCFG,BW0,4000*63

2019-10-09T18:40:00.343Z,1570646400.343 [Micromodem](INFO): Nmea in: $CACFG,BW0,4000*61

2019-10-09T18:40:00.343Z,1570646400.343 [Micromodem](INFO): Nmea out: $CCCFG,BND,0*3A

2019-10-09T18:40:00.711Z,1570646400.711 [Micromodem](INFO): Nmea in: $CACFG,BND,0*38

2019-10-09T18:40:00.712Z,1570646400.712 [Micromodem](INFO): Nmea out: $CCCLK,2019,10,09,18,40,01*4A

2019-10-09T18:40:01.123Z,1570646401.123 [Micromodem](INFO): Nmea in: $CACLK,2019,10,9,18,40,1*48

2019-10-09T18:40:01.919Z,1570646401.919 [Micromodem](INFO): Nmea in: $CATMS,0,2019-10-09T18:40:02Z*7B

2019-10-09T18:40:01.930Z,1570646401.930 [Micromodem](INFO): Nmea in: $CATMG,2019-10-09T18:40:02.027333Z,USER_CMD,RTC*1A

2019-10-09T18:40:05.184Z,1570646405.184 [RDI_Pathfinder](ERROR): Failed to parse:Pathfinder
2019-10-09T18:40:09.590Z,1570646409.590 [DUSBL_Hydroid](INFO): DUSBL Version:O
2019-10-09T18:40:17.667Z,1570646417.667 [NAL9602](INFO): Powering up NAL9602
2019-10-09T18:40:19.747Z,1570646419.747 [SBIT](IMPORTANT): Beginning Startup BIT
2019-10-09T18:40:19.785Z,1570646419.785 [CBIT](IMPORTANT): Beginning ground fault scan
2019-10-09T18:40:28.683Z,1570646428.683 [NAL9602](INFO): NAL9602 initialized
2019-10-09T18:40:29.505Z,1570646429.505 [NAL9602](DEBUG): Fix Requested
2019-10-09T18:40:30.834Z,1570646430.834 [CBIT](IMPORTANT): No ground fault detected
mA:
CHAN A0 (Batt): -0.005919
CHAN A1 (24V): -0.021545
CHAN A2 (12V): -0.004248
CHAN A3 (5V): -0.001922
CHAN B0 (3.3V): 0.000444
CHAN B1 (3.15aV): 0.000004
CHAN B2 (3.15bV): 0.000436
CHAN B3 (GND): 0.001310
OPEN: 0.006047
Full Scale Calc: 4.765 mA, -1.589 mA
2019-10-09T18:40:38.200Z,1570646438.200 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-10-09T18:40:38.201Z,1570646438.201 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19100911455744,35.0, -0.1,   0.0,10
2019-10-09T18:40:40.240Z,1570646440.240 [RDI_Pathfinder](ERROR): only read 2 of 4 data items
2019-10-09T18:40:40.241Z,1570646440.241 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,   +9,    +9,A
2019-10-09T18:41:13.105Z,1570646473.105 [SBIT](IMPORTANT): SBIT PASSED
2019-10-09T18:41:13.170Z,1570646473.170 [CommandLine](IMPORTANT): got command configSet list
2019-10-09T18:41:13.170Z,1570646473.170 [CommandLine](IMPORTANT): Listing configuration overrides from Data/persisted.cfg
2019-10-09T18:41:13.171Z,1570646473.171 [CommandLine](IMPORTANT): Aanderaa_O2.loadAtStartup=0 bool;

2019-10-09T18:41:13.171Z,1570646473.171 [CommandLine](IMPORTANT): CTD_NeilBrown.loadAtStartup=0 bool;

2019-10-09T18:41:13.171Z,1570646473.171 [CommandLine](IMPORTANT): DropWeight.loadAtStartup=0 bool;

2019-10-09T18:41:13.483Z,1570646473.483 [MissionManager](IMPORTANT): Started mission Startup
2019-10-09T18:41:13.483Z,1570646473.483 [Startup] Running Loop=1
2019-10-09T18:41:13.483Z,1570646473.483 [Startup](DEBUG): Aggregate::initialize Startup
2019-10-09T18:41:13.484Z,1570646473.484 [Startup:A.GoToSurface] Running Loop=1
2019-10-09T18:41:13.484Z,1570646473.484 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-10-09T18:41:13.484Z,1570646473.484 [Startup:A.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-10-09T18:41:13.485Z,1570646473.485 [Startup:A.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-10-09T18:41:13.485Z,1570646473.485 [Startup:A.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-10-09T18:41:13.486Z,1570646473.486 [Startup:A.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-10-09T18:41:13.486Z,1570646473.486 [Startup:A.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-10-09T18:41:13.488Z,1570646473.488 [Startup:StartupSatComms] Running Loop=1
2019-10-09T18:41:13.488Z,1570646473.488 [Startup:StartupSatComms](DEBUG): Aggregate::initialize Startup:StartupSatComms
2019-10-09T18:41:13.488Z,1570646473.488 [Startup:StartupSatComms:A] Running Loop=1
2019-10-09T18:41:13.892Z,1570646473.892 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2019-10-09T18:41:46.196Z,1570646506.196 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-10-09T18:41:46.196Z,1570646506.196 [RDI_Pathfinder](ERROR): Failed to parse:
:RA,101.41,  88.94,  92.93,  97.42
2019-10-09T18:42:13.674Z,1570646533.674 [Startup:StartupSatComms:A](INFO): Timed out from 2019-10-09T18:41:13.5Z
2019-10-09T18:42:13.674Z,1570646533.674 [Startup:StartupSatComms:A] Stopped
2019-10-09T18:42:13.674Z,1570646533.674 [Startup:StartupSatComms:B] Running Loop=1
2019-10-09T18:42:14.096Z,1570646534.096 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-10-09T18:42:18.125Z,1570646538.125 [BPC1](ERROR): BPC1A: No match for serial number 1717 in BPC1A's battery stick inventory (sticks 1-32 in onboard configuration file).
2019-10-09T18:42:20.526Z,1570646540.526 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.005723
2019-10-09T18:42:25.097Z,1570646545.097 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 6E279E5D070001005D00000400540100000000000000151D80030C5669BF3148E2FA22188B6A3173E5573572661C0BCBDFFAB423BD32D9A51E8904B0A920CE9A
2019-10-09T18:42:25.097Z,1570646545.097 [Micromodem](INFO): Outgoing frame #2, 64 bytes: EF9F66414C9B9FD6761AD008C3E3DC4FCA2E4E25B6359B14B772C1B5A41FFEA2E08EE2095E27AF9ECCD31317F58171CA369D3F7A35F82AC3A34630791EE173FE
2019-10-09T18:42:25.098Z,1570646545.098 [Micromodem](INFO): Outgoing frame #3, 64 bytes: 73DB8C9C5BF1BB9DBA647994150F3CFDDAFC9A821B1CBCCB1FE2C02BD049A938EF238AA46212AD838F12B3D60F029F0E45CF203BD565DEFC31907B67392AA48F
2019-10-09T18:42:25.098Z,1570646545.098 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,3*53

2019-10-09T18:42:25.347Z,1570646545.347 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,3*51

2019-10-09T18:42:25.703Z,1570646545.703 [Micromodem](INFO): Nmea in: $CADRQ,184225,1,9,0,64,1*46

2019-10-09T18:42:25.703Z,1570646545.703 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,6E279E5D070001005D00000400540100000000000000151D80030C5669BF3148E2FA22188B6A3173E5573572661C0BCBDFFAB423BD32D9A51E8904B0A920CE9A*0F

2019-10-09T18:42:26.107Z,1570646546.107 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71

2019-10-09T18:42:26.520Z,1570646546.520 [Micromodem](INFO): Nmea in: $CADRQ,184226,1,9,0,64,2*46

2019-10-09T18:42:26.520Z,1570646546.520 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,EF9F66414C9B9FD6761AD008C3E3DC4FCA2E4E25B6359B14B772C1B5A41FFEA2E08EE2095E27AF9ECCD31317F58171CA369D3F7A35F82AC3A34630791EE173FE*70

2019-10-09T18:42:26.919Z,1570646546.919 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71

2019-10-09T18:42:27.327Z,1570646547.327 [Micromodem](INFO): Nmea in: $CADRQ,184226,1,9,0,64,3*47

2019-10-09T18:42:27.327Z,1570646547.327 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,73DB8C9C5BF1BB9DBA647994150F3CFDDAFC9A821B1CBCCB1FE2C02BD049A938EF238AA46212AD838F12B3D60F029F0E45CF203BD565DEFC31907B67392AA48F*7C

2019-10-09T18:42:27.727Z,1570646547.727 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71

2019-10-09T18:42:28.126Z,1570646548.126 [Micromodem](INFO): Nmea in: $CATXP,192*48

2019-10-09T18:42:30.436Z,1570646550.436 [DataOverHttps](INFO): Sending 188 bytes from file Logs/20191009T183110/Courier0007.lzma
2019-10-09T18:42:31.767Z,1570646551.767 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2019-10-09T18:42:32.172Z,1570646552.172 [Micromodem](INFO): Nmea in: $CAXST,6,20191009,184227.754298,3,0,200,4000,10000,1,1,9,1,3,3,3,192*4D

2019-10-09T18:42:32.442Z,1570646552.442 [DataOverHttps](INFO): Moved sent file to Logs/20191009T183110/Courier0007.lzma.bak
2019-10-09T18:42:32.442Z,1570646552.442 [DataOverHttps](INFO): SBD MOMSN=11865064
2019-10-09T18:42:42.672Z,1570646562.672 [NAL9602](INFO): SBD MO Status=2, MOMSN=9088, MT Status=2, MTMSN=0
2019-10-09T18:42:42.672Z,1570646562.672 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-10-09T18:42:51.242Z,1570646571.242 [DeadReckonUsingMultipleVelocitySources] Software Fault, FailCount= 1
2019-10-09T18:42:51.242Z,1570646571.242 [DeadReckonUsingMultipleVelocitySources](ERROR): Software Fault
2019-10-09T18:42:51.269Z,1570646571.269 [CBIT](ERROR): Software Fault in component: DeadReckonUsingMultipleVelocitySources
2019-10-09T18:42:51.641Z,1570646571.641 [CBIT](INFO): Clearing failed state for component DeadReckonUsingMultipleVelocitySources
2019-10-09T18:42:51.641Z,1570646571.641 [DeadReckonUsingMultipleVelocitySources] No Fault, FailCount= 1
2019-10-09T18:42:55.657Z,1570646575.657 [DataOverHttps](INFO): Sending 280 bytes from file Logs/20191009T183110/Express0008.lzma
2019-10-09T18:42:57.662Z,1570646577.662 [DataOverHttps](INFO): Moved sent file to Logs/20191009T183110/Express0008.lzma.bak
2019-10-09T18:42:57.662Z,1570646577.662 [DataOverHttps](INFO): SBD MOMSN=11865112
2019-10-09T18:43:13.841Z,1570646593.841 [Startup:StartupSatComms:B](INFO): Timed out from 2019-10-09T18:42:13.7Z
2019-10-09T18:43:13.841Z,1570646593.841 [Startup:StartupSatComms:B] Stopped
2019-10-09T18:43:13.841Z,1570646593.841 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2019-10-09T18:43:13.841Z,1570646593.841 [Startup:StartupSatComms] Stopped
2019-10-09T18:43:13.841Z,1570646593.841 [Startup:StartupSatComms](DEBUG): Aggregate::uninitialize Startup:StartupSatComms
2019-10-09T18:43:13.842Z,1570646593.842 [Startup](INFO): Completed Startup
2019-10-09T18:43:13.842Z,1570646593.842 [MissionManager](INFO): Startup is completed.
2019-10-09T18:43:13.842Z,1570646593.842 [MissionManager](INFO): Uninitializing Mission Startup
2019-10-09T18:43:13.842Z,1570646593.842 [Startup] Stopped
2019-10-09T18:43:13.843Z,1570646593.843 [Startup](DEBUG): Aggregate::uninitialize Startup
2019-10-09T18:43:13.843Z,1570646593.843 [Startup:A.GoToSurface] Stopped
2019-10-09T18:43:13.843Z,1570646593.843 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-10-09T18:43:14.223Z,1570646594.223 [MissionManager](IMPORTANT): Started mission Default
2019-10-09T18:43:14.223Z,1570646594.223 [Default] Running Loop=1
2019-10-09T18:43:14.223Z,1570646594.223 [Default](DEBUG): Aggregate::initialize Default
2019-10-09T18:43:14.223Z,1570646594.223 [Default:B.GoToSurface] Running Loop=1
2019-10-09T18:43:14.223Z,1570646594.223 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-10-09T18:43:14.224Z,1570646594.224 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-10-09T18:43:14.224Z,1570646594.224 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-10-09T18:43:14.224Z,1570646594.224 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-10-09T18:43:14.225Z,1570646594.225 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-10-09T18:43:14.225Z,1570646594.225 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-10-09T18:43:14.226Z,1570646594.226 [Default:A.Wait] Running Loop=1
2019-10-09T18:43:14.226Z,1570646594.226 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-10-09T18:43:19.189Z,1570646599.189 [DataOverHttps](INFO): Sending 686 bytes from file Logs/20191009T183941/Express0001.lzma
2019-10-09T18:43:19.436Z,1570646599.436 [NAL9602](INFO): SBD MO Status=0, MOMSN=9088, MT Status=0, MTMSN=0
2019-10-09T18:43:19.436Z,1570646599.436 [NAL9602](INFO): No messages in MT queue
2019-10-09T18:43:20.261Z,1570646600.261 [NAL9602](DEBUG): Fix Requested
2019-10-09T18:43:21.194Z,1570646601.194 [DataOverHttps](INFO): Moved sent file to Logs/20191009T183941/Express0001.lzma.bak
2019-10-09T18:43:21.194Z,1570646601.194 [DataOverHttps](INFO): SBD MOMSN=11865124
2019-10-09T18:43:22.681Z,1570646602.681 [NAL9602](DEBUG): Fix Requested
2019-10-09T18:43:25.509Z,1570646605.509 [NAL9602](DEBUG): Fix Requested
2019-10-09T18:43:27.567Z,1570646607.567 [Default:A.Wait](INFO): Done Waiting.
2019-10-09T18:43:27.567Z,1570646607.567 [Default:A.Wait] Stopped
2019-10-09T18:43:27.567Z,1570646607.567 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-10-09T18:43:27.986Z,1570646607.986 [Default:CheckIn] Running Loop=1
2019-10-09T18:43:27.987Z,1570646607.987 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-09T18:43:27.987Z,1570646607.987 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-09T18:43:28.363Z,1570646608.363 [Default:CheckIn:Read_GPS](DEBUG): Initialize ReadDataComponent to sense time_fix
2019-10-09T18:43:28.741Z,1570646608.741 [NAL9602](DEBUG): Fix Requested
2019-10-09T18:43:31.498Z,1570646611.498 [CommandLine](IMPORTANT): got command show variable range
2019-10-09T18:43:31.500Z,1570646611.500 [CommandLine](IMPORTANT): acoustic_contact_range (unknown)
2019-10-09T18:43:31.573Z,1570646611.573 [NAL9602](DEBUG): Fix Requested
2019-10-09T18:43:31.662Z,1570646611.662 [CommandLine](IMPORTANT): BR_Ping1D.minRange (meter)
2019-10-09T18:43:31.662Z,1570646611.662 [CommandLine](IMPORTANT): BR_Ping1D.maxRange (meter)
2019-10-09T18:43:31.693Z,1570646611.693 [CommandLine](IMPORTANT): DUSBL_Hydroid.acoustic_contact_range (meter)
2019-10-09T18:43:31.694Z,1570646611.694 [CommandLine](IMPORTANT): Micromodem.range_request (count)
2019-10-09T18:43:31.695Z,1570646611.695 [CommandLine](IMPORTANT): Micromodem.range (meter)
2019-10-09T18:43:31.698Z,1570646611.698 [CommandLine](IMPORTANT): RDI_Pathfinder.Beam1Range (meter)
2019-10-09T18:43:31.698Z,1570646611.698 [CommandLine](IMPORTANT): RDI_Pathfinder.Beam2Range (meter)
2019-10-09T18:43:31.699Z,1570646611.699 [CommandLine](IMPORTANT): RDI_Pathfinder.Beam3Range (meter)
2019-10-09T18:43:31.699Z,1570646611.699 [CommandLine](IMPORTANT): RDI_Pathfinder.Beam4Range (meter)
2019-10-09T18:43:34.397Z,1570646614.397 [NAL9602](DEBUG): Fix Requested
2019-10-09T18:43:37.641Z,1570646617.641 [NAL9602](DEBUG): Fix Requested
2019-10-09T18:43:39.427Z,1570646619.427 [CommandLine](IMPORTANT): got command report touch DUSBL_Hydroid.acoustic_contact_range
2019-10-09T18:43:40.461Z,1570646620.461 [NAL9602](DEBUG): Fix Requested
2019-10-09T18:43:43.693Z,1570646623.693 [NAL9602](DEBUG): Fix Requested
2019-10-09T18:43:46.513Z,1570646626.513 [NAL9602](DEBUG): Fix Requested
2019-10-09T18:43:49.753Z,1570646629.753 [NAL9602](DEBUG): Fix Requested
2019-10-09T18:43:51.666Z,1570646631.666 [CommandLine](IMPORTANT): got command run ./Missions/Maintenance/DUSBL_Tracking.xml
2019-10-09T18:43:51.667Z,1570646631.667 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/DUSBL_Tracking.xml
2019-10-09T18:43:51.714Z,1570646631.714 [MissionManager](INFO): DefineArg DUSBL.MissionTimeout = 10.000000 min
2019-10-09T18:43:51.726Z,1570646631.726 [MissionManager](INFO): DefineArg DUSBL.TransponderCode = 2.000000 count
2019-10-09T18:43:51.759Z,1570646631.759 [MissionManager](INFO): DefineArg DUSBL.TrackingUpdatePeriod = 4.000000 s
2019-10-09T18:43:51.776Z,1570646631.776 [MissionManager](INFO): DefineArg DUSBL.NumberOfPings = 1.000000 count
2019-10-09T18:43:51.801Z,1570646631.801 [MissionManager](INFO): DefineArg DUSBL.EnabledDUSBL = DUSBL_Hydroid.loadAtStartup
2019-10-09T18:43:51.812Z,1570646631.812 [DUSBL:A.Pitch](DEBUG): Construct.
2019-10-09T18:43:51.861Z,1570646631.861 [DUSBL:B.SetSpeed](DEBUG): Construct.
2019-10-09T18:43:52.117Z,1570646632.117 [DUSBL:RequestRepeater:A.Wait](DEBUG): Construct Wait.
2019-10-09T18:43:52.125Z,1570646632.125 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Control="Tethys/Control"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd"
       Id="DUSBL">

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

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

    <DefineArg Name="TransponderCode"><Description>
        Transponder Address.
    </Description><Units:count/><Value>2</Value></DefineArg>
    
    <DefineArg Name="TrackingUpdatePeriod"><Description>
        How long to wait between acoustic queries
    </Description><Units:second/><Value>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-10-09T18:43:52.127Z,1570646632.127 [CommandLine](IMPORTANT): Running ./Missions/Maintenance/DUSBL_Tracking.xml
2019-10-09T18:43:52.197Z,1570646632.197 [Default] Stopped
2019-10-09T18:43:52.197Z,1570646632.197 [Default](DEBUG): Aggregate::uninitialize Default
2019-10-09T18:43:52.197Z,1570646632.197 [Default:B.GoToSurface] Stopped
2019-10-09T18:43:52.197Z,1570646632.197 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-10-09T18:43:52.197Z,1570646632.197 [Default:CheckIn] Stopped
2019-10-09T18:43:52.197Z,1570646632.197 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-09T18:43:52.197Z,1570646632.197 [Default:CheckIn:Read_GPS] Stopped
2019-10-09T18:43:52.198Z,1570646632.198 [MissionManager](IMPORTANT): Started mission DUSBL
2019-10-09T18:43:52.198Z,1570646632.198 [DUSBL] Running Loop=1
2019-10-09T18:43:52.198Z,1570646632.198 [DUSBL](DEBUG): Aggregate::initialize DUSBL
2019-10-09T18:43:52.198Z,1570646632.198 [DUSBL:A.Pitch] Running Loop=1
2019-10-09T18:43:52.198Z,1570646632.198 [DUSBL:A.Pitch](DEBUG): Initialize.
2019-10-09T18:43:52.198Z,1570646632.198 [DUSBL:B.SetSpeed] Running Loop=1
2019-10-09T18:43:52.198Z,1570646632.198 [DUSBL:B.SetSpeed](DEBUG): Initialize.
2019-10-09T18:43:52.199Z,1570646632.199 [DUSBL:C.] Running Loop=1
2019-10-09T18:43:52.199Z,1570646632.199 [DUSBL:C.](INFO): Initializing TrackAcousticContact.
2019-10-09T18:43:52.199Z,1570646632.199 [DUSBL:RequestRepeater] Running Loop=1
2019-10-09T18:43:52.199Z,1570646632.199 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-10-09T18:43:52.199Z,1570646632.199 [DUSBL:RequestRepeater:A.Wait] Running Loop=1
2019-10-09T18:43:52.199Z,1570646632.199 [DUSBL:RequestRepeater:A.Wait](DEBUG): Initialize Wait Component.
2019-10-09T18:43:52.200Z,1570646632.200 [DUSBL:C.] Running Loop=1
2019-10-09T18:43:52.200Z,1570646632.200 [DUSBL:B.SetSpeed] Running Loop=1
2019-10-09T18:43:52.205Z,1570646632.205 [DUSBL:A.Pitch] Running Loop=1
2019-10-09T18:43:52.577Z,1570646632.577 [NAL9602](DEBUG): Fix Requested
2019-10-09T18:43:52.969Z,1570646632.969 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,183746.00,A,3648.15725,N,12147.28957,W,0.156,0.00,091019,,,D*79
2019-10-09T18:43:52.972Z,1570646632.972 [NAL9602](INFO): GPS fix at 20191009T183746: (36.802621, -121.788160)
2019-10-09T18:43:55.866Z,1570646635.866 [CommandLine](IMPORTANT): got command get depth
2019-10-09T18:43:55.867Z,1570646635.867 [CommandLine](IMPORTANT): depth 0.204534 m
2019-10-09T18:43:56.236Z,1570646636.236 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:43:57.011Z,1570646637.011 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:43:57.417Z,1570646637.417 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:43:58.227Z,1570646638.227 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184357.39*58

2019-10-09T18:44:00.294Z,1570646640.294 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:44:00.646Z,1570646640.646 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:44:04.314Z,1570646644.314 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:44:05.091Z,1570646645.091 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:44:05.574Z,1570646645.574 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:44:06.319Z,1570646646.319 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184405.47*51

2019-10-09T18:44:08.385Z,1570646648.385 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:44:08.750Z,1570646648.750 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:44:12.445Z,1570646652.445 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:44:13.188Z,1570646653.188 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:44:13.592Z,1570646653.592 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:44:14.400Z,1570646654.400 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184413.56*56

2019-10-09T18:44:16.470Z,1570646656.470 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:44:16.831Z,1570646656.831 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:44:20.497Z,1570646660.497 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:44:21.271Z,1570646661.271 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:44:21.673Z,1570646661.673 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:44:22.483Z,1570646662.483 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184421.65*57

2019-10-09T18:44:24.542Z,1570646664.542 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:44:24.902Z,1570646664.902 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:44:25.311Z,1570646665.311 [NAL9602](INFO): Not Powering down - fast GPS
2019-10-09T18:44:28.564Z,1570646668.564 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:44:29.351Z,1570646669.351 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:44:29.757Z,1570646669.757 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:44:30.562Z,1570646670.562 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184429.73*58

2019-10-09T18:44:32.623Z,1570646672.623 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:44:32.986Z,1570646672.986 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:44:36.664Z,1570646676.664 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:44:37.427Z,1570646677.427 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:44:37.841Z,1570646677.841 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:44:38.648Z,1570646678.648 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184437.81*5A

2019-10-09T18:44:40.700Z,1570646680.700 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:44:41.066Z,1570646681.066 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:44:44.732Z,1570646684.732 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:44:45.529Z,1570646685.529 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:44:45.917Z,1570646685.917 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:44:46.739Z,1570646686.739 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184445.91*5E

2019-10-09T18:44:48.780Z,1570646688.780 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:44:49.142Z,1570646689.142 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:44:52.849Z,1570646692.849 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:44:53.591Z,1570646693.591 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:44:53.993Z,1570646693.993 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:44:54.830Z,1570646694.830 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184453.97*5F

2019-10-09T18:44:56.880Z,1570646696.880 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:44:57.228Z,1570646697.228 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:45:00.902Z,1570646700.902 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:45:01.671Z,1570646701.671 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:45:02.073Z,1570646702.073 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:45:02.881Z,1570646702.881 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184502.05*51

2019-10-09T18:45:04.936Z,1570646704.936 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:45:05.311Z,1570646705.311 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:45:08.983Z,1570646708.983 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:45:09.748Z,1570646709.748 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:45:10.157Z,1570646710.157 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:45:10.963Z,1570646710.963 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184510.13*55

2019-10-09T18:45:13.048Z,1570646713.048 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:45:13.382Z,1570646713.382 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:45:17.067Z,1570646717.067 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:45:17.843Z,1570646717.843 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:45:18.237Z,1570646718.237 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:45:19.045Z,1570646719.045 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184518.22*5F

2019-10-09T18:45:21.109Z,1570646721.109 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:45:21.459Z,1570646721.459 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:45:25.145Z,1570646725.145 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:45:25.906Z,1570646725.906 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:45:26.317Z,1570646726.317 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:45:27.121Z,1570646727.121 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184526.28*58

2019-10-09T18:45:29.189Z,1570646729.189 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:45:29.546Z,1570646729.546 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:45:33.234Z,1570646733.234 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:45:33.985Z,1570646733.985 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:45:34.397Z,1570646734.397 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:45:35.201Z,1570646735.201 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184534.36*54

2019-10-09T18:45:37.258Z,1570646737.258 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:45:37.626Z,1570646737.626 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:45:41.303Z,1570646741.303 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:45:42.076Z,1570646742.076 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:45:42.471Z,1570646742.471 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:45:43.283Z,1570646743.283 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184542.45*51

2019-10-09T18:45:45.335Z,1570646745.335 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:45:45.706Z,1570646745.706 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:45:49.381Z,1570646749.381 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:45:50.151Z,1570646750.151 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:45:50.557Z,1570646750.557 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:45:51.361Z,1570646751.361 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184550.53*55

2019-10-09T18:45:53.448Z,1570646753.448 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:45:53.783Z,1570646753.783 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:45:57.461Z,1570646757.461 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:45:58.243Z,1570646758.243 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:45:58.643Z,1570646758.643 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:45:59.447Z,1570646759.447 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184558.62*5F

2019-10-09T18:46:01.482Z,1570646761.482 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:46:01.866Z,1570646761.866 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:46:05.539Z,1570646765.539 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:46:06.311Z,1570646766.311 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:46:06.725Z,1570646766.725 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:46:07.520Z,1570646767.520 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184606.69*5C

2019-10-09T18:46:09.570Z,1570646769.570 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:46:09.951Z,1570646769.951 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:46:13.641Z,1570646773.641 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:46:14.403Z,1570646774.403 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:46:14.830Z,1570646774.830 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:46:15.611Z,1570646775.611 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184614.78*5F

2019-10-09T18:46:17.663Z,1570646777.663 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:46:18.026Z,1570646778.026 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:46:21.698Z,1570646781.698 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:46:22.471Z,1570646782.471 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:46:22.873Z,1570646782.873 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:46:23.683Z,1570646783.683 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184622.85*58

2019-10-09T18:46:25.765Z,1570646785.765 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:46:26.116Z,1570646786.116 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:46:29.846Z,1570646789.846 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:46:30.553Z,1570646790.553 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:46:30.953Z,1570646790.953 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:46:31.807Z,1570646791.807 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184630.93*5C

2019-10-09T18:46:33.818Z,1570646793.818 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:46:33.908Z,1570646793.908 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:46:34.603Z,1570646794.603 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:46:35.018Z,1570646795.018 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:46:35.833Z,1570646795.833 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184634.98*53

2019-10-09T18:46:37.919Z,1570646797.919 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:46:38.258Z,1570646798.258 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:46:42.337Z,1570646802.337 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:46:43.088Z,1570646803.088 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:46:43.491Z,1570646803.491 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:46:44.300Z,1570646804.300 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184643.46*50

2019-10-09T18:46:46.427Z,1570646806.427 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:46:46.732Z,1570646806.732 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:46:50.855Z,1570646810.855 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:46:51.575Z,1570646811.575 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:46:51.987Z,1570646811.987 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:46:52.835Z,1570646812.835 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184651.95*5D

2019-10-09T18:46:54.833Z,1570646814.833 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:46:54.869Z,1570646814.869 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:46:55.619Z,1570646815.619 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:46:56.021Z,1570646816.021 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:46:56.867Z,1570646816.867 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184656.00*56

2019-10-09T18:46:58.917Z,1570646818.917 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:46:59.246Z,1570646819.246 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:47:02.976Z,1570646822.976 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:47:03.695Z,1570646823.695 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:47:04.101Z,1570646824.101 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:47:04.155Z,1570646824.155 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for BIT error
2019-10-09T18:47:04.156Z,1570646824.156 [RDI_Pathfinder](ERROR): Failed to parse:
:TS,19100911522344,35.0  0.0,1448.9,  0
2019-10-09T18:47:04.905Z,1570646824.905 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184704.07*57

2019-10-09T18:47:06.997Z,1570646826.997 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:47:07.345Z,1570646827.345 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:47:11.022Z,1570646831.022 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:47:11.774Z,1570646831.774 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:47:12.181Z,1570646832.181 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:47:12.986Z,1570646832.986 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184712.15*53

2019-10-09T18:47:15.050Z,1570646835.050 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:47:15.410Z,1570646835.410 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:47:19.089Z,1570646839.089 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:47:19.849Z,1570646839.849 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:47:20.261Z,1570646840.261 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:47:21.064Z,1570646841.064 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184720.23*57

2019-10-09T18:47:23.131Z,1570646843.131 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:47:23.490Z,1570646843.490 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:47:27.193Z,1570646847.193 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:47:27.929Z,1570646847.929 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:47:28.341Z,1570646848.341 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:47:29.154Z,1570646849.154 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184728.31*5C

2019-10-09T18:47:31.208Z,1570646851.208 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:47:31.570Z,1570646851.570 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:47:35.252Z,1570646855.252 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:47:36.011Z,1570646856.011 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:47:36.421Z,1570646856.421 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:47:37.232Z,1570646857.232 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184736.39*5B

2019-10-09T18:47:39.347Z,1570646859.347 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:47:39.643Z,1570646859.643 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:47:43.724Z,1570646863.724 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:47:44.499Z,1570646864.499 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:47:44.902Z,1570646864.902 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:47:45.712Z,1570646865.712 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184744.88*54

2019-10-09T18:47:47.757Z,1570646867.757 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:47:48.130Z,1570646868.130 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:47:51.830Z,1570646871.830 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:47:52.575Z,1570646872.575 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:47:52.990Z,1570646872.990 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:47:53.800Z,1570646873.800 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184752.95*5F

2019-10-09T18:47:55.830Z,1570646875.830 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:47:55.883Z,1570646875.883 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:47:56.619Z,1570646876.619 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:47:57.033Z,1570646877.033 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:47:57.844Z,1570646877.844 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184756.99*57

2019-10-09T18:47:59.893Z,1570646879.893 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:48:00.254Z,1570646880.254 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:48:03.919Z,1570646883.919 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:48:04.693Z,1570646884.693 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:48:05.105Z,1570646885.105 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:48:05.908Z,1570646885.908 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184805.07*59

2019-10-09T18:48:07.977Z,1570646887.977 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:48:08.330Z,1570646888.330 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:48:12.017Z,1570646892.017 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:48:12.794Z,1570646892.794 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:48:12.877Z,1570646892.877 [RDI_Pathfinder](ERROR): only read 0 of 1 data item for altitude
2019-10-09T18:48:12.877Z,1570646892.877 [RDI_Pathfinder](ERROR): Failed to parse:
:BD,        +3.07,        +5.93,    ,   9.38,  0.00
2019-10-09T18:48:13.181Z,1570646893.181 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:48:13.992Z,1570646893.992 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184813.18*50

2019-10-09T18:48:16.057Z,1570646896.057 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:48:16.410Z,1570646896.410 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:48:19.290Z,1570646899.290 [BPC1](INFO): Calculating totals. Valid battery stick count: 56. Valid reserve battery stick count: 6.
2019-10-09T18:48:19.293Z,1570646899.293 [BPC1](INFO): Received data from all battery sticks.
2019-10-09T18:48:20.091Z,1570646900.091 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:48:20.859Z,1570646900.859 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:48:21.269Z,1570646901.269 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:48:22.075Z,1570646902.075 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184821.24*5E

2019-10-09T18:48:24.137Z,1570646904.137 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:48:24.494Z,1570646904.494 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:48:28.166Z,1570646908.166 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:48:28.933Z,1570646908.933 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:48:29.345Z,1570646909.345 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:48:30.148Z,1570646910.148 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184829.31*52

2019-10-09T18:48:32.210Z,1570646912.210 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:48:32.574Z,1570646912.574 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:48:36.235Z,1570646916.235 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:48:37.019Z,1570646917.019 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:48:37.430Z,1570646917.430 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:48:38.229Z,1570646918.229 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184837.40*5B

2019-10-09T18:48:40.275Z,1570646920.275 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:48:40.660Z,1570646920.660 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:48:44.336Z,1570646924.336 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:48:45.095Z,1570646925.095 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:48:45.501Z,1570646925.501 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:48:46.311Z,1570646926.311 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184845.47*59

2019-10-09T18:48:48.361Z,1570646928.361 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:48:48.734Z,1570646928.734 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:48:52.420Z,1570646932.420 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:48:53.194Z,1570646933.194 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:48:53.595Z,1570646933.595 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:48:54.407Z,1570646934.407 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184853.57*5F

2019-10-09T18:48:56.431Z,1570646936.431 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:48:56.830Z,1570646936.830 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:49:00.483Z,1570646940.483 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:49:01.256Z,1570646941.256 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:49:01.659Z,1570646941.659 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:49:02.468Z,1570646942.468 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184901.64*59

2019-10-09T18:49:04.511Z,1570646944.511 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:49:04.890Z,1570646944.890 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:49:08.569Z,1570646948.569 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:49:09.335Z,1570646949.335 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:49:09.739Z,1570646949.739 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:49:10.555Z,1570646950.555 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184909.71*55

2019-10-09T18:49:12.598Z,1570646952.598 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:49:12.967Z,1570646952.967 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:49:16.641Z,1570646956.641 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:49:17.419Z,1570646957.419 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:49:17.837Z,1570646957.837 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:49:18.629Z,1570646958.629 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184917.80*54

2019-10-09T18:49:20.717Z,1570646960.717 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:49:21.050Z,1570646961.050 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:49:24.722Z,1570646964.722 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:49:25.495Z,1570646965.495 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:49:25.903Z,1570646965.903 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:49:26.709Z,1570646966.709 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184925.87*52

2019-10-09T18:49:28.795Z,1570646968.795 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:49:29.128Z,1570646969.128 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:49:32.842Z,1570646972.842 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:49:33.579Z,1570646973.579 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:49:33.991Z,1570646973.991 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:49:34.801Z,1570646974.801 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184933.96*55

2019-10-09T18:49:36.830Z,1570646976.830 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:49:36.887Z,1570646976.887 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:49:37.615Z,1570646977.615 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:49:38.019Z,1570646978.019 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:49:38.836Z,1570646978.836 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184937.99*5E

2019-10-09T18:49:40.913Z,1570646980.913 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:49:41.260Z,1570646981.260 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:49:44.932Z,1570646984.932 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:49:45.694Z,1570646985.694 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:49:46.099Z,1570646986.099 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:49:46.907Z,1570646986.907 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184946.07*5F

2019-10-09T18:49:48.963Z,1570646988.963 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:49:49.339Z,1570646989.339 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:49:53.010Z,1570646993.010 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:49:53.801Z,1570646993.801 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:49:54.191Z,1570646994.191 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:49:54.991Z,1570646994.991 [Micromodem](INFO): Nmea in: $SNTTA,,,,,184954.18*52

2019-10-09T18:49:57.085Z,1570646997.085 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:49:57.410Z,1570646997.410 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:50:01.094Z,1570647001.094 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:50:01.853Z,1570647001.853 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:50:02.265Z,1570647002.265 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:50:03.071Z,1570647003.071 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185002.23*51

2019-10-09T18:50:05.135Z,1570647005.135 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:50:05.506Z,1570647005.506 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:50:09.160Z,1570647009.160 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:50:09.939Z,1570647009.939 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:50:10.345Z,1570647010.345 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:50:11.151Z,1570647011.151 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185010.32*52

2019-10-09T18:50:13.216Z,1570647013.216 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:50:13.574Z,1570647013.574 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:50:17.257Z,1570647017.257 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:50:18.013Z,1570647018.013 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:50:18.425Z,1570647018.425 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:50:19.227Z,1570647019.227 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185018.39*51

2019-10-09T18:50:21.292Z,1570647021.292 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:50:21.654Z,1570647021.654 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:50:25.320Z,1570647025.320 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:50:26.099Z,1570647026.099 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:50:26.505Z,1570647026.505 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:50:27.309Z,1570647027.309 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185026.48*5A

2019-10-09T18:50:29.351Z,1570647029.351 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:50:29.740Z,1570647029.740 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:50:33.413Z,1570647033.413 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:50:34.175Z,1570647034.175 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:50:34.581Z,1570647034.581 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:50:35.388Z,1570647035.388 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185034.55*55

2019-10-09T18:50:37.443Z,1570647037.443 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:50:37.834Z,1570647037.834 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:50:41.478Z,1570647041.478 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:50:42.258Z,1570647042.258 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:50:42.662Z,1570647042.662 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:50:43.469Z,1570647043.469 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185042.63*51

2019-10-09T18:50:45.523Z,1570647045.523 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:50:45.894Z,1570647045.894 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:50:49.585Z,1570647049.585 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:50:50.333Z,1570647050.333 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:50:50.745Z,1570647050.745 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:50:51.550Z,1570647051.550 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185050.71*51

2019-10-09T18:50:53.602Z,1570647053.602 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:50:53.974Z,1570647053.974 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:50:57.629Z,1570647057.629 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:50:58.421Z,1570647058.421 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:50:58.839Z,1570647058.839 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:50:59.629Z,1570647059.629 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185058.80*57

2019-10-09T18:51:01.682Z,1570647061.682 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:51:02.054Z,1570647062.054 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:51:05.741Z,1570647065.741 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:51:06.495Z,1570647066.495 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:51:06.901Z,1570647066.901 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:51:07.711Z,1570647067.711 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185106.87*5A

2019-10-09T18:51:09.834Z,1570647069.834 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:51:10.135Z,1570647070.135 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:51:13.834Z,1570647073.834 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:51:14.591Z,1570647074.591 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:51:14.979Z,1570647074.979 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:51:15.816Z,1570647075.816 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185114.97*58

2019-10-09T18:51:17.886Z,1570647077.886 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:51:18.212Z,1570647078.212 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:51:22.284Z,1570647082.284 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:51:23.066Z,1570647083.066 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:51:23.469Z,1570647083.469 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:51:24.282Z,1570647084.282 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185123.44*52

2019-10-09T18:51:26.328Z,1570647086.328 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:51:26.692Z,1570647086.692 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:51:30.355Z,1570647090.355 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:51:31.143Z,1570647091.143 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:51:31.557Z,1570647091.557 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:51:32.352Z,1570647092.352 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185131.52*56

2019-10-09T18:51:34.434Z,1570647094.434 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:51:34.790Z,1570647094.790 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:51:38.446Z,1570647098.446 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:51:39.223Z,1570647099.223 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:51:39.645Z,1570647099.645 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:51:40.433Z,1570647100.433 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185139.60*5F

2019-10-09T18:51:42.520Z,1570647102.520 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:51:42.854Z,1570647102.854 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:51:46.529Z,1570647106.529 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:51:47.300Z,1570647107.300 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:51:47.715Z,1570647107.715 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:51:48.512Z,1570647108.512 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185147.68*5E

2019-10-09T18:51:50.565Z,1570647110.565 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:51:50.957Z,1570647110.957 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:51:54.629Z,1570647114.629 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:51:55.383Z,1570647115.383 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:51:55.818Z,1570647115.818 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:51:56.600Z,1570647116.600 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185155.76*52

2019-10-09T18:51:58.646Z,1570647118.646 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:51:59.018Z,1570647119.018 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:52:02.674Z,1570647122.674 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:52:03.463Z,1570647123.463 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:52:03.869Z,1570647123.869 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:52:04.675Z,1570647124.675 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185203.84*5F

2019-10-09T18:52:06.728Z,1570647126.728 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:52:07.092Z,1570647127.092 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:52:10.773Z,1570647130.773 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:52:11.543Z,1570647131.543 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:52:11.949Z,1570647131.949 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:52:12.764Z,1570647132.764 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185211.92*5B

2019-10-09T18:52:14.835Z,1570647134.835 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:52:15.172Z,1570647135.172 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:52:18.956Z,1570647138.956 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:52:19.623Z,1570647139.623 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:52:20.025Z,1570647140.025 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:52:20.847Z,1570647140.847 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185220.00*52

2019-10-09T18:52:23.263Z,1570647143.263 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:52:23.293Z,1570647143.293 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:52:24.071Z,1570647144.071 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:52:24.469Z,1570647144.469 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:52:25.280Z,1570647145.280 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185224.45*57

2019-10-09T18:52:27.331Z,1570647147.331 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:52:27.710Z,1570647147.710 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:52:31.379Z,1570647151.379 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:52:32.143Z,1570647152.143 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:52:32.553Z,1570647152.553 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:52:33.359Z,1570647153.359 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185232.52*56

2019-10-09T18:52:35.415Z,1570647155.415 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:52:35.780Z,1570647155.780 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:52:39.459Z,1570647159.459 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:52:40.233Z,1570647160.233 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:52:40.652Z,1570647160.652 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:52:41.439Z,1570647161.439 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185240.61*53

2019-10-09T18:52:43.484Z,1570647163.484 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:52:43.862Z,1570647163.862 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:52:47.537Z,1570647167.537 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:52:48.313Z,1570647168.313 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:52:48.713Z,1570647168.713 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:52:49.520Z,1570647169.520 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185248.69*53

2019-10-09T18:52:51.560Z,1570647171.560 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:52:51.938Z,1570647171.938 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:52:55.624Z,1570647175.624 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:52:56.387Z,1570647176.387 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:52:56.822Z,1570647176.822 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:52:57.603Z,1570647177.603 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185256.76*52

2019-10-09T18:52:59.645Z,1570647179.645 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:53:00.016Z,1570647180.016 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:53:03.685Z,1570647183.685 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:53:04.465Z,1570647184.465 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:53:04.873Z,1570647184.873 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:53:05.676Z,1570647185.676 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185304.84*59

2019-10-09T18:53:07.740Z,1570647187.740 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:53:08.102Z,1570647188.102 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:53:11.775Z,1570647191.775 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:53:12.547Z,1570647192.547 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:53:12.949Z,1570647192.949 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:53:13.757Z,1570647193.757 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185312.93*58

2019-10-09T18:53:15.838Z,1570647195.838 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:53:16.176Z,1570647196.176 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:53:19.872Z,1570647199.872 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:53:20.621Z,1570647200.621 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:53:21.027Z,1570647201.027 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:53:21.841Z,1570647201.841 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185321.00*52

2019-10-09T18:53:23.900Z,1570647203.900 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:53:24.262Z,1570647204.262 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:53:27.945Z,1570647207.945 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:53:28.727Z,1570647208.727 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:53:29.109Z,1570647209.109 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:53:29.920Z,1570647209.920 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185329.10*5B

2019-10-09T18:53:31.993Z,1570647211.993 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:53:32.338Z,1570647212.338 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:53:36.010Z,1570647216.010 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:53:36.816Z,1570647216.816 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:53:37.193Z,1570647217.193 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:53:38.013Z,1570647218.013 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185337.20*57

2019-10-09T18:53:40.059Z,1570647220.059 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:53:40.422Z,1570647220.422 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:53:44.088Z,1570647224.088 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:53:44.867Z,1570647224.867 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:53:45.273Z,1570647225.273 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:53:46.085Z,1570647226.085 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185345.24*56

2019-10-09T18:53:48.141Z,1570647228.141 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:53:48.502Z,1570647228.502 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:53:52.175Z,1570647232.175 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:53:52.589Z,1570647232.589 [DUSBL](INFO): Timed out from 2019-10-09T18:43:52.2Z
2019-10-09T18:53:52.589Z,1570647232.589 [MissionManager](INFO): DUSBL is completed.
2019-10-09T18:53:52.590Z,1570647232.590 [MissionManager](INFO): Uninitializing Mission DUSBL
2019-10-09T18:53:52.590Z,1570647232.590 [DUSBL] Stopped
2019-10-09T18:53:52.590Z,1570647232.590 [DUSBL](DEBUG): Aggregate::uninitialize DUSBL
2019-10-09T18:53:52.590Z,1570647232.590 [DUSBL:A.Pitch] Stopped
2019-10-09T18:53:52.590Z,1570647232.590 [DUSBL:B.SetSpeed] Stopped
2019-10-09T18:53:52.590Z,1570647232.590 [DUSBL:B.SetSpeed](DEBUG): Uninitialize.
2019-10-09T18:53:52.590Z,1570647232.590 [DUSBL:C.] Stopped
2019-10-09T18:53:52.590Z,1570647232.590 [DUSBL:RequestRepeater] Stopped
2019-10-09T18:53:52.590Z,1570647232.590 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-10-09T18:53:52.590Z,1570647232.590 [DUSBL:RequestRepeater:A.Wait] Stopped
2019-10-09T18:53:52.590Z,1570647232.590 [DUSBL:RequestRepeater:A.Wait](DEBUG): Uninitialize Wait Component.
2019-10-09T18:53:52.995Z,1570647232.995 [MissionManager](IMPORTANT): Started mission Default
2019-10-09T18:53:52.995Z,1570647232.995 [Default] Running Loop=1
2019-10-09T18:53:52.996Z,1570647232.996 [Default](DEBUG): Aggregate::initialize Default
2019-10-09T18:53:52.996Z,1570647232.996 [Default:B.GoToSurface] Running Loop=1
2019-10-09T18:53:52.996Z,1570647232.996 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-10-09T18:53:52.996Z,1570647232.996 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-10-09T18:53:53.001Z,1570647233.001 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-10-09T18:53:53.001Z,1570647233.001 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-10-09T18:53:53.001Z,1570647233.001 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-10-09T18:53:53.002Z,1570647233.002 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-10-09T18:53:53.002Z,1570647233.002 [Default:A.Wait] Running Loop=1
2019-10-09T18:53:53.002Z,1570647233.002 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-10-09T18:54:06.345Z,1570647246.345 [Default:A.Wait](INFO): Done Waiting.
2019-10-09T18:54:06.345Z,1570647246.345 [Default:A.Wait] Stopped
2019-10-09T18:54:06.345Z,1570647246.345 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-10-09T18:54:06.735Z,1570647246.735 [Default:CheckIn] Running Loop=1
2019-10-09T18:54:06.735Z,1570647246.735 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-09T18:54:06.735Z,1570647246.735 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-09T18:54:08.305Z,1570647248.305 [NAL9602](DEBUG): Fix Requested
2019-10-09T18:54:08.704Z,1570647248.704 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,184802.00,A,3648.15903,N,12147.28236,W,0.097,47.34,091019,,,D*4F
2019-10-09T18:54:08.706Z,1570647248.706 [NAL9602](INFO): GPS fix at 20191009T184802: (36.802650, -121.788039)
2019-10-09T18:54:08.757Z,1570647248.757 [Default:CheckIn:Read_GPS] Stopped
2019-10-09T18:54:08.757Z,1570647248.757 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-10-09T18:54:09.170Z,1570647249.170 [Default:CheckIn:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2019-10-09T18:54:09.506Z,1570647249.506 [Micromodem](ERROR): Buffer fill timout failure.
2019-10-09T18:54:13.426Z,1570647253.426 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 6D299E5D040001005D00000400270300000000000000151D80030C5669BF3148E2FA22188B6A3173E6960F887FF8A99E669AC91D474B0DB47F940EC581E0D567
2019-10-09T18:54:13.427Z,1570647253.427 [Micromodem](INFO): Outgoing frame #2, 64 bytes: 266CC0E6F1A517638CB0C0B8BE362003E8C77AD31073D8E3B830D84642FA50DCC30443F63ABFD6289693675E5363BC207B5C64E49FD33E0EB9BB70C222F0983B
2019-10-09T18:54:13.427Z,1570647253.427 [Micromodem](INFO): Outgoing frame #3, 64 bytes: 70E8F52F3F7A4ABAF73F268CD05998C59CA295F552AAD6B5C8634C336DFDD4D67836652BAAA84650A8AB2A00C3D59CB2F405203985B364C828DD36EB5DD8E4C4
2019-10-09T18:54:13.427Z,1570647253.427 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,3*53

2019-10-09T18:54:13.618Z,1570647253.618 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,3*51

2019-10-09T18:54:13.983Z,1570647253.983 [Micromodem](INFO): Nmea in: $CADRQ,185413,1,9,0,64,1*44

2019-10-09T18:54:13.983Z,1570647253.983 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,6D299E5D040001005D00000400270300000000000000151D80030C5669BF3148E2FA22188B6A3173E6960F887FF8A99E669AC91D474B0DB47F940EC581E0D567*7C

2019-10-09T18:54:14.382Z,1570647254.382 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71

2019-10-09T18:54:14.790Z,1570647254.790 [Micromodem](INFO): Nmea in: $CADRQ,185414,1,9,0,64,2*40

2019-10-09T18:54:14.792Z,1570647254.792 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,266CC0E6F1A517638CB0C0B8BE362003E8C77AD31073D8E3B830D84642FA50DCC30443F63ABFD6289693675E5363BC207B5C64E49FD33E0EB9BB70C222F0983B*09

2019-10-09T18:54:15.217Z,1570647255.217 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71

2019-10-09T18:54:15.594Z,1570647255.594 [Micromodem](INFO): Nmea in: $CADRQ,185415,1,9,0,64,3*40

2019-10-09T18:54:15.595Z,1570647255.595 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,70E8F52F3F7A4ABAF73F268CD05998C59CA295F552AAD6B5C8634C336DFDD4D67836652BAAA84650A8AB2A00C3D59CB2F405203985B364C828DD36EB5DD8E4C4*7E

2019-10-09T18:54:15.994Z,1570647255.994 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71

2019-10-09T18:54:16.398Z,1570647256.398 [Micromodem](INFO): Nmea in: $CATXP,192*48

2019-10-09T18:54:20.042Z,1570647260.042 [Micromodem](INFO): Nmea in: $CATXF,192*5E

2019-10-09T18:54:20.440Z,1570647260.440 [Micromodem](INFO): Nmea in: $CAXST,6,20191009,185416.019835,3,0,200,4000,10000,1,1,9,1,3,3,3,192*4B

2019-10-09T18:54:21.217Z,1570647261.217 [DataOverHttps](INFO): Sending 303 bytes from file Logs/20191009T183941/Courier0004.lzma
2019-10-09T18:54:23.238Z,1570647263.238 [DataOverHttps](INFO): Moved sent file to Logs/20191009T183941/Courier0004.lzma.bak
2019-10-09T18:54:23.238Z,1570647263.238 [DataOverHttps](INFO): SBD MOMSN=11865206
2019-10-09T18:54:45.509Z,1570647285.509 [DataOverHttps](INFO): Sending 531 bytes from file Logs/20191009T183941/Express0005.lzma
2019-10-09T18:54:47.520Z,1570647287.520 [DataOverHttps](INFO): Moved sent file to Logs/20191009T183941/Express0005.lzma.bak
2019-10-09T18:54:47.520Z,1570647287.520 [DataOverHttps](INFO): SBD MOMSN=11865209
2019-10-09T18:54:53.215Z,1570647293.215 [Default:CheckIn:Read_Iridium] Stopped
2019-10-09T18:54:53.216Z,1570647293.216 [Default:CheckIn:C.Wait] Running Loop=1
2019-10-09T18:54:53.216Z,1570647293.216 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-10-09T18:55:00.436Z,1570647300.436 [NAL9602](INFO): SBD MO Status=2, MOMSN=9089, MT Status=2, MTMSN=0
2019-10-09T18:55:00.436Z,1570647300.436 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-10-09T18:55:29.524Z,1570647329.524 [NAL9602](INFO): SBD MO Status=2, MOMSN=9089, MT Status=2, MTMSN=0
2019-10-09T18:55:29.524Z,1570647329.524 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-10-09T18:55:59.843Z,1570647359.843 [NAL9602](INFO): SBD MO Status=2, MOMSN=9089, MT Status=2, MTMSN=0
2019-10-09T18:55:59.843Z,1570647359.843 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-10-09T18:56:19.241Z,1570647379.241 [NAL9602](INFO): SBD MO Status=0, MOMSN=9089, MT Status=0, MTMSN=0
2019-10-09T18:56:19.241Z,1570647379.241 [NAL9602](INFO): No messages in MT queue
2019-10-09T18:56:49.919Z,1570647409.919 [NAL9602](INFO): Not Powering down - fast GPS
2019-10-09T18:57:12.306Z,1570647432.306 [CommandLine](IMPORTANT): got command show stack
2019-10-09T18:57:12.307Z,1570647432.307 [CommandLine](IMPORTANT): Behavior Stack:

2019-10-09T18:57:12.307Z,1570647432.307 [Default](IMPORTANT): Priority 0: Default:B.GoToSurface
2019-10-09T18:57:12.307Z,1570647432.307 [Default:CheckIn](IMPORTANT): Priority 1: Default:CheckIn:C.Wait
2019-10-09T18:57:19.498Z,1570647439.498 [CommandLine](IMPORTANT): got command show stack
2019-10-09T18:57:19.498Z,1570647439.498 [CommandLine](IMPORTANT): Behavior Stack:

2019-10-09T18:57:19.498Z,1570647439.498 [Default](IMPORTANT): Priority 0: Default:B.GoToSurface
2019-10-09T18:57:19.498Z,1570647439.498 [Default:CheckIn](IMPORTANT): Priority 1: Default:CheckIn:C.Wait
2019-10-09T18:57:23.962Z,1570647443.962 [CommandLine](IMPORTANT): got command run ./Missions/Maintenance/DUSBL_Tracking.xml
2019-10-09T18:57:23.962Z,1570647443.962 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/DUSBL_Tracking.xml
2019-10-09T18:57:23.988Z,1570647443.988 [MissionManager](INFO): DefineArg DUSBL.MissionTimeout = 10.000000 min
2019-10-09T18:57:23.990Z,1570647443.990 [MissionManager](INFO): DefineArg DUSBL.TransponderCode = 2.000000 count
2019-10-09T18:57:23.992Z,1570647443.992 [MissionManager](INFO): DefineArg DUSBL.TrackingUpdatePeriod = 4.000000 s
2019-10-09T18:57:23.994Z,1570647443.994 [MissionManager](INFO): DefineArg DUSBL.NumberOfPings = 1.000000 count
2019-10-09T18:57:23.996Z,1570647443.996 [MissionManager](INFO): DefineArg DUSBL.EnabledDUSBL = DUSBL_Hydroid.loadAtStartup
2019-10-09T18:57:23.997Z,1570647443.997 [DUSBL:A.Pitch](DEBUG): Construct.
2019-10-09T18:57:24.001Z,1570647444.001 [DUSBL:B.SetSpeed](DEBUG): Construct.
2019-10-09T18:57:24.054Z,1570647444.054 [DUSBL:RequestRepeater:A.Wait](DEBUG): Construct Wait.
2019-10-09T18:57:24.059Z,1570647444.059 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Control="Tethys/Control"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd"
       Id="DUSBL">

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

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

    <DefineArg Name="TransponderCode"><Description>
        Transponder Address.
    </Description><Units:count/><Value>2</Value></DefineArg>
    
    <DefineArg Name="TrackingUpdatePeriod"><Description>
        How long to wait between acoustic queries
    </Description><Units:second/><Value>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-10-09T18:57:24.069Z,1570647444.069 [CommandLine](IMPORTANT): Running ./Missions/Maintenance/DUSBL_Tracking.xml
2019-10-09T18:57:24.297Z,1570647444.297 [Default] Stopped
2019-10-09T18:57:24.297Z,1570647444.297 [Default](DEBUG): Aggregate::uninitialize Default
2019-10-09T18:57:24.297Z,1570647444.297 [Default:B.GoToSurface] Stopped
2019-10-09T18:57:24.297Z,1570647444.297 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-10-09T18:57:24.297Z,1570647444.297 [Default:CheckIn] Stopped
2019-10-09T18:57:24.297Z,1570647444.297 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-09T18:57:24.297Z,1570647444.297 [Default:CheckIn:C.Wait] Stopped
2019-10-09T18:57:24.297Z,1570647444.297 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-10-09T18:57:24.298Z,1570647444.298 [MissionManager](IMPORTANT): Started mission DUSBL
2019-10-09T18:57:24.298Z,1570647444.298 [DUSBL] Running Loop=1
2019-10-09T18:57:24.298Z,1570647444.298 [DUSBL](DEBUG): Aggregate::initialize DUSBL
2019-10-09T18:57:24.298Z,1570647444.298 [DUSBL:A.Pitch] Running Loop=1
2019-10-09T18:57:24.298Z,1570647444.298 [DUSBL:A.Pitch](DEBUG): Initialize.
2019-10-09T18:57:24.298Z,1570647444.298 [DUSBL:B.SetSpeed] Running Loop=1
2019-10-09T18:57:24.298Z,1570647444.298 [DUSBL:B.SetSpeed](DEBUG): Initialize.
2019-10-09T18:57:24.299Z,1570647444.299 [DUSBL:C.] Running Loop=1
2019-10-09T18:57:24.299Z,1570647444.299 [DUSBL:C.](INFO): Initializing TrackAcousticContact.
2019-10-09T18:57:24.299Z,1570647444.299 [DUSBL:RequestRepeater] Running Loop=1
2019-10-09T18:57:24.299Z,1570647444.299 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-10-09T18:57:24.299Z,1570647444.299 [DUSBL:RequestRepeater:A.Wait] Running Loop=1
2019-10-09T18:57:24.299Z,1570647444.299 [DUSBL:RequestRepeater:A.Wait](DEBUG): Initialize Wait Component.
2019-10-09T18:57:24.300Z,1570647444.300 [DUSBL:C.] Running Loop=1
2019-10-09T18:57:24.300Z,1570647444.300 [DUSBL:B.SetSpeed] Running Loop=1
2019-10-09T18:57:24.300Z,1570647444.300 [DUSBL:A.Pitch] Running Loop=1
2019-10-09T18:57:28.331Z,1570647448.331 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:57:29.119Z,1570647449.119 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:57:29.525Z,1570647449.525 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:57:30.328Z,1570647450.328 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185729.50*5B

2019-10-09T18:57:32.405Z,1570647452.405 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:57:32.750Z,1570647452.750 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:57:36.421Z,1570647456.421 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:57:37.199Z,1570647457.199 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:57:37.598Z,1570647457.598 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:57:38.419Z,1570647458.419 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185737.57*53

2019-10-09T18:57:40.463Z,1570647460.463 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:57:40.838Z,1570647460.838 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:57:44.525Z,1570647464.525 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:57:45.280Z,1570647465.280 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:57:45.687Z,1570647465.687 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:57:46.494Z,1570647466.494 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185745.67*55

2019-10-09T18:57:48.539Z,1570647468.539 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:57:48.914Z,1570647468.914 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:57:52.589Z,1570647472.589 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:57:53.359Z,1570647473.359 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:57:53.774Z,1570647473.774 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:57:54.575Z,1570647474.575 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185753.74*50

2019-10-09T18:57:56.618Z,1570647476.618 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:57:56.999Z,1570647476.999 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:58:00.662Z,1570647480.662 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:58:01.442Z,1570647481.442 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:58:01.847Z,1570647481.847 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:58:02.653Z,1570647482.653 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185801.82*51

2019-10-09T18:58:04.707Z,1570647484.707 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:58:05.070Z,1570647485.070 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:58:08.766Z,1570647488.766 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:58:09.515Z,1570647489.515 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:58:09.921Z,1570647489.921 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:58:10.737Z,1570647490.737 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185809.89*52

2019-10-09T18:58:12.809Z,1570647492.809 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:58:13.154Z,1570647493.154 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:58:16.861Z,1570647496.861 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:58:17.629Z,1570647497.629 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:58:18.017Z,1570647498.017 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:58:19.215Z,1570647499.215 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185818.06*55

2019-10-09T18:58:20.882Z,1570647500.882 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:58:21.242Z,1570647501.242 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:58:24.910Z,1570647504.910 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:58:25.679Z,1570647505.679 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:58:26.085Z,1570647506.085 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:58:26.889Z,1570647506.889 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185826.06*58

2019-10-09T18:58:28.962Z,1570647508.962 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:58:29.310Z,1570647509.310 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:58:32.994Z,1570647512.994 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:58:33.761Z,1570647513.761 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:58:34.159Z,1570647514.159 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:58:34.970Z,1570647514.970 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185834.14*58

2019-10-09T18:58:37.029Z,1570647517.029 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:58:37.394Z,1570647517.394 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:58:41.073Z,1570647521.073 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:58:41.839Z,1570647521.839 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:58:42.249Z,1570647522.249 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:58:43.055Z,1570647523.055 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185842.22*5C

2019-10-09T18:58:45.131Z,1570647525.131 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:58:45.470Z,1570647525.470 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:58:46.937Z,1570647526.937 [CommandLine](IMPORTANT): got command stop
2019-10-09T18:58:46.938Z,1570647526.938 [CommandLine](IMPORTANT): Scheduling is paused
2019-10-09T18:58:46.938Z,1570647526.938 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2019-10-09T18:58:47.128Z,1570647527.128 [MissionManager](INFO): MissionManager is completed.
2019-10-09T18:58:47.128Z,1570647527.128 [MissionManager](INFO): Uninitializing Mission DUSBL
2019-10-09T18:58:47.128Z,1570647527.128 [DUSBL] Stopped
2019-10-09T18:58:47.128Z,1570647527.128 [DUSBL](DEBUG): Aggregate::uninitialize DUSBL
2019-10-09T18:58:47.128Z,1570647527.128 [DUSBL:A.Pitch] Stopped
2019-10-09T18:58:47.128Z,1570647527.128 [DUSBL:B.SetSpeed] Stopped
2019-10-09T18:58:47.128Z,1570647527.128 [DUSBL:B.SetSpeed](DEBUG): Uninitialize.
2019-10-09T18:58:47.129Z,1570647527.129 [DUSBL:C.] Stopped
2019-10-09T18:58:47.129Z,1570647527.129 [DUSBL:RequestRepeater] Stopped
2019-10-09T18:58:47.129Z,1570647527.129 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-10-09T18:58:47.129Z,1570647527.129 [DUSBL:RequestRepeater:A.Wait] Stopped
2019-10-09T18:58:47.129Z,1570647527.129 [DUSBL:RequestRepeater:A.Wait](DEBUG): Uninitialize Wait Component.
2019-10-09T18:58:47.534Z,1570647527.534 [MissionManager](IMPORTANT): Started mission Default
2019-10-09T18:58:47.534Z,1570647527.534 [Default] Running Loop=1
2019-10-09T18:58:47.534Z,1570647527.534 [Default](DEBUG): Aggregate::initialize Default
2019-10-09T18:58:47.535Z,1570647527.535 [Default:B.GoToSurface] Running Loop=1
2019-10-09T18:58:47.535Z,1570647527.535 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-10-09T18:58:47.535Z,1570647527.535 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-10-09T18:58:47.535Z,1570647527.535 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-10-09T18:58:47.536Z,1570647527.536 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-10-09T18:58:47.536Z,1570647527.536 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-10-09T18:58:47.536Z,1570647527.536 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-10-09T18:58:47.537Z,1570647527.537 [Default:A.Wait] Running Loop=1
2019-10-09T18:58:47.537Z,1570647527.537 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-10-09T18:59:00.875Z,1570647540.875 [Default:A.Wait](INFO): Done Waiting.
2019-10-09T18:59:00.875Z,1570647540.875 [Default:A.Wait] Stopped
2019-10-09T18:59:00.875Z,1570647540.875 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-10-09T18:59:01.262Z,1570647541.262 [Default:CheckIn] Running Loop=1
2019-10-09T18:59:01.262Z,1570647541.262 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-09T18:59:01.262Z,1570647541.262 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-09T18:59:02.853Z,1570647542.853 [NAL9602](DEBUG): Fix Requested
2019-10-09T18:59:03.260Z,1570647543.260 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185257.00,A,3648.16196,N,12147.28437,W,0.156,47.34,091019,,,D*48
2019-10-09T18:59:03.262Z,1570647543.262 [NAL9602](INFO): GPS fix at 20191009T185257: (36.802699, -121.788073)
2019-10-09T18:59:03.293Z,1570647543.293 [Default:CheckIn:Read_GPS] Stopped
2019-10-09T18:59:03.293Z,1570647543.293 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-10-09T18:59:04.052Z,1570647544.052 [Micromodem](ERROR): Buffer fill timout failure.
2019-10-09T18:59:08.009Z,1570647548.009 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 6D299E5D070000005D00000400370000000000000000151D80030C5669C010B1F8757D1289C5314579F70C2DA3F2CEBBD3F008C18345AF77BE237737FA2CD561
2019-10-09T18:59:08.010Z,1570647548.010 [Micromodem](INFO): Outgoing frame #2, 7 bytes: B0068BDE225C00
2019-10-09T18:59:08.010Z,1570647548.010 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,2*52

2019-10-09T18:59:08.231Z,1570647548.231 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,2*50

2019-10-09T18:59:08.606Z,1570647548.606 [Micromodem](INFO): Nmea in: $CADRQ,185908,1,9,0,64,1*43

2019-10-09T18:59:08.607Z,1570647548.607 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,6D299E5D070000005D00000400370000000000000000151D80030C5669C010B1F8757D1289C5314579F70C2DA3F2CEBBD3F008C18345AF77BE237737FA2CD561*01

2019-10-09T18:59:09.003Z,1570647549.003 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71

2019-10-09T18:59:09.406Z,1570647549.406 [Micromodem](INFO): Nmea in: $CADRQ,185908,1,9,0,64,2*40

2019-10-09T18:59:09.407Z,1570647549.407 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,B0068BDE225C00*08

2019-10-09T18:59:09.826Z,1570647549.826 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,7*44

2019-10-09T18:59:10.218Z,1570647550.218 [Micromodem](INFO): Nmea in: $CATXP,71*74

2019-10-09T18:59:12.638Z,1570647552.638 [Micromodem](INFO): Nmea in: $CATXF,71*62

2019-10-09T18:59:13.046Z,1570647553.046 [Micromodem](INFO): Nmea in: $CAXST,6,20191009,185909.754123,3,0,200,4000,10000,1,1,9,1,2,2,3,71*74

2019-10-09T18:59:14.880Z,1570647554.880 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20191009T183941/Courier0007.lzma
2019-10-09T18:59:16.886Z,1570647556.886 [DataOverHttps](INFO): Moved sent file to Logs/20191009T183941/Courier0007.lzma.bak
2019-10-09T18:59:16.886Z,1570647556.886 [DataOverHttps](INFO): SBD MOMSN=11865238
2019-10-09T18:59:30.658Z,1570647570.658 [CommandLine](IMPORTANT): got command load ./Missions/Maintenance/DUSBL_Tracking.xml
2019-10-09T18:59:30.659Z,1570647570.659 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/DUSBL_Tracking.xml
2019-10-09T18:59:30.684Z,1570647570.684 [MissionManager](INFO): DefineArg DUSBL.MissionTimeout = 10.000000 min
2019-10-09T18:59:30.686Z,1570647570.686 [MissionManager](INFO): DefineArg DUSBL.TransponderCode = 2.000000 count
2019-10-09T18:59:30.688Z,1570647570.688 [MissionManager](INFO): DefineArg DUSBL.TrackingUpdatePeriod = 4.000000 s
2019-10-09T18:59:30.690Z,1570647570.690 [MissionManager](INFO): DefineArg DUSBL.NumberOfPings = 1.000000 count
2019-10-09T18:59:30.692Z,1570647570.692 [MissionManager](INFO): DefineArg DUSBL.EnabledDUSBL = DUSBL_Hydroid.loadAtStartup
2019-10-09T18:59:30.693Z,1570647570.693 [DUSBL:A.Pitch](DEBUG): Construct.
2019-10-09T18:59:30.697Z,1570647570.697 [DUSBL:B.SetSpeed](DEBUG): Construct.
2019-10-09T18:59:30.754Z,1570647570.754 [DUSBL:RequestRepeater:A.Wait](DEBUG): Construct Wait.
2019-10-09T18:59:30.764Z,1570647570.764 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Control="Tethys/Control"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd"
       Id="DUSBL">

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

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

    <DefineArg Name="TransponderCode"><Description>
        Transponder Address.
    </Description><Units:count/><Value>2</Value></DefineArg>
    
    <DefineArg Name="TrackingUpdatePeriod"><Description>
        How long to wait between acoustic queries
    </Description><Units:second/><Value>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-10-09T18:59:30.764Z,1570647570.764 [CommandLine](IMPORTANT): Loaded ./Missions/Maintenance/DUSBL_Tracking.xml
2019-10-09T18:59:40.105Z,1570647580.105 [DataOverHttps](INFO): Sending 343 bytes from file Logs/20191009T183941/Express0008.lzma
2019-10-09T18:59:42.110Z,1570647582.110 [DataOverHttps](INFO): Moved sent file to Logs/20191009T183941/Express0008.lzma.bak
2019-10-09T18:59:42.110Z,1570647582.110 [DataOverHttps](INFO): SBD MOMSN=11865241
2019-10-09T18:59:43.610Z,1570647583.610 [CommandLine](IMPORTANT): got command set DUSBL.TransponderCode 9.000000 count
2019-10-09T18:59:45.523Z,1570647585.523 [CommandLine](IMPORTANT): got command run
2019-10-09T18:59:45.524Z,1570647585.524 [CommandLine](IMPORTANT): Running 
2019-10-09T18:59:45.848Z,1570647585.848 [Default] Stopped
2019-10-09T18:59:45.848Z,1570647585.848 [Default](DEBUG): Aggregate::uninitialize Default
2019-10-09T18:59:45.848Z,1570647585.848 [Default:B.GoToSurface] Stopped
2019-10-09T18:59:45.848Z,1570647585.848 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-10-09T18:59:45.853Z,1570647585.853 [Default:CheckIn] Stopped
2019-10-09T18:59:45.853Z,1570647585.853 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-09T18:59:45.853Z,1570647585.853 [Default:CheckIn:Read_Iridium] Stopped
2019-10-09T18:59:45.853Z,1570647585.853 [MissionManager](IMPORTANT): Started mission DUSBL
2019-10-09T18:59:45.853Z,1570647585.853 [DUSBL] Running Loop=1
2019-10-09T18:59:45.853Z,1570647585.853 [DUSBL](DEBUG): Aggregate::initialize DUSBL
2019-10-09T18:59:45.854Z,1570647585.854 [DUSBL:A.Pitch] Running Loop=1
2019-10-09T18:59:45.854Z,1570647585.854 [DUSBL:A.Pitch](DEBUG): Initialize.
2019-10-09T18:59:45.854Z,1570647585.854 [DUSBL:B.SetSpeed] Running Loop=1
2019-10-09T18:59:45.854Z,1570647585.854 [DUSBL:B.SetSpeed](DEBUG): Initialize.
2019-10-09T18:59:45.854Z,1570647585.854 [DUSBL:C.] Running Loop=1
2019-10-09T18:59:45.854Z,1570647585.854 [DUSBL:C.](INFO): Initializing TrackAcousticContact.
2019-10-09T18:59:45.854Z,1570647585.854 [DUSBL:RequestRepeater] Running Loop=1
2019-10-09T18:59:45.854Z,1570647585.854 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-10-09T18:59:45.854Z,1570647585.854 [DUSBL:RequestRepeater:A.Wait] Running Loop=1
2019-10-09T18:59:45.855Z,1570647585.855 [DUSBL:RequestRepeater:A.Wait](DEBUG): Initialize Wait Component.
2019-10-09T18:59:45.855Z,1570647585.855 [DUSBL:C.] Running Loop=1
2019-10-09T18:59:45.855Z,1570647585.855 [DUSBL:B.SetSpeed] Running Loop=1
2019-10-09T18:59:45.855Z,1570647585.855 [DUSBL:A.Pitch] Running Loop=1
2019-10-09T18:59:49.867Z,1570647589.867 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:59:50.615Z,1570647590.615 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:59:51.021Z,1570647591.021 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:59:51.848Z,1570647591.848 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185950.99*5E

2019-10-09T18:59:53.895Z,1570647593.895 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:59:54.250Z,1570647594.250 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T18:59:57.953Z,1570647597.953 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T18:59:58.711Z,1570647598.711 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T18:59:59.109Z,1570647599.109 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T18:59:59.919Z,1570647599.919 [Micromodem](INFO): Nmea in: $SNTTA,,,,,185959.09*5E

2019-10-09T19:00:01.977Z,1570647601.977 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T19:00:02.338Z,1570647602.338 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T19:00:06.012Z,1570647606.012 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T19:00:06.778Z,1570647606.778 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T19:00:07.181Z,1570647607.181 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T19:00:07.991Z,1570647607.991 [Micromodem](INFO): Nmea in: $SNTTA,,,,,190007.16*56

2019-10-09T19:00:10.061Z,1570647610.061 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T19:00:10.414Z,1570647610.414 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T19:00:14.090Z,1570647614.090 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T19:00:14.855Z,1570647614.855 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T19:00:15.270Z,1570647615.270 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T19:00:16.071Z,1570647616.071 [Micromodem](INFO): Nmea in: $SNTTA,,,,,190015.23*53

2019-10-09T19:00:18.157Z,1570647618.157 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T19:00:18.494Z,1570647618.494 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T19:00:22.182Z,1570647622.182 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T19:00:22.936Z,1570647622.936 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T19:00:23.353Z,1570647623.353 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T19:00:24.148Z,1570647624.148 [Micromodem](INFO): Nmea in: $SNTTA,,,,,190023.31*55

2019-10-09T19:00:26.273Z,1570647626.273 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T19:00:26.570Z,1570647626.570 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T19:00:30.688Z,1570647630.688 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T19:00:31.430Z,1570647631.430 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T19:00:31.832Z,1570647631.832 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T19:00:32.641Z,1570647632.641 [Micromodem](INFO): Nmea in: $SNTTA,,,,,190031.80*5C

2019-10-09T19:00:34.713Z,1570647634.713 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T19:00:35.057Z,1570647635.057 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T19:00:38.718Z,1570647638.718 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T19:00:39.497Z,1570647639.497 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T19:00:39.909Z,1570647639.909 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T19:00:40.713Z,1570647640.713 [Micromodem](INFO): Nmea in: $SNTTA,,,,,190039.87*53

2019-10-09T19:00:42.775Z,1570647642.775 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T19:00:43.138Z,1570647643.138 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T19:00:46.839Z,1570647646.839 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T19:00:47.583Z,1570647647.583 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T19:00:47.984Z,1570647647.984 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T19:00:48.825Z,1570647648.825 [Micromodem](INFO): Nmea in: $SNTTA,,,,,190047.96*5A

2019-10-09T19:00:50.875Z,1570647650.875 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T19:00:51.214Z,1570647651.214 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T19:00:54.894Z,1570647654.894 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T19:00:55.663Z,1570647655.663 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T19:00:56.065Z,1570647656.065 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T19:00:56.875Z,1570647656.875 [Micromodem](INFO): Nmea in: $SNTTA,,,,,190056.04*51

2019-10-09T19:00:58.938Z,1570647658.938 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T19:00:59.298Z,1570647659.298 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T19:01:01.724Z,1570647661.724 [NAL9602](INFO): SBD MO Status=2, MOMSN=9090, MT Status=2, MTMSN=0
2019-10-09T19:01:01.725Z,1570647661.725 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-10-09T19:01:02.979Z,1570647662.979 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T19:01:03.739Z,1570647663.739 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T19:01:04.149Z,1570647664.149 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T19:01:04.955Z,1570647664.955 [Micromodem](INFO): Nmea in: $SNTTA,,,,,190104.12*50

2019-10-09T19:01:07.034Z,1570647667.034 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T19:01:07.379Z,1570647667.379 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T19:01:07.827Z,1570647667.827 [RDI_Pathfinder](ERROR): only read 3 of 4 data items
2019-10-09T19:01:07.827Z,1570647667.827 [RDI_Pathfinder](ERROR): Failed to parse:
:BS,   -28,    -7,    +3   -28,    -7,    +3,A
2019-10-09T19:01:11.053Z,1570647671.053 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T19:01:11.830Z,1570647671.830 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T19:01:12.225Z,1570647672.225 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T19:01:13.036Z,1570647673.036 [Micromodem](INFO): Nmea in: $SNTTA,,,,,190112.20*56

2019-10-09T19:01:15.134Z,1570647675.134 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T19:01:15.454Z,1570647675.454 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T19:01:19.154Z,1570647679.154 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T19:01:19.903Z,1570647679.903 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T19:01:20.326Z,1570647680.326 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T19:01:21.115Z,1570647681.115 [Micromodem](INFO): Nmea in: $SNTTA,,,,,190120.28*5F

2019-10-09T19:01:23.210Z,1570647683.210 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T19:01:23.534Z,1570647683.534 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T19:01:27.211Z,1570647687.211 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T19:01:27.979Z,1570647687.979 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T19:01:28.385Z,1570647688.385 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T19:01:28.823Z,1570647688.823 [NAL9602](INFO): SBD MO Status=2, MOMSN=9090, MT Status=2, MTMSN=0
2019-10-09T19:01:28.823Z,1570647688.823 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-10-09T19:01:29.191Z,1570647689.191 [Micromodem](INFO): Nmea in: $SNTTA,,,,,190128.35*5B

2019-10-09T19:01:31.243Z,1570647691.243 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T19:01:31.612Z,1570647691.612 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T19:01:35.285Z,1570647695.285 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T19:01:36.059Z,1570647696.059 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T19:01:36.475Z,1570647696.475 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T19:01:37.275Z,1570647697.275 [Micromodem](INFO): Nmea in: $SNTTA,,,,,190136.44*52

2019-10-09T19:01:39.376Z,1570647699.376 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T19:01:39.694Z,1570647699.694 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T19:01:43.835Z,1570647703.835 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T19:01:44.547Z,1570647704.547 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T19:01:44.965Z,1570647704.965 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T19:01:45.762Z,1570647705.762 [Micromodem](INFO): Nmea in: $SNTTA,,,,,190144.93*5D

2019-10-09T19:01:47.865Z,1570647707.865 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T19:01:48.182Z,1570647708.182 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T19:01:51.976Z,1570647711.976 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T19:01:52.623Z,1570647712.623 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T19:01:53.041Z,1570647713.041 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T19:01:53.839Z,1570647713.839 [Micromodem](INFO): Nmea in: $SNTTA,,,,,190153.00*51

2019-10-09T19:01:56.262Z,1570647716.262 [DUSBL_Hydroid](ERROR): No response from remote modem.
2019-10-09T19:01:56.305Z,1570647716.305 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T19:01:57.067Z,1570647717.067 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T19:01:57.477Z,1570647717.477 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T19:01:58.281Z,1570647718.281 [Micromodem](INFO): Nmea in: $SNTTA,,,,,190157.44*55

2019-10-09T19:01:58.550Z,1570647718.550 [CommandLine](IMPORTANT): got command stop
2019-10-09T19:01:58.551Z,1570647718.551 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2019-10-09T19:01:58.721Z,1570647718.721 [MissionManager](INFO): MissionManager is completed.
2019-10-09T19:01:58.722Z,1570647718.722 [MissionManager](INFO): Uninitializing Mission DUSBL
2019-10-09T19:01:58.722Z,1570647718.722 [DUSBL] Stopped
2019-10-09T19:01:58.722Z,1570647718.722 [DUSBL](DEBUG): Aggregate::uninitialize DUSBL
2019-10-09T19:01:58.722Z,1570647718.722 [DUSBL:A.Pitch] Stopped
2019-10-09T19:01:58.722Z,1570647718.722 [DUSBL:B.SetSpeed] Stopped
2019-10-09T19:01:58.722Z,1570647718.722 [DUSBL:B.SetSpeed](DEBUG): Uninitialize.
2019-10-09T19:01:58.722Z,1570647718.722 [DUSBL:C.] Stopped
2019-10-09T19:01:58.722Z,1570647718.722 [DUSBL:RequestRepeater] Stopped
2019-10-09T19:01:58.722Z,1570647718.722 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-10-09T19:01:58.722Z,1570647718.722 [DUSBL:RequestRepeater:A.Wait] Stopped
2019-10-09T19:01:58.722Z,1570647718.722 [DUSBL:RequestRepeater:A.Wait](DEBUG): Uninitialize Wait Component.
2019-10-09T19:01:59.128Z,1570647719.128 [MissionManager](IMPORTANT): Started mission Default
2019-10-09T19:01:59.129Z,1570647719.129 [Default] Running Loop=1
2019-10-09T19:01:59.129Z,1570647719.129 [Default](DEBUG): Aggregate::initialize Default
2019-10-09T19:01:59.129Z,1570647719.129 [Default:B.GoToSurface] Running Loop=1
2019-10-09T19:01:59.129Z,1570647719.129 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-10-09T19:01:59.129Z,1570647719.129 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-10-09T19:01:59.130Z,1570647719.130 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-10-09T19:01:59.130Z,1570647719.130 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-10-09T19:01:59.130Z,1570647719.130 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-10-09T19:01:59.131Z,1570647719.131 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-10-09T19:01:59.131Z,1570647719.131 [Default:A.Wait] Running Loop=1
2019-10-09T19:01:59.131Z,1570647719.131 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-10-09T19:02:00.306Z,1570647720.306 [NAL9602](INFO): SBD MO Status=2, MOMSN=9090, MT Status=2, MTMSN=0
2019-10-09T19:02:00.306Z,1570647720.306 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-10-09T19:02:00.728Z,1570647720.728 [CommandLine](IMPORTANT): got command show variable threshold
2019-10-09T19:02:00.861Z,1570647720.861 [CommandLine](IMPORTANT): VerticalControl.surfaceThreshold (meter)
2019-10-09T19:02:00.865Z,1570647720.865 [CommandLine](IMPORTANT): StratificationFrontDetector.threshold (celsius)
2019-10-09T19:02:00.866Z,1570647720.866 [CommandLine](IMPORTANT): DepAvgTempFrontDetector.cntThreshold (count)
2019-10-09T19:02:00.867Z,1570647720.867 [CommandLine](IMPORTANT): DepAvgTempFrontDetector.tempHoriGradThreshold (celsius)
2019-10-09T19:02:00.869Z,1570647720.869 [CommandLine](IMPORTANT): CBIT.humidityThreshold (percent)
2019-10-09T19:02:00.869Z,1570647720.869 [CommandLine](IMPORTANT): CBIT.pressureThreshold (pound_per_square_inch)
2019-10-09T19:02:00.870Z,1570647720.870 [CommandLine](IMPORTANT): CBIT.tempThreshold (fahrenheit)
2019-10-09T19:02:00.871Z,1570647720.871 [CommandLine](IMPORTANT): CBIT.battTempThreshold (celsius)
2019-10-09T19:02:00.872Z,1570647720.872 [CommandLine](IMPORTANT): CBIT.gfChanA0_Threshold (milliampere)
2019-10-09T19:02:00.872Z,1570647720.872 [CommandLine](IMPORTANT): CBIT.gfChanA1_Threshold (milliampere)
2019-10-09T19:02:00.879Z,1570647720.879 [CommandLine](IMPORTANT): CBIT.gfChanA2_Threshold (milliampere)
2019-10-09T19:02:00.879Z,1570647720.879 [CommandLine](IMPORTANT): CBIT.gfChanA3_Threshold (milliampere)
2019-10-09T19:02:00.880Z,1570647720.880 [CommandLine](IMPORTANT): CBIT.gfChanB0_Threshold (milliampere)
2019-10-09T19:02:00.880Z,1570647720.880 [CommandLine](IMPORTANT): CBIT.gfChanB1_Threshold (milliampere)
2019-10-09T19:02:00.886Z,1570647720.886 [CommandLine](IMPORTANT): CBIT.gfChanB2_Threshold (milliampere)
2019-10-09T19:02:00.886Z,1570647720.886 [CommandLine](IMPORTANT): CBIT.gfChanB3_Threshold (milliampere)
2019-10-09T19:02:00.888Z,1570647720.888 [CommandLine](IMPORTANT): IBIT.batteryCapacityThreshold (ampere_hour)
2019-10-09T19:02:00.888Z,1570647720.888 [CommandLine](IMPORTANT): IBIT.batteryVoltageThreshold (volt)
2019-10-09T19:02:00.970Z,1570647720.970 [CommandLine](IMPORTANT): AcousticModem_Benthos_ATM900.surfaceThreshold (meter)
2019-10-09T19:02:00.971Z,1570647720.971 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold (count)
2019-10-09T19:02:00.978Z,1570647720.978 [CommandLine](IMPORTANT): DAT.surfaceThreshold (meter)
2019-10-09T19:02:00.979Z,1570647720.979 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold (count)
2019-10-09T19:02:00.986Z,1570647720.986 [CommandLine](IMPORTANT): Micromodem.surfaceThreshold (meter)
2019-10-09T19:02:09.377Z,1570647729.377 [CommandLine](IMPORTANT): got command get DUSBL_Hydroid.detectionThreshold
2019-10-09T19:02:09.378Z,1570647729.378 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold 32 count
2019-10-09T19:02:12.465Z,1570647732.465 [Default:A.Wait](INFO): Done Waiting.
2019-10-09T19:02:12.465Z,1570647732.465 [Default:A.Wait] Stopped
2019-10-09T19:02:12.466Z,1570647732.466 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-10-09T19:02:12.897Z,1570647732.897 [Default:CheckIn] Running Loop=1
2019-10-09T19:02:12.898Z,1570647732.898 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-09T19:02:12.898Z,1570647732.898 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-09T19:02:21.716Z,1570647741.716 [NAL9602](INFO): SBD MO Status=2, MOMSN=9090, MT Status=2, MTMSN=0
2019-10-09T19:02:21.716Z,1570647741.716 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-10-09T19:02:24.906Z,1570647744.906 [CommandLine](IMPORTANT): got command configSet DUSBL_Hydroid.detectionThreshold 10.000000 count persist
2019-10-09T19:02:37.065Z,1570647757.065 [NAL9602](INFO): SBD MO Status=2, MOMSN=9090, MT Status=2, MTMSN=0
2019-10-09T19:02:37.065Z,1570647757.065 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-10-09T19:02:38.939Z,1570647758.939 [CommandLine](IMPORTANT): got command show variable address
2019-10-09T19:02:38.940Z,1570647758.940 [CommandLine](IMPORTANT): acoustic_contact_address (unknown)
2019-10-09T19:02:39.014Z,1570647759.014 [CommandLine](IMPORTANT): AcousticModem_Benthos_ATM900.localAddress (count)
2019-10-09T19:02:39.015Z,1570647759.015 [CommandLine](IMPORTANT): AcousticModem_Benthos_ATM900.sbdAddress (enum)
2019-10-09T19:02:39.015Z,1570647759.015 [CommandLine](IMPORTANT): AcousticModem_Benthos_ATM900.transponderAddress (enum)
2019-10-09T19:02:39.018Z,1570647759.018 [CommandLine](IMPORTANT): DAT.localAddress (count)
2019-10-09T19:02:39.018Z,1570647759.018 [CommandLine](IMPORTANT): DAT.sbdAddress (enum)
2019-10-09T19:02:39.019Z,1570647759.019 [CommandLine](IMPORTANT): DAT.transponderAddress (enum)
2019-10-09T19:02:39.022Z,1570647759.022 [CommandLine](IMPORTANT): Micromodem.localAddress (enum)
2019-10-09T19:02:39.023Z,1570647759.023 [CommandLine](IMPORTANT): Micromodem.destinationAddress (enum)
2019-10-09T19:02:39.133Z,1570647759.133 [CommandLine](IMPORTANT): DUSBL_Hydroid.acoustic_contact_address (enum)
2019-10-09T19:02:39.134Z,1570647759.134 [CommandLine](IMPORTANT): Micromodem.remote_address (count)
2019-10-09T19:02:39.134Z,1570647759.134 [CommandLine](IMPORTANT): Micromodem.local_address (count)
2019-10-09T19:02:47.954Z,1570647767.954 [CommandLine](IMPORTANT): got command get Micromodem.remote_address
2019-10-09T19:02:47.954Z,1570647767.954 [CommandLine](IMPORTANT): Micromodem.remote_address 0 count
2019-10-09T19:02:57.390Z,1570647777.390 [CommandLine](IMPORTANT): got command get Micromodem.local_address
2019-10-09T19:02:57.391Z,1570647777.391 [CommandLine](IMPORTANT): Micromodem.local_address 0 count
2019-10-09T19:03:21.508Z,1570647801.508 [NAL9602](INFO): SBD MO Status=2, MOMSN=9090, MT Status=2, MTMSN=0
2019-10-09T19:03:21.508Z,1570647801.508 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2019-10-09T19:03:34.030Z,1570647814.030 [NAL9602](INFO): SBD MO Status=0, MOMSN=9090, MT Status=0, MTMSN=0
2019-10-09T19:03:34.030Z,1570647814.030 [NAL9602](INFO): No messages in MT queue
2019-10-09T19:03:34.853Z,1570647814.853 [NAL9602](DEBUG): Fix Requested
2019-10-09T19:03:35.248Z,1570647815.248 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,185729.00,A,3648.16249,N,12147.28220,W,0.194,66.14,091019,,,D*4A
2019-10-09T19:03:35.250Z,1570647815.250 [NAL9602](INFO): GPS fix at 20191009T185729: (36.802708, -121.788037)
2019-10-09T19:03:35.275Z,1570647815.275 [Default:CheckIn:Read_GPS] Stopped
2019-10-09T19:03:35.275Z,1570647815.275 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-10-09T19:03:36.054Z,1570647816.054 [Micromodem](ERROR): Buffer fill timout failure.
2019-10-09T19:03:39.989Z,1570647819.989 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 6D299E5D0A0000005D00000400370000000000000000151D80030C5669C010B1F87285FD8F19F2BF9BE284D6F5B6EA80B85A56BFF878646697437B9D991E6886
2019-10-09T19:03:39.990Z,1570647819.990 [Micromodem](INFO): Outgoing frame #2, 7 bytes: FCA36CC0C92000
2019-10-09T19:03:39.990Z,1570647819.990 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,2*52

2019-10-09T19:03:40.934Z,1570647820.934 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,2*50

2019-10-09T19:03:41.338Z,1570647821.338 [Micromodem](INFO): Nmea in: $CADRQ,190340,1,9,0,64,1*41

2019-10-09T19:03:41.339Z,1570647821.339 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,6D299E5D0A0000005D00000400370000000000000000151D80030C5669C010B1F87285FD8F19F2BF9BE284D6F5B6EA80B85A56BFF878646697437B9D991E6886*05

2019-10-09T19:03:41.742Z,1570647821.742 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71

2019-10-09T19:03:42.147Z,1570647822.147 [Micromodem](INFO): Nmea in: $CADRQ,190341,1,9,0,64,2*43

2019-10-09T19:03:42.147Z,1570647822.147 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,FCA36CC0C92000*78

2019-10-09T19:03:42.550Z,1570647822.550 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,7*44

2019-10-09T19:03:42.954Z,1570647822.954 [Micromodem](INFO): Nmea in: $CATXP,71*74

2019-10-09T19:03:45.389Z,1570647825.389 [Micromodem](INFO): Nmea in: $CATXF,71*62

2019-10-09T19:03:45.881Z,1570647825.881 [Micromodem](INFO): Nmea in: $CAXST,6,20191009,190342.494612,3,0,200,4000,10000,1,1,9,1,2,2,3,71*7F

2019-10-09T19:03:47.450Z,1570647827.450 [DataOverHttps](INFO): Sending 63 bytes from file Logs/20191009T183941/Courier0010.lzma
2019-10-09T19:03:49.458Z,1570647829.458 [DataOverHttps](INFO): Moved sent file to Logs/20191009T183941/Courier0010.lzma.bak
2019-10-09T19:03:49.458Z,1570647829.458 [DataOverHttps](INFO): SBD MOMSN=11865267
2019-10-09T19:04:10.426Z,1570647850.426 [NAL9602](INFO): Not Powering down - fast GPS
2019-10-09T19:04:11.569Z,1570647851.569 [DataOverHttps](INFO): Sending 864 bytes from file Logs/20191009T183941/Express0011.lzma
2019-10-09T19:04:13.574Z,1570647853.574 [DataOverHttps](INFO): Moved sent file to Logs/20191009T183941/Express0011.lzma.bak
2019-10-09T19:04:13.574Z,1570647853.574 [DataOverHttps](INFO): SBD MOMSN=11865312
2019-10-09T19:04:18.959Z,1570647858.959 [Default:CheckIn:Read_Iridium] Stopped
2019-10-09T19:04:18.959Z,1570647858.959 [Default:CheckIn:C.Wait] Running Loop=1
2019-10-09T19:04:18.959Z,1570647858.959 [Default:CheckIn:C.Wait](DEBUG): Initialize Wait Component.
2019-10-09T19:06:35.076Z,1570647995.076 [CommandLine](IMPORTANT): got command run ./Missions/Maintenance/DUSBL_Tracking.xml
2019-10-09T19:06:35.076Z,1570647995.076 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/DUSBL_Tracking.xml
2019-10-09T19:06:35.166Z,1570647995.166 [MissionManager](INFO): DefineArg DUSBL.MissionTimeout = 10.000000 min
2019-10-09T19:06:35.168Z,1570647995.168 [MissionManager](INFO): DefineArg DUSBL.TransponderCode = 2.000000 count
2019-10-09T19:06:35.170Z,1570647995.170 [MissionManager](INFO): DefineArg DUSBL.TrackingUpdatePeriod = 4.000000 s
2019-10-09T19:06:35.172Z,1570647995.172 [MissionManager](INFO): DefineArg DUSBL.NumberOfPings = 1.000000 count
2019-10-09T19:06:35.178Z,1570647995.178 [MissionManager](INFO): DefineArg DUSBL.EnabledDUSBL = DUSBL_Hydroid.loadAtStartup
2019-10-09T19:06:35.179Z,1570647995.179 [DUSBL:A.Pitch](DEBUG): Construct.
2019-10-09T19:06:35.194Z,1570647995.194 [DUSBL:B.SetSpeed](DEBUG): Construct.
2019-10-09T19:06:35.227Z,1570647995.227 [DUSBL:RequestRepeater:A.Wait](DEBUG): Construct Wait.
2019-10-09T19:06:35.232Z,1570647995.232 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Control="Tethys/Control"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd"
       Id="DUSBL">

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

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

    <DefineArg Name="TransponderCode"><Description>
        Transponder Address.
    </Description><Units:count/><Value>2</Value></DefineArg>
    
    <DefineArg Name="TrackingUpdatePeriod"><Description>
        How long to wait between acoustic queries
    </Description><Units:second/><Value>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-10-09T19:06:35.242Z,1570647995.242 [CommandLine](IMPORTANT): Running ./Missions/Maintenance/DUSBL_Tracking.xml
2019-10-09T19:06:35.493Z,1570647995.493 [Default] Stopped
2019-10-09T19:06:35.493Z,1570647995.493 [Default](DEBUG): Aggregate::uninitialize Default
2019-10-09T19:06:35.493Z,1570647995.493 [Default:B.GoToSurface] Stopped
2019-10-09T19:06:35.493Z,1570647995.493 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-10-09T19:06:35.493Z,1570647995.493 [Default:CheckIn] Stopped
2019-10-09T19:06:35.493Z,1570647995.493 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-09T19:06:35.493Z,1570647995.493 [Default:CheckIn:C.Wait] Stopped
2019-10-09T19:06:35.493Z,1570647995.493 [Default:CheckIn:C.Wait](DEBUG): Uninitialize Wait Component.
2019-10-09T19:06:35.493Z,1570647995.493 [MissionManager](IMPORTANT): Started mission DUSBL
2019-10-09T19:06:35.494Z,1570647995.494 [DUSBL] Running Loop=1
2019-10-09T19:06:35.494Z,1570647995.494 [DUSBL](DEBUG): Aggregate::initialize DUSBL
2019-10-09T19:06:35.494Z,1570647995.494 [DUSBL:A.Pitch] Running Loop=1
2019-10-09T19:06:35.494Z,1570647995.494 [DUSBL:A.Pitch](DEBUG): Initialize.
2019-10-09T19:06:35.494Z,1570647995.494 [DUSBL:B.SetSpeed] Running Loop=1
2019-10-09T19:06:35.494Z,1570647995.494 [DUSBL:B.SetSpeed](DEBUG): Initialize.
2019-10-09T19:06:35.494Z,1570647995.494 [DUSBL:C.] Running Loop=1
2019-10-09T19:06:35.494Z,1570647995.494 [DUSBL:C.](INFO): Initializing TrackAcousticContact.
2019-10-09T19:06:35.495Z,1570647995.495 [DUSBL:RequestRepeater] Running Loop=1
2019-10-09T19:06:35.495Z,1570647995.495 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-10-09T19:06:35.495Z,1570647995.495 [DUSBL:RequestRepeater:A.Wait] Running Loop=1
2019-10-09T19:06:35.495Z,1570647995.495 [DUSBL:RequestRepeater:A.Wait](DEBUG): Initialize Wait Component.
2019-10-09T19:06:35.495Z,1570647995.495 [DUSBL:C.] Running Loop=1
2019-10-09T19:06:35.496Z,1570647995.496 [DUSBL:B.SetSpeed] Running Loop=1
2019-10-09T19:06:35.496Z,1570647995.496 [DUSBL:A.Pitch] Running Loop=1
2019-10-09T19:06:39.531Z,1570647999.531 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T19:06:40.310Z,1570648000.310 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T19:06:40.721Z,1570648000.721 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T19:06:41.118Z,1570648001.118 [DUSBL_Hydroid](ERROR): Range is NaN. Not publishing.
2019-10-09T19:06:41.538Z,1570648001.538 [Micromodem](INFO): Nmea in: $SNTTA,,,,,190640.69*5B

2019-10-09T19:06:43.581Z,1570648003.581 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T19:06:44.351Z,1570648004.351 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T19:06:44.760Z,1570648004.760 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T19:06:45.156Z,1570648005.156 [DUSBL_Hydroid](ERROR): Range is NaN. Not publishing.
2019-10-09T19:06:45.578Z,1570648005.578 [Micromodem](INFO): Nmea in: $SNTTA,,,,,190644.73*54

2019-10-09T19:06:47.637Z,1570648007.637 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T19:06:47.861Z,1570648007.861 [CommandLine](IMPORTANT): got command stop
2019-10-09T19:06:47.862Z,1570648007.862 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2019-10-09T19:06:48.031Z,1570648008.031 [MissionManager](INFO): MissionManager is completed.
2019-10-09T19:06:48.031Z,1570648008.031 [MissionManager](INFO): Uninitializing Mission DUSBL
2019-10-09T19:06:48.031Z,1570648008.031 [DUSBL] Stopped
2019-10-09T19:06:48.031Z,1570648008.031 [DUSBL](DEBUG): Aggregate::uninitialize DUSBL
2019-10-09T19:06:48.032Z,1570648008.032 [DUSBL:A.Pitch] Stopped
2019-10-09T19:06:48.032Z,1570648008.032 [DUSBL:B.SetSpeed] Stopped
2019-10-09T19:06:48.032Z,1570648008.032 [DUSBL:B.SetSpeed](DEBUG): Uninitialize.
2019-10-09T19:06:48.032Z,1570648008.032 [DUSBL:C.] Stopped
2019-10-09T19:06:48.032Z,1570648008.032 [DUSBL:RequestRepeater] Stopped
2019-10-09T19:06:48.032Z,1570648008.032 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-10-09T19:06:48.032Z,1570648008.032 [DUSBL:RequestRepeater:A.Wait] Stopped
2019-10-09T19:06:48.032Z,1570648008.032 [DUSBL:RequestRepeater:A.Wait](DEBUG): Uninitialize Wait Component.
2019-10-09T19:06:48.424Z,1570648008.424 [MissionManager](IMPORTANT): Started mission Default
2019-10-09T19:06:48.425Z,1570648008.425 [Default] Running Loop=1
2019-10-09T19:06:48.425Z,1570648008.425 [Default](DEBUG): Aggregate::initialize Default
2019-10-09T19:06:48.425Z,1570648008.425 [Default:B.GoToSurface] Running Loop=1
2019-10-09T19:06:48.425Z,1570648008.425 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-10-09T19:06:48.425Z,1570648008.425 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-10-09T19:06:48.426Z,1570648008.426 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-10-09T19:06:48.426Z,1570648008.426 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-10-09T19:06:48.426Z,1570648008.426 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-10-09T19:06:48.427Z,1570648008.427 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-10-09T19:06:48.427Z,1570648008.427 [Default:A.Wait] Running Loop=1
2019-10-09T19:06:48.427Z,1570648008.427 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-10-09T19:06:53.282Z,1570648013.282 [CommandLine](IMPORTANT): got command show variable range
2019-10-09T19:06:53.283Z,1570648013.283 [CommandLine](IMPORTANT): acoustic_contact_range (unknown)
2019-10-09T19:06:53.395Z,1570648013.395 [CommandLine](IMPORTANT): BR_Ping1D.minRange (meter)
2019-10-09T19:06:53.395Z,1570648013.395 [CommandLine](IMPORTANT): BR_Ping1D.maxRange (meter)
2019-10-09T19:06:53.427Z,1570648013.427 [CommandLine](IMPORTANT): DUSBL_Hydroid.acoustic_contact_range (meter)
2019-10-09T19:06:53.428Z,1570648013.428 [CommandLine](IMPORTANT): Micromodem.range_request (count)
2019-10-09T19:06:53.429Z,1570648013.429 [CommandLine](IMPORTANT): Micromodem.range (meter)
2019-10-09T19:06:53.432Z,1570648013.432 [CommandLine](IMPORTANT): RDI_Pathfinder.Beam1Range (meter)
2019-10-09T19:06:53.432Z,1570648013.432 [CommandLine](IMPORTANT): RDI_Pathfinder.Beam2Range (meter)
2019-10-09T19:06:53.432Z,1570648013.432 [CommandLine](IMPORTANT): RDI_Pathfinder.Beam3Range (meter)
2019-10-09T19:06:53.433Z,1570648013.433 [CommandLine](IMPORTANT): RDI_Pathfinder.Beam4Range (meter)
2019-10-09T19:07:01.822Z,1570648021.822 [Default:A.Wait](INFO): Done Waiting.
2019-10-09T19:07:01.822Z,1570648021.822 [Default:A.Wait] Stopped
2019-10-09T19:07:01.822Z,1570648021.822 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-10-09T19:07:02.218Z,1570648022.218 [Default:CheckIn] Running Loop=1
2019-10-09T19:07:02.218Z,1570648022.218 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-09T19:07:02.218Z,1570648022.218 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-09T19:07:02.687Z,1570648022.687 [CommandLine](IMPORTANT): got command report touch DUSBL_Hydroid.acoustic_contact_range
2019-10-09T19:07:03.763Z,1570648023.763 [NAL9602](DEBUG): Fix Requested
2019-10-09T19:07:04.146Z,1570648024.146 [NAL9602](DEBUG): Parsing RMC sentence: $GPRMC,190058.00,A,3648.16078,N,12147.28904,W,0.019,66.14,091019,,,D*46
2019-10-09T19:07:04.148Z,1570648024.148 [NAL9602](INFO): GPS fix at 20191009T190058: (36.802680, -121.788151)
2019-10-09T19:07:04.226Z,1570648024.226 [Default:CheckIn:Read_GPS] Stopped
2019-10-09T19:07:04.226Z,1570648024.226 [Default:CheckIn:Read_Iridium] Running Loop=1
2019-10-09T19:07:05.070Z,1570648025.070 [Micromodem](ERROR): Buffer fill timout failure.
2019-10-09T19:07:07.976Z,1570648027.976 [DataOverHttps](INFO): Sending 62 bytes from file Logs/20191009T183941/Courier0013.lzma
2019-10-09T19:07:08.705Z,1570648028.705 [Micromodem](INFO): Outgoing frame #1, 64 bytes: 6D299E5D0D0000005D00000400370000000000000000151D80030C5669C010B1F8574B59A4623F97DE6DEA0D0F69A07E32C7AD99D34B0443801CF5D1B1DF9AF8
2019-10-09T19:07:08.706Z,1570648028.706 [Micromodem](INFO): Outgoing frame #2, 6 bytes: 5D98C7A76193
2019-10-09T19:07:08.706Z,1570648028.706 [Micromodem](INFO): Nmea out: $CCCYC,0,1,9,1,0,2*52

2019-10-09T19:07:08.770Z,1570648028.770 [NAL9602](ERROR): parseGSV uart error: serial buffer full
2019-10-09T19:07:09.307Z,1570648029.307 [Micromodem](INFO): Nmea in: $CACYC,0,1,9,1,0,2*50

2019-10-09T19:07:09.723Z,1570648029.723 [Micromodem](INFO): Nmea in: $CADRQ,190709,1,9,0,64,1*48

2019-10-09T19:07:09.723Z,1570648029.723 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,6D299E5D0D0000005D00000400370000000000000000151D80030C5669C010B1F8574B59A4623F97DE6DEA0D0F69A07E32C7AD99D34B0443801CF5D1B1DF9AF8*08

2019-10-09T19:07:09.998Z,1570648029.998 [DataOverHttps](INFO): Moved sent file to Logs/20191009T183941/Courier0013.lzma.bak
2019-10-09T19:07:09.999Z,1570648029.999 [DataOverHttps](INFO): SBD MOMSN=11865372
2019-10-09T19:07:10.138Z,1570648030.138 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,64*71

2019-10-09T19:07:10.534Z,1570648030.534 [Micromodem](INFO): Nmea in: $CADRQ,190710,1,9,0,64,2*43

2019-10-09T19:07:10.535Z,1570648030.535 [Micromodem](INFO): Nmea out: $CCTXD,1,9,1,5D98C7A76193*0E

2019-10-09T19:07:10.940Z,1570648030.940 [Micromodem](INFO): Nmea in: $CATXD,1,9,1,6*45

2019-10-09T19:07:11.338Z,1570648031.338 [Micromodem](INFO): Nmea in: $CATXP,70*75

2019-10-09T19:07:12.009Z,1570648032.009 [CommandLine](IMPORTANT): got command run ./Missions/Maintenance/DUSBL_Tracking.xml
2019-10-09T19:07:12.010Z,1570648032.010 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/DUSBL_Tracking.xml
2019-10-09T19:07:12.034Z,1570648032.034 [MissionManager](INFO): DefineArg DUSBL.MissionTimeout = 10.000000 min
2019-10-09T19:07:12.036Z,1570648032.036 [MissionManager](INFO): DefineArg DUSBL.TransponderCode = 2.000000 count
2019-10-09T19:07:12.058Z,1570648032.058 [MissionManager](INFO): DefineArg DUSBL.TrackingUpdatePeriod = 4.000000 s
2019-10-09T19:07:12.060Z,1570648032.060 [MissionManager](INFO): DefineArg DUSBL.NumberOfPings = 1.000000 count
2019-10-09T19:07:12.074Z,1570648032.074 [MissionManager](INFO): DefineArg DUSBL.EnabledDUSBL = DUSBL_Hydroid.loadAtStartup
2019-10-09T19:07:12.075Z,1570648032.075 [DUSBL:A.Pitch](DEBUG): Construct.
2019-10-09T19:07:12.087Z,1570648032.087 [DUSBL:B.SetSpeed](DEBUG): Construct.
2019-10-09T19:07:12.159Z,1570648032.159 [DUSBL:RequestRepeater:A.Wait](DEBUG): Construct Wait.
2019-10-09T19:07:12.203Z,1570648032.203 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<!-- ====================
     Generated by TethysL
     ==================== -->
<Mission xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
         xmlns="Tethys"
         xmlns:Units="Tethys/Units"
         xmlns:Guidance="Tethys/Guidance"
         xmlns:Control="Tethys/Control"
         xsi:schemaLocation="Tethys http://okeanids.mbari.org/tethys/Xml/Tethys.xsd
                             Tethys/Units http://okeanids.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Guidance http://okeanids.mbari.org/tethys/Xml/Guidance.xsd
                             Tethys/Control http://okeanids.mbari.org/tethys/Xml/Control.xsd"
       Id="DUSBL">

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

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

    <DefineArg Name="TransponderCode"><Description>
        Transponder Address.
    </Description><Units:count/><Value>2</Value></DefineArg>
    
    <DefineArg Name="TrackingUpdatePeriod"><Description>
        How long to wait between acoustic queries
    </Description><Units:second/><Value>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-10-09T19:07:12.229Z,1570648032.229 [CommandLine](IMPORTANT): Running ./Missions/Maintenance/DUSBL_Tracking.xml
2019-10-09T19:07:12.250Z,1570648032.250 [Default] Stopped
2019-10-09T19:07:12.250Z,1570648032.250 [Default](DEBUG): Aggregate::uninitialize Default
2019-10-09T19:07:12.250Z,1570648032.250 [Default:B.GoToSurface] Stopped
2019-10-09T19:07:12.250Z,1570648032.250 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-10-09T19:07:12.250Z,1570648032.250 [Default:CheckIn] Stopped
2019-10-09T19:07:12.250Z,1570648032.250 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-09T19:07:12.250Z,1570648032.250 [Default:CheckIn:Read_Iridium] Stopped
2019-10-09T19:07:12.250Z,1570648032.250 [MissionManager](IMPORTANT): Started mission DUSBL
2019-10-09T19:07:12.251Z,1570648032.251 [DUSBL] Running Loop=1
2019-10-09T19:07:12.251Z,1570648032.251 [DUSBL](DEBUG): Aggregate::initialize DUSBL
2019-10-09T19:07:12.251Z,1570648032.251 [DUSBL:A.Pitch] Running Loop=1
2019-10-09T19:07:12.251Z,1570648032.251 [DUSBL:A.Pitch](DEBUG): Initialize.
2019-10-09T19:07:12.251Z,1570648032.251 [DUSBL:B.SetSpeed] Running Loop=1
2019-10-09T19:07:12.251Z,1570648032.251 [DUSBL:B.SetSpeed](DEBUG): Initialize.
2019-10-09T19:07:12.251Z,1570648032.251 [DUSBL:C.] Running Loop=1
2019-10-09T19:07:12.251Z,1570648032.251 [DUSBL:C.](INFO): Initializing TrackAcousticContact.
2019-10-09T19:07:12.252Z,1570648032.252 [DUSBL:RequestRepeater] Running Loop=1
2019-10-09T19:07:12.252Z,1570648032.252 [DUSBL:RequestRepeater](DEBUG): Aggregate::initialize DUSBL:RequestRepeater
2019-10-09T19:07:12.252Z,1570648032.252 [DUSBL:RequestRepeater:A.Wait] Running Loop=1
2019-10-09T19:07:12.252Z,1570648032.252 [DUSBL:RequestRepeater:A.Wait](DEBUG): Initialize Wait Component.
2019-10-09T19:07:12.252Z,1570648032.252 [DUSBL:C.] Running Loop=1
2019-10-09T19:07:12.253Z,1570648032.253 [DUSBL:B.SetSpeed] Running Loop=1
2019-10-09T19:07:12.253Z,1570648032.253 [DUSBL:A.Pitch] Running Loop=1
2019-10-09T19:07:13.770Z,1570648033.770 [Micromodem](INFO): Nmea in: $CATXF,70*63

2019-10-09T19:07:13.775Z,1570648033.775 [Micromodem](INFO): Nmea in: $CAXST,6,20191009,190710.885606,3,0,200,4000,10000,1,1,9,1,2,2,3,70*74

2019-10-09T19:07:16.653Z,1570648036.653 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T19:07:17.387Z,1570648037.387 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T19:07:17.825Z,1570648037.825 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T19:07:18.194Z,1570648038.194 [DUSBL_Hydroid](ERROR): Range is NaN. Not publishing.
2019-10-09T19:07:18.622Z,1570648038.622 [Micromodem](INFO): Nmea in: $SNTTA,,,,,190717.79*59

2019-10-09T19:07:20.661Z,1570648040.661 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T19:07:21.435Z,1570648041.435 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T19:07:21.856Z,1570648041.856 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T19:07:22.238Z,1570648042.238 [DUSBL_Hydroid](ERROR): Range is NaN. Not publishing.
2019-10-09T19:07:22.658Z,1570648042.658 [Micromodem](INFO): Nmea in: $SNTTA,,,,,190721.81*5B

2019-10-09T19:07:24.712Z,1570648044.712 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T19:07:25.483Z,1570648045.483 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T19:07:25.908Z,1570648045.908 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T19:07:26.278Z,1570648046.278 [DUSBL_Hydroid](ERROR): Range is NaN. Not publishing.
2019-10-09T19:07:26.710Z,1570648046.710 [Micromodem](INFO): Nmea in: $SNTTA,,,,,190725.86*58

2019-10-09T19:07:28.749Z,1570648048.749 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T19:07:29.511Z,1570648049.511 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T19:07:29.912Z,1570648049.912 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T19:07:30.318Z,1570648050.318 [DUSBL_Hydroid](ERROR): Range is NaN. Not publishing.
2019-10-09T19:07:30.734Z,1570648050.734 [Micromodem](INFO): Nmea in: $SNTTA,,,,,190729.89*5B

2019-10-09T19:07:32.822Z,1570648052.822 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T19:07:33.551Z,1570648053.551 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T19:07:33.964Z,1570648053.964 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T19:07:34.354Z,1570648054.354 [DUSBL_Hydroid](ERROR): Range is NaN. Not publishing.
2019-10-09T19:07:34.781Z,1570648054.781 [Micromodem](INFO): Nmea in: $SNTTA,,,,,190733.93*5B

2019-10-09T19:07:36.853Z,1570648056.853 [DUSBL:C.](INFO): *** querying acoustic contact ***
2019-10-09T19:07:37.591Z,1570648057.591 [Micromodem](INFO): Nmea out: $CCPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*77

2019-10-09T19:07:37.997Z,1570648057.997 [Micromodem](INFO): Nmea in: $SNPGT,1,10000,40,0,1000,24000,28,4A1C0370,6C8F7D30,2107E320,37BCD1E0,4000,4000,0*6A

2019-10-09T19:07:38.399Z,1570648058.399 [DUSBL_Hydroid](ERROR): Range is NaN. Not publishing.
2019-10-09T19:07:38.846Z,1570648058.846 [Micromodem](INFO): Nmea in: $SNTTA,,,,,190737.97*5B

2019-10-09T19:07:39.914Z,1570648059.914 [CommandLine](IMPORTANT): got command stop
2019-10-09T19:07:39.915Z,1570648059.915 [Supervisor](INFO): Stop Mission called by CommandLine::commandStop
2019-10-09T19:07:40.081Z,1570648060.081 [MissionManager](INFO): MissionManager is completed.
2019-10-09T19:07:40.081Z,1570648060.081 [MissionManager](INFO): Uninitializing Mission DUSBL
2019-10-09T19:07:40.081Z,1570648060.081 [DUSBL] Stopped
2019-10-09T19:07:40.081Z,1570648060.081 [DUSBL](DEBUG): Aggregate::uninitialize DUSBL
2019-10-09T19:07:40.081Z,1570648060.081 [DUSBL:A.Pitch] Stopped
2019-10-09T19:07:40.081Z,1570648060.081 [DUSBL:B.SetSpeed] Stopped
2019-10-09T19:07:40.081Z,1570648060.081 [DUSBL:B.SetSpeed](DEBUG): Uninitialize.
2019-10-09T19:07:40.081Z,1570648060.081 [DUSBL:C.] Stopped
2019-10-09T19:07:40.081Z,1570648060.081 [DUSBL:RequestRepeater] Stopped
2019-10-09T19:07:40.082Z,1570648060.082 [DUSBL:RequestRepeater](DEBUG): Aggregate::uninitialize DUSBL:RequestRepeater
2019-10-09T19:07:40.082Z,1570648060.082 [DUSBL:RequestRepeater:A.Wait] Stopped
2019-10-09T19:07:40.082Z,1570648060.082 [DUSBL:RequestRepeater:A.Wait](DEBUG): Uninitialize Wait Component.
2019-10-09T19:07:40.485Z,1570648060.485 [MissionManager](IMPORTANT): Started mission Default
2019-10-09T19:07:40.485Z,1570648060.485 [Default] Running Loop=1
2019-10-09T19:07:40.485Z,1570648060.485 [Default](DEBUG): Aggregate::initialize Default
2019-10-09T19:07:40.485Z,1570648060.485 [Default:B.GoToSurface] Running Loop=1
2019-10-09T19:07:40.485Z,1570648060.485 [Default:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2019-10-09T19:07:40.486Z,1570648060.486 [Default:B.GoToSurface](DEBUG): No depth rate setting specified. Using default value of nan m/s.
2019-10-09T19:07:40.486Z,1570648060.486 [Default:B.GoToSurface](DEBUG): No pitch setting specified. Using default value of nan degrees.
2019-10-09T19:07:40.486Z,1570648060.486 [Default:B.GoToSurface](DEBUG): No speed setting specified. Using default value of 1.000000 m/s.
2019-10-09T19:07:40.487Z,1570648060.487 [Default:B.GoToSurface](DEBUG): No surface timeout specified. Using default value of 1000.000000 seconds.
2019-10-09T19:07:40.487Z,1570648060.487 [Default:B.GoToSurface](INFO): Received pitch timeout configuration 60.000000 seconds.
2019-10-09T19:07:40.487Z,1570648060.487 [Default:A.Wait] Running Loop=1
2019-10-09T19:07:40.487Z,1570648060.487 [Default:A.Wait](DEBUG): Initialize Wait Component.
2019-10-09T19:07:42.299Z,1570648062.299 [CommandLine](IMPORTANT): got command show variable threshold
2019-10-09T19:07:42.332Z,1570648062.332 [CommandLine](IMPORTANT): VerticalControl.surfaceThreshold (meter)
2019-10-09T19:07:42.335Z,1570648062.335 [CommandLine](IMPORTANT): StratificationFrontDetector.threshold (celsius)
2019-10-09T19:07:42.336Z,1570648062.336 [CommandLine](IMPORTANT): DepAvgTempFrontDetector.cntThreshold (count)
2019-10-09T19:07:42.337Z,1570648062.337 [CommandLine](IMPORTANT): DepAvgTempFrontDetector.tempHoriGradThreshold (celsius)
2019-10-09T19:07:42.338Z,1570648062.338 [CommandLine](IMPORTANT): CBIT.humidityThreshold (percent)
2019-10-09T19:07:42.338Z,1570648062.338 [CommandLine](IMPORTANT): CBIT.pressureThreshold (pound_per_square_inch)
2019-10-09T19:07:42.339Z,1570648062.339 [CommandLine](IMPORTANT): CBIT.tempThreshold (fahrenheit)
2019-10-09T19:07:42.339Z,1570648062.339 [CommandLine](IMPORTANT): CBIT.battTempThreshold (celsius)
2019-10-09T19:07:42.340Z,1570648062.340 [CommandLine](IMPORTANT): CBIT.gfChanA0_Threshold (milliampere)
2019-10-09T19:07:42.341Z,1570648062.341 [CommandLine](IMPORTANT): CBIT.gfChanA1_Threshold (milliampere)
2019-10-09T19:07:42.341Z,1570648062.341 [CommandLine](IMPORTANT): CBIT.gfChanA2_Threshold (milliampere)
2019-10-09T19:07:42.342Z,1570648062.342 [CommandLine](IMPORTANT): CBIT.gfChanA3_Threshold (milliampere)
2019-10-09T19:07:42.342Z,1570648062.342 [CommandLine](IMPORTANT): CBIT.gfChanB0_Threshold (milliampere)
2019-10-09T19:07:42.342Z,1570648062.342 [CommandLine](IMPORTANT): CBIT.gfChanB1_Threshold (milliampere)
2019-10-09T19:07:42.343Z,1570648062.343 [CommandLine](IMPORTANT): CBIT.gfChanB2_Threshold (milliampere)
2019-10-09T19:07:42.343Z,1570648062.343 [CommandLine](IMPORTANT): CBIT.gfChanB3_Threshold (milliampere)
2019-10-09T19:07:42.385Z,1570648062.385 [CommandLine](IMPORTANT): IBIT.batteryCapacityThreshold (ampere_hour)
2019-10-09T19:07:42.385Z,1570648062.385 [CommandLine](IMPORTANT): IBIT.batteryVoltageThreshold (volt)
2019-10-09T19:07:42.590Z,1570648062.589 [CommandLine](IMPORTANT): AcousticModem_Benthos_ATM900.surfaceThreshold (meter)
2019-10-09T19:07:42.590Z,1570648062.590 [CommandLine](IMPORTANT): BPC1.batteryMissingStickThreshold (count)
2019-10-09T19:07:42.593Z,1570648062.593 [CommandLine](IMPORTANT): DAT.surfaceThreshold (meter)
2019-10-09T19:07:42.594Z,1570648062.594 [CommandLine](IMPORTANT): DUSBL_Hydroid.detectionThreshold (count)
2019-10-09T19:07:42.613Z,1570648062.613 [CommandLine](IMPORTANT): Micromodem.surfaceThreshold (meter)
2019-10-09T19:07:52.587Z,1570648072.587 [CommandLine](IMPORTANT): got command configSet DUSBL_Hydroid.detectionThreshold 15.000000 count persist
2019-10-09T19:07:53.833Z,1570648073.833 [Default:A.Wait](INFO): Done Waiting.
2019-10-09T19:07:53.833Z,1570648073.833 [Default:A.Wait] Stopped
2019-10-09T19:07:53.833Z,1570648073.833 [Default:A.Wait](DEBUG): Uninitialize Wait Component.
2019-10-09T19:07:54.209Z,1570648074.209 [Default:CheckIn] Running Loop=1
2019-10-09T19:07:54.209Z,1570648074.209 [Default:CheckIn](DEBUG): Aggregate::initialize Default:CheckIn
2019-10-09T19:07:54.209Z,1570648074.209 [Default:CheckIn:Read_GPS] Running Loop=1
2019-10-09T19:07:55.079Z,1570648075.079 [CommandLine](IMPORTANT): got command restart application
2019-10-09T19:07:56.085Z,1570648076.085 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-09T19:07:56.085Z,1570648076.085 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-10-09T19:07:56.149Z,1570648076.149 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2019-10-09T19:07:56.149Z,1570648076.149 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-10-09T19:07:56.150Z,1570648076.150 [CommandLine](INFO): Join timeout helper Thread ID is 1077
2019-10-09T19:07:56.199Z,1570648076.199 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2019-10-09T19:07:56.199Z,1570648076.199 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-10-09T19:07:56.213Z,1570648076.213 [NavChartDb](INFO): Join timeout helper Thread ID is 1078
2019-10-09T19:07:56.569Z,1570648076.569 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-09T19:07:56.569Z,1570648076.569 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-10-09T19:07:56.573Z,1570648076.573 [ComponentRegistry](INFO): Shutting down WetLabsSeaOWL_UV_A ThreadHandler
2019-10-09T19:07:56.573Z,1570648076.573 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-10-09T19:07:56.573Z,1570648076.573 [WetLabsSeaOWL_UV_A](INFO): Join timeout helper Thread ID is 1079
2019-10-09T19:07:56.821Z,1570648076.821 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-09T19:07:56.821Z,1570648076.821 [WetLabsSeaOWL_UV_A](INFO): Powering down
2019-10-09T19:07:56.822Z,1570648076.822 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-10-09T19:07:56.841Z,1570648076.841 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2019-10-09T19:07:56.841Z,1570648076.841 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-10-09T19:07:56.841Z,1570648076.841 [Radio_Surface](INFO): Join timeout helper Thread ID is 1080
2019-10-09T19:07:56.842Z,1570648076.842 [Radio_Surface](INFO): Powering down
2019-10-09T19:07:56.843Z,1570648076.843 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-09T19:07:56.843Z,1570648076.843 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-10-09T19:07:56.843Z,1570648076.843 [ComponentRegistry](INFO): Shutting down DataOverHttps ThreadHandler
2019-10-09T19:07:56.843Z,1570648076.843 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-10-09T19:07:56.844Z,1570648076.844 [DataOverHttps](INFO): Join timeout helper Thread ID is 1081
2019-10-09T19:07:57.837Z,1570648077.837 [DataOverHttps ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-09T19:07:57.840Z,1570648077.840 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-10-09T19:07:57.846Z,1570648077.846 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2019-10-09T19:07:57.846Z,1570648077.846 [logger ThreadHandler](INFO): Thread cancelled.
2019-10-09T19:07:57.846Z,1570648077.846 [logger](INFO): Join timeout helper Thread ID is 1082
2019-10-09T19:07:57.853Z,1570648077.853 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-09T19:07:57.853Z,1570648077.853 [logger ThreadHandler](INFO): Thread cancelled.
2019-10-09T19:07:57.866Z,1570648077.866 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2019-10-09T19:07:57.866Z,1570648077.866 [CommandLine ThreadHandler](INFO): Thread cancelled.
2019-10-09T19:07:57.866Z,1570648077.866 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2019-10-09T19:07:57.866Z,1570648077.866 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-10-09T19:07:57.866Z,1570648077.866 [controlThread](INFO): Join timeout helper Thread ID is 1083
2019-10-09T19:07:57.877Z,1570648077.877 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2019-10-09T19:07:57.878Z,1570648077.878 [controlThread](DEBUG): Uninitializing ControlThread
2019-10-09T19:07:57.878Z,1570648077.878 [AHRS_M2](INFO): Powering down
2019-10-09T19:07:57.949Z,1570648077.949 [DUSBL_Hydroid](INFO): Powering down
2019-10-09T19:07:58.021Z,1570648078.021 [Micromodem](INFO): Powering down
2019-10-09T19:07:58.117Z,1570648078.117 [NAL9602](INFO): Powering down
2019-10-09T19:07:58.189Z,1570648078.189 [RDI_Pathfinder](INFO): Powering down
2019-10-09T19:07:58.190Z,1570648078.190 [DepthRateCalculator](DEBUG): Uninitializing DepthRateCalculator.
2019-10-09T19:07:58.191Z,1570648078.191 [ElevatorOffsetCalculator](DEBUG): Uninitializing ElevatorOffsetCalculator.
2019-10-09T19:07:58.192Z,1570648078.192 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2019-10-09T19:07:58.192Z,1570648078.192 [MissionManager](INFO): Uninitializing Mission Default
2019-10-09T19:07:58.192Z,1570648078.192 [Default] Stopped
2019-10-09T19:07:58.192Z,1570648078.192 [Default](DEBUG): Aggregate::uninitialize Default
2019-10-09T19:07:58.193Z,1570648078.193 [Default:B.GoToSurface] Stopped
2019-10-09T19:07:58.193Z,1570648078.193 [Default:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2019-10-09T19:07:58.193Z,1570648078.193 [Default:CheckIn] Stopped
2019-10-09T19:07:58.193Z,1570648078.193 [Default:CheckIn](DEBUG): Aggregate::uninitialize Default:CheckIn
2019-10-09T19:07:58.193Z,1570648078.193 [Default:CheckIn:Read_GPS] Stopped
2019-10-09T19:07:58.195Z,1570648078.195 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2019-10-09T19:07:58.196Z,1570648078.196 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2019-10-09T19:07:58.196Z,1570648078.196 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2019-10-09T19:07:58.196Z,1570648078.196 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2019-10-09T19:07:58.196Z,1570648078.196 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2019-10-09T19:07:58.196Z,1570648078.196 [BuoyancyServo](INFO): Powering down
2019-10-09T19:07:58.209Z,1570648078.209 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2019-10-09T19:07:58.209Z,1570648078.209 [ElevatorServo](INFO): Powering down
2019-10-09T19:07:58.210Z,1570648078.210 [MassServo](DEBUG): Uninitialize Mass Servo.
2019-10-09T19:07:58.210Z,1570648078.210 [MassServo](INFO): Powering down
2019-10-09T19:07:58.211Z,1570648078.211 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2019-10-09T19:07:58.211Z,1570648078.211 [RudderServo](INFO): Powering down
2019-10-09T19:07:58.211Z,1570648078.211 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2019-10-09T19:07:58.212Z,1570648078.212 [ThrusterServo](INFO): Powering down
2019-10-09T19:07:58.212Z,1570648078.212 [SBIT](DEBUG): Uninitialize SBIT Component.
2019-10-09T19:07:58.213Z,1570648078.213 [IBIT](DEBUG): Uninitialize IBIT Component.
2019-10-09T19:07:58.213Z,1570648078.213 [CBIT](DEBUG): Uninitialize CBIT Component.
2019-10-09T19:07:58.213Z,1570648078.213 [CBIT](DEBUG): Powering off loads.
2019-10-09T19:07:58.225Z,1570648078.225 [CBIT](DEBUG): Disabling WDT.
2019-10-09T19:07:58.237Z,1570648078.237 [CBIT](DEBUG): Opening all GF detection circuits.
2019-10-09T19:07:58.238Z,1570648078.238 [controlThread ThreadHandler](INFO): Thread cancelled.
2019-10-09T19:07:58.296Z,1570648078.296 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2019-10-09T19:07:58.305Z,1570648078.305 [DataOverHttps ThreadHandler](INFO): Thread cancelled.
2019-10-09T19:07:58.345Z,1570648078.345 [WetLabsSeaOWL_UV_A ThreadHandler](INFO): Thread cancelled.
2019-10-09T19:07:58.400Z,1570648078.400 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2019-10-09T19:07:58.470Z,1570648078.470 [logger ThreadHandler](INFO): Thread cancelled.