2012-10-04T15:29:58.176Z,1349364598.176 [Supervisor](DEBUG): Initializing supervisor.
2012-10-04T15:29:58.179Z,1349364598.179 [SyncHandler](DEBUG): Created PCaller Thread at 4033B4E0
2012-10-04T15:29:58.179Z,1349364598.180 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2012-10-04T15:29:58.181Z,1349364598.181 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at 4036B4E0
2012-10-04T15:29:58.184Z,1349364598.184 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2012-10-04T15:29:58.195Z,1349364598.195 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2012-10-04T15:29:58.196Z,1349364598.196 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at 4039B4E0
2012-10-04T15:29:58.197Z,1349364598.197 [ComponentRegistry](DEBUG): SyncComponent "logger" handled in the control thread.
2012-10-04T15:29:58.198Z,1349364598.198 [Supervisor](INFO): Looking for Config files in directory: Config/
2012-10-04T15:29:58.199Z,1349364598.199 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2012-10-04T15:29:58.556Z,1349364598.556 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2012-10-04T15:29:58.556Z,1349364598.556 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2012-10-04T15:29:58.744Z,1349364598.744 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2012-10-04T15:29:58.745Z,1349364598.745 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2012-10-04T15:29:58.831Z,1349364598.831 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2012-10-04T15:29:58.832Z,1349364598.832 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2012-10-04T15:29:59.038Z,1349364599.038 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2012-10-04T15:29:59.039Z,1349364599.039 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2012-10-04T15:29:59.174Z,1349364599.174 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2012-10-04T15:29:59.175Z,1349364599.175 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2012-10-04T15:29:59.411Z,1349364599.411 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2012-10-04T15:29:59.412Z,1349364599.412 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2012-10-04T15:29:59.585Z,1349364599.585 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2012-10-04T15:29:59.586Z,1349364599.586 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2012-10-04T15:29:59.845Z,1349364599.845 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2012-10-04T15:29:59.845Z,1349364599.845 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2012-10-04T15:29:59.947Z,1349364599.947 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2012-10-04T15:29:59.948Z,1349364599.948 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2012-10-04T15:30:00.359Z,1349364600.359 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2012-10-04T15:30:00.359Z,1349364600.359 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2012-10-04T15:30:00.475Z,1349364600.475 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2012-10-04T15:30:00.476Z,1349364600.476 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2012-10-04T15:30:00.562Z,1349364600.562 [Supervisor](INFO): Looking for Config files in directory: Config/lrauv-daphne/
2012-10-04T15:30:00.562Z,1349364600.562 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/vehicle.cfg
2012-10-04T15:30:00.665Z,1349364600.665 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Sensor.cfg
2012-10-04T15:30:00.796Z,1349364600.796 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/logger.cfg
2012-10-04T15:30:00.882Z,1349364600.882 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/BIT.cfg
2012-10-04T15:30:00.985Z,1349364600.985 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Servo.cfg
2012-10-04T15:30:01.086Z,1349364601.086 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Science.cfg
2012-10-04T15:30:01.205Z,1349364601.205 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Control.cfg
2012-10-04T15:30:01.295Z,1349364601.295 [Supervisor](INFO): Opening Config file at: Config/lrauv-daphne/Simulator.cfg
2012-10-04T15:30:01.382Z,1349364601.382 [Supervisor](FAULT): Ignoring configuration overrides from Data/persisted.cfg
2012-10-04T15:30:01.414Z,1349364601.414 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2012-10-04T15:30:01.577Z,1349364601.577 [InternalSim] Loaded
2012-10-04T15:30:01.578Z,1349364601.578 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2012-10-04T15:30:01.579Z,1349364601.579 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2012-10-04T15:30:01.579Z,1349364601.579 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2012-10-04T15:30:01.699Z,1349364601.699 [SBIT](DEBUG): Construct Startup Built In Test.
2012-10-04T15:30:01.712Z,1349364601.712 [SBIT] Loaded
2012-10-04T15:30:01.712Z,1349364601.713 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2012-10-04T15:30:01.714Z,1349364601.714 [IBIT](DEBUG): Construct Initiated Built In Test.
2012-10-04T15:30:01.743Z,1349364601.743 [IBIT] Loaded
2012-10-04T15:30:01.743Z,1349364601.743 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2012-10-04T15:30:01.747Z,1349364601.747 [CBIT](DEBUG): Construct CBIT Built In Test.
2012-10-04T15:30:01.871Z,1349364601.871 [CBIT] Loaded
2012-10-04T15:30:01.872Z,1349364601.872 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2012-10-04T15:30:01.872Z,1349364601.872 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2012-10-04T15:30:01.873Z,1349364601.873 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2012-10-04T15:30:02.032Z,1349364602.032 [BuoyancyServo] Loaded
2012-10-04T15:30:02.033Z,1349364602.033 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2012-10-04T15:30:02.042Z,1349364602.042 [ElevatorServo] Loaded
2012-10-04T15:30:02.042Z,1349364602.042 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2012-10-04T15:30:02.055Z,1349364602.055 [MassServo] Loaded
2012-10-04T15:30:02.055Z,1349364602.055 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2012-10-04T15:30:02.067Z,1349364602.067 [RudderServo] Loaded
2012-10-04T15:30:02.067Z,1349364602.067 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2012-10-04T15:30:02.080Z,1349364602.080 [ThrusterServo] Loaded
2012-10-04T15:30:02.080Z,1349364602.080 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2012-10-04T15:30:02.080Z,1349364602.080 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2012-10-04T15:30:02.081Z,1349364602.081 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2012-10-04T15:30:02.098Z,1349364602.099 [DepthRateCalculator] Loaded
2012-10-04T15:30:02.099Z,1349364602.099 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2012-10-04T15:30:06.666Z,1349364606.666 [HFRadarModelCalc] Loaded
2012-10-04T15:30:06.666Z,1349364606.666 [ComponentRegistry](DEBUG): SyncComponent "HFRadarModelCalc" handled in the control thread.
2012-10-04T15:30:06.683Z,1349364606.683 [NavChart] Loaded
2012-10-04T15:30:06.683Z,1349364606.684 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2012-10-04T15:30:06.690Z,1349364606.690 [PitchRateCalculator] Loaded
2012-10-04T15:30:06.690Z,1349364606.690 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2012-10-04T15:30:06.697Z,1349364606.697 [SpeedCalculator] Loaded
2012-10-04T15:30:06.697Z,1349364606.697 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2012-10-04T15:30:06.712Z,1349364606.713 [TempGradientCalculator] Loaded
2012-10-04T15:30:06.713Z,1349364606.713 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2012-10-04T15:30:06.719Z,1349364606.719 [YawRateCalculator] Loaded
2012-10-04T15:30:06.719Z,1349364606.719 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2012-10-04T15:30:06.739Z,1349364606.739 [Navigation] Loaded
2012-10-04T15:30:06.739Z,1349364606.739 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread.
2012-10-04T15:30:06.740Z,1349364606.740 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2012-10-04T15:30:06.740Z,1349364606.740 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2012-10-04T15:30:06.996Z,1349364606.996 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2012-10-04T15:30:06.997Z,1349364606.997 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2012-10-04T15:30:07.037Z,1349364607.037 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2012-10-04T15:30:07.037Z,1349364607.037 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2012-10-04T15:30:07.081Z,1349364607.081 [VerticalControl](DEBUG): Construct VerticalControl.
2012-10-04T15:30:07.125Z,1349364607.125 [VerticalControl] Loaded
2012-10-04T15:30:07.125Z,1349364607.125 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2012-10-04T15:30:07.126Z,1349364607.126 [HorizontalControl](DEBUG): Construct HorizontalControl.
2012-10-04T15:30:07.147Z,1349364607.147 [HorizontalControl] Loaded
2012-10-04T15:30:07.147Z,1349364607.147 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2012-10-04T15:30:07.148Z,1349364607.148 [SpeedControl](DEBUG): Construct SpeedControl.
2012-10-04T15:30:07.150Z,1349364607.150 [SpeedControl] Loaded
2012-10-04T15:30:07.150Z,1349364607.150 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2012-10-04T15:30:07.151Z,1349364607.151 [LoopControl](DEBUG): Construct LoopControl.
2012-10-04T15:30:07.152Z,1349364607.152 [LoopControl] Loaded
2012-10-04T15:30:07.152Z,1349364607.152 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2012-10-04T15:30:07.153Z,1349364607.152 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2012-10-04T15:30:07.153Z,1349364607.153 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2012-10-04T15:30:07.165Z,1349364607.165 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator.
2012-10-04T15:30:07.171Z,1349364607.171 [AsyncPiEstimator] Loaded
2012-10-04T15:30:07.171Z,1349364607.171 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread.
2012-10-04T15:30:07.173Z,1349364607.173 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at 406714E0
2012-10-04T15:30:07.174Z,1349364607.174 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2012-10-04T15:30:07.175Z,1349364607.175 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2012-10-04T15:30:07.306Z,1349364607.306 [AHRS_sp3003D] Loaded
2012-10-04T15:30:07.307Z,1349364607.307 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2012-10-04T15:30:07.322Z,1349364607.322 [Depth_Keller] Loaded
2012-10-04T15:30:07.322Z,1349364607.322 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2012-10-04T15:30:07.328Z,1349364607.328 [DropWeight] Loaded
2012-10-04T15:30:07.328Z,1349364607.328 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2012-10-04T15:30:07.415Z,1349364607.415 [NAL9602] Loaded
2012-10-04T15:30:07.415Z,1349364607.415 [ComponentRegistry](DEBUG): SyncComponent "NAL9602" handled in the control thread.
2012-10-04T15:30:07.468Z,1349364607.468 [Onboard] Loaded
2012-10-04T15:30:07.468Z,1349364607.468 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2012-10-04T15:30:07.476Z,1349364607.476 [Radio_Freewave] Loaded
2012-10-04T15:30:07.476Z,1349364607.476 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread.
2012-10-04T15:30:07.477Z,1349364607.477 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2012-10-04T15:30:07.477Z,1349364607.477 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2012-10-04T15:30:07.528Z,1349364607.528 [PAR_Licor] Loaded
2012-10-04T15:30:07.529Z,1349364607.529 [ComponentRegistry](DEBUG): SyncComponent "PAR_Licor" handled in the control thread.
2012-10-04T15:30:07.546Z,1349364607.546 [Turbulence_NPS] Loaded
2012-10-04T15:30:07.546Z,1349364607.546 [ComponentRegistry](DEBUG): Component "Turbulence_NPS" handled in its own thread.
2012-10-04T15:30:07.549Z,1349364607.549 [Turbulence_NPS ThreadHandler](DEBUG): Created PCaller Thread at 407194E0
2012-10-04T15:30:07.581Z,1349364607.581 [WetLabsBB2FL] Loaded
2012-10-04T15:30:07.581Z,1349364607.581 [ComponentRegistry](DEBUG): Component "WetLabsBB2FL" handled in its own thread.
2012-10-04T15:30:07.582Z,1349364607.582 [WetLabsBB2FL ThreadHandler](DEBUG): Created PCaller Thread at 407494E0
2012-10-04T15:30:07.583Z,1349364607.583 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2012-10-04T15:30:07.585Z,1349364607.585 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2012-10-04T15:30:07.586Z,1349364607.586 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2012-10-04T15:30:07.593Z,1349364607.593 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2012-10-04T15:30:07.594Z,1349364607.594 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at 407794E0
2012-10-04T15:30:07.599Z,1349364607.599 [Supervisor](DEBUG): Running supervisor.
2012-10-04T15:30:07.600Z,1349364607.599 [CommandLine](INFO): Thread ID is 1371
2012-10-04T15:30:07.602Z,1349364607.602 [controlThread](INFO): Thread ID is 1370
2012-10-04T15:30:07.602Z,1349364607.602 [controlThread](DEBUG): Initializing ControlThread
2012-10-04T15:30:07.603Z,1349364607.603 [CycleStarter](INFO): Thread ID is 1369
2012-10-04T15:30:07.603Z,1349364607.603 [InternalSim](DEBUG):  InternalSim initializing...
2012-10-04T15:30:07.639Z,1349364607.639 [AsyncPiEstimator](INFO): Thread ID is 1432
2012-10-04T15:30:07.639Z,1349364607.639 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator.
2012-10-04T15:30:07.650Z,1349364607.650 [SBIT](INFO): Initialize SBIT Component.
2012-10-04T15:30:07.651Z,1349364607.651 [SBIT](IMPORTANT): Tethys CM Info:
$Rev: 9922
2012-10-04T15:30:07.651Z,1349364607.651 [IBIT](INFO): Initialize IBIT Component.
2012-10-04T15:30:07.652Z,1349364607.652 [CBIT](DEBUG): Initialize CBIT Component.
2012-10-04T15:30:07.652Z,1349364607.652 [CBIT](FAULT): LAST RESTART WAS UNINTENTIONAL.
2012-10-04T15:30:07.653Z,1349364607.653 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2012-10-04T15:30:07.654Z,1349364607.654 [Turbulence_NPS](INFO): Thread ID is 1433
2012-10-04T15:30:07.654Z,1349364607.654 [Turbulence_NPS](DEBUG): Initializing Turbulence_NPS.
2012-10-04T15:30:07.655Z,1349364607.655 [Turbulence_NPS](INFO): Opening uart, block timeout 10ths=1
2012-10-04T15:30:07.663Z,1349364607.662 [WetLabsBB2FL](INFO): Thread ID is 1434
2012-10-04T15:30:07.663Z,1349364607.663 [WetLabsBB2FL](INFO): Powering down
2012-10-04T15:30:07.680Z,1349364607.680 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2012-10-04T15:30:07.681Z,1349364607.681 [NavChart](DEBUG): Initialize NavChart Derivation.
2012-10-04T15:30:07.681Z,1349364607.681 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2012-10-04T15:30:07.681Z,1349364607.681 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2012-10-04T15:30:07.682Z,1349364607.682 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2012-10-04T15:30:07.683Z,1349364607.683 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2012-10-04T15:30:07.683Z,1349364607.683 [Navigation](DEBUG): Initializing Navigation.
2012-10-04T15:30:07.684Z,1349364607.684 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2012-10-04T15:30:07.686Z,1349364607.685 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2012-10-04T15:30:07.686Z,1349364607.686 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2012-10-04T15:30:07.687Z,1349364607.687 [LoopControl](DEBUG): Initialize LoopControlComponent.
2012-10-04T15:30:07.695Z,1349364607.695 [NavChartDb](INFO): Thread ID is 1435
2012-10-04T15:30:07.703Z,1349364607.703 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2012-10-04T15:30:07.705Z,1349364607.705 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2012-10-04T15:30:07.706Z,1349364607.706 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US5CA50M.000
2012-10-04T15:30:07.707Z,1349364607.707 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US2WC11M.000
2012-10-04T15:30:07.708Z,1349364607.708 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US1WC07M.000
2012-10-04T15:30:07.708Z,1349364607.708 [NavChartDb](INFO): Already Loaded Electronic Nav Chart data from US3CA52M.000
2012-10-04T15:30:07.719Z,1349364607.719 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2012-10-04T15:30:07.735Z,1349364607.735 [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-04T15:30:07.736Z,1349364607.736 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2012-10-04T15:30:07.763Z,1349364607.763 [Turbulence_NPS](INFO): Pause powering down
2012-10-04T15:30:07.780Z,1349364607.780 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool
2012-10-04T15:30:07.782Z,1349364607.782 [Default:GPS:A.SetSpeed](DEBUG): Construct.
2012-10-04T15:30:07.785Z,1349364607.785 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface.
2012-10-04T15:30:07.802Z,1349364607.802 [Default:Iridium:A.SetSpeed](DEBUG): Construct.
2012-10-04T15:30:07.805Z,1349364607.805 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface.
2012-10-04T15:30:07.812Z,1349364607.812 [Default:Iridium:Read_Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2012-10-04T15:30:07.821Z,1349364607.821 [Default:D.SetSpeed](DEBUG): Construct.
2012-10-04T15:30:07.824Z,1349364607.824 [Default:E.GoToSurface](DEBUG): Construct GoToSurface.
2012-10-04T15:30:07.829Z,1349364607.829 [Default:F.Wait](DEBUG): Construct Wait.
2012-10-04T15:30:07.832Z,1349364607.832 [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-04T15:30:07.837Z,1349364607.837 [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-04T15:30:07.858Z,1349364607.858 [AHRS_sp3003D](DEBUG): Initializing AHRS_sp3003D.
2012-10-04T15:30:08.336Z,1349364608.336 [Radio_Freewave](INFO): Powering up
2012-10-04T15:30:08.439Z,1349364608.439 [BuoyancyServo](DEBUG): Initializing EZServoServo.
2012-10-04T15:30:08.443Z,1349364608.443 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2012-10-04T15:30:08.449Z,1349364608.449 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2012-10-04T15:30:08.455Z,1349364608.455 [MassServo](DEBUG): Initializing EZServoServo.
2012-10-04T15:30:08.456Z,1349364608.456 [MassServo](DEBUG): Initializing MassServo.
2012-10-04T15:30:08.466Z,1349364608.466 [RudderServo](DEBUG): Initializing EZServoServo.
2012-10-04T15:30:08.471Z,1349364608.471 [RudderServo](DEBUG): Initializing RudderServo.
2012-10-04T15:30:08.476Z,1349364608.476 [ThrusterServo](DEBUG): Initializing EZServoServo.
2012-10-04T15:30:08.477Z,1349364608.477 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2012-10-04T15:30:13.587Z,1349364613.587 [NAL9602](INFO): Powering up NAL9602
2012-10-04T15:30:24.929Z,1349364624.930 [SBIT](IMPORTANT): Beginning Startup BIT
2012-10-04T15:30:24.932Z,1349364624.932 [CBIT](IMPORTANT): Beginning GF scan
2012-10-04T15:30:25.183Z,1349364625.183 [NAL9602](INFO): NAL9602 initialized
2012-10-04T15:30:53.102Z,1349364653.102 [CBIT](IMPORTANT): No ground fault detected
2012-10-04T15:30:59.067Z,1349364659.067 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.latitude_fix 36.823200 degree
2012-10-04T15:30:59.312Z,1349364659.312 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.latitude_fix" handled in the control thread.
2012-10-04T15:30:59.313Z,1349364659.313 [CommandLine](IMPORTANT): got command maintain sensor NAL9602.longitude_fix -121827.000000 degree
2012-10-04T15:30:59.314Z,1349364659.314 [ComponentRegistry](DEBUG): SyncComponent "Maintain_NAL9602.longitude_fix" handled in the control thread.
2012-10-04T15:30:59.315Z,1349364659.315 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2012-10-04T15:30:59.316Z,1349364659.316 [CommandLine](IMPORTANT): got command report touch Turbulence_NPS.packetNum
2012-10-04T15:30:59.480Z,1349364659.480 [controlThread](DEBUG): Component order: CycleStarter,InternalSim,AHRS_sp3003D,Depth_Keller,DropWeight,NAL9602,Onboard,Radio_Freewave,PAR_Licor,Depth_Keller,PAR_Licor,Maintain_NAL9602.latitude_fix,Maintain_NAL9602.longitude_fix,DepthRateCalculator,HFRadarModelCalc,NavChart,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,Navigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,logger,
2012-10-04T15:31:11.222Z,1349364671.222 [NAL9602](INFO): Powering down
2012-10-04T15:31:19.705Z,1349364679.705 [SBIT](FAULT): Mass: EXPECTED:0.000000 ACTUAL:-0.000051
2012-10-04T15:31:19.705Z,1349364679.705 [SBIT](FAULT): Control surface position failure.
2012-10-04T15:31:20.104Z,1349364680.104 [SBIT](CRITICAL): SBIT FAILED
2012-10-04T15:31:20.437Z,1349364680.437 [MissionManager](IMPORTANT): Started mission Startup
2012-10-04T15:31:20.437Z,1349364680.437 [Startup] Running Loop=1
2012-10-04T15:31:20.437Z,1349364680.437 [Startup](INFO): Aggregate::initialize Startup
2012-10-04T15:31:20.438Z,1349364680.438 [Startup:A.GoToSurface] Running Loop=1
2012-10-04T15:31:20.438Z,1349364680.438 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-04T15:31:20.444Z,1349364680.444 [Startup:StartupSatComms] Running Loop=1
2012-10-04T15:31:20.444Z,1349364680.444 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2012-10-04T15:31:20.444Z,1349364680.444 [Startup:StartupSatComms:A] Running Loop=1
2012-10-04T15:31:20.835Z,1349364680.835 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-10-04T15:31:20.836Z,1349364680.836 [Startup:StartupSatComms:A] Stopped
2012-10-04T15:31:20.836Z,1349364680.836 [Startup:StartupSatComms:B] Running Loop=1
2012-10-04T15:31:21.225Z,1349364681.225 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2012-10-04T15:31:21.610Z,1349364681.610 [NAL9602](INFO): Powering up
2012-10-04T15:31:32.439Z,1349364692.439 [NAL9602](INFO): NAL9602 initialized
2012-10-04T15:31:56.400Z,1349364716.400 [NAL9602](INFO): SBD MO Status=2, MOMSN=1310, MT Status=2, MTMSN=0
2012-10-04T15:31:56.400Z,1349364716.400 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2012-10-04T15:32:01.467Z,1349364721.467 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2012-10-04T15:32:01.644Z,1349364721.644 [Startup:StartupSatComms:B] Stopped
2012-10-04T15:32:01.644Z,1349364721.644 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2012-10-04T15:32:01.644Z,1349364721.644 [Startup:StartupSatComms] Stopped
2012-10-04T15:32:01.644Z,1349364721.644 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2012-10-04T15:32:01.645Z,1349364721.645 [Startup](INFO): Completed Startup
2012-10-04T15:32:01.645Z,1349364721.645 [Startup] Stopped
2012-10-04T15:32:01.645Z,1349364721.645 [Startup](INFO): Aggregate::uninitialize Startup
2012-10-04T15:32:01.645Z,1349364721.645 [Startup:A.GoToSurface] Stopped
2012-10-04T15:32:01.645Z,1349364721.645 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-04T15:32:02.025Z,1349364722.025 [MissionManager](IMPORTANT): Started mission Default
2012-10-04T15:32:02.025Z,1349364722.025 [Default] Running Loop=1
2012-10-04T15:32:02.025Z,1349364722.025 [Default](INFO): Aggregate::initialize Default
2012-10-04T15:32:02.025Z,1349364722.025 [Default:D.SetSpeed] Running Loop=1
2012-10-04T15:32:02.025Z,1349364722.025 [Default:D.SetSpeed](DEBUG): Initialize.
2012-10-04T15:32:02.025Z,1349364722.025 [Default:E.GoToSurface] Running Loop=1
2012-10-04T15:32:02.025Z,1349364722.025 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-04T15:32:02.026Z,1349364722.026 [Default:Iridium] Running Loop=1
2012-10-04T15:32:02.026Z,1349364722.026 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-10-04T15:32:02.026Z,1349364722.026 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-10-04T15:32:02.026Z,1349364722.026 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-10-04T15:32:02.026Z,1349364722.026 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-10-04T15:32:02.026Z,1349364722.026 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-04T15:32:02.027Z,1349364722.027 [Default:E.GoToSurface] Running Loop=1
2012-10-04T15:32:02.032Z,1349364722.032 [Default:D.SetSpeed] Running Loop=1
2012-10-04T15:32:02.043Z,1349364722.043 [Default:Iridium:B.GoToSurface] Stopped
2012-10-04T15:32:02.043Z,1349364722.043 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-04T15:32:02.043Z,1349364722.043 [Default:Iridium:Read_Iridium] Running Loop=1
2012-10-04T15:32:02.043Z,1349364722.043 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-10-04T15:32:02.048Z,1349364722.048 [Default:GPS] Running Loop=1
2012-10-04T15:32:02.048Z,1349364722.048 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-10-04T15:32:02.048Z,1349364722.048 [Default:GPS:A.SetSpeed] Running Loop=1
2012-10-04T15:32:02.048Z,1349364722.048 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-10-04T15:32:02.048Z,1349364722.048 [Default:GPS:B.GoToSurface] Running Loop=1
2012-10-04T15:32:02.048Z,1349364722.048 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-04T15:32:02.054Z,1349364722.054 [Default:GPS:B.GoToSurface] Stopped
2012-10-04T15:32:02.054Z,1349364722.054 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-04T15:32:02.054Z,1349364722.054 [Default:GPS:Read_GPS] Running Loop=1
2012-10-04T15:32:02.054Z,1349364722.054 [Default:GPS:A.SetSpeed] Running Loop=1
2012-10-04T15:32:02.426Z,1349364722.426 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2012-10-04T15:32:02.431Z,1349364722.431 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-10-04T15:32:02.433Z,1349364722.433 [Default:GPS:Read_GPS] Stopped
2012-10-04T15:32:02.433Z,1349364722.433 [Default:GPS:D] Running Loop=1
2012-10-04T15:32:02.832Z,1349364722.832 [Default:GPS:D] Stopped
2012-10-04T15:32:02.833Z,1349364722.833 [Default:GPS](INFO): Completed Default:GPS
2012-10-04T15:32:02.833Z,1349364722.833 [Default:GPS] Stopped
2012-10-04T15:32:02.833Z,1349364722.833 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-10-04T15:32:02.833Z,1349364722.833 [Default:GPS:A.SetSpeed] Stopped
2012-10-04T15:32:02.833Z,1349364722.833 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-10-04T15:32:04.012Z,1349364724.011 [NAL9602](INFO): SBD MO Status=2, MOMSN=1310, MT Status=2, MTMSN=0
2012-10-04T15:32:04.012Z,1349364724.012 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2012-10-04T15:32:10.007Z,1349364730.007 [NAL9602](INFO): SBD MO Status=2, MOMSN=1310, MT Status=2, MTMSN=0
2012-10-04T15:32:10.007Z,1349364730.007 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2012-10-04T15:32:19.195Z,1349364739.195 [NAL9602](INFO): SBD MO Status=2, MOMSN=1310, MT Status=2, MTMSN=0
2012-10-04T15:32:19.196Z,1349364739.196 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2012-10-04T15:32:27.191Z,1349364747.191 [NAL9602](INFO): SBD MO Status=2, MOMSN=1310, MT Status=2, MTMSN=0
2012-10-04T15:32:27.191Z,1349364747.191 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2012-10-04T15:32:44.397Z,1349364764.397 [NAL9602](INFO): SBD MO Status=2, MOMSN=1310, MT Status=2, MTMSN=0
2012-10-04T15:32:44.397Z,1349364764.397 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2012-10-04T15:32:51.223Z,1349364771.223 [CommandLine](IMPORTANT): got command run ./Missions/Science/science_to_67_70.xml
2012-10-04T15:32:51.223Z,1349364771.223 [MissionManager](INFO): Loading Mission: ./Missions/Science/science_to_67_70.xml
2012-10-04T15:32:51.334Z,1349364771.334 [MissionManager](INFO): DefineArg science_to_67_70.MinAltitude = 10.000000 m
2012-10-04T15:32:51.337Z,1349364771.337 [MissionManager](INFO): DefineArg science_to_67_70.MaxDepth = 105.000000 m
2012-10-04T15:32:51.340Z,1349364771.340 [MissionManager](INFO): DefineArg science_to_67_70.MinOffshore = 2000.000000 m
2012-10-04T15:32:51.343Z,1349364771.343 [MissionManager](INFO): DefineArg science_to_67_70.YoYoMinDepth = 3.000000 m
2012-10-04T15:32:51.346Z,1349364771.346 [MissionManager](INFO): DefineArg science_to_67_70.YoYoMaxDepth = 100.000000 m
2012-10-04T15:32:51.349Z,1349364771.349 [MissionManager](INFO): DefineArg science_to_67_70.YoYoUpPitch = 20.000000 arcdeg
2012-10-04T15:32:51.352Z,1349364771.352 [MissionManager](INFO): DefineArg science_to_67_70.YoYoDownPitch = -15.000000 arcdeg
2012-10-04T15:32:51.355Z,1349364771.355 [MissionManager](INFO): DefineArg science_to_67_70.WptLat = 36.120000 arcdeg
2012-10-04T15:32:51.358Z,1349364771.358 [MissionManager](INFO): DefineArg science_to_67_70.WptLon = -123.486000 arcdeg
2012-10-04T15:32:51.361Z,1349364771.361 [MissionManager](INFO): DefineArg science_to_67_70.NeedCommsTime = 120.000000 min
2012-10-04T15:32:51.364Z,1349364771.364 [MissionManager](INFO): DefineArg science_to_67_70.Speed = 1.000000 m/s
2012-10-04T15:32:51.367Z,1349364771.367 [MissionManager](INFO): DefineArg science_to_67_70.Timeout = 36.000000 h
2012-10-04T15:32:51.371Z,1349364771.371 [MissionManager](INFO): DefineArg science_to_67_70.BuoyancyNeutral = 0.000330 n/a
2012-10-04T15:32:51.374Z,1349364771.374 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2012-10-04T15:32:51.381Z,1349364771.381 [science_to_67_70:AltEnvWrapper:B.Wait](DEBUG): Construct Wait.
2012-10-04T15:32:51.383Z,1349364771.383 [science_to_67_70:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2012-10-04T15:32:51.393Z,1349364771.393 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2012-10-04T15:32:51.399Z,1349364771.399 [science_to_67_70:OffshoreWrapper:B.Wait](DEBUG): Construct Wait.
2012-10-04T15:32:51.400Z,1349364771.400 [MissionManager](INFO): Inserting Stack: Missions/Insert/Surface.xml
2012-10-04T15:32:51.441Z,1349364771.441 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.SurfaceDepthRate = nan m/s
2012-10-04T15:32:51.444Z,1349364771.444 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.SurfacePitch = nan arcdeg
2012-10-04T15:32:51.447Z,1349364771.447 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.SurfaceSpeed = 0.500000 m/s
2012-10-04T15:32:51.451Z,1349364771.451 [MissionManager](INFO): DefineArg science_to_67_70:SURFACECOMMS.IridiumTimeout = 30.000000 min
2012-10-04T15:32:51.452Z,1349364771.452 [science_to_67_70:SURFACECOMMS:A.GoToSurface](DEBUG): Construct GoToSurface.
2012-10-04T15:32:51.465Z,1349364771.465 [MissionManager](INFO): RedefineArg science_to_67_70:SURFACECOMMS.SurfacePitch = 20.000000 arcdeg
2012-10-04T15:32:51.471Z,1349364771.471 [MissionManager](INFO): Inserting Stack: Missions/Insert/Science.xml
2012-10-04T15:32:51.535Z,1349364771.535 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleISUS = 0 bool
2012-10-04T15:32:51.537Z,1349364771.537 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleRSSI = 1 bool
2012-10-04T15:32:51.542Z,1349364771.542 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleADCP = 1 bool
2012-10-04T15:32:51.545Z,1349364771.545 [MissionManager](INFO): DefineArg science_to_67_70:Science.PeakDetectChlActive = 0 bool
2012-10-04T15:32:51.548Z,1349364771.548 [MissionManager](INFO): DefineArg science_to_67_70:Science.PeakDetectNO3Active = 0 bool
2012-10-04T15:32:51.551Z,1349364771.551 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleAanderaaO2 = 0.000000 n/a
2012-10-04T15:32:51.555Z,1349364771.555 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleNeilBrown = 0.000000 n/a
2012-10-04T15:32:51.559Z,1349364771.559 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleTurbulence_NPS = 1.000000 n/a
2012-10-04T15:32:51.562Z,1349364771.562 [MissionManager](INFO): DefineArg science_to_67_70:Science.SampleWetLabsBB2FL = 1.000000 n/a
2012-10-04T15:32:51.565Z,1349364771.565 [MissionManager](INFO): DefineArg science_to_67_70:Science.EnabledDVL = 0.000000 n/a
2012-10-04T15:32:51.569Z,1349364771.569 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChl = 0.000000 ug/l
2012-10-04T15:32:51.577Z,1349364771.577 [NAL9602](INFO): SBD MO Status=2, MOMSN=1310, MT Status=2, MTMSN=0
2012-10-04T15:32:51.577Z,1349364771.577 [NAL9602](ERROR): Failed to initiate SBD session. Error code: 2
2012-10-04T15:32:51.584Z,1349364771.584 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChlDepth = 0.000000 m
2012-10-04T15:32:51.591Z,1349364771.591 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChlLatitude = nan arcdeg
2012-10-04T15:32:51.599Z,1349364771.599 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakChlLongitude = nan arcdeg
2012-10-04T15:32:51.602Z,1349364771.602 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3 = 0.000000 umol/l
2012-10-04T15:32:51.609Z,1349364771.609 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3Depth = 0.000000 m
2012-10-04T15:32:51.616Z,1349364771.616 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3Latitude = nan arcdeg
2012-10-04T15:32:51.625Z,1349364771.625 [MissionManager](INFO): DefineOutput science_to_67_70:Science.PeakNO3Longitude = nan arcdeg
2012-10-04T15:32:51.639Z,1349364771.639 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2012-10-04T15:32:51.652Z,1349364771.652 [science_to_67_70:Science:PeakDetectNO3:A.PeakDetectVsDepth](DEBUG): Construct PeakDetectVsDepth.
2012-10-04T15:32:51.667Z,1349364771.667 [MissionManager](INFO): RedefineArg science_to_67_70:Science.SampleISUS = 1 bool
2012-10-04T15:32:51.671Z,1349364771.671 [MissionManager](INFO): RedefineArg science_to_67_70:Science.PeakDetectChlActive = 1 bool
2012-10-04T15:32:51.672Z,1349364771.672 [science_to_67_70:G.Buoyancy](DEBUG): Construct Buoyancy.
2012-10-04T15:32:51.676Z,1349364771.676 [science_to_67_70:H.SetSpeed](DEBUG): Construct.
2012-10-04T15:32:51.679Z,1349364771.680 [science_to_67_70:I.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2012-10-04T15:32:51.692Z,1349364771.692 [science_to_67_70:J.YoYo](DEBUG): Construct YoYo.
2012-10-04T15:32:51.704Z,1349364771.704 [science_to_67_70:Wpt.Point](DEBUG): Construct.
2012-10-04T15:32:51.752Z,1349364771.752 [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-04T15:32:51.753Z,1349364771.753 [CommandLine](IMPORTANT): Running ./Missions/Science/science_to_67_70.xml
2012-10-04T15:32:52.540Z,1349364772.540 [Default] Stopped
2012-10-04T15:32:52.541Z,1349364772.541 [Default](INFO): Aggregate::uninitialize Default
2012-10-04T15:32:52.541Z,1349364772.541 [Default:Iridium] Stopped
2012-10-04T15:32:52.541Z,1349364772.541 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-10-04T15:32:52.541Z,1349364772.541 [Default:Iridium:A.SetSpeed] Stopped
2012-10-04T15:32:52.541Z,1349364772.541 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-10-04T15:32:52.541Z,1349364772.541 [Default:Iridium:Read_Iridium] Stopped
2012-10-04T15:32:52.541Z,1349364772.541 [Default:D.SetSpeed] Stopped
2012-10-04T15:32:52.541Z,1349364772.541 [Default:D.SetSpeed](DEBUG): Uninitialize.
2012-10-04T15:32:52.541Z,1349364772.541 [Default:E.GoToSurface] Stopped
2012-10-04T15:32:52.541Z,1349364772.541 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-04T15:32:52.542Z,1349364772.542 [MissionManager](IMPORTANT): Started mission science_to_67_70
2012-10-04T15:32:52.542Z,1349364772.542 [science_to_67_70] Running Loop=1
2012-10-04T15:32:52.542Z,1349364772.542 [science_to_67_70](INFO): Aggregate::initialize science_to_67_70
2012-10-04T15:32:52.542Z,1349364772.542 [science_to_67_70:B.DepthEnvelope] Running Loop=1
2012-10-04T15:32:52.542Z,1349364772.542 [science_to_67_70:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2012-10-04T15:32:52.543Z,1349364772.543 [science_to_67_70:Science] Running Loop=1
2012-10-04T15:32:52.543Z,1349364772.543 [science_to_67_70:Science](INFO): Aggregate::initialize science_to_67_70:Science
2012-10-04T15:32:52.544Z,1349364772.543 [science_to_67_70:Science:A] Running Loop=1
2012-10-04T15:32:52.544Z,1349364772.544 [science_to_67_70:Science:B] Running Loop=1
2012-10-04T15:32:52.544Z,1349364772.544 [science_to_67_70:Science:C] Running Loop=1
2012-10-04T15:32:52.544Z,1349364772.544 [science_to_67_70:Science:D] Running Loop=1
2012-10-04T15:32:52.544Z,1349364772.544 [science_to_67_70:Science:E] Running Loop=1
2012-10-04T15:32:52.544Z,1349364772.544 [science_to_67_70:Science:F] Running Loop=1
2012-10-04T15:32:52.544Z,1349364772.544 [science_to_67_70:Science:G] Running Loop=1
2012-10-04T15:32:52.544Z,1349364772.544 [science_to_67_70:G.Buoyancy] Running Loop=1
2012-10-04T15:32:52.544Z,1349364772.544 [science_to_67_70:G.Buoyancy](DEBUG): Initialize Buoyancy Component.
2012-10-04T15:32:52.544Z,1349364772.544 [science_to_67_70:H.SetSpeed] Running Loop=1
2012-10-04T15:32:52.544Z,1349364772.544 [science_to_67_70:H.SetSpeed](DEBUG): Initialize.
2012-10-04T15:32:52.544Z,1349364772.544 [science_to_67_70:I.DepthEnvelope] Running Loop=1
2012-10-04T15:32:52.544Z,1349364772.544 [science_to_67_70:I.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2012-10-04T15:32:52.545Z,1349364772.545 [science_to_67_70:J.YoYo] Running Loop=1
2012-10-04T15:32:52.545Z,1349364772.545 [science_to_67_70:J.YoYo](DEBUG): Initialize YoYoComponent.
2012-10-04T15:32:52.546Z,1349364772.546 [science_to_67_70:SURFACECOMMS] Running Loop=1
2012-10-04T15:32:52.546Z,1349364772.546 [science_to_67_70:SURFACECOMMS](INFO): Aggregate::initialize science_to_67_70:SURFACECOMMS
2012-10-04T15:32:52.546Z,1349364772.546 [science_to_67_70:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-10-04T15:32:52.546Z,1349364772.546 [science_to_67_70:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-04T15:32:52.547Z,1349364772.547 [science_to_67_70:J.YoYo] Running Loop=1
2012-10-04T15:32:52.552Z,1349364772.552 [science_to_67_70:I.DepthEnvelope] Running Loop=1
2012-10-04T15:32:52.558Z,1349364772.558 [science_to_67_70:H.SetSpeed] Running Loop=1
2012-10-04T15:32:52.563Z,1349364772.563 [science_to_67_70:G.Buoyancy] Running Loop=1
2012-10-04T15:32:52.567Z,1349364772.568 [science_to_67_70:Science] Running Loop=1
2012-10-04T15:32:52.568Z,1349364772.568 [science_to_67_70:Science:PeakDetectChl] Running Loop=1
2012-10-04T15:32:52.568Z,1349364772.568 [science_to_67_70:Science:PeakDetectChl](INFO): Aggregate::initialize science_to_67_70:Science:PeakDetectChl
2012-10-04T15:32:52.569Z,1349364772.569 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth] Running Loop=1
2012-10-04T15:32:52.569Z,1349364772.569 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth](INFO): Initialize.
2012-10-04T15:32:52.575Z,1349364772.575 [science_to_67_70:Science:G] Running Loop=1
2012-10-04T15:32:52.576Z,1349364772.576 [science_to_67_70:Science:G](DEBUG): Initialize ReadDataComponent to sense mass_concentration_of_chlorophyll_in_sea_water
2012-10-04T15:32:52.577Z,1349364772.577 [science_to_67_70:Science:D] Running Loop=1
2012-10-04T15:32:52.577Z,1349364772.577 [science_to_67_70:Science:D](DEBUG): Initialize ReadDataComponent to sense Turbulence_NPS.MicroCond
2012-10-04T15:32:52.579Z,1349364772.579 [science_to_67_70:Science:B] Running Loop=1
2012-10-04T15:32:52.580Z,1349364772.580 [science_to_67_70:Science:B](DEBUG): Initialize ReadDataComponent to sense mole_concentration_of_nitrate_in_sea_water
2012-10-04T15:32:52.586Z,1349364772.586 [science_to_67_70:SURFACECOMMS:B] Running Loop=1
2012-10-04T15:32:52.586Z,1349364772.586 [science_to_67_70:SURFACECOMMS:B](INFO): Aggregate::initialize science_to_67_70:SURFACECOMMS:B
2012-10-04T15:32:52.586Z,1349364772.586 [science_to_67_70:SURFACECOMMS:B:A] Running Loop=1
2012-10-04T15:32:52.587Z,1349364772.587 [science_to_67_70:OffshoreWrapper] Running Loop=1
2012-10-04T15:32:52.587Z,1349364772.587 [science_to_67_70:OffshoreWrapper](INFO): Aggregate::initialize science_to_67_70:OffshoreWrapper
2012-10-04T15:32:52.587Z,1349364772.587 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope] Running Loop=1
2012-10-04T15:32:52.587Z,1349364772.587 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2012-10-04T15:32:52.587Z,1349364772.587 [science_to_67_70:OffshoreWrapper:B.Wait] Running Loop=1
2012-10-04T15:32:52.587Z,1349364772.587 [science_to_67_70:OffshoreWrapper:B.Wait](DEBUG): Initialize Wait Component.
2012-10-04T15:32:52.592Z,1349364772.592 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope] Running Loop=1
2012-10-04T15:32:52.597Z,1349364772.597 [science_to_67_70:B.DepthEnvelope] Running Loop=1
2012-10-04T15:32:52.602Z,1349364772.602 [science_to_67_70:AltEnvWrapper] Running Loop=1
2012-10-04T15:32:52.602Z,1349364772.602 [science_to_67_70:AltEnvWrapper](INFO): Aggregate::initialize science_to_67_70:AltEnvWrapper
2012-10-04T15:32:52.603Z,1349364772.602 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope] Running Loop=1
2012-10-04T15:32:52.603Z,1349364772.603 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2012-10-04T15:32:52.603Z,1349364772.603 [science_to_67_70:AltEnvWrapper:B.Wait] Running Loop=1
2012-10-04T15:32:52.603Z,1349364772.603 [science_to_67_70:AltEnvWrapper:B.Wait](DEBUG): Initialize Wait Component.
2012-10-04T15:32:52.608Z,1349364772.608 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope] Running Loop=1
2012-10-04T15:32:52.823Z,1349364772.823 [Turbulence_NPS](INFO): Resume powering up
2012-10-04T15:32:52.823Z,1349364772.823 [Turbulence_NPS](INFO): Opening uart, block timeout 10ths=1
2012-10-04T15:32:52.879Z,1349364772.879 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=4
2012-10-04T15:32:52.879Z,1349364772.879 [WetLabsBB2FL](INFO): Powering up
2012-10-04T15:32:53.131Z,1349364773.131 [science_to_67_70:J.YoYo] Preempted
2012-10-04T15:32:53.131Z,1349364773.131 [science_to_67_70:I.DepthEnvelope] Preempted
2012-10-04T15:32:53.131Z,1349364773.131 [science_to_67_70:H.SetSpeed] Preempted
2012-10-04T15:32:53.131Z,1349364773.131 [science_to_67_70:G.Buoyancy] Preempted
2012-10-04T15:32:53.131Z,1349364773.131 [science_to_67_70:Science] Preempted
2012-10-04T15:32:53.131Z,1349364773.131 [science_to_67_70:Science:B] Preempted
2012-10-04T15:32:53.131Z,1349364773.131 [science_to_67_70:Science:D] Preempted
2012-10-04T15:32:53.131Z,1349364773.131 [science_to_67_70:Science:G] Preempted
2012-10-04T15:32:53.132Z,1349364773.132 [science_to_67_70:SURFACECOMMS:B:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-10-04T15:32:53.133Z,1349364773.133 [science_to_67_70:SURFACECOMMS:B:A] Stopped
2012-10-04T15:32:53.133Z,1349364773.133 [science_to_67_70:SURFACECOMMS:B:B] Running Loop=1
2012-10-04T15:32:53.200Z,1349364773.200 [Turbulence_NPS](INFO): Pause powering down
2012-10-04T15:32:53.535Z,1349364773.535 [science_to_67_70:SURFACECOMMS:B:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2012-10-04T15:32:54.071Z,1349364774.071 [WetLabsBB2FL](INFO): Powering down
2012-10-04T15:32:54.331Z,1349364774.332 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](ERROR): Altitude Measurement is not Active.
2012-10-04T15:32:57.451Z,1349364777.451 [CommandLine](IMPORTANT): got command set NAL9602.platform_communications 1.000000 bool
2012-10-04T15:32:57.533Z,1349364777.533 [science_to_67_70:SURFACECOMMS:B:B] Stopped
2012-10-04T15:32:57.534Z,1349364777.533 [science_to_67_70:SURFACECOMMS:B:C] Running Loop=1
2012-10-04T15:32:57.933Z,1349364777.934 [science_to_67_70:SURFACECOMMS:B:C](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-10-04T15:32:57.935Z,1349364777.935 [science_to_67_70:SURFACECOMMS:B:C] Stopped
2012-10-04T15:32:57.935Z,1349364777.935 [science_to_67_70:SURFACECOMMS:B](INFO): Completed science_to_67_70:SURFACECOMMS:B
2012-10-04T15:32:57.935Z,1349364777.935 [science_to_67_70:SURFACECOMMS:B] Stopped
2012-10-04T15:32:57.936Z,1349364777.935 [science_to_67_70:SURFACECOMMS:B](INFO): Aggregate::uninitialize science_to_67_70:SURFACECOMMS:B
2012-10-04T15:32:57.936Z,1349364777.936 [science_to_67_70:SURFACECOMMS](INFO): Completed science_to_67_70:SURFACECOMMS
2012-10-04T15:32:57.936Z,1349364777.936 [science_to_67_70:SURFACECOMMS] Stopped
2012-10-04T15:32:57.937Z,1349364777.937 [science_to_67_70:SURFACECOMMS](INFO): Aggregate::uninitialize science_to_67_70:SURFACECOMMS
2012-10-04T15:32:57.937Z,1349364777.937 [science_to_67_70:SURFACECOMMS:A.GoToSurface] Stopped
2012-10-04T15:32:57.937Z,1349364777.937 [science_to_67_70:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-04T15:32:57.937Z,1349364777.937 [science_to_67_70:Wpt.Point] Running Loop=1
2012-10-04T15:32:57.937Z,1349364777.937 [science_to_67_70:Wpt.Point](DEBUG): Initialize.
2012-10-04T15:32:58.335Z,1349364778.335 [science_to_67_70:J.YoYo] Running Loop=1
2012-10-04T15:32:58.335Z,1349364778.335 [science_to_67_70:I.DepthEnvelope] Running Loop=1
2012-10-04T15:32:58.336Z,1349364778.336 [science_to_67_70:H.SetSpeed] Running Loop=1
2012-10-04T15:32:58.336Z,1349364778.336 [science_to_67_70:G.Buoyancy] Running Loop=1
2012-10-04T15:32:58.337Z,1349364778.337 [science_to_67_70:Science] Running Loop=1
2012-10-04T15:32:58.337Z,1349364778.337 [science_to_67_70:Science:G] Running Loop=1
2012-10-04T15:32:58.338Z,1349364778.338 [science_to_67_70:Science:D] Running Loop=1
2012-10-04T15:32:58.338Z,1349364778.338 [science_to_67_70:Science:B] Running Loop=1
2012-10-04T15:32:58.456Z,1349364778.456 [Turbulence_NPS](INFO): Resume powering up
2012-10-04T15:32:58.459Z,1349364778.459 [Turbulence_NPS](INFO): Opening uart, block timeout 10ths=1
2012-10-04T15:32:58.523Z,1349364778.523 [WetLabsBB2FL](INFO): Opening uart, block timeout 10ths=4
2012-10-04T15:32:58.523Z,1349364778.523 [WetLabsBB2FL](INFO): Powering up
2012-10-04T15:33:06.207Z,1349364786.207 [Reporter](INFO): Turbulence_NPS.packetNum 2 count
2012-10-04T15:33:06.612Z,1349364786.612 [Reporter](INFO): Turbulence_NPS.packetNum 8 count
2012-10-04T15:33:06.880Z,1349364786.880 [NAL9602](INFO): SBD MO Status=0, MOMSN=1310, MT Status=0, MTMSN=0
2012-10-04T15:33:07.101Z,1349364787.101 [Reporter](INFO): Turbulence_NPS.packetNum 16 count
2012-10-04T15:33:07.525Z,1349364787.525 [Reporter](INFO): Turbulence_NPS.packetNum 23 count
2012-10-04T15:33:07.930Z,1349364787.930 [Reporter](INFO): Turbulence_NPS.packetNum 29 count
2012-10-04T15:33:08.433Z,1349364788.433 [Reporter](INFO): Turbulence_NPS.packetNum 37 count
2012-10-04T15:33:08.808Z,1349364788.808 [Reporter](INFO): Turbulence_NPS.packetNum 43 count
2012-10-04T15:33:09.230Z,1349364789.230 [Reporter](INFO): Turbulence_NPS.packetNum 50 count
2012-10-04T15:33:09.612Z,1349364789.612 [Reporter](INFO): Turbulence_NPS.packetNum 56 count
2012-10-04T15:33:10.062Z,1349364790.062 [Reporter](INFO): Turbulence_NPS.packetNum 63 count
2012-10-04T15:33:11.020Z,1349364791.020 [Reporter](INFO): Turbulence_NPS.packetNum 79 count
2012-10-04T15:33:11.447Z,1349364791.447 [Reporter](INFO): Turbulence_NPS.packetNum 86 count
2012-10-04T15:33:11.870Z,1349364791.870 [Reporter](INFO): Turbulence_NPS.packetNum 92 count
2012-10-04T15:33:12.263Z,1349364792.263 [Reporter](INFO): Turbulence_NPS.packetNum 99 count
2012-10-04T15:33:12.524Z,1349364792.524 [NAL9602](INFO): Powering down
2012-10-04T15:33:12.721Z,1349364792.721 [Reporter](INFO): Turbulence_NPS.packetNum 106 count
2012-10-04T15:33:13.221Z,1349364793.221 [Reporter](INFO): Turbulence_NPS.packetNum 114 count
2012-10-04T15:33:13.570Z,1349364793.570 [Reporter](INFO): Turbulence_NPS.packetNum 120 count
2012-10-04T15:33:13.983Z,1349364793.983 [Reporter](INFO): Turbulence_NPS.packetNum 126 count
2012-10-04T15:33:14.378Z,1349364794.378 [Reporter](INFO): Turbulence_NPS.packetNum 133 count
2012-10-04T15:33:14.816Z,1349364794.816 [Reporter](INFO): Turbulence_NPS.packetNum 140 count
2012-10-04T15:33:15.188Z,1349364795.188 [Reporter](INFO): Turbulence_NPS.packetNum 145 count
2012-10-04T15:33:15.592Z,1349364795.592 [Reporter](INFO): Turbulence_NPS.packetNum 152 count
2012-10-04T15:33:15.989Z,1349364795.989 [Reporter](INFO): Turbulence_NPS.packetNum 158 count
2012-10-04T15:33:16.408Z,1349364796.408 [Reporter](INFO): Turbulence_NPS.packetNum 165 count
2012-10-04T15:33:16.789Z,1349364796.789 [Reporter](INFO): Turbulence_NPS.packetNum 171 count
2012-10-04T15:33:17.175Z,1349364797.174 [Reporter](INFO): Turbulence_NPS.packetNum 177 count
2012-10-04T15:33:17.589Z,1349364797.589 [Reporter](INFO): Turbulence_NPS.packetNum 184 count
2012-10-04T15:33:18.022Z,1349364798.022 [Reporter](INFO): Turbulence_NPS.packetNum 191 count
2012-10-04T15:33:18.415Z,1349364798.415 [Reporter](INFO): Turbulence_NPS.packetNum 197 count
2012-10-04T15:33:18.820Z,1349364798.820 [Reporter](INFO): Turbulence_NPS.packetNum 204 count
2012-10-04T15:33:19.174Z,1349364799.174 [Reporter](INFO): Turbulence_NPS.packetNum 209 count
2012-10-04T15:33:19.585Z,1349364799.585 [Reporter](INFO): Turbulence_NPS.packetNum 216 count
2012-10-04T15:33:19.985Z,1349364799.985 [Reporter](INFO): Turbulence_NPS.packetNum 222 count
2012-10-04T15:33:20.419Z,1349364800.419 [Reporter](INFO): Turbulence_NPS.packetNum 229 count
2012-10-04T15:33:20.831Z,1349364800.831 [Reporter](INFO): Turbulence_NPS.packetNum 236 count
2012-10-04T15:33:21.233Z,1349364801.233 [Reporter](INFO): Turbulence_NPS.packetNum 242 count
2012-10-04T15:33:21.605Z,1349364801.605 [Reporter](INFO): Turbulence_NPS.packetNum 248 count
2012-10-04T15:33:22.009Z,1349364802.009 [Reporter](INFO): Turbulence_NPS.packetNum 255 count
2012-10-04T15:33:22.421Z,1349364802.421 [Reporter](INFO): Turbulence_NPS.packetNum 261 count
2012-10-04T15:33:22.854Z,1349364802.854 [Reporter](INFO): Turbulence_NPS.packetNum 268 count
2012-10-04T15:33:23.254Z,1349364803.254 [Reporter](INFO): Turbulence_NPS.packetNum 275 count
2012-10-04T15:33:23.642Z,1349364803.642 [Reporter](INFO): Turbulence_NPS.packetNum 281 count
2012-10-04T15:33:24.023Z,1349364804.023 [Reporter](INFO): Turbulence_NPS.packetNum 287 count
2012-10-04T15:33:24.417Z,1349364804.417 [Reporter](INFO): Turbulence_NPS.packetNum 293 count
2012-10-04T15:33:24.882Z,1349364804.882 [Reporter](INFO): Turbulence_NPS.packetNum 301 count
2012-10-04T15:33:25.289Z,1349364805.289 [Reporter](INFO): Turbulence_NPS.packetNum 307 count
2012-10-04T15:33:25.715Z,1349364805.715 [Reporter](INFO): Turbulence_NPS.packetNum 314 count
2012-10-04T15:33:26.130Z,1349364806.130 [Reporter](INFO): Turbulence_NPS.packetNum 321 count
2012-10-04T15:33:26.546Z,1349364806.546 [Reporter](INFO): Turbulence_NPS.packetNum 328 count
2012-10-04T15:33:26.938Z,1349364806.938 [Reporter](INFO): Turbulence_NPS.packetNum 334 count
2012-10-04T15:33:27.366Z,1349364807.366 [Reporter](INFO): Turbulence_NPS.packetNum 341 count
2012-10-04T15:33:27.781Z,1349364807.781 [Reporter](INFO): Turbulence_NPS.packetNum 347 count
2012-10-04T15:33:28.208Z,1349364808.208 [Reporter](INFO): Turbulence_NPS.packetNum 354 count
2012-10-04T15:33:28.615Z,1349364808.615 [Reporter](INFO): Turbulence_NPS.packetNum 361 count
2012-10-04T15:33:29.022Z,1349364809.022 [Reporter](INFO): Turbulence_NPS.packetNum 367 count
2012-10-04T15:33:29.419Z,1349364809.419 [Reporter](INFO): Turbulence_NPS.packetNum 374 count
2012-10-04T15:33:29.846Z,1349364809.846 [Reporter](INFO): Turbulence_NPS.packetNum 380 count
2012-10-04T15:33:30.277Z,1349364810.277 [Reporter](INFO): Turbulence_NPS.packetNum 387 count
2012-10-04T15:33:43.307Z,1349364823.307 [Reporter](INFO): Turbulence_NPS.packetNum 0 count
2012-10-04T15:33:43.661Z,1349364823.661 [Reporter](INFO): Turbulence_NPS.packetNum 6 count
2012-10-04T15:33:44.069Z,1349364824.069 [Reporter](INFO): Turbulence_NPS.packetNum 13 count
2012-10-04T15:33:44.461Z,1349364824.461 [Reporter](INFO): Turbulence_NPS.packetNum 19 count
2012-10-04T15:33:44.950Z,1349364824.950 [Reporter](INFO): Turbulence_NPS.packetNum 27 count
2012-10-04T15:33:45.362Z,1349364825.362 [Reporter](INFO): Turbulence_NPS.packetNum 33 count
2012-10-04T15:33:45.762Z,1349364825.762 [Reporter](INFO): Turbulence_NPS.packetNum 40 count
2012-10-04T15:33:46.172Z,1349364826.172 [Reporter](INFO): Turbulence_NPS.packetNum 46 count
2012-10-04T15:33:46.550Z,1349364826.550 [Reporter](INFO): Turbulence_NPS.packetNum 52 count
2012-10-04T15:33:46.972Z,1349364826.972 [Reporter](INFO): Turbulence_NPS.packetNum 59 count
2012-10-04T15:33:47.376Z,1349364827.376 [Reporter](INFO): Turbulence_NPS.packetNum 66 count
2012-10-04T15:33:47.803Z,1349364827.803 [Reporter](INFO): Turbulence_NPS.packetNum 72 count
2012-10-04T15:33:48.218Z,1349364828.218 [Reporter](INFO): Turbulence_NPS.packetNum 79 count
2012-10-04T15:33:48.643Z,1349364828.643 [Reporter](INFO): Turbulence_NPS.packetNum 86 count
2012-10-04T15:33:49.018Z,1349364829.018 [Reporter](INFO): Turbulence_NPS.packetNum 92 count
2012-10-04T15:33:49.461Z,1349364829.461 [Reporter](INFO): Turbulence_NPS.packetNum 99 count
2012-10-04T15:33:49.898Z,1349364829.898 [Reporter](INFO): Turbulence_NPS.packetNum 106 count
2012-10-04T15:33:50.422Z,1349364830.422 [Reporter](INFO): Turbulence_NPS.packetNum 114 count
2012-10-04T15:33:50.822Z,1349364830.822 [Reporter](INFO): Turbulence_NPS.packetNum 121 count
2012-10-04T15:33:51.211Z,1349364831.211 [Reporter](INFO): Turbulence_NPS.packetNum 127 count
2012-10-04T15:33:51.617Z,1349364831.617 [Reporter](INFO): Turbulence_NPS.packetNum 134 count
2012-10-04T15:33:52.015Z,1349364832.015 [Reporter](INFO): Turbulence_NPS.packetNum 140 count
2012-10-04T15:33:52.433Z,1349364832.433 [Reporter](INFO): Turbulence_NPS.packetNum 147 count
2012-10-04T15:33:52.830Z,1349364832.830 [Reporter](INFO): Turbulence_NPS.packetNum 153 count
2012-10-04T15:33:53.234Z,1349364833.234 [Reporter](INFO): Turbulence_NPS.packetNum 159 count
2012-10-04T15:33:53.642Z,1349364833.642 [Reporter](INFO): Turbulence_NPS.packetNum 166 count
2012-10-04T15:33:54.026Z,1349364834.026 [Reporter](INFO): Turbulence_NPS.packetNum 169 count
2012-10-04T15:33:54.135Z,1349364834.135 [Turbulence_NPS](ERROR): Uart error: serial buffer full
2012-10-04T15:33:54.135Z,1349364834.135 [Turbulence_NPS] Communications Fault, FailCount= 1
2012-10-04T15:33:54.135Z,1349364834.135 [Turbulence_NPS](ERROR): Communications Fault
2012-10-04T15:33:54.137Z,1349364834.137 [Turbulence_NPS](INFO): Uninitialize Powering down
2012-10-04T15:33:54.433Z,1349364834.433 [CBIT](ERROR): Communications Fault in component: Turbulence_NPS
2012-10-04T15:33:54.433Z,1349364834.433 [CBIT](INFO): Clearing failed state for component Turbulence_NPS
2012-10-04T15:33:54.433Z,1349364834.433 [Turbulence_NPS] No Fault, FailCount= 1
2012-10-04T15:33:54.539Z,1349364834.539 [Turbulence_NPS](DEBUG): Initializing Turbulence_NPS.
2012-10-04T15:33:54.540Z,1349364834.540 [Turbulence_NPS](INFO): Opening uart, block timeout 10ths=1
2012-10-04T15:34:02.030Z,1349364842.030 [Reporter](INFO): Turbulence_NPS.packetNum 0 count
2012-10-04T15:34:02.477Z,1349364842.477 [Reporter](INFO): Turbulence_NPS.packetNum 7 count
2012-10-04T15:34:02.856Z,1349364842.856 [Reporter](INFO): Turbulence_NPS.packetNum 13 count
2012-10-04T15:34:03.261Z,1349364843.260 [Reporter](INFO): Turbulence_NPS.packetNum 19 count
2012-10-04T15:34:03.660Z,1349364843.660 [Reporter](INFO): Turbulence_NPS.packetNum 26 count
2012-10-04T15:34:04.048Z,1349364844.048 [Reporter](INFO): Turbulence_NPS.packetNum 32 count
2012-10-04T15:34:04.460Z,1349364844.460 [Reporter](INFO): Turbulence_NPS.packetNum 39 count
2012-10-04T15:34:04.848Z,1349364844.848 [Reporter](INFO): Turbulence_NPS.packetNum 41 count
2012-10-04T15:34:12.823Z,1349364852.823 [Turbulence_NPS](ERROR): Uart error: serial buffer full
2012-10-04T15:34:12.824Z,1349364852.824 [Turbulence_NPS] Communications Fault, FailCount= 2
2012-10-04T15:34:12.824Z,1349364852.824 [Turbulence_NPS](ERROR): Communications Fault
2012-10-04T15:34:12.826Z,1349364852.826 [Turbulence_NPS](INFO): Uninitialize Powering down
2012-10-04T15:34:12.942Z,1349364852.942 [CBIT](ERROR): Communications Fault in component: Turbulence_NPS
2012-10-04T15:34:12.943Z,1349364852.943 [CBIT](INFO): Clearing failed state for component Turbulence_NPS
2012-10-04T15:34:12.943Z,1349364852.943 [Turbulence_NPS] No Fault, FailCount= 2
2012-10-04T15:34:13.231Z,1349364853.231 [Turbulence_NPS](DEBUG): Initializing Turbulence_NPS.
2012-10-04T15:34:13.232Z,1349364853.232 [Turbulence_NPS](INFO): Opening uart, block timeout 10ths=1
2012-10-04T15:34:21.085Z,1349364861.085 [Reporter](INFO): Turbulence_NPS.packetNum 6 count
2012-10-04T15:34:21.487Z,1349364861.487 [Reporter](INFO): Turbulence_NPS.packetNum 12 count
2012-10-04T15:34:21.890Z,1349364861.890 [Reporter](INFO): Turbulence_NPS.packetNum 18 count
2012-10-04T15:34:22.279Z,1349364862.279 [Reporter](INFO): Turbulence_NPS.packetNum 25 count
2012-10-04T15:34:22.743Z,1349364862.743 [Reporter](INFO): Turbulence_NPS.packetNum 32 count
2012-10-04T15:34:23.161Z,1349364863.161 [Reporter](INFO): Turbulence_NPS.packetNum 39 count
2012-10-04T15:34:23.559Z,1349364863.559 [Reporter](INFO): Turbulence_NPS.packetNum 45 count
2012-10-04T15:34:23.998Z,1349364863.998 [Reporter](INFO): Turbulence_NPS.packetNum 52 count
2012-10-04T15:34:24.418Z,1349364864.418 [Reporter](INFO): Turbulence_NPS.packetNum 59 count
2012-10-04T15:34:24.860Z,1349364864.860 [Reporter](INFO): Turbulence_NPS.packetNum 66 count
2012-10-04T15:34:25.265Z,1349364865.265 [Reporter](INFO): Turbulence_NPS.packetNum 72 count
2012-10-04T15:34:25.774Z,1349364865.774 [Reporter](INFO): Turbulence_NPS.packetNum 81 count
2012-10-04T15:34:26.167Z,1349364866.167 [Reporter](INFO): Turbulence_NPS.packetNum 87 count
2012-10-04T15:34:26.582Z,1349364866.582 [Reporter](INFO): Turbulence_NPS.packetNum 94 count
2012-10-04T15:34:26.979Z,1349364866.979 [Reporter](INFO): Turbulence_NPS.packetNum 100 count
2012-10-04T15:34:27.063Z,1349364867.063 [CommandLine](IMPORTANT): got command stop
2012-10-04T15:34:27.264Z,1349364867.264 [science_to_67_70] Stopped
2012-10-04T15:34:27.264Z,1349364867.264 [science_to_67_70](INFO): Aggregate::uninitialize science_to_67_70
2012-10-04T15:34:27.264Z,1349364867.264 [science_to_67_70:AltEnvWrapper] Stopped
2012-10-04T15:34:27.264Z,1349364867.264 [science_to_67_70:AltEnvWrapper](INFO): Aggregate::uninitialize science_to_67_70:AltEnvWrapper
2012-10-04T15:34:27.264Z,1349364867.264 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope] Stopped
2012-10-04T15:34:27.264Z,1349364867.264 [science_to_67_70:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2012-10-04T15:34:27.264Z,1349364867.264 [science_to_67_70:AltEnvWrapper:B.Wait] Stopped
2012-10-04T15:34:27.264Z,1349364867.264 [science_to_67_70:AltEnvWrapper:B.Wait](DEBUG): Uninitialize Wait Component.
2012-10-04T15:34:27.264Z,1349364867.264 [science_to_67_70:B.DepthEnvelope] Stopped
2012-10-04T15:34:27.264Z,1349364867.264 [science_to_67_70:B.DepthEnvelope](DEBUG): Uninitialize.
2012-10-04T15:34:27.265Z,1349364867.265 [science_to_67_70:OffshoreWrapper] Stopped
2012-10-04T15:34:27.265Z,1349364867.265 [science_to_67_70:OffshoreWrapper](INFO): Aggregate::uninitialize science_to_67_70:OffshoreWrapper
2012-10-04T15:34:27.265Z,1349364867.265 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope] Stopped
2012-10-04T15:34:27.265Z,1349364867.265 [science_to_67_70:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2012-10-04T15:34:27.265Z,1349364867.265 [science_to_67_70:OffshoreWrapper:B.Wait] Stopped
2012-10-04T15:34:27.265Z,1349364867.265 [science_to_67_70:OffshoreWrapper:B.Wait](DEBUG): Uninitialize Wait Component.
2012-10-04T15:34:27.265Z,1349364867.265 [science_to_67_70:Science] Stopped
2012-10-04T15:34:27.265Z,1349364867.265 [science_to_67_70:Science](INFO): Aggregate::uninitialize science_to_67_70:Science
2012-10-04T15:34:27.265Z,1349364867.265 [science_to_67_70:Science:A] Stopped
2012-10-04T15:34:27.265Z,1349364867.265 [science_to_67_70:Science:B] Stopped
2012-10-04T15:34:27.265Z,1349364867.265 [science_to_67_70:Science:C] Stopped
2012-10-04T15:34:27.265Z,1349364867.265 [science_to_67_70:Science:D] Stopped
2012-10-04T15:34:27.266Z,1349364867.266 [science_to_67_70:Science:E] Stopped
2012-10-04T15:34:27.266Z,1349364867.266 [science_to_67_70:Science:F] Stopped
2012-10-04T15:34:27.266Z,1349364867.266 [science_to_67_70:Science:G] Stopped
2012-10-04T15:34:27.266Z,1349364867.266 [science_to_67_70:Science:PeakDetectChl] Stopped
2012-10-04T15:34:27.266Z,1349364867.266 [science_to_67_70:Science:PeakDetectChl](INFO): Aggregate::uninitialize science_to_67_70:Science:PeakDetectChl
2012-10-04T15:34:27.266Z,1349364867.266 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth] Stopped
2012-10-04T15:34:27.266Z,1349364867.266 [science_to_67_70:Science:PeakDetectChl:A.PeakDetectVsDepth](DEBUG): Uninitialize.
2012-10-04T15:34:27.266Z,1349364867.266 [science_to_67_70:G.Buoyancy] Stopped
2012-10-04T15:34:27.266Z,1349364867.266 [science_to_67_70:G.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2012-10-04T15:34:27.266Z,1349364867.266 [science_to_67_70:H.SetSpeed] Stopped
2012-10-04T15:34:27.267Z,1349364867.267 [science_to_67_70:H.SetSpeed](DEBUG): Uninitialize.
2012-10-04T15:34:27.267Z,1349364867.267 [science_to_67_70:I.DepthEnvelope] Stopped
2012-10-04T15:34:27.267Z,1349364867.267 [science_to_67_70:I.DepthEnvelope](DEBUG): Uninitialize.
2012-10-04T15:34:27.267Z,1349364867.267 [science_to_67_70:J.YoYo] Stopped
2012-10-04T15:34:27.267Z,1349364867.267 [science_to_67_70:J.YoYo](DEBUG): Uninitialize YoYoComponent.
2012-10-04T15:34:27.267Z,1349364867.267 [science_to_67_70:Wpt.Point] Stopped
2012-10-04T15:34:27.324Z,1349364867.324 [Turbulence_NPS](INFO): Pause powering down
2012-10-04T15:34:27.451Z,1349364867.451 [WetLabsBB2FL](INFO): Powering down
2012-10-04T15:34:27.469Z,1349364867.469 [Reporter](INFO): Turbulence_NPS.packetNum no_value
2012-10-04T15:34:27.761Z,1349364867.761 [MissionManager](IMPORTANT): Started mission Default
2012-10-04T15:34:27.761Z,1349364867.761 [Default] Running Loop=1
2012-10-04T15:34:27.761Z,1349364867.761 [Default](INFO): Aggregate::initialize Default
2012-10-04T15:34:27.761Z,1349364867.761 [Default:D.SetSpeed] Running Loop=1
2012-10-04T15:34:27.761Z,1349364867.761 [Default:D.SetSpeed](DEBUG): Initialize.
2012-10-04T15:34:27.761Z,1349364867.761 [Default:E.GoToSurface] Running Loop=1
2012-10-04T15:34:27.761Z,1349364867.761 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-04T15:34:27.761Z,1349364867.761 [Default:Iridium] Running Loop=1
2012-10-04T15:34:27.762Z,1349364867.761 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-10-04T15:34:27.762Z,1349364867.762 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-10-04T15:34:27.762Z,1349364867.762 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-10-04T15:34:27.762Z,1349364867.762 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-10-04T15:34:27.762Z,1349364867.762 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-10-04T15:34:27.767Z,1349364867.767 [Default:Iridium:B.GoToSurface] Stopped
2012-10-04T15:34:27.767Z,1349364867.767 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-04T15:34:27.767Z,1349364867.767 [Default:Iridium:Read_Iridium] Running Loop=1
2012-10-04T15:34:28.554Z,1349364868.554 [NAL9602](INFO): Powering up
2012-10-04T15:34:32.299Z,1349364872.299 [CommandLine](IMPORTANT): got command restart application
2012-10-04T15:34:33.387Z,1349364873.387 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2012-10-04T15:34:33.387Z,1349364873.387 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2012-10-04T15:34:33.550Z,1349364873.550 [ComponentRegistry](INFO): Shutting down WetLabsBB2FL ThreadHandler
2012-10-04T15:34:33.915Z,1349364873.915 [WetLabsBB2FL](INFO): Powering down
2012-10-04T15:34:33.930Z,1349364873.930 [ComponentRegistry](INFO): Shutting down Turbulence_NPS ThreadHandler
2012-10-04T15:34:34.195Z,1349364874.195 [Turbulence_NPS](INFO): Uninitialize Powering down
2012-10-04T15:34:34.210Z,1349364874.210 [ComponentRegistry](INFO): Shutting down AsyncPiEstimator ThreadHandler
2012-10-04T15:34:34.427Z,1349364874.427 [AsyncPiEstimator](DEBUG): Uninitialize AsyncPiEstimator.
2012-10-04T15:34:34.438Z,1349364874.438 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2012-10-04T15:34:34.440Z,1349364874.440 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2012-10-04T15:34:34.653Z,1349364874.653 [controlThread](DEBUG): Uninitializing ControlThread
2012-10-04T15:34:34.654Z,1349364874.654 [AHRS_sp3003D](INFO): Powering down
2012-10-04T15:34:34.743Z,1349364874.743 [NAL9602](INFO): Powering down
2012-10-04T15:34:34.745Z,1349364874.745 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2012-10-04T15:34:34.746Z,1349364874.746 [NavChart](DEBUG): Uninitialize NavChart Derivation.
2012-10-04T15:34:34.747Z,1349364874.747 [Default] Stopped
2012-10-04T15:34:34.747Z,1349364874.747 [Default](INFO): Aggregate::uninitialize Default
2012-10-04T15:34:34.747Z,1349364874.747 [Default:Iridium] Stopped
2012-10-04T15:34:34.748Z,1349364874.747 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-10-04T15:34:34.748Z,1349364874.748 [Default:Iridium:A.SetSpeed] Stopped
2012-10-04T15:34:34.748Z,1349364874.748 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-10-04T15:34:34.748Z,1349364874.748 [Default:Iridium:Read_Iridium] Stopped
2012-10-04T15:34:34.748Z,1349364874.748 [Default:D.SetSpeed] Stopped
2012-10-04T15:34:34.748Z,1349364874.748 [Default:D.SetSpeed](DEBUG): Uninitialize.
2012-10-04T15:34:34.748Z,1349364874.748 [Default:E.GoToSurface] Stopped
2012-10-04T15:34:34.748Z,1349364874.748 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-10-04T15:34:34.752Z,1349364874.752 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2012-10-04T15:34:34.753Z,1349364874.753 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2012-10-04T15:34:34.753Z,1349364874.753 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2012-10-04T15:34:34.753Z,1349364874.753 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2012-10-04T15:34:34.753Z,1349364874.753 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2012-10-04T15:34:34.754Z,1349364874.754 [BuoyancyServo](INFO): Powering down
2012-10-04T15:34:34.759Z,1349364874.759 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2012-10-04T15:34:34.759Z,1349364874.759 [MassServo](DEBUG): Uninitialize Mass Servo.
2012-10-04T15:34:34.759Z,1349364874.759 [MassServo](INFO): Powering down
2012-10-04T15:34:34.760Z,1349364874.760 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2012-10-04T15:34:34.760Z,1349364874.760 [RudderServo](INFO): Powering down
2012-10-04T15:34:34.761Z,1349364874.761 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2012-10-04T15:34:34.761Z,1349364874.761 [ThrusterServo](INFO): Powering down
2012-10-04T15:34:34.762Z,1349364874.762 [SBIT](DEBUG): Uninitialize SBIT Component.
2012-10-04T15:34:34.762Z,1349364874.762 [IBIT](DEBUG): Uninitialize IBIT Component.
2012-10-04T15:34:34.762Z,1349364874.762 [CBIT](DEBUG): Uninitialize CBIT Component.