2012-06-19T18:14:47.799Z,1340129687.799 [Supervisor](DEBUG): Initializing supervisor.
2012-06-19T18:14:47.799Z,1340129687.799 [SyncHandler](DEBUG): Created PCaller Thread at -1219613840
2012-06-19T18:14:47.799Z,1340129687.799 [ComponentRegistry](DEBUG): Component "controlThread" handled in its own thread.
2012-06-19T18:14:47.799Z,1340129687.799 [controlThread ThreadHandler](DEBUG): Created PCaller Thread at -1219814544
2012-06-19T18:14:47.799Z,1340129687.799 [ComponentRegistry](DEBUG): SyncComponent "CycleStarter" handled in the control thread.
2012-06-19T18:14:47.799Z,1340129687.799 [ComponentRegistry](DEBUG): Component "CommandLine" handled in its own thread.
2012-06-19T18:14:47.799Z,1340129687.799 [CommandLine ThreadHandler](DEBUG): Created PCaller Thread at -1220015248
2012-06-19T18:14:47.799Z,1340129687.799 [ComponentRegistry](DEBUG): SyncComponent "logger" handled in the control thread.
2012-06-19T18:14:47.799Z,1340129687.799 [Supervisor](INFO): Looking for Config files in directory: Config/
2012-06-19T18:14:47.799Z,1340129687.799 [Supervisor](INFO): Opening Config file at: Config/vehicle.cfg
2012-06-19T18:14:47.804Z,1340129687.804 [ComponentRegistry](DEBUG): Loaded Config Component "Config/vehicle
2012-06-19T18:14:47.804Z,1340129687.804 [Supervisor](INFO): Opening Config file at: Config/Sensor.cfg
2012-06-19T18:14:47.808Z,1340129687.808 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sensor
2012-06-19T18:14:47.808Z,1340129687.808 [Supervisor](INFO): Opening Config file at: Config/Sample.cfg
2012-06-19T18:14:47.811Z,1340129687.811 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Sample
2012-06-19T18:14:47.811Z,1340129687.811 [Supervisor](INFO): Opening Config file at: Config/logger.cfg
2012-06-19T18:14:47.815Z,1340129687.815 [ComponentRegistry](DEBUG): Loaded Config Component "Config/logger
2012-06-19T18:14:47.815Z,1340129687.815 [Supervisor](INFO): Opening Config file at: Config/BIT.cfg
2012-06-19T18:14:47.819Z,1340129687.819 [ComponentRegistry](DEBUG): Loaded Config Component "Config/BIT
2012-06-19T18:14:47.819Z,1340129687.819 [Supervisor](INFO): Opening Config file at: Config/Servo.cfg
2012-06-19T18:14:47.823Z,1340129687.823 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Servo
2012-06-19T18:14:47.823Z,1340129687.823 [Supervisor](INFO): Opening Config file at: Config/Science.cfg
2012-06-19T18:14:47.843Z,1340129687.843 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Science
2012-06-19T18:14:47.843Z,1340129687.843 [Supervisor](INFO): Opening Config file at: Config/Control.cfg
2012-06-19T18:14:47.847Z,1340129687.847 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Control
2012-06-19T18:14:47.847Z,1340129687.847 [Supervisor](INFO): Opening Config file at: Config/workSite.cfg
2012-06-19T18:14:47.850Z,1340129687.850 [ComponentRegistry](DEBUG): Loaded Config Component "Config/workSite
2012-06-19T18:14:47.850Z,1340129687.850 [Supervisor](INFO): Opening Config file at: Config/Simulator.cfg
2012-06-19T18:14:47.857Z,1340129687.857 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Simulator
2012-06-19T18:14:47.857Z,1340129687.857 [Supervisor](INFO): Opening Config file at: Config/Derivation.cfg
2012-06-19T18:14:47.860Z,1340129687.860 [ComponentRegistry](DEBUG): Loaded Config Component "Config/Derivation
2012-06-19T18:14:47.860Z,1340129687.860 [Supervisor](INFO): Opening Config file at: Config/Guidance.cfg
2012-06-19T18:14:47.863Z,1340129687.863 [Supervisor](INFO): Looking for Config files in directory: Config/tellum/
2012-06-19T18:14:47.863Z,1340129687.863 [Supervisor](INFO): Opening Config file at: Config/tellum/vehicle.cfg
2012-06-19T18:14:47.866Z,1340129687.866 [Supervisor](INFO): Looking for Config files in directory: Config/tellum/rob/
2012-06-19T18:14:47.866Z,1340129687.866 [Supervisor](INFO): Looking for Config files in directory: Config/sim/
2012-06-19T18:14:47.866Z,1340129687.866 [Supervisor](INFO): Opening Config file at: Config/sim/vehicle.cfg
2012-06-19T18:14:47.869Z,1340129687.869 [Supervisor](INFO): Opening Config file at: Config/sim/Sensor.cfg
2012-06-19T18:14:47.872Z,1340129687.872 [Supervisor](INFO): Opening Config file at: Config/sim/Servo.cfg
2012-06-19T18:14:47.876Z,1340129687.876 [Supervisor](INFO): Opening Config file at: Config/sim/Science.cfg
2012-06-19T18:14:47.879Z,1340129687.879 [Supervisor](INFO): Opening Config file at: Config/sim/Simulator.cfg
2012-06-19T18:14:47.882Z,1340129687.882 [Supervisor](INFO): Opening Config file at: Config/sim/Derivation.cfg
2012-06-19T18:14:47.885Z,1340129687.885 [Supervisor](INFO): Looking for Config files in directory: Config/sim/rob/
2012-06-19T18:14:47.885Z,1340129687.885 [Module Loader](DEBUG): Loading Module at Modules/Simulator.so
2012-06-19T18:14:47.887Z,1340129687.887 [ExternalSim] Loaded
2012-06-19T18:14:47.887Z,1340129687.887 [ComponentRegistry](DEBUG): SyncComponent "ExternalSim" handled in the control thread.
2012-06-19T18:14:47.887Z,1340129687.887 [InternalSim] Loaded
2012-06-19T18:14:47.887Z,1340129687.887 [ComponentRegistry](DEBUG): SyncComponent "InternalSim" handled in the control thread.
2012-06-19T18:14:47.887Z,1340129687.887 [Module Loader](DEBUG): Loaded Module: Simulator (This is the module containing the Simulator)
2012-06-19T18:14:47.887Z,1340129687.887 [Module Loader](DEBUG): Loading Module at Modules/BIT.so
2012-06-19T18:14:47.888Z,1340129687.888 [SBIT](DEBUG): Construct Startup Built In Test.
2012-06-19T18:14:47.888Z,1340129687.888 [SBIT] Loaded
2012-06-19T18:14:47.888Z,1340129687.888 [ComponentRegistry](DEBUG): SyncComponent "SBIT" handled in the control thread.
2012-06-19T18:14:47.888Z,1340129687.888 [IBIT](DEBUG): Construct Initiated Built In Test.
2012-06-19T18:14:47.888Z,1340129687.888 [IBIT] Loaded
2012-06-19T18:14:47.888Z,1340129687.888 [ComponentRegistry](DEBUG): SyncComponent "IBIT" handled in the control thread.
2012-06-19T18:14:47.888Z,1340129687.888 [CBIT](DEBUG): Construct CBIT Built In Test.
2012-06-19T18:14:47.889Z,1340129687.889 [CBIT] Loaded
2012-06-19T18:14:47.889Z,1340129687.889 [ComponentRegistry](DEBUG): SyncComponent "CBIT" handled in the control thread.
2012-06-19T18:14:47.889Z,1340129687.889 [Module Loader](DEBUG): Loaded Module: BIT (Contains the BuiltInTest components, such as C Built In Test)
2012-06-19T18:14:47.889Z,1340129687.889 [Module Loader](DEBUG): Loading Module at Modules/Servo.so
2012-06-19T18:14:47.942Z,1340129687.942 [BuoyancyServo] Loaded
2012-06-19T18:14:47.942Z,1340129687.942 [ComponentRegistry](DEBUG): SyncComponent "BuoyancyServo" handled in the control thread.
2012-06-19T18:14:47.942Z,1340129687.942 [ElevatorServo] Loaded
2012-06-19T18:14:47.942Z,1340129687.942 [ComponentRegistry](DEBUG): SyncComponent "ElevatorServo" handled in the control thread.
2012-06-19T18:14:47.942Z,1340129687.942 [MassServo] Loaded
2012-06-19T18:14:47.942Z,1340129687.942 [ComponentRegistry](DEBUG): SyncComponent "MassServo" handled in the control thread.
2012-06-19T18:14:47.942Z,1340129687.942 [RudderServo] Loaded
2012-06-19T18:14:47.942Z,1340129687.942 [ComponentRegistry](DEBUG): SyncComponent "RudderServo" handled in the control thread.
2012-06-19T18:14:47.942Z,1340129687.942 [ThrusterServo] Loaded
2012-06-19T18:14:47.942Z,1340129687.942 [ComponentRegistry](DEBUG): SyncComponent "ThrusterServo" handled in the control thread.
2012-06-19T18:14:47.942Z,1340129687.942 [Module Loader](DEBUG): Loaded Module: Servo (This is the module containing motor controllers)
2012-06-19T18:14:47.942Z,1340129687.942 [Module Loader](DEBUG): Loading Module at Modules/Derivation.so
2012-06-19T18:14:47.942Z,1340129687.942 [DepthRateCalculator] Loaded
2012-06-19T18:14:47.942Z,1340129687.942 [ComponentRegistry](DEBUG): SyncComponent "DepthRateCalculator" handled in the control thread.
2012-06-19T18:14:47.943Z,1340129687.943 [NavChart] Loaded
2012-06-19T18:14:47.943Z,1340129687.943 [ComponentRegistry](DEBUG): SyncComponent "NavChart" handled in the control thread.
2012-06-19T18:14:47.943Z,1340129687.943 [PitchRateCalculator] Loaded
2012-06-19T18:14:47.943Z,1340129687.943 [ComponentRegistry](DEBUG): SyncComponent "PitchRateCalculator" handled in the control thread.
2012-06-19T18:14:47.943Z,1340129687.943 [SpeedCalculator] Loaded
2012-06-19T18:14:47.943Z,1340129687.943 [ComponentRegistry](DEBUG): SyncComponent "SpeedCalculator" handled in the control thread.
2012-06-19T18:14:47.943Z,1340129687.943 [TempGradientCalculator] Loaded
2012-06-19T18:14:47.943Z,1340129687.943 [ComponentRegistry](DEBUG): SyncComponent "TempGradientCalculator" handled in the control thread.
2012-06-19T18:14:47.943Z,1340129687.943 [YawRateCalculator] Loaded
2012-06-19T18:14:47.943Z,1340129687.943 [ComponentRegistry](DEBUG): SyncComponent "YawRateCalculator" handled in the control thread.
2012-06-19T18:14:47.943Z,1340129687.943 [Navigation] Loaded
2012-06-19T18:14:47.943Z,1340129687.943 [ComponentRegistry](DEBUG): SyncComponent "Navigation" handled in the control thread.
2012-06-19T18:14:47.943Z,1340129687.943 [Module Loader](DEBUG): Loaded Module: Derivation (Contains the base derivation components)
2012-06-19T18:14:47.943Z,1340129687.943 [Module Loader](DEBUG): Loading Module at Modules/Guidance.so
2012-06-19T18:14:47.945Z,1340129687.945 [Module Loader](DEBUG): Loaded Module: Guidance (Contains behaviors and commands)
2012-06-19T18:14:47.945Z,1340129687.945 [Module Loader](DEBUG): Loading Module at Modules/Trigger.so
2012-06-19T18:14:47.945Z,1340129687.945 [Module Loader](DEBUG): Loaded Module: Trigger (Contains triggers for use in missions)
2012-06-19T18:14:47.945Z,1340129687.945 [Module Loader](DEBUG): Loading Module at Modules/Control.so
2012-06-19T18:14:47.945Z,1340129687.945 [VerticalControl](DEBUG): Construct VerticalControl.
2012-06-19T18:14:47.946Z,1340129687.946 [VerticalControl] Loaded
2012-06-19T18:14:47.946Z,1340129687.946 [ComponentRegistry](DEBUG): SyncComponent "VerticalControl" handled in the control thread.
2012-06-19T18:14:47.946Z,1340129687.946 [HorizontalControl](DEBUG): Construct HorizontalControl.
2012-06-19T18:14:47.946Z,1340129687.946 [HorizontalControl] Loaded
2012-06-19T18:14:47.946Z,1340129687.946 [ComponentRegistry](DEBUG): SyncComponent "HorizontalControl" handled in the control thread.
2012-06-19T18:14:47.946Z,1340129687.946 [SpeedControl](DEBUG): Construct SpeedControl.
2012-06-19T18:14:47.946Z,1340129687.946 [SpeedControl] Loaded
2012-06-19T18:14:47.946Z,1340129687.946 [ComponentRegistry](DEBUG): SyncComponent "SpeedControl" handled in the control thread.
2012-06-19T18:14:47.946Z,1340129687.946 [LoopControl](DEBUG): Construct LoopControl.
2012-06-19T18:14:47.946Z,1340129687.946 [LoopControl] Loaded
2012-06-19T18:14:47.946Z,1340129687.946 [ComponentRegistry](DEBUG): SyncComponent "LoopControl" handled in the control thread.
2012-06-19T18:14:47.946Z,1340129687.946 [Module Loader](DEBUG): Loaded Module: Control (Contains the Control components, such as Depth, Heading, and Speed Control)
2012-06-19T18:14:47.946Z,1340129687.946 [Module Loader](DEBUG): Loading Module at Modules/Sample.so
2012-06-19T18:14:47.946Z,1340129687.946 [AsyncPiEstimator](DEBUG): Construct AsyncPiEstimator.
2012-06-19T18:14:47.946Z,1340129687.946 [AsyncPiEstimator] Loaded
2012-06-19T18:14:47.946Z,1340129687.946 [ComponentRegistry](DEBUG): Component "AsyncPiEstimator" handled in its own thread.
2012-06-19T18:14:47.946Z,1340129687.946 [AsyncPiEstimator ThreadHandler](DEBUG): Created PCaller Thread at -1221153936
2012-06-19T18:14:47.946Z,1340129687.946 [Module Loader](DEBUG): Loaded Module: Sample (This is a Sample Module of Sample Components)
2012-06-19T18:14:47.946Z,1340129687.946 [Module Loader](DEBUG): Loading Module at Modules/Sensor.so
2012-06-19T18:14:47.947Z,1340129687.947 [AHRS_sp3003D] Loaded
2012-06-19T18:14:47.947Z,1340129687.947 [ComponentRegistry](DEBUG): SyncComponent "AHRS_sp3003D" handled in the control thread.
2012-06-19T18:14:47.948Z,1340129687.948 [AHRS_3DMGX3] Loaded
2012-06-19T18:14:47.948Z,1340129687.948 [ComponentRegistry](DEBUG): SyncComponent "AHRS_3DMGX3" handled in the control thread.
2012-06-19T18:14:47.948Z,1340129687.948 [Depth_Keller] Loaded
2012-06-19T18:14:47.948Z,1340129687.948 [ComponentRegistry](DEBUG): SyncComponent "Depth_Keller" handled in the control thread.
2012-06-19T18:14:47.948Z,1340129687.948 [DropWeight] Loaded
2012-06-19T18:14:47.948Z,1340129687.948 [ComponentRegistry](DEBUG): SyncComponent "DropWeight" handled in the control thread.
2012-06-19T18:14:47.948Z,1340129687.948 [DVL_micro] Loaded
2012-06-19T18:14:47.948Z,1340129687.948 [ComponentRegistry](DEBUG): SyncComponent "DVL_micro" handled in the control thread.
2012-06-19T18:14:47.949Z,1340129687.949 [NAL9601] Loaded
2012-06-19T18:14:47.949Z,1340129687.949 [ComponentRegistry](DEBUG): SyncComponent "NAL9601" handled in the control thread.
2012-06-19T18:14:47.950Z,1340129687.950 [Onboard] Loaded
2012-06-19T18:14:47.950Z,1340129687.950 [ComponentRegistry](DEBUG): SyncComponent "Onboard" handled in the control thread.
2012-06-19T18:14:47.950Z,1340129687.950 [Radio_Freewave] Loaded
2012-06-19T18:14:47.950Z,1340129687.950 [ComponentRegistry](DEBUG): SyncComponent "Radio_Freewave" handled in the control thread.
2012-06-19T18:14:47.950Z,1340129687.950 [DAT] Loaded
2012-06-19T18:14:47.950Z,1340129687.950 [ComponentRegistry](DEBUG): SyncComponent "DAT" handled in the control thread.
2012-06-19T18:14:47.950Z,1340129687.950 [Module Loader](DEBUG): Loaded Module: Sensor (Contains the sensor components)
2012-06-19T18:14:47.950Z,1340129687.950 [Module Loader](DEBUG): Loading Module at Modules/Science.so
2012-06-19T18:14:47.951Z,1340129687.951 [Aanderaa_O2] Loaded
2012-06-19T18:14:47.951Z,1340129687.951 [ComponentRegistry](DEBUG): SyncComponent "Aanderaa_O2" handled in the control thread.
2012-06-19T18:14:47.951Z,1340129687.951 [CTD_NeilBrown] Loaded
2012-06-19T18:14:47.951Z,1340129687.951 [ComponentRegistry](DEBUG): Component "CTD_NeilBrown" handled in its own thread.
2012-06-19T18:14:47.951Z,1340129687.951 [CTD_NeilBrown ThreadHandler](DEBUG): Created PCaller Thread at -1221784720
2012-06-19T18:14:47.951Z,1340129687.951 [ISUS] Loaded
2012-06-19T18:14:47.951Z,1340129687.951 [ComponentRegistry](DEBUG): SyncComponent "ISUS" handled in the control thread.
2012-06-19T18:14:47.951Z,1340129687.951 [Turbulence_NPS] Loaded
2012-06-19T18:14:47.951Z,1340129687.951 [ComponentRegistry](DEBUG): Component "Turbulence_NPS" handled in its own thread.
2012-06-19T18:14:47.951Z,1340129687.951 [Turbulence_NPS ThreadHandler](DEBUG): Created PCaller Thread at -1221985424
2012-06-19T18:14:47.952Z,1340129687.952 [WetLabsBB2FL] Loaded
2012-06-19T18:14:47.952Z,1340129687.952 [ComponentRegistry](DEBUG): SyncComponent "WetLabsBB2FL" handled in the control thread.
2012-06-19T18:14:47.952Z,1340129687.952 [Module Loader](DEBUG): Loaded Module: Science (Contains the science components)
2012-06-19T18:14:47.952Z,1340129687.952 [ComponentRegistry](DEBUG): SyncComponent "MissionManager" handled in the control thread.
2012-06-19T18:14:47.952Z,1340129687.952 [ComponentRegistry](DEBUG): SyncComponent "Reporter" handled in the control thread.
2012-06-19T18:14:47.952Z,1340129687.952 [ComponentRegistry](DEBUG): Component "NavChartDb" handled in its own thread.
2012-06-19T18:14:47.952Z,1340129687.952 [NavChartDb ThreadHandler](DEBUG): Created PCaller Thread at -1222186128
2012-06-19T18:14:47.952Z,1340129687.952 [Supervisor](DEBUG): Running supervisor.
2012-06-19T18:14:47.952Z,1340129687.952 [controlThread](DEBUG): Initializing ControlThread
2012-06-19T18:14:47.952Z,1340129687.952 [AsyncPiEstimator](DEBUG): Initialize AsyncPiEstimator.
2012-06-19T18:14:47.952Z,1340129687.952 [ExternalSim](INFO): ExternalSim initializing...
2012-06-19T18:14:47.952Z,1340129687.952 [CTD_NeilBrown](DEBUG): Initializing CTD_NeilBrown.
2012-06-19T18:14:47.952Z,1340129687.952 [Turbulence_NPS](DEBUG): Initializing Turbulence_NPS.
2012-06-19T18:14:47.953Z,1340129687.953 [ExternalSim](DEBUG): beaconLat = 36.81340
2012-06-19T18:14:47.953Z,1340129687.953 [ExternalSim](DEBUG): beaconLon = -121.82240
2012-06-19T18:14:47.953Z,1340129687.953 [ExternalSim](DEBUG): beaconDepth = 25.00
2012-06-19T18:14:47.953Z,1340129687.953 [ExternalSim](DEBUG): Simulator initialized
2012-06-19T18:14:47.953Z,1340129687.953 [InternalSim](DEBUG):  InternalSim initializing...
2012-06-19T18:14:47.953Z,1340129687.953 [SBIT](INFO): Initialize SBIT Component.
2012-06-19T18:14:47.953Z,1340129687.953 [SBIT](IMPORTANT): Tethys CM Info:
$Rev: 9677 $
2012-06-19T18:14:47.954Z,1340129687.954 [IBIT](INFO): Initialize IBIT Component.
2012-06-19T18:14:47.954Z,1340129687.954 [CBIT](DEBUG): Initialize CBIT Component.
2012-06-19T18:14:47.954Z,1340129687.954 [CBIT](INFO): Last reboot was NOT due to watchdog timer.
2012-06-19T18:14:47.954Z,1340129687.954 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2012-06-19T18:14:47.954Z,1340129687.954 [NavChart](DEBUG): Initialize NavChart Derivation.
2012-06-19T18:14:47.954Z,1340129687.954 [PitchRateCalculator](DEBUG): Initializing PitchRateCalculator.
2012-06-19T18:14:47.954Z,1340129687.954 [SpeedCalculator](DEBUG): Initializing SpeedCalculator.
2012-06-19T18:14:47.954Z,1340129687.954 [TempGradientCalculator](DEBUG): Initializing TempGradientCalculator.
2012-06-19T18:14:47.954Z,1340129687.954 [YawRateCalculator](DEBUG): Initializing YawRateCalculator.
2012-06-19T18:14:47.954Z,1340129687.954 [Navigation](DEBUG): Initializing Navigation.
2012-06-19T18:14:47.954Z,1340129687.954 [VerticalControl](DEBUG): Initialize VerticalControlComponent.
2012-06-19T18:14:47.954Z,1340129687.954 [HorizontalControl](DEBUG): Initialize HorizontalControlComponent.
2012-06-19T18:14:47.954Z,1340129687.954 [SpeedControl](DEBUG): Initialize SpeedControlComponent.
2012-06-19T18:14:47.954Z,1340129687.954 [LoopControl](DEBUG): Initialize LoopControlComponent.
2012-06-19T18:14:47.954Z,1340129687.954 [MissionManager](INFO): Loading Mission: Missions/Startup.xml
2012-06-19T18:14:47.954Z,1340129687.954 [Startup:A.GoToSurface](DEBUG): Construct GoToSurface.
2012-06-19T18:14:47.954Z,1340129687.954 [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-06-19T18:14:47.954Z,1340129687.954 [MissionManager](INFO): Loading Mission: Missions/Default.xml
2012-06-19T18:14:47.954Z,1340129687.954 [MissionManager](INFO): DefineArg Default.NeedGPS = 1 bool
2012-06-19T18:14:47.954Z,1340129687.954 [Default:GPS:A.SetSpeed](DEBUG): Construct.
2012-06-19T18:14:47.954Z,1340129687.954 [Default:GPS:B.GoToSurface](DEBUG): Construct GoToSurface.
2012-06-19T18:14:47.954Z,1340129687.954 [Default:Iridium:A.SetSpeed](DEBUG): Construct.
2012-06-19T18:14:47.955Z,1340129687.955 [Default:Iridium:B.GoToSurface](DEBUG): Construct GoToSurface.
2012-06-19T18:14:47.955Z,1340129687.955 [Default:Iridium:A_Timeout:A.Execute](DEBUG): Construct Execute.
2012-06-19T18:14:47.955Z,1340129687.955 [Default:D.SetSpeed](DEBUG): Construct.
2012-06-19T18:14:47.955Z,1340129687.955 [Default:E.GoToSurface](DEBUG): Construct GoToSurface.
2012-06-19T18:14:47.955Z,1340129687.955 [Default:F.Wait](DEBUG): Construct Wait.
2012-06-19T18:14:47.955Z,1340129687.955 [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-06-19T18:14:47.955Z,1340129687.955 [controlThread](DEBUG): Component order: CycleStarter,ExternalSim,InternalSim,AHRS_sp3003D,AHRS_3DMGX3,Depth_Keller,DropWeight,DVL_micro,NAL9601,Onboard,Radio_Freewave,DAT,Aanderaa_O2,ISUS,WetLabsBB2FL,Depth_Keller,DepthRateCalculator,NavChart,PitchRateCalculator,SpeedCalculator,TempGradientCalculator,YawRateCalculator,Navigation,MissionManager,VerticalControl,HorizontalControl,SpeedControl,LoopControl,BuoyancyServo,ElevatorServo,MassServo,RudderServo,ThrusterServo,SBIT,IBIT,CBIT,Reporter,logger,
2012-06-19T18:14:47.956Z,1340129687.956 [BuoyancyServo](DEBUG): Initializing BuoyancyServo.
2012-06-19T18:14:47.957Z,1340129687.957 [ElevatorServo](DEBUG): Initializing ElevatorServo.
2012-06-19T18:14:47.957Z,1340129687.957 [MassServo](DEBUG): Initializing MassServo.
2012-06-19T18:14:47.957Z,1340129687.957 [RudderServo](DEBUG): Initializing RudderServo.
2012-06-19T18:14:47.957Z,1340129687.957 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2012-06-19T18:14:47.973Z,1340129687.973 [NavChartDb](INFO): Looking for Electronic Nav Chart files in directory: Resources
2012-06-19T18:14:47.975Z,1340129687.975 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US5CA50M.000
2012-06-19T18:14:47.977Z,1340129687.977 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US2WC11M.000
2012-06-19T18:14:47.979Z,1340129687.979 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US1WC07M.000
2012-06-19T18:14:47.980Z,1340129687.980 [NavChartDb](IMPORTANT): Will load Electronic Nav Chart data from US3CA52M.000
2012-06-19T18:14:47.980Z,1340129687.980 [NavChartDb](INFO): Setup scan of Resources/US5CA50M.000
2012-06-19T18:14:48.052Z,1340129688.052 [CommandLine](IMPORTANT): got command run ./Missions/Engineering/triangle_control_speed_compare.xml quitatend
2012-06-19T18:14:48.052Z,1340129688.052 [MissionManager](INFO): Loading Mission: ./Missions/Engineering/triangle_control_speed_compare.xml
2012-06-19T18:14:48.053Z,1340129688.053 [MissionManager](INFO): DefineArg triangle_control_speed_compare.Wpt1Lat = 36.815000 arcdeg
2012-06-19T18:14:48.053Z,1340129688.053 [MissionManager](INFO): DefineArg triangle_control_speed_compare.Wpt1Lon = -121.869000 arcdeg
2012-06-19T18:14:48.053Z,1340129688.053 [MissionManager](INFO): DefineArg triangle_control_speed_compare.Wpt2Lat = 36.829000 arcdeg
2012-06-19T18:14:48.053Z,1340129688.053 [MissionManager](INFO): DefineArg triangle_control_speed_compare.Wpt2Lon = -121.886000 arcdeg
2012-06-19T18:14:48.053Z,1340129688.053 [MissionManager](INFO): DefineArg triangle_control_speed_compare.Wpt3Lat = 36.816000 arcdeg
2012-06-19T18:14:48.053Z,1340129688.053 [MissionManager](INFO): DefineArg triangle_control_speed_compare.Wpt3Lon = -121.883000 arcdeg
2012-06-19T18:14:48.053Z,1340129688.053 [MissionManager](INFO): DefineArg triangle_control_speed_compare.MassHold = 1 bool
2012-06-19T18:14:48.053Z,1340129688.053 [MissionManager](INFO): DefineArg triangle_control_speed_compare.BuoyancyHold = 1 bool
2012-06-19T18:14:48.053Z,1340129688.053 [MissionManager](INFO): DefineArg triangle_control_speed_compare.DepthShallow = 15.000000 m
2012-06-19T18:14:48.053Z,1340129688.053 [MissionManager](INFO): DefineArg triangle_control_speed_compare.DepthDeep = 32.000000 m
2012-06-19T18:14:48.053Z,1340129688.053 [MissionManager](INFO): DefineArg triangle_control_speed_compare.Speed1 = 1.000000 m/s
2012-06-19T18:14:48.053Z,1340129688.053 [MissionManager](INFO): DefineArg triangle_control_speed_compare.Speed2 = 0.750000 m/s
2012-06-19T18:14:48.053Z,1340129688.053 [MissionManager](INFO): DefineArg triangle_control_speed_compare.Speed3 = 0.600000 m/s
2012-06-19T18:14:48.053Z,1340129688.053 [MissionManager](INFO): DefineArg triangle_control_speed_compare.BuoyancyNeutral = 0.000500 n/a
2012-06-19T18:14:48.053Z,1340129688.053 [MissionManager](INFO): DefineArg triangle_control_speed_compare.MassDefault = 0.000000 n/a
2012-06-19T18:14:48.053Z,1340129688.053 [MissionManager](INFO): DefineArg triangle_control_speed_compare.MaxDepth = 37.000000 m
2012-06-19T18:14:48.053Z,1340129688.053 [MissionManager](INFO): DefineArg triangle_control_speed_compare.NeedCommsTime = 50.000000 min
2012-06-19T18:14:48.053Z,1340129688.053 [MissionManager](INFO): DefineArg triangle_control_speed_compare.MinAltitude = 7.000000 m
2012-06-19T18:14:48.053Z,1340129688.053 [MissionManager](INFO): DefineArg triangle_control_speed_compare.TimeoutDuration = 600.000000 min
2012-06-19T18:14:48.053Z,1340129688.053 [triangle_control_speed_compare:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2012-06-19T18:14:48.053Z,1340129688.053 [triangle_control_speed_compare:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2012-06-19T18:14:48.053Z,1340129688.053 [triangle_control_speed_compare:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2012-06-19T18:14:48.054Z,1340129688.054 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface](DEBUG): Construct GoToSurface.
2012-06-19T18:14:48.054Z,1340129688.054 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed](DEBUG): Construct.
2012-06-19T18:14:48.054Z,1340129688.054 [triangle_control_speed_compare:WaypointOne:A.Pitch](DEBUG): Construct.
2012-06-19T18:14:48.054Z,1340129688.054 [triangle_control_speed_compare:WaypointOne:B.SetSpeed](DEBUG): Construct.
2012-06-19T18:14:48.054Z,1340129688.054 [triangle_control_speed_compare:WaypointOne:WaypointW1.Waypoint](DEBUG): Construct Waypoint.
2012-06-19T18:14:48.054Z,1340129688.054 [triangle_control_speed_compare:ThreeLaps:BuoyancyHold:A.Buoyancy](DEBUG): Construct Buoyancy.
2012-06-19T18:14:48.054Z,1340129688.054 [triangle_control_speed_compare:ThreeLaps:MassHold:A.Pitch](DEBUG): Construct.
2012-06-19T18:14:48.054Z,1340129688.054 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:A.SetSpeed](DEBUG): Construct.
2012-06-19T18:14:48.054Z,1340129688.054 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:B.Pitch](DEBUG): Construct.
2012-06-19T18:14:48.054Z,1340129688.054 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:Wpt2.Waypoint](DEBUG): Construct Waypoint.
2012-06-19T18:14:48.054Z,1340129688.054 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:A.SetSpeed](DEBUG): Construct.
2012-06-19T18:14:48.054Z,1340129688.054 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:B.Pitch](DEBUG): Construct.
2012-06-19T18:14:48.054Z,1340129688.054 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:Wpt3.Waypoint](DEBUG): Construct Waypoint.
2012-06-19T18:14:48.054Z,1340129688.054 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:A.SetSpeed](DEBUG): Construct.
2012-06-19T18:14:48.054Z,1340129688.054 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:B.Pitch](DEBUG): Construct.
2012-06-19T18:14:48.054Z,1340129688.054 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2012-06-19T18:14:48.055Z,1340129688.055 [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="triangle_control_speed_compare">

<!-- Wpt1 d=74m; Wpt2 d=75m; Wpt3 d=77m -->

    <DefineArg Name="Wpt1Lat"><Units:degree/><Value>36.815</Value></DefineArg>

    <DefineArg Name="Wpt1Lon"><Units:degree/><Value>-121.869</Value></DefineArg>

    <DefineArg Name="Wpt2Lat"><Units:degree/><Value>36.829</Value></DefineArg>

    <DefineArg Name="Wpt2Lon"><Units:degree/><Value>-121.886</Value></DefineArg>

    <DefineArg Name="Wpt3Lat"><Units:degree/><Value>36.816</Value></DefineArg>

    <DefineArg Name="Wpt3Lon"><Units:degree/><Value>-121.883</Value></DefineArg>

    <DefineArg Name="MassHold"><Description>
        Set to True in order to hold mass at user defined position, False to
        allow mass to run on its own.
    </Description><True/></DefineArg>

    <DefineArg Name="BuoyancyHold"><Description>
        Set to True in order to hold buoyancy at neutral position, False to
        allow buoyancy to run on its own.
    </Description><True/></DefineArg>

    <DefineArg Name="DepthShallow"><Description>
        Depth for transit
    </Description><Units:meter/><Value>15</Value></DefineArg>

    <DefineArg Name="DepthDeep"><Description>
        Depth for test
    </Description><Units:meter/><Value>32</Value></DefineArg>

    <DefineArg Name="Speed1"><Description>
        Speed for leg 1
    </Description><Units:meter_per_second/><Value>1</Value></DefineArg>

    <DefineArg Name="Speed2"><Description>
        Speed for leg 2
    </Description><Units:meter_per_second/><Value>.75</Value></DefineArg>

    <DefineArg Name="Speed3"><Description>
        Speed for leg 3
    </Description><Units:meter_per_second/><Value>.60</Value></DefineArg>

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

    <DefineArg Name="MassDefault"><Description>
        Static setting for mass during the mission.
    </Description><Control:VerticalControl.massDefault/></DefineArg>

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

    <DefineArg Name="NeedCommsTime"><Description>
        Elapsed time after previous surface communications when vehicle will
        begin to ascend for additional surface communications
    </Description><Units:minute/><Value>50</Value></DefineArg>

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

    <DefineArg Name="TimeoutDuration"><Description>
        Maximum length of mission
    </Description><Units:minute/><Value>600</Value></DefineArg>

    <Timeout Duration="TimeoutDuration"/>

    <Guidance:AltitudeEnvelope RunIn="Parallel">
        <Setting><Guidance:AltitudeEnvelope.minAltitude/><Arg Name="MinAltitude"/></Setting>
    </Guidance:AltitudeEnvelope>

    <Guidance:DepthEnvelope RunIn="Parallel">
        <Setting><Guidance:DepthEnvelope.maxDepth/><Arg Name="MaxDepth"/></Setting>
    </Guidance:DepthEnvelope>

    <Guidance:OffshoreEnvelope RunIn="Parallel">
        <Setting><Guidance:OffshoreEnvelope.minOffshore/><Units:kilometer/><Value>1</Value></Setting>
    </Guidance:OffshoreEnvelope>

    <ReadData Strategy="AllDevices">
        <Universal:platform_orientation/>
    </ReadData>

    <Aggregate Id="SURFACECOMMS">

        <Guidance:GoToSurface RunIn="Progression" />

        <Aggregate>

            <Preemptive><True /></Preemptive>

            <Guidance:SetSpeed RunIn="Parallel">
                <Setting><Guidance:SetSpeed.speed /><Units:meter_per_second /><Value>0</Value></Setting>
            </Guidance:SetSpeed>

            <ReadDatum><Universal:latitude_fix />
            </ReadDatum>

            <ReadDatum>
                <Timeout Duration="P30M">
                    <Assign Id="TouchComms"><Universal:platform_communications/></Assign>
                </Timeout><Universal:platform_communications />
            </ReadDatum>

            <ReadDatum><Universal:latitude_fix />
            </ReadDatum>

        </Aggregate>

    </Aggregate>

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

    <Aggregate Id="NeedComms">

        <When>
            <Elapsed><Universal:time_fix/></Elapsed>
            <Gt><Arg Name="NeedCommsTime"/></Gt>
        </When>

        <Call Id="NEEDCOMMS" RefId="SURFACECOMMS" />

    </Aggregate>

    <Aggregate Id="WaypointOne">

<!-- Go to Wpt1 and start from there -->

        <Guidance:Pitch RunIn="Parallel">
            <Setting><Guidance:Pitch.massPosition/><Arg Name="MassDefault"/></Setting>
            <Setting><Guidance:Pitch.depth/><Arg Name="DepthShallow"/></Setting>
        </Guidance:Pitch>

        <Guidance:SetSpeed RunIn="Parallel">
            <Setting><Guidance:SetSpeed.speed/><Units:meter_per_second/><Value>1</Value></Setting>
        </Guidance:SetSpeed>

        <Guidance:Waypoint Id="WaypointW1" RunIn="Sequence">
            <Setting><Guidance:Waypoint.latitude/><Arg Name="Wpt1Lat"/></Setting>
            <Setting><Guidance:Waypoint.longitude/><Arg Name="Wpt1Lon"/></Setting>
        </Guidance:Waypoint>

        <Call Id="PHONEHOMEWPT1" RefId="SURFACECOMMS"/>

    </Aggregate>

    <Aggregate Id="ThreeLaps">

        <Aggregate Id="BuoyancyHold" RunIn="Parallel">

            <While><Arg Name="BuoyancyHold"/>
            </While>

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

        </Aggregate>

        <Aggregate Id="MassHold" RunIn="Parallel">

            <While><Arg Name="MassHold"/>
            </While>

            <Guidance:Pitch RunIn="Parallel">
                <Setting><Guidance:Pitch.massPosition/><Arg Name="MassDefault"/></Setting>
            </Guidance:Pitch>

        </Aggregate>

        <Assign RunIn="Parallel"><Control:VerticalControl.useElevIntInDepthMode/><False/></Assign>
        <Assign RunIn="Parallel"><Control:VerticalControl.massDeadband/><Units:millimeter/><Value>0.0</Value></Assign>

        <Aggregate Id="TestDrive">

            <Aggregate Id="Speed1">

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

                <Guidance:Pitch RunIn="Parallel">
                    <Setting><Guidance:Pitch.depth/><Arg Name="DepthDeep"/></Setting>
                </Guidance:Pitch>

                <Guidance:Waypoint Id="Wpt2" RunIn="Sequence">
                    <Setting><Guidance:Waypoint.latitude/><Arg Name="Wpt2Lat"/></Setting>
                    <Setting><Guidance:Waypoint.longitude/><Arg Name="Wpt2Lon"/></Setting>
                </Guidance:Waypoint>

                <Call Id="PHONEHOMEWPT2" RefId="SURFACECOMMS"/>

            </Aggregate>

            <Aggregate Id="Speed2">

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

                <Guidance:Pitch RunIn="Parallel">
                    <Setting><Guidance:Pitch.depth/><Arg Name="DepthDeep"/></Setting>
                </Guidance:Pitch>

                <Guidance:Waypoint Id="Wpt3" RunIn="Sequence">
                    <Setting><Guidance:Waypoint.latitude/><Arg Name="Wpt3Lat"/></Setting>
                    <Setting><Guidance:Waypoint.longitude/><Arg Name="Wpt3Lon"/></Setting>
                </Guidance:Waypoint>

                <Call Id="PHONEHOMEWPT3" RefId="SURFACECOMMS"/>

            </Aggregate>

            <Aggregate Id="Speed3">

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

                <Guidance:Pitch RunIn="Parallel">
                    <Setting><Guidance:Pitch.depth/><Arg Name="DepthDeep"/></Setting>
                </Guidance:Pitch>

                <Guidance:Waypoint Id="Wpt1" RunIn="Sequence">
                    <Setting><Guidance:Waypoint.latitude/><Arg Name="Wpt1Lat"/></Setting>
                    <Setting><Guidance:Waypoint.longitude/><Arg Name="Wpt1Lon"/></Setting>
                </Guidance:Waypoint>

                <Call Id="PHONEHOMEWPT1" RefId="SURFACECOMMS"/>

            </Aggregate>

        </Aggregate>

        <Aggregate Id="Lap2">

            <Assign RunIn="Parallel"><Control:VerticalControl.elevDeadband/><Units:degree/><Value>0.0</Value></Assign>

            <Call Id="LAP2" RefId="TestDrive"/>

        </Aggregate>

        <Aggregate Id="Lap3">

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

            <Assign RunIn="Parallel"><Control:VerticalControl.elevDeadband/><Units:degree/><Value>0.0</Value></Assign>

            <Call Id="LAP3" RefId="TestDrive"/>

        </Aggregate>

        <Aggregate Id="Lap4">

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

            <Call Id="LAP4" RefId="TestDrive"/>

        </Aggregate>

    </Aggregate>

<!-- End of 3 laps -->

<!--
    <Aggregate Id="NewGains1">

VerticalControl.useElevIntInDepthMode (bool)


        <Assign RunIn="Parallel"><Control:VerticalControl.elevDeadband/><Units:degree/><Value>0.01</Value></Assign>

        <Assign RunIn="Parallel"><Control:VerticalControl.kdPitchMass/><Units:second/><Value>0.2</Value></Assign>

        <Assign RunIn="Parallel"><Control:VerticalControl.kiDepthOff/><Units:meter_per_second/><Value>.2</Value></Assign>

        <Assign RunIn="Parallel"><Control:VerticalControl.kiPitchMass/><Units:reciprocal_second/><Value>0.002</Value></Assign>

        <Assign RunIn="Parallel"><Control:VerticalControl.kpPitchMass/><Units:count/><Value>0.1</Value></Assign>

        <Assign RunIn="Parallel"><Control:VerticalControl.massDeadband/><Units:millimeter/><Value>0.01</Value></Assign>

        <Assign RunIn="Parallel"><Control:VerticalControl.maxDiveAccel/><Units:meter_per_second_squared/><Value>0.001</Value></Assign>

        <Call Id="RUN2" RefId="ThreeLaps"/>

    </Aggregate>-->

</Mission>


2012-06-19T18:14:48.055Z,1340129688.055 [CommandLine](IMPORTANT): Running ./Missions/Engineering/triangle_control_speed_compare.xml
2012-06-19T18:14:48.104Z,1340129688.104 [NavChartDb](INFO): Done scanning features of Resources/US5CA50M.000
2012-06-19T18:14:48.236Z,1340129688.236 [NavChartDb](INFO): # of records loaded: 5000
2012-06-19T18:14:48.294Z,1340129688.294 [NavChartDb](INFO): # of records loaded: 10000
2012-06-19T18:14:48.350Z,1340129688.350 [NavChartDb](INFO): # of records loaded: 15000
2012-06-19T18:14:57.555Z,1340129697.555 [NAL9601](IMPORTANT): GPS fix at: 1340129697.55
2012-06-19T18:15:03.555Z,1340129703.555 [NAL9601](INFO): Powering down
2012-06-19T18:15:03.555Z,1340129703.555 [SBIT](IMPORTANT): Beginning Startup BIT
2012-06-19T18:15:07.555Z,1340129707.555 [NavChartDb](INFO): # of records loaded: 20000
2012-06-19T18:15:39.155Z,1340129739.155 [NavChartDb](INFO): # of records loaded: 25000
2012-06-19T18:15:45.555Z,1340129745.555 [SBIT](IMPORTANT): SBIT PASSED
2012-06-19T18:15:45.955Z,1340129745.955 [MissionManager](IMPORTANT): Started mission Startup
2012-06-19T18:15:45.955Z,1340129745.955 [Startup] Running Loop=1
2012-06-19T18:15:45.955Z,1340129745.955 [Startup](INFO): Aggregate::initialize Startup
2012-06-19T18:15:45.955Z,1340129745.955 [Startup:A.GoToSurface] Running Loop=1
2012-06-19T18:15:45.955Z,1340129745.955 [Startup:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-19T18:15:45.955Z,1340129745.955 [Startup:StartupSatComms] Running Loop=1
2012-06-19T18:15:45.955Z,1340129745.955 [Startup:StartupSatComms](INFO): Aggregate::initialize Startup:StartupSatComms
2012-06-19T18:15:45.955Z,1340129745.955 [Startup:StartupSatComms:A] Running Loop=1
2012-06-19T18:15:46.355Z,1340129746.355 [Startup:StartupSatComms:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-06-19T18:15:46.755Z,1340129746.755 [NAL9601](INFO): Powering up
2012-06-19T18:15:51.955Z,1340129751.955 [NAL9601](IMPORTANT): GPS fix at: 1340129751.95
2012-06-19T18:15:51.955Z,1340129751.955 [Startup:StartupSatComms:A] Stopped
2012-06-19T18:15:51.955Z,1340129751.955 [Startup:StartupSatComms:B] Running Loop=1
2012-06-19T18:15:52.355Z,1340129752.355 [Startup:StartupSatComms:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2012-06-19T18:15:54.755Z,1340129754.755 [NAL9601](INFO): Sent 332 bytes from file Logs/20120619T181447/shore0000.lzma
2012-06-19T18:15:54.755Z,1340129754.755 [NAL9601](INFO): Packets left to send: 1
2012-06-19T18:15:54.755Z,1340129754.755 [NAL9601](INFO): Stored copy of sent data in Logs/20120619T181447/shore0000.lzma.parts/0001.sbd
2012-06-19T18:15:55.955Z,1340129755.955 [NAL9601](INFO): Sent 278 bytes from file Logs/20120619T181447/shore0000.lzma
2012-06-19T18:15:55.955Z,1340129755.955 [NAL9601](INFO): Packets left to send: 0
2012-06-19T18:15:55.955Z,1340129755.955 [NAL9601](INFO): Stored copy of sent data in Logs/20120619T181447/shore0000.lzma.parts/0000.sbd
2012-06-19T18:15:56.755Z,1340129756.755 [Startup:StartupSatComms:B] Stopped
2012-06-19T18:15:56.755Z,1340129756.755 [Startup:StartupSatComms](INFO): Completed Startup:StartupSatComms
2012-06-19T18:15:56.755Z,1340129756.755 [Startup:StartupSatComms] Stopped
2012-06-19T18:15:56.755Z,1340129756.755 [Startup:StartupSatComms](INFO): Aggregate::uninitialize Startup:StartupSatComms
2012-06-19T18:15:56.755Z,1340129756.755 [Startup](INFO): Completed Startup
2012-06-19T18:15:56.755Z,1340129756.755 [Startup] Stopped
2012-06-19T18:15:56.755Z,1340129756.755 [Startup](INFO): Aggregate::uninitialize Startup
2012-06-19T18:15:56.755Z,1340129756.755 [Startup:A.GoToSurface] Stopped
2012-06-19T18:15:56.755Z,1340129756.755 [Startup:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-19T18:15:57.155Z,1340129757.155 [MissionManager](IMPORTANT): Started mission triangle_control_speed_compare
2012-06-19T18:15:57.155Z,1340129757.155 [triangle_control_speed_compare] Running Loop=1
2012-06-19T18:15:57.155Z,1340129757.155 [triangle_control_speed_compare](INFO): Aggregate::initialize triangle_control_speed_compare
2012-06-19T18:15:57.155Z,1340129757.155 [triangle_control_speed_compare:A.AltitudeEnvelope] Running Loop=1
2012-06-19T18:15:57.155Z,1340129757.155 [triangle_control_speed_compare:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2012-06-19T18:15:57.155Z,1340129757.155 [triangle_control_speed_compare:B.DepthEnvelope] Running Loop=1
2012-06-19T18:15:57.155Z,1340129757.155 [triangle_control_speed_compare:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2012-06-19T18:15:57.155Z,1340129757.155 [triangle_control_speed_compare:C.OffshoreEnvelope] Running Loop=1
2012-06-19T18:15:57.155Z,1340129757.155 [triangle_control_speed_compare:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2012-06-19T18:15:57.155Z,1340129757.155 [triangle_control_speed_compare:D] Running Loop=1
2012-06-19T18:15:57.155Z,1340129757.155 [triangle_control_speed_compare:SURFACECOMMS] Running Loop=1
2012-06-19T18:15:57.155Z,1340129757.155 [triangle_control_speed_compare:SURFACECOMMS](INFO): Aggregate::initialize triangle_control_speed_compare:SURFACECOMMS
2012-06-19T18:15:57.155Z,1340129757.155 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-06-19T18:15:57.155Z,1340129757.155 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-19T18:15:57.155Z,1340129757.155 [triangle_control_speed_compare:SURFACECOMMS:B] Running Loop=1
2012-06-19T18:15:57.155Z,1340129757.155 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Aggregate::initialize triangle_control_speed_compare:SURFACECOMMS:B
2012-06-19T18:15:57.155Z,1340129757.155 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed] Running Loop=1
2012-06-19T18:15:57.155Z,1340129757.155 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize.
2012-06-19T18:15:57.155Z,1340129757.155 [triangle_control_speed_compare:SURFACECOMMS:B:B] Running Loop=1
2012-06-19T18:15:57.155Z,1340129757.155 [triangle_control_speed_compare:D] Running Loop=1
2012-06-19T18:15:57.155Z,1340129757.155 [triangle_control_speed_compare:D](DEBUG): Initialize ReadDataComponent to sense platform_orientation
2012-06-19T18:15:57.155Z,1340129757.155 [triangle_control_speed_compare:C.OffshoreEnvelope] Running Loop=1
2012-06-19T18:15:57.155Z,1340129757.155 [triangle_control_speed_compare:B.DepthEnvelope] Running Loop=1
2012-06-19T18:15:57.155Z,1340129757.155 [triangle_control_speed_compare:A.AltitudeEnvelope] Running Loop=1
2012-06-19T18:15:57.555Z,1340129757.555 [triangle_control_speed_compare:SURFACECOMMS:B:B](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-06-19T18:15:57.555Z,1340129757.555 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed] Running Loop=1
2012-06-19T18:16:01.955Z,1340129761.955 [NAL9601](IMPORTANT): GPS fix at: 1340129761.95
2012-06-19T18:16:01.955Z,1340129761.955 [triangle_control_speed_compare:SURFACECOMMS:B:B] Stopped
2012-06-19T18:16:01.955Z,1340129761.955 [triangle_control_speed_compare:SURFACECOMMS:B:C] Running Loop=1
2012-06-19T18:16:02.355Z,1340129762.355 [triangle_control_speed_compare:SURFACECOMMS:B:C](DEBUG): Initialize ReadDataComponent to sense platform_communications
2012-06-19T18:16:04.755Z,1340129764.755 [NAL9601](INFO): Sent 149 bytes from file Logs/20120619T181447/shore0001.lzma
2012-06-19T18:16:04.755Z,1340129764.755 [NAL9601](INFO): Packets left to send: 0
2012-06-19T18:16:04.755Z,1340129764.755 [NAL9601](INFO): Stored copy of sent data in Logs/20120619T181447/shore0001.lzma.parts/0000.sbd
2012-06-19T18:16:05.556Z,1340129765.556 [NavChartDb](INFO): # of records loaded: 30000
2012-06-19T18:16:05.557Z,1340129765.557 [triangle_control_speed_compare:SURFACECOMMS:B:C] Stopped
2012-06-19T18:16:05.557Z,1340129765.557 [triangle_control_speed_compare:SURFACECOMMS:B:D] Running Loop=1
2012-06-19T18:16:05.955Z,1340129765.955 [triangle_control_speed_compare:SURFACECOMMS:B:D](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-06-19T18:16:10.755Z,1340129770.755 [NAL9601](IMPORTANT): GPS fix at: 1340129770.75
2012-06-19T18:16:10.755Z,1340129770.755 [triangle_control_speed_compare:SURFACECOMMS:B:D] Stopped
2012-06-19T18:16:10.755Z,1340129770.755 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Completed triangle_control_speed_compare:SURFACECOMMS:B
2012-06-19T18:16:10.755Z,1340129770.755 [triangle_control_speed_compare:SURFACECOMMS:B] Stopped
2012-06-19T18:16:10.755Z,1340129770.755 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Aggregate::uninitialize triangle_control_speed_compare:SURFACECOMMS:B
2012-06-19T18:16:10.755Z,1340129770.755 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed] Stopped
2012-06-19T18:16:10.755Z,1340129770.755 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize.
2012-06-19T18:16:10.755Z,1340129770.755 [triangle_control_speed_compare:SURFACECOMMS](INFO): Completed triangle_control_speed_compare:SURFACECOMMS
2012-06-19T18:16:10.755Z,1340129770.755 [triangle_control_speed_compare:SURFACECOMMS] Stopped
2012-06-19T18:16:10.755Z,1340129770.755 [triangle_control_speed_compare:SURFACECOMMS](INFO): Aggregate::uninitialize triangle_control_speed_compare:SURFACECOMMS
2012-06-19T18:16:10.755Z,1340129770.755 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface] Stopped
2012-06-19T18:16:10.755Z,1340129770.755 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-19T18:16:10.755Z,1340129770.755 [triangle_control_speed_compare:WaypointOne] Running Loop=1
2012-06-19T18:16:10.755Z,1340129770.755 [triangle_control_speed_compare:WaypointOne](INFO): Aggregate::initialize triangle_control_speed_compare:WaypointOne
2012-06-19T18:16:10.755Z,1340129770.755 [triangle_control_speed_compare:WaypointOne:A.Pitch] Running Loop=1
2012-06-19T18:16:10.755Z,1340129770.755 [triangle_control_speed_compare:WaypointOne:A.Pitch](DEBUG): Initialize.
2012-06-19T18:16:10.755Z,1340129770.755 [triangle_control_speed_compare:WaypointOne:B.SetSpeed] Running Loop=1
2012-06-19T18:16:10.755Z,1340129770.755 [triangle_control_speed_compare:WaypointOne:B.SetSpeed](DEBUG): Initialize.
2012-06-19T18:16:10.755Z,1340129770.755 [triangle_control_speed_compare:WaypointOne:WaypointW1.Waypoint] Running Loop=1
2012-06-19T18:16:10.755Z,1340129770.755 [triangle_control_speed_compare:WaypointOne:WaypointW1.Waypoint](DEBUG): Initialize WaypointComponent.
2012-06-19T18:16:11.155Z,1340129771.155 [triangle_control_speed_compare:WaypointOne:B.SetSpeed] Running Loop=1
2012-06-19T18:16:11.155Z,1340129771.155 [triangle_control_speed_compare:WaypointOne:A.Pitch] Running Loop=1
2012-06-19T18:16:16.755Z,1340129776.755 [NAL9601](INFO): Powering down
2012-06-19T18:16:38.755Z,1340129798.755 [NavChartDb](INFO): # of records loaded: 35000
2012-06-19T18:17:10.355Z,1340129830.355 [NavChartDb](INFO): # of records loaded: 40000
2012-06-19T18:17:37.955Z,1340129857.955 [NavChartDb](INFO): # of records loaded: 45000
2012-06-19T18:18:04.355Z,1340129884.355 [NavChartDb](INFO): # of records loaded: 50000
2012-06-19T18:18:41.155Z,1340129921.155 [NavChartDb](INFO): # of records loaded: 55000
2012-06-19T18:18:47.555Z,1340129927.555 [NavChartDb](INFO): Calculating coverage of Resources/US5CA50M.000
2012-06-19T18:19:11.156Z,1340129951.156 [NavChartDb](INFO): Done scanning edges of Resources/US5CA50M.000
2012-06-19T18:19:31.155Z,1340129971.155 [NavChartDb](INFO): # of records loaded: 60000
2012-06-19T18:20:17.155Z,1340130017.155 [NavChartDb](INFO): Done scanning nodes of Resources/US5CA50M.000
2012-06-19T18:20:17.156Z,1340130017.156 [NavChartDb](INFO): # of records loaded: 62026
2012-06-19T18:20:35.155Z,1340130035.155 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US5CA50M.000
2012-06-19T18:20:35.156Z,1340130035.156 [NavChartDb](INFO): Setup scan of Resources/US3CA52M.000
2012-06-19T18:21:05.555Z,1340130065.555 [NavChartDb](INFO): Done scanning features of Resources/US3CA52M.000
2012-06-19T18:23:49.156Z,1340130229.156 [NavChartDb](INFO): # of records loaded: 5000
2012-06-19T18:24:17.955Z,1340130257.955 [NavChartDb](INFO): # of records loaded: 10000
2012-06-19T18:24:44.756Z,1340130284.756 [NavChartDb](INFO): # of records loaded: 15000
2012-06-19T18:25:11.156Z,1340130311.156 [NavChartDb](INFO): # of records loaded: 20000
2012-06-19T18:25:37.956Z,1340130337.956 [NavChartDb](INFO): # of records loaded: 25000
2012-06-19T18:26:03.955Z,1340130363.955 [NavChartDb](INFO): # of records loaded: 30000
2012-06-19T18:26:29.556Z,1340130389.556 [NavChartDb](INFO): # of records loaded: 35000
2012-06-19T18:26:54.755Z,1340130414.755 [NavChartDb](INFO): # of records loaded: 40000
2012-06-19T18:27:20.756Z,1340130440.756 [NavChartDb](INFO): # of records loaded: 45000
2012-06-19T18:27:48.756Z,1340130468.756 [NavChartDb](INFO): # of records loaded: 50000
2012-06-19T18:28:14.355Z,1340130494.355 [NavChartDb](INFO): # of records loaded: 55000
2012-06-19T18:28:36.756Z,1340130516.756 [NavChartDb](INFO): # of records loaded: 60000
2012-06-19T18:29:02.756Z,1340130542.756 [NavChartDb](INFO): # of records loaded: 65000
2012-06-19T18:29:28.756Z,1340130568.756 [NavChartDb](INFO): # of records loaded: 70000
2012-06-19T18:29:55.955Z,1340130595.955 [NavChartDb](INFO): # of records loaded: 75000
2012-06-19T18:30:23.155Z,1340130623.155 [NavChartDb](INFO): # of records loaded: 80000
2012-06-19T18:30:46.356Z,1340130646.356 [NavChartDb](INFO): # of records loaded: 85000
2012-06-19T18:31:11.155Z,1340130671.155 [NavChartDb](INFO): # of records loaded: 90000
2012-06-19T18:31:25.156Z,1340130685.156 [NavChartDb](INFO): # of records loaded: 95000
2012-06-19T18:31:53.955Z,1340130713.955 [NavChartDb](INFO): # of records loaded: 100000
2012-06-19T18:31:59.956Z,1340130719.956 [NavChartDb](INFO): # of records loaded: 105000
2012-06-19T18:32:20.355Z,1340130740.355 [NavChartDb](INFO): # of records loaded: 110000
2012-06-19T18:32:35.155Z,1340130755.155 [NavChartDb](INFO): Calculating coverage of Resources/US3CA52M.000
2012-06-19T18:32:45.555Z,1340130765.555 [NavChartDb](INFO): Done scanning edges of Resources/US3CA52M.000
2012-06-19T18:32:55.956Z,1340130775.956 [NavChartDb](INFO): # of records loaded: 115000
2012-06-19T18:33:22.755Z,1340130802.755 [NavChartDb](INFO): Done scanning nodes of Resources/US3CA52M.000
2012-06-19T18:33:22.756Z,1340130802.756 [NavChartDb](INFO): # of records loaded: 117290
2012-06-19T18:33:34.355Z,1340130814.355 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US3CA52M.000
2012-06-19T18:33:34.755Z,1340130814.755 [NavChartDb](INFO): Setup scan of Resources/US2WC11M.000
2012-06-19T18:33:54.356Z,1340130834.356 [NavChartDb](INFO): Done scanning features of Resources/US2WC11M.000
2012-06-19T18:34:29.155Z,1340130869.155 [NavChartDb](INFO): # of records loaded: 5000
2012-06-19T18:34:52.755Z,1340130892.755 [NavChartDb](INFO): # of records loaded: 10000
2012-06-19T18:35:19.956Z,1340130919.956 [NavChartDb](INFO): # of records loaded: 15000
2012-06-19T18:35:47.955Z,1340130947.955 [NavChartDb](INFO): # of records loaded: 20000
2012-06-19T18:36:15.155Z,1340130975.155 [NavChartDb](INFO): # of records loaded: 25000
2012-06-19T18:36:42.355Z,1340131002.355 [NavChartDb](INFO): # of records loaded: 30000
2012-06-19T18:37:09.555Z,1340131029.555 [NavChartDb](INFO): # of records loaded: 35000
2012-06-19T18:37:37.555Z,1340131057.555 [NavChartDb](INFO): # of records loaded: 40000
2012-06-19T18:37:41.155Z,1340131061.155 [NavChartDb](INFO): Calculating coverage of Resources/US2WC11M.000
2012-06-19T18:37:49.155Z,1340131069.155 [NavChartDb](INFO): Done scanning edges of Resources/US2WC11M.000
2012-06-19T18:38:23.555Z,1340131103.555 [NavChartDb](INFO): Done scanning nodes of Resources/US2WC11M.000
2012-06-19T18:38:23.555Z,1340131103.555 [NavChartDb](INFO): # of records loaded: 44880
2012-06-19T18:38:36.755Z,1340131116.755 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US2WC11M.000
2012-06-19T18:38:37.155Z,1340131117.155 [NavChartDb](INFO): Setup scan of Resources/US1WC07M.000
2012-06-19T18:39:17.556Z,1340131157.556 [NavChartDb](INFO): Done scanning features of Resources/US1WC07M.000
2012-06-19T18:40:04.755Z,1340131204.755 [NavChartDb](INFO): # of records loaded: 5000
2012-06-19T18:40:33.155Z,1340131233.155 [NavChartDb](INFO): # of records loaded: 10000
2012-06-19T18:40:49.555Z,1340131249.555 [NavChartDb](INFO): # of records loaded: 15000
2012-06-19T18:41:10.755Z,1340131270.755 [NavChartDb](INFO): # of records loaded: 20000
2012-06-19T18:41:17.155Z,1340131277.155 [NavChartDb](INFO): Calculating coverage of Resources/US1WC07M.000
2012-06-19T18:41:34.755Z,1340131294.755 [NavChartDb](INFO): Done scanning edges of Resources/US1WC07M.000
2012-06-19T18:42:43.555Z,1340131363.555 [NavChartDb](INFO): Done scanning nodes of Resources/US1WC07M.000
2012-06-19T18:42:43.556Z,1340131363.556 [NavChartDb](INFO): # of records loaded: 24424
2012-06-19T18:42:53.555Z,1340131373.555 [NavChartDb](IMPORTANT): Loaded Electronic Nav Chart data from US1WC07M.000
2012-06-19T18:42:53.955Z,1340131373.955 [NavChartDb](INFO): Creating index for Soundings
2012-06-19T18:43:25.155Z,1340131405.155 [NavChartDb](INFO): Creating index for Contour 0p0
2012-06-19T18:44:32.356Z,1340131472.356 [NavChartDb](INFO): Creating index for Contour 1p8
2012-06-19T18:44:38.355Z,1340131478.355 [NavChartDb](INFO): Creating index for Contour 3p6
2012-06-19T18:44:43.956Z,1340131483.956 [NavChartDb](INFO): Creating index for Contour 5p4
2012-06-19T18:45:03.555Z,1340131503.555 [NavChartDb](INFO): Creating index for Contour 9p1
2012-06-19T18:45:08.756Z,1340131508.756 [NavChartDb](INFO): Creating index for Contour 10p9
2012-06-19T18:45:12.756Z,1340131512.756 [NavChartDb](INFO): Creating index for Contour 18p2
2012-06-19T18:45:35.555Z,1340131535.555 [NavChartDb](INFO): Creating index for Contour 36p5
2012-06-19T18:45:55.555Z,1340131555.555 [NavChartDb](INFO): Creating index for Contour 54p8
2012-06-19T18:46:25.555Z,1340131585.555 [NavChartDb](INFO): Creating index for Contour 73p1
2012-06-19T18:46:45.955Z,1340131605.955 [NavChartDb](INFO): Creating index for Contour 91p4
2012-06-19T18:47:11.555Z,1340131631.555 [NavChartDb](INFO): Creating index for Contour 182p8
2012-06-19T18:47:51.555Z,1340131671.555 [NavChartDb](INFO): Creating index for Contour 365p7
2012-06-19T18:48:17.955Z,1340131697.955 [NavChartDb](INFO): Creating index for Contour 548p6
2012-06-19T18:48:46.356Z,1340131726.356 [NavChartDb](INFO): Creating index for Contour 731p5
2012-06-19T18:49:14.355Z,1340131754.355 [NavChartDb](INFO): Creating index for Contour 914p4
2012-06-19T18:50:09.155Z,1340131809.155 [NavChartDb](INFO): Creating index for Contour 1097p2
2012-06-19T18:50:34.356Z,1340131834.356 [NavChartDb](INFO): Creating index for Contour 1280p1
2012-06-19T18:50:59.156Z,1340131859.156 [NavChartDb](INFO): Creating index for Contour 1463p0
2012-06-19T18:51:21.155Z,1340131881.155 [NavChartDb](INFO): Creating index for Contour 1645p9
2012-06-19T18:51:42.755Z,1340131902.755 [NavChartDb](INFO): Creating index for Contour 1828p8
2012-06-19T18:52:31.555Z,1340131951.555 [NavChartDb](INFO): Creating index for Contour 2011p6
2012-06-19T18:52:50.356Z,1340131970.356 [NavChartDb](INFO): Creating index for Contour 2194p5
2012-06-19T18:53:07.555Z,1340131987.555 [NavChartDb](INFO): Creating index for Contour 2377p4
2012-06-19T18:53:23.555Z,1340132003.555 [NavChartDb](INFO): Creating index for Contour 2560p3
2012-06-19T18:53:40.756Z,1340132020.756 [NavChartDb](INFO): Creating index for Contour 2743p2
2012-06-19T18:54:06.756Z,1340132046.756 [NavChartDb](INFO): Creating index for Contour 2926p0
2012-06-19T18:54:25.955Z,1340132065.955 [NavChartDb](INFO): Creating index for Contour 3108p9
2012-06-19T18:54:43.955Z,1340132083.955 [NavChartDb](INFO): Creating index for Contour 3291p8
2012-06-19T18:54:59.555Z,1340132099.555 [NavChartDb](INFO): Creating index for Contour 3474p7
2012-06-19T18:55:15.155Z,1340132115.155 [NavChartDb](INFO): Creating index for Contour 3657p6
2012-06-19T18:56:37.555Z,1340132197.555 [NavChartDb](INFO): Charting Soundings
2012-06-19T18:56:39.155Z,1340132199.155 [NavChartDb](INFO): Charting 0p0
2012-06-19T18:56:40.755Z,1340132200.755 [NavChartDb](INFO): Charting 1p8
2012-06-19T18:56:42.355Z,1340132202.355 [NavChartDb](INFO): Charting 3p6
2012-06-19T18:56:43.955Z,1340132203.955 [NavChartDb](INFO): Charting 5p4
2012-06-19T18:56:45.555Z,1340132205.555 [NavChartDb](INFO): Charting 9p1
2012-06-19T18:56:47.155Z,1340132207.155 [NavChartDb](INFO): Charting 10p9
2012-06-19T18:56:48.755Z,1340132208.755 [NavChartDb](INFO): Charting 18p2
2012-06-19T18:56:50.355Z,1340132210.355 [NavChartDb](INFO): Charting 36p5
2012-06-19T18:56:51.955Z,1340132211.955 [NavChartDb](INFO): Charting 54p8
2012-06-19T18:56:53.555Z,1340132213.555 [NavChartDb](INFO): Charting 73p1
2012-06-19T18:56:55.155Z,1340132215.155 [NavChartDb](INFO): Charting 91p4
2012-06-19T18:56:56.755Z,1340132216.755 [NavChartDb](INFO): Charting 182p8
2012-06-19T18:56:58.355Z,1340132218.355 [NavChartDb](INFO): Charting 365p7
2012-06-19T18:56:59.955Z,1340132219.955 [NavChartDb](INFO): Charting 548p6
2012-06-19T18:57:01.555Z,1340132221.555 [NavChartDb](INFO): Charting 731p5
2012-06-19T18:57:03.155Z,1340132223.155 [NavChartDb](INFO): Charting 914p4
2012-06-19T18:57:04.755Z,1340132224.755 [NavChartDb](INFO): Charting 1097p2
2012-06-19T18:57:06.355Z,1340132226.355 [NavChartDb](INFO): Charting 1280p1
2012-06-19T18:57:07.955Z,1340132227.955 [NavChartDb](INFO): Charting 1463p0
2012-06-19T18:57:09.555Z,1340132229.555 [NavChartDb](INFO): Charting 1645p9
2012-06-19T18:57:11.155Z,1340132231.155 [NavChartDb](INFO): Charting 1828p8
2012-06-19T18:57:12.755Z,1340132232.755 [NavChartDb](INFO): Charting 2011p6
2012-06-19T18:57:14.355Z,1340132234.355 [NavChartDb](INFO): Charting 2194p5
2012-06-19T18:57:15.955Z,1340132235.955 [NavChartDb](INFO): Charting 2377p4
2012-06-19T18:57:17.555Z,1340132237.555 [NavChartDb](INFO): Charting 2560p3
2012-06-19T18:57:19.155Z,1340132239.155 [NavChartDb](INFO): Charting 2743p2
2012-06-19T18:57:20.755Z,1340132240.755 [NavChartDb](INFO): Charting 2926p0
2012-06-19T18:57:22.355Z,1340132242.355 [NavChartDb](INFO): Charting 3108p9
2012-06-19T18:57:23.955Z,1340132243.955 [NavChartDb](INFO): Charting 3291p8
2012-06-19T18:57:25.555Z,1340132245.555 [NavChartDb](INFO): Charting 3474p7
2012-06-19T18:57:27.155Z,1340132247.155 [NavChartDb](INFO): Charting 3657p6
2012-06-19T19:06:10.756Z,1340132770.756 [triangle_control_speed_compare:NeedComms] Running Loop=1
2012-06-19T19:06:10.756Z,1340132770.756 [triangle_control_speed_compare:NeedComms](INFO): Aggregate::initialize triangle_control_speed_compare:NeedComms
2012-06-19T19:06:10.756Z,1340132770.756 [triangle_control_speed_compare:NeedComms:NEEDCOMMS] Running Loop=1
2012-06-19T19:06:10.756Z,1340132770.756 [triangle_control_speed_compare:NeedComms:NEEDCOMMS](INFO): Aggregate::initialize triangle_control_speed_compare:NeedComms:NEEDCOMMS
2012-06-19T19:06:11.160Z,1340132771.160 [triangle_control_speed_compare:SURFACECOMMS] Running Loop=1
2012-06-19T19:06:11.160Z,1340132771.160 [triangle_control_speed_compare:SURFACECOMMS](INFO): Aggregate::initialize triangle_control_speed_compare:SURFACECOMMS
2012-06-19T19:06:11.160Z,1340132771.160 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-06-19T19:06:11.160Z,1340132771.160 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-19T19:09:32.356Z,1340132972.356 [triangle_control_speed_compare:SURFACECOMMS:B] Running Loop=1
2012-06-19T19:09:32.356Z,1340132972.356 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Aggregate::initialize triangle_control_speed_compare:SURFACECOMMS:B
2012-06-19T19:09:32.356Z,1340132972.356 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed] Running Loop=1
2012-06-19T19:09:32.356Z,1340132972.356 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize.
2012-06-19T19:09:32.356Z,1340132972.356 [triangle_control_speed_compare:SURFACECOMMS:B:B] Running Loop=1
2012-06-19T19:09:33.155Z,1340132973.155 [NAL9601](INFO): Powering up
2012-06-19T19:09:38.355Z,1340132978.355 [NAL9601](IMPORTANT): GPS fix at: 1340132978.35
2012-06-19T19:09:38.356Z,1340132978.356 [Navigation](IMPORTANT): Error 0.0 %DT at bearing -176.2 deg., over the last 3248.6 m.
2012-06-19T19:09:38.356Z,1340132978.356 [triangle_control_speed_compare:SURFACECOMMS:B:B] Stopped
2012-06-19T19:09:38.356Z,1340132978.356 [triangle_control_speed_compare:SURFACECOMMS:B:C] Running Loop=1
2012-06-19T19:09:41.155Z,1340132981.155 [NAL9601](INFO): Sent 332 bytes from file Logs/20120619T181447/shore0002.lzma
2012-06-19T19:09:41.155Z,1340132981.155 [NAL9601](INFO): Packets left to send: 1
2012-06-19T19:09:41.155Z,1340132981.155 [NAL9601](INFO): Stored copy of sent data in Logs/20120619T181447/shore0002.lzma.parts/0001.sbd
2012-06-19T19:09:42.355Z,1340132982.355 [NAL9601](INFO): Sent 124 bytes from file Logs/20120619T181447/shore0002.lzma
2012-06-19T19:09:42.355Z,1340132982.355 [NAL9601](INFO): Packets left to send: 0
2012-06-19T19:09:42.355Z,1340132982.355 [NAL9601](INFO): Stored copy of sent data in Logs/20120619T181447/shore0002.lzma.parts/0000.sbd
2012-06-19T19:09:43.156Z,1340132983.156 [triangle_control_speed_compare:SURFACECOMMS:B:C] Stopped
2012-06-19T19:09:43.156Z,1340132983.156 [triangle_control_speed_compare:SURFACECOMMS:B:D] Running Loop=1
2012-06-19T19:09:48.355Z,1340132988.355 [NAL9601](IMPORTANT): GPS fix at: 1340132988.35
2012-06-19T19:09:48.356Z,1340132988.356 [triangle_control_speed_compare:SURFACECOMMS:B:D] Stopped
2012-06-19T19:09:48.356Z,1340132988.356 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Completed triangle_control_speed_compare:SURFACECOMMS:B
2012-06-19T19:09:48.356Z,1340132988.356 [triangle_control_speed_compare:SURFACECOMMS:B] Stopped
2012-06-19T19:09:48.356Z,1340132988.356 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Aggregate::uninitialize triangle_control_speed_compare:SURFACECOMMS:B
2012-06-19T19:09:48.356Z,1340132988.356 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed] Stopped
2012-06-19T19:09:48.356Z,1340132988.356 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize.
2012-06-19T19:09:48.356Z,1340132988.356 [triangle_control_speed_compare:SURFACECOMMS](INFO): Completed triangle_control_speed_compare:SURFACECOMMS
2012-06-19T19:09:48.356Z,1340132988.356 [triangle_control_speed_compare:SURFACECOMMS] Stopped
2012-06-19T19:09:48.356Z,1340132988.356 [triangle_control_speed_compare:SURFACECOMMS](INFO): Aggregate::uninitialize triangle_control_speed_compare:SURFACECOMMS
2012-06-19T19:09:48.356Z,1340132988.356 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface] Stopped
2012-06-19T19:09:48.356Z,1340132988.356 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-19T19:09:48.756Z,1340132988.756 [triangle_control_speed_compare:NeedComms:NEEDCOMMS](INFO): Completed triangle_control_speed_compare:NeedComms:NEEDCOMMS
2012-06-19T19:09:48.756Z,1340132988.756 [triangle_control_speed_compare:NeedComms:NEEDCOMMS] Stopped
2012-06-19T19:09:48.756Z,1340132988.756 [triangle_control_speed_compare:NeedComms:NEEDCOMMS](INFO): Aggregate::uninitialize triangle_control_speed_compare:NeedComms:NEEDCOMMS
2012-06-19T19:09:48.756Z,1340132988.756 [triangle_control_speed_compare:NeedComms](INFO): Completed triangle_control_speed_compare:NeedComms
2012-06-19T19:09:48.756Z,1340132988.756 [triangle_control_speed_compare:NeedComms] Stopped
2012-06-19T19:09:48.756Z,1340132988.756 [triangle_control_speed_compare:NeedComms](INFO): Aggregate::uninitialize triangle_control_speed_compare:NeedComms
2012-06-19T19:09:54.355Z,1340132994.355 [NAL9601](INFO): Powering down
2012-06-19T19:27:33.156Z,1340134053.156 [triangle_control_speed_compare:WaypointOne:WaypointW1.Waypoint](INFO): Reached Waypoint: 36.815000,-121.869000
2012-06-19T19:27:33.156Z,1340134053.156 [triangle_control_speed_compare:WaypointOne:WaypointW1.Waypoint] Stopped
2012-06-19T19:27:33.156Z,1340134053.156 [triangle_control_speed_compare:WaypointOne:WaypointW1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2012-06-19T19:27:33.156Z,1340134053.156 [triangle_control_speed_compare:WaypointOne:PHONEHOMEWPT1] Running Loop=1
2012-06-19T19:27:33.156Z,1340134053.156 [triangle_control_speed_compare:WaypointOne:PHONEHOMEWPT1](INFO): Aggregate::initialize triangle_control_speed_compare:WaypointOne:PHONEHOMEWPT1
2012-06-19T19:27:33.556Z,1340134053.556 [triangle_control_speed_compare:SURFACECOMMS] Running Loop=1
2012-06-19T19:27:33.556Z,1340134053.556 [triangle_control_speed_compare:SURFACECOMMS](INFO): Aggregate::initialize triangle_control_speed_compare:SURFACECOMMS
2012-06-19T19:27:33.556Z,1340134053.556 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-06-19T19:27:33.556Z,1340134053.556 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-19T19:30:56.355Z,1340134256.355 [triangle_control_speed_compare:SURFACECOMMS:B] Running Loop=1
2012-06-19T19:30:56.355Z,1340134256.355 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Aggregate::initialize triangle_control_speed_compare:SURFACECOMMS:B
2012-06-19T19:30:56.355Z,1340134256.355 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed] Running Loop=1
2012-06-19T19:30:56.355Z,1340134256.355 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize.
2012-06-19T19:30:56.355Z,1340134256.355 [triangle_control_speed_compare:SURFACECOMMS:B:B] Running Loop=1
2012-06-19T19:30:57.155Z,1340134257.155 [NAL9601](INFO): Powering up
2012-06-19T19:31:02.355Z,1340134262.355 [NAL9601](IMPORTANT): GPS fix at: 1340134262.35
2012-06-19T19:31:02.356Z,1340134262.356 [Navigation](IMPORTANT): Error 0.0 %DT at bearing 100.6 deg., over the last 1238.5 m.
2012-06-19T19:31:02.356Z,1340134262.356 [Navigation](IMPORTANT): Distance made good 1243.2 m, at (36.8153, -121.8703).
2012-06-19T19:31:02.356Z,1340134262.356 [triangle_control_speed_compare:SURFACECOMMS:B:B] Stopped
2012-06-19T19:31:02.356Z,1340134262.356 [triangle_control_speed_compare:SURFACECOMMS:B:C] Running Loop=1
2012-06-19T19:31:05.155Z,1340134265.155 [NAL9601](INFO): Sent 332 bytes from file Logs/20120619T181447/shore0003.lzma
2012-06-19T19:31:05.155Z,1340134265.155 [NAL9601](INFO): Packets left to send: 1
2012-06-19T19:31:05.174Z,1340134265.174 [NAL9601](INFO): Stored copy of sent data in Logs/20120619T181447/shore0003.lzma.parts/0001.sbd
2012-06-19T19:31:06.355Z,1340134266.355 [NAL9601](INFO): Sent 32 bytes from file Logs/20120619T181447/shore0003.lzma
2012-06-19T19:31:06.355Z,1340134266.355 [NAL9601](INFO): Packets left to send: 0
2012-06-19T19:31:06.355Z,1340134266.355 [NAL9601](INFO): Stored copy of sent data in Logs/20120619T181447/shore0003.lzma.parts/0000.sbd
2012-06-19T19:31:07.156Z,1340134267.156 [triangle_control_speed_compare:SURFACECOMMS:B:C] Stopped
2012-06-19T19:31:07.156Z,1340134267.156 [triangle_control_speed_compare:SURFACECOMMS:B:D] Running Loop=1
2012-06-19T19:31:12.355Z,1340134272.355 [NAL9601](IMPORTANT): GPS fix at: 1340134272.35
2012-06-19T19:31:12.356Z,1340134272.356 [triangle_control_speed_compare:SURFACECOMMS:B:D] Stopped
2012-06-19T19:31:12.356Z,1340134272.356 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Completed triangle_control_speed_compare:SURFACECOMMS:B
2012-06-19T19:31:12.356Z,1340134272.356 [triangle_control_speed_compare:SURFACECOMMS:B] Stopped
2012-06-19T19:31:12.356Z,1340134272.356 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Aggregate::uninitialize triangle_control_speed_compare:SURFACECOMMS:B
2012-06-19T19:31:12.356Z,1340134272.356 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed] Stopped
2012-06-19T19:31:12.356Z,1340134272.356 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize.
2012-06-19T19:31:12.356Z,1340134272.356 [triangle_control_speed_compare:SURFACECOMMS](INFO): Completed triangle_control_speed_compare:SURFACECOMMS
2012-06-19T19:31:12.356Z,1340134272.356 [triangle_control_speed_compare:SURFACECOMMS] Stopped
2012-06-19T19:31:12.356Z,1340134272.356 [triangle_control_speed_compare:SURFACECOMMS](INFO): Aggregate::uninitialize triangle_control_speed_compare:SURFACECOMMS
2012-06-19T19:31:12.356Z,1340134272.356 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface] Stopped
2012-06-19T19:31:12.356Z,1340134272.356 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-19T19:31:12.756Z,1340134272.756 [triangle_control_speed_compare:WaypointOne:PHONEHOMEWPT1](INFO): Completed triangle_control_speed_compare:WaypointOne:PHONEHOMEWPT1
2012-06-19T19:31:12.756Z,1340134272.756 [triangle_control_speed_compare:WaypointOne:PHONEHOMEWPT1] Stopped
2012-06-19T19:31:12.756Z,1340134272.756 [triangle_control_speed_compare:WaypointOne:PHONEHOMEWPT1](INFO): Aggregate::uninitialize triangle_control_speed_compare:WaypointOne:PHONEHOMEWPT1
2012-06-19T19:31:12.756Z,1340134272.756 [triangle_control_speed_compare:WaypointOne](INFO): Completed triangle_control_speed_compare:WaypointOne
2012-06-19T19:31:12.756Z,1340134272.756 [triangle_control_speed_compare:WaypointOne] Stopped
2012-06-19T19:31:12.756Z,1340134272.756 [triangle_control_speed_compare:WaypointOne](INFO): Aggregate::uninitialize triangle_control_speed_compare:WaypointOne
2012-06-19T19:31:12.756Z,1340134272.756 [triangle_control_speed_compare:WaypointOne:A.Pitch] Stopped
2012-06-19T19:31:12.756Z,1340134272.756 [triangle_control_speed_compare:WaypointOne:B.SetSpeed] Stopped
2012-06-19T19:31:12.756Z,1340134272.756 [triangle_control_speed_compare:WaypointOne:B.SetSpeed](DEBUG): Uninitialize.
2012-06-19T19:31:12.756Z,1340134272.756 [triangle_control_speed_compare:ThreeLaps] Running Loop=1
2012-06-19T19:31:12.756Z,1340134272.756 [triangle_control_speed_compare:ThreeLaps](INFO): Aggregate::initialize triangle_control_speed_compare:ThreeLaps
2012-06-19T19:31:12.756Z,1340134272.756 [triangle_control_speed_compare:ThreeLaps:BuoyancyHold] Running Loop=1
2012-06-19T19:31:12.756Z,1340134272.756 [triangle_control_speed_compare:ThreeLaps:BuoyancyHold](INFO): Aggregate::initialize triangle_control_speed_compare:ThreeLaps:BuoyancyHold
2012-06-19T19:31:12.756Z,1340134272.756 [triangle_control_speed_compare:ThreeLaps:BuoyancyHold:A.Buoyancy] Running Loop=1
2012-06-19T19:31:12.756Z,1340134272.756 [triangle_control_speed_compare:ThreeLaps:BuoyancyHold:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2012-06-19T19:31:12.756Z,1340134272.756 [triangle_control_speed_compare:ThreeLaps:MassHold] Running Loop=1
2012-06-19T19:31:12.756Z,1340134272.756 [triangle_control_speed_compare:ThreeLaps:MassHold](INFO): Aggregate::initialize triangle_control_speed_compare:ThreeLaps:MassHold
2012-06-19T19:31:12.756Z,1340134272.756 [triangle_control_speed_compare:ThreeLaps:MassHold:A.Pitch] Running Loop=1
2012-06-19T19:31:12.756Z,1340134272.756 [triangle_control_speed_compare:ThreeLaps:MassHold:A.Pitch](DEBUG): Initialize.
2012-06-19T19:31:12.756Z,1340134272.756 [triangle_control_speed_compare:ThreeLaps:C] Running Loop=1
2012-06-19T19:31:12.756Z,1340134272.756 [triangle_control_speed_compare:ThreeLaps:D] Running Loop=1
2012-06-19T19:31:12.756Z,1340134272.756 [triangle_control_speed_compare:ThreeLaps:TestDrive] Running Loop=1
2012-06-19T19:31:12.756Z,1340134272.756 [triangle_control_speed_compare:ThreeLaps:TestDrive](INFO): Aggregate::initialize triangle_control_speed_compare:ThreeLaps:TestDrive
2012-06-19T19:31:12.756Z,1340134272.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1] Running Loop=1
2012-06-19T19:31:12.756Z,1340134272.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1](INFO): Aggregate::initialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1
2012-06-19T19:31:12.756Z,1340134272.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:A.SetSpeed] Running Loop=1
2012-06-19T19:31:12.756Z,1340134272.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:A.SetSpeed](DEBUG): Initialize.
2012-06-19T19:31:12.756Z,1340134272.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:B.Pitch] Running Loop=1
2012-06-19T19:31:12.756Z,1340134272.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:B.Pitch](DEBUG): Initialize.
2012-06-19T19:31:12.756Z,1340134272.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:Wpt2.Waypoint] Running Loop=1
2012-06-19T19:31:12.756Z,1340134272.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:Wpt2.Waypoint](DEBUG): Initialize WaypointComponent.
2012-06-19T19:31:13.156Z,1340134273.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:B.Pitch] Running Loop=1
2012-06-19T19:31:13.156Z,1340134273.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:A.SetSpeed] Running Loop=1
2012-06-19T19:31:13.156Z,1340134273.156 [triangle_control_speed_compare:ThreeLaps:D] Running Loop=1
2012-06-19T19:31:13.156Z,1340134273.156 [triangle_control_speed_compare:ThreeLaps:C] Running Loop=1
2012-06-19T19:31:13.156Z,1340134273.156 [triangle_control_speed_compare:ThreeLaps:MassHold] Running Loop=1
2012-06-19T19:31:13.156Z,1340134273.156 [triangle_control_speed_compare:ThreeLaps:MassHold:A.Pitch] Running Loop=1
2012-06-19T19:31:13.156Z,1340134273.156 [triangle_control_speed_compare:ThreeLaps:BuoyancyHold] Running Loop=1
2012-06-19T19:31:13.156Z,1340134273.156 [triangle_control_speed_compare:ThreeLaps:BuoyancyHold:A.Buoyancy] Running Loop=1
2012-06-19T19:31:18.355Z,1340134278.355 [NAL9601](INFO): Powering down
2012-06-19T20:05:52.356Z,1340136352.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:Wpt2.Waypoint](INFO): Reached Waypoint: 36.829000,-121.886000
2012-06-19T20:05:52.356Z,1340136352.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:Wpt2.Waypoint] Stopped
2012-06-19T20:05:52.356Z,1340136352.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:Wpt2.Waypoint](DEBUG): Uninitialize WaypointComponent.
2012-06-19T20:05:52.356Z,1340136352.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:PHONEHOMEWPT2] Running Loop=1
2012-06-19T20:05:52.356Z,1340136352.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:PHONEHOMEWPT2](INFO): Aggregate::initialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:PHONEHOMEWPT2
2012-06-19T20:05:52.756Z,1340136352.756 [triangle_control_speed_compare:SURFACECOMMS] Running Loop=1
2012-06-19T20:05:52.756Z,1340136352.756 [triangle_control_speed_compare:SURFACECOMMS](INFO): Aggregate::initialize triangle_control_speed_compare:SURFACECOMMS
2012-06-19T20:05:52.756Z,1340136352.756 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-06-19T20:05:52.756Z,1340136352.756 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-19T20:11:03.156Z,1340136663.156 [triangle_control_speed_compare:SURFACECOMMS:B] Running Loop=1
2012-06-19T20:11:03.156Z,1340136663.156 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Aggregate::initialize triangle_control_speed_compare:SURFACECOMMS:B
2012-06-19T20:11:03.156Z,1340136663.156 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed] Running Loop=1
2012-06-19T20:11:03.156Z,1340136663.156 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize.
2012-06-19T20:11:03.156Z,1340136663.156 [triangle_control_speed_compare:SURFACECOMMS:B:B] Running Loop=1
2012-06-19T20:11:03.955Z,1340136663.955 [NAL9601](INFO): Powering up
2012-06-19T20:11:09.155Z,1340136669.155 [NAL9601](IMPORTANT): GPS fix at: 1340136669.15
2012-06-19T20:11:09.156Z,1340136669.156 [Navigation](IMPORTANT): Error 0.0 %DT at bearing 97.4 deg., over the last 2231.4 m.
2012-06-19T20:11:09.156Z,1340136669.156 [Navigation](IMPORTANT): Distance made good 2234.0 m, at (36.8301, -121.8873).
2012-06-19T20:11:09.156Z,1340136669.156 [triangle_control_speed_compare:SURFACECOMMS:B:B] Stopped
2012-06-19T20:11:09.156Z,1340136669.156 [triangle_control_speed_compare:SURFACECOMMS:B:C] Running Loop=1
2012-06-19T20:11:11.955Z,1340136671.955 [NAL9601](INFO): Sent 332 bytes from file Logs/20120619T181447/shore0004.lzma
2012-06-19T20:11:11.955Z,1340136671.955 [NAL9601](INFO): Packets left to send: 1
2012-06-19T20:11:11.955Z,1340136671.955 [NAL9601](INFO): Stored copy of sent data in Logs/20120619T181447/shore0004.lzma.parts/0001.sbd
2012-06-19T20:11:13.155Z,1340136673.155 [NAL9601](INFO): Sent 6 bytes from file Logs/20120619T181447/shore0004.lzma
2012-06-19T20:11:13.155Z,1340136673.155 [NAL9601](INFO): Packets left to send: 0
2012-06-19T20:11:13.155Z,1340136673.155 [NAL9601](INFO): Stored copy of sent data in Logs/20120619T181447/shore0004.lzma.parts/0000.sbd
2012-06-19T20:11:13.956Z,1340136673.956 [triangle_control_speed_compare:SURFACECOMMS:B:C] Stopped
2012-06-19T20:11:13.956Z,1340136673.956 [triangle_control_speed_compare:SURFACECOMMS:B:D] Running Loop=1
2012-06-19T20:11:19.155Z,1340136679.155 [NAL9601](IMPORTANT): GPS fix at: 1340136679.15
2012-06-19T20:11:19.156Z,1340136679.156 [triangle_control_speed_compare:SURFACECOMMS:B:D] Stopped
2012-06-19T20:11:19.156Z,1340136679.156 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Completed triangle_control_speed_compare:SURFACECOMMS:B
2012-06-19T20:11:19.156Z,1340136679.156 [triangle_control_speed_compare:SURFACECOMMS:B] Stopped
2012-06-19T20:11:19.156Z,1340136679.156 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Aggregate::uninitialize triangle_control_speed_compare:SURFACECOMMS:B
2012-06-19T20:11:19.156Z,1340136679.156 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed] Stopped
2012-06-19T20:11:19.156Z,1340136679.156 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize.
2012-06-19T20:11:19.156Z,1340136679.156 [triangle_control_speed_compare:SURFACECOMMS](INFO): Completed triangle_control_speed_compare:SURFACECOMMS
2012-06-19T20:11:19.156Z,1340136679.156 [triangle_control_speed_compare:SURFACECOMMS] Stopped
2012-06-19T20:11:19.156Z,1340136679.156 [triangle_control_speed_compare:SURFACECOMMS](INFO): Aggregate::uninitialize triangle_control_speed_compare:SURFACECOMMS
2012-06-19T20:11:19.156Z,1340136679.156 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface] Stopped
2012-06-19T20:11:19.156Z,1340136679.156 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-19T20:11:19.556Z,1340136679.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:PHONEHOMEWPT2](INFO): Completed triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:PHONEHOMEWPT2
2012-06-19T20:11:19.556Z,1340136679.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:PHONEHOMEWPT2] Stopped
2012-06-19T20:11:19.556Z,1340136679.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:PHONEHOMEWPT2](INFO): Aggregate::uninitialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:PHONEHOMEWPT2
2012-06-19T20:11:19.556Z,1340136679.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1](INFO): Completed triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1
2012-06-19T20:11:19.556Z,1340136679.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1] Stopped
2012-06-19T20:11:19.556Z,1340136679.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1](INFO): Aggregate::uninitialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1
2012-06-19T20:11:19.556Z,1340136679.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:A.SetSpeed] Stopped
2012-06-19T20:11:19.556Z,1340136679.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:A.SetSpeed](DEBUG): Uninitialize.
2012-06-19T20:11:19.556Z,1340136679.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:B.Pitch] Stopped
2012-06-19T20:11:19.556Z,1340136679.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2] Running Loop=1
2012-06-19T20:11:19.556Z,1340136679.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2](INFO): Aggregate::initialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2
2012-06-19T20:11:19.556Z,1340136679.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:A.SetSpeed] Running Loop=1
2012-06-19T20:11:19.556Z,1340136679.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:A.SetSpeed](DEBUG): Initialize.
2012-06-19T20:11:19.556Z,1340136679.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:B.Pitch] Running Loop=1
2012-06-19T20:11:19.556Z,1340136679.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:B.Pitch](DEBUG): Initialize.
2012-06-19T20:11:19.556Z,1340136679.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:Wpt3.Waypoint] Running Loop=1
2012-06-19T20:11:19.556Z,1340136679.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:Wpt3.Waypoint](DEBUG): Initialize WaypointComponent.
2012-06-19T20:11:19.956Z,1340136679.956 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:B.Pitch] Running Loop=1
2012-06-19T20:11:19.956Z,1340136679.956 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:A.SetSpeed] Running Loop=1
2012-06-19T20:11:25.155Z,1340136685.155 [NAL9601](INFO): Powering down
2012-06-19T20:48:17.156Z,1340138897.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:Wpt3.Waypoint](INFO): Reached Waypoint: 36.816000,-121.883000
2012-06-19T20:48:17.156Z,1340138897.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:Wpt3.Waypoint] Stopped
2012-06-19T20:48:17.156Z,1340138897.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:Wpt3.Waypoint](DEBUG): Uninitialize WaypointComponent.
2012-06-19T20:48:17.156Z,1340138897.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:PHONEHOMEWPT3] Running Loop=1
2012-06-19T20:48:17.156Z,1340138897.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:PHONEHOMEWPT3](INFO): Aggregate::initialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:PHONEHOMEWPT3
2012-06-19T20:48:17.561Z,1340138897.561 [triangle_control_speed_compare:SURFACECOMMS] Running Loop=1
2012-06-19T20:48:17.561Z,1340138897.561 [triangle_control_speed_compare:SURFACECOMMS](INFO): Aggregate::initialize triangle_control_speed_compare:SURFACECOMMS
2012-06-19T20:48:17.561Z,1340138897.561 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-06-19T20:48:17.561Z,1340138897.561 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-19T20:53:29.556Z,1340139209.556 [triangle_control_speed_compare:SURFACECOMMS:B] Running Loop=1
2012-06-19T20:53:29.556Z,1340139209.556 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Aggregate::initialize triangle_control_speed_compare:SURFACECOMMS:B
2012-06-19T20:53:29.556Z,1340139209.556 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed] Running Loop=1
2012-06-19T20:53:29.556Z,1340139209.556 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize.
2012-06-19T20:53:29.556Z,1340139209.556 [triangle_control_speed_compare:SURFACECOMMS:B:B] Running Loop=1
2012-06-19T20:53:30.355Z,1340139210.355 [NAL9601](INFO): Powering up
2012-06-19T20:53:35.555Z,1340139215.555 [NAL9601](IMPORTANT): GPS fix at: 1340139215.55
2012-06-19T20:53:35.555Z,1340139215.555 [Navigation](IMPORTANT): Error 0.0 %DT at bearing 137.5 deg., over the last 1823.2 m.
2012-06-19T20:53:35.556Z,1340139215.556 [Navigation](IMPORTANT): Distance made good 1769.6 m, at (36.8146, -121.8827).
2012-06-19T20:53:35.556Z,1340139215.556 [triangle_control_speed_compare:SURFACECOMMS:B:B] Stopped
2012-06-19T20:53:35.556Z,1340139215.556 [triangle_control_speed_compare:SURFACECOMMS:B:C] Running Loop=1
2012-06-19T20:53:38.355Z,1340139218.355 [NAL9601](INFO): Sent 332 bytes from file Logs/20120619T181447/shore0005.lzma
2012-06-19T20:53:38.355Z,1340139218.355 [NAL9601](INFO): Packets left to send: 1
2012-06-19T20:53:38.355Z,1340139218.355 [NAL9601](INFO): Stored copy of sent data in Logs/20120619T181447/shore0005.lzma.parts/0001.sbd
2012-06-19T20:53:39.555Z,1340139219.555 [NAL9601](INFO): Sent 62 bytes from file Logs/20120619T181447/shore0005.lzma
2012-06-19T20:53:39.555Z,1340139219.555 [NAL9601](INFO): Packets left to send: 0
2012-06-19T20:53:39.555Z,1340139219.555 [NAL9601](INFO): Stored copy of sent data in Logs/20120619T181447/shore0005.lzma.parts/0000.sbd
2012-06-19T20:53:40.355Z,1340139220.355 [triangle_control_speed_compare:SURFACECOMMS:B:C] Stopped
2012-06-19T20:53:40.355Z,1340139220.355 [triangle_control_speed_compare:SURFACECOMMS:B:D] Running Loop=1
2012-06-19T20:53:45.555Z,1340139225.555 [NAL9601](IMPORTANT): GPS fix at: 1340139225.55
2012-06-19T20:53:45.556Z,1340139225.556 [triangle_control_speed_compare:SURFACECOMMS:B:D] Stopped
2012-06-19T20:53:45.556Z,1340139225.556 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Completed triangle_control_speed_compare:SURFACECOMMS:B
2012-06-19T20:53:45.556Z,1340139225.556 [triangle_control_speed_compare:SURFACECOMMS:B] Stopped
2012-06-19T20:53:45.556Z,1340139225.556 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Aggregate::uninitialize triangle_control_speed_compare:SURFACECOMMS:B
2012-06-19T20:53:45.556Z,1340139225.556 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed] Stopped
2012-06-19T20:53:45.556Z,1340139225.556 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize.
2012-06-19T20:53:45.556Z,1340139225.556 [triangle_control_speed_compare:SURFACECOMMS](INFO): Completed triangle_control_speed_compare:SURFACECOMMS
2012-06-19T20:53:45.556Z,1340139225.556 [triangle_control_speed_compare:SURFACECOMMS] Stopped
2012-06-19T20:53:45.556Z,1340139225.556 [triangle_control_speed_compare:SURFACECOMMS](INFO): Aggregate::uninitialize triangle_control_speed_compare:SURFACECOMMS
2012-06-19T20:53:45.556Z,1340139225.556 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface] Stopped
2012-06-19T20:53:45.556Z,1340139225.556 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-19T20:53:45.956Z,1340139225.956 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:PHONEHOMEWPT3](INFO): Completed triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:PHONEHOMEWPT3
2012-06-19T20:53:45.956Z,1340139225.956 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:PHONEHOMEWPT3] Stopped
2012-06-19T20:53:45.956Z,1340139225.956 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:PHONEHOMEWPT3](INFO): Aggregate::uninitialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:PHONEHOMEWPT3
2012-06-19T20:53:45.956Z,1340139225.956 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2](INFO): Completed triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2
2012-06-19T20:53:45.956Z,1340139225.956 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2] Stopped
2012-06-19T20:53:45.956Z,1340139225.956 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2](INFO): Aggregate::uninitialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2
2012-06-19T20:53:45.956Z,1340139225.956 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:A.SetSpeed] Stopped
2012-06-19T20:53:45.956Z,1340139225.956 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:A.SetSpeed](DEBUG): Uninitialize.
2012-06-19T20:53:45.956Z,1340139225.956 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:B.Pitch] Stopped
2012-06-19T20:53:45.956Z,1340139225.956 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3] Running Loop=1
2012-06-19T20:53:45.956Z,1340139225.956 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3](INFO): Aggregate::initialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3
2012-06-19T20:53:45.956Z,1340139225.956 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:A.SetSpeed] Running Loop=1
2012-06-19T20:53:45.956Z,1340139225.956 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:A.SetSpeed](DEBUG): Initialize.
2012-06-19T20:53:45.956Z,1340139225.956 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:B.Pitch] Running Loop=1
2012-06-19T20:53:45.956Z,1340139225.956 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:B.Pitch](DEBUG): Initialize.
2012-06-19T20:53:45.956Z,1340139225.956 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:Wpt1.Waypoint] Running Loop=1
2012-06-19T20:53:45.956Z,1340139225.956 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2012-06-19T20:53:46.356Z,1340139226.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:B.Pitch] Running Loop=1
2012-06-19T20:53:46.356Z,1340139226.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:A.SetSpeed] Running Loop=1
2012-06-19T20:53:51.555Z,1340139231.555 [NAL9601](INFO): Powering down
2012-06-19T21:28:14.356Z,1340141294.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:Wpt1.Waypoint](INFO): Reached Waypoint: 36.815000,-121.869000
2012-06-19T21:28:14.356Z,1340141294.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:Wpt1.Waypoint] Stopped
2012-06-19T21:28:14.356Z,1340141294.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2012-06-19T21:28:14.356Z,1340141294.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:PHONEHOMEWPT1] Running Loop=1
2012-06-19T21:28:14.356Z,1340141294.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:PHONEHOMEWPT1](INFO): Aggregate::initialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:PHONEHOMEWPT1
2012-06-19T21:28:14.756Z,1340141294.756 [triangle_control_speed_compare:SURFACECOMMS] Running Loop=1
2012-06-19T21:28:14.756Z,1340141294.756 [triangle_control_speed_compare:SURFACECOMMS](INFO): Aggregate::initialize triangle_control_speed_compare:SURFACECOMMS
2012-06-19T21:28:14.756Z,1340141294.756 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-06-19T21:28:14.756Z,1340141294.756 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-19T21:33:24.756Z,1340141604.756 [triangle_control_speed_compare:SURFACECOMMS:B] Running Loop=1
2012-06-19T21:33:24.756Z,1340141604.756 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Aggregate::initialize triangle_control_speed_compare:SURFACECOMMS:B
2012-06-19T21:33:24.756Z,1340141604.756 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed] Running Loop=1
2012-06-19T21:33:24.756Z,1340141604.756 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize.
2012-06-19T21:33:24.756Z,1340141604.756 [triangle_control_speed_compare:SURFACECOMMS:B:B] Running Loop=1
2012-06-19T21:33:25.555Z,1340141605.555 [NAL9601](INFO): Powering up
2012-06-19T21:33:30.755Z,1340141610.755 [NAL9601](IMPORTANT): GPS fix at: 1340141610.75
2012-06-19T21:33:30.756Z,1340141610.756 [Navigation](IMPORTANT): Error 0.0 %DT at bearing -43.3 deg., over the last 1384.0 m.
2012-06-19T21:33:30.756Z,1340141610.756 [Navigation](IMPORTANT): Distance made good 1370.4 m, at (36.8151, -121.8673).
2012-06-19T21:33:30.756Z,1340141610.756 [triangle_control_speed_compare:SURFACECOMMS:B:B] Stopped
2012-06-19T21:33:30.756Z,1340141610.756 [triangle_control_speed_compare:SURFACECOMMS:B:C] Running Loop=1
2012-06-19T21:33:33.555Z,1340141613.555 [NAL9601](INFO): Sent 332 bytes from file Logs/20120619T181447/shore0006.lzma
2012-06-19T21:33:33.555Z,1340141613.555 [NAL9601](INFO): Packets left to send: 1
2012-06-19T21:33:33.555Z,1340141613.555 [NAL9601](INFO): Stored copy of sent data in Logs/20120619T181447/shore0006.lzma.parts/0001.sbd
2012-06-19T21:33:34.755Z,1340141614.755 [NAL9601](INFO): Sent 38 bytes from file Logs/20120619T181447/shore0006.lzma
2012-06-19T21:33:34.755Z,1340141614.755 [NAL9601](INFO): Packets left to send: 0
2012-06-19T21:33:34.755Z,1340141614.755 [NAL9601](INFO): Stored copy of sent data in Logs/20120619T181447/shore0006.lzma.parts/0000.sbd
2012-06-19T21:33:35.556Z,1340141615.556 [triangle_control_speed_compare:SURFACECOMMS:B:C] Stopped
2012-06-19T21:33:35.556Z,1340141615.556 [triangle_control_speed_compare:SURFACECOMMS:B:D] Running Loop=1
2012-06-19T21:33:40.755Z,1340141620.755 [NAL9601](IMPORTANT): GPS fix at: 1340141620.75
2012-06-19T21:33:40.756Z,1340141620.756 [triangle_control_speed_compare:SURFACECOMMS:B:D] Stopped
2012-06-19T21:33:40.756Z,1340141620.756 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Completed triangle_control_speed_compare:SURFACECOMMS:B
2012-06-19T21:33:40.756Z,1340141620.756 [triangle_control_speed_compare:SURFACECOMMS:B] Stopped
2012-06-19T21:33:40.756Z,1340141620.756 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Aggregate::uninitialize triangle_control_speed_compare:SURFACECOMMS:B
2012-06-19T21:33:40.756Z,1340141620.756 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed] Stopped
2012-06-19T21:33:40.756Z,1340141620.756 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize.
2012-06-19T21:33:40.756Z,1340141620.756 [triangle_control_speed_compare:SURFACECOMMS](INFO): Completed triangle_control_speed_compare:SURFACECOMMS
2012-06-19T21:33:40.756Z,1340141620.756 [triangle_control_speed_compare:SURFACECOMMS] Stopped
2012-06-19T21:33:40.756Z,1340141620.756 [triangle_control_speed_compare:SURFACECOMMS](INFO): Aggregate::uninitialize triangle_control_speed_compare:SURFACECOMMS
2012-06-19T21:33:40.756Z,1340141620.756 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface] Stopped
2012-06-19T21:33:40.756Z,1340141620.756 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-19T21:33:41.156Z,1340141621.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:PHONEHOMEWPT1](INFO): Completed triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:PHONEHOMEWPT1
2012-06-19T21:33:41.156Z,1340141621.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:PHONEHOMEWPT1] Stopped
2012-06-19T21:33:41.156Z,1340141621.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:PHONEHOMEWPT1](INFO): Aggregate::uninitialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:PHONEHOMEWPT1
2012-06-19T21:33:41.156Z,1340141621.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3](INFO): Completed triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3
2012-06-19T21:33:41.156Z,1340141621.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3] Stopped
2012-06-19T21:33:41.156Z,1340141621.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3](INFO): Aggregate::uninitialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3
2012-06-19T21:33:41.156Z,1340141621.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:A.SetSpeed] Stopped
2012-06-19T21:33:41.156Z,1340141621.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:A.SetSpeed](DEBUG): Uninitialize.
2012-06-19T21:33:41.156Z,1340141621.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:B.Pitch] Stopped
2012-06-19T21:33:41.156Z,1340141621.156 [triangle_control_speed_compare:ThreeLaps:TestDrive](INFO): Completed triangle_control_speed_compare:ThreeLaps:TestDrive
2012-06-19T21:33:41.156Z,1340141621.156 [triangle_control_speed_compare:ThreeLaps:TestDrive] Stopped
2012-06-19T21:33:41.156Z,1340141621.156 [triangle_control_speed_compare:ThreeLaps:TestDrive](INFO): Aggregate::uninitialize triangle_control_speed_compare:ThreeLaps:TestDrive
2012-06-19T21:33:41.156Z,1340141621.156 [triangle_control_speed_compare:ThreeLaps:Lap2] Running Loop=1
2012-06-19T21:33:41.156Z,1340141621.156 [triangle_control_speed_compare:ThreeLaps:Lap2](INFO): Aggregate::initialize triangle_control_speed_compare:ThreeLaps:Lap2
2012-06-19T21:33:41.156Z,1340141621.156 [triangle_control_speed_compare:ThreeLaps:Lap2:A] Running Loop=1
2012-06-19T21:33:41.156Z,1340141621.156 [triangle_control_speed_compare:ThreeLaps:Lap2:LAP2] Running Loop=1
2012-06-19T21:33:41.156Z,1340141621.156 [triangle_control_speed_compare:ThreeLaps:Lap2:LAP2](INFO): Aggregate::initialize triangle_control_speed_compare:ThreeLaps:Lap2:LAP2
2012-06-19T21:33:41.556Z,1340141621.556 [triangle_control_speed_compare:ThreeLaps:Lap2:A] Running Loop=1
2012-06-19T21:33:41.556Z,1340141621.556 [triangle_control_speed_compare:ThreeLaps:TestDrive] Running Loop=1
2012-06-19T21:33:41.556Z,1340141621.556 [triangle_control_speed_compare:ThreeLaps:TestDrive](INFO): Aggregate::initialize triangle_control_speed_compare:ThreeLaps:TestDrive
2012-06-19T21:33:41.556Z,1340141621.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1] Running Loop=1
2012-06-19T21:33:41.556Z,1340141621.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1](INFO): Aggregate::initialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1
2012-06-19T21:33:41.556Z,1340141621.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:A.SetSpeed] Running Loop=1
2012-06-19T21:33:41.556Z,1340141621.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:A.SetSpeed](DEBUG): Initialize.
2012-06-19T21:33:41.556Z,1340141621.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:B.Pitch] Running Loop=1
2012-06-19T21:33:41.556Z,1340141621.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:B.Pitch](DEBUG): Initialize.
2012-06-19T21:33:41.556Z,1340141621.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:Wpt2.Waypoint] Running Loop=1
2012-06-19T21:33:41.556Z,1340141621.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:Wpt2.Waypoint](DEBUG): Initialize WaypointComponent.
2012-06-19T21:33:46.755Z,1340141626.755 [NAL9601](INFO): Powering down
2012-06-19T22:12:33.161Z,1340143953.161 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:Wpt2.Waypoint](INFO): Reached Waypoint: 36.829000,-121.886000
2012-06-19T22:12:33.161Z,1340143953.161 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:Wpt2.Waypoint] Stopped
2012-06-19T22:12:33.161Z,1340143953.161 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:Wpt2.Waypoint](DEBUG): Uninitialize WaypointComponent.
2012-06-19T22:12:33.161Z,1340143953.161 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:PHONEHOMEWPT2] Running Loop=1
2012-06-19T22:12:33.161Z,1340143953.161 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:PHONEHOMEWPT2](INFO): Aggregate::initialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:PHONEHOMEWPT2
2012-06-19T22:12:33.559Z,1340143953.559 [triangle_control_speed_compare:SURFACECOMMS] Running Loop=1
2012-06-19T22:12:33.559Z,1340143953.559 [triangle_control_speed_compare:SURFACECOMMS](INFO): Aggregate::initialize triangle_control_speed_compare:SURFACECOMMS
2012-06-19T22:12:33.559Z,1340143953.559 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-06-19T22:12:33.559Z,1340143953.559 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-19T22:17:44.757Z,1340144264.757 [triangle_control_speed_compare:SURFACECOMMS:B] Running Loop=1
2012-06-19T22:17:44.757Z,1340144264.757 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Aggregate::initialize triangle_control_speed_compare:SURFACECOMMS:B
2012-06-19T22:17:44.757Z,1340144264.757 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed] Running Loop=1
2012-06-19T22:17:44.757Z,1340144264.757 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize.
2012-06-19T22:17:44.757Z,1340144264.757 [triangle_control_speed_compare:SURFACECOMMS:B:B] Running Loop=1
2012-06-19T22:17:45.555Z,1340144265.555 [NAL9601](INFO): Powering up
2012-06-19T22:17:50.755Z,1340144270.755 [NAL9601](IMPORTANT): GPS fix at: 1340144270.75
2012-06-19T22:17:50.756Z,1340144270.756 [Navigation](IMPORTANT): Error 0.0 %DT at bearing -84.9 deg., over the last 2474.7 m.
2012-06-19T22:17:50.756Z,1340144270.756 [Navigation](IMPORTANT): Distance made good 2440.7 m, at (36.8300, -121.8875).
2012-06-19T22:17:50.756Z,1340144270.756 [triangle_control_speed_compare:SURFACECOMMS:B:B] Stopped
2012-06-19T22:17:50.756Z,1340144270.756 [triangle_control_speed_compare:SURFACECOMMS:B:C] Running Loop=1
2012-06-19T22:17:53.555Z,1340144273.555 [NAL9601](INFO): Sent 332 bytes from file Logs/20120619T181447/shore0007.lzma
2012-06-19T22:17:53.555Z,1340144273.555 [NAL9601](INFO): Packets left to send: 1
2012-06-19T22:17:53.555Z,1340144273.555 [NAL9601](INFO): Stored copy of sent data in Logs/20120619T181447/shore0007.lzma.parts/0001.sbd
2012-06-19T22:17:54.755Z,1340144274.755 [NAL9601](INFO): Sent 55 bytes from file Logs/20120619T181447/shore0007.lzma
2012-06-19T22:17:54.755Z,1340144274.755 [NAL9601](INFO): Packets left to send: 0
2012-06-19T22:17:54.755Z,1340144274.755 [NAL9601](INFO): Stored copy of sent data in Logs/20120619T181447/shore0007.lzma.parts/0000.sbd
2012-06-19T22:17:55.556Z,1340144275.556 [triangle_control_speed_compare:SURFACECOMMS:B:C] Stopped
2012-06-19T22:17:55.556Z,1340144275.556 [triangle_control_speed_compare:SURFACECOMMS:B:D] Running Loop=1
2012-06-19T22:18:00.755Z,1340144280.755 [NAL9601](IMPORTANT): GPS fix at: 1340144280.75
2012-06-19T22:18:00.756Z,1340144280.756 [triangle_control_speed_compare:SURFACECOMMS:B:D] Stopped
2012-06-19T22:18:00.756Z,1340144280.756 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Completed triangle_control_speed_compare:SURFACECOMMS:B
2012-06-19T22:18:00.756Z,1340144280.756 [triangle_control_speed_compare:SURFACECOMMS:B] Stopped
2012-06-19T22:18:00.756Z,1340144280.756 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Aggregate::uninitialize triangle_control_speed_compare:SURFACECOMMS:B
2012-06-19T22:18:00.756Z,1340144280.756 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed] Stopped
2012-06-19T22:18:00.756Z,1340144280.756 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize.
2012-06-19T22:18:00.756Z,1340144280.756 [triangle_control_speed_compare:SURFACECOMMS](INFO): Completed triangle_control_speed_compare:SURFACECOMMS
2012-06-19T22:18:00.756Z,1340144280.756 [triangle_control_speed_compare:SURFACECOMMS] Stopped
2012-06-19T22:18:00.756Z,1340144280.756 [triangle_control_speed_compare:SURFACECOMMS](INFO): Aggregate::uninitialize triangle_control_speed_compare:SURFACECOMMS
2012-06-19T22:18:00.756Z,1340144280.756 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface] Stopped
2012-06-19T22:18:00.756Z,1340144280.756 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-19T22:18:01.156Z,1340144281.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:PHONEHOMEWPT2](INFO): Completed triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:PHONEHOMEWPT2
2012-06-19T22:18:01.156Z,1340144281.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:PHONEHOMEWPT2] Stopped
2012-06-19T22:18:01.156Z,1340144281.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:PHONEHOMEWPT2](INFO): Aggregate::uninitialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:PHONEHOMEWPT2
2012-06-19T22:18:01.156Z,1340144281.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1](INFO): Completed triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1
2012-06-19T22:18:01.156Z,1340144281.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1] Stopped
2012-06-19T22:18:01.156Z,1340144281.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1](INFO): Aggregate::uninitialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1
2012-06-19T22:18:01.156Z,1340144281.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:A.SetSpeed] Stopped
2012-06-19T22:18:01.156Z,1340144281.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:A.SetSpeed](DEBUG): Uninitialize.
2012-06-19T22:18:01.156Z,1340144281.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:B.Pitch] Stopped
2012-06-19T22:18:01.156Z,1340144281.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2] Running Loop=1
2012-06-19T22:18:01.156Z,1340144281.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2](INFO): Aggregate::initialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2
2012-06-19T22:18:01.156Z,1340144281.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:A.SetSpeed] Running Loop=1
2012-06-19T22:18:01.156Z,1340144281.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:A.SetSpeed](DEBUG): Initialize.
2012-06-19T22:18:01.156Z,1340144281.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:B.Pitch] Running Loop=1
2012-06-19T22:18:01.156Z,1340144281.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:B.Pitch](DEBUG): Initialize.
2012-06-19T22:18:01.156Z,1340144281.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:Wpt3.Waypoint] Running Loop=1
2012-06-19T22:18:01.156Z,1340144281.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:Wpt3.Waypoint](DEBUG): Initialize WaypointComponent.
2012-06-19T22:18:06.755Z,1340144286.755 [NAL9601](INFO): Powering down
2012-06-19T22:54:43.556Z,1340146483.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:Wpt3.Waypoint](INFO): Reached Waypoint: 36.816000,-121.883000
2012-06-19T22:54:43.556Z,1340146483.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:Wpt3.Waypoint] Stopped
2012-06-19T22:54:43.556Z,1340146483.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:Wpt3.Waypoint](DEBUG): Uninitialize WaypointComponent.
2012-06-19T22:54:43.556Z,1340146483.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:PHONEHOMEWPT3] Running Loop=1
2012-06-19T22:54:43.556Z,1340146483.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:PHONEHOMEWPT3](INFO): Aggregate::initialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:PHONEHOMEWPT3
2012-06-19T22:54:43.956Z,1340146483.956 [triangle_control_speed_compare:SURFACECOMMS] Running Loop=1
2012-06-19T22:54:43.956Z,1340146483.956 [triangle_control_speed_compare:SURFACECOMMS](INFO): Aggregate::initialize triangle_control_speed_compare:SURFACECOMMS
2012-06-19T22:54:43.956Z,1340146483.956 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-06-19T22:54:43.956Z,1340146483.956 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-19T22:59:54.756Z,1340146794.756 [triangle_control_speed_compare:SURFACECOMMS:B] Running Loop=1
2012-06-19T22:59:54.756Z,1340146794.756 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Aggregate::initialize triangle_control_speed_compare:SURFACECOMMS:B
2012-06-19T22:59:54.756Z,1340146794.756 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed] Running Loop=1
2012-06-19T22:59:54.756Z,1340146794.756 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize.
2012-06-19T22:59:54.756Z,1340146794.756 [triangle_control_speed_compare:SURFACECOMMS:B:B] Running Loop=1
2012-06-19T22:59:55.555Z,1340146795.555 [NAL9601](INFO): Powering up
2012-06-19T23:00:00.755Z,1340146800.755 [NAL9601](IMPORTANT): GPS fix at: 1340146800.75
2012-06-19T23:00:00.756Z,1340146800.756 [Navigation](IMPORTANT): Error 0.0 %DT at bearing 132.6 deg., over the last 1811.2 m.
2012-06-19T23:00:00.756Z,1340146800.756 [Navigation](IMPORTANT): Distance made good 1760.1 m, at (36.8146, -121.8827).
2012-06-19T23:00:00.756Z,1340146800.756 [triangle_control_speed_compare:SURFACECOMMS:B:B] Stopped
2012-06-19T23:00:00.756Z,1340146800.756 [triangle_control_speed_compare:SURFACECOMMS:B:C] Running Loop=1
2012-06-19T23:00:03.555Z,1340146803.555 [NAL9601](INFO): Sent 332 bytes from file Logs/20120619T181447/shore0008.lzma
2012-06-19T23:00:03.555Z,1340146803.555 [NAL9601](INFO): Packets left to send: 1
2012-06-19T23:00:03.555Z,1340146803.555 [NAL9601](INFO): Stored copy of sent data in Logs/20120619T181447/shore0008.lzma.parts/0001.sbd
2012-06-19T23:00:04.755Z,1340146804.755 [NAL9601](INFO): Sent 53 bytes from file Logs/20120619T181447/shore0008.lzma
2012-06-19T23:00:04.755Z,1340146804.755 [NAL9601](INFO): Packets left to send: 0
2012-06-19T23:00:04.755Z,1340146804.755 [NAL9601](INFO): Stored copy of sent data in Logs/20120619T181447/shore0008.lzma.parts/0000.sbd
2012-06-19T23:00:05.557Z,1340146805.557 [triangle_control_speed_compare:SURFACECOMMS:B:C] Stopped
2012-06-19T23:00:05.557Z,1340146805.557 [triangle_control_speed_compare:SURFACECOMMS:B:D] Running Loop=1
2012-06-19T23:00:10.755Z,1340146810.755 [NAL9601](IMPORTANT): GPS fix at: 1340146810.75
2012-06-19T23:00:10.756Z,1340146810.756 [triangle_control_speed_compare:SURFACECOMMS:B:D] Stopped
2012-06-19T23:00:10.756Z,1340146810.756 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Completed triangle_control_speed_compare:SURFACECOMMS:B
2012-06-19T23:00:10.756Z,1340146810.756 [triangle_control_speed_compare:SURFACECOMMS:B] Stopped
2012-06-19T23:00:10.756Z,1340146810.756 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Aggregate::uninitialize triangle_control_speed_compare:SURFACECOMMS:B
2012-06-19T23:00:10.756Z,1340146810.756 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed] Stopped
2012-06-19T23:00:10.756Z,1340146810.756 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize.
2012-06-19T23:00:10.756Z,1340146810.756 [triangle_control_speed_compare:SURFACECOMMS](INFO): Completed triangle_control_speed_compare:SURFACECOMMS
2012-06-19T23:00:10.756Z,1340146810.756 [triangle_control_speed_compare:SURFACECOMMS] Stopped
2012-06-19T23:00:10.756Z,1340146810.756 [triangle_control_speed_compare:SURFACECOMMS](INFO): Aggregate::uninitialize triangle_control_speed_compare:SURFACECOMMS
2012-06-19T23:00:10.756Z,1340146810.756 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface] Stopped
2012-06-19T23:00:10.756Z,1340146810.756 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-19T23:00:11.156Z,1340146811.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:PHONEHOMEWPT3](INFO): Completed triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:PHONEHOMEWPT3
2012-06-19T23:00:11.156Z,1340146811.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:PHONEHOMEWPT3] Stopped
2012-06-19T23:00:11.156Z,1340146811.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:PHONEHOMEWPT3](INFO): Aggregate::uninitialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:PHONEHOMEWPT3
2012-06-19T23:00:11.156Z,1340146811.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2](INFO): Completed triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2
2012-06-19T23:00:11.156Z,1340146811.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2] Stopped
2012-06-19T23:00:11.156Z,1340146811.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2](INFO): Aggregate::uninitialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2
2012-06-19T23:00:11.156Z,1340146811.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:A.SetSpeed] Stopped
2012-06-19T23:00:11.156Z,1340146811.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:A.SetSpeed](DEBUG): Uninitialize.
2012-06-19T23:00:11.156Z,1340146811.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:B.Pitch] Stopped
2012-06-19T23:00:11.156Z,1340146811.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3] Running Loop=1
2012-06-19T23:00:11.156Z,1340146811.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3](INFO): Aggregate::initialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3
2012-06-19T23:00:11.156Z,1340146811.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:A.SetSpeed] Running Loop=1
2012-06-19T23:00:11.156Z,1340146811.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:A.SetSpeed](DEBUG): Initialize.
2012-06-19T23:00:11.156Z,1340146811.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:B.Pitch] Running Loop=1
2012-06-19T23:00:11.156Z,1340146811.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:B.Pitch](DEBUG): Initialize.
2012-06-19T23:00:11.156Z,1340146811.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:Wpt1.Waypoint] Running Loop=1
2012-06-19T23:00:11.156Z,1340146811.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2012-06-19T23:00:16.755Z,1340146816.755 [NAL9601](INFO): Powering down
2012-06-19T23:34:31.556Z,1340148871.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:Wpt1.Waypoint](INFO): Reached Waypoint: 36.815000,-121.869000
2012-06-19T23:34:31.556Z,1340148871.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:Wpt1.Waypoint] Stopped
2012-06-19T23:34:31.556Z,1340148871.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2012-06-19T23:34:31.556Z,1340148871.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:PHONEHOMEWPT1] Running Loop=1
2012-06-19T23:34:31.556Z,1340148871.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:PHONEHOMEWPT1](INFO): Aggregate::initialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:PHONEHOMEWPT1
2012-06-19T23:34:31.956Z,1340148871.956 [triangle_control_speed_compare:SURFACECOMMS] Running Loop=1
2012-06-19T23:34:31.956Z,1340148871.956 [triangle_control_speed_compare:SURFACECOMMS](INFO): Aggregate::initialize triangle_control_speed_compare:SURFACECOMMS
2012-06-19T23:34:31.956Z,1340148871.956 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-06-19T23:34:31.956Z,1340148871.956 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-19T23:39:43.157Z,1340149183.157 [triangle_control_speed_compare:SURFACECOMMS:B] Running Loop=1
2012-06-19T23:39:43.157Z,1340149183.157 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Aggregate::initialize triangle_control_speed_compare:SURFACECOMMS:B
2012-06-19T23:39:43.157Z,1340149183.157 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed] Running Loop=1
2012-06-19T23:39:43.157Z,1340149183.157 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize.
2012-06-19T23:39:43.157Z,1340149183.157 [triangle_control_speed_compare:SURFACECOMMS:B:B] Running Loop=1
2012-06-19T23:39:43.955Z,1340149183.955 [NAL9601](INFO): Powering up
2012-06-19T23:39:49.155Z,1340149189.155 [NAL9601](IMPORTANT): GPS fix at: 1340149189.15
2012-06-19T23:39:49.156Z,1340149189.156 [Navigation](IMPORTANT): Error 0.0 %DT at bearing -38.5 deg., over the last 1380.1 m.
2012-06-19T23:39:49.156Z,1340149189.156 [Navigation](IMPORTANT): Distance made good 1367.1 m, at (36.8151, -121.8673).
2012-06-19T23:39:49.156Z,1340149189.156 [triangle_control_speed_compare:SURFACECOMMS:B:B] Stopped
2012-06-19T23:39:49.156Z,1340149189.156 [triangle_control_speed_compare:SURFACECOMMS:B:C] Running Loop=1
2012-06-19T23:39:51.955Z,1340149191.955 [NAL9601](INFO): Sent 332 bytes from file Logs/20120619T181447/shore0009.lzma
2012-06-19T23:39:51.955Z,1340149191.955 [NAL9601](INFO): Packets left to send: 1
2012-06-19T23:39:51.955Z,1340149191.955 [NAL9601](INFO): Stored copy of sent data in Logs/20120619T181447/shore0009.lzma.parts/0001.sbd
2012-06-19T23:39:53.155Z,1340149193.155 [NAL9601](INFO): Sent 21 bytes from file Logs/20120619T181447/shore0009.lzma
2012-06-19T23:39:53.155Z,1340149193.155 [NAL9601](INFO): Packets left to send: 0
2012-06-19T23:39:53.155Z,1340149193.155 [NAL9601](INFO): Stored copy of sent data in Logs/20120619T181447/shore0009.lzma.parts/0000.sbd
2012-06-19T23:39:53.956Z,1340149193.955 [triangle_control_speed_compare:SURFACECOMMS:B:C] Stopped
2012-06-19T23:39:53.956Z,1340149193.956 [triangle_control_speed_compare:SURFACECOMMS:B:D] Running Loop=1
2012-06-19T23:39:59.155Z,1340149199.155 [NAL9601](IMPORTANT): GPS fix at: 1340149199.15
2012-06-19T23:39:59.156Z,1340149199.156 [triangle_control_speed_compare:SURFACECOMMS:B:D] Stopped
2012-06-19T23:39:59.156Z,1340149199.156 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Completed triangle_control_speed_compare:SURFACECOMMS:B
2012-06-19T23:39:59.156Z,1340149199.156 [triangle_control_speed_compare:SURFACECOMMS:B] Stopped
2012-06-19T23:39:59.156Z,1340149199.156 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Aggregate::uninitialize triangle_control_speed_compare:SURFACECOMMS:B
2012-06-19T23:39:59.156Z,1340149199.156 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed] Stopped
2012-06-19T23:39:59.156Z,1340149199.156 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize.
2012-06-19T23:39:59.156Z,1340149199.156 [triangle_control_speed_compare:SURFACECOMMS](INFO): Completed triangle_control_speed_compare:SURFACECOMMS
2012-06-19T23:39:59.156Z,1340149199.156 [triangle_control_speed_compare:SURFACECOMMS] Stopped
2012-06-19T23:39:59.156Z,1340149199.156 [triangle_control_speed_compare:SURFACECOMMS](INFO): Aggregate::uninitialize triangle_control_speed_compare:SURFACECOMMS
2012-06-19T23:39:59.156Z,1340149199.156 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface] Stopped
2012-06-19T23:39:59.156Z,1340149199.156 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-19T23:39:59.556Z,1340149199.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:PHONEHOMEWPT1](INFO): Completed triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:PHONEHOMEWPT1
2012-06-19T23:39:59.556Z,1340149199.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:PHONEHOMEWPT1] Stopped
2012-06-19T23:39:59.556Z,1340149199.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:PHONEHOMEWPT1](INFO): Aggregate::uninitialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:PHONEHOMEWPT1
2012-06-19T23:39:59.556Z,1340149199.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3](INFO): Completed triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3
2012-06-19T23:39:59.556Z,1340149199.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3] Stopped
2012-06-19T23:39:59.556Z,1340149199.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3](INFO): Aggregate::uninitialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3
2012-06-19T23:39:59.556Z,1340149199.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:A.SetSpeed] Stopped
2012-06-19T23:39:59.556Z,1340149199.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:A.SetSpeed](DEBUG): Uninitialize.
2012-06-19T23:39:59.556Z,1340149199.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:B.Pitch] Stopped
2012-06-19T23:39:59.556Z,1340149199.556 [triangle_control_speed_compare:ThreeLaps:TestDrive](INFO): Completed triangle_control_speed_compare:ThreeLaps:TestDrive
2012-06-19T23:39:59.556Z,1340149199.556 [triangle_control_speed_compare:ThreeLaps:TestDrive] Stopped
2012-06-19T23:39:59.556Z,1340149199.556 [triangle_control_speed_compare:ThreeLaps:TestDrive](INFO): Aggregate::uninitialize triangle_control_speed_compare:ThreeLaps:TestDrive
2012-06-19T23:39:59.956Z,1340149199.956 [triangle_control_speed_compare:ThreeLaps:Lap2:LAP2](INFO): Completed triangle_control_speed_compare:ThreeLaps:Lap2:LAP2
2012-06-19T23:39:59.956Z,1340149199.956 [triangle_control_speed_compare:ThreeLaps:Lap2:LAP2] Stopped
2012-06-19T23:39:59.956Z,1340149199.956 [triangle_control_speed_compare:ThreeLaps:Lap2:LAP2](INFO): Aggregate::uninitialize triangle_control_speed_compare:ThreeLaps:Lap2:LAP2
2012-06-19T23:39:59.956Z,1340149199.956 [triangle_control_speed_compare:ThreeLaps:Lap2](INFO): Completed triangle_control_speed_compare:ThreeLaps:Lap2
2012-06-19T23:39:59.956Z,1340149199.956 [triangle_control_speed_compare:ThreeLaps:Lap2] Stopped
2012-06-19T23:39:59.956Z,1340149199.956 [triangle_control_speed_compare:ThreeLaps:Lap2](INFO): Aggregate::uninitialize triangle_control_speed_compare:ThreeLaps:Lap2
2012-06-19T23:39:59.956Z,1340149199.956 [triangle_control_speed_compare:ThreeLaps:Lap2:A] Stopped
2012-06-19T23:39:59.956Z,1340149199.956 [triangle_control_speed_compare:ThreeLaps:Lap3] Running Loop=1
2012-06-19T23:39:59.956Z,1340149199.956 [triangle_control_speed_compare:ThreeLaps:Lap3](INFO): Aggregate::initialize triangle_control_speed_compare:ThreeLaps:Lap3
2012-06-19T23:39:59.956Z,1340149199.956 [triangle_control_speed_compare:ThreeLaps:Lap3:B] Running Loop=1
2012-06-19T23:39:59.956Z,1340149199.956 [triangle_control_speed_compare:ThreeLaps:Lap3:A] Running Loop=1
2012-06-19T23:40:00.356Z,1340149200.356 [triangle_control_speed_compare:ThreeLaps:Lap3:B] Running Loop=1
2012-06-19T23:40:00.356Z,1340149200.356 [triangle_control_speed_compare:ThreeLaps:Lap3:A] Stopped
2012-06-19T23:40:00.356Z,1340149200.356 [triangle_control_speed_compare:ThreeLaps:Lap3:LAP3] Running Loop=1
2012-06-19T23:40:00.356Z,1340149200.356 [triangle_control_speed_compare:ThreeLaps:Lap3:LAP3](INFO): Aggregate::initialize triangle_control_speed_compare:ThreeLaps:Lap3:LAP3
2012-06-19T23:40:00.756Z,1340149200.756 [triangle_control_speed_compare:ThreeLaps:TestDrive] Running Loop=1
2012-06-19T23:40:00.756Z,1340149200.756 [triangle_control_speed_compare:ThreeLaps:TestDrive](INFO): Aggregate::initialize triangle_control_speed_compare:ThreeLaps:TestDrive
2012-06-19T23:40:00.756Z,1340149200.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1] Running Loop=1
2012-06-19T23:40:00.756Z,1340149200.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1](INFO): Aggregate::initialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1
2012-06-19T23:40:00.756Z,1340149200.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:A.SetSpeed] Running Loop=1
2012-06-19T23:40:00.756Z,1340149200.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:A.SetSpeed](DEBUG): Initialize.
2012-06-19T23:40:00.756Z,1340149200.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:B.Pitch] Running Loop=1
2012-06-19T23:40:00.756Z,1340149200.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:B.Pitch](DEBUG): Initialize.
2012-06-19T23:40:00.756Z,1340149200.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:Wpt2.Waypoint] Running Loop=1
2012-06-19T23:40:00.756Z,1340149200.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:Wpt2.Waypoint](DEBUG): Initialize WaypointComponent.
2012-06-19T23:40:05.155Z,1340149205.155 [NAL9601](INFO): Powering down
2012-06-20T00:18:53.156Z,1340151533.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:Wpt2.Waypoint](INFO): Reached Waypoint: 36.829000,-121.886000
2012-06-20T00:18:53.156Z,1340151533.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:Wpt2.Waypoint] Stopped
2012-06-20T00:18:53.156Z,1340151533.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:Wpt2.Waypoint](DEBUG): Uninitialize WaypointComponent.
2012-06-20T00:18:53.156Z,1340151533.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:PHONEHOMEWPT2] Running Loop=1
2012-06-20T00:18:53.156Z,1340151533.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:PHONEHOMEWPT2](INFO): Aggregate::initialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:PHONEHOMEWPT2
2012-06-20T00:18:53.556Z,1340151533.556 [triangle_control_speed_compare:SURFACECOMMS] Running Loop=1
2012-06-20T00:18:53.556Z,1340151533.556 [triangle_control_speed_compare:SURFACECOMMS](INFO): Aggregate::initialize triangle_control_speed_compare:SURFACECOMMS
2012-06-20T00:18:53.556Z,1340151533.556 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-06-20T00:18:53.556Z,1340151533.556 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-20T00:24:11.958Z,1340151851.958 [triangle_control_speed_compare:SURFACECOMMS:B] Running Loop=1
2012-06-20T00:24:11.958Z,1340151851.958 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Aggregate::initialize triangle_control_speed_compare:SURFACECOMMS:B
2012-06-20T00:24:11.958Z,1340151851.958 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed] Running Loop=1
2012-06-20T00:24:11.958Z,1340151851.958 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize.
2012-06-20T00:24:11.958Z,1340151851.958 [triangle_control_speed_compare:SURFACECOMMS:B:B] Running Loop=1
2012-06-20T00:24:12.755Z,1340151852.755 [NAL9601](INFO): Powering up
2012-06-20T00:24:17.955Z,1340151857.955 [NAL9601](IMPORTANT): GPS fix at: 1340151857.95
2012-06-20T00:24:17.958Z,1340151857.958 [Navigation](IMPORTANT): Error 0.0 %DT at bearing -83.9 deg., over the last 2479.7 m.
2012-06-20T00:24:17.958Z,1340151857.958 [Navigation](IMPORTANT): Distance made good 2445.2 m, at (36.8300, -121.8875).
2012-06-20T00:24:17.958Z,1340151857.958 [triangle_control_speed_compare:SURFACECOMMS:B:B] Stopped
2012-06-20T00:24:17.958Z,1340151857.958 [triangle_control_speed_compare:SURFACECOMMS:B:C] Running Loop=1
2012-06-20T00:24:20.755Z,1340151860.755 [NAL9601](INFO): Sent 332 bytes from file Logs/20120619T181447/shore0010.lzma
2012-06-20T00:24:20.755Z,1340151860.755 [NAL9601](INFO): Packets left to send: 1
2012-06-20T00:24:20.755Z,1340151860.755 [NAL9601](INFO): Stored copy of sent data in Logs/20120619T181447/shore0010.lzma.parts/0001.sbd
2012-06-20T00:24:21.955Z,1340151861.955 [NAL9601](INFO): Sent 47 bytes from file Logs/20120619T181447/shore0010.lzma
2012-06-20T00:24:21.955Z,1340151861.955 [NAL9601](INFO): Packets left to send: 0
2012-06-20T00:24:21.955Z,1340151861.955 [NAL9601](INFO): Stored copy of sent data in Logs/20120619T181447/shore0010.lzma.parts/0000.sbd
2012-06-20T00:24:22.755Z,1340151862.756 [triangle_control_speed_compare:SURFACECOMMS:B:C] Stopped
2012-06-20T00:24:22.756Z,1340151862.756 [triangle_control_speed_compare:SURFACECOMMS:B:D] Running Loop=1
2012-06-20T00:24:27.955Z,1340151867.955 [NAL9601](IMPORTANT): GPS fix at: 1340151867.95
2012-06-20T00:24:27.958Z,1340151867.958 [triangle_control_speed_compare:SURFACECOMMS:B:D] Stopped
2012-06-20T00:24:27.958Z,1340151867.958 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Completed triangle_control_speed_compare:SURFACECOMMS:B
2012-06-20T00:24:27.958Z,1340151867.958 [triangle_control_speed_compare:SURFACECOMMS:B] Stopped
2012-06-20T00:24:27.958Z,1340151867.958 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Aggregate::uninitialize triangle_control_speed_compare:SURFACECOMMS:B
2012-06-20T00:24:27.958Z,1340151867.958 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed] Stopped
2012-06-20T00:24:27.958Z,1340151867.958 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize.
2012-06-20T00:24:27.958Z,1340151867.958 [triangle_control_speed_compare:SURFACECOMMS](INFO): Completed triangle_control_speed_compare:SURFACECOMMS
2012-06-20T00:24:27.958Z,1340151867.958 [triangle_control_speed_compare:SURFACECOMMS] Stopped
2012-06-20T00:24:27.958Z,1340151867.958 [triangle_control_speed_compare:SURFACECOMMS](INFO): Aggregate::uninitialize triangle_control_speed_compare:SURFACECOMMS
2012-06-20T00:24:27.958Z,1340151867.958 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface] Stopped
2012-06-20T00:24:27.958Z,1340151867.958 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-20T00:24:28.356Z,1340151868.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:PHONEHOMEWPT2](INFO): Completed triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:PHONEHOMEWPT2
2012-06-20T00:24:28.356Z,1340151868.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:PHONEHOMEWPT2] Stopped
2012-06-20T00:24:28.356Z,1340151868.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:PHONEHOMEWPT2](INFO): Aggregate::uninitialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:PHONEHOMEWPT2
2012-06-20T00:24:28.356Z,1340151868.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1](INFO): Completed triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1
2012-06-20T00:24:28.356Z,1340151868.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1] Stopped
2012-06-20T00:24:28.356Z,1340151868.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1](INFO): Aggregate::uninitialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1
2012-06-20T00:24:28.356Z,1340151868.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:A.SetSpeed] Stopped
2012-06-20T00:24:28.356Z,1340151868.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:A.SetSpeed](DEBUG): Uninitialize.
2012-06-20T00:24:28.356Z,1340151868.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:B.Pitch] Stopped
2012-06-20T00:24:28.356Z,1340151868.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2] Running Loop=1
2012-06-20T00:24:28.356Z,1340151868.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2](INFO): Aggregate::initialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2
2012-06-20T00:24:28.356Z,1340151868.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:A.SetSpeed] Running Loop=1
2012-06-20T00:24:28.356Z,1340151868.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:A.SetSpeed](DEBUG): Initialize.
2012-06-20T00:24:28.356Z,1340151868.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:B.Pitch] Running Loop=1
2012-06-20T00:24:28.356Z,1340151868.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:B.Pitch](DEBUG): Initialize.
2012-06-20T00:24:28.356Z,1340151868.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:Wpt3.Waypoint] Running Loop=1
2012-06-20T00:24:28.356Z,1340151868.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:Wpt3.Waypoint](DEBUG): Initialize WaypointComponent.
2012-06-20T00:24:33.955Z,1340151873.955 [NAL9601](INFO): Powering down
2012-06-20T01:01:11.556Z,1340154071.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:Wpt3.Waypoint](INFO): Reached Waypoint: 36.816000,-121.883000
2012-06-20T01:01:11.556Z,1340154071.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:Wpt3.Waypoint] Stopped
2012-06-20T01:01:11.556Z,1340154071.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:Wpt3.Waypoint](DEBUG): Uninitialize WaypointComponent.
2012-06-20T01:01:11.556Z,1340154071.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:PHONEHOMEWPT3] Running Loop=1
2012-06-20T01:01:11.556Z,1340154071.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:PHONEHOMEWPT3](INFO): Aggregate::initialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:PHONEHOMEWPT3
2012-06-20T01:01:11.956Z,1340154071.956 [triangle_control_speed_compare:SURFACECOMMS] Running Loop=1
2012-06-20T01:01:11.956Z,1340154071.956 [triangle_control_speed_compare:SURFACECOMMS](INFO): Aggregate::initialize triangle_control_speed_compare:SURFACECOMMS
2012-06-20T01:01:11.956Z,1340154071.956 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-06-20T01:01:11.956Z,1340154071.956 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-20T01:06:30.357Z,1340154390.357 [triangle_control_speed_compare:SURFACECOMMS:B] Running Loop=1
2012-06-20T01:06:30.357Z,1340154390.357 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Aggregate::initialize triangle_control_speed_compare:SURFACECOMMS:B
2012-06-20T01:06:30.357Z,1340154390.357 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed] Running Loop=1
2012-06-20T01:06:30.357Z,1340154390.357 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize.
2012-06-20T01:06:30.357Z,1340154390.357 [triangle_control_speed_compare:SURFACECOMMS:B:B] Running Loop=1
2012-06-20T01:06:31.155Z,1340154391.155 [NAL9601](INFO): Powering up
2012-06-20T01:06:36.355Z,1340154396.355 [NAL9601](IMPORTANT): GPS fix at: 1340154396.35
2012-06-20T01:06:36.356Z,1340154396.356 [Navigation](IMPORTANT): Error 0.0 %DT at bearing 133.8 deg., over the last 1817.4 m.
2012-06-20T01:06:36.356Z,1340154396.356 [Navigation](IMPORTANT): Distance made good 1766.9 m, at (36.8146, -121.8827).
2012-06-20T01:06:36.356Z,1340154396.356 [triangle_control_speed_compare:SURFACECOMMS:B:B] Stopped
2012-06-20T01:06:36.356Z,1340154396.356 [triangle_control_speed_compare:SURFACECOMMS:B:C] Running Loop=1
2012-06-20T01:06:39.155Z,1340154399.155 [NAL9601](INFO): Sent 332 bytes from file Logs/20120619T181447/shore0011.lzma
2012-06-20T01:06:39.155Z,1340154399.155 [NAL9601](INFO): Packets left to send: 1
2012-06-20T01:06:39.155Z,1340154399.155 [NAL9601](INFO): Stored copy of sent data in Logs/20120619T181447/shore0011.lzma.parts/0001.sbd
2012-06-20T01:06:40.355Z,1340154400.355 [NAL9601](INFO): Sent 44 bytes from file Logs/20120619T181447/shore0011.lzma
2012-06-20T01:06:40.355Z,1340154400.355 [NAL9601](INFO): Packets left to send: 0
2012-06-20T01:06:40.355Z,1340154400.355 [NAL9601](INFO): Stored copy of sent data in Logs/20120619T181447/shore0011.lzma.parts/0000.sbd
2012-06-20T01:06:41.157Z,1340154401.157 [triangle_control_speed_compare:SURFACECOMMS:B:C] Stopped
2012-06-20T01:06:41.157Z,1340154401.157 [triangle_control_speed_compare:SURFACECOMMS:B:D] Running Loop=1
2012-06-20T01:06:46.355Z,1340154406.355 [NAL9601](IMPORTANT): GPS fix at: 1340154406.35
2012-06-20T01:06:46.356Z,1340154406.356 [triangle_control_speed_compare:SURFACECOMMS:B:D] Stopped
2012-06-20T01:06:46.356Z,1340154406.356 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Completed triangle_control_speed_compare:SURFACECOMMS:B
2012-06-20T01:06:46.356Z,1340154406.356 [triangle_control_speed_compare:SURFACECOMMS:B] Stopped
2012-06-20T01:06:46.356Z,1340154406.356 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Aggregate::uninitialize triangle_control_speed_compare:SURFACECOMMS:B
2012-06-20T01:06:46.356Z,1340154406.356 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed] Stopped
2012-06-20T01:06:46.356Z,1340154406.356 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize.
2012-06-20T01:06:46.356Z,1340154406.356 [triangle_control_speed_compare:SURFACECOMMS](INFO): Completed triangle_control_speed_compare:SURFACECOMMS
2012-06-20T01:06:46.356Z,1340154406.356 [triangle_control_speed_compare:SURFACECOMMS] Stopped
2012-06-20T01:06:46.356Z,1340154406.356 [triangle_control_speed_compare:SURFACECOMMS](INFO): Aggregate::uninitialize triangle_control_speed_compare:SURFACECOMMS
2012-06-20T01:06:46.356Z,1340154406.356 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface] Stopped
2012-06-20T01:06:46.356Z,1340154406.356 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-20T01:06:46.756Z,1340154406.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:PHONEHOMEWPT3](INFO): Completed triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:PHONEHOMEWPT3
2012-06-20T01:06:46.756Z,1340154406.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:PHONEHOMEWPT3] Stopped
2012-06-20T01:06:46.756Z,1340154406.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:PHONEHOMEWPT3](INFO): Aggregate::uninitialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:PHONEHOMEWPT3
2012-06-20T01:06:46.756Z,1340154406.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2](INFO): Completed triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2
2012-06-20T01:06:46.756Z,1340154406.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2] Stopped
2012-06-20T01:06:46.756Z,1340154406.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2](INFO): Aggregate::uninitialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2
2012-06-20T01:06:46.756Z,1340154406.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:A.SetSpeed] Stopped
2012-06-20T01:06:46.756Z,1340154406.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:A.SetSpeed](DEBUG): Uninitialize.
2012-06-20T01:06:46.756Z,1340154406.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:B.Pitch] Stopped
2012-06-20T01:06:46.756Z,1340154406.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3] Running Loop=1
2012-06-20T01:06:46.756Z,1340154406.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3](INFO): Aggregate::initialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3
2012-06-20T01:06:46.756Z,1340154406.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:A.SetSpeed] Running Loop=1
2012-06-20T01:06:46.756Z,1340154406.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:A.SetSpeed](DEBUG): Initialize.
2012-06-20T01:06:46.756Z,1340154406.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:B.Pitch] Running Loop=1
2012-06-20T01:06:46.756Z,1340154406.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:B.Pitch](DEBUG): Initialize.
2012-06-20T01:06:46.756Z,1340154406.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:Wpt1.Waypoint] Running Loop=1
2012-06-20T01:06:46.756Z,1340154406.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2012-06-20T01:06:52.355Z,1340154412.355 [NAL9601](INFO): Powering down
2012-06-20T01:41:05.156Z,1340156465.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:Wpt1.Waypoint](INFO): Reached Waypoint: 36.815000,-121.869000
2012-06-20T01:41:05.156Z,1340156465.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:Wpt1.Waypoint] Stopped
2012-06-20T01:41:05.156Z,1340156465.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2012-06-20T01:41:05.156Z,1340156465.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:PHONEHOMEWPT1] Running Loop=1
2012-06-20T01:41:05.156Z,1340156465.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:PHONEHOMEWPT1](INFO): Aggregate::initialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:PHONEHOMEWPT1
2012-06-20T01:41:05.556Z,1340156465.556 [triangle_control_speed_compare:SURFACECOMMS] Running Loop=1
2012-06-20T01:41:05.556Z,1340156465.556 [triangle_control_speed_compare:SURFACECOMMS](INFO): Aggregate::initialize triangle_control_speed_compare:SURFACECOMMS
2012-06-20T01:41:05.556Z,1340156465.556 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-06-20T01:41:05.556Z,1340156465.556 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-20T01:46:23.556Z,1340156783.556 [triangle_control_speed_compare:SURFACECOMMS:B] Running Loop=1
2012-06-20T01:46:23.556Z,1340156783.556 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Aggregate::initialize triangle_control_speed_compare:SURFACECOMMS:B
2012-06-20T01:46:23.556Z,1340156783.556 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed] Running Loop=1
2012-06-20T01:46:23.556Z,1340156783.556 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize.
2012-06-20T01:46:23.556Z,1340156783.556 [triangle_control_speed_compare:SURFACECOMMS:B:B] Running Loop=1
2012-06-20T01:46:24.355Z,1340156784.355 [NAL9601](INFO): Powering up
2012-06-20T01:46:29.555Z,1340156789.555 [NAL9601](IMPORTANT): GPS fix at: 1340156789.55
2012-06-20T01:46:29.557Z,1340156789.557 [Navigation](IMPORTANT): Error 0.0 %DT at bearing -41.1 deg., over the last 1383.8 m.
2012-06-20T01:46:29.557Z,1340156789.557 [Navigation](IMPORTANT): Distance made good 1370.9 m, at (36.8151, -121.8673).
2012-06-20T01:46:29.557Z,1340156789.557 [triangle_control_speed_compare:SURFACECOMMS:B:B] Stopped
2012-06-20T01:46:29.557Z,1340156789.557 [triangle_control_speed_compare:SURFACECOMMS:B:C] Running Loop=1
2012-06-20T01:46:32.355Z,1340156792.355 [NAL9601](INFO): Sent 332 bytes from file Logs/20120619T181447/shore0012.lzma
2012-06-20T01:46:32.355Z,1340156792.355 [NAL9601](INFO): Packets left to send: 1
2012-06-20T01:46:32.355Z,1340156792.355 [NAL9601](INFO): Stored copy of sent data in Logs/20120619T181447/shore0012.lzma.parts/0001.sbd
2012-06-20T01:46:33.555Z,1340156793.555 [NAL9601](INFO): Sent 17 bytes from file Logs/20120619T181447/shore0012.lzma
2012-06-20T01:46:33.555Z,1340156793.555 [NAL9601](INFO): Packets left to send: 0
2012-06-20T01:46:33.555Z,1340156793.555 [NAL9601](INFO): Stored copy of sent data in Logs/20120619T181447/shore0012.lzma.parts/0000.sbd
2012-06-20T01:46:34.356Z,1340156794.356 [triangle_control_speed_compare:SURFACECOMMS:B:C] Stopped
2012-06-20T01:46:34.356Z,1340156794.356 [triangle_control_speed_compare:SURFACECOMMS:B:D] Running Loop=1
2012-06-20T01:46:39.555Z,1340156799.555 [NAL9601](IMPORTANT): GPS fix at: 1340156799.55
2012-06-20T01:46:39.557Z,1340156799.557 [triangle_control_speed_compare:SURFACECOMMS:B:D] Stopped
2012-06-20T01:46:39.557Z,1340156799.557 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Completed triangle_control_speed_compare:SURFACECOMMS:B
2012-06-20T01:46:39.557Z,1340156799.557 [triangle_control_speed_compare:SURFACECOMMS:B] Stopped
2012-06-20T01:46:39.557Z,1340156799.557 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Aggregate::uninitialize triangle_control_speed_compare:SURFACECOMMS:B
2012-06-20T01:46:39.557Z,1340156799.557 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed] Stopped
2012-06-20T01:46:39.557Z,1340156799.557 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize.
2012-06-20T01:46:39.557Z,1340156799.557 [triangle_control_speed_compare:SURFACECOMMS](INFO): Completed triangle_control_speed_compare:SURFACECOMMS
2012-06-20T01:46:39.557Z,1340156799.557 [triangle_control_speed_compare:SURFACECOMMS] Stopped
2012-06-20T01:46:39.557Z,1340156799.557 [triangle_control_speed_compare:SURFACECOMMS](INFO): Aggregate::uninitialize triangle_control_speed_compare:SURFACECOMMS
2012-06-20T01:46:39.557Z,1340156799.557 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface] Stopped
2012-06-20T01:46:39.557Z,1340156799.557 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-20T01:46:39.959Z,1340156799.959 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:PHONEHOMEWPT1](INFO): Completed triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:PHONEHOMEWPT1
2012-06-20T01:46:39.959Z,1340156799.959 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:PHONEHOMEWPT1] Stopped
2012-06-20T01:46:39.959Z,1340156799.959 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:PHONEHOMEWPT1](INFO): Aggregate::uninitialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:PHONEHOMEWPT1
2012-06-20T01:46:39.959Z,1340156799.959 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3](INFO): Completed triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3
2012-06-20T01:46:39.959Z,1340156799.959 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3] Stopped
2012-06-20T01:46:39.959Z,1340156799.959 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3](INFO): Aggregate::uninitialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3
2012-06-20T01:46:39.959Z,1340156799.959 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:A.SetSpeed] Stopped
2012-06-20T01:46:39.959Z,1340156799.959 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:A.SetSpeed](DEBUG): Uninitialize.
2012-06-20T01:46:39.959Z,1340156799.959 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:B.Pitch] Stopped
2012-06-20T01:46:39.959Z,1340156799.959 [triangle_control_speed_compare:ThreeLaps:TestDrive](INFO): Completed triangle_control_speed_compare:ThreeLaps:TestDrive
2012-06-20T01:46:39.959Z,1340156799.959 [triangle_control_speed_compare:ThreeLaps:TestDrive] Stopped
2012-06-20T01:46:39.959Z,1340156799.959 [triangle_control_speed_compare:ThreeLaps:TestDrive](INFO): Aggregate::uninitialize triangle_control_speed_compare:ThreeLaps:TestDrive
2012-06-20T01:46:40.356Z,1340156800.356 [triangle_control_speed_compare:ThreeLaps:Lap3:LAP3](INFO): Completed triangle_control_speed_compare:ThreeLaps:Lap3:LAP3
2012-06-20T01:46:40.356Z,1340156800.356 [triangle_control_speed_compare:ThreeLaps:Lap3:LAP3] Stopped
2012-06-20T01:46:40.356Z,1340156800.356 [triangle_control_speed_compare:ThreeLaps:Lap3:LAP3](INFO): Aggregate::uninitialize triangle_control_speed_compare:ThreeLaps:Lap3:LAP3
2012-06-20T01:46:40.356Z,1340156800.356 [triangle_control_speed_compare:ThreeLaps:Lap3](INFO): Completed triangle_control_speed_compare:ThreeLaps:Lap3
2012-06-20T01:46:40.356Z,1340156800.356 [triangle_control_speed_compare:ThreeLaps:Lap3] Stopped
2012-06-20T01:46:40.356Z,1340156800.356 [triangle_control_speed_compare:ThreeLaps:Lap3](INFO): Aggregate::uninitialize triangle_control_speed_compare:ThreeLaps:Lap3
2012-06-20T01:46:40.356Z,1340156800.356 [triangle_control_speed_compare:ThreeLaps:Lap3:B] Stopped
2012-06-20T01:46:40.356Z,1340156800.356 [triangle_control_speed_compare:ThreeLaps:Lap4] Running Loop=1
2012-06-20T01:46:40.356Z,1340156800.356 [triangle_control_speed_compare:ThreeLaps:Lap4](INFO): Aggregate::initialize triangle_control_speed_compare:ThreeLaps:Lap4
2012-06-20T01:46:40.356Z,1340156800.356 [triangle_control_speed_compare:ThreeLaps:Lap4:A] Running Loop=1
2012-06-20T01:46:40.756Z,1340156800.756 [triangle_control_speed_compare:ThreeLaps:Lap4:A] Stopped
2012-06-20T01:46:40.756Z,1340156800.756 [triangle_control_speed_compare:ThreeLaps:Lap4:LAP4] Running Loop=1
2012-06-20T01:46:40.756Z,1340156800.756 [triangle_control_speed_compare:ThreeLaps:Lap4:LAP4](INFO): Aggregate::initialize triangle_control_speed_compare:ThreeLaps:Lap4:LAP4
2012-06-20T01:46:41.156Z,1340156801.156 [triangle_control_speed_compare:ThreeLaps:TestDrive] Running Loop=1
2012-06-20T01:46:41.156Z,1340156801.156 [triangle_control_speed_compare:ThreeLaps:TestDrive](INFO): Aggregate::initialize triangle_control_speed_compare:ThreeLaps:TestDrive
2012-06-20T01:46:41.156Z,1340156801.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1] Running Loop=1
2012-06-20T01:46:41.156Z,1340156801.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1](INFO): Aggregate::initialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1
2012-06-20T01:46:41.156Z,1340156801.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:A.SetSpeed] Running Loop=1
2012-06-20T01:46:41.156Z,1340156801.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:A.SetSpeed](DEBUG): Initialize.
2012-06-20T01:46:41.156Z,1340156801.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:B.Pitch] Running Loop=1
2012-06-20T01:46:41.156Z,1340156801.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:B.Pitch](DEBUG): Initialize.
2012-06-20T01:46:41.156Z,1340156801.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:Wpt2.Waypoint] Running Loop=1
2012-06-20T01:46:41.156Z,1340156801.156 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:Wpt2.Waypoint](DEBUG): Initialize WaypointComponent.
2012-06-20T01:46:45.555Z,1340156805.555 [NAL9601](INFO): Powering down
2012-06-20T02:25:36.358Z,1340159136.358 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:Wpt2.Waypoint](INFO): Reached Waypoint: 36.829000,-121.886000
2012-06-20T02:25:36.358Z,1340159136.358 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:Wpt2.Waypoint] Stopped
2012-06-20T02:25:36.358Z,1340159136.358 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:Wpt2.Waypoint](DEBUG): Uninitialize WaypointComponent.
2012-06-20T02:25:36.358Z,1340159136.358 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:PHONEHOMEWPT2] Running Loop=1
2012-06-20T02:25:36.358Z,1340159136.358 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:PHONEHOMEWPT2](INFO): Aggregate::initialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:PHONEHOMEWPT2
2012-06-20T02:25:36.758Z,1340159136.758 [triangle_control_speed_compare:SURFACECOMMS] Running Loop=1
2012-06-20T02:25:36.758Z,1340159136.758 [triangle_control_speed_compare:SURFACECOMMS](INFO): Aggregate::initialize triangle_control_speed_compare:SURFACECOMMS
2012-06-20T02:25:36.758Z,1340159136.758 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-06-20T02:25:36.758Z,1340159136.758 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-20T02:30:56.356Z,1340159456.356 [triangle_control_speed_compare:SURFACECOMMS:B] Running Loop=1
2012-06-20T02:30:56.356Z,1340159456.356 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Aggregate::initialize triangle_control_speed_compare:SURFACECOMMS:B
2012-06-20T02:30:56.356Z,1340159456.356 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed] Running Loop=1
2012-06-20T02:30:56.356Z,1340159456.356 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize.
2012-06-20T02:30:56.356Z,1340159456.356 [triangle_control_speed_compare:SURFACECOMMS:B:B] Running Loop=1
2012-06-20T02:30:57.155Z,1340159457.155 [NAL9601](INFO): Powering up
2012-06-20T02:31:02.355Z,1340159462.355 [NAL9601](IMPORTANT): GPS fix at: 1340159462.35
2012-06-20T02:31:02.356Z,1340159462.356 [Navigation](IMPORTANT): Error 0.0 %DT at bearing -82.8 deg., over the last 2482.7 m.
2012-06-20T02:31:02.356Z,1340159462.356 [Navigation](IMPORTANT): Distance made good 2448.1 m, at (36.8300, -121.8875).
2012-06-20T02:31:02.356Z,1340159462.356 [triangle_control_speed_compare:SURFACECOMMS:B:B] Stopped
2012-06-20T02:31:02.356Z,1340159462.356 [triangle_control_speed_compare:SURFACECOMMS:B:C] Running Loop=1
2012-06-20T02:31:05.155Z,1340159465.155 [NAL9601](INFO): Sent 332 bytes from file Logs/20120619T181447/shore0013.lzma
2012-06-20T02:31:05.155Z,1340159465.155 [NAL9601](INFO): Packets left to send: 1
2012-06-20T02:31:05.155Z,1340159465.155 [NAL9601](INFO): Stored copy of sent data in Logs/20120619T181447/shore0013.lzma.parts/0001.sbd
2012-06-20T02:31:06.355Z,1340159466.355 [NAL9601](INFO): Sent 65 bytes from file Logs/20120619T181447/shore0013.lzma
2012-06-20T02:31:06.355Z,1340159466.355 [NAL9601](INFO): Packets left to send: 0
2012-06-20T02:31:06.355Z,1340159466.355 [NAL9601](INFO): Stored copy of sent data in Logs/20120619T181447/shore0013.lzma.parts/0000.sbd
2012-06-20T02:31:07.155Z,1340159467.155 [triangle_control_speed_compare:SURFACECOMMS:B:C] Stopped
2012-06-20T02:31:07.155Z,1340159467.155 [triangle_control_speed_compare:SURFACECOMMS:B:D] Running Loop=1
2012-06-20T02:31:12.355Z,1340159472.355 [NAL9601](IMPORTANT): GPS fix at: 1340159472.35
2012-06-20T02:31:12.356Z,1340159472.356 [triangle_control_speed_compare:SURFACECOMMS:B:D] Stopped
2012-06-20T02:31:12.356Z,1340159472.356 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Completed triangle_control_speed_compare:SURFACECOMMS:B
2012-06-20T02:31:12.356Z,1340159472.356 [triangle_control_speed_compare:SURFACECOMMS:B] Stopped
2012-06-20T02:31:12.356Z,1340159472.356 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Aggregate::uninitialize triangle_control_speed_compare:SURFACECOMMS:B
2012-06-20T02:31:12.356Z,1340159472.356 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed] Stopped
2012-06-20T02:31:12.356Z,1340159472.356 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize.
2012-06-20T02:31:12.356Z,1340159472.356 [triangle_control_speed_compare:SURFACECOMMS](INFO): Completed triangle_control_speed_compare:SURFACECOMMS
2012-06-20T02:31:12.356Z,1340159472.356 [triangle_control_speed_compare:SURFACECOMMS] Stopped
2012-06-20T02:31:12.356Z,1340159472.356 [triangle_control_speed_compare:SURFACECOMMS](INFO): Aggregate::uninitialize triangle_control_speed_compare:SURFACECOMMS
2012-06-20T02:31:12.356Z,1340159472.356 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface] Stopped
2012-06-20T02:31:12.356Z,1340159472.356 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-20T02:31:12.756Z,1340159472.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:PHONEHOMEWPT2](INFO): Completed triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:PHONEHOMEWPT2
2012-06-20T02:31:12.756Z,1340159472.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:PHONEHOMEWPT2] Stopped
2012-06-20T02:31:12.756Z,1340159472.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:PHONEHOMEWPT2](INFO): Aggregate::uninitialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:PHONEHOMEWPT2
2012-06-20T02:31:12.756Z,1340159472.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1](INFO): Completed triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1
2012-06-20T02:31:12.756Z,1340159472.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1] Stopped
2012-06-20T02:31:12.756Z,1340159472.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1](INFO): Aggregate::uninitialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1
2012-06-20T02:31:12.756Z,1340159472.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:A.SetSpeed] Stopped
2012-06-20T02:31:12.756Z,1340159472.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:A.SetSpeed](DEBUG): Uninitialize.
2012-06-20T02:31:12.756Z,1340159472.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed1:B.Pitch] Stopped
2012-06-20T02:31:12.756Z,1340159472.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2] Running Loop=1
2012-06-20T02:31:12.756Z,1340159472.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2](INFO): Aggregate::initialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2
2012-06-20T02:31:12.756Z,1340159472.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:A.SetSpeed] Running Loop=1
2012-06-20T02:31:12.756Z,1340159472.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:A.SetSpeed](DEBUG): Initialize.
2012-06-20T02:31:12.756Z,1340159472.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:B.Pitch] Running Loop=1
2012-06-20T02:31:12.756Z,1340159472.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:B.Pitch](DEBUG): Initialize.
2012-06-20T02:31:12.756Z,1340159472.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:Wpt3.Waypoint] Running Loop=1
2012-06-20T02:31:12.756Z,1340159472.756 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:Wpt3.Waypoint](DEBUG): Initialize WaypointComponent.
2012-06-20T02:31:18.355Z,1340159478.355 [NAL9601](INFO): Powering down
2012-06-20T03:07:59.556Z,1340161679.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:Wpt3.Waypoint](INFO): Reached Waypoint: 36.816000,-121.883000
2012-06-20T03:07:59.556Z,1340161679.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:Wpt3.Waypoint] Stopped
2012-06-20T03:07:59.556Z,1340161679.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:Wpt3.Waypoint](DEBUG): Uninitialize WaypointComponent.
2012-06-20T03:07:59.556Z,1340161679.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:PHONEHOMEWPT3] Running Loop=1
2012-06-20T03:07:59.556Z,1340161679.556 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:PHONEHOMEWPT3](INFO): Aggregate::initialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:PHONEHOMEWPT3
2012-06-20T03:07:59.959Z,1340161679.959 [triangle_control_speed_compare:SURFACECOMMS] Running Loop=1
2012-06-20T03:07:59.959Z,1340161679.959 [triangle_control_speed_compare:SURFACECOMMS](INFO): Aggregate::initialize triangle_control_speed_compare:SURFACECOMMS
2012-06-20T03:07:59.959Z,1340161679.959 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-06-20T03:07:59.959Z,1340161679.959 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-20T03:13:19.956Z,1340161999.956 [triangle_control_speed_compare:SURFACECOMMS:B] Running Loop=1
2012-06-20T03:13:19.956Z,1340161999.956 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Aggregate::initialize triangle_control_speed_compare:SURFACECOMMS:B
2012-06-20T03:13:19.956Z,1340161999.956 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed] Running Loop=1
2012-06-20T03:13:19.956Z,1340161999.956 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize.
2012-06-20T03:13:19.956Z,1340161999.956 [triangle_control_speed_compare:SURFACECOMMS:B:B] Running Loop=1
2012-06-20T03:13:20.755Z,1340162000.755 [NAL9601](INFO): Powering up
2012-06-20T03:13:25.955Z,1340162005.955 [NAL9601](IMPORTANT): GPS fix at: 1340162005.95
2012-06-20T03:13:25.956Z,1340162005.956 [Navigation](IMPORTANT): Error 0.0 %DT at bearing 134.0 deg., over the last 1820.9 m.
2012-06-20T03:13:25.956Z,1340162005.956 [Navigation](IMPORTANT): Distance made good 1770.0 m, at (36.8146, -121.8827).
2012-06-20T03:13:25.956Z,1340162005.956 [triangle_control_speed_compare:SURFACECOMMS:B:B] Stopped
2012-06-20T03:13:25.956Z,1340162005.956 [triangle_control_speed_compare:SURFACECOMMS:B:C] Running Loop=1
2012-06-20T03:13:28.755Z,1340162008.755 [NAL9601](INFO): Sent 332 bytes from file Logs/20120619T181447/shore0014.lzma
2012-06-20T03:13:28.755Z,1340162008.755 [NAL9601](INFO): Packets left to send: 1
2012-06-20T03:13:28.755Z,1340162008.755 [NAL9601](INFO): Stored copy of sent data in Logs/20120619T181447/shore0014.lzma.parts/0001.sbd
2012-06-20T03:13:29.955Z,1340162009.955 [NAL9601](INFO): Sent 49 bytes from file Logs/20120619T181447/shore0014.lzma
2012-06-20T03:13:29.955Z,1340162009.955 [NAL9601](INFO): Packets left to send: 0
2012-06-20T03:13:29.955Z,1340162009.955 [NAL9601](INFO): Stored copy of sent data in Logs/20120619T181447/shore0014.lzma.parts/0000.sbd
2012-06-20T03:13:30.755Z,1340162010.755 [triangle_control_speed_compare:SURFACECOMMS:B:C] Stopped
2012-06-20T03:13:30.756Z,1340162010.756 [triangle_control_speed_compare:SURFACECOMMS:B:D] Running Loop=1
2012-06-20T03:13:35.955Z,1340162015.955 [NAL9601](IMPORTANT): GPS fix at: 1340162015.95
2012-06-20T03:13:35.956Z,1340162015.956 [triangle_control_speed_compare:SURFACECOMMS:B:D] Stopped
2012-06-20T03:13:35.956Z,1340162015.956 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Completed triangle_control_speed_compare:SURFACECOMMS:B
2012-06-20T03:13:35.956Z,1340162015.956 [triangle_control_speed_compare:SURFACECOMMS:B] Stopped
2012-06-20T03:13:35.956Z,1340162015.956 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Aggregate::uninitialize triangle_control_speed_compare:SURFACECOMMS:B
2012-06-20T03:13:35.956Z,1340162015.956 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed] Stopped
2012-06-20T03:13:35.956Z,1340162015.956 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize.
2012-06-20T03:13:35.956Z,1340162015.956 [triangle_control_speed_compare:SURFACECOMMS](INFO): Completed triangle_control_speed_compare:SURFACECOMMS
2012-06-20T03:13:35.956Z,1340162015.956 [triangle_control_speed_compare:SURFACECOMMS] Stopped
2012-06-20T03:13:35.956Z,1340162015.956 [triangle_control_speed_compare:SURFACECOMMS](INFO): Aggregate::uninitialize triangle_control_speed_compare:SURFACECOMMS
2012-06-20T03:13:35.956Z,1340162015.956 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface] Stopped
2012-06-20T03:13:35.956Z,1340162015.956 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-20T03:13:36.356Z,1340162016.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:PHONEHOMEWPT3](INFO): Completed triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:PHONEHOMEWPT3
2012-06-20T03:13:36.356Z,1340162016.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:PHONEHOMEWPT3] Stopped
2012-06-20T03:13:36.356Z,1340162016.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:PHONEHOMEWPT3](INFO): Aggregate::uninitialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:PHONEHOMEWPT3
2012-06-20T03:13:36.356Z,1340162016.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2](INFO): Completed triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2
2012-06-20T03:13:36.356Z,1340162016.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2] Stopped
2012-06-20T03:13:36.356Z,1340162016.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2](INFO): Aggregate::uninitialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2
2012-06-20T03:13:36.356Z,1340162016.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:A.SetSpeed] Stopped
2012-06-20T03:13:36.356Z,1340162016.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:A.SetSpeed](DEBUG): Uninitialize.
2012-06-20T03:13:36.356Z,1340162016.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed2:B.Pitch] Stopped
2012-06-20T03:13:36.356Z,1340162016.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3] Running Loop=1
2012-06-20T03:13:36.356Z,1340162016.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3](INFO): Aggregate::initialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3
2012-06-20T03:13:36.356Z,1340162016.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:A.SetSpeed] Running Loop=1
2012-06-20T03:13:36.356Z,1340162016.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:A.SetSpeed](DEBUG): Initialize.
2012-06-20T03:13:36.356Z,1340162016.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:B.Pitch] Running Loop=1
2012-06-20T03:13:36.356Z,1340162016.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:B.Pitch](DEBUG): Initialize.
2012-06-20T03:13:36.356Z,1340162016.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:Wpt1.Waypoint] Running Loop=1
2012-06-20T03:13:36.356Z,1340162016.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2012-06-20T03:13:41.955Z,1340162021.955 [NAL9601](INFO): Powering down
2012-06-20T03:48:00.356Z,1340164080.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:Wpt1.Waypoint](INFO): Reached Waypoint: 36.815000,-121.869000
2012-06-20T03:48:00.356Z,1340164080.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:Wpt1.Waypoint] Stopped
2012-06-20T03:48:00.356Z,1340164080.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2012-06-20T03:48:00.356Z,1340164080.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:PHONEHOMEWPT1] Running Loop=1
2012-06-20T03:48:00.356Z,1340164080.356 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:PHONEHOMEWPT1](INFO): Aggregate::initialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:PHONEHOMEWPT1
2012-06-20T03:48:00.757Z,1340164080.757 [triangle_control_speed_compare:SURFACECOMMS] Running Loop=1
2012-06-20T03:48:00.757Z,1340164080.757 [triangle_control_speed_compare:SURFACECOMMS](INFO): Aggregate::initialize triangle_control_speed_compare:SURFACECOMMS
2012-06-20T03:48:00.757Z,1340164080.757 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-06-20T03:48:00.757Z,1340164080.757 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-20T03:53:21.556Z,1340164401.556 [triangle_control_speed_compare:SURFACECOMMS:B] Running Loop=1
2012-06-20T03:53:21.556Z,1340164401.556 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Aggregate::initialize triangle_control_speed_compare:SURFACECOMMS:B
2012-06-20T03:53:21.556Z,1340164401.556 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed] Running Loop=1
2012-06-20T03:53:21.556Z,1340164401.556 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize.
2012-06-20T03:53:21.556Z,1340164401.556 [triangle_control_speed_compare:SURFACECOMMS:B:B] Running Loop=1
2012-06-20T03:53:22.355Z,1340164402.355 [NAL9601](INFO): Powering up
2012-06-20T03:53:27.555Z,1340164407.555 [NAL9601](IMPORTANT): GPS fix at: 1340164407.55
2012-06-20T03:53:27.559Z,1340164407.559 [Navigation](IMPORTANT): Error 0.0 %DT at bearing -39.0 deg., over the last 1389.1 m.
2012-06-20T03:53:27.559Z,1340164407.559 [Navigation](IMPORTANT): Distance made good 1375.4 m, at (36.8151, -121.8673).
2012-06-20T03:53:27.559Z,1340164407.559 [triangle_control_speed_compare:SURFACECOMMS:B:B] Stopped
2012-06-20T03:53:27.559Z,1340164407.559 [triangle_control_speed_compare:SURFACECOMMS:B:C] Running Loop=1
2012-06-20T03:53:30.355Z,1340164410.355 [NAL9601](INFO): Sent 332 bytes from file Logs/20120619T181447/shore0015.lzma
2012-06-20T03:53:30.355Z,1340164410.355 [NAL9601](INFO): Packets left to send: 1
2012-06-20T03:53:30.355Z,1340164410.355 [NAL9601](INFO): Stored copy of sent data in Logs/20120619T181447/shore0015.lzma.parts/0001.sbd
2012-06-20T03:53:31.555Z,1340164411.555 [NAL9601](INFO): Sent 18 bytes from file Logs/20120619T181447/shore0015.lzma
2012-06-20T03:53:31.555Z,1340164411.555 [NAL9601](INFO): Packets left to send: 0
2012-06-20T03:53:31.555Z,1340164411.555 [NAL9601](INFO): Stored copy of sent data in Logs/20120619T181447/shore0015.lzma.parts/0000.sbd
2012-06-20T03:53:32.358Z,1340164412.358 [triangle_control_speed_compare:SURFACECOMMS:B:C] Stopped
2012-06-20T03:53:32.358Z,1340164412.358 [triangle_control_speed_compare:SURFACECOMMS:B:D] Running Loop=1
2012-06-20T03:53:37.555Z,1340164417.555 [NAL9601](IMPORTANT): GPS fix at: 1340164417.55
2012-06-20T03:53:37.556Z,1340164417.556 [triangle_control_speed_compare:SURFACECOMMS:B:D] Stopped
2012-06-20T03:53:37.556Z,1340164417.556 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Completed triangle_control_speed_compare:SURFACECOMMS:B
2012-06-20T03:53:37.556Z,1340164417.556 [triangle_control_speed_compare:SURFACECOMMS:B] Stopped
2012-06-20T03:53:37.556Z,1340164417.556 [triangle_control_speed_compare:SURFACECOMMS:B](INFO): Aggregate::uninitialize triangle_control_speed_compare:SURFACECOMMS:B
2012-06-20T03:53:37.556Z,1340164417.556 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed] Stopped
2012-06-20T03:53:37.556Z,1340164417.556 [triangle_control_speed_compare:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize.
2012-06-20T03:53:37.556Z,1340164417.556 [triangle_control_speed_compare:SURFACECOMMS](INFO): Completed triangle_control_speed_compare:SURFACECOMMS
2012-06-20T03:53:37.556Z,1340164417.556 [triangle_control_speed_compare:SURFACECOMMS] Stopped
2012-06-20T03:53:37.556Z,1340164417.556 [triangle_control_speed_compare:SURFACECOMMS](INFO): Aggregate::uninitialize triangle_control_speed_compare:SURFACECOMMS
2012-06-20T03:53:37.556Z,1340164417.556 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface] Stopped
2012-06-20T03:53:37.556Z,1340164417.556 [triangle_control_speed_compare:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-20T03:53:37.956Z,1340164417.956 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:PHONEHOMEWPT1](INFO): Completed triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:PHONEHOMEWPT1
2012-06-20T03:53:37.956Z,1340164417.956 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:PHONEHOMEWPT1] Stopped
2012-06-20T03:53:37.956Z,1340164417.956 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:PHONEHOMEWPT1](INFO): Aggregate::uninitialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:PHONEHOMEWPT1
2012-06-20T03:53:37.956Z,1340164417.956 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3](INFO): Completed triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3
2012-06-20T03:53:37.956Z,1340164417.956 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3] Stopped
2012-06-20T03:53:37.956Z,1340164417.956 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3](INFO): Aggregate::uninitialize triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3
2012-06-20T03:53:37.956Z,1340164417.956 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:A.SetSpeed] Stopped
2012-06-20T03:53:37.956Z,1340164417.956 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:A.SetSpeed](DEBUG): Uninitialize.
2012-06-20T03:53:37.956Z,1340164417.956 [triangle_control_speed_compare:ThreeLaps:TestDrive:Speed3:B.Pitch] Stopped
2012-06-20T03:53:37.956Z,1340164417.956 [triangle_control_speed_compare:ThreeLaps:TestDrive](INFO): Completed triangle_control_speed_compare:ThreeLaps:TestDrive
2012-06-20T03:53:37.956Z,1340164417.956 [triangle_control_speed_compare:ThreeLaps:TestDrive] Stopped
2012-06-20T03:53:37.956Z,1340164417.956 [triangle_control_speed_compare:ThreeLaps:TestDrive](INFO): Aggregate::uninitialize triangle_control_speed_compare:ThreeLaps:TestDrive
2012-06-20T03:53:38.356Z,1340164418.356 [triangle_control_speed_compare:ThreeLaps:Lap4:LAP4](INFO): Completed triangle_control_speed_compare:ThreeLaps:Lap4:LAP4
2012-06-20T03:53:38.356Z,1340164418.356 [triangle_control_speed_compare:ThreeLaps:Lap4:LAP4] Stopped
2012-06-20T03:53:38.356Z,1340164418.356 [triangle_control_speed_compare:ThreeLaps:Lap4:LAP4](INFO): Aggregate::uninitialize triangle_control_speed_compare:ThreeLaps:Lap4:LAP4
2012-06-20T03:53:38.356Z,1340164418.356 [triangle_control_speed_compare:ThreeLaps:Lap4](INFO): Completed triangle_control_speed_compare:ThreeLaps:Lap4
2012-06-20T03:53:38.356Z,1340164418.356 [triangle_control_speed_compare:ThreeLaps:Lap4] Stopped
2012-06-20T03:53:38.356Z,1340164418.356 [triangle_control_speed_compare:ThreeLaps:Lap4](INFO): Aggregate::uninitialize triangle_control_speed_compare:ThreeLaps:Lap4
2012-06-20T03:53:38.356Z,1340164418.356 [triangle_control_speed_compare:ThreeLaps](INFO): Completed triangle_control_speed_compare:ThreeLaps
2012-06-20T03:53:38.356Z,1340164418.356 [triangle_control_speed_compare:ThreeLaps] Stopped
2012-06-20T03:53:38.356Z,1340164418.356 [triangle_control_speed_compare:ThreeLaps](INFO): Aggregate::uninitialize triangle_control_speed_compare:ThreeLaps
2012-06-20T03:53:38.356Z,1340164418.356 [triangle_control_speed_compare:ThreeLaps:BuoyancyHold] Stopped
2012-06-20T03:53:38.356Z,1340164418.356 [triangle_control_speed_compare:ThreeLaps:BuoyancyHold](INFO): Aggregate::uninitialize triangle_control_speed_compare:ThreeLaps:BuoyancyHold
2012-06-20T03:53:38.356Z,1340164418.356 [triangle_control_speed_compare:ThreeLaps:BuoyancyHold:A.Buoyancy] Stopped
2012-06-20T03:53:38.356Z,1340164418.356 [triangle_control_speed_compare:ThreeLaps:BuoyancyHold:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2012-06-20T03:53:38.356Z,1340164418.356 [triangle_control_speed_compare:ThreeLaps:MassHold] Stopped
2012-06-20T03:53:38.356Z,1340164418.356 [triangle_control_speed_compare:ThreeLaps:MassHold](INFO): Aggregate::uninitialize triangle_control_speed_compare:ThreeLaps:MassHold
2012-06-20T03:53:38.356Z,1340164418.356 [triangle_control_speed_compare:ThreeLaps:MassHold:A.Pitch] Stopped
2012-06-20T03:53:38.356Z,1340164418.356 [triangle_control_speed_compare:ThreeLaps:C] Stopped
2012-06-20T03:53:38.356Z,1340164418.356 [triangle_control_speed_compare:ThreeLaps:D] Stopped
2012-06-20T03:53:38.356Z,1340164418.356 [triangle_control_speed_compare](INFO): Completed triangle_control_speed_compare
2012-06-20T03:53:38.356Z,1340164418.356 [triangle_control_speed_compare] Stopped
2012-06-20T03:53:38.356Z,1340164418.356 [triangle_control_speed_compare](INFO): Aggregate::uninitialize triangle_control_speed_compare
2012-06-20T03:53:38.356Z,1340164418.356 [triangle_control_speed_compare:A.AltitudeEnvelope] Stopped
2012-06-20T03:53:38.356Z,1340164418.356 [triangle_control_speed_compare:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2012-06-20T03:53:38.356Z,1340164418.356 [triangle_control_speed_compare:B.DepthEnvelope] Stopped
2012-06-20T03:53:38.356Z,1340164418.356 [triangle_control_speed_compare:B.DepthEnvelope](DEBUG): Uninitialize.
2012-06-20T03:53:38.356Z,1340164418.356 [triangle_control_speed_compare:C.OffshoreEnvelope] Stopped
2012-06-20T03:53:38.356Z,1340164418.356 [triangle_control_speed_compare:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2012-06-20T03:53:38.356Z,1340164418.356 [triangle_control_speed_compare:D] Stopped
2012-06-20T03:53:38.419Z,1340164418.419 [CommandLine](IMPORTANT): got command quit
2012-06-20T03:53:38.606Z,1340164418.606 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2012-06-20T03:53:38.606Z,1340164418.606 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2012-06-20T03:53:38.756Z,1340164418.756 [MissionManager](IMPORTANT): Started mission Default
2012-06-20T03:53:38.756Z,1340164418.756 [Default] Running Loop=1
2012-06-20T03:53:38.756Z,1340164418.756 [Default](INFO): Aggregate::initialize Default
2012-06-20T03:53:38.756Z,1340164418.756 [Default:D.SetSpeed] Running Loop=1
2012-06-20T03:53:38.756Z,1340164418.756 [Default:D.SetSpeed](DEBUG): Initialize.
2012-06-20T03:53:38.756Z,1340164418.756 [Default:E.GoToSurface] Running Loop=1
2012-06-20T03:53:38.756Z,1340164418.756 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-20T03:53:38.756Z,1340164418.756 [Default:Iridium] Running Loop=1
2012-06-20T03:53:38.756Z,1340164418.756 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-06-20T03:53:38.756Z,1340164418.756 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-06-20T03:53:38.756Z,1340164418.756 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-06-20T03:53:38.756Z,1340164418.756 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-06-20T03:53:38.756Z,1340164418.756 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-20T03:53:38.756Z,1340164418.756 [Default:E.GoToSurface] Running Loop=1
2012-06-20T03:53:38.756Z,1340164418.756 [Default:D.SetSpeed] Running Loop=1
2012-06-20T03:53:38.756Z,1340164418.756 [Default:Iridium:B.GoToSurface] Stopped
2012-06-20T03:53:38.756Z,1340164418.756 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-20T03:53:38.756Z,1340164418.756 [Default:Iridium:Read_Iridium] Running Loop=1
2012-06-20T03:53:38.756Z,1340164418.756 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-06-20T03:53:38.756Z,1340164418.756 [Default:GPS] Running Loop=1
2012-06-20T03:53:38.756Z,1340164418.756 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-06-20T03:53:38.756Z,1340164418.756 [Default:GPS:A.SetSpeed] Running Loop=1
2012-06-20T03:53:38.756Z,1340164418.756 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-06-20T03:53:38.756Z,1340164418.756 [Default:GPS:B.GoToSurface] Running Loop=1
2012-06-20T03:53:38.756Z,1340164418.756 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-20T03:53:38.756Z,1340164418.756 [Default:GPS:B.GoToSurface] Stopped
2012-06-20T03:53:38.756Z,1340164418.756 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-20T03:53:38.756Z,1340164418.756 [Default:GPS:Read_GPS] Running Loop=1
2012-06-20T03:53:38.756Z,1340164418.756 [Default:GPS:A.SetSpeed] Running Loop=1
2012-06-20T03:53:38.925Z,1340164418.925 [ComponentRegistry](INFO): Shutting down Turbulence_NPS ThreadHandler
2012-06-20T03:53:38.958Z,1340164418.958 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2012-06-20T03:53:39.024Z,1340164419.024 [ComponentRegistry](INFO): Shutting down AsyncPiEstimator ThreadHandler
2012-06-20T03:53:39.156Z,1340164419.156 [Default:Iridium:Read_Iridium](DEBUG): Initialize ReadDataComponent to sense platform_communications
2012-06-20T03:53:39.156Z,1340164419.156 [Default:GPS:Read_GPS](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-06-20T03:53:39.280Z,1340164419.280 [AsyncPiEstimator](DEBUG): Uninitialize AsyncPiEstimator.
2012-06-20T03:53:39.280Z,1340164419.280 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2012-06-20T03:53:39.280Z,1340164419.280 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2012-06-20T03:53:39.556Z,1340164419.556 [controlThread](DEBUG): Uninitializing ControlThread
2012-06-20T03:53:39.556Z,1340164419.556 [NAL9601](INFO): Powering down
2012-06-20T03:53:39.556Z,1340164419.556 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2012-06-20T03:53:39.556Z,1340164419.556 [NavChart](DEBUG): Uninitialize NavChart Derivation.
2012-06-20T03:53:39.556Z,1340164419.556 [Default] Stopped
2012-06-20T03:53:39.556Z,1340164419.556 [Default](INFO): Aggregate::uninitialize Default
2012-06-20T03:53:39.556Z,1340164419.556 [Default:GPS] Stopped
2012-06-20T03:53:39.556Z,1340164419.556 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-06-20T03:53:39.556Z,1340164419.556 [Default:GPS:A.SetSpeed] Stopped
2012-06-20T03:53:39.556Z,1340164419.556 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-06-20T03:53:39.556Z,1340164419.556 [Default:GPS:Read_GPS] Stopped
2012-06-20T03:53:39.556Z,1340164419.556 [Default:Iridium] Stopped
2012-06-20T03:53:39.556Z,1340164419.556 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-06-20T03:53:39.556Z,1340164419.556 [Default:Iridium:A.SetSpeed] Stopped
2012-06-20T03:53:39.556Z,1340164419.556 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-06-20T03:53:39.556Z,1340164419.556 [Default:Iridium:Read_Iridium] Stopped
2012-06-20T03:53:39.556Z,1340164419.556 [Default:D.SetSpeed] Stopped
2012-06-20T03:53:39.556Z,1340164419.556 [Default:D.SetSpeed](DEBUG): Uninitialize.
2012-06-20T03:53:39.556Z,1340164419.556 [Default:E.GoToSurface] Stopped
2012-06-20T03:53:39.556Z,1340164419.556 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-20T03:53:39.556Z,1340164419.556 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2012-06-20T03:53:39.556Z,1340164419.556 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2012-06-20T03:53:39.556Z,1340164419.556 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2012-06-20T03:53:39.556Z,1340164419.556 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2012-06-20T03:53:39.556Z,1340164419.556 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2012-06-20T03:53:39.556Z,1340164419.556 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2012-06-20T03:53:39.556Z,1340164419.556 [MassServo](DEBUG): Uninitialize Mass Servo.
2012-06-20T03:53:39.556Z,1340164419.556 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2012-06-20T03:53:39.556Z,1340164419.556 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2012-06-20T03:53:39.556Z,1340164419.556 [SBIT](DEBUG): Uninitialize SBIT Component.
2012-06-20T03:53:39.556Z,1340164419.556 [IBIT](DEBUG): Uninitialize IBIT Component.
2012-06-20T03:53:39.556Z,1340164419.556 [CBIT](DEBUG): Uninitialize CBIT Component.