2017-04-19T20:00:07.713Z,1492632007.713 [Supervisor](DEBUG): Initializing supervisor.
2017-04-19T20:00:07.716Z,1492632007.716 [SyncHandler](DEBUG): Created PCaller Thread at 4051A4E0
2017-04-19T20:00:07.717Z,1492632007.717 [SyncHandler](INFO): Protected caller Thread ID is 4487
2017-04-19T20:00:07.717Z,1492632007.717 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2017-04-19T20:00:07.718Z,1492632007.718 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4054A4E0
2017-04-19T20:00:07.719Z,1492632007.719 [controlThread ThreadHandler](INFO): Protected caller Thread ID is 4488
2017-04-19T20:00:07.723Z,1492632007.723 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2017-04-19T20:00:07.737Z,1492632007.737 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2017-04-19T20:00:07.738Z,1492632007.738 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4057A4E0
2017-04-19T20:00:07.738Z,1492632007.738 [CommandLine ThreadHandler](INFO): Protected caller Thread ID is 4489
2017-04-19T20:00:07.739Z,1492632007.739 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2017-04-19T20:00:07.740Z,1492632007.740 [logger ThreadHandler](DEBUG): Created PCaller Thread at 405AA4E0
2017-04-19T20:00:07.741Z,1492632007.741 [logger ThreadHandler](INFO): Protected caller Thread ID is 4490
2017-04-19T20:00:07.743Z,1492632007.743 [ComponentRegistry](DEBUG): SyncComponent "LogSplitter" handled in the control thread.
2017-04-19T20:00:07.743Z,1492632007.743 [Supervisor](INFO): Looking for Config files in directory: Config/
2017-04-19T20:00:07.745Z,1492632007.745 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2017-04-19T20:00:07.833Z,1492632007.833 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2017-04-19T20:00:08.169Z,1492632008.169 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2017-04-19T20:00:08.169Z,1492632008.169 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2017-04-19T20:00:08.307Z,1492632008.307 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2017-04-19T20:00:08.308Z,1492632008.308 [Supervisor](INFO): Opening Config file at: Config/Navigation.cfg
2017-04-19T20:00:08.515Z,1492632008.515 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Navigation
2017-04-19T20:00:08.516Z,1492632008.516 [Supervisor](INFO): Opening Config file at: Config/secure.cfg
2017-04-19T20:00:08.618Z,1492632008.618 [ComponentRegistry](DEBUG): Loaded Config Component "Config/secure
2017-04-19T20:00:08.619Z,1492632008.619 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2017-04-19T20:00:08.821Z,1492632008.821 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2017-04-19T20:00:08.822Z,1492632008.822 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2017-04-19T20:00:09.402Z,1492632009.402 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2017-04-19T20:00:09.402Z,1492632009.402 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2017-04-19T20:00:09.698Z,1492632009.698 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2017-04-19T20:00:09.699Z,1492632009.699 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2017-04-19T20:00:09.787Z,1492632009.787 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2017-04-19T20:00:09.787Z,1492632009.787 [Supervisor](INFO): Opening Config file at: Config/Estimation.cfg
2017-04-19T20:00:09.933Z,1492632009.932 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Estimation
2017-04-19T20:00:09.933Z,1492632009.933 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2017-04-19T20:00:10.133Z,1492632010.133 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2017-04-19T20:00:10.134Z,1492632010.134 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2017-04-19T20:00:10.583Z,1492632010.583 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2017-04-19T20:00:10.584Z,1492632010.584 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2017-04-19T20:00:10.961Z,1492632010.961 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2017-04-19T20:00:10.961Z,1492632010.961 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2017-04-19T20:00:11.276Z,1492632011.276 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2017-04-19T20:00:11.277Z,1492632011.277 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2017-04-19T20:00:11.390Z,1492632011.390 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2017-04-19T20:00:11.393Z,1492632011.393 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-opah/
2017-04-19T20:00:11.394Z,1492632011.394 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/secure.cfg
2017-04-19T20:00:11.483Z,1492632011.483 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/vehicle.cfg
2017-04-19T20:00:11.654Z,1492632011.654 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Battery.cfg
2017-04-19T20:00:11.897Z,1492632011.897 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Battery
2017-04-19T20:00:11.897Z,1492632011.897 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Control.cfg
2017-04-19T20:00:11.998Z,1492632011.998 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/BIT.cfg
2017-04-19T20:00:12.112Z,1492632012.112 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Navigation.cfg
2017-04-19T20:00:12.221Z,1492632012.221 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Science.cfg
2017-04-19T20:00:12.369Z,1492632012.369 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Sensor.cfg
2017-04-19T20:00:12.538Z,1492632012.538 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Servo.cfg
2017-04-19T20:00:12.650Z,1492632012.650 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/Simulator.cfg
2017-04-19T20:00:12.742Z,1492632012.742 [Supervisor](INFO): Opening Config file at: Config/lrauv-opah/logger.cfg
2017-04-19T20:00:12.846Z,1492632012.846 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-opah/root/
2017-04-19T20:00:12.846Z,1492632012.846 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2017-04-19T20:00:12.848Z,1492632012.848 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2017-04-19T20:00:12.899Z,1492632012.898 [VerticalControl](DEBUG): Construct VerticalControl.
2017-04-19T20:00:13.025Z,1492632013.025 [VerticalControl] Loaded
2017-04-19T20:00:13.025Z,1492632013.025 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2017-04-19T20:00:13.025Z,1492632013.026 [HorizontalControl](DEBUG): Construct HorizontalControl.
2017-04-19T20:00:13.101Z,1492632013.101 [HorizontalControl] Loaded
2017-04-19T20:00:13.101Z,1492632013.101 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2017-04-19T20:00:13.102Z,1492632013.102 [SpeedControl](DEBUG): Construct SpeedControl.
2017-04-19T20:00:13.108Z,1492632013.108 [SpeedControl] Loaded
2017-04-19T20:00:13.108Z,1492632013.108 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2017-04-19T20:00:13.109Z,1492632013.109 [LoopControl](DEBUG): Construct LoopControl.
2017-04-19T20:00:13.110Z,1492632013.110 [LoopControl] Loaded
2017-04-19T20:00:13.110Z,1492632013.110 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2017-04-19T20:00:13.110Z,1492632013.110 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2017-04-19T20:00:13.111Z,1492632013.111 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2017-04-19T20:00:13.244Z,1492632013.244 [SBIT](DEBUG): Construct Startup Built In Test.
2017-04-19T20:00:13.257Z,1492632013.257 [SBIT] Loaded
2017-04-19T20:00:13.257Z,1492632013.257 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2017-04-19T20:00:13.258Z,1492632013.258 [IBIT](DEBUG): Construct Initiated Built In Test.
2017-04-19T20:00:13.288Z,1492632013.288 [IBIT] Loaded
2017-04-19T20:00:13.288Z,1492632013.288 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2017-04-19T20:00:13.292Z,1492632013.292 [CBIT](DEBUG): Construct Continuous Built In Test.
2017-04-19T20:00:13.449Z,1492632013.449 [CBIT] Loaded
2017-04-19T20:00:13.450Z,1492632013.450 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2017-04-19T20:00:13.450Z,1492632013.450 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2017-04-19T20:00:13.451Z,1492632013.451 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2017-04-19T20:00:13.478Z,1492632013.478 [DepthRateCalculator] Loaded
2017-04-19T20:00:13.478Z,1492632013.478 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2017-04-19T20:00:13.484Z,1492632013.484 [PitchRateCalculator] Loaded
2017-04-19T20:00:13.484Z,1492632013.484 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2017-04-19T20:00:13.496Z,1492632013.496 [SpeedCalculator] Loaded
2017-04-19T20:00:13.496Z,1492632013.497 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2017-04-19T20:00:13.520Z,1492632013.520 [TempGradientCalculator] Loaded
2017-04-19T20:00:13.520Z,1492632013.520 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2017-04-19T20:00:13.543Z,1492632013.543 [VerticalTemperatureHomogeneityIndexCalculator] Loaded
2017-04-19T20:00:13.567Z,1492632013.567 [ComponentRegistry](DEBUG): SyncComponent "VerticalTemperatureHomogeneityIndexCalculator" handled in the control thread.
2017-04-19T20:00:13.581Z,1492632013.581 [YawRateCalculator] Loaded
2017-04-19T20:00:13.581Z,1492632013.581 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2017-04-19T20:00:13.581Z,1492632013.582 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2017-04-19T20:00:13.582Z,1492632013.582 [Module Loader](DEBUG): Loading Module at Modules/Estimation.so
2017-04-19T20:00:13.666Z,1492632013.666 [StratificationFrontDetector](INFO): threshold set to: 0.399988 degC
2017-04-19T20:00:13.666Z,1492632013.666 [StratificationFrontDetector](DEBUG): (re)initializing
2017-04-19T20:00:13.666Z,1492632013.667 [StratificationFrontDetector] Loaded
2017-04-19T20:00:13.667Z,1492632013.667 [ComponentRegistry](DEBUG): SyncComponent "StratificationFrontDetector" handled in the control thread.
2017-04-19T20:00:13.692Z,1492632013.692 [DepAvgTempFrontDetector] Loaded
2017-04-19T20:00:13.692Z,1492632013.692 [ComponentRegistry](DEBUG): SyncComponent "DepAvgTempFrontDetector" handled in the control thread.
2017-04-19T20:00:13.693Z,1492632013.693 [Module Loader](DEBUG): Loaded Module: Estimation (Contains the base estimation components)
2017-04-19T20:00:13.693Z,1492632013.693 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2017-04-19T20:00:13.940Z,1492632013.940 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2017-04-19T20:00:13.940Z,1492632013.940 [Module Loader](DEBUG): Loading Module at Modules/Navigation.so
2017-04-19T20:00:14.043Z,1492632014.043 [DeadReckonUsingMultipleVelocitySources] Loaded
2017-04-19T20:00:14.043Z,1492632014.043 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingMultipleVelocitySources" handled in the control thread.
2017-04-19T20:00:14.101Z,1492632014.101 [DeadReckonUsingSpeedCalculator] Loaded
2017-04-19T20:00:14.101Z,1492632014.102 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonUsingSpeedCalculator" handled in the control thread.
2017-04-19T20:00:14.161Z,1492632014.161 [DeadReckonWithRespectToSeafloor] Loaded
2017-04-19T20:00:14.161Z,1492632014.161 [ComponentRegistry](DEBUG): SyncComponent "DeadReckonWithRespectToSeafloor" handled in the control thread.
2017-04-19T20:00:14.179Z,1492632014.179 [NavChart] Loaded
2017-04-19T20:00:14.179Z,1492632014.179 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2017-04-19T20:00:14.184Z,1492632014.184 [UniversalFixResidualReporter] Loaded
2017-04-19T20:00:14.184Z,1492632014.184 [ComponentRegistry](DEBUG): SyncComponent "UniversalFixResidualReporter" handled in the control thread.
2017-04-19T20:00:14.185Z,1492632014.185 [Module Loader](DEBUG): Loaded Module: Navigation (Contains the base navigation components)
2017-04-19T20:00:14.185Z,1492632014.185 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2017-04-19T20:00:14.192Z,1492632014.192 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2017-04-19T20:00:14.192Z,1492632014.192 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2017-04-19T20:00:14.347Z,1492632014.347 [Aanderaa_O2] Loaded
2017-04-19T20:00:14.348Z,1492632014.348 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2017-04-19T20:00:14.364Z,1492632014.364 [PAR_Licor] Loaded
2017-04-19T20:00:14.364Z,1492632014.364 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2017-04-19T20:00:14.433Z,1492632014.433 [WetLabsBB2FL] Loaded
2017-04-19T20:00:14.434Z,1492632014.434 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2017-04-19T20:00:14.435Z,1492632014.435 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407734E0
2017-04-19T20:00:14.435Z,1492632014.435 [WetLabsBB2FL ThreadHandler](INFO): Protected caller Thread ID is 4570
2017-04-19T20:00:14.436Z,1492632014.436 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2017-04-19T20:00:14.436Z,1492632014.436 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2017-04-19T20:00:14.743Z,1492632014.743 [DataOverHttps] Loaded
2017-04-19T20:00:14.743Z,1492632014.743 [ComponentRegistry](DEBUG): SyncComponent "DataOverHttps" handled in the control thread.
2017-04-19T20:00:14.758Z,1492632014.758 [Depth_Keller] Loaded
2017-04-19T20:00:14.758Z,1492632014.758 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2017-04-19T20:00:14.763Z,1492632014.763 [DropWeight] Loaded
2017-04-19T20:00:14.764Z,1492632014.764 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2017-04-19T20:00:14.863Z,1492632014.863 [NAL9602] Loaded
2017-04-19T20:00:14.863Z,1492632014.863 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2017-04-19T20:00:14.873Z,1492632014.873 [Onboard] Loaded
2017-04-19T20:00:14.873Z,1492632014.873 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2017-04-19T20:00:14.881Z,1492632014.881 [Radio_Surface] Loaded
2017-04-19T20:00:14.881Z,1492632014.881 [ComponentRegistry](DEBUG): Component "Radio_Surface" handled in its own thread.
2017-04-19T20:00:14.882Z,1492632014.882 [Radio_Surface ThreadHandler](DEBUG): Created PCaller Thread at 409BE4E0
2017-04-19T20:00:14.883Z,1492632014.883 [Radio_Surface ThreadHandler](INFO): Protected caller Thread ID is 4571
2017-04-19T20:00:15.028Z,1492632015.028 [DAT] Loaded
2017-04-19T20:00:15.028Z,1492632015.028 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2017-04-19T20:00:15.035Z,1492632015.035 [SCPI] Loaded
2017-04-19T20:00:15.036Z,1492632015.036 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2017-04-19T20:00:15.088Z,1492632015.088 [PNI_TCM] Loaded
2017-04-19T20:00:15.088Z,1492632015.088 [ComponentRegistry](DEBUG): SyncComponent "PNI_TCM" handled in the control thread.
2017-04-19T20:00:15.161Z,1492632015.161 [Rowe_600LCM] Loaded
2017-04-19T20:00:15.161Z,1492632015.161 [ComponentRegistry](DEBUG): Component "Rowe_600LCM" handled in its own thread.
2017-04-19T20:00:15.162Z,1492632015.162 [Rowe_600LCM ThreadHandler](DEBUG): Created PCaller Thread at 409EE4E0
2017-04-19T20:00:15.163Z,1492632015.163 [Rowe_600LCM ThreadHandler](INFO): Protected caller Thread ID is 4572
2017-04-19T20:00:17.161Z,1492632017.161 [BPC1] Loaded
2017-04-19T20:00:17.161Z,1492632017.161 [ComponentRegistry](DEBUG): SyncComponent "BPC1" handled in the control thread.
2017-04-19T20:00:17.162Z,1492632017.162 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2017-04-19T20:00:17.162Z,1492632017.162 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2017-04-19T20:00:17.265Z,1492632017.265 [BuoyancyServo] Loaded
2017-04-19T20:00:17.266Z,1492632017.266 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2017-04-19T20:00:17.278Z,1492632017.278 [ElevatorServo] Loaded
2017-04-19T20:00:17.278Z,1492632017.278 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2017-04-19T20:00:17.290Z,1492632017.290 [MassServo] Loaded
2017-04-19T20:00:17.291Z,1492632017.291 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2017-04-19T20:00:17.303Z,1492632017.303 [RudderServo] Loaded
2017-04-19T20:00:17.303Z,1492632017.303 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2017-04-19T20:00:17.315Z,1492632017.315 [ThrusterServo] Loaded
2017-04-19T20:00:17.316Z,1492632017.316 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2017-04-19T20:00:17.316Z,1492632017.316 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2017-04-19T20:00:17.317Z,1492632017.317 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2017-04-19T20:00:17.419Z,1492632017.419 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2017-04-19T20:00:17.420Z,1492632017.420 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2017-04-19T20:00:17.445Z,1492632017.445 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2017-04-19T20:00:17.450Z,1492632017.450 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2017-04-19T20:00:17.451Z,1492632017.451 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2017-04-19T20:00:17.458Z,1492632017.458 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2017-04-19T20:00:17.460Z,1492632017.460 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 40A9C4E0
2017-04-19T20:00:17.460Z,1492632017.460 [NavChartDb ThreadHandler](INFO): Protected caller Thread ID is 4573
2017-04-19T20:00:17.465Z,1492632017.465 [Supervisor](INFO): Main Thread ID is 4486
2017-04-19T20:00:17.465Z,1492632017.465 [Supervisor](DEBUG): Running supervisor.
2017-04-19T20:00:17.466Z,1492632017.466 [CommandLine ThreadHandler](INFO): Handler Thread ID is 4574
2017-04-19T20:00:17.469Z,1492632017.469 [controlThread ThreadHandler](INFO): Handler Thread ID is 4575
2017-04-19T20:00:17.470Z,1492632017.470 [controlThread](DEBUG): Initializing ControlThread
2017-04-19T20:00:17.471Z,1492632017.471 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2017-04-19T20:00:17.473Z,1492632017.473 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2017-04-19T20:00:17.474Z,1492632017.474 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2017-04-19T20:00:17.474Z,1492632017.474 [LoopControl](DEBUG): Initialize LoopControlComponent.
2017-04-19T20:00:17.474Z,1492632017.474 [SBIT](INFO): Initialize SBIT Component.
2017-04-19T20:00:17.475Z,1492632017.475 [SBIT](IMPORTANT): git: 2017-04-12-5-g071e189
2017-04-19T20:00:17.475Z,1492632017.475 [SBIT](INFO): git hash: 071e18912e7779044b4eb542ad1ada326376074c
2017-04-19T20:00:17.477Z,1492632017.477 [SBIT](INFO): Kernel Reporting Different Release From Configuration.
Kernel Expected: 2.6.27.8
Kernel Reported: 2.6.27.8-00011-g2bc81df-dirty
2017-04-19T20:00:17.477Z,1492632017.477 [SBIT](INFO): Kernel Reporting Different Version From Configuration.
Kernel Expected: #639 PREEMPT Wed Mar 12 12:53:33 PDT 2014
Kernel Reported: #646 PREEMPT Thu Feb 11 17:01:12 PST 2016
2017-04-19T20:00:17.478Z,1492632017.478 [SBIT](INFO): Beginning SBIT in 79.000000 seconds.
2017-04-19T20:00:17.479Z,1492632017.479 [IBIT](INFO): Initialize IBIT Component.
2017-04-19T20:00:17.479Z,1492632017.480 [CBIT](DEBUG): Initialize CBIT Component.
2017-04-19T20:00:17.480Z,1492632017.480 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2017-04-19T20:00:17.481Z,1492632017.481 [logger ThreadHandler](INFO): Handler Thread ID is 4576
2017-04-19T20:00:17.500Z,1492632017.500 [WetLabsBB2FL ThreadHandler](INFO): Handler Thread ID is 4577
2017-04-19T20:00:17.501Z,1492632017.501 [WetLabsBB2FL](INFO): Powering down
2017-04-19T20:00:17.520Z,1492632017.520 [Radio_Surface ThreadHandler](INFO): Handler Thread ID is 4578
2017-04-19T20:00:17.531Z,1492632017.531 [Radio_Surface](INFO): Powering up
2017-04-19T20:00:17.540Z,1492632017.540 [Rowe_600LCM ThreadHandler](INFO): Handler Thread ID is 4579
2017-04-19T20:00:17.541Z,1492632017.541 [Rowe_600LCM](INFO): Initializing
2017-04-19T20:00:17.541Z,1492632017.541 [Rowe_600LCM](INFO): Checking LCM
2017-04-19T20:00:17.541Z,1492632017.542 [Rowe_600LCM](INFO): LCM OK
2017-04-19T20:00:17.542Z,1492632017.542 [Rowe_600LCM](INFO): Powering up
2017-04-19T20:00:17.548Z,1492632017.548 [NavChartDb ThreadHandler](INFO): Handler Thread ID is 4580
2017-04-19T20:00:17.552Z,1492632017.552 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US1WC07M.000
2017-04-19T20:00:17.552Z,1492632017.552 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2017-04-19T20:00:17.552Z,1492632017.552 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US2WC11M.000
2017-04-19T20:00:17.552Z,1492632017.552 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2017-04-19T20:00:17.552Z,1492632017.552 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US3CA52M.000
2017-04-19T20:00:17.553Z,1492632017.553 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2017-04-19T20:00:17.553Z,1492632017.553 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US4CA60M.000
2017-04-19T20:00:17.553Z,1492632017.553 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2017-04-19T20:00:17.553Z,1492632017.553 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA50M.000
2017-04-19T20:00:17.553Z,1492632017.553 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2017-04-19T20:00:17.553Z,1492632017.553 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA61M.000
2017-04-19T20:00:17.554Z,1492632017.554 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2017-04-19T20:00:17.554Z,1492632017.554 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA62M.000
2017-04-19T20:00:17.554Z,1492632017.554 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2017-04-19T20:00:17.554Z,1492632017.554 [NavChartDb](INFO): Looking for Electronic Nav Chart file at: Resources/ElectronicNavigationCharts/US5CA83M.000
2017-04-19T20:00:17.554Z,1492632017.554 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2017-04-19T20:00:17.564Z,1492632017.564 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-04-19T20:00:17.564Z,1492632017.564 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2017-04-19T20:00:17.565Z,1492632017.565 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2017-04-19T20:00:17.565Z,1492632017.565 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2017-04-19T20:00:17.566Z,1492632017.566 [VerticalTemperatureHomogeneityIndexCalculator](DEBUG): (re)initializing
2017-04-19T20:00:17.566Z,1492632017.566 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2017-04-19T20:00:17.567Z,1492632017.567 [DeadReckonUsingMultipleVelocitySources](DEBUG): Initializing DeadReckonUsingMultipleVelocitySources component.
2017-04-19T20:00:17.568Z,1492632017.568 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider orientation measurement stale after 120s.
2017-04-19T20:00:17.568Z,1492632017.568 [DeadReckonUsingMultipleVelocitySources](INFO): Will consider velocity measurement stale after 20s.
2017-04-19T20:00:17.569Z,1492632017.569 [DeadReckonUsingSpeedCalculator](DEBUG): Initializing DeadReckonUsingSpeedCalculator component.
2017-04-19T20:00:17.569Z,1492632017.569 [DeadReckonUsingSpeedCalculator](INFO): Will consider orientation measurement stale after 120s.
2017-04-19T20:00:17.570Z,1492632017.570 [DeadReckonUsingSpeedCalculator](INFO): Will consider velocity measurement stale after 20s.
2017-04-19T20:00:17.570Z,1492632017.570 [DeadReckonWithRespectToSeafloor](DEBUG): Initializing DeadReckonWithRespectToSeafloor component.
2017-04-19T20:00:17.571Z,1492632017.570 [DeadReckonWithRespectToSeafloor](INFO): Will consider orientation measurement stale after 120s.
2017-04-19T20:00:17.571Z,1492632017.571 [DeadReckonWithRespectToSeafloor](INFO): Will consider velocity measurement stale after 20s.
2017-04-19T20:00:17.572Z,1492632017.572 [NavChart](DEBUG): Initialize NavChart Navigation.
2017-04-19T20:00:17.572Z,1492632017.572 [UniversalFixResidualReporter](DEBUG): Initializing UniversalFixResidualReporter component.
2017-04-19T20:00:17.597Z,1492632017.597 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2017-04-19T20:00:17.633Z,1492632017.633 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2017-04-19T20:00:17.672Z,1492632017.672 [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>


2017-04-19T20:00:17.673Z,1492632017.673 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2017-04-19T20:00:17.745Z,1492632017.745 [MissionManager](INFO): DefineArg Default.ElapsedSinceDefaultStarted = 0.000000 min
2017-04-19T20:00:17.746Z,1492632017.746 [Default:A.Wait](DEBUG): Construct Wait.
2017-04-19T20:00:17.756Z,1492632017.756 [Default:B.GoToSurface](DEBUG): Construct GoToSurface.
2017-04-19T20:00:17.783Z,1492632017.783 [Default:CheckIn:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2017-04-19T20:00:17.794Z,1492632017.794 [Default:CheckIn:C.Wait](DEBUG): Construct Wait.
2017-04-19T20:00:17.816Z,1492632017.816 [Default:E.Execute](DEBUG): Construct Execute.
2017-04-19T20:00:17.828Z,1492632017.828 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
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/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="Default">

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

    <Guidance:Wait>
        <Sequence/>
        <Description>
            Wait a moment to see if the scheduler starts a new mission before
            starting to actually run Default.
        </Description>
        <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">
            <Sequence/>
            <Timeout Duration="P5M"/>
            <Universal:time_fix/>
        </ReadDatum>

        <ReadDatum Id="Read_Iridium">
            <Sequence/>
            <Timeout Duration="P2H">
                <Guidance:Execute>
                    <Sequence/>
                    <Setting><Guidance:Execute.command/><String>Burn 300</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>


2017-04-19T20:00:17.834Z,1492632017.834 [controlThread](DEBUG): Component order: CycleStarter,Aanderaa_O2,PAR_Licor,DataOverHttps,Depth_Keller,DropWeight,NAL9602,Onboard,DAT,SCPI,PNI_TCM,BPC1,PAR_Licor,Depth_Keller,DepthRateCalculator,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,VerticalTemperatureHomogeneityIndexCalculator,YawRateCalculator,StratificationFrontDetector,DepAvgTempFrontDetector,DeadReckonUsingMultipleVelocitySources,DeadReckonUsingSpeedCalculator,DeadReckonWithRespectToSeafloor,NavChart,UniversalFixResidualReporter,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,LogSplitter,
2017-04-19T20:00:17.969Z,1492632017.969 [DataOverHttps](DEBUG): dashIP=134.89.2.23 starts with a digit so assuming it is a numeric IP
2017-04-19T20:00:18.189Z,1492632018.189 [DAT](INFO): Powering up
2017-04-19T20:00:18.189Z,1492632018.189 [DAT](DEBUG): Initializing DAT.
2017-04-19T20:00:18.468Z,1492632018.468 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T20:00:18.468Z,1492632018.468 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2017-04-19T20:00:18.471Z,1492632018.471 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:18.497Z,1492632018.497 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T20:00:18.519Z,1492632018.519 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T20:00:18.617Z,1492632018.617 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2017-04-19T20:00:18.623Z,1492632018.624 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2017-04-19T20:00:18.646Z,1492632018.646 [ElevatorServo](DEBUG): Initializing EZServoServo.
2017-04-19T20:00:18.651Z,1492632018.651 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2017-04-19T20:00:18.665Z,1492632018.665 [MassServo](DEBUG): Initializing EZServoServo.
2017-04-19T20:00:18.666Z,1492632018.666 [MassServo](DEBUG): Initializing MassServo.
2017-04-19T20:00:18.688Z,1492632018.688 [RudderServo](DEBUG): Initializing EZServoServo.
2017-04-19T20:00:18.695Z,1492632018.695 [RudderServo](DEBUG): Initializing RudderServo.
2017-04-19T20:00:18.709Z,1492632018.709 [ThrusterServo](DEBUG): Initializing EZServoServo.
2017-04-19T20:00:18.715Z,1492632018.716 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2017-04-19T20:00:18.999Z,1492632019.000 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2017-04-19T20:00:18.000Z,1492632019.000 [DropWeight] Hardware Fault, FailCount= 1
2017-04-19T20:00:18.000Z,1492632019.000 [DropWeight](ERROR): Hardware Fault
2017-04-19T20:00:19.083Z,1492632019.083 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T20:00:19.083Z,1492632019.083 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2017-04-19T20:00:19.086Z,1492632019.086 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:19.092Z,1492632019.092 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T20:00:19.093Z,1492632019.093 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T20:00:19.139Z,1492632019.139 [CommandLine](FAULT): Scheduling is paused
2017-04-19T20:00:19.140Z,1492632019.140 [CBIT](INFO): Critical error at 20170419T200018
2017-04-19T20:00:19.140Z,1492632019.141 [Supervisor](INFO): Stop Mission called by CBIT::checkCriticals
2017-04-19T20:00:19.143Z,1492632019.143 [CBIT](ERROR): Hardware Fault in component: DropWeight
2017-04-19T20:00:19.144Z,1492632019.144 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2017-04-19T20:00:21.876Z,1492632021.876 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of Rowe LCM interface
2017-04-19T20:00:22.038Z,1492632022.038 [Rowe_600LCM](INFO): Started Rowe LCM interface with command:nohup ./lrauv-framework/onboard/bin/roweadcp -ldir /mnt/mmc/LRAUV/Logs/latest/ -dev /dev/ttyB4 -b 38400 >& /dev/null &
2017-04-19T20:00:22.134Z,1492632022.134 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.bottom_track
2017-04-19T20:00:22.151Z,1492632022.151 [Rowe_600LCM](INFO): LCM subscribed to channel:Rowe_600LCM.adcp_dvl.vehicle_water_velocity
2017-04-19T20:00:22.152Z,1492632022.152 [Rowe_600LCM](INFO): LCM subscribed to channel:rowe_dvl.rowe
2017-04-19T20:00:29.728Z,1492632029.727 [NAL9602](INFO): Powering up NAL9602
2017-04-19T20:00:29.753Z,1492632029.753 [DAT](DEBUG): 
2017-04-19T20:00:29.754Z,1492632029.754 [DAT](DEBUG): Teledyne Benthos DAT-900 Series
2017-04-19T20:00:29.755Z,1492632029.755 [DAT](DEBUG): MF Frequency Band
2017-04-19T20:00:29.756Z,1492632029.756 [DAT](DEBUG): Directional Acoustic Transponder version 8.10.9
2017-04-19T20:00:29.757Z,1492632029.757 [DAT](DEBUG): Apr 19 2017 20:00:45 
2017-04-19T20:00:29.817Z,1492632029.817 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T20:00:29.817Z,1492632029.817 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2017-04-19T20:00:29.818Z,1492632029.818 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:29.819Z,1492632029.819 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T20:00:29.827Z,1492632029.827 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T20:00:30.126Z,1492632030.126 [CBIT](INFO): Critical error at 20170419T200019
2017-04-19T20:00:30.284Z,1492632030.284 [DAT](DEBUG): WARNING: battery low
2017-04-19T20:00:30.286Z,1492632030.286 [DAT](DEBUG): Features enabled [Bearing]
2017-04-19T20:00:30.340Z,1492632030.340 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T20:00:30.340Z,1492632030.340 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2017-04-19T20:00:30.344Z,1492632030.344 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:30.360Z,1492632030.360 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T20:00:30.363Z,1492632030.363 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T20:00:30.698Z,1492632030.698 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T20:00:30.699Z,1492632030.699 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2017-04-19T20:00:30.711Z,1492632030.711 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:30.720Z,1492632030.720 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T20:00:30.732Z,1492632030.732 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T20:00:30.949Z,1492632030.949 [Aanderaa_O2](INFO): Powering down
2017-04-19T20:00:31.238Z,1492632031.238 [DataOverHttps](INFO): setting available, lastComms_.elapsed()=0.031400
2017-04-19T20:00:31.304Z,1492632031.304 [DAT](INFO): entering command mode
2017-04-19T20:00:31.371Z,1492632031.371 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T20:00:31.371Z,1492632031.371 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2017-04-19T20:00:31.372Z,1492632031.372 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:31.373Z,1492632031.373 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T20:00:31.374Z,1492632031.374 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T20:00:31.473Z,1492632031.473 [DAT](DEBUG): checking for command mode acknowledgment
2017-04-19T20:00:31.505Z,1492632031.505 [DeadReckonUsingMultipleVelocitySources](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T20:00:31.505Z,1492632031.505 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2017-04-19T20:00:31.506Z,1492632031.506 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:31.515Z,1492632031.515 [DeadReckonUsingSpeedCalculator](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T20:00:31.516Z,1492632031.516 [DeadReckonWithRespectToSeafloor](DEBUG): Starting up and don't have orientation data yet.
2017-04-19T20:00:31.833Z,1492632031.833 [DAT](DEBUG): checking for command mode acknowledgment
2017-04-19T20:00:31.877Z,1492632031.877 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2017-04-19T20:00:31.878Z,1492632031.878 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:32.305Z,1492632032.305 [DAT](DEBUG): checking for command mode acknowledgment
2017-04-19T20:00:32.479Z,1492632032.479 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2017-04-19T20:00:32.482Z,1492632032.483 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:32.874Z,1492632032.875 [DAT](DEBUG): checking for command mode acknowledgment
2017-04-19T20:00:33.075Z,1492632033.075 [DeadReckonUsingMultipleVelocitySources](DEBUG): All data for platform velocity is invalid.
2017-04-19T20:00:33.086Z,1492632033.086 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:33.502Z,1492632033.502 [DAT](DEBUG): checking for command mode acknowledgment
2017-04-19T20:00:33.574Z,1492632033.574 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:33.865Z,1492632033.865 [DAT](DEBUG): checking for command mode acknowledgment
2017-04-19T20:00:33.946Z,1492632033.946 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:34.317Z,1492632034.317 [DAT](DEBUG): checking for command mode acknowledgment
2017-04-19T20:00:34.590Z,1492632034.591 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:34.842Z,1492632034.842 [DAT](DEBUG): checking for command mode acknowledgment
2017-04-19T20:00:34.987Z,1492632034.987 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:35.222Z,1492632035.223 [DAT](DEBUG): checking for command mode acknowledgment
2017-04-19T20:00:35.295Z,1492632035.295 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:35.565Z,1492632035.565 [DAT](DEBUG): checking for command mode acknowledgment
2017-04-19T20:00:35.615Z,1492632035.615 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:35.954Z,1492632035.954 [DAT](DEBUG): checking for command mode acknowledgment
2017-04-19T20:00:36.018Z,1492632036.018 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:36.373Z,1492632036.373 [DAT](DEBUG): checking for command mode acknowledgment
2017-04-19T20:00:36.521Z,1492632036.521 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:36.792Z,1492632036.792 [DAT](DEBUG): checking for command mode acknowledgment
2017-04-19T20:00:36.979Z,1492632036.980 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:37.200Z,1492632037.200 [DAT](DEBUG): checking for command mode acknowledgment
2017-04-19T20:00:37.297Z,1492632037.297 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:37.553Z,1492632037.553 [DAT](DEBUG): checking for command mode acknowledgment
2017-04-19T20:00:37.610Z,1492632037.610 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:37.953Z,1492632037.953 [DAT](DEBUG): checking for command mode acknowledgment
2017-04-19T20:00:37.987Z,1492632037.987 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:38.352Z,1492632038.352 [DAT](DEBUG): checking for command mode acknowledgment
2017-04-19T20:00:38.491Z,1492632038.491 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:38.768Z,1492632038.768 [DAT](DEBUG): checking for command mode acknowledgment
2017-04-19T20:00:38.927Z,1492632038.927 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:39.210Z,1492632039.210 [DAT](DEBUG): checking for command mode acknowledgment
2017-04-19T20:00:39.406Z,1492632039.406 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:39.600Z,1492632039.600 [DAT](FAULT): invalid communications rate; deviceResponse_:Teledyne Benthos DAT-900 Series
MF Frequency Band
Directional Acoustic Transponder version 8.10.9
Apr 19 2017 20:00:55 
WARNING: battery low
Features enabled [Bearing]
WARNING: Modem reset during xmit; lowering xmit power level by 3 dB.
WARNING: Reduced xmit power level to 7 due to weak battery.
CONNECT 00800 bits/sec
2017-04-19T20:00:39.600Z,1492632039.600 [DAT] Communications Fault, FailCount= 1
2017-04-19T20:00:39.600Z,1492632039.600 [DAT](ERROR): Communications Fault
2017-04-19T20:00:39.664Z,1492632039.664 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:39.713Z,1492632039.713 [CBIT](ERROR): Communications Fault in component: DAT
2017-04-19T20:00:39.949Z,1492632039.949 [DAT](INFO): Powering down
2017-04-19T20:00:40.043Z,1492632040.043 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:40.394Z,1492632040.394 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:40.748Z,1492632040.748 [NAL9602](INFO): NAL9602 initialized
2017-04-19T20:00:40.986Z,1492632040.986 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:41.176Z,1492632041.176 [CBIT](INFO): Clearing failed state for component DAT
2017-04-19T20:00:41.177Z,1492632041.177 [DAT] No Fault, FailCount= 1
2017-04-19T20:00:41.597Z,1492632041.597 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:41.855Z,1492632041.855 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:42.243Z,1492632042.243 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:42.678Z,1492632042.678 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:43.022Z,1492632043.022 [DAT](INFO): Powering up
2017-04-19T20:00:43.022Z,1492632043.022 [DAT](DEBUG): Initializing DAT.
2017-04-19T20:00:43.192Z,1492632043.193 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:43.614Z,1492632043.614 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:43.847Z,1492632043.847 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:44.433Z,1492632044.433 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:44.885Z,1492632044.885 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:45.318Z,1492632045.318 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:45.502Z,1492632045.503 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:45.886Z,1492632045.886 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:46.531Z,1492632046.531 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:46.934Z,1492632046.934 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:47.290Z,1492632047.290 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:47.582Z,1492632047.582 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:47.956Z,1492632047.955 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:48.559Z,1492632048.559 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:48.986Z,1492632048.986 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:49.306Z,1492632049.306 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:49.618Z,1492632049.618 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:50.045Z,1492632050.045 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:50.679Z,1492632050.679 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:51.208Z,1492632051.208 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:51.472Z,1492632051.471 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:51.872Z,1492632051.872 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:52.250Z,1492632052.250 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:52.685Z,1492632052.685 [DAT](DEBUG): 
2017-04-19T20:00:52.888Z,1492632052.888 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:53.451Z,1492632053.451 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:53.693Z,1492632053.693 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:54.057Z,1492632054.057 [DAT](DEBUG): Teledyne Benthos DAT-900 Series
2017-04-19T20:00:54.057Z,1492632054.057 [DAT](DEBUG): MF Frequency Band
2017-04-19T20:00:54.059Z,1492632054.059 [DAT](DEBUG): Directional Acoustic Transponder version 8.10.9
2017-04-19T20:00:54.059Z,1492632054.059 [DAT](DEBUG): Apr 19 2017 20:01:10 
2017-04-19T20:00:54.106Z,1492632054.106 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:54.466Z,1492632054.466 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:54.856Z,1492632054.856 [DAT](DEBUG): WARNING: battery low
2017-04-19T20:00:54.858Z,1492632054.858 [DAT](DEBUG): Features enabled [Bearing]
2017-04-19T20:00:55.007Z,1492632055.007 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:55.400Z,1492632055.400 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:55.674Z,1492632055.674 [DAT](INFO): entering command mode
2017-04-19T20:00:55.801Z,1492632055.801 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:56.031Z,1492632056.030 [DAT](DEBUG): checking for command mode acknowledgment
2017-04-19T20:00:56.081Z,1492632056.081 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:56.438Z,1492632056.438 [DAT](DEBUG): checking for command mode acknowledgment
2017-04-19T20:00:56.467Z,1492632056.467 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:56.867Z,1492632056.866 [DAT](DEBUG): checking for command mode acknowledgment
2017-04-19T20:00:56.954Z,1492632056.954 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:57.272Z,1492632057.272 [DAT](DEBUG): checking for command mode acknowledgment
2017-04-19T20:00:57.455Z,1492632057.455 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:57.767Z,1492632057.767 [DAT](DEBUG): checking for command mode acknowledgment
2017-04-19T20:00:57.807Z,1492632057.806 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:58.089Z,1492632058.089 [DAT](DEBUG): checking for command mode acknowledgment
2017-04-19T20:00:58.149Z,1492632058.149 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:58.530Z,1492632058.530 [DAT](DEBUG): checking for command mode acknowledgment
2017-04-19T20:00:58.659Z,1492632058.658 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:58.933Z,1492632058.933 [DAT](DEBUG): checking for command mode acknowledgment
2017-04-19T20:00:59.090Z,1492632059.090 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:59.304Z,1492632059.304 [DAT](DEBUG): checking for command mode acknowledgment
2017-04-19T20:00:59.338Z,1492632059.338 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:00:59.700Z,1492632059.700 [DAT](DEBUG): checking for command mode acknowledgment
2017-04-19T20:00:59.726Z,1492632059.726 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:00.100Z,1492632060.100 [DAT](DEBUG): checking for command mode acknowledgment
2017-04-19T20:01:00.333Z,1492632060.333 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:00.590Z,1492632060.589 [DAT](DEBUG): checking for command mode acknowledgment
2017-04-19T20:01:00.730Z,1492632060.730 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:00.973Z,1492632060.973 [DAT](DEBUG): checking for command mode acknowledgment
2017-04-19T20:01:01.100Z,1492632061.100 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:01.326Z,1492632061.326 [DAT](DEBUG): checking for command mode acknowledgment
2017-04-19T20:01:01.364Z,1492632061.364 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:01.732Z,1492632061.732 [DAT](DEBUG): checking for command mode acknowledgment
2017-04-19T20:01:01.780Z,1492632061.780 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:02.130Z,1492632062.129 [DAT](DEBUG): checking for command mode acknowledgment
2017-04-19T20:01:02.286Z,1492632062.286 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:02.551Z,1492632062.551 [DAT](DEBUG): checking for command mode acknowledgment
2017-04-19T20:01:02.703Z,1492632062.703 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:02.965Z,1492632062.965 [DAT](DEBUG): checking for command mode acknowledgment
2017-04-19T20:01:03.090Z,1492632063.090 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:03.328Z,1492632063.328 [DAT](DEBUG): checking for command mode acknowledgment
2017-04-19T20:01:03.370Z,1492632063.370 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:03.747Z,1492632063.747 [DAT](DEBUG): checking for command mode acknowledgment
2017-04-19T20:01:03.820Z,1492632063.820 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:04.299Z,1492632064.299 [DAT](FAULT): invalid communications rate; deviceResponse_:Teledyne Benthos DAT-900 Series
MF Frequency Band
Directional Acoustic Transponder version 8.10.9
Apr 19 2017 20:01:19 
WARNING: battery low
Features enabled [Bearing]
WARNING: Modem reset during xmit; lowering xmit power level by 3 dB.
WARNING: Reduced xmit power level to 7 due to weak battery.
CONNECT 00800 bits/sec
2017-04-19T20:01:04.312Z,1492632064.312 [DAT] Communications Fault, FailCount= 2
2017-04-19T20:01:04.312Z,1492632064.312 [DAT](ERROR): Communications Fault
2017-04-19T20:01:04.445Z,1492632064.445 [BPC1](ERROR): Failed to receive battery data
2017-04-19T20:01:04.445Z,1492632064.445 [BPC1] Communications Fault, FailCount= 1
2017-04-19T20:01:04.446Z,1492632064.446 [BPC1](ERROR): Communications Fault
2017-04-19T20:01:04.537Z,1492632064.537 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:04.695Z,1492632064.694 [CBIT](ERROR): Communications Fault in component: DAT
2017-04-19T20:01:04.708Z,1492632064.708 [CBIT](ERROR): Communications Fault in component: BPC1
2017-04-19T20:01:04.882Z,1492632064.882 [DAT](INFO): Powering down
2017-04-19T20:01:05.101Z,1492632065.101 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:05.417Z,1492632065.417 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:05.808Z,1492632065.808 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:05.888Z,1492632065.888 [CBIT](INFO): Clearing failed state for component DAT
2017-04-19T20:01:05.888Z,1492632065.888 [DAT] No Fault, FailCount= 2
2017-04-19T20:01:05.888Z,1492632065.888 [CBIT](INFO): Clearing failed state for component BPC1
2017-04-19T20:01:05.889Z,1492632065.889 [BPC1] No Fault, FailCount= 1
2017-04-19T20:01:06.337Z,1492632066.337 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:06.862Z,1492632066.862 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:07.437Z,1492632067.437 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:07.606Z,1492632067.606 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:07.938Z,1492632067.938 [DAT](INFO): Powering up
2017-04-19T20:01:07.938Z,1492632067.938 [DAT](DEBUG): Initializing DAT.
2017-04-19T20:01:07.987Z,1492632067.987 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:08.433Z,1492632068.433 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:08.950Z,1492632068.950 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:09.512Z,1492632069.511 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:09.890Z,1492632069.890 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:10.191Z,1492632070.191 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:10.578Z,1492632070.578 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:11.184Z,1492632071.184 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:11.697Z,1492632071.697 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:11.904Z,1492632071.904 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:12.486Z,1492632072.486 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:13.030Z,1492632073.030 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:13.317Z,1492632073.317 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:13.669Z,1492632073.669 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:14.057Z,1492632074.057 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:14.702Z,1492632074.702 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:15.217Z,1492632075.217 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:15.419Z,1492632075.419 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:15.784Z,1492632075.784 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:16.423Z,1492632076.422 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:16.931Z,1492632076.931 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:17.317Z,1492632077.317 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:17.615Z,1492632077.615 [DAT](DEBUG): 
2017-04-19T20:01:17.661Z,1492632077.661 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:18.054Z,1492632078.055 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:18.748Z,1492632078.748 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:24.998Z,1492632084.999 [CommandLine](IMPORTANT): got command quit
2017-04-19T20:01:26.018Z,1492632086.018 [CommandLine ThreadHandler](INFO): Uninitializing protected caller thread.
2017-04-19T20:01:26.019Z,1492632086.019 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-04-19T20:01:26.172Z,1492632086.172 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2017-04-19T20:01:26.172Z,1492632086.172 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-04-19T20:01:26.175Z,1492632086.174 [CommandLine](INFO): Join timeout helper Thread ID is 4596
2017-04-19T20:01:26.196Z,1492632086.196 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2017-04-19T20:01:26.196Z,1492632086.196 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-04-19T20:01:26.198Z,1492632086.198 [NavChartDb](INFO): Join timeout helper Thread ID is 4597
2017-04-19T20:01:26.758Z,1492632086.758 [NavChartDb ThreadHandler](INFO): Uninitializing protected caller thread.
2017-04-19T20:01:26.776Z,1492632086.776 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-04-19T20:01:26.780Z,1492632086.780 [ComponentRegistry](INFO): Shutting down Rowe_600LCM ThreadHandler
2017-04-19T20:01:26.781Z,1492632086.781 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2017-04-19T20:01:26.782Z,1492632086.782 [Rowe_600LCM](INFO): Join timeout helper Thread ID is 4598
2017-04-19T20:01:27.080Z,1492632087.080 [Rowe_600LCM ThreadHandler](INFO): Uninitializing protected caller thread.
2017-04-19T20:01:27.081Z,1492632087.081 [Rowe_600LCM](INFO): Stopping potential previous instance(s) of roweadcp LCM interface
2017-04-19T20:01:27.492Z,1492632087.492 [Rowe_600LCM](INFO): Powering down
2017-04-19T20:01:27.493Z,1492632087.493 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2017-04-19T20:01:27.503Z,1492632087.503 [ComponentRegistry](INFO): Shutting down Radio_Surface ThreadHandler
2017-04-19T20:01:27.504Z,1492632087.504 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-04-19T20:01:27.505Z,1492632087.505 [Radio_Surface](INFO): Join timeout helper Thread ID is 4600
2017-04-19T20:01:27.535Z,1492632087.535 [Radio_Surface ThreadHandler](INFO): Uninitializing protected caller thread.
2017-04-19T20:01:27.536Z,1492632087.536 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-04-19T20:01:27.551Z,1492632087.551 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2017-04-19T20:01:27.551Z,1492632087.551 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-04-19T20:01:27.552Z,1492632087.552 [WetLabsBB2FL](INFO): Join timeout helper Thread ID is 4601
2017-04-19T20:01:27.947Z,1492632087.947 [WetLabsBB2FL ThreadHandler](INFO): Uninitializing protected caller thread.
2017-04-19T20:01:27.948Z,1492632087.948 [WetLabsBB2FL](INFO): Powering down
2017-04-19T20:01:27.949Z,1492632087.949 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-04-19T20:01:27.968Z,1492632087.968 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2017-04-19T20:01:27.968Z,1492632087.968 [logger ThreadHandler](INFO): Thread cancelled.
2017-04-19T20:01:27.969Z,1492632087.969 [logger](INFO): Join timeout helper Thread ID is 4602
2017-04-19T20:01:27.970Z,1492632087.970 [logger ThreadHandler](INFO): Uninitializing protected caller thread.
2017-04-19T20:01:27.970Z,1492632087.970 [logger ThreadHandler](INFO): Thread cancelled.
2017-04-19T20:01:27.984Z,1492632087.984 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2017-04-19T20:01:27.984Z,1492632087.984 [CommandLine ThreadHandler](INFO): Thread cancelled.
2017-04-19T20:01:27.984Z,1492632087.984 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2017-04-19T20:01:27.984Z,1492632087.984 [controlThread ThreadHandler](INFO): Thread cancelled.
2017-04-19T20:01:27.985Z,1492632087.985 [controlThread](INFO): Join timeout helper Thread ID is 4603
2017-04-19T20:01:31.222Z,1492632091.222 [DAT](DEBUG): Teledyne Benthos DAT-900 Series
2017-04-19T20:01:31.224Z,1492632091.224 [DAT](DEBUG): MF Frequency Band
2017-04-19T20:01:31.227Z,1492632091.227 [DAT](DEBUG): Directional Acoustic Transponder version 8.10.9
2017-04-19T20:01:31.228Z,1492632091.228 [DAT](DEBUG): Apr 19 2017 20:01:35 
2017-04-19T20:01:31.229Z,1492632091.229 [DAT](DEBUG): WARNING: battery low
2017-04-19T20:01:31.231Z,1492632091.231 [DAT](DEBUG): Features enabled [Bearing]
2017-04-19T20:01:31.232Z,1492632091.232 [DAT](DEBUG): CONNECT 00800 bits/sec
2017-04-19T20:01:31.233Z,1492632091.233 [DAT](DEBUG): 
2017-04-19T20:01:31.234Z,1492632091.234 [DAT](DEBUG): Teledyne Benthos DAT-900 Series
2017-04-19T20:01:31.235Z,1492632091.235 [DAT](DEBUG): MF Frequency Band
2017-04-19T20:01:31.237Z,1492632091.237 [DAT](DEBUG): Directional Acoustic Transponder version 8.10.9
2017-04-19T20:01:31.238Z,1492632091.238 [DAT](DEBUG): Apr 19 2017 20:01:44 
2017-04-19T20:01:31.239Z,1492632091.239 [DAT](DEBUG): WARNING: battery low
2017-04-19T20:01:31.240Z,1492632091.240 [DAT](DEBUG): Features enabled [Bearing]
2017-04-19T20:01:31.242Z,1492632091.242 [DAT](DEBUG): WARNING: Modem reset during xmit; lowering xmit power level by 3 dB.
2017-04-19T20:01:31.244Z,1492632091.244 [DAT](DEBUG): WARNING: Reduced xmit power level to 7 due to weak battery.
2017-04-19T20:01:31.256Z,1492632091.256 [BPC1](ERROR): Failed to receive battery data
2017-04-19T20:01:31.256Z,1492632091.256 [BPC1] Communications Fault, FailCount= 2
2017-04-19T20:01:31.256Z,1492632091.256 [BPC1](ERROR): Communications Fault
2017-04-19T20:01:31.270Z,1492632091.270 [DeadReckonUsingMultipleVelocitySources](DEBUG): Will not write estimated position: latitudeAccuracy_ = nan, longitudeAccuracy_ = nan, depthAccuracy_ = nan
2017-04-19T20:01:31.286Z,1492632091.286 [CBIT](ERROR): Communications Fault in component: BPC1
2017-04-19T20:01:31.288Z,1492632091.288 [controlThread ThreadHandler](INFO): Uninitializing protected caller thread.
2017-04-19T20:01:31.288Z,1492632091.288 [controlThread](DEBUG): Uninitializing ControlThread
2017-04-19T20:01:31.289Z,1492632091.289 [Aanderaa_O2](INFO): Powering down
2017-04-19T20:01:31.293Z,1492632091.293 [NAL9602](INFO): Powering down
2017-04-19T20:01:31.294Z,1492632091.294 [DAT](INFO): Powering down
2017-04-19T20:01:31.416Z,1492632091.416 [PNI_TCM](INFO): Powering down
2017-04-19T20:01:31.505Z,1492632091.505 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2017-04-19T20:01:31.507Z,1492632091.507 [NavChart](DEBUG): Uninitialize NavChart Navigation.
2017-04-19T20:01:31.508Z,1492632091.508 [MissionManager](INFO): Uninitializing Mission Default
2017-04-19T20:01:31.511Z,1492632091.511 [MissionManager](INFO): Uninitializing Mission Startup
2017-04-19T20:01:31.512Z,1492632091.512 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2017-04-19T20:01:31.512Z,1492632091.512 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2017-04-19T20:01:31.512Z,1492632091.513 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2017-04-19T20:01:31.513Z,1492632091.513 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2017-04-19T20:01:31.513Z,1492632091.513 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2017-04-19T20:01:31.513Z,1492632091.513 [BuoyancyServo](INFO): Powering down
2017-04-19T20:01:31.527Z,1492632091.527 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2017-04-19T20:01:31.527Z,1492632091.527 [ElevatorServo](INFO): Powering down
2017-04-19T20:01:31.548Z,1492632091.547 [MassServo](DEBUG): Uninitialize Mass Servo.
2017-04-19T20:01:31.548Z,1492632091.548 [MassServo](INFO): Powering down
2017-04-19T20:01:31.549Z,1492632091.549 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2017-04-19T20:01:31.549Z,1492632091.549 [RudderServo](INFO): Powering down
2017-04-19T20:01:31.549Z,1492632091.549 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2017-04-19T20:01:31.550Z,1492632091.550 [ThrusterServo](INFO): Powering down
2017-04-19T20:01:31.550Z,1492632091.550 [SBIT](DEBUG): Uninitialize SBIT Component.
2017-04-19T20:01:31.551Z,1492632091.551 [IBIT](DEBUG): Uninitialize IBIT Component.
2017-04-19T20:01:31.551Z,1492632091.551 [CBIT](DEBUG): Uninitialize CBIT Component.
2017-04-19T20:01:31.552Z,1492632091.552 [controlThread ThreadHandler](INFO): Thread cancelled.
2017-04-19T20:01:31.614Z,1492632091.614 [WetLabsBB2FL ThreadHandler](INFO): Thread cancelled.
2017-04-19T20:01:31.674Z,1492632091.674 [Rowe_600LCM ThreadHandler](INFO): Thread cancelled.
2017-04-19T20:01:31.708Z,1492632091.708 [Radio_Surface ThreadHandler](INFO): Thread cancelled.
2017-04-19T20:01:31.868Z,1492632091.868 [NavChartDb ThreadHandler](INFO): Thread cancelled.
2017-04-19T20:01:31.915Z,1492632091.915 [logger ThreadHandler](INFO): Thread cancelled.