2012-06-22T01:40:39.654Z,1340329239.654 [NAL9601](INFO): SBD MO Status=1, MOMSN=10540, MT Status=0, MTMSN=0
2012-06-22T01:40:39.791Z,1340329239.791 [NAL9601](INFO): Sent 146 bytes from file Logs/20120621T224824/shore0011.lzma
2012-06-22T01:40:39.791Z,1340329239.791 [NAL9601](INFO): Packets left to send: 0
2012-06-22T01:40:40.351Z,1340329240.351 [NAL9601](INFO): Stored copy of sent data in Logs/20120621T224824/shore0011.lzma.parts/0000.sbd
2012-06-22T01:40:46.466Z,1340329246.466 [NAL9601](INFO): SBD MO Status=0, MOMSN=10541, MT Status=0, MTMSN=0
2012-06-22T01:41:02.016Z,1340329262.016 [NAL9601](INFO): SBD MO Status=1, MOMSN=10542, MT Status=0, MTMSN=0
2012-06-22T01:41:02.143Z,1340329262.143 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0000.lzma
2012-06-22T01:41:02.143Z,1340329262.143 [NAL9601](INFO): Packets left to send: 1
2012-06-22T01:41:02.145Z,1340329262.145 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0000.lzma.parts/0001.sbd
2012-06-22T01:41:09.786Z,1340329269.786 [NAL9601](INFO): SBD MO Status=1, MOMSN=10543, MT Status=0, MTMSN=0
2012-06-22T01:41:09.931Z,1340329269.931 [NAL9601](INFO): Sent 185 bytes from file Logs/20120622T014020/shore0000.lzma
2012-06-22T01:41:09.931Z,1340329269.931 [NAL9601](INFO): Packets left to send: 0
2012-06-22T01:41:09.932Z,1340329269.932 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0000.lzma.parts/0000.sbd
2012-06-22T01:41:17.004Z,1340329277.004 [NAL9601](INFO): SBD MO Status=0, MOMSN=10544, MT Status=0, MTMSN=0
2012-06-22T01:41:17.234Z,1340329277.234 [Default:Iridium:Read_Iridium] Stopped
2012-06-22T01:41:17.234Z,1340329277.234 [Default:Iridium](INFO): Completed Default:Iridium
2012-06-22T01:41:17.234Z,1340329277.234 [Default:Iridium] Stopped
2012-06-22T01:41:17.235Z,1340329277.234 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-06-22T01:41:17.235Z,1340329277.235 [Default:Iridium:A.SetSpeed] Stopped
2012-06-22T01:41:17.235Z,1340329277.235 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T01:41:17.395Z,1340329277.395 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2012-06-22T01:41:17.395Z,1340329277.395 [Default:CallIridium:B] Stopped
2012-06-22T01:41:17.395Z,1340329277.395 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-06-22T01:41:17.396Z,1340329277.396 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-06-22T01:41:17.396Z,1340329277.396 [Default:CallIridium] Stopped
2012-06-22T01:41:17.396Z,1340329277.396 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-06-22T01:41:27.673Z,1340329287.673 [NAL9601](INFO): Powering down
2012-06-22T01:46:17.562Z,1340329577.562 [Default:CallIridium] Running Loop=1
2012-06-22T01:46:17.562Z,1340329577.562 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-06-22T01:46:17.562Z,1340329577.562 [Default:CallIridium:A] Running Loop=1
2012-06-22T01:46:17.563Z,1340329577.563 [Default:CallIridium:A] Stopped
2012-06-22T01:46:17.563Z,1340329577.563 [Default:CallIridium:B] Running Loop=1
2012-06-22T01:46:17.563Z,1340329577.563 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-06-22T01:46:22.583Z,1340329582.583 [Default:Iridium] Running Loop=1
2012-06-22T01:46:22.583Z,1340329582.583 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-06-22T01:46:22.583Z,1340329582.583 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-06-22T01:46:22.583Z,1340329582.583 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-06-22T01:46:22.584Z,1340329582.583 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-06-22T01:46:22.584Z,1340329582.583 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T01:46:22.584Z,1340329582.584 [Default:Iridium:B.GoToSurface] Stopped
2012-06-22T01:46:22.584Z,1340329582.584 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T01:46:22.585Z,1340329582.585 [Default:Iridium:Read_Iridium] Running Loop=1
2012-06-22T01:46:22.585Z,1340329582.585 [Default:GPS] Running Loop=1
2012-06-22T01:46:22.585Z,1340329582.585 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-06-22T01:46:22.585Z,1340329582.585 [Default:GPS:A.SetSpeed] Running Loop=1
2012-06-22T01:46:22.585Z,1340329582.585 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-06-22T01:46:22.585Z,1340329582.585 [Default:GPS:B.GoToSurface] Running Loop=1
2012-06-22T01:46:22.585Z,1340329582.585 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T01:46:22.586Z,1340329582.586 [Default:GPS:B.GoToSurface] Stopped
2012-06-22T01:46:22.586Z,1340329582.586 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T01:46:22.586Z,1340329582.586 [Default:GPS:Read_GPS] Running Loop=1
2012-06-22T01:46:23.185Z,1340329583.185 [NAL9601](INFO): Powering up
2012-06-22T01:47:28.911Z,1340329648.911 [NAL9601](INFO): NAL9601 initialized
2012-06-22T01:47:52.318Z,1340329672.318 [NAL9601](INFO): SBD MO Status=2, MOMSN=10545, MT Status=2, MTMSN=0
2012-06-22T01:47:52.319Z,1340329672.319 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-06-22T01:48:30.582Z,1340329710.582 [NAL9601](IMPORTANT): SBD MO Status=1, MOMSN=10545, MT Status=1, MTMSN=2778
2012-06-22T01:48:30.703Z,1340329710.703 [NAL9601](INFO): Sent 104 bytes from file Logs/20120622T014020/shore0001.lzma
2012-06-22T01:48:30.703Z,1340329710.703 [NAL9601](INFO): Packets left to send: 0
2012-06-22T01:48:30.705Z,1340329710.705 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0001.lzma.parts/0000.sbd
2012-06-22T01:48:31.344Z,1340329711.344 [NAL9601](INFO): Received command:load Engineering/triangle_control_speed_onelap.xml;set triangle_control_speed_onelap.Speed1 0.90 meter_per_second;set triangle_control_speed_onelap.Speed2 0.85 meter_per_second;set triangle_control_speed_onelap.Speed3 0.50 meter_per_second
2012-06-22T01:48:31.395Z,1340329711.395 [CommandLine](IMPORTANT): got command load ./Missions/Engineering/triangle_control_speed_onelap.xml
2012-06-22T01:48:31.396Z,1340329711.396 [MissionManager](INFO): Loading Mission: ./Missions/Engineering/triangle_control_speed_onelap.xml
2012-06-22T01:48:31.571Z,1340329711.571 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.Wpt1Lat = 36.815000 arcdeg
2012-06-22T01:48:31.573Z,1340329711.573 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.Wpt1Lon = -121.869000 arcdeg
2012-06-22T01:48:31.576Z,1340329711.576 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.Speed1 = 1.000000 m/s
2012-06-22T01:48:31.578Z,1340329711.578 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.Wpt2Lat = 36.829000 arcdeg
2012-06-22T01:48:31.580Z,1340329711.580 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.Wpt2Lon = -121.886000 arcdeg
2012-06-22T01:48:31.583Z,1340329711.583 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.Speed2 = 0.750000 m/s
2012-06-22T01:48:31.585Z,1340329711.585 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.Wpt3Lat = 36.816000 arcdeg
2012-06-22T01:48:31.587Z,1340329711.587 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.Wpt3Lon = -121.883000 arcdeg
2012-06-22T01:48:31.590Z,1340329711.589 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.Speed3 = 0.600000 m/s
2012-06-22T01:48:31.592Z,1340329711.592 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.MaxDepth = 37.000000 m
2012-06-22T01:48:31.594Z,1340329711.594 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.MinAltitude = 7.000000 m
2012-06-22T01:48:31.596Z,1340329711.596 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.DepthShallow = 15.000000 m
2012-06-22T01:48:31.598Z,1340329711.598 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.DepthDeep = 32.000000 m
2012-06-22T01:48:31.600Z,1340329711.600 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.NeedCommsTime = 50.000000 min
2012-06-22T01:48:31.603Z,1340329711.602 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.TimeoutDuration = 250.000000 min
2012-06-22T01:48:31.604Z,1340329711.604 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.MassHold = 1 bool
2012-06-22T01:48:31.607Z,1340329711.607 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.MassDefault = -0.007500 n/a
2012-06-22T01:48:31.609Z,1340329711.609 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.massDeadband = 0.000000 mm
2012-06-22T01:48:31.611Z,1340329711.611 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.BuoyancyHold = 1 bool
2012-06-22T01:48:31.613Z,1340329711.613 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.BuoyancyNeutral = 0.000505 n/a
2012-06-22T01:48:31.615Z,1340329711.615 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.ElevatorHold = 0 bool
2012-06-22T01:48:31.617Z,1340329711.617 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.ElevatorDefault = 0.000000 arcdeg
2012-06-22T01:48:31.620Z,1340329711.620 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.elevDeadband = 0.000000 arcdeg
2012-06-22T01:48:31.621Z,1340329711.621 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.ElevIntInDepthMode = 0 bool
2012-06-22T01:48:31.624Z,1340329711.624 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.StopDepthExcursion = 5.000000 n/a
2012-06-22T01:48:31.626Z,1340329711.626 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.kdPitchMass = 0.000000 n/a
2012-06-22T01:48:31.629Z,1340329711.629 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.kiDepthOff = 0.100000 n/a
2012-06-22T01:48:31.631Z,1340329711.631 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.kiPitchMass = 0.001500 n/a
2012-06-22T01:48:31.633Z,1340329711.633 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.kpPitchMass = 0.010000 n/a
2012-06-22T01:48:31.636Z,1340329711.636 [MissionManager](INFO): DefineArg triangle_control_speed_onelap.maxDiveAccel = 0.001000 n/a
2012-06-22T01:48:31.637Z,1340329711.637 [triangle_control_speed_onelap:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2012-06-22T01:48:31.645Z,1340329711.645 [triangle_control_speed_onelap:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2012-06-22T01:48:31.650Z,1340329711.650 [triangle_control_speed_onelap:C.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2012-06-22T01:48:31.656Z,1340329711.656 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Construct GoToSurface.
2012-06-22T01:48:31.659Z,1340329711.659 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Construct.
2012-06-22T01:48:31.668Z,1340329711.668 [triangle_control_speed_onelap:WaypointOne:A.Pitch](DEBUG): Construct.
2012-06-22T01:48:31.672Z,1340329711.672 [triangle_control_speed_onelap:WaypointOne:B.SetSpeed](DEBUG): Construct.
2012-06-22T01:48:31.675Z,1340329711.675 [triangle_control_speed_onelap:WaypointOne:WaypointW1.Waypoint](DEBUG): Construct Waypoint.
2012-06-22T01:48:31.702Z,1340329711.702 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold:A.Buoyancy](DEBUG): Construct Buoyancy.
2012-06-22T01:48:31.705Z,1340329711.705 [triangle_control_speed_onelap:ThreeLegs:MassHold:A.Pitch](DEBUG): Construct.
2012-06-22T01:48:31.710Z,1340329711.710 [triangle_control_speed_onelap:ThreeLegs:ElevatorHold:A.Pitch](DEBUG): Construct.
2012-06-22T01:48:31.725Z,1340329711.725 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:A.SetSpeed](DEBUG): Construct.
2012-06-22T01:48:31.727Z,1340329711.727 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:B.Pitch](DEBUG): Construct.
2012-06-22T01:48:31.730Z,1340329711.730 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:Wpt2.Waypoint](DEBUG): Construct Waypoint.
2012-06-22T01:48:31.736Z,1340329711.736 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:A.SetSpeed](DEBUG): Construct.
2012-06-22T01:48:31.738Z,1340329711.738 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:B.Pitch](DEBUG): Construct.
2012-06-22T01:48:31.741Z,1340329711.741 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:Wpt3.Waypoint](DEBUG): Construct Waypoint.
2012-06-22T01:48:31.746Z,1340329711.746 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:A.SetSpeed](DEBUG): Construct.
2012-06-22T01:48:31.749Z,1340329711.749 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:B.Pitch](DEBUG): Construct.
2012-06-22T01:48:31.752Z,1340329711.752 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:Wpt1.Waypoint](DEBUG): Construct Waypoint.
2012-06-22T01:48:31.769Z,1340329711.769 [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-22T01:48:31.770Z,1340329711.770 [CommandLine](IMPORTANT): Loaded ./Missions/Engineering/triangle_control_speed_onelap.xml
2012-06-22T01:48:31.923Z,1340329711.923 [CommandLine](IMPORTANT): got command set triangle_control_speed_onelap.Speed1 0.900000 meter_per_second
2012-06-22T01:48:32.097Z,1340329712.098 [CommandLine](IMPORTANT): got command set triangle_control_speed_onelap.Speed2 0.850000 meter_per_second
2012-06-22T01:48:32.251Z,1340329712.251 [CommandLine](IMPORTANT): got command set triangle_control_speed_onelap.Speed3 0.500000 meter_per_second
2012-06-22T01:48:50.705Z,1340329730.705 [NAL9601](IMPORTANT): SBD MO Status=0, MOMSN=10546, MT Status=1, MTMSN=2779
2012-06-22T01:48:51.316Z,1340329731.316 [NAL9601](INFO): Received command:set triangle_control_speed_onelap.StopDepthExcursion 15 meter;run 
2012-06-22T01:48:51.653Z,1340329731.653 [CommandLine](IMPORTANT): got command set triangle_control_speed_onelap.StopDepthExcursion 15.000000 meter
2012-06-22T01:48:51.654Z,1340329731.654 [CommandLine](IMPORTANT): got command run 
2012-06-22T01:48:51.654Z,1340329731.654 [CommandLine](IMPORTANT): Running 
2012-06-22T01:48:51.811Z,1340329731.811 [Default] Stopped
2012-06-22T01:48:51.812Z,1340329731.812 [Default](INFO): Aggregate::uninitialize Default
2012-06-22T01:48:51.812Z,1340329731.812 [Default:GPS] Stopped
2012-06-22T01:48:51.812Z,1340329731.812 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-06-22T01:48:51.812Z,1340329731.812 [Default:GPS:A.SetSpeed] Stopped
2012-06-22T01:48:51.812Z,1340329731.812 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T01:48:51.812Z,1340329731.812 [Default:GPS:Read_GPS] Stopped
2012-06-22T01:48:51.812Z,1340329731.812 [Default:Iridium] Stopped
2012-06-22T01:48:51.812Z,1340329731.812 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-06-22T01:48:51.812Z,1340329731.812 [Default:Iridium:A.SetSpeed] Stopped
2012-06-22T01:48:51.812Z,1340329731.812 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T01:48:51.812Z,1340329731.812 [Default:Iridium:Read_Iridium] Stopped
2012-06-22T01:48:51.812Z,1340329731.812 [Default:CallIridium] Stopped
2012-06-22T01:48:51.812Z,1340329731.812 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-06-22T01:48:51.813Z,1340329731.813 [Default:CallIridium:B] Stopped
2012-06-22T01:48:51.813Z,1340329731.813 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-06-22T01:48:51.813Z,1340329731.813 [Default:D.SetSpeed] Stopped
2012-06-22T01:48:51.813Z,1340329731.813 [Default:D.SetSpeed](DEBUG): Uninitialize.
2012-06-22T01:48:51.813Z,1340329731.813 [Default:E.GoToSurface] Stopped
2012-06-22T01:48:51.813Z,1340329731.813 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T01:48:51.813Z,1340329731.813 [Default:F.Wait] Stopped
2012-06-22T01:48:51.813Z,1340329731.813 [Default:F.Wait](DEBUG): Uninitialize Wait Component.
2012-06-22T01:48:51.813Z,1340329731.813 [MissionManager](IMPORTANT): Started mission triangle_control_speed_onelap
2012-06-22T01:48:51.813Z,1340329731.813 [triangle_control_speed_onelap] Running Loop=1
2012-06-22T01:48:51.814Z,1340329731.814 [triangle_control_speed_onelap](INFO): Aggregate::initialize triangle_control_speed_onelap
2012-06-22T01:48:51.814Z,1340329731.814 [triangle_control_speed_onelap:A.AltitudeEnvelope] Running Loop=1
2012-06-22T01:48:51.814Z,1340329731.814 [triangle_control_speed_onelap:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2012-06-22T01:48:51.814Z,1340329731.814 [triangle_control_speed_onelap:B.DepthEnvelope] Running Loop=1
2012-06-22T01:48:51.814Z,1340329731.814 [triangle_control_speed_onelap:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2012-06-22T01:48:51.819Z,1340329731.819 [triangle_control_speed_onelap:C.OffshoreEnvelope] Running Loop=1
2012-06-22T01:48:51.819Z,1340329731.819 [triangle_control_speed_onelap:C.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2012-06-22T01:48:51.819Z,1340329731.819 [triangle_control_speed_onelap:D] Running Loop=1
2012-06-22T01:48:51.819Z,1340329731.819 [triangle_control_speed_onelap:SURFACECOMMS] Running Loop=1
2012-06-22T01:48:51.819Z,1340329731.819 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS
2012-06-22T01:48:51.820Z,1340329731.819 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-06-22T01:48:51.820Z,1340329731.819 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T01:48:51.821Z,1340329731.821 [triangle_control_speed_onelap:SURFACECOMMS:B] Running Loop=1
2012-06-22T01:48:51.822Z,1340329731.822 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS:B
2012-06-22T01:48:51.822Z,1340329731.822 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Running Loop=1
2012-06-22T01:48:51.822Z,1340329731.822 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize.
2012-06-22T01:48:51.822Z,1340329731.822 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Running Loop=1
2012-06-22T01:48:51.822Z,1340329731.822 [triangle_control_speed_onelap:D] Running Loop=1
2012-06-22T01:48:51.827Z,1340329731.827 [triangle_control_speed_onelap:D](DEBUG): Initialize ReadDataComponent to sense platform_orientation
2012-06-22T01:48:51.827Z,1340329731.828 [triangle_control_speed_onelap:C.OffshoreEnvelope] Running Loop=1
2012-06-22T01:48:51.829Z,1340329731.829 [triangle_control_speed_onelap:B.DepthEnvelope] Running Loop=1
2012-06-22T01:48:51.830Z,1340329731.830 [triangle_control_speed_onelap:A.AltitudeEnvelope] Running Loop=1
2012-06-22T01:48:52.056Z,1340329732.056 [triangle_control_speed_onelap:SURFACECOMMS:B:B](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-06-22T01:48:52.056Z,1340329732.056 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Running Loop=1
2012-06-22T01:49:10.007Z,1340329750.007 [Radio_Freewave](INFO): Powering down
2012-06-22T01:49:15.925Z,1340329755.925 [Radio_Freewave](INFO): Powering up
2012-06-22T01:49:50.326Z,1340329790.326 [NAL9601](IMPORTANT): GPS fix at: 1340329972.00
2012-06-22T01:49:50.341Z,1340329790.341 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Stopped
2012-06-22T01:49:50.341Z,1340329790.341 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Running Loop=1
2012-06-22T01:49:50.746Z,1340329790.746 [triangle_control_speed_onelap:SURFACECOMMS:B:C](DEBUG): Initialize ReadDataComponent to sense platform_communications
2012-06-22T01:50:03.345Z,1340329803.345 [NAL9601](INFO): SBD MO Status=0, MOMSN=10547, MT Status=0, MTMSN=0
2012-06-22T01:50:22.882Z,1340329822.882 [NAL9601](INFO): SBD MO Status=1, MOMSN=10548, MT Status=0, MTMSN=0
2012-06-22T01:50:23.027Z,1340329823.027 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0002.lzma
2012-06-22T01:50:23.027Z,1340329823.027 [NAL9601](INFO): Packets left to send: 1
2012-06-22T01:50:23.029Z,1340329823.029 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0002.lzma.parts/0001.sbd
2012-06-22T01:50:34.256Z,1340329834.256 [NAL9601](INFO): SBD MO Status=1, MOMSN=10549, MT Status=0, MTMSN=0
2012-06-22T01:50:34.411Z,1340329834.411 [NAL9601](INFO): Sent 284 bytes from file Logs/20120622T014020/shore0002.lzma
2012-06-22T01:50:34.411Z,1340329834.411 [NAL9601](INFO): Packets left to send: 0
2012-06-22T01:50:34.412Z,1340329834.412 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0002.lzma.parts/0000.sbd
2012-06-22T01:50:41.041Z,1340329841.041 [NAL9601](INFO): SBD MO Status=0, MOMSN=10550, MT Status=0, MTMSN=0
2012-06-22T01:50:41.222Z,1340329841.222 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Stopped
2012-06-22T01:50:41.222Z,1340329841.223 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Running Loop=1
2012-06-22T01:50:41.482Z,1340329841.482 [triangle_control_speed_onelap:SURFACECOMMS:B:D](DEBUG): Initialize ReadDataComponent to sense latitude_fix
2012-06-22T01:50:43.439Z,1340329843.439 [NAL9601](IMPORTANT): GPS fix at: 1340330025.00
2012-06-22T01:50:43.466Z,1340329843.466 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Stopped
2012-06-22T01:50:43.467Z,1340329843.467 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS:B
2012-06-22T01:50:43.467Z,1340329843.467 [triangle_control_speed_onelap:SURFACECOMMS:B] Stopped
2012-06-22T01:50:43.467Z,1340329843.467 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS:B
2012-06-22T01:50:43.467Z,1340329843.467 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Stopped
2012-06-22T01:50:43.467Z,1340329843.467 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T01:50:43.468Z,1340329843.468 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS
2012-06-22T01:50:43.468Z,1340329843.468 [triangle_control_speed_onelap:SURFACECOMMS] Stopped
2012-06-22T01:50:43.468Z,1340329843.468 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS
2012-06-22T01:50:43.468Z,1340329843.468 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Stopped
2012-06-22T01:50:43.468Z,1340329843.468 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T01:50:43.469Z,1340329843.469 [triangle_control_speed_onelap:WaypointOne] Running Loop=1
2012-06-22T01:50:43.469Z,1340329843.469 [triangle_control_speed_onelap:WaypointOne](INFO): Aggregate::initialize triangle_control_speed_onelap:WaypointOne
2012-06-22T01:50:43.469Z,1340329843.469 [triangle_control_speed_onelap:WaypointOne:A.Pitch] Running Loop=1
2012-06-22T01:50:43.469Z,1340329843.469 [triangle_control_speed_onelap:WaypointOne:A.Pitch](DEBUG): Initialize.
2012-06-22T01:50:43.469Z,1340329843.469 [triangle_control_speed_onelap:WaypointOne:B.SetSpeed] Running Loop=1
2012-06-22T01:50:43.469Z,1340329843.469 [triangle_control_speed_onelap:WaypointOne:B.SetSpeed](DEBUG): Initialize.
2012-06-22T01:50:43.469Z,1340329843.469 [triangle_control_speed_onelap:WaypointOne:WaypointW1.Waypoint] Running Loop=1
2012-06-22T01:50:43.470Z,1340329843.470 [triangle_control_speed_onelap:WaypointOne:WaypointW1.Waypoint](DEBUG): Initialize WaypointComponent.
2012-06-22T01:50:43.870Z,1340329843.870 [triangle_control_speed_onelap:WaypointOne:B.SetSpeed] Running Loop=1
2012-06-22T01:50:43.871Z,1340329843.871 [triangle_control_speed_onelap:WaypointOne:A.Pitch] Running Loop=1
2012-06-22T01:50:50.949Z,1340329850.949 [NAL9601](INFO): Powering down
2012-06-22T01:51:00.496Z,1340329860.496 [Radio_Freewave](INFO): Powering down
2012-06-22T01:51:01.675Z,1340329861.675 [Radio_Freewave](INFO): Powering up
2012-06-22T01:51:13.706Z,1340329873.706 [Radio_Freewave](INFO): Powering down
2012-06-22T01:51:20.181Z,1340329880.181 [Radio_Freewave](INFO): Powering up
2012-06-22T01:51:30.181Z,1340329890.181 [Radio_Freewave](INFO): Powering down
2012-06-22T01:51:43.389Z,1340329903.389 [Radio_Freewave](INFO): Powering up
2012-06-22T01:51:52.631Z,1340329912.631 [Radio_Freewave](INFO): Powering down
2012-06-22T01:51:53.784Z,1340329913.784 [Radio_Freewave](INFO): Powering up
2012-06-22T01:51:55.365Z,1340329915.365 [Radio_Freewave](INFO): Powering down
2012-06-22T01:52:04.200Z,1340329924.201 [Radio_Freewave](INFO): Powering up
2012-06-22T01:52:08.980Z,1340329928.980 [Radio_Freewave](INFO): Powering down
2012-06-22T01:52:10.965Z,1340329930.965 [Radio_Freewave](INFO): Powering up
2012-06-22T01:52:12.590Z,1340329932.589 [Radio_Freewave](INFO): Powering down
2012-06-22T01:57:27.180Z,1340330247.180 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T01:57:29.580Z,1340330249.580 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T01:57:31.986Z,1340330251.986 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T01:57:34.379Z,1340330254.379 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T01:57:36.794Z,1340330256.793 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T01:57:39.223Z,1340330259.224 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T01:57:41.624Z,1340330261.624 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T01:57:42.418Z,1340330262.418 [triangle_control_speed_onelap:WaypointOne:WaypointW1.Waypoint](INFO): Reached Waypoint: 36.815000,-121.869000
2012-06-22T01:57:42.419Z,1340330262.419 [triangle_control_speed_onelap:WaypointOne:WaypointW1.Waypoint] Stopped
2012-06-22T01:57:42.419Z,1340330262.419 [triangle_control_speed_onelap:WaypointOne:WaypointW1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2012-06-22T01:57:42.419Z,1340330262.419 [triangle_control_speed_onelap:WaypointOne:PHONEHOMEWPT1] Running Loop=1
2012-06-22T01:57:42.419Z,1340330262.419 [triangle_control_speed_onelap:WaypointOne:PHONEHOMEWPT1](INFO): Aggregate::initialize triangle_control_speed_onelap:WaypointOne:PHONEHOMEWPT1
2012-06-22T01:57:42.831Z,1340330262.831 [triangle_control_speed_onelap:SURFACECOMMS] Running Loop=1
2012-06-22T01:57:42.831Z,1340330262.831 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS
2012-06-22T01:57:42.831Z,1340330262.832 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-06-22T01:57:42.832Z,1340330262.832 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T01:57:44.075Z,1340330264.075 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T01:57:46.423Z,1340330266.423 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T01:57:48.824Z,1340330268.824 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T01:57:51.223Z,1340330271.223 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T01:57:53.624Z,1340330273.624 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T01:57:56.036Z,1340330276.036 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T01:57:58.423Z,1340330278.423 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T01:58:00.789Z,1340330280.789 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T01:58:03.189Z,1340330283.189 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T01:58:05.990Z,1340330285.990 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T01:58:08.388Z,1340330288.388 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T01:58:10.724Z,1340330290.724 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T01:58:13.123Z,1340330293.123 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T01:58:15.523Z,1340330295.523 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T01:58:17.939Z,1340330297.940 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T01:58:20.340Z,1340330300.339 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T01:58:22.735Z,1340330302.735 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T01:58:25.180Z,1340330305.180 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T01:58:27.440Z,1340330307.440 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T01:58:29.828Z,1340330309.828 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T01:58:32.228Z,1340330312.227 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T01:58:34.920Z,1340330314.920 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T01:58:37.322Z,1340330317.322 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T01:58:39.723Z,1340330319.723 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T01:58:42.123Z,1340330322.123 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T01:58:44.416Z,1340330324.416 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T01:58:46.816Z,1340330326.816 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T01:58:49.228Z,1340330329.228 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T01:58:51.632Z,1340330331.632 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T01:58:53.968Z,1340330333.968 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T01:58:56.768Z,1340330336.768 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T02:01:10.278Z,1340330470.279 [Radio_Freewave](INFO): Powering up
2012-06-22T02:01:10.290Z,1340330470.290 [triangle_control_speed_onelap:SURFACECOMMS:B] Running Loop=1
2012-06-22T02:01:10.290Z,1340330470.290 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS:B
2012-06-22T02:01:10.290Z,1340330470.290 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Running Loop=1
2012-06-22T02:01:10.290Z,1340330470.290 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize.
2012-06-22T02:01:10.291Z,1340330470.291 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Running Loop=1
2012-06-22T02:01:11.059Z,1340330471.059 [NAL9601](INFO): Powering up
2012-06-22T02:02:16.683Z,1340330536.683 [NAL9601](INFO): NAL9601 initialized
2012-06-22T02:02:17.828Z,1340330537.828 [NAL9601](IMPORTANT): GPS fix at: 1340330721.00
2012-06-22T02:02:17.842Z,1340330537.842 [Navigation](IMPORTANT): Error 4.7 %DT at bearing 159.1 deg., over the last 527.6 m.
2012-06-22T02:02:17.843Z,1340330537.843 [Navigation](IMPORTANT): Distance made good 252.2 m, at (36.8161, -121.8691).
2012-06-22T02:02:17.844Z,1340330537.844 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Stopped
2012-06-22T02:02:17.844Z,1340330537.844 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Running Loop=1
2012-06-22T02:02:45.447Z,1340330565.447 [NAL9601](INFO): SBD MO Status=2, MOMSN=10551, MT Status=2, MTMSN=0
2012-06-22T02:02:45.447Z,1340330565.447 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-06-22T02:03:12.573Z,1340330592.573 [NAL9601](INFO): SBD MO Status=1, MOMSN=10551, MT Status=0, MTMSN=0
2012-06-22T02:03:12.787Z,1340330592.787 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0003.lzma
2012-06-22T02:03:12.787Z,1340330592.787 [NAL9601](INFO): Packets left to send: 4
2012-06-22T02:03:12.789Z,1340330592.789 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0003.lzma.parts/0004.sbd
2012-06-22T02:03:25.531Z,1340330605.531 [NAL9601](INFO): SBD MO Status=1, MOMSN=10552, MT Status=0, MTMSN=0
2012-06-22T02:03:25.663Z,1340330605.663 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0003.lzma
2012-06-22T02:03:25.663Z,1340330605.663 [NAL9601](INFO): Packets left to send: 3
2012-06-22T02:03:25.664Z,1340330605.664 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0003.lzma.parts/0003.sbd
2012-06-22T02:03:35.623Z,1340330615.623 [NAL9601](INFO): SBD MO Status=2, MOMSN=10553, MT Status=2, MTMSN=0
2012-06-22T02:03:35.623Z,1340330615.623 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-06-22T02:03:57.162Z,1340330637.162 [NAL9601](INFO): SBD MO Status=1, MOMSN=10553, MT Status=0, MTMSN=0
2012-06-22T02:03:57.319Z,1340330637.319 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0003.lzma
2012-06-22T02:03:57.319Z,1340330637.319 [NAL9601](INFO): Packets left to send: 2
2012-06-22T02:03:57.321Z,1340330637.321 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0003.lzma.parts/0002.sbd
2012-06-22T02:04:20.861Z,1340330660.861 [NAL9601](INFO): SBD MO Status=2, MOMSN=10554, MT Status=2, MTMSN=0
2012-06-22T02:04:20.861Z,1340330660.861 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-06-22T02:04:46.457Z,1340330686.457 [NAL9601](INFO): SBD MO Status=2, MOMSN=10554, MT Status=2, MTMSN=0
2012-06-22T02:04:46.457Z,1340330686.457 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-06-22T02:05:45.682Z,1340330745.682 [NAL9601](INFO): SBD MO Status=2, MOMSN=10554, MT Status=2, MTMSN=0
2012-06-22T02:05:45.682Z,1340330745.682 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-06-22T02:09:34.662Z,1340330974.662 [NAL9601](INFO): SBD MO Status=2, MOMSN=10554, MT Status=2, MTMSN=0
2012-06-22T02:09:34.662Z,1340330974.662 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-06-22T02:09:58.976Z,1340330998.976 [NAL9601](INFO): SBD MO Status=2, MOMSN=10554, MT Status=2, MTMSN=0
2012-06-22T02:09:58.977Z,1340330998.977 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-06-22T02:10:24.898Z,1340331024.898 [NAL9601](INFO): SBD MO Status=2, MOMSN=10554, MT Status=2, MTMSN=0
2012-06-22T02:10:24.898Z,1340331024.898 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-06-22T02:11:19.409Z,1340331079.409 [NAL9601](INFO): SBD MO Status=1, MOMSN=10554, MT Status=0, MTMSN=0
2012-06-22T02:11:19.535Z,1340331079.535 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0003.lzma
2012-06-22T02:11:19.535Z,1340331079.535 [NAL9601](INFO): Packets left to send: 1
2012-06-22T02:11:19.536Z,1340331079.536 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0003.lzma.parts/0001.sbd
2012-06-22T02:11:36.780Z,1340331096.780 [NAL9601](INFO): SBD MO Status=2, MOMSN=10555, MT Status=2, MTMSN=0
2012-06-22T02:11:36.781Z,1340331096.781 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-06-22T02:11:47.911Z,1340331107.911 [NAL9601](INFO): SBD MO Status=1, MOMSN=10555, MT Status=0, MTMSN=0
2012-06-22T02:11:48.087Z,1340331108.087 [NAL9601](INFO): Sent 83 bytes from file Logs/20120622T014020/shore0003.lzma
2012-06-22T02:11:48.087Z,1340331108.087 [NAL9601](INFO): Packets left to send: 0
2012-06-22T02:11:48.088Z,1340331108.088 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0003.lzma.parts/0000.sbd
2012-06-22T02:12:09.167Z,1340331129.167 [NAL9601](INFO): SBD MO Status=0, MOMSN=10556, MT Status=0, MTMSN=0
2012-06-22T02:12:09.369Z,1340331129.369 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Stopped
2012-06-22T02:12:09.369Z,1340331129.369 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Running Loop=1
2012-06-22T02:12:11.512Z,1340331131.512 [NAL9601](IMPORTANT): GPS fix at: 1340331322.00
2012-06-22T02:12:11.532Z,1340331131.532 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Stopped
2012-06-22T02:12:11.533Z,1340331131.533 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS:B
2012-06-22T02:12:11.533Z,1340331131.533 [triangle_control_speed_onelap:SURFACECOMMS:B] Stopped
2012-06-22T02:12:11.533Z,1340331131.533 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS:B
2012-06-22T02:12:11.533Z,1340331131.533 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Stopped
2012-06-22T02:12:11.533Z,1340331131.533 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T02:12:11.534Z,1340331131.534 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS
2012-06-22T02:12:11.534Z,1340331131.534 [triangle_control_speed_onelap:SURFACECOMMS] Stopped
2012-06-22T02:12:11.534Z,1340331131.534 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS
2012-06-22T02:12:11.535Z,1340331131.534 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Stopped
2012-06-22T02:12:11.535Z,1340331131.534 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T02:12:11.963Z,1340331131.963 [triangle_control_speed_onelap:WaypointOne:PHONEHOMEWPT1](INFO): Completed triangle_control_speed_onelap:WaypointOne:PHONEHOMEWPT1
2012-06-22T02:12:11.963Z,1340331131.963 [triangle_control_speed_onelap:WaypointOne:PHONEHOMEWPT1] Stopped
2012-06-22T02:12:11.963Z,1340331131.963 [triangle_control_speed_onelap:WaypointOne:PHONEHOMEWPT1](INFO): Aggregate::uninitialize triangle_control_speed_onelap:WaypointOne:PHONEHOMEWPT1
2012-06-22T02:12:11.964Z,1340331131.964 [triangle_control_speed_onelap:WaypointOne](INFO): Completed triangle_control_speed_onelap:WaypointOne
2012-06-22T02:12:11.964Z,1340331131.964 [triangle_control_speed_onelap:WaypointOne] Stopped
2012-06-22T02:12:11.965Z,1340331131.965 [triangle_control_speed_onelap:WaypointOne](INFO): Aggregate::uninitialize triangle_control_speed_onelap:WaypointOne
2012-06-22T02:12:11.965Z,1340331131.965 [triangle_control_speed_onelap:WaypointOne:A.Pitch] Stopped
2012-06-22T02:12:11.965Z,1340331131.965 [triangle_control_speed_onelap:WaypointOne:B.SetSpeed] Stopped
2012-06-22T02:12:11.965Z,1340331131.965 [triangle_control_speed_onelap:WaypointOne:B.SetSpeed](DEBUG): Uninitialize.
2012-06-22T02:12:11.965Z,1340331131.965 [triangle_control_speed_onelap:ThreeLegs] Running Loop=1
2012-06-22T02:12:11.965Z,1340331131.965 [triangle_control_speed_onelap:ThreeLegs](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs
2012-06-22T02:12:11.965Z,1340331131.965 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold] Running Loop=1
2012-06-22T02:12:11.965Z,1340331131.965 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:BuoyancyHold
2012-06-22T02:12:11.965Z,1340331131.965 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold:A.Buoyancy] Running Loop=1
2012-06-22T02:12:11.965Z,1340331131.966 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold:A.Buoyancy](DEBUG): Initialize Buoyancy Component.
2012-06-22T02:12:11.966Z,1340331131.966 [triangle_control_speed_onelap:ThreeLegs:MassHold] Running Loop=1
2012-06-22T02:12:11.966Z,1340331131.966 [triangle_control_speed_onelap:ThreeLegs:MassHold](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:MassHold
2012-06-22T02:12:11.966Z,1340331131.966 [triangle_control_speed_onelap:ThreeLegs:MassHold:A.Pitch] Running Loop=1
2012-06-22T02:12:11.966Z,1340331131.966 [triangle_control_speed_onelap:ThreeLegs:MassHold:A.Pitch](DEBUG): Initialize.
2012-06-22T02:12:11.967Z,1340331131.967 [triangle_control_speed_onelap:ThreeLegs:ElevatorHold] Running Loop=1
2012-06-22T02:12:11.967Z,1340331131.967 [triangle_control_speed_onelap:ThreeLegs:ElevatorHold](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:ElevatorHold
2012-06-22T02:12:11.967Z,1340331131.967 [triangle_control_speed_onelap:ThreeLegs:ElevatorHold:A.Pitch] Running Loop=1
2012-06-22T02:12:11.967Z,1340331131.967 [triangle_control_speed_onelap:ThreeLegs:ElevatorHold:A.Pitch](DEBUG): Initialize.
2012-06-22T02:12:11.967Z,1340331131.967 [triangle_control_speed_onelap:ThreeLegs:D] Running Loop=1
2012-06-22T02:12:11.967Z,1340331131.967 [triangle_control_speed_onelap:ThreeLegs:E] Running Loop=1
2012-06-22T02:12:11.967Z,1340331131.967 [triangle_control_speed_onelap:ThreeLegs:F] Running Loop=1
2012-06-22T02:12:11.967Z,1340331131.967 [triangle_control_speed_onelap:ThreeLegs:G] Running Loop=1
2012-06-22T02:12:11.967Z,1340331131.967 [triangle_control_speed_onelap:ThreeLegs:H] Running Loop=1
2012-06-22T02:12:11.967Z,1340331131.967 [triangle_control_speed_onelap:ThreeLegs:I] Running Loop=1
2012-06-22T02:12:11.967Z,1340331131.967 [triangle_control_speed_onelap:ThreeLegs:J] Running Loop=1
2012-06-22T02:12:11.967Z,1340331131.967 [triangle_control_speed_onelap:ThreeLegs:K] Running Loop=1
2012-06-22T02:12:11.967Z,1340331131.967 [triangle_control_speed_onelap:ThreeLegs:L] Running Loop=1
2012-06-22T02:12:11.968Z,1340331131.968 [triangle_control_speed_onelap:ThreeLegs:TestDrive] Running Loop=1
2012-06-22T02:12:11.968Z,1340331131.968 [triangle_control_speed_onelap:ThreeLegs:TestDrive](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:TestDrive
2012-06-22T02:12:11.968Z,1340331131.968 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1] Running Loop=1
2012-06-22T02:12:11.968Z,1340331131.968 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1
2012-06-22T02:12:11.968Z,1340331131.968 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:A.SetSpeed] Running Loop=1
2012-06-22T02:12:11.968Z,1340331131.968 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:A.SetSpeed](DEBUG): Initialize.
2012-06-22T02:12:11.968Z,1340331131.968 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:B.Pitch] Running Loop=1
2012-06-22T02:12:11.969Z,1340331131.968 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:B.Pitch](DEBUG): Initialize.
2012-06-22T02:12:11.969Z,1340331131.969 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:Wpt2.Waypoint] Running Loop=1
2012-06-22T02:12:11.969Z,1340331131.969 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:Wpt2.Waypoint](DEBUG): Initialize WaypointComponent.
2012-06-22T02:12:12.349Z,1340331132.349 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:B.Pitch] Running Loop=1
2012-06-22T02:12:12.350Z,1340331132.350 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:A.SetSpeed] Running Loop=1
2012-06-22T02:12:12.355Z,1340331132.355 [triangle_control_speed_onelap:ThreeLegs:L] Running Loop=1
2012-06-22T02:12:12.356Z,1340331132.356 [triangle_control_speed_onelap:ThreeLegs:K] Running Loop=1
2012-06-22T02:12:12.357Z,1340331132.357 [triangle_control_speed_onelap:ThreeLegs:J] Running Loop=1
2012-06-22T02:12:12.358Z,1340331132.358 [triangle_control_speed_onelap:ThreeLegs:I] Running Loop=1
2012-06-22T02:12:12.363Z,1340331132.363 [triangle_control_speed_onelap:ThreeLegs:H] Running Loop=1
2012-06-22T02:12:12.364Z,1340331132.364 [triangle_control_speed_onelap:ThreeLegs:G] Running Loop=1
2012-06-22T02:12:12.365Z,1340331132.365 [triangle_control_speed_onelap:ThreeLegs:F] Running Loop=1
2012-06-22T02:12:12.366Z,1340331132.366 [triangle_control_speed_onelap:ThreeLegs:E] Running Loop=1
2012-06-22T02:12:12.371Z,1340331132.371 [triangle_control_speed_onelap:ThreeLegs:D] Running Loop=1
2012-06-22T02:12:12.372Z,1340331132.372 [triangle_control_speed_onelap:ThreeLegs:MassHold] Running Loop=1
2012-06-22T02:12:12.372Z,1340331132.372 [triangle_control_speed_onelap:ThreeLegs:MassHold:A.Pitch] Running Loop=1
2012-06-22T02:12:12.381Z,1340331132.381 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold] Running Loop=1
2012-06-22T02:12:12.381Z,1340331132.381 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold:A.Buoyancy] Running Loop=1
2012-06-22T02:12:17.875Z,1340331137.875 [NAL9601](INFO): Powering down
2012-06-22T02:12:44.301Z,1340331164.301 [Radio_Freewave](INFO): Powering down
2012-06-22T02:12:44.715Z,1340331164.715 [Radio_Freewave](INFO): Powering up
2012-06-22T02:12:46.317Z,1340331166.318 [Radio_Freewave](INFO): Powering down
2012-06-22T02:54:43.426Z,1340333683.426 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:Wpt2.Waypoint](INFO): Reached Waypoint: 36.829000,-121.886000
2012-06-22T02:54:43.426Z,1340333683.426 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:Wpt2.Waypoint] Stopped
2012-06-22T02:54:43.426Z,1340333683.426 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:Wpt2.Waypoint](DEBUG): Uninitialize WaypointComponent.
2012-06-22T02:54:43.426Z,1340333683.426 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:PHONEHOMEWPT2] Running Loop=1
2012-06-22T02:54:43.426Z,1340333683.426 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:PHONEHOMEWPT2](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:PHONEHOMEWPT2
2012-06-22T02:54:43.844Z,1340333683.844 [triangle_control_speed_onelap:SURFACECOMMS] Running Loop=1
2012-06-22T02:54:43.844Z,1340333683.844 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS
2012-06-22T02:54:43.844Z,1340333683.844 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-06-22T02:54:43.844Z,1340333683.844 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T02:55:48.209Z,1340333748.209 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T02:55:50.617Z,1340333750.617 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T02:55:52.729Z,1340333752.728 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T02:55:55.119Z,1340333755.119 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T02:55:58.028Z,1340333758.028 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T02:56:00.360Z,1340333760.360 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T02:56:02.740Z,1340333762.740 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T02:56:05.136Z,1340333765.136 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T02:56:07.536Z,1340333767.536 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T02:56:09.941Z,1340333769.941 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T02:56:12.340Z,1340333772.341 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T02:56:14.736Z,1340333774.736 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T02:56:17.149Z,1340333777.149 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T02:56:19.536Z,1340333779.536 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T02:56:21.936Z,1340333781.936 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T02:56:24.340Z,1340333784.341 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T02:56:26.705Z,1340333786.705 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T02:56:29.151Z,1340333789.151 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T02:56:31.537Z,1340333791.537 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T02:56:33.851Z,1340333793.851 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T02:56:36.536Z,1340333796.536 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T02:56:38.937Z,1340333798.937 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T02:56:41.349Z,1340333801.349 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T02:56:43.709Z,1340333803.709 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T02:56:46.109Z,1340333806.109 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T02:56:48.509Z,1340333808.509 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T02:56:51.369Z,1340333811.369 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T02:56:53.397Z,1340333813.397 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T02:56:55.752Z,1340333815.752 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T02:56:58.153Z,1340333818.152 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T02:57:00.552Z,1340333820.552 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T02:57:02.965Z,1340333822.965 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T02:57:05.456Z,1340333825.456 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T02:57:07.792Z,1340333827.792 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T02:57:10.189Z,1340333830.189 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#11 STATUS: 65535
2012-06-22T03:00:14.647Z,1340334014.647 [Radio_Freewave](INFO): Powering up
2012-06-22T03:00:14.661Z,1340334014.661 [triangle_control_speed_onelap:SURFACECOMMS:B] Running Loop=1
2012-06-22T03:00:14.661Z,1340334014.661 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS:B
2012-06-22T03:00:14.661Z,1340334014.661 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Running Loop=1
2012-06-22T03:00:14.661Z,1340334014.661 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize.
2012-06-22T03:00:14.662Z,1340334014.662 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Running Loop=1
2012-06-22T03:00:15.439Z,1340334015.439 [NAL9601](INFO): Powering up
2012-06-22T03:01:21.263Z,1340334081.263 [NAL9601](INFO): NAL9601 initialized
2012-06-22T03:01:22.439Z,1340334082.439 [NAL9601](IMPORTANT): GPS fix at: 1340334271.00
2012-06-22T03:01:22.453Z,1340334082.453 [Navigation](IMPORTANT): Error 7.7 %DT at bearing -155.3 deg., over the last 2375.0 m.
2012-06-22T03:01:22.454Z,1340334082.454 [Navigation](IMPORTANT): Distance made good 2147.8 m, at (36.8310, -121.8845).
2012-06-22T03:01:22.455Z,1340334082.455 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Stopped
2012-06-22T03:01:22.455Z,1340334082.455 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Running Loop=1
2012-06-22T03:01:38.325Z,1340334098.325 [NAL9601](INFO): SBD MO Status=1, MOMSN=10557, MT Status=0, MTMSN=0
2012-06-22T03:01:38.523Z,1340334098.523 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0004.lzma
2012-06-22T03:01:38.523Z,1340334098.523 [NAL9601](INFO): Packets left to send: 15
2012-06-22T03:01:38.525Z,1340334098.525 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0004.lzma.parts/0015.sbd
2012-06-22T03:01:49.317Z,1340334109.318 [NAL9601](INFO): SBD MO Status=1, MOMSN=10558, MT Status=0, MTMSN=0
2012-06-22T03:01:49.507Z,1340334109.507 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0004.lzma
2012-06-22T03:01:49.507Z,1340334109.507 [NAL9601](INFO): Packets left to send: 14
2012-06-22T03:01:49.675Z,1340334109.675 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0004.lzma.parts/0014.sbd
2012-06-22T03:01:59.889Z,1340334119.889 [NAL9601](INFO): SBD MO Status=1, MOMSN=10559, MT Status=0, MTMSN=0
2012-06-22T03:02:00.091Z,1340334120.091 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0004.lzma
2012-06-22T03:02:00.091Z,1340334120.091 [NAL9601](INFO): Packets left to send: 13
2012-06-22T03:02:00.405Z,1340334120.405 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0004.lzma.parts/0013.sbd
2012-06-22T03:02:08.697Z,1340334128.697 [NAL9601](INFO): SBD MO Status=1, MOMSN=10560, MT Status=0, MTMSN=0
2012-06-22T03:02:08.879Z,1340334128.879 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0004.lzma
2012-06-22T03:02:08.879Z,1340334128.879 [NAL9601](INFO): Packets left to send: 12
2012-06-22T03:02:08.880Z,1340334128.880 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0004.lzma.parts/0012.sbd
2012-06-22T03:02:17.618Z,1340334137.618 [NAL9601](INFO): SBD MO Status=1, MOMSN=10561, MT Status=0, MTMSN=0
2012-06-22T03:02:17.763Z,1340334137.763 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0004.lzma
2012-06-22T03:02:17.763Z,1340334137.763 [NAL9601](INFO): Packets left to send: 11
2012-06-22T03:02:17.764Z,1340334137.764 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0004.lzma.parts/0011.sbd
2012-06-22T03:02:23.367Z,1340334143.367 [NAL9601](INFO): SBD MO Status=2, MOMSN=10562, MT Status=2, MTMSN=0
2012-06-22T03:02:23.367Z,1340334143.367 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-06-22T03:02:35.745Z,1340334155.745 [NAL9601](INFO): SBD MO Status=1, MOMSN=10562, MT Status=0, MTMSN=0
2012-06-22T03:02:35.935Z,1340334155.935 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0004.lzma
2012-06-22T03:02:35.935Z,1340334155.935 [NAL9601](INFO): Packets left to send: 10
2012-06-22T03:02:35.936Z,1340334155.936 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0004.lzma.parts/0010.sbd
2012-06-22T03:02:47.878Z,1340334167.878 [NAL9601](INFO): SBD MO Status=1, MOMSN=10563, MT Status=0, MTMSN=0
2012-06-22T03:02:48.019Z,1340334168.019 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0004.lzma
2012-06-22T03:02:48.019Z,1340334168.019 [NAL9601](INFO): Packets left to send: 9
2012-06-22T03:02:48.020Z,1340334168.020 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0004.lzma.parts/0009.sbd
2012-06-22T03:03:01.173Z,1340334181.173 [NAL9601](INFO): SBD MO Status=1, MOMSN=10564, MT Status=0, MTMSN=0
2012-06-22T03:03:01.301Z,1340334181.301 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0004.lzma
2012-06-22T03:03:01.301Z,1340334181.301 [NAL9601](INFO): Packets left to send: 8
2012-06-22T03:03:01.303Z,1340334181.303 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0004.lzma.parts/0008.sbd
2012-06-22T03:03:11.453Z,1340334191.453 [Radio_Freewave](INFO): Powering down
2012-06-22T03:03:16.163Z,1340334196.163 [NAL9601](INFO): SBD MO Status=2, MOMSN=10565, MT Status=2, MTMSN=0
2012-06-22T03:03:16.163Z,1340334196.164 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-06-22T03:03:21.047Z,1340334201.047 [Radio_Freewave](INFO): Powering up
2012-06-22T03:03:35.191Z,1340334215.191 [NAL9601](INFO): SBD MO Status=1, MOMSN=10565, MT Status=0, MTMSN=0
2012-06-22T03:03:35.343Z,1340334215.343 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0004.lzma
2012-06-22T03:03:35.343Z,1340334215.343 [NAL9601](INFO): Packets left to send: 7
2012-06-22T03:03:35.344Z,1340334215.344 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0004.lzma.parts/0007.sbd
2012-06-22T03:03:43.728Z,1340334223.727 [NAL9601](INFO): SBD MO Status=1, MOMSN=10566, MT Status=0, MTMSN=0
2012-06-22T03:03:43.931Z,1340334223.931 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0004.lzma
2012-06-22T03:03:43.931Z,1340334223.931 [NAL9601](INFO): Packets left to send: 6
2012-06-22T03:03:45.050Z,1340334225.050 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0004.lzma.parts/0006.sbd
2012-06-22T03:03:53.308Z,1340334233.307 [NAL9601](INFO): SBD MO Status=1, MOMSN=10567, MT Status=0, MTMSN=0
2012-06-22T03:03:53.515Z,1340334233.515 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0004.lzma
2012-06-22T03:03:53.515Z,1340334233.515 [NAL9601](INFO): Packets left to send: 5
2012-06-22T03:03:53.517Z,1340334233.517 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0004.lzma.parts/0005.sbd
2012-06-22T03:04:01.402Z,1340334241.402 [NAL9601](INFO): SBD MO Status=1, MOMSN=10568, MT Status=0, MTMSN=0
2012-06-22T03:04:01.603Z,1340334241.603 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0004.lzma
2012-06-22T03:04:01.603Z,1340334241.603 [NAL9601](INFO): Packets left to send: 4
2012-06-22T03:04:01.604Z,1340334241.604 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0004.lzma.parts/0004.sbd
2012-06-22T03:04:09.898Z,1340334249.898 [NAL9601](INFO): SBD MO Status=1, MOMSN=10569, MT Status=0, MTMSN=0
2012-06-22T03:04:10.091Z,1340334250.091 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0004.lzma
2012-06-22T03:04:10.091Z,1340334250.091 [NAL9601](INFO): Packets left to send: 3
2012-06-22T03:04:10.092Z,1340334250.092 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0004.lzma.parts/0003.sbd
2012-06-22T03:04:21.630Z,1340334261.630 [NAL9601](INFO): SBD MO Status=1, MOMSN=10570, MT Status=0, MTMSN=0
2012-06-22T03:04:21.771Z,1340334261.771 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0004.lzma
2012-06-22T03:04:21.771Z,1340334261.771 [NAL9601](INFO): Packets left to send: 2
2012-06-22T03:04:21.772Z,1340334261.772 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0004.lzma.parts/0002.sbd
2012-06-22T03:04:32.553Z,1340334272.553 [NAL9601](INFO): SBD MO Status=1, MOMSN=10571, MT Status=0, MTMSN=0
2012-06-22T03:04:32.755Z,1340334272.755 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0004.lzma
2012-06-22T03:04:32.755Z,1340334272.755 [NAL9601](INFO): Packets left to send: 1
2012-06-22T03:04:32.756Z,1340334272.756 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0004.lzma.parts/0001.sbd
2012-06-22T03:04:41.844Z,1340334281.843 [NAL9601](INFO): SBD MO Status=1, MOMSN=10572, MT Status=0, MTMSN=0
2012-06-22T03:04:42.039Z,1340334282.039 [NAL9601](INFO): Sent 100 bytes from file Logs/20120622T014020/shore0004.lzma
2012-06-22T03:04:42.039Z,1340334282.039 [NAL9601](INFO): Packets left to send: 0
2012-06-22T03:04:42.040Z,1340334282.040 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0004.lzma.parts/0000.sbd
2012-06-22T03:04:52.630Z,1340334292.630 [NAL9601](INFO): SBD MO Status=0, MOMSN=10573, MT Status=0, MTMSN=0
2012-06-22T03:04:52.837Z,1340334292.837 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Stopped
2012-06-22T03:04:52.837Z,1340334292.837 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Running Loop=1
2012-06-22T03:04:55.026Z,1340334295.026 [NAL9601](IMPORTANT): GPS fix at: 1340334484.00
2012-06-22T03:04:55.041Z,1340334295.041 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Stopped
2012-06-22T03:04:55.042Z,1340334295.042 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS:B
2012-06-22T03:04:55.042Z,1340334295.042 [triangle_control_speed_onelap:SURFACECOMMS:B] Stopped
2012-06-22T03:04:55.042Z,1340334295.042 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS:B
2012-06-22T03:04:55.042Z,1340334295.042 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Stopped
2012-06-22T03:04:55.043Z,1340334295.043 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T03:04:55.043Z,1340334295.043 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS
2012-06-22T03:04:55.043Z,1340334295.043 [triangle_control_speed_onelap:SURFACECOMMS] Stopped
2012-06-22T03:04:55.044Z,1340334295.044 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS
2012-06-22T03:04:55.044Z,1340334295.044 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Stopped
2012-06-22T03:04:55.044Z,1340334295.044 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T03:04:55.443Z,1340334295.443 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:PHONEHOMEWPT2](INFO): Completed triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:PHONEHOMEWPT2
2012-06-22T03:04:55.443Z,1340334295.443 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:PHONEHOMEWPT2] Stopped
2012-06-22T03:04:55.444Z,1340334295.444 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:PHONEHOMEWPT2](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:PHONEHOMEWPT2
2012-06-22T03:04:55.444Z,1340334295.444 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1](INFO): Completed triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1
2012-06-22T03:04:55.444Z,1340334295.444 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1] Stopped
2012-06-22T03:04:55.445Z,1340334295.445 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1
2012-06-22T03:04:55.445Z,1340334295.445 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:A.SetSpeed] Stopped
2012-06-22T03:04:55.445Z,1340334295.445 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T03:04:55.445Z,1340334295.445 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed1:B.Pitch] Stopped
2012-06-22T03:04:55.445Z,1340334295.445 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2] Running Loop=1
2012-06-22T03:04:55.445Z,1340334295.445 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2
2012-06-22T03:04:55.445Z,1340334295.445 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:A.SetSpeed] Running Loop=1
2012-06-22T03:04:55.445Z,1340334295.445 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:A.SetSpeed](DEBUG): Initialize.
2012-06-22T03:04:55.445Z,1340334295.445 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:B.Pitch] Running Loop=1
2012-06-22T03:04:55.445Z,1340334295.445 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:B.Pitch](DEBUG): Initialize.
2012-06-22T03:04:55.446Z,1340334295.446 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:Wpt3.Waypoint] Running Loop=1
2012-06-22T03:04:55.446Z,1340334295.446 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:Wpt3.Waypoint](DEBUG): Initialize WaypointComponent.
2012-06-22T03:04:55.888Z,1340334295.888 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:B.Pitch] Running Loop=1
2012-06-22T03:04:55.889Z,1340334295.889 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:A.SetSpeed] Running Loop=1
2012-06-22T03:05:01.436Z,1340334301.436 [NAL9601](INFO): Powering down
2012-06-22T03:05:32.629Z,1340334332.629 [Radio_Freewave](INFO): Powering down
2012-06-22T03:40:57.936Z,1340336457.936 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:Wpt3.Waypoint](INFO): Reached Waypoint: 36.816000,-121.883000
2012-06-22T03:40:57.936Z,1340336457.936 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:Wpt3.Waypoint] Stopped
2012-06-22T03:40:57.936Z,1340336457.936 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:Wpt3.Waypoint](DEBUG): Uninitialize WaypointComponent.
2012-06-22T03:40:57.936Z,1340336457.936 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:PHONEHOMEWPT3] Running Loop=1
2012-06-22T03:40:57.937Z,1340336457.937 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:PHONEHOMEWPT3](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:PHONEHOMEWPT3
2012-06-22T03:40:58.306Z,1340336458.306 [triangle_control_speed_onelap:SURFACECOMMS] Running Loop=1
2012-06-22T03:40:58.306Z,1340336458.306 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS
2012-06-22T03:40:58.306Z,1340336458.306 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-06-22T03:40:58.306Z,1340336458.306 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T03:46:15.433Z,1340336775.433 [Radio_Freewave](INFO): Powering up
2012-06-22T03:46:15.447Z,1340336775.447 [triangle_control_speed_onelap:SURFACECOMMS:B] Running Loop=1
2012-06-22T03:46:15.447Z,1340336775.447 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS:B
2012-06-22T03:46:15.447Z,1340336775.447 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Running Loop=1
2012-06-22T03:46:15.447Z,1340336775.447 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize.
2012-06-22T03:46:15.447Z,1340336775.447 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Running Loop=1
2012-06-22T03:46:16.232Z,1340336776.232 [NAL9601](INFO): Powering up
2012-06-22T03:47:21.955Z,1340336841.955 [NAL9601](INFO): NAL9601 initialized
2012-06-22T03:47:23.079Z,1340336843.079 [NAL9601](IMPORTANT): GPS fix at: 1340337036.00
2012-06-22T03:47:23.094Z,1340336843.094 [Navigation](IMPORTANT): Error 6.5 %DT at bearing -56.3 deg., over the last 1827.7 m.
2012-06-22T03:47:23.095Z,1340336843.095 [Navigation](IMPORTANT): Distance made good 1761.3 m, at (36.8152, -121.8829).
2012-06-22T03:47:23.096Z,1340336843.096 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Stopped
2012-06-22T03:47:23.096Z,1340336843.096 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Running Loop=1
2012-06-22T03:47:37.466Z,1340336857.466 [NAL9601](INFO): SBD MO Status=1, MOMSN=10574, MT Status=0, MTMSN=0
2012-06-22T03:47:37.623Z,1340336857.623 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0005.lzma
2012-06-22T03:47:37.623Z,1340336857.623 [NAL9601](INFO): Packets left to send: 11
2012-06-22T03:47:37.624Z,1340336857.625 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0005.lzma.parts/0011.sbd
2012-06-22T03:47:49.561Z,1340336869.561 [NAL9601](INFO): SBD MO Status=1, MOMSN=10575, MT Status=0, MTMSN=0
2012-06-22T03:47:49.703Z,1340336869.703 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0005.lzma
2012-06-22T03:47:49.703Z,1340336869.703 [NAL9601](INFO): Packets left to send: 10
2012-06-22T03:47:49.704Z,1340336869.704 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0005.lzma.parts/0010.sbd
2012-06-22T03:47:59.395Z,1340336879.395 [NAL9601](INFO): SBD MO Status=1, MOMSN=10576, MT Status=0, MTMSN=0
2012-06-22T03:47:59.587Z,1340336879.587 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0005.lzma
2012-06-22T03:47:59.587Z,1340336879.587 [NAL9601](INFO): Packets left to send: 9
2012-06-22T03:47:59.588Z,1340336879.588 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0005.lzma.parts/0009.sbd
2012-06-22T03:48:09.501Z,1340336889.501 [NAL9601](INFO): SBD MO Status=1, MOMSN=10577, MT Status=0, MTMSN=0
2012-06-22T03:48:09.671Z,1340336889.671 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0005.lzma
2012-06-22T03:48:09.671Z,1340336889.671 [NAL9601](INFO): Packets left to send: 8
2012-06-22T03:48:10.171Z,1340336890.171 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0005.lzma.parts/0008.sbd
2012-06-22T03:48:15.595Z,1340336895.595 [Radio_Freewave](INFO): Powering down
2012-06-22T03:48:16.906Z,1340336896.906 [ThrusterServo](ERROR): Thruster uart error: serial timeout
2012-06-22T03:48:16.906Z,1340336896.907 [ThrusterServo] Communications Fault, FailCount= 1
2012-06-22T03:48:16.907Z,1340336896.907 [ThrusterServo](ERROR): Communications Fault
2012-06-22T03:48:16.909Z,1340336896.909 [CBIT](ERROR): Communications Fault in component: ThrusterServo
2012-06-22T03:48:17.112Z,1340336897.112 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2012-06-22T03:48:17.113Z,1340336897.113 [ThrusterServo](INFO): Powering down
2012-06-22T03:48:17.116Z,1340336897.116 [CBIT](INFO): Clearing failed state for component ThrusterServo
2012-06-22T03:48:17.116Z,1340336897.116 [ThrusterServo] No Fault, FailCount= 1
2012-06-22T03:48:17.832Z,1340336897.832 [ThrusterServo](DEBUG): Initializing EZServoServo.
2012-06-22T03:48:17.951Z,1340336897.951 [ThrusterServo](DEBUG): Initializing ThrusterServo.
2012-06-22T03:48:18.203Z,1340336898.203 [Radio_Freewave](INFO): Powering up
2012-06-22T03:48:33.000Z,1340336913.000 [NAL9601](INFO): SBD MO Status=2, MOMSN=10578, MT Status=2, MTMSN=0
2012-06-22T03:48:33.001Z,1340336913.000 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-06-22T03:48:54.194Z,1340336934.194 [NAL9601](INFO): SBD MO Status=1, MOMSN=10578, MT Status=0, MTMSN=0
2012-06-22T03:48:54.407Z,1340336934.407 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0005.lzma
2012-06-22T03:48:54.407Z,1340336934.407 [NAL9601](INFO): Packets left to send: 7
2012-06-22T03:48:54.408Z,1340336934.408 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0005.lzma.parts/0007.sbd
2012-06-22T03:49:04.714Z,1340336944.714 [NAL9601](INFO): SBD MO Status=1, MOMSN=10579, MT Status=0, MTMSN=0
2012-06-22T03:49:04.887Z,1340336944.887 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0005.lzma
2012-06-22T03:49:04.887Z,1340336944.887 [NAL9601](INFO): Packets left to send: 6
2012-06-22T03:49:04.888Z,1340336944.888 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0005.lzma.parts/0006.sbd
2012-06-22T03:49:21.201Z,1340336961.201 [NAL9601](INFO): SBD MO Status=1, MOMSN=10580, MT Status=0, MTMSN=0
2012-06-22T03:49:21.363Z,1340336961.363 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0005.lzma
2012-06-22T03:49:21.363Z,1340336961.363 [NAL9601](INFO): Packets left to send: 5
2012-06-22T03:49:21.364Z,1340336961.364 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0005.lzma.parts/0005.sbd
2012-06-22T03:49:36.578Z,1340336976.578 [NAL9601](INFO): SBD MO Status=1, MOMSN=10581, MT Status=0, MTMSN=0
2012-06-22T03:49:36.735Z,1340336976.735 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0005.lzma
2012-06-22T03:49:36.735Z,1340336976.735 [NAL9601](INFO): Packets left to send: 4
2012-06-22T03:49:36.736Z,1340336976.736 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0005.lzma.parts/0004.sbd
2012-06-22T03:49:53.472Z,1340336993.473 [NAL9601](INFO): SBD MO Status=1, MOMSN=10582, MT Status=0, MTMSN=0
2012-06-22T03:49:53.615Z,1340336993.615 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0005.lzma
2012-06-22T03:49:53.615Z,1340336993.615 [NAL9601](INFO): Packets left to send: 3
2012-06-22T03:49:53.616Z,1340336993.616 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0005.lzma.parts/0003.sbd
2012-06-22T03:50:22.845Z,1340337022.845 [NAL9601](INFO): SBD MO Status=2, MOMSN=10583, MT Status=2, MTMSN=0
2012-06-22T03:50:22.846Z,1340337022.846 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-06-22T03:50:47.987Z,1340337047.987 [NAL9601](INFO): SBD MO Status=1, MOMSN=10583, MT Status=0, MTMSN=0
2012-06-22T03:50:48.127Z,1340337048.127 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0005.lzma
2012-06-22T03:50:48.127Z,1340337048.127 [NAL9601](INFO): Packets left to send: 2
2012-06-22T03:50:48.128Z,1340337048.128 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0005.lzma.parts/0002.sbd
2012-06-22T03:51:07.277Z,1340337067.277 [NAL9601](INFO): SBD MO Status=1, MOMSN=10584, MT Status=0, MTMSN=0
2012-06-22T03:51:07.399Z,1340337067.399 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0005.lzma
2012-06-22T03:51:07.399Z,1340337067.399 [NAL9601](INFO): Packets left to send: 1
2012-06-22T03:51:07.400Z,1340337067.400 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0005.lzma.parts/0001.sbd
2012-06-22T03:51:17.419Z,1340337077.419 [NAL9601](INFO): SBD MO Status=2, MOMSN=10585, MT Status=2, MTMSN=0
2012-06-22T03:51:17.420Z,1340337077.420 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-06-22T03:51:57.225Z,1340337117.225 [NAL9601](INFO): SBD MO Status=1, MOMSN=10585, MT Status=0, MTMSN=0
2012-06-22T03:51:57.423Z,1340337117.423 [NAL9601](INFO): Sent 65 bytes from file Logs/20120622T014020/shore0005.lzma
2012-06-22T03:51:57.423Z,1340337117.423 [NAL9601](INFO): Packets left to send: 0
2012-06-22T03:51:57.425Z,1340337117.425 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0005.lzma.parts/0000.sbd
2012-06-22T03:52:13.262Z,1340337133.263 [NAL9601](INFO): SBD MO Status=2, MOMSN=10586, MT Status=2, MTMSN=0
2012-06-22T03:52:13.263Z,1340337133.263 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-06-22T03:52:18.259Z,1340337138.259 [NAL9601](ERROR): Inadequate Iridium signal strength reported: 0
2012-06-22T03:52:37.313Z,1340337157.313 [NAL9601](INFO): SBD MO Status=2, MOMSN=10586, MT Status=2, MTMSN=0
2012-06-22T03:52:37.313Z,1340337157.313 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-06-22T03:52:42.313Z,1340337162.313 [NAL9601](ERROR): Inadequate Iridium signal strength reported: 0
2012-06-22T03:52:44.584Z,1340337164.584 [NAL9601](ERROR): Inadequate Iridium signal strength reported: 0
2012-06-22T03:52:53.947Z,1340337173.947 [NAL9601](INFO): SBD MO Status=0, MOMSN=10586, MT Status=0, MTMSN=0
2012-06-22T03:52:54.159Z,1340337174.159 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Stopped
2012-06-22T03:52:54.159Z,1340337174.159 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Running Loop=1
2012-06-22T03:52:56.326Z,1340337176.326 [NAL9601](IMPORTANT): GPS fix at: 1340337377.00
2012-06-22T03:52:56.341Z,1340337176.341 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Stopped
2012-06-22T03:52:56.341Z,1340337176.341 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS:B
2012-06-22T03:52:56.342Z,1340337176.342 [triangle_control_speed_onelap:SURFACECOMMS:B] Stopped
2012-06-22T03:52:56.342Z,1340337176.342 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS:B
2012-06-22T03:52:56.342Z,1340337176.342 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Stopped
2012-06-22T03:52:56.342Z,1340337176.342 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T03:52:56.343Z,1340337176.343 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS
2012-06-22T03:52:56.343Z,1340337176.343 [triangle_control_speed_onelap:SURFACECOMMS] Stopped
2012-06-22T03:52:56.343Z,1340337176.343 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS
2012-06-22T03:52:56.343Z,1340337176.343 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Stopped
2012-06-22T03:52:56.343Z,1340337176.343 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T03:52:56.747Z,1340337176.747 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:PHONEHOMEWPT3](INFO): Completed triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:PHONEHOMEWPT3
2012-06-22T03:52:56.747Z,1340337176.747 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:PHONEHOMEWPT3] Stopped
2012-06-22T03:52:56.747Z,1340337176.747 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:PHONEHOMEWPT3](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:PHONEHOMEWPT3
2012-06-22T03:52:56.748Z,1340337176.748 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2](INFO): Completed triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2
2012-06-22T03:52:56.748Z,1340337176.748 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2] Stopped
2012-06-22T03:52:56.748Z,1340337176.748 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2
2012-06-22T03:52:56.748Z,1340337176.748 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:A.SetSpeed] Stopped
2012-06-22T03:52:56.748Z,1340337176.748 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T03:52:56.748Z,1340337176.748 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed2:B.Pitch] Stopped
2012-06-22T03:52:56.748Z,1340337176.749 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3] Running Loop=1
2012-06-22T03:52:56.749Z,1340337176.749 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3
2012-06-22T03:52:56.749Z,1340337176.749 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:A.SetSpeed] Running Loop=1
2012-06-22T03:52:56.749Z,1340337176.749 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:A.SetSpeed](DEBUG): Initialize.
2012-06-22T03:52:56.749Z,1340337176.749 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:B.Pitch] Running Loop=1
2012-06-22T03:52:56.749Z,1340337176.749 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:B.Pitch](DEBUG): Initialize.
2012-06-22T03:52:56.749Z,1340337176.749 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:Wpt1.Waypoint] Running Loop=1
2012-06-22T03:52:56.749Z,1340337176.749 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:Wpt1.Waypoint](DEBUG): Initialize WaypointComponent.
2012-06-22T03:52:57.139Z,1340337177.139 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:B.Pitch] Running Loop=1
2012-06-22T03:52:57.140Z,1340337177.140 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:A.SetSpeed] Running Loop=1
2012-06-22T03:53:02.719Z,1340337182.719 [NAL9601](INFO): Powering down
2012-06-22T03:53:51.393Z,1340337231.393 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T03:53:53.780Z,1340337233.780 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T03:53:56.180Z,1340337236.180 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T03:53:58.580Z,1340337238.580 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T03:54:00.999Z,1340337240.999 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T03:54:03.380Z,1340337243.380 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T03:54:05.813Z,1340337245.813 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T03:54:08.180Z,1340337248.180 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T03:54:10.593Z,1340337250.593 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T03:54:12.954Z,1340337252.954 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T03:54:15.709Z,1340337255.708 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T03:54:17.797Z,1340337257.797 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T03:54:20.532Z,1340337260.532 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T03:54:23.030Z,1340337263.030 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T03:54:25.344Z,1340337265.344 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T03:54:27.812Z,1340337267.812 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T03:54:30.172Z,1340337270.172 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T03:54:32.540Z,1340337272.540 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T03:54:34.952Z,1340337274.952 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T03:54:37.344Z,1340337277.344 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T03:54:39.752Z,1340337279.752 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T03:54:42.140Z,1340337282.140 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T03:54:44.540Z,1340337284.540 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T03:54:46.952Z,1340337286.952 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T03:54:49.344Z,1340337289.344 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T03:54:51.780Z,1340337291.780 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T03:54:54.140Z,1340337294.140 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T03:54:56.540Z,1340337296.540 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T03:54:58.989Z,1340337298.989 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T03:55:01.344Z,1340337301.344 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T03:55:03.740Z,1340337303.740 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T03:55:06.540Z,1340337306.540 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T03:55:08.940Z,1340337308.940 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T03:55:11.360Z,1340337311.360 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T03:55:13.740Z,1340337313.740 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T03:55:16.164Z,1340337316.164 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T03:55:17.386Z,1340337317.386 [Radio_Freewave](INFO): Powering down
2012-06-22T03:55:18.539Z,1340337318.539 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T03:55:20.940Z,1340337320.940 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#10 STATUS: 65535
2012-06-22T04:33:12.211Z,1340339592.211 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:Wpt1.Waypoint](INFO): Reached Waypoint: 36.815000,-121.869000
2012-06-22T04:33:12.211Z,1340339592.211 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:Wpt1.Waypoint] Stopped
2012-06-22T04:33:12.212Z,1340339592.211 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:Wpt1.Waypoint](DEBUG): Uninitialize WaypointComponent.
2012-06-22T04:33:12.212Z,1340339592.212 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:PHONEHOMEWPT1] Running Loop=1
2012-06-22T04:33:12.212Z,1340339592.212 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:PHONEHOMEWPT1](INFO): Aggregate::initialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:PHONEHOMEWPT1
2012-06-22T04:33:12.630Z,1340339592.630 [triangle_control_speed_onelap:SURFACECOMMS] Running Loop=1
2012-06-22T04:33:12.630Z,1340339592.630 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS
2012-06-22T04:33:12.642Z,1340339592.642 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Running Loop=1
2012-06-22T04:33:12.642Z,1340339592.642 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T04:38:37.049Z,1340339917.049 [Radio_Freewave](INFO): Powering up
2012-06-22T04:38:37.064Z,1340339917.063 [triangle_control_speed_onelap:SURFACECOMMS:B] Running Loop=1
2012-06-22T04:38:37.064Z,1340339917.064 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::initialize triangle_control_speed_onelap:SURFACECOMMS:B
2012-06-22T04:38:37.064Z,1340339917.064 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Running Loop=1
2012-06-22T04:38:37.064Z,1340339917.064 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Initialize.
2012-06-22T04:38:37.064Z,1340339917.064 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Running Loop=1
2012-06-22T04:38:37.836Z,1340339917.836 [NAL9601](INFO): Powering up
2012-06-22T04:38:42.273Z,1340339922.273 [Radio_Freewave](INFO): Powering down
2012-06-22T04:38:46.625Z,1340339926.625 [Radio_Freewave](INFO): Powering up
2012-06-22T04:39:32.497Z,1340339972.497 [Radio_Freewave](INFO): Powering down
2012-06-22T04:39:41.213Z,1340339981.213 [Radio_Freewave](INFO): Powering up
2012-06-22T04:39:43.579Z,1340339983.579 [NAL9601](INFO): NAL9601 initialized
2012-06-22T04:39:48.719Z,1340339988.719 [NAL9601](IMPORTANT): GPS fix at: 1340340186.00
2012-06-22T04:39:48.750Z,1340339988.750 [Navigation](IMPORTANT): Error 6.7 %DT at bearing -45.0 deg., over the last 1444.3 m.
2012-06-22T04:39:48.750Z,1340339988.750 [Navigation](IMPORTANT): Distance made good 1316.2 m, at (36.8137, -121.8683).
2012-06-22T04:39:48.755Z,1340339988.755 [triangle_control_speed_onelap:SURFACECOMMS:B:B] Stopped
2012-06-22T04:39:48.755Z,1340339988.755 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Running Loop=1
2012-06-22T04:40:17.848Z,1340340017.848 [NAL9601](INFO): SBD MO Status=1, MOMSN=10587, MT Status=0, MTMSN=0
2012-06-22T04:40:17.995Z,1340340017.995 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0006.lzma
2012-06-22T04:40:17.995Z,1340340017.995 [NAL9601](INFO): Packets left to send: 11
2012-06-22T04:40:17.997Z,1340340017.997 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0006.lzma.parts/0011.sbd
2012-06-22T04:40:25.968Z,1340340025.968 [NAL9601](INFO): SBD MO Status=1, MOMSN=10588, MT Status=0, MTMSN=0
2012-06-22T04:40:26.183Z,1340340026.183 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0006.lzma
2012-06-22T04:40:26.183Z,1340340026.183 [NAL9601](INFO): Packets left to send: 10
2012-06-22T04:40:26.184Z,1340340026.184 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0006.lzma.parts/0010.sbd
2012-06-22T04:40:34.516Z,1340340034.516 [NAL9601](INFO): SBD MO Status=1, MOMSN=10589, MT Status=0, MTMSN=0
2012-06-22T04:40:34.671Z,1340340034.671 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0006.lzma
2012-06-22T04:40:34.671Z,1340340034.671 [NAL9601](INFO): Packets left to send: 9
2012-06-22T04:40:34.673Z,1340340034.673 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0006.lzma.parts/0009.sbd
2012-06-22T04:41:13.813Z,1340340073.813 [Radio_Freewave](INFO): Powering down
2012-06-22T04:41:14.626Z,1340340074.626 [NAL9601](INFO): SBD MO Status=2, MOMSN=10590, MT Status=2, MTMSN=0
2012-06-22T04:41:14.626Z,1340340074.626 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-06-22T04:41:20.879Z,1340340080.879 [Radio_Freewave](INFO): Powering up
2012-06-22T04:41:42.104Z,1340340102.104 [NAL9601](INFO): SBD MO Status=1, MOMSN=10590, MT Status=0, MTMSN=0
2012-06-22T04:41:42.267Z,1340340102.267 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0006.lzma
2012-06-22T04:41:42.267Z,1340340102.267 [NAL9601](INFO): Packets left to send: 8
2012-06-22T04:41:42.268Z,1340340102.268 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0006.lzma.parts/0008.sbd
2012-06-22T04:41:52.615Z,1340340112.615 [NAL9601](INFO): SBD MO Status=1, MOMSN=10591, MT Status=0, MTMSN=0
2012-06-22T04:41:52.747Z,1340340112.747 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0006.lzma
2012-06-22T04:41:52.747Z,1340340112.747 [NAL9601](INFO): Packets left to send: 7
2012-06-22T04:41:52.748Z,1340340112.748 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0006.lzma.parts/0007.sbd
2012-06-22T04:42:07.512Z,1340340127.513 [NAL9601](INFO): SBD MO Status=1, MOMSN=10592, MT Status=0, MTMSN=0
2012-06-22T04:42:07.727Z,1340340127.727 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0006.lzma
2012-06-22T04:42:07.727Z,1340340127.727 [NAL9601](INFO): Packets left to send: 6
2012-06-22T04:42:07.728Z,1340340127.728 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0006.lzma.parts/0006.sbd
2012-06-22T04:42:18.024Z,1340340138.024 [NAL9601](INFO): SBD MO Status=1, MOMSN=10593, MT Status=0, MTMSN=0
2012-06-22T04:42:18.211Z,1340340138.211 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0006.lzma
2012-06-22T04:42:18.211Z,1340340138.211 [NAL9601](INFO): Packets left to send: 5
2012-06-22T04:42:18.212Z,1340340138.212 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0006.lzma.parts/0005.sbd
2012-06-22T04:42:30.501Z,1340340150.501 [NAL9601](INFO): SBD MO Status=1, MOMSN=10594, MT Status=0, MTMSN=0
2012-06-22T04:42:30.691Z,1340340150.691 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0006.lzma
2012-06-22T04:42:30.691Z,1340340150.691 [NAL9601](INFO): Packets left to send: 4
2012-06-22T04:42:30.692Z,1340340150.692 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0006.lzma.parts/0004.sbd
2012-06-22T04:42:53.377Z,1340340173.378 [NAL9601](INFO): SBD MO Status=2, MOMSN=10595, MT Status=2, MTMSN=0
2012-06-22T04:42:53.378Z,1340340173.378 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-06-22T04:43:11.739Z,1340340191.739 [NAL9601](INFO): SBD MO Status=1, MOMSN=10595, MT Status=0, MTMSN=0
2012-06-22T04:43:11.927Z,1340340191.927 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0006.lzma
2012-06-22T04:43:11.927Z,1340340191.927 [NAL9601](INFO): Packets left to send: 3
2012-06-22T04:43:11.928Z,1340340191.928 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0006.lzma.parts/0003.sbd
2012-06-22T04:43:29.877Z,1340340209.877 [NAL9601](INFO): SBD MO Status=1, MOMSN=10596, MT Status=0, MTMSN=0
2012-06-22T04:43:30.003Z,1340340210.003 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0006.lzma
2012-06-22T04:43:30.003Z,1340340210.003 [NAL9601](INFO): Packets left to send: 2
2012-06-22T04:43:30.004Z,1340340210.004 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0006.lzma.parts/0002.sbd
2012-06-22T04:43:41.167Z,1340340221.167 [NAL9601](INFO): SBD MO Status=1, MOMSN=10597, MT Status=0, MTMSN=0
2012-06-22T04:43:41.379Z,1340340221.379 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0006.lzma
2012-06-22T04:43:41.379Z,1340340221.379 [NAL9601](INFO): Packets left to send: 1
2012-06-22T04:43:41.380Z,1340340221.380 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0006.lzma.parts/0001.sbd
2012-06-22T04:43:56.169Z,1340340236.169 [NAL9601](INFO): SBD MO Status=1, MOMSN=10598, MT Status=0, MTMSN=0
2012-06-22T04:43:56.355Z,1340340236.355 [NAL9601](INFO): Sent 234 bytes from file Logs/20120622T014020/shore0006.lzma
2012-06-22T04:43:56.355Z,1340340236.355 [NAL9601](INFO): Packets left to send: 0
2012-06-22T04:43:56.356Z,1340340236.356 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0006.lzma.parts/0000.sbd
2012-06-22T04:44:05.758Z,1340340245.758 [NAL9601](INFO): SBD MO Status=0, MOMSN=10599, MT Status=0, MTMSN=0
2012-06-22T04:44:05.957Z,1340340245.957 [triangle_control_speed_onelap:SURFACECOMMS:B:C] Stopped
2012-06-22T04:44:05.957Z,1340340245.957 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Running Loop=1
2012-06-22T04:44:08.155Z,1340340248.155 [NAL9601](IMPORTANT): GPS fix at: 1340340453.00
2012-06-22T04:44:08.183Z,1340340248.183 [triangle_control_speed_onelap:SURFACECOMMS:B:D] Stopped
2012-06-22T04:44:08.183Z,1340340248.183 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS:B
2012-06-22T04:44:08.184Z,1340340248.184 [triangle_control_speed_onelap:SURFACECOMMS:B] Stopped
2012-06-22T04:44:08.184Z,1340340248.184 [triangle_control_speed_onelap:SURFACECOMMS:B](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS:B
2012-06-22T04:44:08.184Z,1340340248.184 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed] Stopped
2012-06-22T04:44:08.184Z,1340340248.184 [triangle_control_speed_onelap:SURFACECOMMS:B:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T04:44:08.185Z,1340340248.185 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Completed triangle_control_speed_onelap:SURFACECOMMS
2012-06-22T04:44:08.185Z,1340340248.185 [triangle_control_speed_onelap:SURFACECOMMS] Stopped
2012-06-22T04:44:08.185Z,1340340248.185 [triangle_control_speed_onelap:SURFACECOMMS](INFO): Aggregate::uninitialize triangle_control_speed_onelap:SURFACECOMMS
2012-06-22T04:44:08.185Z,1340340248.185 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface] Stopped
2012-06-22T04:44:08.185Z,1340340248.185 [triangle_control_speed_onelap:SURFACECOMMS:A.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T04:44:08.619Z,1340340248.619 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:PHONEHOMEWPT1](INFO): Completed triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:PHONEHOMEWPT1
2012-06-22T04:44:08.619Z,1340340248.619 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:PHONEHOMEWPT1] Stopped
2012-06-22T04:44:08.619Z,1340340248.619 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:PHONEHOMEWPT1](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:PHONEHOMEWPT1
2012-06-22T04:44:08.620Z,1340340248.620 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3](INFO): Completed triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3
2012-06-22T04:44:08.620Z,1340340248.620 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3] Stopped
2012-06-22T04:44:08.620Z,1340340248.620 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3
2012-06-22T04:44:08.620Z,1340340248.620 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:A.SetSpeed] Stopped
2012-06-22T04:44:08.620Z,1340340248.620 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T04:44:08.620Z,1340340248.620 [triangle_control_speed_onelap:ThreeLegs:TestDrive:Speed3:B.Pitch] Stopped
2012-06-22T04:44:08.620Z,1340340248.620 [triangle_control_speed_onelap:ThreeLegs:TestDrive](INFO): Completed triangle_control_speed_onelap:ThreeLegs:TestDrive
2012-06-22T04:44:08.621Z,1340340248.621 [triangle_control_speed_onelap:ThreeLegs:TestDrive] Stopped
2012-06-22T04:44:08.621Z,1340340248.621 [triangle_control_speed_onelap:ThreeLegs:TestDrive](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:TestDrive
2012-06-22T04:44:08.635Z,1340340248.635 [triangle_control_speed_onelap:ThreeLegs](INFO): Completed triangle_control_speed_onelap:ThreeLegs
2012-06-22T04:44:08.635Z,1340340248.635 [triangle_control_speed_onelap:ThreeLegs] Stopped
2012-06-22T04:44:08.635Z,1340340248.635 [triangle_control_speed_onelap:ThreeLegs](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs
2012-06-22T04:44:08.635Z,1340340248.635 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold] Stopped
2012-06-22T04:44:08.636Z,1340340248.635 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:BuoyancyHold
2012-06-22T04:44:08.636Z,1340340248.636 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold:A.Buoyancy] Stopped
2012-06-22T04:44:08.636Z,1340340248.636 [triangle_control_speed_onelap:ThreeLegs:BuoyancyHold:A.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2012-06-22T04:44:08.636Z,1340340248.636 [triangle_control_speed_onelap:ThreeLegs:MassHold] Stopped
2012-06-22T04:44:08.636Z,1340340248.636 [triangle_control_speed_onelap:ThreeLegs:MassHold](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:MassHold
2012-06-22T04:44:08.636Z,1340340248.636 [triangle_control_speed_onelap:ThreeLegs:MassHold:A.Pitch] Stopped
2012-06-22T04:44:08.636Z,1340340248.636 [triangle_control_speed_onelap:ThreeLegs:ElevatorHold] Stopped
2012-06-22T04:44:08.636Z,1340340248.636 [triangle_control_speed_onelap:ThreeLegs:ElevatorHold](INFO): Aggregate::uninitialize triangle_control_speed_onelap:ThreeLegs:ElevatorHold
2012-06-22T04:44:08.636Z,1340340248.636 [triangle_control_speed_onelap:ThreeLegs:ElevatorHold:A.Pitch] Stopped
2012-06-22T04:44:08.636Z,1340340248.636 [triangle_control_speed_onelap:ThreeLegs:D] Stopped
2012-06-22T04:44:08.636Z,1340340248.636 [triangle_control_speed_onelap:ThreeLegs:E] Stopped
2012-06-22T04:44:08.636Z,1340340248.636 [triangle_control_speed_onelap:ThreeLegs:F] Stopped
2012-06-22T04:44:08.636Z,1340340248.636 [triangle_control_speed_onelap:ThreeLegs:G] Stopped
2012-06-22T04:44:08.636Z,1340340248.636 [triangle_control_speed_onelap:ThreeLegs:H] Stopped
2012-06-22T04:44:08.636Z,1340340248.636 [triangle_control_speed_onelap:ThreeLegs:I] Stopped
2012-06-22T04:44:08.637Z,1340340248.636 [triangle_control_speed_onelap:ThreeLegs:J] Stopped
2012-06-22T04:44:08.637Z,1340340248.636 [triangle_control_speed_onelap:ThreeLegs:K] Stopped
2012-06-22T04:44:08.637Z,1340340248.637 [triangle_control_speed_onelap:ThreeLegs:L] Stopped
2012-06-22T04:44:08.642Z,1340340248.642 [triangle_control_speed_onelap](INFO): Completed triangle_control_speed_onelap
2012-06-22T04:44:08.642Z,1340340248.642 [triangle_control_speed_onelap] Stopped
2012-06-22T04:44:08.643Z,1340340248.643 [triangle_control_speed_onelap](INFO): Aggregate::uninitialize triangle_control_speed_onelap
2012-06-22T04:44:08.643Z,1340340248.643 [triangle_control_speed_onelap:A.AltitudeEnvelope] Stopped
2012-06-22T04:44:08.643Z,1340340248.643 [triangle_control_speed_onelap:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2012-06-22T04:44:08.643Z,1340340248.643 [triangle_control_speed_onelap:B.DepthEnvelope] Stopped
2012-06-22T04:44:08.643Z,1340340248.643 [triangle_control_speed_onelap:B.DepthEnvelope](DEBUG): Uninitialize.
2012-06-22T04:44:08.643Z,1340340248.643 [triangle_control_speed_onelap:C.OffshoreEnvelope] Stopped
2012-06-22T04:44:08.643Z,1340340248.643 [triangle_control_speed_onelap:C.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2012-06-22T04:44:08.643Z,1340340248.643 [triangle_control_speed_onelap:D] Stopped
2012-06-22T04:44:08.987Z,1340340248.987 [MissionManager](IMPORTANT): Started mission Default
2012-06-22T04:44:08.987Z,1340340248.987 [Default] Running Loop=1
2012-06-22T04:44:08.987Z,1340340248.987 [Default](INFO): Aggregate::initialize Default
2012-06-22T04:44:08.987Z,1340340248.987 [Default:D.SetSpeed] Running Loop=1
2012-06-22T04:44:08.987Z,1340340248.987 [Default:D.SetSpeed](DEBUG): Initialize.
2012-06-22T04:44:08.987Z,1340340248.987 [Default:E.GoToSurface] Running Loop=1
2012-06-22T04:44:08.987Z,1340340248.987 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T04:44:08.988Z,1340340248.987 [Default:Iridium] Running Loop=1
2012-06-22T04:44:08.988Z,1340340248.988 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-06-22T04:44:08.988Z,1340340248.988 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-06-22T04:44:08.988Z,1340340248.988 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-06-22T04:44:08.988Z,1340340248.988 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-06-22T04:44:08.988Z,1340340248.988 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T04:44:08.990Z,1340340248.990 [Default:Iridium:B.GoToSurface] Stopped
2012-06-22T04:44:08.990Z,1340340248.990 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T04:44:08.990Z,1340340248.990 [Default:Iridium:Read_Iridium] Running Loop=1
2012-06-22T04:44:08.991Z,1340340248.991 [Default:GPS] Running Loop=1
2012-06-22T04:44:08.991Z,1340340248.991 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-06-22T04:44:08.991Z,1340340248.991 [Default:GPS:A.SetSpeed] Running Loop=1
2012-06-22T04:44:08.991Z,1340340248.991 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-06-22T04:44:08.991Z,1340340248.991 [Default:GPS:B.GoToSurface] Running Loop=1
2012-06-22T04:44:08.991Z,1340340248.991 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T04:44:08.992Z,1340340248.992 [Default:GPS:B.GoToSurface] Stopped
2012-06-22T04:44:08.992Z,1340340248.992 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T04:44:08.992Z,1340340248.992 [Default:GPS:Read_GPS] Running Loop=1
2012-06-22T04:44:11.359Z,1340340251.359 [NAL9601](IMPORTANT): GPS fix at: 1340340456.00
2012-06-22T04:44:11.376Z,1340340251.376 [Default:GPS:Read_GPS] Stopped
2012-06-22T04:44:11.376Z,1340340251.376 [Default:GPS:D] Running Loop=1
2012-06-22T04:44:11.787Z,1340340251.787 [Default:GPS:D] Stopped
2012-06-22T04:44:11.788Z,1340340251.788 [Default:GPS](INFO): Completed Default:GPS
2012-06-22T04:44:11.788Z,1340340251.788 [Default:GPS] Stopped
2012-06-22T04:44:11.788Z,1340340251.788 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-06-22T04:44:11.788Z,1340340251.788 [Default:GPS:A.SetSpeed] Stopped
2012-06-22T04:44:11.788Z,1340340251.788 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T04:44:28.625Z,1340340268.625 [Radio_Freewave](INFO): Powering down
2012-06-22T04:44:31.040Z,1340340271.040 [NAL9601](INFO): SBD MO Status=2, MOMSN=10600, MT Status=2, MTMSN=0
2012-06-22T04:44:31.040Z,1340340271.040 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-06-22T04:44:33.405Z,1340340273.405 [Radio_Freewave](INFO): Powering up
2012-06-22T04:44:58.314Z,1340340298.314 [NAL9601](INFO): SBD MO Status=1, MOMSN=10600, MT Status=0, MTMSN=0
2012-06-22T04:44:58.459Z,1340340298.459 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0007.lzma
2012-06-22T04:44:58.459Z,1340340298.459 [NAL9601](INFO): Packets left to send: 2
2012-06-22T04:44:58.461Z,1340340298.461 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0007.lzma.parts/0002.sbd
2012-06-22T04:45:10.933Z,1340340310.933 [NAL9601](INFO): SBD MO Status=1, MOMSN=10601, MT Status=0, MTMSN=0
2012-06-22T04:45:11.143Z,1340340311.143 [NAL9601](INFO): Sent 332 bytes from file Logs/20120622T014020/shore0007.lzma
2012-06-22T04:45:11.143Z,1340340311.143 [NAL9601](INFO): Packets left to send: 1
2012-06-22T04:45:11.144Z,1340340311.144 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0007.lzma.parts/0001.sbd
2012-06-22T04:45:33.477Z,1340340333.477 [NAL9601](INFO): SBD MO Status=1, MOMSN=10602, MT Status=0, MTMSN=0
2012-06-22T04:45:33.607Z,1340340333.607 [NAL9601](INFO): Sent 28 bytes from file Logs/20120622T014020/shore0007.lzma
2012-06-22T04:45:33.607Z,1340340333.607 [NAL9601](INFO): Packets left to send: 0
2012-06-22T04:45:33.608Z,1340340333.608 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0007.lzma.parts/0000.sbd
2012-06-22T04:45:44.277Z,1340340344.277 [NAL9601](INFO): SBD MO Status=0, MOMSN=10603, MT Status=0, MTMSN=0
2012-06-22T04:45:44.515Z,1340340344.514 [Default:Iridium:Read_Iridium] Stopped
2012-06-22T04:45:44.515Z,1340340344.515 [Default:Iridium](INFO): Completed Default:Iridium
2012-06-22T04:45:44.515Z,1340340344.515 [Default:Iridium] Stopped
2012-06-22T04:45:44.515Z,1340340344.515 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-06-22T04:45:44.515Z,1340340344.515 [Default:Iridium:A.SetSpeed] Stopped
2012-06-22T04:45:44.515Z,1340340344.515 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T04:45:44.516Z,1340340344.516 [Default:F.Wait] Running Loop=1
2012-06-22T04:45:44.516Z,1340340344.516 [Default:F.Wait](DEBUG): Initialize Wait Component.
2012-06-22T04:45:54.837Z,1340340354.837 [NAL9601](INFO): Powering down
2012-06-22T04:49:20.071Z,1340340560.071 [CBIT](IMPORTANT): Beginning GF scan
2012-06-22T04:50:09.976Z,1340340609.976 [CBIT](INFO): No ground fault detected
2012-06-22T04:50:44.815Z,1340340644.815 [Default:CallIridium] Running Loop=1
2012-06-22T04:50:44.816Z,1340340644.816 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-06-22T04:50:44.816Z,1340340644.816 [Default:CallIridium:A] Running Loop=1
2012-06-22T04:50:44.816Z,1340340644.816 [Default:CallIridium:A] Stopped
2012-06-22T04:50:44.816Z,1340340644.816 [Default:CallIridium:B] Running Loop=1
2012-06-22T04:50:44.816Z,1340340644.816 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-06-22T04:50:49.876Z,1340340649.876 [Default:Iridium] Running Loop=1
2012-06-22T04:50:49.876Z,1340340649.876 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-06-22T04:50:49.876Z,1340340649.876 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-06-22T04:50:49.876Z,1340340649.876 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-06-22T04:50:49.877Z,1340340649.876 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-06-22T04:50:49.877Z,1340340649.877 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T04:50:49.877Z,1340340649.877 [Default:Iridium:B.GoToSurface] Stopped
2012-06-22T04:50:49.877Z,1340340649.877 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T04:50:49.877Z,1340340649.878 [Default:Iridium:Read_Iridium] Running Loop=1
2012-06-22T04:50:49.878Z,1340340649.878 [Default:GPS] Running Loop=1
2012-06-22T04:50:49.878Z,1340340649.878 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-06-22T04:50:49.878Z,1340340649.878 [Default:GPS:A.SetSpeed] Running Loop=1
2012-06-22T04:50:49.878Z,1340340649.878 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-06-22T04:50:49.878Z,1340340649.878 [Default:GPS:B.GoToSurface] Running Loop=1
2012-06-22T04:50:49.878Z,1340340649.878 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-22T04:50:49.879Z,1340340649.879 [Default:GPS:B.GoToSurface] Stopped
2012-06-22T04:50:49.879Z,1340340649.879 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-22T04:50:49.879Z,1340340649.879 [Default:GPS:Read_GPS] Running Loop=1
2012-06-22T04:50:50.475Z,1340340650.475 [NAL9601](INFO): Powering up
2012-06-22T04:50:57.686Z,1340340657.686 [Radio_Freewave](INFO): Powering down
2012-06-22T04:50:58.080Z,1340340658.080 [Radio_Freewave](INFO): Powering up
2012-06-22T04:51:56.199Z,1340340716.199 [NAL9601](INFO): NAL9601 initialized
2012-06-22T04:52:24.054Z,1340340744.054 [NAL9601](INFO): SBD MO Status=2, MOMSN=10604, MT Status=2, MTMSN=0
2012-06-22T04:52:24.054Z,1340340744.054 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-06-22T04:52:25.251Z,1340340745.251 [NAL9601](IMPORTANT): GPS fix at: 1340340944.00
2012-06-22T04:52:25.354Z,1340340745.354 [Default:GPS:Read_GPS] Stopped
2012-06-22T04:52:25.354Z,1340340745.354 [Default:GPS:D] Running Loop=1
2012-06-22T04:52:25.672Z,1340340745.672 [Default:GPS:D] Stopped
2012-06-22T04:52:25.673Z,1340340745.673 [Default:GPS](INFO): Completed Default:GPS
2012-06-22T04:52:25.673Z,1340340745.673 [Default:GPS] Stopped
2012-06-22T04:52:25.673Z,1340340745.673 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-06-22T04:52:25.673Z,1340340745.673 [Default:GPS:A.SetSpeed] Stopped
2012-06-22T04:52:25.673Z,1340340745.673 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-06-22T04:52:47.174Z,1340340767.174 [NAL9601](IMPORTANT): SBD MO Status=1, MOMSN=10604, MT Status=1, MTMSN=2780
2012-06-22T04:52:47.307Z,1340340767.307 [NAL9601](INFO): Sent 153 bytes from file Logs/20120622T014020/shore0008.lzma
2012-06-22T04:52:47.307Z,1340340767.307 [NAL9601](INFO): Packets left to send: 0
2012-06-22T04:52:47.309Z,1340340767.309 [NAL9601](INFO): Stored copy of sent data in Logs/20120622T014020/shore0008.lzma.parts/0000.sbd
2012-06-22T04:52:47.720Z,1340340767.720 [NAL9601](INFO): Received command:restart logs
2012-06-22T04:52:47.747Z,1340340767.747 [CommandLine](IMPORTANT): got command restart logs