2012-09-24T23:49:41.495Z,1348530581.494 [Supervisor](DEBUG): Initializing supervisor.
2012-09-24T23:49:41.502Z,1348530581.503 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0
2012-09-24T23:49:41.503Z,1348530581.503 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2012-09-24T23:49:41.507Z,1348530581.507 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4036B4E0
2012-09-24T23:49:41.510Z,1348530581.510 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2012-09-24T23:49:41.521Z,1348530581.521 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2012-09-24T23:49:41.522Z,1348530581.523 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4039B4E0
2012-09-24T23:49:41.523Z,1348530581.523 [ComponentRegistry](DEBUG): SyncComponent "logger" handled in the control thread.
2012-09-24T23:49:41.524Z,1348530581.524 [Supervisor](INFO): Looking for Config files in directory: Config/
2012-09-24T23:49:41.527Z,1348530581.527 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2012-09-24T23:49:41.842Z,1348530581.842 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2012-09-24T23:49:41.844Z,1348530581.844 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2012-09-24T23:49:42.043Z,1348530582.043 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2012-09-24T23:49:42.044Z,1348530582.044 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2012-09-24T23:49:42.138Z,1348530582.138 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2012-09-24T23:49:42.140Z,1348530582.140 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2012-09-24T23:49:42.359Z,1348530582.359 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2012-09-24T23:49:42.360Z,1348530582.360 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2012-09-24T23:49:42.507Z,1348530582.507 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2012-09-24T23:49:42.509Z,1348530582.509 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2012-09-24T23:49:42.766Z,1348530582.766 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2012-09-24T23:49:42.767Z,1348530582.767 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2012-09-24T23:49:42.955Z,1348530582.955 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2012-09-24T23:49:42.957Z,1348530582.957 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2012-09-24T23:49:43.244Z,1348530583.244 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2012-09-24T23:49:43.246Z,1348530583.246 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2012-09-24T23:49:43.359Z,1348530583.359 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2012-09-24T23:49:43.360Z,1348530583.360 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2012-09-24T23:49:43.824Z,1348530583.824 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2012-09-24T23:49:43.824Z,1348530583.824 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2012-09-24T23:49:43.955Z,1348530583.955 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2012-09-24T23:49:43.955Z,1348530583.955 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2012-09-24T23:49:44.052Z,1348530584.052 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2012-09-24T23:49:44.094Z,1348530584.094 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2012-09-24T23:49:44.206Z,1348530584.206 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2012-09-24T23:49:44.350Z,1348530584.351 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2012-09-24T23:49:44.446Z,1348530584.446 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2012-09-24T23:49:44.558Z,1348530584.558 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2012-09-24T23:49:44.672Z,1348530584.672 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2012-09-24T23:49:44.808Z,1348530584.808 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2012-09-24T23:49:44.910Z,1348530584.910 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2012-09-24T23:49:45.060Z,1348530585.060 [Supervisor](FAULT): Ignoring configuration overrides from Data/config.db
2012-09-24T23:49:45.097Z,1348530585.097 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2012-09-24T23:49:45.276Z,1348530585.276 [InternalSim] Loaded
2012-09-24T23:49:45.276Z,1348530585.276 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2012-09-24T23:49:45.277Z,1348530585.277 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2012-09-24T23:49:45.278Z,1348530585.278 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2012-09-24T23:49:45.359Z,1348530585.359 [SBIT](DEBUG): Construct Startup Built In Test.
2012-09-24T23:49:45.376Z,1348530585.376 [SBIT] Loaded
2012-09-24T23:49:45.376Z,1348530585.376 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2012-09-24T23:49:45.377Z,1348530585.377 [IBIT](DEBUG): Construct Initiated Built In Test.
2012-09-24T23:49:45.403Z,1348530585.404 [IBIT] Loaded
2012-09-24T23:49:45.404Z,1348530585.404 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2012-09-24T23:49:45.411Z,1348530585.411 [CBIT](DEBUG): Construct CBIT Built In Test.
2012-09-24T23:49:45.531Z,1348530585.531 [CBIT] Loaded
2012-09-24T23:49:45.531Z,1348530585.531 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2012-09-24T23:49:45.531Z,1348530585.532 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2012-09-24T23:49:45.532Z,1348530585.532 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2012-09-24T23:49:45.697Z,1348530585.697 [BuoyancyServo] Loaded
2012-09-24T23:49:45.697Z,1348530585.697 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2012-09-24T23:49:45.706Z,1348530585.706 [ElevatorServo] Loaded
2012-09-24T23:49:45.706Z,1348530585.706 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2012-09-24T23:49:45.717Z,1348530585.717 [MassServo] Loaded
2012-09-24T23:49:45.718Z,1348530585.718 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2012-09-24T23:49:45.733Z,1348530585.733 [RudderServo] Loaded
2012-09-24T23:49:45.733Z,1348530585.733 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2012-09-24T23:49:45.744Z,1348530585.744 [ThrusterServo] Loaded
2012-09-24T23:49:45.745Z,1348530585.745 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2012-09-24T23:49:45.745Z,1348530585.745 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2012-09-24T23:49:45.746Z,1348530585.746 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2012-09-24T23:49:45.782Z,1348530585.782 [DepthRateCalculator] Loaded
2012-09-24T23:49:45.783Z,1348530585.783 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2012-09-24T23:49:48.535Z,1348530588.535 [HFRadarModelCalc] Loaded
2012-09-24T23:49:48.535Z,1348530588.535 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread.
2012-09-24T23:49:48.550Z,1348530588.550 [NavChart] Loaded
2012-09-24T23:49:48.551Z,1348530588.551 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2012-09-24T23:49:48.557Z,1348530588.556 [PitchRateCalculator] Loaded
2012-09-24T23:49:48.557Z,1348530588.557 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2012-09-24T23:49:48.563Z,1348530588.563 [SpeedCalculator] Loaded
2012-09-24T23:49:48.563Z,1348530588.563 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2012-09-24T23:49:48.582Z,1348530588.582 [TempGradientCalculator] Loaded
2012-09-24T23:49:48.582Z,1348530588.582 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2012-09-24T23:49:48.588Z,1348530588.588 [YawRateCalculator] Loaded
2012-09-24T23:49:48.588Z,1348530588.588 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2012-09-24T23:49:48.607Z,1348530588.607 [Navigation] Loaded
2012-09-24T23:49:48.608Z,1348530588.608 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread.
2012-09-24T23:49:48.608Z,1348530588.608 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2012-09-24T23:49:48.609Z,1348530588.609 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2012-09-24T23:49:48.885Z,1348530588.885 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2012-09-24T23:49:48.886Z,1348530588.886 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2012-09-24T23:49:48.931Z,1348530588.931 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2012-09-24T23:49:48.931Z,1348530588.931 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2012-09-24T23:49:48.993Z,1348530588.993 [VerticalControl](DEBUG): Construct VerticalControl.
2012-09-24T23:49:49.040Z,1348530589.040 [VerticalControl] Loaded
2012-09-24T23:49:49.040Z,1348530589.040 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2012-09-24T23:49:49.041Z,1348530589.041 [HorizontalControl](DEBUG): Construct HorizontalControl.
2012-09-24T23:49:49.065Z,1348530589.065 [HorizontalControl] Loaded
2012-09-24T23:49:49.066Z,1348530589.066 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2012-09-24T23:49:49.067Z,1348530589.067 [SpeedControl](DEBUG): Construct SpeedControl.
2012-09-24T23:49:49.068Z,1348530589.068 [SpeedControl] Loaded
2012-09-24T23:49:49.069Z,1348530589.069 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2012-09-24T23:49:49.069Z,1348530589.069 [LoopControl](DEBUG): Construct LoopControl.
2012-09-24T23:49:49.070Z,1348530589.070 [LoopControl] Loaded
2012-09-24T23:49:49.070Z,1348530589.070 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2012-09-24T23:49:49.071Z,1348530589.071 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2012-09-24T23:49:49.072Z,1348530589.072 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2012-09-24T23:49:49.084Z,1348530589.084 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator.
2012-09-24T23:49:49.088Z,1348530589.088 [AsyncPiEstimator] Loaded
2012-09-24T23:49:49.089Z,1348530589.089 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread.
2012-09-24T23:49:49.090Z,1348530589.090 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 4060A4E0
2012-09-24T23:49:49.091Z,1348530589.091 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2012-09-24T23:49:49.095Z,1348530589.095 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2012-09-24T23:49:49.317Z,1348530589.317 [AHRS_sp3003D] Loaded
2012-09-24T23:49:49.317Z,1348530589.317 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2012-09-24T23:49:49.331Z,1348530589.330 [Depth_Keller] Loaded
2012-09-24T23:49:49.331Z,1348530589.331 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2012-09-24T23:49:49.340Z,1348530589.340 [DropWeight] Loaded
2012-09-24T23:49:49.341Z,1348530589.341 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2012-09-24T23:49:49.465Z,1348530589.464 [DVL_micro] Loaded
2012-09-24T23:49:49.465Z,1348530589.465 [ComponentRegistry](DEBUG): Component "DVL_micro" handled in its own thread.
2012-09-24T23:49:49.467Z,1348530589.467 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 4068A4E0
2012-09-24T23:49:49.557Z,1348530589.557 [NAL9602] Loaded
2012-09-24T23:49:49.558Z,1348530589.558 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2012-09-24T23:49:49.611Z,1348530589.611 [Onboard] Loaded
2012-09-24T23:49:49.611Z,1348530589.611 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2012-09-24T23:49:49.618Z,1348530589.618 [Radio_Freewave] Loaded
2012-09-24T23:49:49.618Z,1348530589.618 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread.
2012-09-24T23:49:49.619Z,1348530589.619 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2012-09-24T23:49:49.619Z,1348530589.619 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2012-09-24T23:49:49.720Z,1348530589.720 [CTD_NeilBrown] Loaded
2012-09-24T23:49:49.720Z,1348530589.720 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2012-09-24T23:49:49.723Z,1348530589.723 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 406E24E0
2012-09-24T23:49:49.733Z,1348530589.733 [PAR_Licor] Loaded
2012-09-24T23:49:49.733Z,1348530589.733 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2012-09-24T23:49:49.753Z,1348530589.753 [Turbulence_NPS] Loaded
2012-09-24T23:49:49.753Z,1348530589.753 [ComponentRegistry](DEBUG): Component "Turbulence_NPS" handled in its own thread.
2012-09-24T23:49:49.758Z,1348530589.759 [Turbulence_NPS ThreadHandler](DEBUG): Created PCaller Thread at 407124E0
2012-09-24T23:49:49.793Z,1348530589.793 [WetLabsBB2FL] Loaded
2012-09-24T23:49:49.793Z,1348530589.793 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2012-09-24T23:49:49.794Z,1348530589.795 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407424E0
2012-09-24T23:49:49.795Z,1348530589.795 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2012-09-24T23:49:49.798Z,1348530589.797 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2012-09-24T23:49:49.798Z,1348530589.798 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2012-09-24T23:49:49.804Z,1348530589.804 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2012-09-24T23:49:49.807Z,1348530589.807 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 407724E0
2012-09-24T23:49:49.811Z,1348530589.811 [Supervisor](DEBUG): Running supervisor.
2012-09-24T23:49:49.812Z,1348530589.812 [CommandLine](INFO): Thread ID is 749
2012-09-24T23:49:49.815Z,1348530589.815 [controlThread](INFO): Thread ID is 748
2012-09-24T23:49:49.815Z,1348530589.815 [controlThread](DEBUG): Initializing ControlThread
2012-09-24T23:49:49.816Z,1348530589.816 [CycleStarter](INFO): Thread ID is 747
2012-09-24T23:49:49.817Z,1348530589.816 [InternalSim](DEBUG):  InternalSim initializing...
2012-09-24T23:49:49.921Z,1348530589.921 [AsyncPiEstimator](INFO): Thread ID is 810
2012-09-24T23:49:49.921Z,1348530589.921 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator.
2012-09-24T23:49:49.984Z,1348530589.984 [DVL_micro](INFO): Thread ID is 811
2012-09-24T23:49:50.068Z,1348530590.068 [CTD_NeilBrown](INFO): Thread ID is 812
2012-09-24T23:49:50.069Z,1348530590.069 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2012-09-24T23:49:50.071Z,1348530590.071 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2012-09-24T23:49:50.094Z,1348530590.094 [DVL_micro](INFO): Initializing
2012-09-24T23:49:50.100Z,1348530590.100 [DVL_micro](INFO): start:Powering up
2012-09-24T23:49:50.101Z,1348530590.101 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:49:50.101Z,1348530590.101 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:49:50.127Z,1348530590.127 [Turbulence_NPS](INFO): Thread ID is 813
2012-09-24T23:49:50.128Z,1348530590.128 [Turbulence_NPS](DEBUG): Initializing Turbulence_NPS.
2012-09-24T23:49:50.135Z,1348530590.135 [WetLabsBB2FL](INFO): Thread ID is 814
2012-09-24T23:49:50.136Z,1348530590.136 [WetLabsBB2FL](INFO): Powering down
2012-09-24T23:49:50.160Z,1348530590.159 [NavChartDb](INFO): Thread ID is 815
2012-09-24T23:49:50.167Z,1348530590.167 [SBIT](INFO): Initialize SBIT Component.
2012-09-24T23:49:50.167Z,1348530590.167 [SBIT](IMPORTANT): Tethys CM Info:
$Rev: 9883
2012-09-24T23:49:50.168Z,1348530590.168 [IBIT](INFO): Initialize IBIT Component.
2012-09-24T23:49:50.169Z,1348530590.169 [CBIT](DEBUG): Initialize CBIT Component.
2012-09-24T23:49:50.169Z,1348530590.169 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2012-09-24T23:49:50.169Z,1348530590.169 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2012-09-24T23:49:50.244Z,1348530590.244 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2012-09-24T23:49:50.244Z,1348530590.244 [NavChart](DEBUG): Initialize NavChart Derivation.
2012-09-24T23:49:50.245Z,1348530590.245 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2012-09-24T23:49:50.245Z,1348530590.245 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2012-09-24T23:49:50.246Z,1348530590.246 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2012-09-24T23:49:50.247Z,1348530590.247 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2012-09-24T23:49:50.248Z,1348530590.248 [Navigation](DEBUG): Initializing Navigation.
2012-09-24T23:49:50.248Z,1348530590.248 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2012-09-24T23:49:50.250Z,1348530590.250 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2012-09-24T23:49:50.251Z,1348530590.251 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2012-09-24T23:49:50.251Z,1348530590.251 [LoopControl](DEBUG): Initialize LoopControlComponent.
2012-09-24T23:49:50.273Z,1348530590.273 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2012-09-24T23:49:50.298Z,1348530590.298 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2012-09-24T23:49:50.317Z,1348530590.316 [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>


2012-09-24T23:49:50.317Z,1348530590.317 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2012-09-24T23:49:50.387Z,1348530590.387 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool
2012-09-24T23:49:50.389Z,1348530590.389 [Default:GPS:A.SetSpeed](DEBUG): Construct.
2012-09-24T23:49:50.396Z,1348530590.396 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface.
2012-09-24T23:49:50.404Z,1348530590.404 [Default:Iridium:A.SetSpeed](DEBUG): Construct.
2012-09-24T23:49:50.407Z,1348530590.407 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface.
2012-09-24T23:49:50.414Z,1348530590.414 [Default:Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2012-09-24T23:49:50.422Z,1348530590.422 [Default:D.SetSpeed](DEBUG): Construct.
2012-09-24T23:49:50.425Z,1348530590.425 [Default:E.GoToSurface](DEBUG): Construct GoToSurface.
2012-09-24T23:49:50.430Z,1348530590.430 [Default:F.Wait](DEBUG): Construct Wait.
2012-09-24T23:49:50.434Z,1348530590.434 [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>


2012-09-24T23:49:50.438Z,1348530590.438 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Depth_Keller,DropWeight,NAL9602,Onboard,Radio_Freewave,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,logger,
2012-09-24T23:49:50.462Z,1348530590.462 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-24T23:49:50.615Z,1348530590.615 [Depth_Keller](INFO): A/D timeout, 2 tries over 139 ms

2012-09-24T23:49:50.615Z,1348530590.615 [Depth_Keller] Data Fault, FailCount= 1
2012-09-24T23:49:50.616Z,1348530590.616 [Depth_Keller](ERROR): Data Fault
2012-09-24T23:49:50.642Z,1348530590.642 [Radio_Freewave](INFO): Powering up
2012-09-24T23:49:50.799Z,1348530590.799 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2012-09-24T23:49:50.803Z,1348530590.803 [PAR_Licor](INFO): A/D timeout, 2 tries over 143 ms

2012-09-24T23:49:50.803Z,1348530590.803 [PAR_Licor] Data Fault, FailCount= 1
2012-09-24T23:49:50.803Z,1348530590.803 [PAR_Licor](ERROR): Data Fault
2012-09-24T23:49:50.883Z,1348530590.883 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2012-09-24T23:49:50.887Z,1348530590.887 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2012-09-24T23:49:50.893Z,1348530590.894 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2012-09-24T23:49:50.899Z,1348530590.899 [MassServo](DEBUG): Initializing MassServo.
2012-09-24T23:49:50.905Z,1348530590.905 [RudderServo](DEBUG): Initializing EZServoServo.
2012-09-24T23:49:50.907Z,1348530590.907 [RudderServo](DEBUG): Initializing RudderServo.
2012-09-24T23:49:50.913Z,1348530590.913 [ThrusterServo](DEBUG): Initializing EZServoServo.
2012-09-24T23:49:50.914Z,1348530590.914 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2012-09-24T23:49:50.938Z,1348530590.938 [CBIT](ERROR): Data Fault in component: Depth_Keller
2012-09-24T23:49:50.938Z,1348530590.938 [CBIT](INFO): Clearing failed state for component Depth_Keller
2012-09-24T23:49:50.938Z,1348530590.938 [Depth_Keller] No Fault, FailCount= 1
2012-09-24T23:49:50.938Z,1348530590.938 [CBIT](ERROR): Data Fault in component: PAR_Licor
2012-09-24T23:49:50.938Z,1348530590.938 [CBIT](INFO): Clearing failed state for component PAR_Licor
2012-09-24T23:49:50.938Z,1348530590.938 [PAR_Licor] No Fault, FailCount= 1
2012-09-24T23:49:52.159Z,1348530592.159 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:49:52.159Z,1348530592.159 [DVL_micro](INFO): Querying output modes
2012-09-24T23:49:52.160Z,1348530592.160 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:49:53.143Z,1348530593.143 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2012-09-24T23:49:53.143Z,1348530593.143 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2012-09-24T23:49:53.143Z,1348530593.143 [AHRS_sp3003D] Hardware Fault, FailCount= 1
2012-09-24T23:49:53.143Z,1348530593.143 [AHRS_sp3003D](ERROR): Hardware Fault
2012-09-24T23:49:53.477Z,1348530593.477 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2012-09-24T23:49:53.518Z,1348530593.518 [AHRS_sp3003D](INFO): Powering down
2012-09-24T23:49:53.663Z,1348530593.663 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2012-09-24T23:49:53.663Z,1348530593.663 [AHRS_sp3003D] No Fault, FailCount= 1
2012-09-24T23:49:54.227Z,1348530594.227 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:49:54.227Z,1348530594.227 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:49:54.227Z,1348530594.227 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:49:54.678Z,1348530594.678 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2012-09-24T23:49:54.749Z,1348530594.749 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-24T23:49:54.757Z,1348530594.757 [NAL9602](INFO): Powering up NAL9602
2012-09-24T23:49:54.789Z,1348530594.789 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2012-09-24T23:49:54.971Z,1348530594.971 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2012-09-24T23:49:55.007Z,1348530595.007 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2012-09-24T23:49:55.044Z,1348530595.044 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2012-09-24T23:49:55.044Z,1348530595.044 [NavChartDb](INFO): Creating index for Soundings
2012-09-24T23:49:55.088Z,1348530595.088 [NavChartDb](INFO): Creating index for Contour 0p0
2012-09-24T23:49:55.090Z,1348530595.090 [NavChartDb](INFO): Creating index for Contour 1p8
2012-09-24T23:49:55.152Z,1348530595.152 [NavChartDb](INFO): Creating index for Contour 3p6
2012-09-24T23:49:55.154Z,1348530595.154 [NavChartDb](INFO): Creating index for Contour 5p4
2012-09-24T23:49:55.289Z,1348530595.289 [NavChartDb](INFO): Creating index for Contour 9p1
2012-09-24T23:49:55.350Z,1348530595.350 [NavChartDb](INFO): Creating index for Contour 10p9
2012-09-24T23:49:55.464Z,1348530595.464 [NavChartDb](INFO): Creating index for Contour 18p2
2012-09-24T23:49:55.466Z,1348530595.466 [NavChartDb](INFO): Creating index for Contour 36p5
2012-09-24T23:49:55.492Z,1348530595.492 [NavChartDb](INFO): Creating index for Contour 54p8
2012-09-24T23:49:55.494Z,1348530595.494 [NavChartDb](INFO): Creating index for Contour 73p1
2012-09-24T23:49:55.552Z,1348530595.552 [NavChartDb](INFO): Creating index for Contour 91p4
2012-09-24T23:49:55.554Z,1348530595.554 [NavChartDb](INFO): Creating index for Contour 182p8
2012-09-24T23:49:55.611Z,1348530595.611 [NavChartDb](INFO): Creating index for Contour 365p7
2012-09-24T23:49:55.613Z,1348530595.613 [NavChartDb](INFO): Creating index for Contour 548p6
2012-09-24T23:49:55.636Z,1348530595.636 [NavChartDb](INFO): Creating index for Contour 731p5
2012-09-24T23:49:55.637Z,1348530595.638 [NavChartDb](INFO): Creating index for Contour 914p4
2012-09-24T23:49:55.668Z,1348530595.668 [NavChartDb](INFO): Creating index for Contour 1097p2
2012-09-24T23:49:55.670Z,1348530595.670 [NavChartDb](INFO): Creating index for Contour 1280p1
2012-09-24T23:49:55.752Z,1348530595.752 [NavChartDb](INFO): Creating index for Contour 1463p0
2012-09-24T23:49:55.754Z,1348530595.754 [NavChartDb](INFO): Creating index for Contour 1645p9
2012-09-24T23:49:55.784Z,1348530595.784 [NavChartDb](INFO): Creating index for Contour 1828p8
2012-09-24T23:49:55.786Z,1348530595.786 [NavChartDb](INFO): Creating index for Contour 2011p6
2012-09-24T23:49:55.816Z,1348530595.816 [NavChartDb](INFO): Creating index for Contour 2194p5
2012-09-24T23:49:55.818Z,1348530595.818 [NavChartDb](INFO): Creating index for Contour 2377p4
2012-09-24T23:49:55.902Z,1348530595.902 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2012-09-24T23:49:55.903Z,1348530595.903 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2012-09-24T23:49:55.903Z,1348530595.903 [AHRS_sp3003D] Hardware Fault, FailCount= 2
2012-09-24T23:49:55.903Z,1348530595.903 [AHRS_sp3003D](ERROR): Hardware Fault
2012-09-24T23:49:55.927Z,1348530595.927 [NavChartDb](INFO): Creating index for Contour 2560p3
2012-09-24T23:49:55.929Z,1348530595.929 [NavChartDb](INFO): Creating index for Contour 2743p2
2012-09-24T23:49:55.931Z,1348530595.931 [NavChartDb](INFO): Creating index for Contour 2926p0
2012-09-24T23:49:55.933Z,1348530595.933 [NavChartDb](INFO): Creating index for Contour 3108p9
2012-09-24T23:49:56.091Z,1348530596.091 [NavChartDb](INFO): Creating index for Contour 3291p8
2012-09-24T23:49:56.093Z,1348530596.093 [NavChartDb](INFO): Creating index for Contour 3474p7
2012-09-24T23:49:56.111Z,1348530596.111 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2012-09-24T23:49:56.132Z,1348530596.132 [NavChartDb](INFO): Creating index for Contour 3657p6
2012-09-24T23:49:56.230Z,1348530596.230 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:49:56.235Z,1348530596.235 [DVL_micro](INFO): pause:Powering down
2012-09-24T23:49:56.237Z,1348530596.237 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:49:56.237Z,1348530596.237 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:49:56.237Z,1348530596.237 [DVL_micro](INFO): resume:Powering up
2012-09-24T23:49:56.238Z,1348530596.238 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:49:56.679Z,1348530596.679 [AHRS_sp3003D](INFO): Powering down
2012-09-24T23:49:57.021Z,1348530597.021 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2012-09-24T23:49:57.021Z,1348530597.021 [AHRS_sp3003D] No Fault, FailCount= 2
2012-09-24T23:49:57.873Z,1348530597.873 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-24T23:49:58.247Z,1348530598.247 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:49:58.247Z,1348530598.247 [DVL_micro](INFO): Querying output modes
2012-09-24T23:49:58.248Z,1348530598.247 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:49:58.759Z,1348530598.759 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2012-09-24T23:49:58.759Z,1348530598.759 [AHRS_sp3003D](ERROR): readHeadingMagBin got 0x240100
2012-09-24T23:49:58.759Z,1348530598.759 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2012-09-24T23:49:58.759Z,1348530598.759 [AHRS_sp3003D] Hardware Fault, FailCount= 3
2012-09-24T23:49:58.759Z,1348530598.759 [AHRS_sp3003D](ERROR): Hardware Fault
2012-09-24T23:49:58.855Z,1348530598.855 [RudderServo](FAULT): Overload Error
2012-09-24T23:49:58.855Z,1348530598.855 [RudderServo] Hardware Fault, FailCount= 1
2012-09-24T23:49:58.855Z,1348530598.855 [RudderServo](ERROR): Hardware Fault
2012-09-24T23:49:58.889Z,1348530598.889 [CBIT](ERROR): Hardware Fault in component: RudderServo
2012-09-24T23:49:58.890Z,1348530598.889 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2012-09-24T23:49:59.073Z,1348530599.073 [AHRS_sp3003D](INFO): Powering down
2012-09-24T23:49:59.207Z,1348530599.207 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2012-09-24T23:49:59.207Z,1348530599.207 [RudderServo](INFO): Powering down
2012-09-24T23:49:59.210Z,1348530599.210 [CBIT](INFO): Clearing failed state for component RudderServo
2012-09-24T23:49:59.210Z,1348530599.210 [RudderServo] No Fault, FailCount= 1
2012-09-24T23:49:59.210Z,1348530599.210 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2012-09-24T23:49:59.211Z,1348530599.211 [AHRS_sp3003D] No Fault, FailCount= 3
2012-09-24T23:49:59.903Z,1348530599.903 [RudderServo](DEBUG): Initializing EZServoServo.
2012-09-24T23:50:00.024Z,1348530600.024 [RudderServo](DEBUG): Initialization error opening port: port not yet opened
2012-09-24T23:50:00.024Z,1348530600.024 [RudderServo](DEBUG): Initializing RudderServo.
2012-09-24T23:50:00.250Z,1348530600.250 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:50:00.251Z,1348530600.251 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:50:00.251Z,1348530600.251 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:50:00.273Z,1348530600.273 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-24T23:50:00.303Z,1348530600.303 [CBIT](CRITICAL): Environmental Failure. Press:13.920176 PSI. Humidity:40%. Temp:21 C. ABORTING MISSION
2012-09-24T23:50:01.155Z,1348530601.155 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2012-09-24T23:50:01.155Z,1348530601.155 [AHRS_sp3003D](ERROR): readHeadingMagBin got 0xA409A0
2012-09-24T23:50:01.155Z,1348530601.155 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2012-09-24T23:50:01.155Z,1348530601.155 [AHRS_sp3003D] Hardware Fault, FailCount= 4
2012-09-24T23:50:01.155Z,1348530601.155 [AHRS_sp3003D](ERROR): Hardware Fault
2012-09-24T23:50:01.180Z,1348530601.180 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2012-09-24T23:50:01.473Z,1348530601.473 [AHRS_sp3003D](INFO): Powering down
2012-09-24T23:50:01.584Z,1348530601.584 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2012-09-24T23:50:01.584Z,1348530601.584 [AHRS_sp3003D] No Fault, FailCount= 4
2012-09-24T23:50:02.255Z,1348530602.254 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:50:02.257Z,1348530602.257 [DVL_micro](INFO): pause:Powering down
2012-09-24T23:50:02.674Z,1348530602.674 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-24T23:50:03.563Z,1348530603.563 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2012-09-24T23:50:03.563Z,1348530603.563 [AHRS_sp3003D](ERROR): readHeadingMagBin got 0xA409A0
2012-09-24T23:50:03.563Z,1348530603.563 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2012-09-24T23:50:03.563Z,1348530603.563 [AHRS_sp3003D] Hardware Fault, FailCount= 5
2012-09-24T23:50:03.563Z,1348530603.563 [AHRS_sp3003D](ERROR): Hardware Fault
2012-09-24T23:50:03.621Z,1348530603.621 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2012-09-24T23:50:03.622Z,1348530603.622 [CBIT](CRITICAL): Hardware Fault in component: AHRS_sp3003D
2012-09-24T23:50:03.873Z,1348530603.873 [AHRS_sp3003D](INFO): Powering down
2012-09-24T23:50:03.875Z,1348530603.875 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:50:03.876Z,1348530603.876 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:50:03.876Z,1348530603.876 [DVL_micro](INFO): resume:Powering up
2012-09-24T23:50:03.876Z,1348530603.876 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:50:04.723Z,1348530604.723 [RudderServo](FAULT): Overload Error
2012-09-24T23:50:04.723Z,1348530604.723 [RudderServo] Hardware Fault, FailCount= 2
2012-09-24T23:50:04.723Z,1348530604.723 [RudderServo](ERROR): Hardware Fault
2012-09-24T23:50:04.749Z,1348530604.749 [CBIT](ERROR): Hardware Fault in component: RudderServo
2012-09-24T23:50:05.094Z,1348530605.095 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2012-09-24T23:50:05.095Z,1348530605.095 [RudderServo](INFO): Powering down
2012-09-24T23:50:05.097Z,1348530605.097 [CBIT](INFO): Clearing failed state for component RudderServo
2012-09-24T23:50:05.097Z,1348530605.098 [RudderServo] No Fault, FailCount= 2
2012-09-24T23:50:05.503Z,1348530605.503 [NAL9602](INFO): NAL9602 initialized
2012-09-24T23:50:05.883Z,1348530605.883 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:50:05.883Z,1348530605.883 [DVL_micro](INFO): Querying output modes
2012-09-24T23:50:05.883Z,1348530605.883 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:50:05.901Z,1348530605.901 [RudderServo](DEBUG): Initializing EZServoServo.
2012-09-24T23:50:06.020Z,1348530606.020 [RudderServo](DEBUG): Initialization error opening port: port not yet opened
2012-09-24T23:50:06.020Z,1348530606.020 [RudderServo](DEBUG): Initializing RudderServo.
2012-09-24T23:50:06.021Z,1348530606.021 [SBIT](IMPORTANT): Beginning Startup BIT
2012-09-24T23:50:06.024Z,1348530606.024 [CBIT](IMPORTANT): Beginning GF scan
2012-09-24T23:50:06.684Z,1348530606.684 [Radio_Freewave](INFO): Powering down
2012-09-24T23:50:07.084Z,1348530607.084 [Radio_Freewave](INFO): Powering up
2012-09-24T23:50:07.887Z,1348530607.887 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:50:07.887Z,1348530607.887 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:50:07.887Z,1348530607.887 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:50:09.891Z,1348530609.891 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:50:09.892Z,1348530609.892 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:50:09.892Z,1348530609.892 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:50:10.723Z,1348530610.723 [RudderServo](FAULT): Overload Error
2012-09-24T23:50:10.723Z,1348530610.723 [RudderServo] Hardware Fault, FailCount= 3
2012-09-24T23:50:10.723Z,1348530610.723 [RudderServo](ERROR): Hardware Fault
2012-09-24T23:50:10.754Z,1348530610.754 [CBIT](ERROR): Hardware Fault in component: RudderServo
2012-09-24T23:50:10.754Z,1348530610.754 [CBIT](CRITICAL): Hardware Fault in component: RudderServo
2012-09-24T23:50:11.100Z,1348530611.100 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2012-09-24T23:50:11.100Z,1348530611.100 [RudderServo](INFO): Powering down
2012-09-24T23:50:11.899Z,1348530611.899 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:50:11.899Z,1348530611.899 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:50:13.903Z,1348530613.903 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:50:13.903Z,1348530613.903 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:50:13.903Z,1348530613.903 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:50:15.906Z,1348530615.907 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:50:15.907Z,1348530615.907 [DVL_micro](INFO): Querying output modes
2012-09-24T23:50:15.907Z,1348530615.907 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:50:17.910Z,1348530617.911 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:50:17.911Z,1348530617.911 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:50:17.911Z,1348530617.911 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:50:19.915Z,1348530619.915 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:50:21.919Z,1348530621.919 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:50:21.919Z,1348530621.919 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:50:23.927Z,1348530623.927 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:50:23.927Z,1348530623.927 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:50:25.930Z,1348530625.931 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:50:25.931Z,1348530625.931 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:50:25.931Z,1348530625.931 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:50:27.934Z,1348530627.934 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:50:27.935Z,1348530627.935 [DVL_micro](INFO): Querying output modes
2012-09-24T23:50:27.935Z,1348530627.935 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:50:29.938Z,1348530629.938 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:50:29.939Z,1348530629.939 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:50:29.939Z,1348530629.939 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:50:31.943Z,1348530631.943 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:50:32.300Z,1348530632.300 [CBIT](IMPORTANT): No ground fault detected
2012-09-24T23:50:33.947Z,1348530633.947 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:50:33.947Z,1348530633.947 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:50:35.955Z,1348530635.955 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:50:35.955Z,1348530635.955 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:50:37.959Z,1348530637.958 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:50:37.959Z,1348530637.959 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:50:37.959Z,1348530637.959 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:50:39.962Z,1348530639.963 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:50:39.963Z,1348530639.963 [DVL_micro](INFO): Querying output modes
2012-09-24T23:50:39.963Z,1348530639.963 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:50:41.966Z,1348530641.967 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:50:41.967Z,1348530641.967 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:50:41.967Z,1348530641.967 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:50:43.971Z,1348530643.971 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:50:45.975Z,1348530645.975 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:50:45.975Z,1348530645.975 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:50:47.983Z,1348530647.983 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:50:47.983Z,1348530647.983 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:50:49.987Z,1348530649.987 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:50:49.987Z,1348530649.987 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:50:49.987Z,1348530649.987 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:50:51.990Z,1348530651.990 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:50:51.991Z,1348530651.991 [DVL_micro](INFO): Querying output modes
2012-09-24T23:50:51.991Z,1348530651.991 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:50:53.994Z,1348530653.994 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:50:53.995Z,1348530653.995 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:50:53.995Z,1348530653.995 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:50:55.998Z,1348530655.999 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:50:58.003Z,1348530658.003 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:50:58.003Z,1348530658.003 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:50:59.502Z,1348530659.502 [SBIT](IMPORTANT): SBIT PASSED
2012-09-24T23:50:59.896Z,1348530659.896 [MissionManager](IMPORTANT): Started mission Startup
2012-09-24T23:50:59.896Z,1348530659.896 [Startup] Running Loop=1
2012-09-24T23:50:59.896Z,1348530659.896 [Startup](INFO): Aggregate::initialize Startup
2012-09-24T23:50:59.896Z,1348530659.896 [Startup:A.GoToSurface] Running Loop=1
2012-09-24T23:50:59.896Z,1348530659.896 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-09-24T23:50:59.902Z,1348530659.902 [Startup:StartupSatComms] Running Loop=1
2012-09-24T23:50:59.902Z,1348530659.902 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2012-09-24T23:50:59.902Z,1348530659.902 [Startup:StartupSatComms:A] Running Loop=1
2012-09-24T23:51:00.011Z,1348530660.011 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:51:00.011Z,1348530660.011 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:51:00.291Z,1348530660.291 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-09-24T23:51:02.014Z,1348530662.014 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:51:02.015Z,1348530662.015 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:51:02.015Z,1348530662.015 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:51:04.019Z,1348530664.019 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:51:04.019Z,1348530664.019 [DVL_micro](INFO): Querying output modes
2012-09-24T23:51:04.019Z,1348530664.019 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:51:06.023Z,1348530666.023 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:51:06.023Z,1348530666.023 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:51:06.023Z,1348530666.023 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:51:08.026Z,1348530668.026 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:51:10.031Z,1348530670.031 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:51:10.031Z,1348530670.031 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:51:12.039Z,1348530672.039 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:51:12.039Z,1348530672.039 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:51:14.043Z,1348530674.043 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:51:14.043Z,1348530674.043 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:51:14.043Z,1348530674.043 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:51:16.047Z,1348530676.046 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:51:16.047Z,1348530676.047 [DVL_micro](INFO): Querying output modes
2012-09-24T23:51:16.047Z,1348530676.047 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:51:18.051Z,1348530678.050 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:51:18.051Z,1348530678.051 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:51:18.051Z,1348530678.051 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:51:20.054Z,1348530680.055 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:51:22.059Z,1348530682.059 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:51:22.059Z,1348530682.059 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:51:24.067Z,1348530684.067 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:51:24.068Z,1348530684.068 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:51:26.071Z,1348530686.071 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:51:26.071Z,1348530686.071 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:51:26.071Z,1348530686.071 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:51:28.075Z,1348530688.075 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:51:28.075Z,1348530688.075 [DVL_micro](INFO): Querying output modes
2012-09-24T23:51:28.075Z,1348530688.075 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:51:30.079Z,1348530690.079 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:51:30.079Z,1348530690.079 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:51:30.079Z,1348530690.079 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:51:32.083Z,1348530692.082 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:51:34.088Z,1348530694.088 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:51:34.088Z,1348530694.088 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:51:36.095Z,1348530696.095 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:51:36.095Z,1348530696.095 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:51:38.098Z,1348530698.099 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:51:38.099Z,1348530698.099 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:51:38.099Z,1348530698.099 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:51:40.102Z,1348530700.102 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:51:40.103Z,1348530700.103 [DVL_micro](INFO): Querying output modes
2012-09-24T23:51:40.103Z,1348530700.103 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:51:42.106Z,1348530702.106 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:51:42.107Z,1348530702.107 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:51:42.107Z,1348530702.107 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:51:44.111Z,1348530704.111 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:51:46.115Z,1348530706.115 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:51:46.115Z,1348530706.115 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:51:48.123Z,1348530708.123 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:51:48.123Z,1348530708.123 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:51:50.126Z,1348530710.126 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:51:50.127Z,1348530710.127 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:51:50.127Z,1348530710.127 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:51:52.131Z,1348530712.131 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:51:52.131Z,1348530712.131 [DVL_micro](INFO): Querying output modes
2012-09-24T23:51:52.131Z,1348530712.131 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:51:54.135Z,1348530714.135 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:51:54.135Z,1348530714.135 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:51:54.135Z,1348530714.135 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:51:56.139Z,1348530716.139 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:51:58.143Z,1348530718.143 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:51:58.143Z,1348530718.143 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:52:00.151Z,1348530720.151 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:52:00.151Z,1348530720.151 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:52:00.290Z,1348530720.290 [Startup:StartupSatComms:A](INFO): Timed out from 2012-09-24T23:50:59.9Z
2012-09-24T23:52:00.290Z,1348530720.290 [Startup:StartupSatComms:A_Timeout] Running Loop=1
2012-09-24T23:52:00.290Z,1348530720.290 [Startup:StartupSatComms:A_Timeout](INFO): Aggregate::initialize Startup:StartupSatComms:A_Timeout
2012-09-24T23:52:00.291Z,1348530720.291 [Startup:StartupSatComms:A_Timeout](INFO): Completed Startup:StartupSatComms:A_Timeout
2012-09-24T23:52:00.291Z,1348530720.291 [Startup:StartupSatComms:A] Stopped
2012-09-24T23:52:00.291Z,1348530720.291 [Startup:StartupSatComms:B] Running Loop=1
2012-09-24T23:52:00.690Z,1348530720.690 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2012-09-24T23:52:02.154Z,1348530722.155 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:52:02.155Z,1348530722.155 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:52:02.155Z,1348530722.155 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:52:04.159Z,1348530724.158 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:52:04.159Z,1348530724.159 [DVL_micro](INFO): Querying output modes
2012-09-24T23:52:04.159Z,1348530724.159 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:52:06.162Z,1348530726.162 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:52:06.163Z,1348530726.163 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:52:06.163Z,1348530726.163 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:52:08.167Z,1348530728.167 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:52:10.171Z,1348530730.171 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:52:10.171Z,1348530730.171 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:52:12.179Z,1348530732.179 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:52:12.179Z,1348530732.179 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:52:14.183Z,1348530734.182 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:52:14.183Z,1348530734.183 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:52:14.183Z,1348530734.183 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:52:16.186Z,1348530736.187 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:52:16.187Z,1348530736.187 [DVL_micro](INFO): Querying output modes
2012-09-24T23:52:16.187Z,1348530736.187 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:52:18.191Z,1348530738.191 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:52:18.191Z,1348530738.191 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:52:18.191Z,1348530738.191 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:52:20.194Z,1348530740.194 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:52:22.199Z,1348530742.199 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:52:22.199Z,1348530742.199 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:52:24.207Z,1348530744.207 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:52:24.207Z,1348530744.207 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:52:26.211Z,1348530746.211 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:52:26.211Z,1348530746.211 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:52:26.211Z,1348530746.211 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:52:28.215Z,1348530748.215 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:52:28.215Z,1348530748.215 [DVL_micro](INFO): Querying output modes
2012-09-24T23:52:28.215Z,1348530748.215 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:52:30.219Z,1348530750.219 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:52:30.219Z,1348530750.219 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:52:30.219Z,1348530750.219 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:52:32.222Z,1348530752.223 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:52:34.227Z,1348530754.227 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:52:34.227Z,1348530754.227 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:52:36.235Z,1348530756.235 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:52:36.235Z,1348530756.235 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:52:38.238Z,1348530758.239 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:52:38.239Z,1348530758.239 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:52:38.239Z,1348530758.239 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:52:40.243Z,1348530760.243 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:52:40.243Z,1348530760.243 [DVL_micro](INFO): Querying output modes
2012-09-24T23:52:40.243Z,1348530760.243 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:52:42.247Z,1348530762.247 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:52:42.247Z,1348530762.247 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:52:42.247Z,1348530762.247 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:52:44.250Z,1348530764.250 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:52:46.255Z,1348530766.255 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:52:46.255Z,1348530766.255 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:52:48.263Z,1348530768.263 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:52:48.263Z,1348530768.263 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:52:50.266Z,1348530770.266 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:52:50.267Z,1348530770.267 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:52:50.267Z,1348530770.267 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:52:52.271Z,1348530772.270 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:52:52.271Z,1348530772.271 [DVL_micro](INFO): Querying output modes
2012-09-24T23:52:52.271Z,1348530772.271 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:52:54.275Z,1348530774.274 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:52:54.275Z,1348530774.275 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:52:54.275Z,1348530774.275 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:52:56.279Z,1348530776.279 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:52:58.283Z,1348530778.283 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:52:58.283Z,1348530778.283 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:53:00.291Z,1348530780.291 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:53:00.291Z,1348530780.291 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:53:00.537Z,1348530780.537 [Startup:StartupSatComms:B](INFO): Timed out from 2012-09-24T23:52:00.3Z
2012-09-24T23:53:00.538Z,1348530780.537 [Startup:StartupSatComms:A_Timeout] Running Loop=1
2012-09-24T23:53:00.538Z,1348530780.538 [Startup:StartupSatComms:A_Timeout](INFO): Aggregate::initialize Startup:StartupSatComms:A_Timeout
2012-09-24T23:53:00.538Z,1348530780.538 [Startup:StartupSatComms:A_Timeout](INFO): Completed Startup:StartupSatComms:A_Timeout
2012-09-24T23:53:00.538Z,1348530780.538 [Startup:StartupSatComms:B] Stopped
2012-09-24T23:53:00.538Z,1348530780.538 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2012-09-24T23:53:00.538Z,1348530780.538 [Startup:StartupSatComms] Stopped
2012-09-24T23:53:00.539Z,1348530780.539 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2012-09-24T23:53:00.539Z,1348530780.539 [Startup](INFO): Completed Startup
2012-09-24T23:53:00.540Z,1348530780.540 [Startup] Stopped
2012-09-24T23:53:00.540Z,1348530780.540 [Startup](INFO): Aggregate::uninitialize Startup
2012-09-24T23:53:00.540Z,1348530780.540 [Startup:A.GoToSurface] Stopped
2012-09-24T23:53:00.540Z,1348530780.540 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-09-24T23:53:01.436Z,1348530781.436 [MissionManager](IMPORTANT): Started mission Default
2012-09-24T23:53:01.436Z,1348530781.436 [Default] Running Loop=1
2012-09-24T23:53:01.436Z,1348530781.436 [Default](INFO): Aggregate::initialize Default
2012-09-24T23:53:01.436Z,1348530781.436 [Default:D.SetSpeed] Running Loop=1
2012-09-24T23:53:01.436Z,1348530781.436 [Default:D.SetSpeed](DEBUG): Initialize.
2012-09-24T23:53:01.437Z,1348530781.437 [Default:E.GoToSurface] Running Loop=1
2012-09-24T23:53:01.437Z,1348530781.437 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-09-24T23:53:01.437Z,1348530781.437 [Default:Iridium] Running Loop=1
2012-09-24T23:53:01.437Z,1348530781.437 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-09-24T23:53:01.437Z,1348530781.437 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-09-24T23:53:01.437Z,1348530781.437 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-09-24T23:53:01.437Z,1348530781.437 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-09-24T23:53:01.438Z,1348530781.438 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-09-24T23:53:01.438Z,1348530781.438 [Default:E.GoToSurface] Running Loop=1
2012-09-24T23:53:01.443Z,1348530781.443 [Default:D.SetSpeed] Running Loop=1
2012-09-24T23:53:01.449Z,1348530781.449 [Default:CallIridium] Running Loop=1
2012-09-24T23:53:01.449Z,1348530781.449 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-09-24T23:53:01.449Z,1348530781.449 [Default:CallIridium:A] Running Loop=1
2012-09-24T23:53:01.451Z,1348530781.451 [Default:CallIridium:A] Stopped
2012-09-24T23:53:01.451Z,1348530781.451 [Default:CallIridium:B] Running Loop=1
2012-09-24T23:53:01.451Z,1348530781.451 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-09-24T23:53:01.456Z,1348530781.456 [Default:Iridium:B.GoToSurface] Stopped
2012-09-24T23:53:01.456Z,1348530781.456 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-09-24T23:53:01.456Z,1348530781.457 [Default:Iridium:Read_Iridium] Running Loop=1
2012-09-24T23:53:01.456Z,1348530781.457 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-09-24T23:53:01.461Z,1348530781.461 [Default:GPS] Running Loop=1
2012-09-24T23:53:01.461Z,1348530781.461 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-09-24T23:53:01.461Z,1348530781.461 [Default:GPS:A.SetSpeed] Running Loop=1
2012-09-24T23:53:01.461Z,1348530781.461 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-09-24T23:53:01.462Z,1348530781.462 [Default:GPS:B.GoToSurface] Running Loop=1
2012-09-24T23:53:01.462Z,1348530781.462 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-09-24T23:53:01.467Z,1348530781.467 [Default:GPS:B.GoToSurface] Stopped
2012-09-24T23:53:01.467Z,1348530781.467 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-09-24T23:53:01.468Z,1348530781.467 [Default:GPS:Read_GPS] Running Loop=1
2012-09-24T23:53:01.468Z,1348530781.468 [Default:GPS:A.SetSpeed] Running Loop=1
2012-09-24T23:53:01.598Z,1348530781.598 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2012-09-24T23:53:01.601Z,1348530781.601 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-09-24T23:53:02.294Z,1348530782.295 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:53:02.295Z,1348530782.295 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:53:02.295Z,1348530782.295 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:53:04.299Z,1348530784.299 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:53:04.299Z,1348530784.299 [DVL_micro](INFO): Querying output modes
2012-09-24T23:53:04.299Z,1348530784.299 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:53:06.303Z,1348530786.303 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:53:06.303Z,1348530786.303 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:53:06.303Z,1348530786.303 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:53:08.306Z,1348530788.306 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:53:10.308Z,1348530790.308 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:53:10.308Z,1348530790.308 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:53:12.315Z,1348530792.315 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:53:12.315Z,1348530792.315 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:53:14.319Z,1348530794.319 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:53:14.319Z,1348530794.319 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:53:14.319Z,1348530794.319 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:53:16.322Z,1348530796.322 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:53:16.323Z,1348530796.323 [DVL_micro](INFO): Querying output modes
2012-09-24T23:53:16.323Z,1348530796.323 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:53:18.326Z,1348530798.326 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:53:18.327Z,1348530798.327 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:53:18.327Z,1348530798.327 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:53:20.331Z,1348530800.330 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:53:22.335Z,1348530802.335 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:53:22.335Z,1348530802.335 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:53:24.343Z,1348530804.343 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:53:24.343Z,1348530804.343 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:53:26.347Z,1348530806.346 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:53:26.347Z,1348530806.347 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:53:26.347Z,1348530806.347 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:53:28.350Z,1348530808.351 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:53:28.351Z,1348530808.351 [DVL_micro](INFO): Querying output modes
2012-09-24T23:53:28.351Z,1348530808.351 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:53:30.355Z,1348530810.355 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:53:30.355Z,1348530810.355 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:53:30.355Z,1348530810.355 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:53:32.359Z,1348530812.359 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:53:34.363Z,1348530814.363 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:53:34.363Z,1348530814.363 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:53:36.371Z,1348530816.371 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:53:36.371Z,1348530816.371 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:53:38.375Z,1348530818.375 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:53:38.375Z,1348530818.375 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:53:38.375Z,1348530818.375 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:53:40.379Z,1348530820.379 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:53:40.379Z,1348530820.379 [DVL_micro](INFO): Querying output modes
2012-09-24T23:53:40.379Z,1348530820.379 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:53:42.382Z,1348530822.382 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:53:42.383Z,1348530822.383 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:53:42.383Z,1348530822.383 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:53:44.387Z,1348530824.386 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:53:46.391Z,1348530826.391 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:53:46.391Z,1348530826.391 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:53:48.399Z,1348530828.399 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:53:48.399Z,1348530828.399 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:53:50.403Z,1348530830.402 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:53:50.403Z,1348530830.403 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:53:50.403Z,1348530830.403 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:53:52.407Z,1348530832.407 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:53:52.407Z,1348530832.407 [DVL_micro](INFO): Querying output modes
2012-09-24T23:53:52.407Z,1348530832.407 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:53:54.410Z,1348530834.411 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:53:54.411Z,1348530834.411 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:53:54.411Z,1348530834.411 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:53:54.839Z,1348530834.839 [CBIT](CRITICAL): Environmental Failure. Press:14.451184 PSI. Humidity:41%. Temp:21 C. ABORTING MISSION
2012-09-24T23:53:56.414Z,1348530836.414 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:53:58.420Z,1348530838.420 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:53:58.420Z,1348530838.420 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:54:00.427Z,1348530840.427 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:54:00.427Z,1348530840.427 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:54:02.431Z,1348530842.431 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:54:02.431Z,1348530842.431 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:54:02.431Z,1348530842.431 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:54:04.435Z,1348530844.435 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:54:04.435Z,1348530844.435 [DVL_micro](INFO): Querying output modes
2012-09-24T23:54:04.435Z,1348530844.435 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:54:06.439Z,1348530846.439 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:54:06.439Z,1348530846.439 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:54:06.439Z,1348530846.439 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:54:08.442Z,1348530848.443 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:54:10.444Z,1348530850.444 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:54:10.444Z,1348530850.444 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:54:12.451Z,1348530852.451 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:54:12.451Z,1348530852.451 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:54:14.454Z,1348530854.454 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:54:14.455Z,1348530854.455 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:54:14.455Z,1348530854.455 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:54:16.458Z,1348530856.458 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:54:16.459Z,1348530856.459 [DVL_micro](INFO): Querying output modes
2012-09-24T23:54:16.459Z,1348530856.459 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:54:18.463Z,1348530858.463 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:54:18.463Z,1348530858.463 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:54:18.463Z,1348530858.463 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:54:20.467Z,1348530860.467 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:54:22.471Z,1348530862.471 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:54:22.471Z,1348530862.471 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:54:24.479Z,1348530864.479 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:54:24.479Z,1348530864.479 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:54:26.483Z,1348530866.483 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:54:26.483Z,1348530866.483 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:54:26.483Z,1348530866.483 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:54:28.487Z,1348530868.487 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:54:28.487Z,1348530868.487 [DVL_micro](INFO): Querying output modes
2012-09-24T23:54:28.487Z,1348530868.487 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:54:30.491Z,1348530870.490 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:54:30.491Z,1348530870.491 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:54:30.491Z,1348530870.491 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:54:32.495Z,1348530872.494 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:54:34.499Z,1348530874.499 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:54:34.499Z,1348530874.499 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:54:36.507Z,1348530876.507 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:54:36.507Z,1348530876.507 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:54:38.508Z,1348530878.508 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:54:38.509Z,1348530878.509 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:54:38.509Z,1348530878.509 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:54:40.511Z,1348530880.510 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:54:40.511Z,1348530880.511 [DVL_micro](INFO): Querying output modes
2012-09-24T23:54:40.511Z,1348530880.511 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:54:42.515Z,1348530882.514 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:54:42.515Z,1348530882.515 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:54:42.515Z,1348530882.515 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:54:44.519Z,1348530884.519 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:54:46.523Z,1348530886.523 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:54:46.523Z,1348530886.523 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:54:48.531Z,1348530888.531 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:54:48.531Z,1348530888.531 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:54:50.535Z,1348530890.535 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:54:50.535Z,1348530890.535 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:54:50.535Z,1348530890.535 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:54:52.539Z,1348530892.539 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:54:52.539Z,1348530892.539 [DVL_micro](INFO): Querying output modes
2012-09-24T23:54:52.539Z,1348530892.539 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:54:54.543Z,1348530894.543 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:54:54.543Z,1348530894.543 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:54:54.543Z,1348530894.543 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:54:56.547Z,1348530896.546 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:54:58.551Z,1348530898.551 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:54:58.551Z,1348530898.551 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:55:00.559Z,1348530900.559 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:55:00.559Z,1348530900.559 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:55:02.562Z,1348530902.562 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:55:02.563Z,1348530902.563 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:55:02.563Z,1348530902.563 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:55:03.939Z,1348530903.939 [CBIT](INFO): Clearing failed count for component AHRS_sp3003D
2012-09-24T23:55:03.939Z,1348530903.939 [AHRS_sp3003D] No Fault, FailCount= 5
2012-09-24T23:55:04.567Z,1348530904.566 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:55:04.567Z,1348530904.567 [DVL_micro](INFO): Querying output modes
2012-09-24T23:55:04.567Z,1348530904.567 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:55:04.709Z,1348530904.709 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-24T23:55:05.998Z,1348530905.999 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2012-09-24T23:55:05.999Z,1348530905.999 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2012-09-24T23:55:05.999Z,1348530905.999 [AHRS_sp3003D] Hardware Fault, FailCount= 1
2012-09-24T23:55:05.999Z,1348530905.999 [AHRS_sp3003D](ERROR): Hardware Fault
2012-09-24T23:55:06.026Z,1348530906.026 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2012-09-24T23:55:06.080Z,1348530906.080 [AHRS_sp3003D](INFO): Powering down
2012-09-24T23:55:06.196Z,1348530906.196 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2012-09-24T23:55:06.196Z,1348530906.196 [AHRS_sp3003D] No Fault, FailCount= 1
2012-09-24T23:55:06.571Z,1348530906.570 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:55:06.571Z,1348530906.571 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:55:06.571Z,1348530906.571 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:55:07.281Z,1348530907.281 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-24T23:55:08.398Z,1348530908.398 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2012-09-24T23:55:08.399Z,1348530908.399 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2012-09-24T23:55:08.399Z,1348530908.399 [AHRS_sp3003D] Hardware Fault, FailCount= 2
2012-09-24T23:55:08.399Z,1348530908.399 [AHRS_sp3003D](ERROR): Hardware Fault
2012-09-24T23:55:08.426Z,1348530908.426 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2012-09-24T23:55:08.481Z,1348530908.481 [AHRS_sp3003D](INFO): Powering down
2012-09-24T23:55:08.575Z,1348530908.575 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:55:08.599Z,1348530908.599 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2012-09-24T23:55:08.599Z,1348530908.599 [AHRS_sp3003D] No Fault, FailCount= 2
2012-09-24T23:55:09.841Z,1348530909.841 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-24T23:55:10.580Z,1348530910.580 [DVL_micro](INFO): pause:Powering down
2012-09-24T23:55:10.959Z,1348530910.958 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2012-09-24T23:55:10.959Z,1348530910.959 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2012-09-24T23:55:10.959Z,1348530910.959 [AHRS_sp3003D] Hardware Fault, FailCount= 3
2012-09-24T23:55:10.959Z,1348530910.959 [AHRS_sp3003D](ERROR): Hardware Fault
2012-09-24T23:55:10.983Z,1348530910.983 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:55:10.984Z,1348530910.984 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:55:10.984Z,1348530910.984 [DVL_micro](INFO): resume:Powering up
2012-09-24T23:55:10.984Z,1348530910.984 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:55:10.993Z,1348530910.993 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2012-09-24T23:55:11.045Z,1348530911.045 [AHRS_sp3003D](INFO): Powering down
2012-09-24T23:55:11.168Z,1348530911.168 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2012-09-24T23:55:11.168Z,1348530911.168 [AHRS_sp3003D] No Fault, FailCount= 3
2012-09-24T23:55:12.248Z,1348530912.248 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-24T23:55:12.991Z,1348530912.991 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:55:12.991Z,1348530912.991 [DVL_micro](INFO): Querying output modes
2012-09-24T23:55:12.991Z,1348530912.991 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:55:13.535Z,1348530913.534 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2012-09-24T23:55:13.535Z,1348530913.535 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2012-09-24T23:55:13.535Z,1348530913.535 [AHRS_sp3003D] Hardware Fault, FailCount= 4
2012-09-24T23:55:13.535Z,1348530913.535 [AHRS_sp3003D](ERROR): Hardware Fault
2012-09-24T23:55:13.575Z,1348530913.575 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2012-09-24T23:55:13.618Z,1348530913.618 [AHRS_sp3003D](INFO): Powering down
2012-09-24T23:55:13.729Z,1348530913.729 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2012-09-24T23:55:13.729Z,1348530913.729 [AHRS_sp3003D] No Fault, FailCount= 4
2012-09-24T23:55:14.820Z,1348530914.820 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-24T23:55:14.994Z,1348530914.994 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:55:14.995Z,1348530914.995 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:55:14.995Z,1348530914.995 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:55:15.938Z,1348530915.938 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2012-09-24T23:55:15.939Z,1348530915.939 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2012-09-24T23:55:15.939Z,1348530915.939 [AHRS_sp3003D] Hardware Fault, FailCount= 5
2012-09-24T23:55:15.939Z,1348530915.939 [AHRS_sp3003D](ERROR): Hardware Fault
2012-09-24T23:55:15.966Z,1348530915.967 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2012-09-24T23:55:15.967Z,1348530915.967 [CBIT](CRITICAL): Hardware Fault in component: AHRS_sp3003D
2012-09-24T23:55:16.020Z,1348530916.020 [AHRS_sp3003D](INFO): Powering down
2012-09-24T23:55:16.998Z,1348530916.999 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:55:17.000Z,1348530917.000 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:55:17.000Z,1348530917.000 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:55:19.007Z,1348530919.007 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:55:19.007Z,1348530919.007 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:55:21.010Z,1348530921.010 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:55:21.011Z,1348530921.011 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:55:21.011Z,1348530921.011 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:55:23.014Z,1348530923.014 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:55:23.015Z,1348530923.015 [DVL_micro](INFO): Querying output modes
2012-09-24T23:55:23.015Z,1348530923.015 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:55:25.018Z,1348530925.018 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:55:25.019Z,1348530925.019 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:55:25.019Z,1348530925.019 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:55:27.022Z,1348530927.022 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:55:29.027Z,1348530929.027 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:55:29.027Z,1348530929.027 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:55:31.035Z,1348530931.035 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:55:31.035Z,1348530931.035 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:55:33.038Z,1348530933.039 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:55:33.039Z,1348530933.039 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:55:33.039Z,1348530933.039 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:55:35.043Z,1348530935.043 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:55:35.043Z,1348530935.043 [DVL_micro](INFO): Querying output modes
2012-09-24T23:55:35.043Z,1348530935.043 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:55:37.047Z,1348530937.046 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:55:37.047Z,1348530937.047 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:55:37.047Z,1348530937.047 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:55:39.051Z,1348530939.050 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:55:41.052Z,1348530941.052 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:55:41.052Z,1348530941.052 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:55:43.059Z,1348530943.059 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:55:43.059Z,1348530943.059 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:55:45.062Z,1348530945.062 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:55:45.063Z,1348530945.063 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:55:45.063Z,1348530945.063 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:55:47.067Z,1348530947.066 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:55:47.067Z,1348530947.067 [DVL_micro](INFO): Querying output modes
2012-09-24T23:55:47.067Z,1348530947.067 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:55:49.071Z,1348530949.070 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:55:49.071Z,1348530949.071 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:55:49.071Z,1348530949.071 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:55:51.075Z,1348530951.075 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:55:53.079Z,1348530953.079 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:55:53.079Z,1348530953.079 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:55:55.087Z,1348530955.087 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:55:55.087Z,1348530955.087 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:55:57.090Z,1348530957.091 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:55:57.091Z,1348530957.091 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:55:57.091Z,1348530957.091 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:55:58.987Z,1348530958.987 [CBIT](CRITICAL): Environmental Failure. Press:13.920176 PSI. Humidity:44%. Temp:21 C. ABORTING MISSION
2012-09-24T23:55:59.095Z,1348530959.095 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:55:59.095Z,1348530959.095 [DVL_micro](INFO): Querying output modes
2012-09-24T23:55:59.095Z,1348530959.095 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:56:01.099Z,1348530961.099 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:56:01.099Z,1348530961.099 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:56:01.099Z,1348530961.099 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:56:03.102Z,1348530963.102 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:56:05.107Z,1348530965.107 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:56:05.107Z,1348530965.107 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:56:07.115Z,1348530967.115 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:56:07.115Z,1348530967.115 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:56:09.119Z,1348530969.118 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:56:09.119Z,1348530969.119 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:56:09.119Z,1348530969.119 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:56:11.123Z,1348530971.122 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:56:11.123Z,1348530971.123 [DVL_micro](INFO): Querying output modes
2012-09-24T23:56:11.123Z,1348530971.123 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:56:13.127Z,1348530973.127 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:56:13.127Z,1348530973.127 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:56:13.127Z,1348530973.127 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:56:15.131Z,1348530975.131 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:56:17.135Z,1348530977.135 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:56:17.135Z,1348530977.135 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:56:19.143Z,1348530979.143 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:56:19.143Z,1348530979.143 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:56:21.147Z,1348530981.147 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:56:21.147Z,1348530981.147 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:56:21.147Z,1348530981.147 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:56:23.150Z,1348530983.150 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:56:23.151Z,1348530983.151 [DVL_micro](INFO): Querying output modes
2012-09-24T23:56:23.151Z,1348530983.151 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:56:25.154Z,1348530985.155 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:56:25.155Z,1348530985.155 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:56:25.155Z,1348530985.155 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:56:27.159Z,1348530987.158 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:56:29.163Z,1348530989.163 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:56:29.163Z,1348530989.163 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:56:31.171Z,1348530991.171 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:56:31.171Z,1348530991.171 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:56:33.175Z,1348530993.175 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:56:33.175Z,1348530993.175 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:56:33.175Z,1348530993.175 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:56:35.178Z,1348530995.178 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:56:35.179Z,1348530995.179 [DVL_micro](INFO): Querying output modes
2012-09-24T23:56:35.179Z,1348530995.179 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:56:37.183Z,1348530997.182 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:56:37.183Z,1348530997.183 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:56:37.183Z,1348530997.183 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:56:39.186Z,1348530999.186 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:56:41.191Z,1348531001.191 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:56:41.191Z,1348531001.191 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:56:43.199Z,1348531003.199 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:56:43.200Z,1348531003.200 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:56:45.203Z,1348531005.203 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:56:45.203Z,1348531005.203 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:56:45.203Z,1348531005.203 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:56:47.207Z,1348531007.207 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:56:47.207Z,1348531007.207 [DVL_micro](INFO): Querying output modes
2012-09-24T23:56:47.207Z,1348531007.207 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:56:49.211Z,1348531009.211 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:56:49.211Z,1348531009.211 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:56:49.211Z,1348531009.211 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:56:51.214Z,1348531011.214 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:56:53.219Z,1348531013.219 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:56:53.219Z,1348531013.219 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:56:55.227Z,1348531015.227 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:56:55.227Z,1348531015.227 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:56:57.230Z,1348531017.230 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:56:57.231Z,1348531017.231 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:56:57.231Z,1348531017.231 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:56:59.235Z,1348531019.234 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:56:59.235Z,1348531019.235 [DVL_micro](INFO): Querying output modes
2012-09-24T23:56:59.235Z,1348531019.235 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:57:01.238Z,1348531021.239 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:57:01.239Z,1348531021.239 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:57:01.239Z,1348531021.239 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:57:03.243Z,1348531023.243 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:57:05.247Z,1348531025.247 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:57:05.247Z,1348531025.247 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:57:07.255Z,1348531027.255 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:57:07.255Z,1348531027.255 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:57:09.259Z,1348531029.259 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:57:09.259Z,1348531029.259 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:57:09.259Z,1348531029.259 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:57:11.262Z,1348531031.263 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:57:11.263Z,1348531031.263 [DVL_micro](INFO): Querying output modes
2012-09-24T23:57:11.263Z,1348531031.263 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:57:13.266Z,1348531033.266 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:57:13.267Z,1348531033.267 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:57:13.267Z,1348531033.267 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:57:15.271Z,1348531035.270 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:57:17.275Z,1348531037.275 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:57:17.275Z,1348531037.275 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:57:19.283Z,1348531039.283 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:57:19.283Z,1348531039.283 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:57:21.287Z,1348531041.286 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:57:21.287Z,1348531041.287 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:57:21.287Z,1348531041.287 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:57:23.290Z,1348531043.290 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:57:23.291Z,1348531043.291 [DVL_micro](INFO): Querying output modes
2012-09-24T23:57:23.291Z,1348531043.291 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:57:25.291Z,1348531045.291 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:57:25.291Z,1348531045.291 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:57:25.291Z,1348531045.291 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:57:27.294Z,1348531047.295 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:57:29.299Z,1348531049.299 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:57:29.299Z,1348531049.299 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:57:31.307Z,1348531051.307 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:57:31.307Z,1348531051.307 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:57:33.311Z,1348531053.311 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:57:33.311Z,1348531053.311 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:57:33.311Z,1348531053.311 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:57:35.315Z,1348531055.315 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:57:35.315Z,1348531055.315 [DVL_micro](INFO): Querying output modes
2012-09-24T23:57:35.315Z,1348531055.315 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:57:37.319Z,1348531057.319 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:57:37.319Z,1348531057.319 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:57:37.319Z,1348531057.319 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:57:39.321Z,1348531059.321 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:57:41.323Z,1348531061.323 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:57:41.323Z,1348531061.323 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:57:43.331Z,1348531063.331 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:57:43.331Z,1348531063.331 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:57:45.335Z,1348531065.335 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:57:45.335Z,1348531065.335 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:57:45.335Z,1348531065.335 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:57:47.338Z,1348531067.339 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:57:47.339Z,1348531067.339 [DVL_micro](INFO): Querying output modes
2012-09-24T23:57:47.339Z,1348531067.339 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:57:49.343Z,1348531069.342 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:57:49.343Z,1348531069.343 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:57:49.343Z,1348531069.343 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:57:51.347Z,1348531071.346 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:57:53.351Z,1348531073.351 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:57:53.351Z,1348531073.351 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:57:55.359Z,1348531075.359 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:57:55.359Z,1348531075.359 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:57:57.363Z,1348531077.362 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:57:57.363Z,1348531077.363 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:57:57.363Z,1348531077.363 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:57:59.367Z,1348531079.367 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:57:59.367Z,1348531079.367 [DVL_micro](INFO): Querying output modes
2012-09-24T23:57:59.367Z,1348531079.367 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:58:01.370Z,1348531081.371 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:58:01.371Z,1348531081.371 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:58:01.371Z,1348531081.371 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:58:03.375Z,1348531083.375 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:58:05.379Z,1348531085.379 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:58:05.379Z,1348531085.379 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:58:07.387Z,1348531087.387 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:58:07.387Z,1348531087.387 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:58:09.391Z,1348531089.391 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:58:09.391Z,1348531089.391 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:58:09.391Z,1348531089.391 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:58:11.395Z,1348531091.395 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:58:11.395Z,1348531091.395 [DVL_micro](INFO): Querying output modes
2012-09-24T23:58:11.395Z,1348531091.395 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:58:13.398Z,1348531093.398 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:58:13.399Z,1348531093.399 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:58:13.399Z,1348531093.399 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:58:15.403Z,1348531095.402 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:58:17.407Z,1348531097.407 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:58:17.407Z,1348531097.407 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:58:19.415Z,1348531099.415 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:58:19.415Z,1348531099.415 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:58:21.419Z,1348531101.418 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:58:21.419Z,1348531101.419 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:58:21.419Z,1348531101.419 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:58:23.423Z,1348531103.422 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:58:23.423Z,1348531103.423 [DVL_micro](INFO): Querying output modes
2012-09-24T23:58:23.423Z,1348531103.423 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:58:25.426Z,1348531105.427 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:58:25.427Z,1348531105.427 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:58:25.427Z,1348531105.427 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:58:27.431Z,1348531107.431 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:58:29.435Z,1348531109.435 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:58:29.435Z,1348531109.435 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:58:31.443Z,1348531111.443 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:58:31.443Z,1348531111.443 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:58:33.445Z,1348531113.445 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:58:33.445Z,1348531113.445 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:58:33.445Z,1348531113.445 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:58:35.447Z,1348531115.447 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:58:35.447Z,1348531115.447 [DVL_micro](INFO): Querying output modes
2012-09-24T23:58:35.447Z,1348531115.447 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:58:37.451Z,1348531117.451 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:58:37.451Z,1348531117.451 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:58:37.451Z,1348531117.451 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:58:39.454Z,1348531119.454 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:58:41.459Z,1348531121.459 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:58:41.459Z,1348531121.459 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:58:43.467Z,1348531123.467 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:58:43.467Z,1348531123.467 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:58:45.470Z,1348531125.470 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:58:45.471Z,1348531125.471 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:58:45.471Z,1348531125.471 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:58:47.475Z,1348531127.474 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:58:47.475Z,1348531127.475 [DVL_micro](INFO): Querying output modes
2012-09-24T23:58:47.475Z,1348531127.475 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:58:49.479Z,1348531129.478 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:58:49.479Z,1348531129.479 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:58:49.479Z,1348531129.479 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:58:51.482Z,1348531131.483 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:58:53.488Z,1348531133.488 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:58:53.488Z,1348531133.488 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:58:55.495Z,1348531135.495 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:58:55.495Z,1348531135.495 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:58:57.498Z,1348531137.499 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:58:57.499Z,1348531137.499 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:58:57.499Z,1348531137.499 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:58:59.502Z,1348531139.503 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:58:59.503Z,1348531139.503 [DVL_micro](INFO): Querying output modes
2012-09-24T23:58:59.503Z,1348531139.503 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:59:01.507Z,1348531141.507 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:59:01.507Z,1348531141.507 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:59:01.507Z,1348531141.507 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:59:03.511Z,1348531143.510 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:59:05.515Z,1348531145.515 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:59:05.515Z,1348531145.515 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:59:07.523Z,1348531147.523 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:59:07.523Z,1348531147.523 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:59:09.526Z,1348531149.526 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:59:09.527Z,1348531149.527 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:59:09.527Z,1348531149.527 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:59:11.530Z,1348531151.530 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:59:11.531Z,1348531151.531 [DVL_micro](INFO): Querying output modes
2012-09-24T23:59:11.531Z,1348531151.531 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:59:13.535Z,1348531153.534 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:59:13.535Z,1348531153.535 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:59:13.535Z,1348531153.535 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:59:15.539Z,1348531155.539 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:59:17.544Z,1348531157.543 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:59:17.544Z,1348531157.544 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:59:19.551Z,1348531159.551 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:59:19.551Z,1348531159.551 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:59:21.554Z,1348531161.555 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:59:21.555Z,1348531161.555 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:59:21.555Z,1348531161.555 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:59:23.558Z,1348531163.559 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:59:23.559Z,1348531163.559 [DVL_micro](INFO): Querying output modes
2012-09-24T23:59:23.559Z,1348531163.559 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:59:25.562Z,1348531165.562 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:59:25.563Z,1348531165.563 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:59:25.563Z,1348531165.563 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:59:27.564Z,1348531167.564 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:59:29.573Z,1348531169.573 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:59:29.574Z,1348531169.574 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:59:31.583Z,1348531171.583 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:59:31.583Z,1348531171.583 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:59:33.586Z,1348531173.586 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:59:33.587Z,1348531173.587 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:59:33.587Z,1348531173.587 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:59:35.593Z,1348531175.593 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:59:35.593Z,1348531175.593 [DVL_micro](INFO): Querying output modes
2012-09-24T23:59:35.593Z,1348531175.593 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:59:37.595Z,1348531177.595 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:59:37.595Z,1348531177.595 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:59:37.595Z,1348531177.595 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:59:39.599Z,1348531179.599 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:59:41.603Z,1348531181.603 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:59:41.603Z,1348531181.603 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:59:43.611Z,1348531183.611 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:59:43.611Z,1348531183.611 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:59:45.615Z,1348531185.615 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:59:45.615Z,1348531185.615 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:59:45.615Z,1348531185.615 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:59:47.623Z,1348531187.623 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:59:47.623Z,1348531187.623 [DVL_micro](INFO): Querying output modes
2012-09-24T23:59:47.623Z,1348531187.623 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T23:59:49.627Z,1348531189.626 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:59:49.627Z,1348531189.627 [DVL_micro](INFO): Output Modes: No Response
2012-09-24T23:59:49.627Z,1348531189.627 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T23:59:51.631Z,1348531191.631 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:59:53.635Z,1348531193.635 [DVL_micro](INFO): NQ1 requested
2012-09-24T23:59:53.635Z,1348531193.635 [DVL_micro](INFO): Cycling power to configure device.
2012-09-24T23:59:55.643Z,1348531195.643 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T23:59:55.643Z,1348531195.643 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T23:59:57.646Z,1348531197.647 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:59:57.647Z,1348531197.647 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T23:59:57.647Z,1348531197.647 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T23:59:59.650Z,1348531199.651 [DVL_micro](DEBUG): cmdResponse: 
2012-09-24T23:59:59.651Z,1348531199.651 [DVL_micro](INFO): Querying output modes
2012-09-24T23:59:59.651Z,1348531199.651 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:00:01.655Z,1348531201.655 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:00:01.655Z,1348531201.655 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:00:01.655Z,1348531201.655 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:00:03.659Z,1348531203.658 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:00:05.663Z,1348531205.663 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:00:05.663Z,1348531205.663 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:00:07.671Z,1348531207.671 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:00:07.671Z,1348531207.671 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:00:08.440Z,1348531208.440 [NAL9602](FAULT): GPS failed to acquire within timeout.
2012-09-25T00:00:08.440Z,1348531208.440 [NAL9602] Data Fault, FailCount= 1
2012-09-25T00:00:08.440Z,1348531208.440 [NAL9602](ERROR): Data Fault
2012-09-25T00:00:08.509Z,1348531208.509 [CBIT](ERROR): Data Fault in component: NAL9602
2012-09-25T00:00:08.840Z,1348531208.840 [NAL9602](INFO): Powering down
2012-09-25T00:00:08.901Z,1348531208.901 [CBIT](INFO): Clearing failed state for component NAL9602
2012-09-25T00:00:08.901Z,1348531208.901 [NAL9602] No Fault, FailCount= 1
2012-09-25T00:00:09.675Z,1348531209.675 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:00:09.675Z,1348531209.675 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:00:09.675Z,1348531209.675 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:00:11.679Z,1348531211.679 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:00:11.679Z,1348531211.679 [DVL_micro](INFO): Querying output modes
2012-09-25T00:00:11.679Z,1348531211.679 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:00:13.683Z,1348531213.683 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:00:13.683Z,1348531213.683 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:00:13.683Z,1348531213.683 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:00:14.046Z,1348531214.046 [NAL9602](INFO): Powering up NAL9602
2012-09-25T00:00:15.687Z,1348531215.687 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:00:16.097Z,1348531216.097 [CBIT](INFO): Clearing failed count for component AHRS_sp3003D
2012-09-25T00:00:16.097Z,1348531216.097 [AHRS_sp3003D] No Fault, FailCount= 5
2012-09-25T00:00:16.837Z,1348531216.838 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-25T00:00:17.688Z,1348531217.688 [DVL_micro](INFO): pause:Powering down
2012-09-25T00:00:17.959Z,1348531217.959 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2012-09-25T00:00:17.959Z,1348531217.959 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2012-09-25T00:00:17.959Z,1348531217.959 [AHRS_sp3003D] Hardware Fault, FailCount= 1
2012-09-25T00:00:17.959Z,1348531217.959 [AHRS_sp3003D](ERROR): Hardware Fault
2012-09-25T00:00:18.035Z,1348531218.035 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2012-09-25T00:00:18.091Z,1348531218.091 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:00:18.091Z,1348531218.092 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:00:18.092Z,1348531218.092 [DVL_micro](INFO): resume:Powering up
2012-09-25T00:00:18.092Z,1348531218.092 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:00:18.156Z,1348531218.156 [AHRS_sp3003D](INFO): Powering down
2012-09-25T00:00:18.294Z,1348531218.294 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2012-09-25T00:00:18.294Z,1348531218.294 [AHRS_sp3003D] No Fault, FailCount= 1
2012-09-25T00:00:19.342Z,1348531219.342 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-25T00:00:20.099Z,1348531220.099 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:00:20.099Z,1348531220.099 [DVL_micro](INFO): Querying output modes
2012-09-25T00:00:20.099Z,1348531220.099 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:00:20.459Z,1348531220.459 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2012-09-25T00:00:20.459Z,1348531220.459 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2012-09-25T00:00:20.459Z,1348531220.459 [AHRS_sp3003D] Hardware Fault, FailCount= 2
2012-09-25T00:00:20.459Z,1348531220.459 [AHRS_sp3003D](ERROR): Hardware Fault
2012-09-25T00:00:20.518Z,1348531220.518 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2012-09-25T00:00:20.642Z,1348531220.642 [AHRS_sp3003D](INFO): Powering down
2012-09-25T00:00:20.785Z,1348531220.785 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2012-09-25T00:00:20.785Z,1348531220.786 [AHRS_sp3003D] No Fault, FailCount= 2
2012-09-25T00:00:21.838Z,1348531221.838 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-25T00:00:22.103Z,1348531222.103 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:00:22.103Z,1348531222.103 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:00:22.103Z,1348531222.103 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:00:22.955Z,1348531222.955 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2012-09-25T00:00:22.955Z,1348531222.955 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2012-09-25T00:00:22.955Z,1348531222.955 [AHRS_sp3003D] Hardware Fault, FailCount= 3
2012-09-25T00:00:22.955Z,1348531222.955 [AHRS_sp3003D](ERROR): Hardware Fault
2012-09-25T00:00:23.005Z,1348531223.005 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2012-09-25T00:00:23.104Z,1348531223.104 [AHRS_sp3003D](INFO): Powering down
2012-09-25T00:00:23.274Z,1348531223.274 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2012-09-25T00:00:23.274Z,1348531223.274 [AHRS_sp3003D] No Fault, FailCount= 3
2012-09-25T00:00:24.107Z,1348531224.107 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:00:24.109Z,1348531224.109 [DVL_micro](INFO): pause:Powering down
2012-09-25T00:00:24.293Z,1348531224.293 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-25T00:00:24.719Z,1348531224.719 [NAL9602](INFO): NAL9602 initialized
2012-09-25T00:00:25.411Z,1348531225.411 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2012-09-25T00:00:25.411Z,1348531225.411 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2012-09-25T00:00:25.411Z,1348531225.411 [AHRS_sp3003D] Hardware Fault, FailCount= 4
2012-09-25T00:00:25.411Z,1348531225.411 [AHRS_sp3003D](ERROR): Hardware Fault
2012-09-25T00:00:25.503Z,1348531225.503 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2012-09-25T00:00:25.594Z,1348531225.593 [AHRS_sp3003D](INFO): Powering down
2012-09-25T00:00:25.727Z,1348531225.727 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:00:25.728Z,1348531225.728 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:00:25.728Z,1348531225.728 [DVL_micro](INFO): resume:Powering up
2012-09-25T00:00:25.728Z,1348531225.728 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:00:25.734Z,1348531225.734 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2012-09-25T00:00:25.734Z,1348531225.734 [AHRS_sp3003D] No Fault, FailCount= 4
2012-09-25T00:00:26.782Z,1348531226.782 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-25T00:00:27.736Z,1348531227.736 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:00:27.736Z,1348531227.736 [DVL_micro](INFO): Querying output modes
2012-09-25T00:00:27.736Z,1348531227.736 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:00:27.899Z,1348531227.899 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2012-09-25T00:00:27.899Z,1348531227.899 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2012-09-25T00:00:27.899Z,1348531227.899 [AHRS_sp3003D] Hardware Fault, FailCount= 5
2012-09-25T00:00:27.899Z,1348531227.899 [AHRS_sp3003D](ERROR): Hardware Fault
2012-09-25T00:00:27.965Z,1348531227.966 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2012-09-25T00:00:27.966Z,1348531227.966 [CBIT](CRITICAL): Hardware Fault in component: AHRS_sp3003D
2012-09-25T00:00:28.072Z,1348531228.072 [AHRS_sp3003D](INFO): Powering down
2012-09-25T00:00:29.739Z,1348531229.739 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:00:29.739Z,1348531229.739 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:00:29.739Z,1348531229.739 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:00:31.743Z,1348531231.743 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:00:31.744Z,1348531231.744 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:00:31.744Z,1348531231.744 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:00:33.751Z,1348531233.751 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:00:33.752Z,1348531233.751 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:00:35.754Z,1348531235.754 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:00:35.755Z,1348531235.755 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:00:35.755Z,1348531235.755 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:00:37.759Z,1348531237.759 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:00:37.759Z,1348531237.759 [DVL_micro](INFO): Querying output modes
2012-09-25T00:00:37.759Z,1348531237.759 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:00:39.763Z,1348531239.763 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:00:39.763Z,1348531239.763 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:00:39.763Z,1348531239.763 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:00:41.767Z,1348531241.767 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:00:43.771Z,1348531243.771 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:00:43.771Z,1348531243.771 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:00:45.779Z,1348531245.779 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:00:45.779Z,1348531245.779 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:00:47.783Z,1348531247.783 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:00:47.783Z,1348531247.783 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:00:47.783Z,1348531247.783 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:00:49.799Z,1348531249.799 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:00:49.799Z,1348531249.799 [DVL_micro](INFO): Querying output modes
2012-09-25T00:00:49.799Z,1348531249.799 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:00:51.803Z,1348531251.803 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:00:51.803Z,1348531251.803 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:00:51.803Z,1348531251.803 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:00:53.807Z,1348531253.807 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:00:55.811Z,1348531255.811 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:00:55.811Z,1348531255.811 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:00:57.819Z,1348531257.819 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:00:57.819Z,1348531257.819 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:00:59.823Z,1348531259.823 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:00:59.823Z,1348531259.823 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:00:59.823Z,1348531259.823 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:01:01.826Z,1348531261.826 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:01:01.827Z,1348531261.827 [DVL_micro](INFO): Querying output modes
2012-09-25T00:01:01.827Z,1348531261.827 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:01:03.831Z,1348531263.831 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:01:03.831Z,1348531263.831 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:01:03.831Z,1348531263.831 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:01:05.835Z,1348531265.835 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:01:07.839Z,1348531267.839 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:01:07.839Z,1348531267.839 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:01:09.847Z,1348531269.847 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:01:09.847Z,1348531269.847 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:01:11.851Z,1348531271.851 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:01:11.851Z,1348531271.851 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:01:11.851Z,1348531271.851 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:01:13.855Z,1348531273.855 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:01:13.855Z,1348531273.855 [DVL_micro](INFO): Querying output modes
2012-09-25T00:01:13.855Z,1348531273.855 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:01:15.859Z,1348531275.859 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:01:15.859Z,1348531275.859 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:01:15.859Z,1348531275.859 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:01:17.863Z,1348531277.863 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:01:19.864Z,1348531279.864 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:01:19.864Z,1348531279.864 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:01:21.871Z,1348531281.871 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:01:21.871Z,1348531281.871 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:01:23.875Z,1348531283.875 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:01:23.875Z,1348531283.875 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:01:23.875Z,1348531283.875 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:01:25.879Z,1348531285.879 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:01:25.879Z,1348531285.879 [DVL_micro](INFO): Querying output modes
2012-09-25T00:01:25.879Z,1348531285.879 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:01:27.883Z,1348531287.883 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:01:27.883Z,1348531287.883 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:01:27.883Z,1348531287.883 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:01:29.887Z,1348531289.887 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:01:31.891Z,1348531291.891 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:01:31.891Z,1348531291.891 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:01:33.899Z,1348531293.899 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:01:33.899Z,1348531293.899 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:01:35.903Z,1348531295.903 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:01:35.903Z,1348531295.903 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:01:35.903Z,1348531295.903 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:01:37.907Z,1348531297.907 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:01:37.907Z,1348531297.907 [DVL_micro](INFO): Querying output modes
2012-09-25T00:01:37.907Z,1348531297.907 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:01:39.910Z,1348531299.911 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:01:39.911Z,1348531299.911 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:01:39.911Z,1348531299.911 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:01:41.915Z,1348531301.915 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:01:43.919Z,1348531303.919 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:01:43.919Z,1348531303.919 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:01:45.927Z,1348531305.927 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:01:45.927Z,1348531305.927 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:01:47.931Z,1348531307.931 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:01:47.931Z,1348531307.931 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:01:47.931Z,1348531307.931 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:01:49.935Z,1348531309.935 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:01:49.935Z,1348531309.935 [DVL_micro](INFO): Querying output modes
2012-09-25T00:01:49.935Z,1348531309.935 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:01:51.939Z,1348531311.939 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:01:51.939Z,1348531311.939 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:01:51.939Z,1348531311.939 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:01:53.943Z,1348531313.943 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:01:55.948Z,1348531315.948 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:01:55.948Z,1348531315.948 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:01:57.955Z,1348531317.955 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:01:57.956Z,1348531317.955 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:01:59.959Z,1348531319.959 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:01:59.959Z,1348531319.959 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:01:59.959Z,1348531319.959 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:02:01.963Z,1348531321.963 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:02:01.963Z,1348531321.963 [DVL_micro](INFO): Querying output modes
2012-09-25T00:02:01.963Z,1348531321.963 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:02:03.966Z,1348531323.967 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:02:03.967Z,1348531323.967 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:02:03.967Z,1348531323.967 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:02:05.971Z,1348531325.971 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:02:07.975Z,1348531327.975 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:02:07.975Z,1348531327.975 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:02:09.983Z,1348531329.983 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:02:09.983Z,1348531329.983 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:02:11.987Z,1348531331.987 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:02:11.987Z,1348531331.987 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:02:11.987Z,1348531331.987 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:02:13.991Z,1348531333.991 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:02:13.991Z,1348531333.991 [DVL_micro](INFO): Querying output modes
2012-09-25T00:02:13.991Z,1348531333.991 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:02:15.995Z,1348531335.995 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:02:15.995Z,1348531335.995 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:02:15.995Z,1348531335.995 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:02:17.999Z,1348531337.999 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:02:20.003Z,1348531340.003 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:02:20.003Z,1348531340.003 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:02:22.011Z,1348531342.011 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:02:22.011Z,1348531342.011 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:02:24.015Z,1348531344.015 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:02:24.015Z,1348531344.015 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:02:24.015Z,1348531344.015 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:02:26.019Z,1348531346.019 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:02:26.019Z,1348531346.019 [DVL_micro](INFO): Querying output modes
2012-09-25T00:02:26.019Z,1348531346.019 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:02:28.022Z,1348531348.022 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:02:28.022Z,1348531348.022 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:02:28.022Z,1348531348.022 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:02:30.031Z,1348531350.031 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:02:32.035Z,1348531352.035 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:02:32.035Z,1348531352.035 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:02:34.043Z,1348531354.043 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:02:34.043Z,1348531354.043 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:02:36.047Z,1348531356.047 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:02:36.047Z,1348531356.047 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:02:36.047Z,1348531356.047 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:02:38.051Z,1348531358.051 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:02:38.051Z,1348531358.051 [DVL_micro](INFO): Querying output modes
2012-09-25T00:02:38.051Z,1348531358.051 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:02:40.055Z,1348531360.055 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:02:40.055Z,1348531360.055 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:02:40.055Z,1348531360.055 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:02:42.059Z,1348531362.059 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:02:44.063Z,1348531364.063 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:02:44.063Z,1348531364.063 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:02:46.071Z,1348531366.071 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:02:46.071Z,1348531366.071 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:02:48.075Z,1348531368.075 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:02:48.075Z,1348531368.075 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:02:48.075Z,1348531368.075 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:02:50.080Z,1348531370.080 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:02:50.080Z,1348531370.080 [DVL_micro](INFO): Querying output modes
2012-09-25T00:02:50.081Z,1348531370.081 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:02:52.083Z,1348531372.083 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:02:52.083Z,1348531372.083 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:02:52.083Z,1348531372.083 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:02:54.087Z,1348531374.087 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:02:56.091Z,1348531376.091 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:02:56.091Z,1348531376.092 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:02:58.099Z,1348531378.099 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:02:58.099Z,1348531378.099 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:03:00.103Z,1348531380.103 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:03:00.103Z,1348531380.103 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:03:00.103Z,1348531380.103 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:03:02.107Z,1348531382.107 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:03:02.107Z,1348531382.107 [DVL_micro](INFO): Querying output modes
2012-09-25T00:03:02.107Z,1348531382.107 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:03:04.111Z,1348531384.111 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:03:04.111Z,1348531384.111 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:03:04.111Z,1348531384.111 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:03:06.115Z,1348531386.115 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:03:08.119Z,1348531388.119 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:03:08.119Z,1348531388.119 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:03:10.127Z,1348531390.127 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:03:10.127Z,1348531390.127 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:03:12.131Z,1348531392.131 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:03:12.131Z,1348531392.131 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:03:12.131Z,1348531392.131 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:03:14.135Z,1348531394.135 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:03:14.135Z,1348531394.135 [DVL_micro](INFO): Querying output modes
2012-09-25T00:03:14.136Z,1348531394.136 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:03:16.139Z,1348531396.139 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:03:16.139Z,1348531396.139 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:03:16.139Z,1348531396.139 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:03:18.143Z,1348531398.143 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:03:20.147Z,1348531400.147 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:03:20.148Z,1348531400.148 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:03:22.155Z,1348531402.155 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:03:22.155Z,1348531402.155 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:03:24.159Z,1348531404.159 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:03:24.159Z,1348531404.159 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:03:24.159Z,1348531404.159 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:03:26.163Z,1348531406.163 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:03:26.163Z,1348531406.163 [DVL_micro](INFO): Querying output modes
2012-09-25T00:03:26.163Z,1348531406.163 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:03:28.169Z,1348531408.169 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:03:28.169Z,1348531408.169 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:03:28.169Z,1348531408.169 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:03:30.184Z,1348531410.184 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:03:32.187Z,1348531412.187 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:03:32.187Z,1348531412.187 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:03:34.195Z,1348531414.195 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:03:34.196Z,1348531414.195 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:03:36.199Z,1348531416.199 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:03:36.199Z,1348531416.199 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:03:36.199Z,1348531416.199 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:03:38.203Z,1348531418.203 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:03:38.203Z,1348531418.203 [DVL_micro](INFO): Querying output modes
2012-09-25T00:03:38.203Z,1348531418.203 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:03:40.207Z,1348531420.207 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:03:40.207Z,1348531420.207 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:03:40.207Z,1348531420.207 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:03:42.211Z,1348531422.211 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:03:44.215Z,1348531424.215 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:03:44.215Z,1348531424.215 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:03:46.223Z,1348531426.223 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:03:46.223Z,1348531426.223 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:03:48.227Z,1348531428.227 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:03:48.227Z,1348531428.227 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:03:48.227Z,1348531428.227 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:03:50.231Z,1348531430.231 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:03:50.231Z,1348531430.231 [DVL_micro](INFO): Querying output modes
2012-09-25T00:03:50.231Z,1348531430.231 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:03:52.235Z,1348531432.235 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:03:52.235Z,1348531432.235 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:03:52.235Z,1348531432.235 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:03:54.239Z,1348531434.239 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:03:56.243Z,1348531436.243 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:03:56.243Z,1348531436.243 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:03:58.251Z,1348531438.251 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:03:58.252Z,1348531438.251 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:04:00.258Z,1348531440.258 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:04:00.258Z,1348531440.258 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:04:00.258Z,1348531440.258 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:04:02.259Z,1348531442.259 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:04:02.259Z,1348531442.259 [DVL_micro](INFO): Querying output modes
2012-09-25T00:04:02.259Z,1348531442.259 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:04:04.263Z,1348531444.263 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:04:04.263Z,1348531444.263 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:04:04.263Z,1348531444.263 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:04:06.267Z,1348531446.267 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:04:08.271Z,1348531448.271 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:04:08.271Z,1348531448.271 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:04:10.279Z,1348531450.279 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:04:10.279Z,1348531450.279 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:04:12.283Z,1348531452.283 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:04:12.283Z,1348531452.283 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:04:12.283Z,1348531452.283 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:04:14.287Z,1348531454.287 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:04:14.287Z,1348531454.287 [DVL_micro](INFO): Querying output modes
2012-09-25T00:04:14.287Z,1348531454.287 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:04:16.291Z,1348531456.291 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:04:16.291Z,1348531456.291 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:04:16.291Z,1348531456.291 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:04:18.295Z,1348531458.295 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:04:20.299Z,1348531460.299 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:04:20.299Z,1348531460.299 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:04:22.307Z,1348531462.307 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:04:22.308Z,1348531462.307 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:04:24.311Z,1348531464.311 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:04:24.311Z,1348531464.311 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:04:24.311Z,1348531464.311 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:04:26.315Z,1348531466.315 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:04:26.315Z,1348531466.315 [DVL_micro](INFO): Querying output modes
2012-09-25T00:04:26.315Z,1348531466.315 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:04:28.319Z,1348531468.319 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:04:28.319Z,1348531468.319 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:04:28.319Z,1348531468.319 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:04:30.323Z,1348531470.323 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:04:32.327Z,1348531472.327 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:04:32.327Z,1348531472.327 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:04:34.335Z,1348531474.335 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:04:34.335Z,1348531474.335 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:04:36.345Z,1348531476.345 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:04:36.345Z,1348531476.345 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:04:36.346Z,1348531476.346 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:04:38.347Z,1348531478.347 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:04:38.347Z,1348531478.347 [DVL_micro](INFO): Querying output modes
2012-09-25T00:04:38.347Z,1348531478.347 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:04:40.351Z,1348531480.351 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:04:40.351Z,1348531480.351 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:04:40.351Z,1348531480.351 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:04:42.354Z,1348531482.354 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:04:44.355Z,1348531484.355 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:04:44.355Z,1348531484.355 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:04:46.363Z,1348531486.363 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:04:46.363Z,1348531486.364 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:04:48.367Z,1348531488.367 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:04:48.367Z,1348531488.367 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:04:48.367Z,1348531488.367 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:04:50.371Z,1348531490.371 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:04:50.371Z,1348531490.371 [DVL_micro](INFO): Querying output modes
2012-09-25T00:04:50.371Z,1348531490.371 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:04:52.375Z,1348531492.375 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:04:52.375Z,1348531492.375 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:04:52.375Z,1348531492.375 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:04:54.379Z,1348531494.379 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:04:56.383Z,1348531496.383 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:04:56.383Z,1348531496.383 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:04:58.391Z,1348531498.391 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:04:58.391Z,1348531498.391 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:05:00.395Z,1348531500.395 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:05:00.395Z,1348531500.395 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:05:00.395Z,1348531500.395 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:05:02.399Z,1348531502.399 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:05:02.399Z,1348531502.399 [DVL_micro](INFO): Querying output modes
2012-09-25T00:05:02.399Z,1348531502.399 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:05:04.403Z,1348531504.403 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:05:04.403Z,1348531504.403 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:05:04.403Z,1348531504.403 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:05:06.407Z,1348531506.407 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:05:08.411Z,1348531508.411 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:05:08.412Z,1348531508.412 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:05:10.419Z,1348531510.419 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:05:10.419Z,1348531510.420 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:05:10.794Z,1348531510.794 [CBIT](INFO): Clearing failed count for component RudderServo
2012-09-25T00:05:10.799Z,1348531510.799 [RudderServo] No Fault, FailCount= 3
2012-09-25T00:05:11.559Z,1348531511.559 [RudderServo](DEBUG): Initializing EZServoServo.
2012-09-25T00:05:11.680Z,1348531511.680 [RudderServo](DEBUG): Initialization error opening port: port not yet opened
2012-09-25T00:05:11.680Z,1348531511.680 [RudderServo](DEBUG): Initializing RudderServo.
2012-09-25T00:05:12.423Z,1348531512.422 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:05:12.423Z,1348531512.423 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:05:12.423Z,1348531512.423 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:05:14.427Z,1348531514.427 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:05:14.427Z,1348531514.427 [DVL_micro](INFO): Querying output modes
2012-09-25T00:05:14.427Z,1348531514.427 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:05:16.431Z,1348531516.431 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:05:16.431Z,1348531516.431 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:05:16.431Z,1348531516.431 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:05:16.647Z,1348531516.647 [RudderServo](FAULT): Overload Error
2012-09-25T00:05:16.647Z,1348531516.647 [RudderServo] Hardware Fault, FailCount= 1
2012-09-25T00:05:16.647Z,1348531516.647 [RudderServo](ERROR): Hardware Fault
2012-09-25T00:05:16.678Z,1348531516.678 [CBIT](ERROR): Hardware Fault in component: RudderServo
2012-09-25T00:05:17.031Z,1348531517.031 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2012-09-25T00:05:17.031Z,1348531517.031 [RudderServo](INFO): Powering down
2012-09-25T00:05:17.034Z,1348531517.034 [CBIT](INFO): Clearing failed state for component RudderServo
2012-09-25T00:05:17.034Z,1348531517.034 [RudderServo] No Fault, FailCount= 1
2012-09-25T00:05:17.799Z,1348531517.799 [RudderServo](DEBUG): Initializing EZServoServo.
2012-09-25T00:05:17.920Z,1348531517.920 [RudderServo](DEBUG): Initialization error opening port: port not yet opened
2012-09-25T00:05:17.920Z,1348531517.920 [RudderServo](DEBUG): Initializing RudderServo.
2012-09-25T00:05:18.435Z,1348531518.435 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:05:20.439Z,1348531520.439 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:05:20.440Z,1348531520.440 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:05:22.447Z,1348531522.447 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:05:22.447Z,1348531522.447 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:05:22.967Z,1348531522.967 [RudderServo](FAULT): Overload Error
2012-09-25T00:05:22.967Z,1348531522.967 [RudderServo] Hardware Fault, FailCount= 2
2012-09-25T00:05:22.967Z,1348531522.967 [RudderServo](ERROR): Hardware Fault
2012-09-25T00:05:22.994Z,1348531522.994 [CBIT](ERROR): Hardware Fault in component: RudderServo
2012-09-25T00:05:23.133Z,1348531523.133 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2012-09-25T00:05:23.133Z,1348531523.133 [RudderServo](INFO): Powering down
2012-09-25T00:05:23.136Z,1348531523.136 [CBIT](INFO): Clearing failed state for component RudderServo
2012-09-25T00:05:23.136Z,1348531523.136 [RudderServo] No Fault, FailCount= 2
2012-09-25T00:05:23.930Z,1348531523.930 [RudderServo](DEBUG): Initializing EZServoServo.
2012-09-25T00:05:24.048Z,1348531524.048 [RudderServo](DEBUG): Initialization error opening port: port not yet opened
2012-09-25T00:05:24.048Z,1348531524.048 [RudderServo](DEBUG): Initializing RudderServo.
2012-09-25T00:05:24.451Z,1348531524.451 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:05:24.451Z,1348531524.451 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:05:24.451Z,1348531524.451 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:05:26.455Z,1348531526.455 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:05:26.455Z,1348531526.455 [DVL_micro](INFO): Querying output modes
2012-09-25T00:05:26.455Z,1348531526.455 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:05:28.186Z,1348531528.186 [CBIT](INFO): Clearing failed count for component AHRS_sp3003D
2012-09-25T00:05:28.186Z,1348531528.186 [AHRS_sp3003D] No Fault, FailCount= 5
2012-09-25T00:05:28.459Z,1348531528.459 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:05:28.459Z,1348531528.459 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:05:28.459Z,1348531528.459 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:05:28.913Z,1348531528.913 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-25T00:05:28.995Z,1348531528.995 [RudderServo](FAULT): Overload Error
2012-09-25T00:05:28.995Z,1348531528.995 [RudderServo] Hardware Fault, FailCount= 3
2012-09-25T00:05:28.995Z,1348531528.995 [RudderServo](ERROR): Hardware Fault
2012-09-25T00:05:29.025Z,1348531529.025 [CBIT](ERROR): Hardware Fault in component: RudderServo
2012-09-25T00:05:29.026Z,1348531529.026 [CBIT](CRITICAL): Hardware Fault in component: RudderServo
2012-09-25T00:05:29.384Z,1348531529.384 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2012-09-25T00:05:29.384Z,1348531529.384 [RudderServo](INFO): Powering down
2012-09-25T00:05:30.035Z,1348531530.035 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2012-09-25T00:05:30.035Z,1348531530.035 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2012-09-25T00:05:30.035Z,1348531530.035 [AHRS_sp3003D] Hardware Fault, FailCount= 1
2012-09-25T00:05:30.035Z,1348531530.035 [AHRS_sp3003D](ERROR): Hardware Fault
2012-09-25T00:05:30.093Z,1348531530.093 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2012-09-25T00:05:30.179Z,1348531530.179 [AHRS_sp3003D](INFO): Powering down
2012-09-25T00:05:30.342Z,1348531530.342 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2012-09-25T00:05:30.342Z,1348531530.342 [AHRS_sp3003D] No Fault, FailCount= 1
2012-09-25T00:05:30.463Z,1348531530.463 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:05:31.582Z,1348531531.582 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-25T00:05:32.468Z,1348531532.468 [DVL_micro](INFO): pause:Powering down
2012-09-25T00:05:32.699Z,1348531532.699 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2012-09-25T00:05:32.699Z,1348531532.699 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2012-09-25T00:05:32.699Z,1348531532.699 [AHRS_sp3003D] Hardware Fault, FailCount= 2
2012-09-25T00:05:32.699Z,1348531532.699 [AHRS_sp3003D](ERROR): Hardware Fault
2012-09-25T00:05:32.822Z,1348531532.822 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2012-09-25T00:05:32.871Z,1348531532.871 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:05:32.872Z,1348531532.872 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:05:32.872Z,1348531532.872 [DVL_micro](INFO): resume:Powering up
2012-09-25T00:05:32.872Z,1348531532.872 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:05:32.938Z,1348531532.938 [AHRS_sp3003D](INFO): Powering down
2012-09-25T00:05:33.079Z,1348531533.079 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2012-09-25T00:05:33.079Z,1348531533.079 [AHRS_sp3003D] No Fault, FailCount= 2
2012-09-25T00:05:34.125Z,1348531534.125 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-25T00:05:34.879Z,1348531534.879 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:05:34.879Z,1348531534.879 [DVL_micro](INFO): Querying output modes
2012-09-25T00:05:34.879Z,1348531534.879 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:05:35.247Z,1348531535.247 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2012-09-25T00:05:35.247Z,1348531535.247 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2012-09-25T00:05:35.247Z,1348531535.247 [AHRS_sp3003D] Hardware Fault, FailCount= 3
2012-09-25T00:05:35.247Z,1348531535.247 [AHRS_sp3003D](ERROR): Hardware Fault
2012-09-25T00:05:35.808Z,1348531535.808 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2012-09-25T00:05:35.929Z,1348531535.930 [AHRS_sp3003D](INFO): Powering down
2012-09-25T00:05:36.070Z,1348531536.070 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2012-09-25T00:05:36.070Z,1348531536.070 [AHRS_sp3003D] No Fault, FailCount= 3
2012-09-25T00:05:36.883Z,1348531536.883 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:05:36.883Z,1348531536.883 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:05:36.883Z,1348531536.883 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:05:37.101Z,1348531537.101 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-25T00:05:38.219Z,1348531538.219 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2012-09-25T00:05:38.219Z,1348531538.219 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2012-09-25T00:05:38.219Z,1348531538.219 [AHRS_sp3003D] Hardware Fault, FailCount= 4
2012-09-25T00:05:38.219Z,1348531538.219 [AHRS_sp3003D](ERROR): Hardware Fault
2012-09-25T00:05:38.278Z,1348531538.278 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2012-09-25T00:05:38.372Z,1348531538.372 [AHRS_sp3003D](INFO): Powering down
2012-09-25T00:05:38.536Z,1348531538.536 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2012-09-25T00:05:38.536Z,1348531538.536 [AHRS_sp3003D] No Fault, FailCount= 4
2012-09-25T00:05:38.887Z,1348531538.887 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:05:38.890Z,1348531538.889 [DVL_micro](INFO): pause:Powering down
2012-09-25T00:05:39.805Z,1348531539.805 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-25T00:05:40.907Z,1348531540.907 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2012-09-25T00:05:40.907Z,1348531540.907 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2012-09-25T00:05:40.907Z,1348531540.907 [AHRS_sp3003D] Hardware Fault, FailCount= 5
2012-09-25T00:05:40.907Z,1348531540.907 [AHRS_sp3003D](ERROR): Hardware Fault
2012-09-25T00:05:40.911Z,1348531540.911 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:05:40.912Z,1348531540.912 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:05:40.912Z,1348531540.912 [DVL_micro](INFO): resume:Powering up
2012-09-25T00:05:40.912Z,1348531540.912 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:05:40.970Z,1348531540.970 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2012-09-25T00:05:40.975Z,1348531540.975 [CBIT](CRITICAL): Hardware Fault in component: AHRS_sp3003D
2012-09-25T00:05:41.087Z,1348531541.087 [AHRS_sp3003D](INFO): Powering down
2012-09-25T00:05:42.919Z,1348531542.919 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:05:42.919Z,1348531542.919 [DVL_micro](INFO): Querying output modes
2012-09-25T00:05:42.919Z,1348531542.919 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:05:44.925Z,1348531544.925 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:05:44.925Z,1348531544.925 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:05:44.925Z,1348531544.925 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:05:46.931Z,1348531546.931 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:05:46.932Z,1348531546.932 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:05:46.932Z,1348531546.932 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:05:48.939Z,1348531548.939 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:05:48.939Z,1348531548.939 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:05:50.943Z,1348531550.943 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:05:50.943Z,1348531550.943 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:05:50.943Z,1348531550.943 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:05:52.947Z,1348531552.947 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:05:52.947Z,1348531552.947 [DVL_micro](INFO): Querying output modes
2012-09-25T00:05:52.947Z,1348531552.947 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:05:54.951Z,1348531554.951 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:05:54.951Z,1348531554.951 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:05:54.952Z,1348531554.952 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:05:56.955Z,1348531556.955 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:05:58.959Z,1348531558.959 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:05:58.959Z,1348531558.959 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:06:00.967Z,1348531560.967 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:06:00.967Z,1348531560.967 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:06:02.971Z,1348531562.971 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:06:02.971Z,1348531562.971 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:06:02.971Z,1348531562.971 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:06:04.975Z,1348531564.975 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:06:04.975Z,1348531564.975 [DVL_micro](INFO): Querying output modes
2012-09-25T00:06:04.975Z,1348531564.975 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:06:06.979Z,1348531566.979 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:06:06.979Z,1348531566.979 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:06:06.979Z,1348531566.979 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:06:08.983Z,1348531568.983 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:06:10.987Z,1348531570.987 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:06:10.987Z,1348531570.987 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:06:12.995Z,1348531572.995 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:06:12.995Z,1348531572.995 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:06:14.999Z,1348531574.999 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:06:14.999Z,1348531574.999 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:06:14.999Z,1348531574.999 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:06:17.003Z,1348531577.003 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:06:17.003Z,1348531577.003 [DVL_micro](INFO): Querying output modes
2012-09-25T00:06:17.003Z,1348531577.003 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:06:19.007Z,1348531579.007 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:06:19.007Z,1348531579.007 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:06:19.007Z,1348531579.007 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:06:21.011Z,1348531581.011 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:06:23.015Z,1348531583.015 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:06:23.015Z,1348531583.016 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:06:25.023Z,1348531585.023 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:06:25.023Z,1348531585.023 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:06:27.027Z,1348531587.027 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:06:27.027Z,1348531587.027 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:06:27.027Z,1348531587.027 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:06:29.031Z,1348531589.031 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:06:29.031Z,1348531589.031 [DVL_micro](INFO): Querying output modes
2012-09-25T00:06:29.031Z,1348531589.031 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:06:31.035Z,1348531591.035 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:06:31.035Z,1348531591.035 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:06:31.035Z,1348531591.035 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:06:33.039Z,1348531593.039 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:06:35.043Z,1348531595.043 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:06:35.043Z,1348531595.043 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:06:37.051Z,1348531597.051 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:06:37.051Z,1348531597.051 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:06:39.055Z,1348531599.055 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:06:39.055Z,1348531599.055 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:06:39.055Z,1348531599.055 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:06:41.059Z,1348531601.059 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:06:41.059Z,1348531601.059 [DVL_micro](INFO): Querying output modes
2012-09-25T00:06:41.059Z,1348531601.059 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:06:43.063Z,1348531603.063 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:06:43.063Z,1348531603.063 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:06:43.063Z,1348531603.063 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:06:45.067Z,1348531605.067 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:06:47.082Z,1348531607.082 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:06:47.082Z,1348531607.082 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:06:49.091Z,1348531609.091 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:06:49.091Z,1348531609.091 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:06:51.095Z,1348531611.095 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:06:51.095Z,1348531611.095 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:06:51.095Z,1348531611.095 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:06:53.099Z,1348531613.099 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:06:53.099Z,1348531613.099 [DVL_micro](INFO): Querying output modes
2012-09-25T00:06:53.099Z,1348531613.099 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:06:55.101Z,1348531615.101 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:06:55.101Z,1348531615.102 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:06:55.102Z,1348531615.102 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:06:57.107Z,1348531617.107 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:06:59.111Z,1348531619.111 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:06:59.111Z,1348531619.111 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:07:01.119Z,1348531621.119 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:07:01.119Z,1348531621.119 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:07:03.123Z,1348531623.123 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:07:03.123Z,1348531623.123 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:07:03.123Z,1348531623.123 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:07:05.127Z,1348531625.127 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:07:05.127Z,1348531625.127 [DVL_micro](INFO): Querying output modes
2012-09-25T00:07:05.127Z,1348531625.127 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:07:07.131Z,1348531627.131 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:07:07.131Z,1348531627.131 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:07:07.131Z,1348531627.131 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:07:09.135Z,1348531629.135 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:07:11.139Z,1348531631.139 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:07:11.140Z,1348531631.139 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:07:13.147Z,1348531633.147 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:07:13.147Z,1348531633.147 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:07:15.151Z,1348531635.151 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:07:15.151Z,1348531635.151 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:07:15.151Z,1348531635.151 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:07:17.155Z,1348531637.155 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:07:17.155Z,1348531637.155 [DVL_micro](INFO): Querying output modes
2012-09-25T00:07:17.155Z,1348531637.155 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:07:19.159Z,1348531639.159 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:07:19.159Z,1348531639.159 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:07:19.159Z,1348531639.159 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:07:21.169Z,1348531641.169 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:07:23.175Z,1348531643.175 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:07:23.175Z,1348531643.175 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:07:25.183Z,1348531645.183 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:07:25.184Z,1348531645.184 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:07:27.187Z,1348531647.187 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:07:27.187Z,1348531647.187 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:07:27.187Z,1348531647.187 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:07:29.191Z,1348531649.191 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:07:29.191Z,1348531649.191 [DVL_micro](INFO): Querying output modes
2012-09-25T00:07:29.191Z,1348531649.191 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:07:31.195Z,1348531651.195 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:07:31.195Z,1348531651.195 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:07:31.195Z,1348531651.195 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:07:33.199Z,1348531653.199 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:07:35.203Z,1348531655.203 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:07:35.203Z,1348531655.203 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:07:37.211Z,1348531657.211 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:07:37.212Z,1348531657.212 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:07:39.215Z,1348531659.215 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:07:39.215Z,1348531659.215 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:07:39.215Z,1348531659.215 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:07:41.219Z,1348531661.219 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:07:41.219Z,1348531661.219 [DVL_micro](INFO): Querying output modes
2012-09-25T00:07:41.219Z,1348531661.219 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:07:43.223Z,1348531663.223 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:07:43.223Z,1348531663.223 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:07:43.223Z,1348531663.223 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:07:45.227Z,1348531665.227 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:07:47.231Z,1348531667.231 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:07:47.231Z,1348531667.231 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:07:49.239Z,1348531669.239 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:07:49.239Z,1348531669.239 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:07:51.247Z,1348531671.247 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:07:51.247Z,1348531671.247 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:07:51.247Z,1348531671.247 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:07:53.251Z,1348531673.251 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:07:53.251Z,1348531673.251 [DVL_micro](INFO): Querying output modes
2012-09-25T00:07:53.251Z,1348531673.251 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:07:55.257Z,1348531675.257 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:07:55.257Z,1348531675.257 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:07:55.257Z,1348531675.257 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:07:57.259Z,1348531677.259 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:07:59.263Z,1348531679.263 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:07:59.263Z,1348531679.263 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:08:01.271Z,1348531681.271 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:08:01.271Z,1348531681.271 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:08:03.275Z,1348531683.275 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:08:03.275Z,1348531683.275 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:08:03.275Z,1348531683.275 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:08:05.279Z,1348531685.279 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:08:05.279Z,1348531685.279 [DVL_micro](INFO): Querying output modes
2012-09-25T00:08:05.279Z,1348531685.279 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:08:07.282Z,1348531687.283 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:08:07.283Z,1348531687.283 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:08:07.283Z,1348531687.283 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:08:09.286Z,1348531689.286 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:08:11.291Z,1348531691.291 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:08:11.291Z,1348531691.291 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:08:13.299Z,1348531693.299 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:08:13.299Z,1348531693.299 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:08:15.303Z,1348531695.303 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:08:15.303Z,1348531695.303 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:08:15.303Z,1348531695.303 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:08:17.306Z,1348531697.306 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:08:17.307Z,1348531697.307 [DVL_micro](INFO): Querying output modes
2012-09-25T00:08:17.307Z,1348531697.307 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:08:19.310Z,1348531699.310 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:08:19.311Z,1348531699.311 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:08:19.311Z,1348531699.311 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:08:21.315Z,1348531701.315 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:08:23.319Z,1348531703.319 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:08:23.319Z,1348531703.319 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:08:25.327Z,1348531705.327 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:08:25.327Z,1348531705.327 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:08:27.331Z,1348531707.330 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:08:27.331Z,1348531707.331 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:08:27.331Z,1348531707.331 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:08:29.335Z,1348531709.335 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:08:29.335Z,1348531709.335 [DVL_micro](INFO): Querying output modes
2012-09-25T00:08:29.335Z,1348531709.335 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:08:31.339Z,1348531711.339 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:08:31.339Z,1348531711.339 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:08:31.339Z,1348531711.339 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:08:33.343Z,1348531713.342 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:08:35.347Z,1348531715.347 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:08:35.347Z,1348531715.347 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:08:37.355Z,1348531717.355 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:08:37.355Z,1348531717.355 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:08:39.359Z,1348531719.359 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:08:39.359Z,1348531719.359 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:08:39.359Z,1348531719.359 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:08:41.362Z,1348531721.362 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:08:41.363Z,1348531721.363 [DVL_micro](INFO): Querying output modes
2012-09-25T00:08:41.363Z,1348531721.363 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:08:43.366Z,1348531723.366 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:08:43.367Z,1348531723.367 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:08:43.367Z,1348531723.367 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:08:45.370Z,1348531725.371 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:08:47.375Z,1348531727.375 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:08:47.375Z,1348531727.375 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:08:49.383Z,1348531729.383 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:08:49.383Z,1348531729.383 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:08:51.387Z,1348531731.386 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:08:51.387Z,1348531731.387 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:08:51.387Z,1348531731.387 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:08:53.391Z,1348531733.391 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:08:53.391Z,1348531733.391 [DVL_micro](INFO): Querying output modes
2012-09-25T00:08:53.391Z,1348531733.391 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:08:55.395Z,1348531735.395 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:08:55.395Z,1348531735.395 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:08:55.395Z,1348531735.395 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:08:57.398Z,1348531737.398 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:08:59.403Z,1348531739.403 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:08:59.403Z,1348531739.403 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:09:01.411Z,1348531741.411 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:09:01.411Z,1348531741.411 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:09:03.414Z,1348531743.414 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:09:03.415Z,1348531743.415 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:09:03.415Z,1348531743.415 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:09:05.419Z,1348531745.418 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:09:05.419Z,1348531745.419 [DVL_micro](INFO): Querying output modes
2012-09-25T00:09:05.419Z,1348531745.419 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:09:07.423Z,1348531747.423 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:09:07.423Z,1348531747.423 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:09:07.423Z,1348531747.423 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:09:09.426Z,1348531749.427 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:09:11.431Z,1348531751.431 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:09:11.431Z,1348531751.431 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:09:13.439Z,1348531753.439 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:09:13.439Z,1348531753.439 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:09:15.443Z,1348531755.443 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:09:15.443Z,1348531755.443 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:09:15.443Z,1348531755.443 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:09:17.447Z,1348531757.447 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:09:17.447Z,1348531757.447 [DVL_micro](INFO): Querying output modes
2012-09-25T00:09:17.447Z,1348531757.447 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:09:19.451Z,1348531759.451 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:09:19.451Z,1348531759.451 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:09:19.451Z,1348531759.451 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:09:21.455Z,1348531761.455 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:09:23.459Z,1348531763.459 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:09:23.459Z,1348531763.459 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:09:25.467Z,1348531765.467 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:09:25.467Z,1348531765.467 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:09:27.471Z,1348531767.471 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:09:27.471Z,1348531767.471 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:09:27.471Z,1348531767.471 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:09:29.475Z,1348531769.474 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:09:29.475Z,1348531769.475 [DVL_micro](INFO): Querying output modes
2012-09-25T00:09:29.475Z,1348531769.475 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:09:31.479Z,1348531771.478 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:09:31.479Z,1348531771.479 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:09:31.479Z,1348531771.479 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:09:33.482Z,1348531773.483 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:09:35.487Z,1348531775.487 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:09:35.487Z,1348531775.487 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:09:37.495Z,1348531777.495 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:09:37.495Z,1348531777.495 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:09:39.500Z,1348531779.500 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:09:39.500Z,1348531779.500 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:09:39.500Z,1348531779.500 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:09:41.502Z,1348531781.503 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:09:41.503Z,1348531781.503 [DVL_micro](INFO): Querying output modes
2012-09-25T00:09:41.503Z,1348531781.503 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:09:43.507Z,1348531783.507 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:09:43.507Z,1348531783.507 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:09:43.507Z,1348531783.507 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:09:45.511Z,1348531785.510 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:09:47.515Z,1348531787.515 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:09:47.515Z,1348531787.515 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:09:49.523Z,1348531789.523 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:09:49.523Z,1348531789.523 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:09:51.526Z,1348531791.526 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:09:51.527Z,1348531791.527 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:09:51.527Z,1348531791.527 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:09:53.530Z,1348531793.530 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:09:53.531Z,1348531793.531 [DVL_micro](INFO): Querying output modes
2012-09-25T00:09:53.531Z,1348531793.531 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:09:55.535Z,1348531795.535 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:09:55.535Z,1348531795.535 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:09:55.535Z,1348531795.535 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:09:57.539Z,1348531797.539 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:09:59.543Z,1348531799.543 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:09:59.543Z,1348531799.543 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:10:01.553Z,1348531801.553 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:10:01.553Z,1348531801.553 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:10:03.554Z,1348531803.555 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:10:03.555Z,1348531803.555 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:10:03.555Z,1348531803.555 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:10:05.558Z,1348531805.559 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:10:05.559Z,1348531805.559 [DVL_micro](INFO): Querying output modes
2012-09-25T00:10:05.559Z,1348531805.559 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:10:07.562Z,1348531807.562 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:10:07.563Z,1348531807.563 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:10:07.563Z,1348531807.563 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:10:09.567Z,1348531809.566 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:10:11.571Z,1348531811.571 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:10:11.571Z,1348531811.571 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:10:13.579Z,1348531813.579 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:10:13.580Z,1348531813.580 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:10:15.583Z,1348531815.583 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:10:15.583Z,1348531815.583 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:10:15.583Z,1348531815.583 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:10:17.587Z,1348531817.587 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:10:17.587Z,1348531817.587 [DVL_micro](INFO): Querying output modes
2012-09-25T00:10:17.587Z,1348531817.587 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:10:19.593Z,1348531819.593 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:10:19.593Z,1348531819.593 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:10:19.593Z,1348531819.593 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:10:21.595Z,1348531821.595 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:10:23.599Z,1348531823.599 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:10:23.599Z,1348531823.599 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:10:25.607Z,1348531825.607 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:10:25.607Z,1348531825.607 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:10:27.610Z,1348531827.611 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:10:27.611Z,1348531827.611 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:10:27.611Z,1348531827.611 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:10:28.291Z,1348531828.291 [NAL9602](FAULT): GPS failed to acquire within timeout.
2012-09-25T00:10:28.291Z,1348531828.291 [NAL9602] Data Fault, FailCount= 2
2012-09-25T00:10:28.291Z,1348531828.291 [NAL9602](ERROR): Data Fault
2012-09-25T00:10:28.316Z,1348531828.316 [CBIT](ERROR): Data Fault in component: NAL9602
2012-09-25T00:10:28.316Z,1348531828.316 [CBIT](CRITICAL): Data Fault in component: NAL9602
2012-09-25T00:10:28.691Z,1348531828.691 [NAL9602](INFO): Powering down
2012-09-25T00:10:29.614Z,1348531829.615 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:10:29.615Z,1348531829.615 [DVL_micro](INFO): Querying output modes
2012-09-25T00:10:29.615Z,1348531829.615 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:10:31.618Z,1348531831.618 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:10:31.619Z,1348531831.619 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:10:31.619Z,1348531831.619 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:10:33.623Z,1348531833.622 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:10:35.627Z,1348531835.627 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:10:35.627Z,1348531835.627 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:10:37.635Z,1348531837.635 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:10:37.635Z,1348531837.635 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:10:39.638Z,1348531839.638 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:10:39.639Z,1348531839.639 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:10:39.639Z,1348531839.639 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:10:41.114Z,1348531841.114 [CBIT](INFO): Clearing failed count for component AHRS_sp3003D
2012-09-25T00:10:41.114Z,1348531841.114 [AHRS_sp3003D] No Fault, FailCount= 5
2012-09-25T00:10:41.642Z,1348531841.642 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:10:41.643Z,1348531841.643 [DVL_micro](INFO): Querying output modes
2012-09-25T00:10:41.643Z,1348531841.643 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:10:41.889Z,1348531841.889 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-25T00:10:43.007Z,1348531843.007 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2012-09-25T00:10:43.007Z,1348531843.007 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2012-09-25T00:10:43.007Z,1348531843.007 [AHRS_sp3003D] Hardware Fault, FailCount= 1
2012-09-25T00:10:43.007Z,1348531843.007 [AHRS_sp3003D](ERROR): Hardware Fault
2012-09-25T00:10:43.033Z,1348531843.033 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2012-09-25T00:10:43.089Z,1348531843.089 [AHRS_sp3003D](INFO): Powering down
2012-09-25T00:10:43.203Z,1348531843.203 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2012-09-25T00:10:43.203Z,1348531843.203 [AHRS_sp3003D] No Fault, FailCount= 1
2012-09-25T00:10:43.646Z,1348531843.647 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:10:43.647Z,1348531843.647 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:10:43.647Z,1348531843.647 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:10:44.289Z,1348531844.289 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-25T00:10:45.407Z,1348531845.407 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2012-09-25T00:10:45.407Z,1348531845.407 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2012-09-25T00:10:45.407Z,1348531845.407 [AHRS_sp3003D] Hardware Fault, FailCount= 2
2012-09-25T00:10:45.407Z,1348531845.407 [AHRS_sp3003D](ERROR): Hardware Fault
2012-09-25T00:10:45.433Z,1348531845.433 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2012-09-25T00:10:45.489Z,1348531845.489 [AHRS_sp3003D](INFO): Powering down
2012-09-25T00:10:45.605Z,1348531845.605 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2012-09-25T00:10:45.605Z,1348531845.605 [AHRS_sp3003D] No Fault, FailCount= 2
2012-09-25T00:10:45.650Z,1348531845.651 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:10:46.689Z,1348531846.689 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-25T00:10:47.656Z,1348531847.656 [DVL_micro](INFO): pause:Powering down
2012-09-25T00:10:47.807Z,1348531847.806 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2012-09-25T00:10:47.807Z,1348531847.807 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2012-09-25T00:10:47.807Z,1348531847.807 [AHRS_sp3003D] Hardware Fault, FailCount= 3
2012-09-25T00:10:47.807Z,1348531847.807 [AHRS_sp3003D](ERROR): Hardware Fault
2012-09-25T00:10:47.833Z,1348531847.833 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2012-09-25T00:10:47.889Z,1348531847.889 [AHRS_sp3003D](INFO): Powering down
2012-09-25T00:10:48.007Z,1348531848.007 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2012-09-25T00:10:48.007Z,1348531848.007 [AHRS_sp3003D] No Fault, FailCount= 3
2012-09-25T00:10:49.089Z,1348531849.089 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-25T00:10:50.206Z,1348531850.207 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2012-09-25T00:10:50.207Z,1348531850.207 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2012-09-25T00:10:50.207Z,1348531850.207 [AHRS_sp3003D] Hardware Fault, FailCount= 4
2012-09-25T00:10:50.207Z,1348531850.207 [AHRS_sp3003D](ERROR): Hardware Fault
2012-09-25T00:10:50.239Z,1348531850.239 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2012-09-25T00:10:50.289Z,1348531850.289 [AHRS_sp3003D](INFO): Powering down
2012-09-25T00:10:50.407Z,1348531850.407 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2012-09-25T00:10:50.407Z,1348531850.407 [AHRS_sp3003D] No Fault, FailCount= 4
2012-09-25T00:10:51.489Z,1348531851.489 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-25T00:10:52.607Z,1348531852.606 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2012-09-25T00:10:52.607Z,1348531852.607 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2012-09-25T00:10:52.607Z,1348531852.607 [AHRS_sp3003D] Hardware Fault, FailCount= 5
2012-09-25T00:10:52.607Z,1348531852.607 [AHRS_sp3003D](ERROR): Hardware Fault
2012-09-25T00:10:52.639Z,1348531852.639 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2012-09-25T00:10:52.639Z,1348531852.639 [CBIT](CRITICAL): Hardware Fault in component: AHRS_sp3003D
2012-09-25T00:10:52.689Z,1348531852.689 [AHRS_sp3003D](INFO): Powering down
2012-09-25T00:10:52.907Z,1348531852.907 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:10:52.908Z,1348531852.908 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:10:52.908Z,1348531852.908 [DVL_micro](INFO): resume:Powering up
2012-09-25T00:10:52.908Z,1348531852.908 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:10:54.915Z,1348531854.915 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:10:54.915Z,1348531854.915 [DVL_micro](INFO): Querying output modes
2012-09-25T00:10:54.915Z,1348531854.915 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:10:56.919Z,1348531856.918 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:10:56.919Z,1348531856.919 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:10:56.919Z,1348531856.919 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:10:58.925Z,1348531858.925 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:10:58.926Z,1348531858.926 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:10:58.927Z,1348531858.927 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:11:00.935Z,1348531860.935 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:11:00.936Z,1348531860.936 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:11:02.938Z,1348531862.938 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:11:02.939Z,1348531862.939 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:11:02.939Z,1348531862.939 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:11:04.942Z,1348531864.943 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:11:04.943Z,1348531864.943 [DVL_micro](INFO): Querying output modes
2012-09-25T00:11:04.943Z,1348531864.943 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:11:06.946Z,1348531866.946 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:11:06.947Z,1348531866.947 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:11:06.947Z,1348531866.947 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:11:08.951Z,1348531868.951 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:11:10.955Z,1348531870.955 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:11:10.956Z,1348531870.956 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:11:12.963Z,1348531872.963 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:11:12.963Z,1348531872.963 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:11:14.966Z,1348531874.967 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:11:14.967Z,1348531874.967 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:11:14.967Z,1348531874.967 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:11:16.970Z,1348531876.970 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:11:16.971Z,1348531876.971 [DVL_micro](INFO): Querying output modes
2012-09-25T00:11:16.971Z,1348531876.971 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:11:18.975Z,1348531878.975 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:11:18.975Z,1348531878.975 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:11:18.975Z,1348531878.975 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:11:20.979Z,1348531880.978 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:11:22.983Z,1348531882.983 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:11:22.983Z,1348531882.983 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:11:24.991Z,1348531884.991 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:11:24.992Z,1348531884.992 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:11:26.994Z,1348531886.994 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:11:26.995Z,1348531886.995 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:11:26.995Z,1348531886.995 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:11:28.998Z,1348531888.999 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:11:28.999Z,1348531888.999 [DVL_micro](INFO): Querying output modes
2012-09-25T00:11:28.999Z,1348531888.999 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:11:31.002Z,1348531891.003 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:11:31.003Z,1348531891.003 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:11:31.003Z,1348531891.003 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:11:33.006Z,1348531893.006 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:11:35.011Z,1348531895.011 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:11:35.011Z,1348531895.011 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:11:37.019Z,1348531897.019 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:11:37.019Z,1348531897.019 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:11:39.023Z,1348531899.023 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:11:39.023Z,1348531899.023 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:11:39.023Z,1348531899.023 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:11:41.026Z,1348531901.026 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:11:41.027Z,1348531901.027 [DVL_micro](INFO): Querying output modes
2012-09-25T00:11:41.027Z,1348531901.027 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:11:43.030Z,1348531903.030 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:11:43.031Z,1348531903.031 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:11:43.031Z,1348531903.031 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:11:45.035Z,1348531905.035 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:11:47.039Z,1348531907.039 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:11:47.039Z,1348531907.039 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:11:49.047Z,1348531909.047 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:11:49.047Z,1348531909.047 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:11:51.051Z,1348531911.050 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:11:51.051Z,1348531911.051 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:11:51.051Z,1348531911.051 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:11:53.054Z,1348531913.055 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:11:53.055Z,1348531913.055 [DVL_micro](INFO): Querying output modes
2012-09-25T00:11:53.055Z,1348531913.055 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:11:55.058Z,1348531915.059 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:11:55.059Z,1348531915.059 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:11:55.059Z,1348531915.059 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:11:57.062Z,1348531917.062 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:11:59.067Z,1348531919.067 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:11:59.067Z,1348531919.067 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:12:01.075Z,1348531921.075 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:12:01.075Z,1348531921.075 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:12:03.081Z,1348531923.081 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:12:03.081Z,1348531923.081 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:12:03.081Z,1348531923.081 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:12:05.089Z,1348531925.089 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:12:05.089Z,1348531925.089 [DVL_micro](INFO): Querying output modes
2012-09-25T00:12:05.089Z,1348531925.089 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:12:07.090Z,1348531927.091 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:12:07.091Z,1348531927.091 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:12:07.091Z,1348531927.091 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:12:09.094Z,1348531929.095 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:12:11.099Z,1348531931.099 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:12:11.099Z,1348531931.099 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:12:13.107Z,1348531933.107 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:12:13.107Z,1348531933.107 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:12:15.111Z,1348531935.111 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:12:15.111Z,1348531935.111 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:12:15.111Z,1348531935.111 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:12:17.115Z,1348531937.115 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:12:17.115Z,1348531937.115 [DVL_micro](INFO): Querying output modes
2012-09-25T00:12:17.115Z,1348531937.115 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:12:19.119Z,1348531939.119 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:12:19.119Z,1348531939.119 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:12:19.119Z,1348531939.119 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:12:21.123Z,1348531941.122 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:12:23.127Z,1348531943.127 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:12:23.127Z,1348531943.127 [DVL_micro](INFO): Cycling power to configure device.
2012-09-25T00:12:25.135Z,1348531945.135 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-25T00:12:25.135Z,1348531945.135 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-25T00:12:27.139Z,1348531947.138 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:12:27.139Z,1348531947.139 [DVL_micro](INFO): Enabling NQ1 output
2012-09-25T00:12:27.139Z,1348531947.139 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-25T00:12:28.315Z,1348531948.315 [CBIT](INFO): Clearing failed count for component NAL9602
2012-09-25T00:12:28.315Z,1348531948.315 [NAL9602] No Fault, FailCount= 2
2012-09-25T00:12:28.692Z,1348531948.692 [NAL9602](INFO): Powering up NAL9602
2012-09-25T00:12:29.142Z,1348531949.142 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:12:29.143Z,1348531949.143 [DVL_micro](INFO): Querying output modes
2012-09-25T00:12:29.143Z,1348531949.143 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-25T00:12:31.147Z,1348531951.147 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:12:31.147Z,1348531951.147 [DVL_micro](INFO): Output Modes: No Response
2012-09-25T00:12:31.147Z,1348531951.147 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-25T00:12:33.150Z,1348531953.151 [DVL_micro](DEBUG): cmdResponse: 
2012-09-25T00:12:35.155Z,1348531955.155 [DVL_micro](INFO): NQ1 requested
2012-09-25T00:12:35.155Z,1348531955.155 [DVL_micro](INFO): Cycling power to configure device.