2013-09-16T22:55:17.623Z,1379372117.623 [Supervisor](DEBUG): Initializing supervisor.
2013-09-16T22:55:17.626Z,1379372117.626 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0
2013-09-16T22:55:17.626Z,1379372117.626 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2013-09-16T22:55:17.627Z,1379372117.627 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4036B4E0
2013-09-16T22:55:17.630Z,1379372117.630 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2013-09-16T22:55:17.641Z,1379372117.641 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2013-09-16T22:55:17.642Z,1379372117.642 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4039B4E0
2013-09-16T22:55:17.643Z,1379372117.643 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2013-09-16T22:55:17.644Z,1379372117.644 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403CB4E0
2013-09-16T22:55:17.644Z,1379372117.644 [Supervisor](INFO): Looking for Config files in directory: Config/
2013-09-16T22:55:17.645Z,1379372117.645 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2013-09-16T22:55:17.926Z,1379372117.926 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2013-09-16T22:55:17.927Z,1379372117.927 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2013-09-16T22:55:18.119Z,1379372118.119 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2013-09-16T22:55:18.120Z,1379372118.120 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2013-09-16T22:55:18.214Z,1379372118.214 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2013-09-16T22:55:18.214Z,1379372118.214 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2013-09-16T22:55:18.328Z,1379372118.328 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2013-09-16T22:55:18.329Z,1379372118.329 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2013-09-16T22:55:18.472Z,1379372118.472 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2013-09-16T22:55:18.472Z,1379372118.472 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2013-09-16T22:55:18.711Z,1379372118.711 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2013-09-16T22:55:18.712Z,1379372118.712 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2013-09-16T22:55:18.875Z,1379372118.875 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2013-09-16T22:55:18.876Z,1379372118.876 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2013-09-16T22:55:19.137Z,1379372119.137 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2013-09-16T22:55:19.138Z,1379372119.138 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2013-09-16T22:55:19.239Z,1379372119.239 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2013-09-16T22:55:19.240Z,1379372119.240 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2013-09-16T22:55:19.653Z,1379372119.653 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2013-09-16T22:55:19.654Z,1379372119.654 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2013-09-16T22:55:19.764Z,1379372119.764 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2013-09-16T22:55:19.764Z,1379372119.764 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2013-09-16T22:55:19.850Z,1379372119.850 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2013-09-16T22:55:19.851Z,1379372119.851 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2013-09-16T22:55:19.948Z,1379372119.948 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2013-09-16T22:55:20.074Z,1379372120.074 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2013-09-16T22:55:20.168Z,1379372120.168 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2013-09-16T22:55:20.271Z,1379372120.271 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2013-09-16T22:55:20.366Z,1379372120.366 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2013-09-16T22:55:20.480Z,1379372120.480 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2013-09-16T22:55:20.574Z,1379372120.574 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2013-09-16T22:55:20.659Z,1379372120.659 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/LOGIN/
2013-09-16T22:55:20.660Z,1379372120.660 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2013-09-16T22:55:20.663Z,1379372120.663 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2013-09-16T22:55:20.822Z,1379372120.822 [InternalSim] Loaded
2013-09-16T22:55:20.823Z,1379372120.823 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2013-09-16T22:55:20.823Z,1379372120.823 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2013-09-16T22:55:20.824Z,1379372120.824 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2013-09-16T22:55:20.910Z,1379372120.910 [SBIT](DEBUG): Construct Startup Built In Test.
2013-09-16T22:55:20.938Z,1379372120.938 [SBIT] Loaded
2013-09-16T22:55:20.939Z,1379372120.939 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2013-09-16T22:55:20.939Z,1379372120.939 [IBIT](DEBUG): Construct Initiated Built In Test.
2013-09-16T22:55:20.968Z,1379372120.968 [IBIT] Loaded
2013-09-16T22:55:20.968Z,1379372120.968 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2013-09-16T22:55:20.971Z,1379372120.971 [CBIT](DEBUG): Construct CBIT Built In Test.
2013-09-16T22:55:21.087Z,1379372121.087 [CBIT] Loaded
2013-09-16T22:55:21.087Z,1379372121.087 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2013-09-16T22:55:21.088Z,1379372121.088 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2013-09-16T22:55:21.088Z,1379372121.088 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2013-09-16T22:55:21.255Z,1379372121.255 [BuoyancyServo] Loaded
2013-09-16T22:55:21.255Z,1379372121.255 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2013-09-16T22:55:21.266Z,1379372121.266 [ElevatorServo] Loaded
2013-09-16T22:55:21.267Z,1379372121.267 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2013-09-16T22:55:21.278Z,1379372121.278 [MassServo] Loaded
2013-09-16T22:55:21.278Z,1379372121.278 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2013-09-16T22:55:21.289Z,1379372121.289 [RudderServo] Loaded
2013-09-16T22:55:21.290Z,1379372121.290 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2013-09-16T22:55:21.300Z,1379372121.300 [ThrusterServo] Loaded
2013-09-16T22:55:21.301Z,1379372121.301 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2013-09-16T22:55:21.301Z,1379372121.301 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2013-09-16T22:55:21.302Z,1379372121.302 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2013-09-16T22:55:21.336Z,1379372121.336 [DepthRateCalculator] Loaded
2013-09-16T22:55:21.336Z,1379372121.336 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2013-09-16T22:55:24.422Z,1379372124.422 [HFRadarModelCalc] Loaded
2013-09-16T22:55:24.422Z,1379372124.422 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread.
2013-09-16T22:55:24.438Z,1379372124.438 [NavChart] Loaded
2013-09-16T22:55:24.438Z,1379372124.438 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2013-09-16T22:55:24.444Z,1379372124.444 [PitchRateCalculator] Loaded
2013-09-16T22:55:24.444Z,1379372124.444 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2013-09-16T22:55:24.454Z,1379372124.454 [SpeedCalculator] Loaded
2013-09-16T22:55:24.455Z,1379372124.455 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2013-09-16T22:55:24.471Z,1379372124.471 [TempGradientCalculator] Loaded
2013-09-16T22:55:24.471Z,1379372124.471 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2013-09-16T22:55:24.476Z,1379372124.476 [YawRateCalculator] Loaded
2013-09-16T22:55:24.477Z,1379372124.477 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2013-09-16T22:55:24.522Z,1379372124.522 [Navigation] Loaded
2013-09-16T22:55:24.523Z,1379372124.523 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread.
2013-09-16T22:55:24.523Z,1379372124.523 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2013-09-16T22:55:24.524Z,1379372124.524 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2013-09-16T22:55:24.757Z,1379372124.757 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2013-09-16T22:55:24.758Z,1379372124.758 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2013-09-16T22:55:24.781Z,1379372124.781 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2013-09-16T22:55:24.781Z,1379372124.781 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2013-09-16T22:55:24.827Z,1379372124.827 [VerticalControl](DEBUG): Construct VerticalControl.
2013-09-16T22:55:24.919Z,1379372124.919 [VerticalControl] Loaded
2013-09-16T22:55:24.919Z,1379372124.919 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2013-09-16T22:55:24.920Z,1379372124.920 [HorizontalControl](DEBUG): Construct HorizontalControl.
2013-09-16T22:55:24.977Z,1379372124.977 [HorizontalControl] Loaded
2013-09-16T22:55:24.977Z,1379372124.977 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2013-09-16T22:55:24.978Z,1379372124.978 [SpeedControl](DEBUG): Construct SpeedControl.
2013-09-16T22:55:24.980Z,1379372124.980 [SpeedControl] Loaded
2013-09-16T22:55:24.980Z,1379372124.980 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2013-09-16T22:55:24.981Z,1379372124.981 [LoopControl](DEBUG): Construct LoopControl.
2013-09-16T22:55:24.981Z,1379372124.981 [LoopControl] Loaded
2013-09-16T22:55:24.981Z,1379372124.981 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2013-09-16T22:55:24.982Z,1379372124.982 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2013-09-16T22:55:24.982Z,1379372124.982 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2013-09-16T22:55:24.988Z,1379372124.988 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator.
2013-09-16T22:55:24.993Z,1379372124.993 [AsyncPiEstimator] Loaded
2013-09-16T22:55:24.994Z,1379372124.994 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread.
2013-09-16T22:55:24.995Z,1379372124.995 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 406474E0
2013-09-16T22:55:24.995Z,1379372124.995 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2013-09-16T22:55:24.996Z,1379372124.996 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2013-09-16T22:55:25.185Z,1379372125.185 [AHRS_sp3003D] Loaded
2013-09-16T22:55:25.186Z,1379372125.186 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2013-09-16T22:55:25.199Z,1379372125.199 [Depth_Keller] Loaded
2013-09-16T22:55:25.199Z,1379372125.199 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2013-09-16T22:55:25.378Z,1379372125.378 [DVL_micro] Loaded
2013-09-16T22:55:25.378Z,1379372125.378 [ComponentRegistry](DEBUG): Component "DVL_micro" handled in its own thread.
2013-09-16T22:55:25.380Z,1379372125.380 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406CE4E0
2013-09-16T22:55:25.467Z,1379372125.467 [NAL9602] Loaded
2013-09-16T22:55:25.467Z,1379372125.467 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2013-09-16T22:55:25.509Z,1379372125.509 [Onboard] Loaded
2013-09-16T22:55:25.510Z,1379372125.510 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2013-09-16T22:55:25.516Z,1379372125.516 [Radio_Freewave] Loaded
2013-09-16T22:55:25.517Z,1379372125.517 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread.
2013-09-16T22:55:25.654Z,1379372125.654 [DAT] Loaded
2013-09-16T22:55:25.654Z,1379372125.654 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2013-09-16T22:55:25.661Z,1379372125.661 [SCPI] Loaded
2013-09-16T22:55:25.661Z,1379372125.661 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2013-09-16T22:55:25.661Z,1379372125.661 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2013-09-16T22:55:25.662Z,1379372125.662 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2013-09-16T22:55:25.758Z,1379372125.758 [CTD_NeilBrown] Loaded
2013-09-16T22:55:25.758Z,1379372125.758 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2013-09-16T22:55:25.759Z,1379372125.759 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4072B4E0
2013-09-16T22:55:25.775Z,1379372125.775 [PAR_Licor] Loaded
2013-09-16T22:55:25.775Z,1379372125.775 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2013-09-16T22:55:25.807Z,1379372125.807 [WetLabsBB2FL] Loaded
2013-09-16T22:55:25.807Z,1379372125.807 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2013-09-16T22:55:25.808Z,1379372125.808 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4075B4E0
2013-09-16T22:55:25.809Z,1379372125.809 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2013-09-16T22:55:25.811Z,1379372125.811 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2013-09-16T22:55:25.812Z,1379372125.812 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2013-09-16T22:55:25.819Z,1379372125.819 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2013-09-16T22:55:25.820Z,1379372125.820 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 4078B4E0
2013-09-16T22:55:25.824Z,1379372125.824 [Supervisor](DEBUG): Running supervisor.
2013-09-16T22:55:25.825Z,1379372125.825 [CommandLine](INFO): Thread ID is 1678
2013-09-16T22:55:25.828Z,1379372125.828 [controlThread](INFO): Thread ID is 1677
2013-09-16T22:55:25.828Z,1379372125.828 [controlThread](DEBUG): Initializing ControlThread
2013-09-16T22:55:25.828Z,1379372125.828 [CycleStarter](INFO): Thread ID is 1676
2013-09-16T22:55:25.829Z,1379372125.829 [InternalSim](DEBUG):  InternalSim initializing...
2013-09-16T22:55:25.865Z,1379372125.865 [logger](INFO): Thread ID is 1679
2013-09-16T22:55:25.889Z,1379372125.889 [SBIT](INFO): Initialize SBIT Component.
2013-09-16T22:55:25.889Z,1379372125.889 [SBIT](IMPORTANT): Tethys CM Info: 
$Rev:10634
2013-09-16T22:55:25.890Z,1379372125.890 [SBIT](IMPORTANT): Kernel Release:2.6.27.8
2013-09-16T22:55:25.890Z,1379372125.890 [SBIT](IMPORTANT): Kernel Version:#634 PREEMPT Wed Feb 13 10:21:48 PST 2013
2013-09-16T22:55:25.890Z,1379372125.890 [IBIT](INFO): Initialize IBIT Component.
2013-09-16T22:55:25.891Z,1379372125.891 [CBIT](DEBUG): Initialize CBIT Component.
2013-09-16T22:55:25.892Z,1379372125.892 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2013-09-16T22:55:25.901Z,1379372125.901 [AsyncPiEstimator](INFO): Thread ID is 1740
2013-09-16T22:55:25.901Z,1379372125.901 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator.
2013-09-16T22:55:25.919Z,1379372125.919 [DVL_micro](INFO): Thread ID is 1741
2013-09-16T22:55:25.922Z,1379372125.922 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2013-09-16T22:55:25.922Z,1379372125.922 [NavChart](DEBUG): Initialize NavChart Derivation.
2013-09-16T22:55:25.923Z,1379372125.923 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2013-09-16T22:55:25.923Z,1379372125.923 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2013-09-16T22:55:25.923Z,1379372125.923 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2013-09-16T22:55:25.924Z,1379372125.924 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2013-09-16T22:55:25.925Z,1379372125.925 [Navigation](DEBUG): Initializing Navigation.
2013-09-16T22:55:25.925Z,1379372125.925 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2013-09-16T22:55:25.927Z,1379372125.927 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2013-09-16T22:55:25.928Z,1379372125.928 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2013-09-16T22:55:25.928Z,1379372125.928 [LoopControl](DEBUG): Initialize LoopControlComponent.
2013-09-16T22:55:25.936Z,1379372125.936 [DVL_micro](INFO): Initializing
2013-09-16T22:55:25.936Z,1379372125.936 [DVL_micro](INFO): start:Powering up
2013-09-16T22:55:25.941Z,1379372125.941 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T22:55:25.942Z,1379372125.942 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T22:55:25.948Z,1379372125.948 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2013-09-16T22:55:25.954Z,1379372125.954 [CTD_NeilBrown](INFO): Thread ID is 1742
2013-09-16T22:55:25.954Z,1379372125.954 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2013-09-16T22:55:25.961Z,1379372125.961 [WetLabsBB2FL](INFO): Thread ID is 1743
2013-09-16T22:55:25.962Z,1379372125.962 [WetLabsBB2FL](INFO): Powering down
2013-09-16T22:55:25.967Z,1379372125.967 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2013-09-16T22:55:26.006Z,1379372126.006 [NavChartDb](INFO): Thread ID is 1744
2013-09-16T22:55:26.008Z,1379372126.008 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2013-09-16T22:55:26.009Z,1379372126.009 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2013-09-16T22:55:26.010Z,1379372126.010 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2013-09-16T22:55:26.010Z,1379372126.010 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2013-09-16T22:55:26.010Z,1379372126.010 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2013-09-16T22:55:26.010Z,1379372126.010 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2013-09-16T22:55:26.011Z,1379372126.011 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2013-09-16T22:55:26.011Z,1379372126.011 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2013-09-16T22:55:26.011Z,1379372126.011 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2013-09-16T22:55:26.039Z,1379372126.039 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2013-09-16T22:55:26.066Z,1379372126.066 [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://aosn.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface RunIn="Progression"/>

    <Aggregate Id="StartupSatComms" >

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

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

    </Aggregate>

</Mission>


2013-09-16T22:55:26.067Z,1379372126.067 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2013-09-16T22:55:26.185Z,1379372126.185 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool
2013-09-16T22:55:26.188Z,1379372126.188 [Default:GPS:A.SetSpeed](DEBUG): Construct.
2013-09-16T22:55:26.203Z,1379372126.203 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface.
2013-09-16T22:55:26.228Z,1379372126.228 [Default:Iridium:A.SetSpeed](DEBUG): Construct.
2013-09-16T22:55:26.231Z,1379372126.231 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface.
2013-09-16T22:55:26.249Z,1379372126.249 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2013-09-16T22:55:26.290Z,1379372126.290 [Default:D.SetSpeed](DEBUG): Construct.
2013-09-16T22:55:26.293Z,1379372126.293 [Default:E.GoToSurface](DEBUG): Construct GoToSurface.
2013-09-16T22:55:26.311Z,1379372126.311 [Default:F.Wait](DEBUG): Construct Wait.
2013-09-16T22:55:26.323Z,1379372126.323 [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://aosn.mbari.org/tethys/Xml/Tethys.xsd
Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
Id="Default">

    <DefineArg Name="NeedGPS"><True/></DefineArg>

    <Aggregate Id="GPS" >

        <When>
            <Arg Name="NeedGPS"/>
        </When>

<!-- Run at high speed -->

        <Guidance:SetSpeed RunIn="Parallel">
            <Setting><Guidance:SetSpeed.period/><Units:millisecond/><Value>400</Value></Setting>
        </Guidance:SetSpeed>

<!-- Get to the surface -->

        <Guidance:GoToSurface RunIn="Sequence"/>

<!-- Get GPS fix -->

        <ReadDatum Id="Read_GPS">
            <Universal:latitude_fix/>
        </ReadDatum>

        <Assign><Arg Name="NeedGPS"/><False/></Assign>

    </Aggregate>

    <Aggregate Id="Iridium" >

<!-- Run at high speed -->

        <Guidance:SetSpeed RunIn="Parallel">
            <Setting><Guidance:SetSpeed.period/><Units:millisecond/><Value>400</Value></Setting>
        </Guidance:SetSpeed>

<!-- Get to the surface -->

        <Guidance:GoToSurface RunIn="Sequence"/>

<!-- Do data comms -->

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

    </Aggregate>

    <Aggregate Id="CallIridium" >

        <When>
            <Elapsed><Universal:platform_communications/></Elapsed>
            <Gt><Units:minute/><Value>5.0</Value></Gt>
            <And><Universal:platform_conversation/><Eq><True/></Eq></And>
            <Or><Elapsed><Universal:platform_communications/></Elapsed><Gt><Units:hour/><Value>1.0</Value></Gt></Or>
        </When>

        <Assign><Arg Name="NeedGPS"/><True/></Assign>

        <Call RefId="Iridium"/>

    </Aggregate>

<!-- Wait forever at the surface-->

<!-- Run at low speed -->

    <Guidance:SetSpeed RunIn="Parallel">
        <Setting><Guidance:SetSpeed.period/><Units:second/><Value>5</Value></Setting>
    </Guidance:SetSpeed>

    <Guidance:GoToSurface RunIn="Parallel"/>

    <Guidance:Wait RunIn="Sequence"></Guidance:Wait>

</Mission>


2013-09-16T22:55:26.328Z,1379372126.328 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Depth_Keller,NAL9602,Onboard,Radio_Freewave,DAT,SCPI,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,HFRadarModelCalc,NavChart,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,Navigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,
2013-09-16T22:55:26.389Z,1379372126.389 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-09-16T22:55:26.566Z,1379372126.566 [Radio_Freewave](INFO): Powering up
2013-09-16T22:55:26.603Z,1379372126.603 [DAT](INFO): Powering up
2013-09-16T22:55:26.603Z,1379372126.603 [DAT](DEBUG): Initializing DAT.
2013-09-16T22:55:26.956Z,1379372126.956 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-09-16T22:55:26.963Z,1379372126.963 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-09-16T22:55:26.993Z,1379372126.993 [ElevatorServo](DEBUG): Initializing EZServoServo.
2013-09-16T22:55:26.999Z,1379372126.999 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2013-09-16T22:55:27.013Z,1379372127.013 [MassServo](DEBUG): Initializing EZServoServo.
2013-09-16T22:55:27.019Z,1379372127.019 [MassServo](DEBUG): Initializing MassServo.
2013-09-16T22:55:27.025Z,1379372127.025 [RudderServo](DEBUG): Initializing EZServoServo.
2013-09-16T22:55:27.031Z,1379372127.031 [RudderServo](DEBUG): Initializing RudderServo.
2013-09-16T22:55:27.048Z,1379372127.048 [ThrusterServo](DEBUG): Initializing EZServoServo.
2013-09-16T22:55:27.055Z,1379372127.055 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2013-09-16T22:55:27.112Z,1379372127.112 [CBIT](FAULT): Main Battery Failure. Count: 1
2013-09-16T22:55:27.339Z,1379372127.339 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2013-09-16T22:55:27.339Z,1379372127.339 [AHRS_sp3003D](ERROR): readHeadingMagBin got 0x0000
2013-09-16T22:55:27.339Z,1379372127.339 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2013-09-16T22:55:27.339Z,1379372127.339 [AHRS_sp3003D] Hardware Fault, FailCount= 1
2013-09-16T22:55:27.339Z,1379372127.339 [AHRS_sp3003D](ERROR): Hardware Fault
2013-09-16T22:55:27.438Z,1379372127.438 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2013-09-16T22:55:27.543Z,1379372127.543 [AHRS_sp3003D](INFO): Powering down
2013-09-16T22:55:28.060Z,1379372128.060 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T22:55:28.061Z,1379372128.061 [DVL_micro](INFO): Querying output modes
2013-09-16T22:55:28.061Z,1379372128.061 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T22:55:28.932Z,1379372128.932 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2013-09-16T22:55:28.932Z,1379372128.932 [AHRS_sp3003D] No Fault, FailCount= 1
2013-09-16T22:55:29.229Z,1379372129.229 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-09-16T22:55:30.075Z,1379372130.075 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:55:30.075Z,1379372130.075 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T22:55:30.076Z,1379372130.076 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T22:55:30.376Z,1379372130.376 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2013-09-16T22:55:30.376Z,1379372130.376 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2013-09-16T22:55:30.376Z,1379372130.376 [AHRS_sp3003D] Hardware Fault, FailCount= 2
2013-09-16T22:55:30.376Z,1379372130.376 [AHRS_sp3003D](ERROR): Hardware Fault
2013-09-16T22:55:30.707Z,1379372130.707 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2013-09-16T22:55:30.732Z,1379372130.732 [AHRS_sp3003D](INFO): Powering down
2013-09-16T22:55:30.822Z,1379372130.822 [NAL9602](INFO): Powering up NAL9602
2013-09-16T22:55:32.078Z,1379372132.078 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:55:32.084Z,1379372132.084 [DVL_micro](INFO): pause:Powering down
2013-09-16T22:55:32.086Z,1379372132.086 [DVL_micro](INFO): NQ1 requested
2013-09-16T22:55:32.086Z,1379372132.086 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T22:55:32.087Z,1379372132.087 [DVL_micro](INFO): resume:Powering up
2013-09-16T22:55:32.087Z,1379372132.087 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T22:55:32.155Z,1379372132.155 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2013-09-16T22:55:32.155Z,1379372132.155 [AHRS_sp3003D] No Fault, FailCount= 2
2013-09-16T22:55:32.458Z,1379372132.458 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-09-16T22:55:33.601Z,1379372133.601 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2013-09-16T22:55:33.601Z,1379372133.601 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2013-09-16T22:55:33.601Z,1379372133.601 [AHRS_sp3003D] Hardware Fault, FailCount= 3
2013-09-16T22:55:33.601Z,1379372133.601 [AHRS_sp3003D](ERROR): Hardware Fault
2013-09-16T22:55:33.862Z,1379372133.862 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2013-09-16T22:55:33.902Z,1379372133.902 [AHRS_sp3003D](INFO): Powering down
2013-09-16T22:55:34.090Z,1379372134.090 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T22:55:34.090Z,1379372134.090 [DVL_micro](INFO): Querying output modes
2013-09-16T22:55:34.090Z,1379372134.090 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T22:55:35.144Z,1379372135.144 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2013-09-16T22:55:35.144Z,1379372135.144 [AHRS_sp3003D] No Fault, FailCount= 3
2013-09-16T22:55:35.487Z,1379372135.487 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-09-16T22:55:36.100Z,1379372136.100 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:55:36.101Z,1379372136.101 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T22:55:36.101Z,1379372136.101 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T22:55:36.625Z,1379372136.625 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2013-09-16T22:55:36.625Z,1379372136.625 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2013-09-16T22:55:36.626Z,1379372136.626 [AHRS_sp3003D] Hardware Fault, FailCount= 4
2013-09-16T22:55:36.626Z,1379372136.626 [AHRS_sp3003D](ERROR): Hardware Fault
2013-09-16T22:55:36.738Z,1379372136.738 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2013-09-16T22:55:36.751Z,1379372136.751 [AHRS_sp3003D](INFO): Powering down
2013-09-16T22:55:38.057Z,1379372138.057 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2013-09-16T22:55:38.057Z,1379372138.057 [AHRS_sp3003D] No Fault, FailCount= 4
2013-09-16T22:55:38.112Z,1379372138.112 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:55:38.118Z,1379372138.118 [DVL_micro](INFO): pause:Powering down
2013-09-16T22:55:38.351Z,1379372138.351 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-09-16T22:55:39.490Z,1379372139.490 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2013-09-16T22:55:39.490Z,1379372139.490 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2013-09-16T22:55:39.490Z,1379372139.490 [AHRS_sp3003D] Hardware Fault, FailCount= 5
2013-09-16T22:55:39.490Z,1379372139.490 [AHRS_sp3003D](ERROR): Hardware Fault
2013-09-16T22:55:39.562Z,1379372139.562 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2013-09-16T22:55:39.563Z,1379372139.563 [CBIT](CRITICAL): Hardware Fault in component: AHRS_sp3003D
2013-09-16T22:55:39.608Z,1379372139.608 [AHRS_sp3003D](INFO): Powering down
2013-09-16T22:55:39.727Z,1379372139.727 [DVL_micro](INFO): NQ1 requested
2013-09-16T22:55:39.727Z,1379372139.727 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T22:55:39.727Z,1379372139.727 [DVL_micro](INFO): resume:Powering up
2013-09-16T22:55:39.728Z,1379372139.728 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T22:55:39.778Z,1379372139.778 [CommandLine](FAULT): Scheduling is paused
2013-09-16T22:55:41.621Z,1379372141.621 [NAL9602](INFO): NAL9602 initialized
2013-09-16T22:55:41.692Z,1379372141.692 [SBIT](IMPORTANT): Beginning Startup BIT
2013-09-16T22:55:41.696Z,1379372141.696 [CBIT](IMPORTANT): Beginning GF scan
2013-09-16T22:55:41.734Z,1379372141.734 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T22:55:41.734Z,1379372141.734 [DVL_micro](INFO): Querying output modes
2013-09-16T22:55:41.734Z,1379372141.734 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T22:55:43.740Z,1379372143.740 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:55:43.740Z,1379372143.740 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T22:55:43.741Z,1379372143.741 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T22:55:45.751Z,1379372145.751 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:55:45.753Z,1379372145.753 [DVL_micro](INFO): NQ1 requested
2013-09-16T22:55:45.753Z,1379372145.753 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T22:55:46.740Z,1379372146.740 [DAT](INFO): Powering down
2013-09-16T22:55:47.759Z,1379372147.759 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T22:55:47.759Z,1379372147.759 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T22:55:49.762Z,1379372149.762 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:55:49.762Z,1379372149.762 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T22:55:49.762Z,1379372149.762 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T22:55:51.765Z,1379372151.765 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:55:51.765Z,1379372151.765 [DVL_micro](INFO): Querying output modes
2013-09-16T22:55:51.765Z,1379372151.765 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T22:55:51.833Z,1379372151.833 [CommandLine](IMPORTANT): got command report mod platform_buoyancy_position
2013-09-16T22:55:52.163Z,1379372152.163 [Reporter](INFO): platform_buoyancy_position 382.879254 cc
2013-09-16T22:55:52.576Z,1379372152.576 [Reporter](INFO): platform_buoyancy_position 383.013539 cc
2013-09-16T22:55:52.821Z,1379372152.821 [Reporter](INFO): platform_buoyancy_position 384.893734 cc
2013-09-16T22:55:53.230Z,1379372153.230 [Reporter](INFO): platform_buoyancy_position 386.773929 cc
2013-09-16T22:55:53.658Z,1379372153.658 [Reporter](INFO): platform_buoyancy_position 387.176842 cc
2013-09-16T22:55:53.772Z,1379372153.772 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:55:53.772Z,1379372153.772 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T22:55:53.772Z,1379372153.772 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T22:55:54.039Z,1379372154.039 [Reporter](INFO): platform_buoyancy_position 389.191351 cc
2013-09-16T22:55:54.492Z,1379372154.492 [Reporter](INFO): platform_buoyancy_position 391.205831 cc
2013-09-16T22:55:54.804Z,1379372154.804 [Reporter](INFO): platform_buoyancy_position 391.474430 cc
2013-09-16T22:55:55.225Z,1379372155.225 [Reporter](INFO): platform_buoyancy_position 393.488939 cc
2013-09-16T22:55:55.649Z,1379372155.649 [Reporter](INFO): platform_buoyancy_position 393.623224 cc
2013-09-16T22:55:55.783Z,1379372155.783 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:55:56.031Z,1379372156.031 [Reporter](INFO): platform_buoyancy_position 395.772047 cc
2013-09-16T22:55:57.531Z,1379372157.531 [Reporter](INFO): platform_buoyancy_position 397.920841 cc
2013-09-16T22:55:57.748Z,1379372157.748 [Reporter](INFO): platform_buoyancy_position 400.069635 cc
2013-09-16T22:55:57.787Z,1379372157.787 [DVL_micro](INFO): NQ1 requested
2013-09-16T22:55:57.787Z,1379372157.787 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T22:55:58.128Z,1379372158.128 [Reporter](INFO): platform_buoyancy_position 402.084144 cc
2013-09-16T22:55:58.541Z,1379372158.541 [Reporter](INFO): platform_buoyancy_position 404.232938 cc
2013-09-16T22:55:58.929Z,1379372158.929 [Reporter](INFO): platform_buoyancy_position 404.367252 cc
2013-09-16T22:55:59.338Z,1379372159.338 [Reporter](INFO): platform_buoyancy_position 406.381732 cc
2013-09-16T22:55:59.730Z,1379372159.730 [Reporter](INFO): platform_buoyancy_position 406.650332 cc
2013-09-16T22:55:59.790Z,1379372159.790 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T22:55:59.790Z,1379372159.790 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T22:56:00.131Z,1379372160.131 [Reporter](INFO): platform_buoyancy_position 408.530555 cc
2013-09-16T22:56:00.531Z,1379372160.531 [Reporter](INFO): platform_buoyancy_position 410.007837 cc
2013-09-16T22:56:00.935Z,1379372160.935 [CBIT](CRITICAL): Environmental Failure. Press:14.982194 PSI. Humidity:42%. Temp:22 C. ABORTING MISSION
2013-09-16T22:56:00.937Z,1379372160.937 [Reporter](INFO): platform_buoyancy_position 410.679349 cc
2013-09-16T22:56:01.341Z,1379372161.341 [Reporter](INFO): platform_buoyancy_position 412.828143 cc
2013-09-16T22:56:01.742Z,1379372161.742 [Reporter](INFO): platform_buoyancy_position 413.096743 cc
2013-09-16T22:56:01.800Z,1379372161.800 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:56:01.800Z,1379372161.800 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T22:56:01.801Z,1379372161.801 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T22:56:02.167Z,1379372162.167 [Reporter](INFO): platform_buoyancy_position 414.976967 cc
2013-09-16T22:56:02.679Z,1379372162.679 [Reporter](INFO): platform_buoyancy_position 417.125761 cc
2013-09-16T22:56:02.999Z,1379372162.999 [Reporter](INFO): platform_buoyancy_position 417.260046 cc
2013-09-16T22:56:03.281Z,1379372163.281 [Reporter](INFO): platform_buoyancy_position 419.274555 cc
2013-09-16T22:56:03.765Z,1379372163.765 [Reporter](INFO): platform_buoyancy_position 421.423378 cc
2013-09-16T22:56:03.807Z,1379372163.807 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:56:03.808Z,1379372163.808 [DVL_micro](INFO): Querying output modes
2013-09-16T22:56:03.808Z,1379372163.808 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T22:56:04.082Z,1379372164.082 [Reporter](INFO): platform_buoyancy_position 421.557634 cc
2013-09-16T22:56:04.482Z,1379372164.482 [Reporter](INFO): platform_buoyancy_position 423.572114 cc
2013-09-16T22:56:04.894Z,1379372164.894 [Reporter](INFO): platform_buoyancy_position 423.840742 cc
2013-09-16T22:56:05.291Z,1379372165.291 [Reporter](INFO): platform_buoyancy_position 425.720908 cc
2013-09-16T22:56:05.692Z,1379372165.692 [Reporter](INFO): platform_buoyancy_position 425.855251 cc
2013-09-16T22:56:05.818Z,1379372165.818 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:56:05.819Z,1379372165.819 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T22:56:05.819Z,1379372165.819 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T22:56:06.105Z,1379372166.105 [Reporter](INFO): platform_buoyancy_position 427.869731 cc
2013-09-16T22:56:06.481Z,1379372166.481 [Reporter](INFO): platform_buoyancy_position 430.018525 cc
2013-09-16T22:56:07.263Z,1379372167.263 [Reporter](INFO): platform_buoyancy_position 432.167319 cc
2013-09-16T22:56:07.663Z,1379372167.663 [Reporter](INFO): platform_buoyancy_position 434.316142 cc
2013-09-16T22:56:07.822Z,1379372167.822 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:56:08.075Z,1379372168.075 [Reporter](INFO): platform_buoyancy_position 434.450456 cc
2013-09-16T22:56:08.460Z,1379372168.460 [CBIT](IMPORTANT): No ground fault detected
2013-09-16T22:56:08.461Z,1379372168.461 [Reporter](INFO): platform_buoyancy_position 436.464936 cc
2013-09-16T22:56:08.861Z,1379372168.861 [Reporter](INFO): platform_buoyancy_position 436.599279 cc
2013-09-16T22:56:09.257Z,1379372169.257 [Reporter](INFO): platform_buoyancy_position 438.748015 cc
2013-09-16T22:56:09.666Z,1379372169.666 [Reporter](INFO): platform_buoyancy_position 440.493925 cc
2013-09-16T22:56:09.825Z,1379372169.825 [DVL_micro](INFO): NQ1 requested
2013-09-16T22:56:09.825Z,1379372169.825 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T22:56:10.083Z,1379372170.083 [Reporter](INFO): platform_buoyancy_position 440.762553 cc
2013-09-16T22:56:10.467Z,1379372170.467 [Reporter](INFO): platform_buoyancy_position 442.911347 cc
2013-09-16T22:56:10.860Z,1379372170.860 [Reporter](INFO): platform_buoyancy_position 443.045632 cc
2013-09-16T22:56:11.261Z,1379372171.261 [Reporter](INFO): platform_buoyancy_position 445.060141 cc
2013-09-16T22:56:11.657Z,1379372171.657 [Reporter](INFO): platform_buoyancy_position 447.208964 cc
2013-09-16T22:56:11.828Z,1379372171.828 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T22:56:11.828Z,1379372171.828 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T22:56:12.463Z,1379372172.463 [Reporter](INFO): platform_buoyancy_position 449.357758 cc
2013-09-16T22:56:12.891Z,1379372172.891 [Reporter](INFO): platform_buoyancy_position 449.492043 cc
2013-09-16T22:56:13.264Z,1379372173.264 [Reporter](INFO): platform_buoyancy_position 451.506552 cc
2013-09-16T22:56:13.744Z,1379372173.744 [Reporter](INFO): platform_buoyancy_position 451.775122 cc
2013-09-16T22:56:13.831Z,1379372173.831 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:56:13.831Z,1379372173.831 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T22:56:13.831Z,1379372173.831 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T22:56:14.017Z,1379372174.017 [Reporter](INFO): platform_buoyancy_position 453.789631 cc
2013-09-16T22:56:14.426Z,1379372174.426 [Reporter](INFO): platform_buoyancy_position 455.804169 cc
2013-09-16T22:56:14.815Z,1379372174.815 [Reporter](INFO): platform_buoyancy_position 455.938454 cc
2013-09-16T22:56:15.203Z,1379372175.203 [Reporter](INFO): platform_buoyancy_position 457.952963 cc
2013-09-16T22:56:15.632Z,1379372175.632 [Reporter](INFO): platform_buoyancy_position 460.236042 cc
2013-09-16T22:56:15.842Z,1379372175.842 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:56:15.842Z,1379372175.842 [DVL_micro](INFO): Querying output modes
2013-09-16T22:56:15.842Z,1379372175.842 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T22:56:16.029Z,1379372176.029 [Reporter](INFO): platform_buoyancy_position 460.370327 cc
2013-09-16T22:56:16.433Z,1379372176.433 [Reporter](INFO): platform_buoyancy_position 462.250522 cc
2013-09-16T22:56:16.834Z,1379372176.834 [Reporter](INFO): platform_buoyancy_position 464.399316 cc
2013-09-16T22:56:17.211Z,1379372177.211 [Reporter](INFO): platform_buoyancy_position 464.533659 cc
2013-09-16T22:56:17.596Z,1379372177.596 [Reporter](INFO): platform_buoyancy_position 466.548139 cc
2013-09-16T22:56:17.849Z,1379372177.849 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:56:17.849Z,1379372177.849 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T22:56:17.849Z,1379372177.849 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T22:56:18.024Z,1379372178.024 [Reporter](INFO): platform_buoyancy_position 466.816738 cc
2013-09-16T22:56:18.408Z,1379372178.408 [Reporter](INFO): platform_buoyancy_position 468.696933 cc
2013-09-16T22:56:18.825Z,1379372178.825 [Reporter](INFO): platform_buoyancy_position 470.845727 cc
2013-09-16T22:56:19.225Z,1379372179.225 [Reporter](INFO): platform_buoyancy_position 470.980071 cc
2013-09-16T22:56:19.630Z,1379372179.630 [Reporter](INFO): platform_buoyancy_position 472.994550 cc
2013-09-16T22:56:19.856Z,1379372179.856 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:56:20.027Z,1379372180.027 [Reporter](INFO): platform_buoyancy_position 473.128865 cc
2013-09-16T22:56:20.427Z,1379372180.427 [Reporter](INFO): platform_buoyancy_position 475.277629 cc
2013-09-16T22:56:20.808Z,1379372180.808 [Reporter](INFO): platform_buoyancy_position 477.292138 cc
2013-09-16T22:56:21.244Z,1379372181.244 [Reporter](INFO): platform_buoyancy_position 477.560767 cc
2013-09-16T22:56:21.629Z,1379372181.629 [Reporter](INFO): platform_buoyancy_position 479.575217 cc
2013-09-16T22:56:21.860Z,1379372181.860 [DVL_micro](INFO): NQ1 requested
2013-09-16T22:56:21.860Z,1379372181.860 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T22:56:22.050Z,1379372182.050 [Reporter](INFO): platform_buoyancy_position 481.186871 cc
2013-09-16T22:56:22.433Z,1379372182.433 [Reporter](INFO): platform_buoyancy_position 481.724041 cc
2013-09-16T22:56:22.827Z,1379372182.827 [Reporter](INFO): platform_buoyancy_position 483.872835 cc
2013-09-16T22:56:23.863Z,1379372183.863 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T22:56:23.863Z,1379372183.863 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T22:56:24.264Z,1379372184.264 [Reporter](INFO): platform_buoyancy_position 486.021629 cc
2013-09-16T22:56:24.460Z,1379372184.460 [Reporter](INFO): platform_buoyancy_position 488.036167 cc
2013-09-16T22:56:24.841Z,1379372184.841 [Reporter](INFO): platform_buoyancy_position 490.184990 cc
2013-09-16T22:56:25.258Z,1379372185.258 [Reporter](INFO): platform_buoyancy_position 490.319217 cc
2013-09-16T22:56:25.677Z,1379372185.677 [Reporter](INFO): platform_buoyancy_position 492.333784 cc
2013-09-16T22:56:25.883Z,1379372185.883 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:56:25.883Z,1379372185.883 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T22:56:25.884Z,1379372185.884 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T22:56:26.047Z,1379372186.047 [Reporter](INFO): platform_buoyancy_position 494.482578 cc
2013-09-16T22:56:26.612Z,1379372186.612 [Reporter](INFO): platform_buoyancy_position 494.616863 cc
2013-09-16T22:56:26.800Z,1379372186.800 [Reporter](INFO): platform_buoyancy_position 496.765657 cc
2013-09-16T22:56:27.193Z,1379372187.193 [Reporter](INFO): platform_buoyancy_position 496.631372 cc
2013-09-16T22:56:27.614Z,1379372187.614 [Reporter](INFO): platform_buoyancy_position 498.780108 cc
2013-09-16T22:56:27.887Z,1379372187.887 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:56:27.887Z,1379372187.887 [DVL_micro](INFO): Querying output modes
2013-09-16T22:56:27.888Z,1379372187.888 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T22:56:28.003Z,1379372188.003 [Reporter](INFO): platform_buoyancy_position 499.720220 cc
2013-09-16T22:56:28.399Z,1379372188.399 [Reporter](INFO): platform_buoyancy_position 501.063245 cc
2013-09-16T22:56:28.784Z,1379372188.784 [Reporter](INFO): platform_buoyancy_position 503.077754 cc
2013-09-16T22:56:29.184Z,1379372189.184 [Reporter](INFO): platform_buoyancy_position 503.212039 cc
2013-09-16T22:56:29.625Z,1379372189.625 [Reporter](INFO): platform_buoyancy_position 505.226548 cc
2013-09-16T22:56:29.892Z,1379372189.892 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:56:29.892Z,1379372189.892 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T22:56:29.892Z,1379372189.892 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T22:56:30.010Z,1379372190.010 [Reporter](INFO): platform_buoyancy_position 507.375342 cc
2013-09-16T22:56:30.390Z,1379372190.390 [Reporter](INFO): platform_buoyancy_position 507.509685 cc
2013-09-16T22:56:30.791Z,1379372190.791 [Reporter](INFO): platform_buoyancy_position 509.524136 cc
2013-09-16T22:56:31.191Z,1379372191.191 [Reporter](INFO): platform_buoyancy_position 511.538645 cc
2013-09-16T22:56:31.596Z,1379372191.596 [Reporter](INFO): platform_buoyancy_position 511.807215 cc
2013-09-16T22:56:31.905Z,1379372191.905 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:56:32.001Z,1379372192.001 [Reporter](INFO): platform_buoyancy_position 513.821724 cc
2013-09-16T22:56:32.413Z,1379372192.413 [Reporter](INFO): platform_buoyancy_position 514.090352 cc
2013-09-16T22:56:32.862Z,1379372192.862 [Reporter](INFO): platform_buoyancy_position 516.104803 cc
2013-09-16T22:56:33.025Z,1379372193.025 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 550.000000 cubic_centimeter
2013-09-16T22:56:33.027Z,1379372193.027 [ComponentRegistry](DEBUG): SyncComponent "Maintain_VerticalControl.buoyancyAction" handled in the control thread.
2013-09-16T22:56:33.143Z,1379372193.143 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Depth_Keller,NAL9602,Onboard,Radio_Freewave,DAT,SCPI,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,HFRadarModelCalc,NavChart,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,Navigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,Maintain_VerticalControl.buoyancyAction,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,
2013-09-16T22:56:33.604Z,1379372193.604 [Reporter](INFO): platform_buoyancy_position 518.253597 cc
2013-09-16T22:56:33.907Z,1379372193.907 [DVL_micro](INFO): NQ1 requested
2013-09-16T22:56:33.907Z,1379372193.907 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T22:56:33.990Z,1379372193.990 [Reporter](INFO): platform_buoyancy_position 520.268106 cc
2013-09-16T22:56:34.353Z,1379372194.353 [Reporter](INFO): platform_buoyancy_position 520.402391 cc
2013-09-16T22:56:34.742Z,1379372194.742 [Reporter](INFO): platform_buoyancy_position 521.611131 cc
2013-09-16T22:56:35.138Z,1379372195.138 [SBIT](IMPORTANT): SBIT PASSED
2013-09-16T22:56:35.142Z,1379372195.142 [Reporter](INFO): platform_buoyancy_position 522.416958 cc
2013-09-16T22:56:35.521Z,1379372195.521 [MissionManager](IMPORTANT): Started mission Startup
2013-09-16T22:56:35.521Z,1379372195.521 [Startup] Running Loop=1
2013-09-16T22:56:35.521Z,1379372195.521 [Startup](INFO): Aggregate::initialize Startup
2013-09-16T22:56:35.521Z,1379372195.521 [Startup:A.GoToSurface] Running Loop=1
2013-09-16T22:56:35.521Z,1379372195.521 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-09-16T22:56:35.528Z,1379372195.528 [Startup:StartupSatComms] Running Loop=1
2013-09-16T22:56:35.529Z,1379372195.529 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2013-09-16T22:56:35.529Z,1379372195.529 [Startup:StartupSatComms:A] Running Loop=1
2013-09-16T22:56:35.567Z,1379372195.567 [Reporter](INFO): platform_buoyancy_position 524.565752 cc
2013-09-16T22:56:35.910Z,1379372195.910 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T22:56:35.910Z,1379372195.910 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T22:56:35.924Z,1379372195.924 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2013-09-16T22:56:36.351Z,1379372196.351 [Reporter](INFO): platform_buoyancy_position 526.848831 cc
2013-09-16T22:56:36.736Z,1379372196.736 [Reporter](INFO): platform_buoyancy_position 526.983116 cc
2013-09-16T22:56:37.141Z,1379372197.141 [Reporter](INFO): platform_buoyancy_position 528.997625 cc
2013-09-16T22:56:37.541Z,1379372197.541 [Reporter](INFO): platform_buoyancy_position 531.012134 cc
2013-09-16T22:56:37.920Z,1379372197.920 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:56:37.921Z,1379372197.921 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T22:56:37.921Z,1379372197.921 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T22:56:37.958Z,1379372197.958 [Reporter](INFO): platform_buoyancy_position 531.146419 cc
2013-09-16T22:56:38.363Z,1379372198.363 [Reporter](INFO): platform_buoyancy_position 533.295213 cc
2013-09-16T22:56:39.140Z,1379372199.140 [Reporter](INFO): platform_buoyancy_position 535.309664 cc
2013-09-16T22:56:39.541Z,1379372199.541 [Reporter](INFO): platform_buoyancy_position 537.458516 cc
2013-09-16T22:56:39.931Z,1379372199.931 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:56:39.931Z,1379372199.931 [DVL_micro](INFO): Querying output modes
2013-09-16T22:56:39.932Z,1379372199.932 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T22:56:40.338Z,1379372200.338 [Reporter](INFO): platform_buoyancy_position 539.741653 cc
2013-09-16T22:56:40.759Z,1379372200.759 [Reporter](INFO): platform_buoyancy_position 539.875939 cc
2013-09-16T22:56:41.159Z,1379372201.159 [Reporter](INFO): platform_buoyancy_position 541.890447 cc
2013-09-16T22:56:41.942Z,1379372201.942 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:56:41.943Z,1379372201.943 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T22:56:41.943Z,1379372201.943 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T22:56:43.946Z,1379372203.946 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:56:45.953Z,1379372205.953 [DVL_micro](INFO): NQ1 requested
2013-09-16T22:56:45.953Z,1379372205.953 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T22:56:47.956Z,1379372207.956 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T22:56:47.956Z,1379372207.956 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T22:56:49.983Z,1379372209.983 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:56:49.983Z,1379372209.983 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T22:56:49.983Z,1379372209.983 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T22:56:51.994Z,1379372211.994 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:56:51.994Z,1379372211.994 [DVL_micro](INFO): Querying output modes
2013-09-16T22:56:51.994Z,1379372211.994 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T22:56:54.001Z,1379372214.001 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:56:54.001Z,1379372214.001 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T22:56:54.002Z,1379372214.002 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T22:56:56.008Z,1379372216.008 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:56:58.020Z,1379372218.020 [DVL_micro](INFO): NQ1 requested
2013-09-16T22:56:58.020Z,1379372218.020 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T22:57:00.023Z,1379372220.023 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T22:57:00.023Z,1379372220.023 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T22:57:02.034Z,1379372222.034 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:57:02.034Z,1379372222.034 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T22:57:02.034Z,1379372222.034 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T22:57:04.037Z,1379372224.037 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:57:04.037Z,1379372224.037 [DVL_micro](INFO): Querying output modes
2013-09-16T22:57:04.037Z,1379372224.037 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T22:57:06.048Z,1379372226.048 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:57:06.048Z,1379372226.048 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T22:57:06.048Z,1379372226.048 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T22:57:08.059Z,1379372228.059 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:57:10.068Z,1379372230.068 [DVL_micro](INFO): NQ1 requested
2013-09-16T22:57:10.068Z,1379372230.068 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T22:57:12.074Z,1379372232.074 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T22:57:12.074Z,1379372232.074 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T22:57:14.080Z,1379372234.080 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:57:14.080Z,1379372234.080 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T22:57:14.081Z,1379372234.081 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T22:57:16.087Z,1379372236.087 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:57:16.088Z,1379372236.088 [DVL_micro](INFO): Querying output modes
2013-09-16T22:57:16.088Z,1379372236.088 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T22:57:18.091Z,1379372238.091 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:57:18.091Z,1379372238.091 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T22:57:18.091Z,1379372238.091 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T22:57:20.094Z,1379372240.094 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:57:22.097Z,1379372242.097 [DVL_micro](INFO): NQ1 requested
2013-09-16T22:57:22.097Z,1379372242.097 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T22:57:23.311Z,1379372243.311 [CommandLine](IMPORTANT): got command maintain control VerticalControl.buoyancyAction 231.000000 cubic_centimeter
2013-09-16T22:57:24.100Z,1379372244.100 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T22:57:24.101Z,1379372244.101 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T22:57:24.779Z,1379372244.779 [Reporter](INFO): platform_buoyancy_position 544.039242 cc
2013-09-16T22:57:25.143Z,1379372245.143 [Reporter](INFO): platform_buoyancy_position 544.173527 cc
2013-09-16T22:57:25.957Z,1379372245.957 [Reporter](INFO): platform_buoyancy_position 543.904898 cc
2013-09-16T22:57:26.111Z,1379372246.111 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:57:26.111Z,1379372246.111 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T22:57:26.111Z,1379372246.111 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T22:57:26.758Z,1379372246.758 [Reporter](INFO): platform_buoyancy_position 541.756104 cc
2013-09-16T22:57:27.138Z,1379372247.138 [Reporter](INFO): platform_buoyancy_position 539.741653 cc
2013-09-16T22:57:27.540Z,1379372247.540 [Reporter](INFO): platform_buoyancy_position 539.607310 cc
2013-09-16T22:57:27.944Z,1379372247.944 [Reporter](INFO): platform_buoyancy_position 537.592859 cc
2013-09-16T22:57:28.122Z,1379372248.122 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:57:28.122Z,1379372248.122 [DVL_micro](INFO): Querying output modes
2013-09-16T22:57:28.122Z,1379372248.122 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T22:57:28.340Z,1379372248.340 [Reporter](INFO): platform_buoyancy_position 535.444007 cc
2013-09-16T22:57:28.757Z,1379372248.757 [Reporter](INFO): platform_buoyancy_position 535.309664 cc
2013-09-16T22:57:29.153Z,1379372249.153 [Reporter](INFO): platform_buoyancy_position 533.295213 cc
2013-09-16T22:57:29.558Z,1379372249.558 [Reporter](INFO): platform_buoyancy_position 533.160928 cc
2013-09-16T22:57:29.947Z,1379372249.947 [Reporter](INFO): platform_buoyancy_position 531.012134 cc
2013-09-16T22:57:30.125Z,1379372250.125 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:57:30.125Z,1379372250.125 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T22:57:30.125Z,1379372250.125 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T22:57:30.352Z,1379372250.352 [Reporter](INFO): platform_buoyancy_position 528.997625 cc
2013-09-16T22:57:31.141Z,1379372251.141 [Reporter](INFO): platform_buoyancy_position 526.714546 cc
2013-09-16T22:57:31.541Z,1379372251.541 [Reporter](INFO): platform_buoyancy_position 525.505806 cc
2013-09-16T22:57:31.954Z,1379372251.954 [Reporter](INFO): platform_buoyancy_position 524.700037 cc
2013-09-16T22:57:32.128Z,1379372252.128 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:57:32.359Z,1379372252.359 [Reporter](INFO): platform_buoyancy_position 522.551185 cc
2013-09-16T22:57:32.759Z,1379372252.759 [Reporter](INFO): platform_buoyancy_position 522.282615 cc
2013-09-16T22:57:33.135Z,1379372253.135 [Reporter](INFO): platform_buoyancy_position 520.402391 cc
2013-09-16T22:57:33.536Z,1379372253.536 [Reporter](INFO): platform_buoyancy_position 518.253597 cc
2013-09-16T22:57:34.132Z,1379372254.132 [DVL_micro](INFO): NQ1 requested
2013-09-16T22:57:34.132Z,1379372254.132 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T22:57:34.346Z,1379372254.346 [Reporter](INFO): platform_buoyancy_position 515.970518 cc
2013-09-16T22:57:35.159Z,1379372255.159 [Reporter](INFO): platform_buoyancy_position 513.956009 cc
2013-09-16T22:57:35.560Z,1379372255.560 [Reporter](INFO): platform_buoyancy_position 511.672930 cc
2013-09-16T22:57:35.906Z,1379372255.906 [Startup:StartupSatComms:A](INFO): Timed out from 2013-09-16T22:56:35.5Z
2013-09-16T22:57:35.906Z,1379372255.906 [Startup:StartupSatComms:A:A_Timeout] Running Loop=1
2013-09-16T22:57:35.906Z,1379372255.906 [Startup:StartupSatComms:A:A_Timeout](INFO): Aggregate::initialize Startup:StartupSatComms:A:A_Timeout
2013-09-16T22:57:35.907Z,1379372255.907 [Startup:StartupSatComms:A:A_Timeout](INFO): Completed Startup:StartupSatComms:A:A_Timeout
2013-09-16T22:57:35.907Z,1379372255.907 [Startup:StartupSatComms:A] Stopped
2013-09-16T22:57:35.907Z,1379372255.907 [Startup:StartupSatComms:B] Running Loop=1
2013-09-16T22:57:36.135Z,1379372256.135 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T22:57:36.135Z,1379372256.135 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T22:57:36.305Z,1379372256.305 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2013-09-16T22:57:36.345Z,1379372256.345 [Reporter](INFO): platform_buoyancy_position 509.792764 cc
2013-09-16T22:57:36.738Z,1379372256.738 [Reporter](INFO): platform_buoyancy_position 509.524136 cc
2013-09-16T22:57:37.142Z,1379372257.142 [Reporter](INFO): platform_buoyancy_position 507.509685 cc
2013-09-16T22:57:37.897Z,1379372257.897 [Reporter](INFO): platform_buoyancy_position 505.226548 cc
2013-09-16T22:57:38.138Z,1379372258.138 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:57:38.138Z,1379372258.138 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T22:57:38.138Z,1379372258.138 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T22:57:38.900Z,1379372258.900 [Reporter](INFO): platform_buoyancy_position 501.063245 cc
2013-09-16T22:57:39.017Z,1379372259.017 [Reporter](INFO): platform_buoyancy_position 500.928902 cc
2013-09-16T22:57:39.794Z,1379372259.794 [Reporter](INFO): platform_buoyancy_position 498.914451 cc
2013-09-16T22:57:40.141Z,1379372260.141 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:57:40.141Z,1379372260.141 [DVL_micro](INFO): Querying output modes
2013-09-16T22:57:40.141Z,1379372260.141 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T22:57:40.191Z,1379372260.191 [Reporter](INFO): platform_buoyancy_position 496.765657 cc
2013-09-16T22:57:40.588Z,1379372260.588 [Reporter](INFO): platform_buoyancy_position 496.631372 cc
2013-09-16T22:57:40.996Z,1379372260.996 [Reporter](INFO): platform_buoyancy_position 494.616863 cc
2013-09-16T22:57:41.381Z,1379372261.381 [Reporter](INFO): platform_buoyancy_position 494.348234 cc
2013-09-16T22:57:41.789Z,1379372261.789 [Reporter](INFO): platform_buoyancy_position 492.333784 cc
2013-09-16T22:57:42.152Z,1379372262.152 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:57:42.152Z,1379372262.152 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T22:57:42.152Z,1379372262.152 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T22:57:42.194Z,1379372262.194 [Reporter](INFO): platform_buoyancy_position 490.319217 cc
2013-09-16T22:57:43.083Z,1379372263.083 [Reporter](INFO): platform_buoyancy_position 488.036167 cc
2013-09-16T22:57:43.188Z,1379372263.188 [Reporter](INFO): platform_buoyancy_position 488.170452 cc
2013-09-16T22:57:43.556Z,1379372263.556 [Reporter](INFO): platform_buoyancy_position 486.021629 cc
2013-09-16T22:57:43.989Z,1379372263.989 [Reporter](INFO): platform_buoyancy_position 485.887373 cc
2013-09-16T22:57:44.155Z,1379372264.155 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:57:44.385Z,1379372264.385 [Reporter](INFO): platform_buoyancy_position 483.738550 cc
2013-09-16T22:57:44.790Z,1379372264.790 [Reporter](INFO): platform_buoyancy_position 481.858355 cc
2013-09-16T22:57:45.154Z,1379372265.154 [Reporter](INFO): platform_buoyancy_position 481.589756 cc
2013-09-16T22:57:45.555Z,1379372265.555 [Reporter](INFO): platform_buoyancy_position 479.575217 cc
2013-09-16T22:57:45.960Z,1379372265.960 [Reporter](INFO): platform_buoyancy_position 477.829366 cc
2013-09-16T22:57:46.159Z,1379372266.159 [DVL_micro](INFO): NQ1 requested
2013-09-16T22:57:46.159Z,1379372266.159 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T22:57:46.388Z,1379372266.388 [Reporter](INFO): platform_buoyancy_position 477.426423 cc
2013-09-16T22:57:47.293Z,1379372267.293 [Reporter](INFO): platform_buoyancy_position 474.471832 cc
2013-09-16T22:57:47.398Z,1379372267.398 [Reporter](INFO): platform_buoyancy_position 473.128865 cc
2013-09-16T22:57:48.162Z,1379372268.162 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T22:57:48.162Z,1379372268.162 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T22:57:48.187Z,1379372268.187 [Reporter](INFO): platform_buoyancy_position 470.845727 cc
2013-09-16T22:57:48.600Z,1379372268.600 [Reporter](INFO): platform_buoyancy_position 470.442843 cc
2013-09-16T22:57:48.985Z,1379372268.985 [Reporter](INFO): platform_buoyancy_position 468.831277 cc
2013-09-16T22:57:49.365Z,1379372269.365 [Reporter](INFO): platform_buoyancy_position 466.816738 cc
2013-09-16T22:57:49.765Z,1379372269.765 [Reporter](INFO): platform_buoyancy_position 466.548139 cc
2013-09-16T22:57:50.168Z,1379372270.168 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:57:50.169Z,1379372270.169 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T22:57:50.169Z,1379372270.169 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T22:57:50.190Z,1379372270.190 [Reporter](INFO): platform_buoyancy_position 464.399316 cc
2013-09-16T22:57:51.087Z,1379372271.087 [Reporter](INFO): platform_buoyancy_position 462.250522 cc
2013-09-16T22:57:51.216Z,1379372271.216 [Reporter](INFO): platform_buoyancy_position 461.579068 cc
2013-09-16T22:57:51.560Z,1379372271.560 [Reporter](INFO): platform_buoyancy_position 460.236042 cc
2013-09-16T22:57:51.989Z,1379372271.989 [Reporter](INFO): platform_buoyancy_position 458.087248 cc
2013-09-16T22:57:52.175Z,1379372272.175 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:57:52.175Z,1379372272.175 [DVL_micro](INFO): Querying output modes
2013-09-16T22:57:52.176Z,1379372272.176 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T22:57:52.396Z,1379372272.396 [Reporter](INFO): platform_buoyancy_position 457.952963 cc
2013-09-16T22:57:52.793Z,1379372272.793 [Reporter](INFO): platform_buoyancy_position 455.938454 cc
2013-09-16T22:57:53.159Z,1379372273.159 [Reporter](INFO): platform_buoyancy_position 455.669826 cc
2013-09-16T22:57:53.559Z,1379372273.559 [Reporter](INFO): platform_buoyancy_position 453.789631 cc
2013-09-16T22:57:53.984Z,1379372273.984 [Reporter](INFO): platform_buoyancy_position 451.640837 cc
2013-09-16T22:57:54.182Z,1379372274.182 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:57:54.183Z,1379372274.183 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T22:57:54.183Z,1379372274.183 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T22:57:54.393Z,1379372274.393 [Reporter](INFO): platform_buoyancy_position 451.506552 cc
2013-09-16T22:57:55.286Z,1379372275.286 [Reporter](INFO): platform_buoyancy_position 447.343220 cc
2013-09-16T22:57:56.194Z,1379372276.194 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:57:56.196Z,1379372276.196 [Reporter](INFO): platform_buoyancy_position 445.194426 cc
2013-09-16T22:57:56.584Z,1379372276.584 [Reporter](INFO): platform_buoyancy_position 443.045632 cc
2013-09-16T22:57:57.357Z,1379372277.357 [Reporter](INFO): platform_buoyancy_position 440.896809 cc
2013-09-16T22:57:57.754Z,1379372277.754 [Reporter](INFO): platform_buoyancy_position 440.493925 cc
2013-09-16T22:57:58.187Z,1379372278.187 [Reporter](INFO): platform_buoyancy_position 438.613730 cc
2013-09-16T22:57:58.197Z,1379372278.197 [DVL_micro](INFO): NQ1 requested
2013-09-16T22:57:58.198Z,1379372278.198 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T22:57:59.095Z,1379372279.095 [Reporter](INFO): platform_buoyancy_position 436.464936 cc
2013-09-16T22:57:59.189Z,1379372279.189 [Reporter](INFO): platform_buoyancy_position 434.450456 cc
2013-09-16T22:57:59.585Z,1379372279.585 [Reporter](INFO): platform_buoyancy_position 434.316142 cc
2013-09-16T22:57:59.998Z,1379372279.998 [Reporter](INFO): platform_buoyancy_position 432.301662 cc
2013-09-16T22:58:00.200Z,1379372280.200 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T22:58:00.201Z,1379372280.201 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T22:58:00.393Z,1379372280.393 [Reporter](INFO): platform_buoyancy_position 430.958637 cc
2013-09-16T22:58:00.794Z,1379372280.794 [Reporter](INFO): platform_buoyancy_position 430.152868 cc
2013-09-16T22:58:01.168Z,1379372281.168 [Reporter](INFO): platform_buoyancy_position 428.004045 cc
2013-09-16T22:58:01.564Z,1379372281.564 [Reporter](INFO): platform_buoyancy_position 427.869731 cc
2013-09-16T22:58:01.985Z,1379372281.985 [Reporter](INFO): platform_buoyancy_position 425.855251 cc
2013-09-16T22:58:02.207Z,1379372282.207 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:58:02.207Z,1379372282.207 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T22:58:02.207Z,1379372282.207 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T22:58:02.389Z,1379372282.389 [Reporter](INFO): platform_buoyancy_position 424.780854 cc
2013-09-16T22:58:03.287Z,1379372283.287 [Reporter](INFO): platform_buoyancy_position 421.423378 cc
2013-09-16T22:58:03.759Z,1379372283.759 [Reporter](INFO): platform_buoyancy_position 419.677468 cc
2013-09-16T22:58:04.196Z,1379372284.196 [Reporter](INFO): platform_buoyancy_position 419.408840 cc
2013-09-16T22:58:04.214Z,1379372284.214 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:58:04.214Z,1379372284.214 [DVL_micro](INFO): Querying output modes
2013-09-16T22:58:04.214Z,1379372284.214 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T22:58:04.588Z,1379372284.588 [Reporter](INFO): platform_buoyancy_position 417.125761 cc
2013-09-16T22:58:04.989Z,1379372284.989 [Reporter](INFO): platform_buoyancy_position 416.051364 cc
2013-09-16T22:58:05.361Z,1379372285.361 [Reporter](INFO): platform_buoyancy_position 415.111223 cc
2013-09-16T22:58:05.758Z,1379372285.758 [Reporter](INFO): platform_buoyancy_position 412.962429 cc
2013-09-16T22:58:06.191Z,1379372286.191 [Reporter](INFO): platform_buoyancy_position 412.828143 cc
2013-09-16T22:58:06.225Z,1379372286.225 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:58:06.225Z,1379372286.225 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T22:58:06.225Z,1379372286.225 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T22:58:07.064Z,1379372287.064 [Reporter](INFO): platform_buoyancy_position 408.530555 cc
2013-09-16T22:58:07.181Z,1379372287.181 [Reporter](INFO): platform_buoyancy_position 408.664870 cc
2013-09-16T22:58:07.541Z,1379372287.541 [Reporter](INFO): platform_buoyancy_position 408.530555 cc
2013-09-16T22:58:07.958Z,1379372287.958 [Reporter](INFO): platform_buoyancy_position 406.381732 cc
2013-09-16T22:58:08.239Z,1379372288.239 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:58:08.358Z,1379372288.358 [Reporter](INFO): platform_buoyancy_position 404.232938 cc
2013-09-16T22:58:09.139Z,1379372289.139 [Reporter](INFO): platform_buoyancy_position 402.218458 cc
2013-09-16T22:58:09.532Z,1379372289.532 [Reporter](INFO): platform_buoyancy_position 401.546946 cc
2013-09-16T22:58:09.945Z,1379372289.945 [Reporter](INFO): platform_buoyancy_position 399.935321 cc
2013-09-16T22:58:10.244Z,1379372290.244 [DVL_micro](INFO): NQ1 requested
2013-09-16T22:58:10.244Z,1379372290.244 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T22:58:10.361Z,1379372290.361 [Reporter](INFO): platform_buoyancy_position 397.920841 cc
2013-09-16T22:58:11.255Z,1379372291.255 [Reporter](INFO): platform_buoyancy_position 395.637733 cc
2013-09-16T22:58:11.367Z,1379372291.367 [Reporter](INFO): platform_buoyancy_position 395.772047 cc
2013-09-16T22:58:11.744Z,1379372291.744 [Reporter](INFO): platform_buoyancy_position 393.488939 cc
2013-09-16T22:58:12.148Z,1379372292.148 [Reporter](INFO): platform_buoyancy_position 393.220340 cc
2013-09-16T22:58:12.247Z,1379372292.247 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T22:58:12.247Z,1379372292.247 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T22:58:12.545Z,1379372292.545 [Reporter](INFO): platform_buoyancy_position 391.340145 cc
2013-09-16T22:58:12.954Z,1379372292.954 [Reporter](INFO): platform_buoyancy_position 389.191351 cc
2013-09-16T22:58:13.731Z,1379372293.731 [Reporter](INFO): platform_buoyancy_position 387.176842 cc
2013-09-16T22:58:14.139Z,1379372294.139 [Reporter](INFO): platform_buoyancy_position 386.505330 cc
2013-09-16T22:58:14.258Z,1379372294.258 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:58:14.258Z,1379372294.258 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T22:58:14.258Z,1379372294.258 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T22:58:14.540Z,1379372294.540 [Reporter](INFO): platform_buoyancy_position 384.893734 cc
2013-09-16T22:58:15.441Z,1379372295.441 [Reporter](INFO): platform_buoyancy_position 382.744940 cc
2013-09-16T22:58:15.582Z,1379372295.582 [Reporter](INFO): platform_buoyancy_position 380.730431 cc
2013-09-16T22:58:15.931Z,1379372295.931 [Reporter](INFO): platform_buoyancy_position 380.596117 cc
2013-09-16T22:58:16.276Z,1379372296.276 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:58:16.276Z,1379372296.276 [DVL_micro](INFO): Querying output modes
2013-09-16T22:58:16.276Z,1379372296.276 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T22:58:16.331Z,1379372296.331 [Reporter](INFO): platform_buoyancy_position 378.715951 cc
2013-09-16T22:58:16.732Z,1379372296.732 [Reporter](INFO): platform_buoyancy_position 378.447323 cc
2013-09-16T22:58:17.136Z,1379372297.136 [Reporter](INFO): platform_buoyancy_position 376.298558 cc
2013-09-16T22:58:17.525Z,1379372297.525 [Reporter](INFO): platform_buoyancy_position 374.284020 cc
2013-09-16T22:58:17.926Z,1379372297.926 [Reporter](INFO): platform_buoyancy_position 374.149764 cc
2013-09-16T22:58:18.280Z,1379372298.280 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:58:18.280Z,1379372298.280 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T22:58:18.280Z,1379372298.280 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T22:58:18.341Z,1379372298.341 [Reporter](INFO): platform_buoyancy_position 372.269540 cc
2013-09-16T22:58:18.727Z,1379372298.727 [Reporter](INFO): platform_buoyancy_position 371.866627 cc
2013-09-16T22:58:19.628Z,1379372299.628 [Reporter](INFO): platform_buoyancy_position 367.837638 cc
2013-09-16T22:58:20.101Z,1379372300.101 [Reporter](INFO): platform_buoyancy_position 365.823158 cc
2013-09-16T22:58:20.291Z,1379372300.291 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:58:20.529Z,1379372300.529 [Reporter](INFO): platform_buoyancy_position 365.554530 cc
2013-09-16T22:58:20.926Z,1379372300.926 [Reporter](INFO): platform_buoyancy_position 363.405736 cc
2013-09-16T22:58:21.334Z,1379372301.334 [Reporter](INFO): platform_buoyancy_position 362.331339 cc
2013-09-16T22:58:21.727Z,1379372301.727 [Reporter](INFO): platform_buoyancy_position 361.256942 cc
2013-09-16T22:58:22.104Z,1379372302.104 [Reporter](INFO): platform_buoyancy_position 359.242433 cc
2013-09-16T22:58:22.303Z,1379372302.303 [DVL_micro](INFO): NQ1 requested
2013-09-16T22:58:22.303Z,1379372302.303 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T22:58:22.525Z,1379372302.525 [Reporter](INFO): platform_buoyancy_position 359.108119 cc
2013-09-16T22:58:23.421Z,1379372303.421 [Reporter](INFO): platform_buoyancy_position 354.944816 cc
2013-09-16T22:58:23.558Z,1379372303.558 [Reporter](INFO): platform_buoyancy_position 354.810560 cc
2013-09-16T22:58:24.306Z,1379372304.306 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T22:58:24.306Z,1379372304.306 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T22:58:24.327Z,1379372304.327 [Reporter](INFO): platform_buoyancy_position 352.661737 cc
2013-09-16T22:58:24.724Z,1379372304.724 [Reporter](INFO): platform_buoyancy_position 350.647257 cc
2013-09-16T22:58:25.125Z,1379372305.125 [Reporter](INFO): platform_buoyancy_position 350.512943 cc
2013-09-16T22:58:25.500Z,1379372305.500 [Reporter](INFO): platform_buoyancy_position 348.364149 cc
2013-09-16T22:58:26.312Z,1379372306.312 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:58:26.312Z,1379372306.312 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T22:58:26.313Z,1379372306.313 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T22:58:26.327Z,1379372306.327 [Reporter](INFO): platform_buoyancy_position 346.215325 cc
2013-09-16T22:58:26.695Z,1379372306.695 [Reporter](INFO): platform_buoyancy_position 344.335131 cc
2013-09-16T22:58:27.624Z,1379372307.624 [Reporter](INFO): platform_buoyancy_position 341.917737 cc
2013-09-16T22:58:28.113Z,1379372308.113 [Reporter](INFO): platform_buoyancy_position 339.903228 cc
2013-09-16T22:58:28.324Z,1379372308.324 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:58:28.324Z,1379372308.324 [DVL_micro](INFO): Querying output modes
2013-09-16T22:58:28.324Z,1379372308.324 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T22:58:28.529Z,1379372308.529 [Reporter](INFO): platform_buoyancy_position 339.768943 cc
2013-09-16T22:58:28.918Z,1379372308.918 [Reporter](INFO): platform_buoyancy_position 337.754434 cc
2013-09-16T22:58:29.335Z,1379372309.335 [Reporter](INFO): platform_buoyancy_position 335.605611 cc
2013-09-16T22:58:29.699Z,1379372309.699 [Reporter](INFO): platform_buoyancy_position 335.471355 cc
2013-09-16T22:58:30.100Z,1379372310.100 [Reporter](INFO): platform_buoyancy_position 333.456846 cc
2013-09-16T22:58:30.327Z,1379372310.327 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:58:30.327Z,1379372310.327 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T22:58:30.327Z,1379372310.327 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T22:58:30.529Z,1379372310.529 [Reporter](INFO): platform_buoyancy_position 332.516734 cc
2013-09-16T22:58:31.422Z,1379372311.422 [Reporter](INFO): platform_buoyancy_position 329.159229 cc
2013-09-16T22:58:31.519Z,1379372311.519 [Reporter](INFO): platform_buoyancy_position 329.024944 cc
2013-09-16T22:58:31.895Z,1379372311.895 [Reporter](INFO): platform_buoyancy_position 326.876121 cc
2013-09-16T22:58:32.328Z,1379372312.328 [Reporter](INFO): platform_buoyancy_position 327.010435 cc
2013-09-16T22:58:32.338Z,1379372312.338 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:58:32.712Z,1379372312.712 [Reporter](INFO): platform_buoyancy_position 324.727327 cc
2013-09-16T22:58:33.502Z,1379372313.502 [Reporter](INFO): platform_buoyancy_position 322.712818 cc
2013-09-16T22:58:33.898Z,1379372313.898 [Reporter](INFO): platform_buoyancy_position 320.564024 cc
2013-09-16T22:58:34.331Z,1379372314.331 [Reporter](INFO): platform_buoyancy_position 320.429739 cc
2013-09-16T22:58:34.349Z,1379372314.349 [DVL_micro](INFO): NQ1 requested
2013-09-16T22:58:34.350Z,1379372314.350 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T22:58:34.703Z,1379372314.703 [Reporter](INFO): platform_buoyancy_position 318.415230 cc
2013-09-16T22:58:35.620Z,1379372315.620 [Reporter](INFO): platform_buoyancy_position 316.132151 cc
2013-09-16T22:58:36.056Z,1379372316.056 [Startup:StartupSatComms:B](INFO): Timed out from 2013-09-16T22:57:35.9Z
2013-09-16T22:58:36.056Z,1379372316.056 [Startup:StartupSatComms:B:A_Timeout] Running Loop=1
2013-09-16T22:58:36.056Z,1379372316.056 [Startup:StartupSatComms:B:A_Timeout](INFO): Aggregate::initialize Startup:StartupSatComms:B:A_Timeout
2013-09-16T22:58:36.056Z,1379372316.056 [Startup:StartupSatComms:B:A_Timeout](INFO): Completed Startup:StartupSatComms:B:A_Timeout
2013-09-16T22:58:36.057Z,1379372316.057 [Startup:StartupSatComms:B] Stopped
2013-09-16T22:58:36.057Z,1379372316.057 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2013-09-16T22:58:36.057Z,1379372316.057 [Startup:StartupSatComms] Stopped
2013-09-16T22:58:36.057Z,1379372316.057 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2013-09-16T22:58:36.058Z,1379372316.058 [Startup](INFO): Completed Startup
2013-09-16T22:58:36.058Z,1379372316.058 [Startup] Stopped
2013-09-16T22:58:36.058Z,1379372316.058 [Startup](INFO): Aggregate::uninitialize Startup
2013-09-16T22:58:36.058Z,1379372316.058 [Startup:A.GoToSurface] Stopped
2013-09-16T22:58:36.058Z,1379372316.058 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-09-16T22:58:36.105Z,1379372316.105 [Reporter](INFO): platform_buoyancy_position 314.117642 cc
2013-09-16T22:58:36.352Z,1379372316.352 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T22:58:36.353Z,1379372316.353 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T22:58:36.490Z,1379372316.490 [MissionManager](IMPORTANT): Started mission Default
2013-09-16T22:58:36.490Z,1379372316.490 [Default] Running Loop=1
2013-09-16T22:58:36.491Z,1379372316.491 [Default](INFO): Aggregate::initialize Default
2013-09-16T22:58:36.491Z,1379372316.491 [Default:D.SetSpeed] Running Loop=1
2013-09-16T22:58:36.491Z,1379372316.491 [Default:D.SetSpeed](DEBUG): Initialize.
2013-09-16T22:58:36.491Z,1379372316.491 [Default:E.GoToSurface] Running Loop=1
2013-09-16T22:58:36.491Z,1379372316.491 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-09-16T22:58:36.491Z,1379372316.491 [Default:Iridium] Running Loop=1
2013-09-16T22:58:36.491Z,1379372316.491 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2013-09-16T22:58:36.491Z,1379372316.491 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-09-16T22:58:36.491Z,1379372316.491 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2013-09-16T22:58:36.492Z,1379372316.492 [Default:Iridium:B.GoToSurface] Running Loop=1
2013-09-16T22:58:36.492Z,1379372316.492 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-09-16T22:58:36.492Z,1379372316.492 [Default:E.GoToSurface] Running Loop=1
2013-09-16T22:58:36.497Z,1379372316.497 [Default:D.SetSpeed] Running Loop=1
2013-09-16T22:58:36.502Z,1379372316.502 [Default:CallIridium] Running Loop=1
2013-09-16T22:58:36.502Z,1379372316.502 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2013-09-16T22:58:36.503Z,1379372316.503 [Default:CallIridium:A] Running Loop=1
2013-09-16T22:58:36.505Z,1379372316.505 [Default:CallIridium:A] Stopped
2013-09-16T22:58:36.505Z,1379372316.505 [Default:CallIridium:B] Running Loop=1
2013-09-16T22:58:36.505Z,1379372316.505 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2013-09-16T22:58:36.510Z,1379372316.510 [Default:Iridium:B.GoToSurface] Stopped
2013-09-16T22:58:36.510Z,1379372316.510 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-09-16T22:58:36.510Z,1379372316.510 [Default:Iridium:Read_Iridium] Running Loop=1
2013-09-16T22:58:36.510Z,1379372316.510 [Default:Iridium:A.SetSpeed] Running Loop=1
2013-09-16T22:58:36.515Z,1379372316.515 [Default:GPS] Running Loop=1
2013-09-16T22:58:36.515Z,1379372316.515 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2013-09-16T22:58:36.515Z,1379372316.515 [Default:GPS:A.SetSpeed] Running Loop=1
2013-09-16T22:58:36.515Z,1379372316.515 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2013-09-16T22:58:36.516Z,1379372316.516 [Default:GPS:B.GoToSurface] Running Loop=1
2013-09-16T22:58:36.516Z,1379372316.516 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-09-16T22:58:36.521Z,1379372316.521 [Default:GPS:B.GoToSurface] Stopped
2013-09-16T22:58:36.521Z,1379372316.521 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-09-16T22:58:36.521Z,1379372316.521 [Default:GPS:Read_GPS] Running Loop=1
2013-09-16T22:58:36.521Z,1379372316.521 [Default:GPS:A.SetSpeed] Running Loop=1
2013-09-16T22:58:36.562Z,1379372316.562 [Reporter](INFO): platform_buoyancy_position 313.983328 cc
2013-09-16T22:58:36.892Z,1379372316.892 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2013-09-16T22:58:36.895Z,1379372316.895 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2013-09-16T22:58:36.935Z,1379372316.935 [Reporter](INFO): platform_buoyancy_position 311.968848 cc
2013-09-16T22:58:37.331Z,1379372317.331 [Reporter](INFO): platform_buoyancy_position 309.954339 cc
2013-09-16T22:58:37.728Z,1379372317.728 [Reporter](INFO): platform_buoyancy_position 309.685740 cc
2013-09-16T22:58:38.086Z,1379372318.086 [Reporter](INFO): platform_buoyancy_position 307.671231 cc
2013-09-16T22:58:38.355Z,1379372318.355 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:58:38.355Z,1379372318.355 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T22:58:38.355Z,1379372318.355 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T22:58:38.529Z,1379372318.529 [Reporter](INFO): platform_buoyancy_position 305.656751 cc
2013-09-16T22:58:39.426Z,1379372319.426 [Reporter](INFO): platform_buoyancy_position 303.239329 cc
2013-09-16T22:58:39.915Z,1379372319.915 [Reporter](INFO): platform_buoyancy_position 301.224820 cc
2013-09-16T22:58:40.328Z,1379372320.328 [Reporter](INFO): platform_buoyancy_position 299.076026 cc
2013-09-16T22:58:40.358Z,1379372320.358 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:58:40.358Z,1379372320.358 [DVL_micro](INFO): Querying output modes
2013-09-16T22:58:40.358Z,1379372320.358 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T22:58:41.126Z,1379372321.126 [Reporter](INFO): platform_buoyancy_position 296.927232 cc
2013-09-16T22:58:41.518Z,1379372321.518 [Reporter](INFO): platform_buoyancy_position 296.792947 cc
2013-09-16T22:58:41.898Z,1379372321.898 [Reporter](INFO): platform_buoyancy_position 294.778438 cc
2013-09-16T22:58:42.332Z,1379372322.332 [Reporter](INFO): platform_buoyancy_position 294.375524 cc
2013-09-16T22:58:42.361Z,1379372322.361 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:58:42.361Z,1379372322.361 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T22:58:42.361Z,1379372322.361 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T22:58:42.719Z,1379372322.719 [Reporter](INFO): platform_buoyancy_position 292.629644 cc
2013-09-16T22:58:43.625Z,1379372323.625 [Reporter](INFO): platform_buoyancy_position 290.212221 cc
2013-09-16T22:58:43.765Z,1379372323.765 [Reporter](INFO): platform_buoyancy_position 288.466341 cc
2013-09-16T22:58:44.117Z,1379372324.117 [Reporter](INFO): platform_buoyancy_position 288.332027 cc
2013-09-16T22:58:44.364Z,1379372324.364 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:58:44.530Z,1379372324.530 [Reporter](INFO): platform_buoyancy_position 286.183233 cc
2013-09-16T22:58:44.927Z,1379372324.927 [Reporter](INFO): platform_buoyancy_position 286.048918 cc
2013-09-16T22:58:45.336Z,1379372325.336 [Reporter](INFO): platform_buoyancy_position 284.034410 cc
2013-09-16T22:58:45.724Z,1379372325.724 [Reporter](INFO): platform_buoyancy_position 281.885616 cc
2013-09-16T22:58:46.372Z,1379372326.372 [DVL_micro](INFO): NQ1 requested
2013-09-16T22:58:46.372Z,1379372326.372 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T22:58:46.534Z,1379372326.534 [Reporter](INFO): platform_buoyancy_position 279.736851 cc
2013-09-16T22:58:47.427Z,1379372327.427 [Reporter](INFO): platform_buoyancy_position 277.319428 cc
2013-09-16T22:58:47.916Z,1379372327.916 [Reporter](INFO): platform_buoyancy_position 275.439234 cc
2013-09-16T22:58:48.332Z,1379372328.332 [Reporter](INFO): platform_buoyancy_position 273.290440 cc
2013-09-16T22:58:48.375Z,1379372328.375 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T22:58:48.375Z,1379372328.375 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T22:58:48.729Z,1379372328.729 [Reporter](INFO): platform_buoyancy_position 273.156125 cc
2013-09-16T22:58:49.126Z,1379372329.126 [Reporter](INFO): platform_buoyancy_position 271.141645 cc
2013-09-16T22:58:49.531Z,1379372329.531 [Reporter](INFO): platform_buoyancy_position 270.738732 cc
2013-09-16T22:58:49.919Z,1379372329.919 [Reporter](INFO): platform_buoyancy_position 268.858508 cc
2013-09-16T22:58:50.332Z,1379372330.332 [Reporter](INFO): platform_buoyancy_position 266.978343 cc
2013-09-16T22:58:50.382Z,1379372330.382 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:58:50.382Z,1379372330.382 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T22:58:50.382Z,1379372330.382 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T22:58:50.728Z,1379372330.728 [Reporter](INFO): platform_buoyancy_position 266.575429 cc
2013-09-16T22:58:51.625Z,1379372331.625 [Reporter](INFO): platform_buoyancy_position 262.412126 cc
2013-09-16T22:58:51.785Z,1379372331.785 [Reporter](INFO): platform_buoyancy_position 262.546411 cc
2013-09-16T22:58:52.094Z,1379372332.094 [Reporter](INFO): platform_buoyancy_position 262.412126 cc
2013-09-16T22:58:52.385Z,1379372332.385 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:58:52.385Z,1379372332.385 [DVL_micro](INFO): Querying output modes
2013-09-16T22:58:52.385Z,1379372332.385 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T22:58:52.519Z,1379372332.519 [Reporter](INFO): platform_buoyancy_position 260.397646 cc
2013-09-16T22:58:52.927Z,1379372332.927 [Reporter](INFO): platform_buoyancy_position 258.248852 cc
2013-09-16T22:58:53.328Z,1379372333.328 [Reporter](INFO): platform_buoyancy_position 258.114538 cc
2013-09-16T22:58:53.713Z,1379372333.713 [Reporter](INFO): platform_buoyancy_position 256.234343 cc
2013-09-16T22:58:54.102Z,1379372334.102 [Reporter](INFO): platform_buoyancy_position 255.965715 cc
2013-09-16T22:58:54.388Z,1379372334.388 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:58:54.388Z,1379372334.388 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T22:58:54.388Z,1379372334.388 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T22:58:54.542Z,1379372334.542 [Reporter](INFO): platform_buoyancy_position 253.816921 cc
2013-09-16T22:58:55.427Z,1379372335.427 [Reporter](INFO): platform_buoyancy_position 250.593730 cc
2013-09-16T22:58:55.547Z,1379372335.547 [Reporter](INFO): platform_buoyancy_position 249.787932 cc
2013-09-16T22:58:55.916Z,1379372335.916 [Reporter](INFO): platform_buoyancy_position 249.519304 cc
2013-09-16T22:58:56.329Z,1379372336.329 [Reporter](INFO): platform_buoyancy_position 247.504824 cc
2013-09-16T22:58:56.395Z,1379372336.395 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:58:56.730Z,1379372336.730 [Reporter](INFO): platform_buoyancy_position 247.370539 cc
2013-09-16T22:58:57.146Z,1379372337.146 [Reporter](INFO): platform_buoyancy_position 245.356030 cc
2013-09-16T22:58:57.531Z,1379372337.531 [Reporter](INFO): platform_buoyancy_position 243.207222 cc
2013-09-16T22:58:57.919Z,1379372337.919 [Reporter](INFO): platform_buoyancy_position 243.072936 cc
2013-09-16T22:58:58.332Z,1379372338.332 [Reporter](INFO): platform_buoyancy_position 240.924128 cc
2013-09-16T22:58:58.407Z,1379372338.407 [DVL_micro](INFO): NQ1 requested
2013-09-16T22:58:58.407Z,1379372338.407 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T22:59:00.410Z,1379372340.410 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T22:59:00.410Z,1379372340.410 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T22:59:02.414Z,1379372342.414 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:59:02.414Z,1379372342.414 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T22:59:02.414Z,1379372342.414 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T22:59:04.423Z,1379372344.423 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:59:04.424Z,1379372344.424 [DVL_micro](INFO): Querying output modes
2013-09-16T22:59:04.424Z,1379372344.424 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T22:59:06.426Z,1379372346.426 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:59:06.427Z,1379372346.427 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T22:59:06.427Z,1379372346.427 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T22:59:08.430Z,1379372348.430 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:59:10.433Z,1379372350.433 [DVL_micro](INFO): NQ1 requested
2013-09-16T22:59:10.433Z,1379372350.433 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T22:59:12.436Z,1379372352.436 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T22:59:12.436Z,1379372352.436 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T22:59:14.447Z,1379372354.447 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:59:14.447Z,1379372354.447 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T22:59:14.447Z,1379372354.447 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T22:59:16.454Z,1379372356.454 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:59:16.454Z,1379372356.454 [DVL_micro](INFO): Querying output modes
2013-09-16T22:59:16.454Z,1379372356.454 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T22:59:18.465Z,1379372358.465 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:59:18.465Z,1379372358.465 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T22:59:18.465Z,1379372358.465 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T22:59:20.484Z,1379372360.484 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:59:22.492Z,1379372362.492 [DVL_micro](INFO): NQ1 requested
2013-09-16T22:59:22.492Z,1379372362.492 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T22:59:24.495Z,1379372364.495 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T22:59:24.495Z,1379372364.495 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T22:59:26.502Z,1379372366.502 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:59:26.502Z,1379372366.502 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T22:59:26.502Z,1379372366.502 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T22:59:28.505Z,1379372368.505 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:59:28.505Z,1379372368.505 [DVL_micro](INFO): Querying output modes
2013-09-16T22:59:28.505Z,1379372368.505 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T22:59:30.508Z,1379372370.508 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:59:30.508Z,1379372370.508 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T22:59:30.508Z,1379372370.508 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T22:59:32.511Z,1379372372.511 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:59:34.519Z,1379372374.519 [DVL_micro](INFO): NQ1 requested
2013-09-16T22:59:34.519Z,1379372374.519 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T22:59:36.522Z,1379372376.522 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T22:59:36.522Z,1379372376.522 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T22:59:38.532Z,1379372378.532 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:59:38.532Z,1379372378.532 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T22:59:38.533Z,1379372378.533 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T22:59:40.535Z,1379372380.535 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:59:40.535Z,1379372380.535 [DVL_micro](INFO): Querying output modes
2013-09-16T22:59:40.536Z,1379372380.536 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T22:59:42.538Z,1379372382.538 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:59:42.539Z,1379372382.539 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T22:59:42.539Z,1379372382.539 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T22:59:44.542Z,1379372384.542 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:59:46.545Z,1379372386.545 [DVL_micro](INFO): NQ1 requested
2013-09-16T22:59:46.545Z,1379372386.545 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T22:59:48.548Z,1379372388.548 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T22:59:48.548Z,1379372388.548 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T22:59:50.555Z,1379372390.555 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:59:50.555Z,1379372390.555 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T22:59:50.555Z,1379372390.555 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T22:59:52.562Z,1379372392.562 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:59:52.562Z,1379372392.562 [DVL_micro](INFO): Querying output modes
2013-09-16T22:59:52.563Z,1379372392.563 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T22:59:54.572Z,1379372394.572 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:59:54.572Z,1379372394.572 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T22:59:54.572Z,1379372394.572 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T22:59:56.576Z,1379372396.576 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T22:59:58.584Z,1379372398.584 [DVL_micro](INFO): NQ1 requested
2013-09-16T22:59:58.584Z,1379372398.584 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:00:00.587Z,1379372400.587 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:00:00.587Z,1379372400.587 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:00:02.590Z,1379372402.590 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:00:02.590Z,1379372402.590 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:00:02.590Z,1379372402.590 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:00:04.597Z,1379372404.597 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:00:04.597Z,1379372404.597 [DVL_micro](INFO): Querying output modes
2013-09-16T23:00:04.597Z,1379372404.597 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:00:06.604Z,1379372406.604 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:00:06.604Z,1379372406.604 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:00:06.604Z,1379372406.604 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:00:08.611Z,1379372408.611 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:00:10.618Z,1379372410.618 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:00:10.619Z,1379372410.619 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:00:12.622Z,1379372412.622 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:00:12.622Z,1379372412.622 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:00:14.624Z,1379372414.624 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:00:14.624Z,1379372414.624 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:00:14.625Z,1379372414.625 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:00:16.627Z,1379372416.627 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:00:16.627Z,1379372416.627 [DVL_micro](INFO): Querying output modes
2013-09-16T23:00:16.628Z,1379372416.628 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:00:18.638Z,1379372418.638 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:00:18.639Z,1379372418.639 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:00:18.639Z,1379372418.639 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:00:20.650Z,1379372420.650 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:00:22.661Z,1379372422.661 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:00:22.661Z,1379372422.661 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:00:24.664Z,1379372424.664 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:00:24.665Z,1379372424.665 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:00:26.675Z,1379372426.675 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:00:26.675Z,1379372426.675 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:00:26.675Z,1379372426.675 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:00:28.682Z,1379372428.682 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:00:28.682Z,1379372428.682 [DVL_micro](INFO): Querying output modes
2013-09-16T23:00:28.682Z,1379372428.682 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:00:30.693Z,1379372430.693 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:00:30.693Z,1379372430.693 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:00:30.693Z,1379372430.693 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:00:32.696Z,1379372432.696 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:00:34.708Z,1379372434.708 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:00:34.708Z,1379372434.708 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:00:36.711Z,1379372436.711 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:00:36.711Z,1379372436.711 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:00:38.718Z,1379372438.718 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:00:38.718Z,1379372438.718 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:00:38.718Z,1379372438.718 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:00:39.709Z,1379372439.709 [CBIT](INFO): Clearing failed count for component AHRS_sp3003D
2013-09-16T23:00:39.709Z,1379372439.709 [AHRS_sp3003D] No Fault, FailCount= 5
2013-09-16T23:00:40.075Z,1379372440.075 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-09-16T23:00:40.729Z,1379372440.729 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:00:40.729Z,1379372440.729 [DVL_micro](INFO): Querying output modes
2013-09-16T23:00:40.729Z,1379372440.729 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:00:41.205Z,1379372441.205 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2013-09-16T23:00:41.206Z,1379372441.206 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2013-09-16T23:00:41.206Z,1379372441.206 [AHRS_sp3003D] Hardware Fault, FailCount= 1
2013-09-16T23:00:41.206Z,1379372441.206 [AHRS_sp3003D](ERROR): Hardware Fault
2013-09-16T23:00:41.260Z,1379372441.260 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2013-09-16T23:00:41.298Z,1379372441.298 [AHRS_sp3003D](INFO): Powering down
2013-09-16T23:00:42.509Z,1379372442.509 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2013-09-16T23:00:42.509Z,1379372442.509 [AHRS_sp3003D] No Fault, FailCount= 1
2013-09-16T23:00:42.736Z,1379372442.736 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:00:42.736Z,1379372442.736 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:00:42.736Z,1379372442.736 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:00:42.875Z,1379372442.875 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-09-16T23:00:44.174Z,1379372444.174 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2013-09-16T23:00:44.174Z,1379372444.174 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2013-09-16T23:00:44.174Z,1379372444.174 [AHRS_sp3003D] Hardware Fault, FailCount= 2
2013-09-16T23:00:44.174Z,1379372444.174 [AHRS_sp3003D](ERROR): Hardware Fault
2013-09-16T23:00:44.210Z,1379372444.210 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2013-09-16T23:00:44.233Z,1379372444.233 [AHRS_sp3003D](INFO): Powering down
2013-09-16T23:00:44.743Z,1379372444.743 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:00:45.468Z,1379372445.468 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2013-09-16T23:00:45.468Z,1379372445.468 [AHRS_sp3003D] No Fault, FailCount= 2
2013-09-16T23:00:45.832Z,1379372445.832 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-09-16T23:00:46.748Z,1379372446.748 [DVL_micro](INFO): pause:Powering down
2013-09-16T23:00:46.968Z,1379372446.968 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2013-09-16T23:00:46.968Z,1379372446.968 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2013-09-16T23:00:46.968Z,1379372446.968 [AHRS_sp3003D] Hardware Fault, FailCount= 3
2013-09-16T23:00:46.968Z,1379372446.968 [AHRS_sp3003D](ERROR): Hardware Fault
2013-09-16T23:00:47.152Z,1379372447.152 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:00:47.152Z,1379372447.152 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:00:47.152Z,1379372447.152 [DVL_micro](INFO): resume:Powering up
2013-09-16T23:00:47.153Z,1379372447.153 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:00:47.519Z,1379372447.519 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2013-09-16T23:00:47.539Z,1379372447.539 [AHRS_sp3003D](INFO): Powering down
2013-09-16T23:00:48.808Z,1379372448.808 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2013-09-16T23:00:48.808Z,1379372448.808 [AHRS_sp3003D] No Fault, FailCount= 3
2013-09-16T23:00:49.134Z,1379372449.134 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-09-16T23:00:49.158Z,1379372449.158 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:00:49.158Z,1379372449.158 [DVL_micro](INFO): Querying output modes
2013-09-16T23:00:49.159Z,1379372449.159 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:00:50.279Z,1379372450.279 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2013-09-16T23:00:50.280Z,1379372450.280 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2013-09-16T23:00:50.280Z,1379372450.280 [AHRS_sp3003D] Hardware Fault, FailCount= 4
2013-09-16T23:00:50.280Z,1379372450.280 [AHRS_sp3003D](ERROR): Hardware Fault
2013-09-16T23:00:50.310Z,1379372450.310 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2013-09-16T23:00:50.335Z,1379372450.335 [AHRS_sp3003D](INFO): Powering down
2013-09-16T23:00:51.165Z,1379372451.165 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:00:51.165Z,1379372451.165 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:00:51.165Z,1379372451.165 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:00:51.741Z,1379372451.741 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2013-09-16T23:00:51.741Z,1379372451.741 [AHRS_sp3003D] No Fault, FailCount= 4
2013-09-16T23:00:52.110Z,1379372452.110 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-09-16T23:00:53.176Z,1379372453.176 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:00:53.179Z,1379372453.179 [DVL_micro](INFO): pause:Powering down
2013-09-16T23:00:53.252Z,1379372453.252 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2013-09-16T23:00:53.252Z,1379372453.252 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2013-09-16T23:00:53.252Z,1379372453.252 [AHRS_sp3003D] Hardware Fault, FailCount= 5
2013-09-16T23:00:53.252Z,1379372453.252 [AHRS_sp3003D](ERROR): Hardware Fault
2013-09-16T23:00:53.332Z,1379372453.332 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2013-09-16T23:00:53.332Z,1379372453.332 [CBIT](CRITICAL): Hardware Fault in component: AHRS_sp3003D
2013-09-16T23:00:53.347Z,1379372453.347 [AHRS_sp3003D](INFO): Powering down
2013-09-16T23:00:53.583Z,1379372453.583 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:00:53.584Z,1379372453.584 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:00:53.584Z,1379372453.584 [DVL_micro](INFO): resume:Powering up
2013-09-16T23:00:53.585Z,1379372453.585 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:00:55.588Z,1379372455.588 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:00:55.589Z,1379372455.589 [DVL_micro](INFO): Querying output modes
2013-09-16T23:00:55.589Z,1379372455.589 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:00:57.591Z,1379372457.591 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:00:57.591Z,1379372457.591 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:00:57.591Z,1379372457.591 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:00:59.602Z,1379372459.602 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:00:59.604Z,1379372459.604 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:00:59.604Z,1379372459.604 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:01:01.609Z,1379372461.609 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:01:01.610Z,1379372461.610 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:01:03.616Z,1379372463.616 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:01:03.616Z,1379372463.616 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:01:03.616Z,1379372463.616 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:01:05.627Z,1379372465.627 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:01:05.627Z,1379372465.627 [DVL_micro](INFO): Querying output modes
2013-09-16T23:01:05.628Z,1379372465.628 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:01:07.634Z,1379372467.634 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:01:07.635Z,1379372467.635 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:01:07.635Z,1379372467.635 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:01:09.646Z,1379372469.646 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:01:11.657Z,1379372471.657 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:01:11.657Z,1379372471.657 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:01:13.660Z,1379372473.660 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:01:13.660Z,1379372473.660 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:01:15.675Z,1379372475.675 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:01:15.675Z,1379372475.675 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:01:15.675Z,1379372475.675 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:01:17.682Z,1379372477.682 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:01:17.682Z,1379372477.682 [DVL_micro](INFO): Querying output modes
2013-09-16T23:01:17.682Z,1379372477.682 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:01:19.693Z,1379372479.693 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:01:19.693Z,1379372479.693 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:01:19.693Z,1379372479.693 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:01:21.696Z,1379372481.696 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:01:23.708Z,1379372483.708 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:01:23.708Z,1379372483.708 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:01:25.711Z,1379372485.711 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:01:25.711Z,1379372485.711 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:01:27.718Z,1379372487.718 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:01:27.718Z,1379372487.718 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:01:27.718Z,1379372487.718 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:01:29.729Z,1379372489.729 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:01:29.729Z,1379372489.729 [DVL_micro](INFO): Querying output modes
2013-09-16T23:01:29.729Z,1379372489.729 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:01:31.740Z,1379372491.740 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:01:31.740Z,1379372491.740 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:01:31.740Z,1379372491.740 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:01:33.743Z,1379372493.743 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:01:35.755Z,1379372495.755 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:01:35.755Z,1379372495.755 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:01:37.758Z,1379372497.758 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:01:37.758Z,1379372497.758 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:01:39.760Z,1379372499.760 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:01:39.760Z,1379372499.760 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:01:39.761Z,1379372499.761 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:01:41.783Z,1379372501.783 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:01:41.784Z,1379372501.784 [DVL_micro](INFO): Querying output modes
2013-09-16T23:01:41.784Z,1379372501.784 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:01:43.786Z,1379372503.786 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:01:43.787Z,1379372503.787 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:01:43.787Z,1379372503.787 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:01:45.798Z,1379372505.798 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:01:47.809Z,1379372507.809 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:01:47.809Z,1379372507.809 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:01:49.812Z,1379372509.812 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:01:49.812Z,1379372509.812 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:01:51.819Z,1379372511.819 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:01:51.819Z,1379372511.819 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:01:51.819Z,1379372511.819 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:01:53.830Z,1379372513.830 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:01:53.830Z,1379372513.830 [DVL_micro](INFO): Querying output modes
2013-09-16T23:01:53.830Z,1379372513.830 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:01:55.841Z,1379372515.841 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:01:55.841Z,1379372515.841 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:01:55.841Z,1379372515.841 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:01:57.852Z,1379372517.852 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:01:59.864Z,1379372519.864 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:01:59.864Z,1379372519.864 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:02:01.899Z,1379372521.899 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:02:01.899Z,1379372521.899 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:02:03.904Z,1379372523.904 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:02:03.904Z,1379372523.904 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:02:03.904Z,1379372523.904 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:02:05.909Z,1379372525.909 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:02:05.909Z,1379372525.909 [DVL_micro](INFO): Querying output modes
2013-09-16T23:02:05.909Z,1379372525.909 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:02:07.916Z,1379372527.916 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:02:07.916Z,1379372527.916 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:02:07.916Z,1379372527.916 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:02:09.919Z,1379372529.919 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:02:11.931Z,1379372531.931 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:02:11.931Z,1379372531.931 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:02:13.934Z,1379372533.934 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:02:13.934Z,1379372533.934 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:02:15.944Z,1379372535.944 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:02:15.944Z,1379372535.944 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:02:15.945Z,1379372535.945 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:02:17.952Z,1379372537.952 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:02:17.952Z,1379372537.952 [DVL_micro](INFO): Querying output modes
2013-09-16T23:02:17.952Z,1379372537.952 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:02:19.959Z,1379372539.959 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:02:19.959Z,1379372539.959 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:02:19.959Z,1379372539.959 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:02:21.966Z,1379372541.966 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:02:23.977Z,1379372543.977 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:02:23.977Z,1379372543.977 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:02:25.980Z,1379372545.980 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:02:25.981Z,1379372545.981 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:02:27.991Z,1379372547.991 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:02:27.991Z,1379372547.991 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:02:27.991Z,1379372547.991 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:02:30.018Z,1379372550.018 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:02:30.019Z,1379372550.019 [DVL_micro](INFO): Querying output modes
2013-09-16T23:02:30.019Z,1379372550.019 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:02:32.021Z,1379372552.021 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:02:32.021Z,1379372552.021 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:02:32.022Z,1379372552.022 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:02:34.024Z,1379372554.024 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:02:36.036Z,1379372556.036 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:02:36.036Z,1379372556.036 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:02:38.039Z,1379372558.039 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:02:38.039Z,1379372558.039 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:02:40.050Z,1379372560.050 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:02:40.050Z,1379372560.050 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:02:40.050Z,1379372560.050 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:02:42.053Z,1379372562.053 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:02:42.053Z,1379372562.053 [DVL_micro](INFO): Querying output modes
2013-09-16T23:02:42.053Z,1379372562.053 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:02:44.060Z,1379372564.060 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:02:44.060Z,1379372564.060 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:02:44.060Z,1379372564.060 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:02:46.067Z,1379372566.067 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:02:48.083Z,1379372568.083 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:02:48.083Z,1379372568.083 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:02:50.086Z,1379372570.086 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:02:50.086Z,1379372570.086 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:02:52.092Z,1379372572.092 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:02:52.092Z,1379372572.092 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:02:52.093Z,1379372572.093 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:02:54.099Z,1379372574.099 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:02:54.100Z,1379372574.100 [DVL_micro](INFO): Querying output modes
2013-09-16T23:02:54.100Z,1379372574.100 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:02:56.107Z,1379372576.107 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:02:56.107Z,1379372576.107 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:02:56.107Z,1379372576.107 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:02:58.114Z,1379372578.114 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:03:00.126Z,1379372580.126 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:03:00.126Z,1379372580.126 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:03:02.129Z,1379372582.129 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:03:02.129Z,1379372582.129 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:03:04.139Z,1379372584.139 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:03:04.139Z,1379372584.139 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:03:04.139Z,1379372584.139 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:03:06.146Z,1379372586.146 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:03:06.146Z,1379372586.146 [DVL_micro](INFO): Querying output modes
2013-09-16T23:03:06.147Z,1379372586.147 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:03:08.149Z,1379372588.149 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:03:08.149Z,1379372588.149 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:03:08.150Z,1379372588.150 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:03:10.156Z,1379372590.156 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:03:12.160Z,1379372592.160 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:03:12.160Z,1379372592.160 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:03:14.163Z,1379372594.163 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:03:14.163Z,1379372594.163 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:03:16.174Z,1379372596.174 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:03:16.174Z,1379372596.174 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:03:16.174Z,1379372596.174 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:03:18.185Z,1379372598.185 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:03:18.185Z,1379372598.185 [DVL_micro](INFO): Querying output modes
2013-09-16T23:03:18.185Z,1379372598.185 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:03:20.196Z,1379372600.196 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:03:20.196Z,1379372600.196 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:03:20.196Z,1379372600.196 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:03:22.199Z,1379372602.199 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:03:24.211Z,1379372604.211 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:03:24.211Z,1379372604.211 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:03:26.226Z,1379372606.226 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:03:26.226Z,1379372606.226 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:03:28.232Z,1379372608.232 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:03:28.232Z,1379372608.232 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:03:28.233Z,1379372608.233 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:03:30.239Z,1379372610.239 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:03:30.240Z,1379372610.240 [DVL_micro](INFO): Querying output modes
2013-09-16T23:03:30.240Z,1379372610.240 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:03:32.251Z,1379372612.251 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:03:32.251Z,1379372612.251 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:03:32.251Z,1379372612.251 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:03:34.254Z,1379372614.254 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:03:36.265Z,1379372616.265 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:03:36.266Z,1379372616.266 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:03:38.268Z,1379372618.268 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:03:38.269Z,1379372618.269 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:03:40.275Z,1379372620.275 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:03:40.275Z,1379372620.275 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:03:40.275Z,1379372620.275 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:03:42.282Z,1379372622.282 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:03:42.282Z,1379372622.282 [DVL_micro](INFO): Querying output modes
2013-09-16T23:03:42.282Z,1379372622.282 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:03:44.289Z,1379372624.289 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:03:44.290Z,1379372624.290 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:03:44.290Z,1379372624.290 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:03:46.292Z,1379372626.292 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:03:48.304Z,1379372628.304 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:03:48.304Z,1379372628.304 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:03:50.307Z,1379372630.307 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:03:50.307Z,1379372630.307 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:03:52.314Z,1379372632.314 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:03:52.314Z,1379372632.314 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:03:52.314Z,1379372632.314 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:03:54.321Z,1379372634.321 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:03:54.321Z,1379372634.321 [DVL_micro](INFO): Querying output modes
2013-09-16T23:03:54.321Z,1379372634.321 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:03:56.332Z,1379372636.332 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:03:56.332Z,1379372636.332 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:03:56.333Z,1379372636.333 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:03:58.343Z,1379372638.343 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:04:00.355Z,1379372640.355 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:04:00.355Z,1379372640.355 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:04:02.358Z,1379372642.358 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:04:02.358Z,1379372642.358 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:04:04.360Z,1379372644.360 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:04:04.360Z,1379372644.360 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:04:04.361Z,1379372644.361 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:04:06.384Z,1379372646.384 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:04:06.384Z,1379372646.384 [DVL_micro](INFO): Querying output modes
2013-09-16T23:04:06.384Z,1379372646.384 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:04:08.391Z,1379372648.391 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:04:08.391Z,1379372648.391 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:04:08.391Z,1379372648.391 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:04:10.402Z,1379372650.402 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:04:12.410Z,1379372652.410 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:04:12.410Z,1379372652.410 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:04:14.412Z,1379372654.412 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:04:14.413Z,1379372654.413 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:04:16.424Z,1379372656.424 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:04:16.424Z,1379372656.424 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:04:16.424Z,1379372656.424 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:04:18.434Z,1379372658.434 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:04:18.434Z,1379372658.434 [DVL_micro](INFO): Querying output modes
2013-09-16T23:04:18.435Z,1379372658.435 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:04:20.445Z,1379372660.445 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:04:20.445Z,1379372660.445 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:04:20.446Z,1379372660.446 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:04:22.456Z,1379372662.456 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:04:24.460Z,1379372664.460 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:04:24.460Z,1379372664.460 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:04:26.463Z,1379372666.463 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:04:26.463Z,1379372666.463 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:04:28.474Z,1379372668.474 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:04:28.474Z,1379372668.474 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:04:28.474Z,1379372668.474 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:04:30.477Z,1379372670.477 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:04:30.477Z,1379372670.477 [DVL_micro](INFO): Querying output modes
2013-09-16T23:04:30.477Z,1379372670.477 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:04:32.480Z,1379372672.480 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:04:32.480Z,1379372672.480 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:04:32.480Z,1379372672.480 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:04:34.491Z,1379372674.491 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:04:36.503Z,1379372676.503 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:04:36.503Z,1379372676.503 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:04:38.506Z,1379372678.506 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:04:38.506Z,1379372678.506 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:04:40.513Z,1379372680.513 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:04:40.513Z,1379372680.513 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:04:40.513Z,1379372680.513 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:04:42.516Z,1379372682.516 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:04:42.516Z,1379372682.516 [DVL_micro](INFO): Querying output modes
2013-09-16T23:04:42.516Z,1379372682.516 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:04:44.523Z,1379372684.523 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:04:44.523Z,1379372684.523 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:04:44.523Z,1379372684.523 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:04:46.530Z,1379372686.530 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:04:48.538Z,1379372688.538 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:04:48.538Z,1379372688.538 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:04:50.540Z,1379372690.540 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:04:50.541Z,1379372690.541 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:04:52.551Z,1379372692.551 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:04:52.551Z,1379372692.551 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:04:52.551Z,1379372692.551 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:04:54.562Z,1379372694.562 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:04:54.562Z,1379372694.562 [DVL_micro](INFO): Querying output modes
2013-09-16T23:04:54.563Z,1379372694.563 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:04:56.565Z,1379372696.565 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:04:56.566Z,1379372696.566 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:04:56.566Z,1379372696.566 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:04:58.573Z,1379372698.573 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:05:00.575Z,1379372700.575 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:05:00.575Z,1379372700.575 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:05:02.579Z,1379372702.579 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:05:02.579Z,1379372702.579 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:05:04.586Z,1379372704.586 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:05:04.586Z,1379372704.586 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:05:04.586Z,1379372704.586 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:05:06.589Z,1379372706.589 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:05:06.589Z,1379372706.589 [DVL_micro](INFO): Querying output modes
2013-09-16T23:05:06.589Z,1379372706.589 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:05:08.596Z,1379372708.596 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:05:08.596Z,1379372708.596 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:05:08.596Z,1379372708.596 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:05:10.607Z,1379372710.607 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:05:12.611Z,1379372712.611 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:05:12.611Z,1379372712.611 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:05:14.614Z,1379372714.614 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:05:14.614Z,1379372714.614 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:05:16.620Z,1379372716.620 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:05:16.621Z,1379372716.621 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:05:16.621Z,1379372716.621 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:05:18.628Z,1379372718.628 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:05:18.628Z,1379372718.628 [DVL_micro](INFO): Querying output modes
2013-09-16T23:05:18.628Z,1379372718.628 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:05:20.639Z,1379372720.639 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:05:20.639Z,1379372720.639 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:05:20.639Z,1379372720.639 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:05:22.646Z,1379372722.646 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:05:24.666Z,1379372724.666 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:05:24.666Z,1379372724.666 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:05:26.668Z,1379372726.668 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:05:26.669Z,1379372726.669 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:05:28.679Z,1379372728.679 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:05:28.679Z,1379372728.679 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:05:28.680Z,1379372728.680 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:05:30.690Z,1379372730.690 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:05:30.690Z,1379372730.690 [DVL_micro](INFO): Querying output modes
2013-09-16T23:05:30.691Z,1379372730.691 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:05:32.697Z,1379372732.697 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:05:32.698Z,1379372732.698 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:05:32.698Z,1379372732.698 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:05:34.700Z,1379372734.700 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:05:36.704Z,1379372736.704 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:05:36.704Z,1379372736.704 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:05:38.707Z,1379372738.707 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:05:38.707Z,1379372738.707 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:05:40.714Z,1379372740.714 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:05:40.714Z,1379372740.714 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:05:40.714Z,1379372740.714 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:05:42.725Z,1379372742.725 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:05:42.725Z,1379372742.725 [DVL_micro](INFO): Querying output modes
2013-09-16T23:05:42.725Z,1379372742.725 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:05:43.918Z,1379372743.918 [NAL9602](FAULT): GPS failed to acquire within timeout.
2013-09-16T23:05:43.918Z,1379372743.918 [NAL9602] Data Fault, FailCount= 1
2013-09-16T23:05:43.918Z,1379372743.918 [NAL9602](ERROR): Data Fault
2013-09-16T23:05:43.945Z,1379372743.945 [CBIT](ERROR): Data Fault in component: NAL9602
2013-09-16T23:05:44.313Z,1379372744.313 [NAL9602](INFO): Powering down
2013-09-16T23:05:44.728Z,1379372744.728 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:05:44.728Z,1379372744.728 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:05:44.728Z,1379372744.728 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:05:45.144Z,1379372745.144 [CBIT](INFO): Clearing failed state for component NAL9602
2013-09-16T23:05:45.144Z,1379372745.144 [NAL9602] No Fault, FailCount= 1
2013-09-16T23:05:46.739Z,1379372746.739 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:05:48.743Z,1379372748.743 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:05:48.743Z,1379372748.743 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:05:49.513Z,1379372749.513 [NAL9602](INFO): Powering up NAL9602
2013-09-16T23:05:50.746Z,1379372750.746 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:05:50.746Z,1379372750.746 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:05:52.748Z,1379372752.748 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:05:52.749Z,1379372752.749 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:05:52.749Z,1379372752.749 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:05:53.541Z,1379372753.541 [CBIT](INFO): Clearing failed count for component AHRS_sp3003D
2013-09-16T23:05:53.541Z,1379372753.541 [AHRS_sp3003D] No Fault, FailCount= 5
2013-09-16T23:05:53.916Z,1379372753.916 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-09-16T23:05:54.752Z,1379372754.752 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:05:54.752Z,1379372754.752 [DVL_micro](INFO): Querying output modes
2013-09-16T23:05:54.752Z,1379372754.752 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:05:54.828Z,1379372754.828 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2013-09-16T23:05:54.828Z,1379372754.828 [AHRS_sp3003D](ERROR): readHeadingMagBin got 0x000000
2013-09-16T23:05:54.828Z,1379372754.828 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2013-09-16T23:05:54.828Z,1379372754.828 [AHRS_sp3003D] Hardware Fault, FailCount= 1
2013-09-16T23:05:54.828Z,1379372754.828 [AHRS_sp3003D](ERROR): Hardware Fault
2013-09-16T23:05:54.856Z,1379372754.856 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2013-09-16T23:05:55.111Z,1379372755.111 [AHRS_sp3003D](INFO): Powering down
2013-09-16T23:05:56.342Z,1379372756.342 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2013-09-16T23:05:56.342Z,1379372756.342 [AHRS_sp3003D] No Fault, FailCount= 1
2013-09-16T23:05:56.714Z,1379372756.714 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-09-16T23:05:56.755Z,1379372756.755 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:05:56.755Z,1379372756.755 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:05:56.755Z,1379372756.755 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:05:57.848Z,1379372757.848 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2013-09-16T23:05:57.848Z,1379372757.848 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2013-09-16T23:05:57.849Z,1379372757.849 [AHRS_sp3003D] Hardware Fault, FailCount= 2
2013-09-16T23:05:57.849Z,1379372757.849 [AHRS_sp3003D](ERROR): Hardware Fault
2013-09-16T23:05:57.886Z,1379372757.886 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2013-09-16T23:05:57.919Z,1379372757.919 [AHRS_sp3003D](INFO): Powering down
2013-09-16T23:05:58.762Z,1379372758.762 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:05:59.147Z,1379372759.147 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2013-09-16T23:05:59.147Z,1379372759.147 [AHRS_sp3003D] No Fault, FailCount= 2
2013-09-16T23:05:59.514Z,1379372759.514 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-09-16T23:05:59.952Z,1379372759.952 [NAL9602](INFO): NAL9602 initialized
2013-09-16T23:06:00.412Z,1379372760.412 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2013-09-16T23:06:00.413Z,1379372760.413 [AHRS_sp3003D](ERROR): readHeadingMagBin got 0x00
2013-09-16T23:06:00.413Z,1379372760.413 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2013-09-16T23:06:00.413Z,1379372760.413 [AHRS_sp3003D] Hardware Fault, FailCount= 3
2013-09-16T23:06:00.413Z,1379372760.413 [AHRS_sp3003D](ERROR): Hardware Fault
2013-09-16T23:06:00.448Z,1379372760.448 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2013-09-16T23:06:00.712Z,1379372760.712 [AHRS_sp3003D](INFO): Powering down
2013-09-16T23:06:00.777Z,1379372760.777 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:06:00.777Z,1379372760.777 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:06:01.954Z,1379372761.954 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2013-09-16T23:06:01.954Z,1379372761.954 [AHRS_sp3003D] No Fault, FailCount= 3
2013-09-16T23:06:02.310Z,1379372762.310 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-09-16T23:06:02.780Z,1379372762.780 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:06:02.781Z,1379372762.781 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:06:03.454Z,1379372763.454 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2013-09-16T23:06:03.454Z,1379372763.454 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2013-09-16T23:06:03.454Z,1379372763.454 [AHRS_sp3003D] Hardware Fault, FailCount= 4
2013-09-16T23:06:03.454Z,1379372763.454 [AHRS_sp3003D](ERROR): Hardware Fault
2013-09-16T23:06:03.520Z,1379372763.520 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2013-09-16T23:06:03.534Z,1379372763.534 [AHRS_sp3003D](INFO): Powering down
2013-09-16T23:06:04.791Z,1379372764.791 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:06:04.791Z,1379372764.791 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:06:04.792Z,1379372764.792 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:06:05.047Z,1379372765.047 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2013-09-16T23:06:05.047Z,1379372765.047 [AHRS_sp3003D] No Fault, FailCount= 4
2013-09-16T23:06:05.397Z,1379372765.397 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-09-16T23:06:06.530Z,1379372766.530 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2013-09-16T23:06:06.530Z,1379372766.530 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2013-09-16T23:06:06.530Z,1379372766.530 [AHRS_sp3003D] Hardware Fault, FailCount= 5
2013-09-16T23:06:06.530Z,1379372766.530 [AHRS_sp3003D](ERROR): Hardware Fault
2013-09-16T23:06:06.590Z,1379372766.590 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2013-09-16T23:06:06.590Z,1379372766.590 [CBIT](CRITICAL): Hardware Fault in component: AHRS_sp3003D
2013-09-16T23:06:06.614Z,1379372766.614 [AHRS_sp3003D](INFO): Powering down
2013-09-16T23:06:06.802Z,1379372766.802 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:06:06.802Z,1379372766.802 [DVL_micro](INFO): Querying output modes
2013-09-16T23:06:06.803Z,1379372766.803 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:06:08.805Z,1379372768.805 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:06:08.805Z,1379372768.805 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:06:08.806Z,1379372768.806 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:06:10.817Z,1379372770.817 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:06:12.820Z,1379372772.820 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:06:12.821Z,1379372772.821 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:06:14.823Z,1379372774.823 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:06:14.823Z,1379372774.823 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:06:16.834Z,1379372776.834 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:06:16.834Z,1379372776.834 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:06:16.834Z,1379372776.834 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:06:18.841Z,1379372778.841 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:06:18.841Z,1379372778.841 [DVL_micro](INFO): Querying output modes
2013-09-16T23:06:18.841Z,1379372778.841 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:06:20.844Z,1379372780.844 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:06:20.844Z,1379372780.844 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:06:20.845Z,1379372780.845 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:06:22.855Z,1379372782.855 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:06:24.863Z,1379372784.863 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:06:24.863Z,1379372784.863 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:06:26.866Z,1379372786.866 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:06:26.866Z,1379372786.866 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:06:28.869Z,1379372788.869 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:06:28.869Z,1379372788.869 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:06:28.869Z,1379372788.869 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:06:29.209Z,1379372789.209 [CBIT](CRITICAL): Environmental Failure. Press:14.451184 PSI. Humidity:41%. Temp:21 C. ABORTING MISSION
2013-09-16T23:06:30.892Z,1379372790.892 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:06:30.892Z,1379372790.892 [DVL_micro](INFO): Querying output modes
2013-09-16T23:06:30.892Z,1379372790.892 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:06:32.895Z,1379372792.895 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:06:32.895Z,1379372792.895 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:06:32.895Z,1379372792.895 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:06:34.906Z,1379372794.906 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:06:36.910Z,1379372796.910 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:06:36.910Z,1379372796.910 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:06:38.912Z,1379372798.912 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:06:38.913Z,1379372798.913 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:06:40.919Z,1379372800.919 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:06:40.919Z,1379372800.919 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:06:40.920Z,1379372800.920 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:06:42.922Z,1379372802.922 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:06:42.923Z,1379372802.923 [DVL_micro](INFO): Querying output modes
2013-09-16T23:06:42.923Z,1379372802.923 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:06:44.929Z,1379372804.929 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:06:44.929Z,1379372804.929 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:06:44.930Z,1379372804.930 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:06:46.932Z,1379372806.932 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:06:48.940Z,1379372808.940 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:06:48.940Z,1379372808.940 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:06:50.943Z,1379372810.943 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:06:50.943Z,1379372810.943 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:06:52.954Z,1379372812.954 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:06:52.954Z,1379372812.954 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:06:52.954Z,1379372812.954 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:06:54.961Z,1379372814.961 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:06:54.961Z,1379372814.961 [DVL_micro](INFO): Querying output modes
2013-09-16T23:06:54.961Z,1379372814.961 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:06:56.972Z,1379372816.972 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:06:56.972Z,1379372816.972 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:06:56.972Z,1379372816.972 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:06:58.975Z,1379372818.975 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:07:00.987Z,1379372820.987 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:07:00.987Z,1379372820.987 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:07:02.990Z,1379372822.990 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:07:02.990Z,1379372822.990 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:07:04.993Z,1379372824.993 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:07:04.993Z,1379372824.993 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:07:04.993Z,1379372824.993 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:07:06.000Z,1379372827.000 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:07:06.000Z,1379372827.000 [DVL_micro](INFO): Querying output modes
2013-09-16T23:07:06.000Z,1379372827.000 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:07:09.007Z,1379372829.007 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:07:09.007Z,1379372829.007 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:07:09.007Z,1379372829.007 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:07:11.014Z,1379372831.014 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:07:12.054Z,1379372832.054 [CBIT](CRITICAL): Environmental Failure. Press:14.451184 PSI. Humidity:41%. Temp:22 C. ABORTING MISSION
2013-09-16T23:07:13.021Z,1379372833.021 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:07:13.022Z,1379372833.022 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:07:15.024Z,1379372835.024 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:07:15.025Z,1379372835.025 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:07:17.031Z,1379372837.031 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:07:17.031Z,1379372837.031 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:07:17.031Z,1379372837.031 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:07:19.038Z,1379372839.038 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:07:19.038Z,1379372839.038 [DVL_micro](INFO): Querying output modes
2013-09-16T23:07:19.039Z,1379372839.039 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:07:21.041Z,1379372841.041 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:07:21.041Z,1379372841.041 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:07:21.042Z,1379372841.042 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:07:23.048Z,1379372843.048 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:07:25.060Z,1379372845.060 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:07:25.060Z,1379372845.060 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:07:27.063Z,1379372847.063 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:07:27.063Z,1379372847.063 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:07:29.074Z,1379372849.074 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:07:29.074Z,1379372849.074 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:07:29.074Z,1379372849.074 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:07:31.081Z,1379372851.081 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:07:31.081Z,1379372851.081 [DVL_micro](INFO): Querying output modes
2013-09-16T23:07:31.081Z,1379372851.081 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:07:33.092Z,1379372853.092 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:07:33.092Z,1379372853.092 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:07:33.093Z,1379372853.093 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:07:35.095Z,1379372855.095 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:07:37.099Z,1379372857.099 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:07:37.099Z,1379372857.099 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:07:39.102Z,1379372859.102 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:07:39.102Z,1379372859.102 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:07:41.108Z,1379372861.108 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:07:41.109Z,1379372861.109 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:07:41.109Z,1379372861.109 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:07:43.112Z,1379372863.112 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:07:43.112Z,1379372863.112 [DVL_micro](INFO): Querying output modes
2013-09-16T23:07:43.112Z,1379372863.112 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:07:45.119Z,1379372865.119 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:07:45.119Z,1379372865.119 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:07:45.119Z,1379372865.119 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:07:47.126Z,1379372867.126 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:07:49.134Z,1379372869.134 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:07:49.134Z,1379372869.134 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:07:51.137Z,1379372871.137 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:07:51.137Z,1379372871.137 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:07:53.147Z,1379372873.147 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:07:53.147Z,1379372873.147 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:07:53.148Z,1379372873.148 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:07:55.158Z,1379372875.158 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:07:55.158Z,1379372875.158 [DVL_micro](INFO): Querying output modes
2013-09-16T23:07:55.159Z,1379372875.159 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:07:57.161Z,1379372877.161 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:07:57.162Z,1379372877.162 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:07:57.162Z,1379372877.162 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:07:59.185Z,1379372879.185 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:08:01.196Z,1379372881.196 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:08:01.196Z,1379372881.196 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:08:03.199Z,1379372883.199 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:08:03.199Z,1379372883.199 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:08:05.210Z,1379372885.210 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:08:05.210Z,1379372885.210 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:08:05.210Z,1379372885.210 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:08:07.217Z,1379372887.217 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:08:07.217Z,1379372887.217 [DVL_micro](INFO): Querying output modes
2013-09-16T23:08:07.217Z,1379372887.217 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:08:09.224Z,1379372889.224 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:08:09.224Z,1379372889.224 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:08:09.225Z,1379372889.225 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:08:11.238Z,1379372891.238 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:08:13.251Z,1379372893.251 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:08:13.251Z,1379372893.251 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:08:15.254Z,1379372895.254 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:08:15.254Z,1379372895.254 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:08:17.265Z,1379372897.265 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:08:17.265Z,1379372897.265 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:08:17.265Z,1379372897.265 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:08:19.288Z,1379372899.288 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:08:19.288Z,1379372899.288 [DVL_micro](INFO): Querying output modes
2013-09-16T23:08:19.288Z,1379372899.288 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:08:21.291Z,1379372901.291 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:08:21.291Z,1379372901.291 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:08:21.291Z,1379372901.291 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:08:23.298Z,1379372903.298 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:08:25.306Z,1379372905.306 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:08:25.306Z,1379372905.306 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:08:27.308Z,1379372907.308 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:08:27.309Z,1379372907.309 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:08:29.311Z,1379372909.311 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:08:29.311Z,1379372909.311 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:08:29.311Z,1379372909.311 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:08:31.314Z,1379372911.314 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:08:31.314Z,1379372911.314 [DVL_micro](INFO): Querying output modes
2013-09-16T23:08:31.315Z,1379372911.315 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:08:33.325Z,1379372913.325 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:08:33.326Z,1379372913.326 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:08:33.326Z,1379372913.326 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:08:35.329Z,1379372915.329 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:08:37.340Z,1379372917.340 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:08:37.341Z,1379372917.341 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:08:39.343Z,1379372919.343 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:08:39.344Z,1379372919.344 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:08:41.354Z,1379372921.354 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:08:41.354Z,1379372921.354 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:08:41.354Z,1379372921.354 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:08:43.365Z,1379372923.365 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:08:43.365Z,1379372923.365 [DVL_micro](INFO): Querying output modes
2013-09-16T23:08:43.365Z,1379372923.365 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:08:45.372Z,1379372925.372 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:08:45.372Z,1379372925.372 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:08:45.372Z,1379372925.372 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:08:47.375Z,1379372927.375 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:08:49.387Z,1379372929.387 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:08:49.387Z,1379372929.387 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:08:51.390Z,1379372931.390 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:08:51.390Z,1379372931.390 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:08:53.397Z,1379372933.397 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:08:53.397Z,1379372933.397 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:08:53.397Z,1379372933.397 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:08:55.400Z,1379372935.400 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:08:55.400Z,1379372935.400 [DVL_micro](INFO): Querying output modes
2013-09-16T23:08:55.400Z,1379372935.400 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:08:57.411Z,1379372937.411 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:08:57.411Z,1379372937.411 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:08:57.411Z,1379372937.411 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:08:59.418Z,1379372939.418 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:09:01.426Z,1379372941.426 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:09:01.426Z,1379372941.426 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:09:03.429Z,1379372943.429 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:09:03.429Z,1379372943.429 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:09:05.432Z,1379372945.432 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:09:05.432Z,1379372945.432 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:09:05.432Z,1379372945.432 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:09:07.438Z,1379372947.438 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:09:07.438Z,1379372947.438 [DVL_micro](INFO): Querying output modes
2013-09-16T23:09:07.439Z,1379372947.439 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:09:09.449Z,1379372949.449 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:09:09.450Z,1379372949.450 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:09:09.450Z,1379372949.450 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:09:11.457Z,1379372951.457 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:09:13.460Z,1379372953.460 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:09:13.460Z,1379372953.460 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:09:15.463Z,1379372955.463 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:09:15.464Z,1379372955.464 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:09:17.466Z,1379372957.466 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:09:17.466Z,1379372957.466 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:09:17.466Z,1379372957.466 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:09:19.470Z,1379372959.470 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:09:19.470Z,1379372959.470 [DVL_micro](INFO): Querying output modes
2013-09-16T23:09:19.471Z,1379372959.471 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:09:21.488Z,1379372961.488 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:09:21.488Z,1379372961.488 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:09:21.488Z,1379372961.488 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:09:23.499Z,1379372963.499 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:09:25.515Z,1379372965.515 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:09:25.515Z,1379372965.515 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:09:27.518Z,1379372967.518 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:09:27.518Z,1379372967.518 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:09:29.521Z,1379372969.521 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:09:29.521Z,1379372969.521 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:09:29.521Z,1379372969.521 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:09:31.528Z,1379372971.528 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:09:31.528Z,1379372971.528 [DVL_micro](INFO): Querying output modes
2013-09-16T23:09:31.528Z,1379372971.528 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:09:33.535Z,1379372973.535 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:09:33.535Z,1379372973.535 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:09:33.535Z,1379372973.535 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:09:35.538Z,1379372975.538 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:09:37.541Z,1379372977.541 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:09:37.542Z,1379372977.542 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:09:39.544Z,1379372979.544 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:09:39.545Z,1379372979.545 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:09:41.555Z,1379372981.555 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:09:41.555Z,1379372981.555 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:09:41.555Z,1379372981.555 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:09:43.558Z,1379372983.558 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:09:43.558Z,1379372983.558 [DVL_micro](INFO): Querying output modes
2013-09-16T23:09:43.559Z,1379372983.559 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:09:45.561Z,1379372985.561 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:09:45.562Z,1379372985.562 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:09:45.562Z,1379372985.562 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:09:47.565Z,1379372987.565 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:09:49.573Z,1379372989.573 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:09:49.573Z,1379372989.573 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:09:51.579Z,1379372991.579 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:09:51.580Z,1379372991.580 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:09:53.586Z,1379372993.586 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:09:53.586Z,1379372993.586 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:09:53.586Z,1379372993.586 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:09:55.597Z,1379372995.597 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:09:55.597Z,1379372995.597 [DVL_micro](INFO): Querying output modes
2013-09-16T23:09:55.597Z,1379372995.597 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:09:57.600Z,1379372997.600 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:09:57.600Z,1379372997.600 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:09:57.600Z,1379372997.600 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:09:59.607Z,1379372999.607 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:10:01.619Z,1379373001.619 [DVL_micro](INFO): NQ1 requested
2013-09-16T23:10:01.619Z,1379373001.619 [DVL_micro](INFO): Cycling power to configure device.
2013-09-16T23:10:03.622Z,1379373003.622 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-16T23:10:03.622Z,1379373003.622 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-16T23:10:03.894Z,1379373003.894 [CommandLine](IMPORTANT): got command quit
2013-09-16T23:10:04.911Z,1379373004.911 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2013-09-16T23:10:04.912Z,1379373004.912 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2013-09-16T23:10:05.132Z,1379373005.132 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2013-09-16T23:10:05.380Z,1379373005.380 [WetLabsBB2FL](INFO): Powering down
2013-09-16T23:10:05.400Z,1379373005.400 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2013-09-16T23:10:05.577Z,1379373005.577 [CTD_NeilBrown](INFO): Powering down
2013-09-16T23:10:05.585Z,1379373005.585 [ComponentRegistry](INFO): Shutting down DVL_micro ThreadHandler
2013-09-16T23:10:05.629Z,1379373005.629 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:10:05.629Z,1379373005.629 [DVL_micro](INFO): Enabling NQ1 output
2013-09-16T23:10:05.629Z,1379373005.629 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-16T23:10:07.632Z,1379373007.632 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:10:07.632Z,1379373007.632 [DVL_micro](INFO): Querying output modes
2013-09-16T23:10:07.632Z,1379373007.632 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-16T23:10:09.639Z,1379373009.639 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:10:09.639Z,1379373009.639 [DVL_micro](INFO): Output Modes: No Response
2013-09-16T23:10:09.639Z,1379373009.639 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-16T23:10:11.650Z,1379373011.650 [DVL_micro](DEBUG): cmdResponse: 
2013-09-16T23:10:11.650Z,1379373011.650 [DVL_micro](INFO): uninitialize:Powering down
2013-09-16T23:10:12.055Z,1379373012.055 [DVL_micro](INFO): uninitialize:Powering down
2013-09-16T23:10:12.062Z,1379373012.062 [ComponentRegistry](INFO): Shutting down AsyncPiEstimator ThreadHandler
2013-09-16T23:10:12.235Z,1379373012.235 [AsyncPiEstimator](DEBUG): Uninitialize AsyncPiEstimator.
2013-09-16T23:10:12.244Z,1379373012.244 [ComponentRegistry](INFO): Shutting down logger ThreadHandler
2013-09-16T23:10:12.284Z,1379373012.284 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2013-09-16T23:10:12.285Z,1379373012.285 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2013-09-16T23:10:12.475Z,1379373012.475 [controlThread](DEBUG): Uninitializing ControlThread
2013-09-16T23:10:12.476Z,1379373012.476 [AHRS_sp3003D](INFO): Powering down
2013-09-16T23:10:12.477Z,1379373012.477 [NAL9602](INFO): Powering down
2013-09-16T23:10:12.478Z,1379373012.478 [DAT](INFO): Powering down
2013-09-16T23:10:12.480Z,1379373012.480 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2013-09-16T23:10:12.480Z,1379373012.480 [NavChart](DEBUG): Uninitialize NavChart Derivation.
2013-09-16T23:10:12.481Z,1379373012.481 [Default] Stopped
2013-09-16T23:10:12.481Z,1379373012.481 [Default](INFO): Aggregate::uninitialize Default
2013-09-16T23:10:12.481Z,1379373012.481 [Default:GPS] Stopped
2013-09-16T23:10:12.481Z,1379373012.481 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2013-09-16T23:10:12.481Z,1379373012.481 [Default:GPS:A.SetSpeed] Stopped
2013-09-16T23:10:12.481Z,1379373012.481 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2013-09-16T23:10:12.481Z,1379373012.481 [Default:GPS:Read_GPS] Stopped
2013-09-16T23:10:12.481Z,1379373012.481 [Default:Iridium] Stopped
2013-09-16T23:10:12.481Z,1379373012.481 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2013-09-16T23:10:12.481Z,1379373012.481 [Default:Iridium:A.SetSpeed] Stopped
2013-09-16T23:10:12.482Z,1379373012.482 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2013-09-16T23:10:12.482Z,1379373012.482 [Default:Iridium:Read_Iridium] Stopped
2013-09-16T23:10:12.482Z,1379373012.482 [Default:CallIridium] Stopped
2013-09-16T23:10:12.482Z,1379373012.482 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2013-09-16T23:10:12.482Z,1379373012.482 [Default:CallIridium:B] Stopped
2013-09-16T23:10:12.482Z,1379373012.482 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2013-09-16T23:10:12.482Z,1379373012.482 [Default:D.SetSpeed] Stopped
2013-09-16T23:10:12.482Z,1379373012.482 [Default:D.SetSpeed](DEBUG): Uninitialize.
2013-09-16T23:10:12.482Z,1379373012.482 [Default:E.GoToSurface] Stopped
2013-09-16T23:10:12.482Z,1379373012.482 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2013-09-16T23:10:12.486Z,1379373012.486 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2013-09-16T23:10:12.487Z,1379373012.487 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2013-09-16T23:10:12.487Z,1379373012.487 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2013-09-16T23:10:12.487Z,1379373012.487 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2013-09-16T23:10:12.488Z,1379373012.488 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2013-09-16T23:10:12.488Z,1379373012.488 [BuoyancyServo](INFO): Powering down
2013-09-16T23:10:12.499Z,1379373012.499 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2013-09-16T23:10:12.499Z,1379373012.499 [ElevatorServo](INFO): Powering down
2013-09-16T23:10:12.500Z,1379373012.500 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-09-16T23:10:12.500Z,1379373012.500 [MassServo](INFO): Powering down
2013-09-16T23:10:12.501Z,1379373012.501 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2013-09-16T23:10:12.501Z,1379373012.501 [RudderServo](INFO): Powering down
2013-09-16T23:10:12.502Z,1379373012.502 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2013-09-16T23:10:12.502Z,1379373012.502 [ThrusterServo](INFO): Powering down
2013-09-16T23:10:12.503Z,1379373012.503 [SBIT](DEBUG): Uninitialize SBIT Component.
2013-09-16T23:10:12.504Z,1379373012.504 [IBIT](DEBUG): Uninitialize IBIT Component.
2013-09-16T23:10:12.504Z,1379373012.504 [CBIT](DEBUG): Uninitialize CBIT Component.