2012-06-22T08:05:23.264Z,1340352323.264 [NAL9601](INFO): SBD MO Status=1, MOMSN=10667, MT Status=0, MTMSN=0
2012-06-22T08:05:23.427Z,1340352323.427 [NAL9601](INFO): Sent 145 bytes from file Logs/20120622T045247/shore0011.lzma
2012-06-22T08:05:23.427Z,1340352323.427 [NAL9601](INFO): Packets left to send: 0
2012-06-22T08:05:23.429Z,1340352323.429 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T045247/shore0011.lzma.parts/0000.sbd
2012-06-22T08:05:32.121Z,1340352332.121 [NAL9601](INFO): SBD MO Status=0, MOMSN=10668, MT Status=0, MTMSN=0
2012-06-22T08:05:51.978Z,1340352351.978 [NAL9601](IMPORTANT): SBD MO Status=1, MOMSN=10669, MT Status=1, MTMSN=2783
2012-06-22T08:05:52.183Z,1340352352.183 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0000.lzma
2012-06-22T08:05:52.183Z,1340352352.183 [NAL9601](INFO): Packets left to send: 1
2012-06-22T08:05:52.185Z,1340352352.185 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0000.lzma.parts/0001.sbd
2012-06-22T08:05:52.688Z,1340352352.688 [NAL9601](INFO): Received command:load Engineering/triangle_control_speed_onelap.xml;set triangle_control_speed_onelap.elevDeadband 1.0 degree;set triangle_control_speed_onelap.StopDepthExcursion 15 meter;run 
2012-06-22T08:05:52.737Z,1340352352.737 [CommandLine](IMPORTANT): got command load ./Missions/Engineering/triangle_control_speed_onelap.xml
2012-06-22T08:05:52.737Z,1340352352.737 [MissionManager](INFO): Loading Mission: ./Missions/Engineering/triangle_control_speed_onelap.xml
2012-06-22T08:05:52.845Z,1340352352.845 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.Wpt1Lat = 36.815000 arcdeg
2012-06-22T08:05:52.848Z,1340352352.848 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.Wpt1Lon = -121.869000 arcdeg
2012-06-22T08:05:52.850Z,1340352352.850 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.Speed1 = 1.000000 m/s
2012-06-22T08:05:52.852Z,1340352352.852 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.Wpt2Lat = 36.829000 arcdeg
2012-06-22T08:05:52.854Z,1340352352.854 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.Wpt2Lon = -121.886000 arcdeg
2012-06-22T08:05:52.856Z,1340352352.856 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.Speed2 = 0.750000 m/s
2012-06-22T08:05:52.859Z,1340352352.859 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.Wpt3Lat = 36.816000 arcdeg
2012-06-22T08:05:52.861Z,1340352352.861 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.Wpt3Lon = -121.883000 arcdeg
2012-06-22T08:05:52.863Z,1340352352.863 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.Speed3 = 0.600000 m/s
2012-06-22T08:05:52.865Z,1340352352.865 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.MaxDepth = 37.000000 m
2012-06-22T08:05:52.867Z,1340352352.867 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.MinAltitude = 7.000000 m
2012-06-22T08:05:52.869Z,1340352352.869 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.DepthShallow = 15.000000 m
2012-06-22T08:05:52.872Z,1340352352.872 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.DepthDeep = 32.000000 m
2012-06-22T08:05:52.874Z,1340352352.874 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.NeedCommsTime = 50.000000 min
2012-06-22T08:05:52.876Z,1340352352.876 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.TimeoutDuration = 250.000000 min
2012-06-22T08:05:52.878Z,1340352352.878 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.MassHold = 1 bool
2012-06-22T08:05:52.881Z,1340352352.881 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.MassDefault = -0.007500 n/a
2012-06-22T08:05:52.884Z,1340352352.884 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.massDeadband = 0.000000 mm
2012-06-22T08:05:52.886Z,1340352352.886 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.BuoyancyHold = 1 bool
2012-06-22T08:05:52.888Z,1340352352.888 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.BuoyancyNeutral = 0.000505 n/a
2012-06-22T08:05:52.890Z,1340352352.890 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.ElevatorHold = 0 bool
2012-06-22T08:05:52.892Z,1340352352.892 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.ElevatorDefault = 0.000000 arcdeg
2012-06-22T08:05:52.894Z,1340352352.894 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.elevDeadband = 0.000000 arcdeg
2012-06-22T08:05:52.896Z,1340352352.896 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.ElevIntInDepthMode = 0 bool
2012-06-22T08:05:52.899Z,1340352352.899 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.StopDepthExcursion = 5.000000 n/a
2012-06-22T08:05:52.901Z,1340352352.901 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.kdPitchMass = 0.000000 n/a
2012-06-22T08:05:52.903Z,1340352352.903 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.kiDepthOff = 0.100000 n/a
2012-06-22T08:05:52.906Z,1340352352.906 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.kiPitchMass = 0.001500 n/a
2012-06-22T08:05:52.908Z,1340352352.908 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.kpPitchMass = 0.010000 n/a
2012-06-22T08:05:52.911Z,1340352352.911 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.maxDiveAccel = 0.001000 n/a
2012-06-22T08:05:52.912Z,1340352352.912 [triangle_control_speed_onelap:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2012-06-22T08:05:52.917Z,1340352352.917 [triangle_control_speed_onelap:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2012-06-22T08:05:52.922Z,1340352352.922 [triangle_control_speed_onelap:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2012-06-22T08:05:52.928Z,1340352352.928 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Construct GoToSurface.
2012-06-22T08:05:52.931Z,1340352352.931 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Construct.
2012-06-22T08:05:52.940Z,1340352352.940 [triangle_control_speed_onelap:WaypointOne:A.Pitch](DEBUG): Construct.
2012-06-22T08:05:52.944Z,1340352352.944 [triangle_control_speed_onelap:WaypointOne:B.SetSpeed](DEBUG): Construct.
2012-06-22T08:05:52.949Z,1340352352.949 [triangle_control_speed_onelap:WaypointOne:WaypointW1.Waypoint](DEBUG): Construct Waypoint.
2012-06-22T08:05:52.955Z,1340352352.955 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold:A.Buoyancy](DEBUG): Construct Buoyancy.
2012-06-22T08:05:52.958Z,1340352352.958 [triangle_control_speed_onelap:ThreeLegs:MassHold:A.Pitch](DEBUG): Construct.
2012-06-22T08:05:52.962Z,1340352352.962 [triangle_control_speed_onelap:ThreeLegs:ElevatorHold:A.Pitch](DEBUG): Construct.
2012-06-22T08:05:52.977Z,1340352352.977 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:A.SetSpeed](DEBUG): Construct.
2012-06-22T08:05:52.979Z,1340352352.980 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:B.Pitch](DEBUG): Construct.
2012-06-22T08:05:52.984Z,1340352352.984 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:Wpt2.Waypoint](DEBUG): Construct Waypoint.
2012-06-22T08:05:52.989Z,1340352352.989 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:A.SetSpeed](DEBUG): Construct.
2012-06-22T08:05:52.991Z,1340352352.991 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:B.Pitch](DEBUG): Construct.
2012-06-22T08:05:52.995Z,1340352352.995 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:Wpt3.Waypoint](DEBUG): Construct Waypoint.
2012-06-22T08:05:52.000Z,1340352353.000 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:A.SetSpeed](DEBUG): Construct.
2012-06-22T08:05:53.002Z,1340352353.002 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:B.Pitch](DEBUG): Construct.
2012-06-22T08:05:53.005Z,1340352353.006 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2012-06-22T08:05:53.058Z,1340352353.058 [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_onelap">

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

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

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

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

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

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

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

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

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

    <DefineArg Name="MaxDepth"><Description>
        Maximum depth for the entire mission.
    </Description><Units:meter/><Value>37</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="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="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="TimeoutDuration"><Description>
        Maximum length of mission
    </Description><Units:minute/><Value>250</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="MassDefault"><Description>
        Static setting for mass when the mass position is held.
    </Description><Control:VerticalControl.massDefault/></DefineArg>

    <DefineArg Name="massDeadband"><Description>
        Sets the value of VerticalControl.massDeadband
    </Description><Units:millimeter/><Value>0.0</Value></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="BuoyancyNeutral"><Description>
        Buoyancy bladder position when buoyancy is held.
	Defaults to buoyancyNeutral setting in the Config/Control.cfg file.
    </Description><Control:VerticalControl.buoyancyNeutral/></DefineArg>

    <DefineArg Name="ElevatorHold"><Description>
        Set to True in order to hold the elevators at neutral position, False to
        allow them to run on their own.
    </Description><False/></DefineArg>

    <DefineArg Name="ElevatorDefault"><Description>
        Static setting for elevator if elevator position is held.
    </Description><Units:degree/><Value>0.0</Value></DefineArg>

    <DefineArg Name="elevDeadband"><Description>
        Sets the value of VerticalControl.elevDeadband
    </Description><Units:degree/><Value>0.0</Value></DefineArg>

    <DefineArg Name="ElevIntInDepthMode"><Description>
        Sets VerticalControl.useElevIntInDepthMode value
    </Description><False/></DefineArg>

    <DefineArg Name="StopDepthExcursion"><Description>
        Sets VerticalControl.stopDepthExcursion within the triangle,
        but not during the transit.
    </Description><Control:VerticalControl.stopDepthExcursion/></DefineArg>

    <DefineArg Name="kdPitchMass"><Description>
        Sets the value of VerticalControl.kdPitchMass
    </Description><Control:VerticalControl.kdPitchMass/></DefineArg>

    <DefineArg Name="kiDepthOff"><Description>
        Sets the value of VerticalControl.kiDepthOff
    </Description><Control:VerticalControl.kiDepthOff/></DefineArg>

    <DefineArg Name="kiPitchMass"><Description>
        Sets the value of VerticalControl.kiPitchMass
    </Description><Control:VerticalControl.kiPitchMass/></DefineArg>

    <DefineArg Name="kpPitchMass"><Description>
        Sets the value of VerticalControl.kpPitchMass
    </Description><Control:VerticalControl.kpPitchMass/></DefineArg>

    <DefineArg Name="maxDiveAccel"><Description>
        Sets the value of VerticalControl.maxDiveAccel
    </Description><Control:VerticalControl.maxDiveAccel/></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="ThreeLegs">

        <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>

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

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

            <Guidance:Pitch RunIn="Parallel">
              <Setting><Guidance:Pitch.elevatorAngle/><Arg Name="ElevatorDefault"/></Setting>
            </Guidance:Pitch>

        </Aggregate>

        <Assign RunIn="Parallel"><Control:VerticalControl.useElevIntInDepthMode/><Arg Name="ElevIntInDepthMode"/></Assign>

        <Assign RunIn="Parallel"><Control:VerticalControl.elevDeadband/><Arg Name="elevDeadband"/></Assign>

        <Assign RunIn="Parallel"><Control:VerticalControl.kdPitchMass/><Arg Name="kdPitchMass"/></Assign>

        <Assign RunIn="Parallel"><Control:VerticalControl.kiDepthOff/><Arg Name="kiDepthOff"/></Assign>

        <Assign RunIn="Parallel"><Control:VerticalControl.kiPitchMass/><Arg Name="kiPitchMass"/></Assign>

        <Assign RunIn="Parallel"><Control:VerticalControl.kpPitchMass/><Arg Name="kpPitchMass"/></Assign>

        <Assign RunIn="Parallel"><Control:VerticalControl.massDeadband/><Arg Name="massDeadband"/></Assign>

        <Assign RunIn="Parallel"><Control:VerticalControl.maxDiveAccel/><Arg Name="maxDiveAccel"/></Assign>

        <Assign RunIn="Parallel"><Control:VerticalControl.stopDepthExcursion/><Arg Name="StopDepthExcursion"/></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>

</Mission>


2012-06-22T08:05:53.059Z,1340352353.059 [CommandLine](IMPORTANT): Loaded ./Missions/Engineering/triangle_control_speed_onelap.xml
2012-06-22T08:05:53.291Z,1340352353.291 [CommandLine](IMPORTANT): got command set triangle_control_speed_onelap.elevDeadband 1.000000 degree
2012-06-22T08:05:53.463Z,1340352353.463 [CommandLine](IMPORTANT): got command set triangle_control_speed_onelap.StopDepthExcursion 15.000000 meter
2012-06-22T08:05:53.464Z,1340352353.464 [CommandLine](IMPORTANT): got command run 
2012-06-22T08:05:53.465Z,1340352353.465 [CommandLine](IMPORTANT): Running 
2012-06-22T08:05:53.479Z,1340352353.479 [Default] Stopped
2012-06-22T08:05:53.479Z,1340352353.479 [Default](INFO): Aggregate::uninitialize Default
2012-06-22T08:05:53.479Z,1340352353.479 [Default:Iridium] Stopped
2012-06-22T08:05:53.479Z,1340352353.479 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-06-22T08:05:53.479Z,1340352353.479 [Default:Iridium:A.SetSpeed] Stopped
2012-06-22T08:05:53.479Z,1340352353.479 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T08:05:53.479Z,1340352353.479 [Default:Iridium:Read_Iridium] Stopped
2012-06-22T08:05:53.479Z,1340352353.479 [Default:CallIridium] Stopped
2012-06-22T08:05:53.479Z,1340352353.479 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-06-22T08:05:53.479Z,1340352353.480 [Default:CallIridium:B] Stopped
2012-06-22T08:05:53.480Z,1340352353.480 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-06-22T08:05:53.480Z,1340352353.480 [Default:D.SetSpeed] Stopped
2012-06-22T08:05:53.480Z,1340352353.480 [Default:D.SetSpeed](DEBUG): Uninitialize.
2012-06-22T08:05:53.480Z,1340352353.480 [Default:E.GoToSurface] Stopped
2012-06-22T08:05:53.480Z,1340352353.480 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T08:05:53.480Z,1340352353.480 [Default:F.Wait] Stopped
2012-06-22T08:05:53.480Z,1340352353.480 [Default:F.Wait](DEBUG): Uninitialize Wait Component.
2012-06-22T08:05:53.480Z,1340352353.480 [MissionManager](IMPORTANT): Started mission triangle_control_speed_onelap
2012-06-22T08:05:53.480Z,1340352353.480 [triangle_control_speed_onelap] Running Loop=1
2012-06-22T08:05:53.481Z,1340352353.480 [triangle_control_speed_onelap](INFO): Aggregate::initialize triangle_control_speed_onelap
2012-06-22T08:05:53.481Z,1340352353.481 [triangle_control_speed_onelap:A.AltitudeEnvelope] Running Loop=1
2012-06-22T08:05:53.481Z,1340352353.481 [triangle_control_speed_onelap:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2012-06-22T08:05:53.481Z,1340352353.481 [triangle_control_speed_onelap:B.DepthEnvelope] Running Loop=1
2012-06-22T08:05:53.481Z,1340352353.481 [triangle_control_speed_onelap:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2012-06-22T08:05:53.481Z,1340352353.481 [triangle_control_speed_onelap:C.OffshoreEnvelope] Running Loop=1
2012-06-22T08:05:53.482Z,1340352353.482 [triangle_control_speed_onelap:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2012-06-22T08:05:53.482Z,1340352353.482 [triangle_control_speed_onelap:D] Running Loop=1
2012-06-22T08:05:53.482Z,1340352353.482 [triangle_control_speed_onelap:SURFACECOMMS] Running Loop=1
2012-06-22T08:05:53.482Z,1340352353.482 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS
2012-06-22T08:05:53.483Z,1340352353.483 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-06-22T08:05:53.483Z,1340352353.483 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T08:05:53.485Z,1340352353.485 [triangle_control_speed_onelap:SURFACECOMMS:B] Running Loop=1
2012-06-22T08:05:53.485Z,1340352353.485 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS:B
2012-06-22T08:05:53.485Z,1340352353.485 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Running Loop=1
2012-06-22T08:05:53.485Z,1340352353.485 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize.
2012-06-22T08:05:53.486Z,1340352353.486 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Running Loop=1
2012-06-22T08:05:53.486Z,1340352353.486 [triangle_control_speed_onelap:D] Running Loop=1
2012-06-22T08:05:53.486Z,1340352353.486 [triangle_control_speed_onelap:D](DEBUG): Initialize ReadDataComponent to sense platform_orientation
2012-06-22T08:05:53.487Z,1340352353.487 [triangle_control_speed_onelap:C.OffshoreEnvelope] Running Loop=1
2012-06-22T08:05:53.488Z,1340352353.488 [triangle_control_speed_onelap:B.DepthEnvelope] Running Loop=1
2012-06-22T08:05:53.489Z,1340352353.489 [triangle_control_speed_onelap:A.AltitudeEnvelope] Running Loop=1
2012-06-22T08:05:54.328Z,1340352354.328 [triangle_control_speed_onelap:SURFACECOMMS:B:B](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-06-22T08:05:54.329Z,1340352354.329 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Running Loop=1
2012-06-22T08:05:56.167Z,1340352356.167 [NAL9601](IMPORTANT): GPS fix at: 1340352573.00
2012-06-22T08:05:56.182Z,1340352356.182 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Stopped
2012-06-22T08:05:56.183Z,1340352356.183 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Running Loop=1
2012-06-22T08:05:56.593Z,1340352356.593 [triangle_control_speed_onelap:SURFACECOMMS:B:C](DEBUG): Initialize ReadDataComponent to sense platform_communications
2012-06-22T08:06:13.721Z,1340352373.721 [NAL9601](INFO): SBD MO Status=1, MOMSN=10670, MT Status=0, MTMSN=0
2012-06-22T08:06:13.847Z,1340352373.847 [NAL9601](INFO): Sent 185 bytes from file Logs/20120622T080505/shore0000.lzma
2012-06-22T08:06:13.847Z,1340352373.847 [NAL9601](INFO): Packets left to send: 0
2012-06-22T08:06:13.848Z,1340352373.848 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0000.lzma.parts/0000.sbd
2012-06-22T08:06:18.143Z,1340352378.143 [NAL9601](INFO): SBD MO Status=0, MOMSN=10671, MT Status=0, MTMSN=0
2012-06-22T08:06:39.627Z,1340352399.627 [NAL9601](INFO): SBD MO Status=1, MOMSN=10672, MT Status=0, MTMSN=0
2012-06-22T08:06:39.807Z,1340352399.807 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0001.lzma
2012-06-22T08:06:39.807Z,1340352399.807 [NAL9601](INFO): Packets left to send: 1
2012-06-22T08:06:39.809Z,1340352399.809 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0001.lzma.parts/0001.sbd
2012-06-22T08:06:50.644Z,1340352410.644 [NAL9601](INFO): SBD MO Status=1, MOMSN=10673, MT Status=0, MTMSN=0
2012-06-22T08:06:50.791Z,1340352410.791 [NAL9601](INFO): Sent 112 bytes from file Logs/20120622T080505/shore0001.lzma
2012-06-22T08:06:50.791Z,1340352410.791 [NAL9601](INFO): Packets left to send: 0
2012-06-22T08:06:50.792Z,1340352410.792 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0001.lzma.parts/0000.sbd
2012-06-22T08:06:55.002Z,1340352415.001 [NAL9601](INFO): SBD MO Status=2, MOMSN=10674, MT Status=2, MTMSN=0
2012-06-22T08:06:55.002Z,1340352415.002 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-06-22T08:07:04.013Z,1340352424.013 [NAL9601](INFO): SBD MO Status=0, MOMSN=10674, MT Status=0, MTMSN=0
2012-06-22T08:07:04.199Z,1340352424.199 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Stopped
2012-06-22T08:07:04.199Z,1340352424.199 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Running Loop=1
2012-06-22T08:07:04.447Z,1340352424.447 [triangle_control_speed_onelap:SURFACECOMMS:B:D](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-06-22T08:07:06.419Z,1340352426.419 [NAL9601](IMPORTANT): GPS fix at: 1340352643.00
2012-06-22T08:07:06.435Z,1340352426.434 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Stopped
2012-06-22T08:07:06.435Z,1340352426.435 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS:B
2012-06-22T08:07:06.435Z,1340352426.435 [triangle_control_speed_onelap:SURFACECOMMS:B] Stopped
2012-06-22T08:07:06.435Z,1340352426.435 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS:B
2012-06-22T08:07:06.435Z,1340352426.435 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Stopped
2012-06-22T08:07:06.435Z,1340352426.435 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T08:07:06.436Z,1340352426.436 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS
2012-06-22T08:07:06.436Z,1340352426.436 [triangle_control_speed_onelap:SURFACECOMMS] Stopped
2012-06-22T08:07:06.436Z,1340352426.436 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS
2012-06-22T08:07:06.437Z,1340352426.437 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Stopped
2012-06-22T08:07:06.437Z,1340352426.437 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T08:07:06.437Z,1340352426.437 [triangle_control_speed_onelap:WaypointOne] Running Loop=1
2012-06-22T08:07:06.437Z,1340352426.437 [triangle_control_speed_onelap:WaypointOne](INFO): Aggregate::initialize triangle_control_speed_onelap:WaypointOne
2012-06-22T08:07:06.437Z,1340352426.437 [triangle_control_speed_onelap:WaypointOne:A.Pitch] Running Loop=1
2012-06-22T08:07:06.437Z,1340352426.437 [triangle_control_speed_onelap:WaypointOne:A.Pitch](DEBUG): Initialize.
2012-06-22T08:07:06.437Z,1340352426.437 [triangle_control_speed_onelap:WaypointOne:B.SetSpeed] Running Loop=1
2012-06-22T08:07:06.437Z,1340352426.437 [triangle_control_speed_onelap:WaypointOne:B.SetSpeed](DEBUG): Initialize.
2012-06-22T08:07:06.438Z,1340352426.438 [triangle_control_speed_onelap:WaypointOne:WaypointW1.Waypoint] Running Loop=1
2012-06-22T08:07:06.438Z,1340352426.438 [triangle_control_speed_onelap:WaypointOne:WaypointW1.Waypoint](DEBUG): Initialize WaypointComponent.
2012-06-22T08:07:06.832Z,1340352426.832 [triangle_control_speed_onelap:WaypointOne:B.SetSpeed] Running Loop=1
2012-06-22T08:07:06.833Z,1340352426.833 [triangle_control_speed_onelap:WaypointOne:A.Pitch] Running Loop=1
2012-06-22T08:07:12.811Z,1340352432.811 [NAL9601](INFO): Powering down
2012-06-22T08:07:21.221Z,1340352441.221 [Radio_Freewave](INFO): Powering down
2012-06-22T08:07:26.865Z,1340352446.865 [Radio_Freewave](INFO): Powering up
2012-06-22T08:08:10.575Z,1340352490.575 [Radio_Freewave](INFO): Powering down
2012-06-22T08:14:25.094Z,1340352865.095 [triangle_control_speed_onelap:WaypointOne:WaypointW1.Waypoint](INFO): Reached Waypoint: 36.815000,-121.869000
2012-06-22T08:14:25.095Z,1340352865.095 [triangle_control_speed_onelap:WaypointOne:WaypointW1.Waypoint] Stopped
2012-06-22T08:14:25.095Z,1340352865.095 [triangle_control_speed_onelap:WaypointOne:WaypointW1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2012-06-22T08:14:25.095Z,1340352865.095 [triangle_control_speed_onelap:WaypointOne:PHONEHOMEWPT1] Running Loop=1
2012-06-22T08:14:25.095Z,1340352865.095 [triangle_control_speed_onelap:WaypointOne:PHONEHOMEWPT1](INFO): Aggregate::initialize triangle_control_speed_onelap:WaypointOne:PHONEHOMEWPT1
2012-06-22T08:14:25.470Z,1340352865.470 [triangle_control_speed_onelap:SURFACECOMMS] Running Loop=1
2012-06-22T08:14:25.470Z,1340352865.470 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS
2012-06-22T08:14:25.470Z,1340352865.470 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-06-22T08:14:25.470Z,1340352865.470 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T08:17:46.881Z,1340353066.881 [Radio_Freewave](INFO): Powering up
2012-06-22T08:17:46.892Z,1340353066.892 [triangle_control_speed_onelap:SURFACECOMMS:B] Running Loop=1
2012-06-22T08:17:46.892Z,1340353066.892 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS:B
2012-06-22T08:17:46.893Z,1340353066.893 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Running Loop=1
2012-06-22T08:17:46.893Z,1340353066.893 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize.
2012-06-22T08:17:46.893Z,1340353066.893 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Running Loop=1
2012-06-22T08:17:47.795Z,1340353067.795 [NAL9601](INFO): Powering up
2012-06-22T08:18:53.319Z,1340353133.319 [NAL9601](INFO): NAL9601 initialized
2012-06-22T08:18:54.481Z,1340353134.480 [NAL9601](IMPORTANT): GPS fix at: 1340353353.00
2012-06-22T08:18:54.512Z,1340353134.512 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Stopped
2012-06-22T08:18:54.512Z,1340353134.512 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Running Loop=1
2012-06-22T08:19:10.461Z,1340353150.461 [NAL9601](INFO): SBD MO Status=1, MOMSN=10675, MT Status=0, MTMSN=0
2012-06-22T08:19:10.583Z,1340353150.583 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0002.lzma
2012-06-22T08:19:10.583Z,1340353150.583 [NAL9601](INFO): Packets left to send: 4
2012-06-22T08:19:10.585Z,1340353150.585 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0002.lzma.parts/0004.sbd
2012-06-22T08:19:19.352Z,1340353159.352 [NAL9601](INFO): SBD MO Status=1, MOMSN=10676, MT Status=0, MTMSN=0
2012-06-22T08:19:19.567Z,1340353159.567 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0002.lzma
2012-06-22T08:19:19.567Z,1340353159.567 [NAL9601](INFO): Packets left to send: 3
2012-06-22T08:19:19.568Z,1340353159.568 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0002.lzma.parts/0003.sbd
2012-06-22T08:19:27.102Z,1340353167.102 [NAL9601](INFO): SBD MO Status=1, MOMSN=10677, MT Status=0, MTMSN=0
2012-06-22T08:19:27.255Z,1340353167.255 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0002.lzma
2012-06-22T08:19:27.255Z,1340353167.255 [NAL9601](INFO): Packets left to send: 2
2012-06-22T08:19:27.271Z,1340353167.271 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0002.lzma.parts/0002.sbd
2012-06-22T08:19:36.822Z,1340353176.822 [NAL9601](INFO): SBD MO Status=1, MOMSN=10678, MT Status=0, MTMSN=0
2012-06-22T08:19:36.939Z,1340353176.939 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0002.lzma
2012-06-22T08:19:36.939Z,1340353176.939 [NAL9601](INFO): Packets left to send: 1
2012-06-22T08:19:36.940Z,1340353176.940 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0002.lzma.parts/0001.sbd
2012-06-22T08:19:44.137Z,1340353184.137 [NAL9601](INFO): SBD MO Status=1, MOMSN=10679, MT Status=0, MTMSN=0
2012-06-22T08:19:44.331Z,1340353184.331 [NAL9601](INFO): Sent 212 bytes from file Logs/20120622T080505/shore0002.lzma
2012-06-22T08:19:44.331Z,1340353184.331 [NAL9601](INFO): Packets left to send: 0
2012-06-22T08:19:44.332Z,1340353184.332 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0002.lzma.parts/0000.sbd
2012-06-22T08:19:47.737Z,1340353187.737 [NAL9601](INFO): SBD MO Status=0, MOMSN=10680, MT Status=0, MTMSN=0
2012-06-22T08:19:47.952Z,1340353187.953 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Stopped
2012-06-22T08:19:47.953Z,1340353187.953 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Running Loop=1
2012-06-22T08:19:50.138Z,1340353190.138 [NAL9601](IMPORTANT): GPS fix at: 1340353408.00
2012-06-22T08:19:50.153Z,1340353190.153 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Stopped
2012-06-22T08:19:50.154Z,1340353190.154 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS:B
2012-06-22T08:19:50.154Z,1340353190.154 [triangle_control_speed_onelap:SURFACECOMMS:B] Stopped
2012-06-22T08:19:50.154Z,1340353190.154 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS:B
2012-06-22T08:19:50.154Z,1340353190.154 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Stopped
2012-06-22T08:19:50.154Z,1340353190.154 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T08:19:50.155Z,1340353190.155 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS
2012-06-22T08:19:50.155Z,1340353190.155 [triangle_control_speed_onelap:SURFACECOMMS] Stopped
2012-06-22T08:19:50.155Z,1340353190.155 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS
2012-06-22T08:19:50.155Z,1340353190.155 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Stopped
2012-06-22T08:19:50.155Z,1340353190.155 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T08:19:50.550Z,1340353190.550 [triangle_control_speed_onelap:WaypointOne:PHONEHOMEWPT1](INFO): Completed triangle_control_speed_onelap:WaypointOne:PHONEHOMEWPT1
2012-06-22T08:19:50.550Z,1340353190.550 [triangle_control_speed_onelap:WaypointOne:PHONEHOMEWPT1] Stopped
2012-06-22T08:19:50.550Z,1340353190.550 [triangle_control_speed_onelap:WaypointOne:PHONEHOMEWPT1](INFO): Aggregate::uninitialize triangle_control_speed_onelap:WaypointOne:PHONEHOMEWPT1
2012-06-22T08:19:50.551Z,1340353190.551 [triangle_control_speed_onelap:WaypointOne](INFO): Completed triangle_control_speed_onelap:WaypointOne
2012-06-22T08:19:50.551Z,1340353190.551 [triangle_control_speed_onelap:WaypointOne] Stopped
2012-06-22T08:19:50.551Z,1340353190.551 [triangle_control_speed_onelap:WaypointOne](INFO): Aggregate::uninitialize triangle_control_speed_onelap:WaypointOne
2012-06-22T08:19:50.551Z,1340353190.551 [triangle_control_speed_onelap:WaypointOne:A.Pitch] Stopped
2012-06-22T08:19:50.552Z,1340353190.552 [triangle_control_speed_onelap:WaypointOne:B.SetSpeed] Stopped
2012-06-22T08:19:50.552Z,1340353190.552 [triangle_control_speed_onelap:WaypointOne:B.SetSpeed](DEBUG): Uninitialize.
2012-06-22T08:19:50.552Z,1340353190.552 [triangle_control_speed_onelap:ThreeLegs] Running Loop=1
2012-06-22T08:19:50.552Z,1340353190.552 [triangle_control_speed_onelap:ThreeLegs](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs
2012-06-22T08:19:50.552Z,1340353190.552 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold] Running Loop=1
2012-06-22T08:19:50.552Z,1340353190.552 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:BuoyancyHold
2012-06-22T08:19:50.552Z,1340353190.552 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold:A.Buoyancy] Running Loop=1
2012-06-22T08:19:50.552Z,1340353190.552 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2012-06-22T08:19:50.553Z,1340353190.553 [triangle_control_speed_onelap:ThreeLegs:MassHold] Running Loop=1
2012-06-22T08:19:50.553Z,1340353190.553 [triangle_control_speed_onelap:ThreeLegs:MassHold](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:MassHold
2012-06-22T08:19:50.553Z,1340353190.553 [triangle_control_speed_onelap:ThreeLegs:MassHold:A.Pitch] Running Loop=1
2012-06-22T08:19:50.553Z,1340353190.553 [triangle_control_speed_onelap:ThreeLegs:MassHold:A.Pitch](DEBUG): Initialize.
2012-06-22T08:19:50.553Z,1340353190.553 [triangle_control_speed_onelap:ThreeLegs:ElevatorHold] Running Loop=1
2012-06-22T08:19:50.553Z,1340353190.553 [triangle_control_speed_onelap:ThreeLegs:ElevatorHold](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:ElevatorHold
2012-06-22T08:19:50.553Z,1340353190.553 [triangle_control_speed_onelap:ThreeLegs:ElevatorHold:A.Pitch] Running Loop=1
2012-06-22T08:19:50.553Z,1340353190.553 [triangle_control_speed_onelap:ThreeLegs:ElevatorHold:A.Pitch](DEBUG): Initialize.
2012-06-22T08:19:50.554Z,1340353190.554 [triangle_control_speed_onelap:ThreeLegs:D] Running Loop=1
2012-06-22T08:19:50.554Z,1340353190.554 [triangle_control_speed_onelap:ThreeLegs:E] Running Loop=1
2012-06-22T08:19:50.554Z,1340353190.554 [triangle_control_speed_onelap:ThreeLegs:F] Running Loop=1
2012-06-22T08:19:50.554Z,1340353190.554 [triangle_control_speed_onelap:ThreeLegs:G] Running Loop=1
2012-06-22T08:19:50.554Z,1340353190.554 [triangle_control_speed_onelap:ThreeLegs:H] Running Loop=1
2012-06-22T08:19:50.554Z,1340353190.554 [triangle_control_speed_onelap:ThreeLegs:I] Running Loop=1
2012-06-22T08:19:50.554Z,1340353190.554 [triangle_control_speed_onelap:ThreeLegs:J] Running Loop=1
2012-06-22T08:19:50.554Z,1340353190.554 [triangle_control_speed_onelap:ThreeLegs:K] Running Loop=1
2012-06-22T08:19:50.554Z,1340353190.554 [triangle_control_speed_onelap:ThreeLegs:L] Running Loop=1
2012-06-22T08:19:50.554Z,1340353190.555 [triangle_control_speed_onelap:ThreeLegs:TestDrive] Running Loop=1
2012-06-22T08:19:50.555Z,1340353190.555 [triangle_control_speed_onelap:ThreeLegs:TestDrive](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:TestDrive
2012-06-22T08:19:50.555Z,1340353190.555 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1] Running Loop=1
2012-06-22T08:19:50.555Z,1340353190.555 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1
2012-06-22T08:19:50.555Z,1340353190.555 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:A.SetSpeed] Running Loop=1
2012-06-22T08:19:50.555Z,1340353190.555 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:A.SetSpeed](DEBUG): Initialize.
2012-06-22T08:19:50.555Z,1340353190.555 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:B.Pitch] Running Loop=1
2012-06-22T08:19:50.555Z,1340353190.555 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:B.Pitch](DEBUG): Initialize.
2012-06-22T08:19:50.556Z,1340353190.556 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:Wpt2.Waypoint] Running Loop=1
2012-06-22T08:19:50.556Z,1340353190.556 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:Wpt2.Waypoint](DEBUG): Initialize WaypointComponent.
2012-06-22T08:19:50.990Z,1340353190.990 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:B.Pitch] Running Loop=1
2012-06-22T08:19:50.991Z,1340353190.991 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:A.SetSpeed] Running Loop=1
2012-06-22T08:19:50.992Z,1340353190.992 [triangle_control_speed_onelap:ThreeLegs:L] Running Loop=1
2012-06-22T08:19:50.993Z,1340353190.993 [triangle_control_speed_onelap:ThreeLegs:K] Running Loop=1
2012-06-22T08:19:50.994Z,1340353190.994 [triangle_control_speed_onelap:ThreeLegs:J] Running Loop=1
2012-06-22T08:19:50.995Z,1340353190.995 [triangle_control_speed_onelap:ThreeLegs:I] Running Loop=1
2012-06-22T08:19:50.996Z,1340353190.996 [triangle_control_speed_onelap:ThreeLegs:H] Running Loop=1
2012-06-22T08:19:50.997Z,1340353190.997 [triangle_control_speed_onelap:ThreeLegs:G] Running Loop=1
2012-06-22T08:19:50.998Z,1340353190.998 [triangle_control_speed_onelap:ThreeLegs:F] Running Loop=1
2012-06-22T08:19:50.999Z,1340353190.999 [triangle_control_speed_onelap:ThreeLegs:E] Running Loop=1
2012-06-22T08:19:50.000Z,1340353191.000 [triangle_control_speed_onelap:ThreeLegs:D] Running Loop=1
2012-06-22T08:19:51.001Z,1340353191.001 [triangle_control_speed_onelap:ThreeLegs:MassHold] Running Loop=1
2012-06-22T08:19:51.001Z,1340353191.001 [triangle_control_speed_onelap:ThreeLegs:MassHold:A.Pitch] Running Loop=1
2012-06-22T08:19:51.002Z,1340353191.001 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold] Running Loop=1
2012-06-22T08:19:51.002Z,1340353191.002 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold:A.Buoyancy] Running Loop=1
2012-06-22T08:19:56.540Z,1340353196.540 [NAL9601](INFO): Powering down
2012-06-22T08:20:10.159Z,1340353210.159 [Radio_Freewave](INFO): Powering down
2012-06-22T08:20:23.745Z,1340353223.745 [Radio_Freewave](INFO): Powering up
2012-06-22T08:21:00.557Z,1340353260.557 [Radio_Freewave](INFO): Powering down
2012-06-22T08:25:20.516Z,1340353520.516 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2012-06-22T08:25:22.916Z,1340353522.916 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2012-06-22T08:25:25.319Z,1340353525.319 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2012-06-22T08:25:27.732Z,1340353527.731 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2012-06-22T08:25:30.119Z,1340353530.119 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2012-06-22T08:25:32.516Z,1340353532.516 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2012-06-22T08:25:34.939Z,1340353534.940 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2012-06-22T08:25:37.727Z,1340353537.727 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2012-06-22T08:25:40.115Z,1340353540.115 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2012-06-22T08:25:42.531Z,1340353542.531 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2012-06-22T08:25:44.931Z,1340353544.931 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2012-06-22T08:25:47.319Z,1340353547.319 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2012-06-22T08:25:49.715Z,1340353549.715 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2012-06-22T08:25:52.115Z,1340353552.115 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2012-06-22T08:25:54.518Z,1340353554.519 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2012-06-22T08:25:56.919Z,1340353556.918 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2012-06-22T08:25:59.416Z,1340353559.416 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2012-06-22T08:26:01.739Z,1340353561.739 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2012-06-22T08:26:04.119Z,1340353564.119 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2012-06-22T08:26:06.527Z,1340353566.527 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2012-06-22T08:26:08.931Z,1340353568.931 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2012-06-22T08:26:11.359Z,1340353571.359 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2012-06-22T08:26:14.119Z,1340353574.119 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2012-06-22T08:26:16.518Z,1340353576.518 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2012-06-22T08:26:18.931Z,1340353578.931 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2012-06-22T08:26:21.318Z,1340353581.318 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2012-06-22T08:26:23.723Z,1340353583.723 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2012-06-22T08:26:26.147Z,1340353586.147 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2012-06-22T08:26:28.543Z,1340353588.543 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2012-06-22T08:26:30.931Z,1340353590.931 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#9 STATUS: 65535
2012-06-22T08:58:32.323Z,1340355512.323 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:Wpt2.Waypoint](INFO): Reached Waypoint: 36.829000,-121.886000
2012-06-22T08:58:32.323Z,1340355512.323 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:Wpt2.Waypoint] Stopped
2012-06-22T08:58:32.323Z,1340355512.323 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:Wpt2.Waypoint](DEBUG): Uninitialize WaypointComponent.
2012-06-22T08:58:32.324Z,1340355512.323 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:PHONEHOMEWPT2] Running Loop=1
2012-06-22T08:58:32.324Z,1340355512.324 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:PHONEHOMEWPT2](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:PHONEHOMEWPT2
2012-06-22T08:58:32.698Z,1340355512.698 [triangle_control_speed_onelap:SURFACECOMMS] Running Loop=1
2012-06-22T08:58:32.698Z,1340355512.698 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS
2012-06-22T08:58:32.698Z,1340355512.698 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-06-22T08:58:32.698Z,1340355512.698 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T09:03:28.905Z,1340355808.905 [Radio_Freewave](INFO): Powering up
2012-06-22T09:03:28.920Z,1340355808.920 [triangle_control_speed_onelap:SURFACECOMMS:B] Running Loop=1
2012-06-22T09:03:28.920Z,1340355808.920 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS:B
2012-06-22T09:03:28.920Z,1340355808.920 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Running Loop=1
2012-06-22T09:03:28.920Z,1340355808.920 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize.
2012-06-22T09:03:28.920Z,1340355808.920 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Running Loop=1
2012-06-22T09:03:29.707Z,1340355809.707 [NAL9601](INFO): Powering up
2012-06-22T09:04:35.331Z,1340355875.331 [NAL9601](INFO): NAL9601 initialized
2012-06-22T09:04:36.405Z,1340355876.405 [NAL9601](IMPORTANT): GPS fix at: 1340356099.00
2012-06-22T09:04:36.419Z,1340355876.420 [Navigation](IMPORTANT): Error 7.2 %DT at bearing -161.3 deg., over the last 2370.1 m.
2012-06-22T09:04:36.420Z,1340355876.420 [Navigation](IMPORTANT): Distance made good 2435.6 m, at (36.8312, -121.8848).
2012-06-22T09:04:36.421Z,1340355876.421 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Stopped
2012-06-22T09:04:36.421Z,1340355876.421 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Running Loop=1
2012-06-22T09:04:52.729Z,1340355892.729 [NAL9601](INFO): SBD MO Status=1, MOMSN=10681, MT Status=0, MTMSN=0
2012-06-22T09:04:52.899Z,1340355892.899 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0003.lzma
2012-06-22T09:04:52.899Z,1340355892.899 [NAL9601](INFO): Packets left to send: 9
2012-06-22T09:04:52.901Z,1340355892.901 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0003.lzma.parts/0009.sbd
2012-06-22T09:05:01.237Z,1340355901.237 [NAL9601](INFO): SBD MO Status=1, MOMSN=10682, MT Status=0, MTMSN=0
2012-06-22T09:05:01.379Z,1340355901.379 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0003.lzma
2012-06-22T09:05:01.379Z,1340355901.379 [NAL9601](INFO): Packets left to send: 8
2012-06-22T09:05:01.380Z,1340355901.380 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0003.lzma.parts/0008.sbd
2012-06-22T09:05:08.942Z,1340355908.942 [NAL9601](INFO): SBD MO Status=1, MOMSN=10683, MT Status=0, MTMSN=0
2012-06-22T09:05:09.067Z,1340355909.067 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0003.lzma
2012-06-22T09:05:09.067Z,1340355909.067 [NAL9601](INFO): Packets left to send: 7
2012-06-22T09:05:09.068Z,1340355909.068 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0003.lzma.parts/0007.sbd
2012-06-22T09:05:16.675Z,1340355916.675 [NAL9601](INFO): SBD MO Status=1, MOMSN=10684, MT Status=0, MTMSN=0
2012-06-22T09:05:16.855Z,1340355916.855 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0003.lzma
2012-06-22T09:05:16.855Z,1340355916.855 [NAL9601](INFO): Packets left to send: 6
2012-06-22T09:05:16.856Z,1340355916.856 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0003.lzma.parts/0006.sbd
2012-06-22T09:05:24.386Z,1340355924.386 [NAL9601](INFO): SBD MO Status=1, MOMSN=10685, MT Status=0, MTMSN=0
2012-06-22T09:05:24.543Z,1340355924.543 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0003.lzma
2012-06-22T09:05:24.543Z,1340355924.543 [NAL9601](INFO): Packets left to send: 5
2012-06-22T09:05:24.544Z,1340355924.544 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0003.lzma.parts/0005.sbd
2012-06-22T09:05:32.086Z,1340355932.086 [NAL9601](INFO): SBD MO Status=1, MOMSN=10686, MT Status=0, MTMSN=0
2012-06-22T09:05:32.231Z,1340355932.231 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0003.lzma
2012-06-22T09:05:32.231Z,1340355932.231 [NAL9601](INFO): Packets left to send: 4
2012-06-22T09:05:32.232Z,1340355932.232 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0003.lzma.parts/0004.sbd
2012-06-22T09:05:44.602Z,1340355944.602 [NAL9601](INFO): SBD MO Status=1, MOMSN=10687, MT Status=0, MTMSN=0
2012-06-22T09:05:44.815Z,1340355944.815 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0003.lzma
2012-06-22T09:05:44.815Z,1340355944.815 [NAL9601](INFO): Packets left to send: 3
2012-06-22T09:05:44.816Z,1340355944.816 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0003.lzma.parts/0003.sbd
2012-06-22T09:05:53.570Z,1340355953.570 [NAL9601](INFO): SBD MO Status=1, MOMSN=10688, MT Status=0, MTMSN=0
2012-06-22T09:05:53.699Z,1340355953.699 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0003.lzma
2012-06-22T09:05:53.699Z,1340355953.699 [NAL9601](INFO): Packets left to send: 2
2012-06-22T09:05:53.700Z,1340355953.700 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0003.lzma.parts/0002.sbd
2012-06-22T09:06:06.466Z,1340355966.466 [NAL9601](INFO): SBD MO Status=1, MOMSN=10689, MT Status=0, MTMSN=0
2012-06-22T09:06:06.679Z,1340355966.679 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0003.lzma
2012-06-22T09:06:06.679Z,1340355966.679 [NAL9601](INFO): Packets left to send: 1
2012-06-22T09:06:06.680Z,1340355966.680 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0003.lzma.parts/0001.sbd
2012-06-22T09:06:18.978Z,1340355978.978 [NAL9601](INFO): SBD MO Status=1, MOMSN=10690, MT Status=0, MTMSN=0
2012-06-22T09:06:19.159Z,1340355979.159 [NAL9601](INFO): Sent 290 bytes from file Logs/20120622T080505/shore0003.lzma
2012-06-22T09:06:19.159Z,1340355979.159 [NAL9601](INFO): Packets left to send: 0
2012-06-22T09:06:19.160Z,1340355979.160 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0003.lzma.parts/0000.sbd
2012-06-22T09:06:22.215Z,1340355982.215 [NAL9601](INFO): SBD MO Status=0, MOMSN=10691, MT Status=0, MTMSN=0
2012-06-22T09:06:22.383Z,1340355982.383 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Stopped
2012-06-22T09:06:22.383Z,1340355982.383 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Running Loop=1
2012-06-22T09:06:24.748Z,1340355984.748 [NAL9601](IMPORTANT): GPS fix at: 1340356207.00
2012-06-22T09:06:24.763Z,1340355984.763 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Stopped
2012-06-22T09:06:24.764Z,1340355984.764 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS:B
2012-06-22T09:06:24.764Z,1340355984.764 [triangle_control_speed_onelap:SURFACECOMMS:B] Stopped
2012-06-22T09:06:24.764Z,1340355984.764 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS:B
2012-06-22T09:06:24.764Z,1340355984.764 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Stopped
2012-06-22T09:06:24.764Z,1340355984.764 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T09:06:24.765Z,1340355984.765 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS
2012-06-22T09:06:24.765Z,1340355984.765 [triangle_control_speed_onelap:SURFACECOMMS] Stopped
2012-06-22T09:06:24.765Z,1340355984.765 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS
2012-06-22T09:06:24.765Z,1340355984.765 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Stopped
2012-06-22T09:06:24.765Z,1340355984.765 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T09:06:25.168Z,1340355985.168 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:PHONEHOMEWPT2](INFO): Completed triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:PHONEHOMEWPT2
2012-06-22T09:06:25.168Z,1340355985.168 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:PHONEHOMEWPT2] Stopped
2012-06-22T09:06:25.168Z,1340355985.168 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:PHONEHOMEWPT2](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:PHONEHOMEWPT2
2012-06-22T09:06:25.169Z,1340355985.169 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1](INFO): Completed triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1
2012-06-22T09:06:25.169Z,1340355985.169 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1] Stopped
2012-06-22T09:06:25.169Z,1340355985.169 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1
2012-06-22T09:06:25.169Z,1340355985.169 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:A.SetSpeed] Stopped
2012-06-22T09:06:25.169Z,1340355985.169 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T09:06:25.169Z,1340355985.169 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:B.Pitch] Stopped
2012-06-22T09:06:25.170Z,1340355985.170 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2] Running Loop=1
2012-06-22T09:06:25.170Z,1340355985.170 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2
2012-06-22T09:06:25.170Z,1340355985.170 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:A.SetSpeed] Running Loop=1
2012-06-22T09:06:25.170Z,1340355985.170 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:A.SetSpeed](DEBUG): Initialize.
2012-06-22T09:06:25.174Z,1340355985.174 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:B.Pitch] Running Loop=1
2012-06-22T09:06:25.175Z,1340355985.174 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:B.Pitch](DEBUG): Initialize.
2012-06-22T09:06:25.175Z,1340355985.175 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:Wpt3.Waypoint] Running Loop=1
2012-06-22T09:06:25.175Z,1340355985.175 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:Wpt3.Waypoint](DEBUG): Initialize WaypointComponent.
2012-06-22T09:06:25.669Z,1340355985.670 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:B.Pitch] Running Loop=1
2012-06-22T09:06:25.671Z,1340355985.671 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:A.SetSpeed] Running Loop=1
2012-06-22T09:06:31.131Z,1340355991.131 [NAL9601](INFO): Powering down
2012-06-22T09:07:04.775Z,1340356024.775 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T09:07:06.798Z,1340356026.798 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T09:07:09.551Z,1340356029.551 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T09:07:12.000Z,1340356032.000 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T09:07:14.351Z,1340356034.351 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T09:07:16.337Z,1340356036.337 [Radio_Freewave](INFO): Powering down
2012-06-22T09:07:16.750Z,1340356036.750 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T09:07:19.162Z,1340356039.162 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T09:07:21.550Z,1340356041.550 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T09:07:23.952Z,1340356043.952 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T09:07:26.350Z,1340356046.350 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T09:07:28.750Z,1340356048.750 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T09:07:31.150Z,1340356051.150 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T09:07:33.550Z,1340356053.550 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T09:07:35.952Z,1340356055.952 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T09:07:38.751Z,1340356058.751 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T09:07:41.151Z,1340356061.151 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T09:07:43.551Z,1340356063.551 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T09:07:45.953Z,1340356065.953 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T09:07:48.571Z,1340356068.571 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T09:07:50.871Z,1340356070.871 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T09:07:53.290Z,1340356073.290 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T09:07:55.679Z,1340356075.679 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T09:07:58.059Z,1340356078.059 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T09:08:00.459Z,1340356080.459 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T09:08:02.874Z,1340356082.874 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T09:08:05.290Z,1340356085.290 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T09:08:07.663Z,1340356087.663 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T09:08:10.267Z,1340356090.267 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T09:08:12.650Z,1340356092.650 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T09:08:15.071Z,1340356095.071 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T09:08:17.469Z,1340356097.469 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T09:08:19.871Z,1340356099.871 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T09:08:22.271Z,1340356102.271 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T09:43:41.972Z,1340358221.972 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:Wpt3.Waypoint](INFO): Reached Waypoint: 36.816000,-121.883000
2012-06-22T09:43:41.973Z,1340358221.973 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:Wpt3.Waypoint] Stopped
2012-06-22T09:43:41.973Z,1340358221.973 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:Wpt3.Waypoint](DEBUG): Uninitialize WaypointComponent.
2012-06-22T09:43:41.973Z,1340358221.973 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:PHONEHOMEWPT3] Running Loop=1
2012-06-22T09:43:41.973Z,1340358221.973 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:PHONEHOMEWPT3](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:PHONEHOMEWPT3
2012-06-22T09:43:42.309Z,1340358222.309 [triangle_control_speed_onelap:SURFACECOMMS] Running Loop=1
2012-06-22T09:43:42.309Z,1340358222.309 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS
2012-06-22T09:43:42.310Z,1340358222.309 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-06-22T09:43:42.310Z,1340358222.309 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T09:48:47.765Z,1340358527.765 [Radio_Freewave](INFO): Powering up
2012-06-22T09:48:47.796Z,1340358527.796 [triangle_control_speed_onelap:SURFACECOMMS:B] Running Loop=1
2012-06-22T09:48:47.796Z,1340358527.796 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS:B
2012-06-22T09:48:47.796Z,1340358527.796 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Running Loop=1
2012-06-22T09:48:47.796Z,1340358527.796 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize.
2012-06-22T09:48:47.797Z,1340358527.797 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Running Loop=1
2012-06-22T09:48:48.564Z,1340358528.564 [NAL9601](INFO): Powering up
2012-06-22T09:49:54.087Z,1340358594.087 [NAL9601](INFO): NAL9601 initialized
2012-06-22T09:49:55.262Z,1340358595.262 [NAL9601](IMPORTANT): GPS fix at: 1340358822.00
2012-06-22T09:49:55.279Z,1340358595.279 [Navigation](IMPORTANT): Error 4.4 %DT at bearing -47.1 deg., over the last 1897.6 m.
2012-06-22T09:49:55.279Z,1340358595.279 [Navigation](IMPORTANT): Distance made good 1812.9 m, at (36.8149, -121.8838).
2012-06-22T09:49:55.280Z,1340358595.280 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Stopped
2012-06-22T09:49:55.280Z,1340358595.280 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Running Loop=1
2012-06-22T09:50:23.574Z,1340358623.574 [NAL9601](INFO): SBD MO Status=1, MOMSN=10692, MT Status=0, MTMSN=0
2012-06-22T09:50:23.731Z,1340358623.731 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0004.lzma
2012-06-22T09:50:23.731Z,1340358623.731 [NAL9601](INFO): Packets left to send: 11
2012-06-22T09:50:23.733Z,1340358623.733 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0004.lzma.parts/0011.sbd
2012-06-22T09:50:37.345Z,1340358637.345 [NAL9601](INFO): SBD MO Status=1, MOMSN=10693, MT Status=0, MTMSN=0
2012-06-22T09:50:37.511Z,1340358637.511 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0004.lzma
2012-06-22T09:50:37.511Z,1340358637.511 [NAL9601](INFO): Packets left to send: 10
2012-06-22T09:50:37.512Z,1340358637.513 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0004.lzma.parts/0010.sbd
2012-06-22T09:50:50.239Z,1340358650.239 [NAL9601](INFO): SBD MO Status=1, MOMSN=10694, MT Status=0, MTMSN=0
2012-06-22T09:50:50.391Z,1340358650.391 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0004.lzma
2012-06-22T09:50:50.391Z,1340358650.391 [NAL9601](INFO): Packets left to send: 9
2012-06-22T09:50:50.392Z,1340358650.392 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0004.lzma.parts/0009.sbd
2012-06-22T09:51:05.124Z,1340358665.125 [NAL9601](INFO): SBD MO Status=1, MOMSN=10695, MT Status=0, MTMSN=0
2012-06-22T09:51:05.267Z,1340358665.267 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0004.lzma
2012-06-22T09:51:05.267Z,1340358665.267 [NAL9601](INFO): Packets left to send: 8
2012-06-22T09:51:05.339Z,1340358665.339 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0004.lzma.parts/0008.sbd
2012-06-22T09:51:22.449Z,1340358682.449 [NAL9601](INFO): SBD MO Status=1, MOMSN=10696, MT Status=0, MTMSN=0
2012-06-22T09:51:22.643Z,1340358682.643 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0004.lzma
2012-06-22T09:51:22.643Z,1340358682.643 [NAL9601](INFO): Packets left to send: 7
2012-06-22T09:51:22.644Z,1340358682.644 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0004.lzma.parts/0007.sbd
2012-06-22T09:51:41.385Z,1340358701.385 [NAL9601](INFO): SBD MO Status=1, MOMSN=10697, MT Status=0, MTMSN=0
2012-06-22T09:51:41.511Z,1340358701.511 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0004.lzma
2012-06-22T09:51:41.511Z,1340358701.511 [NAL9601](INFO): Packets left to send: 6
2012-06-22T09:51:41.512Z,1340358701.512 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0004.lzma.parts/0006.sbd
2012-06-22T09:51:53.218Z,1340358713.218 [NAL9601](INFO): SBD MO Status=1, MOMSN=10698, MT Status=0, MTMSN=0
2012-06-22T09:51:53.391Z,1340358713.391 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0004.lzma
2012-06-22T09:51:53.391Z,1340358713.391 [NAL9601](INFO): Packets left to send: 5
2012-06-22T09:51:53.392Z,1340358713.392 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0004.lzma.parts/0005.sbd
2012-06-22T09:52:02.925Z,1340358722.925 [NAL9601](INFO): SBD MO Status=1, MOMSN=10699, MT Status=0, MTMSN=0
2012-06-22T09:52:03.075Z,1340358723.075 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0004.lzma
2012-06-22T09:52:03.075Z,1340358723.075 [NAL9601](INFO): Packets left to send: 4
2012-06-22T09:52:03.076Z,1340358723.076 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0004.lzma.parts/0004.sbd
2012-06-22T09:52:13.451Z,1340358733.451 [NAL9601](INFO): SBD MO Status=1, MOMSN=10700, MT Status=0, MTMSN=0
2012-06-22T09:52:13.659Z,1340358733.659 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0004.lzma
2012-06-22T09:52:13.659Z,1340358733.659 [NAL9601](INFO): Packets left to send: 3
2012-06-22T09:52:13.660Z,1340358733.660 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0004.lzma.parts/0003.sbd
2012-06-22T09:52:25.937Z,1340358745.937 [NAL9601](INFO): SBD MO Status=1, MOMSN=10701, MT Status=0, MTMSN=0
2012-06-22T09:52:26.143Z,1340358746.143 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0004.lzma
2012-06-22T09:52:26.143Z,1340358746.143 [NAL9601](INFO): Packets left to send: 2
2012-06-22T09:52:26.144Z,1340358746.144 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0004.lzma.parts/0002.sbd
2012-06-22T09:52:36.451Z,1340358756.451 [NAL9601](INFO): SBD MO Status=1, MOMSN=10702, MT Status=0, MTMSN=0
2012-06-22T09:52:36.623Z,1340358756.623 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0004.lzma
2012-06-22T09:52:36.623Z,1340358756.623 [NAL9601](INFO): Packets left to send: 1
2012-06-22T09:52:36.624Z,1340358756.624 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0004.lzma.parts/0001.sbd
2012-06-22T09:52:46.577Z,1340358766.577 [NAL9601](INFO): SBD MO Status=1, MOMSN=10703, MT Status=0, MTMSN=0
2012-06-22T09:52:46.711Z,1340358766.711 [NAL9601](INFO): Sent 160 bytes from file Logs/20120622T080505/shore0004.lzma
2012-06-22T09:52:46.711Z,1340358766.711 [NAL9601](INFO): Packets left to send: 0
2012-06-22T09:52:46.712Z,1340358766.712 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0004.lzma.parts/0000.sbd
2012-06-22T09:52:51.758Z,1340358771.758 [NAL9601](INFO): SBD MO Status=0, MOMSN=10704, MT Status=0, MTMSN=0
2012-06-22T09:52:51.930Z,1340358771.930 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Stopped
2012-06-22T09:52:51.934Z,1340358771.934 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Running Loop=1
2012-06-22T09:52:54.216Z,1340358774.216 [NAL9601](IMPORTANT): GPS fix at: 1340359008.00
2012-06-22T09:52:54.238Z,1340358774.238 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Stopped
2012-06-22T09:52:54.239Z,1340358774.239 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS:B
2012-06-22T09:52:54.239Z,1340358774.239 [triangle_control_speed_onelap:SURFACECOMMS:B] Stopped
2012-06-22T09:52:54.239Z,1340358774.239 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS:B
2012-06-22T09:52:54.239Z,1340358774.239 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Stopped
2012-06-22T09:52:54.239Z,1340358774.239 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T09:52:54.240Z,1340358774.240 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS
2012-06-22T09:52:54.240Z,1340358774.240 [triangle_control_speed_onelap:SURFACECOMMS] Stopped
2012-06-22T09:52:54.240Z,1340358774.240 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS
2012-06-22T09:52:54.240Z,1340358774.240 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Stopped
2012-06-22T09:52:54.241Z,1340358774.240 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T09:52:54.591Z,1340358774.592 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:PHONEHOMEWPT3](INFO): Completed triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:PHONEHOMEWPT3
2012-06-22T09:52:54.592Z,1340358774.592 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:PHONEHOMEWPT3] Stopped
2012-06-22T09:52:54.592Z,1340358774.592 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:PHONEHOMEWPT3](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:PHONEHOMEWPT3
2012-06-22T09:52:54.593Z,1340358774.593 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2](INFO): Completed triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2
2012-06-22T09:52:54.593Z,1340358774.593 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2] Stopped
2012-06-22T09:52:54.593Z,1340358774.593 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2
2012-06-22T09:52:54.593Z,1340358774.593 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:A.SetSpeed] Stopped
2012-06-22T09:52:54.593Z,1340358774.593 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T09:52:54.593Z,1340358774.593 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:B.Pitch] Stopped
2012-06-22T09:52:54.593Z,1340358774.593 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3] Running Loop=1
2012-06-22T09:52:54.593Z,1340358774.593 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3
2012-06-22T09:52:54.593Z,1340358774.593 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:A.SetSpeed] Running Loop=1
2012-06-22T09:52:54.594Z,1340358774.593 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:A.SetSpeed](DEBUG): Initialize.
2012-06-22T09:52:54.594Z,1340358774.594 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:B.Pitch] Running Loop=1
2012-06-22T09:52:54.594Z,1340358774.594 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:B.Pitch](DEBUG): Initialize.
2012-06-22T09:52:54.594Z,1340358774.594 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:Wpt1.Waypoint] Running Loop=1
2012-06-22T09:52:54.598Z,1340358774.598 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2012-06-22T09:52:55.001Z,1340358775.000 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:B.Pitch] Running Loop=1
2012-06-22T09:52:55.002Z,1340358775.001 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:A.SetSpeed] Running Loop=1
2012-06-22T09:53:00.560Z,1340358780.560 [NAL9601](INFO): Powering down
2012-06-22T09:54:13.257Z,1340358853.257 [Radio_Freewave](INFO): Powering down
2012-06-22T10:15:23.611Z,1340360123.611 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T10:15:26.021Z,1340360126.021 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T10:15:28.421Z,1340360128.421 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T10:15:30.809Z,1340360130.809 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T10:15:33.216Z,1340360133.216 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T10:15:35.738Z,1340360135.738 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T10:15:38.072Z,1340360138.072 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T10:15:40.917Z,1340360140.917 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T10:15:43.282Z,1340360143.282 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T10:15:45.680Z,1340360145.680 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T10:15:48.079Z,1340360148.079 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T10:15:50.480Z,1340360150.480 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T10:15:52.879Z,1340360152.879 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T10:15:55.286Z,1340360155.286 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T10:15:57.684Z,1340360157.684 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T10:16:00.079Z,1340360160.079 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T10:16:02.480Z,1340360162.480 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T10:16:04.879Z,1340360164.879 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T10:16:07.284Z,1340360167.284 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T10:16:09.689Z,1340360169.689 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T10:16:12.079Z,1340360172.079 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T10:16:14.480Z,1340360174.480 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T10:16:16.925Z,1340360176.925 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T10:32:58.099Z,1340361178.099 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:Wpt1.Waypoint](INFO): Reached Waypoint: 36.815000,-121.869000
2012-06-22T10:32:58.099Z,1340361178.099 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:Wpt1.Waypoint] Stopped
2012-06-22T10:32:58.099Z,1340361178.099 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2012-06-22T10:32:58.099Z,1340361178.099 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:PHONEHOMEWPT1] Running Loop=1
2012-06-22T10:32:58.099Z,1340361178.099 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:PHONEHOMEWPT1](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:PHONEHOMEWPT1
2012-06-22T10:32:58.516Z,1340361178.516 [triangle_control_speed_onelap:SURFACECOMMS] Running Loop=1
2012-06-22T10:32:58.516Z,1340361178.516 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS
2012-06-22T10:32:58.516Z,1340361178.516 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-06-22T10:32:58.516Z,1340361178.516 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T10:38:08.802Z,1340361488.802 [Radio_Freewave](INFO): Powering up
2012-06-22T10:38:08.816Z,1340361488.816 [triangle_control_speed_onelap:SURFACECOMMS:B] Running Loop=1
2012-06-22T10:38:08.816Z,1340361488.816 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS:B
2012-06-22T10:38:08.816Z,1340361488.816 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Running Loop=1
2012-06-22T10:38:08.816Z,1340361488.816 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize.
2012-06-22T10:38:08.816Z,1340361488.816 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Running Loop=1
2012-06-22T10:38:09.590Z,1340361489.590 [NAL9601](INFO): Powering up
2012-06-22T10:39:15.224Z,1340361555.224 [NAL9601](INFO): NAL9601 initialized
2012-06-22T10:39:16.291Z,1340361556.291 [NAL9601](IMPORTANT): GPS fix at: 1340361787.00
2012-06-22T10:39:16.305Z,1340361556.305 [Navigation](IMPORTANT): Error 4.7 %DT at bearing -50.0 deg., over the last 1563.4 m.
2012-06-22T10:39:16.305Z,1340361556.305 [Navigation](IMPORTANT): Distance made good 1399.2 m, at (36.8137, -121.8681).
2012-06-22T10:39:16.306Z,1340361556.306 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Stopped
2012-06-22T10:39:16.306Z,1340361556.306 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Running Loop=1
2012-06-22T10:39:33.944Z,1340361573.944 [NAL9601](INFO): SBD MO Status=1, MOMSN=10705, MT Status=0, MTMSN=0
2012-06-22T10:39:34.075Z,1340361574.075 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0005.lzma
2012-06-22T10:39:34.075Z,1340361574.075 [NAL9601](INFO): Packets left to send: 12
2012-06-22T10:39:34.077Z,1340361574.077 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0005.lzma.parts/0012.sbd
2012-06-22T10:39:43.173Z,1340361583.173 [NAL9601](INFO): SBD MO Status=1, MOMSN=10706, MT Status=0, MTMSN=0
2012-06-22T10:39:43.359Z,1340361583.359 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0005.lzma
2012-06-22T10:39:43.359Z,1340361583.359 [NAL9601](INFO): Packets left to send: 11
2012-06-22T10:39:43.360Z,1340361583.360 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0005.lzma.parts/0011.sbd
2012-06-22T10:39:53.754Z,1340361593.754 [NAL9601](INFO): SBD MO Status=1, MOMSN=10707, MT Status=0, MTMSN=0
2012-06-22T10:39:53.943Z,1340361593.943 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0005.lzma
2012-06-22T10:39:53.943Z,1340361593.943 [NAL9601](INFO): Packets left to send: 10
2012-06-22T10:39:55.615Z,1340361595.615 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0005.lzma.parts/0010.sbd
2012-06-22T10:40:03.045Z,1340361603.045 [NAL9601](INFO): SBD MO Status=1, MOMSN=10708, MT Status=0, MTMSN=0
2012-06-22T10:40:03.227Z,1340361603.227 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0005.lzma
2012-06-22T10:40:03.227Z,1340361603.227 [NAL9601](INFO): Packets left to send: 9
2012-06-22T10:40:03.228Z,1340361603.228 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0005.lzma.parts/0009.sbd
2012-06-22T10:40:13.160Z,1340361613.160 [NAL9601](INFO): SBD MO Status=1, MOMSN=10709, MT Status=0, MTMSN=0
2012-06-22T10:40:13.315Z,1340361613.315 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0005.lzma
2012-06-22T10:40:13.315Z,1340361613.315 [NAL9601](INFO): Packets left to send: 8
2012-06-22T10:40:13.316Z,1340361613.316 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0005.lzma.parts/0008.sbd
2012-06-22T10:40:21.686Z,1340361621.686 [NAL9601](INFO): SBD MO Status=1, MOMSN=10710, MT Status=0, MTMSN=0
2012-06-22T10:40:21.899Z,1340361621.899 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0005.lzma
2012-06-22T10:40:21.899Z,1340361621.899 [NAL9601](INFO): Packets left to send: 7
2012-06-22T10:40:21.900Z,1340361621.900 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0005.lzma.parts/0007.sbd
2012-06-22T10:40:30.586Z,1340361630.586 [NAL9601](INFO): SBD MO Status=1, MOMSN=10711, MT Status=0, MTMSN=0
2012-06-22T10:40:30.787Z,1340361630.787 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0005.lzma
2012-06-22T10:40:30.787Z,1340361630.787 [NAL9601](INFO): Packets left to send: 6
2012-06-22T10:40:30.788Z,1340361630.788 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0005.lzma.parts/0006.sbd
2012-06-22T10:40:43.495Z,1340361643.495 [NAL9601](INFO): SBD MO Status=1, MOMSN=10712, MT Status=0, MTMSN=0
2012-06-22T10:40:43.667Z,1340361643.667 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0005.lzma
2012-06-22T10:40:43.667Z,1340361643.667 [NAL9601](INFO): Packets left to send: 5
2012-06-22T10:40:43.669Z,1340361643.669 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0005.lzma.parts/0005.sbd
2012-06-22T10:40:52.101Z,1340361652.101 [NAL9601](INFO): SBD MO Status=1, MOMSN=10713, MT Status=0, MTMSN=0
2012-06-22T10:40:52.255Z,1340361652.255 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0005.lzma
2012-06-22T10:40:52.255Z,1340361652.255 [NAL9601](INFO): Packets left to send: 4
2012-06-22T10:40:52.256Z,1340361652.256 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0005.lzma.parts/0004.sbd
2012-06-22T10:41:03.860Z,1340361663.860 [NAL9601](INFO): SBD MO Status=1, MOMSN=10714, MT Status=0, MTMSN=0
2012-06-22T10:41:04.035Z,1340361664.035 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0005.lzma
2012-06-22T10:41:04.035Z,1340361664.035 [NAL9601](INFO): Packets left to send: 3
2012-06-22T10:41:04.036Z,1340361664.036 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0005.lzma.parts/0003.sbd
2012-06-22T10:41:14.042Z,1340361674.042 [NAL9601](INFO): SBD MO Status=1, MOMSN=10715, MT Status=0, MTMSN=0
2012-06-22T10:41:14.219Z,1340361674.219 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0005.lzma
2012-06-22T10:41:14.219Z,1340361674.219 [NAL9601](INFO): Packets left to send: 2
2012-06-22T10:41:14.220Z,1340361674.220 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0005.lzma.parts/0002.sbd
2012-06-22T10:41:21.844Z,1340361681.844 [NAL9601](INFO): SBD MO Status=1, MOMSN=10716, MT Status=0, MTMSN=0
2012-06-22T10:41:22.007Z,1340361682.007 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0005.lzma
2012-06-22T10:41:22.007Z,1340361682.007 [NAL9601](INFO): Packets left to send: 1
2012-06-22T10:41:22.008Z,1340361682.008 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0005.lzma.parts/0001.sbd
2012-06-22T10:41:27.518Z,1340361687.518 [NAL9601](INFO): SBD MO Status=1, MOMSN=10717, MT Status=0, MTMSN=0
2012-06-22T10:41:27.699Z,1340361687.699 [NAL9601](INFO): Sent 19 bytes from file Logs/20120622T080505/shore0005.lzma
2012-06-22T10:41:27.699Z,1340361687.699 [NAL9601](INFO): Packets left to send: 0
2012-06-22T10:41:27.700Z,1340361687.700 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0005.lzma.parts/0000.sbd
2012-06-22T10:41:35.932Z,1340361695.932 [NAL9601](INFO): SBD MO Status=0, MOMSN=10718, MT Status=0, MTMSN=0
2012-06-22T10:41:36.098Z,1340361696.098 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Stopped
2012-06-22T10:41:36.098Z,1340361696.098 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Running Loop=1
2012-06-22T10:41:38.338Z,1340361698.338 [NAL9601](IMPORTANT): GPS fix at: 1340361930.00
2012-06-22T10:41:38.369Z,1340361698.369 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Stopped
2012-06-22T10:41:38.369Z,1340361698.369 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS:B
2012-06-22T10:41:38.369Z,1340361698.369 [triangle_control_speed_onelap:SURFACECOMMS:B] Stopped
2012-06-22T10:41:38.370Z,1340361698.369 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS:B
2012-06-22T10:41:38.370Z,1340361698.370 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Stopped
2012-06-22T10:41:38.370Z,1340361698.370 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T10:41:38.375Z,1340361698.375 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS
2012-06-22T10:41:38.375Z,1340361698.375 [triangle_control_speed_onelap:SURFACECOMMS] Stopped
2012-06-22T10:41:38.375Z,1340361698.375 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS
2012-06-22T10:41:38.375Z,1340361698.375 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Stopped
2012-06-22T10:41:38.375Z,1340361698.375 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T10:41:38.771Z,1340361698.771 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:PHONEHOMEWPT1](INFO): Completed triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:PHONEHOMEWPT1
2012-06-22T10:41:38.771Z,1340361698.772 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:PHONEHOMEWPT1] Stopped
2012-06-22T10:41:38.772Z,1340361698.772 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:PHONEHOMEWPT1](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:PHONEHOMEWPT1
2012-06-22T10:41:38.773Z,1340361698.773 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3](INFO): Completed triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3
2012-06-22T10:41:38.773Z,1340361698.773 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3] Stopped
2012-06-22T10:41:38.773Z,1340361698.773 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3
2012-06-22T10:41:38.773Z,1340361698.773 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:A.SetSpeed] Stopped
2012-06-22T10:41:38.773Z,1340361698.773 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T10:41:38.773Z,1340361698.773 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:B.Pitch] Stopped
2012-06-22T10:41:38.773Z,1340361698.773 [triangle_control_speed_onelap:ThreeLegs:TestDrive](INFO): Completed triangle_control_speed_onelap:ThreeLegs:TestDrive
2012-06-22T10:41:38.773Z,1340361698.773 [triangle_control_speed_onelap:ThreeLegs:TestDrive] Stopped
2012-06-22T10:41:38.773Z,1340361698.773 [triangle_control_speed_onelap:ThreeLegs:TestDrive](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:TestDrive
2012-06-22T10:41:38.777Z,1340361698.777 [triangle_control_speed_onelap:ThreeLegs](INFO): Completed triangle_control_speed_onelap:ThreeLegs
2012-06-22T10:41:38.777Z,1340361698.777 [triangle_control_speed_onelap:ThreeLegs] Stopped
2012-06-22T10:41:38.777Z,1340361698.777 [triangle_control_speed_onelap:ThreeLegs](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs
2012-06-22T10:41:38.777Z,1340361698.777 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold] Stopped
2012-06-22T10:41:38.777Z,1340361698.777 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:BuoyancyHold
2012-06-22T10:41:38.778Z,1340361698.778 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold:A.Buoyancy] Stopped
2012-06-22T10:41:38.778Z,1340361698.778 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2012-06-22T10:41:38.778Z,1340361698.778 [triangle_control_speed_onelap:ThreeLegs:MassHold] Stopped
2012-06-22T10:41:38.778Z,1340361698.778 [triangle_control_speed_onelap:ThreeLegs:MassHold](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:MassHold
2012-06-22T10:41:38.778Z,1340361698.778 [triangle_control_speed_onelap:ThreeLegs:MassHold:A.Pitch] Stopped
2012-06-22T10:41:38.778Z,1340361698.778 [triangle_control_speed_onelap:ThreeLegs:ElevatorHold] Stopped
2012-06-22T10:41:38.778Z,1340361698.778 [triangle_control_speed_onelap:ThreeLegs:ElevatorHold](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:ElevatorHold
2012-06-22T10:41:38.778Z,1340361698.778 [triangle_control_speed_onelap:ThreeLegs:ElevatorHold:A.Pitch] Stopped
2012-06-22T10:41:38.779Z,1340361698.779 [triangle_control_speed_onelap:ThreeLegs:D] Stopped
2012-06-22T10:41:38.779Z,1340361698.779 [triangle_control_speed_onelap:ThreeLegs:E] Stopped
2012-06-22T10:41:38.779Z,1340361698.779 [triangle_control_speed_onelap:ThreeLegs:F] Stopped
2012-06-22T10:41:38.779Z,1340361698.779 [triangle_control_speed_onelap:ThreeLegs:G] Stopped
2012-06-22T10:41:38.779Z,1340361698.779 [triangle_control_speed_onelap:ThreeLegs:H] Stopped
2012-06-22T10:41:38.779Z,1340361698.779 [triangle_control_speed_onelap:ThreeLegs:I] Stopped
2012-06-22T10:41:38.779Z,1340361698.779 [triangle_control_speed_onelap:ThreeLegs:J] Stopped
2012-06-22T10:41:38.779Z,1340361698.779 [triangle_control_speed_onelap:ThreeLegs:K] Stopped
2012-06-22T10:41:38.779Z,1340361698.779 [triangle_control_speed_onelap:ThreeLegs:L] Stopped
2012-06-22T10:41:38.780Z,1340361698.780 [triangle_control_speed_onelap](INFO): Completed triangle_control_speed_onelap
2012-06-22T10:41:38.780Z,1340361698.780 [triangle_control_speed_onelap] Stopped
2012-06-22T10:41:38.781Z,1340361698.781 [triangle_control_speed_onelap](INFO): Aggregate::uninitialize triangle_control_speed_onelap
2012-06-22T10:41:38.781Z,1340361698.781 [triangle_control_speed_onelap:A.AltitudeEnvelope] Stopped
2012-06-22T10:41:38.781Z,1340361698.781 [triangle_control_speed_onelap:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2012-06-22T10:41:38.781Z,1340361698.781 [triangle_control_speed_onelap:B.DepthEnvelope] Stopped
2012-06-22T10:41:38.781Z,1340361698.781 [triangle_control_speed_onelap:B.DepthEnvelope](DEBUG): Uninitialize.
2012-06-22T10:41:38.781Z,1340361698.781 [triangle_control_speed_onelap:C.OffshoreEnvelope] Stopped
2012-06-22T10:41:38.781Z,1340361698.781 [triangle_control_speed_onelap:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2012-06-22T10:41:38.781Z,1340361698.781 [triangle_control_speed_onelap:D] Stopped
2012-06-22T10:41:39.131Z,1340361699.131 [MissionManager](IMPORTANT): Started mission Default
2012-06-22T10:41:39.131Z,1340361699.131 [Default] Running Loop=1
2012-06-22T10:41:39.131Z,1340361699.131 [Default](INFO): Aggregate::initialize Default
2012-06-22T10:41:39.131Z,1340361699.131 [Default:D.SetSpeed] Running Loop=1
2012-06-22T10:41:39.131Z,1340361699.131 [Default:D.SetSpeed](DEBUG): Initialize.
2012-06-22T10:41:39.131Z,1340361699.131 [Default:E.GoToSurface] Running Loop=1
2012-06-22T10:41:39.131Z,1340361699.131 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T10:41:39.132Z,1340361699.132 [Default:Iridium] Running Loop=1
2012-06-22T10:41:39.132Z,1340361699.132 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-06-22T10:41:39.132Z,1340361699.132 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-06-22T10:41:39.132Z,1340361699.132 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-06-22T10:41:39.132Z,1340361699.132 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-06-22T10:41:39.132Z,1340361699.132 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T10:41:39.134Z,1340361699.134 [Default:Iridium:B.GoToSurface] Stopped
2012-06-22T10:41:39.134Z,1340361699.134 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T10:41:39.134Z,1340361699.134 [Default:Iridium:Read_Iridium] Running Loop=1
2012-06-22T10:41:51.073Z,1340361711.073 [NAL9601](INFO): SBD MO Status=1, MOMSN=10719, MT Status=0, MTMSN=0
2012-06-22T10:41:51.259Z,1340361711.259 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0006.lzma
2012-06-22T10:41:51.259Z,1340361711.259 [NAL9601](INFO): Packets left to send: 1
2012-06-22T10:41:51.261Z,1340361711.261 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0006.lzma.parts/0001.sbd
2012-06-22T10:41:57.568Z,1340361717.568 [NAL9601](INFO): SBD MO Status=1, MOMSN=10720, MT Status=0, MTMSN=0
2012-06-22T10:41:57.751Z,1340361717.751 [NAL9601](INFO): Sent 70 bytes from file Logs/20120622T080505/shore0006.lzma
2012-06-22T10:41:57.751Z,1340361717.751 [NAL9601](INFO): Packets left to send: 0
2012-06-22T10:41:57.770Z,1340361717.770 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0006.lzma.parts/0000.sbd
2012-06-22T10:42:02.354Z,1340361722.354 [NAL9601](INFO): SBD MO Status=0, MOMSN=10721, MT Status=0, MTMSN=0
2012-06-22T10:42:02.557Z,1340361722.557 [Default:Iridium:Read_Iridium] Stopped
2012-06-22T10:42:02.558Z,1340361722.558 [Default:Iridium](INFO): Completed Default:Iridium
2012-06-22T10:42:02.558Z,1340361722.558 [Default:Iridium] Stopped
2012-06-22T10:42:02.558Z,1340361722.558 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-06-22T10:42:02.558Z,1340361722.558 [Default:Iridium:A.SetSpeed] Stopped
2012-06-22T10:42:02.558Z,1340361722.558 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T10:42:02.558Z,1340361722.558 [Default:F.Wait] Running Loop=1
2012-06-22T10:42:02.558Z,1340361722.559 [Default:F.Wait](DEBUG): Initialize Wait Component.
2012-06-22T10:42:12.896Z,1340361732.897 [NAL9601](INFO): Powering down
2012-06-22T10:47:02.947Z,1340362022.947 [Default:CallIridium] Running Loop=1
2012-06-22T10:47:02.947Z,1340362022.947 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-06-22T10:47:02.947Z,1340362022.947 [Default:CallIridium:A] Running Loop=1
2012-06-22T10:47:02.948Z,1340362022.948 [Default:CallIridium:A] Stopped
2012-06-22T10:47:02.948Z,1340362022.948 [Default:CallIridium:B] Running Loop=1
2012-06-22T10:47:02.948Z,1340362022.948 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-06-22T10:47:07.900Z,1340362027.900 [Default:Iridium] Running Loop=1
2012-06-22T10:47:07.900Z,1340362027.900 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-06-22T10:47:07.900Z,1340362027.900 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-06-22T10:47:07.900Z,1340362027.900 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-06-22T10:47:07.900Z,1340362027.900 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-06-22T10:47:07.900Z,1340362027.900 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T10:47:07.901Z,1340362027.901 [Default:Iridium:B.GoToSurface] Stopped
2012-06-22T10:47:07.901Z,1340362027.901 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T10:47:07.901Z,1340362027.901 [Default:Iridium:Read_Iridium] Running Loop=1
2012-06-22T10:47:07.902Z,1340362027.902 [Default:GPS] Running Loop=1
2012-06-22T10:47:07.902Z,1340362027.902 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-06-22T10:47:07.902Z,1340362027.902 [Default:GPS:A.SetSpeed] Running Loop=1
2012-06-22T10:47:07.902Z,1340362027.902 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-06-22T10:47:07.902Z,1340362027.902 [Default:GPS:B.GoToSurface] Running Loop=1
2012-06-22T10:47:07.902Z,1340362027.902 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T10:47:07.903Z,1340362027.903 [Default:GPS:B.GoToSurface] Stopped
2012-06-22T10:47:07.903Z,1340362027.903 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T10:47:07.903Z,1340362027.903 [Default:GPS:Read_GPS] Running Loop=1
2012-06-22T10:47:08.556Z,1340362028.556 [NAL9601](INFO): Powering up
2012-06-22T10:48:14.279Z,1340362094.279 [NAL9601](INFO): NAL9601 initialized
2012-06-22T10:48:15.351Z,1340362095.351 [NAL9601](IMPORTANT): GPS fix at: 1340362327.00
2012-06-22T10:48:15.380Z,1340362095.379 [Default:GPS:Read_GPS] Stopped
2012-06-22T10:48:15.380Z,1340362095.380 [Default:GPS:D] Running Loop=1
2012-06-22T10:48:15.799Z,1340362095.799 [Default:GPS:D] Stopped
2012-06-22T10:48:15.799Z,1340362095.799 [Default:GPS](INFO): Completed Default:GPS
2012-06-22T10:48:15.799Z,1340362095.799 [Default:GPS] Stopped
2012-06-22T10:48:15.799Z,1340362095.799 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-06-22T10:48:15.799Z,1340362095.799 [Default:GPS:A.SetSpeed] Stopped
2012-06-22T10:48:15.799Z,1340362095.799 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T10:48:29.697Z,1340362109.697 [NAL9601](INFO): SBD MO Status=1, MOMSN=10722, MT Status=0, MTMSN=0
2012-06-22T10:48:29.843Z,1340362109.843 [NAL9601](INFO): Sent 146 bytes from file Logs/20120622T080505/shore0007.lzma
2012-06-22T10:48:29.843Z,1340362109.843 [NAL9601](INFO): Packets left to send: 0
2012-06-22T10:48:29.845Z,1340362109.845 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0007.lzma.parts/0000.sbd
2012-06-22T10:48:37.641Z,1340362117.641 [NAL9601](INFO): SBD MO Status=0, MOMSN=10723, MT Status=0, MTMSN=0
2012-06-22T10:48:37.846Z,1340362117.846 [Default:Iridium:Read_Iridium] Stopped
2012-06-22T10:48:37.846Z,1340362117.846 [Default:Iridium](INFO): Completed Default:Iridium
2012-06-22T10:48:37.846Z,1340362117.846 [Default:Iridium] Stopped
2012-06-22T10:48:37.846Z,1340362117.846 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-06-22T10:48:37.846Z,1340362117.846 [Default:Iridium:A.SetSpeed] Stopped
2012-06-22T10:48:37.846Z,1340362117.846 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T10:48:38.048Z,1340362118.048 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2012-06-22T10:48:38.048Z,1340362118.048 [Default:CallIridium:B] Stopped
2012-06-22T10:48:38.048Z,1340362118.048 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-06-22T10:48:38.048Z,1340362118.048 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-06-22T10:48:38.048Z,1340362118.048 [Default:CallIridium] Stopped
2012-06-22T10:48:38.049Z,1340362118.049 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-06-22T10:48:48.154Z,1340362128.154 [NAL9601](INFO): Powering down
2012-06-22T10:50:13.222Z,1340362213.222 [CBIT](IMPORTANT): Beginning GF scan
2012-06-22T10:50:48.187Z,1340362248.187 [CBIT](INFO): No ground fault detected
2012-06-22T10:53:38.245Z,1340362418.245 [Default:CallIridium] Running Loop=1
2012-06-22T10:53:38.245Z,1340362418.245 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-06-22T10:53:38.245Z,1340362418.246 [Default:CallIridium:A] Running Loop=1
2012-06-22T10:53:38.246Z,1340362418.246 [Default:CallIridium:A] Stopped
2012-06-22T10:53:38.246Z,1340362418.246 [Default:CallIridium:B] Running Loop=1
2012-06-22T10:53:38.246Z,1340362418.246 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-06-22T10:53:43.206Z,1340362423.206 [Default:Iridium] Running Loop=1
2012-06-22T10:53:43.206Z,1340362423.206 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-06-22T10:53:43.206Z,1340362423.207 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-06-22T10:53:43.207Z,1340362423.207 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-06-22T10:53:43.207Z,1340362423.207 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-06-22T10:53:43.207Z,1340362423.207 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T10:53:43.208Z,1340362423.208 [Default:Iridium:B.GoToSurface] Stopped
2012-06-22T10:53:43.208Z,1340362423.208 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T10:53:43.208Z,1340362423.208 [Default:Iridium:Read_Iridium] Running Loop=1
2012-06-22T10:53:43.208Z,1340362423.208 [Default:GPS] Running Loop=1
2012-06-22T10:53:43.208Z,1340362423.208 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-06-22T10:53:43.208Z,1340362423.208 [Default:GPS:A.SetSpeed] Running Loop=1
2012-06-22T10:53:43.208Z,1340362423.208 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-06-22T10:53:43.209Z,1340362423.209 [Default:GPS:B.GoToSurface] Running Loop=1
2012-06-22T10:53:43.209Z,1340362423.209 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T10:53:43.209Z,1340362423.209 [Default:GPS:B.GoToSurface] Stopped
2012-06-22T10:53:43.209Z,1340362423.210 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T10:53:43.210Z,1340362423.210 [Default:GPS:Read_GPS] Running Loop=1
2012-06-22T10:53:43.850Z,1340362423.851 [NAL9601](INFO): Powering up
2012-06-22T10:54:49.475Z,1340362489.475 [NAL9601](INFO): NAL9601 initialized
2012-06-22T10:54:50.638Z,1340362490.638 [NAL9601](IMPORTANT): GPS fix at: 1340362730.00
2012-06-22T10:54:50.654Z,1340362490.654 [Default:GPS:Read_GPS] Stopped
2012-06-22T10:54:50.654Z,1340362490.654 [Default:GPS:D] Running Loop=1
2012-06-22T10:54:51.047Z,1340362491.047 [Default:GPS:D] Stopped
2012-06-22T10:54:51.047Z,1340362491.047 [Default:GPS](INFO): Completed Default:GPS
2012-06-22T10:54:51.047Z,1340362491.047 [Default:GPS] Stopped
2012-06-22T10:54:51.048Z,1340362491.048 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-06-22T10:54:51.048Z,1340362491.048 [Default:GPS:A.SetSpeed] Stopped
2012-06-22T10:54:51.048Z,1340362491.048 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T10:56:46.817Z,1340362606.817 [NAL9601](INFO): SBD MO Status=1, MOMSN=10724, MT Status=0, MTMSN=0
2012-06-22T10:56:46.979Z,1340362606.979 [NAL9601](INFO): Sent 196 bytes from file Logs/20120622T080505/shore0008.lzma
2012-06-22T10:56:46.979Z,1340362606.979 [NAL9601](INFO): Packets left to send: 0
2012-06-22T10:56:46.981Z,1340362606.981 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0008.lzma.parts/0000.sbd
2012-06-22T10:56:56.417Z,1340362616.417 [NAL9601](INFO): SBD MO Status=0, MOMSN=10725, MT Status=0, MTMSN=0
2012-06-22T10:56:56.588Z,1340362616.588 [Default:Iridium:Read_Iridium] Stopped
2012-06-22T10:56:56.588Z,1340362616.588 [Default:Iridium](INFO): Completed Default:Iridium
2012-06-22T10:56:56.588Z,1340362616.588 [Default:Iridium] Stopped
2012-06-22T10:56:56.588Z,1340362616.588 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-06-22T10:56:56.588Z,1340362616.588 [Default:Iridium:A.SetSpeed] Stopped
2012-06-22T10:56:56.588Z,1340362616.588 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T10:56:56.833Z,1340362616.833 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2012-06-22T10:56:56.833Z,1340362616.833 [Default:CallIridium:B] Stopped
2012-06-22T10:56:56.833Z,1340362616.833 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-06-22T10:56:56.833Z,1340362616.833 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-06-22T10:56:56.833Z,1340362616.833 [Default:CallIridium] Stopped
2012-06-22T10:56:56.834Z,1340362616.833 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-06-22T10:57:06.968Z,1340362626.968 [NAL9601](INFO): Powering down
2012-06-22T11:01:57.002Z,1340362917.003 [Default:CallIridium] Running Loop=1
2012-06-22T11:01:57.003Z,1340362917.003 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-06-22T11:01:57.003Z,1340362917.003 [Default:CallIridium:A] Running Loop=1
2012-06-22T11:01:57.003Z,1340362917.003 [Default:CallIridium:A] Stopped
2012-06-22T11:01:57.003Z,1340362917.003 [Default:CallIridium:B] Running Loop=1
2012-06-22T11:01:57.004Z,1340362917.003 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-06-22T11:02:02.100Z,1340362922.100 [Default:Iridium] Running Loop=1
2012-06-22T11:02:02.100Z,1340362922.100 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-06-22T11:02:02.100Z,1340362922.100 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-06-22T11:02:02.100Z,1340362922.100 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-06-22T11:02:02.100Z,1340362922.100 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-06-22T11:02:02.100Z,1340362922.100 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T11:02:02.101Z,1340362922.101 [Default:Iridium:B.GoToSurface] Stopped
2012-06-22T11:02:02.101Z,1340362922.101 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T11:02:02.101Z,1340362922.101 [Default:Iridium:Read_Iridium] Running Loop=1
2012-06-22T11:02:02.102Z,1340362922.102 [Default:GPS] Running Loop=1
2012-06-22T11:02:02.102Z,1340362922.102 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-06-22T11:02:02.102Z,1340362922.102 [Default:GPS:A.SetSpeed] Running Loop=1
2012-06-22T11:02:02.102Z,1340362922.102 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-06-22T11:02:02.102Z,1340362922.102 [Default:GPS:B.GoToSurface] Running Loop=1
2012-06-22T11:02:02.102Z,1340362922.102 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T11:02:02.103Z,1340362922.103 [Default:GPS:B.GoToSurface] Stopped
2012-06-22T11:02:02.103Z,1340362922.103 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T11:02:02.103Z,1340362922.103 [Default:GPS:Read_GPS] Running Loop=1
2012-06-22T11:02:02.624Z,1340362922.624 [NAL9601](INFO): Powering up
2012-06-22T11:03:08.347Z,1340362988.347 [NAL9601](INFO): NAL9601 initialized
2012-06-22T11:03:09.418Z,1340362989.418 [NAL9601](IMPORTANT): GPS fix at: 1340363223.00
2012-06-22T11:03:09.434Z,1340362989.434 [Default:GPS:Read_GPS] Stopped
2012-06-22T11:03:09.434Z,1340362989.434 [Default:GPS:D] Running Loop=1
2012-06-22T11:03:09.831Z,1340362989.831 [Default:GPS:D] Stopped
2012-06-22T11:03:09.831Z,1340362989.832 [Default:GPS](INFO): Completed Default:GPS
2012-06-22T11:03:09.832Z,1340362989.832 [Default:GPS] Stopped
2012-06-22T11:03:09.832Z,1340362989.832 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-06-22T11:03:09.832Z,1340362989.832 [Default:GPS:A.SetSpeed] Stopped
2012-06-22T11:03:09.832Z,1340362989.832 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T11:03:23.752Z,1340363003.752 [NAL9601](INFO): SBD MO Status=1, MOMSN=10726, MT Status=0, MTMSN=0
2012-06-22T11:03:23.911Z,1340363003.911 [NAL9601](INFO): Sent 144 bytes from file Logs/20120622T080505/shore0009.lzma
2012-06-22T11:03:23.911Z,1340363003.911 [NAL9601](INFO): Packets left to send: 0
2012-06-22T11:03:23.913Z,1340363003.913 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0009.lzma.parts/0000.sbd
2012-06-22T11:03:33.313Z,1340363013.313 [NAL9601](INFO): SBD MO Status=0, MOMSN=10727, MT Status=0, MTMSN=0
2012-06-22T11:03:33.514Z,1340363013.514 [Default:Iridium:Read_Iridium] Stopped
2012-06-22T11:03:33.514Z,1340363013.514 [Default:Iridium](INFO): Completed Default:Iridium
2012-06-22T11:03:33.515Z,1340363013.514 [Default:Iridium] Stopped
2012-06-22T11:03:33.515Z,1340363013.515 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-06-22T11:03:33.515Z,1340363013.515 [Default:Iridium:A.SetSpeed] Stopped
2012-06-22T11:03:33.515Z,1340363013.515 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T11:03:33.787Z,1340363013.787 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2012-06-22T11:03:33.787Z,1340363013.787 [Default:CallIridium:B] Stopped
2012-06-22T11:03:33.787Z,1340363013.787 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-06-22T11:03:33.787Z,1340363013.787 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-06-22T11:03:33.787Z,1340363013.787 [Default:CallIridium] Stopped
2012-06-22T11:03:33.787Z,1340363013.787 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-06-22T11:03:43.869Z,1340363023.869 [NAL9601](INFO): Powering down
2012-06-22T11:08:33.889Z,1340363313.889 [Default:CallIridium] Running Loop=1
2012-06-22T11:08:33.889Z,1340363313.889 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-06-22T11:08:33.889Z,1340363313.889 [Default:CallIridium:A] Running Loop=1
2012-06-22T11:08:33.890Z,1340363313.890 [Default:CallIridium:A] Stopped
2012-06-22T11:08:33.890Z,1340363313.890 [Default:CallIridium:B] Running Loop=1
2012-06-22T11:08:33.890Z,1340363313.890 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-06-22T11:08:39.142Z,1340363319.142 [Default:Iridium] Running Loop=1
2012-06-22T11:08:39.142Z,1340363319.142 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-06-22T11:08:39.142Z,1340363319.142 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-06-22T11:08:39.142Z,1340363319.142 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-06-22T11:08:39.142Z,1340363319.142 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-06-22T11:08:39.143Z,1340363319.143 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T11:08:39.143Z,1340363319.143 [Default:Iridium:B.GoToSurface] Stopped
2012-06-22T11:08:39.143Z,1340363319.143 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T11:08:39.144Z,1340363319.144 [Default:Iridium:Read_Iridium] Running Loop=1
2012-06-22T11:08:39.144Z,1340363319.144 [Default:GPS] Running Loop=1
2012-06-22T11:08:39.144Z,1340363319.144 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-06-22T11:08:39.144Z,1340363319.144 [Default:GPS:A.SetSpeed] Running Loop=1
2012-06-22T11:08:39.144Z,1340363319.144 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-06-22T11:08:39.144Z,1340363319.144 [Default:GPS:B.GoToSurface] Running Loop=1
2012-06-22T11:08:39.144Z,1340363319.144 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T11:08:39.145Z,1340363319.145 [Default:GPS:B.GoToSurface] Stopped
2012-06-22T11:08:39.145Z,1340363319.145 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T11:08:39.145Z,1340363319.145 [Default:GPS:Read_GPS] Running Loop=1
2012-06-22T11:08:39.736Z,1340363319.736 [NAL9601](INFO): Powering up
2012-06-22T11:09:45.463Z,1340363385.463 [NAL9601](INFO): NAL9601 initialized
2012-06-22T11:10:03.268Z,1340363403.268 [NAL9601](INFO): SBD MO Status=1, MOMSN=10728, MT Status=0, MTMSN=0
2012-06-22T11:10:03.423Z,1340363403.423 [NAL9601](INFO): Sent 107 bytes from file Logs/20120622T080505/shore0010.lzma
2012-06-22T11:10:03.423Z,1340363403.423 [NAL9601](INFO): Packets left to send: 0
2012-06-22T11:10:03.425Z,1340363403.425 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0010.lzma.parts/0000.sbd
2012-06-22T11:10:11.278Z,1340363411.278 [NAL9601](INFO): SBD MO Status=0, MOMSN=10729, MT Status=0, MTMSN=0
2012-06-22T11:10:11.426Z,1340363411.426 [Default:Iridium:Read_Iridium] Stopped
2012-06-22T11:10:11.427Z,1340363411.427 [Default:Iridium](INFO): Completed Default:Iridium
2012-06-22T11:10:11.427Z,1340363411.427 [Default:Iridium] Stopped
2012-06-22T11:10:11.427Z,1340363411.427 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-06-22T11:10:11.427Z,1340363411.427 [Default:Iridium:A.SetSpeed] Stopped
2012-06-22T11:10:11.427Z,1340363411.427 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T11:10:11.687Z,1340363411.687 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2012-06-22T11:10:11.687Z,1340363411.687 [Default:CallIridium:B] Stopped
2012-06-22T11:10:11.687Z,1340363411.687 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-06-22T11:10:11.687Z,1340363411.687 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-06-22T11:10:11.687Z,1340363411.687 [Default:CallIridium] Stopped
2012-06-22T11:10:11.687Z,1340363411.687 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-06-22T11:12:04.455Z,1340363524.455 [NAL9601](IMPORTANT): GPS fix at: 1340363759.00
2012-06-22T11:12:04.471Z,1340363524.471 [Default:GPS:Read_GPS] Stopped
2012-06-22T11:12:04.471Z,1340363524.471 [Default:GPS:D] Running Loop=1
2012-06-22T11:12:04.863Z,1340363524.863 [Default:GPS:D] Stopped
2012-06-22T11:12:04.863Z,1340363524.863 [Default:GPS](INFO): Completed Default:GPS
2012-06-22T11:12:04.863Z,1340363524.863 [Default:GPS] Stopped
2012-06-22T11:12:04.863Z,1340363524.863 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-06-22T11:12:04.863Z,1340363524.864 [Default:GPS:A.SetSpeed] Stopped
2012-06-22T11:12:04.864Z,1340363524.864 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T11:12:20.394Z,1340363540.394 [NAL9601](INFO): Powering down
2012-06-22T11:15:15.444Z,1340363715.444 [Default:CallIridium] Running Loop=1
2012-06-22T11:15:15.444Z,1340363715.444 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-06-22T11:15:15.444Z,1340363715.444 [Default:CallIridium:A] Running Loop=1
2012-06-22T11:15:15.444Z,1340363715.444 [Default:CallIridium:A] Stopped
2012-06-22T11:15:15.445Z,1340363715.445 [Default:CallIridium:B] Running Loop=1
2012-06-22T11:15:15.445Z,1340363715.445 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-06-22T11:15:20.452Z,1340363720.452 [Default:Iridium] Running Loop=1
2012-06-22T11:15:20.452Z,1340363720.452 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-06-22T11:15:20.452Z,1340363720.452 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-06-22T11:15:20.452Z,1340363720.452 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-06-22T11:15:20.453Z,1340363720.453 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-06-22T11:15:20.453Z,1340363720.453 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T11:15:20.453Z,1340363720.453 [Default:Iridium:B.GoToSurface] Stopped
2012-06-22T11:15:20.454Z,1340363720.454 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T11:15:20.454Z,1340363720.454 [Default:Iridium:Read_Iridium] Running Loop=1
2012-06-22T11:15:20.454Z,1340363720.454 [Default:GPS] Running Loop=1
2012-06-22T11:15:20.454Z,1340363720.454 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-06-22T11:15:20.454Z,1340363720.454 [Default:GPS:A.SetSpeed] Running Loop=1
2012-06-22T11:15:20.454Z,1340363720.454 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-06-22T11:15:20.454Z,1340363720.454 [Default:GPS:B.GoToSurface] Running Loop=1
2012-06-22T11:15:20.455Z,1340363720.455 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T11:15:20.455Z,1340363720.455 [Default:GPS:B.GoToSurface] Stopped
2012-06-22T11:15:20.455Z,1340363720.455 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T11:15:20.456Z,1340363720.455 [Default:GPS:Read_GPS] Running Loop=1
2012-06-22T11:15:21.052Z,1340363721.052 [NAL9601](INFO): Powering up
2012-06-22T11:16:26.775Z,1340363786.775 [NAL9601](INFO): NAL9601 initialized
2012-06-22T11:16:52.162Z,1340363812.162 [NAL9601](INFO): SBD MO Status=1, MOMSN=10730, MT Status=0, MTMSN=0
2012-06-22T11:16:52.327Z,1340363812.327 [NAL9601](INFO): Sent 137 bytes from file Logs/20120622T080505/shore0011.lzma
2012-06-22T11:16:52.327Z,1340363812.327 [NAL9601](INFO): Packets left to send: 0
2012-06-22T11:16:52.329Z,1340363812.329 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0011.lzma.parts/0000.sbd
2012-06-22T11:17:01.361Z,1340363821.361 [NAL9601](INFO): SBD MO Status=0, MOMSN=10731, MT Status=0, MTMSN=0
2012-06-22T11:17:01.526Z,1340363821.526 [Default:Iridium:Read_Iridium] Stopped
2012-06-22T11:17:01.526Z,1340363821.526 [Default:Iridium](INFO): Completed Default:Iridium
2012-06-22T11:17:01.526Z,1340363821.526 [Default:Iridium] Stopped
2012-06-22T11:17:01.526Z,1340363821.526 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-06-22T11:17:01.526Z,1340363821.526 [Default:Iridium:A.SetSpeed] Stopped
2012-06-22T11:17:01.526Z,1340363821.526 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T11:17:01.834Z,1340363821.834 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2012-06-22T11:17:01.834Z,1340363821.834 [Default:CallIridium:B] Stopped
2012-06-22T11:17:01.834Z,1340363821.834 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-06-22T11:17:01.835Z,1340363821.835 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-06-22T11:17:01.835Z,1340363821.835 [Default:CallIridium] Stopped
2012-06-22T11:17:01.835Z,1340363821.835 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-06-22T11:18:26.567Z,1340363906.567 [NAL9601](IMPORTANT): GPS fix at: 1340364141.00
2012-06-22T11:18:26.583Z,1340363906.583 [Default:GPS:Read_GPS] Stopped
2012-06-22T11:18:26.584Z,1340363906.583 [Default:GPS:D] Running Loop=1
2012-06-22T11:18:26.984Z,1340363906.984 [Default:GPS:D] Stopped
2012-06-22T11:18:26.985Z,1340363906.985 [Default:GPS](INFO): Completed Default:GPS
2012-06-22T11:18:26.985Z,1340363906.985 [Default:GPS] Stopped
2012-06-22T11:18:26.985Z,1340363906.985 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-06-22T11:18:26.985Z,1340363906.985 [Default:GPS:A.SetSpeed] Stopped
2012-06-22T11:18:26.985Z,1340363906.985 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T11:18:42.510Z,1340363922.510 [NAL9601](INFO): Powering down
2012-06-22T11:22:02.708Z,1340364122.708 [Default:CallIridium] Running Loop=1
2012-06-22T11:22:02.708Z,1340364122.708 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-06-22T11:22:02.708Z,1340364122.708 [Default:CallIridium:A] Running Loop=1
2012-06-22T11:22:02.708Z,1340364122.708 [Default:CallIridium:A] Stopped
2012-06-22T11:22:02.709Z,1340364122.709 [Default:CallIridium:B] Running Loop=1
2012-06-22T11:22:02.709Z,1340364122.709 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-06-22T11:22:07.467Z,1340364127.467 [Default:Iridium] Running Loop=1
2012-06-22T11:22:07.467Z,1340364127.467 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-06-22T11:22:07.467Z,1340364127.467 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-06-22T11:22:07.467Z,1340364127.467 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-06-22T11:22:07.467Z,1340364127.467 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-06-22T11:22:07.467Z,1340364127.467 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T11:22:07.468Z,1340364127.468 [Default:Iridium:B.GoToSurface] Stopped
2012-06-22T11:22:07.468Z,1340364127.468 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T11:22:07.468Z,1340364127.468 [Default:Iridium:Read_Iridium] Running Loop=1
2012-06-22T11:22:07.469Z,1340364127.469 [Default:GPS] Running Loop=1
2012-06-22T11:22:07.469Z,1340364127.469 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-06-22T11:22:07.469Z,1340364127.469 [Default:GPS:A.SetSpeed] Running Loop=1
2012-06-22T11:22:07.469Z,1340364127.469 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-06-22T11:22:07.469Z,1340364127.469 [Default:GPS:B.GoToSurface] Running Loop=1
2012-06-22T11:22:07.469Z,1340364127.469 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T11:22:07.470Z,1340364127.470 [Default:GPS:B.GoToSurface] Stopped
2012-06-22T11:22:07.470Z,1340364127.470 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T11:22:07.470Z,1340364127.470 [Default:GPS:Read_GPS] Running Loop=1
2012-06-22T11:22:08.178Z,1340364128.178 [NAL9601](INFO): Powering up
2012-06-22T11:23:13.803Z,1340364193.803 [NAL9601](INFO): NAL9601 initialized
2012-06-22T11:23:27.553Z,1340364207.553 [NAL9601](INFO): SBD MO Status=1, MOMSN=10732, MT Status=0, MTMSN=0
2012-06-22T11:23:27.771Z,1340364207.771 [NAL9601](INFO): Sent 137 bytes from file Logs/20120622T080505/shore0012.lzma
2012-06-22T11:23:27.771Z,1340364207.771 [NAL9601](INFO): Packets left to send: 0
2012-06-22T11:23:27.773Z,1340364207.773 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0012.lzma.parts/0000.sbd
2012-06-22T11:23:34.353Z,1340364214.353 [NAL9601](INFO): SBD MO Status=0, MOMSN=10733, MT Status=0, MTMSN=0
2012-06-22T11:23:34.601Z,1340364214.601 [Default:Iridium:Read_Iridium] Stopped
2012-06-22T11:23:34.601Z,1340364214.601 [Default:Iridium](INFO): Completed Default:Iridium
2012-06-22T11:23:34.601Z,1340364214.601 [Default:Iridium] Stopped
2012-06-22T11:23:34.601Z,1340364214.602 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-06-22T11:23:34.602Z,1340364214.602 [Default:Iridium:A.SetSpeed] Stopped
2012-06-22T11:23:34.602Z,1340364214.602 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T11:23:34.810Z,1340364214.810 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2012-06-22T11:23:34.810Z,1340364214.810 [Default:CallIridium:B] Stopped
2012-06-22T11:23:34.810Z,1340364214.810 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-06-22T11:23:34.810Z,1340364214.810 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-06-22T11:23:34.810Z,1340364214.810 [Default:CallIridium] Stopped
2012-06-22T11:23:34.811Z,1340364214.811 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-06-22T11:26:05.729Z,1340364365.728 [NAL9601](IMPORTANT): GPS fix at: 1340364601.00
2012-06-22T11:26:05.744Z,1340364365.744 [Default:GPS:Read_GPS] Stopped
2012-06-22T11:26:05.744Z,1340364365.744 [Default:GPS:D] Running Loop=1
2012-06-22T11:26:06.155Z,1340364366.155 [Default:GPS:D] Stopped
2012-06-22T11:26:06.155Z,1340364366.155 [Default:GPS](INFO): Completed Default:GPS
2012-06-22T11:26:06.155Z,1340364366.155 [Default:GPS] Stopped
2012-06-22T11:26:06.155Z,1340364366.155 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-06-22T11:26:06.156Z,1340364366.156 [Default:GPS:A.SetSpeed] Stopped
2012-06-22T11:26:06.156Z,1340364366.156 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T11:26:21.704Z,1340364381.704 [NAL9601](INFO): Powering down
2012-06-22T11:28:36.703Z,1340364516.703 [Default:CallIridium] Running Loop=1
2012-06-22T11:28:36.703Z,1340364516.703 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-06-22T11:28:36.703Z,1340364516.703 [Default:CallIridium:A] Running Loop=1
2012-06-22T11:28:36.703Z,1340364516.703 [Default:CallIridium:A] Stopped
2012-06-22T11:28:36.703Z,1340364516.703 [Default:CallIridium:B] Running Loop=1
2012-06-22T11:28:36.704Z,1340364516.704 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-06-22T11:28:41.684Z,1340364521.684 [Default:Iridium] Running Loop=1
2012-06-22T11:28:41.684Z,1340364521.684 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-06-22T11:28:41.684Z,1340364521.684 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-06-22T11:28:41.684Z,1340364521.684 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-06-22T11:28:41.684Z,1340364521.684 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-06-22T11:28:41.684Z,1340364521.684 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T11:28:41.685Z,1340364521.685 [Default:Iridium:B.GoToSurface] Stopped
2012-06-22T11:28:41.685Z,1340364521.685 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T11:28:41.685Z,1340364521.685 [Default:Iridium:Read_Iridium] Running Loop=1
2012-06-22T11:28:41.686Z,1340364521.685 [Default:GPS] Running Loop=1
2012-06-22T11:28:41.686Z,1340364521.686 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-06-22T11:28:41.686Z,1340364521.686 [Default:GPS:A.SetSpeed] Running Loop=1
2012-06-22T11:28:41.686Z,1340364521.686 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-06-22T11:28:41.686Z,1340364521.686 [Default:GPS:B.GoToSurface] Running Loop=1
2012-06-22T11:28:41.686Z,1340364521.686 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T11:28:41.687Z,1340364521.687 [Default:GPS:B.GoToSurface] Stopped
2012-06-22T11:28:41.687Z,1340364521.687 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T11:28:41.687Z,1340364521.687 [Default:GPS:Read_GPS] Running Loop=1
2012-06-22T11:28:42.320Z,1340364522.320 [NAL9601](INFO): Powering up
2012-06-22T11:29:48.043Z,1340364588.043 [NAL9601](INFO): NAL9601 initialized
2012-06-22T11:30:11.010Z,1340364611.010 [NAL9601](IMPORTANT): SBD MO Status=1, MOMSN=10734, MT Status=1, MTMSN=2784
2012-06-22T11:30:11.195Z,1340364611.195 [NAL9601](INFO): Sent 166 bytes from file Logs/20120622T080505/shore0013.lzma
2012-06-22T11:30:11.195Z,1340364611.195 [NAL9601](INFO): Packets left to send: 0
2012-06-22T11:30:11.197Z,1340364611.197 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0013.lzma.parts/0000.sbd
2012-06-22T11:30:11.612Z,1340364611.612 [NAL9601](INFO): Received command:run Transport/keepstation.xml
2012-06-22T11:30:11.633Z,1340364611.633 [CommandLine](IMPORTANT): got command run 
2012-06-22T11:30:11.633Z,1340364611.633 [CommandLine](FAULT): Incomplete syntax. Try: help run
2012-06-22T11:30:18.421Z,1340364618.421 [NAL9601](INFO): SBD MO Status=0, MOMSN=10735, MT Status=0, MTMSN=0
2012-06-22T11:30:37.085Z,1340364637.085 [NAL9601](INFO): SBD MO Status=1, MOMSN=10736, MT Status=0, MTMSN=0
2012-06-22T11:30:37.263Z,1340364637.263 [NAL9601](INFO): Sent 203 bytes from file Logs/20120622T080505/shore0014.lzma
2012-06-22T11:30:37.263Z,1340364637.263 [NAL9601](INFO): Packets left to send: 0
2012-06-22T11:30:37.265Z,1340364637.265 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0014.lzma.parts/0000.sbd
2012-06-22T11:30:44.290Z,1340364644.290 [NAL9601](INFO): SBD MO Status=0, MOMSN=10737, MT Status=0, MTMSN=0
2012-06-22T11:30:44.479Z,1340364644.479 [Default:Iridium:Read_Iridium] Stopped
2012-06-22T11:30:44.479Z,1340364644.479 [Default:Iridium](INFO): Completed Default:Iridium
2012-06-22T11:30:44.479Z,1340364644.480 [Default:Iridium] Stopped
2012-06-22T11:30:44.480Z,1340364644.480 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-06-22T11:30:44.480Z,1340364644.480 [Default:Iridium:A.SetSpeed] Stopped
2012-06-22T11:30:44.480Z,1340364644.480 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T11:30:44.700Z,1340364644.700 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2012-06-22T11:30:44.700Z,1340364644.700 [Default:CallIridium:B] Stopped
2012-06-22T11:30:44.700Z,1340364644.700 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-06-22T11:30:44.700Z,1340364644.700 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-06-22T11:30:44.700Z,1340364644.700 [Default:CallIridium] Stopped
2012-06-22T11:30:44.700Z,1340364644.700 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-06-22T11:30:45.507Z,1340364645.507 [NAL9601](IMPORTANT): GPS fix at: 1340364881.00
2012-06-22T11:30:45.523Z,1340364645.523 [Default:GPS:Read_GPS] Stopped
2012-06-22T11:30:45.523Z,1340364645.523 [Default:GPS:D] Running Loop=1
2012-06-22T11:30:45.903Z,1340364645.903 [Default:GPS:D] Stopped
2012-06-22T11:30:45.904Z,1340364645.904 [Default:GPS](INFO): Completed Default:GPS
2012-06-22T11:30:45.904Z,1340364645.904 [Default:GPS] Stopped
2012-06-22T11:30:45.904Z,1340364645.904 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-06-22T11:30:45.904Z,1340364645.904 [Default:GPS:A.SetSpeed] Stopped
2012-06-22T11:30:45.904Z,1340364645.904 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T11:31:01.453Z,1340364661.453 [NAL9601](INFO): Powering down
2012-06-22T11:35:46.468Z,1340364946.468 [Default:CallIridium] Running Loop=1
2012-06-22T11:35:46.468Z,1340364946.468 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-06-22T11:35:46.468Z,1340364946.468 [Default:CallIridium:A] Running Loop=1
2012-06-22T11:35:46.469Z,1340364946.469 [Default:CallIridium:A] Stopped
2012-06-22T11:35:46.469Z,1340364946.469 [Default:CallIridium:B] Running Loop=1
2012-06-22T11:35:46.469Z,1340364946.469 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-06-22T11:35:51.440Z,1340364951.440 [Default:Iridium] Running Loop=1
2012-06-22T11:35:51.440Z,1340364951.440 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-06-22T11:35:51.440Z,1340364951.440 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-06-22T11:35:51.440Z,1340364951.440 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-06-22T11:35:51.441Z,1340364951.441 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-06-22T11:35:51.441Z,1340364951.441 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T11:35:51.441Z,1340364951.441 [Default:Iridium:B.GoToSurface] Stopped
2012-06-22T11:35:51.441Z,1340364951.441 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T11:35:51.442Z,1340364951.442 [Default:Iridium:Read_Iridium] Running Loop=1
2012-06-22T11:35:51.442Z,1340364951.442 [Default:GPS] Running Loop=1
2012-06-22T11:35:51.442Z,1340364951.443 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-06-22T11:35:51.443Z,1340364951.443 [Default:GPS:A.SetSpeed] Running Loop=1
2012-06-22T11:35:51.443Z,1340364951.443 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-06-22T11:35:51.443Z,1340364951.443 [Default:GPS:B.GoToSurface] Running Loop=1
2012-06-22T11:35:51.443Z,1340364951.443 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T11:35:51.444Z,1340364951.444 [Default:GPS:B.GoToSurface] Stopped
2012-06-22T11:35:51.444Z,1340364951.444 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T11:35:51.444Z,1340364951.444 [Default:GPS:Read_GPS] Running Loop=1
2012-06-22T11:35:52.085Z,1340364952.085 [NAL9601](INFO): Powering up
2012-06-22T11:36:57.811Z,1340365017.811 [NAL9601](INFO): NAL9601 initialized
2012-06-22T11:37:15.961Z,1340365035.961 [NAL9601](INFO): SBD MO Status=1, MOMSN=10738, MT Status=0, MTMSN=0
2012-06-22T11:37:16.171Z,1340365036.171 [NAL9601](INFO): Sent 167 bytes from file Logs/20120622T080505/shore0015.lzma
2012-06-22T11:37:16.171Z,1340365036.171 [NAL9601](INFO): Packets left to send: 0
2012-06-22T11:37:16.173Z,1340365036.173 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0015.lzma.parts/0000.sbd
2012-06-22T11:37:21.150Z,1340365041.150 [NAL9601](INFO): SBD MO Status=0, MOMSN=10739, MT Status=0, MTMSN=0
2012-06-22T11:37:21.383Z,1340365041.383 [Default:Iridium:Read_Iridium] Stopped
2012-06-22T11:37:21.384Z,1340365041.384 [Default:Iridium](INFO): Completed Default:Iridium
2012-06-22T11:37:21.384Z,1340365041.384 [Default:Iridium] Stopped
2012-06-22T11:37:21.384Z,1340365041.384 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-06-22T11:37:21.384Z,1340365041.384 [Default:Iridium:A.SetSpeed] Stopped
2012-06-22T11:37:21.384Z,1340365041.384 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T11:37:21.558Z,1340365041.558 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2012-06-22T11:37:21.558Z,1340365041.558 [Default:CallIridium:B] Stopped
2012-06-22T11:37:21.559Z,1340365041.559 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-06-22T11:37:21.559Z,1340365041.559 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-06-22T11:37:21.559Z,1340365041.559 [Default:CallIridium] Stopped
2012-06-22T11:37:21.559Z,1340365041.559 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-06-22T11:38:48.367Z,1340365128.367 [NAL9601](IMPORTANT): GPS fix at: 1340365365.00
2012-06-22T11:38:48.382Z,1340365128.382 [Default:GPS:Read_GPS] Stopped
2012-06-22T11:38:48.383Z,1340365128.383 [Default:GPS:D] Running Loop=1
2012-06-22T11:38:48.844Z,1340365128.843 [Default:GPS:D] Stopped
2012-06-22T11:38:48.844Z,1340365128.844 [Default:GPS](INFO): Completed Default:GPS
2012-06-22T11:38:48.844Z,1340365128.844 [Default:GPS] Stopped
2012-06-22T11:38:48.844Z,1340365128.844 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-06-22T11:38:48.844Z,1340365128.844 [Default:GPS:A.SetSpeed] Stopped
2012-06-22T11:38:48.844Z,1340365128.844 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T11:39:04.415Z,1340365144.415 [NAL9601](INFO): Powering down
2012-06-22T11:42:24.330Z,1340365344.330 [Default:CallIridium] Running Loop=1
2012-06-22T11:42:24.331Z,1340365344.331 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-06-22T11:42:24.331Z,1340365344.331 [Default:CallIridium:A] Running Loop=1
2012-06-22T11:42:24.331Z,1340365344.331 [Default:CallIridium:A] Stopped
2012-06-22T11:42:24.331Z,1340365344.331 [Default:CallIridium:B] Running Loop=1
2012-06-22T11:42:24.331Z,1340365344.331 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-06-22T11:42:29.347Z,1340365349.347 [Default:Iridium] Running Loop=1
2012-06-22T11:42:29.347Z,1340365349.347 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-06-22T11:42:29.347Z,1340365349.347 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-06-22T11:42:29.347Z,1340365349.347 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-06-22T11:42:29.347Z,1340365349.347 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-06-22T11:42:29.347Z,1340365349.347 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T11:42:29.348Z,1340365349.348 [Default:Iridium:B.GoToSurface] Stopped
2012-06-22T11:42:29.348Z,1340365349.348 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T11:42:29.348Z,1340365349.348 [Default:Iridium:Read_Iridium] Running Loop=1
2012-06-22T11:42:29.349Z,1340365349.349 [Default:GPS] Running Loop=1
2012-06-22T11:42:29.349Z,1340365349.349 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-06-22T11:42:29.349Z,1340365349.349 [Default:GPS:A.SetSpeed] Running Loop=1
2012-06-22T11:42:29.349Z,1340365349.349 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-06-22T11:42:29.349Z,1340365349.349 [Default:GPS:B.GoToSurface] Running Loop=1
2012-06-22T11:42:29.349Z,1340365349.349 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T11:42:29.350Z,1340365349.350 [Default:GPS:B.GoToSurface] Stopped
2012-06-22T11:42:29.350Z,1340365349.350 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T11:42:29.355Z,1340365349.355 [Default:GPS:Read_GPS] Running Loop=1
2012-06-22T11:42:29.952Z,1340365349.952 [NAL9601](INFO): Powering up
2012-06-22T11:43:35.675Z,1340365415.675 [NAL9601](INFO): NAL9601 initialized
2012-06-22T11:43:36.767Z,1340365416.767 [NAL9601](IMPORTANT): GPS fix at: 1340365654.00
2012-06-22T11:43:36.784Z,1340365416.783 [Default:GPS:Read_GPS] Stopped
2012-06-22T11:43:36.784Z,1340365416.784 [Default:GPS:D] Running Loop=1
2012-06-22T11:43:37.164Z,1340365417.164 [Default:GPS:D] Stopped
2012-06-22T11:43:37.164Z,1340365417.164 [Default:GPS](INFO): Completed Default:GPS
2012-06-22T11:43:37.164Z,1340365417.164 [Default:GPS] Stopped
2012-06-22T11:43:37.165Z,1340365417.165 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-06-22T11:43:37.165Z,1340365417.165 [Default:GPS:A.SetSpeed] Stopped
2012-06-22T11:43:37.165Z,1340365417.165 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T11:43:51.054Z,1340365431.054 [NAL9601](INFO): SBD MO Status=1, MOMSN=10740, MT Status=0, MTMSN=0
2012-06-22T11:43:51.239Z,1340365431.239 [NAL9601](INFO): Sent 147 bytes from file Logs/20120622T080505/shore0016.lzma
2012-06-22T11:43:51.239Z,1340365431.239 [NAL9601](INFO): Packets left to send: 0
2012-06-22T11:43:51.241Z,1340365431.241 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0016.lzma.parts/0000.sbd
2012-06-22T11:43:59.850Z,1340365439.850 [NAL9601](INFO): SBD MO Status=0, MOMSN=10741, MT Status=0, MTMSN=0
2012-06-22T11:44:00.055Z,1340365440.055 [Default:Iridium:Read_Iridium] Stopped
2012-06-22T11:44:00.056Z,1340365440.056 [Default:Iridium](INFO): Completed Default:Iridium
2012-06-22T11:44:00.056Z,1340365440.056 [Default:Iridium] Stopped
2012-06-22T11:44:00.056Z,1340365440.056 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-06-22T11:44:00.056Z,1340365440.056 [Default:Iridium:A.SetSpeed] Stopped
2012-06-22T11:44:00.056Z,1340365440.056 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T11:44:00.352Z,1340365440.352 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2012-06-22T11:44:00.352Z,1340365440.352 [Default:CallIridium:B] Stopped
2012-06-22T11:44:00.352Z,1340365440.352 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-06-22T11:44:00.352Z,1340365440.352 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-06-22T11:44:00.352Z,1340365440.352 [Default:CallIridium] Stopped
2012-06-22T11:44:00.352Z,1340365440.352 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-06-22T11:44:10.356Z,1340365450.355 [NAL9601](INFO): Powering down
2012-06-22T11:49:00.423Z,1340365740.423 [Default:CallIridium] Running Loop=1
2012-06-22T11:49:00.423Z,1340365740.423 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-06-22T11:49:00.424Z,1340365740.424 [Default:CallIridium:A] Running Loop=1
2012-06-22T11:49:00.424Z,1340365740.424 [Default:CallIridium:A] Stopped
2012-06-22T11:49:00.424Z,1340365740.424 [Default:CallIridium:B] Running Loop=1
2012-06-22T11:49:00.424Z,1340365740.424 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-06-22T11:49:05.428Z,1340365745.428 [Default:Iridium] Running Loop=1
2012-06-22T11:49:05.428Z,1340365745.428 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-06-22T11:49:05.428Z,1340365745.428 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-06-22T11:49:05.428Z,1340365745.428 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-06-22T11:49:05.428Z,1340365745.428 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-06-22T11:49:05.428Z,1340365745.428 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T11:49:05.429Z,1340365745.429 [Default:Iridium:B.GoToSurface] Stopped
2012-06-22T11:49:05.429Z,1340365745.429 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T11:49:05.429Z,1340365745.429 [Default:Iridium:Read_Iridium] Running Loop=1
2012-06-22T11:49:05.430Z,1340365745.430 [Default:GPS] Running Loop=1
2012-06-22T11:49:05.430Z,1340365745.430 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-06-22T11:49:05.430Z,1340365745.430 [Default:GPS:A.SetSpeed] Running Loop=1
2012-06-22T11:49:05.430Z,1340365745.430 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-06-22T11:49:05.430Z,1340365745.430 [Default:GPS:B.GoToSurface] Running Loop=1
2012-06-22T11:49:05.430Z,1340365745.430 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T11:49:05.431Z,1340365745.431 [Default:GPS:B.GoToSurface] Stopped
2012-06-22T11:49:05.431Z,1340365745.431 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T11:49:05.431Z,1340365745.431 [Default:GPS:Read_GPS] Running Loop=1
2012-06-22T11:49:06.052Z,1340365746.052 [NAL9601](INFO): Powering up
2012-06-22T11:50:11.779Z,1340365811.779 [NAL9601](INFO): NAL9601 initialized
2012-06-22T11:50:36.758Z,1340365836.758 [NAL9601](INFO): SBD MO Status=1, MOMSN=10742, MT Status=0, MTMSN=0
2012-06-22T11:50:36.930Z,1340365836.930 [NAL9601](INFO): Sent 100 bytes from file Logs/20120622T080505/shore0017.lzma
2012-06-22T11:50:36.930Z,1340365836.930 [NAL9601](INFO): Packets left to send: 0
2012-06-22T11:50:36.932Z,1340365836.932 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0017.lzma.parts/0000.sbd
2012-06-22T11:50:41.961Z,1340365841.961 [NAL9601](INFO): SBD MO Status=0, MOMSN=10743, MT Status=0, MTMSN=0
2012-06-22T11:50:42.143Z,1340365842.143 [Default:Iridium:Read_Iridium] Stopped
2012-06-22T11:50:42.144Z,1340365842.144 [Default:Iridium](INFO): Completed Default:Iridium
2012-06-22T11:50:42.144Z,1340365842.144 [Default:Iridium] Stopped
2012-06-22T11:50:42.144Z,1340365842.144 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-06-22T11:50:42.144Z,1340365842.144 [Default:Iridium:A.SetSpeed] Stopped
2012-06-22T11:50:42.144Z,1340365842.144 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T11:50:42.371Z,1340365842.371 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2012-06-22T11:50:42.371Z,1340365842.371 [Default:CallIridium:B] Stopped
2012-06-22T11:50:42.371Z,1340365842.371 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-06-22T11:50:42.371Z,1340365842.371 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-06-22T11:50:42.371Z,1340365842.371 [Default:CallIridium] Stopped
2012-06-22T11:50:42.372Z,1340365842.372 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-06-22T11:51:25.159Z,1340365885.159 [NAL9601](IMPORTANT): GPS fix at: 1340366123.00
2012-06-22T11:51:25.175Z,1340365885.175 [Default:GPS:Read_GPS] Stopped
2012-06-22T11:51:25.175Z,1340365885.175 [Default:GPS:D] Running Loop=1
2012-06-22T11:51:25.572Z,1340365885.572 [Default:GPS:D] Stopped
2012-06-22T11:51:25.572Z,1340365885.572 [Default:GPS](INFO): Completed Default:GPS
2012-06-22T11:51:25.572Z,1340365885.572 [Default:GPS] Stopped
2012-06-22T11:51:25.572Z,1340365885.572 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-06-22T11:51:25.572Z,1340365885.572 [Default:GPS:A.SetSpeed] Stopped
2012-06-22T11:51:25.572Z,1340365885.572 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T11:51:41.098Z,1340365901.098 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T11:51:41.141Z,1340365901.141 [NAL9601](INFO): Powering down
2012-06-22T11:51:46.124Z,1340365906.124 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T11:55:46.094Z,1340366146.093 [Default:CallIridium] Running Loop=1
2012-06-22T11:55:46.094Z,1340366146.094 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-06-22T11:55:46.094Z,1340366146.094 [Default:CallIridium:A] Running Loop=1
2012-06-22T11:55:46.094Z,1340366146.094 [Default:CallIridium:A] Stopped
2012-06-22T11:55:46.095Z,1340366146.095 [Default:CallIridium:B] Running Loop=1
2012-06-22T11:55:46.095Z,1340366146.095 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-06-22T11:55:51.143Z,1340366151.143 [Default:Iridium] Running Loop=1
2012-06-22T11:55:51.143Z,1340366151.143 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-06-22T11:55:51.143Z,1340366151.143 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-06-22T11:55:51.144Z,1340366151.144 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-06-22T11:55:51.144Z,1340366151.144 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-06-22T11:55:51.144Z,1340366151.144 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T11:55:51.145Z,1340366151.145 [Default:Iridium:B.GoToSurface] Stopped
2012-06-22T11:55:51.145Z,1340366151.145 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T11:55:51.145Z,1340366151.145 [Default:Iridium:Read_Iridium] Running Loop=1
2012-06-22T11:55:51.145Z,1340366151.145 [Default:GPS] Running Loop=1
2012-06-22T11:55:51.145Z,1340366151.145 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-06-22T11:55:51.145Z,1340366151.145 [Default:GPS:A.SetSpeed] Running Loop=1
2012-06-22T11:55:51.145Z,1340366151.145 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-06-22T11:55:51.146Z,1340366151.145 [Default:GPS:B.GoToSurface] Running Loop=1
2012-06-22T11:55:51.146Z,1340366151.146 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T11:55:51.147Z,1340366151.147 [Default:GPS:B.GoToSurface] Stopped
2012-06-22T11:55:51.147Z,1340366151.147 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T11:55:51.147Z,1340366151.147 [Default:GPS:Read_GPS] Running Loop=1
2012-06-22T11:55:51.764Z,1340366151.764 [NAL9601](INFO): Powering up
2012-06-22T11:56:57.487Z,1340366217.487 [NAL9601](INFO): NAL9601 initialized
2012-06-22T11:56:58.579Z,1340366218.579 [NAL9601](IMPORTANT): GPS fix at: 1340366457.00
2012-06-22T11:56:58.596Z,1340366218.596 [Default:GPS:Read_GPS] Stopped
2012-06-22T11:56:58.596Z,1340366218.596 [Default:GPS:D] Running Loop=1
2012-06-22T11:56:58.971Z,1340366218.971 [Default:GPS:D] Stopped
2012-06-22T11:56:58.971Z,1340366218.971 [Default:GPS](INFO): Completed Default:GPS
2012-06-22T11:56:58.971Z,1340366218.971 [Default:GPS] Stopped
2012-06-22T11:56:58.971Z,1340366218.971 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-06-22T11:56:58.971Z,1340366218.971 [Default:GPS:A.SetSpeed] Stopped
2012-06-22T11:56:58.971Z,1340366218.971 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T11:57:12.889Z,1340366232.889 [NAL9601](IMPORTANT): SBD MO Status=1, MOMSN=10744, MT Status=1, MTMSN=2785
2012-06-22T11:57:13.051Z,1340366233.051 [NAL9601](INFO): Sent 166 bytes from file Logs/20120622T080505/shore0018.lzma
2012-06-22T11:57:13.051Z,1340366233.051 [NAL9601](INFO): Packets left to send: 0
2012-06-22T11:57:13.053Z,1340366233.053 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0018.lzma.parts/0000.sbd
2012-06-22T11:57:13.524Z,1340366233.524 [NAL9601](INFO): Received command:load Transport/keepstation.xml;set keepstation.Latitude 36.806966 degree;set keepstation.Longitude -121.824326 degree;run 
2012-06-22T11:57:13.538Z,1340366233.538 [CommandLine](IMPORTANT): got command load 
2012-06-22T11:57:13.538Z,1340366233.538 [CommandLine](FAULT): Incomplete syntax. Try: help load
2012-06-22T11:57:13.857Z,1340366233.857 [CommandLine](IMPORTANT): got command set 
2012-06-22T11:57:13.857Z,1340366233.857 [CommandLine](FAULT): Incomplete syntax. Try: help set
2012-06-22T11:57:14.206Z,1340366234.205 [CommandLine](IMPORTANT): got command set 
2012-06-22T11:57:14.206Z,1340366234.206 [CommandLine](FAULT): Incomplete syntax. Try: help set
2012-06-22T11:57:14.207Z,1340366234.207 [CommandLine](IMPORTANT): got command run 
2012-06-22T11:57:14.207Z,1340366234.207 [CommandLine](CRITICAL): Could not run 
2012-06-22T11:57:22.526Z,1340366242.526 [NAL9601](INFO): SBD MO Status=0, MOMSN=10745, MT Status=0, MTMSN=0
2012-06-22T11:57:34.800Z,1340366254.800 [NAL9601](INFO): SBD MO Status=1, MOMSN=10746, MT Status=0, MTMSN=0
2012-06-22T11:57:34.919Z,1340366254.919 [NAL9601](INFO): Sent 257 bytes from file Logs/20120622T080505/shore0019.lzma
2012-06-22T11:57:34.919Z,1340366254.919 [NAL9601](INFO): Packets left to send: 0
2012-06-22T11:57:34.921Z,1340366254.921 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0019.lzma.parts/0000.sbd
2012-06-22T11:57:40.001Z,1340366260.001 [NAL9601](INFO): SBD MO Status=0, MOMSN=10747, MT Status=0, MTMSN=0
2012-06-22T11:57:40.226Z,1340366260.226 [Default:Iridium:Read_Iridium] Stopped
2012-06-22T11:57:40.226Z,1340366260.226 [Default:Iridium](INFO): Completed Default:Iridium
2012-06-22T11:57:40.227Z,1340366260.227 [Default:Iridium] Stopped
2012-06-22T11:57:40.227Z,1340366260.227 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-06-22T11:57:40.227Z,1340366260.227 [Default:Iridium:A.SetSpeed] Stopped
2012-06-22T11:57:40.227Z,1340366260.227 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T11:57:40.414Z,1340366260.414 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2012-06-22T11:57:40.415Z,1340366260.415 [Default:CallIridium:B] Stopped
2012-06-22T11:57:40.415Z,1340366260.415 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-06-22T11:57:40.415Z,1340366260.415 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-06-22T11:57:40.415Z,1340366260.415 [Default:CallIridium] Stopped
2012-06-22T11:57:40.415Z,1340366260.415 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-06-22T11:57:50.626Z,1340366270.626 [NAL9601](INFO): Powering down
2012-06-22T12:02:40.648Z,1340366560.648 [Default:CallIridium] Running Loop=1
2012-06-22T12:02:40.649Z,1340366560.649 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-06-22T12:02:40.649Z,1340366560.649 [Default:CallIridium:A] Running Loop=1
2012-06-22T12:02:40.649Z,1340366560.649 [Default:CallIridium:A] Stopped
2012-06-22T12:02:40.649Z,1340366560.649 [Default:CallIridium:B] Running Loop=1
2012-06-22T12:02:40.649Z,1340366560.649 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-06-22T12:02:45.580Z,1340366565.580 [Default:Iridium] Running Loop=1
2012-06-22T12:02:45.580Z,1340366565.580 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-06-22T12:02:45.580Z,1340366565.580 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-06-22T12:02:45.580Z,1340366565.580 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-06-22T12:02:45.580Z,1340366565.580 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-06-22T12:02:45.580Z,1340366565.580 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T12:02:45.581Z,1340366565.581 [Default:Iridium:B.GoToSurface] Stopped
2012-06-22T12:02:45.581Z,1340366565.581 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T12:02:45.581Z,1340366565.581 [Default:Iridium:Read_Iridium] Running Loop=1
2012-06-22T12:02:45.582Z,1340366565.582 [Default:GPS] Running Loop=1
2012-06-22T12:02:45.582Z,1340366565.582 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-06-22T12:02:45.582Z,1340366565.582 [Default:GPS:A.SetSpeed] Running Loop=1
2012-06-22T12:02:45.582Z,1340366565.582 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-06-22T12:02:45.583Z,1340366565.583 [Default:GPS:B.GoToSurface] Running Loop=1
2012-06-22T12:02:45.583Z,1340366565.583 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T12:02:45.584Z,1340366565.584 [Default:GPS:B.GoToSurface] Stopped
2012-06-22T12:02:45.584Z,1340366565.584 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T12:02:45.584Z,1340366565.584 [Default:GPS:Read_GPS] Running Loop=1
2012-06-22T12:02:46.204Z,1340366566.204 [NAL9601](INFO): Powering up
2012-06-22T12:03:51.927Z,1340366631.927 [NAL9601](INFO): NAL9601 initialized
2012-06-22T12:04:11.310Z,1340366651.310 [NAL9601](IMPORTANT): SBD MO Status=1, MOMSN=10748, MT Status=1, MTMSN=2786
2012-06-22T12:04:11.487Z,1340366651.487 [NAL9601](INFO): Sent 101 bytes from file Logs/20120622T080505/shore0020.lzma
2012-06-22T12:04:11.487Z,1340366651.487 [NAL9601](INFO): Packets left to send: 0
2012-06-22T12:04:11.489Z,1340366651.489 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0020.lzma.parts/0000.sbd
2012-06-22T12:04:11.915Z,1340366651.915 [NAL9601](INFO): Received command:run Transport/keepstation_3km.xml
2012-06-22T12:04:11.931Z,1340366651.931 [CommandLine](IMPORTANT): got command run ./Missions/Transport/keepstation_3km.xml
2012-06-22T12:04:11.931Z,1340366651.931 [MissionManager](INFO): Loading Mission: ./Missions/Transport/keepstation_3km.xml
2012-06-22T12:04:12.061Z,1340366652.061 [MissionManager](INFO): DefineArg keepstation_3km.Depth = 30.000000 m
2012-06-22T12:04:12.076Z,1340366652.076 [MissionManager](INFO): DefineArg keepstation_3km.FastDiveDepth = 10.000000 m
2012-06-22T12:04:12.084Z,1340366652.084 [MissionManager](INFO): DefineArg keepstation_3km.Latitude = 36.806966 arcdeg
2012-06-22T12:04:12.088Z,1340366652.088 [MissionManager](INFO): DefineArg keepstation_3km.Longitude = -121.824326 arcdeg
2012-06-22T12:04:12.093Z,1340366652.093 [MissionManager](INFO): DefineArg keepstation_3km.Radius = 200.000000 m
2012-06-22T12:04:12.098Z,1340366652.098 [MissionManager](INFO): DefineArg keepstation_3km.MinAltitude = 7.000000 m
2012-06-22T12:04:12.111Z,1340366652.111 [MissionManager](INFO): DefineArg keepstation_3km.MaxDepth = 45.000000 m
2012-06-22T12:04:12.114Z,1340366652.114 [MissionManager](INFO): DefineArg keepstation_3km.MinOffshore = 2000.000000 m
2012-06-22T12:04:12.117Z,1340366652.117 [MissionManager](INFO): DefineArg keepstation_3km.NeedCommsTime = 45.000000 min
2012-06-22T12:04:12.120Z,1340366652.120 [MissionManager](INFO): DefineArg keepstation_3km.MaxDuration = 24.000000 h
2012-06-22T12:04:12.127Z,1340366652.127 [MissionManager](INFO): DefineArg keepstation_3km.DepthDeadband = 4.000000 m
2012-06-22T12:04:12.131Z,1340366652.131 [MissionManager](INFO): DefineArg keepstation_3km.MassDefault = -0.007500 n/a
2012-06-22T12:04:12.134Z,1340366652.134 [MissionManager](INFO): DefineArg keepstation_3km.BuoyancyNeutral = 0.000505 n/a
2012-06-22T12:04:12.137Z,1340366652.137 [MissionManager](INFO): DefineArg keepstation_3km.Speed = 0.750000 m/s
2012-06-22T12:04:12.149Z,1340366652.149 [MissionManager](INFO): DefineArg keepstation_3km.ApproachDepth = 15.000000 m
2012-06-22T12:04:12.161Z,1340366652.161 [keepstation_3km:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2012-06-22T12:04:12.168Z,1340366652.168 [keepstation_3km:AltEnvWrapper:B.Wait](DEBUG): Construct Wait.
2012-06-22T12:04:12.169Z,1340366652.169 [keepstation_3km:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2012-06-22T12:04:12.189Z,1340366652.189 [keepstation_3km:SURFACECOMMS:A.GoToSurface](DEBUG): Construct GoToSurface.
2012-06-22T12:04:12.213Z,1340366652.213 [keepstation_3km:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2012-06-22T12:04:12.219Z,1340366652.219 [keepstation_3km:OffshoreWrapper:B.Wait](DEBUG): Construct Wait.
2012-06-22T12:04:12.236Z,1340366652.236 [keepstation_3km:TransitToStation:A.Buoyancy](DEBUG): Construct Buoyancy.
2012-06-22T12:04:12.239Z,1340366652.239 [keepstation_3km:TransitToStation:B.Pitch](DEBUG): Construct.
2012-06-22T12:04:12.256Z,1340366652.256 [keepstation_3km:TransitToStation:C.Pitch](DEBUG): Construct.
2012-06-22T12:04:12.261Z,1340366652.261 [keepstation_3km:TransitToStation:D.SetSpeed](DEBUG): Construct.
2012-06-22T12:04:12.264Z,1340366652.264 [keepstation_3km:TransitToStation:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2012-06-22T12:04:12.285Z,1340366652.285 [keepstation_3km:KeepStation:B.Pitch](DEBUG): Construct.
2012-06-22T12:04:12.290Z,1340366652.290 [keepstation_3km:KeepStation:C.SetSpeed](DEBUG): Construct.
2012-06-22T12:04:12.307Z,1340366652.307 [keepstation_3km:KeepStation:D.KeepStation](DEBUG): Construct KeepStation.
2012-06-22T12:04:12.311Z,1340366652.311 [keepstation_3km:KeepStation:E.Wait](DEBUG): Construct Wait.
2012-06-22T12:04:12.332Z,1340366652.332 [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="keepstation_3km">

    <Description>
        Vehicle transits to desired waypoint and stays within the specified
        radius.
    </Description>

    <DefineArg Name="Depth"><Description>
        Depth held during drift mode
    </Description><Units:meter /><Value>30.0</Value></DefineArg>

    <DefineArg Name="FastDiveDepth"><Description>
        Depth to which vehicle should dive using propeller during drift mode
    </Description><Units:meter /><Value>10.0</Value></DefineArg>

    <DefineArg Name="Latitude"><Description>
        Latitude of waypoint to hold. If NaN, hold the latitude at start of
        mission.
    </Description><Units:degree /><Value>36.806966</Value></DefineArg>

    <DefineArg Name="Longitude"><Description>
        Longitude of waypoint to hold. If NaN, hold the longitude at start of
        mission.
    </Description><Units:degree /><Value>-121.824326</Value></DefineArg>

    <DefineArg Name="Radius"><Description>
        Radius of circle around waypoint to hold.
    </Description><Units:meter/><Value>200</Value></DefineArg>

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

    <DefineArg Name="MaxDepth"><Description>
        Maximum allowable depth during the mission.
    </Description><Units:meter/><Value>45</Value></DefineArg>

    <DefineArg Name="MinOffshore"><Description>
        Minimum distance from the shoreline to maintain
    </Description><Units:meter/><Value>2000</Value></DefineArg>

    <DefineArg Name="NeedCommsTime"><Description>
        How often to surface for commumications
    </Description><Units:minute/><Value>45</Value></DefineArg>

    <DefineArg Name="MaxDuration"><Description>
        How long the mission can last
    </Description><Units:hour/><Value>24</Value></DefineArg>

    <DefineArg Name="DepthDeadband"><Description>
        How much vertical drift from the specified depth is allowed in drift
        mode
    </Description><Units:meter/><Value>4.0</Value></DefineArg>

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

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

    <DefineArg Name="Speed"><Description>
        Vehicle transit speed.
    </Description><Units:meter_per_second /><Value>0.75</Value></DefineArg>

    <DefineArg Name="ApproachDepth"><Description>
        Depth for initial approach to station.
    </Description><Units:meter/><Value>15.0</Value></DefineArg>

    <Aggregate Id="AltEnvWrapper">

        <When><Arg Name="MinAltitude"/>
            <Gt><Units:meter/><Value>0</Value></Gt>
        </When>

        <Guidance:AltitudeEnvelope RunIn="Parallel">
            <Description>
                Safety behavior that keeps vehicle off sea floor
            </Description>
            <Setting><Guidance:AltitudeEnvelope.minAltitude/><Arg Name="MinAltitude"/></Setting>
        </Guidance:AltitudeEnvelope>

        <Guidance:Wait RunIn="Sequence"/>

    </Aggregate>

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

    <Aggregate Id="SURFACECOMMS">

        <Guidance:GoToSurface RunIn="Progression" />

        <Aggregate>

            <Preemptive><True /></Preemptive>

            <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>

    <Aggregate Id="OffshoreWrapper">

        <When><Arg Name="MinOffshore"/>
            <Gt><Units:meter/><Value>0</Value></Gt>
        </When>

        <Guidance:OffshoreEnvelope RunIn="Parallel">
            <Description>
                Safety behavior that keeps vehicle away from the shore
            </Description>
            <Setting><Guidance:OffshoreEnvelope.minOffshore/><Arg Name="MinOffshore"/></Setting>
        </Guidance:OffshoreEnvelope>

        <Guidance:Wait RunIn="Sequence"/>

    </Aggregate>

<!-- 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 RefId="SURFACECOMMS" PriorityHere="false"/>

    </Aggregate>

    <Aggregate Id="TransitToStation">

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

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

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

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

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

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

    </Aggregate>

    <Aggregate Id="KeepStation">

        <Assign RunIn="Parallel"><Control:VerticalControl.depthDeadband/><Arg Name="DepthDeadband"/></Assign>

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

        <Guidance:SetSpeed RunIn="Parallel">
            <While><Universal:depth/>
                <Lt><Arg Name="FastDiveDepth"/></Lt>
            </While>
            <Setting><Guidance:SetSpeed.speed/><Units:meter_per_second/><Value>1</Value></Setting>
        </Guidance:SetSpeed>

        <Guidance:KeepStation RunIn="Parallel">
            <Setting><Guidance:KeepStation.latitude /><Arg Name="Latitude" /></Setting>
            <Setting><Guidance:KeepStation.longitude /><Arg Name="Longitude" /></Setting>
            <Setting><Guidance:KeepStation.radius /><Arg Name="Radius" /></Setting>
        </Guidance:KeepStation>

        <Guidance:Wait RunIn="Sequence">
            <Setting><Guidance:Wait.duration /><Arg Name="MaxDuration"/></Setting>
        </Guidance:Wait>

        <Call RefId="SURFACECOMMS" />

    </Aggregate>

</Mission>


2012-06-22T12:04:12.333Z,1340366652.333 [CommandLine](IMPORTANT): Running ./Missions/Transport/keepstation_3km.xml
2012-06-22T12:04:12.633Z,1340366652.634 [Default] Stopped
2012-06-22T12:04:12.634Z,1340366652.634 [Default](INFO): Aggregate::uninitialize Default
2012-06-22T12:04:12.634Z,1340366652.634 [Default:GPS] Stopped
2012-06-22T12:04:12.634Z,1340366652.634 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-06-22T12:04:12.634Z,1340366652.634 [Default:GPS:A.SetSpeed] Stopped
2012-06-22T12:04:12.636Z,1340366652.636 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T12:04:12.636Z,1340366652.636 [Default:GPS:Read_GPS] Stopped
2012-06-22T12:04:12.636Z,1340366652.636 [Default:Iridium] Stopped
2012-06-22T12:04:12.636Z,1340366652.636 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-06-22T12:04:12.636Z,1340366652.636 [Default:Iridium:A.SetSpeed] Stopped
2012-06-22T12:04:12.636Z,1340366652.636 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T12:04:12.636Z,1340366652.636 [Default:Iridium:Read_Iridium] Stopped
2012-06-22T12:04:12.636Z,1340366652.636 [Default:CallIridium] Stopped
2012-06-22T12:04:12.636Z,1340366652.636 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-06-22T12:04:12.636Z,1340366652.636 [Default:CallIridium:B] Stopped
2012-06-22T12:04:12.637Z,1340366652.636 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-06-22T12:04:12.637Z,1340366652.637 [Default:D.SetSpeed] Stopped
2012-06-22T12:04:12.637Z,1340366652.637 [Default:D.SetSpeed](DEBUG): Uninitialize.
2012-06-22T12:04:12.637Z,1340366652.637 [Default:E.GoToSurface] Stopped
2012-06-22T12:04:12.637Z,1340366652.637 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T12:04:12.637Z,1340366652.637 [Default:F.Wait] Stopped
2012-06-22T12:04:12.637Z,1340366652.637 [Default:F.Wait](DEBUG): Uninitialize Wait Component.
2012-06-22T12:04:12.637Z,1340366652.637 [MissionManager](IMPORTANT): Started mission keepstation_3km
2012-06-22T12:04:12.637Z,1340366652.637 [keepstation_3km] Running Loop=1
2012-06-22T12:04:12.637Z,1340366652.637 [keepstation_3km](INFO): Aggregate::initialize keepstation_3km
2012-06-22T12:04:12.637Z,1340366652.637 [keepstation_3km:B.DepthEnvelope] Running Loop=1
2012-06-22T12:04:12.637Z,1340366652.637 [keepstation_3km:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2012-06-22T12:04:12.639Z,1340366652.639 [keepstation_3km:SURFACECOMMS] Running Loop=1
2012-06-22T12:04:12.639Z,1340366652.639 [keepstation_3km:SURFACECOMMS](INFO): Aggregate::initialize keepstation_3km:SURFACECOMMS
2012-06-22T12:04:12.639Z,1340366652.639 [keepstation_3km:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-06-22T12:04:12.639Z,1340366652.639 [keepstation_3km:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T12:04:12.640Z,1340366652.640 [keepstation_3km:OffshoreWrapper] Running Loop=1
2012-06-22T12:04:12.640Z,1340366652.640 [keepstation_3km:OffshoreWrapper](INFO): Aggregate::initialize keepstation_3km:OffshoreWrapper
2012-06-22T12:04:12.640Z,1340366652.640 [keepstation_3km:OffshoreWrapper:A.OffshoreEnvelope] Running Loop=1
2012-06-22T12:04:12.640Z,1340366652.640 [keepstation_3km:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2012-06-22T12:04:12.640Z,1340366652.640 [keepstation_3km:OffshoreWrapper:B.Wait] Running Loop=1
2012-06-22T12:04:12.640Z,1340366652.640 [keepstation_3km:OffshoreWrapper:B.Wait](DEBUG): Initialize Wait Component.
2012-06-22T12:04:12.645Z,1340366652.645 [keepstation_3km:OffshoreWrapper:A.OffshoreEnvelope] Running Loop=1
2012-06-22T12:04:12.655Z,1340366652.655 [keepstation_3km:SURFACECOMMS:B] Running Loop=1
2012-06-22T12:04:12.655Z,1340366652.655 [keepstation_3km:SURFACECOMMS:B](INFO): Aggregate::initialize keepstation_3km:SURFACECOMMS:B
2012-06-22T12:04:12.655Z,1340366652.655 [keepstation_3km:SURFACECOMMS:B:A] Running Loop=1
2012-06-22T12:04:12.655Z,1340366652.655 [keepstation_3km:B.DepthEnvelope] Running Loop=1
2012-06-22T12:04:12.660Z,1340366652.660 [keepstation_3km:AltEnvWrapper] Running Loop=1
2012-06-22T12:04:12.660Z,1340366652.660 [keepstation_3km:AltEnvWrapper](INFO): Aggregate::initialize keepstation_3km:AltEnvWrapper
2012-06-22T12:04:12.660Z,1340366652.660 [keepstation_3km:AltEnvWrapper:A.AltitudeEnvelope] Running Loop=1
2012-06-22T12:04:12.660Z,1340366652.660 [keepstation_3km:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2012-06-22T12:04:12.660Z,1340366652.661 [keepstation_3km:AltEnvWrapper:B.Wait] Running Loop=1
2012-06-22T12:04:12.661Z,1340366652.661 [keepstation_3km:AltEnvWrapper:B.Wait](DEBUG): Initialize Wait Component.
2012-06-22T12:04:12.665Z,1340366652.665 [keepstation_3km:AltEnvWrapper:A.AltitudeEnvelope] Running Loop=1
2012-06-22T12:04:13.036Z,1340366653.036 [keepstation_3km:SURFACECOMMS:B:A](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-06-22T12:04:54.643Z,1340366694.644 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#3 STATUS: 65535
2012-06-22T12:05:13.027Z,1340366713.027 [NAL9601](IMPORTANT): GPS fix at: 1340366952.00
2012-06-22T12:05:13.043Z,1340366713.043 [keepstation_3km:SURFACECOMMS:B:A] Stopped
2012-06-22T12:05:13.043Z,1340366713.043 [keepstation_3km:SURFACECOMMS:B:B] Running Loop=1
2012-06-22T12:05:13.435Z,1340366713.435 [keepstation_3km:SURFACECOMMS:B:B](DEBUG): Initialize ReadDataComponent to sense platform_communications
2012-06-22T12:05:25.618Z,1340366725.618 [NAL9601](INFO): SBD MO Status=0, MOMSN=10749, MT Status=0, MTMSN=0
2012-06-22T12:05:38.399Z,1340366738.399 [NAL9601](INFO): SBD MO Status=1, MOMSN=10750, MT Status=0, MTMSN=0
2012-06-22T12:05:38.563Z,1340366738.563 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0021.lzma
2012-06-22T12:05:38.563Z,1340366738.563 [NAL9601](INFO): Packets left to send: 1
2012-06-22T12:05:38.565Z,1340366738.565 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0021.lzma.parts/0001.sbd
2012-06-22T12:05:44.077Z,1340366744.077 [NAL9601](INFO): SBD MO Status=1, MOMSN=10751, MT Status=0, MTMSN=0
2012-06-22T12:05:44.243Z,1340366744.243 [NAL9601](INFO): Sent 123 bytes from file Logs/20120622T080505/shore0021.lzma
2012-06-22T12:05:44.243Z,1340366744.243 [NAL9601](INFO): Packets left to send: 0
2012-06-22T12:05:44.244Z,1340366744.244 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0021.lzma.parts/0000.sbd
2012-06-22T12:05:53.677Z,1340366753.677 [NAL9601](INFO): SBD MO Status=0, MOMSN=10752, MT Status=0, MTMSN=0
2012-06-22T12:05:53.841Z,1340366753.842 [keepstation_3km:SURFACECOMMS:B:B] Stopped
2012-06-22T12:05:53.842Z,1340366753.842 [keepstation_3km:SURFACECOMMS:B:C] Running Loop=1
2012-06-22T12:05:54.091Z,1340366754.091 [keepstation_3km:SURFACECOMMS:B:C](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-06-22T12:05:56.079Z,1340366756.079 [NAL9601](IMPORTANT): GPS fix at: 1340366995.00
2012-06-22T12:05:56.094Z,1340366756.095 [keepstation_3km:SURFACECOMMS:B:C] Stopped
2012-06-22T12:05:56.095Z,1340366756.095 [keepstation_3km:SURFACECOMMS:B](INFO): Completed keepstation_3km:SURFACECOMMS:B
2012-06-22T12:05:56.095Z,1340366756.095 [keepstation_3km:SURFACECOMMS:B] Stopped
2012-06-22T12:05:56.095Z,1340366756.095 [keepstation_3km:SURFACECOMMS:B](INFO): Aggregate::uninitialize keepstation_3km:SURFACECOMMS:B
2012-06-22T12:05:56.096Z,1340366756.096 [keepstation_3km:SURFACECOMMS](INFO): Completed keepstation_3km:SURFACECOMMS
2012-06-22T12:05:56.096Z,1340366756.096 [keepstation_3km:SURFACECOMMS] Stopped
2012-06-22T12:05:56.096Z,1340366756.096 [keepstation_3km:SURFACECOMMS](INFO): Aggregate::uninitialize keepstation_3km:SURFACECOMMS
2012-06-22T12:05:56.096Z,1340366756.096 [keepstation_3km:SURFACECOMMS:A.GoToSurface] Stopped
2012-06-22T12:05:56.096Z,1340366756.096 [keepstation_3km:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T12:05:56.096Z,1340366756.096 [keepstation_3km:TransitToStation] Running Loop=1
2012-06-22T12:05:56.096Z,1340366756.096 [keepstation_3km:TransitToStation](INFO): Aggregate::initialize keepstation_3km:TransitToStation
2012-06-22T12:05:56.097Z,1340366756.096 [keepstation_3km:TransitToStation:A.Buoyancy] Running Loop=1
2012-06-22T12:05:56.097Z,1340366756.096 [keepstation_3km:TransitToStation:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2012-06-22T12:05:56.097Z,1340366756.097 [keepstation_3km:TransitToStation:B.Pitch] Running Loop=1
2012-06-22T12:05:56.097Z,1340366756.097 [keepstation_3km:TransitToStation:B.Pitch](DEBUG): Initialize.
2012-06-22T12:05:56.097Z,1340366756.097 [keepstation_3km:TransitToStation:C.Pitch] Running Loop=1
2012-06-22T12:05:56.097Z,1340366756.097 [keepstation_3km:TransitToStation:C.Pitch](DEBUG): Initialize.
2012-06-22T12:05:56.097Z,1340366756.098 [keepstation_3km:TransitToStation:D.SetSpeed] Running Loop=1
2012-06-22T12:05:56.097Z,1340366756.098 [keepstation_3km:TransitToStation:D.SetSpeed](DEBUG): Initialize.
2012-06-22T12:05:56.098Z,1340366756.098 [keepstation_3km:TransitToStation:Wpt1.Waypoint] Running Loop=1
2012-06-22T12:05:56.098Z,1340366756.098 [keepstation_3km:TransitToStation:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2012-06-22T12:05:56.501Z,1340366756.501 [keepstation_3km:TransitToStation:D.SetSpeed] Running Loop=1
2012-06-22T12:05:56.505Z,1340366756.505 [keepstation_3km:TransitToStation:C.Pitch] Running Loop=1
2012-06-22T12:05:56.510Z,1340366756.510 [keepstation_3km:TransitToStation:B.Pitch] Running Loop=1
2012-06-22T12:05:56.515Z,1340366756.515 [keepstation_3km:TransitToStation:A.Buoyancy] Running Loop=1
2012-06-22T12:06:02.476Z,1340366762.476 [NAL9601](INFO): Powering down
2012-06-22T12:07:08.765Z,1340366828.765 [Radio_Freewave](INFO): Powering down
2012-06-22T12:50:56.204Z,1340369456.204 [keepstation_3km:NeedComms] Running Loop=1
2012-06-22T12:50:56.205Z,1340369456.204 [keepstation_3km:NeedComms](INFO): Aggregate::initialize keepstation_3km:NeedComms
2012-06-22T12:50:56.205Z,1340369456.205 [keepstation_3km:NeedComms:A] Running Loop=1
2012-06-22T12:50:56.205Z,1340369456.205 [keepstation_3km:NeedComms:A](INFO): Aggregate::initialize keepstation_3km:NeedComms:A
2012-06-22T12:50:56.612Z,1340369456.612 [keepstation_3km:SURFACECOMMS] Running Loop=1
2012-06-22T12:50:56.612Z,1340369456.612 [keepstation_3km:SURFACECOMMS](INFO): Aggregate::initialize keepstation_3km:SURFACECOMMS
2012-06-22T12:50:56.612Z,1340369456.612 [keepstation_3km:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-06-22T12:50:56.612Z,1340369456.612 [keepstation_3km:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T12:54:27.321Z,1340369667.321 [Radio_Freewave](INFO): Powering up
2012-06-22T12:54:27.333Z,1340369667.333 [keepstation_3km:SURFACECOMMS:B] Running Loop=1
2012-06-22T12:54:27.333Z,1340369667.333 [keepstation_3km:SURFACECOMMS:B](INFO): Aggregate::initialize keepstation_3km:SURFACECOMMS:B
2012-06-22T12:54:27.333Z,1340369667.333 [keepstation_3km:SURFACECOMMS:B:A] Running Loop=1
2012-06-22T12:54:28.128Z,1340369668.128 [NAL9601](INFO): Powering up
2012-06-22T12:55:33.755Z,1340369733.755 [NAL9601](INFO): NAL9601 initialized
2012-06-22T12:55:34.875Z,1340369734.875 [NAL9601](IMPORTANT): GPS fix at: 1340369979.00
2012-06-22T12:55:34.890Z,1340369734.890 [Navigation](IMPORTANT): Error 6.5 %DT at bearing -45.0 deg., over the last 2130.6 m.
2012-06-22T12:55:34.890Z,1340369734.890 [Navigation](IMPORTANT): Distance made good 2240.8 m, at (36.8090, -121.8437).
2012-06-22T12:55:34.891Z,1340369734.891 [keepstation_3km:SURFACECOMMS:B:A] Stopped
2012-06-22T12:55:34.891Z,1340369734.891 [keepstation_3km:SURFACECOMMS:B:B] Running Loop=1
2012-06-22T12:55:52.786Z,1340369752.786 [NAL9601](INFO): SBD MO Status=1, MOMSN=10753, MT Status=0, MTMSN=0
2012-06-22T12:55:52.911Z,1340369752.911 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0022.lzma
2012-06-22T12:55:52.911Z,1340369752.911 [NAL9601](INFO): Packets left to send: 10
2012-06-22T12:55:52.913Z,1340369752.913 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0022.lzma.parts/0010.sbd
2012-06-22T12:56:02.536Z,1340369762.536 [NAL9601](INFO): SBD MO Status=2, MOMSN=10754, MT Status=2, MTMSN=0
2012-06-22T12:56:02.536Z,1340369762.536 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-06-22T12:56:15.192Z,1340369775.193 [NAL9601](INFO): SBD MO Status=1, MOMSN=10754, MT Status=0, MTMSN=0
2012-06-22T12:56:15.375Z,1340369775.375 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0022.lzma
2012-06-22T12:56:15.375Z,1340369775.375 [NAL9601](INFO): Packets left to send: 9
2012-06-22T12:56:15.377Z,1340369775.377 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0022.lzma.parts/0009.sbd
2012-06-22T12:56:26.894Z,1340369786.894 [NAL9601](INFO): SBD MO Status=1, MOMSN=10755, MT Status=0, MTMSN=0
2012-06-22T12:56:27.059Z,1340369787.059 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0022.lzma
2012-06-22T12:56:27.059Z,1340369787.059 [NAL9601](INFO): Packets left to send: 8
2012-06-22T12:56:27.265Z,1340369787.265 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0022.lzma.parts/0008.sbd
2012-06-22T12:56:38.782Z,1340369798.782 [NAL9601](INFO): SBD MO Status=1, MOMSN=10756, MT Status=0, MTMSN=0
2012-06-22T12:56:38.939Z,1340369798.939 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0022.lzma
2012-06-22T12:56:38.939Z,1340369798.939 [NAL9601](INFO): Packets left to send: 7
2012-06-22T12:56:38.940Z,1340369798.940 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0022.lzma.parts/0007.sbd
2012-06-22T12:56:48.142Z,1340369808.142 [NAL9601](INFO): SBD MO Status=1, MOMSN=10757, MT Status=0, MTMSN=0
2012-06-22T12:56:48.327Z,1340369808.327 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0022.lzma
2012-06-22T12:56:48.327Z,1340369808.327 [NAL9601](INFO): Packets left to send: 6
2012-06-22T12:56:48.328Z,1340369808.328 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0022.lzma.parts/0006.sbd
2012-06-22T12:56:56.237Z,1340369816.237 [NAL9601](INFO): SBD MO Status=1, MOMSN=10758, MT Status=0, MTMSN=0
2012-06-22T12:56:56.419Z,1340369816.419 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0022.lzma
2012-06-22T12:56:56.419Z,1340369816.419 [NAL9601](INFO): Packets left to send: 5
2012-06-22T12:56:56.421Z,1340369816.421 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0022.lzma.parts/0005.sbd
2012-06-22T12:57:06.357Z,1340369826.357 [NAL9601](INFO): SBD MO Status=1, MOMSN=10759, MT Status=0, MTMSN=0
2012-06-22T12:57:06.495Z,1340369826.495 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0022.lzma
2012-06-22T12:57:06.495Z,1340369826.495 [NAL9601](INFO): Packets left to send: 4
2012-06-22T12:57:06.496Z,1340369826.496 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0022.lzma.parts/0004.sbd
2012-06-22T12:57:19.322Z,1340369839.322 [NAL9601](INFO): SBD MO Status=1, MOMSN=10760, MT Status=0, MTMSN=0
2012-06-22T12:57:19.475Z,1340369839.475 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0022.lzma
2012-06-22T12:57:19.475Z,1340369839.475 [NAL9601](INFO): Packets left to send: 3
2012-06-22T12:57:19.476Z,1340369839.476 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0022.lzma.parts/0003.sbd
2012-06-22T12:57:27.417Z,1340369847.418 [NAL9601](INFO): SBD MO Status=1, MOMSN=10761, MT Status=0, MTMSN=0
2012-06-22T12:57:27.571Z,1340369847.571 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0022.lzma
2012-06-22T12:57:27.571Z,1340369847.571 [NAL9601](INFO): Packets left to send: 2
2012-06-22T12:57:27.572Z,1340369847.572 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0022.lzma.parts/0002.sbd
2012-06-22T12:57:39.106Z,1340369859.106 [NAL9601](INFO): SBD MO Status=1, MOMSN=10762, MT Status=0, MTMSN=0
2012-06-22T12:57:39.247Z,1340369859.247 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0022.lzma
2012-06-22T12:57:39.247Z,1340369859.247 [NAL9601](INFO): Packets left to send: 1
2012-06-22T12:57:39.248Z,1340369859.248 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0022.lzma.parts/0001.sbd
2012-06-22T12:57:48.426Z,1340369868.425 [NAL9601](INFO): SBD MO Status=1, MOMSN=10763, MT Status=0, MTMSN=0
2012-06-22T12:57:48.631Z,1340369868.631 [NAL9601](INFO): Sent 113 bytes from file Logs/20120622T080505/shore0022.lzma
2012-06-22T12:57:48.631Z,1340369868.631 [NAL9601](INFO): Packets left to send: 0
2012-06-22T12:57:48.633Z,1340369868.633 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0022.lzma.parts/0000.sbd
2012-06-22T12:58:00.825Z,1340369880.825 [NAL9601](INFO): SBD MO Status=0, MOMSN=10764, MT Status=0, MTMSN=0
2012-06-22T12:58:01.025Z,1340369881.025 [keepstation_3km:SURFACECOMMS:B:B] Stopped
2012-06-22T12:58:01.025Z,1340369881.025 [keepstation_3km:SURFACECOMMS:B:C] Running Loop=1
2012-06-22T12:58:03.227Z,1340369883.227 [NAL9601](IMPORTANT): GPS fix at: 1340370127.00
2012-06-22T12:58:03.241Z,1340369883.241 [keepstation_3km:SURFACECOMMS:B:C] Stopped
2012-06-22T12:58:03.242Z,1340369883.242 [keepstation_3km:SURFACECOMMS:B](INFO): Completed keepstation_3km:SURFACECOMMS:B
2012-06-22T12:58:03.242Z,1340369883.242 [keepstation_3km:SURFACECOMMS:B] Stopped
2012-06-22T12:58:03.242Z,1340369883.242 [keepstation_3km:SURFACECOMMS:B](INFO): Aggregate::uninitialize keepstation_3km:SURFACECOMMS:B
2012-06-22T12:58:03.243Z,1340369883.243 [keepstation_3km:SURFACECOMMS](INFO): Completed keepstation_3km:SURFACECOMMS
2012-06-22T12:58:03.243Z,1340369883.243 [keepstation_3km:SURFACECOMMS] Stopped
2012-06-22T12:58:03.243Z,1340369883.243 [keepstation_3km:SURFACECOMMS](INFO): Aggregate::uninitialize keepstation_3km:SURFACECOMMS
2012-06-22T12:58:03.243Z,1340369883.243 [keepstation_3km:SURFACECOMMS:A.GoToSurface] Stopped
2012-06-22T12:58:03.243Z,1340369883.243 [keepstation_3km:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T12:58:03.645Z,1340369883.645 [keepstation_3km:NeedComms:A](INFO): Completed keepstation_3km:NeedComms:A
2012-06-22T12:58:03.645Z,1340369883.645 [keepstation_3km:NeedComms:A] Stopped
2012-06-22T12:58:03.645Z,1340369883.645 [keepstation_3km:NeedComms:A](INFO): Aggregate::uninitialize keepstation_3km:NeedComms:A
2012-06-22T12:58:03.645Z,1340369883.645 [keepstation_3km:NeedComms](INFO): Completed keepstation_3km:NeedComms
2012-06-22T12:58:03.645Z,1340369883.645 [keepstation_3km:NeedComms] Stopped
2012-06-22T12:58:03.645Z,1340369883.645 [keepstation_3km:NeedComms](INFO): Aggregate::uninitialize keepstation_3km:NeedComms
2012-06-22T12:58:09.628Z,1340369889.628 [NAL9601](INFO): Powering down
2012-06-22T12:59:07.633Z,1340369947.634 [Radio_Freewave](INFO): Powering down
2012-06-22T13:35:12.040Z,1340372112.040 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#7 STATUS: 65535
2012-06-22T13:39:27.663Z,1340372367.663 [keepstation_3km:TransitToStation:Wpt1.Waypoint](INFO): Reached Waypoint: 36.806966,-121.824326
2012-06-22T13:39:27.663Z,1340372367.663 [keepstation_3km:TransitToStation:Wpt1.Waypoint] Stopped
2012-06-22T13:39:27.663Z,1340372367.663 [keepstation_3km:TransitToStation:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2012-06-22T13:39:27.663Z,1340372367.663 [keepstation_3km:TransitToStation:TransitToStation] Running Loop=1
2012-06-22T13:39:27.664Z,1340372367.664 [keepstation_3km:TransitToStation:TransitToStation](INFO): Aggregate::initialize keepstation_3km:TransitToStation:TransitToStation
2012-06-22T13:39:28.052Z,1340372368.052 [keepstation_3km:SURFACECOMMS] Running Loop=1
2012-06-22T13:39:28.052Z,1340372368.052 [keepstation_3km:SURFACECOMMS](INFO): Aggregate::initialize keepstation_3km:SURFACECOMMS
2012-06-22T13:39:28.052Z,1340372368.052 [keepstation_3km:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-06-22T13:39:28.052Z,1340372368.053 [keepstation_3km:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T13:42:40.040Z,1340372560.040 [Radio_Freewave](INFO): Powering up
2012-06-22T13:42:40.052Z,1340372560.052 [keepstation_3km:SURFACECOMMS:B] Running Loop=1
2012-06-22T13:42:40.052Z,1340372560.052 [keepstation_3km:SURFACECOMMS:B](INFO): Aggregate::initialize keepstation_3km:SURFACECOMMS:B
2012-06-22T13:42:40.052Z,1340372560.052 [keepstation_3km:SURFACECOMMS:B:A] Running Loop=1
2012-06-22T13:42:40.835Z,1340372560.835 [NAL9601](INFO): Powering up
2012-06-22T13:43:46.463Z,1340372626.463 [NAL9601](INFO): NAL9601 initialized
2012-06-22T13:43:47.604Z,1340372627.604 [NAL9601](IMPORTANT): GPS fix at: 1340372876.00
2012-06-22T13:43:47.618Z,1340372627.618 [Navigation](IMPORTANT): Error 8.0 %DT at bearing -70.3 deg., over the last 1930.4 m.
2012-06-22T13:43:47.619Z,1340372627.619 [Navigation](IMPORTANT): Distance made good 1974.0 m, at (36.8057, -121.8219).
2012-06-22T13:43:47.620Z,1340372627.620 [keepstation_3km:SURFACECOMMS:B:A] Stopped
2012-06-22T13:43:47.620Z,1340372627.620 [keepstation_3km:SURFACECOMMS:B:B] Running Loop=1
2012-06-22T13:44:05.666Z,1340372645.666 [NAL9601](INFO): SBD MO Status=1, MOMSN=10765, MT Status=0, MTMSN=0
2012-06-22T13:44:05.819Z,1340372645.819 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0023.lzma
2012-06-22T13:44:05.819Z,1340372645.819 [NAL9601](INFO): Packets left to send: 10
2012-06-22T13:44:05.821Z,1340372645.821 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0023.lzma.parts/0010.sbd
2012-06-22T13:44:14.641Z,1340372654.641 [NAL9601](INFO): SBD MO Status=1, MOMSN=10766, MT Status=0, MTMSN=0
2012-06-22T13:44:14.807Z,1340372654.807 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0023.lzma
2012-06-22T13:44:14.807Z,1340372654.807 [NAL9601](INFO): Packets left to send: 9
2012-06-22T13:44:14.808Z,1340372654.808 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0023.lzma.parts/0009.sbd
2012-06-22T13:44:23.183Z,1340372663.183 [NAL9601](INFO): SBD MO Status=1, MOMSN=10767, MT Status=0, MTMSN=0
2012-06-22T13:44:23.391Z,1340372663.391 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0023.lzma
2012-06-22T13:44:23.391Z,1340372663.391 [NAL9601](INFO): Packets left to send: 8
2012-06-22T13:44:23.392Z,1340372663.392 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0023.lzma.parts/0008.sbd
2012-06-22T13:44:30.831Z,1340372670.831 [NAL9601](INFO): SBD MO Status=1, MOMSN=10768, MT Status=0, MTMSN=0
2012-06-22T13:44:30.979Z,1340372670.979 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0023.lzma
2012-06-22T13:44:30.979Z,1340372670.979 [NAL9601](INFO): Packets left to send: 7
2012-06-22T13:44:30.980Z,1340372670.980 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0023.lzma.parts/0007.sbd
2012-06-22T13:44:40.555Z,1340372680.555 [NAL9601](INFO): SBD MO Status=1, MOMSN=10769, MT Status=0, MTMSN=0
2012-06-22T13:44:40.767Z,1340372680.767 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0023.lzma
2012-06-22T13:44:40.767Z,1340372680.767 [NAL9601](INFO): Packets left to send: 6
2012-06-22T13:44:40.768Z,1340372680.769 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0023.lzma.parts/0006.sbd
2012-06-22T13:44:48.268Z,1340372688.268 [NAL9601](INFO): SBD MO Status=1, MOMSN=10770, MT Status=0, MTMSN=0
2012-06-22T13:44:48.451Z,1340372688.451 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0023.lzma
2012-06-22T13:44:48.451Z,1340372688.451 [NAL9601](INFO): Packets left to send: 5
2012-06-22T13:44:48.452Z,1340372688.452 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0023.lzma.parts/0005.sbd
2012-06-22T13:44:56.043Z,1340372696.043 [NAL9601](INFO): SBD MO Status=1, MOMSN=10771, MT Status=0, MTMSN=0
2012-06-22T13:44:56.239Z,1340372696.239 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0023.lzma
2012-06-22T13:44:56.239Z,1340372696.239 [NAL9601](INFO): Packets left to send: 4
2012-06-22T13:44:56.241Z,1340372696.241 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0023.lzma.parts/0004.sbd
2012-06-22T13:45:03.703Z,1340372703.703 [NAL9601](INFO): SBD MO Status=1, MOMSN=10772, MT Status=0, MTMSN=0
2012-06-22T13:45:03.831Z,1340372703.831 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0023.lzma
2012-06-22T13:45:03.831Z,1340372703.831 [NAL9601](INFO): Packets left to send: 3
2012-06-22T13:45:03.832Z,1340372703.832 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0023.lzma.parts/0003.sbd
2012-06-22T13:45:13.786Z,1340372713.786 [NAL9601](INFO): SBD MO Status=1, MOMSN=10773, MT Status=0, MTMSN=0
2012-06-22T13:45:13.915Z,1340372713.915 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0023.lzma
2012-06-22T13:45:13.915Z,1340372713.915 [NAL9601](INFO): Packets left to send: 2
2012-06-22T13:45:13.916Z,1340372713.916 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0023.lzma.parts/0002.sbd
2012-06-22T13:45:22.313Z,1340372722.313 [NAL9601](INFO): SBD MO Status=1, MOMSN=10774, MT Status=0, MTMSN=0
2012-06-22T13:45:22.503Z,1340372722.503 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0023.lzma
2012-06-22T13:45:22.503Z,1340372722.503 [NAL9601](INFO): Packets left to send: 1
2012-06-22T13:45:22.504Z,1340372722.504 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0023.lzma.parts/0001.sbd
2012-06-22T13:45:28.414Z,1340372728.414 [NAL9601](INFO): SBD MO Status=1, MOMSN=10775, MT Status=0, MTMSN=0
2012-06-22T13:45:28.591Z,1340372728.591 [NAL9601](INFO): Sent 130 bytes from file Logs/20120622T080505/shore0023.lzma
2012-06-22T13:45:28.591Z,1340372728.591 [NAL9601](INFO): Packets left to send: 0
2012-06-22T13:45:28.592Z,1340372728.592 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0023.lzma.parts/0000.sbd
2012-06-22T13:45:37.628Z,1340372737.628 [NAL9601](INFO): SBD MO Status=0, MOMSN=10776, MT Status=0, MTMSN=0
2012-06-22T13:45:37.799Z,1340372737.799 [keepstation_3km:SURFACECOMMS:B:B] Stopped
2012-06-22T13:45:37.799Z,1340372737.799 [keepstation_3km:SURFACECOMMS:B:C] Running Loop=1
2012-06-22T13:45:40.010Z,1340372740.010 [NAL9601](IMPORTANT): GPS fix at: 1340372989.00
2012-06-22T13:45:40.025Z,1340372740.025 [keepstation_3km:SURFACECOMMS:B:C] Stopped
2012-06-22T13:45:40.025Z,1340372740.025 [keepstation_3km:SURFACECOMMS:B](INFO): Completed keepstation_3km:SURFACECOMMS:B
2012-06-22T13:45:40.025Z,1340372740.026 [keepstation_3km:SURFACECOMMS:B] Stopped
2012-06-22T13:45:40.026Z,1340372740.026 [keepstation_3km:SURFACECOMMS:B](INFO): Aggregate::uninitialize keepstation_3km:SURFACECOMMS:B
2012-06-22T13:45:40.027Z,1340372740.027 [keepstation_3km:SURFACECOMMS](INFO): Completed keepstation_3km:SURFACECOMMS
2012-06-22T13:45:40.027Z,1340372740.027 [keepstation_3km:SURFACECOMMS] Stopped
2012-06-22T13:45:40.027Z,1340372740.027 [keepstation_3km:SURFACECOMMS](INFO): Aggregate::uninitialize keepstation_3km:SURFACECOMMS
2012-06-22T13:45:40.027Z,1340372740.027 [keepstation_3km:SURFACECOMMS:A.GoToSurface] Stopped
2012-06-22T13:45:40.027Z,1340372740.027 [keepstation_3km:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T13:45:40.436Z,1340372740.436 [keepstation_3km:TransitToStation:TransitToStation](INFO): Completed keepstation_3km:TransitToStation:TransitToStation
2012-06-22T13:45:40.436Z,1340372740.436 [keepstation_3km:TransitToStation:TransitToStation] Stopped
2012-06-22T13:45:40.436Z,1340372740.436 [keepstation_3km:TransitToStation:TransitToStation](INFO): Aggregate::uninitialize keepstation_3km:TransitToStation:TransitToStation
2012-06-22T13:45:40.438Z,1340372740.438 [keepstation_3km:TransitToStation](INFO): Completed keepstation_3km:TransitToStation
2012-06-22T13:45:40.438Z,1340372740.438 [keepstation_3km:TransitToStation] Stopped
2012-06-22T13:45:40.438Z,1340372740.438 [keepstation_3km:TransitToStation](INFO): Aggregate::uninitialize keepstation_3km:TransitToStation
2012-06-22T13:45:40.438Z,1340372740.438 [keepstation_3km:TransitToStation:A.Buoyancy] Stopped
2012-06-22T13:45:40.438Z,1340372740.438 [keepstation_3km:TransitToStation:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2012-06-22T13:45:40.438Z,1340372740.438 [keepstation_3km:TransitToStation:B.Pitch] Stopped
2012-06-22T13:45:40.438Z,1340372740.438 [keepstation_3km:TransitToStation:C.Pitch] Stopped
2012-06-22T13:45:40.438Z,1340372740.438 [keepstation_3km:TransitToStation:D.SetSpeed] Stopped
2012-06-22T13:45:40.438Z,1340372740.438 [keepstation_3km:TransitToStation:D.SetSpeed](DEBUG): Uninitialize.
2012-06-22T13:45:40.439Z,1340372740.439 [keepstation_3km:KeepStation] Running Loop=1
2012-06-22T13:45:40.439Z,1340372740.439 [keepstation_3km:KeepStation](INFO): Aggregate::initialize keepstation_3km:KeepStation
2012-06-22T13:45:40.439Z,1340372740.439 [keepstation_3km:KeepStation:A] Running Loop=1
2012-06-22T13:45:40.439Z,1340372740.439 [keepstation_3km:KeepStation:B.Pitch] Running Loop=1
2012-06-22T13:45:40.439Z,1340372740.439 [keepstation_3km:KeepStation:B.Pitch](DEBUG): Initialize.
2012-06-22T13:45:40.439Z,1340372740.439 [keepstation_3km:KeepStation:C.SetSpeed] Running Loop=1
2012-06-22T13:45:40.439Z,1340372740.439 [keepstation_3km:KeepStation:C.SetSpeed](DEBUG): Initialize.
2012-06-22T13:45:40.439Z,1340372740.439 [keepstation_3km:KeepStation:D.KeepStation] Running Loop=1
2012-06-22T13:45:40.439Z,1340372740.439 [keepstation_3km:KeepStation:D.KeepStation](DEBUG): Initialize KeepStationComponent.
2012-06-22T13:45:40.440Z,1340372740.440 [keepstation_3km:KeepStation:E.Wait] Running Loop=1
2012-06-22T13:45:40.440Z,1340372740.440 [keepstation_3km:KeepStation:E.Wait](DEBUG): Initialize Wait Component.
2012-06-22T13:45:40.853Z,1340372740.853 [keepstation_3km:KeepStation:D.KeepStation] Running Loop=1
2012-06-22T13:45:40.854Z,1340372740.853 [keepstation_3km:KeepStation:D.KeepStation](INFO): Rest->Waypoint
2012-06-22T13:45:40.859Z,1340372740.859 [keepstation_3km:KeepStation:C.SetSpeed] Running Loop=1
2012-06-22T13:45:40.863Z,1340372740.863 [keepstation_3km:KeepStation:B.Pitch] Running Loop=1
2012-06-22T13:45:40.885Z,1340372740.885 [keepstation_3km:KeepStation:A] Running Loop=1
2012-06-22T13:45:46.421Z,1340372746.421 [NAL9601](INFO): Powering down
2012-06-22T13:46:00.822Z,1340372760.822 [Radio_Freewave](INFO): Powering down
2012-06-22T13:46:09.604Z,1340372769.604 [Radio_Freewave](INFO): Powering up
2012-06-22T13:46:30.472Z,1340372790.471 [Radio_Freewave](INFO): Powering down
2012-06-22T13:46:41.345Z,1340372801.345 [Radio_Freewave](INFO): Powering up
2012-06-22T13:46:48.157Z,1340372808.158 [Radio_Freewave](INFO): Powering down
2012-06-22T13:54:23.228Z,1340373263.228 [keepstation_3km:KeepStation:D.KeepStation](INFO): Waypoint->Rest
2012-06-22T14:09:00.366Z,1340374140.366 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T14:09:02.812Z,1340374142.812 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T14:09:05.166Z,1340374145.166 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T14:09:07.566Z,1340374147.566 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T14:09:09.970Z,1340374149.970 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T14:09:12.366Z,1340374152.366 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T14:09:14.766Z,1340374154.766 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T14:09:17.166Z,1340374157.166 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T14:09:19.566Z,1340374159.566 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T14:09:21.567Z,1340374161.567 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T14:09:23.970Z,1340374163.970 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T14:09:26.367Z,1340374166.367 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T14:09:28.871Z,1340374168.871 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T14:09:31.175Z,1340374171.175 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T14:09:33.571Z,1340374173.571 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T14:09:35.970Z,1340374175.970 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T14:09:38.375Z,1340374178.375 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T14:09:40.767Z,1340374180.767 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T14:09:43.175Z,1340374183.175 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T14:09:45.592Z,1340374185.592 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T14:09:47.971Z,1340374187.971 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#8 STATUS: 65535
2012-06-22T14:23:01.019Z,1340374981.019 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T14:23:03.419Z,1340374983.419 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T14:23:05.440Z,1340374985.440 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T14:23:07.820Z,1340374987.820 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T14:23:10.232Z,1340374990.232 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T14:23:12.636Z,1340374992.636 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T14:23:15.020Z,1340374995.020 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T14:23:17.480Z,1340374997.480 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T14:23:19.820Z,1340374999.820 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T14:23:22.220Z,1340375002.220 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T14:23:24.636Z,1340375004.636 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T14:23:27.061Z,1340375007.061 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T14:23:29.420Z,1340375009.420 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T14:23:31.832Z,1340375011.832 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T14:30:40.023Z,1340375440.023 [keepstation_3km:NeedComms] Running Loop=1
2012-06-22T14:30:40.023Z,1340375440.023 [keepstation_3km:NeedComms](INFO): Aggregate::initialize keepstation_3km:NeedComms
2012-06-22T14:30:40.024Z,1340375440.024 [keepstation_3km:NeedComms:A] Running Loop=1
2012-06-22T14:30:40.024Z,1340375440.024 [keepstation_3km:NeedComms:A](INFO): Aggregate::initialize keepstation_3km:NeedComms:A
2012-06-22T14:30:40.438Z,1340375440.438 [keepstation_3km:SURFACECOMMS] Running Loop=1
2012-06-22T14:30:40.438Z,1340375440.438 [keepstation_3km:SURFACECOMMS](INFO): Aggregate::initialize keepstation_3km:SURFACECOMMS
2012-06-22T14:30:40.438Z,1340375440.438 [keepstation_3km:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-06-22T14:30:40.438Z,1340375440.438 [keepstation_3km:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T14:35:05.166Z,1340375705.166 [Radio_Freewave](INFO): Powering up
2012-06-22T14:35:05.179Z,1340375705.179 [keepstation_3km:SURFACECOMMS:B] Running Loop=1
2012-06-22T14:35:05.179Z,1340375705.179 [keepstation_3km:SURFACECOMMS:B](INFO): Aggregate::initialize keepstation_3km:SURFACECOMMS:B
2012-06-22T14:35:05.179Z,1340375705.179 [keepstation_3km:SURFACECOMMS:B:A] Running Loop=1
2012-06-22T14:35:05.970Z,1340375705.970 [NAL9601](INFO): Powering up
2012-06-22T14:36:11.595Z,1340375771.595 [NAL9601](INFO): NAL9601 initialized
2012-06-22T14:36:12.716Z,1340375772.716 [NAL9601](IMPORTANT): GPS fix at: 1340376026.00
2012-06-22T14:36:12.731Z,1340375772.731 [Navigation](IMPORTANT): Error 2.7 %DT at bearing -173.9 deg., over the last 781.9 m.
2012-06-22T14:36:12.731Z,1340375772.731 [Navigation](IMPORTANT): Distance made good 210.1 m, at (36.8066, -121.8240).
2012-06-22T14:36:12.732Z,1340375772.732 [keepstation_3km:SURFACECOMMS:B:A] Stopped
2012-06-22T14:36:12.733Z,1340375772.733 [keepstation_3km:SURFACECOMMS:B:B] Running Loop=1
2012-06-22T14:36:31.821Z,1340375791.821 [NAL9601](INFO): SBD MO Status=1, MOMSN=10777, MT Status=0, MTMSN=0
2012-06-22T14:36:31.967Z,1340375791.967 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0024.lzma
2012-06-22T14:36:31.967Z,1340375791.967 [NAL9601](INFO): Packets left to send: 4
2012-06-22T14:36:31.969Z,1340375791.969 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0024.lzma.parts/0004.sbd
2012-06-22T14:36:40.325Z,1340375800.325 [NAL9601](INFO): SBD MO Status=1, MOMSN=10778, MT Status=0, MTMSN=0
2012-06-22T14:36:40.539Z,1340375800.539 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0024.lzma
2012-06-22T14:36:40.539Z,1340375800.539 [NAL9601](INFO): Packets left to send: 3
2012-06-22T14:36:40.540Z,1340375800.540 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0024.lzma.parts/0003.sbd
2012-06-22T14:36:52.874Z,1340375812.874 [NAL9601](INFO): SBD MO Status=1, MOMSN=10779, MT Status=0, MTMSN=0
2012-06-22T14:36:53.019Z,1340375813.019 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0024.lzma
2012-06-22T14:36:53.019Z,1340375813.019 [NAL9601](INFO): Packets left to send: 2
2012-06-22T14:36:53.020Z,1340375813.020 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0024.lzma.parts/0002.sbd
2012-06-22T14:37:02.199Z,1340375822.199 [NAL9601](INFO): SBD MO Status=1, MOMSN=10780, MT Status=0, MTMSN=0
2012-06-22T14:37:02.403Z,1340375822.403 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0024.lzma
2012-06-22T14:37:02.403Z,1340375822.403 [NAL9601](INFO): Packets left to send: 1
2012-06-22T14:37:02.404Z,1340375822.404 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0024.lzma.parts/0001.sbd
2012-06-22T14:37:13.569Z,1340375833.569 [NAL9601](INFO): SBD MO Status=1, MOMSN=10781, MT Status=0, MTMSN=0
2012-06-22T14:37:13.787Z,1340375833.787 [NAL9601](INFO): Sent 108 bytes from file Logs/20120622T080505/shore0024.lzma
2012-06-22T14:37:13.787Z,1340375833.787 [NAL9601](INFO): Packets left to send: 0
2012-06-22T14:37:13.788Z,1340375833.788 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0024.lzma.parts/0000.sbd
2012-06-22T14:37:21.537Z,1340375841.537 [NAL9601](INFO): SBD MO Status=0, MOMSN=10782, MT Status=0, MTMSN=0
2012-06-22T14:37:21.722Z,1340375841.722 [keepstation_3km:SURFACECOMMS:B:B] Stopped
2012-06-22T14:37:21.722Z,1340375841.722 [keepstation_3km:SURFACECOMMS:B:C] Running Loop=1
2012-06-22T14:37:23.943Z,1340375843.943 [NAL9601](IMPORTANT): GPS fix at: 1340376097.00
2012-06-22T14:37:23.958Z,1340375843.958 [keepstation_3km:SURFACECOMMS:B:C] Stopped
2012-06-22T14:37:23.958Z,1340375843.958 [keepstation_3km:SURFACECOMMS:B](INFO): Completed keepstation_3km:SURFACECOMMS:B
2012-06-22T14:37:23.959Z,1340375843.958 [keepstation_3km:SURFACECOMMS:B] Stopped
2012-06-22T14:37:23.959Z,1340375843.959 [keepstation_3km:SURFACECOMMS:B](INFO): Aggregate::uninitialize keepstation_3km:SURFACECOMMS:B
2012-06-22T14:37:23.959Z,1340375843.959 [keepstation_3km:SURFACECOMMS](INFO): Completed keepstation_3km:SURFACECOMMS
2012-06-22T14:37:23.960Z,1340375843.960 [keepstation_3km:SURFACECOMMS] Stopped
2012-06-22T14:37:23.960Z,1340375843.960 [keepstation_3km:SURFACECOMMS](INFO): Aggregate::uninitialize keepstation_3km:SURFACECOMMS
2012-06-22T14:37:23.960Z,1340375843.960 [keepstation_3km:SURFACECOMMS:A.GoToSurface] Stopped
2012-06-22T14:37:23.960Z,1340375843.960 [keepstation_3km:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T14:37:24.364Z,1340375844.364 [keepstation_3km:NeedComms:A](INFO): Completed keepstation_3km:NeedComms:A
2012-06-22T14:37:24.364Z,1340375844.365 [keepstation_3km:NeedComms:A] Stopped
2012-06-22T14:37:24.365Z,1340375844.365 [keepstation_3km:NeedComms:A](INFO): Aggregate::uninitialize keepstation_3km:NeedComms:A
2012-06-22T14:37:24.365Z,1340375844.365 [keepstation_3km:NeedComms](INFO): Completed keepstation_3km:NeedComms
2012-06-22T14:37:24.365Z,1340375844.365 [keepstation_3km:NeedComms] Stopped
2012-06-22T14:37:24.365Z,1340375844.365 [keepstation_3km:NeedComms](INFO): Aggregate::uninitialize keepstation_3km:NeedComms
2012-06-22T14:37:30.351Z,1340375850.351 [NAL9601](INFO): Powering down
2012-06-22T14:37:41.176Z,1340375861.177 [Radio_Freewave](INFO): Powering down
2012-06-22T14:37:47.421Z,1340375867.421 [Radio_Freewave](INFO): Powering up
2012-06-22T14:37:59.080Z,1340375879.080 [Radio_Freewave](INFO): Powering down
2012-06-22T14:38:05.435Z,1340375885.435 [Radio_Freewave](INFO): Powering up
2012-06-22T14:38:14.618Z,1340375894.618 [Radio_Freewave](INFO): Powering down
2012-06-22T14:38:21.013Z,1340375901.013 [Radio_Freewave](INFO): Powering up
2012-06-22T14:38:43.405Z,1340375923.405 [Radio_Freewave](INFO): Powering down
2012-06-22T14:38:59.733Z,1340375939.733 [Radio_Freewave](INFO): Powering up
2012-06-22T14:39:01.339Z,1340375941.339 [Radio_Freewave](INFO): Powering down
2012-06-22T15:22:24.244Z,1340378544.244 [keepstation_3km:NeedComms] Running Loop=1
2012-06-22T15:22:24.245Z,1340378544.245 [keepstation_3km:NeedComms](INFO): Aggregate::initialize keepstation_3km:NeedComms
2012-06-22T15:22:24.245Z,1340378544.245 [keepstation_3km:NeedComms:A] Running Loop=1
2012-06-22T15:22:24.245Z,1340378544.245 [keepstation_3km:NeedComms:A](INFO): Aggregate::initialize keepstation_3km:NeedComms:A
2012-06-22T15:22:24.637Z,1340378544.636 [keepstation_3km:SURFACECOMMS] Running Loop=1
2012-06-22T15:22:24.637Z,1340378544.637 [keepstation_3km:SURFACECOMMS](INFO): Aggregate::initialize keepstation_3km:SURFACECOMMS
2012-06-22T15:22:24.637Z,1340378544.637 [keepstation_3km:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-06-22T15:22:24.637Z,1340378544.637 [keepstation_3km:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T15:23:17.646Z,1340378597.646 [keepstation_3km:KeepStation:D.KeepStation](INFO): Rest->Waypoint
2012-06-22T15:27:09.542Z,1340378829.542 [Radio_Freewave](INFO): Powering up
2012-06-22T15:27:09.554Z,1340378829.555 [keepstation_3km:SURFACECOMMS:B] Running Loop=1
2012-06-22T15:27:09.555Z,1340378829.555 [keepstation_3km:SURFACECOMMS:B](INFO): Aggregate::initialize keepstation_3km:SURFACECOMMS:B
2012-06-22T15:27:09.555Z,1340378829.555 [keepstation_3km:SURFACECOMMS:B:A] Running Loop=1
2012-06-22T15:27:10.341Z,1340378830.341 [NAL9601](INFO): Powering up
2012-06-22T15:28:15.967Z,1340378895.967 [NAL9601](INFO): NAL9601 initialized
2012-06-22T15:28:17.034Z,1340378897.034 [NAL9601](IMPORTANT): GPS fix at: 1340379155.00
2012-06-22T15:28:17.049Z,1340378897.049 [Navigation](IMPORTANT): Error 6.7 %DT at bearing -63.7 deg., over the last 587.6 m.
2012-06-22T15:28:17.049Z,1340378897.049 [Navigation](IMPORTANT): Distance made good 87.4 m, at (36.8059, -121.8235).
2012-06-22T15:28:17.051Z,1340378897.050 [keepstation_3km:SURFACECOMMS:B:A] Stopped
2012-06-22T15:28:17.051Z,1340378897.051 [keepstation_3km:SURFACECOMMS:B:B] Running Loop=1
2012-06-22T15:28:36.203Z,1340378916.203 [NAL9601](IMPORTANT): SBD MO Status=1, MOMSN=10783, MT Status=1, MTMSN=2787
2012-06-22T15:28:36.323Z,1340378916.323 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0025.lzma
2012-06-22T15:28:36.323Z,1340378916.323 [NAL9601](INFO): Packets left to send: 3
2012-06-22T15:28:36.326Z,1340378916.326 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0025.lzma.parts/0003.sbd
2012-06-22T15:28:36.739Z,1340378916.740 [NAL9601](INFO): Received command:stop
2012-06-22T15:28:36.743Z,1340378916.743 [CommandLine](IMPORTANT): got command stop
2012-06-22T15:28:36.764Z,1340378916.764 [keepstation_3km] Stopped
2012-06-22T15:28:36.764Z,1340378916.764 [keepstation_3km](INFO): Aggregate::uninitialize keepstation_3km
2012-06-22T15:28:36.764Z,1340378916.764 [keepstation_3km:AltEnvWrapper] Stopped
2012-06-22T15:28:36.764Z,1340378916.764 [keepstation_3km:AltEnvWrapper](INFO): Aggregate::uninitialize keepstation_3km:AltEnvWrapper
2012-06-22T15:28:36.765Z,1340378916.764 [keepstation_3km:AltEnvWrapper:A.AltitudeEnvelope] Stopped
2012-06-22T15:28:36.765Z,1340378916.765 [keepstation_3km:AltEnvWrapper:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2012-06-22T15:28:36.765Z,1340378916.765 [keepstation_3km:AltEnvWrapper:B.Wait] Stopped
2012-06-22T15:28:36.765Z,1340378916.765 [keepstation_3km:AltEnvWrapper:B.Wait](DEBUG): Uninitialize Wait Component.
2012-06-22T15:28:36.765Z,1340378916.765 [keepstation_3km:B.DepthEnvelope] Stopped
2012-06-22T15:28:36.765Z,1340378916.765 [keepstation_3km:B.DepthEnvelope](DEBUG): Uninitialize.
2012-06-22T15:28:36.765Z,1340378916.765 [keepstation_3km:SURFACECOMMS] Stopped
2012-06-22T15:28:36.765Z,1340378916.765 [keepstation_3km:SURFACECOMMS](INFO): Aggregate::uninitialize keepstation_3km:SURFACECOMMS
2012-06-22T15:28:36.765Z,1340378916.765 [keepstation_3km:SURFACECOMMS:A.GoToSurface] Stopped
2012-06-22T15:28:36.765Z,1340378916.765 [keepstation_3km:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T15:28:36.765Z,1340378916.765 [keepstation_3km:SURFACECOMMS:B] Stopped
2012-06-22T15:28:36.765Z,1340378916.765 [keepstation_3km:SURFACECOMMS:B](INFO): Aggregate::uninitialize keepstation_3km:SURFACECOMMS:B
2012-06-22T15:28:36.766Z,1340378916.766 [keepstation_3km:SURFACECOMMS:B:B] Stopped
2012-06-22T15:28:36.766Z,1340378916.766 [keepstation_3km:OffshoreWrapper] Stopped
2012-06-22T15:28:36.766Z,1340378916.766 [keepstation_3km:OffshoreWrapper](INFO): Aggregate::uninitialize keepstation_3km:OffshoreWrapper
2012-06-22T15:28:36.766Z,1340378916.766 [keepstation_3km:OffshoreWrapper:A.OffshoreEnvelope] Stopped
2012-06-22T15:28:36.766Z,1340378916.766 [keepstation_3km:OffshoreWrapper:A.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2012-06-22T15:28:36.766Z,1340378916.766 [keepstation_3km:OffshoreWrapper:B.Wait] Stopped
2012-06-22T15:28:36.766Z,1340378916.766 [keepstation_3km:OffshoreWrapper:B.Wait](DEBUG): Uninitialize Wait Component.
2012-06-22T15:28:36.766Z,1340378916.766 [keepstation_3km:NeedComms] Stopped
2012-06-22T15:28:36.767Z,1340378916.767 [keepstation_3km:NeedComms](INFO): Aggregate::uninitialize keepstation_3km:NeedComms
2012-06-22T15:28:36.767Z,1340378916.767 [keepstation_3km:NeedComms:A] Stopped
2012-06-22T15:28:36.767Z,1340378916.767 [keepstation_3km:NeedComms:A](INFO): Aggregate::uninitialize keepstation_3km:NeedComms:A
2012-06-22T15:28:36.767Z,1340378916.767 [keepstation_3km:KeepStation] Stopped
2012-06-22T15:28:36.767Z,1340378916.767 [keepstation_3km:KeepStation](INFO): Aggregate::uninitialize keepstation_3km:KeepStation
2012-06-22T15:28:36.767Z,1340378916.767 [keepstation_3km:KeepStation:A] Stopped
2012-06-22T15:28:36.767Z,1340378916.767 [keepstation_3km:KeepStation:B.Pitch] Stopped
2012-06-22T15:28:36.767Z,1340378916.767 [keepstation_3km:KeepStation:C.SetSpeed] Stopped
2012-06-22T15:28:36.767Z,1340378916.767 [keepstation_3km:KeepStation:C.SetSpeed](DEBUG): Uninitialize.
2012-06-22T15:28:36.767Z,1340378916.767 [keepstation_3km:KeepStation:D.KeepStation] Stopped
2012-06-22T15:28:36.767Z,1340378916.767 [keepstation_3km:KeepStation:D.KeepStation](DEBUG): Uninitialize KeepStationComponent.
2012-06-22T15:28:36.767Z,1340378916.767 [keepstation_3km:KeepStation:E.Wait] Stopped
2012-06-22T15:28:36.767Z,1340378916.767 [keepstation_3km:KeepStation:E.Wait](DEBUG): Uninitialize Wait Component.
2012-06-22T15:28:37.158Z,1340378917.158 [MissionManager](IMPORTANT): Started mission Default
2012-06-22T15:28:37.158Z,1340378917.158 [Default] Running Loop=1
2012-06-22T15:28:37.158Z,1340378917.158 [Default](INFO): Aggregate::initialize Default
2012-06-22T15:28:37.158Z,1340378917.158 [Default:D.SetSpeed] Running Loop=1
2012-06-22T15:28:37.158Z,1340378917.158 [Default:D.SetSpeed](DEBUG): Initialize.
2012-06-22T15:28:37.158Z,1340378917.158 [Default:E.GoToSurface] Running Loop=1
2012-06-22T15:28:37.158Z,1340378917.158 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T15:28:37.159Z,1340378917.159 [Default:Iridium] Running Loop=1
2012-06-22T15:28:37.159Z,1340378917.159 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-06-22T15:28:37.159Z,1340378917.159 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-06-22T15:28:37.159Z,1340378917.159 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-06-22T15:28:37.159Z,1340378917.159 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-06-22T15:28:37.159Z,1340378917.159 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T15:28:37.160Z,1340378917.160 [Default:CallIridium] Running Loop=1
2012-06-22T15:28:37.161Z,1340378917.161 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-06-22T15:28:37.161Z,1340378917.161 [Default:CallIridium:A] Running Loop=1
2012-06-22T15:28:37.161Z,1340378917.161 [Default:CallIridium:A] Stopped
2012-06-22T15:28:37.161Z,1340378917.161 [Default:CallIridium:B] Running Loop=1
2012-06-22T15:28:37.161Z,1340378917.161 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-06-22T15:28:37.162Z,1340378917.162 [Default:Iridium:B.GoToSurface] Stopped
2012-06-22T15:28:37.162Z,1340378917.162 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T15:28:37.162Z,1340378917.162 [Default:Iridium:Read_Iridium] Running Loop=1
2012-06-22T15:28:37.163Z,1340378917.163 [Default:GPS] Running Loop=1
2012-06-22T15:28:37.163Z,1340378917.163 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-06-22T15:28:37.163Z,1340378917.163 [Default:GPS:A.SetSpeed] Running Loop=1
2012-06-22T15:28:37.163Z,1340378917.163 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-06-22T15:28:37.163Z,1340378917.163 [Default:GPS:B.GoToSurface] Running Loop=1
2012-06-22T15:28:37.163Z,1340378917.163 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T15:28:37.164Z,1340378917.164 [Default:GPS:B.GoToSurface] Stopped
2012-06-22T15:28:37.164Z,1340378917.164 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T15:28:37.164Z,1340378917.164 [Default:GPS:Read_GPS] Running Loop=1
2012-06-22T15:28:39.159Z,1340378919.159 [NAL9601](IMPORTANT): GPS fix at: 1340379177.00
2012-06-22T15:28:39.175Z,1340378919.175 [Default:GPS:Read_GPS] Stopped
2012-06-22T15:28:39.175Z,1340378919.175 [Default:GPS:D] Running Loop=1
2012-06-22T15:28:39.531Z,1340378919.531 [Default:GPS:D] Stopped
2012-06-22T15:28:39.532Z,1340378919.532 [Default:GPS](INFO): Completed Default:GPS
2012-06-22T15:28:39.532Z,1340378919.532 [Default:GPS] Stopped
2012-06-22T15:28:39.532Z,1340378919.532 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-06-22T15:28:39.532Z,1340378919.532 [Default:GPS:A.SetSpeed] Stopped
2012-06-22T15:28:39.532Z,1340378919.532 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T15:29:00.217Z,1340378940.217 [NAL9601](IMPORTANT): SBD MO Status=1, MOMSN=10784, MT Status=1, MTMSN=2788
2012-06-22T15:29:00.387Z,1340378940.387 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T080505/shore0025.lzma
2012-06-22T15:29:00.387Z,1340378940.387 [NAL9601](INFO): Packets left to send: 2
2012-06-22T15:29:00.388Z,1340378940.388 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T080505/shore0025.lzma.parts/0002.sbd
2012-06-22T15:29:00.796Z,1340378940.796 [NAL9601](INFO): Received command:restart logs
2012-06-22T15:29:00.859Z,1340378940.859 [CommandLine](IMPORTANT): got command restart logs