2012-10-09T16:14:36.349Z,1349799276.349 [Supervisor](DEBUG): Initializing supervisor.
2012-10-09T16:14:36.352Z,1349799276.352 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0
2012-10-09T16:14:36.352Z,1349799276.352 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2012-10-09T16:14:36.353Z,1349799276.353 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4036B4E0
2012-10-09T16:14:36.357Z,1349799276.357 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2012-10-09T16:14:36.367Z,1349799276.367 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2012-10-09T16:14:36.368Z,1349799276.368 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4039B4E0
2012-10-09T16:14:36.369Z,1349799276.369 [ComponentRegistry](DEBUG): SyncComponent "logger" handled in the control thread.
2012-10-09T16:14:36.369Z,1349799276.369 [Supervisor](INFO): Looking for Config files in directory: Config/
2012-10-09T16:14:36.370Z,1349799276.371 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2012-10-09T16:14:36.642Z,1349799276.642 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2012-10-09T16:14:36.643Z,1349799276.643 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2012-10-09T16:14:36.819Z,1349799276.819 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2012-10-09T16:14:36.820Z,1349799276.820 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2012-10-09T16:14:36.904Z,1349799276.904 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2012-10-09T16:14:36.905Z,1349799276.905 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2012-10-09T16:14:37.109Z,1349799277.109 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2012-10-09T16:14:37.110Z,1349799277.110 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2012-10-09T16:14:37.252Z,1349799277.251 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2012-10-09T16:14:37.252Z,1349799277.252 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2012-10-09T16:14:37.489Z,1349799277.489 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2012-10-09T16:14:37.490Z,1349799277.490 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2012-10-09T16:14:37.657Z,1349799277.657 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2012-10-09T16:14:37.657Z,1349799277.657 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2012-10-09T16:14:37.937Z,1349799277.937 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2012-10-09T16:14:37.937Z,1349799277.937 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2012-10-09T16:14:38.035Z,1349799278.035 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2012-10-09T16:14:38.036Z,1349799278.036 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2012-10-09T16:14:38.418Z,1349799278.418 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2012-10-09T16:14:38.419Z,1349799278.418 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2012-10-09T16:14:38.581Z,1349799278.580 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2012-10-09T16:14:38.581Z,1349799278.581 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2012-10-09T16:14:38.670Z,1349799278.670 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2012-10-09T16:14:38.671Z,1349799278.671 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2012-10-09T16:14:38.771Z,1349799278.772 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2012-10-09T16:14:38.897Z,1349799278.897 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2012-10-09T16:14:38.985Z,1349799278.985 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2012-10-09T16:14:39.089Z,1349799279.089 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2012-10-09T16:14:39.188Z,1349799279.188 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2012-10-09T16:14:39.302Z,1349799279.302 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2012-10-09T16:14:39.440Z,1349799279.440 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2012-10-09T16:14:39.618Z,1349799279.618 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2012-10-09T16:14:39.648Z,1349799279.648 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2012-10-09T16:14:39.995Z,1349799279.995 [InternalSim] Loaded
2012-10-09T16:14:39.995Z,1349799279.995 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2012-10-09T16:14:39.996Z,1349799279.996 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2012-10-09T16:14:39.996Z,1349799279.996 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2012-10-09T16:14:40.051Z,1349799280.051 [SBIT](DEBUG): Construct Startup Built In Test.
2012-10-09T16:14:40.064Z,1349799280.064 [SBIT] Loaded
2012-10-09T16:14:40.065Z,1349799280.065 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2012-10-09T16:14:40.065Z,1349799280.066 [IBIT](DEBUG): Construct Initiated Built In Test.
2012-10-09T16:14:40.093Z,1349799280.093 [IBIT] Loaded
2012-10-09T16:14:40.093Z,1349799280.093 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2012-10-09T16:14:40.096Z,1349799280.096 [CBIT](DEBUG): Construct CBIT Built In Test.
2012-10-09T16:14:40.209Z,1349799280.209 [CBIT] Loaded
2012-10-09T16:14:40.210Z,1349799280.210 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2012-10-09T16:14:40.210Z,1349799280.210 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2012-10-09T16:14:40.211Z,1349799280.211 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2012-10-09T16:14:40.352Z,1349799280.352 [BuoyancyServo] Loaded
2012-10-09T16:14:40.352Z,1349799280.352 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2012-10-09T16:14:40.361Z,1349799280.361 [ElevatorServo] Loaded
2012-10-09T16:14:40.362Z,1349799280.362 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2012-10-09T16:14:40.373Z,1349799280.373 [MassServo] Loaded
2012-10-09T16:14:40.373Z,1349799280.373 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2012-10-09T16:14:40.385Z,1349799280.385 [RudderServo] Loaded
2012-10-09T16:14:40.385Z,1349799280.385 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2012-10-09T16:14:40.396Z,1349799280.396 [ThrusterServo] Loaded
2012-10-09T16:14:40.396Z,1349799280.397 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2012-10-09T16:14:40.397Z,1349799280.397 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2012-10-09T16:14:40.398Z,1349799280.398 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2012-10-09T16:14:40.414Z,1349799280.414 [DepthRateCalculator] Loaded
2012-10-09T16:14:40.414Z,1349799280.414 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2012-10-09T16:14:44.380Z,1349799284.380 [HFRadarModelCalc] Loaded
2012-10-09T16:14:44.380Z,1349799284.380 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread.
2012-10-09T16:14:44.396Z,1349799284.396 [NavChart] Loaded
2012-10-09T16:14:44.396Z,1349799284.396 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2012-10-09T16:14:44.402Z,1349799284.402 [PitchRateCalculator] Loaded
2012-10-09T16:14:44.402Z,1349799284.402 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2012-10-09T16:14:44.408Z,1349799284.408 [SpeedCalculator] Loaded
2012-10-09T16:14:44.408Z,1349799284.408 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2012-10-09T16:14:44.423Z,1349799284.422 [TempGradientCalculator] Loaded
2012-10-09T16:14:44.423Z,1349799284.423 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2012-10-09T16:14:44.428Z,1349799284.428 [YawRateCalculator] Loaded
2012-10-09T16:14:44.429Z,1349799284.429 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2012-10-09T16:14:44.447Z,1349799284.447 [Navigation] Loaded
2012-10-09T16:14:44.448Z,1349799284.448 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread.
2012-10-09T16:14:44.448Z,1349799284.448 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2012-10-09T16:14:44.449Z,1349799284.449 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2012-10-09T16:14:44.664Z,1349799284.664 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2012-10-09T16:14:44.665Z,1349799284.665 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2012-10-09T16:14:44.687Z,1349799284.687 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2012-10-09T16:14:44.687Z,1349799284.687 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2012-10-09T16:14:44.727Z,1349799284.727 [VerticalControl](DEBUG): Construct VerticalControl.
2012-10-09T16:14:44.770Z,1349799284.770 [VerticalControl] Loaded
2012-10-09T16:14:44.771Z,1349799284.771 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2012-10-09T16:14:44.772Z,1349799284.772 [HorizontalControl](DEBUG): Construct HorizontalControl.
2012-10-09T16:14:44.803Z,1349799284.803 [HorizontalControl] Loaded
2012-10-09T16:14:44.804Z,1349799284.804 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2012-10-09T16:14:44.805Z,1349799284.805 [SpeedControl](DEBUG): Construct SpeedControl.
2012-10-09T16:14:44.806Z,1349799284.806 [SpeedControl] Loaded
2012-10-09T16:14:44.807Z,1349799284.806 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2012-10-09T16:14:44.808Z,1349799284.807 [LoopControl](DEBUG): Construct LoopControl.
2012-10-09T16:14:44.808Z,1349799284.808 [LoopControl] Loaded
2012-10-09T16:14:44.808Z,1349799284.808 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2012-10-09T16:14:44.809Z,1349799284.809 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2012-10-09T16:14:44.809Z,1349799284.809 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2012-10-09T16:14:44.814Z,1349799284.814 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator.
2012-10-09T16:14:44.819Z,1349799284.819 [AsyncPiEstimator] Loaded
2012-10-09T16:14:44.819Z,1349799284.819 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread.
2012-10-09T16:14:44.821Z,1349799284.820 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 406724E0
2012-10-09T16:14:44.821Z,1349799284.821 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2012-10-09T16:14:44.822Z,1349799284.822 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2012-10-09T16:14:44.944Z,1349799284.944 [AHRS_sp3003D] Loaded
2012-10-09T16:14:44.944Z,1349799284.944 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2012-10-09T16:14:44.958Z,1349799284.958 [Depth_Keller] Loaded
2012-10-09T16:14:44.958Z,1349799284.958 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2012-10-09T16:14:44.963Z,1349799284.963 [DropWeight] Loaded
2012-10-09T16:14:44.964Z,1349799284.964 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2012-10-09T16:14:45.101Z,1349799285.101 [DVL_micro] Loaded
2012-10-09T16:14:45.101Z,1349799285.101 [ComponentRegistry](DEBUG): Component "DVL_micro" handled in its own thread.
2012-10-09T16:14:45.103Z,1349799285.103 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406F34E0
2012-10-09T16:14:45.182Z,1349799285.182 [NAL9602] Loaded
2012-10-09T16:14:45.182Z,1349799285.182 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2012-10-09T16:14:45.231Z,1349799285.231 [Onboard] Loaded
2012-10-09T16:14:45.232Z,1349799285.232 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2012-10-09T16:14:45.238Z,1349799285.238 [Radio_Freewave] Loaded
2012-10-09T16:14:45.238Z,1349799285.239 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread.
2012-10-09T16:14:45.239Z,1349799285.239 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2012-10-09T16:14:45.240Z,1349799285.240 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2012-10-09T16:14:45.309Z,1349799285.309 [CTD_NeilBrown] Loaded
2012-10-09T16:14:45.309Z,1349799285.309 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2012-10-09T16:14:45.310Z,1349799285.310 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4074B4E0
2012-10-09T16:14:45.320Z,1349799285.320 [PAR_Licor] Loaded
2012-10-09T16:14:45.321Z,1349799285.321 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2012-10-09T16:14:45.337Z,1349799285.337 [Turbulence_NPS] Loaded
2012-10-09T16:14:45.337Z,1349799285.337 [ComponentRegistry](DEBUG): Component "Turbulence_NPS" handled in its own thread.
2012-10-09T16:14:45.338Z,1349799285.338 [Turbulence_NPS ThreadHandler](DEBUG): Created PCaller Thread at 4077B4E0
2012-10-09T16:14:45.367Z,1349799285.367 [WetLabsBB2FL] Loaded
2012-10-09T16:14:45.367Z,1349799285.367 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2012-10-09T16:14:45.368Z,1349799285.368 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407AB4E0
2012-10-09T16:14:45.369Z,1349799285.369 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2012-10-09T16:14:45.371Z,1349799285.371 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2012-10-09T16:14:45.372Z,1349799285.372 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2012-10-09T16:14:45.378Z,1349799285.378 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2012-10-09T16:14:45.380Z,1349799285.379 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 407DB4E0
2012-10-09T16:14:45.384Z,1349799285.384 [Supervisor](DEBUG): Running supervisor.
2012-10-09T16:14:45.385Z,1349799285.385 [CommandLine](INFO): Thread ID is 7493
2012-10-09T16:14:45.389Z,1349799285.389 [controlThread](INFO): Thread ID is 7492
2012-10-09T16:14:45.389Z,1349799285.389 [controlThread](DEBUG): Initializing ControlThread
2012-10-09T16:14:45.390Z,1349799285.390 [CycleStarter](INFO): Thread ID is 7491
2012-10-09T16:14:45.391Z,1349799285.391 [InternalSim](DEBUG):  InternalSim initializing...
2012-10-09T16:14:45.426Z,1349799285.426 [AsyncPiEstimator](INFO): Thread ID is 7565
2012-10-09T16:14:45.426Z,1349799285.426 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator.
2012-10-09T16:14:45.435Z,1349799285.435 [SBIT](INFO): Initialize SBIT Component.
2012-10-09T16:14:45.435Z,1349799285.435 [SBIT](IMPORTANT): Tethys CM Info:
$Rev: 9939
2012-10-09T16:14:45.436Z,1349799285.436 [IBIT](INFO): Initialize IBIT Component.
2012-10-09T16:14:45.437Z,1349799285.437 [CBIT](DEBUG): Initialize CBIT Component.
2012-10-09T16:14:45.437Z,1349799285.437 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2012-10-09T16:14:45.438Z,1349799285.438 [DVL_micro](INFO): Thread ID is 7566
2012-10-09T16:14:45.447Z,1349799285.447 [DVL_micro](INFO): Initializing
2012-10-09T16:14:45.447Z,1349799285.447 [DVL_micro](INFO): start:Powering up
2012-10-09T16:14:45.448Z,1349799285.448 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-10-09T16:14:45.449Z,1349799285.449 [DVL_micro](INFO): Cycling power to configure device.
2012-10-09T16:14:45.464Z,1349799285.464 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2012-10-09T16:14:45.464Z,1349799285.464 [NavChart](DEBUG): Initialize NavChart Derivation.
2012-10-09T16:14:45.465Z,1349799285.465 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2012-10-09T16:14:45.465Z,1349799285.465 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2012-10-09T16:14:45.465Z,1349799285.465 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2012-10-09T16:14:45.467Z,1349799285.467 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2012-10-09T16:14:45.467Z,1349799285.467 [Navigation](DEBUG): Initializing Navigation.
2012-10-09T16:14:45.468Z,1349799285.468 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2012-10-09T16:14:45.469Z,1349799285.469 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2012-10-09T16:14:45.470Z,1349799285.470 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2012-10-09T16:14:45.470Z,1349799285.470 [LoopControl](DEBUG): Initialize LoopControlComponent.
2012-10-09T16:14:45.475Z,1349799285.475 [CTD_NeilBrown](INFO): Thread ID is 7567
2012-10-09T16:14:45.476Z,1349799285.476 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2012-10-09T16:14:45.483Z,1349799285.483 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2012-10-09T16:14:45.492Z,1349799285.492 [Turbulence_NPS](INFO): Thread ID is 7568
2012-10-09T16:14:45.492Z,1349799285.492 [Turbulence_NPS](DEBUG): Initializing Turbulence_NPS.
2012-10-09T16:14:45.493Z,1349799285.493 [Turbulence_NPS](INFO): Opening uart, block timeout 10ths=1
2012-10-09T16:14:45.503Z,1349799285.503 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2012-10-09T16:14:45.512Z,1349799285.511 [WetLabsBB2FL](INFO): Thread ID is 7569
2012-10-09T16:14:45.512Z,1349799285.512 [WetLabsBB2FL](INFO): Powering down
2012-10-09T16:14:45.537Z,1349799285.537 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2012-10-09T16:14:45.541Z,1349799285.541 [NavChartDb](INFO): Thread ID is 7570
2012-10-09T16:14:45.553Z,1349799285.553 [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-10-09T16:14:45.553Z,1349799285.553 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2012-10-09T16:14:45.564Z,1349799285.564 [Turbulence_NPS](INFO): Pause powering down
2012-10-09T16:14:45.591Z,1349799285.591 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2012-10-09T16:14:45.592Z,1349799285.592 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2012-10-09T16:14:45.592Z,1349799285.592 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2012-10-09T16:14:45.592Z,1349799285.592 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2012-10-09T16:14:45.592Z,1349799285.592 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2012-10-09T16:14:45.636Z,1349799285.636 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool
2012-10-09T16:14:45.638Z,1349799285.638 [Default:GPS:A.SetSpeed](DEBUG): Construct.
2012-10-09T16:14:45.642Z,1349799285.642 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface.
2012-10-09T16:14:45.650Z,1349799285.650 [Default:Iridium:A.SetSpeed](DEBUG): Construct.
2012-10-09T16:14:45.653Z,1349799285.653 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface.
2012-10-09T16:14:45.659Z,1349799285.659 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2012-10-09T16:14:45.668Z,1349799285.668 [Default:D.SetSpeed](DEBUG): Construct.
2012-10-09T16:14:45.671Z,1349799285.671 [Default:E.GoToSurface](DEBUG): Construct GoToSurface.
2012-10-09T16:14:45.676Z,1349799285.676 [Default:F.Wait](DEBUG): Construct Wait.
2012-10-09T16:14:45.680Z,1349799285.680 [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-10-09T16:14:45.684Z,1349799285.684 [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-10-09T16:14:45.709Z,1349799285.709 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-10-09T16:14:47.068Z,1349799287.069 [Radio_Freewave](INFO): Powering up
2012-10-09T16:14:47.176Z,1349799287.176 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2012-10-09T16:14:47.179Z,1349799287.179 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2012-10-09T16:14:47.185Z,1349799287.185 [ElevatorServo](DEBUG): Initializing EZServoServo.
2012-10-09T16:14:47.187Z,1349799287.187 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2012-10-09T16:14:47.192Z,1349799287.193 [MassServo](DEBUG): Initializing EZServoServo.
2012-10-09T16:14:47.195Z,1349799287.195 [MassServo](DEBUG): Initializing MassServo.
2012-10-09T16:14:47.200Z,1349799287.201 [RudderServo](DEBUG): Initializing EZServoServo.
2012-10-09T16:14:47.201Z,1349799287.201 [RudderServo](DEBUG): Initializing RudderServo.
2012-10-09T16:14:47.208Z,1349799287.208 [ThrusterServo](DEBUG): Initializing EZServoServo.
2012-10-09T16:14:47.211Z,1349799287.211 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2012-10-09T16:14:47.515Z,1349799287.515 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-10-09T16:14:47.515Z,1349799287.515 [DVL_micro](INFO): Querying output modes
2012-10-09T16:14:47.516Z,1349799287.516 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-10-09T16:14:47.519Z,1349799287.519 [DVL_micro](DEBUG): cmdResponse: 01 03 16
2012-10-09T16:14:47.519Z,1349799287.519 [DVL_micro](INFO): NQ1 output enabled
2012-10-09T16:14:47.519Z,1349799287.519 [DVL_micro](INFO): RSSI output enabled
2012-10-09T16:14:47.519Z,1349799287.519 [DVL_micro](INFO): ADCP output enabled
2012-10-09T16:14:47.520Z,1349799287.520 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-10-09T16:14:47.527Z,1349799287.527 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2012-10-09T16:14:47.531Z,1349799287.531 [DVL_micro](INFO): pause:Powering down
2012-10-09T16:14:58.165Z,1349799298.165 [NAL9602](INFO): Powering up NAL9602
2012-10-09T16:14:59.491Z,1349799299.490 [ThrusterServo](ERROR): Thruster initialization uart error serial timeout
2012-10-09T16:14:59.491Z,1349799299.491 [ThrusterServo](FAULT): Thruster failed to initialize
2012-10-09T16:14:59.491Z,1349799299.491 [ThrusterServo] Communications Fault, FailCount= 1
2012-10-09T16:14:59.491Z,1349799299.491 [ThrusterServo](ERROR): Communications Fault
2012-10-09T16:14:59.493Z,1349799299.493 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2012-10-09T16:14:59.579Z,1349799299.579 [AHRS_sp3003D](ERROR): readHeadingMagBin got 0xA409A04236
2012-10-09T16:14:59.579Z,1349799299.579 [AHRS_sp3003D](FAULT): Read Heading/Pitch/Roll Failure.
2012-10-09T16:14:59.579Z,1349799299.579 [AHRS_sp3003D] Communications Fault, FailCount= 1
2012-10-09T16:14:59.579Z,1349799299.579 [AHRS_sp3003D](ERROR): Communications Fault
2012-10-09T16:14:59.769Z,1349799299.769 [DVL_micro](INFO): RSSI unrequested
2012-10-09T16:14:59.769Z,1349799299.769 [DVL_micro](INFO): ADCP unrequested
2012-10-09T16:14:59.770Z,1349799299.770 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-10-09T16:14:59.770Z,1349799299.770 [DVL_micro](INFO): resume:Powering up
2012-10-09T16:14:59.774Z,1349799299.774 [DVL_micro](INFO): Cycling power to configure device.
2012-10-09T16:14:59.950Z,1349799299.950 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2012-10-09T16:14:59.950Z,1349799299.950 [ThrusterServo](INFO): Powering down
2012-10-09T16:14:59.974Z,1349799299.974 [CBIT](INFO): Clearing failed state for component ThrusterServo
2012-10-09T16:14:59.974Z,1349799299.974 [ThrusterServo] No Fault, FailCount= 1
2012-10-09T16:14:59.975Z,1349799299.975 [CBIT](ERROR): Communications Fault in component: AHRS_sp3003D
2012-10-09T16:15:00.040Z,1349799300.040 [AHRS_sp3003D](INFO): Powering down
2012-10-09T16:15:00.641Z,1349799300.641 [CBIT](INFO): Clearing failed state for component AHRS_sp3003D
2012-10-09T16:15:00.641Z,1349799300.641 [AHRS_sp3003D] No Fault, FailCount= 1
2012-10-09T16:15:01.073Z,1349799301.073 [ThrusterServo](DEBUG): Initializing EZServoServo.
2012-10-09T16:15:01.195Z,1349799301.195 [ThrusterServo](DEBUG): Initialization error opening port: port not yet opened
2012-10-09T16:15:01.195Z,1349799301.195 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2012-10-09T16:15:01.196Z,1349799301.196 [SBIT](IMPORTANT): Beginning Startup BIT
2012-10-09T16:15:01.199Z,1349799301.199 [CBIT](IMPORTANT): Beginning GF scan
2012-10-09T16:15:01.645Z,1349799301.645 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-10-09T16:15:01.891Z,1349799301.891 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-10-09T16:15:01.891Z,1349799301.891 [DVL_micro](INFO): Querying output modes
2012-10-09T16:15:01.891Z,1349799301.891 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-10-09T16:15:01.947Z,1349799301.947 [DVL_micro](DEBUG): cmdResponse: 01 03 16
2012-10-09T16:15:01.947Z,1349799301.947 [DVL_micro](INFO): NQ1 output enabled
2012-10-09T16:15:01.947Z,1349799301.947 [DVL_micro](INFO): RSSI output enabled
2012-10-09T16:15:01.947Z,1349799301.947 [DVL_micro](INFO): ADCP output enabled
2012-10-09T16:15:01.947Z,1349799301.947 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-10-09T16:15:01.955Z,1349799301.955 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2012-10-09T16:15:01.957Z,1349799301.957 [DVL_micro](INFO): pause:Powering down
2012-10-09T16:15:09.595Z,1349799309.595 [NAL9602](INFO): NAL9602 initialized
2012-10-09T16:15:14.665Z,1349799314.665 [SBIT](FAULT): Mass: EXPECTED:0.005600 ACTUAL:0.002448
2012-10-09T16:15:19.015Z,1349799319.015 [MassServo](ERROR): getPosition uart error serial timeout
2012-10-09T16:15:19.015Z,1349799319.015 [MassServo](FAULT): Invalid EZ Servo response:"�"
2012-10-09T16:15:19.015Z,1349799319.015 [MassServo] Communications Fault, FailCount= 1
2012-10-09T16:15:19.015Z,1349799319.015 [MassServo](ERROR): Communications Fault
2012-10-09T16:15:19.070Z,1349799319.070 [CBIT](ERROR): Communications Fault in component: MassServo
2012-10-09T16:15:20.263Z,1349799320.263 [ElevatorServo](ERROR): getPosition uart error serial timeout
2012-10-09T16:15:20.263Z,1349799320.263 [ElevatorServo](FAULT): Elevator uart error - getPosition.serial timeout
2012-10-09T16:15:20.263Z,1349799320.263 [ElevatorServo] Communications Fault, FailCount= 1
2012-10-09T16:15:20.263Z,1349799320.263 [ElevatorServo](ERROR): Communications Fault
2012-10-09T16:15:20.264Z,1349799320.264 [MassServo](DEBUG): Uninitialize Mass Servo.
2012-10-09T16:15:20.264Z,1349799320.264 [MassServo](INFO): Powering down
2012-10-09T16:15:20.606Z,1349799320.606 [CBIT](ERROR): Communications Fault in component: ElevatorServo
2012-10-09T16:15:20.611Z,1349799320.611 [CBIT](INFO): Clearing failed state for component MassServo
2012-10-09T16:15:20.611Z,1349799320.611 [MassServo] No Fault, FailCount= 1
2012-10-09T16:15:22.398Z,1349799322.398 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2012-10-09T16:15:22.398Z,1349799322.398 [ElevatorServo](INFO): Powering down
2012-10-09T16:15:22.403Z,1349799322.403 [MassServo](DEBUG): Initializing EZServoServo.
2012-10-09T16:15:22.732Z,1349799322.732 [MassServo](DEBUG): Initialization error opening port: port not yet opened
2012-10-09T16:15:22.732Z,1349799322.732 [MassServo](DEBUG): Initializing MassServo.
2012-10-09T16:15:23.075Z,1349799323.075 [CBIT](INFO): Clearing failed state for component ElevatorServo
2012-10-09T16:15:23.075Z,1349799323.075 [ElevatorServo] No Fault, FailCount= 1
2012-10-09T16:15:25.225Z,1349799325.225 [ElevatorServo](DEBUG): Initializing EZServoServo.
2012-10-09T16:15:25.347Z,1349799325.347 [ElevatorServo](DEBUG): Initialization error opening port: port not yet opened
2012-10-09T16:15:25.347Z,1349799325.347 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2012-10-09T16:15:28.563Z,1349799328.563 [CBIT](IMPORTANT): No ground fault detected
2012-10-09T16:15:55.361Z,1349799355.361 [SBIT](CRITICAL): SBIT FAILED
2012-10-09T16:15:55.756Z,1349799355.756 [MissionManager](IMPORTANT): Started mission Startup
2012-10-09T16:15:55.756Z,1349799355.756 [Startup] Running Loop=1
2012-10-09T16:15:55.756Z,1349799355.756 [Startup](INFO): Aggregate::initialize Startup
2012-10-09T16:15:55.756Z,1349799355.756 [Startup:A.GoToSurface] Running Loop=1
2012-10-09T16:15:55.756Z,1349799355.756 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-09T16:15:55.762Z,1349799355.762 [Startup:StartupSatComms] Running Loop=1
2012-10-09T16:15:55.762Z,1349799355.762 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2012-10-09T16:15:55.762Z,1349799355.763 [Startup:StartupSatComms:A] Running Loop=1
2012-10-09T16:15:56.163Z,1349799356.163 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-10-09T16:16:56.242Z,1349799416.242 [Startup:StartupSatComms:A](INFO): Timed out from 2012-10-09T16:15:55.8Z
2012-10-09T16:16:56.242Z,1349799416.242 [Startup:StartupSatComms:A:A_Timeout] Running Loop=1
2012-10-09T16:16:56.242Z,1349799416.242 [Startup:StartupSatComms:A:A_Timeout](INFO): Aggregate::initialize Startup:StartupSatComms:A:A_Timeout
2012-10-09T16:16:56.242Z,1349799416.242 [Startup:StartupSatComms:A:A_Timeout](INFO): Completed Startup:StartupSatComms:A:A_Timeout
2012-10-09T16:16:56.242Z,1349799416.242 [Startup:StartupSatComms:A] Stopped
2012-10-09T16:16:56.242Z,1349799416.242 [Startup:StartupSatComms:B] Running Loop=1
2012-10-09T16:16:56.596Z,1349799416.596 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2012-10-09T16:16:56.843Z,1349799416.843 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.823200 degree
2012-10-09T16:16:56.845Z,1349799416.845 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread.
2012-10-09T16:16:56.845Z,1349799416.845 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121827.000000 degree
2012-10-09T16:16:56.847Z,1349799416.847 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread.
2012-10-09T16:16:56.847Z,1349799416.847 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2012-10-09T16:16:56.848Z,1349799416.848 [CommandLine](IMPORTANT): got command report touch Turbulence_NPS.packetNum
2012-10-09T16:16:56.965Z,1349799416.965 [Startup:StartupSatComms:B] Stopped
2012-10-09T16:16:56.966Z,1349799416.966 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2012-10-09T16:16:56.966Z,1349799416.966 [Startup:StartupSatComms] Stopped
2012-10-09T16:16:56.966Z,1349799416.966 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2012-10-09T16:16:56.967Z,1349799416.967 [Startup](INFO): Completed Startup
2012-10-09T16:16:56.967Z,1349799416.967 [Startup] Stopped
2012-10-09T16:16:56.967Z,1349799416.967 [Startup](INFO): Aggregate::uninitialize Startup
2012-10-09T16:16:56.967Z,1349799416.967 [Startup:A.GoToSurface] Stopped
2012-10-09T16:16:56.967Z,1349799416.967 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-09T16:16:57.031Z,1349799417.031 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Depth_Keller,DropWeight,NAL9602,Onboard,Radio_Freewave,PAR_Licor,Depth_Keller,PAR_Licor,Maintain_NAL9602.latitude_fix,Maintain_NAL9602.longitude_fix,DepthRateCalculator,HFRadarModelCalc,NavChart,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,Navigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,logger,
2012-10-09T16:16:57.368Z,1349799417.368 [MissionManager](IMPORTANT): Started mission Default
2012-10-09T16:16:57.369Z,1349799417.369 [Default] Running Loop=1
2012-10-09T16:16:57.369Z,1349799417.369 [Default](INFO): Aggregate::initialize Default
2012-10-09T16:16:57.369Z,1349799417.369 [Default:D.SetSpeed] Running Loop=1
2012-10-09T16:16:57.369Z,1349799417.369 [Default:D.SetSpeed](DEBUG): Initialize.
2012-10-09T16:16:57.369Z,1349799417.369 [Default:E.GoToSurface] Running Loop=1
2012-10-09T16:16:57.369Z,1349799417.369 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-09T16:16:57.369Z,1349799417.369 [Default:Iridium] Running Loop=1
2012-10-09T16:16:57.370Z,1349799417.369 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-10-09T16:16:57.370Z,1349799417.369 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-10-09T16:16:57.370Z,1349799417.370 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-10-09T16:16:57.370Z,1349799417.370 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-10-09T16:16:57.370Z,1349799417.370 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-09T16:16:57.370Z,1349799417.371 [Default:E.GoToSurface] Running Loop=1
2012-10-09T16:16:57.376Z,1349799417.376 [Default:D.SetSpeed] Running Loop=1
2012-10-09T16:16:57.385Z,1349799417.385 [Default:Iridium:B.GoToSurface] Stopped
2012-10-09T16:16:57.385Z,1349799417.385 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-09T16:16:57.386Z,1349799417.386 [Default:Iridium:Read_Iridium] Running Loop=1
2012-10-09T16:16:57.386Z,1349799417.386 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-10-09T16:16:57.391Z,1349799417.391 [Default:GPS] Running Loop=1
2012-10-09T16:16:57.391Z,1349799417.391 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-10-09T16:16:57.391Z,1349799417.391 [Default:GPS:A.SetSpeed] Running Loop=1
2012-10-09T16:16:57.391Z,1349799417.391 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-10-09T16:16:57.391Z,1349799417.391 [Default:GPS:B.GoToSurface] Running Loop=1
2012-10-09T16:16:57.392Z,1349799417.392 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-09T16:16:57.397Z,1349799417.397 [Default:GPS:B.GoToSurface] Stopped
2012-10-09T16:16:57.397Z,1349799417.397 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-09T16:16:57.397Z,1349799417.397 [Default:GPS:Read_GPS] Running Loop=1
2012-10-09T16:16:57.397Z,1349799417.397 [Default:GPS:A.SetSpeed] Running Loop=1
2012-10-09T16:16:57.773Z,1349799417.773 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2012-10-09T16:16:57.776Z,1349799417.776 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-10-09T16:16:57.777Z,1349799417.777 [Default:GPS:Read_GPS] Stopped
2012-10-09T16:16:57.777Z,1349799417.777 [Default:GPS:D] Running Loop=1
2012-10-09T16:16:58.169Z,1349799418.168 [Default:GPS:D] Stopped
2012-10-09T16:16:58.169Z,1349799418.169 [Default:GPS](INFO): Completed Default:GPS
2012-10-09T16:16:58.169Z,1349799418.169 [Default:GPS] Stopped
2012-10-09T16:16:58.169Z,1349799418.169 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-10-09T16:16:58.169Z,1349799418.169 [Default:GPS:A.SetSpeed] Stopped
2012-10-09T16:16:58.169Z,1349799418.169 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-10-09T16:17:05.547Z,1349799425.547 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2012-10-09T16:17:05.615Z,1349799425.615 [Default:Iridium:Read_Iridium] Stopped
2012-10-09T16:17:05.615Z,1349799425.615 [Default:Iridium](INFO): Completed Default:Iridium
2012-10-09T16:17:05.615Z,1349799425.615 [Default:Iridium] Stopped
2012-10-09T16:17:05.615Z,1349799425.615 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-10-09T16:17:05.615Z,1349799425.615 [Default:Iridium:A.SetSpeed] Stopped
2012-10-09T16:17:05.616Z,1349799425.616 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-10-09T16:17:05.616Z,1349799425.616 [Default:F.Wait] Running Loop=1
2012-10-09T16:17:05.616Z,1349799425.616 [Default:F.Wait](DEBUG): Initialize Wait Component.
2012-10-09T16:17:13.659Z,1349799433.659 [CommandLine](IMPORTANT): got command run ./Missions/Science/science_to_67_70.xml
2012-10-09T16:17:13.659Z,1349799433.659 [MissionManager](INFO): Loading Mission: ./Missions/Science/science_to_67_70.xml
2012-10-09T16:17:13.717Z,1349799433.717 [MissionManager](INFO): DefineArg science_to_67_70.MinAltitude = 10.000000 m
2012-10-09T16:17:13.720Z,1349799433.720 [MissionManager](INFO): DefineArg science_to_67_70.MaxDepth = 105.000000 m
2012-10-09T16:17:13.723Z,1349799433.723 [MissionManager](INFO): DefineArg science_to_67_70.MinOffshore = 2000.000000 m
2012-10-09T16:17:13.726Z,1349799433.726 [MissionManager](INFO): DefineArg science_to_67_70.YoYoMinDepth = 3.000000 m
2012-10-09T16:17:13.729Z,1349799433.729 [MissionManager](INFO): DefineArg science_to_67_70.YoYoMaxDepth = 100.000000 m
2012-10-09T16:17:13.731Z,1349799433.731 [MissionManager](INFO): DefineArg science_to_67_70.YoYoUpPitch = 20.000000 arcdeg
2012-10-09T16:17:13.734Z,1349799433.734 [MissionManager](INFO): DefineArg science_to_67_70.YoYoDownPitch = -15.000000 arcdeg
2012-10-09T16:17:13.737Z,1349799433.737 [MissionManager](INFO): DefineArg science_to_67_70.WptLat = 36.120000 arcdeg
2012-10-09T16:17:13.740Z,1349799433.740 [MissionManager](INFO): DefineArg science_to_67_70.WptLon = -123.486000 arcdeg
2012-10-09T16:17:13.743Z,1349799433.743 [MissionManager](INFO): DefineArg science_to_67_70.NeedCommsTime = 120.000000 min
2012-10-09T16:17:13.746Z,1349799433.746 [MissionManager](INFO): DefineArg science_to_67_70.Speed = 1.000000 m/s
2012-10-09T16:17:13.748Z,1349799433.748 [MissionManager](INFO): DefineArg science_to_67_70.Timeout = 36.000000 h
2012-10-09T16:17:13.752Z,1349799433.752 [MissionManager](INFO): DefineArg science_to_67_70.BuoyancyNeutral = 0.000330 n/a
2012-10-09T16:17:13.755Z,1349799433.755 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2012-10-09T16:17:13.761Z,1349799433.761 [science_to_67_70:AltEnvWrapper:B.Wait](DEBUG): Construct Wait.
2012-10-09T16:17:13.763Z,1349799433.763 [science_to_67_70:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2012-10-09T16:17:13.764Z,1349799433.764 [science_to_67_70:B.DepthEnvelope](CRITICAL): No configuration setting for: VerticalControl.massTurnTime
2012-10-09T16:17:13.776Z,1349799433.776 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2012-10-09T16:17:13.782Z,1349799433.782 [science_to_67_70:OffshoreWrapper:B.Wait](DEBUG): Construct Wait.
2012-10-09T16:17:13.783Z,1349799433.783 [MissionManager](INFO): Inserting Stack: Missions/Insert/Surface.xml
2012-10-09T16:17:13.800Z,1349799433.800 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.SurfaceDepthRate = nan m/s
2012-10-09T16:17:13.803Z,1349799433.803 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.SurfacePitch = nan arcdeg
2012-10-09T16:17:13.806Z,1349799433.806 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.SurfaceSpeed = 0.500000 m/s
2012-10-09T16:17:13.809Z,1349799433.809 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.IridiumTimeout = 30.000000 min
2012-10-09T16:17:13.810Z,1349799433.810 [science_to_67_70:SURFACECOMMS:A.GoToSurface](DEBUG): Construct GoToSurface.
2012-10-09T16:17:13.819Z,1349799433.819 [MissionManager](INFO): RedefineArg science_to_67_70:SURFACECOMMS.SurfacePitch = 20.000000 arcdeg
2012-10-09T16:17:13.825Z,1349799433.825 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2012-10-09T16:17:13.893Z,1349799433.893 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleISUS = 0 bool
2012-10-09T16:17:13.895Z,1349799433.895 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleRSSI = 1 bool
2012-10-09T16:17:13.898Z,1349799433.898 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleADCP = 1 bool
2012-10-09T16:17:13.900Z,1349799433.900 [MissionManager](INFO): DefineArg science_to_67_70:Science.PeakDetectChlActive = 0 bool
2012-10-09T16:17:13.903Z,1349799433.903 [MissionManager](INFO): DefineArg science_to_67_70:Science.PeakDetectNO3Active = 0 bool
2012-10-09T16:17:13.905Z,1349799433.905 [MissionManager](INFO): DefineArg science_to_67_70:Science.PeakDetectRSSIActive = 0 bool
2012-10-09T16:17:13.908Z,1349799433.908 [MissionManager](INFO): DefineArg science_to_67_70:Science.EnabledAanderaaO2 = 0.000000 n/a
2012-10-09T16:17:13.912Z,1349799433.912 [MissionManager](INFO): DefineArg science_to_67_70:Science.EnabledNeilBrown = 1.000000 n/a
2012-10-09T16:17:13.915Z,1349799433.915 [MissionManager](INFO): DefineArg science_to_67_70:Science.EnabledTurbulence_NPS = 1.000000 n/a
2012-10-09T16:17:13.919Z,1349799433.919 [MissionManager](INFO): DefineArg science_to_67_70:Science.EnabledWetLabsBB2FL = 1.000000 n/a
2012-10-09T16:17:13.922Z,1349799433.922 [MissionManager](INFO): DefineArg science_to_67_70:Science.EnabledDVL = 1.000000 n/a
2012-10-09T16:17:13.925Z,1349799433.925 [MissionManager](INFO): DefineArg science_to_67_70:Science.EnabledISUS = 0.000000 n/a
2012-10-09T16:17:13.928Z,1349799433.928 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChl = 0.000000 ug/l
2012-10-09T16:17:13.931Z,1349799433.931 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChlDepth = 0.000000 m
2012-10-09T16:17:13.934Z,1349799433.934 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChlLatitude = nan arcdeg
2012-10-09T16:17:13.937Z,1349799433.937 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChlLongitude = nan arcdeg
2012-10-09T16:17:13.940Z,1349799433.940 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3 = 0.000000 umol/l
2012-10-09T16:17:13.942Z,1349799433.942 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3Depth = 0.000000 m
2012-10-09T16:17:13.946Z,1349799433.945 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3Latitude = nan arcdeg
2012-10-09T16:17:13.948Z,1349799433.948 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3Longitude = nan arcdeg
2012-10-09T16:17:13.951Z,1349799433.951 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakRSSI = 0.000000 dB
2012-10-09T16:17:13.954Z,1349799433.954 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakRSSIDepth = 0.000000 m
2012-10-09T16:17:13.957Z,1349799433.957 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakRSSILatitude = nan arcdeg
2012-10-09T16:17:13.960Z,1349799433.960 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakRSSILongitude = nan arcdeg
2012-10-09T16:17:13.971Z,1349799433.971 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2012-10-09T16:17:13.979Z,1349799433.979 [science_to_67_70:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2012-10-09T16:17:13.989Z,1349799433.989 [science_to_67_70:Science:PeakDetectRSSI:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2012-10-09T16:17:13.996Z,1349799433.996 [MissionManager](INFO): RedefineArg science_to_67_70:Science.SampleISUS = 1 bool
2012-10-09T16:17:13.997Z,1349799433.997 [MissionManager](INFO): RedefineArg science_to_67_70:Science.PeakDetectChlActive = 1 bool
2012-10-09T16:17:13.999Z,1349799433.999 [science_to_67_70:G.Buoyancy](DEBUG): Construct Buoyancy.
2012-10-09T16:17:14.002Z,1349799434.002 [science_to_67_70:H.SetSpeed](DEBUG): Construct.
2012-10-09T16:17:14.005Z,1349799434.005 [science_to_67_70:I.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2012-10-09T16:17:14.014Z,1349799434.014 [science_to_67_70:J.YoYo](DEBUG): Construct YoYo.
2012-10-09T16:17:14.021Z,1349799434.021 [science_to_67_70:Wpt.Point](DEBUG): Construct.
2012-10-09T16:17:14.039Z,1349799434.039 [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-10-09T16:17:14.040Z,1349799434.040 [CommandLine](IMPORTANT): Running ./Missions/Science/science_to_67_70.xml
2012-10-09T16:17:16.073Z,1349799436.073 [Default] Stopped
2012-10-09T16:17:16.073Z,1349799436.073 [Default](INFO): Aggregate::uninitialize Default
2012-10-09T16:17:16.073Z,1349799436.073 [Default:D.SetSpeed] Stopped
2012-10-09T16:17:16.073Z,1349799436.073 [Default:D.SetSpeed](DEBUG): Uninitialize.
2012-10-09T16:17:16.074Z,1349799436.074 [Default:E.GoToSurface] Stopped
2012-10-09T16:17:16.074Z,1349799436.074 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-09T16:17:16.074Z,1349799436.074 [Default:F.Wait] Stopped
2012-10-09T16:17:16.074Z,1349799436.074 [Default:F.Wait](DEBUG): Uninitialize Wait Component.
2012-10-09T16:17:16.074Z,1349799436.074 [MissionManager](IMPORTANT): Started mission science_to_67_70
2012-10-09T16:17:16.074Z,1349799436.074 [science_to_67_70] Running Loop=1
2012-10-09T16:17:16.074Z,1349799436.074 [science_to_67_70](INFO): Aggregate::initialize science_to_67_70
2012-10-09T16:17:16.075Z,1349799436.075 [science_to_67_70:B.DepthEnvelope] Running Loop=1
2012-10-09T16:17:16.075Z,1349799436.075 [science_to_67_70:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2012-10-09T16:17:16.076Z,1349799436.076 [science_to_67_70:Science] Running Loop=1
2012-10-09T16:17:16.076Z,1349799436.076 [science_to_67_70:Science](INFO): Aggregate::initialize science_to_67_70:Science
2012-10-09T16:17:16.076Z,1349799436.076 [science_to_67_70:Science:A] Running Loop=1
2012-10-09T16:17:16.076Z,1349799436.076 [science_to_67_70:Science:B] Running Loop=1
2012-10-09T16:17:16.076Z,1349799436.076 [science_to_67_70:Science:C] Running Loop=1
2012-10-09T16:17:16.076Z,1349799436.076 [science_to_67_70:Science:D] Running Loop=1
2012-10-09T16:17:16.076Z,1349799436.076 [science_to_67_70:Science:E] Running Loop=1
2012-10-09T16:17:16.076Z,1349799436.076 [science_to_67_70:Science:F] Running Loop=1
2012-10-09T16:17:16.076Z,1349799436.076 [science_to_67_70:Science:G] Running Loop=1
2012-10-09T16:17:16.076Z,1349799436.076 [science_to_67_70:G.Buoyancy] Running Loop=1
2012-10-09T16:17:16.076Z,1349799436.076 [science_to_67_70:G.Buoyancy](DEBUG): Initialize Buoyancy Component.
2012-10-09T16:17:16.076Z,1349799436.076 [science_to_67_70:H.SetSpeed] Running Loop=1
2012-10-09T16:17:16.076Z,1349799436.076 [science_to_67_70:H.SetSpeed](DEBUG): Initialize.
2012-10-09T16:17:16.077Z,1349799436.077 [science_to_67_70:I.DepthEnvelope] Running Loop=1
2012-10-09T16:17:16.077Z,1349799436.077 [science_to_67_70:I.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2012-10-09T16:17:16.078Z,1349799436.078 [science_to_67_70:J.YoYo] Running Loop=1
2012-10-09T16:17:16.078Z,1349799436.078 [science_to_67_70:J.YoYo](DEBUG): Initialize YoYoComponent.
2012-10-09T16:17:16.078Z,1349799436.078 [science_to_67_70:SURFACECOMMS] Running Loop=1
2012-10-09T16:17:16.079Z,1349799436.079 [science_to_67_70:SURFACECOMMS](INFO): Aggregate::initialize science_to_67_70:SURFACECOMMS
2012-10-09T16:17:16.079Z,1349799436.079 [science_to_67_70:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-10-09T16:17:16.079Z,1349799436.079 [science_to_67_70:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-09T16:17:16.080Z,1349799436.080 [science_to_67_70:J.YoYo] Running Loop=1
2012-10-09T16:17:16.085Z,1349799436.085 [science_to_67_70:I.DepthEnvelope] Running Loop=1
2012-10-09T16:17:16.090Z,1349799436.091 [science_to_67_70:H.SetSpeed] Running Loop=1
2012-10-09T16:17:16.095Z,1349799436.095 [science_to_67_70:G.Buoyancy] Running Loop=1
2012-10-09T16:17:16.102Z,1349799436.102 [science_to_67_70:Science] Running Loop=1
2012-10-09T16:17:16.103Z,1349799436.103 [science_to_67_70:Science:PeakDetectChl] Running Loop=1
2012-10-09T16:17:16.104Z,1349799436.103 [science_to_67_70:Science:PeakDetectChl](INFO): Aggregate::initialize science_to_67_70:Science:PeakDetectChl
2012-10-09T16:17:16.104Z,1349799436.104 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2012-10-09T16:17:16.104Z,1349799436.104 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth](INFO): Initialize.
2012-10-09T16:17:16.110Z,1349799436.110 [science_to_67_70:Science:G] Running Loop=1
2012-10-09T16:17:16.111Z,1349799436.111 [science_to_67_70:Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2012-10-09T16:17:16.112Z,1349799436.112 [science_to_67_70:Science:F] Running Loop=1
2012-10-09T16:17:16.112Z,1349799436.112 [science_to_67_70:Science:F](DEBUG): Initialize ReadDataComponent to sense DVL_micro.Beam1RSSI
2012-10-09T16:17:16.114Z,1349799436.114 [science_to_67_70:Science:E] Running Loop=1
2012-10-09T16:17:16.115Z,1349799436.115 [science_to_67_70:Science:E](DEBUG): Initialize ReadDataComponent to sense DVL_micro.AdcpXWaterVelocity
2012-10-09T16:17:16.116Z,1349799436.116 [science_to_67_70:Science:D] Running Loop=1
2012-10-09T16:17:16.117Z,1349799436.117 [science_to_67_70:Science:D](DEBUG): Initialize ReadDataComponent to sense Turbulence_NPS.MicroCond
2012-10-09T16:17:16.118Z,1349799436.118 [science_to_67_70:Science:C] Running Loop=1
2012-10-09T16:17:16.119Z,1349799436.119 [science_to_67_70:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2012-10-09T16:17:16.119Z,1349799436.119 [science_to_67_70:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2012-10-09T16:17:16.126Z,1349799436.126 [science_to_67_70:SURFACECOMMS:B] Running Loop=1
2012-10-09T16:17:16.126Z,1349799436.126 [science_to_67_70:SURFACECOMMS:B](INFO): Aggregate::initialize science_to_67_70:SURFACECOMMS:B
2012-10-09T16:17:16.126Z,1349799436.126 [science_to_67_70:SURFACECOMMS:B:A] Running Loop=1
2012-10-09T16:17:16.126Z,1349799436.126 [science_to_67_70:OffshoreWrapper] Running Loop=1
2012-10-09T16:17:16.126Z,1349799436.126 [science_to_67_70:OffshoreWrapper](INFO): Aggregate::initialize science_to_67_70:OffshoreWrapper
2012-10-09T16:17:16.126Z,1349799436.126 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope] Running Loop=1
2012-10-09T16:17:16.126Z,1349799436.126 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2012-10-09T16:17:16.127Z,1349799436.127 [science_to_67_70:OffshoreWrapper:B.Wait] Running Loop=1
2012-10-09T16:17:16.127Z,1349799436.127 [science_to_67_70:OffshoreWrapper:B.Wait](DEBUG): Initialize Wait Component.
2012-10-09T16:17:16.131Z,1349799436.131 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope] Running Loop=1
2012-10-09T16:17:16.136Z,1349799436.136 [science_to_67_70:B.DepthEnvelope] Running Loop=1
2012-10-09T16:17:16.141Z,1349799436.141 [science_to_67_70:AltEnvWrapper] Running Loop=1
2012-10-09T16:17:16.141Z,1349799436.141 [science_to_67_70:AltEnvWrapper](INFO): Aggregate::initialize science_to_67_70:AltEnvWrapper
2012-10-09T16:17:16.141Z,1349799436.141 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope] Running Loop=1
2012-10-09T16:17:16.141Z,1349799436.141 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2012-10-09T16:17:16.142Z,1349799436.142 [science_to_67_70:AltEnvWrapper:B.Wait] Running Loop=1
2012-10-09T16:17:16.142Z,1349799436.142 [science_to_67_70:AltEnvWrapper:B.Wait](DEBUG): Initialize Wait Component.
2012-10-09T16:17:16.147Z,1349799436.147 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope] Running Loop=1
2012-10-09T16:17:16.291Z,1349799436.291 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-10-09T16:17:16.292Z,1349799436.292 [DVL_micro](INFO): resume:Powering up
2012-10-09T16:17:16.292Z,1349799436.292 [DVL_micro](INFO): Cycling power to configure device.
2012-10-09T16:17:16.293Z,1349799436.293 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2012-10-09T16:17:16.303Z,1349799436.303 [Turbulence_NPS](INFO): Resume powering up
2012-10-09T16:17:16.304Z,1349799436.304 [Turbulence_NPS](INFO): Opening uart, block timeout 10ths=1
2012-10-09T16:17:16.347Z,1349799436.347 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=4
2012-10-09T16:17:16.347Z,1349799436.347 [WetLabsBB2FL](INFO): Powering up
2012-10-09T16:17:17.310Z,1349799437.309 [science_to_67_70:J.YoYo] Preempted
2012-10-09T16:17:17.310Z,1349799437.310 [science_to_67_70:I.DepthEnvelope] Preempted
2012-10-09T16:17:17.310Z,1349799437.310 [science_to_67_70:H.SetSpeed] Preempted
2012-10-09T16:17:17.310Z,1349799437.310 [science_to_67_70:G.Buoyancy] Preempted
2012-10-09T16:17:17.310Z,1349799437.310 [science_to_67_70:Science] Preempted
2012-10-09T16:17:17.310Z,1349799437.310 [science_to_67_70:Science:C] Preempted
2012-10-09T16:17:17.310Z,1349799437.310 [science_to_67_70:Science:D] Preempted
2012-10-09T16:17:17.310Z,1349799437.310 [science_to_67_70:Science:E] Preempted
2012-10-09T16:17:17.310Z,1349799437.310 [science_to_67_70:Science:F] Preempted
2012-10-09T16:17:17.310Z,1349799437.310 [science_to_67_70:Science:G] Preempted
2012-10-09T16:17:17.315Z,1349799437.315 [science_to_67_70:SURFACECOMMS:B:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-10-09T16:17:17.317Z,1349799437.317 [science_to_67_70:SURFACECOMMS:B:A] Stopped
2012-10-09T16:17:17.317Z,1349799437.317 [science_to_67_70:SURFACECOMMS:B:B] Running Loop=1
2012-10-09T16:17:17.324Z,1349799437.324 [science_to_67_70] Stopped
2012-10-09T16:17:17.324Z,1349799437.324 [science_to_67_70](INFO): Aggregate::uninitialize science_to_67_70
2012-10-09T16:17:17.324Z,1349799437.324 [science_to_67_70:AltEnvWrapper] Stopped
2012-10-09T16:17:17.324Z,1349799437.325 [science_to_67_70:AltEnvWrapper](INFO): Aggregate::uninitialize science_to_67_70:AltEnvWrapper
2012-10-09T16:17:17.325Z,1349799437.325 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope] Stopped
2012-10-09T16:17:17.325Z,1349799437.325 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2012-10-09T16:17:17.325Z,1349799437.325 [science_to_67_70:AltEnvWrapper:B.Wait] Stopped
2012-10-09T16:17:17.325Z,1349799437.325 [science_to_67_70:AltEnvWrapper:B.Wait](DEBUG): Uninitialize Wait Component.
2012-10-09T16:17:17.325Z,1349799437.325 [science_to_67_70:B.DepthEnvelope] Stopped
2012-10-09T16:17:17.325Z,1349799437.325 [science_to_67_70:B.DepthEnvelope](DEBUG): Uninitialize.
2012-10-09T16:17:17.325Z,1349799437.325 [science_to_67_70:OffshoreWrapper] Stopped
2012-10-09T16:17:17.325Z,1349799437.325 [science_to_67_70:OffshoreWrapper](INFO): Aggregate::uninitialize science_to_67_70:OffshoreWrapper
2012-10-09T16:17:17.325Z,1349799437.325 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope] Stopped
2012-10-09T16:17:17.325Z,1349799437.325 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2012-10-09T16:17:17.325Z,1349799437.325 [science_to_67_70:OffshoreWrapper:B.Wait] Stopped
2012-10-09T16:17:17.325Z,1349799437.325 [science_to_67_70:OffshoreWrapper:B.Wait](DEBUG): Uninitialize Wait Component.
2012-10-09T16:17:17.325Z,1349799437.325 [science_to_67_70:SURFACECOMMS] Stopped
2012-10-09T16:17:17.326Z,1349799437.326 [science_to_67_70:SURFACECOMMS](INFO): Aggregate::uninitialize science_to_67_70:SURFACECOMMS
2012-10-09T16:17:17.326Z,1349799437.326 [science_to_67_70:SURFACECOMMS:A.GoToSurface] Stopped
2012-10-09T16:17:17.326Z,1349799437.326 [science_to_67_70:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-09T16:17:17.326Z,1349799437.326 [science_to_67_70:SURFACECOMMS:B] Stopped
2012-10-09T16:17:17.326Z,1349799437.326 [science_to_67_70:SURFACECOMMS:B](INFO): Aggregate::uninitialize science_to_67_70:SURFACECOMMS:B
2012-10-09T16:17:17.326Z,1349799437.326 [science_to_67_70:SURFACECOMMS:B:B] Stopped
2012-10-09T16:17:17.326Z,1349799437.326 [science_to_67_70:Science] Stopped
2012-10-09T16:17:17.331Z,1349799437.331 [science_to_67_70:Science](INFO): Aggregate::uninitialize science_to_67_70:Science
2012-10-09T16:17:17.331Z,1349799437.331 [science_to_67_70:Science:A] Stopped
2012-10-09T16:17:17.331Z,1349799437.331 [science_to_67_70:Science:B] Stopped
2012-10-09T16:17:17.331Z,1349799437.331 [science_to_67_70:Science:C] Stopped
2012-10-09T16:17:17.331Z,1349799437.331 [science_to_67_70:Science:D] Stopped
2012-10-09T16:17:17.331Z,1349799437.331 [science_to_67_70:Science:E] Stopped
2012-10-09T16:17:17.331Z,1349799437.331 [science_to_67_70:Science:F] Stopped
2012-10-09T16:17:17.331Z,1349799437.331 [science_to_67_70:Science:G] Stopped
2012-10-09T16:17:17.331Z,1349799437.331 [science_to_67_70:Science:PeakDetectChl] Stopped
2012-10-09T16:17:17.331Z,1349799437.331 [science_to_67_70:Science:PeakDetectChl](INFO): Aggregate::uninitialize science_to_67_70:Science:PeakDetectChl
2012-10-09T16:17:17.331Z,1349799437.331 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2012-10-09T16:17:17.331Z,1349799437.331 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2012-10-09T16:17:17.331Z,1349799437.332 [science_to_67_70:G.Buoyancy] Stopped
2012-10-09T16:17:17.331Z,1349799437.332 [science_to_67_70:G.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2012-10-09T16:17:17.332Z,1349799437.332 [science_to_67_70:H.SetSpeed] Stopped
2012-10-09T16:17:17.332Z,1349799437.332 [science_to_67_70:H.SetSpeed](DEBUG): Uninitialize.
2012-10-09T16:17:17.332Z,1349799437.332 [science_to_67_70:I.DepthEnvelope] Stopped
2012-10-09T16:17:17.332Z,1349799437.332 [science_to_67_70:I.DepthEnvelope](DEBUG): Uninitialize.
2012-10-09T16:17:17.332Z,1349799437.332 [science_to_67_70:J.YoYo] Stopped
2012-10-09T16:17:17.332Z,1349799437.332 [science_to_67_70:J.YoYo](DEBUG): Uninitialize YoYoComponent.
2012-10-09T16:17:17.335Z,1349799437.335 [Turbulence_NPS](INFO): Pause powering down
2012-10-09T16:17:17.539Z,1349799437.539 [WetLabsBB2FL](INFO): Powering down
2012-10-09T16:17:17.848Z,1349799437.848 [MissionManager](IMPORTANT): Started mission Default
2012-10-09T16:17:17.848Z,1349799437.848 [Default] Running Loop=1
2012-10-09T16:17:17.848Z,1349799437.848 [Default](INFO): Aggregate::initialize Default
2012-10-09T16:17:17.848Z,1349799437.848 [Default:D.SetSpeed] Running Loop=1
2012-10-09T16:17:17.848Z,1349799437.848 [Default:D.SetSpeed](DEBUG): Initialize.
2012-10-09T16:17:17.848Z,1349799437.848 [Default:E.GoToSurface] Running Loop=1
2012-10-09T16:17:17.848Z,1349799437.848 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-09T16:17:17.849Z,1349799437.849 [Default:Iridium] Running Loop=1
2012-10-09T16:17:17.849Z,1349799437.849 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-10-09T16:17:17.849Z,1349799437.849 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-10-09T16:17:17.849Z,1349799437.849 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-10-09T16:17:17.849Z,1349799437.849 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-10-09T16:17:17.849Z,1349799437.849 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-09T16:17:17.855Z,1349799437.855 [Default:Iridium:B.GoToSurface] Stopped
2012-10-09T16:17:17.855Z,1349799437.855 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-09T16:17:17.855Z,1349799437.855 [Default:Iridium:Read_Iridium] Running Loop=1
2012-10-09T16:17:18.299Z,1349799438.299 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-10-09T16:17:18.299Z,1349799438.299 [DVL_micro](INFO): Querying output modes
2012-10-09T16:17:18.299Z,1349799438.299 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-10-09T16:17:18.303Z,1349799438.303 [DVL_micro](DEBUG): cmdResponse: ���;r���W[�kg#f�e�d�e�d�f{�
2012-10-09T16:17:18.303Z,1349799438.303 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-10-09T16:17:18.307Z,1349799438.307 [DVL_micro](DEBUG): cmdResponse: ke+W+�.�
2012-10-09T16:17:18.309Z,1349799438.309 [DVL_micro](INFO): pause:Powering down
2012-10-09T16:17:21.411Z,1349799441.411 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2012-10-09T16:17:21.437Z,1349799441.437 [Default:Iridium:Read_Iridium] Stopped
2012-10-09T16:17:21.438Z,1349799441.438 [Default:Iridium](INFO): Completed Default:Iridium
2012-10-09T16:17:21.438Z,1349799441.438 [Default:Iridium] Stopped
2012-10-09T16:17:21.438Z,1349799441.438 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-10-09T16:17:21.438Z,1349799441.438 [Default:Iridium:A.SetSpeed] Stopped
2012-10-09T16:17:21.438Z,1349799441.438 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-10-09T16:17:21.438Z,1349799441.438 [Default:F.Wait] Running Loop=1
2012-10-09T16:17:21.438Z,1349799441.438 [Default:F.Wait](DEBUG): Initialize Wait Component.
2012-10-09T16:17:28.947Z,1349799448.947 [CommandLine](IMPORTANT): got command run ./Missions/Science/science_to_67_70.xml
2012-10-09T16:17:28.947Z,1349799448.947 [MissionManager](INFO): Loading Mission: ./Missions/Science/science_to_67_70.xml
2012-10-09T16:17:29.008Z,1349799449.008 [MissionManager](INFO): DefineArg science_to_67_70.MinAltitude = 10.000000 m
2012-10-09T16:17:29.010Z,1349799449.010 [MissionManager](INFO): DefineArg science_to_67_70.MaxDepth = 105.000000 m
2012-10-09T16:17:29.012Z,1349799449.012 [MissionManager](INFO): DefineArg science_to_67_70.MinOffshore = 2000.000000 m
2012-10-09T16:17:29.015Z,1349799449.015 [MissionManager](INFO): DefineArg science_to_67_70.YoYoMinDepth = 3.000000 m
2012-10-09T16:17:29.017Z,1349799449.017 [MissionManager](INFO): DefineArg science_to_67_70.YoYoMaxDepth = 100.000000 m
2012-10-09T16:17:29.019Z,1349799449.019 [MissionManager](INFO): DefineArg science_to_67_70.YoYoUpPitch = 20.000000 arcdeg
2012-10-09T16:17:29.021Z,1349799449.021 [MissionManager](INFO): DefineArg science_to_67_70.YoYoDownPitch = -15.000000 arcdeg
2012-10-09T16:17:29.023Z,1349799449.023 [MissionManager](INFO): DefineArg science_to_67_70.WptLat = 36.120000 arcdeg
2012-10-09T16:17:29.025Z,1349799449.025 [MissionManager](INFO): DefineArg science_to_67_70.WptLon = -123.486000 arcdeg
2012-10-09T16:17:29.028Z,1349799449.028 [MissionManager](INFO): DefineArg science_to_67_70.NeedCommsTime = 120.000000 min
2012-10-09T16:17:29.030Z,1349799449.030 [MissionManager](INFO): DefineArg science_to_67_70.Speed = 1.000000 m/s
2012-10-09T16:17:29.032Z,1349799449.032 [MissionManager](INFO): DefineArg science_to_67_70.Timeout = 36.000000 h
2012-10-09T16:17:29.034Z,1349799449.034 [MissionManager](INFO): DefineArg science_to_67_70.BuoyancyNeutral = 0.000330 n/a
2012-10-09T16:17:29.037Z,1349799449.037 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2012-10-09T16:17:29.041Z,1349799449.041 [science_to_67_70:AltEnvWrapper:B.Wait](DEBUG): Construct Wait.
2012-10-09T16:17:29.042Z,1349799449.042 [science_to_67_70:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2012-10-09T16:17:29.049Z,1349799449.049 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2012-10-09T16:17:29.053Z,1349799449.053 [science_to_67_70:OffshoreWrapper:B.Wait](DEBUG): Construct Wait.
2012-10-09T16:17:29.054Z,1349799449.054 [MissionManager](INFO): Inserting Stack: Missions/Insert/Surface.xml
2012-10-09T16:17:29.070Z,1349799449.070 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.SurfaceDepthRate = nan m/s
2012-10-09T16:17:29.072Z,1349799449.072 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.SurfacePitch = nan arcdeg
2012-10-09T16:17:29.074Z,1349799449.074 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.SurfaceSpeed = 0.500000 m/s
2012-10-09T16:17:29.076Z,1349799449.076 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.IridiumTimeout = 30.000000 min
2012-10-09T16:17:29.077Z,1349799449.077 [science_to_67_70:SURFACECOMMS:A.GoToSurface](DEBUG): Construct GoToSurface.
2012-10-09T16:17:29.085Z,1349799449.085 [MissionManager](INFO): RedefineArg science_to_67_70:SURFACECOMMS.SurfacePitch = 20.000000 arcdeg
2012-10-09T16:17:29.090Z,1349799449.090 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2012-10-09T16:17:29.158Z,1349799449.158 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleISUS = 0 bool
2012-10-09T16:17:29.160Z,1349799449.160 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleRSSI = 1 bool
2012-10-09T16:17:29.162Z,1349799449.161 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleADCP = 1 bool
2012-10-09T16:17:29.163Z,1349799449.163 [MissionManager](INFO): DefineArg science_to_67_70:Science.PeakDetectChlActive = 0 bool
2012-10-09T16:17:29.165Z,1349799449.165 [MissionManager](INFO): DefineArg science_to_67_70:Science.PeakDetectNO3Active = 0 bool
2012-10-09T16:17:29.167Z,1349799449.167 [MissionManager](INFO): DefineArg science_to_67_70:Science.PeakDetectRSSIActive = 0 bool
2012-10-09T16:17:29.169Z,1349799449.169 [MissionManager](INFO): DefineArg science_to_67_70:Science.EnabledAanderaaO2 = 0.000000 n/a
2012-10-09T16:17:29.171Z,1349799449.171 [MissionManager](INFO): DefineArg science_to_67_70:Science.EnabledNeilBrown = 1.000000 n/a
2012-10-09T16:17:29.174Z,1349799449.174 [MissionManager](INFO): DefineArg science_to_67_70:Science.EnabledTurbulence_NPS = 1.000000 n/a
2012-10-09T16:17:29.176Z,1349799449.176 [MissionManager](INFO): DefineArg science_to_67_70:Science.EnabledWetLabsBB2FL = 1.000000 n/a
2012-10-09T16:17:29.178Z,1349799449.178 [MissionManager](INFO): DefineArg science_to_67_70:Science.EnabledDVL = 1.000000 n/a
2012-10-09T16:17:29.180Z,1349799449.180 [MissionManager](INFO): DefineArg science_to_67_70:Science.EnabledISUS = 0.000000 n/a
2012-10-09T16:17:29.183Z,1349799449.183 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChl = 0.000000 ug/l
2012-10-09T16:17:29.185Z,1349799449.185 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChlDepth = 0.000000 m
2012-10-09T16:17:29.187Z,1349799449.187 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChlLatitude = nan arcdeg
2012-10-09T16:17:29.189Z,1349799449.189 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChlLongitude = nan arcdeg
2012-10-09T16:17:29.191Z,1349799449.191 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3 = 0.000000 umol/l
2012-10-09T16:17:29.193Z,1349799449.193 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3Depth = 0.000000 m
2012-10-09T16:17:29.196Z,1349799449.196 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3Latitude = nan arcdeg
2012-10-09T16:17:29.198Z,1349799449.198 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3Longitude = nan arcdeg
2012-10-09T16:17:29.200Z,1349799449.200 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakRSSI = 0.000000 dB
2012-10-09T16:17:29.202Z,1349799449.202 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakRSSIDepth = 0.000000 m
2012-10-09T16:17:29.204Z,1349799449.204 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakRSSILatitude = nan arcdeg
2012-10-09T16:17:29.207Z,1349799449.207 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakRSSILongitude = nan arcdeg
2012-10-09T16:17:29.216Z,1349799449.216 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2012-10-09T16:17:29.223Z,1349799449.224 [science_to_67_70:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2012-10-09T16:17:29.230Z,1349799449.230 [science_to_67_70:Science:PeakDetectRSSI:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2012-10-09T16:17:29.237Z,1349799449.237 [MissionManager](INFO): RedefineArg science_to_67_70:Science.SampleISUS = 1 bool
2012-10-09T16:17:29.238Z,1349799449.238 [MissionManager](INFO): RedefineArg science_to_67_70:Science.PeakDetectChlActive = 1 bool
2012-10-09T16:17:29.239Z,1349799449.239 [science_to_67_70:G.Buoyancy](DEBUG): Construct Buoyancy.
2012-10-09T16:17:29.242Z,1349799449.241 [science_to_67_70:H.SetSpeed](DEBUG): Construct.
2012-10-09T16:17:29.244Z,1349799449.244 [science_to_67_70:I.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2012-10-09T16:17:29.250Z,1349799449.250 [science_to_67_70:J.YoYo](DEBUG): Construct YoYo.
2012-10-09T16:17:29.255Z,1349799449.255 [science_to_67_70:Wpt.Point](DEBUG): Construct.
2012-10-09T16:17:29.273Z,1349799449.273 [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-10-09T16:17:29.273Z,1349799449.273 [CommandLine](IMPORTANT): Running ./Missions/Science/science_to_67_70.xml
2012-10-09T16:17:31.869Z,1349799451.869 [Default] Stopped
2012-10-09T16:17:31.869Z,1349799451.869 [Default](INFO): Aggregate::uninitialize Default
2012-10-09T16:17:31.869Z,1349799451.869 [Default:D.SetSpeed] Stopped
2012-10-09T16:17:31.869Z,1349799451.869 [Default:D.SetSpeed](DEBUG): Uninitialize.
2012-10-09T16:17:31.870Z,1349799451.870 [Default:E.GoToSurface] Stopped
2012-10-09T16:17:31.870Z,1349799451.870 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-09T16:17:31.870Z,1349799451.870 [Default:F.Wait] Stopped
2012-10-09T16:17:31.870Z,1349799451.870 [Default:F.Wait](DEBUG): Uninitialize Wait Component.
2012-10-09T16:17:31.870Z,1349799451.870 [MissionManager](IMPORTANT): Started mission science_to_67_70
2012-10-09T16:17:31.870Z,1349799451.870 [science_to_67_70] Running Loop=1
2012-10-09T16:17:31.871Z,1349799451.871 [science_to_67_70](INFO): Aggregate::initialize science_to_67_70
2012-10-09T16:17:31.871Z,1349799451.871 [science_to_67_70:B.DepthEnvelope] Running Loop=1
2012-10-09T16:17:31.871Z,1349799451.871 [science_to_67_70:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2012-10-09T16:17:31.871Z,1349799451.871 [science_to_67_70:Science] Running Loop=1
2012-10-09T16:17:31.872Z,1349799451.872 [science_to_67_70:Science](INFO): Aggregate::initialize science_to_67_70:Science
2012-10-09T16:17:31.872Z,1349799451.872 [science_to_67_70:Science:A] Running Loop=1
2012-10-09T16:17:31.872Z,1349799451.872 [science_to_67_70:Science:B] Running Loop=1
2012-10-09T16:17:31.872Z,1349799451.872 [science_to_67_70:Science:C] Running Loop=1
2012-10-09T16:17:31.872Z,1349799451.872 [science_to_67_70:Science:D] Running Loop=1
2012-10-09T16:17:31.872Z,1349799451.872 [science_to_67_70:Science:E] Running Loop=1
2012-10-09T16:17:31.872Z,1349799451.872 [science_to_67_70:Science:F] Running Loop=1
2012-10-09T16:17:31.872Z,1349799451.872 [science_to_67_70:Science:G] Running Loop=1
2012-10-09T16:17:31.872Z,1349799451.872 [science_to_67_70:G.Buoyancy] Running Loop=1
2012-10-09T16:17:31.872Z,1349799451.872 [science_to_67_70:G.Buoyancy](DEBUG): Initialize Buoyancy Component.
2012-10-09T16:17:31.872Z,1349799451.872 [science_to_67_70:H.SetSpeed] Running Loop=1
2012-10-09T16:17:31.872Z,1349799451.872 [science_to_67_70:H.SetSpeed](DEBUG): Initialize.
2012-10-09T16:17:31.872Z,1349799451.872 [science_to_67_70:I.DepthEnvelope] Running Loop=1
2012-10-09T16:17:31.873Z,1349799451.872 [science_to_67_70:I.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2012-10-09T16:17:31.873Z,1349799451.873 [science_to_67_70:J.YoYo] Running Loop=1
2012-10-09T16:17:31.873Z,1349799451.873 [science_to_67_70:J.YoYo](DEBUG): Initialize YoYoComponent.
2012-10-09T16:17:31.873Z,1349799451.874 [science_to_67_70:SURFACECOMMS] Running Loop=1
2012-10-09T16:17:31.874Z,1349799451.874 [science_to_67_70:SURFACECOMMS](INFO): Aggregate::initialize science_to_67_70:SURFACECOMMS
2012-10-09T16:17:31.874Z,1349799451.874 [science_to_67_70:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-10-09T16:17:31.874Z,1349799451.874 [science_to_67_70:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-09T16:17:31.875Z,1349799451.875 [science_to_67_70:J.YoYo] Running Loop=1
2012-10-09T16:17:31.876Z,1349799451.876 [science_to_67_70:I.DepthEnvelope] Running Loop=1
2012-10-09T16:17:31.877Z,1349799451.878 [science_to_67_70:H.SetSpeed] Running Loop=1
2012-10-09T16:17:31.879Z,1349799451.879 [science_to_67_70:G.Buoyancy] Running Loop=1
2012-10-09T16:17:31.879Z,1349799451.879 [science_to_67_70:Science] Running Loop=1
2012-10-09T16:17:31.880Z,1349799451.880 [science_to_67_70:Science:PeakDetectChl] Running Loop=1
2012-10-09T16:17:31.880Z,1349799451.880 [science_to_67_70:Science:PeakDetectChl](INFO): Aggregate::initialize science_to_67_70:Science:PeakDetectChl
2012-10-09T16:17:31.881Z,1349799451.881 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2012-10-09T16:17:31.881Z,1349799451.881 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth](INFO): Initialize.
2012-10-09T16:17:31.883Z,1349799451.883 [science_to_67_70:Science:G] Running Loop=1
2012-10-09T16:17:31.883Z,1349799451.883 [science_to_67_70:Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2012-10-09T16:17:31.884Z,1349799451.884 [science_to_67_70:Science:F] Running Loop=1
2012-10-09T16:17:31.885Z,1349799451.885 [science_to_67_70:Science:F](DEBUG): Initialize ReadDataComponent to sense DVL_micro.Beam1RSSI
2012-10-09T16:17:31.885Z,1349799451.885 [science_to_67_70:Science:E] Running Loop=1
2012-10-09T16:17:31.886Z,1349799451.886 [science_to_67_70:Science:E](DEBUG): Initialize ReadDataComponent to sense DVL_micro.AdcpXWaterVelocity
2012-10-09T16:17:31.887Z,1349799451.887 [science_to_67_70:Science:D] Running Loop=1
2012-10-09T16:17:31.887Z,1349799451.887 [science_to_67_70:Science:D](DEBUG): Initialize ReadDataComponent to sense Turbulence_NPS.MicroCond
2012-10-09T16:17:31.888Z,1349799451.888 [science_to_67_70:Science:C] Running Loop=1
2012-10-09T16:17:31.889Z,1349799451.889 [science_to_67_70:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2012-10-09T16:17:31.889Z,1349799451.889 [science_to_67_70:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2012-10-09T16:17:31.891Z,1349799451.891 [science_to_67_70:SURFACECOMMS:B] Running Loop=1
2012-10-09T16:17:31.891Z,1349799451.891 [science_to_67_70:SURFACECOMMS:B](INFO): Aggregate::initialize science_to_67_70:SURFACECOMMS:B
2012-10-09T16:17:31.892Z,1349799451.892 [science_to_67_70:SURFACECOMMS:B:A] Running Loop=1
2012-10-09T16:17:31.892Z,1349799451.892 [science_to_67_70:OffshoreWrapper] Running Loop=1
2012-10-09T16:17:31.892Z,1349799451.892 [science_to_67_70:OffshoreWrapper](INFO): Aggregate::initialize science_to_67_70:OffshoreWrapper
2012-10-09T16:17:31.892Z,1349799451.892 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope] Running Loop=1
2012-10-09T16:17:31.892Z,1349799451.892 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2012-10-09T16:17:31.892Z,1349799451.892 [science_to_67_70:OffshoreWrapper:B.Wait] Running Loop=1
2012-10-09T16:17:31.892Z,1349799451.892 [science_to_67_70:OffshoreWrapper:B.Wait](DEBUG): Initialize Wait Component.
2012-10-09T16:17:31.893Z,1349799451.893 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope] Running Loop=1
2012-10-09T16:17:31.894Z,1349799451.894 [science_to_67_70:B.DepthEnvelope] Running Loop=1
2012-10-09T16:17:31.895Z,1349799451.895 [Turbulence_NPS](INFO): Resume powering up
2012-10-09T16:17:31.896Z,1349799451.895 [Turbulence_NPS](INFO): Opening uart, block timeout 10ths=1
2012-10-09T16:17:31.898Z,1349799451.898 [science_to_67_70:AltEnvWrapper] Running Loop=1
2012-10-09T16:17:31.899Z,1349799451.899 [science_to_67_70:AltEnvWrapper](INFO): Aggregate::initialize science_to_67_70:AltEnvWrapper
2012-10-09T16:17:31.899Z,1349799451.899 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope] Running Loop=1
2012-10-09T16:17:31.899Z,1349799451.899 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2012-10-09T16:17:31.899Z,1349799451.899 [science_to_67_70:AltEnvWrapper:B.Wait] Running Loop=1
2012-10-09T16:17:31.899Z,1349799451.899 [science_to_67_70:AltEnvWrapper:B.Wait](DEBUG): Initialize Wait Component.
2012-10-09T16:17:31.900Z,1349799451.900 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope] Running Loop=1
2012-10-09T16:17:31.987Z,1349799451.987 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2012-10-09T16:17:32.051Z,1349799452.051 [DVL_micro](INFO): RSSI requested
2012-10-09T16:17:32.051Z,1349799452.051 [DVL_micro](INFO): ADCP requested
2012-10-09T16:17:32.051Z,1349799452.051 [DVL_micro](INFO): NQ1 requested
2012-10-09T16:17:32.052Z,1349799452.052 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-10-09T16:17:32.052Z,1349799452.052 [DVL_micro](INFO): resume:Powering up
2012-10-09T16:17:32.052Z,1349799452.052 [DVL_micro](INFO): Cycling power to configure device.
2012-10-09T16:17:32.084Z,1349799452.084 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=4
2012-10-09T16:17:32.084Z,1349799452.084 [WetLabsBB2FL](INFO): Powering up
2012-10-09T16:17:33.129Z,1349799453.129 [science_to_67_70:J.YoYo] Preempted
2012-10-09T16:17:33.129Z,1349799453.129 [science_to_67_70:I.DepthEnvelope] Preempted
2012-10-09T16:17:33.129Z,1349799453.129 [science_to_67_70:H.SetSpeed] Preempted
2012-10-09T16:17:33.129Z,1349799453.129 [science_to_67_70:G.Buoyancy] Preempted
2012-10-09T16:17:33.129Z,1349799453.129 [science_to_67_70:Science] Preempted
2012-10-09T16:17:33.129Z,1349799453.129 [science_to_67_70:Science:C] Preempted
2012-10-09T16:17:33.129Z,1349799453.129 [science_to_67_70:Science:D] Preempted
2012-10-09T16:17:33.129Z,1349799453.129 [science_to_67_70:Science:E] Preempted
2012-10-09T16:17:33.129Z,1349799453.129 [science_to_67_70:Science:F] Preempted
2012-10-09T16:17:33.129Z,1349799453.129 [science_to_67_70:Science:G] Preempted
2012-10-09T16:17:33.130Z,1349799453.130 [science_to_67_70:SURFACECOMMS:B:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-10-09T16:17:33.131Z,1349799453.131 [science_to_67_70:SURFACECOMMS:B:A] Stopped
2012-10-09T16:17:33.131Z,1349799453.131 [science_to_67_70:SURFACECOMMS:B:B] Running Loop=1
2012-10-09T16:17:33.179Z,1349799453.179 [Turbulence_NPS](INFO): Pause powering down
2012-10-09T16:17:33.275Z,1349799453.275 [WetLabsBB2FL](INFO): Powering down
2012-10-09T16:17:33.509Z,1349799453.509 [science_to_67_70:SURFACECOMMS:B:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2012-10-09T16:17:34.059Z,1349799454.059 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-10-09T16:17:34.059Z,1349799454.059 [DVL_micro](INFO): Querying output modes
2012-10-09T16:17:34.059Z,1349799454.059 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-10-09T16:17:34.063Z,1349799454.063 [DVL_micro](DEBUG): cmdResponse: 01 03 16
2012-10-09T16:17:34.063Z,1349799454.063 [DVL_micro](INFO): NQ1 output enabled
2012-10-09T16:17:34.063Z,1349799454.063 [DVL_micro](INFO): RSSI output enabled
2012-10-09T16:17:34.063Z,1349799454.063 [DVL_micro](INFO): ADCP output enabled
2012-10-09T16:17:34.064Z,1349799454.063 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-10-09T16:17:34.071Z,1349799454.071 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2012-10-09T16:17:34.072Z,1349799454.072 [DVL_micro](INFO): RSSI unrequested
2012-10-09T16:17:34.072Z,1349799454.072 [DVL_micro](INFO): ADCP unrequested
2012-10-09T16:17:34.072Z,1349799454.072 [DVL_micro](INFO): Cycling power to configure device.
2012-10-09T16:17:34.310Z,1349799454.310 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2012-10-09T16:17:36.079Z,1349799456.079 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-10-09T16:17:36.079Z,1349799456.079 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-10-09T16:17:36.087Z,1349799456.087 [DVL_micro](DEBUG): cmdResponse: �R�������
2012-10-09T16:17:36.087Z,1349799456.087 [DVL_micro](INFO): Enabling NQ1 output
2012-10-09T16:17:36.087Z,1349799456.087 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-10-09T16:17:36.102Z,1349799456.102 [DVL_micro](DEBUG): cmdResponse: ����������������1������������~���+�/������������j�c�K�H�E��������������������
2012-10-09T16:17:36.102Z,1349799456.102 [DVL_micro](INFO): Querying output modes
2012-10-09T16:17:36.119Z,1349799456.119 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-10-09T16:17:36.131Z,1349799456.131 [DVL_micro](DEBUG): cmdResponse: �H�?�����v�a�B�K�J�f���x�W�D�u����c�s�[�S�u���r�_�h��d�U�I�8�N�a�m�������n�_�p�R�O�
2012-10-09T16:17:36.131Z,1349799456.131 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-10-09T16:17:36.143Z,1349799456.143 [DVL_micro](DEBUG): cmdResponse: �r�a�i�t�j���z��|�z�g�K�I�s�o�������h�a������z�]�N�S�F��������k���x����
2012-10-09T16:17:36.691Z,1349799456.691 [DVL_micro](INFO): NQ1 requested
2012-10-09T16:17:36.691Z,1349799456.691 [DVL_micro](INFO): Cycling power to configure device.
2012-10-09T16:17:38.147Z,1349799458.147 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2012-10-09T16:17:38.262Z,1349799458.262 [science_to_67_70:SURFACECOMMS:B:B] Stopped
2012-10-09T16:17:38.262Z,1349799458.262 [science_to_67_70:SURFACECOMMS:B:C] Running Loop=1
2012-10-09T16:17:38.657Z,1349799458.657 [science_to_67_70:SURFACECOMMS:B:C](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-10-09T16:17:38.659Z,1349799458.659 [science_to_67_70:SURFACECOMMS:B:C] Stopped
2012-10-09T16:17:38.659Z,1349799458.659 [science_to_67_70:SURFACECOMMS:B](INFO): Completed science_to_67_70:SURFACECOMMS:B
2012-10-09T16:17:38.659Z,1349799458.659 [science_to_67_70:SURFACECOMMS:B] Stopped
2012-10-09T16:17:38.659Z,1349799458.659 [science_to_67_70:SURFACECOMMS:B](INFO): Aggregate::uninitialize science_to_67_70:SURFACECOMMS:B
2012-10-09T16:17:38.660Z,1349799458.660 [science_to_67_70:SURFACECOMMS](INFO): Completed science_to_67_70:SURFACECOMMS
2012-10-09T16:17:38.660Z,1349799458.660 [science_to_67_70:SURFACECOMMS] Stopped
2012-10-09T16:17:38.660Z,1349799458.660 [science_to_67_70:SURFACECOMMS](INFO): Aggregate::uninitialize science_to_67_70:SURFACECOMMS
2012-10-09T16:17:38.660Z,1349799458.661 [science_to_67_70:SURFACECOMMS:A.GoToSurface] Stopped
2012-10-09T16:17:38.660Z,1349799458.661 [science_to_67_70:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-09T16:17:38.661Z,1349799458.661 [science_to_67_70:Wpt.Point] Running Loop=1
2012-10-09T16:17:38.661Z,1349799458.661 [science_to_67_70:Wpt.Point](DEBUG): Initialize.
2012-10-09T16:17:38.699Z,1349799458.699 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-10-09T16:17:38.699Z,1349799458.699 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-10-09T16:17:38.883Z,1349799458.883 [DVL_micro](DEBUG): cmdResponse: 00
2012-10-09T16:17:38.883Z,1349799458.883 [DVL_micro](INFO): Enabling NQ1 output
2012-10-09T16:17:38.883Z,1349799458.883 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-10-09T16:17:39.062Z,1349799459.062 [science_to_67_70:J.YoYo] Running Loop=1
2012-10-09T16:17:39.063Z,1349799459.063 [science_to_67_70:I.DepthEnvelope] Running Loop=1
2012-10-09T16:17:39.064Z,1349799459.064 [science_to_67_70:H.SetSpeed] Running Loop=1
2012-10-09T16:17:39.064Z,1349799459.064 [science_to_67_70:G.Buoyancy] Running Loop=1
2012-10-09T16:17:39.064Z,1349799459.065 [science_to_67_70:Science] Running Loop=1
2012-10-09T16:17:39.065Z,1349799459.065 [science_to_67_70:Science:G] Running Loop=1
2012-10-09T16:17:39.065Z,1349799459.066 [science_to_67_70:Science:F] Running Loop=1
2012-10-09T16:17:39.066Z,1349799459.066 [science_to_67_70:Science:E] Running Loop=1
2012-10-09T16:17:39.066Z,1349799459.066 [science_to_67_70:Science:D] Running Loop=1
2012-10-09T16:17:39.066Z,1349799459.066 [science_to_67_70:Science:C] Running Loop=1
2012-10-09T16:17:39.067Z,1349799459.067 [DVL_micro](DEBUG): cmdResponse: 01
2012-10-09T16:17:39.067Z,1349799459.067 [DVL_micro](INFO): Querying output modes
2012-10-09T16:17:39.067Z,1349799459.067 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-10-09T16:17:39.071Z,1349799459.071 [DVL_micro](DEBUG): cmdResponse: 01
2012-10-09T16:17:39.071Z,1349799459.071 [DVL_micro](INFO): NQ1 output enabled
2012-10-09T16:17:39.071Z,1349799459.071 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-10-09T16:17:39.075Z,1349799459.075 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2012-10-09T16:17:39.139Z,1349799459.139 [DVL_micro](INFO): RSSI requested
2012-10-09T16:17:39.139Z,1349799459.139 [DVL_micro](INFO): ADCP requested
2012-10-09T16:17:39.139Z,1349799459.139 [DVL_micro](INFO): Cycling power to configure device.
2012-10-09T16:17:39.239Z,1349799459.239 [Turbulence_NPS](INFO): Resume powering up
2012-10-09T16:17:39.239Z,1349799459.239 [Turbulence_NPS](INFO): Opening uart, block timeout 10ths=1
2012-10-09T16:17:39.316Z,1349799459.316 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2012-10-09T16:17:39.336Z,1349799459.336 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=4
2012-10-09T16:17:39.336Z,1349799459.336 [WetLabsBB2FL](INFO): Powering up
2012-10-09T16:17:40.760Z,1349799460.760 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.098030 psu
2012-10-09T16:17:40.959Z,1349799460.959 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.115070 psu
2012-10-09T16:17:41.148Z,1349799461.148 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-10-09T16:17:41.148Z,1349799461.148 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-10-09T16:17:41.160Z,1349799461.160 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.101180 psu
2012-10-09T16:17:41.359Z,1349799461.359 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.081310 psu
2012-10-09T16:17:41.560Z,1349799461.560 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.082870 psu
2012-10-09T16:17:41.743Z,1349799461.743 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.718580 psu
2012-10-09T16:17:41.783Z,1349799461.783 [DVL_micro](DEBUG): cmdResponse: 00
2012-10-09T16:17:41.783Z,1349799461.783 [DVL_micro](INFO): Enabling RSSI output
2012-10-09T16:17:41.783Z,1349799461.783 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 16 7

2012-10-09T16:17:41.943Z,1349799461.943 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.109640 psu
2012-10-09T16:17:42.039Z,1349799462.039 [DVL_micro](DEBUG): cmdResponse: 16
2012-10-09T16:17:42.039Z,1349799462.039 [DVL_micro](INFO): Enabling ADCP output
2012-10-09T16:17:42.039Z,1349799462.039 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 03 3

2012-10-09T16:17:42.143Z,1349799462.143 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.096120 psu
2012-10-09T16:17:42.343Z,1349799462.343 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.073480 psu
2012-10-09T16:17:42.379Z,1349799462.379 [DVL_micro](DEBUG): cmdResponse: 03 16
2012-10-09T16:17:42.380Z,1349799462.380 [DVL_micro](INFO): Enabling NQ1 output
2012-10-09T16:17:42.380Z,1349799462.380 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-10-09T16:17:42.548Z,1349799462.548 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.088320 psu
2012-10-09T16:17:42.627Z,1349799462.627 [DVL_micro](DEBUG): cmdResponse: 01 03 16
2012-10-09T16:17:42.627Z,1349799462.627 [DVL_micro](INFO): Querying output modes
2012-10-09T16:17:42.627Z,1349799462.627 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-10-09T16:17:42.743Z,1349799462.743 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.098530 psu
2012-10-09T16:17:42.803Z,1349799462.803 [DVL_micro](DEBUG): cmdResponse: 01 03 16
2012-10-09T16:17:42.803Z,1349799462.803 [DVL_micro](INFO): NQ1 output enabled
2012-10-09T16:17:42.803Z,1349799462.803 [DVL_micro](INFO): RSSI output enabled
2012-10-09T16:17:42.803Z,1349799462.803 [DVL_micro](INFO): ADCP output enabled
2012-10-09T16:17:42.803Z,1349799462.803 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-10-09T16:17:42.943Z,1349799462.943 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.096900 psu
2012-10-09T16:17:43.007Z,1349799463.007 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2012-10-09T16:17:43.140Z,1349799463.140 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.078320 psu
2012-10-09T16:17:43.144Z,1349799463.144 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=8
2012-10-09T16:17:43.199Z,1349799463.199 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=28
2012-10-09T16:17:43.251Z,1349799463.251 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=16
2012-10-09T16:17:43.304Z,1349799463.304 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-10-09T16:17:43.341Z,1349799463.341 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.106850 psu
2012-10-09T16:17:43.540Z,1349799463.540 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.094430 psu
2012-10-09T16:17:43.588Z,1349799463.588 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=56
2012-10-09T16:17:43.691Z,1349799463.691 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=60
2012-10-09T16:17:43.740Z,1349799463.740 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.070390 psu
2012-10-09T16:17:43.795Z,1349799463.795 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=64
2012-10-09T16:17:43.895Z,1349799463.895 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-10-09T16:17:43.940Z,1349799463.940 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.135610 psu
2012-10-09T16:17:44.031Z,1349799464.031 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=40
2012-10-09T16:17:44.087Z,1349799464.087 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=60
2012-10-09T16:17:44.140Z,1349799464.140 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.073150 psu
2012-10-09T16:17:44.141Z,1349799464.141 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=16
2012-10-09T16:17:44.191Z,1349799464.191 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-10-09T16:17:44.340Z,1349799464.339 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.079010 psu
2012-10-09T16:17:44.476Z,1349799464.476 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=56
2012-10-09T16:17:44.540Z,1349799464.540 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.083560 psu
2012-10-09T16:17:44.576Z,1349799464.576 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28
2012-10-09T16:17:44.679Z,1349799464.679 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=32
2012-10-09T16:17:44.739Z,1349799464.740 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.116170 psu
2012-10-09T16:17:44.781Z,1349799464.781 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-10-09T16:17:44.941Z,1349799464.941 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.099610 psu
2012-10-09T16:17:45.132Z,1349799465.132 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=24
2012-10-09T16:17:45.140Z,1349799465.140 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.078710 psu
2012-10-09T16:17:45.275Z,1349799465.275 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=28
2012-10-09T16:17:45.348Z,1349799465.348 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.102290 psu
2012-10-09T16:17:45.419Z,1349799465.419 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=0
2012-10-09T16:17:45.540Z,1349799465.540 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.080250 psu
2012-10-09T16:17:45.564Z,1349799465.564 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4
2012-10-09T16:17:45.740Z,1349799465.740 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.076280 psu
2012-10-09T16:17:45.757Z,1349799465.757 [NAL9602](INFO): Powering down
2012-10-09T16:17:45.887Z,1349799465.888 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=28
2012-10-09T16:17:45.940Z,1349799465.940 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.135850 psu
2012-10-09T16:17:46.003Z,1349799466.003 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=36
2012-10-09T16:17:46.120Z,1349799466.120 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=12
2012-10-09T16:17:46.139Z,1349799466.139 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.132220 psu
2012-10-09T16:17:46.236Z,1349799466.236 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4
2012-10-09T16:17:46.341Z,1349799466.341 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.083310 psu
2012-10-09T16:17:46.540Z,1349799466.540 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.097500 psu
2012-10-09T16:17:46.744Z,1349799466.744 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.076720 psu
2012-10-09T16:17:46.763Z,1349799466.763 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=60
2012-10-09T16:17:46.940Z,1349799466.940 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.106600 psu
2012-10-09T16:17:46.995Z,1349799466.995 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4
2012-10-09T16:17:47.140Z,1349799467.140 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.066810 psu
2012-10-09T16:17:47.235Z,1349799467.235 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=44
2012-10-09T16:17:47.340Z,1349799467.340 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.054640 psu
2012-10-09T16:17:47.471Z,1349799467.471 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4
2012-10-09T16:17:47.540Z,1349799467.540 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.131360 psu
2012-10-09T16:17:47.737Z,1349799467.737 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.086900 psu
2012-10-09T16:17:47.937Z,1349799467.937 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.092550 psu
2012-10-09T16:17:48.139Z,1349799468.139 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.062570 psu
2012-10-09T16:17:48.339Z,1349799468.339 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.079430 psu
2012-10-09T16:17:48.391Z,1349799468.391 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=28
2012-10-09T16:17:48.539Z,1349799468.539 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.089390 psu
2012-10-09T16:17:48.739Z,1349799468.739 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.100070 psu
2012-10-09T16:17:48.863Z,1349799468.863 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4
2012-10-09T16:17:48.939Z,1349799468.939 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.075250 psu
2012-10-09T16:17:49.139Z,1349799469.139 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.093680 psu
2012-10-09T16:17:49.339Z,1349799469.339 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.095740 psu
2012-10-09T16:17:49.341Z,1349799469.341 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=13
2012-10-09T16:17:49.539Z,1349799469.539 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.089950 psu
2012-10-09T16:17:49.739Z,1349799469.739 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.109020 psu
2012-10-09T16:17:49.811Z,1349799469.811 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4
2012-10-09T16:17:49.935Z,1349799469.935 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.073080 psu
2012-10-09T16:17:50.136Z,1349799470.136 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.076880 psu
2012-10-09T16:17:50.339Z,1349799470.339 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.074250 psu
2012-10-09T16:17:50.536Z,1349799470.536 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.077970 psu
2012-10-09T16:17:50.706Z,1349799470.705 [DVL_micro](DEBUG): NQ1
2012-10-09T16:17:50.736Z,1349799470.736 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.099110 psu
2012-10-09T16:17:50.773Z,1349799470.773 [DVL_micro](DEBUG): ADCP
2012-10-09T16:17:50.936Z,1349799470.936 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.080930 psu
2012-10-09T16:17:51.136Z,1349799471.136 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.109720 psu
2012-10-09T16:17:51.336Z,1349799471.336 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.075490 psu
2012-10-09T16:17:51.536Z,1349799471.536 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.079940 psu
2012-10-09T16:17:51.553Z,1349799471.553 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=13
2012-10-09T16:17:51.736Z,1349799471.736 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.074240 psu
2012-10-09T16:17:51.935Z,1349799471.935 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.078810 psu
2012-10-09T16:17:52.135Z,1349799472.135 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.096770 psu
2012-10-09T16:17:52.335Z,1349799472.335 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.080690 psu
2012-10-09T16:17:52.391Z,1349799472.391 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=53
2012-10-09T16:17:52.535Z,1349799472.535 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.073040 psu
2012-10-09T16:17:52.736Z,1349799472.736 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.081810 psu
2012-10-09T16:17:52.936Z,1349799472.936 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.091910 psu
2012-10-09T16:17:53.136Z,1349799473.136 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.114830 psu
2012-10-09T16:17:53.229Z,1349799473.229 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=29
2012-10-09T16:17:53.336Z,1349799473.336 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.097470 psu
2012-10-09T16:17:53.536Z,1349799473.536 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.107930 psu
2012-10-09T16:17:53.736Z,1349799473.736 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.067930 psu
2012-10-09T16:17:53.935Z,1349799473.935 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.074260 psu
2012-10-09T16:17:54.064Z,1349799474.064 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-10-09T16:17:54.135Z,1349799474.135 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.109290 psu
2012-10-09T16:17:54.259Z,1349799474.259 [Turbulence_NPS](ERROR): No communication! Re-initializing
2012-10-09T16:17:54.259Z,1349799474.259 [Turbulence_NPS] Communications Fault, FailCount= 1
2012-10-09T16:17:54.259Z,1349799474.259 [Turbulence_NPS](ERROR): Communications Fault
2012-10-09T16:17:54.260Z,1349799474.260 [Turbulence_NPS](INFO): Uninitialize Powering down
2012-10-09T16:17:54.336Z,1349799474.336 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.078540 psu
2012-10-09T16:17:54.360Z,1349799474.360 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=1864
2012-10-09T16:17:54.361Z,1349799474.361 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=1244
2012-10-09T16:17:54.361Z,1349799474.361 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=624
2012-10-09T16:17:54.362Z,1349799474.362 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-10-09T16:17:54.536Z,1349799474.536 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.096320 psu
2012-10-09T16:17:54.605Z,1349799474.605 [CBIT](ERROR): Communications Fault in component: Turbulence_NPS
2012-10-09T16:17:54.606Z,1349799474.606 [CBIT](INFO): Clearing failed state for component Turbulence_NPS
2012-10-09T16:17:54.606Z,1349799474.606 [Turbulence_NPS] No Fault, FailCount= 1
2012-10-09T16:17:54.643Z,1349799474.643 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=56
2012-10-09T16:17:54.663Z,1349799474.663 [Turbulence_NPS](DEBUG): Initializing Turbulence_NPS.
2012-10-09T16:17:54.664Z,1349799474.664 [Turbulence_NPS](INFO): Opening uart, block timeout 10ths=1
2012-10-09T16:17:54.733Z,1349799474.733 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.083630 psu
2012-10-09T16:17:54.743Z,1349799474.743 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28
2012-10-09T16:17:54.847Z,1349799474.847 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=32
2012-10-09T16:17:54.941Z,1349799474.941 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.110370 psu
2012-10-09T16:17:54.947Z,1349799474.947 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-10-09T16:17:55.087Z,1349799475.087 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=40
2012-10-09T16:17:55.136Z,1349799475.136 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.098850 psu
2012-10-09T16:17:55.139Z,1349799475.139 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=28
2012-10-09T16:17:55.195Z,1349799475.195 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=48
2012-10-09T16:17:55.247Z,1349799475.247 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-10-09T16:17:55.335Z,1349799475.335 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.101940 psu
2012-10-09T16:17:55.527Z,1349799475.527 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24
2012-10-09T16:17:55.533Z,1349799475.533 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.106630 psu
2012-10-09T16:17:55.631Z,1349799475.631 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28
2012-10-09T16:17:55.733Z,1349799475.733 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.079070 psu
2012-10-09T16:17:55.735Z,1349799475.735 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=32
2012-10-09T16:17:55.836Z,1349799475.836 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-10-09T16:17:55.935Z,1349799475.935 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.097930 psu
2012-10-09T16:17:56.132Z,1349799476.132 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.073480 psu
2012-10-09T16:17:56.190Z,1349799476.190 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=56
2012-10-09T16:17:56.331Z,1349799476.331 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=28
2012-10-09T16:17:56.332Z,1349799476.332 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.108560 psu
2012-10-09T16:17:56.475Z,1349799476.475 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=0
2012-10-09T16:17:56.532Z,1349799476.532 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.087520 psu
2012-10-09T16:17:56.620Z,1349799476.620 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4
2012-10-09T16:17:56.732Z,1349799476.732 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.120810 psu
2012-10-09T16:17:56.932Z,1349799476.932 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.062830 psu
2012-10-09T16:17:56.943Z,1349799476.943 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=28
2012-10-09T16:17:57.059Z,1349799477.059 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=36
2012-10-09T16:17:57.132Z,1349799477.132 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.077370 psu
2012-10-09T16:17:57.176Z,1349799477.176 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=12
2012-10-09T16:17:57.291Z,1349799477.291 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4
2012-10-09T16:17:57.331Z,1349799477.331 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.071290 psu
2012-10-09T16:17:57.535Z,1349799477.535 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.080920 psu
2012-10-09T16:17:57.732Z,1349799477.731 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.105380 psu
2012-10-09T16:17:57.819Z,1349799477.819 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=60
2012-10-09T16:17:57.932Z,1349799477.931 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.083250 psu
2012-10-09T16:17:58.051Z,1349799478.051 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4
2012-10-09T16:17:58.132Z,1349799478.132 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.067020 psu
2012-10-09T16:17:58.291Z,1349799478.291 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=44
2012-10-09T16:17:58.331Z,1349799478.331 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.090140 psu
2012-10-09T16:17:58.527Z,1349799478.527 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4
2012-10-09T16:17:58.531Z,1349799478.531 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.083320 psu
2012-10-09T16:17:58.731Z,1349799478.731 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.103690 psu
2012-10-09T16:17:58.931Z,1349799478.931 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.095950 psu
2012-10-09T16:17:59.131Z,1349799479.131 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.084940 psu
2012-10-09T16:17:59.331Z,1349799479.331 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.090510 psu
2012-10-09T16:17:59.451Z,1349799479.451 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=60
2012-10-09T16:17:59.531Z,1349799479.531 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.096630 psu
2012-10-09T16:17:59.733Z,1349799479.733 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.081080 psu
2012-10-09T16:17:59.924Z,1349799479.924 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=36
2012-10-09T16:17:59.931Z,1349799479.931 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.106700 psu
2012-10-09T16:18:00.131Z,1349799480.131 [CTD_NeilBrown](ERROR): Bad response:  -0.465
2012-10-09T16:18:00.332Z,1349799480.332 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.114580 psu
2012-10-09T16:18:00.399Z,1349799480.399 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=46
2012-10-09T16:18:00.534Z,1349799480.534 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.065840 psu
2012-10-09T16:18:00.732Z,1349799480.732 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.127790 psu
2012-10-09T16:18:00.871Z,1349799480.871 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4
2012-10-09T16:18:00.932Z,1349799480.932 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.105770 psu
2012-10-09T16:18:01.132Z,1349799481.132 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.082490 psu
2012-10-09T16:18:01.332Z,1349799481.332 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.085850 psu
2012-10-09T16:18:01.529Z,1349799481.529 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.070840 psu
2012-10-09T16:18:01.732Z,1349799481.732 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.084720 psu
2012-10-09T16:18:01.755Z,1349799481.755 [DVL_micro](DEBUG): NQ1
2012-10-09T16:18:01.791Z,1349799481.791 [DVL_micro](DEBUG): ADCP
2012-10-09T16:18:01.932Z,1349799481.932 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.073710 psu
2012-10-09T16:18:02.132Z,1349799482.132 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.117440 psu
2012-10-09T16:18:02.327Z,1349799482.327 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.065060 psu
2012-10-09T16:18:02.528Z,1349799482.528 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.072640 psu
2012-10-09T16:18:02.612Z,1349799482.612 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=13
2012-10-09T16:18:02.728Z,1349799482.728 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.094440 psu
2012-10-09T16:18:02.939Z,1349799482.939 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.165580 psu
2012-10-09T16:18:03.128Z,1349799483.128 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.065690 psu
2012-10-09T16:18:03.328Z,1349799483.328 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.082590 psu
2012-10-09T16:18:03.451Z,1349799483.451 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=22
2012-10-09T16:18:03.529Z,1349799483.529 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.114850 psu
2012-10-09T16:18:03.731Z,1349799483.731 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.086490 psu
2012-10-09T16:18:03.928Z,1349799483.928 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.098990 psu
2012-10-09T16:18:04.127Z,1349799484.128 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.053620 psu
2012-10-09T16:18:04.289Z,1349799484.289 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=30
2012-10-09T16:18:04.327Z,1349799484.328 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.061140 psu
2012-10-09T16:18:04.527Z,1349799484.527 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.072520 psu
2012-10-09T16:18:04.727Z,1349799484.727 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.083260 psu
2012-10-09T16:18:04.928Z,1349799484.928 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.069310 psu
2012-10-09T16:18:05.123Z,1349799485.124 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-10-09T16:18:05.128Z,1349799485.128 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.080500 psu
2012-10-09T16:18:05.327Z,1349799485.327 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.070470 psu
2012-10-09T16:18:05.428Z,1349799485.428 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=1864
2012-10-09T16:18:05.429Z,1349799485.429 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=1244
2012-10-09T16:18:05.429Z,1349799485.429 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=624
2012-10-09T16:18:05.430Z,1349799485.430 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-10-09T16:18:05.527Z,1349799485.527 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.082830 psu
2012-10-09T16:18:05.703Z,1349799485.703 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24
2012-10-09T16:18:05.728Z,1349799485.727 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.082230 psu
2012-10-09T16:18:05.807Z,1349799485.807 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28
2012-10-09T16:18:05.907Z,1349799485.907 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=0
2012-10-09T16:18:05.928Z,1349799485.928 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.112550 psu
2012-10-09T16:18:06.011Z,1349799486.011 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-10-09T16:18:06.127Z,1349799486.127 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.087950 psu
2012-10-09T16:18:06.147Z,1349799486.147 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=8
2012-10-09T16:18:06.203Z,1349799486.203 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=28
2012-10-09T16:18:06.257Z,1349799486.257 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=16
2012-10-09T16:18:06.311Z,1349799486.311 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-10-09T16:18:06.327Z,1349799486.327 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.064100 psu
2012-10-09T16:18:06.528Z,1349799486.528 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.099520 psu
2012-10-09T16:18:06.592Z,1349799486.592 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24
2012-10-09T16:18:06.695Z,1349799486.695 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28
2012-10-09T16:18:06.727Z,1349799486.727 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.079610 psu
2012-10-09T16:18:06.795Z,1349799486.795 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=0
2012-10-09T16:18:06.899Z,1349799486.899 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-10-09T16:18:06.927Z,1349799486.927 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.086010 psu
2012-10-09T16:18:07.127Z,1349799487.127 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.097880 psu
2012-10-09T16:18:07.251Z,1349799487.251 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=57
2012-10-09T16:18:07.327Z,1349799487.327 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.077660 psu
2012-10-09T16:18:07.395Z,1349799487.395 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=29
2012-10-09T16:18:07.527Z,1349799487.527 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.117150 psu
2012-10-09T16:18:07.539Z,1349799487.539 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=33
2012-10-09T16:18:07.683Z,1349799487.683 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4
2012-10-09T16:18:07.727Z,1349799487.727 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.093360 psu
2012-10-09T16:18:07.927Z,1349799487.927 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.085350 psu
2012-10-09T16:18:08.128Z,1349799488.128 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.114440 psu
2012-10-09T16:18:08.203Z,1349799488.203 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=2236
2012-10-09T16:18:08.204Z,1349799488.204 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=964
2012-10-09T16:18:08.240Z,1349799488.240 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=44
2012-10-09T16:18:08.328Z,1349799488.328 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.093610 psu
2012-10-09T16:18:08.356Z,1349799488.356 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4
2012-10-09T16:18:08.528Z,1349799488.528 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.079630 psu
2012-10-09T16:18:08.725Z,1349799488.725 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.097060 psu
2012-10-09T16:18:08.879Z,1349799488.879 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=28
2012-10-09T16:18:08.924Z,1349799488.924 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.072920 psu
2012-10-09T16:18:09.116Z,1349799489.116 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4
2012-10-09T16:18:09.125Z,1349799489.125 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.109680 psu
2012-10-09T16:18:09.324Z,1349799489.324 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.082410 psu
2012-10-09T16:18:09.352Z,1349799489.352 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=12
2012-10-09T16:18:09.524Z,1349799489.524 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.087060 psu
2012-10-09T16:18:09.587Z,1349799489.587 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4
2012-10-09T16:18:09.723Z,1349799489.723 [Turbulence_NPS](ERROR): No communication! Re-initializing
2012-10-09T16:18:09.723Z,1349799489.723 [Turbulence_NPS] Communications Fault, FailCount= 2
2012-10-09T16:18:09.723Z,1349799489.723 [Turbulence_NPS](ERROR): Communications Fault
2012-10-09T16:18:09.726Z,1349799489.726 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.086160 psu
2012-10-09T16:18:09.728Z,1349799489.728 [Turbulence_NPS](INFO): Uninitialize Powering down
2012-10-09T16:18:09.924Z,1349799489.924 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.102780 psu
2012-10-09T16:18:10.124Z,1349799490.124 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.108710 psu
2012-10-09T16:18:10.324Z,1349799490.324 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.086180 psu
2012-10-09T16:18:10.426Z,1349799490.426 [CBIT](ERROR): Communications Fault in component: Turbulence_NPS
2012-10-09T16:18:10.428Z,1349799490.428 [CBIT](INFO): Clearing failed state for component Turbulence_NPS
2012-10-09T16:18:10.428Z,1349799490.428 [Turbulence_NPS] No Fault, FailCount= 2
2012-10-09T16:18:10.450Z,1349799490.450 [Turbulence_NPS](DEBUG): Initializing Turbulence_NPS.
2012-10-09T16:18:10.451Z,1349799490.451 [Turbulence_NPS](INFO): Opening uart, block timeout 10ths=1
2012-10-09T16:18:10.512Z,1349799490.512 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=28
2012-10-09T16:18:10.524Z,1349799490.524 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.082150 psu
2012-10-09T16:18:10.729Z,1349799490.729 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.083280 psu
2012-10-09T16:18:10.924Z,1349799490.924 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.101930 psu
2012-10-09T16:18:10.983Z,1349799490.983 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4
2012-10-09T16:18:11.127Z,1349799491.127 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.095330 psu
2012-10-09T16:18:11.323Z,1349799491.323 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.110570 psu
2012-10-09T16:18:11.467Z,1349799491.467 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=109
2012-10-09T16:18:11.522Z,1349799491.522 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.119040 psu
2012-10-09T16:18:11.723Z,1349799491.723 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.099630 psu
2012-10-09T16:18:11.923Z,1349799491.923 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.099620 psu
2012-10-09T16:18:11.931Z,1349799491.931 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4
2012-10-09T16:18:12.123Z,1349799492.123 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.082120 psu
2012-10-09T16:18:12.323Z,1349799492.323 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.087520 psu
2012-10-09T16:18:12.527Z,1349799492.527 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.087670 psu
2012-10-09T16:18:12.724Z,1349799492.724 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.101450 psu
2012-10-09T16:18:12.815Z,1349799492.815 [DVL_micro](DEBUG): NQ1
2012-10-09T16:18:12.842Z,1349799492.842 [DVL_micro](DEBUG): ADCP
2012-10-09T16:18:12.923Z,1349799492.923 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.118020 psu
2012-10-09T16:18:13.123Z,1349799493.123 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.079350 psu
2012-10-09T16:18:13.323Z,1349799493.323 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.105620 psu
2012-10-09T16:18:13.523Z,1349799493.523 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.109110 psu
2012-10-09T16:18:13.679Z,1349799493.679 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=42
2012-10-09T16:18:13.723Z,1349799493.723 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.100170 psu
2012-10-09T16:18:13.923Z,1349799493.923 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.079770 psu
2012-10-09T16:18:14.123Z,1349799494.123 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.045870 psu
2012-10-09T16:18:14.323Z,1349799494.323 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.084090 psu
2012-10-09T16:18:14.515Z,1349799494.515 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=18
2012-10-09T16:18:14.519Z,1349799494.519 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.108970 psu
2012-10-09T16:18:14.720Z,1349799494.720 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.099080 psu
2012-10-09T16:18:14.920Z,1349799494.920 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.080870 psu
2012-10-09T16:18:15.120Z,1349799495.120 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.083020 psu
2012-10-09T16:18:15.319Z,1349799495.319 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.089360 psu
2012-10-09T16:18:15.355Z,1349799495.355 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=58
2012-10-09T16:18:15.520Z,1349799495.520 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.077830 psu
2012-10-09T16:18:15.720Z,1349799495.720 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.096320 psu
2012-10-09T16:18:15.919Z,1349799495.919 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.071940 psu
2012-10-09T16:18:16.120Z,1349799496.120 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.071170 psu
2012-10-09T16:18:16.189Z,1349799496.189 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-10-09T16:18:16.321Z,1349799496.321 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.099230 psu
2012-10-09T16:18:16.457Z,1349799496.457 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=1512
2012-10-09T16:18:16.457Z,1349799496.457 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=892
2012-10-09T16:18:16.458Z,1349799496.458 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=272
2012-10-09T16:18:16.488Z,1349799496.488 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-10-09T16:18:16.520Z,1349799496.520 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.094380 psu
2012-10-09T16:18:16.720Z,1349799496.720 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.097510 psu
2012-10-09T16:18:16.770Z,1349799496.770 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24
2012-10-09T16:18:16.871Z,1349799496.871 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28
2012-10-09T16:18:16.920Z,1349799496.920 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.115210 psu
2012-10-09T16:18:16.971Z,1349799496.971 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=2
2012-10-09T16:18:17.075Z,1349799497.075 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-10-09T16:18:17.119Z,1349799497.119 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.097320 psu
2012-10-09T16:18:17.213Z,1349799497.213 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=8
2012-10-09T16:18:17.267Z,1349799497.267 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=28
2012-10-09T16:18:17.320Z,1349799497.320 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.084690 psu
2012-10-09T16:18:17.325Z,1349799497.325 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=48
2012-10-09T16:18:17.379Z,1349799497.379 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-10-09T16:18:17.524Z,1349799497.524 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.100710 psu
2012-10-09T16:18:17.655Z,1349799497.655 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=25
2012-10-09T16:18:17.721Z,1349799497.721 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.069190 psu
2012-10-09T16:18:17.759Z,1349799497.759 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=29
2012-10-09T16:18:17.863Z,1349799497.863 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=33
2012-10-09T16:18:17.921Z,1349799497.921 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.080340 psu
2012-10-09T16:18:17.964Z,1349799497.964 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-10-09T16:18:18.120Z,1349799498.120 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.089100 psu
2012-10-09T16:18:18.316Z,1349799498.316 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=56
2012-10-09T16:18:18.320Z,1349799498.320 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.102660 psu
2012-10-09T16:18:18.459Z,1349799498.459 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=28
2012-10-09T16:18:18.520Z,1349799498.520 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.099560 psu
2012-10-09T16:18:18.604Z,1349799498.604 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=33
2012-10-09T16:18:18.721Z,1349799498.721 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.097920 psu
2012-10-09T16:18:18.747Z,1349799498.747 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4
2012-10-09T16:18:18.919Z,1349799498.919 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.089000 psu
2012-10-09T16:18:19.072Z,1349799499.072 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=62
2012-10-09T16:18:19.119Z,1349799499.119 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.094010 psu
2012-10-09T16:18:19.184Z,1349799499.184 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=7
2012-10-09T16:18:19.303Z,1349799499.303 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=48
2012-10-09T16:18:19.319Z,1349799499.319 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.115200 psu
2012-10-09T16:18:19.419Z,1349799499.419 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4
2012-10-09T16:18:19.519Z,1349799499.519 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.094550 psu
2012-10-09T16:18:19.720Z,1349799499.720 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.107380 psu
2012-10-09T16:18:19.919Z,1349799499.920 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.069080 psu
2012-10-09T16:18:19.943Z,1349799499.943 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=30
2012-10-09T16:18:20.120Z,1349799500.120 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.075970 psu
2012-10-09T16:18:20.180Z,1349799500.180 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=6
2012-10-09T16:18:20.317Z,1349799500.317 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.096810 psu
2012-10-09T16:18:20.415Z,1349799500.415 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=15
2012-10-09T16:18:20.516Z,1349799500.516 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.093750 psu
2012-10-09T16:18:20.651Z,1349799500.651 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4
2012-10-09T16:18:20.717Z,1349799500.717 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.086440 psu
2012-10-09T16:18:20.919Z,1349799500.919 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.082840 psu
2012-10-09T16:18:21.116Z,1349799501.116 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.101440 psu
2012-10-09T16:18:21.315Z,1349799501.316 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.123420 psu
2012-10-09T16:18:21.516Z,1349799501.516 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.105600 psu
2012-10-09T16:18:21.576Z,1349799501.576 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=31
2012-10-09T16:18:21.716Z,1349799501.716 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.094680 psu
2012-10-09T16:18:21.916Z,1349799501.916 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.065270 psu
2012-10-09T16:18:22.048Z,1349799502.048 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=10
2012-10-09T16:18:22.116Z,1349799502.116 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.070670 psu
2012-10-09T16:18:22.316Z,1349799502.316 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.100880 psu
2012-10-09T16:18:22.516Z,1349799502.516 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.086350 psu
2012-10-09T16:18:22.523Z,1349799502.523 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=19
2012-10-09T16:18:22.716Z,1349799502.716 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.116320 psu
2012-10-09T16:18:22.916Z,1349799502.916 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.114130 psu
2012-10-09T16:18:22.995Z,1349799502.995 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4
2012-10-09T16:18:23.116Z,1349799503.116 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.087610 psu
2012-10-09T16:18:23.316Z,1349799503.316 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.100590 psu
2012-10-09T16:18:23.516Z,1349799503.516 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.066710 psu
2012-10-09T16:18:23.715Z,1349799503.715 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.105060 psu
2012-10-09T16:18:23.916Z,1349799503.916 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.088230 psu
2012-10-09T16:18:23.957Z,1349799503.957 [DVL_micro](DEBUG): NQ1
2012-10-09T16:18:24.105Z,1349799504.105 [DVL_micro](DEBUG): ADCP
2012-10-09T16:18:24.116Z,1349799504.116 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.085210 psu
2012-10-09T16:18:24.316Z,1349799504.316 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.062230 psu
2012-10-09T16:18:24.516Z,1349799504.516 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.105920 psu
2012-10-09T16:18:24.716Z,1349799504.716 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.095780 psu
2012-10-09T16:18:24.741Z,1349799504.741 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=13
2012-10-09T16:18:24.916Z,1349799504.916 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.102200 psu
2012-10-09T16:18:25.063Z,1349799505.063 [CommandLine](IMPORTANT): got command stop
2012-10-09T16:18:25.117Z,1349799505.117 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.103280 psu
2012-10-09T16:18:25.190Z,1349799505.190 [science_to_67_70] Stopped
2012-10-09T16:18:25.195Z,1349799505.195 [science_to_67_70](INFO): Aggregate::uninitialize science_to_67_70
2012-10-09T16:18:25.195Z,1349799505.195 [science_to_67_70:AltEnvWrapper] Stopped
2012-10-09T16:18:25.195Z,1349799505.195 [science_to_67_70:AltEnvWrapper](INFO): Aggregate::uninitialize science_to_67_70:AltEnvWrapper
2012-10-09T16:18:25.196Z,1349799505.195 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope] Stopped
2012-10-09T16:18:25.196Z,1349799505.195 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2012-10-09T16:18:25.196Z,1349799505.196 [science_to_67_70:AltEnvWrapper:B.Wait] Stopped
2012-10-09T16:18:25.196Z,1349799505.196 [science_to_67_70:AltEnvWrapper:B.Wait](DEBUG): Uninitialize Wait Component.
2012-10-09T16:18:25.196Z,1349799505.196 [science_to_67_70:B.DepthEnvelope] Stopped
2012-10-09T16:18:25.196Z,1349799505.196 [science_to_67_70:B.DepthEnvelope](DEBUG): Uninitialize.
2012-10-09T16:18:25.196Z,1349799505.196 [science_to_67_70:OffshoreWrapper] Stopped
2012-10-09T16:18:25.196Z,1349799505.196 [science_to_67_70:OffshoreWrapper](INFO): Aggregate::uninitialize science_to_67_70:OffshoreWrapper
2012-10-09T16:18:25.196Z,1349799505.196 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope] Stopped
2012-10-09T16:18:25.196Z,1349799505.196 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2012-10-09T16:18:25.196Z,1349799505.196 [science_to_67_70:OffshoreWrapper:B.Wait] Stopped
2012-10-09T16:18:25.196Z,1349799505.196 [science_to_67_70:OffshoreWrapper:B.Wait](DEBUG): Uninitialize Wait Component.
2012-10-09T16:18:25.196Z,1349799505.196 [science_to_67_70:Science] Stopped
2012-10-09T16:18:25.197Z,1349799505.197 [science_to_67_70:Science](INFO): Aggregate::uninitialize science_to_67_70:Science
2012-10-09T16:18:25.197Z,1349799505.197 [science_to_67_70:Science:A] Stopped
2012-10-09T16:18:25.197Z,1349799505.197 [science_to_67_70:Science:B] Stopped
2012-10-09T16:18:25.197Z,1349799505.197 [science_to_67_70:Science:C] Stopped
2012-10-09T16:18:25.197Z,1349799505.197 [science_to_67_70:Science:D] Stopped
2012-10-09T16:18:25.197Z,1349799505.197 [science_to_67_70:Science:E] Stopped
2012-10-09T16:18:25.197Z,1349799505.197 [science_to_67_70:Science:F] Stopped
2012-10-09T16:18:25.197Z,1349799505.197 [science_to_67_70:Science:G] Stopped
2012-10-09T16:18:25.197Z,1349799505.197 [science_to_67_70:Science:PeakDetectChl] Stopped
2012-10-09T16:18:25.197Z,1349799505.197 [science_to_67_70:Science:PeakDetectChl](INFO): Aggregate::uninitialize science_to_67_70:Science:PeakDetectChl
2012-10-09T16:18:25.197Z,1349799505.197 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2012-10-09T16:18:25.197Z,1349799505.197 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2012-10-09T16:18:25.197Z,1349799505.197 [science_to_67_70:G.Buoyancy] Stopped
2012-10-09T16:18:25.197Z,1349799505.197 [science_to_67_70:G.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2012-10-09T16:18:25.197Z,1349799505.197 [science_to_67_70:H.SetSpeed] Stopped
2012-10-09T16:18:25.198Z,1349799505.197 [science_to_67_70:H.SetSpeed](DEBUG): Uninitialize.
2012-10-09T16:18:25.198Z,1349799505.197 [science_to_67_70:I.DepthEnvelope] Stopped
2012-10-09T16:18:25.198Z,1349799505.198 [science_to_67_70:I.DepthEnvelope](DEBUG): Uninitialize.
2012-10-09T16:18:25.198Z,1349799505.198 [science_to_67_70:J.YoYo] Stopped
2012-10-09T16:18:25.198Z,1349799505.198 [science_to_67_70:J.YoYo](DEBUG): Uninitialize YoYoComponent.
2012-10-09T16:18:25.198Z,1349799505.198 [science_to_67_70:Wpt.Point] Stopped
2012-10-09T16:18:25.220Z,1349799505.220 [Turbulence_NPS](INFO): Pause powering down
2012-10-09T16:18:25.295Z,1349799505.295 [WetLabsBB2FL](INFO): Powering down
2012-10-09T16:18:25.583Z,1349799505.583 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=86
2012-10-09T16:18:26.161Z,1349799506.161 [MissionManager](IMPORTANT): Started mission Default
2012-10-09T16:18:26.161Z,1349799506.161 [Default] Running Loop=1
2012-10-09T16:18:26.161Z,1349799506.161 [Default](INFO): Aggregate::initialize Default
2012-10-09T16:18:26.161Z,1349799506.161 [Default:D.SetSpeed] Running Loop=1
2012-10-09T16:18:26.161Z,1349799506.161 [Default:D.SetSpeed](DEBUG): Initialize.
2012-10-09T16:18:26.161Z,1349799506.161 [Default:E.GoToSurface] Running Loop=1
2012-10-09T16:18:26.161Z,1349799506.161 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-09T16:18:26.162Z,1349799506.161 [Default:Iridium] Running Loop=1
2012-10-09T16:18:26.162Z,1349799506.162 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-10-09T16:18:26.162Z,1349799506.162 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-10-09T16:18:26.162Z,1349799506.162 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-10-09T16:18:26.162Z,1349799506.162 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-10-09T16:18:26.162Z,1349799506.162 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-09T16:18:26.174Z,1349799506.174 [Default:Iridium:B.GoToSurface] Stopped
2012-10-09T16:18:26.174Z,1349799506.174 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-09T16:18:26.174Z,1349799506.174 [Default:Iridium:Read_Iridium] Running Loop=1
2012-10-09T16:18:26.415Z,1349799506.415 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=31
2012-10-09T16:18:26.996Z,1349799506.996 [NAL9602](INFO): Powering up
2012-10-09T16:18:27.251Z,1349799507.251 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-10-09T16:18:27.563Z,1349799507.563 [DVL_micro](INFO): pause:Powering down
2012-10-09T16:18:33.251Z,1349799513.251 [CommandLine](IMPORTANT): got command restart application
2012-10-09T16:18:34.326Z,1349799514.326 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2012-10-09T16:18:34.327Z,1349799514.327 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2012-10-09T16:18:34.607Z,1349799514.607 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2012-10-09T16:18:34.995Z,1349799514.995 [WetLabsBB2FL](INFO): Powering down
2012-10-09T16:18:35.007Z,1349799515.007 [ComponentRegistry](INFO): Shutting down Turbulence_NPS ThreadHandler
2012-10-09T16:18:35.331Z,1349799515.331 [Turbulence_NPS](INFO): Uninitialize Powering down
2012-10-09T16:18:35.339Z,1349799515.339 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2012-10-09T16:18:35.471Z,1349799515.471 [CTD_NeilBrown](INFO): Powering down
2012-10-09T16:18:35.475Z,1349799515.475 [ComponentRegistry](INFO): Shutting down DVL_micro ThreadHandler
2012-10-09T16:18:35.643Z,1349799515.643 [DVL_micro](INFO): uninitialize:Powering down
2012-10-09T16:18:35.648Z,1349799515.648 [ComponentRegistry](INFO): Shutting down AsyncPiEstimator ThreadHandler
2012-10-09T16:18:35.767Z,1349799515.767 [AsyncPiEstimator](DEBUG): Uninitialize AsyncPiEstimator.
2012-10-09T16:18:35.769Z,1349799515.769 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2012-10-09T16:18:35.773Z,1349799515.773 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2012-10-09T16:18:35.852Z,1349799515.852 [controlThread](DEBUG): Uninitializing ControlThread
2012-10-09T16:18:35.852Z,1349799515.852 [AHRS_sp3003D](INFO): Powering down
2012-10-09T16:18:35.939Z,1349799515.939 [NAL9602](INFO): Powering down
2012-10-09T16:18:35.942Z,1349799515.942 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2012-10-09T16:18:35.942Z,1349799515.942 [NavChart](DEBUG): Uninitialize NavChart Derivation.
2012-10-09T16:18:35.943Z,1349799515.943 [Default] Stopped
2012-10-09T16:18:35.944Z,1349799515.944 [Default](INFO): Aggregate::uninitialize Default
2012-10-09T16:18:35.944Z,1349799515.944 [Default:Iridium] Stopped
2012-10-09T16:18:35.944Z,1349799515.944 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-10-09T16:18:35.944Z,1349799515.944 [Default:Iridium:A.SetSpeed] Stopped
2012-10-09T16:18:35.944Z,1349799515.944 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-10-09T16:18:35.944Z,1349799515.944 [Default:Iridium:Read_Iridium] Stopped
2012-10-09T16:18:35.944Z,1349799515.944 [Default:D.SetSpeed] Stopped
2012-10-09T16:18:35.944Z,1349799515.944 [Default:D.SetSpeed](DEBUG): Uninitialize.
2012-10-09T16:18:35.944Z,1349799515.944 [Default:E.GoToSurface] Stopped
2012-10-09T16:18:35.944Z,1349799515.944 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-09T16:18:35.948Z,1349799515.948 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2012-10-09T16:18:35.949Z,1349799515.949 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2012-10-09T16:18:35.949Z,1349799515.949 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2012-10-09T16:18:35.949Z,1349799515.949 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2012-10-09T16:18:35.950Z,1349799515.950 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2012-10-09T16:18:35.950Z,1349799515.950 [BuoyancyServo](INFO): Powering down
2012-10-09T16:18:35.955Z,1349799515.955 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2012-10-09T16:18:35.955Z,1349799515.955 [ElevatorServo](INFO): Powering down
2012-10-09T16:18:35.956Z,1349799515.956 [MassServo](DEBUG): Uninitialize Mass Servo.
2012-10-09T16:18:35.956Z,1349799515.956 [MassServo](INFO): Powering down
2012-10-09T16:18:35.957Z,1349799515.957 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2012-10-09T16:18:35.957Z,1349799515.957 [RudderServo](INFO): Powering down
2012-10-09T16:18:35.958Z,1349799515.958 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2012-10-09T16:18:35.958Z,1349799515.958 [ThrusterServo](INFO): Powering down
2012-10-09T16:18:35.959Z,1349799515.959 [SBIT](DEBUG): Uninitialize SBIT Component.
2012-10-09T16:18:35.959Z,1349799515.959 [IBIT](DEBUG): Uninitialize IBIT Component.
2012-10-09T16:18:35.959Z,1349799515.959 [CBIT](DEBUG): Uninitialize CBIT Component.