2012-10-01T14:46:03.361Z,1349102763.361 [Supervisor](DEBUG): Initializing supervisor.
2012-10-01T14:46:03.364Z,1349102763.364 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0
2012-10-01T14:46:03.365Z,1349102763.365 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2012-10-01T14:46:03.366Z,1349102763.366 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4036B4E0
2012-10-01T14:46:03.369Z,1349102763.369 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2012-10-01T14:46:03.380Z,1349102763.380 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2012-10-01T14:46:03.381Z,1349102763.381 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4039B4E0
2012-10-01T14:46:03.382Z,1349102763.382 [ComponentRegistry](DEBUG): SyncComponent "logger" handled in the control thread.
2012-10-01T14:46:03.382Z,1349102763.382 [Supervisor](INFO): Looking for Config files in directory: Config/
2012-10-01T14:46:03.383Z,1349102763.383 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2012-10-01T14:46:03.884Z,1349102763.884 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2012-10-01T14:46:03.885Z,1349102763.885 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2012-10-01T14:46:04.062Z,1349102764.062 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2012-10-01T14:46:04.063Z,1349102764.063 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2012-10-01T14:46:04.147Z,1349102764.147 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2012-10-01T14:46:04.148Z,1349102764.148 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2012-10-01T14:46:04.349Z,1349102764.349 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2012-10-01T14:46:04.350Z,1349102764.350 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2012-10-01T14:46:04.483Z,1349102764.483 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2012-10-01T14:46:04.484Z,1349102764.484 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2012-10-01T14:46:04.708Z,1349102764.708 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2012-10-01T14:46:04.709Z,1349102764.709 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2012-10-01T14:46:04.877Z,1349102764.877 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2012-10-01T14:46:04.877Z,1349102764.878 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2012-10-01T14:46:05.120Z,1349102765.120 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2012-10-01T14:46:05.121Z,1349102765.121 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2012-10-01T14:46:05.219Z,1349102765.219 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2012-10-01T14:46:05.220Z,1349102765.220 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2012-10-01T14:46:05.650Z,1349102765.650 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2012-10-01T14:46:05.651Z,1349102765.651 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2012-10-01T14:46:05.765Z,1349102765.765 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2012-10-01T14:46:05.765Z,1349102765.765 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2012-10-01T14:46:05.850Z,1349102765.851 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2012-10-01T14:46:05.851Z,1349102765.852 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2012-10-01T14:46:05.952Z,1349102765.952 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2012-10-01T14:46:06.079Z,1349102766.079 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2012-10-01T14:46:06.164Z,1349102766.164 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2012-10-01T14:46:06.264Z,1349102766.264 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2012-10-01T14:46:06.364Z,1349102766.364 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2012-10-01T14:46:06.481Z,1349102766.481 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2012-10-01T14:46:06.569Z,1349102766.569 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2012-10-01T14:46:06.660Z,1349102766.660 [Supervisor](IMPORTANT): Reading configuration overrides from Data/config.db
2012-10-01T14:46:06.669Z,1349102766.668 [ComponentRegistry](DEBUG): Loaded Config Component "configDb
2012-10-01T14:46:06.737Z,1349102766.737 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2012-10-01T14:46:06.926Z,1349102766.926 [InternalSim] Loaded
2012-10-01T14:46:06.926Z,1349102766.926 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2012-10-01T14:46:06.927Z,1349102766.927 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2012-10-01T14:46:06.928Z,1349102766.928 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2012-10-01T14:46:06.989Z,1349102766.989 [SBIT](DEBUG): Construct Startup Built In Test.
2012-10-01T14:46:07.002Z,1349102767.002 [SBIT] Loaded
2012-10-01T14:46:07.002Z,1349102767.002 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2012-10-01T14:46:07.003Z,1349102767.003 [IBIT](DEBUG): Construct Initiated Built In Test.
2012-10-01T14:46:07.032Z,1349102767.032 [IBIT] Loaded
2012-10-01T14:46:07.032Z,1349102767.032 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2012-10-01T14:46:07.035Z,1349102767.035 [CBIT](DEBUG): Construct CBIT Built In Test.
2012-10-01T14:46:07.155Z,1349102767.155 [CBIT] Loaded
2012-10-01T14:46:07.155Z,1349102767.155 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2012-10-01T14:46:07.156Z,1349102767.156 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2012-10-01T14:46:07.156Z,1349102767.157 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2012-10-01T14:46:07.305Z,1349102767.305 [BuoyancyServo] Loaded
2012-10-01T14:46:07.305Z,1349102767.305 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2012-10-01T14:46:07.314Z,1349102767.314 [ElevatorServo] Loaded
2012-10-01T14:46:07.314Z,1349102767.314 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2012-10-01T14:46:07.326Z,1349102767.326 [MassServo] Loaded
2012-10-01T14:46:07.326Z,1349102767.326 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2012-10-01T14:46:07.338Z,1349102767.338 [RudderServo] Loaded
2012-10-01T14:46:07.339Z,1349102767.339 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2012-10-01T14:46:07.350Z,1349102767.350 [ThrusterServo] Loaded
2012-10-01T14:46:07.351Z,1349102767.351 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2012-10-01T14:46:07.351Z,1349102767.351 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2012-10-01T14:46:07.352Z,1349102767.352 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2012-10-01T14:46:07.369Z,1349102767.369 [DepthRateCalculator] Loaded
2012-10-01T14:46:07.369Z,1349102767.369 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2012-10-01T14:46:11.219Z,1349102771.218 [HFRadarModelCalc] Loaded
2012-10-01T14:46:11.219Z,1349102771.219 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread.
2012-10-01T14:46:11.235Z,1349102771.235 [NavChart] Loaded
2012-10-01T14:46:11.236Z,1349102771.236 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2012-10-01T14:46:11.242Z,1349102771.242 [PitchRateCalculator] Loaded
2012-10-01T14:46:11.242Z,1349102771.242 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2012-10-01T14:46:11.248Z,1349102771.248 [SpeedCalculator] Loaded
2012-10-01T14:46:11.249Z,1349102771.249 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2012-10-01T14:46:11.264Z,1349102771.264 [TempGradientCalculator] Loaded
2012-10-01T14:46:11.264Z,1349102771.264 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2012-10-01T14:46:11.270Z,1349102771.270 [YawRateCalculator] Loaded
2012-10-01T14:46:11.271Z,1349102771.270 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2012-10-01T14:46:11.290Z,1349102771.290 [Navigation] Loaded
2012-10-01T14:46:11.290Z,1349102771.290 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread.
2012-10-01T14:46:11.291Z,1349102771.291 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2012-10-01T14:46:11.292Z,1349102771.292 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2012-10-01T14:46:11.533Z,1349102771.533 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2012-10-01T14:46:11.533Z,1349102771.533 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2012-10-01T14:46:11.556Z,1349102771.556 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2012-10-01T14:46:11.557Z,1349102771.557 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2012-10-01T14:46:11.598Z,1349102771.598 [VerticalControl](DEBUG): Construct VerticalControl.
2012-10-01T14:46:11.642Z,1349102771.642 [VerticalControl] Loaded
2012-10-01T14:46:11.642Z,1349102771.642 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2012-10-01T14:46:11.644Z,1349102771.644 [HorizontalControl](DEBUG): Construct HorizontalControl.
2012-10-01T14:46:11.664Z,1349102771.664 [HorizontalControl] Loaded
2012-10-01T14:46:11.664Z,1349102771.664 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2012-10-01T14:46:11.665Z,1349102771.665 [SpeedControl](DEBUG): Construct SpeedControl.
2012-10-01T14:46:11.667Z,1349102771.667 [SpeedControl] Loaded
2012-10-01T14:46:11.667Z,1349102771.667 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2012-10-01T14:46:11.668Z,1349102771.668 [LoopControl](DEBUG): Construct LoopControl.
2012-10-01T14:46:11.669Z,1349102771.668 [LoopControl] Loaded
2012-10-01T14:46:11.669Z,1349102771.669 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2012-10-01T14:46:11.669Z,1349102771.669 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2012-10-01T14:46:11.670Z,1349102771.670 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2012-10-01T14:46:11.675Z,1349102771.675 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator.
2012-10-01T14:46:11.680Z,1349102771.680 [AsyncPiEstimator] Loaded
2012-10-01T14:46:11.680Z,1349102771.680 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread.
2012-10-01T14:46:11.682Z,1349102771.682 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 406744E0
2012-10-01T14:46:11.682Z,1349102771.682 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2012-10-01T14:46:11.683Z,1349102771.683 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2012-10-01T14:46:11.811Z,1349102771.810 [AHRS_sp3003D] Loaded
2012-10-01T14:46:11.811Z,1349102771.811 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2012-10-01T14:46:11.825Z,1349102771.825 [Depth_Keller] Loaded
2012-10-01T14:46:11.825Z,1349102771.825 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2012-10-01T14:46:11.831Z,1349102771.830 [DropWeight] Loaded
2012-10-01T14:46:11.831Z,1349102771.831 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2012-10-01T14:46:11.946Z,1349102771.946 [DVL_micro] Loaded
2012-10-01T14:46:11.947Z,1349102771.947 [ComponentRegistry](DEBUG): Component "DVL_micro" handled in its own thread.
2012-10-01T14:46:11.948Z,1349102771.948 [DVL_micro ThreadHandler](DEBUG): Created PCaller Thread at 406F44E0
2012-10-01T14:46:12.032Z,1349102772.033 [NAL9602] Loaded
2012-10-01T14:46:12.033Z,1349102772.033 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2012-10-01T14:46:12.083Z,1349102772.083 [Onboard] Loaded
2012-10-01T14:46:12.084Z,1349102772.084 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2012-10-01T14:46:12.091Z,1349102772.091 [Radio_Freewave] Loaded
2012-10-01T14:46:12.091Z,1349102772.091 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread.
2012-10-01T14:46:12.092Z,1349102772.092 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2012-10-01T14:46:12.093Z,1349102772.092 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2012-10-01T14:46:12.165Z,1349102772.165 [CTD_NeilBrown] Loaded
2012-10-01T14:46:12.165Z,1349102772.165 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2012-10-01T14:46:12.166Z,1349102772.166 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at 4074C4E0
2012-10-01T14:46:12.177Z,1349102772.177 [PAR_Licor] Loaded
2012-10-01T14:46:12.177Z,1349102772.177 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2012-10-01T14:46:12.194Z,1349102772.194 [Turbulence_NPS] Loaded
2012-10-01T14:46:12.194Z,1349102772.194 [ComponentRegistry](DEBUG): Component "Turbulence_NPS" handled in its own thread.
2012-10-01T14:46:12.195Z,1349102772.195 [Turbulence_NPS ThreadHandler](DEBUG): Created PCaller Thread at 4077C4E0
2012-10-01T14:46:12.226Z,1349102772.226 [WetLabsBB2FL] Loaded
2012-10-01T14:46:12.227Z,1349102772.227 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2012-10-01T14:46:12.228Z,1349102772.228 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407AC4E0
2012-10-01T14:46:12.228Z,1349102772.228 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2012-10-01T14:46:12.231Z,1349102772.231 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2012-10-01T14:46:12.232Z,1349102772.232 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2012-10-01T14:46:12.238Z,1349102772.238 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2012-10-01T14:46:12.240Z,1349102772.240 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 407DC4E0
2012-10-01T14:46:12.244Z,1349102772.244 [Supervisor](DEBUG): Running supervisor.
2012-10-01T14:46:12.245Z,1349102772.245 [CommandLine](INFO): Thread ID is 7033
2012-10-01T14:46:12.248Z,1349102772.247 [controlThread](INFO): Thread ID is 7032
2012-10-01T14:46:12.248Z,1349102772.248 [controlThread](DEBUG): Initializing ControlThread
2012-10-01T14:46:12.248Z,1349102772.248 [CycleStarter](INFO): Thread ID is 7031
2012-10-01T14:46:12.249Z,1349102772.249 [InternalSim](DEBUG):  InternalSim initializing...
2012-10-01T14:46:12.284Z,1349102772.284 [AsyncPiEstimator](INFO): Thread ID is 7098
2012-10-01T14:46:12.284Z,1349102772.284 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator.
2012-10-01T14:46:12.293Z,1349102772.293 [SBIT](INFO): Initialize SBIT Component.
2012-10-01T14:46:12.293Z,1349102772.293 [SBIT](IMPORTANT): Tethys CM Info:
$Rev: 9907
2012-10-01T14:46:12.294Z,1349102772.294 [IBIT](INFO): Initialize IBIT Component.
2012-10-01T14:46:12.295Z,1349102772.295 [CBIT](DEBUG): Initialize CBIT Component.
2012-10-01T14:46:12.295Z,1349102772.295 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2012-10-01T14:46:12.296Z,1349102772.296 [DVL_micro](INFO): Thread ID is 7099
2012-10-01T14:46:12.305Z,1349102772.305 [DVL_micro](INFO): Initializing
2012-10-01T14:46:12.306Z,1349102772.306 [DVL_micro](INFO): start:Powering up
2012-10-01T14:46:12.307Z,1349102772.307 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-10-01T14:46:12.308Z,1349102772.307 [DVL_micro](INFO): Cycling power to configure device.
2012-10-01T14:46:12.320Z,1349102772.320 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2012-10-01T14:46:12.320Z,1349102772.320 [NavChart](DEBUG): Initialize NavChart Derivation.
2012-10-01T14:46:12.321Z,1349102772.321 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2012-10-01T14:46:12.321Z,1349102772.321 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2012-10-01T14:46:12.322Z,1349102772.322 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2012-10-01T14:46:12.323Z,1349102772.323 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2012-10-01T14:46:12.323Z,1349102772.323 [Navigation](DEBUG): Initializing Navigation.
2012-10-01T14:46:12.324Z,1349102772.324 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2012-10-01T14:46:12.325Z,1349102772.325 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2012-10-01T14:46:12.326Z,1349102772.326 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2012-10-01T14:46:12.327Z,1349102772.327 [LoopControl](DEBUG): Initialize LoopControlComponent.
2012-10-01T14:46:12.332Z,1349102772.332 [CTD_NeilBrown](INFO): Thread ID is 7100
2012-10-01T14:46:12.332Z,1349102772.332 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2012-10-01T14:46:12.335Z,1349102772.335 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2012-10-01T14:46:12.339Z,1349102772.339 [Turbulence_NPS](INFO): Thread ID is 7101
2012-10-01T14:46:12.340Z,1349102772.340 [Turbulence_NPS](DEBUG): Initializing Turbulence_NPS.
2012-10-01T14:46:12.340Z,1349102772.341 [Turbulence_NPS](INFO): Opening uart, block timeout 10ths=1
2012-10-01T14:46:12.347Z,1349102772.347 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2012-10-01T14:46:12.364Z,1349102772.364 [WetLabsBB2FL](INFO): Thread ID is 7102
2012-10-01T14:46:12.364Z,1349102772.365 [WetLabsBB2FL](INFO): Powering down
2012-10-01T14:46:12.392Z,1349102772.392 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2012-10-01T14:46:12.400Z,1349102772.400 [NavChartDb](INFO): Thread ID is 7103
2012-10-01T14:46:12.408Z,1349102772.408 [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-01T14:46:12.409Z,1349102772.409 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2012-10-01T14:46:12.455Z,1349102772.455 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool
2012-10-01T14:46:12.456Z,1349102772.457 [Default:GPS:A.SetSpeed](DEBUG): Construct.
2012-10-01T14:46:12.460Z,1349102772.460 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface.
2012-10-01T14:46:12.468Z,1349102772.468 [Default:Iridium:A.SetSpeed](DEBUG): Construct.
2012-10-01T14:46:12.471Z,1349102772.471 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface.
2012-10-01T14:46:12.477Z,1349102772.477 [Default:Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2012-10-01T14:46:12.486Z,1349102772.486 [Default:D.SetSpeed](DEBUG): Construct.
2012-10-01T14:46:12.489Z,1349102772.489 [Default:E.GoToSurface](DEBUG): Construct GoToSurface.
2012-10-01T14:46:12.494Z,1349102772.494 [Default:F.Wait](DEBUG): Construct Wait.
2012-10-01T14:46:12.498Z,1349102772.498 [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-01T14:46:12.502Z,1349102772.502 [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-01T14:46:12.522Z,1349102772.522 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-10-01T14:46:12.550Z,1349102772.550 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2012-10-01T14:46:12.551Z,1349102772.551 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2012-10-01T14:46:12.551Z,1349102772.551 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2012-10-01T14:46:12.551Z,1349102772.551 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2012-10-01T14:46:12.552Z,1349102772.552 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2012-10-01T14:46:12.634Z,1349102772.634 [Radio_Freewave](INFO): Powering up
2012-10-01T14:46:12.725Z,1349102772.725 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2012-10-01T14:46:12.726Z,1349102772.726 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2012-10-01T14:46:12.732Z,1349102772.732 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2012-10-01T14:46:12.738Z,1349102772.738 [MassServo](DEBUG): Initializing MassServo.
2012-10-01T14:46:12.747Z,1349102772.747 [RudderServo](DEBUG): Initializing RudderServo.
2012-10-01T14:46:12.752Z,1349102772.752 [ThrusterServo](DEBUG): Initializing EZServoServo.
2012-10-01T14:46:12.753Z,1349102772.753 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2012-10-01T14:46:14.415Z,1349102774.415 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-10-01T14:46:14.415Z,1349102774.415 [DVL_micro](INFO): Querying output modes
2012-10-01T14:46:14.416Z,1349102774.416 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-10-01T14:46:14.419Z,1349102774.419 [DVL_micro](DEBUG): cmdResponse: 01
2012-10-01T14:46:14.419Z,1349102774.419 [DVL_micro](INFO): NQ1 output enabled
2012-10-01T14:46:14.419Z,1349102774.419 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-10-01T14:46:14.830Z,1349102774.829 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2012-10-01T14:46:14.835Z,1349102774.835 [DVL_micro](INFO): pause:Powering down
2012-10-01T14:46:17.361Z,1349102777.361 [NAL9602](INFO): Powering up NAL9602
2012-10-01T14:46:27.899Z,1349102787.899 [NAL9602](INFO): NAL9602 initialized
2012-10-01T14:46:27.935Z,1349102787.935 [SBIT](IMPORTANT): Beginning Startup BIT
2012-10-01T14:46:27.938Z,1349102787.938 [CBIT](IMPORTANT): Beginning GF scan
2012-10-01T14:46:28.687Z,1349102788.687 [Radio_Freewave](INFO): Powering down
2012-10-01T14:46:29.176Z,1349102789.175 [Radio_Freewave](INFO): Powering up
2012-10-01T14:46:41.104Z,1349102801.104 [SBIT](FAULT): Mass: EXPECTED:0.004800 ACTUAL:-0.001032
2012-10-01T14:46:54.303Z,1349102814.303 [CBIT](IMPORTANT): No ground fault detected
2012-10-01T14:47:18.485Z,1349102838.485 [CommandLine](IMPORTANT): got command set NAL9602.latitude_fix 36.799999 degree
2012-10-01T14:47:18.486Z,1349102838.486 [CommandLine](IMPORTANT): got command set NAL9602.longitude_fix -121.779999 degree
2012-10-01T14:47:18.634Z,1349102838.634 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2012-10-01T14:47:21.503Z,1349102841.503 [SBIT](CRITICAL): SBIT FAILED
2012-10-01T14:47:21.902Z,1349102841.902 [MissionManager](IMPORTANT): Started mission Startup
2012-10-01T14:47:21.903Z,1349102841.903 [Startup] Running Loop=1
2012-10-01T14:47:21.903Z,1349102841.903 [Startup](INFO): Aggregate::initialize Startup
2012-10-01T14:47:21.903Z,1349102841.903 [Startup:A.GoToSurface] Running Loop=1
2012-10-01T14:47:21.903Z,1349102841.903 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-01T14:47:21.909Z,1349102841.908 [Startup:StartupSatComms] Running Loop=1
2012-10-01T14:47:21.909Z,1349102841.909 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2012-10-01T14:47:21.909Z,1349102841.909 [Startup:StartupSatComms:A] Running Loop=1
2012-10-01T14:47:22.310Z,1349102842.310 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-10-01T14:47:26.201Z,1349102846.201 [CommandLine](IMPORTANT): got command set NAL9602.latitude_fix 36.799999 degree
2012-10-01T14:47:26.202Z,1349102846.202 [CommandLine](IMPORTANT): got command set NAL9602.longitude_fix -121.779999 degree
2012-10-01T14:47:26.327Z,1349102846.327 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2012-10-01T14:47:26.393Z,1349102846.393 [Startup:StartupSatComms:A] Stopped
2012-10-01T14:47:26.393Z,1349102846.393 [Startup:StartupSatComms:B] Running Loop=1
2012-10-01T14:47:26.756Z,1349102846.756 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2012-10-01T14:47:32.841Z,1349102852.841 [CommandLine](IMPORTANT): got command set NAL9602.latitude_fix 36.799999 degree
2012-10-01T14:47:32.842Z,1349102852.842 [CommandLine](IMPORTANT): got command set NAL9602.longitude_fix -121.779999 degree
2012-10-01T14:47:32.985Z,1349102852.985 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2012-10-01T14:47:33.627Z,1349102853.627 [Startup:StartupSatComms:B] Stopped
2012-10-01T14:47:33.627Z,1349102853.628 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2012-10-01T14:47:33.628Z,1349102853.628 [Startup:StartupSatComms] Stopped
2012-10-01T14:47:33.628Z,1349102853.628 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2012-10-01T14:47:33.628Z,1349102853.628 [Startup](INFO): Completed Startup
2012-10-01T14:47:33.629Z,1349102853.629 [Startup] Stopped
2012-10-01T14:47:33.629Z,1349102853.629 [Startup](INFO): Aggregate::uninitialize Startup
2012-10-01T14:47:33.629Z,1349102853.629 [Startup:A.GoToSurface] Stopped
2012-10-01T14:47:33.629Z,1349102853.629 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-01T14:47:33.873Z,1349102853.873 [MissionManager](IMPORTANT): Started mission Default
2012-10-01T14:47:33.873Z,1349102853.873 [Default] Running Loop=1
2012-10-01T14:47:33.873Z,1349102853.873 [Default](INFO): Aggregate::initialize Default
2012-10-01T14:47:33.873Z,1349102853.873 [Default:D.SetSpeed] Running Loop=1
2012-10-01T14:47:33.873Z,1349102853.873 [Default:D.SetSpeed](DEBUG): Initialize.
2012-10-01T14:47:33.873Z,1349102853.873 [Default:E.GoToSurface] Running Loop=1
2012-10-01T14:47:33.873Z,1349102853.873 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-01T14:47:33.873Z,1349102853.874 [Default:Iridium] Running Loop=1
2012-10-01T14:47:33.874Z,1349102853.874 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-10-01T14:47:33.874Z,1349102853.874 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-10-01T14:47:33.874Z,1349102853.874 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-10-01T14:47:33.874Z,1349102853.874 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-10-01T14:47:33.874Z,1349102853.874 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-01T14:47:33.875Z,1349102853.875 [Default:E.GoToSurface] Running Loop=1
2012-10-01T14:47:33.880Z,1349102853.880 [Default:D.SetSpeed] Running Loop=1
2012-10-01T14:47:33.890Z,1349102853.890 [Default:Iridium:B.GoToSurface] Stopped
2012-10-01T14:47:33.890Z,1349102853.890 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-01T14:47:33.890Z,1349102853.890 [Default:Iridium:Read_Iridium] Running Loop=1
2012-10-01T14:47:33.891Z,1349102853.891 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-10-01T14:47:33.895Z,1349102853.895 [Default:GPS] Running Loop=1
2012-10-01T14:47:33.895Z,1349102853.895 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-10-01T14:47:33.896Z,1349102853.895 [Default:GPS:A.SetSpeed] Running Loop=1
2012-10-01T14:47:33.896Z,1349102853.895 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-10-01T14:47:33.896Z,1349102853.896 [Default:GPS:B.GoToSurface] Running Loop=1
2012-10-01T14:47:33.896Z,1349102853.896 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-01T14:47:33.901Z,1349102853.901 [Default:GPS:B.GoToSurface] Stopped
2012-10-01T14:47:33.901Z,1349102853.901 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-01T14:47:33.901Z,1349102853.901 [Default:GPS:Read_GPS] Running Loop=1
2012-10-01T14:47:33.901Z,1349102853.901 [Default:GPS:A.SetSpeed] Running Loop=1
2012-10-01T14:47:34.265Z,1349102854.265 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2012-10-01T14:47:34.268Z,1349102854.268 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-10-01T14:47:53.855Z,1349102873.855 [CommandLine](IMPORTANT): got command run ./Missions/Science/science_to_67_70.xml
2012-10-01T14:47:53.855Z,1349102873.855 [MissionManager](INFO): Loading Mission: ./Missions/Science/science_to_67_70.xml
2012-10-01T14:47:53.919Z,1349102873.919 [MissionManager](INFO): DefineArg science_to_67_70.MinAltitude = 10.000000 m
2012-10-01T14:47:53.922Z,1349102873.922 [MissionManager](INFO): DefineArg science_to_67_70.MaxDepth = 105.000000 m
2012-10-01T14:47:53.925Z,1349102873.925 [MissionManager](INFO): DefineArg science_to_67_70.MinOffshore = 2000.000000 m
2012-10-01T14:47:53.928Z,1349102873.928 [MissionManager](INFO): DefineArg science_to_67_70.YoYoMinDepth = 3.000000 m
2012-10-01T14:47:53.931Z,1349102873.931 [MissionManager](INFO): DefineArg science_to_67_70.YoYoMaxDepth = 100.000000 m
2012-10-01T14:47:53.933Z,1349102873.934 [MissionManager](INFO): DefineArg science_to_67_70.YoYoUpPitch = 20.000000 arcdeg
2012-10-01T14:47:53.937Z,1349102873.937 [MissionManager](INFO): DefineArg science_to_67_70.YoYoDownPitch = -15.000000 arcdeg
2012-10-01T14:47:53.939Z,1349102873.940 [MissionManager](INFO): DefineArg science_to_67_70.WptLat = 36.120000 arcdeg
2012-10-01T14:47:53.943Z,1349102873.943 [MissionManager](INFO): DefineArg science_to_67_70.WptLon = -123.486000 arcdeg
2012-10-01T14:47:53.946Z,1349102873.945 [MissionManager](INFO): DefineArg science_to_67_70.NeedCommsTime = 120.000000 min
2012-10-01T14:47:53.948Z,1349102873.948 [MissionManager](INFO): DefineArg science_to_67_70.Speed = 1.000000 m/s
2012-10-01T14:47:53.951Z,1349102873.951 [MissionManager](INFO): DefineArg science_to_67_70.Timeout = 36.000000 h
2012-10-01T14:47:53.955Z,1349102873.955 [MissionManager](INFO): DefineArg science_to_67_70.BuoyancyNeutral = 0.000330 n/a
2012-10-01T14:47:53.957Z,1349102873.957 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2012-10-01T14:47:53.965Z,1349102873.965 [science_to_67_70:AltEnvWrapper:B.Wait](DEBUG): Construct Wait.
2012-10-01T14:47:53.966Z,1349102873.966 [science_to_67_70:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2012-10-01T14:47:53.994Z,1349102873.994 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2012-10-01T14:47:54.002Z,1349102874.002 [science_to_67_70:OffshoreWrapper:B.Wait](DEBUG): Construct Wait.
2012-10-01T14:47:54.003Z,1349102874.003 [MissionManager](INFO): Inserting Stack: Missions/Insert/Surface.xml
2012-10-01T14:47:54.025Z,1349102874.025 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.SurfaceDepthRate = nan m/s
2012-10-01T14:47:54.028Z,1349102874.028 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.SurfacePitch = nan arcdeg
2012-10-01T14:47:54.031Z,1349102874.031 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.SurfaceSpeed = 0.500000 m/s
2012-10-01T14:47:54.034Z,1349102874.034 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.IridiumTimeout = 30.000000 min
2012-10-01T14:47:54.036Z,1349102874.036 [science_to_67_70:SURFACECOMMS:A.GoToSurface](DEBUG): Construct GoToSurface.
2012-10-01T14:47:54.047Z,1349102874.047 [MissionManager](INFO): RedefineArg science_to_67_70:SURFACECOMMS.SurfacePitch = 20.000000 arcdeg
2012-10-01T14:47:54.053Z,1349102874.053 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2012-10-01T14:47:54.116Z,1349102874.116 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleISUS = 0 bool
2012-10-01T14:47:54.119Z,1349102874.119 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleRSSI = 1 bool
2012-10-01T14:47:54.122Z,1349102874.122 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleADCP = 1 bool
2012-10-01T14:47:54.125Z,1349102874.125 [MissionManager](INFO): DefineArg science_to_67_70:Science.PeakDetectChlActive = 0 bool
2012-10-01T14:47:54.128Z,1349102874.128 [MissionManager](INFO): DefineArg science_to_67_70:Science.PeakDetectNO3Active = 0 bool
2012-10-01T14:47:54.131Z,1349102874.131 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleAanderaaO2 = 0.000000 n/a
2012-10-01T14:47:54.141Z,1349102874.141 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleNeilBrown = 1.000000 n/a
2012-10-01T14:47:54.153Z,1349102874.153 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleTurbulence_NPS = 1.000000 n/a
2012-10-01T14:47:54.162Z,1349102874.162 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleWetLabsBB2FL = 1.000000 n/a
2012-10-01T14:47:54.170Z,1349102874.170 [MissionManager](INFO): DefineArg science_to_67_70:Science.EnabledDVL = 1.000000 n/a
2012-10-01T14:47:54.172Z,1349102874.172 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChl = 0.000000 ug/l
2012-10-01T14:47:54.175Z,1349102874.175 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChlDepth = 0.000000 m
2012-10-01T14:47:54.178Z,1349102874.178 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChlLatitude = nan arcdeg
2012-10-01T14:47:54.190Z,1349102874.190 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChlLongitude = nan arcdeg
2012-10-01T14:47:54.192Z,1349102874.193 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3 = 0.000000 umol/l
2012-10-01T14:47:54.196Z,1349102874.195 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3Depth = 0.000000 m
2012-10-01T14:47:54.211Z,1349102874.211 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3Latitude = nan arcdeg
2012-10-01T14:47:54.214Z,1349102874.214 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3Longitude = nan arcdeg
2012-10-01T14:47:54.237Z,1349102874.237 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2012-10-01T14:47:54.257Z,1349102874.257 [science_to_67_70:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2012-10-01T14:47:54.279Z,1349102874.279 [MissionManager](INFO): RedefineArg science_to_67_70:Science.SampleISUS = 1 bool
2012-10-01T14:47:54.280Z,1349102874.280 [MissionManager](INFO): RedefineArg science_to_67_70:Science.PeakDetectChlActive = 1 bool
2012-10-01T14:47:54.281Z,1349102874.281 [science_to_67_70:G.Buoyancy](DEBUG): Construct Buoyancy.
2012-10-01T14:47:54.285Z,1349102874.285 [science_to_67_70:H.SetSpeed](DEBUG): Construct.
2012-10-01T14:47:54.287Z,1349102874.287 [science_to_67_70:I.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2012-10-01T14:47:54.309Z,1349102874.309 [science_to_67_70:J.YoYo](DEBUG): Construct YoYo.
2012-10-01T14:47:54.326Z,1349102874.326 [science_to_67_70:Wpt.Point](DEBUG): Construct.
2012-10-01T14:47:54.365Z,1349102874.365 [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-01T14:47:54.365Z,1349102874.365 [CommandLine](IMPORTANT): Running ./Missions/Science/science_to_67_70.xml
2012-10-01T14:47:55.091Z,1349102875.091 [Default] Stopped
2012-10-01T14:47:55.091Z,1349102875.091 [Default](INFO): Aggregate::uninitialize Default
2012-10-01T14:47:55.091Z,1349102875.091 [Default:GPS] Stopped
2012-10-01T14:47:55.091Z,1349102875.091 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-10-01T14:47:55.091Z,1349102875.091 [Default:GPS:A.SetSpeed] Stopped
2012-10-01T14:47:55.091Z,1349102875.091 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-10-01T14:47:55.091Z,1349102875.092 [Default:GPS:Read_GPS] Stopped
2012-10-01T14:47:55.091Z,1349102875.092 [Default:Iridium] Stopped
2012-10-01T14:47:55.092Z,1349102875.092 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-10-01T14:47:55.092Z,1349102875.092 [Default:Iridium:A.SetSpeed] Stopped
2012-10-01T14:47:55.092Z,1349102875.092 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-10-01T14:47:55.092Z,1349102875.092 [Default:Iridium:Read_Iridium] Stopped
2012-10-01T14:47:55.092Z,1349102875.092 [Default:D.SetSpeed] Stopped
2012-10-01T14:47:55.092Z,1349102875.092 [Default:D.SetSpeed](DEBUG): Uninitialize.
2012-10-01T14:47:55.092Z,1349102875.092 [Default:E.GoToSurface] Stopped
2012-10-01T14:47:55.092Z,1349102875.092 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-01T14:47:55.092Z,1349102875.092 [MissionManager](IMPORTANT): Started mission science_to_67_70
2012-10-01T14:47:55.092Z,1349102875.092 [science_to_67_70] Running Loop=1
2012-10-01T14:47:55.093Z,1349102875.092 [science_to_67_70](INFO): Aggregate::initialize science_to_67_70
2012-10-01T14:47:55.093Z,1349102875.093 [science_to_67_70:B.DepthEnvelope] Running Loop=1
2012-10-01T14:47:55.093Z,1349102875.093 [science_to_67_70:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2012-10-01T14:47:55.094Z,1349102875.094 [science_to_67_70:Science] Running Loop=1
2012-10-01T14:47:55.094Z,1349102875.094 [science_to_67_70:Science](INFO): Aggregate::initialize science_to_67_70:Science
2012-10-01T14:47:55.094Z,1349102875.094 [science_to_67_70:Science:A] Running Loop=1
2012-10-01T14:47:55.094Z,1349102875.094 [science_to_67_70:Science:B] Running Loop=1
2012-10-01T14:47:55.094Z,1349102875.094 [science_to_67_70:Science:C] Running Loop=1
2012-10-01T14:47:55.094Z,1349102875.094 [science_to_67_70:Science:D] Running Loop=1
2012-10-01T14:47:55.094Z,1349102875.094 [science_to_67_70:Science:E] Running Loop=1
2012-10-01T14:47:55.094Z,1349102875.094 [science_to_67_70:Science:F] Running Loop=1
2012-10-01T14:47:55.094Z,1349102875.095 [science_to_67_70:Science:G] Running Loop=1
2012-10-01T14:47:55.095Z,1349102875.095 [science_to_67_70:G.Buoyancy] Running Loop=1
2012-10-01T14:47:55.095Z,1349102875.095 [science_to_67_70:G.Buoyancy](DEBUG): Initialize Buoyancy Component.
2012-10-01T14:47:55.095Z,1349102875.095 [science_to_67_70:H.SetSpeed] Running Loop=1
2012-10-01T14:47:55.095Z,1349102875.095 [science_to_67_70:H.SetSpeed](DEBUG): Initialize.
2012-10-01T14:47:55.095Z,1349102875.095 [science_to_67_70:I.DepthEnvelope] Running Loop=1
2012-10-01T14:47:55.095Z,1349102875.095 [science_to_67_70:I.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2012-10-01T14:47:55.096Z,1349102875.096 [science_to_67_70:J.YoYo] Running Loop=1
2012-10-01T14:47:55.096Z,1349102875.096 [science_to_67_70:J.YoYo](DEBUG): Initialize YoYoComponent.
2012-10-01T14:47:55.096Z,1349102875.096 [science_to_67_70:SURFACECOMMS] Running Loop=1
2012-10-01T14:47:55.097Z,1349102875.096 [science_to_67_70:SURFACECOMMS](INFO): Aggregate::initialize science_to_67_70:SURFACECOMMS
2012-10-01T14:47:55.097Z,1349102875.097 [science_to_67_70:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-10-01T14:47:55.097Z,1349102875.097 [science_to_67_70:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-01T14:47:55.098Z,1349102875.098 [science_to_67_70:J.YoYo] Running Loop=1
2012-10-01T14:47:55.103Z,1349102875.103 [science_to_67_70:I.DepthEnvelope] Running Loop=1
2012-10-01T14:47:55.108Z,1349102875.108 [science_to_67_70:H.SetSpeed] Running Loop=1
2012-10-01T14:47:55.112Z,1349102875.112 [science_to_67_70:G.Buoyancy] Running Loop=1
2012-10-01T14:47:55.117Z,1349102875.117 [science_to_67_70:Science] Running Loop=1
2012-10-01T14:47:55.118Z,1349102875.118 [science_to_67_70:Science:PeakDetectChl] Running Loop=1
2012-10-01T14:47:55.118Z,1349102875.118 [science_to_67_70:Science:PeakDetectChl](INFO): Aggregate::initialize science_to_67_70:Science:PeakDetectChl
2012-10-01T14:47:55.118Z,1349102875.118 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2012-10-01T14:47:55.118Z,1349102875.118 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth](INFO): Initialize.
2012-10-01T14:47:55.124Z,1349102875.124 [science_to_67_70:Science:G] Running Loop=1
2012-10-01T14:47:55.125Z,1349102875.125 [science_to_67_70:Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2012-10-01T14:47:55.126Z,1349102875.126 [science_to_67_70:Science:F] Running Loop=1
2012-10-01T14:47:55.127Z,1349102875.127 [science_to_67_70:Science:F](DEBUG): Initialize ReadDataComponent to sense DVL_micro.Beam1RSSI
2012-10-01T14:47:55.128Z,1349102875.128 [science_to_67_70:Science:E] Running Loop=1
2012-10-01T14:47:55.129Z,1349102875.129 [science_to_67_70:Science:E](DEBUG): Initialize ReadDataComponent to sense DVL_micro.AdcpXWaterVelocity
2012-10-01T14:47:55.130Z,1349102875.130 [science_to_67_70:Science:D] Running Loop=1
2012-10-01T14:47:55.131Z,1349102875.131 [science_to_67_70:Science:D](DEBUG): Initialize ReadDataComponent to sense Turbulence_NPS.MicroCond
2012-10-01T14:47:55.132Z,1349102875.132 [science_to_67_70:Science:C] Running Loop=1
2012-10-01T14:47:55.133Z,1349102875.133 [science_to_67_70:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_temperature
2012-10-01T14:47:55.133Z,1349102875.133 [science_to_67_70:Science:C](DEBUG): Initialize ReadDataComponent to sense sea_water_salinity
2012-10-01T14:47:55.135Z,1349102875.135 [science_to_67_70:Science:B] Running Loop=1
2012-10-01T14:47:55.135Z,1349102875.135 [science_to_67_70:Science:B](DEBUG): Initialize ReadDataComponent to sense mole_concentration_of_nitrate_in_sea_water
2012-10-01T14:47:55.142Z,1349102875.142 [science_to_67_70:SURFACECOMMS:B] Running Loop=1
2012-10-01T14:47:55.142Z,1349102875.142 [science_to_67_70:SURFACECOMMS:B](INFO): Aggregate::initialize science_to_67_70:SURFACECOMMS:B
2012-10-01T14:47:55.142Z,1349102875.142 [science_to_67_70:SURFACECOMMS:B:A] Running Loop=1
2012-10-01T14:47:55.142Z,1349102875.142 [science_to_67_70:OffshoreWrapper] Running Loop=1
2012-10-01T14:47:55.143Z,1349102875.143 [science_to_67_70:OffshoreWrapper](INFO): Aggregate::initialize science_to_67_70:OffshoreWrapper
2012-10-01T14:47:55.143Z,1349102875.143 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope] Running Loop=1
2012-10-01T14:47:55.143Z,1349102875.143 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2012-10-01T14:47:55.143Z,1349102875.143 [science_to_67_70:OffshoreWrapper:B.Wait] Running Loop=1
2012-10-01T14:47:55.143Z,1349102875.143 [science_to_67_70:OffshoreWrapper:B.Wait](DEBUG): Initialize Wait Component.
2012-10-01T14:47:55.147Z,1349102875.148 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope] Running Loop=1
2012-10-01T14:47:55.161Z,1349102875.161 [science_to_67_70:B.DepthEnvelope] Running Loop=1
2012-10-01T14:47:55.166Z,1349102875.166 [science_to_67_70:AltEnvWrapper] Running Loop=1
2012-10-01T14:47:55.166Z,1349102875.166 [science_to_67_70:AltEnvWrapper](INFO): Aggregate::initialize science_to_67_70:AltEnvWrapper
2012-10-01T14:47:55.166Z,1349102875.166 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope] Running Loop=1
2012-10-01T14:47:55.166Z,1349102875.166 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2012-10-01T14:47:55.167Z,1349102875.167 [science_to_67_70:AltEnvWrapper:B.Wait] Running Loop=1
2012-10-01T14:47:55.167Z,1349102875.167 [science_to_67_70:AltEnvWrapper:B.Wait](DEBUG): Initialize Wait Component.
2012-10-01T14:47:55.172Z,1349102875.172 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope] Running Loop=1
2012-10-01T14:47:55.179Z,1349102875.179 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2012-10-01T14:47:55.204Z,1349102875.204 [Turbulence_NPS](INFO): Opening uart, block timeout 10ths=1
2012-10-01T14:47:55.206Z,1349102875.205 [DVL_micro](INFO): RSSI requested
2012-10-01T14:47:55.206Z,1349102875.206 [DVL_micro](INFO): ADCP requested
2012-10-01T14:47:55.206Z,1349102875.206 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-10-01T14:47:55.207Z,1349102875.207 [DVL_micro](INFO): resume:Powering up
2012-10-01T14:47:55.207Z,1349102875.207 [DVL_micro](INFO): Cycling power to configure device.
2012-10-01T14:47:55.243Z,1349102875.243 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=4
2012-10-01T14:47:55.243Z,1349102875.243 [WetLabsBB2FL](INFO): Powering up
2012-10-01T14:47:55.622Z,1349102875.622 [science_to_67_70:J.YoYo] Preempted
2012-10-01T14:47:55.622Z,1349102875.622 [science_to_67_70:I.DepthEnvelope] Preempted
2012-10-01T14:47:55.622Z,1349102875.622 [science_to_67_70:H.SetSpeed] Preempted
2012-10-01T14:47:55.622Z,1349102875.622 [science_to_67_70:G.Buoyancy] Preempted
2012-10-01T14:47:55.622Z,1349102875.622 [science_to_67_70:Science] Preempted
2012-10-01T14:47:55.622Z,1349102875.622 [science_to_67_70:Science:B] Preempted
2012-10-01T14:47:55.622Z,1349102875.622 [science_to_67_70:Science:C] Preempted
2012-10-01T14:47:55.622Z,1349102875.622 [science_to_67_70:Science:D] Preempted
2012-10-01T14:47:55.622Z,1349102875.622 [science_to_67_70:Science:E] Preempted
2012-10-01T14:47:55.622Z,1349102875.622 [science_to_67_70:Science:F] Preempted
2012-10-01T14:47:55.622Z,1349102875.622 [science_to_67_70:Science:G] Preempted
2012-10-01T14:47:55.623Z,1349102875.624 [science_to_67_70:SURFACECOMMS:B:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-10-01T14:47:56.488Z,1349102876.488 [WetLabsBB2FL](INFO): Powering down
2012-10-01T14:47:57.215Z,1349102877.215 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-10-01T14:47:57.215Z,1349102877.215 [DVL_micro](INFO): Querying output modes
2012-10-01T14:47:57.215Z,1349102877.216 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-10-01T14:47:57.219Z,1349102877.219 [DVL_micro](DEBUG): cmdResponse: 01
2012-10-01T14:47:57.219Z,1349102877.219 [DVL_micro](INFO): NQ1 output enabled
2012-10-01T14:47:57.219Z,1349102877.219 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-10-01T14:47:57.223Z,1349102877.223 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2012-10-01T14:47:59.751Z,1349102879.751 [DVL_micro](DEBUG): NQ1
2012-10-01T14:48:02.287Z,1349102882.287 [DVL_micro](DEBUG): NQ1
2012-10-01T14:48:04.819Z,1349102884.819 [DVL_micro](DEBUG): NQ1
2012-10-01T14:48:07.371Z,1349102887.371 [DVL_micro](DEBUG): NQ1
2012-10-01T14:48:09.493Z,1349102889.493 [CommandLine](IMPORTANT): got command set NAL9602.latitude_fix 36.799999 degree
2012-10-01T14:48:09.493Z,1349102889.493 [CommandLine](IMPORTANT): got command set NAL9602.longitude_fix -121.779999 degree
2012-10-01T14:48:09.619Z,1349102889.619 [science_to_67_70:SURFACECOMMS:B:A] Stopped
2012-10-01T14:48:09.619Z,1349102889.619 [science_to_67_70:SURFACECOMMS:B:B] Running Loop=1
2012-10-01T14:48:09.699Z,1349102889.699 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2012-10-01T14:48:09.907Z,1349102889.907 [DVL_micro](DEBUG): NQ1
2012-10-01T14:48:10.016Z,1349102890.016 [science_to_67_70:SURFACECOMMS:B:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2012-10-01T14:48:10.017Z,1349102890.017 [science_to_67_70:SURFACECOMMS:B:B] Stopped
2012-10-01T14:48:10.018Z,1349102890.018 [science_to_67_70:SURFACECOMMS:B:C] Running Loop=1
2012-10-01T14:48:10.409Z,1349102890.409 [science_to_67_70:SURFACECOMMS:B:C](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-10-01T14:48:12.451Z,1349102892.451 [DVL_micro](DEBUG): NQ1
2012-10-01T14:48:14.987Z,1349102894.987 [DVL_micro](DEBUG): NQ1
2012-10-01T14:48:17.527Z,1349102897.527 [DVL_micro](DEBUG): NQ1
2012-10-01T14:48:20.067Z,1349102900.067 [DVL_micro](DEBUG): NQ1
2012-10-01T14:48:22.603Z,1349102902.603 [DVL_micro](DEBUG): NQ1
2012-10-01T14:48:25.139Z,1349102905.139 [DVL_micro](DEBUG): NQ1
2012-10-01T14:48:27.679Z,1349102907.679 [DVL_micro](DEBUG): NQ1
2012-10-01T14:48:30.223Z,1349102910.223 [DVL_micro](DEBUG): NQ1
2012-10-01T14:48:32.763Z,1349102912.763 [DVL_micro](DEBUG): NQ1
2012-10-01T14:48:35.307Z,1349102915.307 [DVL_micro](DEBUG): NQ1
2012-10-01T14:48:37.843Z,1349102917.843 [DVL_micro](DEBUG): NQ1
2012-10-01T14:48:40.383Z,1349102920.383 [DVL_micro](DEBUG): NQ1
2012-10-01T14:48:42.919Z,1349102922.919 [DVL_micro](DEBUG): NQ1
2012-10-01T14:48:45.455Z,1349102925.455 [DVL_micro](DEBUG): NQ1
2012-10-01T14:48:47.987Z,1349102927.987 [DVL_micro](DEBUG): NQ1
2012-10-01T14:48:48.316Z,1349102928.316 [CommandLine](IMPORTANT): got command set NAL9602.latitude_fix 36.799999 degree
2012-10-01T14:48:48.317Z,1349102928.317 [CommandLine](IMPORTANT): got command set NAL9602.longitude_fix -121.779999 degree
2012-10-01T14:48:48.490Z,1349102928.490 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2012-10-01T14:48:48.550Z,1349102928.550 [science_to_67_70:SURFACECOMMS:B:C] Stopped
2012-10-01T14:48:48.550Z,1349102928.550 [science_to_67_70:SURFACECOMMS:B](INFO): Completed science_to_67_70:SURFACECOMMS:B
2012-10-01T14:48:48.550Z,1349102928.550 [science_to_67_70:SURFACECOMMS:B] Stopped
2012-10-01T14:48:48.551Z,1349102928.551 [science_to_67_70:SURFACECOMMS:B](INFO): Aggregate::uninitialize science_to_67_70:SURFACECOMMS:B
2012-10-01T14:48:48.552Z,1349102928.552 [science_to_67_70:SURFACECOMMS](INFO): Completed science_to_67_70:SURFACECOMMS
2012-10-01T14:48:48.552Z,1349102928.552 [science_to_67_70:SURFACECOMMS] Stopped
2012-10-01T14:48:48.552Z,1349102928.552 [science_to_67_70:SURFACECOMMS](INFO): Aggregate::uninitialize science_to_67_70:SURFACECOMMS
2012-10-01T14:48:48.552Z,1349102928.552 [science_to_67_70:SURFACECOMMS:A.GoToSurface] Stopped
2012-10-01T14:48:48.552Z,1349102928.552 [science_to_67_70:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-01T14:48:48.552Z,1349102928.552 [science_to_67_70:Wpt.Point] Running Loop=1
2012-10-01T14:48:48.552Z,1349102928.552 [science_to_67_70:Wpt.Point](DEBUG): Initialize.
2012-10-01T14:48:48.955Z,1349102928.955 [science_to_67_70:J.YoYo] Running Loop=1
2012-10-01T14:48:48.956Z,1349102928.956 [science_to_67_70:I.DepthEnvelope] Running Loop=1
2012-10-01T14:48:48.957Z,1349102928.957 [science_to_67_70:H.SetSpeed] Running Loop=1
2012-10-01T14:48:48.957Z,1349102928.957 [science_to_67_70:G.Buoyancy] Running Loop=1
2012-10-01T14:48:48.957Z,1349102928.957 [science_to_67_70:Science] Running Loop=1
2012-10-01T14:48:48.958Z,1349102928.958 [science_to_67_70:Science:G] Running Loop=1
2012-10-01T14:48:48.958Z,1349102928.958 [science_to_67_70:Science:F] Running Loop=1
2012-10-01T14:48:48.959Z,1349102928.959 [science_to_67_70:Science:E] Running Loop=1
2012-10-01T14:48:48.959Z,1349102928.959 [science_to_67_70:Science:D] Running Loop=1
2012-10-01T14:48:48.959Z,1349102928.959 [science_to_67_70:Science:C] Running Loop=1
2012-10-01T14:48:48.959Z,1349102928.959 [science_to_67_70:Science:B] Running Loop=1
2012-10-01T14:48:49.208Z,1349102929.208 [Turbulence_NPS](INFO): Opening uart, block timeout 10ths=1
2012-10-01T14:48:49.212Z,1349102929.212 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=4
2012-10-01T14:48:49.212Z,1349102929.212 [WetLabsBB2FL](INFO): Powering up
2012-10-01T14:48:49.232Z,1349102929.232 [CTD_NeilBrown](INFO): Opening uart, block timeout 10ths=4
2012-10-01T14:48:50.080Z,1349102930.080 [DVL_micro](INFO): RSSI requested
2012-10-01T14:48:50.080Z,1349102930.080 [DVL_micro](INFO): ADCP requested
2012-10-01T14:48:50.080Z,1349102930.080 [DVL_micro](INFO): Cycling power to configure device.
2012-10-01T14:48:50.828Z,1349102930.828 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.219140 psu
2012-10-01T14:48:51.028Z,1349102931.028 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.483960 psu
2012-10-01T14:48:51.231Z,1349102931.231 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.372600 psu
2012-10-01T14:48:51.433Z,1349102931.433 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.244010 psu
2012-10-01T14:48:51.635Z,1349102931.635 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.579600 psu
2012-10-01T14:48:51.835Z,1349102931.835 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.225350 psu
2012-10-01T14:48:52.056Z,1349102932.056 [CTD_NeilBrown](ERROR): Salinity reading out of range: -14.394970 psu
2012-10-01T14:48:52.087Z,1349102932.088 [DVL_micro](INFO): Opening uart, block timeout 10ths=20
2012-10-01T14:48:52.088Z,1349102932.088 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD1616 00 0

2012-10-01T14:48:52.123Z,1349102932.123 [CTD_NeilBrown](ERROR): Salinity reading out of range: -13.660690 psu
2012-10-01T14:48:52.124Z,1349102932.124 [CTD_NeilBrown](ERROR): Pressure reading out of range: -12.900001 decibar
2012-10-01T14:48:52.303Z,1349102932.303 [DVL_micro](DEBUG): cmdResponse: 00
2012-10-01T14:48:52.303Z,1349102932.303 [DVL_micro](INFO): Enabling RSSI output
2012-10-01T14:48:52.303Z,1349102932.303 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 16 7

2012-10-01T14:48:52.423Z,1349102932.423 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.643010 psu
2012-10-01T14:48:52.507Z,1349102932.507 [DVL_micro](DEBUG): cmdResponse: 16
2012-10-01T14:48:52.507Z,1349102932.507 [DVL_micro](INFO): Enabling ADCP output
2012-10-01T14:48:52.507Z,1349102932.507 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 03 3

2012-10-01T14:48:52.624Z,1349102932.624 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.215410 psu
2012-10-01T14:48:52.699Z,1349102932.699 [DVL_micro](DEBUG): cmdResponse: 03 16
2012-10-01T14:48:52.699Z,1349102932.699 [DVL_micro](INFO): Enabling NQ1 output
2012-10-01T14:48:52.699Z,1349102932.699 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0505 01 1

2012-10-01T14:48:52.828Z,1349102932.828 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.109770 psu
2012-10-01T14:48:52.895Z,1349102932.895 [DVL_micro](DEBUG): cmdResponse: 01 03 16
2012-10-01T14:48:52.895Z,1349102932.895 [DVL_micro](INFO): Querying output modes
2012-10-01T14:48:52.895Z,1349102932.895 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD0606

2012-10-01T14:48:52.900Z,1349102932.900 [DVL_micro](DEBUG): cmdResponse: 01 03 16
2012-10-01T14:48:52.900Z,1349102932.900 [DVL_micro](INFO): NQ1 output enabled
2012-10-01T14:48:52.900Z,1349102932.900 [DVL_micro](INFO): RSSI output enabled
2012-10-01T14:48:52.900Z,1349102932.900 [DVL_micro](INFO): ADCP output enabled
2012-10-01T14:48:52.900Z,1349102932.900 [DVL_micro](DEBUG): commandDVL: #&!LQNQ.COMD2525

2012-10-01T14:48:52.907Z,1349102932.907 [DVL_micro](DEBUG): cmdResponse: AUTO_VEL_ON 
2012-10-01T14:48:53.029Z,1349102933.029 [CTD_NeilBrown](ERROR): Salinity reading out of range: -4.118710 psu
2012-10-01T14:48:53.044Z,1349102933.044 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=8
2012-10-01T14:48:53.099Z,1349102933.099 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=60
2012-10-01T14:48:53.151Z,1349102933.151 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=16
2012-10-01T14:48:53.203Z,1349102933.203 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-10-01T14:48:53.228Z,1349102933.228 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.549750 psu
2012-10-01T14:48:53.432Z,1349102933.432 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.851790 psu
2012-10-01T14:48:53.483Z,1349102933.483 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24
2012-10-01T14:48:53.587Z,1349102933.587 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28
2012-10-01T14:48:53.632Z,1349102933.632 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.859810 psu
2012-10-01T14:48:53.688Z,1349102933.688 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=0
2012-10-01T14:48:53.791Z,1349102933.792 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-10-01T14:48:53.835Z,1349102933.835 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.803230 psu
2012-10-01T14:48:53.945Z,1349102933.945 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=168
2012-10-01T14:48:53.983Z,1349102933.983 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=28
2012-10-01T14:48:54.036Z,1349102934.036 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.098180 psu
2012-10-01T14:48:54.043Z,1349102934.043 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=81
2012-10-01T14:48:54.143Z,1349102934.143 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-10-01T14:48:54.237Z,1349102934.237 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.562520 psu
2012-10-01T14:48:54.371Z,1349102934.371 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24
2012-10-01T14:48:54.440Z,1349102934.440 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.306160 psu
2012-10-01T14:48:54.475Z,1349102934.475 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28
2012-10-01T14:48:54.575Z,1349102934.575 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=0
2012-10-01T14:48:54.639Z,1349102934.639 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.589700 psu
2012-10-01T14:48:54.679Z,1349102934.679 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-10-01T14:48:54.839Z,1349102934.839 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.673570 psu
2012-10-01T14:48:55.027Z,1349102935.027 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=24
2012-10-01T14:48:55.043Z,1349102935.043 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.535450 psu
2012-10-01T14:48:55.175Z,1349102935.175 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=28
2012-10-01T14:48:55.243Z,1349102935.243 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.262320 psu
2012-10-01T14:48:55.316Z,1349102935.316 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=0
2012-10-01T14:48:55.444Z,1349102935.444 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.476780 psu
2012-10-01T14:48:55.463Z,1349102935.463 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4
2012-10-01T14:48:55.647Z,1349102935.647 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.853530 psu
2012-10-01T14:48:55.784Z,1349102935.784 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=28
2012-10-01T14:48:55.848Z,1349102935.848 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.176440 psu
2012-10-01T14:48:55.899Z,1349102935.899 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4
2012-10-01T14:48:56.015Z,1349102936.015 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=12
2012-10-01T14:48:56.048Z,1349102936.048 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.520940 psu
2012-10-01T14:48:56.131Z,1349102936.131 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4
2012-10-01T14:48:56.251Z,1349102936.251 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.829610 psu
2012-10-01T14:48:56.451Z,1349102936.451 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.971840 psu
2012-10-01T14:48:56.640Z,1349102936.640 [NAL9602](INFO): Powering down
2012-10-01T14:48:56.652Z,1349102936.652 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.708400 psu
2012-10-01T14:48:56.655Z,1349102936.655 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=28
2012-10-01T14:48:56.852Z,1349102936.852 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.038980 psu
2012-10-01T14:48:56.891Z,1349102936.891 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=5
2012-10-01T14:48:57.056Z,1349102937.056 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.492100 psu
2012-10-01T14:48:57.127Z,1349102937.127 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=13
2012-10-01T14:48:57.256Z,1349102937.256 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.048750 psu
2012-10-01T14:48:57.364Z,1349102937.364 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4
2012-10-01T14:48:57.460Z,1349102937.460 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.869980 psu
2012-10-01T14:48:57.660Z,1349102937.660 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.925230 psu
2012-10-01T14:48:57.868Z,1349102937.868 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.915060 psu
2012-10-01T14:48:58.064Z,1349102938.064 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.736160 psu
2012-10-01T14:48:58.267Z,1349102938.267 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.610780 psu
2012-10-01T14:48:58.287Z,1349102938.287 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=29
2012-10-01T14:48:58.468Z,1349102938.468 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.873350 psu
2012-10-01T14:48:58.668Z,1349102938.668 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.213850 psu
2012-10-01T14:48:58.759Z,1349102938.759 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=5
2012-10-01T14:48:58.871Z,1349102938.871 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.926950 psu
2012-10-01T14:48:59.072Z,1349102939.072 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.969650 psu
2012-10-01T14:48:59.235Z,1349102939.236 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=13
2012-10-01T14:48:59.275Z,1349102939.275 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.225120 psu
2012-10-01T14:48:59.476Z,1349102939.476 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.399050 psu
2012-10-01T14:48:59.676Z,1349102939.676 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.834630 psu
2012-10-01T14:48:59.707Z,1349102939.707 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4
2012-10-01T14:48:59.879Z,1349102939.879 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.062730 psu
2012-10-01T14:49:00.083Z,1349102940.083 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.107710 psu
2012-10-01T14:49:00.285Z,1349102940.284 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.872360 psu
2012-10-01T14:49:00.484Z,1349102940.484 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.920100 psu
2012-10-01T14:49:00.587Z,1349102940.587 [DVL_micro](DEBUG): NQ1
2012-10-01T14:49:00.613Z,1349102940.614 [DVL_micro](DEBUG): ADCP
2012-10-01T14:49:00.688Z,1349102940.688 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.663680 psu
2012-10-01T14:49:00.889Z,1349102940.889 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.677940 psu
2012-10-01T14:49:01.088Z,1349102941.088 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.771140 psu
2012-10-01T14:49:01.292Z,1349102941.292 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.650320 psu
2012-10-01T14:49:01.447Z,1349102941.447 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=9
2012-10-01T14:49:01.492Z,1349102941.492 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.545260 psu
2012-10-01T14:49:01.692Z,1349102941.692 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.862190 psu
2012-10-01T14:49:01.896Z,1349102941.896 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.009790 psu
2012-10-01T14:49:02.097Z,1349102942.097 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.130130 psu
2012-10-01T14:49:02.284Z,1349102942.284 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=17
2012-10-01T14:49:02.333Z,1349102942.332 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.813550 psu
2012-10-01T14:49:02.499Z,1349102942.499 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.710640 psu
2012-10-01T14:49:02.703Z,1349102942.703 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.195530 psu
2012-10-01T14:49:02.900Z,1349102942.900 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.093810 psu
2012-10-01T14:49:03.103Z,1349102943.103 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.294490 psu
2012-10-01T14:49:03.123Z,1349102943.124 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=25
2012-10-01T14:49:03.303Z,1349102943.303 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.527100 psu
2012-10-01T14:49:03.507Z,1349102943.507 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.622710 psu
2012-10-01T14:49:03.707Z,1349102943.707 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.702320 psu
2012-10-01T14:49:03.960Z,1349102943.960 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-10-01T14:49:04.007Z,1349102944.007 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.536400 psu
2012-10-01T14:49:04.095Z,1349102944.095 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=8
2012-10-01T14:49:04.152Z,1349102944.152 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=28
2012-10-01T14:49:04.207Z,1349102944.207 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=48
2012-10-01T14:49:04.210Z,1349102944.210 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.520180 psu
2012-10-01T14:49:04.257Z,1349102944.257 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-10-01T14:49:04.437Z,1349102944.437 [CTD_NeilBrown](ERROR): Salinity reading out of range: -3.039780 psu
2012-10-01T14:49:04.540Z,1349102944.540 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=58
2012-10-01T14:49:04.612Z,1349102944.612 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.404160 psu
2012-10-01T14:49:04.644Z,1349102944.644 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=63
2012-10-01T14:49:04.744Z,1349102944.744 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=35
2012-10-01T14:49:04.813Z,1349102944.813 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.398420 psu
2012-10-01T14:49:04.845Z,1349102944.845 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-10-01T14:49:04.983Z,1349102944.983 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=40
2012-10-01T14:49:05.016Z,1349102945.016 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.634590 psu
2012-10-01T14:49:05.040Z,1349102945.040 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=60
2012-10-01T14:49:05.091Z,1349102945.091 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=16
2012-10-01T14:49:05.143Z,1349102945.143 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-10-01T14:49:05.216Z,1349102945.216 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.949670 psu
2012-10-01T14:49:05.416Z,1349102945.416 [CTD_NeilBrown](ERROR): Salinity reading out of range: -3.493380 psu
2012-10-01T14:49:05.428Z,1349102945.428 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=56
2012-10-01T14:49:05.531Z,1349102945.531 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=60
2012-10-01T14:49:05.620Z,1349102945.620 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.670570 psu
2012-10-01T14:49:05.634Z,1349102945.634 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=32
2012-10-01T14:49:05.735Z,1349102945.735 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-10-01T14:49:05.820Z,1349102945.819 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.978290 psu
2012-10-01T14:49:06.023Z,1349102946.023 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.944670 psu
2012-10-01T14:49:06.083Z,1349102946.083 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=24
2012-10-01T14:49:06.223Z,1349102946.223 [CTD_NeilBrown](ERROR): Salinity reading out of range: -3.103760 psu
2012-10-01T14:49:06.227Z,1349102946.227 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=28
2012-10-01T14:49:06.371Z,1349102946.371 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=0
2012-10-01T14:49:06.424Z,1349102946.424 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.149500 psu
2012-10-01T14:49:06.517Z,1349102946.517 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4
2012-10-01T14:49:06.627Z,1349102946.628 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.541700 psu
2012-10-01T14:49:06.827Z,1349102946.827 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.388730 psu
2012-10-01T14:49:06.843Z,1349102946.843 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=28
2012-10-01T14:49:06.959Z,1349102946.959 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=36
2012-10-01T14:49:07.031Z,1349102947.031 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.907370 psu
2012-10-01T14:49:07.076Z,1349102947.076 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=12
2012-10-01T14:49:07.188Z,1349102947.188 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4
2012-10-01T14:49:07.231Z,1349102947.231 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.197390 psu
2012-10-01T14:49:07.435Z,1349102947.435 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.319250 psu
2012-10-01T14:49:07.635Z,1349102947.635 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.676180 psu
2012-10-01T14:49:07.711Z,1349102947.711 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=28
2012-10-01T14:49:07.852Z,1349102947.852 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.562990 psu
2012-10-01T14:49:07.947Z,1349102947.947 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4
2012-10-01T14:49:08.039Z,1349102948.039 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.162130 psu
2012-10-01T14:49:08.186Z,1349102948.186 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=12
2012-10-01T14:49:08.240Z,1349102948.240 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.862880 psu
2012-10-01T14:49:08.419Z,1349102948.419 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4
2012-10-01T14:49:08.443Z,1349102948.443 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.398770 psu
2012-10-01T14:49:08.644Z,1349102948.644 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.353600 psu
2012-10-01T14:49:08.844Z,1349102948.844 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.608730 psu
2012-10-01T14:49:09.044Z,1349102949.044 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.315040 psu
2012-10-01T14:49:09.248Z,1349102949.248 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.831360 psu
2012-10-01T14:49:09.339Z,1349102949.339 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=29
2012-10-01T14:49:09.448Z,1349102949.448 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.987150 psu
2012-10-01T14:49:09.648Z,1349102949.648 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.682890 psu
2012-10-01T14:49:09.813Z,1349102949.813 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=5
2012-10-01T14:49:09.852Z,1349102949.852 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.615730 psu
2012-10-01T14:49:10.052Z,1349102950.052 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.168650 psu
2012-10-01T14:49:10.255Z,1349102950.255 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.524480 psu
2012-10-01T14:49:10.300Z,1349102950.300 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=141
2012-10-01T14:49:10.456Z,1349102950.455 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.438300 psu
2012-10-01T14:49:10.660Z,1349102950.660 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.329270 psu
2012-10-01T14:49:10.759Z,1349102950.759 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4
2012-10-01T14:49:10.859Z,1349102950.859 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.327390 psu
2012-10-01T14:49:11.060Z,1349102951.060 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.694550 psu
2012-10-01T14:49:11.264Z,1349102951.264 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.803670 psu
2012-10-01T14:49:11.464Z,1349102951.464 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.239270 psu
2012-10-01T14:49:11.639Z,1349102951.639 [DVL_micro](DEBUG): NQ1
2012-10-01T14:49:11.664Z,1349102951.664 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.808920 psu
2012-10-01T14:49:11.675Z,1349102951.675 [DVL_micro](DEBUG): ADCP
2012-10-01T14:49:11.868Z,1349102951.868 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.054170 psu
2012-10-01T14:49:12.068Z,1349102952.068 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.148440 psu
2012-10-01T14:49:12.271Z,1349102952.271 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.745910 psu
2012-10-01T14:49:12.472Z,1349102952.472 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.256400 psu
2012-10-01T14:49:12.500Z,1349102952.500 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=6
2012-10-01T14:49:12.672Z,1349102952.672 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.932740 psu
2012-10-01T14:49:12.880Z,1349102952.880 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.718680 psu
2012-10-01T14:49:13.076Z,1349102953.076 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.471630 psu
2012-10-01T14:49:13.280Z,1349102953.280 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.174790 psu
2012-10-01T14:49:13.339Z,1349102953.339 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=15
2012-10-01T14:49:13.480Z,1349102953.480 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.626270 psu
2012-10-01T14:49:13.687Z,1349102953.687 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.085030 psu
2012-10-01T14:49:13.885Z,1349102953.885 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.974200 psu
2012-10-01T14:49:14.087Z,1349102954.087 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.547050 psu
2012-10-01T14:49:14.176Z,1349102954.176 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=23
2012-10-01T14:49:14.287Z,1349102954.287 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.694550 psu
2012-10-01T14:49:14.491Z,1349102954.491 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.569680 psu
2012-10-01T14:49:14.791Z,1349102954.791 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.361450 psu
2012-10-01T14:49:14.992Z,1349102954.992 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.837250 psu
2012-10-01T14:49:15.011Z,1349102955.011 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-10-01T14:49:15.171Z,1349102955.171 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=168
2012-10-01T14:49:15.200Z,1349102955.200 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.001530 psu
2012-10-01T14:49:15.212Z,1349102955.211 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=92
2012-10-01T14:49:15.259Z,1349102955.259 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=48
2012-10-01T14:49:15.311Z,1349102955.311 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-10-01T14:49:15.398Z,1349102955.398 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.856950 psu
2012-10-01T14:49:15.591Z,1349102955.592 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24
2012-10-01T14:49:15.599Z,1349102955.599 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.322360 psu
2012-10-01T14:49:15.695Z,1349102955.695 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28
2012-10-01T14:49:15.795Z,1349102955.796 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=0
2012-10-01T14:49:15.800Z,1349102955.800 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.502550 psu
2012-10-01T14:49:15.899Z,1349102955.899 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-10-01T14:49:15.000Z,1349102956.000 [CTD_NeilBrown](ERROR): Salinity reading out of range: -4.127400 psu
2012-10-01T14:49:16.036Z,1349102956.036 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=8
2012-10-01T14:49:16.092Z,1349102956.092 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=28
2012-10-01T14:49:16.143Z,1349102956.143 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=16
2012-10-01T14:49:16.200Z,1349102956.200 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-10-01T14:49:16.204Z,1349102956.204 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.795100 psu
2012-10-01T14:49:16.404Z,1349102956.404 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.627370 psu
2012-10-01T14:49:16.479Z,1349102956.479 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24
2012-10-01T14:49:16.584Z,1349102956.584 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28
2012-10-01T14:49:16.608Z,1349102956.608 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.027280 psu
2012-10-01T14:49:16.684Z,1349102956.684 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=0
2012-10-01T14:49:16.787Z,1349102956.787 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-10-01T14:49:16.812Z,1349102956.812 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.141040 psu
2012-10-01T14:49:17.029Z,1349102957.029 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.093400 psu
2012-10-01T14:49:17.139Z,1349102957.139 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=56
2012-10-01T14:49:17.212Z,1349102957.212 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.728030 psu
2012-10-01T14:49:17.283Z,1349102957.283 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=28
2012-10-01T14:49:17.415Z,1349102957.415 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.821570 psu
2012-10-01T14:49:17.430Z,1349102957.430 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=32
2012-10-01T14:49:17.571Z,1349102957.571 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4
2012-10-01T14:49:17.620Z,1349102957.620 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.509300 psu
2012-10-01T14:49:17.820Z,1349102957.819 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.490510 psu
2012-10-01T14:49:17.891Z,1349102957.891 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=28
2012-10-01T14:49:18.008Z,1349102958.008 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4
2012-10-01T14:49:18.019Z,1349102958.019 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.903860 psu
2012-10-01T14:49:18.123Z,1349102958.123 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=12
2012-10-01T14:49:18.220Z,1349102958.220 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.489880 psu
2012-10-01T14:49:18.450Z,1349102958.450 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4
2012-10-01T14:49:18.472Z,1349102958.472 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.681770 psu
2012-10-01T14:49:18.619Z,1349102958.619 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.689140 psu
2012-10-01T14:49:18.760Z,1349102958.760 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=29
2012-10-01T14:49:18.824Z,1349102958.824 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.898110 psu
2012-10-01T14:49:18.995Z,1349102958.995 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=5
2012-10-01T14:49:19.072Z,1349102959.072 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.048700 psu
2012-10-01T14:49:19.227Z,1349102959.227 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.101840 psu
2012-10-01T14:49:19.232Z,1349102959.232 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=13
2012-10-01T14:49:19.452Z,1349102959.452 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.727320 psu
2012-10-01T14:49:19.467Z,1349102959.467 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4
2012-10-01T14:49:19.627Z,1349102959.628 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.713520 psu
2012-10-01T14:49:19.858Z,1349102959.858 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.337660 psu
2012-10-01T14:49:20.032Z,1349102960.032 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.885210 psu
2012-10-01T14:49:20.236Z,1349102960.236 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.046770 psu
2012-10-01T14:49:20.387Z,1349102960.387 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=29
2012-10-01T14:49:20.436Z,1349102960.436 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.591800 psu
2012-10-01T14:49:20.639Z,1349102960.639 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.764260 psu
2012-10-01T14:49:20.840Z,1349102960.840 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.247350 psu
2012-10-01T14:49:20.859Z,1349102960.859 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=5
2012-10-01T14:49:21.071Z,1349102961.071 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.301610 psu
2012-10-01T14:49:21.240Z,1349102961.240 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.220130 psu
2012-10-01T14:49:21.335Z,1349102961.335 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=13
2012-10-01T14:49:21.440Z,1349102961.440 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.554190 psu
2012-10-01T14:49:21.642Z,1349102961.642 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.703980 psu
2012-10-01T14:49:21.808Z,1349102961.808 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4
2012-10-01T14:49:21.844Z,1349102961.843 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.115020 psu
2012-10-01T14:49:22.047Z,1349102962.047 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.951770 psu
2012-10-01T14:49:22.248Z,1349102962.247 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.701700 psu
2012-10-01T14:49:22.452Z,1349102962.452 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.247490 psu
2012-10-01T14:49:22.656Z,1349102962.656 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.253540 psu
2012-10-01T14:49:22.692Z,1349102962.691 [DVL_micro](DEBUG): NQ1
2012-10-01T14:49:22.724Z,1349102962.724 [DVL_micro](DEBUG): ADCP
2012-10-01T14:49:22.852Z,1349102962.852 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.797560 psu
2012-10-01T14:49:23.055Z,1349102963.055 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.278550 psu
2012-10-01T14:49:23.255Z,1349102963.255 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.617330 psu
2012-10-01T14:49:23.456Z,1349102963.456 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.475610 psu
2012-10-01T14:49:23.551Z,1349102963.551 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=39
2012-10-01T14:49:23.659Z,1349102963.659 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.813280 psu
2012-10-01T14:49:23.859Z,1349102963.859 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.364040 psu
2012-10-01T14:49:24.060Z,1349102964.059 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.747870 psu
2012-10-01T14:49:24.265Z,1349102964.265 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.516260 psu
2012-10-01T14:49:24.389Z,1349102964.388 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=17
2012-10-01T14:49:24.464Z,1349102964.464 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.039960 psu
2012-10-01T14:49:24.668Z,1349102964.668 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.725920 psu
2012-10-01T14:49:24.868Z,1349102964.868 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.818360 psu
2012-10-01T14:49:25.068Z,1349102965.068 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.708300 psu
2012-10-01T14:49:25.232Z,1349102965.232 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=89
2012-10-01T14:49:25.273Z,1349102965.273 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.807970 psu
2012-10-01T14:49:25.570Z,1349102965.569 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.497520 psu
2012-10-01T14:49:25.770Z,1349102965.770 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.013360 psu
2012-10-01T14:49:25.974Z,1349102965.974 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.373440 psu
2012-10-01T14:49:26.063Z,1349102966.063 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-10-01T14:49:26.175Z,1349102966.175 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.918340 psu
2012-10-01T14:49:26.199Z,1349102966.199 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=8
2012-10-01T14:49:26.257Z,1349102966.257 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=60
2012-10-01T14:49:26.307Z,1349102966.307 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=16
2012-10-01T14:49:26.359Z,1349102966.359 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-10-01T14:49:26.376Z,1349102966.376 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.417440 psu
2012-10-01T14:49:26.579Z,1349102966.579 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.633110 psu
2012-10-01T14:49:26.644Z,1349102966.644 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=56
2012-10-01T14:49:26.744Z,1349102966.744 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28
2012-10-01T14:49:26.780Z,1349102966.780 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.575860 psu
2012-10-01T14:49:26.848Z,1349102966.848 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=33
2012-10-01T14:49:26.947Z,1349102966.947 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-10-01T14:49:26.979Z,1349102966.980 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.617490 psu
2012-10-01T14:49:27.087Z,1349102967.087 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=40
2012-10-01T14:49:27.141Z,1349102967.141 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=28
2012-10-01T14:49:27.184Z,1349102967.184 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.847790 psu
2012-10-01T14:49:27.195Z,1349102967.195 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=48
2012-10-01T14:49:27.247Z,1349102967.247 [DVL_micro](DEBUG): RSSI read bytes: 620 of 620, #buffered=4
2012-10-01T14:49:27.383Z,1349102967.383 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.904550 psu
2012-10-01T14:49:27.528Z,1349102967.528 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=24
2012-10-01T14:49:27.584Z,1349102967.584 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.493980 psu
2012-10-01T14:49:27.631Z,1349102967.631 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=28
2012-10-01T14:49:27.731Z,1349102967.731 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=0
2012-10-01T14:49:27.787Z,1349102967.787 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.710370 psu
2012-10-01T14:49:27.835Z,1349102967.835 [DVL_micro](DEBUG): RSSI read bytes: 1180 of 1180, #buffered=4
2012-10-01T14:49:27.988Z,1349102967.988 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.282560 psu
2012-10-01T14:49:28.188Z,1349102968.188 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=24
2012-10-01T14:49:28.196Z,1349102968.195 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.810000 psu
2012-10-01T14:49:28.331Z,1349102968.331 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=28
2012-10-01T14:49:28.404Z,1349102968.404 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.488090 psu
2012-10-01T14:49:28.475Z,1349102968.475 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=32
2012-10-01T14:49:28.596Z,1349102968.596 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.848370 psu
2012-10-01T14:49:28.619Z,1349102968.619 [DVL_micro](DEBUG): RSSI read bytes: 1660 of 1660, #buffered=4
2012-10-01T14:49:28.795Z,1349102968.796 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.679620 psu
2012-10-01T14:49:28.943Z,1349102968.943 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=60
2012-10-01T14:49:28.000Z,1349102969.000 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.385450 psu
2012-10-01T14:49:29.055Z,1349102969.055 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4
2012-10-01T14:49:29.176Z,1349102969.176 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=44
2012-10-01T14:49:29.200Z,1349102969.200 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.094110 psu
2012-10-01T14:49:29.291Z,1349102969.291 [DVL_micro](DEBUG): RSSI read bytes: 1336 of 1336, #buffered=4
2012-10-01T14:49:29.401Z,1349102969.401 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.360850 psu
2012-10-01T14:49:29.600Z,1349102969.600 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.381210 psu
2012-10-01T14:49:29.803Z,1349102969.803 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.261400 psu
2012-10-01T14:49:29.815Z,1349102969.815 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=60
2012-10-01T14:49:30.011Z,1349102970.011 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.726570 psu
2012-10-01T14:49:30.047Z,1349102970.047 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4
2012-10-01T14:49:30.207Z,1349102970.207 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.485240 psu
2012-10-01T14:49:30.287Z,1349102970.287 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=44
2012-10-01T14:49:30.412Z,1349102970.411 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.628320 psu
2012-10-01T14:49:30.523Z,1349102970.523 [DVL_micro](DEBUG): RSSI read bytes: 2712 of 2712, #buffered=4
2012-10-01T14:49:30.611Z,1349102970.611 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.740840 psu
2012-10-01T14:49:30.811Z,1349102970.811 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.959720 psu
2012-10-01T14:49:31.013Z,1349102971.013 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.860290 psu
2012-10-01T14:49:31.218Z,1349102971.218 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.036440 psu
2012-10-01T14:49:31.415Z,1349102971.415 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.984310 psu
2012-10-01T14:49:31.456Z,1349102971.456 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=188
2012-10-01T14:49:31.615Z,1349102971.615 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.078010 psu
2012-10-01T14:49:31.822Z,1349102971.822 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.948140 psu
2012-10-01T14:49:31.915Z,1349102971.915 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=37
2012-10-01T14:49:32.022Z,1349102972.022 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.400940 psu
2012-10-01T14:49:32.272Z,1349102972.272 [CTD_NeilBrown](ERROR): Bad response: .90302
2012-10-01T14:49:32.392Z,1349102972.392 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=47
2012-10-01T14:49:32.420Z,1349102972.420 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.482170 psu
2012-10-01T14:49:32.623Z,1349102972.623 [CTD_NeilBrown](ERROR): Bad response: 11.73239
2012-10-01T14:49:32.824Z,1349102972.824 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.961360 psu
2012-10-01T14:49:32.863Z,1349102972.863 [DVL_micro](DEBUG): RSSI read bytes: 5432 of 5432, #buffered=4
2012-10-01T14:49:33.024Z,1349102973.024 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.714370 psu
2012-10-01T14:49:33.228Z,1349102973.228 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.621240 psu
2012-10-01T14:49:33.429Z,1349102973.429 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.998390 psu
2012-10-01T14:49:33.628Z,1349102973.628 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.649560 psu
2012-10-01T14:49:33.743Z,1349102973.743 [DVL_micro](DEBUG): NQ1
2012-10-01T14:49:33.766Z,1349102973.766 [DVL_micro](DEBUG): ADCP
2012-10-01T14:49:33.834Z,1349102973.834 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.866400 psu
2012-10-01T14:49:34.031Z,1349102974.031 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.641080 psu
2012-10-01T14:49:34.231Z,1349102974.231 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.581210 psu
2012-10-01T14:49:34.444Z,1349102974.444 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.108940 psu
2012-10-01T14:49:34.600Z,1349102974.599 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=13
2012-10-01T14:49:34.635Z,1349102974.635 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.583500 psu
2012-10-01T14:49:34.836Z,1349102974.836 [CTD_NeilBrown](ERROR): Salinity reading out of range: -0.934630 psu
2012-10-01T14:49:35.084Z,1349102975.084 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.489330 psu
2012-10-01T14:49:35.355Z,1349102975.355 [ThrusterServo](FAULT): getVelocity uart error serial timeout
2012-10-01T14:49:35.355Z,1349102975.355 [ThrusterServo](FAULT): Thruster uart error: serial timeout
2012-10-01T14:49:35.356Z,1349102975.355 [ThrusterServo] Communications Fault, FailCount= 1
2012-10-01T14:49:35.356Z,1349102975.355 [ThrusterServo](ERROR): Communications Fault
2012-10-01T14:49:35.368Z,1349102975.368 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2012-10-01T14:49:35.415Z,1349102975.415 [DVL_micro](DEBUG): RSSI read bytes: 6831 of 9592, #buffered=0
2012-10-01T14:49:35.531Z,1349102975.531 [DVL_micro](DEBUG): RSSI read bytes: 2761 of 2761, #buffered=1080
2012-10-01T14:49:35.578Z,1349102975.578 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.362010 psu
2012-10-01T14:49:35.605Z,1349102975.605 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.907870 psu
2012-10-01T14:49:35.643Z,1349102975.643 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.397670 psu
2012-10-01T14:49:35.827Z,1349102975.827 [CommandLine](IMPORTANT): got command stop
2012-10-01T14:49:35.844Z,1349102975.844 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.340990 psu
2012-10-01T14:49:36.044Z,1349102976.044 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.882790 psu
2012-10-01T14:49:36.244Z,1349102976.244 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.925120 psu
2012-10-01T14:49:36.277Z,1349102976.277 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=1
2012-10-01T14:49:36.448Z,1349102976.448 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.965530 psu
2012-10-01T14:49:36.648Z,1349102976.648 [CTD_NeilBrown](ERROR): Salinity reading out of range: -1.678740 psu
2012-10-01T14:49:36.852Z,1349102976.852 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.114640 psu
2012-10-01T14:49:36.897Z,1349102976.897 [science_to_67_70] Stopped
2012-10-01T14:49:36.897Z,1349102976.897 [science_to_67_70](INFO): Aggregate::uninitialize science_to_67_70
2012-10-01T14:49:36.897Z,1349102976.897 [science_to_67_70:AltEnvWrapper] Stopped
2012-10-01T14:49:36.897Z,1349102976.898 [science_to_67_70:AltEnvWrapper](INFO): Aggregate::uninitialize science_to_67_70:AltEnvWrapper
2012-10-01T14:49:36.897Z,1349102976.898 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope] Stopped
2012-10-01T14:49:36.898Z,1349102976.898 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2012-10-01T14:49:36.898Z,1349102976.898 [science_to_67_70:AltEnvWrapper:B.Wait] Stopped
2012-10-01T14:49:36.898Z,1349102976.898 [science_to_67_70:AltEnvWrapper:B.Wait](DEBUG): Uninitialize Wait Component.
2012-10-01T14:49:36.898Z,1349102976.898 [science_to_67_70:B.DepthEnvelope] Stopped
2012-10-01T14:49:36.898Z,1349102976.898 [science_to_67_70:B.DepthEnvelope](DEBUG): Uninitialize.
2012-10-01T14:49:36.898Z,1349102976.898 [science_to_67_70:OffshoreWrapper] Stopped
2012-10-01T14:49:36.898Z,1349102976.898 [science_to_67_70:OffshoreWrapper](INFO): Aggregate::uninitialize science_to_67_70:OffshoreWrapper
2012-10-01T14:49:36.898Z,1349102976.898 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope] Stopped
2012-10-01T14:49:36.898Z,1349102976.898 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2012-10-01T14:49:36.903Z,1349102976.903 [science_to_67_70:OffshoreWrapper:B.Wait] Stopped
2012-10-01T14:49:36.903Z,1349102976.903 [science_to_67_70:OffshoreWrapper:B.Wait](DEBUG): Uninitialize Wait Component.
2012-10-01T14:49:36.903Z,1349102976.903 [science_to_67_70:Science] Stopped
2012-10-01T14:49:36.903Z,1349102976.903 [science_to_67_70:Science](INFO): Aggregate::uninitialize science_to_67_70:Science
2012-10-01T14:49:36.904Z,1349102976.904 [science_to_67_70:Science:A] Stopped
2012-10-01T14:49:36.904Z,1349102976.904 [science_to_67_70:Science:B] Stopped
2012-10-01T14:49:36.904Z,1349102976.904 [science_to_67_70:Science:C] Stopped
2012-10-01T14:49:36.904Z,1349102976.904 [science_to_67_70:Science:D] Stopped
2012-10-01T14:49:36.904Z,1349102976.904 [science_to_67_70:Science:E] Stopped
2012-10-01T14:49:36.904Z,1349102976.904 [science_to_67_70:Science:F] Stopped
2012-10-01T14:49:36.904Z,1349102976.904 [science_to_67_70:Science:G] Stopped
2012-10-01T14:49:36.904Z,1349102976.904 [science_to_67_70:Science:PeakDetectChl] Stopped
2012-10-01T14:49:36.904Z,1349102976.904 [science_to_67_70:Science:PeakDetectChl](INFO): Aggregate::uninitialize science_to_67_70:Science:PeakDetectChl
2012-10-01T14:49:36.904Z,1349102976.904 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2012-10-01T14:49:36.904Z,1349102976.904 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2012-10-01T14:49:36.904Z,1349102976.904 [science_to_67_70:G.Buoyancy] Stopped
2012-10-01T14:49:36.904Z,1349102976.904 [science_to_67_70:G.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2012-10-01T14:49:36.904Z,1349102976.904 [science_to_67_70:H.SetSpeed] Stopped
2012-10-01T14:49:36.904Z,1349102976.904 [science_to_67_70:H.SetSpeed](DEBUG): Uninitialize.
2012-10-01T14:49:36.905Z,1349102976.905 [science_to_67_70:I.DepthEnvelope] Stopped
2012-10-01T14:49:36.905Z,1349102976.905 [science_to_67_70:I.DepthEnvelope](DEBUG): Uninitialize.
2012-10-01T14:49:36.905Z,1349102976.905 [science_to_67_70:J.YoYo] Stopped
2012-10-01T14:49:36.905Z,1349102976.905 [science_to_67_70:J.YoYo](DEBUG): Uninitialize YoYoComponent.
2012-10-01T14:49:36.905Z,1349102976.905 [science_to_67_70:Wpt.Point] Stopped
2012-10-01T14:49:37.018Z,1349102977.018 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2012-10-01T14:49:37.019Z,1349102977.019 [ThrusterServo](INFO): Powering down
2012-10-01T14:49:37.022Z,1349102977.022 [CBIT](INFO): Clearing failed state for component ThrusterServo
2012-10-01T14:49:37.022Z,1349102977.022 [ThrusterServo] No Fault, FailCount= 1
2012-10-01T14:49:37.055Z,1349102977.055 [WetLabsBB2FL](INFO): Powering down
2012-10-01T14:49:37.111Z,1349102977.111 [DVL_micro](DEBUG): RSSI read bytes: 9592 of 9592, #buffered=4
2012-10-01T14:49:37.151Z,1349102977.151 [CTD_NeilBrown](ERROR): Salinity reading out of range: -2.036380 psu
2012-10-01T14:49:37.200Z,1349102977.200 [DVL_micro](INFO): pause:Powering down
2012-10-01T14:49:38.096Z,1349102978.096 [MissionManager](IMPORTANT): Started mission Default
2012-10-01T14:49:38.096Z,1349102978.096 [Default] Running Loop=1
2012-10-01T14:49:38.096Z,1349102978.096 [Default](INFO): Aggregate::initialize Default
2012-10-01T14:49:38.096Z,1349102978.096 [Default:D.SetSpeed] Running Loop=1
2012-10-01T14:49:38.097Z,1349102978.096 [Default:D.SetSpeed](DEBUG): Initialize.
2012-10-01T14:49:38.097Z,1349102978.097 [Default:E.GoToSurface] Running Loop=1
2012-10-01T14:49:38.097Z,1349102978.097 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-01T14:49:38.097Z,1349102978.097 [Default:Iridium] Running Loop=1
2012-10-01T14:49:38.097Z,1349102978.097 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-10-01T14:49:38.097Z,1349102978.097 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-10-01T14:49:38.097Z,1349102978.097 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-10-01T14:49:38.097Z,1349102978.097 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-10-01T14:49:38.097Z,1349102978.097 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-01T14:49:38.104Z,1349102978.104 [Default:Iridium:B.GoToSurface] Stopped
2012-10-01T14:49:38.104Z,1349102978.104 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-01T14:49:38.104Z,1349102978.104 [Default:Iridium:Read_Iridium] Running Loop=1
2012-10-01T14:49:38.104Z,1349102978.104 [Default:GPS] Running Loop=1
2012-10-01T14:49:38.104Z,1349102978.104 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-10-01T14:49:38.105Z,1349102978.105 [Default:GPS:A.SetSpeed] Running Loop=1
2012-10-01T14:49:38.105Z,1349102978.105 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-10-01T14:49:38.105Z,1349102978.105 [Default:GPS:B.GoToSurface] Running Loop=1
2012-10-01T14:49:38.105Z,1349102978.105 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-01T14:49:38.105Z,1349102978.105 [Default:GPS:B.GoToSurface] Stopped
2012-10-01T14:49:38.105Z,1349102978.105 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-01T14:49:38.106Z,1349102978.106 [Default:GPS:Read_GPS] Running Loop=1
2012-10-01T14:49:38.858Z,1349102978.858 [ThrusterServo](DEBUG): Initializing EZServoServo.
2012-10-01T14:49:38.976Z,1349102978.976 [ThrusterServo](DEBUG): Initialization error opening port: port not yet opened
2012-10-01T14:49:38.976Z,1349102978.976 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2012-10-01T14:49:39.234Z,1349102979.234 [NAL9602](INFO): Powering up
2012-10-01T14:49:39.471Z,1349102979.471 [CommandLine](IMPORTANT): got command quit
2012-10-01T14:49:40.655Z,1349102980.655 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2012-10-01T14:49:40.655Z,1349102980.655 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2012-10-01T14:49:40.987Z,1349102980.987 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2012-10-01T14:49:41.199Z,1349102981.199 [WetLabsBB2FL](INFO): Powering down
2012-10-01T14:49:41.203Z,1349102981.203 [ComponentRegistry](INFO): Shutting down Turbulence_NPS ThreadHandler
2012-10-01T14:49:41.580Z,1349102981.580 [Turbulence_NPS](INFO): Powering down
2012-10-01T14:49:41.583Z,1349102981.583 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2012-10-01T14:49:41.729Z,1349102981.729 [CTD_NeilBrown](INFO): Powering down
2012-10-01T14:49:41.743Z,1349102981.743 [ComponentRegistry](INFO): Shutting down DVL_micro ThreadHandler
2012-10-01T14:49:42.063Z,1349102982.063 [DVL_micro](INFO): uninitialize:Powering down
2012-10-01T14:49:42.075Z,1349102982.076 [ComponentRegistry](INFO): Shutting down AsyncPiEstimator ThreadHandler
2012-10-01T14:49:42.076Z,1349102982.076 [AsyncPiEstimator](DEBUG): Uninitialize AsyncPiEstimator.
2012-10-01T14:49:42.078Z,1349102982.078 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2012-10-01T14:49:42.091Z,1349102982.092 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2012-10-01T14:49:42.121Z,1349102982.121 [controlThread](DEBUG): Uninitializing ControlThread
2012-10-01T14:49:42.121Z,1349102982.121 [AHRS_sp3003D](INFO): Powering down
2012-10-01T14:49:42.207Z,1349102982.207 [NAL9602](INFO): Powering down
2012-10-01T14:49:42.209Z,1349102982.210 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2012-10-01T14:49:42.210Z,1349102982.210 [NavChart](DEBUG): Uninitialize NavChart Derivation.
2012-10-01T14:49:42.211Z,1349102982.211 [Default] Stopped
2012-10-01T14:49:42.211Z,1349102982.211 [Default](INFO): Aggregate::uninitialize Default
2012-10-01T14:49:42.211Z,1349102982.211 [Default:GPS] Stopped
2012-10-01T14:49:42.212Z,1349102982.211 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-10-01T14:49:42.212Z,1349102982.212 [Default:GPS:A.SetSpeed] Stopped
2012-10-01T14:49:42.212Z,1349102982.212 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-10-01T14:49:42.212Z,1349102982.212 [Default:GPS:Read_GPS] Stopped
2012-10-01T14:49:42.212Z,1349102982.212 [Default:Iridium] Stopped
2012-10-01T14:49:42.212Z,1349102982.212 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-10-01T14:49:42.212Z,1349102982.212 [Default:Iridium:A.SetSpeed] Stopped
2012-10-01T14:49:42.212Z,1349102982.212 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-10-01T14:49:42.212Z,1349102982.212 [Default:Iridium:Read_Iridium] Stopped
2012-10-01T14:49:42.212Z,1349102982.212 [Default:D.SetSpeed] Stopped
2012-10-01T14:49:42.212Z,1349102982.212 [Default:D.SetSpeed](DEBUG): Uninitialize.
2012-10-01T14:49:42.212Z,1349102982.212 [Default:E.GoToSurface] Stopped
2012-10-01T14:49:42.212Z,1349102982.212 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-01T14:49:42.216Z,1349102982.216 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2012-10-01T14:49:42.217Z,1349102982.217 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2012-10-01T14:49:42.217Z,1349102982.217 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2012-10-01T14:49:42.217Z,1349102982.217 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2012-10-01T14:49:42.218Z,1349102982.217 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2012-10-01T14:49:42.218Z,1349102982.218 [BuoyancyServo](INFO): Powering down
2012-10-01T14:49:42.223Z,1349102982.223 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2012-10-01T14:49:42.223Z,1349102982.223 [MassServo](DEBUG): Uninitialize Mass Servo.
2012-10-01T14:49:42.224Z,1349102982.224 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2012-10-01T14:49:42.224Z,1349102982.224 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2012-10-01T14:49:42.224Z,1349102982.224 [ThrusterServo](INFO): Powering down
2012-10-01T14:49:42.225Z,1349102982.225 [SBIT](DEBUG): Uninitialize SBIT Component.
2012-10-01T14:49:42.225Z,1349102982.225 [IBIT](DEBUG): Uninitialize IBIT Component.
2012-10-01T14:49:42.225Z,1349102982.225 [CBIT](DEBUG): Uninitialize CBIT Component.