2012-10-09T16:12:29.655Z,1349799149.655 [Supervisor](DEBUG): Initializing supervisor.
2012-10-09T16:12:29.657Z,1349799149.657 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0
2012-10-09T16:12:29.658Z,1349799149.658 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2012-10-09T16:12:29.659Z,1349799149.659 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4036B4E0
2012-10-09T16:12:29.663Z,1349799149.662 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2012-10-09T16:12:29.673Z,1349799149.672 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2012-10-09T16:12:29.674Z,1349799149.674 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4039B4E0
2012-10-09T16:12:29.674Z,1349799149.674 [ComponentRegistry](DEBUG): SyncComponent "logger" handled in the control thread.
2012-10-09T16:12:29.675Z,1349799149.675 [Supervisor](INFO): Looking for Config files in directory: Config/
2012-10-09T16:12:29.676Z,1349799149.676 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2012-10-09T16:12:30.013Z,1349799150.013 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2012-10-09T16:12:30.013Z,1349799150.013 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2012-10-09T16:12:30.193Z,1349799150.194 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2012-10-09T16:12:30.194Z,1349799150.194 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2012-10-09T16:12:30.282Z,1349799150.282 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2012-10-09T16:12:30.283Z,1349799150.283 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2012-10-09T16:12:30.479Z,1349799150.479 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2012-10-09T16:12:30.480Z,1349799150.480 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2012-10-09T16:12:30.610Z,1349799150.610 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2012-10-09T16:12:30.611Z,1349799150.611 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2012-10-09T16:12:30.830Z,1349799150.830 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2012-10-09T16:12:30.831Z,1349799150.831 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2012-10-09T16:12:30.998Z,1349799150.997 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2012-10-09T16:12:30.998Z,1349799150.998 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2012-10-09T16:12:31.237Z,1349799151.237 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2012-10-09T16:12:31.238Z,1349799151.238 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2012-10-09T16:12:31.335Z,1349799151.335 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2012-10-09T16:12:31.336Z,1349799151.336 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2012-10-09T16:12:31.718Z,1349799151.718 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2012-10-09T16:12:31.719Z,1349799151.719 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2012-10-09T16:12:31.832Z,1349799151.832 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2012-10-09T16:12:31.833Z,1349799151.833 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2012-10-09T16:12:31.919Z,1349799151.919 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2012-10-09T16:12:31.920Z,1349799151.920 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2012-10-09T16:12:32.021Z,1349799152.021 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2012-10-09T16:12:32.147Z,1349799152.147 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2012-10-09T16:12:32.233Z,1349799152.233 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2012-10-09T16:12:32.333Z,1349799152.333 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2012-10-09T16:12:32.433Z,1349799152.433 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2012-10-09T16:12:32.548Z,1349799152.548 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2012-10-09T16:12:32.639Z,1349799152.639 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2012-10-09T16:12:32.726Z,1349799152.726 [Supervisor](IMPORTANT): Reading configuration overrides from Data/persisted.cfg
2012-10-09T16:12:32.756Z,1349799152.756 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2012-10-09T16:12:32.891Z,1349799152.891 [InternalSim] Loaded
2012-10-09T16:12:32.892Z,1349799152.892 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2012-10-09T16:12:32.892Z,1349799152.892 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2012-10-09T16:12:32.893Z,1349799152.893 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2012-10-09T16:12:32.948Z,1349799152.948 [SBIT](DEBUG): Construct Startup Built In Test.
2012-10-09T16:12:32.961Z,1349799152.961 [SBIT] Loaded
2012-10-09T16:12:32.961Z,1349799152.961 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2012-10-09T16:12:32.962Z,1349799152.962 [IBIT](DEBUG): Construct Initiated Built In Test.
2012-10-09T16:12:32.989Z,1349799152.989 [IBIT] Loaded
2012-10-09T16:12:32.989Z,1349799152.989 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2012-10-09T16:12:32.992Z,1349799152.992 [CBIT](DEBUG): Construct CBIT Built In Test.
2012-10-09T16:12:33.112Z,1349799153.112 [CBIT] Loaded
2012-10-09T16:12:33.112Z,1349799153.112 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2012-10-09T16:12:33.113Z,1349799153.113 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2012-10-09T16:12:33.113Z,1349799153.113 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2012-10-09T16:12:33.256Z,1349799153.256 [BuoyancyServo] Loaded
2012-10-09T16:12:33.256Z,1349799153.256 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2012-10-09T16:12:33.265Z,1349799153.265 [ElevatorServo] Loaded
2012-10-09T16:12:33.265Z,1349799153.265 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2012-10-09T16:12:33.277Z,1349799153.277 [MassServo] Loaded
2012-10-09T16:12:33.277Z,1349799153.277 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2012-10-09T16:12:33.289Z,1349799153.289 [RudderServo] Loaded
2012-10-09T16:12:33.289Z,1349799153.289 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2012-10-09T16:12:33.300Z,1349799153.300 [ThrusterServo] Loaded
2012-10-09T16:12:33.300Z,1349799153.300 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2012-10-09T16:12:33.301Z,1349799153.301 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2012-10-09T16:12:33.301Z,1349799153.301 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2012-10-09T16:12:33.317Z,1349799153.318 [DepthRateCalculator] Loaded
2012-10-09T16:12:33.318Z,1349799153.318 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2012-10-09T16:12:37.762Z,1349799157.762 [HFRadarModelCalc] Loaded
2012-10-09T16:12:37.762Z,1349799157.762 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread.
2012-10-09T16:12:37.779Z,1349799157.779 [NavChart] Loaded
2012-10-09T16:12:37.779Z,1349799157.779 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2012-10-09T16:12:37.785Z,1349799157.784 [PitchRateCalculator] Loaded
2012-10-09T16:12:37.785Z,1349799157.785 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2012-10-09T16:12:37.791Z,1349799157.791 [SpeedCalculator] Loaded
2012-10-09T16:12:37.791Z,1349799157.791 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2012-10-09T16:12:37.805Z,1349799157.805 [TempGradientCalculator] Loaded
2012-10-09T16:12:37.805Z,1349799157.805 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2012-10-09T16:12:37.811Z,1349799157.811 [YawRateCalculator] Loaded
2012-10-09T16:12:37.811Z,1349799157.811 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2012-10-09T16:12:37.830Z,1349799157.830 [Navigation] Loaded
2012-10-09T16:12:37.830Z,1349799157.830 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread.
2012-10-09T16:12:37.831Z,1349799157.831 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2012-10-09T16:12:37.831Z,1349799157.831 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2012-10-09T16:12:38.051Z,1349799158.051 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2012-10-09T16:12:38.051Z,1349799158.051 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2012-10-09T16:12:38.076Z,1349799158.076 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2012-10-09T16:12:38.077Z,1349799158.077 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2012-10-09T16:12:38.116Z,1349799158.116 [VerticalControl](DEBUG): Construct VerticalControl.
2012-10-09T16:12:38.159Z,1349799158.159 [VerticalControl] Loaded
2012-10-09T16:12:38.159Z,1349799158.159 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2012-10-09T16:12:38.160Z,1349799158.160 [HorizontalControl](DEBUG): Construct HorizontalControl.
2012-10-09T16:12:38.180Z,1349799158.180 [HorizontalControl] Loaded
2012-10-09T16:12:38.180Z,1349799158.180 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2012-10-09T16:12:38.181Z,1349799158.181 [SpeedControl](DEBUG): Construct SpeedControl.
2012-10-09T16:12:38.183Z,1349799158.182 [SpeedControl] Loaded
2012-10-09T16:12:38.183Z,1349799158.183 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2012-10-09T16:12:38.184Z,1349799158.184 [LoopControl](DEBUG): Construct LoopControl.
2012-10-09T16:12:38.184Z,1349799158.184 [LoopControl] Loaded
2012-10-09T16:12:38.184Z,1349799158.184 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2012-10-09T16:12:38.185Z,1349799158.185 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2012-10-09T16:12:38.185Z,1349799158.185 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2012-10-09T16:12:38.191Z,1349799158.191 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator.
2012-10-09T16:12:38.195Z,1349799158.195 [AsyncPiEstimator] Loaded
2012-10-09T16:12:38.196Z,1349799158.195 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread.
2012-10-09T16:12:38.197Z,1349799158.197 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 406724E0
2012-10-09T16:12:38.197Z,1349799158.197 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2012-10-09T16:12:38.198Z,1349799158.198 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2012-10-09T16:12:38.320Z,1349799158.320 [AHRS_sp3003D] Loaded
2012-10-09T16:12:38.320Z,1349799158.320 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2012-10-09T16:12:38.333Z,1349799158.333 [Depth_Keller] Loaded
2012-10-09T16:12:38.334Z,1349799158.334 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2012-10-09T16:12:38.339Z,1349799158.339 [DropWeight] Loaded
2012-10-09T16:12:38.339Z,1349799158.339 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2012-10-09T16:12:38.451Z,1349799158.451 [DVL_micro] Loaded
2012-10-09T16:12:38.451Z,1349799158.451 [ComponentRegistry](DEBUG): Component "DVL_micro" handled in its own thread.
2012-10-09T16:12:38.452Z,1349799158.452 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406F34E0
2012-10-09T16:12:38.531Z,1349799158.531 [NAL9602] Loaded
2012-10-09T16:12:38.532Z,1349799158.532 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2012-10-09T16:12:38.581Z,1349799158.580 [Onboard] Loaded
2012-10-09T16:12:38.581Z,1349799158.581 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2012-10-09T16:12:38.587Z,1349799158.587 [Radio_Freewave] Loaded
2012-10-09T16:12:38.588Z,1349799158.588 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread.
2012-10-09T16:12:38.588Z,1349799158.588 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2012-10-09T16:12:38.589Z,1349799158.589 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2012-10-09T16:12:38.661Z,1349799158.661 [CTD_NeilBrown] Loaded
2012-10-09T16:12:38.662Z,1349799158.662 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2012-10-09T16:12:38.663Z,1349799158.663 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4074B4E0
2012-10-09T16:12:38.673Z,1349799158.673 [PAR_Licor] Loaded
2012-10-09T16:12:38.673Z,1349799158.673 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2012-10-09T16:12:38.689Z,1349799158.689 [Turbulence_NPS] Loaded
2012-10-09T16:12:38.689Z,1349799158.689 [ComponentRegistry](DEBUG): Component "Turbulence_NPS" handled in its own thread.
2012-10-09T16:12:38.690Z,1349799158.690 [Turbulence_NPS ThreadHandler](DEBUG): Created PCaller Thread at 4077B4E0
2012-10-09T16:12:38.720Z,1349799158.720 [WetLabsBB2FL] Loaded
2012-10-09T16:12:38.720Z,1349799158.720 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2012-10-09T16:12:38.721Z,1349799158.721 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407AB4E0
2012-10-09T16:12:38.721Z,1349799158.721 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2012-10-09T16:12:38.724Z,1349799158.724 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2012-10-09T16:12:38.725Z,1349799158.724 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2012-10-09T16:12:38.730Z,1349799158.730 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2012-10-09T16:12:38.732Z,1349799158.732 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 407DB4E0
2012-10-09T16:12:38.736Z,1349799158.736 [Supervisor](DEBUG): Running supervisor.
2012-10-09T16:12:38.737Z,1349799158.737 [CommandLine](INFO): Thread ID is 7363
2012-10-09T16:12:38.740Z,1349799158.740 [controlThread](INFO): Thread ID is 7362
2012-10-09T16:12:38.740Z,1349799158.740 [controlThread](DEBUG): Initializing ControlThread
2012-10-09T16:12:38.740Z,1349799158.740 [CycleStarter](INFO): Thread ID is 7361
2012-10-09T16:12:38.741Z,1349799158.741 [InternalSim](DEBUG):  InternalSim initializing...
2012-10-09T16:12:38.775Z,1349799158.775 [AsyncPiEstimator](INFO): Thread ID is 7424
2012-10-09T16:12:38.775Z,1349799158.775 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator.
2012-10-09T16:12:38.785Z,1349799158.785 [SBIT](INFO): Initialize SBIT Component.
2012-10-09T16:12:38.786Z,1349799158.786 [SBIT](IMPORTANT): Tethys CM Info:
$Rev: 9939
2012-10-09T16:12:38.786Z,1349799158.786 [IBIT](INFO): Initialize IBIT Component.
2012-10-09T16:12:38.787Z,1349799158.787 [CBIT](DEBUG): Initialize CBIT Component.
2012-10-09T16:12:38.787Z,1349799158.787 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2012-10-09T16:12:38.788Z,1349799158.788 [DVL_micro](INFO): Thread ID is 7425
2012-10-09T16:12:38.800Z,1349799158.800 [DVL_micro](INFO): Initializing
2012-10-09T16:12:38.801Z,1349799158.801 [DVL_micro](INFO): start:Powering up
2012-10-09T16:12:38.802Z,1349799158.802 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-10-09T16:12:38.803Z,1349799158.803 [DVL_micro](INFO): Cycling power to configure device.
2012-10-09T16:12:38.812Z,1349799158.812 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2012-10-09T16:12:38.812Z,1349799158.812 [NavChart](DEBUG): Initialize NavChart Derivation.
2012-10-09T16:12:38.813Z,1349799158.813 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2012-10-09T16:12:38.813Z,1349799158.813 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2012-10-09T16:12:38.813Z,1349799158.813 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2012-10-09T16:12:38.815Z,1349799158.815 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2012-10-09T16:12:38.815Z,1349799158.815 [Navigation](DEBUG): Initializing Navigation.
2012-10-09T16:12:38.816Z,1349799158.816 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2012-10-09T16:12:38.817Z,1349799158.817 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2012-10-09T16:12:38.818Z,1349799158.818 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2012-10-09T16:12:38.819Z,1349799158.819 [LoopControl](DEBUG): Initialize LoopControlComponent.
2012-10-09T16:12:38.823Z,1349799158.823 [CTD_NeilBrown](INFO): Thread ID is 7426
2012-10-09T16:12:38.824Z,1349799158.824 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2012-10-09T16:12:38.830Z,1349799158.829 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2012-10-09T16:12:38.831Z,1349799158.831 [Turbulence_NPS](INFO): Thread ID is 7427
2012-10-09T16:12:38.832Z,1349799158.832 [Turbulence_NPS](DEBUG): Initializing Turbulence_NPS.
2012-10-09T16:12:38.832Z,1349799158.832 [Turbulence_NPS](INFO): Opening uart, block timeout 10ths=1
2012-10-09T16:12:38.839Z,1349799158.839 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2012-10-09T16:12:38.856Z,1349799158.856 [WetLabsBB2FL](INFO): Thread ID is 7428
2012-10-09T16:12:38.857Z,1349799158.857 [WetLabsBB2FL](INFO): Powering down
2012-10-09T16:12:38.883Z,1349799158.884 [NavChartDb](INFO): Thread ID is 7429
2012-10-09T16:12:38.894Z,1349799158.894 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2012-10-09T16:12:38.904Z,1349799158.904 [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:12:38.905Z,1349799158.905 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2012-10-09T16:12:38.935Z,1349799158.935 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool
2012-10-09T16:12:38.937Z,1349799158.937 [Default:GPS:A.SetSpeed](DEBUG): Construct.
2012-10-09T16:12:38.945Z,1349799158.945 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface.
2012-10-09T16:12:38.961Z,1349799158.961 [Default:Iridium:A.SetSpeed](DEBUG): Construct.
2012-10-09T16:12:38.969Z,1349799158.969 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface.
2012-10-09T16:12:38.979Z,1349799158.979 [Turbulence_NPS](INFO): Pause powering down
2012-10-09T16:12:38.985Z,1349799158.985 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2012-10-09T16:12:39.007Z,1349799159.007 [Default:D.SetSpeed](DEBUG): Construct.
2012-10-09T16:12:39.010Z,1349799159.010 [Default:E.GoToSurface](DEBUG): Construct GoToSurface.
2012-10-09T16:12:39.019Z,1349799159.019 [Default:F.Wait](DEBUG): Construct Wait.
2012-10-09T16:12:39.022Z,1349799159.022 [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:12:39.027Z,1349799159.027 [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:12:39.050Z,1349799159.050 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-10-09T16:12:39.059Z,1349799159.059 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2012-10-09T16:12:39.060Z,1349799159.060 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2012-10-09T16:12:39.060Z,1349799159.060 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2012-10-09T16:12:39.060Z,1349799159.060 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2012-10-09T16:12:39.061Z,1349799159.061 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2012-10-09T16:12:39.531Z,1349799159.531 [Radio_Freewave](INFO): Powering up
2012-10-09T16:12:39.640Z,1349799159.640 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2012-10-09T16:12:39.643Z,1349799159.643 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2012-10-09T16:12:39.649Z,1349799159.649 [ElevatorServo](DEBUG): Initializing EZServoServo.
2012-10-09T16:12:39.650Z,1349799159.650 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2012-10-09T16:12:39.656Z,1349799159.656 [MassServo](DEBUG): Initializing EZServoServo.
2012-10-09T16:12:39.659Z,1349799159.659 [MassServo](DEBUG): Initializing MassServo.
2012-10-09T16:12:39.664Z,1349799159.664 [RudderServo](DEBUG): Initializing EZServoServo.
2012-10-09T16:12:39.665Z,1349799159.665 [RudderServo](DEBUG): Initializing RudderServo.
2012-10-09T16:12:39.672Z,1349799159.671 [ThrusterServo](DEBUG): Initializing EZServoServo.
2012-10-09T16:12:39.675Z,1349799159.675 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2012-10-09T16:12:40.883Z,1349799160.883 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-10-09T16:12:40.883Z,1349799160.883 [DVL_micro](INFO): Querying output modes
2012-10-09T16:12:40.883Z,1349799160.884 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

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

2012-10-09T16:12:40.891Z,1349799160.891 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2012-10-09T16:12:40.895Z,1349799160.895 [DVL_micro](INFO): pause:Powering down
2012-10-09T16:12:43.997Z,1349799163.997 [NAL9602](INFO): Powering up NAL9602
2012-10-09T16:12:55.326Z,1349799175.326 [SBIT](IMPORTANT): Beginning Startup BIT
2012-10-09T16:12:55.328Z,1349799175.328 [CBIT](IMPORTANT): Beginning GF scan
2012-10-09T16:12:55.555Z,1349799175.555 [NAL9602](INFO): NAL9602 initialized
2012-10-09T16:12:58.863Z,1349799178.863 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.823200 degree
2012-10-09T16:12:58.865Z,1349799178.865 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread.
2012-10-09T16:12:58.865Z,1349799178.865 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121827.000000 degree
2012-10-09T16:12:58.867Z,1349799178.866 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread.
2012-10-09T16:12:58.867Z,1349799178.867 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2012-10-09T16:12:58.868Z,1349799178.868 [CommandLine](IMPORTANT): got command report touch Turbulence_NPS.packetNum
2012-10-09T16:12:59.633Z,1349799179.633 [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:13:07.144Z,1349799187.144 [NAL9602](INFO): Powering down
2012-10-09T16:13:08.871Z,1349799188.871 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2012-10-09T16:13:24.772Z,1349799204.772 [CBIT](IMPORTANT): No ground fault detected
2012-10-09T16:13:42.479Z,1349799222.479 [CommandLine](IMPORTANT): got command report touch platform_pitch_angle
2012-10-09T16:13:43.555Z,1349799223.555 [Reporter](INFO): platform_pitch_angle -0.525508 arcdeg
2012-10-09T16:13:45.035Z,1349799225.035 [Reporter](INFO): platform_pitch_angle -0.437617 arcdeg
2012-10-09T16:13:45.695Z,1349799225.695 [Reporter](INFO): platform_pitch_angle -0.481562 arcdeg
2012-10-09T16:13:46.243Z,1349799226.243 [Reporter](INFO): platform_pitch_angle -0.459590 arcdeg
2012-10-09T16:13:47.183Z,1349799227.183 [Reporter](INFO): platform_pitch_angle -0.503535 arcdeg
2012-10-09T16:13:48.139Z,1349799228.139 [Reporter](INFO): platform_pitch_angle -0.459590 arcdeg
2012-10-09T16:13:48.191Z,1349799228.191 [CommandLine](IMPORTANT): got command report clear
2012-10-09T16:13:50.660Z,1349799230.660 [SBIT](IMPORTANT): SBIT PASSED
2012-10-09T16:13:51.038Z,1349799231.038 [MissionManager](IMPORTANT): Started mission Startup
2012-10-09T16:13:51.038Z,1349799231.038 [Startup] Running Loop=1
2012-10-09T16:13:51.038Z,1349799231.039 [Startup](INFO): Aggregate::initialize Startup
2012-10-09T16:13:51.039Z,1349799231.039 [Startup:A.GoToSurface] Running Loop=1
2012-10-09T16:13:51.039Z,1349799231.039 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-09T16:13:51.045Z,1349799231.045 [Startup:StartupSatComms] Running Loop=1
2012-10-09T16:13:51.045Z,1349799231.045 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2012-10-09T16:13:51.045Z,1349799231.045 [Startup:StartupSatComms:A] Running Loop=1
2012-10-09T16:13:51.438Z,1349799231.438 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-10-09T16:13:51.440Z,1349799231.440 [Startup:StartupSatComms:A] Stopped
2012-10-09T16:13:51.440Z,1349799231.440 [Startup:StartupSatComms:B] Running Loop=1
2012-10-09T16:13:51.841Z,1349799231.841 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2012-10-09T16:13:51.975Z,1349799231.975 [CommandLine](IMPORTANT): got command show stack
2012-10-09T16:13:51.975Z,1349799231.975 [CommandLine](INFO): Behavior Stack:

2012-10-09T16:13:51.975Z,1349799231.975 [Startup](INFO): Priority 0: Startup:A.GoToSurface
2012-10-09T16:13:51.976Z,1349799231.976 [Startup:StartupSatComms](INFO): Priority 1: Startup:StartupSatComms:B
2012-10-09T16:13:52.219Z,1349799232.218 [NAL9602](INFO): Powering up
2012-10-09T16:13:57.071Z,1349799237.071 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2012-10-09T16:13:57.433Z,1349799237.432 [Startup:StartupSatComms:B] Stopped
2012-10-09T16:13:57.433Z,1349799237.433 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2012-10-09T16:13:57.433Z,1349799237.433 [Startup:StartupSatComms] Stopped
2012-10-09T16:13:57.433Z,1349799237.433 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2012-10-09T16:13:57.434Z,1349799237.434 [Startup](INFO): Completed Startup
2012-10-09T16:13:57.434Z,1349799237.434 [Startup] Stopped
2012-10-09T16:13:57.434Z,1349799237.434 [Startup](INFO): Aggregate::uninitialize Startup
2012-10-09T16:13:57.434Z,1349799237.434 [Startup:A.GoToSurface] Stopped
2012-10-09T16:13:57.434Z,1349799237.434 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-09T16:13:57.832Z,1349799237.832 [MissionManager](IMPORTANT): Started mission Default
2012-10-09T16:13:57.832Z,1349799237.832 [Default] Running Loop=1
2012-10-09T16:13:57.832Z,1349799237.832 [Default](INFO): Aggregate::initialize Default
2012-10-09T16:13:57.832Z,1349799237.832 [Default:D.SetSpeed] Running Loop=1
2012-10-09T16:13:57.832Z,1349799237.832 [Default:D.SetSpeed](DEBUG): Initialize.
2012-10-09T16:13:57.832Z,1349799237.832 [Default:E.GoToSurface] Running Loop=1
2012-10-09T16:13:57.832Z,1349799237.832 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-09T16:13:57.833Z,1349799237.833 [Default:Iridium] Running Loop=1
2012-10-09T16:13:57.833Z,1349799237.833 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-10-09T16:13:57.833Z,1349799237.833 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-10-09T16:13:57.833Z,1349799237.833 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-10-09T16:13:57.833Z,1349799237.833 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-10-09T16:13:57.833Z,1349799237.833 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-09T16:13:57.834Z,1349799237.834 [Default:E.GoToSurface] Running Loop=1
2012-10-09T16:13:57.842Z,1349799237.842 [Default:D.SetSpeed] Running Loop=1
2012-10-09T16:13:57.852Z,1349799237.852 [Default:Iridium:B.GoToSurface] Stopped
2012-10-09T16:13:57.852Z,1349799237.852 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-09T16:13:57.852Z,1349799237.852 [Default:Iridium:Read_Iridium] Running Loop=1
2012-10-09T16:13:57.852Z,1349799237.852 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-10-09T16:13:57.857Z,1349799237.857 [Default:GPS] Running Loop=1
2012-10-09T16:13:57.857Z,1349799237.857 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-10-09T16:13:57.857Z,1349799237.857 [Default:GPS:A.SetSpeed] Running Loop=1
2012-10-09T16:13:57.857Z,1349799237.857 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-10-09T16:13:57.858Z,1349799237.858 [Default:GPS:B.GoToSurface] Running Loop=1
2012-10-09T16:13:57.858Z,1349799237.858 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-09T16:13:57.863Z,1349799237.863 [Default:GPS:B.GoToSurface] Stopped
2012-10-09T16:13:57.863Z,1349799237.863 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-09T16:13:57.863Z,1349799237.863 [Default:GPS:Read_GPS] Running Loop=1
2012-10-09T16:13:57.863Z,1349799237.863 [Default:GPS:A.SetSpeed] Running Loop=1
2012-10-09T16:13:58.234Z,1349799238.234 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2012-10-09T16:13:58.236Z,1349799238.236 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-10-09T16:13:58.238Z,1349799238.238 [Default:GPS:Read_GPS] Stopped
2012-10-09T16:13:58.238Z,1349799238.238 [Default:GPS:D] Running Loop=1
2012-10-09T16:13:58.635Z,1349799238.635 [Default:GPS:D] Stopped
2012-10-09T16:13:58.635Z,1349799238.635 [Default:GPS](INFO): Completed Default:GPS
2012-10-09T16:13:58.635Z,1349799238.635 [Default:GPS] Stopped
2012-10-09T16:13:58.636Z,1349799238.635 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-10-09T16:13:58.636Z,1349799238.635 [Default:GPS:A.SetSpeed] Stopped
2012-10-09T16:13:58.636Z,1349799238.636 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-10-09T16:14:02.643Z,1349799242.643 [NAL9602](INFO): NAL9602 initialized
2012-10-09T16:14:04.471Z,1349799244.471 [CommandLine](IMPORTANT): got command run ./Missions/Science/science_to_67_70.xml
2012-10-09T16:14:04.471Z,1349799244.471 [MissionManager](INFO): Loading Mission: ./Missions/Science/science_to_67_70.xml
2012-10-09T16:14:04.557Z,1349799244.557 [MissionManager](INFO): DefineArg science_to_67_70.MinAltitude = 10.000000 m
2012-10-09T16:14:04.560Z,1349799244.560 [MissionManager](INFO): DefineArg science_to_67_70.MaxDepth = 105.000000 m
2012-10-09T16:14:04.567Z,1349799244.567 [MissionManager](INFO): DefineArg science_to_67_70.MinOffshore = 2000.000000 m
2012-10-09T16:14:04.575Z,1349799244.575 [MissionManager](INFO): DefineArg science_to_67_70.YoYoMinDepth = 3.000000 m
2012-10-09T16:14:04.577Z,1349799244.577 [MissionManager](INFO): DefineArg science_to_67_70.YoYoMaxDepth = 100.000000 m
2012-10-09T16:14:04.584Z,1349799244.584 [MissionManager](INFO): DefineArg science_to_67_70.YoYoUpPitch = 20.000000 arcdeg
2012-10-09T16:14:04.591Z,1349799244.592 [MissionManager](INFO): DefineArg science_to_67_70.YoYoDownPitch = -15.000000 arcdeg
2012-10-09T16:14:04.599Z,1349799244.599 [MissionManager](INFO): DefineArg science_to_67_70.WptLat = 36.120000 arcdeg
2012-10-09T16:14:04.601Z,1349799244.602 [MissionManager](INFO): DefineArg science_to_67_70.WptLon = -123.486000 arcdeg
2012-10-09T16:14:04.609Z,1349799244.609 [MissionManager](INFO): DefineArg science_to_67_70.NeedCommsTime = 120.000000 min
2012-10-09T16:14:04.616Z,1349799244.616 [MissionManager](INFO): DefineArg science_to_67_70.Speed = 1.000000 m/s
2012-10-09T16:14:04.623Z,1349799244.623 [MissionManager](INFO): DefineArg science_to_67_70.Timeout = 36.000000 h
2012-10-09T16:14:04.626Z,1349799244.626 [MissionManager](INFO): DefineArg science_to_67_70.BuoyancyNeutral = 0.000330 n/a
2012-10-09T16:14:04.633Z,1349799244.633 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2012-10-09T16:14:04.649Z,1349799244.649 [science_to_67_70:AltEnvWrapper:B.Wait](DEBUG): Construct Wait.
2012-10-09T16:14:04.650Z,1349799244.650 [science_to_67_70:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2012-10-09T16:14:04.672Z,1349799244.672 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2012-10-09T16:14:04.687Z,1349799244.687 [science_to_67_70:OffshoreWrapper:B.Wait](DEBUG): Construct Wait.
2012-10-09T16:14:04.688Z,1349799244.688 [MissionManager](INFO): Inserting Stack: Missions/Insert/Surface.xml
2012-10-09T16:14:04.715Z,1349799244.715 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.SurfaceDepthRate = nan m/s
2012-10-09T16:14:04.718Z,1349799244.718 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.SurfacePitch = nan arcdeg
2012-10-09T16:14:04.733Z,1349799244.733 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.SurfaceSpeed = 0.500000 m/s
2012-10-09T16:14:04.736Z,1349799244.736 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.IridiumTimeout = 30.000000 min
2012-10-09T16:14:04.737Z,1349799244.737 [science_to_67_70:SURFACECOMMS:A.GoToSurface](DEBUG): Construct GoToSurface.
2012-10-09T16:14:04.759Z,1349799244.759 [MissionManager](INFO): RedefineArg science_to_67_70:SURFACECOMMS.SurfacePitch = 20.000000 arcdeg
2012-10-09T16:14:04.781Z,1349799244.781 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2012-10-09T16:14:04.919Z,1349799244.919 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleISUS = 0 bool
2012-10-09T16:14:04.921Z,1349799244.921 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleRSSI = 1 bool
2012-10-09T16:14:04.942Z,1349799244.942 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleADCP = 1 bool
2012-10-09T16:14:04.944Z,1349799244.944 [MissionManager](INFO): DefineArg science_to_67_70:Science.PeakDetectChlActive = 0 bool
2012-10-09T16:14:04.947Z,1349799244.947 [MissionManager](INFO): DefineArg science_to_67_70:Science.PeakDetectNO3Active = 0 bool
2012-10-09T16:14:04.949Z,1349799244.949 [MissionManager](INFO): DefineArg science_to_67_70:Science.PeakDetectRSSIActive = 0 bool
2012-10-09T16:14:04.954Z,1349799244.954 [MissionManager](INFO): DefineArg science_to_67_70:Science.EnabledAanderaaO2 = 0.000000 n/a
2012-10-09T16:14:04.957Z,1349799244.957 [MissionManager](INFO): DefineArg science_to_67_70:Science.EnabledNeilBrown = 1.000000 n/a
2012-10-09T16:14:04.961Z,1349799244.961 [MissionManager](INFO): DefineArg science_to_67_70:Science.EnabledTurbulence_NPS = 1.000000 n/a
2012-10-09T16:14:04.964Z,1349799244.964 [MissionManager](INFO): DefineArg science_to_67_70:Science.EnabledWetLabsBB2FL = 1.000000 n/a
2012-10-09T16:14:04.968Z,1349799244.968 [MissionManager](INFO): DefineArg science_to_67_70:Science.EnabledDVL = 1.000000 n/a
2012-10-09T16:14:04.971Z,1349799244.971 [MissionManager](INFO): DefineArg science_to_67_70:Science.EnabledISUS = 0.000000 n/a
2012-10-09T16:14:04.974Z,1349799244.974 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChl = 0.000000 ug/l
2012-10-09T16:14:04.979Z,1349799244.979 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChlDepth = 0.000000 m
2012-10-09T16:14:04.982Z,1349799244.982 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChlLatitude = nan arcdeg
2012-10-09T16:14:04.985Z,1349799244.985 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChlLongitude = nan arcdeg
2012-10-09T16:14:04.988Z,1349799244.988 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3 = 0.000000 umol/l
2012-10-09T16:14:04.992Z,1349799244.992 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3Depth = 0.000000 m
2012-10-09T16:14:04.995Z,1349799244.995 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3Latitude = nan arcdeg
2012-10-09T16:14:04.998Z,1349799244.998 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3Longitude = nan arcdeg
2012-10-09T16:14:05.001Z,1349799245.001 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakRSSI = 0.000000 dB
2012-10-09T16:14:05.004Z,1349799245.004 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakRSSIDepth = 0.000000 m
2012-10-09T16:14:05.008Z,1349799245.008 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakRSSILatitude = nan arcdeg
2012-10-09T16:14:05.012Z,1349799245.012 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakRSSILongitude = nan arcdeg
2012-10-09T16:14:05.024Z,1349799245.024 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2012-10-09T16:14:05.033Z,1349799245.033 [science_to_67_70:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2012-10-09T16:14:05.043Z,1349799245.043 [science_to_67_70:Science:PeakDetectRSSI:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2012-10-09T16:14:05.052Z,1349799245.052 [MissionManager](INFO): RedefineArg science_to_67_70:Science.SampleISUS = 1 bool
2012-10-09T16:14:05.053Z,1349799245.053 [MissionManager](INFO): RedefineArg science_to_67_70:Science.PeakDetectChlActive = 1 bool
2012-10-09T16:14:05.054Z,1349799245.054 [science_to_67_70:G.Buoyancy](DEBUG): Construct Buoyancy.
2012-10-09T16:14:05.058Z,1349799245.058 [science_to_67_70:H.SetSpeed](DEBUG): Construct.
2012-10-09T16:14:05.061Z,1349799245.061 [science_to_67_70:I.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2012-10-09T16:14:05.071Z,1349799245.070 [science_to_67_70:J.YoYo](DEBUG): Construct YoYo.
2012-10-09T16:14:05.078Z,1349799245.078 [science_to_67_70:Wpt.Point](DEBUG): Construct.
2012-10-09T16:14:05.113Z,1349799245.113 [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:14:05.114Z,1349799245.114 [CommandLine](IMPORTANT): Running ./Missions/Science/science_to_67_70.xml
2012-10-09T16:14:05.119Z,1349799245.119 [Default] Stopped
2012-10-09T16:14:05.119Z,1349799245.119 [Default](INFO): Aggregate::uninitialize Default
2012-10-09T16:14:05.119Z,1349799245.119 [Default:Iridium] Stopped
2012-10-09T16:14:05.119Z,1349799245.119 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-10-09T16:14:05.119Z,1349799245.119 [Default:Iridium:A.SetSpeed] Stopped
2012-10-09T16:14:05.119Z,1349799245.119 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-10-09T16:14:05.119Z,1349799245.119 [Default:Iridium:Read_Iridium] Stopped
2012-10-09T16:14:05.119Z,1349799245.119 [Default:D.SetSpeed] Stopped
2012-10-09T16:14:05.119Z,1349799245.119 [Default:D.SetSpeed](DEBUG): Uninitialize.
2012-10-09T16:14:05.120Z,1349799245.120 [Default:E.GoToSurface] Stopped
2012-10-09T16:14:05.120Z,1349799245.120 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-09T16:14:05.120Z,1349799245.120 [MissionManager](IMPORTANT): Started mission science_to_67_70
2012-10-09T16:14:05.120Z,1349799245.120 [science_to_67_70] Running Loop=1
2012-10-09T16:14:05.120Z,1349799245.120 [science_to_67_70](INFO): Aggregate::initialize science_to_67_70
2012-10-09T16:14:05.120Z,1349799245.120 [science_to_67_70:B.DepthEnvelope] Running Loop=1
2012-10-09T16:14:05.120Z,1349799245.120 [science_to_67_70:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2012-10-09T16:14:05.121Z,1349799245.121 [science_to_67_70:Science] Running Loop=1
2012-10-09T16:14:05.122Z,1349799245.122 [science_to_67_70:Science](INFO): Aggregate::initialize science_to_67_70:Science
2012-10-09T16:14:05.122Z,1349799245.122 [science_to_67_70:Science:A] Running Loop=1
2012-10-09T16:14:05.122Z,1349799245.122 [science_to_67_70:Science:B] Running Loop=1
2012-10-09T16:14:05.122Z,1349799245.122 [science_to_67_70:Science:C] Running Loop=1
2012-10-09T16:14:05.122Z,1349799245.122 [science_to_67_70:Science:D] Running Loop=1
2012-10-09T16:14:05.122Z,1349799245.122 [science_to_67_70:Science:E] Running Loop=1
2012-10-09T16:14:05.122Z,1349799245.122 [science_to_67_70:Science:F] Running Loop=1
2012-10-09T16:14:05.122Z,1349799245.122 [science_to_67_70:Science:G] Running Loop=1
2012-10-09T16:14:05.122Z,1349799245.122 [science_to_67_70:G.Buoyancy] Running Loop=1
2012-10-09T16:14:05.122Z,1349799245.122 [science_to_67_70:G.Buoyancy](DEBUG): Initialize Buoyancy Component.
2012-10-09T16:14:05.122Z,1349799245.122 [science_to_67_70:H.SetSpeed] Running Loop=1
2012-10-09T16:14:05.122Z,1349799245.122 [science_to_67_70:H.SetSpeed](DEBUG): Initialize.
2012-10-09T16:14:05.123Z,1349799245.123 [science_to_67_70:I.DepthEnvelope] Running Loop=1
2012-10-09T16:14:05.123Z,1349799245.123 [science_to_67_70:I.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2012-10-09T16:14:05.123Z,1349799245.124 [science_to_67_70:J.YoYo] Running Loop=1
2012-10-09T16:14:05.124Z,1349799245.124 [science_to_67_70:J.YoYo](DEBUG): Initialize YoYoComponent.
2012-10-09T16:14:05.124Z,1349799245.124 [science_to_67_70:SURFACECOMMS] Running Loop=1
2012-10-09T16:14:05.124Z,1349799245.124 [science_to_67_70:SURFACECOMMS](INFO): Aggregate::initialize science_to_67_70:SURFACECOMMS
2012-10-09T16:14:05.124Z,1349799245.124 [science_to_67_70:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-10-09T16:14:05.124Z,1349799245.124 [science_to_67_70:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-09T16:14:05.125Z,1349799245.125 [science_to_67_70:J.YoYo] Running Loop=1
2012-10-09T16:14:05.130Z,1349799245.130 [science_to_67_70:I.DepthEnvelope] Running Loop=1
2012-10-09T16:14:05.136Z,1349799245.136 [science_to_67_70:H.SetSpeed] Running Loop=1
2012-10-09T16:14:05.141Z,1349799245.141 [science_to_67_70:G.Buoyancy] Running Loop=1
2012-10-09T16:14:05.146Z,1349799245.146 [science_to_67_70:Science] Running Loop=1
2012-10-09T16:14:05.147Z,1349799245.147 [science_to_67_70:Science:PeakDetectChl] Running Loop=1
2012-10-09T16:14:05.147Z,1349799245.147 [science_to_67_70:Science:PeakDetectChl](INFO): Aggregate::initialize science_to_67_70:Science:PeakDetectChl
2012-10-09T16:14:05.147Z,1349799245.147 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2012-10-09T16:14:05.147Z,1349799245.147 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth](INFO): Initialize.
2012-10-09T16:14:05.153Z,1349799245.153 [science_to_67_70:Science:G] Running Loop=1
2012-10-09T16:14:05.154Z,1349799245.154 [science_to_67_70:Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2012-10-09T16:14:05.155Z,1349799245.155 [science_to_67_70:Science:F] Running Loop=1
2012-10-09T16:14:05.156Z,1349799245.156 [science_to_67_70:Science:F](DEBUG): Initialize ReadDataComponent to sense DVL_micro.Beam1RSSI
2012-10-09T16:14:05.157Z,1349799245.157 [science_to_67_70:Science:E] Running Loop=1
2012-10-09T16:14:05.158Z,1349799245.158 [science_to_67_70:Science:E](DEBUG): Initialize ReadDataComponent to sense DVL_micro.AdcpXWaterVelocity
2012-10-09T16:14:05.160Z,1349799245.159 [science_to_67_70:Science:D] Running Loop=1
2012-10-09T16:14:05.160Z,1349799245.160 [science_to_67_70:Science:D](DEBUG): Initialize ReadDataComponent to sense Turbulence_NPS.MicroCond
2012-10-09T16:14:05.161Z,1349799245.161 [science_to_67_70:Science:C] Running Loop=1
2012-10-09T16:14:05.162Z,1349799245.162 [science_to_67_70:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2012-10-09T16:14:05.163Z,1349799245.163 [science_to_67_70:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2012-10-09T16:14:05.169Z,1349799245.169 [science_to_67_70:SURFACECOMMS:B] Running Loop=1
2012-10-09T16:14:05.169Z,1349799245.169 [science_to_67_70:SURFACECOMMS:B](INFO): Aggregate::initialize science_to_67_70:SURFACECOMMS:B
2012-10-09T16:14:05.169Z,1349799245.170 [science_to_67_70:SURFACECOMMS:B:A] Running Loop=1
2012-10-09T16:14:05.169Z,1349799245.170 [science_to_67_70:OffshoreWrapper] Running Loop=1
2012-10-09T16:14:05.170Z,1349799245.170 [science_to_67_70:OffshoreWrapper](INFO): Aggregate::initialize science_to_67_70:OffshoreWrapper
2012-10-09T16:14:05.170Z,1349799245.170 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope] Running Loop=1
2012-10-09T16:14:05.170Z,1349799245.170 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2012-10-09T16:14:05.170Z,1349799245.170 [science_to_67_70:OffshoreWrapper:B.Wait] Running Loop=1
2012-10-09T16:14:05.170Z,1349799245.170 [science_to_67_70:OffshoreWrapper:B.Wait](DEBUG): Initialize Wait Component.
2012-10-09T16:14:05.177Z,1349799245.177 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope] Running Loop=1
2012-10-09T16:14:05.184Z,1349799245.184 [science_to_67_70:B.DepthEnvelope] Running Loop=1
2012-10-09T16:14:05.189Z,1349799245.189 [science_to_67_70:AltEnvWrapper] Running Loop=1
2012-10-09T16:14:05.189Z,1349799245.189 [science_to_67_70:AltEnvWrapper](INFO): Aggregate::initialize science_to_67_70:AltEnvWrapper
2012-10-09T16:14:05.189Z,1349799245.189 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope] Running Loop=1
2012-10-09T16:14:05.189Z,1349799245.189 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2012-10-09T16:14:05.190Z,1349799245.190 [science_to_67_70:AltEnvWrapper:B.Wait] Running Loop=1
2012-10-09T16:14:05.190Z,1349799245.190 [science_to_67_70:AltEnvWrapper:B.Wait](DEBUG): Initialize Wait Component.
2012-10-09T16:14:05.196Z,1349799245.196 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope] Running Loop=1
2012-10-09T16:14:05.199Z,1349799245.199 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2012-10-09T16:14:05.299Z,1349799245.299 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=4
2012-10-09T16:14:05.299Z,1349799245.299 [WetLabsBB2FL](INFO): Powering up
2012-10-09T16:14:05.311Z,1349799245.311 [DVL_micro](INFO): RSSI requested
2012-10-09T16:14:05.311Z,1349799245.311 [DVL_micro](INFO): ADCP requested
2012-10-09T16:14:05.312Z,1349799245.312 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-10-09T16:14:05.312Z,1349799245.312 [DVL_micro](INFO): resume:Powering up
2012-10-09T16:14:05.312Z,1349799245.312 [DVL_micro](INFO): Cycling power to configure device.
2012-10-09T16:14:05.547Z,1349799245.547 [Turbulence_NPS](INFO): Resume powering up
2012-10-09T16:14:05.548Z,1349799245.548 [Turbulence_NPS](INFO): Opening uart, block timeout 10ths=1
2012-10-09T16:14:06.140Z,1349799246.140 [science_to_67_70:J.YoYo] Preempted
2012-10-09T16:14:06.140Z,1349799246.140 [science_to_67_70:I.DepthEnvelope] Preempted
2012-10-09T16:14:06.140Z,1349799246.141 [science_to_67_70:H.SetSpeed] Preempted
2012-10-09T16:14:06.140Z,1349799246.141 [science_to_67_70:G.Buoyancy] Preempted
2012-10-09T16:14:06.141Z,1349799246.141 [science_to_67_70:Science] Preempted
2012-10-09T16:14:06.141Z,1349799246.141 [science_to_67_70:Science:C] Preempted
2012-10-09T16:14:06.141Z,1349799246.141 [science_to_67_70:Science:D] Preempted
2012-10-09T16:14:06.141Z,1349799246.141 [science_to_67_70:Science:E] Preempted
2012-10-09T16:14:06.141Z,1349799246.141 [science_to_67_70:Science:F] Preempted
2012-10-09T16:14:06.141Z,1349799246.141 [science_to_67_70:Science:G] Preempted
2012-10-09T16:14:06.142Z,1349799246.142 [science_to_67_70:SURFACECOMMS:B:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-10-09T16:14:06.144Z,1349799246.144 [science_to_67_70:SURFACECOMMS:B:A] Stopped
2012-10-09T16:14:06.144Z,1349799246.144 [science_to_67_70:SURFACECOMMS:B:B] Running Loop=1
2012-10-09T16:14:06.195Z,1349799246.195 [Turbulence_NPS](INFO): Pause powering down
2012-10-09T16:14:06.491Z,1349799246.491 [WetLabsBB2FL](INFO): Powering down
2012-10-09T16:14:06.529Z,1349799246.529 [science_to_67_70:SURFACECOMMS:B:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2012-10-09T16:14:07.319Z,1349799247.319 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-10-09T16:14:07.319Z,1349799247.319 [DVL_micro](INFO): Querying output modes
2012-10-09T16:14:07.319Z,1349799247.319 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

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

2012-10-09T16:14:07.327Z,1349799247.327 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2012-10-09T16:14:07.332Z,1349799247.332 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2012-10-09T16:14:09.171Z,1349799249.171 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2012-10-09T16:14:09.255Z,1349799249.256 [science_to_67_70:SURFACECOMMS:B:B] Stopped
2012-10-09T16:14:09.256Z,1349799249.256 [science_to_67_70:SURFACECOMMS:B:C] Running Loop=1
2012-10-09T16:14:09.647Z,1349799249.647 [science_to_67_70:SURFACECOMMS:B:C](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-10-09T16:14:09.649Z,1349799249.649 [science_to_67_70:SURFACECOMMS:B:C] Stopped
2012-10-09T16:14:09.649Z,1349799249.649 [science_to_67_70:SURFACECOMMS:B](INFO): Completed science_to_67_70:SURFACECOMMS:B
2012-10-09T16:14:09.649Z,1349799249.649 [science_to_67_70:SURFACECOMMS:B] Stopped
2012-10-09T16:14:09.649Z,1349799249.649 [science_to_67_70:SURFACECOMMS:B](INFO): Aggregate::uninitialize science_to_67_70:SURFACECOMMS:B
2012-10-09T16:14:09.650Z,1349799249.650 [science_to_67_70:SURFACECOMMS](INFO): Completed science_to_67_70:SURFACECOMMS
2012-10-09T16:14:09.650Z,1349799249.650 [science_to_67_70:SURFACECOMMS] Stopped
2012-10-09T16:14:09.650Z,1349799249.651 [science_to_67_70:SURFACECOMMS](INFO): Aggregate::uninitialize science_to_67_70:SURFACECOMMS
2012-10-09T16:14:09.651Z,1349799249.651 [science_to_67_70:SURFACECOMMS:A.GoToSurface] Stopped
2012-10-09T16:14:09.651Z,1349799249.651 [science_to_67_70:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-09T16:14:09.651Z,1349799249.651 [science_to_67_70:Wpt.Point] Running Loop=1
2012-10-09T16:14:09.651Z,1349799249.651 [science_to_67_70:Wpt.Point](DEBUG): Initialize.
2012-10-09T16:14:09.855Z,1349799249.855 [DVL_micro](DEBUG): NQ1
2012-10-09T16:14:10.063Z,1349799250.063 [science_to_67_70:J.YoYo] Running Loop=1
2012-10-09T16:14:10.064Z,1349799250.064 [science_to_67_70:I.DepthEnvelope] Running Loop=1
2012-10-09T16:14:10.064Z,1349799250.064 [science_to_67_70:H.SetSpeed] Running Loop=1
2012-10-09T16:14:10.065Z,1349799250.065 [science_to_67_70:G.Buoyancy] Running Loop=1
2012-10-09T16:14:10.065Z,1349799250.065 [science_to_67_70:Science] Running Loop=1
2012-10-09T16:14:10.066Z,1349799250.066 [science_to_67_70:Science:G] Running Loop=1
2012-10-09T16:14:10.066Z,1349799250.066 [science_to_67_70:Science:F] Running Loop=1
2012-10-09T16:14:10.067Z,1349799250.067 [science_to_67_70:Science:E] Running Loop=1
2012-10-09T16:14:10.067Z,1349799250.067 [science_to_67_70:Science:D] Running Loop=1
2012-10-09T16:14:10.067Z,1349799250.067 [science_to_67_70:Science:C] Running Loop=1
2012-10-09T16:14:10.087Z,1349799250.087 [DVL_micro](INFO): RSSI requested
2012-10-09T16:14:10.087Z,1349799250.087 [DVL_micro](INFO): ADCP requested
2012-10-09T16:14:10.087Z,1349799250.087 [DVL_micro](INFO): Cycling power to configure device.
2012-10-09T16:14:10.133Z,1349799250.134 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=4
2012-10-09T16:14:10.134Z,1349799250.134 [WetLabsBB2FL](INFO): Powering up
2012-10-09T16:14:10.235Z,1349799250.235 [Turbulence_NPS](INFO): Resume powering up
2012-10-09T16:14:10.235Z,1349799250.236 [Turbulence_NPS](INFO): Opening uart, block timeout 10ths=1
2012-10-09T16:14:10.320Z,1349799250.320 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2012-10-09T16:14:11.736Z,1349799251.736 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.102980 psu
2012-10-09T16:14:11.935Z,1349799251.935 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.108940 psu
2012-10-09T16:14:12.095Z,1349799252.095 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-10-09T16:14:12.096Z,1349799252.096 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-10-09T16:14:12.132Z,1349799252.132 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.086850 psu
2012-10-09T16:14:12.279Z,1349799252.279 [DVL_micro](DEBUG): cmdResponse: 00
2012-10-09T16:14:12.279Z,1349799252.279 [DVL_micro](INFO): Enabling RSSI output
2012-10-09T16:14:12.279Z,1349799252.279 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 16 7

2012-10-09T16:14:12.332Z,1349799252.332 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.103720 psu
2012-10-09T16:14:12.463Z,1349799252.463 [DVL_micro](DEBUG): cmdResponse: 16
2012-10-09T16:14:12.463Z,1349799252.463 [DVL_micro](INFO): Enabling ADCP output
2012-10-09T16:14:12.463Z,1349799252.463 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 03 3

2012-10-09T16:14:12.535Z,1349799252.535 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.096640 psu
2012-10-09T16:14:12.536Z,1349799252.536 [CTD_NeilBrown](ERROR): Pressure reading out of range: -13.760000 decibar
2012-10-09T16:14:12.635Z,1349799252.635 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.122040 psu
2012-10-09T16:14:12.647Z,1349799252.647 [DVL_micro](DEBUG): cmdResponse: 03 16
2012-10-09T16:14:12.647Z,1349799252.647 [DVL_micro](INFO): Enabling NQ1 output
2012-10-09T16:14:12.647Z,1349799252.647 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-10-09T16:14:12.831Z,1349799252.831 [DVL_micro](DEBUG): cmdResponse: 01 03 16
2012-10-09T16:14:12.831Z,1349799252.831 [DVL_micro](INFO): Querying output modes
2012-10-09T16:14:12.831Z,1349799252.831 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

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

2012-10-09T16:14:12.841Z,1349799252.841 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2012-10-09T16:14:12.931Z,1349799252.931 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.164820 psu
2012-10-09T16:14:12.980Z,1349799252.980 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=41
2012-10-09T16:14:13.035Z,1349799253.035 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=61
2012-10-09T16:14:13.087Z,1349799253.087 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=17
2012-10-09T16:14:13.131Z,1349799253.131 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.151500 psu
2012-10-09T16:14:13.139Z,1349799253.139 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-10-09T16:14:13.331Z,1349799253.331 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.111750 psu
2012-10-09T16:14:13.419Z,1349799253.419 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24
2012-10-09T16:14:13.524Z,1349799253.524 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28
2012-10-09T16:14:13.531Z,1349799253.531 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.084160 psu
2012-10-09T16:14:13.624Z,1349799253.624 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=0
2012-10-09T16:14:13.727Z,1349799253.727 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-10-09T16:14:13.731Z,1349799253.731 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.094710 psu
2012-10-09T16:14:13.864Z,1349799253.864 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=8
2012-10-09T16:14:13.919Z,1349799253.919 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=28
2012-10-09T16:14:13.932Z,1349799253.932 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.084180 psu
2012-10-09T16:14:13.971Z,1349799253.971 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=16
2012-10-09T16:14:14.027Z,1349799254.027 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-10-09T16:14:14.131Z,1349799254.131 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.087810 psu
2012-10-09T16:14:14.307Z,1349799254.307 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24
2012-10-09T16:14:14.331Z,1349799254.331 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.092020 psu
2012-10-09T16:14:14.411Z,1349799254.411 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28
2012-10-09T16:14:14.512Z,1349799254.511 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=0
2012-10-09T16:14:14.531Z,1349799254.531 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.076890 psu
2012-10-09T16:14:14.615Z,1349799254.615 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-10-09T16:14:14.731Z,1349799254.731 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.098310 psu
2012-10-09T16:14:14.797Z,1349799254.797 [NAL9602](INFO): Powering down
2012-10-09T16:14:14.931Z,1349799254.931 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.102960 psu
2012-10-09T16:14:14.967Z,1349799254.967 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=56
2012-10-09T16:14:15.111Z,1349799255.111 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=28
2012-10-09T16:14:15.131Z,1349799255.131 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.121970 psu
2012-10-09T16:14:15.255Z,1349799255.255 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=33
2012-10-09T16:14:15.331Z,1349799255.331 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.091900 psu
2012-10-09T16:14:15.399Z,1349799255.399 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4
2012-10-09T16:14:15.531Z,1349799255.531 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.093100 psu
2012-10-09T16:14:15.724Z,1349799255.724 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=60
2012-10-09T16:14:15.728Z,1349799255.728 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.077020 psu
2012-10-09T16:14:15.836Z,1349799255.836 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4
2012-10-09T16:14:15.928Z,1349799255.928 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.081820 psu
2012-10-09T16:14:15.956Z,1349799255.955 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=44
2012-10-09T16:14:16.071Z,1349799256.071 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4
2012-10-09T16:14:16.129Z,1349799256.129 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.102020 psu
2012-10-09T16:14:16.328Z,1349799256.328 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.071050 psu
2012-10-09T16:14:16.528Z,1349799256.528 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.096210 psu
2012-10-09T16:14:16.596Z,1349799256.596 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=28
2012-10-09T16:14:16.729Z,1349799256.729 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.085620 psu
2012-10-09T16:14:16.831Z,1349799256.831 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4
2012-10-09T16:14:16.928Z,1349799256.928 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.073860 psu
2012-10-09T16:14:17.067Z,1349799257.067 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=12
2012-10-09T16:14:17.128Z,1349799257.128 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.110040 psu
2012-10-09T16:14:17.304Z,1349799257.304 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4
2012-10-09T16:14:17.328Z,1349799257.328 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.082290 psu
2012-10-09T16:14:17.528Z,1349799257.528 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.085120 psu
2012-10-09T16:14:17.728Z,1349799257.728 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.115930 psu
2012-10-09T16:14:17.929Z,1349799257.929 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.083610 psu
2012-10-09T16:14:18.128Z,1349799258.128 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.093400 psu
2012-10-09T16:14:18.228Z,1349799258.228 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=31
2012-10-09T16:14:18.328Z,1349799258.328 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.152950 psu
2012-10-09T16:14:18.528Z,1349799258.528 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.132010 psu
2012-10-09T16:14:18.700Z,1349799258.700 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=8
2012-10-09T16:14:18.729Z,1349799258.729 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.088060 psu
2012-10-09T16:14:18.927Z,1349799258.927 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.101130 psu
2012-10-09T16:14:19.127Z,1349799259.127 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.076000 psu
2012-10-09T16:14:19.175Z,1349799259.175 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=16
2012-10-09T16:14:19.328Z,1349799259.328 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.078330 psu
2012-10-09T16:14:19.527Z,1349799259.527 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.117940 psu
2012-10-09T16:14:19.648Z,1349799259.648 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4
2012-10-09T16:14:19.728Z,1349799259.728 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.052910 psu
2012-10-09T16:14:19.936Z,1349799259.935 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.072740 psu
2012-10-09T16:14:20.127Z,1349799260.127 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.093780 psu
2012-10-09T16:14:20.327Z,1349799260.327 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.096180 psu
2012-10-09T16:14:20.527Z,1349799260.527 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.089240 psu
2012-10-09T16:14:20.535Z,1349799260.535 [DVL_micro](DEBUG): NQ1
2012-10-09T16:14:20.559Z,1349799260.559 [DVL_micro](DEBUG): ADCP
2012-10-09T16:14:20.727Z,1349799260.727 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.090890 psu
2012-10-09T16:14:20.928Z,1349799260.928 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.094350 psu
2012-10-09T16:14:21.124Z,1349799261.124 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.076940 psu
2012-10-09T16:14:21.324Z,1349799261.324 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.112330 psu
2012-10-09T16:14:21.395Z,1349799261.395 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=13
2012-10-09T16:14:21.525Z,1349799261.525 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.070710 psu
2012-10-09T16:14:21.724Z,1349799261.724 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.086970 psu
2012-10-09T16:14:21.924Z,1349799261.924 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.067920 psu
2012-10-09T16:14:22.124Z,1349799262.124 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.096790 psu
2012-10-09T16:14:22.235Z,1349799262.235 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=57
2012-10-09T16:14:22.324Z,1349799262.324 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.100680 psu
2012-10-09T16:14:22.524Z,1349799262.524 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.080500 psu
2012-10-09T16:14:22.724Z,1349799262.724 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.074910 psu
2012-10-09T16:14:22.925Z,1349799262.925 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.107280 psu
2012-10-09T16:14:23.071Z,1349799263.071 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=38
2012-10-09T16:14:23.124Z,1349799263.124 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.113870 psu
2012-10-09T16:14:23.324Z,1349799263.324 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.117050 psu
2012-10-09T16:14:23.524Z,1349799263.524 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.075730 psu
2012-10-09T16:14:23.723Z,1349799263.723 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.064730 psu
2012-10-09T16:14:23.908Z,1349799263.908 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-10-09T16:14:23.923Z,1349799263.923 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.079110 psu
2012-10-09T16:14:24.125Z,1349799264.125 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.090470 psu
2012-10-09T16:14:24.324Z,1349799264.324 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.077910 psu
2012-10-09T16:14:24.444Z,1349799264.444 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=2600
2012-10-09T16:14:24.445Z,1349799264.445 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=1980
2012-10-09T16:14:24.446Z,1349799264.446 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=1360
2012-10-09T16:14:24.458Z,1349799264.458 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=740
2012-10-09T16:14:24.523Z,1349799264.523 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.101060 psu
2012-10-09T16:14:24.526Z,1349799264.526 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=216
2012-10-09T16:14:24.591Z,1349799264.591 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=60
2012-10-09T16:14:24.692Z,1349799264.692 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=32
2012-10-09T16:14:24.724Z,1349799264.724 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.071980 psu
2012-10-09T16:14:24.795Z,1349799264.795 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-10-09T16:14:24.924Z,1349799264.924 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.091810 psu
2012-10-09T16:14:24.933Z,1349799264.933 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=40
2012-10-09T16:14:24.987Z,1349799264.987 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=60
2012-10-09T16:14:25.040Z,1349799265.040 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=16
2012-10-09T16:14:25.091Z,1349799265.092 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-10-09T16:14:25.124Z,1349799265.124 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.071980 psu
2012-10-09T16:14:25.275Z,1349799265.275 [Turbulence_NPS](ERROR): No communication! Re-initializing
2012-10-09T16:14:25.275Z,1349799265.275 [Turbulence_NPS] Communications Fault, FailCount= 1
2012-10-09T16:14:25.275Z,1349799265.275 [Turbulence_NPS](ERROR): Communications Fault
2012-10-09T16:14:25.277Z,1349799265.277 [Turbulence_NPS](INFO): Uninitialize Powering down
2012-10-09T16:14:25.324Z,1349799265.324 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.096200 psu
2012-10-09T16:14:25.375Z,1349799265.375 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=58
2012-10-09T16:14:25.479Z,1349799265.479 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=62
2012-10-09T16:14:25.529Z,1349799265.529 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.098470 psu
2012-10-09T16:14:25.578Z,1349799265.578 [CBIT](ERROR): Communications Fault in component: Turbulence_NPS
2012-10-09T16:14:25.578Z,1349799265.578 [CBIT](INFO): Clearing failed state for component Turbulence_NPS
2012-10-09T16:14:25.578Z,1349799265.578 [Turbulence_NPS] No Fault, FailCount= 1
2012-10-09T16:14:25.587Z,1349799265.587 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=98
2012-10-09T16:14:25.679Z,1349799265.679 [Turbulence_NPS](DEBUG): Initializing Turbulence_NPS.
2012-10-09T16:14:25.680Z,1349799265.680 [Turbulence_NPS](INFO): Opening uart, block timeout 10ths=1
2012-10-09T16:14:25.688Z,1349799265.688 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-10-09T16:14:25.724Z,1349799265.724 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.084700 psu
2012-10-09T16:14:25.924Z,1349799265.924 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.109470 psu
2012-10-09T16:14:26.032Z,1349799266.032 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=25
2012-10-09T16:14:26.121Z,1349799266.121 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.122420 psu
2012-10-09T16:14:26.179Z,1349799266.179 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=61
2012-10-09T16:14:26.320Z,1349799266.320 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=2
2012-10-09T16:14:26.322Z,1349799266.322 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.097120 psu
2012-10-09T16:14:26.467Z,1349799266.467 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4
2012-10-09T16:14:26.524Z,1349799266.524 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.082740 psu
2012-10-09T16:14:26.724Z,1349799266.724 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.097010 psu
2012-10-09T16:14:26.787Z,1349799266.787 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=28
2012-10-09T16:14:26.905Z,1349799266.905 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=38
2012-10-09T16:14:26.923Z,1349799266.923 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.117040 psu
2012-10-09T16:14:27.019Z,1349799267.019 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=14
2012-10-09T16:14:27.120Z,1349799267.120 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.061960 psu
2012-10-09T16:14:27.135Z,1349799267.135 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4
2012-10-09T16:14:27.320Z,1349799267.320 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.070740 psu
2012-10-09T16:14:27.520Z,1349799267.520 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.118780 psu
2012-10-09T16:14:27.663Z,1349799267.663 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=60
2012-10-09T16:14:27.721Z,1349799267.721 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.078950 psu
2012-10-09T16:14:27.896Z,1349799267.896 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=6
2012-10-09T16:14:27.920Z,1349799267.920 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.087830 psu
2012-10-09T16:14:28.120Z,1349799268.120 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.094820 psu
2012-10-09T16:14:28.135Z,1349799268.135 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=14
2012-10-09T16:14:28.320Z,1349799268.320 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.120320 psu
2012-10-09T16:14:28.373Z,1349799268.372 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4
2012-10-09T16:14:28.520Z,1349799268.520 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.106580 psu
2012-10-09T16:14:28.720Z,1349799268.720 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.069810 psu
2012-10-09T16:14:28.919Z,1349799268.919 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.078510 psu
2012-10-09T16:14:29.120Z,1349799269.120 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.097900 psu
2012-10-09T16:14:29.319Z,1349799269.319 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.057340 psu
2012-10-09T16:14:29.407Z,1349799269.408 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=1309
2012-10-09T16:14:29.520Z,1349799269.520 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.072020 psu
2012-10-09T16:14:29.720Z,1349799269.720 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.081180 psu
2012-10-09T16:14:29.771Z,1349799269.771 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=7
2012-10-09T16:14:29.920Z,1349799269.920 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.106600 psu
2012-10-09T16:14:30.119Z,1349799270.119 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.091380 psu
2012-10-09T16:14:30.244Z,1349799270.244 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=15
2012-10-09T16:14:30.320Z,1349799270.320 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.086410 psu
2012-10-09T16:14:30.520Z,1349799270.520 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.084060 psu
2012-10-09T16:14:30.715Z,1349799270.715 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4
2012-10-09T16:14:30.719Z,1349799270.719 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.109680 psu
2012-10-09T16:14:30.919Z,1349799270.919 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.072490 psu
2012-10-09T16:14:31.120Z,1349799271.120 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.071300 psu
2012-10-09T16:14:31.319Z,1349799271.319 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.082540 psu
2012-10-09T16:14:31.523Z,1349799271.523 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.075250 psu
2012-10-09T16:14:31.615Z,1349799271.615 [CommandLine](IMPORTANT): got command restart application
2012-10-09T16:14:31.706Z,1349799271.706 [DVL_micro](DEBUG): NQ1
2012-10-09T16:14:31.720Z,1349799271.720 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.085100 psu
2012-10-09T16:14:31.919Z,1349799271.919 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.107630 psu
2012-10-09T16:14:31.969Z,1349799271.969 [DVL_micro](DEBUG): ADCP
2012-10-09T16:14:32.121Z,1349799272.121 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.083600 psu
2012-10-09T16:14:32.318Z,1349799272.318 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.087950 psu
2012-10-09T16:14:32.459Z,1349799272.459 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=49
2012-10-09T16:14:32.517Z,1349799272.517 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.090380 psu
2012-10-09T16:14:32.631Z,1349799272.631 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2012-10-09T16:14:32.631Z,1349799272.631 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2012-10-09T16:14:32.717Z,1349799272.717 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.085780 psu
2012-10-09T16:14:32.730Z,1349799272.730 [science_to_67_70] Stopped
2012-10-09T16:14:32.730Z,1349799272.730 [science_to_67_70](INFO): Aggregate::uninitialize science_to_67_70
2012-10-09T16:14:32.736Z,1349799272.736 [science_to_67_70:AltEnvWrapper] Stopped
2012-10-09T16:14:32.736Z,1349799272.736 [science_to_67_70:AltEnvWrapper](INFO): Aggregate::uninitialize science_to_67_70:AltEnvWrapper
2012-10-09T16:14:32.736Z,1349799272.736 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope] Stopped
2012-10-09T16:14:32.736Z,1349799272.736 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2012-10-09T16:14:32.736Z,1349799272.736 [science_to_67_70:AltEnvWrapper:B.Wait] Stopped
2012-10-09T16:14:32.736Z,1349799272.736 [science_to_67_70:AltEnvWrapper:B.Wait](DEBUG): Uninitialize Wait Component.
2012-10-09T16:14:32.737Z,1349799272.737 [science_to_67_70:B.DepthEnvelope] Stopped
2012-10-09T16:14:32.737Z,1349799272.737 [science_to_67_70:B.DepthEnvelope](DEBUG): Uninitialize.
2012-10-09T16:14:32.737Z,1349799272.737 [science_to_67_70:OffshoreWrapper] Stopped
2012-10-09T16:14:32.737Z,1349799272.737 [science_to_67_70:OffshoreWrapper](INFO): Aggregate::uninitialize science_to_67_70:OffshoreWrapper
2012-10-09T16:14:32.737Z,1349799272.737 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope] Stopped
2012-10-09T16:14:32.737Z,1349799272.737 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2012-10-09T16:14:32.738Z,1349799272.737 [science_to_67_70:OffshoreWrapper:B.Wait] Stopped
2012-10-09T16:14:32.738Z,1349799272.737 [science_to_67_70:OffshoreWrapper:B.Wait](DEBUG): Uninitialize Wait Component.
2012-10-09T16:14:32.738Z,1349799272.738 [science_to_67_70:Science] Stopped
2012-10-09T16:14:32.738Z,1349799272.738 [science_to_67_70:Science](INFO): Aggregate::uninitialize science_to_67_70:Science
2012-10-09T16:14:32.738Z,1349799272.738 [science_to_67_70:Science:A] Stopped
2012-10-09T16:14:32.745Z,1349799272.745 [science_to_67_70:Science:B] Stopped
2012-10-09T16:14:32.745Z,1349799272.745 [science_to_67_70:Science:C] Stopped
2012-10-09T16:14:32.745Z,1349799272.745 [science_to_67_70:Science:D] Stopped
2012-10-09T16:14:32.745Z,1349799272.745 [science_to_67_70:Science:E] Stopped
2012-10-09T16:14:32.745Z,1349799272.745 [science_to_67_70:Science:F] Stopped
2012-10-09T16:14:32.745Z,1349799272.745 [science_to_67_70:Science:G] Stopped
2012-10-09T16:14:32.745Z,1349799272.745 [science_to_67_70:Science:PeakDetectChl] Stopped
2012-10-09T16:14:32.745Z,1349799272.745 [science_to_67_70:Science:PeakDetectChl](INFO): Aggregate::uninitialize science_to_67_70:Science:PeakDetectChl
2012-10-09T16:14:32.745Z,1349799272.745 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2012-10-09T16:14:32.745Z,1349799272.745 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2012-10-09T16:14:32.745Z,1349799272.745 [science_to_67_70:G.Buoyancy] Stopped
2012-10-09T16:14:32.746Z,1349799272.746 [science_to_67_70:G.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2012-10-09T16:14:32.746Z,1349799272.746 [science_to_67_70:H.SetSpeed] Stopped
2012-10-09T16:14:32.746Z,1349799272.746 [science_to_67_70:H.SetSpeed](DEBUG): Uninitialize.
2012-10-09T16:14:32.746Z,1349799272.746 [science_to_67_70:I.DepthEnvelope] Stopped
2012-10-09T16:14:32.746Z,1349799272.746 [science_to_67_70:I.DepthEnvelope](DEBUG): Uninitialize.
2012-10-09T16:14:32.746Z,1349799272.746 [science_to_67_70:J.YoYo] Stopped
2012-10-09T16:14:32.746Z,1349799272.746 [science_to_67_70:J.YoYo](DEBUG): Uninitialize YoYoComponent.
2012-10-09T16:14:32.746Z,1349799272.746 [science_to_67_70:Wpt.Point] Stopped
2012-10-09T16:14:32.791Z,1349799272.791 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2012-10-09T16:14:32.803Z,1349799272.803 [Turbulence_NPS](INFO): Pause powering down
2012-10-09T16:14:32.899Z,1349799272.899 [WetLabsBB2FL](INFO): Powering down
2012-10-09T16:14:32.911Z,1349799272.911 [ComponentRegistry](INFO): Shutting down Turbulence_NPS ThreadHandler
2012-10-09T16:14:33.207Z,1349799273.207 [Turbulence_NPS](INFO): Uninitialize Powering down
2012-10-09T16:14:33.215Z,1349799273.215 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2012-10-09T16:14:33.295Z,1349799273.295 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=25
2012-10-09T16:14:33.307Z,1349799273.307 [CTD_NeilBrown](INFO): Powering down
2012-10-09T16:14:33.315Z,1349799273.315 [ComponentRegistry](INFO): Shutting down DVL_micro ThreadHandler
2012-10-09T16:14:33.544Z,1349799273.544 [MissionManager](IMPORTANT): Started mission Default
2012-10-09T16:14:33.544Z,1349799273.544 [Default] Running Loop=1
2012-10-09T16:14:33.544Z,1349799273.544 [Default](INFO): Aggregate::initialize Default
2012-10-09T16:14:33.544Z,1349799273.545 [Default:D.SetSpeed] Running Loop=1
2012-10-09T16:14:33.545Z,1349799273.545 [Default:D.SetSpeed](DEBUG): Initialize.
2012-10-09T16:14:33.545Z,1349799273.545 [Default:E.GoToSurface] Running Loop=1
2012-10-09T16:14:33.545Z,1349799273.545 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-09T16:14:33.545Z,1349799273.545 [Default:Iridium] Running Loop=1
2012-10-09T16:14:33.545Z,1349799273.545 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-10-09T16:14:33.545Z,1349799273.545 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-10-09T16:14:33.545Z,1349799273.545 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-10-09T16:14:33.545Z,1349799273.545 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-10-09T16:14:33.545Z,1349799273.546 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-09T16:14:33.552Z,1349799273.552 [Default:Iridium:B.GoToSurface] Stopped
2012-10-09T16:14:33.552Z,1349799273.552 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-09T16:14:33.552Z,1349799273.552 [Default:Iridium:Read_Iridium] Running Loop=1
2012-10-09T16:14:34.131Z,1349799274.131 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=1
2012-10-09T16:14:34.467Z,1349799274.467 [DVL_micro](CRITICAL): SIGSEGV: address not mapped to object
2012-10-09T16:14:34.467Z,1349799274.467 [DVL_micro](CRITICAL): Bad address: 0x0
2012-10-09T16:14:34.468Z,1349799274.468 [DVL_micro](CRITICAL): Backtrace: 
./bin/LRAUV(_ZN7PCaller20DefaultSignalHandlerEiP7siginfoPv+0x20) [0x142a38]
/lib/libc.so.6(__default_rt_sa_restorer+0) [0x40203510]

2012-10-09T16:14:34.468Z,1349799274.468 [DVL_micro] Software Fault, FailCount= 1
2012-10-09T16:14:34.468Z,1349799274.468 [DVL_micro](ERROR): Software Fault
2012-10-09T16:14:34.477Z,1349799274.477 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406F34E0
2012-10-09T16:14:34.478Z,1349799274.478 [DVL_micro](INFO): Thread ID is 7487
2012-10-09T16:14:34.479Z,1349799274.479 [DVL_micro](INFO): uninitialize:Powering down
2012-10-09T16:14:34.496Z,1349799274.496 [ComponentRegistry](INFO): Shutting down AsyncPiEstimator ThreadHandler
2012-10-09T16:14:34.507Z,1349799274.507 [AsyncPiEstimator](DEBUG): Uninitialize AsyncPiEstimator.
2012-10-09T16:14:34.516Z,1349799274.516 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2012-10-09T16:14:34.528Z,1349799274.528 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2012-10-09T16:14:34.596Z,1349799274.596 [NAL9602](INFO): Powering up
2012-10-09T16:14:34.698Z,1349799274.698 [CBIT](ERROR): Software Fault in component: DVL_micro
2012-10-09T16:14:34.817Z,1349799274.817 [controlThread](DEBUG): Uninitializing ControlThread
2012-10-09T16:14:34.817Z,1349799274.817 [AHRS_sp3003D](INFO): Powering down
2012-10-09T16:14:34.899Z,1349799274.899 [NAL9602](INFO): Powering down
2012-10-09T16:14:34.901Z,1349799274.901 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2012-10-09T16:14:34.902Z,1349799274.902 [NavChart](DEBUG): Uninitialize NavChart Derivation.
2012-10-09T16:14:34.903Z,1349799274.903 [Default] Stopped
2012-10-09T16:14:34.904Z,1349799274.904 [Default](INFO): Aggregate::uninitialize Default
2012-10-09T16:14:34.904Z,1349799274.904 [Default:Iridium] Stopped
2012-10-09T16:14:34.904Z,1349799274.904 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-10-09T16:14:34.904Z,1349799274.904 [Default:Iridium:A.SetSpeed] Stopped
2012-10-09T16:14:34.904Z,1349799274.904 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-10-09T16:14:34.904Z,1349799274.904 [Default:Iridium:Read_Iridium] Stopped
2012-10-09T16:14:34.904Z,1349799274.904 [Default:D.SetSpeed] Stopped
2012-10-09T16:14:34.904Z,1349799274.904 [Default:D.SetSpeed](DEBUG): Uninitialize.
2012-10-09T16:14:34.904Z,1349799274.904 [Default:E.GoToSurface] Stopped
2012-10-09T16:14:34.904Z,1349799274.904 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-09T16:14:34.909Z,1349799274.909 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2012-10-09T16:14:34.909Z,1349799274.909 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2012-10-09T16:14:34.909Z,1349799274.909 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2012-10-09T16:14:34.910Z,1349799274.910 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2012-10-09T16:14:34.910Z,1349799274.910 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2012-10-09T16:14:34.910Z,1349799274.910 [BuoyancyServo](INFO): Powering down
2012-10-09T16:14:34.915Z,1349799274.915 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2012-10-09T16:14:34.915Z,1349799274.915 [ElevatorServo](INFO): Powering down
2012-10-09T16:14:34.916Z,1349799274.916 [MassServo](DEBUG): Uninitialize Mass Servo.
2012-10-09T16:14:34.916Z,1349799274.916 [MassServo](INFO): Powering down
2012-10-09T16:14:34.917Z,1349799274.917 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2012-10-09T16:14:34.917Z,1349799274.917 [RudderServo](INFO): Powering down
2012-10-09T16:14:34.918Z,1349799274.918 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2012-10-09T16:14:34.918Z,1349799274.918 [ThrusterServo](INFO): Powering down
2012-10-09T16:14:34.919Z,1349799274.919 [SBIT](DEBUG): Uninitialize SBIT Component.
2012-10-09T16:14:34.919Z,1349799274.919 [IBIT](DEBUG): Uninitialize IBIT Component.
2012-10-09T16:14:34.919Z,1349799274.919 [CBIT](DEBUG): Uninitialize CBIT Component.