2013-09-17T00:47:20.042Z,1379378840.042 [Supervisor](DEBUG): Initializing supervisor.
2013-09-17T00:47:20.048Z,1379378840.048 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0
2013-09-17T00:47:20.048Z,1379378840.048 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2013-09-17T00:47:20.052Z,1379378840.052 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4036B4E0
2013-09-17T00:47:20.054Z,1379378840.054 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2013-09-17T00:47:20.065Z,1379378840.065 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2013-09-17T00:47:20.068Z,1379378840.068 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4039B4E0
2013-09-17T00:47:20.069Z,1379378840.069 [ComponentRegistry](DEBUG): Component "logger" handled in its own thread.
2013-09-17T00:47:20.072Z,1379378840.072 [logger ThreadHandler](DEBUG): Created PCaller Thread at 403CB4E0
2013-09-17T00:47:20.072Z,1379378840.072 [Supervisor](INFO): Looking for Config files in directory: Config/
2013-09-17T00:47:20.076Z,1379378840.076 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2013-09-17T00:47:20.391Z,1379378840.391 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2013-09-17T00:47:20.392Z,1379378840.392 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2013-09-17T00:47:20.609Z,1379378840.609 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2013-09-17T00:47:20.610Z,1379378840.610 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2013-09-17T00:47:20.708Z,1379378840.708 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2013-09-17T00:47:20.710Z,1379378840.710 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2013-09-17T00:47:20.838Z,1379378840.838 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2013-09-17T00:47:20.839Z,1379378840.839 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2013-09-17T00:47:20.998Z,1379378840.998 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2013-09-17T00:47:20.000Z,1379378841.000 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2013-09-17T00:47:21.265Z,1379378841.265 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2013-09-17T00:47:21.266Z,1379378841.266 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2013-09-17T00:47:21.454Z,1379378841.454 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2013-09-17T00:47:21.457Z,1379378841.457 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2013-09-17T00:47:21.750Z,1379378841.750 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2013-09-17T00:47:21.752Z,1379378841.752 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2013-09-17T00:47:21.867Z,1379378841.867 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2013-09-17T00:47:21.868Z,1379378841.868 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2013-09-17T00:47:22.327Z,1379378842.327 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2013-09-17T00:47:22.327Z,1379378842.327 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2013-09-17T00:47:22.447Z,1379378842.447 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2013-09-17T00:47:22.448Z,1379378842.448 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2013-09-17T00:47:22.545Z,1379378842.545 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2013-09-17T00:47:22.549Z,1379378842.549 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2013-09-17T00:47:22.658Z,1379378842.658 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2013-09-17T00:47:22.801Z,1379378842.801 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2013-09-17T00:47:22.909Z,1379378842.909 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2013-09-17T00:47:23.025Z,1379378843.025 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2013-09-17T00:47:23.134Z,1379378843.134 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2013-09-17T00:47:23.266Z,1379378843.266 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2013-09-17T00:47:23.374Z,1379378843.374 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2013-09-17T00:47:23.468Z,1379378843.468 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2013-09-17T00:47:23.473Z,1379378843.473 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2013-09-17T00:47:23.698Z,1379378843.698 [InternalSim] Loaded
2013-09-17T00:47:23.699Z,1379378843.699 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2013-09-17T00:47:23.699Z,1379378843.699 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2013-09-17T00:47:23.700Z,1379378843.700 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2013-09-17T00:47:23.839Z,1379378843.839 [SBIT](DEBUG): Construct Startup Built In Test.
2013-09-17T00:47:23.869Z,1379378843.869 [SBIT] Loaded
2013-09-17T00:47:23.869Z,1379378843.869 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2013-09-17T00:47:23.870Z,1379378843.870 [IBIT](DEBUG): Construct Initiated Built In Test.
2013-09-17T00:47:23.904Z,1379378843.904 [IBIT] Loaded
2013-09-17T00:47:23.904Z,1379378843.904 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2013-09-17T00:47:23.907Z,1379378843.907 [CBIT](DEBUG): Construct CBIT Built In Test.
2013-09-17T00:47:24.037Z,1379378844.037 [CBIT] Loaded
2013-09-17T00:47:24.037Z,1379378844.037 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2013-09-17T00:47:24.037Z,1379378844.037 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2013-09-17T00:47:24.038Z,1379378844.038 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2013-09-17T00:47:28.458Z,1379378848.458 [BuoyancyServo] Loaded
2013-09-17T00:47:28.458Z,1379378848.458 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2013-09-17T00:47:28.470Z,1379378848.470 [ElevatorServo] Loaded
2013-09-17T00:47:28.471Z,1379378848.471 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2013-09-17T00:47:28.486Z,1379378848.486 [MassServo] Loaded
2013-09-17T00:47:28.486Z,1379378848.486 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2013-09-17T00:47:28.498Z,1379378848.498 [RudderServo] Loaded
2013-09-17T00:47:28.498Z,1379378848.498 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2013-09-17T00:47:28.509Z,1379378848.509 [ThrusterServo] Loaded
2013-09-17T00:47:28.509Z,1379378848.509 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2013-09-17T00:47:28.510Z,1379378848.510 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2013-09-17T00:47:28.511Z,1379378848.511 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2013-09-17T00:47:29.041Z,1379378849.041 [DepthRateCalculator] Loaded
2013-09-17T00:47:29.041Z,1379378849.041 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2013-09-17T00:47:32.557Z,1379378852.557 [HFRadarModelCalc] Loaded
2013-09-17T00:47:32.557Z,1379378852.557 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread.
2013-09-17T00:47:32.577Z,1379378852.577 [NavChart] Loaded
2013-09-17T00:47:32.578Z,1379378852.578 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2013-09-17T00:47:32.583Z,1379378852.583 [PitchRateCalculator] Loaded
2013-09-17T00:47:32.584Z,1379378852.584 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2013-09-17T00:47:32.598Z,1379378852.598 [SpeedCalculator] Loaded
2013-09-17T00:47:32.599Z,1379378852.599 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2013-09-17T00:47:32.616Z,1379378852.616 [TempGradientCalculator] Loaded
2013-09-17T00:47:32.616Z,1379378852.616 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2013-09-17T00:47:32.621Z,1379378852.621 [YawRateCalculator] Loaded
2013-09-17T00:47:32.622Z,1379378852.622 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2013-09-17T00:47:32.673Z,1379378852.673 [Navigation] Loaded
2013-09-17T00:47:32.673Z,1379378852.673 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread.
2013-09-17T00:47:32.674Z,1379378852.674 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2013-09-17T00:47:32.675Z,1379378852.675 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2013-09-17T00:47:32.992Z,1379378852.992 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2013-09-17T00:47:32.993Z,1379378852.993 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2013-09-17T00:47:33.037Z,1379378853.037 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2013-09-17T00:47:33.038Z,1379378853.038 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2013-09-17T00:47:33.115Z,1379378853.115 [VerticalControl](DEBUG): Construct VerticalControl.
2013-09-17T00:47:33.218Z,1379378853.218 [VerticalControl] Loaded
2013-09-17T00:47:33.218Z,1379378853.218 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2013-09-17T00:47:33.219Z,1379378853.219 [HorizontalControl](DEBUG): Construct HorizontalControl.
2013-09-17T00:47:33.281Z,1379378853.281 [HorizontalControl] Loaded
2013-09-17T00:47:33.282Z,1379378853.282 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2013-09-17T00:47:33.282Z,1379378853.282 [SpeedControl](DEBUG): Construct SpeedControl.
2013-09-17T00:47:33.288Z,1379378853.288 [SpeedControl] Loaded
2013-09-17T00:47:33.288Z,1379378853.288 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2013-09-17T00:47:33.289Z,1379378853.289 [LoopControl](DEBUG): Construct LoopControl.
2013-09-17T00:47:33.290Z,1379378853.290 [LoopControl] Loaded
2013-09-17T00:47:33.290Z,1379378853.290 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2013-09-17T00:47:33.290Z,1379378853.290 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2013-09-17T00:47:33.291Z,1379378853.291 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2013-09-17T00:47:33.299Z,1379378853.299 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator.
2013-09-17T00:47:33.305Z,1379378853.305 [AsyncPiEstimator] Loaded
2013-09-17T00:47:33.305Z,1379378853.305 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread.
2013-09-17T00:47:33.308Z,1379378853.308 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 406474E0
2013-09-17T00:47:33.309Z,1379378853.309 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2013-09-17T00:47:33.310Z,1379378853.310 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2013-09-17T00:47:33.575Z,1379378853.575 [AHRS_sp3003D] Loaded
2013-09-17T00:47:33.575Z,1379378853.575 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2013-09-17T00:47:33.589Z,1379378853.589 [Depth_Keller] Loaded
2013-09-17T00:47:33.589Z,1379378853.589 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2013-09-17T00:47:33.595Z,1379378853.595 [DropWeight] Loaded
2013-09-17T00:47:33.595Z,1379378853.595 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2013-09-17T00:47:33.801Z,1379378853.801 [DVL_micro] Loaded
2013-09-17T00:47:33.801Z,1379378853.801 [ComponentRegistry](DEBUG): Component "DVL_micro" handled in its own thread.
2013-09-17T00:47:33.805Z,1379378853.805 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406CE4E0
2013-09-17T00:47:33.903Z,1379378853.903 [NAL9602] Loaded
2013-09-17T00:47:33.903Z,1379378853.903 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2013-09-17T00:47:33.951Z,1379378853.951 [Onboard] Loaded
2013-09-17T00:47:33.951Z,1379378853.951 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2013-09-17T00:47:33.958Z,1379378853.958 [Radio_Freewave] Loaded
2013-09-17T00:47:33.958Z,1379378853.958 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread.
2013-09-17T00:47:34.117Z,1379378854.117 [DAT] Loaded
2013-09-17T00:47:34.118Z,1379378854.118 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2013-09-17T00:47:34.124Z,1379378854.124 [SCPI] Loaded
2013-09-17T00:47:34.125Z,1379378854.125 [ComponentRegistry](DEBUG): SyncComponent "SCPI" handled in the control thread.
2013-09-17T00:47:34.125Z,1379378854.125 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2013-09-17T00:47:34.126Z,1379378854.126 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2013-09-17T00:47:34.262Z,1379378854.262 [CTD_NeilBrown] Loaded
2013-09-17T00:47:34.262Z,1379378854.262 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2013-09-17T00:47:34.266Z,1379378854.266 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4072B4E0
2013-09-17T00:47:34.281Z,1379378854.281 [PAR_Licor] Loaded
2013-09-17T00:47:34.281Z,1379378854.281 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2013-09-17T00:47:34.318Z,1379378854.318 [WetLabsBB2FL] Loaded
2013-09-17T00:47:34.318Z,1379378854.318 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2013-09-17T00:47:34.322Z,1379378854.322 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 4075B4E0
2013-09-17T00:47:34.322Z,1379378854.322 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2013-09-17T00:47:34.325Z,1379378854.325 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2013-09-17T00:47:34.326Z,1379378854.326 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2013-09-17T00:47:34.339Z,1379378854.339 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2013-09-17T00:47:34.342Z,1379378854.342 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 4078B4E0
2013-09-17T00:47:34.346Z,1379378854.346 [Supervisor](DEBUG): Running supervisor.
2013-09-17T00:47:34.347Z,1379378854.347 [CommandLine](INFO): Thread ID is 760
2013-09-17T00:47:34.350Z,1379378854.350 [controlThread](INFO): Thread ID is 759
2013-09-17T00:47:34.351Z,1379378854.351 [controlThread](DEBUG): Initializing ControlThread
2013-09-17T00:47:34.351Z,1379378854.351 [CycleStarter](INFO): Thread ID is 758
2013-09-17T00:47:34.352Z,1379378854.352 [InternalSim](DEBUG):  InternalSim initializing...
2013-09-17T00:47:34.434Z,1379378854.434 [logger](INFO): Thread ID is 761
2013-09-17T00:47:34.483Z,1379378854.483 [AsyncPiEstimator](INFO): Thread ID is 822
2013-09-17T00:47:34.483Z,1379378854.483 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator.
2013-09-17T00:47:34.497Z,1379378854.497 [DVL_micro](INFO): Thread ID is 823
2013-09-17T00:47:34.499Z,1379378854.499 [SBIT](INFO): Initialize SBIT Component.
2013-09-17T00:47:34.500Z,1379378854.500 [SBIT](IMPORTANT): Tethys CM Info: 
$Rev:10634
2013-09-17T00:47:34.500Z,1379378854.500 [SBIT](IMPORTANT): Kernel Release:2.6.27.8
2013-09-17T00:47:34.500Z,1379378854.500 [SBIT](IMPORTANT): Kernel Version:#634 PREEMPT Wed Feb 13 10:21:48 PST 2013
2013-09-17T00:47:34.501Z,1379378854.501 [IBIT](INFO): Initialize IBIT Component.
2013-09-17T00:47:34.502Z,1379378854.502 [CBIT](DEBUG): Initialize CBIT Component.
2013-09-17T00:47:34.502Z,1379378854.502 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2013-09-17T00:47:34.502Z,1379378854.502 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2013-09-17T00:47:34.514Z,1379378854.514 [CTD_NeilBrown](INFO): Thread ID is 824
2013-09-17T00:47:34.514Z,1379378854.514 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2013-09-17T00:47:34.516Z,1379378854.516 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2013-09-17T00:47:34.527Z,1379378854.527 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2013-09-17T00:47:34.528Z,1379378854.528 [NavChart](DEBUG): Initialize NavChart Derivation.
2013-09-17T00:47:34.528Z,1379378854.528 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2013-09-17T00:47:34.529Z,1379378854.529 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2013-09-17T00:47:34.529Z,1379378854.529 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2013-09-17T00:47:34.530Z,1379378854.530 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2013-09-17T00:47:34.530Z,1379378854.530 [Navigation](DEBUG): Initializing Navigation.
2013-09-17T00:47:34.531Z,1379378854.531 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2013-09-17T00:47:34.532Z,1379378854.532 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2013-09-17T00:47:34.533Z,1379378854.533 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2013-09-17T00:47:34.534Z,1379378854.534 [LoopControl](DEBUG): Initialize LoopControlComponent.
2013-09-17T00:47:34.563Z,1379378854.563 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2013-09-17T00:47:34.564Z,1379378854.564 [DropWeight] Hardware Fault, FailCount= 1
2013-09-17T00:47:34.564Z,1379378854.564 [DropWeight](ERROR): Hardware Fault
2013-09-17T00:47:34.567Z,1379378854.567 [WetLabsBB2FL](INFO): Thread ID is 825
2013-09-17T00:47:34.568Z,1379378854.568 [WetLabsBB2FL](INFO): Powering down
2013-09-17T00:47:34.584Z,1379378854.584 [DVL_micro](INFO): Initializing
2013-09-17T00:47:34.585Z,1379378854.585 [DVL_micro](INFO): start:Powering up
2013-09-17T00:47:34.585Z,1379378854.585 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:47:34.592Z,1379378854.592 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:47:34.595Z,1379378854.595 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2013-09-17T00:47:34.607Z,1379378854.607 [NavChartDb](INFO): Thread ID is 826
2013-09-17T00:47:34.635Z,1379378854.635 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2013-09-17T00:47:34.636Z,1379378854.636 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2013-09-17T00:47:34.636Z,1379378854.636 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2013-09-17T00:47:34.637Z,1379378854.637 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2013-09-17T00:47:34.637Z,1379378854.637 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2013-09-17T00:47:34.637Z,1379378854.637 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA61M.000
2013-09-17T00:47:34.638Z,1379378854.638 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA83M.000
2013-09-17T00:47:34.638Z,1379378854.638 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA62M.000
2013-09-17T00:47:34.662Z,1379378854.662 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US4CA60M.000
2013-09-17T00:47:34.695Z,1379378854.695 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2013-09-17T00:47:34.765Z,1379378854.765 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface RunIn="Progression"/>

    <Aggregate Id="StartupSatComms" >

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

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

    </Aggregate>

</Mission>


2013-09-17T00:47:34.771Z,1379378854.771 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2013-09-17T00:47:34.837Z,1379378854.837 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool
2013-09-17T00:47:34.872Z,1379378854.872 [Default:GPS:A.SetSpeed](DEBUG): Construct.
2013-09-17T00:47:34.875Z,1379378854.875 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface.
2013-09-17T00:47:34.899Z,1379378854.899 [Default:Iridium:A.SetSpeed](DEBUG): Construct.
2013-09-17T00:47:34.902Z,1379378854.902 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface.
2013-09-17T00:47:34.925Z,1379378854.925 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2013-09-17T00:47:34.954Z,1379378854.954 [Default:D.SetSpeed](DEBUG): Construct.
2013-09-17T00:47:34.985Z,1379378854.985 [Default:E.GoToSurface](DEBUG): Construct GoToSurface.
2013-09-17T00:47:34.990Z,1379378854.990 [Default:F.Wait](DEBUG): Construct Wait.
2013-09-17T00:47:35.006Z,1379378855.006 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
xmlns:Control="Tethys/Control"
xmlns:Guidance="Tethys/Guidance" 
xmlns:Units="Tethys/Units"
xmlns:Universal="Tethys/Universal"
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
Id="Default">

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

    <Aggregate Id="GPS" >

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

<!-- Run at high speed -->

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

<!-- Get to the surface -->

        <Guidance:GoToSurface RunIn="Sequence"/>

<!-- Get GPS fix -->

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

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

    </Aggregate>

    <Aggregate Id="Iridium" >

<!-- Run at high speed -->

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

<!-- Get to the surface -->

        <Guidance:GoToSurface RunIn="Sequence"/>

<!-- Do data comms -->

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

    </Aggregate>

    <Aggregate Id="CallIridium" >

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

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

        <Call RefId="Iridium"/>

    </Aggregate>

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

<!-- Run at low speed -->

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

    <Guidance:GoToSurface RunIn="Parallel"/>

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

</Mission>


2013-09-17T00:47:35.010Z,1379378855.010 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Depth_Keller,DropWeight,NAL9602,Onboard,Radio_Freewave,DAT,SCPI,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,HFRadarModelCalc,NavChart,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,Navigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,
2013-09-17T00:47:35.068Z,1379378855.068 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-09-17T00:47:35.253Z,1379378855.253 [DAT](INFO): Powering up
2013-09-17T00:47:35.253Z,1379378855.253 [DAT](DEBUG): Initializing DAT.
2013-09-17T00:47:35.597Z,1379378855.597 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-09-17T00:47:35.612Z,1379378855.612 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-09-17T00:47:35.618Z,1379378855.618 [ElevatorServo](DEBUG): Initializing EZServoServo.
2013-09-17T00:47:35.624Z,1379378855.624 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2013-09-17T00:47:35.630Z,1379378855.630 [MassServo](DEBUG): Initializing EZServoServo.
2013-09-17T00:47:35.660Z,1379378855.660 [MassServo](DEBUG): Initializing MassServo.
2013-09-17T00:47:35.666Z,1379378855.666 [RudderServo](DEBUG): Initializing EZServoServo.
2013-09-17T00:47:35.672Z,1379378855.672 [RudderServo](DEBUG): Initializing RudderServo.
2013-09-17T00:47:35.678Z,1379378855.678 [ThrusterServo](DEBUG): Initializing EZServoServo.
2013-09-17T00:47:35.684Z,1379378855.684 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2013-09-17T00:47:35.717Z,1379378855.717 [CommandLine](FAULT): Scheduling is paused
2013-09-17T00:47:35.718Z,1379378855.718 [CBIT](FAULT): Main Battery Failure. Count: 1
2013-09-17T00:47:35.719Z,1379378855.719 [CBIT](ERROR): Hardware Fault in component: DropWeight
2013-09-17T00:47:35.720Z,1379378855.720 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2013-09-17T00:47:36.169Z,1379378856.169 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2013-09-17T00:47:36.169Z,1379378856.169 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2013-09-17T00:47:36.169Z,1379378856.169 [AHRS_sp3003D] Hardware Fault, FailCount= 1
2013-09-17T00:47:36.169Z,1379378856.169 [AHRS_sp3003D](ERROR): Hardware Fault
2013-09-17T00:47:36.630Z,1379378856.630 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:47:36.630Z,1379378856.630 [DVL_micro](INFO): Querying output modes
2013-09-17T00:47:36.630Z,1379378856.630 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:47:36.786Z,1379378856.786 [BuoyancyServo](ERROR): Buoyancy initialization uart error serial timeout
2013-09-17T00:47:36.786Z,1379378856.786 [BuoyancyServo](FAULT): Buoyancy failed to initialize
2013-09-17T00:47:36.786Z,1379378856.786 [BuoyancyServo] Communications Fault, FailCount= 1
2013-09-17T00:47:36.786Z,1379378856.786 [BuoyancyServo](ERROR): Communications Fault
2013-09-17T00:47:36.996Z,1379378856.996 [CBIT](ERROR): Communications Fault in component: BuoyancyServo
2013-09-17T00:47:36.996Z,1379378856.996 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2013-09-17T00:47:37.030Z,1379378857.030 [AHRS_sp3003D](INFO): Powering down
2013-09-17T00:47:37.178Z,1379378857.178 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2013-09-17T00:47:37.180Z,1379378857.180 [BuoyancyServo](INFO): Powering down
2013-09-17T00:47:38.419Z,1379378858.419 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2013-09-17T00:47:38.419Z,1379378858.419 [AHRS_sp3003D] No Fault, FailCount= 1
2013-09-17T00:47:38.616Z,1379378858.616 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-09-17T00:47:38.640Z,1379378858.640 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:47:38.641Z,1379378858.641 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:47:38.641Z,1379378858.641 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:47:39.018Z,1379378859.018 [NAL9602](INFO): Powering up NAL9602
2013-09-17T00:47:39.754Z,1379378859.754 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2013-09-17T00:47:39.754Z,1379378859.754 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2013-09-17T00:47:39.754Z,1379378859.754 [AHRS_sp3003D] Hardware Fault, FailCount= 2
2013-09-17T00:47:39.755Z,1379378859.755 [AHRS_sp3003D](ERROR): Hardware Fault
2013-09-17T00:47:40.145Z,1379378860.145 [CBIT](INFO): Clearing failed state for component BuoyancyServo
2013-09-17T00:47:40.146Z,1379378860.146 [BuoyancyServo] No Fault, FailCount= 1
2013-09-17T00:47:40.146Z,1379378860.146 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2013-09-17T00:47:40.192Z,1379378860.192 [AHRS_sp3003D](INFO): Powering down
2013-09-17T00:47:40.330Z,1379378860.330 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2013-09-17T00:47:40.352Z,1379378860.352 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2013-09-17T00:47:40.648Z,1379378860.648 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:47:40.653Z,1379378860.653 [DVL_micro](INFO): pause:Powering down
2013-09-17T00:47:40.655Z,1379378860.655 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:47:40.660Z,1379378860.660 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:47:40.660Z,1379378860.660 [DVL_micro](INFO): resume:Powering up
2013-09-17T00:47:40.660Z,1379378860.660 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:47:41.632Z,1379378861.632 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2013-09-17T00:47:41.632Z,1379378861.632 [AHRS_sp3003D] No Fault, FailCount= 2
2013-09-17T00:47:41.889Z,1379378861.889 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-09-17T00:47:42.663Z,1379378862.663 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:47:42.663Z,1379378862.663 [DVL_micro](INFO): Querying output modes
2013-09-17T00:47:42.664Z,1379378862.664 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:47:43.051Z,1379378863.051 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2013-09-17T00:47:43.051Z,1379378863.051 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2013-09-17T00:47:43.052Z,1379378863.052 [AHRS_sp3003D] Hardware Fault, FailCount= 3
2013-09-17T00:47:43.052Z,1379378863.052 [AHRS_sp3003D](ERROR): Hardware Fault
2013-09-17T00:47:43.251Z,1379378863.251 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2013-09-17T00:47:43.289Z,1379378863.289 [AHRS_sp3003D](INFO): Powering down
2013-09-17T00:47:44.572Z,1379378864.572 [CBIT](CRITICAL): ABORT DEPTH REACHED. ACTIVATING BURNWIRE.

2013-09-17T00:47:44.586Z,1379378864.586 [CBIT](CRITICAL): STOP DEPTH REACHED. Terminating Mission.

2013-09-17T00:47:44.587Z,1379378864.587 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2013-09-17T00:47:44.587Z,1379378864.587 [AHRS_sp3003D] No Fault, FailCount= 3
2013-09-17T00:47:44.670Z,1379378864.670 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:47:44.670Z,1379378864.670 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:47:44.670Z,1379378864.670 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:47:44.865Z,1379378864.865 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-09-17T00:47:45.000Z,1379378866.000 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2013-09-17T00:47:45.000Z,1379378866.000 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2013-09-17T00:47:46.000Z,1379378866.000 [AHRS_sp3003D] Hardware Fault, FailCount= 4
2013-09-17T00:47:46.000Z,1379378866.000 [AHRS_sp3003D](ERROR): Hardware Fault
2013-09-17T00:47:46.111Z,1379378866.111 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2013-09-17T00:47:46.124Z,1379378866.124 [AHRS_sp3003D](INFO): Powering down
2013-09-17T00:47:46.677Z,1379378866.677 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:47:46.679Z,1379378866.679 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:47:46.679Z,1379378866.679 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:47:47.434Z,1379378867.434 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2013-09-17T00:47:47.434Z,1379378867.434 [AHRS_sp3003D] No Fault, FailCount= 4
2013-09-17T00:47:47.726Z,1379378867.726 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-09-17T00:47:48.684Z,1379378868.684 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:47:48.685Z,1379378868.685 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:47:48.860Z,1379378868.860 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2013-09-17T00:47:48.860Z,1379378868.860 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2013-09-17T00:47:48.861Z,1379378868.861 [AHRS_sp3003D] Hardware Fault, FailCount= 5
2013-09-17T00:47:48.861Z,1379378868.861 [AHRS_sp3003D](ERROR): Hardware Fault
2013-09-17T00:47:48.932Z,1379378868.932 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2013-09-17T00:47:48.932Z,1379378868.932 [CBIT](CRITICAL): Hardware Fault in component: AHRS_sp3003D
2013-09-17T00:47:48.988Z,1379378868.988 [AHRS_sp3003D](INFO): Powering down
2013-09-17T00:47:49.390Z,1379378869.390 [NAL9602](INFO): NAL9602 initialized
2013-09-17T00:47:49.822Z,1379378869.822 [SBIT](IMPORTANT): Beginning Startup BIT
2013-09-17T00:47:49.825Z,1379378869.825 [CBIT](IMPORTANT): Beginning GF scan
2013-09-17T00:47:50.687Z,1379378870.687 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:47:50.687Z,1379378870.687 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:47:50.687Z,1379378870.687 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:47:52.691Z,1379378872.691 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:47:52.691Z,1379378872.691 [DVL_micro](INFO): Querying output modes
2013-09-17T00:47:52.692Z,1379378872.692 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:47:54.693Z,1379378874.693 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:47:54.694Z,1379378874.694 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:47:54.694Z,1379378874.694 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:47:55.470Z,1379378875.470 [DAT](INFO): Powering down
2013-09-17T00:47:56.705Z,1379378876.705 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:47:58.708Z,1379378878.708 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:47:58.708Z,1379378878.708 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:48:00.711Z,1379378880.711 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:48:00.711Z,1379378880.711 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:48:02.718Z,1379378882.718 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:48:02.718Z,1379378882.718 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:48:02.718Z,1379378882.718 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:48:04.721Z,1379378884.721 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:48:04.721Z,1379378884.721 [DVL_micro](INFO): Querying output modes
2013-09-17T00:48:04.721Z,1379378884.721 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:48:06.724Z,1379378886.724 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:48:06.724Z,1379378886.724 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:48:06.724Z,1379378886.724 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:48:08.731Z,1379378888.731 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:48:10.755Z,1379378890.755 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:48:10.755Z,1379378890.755 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:48:12.758Z,1379378892.758 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:48:12.758Z,1379378892.758 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:48:14.765Z,1379378894.765 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:48:14.765Z,1379378894.765 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:48:14.766Z,1379378894.766 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:48:16.767Z,1379378896.767 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:48:16.768Z,1379378896.768 [DVL_micro](INFO): Querying output modes
2013-09-17T00:48:16.768Z,1379378896.768 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:48:16.773Z,1379378896.773 [CBIT](IMPORTANT): No ground fault detected
2013-09-17T00:48:18.771Z,1379378898.771 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:48:18.771Z,1379378898.771 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:48:18.771Z,1379378898.771 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:48:20.774Z,1379378900.774 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:48:22.781Z,1379378902.781 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:48:22.782Z,1379378902.782 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:48:24.784Z,1379378904.784 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:48:24.785Z,1379378904.785 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:48:26.795Z,1379378906.795 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:48:26.795Z,1379378906.795 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:48:26.795Z,1379378906.795 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:48:28.806Z,1379378908.806 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:48:28.806Z,1379378908.806 [DVL_micro](INFO): Querying output modes
2013-09-17T00:48:28.807Z,1379378908.807 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:48:30.820Z,1379378910.820 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:48:30.820Z,1379378910.820 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:48:30.820Z,1379378910.820 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:48:32.829Z,1379378912.829 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:48:34.839Z,1379378914.839 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:48:34.839Z,1379378914.839 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:48:36.843Z,1379378916.843 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:48:36.843Z,1379378916.843 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:48:38.850Z,1379378918.850 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:48:38.850Z,1379378918.850 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:48:38.850Z,1379378918.850 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:48:40.857Z,1379378920.857 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:48:40.857Z,1379378920.857 [DVL_micro](INFO): Querying output modes
2013-09-17T00:48:40.857Z,1379378920.857 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:48:42.868Z,1379378922.868 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:48:42.868Z,1379378922.868 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:48:42.868Z,1379378922.868 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:48:43.784Z,1379378923.784 [SBIT](IMPORTANT): SBIT PASSED
2013-09-17T00:48:44.167Z,1379378924.167 [MissionManager](IMPORTANT): Started mission Startup
2013-09-17T00:48:44.167Z,1379378924.167 [Startup] Running Loop=1
2013-09-17T00:48:44.167Z,1379378924.167 [Startup](INFO): Aggregate::initialize Startup
2013-09-17T00:48:44.167Z,1379378924.167 [Startup:A.GoToSurface] Running Loop=1
2013-09-17T00:48:44.168Z,1379378924.168 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2013-09-17T00:48:44.875Z,1379378924.875 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:48:46.883Z,1379378926.883 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:48:46.883Z,1379378926.883 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:48:48.886Z,1379378928.886 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:48:48.886Z,1379378928.886 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:48:50.888Z,1379378930.888 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:48:50.888Z,1379378930.888 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:48:50.889Z,1379378930.889 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:48:52.891Z,1379378932.891 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:48:52.892Z,1379378932.892 [DVL_micro](INFO): Querying output modes
2013-09-17T00:48:52.892Z,1379378932.892 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:48:54.899Z,1379378934.899 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:48:54.899Z,1379378934.899 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:48:54.899Z,1379378934.899 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:48:56.906Z,1379378936.906 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:48:58.909Z,1379378938.909 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:48:58.910Z,1379378938.910 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:49:00.912Z,1379378940.912 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:49:00.913Z,1379378940.913 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:49:02.915Z,1379378942.915 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:49:02.915Z,1379378942.915 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:49:02.916Z,1379378942.916 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:49:04.918Z,1379378944.918 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:49:04.918Z,1379378944.918 [DVL_micro](INFO): Querying output modes
2013-09-17T00:49:04.919Z,1379378944.919 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:49:06.929Z,1379378946.929 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:49:06.929Z,1379378946.929 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:49:06.930Z,1379378946.930 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:49:08.936Z,1379378948.936 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:49:10.948Z,1379378950.948 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:49:10.948Z,1379378950.948 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:49:12.951Z,1379378952.951 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:49:12.951Z,1379378952.951 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:49:14.958Z,1379378954.958 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:49:14.958Z,1379378954.958 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:49:14.958Z,1379378954.958 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:49:16.969Z,1379378956.969 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:49:16.969Z,1379378956.969 [DVL_micro](INFO): Querying output modes
2013-09-17T00:49:16.969Z,1379378956.969 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:49:18.976Z,1379378958.976 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:49:18.976Z,1379378958.976 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:49:18.976Z,1379378958.976 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:49:20.979Z,1379378960.979 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:49:22.983Z,1379378962.983 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:49:22.983Z,1379378962.983 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:49:24.986Z,1379378964.986 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:49:24.986Z,1379378964.986 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:49:26.996Z,1379378966.996 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:49:26.997Z,1379378966.997 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:49:26.997Z,1379378966.997 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:49:28.999Z,1379378968.999 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:49:28.000Z,1379378969.000 [DVL_micro](INFO): Querying output modes
2013-09-17T00:49:28.000Z,1379378969.000 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:49:31.003Z,1379378971.003 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:49:31.003Z,1379378971.003 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:49:31.003Z,1379378971.003 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:49:33.010Z,1379378973.010 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:49:35.021Z,1379378975.021 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:49:35.022Z,1379378975.022 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:49:37.024Z,1379378977.024 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:49:37.025Z,1379378977.025 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:49:39.031Z,1379378979.031 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:49:39.031Z,1379378979.031 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:49:39.031Z,1379378979.031 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:49:41.039Z,1379378981.039 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:49:41.039Z,1379378981.039 [DVL_micro](INFO): Querying output modes
2013-09-17T00:49:41.039Z,1379378981.039 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:49:43.041Z,1379378983.041 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:49:43.041Z,1379378983.041 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:49:43.042Z,1379378983.042 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:49:45.044Z,1379378985.044 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:49:47.048Z,1379378987.048 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:49:47.048Z,1379378987.048 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:49:49.051Z,1379378989.051 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:49:49.051Z,1379378989.051 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:49:51.062Z,1379378991.062 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:49:51.062Z,1379378991.062 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:49:51.062Z,1379378991.062 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:49:53.073Z,1379378993.073 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:49:53.073Z,1379378993.073 [DVL_micro](INFO): Querying output modes
2013-09-17T00:49:53.073Z,1379378993.073 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:49:55.088Z,1379378995.088 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:49:55.088Z,1379378995.088 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:49:55.088Z,1379378995.088 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:49:57.095Z,1379378997.095 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:49:59.099Z,1379378999.099 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:49:59.099Z,1379378999.099 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:50:01.102Z,1379379001.102 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:50:01.102Z,1379379001.102 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:50:03.104Z,1379379003.104 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:50:03.105Z,1379379003.105 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:50:03.105Z,1379379003.105 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:50:05.107Z,1379379005.107 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:50:05.108Z,1379379005.108 [DVL_micro](INFO): Querying output modes
2013-09-17T00:50:05.108Z,1379379005.108 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:50:07.115Z,1379379007.115 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:50:07.115Z,1379379007.115 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:50:07.115Z,1379379007.115 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:50:09.118Z,1379379009.118 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:50:11.121Z,1379379011.121 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:50:11.121Z,1379379011.121 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:50:13.124Z,1379379013.124 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:50:13.125Z,1379379013.125 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:50:15.127Z,1379379015.127 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:50:15.127Z,1379379015.127 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:50:15.127Z,1379379015.127 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:50:17.138Z,1379379017.138 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:50:17.138Z,1379379017.138 [DVL_micro](INFO): Querying output modes
2013-09-17T00:50:17.139Z,1379379017.139 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:50:19.156Z,1379379019.156 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:50:19.156Z,1379379019.156 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:50:19.156Z,1379379019.156 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:50:21.160Z,1379379021.160 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:50:23.172Z,1379379023.172 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:50:23.172Z,1379379023.172 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:50:25.175Z,1379379025.175 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:50:25.175Z,1379379025.175 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:50:27.182Z,1379379027.182 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:50:27.182Z,1379379027.182 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:50:27.182Z,1379379027.182 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:50:29.193Z,1379379029.193 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:50:29.193Z,1379379029.193 [DVL_micro](INFO): Querying output modes
2013-09-17T00:50:29.193Z,1379379029.193 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:50:31.204Z,1379379031.204 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:50:31.204Z,1379379031.204 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:50:31.204Z,1379379031.204 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:50:33.207Z,1379379033.207 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:50:35.219Z,1379379035.219 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:50:35.219Z,1379379035.219 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:50:37.222Z,1379379037.222 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:50:37.222Z,1379379037.222 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:50:39.232Z,1379379039.232 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:50:39.232Z,1379379039.232 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:50:39.233Z,1379379039.233 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:50:41.248Z,1379379041.248 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:50:41.248Z,1379379041.248 [DVL_micro](INFO): Querying output modes
2013-09-17T00:50:41.248Z,1379379041.248 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:50:43.255Z,1379379043.255 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:50:43.255Z,1379379043.255 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:50:43.255Z,1379379043.255 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:50:45.258Z,1379379045.258 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:50:47.262Z,1379379047.262 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:50:47.262Z,1379379047.262 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:50:49.264Z,1379379049.264 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:50:49.265Z,1379379049.265 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:50:51.275Z,1379379051.275 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:50:51.275Z,1379379051.275 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:50:51.275Z,1379379051.275 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:50:53.286Z,1379379053.286 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:50:53.286Z,1379379053.286 [DVL_micro](INFO): Querying output modes
2013-09-17T00:50:53.286Z,1379379053.286 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:50:55.289Z,1379379055.289 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:50:55.289Z,1379379055.289 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:50:55.290Z,1379379055.290 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:50:57.296Z,1379379057.296 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:50:59.300Z,1379379059.300 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:50:59.300Z,1379379059.300 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:51:01.303Z,1379379061.303 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:51:01.303Z,1379379061.303 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:51:03.314Z,1379379063.314 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:51:03.314Z,1379379063.314 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:51:03.315Z,1379379063.315 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:51:05.317Z,1379379065.317 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:51:05.317Z,1379379065.317 [DVL_micro](INFO): Querying output modes
2013-09-17T00:51:05.317Z,1379379065.317 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:51:07.324Z,1379379067.324 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:51:07.324Z,1379379067.324 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:51:07.324Z,1379379067.324 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:51:09.335Z,1379379069.335 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:51:11.347Z,1379379071.347 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:51:11.347Z,1379379071.347 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:51:13.350Z,1379379073.350 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:51:13.350Z,1379379073.350 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:51:15.356Z,1379379075.356 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:51:15.356Z,1379379075.356 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:51:15.357Z,1379379075.357 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:51:17.367Z,1379379077.367 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:51:17.368Z,1379379077.368 [DVL_micro](INFO): Querying output modes
2013-09-17T00:51:17.368Z,1379379077.368 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:51:19.371Z,1379379079.371 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:51:19.371Z,1379379079.371 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:51:19.371Z,1379379079.371 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:51:20.505Z,1379379080.505 [MassServo](FAULT): Overload Error
2013-09-17T00:51:20.505Z,1379379080.505 [MassServo] Hardware Fault, FailCount= 1
2013-09-17T00:51:20.505Z,1379379080.505 [MassServo](ERROR): Hardware Fault
2013-09-17T00:51:20.571Z,1379379080.571 [CBIT](ERROR): Hardware Fault in component: MassServo
2013-09-17T00:51:20.873Z,1379379080.873 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-09-17T00:51:20.873Z,1379379080.873 [MassServo](INFO): Powering down
2013-09-17T00:51:21.378Z,1379379081.378 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:51:21.654Z,1379379081.654 [MassServo](DEBUG): Initializing EZServoServo.
2013-09-17T00:51:21.775Z,1379379081.775 [MassServo](DEBUG): Initializing MassServo.
2013-09-17T00:51:21.805Z,1379379081.805 [CBIT](INFO): Clearing failed state for component MassServo
2013-09-17T00:51:21.805Z,1379379081.805 [MassServo] No Fault, FailCount= 1
2013-09-17T00:51:23.382Z,1379379083.382 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:51:23.382Z,1379379083.382 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:51:25.384Z,1379379085.384 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:51:25.385Z,1379379085.385 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:51:27.391Z,1379379087.391 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:51:27.391Z,1379379087.391 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:51:27.391Z,1379379087.391 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:51:29.402Z,1379379089.402 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:51:29.402Z,1379379089.402 [DVL_micro](INFO): Querying output modes
2013-09-17T00:51:29.402Z,1379379089.402 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:51:31.413Z,1379379091.413 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:51:31.413Z,1379379091.413 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:51:31.414Z,1379379091.414 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:51:33.424Z,1379379093.424 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:51:35.432Z,1379379095.432 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:51:35.432Z,1379379095.432 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:51:37.435Z,1379379097.435 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:51:37.435Z,1379379097.435 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:51:39.442Z,1379379099.442 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:51:39.442Z,1379379099.442 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:51:39.442Z,1379379099.442 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:51:41.449Z,1379379101.449 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:51:41.449Z,1379379101.449 [DVL_micro](INFO): Querying output modes
2013-09-17T00:51:41.449Z,1379379101.449 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:51:43.460Z,1379379103.460 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:51:43.460Z,1379379103.460 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:51:43.460Z,1379379103.460 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:51:45.463Z,1379379105.463 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:51:47.475Z,1379379107.475 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:51:47.475Z,1379379107.475 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:51:49.478Z,1379379109.478 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:51:49.478Z,1379379109.478 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:51:51.488Z,1379379111.488 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:51:51.488Z,1379379111.488 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:51:51.489Z,1379379111.489 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:51:53.492Z,1379379113.492 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:51:53.492Z,1379379113.492 [DVL_micro](INFO): Querying output modes
2013-09-17T00:51:53.492Z,1379379113.492 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:51:55.499Z,1379379115.499 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:51:55.499Z,1379379115.499 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:51:55.499Z,1379379115.499 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:51:57.510Z,1379379117.510 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:51:59.517Z,1379379119.517 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:51:59.517Z,1379379119.517 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:52:01.520Z,1379379121.520 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:52:01.521Z,1379379121.521 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:52:03.523Z,1379379123.523 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:52:03.523Z,1379379123.523 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:52:03.523Z,1379379123.523 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:52:05.526Z,1379379125.526 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:52:05.526Z,1379379125.526 [DVL_micro](INFO): Querying output modes
2013-09-17T00:52:05.526Z,1379379125.526 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:52:07.533Z,1379379127.533 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:52:07.533Z,1379379127.533 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:52:07.534Z,1379379127.534 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:52:09.536Z,1379379129.536 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:52:11.548Z,1379379131.548 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:52:11.548Z,1379379131.548 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:52:13.551Z,1379379133.551 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:52:13.551Z,1379379133.551 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:52:15.558Z,1379379135.558 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:52:15.558Z,1379379135.558 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:52:15.558Z,1379379135.558 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:52:17.561Z,1379379137.561 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:52:17.561Z,1379379137.561 [DVL_micro](INFO): Querying output modes
2013-09-17T00:52:17.561Z,1379379137.561 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:52:19.572Z,1379379139.572 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:52:19.572Z,1379379139.572 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:52:19.572Z,1379379139.572 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:52:21.583Z,1379379141.583 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:52:23.587Z,1379379143.587 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:52:23.587Z,1379379143.587 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:52:25.590Z,1379379145.590 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:52:25.590Z,1379379145.590 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:52:27.599Z,1379379147.599 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:52:27.599Z,1379379147.599 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:52:27.599Z,1379379147.599 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:52:27.697Z,1379379147.697 [MassServo](FAULT): Overload Error
2013-09-17T00:52:27.697Z,1379379147.697 [MassServo] Hardware Fault, FailCount= 2
2013-09-17T00:52:27.697Z,1379379147.697 [MassServo](ERROR): Hardware Fault
2013-09-17T00:52:27.763Z,1379379147.763 [CBIT](ERROR): Hardware Fault in component: MassServo
2013-09-17T00:52:28.035Z,1379379148.035 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-09-17T00:52:28.035Z,1379379148.035 [MassServo](INFO): Powering down
2013-09-17T00:52:28.868Z,1379379148.868 [MassServo](DEBUG): Initializing EZServoServo.
2013-09-17T00:52:28.000Z,1379379149.000 [MassServo](DEBUG): Initializing MassServo.
2013-09-17T00:52:29.029Z,1379379149.029 [CBIT](INFO): Clearing failed state for component MassServo
2013-09-17T00:52:29.029Z,1379379149.029 [MassServo] No Fault, FailCount= 2
2013-09-17T00:52:29.604Z,1379379149.604 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:52:29.604Z,1379379149.604 [DVL_micro](INFO): Querying output modes
2013-09-17T00:52:29.604Z,1379379149.604 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:52:31.607Z,1379379151.607 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:52:31.607Z,1379379151.607 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:52:31.607Z,1379379151.607 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:52:33.609Z,1379379153.609 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:52:34.923Z,1379379154.923 [CBIT](INFO): Clearing failed count for component DropWeight
2013-09-17T00:52:34.923Z,1379379154.923 [DropWeight] No Fault, FailCount= 1
2013-09-17T00:52:35.213Z,1379379155.213 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2013-09-17T00:52:35.213Z,1379379155.213 [DropWeight] Hardware Fault, FailCount= 1
2013-09-17T00:52:35.214Z,1379379155.214 [DropWeight](ERROR): Hardware Fault
2013-09-17T00:52:35.331Z,1379379155.331 [CBIT](ERROR): Hardware Fault in component: DropWeight
2013-09-17T00:52:35.332Z,1379379155.332 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2013-09-17T00:52:35.622Z,1379379155.622 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:52:35.622Z,1379379155.622 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:52:37.628Z,1379379157.628 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:52:37.629Z,1379379157.629 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:52:39.631Z,1379379159.631 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:52:39.631Z,1379379159.631 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:52:39.631Z,1379379159.631 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:52:41.634Z,1379379161.634 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:52:41.634Z,1379379161.634 [DVL_micro](INFO): Querying output modes
2013-09-17T00:52:41.635Z,1379379161.635 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:52:43.645Z,1379379163.645 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:52:43.645Z,1379379163.645 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:52:43.646Z,1379379163.646 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:52:45.656Z,1379379165.656 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:52:47.668Z,1379379167.668 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:52:47.668Z,1379379167.668 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:52:48.924Z,1379379168.924 [CBIT](INFO): Clearing failed count for component AHRS_sp3003D
2013-09-17T00:52:48.924Z,1379379168.924 [AHRS_sp3003D] No Fault, FailCount= 5
2013-09-17T00:52:49.209Z,1379379169.209 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-09-17T00:52:49.671Z,1379379169.671 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:52:49.672Z,1379379169.672 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:52:50.340Z,1379379170.340 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2013-09-17T00:52:50.340Z,1379379170.340 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2013-09-17T00:52:50.340Z,1379379170.340 [AHRS_sp3003D] Hardware Fault, FailCount= 1
2013-09-17T00:52:50.340Z,1379379170.340 [AHRS_sp3003D](ERROR): Hardware Fault
2013-09-17T00:52:50.434Z,1379379170.434 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2013-09-17T00:52:50.464Z,1379379170.464 [AHRS_sp3003D](INFO): Powering down
2013-09-17T00:52:51.678Z,1379379171.678 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:52:51.678Z,1379379171.678 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:52:51.678Z,1379379171.678 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:52:51.736Z,1379379171.736 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2013-09-17T00:52:51.737Z,1379379171.737 [AHRS_sp3003D] No Fault, FailCount= 1
2013-09-17T00:52:52.050Z,1379379172.050 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-09-17T00:52:53.196Z,1379379173.196 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2013-09-17T00:52:53.196Z,1379379173.196 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2013-09-17T00:52:53.196Z,1379379173.196 [AHRS_sp3003D] Hardware Fault, FailCount= 2
2013-09-17T00:52:53.196Z,1379379173.196 [AHRS_sp3003D](ERROR): Hardware Fault
2013-09-17T00:52:53.319Z,1379379173.319 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2013-09-17T00:52:53.334Z,1379379173.334 [AHRS_sp3003D](INFO): Powering down
2013-09-17T00:52:53.685Z,1379379173.685 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:52:53.685Z,1379379173.685 [DVL_micro](INFO): Querying output modes
2013-09-17T00:52:53.685Z,1379379173.685 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:52:54.633Z,1379379174.633 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2013-09-17T00:52:54.633Z,1379379174.633 [AHRS_sp3003D] No Fault, FailCount= 2
2013-09-17T00:52:54.935Z,1379379174.935 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-09-17T00:52:55.696Z,1379379175.696 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:52:55.696Z,1379379175.696 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:52:55.696Z,1379379175.696 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:52:56.073Z,1379379176.073 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2013-09-17T00:52:56.073Z,1379379176.073 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2013-09-17T00:52:56.073Z,1379379176.073 [AHRS_sp3003D] Hardware Fault, FailCount= 3
2013-09-17T00:52:56.073Z,1379379176.073 [AHRS_sp3003D](ERROR): Hardware Fault
2013-09-17T00:52:56.175Z,1379379176.175 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2013-09-17T00:52:56.190Z,1379379176.190 [AHRS_sp3003D](INFO): Powering down
2013-09-17T00:52:57.481Z,1379379177.481 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2013-09-17T00:52:57.482Z,1379379177.482 [AHRS_sp3003D] No Fault, FailCount= 3
2013-09-17T00:52:57.707Z,1379379177.707 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:52:57.792Z,1379379177.792 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-09-17T00:52:58.929Z,1379379178.929 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2013-09-17T00:52:58.929Z,1379379178.929 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2013-09-17T00:52:58.929Z,1379379178.929 [AHRS_sp3003D] Hardware Fault, FailCount= 4
2013-09-17T00:52:58.929Z,1379379178.929 [AHRS_sp3003D](ERROR): Hardware Fault
2013-09-17T00:52:59.055Z,1379379179.055 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2013-09-17T00:52:59.073Z,1379379179.073 [AHRS_sp3003D](INFO): Powering down
2013-09-17T00:52:59.711Z,1379379179.711 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:52:59.711Z,1379379179.711 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:53:00.358Z,1379379180.358 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2013-09-17T00:53:00.358Z,1379379180.358 [AHRS_sp3003D] No Fault, FailCount= 4
2013-09-17T00:53:00.672Z,1379379180.672 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-09-17T00:53:01.714Z,1379379181.714 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:53:01.714Z,1379379181.714 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:53:01.802Z,1379379181.802 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2013-09-17T00:53:01.802Z,1379379181.802 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2013-09-17T00:53:01.802Z,1379379181.802 [AHRS_sp3003D] Hardware Fault, FailCount= 5
2013-09-17T00:53:01.802Z,1379379181.802 [AHRS_sp3003D](ERROR): Hardware Fault
2013-09-17T00:53:01.924Z,1379379181.924 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2013-09-17T00:53:01.925Z,1379379181.925 [CBIT](CRITICAL): Hardware Fault in component: AHRS_sp3003D
2013-09-17T00:53:01.946Z,1379379181.946 [AHRS_sp3003D](INFO): Powering down
2013-09-17T00:53:03.720Z,1379379183.720 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:53:03.721Z,1379379183.721 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:53:03.721Z,1379379183.721 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:53:05.727Z,1379379185.727 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:53:05.728Z,1379379185.728 [DVL_micro](INFO): Querying output modes
2013-09-17T00:53:05.728Z,1379379185.728 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:53:07.739Z,1379379187.739 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:53:07.739Z,1379379187.739 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:53:07.739Z,1379379187.739 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:53:09.750Z,1379379189.750 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:53:11.761Z,1379379191.761 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:53:11.762Z,1379379191.762 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:53:13.764Z,1379379193.764 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:53:13.765Z,1379379193.765 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:53:15.772Z,1379379195.772 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:53:15.772Z,1379379195.772 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:53:15.772Z,1379379195.772 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:53:17.782Z,1379379197.782 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:53:17.782Z,1379379197.782 [DVL_micro](INFO): Querying output modes
2013-09-17T00:53:17.782Z,1379379197.782 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:53:19.786Z,1379379199.786 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:53:19.786Z,1379379199.786 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:53:19.786Z,1379379199.786 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:53:21.796Z,1379379201.796 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:53:23.808Z,1379379203.808 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:53:23.808Z,1379379203.808 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:53:25.811Z,1379379205.811 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:53:25.811Z,1379379205.811 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:53:27.820Z,1379379207.820 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:53:27.820Z,1379379207.820 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:53:27.820Z,1379379207.820 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:53:29.821Z,1379379209.821 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:53:29.821Z,1379379209.821 [DVL_micro](INFO): Querying output modes
2013-09-17T00:53:29.821Z,1379379209.821 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:53:31.824Z,1379379211.824 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:53:31.824Z,1379379211.824 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:53:31.824Z,1379379211.824 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:53:33.827Z,1379379213.827 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:53:34.428Z,1379379214.428 [MassServo](FAULT): Overload Error
2013-09-17T00:53:34.428Z,1379379214.428 [MassServo] Hardware Fault, FailCount= 3
2013-09-17T00:53:34.428Z,1379379214.428 [MassServo](ERROR): Hardware Fault
2013-09-17T00:53:34.503Z,1379379214.503 [CBIT](ERROR): Hardware Fault in component: MassServo
2013-09-17T00:53:34.789Z,1379379214.789 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-09-17T00:53:34.789Z,1379379214.789 [MassServo](INFO): Powering down
2013-09-17T00:53:35.590Z,1379379215.590 [MassServo](DEBUG): Initializing EZServoServo.
2013-09-17T00:53:35.707Z,1379379215.707 [MassServo](DEBUG): Initializing MassServo.
2013-09-17T00:53:35.737Z,1379379215.737 [CBIT](INFO): Clearing failed state for component MassServo
2013-09-17T00:53:35.737Z,1379379215.737 [MassServo] No Fault, FailCount= 3
2013-09-17T00:53:35.831Z,1379379215.831 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:53:35.831Z,1379379215.831 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:53:37.834Z,1379379217.834 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:53:37.834Z,1379379217.834 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:53:39.844Z,1379379219.844 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:53:39.844Z,1379379219.844 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:53:39.845Z,1379379219.845 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:53:41.852Z,1379379221.852 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:53:41.852Z,1379379221.852 [DVL_micro](INFO): Querying output modes
2013-09-17T00:53:41.852Z,1379379221.852 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:53:43.855Z,1379379223.855 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:53:43.855Z,1379379223.855 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:53:43.855Z,1379379223.855 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:53:45.858Z,1379379225.858 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:53:47.870Z,1379379227.870 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:53:47.870Z,1379379227.870 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:53:49.872Z,1379379229.872 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:53:49.873Z,1379379229.873 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:53:51.875Z,1379379231.875 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:53:51.875Z,1379379231.875 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:53:51.875Z,1379379231.875 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:53:53.878Z,1379379233.878 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:53:53.878Z,1379379233.878 [DVL_micro](INFO): Querying output modes
2013-09-17T00:53:53.878Z,1379379233.878 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:53:54.367Z,1379379234.367 [VerticalControl](CRITICAL): Failure to ascend, stopping mission after 300.191315 seconds, depthRate=0.000216 m/s, pitch=30.238388 deg.
2013-09-17T00:53:55.886Z,1379379235.886 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:53:55.886Z,1379379235.886 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:53:55.886Z,1379379235.886 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:53:57.892Z,1379379237.892 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:53:59.904Z,1379379239.904 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:53:59.904Z,1379379239.904 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:54:01.907Z,1379379241.907 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:54:01.907Z,1379379241.907 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:54:03.918Z,1379379243.918 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:54:03.918Z,1379379243.918 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:54:03.918Z,1379379243.918 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:54:05.929Z,1379379245.929 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:54:05.929Z,1379379245.929 [DVL_micro](INFO): Querying output modes
2013-09-17T00:54:05.929Z,1379379245.929 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:54:07.939Z,1379379247.939 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:54:07.940Z,1379379247.940 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:54:07.948Z,1379379247.948 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:54:09.955Z,1379379249.955 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:54:11.967Z,1379379251.967 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:54:11.967Z,1379379251.967 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:54:13.970Z,1379379253.970 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:54:13.970Z,1379379253.970 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:54:15.972Z,1379379255.972 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:54:15.973Z,1379379255.973 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:54:15.973Z,1379379255.973 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:54:17.976Z,1379379257.976 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:54:17.976Z,1379379257.976 [DVL_micro](INFO): Querying output modes
2013-09-17T00:54:17.976Z,1379379257.976 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:54:19.979Z,1379379259.979 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:54:19.979Z,1379379259.979 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:54:19.979Z,1379379259.979 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:54:21.990Z,1379379261.990 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:54:24.002Z,1379379264.002 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:54:24.002Z,1379379264.002 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:54:26.004Z,1379379266.004 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:54:26.005Z,1379379266.005 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:54:28.016Z,1379379268.016 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:54:28.017Z,1379379268.017 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:54:28.018Z,1379379268.018 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:54:30.022Z,1379379270.022 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:54:30.022Z,1379379270.022 [DVL_micro](INFO): Querying output modes
2013-09-17T00:54:30.023Z,1379379270.023 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:54:32.026Z,1379379272.026 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:54:32.026Z,1379379272.026 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:54:32.026Z,1379379272.026 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:54:34.061Z,1379379274.061 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:54:36.068Z,1379379276.068 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:54:36.068Z,1379379276.068 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:54:38.071Z,1379379278.071 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:54:38.071Z,1379379278.071 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:54:40.082Z,1379379280.082 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:54:40.082Z,1379379280.082 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:54:40.082Z,1379379280.082 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:54:41.220Z,1379379281.220 [MassServo](FAULT): Overload Error
2013-09-17T00:54:41.220Z,1379379281.220 [MassServo] Hardware Fault, FailCount= 4
2013-09-17T00:54:41.220Z,1379379281.220 [MassServo](ERROR): Hardware Fault
2013-09-17T00:54:41.286Z,1379379281.286 [CBIT](ERROR): Hardware Fault in component: MassServo
2013-09-17T00:54:41.592Z,1379379281.592 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-09-17T00:54:41.593Z,1379379281.593 [MassServo](INFO): Powering down
2013-09-17T00:54:42.093Z,1379379282.093 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:54:42.093Z,1379379282.093 [DVL_micro](INFO): Querying output modes
2013-09-17T00:54:42.093Z,1379379282.093 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:54:42.372Z,1379379282.372 [MassServo](DEBUG): Initializing EZServoServo.
2013-09-17T00:54:42.489Z,1379379282.489 [MassServo](DEBUG): Initializing MassServo.
2013-09-17T00:54:42.520Z,1379379282.520 [CBIT](INFO): Clearing failed state for component MassServo
2013-09-17T00:54:42.520Z,1379379282.520 [MassServo] No Fault, FailCount= 4
2013-09-17T00:54:44.096Z,1379379284.096 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:54:44.096Z,1379379284.096 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:54:44.096Z,1379379284.096 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:54:46.107Z,1379379286.107 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:54:48.119Z,1379379288.119 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:54:48.119Z,1379379288.119 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:54:50.122Z,1379379290.122 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:54:50.122Z,1379379290.122 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:54:52.128Z,1379379292.128 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:54:52.129Z,1379379292.129 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:54:52.129Z,1379379292.129 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:54:54.140Z,1379379294.140 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:54:54.140Z,1379379294.140 [DVL_micro](INFO): Querying output modes
2013-09-17T00:54:54.140Z,1379379294.140 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:54:56.153Z,1379379296.153 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:54:56.153Z,1379379296.153 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:54:56.154Z,1379379296.154 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:54:58.166Z,1379379298.166 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:55:00.178Z,1379379300.178 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:55:00.178Z,1379379300.178 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:55:02.180Z,1379379302.180 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:55:02.181Z,1379379302.181 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:55:04.191Z,1379379304.191 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:55:04.191Z,1379379304.191 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:55:04.191Z,1379379304.191 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:55:06.202Z,1379379306.202 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:55:06.202Z,1379379306.202 [DVL_micro](INFO): Querying output modes
2013-09-17T00:55:06.203Z,1379379306.203 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:55:08.205Z,1379379308.205 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:55:08.206Z,1379379308.206 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:55:08.206Z,1379379308.206 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:55:10.213Z,1379379310.213 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:55:12.220Z,1379379312.220 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:55:12.220Z,1379379312.220 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:55:14.223Z,1379379314.223 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:55:14.223Z,1379379314.223 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:55:16.226Z,1379379316.226 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:55:16.226Z,1379379316.226 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:55:16.226Z,1379379316.226 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:55:18.233Z,1379379318.233 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:55:18.233Z,1379379318.233 [DVL_micro](INFO): Querying output modes
2013-09-17T00:55:18.233Z,1379379318.233 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:55:20.244Z,1379379320.244 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:55:20.244Z,1379379320.244 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:55:20.244Z,1379379320.244 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:55:22.255Z,1379379322.255 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:55:24.267Z,1379379324.267 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:55:24.267Z,1379379324.267 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:55:26.270Z,1379379326.270 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:55:26.270Z,1379379326.270 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:55:28.281Z,1379379328.281 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:55:28.281Z,1379379328.281 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:55:28.281Z,1379379328.281 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:55:30.288Z,1379379330.288 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:55:30.288Z,1379379330.288 [DVL_micro](INFO): Querying output modes
2013-09-17T00:55:30.288Z,1379379330.288 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:55:32.291Z,1379379332.291 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:55:32.291Z,1379379332.291 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:55:32.291Z,1379379332.291 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:55:34.294Z,1379379334.294 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:55:36.298Z,1379379336.298 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:55:36.298Z,1379379336.298 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:55:38.300Z,1379379338.300 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:55:38.301Z,1379379338.301 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:55:40.303Z,1379379340.303 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:55:40.303Z,1379379340.303 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:55:40.303Z,1379379340.303 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:55:42.306Z,1379379342.306 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:55:42.306Z,1379379342.306 [DVL_micro](INFO): Querying output modes
2013-09-17T00:55:42.307Z,1379379342.307 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:55:44.309Z,1379379344.309 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:55:44.310Z,1379379344.310 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:55:44.310Z,1379379344.310 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:55:46.312Z,1379379346.312 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:55:48.007Z,1379379348.007 [MassServo](FAULT): Overload Error
2013-09-17T00:55:48.007Z,1379379348.007 [MassServo] Hardware Fault, FailCount= 5
2013-09-17T00:55:48.008Z,1379379348.008 [MassServo](ERROR): Hardware Fault
2013-09-17T00:55:48.082Z,1379379348.082 [CBIT](ERROR): Hardware Fault in component: MassServo
2013-09-17T00:55:48.082Z,1379379348.082 [CBIT](CRITICAL): Hardware Fault in component: MassServo
2013-09-17T00:55:48.320Z,1379379348.320 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:55:48.320Z,1379379348.320 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:55:48.388Z,1379379348.388 [MassServo](DEBUG): Uninitialize Mass Servo.
2013-09-17T00:55:48.388Z,1379379348.388 [MassServo](INFO): Powering down
2013-09-17T00:55:49.192Z,1379379349.192 [MassServo](DEBUG): Initializing EZServoServo.
2013-09-17T00:55:49.306Z,1379379349.306 [MassServo](DEBUG): Initializing MassServo.
2013-09-17T00:55:50.323Z,1379379350.323 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:55:50.323Z,1379379350.323 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:55:52.342Z,1379379352.342 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:55:52.342Z,1379379352.342 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:55:52.342Z,1379379352.342 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:55:54.353Z,1379379354.353 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:55:54.353Z,1379379354.353 [DVL_micro](INFO): Querying output modes
2013-09-17T00:55:54.353Z,1379379354.353 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:55:56.364Z,1379379356.364 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:55:56.364Z,1379379356.364 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:55:56.364Z,1379379356.364 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:55:58.376Z,1379379358.376 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:56:00.383Z,1379379360.383 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:56:00.383Z,1379379360.383 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:56:02.386Z,1379379362.386 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:56:02.386Z,1379379362.386 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:56:04.389Z,1379379364.389 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:56:04.389Z,1379379364.389 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:56:04.389Z,1379379364.389 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:56:06.400Z,1379379366.400 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:56:06.400Z,1379379366.400 [DVL_micro](INFO): Querying output modes
2013-09-17T00:56:06.400Z,1379379366.400 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:56:08.411Z,1379379368.411 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:56:08.411Z,1379379368.411 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:56:08.411Z,1379379368.411 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:56:10.418Z,1379379370.418 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:56:12.426Z,1379379372.426 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:56:12.426Z,1379379372.426 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:56:14.428Z,1379379374.428 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:56:14.429Z,1379379374.429 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:56:16.451Z,1379379376.451 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:56:16.451Z,1379379376.451 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:56:16.452Z,1379379376.452 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:56:18.458Z,1379379378.458 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:56:18.459Z,1379379378.459 [DVL_micro](INFO): Querying output modes
2013-09-17T00:56:18.459Z,1379379378.459 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:56:20.469Z,1379379380.469 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:56:20.470Z,1379379380.470 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:56:20.470Z,1379379380.470 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:56:22.473Z,1379379382.473 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:56:24.480Z,1379379384.480 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:56:24.480Z,1379379384.480 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:56:26.485Z,1379379386.485 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:56:26.486Z,1379379386.486 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:56:28.490Z,1379379388.490 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:56:28.490Z,1379379388.490 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:56:28.490Z,1379379388.490 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:56:30.493Z,1379379390.493 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:56:30.493Z,1379379390.493 [DVL_micro](INFO): Querying output modes
2013-09-17T00:56:30.493Z,1379379390.493 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:56:32.500Z,1379379392.500 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:56:32.500Z,1379379392.500 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:56:32.501Z,1379379392.501 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:56:34.511Z,1379379394.511 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:56:36.516Z,1379379396.516 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:56:36.516Z,1379379396.516 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:56:38.518Z,1379379398.518 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:56:38.518Z,1379379398.518 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:56:40.521Z,1379379400.521 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:56:40.521Z,1379379400.521 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:56:40.521Z,1379379400.521 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:56:42.528Z,1379379402.528 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:56:42.528Z,1379379402.528 [DVL_micro](INFO): Querying output modes
2013-09-17T00:56:42.528Z,1379379402.528 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:56:44.537Z,1379379404.537 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:56:44.537Z,1379379404.537 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:56:44.537Z,1379379404.537 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:56:46.542Z,1379379406.542 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:56:48.550Z,1379379408.550 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:56:48.550Z,1379379408.550 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:56:50.552Z,1379379410.552 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:56:50.553Z,1379379410.553 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:56:52.559Z,1379379412.559 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:56:52.559Z,1379379412.559 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:56:52.559Z,1379379412.559 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:56:54.570Z,1379379414.570 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:56:54.570Z,1379379414.570 [DVL_micro](INFO): Querying output modes
2013-09-17T00:56:54.571Z,1379379414.571 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:56:56.577Z,1379379416.577 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:56:56.578Z,1379379416.578 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:56:56.578Z,1379379416.578 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:56:58.585Z,1379379418.585 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:57:00.596Z,1379379420.596 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:57:00.596Z,1379379420.596 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:57:02.599Z,1379379422.599 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:57:02.599Z,1379379422.599 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:57:04.610Z,1379379424.610 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:57:04.610Z,1379379424.610 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:57:04.610Z,1379379424.610 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:57:06.621Z,1379379426.621 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:57:06.621Z,1379379426.621 [DVL_micro](INFO): Querying output modes
2013-09-17T00:57:06.621Z,1379379426.621 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:57:08.632Z,1379379428.632 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:57:08.632Z,1379379428.632 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:57:08.632Z,1379379428.632 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:57:10.635Z,1379379430.635 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:57:12.643Z,1379379432.643 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:57:12.643Z,1379379432.643 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:57:14.646Z,1379379434.646 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:57:14.646Z,1379379434.646 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:57:16.657Z,1379379436.657 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:57:16.657Z,1379379436.657 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:57:16.657Z,1379379436.657 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:57:18.660Z,1379379438.660 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:57:18.660Z,1379379438.660 [DVL_micro](INFO): Querying output modes
2013-09-17T00:57:18.660Z,1379379438.660 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:57:20.671Z,1379379440.671 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:57:20.671Z,1379379440.671 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:57:20.671Z,1379379440.671 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:57:22.674Z,1379379442.674 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:57:24.682Z,1379379444.682 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:57:24.682Z,1379379444.682 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:57:26.684Z,1379379446.684 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:57:26.685Z,1379379446.685 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:57:28.691Z,1379379448.691 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:57:28.691Z,1379379448.691 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:57:28.692Z,1379379448.692 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:57:30.702Z,1379379450.702 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:57:30.702Z,1379379450.702 [DVL_micro](INFO): Querying output modes
2013-09-17T00:57:30.703Z,1379379450.703 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:57:32.709Z,1379379452.709 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:57:32.710Z,1379379452.710 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:57:32.710Z,1379379452.710 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:57:34.721Z,1379379454.721 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:57:35.625Z,1379379455.625 [CBIT](INFO): Clearing failed count for component DropWeight
2013-09-17T00:57:35.625Z,1379379455.625 [DropWeight] No Fault, FailCount= 1
2013-09-17T00:57:35.949Z,1379379455.949 [DropWeight](CRITICAL): DROP WEIGHT MISSING.

2013-09-17T00:57:35.950Z,1379379455.950 [DropWeight] Hardware Fault, FailCount= 1
2013-09-17T00:57:35.950Z,1379379455.950 [DropWeight](ERROR): Hardware Fault
2013-09-17T00:57:36.001Z,1379379456.001 [CBIT](ERROR): Hardware Fault in component: DropWeight
2013-09-17T00:57:36.002Z,1379379456.002 [CBIT](CRITICAL): Hardware Fault in component: DropWeight
2013-09-17T00:57:36.746Z,1379379456.746 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:57:36.746Z,1379379456.746 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:57:38.751Z,1379379458.751 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:57:38.751Z,1379379458.751 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:57:40.754Z,1379379460.754 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:57:40.754Z,1379379460.754 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:57:40.754Z,1379379460.754 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:57:42.769Z,1379379462.769 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:57:42.769Z,1379379462.769 [DVL_micro](INFO): Querying output modes
2013-09-17T00:57:42.770Z,1379379462.770 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:57:44.777Z,1379379464.777 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:57:44.778Z,1379379464.778 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:57:44.779Z,1379379464.779 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:57:46.792Z,1379379466.792 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:57:48.800Z,1379379468.800 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:57:48.800Z,1379379468.800 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:57:50.749Z,1379379470.749 [NAL9602](FAULT): GPS failed to acquire within timeout.
2013-09-17T00:57:50.749Z,1379379470.749 [NAL9602] Data Fault, FailCount= 1
2013-09-17T00:57:50.749Z,1379379470.749 [NAL9602](ERROR): Data Fault
2013-09-17T00:57:50.801Z,1379379470.801 [CBIT](ERROR): Data Fault in component: NAL9602
2013-09-17T00:57:50.806Z,1379379470.806 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:57:50.806Z,1379379470.806 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:57:51.157Z,1379379471.157 [NAL9602](INFO): Powering down
2013-09-17T00:57:52.002Z,1379379472.002 [CBIT](INFO): Clearing failed state for component NAL9602
2013-09-17T00:57:52.002Z,1379379472.002 [NAL9602] No Fault, FailCount= 1
2013-09-17T00:57:52.813Z,1379379472.813 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:57:52.813Z,1379379472.813 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:57:52.813Z,1379379472.813 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:57:54.816Z,1379379474.816 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:57:54.816Z,1379379474.816 [DVL_micro](INFO): Querying output modes
2013-09-17T00:57:54.816Z,1379379474.816 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:57:56.347Z,1379379476.347 [NAL9602](INFO): Powering up NAL9602
2013-09-17T00:57:56.823Z,1379379476.823 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:57:56.823Z,1379379476.823 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:57:56.823Z,1379379476.823 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:57:58.830Z,1379379478.830 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:58:00.834Z,1379379480.834 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:58:00.834Z,1379379480.834 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:58:02.001Z,1379379482.001 [CBIT](INFO): Clearing failed count for component AHRS_sp3003D
2013-09-17T00:58:02.002Z,1379379482.002 [AHRS_sp3003D] No Fault, FailCount= 5
2013-09-17T00:58:02.345Z,1379379482.345 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-09-17T00:58:02.837Z,1379379482.837 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:58:02.837Z,1379379482.837 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:58:03.261Z,1379379483.261 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2013-09-17T00:58:03.261Z,1379379483.261 [AHRS_sp3003D](ERROR): readHeadingMagBin got 0xA401A0
2013-09-17T00:58:03.261Z,1379379483.261 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2013-09-17T00:58:03.261Z,1379379483.261 [AHRS_sp3003D] Hardware Fault, FailCount= 1
2013-09-17T00:58:03.261Z,1379379483.261 [AHRS_sp3003D](ERROR): Hardware Fault
2013-09-17T00:58:03.320Z,1379379483.320 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2013-09-17T00:58:03.541Z,1379379483.541 [AHRS_sp3003D](INFO): Powering down
2013-09-17T00:58:04.798Z,1379379484.798 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2013-09-17T00:58:04.798Z,1379379484.798 [AHRS_sp3003D] No Fault, FailCount= 1
2013-09-17T00:58:04.843Z,1379379484.843 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:58:04.843Z,1379379484.843 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:58:04.844Z,1379379484.844 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:58:05.142Z,1379379485.142 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-09-17T00:58:06.289Z,1379379486.289 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2013-09-17T00:58:06.290Z,1379379486.290 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2013-09-17T00:58:06.290Z,1379379486.290 [AHRS_sp3003D] Hardware Fault, FailCount= 2
2013-09-17T00:58:06.290Z,1379379486.290 [AHRS_sp3003D](ERROR): Hardware Fault
2013-09-17T00:58:06.345Z,1379379486.345 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2013-09-17T00:58:06.367Z,1379379486.367 [AHRS_sp3003D](INFO): Powering down
2013-09-17T00:58:06.811Z,1379379486.811 [NAL9602](INFO): NAL9602 initialized
2013-09-17T00:58:06.846Z,1379379486.846 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:58:06.847Z,1379379486.847 [DVL_micro](INFO): Querying output modes
2013-09-17T00:58:06.847Z,1379379486.847 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:58:07.662Z,1379379487.662 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2013-09-17T00:58:07.662Z,1379379487.662 [AHRS_sp3003D] No Fault, FailCount= 2
2013-09-17T00:58:07.968Z,1379379487.968 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-09-17T00:58:08.858Z,1379379488.858 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:58:08.858Z,1379379488.858 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:58:08.858Z,1379379488.858 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:58:09.114Z,1379379489.114 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2013-09-17T00:58:09.114Z,1379379489.114 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2013-09-17T00:58:09.114Z,1379379489.114 [AHRS_sp3003D] Hardware Fault, FailCount= 3
2013-09-17T00:58:09.114Z,1379379489.114 [AHRS_sp3003D](ERROR): Hardware Fault
2013-09-17T00:58:09.205Z,1379379489.205 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2013-09-17T00:58:09.218Z,1379379489.218 [AHRS_sp3003D](INFO): Powering down
2013-09-17T00:58:10.503Z,1379379490.503 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2013-09-17T00:58:10.503Z,1379379490.503 [AHRS_sp3003D] No Fault, FailCount= 3
2013-09-17T00:58:10.823Z,1379379490.823 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-09-17T00:58:10.869Z,1379379490.869 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:58:11.958Z,1379379491.958 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2013-09-17T00:58:11.958Z,1379379491.958 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2013-09-17T00:58:11.958Z,1379379491.958 [AHRS_sp3003D] Hardware Fault, FailCount= 4
2013-09-17T00:58:11.959Z,1379379491.959 [AHRS_sp3003D](ERROR): Hardware Fault
2013-09-17T00:58:12.021Z,1379379492.021 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2013-09-17T00:58:12.035Z,1379379492.035 [AHRS_sp3003D](INFO): Powering down
2013-09-17T00:58:12.883Z,1379379492.883 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:58:12.883Z,1379379492.883 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:58:13.307Z,1379379493.307 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2013-09-17T00:58:13.307Z,1379379493.307 [AHRS_sp3003D] No Fault, FailCount= 4
2013-09-17T00:58:13.637Z,1379379493.637 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2013-09-17T00:58:14.783Z,1379379494.783 [AHRS_sp3003D](ERROR): readHeadingMagBin UART error: serial timeout
2013-09-17T00:58:14.783Z,1379379494.783 [AHRS_sp3003D](ERROR): SP3003D failed to initialize
2013-09-17T00:58:14.783Z,1379379494.783 [AHRS_sp3003D] Hardware Fault, FailCount= 5
2013-09-17T00:58:14.783Z,1379379494.783 [AHRS_sp3003D](ERROR): Hardware Fault
2013-09-17T00:58:14.886Z,1379379494.886 [CBIT](ERROR): Hardware Fault in component: AHRS_sp3003D
2013-09-17T00:58:14.886Z,1379379494.886 [CBIT](CRITICAL): Hardware Fault in component: AHRS_sp3003D
2013-09-17T00:58:14.887Z,1379379494.887 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:58:14.888Z,1379379494.888 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:58:14.903Z,1379379494.903 [AHRS_sp3003D](INFO): Powering down
2013-09-17T00:58:16.902Z,1379379496.902 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:58:16.902Z,1379379496.902 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:58:16.902Z,1379379496.902 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:58:18.905Z,1379379498.905 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:58:18.905Z,1379379498.905 [DVL_micro](INFO): Querying output modes
2013-09-17T00:58:18.905Z,1379379498.905 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:58:20.916Z,1379379500.916 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:58:20.916Z,1379379500.916 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:58:20.917Z,1379379500.917 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:58:22.927Z,1379379502.927 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:58:24.939Z,1379379504.939 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:58:24.939Z,1379379504.939 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:58:26.942Z,1379379506.942 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:58:26.942Z,1379379506.942 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:58:28.953Z,1379379508.953 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:58:28.953Z,1379379508.953 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:58:28.953Z,1379379508.953 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:58:30.956Z,1379379510.956 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:58:30.956Z,1379379510.956 [DVL_micro](INFO): Querying output modes
2013-09-17T00:58:30.956Z,1379379510.956 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:58:32.963Z,1379379512.963 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:58:32.963Z,1379379512.963 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:58:32.963Z,1379379512.963 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:58:34.974Z,1379379514.974 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:58:36.982Z,1379379516.982 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:58:36.982Z,1379379516.982 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:58:38.985Z,1379379518.985 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:58:38.985Z,1379379518.985 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:58:40.991Z,1379379520.991 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:58:40.991Z,1379379520.991 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:58:40.992Z,1379379520.992 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:58:42.998Z,1379379522.998 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:58:42.999Z,1379379522.999 [DVL_micro](INFO): Querying output modes
2013-09-17T00:58:42.999Z,1379379522.999 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2013-09-17T00:58:45.010Z,1379379525.010 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:58:45.010Z,1379379525.010 [DVL_micro](INFO): Output Modes: No Response
2013-09-17T00:58:45.010Z,1379379525.010 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2013-09-17T00:58:47.017Z,1379379527.017 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:58:49.028Z,1379379529.028 [DVL_micro](INFO): NQ1 requested
2013-09-17T00:58:49.028Z,1379379529.028 [DVL_micro](INFO): Cycling power to configure device.
2013-09-17T00:58:51.031Z,1379379531.031 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2013-09-17T00:58:51.032Z,1379379531.032 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2013-09-17T00:58:53.034Z,1379379533.034 [DVL_micro](DEBUG): cmdResponse: 
2013-09-17T00:58:53.034Z,1379379533.034 [DVL_micro](INFO): Enabling NQ1 output
2013-09-17T00:58:53.034Z,1379379533.034 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2013-09-17T00:58:54.530Z,1379379534.530 [Ve