2012-09-24T18:47:16.650Z,1348512436.650 [Supervisor](DEBUG): Initializing supervisor.
2012-09-24T18:47:16.653Z,1348512436.653 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0
2012-09-24T18:47:16.654Z,1348512436.654 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2012-09-24T18:47:16.655Z,1348512436.655 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4036B4E0
2012-09-24T18:47:16.660Z,1348512436.660 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2012-09-24T18:47:16.671Z,1348512436.671 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2012-09-24T18:47:16.672Z,1348512436.672 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4039B4E0
2012-09-24T18:47:16.672Z,1348512436.672 [ComponentRegistry](DEBUG): SyncComponent "logger" handled in the control thread.
2012-09-24T18:47:16.673Z,1348512436.673 [Supervisor](INFO): Looking for Config files in directory: Config/
2012-09-24T18:47:16.674Z,1348512436.674 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2012-09-24T18:47:16.949Z,1348512436.949 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2012-09-24T18:47:16.950Z,1348512436.950 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2012-09-24T18:47:17.126Z,1348512437.126 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2012-09-24T18:47:17.127Z,1348512437.127 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2012-09-24T18:47:17.211Z,1348512437.211 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2012-09-24T18:47:17.212Z,1348512437.212 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2012-09-24T18:47:17.405Z,1348512437.405 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2012-09-24T18:47:17.405Z,1348512437.405 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2012-09-24T18:47:17.537Z,1348512437.537 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2012-09-24T18:47:17.538Z,1348512437.537 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2012-09-24T18:47:17.767Z,1348512437.767 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2012-09-24T18:47:17.767Z,1348512437.767 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2012-09-24T18:47:17.935Z,1348512437.935 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2012-09-24T18:47:17.935Z,1348512437.935 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2012-09-24T18:47:18.185Z,1348512438.185 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2012-09-24T18:47:18.186Z,1348512438.186 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2012-09-24T18:47:18.285Z,1348512438.284 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2012-09-24T18:47:18.285Z,1348512438.285 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2012-09-24T18:47:18.692Z,1348512438.692 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2012-09-24T18:47:18.692Z,1348512438.692 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2012-09-24T18:47:18.806Z,1348512438.806 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2012-09-24T18:47:18.806Z,1348512438.806 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2012-09-24T18:47:18.892Z,1348512438.892 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2012-09-24T18:47:18.892Z,1348512438.892 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2012-09-24T18:47:18.993Z,1348512438.993 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2012-09-24T18:47:19.163Z,1348512439.163 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2012-09-24T18:47:19.326Z,1348512439.326 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2012-09-24T18:47:19.427Z,1348512439.427 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2012-09-24T18:47:19.527Z,1348512439.527 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2012-09-24T18:47:19.644Z,1348512439.644 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2012-09-24T18:47:19.733Z,1348512439.733 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2012-09-24T18:47:19.826Z,1348512439.826 [Supervisor](IMPORTANT): Reading configuration overrides from Data/config.db
2012-09-24T18:47:19.838Z,1348512439.838 [ComponentRegistry](DEBUG): Loaded Config Component "configDb
2012-09-24T18:47:19.869Z,1348512439.869 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2012-09-24T18:47:19.999Z,1348512439.999 [InternalSim] Loaded
2012-09-24T18:47:19.999Z,1348512439.999 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2012-09-24T18:47:20.000Z,1348512440.000 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2012-09-24T18:47:20.001Z,1348512440.001 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2012-09-24T18:47:20.054Z,1348512440.054 [SBIT](DEBUG): Construct Startup Built In Test.
2012-09-24T18:47:20.067Z,1348512440.067 [SBIT] Loaded
2012-09-24T18:47:20.068Z,1348512440.068 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2012-09-24T18:47:20.068Z,1348512440.068 [IBIT](DEBUG): Construct Initiated Built In Test.
2012-09-24T18:47:20.094Z,1348512440.095 [IBIT] Loaded
2012-09-24T18:47:20.095Z,1348512440.095 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2012-09-24T18:47:20.098Z,1348512440.098 [CBIT](DEBUG): Construct CBIT Built In Test.
2012-09-24T18:47:20.208Z,1348512440.208 [CBIT] Loaded
2012-09-24T18:47:20.208Z,1348512440.208 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2012-09-24T18:47:20.208Z,1348512440.208 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2012-09-24T18:47:20.209Z,1348512440.209 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2012-09-24T18:47:20.348Z,1348512440.348 [BuoyancyServo] Loaded
2012-09-24T18:47:20.349Z,1348512440.349 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2012-09-24T18:47:20.358Z,1348512440.358 [ElevatorServo] Loaded
2012-09-24T18:47:20.358Z,1348512440.358 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2012-09-24T18:47:20.369Z,1348512440.369 [MassServo] Loaded
2012-09-24T18:47:20.369Z,1348512440.369 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2012-09-24T18:47:20.380Z,1348512440.381 [RudderServo] Loaded
2012-09-24T18:47:20.381Z,1348512440.381 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2012-09-24T18:47:20.392Z,1348512440.392 [ThrusterServo] Loaded
2012-09-24T18:47:20.392Z,1348512440.392 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2012-09-24T18:47:20.393Z,1348512440.393 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2012-09-24T18:47:20.393Z,1348512440.393 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2012-09-24T18:47:20.410Z,1348512440.410 [DepthRateCalculator] Loaded
2012-09-24T18:47:20.410Z,1348512440.410 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2012-09-24T18:47:22.960Z,1348512442.960 [HFRadarModelCalc] Loaded
2012-09-24T18:47:22.960Z,1348512442.960 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread.
2012-09-24T18:47:22.976Z,1348512442.976 [NavChart] Loaded
2012-09-24T18:47:22.976Z,1348512442.976 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2012-09-24T18:47:22.982Z,1348512442.982 [PitchRateCalculator] Loaded
2012-09-24T18:47:22.982Z,1348512442.982 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2012-09-24T18:47:22.988Z,1348512442.988 [SpeedCalculator] Loaded
2012-09-24T18:47:22.988Z,1348512442.988 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2012-09-24T18:47:23.002Z,1348512443.002 [TempGradientCalculator] Loaded
2012-09-24T18:47:23.003Z,1348512443.003 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2012-09-24T18:47:23.008Z,1348512443.008 [YawRateCalculator] Loaded
2012-09-24T18:47:23.008Z,1348512443.008 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2012-09-24T18:47:23.027Z,1348512443.027 [Navigation] Loaded
2012-09-24T18:47:23.027Z,1348512443.027 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread.
2012-09-24T18:47:23.028Z,1348512443.028 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2012-09-24T18:47:23.029Z,1348512443.029 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2012-09-24T18:47:23.238Z,1348512443.238 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2012-09-24T18:47:23.239Z,1348512443.239 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2012-09-24T18:47:23.260Z,1348512443.260 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2012-09-24T18:47:23.260Z,1348512443.260 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2012-09-24T18:47:23.298Z,1348512443.298 [VerticalControl](DEBUG): Construct VerticalControl.
2012-09-24T18:47:23.341Z,1348512443.341 [VerticalControl] Loaded
2012-09-24T18:47:23.341Z,1348512443.341 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2012-09-24T18:47:23.342Z,1348512443.342 [HorizontalControl](DEBUG): Construct HorizontalControl.
2012-09-24T18:47:23.361Z,1348512443.362 [HorizontalControl] Loaded
2012-09-24T18:47:23.362Z,1348512443.362 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2012-09-24T18:47:23.363Z,1348512443.363 [SpeedControl](DEBUG): Construct SpeedControl.
2012-09-24T18:47:23.364Z,1348512443.364 [SpeedControl] Loaded
2012-09-24T18:47:23.365Z,1348512443.365 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2012-09-24T18:47:23.366Z,1348512443.365 [LoopControl](DEBUG): Construct LoopControl.
2012-09-24T18:47:23.366Z,1348512443.366 [LoopControl] Loaded
2012-09-24T18:47:23.366Z,1348512443.366 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2012-09-24T18:47:23.367Z,1348512443.367 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2012-09-24T18:47:23.367Z,1348512443.367 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2012-09-24T18:47:23.372Z,1348512443.372 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator.
2012-09-24T18:47:23.377Z,1348512443.377 [AsyncPiEstimator] Loaded
2012-09-24T18:47:23.377Z,1348512443.377 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread.
2012-09-24T18:47:23.378Z,1348512443.378 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 4060A4E0
2012-09-24T18:47:23.379Z,1348512443.379 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2012-09-24T18:47:23.380Z,1348512443.379 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2012-09-24T18:47:23.494Z,1348512443.494 [AHRS_sp3003D] Loaded
2012-09-24T18:47:23.495Z,1348512443.495 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2012-09-24T18:47:23.508Z,1348512443.508 [Depth_Keller] Loaded
2012-09-24T18:47:23.508Z,1348512443.508 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2012-09-24T18:47:23.513Z,1348512443.513 [DropWeight] Loaded
2012-09-24T18:47:23.513Z,1348512443.513 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2012-09-24T18:47:23.623Z,1348512443.623 [DVL_micro] Loaded
2012-09-24T18:47:23.623Z,1348512443.623 [ComponentRegistry](DEBUG): Component "DVL_micro" handled in its own thread.
2012-09-24T18:47:23.624Z,1348512443.624 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 4068A4E0
2012-09-24T18:47:23.702Z,1348512443.702 [NAL9602] Loaded
2012-09-24T18:47:23.702Z,1348512443.702 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2012-09-24T18:47:23.749Z,1348512443.749 [Onboard] Loaded
2012-09-24T18:47:23.749Z,1348512443.749 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2012-09-24T18:47:23.756Z,1348512443.756 [Radio_Freewave] Loaded
2012-09-24T18:47:23.756Z,1348512443.756 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread.
2012-09-24T18:47:23.757Z,1348512443.757 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2012-09-24T18:47:23.757Z,1348512443.757 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2012-09-24T18:47:23.826Z,1348512443.826 [CTD_NeilBrown] Loaded
2012-09-24T18:47:23.826Z,1348512443.826 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2012-09-24T18:47:23.827Z,1348512443.827 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 406E24E0
2012-09-24T18:47:23.837Z,1348512443.837 [PAR_Licor] Loaded
2012-09-24T18:47:23.837Z,1348512443.837 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2012-09-24T18:47:23.853Z,1348512443.853 [Turbulence_NPS] Loaded
2012-09-24T18:47:23.854Z,1348512443.854 [ComponentRegistry](DEBUG): Component "Turbulence_NPS" handled in its own thread.
2012-09-24T18:47:23.855Z,1348512443.855 [Turbulence_NPS ThreadHandler](DEBUG): Created PCaller Thread at 407124E0
2012-09-24T18:47:23.883Z,1348512443.883 [WetLabsBB2FL] Loaded
2012-09-24T18:47:23.883Z,1348512443.883 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2012-09-24T18:47:23.884Z,1348512443.884 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407424E0
2012-09-24T18:47:23.885Z,1348512443.885 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2012-09-24T18:47:23.887Z,1348512443.887 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2012-09-24T18:47:23.888Z,1348512443.888 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2012-09-24T18:47:23.894Z,1348512443.894 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2012-09-24T18:47:23.895Z,1348512443.895 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 407724E0
2012-09-24T18:47:23.900Z,1348512443.900 [Supervisor](DEBUG): Running supervisor.
2012-09-24T18:47:23.901Z,1348512443.901 [CommandLine](INFO): Thread ID is 3851
2012-09-24T18:47:23.904Z,1348512443.904 [controlThread](INFO): Thread ID is 3850
2012-09-24T18:47:23.904Z,1348512443.904 [controlThread](DEBUG): Initializing ControlThread
2012-09-24T18:47:23.904Z,1348512443.904 [CycleStarter](INFO): Thread ID is 3849
2012-09-24T18:47:23.905Z,1348512443.905 [InternalSim](DEBUG):  InternalSim initializing...
2012-09-24T18:47:23.939Z,1348512443.939 [AsyncPiEstimator](INFO): Thread ID is 3912
2012-09-24T18:47:23.939Z,1348512443.939 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator.
2012-09-24T18:47:23.947Z,1348512443.947 [SBIT](INFO): Initialize SBIT Component.
2012-09-24T18:47:23.948Z,1348512443.948 [SBIT](IMPORTANT): Tethys CM Info:
$Rev: 9883
2012-09-24T18:47:23.948Z,1348512443.948 [IBIT](INFO): Initialize IBIT Component.
2012-09-24T18:47:23.949Z,1348512443.949 [CBIT](DEBUG): Initialize CBIT Component.
2012-09-24T18:47:23.950Z,1348512443.950 [CBIT](CRITICAL): LAST REBOOT DUE TO WATCHDOG TIMER RESET.
2012-09-24T18:47:23.951Z,1348512443.951 [DVL_micro](INFO): Thread ID is 3913
2012-09-24T18:47:23.960Z,1348512443.960 [DVL_micro](INFO): Initializing
2012-09-24T18:47:23.960Z,1348512443.960 [DVL_micro](INFO): start:Powering up
2012-09-24T18:47:23.961Z,1348512443.961 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T18:47:23.976Z,1348512443.976 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2012-09-24T18:47:23.976Z,1348512443.976 [NavChart](DEBUG): Initialize NavChart Derivation.
2012-09-24T18:47:23.977Z,1348512443.977 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2012-09-24T18:47:23.977Z,1348512443.977 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2012-09-24T18:47:23.978Z,1348512443.977 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2012-09-24T18:47:23.979Z,1348512443.979 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2012-09-24T18:47:23.979Z,1348512443.979 [Navigation](DEBUG): Initializing Navigation.
2012-09-24T18:47:23.980Z,1348512443.980 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2012-09-24T18:47:23.982Z,1348512443.981 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2012-09-24T18:47:23.982Z,1348512443.982 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2012-09-24T18:47:23.983Z,1348512443.983 [LoopControl](DEBUG): Initialize LoopControlComponent.
2012-09-24T18:47:23.987Z,1348512443.987 [CTD_NeilBrown](INFO): Thread ID is 3914
2012-09-24T18:47:23.988Z,1348512443.988 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2012-09-24T18:47:23.995Z,1348512443.995 [Turbulence_NPS](INFO): Thread ID is 3915
2012-09-24T18:47:23.996Z,1348512443.996 [Turbulence_NPS](DEBUG): Initializing Turbulence_NPS.
2012-09-24T18:47:24.001Z,1348512444.001 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2012-09-24T18:47:24.014Z,1348512444.014 [WetLabsBB2FL](INFO): Thread ID is 3916
2012-09-24T18:47:24.015Z,1348512444.015 [WetLabsBB2FL](INFO): Powering down
2012-09-24T18:47:24.028Z,1348512444.028 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2012-09-24T18:47:24.041Z,1348512444.041 [NavChartDb](INFO): Thread ID is 3917
2012-09-24T18:47:24.047Z,1348512444.047 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2012-09-24T18:47:24.058Z,1348512444.058 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
       Id="Startup">

    <Guidance:GoToSurface RunIn="Progression"/>

    <Aggregate Id="StartupSatComms" >

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

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

    </Aggregate>

</Mission>


2012-09-24T18:47:24.059Z,1348512444.059 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2012-09-24T18:47:24.086Z,1348512444.086 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool
2012-09-24T18:47:24.088Z,1348512444.088 [Default:GPS:A.SetSpeed](DEBUG): Construct.
2012-09-24T18:47:24.091Z,1348512444.092 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface.
2012-09-24T18:47:24.099Z,1348512444.099 [Default:Iridium:A.SetSpeed](DEBUG): Construct.
2012-09-24T18:47:24.102Z,1348512444.102 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface.
2012-09-24T18:47:24.109Z,1348512444.109 [Default:Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2012-09-24T18:47:24.117Z,1348512444.117 [Default:D.SetSpeed](DEBUG): Construct.
2012-09-24T18:47:24.120Z,1348512444.121 [Default:E.GoToSurface](DEBUG): Construct GoToSurface.
2012-09-24T18:47:24.125Z,1348512444.125 [Default:F.Wait](DEBUG): Construct Wait.
2012-09-24T18:47:24.129Z,1348512444.129 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
xmlns:Control="Tethys/Control"
xmlns:Guidance="Tethys/Guidance" 
xmlns:Units="Tethys/Units"
xmlns:Universal="Tethys/Universal"
xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
Id="Default">

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

    <Aggregate Id="GPS" >

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

<!-- Run at high speed -->

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

<!-- Get to the surface -->

        <Guidance:GoToSurface RunIn="Sequence"/>

<!-- Get GPS fix -->

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

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

    </Aggregate>

    <Aggregate Id="Iridium" >

<!-- Run at high speed -->

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

<!-- Get to the surface -->

        <Guidance:GoToSurface RunIn="Sequence"/>

<!-- Do data comms -->

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

    </Aggregate>

    <Aggregate Id="CallIridium" >

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

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

        <Call RefId="Iridium"/>

    </Aggregate>

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

<!-- Run at low speed -->

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

    <Guidance:GoToSurface RunIn="Parallel"/>

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

</Mission>


2012-09-24T18:47:24.133Z,1348512444.133 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Depth_Keller,DropWeight,NAL9602,Onboard,Radio_Freewave,PAR_Licor,Depth_Keller,PAR_Licor,DepthRateCalculator,HFRadarModelCalc,NavChart,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,Navigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,logger,
2012-09-24T18:47:24.153Z,1348512444.153 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-09-24T18:47:24.309Z,1348512444.309 [Radio_Freewave](INFO): Powering up
2012-09-24T18:47:24.411Z,1348512444.411 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2012-09-24T18:47:24.419Z,1348512444.419 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2012-09-24T18:47:24.425Z,1348512444.425 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2012-09-24T18:47:24.447Z,1348512444.447 [MassServo](DEBUG): Initializing MassServo.
2012-09-24T18:47:24.456Z,1348512444.456 [RudderServo](DEBUG): Initializing EZServoServo.
2012-09-24T18:47:24.459Z,1348512444.459 [RudderServo](DEBUG): Initializing RudderServo.
2012-09-24T18:47:24.465Z,1348512444.465 [ThrusterServo](DEBUG): Initializing EZServoServo.
2012-09-24T18:47:24.466Z,1348512444.466 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2012-09-24T18:47:25.519Z,1348512445.519 [DVL_micro](INFO): Querying output modes
2012-09-24T18:47:25.519Z,1348512445.519 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T18:47:25.523Z,1348512445.523 [DVL_micro](DEBUG): cmdResponse: 01 03 16
2012-09-24T18:47:25.523Z,1348512445.523 [DVL_micro](INFO): NQ1 output enabled
2012-09-24T18:47:25.523Z,1348512445.523 [DVL_micro](INFO): RSSI output enabled
2012-09-24T18:47:25.523Z,1348512445.523 [DVL_micro](INFO): ADCP output enabled
2012-09-24T18:47:25.524Z,1348512445.523 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T18:47:25.531Z,1348512445.531 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2012-09-24T18:47:25.535Z,1348512445.536 [DVL_micro](INFO): pause:Powering down
2012-09-24T18:47:26.333Z,1348512446.333 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2012-09-24T18:47:26.338Z,1348512446.338 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2012-09-24T18:47:26.350Z,1348512446.350 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2012-09-24T18:47:26.670Z,1348512446.670 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2012-09-24T18:47:27.014Z,1348512447.014 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2012-09-24T18:47:27.018Z,1348512447.018 [NavChartDb](INFO): Creating index for Soundings
2012-09-24T18:47:27.022Z,1348512447.022 [NavChartDb](INFO): Creating index for Contour 0p0
2012-09-24T18:47:27.285Z,1348512447.285 [NavChartDb](INFO): Creating index for Contour 1p8
2012-09-24T18:47:27.298Z,1348512447.298 [NavChartDb](INFO): Creating index for Contour 3p6
2012-09-24T18:47:27.314Z,1348512447.314 [NavChartDb](INFO): Creating index for Contour 5p4
2012-09-24T18:47:27.332Z,1348512447.332 [NavChartDb](INFO): Creating index for Contour 9p1
2012-09-24T18:47:27.352Z,1348512447.352 [NavChartDb](INFO): Creating index for Contour 10p9
2012-09-24T18:47:27.354Z,1348512447.354 [NavChartDb](INFO): Creating index for Contour 18p2
2012-09-24T18:47:27.357Z,1348512447.358 [NavChartDb](INFO): Creating index for Contour 36p5
2012-09-24T18:47:27.360Z,1348512447.360 [NavChartDb](INFO): Creating index for Contour 54p8
2012-09-24T18:47:27.363Z,1348512447.363 [NavChartDb](INFO): Creating index for Contour 73p1
2012-09-24T18:47:27.365Z,1348512447.365 [NavChartDb](INFO): Creating index for Contour 91p4
2012-09-24T18:47:27.375Z,1348512447.375 [NavChartDb](INFO): Creating index for Contour 182p8
2012-09-24T18:47:27.390Z,1348512447.390 [NavChartDb](INFO): Creating index for Contour 365p7
2012-09-24T18:47:27.397Z,1348512447.397 [NavChartDb](INFO): Creating index for Contour 548p6
2012-09-24T18:47:27.399Z,1348512447.399 [NavChartDb](INFO): Creating index for Contour 731p5
2012-09-24T18:47:27.401Z,1348512447.401 [NavChartDb](INFO): Creating index for Contour 914p4
2012-09-24T18:47:27.403Z,1348512447.403 [NavChartDb](INFO): Creating index for Contour 1097p2
2012-09-24T18:47:27.405Z,1348512447.405 [NavChartDb](INFO): Creating index for Contour 1280p1
2012-09-24T18:47:27.408Z,1348512447.408 [NavChartDb](INFO): Creating index for Contour 1463p0
2012-09-24T18:47:27.412Z,1348512447.412 [NavChartDb](INFO): Creating index for Contour 1645p9
2012-09-24T18:47:27.414Z,1348512447.414 [NavChartDb](INFO): Creating index for Contour 1828p8
2012-09-24T18:47:27.424Z,1348512447.424 [NavChartDb](INFO): Creating index for Contour 2011p6
2012-09-24T18:47:27.436Z,1348512447.436 [NavChartDb](INFO): Creating index for Contour 2194p5
2012-09-24T18:47:27.447Z,1348512447.447 [NavChartDb](INFO): Creating index for Contour 2377p4
2012-09-24T18:47:27.453Z,1348512447.453 [NavChartDb](INFO): Creating index for Contour 2560p3
2012-09-24T18:47:27.455Z,1348512447.455 [NavChartDb](INFO): Creating index for Contour 2743p2
2012-09-24T18:47:27.457Z,1348512447.457 [NavChartDb](INFO): Creating index for Contour 2926p0
2012-09-24T18:47:27.459Z,1348512447.459 [NavChartDb](INFO): Creating index for Contour 3108p9
2012-09-24T18:47:27.461Z,1348512447.461 [NavChartDb](INFO): Creating index for Contour 3291p8
2012-09-24T18:47:27.464Z,1348512447.464 [NavChartDb](INFO): Creating index for Contour 3474p7
2012-09-24T18:47:27.491Z,1348512447.491 [NavChartDb](INFO): Creating index for Contour 3657p6
2012-09-24T18:47:28.935Z,1348512448.935 [NAL9602](INFO): Powering up NAL9602
2012-09-24T18:47:39.387Z,1348512459.387 [NAL9602](INFO): NAL9602 initialized
2012-09-24T18:47:39.430Z,1348512459.430 [SBIT](IMPORTANT): Beginning Startup BIT
2012-09-24T18:47:39.433Z,1348512459.433 [CBIT](IMPORTANT): Beginning GF scan
2012-09-24T18:47:52.661Z,1348512472.661 [SBIT](FAULT): Mass: EXPECTED:0.004800 ACTUAL:-0.001040
2012-09-24T18:47:52.661Z,1348512472.661 [SBIT](FAULT): Rudder: EXPECTED:15.000000 ACTUAL:14.901049
2012-09-24T18:48:05.817Z,1348512485.816 [CBIT](IMPORTANT): No ground fault detected
2012-09-24T18:48:33.409Z,1348512513.409 [SBIT](CRITICAL): SBIT FAILED
2012-09-24T18:48:33.775Z,1348512513.775 [MissionManager](IMPORTANT): Started mission Startup
2012-09-24T18:48:33.775Z,1348512513.776 [Startup] Running Loop=1
2012-09-24T18:48:33.776Z,1348512513.776 [Startup](INFO): Aggregate::initialize Startup
2012-09-24T18:48:33.776Z,1348512513.776 [Startup:A.GoToSurface] Running Loop=1
2012-09-24T18:48:33.776Z,1348512513.776 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-09-24T18:48:33.781Z,1348512513.781 [Startup:StartupSatComms] Running Loop=1
2012-09-24T18:48:33.781Z,1348512513.781 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2012-09-24T18:48:33.781Z,1348512513.782 [Startup:StartupSatComms:A] Running Loop=1
2012-09-24T18:48:34.172Z,1348512514.172 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-09-24T18:48:38.537Z,1348512518.537 [CommandLine](IMPORTANT): got command set NAL9602.latitude_fix 36.799999 degree
2012-09-24T18:48:38.539Z,1348512518.539 [CommandLine](IMPORTANT): got command set NAL9602.longitude_fix -121.779999 degree
2012-09-24T18:48:38.612Z,1348512518.612 [Startup:StartupSatComms:A] Stopped
2012-09-24T18:48:38.612Z,1348512518.612 [Startup:StartupSatComms:B] Running Loop=1
2012-09-24T18:48:38.913Z,1348512518.913 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2012-09-24T18:48:38.991Z,1348512518.991 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2012-09-24T18:48:38.992Z,1348512518.992 [Startup:StartupSatComms:B] Stopped
2012-09-24T18:48:38.993Z,1348512518.993 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2012-09-24T18:48:38.993Z,1348512518.993 [Startup:StartupSatComms] Stopped
2012-09-24T18:48:38.993Z,1348512518.993 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2012-09-24T18:48:38.993Z,1348512518.993 [Startup](INFO): Completed Startup
2012-09-24T18:48:38.994Z,1348512518.994 [Startup] Stopped
2012-09-24T18:48:38.994Z,1348512518.994 [Startup](INFO): Aggregate::uninitialize Startup
2012-09-24T18:48:38.994Z,1348512518.994 [Startup:A.GoToSurface] Stopped
2012-09-24T18:48:38.994Z,1348512518.994 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-09-24T18:48:39.385Z,1348512519.385 [MissionManager](IMPORTANT): Started mission Default
2012-09-24T18:48:39.386Z,1348512519.385 [Default] Running Loop=1
2012-09-24T18:48:39.386Z,1348512519.386 [Default](INFO): Aggregate::initialize Default
2012-09-24T18:48:39.386Z,1348512519.386 [Default:D.SetSpeed] Running Loop=1
2012-09-24T18:48:39.386Z,1348512519.386 [Default:D.SetSpeed](DEBUG): Initialize.
2012-09-24T18:48:39.386Z,1348512519.386 [Default:E.GoToSurface] Running Loop=1
2012-09-24T18:48:39.386Z,1348512519.386 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-09-24T18:48:39.386Z,1348512519.386 [Default:Iridium] Running Loop=1
2012-09-24T18:48:39.387Z,1348512519.387 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-09-24T18:48:39.387Z,1348512519.387 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-09-24T18:48:39.387Z,1348512519.387 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-09-24T18:48:39.387Z,1348512519.387 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-09-24T18:48:39.387Z,1348512519.387 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-09-24T18:48:39.387Z,1348512519.387 [Default:E.GoToSurface] Running Loop=1
2012-09-24T18:48:39.393Z,1348512519.393 [Default:D.SetSpeed] Running Loop=1
2012-09-24T18:48:39.402Z,1348512519.402 [Default:Iridium:B.GoToSurface] Stopped
2012-09-24T18:48:39.403Z,1348512519.402 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-09-24T18:48:39.403Z,1348512519.403 [Default:Iridium:Read_Iridium] Running Loop=1
2012-09-24T18:48:39.403Z,1348512519.403 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-09-24T18:48:39.407Z,1348512519.408 [Default:GPS] Running Loop=1
2012-09-24T18:48:39.408Z,1348512519.408 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-09-24T18:48:39.408Z,1348512519.408 [Default:GPS:A.SetSpeed] Running Loop=1
2012-09-24T18:48:39.408Z,1348512519.408 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-09-24T18:48:39.408Z,1348512519.408 [Default:GPS:B.GoToSurface] Running Loop=1
2012-09-24T18:48:39.408Z,1348512519.408 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-09-24T18:48:39.414Z,1348512519.414 [Default:GPS:B.GoToSurface] Stopped
2012-09-24T18:48:39.414Z,1348512519.414 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-09-24T18:48:39.414Z,1348512519.414 [Default:GPS:Read_GPS] Running Loop=1
2012-09-24T18:48:39.414Z,1348512519.414 [Default:GPS:A.SetSpeed] Running Loop=1
2012-09-24T18:48:39.785Z,1348512519.785 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2012-09-24T18:48:39.788Z,1348512519.788 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-09-24T18:49:16.159Z,1348512556.159 [CommandLine](IMPORTANT): got command run ./Missions/Science/science_to_67_70.xml
2012-09-24T18:49:16.159Z,1348512556.159 [MissionManager](INFO): Loading Mission: ./Missions/Science/science_to_67_70.xml
2012-09-24T18:49:16.245Z,1348512556.245 [MissionManager](INFO): DefineArg science_to_67_70.MinAltitude = 10.000000 m
2012-09-24T18:49:16.248Z,1348512556.248 [MissionManager](INFO): DefineArg science_to_67_70.MaxDepth = 105.000000 m
2012-09-24T18:49:16.252Z,1348512556.252 [MissionManager](INFO): DefineArg science_to_67_70.MinOffshore = 2000.000000 m
2012-09-24T18:49:16.255Z,1348512556.255 [MissionManager](INFO): DefineArg science_to_67_70.YoYoMinDepth = 3.000000 m
2012-09-24T18:49:16.259Z,1348512556.259 [MissionManager](INFO): DefineArg science_to_67_70.YoYoMaxDepth = 100.000000 m
2012-09-24T18:49:16.263Z,1348512556.263 [MissionManager](INFO): DefineArg science_to_67_70.YoYoUpPitch = 20.000000 arcdeg
2012-09-24T18:49:16.266Z,1348512556.266 [MissionManager](INFO): DefineArg science_to_67_70.YoYoDownPitch = -15.000000 arcdeg
2012-09-24T18:49:16.269Z,1348512556.269 [MissionManager](INFO): DefineArg science_to_67_70.WptLat = 36.120000 arcdeg
2012-09-24T18:49:16.272Z,1348512556.272 [MissionManager](INFO): DefineArg science_to_67_70.WptLon = -123.486000 arcdeg
2012-09-24T18:49:16.278Z,1348512556.278 [MissionManager](INFO): DefineArg science_to_67_70.NeedCommsTime = 120.000000 min
2012-09-24T18:49:16.281Z,1348512556.281 [MissionManager](INFO): DefineArg science_to_67_70.Speed = 1.000000 m/s
2012-09-24T18:49:16.284Z,1348512556.284 [MissionManager](INFO): DefineArg science_to_67_70.Timeout = 36.000000 h
2012-09-24T18:49:16.288Z,1348512556.288 [MissionManager](INFO): DefineArg science_to_67_70.BuoyancyNeutral = 0.000330 n/a
2012-09-24T18:49:16.291Z,1348512556.291 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2012-09-24T18:49:16.301Z,1348512556.301 [science_to_67_70:AltEnvWrapper:B.Wait](DEBUG): Construct Wait.
2012-09-24T18:49:16.302Z,1348512556.302 [science_to_67_70:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2012-09-24T18:49:16.312Z,1348512556.312 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2012-09-24T18:49:16.319Z,1348512556.319 [science_to_67_70:OffshoreWrapper:B.Wait](DEBUG): Construct Wait.
2012-09-24T18:49:16.320Z,1348512556.320 [MissionManager](INFO): Inserting Stack: Missions/Insert/Surface.xml
2012-09-24T18:49:16.351Z,1348512556.351 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.SurfaceDepthRate = nan m/s
2012-09-24T18:49:16.354Z,1348512556.354 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.SurfacePitch = nan arcdeg
2012-09-24T18:49:16.357Z,1348512556.357 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.SurfaceSpeed = 0.500000 m/s
2012-09-24T18:49:16.360Z,1348512556.360 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.IridiumTimeout = 30.000000 min
2012-09-24T18:49:16.361Z,1348512556.361 [science_to_67_70:SURFACECOMMS:A.GoToSurface](DEBUG): Construct GoToSurface.
2012-09-24T18:49:16.380Z,1348512556.380 [MissionManager](INFO): RedefineArg science_to_67_70:SURFACECOMMS.SurfacePitch = 20.000000 arcdeg
2012-09-24T18:49:16.386Z,1348512556.385 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2012-09-24T18:49:16.511Z,1348512556.511 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleISUS = 0 bool
2012-09-24T18:49:16.513Z,1348512556.513 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleRSSI = 1 bool
2012-09-24T18:49:16.528Z,1348512556.528 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleADCP = 1 bool
2012-09-24T18:49:16.530Z,1348512556.530 [MissionManager](INFO): DefineArg science_to_67_70:Science.PeakDetectChlActive = 0 bool
2012-09-24T18:49:16.533Z,1348512556.533 [MissionManager](INFO): DefineArg science_to_67_70:Science.PeakDetectNO3Active = 0 bool
2012-09-24T18:49:16.536Z,1348512556.536 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleAanderaaO2 = 0.000000 n/a
2012-09-24T18:49:16.552Z,1348512556.552 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleNeilBrown = 1.000000 n/a
2012-09-24T18:49:16.555Z,1348512556.555 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleTurbulence_NPS = 1.000000 n/a
2012-09-24T18:49:16.558Z,1348512556.558 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleWetLabsBB2FL = 1.000000 n/a
2012-09-24T18:49:16.561Z,1348512556.561 [MissionManager](INFO): DefineArg science_to_67_70:Science.EnabledDVL = 1.000000 n/a
2012-09-24T18:49:16.577Z,1348512556.577 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChl = 0.000000 ug/l
2012-09-24T18:49:16.580Z,1348512556.580 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChlDepth = 0.000000 m
2012-09-24T18:49:16.583Z,1348512556.583 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChlLatitude = nan arcdeg
2012-09-24T18:49:16.586Z,1348512556.586 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChlLongitude = nan arcdeg
2012-09-24T18:49:16.601Z,1348512556.601 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3 = 0.000000 umol/l
2012-09-24T18:49:16.604Z,1348512556.604 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3Depth = 0.000000 m
2012-09-24T18:49:16.610Z,1348512556.610 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3Latitude = nan arcdeg
2012-09-24T18:49:16.613Z,1348512556.613 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3Longitude = nan arcdeg
2012-09-24T18:49:16.637Z,1348512556.637 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2012-09-24T18:49:16.657Z,1348512556.657 [science_to_67_70:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2012-09-24T18:49:16.666Z,1348512556.666 [MissionManager](INFO): RedefineArg science_to_67_70:Science.SampleISUS = 1 bool
2012-09-24T18:49:16.679Z,1348512556.679 [MissionManager](INFO): RedefineArg science_to_67_70:Science.PeakDetectChlActive = 1 bool
2012-09-24T18:49:16.680Z,1348512556.681 [science_to_67_70:G.Buoyancy](DEBUG): Construct Buoyancy.
2012-09-24T18:49:16.684Z,1348512556.684 [science_to_67_70:H.SetSpeed](DEBUG): Construct.
2012-09-24T18:49:16.687Z,1348512556.687 [science_to_67_70:I.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2012-09-24T18:49:16.713Z,1348512556.713 [science_to_67_70:J.YoYo](DEBUG): Construct YoYo.
2012-09-24T18:49:16.760Z,1348512556.760 [science_to_67_70:Wpt.Point](DEBUG): Construct.
2012-09-24T18:49:16.808Z,1348512556.808 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Trigger="Tethys/Trigger" 
       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/Trigger http://aosn.mbari.org/tethys/Xml/Trigger.xsd
                           Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
       Id="science_to_67_70">

    <Description>
        Vehicle yo-yo's to the specified waypoint, with science turned on.
    </Description>

    <DefineArg Name="MinAltitude"><Description>
        Minimum height above the sea floor for the entire mission.
    </Description><Units:meter/><Value>10.0</Value></DefineArg>

    <DefineArg Name="MaxDepth"><Description>
        Maximum depth for the entire mission.
    </Description><Units:meter/><Value>105.0</Value></DefineArg>

    <DefineArg Name="MinOffshore"><Description>
        Minimum offshore distance for the entire mission.
    </Description><Units:meter/><Value>2000.0</Value></DefineArg>

    <DefineArg Name="YoYoMinDepth"><Description>
        Minimum depth while performing the YoYo behavior.
    </Description><Units:meter/><Value>3.0</Value></DefineArg>

    <DefineArg Name="YoYoMaxDepth"><Description>
        Maximum depth while performing the YoYo behavior.
    </Description><Units:meter/><Value>100.0</Value></DefineArg>

    <DefineArg Name="YoYoUpPitch"><Description>
        Vehicle up pitch while performing the YoYo behavior.
    </Description><Units:degree/><Value>20.0</Value></DefineArg>

    <DefineArg Name="YoYoDownPitch"><Description>
        Vehicle down pitch while performing the YoYo behavior.
    </Description><Units:degree/><Value>-15.0</Value></DefineArg>

    <DefineArg Name="WptLat"><Description>
        Latitude of waypoint.
    </Description><Units:degree/><Value>36.120</Value></DefineArg>

    <DefineArg Name="WptLon"><Description>
        Longitude of waypoint.
    </Description><Units:degree/><Value>-123.486</Value></DefineArg>

    <DefineArg Name="NeedCommsTime"><Description>
        Elapsed time after previous surface communications when vehicle will
        begin to ascend for additional surface communications, if the vehicle
        has a positive pitch. If the vehicle has a negative pitch, it will wait
        up to 10 minutes more to begin ascent.
    </Description><Units:minute/><Value>120</Value></DefineArg>

    <DefineArg Name="Speed"><Description>
        Speed while performing the YoYo behavior.
    </Description><Units:meter_per_second/><Value>1</Value></DefineArg>

    <DefineArg Name="Timeout"><Description>
        Maximum length of mission
    </Description><Units:hour/><Value>36</Value></DefineArg>

    <DefineArg Name="BuoyancyNeutral"><Description>
        Buoyancy bladder position while performing the YoYo behavior. Defaults
        to buoyancyNeutral setting in the Config/Control.cfg file.
    </Description><Control:VerticalControl.buoyancyNeutral/></DefineArg>

    <Timeout Duration="Timeout"/>

    <Aggregate Id="AltEnvWrapper">

        <When><Arg Name="MinAltitude"/>
            <Gt><Units:meter/><Value>0</Value></Gt>
        </When>

        <Guidance:AltitudeEnvelope RunIn="Parallel">
            <Description>
                Safety behavior that keeps vehicle off sea floor
            </Description>
            <Setting><Guidance:AltitudeEnvelope.minAltitude/><Arg Name="MinAltitude"/></Setting>
        </Guidance:AltitudeEnvelope>

        <Guidance:Wait RunIn="Sequence"/>

    </Aggregate>

    <Guidance:DepthEnvelope RunIn="Parallel">
        <Description>
            Safety behavior that keeps vehicle from going too deep
        </Description>
        <Setting><Guidance:DepthEnvelope.maxDepth/><Arg Name="MaxDepth"/></Setting>
    </Guidance:DepthEnvelope>

    <Aggregate Id="OffshoreWrapper">

        <When><Arg Name="MinOffshore"/>
            <Gt><Units:meter/><Value>0</Value></Gt>
        </When>

        <Guidance:OffshoreEnvelope RunIn="Parallel">
            <Description>
                Safety behavior that keeps vehicle away from the shore
            </Description>
            <Setting><Guidance:OffshoreEnvelope.minOffshore/><Arg Name="MinOffshore"/></Setting>
        </Guidance:OffshoreEnvelope>

        <Guidance:Wait RunIn="Sequence"/>

    </Aggregate>

    <Insert Filename="Insert/Surface.xml" Id="SURFACECOMMS">
        <RedefineArg Name="SurfacePitch"><Units:degree/><Value>20</Value></RedefineArg>
    </Insert>

<!-- GPS Update.  Don't go too long w/o a GPS fix. -->

    <Aggregate Id="NeedComms">

        <When><Universal:platform_pitch_angle/>
            <Gt><Units:degree/><Value>0</Value></Gt>
            <And>
            <Elapsed><Universal:latitude_fix/></Elapsed><Gt><Arg Name="NeedCommsTime"/></Gt></And>
            <Or>
            <Elapsed><Universal:latitude_fix/></Elapsed><Gt><Arg Name="NeedCommsTime"/><Add><Units:minute/><Value>10</Value></Add></Gt></Or>
        </When>

        <Call RefId="SURFACECOMMS"/>

    </Aggregate>

    <Insert Filename="Insert/Science.xml">
        <Description>
            If we're executing here or below, lets' get science data!
        </Description>
        <RedefineArg Name="SampleISUS"><True/></RedefineArg>
        <RedefineArg Name="PeakDetectChlActive"><True/></RedefineArg>
    </Insert>

    <Guidance:Buoyancy RunIn="Parallel">
        <Setting><Guidance:Buoyancy.position /><Arg Name="BuoyancyNeutral"/></Setting>
    </Guidance:Buoyancy>

    <Guidance:SetSpeed RunIn="Parallel">
        <Setting><Guidance:SetSpeed.speed/><Arg Name="Speed"/></Setting>
    </Guidance:SetSpeed>

    <Guidance:DepthEnvelope RunIn="Parallel">
        <Setting><Guidance:DepthEnvelope.minDepth/><Arg Name="YoYoMinDepth"/></Setting>
        <Setting><Guidance:DepthEnvelope.maxDepth/><Arg Name="YoYoMaxDepth"/></Setting>
        <Setting><Guidance:DepthEnvelope.upPitch/><Arg Name="YoYoUpPitch"/></Setting>
        <Setting><Guidance:DepthEnvelope.downPitch/><Arg Name="YoYoDownPitch"/></Setting>
    </Guidance:DepthEnvelope>

    <Guidance:YoYo RunIn="Parallel">
        <Setting><Guidance:YoYo.upPitch/><Arg Name="YoYoUpPitch"/></Setting>
        <Setting><Guidance:YoYo.downPitch/><Arg Name="YoYoDownPitch"/></Setting>
    </Guidance:YoYo>

    <Guidance:Point Id="Wpt" RunIn="Sequence">
        <Setting><Guidance:Point.latitude/><Arg Name="WptLat"/></Setting>
        <Setting><Guidance:Point.longitude/><Arg Name="WptLon"/></Setting>
    </Guidance:Point>

</Mission>


2012-09-24T18:49:16.808Z,1348512556.808 [CommandLine](IMPORTANT): Running ./Missions/Science/science_to_67_70.xml
2012-09-24T18:49:17.224Z,1348512557.224 [Default] Stopped
2012-09-24T18:49:17.224Z,1348512557.224 [Default](INFO): Aggregate::uninitialize Default
2012-09-24T18:49:17.224Z,1348512557.224 [Default:GPS] Stopped
2012-09-24T18:49:17.224Z,1348512557.224 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-09-24T18:49:17.224Z,1348512557.224 [Default:GPS:A.SetSpeed] Stopped
2012-09-24T18:49:17.224Z,1348512557.224 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-09-24T18:49:17.225Z,1348512557.224 [Default:GPS:Read_GPS] Stopped
2012-09-24T18:49:17.225Z,1348512557.225 [Default:Iridium] Stopped
2012-09-24T18:49:17.225Z,1348512557.225 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-09-24T18:49:17.225Z,1348512557.225 [Default:Iridium:A.SetSpeed] Stopped
2012-09-24T18:49:17.225Z,1348512557.225 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-09-24T18:49:17.225Z,1348512557.225 [Default:Iridium:Read_Iridium] Stopped
2012-09-24T18:49:17.225Z,1348512557.225 [Default:D.SetSpeed] Stopped
2012-09-24T18:49:17.225Z,1348512557.225 [Default:D.SetSpeed](DEBUG): Uninitialize.
2012-09-24T18:49:17.225Z,1348512557.225 [Default:E.GoToSurface] Stopped
2012-09-24T18:49:17.225Z,1348512557.225 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-09-24T18:49:17.225Z,1348512557.225 [MissionManager](IMPORTANT): Started mission science_to_67_70
2012-09-24T18:49:17.225Z,1348512557.225 [science_to_67_70] Running Loop=1
2012-09-24T18:49:17.226Z,1348512557.226 [science_to_67_70](INFO): Aggregate::initialize science_to_67_70
2012-09-24T18:49:17.226Z,1348512557.226 [science_to_67_70:B.DepthEnvelope] Running Loop=1
2012-09-24T18:49:17.226Z,1348512557.226 [science_to_67_70:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2012-09-24T18:49:17.227Z,1348512557.227 [science_to_67_70:Science] Running Loop=1
2012-09-24T18:49:17.227Z,1348512557.227 [science_to_67_70:Science](INFO): Aggregate::initialize science_to_67_70:Science
2012-09-24T18:49:17.227Z,1348512557.227 [science_to_67_70:Science:A] Running Loop=1
2012-09-24T18:49:17.227Z,1348512557.227 [science_to_67_70:Science:B] Running Loop=1
2012-09-24T18:49:17.227Z,1348512557.227 [science_to_67_70:Science:C] Running Loop=1
2012-09-24T18:49:17.227Z,1348512557.227 [science_to_67_70:Science:D] Running Loop=1
2012-09-24T18:49:17.228Z,1348512557.227 [science_to_67_70:Science:E] Running Loop=1
2012-09-24T18:49:17.228Z,1348512557.227 [science_to_67_70:Science:F] Running Loop=1
2012-09-24T18:49:17.228Z,1348512557.228 [science_to_67_70:Science:G] Running Loop=1
2012-09-24T18:49:17.228Z,1348512557.228 [science_to_67_70:G.Buoyancy] Running Loop=1
2012-09-24T18:49:17.228Z,1348512557.228 [science_to_67_70:G.Buoyancy](DEBUG): Initialize Buoyancy Component.
2012-09-24T18:49:17.228Z,1348512557.228 [science_to_67_70:H.SetSpeed] Running Loop=1
2012-09-24T18:49:17.228Z,1348512557.228 [science_to_67_70:H.SetSpeed](DEBUG): Initialize.
2012-09-24T18:49:17.228Z,1348512557.228 [science_to_67_70:I.DepthEnvelope] Running Loop=1
2012-09-24T18:49:17.228Z,1348512557.228 [science_to_67_70:I.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2012-09-24T18:49:17.229Z,1348512557.229 [science_to_67_70:J.YoYo] Running Loop=1
2012-09-24T18:49:17.229Z,1348512557.229 [science_to_67_70:J.YoYo](DEBUG): Initialize YoYoComponent.
2012-09-24T18:49:17.229Z,1348512557.229 [science_to_67_70:SURFACECOMMS] Running Loop=1
2012-09-24T18:49:17.230Z,1348512557.230 [science_to_67_70:SURFACECOMMS](INFO): Aggregate::initialize science_to_67_70:SURFACECOMMS
2012-09-24T18:49:17.230Z,1348512557.230 [science_to_67_70:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-09-24T18:49:17.230Z,1348512557.230 [science_to_67_70:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-09-24T18:49:17.231Z,1348512557.231 [science_to_67_70:J.YoYo] Running Loop=1
2012-09-24T18:49:17.236Z,1348512557.236 [science_to_67_70:I.DepthEnvelope] Running Loop=1
2012-09-24T18:49:17.241Z,1348512557.241 [science_to_67_70:H.SetSpeed] Running Loop=1
2012-09-24T18:49:17.246Z,1348512557.246 [science_to_67_70:G.Buoyancy] Running Loop=1
2012-09-24T18:49:17.251Z,1348512557.251 [science_to_67_70:Science] Running Loop=1
2012-09-24T18:49:17.251Z,1348512557.251 [science_to_67_70:Science:PeakDetectChl] Running Loop=1
2012-09-24T18:49:17.251Z,1348512557.251 [science_to_67_70:Science:PeakDetectChl](INFO): Aggregate::initialize science_to_67_70:Science:PeakDetectChl
2012-09-24T18:49:17.252Z,1348512557.252 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2012-09-24T18:49:17.252Z,1348512557.252 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth](INFO): Initialize.
2012-09-24T18:49:17.257Z,1348512557.257 [science_to_67_70:Science:G] Running Loop=1
2012-09-24T18:49:17.258Z,1348512557.258 [science_to_67_70:Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2012-09-24T18:49:17.260Z,1348512557.260 [science_to_67_70:Science:F] Running Loop=1
2012-09-24T18:49:17.260Z,1348512557.260 [science_to_67_70:Science:F](DEBUG): Initialize ReadDataComponent to sense DVL_micro.Beam1RSSI
2012-09-24T18:49:17.262Z,1348512557.262 [science_to_67_70:Science:E] Running Loop=1
2012-09-24T18:49:17.262Z,1348512557.262 [science_to_67_70:Science:E](DEBUG): Initialize ReadDataComponent to sense DVL_micro.AdcpXWaterVelocity
2012-09-24T18:49:17.264Z,1348512557.264 [science_to_67_70:Science:D] Running Loop=1
2012-09-24T18:49:17.264Z,1348512557.264 [science_to_67_70:Science:D](DEBUG): Initialize ReadDataComponent to sense Turbulence_NPS.MicroCond
2012-09-24T18:49:17.266Z,1348512557.266 [science_to_67_70:Science:C] Running Loop=1
2012-09-24T18:49:17.267Z,1348512557.267 [science_to_67_70:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2012-09-24T18:49:17.268Z,1348512557.268 [science_to_67_70:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2012-09-24T18:49:17.269Z,1348512557.269 [science_to_67_70:Science:B] Running Loop=1
2012-09-24T18:49:17.270Z,1348512557.270 [science_to_67_70:Science:B](DEBUG): Initialize ReadDataComponent to sense mole_concentration_of_nitrate_in_sea_water
2012-09-24T18:49:17.279Z,1348512557.279 [science_to_67_70:SURFACECOMMS:B] Running Loop=1
2012-09-24T18:49:17.279Z,1348512557.279 [science_to_67_70:SURFACECOMMS:B](INFO): Aggregate::initialize science_to_67_70:SURFACECOMMS:B
2012-09-24T18:49:17.279Z,1348512557.279 [science_to_67_70:SURFACECOMMS:B:A] Running Loop=1
2012-09-24T18:49:17.279Z,1348512557.279 [science_to_67_70:OffshoreWrapper] Running Loop=1
2012-09-24T18:49:17.280Z,1348512557.280 [science_to_67_70:OffshoreWrapper](INFO): Aggregate::initialize science_to_67_70:OffshoreWrapper
2012-09-24T18:49:17.280Z,1348512557.280 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope] Running Loop=1
2012-09-24T18:49:17.280Z,1348512557.280 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2012-09-24T18:49:17.280Z,1348512557.280 [science_to_67_70:OffshoreWrapper:B.Wait] Running Loop=1
2012-09-24T18:49:17.280Z,1348512557.280 [science_to_67_70:OffshoreWrapper:B.Wait](DEBUG): Initialize Wait Component.
2012-09-24T18:49:17.286Z,1348512557.286 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope] Running Loop=1
2012-09-24T18:49:17.291Z,1348512557.291 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=4
2012-09-24T18:49:17.291Z,1348512557.291 [WetLabsBB2FL](INFO): Powering up
2012-09-24T18:49:17.292Z,1348512557.292 [science_to_67_70:B.DepthEnvelope] Running Loop=1
2012-09-24T18:49:17.297Z,1348512557.297 [science_to_67_70:AltEnvWrapper] Running Loop=1
2012-09-24T18:49:17.298Z,1348512557.297 [science_to_67_70:AltEnvWrapper](INFO): Aggregate::initialize science_to_67_70:AltEnvWrapper
2012-09-24T18:49:17.298Z,1348512557.298 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope] Running Loop=1
2012-09-24T18:49:17.298Z,1348512557.298 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2012-09-24T18:49:17.298Z,1348512557.298 [science_to_67_70:AltEnvWrapper:B.Wait] Running Loop=1
2012-09-24T18:49:17.298Z,1348512557.298 [science_to_67_70:AltEnvWrapper:B.Wait](DEBUG): Initialize Wait Component.
2012-09-24T18:49:17.304Z,1348512557.304 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope] Running Loop=1
2012-09-24T18:49:17.544Z,1348512557.544 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-09-24T18:49:17.544Z,1348512557.544 [DVL_micro](INFO): resume:Powering up
2012-09-24T18:49:17.674Z,1348512557.674 [science_to_67_70:J.YoYo] Preempted
2012-09-24T18:49:17.674Z,1348512557.674 [science_to_67_70:I.DepthEnvelope] Preempted
2012-09-24T18:49:17.674Z,1348512557.674 [science_to_67_70:H.SetSpeed] Preempted
2012-09-24T18:49:17.674Z,1348512557.674 [science_to_67_70:G.Buoyancy] Preempted
2012-09-24T18:49:17.674Z,1348512557.674 [science_to_67_70:Science] Preempted
2012-09-24T18:49:17.674Z,1348512557.674 [science_to_67_70:Science:B] Preempted
2012-09-24T18:49:17.674Z,1348512557.674 [science_to_67_70:Science:C] Preempted
2012-09-24T18:49:17.674Z,1348512557.674 [science_to_67_70:Science:D] Preempted
2012-09-24T18:49:17.674Z,1348512557.674 [science_to_67_70:Science:E] Preempted
2012-09-24T18:49:17.674Z,1348512557.674 [science_to_67_70:Science:F] Preempted
2012-09-24T18:49:17.674Z,1348512557.674 [science_to_67_70:Science:G] Preempted
2012-09-24T18:49:17.675Z,1348512557.675 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2012-09-24T18:49:17.679Z,1348512557.679 [science_to_67_70:SURFACECOMMS:B:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-09-24T18:49:18.483Z,1348512558.483 [WetLabsBB2FL](INFO): Powering down
2012-09-24T18:49:18.876Z,1348512558.876 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2012-09-24T18:49:19.050Z,1348512559.050 [DVL_micro](INFO): Querying output modes
2012-09-24T18:49:19.051Z,1348512559.051 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T18:49:19.058Z,1348512559.059 [DVL_micro](DEBUG): cmdResponse: 01 03 16
2012-09-24T18:49:19.059Z,1348512559.059 [DVL_micro](INFO): NQ1 output enabled
2012-09-24T18:49:19.059Z,1348512559.059 [DVL_micro](INFO): RSSI output enabled
2012-09-24T18:49:19.059Z,1348512559.059 [DVL_micro](INFO): ADCP output enabled
2012-09-24T18:49:19.059Z,1348512559.059 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T18:49:19.067Z,1348512559.067 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2012-09-24T18:49:19.068Z,1348512559.068 [DVL_micro](INFO): RSSI unrequested
2012-09-24T18:49:19.068Z,1348512559.068 [DVL_micro](INFO): ADCP unrequested
2012-09-24T18:49:20.575Z,1348512560.575 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T18:49:20.640Z,1348512560.640 [DVL_micro](DEBUG): cmdResponse: ���2�$��o�o�B�X�P�u��V�	��F�Y�W�s�>�V�Z�\���x��o��������������y�X�/�_���v����h��
2012-09-24T18:49:20.640Z,1348512560.640 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T18:49:20.640Z,1348512560.640 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T18:49:20.650Z,1348512560.651 [DVL_micro](DEBUG): cmdResponse: L�#��m���R�t��h�;����|�3�	���������
��g��������5�F�c�������������������	������
2012-09-24T18:49:20.651Z,1348512560.651 [DVL_micro](INFO): Querying output modes
2012-09-24T18:49:20.651Z,1348512560.651 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T18:49:20.663Z,1348512560.662 [DVL_micro](DEBUG): cmdResponse: Q�V�N�"��������������������������b�O��������F�#�1� �1�A�z�i�l���P�q�C�7�V�r�_�P�W��K�B�
2012-09-24T18:49:20.663Z,1348512560.663 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T18:49:20.674Z,1348512560.674 [DVL_micro](DEBUG): cmdResponse: �����2�I�P�q�7�������������������g�v���?�A�r�E�+�
��������������������7�C�������������
2012-09-24T18:49:20.903Z,1348512560.903 [DVL_micro](INFO): NQ1 requested
2012-09-24T18:49:22.411Z,1348512562.411 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T18:49:22.603Z,1348512562.602 [DVL_micro](DEBUG): cmdResponse: 00
2012-09-24T18:49:22.603Z,1348512562.603 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T18:49:22.603Z,1348512562.603 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T18:49:22.794Z,1348512562.795 [DVL_micro](DEBUG): cmdResponse: 01
2012-09-24T18:49:22.795Z,1348512562.795 [DVL_micro](INFO): Querying output modes
2012-09-24T18:49:22.795Z,1348512562.795 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T18:49:22.799Z,1348512562.799 [DVL_micro](DEBUG): cmdResponse: 01
2012-09-24T18:49:22.799Z,1348512562.799 [DVL_micro](INFO): NQ1 output enabled
2012-09-24T18:49:22.799Z,1348512562.799 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T18:49:22.803Z,1348512562.803 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2012-09-24T18:49:25.331Z,1348512565.330 [DVL_micro](DEBUG): NQ1
2012-09-24T18:49:27.867Z,1348512567.866 [DVL_micro](DEBUG): NQ1
2012-09-24T18:49:30.403Z,1348512570.402 [DVL_micro](DEBUG): NQ1
2012-09-24T18:49:32.939Z,1348512572.939 [DVL_micro](DEBUG): NQ1
2012-09-24T18:49:35.475Z,1348512575.474 [DVL_micro](DEBUG): NQ1
2012-09-24T18:49:38.015Z,1348512578.015 [DVL_micro](DEBUG): NQ1
2012-09-24T18:49:40.550Z,1348512580.550 [DVL_micro](DEBUG): NQ1
2012-09-24T18:49:43.086Z,1348512583.086 [DVL_micro](DEBUG): NQ1
2012-09-24T18:49:45.627Z,1348512585.626 [DVL_micro](DEBUG): NQ1
2012-09-24T18:49:48.162Z,1348512588.162 [DVL_micro](DEBUG): NQ1
2012-09-24T18:49:49.465Z,1348512589.465 [CommandLine](IMPORTANT): got command set NAL9602.latitude_fix 36.799999 degree
2012-09-24T18:49:49.465Z,1348512589.466 [CommandLine](IMPORTANT): got command set NAL9602.longitude_fix -121.779999 degree
2012-09-24T18:49:49.650Z,1348512589.650 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2012-09-24T18:49:49.676Z,1348512589.676 [science_to_67_70:SURFACECOMMS:B:A] Stopped
2012-09-24T18:49:49.677Z,1348512589.677 [science_to_67_70:SURFACECOMMS:B:B] Running Loop=1
2012-09-24T18:49:50.074Z,1348512590.074 [science_to_67_70:SURFACECOMMS:B:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2012-09-24T18:49:50.707Z,1348512590.707 [DVL_micro](DEBUG): NQ1
2012-09-24T18:49:52.778Z,1348512592.778 [CommandLine](IMPORTANT): got command set NAL9602.latitude_fix 36.799999 degree
2012-09-24T18:49:52.779Z,1348512592.779 [CommandLine](IMPORTANT): got command set NAL9602.longitude_fix -121.779999 degree
2012-09-24T18:49:53.013Z,1348512593.013 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2012-09-24T18:49:53.235Z,1348512593.234 [DVL_micro](DEBUG): NQ1
2012-09-24T18:49:53.783Z,1348512593.783 [science_to_67_70:SURFACECOMMS:B:B] Stopped
2012-09-24T18:49:53.783Z,1348512593.783 [science_to_67_70:SURFACECOMMS:B:C] Running Loop=1
2012-09-24T18:49:54.019Z,1348512594.019 [science_to_67_70:SURFACECOMMS:B:C](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-09-24T18:49:55.767Z,1348512595.767 [DVL_micro](DEBUG): NQ1
2012-09-24T18:49:56.305Z,1348512596.305 [CommandLine](IMPORTANT): got command set NAL9602.latitude_fix 36.799999 degree
2012-09-24T18:49:56.306Z,1348512596.306 [CommandLine](IMPORTANT): got command set NAL9602.longitude_fix -121.779999 degree
2012-09-24T18:49:56.431Z,1348512596.431 [science_to_67_70:SURFACECOMMS:B:C] Stopped
2012-09-24T18:49:56.431Z,1348512596.431 [science_to_67_70:SURFACECOMMS:B](INFO): Completed science_to_67_70:SURFACECOMMS:B
2012-09-24T18:49:56.431Z,1348512596.431 [science_to_67_70:SURFACECOMMS:B] Stopped
2012-09-24T18:49:56.431Z,1348512596.431 [science_to_67_70:SURFACECOMMS:B](INFO): Aggregate::uninitialize science_to_67_70:SURFACECOMMS:B
2012-09-24T18:49:56.432Z,1348512596.432 [science_to_67_70:SURFACECOMMS](INFO): Completed science_to_67_70:SURFACECOMMS
2012-09-24T18:49:56.432Z,1348512596.432 [science_to_67_70:SURFACECOMMS] Stopped
2012-09-24T18:49:56.432Z,1348512596.432 [science_to_67_70:SURFACECOMMS](INFO): Aggregate::uninitialize science_to_67_70:SURFACECOMMS
2012-09-24T18:49:56.433Z,1348512596.432 [science_to_67_70:SURFACECOMMS:A.GoToSurface] Stopped
2012-09-24T18:49:56.433Z,1348512596.432 [science_to_67_70:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-09-24T18:49:56.433Z,1348512596.433 [science_to_67_70:Wpt.Point] Running Loop=1
2012-09-24T18:49:56.433Z,1348512596.433 [science_to_67_70:Wpt.Point](DEBUG): Initialize.
2012-09-24T18:49:56.564Z,1348512596.564 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2012-09-24T18:49:56.814Z,1348512596.814 [science_to_67_70:J.YoYo] Running Loop=1
2012-09-24T18:49:56.815Z,1348512596.815 [science_to_67_70:I.DepthEnvelope] Running Loop=1
2012-09-24T18:49:56.816Z,1348512596.816 [science_to_67_70:H.SetSpeed] Running Loop=1
2012-09-24T18:49:56.816Z,1348512596.816 [science_to_67_70:G.Buoyancy] Running Loop=1
2012-09-24T18:49:56.816Z,1348512596.816 [science_to_67_70:Science] Running Loop=1
2012-09-24T18:49:56.817Z,1348512596.817 [science_to_67_70:Science:G] Running Loop=1
2012-09-24T18:49:56.817Z,1348512596.817 [science_to_67_70:Science:F] Running Loop=1
2012-09-24T18:49:56.817Z,1348512596.818 [science_to_67_70:Science:E] Running Loop=1
2012-09-24T18:49:56.818Z,1348512596.818 [science_to_67_70:Science:D] Running Loop=1
2012-09-24T18:49:56.818Z,1348512596.818 [science_to_67_70:Science:C] Running Loop=1
2012-09-24T18:49:56.818Z,1348512596.818 [science_to_67_70:Science:B] Running Loop=1
2012-09-24T18:49:56.880Z,1348512596.880 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=4
2012-09-24T18:49:56.880Z,1348512596.880 [WetLabsBB2FL](INFO): Powering up
2012-09-24T18:49:56.988Z,1348512596.988 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2012-09-24T18:49:57.859Z,1348512597.859 [DVL_micro](INFO): RSSI requested
2012-09-24T18:49:57.859Z,1348512597.859 [DVL_micro](INFO): ADCP requested
2012-09-24T18:49:59.367Z,1348512599.367 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-09-24T18:49:59.559Z,1348512599.559 [DVL_micro](DEBUG): cmdResponse: 00
2012-09-24T18:49:59.559Z,1348512599.559 [DVL_micro](INFO): Enabling RSSI output
2012-09-24T18:49:59.560Z,1348512599.560 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 16 7

2012-09-24T18:49:59.588Z,1348512599.588 [CTD_NeilBrown](ERROR): Pressure reading out of range: -13.750000 decibar
2012-09-24T18:49:59.744Z,1348512599.744 [DVL_micro](DEBUG): cmdResponse: 16
2012-09-24T18:49:59.744Z,1348512599.744 [DVL_micro](INFO): Enabling ADCP output
2012-09-24T18:49:59.744Z,1348512599.744 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 03 3

2012-09-24T18:49:59.927Z,1348512599.927 [DVL_micro](DEBUG): cmdResponse: 03 16
2012-09-24T18:49:59.927Z,1348512599.927 [DVL_micro](INFO): Enabling NQ1 output
2012-09-24T18:49:59.927Z,1348512599.927 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-09-24T18:50:00.111Z,1348512600.111 [DVL_micro](DEBUG): cmdResponse: 01 03 16
2012-09-24T18:50:00.111Z,1348512600.111 [DVL_micro](INFO): Querying output modes
2012-09-24T18:50:00.111Z,1348512600.111 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-09-24T18:50:00.115Z,1348512600.115 [DVL_micro](DEBUG): cmdResponse: 01 03 16
2012-09-24T18:50:00.115Z,1348512600.115 [DVL_micro](INFO): NQ1 output enabled
2012-09-24T18:50:00.115Z,1348512600.115 [DVL_micro](INFO): RSSI output enabled
2012-09-24T18:50:00.115Z,1348512600.115 [DVL_micro](INFO): ADCP output enabled
2012-09-24T18:50:00.115Z,1348512600.115 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-09-24T18:50:00.123Z,1348512600.123 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2012-09-24T18:50:00.259Z,1348512600.259 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=41
2012-09-24T18:50:00.315Z,1348512600.315 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=61
2012-09-24T18:50:00.368Z,1348512600.368 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=17
2012-09-24T18:50:00.419Z,1348512600.419 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-09-24T18:50:00.699Z,1348512600.699 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=25
2012-09-24T18:50:00.803Z,1348512600.803 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=29
2012-09-24T18:50:00.904Z,1348512600.904 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=1
2012-09-24T18:50:01.007Z,1348512601.007 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-09-24T18:50:01.143Z,1348512601.143 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=8
2012-09-24T18:50:01.200Z,1348512601.200 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=28
2012-09-24T18:50:01.252Z,1348512601.252 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=17
2012-09-24T18:50:01.307Z,1348512601.307 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-09-24T18:50:01.587Z,1348512601.587 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24
2012-09-24T18:50:01.691Z,1348512601.691 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28
2012-09-24T18:50:01.791Z,1348512601.791 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=2
2012-09-24T18:50:01.888Z,1348512601.888 [NAL9602](INFO): Powering down
2012-09-24T18:50:01.899Z,1348512601.899 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-09-24T18:50:02.244Z,1348512602.244 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=25
2012-09-24T18:50:02.387Z,1348512602.387 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=29
2012-09-24T18:50:02.532Z,1348512602.532 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=1
2012-09-24T18:50:02.675Z,1348512602.675 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4
2012-09-24T18:50:02.999Z,1348512602.999 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=60
2012-09-24T18:50:03.111Z,1348512603.111 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4
2012-09-24T18:50:03.232Z,1348512603.232 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=44
2012-09-24T18:50:03.348Z,1348512603.348 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4
2012-09-24T18:50:03.872Z,1348512603.872 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=29
2012-09-24T18:50:04.107Z,1348512604.107 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=37
2012-09-24T18:50:04.344Z,1348512604.344 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=13
2012-09-24T18:50:04.580Z,1348512604.579 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4
2012-09-24T18:50:05.499Z,1348512605.499 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=28
2012-09-24T18:50:05.971Z,1348512605.971 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=5
2012-09-24T18:50:06.448Z,1348512606.448 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=45
2012-09-24T18:50:06.924Z,1348512606.924 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4
2012-09-24T18:50:07.799Z,1348512607.799 [DVL_micro](DEBUG): NQ1
2012-09-24T18:50:07.825Z,1348512607.825 [DVL_micro](DEBUG): ADCP
2012-09-24T18:50:08.659Z,1348512608.659 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=6
2012-09-24T18:50:09.495Z,1348512609.495 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=15
2012-09-24T18:50:10.339Z,1348512610.339 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=87
2012-09-24T18:50:11.171Z,1348512611.171 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-24T18:50:11.307Z,1348512611.307 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=8
2012-09-24T18:50:11.363Z,1348512611.363 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=60
2012-09-24T18:50:11.416Z,1348512611.416 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=16
2012-09-24T18:50:11.467Z,1348512611.467 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-09-24T18:50:11.751Z,1348512611.751 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=56
2012-09-24T18:50:11.851Z,1348512611.851 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28
2012-09-24T18:50:11.955Z,1348512611.955 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=32
2012-09-24T18:50:12.056Z,1348512612.056 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-09-24T18:50:12.191Z,1348512612.191 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=8
2012-09-24T18:50:12.247Z,1348512612.247 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=29
2012-09-24T18:50:12.303Z,1348512612.303 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=49
2012-09-24T18:50:12.355Z,1348512612.355 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-09-24T18:50:12.639Z,1348512612.639 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=56
2012-09-24T18:50:12.740Z,1348512612.740 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28
2012-09-24T18:50:12.839Z,1348512612.839 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=0
2012-09-24T18:50:12.956Z,1348512612.956 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-09-24T18:50:13.295Z,1348512613.295 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=56
2012-09-24T18:50:13.439Z,1348512613.439 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=28
2012-09-24T18:50:13.579Z,1348512613.579 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=0
2012-09-24T18:50:13.727Z,1348512613.727 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4
2012-09-24T18:50:14.043Z,1348512614.043 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=6
2012-09-24T18:50:14.163Z,1348512614.163 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=46
2012-09-24T18:50:14.278Z,1348512614.278 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=22
2012-09-24T18:50:14.395Z,1348512614.395 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4
2012-09-24T18:50:14.919Z,1348512614.920 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=28
2012-09-24T18:50:15.155Z,1348512615.155 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=36
2012-09-24T18:50:15.401Z,1348512615.401 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=108
2012-09-24T18:50:15.628Z,1348512615.628 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4
2012-09-24T18:50:16.547Z,1348512616.547 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=28
2012-09-24T18:50:17.019Z,1348512617.019 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=5
2012-09-24T18:50:17.497Z,1348512617.497 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=13
2012-09-24T18:50:17.967Z,1348512617.967 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4
2012-09-24T18:50:18.851Z,1348512618.851 [DVL_micro](DEBUG): NQ1
2012-09-24T18:50:18.875Z,1348512618.875 [DVL_micro](DEBUG): ADCP
2012-09-24T18:50:19.711Z,1348512619.711 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=51
2012-09-24T18:50:20.547Z,1348512620.547 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=28
2012-09-24T18:50:21.383Z,1348512621.383 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-24T18:50:22.223Z,1348512622.223 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-24T18:50:22.360Z,1348512622.360 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=41
2012-09-24T18:50:22.415Z,1348512622.415 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=62
2012-09-24T18:50:22.466Z,1348512622.466 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=18
2012-09-24T18:50:22.519Z,1348512622.519 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-09-24T18:50:22.800Z,1348512622.800 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24
2012-09-24T18:50:22.904Z,1348512622.904 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28
2012-09-24T18:50:23.021Z,1348512623.021 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=192
2012-09-24T18:50:23.107Z,1348512623.107 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-09-24T18:50:23.243Z,1348512623.243 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=8
2012-09-24T18:50:23.299Z,1348512623.299 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=28
2012-09-24T18:50:23.351Z,1348512623.351 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=16
2012-09-24T18:50:23.407Z,1348512623.407 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-09-24T18:50:23.689Z,1348512623.689 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24
2012-09-24T18:50:23.792Z,1348512623.792 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28
2012-09-24T18:50:23.891Z,1348512623.891 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=0
2012-09-24T18:50:23.995Z,1348512623.995 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-09-24T18:50:24.343Z,1348512624.343 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=24
2012-09-24T18:50:24.491Z,1348512624.491 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=60
2012-09-24T18:50:24.631Z,1348512624.631 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=0
2012-09-24T18:50:24.775Z,1348512624.775 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4
2012-09-24T18:50:25.097Z,1348512625.096 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=29
2012-09-24T18:50:25.211Z,1348512625.211 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=5
2012-09-24T18:50:25.331Z,1348512625.331 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=45
2012-09-24T18:50:25.451Z,1348512625.451 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4
2012-09-24T18:50:25.971Z,1348512625.971 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=60
2012-09-24T18:50:26.203Z,1348512626.203 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=5
2012-09-24T18:50:26.443Z,1348512626.443 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=45
2012-09-24T18:50:26.680Z,1348512626.680 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4
2012-09-24T18:50:27.600Z,1348512627.600 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=28
2012-09-24T18:50:28.071Z,1348512628.071 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4
2012-09-24T18:50:28.547Z,1348512628.547 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=12
2012-09-24T18:50:29.019Z,1348512629.019 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4
2012-09-24T18:50:29.896Z,1348512629.896 [DVL_micro](DEBUG): NQ1
2012-09-24T18:50:29.921Z,1348512629.921 [DVL_micro](DEBUG): ADCP
2012-09-24T18:50:30.756Z,1348512630.756 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=9
2012-09-24T18:50:31.595Z,1348512631.595 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=49
2012-09-24T18:50:32.431Z,1348512632.431 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=26
2012-09-24T18:50:33.268Z,1348512633.268 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-24T18:50:33.403Z,1348512633.403 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=8
2012-09-24T18:50:33.460Z,1348512633.460 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=28
2012-09-24T18:50:33.515Z,1348512633.515 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=48
2012-09-24T18:50:33.567Z,1348512633.567 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-09-24T18:50:33.847Z,1348512633.847 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24
2012-09-24T18:50:33.951Z,1348512633.951 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28
2012-09-24T18:50:34.051Z,1348512634.051 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=0
2012-09-24T18:50:34.159Z,1348512634.159 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-09-24T18:50:34.293Z,1348512634.293 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=8
2012-09-24T18:50:34.347Z,1348512634.347 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=28
2012-09-24T18:50:34.399Z,1348512634.399 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=16
2012-09-24T18:50:34.451Z,1348512634.451 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-09-24T18:50:34.735Z,1348512634.735 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=56
2012-09-24T18:50:34.839Z,1348512634.839 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=60
2012-09-24T18:50:34.939Z,1348512634.939 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=32
2012-09-24T18:50:35.041Z,1348512635.041 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-09-24T18:50:35.391Z,1348512635.391 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=56
2012-09-24T18:50:35.535Z,1348512635.535 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=28
2012-09-24T18:50:35.679Z,1348512635.680 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=32
2012-09-24T18:50:35.823Z,1348512635.823 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4
2012-09-24T18:50:36.143Z,1348512636.143 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=28
2012-09-24T18:50:36.259Z,1348512636.259 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4
2012-09-24T18:50:36.375Z,1348512636.375 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=12
2012-09-24T18:50:36.491Z,1348512636.491 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4
2012-09-24T18:50:37.015Z,1348512637.015 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=28
2012-09-24T18:50:37.252Z,1348512637.252 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4
2012-09-24T18:50:37.487Z,1348512637.487 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=12
2012-09-24T18:50:37.727Z,1348512637.727 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4
2012-09-24T18:50:38.651Z,1348512638.651 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=61
2012-09-24T18:50:39.119Z,1348512639.119 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=5
2012-09-24T18:50:39.595Z,1348512639.595 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=45
2012-09-24T18:50:40.067Z,1348512640.067 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4
2012-09-24T18:50:40.948Z,1348512640.948 [DVL_micro](DEBUG): NQ1
2012-09-24T18:50:40.982Z,1348512640.982 [DVL_micro](DEBUG): ADCP
2012-09-24T18:50:41.807Z,1348512641.807 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=6
2012-09-24T18:50:42.648Z,1348512642.648 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=46
2012-09-24T18:50:43.483Z,1348512643.483 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=23
2012-09-24T18:50:44.320Z,1348512644.320 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-24T18:50:44.456Z,1348512644.456 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=8
2012-09-24T18:50:44.511Z,1348512644.511 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=28
2012-09-24T18:50:44.563Z,1348512644.563 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=16
2012-09-24T18:50:44.619Z,1348512644.619 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-09-24T18:50:44.899Z,1348512644.899 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=56
2012-09-24T18:50:45.003Z,1348512645.003 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=60
2012-09-24T18:50:45.103Z,1348512645.103 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=32
2012-09-24T18:50:45.211Z,1348512645.211 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-09-24T18:50:45.344Z,1348512645.344 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=40
2012-09-24T18:50:45.399Z,1348512645.399 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=60
2012-09-24T18:50:45.452Z,1348512645.452 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=48
2012-09-24T18:50:45.503Z,1348512645.503 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-09-24T18:50:45.783Z,1348512645.783 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24
2012-09-24T18:50:45.887Z,1348512645.887 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28
2012-09-24T18:50:45.987Z,1348512645.987 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=0
2012-09-24T18:50:46.091Z,1348512646.091 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-09-24T18:50:46.439Z,1348512646.439 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=24
2012-09-24T18:50:46.587Z,1348512646.587 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=29
2012-09-24T18:50:46.728Z,1348512646.727 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=1
2012-09-24T18:50:46.875Z,1348512646.875 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4
2012-09-24T18:50:47.195Z,1348512647.195 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=28
2012-09-24T18:50:47.311Z,1348512647.311 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=36
2012-09-24T18:50:47.428Z,1348512647.428 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=12
2012-09-24T18:50:47.543Z,1348512647.543 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4
2012-09-24T18:50:48.067Z,1348512648.067 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=28
2012-09-24T18:50:48.303Z,1348512648.303 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=5
2012-09-24T18:50:48.543Z,1348512648.543 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=45
2012-09-24T18:50:48.779Z,1348512648.779 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4
2012-09-24T18:50:49.699Z,1348512649.699 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=30
2012-09-24T18:50:50.171Z,1348512650.171 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=7
2012-09-24T18:50:50.649Z,1348512650.649 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=47
2012-09-24T18:50:51.121Z,1348512651.121 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4
2012-09-24T18:50:51.999Z,1348512651.999 [DVL_micro](DEBUG): NQ1
2012-09-24T18:50:52.044Z,1348512652.044 [DVL_micro](DEBUG): ADCP
2012-09-24T18:50:52.859Z,1348512652.859 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=7
2012-09-24T18:50:53.699Z,1348512653.699 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=48
2012-09-24T18:50:54.536Z,1348512654.536 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=24
2012-09-24T18:50:55.371Z,1348512655.372 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-24T18:50:55.508Z,1348512655.508 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=8
2012-09-24T18:50:55.563Z,1348512655.563 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=28
2012-09-24T18:50:55.619Z,1348512655.619 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=48
2012-09-24T18:50:55.671Z,1348512655.671 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-09-24T18:50:55.951Z,1348512655.951 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=25
2012-09-24T18:50:56.055Z,1348512656.055 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=62
2012-09-24T18:50:56.155Z,1348512656.155 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=2
2012-09-24T18:50:56.259Z,1348512656.259 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-09-24T18:50:56.396Z,1348512656.395 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=8
2012-09-24T18:50:56.451Z,1348512656.451 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=60
2012-09-24T18:50:56.504Z,1348512656.504 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=16
2012-09-24T18:50:56.555Z,1348512656.555 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-09-24T18:50:56.839Z,1348512656.839 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=56
2012-09-24T18:50:56.945Z,1348512656.945 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=60
2012-09-24T18:50:57.043Z,1348512657.043 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=32
2012-09-24T18:50:57.161Z,1348512657.161 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-09-24T18:50:57.495Z,1348512657.496 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=56
2012-09-24T18:50:57.643Z,1348512657.643 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=28
2012-09-24T18:50:57.783Z,1348512657.783 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=0
2012-09-24T18:50:57.927Z,1348512657.927 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4
2012-09-24T18:50:58.247Z,1348512658.247 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=28
2012-09-24T18:50:58.363Z,1348512658.363 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4
2012-09-24T18:50:58.479Z,1348512658.479 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=12
2012-09-24T18:50:58.595Z,1348512658.595 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4
2012-09-24T18:50:59.120Z,1348512659.120 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=28
2012-09-24T18:50:59.356Z,1348512659.355 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4
2012-09-24T18:50:59.591Z,1348512659.591 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=12
2012-09-24T18:50:59.828Z,1348512659.828 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4
2012-09-24T18:51:00.751Z,1348512660.751 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=28
2012-09-24T18:51:01.224Z,1348512661.224 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=37
2012-09-24T18:51:01.699Z,1348512661.699 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=45
2012-09-24T18:51:02.171Z,1348512662.171 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4
2012-09-24T18:51:03.047Z,1348512663.047 [DVL_micro](DEBUG): NQ1
2012-09-24T18:51:03.075Z,1348512663.075 [DVL_micro](DEBUG): ADCP
2012-09-24T18:51:03.907Z,1348512663.907 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=5
2012-09-24T18:51:04.747Z,1348512664.747 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=13
2012-09-24T18:51:05.584Z,1348512665.583 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=21
2012-09-24T18:51:06.419Z,1348512666.419 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-24T18:51:06.555Z,1348512666.555 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=8
2012-09-24T18:51:06.612Z,1348512666.612 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=28
2012-09-24T18:51:06.667Z,1348512666.667 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=48
2012-09-24T18:51:06.719Z,1348512666.719 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-09-24T18:51:07.001Z,1348512667.001 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=56
2012-09-24T18:51:07.103Z,1348512667.103 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=60
2012-09-24T18:51:07.203Z,1348512667.203 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=0
2012-09-24T18:51:07.307Z,1348512667.307 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-09-24T18:51:07.443Z,1348512667.443 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=41
2012-09-24T18:51:07.499Z,1348512667.499 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=61
2012-09-24T18:51:07.551Z,1348512667.551 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=17
2012-09-24T18:51:07.603Z,1348512667.603 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-09-24T18:51:07.887Z,1348512667.888 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=56
2012-09-24T18:51:07.991Z,1348512667.991 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=60
2012-09-24T18:51:08.091Z,1348512668.091 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=32
2012-09-24T18:51:08.195Z,1348512668.195 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-09-24T18:51:08.543Z,1348512668.543 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=24
2012-09-24T18:51:08.687Z,1348512668.687 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=28
2012-09-24T18:51:08.831Z,1348512668.831 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=0
2012-09-24T18:51:08.992Z,1348512668.991 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4
2012-09-24T18:51:09.299Z,1348512669.299 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=60
2012-09-24T18:51:09.411Z,1348512669.411 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4
2012-09-24T18:51:09.531Z,1348512669.531 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=44
2012-09-24T18:51:09.647Z,1348512669.647 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4
2012-09-24T18:51:10.171Z,1348512670.171 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=60
2012-09-24T18:51:10.403Z,1348512670.403 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4
2012-09-24T18:51:10.643Z,1348512670.643 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=12
2012-09-24T18:51:10.903Z,1348512670.903 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4
2012-09-24T18:51:11.795Z,1348512671.796 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=28
2012-09-24T18:51:12.267Z,1348512672.267 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4
2012-09-24T18:51:12.743Z,1348512672.743 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=12
2012-09-24T18:51:13.219Z,1348512673.219 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4
2012-09-24T18:51:14.100Z,1348512674.100 [DVL_micro](DEBUG): NQ1
2012-09-24T18:51:14.125Z,1348512674.125 [DVL_micro](DEBUG): ADCP
2012-09-24T18:51:14.959Z,1348512674.959 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=45
2012-09-24T18:51:15.795Z,1348512675.795 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=21
2012-09-24T18:51:16.635Z,1348512676.635 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=61
2012-09-24T18:51:17.468Z,1348512677.468 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-24T18:51:17.612Z,1348512677.612 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=72
2012-09-24T18:51:17.660Z,1348512677.660 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=29
2012-09-24T18:51:17.715Z,1348512677.715 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=50
2012-09-24T18:51:17.767Z,1348512677.767 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-09-24T18:51:18.048Z,1348512678.048 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24
2012-09-24T18:51:18.151Z,1348512678.151 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28
2012-09-24T18:51:18.255Z,1348512678.255 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=0
2012-09-24T18:51:18.356Z,1348512678.356 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-09-24T18:51:18.491Z,1348512678.491 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=8
2012-09-24T18:51:18.548Z,1348512678.548 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=28
2012-09-24T18:51:18.599Z,1348512678.599 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=16
2012-09-24T18:51:18.655Z,1348512678.655 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-09-24T18:51:18.935Z,1348512678.935 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24
2012-09-24T18:51:19.039Z,1348512679.039 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28
2012-09-24T18:51:19.139Z,1348512679.139 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=0
2012-09-24T18:51:19.243Z,1348512679.243 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-09-24T18:51:19.591Z,1348512679.591 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=24
2012-09-24T18:51:19.735Z,1348512679.735 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=28
2012-09-24T18:51:19.879Z,1348512679.879 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=0
2012-09-24T18:51:20.023Z,1348512680.023 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4
2012-09-24T18:51:20.348Z,1348512680.348 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=28
2012-09-24T18:51:20.464Z,1348512680.464 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=36
2012-09-24T18:51:20.580Z,1348512680.580 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=12
2012-09-24T18:51:20.697Z,1348512680.697 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4
2012-09-24T18:51:21.219Z,1348512681.219 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=28
2012-09-24T18:51:21.452Z,1348512681.452 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4
2012-09-24T18:51:21.691Z,1348512681.691 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=12
2012-09-24T18:51:21.927Z,1348512681.927 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4
2012-09-24T18:51:22.848Z,1348512682.848 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=0
2012-09-24T18:51:23.323Z,1348512683.323 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=40
2012-09-24T18:51:23.795Z,1348512683.795 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=16
2012-09-24T18:51:24.271Z,1348512684.271 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4
2012-09-24T18:51:25.155Z,1348512685.155 [DVL_micro](DEBUG): NQ1
2012-09-24T18:51:25.180Z,1348512685.180 [DVL_micro](DEBUG): ADCP
2012-09-24T18:51:26.015Z,1348512686.015 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=13
2012-09-24T18:51:26.855Z,1348512686.855 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=53
2012-09-24T18:51:27.691Z,1348512687.691 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=29
2012-09-24T18:51:28.527Z,1348512688.527 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-24T18:51:28.663Z,1348512688.663 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=40
2012-09-24T18:51:28.719Z,1348512688.719 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=60
2012-09-24T18:51:28.771Z,1348512688.771 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=16
2012-09-24T18:51:28.823Z,1348512688.823 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-09-24T18:51:29.104Z,1348512689.103 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24
2012-09-24T18:51:29.207Z,1348512689.207 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28
2012-09-24T18:51:29.307Z,1348512689.307 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=0
2012-09-24T18:51:29.411Z,1348512689.411 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-09-24T18:51:29.547Z,1348512689.547 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=8
2012-09-24T18:51:29.604Z,1348512689.603 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=28
2012-09-24T18:51:29.660Z,1348512689.660 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=48
2012-09-24T18:51:29.711Z,1348512689.711 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-09-24T18:51:29.991Z,1348512689.991 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24
2012-09-24T18:51:30.095Z,1348512690.095 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28
2012-09-24T18:51:30.195Z,1348512690.195 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=0
2012-09-24T18:51:30.299Z,1348512690.299 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-09-24T18:51:30.648Z,1348512690.648 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=25
2012-09-24T18:51:30.795Z,1348512690.795 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=61
2012-09-24T18:51:30.936Z,1348512690.936 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=2
2012-09-24T18:51:31.083Z,1348512691.083 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4
2012-09-24T18:51:31.403Z,1348512691.403 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=28
2012-09-24T18:51:31.520Z,1348512691.520 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=36
2012-09-24T18:51:31.635Z,1348512691.635 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=12
2012-09-24T18:51:31.752Z,1348512691.752 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4
2012-09-24T18:51:32.287Z,1348512692.287 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=60
2012-09-24T18:51:32.511Z,1348512692.511 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=36
2012-09-24T18:51:32.748Z,1348512692.748 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=12
2012-09-24T18:51:32.983Z,1348512692.983 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4
2012-09-24T18:51:33.903Z,1348512693.903 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=61
2012-09-24T18:51:34.375Z,1348512694.375 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=37
2012-09-24T18:51:34.847Z,1348512694.847 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=13
2012-09-24T18:51:35.323Z,1348512695.323 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4
2012-09-24T18:51:36.203Z,1348512696.203 [DVL_micro](DEBUG): NQ1
2012-09-24T18:51:36.233Z,1348512696.233 [DVL_micro](DEBUG): ADCP
2012-09-24T18:51:37.059Z,1348512697.059 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=11
2012-09-24T18:51:37.899Z,1348512697.899 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=19
2012-09-24T18:51:38.739Z,1348512698.739 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=59
2012-09-24T18:51:39.571Z,1348512699.571 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-24T18:51:39.708Z,1348512699.708 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=8
2012-09-24T18:51:39.763Z,1348512699.763 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=28
2012-09-24T18:51:39.820Z,1348512699.820 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=48
2012-09-24T18:51:39.871Z,1348512699.871 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-09-24T18:51:40.151Z,1348512700.151 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24
2012-09-24T18:51:40.255Z,1348512700.255 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=60
2012-09-24T18:51:40.355Z,1348512700.355 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=0
2012-09-24T18:51:40.459Z,1348512700.459 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-09-24T18:51:40.600Z,1348512700.600 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=40
2012-09-24T18:51:40.651Z,1348512700.651 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=61
2012-09-24T18:51:40.703Z,1348512700.703 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=17
2012-09-24T18:51:40.755Z,1348512700.755 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-09-24T18:51:41.040Z,1348512701.039 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=56
2012-09-24T18:51:41.143Z,1348512701.143 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=60
2012-09-24T18:51:41.243Z,1348512701.243 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=33
2012-09-24T18:51:41.347Z,1348512701.347 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-09-24T18:51:41.695Z,1348512701.695 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=56
2012-09-24T18:51:41.839Z,1348512701.839 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=28
2012-09-24T18:51:41.983Z,1348512701.983 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=33
2012-09-24T18:51:42.127Z,1348512702.127 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4
2012-09-24T18:51:42.447Z,1348512702.447 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=28
2012-09-24T18:51:42.563Z,1348512702.563 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4
2012-09-24T18:51:42.679Z,1348512702.679 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=12
2012-09-24T18:51:42.795Z,1348512702.795 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4
2012-09-24T18:51:43.319Z,1348512703.319 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=28
2012-09-24T18:51:43.555Z,1348512703.555 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4
2012-09-24T18:51:43.795Z,1348512703.795 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=44
2012-09-24T18:51:44.031Z,1348512704.031 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4
2012-09-24T18:51:44.951Z,1348512704.951 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=60
2012-09-24T18:51:45.423Z,1348512705.423 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=36
2012-09-24T18:51:45.896Z,1348512705.896 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=12
2012-09-24T18:51:46.371Z,1348512706.371 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4
2012-09-24T18:51:47.251Z,1348512707.251 [DVL_micro](DEBUG): NQ1
2012-09-24T18:51:47.282Z,1348512707.282 [DVL_micro](DEBUG): ADCP
2012-09-24T18:51:48.111Z,1348512708.111 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=42
2012-09-24T18:51:48.947Z,1348512708.947 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=18
2012-09-24T18:51:49.763Z,1348512709.763 [CommandLine](IMPORTANT): got command quit
2012-09-24T18:51:49.787Z,1348512709.787 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=59
2012-09-24T18:51:50.623Z,1348512710.623 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-09-24T18:51:50.759Z,1348512710.759 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=40
2012-09-24T18:51:50.815Z,1348512710.815 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=60
2012-09-24T18:51:50.859Z,1348512710.859 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2012-09-24T18:51:50.859Z,1348512710.859 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2012-09-24T18:51:50.867Z,1348512710.867 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=16
2012-09-24T18:51:50.919Z,1348512710.919 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-09-24T18:51:51.103Z,1348512711.103 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2012-09-24T18:51:51.114Z,1348512711.114 [science_to_67_70] Stopped
2012-09-24T18:51:51.114Z,1348512711.114 [science_to_67_70](INFO): Aggregate::uninitialize science_to_67_70
2012-09-24T18:51:51.114Z,1348512711.114 [science_to_67_70:AltEnvWrapper] Stopped
2012-09-24T18:51:51.114Z,1348512711.114 [science_to_67_70:AltEnvWrapper](INFO): Aggregate::uninitialize science_to_67_70:AltEnvWrapper
2012-09-24T18:51:51.115Z,1348512711.115 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope] Stopped
2012-09-24T18:51:51.115Z,1348512711.115 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2012-09-24T18:51:51.115Z,1348512711.115 [science_to_67_70:AltEnvWrapper:B.Wait] Stopped
2012-09-24T18:51:51.115Z,1348512711.115 [science_to_67_70:AltEnvWrapper:B.Wait](DEBUG): Uninitialize Wait Component.
2012-09-24T18:51:51.115Z,1348512711.115 [science_to_67_70:B.DepthEnvelope] Stopped
2012-09-24T18:51:51.115Z,1348512711.115 [science_to_67_70:B.DepthEnvelope](DEBUG): Uninitialize.
2012-09-24T18:51:51.115Z,1348512711.115 [science_to_67_70:OffshoreWrapper] Stopped
2012-09-24T18:51:51.115Z,1348512711.115 [science_to_67_70:OffshoreWrapper](INFO): Aggregate::uninitialize science_to_67_70:OffshoreWrapper
2012-09-24T18:51:51.115Z,1348512711.115 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope] Stopped
2012-09-24T18:51:51.115Z,1348512711.115 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2012-09-24T18:51:51.115Z,1348512711.115 [science_to_67_70:OffshoreWrapper:B.Wait] Stopped
2012-09-24T18:51:51.115Z,1348512711.115 [science_to_67_70:OffshoreWrapper:B.Wait](DEBUG): Uninitialize Wait Component.
2012-09-24T18:51:51.116Z,1348512711.115 [science_to_67_70:Science] Stopped
2012-09-24T18:51:51.116Z,1348512711.116 [science_to_67_70:Science](INFO): Aggregate::uninitialize science_to_67_70:Science
2012-09-24T18:51:51.116Z,1348512711.116 [science_to_67_70:Science:A] Stopped
2012-09-24T18:51:51.116Z,1348512711.116 [science_to_67_70:Science:B] Stopped
2012-09-24T18:51:51.116Z,1348512711.116 [science_to_67_70:Science:C] Stopped
2012-09-24T18:51:51.116Z,1348512711.116 [science_to_67_70:Science:D] Stopped
2012-09-24T18:51:51.116Z,1348512711.116 [science_to_67_70:Science:E] Stopped
2012-09-24T18:51:51.116Z,1348512711.116 [science_to_67_70:Science:F] Stopped
2012-09-24T18:51:51.116Z,1348512711.116 [science_to_67_70:Science:G] Stopped
2012-09-24T18:51:51.116Z,1348512711.116 [science_to_67_70:Science:PeakDetectChl] Stopped
2012-09-24T18:51:51.116Z,1348512711.116 [science_to_67_70:Science:PeakDetectChl](INFO): Aggregate::uninitialize science_to_67_70:Science:PeakDetectChl
2012-09-24T18:51:51.116Z,1348512711.116 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2012-09-24T18:51:51.116Z,1348512711.116 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2012-09-24T18:51:51.116Z,1348512711.116 [science_to_67_70:G.Buoyancy] Stopped
2012-09-24T18:51:51.116Z,1348512711.116 [science_to_67_70:G.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2012-09-24T18:51:51.116Z,1348512711.116 [science_to_67_70:H.SetSpeed] Stopped
2012-09-24T18:51:51.117Z,1348512711.117 [science_to_67_70:H.SetSpeed](DEBUG): Uninitialize.
2012-09-24T18:51:51.117Z,1348512711.117 [science_to_67_70:I.DepthEnvelope] Stopped
2012-09-24T18:51:51.117Z,1348512711.117 [science_to_67_70:I.DepthEnvelope](DEBUG): Uninitialize.
2012-09-24T18:51:51.117Z,1348512711.117 [science_to_67_70:J.YoYo] Stopped
2012-09-24T18:51:51.117Z,1348512711.117 [science_to_67_70:J.YoYo](DEBUG): Uninitialize YoYoComponent.
2012-09-24T18:51:51.117Z,1348512711.117 [science_to_67_70:Wpt.Point] Stopped
2012-09-24T18:51:51.199Z,1348512711.199 [WetLabsBB2FL](INFO): Powering down
2012-09-24T18:51:51.201Z,1348512711.201 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24
2012-09-24T18:51:51.203Z,1348512711.203 [ComponentRegistry](INFO): Shutting down Turbulence_NPS ThreadHandler
2012-09-24T18:51:51.307Z,1348512711.307 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28
2012-09-24T18:51:51.403Z,1348512711.403 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=0
2012-09-24T18:51:51.507Z,1348512711.507 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-09-24T18:51:51.514Z,1348512711.514 [DVL_micro](INFO): pause:Powering down
2012-09-24T18:51:51.517Z,1348512711.517 [MissionManager](IMPORTANT): Started mission Default
2012-09-24T18:51:51.517Z,1348512711.517 [Default] Running Loop=1
2012-09-24T18:51:51.517Z,1348512711.517 [Default](INFO): Aggregate::initialize Default
2012-09-24T18:51:51.518Z,1348512711.518 [Default:D.SetSpeed] Running Loop=1
2012-09-24T18:51:51.518Z,1348512711.518 [Default:D.SetSpeed](DEBUG): Initialize.
2012-09-24T18:51:51.518Z,1348512711.518 [Default:E.GoToSurface] Running Loop=1
2012-09-24T18:51:51.518Z,1348512711.518 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-09-24T18:51:51.518Z,1348512711.518 [Default:Iridium] Running Loop=1
2012-09-24T18:51:51.518Z,1348512711.518 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-09-24T18:51:51.518Z,1348512711.519 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-09-24T18:51:51.518Z,1348512711.519 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-09-24T18:51:51.519Z,1348512711.519 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-09-24T18:51:51.519Z,1348512711.519 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-09-24T18:51:51.521Z,1348512711.521 [Default:Iridium:B.GoToSurface] Stopped
2012-09-24T18:51:51.521Z,1348512711.521 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-09-24T18:51:51.521Z,1348512711.521 [Default:Iridium:Read_Iridium] Running Loop=1
2012-09-24T18:51:51.521Z,1348512711.521 [Default:GPS] Running Loop=1
2012-09-24T18:51:51.521Z,1348512711.521 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-09-24T18:51:51.521Z,1348512711.521 [Default:GPS:A.SetSpeed] Running Loop=1
2012-09-24T18:51:51.521Z,1348512711.521 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-09-24T18:51:51.522Z,1348512711.522 [Default:GPS:B.GoToSurface] Running Loop=1
2012-09-24T18:51:51.522Z,1348512711.522 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-09-24T18:51:51.523Z,1348512711.523 [Default:GPS:B.GoToSurface] Stopped
2012-09-24T18:51:51.523Z,1348512711.523 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-09-24T18:51:51.523Z,1348512711.523 [Default:GPS:Read_GPS] Running Loop=1
2012-09-24T18:51:51.643Z,1348512711.643 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2012-09-24T18:51:51.655Z,1348512711.655 [CTD_NeilBrown](INFO): Powering down
2012-09-24T18:51:51.670Z,1348512711.671 [ComponentRegistry](INFO): Shutting down DVL_micro ThreadHandler
2012-09-24T18:51:51.919Z,1348512711.919 [DVL_micro](INFO): uninitialize:Powering down
2012-09-24T18:51:51.932Z,1348512711.932 [ComponentRegistry](INFO): Shutting down AsyncPiEstimator ThreadHandler
2012-09-24T18:51:51.943Z,1348512711.943 [AsyncPiEstimator](DEBUG): Uninitialize AsyncPiEstimator.
2012-09-24T18:51:51.955Z,1348512711.955 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2012-09-24T18:51:51.957Z,1348512711.957 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2012-09-24T18:51:52.086Z,1348512712.086 [controlThread](DEBUG): Uninitializing ControlThread
2012-09-24T18:51:52.086Z,1348512712.086 [AHRS_sp3003D](INFO): Powering down
2012-09-24T18:51:52.175Z,1348512712.175 [NAL9602](INFO): Powering down
2012-09-24T18:51:52.177Z,1348512712.177 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2012-09-24T18:51:52.177Z,1348512712.178 [NavChart](DEBUG): Uninitialize NavChart Derivation.
2012-09-24T18:51:52.179Z,1348512712.179 [Default] Stopped
2012-09-24T18:51:52.179Z,1348512712.179 [Default](INFO): Aggregate::uninitialize Default
2012-09-24T18:51:52.179Z,1348512712.179 [Default:GPS] Stopped
2012-09-24T18:51:52.179Z,1348512712.179 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-09-24T18:51:52.179Z,1348512712.179 [Default:GPS:A.SetSpeed] Stopped
2012-09-24T18:51:52.179Z,1348512712.179 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-09-24T18:51:52.179Z,1348512712.179 [Default:GPS:Read_GPS] Stopped
2012-09-24T18:51:52.179Z,1348512712.179 [Default:Iridium] Stopped
2012-09-24T18:51:52.179Z,1348512712.180 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-09-24T18:51:52.179Z,1348512712.180 [Default:Iridium:A.SetSpeed] Stopped
2012-09-24T18:51:52.180Z,1348512712.180 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-09-24T18:51:52.180Z,1348512712.180 [Default:Iridium:Read_Iridium] Stopped
2012-09-24T18:51:52.180Z,1348512712.180 [Default:D.SetSpeed] Stopped
2012-09-24T18:51:52.180Z,1348512712.180 [Default:D.SetSpeed](DEBUG): Uninitialize.
2012-09-24T18:51:52.180Z,1348512712.180 [Default:E.GoToSurface] Stopped
2012-09-24T18:51:52.180Z,1348512712.180 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-09-24T18:51:52.184Z,1348512712.184 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2012-09-24T18:51:52.184Z,1348512712.184 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2012-09-24T18:51:52.185Z,1348512712.185 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2012-09-24T18:51:52.185Z,1348512712.185 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2012-09-24T18:51:52.185Z,1348512712.185 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2012-09-24T18:51:52.185Z,1348512712.185 [BuoyancyServo](INFO): Powering down
2012-09-24T18:51:52.191Z,1348512712.191 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2012-09-24T18:51:52.191Z,1348512712.191 [MassServo](DEBUG): Uninitialize Mass Servo.
2012-09-24T18:51:52.191Z,1348512712.191 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2012-09-24T18:51:52.191Z,1348512712.191 [RudderServo](INFO): Powering down
2012-09-24T18:51:52.192Z,1348512712.192 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2012-09-24T18:51:52.192Z,1348512712.192 [ThrusterServo](INFO): Powering down
2012-09-24T18:51:52.193Z,1348512712.193 [SBIT](DEBUG): Uninitialize SBIT Component.
2012-09-24T18:51:52.193Z,1348512712.193 [IBIT](DEBUG): Uninitialize IBIT Component.
2012-09-24T18:51:52.194Z,1348512712.194 [CBIT](DEBUG): Uninitialize CBIT Component.