2012-06-03T20:06:20.047Z,1338753980.047 [NAL9601](IMPORTANT): GPS fix at: 1338754432.00
2012-06-03T20:06:20.072Z,1338753980.072 [Default:GPS:Read_GPS] Stopped
2012-06-03T20:06:20.072Z,1338753980.072 [Default:GPS:D] Running Loop=1
2012-06-03T20:06:20.418Z,1338753980.418 [Default:GPS:D] Stopped
2012-06-03T20:06:20.419Z,1338753980.419 [Default:GPS](INFO): Completed Default:GPS
2012-06-03T20:06:20.419Z,1338753980.419 [Default:GPS] Stopped
2012-06-03T20:06:20.419Z,1338753980.419 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-06-03T20:06:20.419Z,1338753980.419 [Default:GPS:A.SetSpeed] Stopped
2012-06-03T20:06:20.419Z,1338753980.419 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-06-03T20:06:38.788Z,1338753998.788 [NAL9601](IMPORTANT): SBD MO Status=1, MOMSN=8830, MT Status=1, MTMSN=2692
2012-06-03T20:06:38.982Z,1338753998.982 [NAL9601](INFO): Sent 169 bytes from file Logs/20120603T002455/shore0022.lzma
2012-06-03T20:06:38.983Z,1338753998.983 [NAL9601](INFO): Packets left to send: 0
2012-06-03T20:06:38.984Z,1338753998.984 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000627
2012-06-03T20:06:39.411Z,1338753999.411 [NAL9601](INFO): Received command:run Maintenance/calibrateSparton2DWithPitch.xml
2012-06-03T20:06:39.811Z,1338753999.810 [CommandLine](IMPORTANT): got command run ./Missions/Maintenance/calibrateSparton2DWithPitch.xml
2012-06-03T20:06:39.811Z,1338753999.811 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/calibrateSparton2DWithPitch.xml
2012-06-03T20:06:39.997Z,1338753999.997 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.MassDefault = -0.006270 n/a
2012-06-03T20:06:40.003Z,1338754000.003 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.Depth = 20.000000 m
2012-06-03T20:06:40.008Z,1338754000.008 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.Speed = 1.000000 m/s
2012-06-03T20:06:40.013Z,1338754000.013 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.WaitDuration = 20.000000 s
2012-06-03T20:06:40.029Z,1338754000.029 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.RudderAngle = 7.000000 arcdeg
2012-06-03T20:06:40.036Z,1338754000.036 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.MagNorthHeading = 15.000000 arcdeg
2012-06-03T20:06:40.043Z,1338754000.043 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.MinAltitude = 7.000000 m
2012-06-03T20:06:40.050Z,1338754000.050 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.MaxDepth = 35.000000 m
2012-06-03T20:06:40.053Z,1338754000.053 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.MinWaterDepth = 15.000000 m
2012-06-03T20:06:40.061Z,1338754000.061 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.MinOffshore = 2000.000000 m
2012-06-03T20:06:40.068Z,1338754000.068 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.NeedCommsTime = 30.000000 min
2012-06-03T20:06:40.071Z,1338754000.071 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.ManualLoops = 1.000000 count
2012-06-03T20:06:40.078Z,1338754000.078 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.AutoLoops = 2.000000 count
2012-06-03T20:06:40.081Z,1338754000.081 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.CheckLoops = 2.000000 count
2012-06-03T20:06:40.085Z,1338754000.085 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.BuoyancyNeutral = 0.000400 n/a
2012-06-03T20:06:40.088Z,1338754000.088 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.TimeoutDuration = 70.000000 min
2012-06-03T20:06:40.099Z,1338754000.099 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.YoYoMinDepth = 15.000000 m
2012-06-03T20:06:40.102Z,1338754000.102 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.YoYoMaxDepth = 30.000000 m
2012-06-03T20:06:40.105Z,1338754000.105 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.YoYoUpPitch = 30.000000 arcdeg
2012-06-03T20:06:40.112Z,1338754000.112 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.YoYoDownPitch = -30.000000 arcdeg
2012-06-03T20:06:40.113Z,1338754000.114 [calibrateSparton2DWithPitch:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2012-06-03T20:06:40.137Z,1338754000.137 [calibrateSparton2DWithPitch:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2012-06-03T20:06:40.181Z,1338754000.181 [calibrateSparton2DWithPitch:C.WaterDepthEnvelope](DEBUG): Construct WaterDepthEnvelope.
2012-06-03T20:06:40.197Z,1338754000.197 [calibrateSparton2DWithPitch:D.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2012-06-03T20:06:40.213Z,1338754000.213 [calibrateSparton2DWithPitch:F.Buoyancy](DEBUG): Construct Buoyancy.
2012-06-03T20:06:40.216Z,1338754000.217 [calibrateSparton2DWithPitch:G.SetSpeed](DEBUG): Construct.
2012-06-03T20:06:40.232Z,1338754000.232 [calibrateSparton2DWithPitch:H.Pitch](DEBUG): Construct.
2012-06-03T20:06:40.246Z,1338754000.246 [calibrateSparton2DWithPitch:MassOff:A.Pitch](DEBUG): Construct.
2012-06-03T20:06:40.252Z,1338754000.252 [calibrateSparton2DWithPitch:MassOff:Initialization:A.Pitch](DEBUG): Construct.
2012-06-03T20:06:40.266Z,1338754000.266 [calibrateSparton2DWithPitch:MassOff:Initialization:B.Point](DEBUG): Construct.
2012-06-03T20:06:40.272Z,1338754000.272 [calibrateSparton2DWithPitch:MassOff:Initialization:C.Wait](DEBUG): Construct Wait.
2012-06-03T20:06:40.283Z,1338754000.283 [calibrateSparton2DWithPitch:MassOff:PreCircles:A.Point](DEBUG): Construct.
2012-06-03T20:06:40.289Z,1338754000.289 [calibrateSparton2DWithPitch:MassOff:PreCircles:B.Pitch](DEBUG): Construct.
2012-06-03T20:06:40.307Z,1338754000.307 [calibrateSparton2DWithPitch:MassOff:PreCircles:C.Wait](DEBUG): Construct Wait.
2012-06-03T20:06:40.308Z,1338754000.308 [calibrateSparton2DWithPitch:MassOff:PreCircles:D.Point](DEBUG): Construct.
2012-06-03T20:06:40.324Z,1338754000.324 [calibrateSparton2DWithPitch:MassOff:Calibration:Manual_Loop:A.Point](DEBUG): Construct.
2012-06-03T20:06:40.330Z,1338754000.330 [calibrateSparton2DWithPitch:MassOff:Calibration:Manual_Loop:B.Pitch](DEBUG): Construct.
2012-06-03T20:06:40.351Z,1338754000.351 [calibrateSparton2DWithPitch:MassOff:Calibration:Manual_Loop:C.Wait](DEBUG): Construct Wait.
2012-06-03T20:06:40.353Z,1338754000.353 [calibrateSparton2DWithPitch:MassOff:Calibration:Manual_Loop:D.Point](DEBUG): Construct.
2012-06-03T20:06:40.364Z,1338754000.364 [calibrateSparton2DWithPitch:MassOff:Calibration:B.Wait](DEBUG): Construct Wait.
2012-06-03T20:06:40.366Z,1338754000.366 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:A.Point](DEBUG): Construct.
2012-06-03T20:06:40.380Z,1338754000.380 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:PitchDown.Pitch](DEBUG): Construct.
2012-06-03T20:06:40.395Z,1338754000.395 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:PitchUp.Pitch](DEBUG): Construct.
2012-06-03T20:06:40.402Z,1338754000.402 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:D.Wait](DEBUG): Construct Wait.
2012-06-03T20:06:40.403Z,1338754000.403 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:E.Point](DEBUG): Construct.
2012-06-03T20:06:40.422Z,1338754000.422 [calibrateSparton2DWithPitch:MassOff:Calibration:D.CalibrateSparton](DEBUG): Construct CalibrateSparton.
2012-06-03T20:06:40.428Z,1338754000.428 [calibrateSparton2DWithPitch:MassOff:PostCircles:A.Point](DEBUG): Construct.
2012-06-03T20:06:40.434Z,1338754000.434 [calibrateSparton2DWithPitch:MassOff:PostCircles:PitchDown.Pitch](DEBUG): Construct.
2012-06-03T20:06:40.453Z,1338754000.453 [calibrateSparton2DWithPitch:MassOff:PostCircles:PitchUp.Pitch](DEBUG): Construct.
2012-06-03T20:06:40.472Z,1338754000.472 [calibrateSparton2DWithPitch:MassOff:PostCircles:D.Wait](DEBUG): Construct Wait.
2012-06-03T20:06:40.474Z,1338754000.474 [calibrateSparton2DWithPitch:MassOff:PostCircles:E.Point](DEBUG): Construct.
2012-06-03T20:06:40.480Z,1338754000.480 [calibrateSparton2DWithPitch:PostCirclesMass:A.Point](DEBUG): Construct.
2012-06-03T20:06:40.502Z,1338754000.502 [calibrateSparton2DWithPitch:PostCirclesMass:PitchDown.Pitch](DEBUG): Construct.
2012-06-03T20:06:40.509Z,1338754000.509 [calibrateSparton2DWithPitch:PostCirclesMass:PitchUp.Pitch](DEBUG): Construct.
2012-06-03T20:06:40.528Z,1338754000.528 [calibrateSparton2DWithPitch:PostCirclesMass:D.Wait](DEBUG): Construct Wait.
2012-06-03T20:06:40.529Z,1338754000.529 [calibrateSparton2DWithPitch:PostCirclesMass:E.Point](DEBUG): Construct.
2012-06-03T20:06:40.582Z,1338754000.582 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="utf-8"?>
<Mission xmlns="Tethys" 
         xmlns:Control="Tethys/Control" 
         xmlns:Guidance="Tethys/Guidance" 
         xmlns:Sensor="Tethys/Sensor"
         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/Sensor http://aosn.mbari.org/tethys/Xml/Sensor.xsd
                             Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
         Id="calibrateSparton2DWithPitch">

    <DefineArg Name="MassDefault"><Control:VerticalControl.massDefault/></DefineArg>

    <DefineArg Name="Depth"><Units:meter /><Value>20.0</Value></DefineArg>

    <DefineArg Name="Speed"><Units:meter_per_second /><Value>1</Value></DefineArg>

    <DefineArg Name="WaitDuration"><Units:second /><Value>20</Value></DefineArg>

    <DefineArg Name="RudderAngle"><Units:degree /><Value>7</Value></DefineArg>

    <DefineArg Name="MagNorthHeading"><Units:degree /><Value>15</Value></DefineArg>

    <DefineArg Name="MinAltitude"><Units:meter/><Value>7</Value></DefineArg>

    <DefineArg Name="MaxDepth"><Units:meter/><Value>35</Value></DefineArg>

    <DefineArg Name="MinWaterDepth"><Units:meter/><Value>15</Value></DefineArg>

    <DefineArg Name="MinOffshore"><Units:meter/><Value>2000</Value></DefineArg>

    <DefineArg Name="NeedCommsTime"><Units:minute/><Value>30</Value></DefineArg>

    <DefineArg Name="ManualLoops"><Units:count/><Value>1</Value></DefineArg>

    <DefineArg Name="AutoLoops"><Units:count/><Value>2</Value></DefineArg>

    <DefineArg Name="CheckLoops"><Units:count/><Value>2</Value></DefineArg>

    <DefineArg Name="BuoyancyNeutral"><Control:VerticalControl.buoyancyNeutral/></DefineArg>

    <DefineArg Name="TimeoutDuration"><Units:minute/><Value>70</Value></DefineArg>

    <DefineArg Name="YoYoMinDepth"><Units:meter/><Value>15.0</Value></DefineArg>

    <DefineArg Name="YoYoMaxDepth"><Units:meter/><Value>30.0</Value></DefineArg>

    <DefineArg Name="YoYoUpPitch"><Units:degree/><Value>30.0</Value></DefineArg>

    <DefineArg Name="YoYoDownPitch"><Units:degree/><Value>-30.0</Value></DefineArg>

    <Timeout Duration="TimeoutDuration"/>

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

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

    <Guidance:WaterDepthEnvelope RunIn="Parallel">
        <Setting><Guidance:WaterDepthEnvelope.minWaterDepth/><Arg Name="MinWaterDepth"/></Setting>
    </Guidance:WaterDepthEnvelope>

    <Guidance:OffshoreEnvelope RunIn="Parallel">
        <Setting><Guidance:OffshoreEnvelope.minOffshore/><Arg Name="MinOffshore"/></Setting>
    </Guidance:OffshoreEnvelope>

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

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

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

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

    <Aggregate Id="MassOff">

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

        <Aggregate Id="Initialization">

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

            <Guidance:Point RunIn="Progression">
                <Setting><Guidance:Point.heading /><Arg Name="MagNorthHeading" /></Setting>
            </Guidance:Point>

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

        </Aggregate>

        <Aggregate Id="PreCircles" Repeat = "CheckLoops">

            <Guidance:Point RunIn="Parallel">
                <Setting><Guidance:Point.rudderAngle /><Arg Name="RudderAngle" /></Setting>
            </Guidance:Point>

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

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

            <Guidance:Point RunIn="Sequence">
                <Setting><Guidance:Point.heading /><Arg Name="MagNorthHeading" /></Setting>
            </Guidance:Point>

        </Aggregate>

        <Aggregate Id="Calibration">

            <Aggregate Id="Manual_Loop" Repeat="ManualLoops">

                <Guidance:Point RunIn="Parallel">
                    <Setting><Guidance:Point.rudderAngle /><Arg Name="RudderAngle" /></Setting>
                </Guidance:Point>

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

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

                <Guidance:Point RunIn="Sequence">
                    <Setting><Guidance:Point.heading /><Arg Name="MagNorthHeading" /></Setting>
                </Guidance:Point>

            </Aggregate>

            <Guidance:Wait RunIn="Sequence">
                <Preemptive><True/></Preemptive>
                <Setting><Guidance:Wait.duration /><Units:second/><Value>0</Value></Setting>
            </Guidance:Wait>

            <Aggregate Id="Auto_Loop" Repeat="AutoLoops">

                <Guidance:Point RunIn="Parallel">
                    <Setting><Guidance:Point.rudderAngle /><Arg Name="RudderAngle" /></Setting>
                </Guidance:Point>

                <Guidance:Pitch Id="PitchDown" RunIn="Parallel">
                    <Until><Universal:platform_pitch_angle/>
                        <Le><Arg Name="YoYoDownPitch"/></Le>
                    </Until>
                    <When><Universal:platform_pitch_angle/>
                        <Ge><Arg Name="YoYoUpPitch"/></Ge>
                    </When>
                    <Setting><Guidance:Pitch.pitch/><Arg Name="YoYoDownPitch"/></Setting>
                </Guidance:Pitch>

                <Guidance:Pitch Id="PitchUp" RunIn="Parallel">
                    <Until><Universal:platform_pitch_angle/>
                        <Ge><Arg Name="YoYoUpPitch"/></Ge>
                    </Until>
                    <When><Universal:platform_pitch_angle/>
                        <Le><Arg Name="YoYoDownPitch"/></Le>
                    </When>
                    <Setting><Guidance:Pitch.pitch/><Arg Name="YoYoUpPitch"/></Setting>
                </Guidance:Pitch>

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

                <Guidance:Point RunIn="Sequence">
                    <Setting><Guidance:Point.heading /><Arg Name="MagNorthHeading" /></Setting>
                </Guidance:Point>

            </Aggregate>

            <Sensor:CalibrateSparton RunIn="Parallel"></Sensor:CalibrateSparton>

        </Aggregate>

        <Aggregate Id="PostCircles" Repeat= "CheckLoops">

            <Guidance:Point RunIn="Parallel">
                <Setting><Guidance:Point.rudderAngle /><Arg Name="RudderAngle" /></Setting>
            </Guidance:Point>

            <Guidance:Pitch Id="PitchDown" RunIn="Parallel">
                <Until><Universal:platform_pitch_angle/>
                    <Le><Arg Name="YoYoDownPitch"/></Le>
                </Until>
                <When><Universal:platform_pitch_angle/>
                    <Ge><Arg Name="YoYoUpPitch"/></Ge>
                </When>
                <Setting><Guidance:Pitch.pitch/><Arg Name="YoYoDownPitch"/></Setting>
            </Guidance:Pitch>

            <Guidance:Pitch Id="PitchUp" RunIn="Parallel">
                <Until><Universal:platform_pitch_angle/>
                    <Ge><Arg Name="YoYoUpPitch"/></Ge>
                </Until>
                <When><Universal:platform_pitch_angle/>
                    <Le><Arg Name="YoYoDownPitch"/></Le>
                </When>
                <Setting><Guidance:Pitch.pitch/><Arg Name="YoYoUpPitch"/></Setting>
            </Guidance:Pitch>

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

            <Guidance:Point RunIn="Sequence">
                <Setting><Guidance:Point.heading /><Arg Name="MagNorthHeading" /></Setting>
            </Guidance:Point>

        </Aggregate>

    </Aggregate>

    <Aggregate Id="PostCirclesMass" Repeat= "CheckLoops">

        <Guidance:Point RunIn="Parallel">
            <Setting><Guidance:Point.rudderAngle /><Arg Name="RudderAngle" /></Setting>
        </Guidance:Point>

        <Guidance:Pitch Id="PitchDown" RunIn="Parallel">
            <Until><Universal:platform_pitch_angle/>
                <Le><Arg Name="YoYoDownPitch"/></Le>
            </Until>
            <When><Universal:platform_pitch_angle/>
                <Ge><Arg Name="YoYoUpPitch"/></Ge>
            </When>
            <Setting><Guidance:Pitch.pitch/><Arg Name="YoYoDownPitch"/></Setting>
        </Guidance:Pitch>

        <Guidance:Pitch Id="PitchUp" RunIn="Parallel">
            <Until><Universal:platform_pitch_angle/>
                <Ge><Arg Name="YoYoUpPitch"/></Ge>
            </Until>
            <When><Universal:platform_pitch_angle/>
                <Le><Arg Name="YoYoDownPitch"/></Le>
            </When>
            <Setting><Guidance:Pitch.pitch/><Arg Name="YoYoUpPitch"/></Setting>
        </Guidance:Pitch>

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

        <Guidance:Point RunIn="Sequence">
            <Setting><Guidance:Point.heading /><Arg Name="MagNorthHeading" /></Setting>
        </Guidance:Point>

    </Aggregate>

</Mission>


2012-06-03T20:06:40.583Z,1338754000.583 [CommandLine](IMPORTANT): Running ./Missions/Maintenance/calibrateSparton2DWithPitch.xml
2012-06-03T20:06:40.828Z,1338754000.828 [Default] Stopped
2012-06-03T20:06:40.828Z,1338754000.828 [Default](INFO): Aggregate::uninitialize Default
2012-06-03T20:06:40.828Z,1338754000.828 [Default:Iridium] Stopped
2012-06-03T20:06:40.828Z,1338754000.828 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-06-03T20:06:40.828Z,1338754000.828 [Default:Iridium:A.SetSpeed] Stopped
2012-06-03T20:06:40.828Z,1338754000.828 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-06-03T20:06:40.828Z,1338754000.828 [Default:Iridium:Read_Iridium] Stopped
2012-06-03T20:06:40.828Z,1338754000.828 [Default:CallIridium] Stopped
2012-06-03T20:06:40.828Z,1338754000.828 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-06-03T20:06:40.828Z,1338754000.829 [Default:CallIridium:B] Stopped
2012-06-03T20:06:40.829Z,1338754000.829 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-06-03T20:06:40.829Z,1338754000.829 [Default:D.SetSpeed] Stopped
2012-06-03T20:06:40.829Z,1338754000.829 [Default:D.SetSpeed](DEBUG): Uninitialize.
2012-06-03T20:06:40.829Z,1338754000.829 [Default:E.GoToSurface] Stopped
2012-06-03T20:06:40.829Z,1338754000.829 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-03T20:06:40.829Z,1338754000.829 [Default:F.Wait] Stopped
2012-06-03T20:06:40.829Z,1338754000.829 [Default:F.Wait](DEBUG): Uninitialize Wait Component.
2012-06-03T20:06:40.829Z,1338754000.829 [MissionManager](IMPORTANT): Started mission calibrateSparton2DWithPitch
2012-06-03T20:06:40.829Z,1338754000.829 [calibrateSparton2DWithPitch] Running Loop=1
2012-06-03T20:06:40.829Z,1338754000.829 [calibrateSparton2DWithPitch](INFO): Aggregate::initialize calibrateSparton2DWithPitch
2012-06-03T20:06:40.830Z,1338754000.829 [calibrateSparton2DWithPitch:A.AltitudeEnvelope] Running Loop=1
2012-06-03T20:06:40.830Z,1338754000.830 [calibrateSparton2DWithPitch:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2012-06-03T20:06:40.830Z,1338754000.830 [calibrateSparton2DWithPitch:B.DepthEnvelope] Running Loop=1
2012-06-03T20:06:40.830Z,1338754000.830 [calibrateSparton2DWithPitch:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2012-06-03T20:06:40.831Z,1338754000.831 [calibrateSparton2DWithPitch:C.WaterDepthEnvelope] Running Loop=1
2012-06-03T20:06:40.831Z,1338754000.831 [calibrateSparton2DWithPitch:C.WaterDepthEnvelope](DEBUG): Initialize WaterDepthEnvelopeComponent.
2012-06-03T20:06:40.831Z,1338754000.831 [calibrateSparton2DWithPitch:D.OffshoreEnvelope] Running Loop=1
2012-06-03T20:06:40.831Z,1338754000.831 [calibrateSparton2DWithPitch:D.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2012-06-03T20:06:40.831Z,1338754000.831 [calibrateSparton2DWithPitch:E] Running Loop=1
2012-06-03T20:06:40.831Z,1338754000.831 [calibrateSparton2DWithPitch:F.Buoyancy] Running Loop=1
2012-06-03T20:06:40.831Z,1338754000.831 [calibrateSparton2DWithPitch:F.Buoyancy](DEBUG): Initialize Buoyancy Component.
2012-06-03T20:06:40.832Z,1338754000.832 [calibrateSparton2DWithPitch:G.SetSpeed] Running Loop=1
2012-06-03T20:06:40.832Z,1338754000.832 [calibrateSparton2DWithPitch:G.SetSpeed](DEBUG): Initialize.
2012-06-03T20:06:40.832Z,1338754000.832 [calibrateSparton2DWithPitch:H.Pitch] Running Loop=1
2012-06-03T20:06:40.832Z,1338754000.832 [calibrateSparton2DWithPitch:H.Pitch](DEBUG): Initialize.
2012-06-03T20:06:40.832Z,1338754000.832 [calibrateSparton2DWithPitch:H.Pitch] Running Loop=1
2012-06-03T20:06:40.837Z,1338754000.838 [calibrateSparton2DWithPitch:G.SetSpeed] Running Loop=1
2012-06-03T20:06:40.847Z,1338754000.847 [calibrateSparton2DWithPitch:E] Running Loop=1
2012-06-03T20:06:40.848Z,1338754000.848 [calibrateSparton2DWithPitch:E](DEBUG): Initialize ReadDataComponent to sense platform_orientation
2012-06-03T20:06:40.849Z,1338754000.849 [calibrateSparton2DWithPitch:D.OffshoreEnvelope] Running Loop=1
2012-06-03T20:06:40.854Z,1338754000.854 [calibrateSparton2DWithPitch:C.WaterDepthEnvelope] Running Loop=1
2012-06-03T20:06:40.859Z,1338754000.859 [calibrateSparton2DWithPitch:B.DepthEnvelope] Running Loop=1
2012-06-03T20:06:40.865Z,1338754000.865 [calibrateSparton2DWithPitch:A.AltitudeEnvelope] Running Loop=1
2012-06-03T20:07:01.064Z,1338754021.064 [NAL9601](INFO): SBD MO Status=2, MOMSN=8831, MT Status=2, MTMSN=0
2012-06-03T20:07:01.064Z,1338754021.064 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-06-03T20:07:04.268Z,1338754024.268 [Radio_Freewave](INFO): Powering down
2012-06-03T20:07:04.648Z,1338754024.648 [Radio_Freewave](INFO): Powering up
2012-06-03T20:07:06.723Z,1338754026.723 [NAL9601](INFO): Powering down
2012-06-03T20:07:11.049Z,1338754031.049 [Radio_Freewave](INFO): Powering down
2012-06-03T20:07:30.253Z,1338754050.253 [Radio_Freewave](INFO): Powering up
2012-06-03T20:07:45.868Z,1338754065.868 [Radio_Freewave](INFO): Powering down
2012-06-03T20:07:59.467Z,1338754079.467 [Radio_Freewave](INFO): Powering up
2012-06-03T20:08:11.857Z,1338754091.857 [Radio_Freewave](INFO): Powering down
2012-06-03T20:08:28.648Z,1338754108.648 [Radio_Freewave](INFO): Powering up
2012-06-03T20:08:43.072Z,1338754123.072 [Radio_Freewave](INFO): Powering down
2012-06-03T20:08:48.267Z,1338754128.267 [Radio_Freewave](INFO): Powering up
2012-06-03T20:08:58.248Z,1338754138.248 [Radio_Freewave](INFO): Powering down
2012-06-03T20:09:09.062Z,1338754149.062 [Radio_Freewave](INFO): Powering up
2012-06-03T20:09:19.867Z,1338754159.867 [Radio_Freewave](INFO): Powering down
2012-06-03T20:09:24.648Z,1338754164.648 [Radio_Freewave](INFO): Powering up
2012-06-03T20:09:29.481Z,1338754169.481 [calibrateSparton2DWithPitch:MassOff] Running Loop=1
2012-06-03T20:09:29.481Z,1338754169.481 [calibrateSparton2DWithPitch:MassOff](INFO): Aggregate::initialize calibrateSparton2DWithPitch:MassOff
2012-06-03T20:09:29.481Z,1338754169.481 [calibrateSparton2DWithPitch:MassOff:A.Pitch] Running Loop=1
2012-06-03T20:09:29.481Z,1338754169.481 [calibrateSparton2DWithPitch:MassOff:A.Pitch](DEBUG): Initialize.
2012-06-03T20:09:29.481Z,1338754169.481 [calibrateSparton2DWithPitch:MassOff:Initialization] Running Loop=1
2012-06-03T20:09:29.482Z,1338754169.482 [calibrateSparton2DWithPitch:MassOff:Initialization](INFO): Aggregate::initialize calibrateSparton2DWithPitch:MassOff:Initialization
2012-06-03T20:09:29.482Z,1338754169.482 [calibrateSparton2DWithPitch:MassOff:Initialization:A.Pitch] Running Loop=1
2012-06-03T20:09:29.482Z,1338754169.482 [calibrateSparton2DWithPitch:MassOff:Initialization:A.Pitch](DEBUG): Initialize.
2012-06-03T20:09:29.482Z,1338754169.482 [calibrateSparton2DWithPitch:MassOff:Initialization:B.Point] Running Loop=1
2012-06-03T20:09:29.482Z,1338754169.482 [calibrateSparton2DWithPitch:MassOff:Initialization:B.Point](DEBUG): Initialize.
2012-06-03T20:09:29.891Z,1338754169.891 [calibrateSparton2DWithPitch:MassOff:A.Pitch] Running Loop=1
2012-06-03T20:09:34.410Z,1338754174.410 [RudderServo](FAULT): Overload Error
2012-06-03T20:09:34.410Z,1338754174.410 [RudderServo] Hardware Fault, FailCount= 1
2012-06-03T20:09:34.410Z,1338754174.410 [RudderServo](ERROR): Hardware Fault
2012-06-03T20:09:34.465Z,1338754174.465 [CBIT](ERROR): Hardware Fault in component: RudderServo
2012-06-03T20:09:34.682Z,1338754174.682 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2012-06-03T20:09:34.683Z,1338754174.682 [RudderServo](INFO): Powering down
2012-06-03T20:09:34.708Z,1338754174.708 [CBIT](INFO): Clearing failed state for component RudderServo
2012-06-03T20:09:34.708Z,1338754174.708 [RudderServo] No Fault, FailCount= 1
2012-06-03T20:09:35.482Z,1338754175.482 [RudderServo](DEBUG): Initializing EZServoServo.
2012-06-03T20:09:35.603Z,1338754175.603 [RudderServo](DEBUG): Initializing RudderServo.
2012-06-03T20:09:40.248Z,1338754180.248 [Radio_Freewave](INFO): Powering down
2012-06-03T20:09:40.338Z,1338754180.338 [RudderServo](FAULT): Overload Error
2012-06-03T20:09:40.338Z,1338754180.338 [RudderServo] Hardware Fault, FailCount= 2
2012-06-03T20:09:40.338Z,1338754180.338 [RudderServo](ERROR): Hardware Fault
2012-06-03T20:09:40.392Z,1338754180.392 [CBIT](ERROR): Hardware Fault in component: RudderServo
2012-06-03T20:09:40.711Z,1338754180.711 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2012-06-03T20:09:40.711Z,1338754180.711 [RudderServo](INFO): Powering down
2012-06-03T20:09:40.736Z,1338754180.736 [CBIT](INFO): Clearing failed state for component RudderServo
2012-06-03T20:09:40.737Z,1338754180.737 [RudderServo] No Fault, FailCount= 2
2012-06-03T20:09:41.512Z,1338754181.512 [RudderServo](DEBUG): Initializing EZServoServo.
2012-06-03T20:09:41.631Z,1338754181.631 [RudderServo](DEBUG): Initializing RudderServo.
2012-06-03T20:09:46.267Z,1338754186.267 [Radio_Freewave](INFO): Powering up
2012-06-03T20:09:46.334Z,1338754186.334 [RudderServo](FAULT): Overload Error
2012-06-03T20:09:46.334Z,1338754186.334 [RudderServo] Hardware Fault, FailCount= 3
2012-06-03T20:09:46.335Z,1338754186.335 [RudderServo](ERROR): Hardware Fault
2012-06-03T20:09:46.384Z,1338754186.384 [CBIT](ERROR): Hardware Fault in component: RudderServo
2012-06-03T20:09:46.385Z,1338754186.385 [CBIT](CRITICAL): Hardware Fault in component: RudderServo
2012-06-03T20:09:46.691Z,1338754186.691 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2012-06-03T20:09:46.692Z,1338754186.691 [RudderServo](INFO): Powering down
2012-06-03T20:09:47.080Z,1338754187.080 [calibrateSparton2DWithPitch] Stopped
2012-06-03T20:09:47.081Z,1338754187.081 [calibrateSparton2DWithPitch](INFO): Aggregate::uninitialize calibrateSparton2DWithPitch
2012-06-03T20:09:47.081Z,1338754187.081 [calibrateSparton2DWithPitch:A.AltitudeEnvelope] Stopped
2012-06-03T20:09:47.081Z,1338754187.081 [calibrateSparton2DWithPitch:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2012-06-03T20:09:47.081Z,1338754187.081 [calibrateSparton2DWithPitch:B.DepthEnvelope] Stopped
2012-06-03T20:09:47.081Z,1338754187.081 [calibrateSparton2DWithPitch:B.DepthEnvelope](DEBUG): Uninitialize.
2012-06-03T20:09:47.081Z,1338754187.081 [calibrateSparton2DWithPitch:C.WaterDepthEnvelope] Stopped
2012-06-03T20:09:47.081Z,1338754187.081 [calibrateSparton2DWithPitch:C.WaterDepthEnvelope](DEBUG): Uninitialize WaterDepthEnvelopeComponent.
2012-06-03T20:09:47.081Z,1338754187.081 [calibrateSparton2DWithPitch:D.OffshoreEnvelope] Stopped
2012-06-03T20:09:47.081Z,1338754187.081 [calibrateSparton2DWithPitch:D.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2012-06-03T20:09:47.081Z,1338754187.081 [calibrateSparton2DWithPitch:E] Stopped
2012-06-03T20:09:47.081Z,1338754187.081 [calibrateSparton2DWithPitch:F.Buoyancy] Stopped
2012-06-03T20:09:47.081Z,1338754187.081 [calibrateSparton2DWithPitch:F.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2012-06-03T20:09:47.081Z,1338754187.081 [calibrateSparton2DWithPitch:G.SetSpeed] Stopped
2012-06-03T20:09:47.082Z,1338754187.082 [calibrateSparton2DWithPitch:G.SetSpeed](DEBUG): Uninitialize.
2012-06-03T20:09:47.082Z,1338754187.082 [calibrateSparton2DWithPitch:H.Pitch] Stopped
2012-06-03T20:09:47.082Z,1338754187.082 [calibrateSparton2DWithPitch:MassOff] Stopped
2012-06-03T20:09:47.082Z,1338754187.082 [calibrateSparton2DWithPitch:MassOff](INFO): Aggregate::uninitialize calibrateSparton2DWithPitch:MassOff
2012-06-03T20:09:47.082Z,1338754187.082 [calibrateSparton2DWithPitch:MassOff:A.Pitch] Stopped
2012-06-03T20:09:47.082Z,1338754187.082 [calibrateSparton2DWithPitch:MassOff:Initialization] Stopped
2012-06-03T20:09:47.082Z,1338754187.082 [calibrateSparton2DWithPitch:MassOff:Initialization](INFO): Aggregate::uninitialize calibrateSparton2DWithPitch:MassOff:Initialization
2012-06-03T20:09:47.082Z,1338754187.082 [calibrateSparton2DWithPitch:MassOff:Initialization:A.Pitch] Stopped
2012-06-03T20:09:47.082Z,1338754187.082 [calibrateSparton2DWithPitch:MassOff:Initialization:B.Point] Stopped
2012-06-03T20:09:47.457Z,1338754187.457 [MissionManager](IMPORTANT): Started mission Default
2012-06-03T20:09:47.457Z,1338754187.457 [Default] Running Loop=1
2012-06-03T20:09:47.457Z,1338754187.457 [Default](INFO): Aggregate::initialize Default
2012-06-03T20:09:47.457Z,1338754187.457 [Default:D.SetSpeed] Running Loop=1
2012-06-03T20:09:47.457Z,1338754187.457 [Default:D.SetSpeed](DEBUG): Initialize.
2012-06-03T20:09:47.457Z,1338754187.457 [Default:E.GoToSurface] Running Loop=1
2012-06-03T20:09:47.457Z,1338754187.457 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-03T20:09:47.458Z,1338754187.458 [Default:Iridium] Running Loop=1
2012-06-03T20:09:47.458Z,1338754187.458 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-06-03T20:09:47.458Z,1338754187.458 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-06-03T20:09:47.458Z,1338754187.458 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-06-03T20:09:47.458Z,1338754187.458 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-06-03T20:09:47.458Z,1338754187.458 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-03T20:09:47.460Z,1338754187.460 [Default:CallIridium] Running Loop=1
2012-06-03T20:09:47.460Z,1338754187.460 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-06-03T20:09:47.460Z,1338754187.460 [Default:CallIridium:A] Running Loop=1
2012-06-03T20:09:47.461Z,1338754187.461 [Default:CallIridium:A] Stopped
2012-06-03T20:09:47.461Z,1338754187.461 [Default:CallIridium:B] Running Loop=1
2012-06-03T20:09:47.461Z,1338754187.461 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-06-03T20:09:47.461Z,1338754187.461 [Default:Iridium:B.GoToSurface] Stopped
2012-06-03T20:09:47.462Z,1338754187.462 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-03T20:09:47.463Z,1338754187.463 [Default:Iridium:Read_Iridium] Running Loop=1
2012-06-03T20:09:47.870Z,1338754187.870 [Radio_Freewave](INFO): Powering down
2012-06-03T20:09:47.881Z,1338754187.881 [Default:GPS] Running Loop=1
2012-06-03T20:09:47.881Z,1338754187.881 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-06-03T20:09:47.881Z,1338754187.881 [Default:GPS:A.SetSpeed] Running Loop=1
2012-06-03T20:09:47.881Z,1338754187.881 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-06-03T20:09:47.881Z,1338754187.881 [Default:GPS:B.GoToSurface] Running Loop=1
2012-06-03T20:09:47.881Z,1338754187.881 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-03T20:09:48.244Z,1338754188.244 [NAL9601](INFO): Powering up
2012-06-03T20:09:58.592Z,1338754198.592 [Radio_Freewave](INFO): Powering up
2012-06-03T20:09:58.604Z,1338754198.604 [Default:GPS:B.GoToSurface] Stopped
2012-06-03T20:09:58.604Z,1338754198.604 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-03T20:09:58.604Z,1338754198.604 [Default:GPS:Read_GPS] Running Loop=1
2012-06-03T20:10:53.870Z,1338754253.870 [NAL9601](INFO): NAL9601 initialized
2012-06-03T20:11:09.229Z,1338754269.229 [NAL9601](INFO): SBD MO Status=0, MOMSN=8831, MT Status=0, MTMSN=0
2012-06-03T20:11:09.483Z,1338754269.483 [Default:Iridium:Read_Iridium] Stopped
2012-06-03T20:11:09.483Z,1338754269.483 [Default:Iridium](INFO): Completed Default:Iridium
2012-06-03T20:11:09.483Z,1338754269.483 [Default:Iridium] Stopped
2012-06-03T20:11:09.483Z,1338754269.483 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-06-03T20:11:09.483Z,1338754269.483 [Default:Iridium:A.SetSpeed] Stopped
2012-06-03T20:11:09.483Z,1338754269.483 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-06-03T20:11:09.484Z,1338754269.484 [Default:F.Wait] Running Loop=1
2012-06-03T20:11:09.484Z,1338754269.484 [Default:F.Wait](DEBUG): Initialize Wait Component.
2012-06-03T20:11:09.642Z,1338754269.642 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2012-06-03T20:11:09.642Z,1338754269.642 [Default:CallIridium:B] Stopped
2012-06-03T20:11:09.643Z,1338754269.643 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-06-03T20:11:09.643Z,1338754269.643 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-06-03T20:11:09.643Z,1338754269.643 [Default:CallIridium] Stopped
2012-06-03T20:11:09.643Z,1338754269.643 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-06-03T20:11:52.431Z,1338754312.431 [NAL9601](IMPORTANT): GPS fix at: 1338754766.00
2012-06-03T20:11:52.447Z,1338754312.447 [Default:GPS:Read_GPS] Stopped
2012-06-03T20:11:52.447Z,1338754312.447 [Default:GPS:D] Running Loop=1
2012-06-03T20:11:52.847Z,1338754312.847 [Default:GPS:D] Stopped
2012-06-03T20:11:52.847Z,1338754312.847 [Default:GPS](INFO): Completed Default:GPS
2012-06-03T20:11:52.848Z,1338754312.848 [Default:GPS] Stopped
2012-06-03T20:11:52.848Z,1338754312.848 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-06-03T20:11:52.848Z,1338754312.848 [Default:GPS:A.SetSpeed] Stopped
2012-06-03T20:11:52.848Z,1338754312.848 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-06-03T20:12:08.399Z,1338754328.399 [NAL9601](INFO): Powering down
2012-06-03T20:16:13.408Z,1338754573.408 [Default:CallIridium] Running Loop=1
2012-06-03T20:16:13.409Z,1338754573.409 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-06-03T20:16:13.409Z,1338754573.409 [Default:CallIridium:A] Running Loop=1
2012-06-03T20:16:13.409Z,1338754573.409 [Default:CallIridium:A] Stopped
2012-06-03T20:16:13.409Z,1338754573.409 [Default:CallIridium:B] Running Loop=1
2012-06-03T20:16:13.409Z,1338754573.409 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-06-03T20:16:18.492Z,1338754578.492 [Default:Iridium] Running Loop=1
2012-06-03T20:16:18.492Z,1338754578.492 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-06-03T20:16:18.492Z,1338754578.492 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-06-03T20:16:18.492Z,1338754578.492 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-06-03T20:16:18.492Z,1338754578.492 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-06-03T20:16:18.492Z,1338754578.492 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-03T20:16:18.493Z,1338754578.493 [Default:Iridium:B.GoToSurface] Stopped
2012-06-03T20:16:18.493Z,1338754578.493 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-03T20:16:18.493Z,1338754578.493 [Default:Iridium:Read_Iridium] Running Loop=1
2012-06-03T20:16:18.494Z,1338754578.494 [Default:GPS] Running Loop=1
2012-06-03T20:16:18.506Z,1338754578.506 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-06-03T20:16:18.506Z,1338754578.506 [Default:GPS:A.SetSpeed] Running Loop=1
2012-06-03T20:16:18.506Z,1338754578.506 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-06-03T20:16:18.506Z,1338754578.506 [Default:GPS:B.GoToSurface] Running Loop=1
2012-06-03T20:16:18.506Z,1338754578.506 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-03T20:16:18.507Z,1338754578.507 [Default:GPS:B.GoToSurface] Stopped
2012-06-03T20:16:18.507Z,1338754578.507 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-03T20:16:18.508Z,1338754578.508 [Default:GPS:Read_GPS] Running Loop=1
2012-06-03T20:16:19.088Z,1338754579.088 [NAL9601](INFO): Powering up
2012-06-03T20:16:49.789Z,1338754609.789 [Radio_Freewave](INFO): Powering down
2012-06-03T20:16:51.393Z,1338754611.393 [Radio_Freewave](INFO): Powering up
2012-06-03T20:17:24.714Z,1338754644.714 [NAL9601](INFO): NAL9601 initialized
2012-06-03T20:17:40.125Z,1338754660.125 [NAL9601](INFO): SBD MO Status=1, MOMSN=8832, MT Status=0, MTMSN=0
2012-06-03T20:17:40.278Z,1338754660.279 [NAL9601](INFO): Sent 332 bytes from file Logs/20120603T200613/shore0000.lzma
2012-06-03T20:17:40.279Z,1338754660.279 [NAL9601](INFO): Packets left to send: 3
2012-06-03T20:17:40.280Z,1338754660.280 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000628
2012-06-03T20:17:50.221Z,1338754670.221 [NAL9601](INFO): SBD MO Status=1, MOMSN=8833, MT Status=0, MTMSN=0
2012-06-03T20:17:50.363Z,1338754670.362 [NAL9601](INFO): Sent 332 bytes from file Logs/20120603T200613/shore0000.lzma
2012-06-03T20:17:50.363Z,1338754670.363 [NAL9601](INFO): Packets left to send: 2
2012-06-03T20:17:50.364Z,1338754670.364 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000629
2012-06-03T20:17:59.521Z,1338754679.521 [NAL9601](INFO): SBD MO Status=1, MOMSN=8834, MT Status=0, MTMSN=0
2012-06-03T20:17:59.646Z,1338754679.646 [NAL9601](INFO): Sent 332 bytes from file Logs/20120603T200613/shore0000.lzma
2012-06-03T20:17:59.647Z,1338754679.647 [NAL9601](INFO): Packets left to send: 1
2012-06-03T20:17:59.648Z,1338754679.648 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000630
2012-06-03T20:18:06.444Z,1338754686.444 [NAL9601](INFO): SBD MO Status=1, MOMSN=8835, MT Status=0, MTMSN=0
2012-06-03T20:18:06.634Z,1338754686.634 [NAL9601](INFO): Sent 160 bytes from file Logs/20120603T200613/shore0000.lzma
2012-06-03T20:18:06.635Z,1338754686.635 [NAL9601](INFO): Packets left to send: 0
2012-06-03T20:18:06.636Z,1338754686.636 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000631
2012-06-03T20:18:10.527Z,1338754690.527 [NAL9601](INFO): SBD MO Status=0, MOMSN=8836, MT Status=0, MTMSN=0
2012-06-03T20:18:10.745Z,1338754690.745 [Default:Iridium:Read_Iridium] Stopped
2012-06-03T20:18:10.745Z,1338754690.745 [Default:Iridium](INFO): Completed Default:Iridium
2012-06-03T20:18:10.746Z,1338754690.746 [Default:Iridium] Stopped
2012-06-03T20:18:10.746Z,1338754690.746 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-06-03T20:18:10.746Z,1338754690.746 [Default:Iridium:A.SetSpeed] Stopped
2012-06-03T20:18:10.746Z,1338754690.746 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-06-03T20:18:10.862Z,1338754690.862 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2012-06-03T20:18:10.862Z,1338754690.862 [Default:CallIridium:B] Stopped
2012-06-03T20:18:10.863Z,1338754690.862 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-06-03T20:18:10.863Z,1338754690.863 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-06-03T20:18:10.863Z,1338754690.863 [Default:CallIridium] Stopped
2012-06-03T20:18:10.863Z,1338754690.863 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-06-03T20:18:47.647Z,1338754727.647 [NAL9601](IMPORTANT): GPS fix at: 1338755181.00
2012-06-03T20:18:47.679Z,1338754727.679 [Default:GPS:Read_GPS] Stopped
2012-06-03T20:18:47.679Z,1338754727.680 [Default:GPS:D] Running Loop=1
2012-06-03T20:18:48.106Z,1338754728.106 [Default:GPS:D] Stopped
2012-06-03T20:18:48.106Z,1338754728.106 [Default:GPS](INFO): Completed Default:GPS
2012-06-03T20:18:48.106Z,1338754728.106 [Default:GPS] Stopped
2012-06-03T20:18:48.106Z,1338754728.106 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-06-03T20:18:48.106Z,1338754728.106 [Default:GPS:A.SetSpeed] Stopped
2012-06-03T20:18:48.107Z,1338754728.107 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-06-03T20:19:03.638Z,1338754743.638 [NAL9601](INFO): Powering down
2012-06-03T20:23:13.615Z,1338754993.615 [Default:CallIridium] Running Loop=1
2012-06-03T20:23:13.615Z,1338754993.615 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-06-03T20:23:13.615Z,1338754993.615 [Default:CallIridium:A] Running Loop=1
2012-06-03T20:23:13.615Z,1338754993.615 [Default:CallIridium:A] Stopped
2012-06-03T20:23:13.615Z,1338754993.615 [Default:CallIridium:B] Running Loop=1
2012-06-03T20:23:13.616Z,1338754993.616 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-06-03T20:23:18.618Z,1338754998.618 [Default:Iridium] Running Loop=1
2012-06-03T20:23:18.618Z,1338754998.618 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-06-03T20:23:18.618Z,1338754998.618 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-06-03T20:23:18.618Z,1338754998.618 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-06-03T20:23:18.618Z,1338754998.618 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-06-03T20:23:18.618Z,1338754998.618 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-03T20:23:18.619Z,1338754998.619 [Default:Iridium:B.GoToSurface] Stopped
2012-06-03T20:23:18.620Z,1338754998.620 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-03T20:23:18.620Z,1338754998.620 [Default:Iridium:Read_Iridium] Running Loop=1
2012-06-03T20:23:18.620Z,1338754998.620 [Default:GPS] Running Loop=1
2012-06-03T20:23:18.620Z,1338754998.620 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-06-03T20:23:18.620Z,1338754998.620 [Default:GPS:A.SetSpeed] Running Loop=1
2012-06-03T20:23:18.620Z,1338754998.620 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-06-03T20:23:18.621Z,1338754998.621 [Default:GPS:B.GoToSurface] Running Loop=1
2012-06-03T20:23:18.621Z,1338754998.621 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-03T20:23:18.622Z,1338754998.622 [Default:GPS:B.GoToSurface] Stopped
2012-06-03T20:23:18.622Z,1338754998.622 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-03T20:23:18.622Z,1338754998.622 [Default:GPS:Read_GPS] Running Loop=1
2012-06-03T20:23:19.248Z,1338754999.248 [NAL9601](INFO): Powering up
2012-06-03T20:24:24.970Z,1338755064.970 [NAL9601](INFO): NAL9601 initialized
2012-06-03T20:24:26.066Z,1338755066.066 [NAL9601](IMPORTANT): GPS fix at: 1338755520.00
2012-06-03T20:24:26.081Z,1338755066.081 [Default:GPS:Read_GPS] Stopped
2012-06-03T20:24:26.082Z,1338755066.082 [Default:GPS:D] Running Loop=1
2012-06-03T20:24:26.464Z,1338755066.464 [Default:GPS:D] Stopped
2012-06-03T20:24:26.464Z,1338755066.464 [Default:GPS](INFO): Completed Default:GPS
2012-06-03T20:24:26.464Z,1338755066.464 [Default:GPS] Stopped
2012-06-03T20:24:26.465Z,1338755066.464 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-06-03T20:24:26.465Z,1338755066.465 [Default:GPS:A.SetSpeed] Stopped
2012-06-03T20:24:26.465Z,1338755066.465 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-06-03T20:24:46.414Z,1338755086.414 [CBIT](INFO): Clearing failed count for component RudderServo
2012-06-03T20:24:46.414Z,1338755086.414 [RudderServo] No Fault, FailCount= 3
2012-06-03T20:24:47.212Z,1338755087.212 [RudderServo](DEBUG): Initializing EZServoServo.
2012-06-03T20:24:47.335Z,1338755087.335 [RudderServo](DEBUG): Initializing RudderServo.
2012-06-03T20:24:52.411Z,1338755092.411 [NAL9601](INFO): SBD MO Status=1, MOMSN=8837, MT Status=0, MTMSN=0
2012-06-03T20:24:52.618Z,1338755092.618 [NAL9601](INFO): Sent 212 bytes from file Logs/20120603T200613/shore0001.lzma
2012-06-03T20:24:52.619Z,1338755092.619 [NAL9601](INFO): Packets left to send: 0
2012-06-03T20:24:52.620Z,1338755092.620 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000632
2012-06-03T20:24:59.197Z,1338755099.197 [NAL9601](INFO): SBD MO Status=0, MOMSN=8838, MT Status=0, MTMSN=0
2012-06-03T20:24:59.421Z,1338755099.421 [Default:Iridium:Read_Iridium] Stopped
2012-06-03T20:24:59.421Z,1338755099.421 [Default:Iridium](INFO): Completed Default:Iridium
2012-06-03T20:24:59.421Z,1338755099.421 [Default:Iridium] Stopped
2012-06-03T20:24:59.422Z,1338755099.422 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-06-03T20:24:59.422Z,1338755099.422 [Default:Iridium:A.SetSpeed] Stopped
2012-06-03T20:24:59.422Z,1338755099.422 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-06-03T20:24:59.605Z,1338755099.605 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2012-06-03T20:24:59.605Z,1338755099.605 [Default:CallIridium:B] Stopped
2012-06-03T20:24:59.606Z,1338755099.606 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-06-03T20:24:59.606Z,1338755099.606 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-06-03T20:24:59.606Z,1338755099.606 [Default:CallIridium] Stopped
2012-06-03T20:24:59.606Z,1338755099.606 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-06-03T20:25:09.774Z,1338755109.774 [NAL9601](INFO): Powering down
2012-06-03T20:27:39.779Z,1338755259.779 [Radio_Freewave](INFO): Powering down
2012-06-03T20:27:44.731Z,1338755264.731 [Radio_Freewave](INFO): Powering up
2012-06-03T20:29:59.774Z,1338755399.773 [Default:CallIridium] Running Loop=1
2012-06-03T20:29:59.774Z,1338755399.774 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-06-03T20:29:59.774Z,1338755399.774 [Default:CallIridium:A] Running Loop=1
2012-06-03T20:29:59.775Z,1338755399.774 [Default:CallIridium:A] Stopped
2012-06-03T20:29:59.775Z,1338755399.775 [Default:CallIridium:B] Running Loop=1
2012-06-03T20:29:59.775Z,1338755399.775 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-06-03T20:30:04.769Z,1338755404.769 [Default:Iridium] Running Loop=1
2012-06-03T20:30:04.769Z,1338755404.769 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-06-03T20:30:04.769Z,1338755404.769 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-06-03T20:30:04.769Z,1338755404.769 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-06-03T20:30:04.769Z,1338755404.769 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-06-03T20:30:04.770Z,1338755404.770 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-03T20:30:04.771Z,1338755404.771 [Default:Iridium:B.GoToSurface] Stopped
2012-06-03T20:30:04.771Z,1338755404.771 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-03T20:30:04.771Z,1338755404.771 [Default:Iridium:Read_Iridium] Running Loop=1
2012-06-03T20:30:04.771Z,1338755404.771 [Default:GPS] Running Loop=1
2012-06-03T20:30:04.771Z,1338755404.771 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-06-03T20:30:04.771Z,1338755404.771 [Default:GPS:A.SetSpeed] Running Loop=1
2012-06-03T20:30:04.771Z,1338755404.772 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-06-03T20:30:04.772Z,1338755404.772 [Default:GPS:B.GoToSurface] Running Loop=1
2012-06-03T20:30:04.772Z,1338755404.772 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-03T20:30:04.773Z,1338755404.773 [Default:GPS:B.GoToSurface] Stopped
2012-06-03T20:30:04.773Z,1338755404.773 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-03T20:30:04.773Z,1338755404.773 [Default:GPS:Read_GPS] Running Loop=1
2012-06-03T20:30:05.396Z,1338755405.396 [NAL9601](INFO): Powering up
2012-06-03T20:31:11.122Z,1338755471.122 [NAL9601](INFO): NAL9601 initialized
2012-06-03T20:31:12.199Z,1338755472.199 [NAL9601](IMPORTANT): GPS fix at: 1338755927.00
2012-06-03T20:31:12.215Z,1338755472.215 [Default:GPS:Read_GPS] Stopped
2012-06-03T20:31:12.215Z,1338755472.215 [Default:GPS:D] Running Loop=1
2012-06-03T20:31:12.631Z,1338755472.631 [Default:GPS:D] Stopped
2012-06-03T20:31:12.632Z,1338755472.632 [Default:GPS](INFO): Completed Default:GPS
2012-06-03T20:31:12.632Z,1338755472.632 [Default:GPS] Stopped
2012-06-03T20:31:12.632Z,1338755472.632 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-06-03T20:31:12.632Z,1338755472.632 [Default:GPS:A.SetSpeed] Stopped
2012-06-03T20:31:12.632Z,1338755472.632 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-06-03T20:31:46.899Z,1338755506.899 [NAL9601](IMPORTANT): SBD MO Status=1, MOMSN=8839, MT Status=1, MTMSN=2693
2012-06-03T20:31:47.050Z,1338755507.050 [NAL9601](INFO): Sent 176 bytes from file Logs/20120603T200613/shore0002.lzma
2012-06-03T20:31:47.051Z,1338755507.051 [NAL9601](INFO): Packets left to send: 0
2012-06-03T20:31:47.052Z,1338755507.052 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000633
2012-06-03T20:31:47.483Z,1338755507.483 [NAL9601](INFO): Received command:run Maintenance/calibrateSparton2DWithPitch.xml
2012-06-03T20:31:47.514Z,1338755507.514 [CommandLine](IMPORTANT): got command run ./Missions/Maintenance/calibrateSparton2DWithPitch.xml
2012-06-03T20:31:47.515Z,1338755507.515 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/calibrateSparton2DWithPitch.xml
2012-06-03T20:31:47.698Z,1338755507.698 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.MassDefault = -0.006270 n/a
2012-06-03T20:31:47.700Z,1338755507.700 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.Depth = 20.000000 m
2012-06-03T20:31:47.707Z,1338755507.707 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.Speed = 1.000000 m/s
2012-06-03T20:31:47.709Z,1338755507.709 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.WaitDuration = 20.000000 s
2012-06-03T20:31:47.715Z,1338755507.716 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.RudderAngle = 7.000000 arcdeg
2012-06-03T20:31:47.718Z,1338755507.718 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.MagNorthHeading = 15.000000 arcdeg
2012-06-03T20:31:47.728Z,1338755507.728 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.MinAltitude = 7.000000 m
2012-06-03T20:31:47.735Z,1338755507.735 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.MaxDepth = 35.000000 m
2012-06-03T20:31:47.737Z,1338755507.737 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.MinWaterDepth = 15.000000 m
2012-06-03T20:31:47.743Z,1338755507.743 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.MinOffshore = 2000.000000 m
2012-06-03T20:31:47.746Z,1338755507.746 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.NeedCommsTime = 30.000000 min
2012-06-03T20:31:47.752Z,1338755507.752 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.ManualLoops = 1.000000 count
2012-06-03T20:31:47.759Z,1338755507.759 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.AutoLoops = 2.000000 count
2012-06-03T20:31:47.761Z,1338755507.761 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.CheckLoops = 2.000000 count
2012-06-03T20:31:47.768Z,1338755507.768 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.BuoyancyNeutral = 0.000400 n/a
2012-06-03T20:31:47.774Z,1338755507.774 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.TimeoutDuration = 70.000000 min
2012-06-03T20:31:47.776Z,1338755507.776 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.YoYoMinDepth = 15.000000 m
2012-06-03T20:31:47.783Z,1338755507.783 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.YoYoMaxDepth = 30.000000 m
2012-06-03T20:31:47.785Z,1338755507.785 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.YoYoUpPitch = 30.000000 arcdeg
2012-06-03T20:31:47.799Z,1338755507.799 [MissionManager](INFO): DefineArg calibrateSparton2DWithPitch.YoYoDownPitch = -30.000000 arcdeg
2012-06-03T20:31:47.800Z,1338755507.800 [calibrateSparton2DWithPitch:A.AltitudeEnvelope](DEBUG): Construct AltitudeEnvelope.
2012-06-03T20:31:47.809Z,1338755507.809 [calibrateSparton2DWithPitch:B.DepthEnvelope](DEBUG): Construct DepthEnvelope.
2012-06-03T20:31:47.823Z,1338755507.823 [calibrateSparton2DWithPitch:C.WaterDepthEnvelope](DEBUG): Construct WaterDepthEnvelope.
2012-06-03T20:31:47.832Z,1338755507.832 [calibrateSparton2DWithPitch:D.OffshoreEnvelope](DEBUG): Construct OffshoreEnvelope.
2012-06-03T20:31:47.842Z,1338755507.842 [calibrateSparton2DWithPitch:F.Buoyancy](DEBUG): Construct Buoyancy.
2012-06-03T20:31:47.848Z,1338755507.848 [calibrateSparton2DWithPitch:G.SetSpeed](DEBUG): Construct.
2012-06-03T20:31:47.855Z,1338755507.855 [calibrateSparton2DWithPitch:H.Pitch](DEBUG): Construct.
2012-06-03T20:31:47.864Z,1338755507.864 [calibrateSparton2DWithPitch:MassOff:A.Pitch](DEBUG): Construct.
2012-06-03T20:31:47.873Z,1338755507.873 [calibrateSparton2DWithPitch:MassOff:Initialization:A.Pitch](DEBUG): Construct.
2012-06-03T20:31:47.881Z,1338755507.881 [calibrateSparton2DWithPitch:MassOff:Initialization:B.Point](DEBUG): Construct.
2012-06-03T20:31:47.893Z,1338755507.893 [calibrateSparton2DWithPitch:MassOff:Initialization:C.Wait](DEBUG): Construct Wait.
2012-06-03T20:31:47.895Z,1338755507.895 [calibrateSparton2DWithPitch:MassOff:PreCircles:A.Point](DEBUG): Construct.
2012-06-03T20:31:47.900Z,1338755507.900 [calibrateSparton2DWithPitch:MassOff:PreCircles:B.Pitch](DEBUG): Construct.
2012-06-03T20:31:47.904Z,1338755507.904 [calibrateSparton2DWithPitch:MassOff:PreCircles:C.Wait](DEBUG): Construct Wait.
2012-06-03T20:31:47.905Z,1338755507.905 [calibrateSparton2DWithPitch:MassOff:PreCircles:D.Point](DEBUG): Construct.
2012-06-03T20:31:47.910Z,1338755507.911 [calibrateSparton2DWithPitch:MassOff:Calibration:Manual_Loop:A.Point](DEBUG): Construct.
2012-06-03T20:31:47.915Z,1338755507.915 [calibrateSparton2DWithPitch:MassOff:Calibration:Manual_Loop:B.Pitch](DEBUG): Construct.
2012-06-03T20:31:47.918Z,1338755507.918 [calibrateSparton2DWithPitch:MassOff:Calibration:Manual_Loop:C.Wait](DEBUG): Construct Wait.
2012-06-03T20:31:47.920Z,1338755507.920 [calibrateSparton2DWithPitch:MassOff:Calibration:Manual_Loop:D.Point](DEBUG): Construct.
2012-06-03T20:31:47.924Z,1338755507.924 [calibrateSparton2DWithPitch:MassOff:Calibration:B.Wait](DEBUG): Construct Wait.
2012-06-03T20:31:47.926Z,1338755507.926 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:A.Point](DEBUG): Construct.
2012-06-03T20:31:47.930Z,1338755507.931 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:PitchDown.Pitch](DEBUG): Construct.
2012-06-03T20:31:47.936Z,1338755507.936 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:PitchUp.Pitch](DEBUG): Construct.
2012-06-03T20:31:47.941Z,1338755507.941 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:D.Wait](DEBUG): Construct Wait.
2012-06-03T20:31:47.942Z,1338755507.942 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:E.Point](DEBUG): Construct.
2012-06-03T20:31:47.947Z,1338755507.947 [calibrateSparton2DWithPitch:MassOff:Calibration:D.CalibrateSparton](DEBUG): Construct CalibrateSparton.
2012-06-03T20:31:47.948Z,1338755507.948 [calibrateSparton2DWithPitch:MassOff:PostCircles:A.Point](DEBUG): Construct.
2012-06-03T20:31:47.952Z,1338755507.952 [calibrateSparton2DWithPitch:MassOff:PostCircles:PitchDown.Pitch](DEBUG): Construct.
2012-06-03T20:31:47.957Z,1338755507.957 [calibrateSparton2DWithPitch:MassOff:PostCircles:PitchUp.Pitch](DEBUG): Construct.
2012-06-03T20:31:47.964Z,1338755507.964 [calibrateSparton2DWithPitch:MassOff:PostCircles:D.Wait](DEBUG): Construct Wait.
2012-06-03T20:31:47.965Z,1338755507.965 [calibrateSparton2DWithPitch:MassOff:PostCircles:E.Point](DEBUG): Construct.
2012-06-03T20:31:47.970Z,1338755507.970 [calibrateSparton2DWithPitch:PostCirclesMass:A.Point](DEBUG): Construct.
2012-06-03T20:31:47.974Z,1338755507.974 [calibrateSparton2DWithPitch:PostCirclesMass:PitchDown.Pitch](DEBUG): Construct.
2012-06-03T20:31:47.979Z,1338755507.979 [calibrateSparton2DWithPitch:PostCirclesMass:PitchUp.Pitch](DEBUG): Construct.
2012-06-03T20:31:47.984Z,1338755507.984 [calibrateSparton2DWithPitch:PostCirclesMass:D.Wait](DEBUG): Construct Wait.
2012-06-03T20:31:47.986Z,1338755507.986 [calibrateSparton2DWithPitch:PostCirclesMass:E.Point](DEBUG): Construct.
2012-06-03T20:31:48.037Z,1338755508.037 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="utf-8"?>
<Mission xmlns="Tethys" 
         xmlns:Control="Tethys/Control" 
         xmlns:Guidance="Tethys/Guidance" 
         xmlns:Sensor="Tethys/Sensor"
         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/Sensor http://aosn.mbari.org/tethys/Xml/Sensor.xsd
                             Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
                             Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
         Id="calibrateSparton2DWithPitch">

    <DefineArg Name="MassDefault"><Control:VerticalControl.massDefault/></DefineArg>

    <DefineArg Name="Depth"><Units:meter /><Value>20.0</Value></DefineArg>

    <DefineArg Name="Speed"><Units:meter_per_second /><Value>1</Value></DefineArg>

    <DefineArg Name="WaitDuration"><Units:second /><Value>20</Value></DefineArg>

    <DefineArg Name="RudderAngle"><Units:degree /><Value>7</Value></DefineArg>

    <DefineArg Name="MagNorthHeading"><Units:degree /><Value>15</Value></DefineArg>

    <DefineArg Name="MinAltitude"><Units:meter/><Value>7</Value></DefineArg>

    <DefineArg Name="MaxDepth"><Units:meter/><Value>35</Value></DefineArg>

    <DefineArg Name="MinWaterDepth"><Units:meter/><Value>15</Value></DefineArg>

    <DefineArg Name="MinOffshore"><Units:meter/><Value>2000</Value></DefineArg>

    <DefineArg Name="NeedCommsTime"><Units:minute/><Value>30</Value></DefineArg>

    <DefineArg Name="ManualLoops"><Units:count/><Value>1</Value></DefineArg>

    <DefineArg Name="AutoLoops"><Units:count/><Value>2</Value></DefineArg>

    <DefineArg Name="CheckLoops"><Units:count/><Value>2</Value></DefineArg>

    <DefineArg Name="BuoyancyNeutral"><Control:VerticalControl.buoyancyNeutral/></DefineArg>

    <DefineArg Name="TimeoutDuration"><Units:minute/><Value>70</Value></DefineArg>

    <DefineArg Name="YoYoMinDepth"><Units:meter/><Value>15.0</Value></DefineArg>

    <DefineArg Name="YoYoMaxDepth"><Units:meter/><Value>30.0</Value></DefineArg>

    <DefineArg Name="YoYoUpPitch"><Units:degree/><Value>30.0</Value></DefineArg>

    <DefineArg Name="YoYoDownPitch"><Units:degree/><Value>-30.0</Value></DefineArg>

    <Timeout Duration="TimeoutDuration"/>

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

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

    <Guidance:WaterDepthEnvelope RunIn="Parallel">
        <Setting><Guidance:WaterDepthEnvelope.minWaterDepth/><Arg Name="MinWaterDepth"/></Setting>
    </Guidance:WaterDepthEnvelope>

    <Guidance:OffshoreEnvelope RunIn="Parallel">
        <Setting><Guidance:OffshoreEnvelope.minOffshore/><Arg Name="MinOffshore"/></Setting>
    </Guidance:OffshoreEnvelope>

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

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

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

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

    <Aggregate Id="MassOff">

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

        <Aggregate Id="Initialization">

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

            <Guidance:Point RunIn="Progression">
                <Setting><Guidance:Point.heading /><Arg Name="MagNorthHeading" /></Setting>
            </Guidance:Point>

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

        </Aggregate>

        <Aggregate Id="PreCircles" Repeat = "CheckLoops">

            <Guidance:Point RunIn="Parallel">
                <Setting><Guidance:Point.rudderAngle /><Arg Name="RudderAngle" /></Setting>
            </Guidance:Point>

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

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

            <Guidance:Point RunIn="Sequence">
                <Setting><Guidance:Point.heading /><Arg Name="MagNorthHeading" /></Setting>
            </Guidance:Point>

        </Aggregate>

        <Aggregate Id="Calibration">

            <Aggregate Id="Manual_Loop" Repeat="ManualLoops">

                <Guidance:Point RunIn="Parallel">
                    <Setting><Guidance:Point.rudderAngle /><Arg Name="RudderAngle" /></Setting>
                </Guidance:Point>

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

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

                <Guidance:Point RunIn="Sequence">
                    <Setting><Guidance:Point.heading /><Arg Name="MagNorthHeading" /></Setting>
                </Guidance:Point>

            </Aggregate>

            <Guidance:Wait RunIn="Sequence">
                <Preemptive><True/></Preemptive>
                <Setting><Guidance:Wait.duration /><Units:second/><Value>0</Value></Setting>
            </Guidance:Wait>

            <Aggregate Id="Auto_Loop" Repeat="AutoLoops">

                <Guidance:Point RunIn="Parallel">
                    <Setting><Guidance:Point.rudderAngle /><Arg Name="RudderAngle" /></Setting>
                </Guidance:Point>

                <Guidance:Pitch Id="PitchDown" RunIn="Parallel">
                    <Until><Universal:platform_pitch_angle/>
                        <Le><Arg Name="YoYoDownPitch"/></Le>
                    </Until>
                    <When><Universal:platform_pitch_angle/>
                        <Ge><Arg Name="YoYoUpPitch"/></Ge>
                    </When>
                    <Setting><Guidance:Pitch.pitch/><Arg Name="YoYoDownPitch"/></Setting>
                </Guidance:Pitch>

                <Guidance:Pitch Id="PitchUp" RunIn="Parallel">
                    <Until><Universal:platform_pitch_angle/>
                        <Ge><Arg Name="YoYoUpPitch"/></Ge>
                    </Until>
                    <When><Universal:platform_pitch_angle/>
                        <Le><Arg Name="YoYoDownPitch"/></Le>
                    </When>
                    <Setting><Guidance:Pitch.pitch/><Arg Name="YoYoUpPitch"/></Setting>
                </Guidance:Pitch>

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

                <Guidance:Point RunIn="Sequence">
                    <Setting><Guidance:Point.heading /><Arg Name="MagNorthHeading" /></Setting>
                </Guidance:Point>

            </Aggregate>

            <Sensor:CalibrateSparton RunIn="Parallel"></Sensor:CalibrateSparton>

        </Aggregate>

        <Aggregate Id="PostCircles" Repeat= "CheckLoops">

            <Guidance:Point RunIn="Parallel">
                <Setting><Guidance:Point.rudderAngle /><Arg Name="RudderAngle" /></Setting>
            </Guidance:Point>

            <Guidance:Pitch Id="PitchDown" RunIn="Parallel">
                <Until><Universal:platform_pitch_angle/>
                    <Le><Arg Name="YoYoDownPitch"/></Le>
                </Until>
                <When><Universal:platform_pitch_angle/>
                    <Ge><Arg Name="YoYoUpPitch"/></Ge>
                </When>
                <Setting><Guidance:Pitch.pitch/><Arg Name="YoYoDownPitch"/></Setting>
            </Guidance:Pitch>

            <Guidance:Pitch Id="PitchUp" RunIn="Parallel">
                <Until><Universal:platform_pitch_angle/>
                    <Ge><Arg Name="YoYoUpPitch"/></Ge>
                </Until>
                <When><Universal:platform_pitch_angle/>
                    <Le><Arg Name="YoYoDownPitch"/></Le>
                </When>
                <Setting><Guidance:Pitch.pitch/><Arg Name="YoYoUpPitch"/></Setting>
            </Guidance:Pitch>

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

            <Guidance:Point RunIn="Sequence">
                <Setting><Guidance:Point.heading /><Arg Name="MagNorthHeading" /></Setting>
            </Guidance:Point>

        </Aggregate>

    </Aggregate>

    <Aggregate Id="PostCirclesMass" Repeat= "CheckLoops">

        <Guidance:Point RunIn="Parallel">
            <Setting><Guidance:Point.rudderAngle /><Arg Name="RudderAngle" /></Setting>
        </Guidance:Point>

        <Guidance:Pitch Id="PitchDown" RunIn="Parallel">
            <Until><Universal:platform_pitch_angle/>
                <Le><Arg Name="YoYoDownPitch"/></Le>
            </Until>
            <When><Universal:platform_pitch_angle/>
                <Ge><Arg Name="YoYoUpPitch"/></Ge>
            </When>
            <Setting><Guidance:Pitch.pitch/><Arg Name="YoYoDownPitch"/></Setting>
        </Guidance:Pitch>

        <Guidance:Pitch Id="PitchUp" RunIn="Parallel">
            <Until><Universal:platform_pitch_angle/>
                <Ge><Arg Name="YoYoUpPitch"/></Ge>
            </Until>
            <When><Universal:platform_pitch_angle/>
                <Le><Arg Name="YoYoDownPitch"/></Le>
            </When>
            <Setting><Guidance:Pitch.pitch/><Arg Name="YoYoUpPitch"/></Setting>
        </Guidance:Pitch>

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

        <Guidance:Point RunIn="Sequence">
            <Setting><Guidance:Point.heading /><Arg Name="MagNorthHeading" /></Setting>
        </Guidance:Point>

    </Aggregate>

</Mission>


2012-06-03T20:31:48.064Z,1338755508.063 [CommandLine](IMPORTANT): Running ./Missions/Maintenance/calibrateSparton2DWithPitch.xml
2012-06-03T20:31:48.093Z,1338755508.093 [Default] Stopped
2012-06-03T20:31:48.093Z,1338755508.093 [Default](INFO): Aggregate::uninitialize Default
2012-06-03T20:31:48.093Z,1338755508.093 [Default:Iridium] Stopped
2012-06-03T20:31:48.093Z,1338755508.093 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-06-03T20:31:48.094Z,1338755508.093 [Default:Iridium:A.SetSpeed] Stopped
2012-06-03T20:31:48.094Z,1338755508.093 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-06-03T20:31:48.094Z,1338755508.094 [Default:Iridium:Read_Iridium] Stopped
2012-06-03T20:31:48.094Z,1338755508.094 [Default:CallIridium] Stopped
2012-06-03T20:31:48.094Z,1338755508.094 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-06-03T20:31:48.094Z,1338755508.094 [Default:CallIridium:B] Stopped
2012-06-03T20:31:48.094Z,1338755508.094 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-06-03T20:31:48.094Z,1338755508.094 [Default:D.SetSpeed] Stopped
2012-06-03T20:31:48.094Z,1338755508.094 [Default:D.SetSpeed](DEBUG): Uninitialize.
2012-06-03T20:31:48.094Z,1338755508.094 [Default:E.GoToSurface] Stopped
2012-06-03T20:31:48.094Z,1338755508.095 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-03T20:31:48.095Z,1338755508.095 [Default:F.Wait] Stopped
2012-06-03T20:31:48.095Z,1338755508.095 [Default:F.Wait](DEBUG): Uninitialize Wait Component.
2012-06-03T20:31:48.095Z,1338755508.095 [MissionManager](IMPORTANT): Started mission calibrateSparton2DWithPitch
2012-06-03T20:31:48.095Z,1338755508.095 [calibrateSparton2DWithPitch] Running Loop=1
2012-06-03T20:31:48.095Z,1338755508.095 [calibrateSparton2DWithPitch](INFO): Aggregate::initialize calibrateSparton2DWithPitch
2012-06-03T20:31:48.095Z,1338755508.095 [calibrateSparton2DWithPitch:A.AltitudeEnvelope] Running Loop=1
2012-06-03T20:31:48.095Z,1338755508.095 [calibrateSparton2DWithPitch:A.AltitudeEnvelope](DEBUG): Initialize AltitudeEnvelopeComponent.
2012-06-03T20:31:48.095Z,1338755508.095 [calibrateSparton2DWithPitch:B.DepthEnvelope] Running Loop=1
2012-06-03T20:31:48.095Z,1338755508.095 [calibrateSparton2DWithPitch:B.DepthEnvelope](DEBUG): Initialize DepthEnvelopeComponent.
2012-06-03T20:31:48.096Z,1338755508.096 [calibrateSparton2DWithPitch:C.WaterDepthEnvelope] Running Loop=1
2012-06-03T20:31:48.096Z,1338755508.096 [calibrateSparton2DWithPitch:C.WaterDepthEnvelope](DEBUG): Initialize WaterDepthEnvelopeComponent.
2012-06-03T20:31:48.096Z,1338755508.096 [calibrateSparton2DWithPitch:D.OffshoreEnvelope] Running Loop=1
2012-06-03T20:31:48.096Z,1338755508.096 [calibrateSparton2DWithPitch:D.OffshoreEnvelope](DEBUG): Initialize OffshoreEnvelopeComponent.
2012-06-03T20:31:48.096Z,1338755508.096 [calibrateSparton2DWithPitch:E] Running Loop=1
2012-06-03T20:31:48.097Z,1338755508.096 [calibrateSparton2DWithPitch:F.Buoyancy] Running Loop=1
2012-06-03T20:31:48.097Z,1338755508.096 [calibrateSparton2DWithPitch:F.Buoyancy](DEBUG): Initialize Buoyancy Component.
2012-06-03T20:31:48.097Z,1338755508.097 [calibrateSparton2DWithPitch:G.SetSpeed] Running Loop=1
2012-06-03T20:31:48.097Z,1338755508.097 [calibrateSparton2DWithPitch:G.SetSpeed](DEBUG): Initialize.
2012-06-03T20:31:48.097Z,1338755508.097 [calibrateSparton2DWithPitch:H.Pitch] Running Loop=1
2012-06-03T20:31:48.097Z,1338755508.097 [calibrateSparton2DWithPitch:H.Pitch](DEBUG): Initialize.
2012-06-03T20:31:48.098Z,1338755508.098 [calibrateSparton2DWithPitch:H.Pitch] Running Loop=1
2012-06-03T20:31:48.099Z,1338755508.099 [calibrateSparton2DWithPitch:G.SetSpeed] Running Loop=1
2012-06-03T20:31:48.101Z,1338755508.101 [calibrateSparton2DWithPitch:E] Running Loop=1
2012-06-03T20:31:48.101Z,1338755508.101 [calibrateSparton2DWithPitch:E](DEBUG): Initialize ReadDataComponent to sense platform_orientation
2012-06-03T20:31:48.102Z,1338755508.102 [calibrateSparton2DWithPitch:D.OffshoreEnvelope] Running Loop=1
2012-06-03T20:31:48.103Z,1338755508.103 [calibrateSparton2DWithPitch:C.WaterDepthEnvelope] Running Loop=1
2012-06-03T20:31:48.104Z,1338755508.104 [calibrateSparton2DWithPitch:B.DepthEnvelope] Running Loop=1
2012-06-03T20:31:48.105Z,1338755508.105 [calibrateSparton2DWithPitch:A.AltitudeEnvelope] Running Loop=1
2012-06-03T20:31:54.754Z,1338755514.754 [NAL9601](INFO): SBD MO Status=0, MOMSN=8840, MT Status=0, MTMSN=0
2012-06-03T20:32:00.785Z,1338755520.785 [NAL9601](INFO): Powering down
2012-06-03T20:32:19.968Z,1338755539.968 [Radio_Freewave](INFO): Powering down
2012-06-03T20:32:21.958Z,1338755541.958 [Radio_Freewave](INFO): Powering up
2012-06-03T20:32:31.565Z,1338755551.565 [Radio_Freewave](INFO): Powering down
2012-06-03T20:32:35.949Z,1338755555.949 [Radio_Freewave](INFO): Powering up
2012-06-03T20:34:37.617Z,1338755677.617 [calibrateSparton2DWithPitch:MassOff] Running Loop=1
2012-06-03T20:34:37.617Z,1338755677.617 [calibrateSparton2DWithPitch:MassOff](INFO): Aggregate::initialize calibrateSparton2DWithPitch:MassOff
2012-06-03T20:34:37.617Z,1338755677.617 [calibrateSparton2DWithPitch:MassOff:A.Pitch] Running Loop=1
2012-06-03T20:34:37.617Z,1338755677.617 [calibrateSparton2DWithPitch:MassOff:A.Pitch](DEBUG): Initialize.
2012-06-03T20:34:37.618Z,1338755677.618 [calibrateSparton2DWithPitch:MassOff:Initialization] Running Loop=1
2012-06-03T20:34:37.618Z,1338755677.618 [calibrateSparton2DWithPitch:MassOff:Initialization](INFO): Aggregate::initialize calibrateSparton2DWithPitch:MassOff:Initialization
2012-06-03T20:34:37.618Z,1338755677.618 [calibrateSparton2DWithPitch:MassOff:Initialization:A.Pitch] Running Loop=1
2012-06-03T20:34:37.618Z,1338755677.618 [calibrateSparton2DWithPitch:MassOff:Initialization:A.Pitch](DEBUG): Initialize.
2012-06-03T20:34:37.618Z,1338755677.618 [calibrateSparton2DWithPitch:MassOff:Initialization:B.Point] Running Loop=1
2012-06-03T20:34:37.618Z,1338755677.618 [calibrateSparton2DWithPitch:MassOff:Initialization:B.Point](DEBUG): Initialize.
2012-06-03T20:34:37.955Z,1338755677.955 [calibrateSparton2DWithPitch:MassOff:A.Pitch] Running Loop=1
2012-06-03T20:35:14.428Z,1338755714.428 [Radio_Freewave](INFO): Powering down
2012-06-03T20:38:54.041Z,1338755934.041 [calibrateSparton2DWithPitch:MassOff:Initialization:C.Wait] Running Loop=1
2012-06-03T20:38:54.041Z,1338755934.041 [calibrateSparton2DWithPitch:MassOff:Initialization:C.Wait](DEBUG): Initialize Wait Component.
2012-06-03T20:39:14.393Z,1338755954.393 [calibrateSparton2DWithPitch:MassOff:Initialization:C.Wait](INFO): Done Waiting.
2012-06-03T20:39:14.394Z,1338755954.394 [calibrateSparton2DWithPitch:MassOff:Initialization:C.Wait] Stopped
2012-06-03T20:39:14.394Z,1338755954.394 [calibrateSparton2DWithPitch:MassOff:Initialization:C.Wait](DEBUG): Uninitialize Wait Component.
2012-06-03T20:39:14.395Z,1338755954.395 [calibrateSparton2DWithPitch:MassOff:Initialization](INFO): Completed calibrateSparton2DWithPitch:MassOff:Initialization
2012-06-03T20:39:14.395Z,1338755954.395 [calibrateSparton2DWithPitch:MassOff:Initialization] Stopped
2012-06-03T20:39:14.395Z,1338755954.395 [calibrateSparton2DWithPitch:MassOff:Initialization](INFO): Aggregate::uninitialize calibrateSparton2DWithPitch:MassOff:Initialization
2012-06-03T20:39:14.395Z,1338755954.395 [calibrateSparton2DWithPitch:MassOff:Initialization:A.Pitch] Stopped
2012-06-03T20:39:14.395Z,1338755954.395 [calibrateSparton2DWithPitch:MassOff:Initialization:B.Point] Stopped
2012-06-03T20:39:14.396Z,1338755954.396 [calibrateSparton2DWithPitch:MassOff:PreCircles] Running Loop=1
2012-06-03T20:39:14.396Z,1338755954.396 [calibrateSparton2DWithPitch:MassOff:PreCircles](INFO): Aggregate::initialize calibrateSparton2DWithPitch:MassOff:PreCircles
2012-06-03T20:39:14.396Z,1338755954.396 [calibrateSparton2DWithPitch:MassOff:PreCircles:A.Point] Running Loop=1
2012-06-03T20:39:14.396Z,1338755954.396 [calibrateSparton2DWithPitch:MassOff:PreCircles:A.Point](DEBUG): Initialize.
2012-06-03T20:39:14.396Z,1338755954.396 [calibrateSparton2DWithPitch:MassOff:PreCircles:B.Pitch] Running Loop=1
2012-06-03T20:39:14.396Z,1338755954.396 [calibrateSparton2DWithPitch:MassOff:PreCircles:B.Pitch](DEBUG): Initialize.
2012-06-03T20:39:14.396Z,1338755954.397 [calibrateSparton2DWithPitch:MassOff:PreCircles:C.Wait] Running Loop=1
2012-06-03T20:39:14.396Z,1338755954.397 [calibrateSparton2DWithPitch:MassOff:PreCircles:C.Wait](DEBUG): Initialize Wait Component.
2012-06-03T20:39:14.874Z,1338755954.874 [calibrateSparton2DWithPitch:MassOff:PreCircles:B.Pitch] Running Loop=1
2012-06-03T20:39:14.879Z,1338755954.879 [calibrateSparton2DWithPitch:MassOff:PreCircles:A.Point] Running Loop=1
2012-06-03T20:39:35.190Z,1338755975.190 [calibrateSparton2DWithPitch:MassOff:PreCircles:C.Wait](INFO): Done Waiting.
2012-06-03T20:39:35.190Z,1338755975.190 [calibrateSparton2DWithPitch:MassOff:PreCircles:C.Wait] Stopped
2012-06-03T20:39:35.191Z,1338755975.191 [calibrateSparton2DWithPitch:MassOff:PreCircles:C.Wait](DEBUG): Uninitialize Wait Component.
2012-06-03T20:39:35.191Z,1338755975.191 [calibrateSparton2DWithPitch:MassOff:PreCircles:D.Point] Running Loop=1
2012-06-03T20:39:35.191Z,1338755975.191 [calibrateSparton2DWithPitch:MassOff:PreCircles:D.Point](DEBUG): Initialize.
2012-06-03T20:41:49.230Z,1338756109.230 [calibrateSparton2DWithPitch:MassOff:PreCircles:D.Point] Stopped
2012-06-03T20:41:49.231Z,1338756109.231 [calibrateSparton2DWithPitch:MassOff:PreCircles](INFO): Completed calibrateSparton2DWithPitch:MassOff:PreCircles
2012-06-03T20:41:49.231Z,1338756109.231 [calibrateSparton2DWithPitch:MassOff:PreCircles] Stopped
2012-06-03T20:41:49.231Z,1338756109.231 [calibrateSparton2DWithPitch:MassOff:PreCircles](INFO): Aggregate::uninitialize calibrateSparton2DWithPitch:MassOff:PreCircles
2012-06-03T20:41:49.231Z,1338756109.231 [calibrateSparton2DWithPitch:MassOff:PreCircles:A.Point] Stopped
2012-06-03T20:41:49.231Z,1338756109.231 [calibrateSparton2DWithPitch:MassOff:PreCircles:B.Pitch] Stopped
2012-06-03T20:41:49.231Z,1338756109.231 [calibrateSparton2DWithPitch:MassOff:PreCircles](INFO): Running loop #2
2012-06-03T20:41:49.231Z,1338756109.231 [calibrateSparton2DWithPitch:MassOff:PreCircles] Running Loop=2
2012-06-03T20:41:49.232Z,1338756109.232 [calibrateSparton2DWithPitch:MassOff:PreCircles](INFO): Aggregate::initialize calibrateSparton2DWithPitch:MassOff:PreCircles
2012-06-03T20:41:49.232Z,1338756109.232 [calibrateSparton2DWithPitch:MassOff:PreCircles:A.Point] Running Loop=1
2012-06-03T20:41:49.232Z,1338756109.232 [calibrateSparton2DWithPitch:MassOff:PreCircles:A.Point](DEBUG): Initialize.
2012-06-03T20:41:49.232Z,1338756109.232 [calibrateSparton2DWithPitch:MassOff:PreCircles:B.Pitch] Running Loop=1
2012-06-03T20:41:49.232Z,1338756109.232 [calibrateSparton2DWithPitch:MassOff:PreCircles:B.Pitch](DEBUG): Initialize.
2012-06-03T20:41:49.232Z,1338756109.232 [calibrateSparton2DWithPitch:MassOff:PreCircles:C.Wait] Running Loop=1
2012-06-03T20:41:49.232Z,1338756109.232 [calibrateSparton2DWithPitch:MassOff:PreCircles:C.Wait](DEBUG): Initialize Wait Component.
2012-06-03T20:42:09.661Z,1338756129.661 [calibrateSparton2DWithPitch:MassOff:PreCircles:C.Wait](INFO): Done Waiting.
2012-06-03T20:42:09.661Z,1338756129.661 [calibrateSparton2DWithPitch:MassOff:PreCircles:C.Wait] Stopped
2012-06-03T20:42:09.661Z,1338756129.661 [calibrateSparton2DWithPitch:MassOff:PreCircles:C.Wait](DEBUG): Uninitialize Wait Component.
2012-06-03T20:42:09.661Z,1338756129.661 [calibrateSparton2DWithPitch:MassOff:PreCircles:D.Point] Running Loop=1
2012-06-03T20:42:09.661Z,1338756129.661 [calibrateSparton2DWithPitch:MassOff:PreCircles:D.Point](DEBUG): Initialize.
2012-06-03T20:44:21.260Z,1338756261.260 [calibrateSparton2DWithPitch:MassOff:PreCircles:D.Point] Stopped
2012-06-03T20:44:21.261Z,1338756261.261 [calibrateSparton2DWithPitch:MassOff:PreCircles](INFO): Completed calibrateSparton2DWithPitch:MassOff:PreCircles
2012-06-03T20:44:21.261Z,1338756261.261 [calibrateSparton2DWithPitch:MassOff:PreCircles] Stopped
2012-06-03T20:44:21.261Z,1338756261.261 [calibrateSparton2DWithPitch:MassOff:PreCircles](INFO): Aggregate::uninitialize calibrateSparton2DWithPitch:MassOff:PreCircles
2012-06-03T20:44:21.261Z,1338756261.261 [calibrateSparton2DWithPitch:MassOff:PreCircles:A.Point] Stopped
2012-06-03T20:44:21.261Z,1338756261.261 [calibrateSparton2DWithPitch:MassOff:PreCircles:B.Pitch] Stopped
2012-06-03T20:44:21.261Z,1338756261.261 [calibrateSparton2DWithPitch:MassOff:Calibration] Running Loop=1
2012-06-03T20:44:21.261Z,1338756261.261 [calibrateSparton2DWithPitch:MassOff:Calibration](INFO): Aggregate::initialize calibrateSparton2DWithPitch:MassOff:Calibration
2012-06-03T20:44:21.261Z,1338756261.261 [calibrateSparton2DWithPitch:MassOff:Calibration:D.CalibrateSparton] Running Loop=1
2012-06-03T20:44:21.261Z,1338756261.261 [calibrateSparton2DWithPitch:MassOff:Calibration:D.CalibrateSparton](INFO): Initialize CalibrateSpartonComponent.
2012-06-03T20:44:21.328Z,1338756261.328 [calibrateSparton2DWithPitch:MassOff:Calibration:Manual_Loop] Running Loop=1
2012-06-03T20:44:21.328Z,1338756261.328 [calibrateSparton2DWithPitch:MassOff:Calibration:Manual_Loop](INFO): Aggregate::initialize calibrateSparton2DWithPitch:MassOff:Calibration:Manual_Loop
2012-06-03T20:44:21.328Z,1338756261.328 [calibrateSparton2DWithPitch:MassOff:Calibration:Manual_Loop:A.Point] Running Loop=1
2012-06-03T20:44:21.328Z,1338756261.328 [calibrateSparton2DWithPitch:MassOff:Calibration:Manual_Loop:A.Point](DEBUG): Initialize.
2012-06-03T20:44:21.328Z,1338756261.328 [calibrateSparton2DWithPitch:MassOff:Calibration:Manual_Loop:B.Pitch] Running Loop=1
2012-06-03T20:44:21.328Z,1338756261.328 [calibrateSparton2DWithPitch:MassOff:Calibration:Manual_Loop:B.Pitch](DEBUG): Initialize.
2012-06-03T20:44:21.329Z,1338756261.329 [calibrateSparton2DWithPitch:MassOff:Calibration:Manual_Loop:C.Wait] Running Loop=1
2012-06-03T20:44:21.329Z,1338756261.329 [calibrateSparton2DWithPitch:MassOff:Calibration:Manual_Loop:C.Wait](DEBUG): Initialize Wait Component.
2012-06-03T20:44:21.622Z,1338756261.622 [calibrateSparton2DWithPitch:MassOff:Calibration:D.CalibrateSparton] Running Loop=1
2012-06-03T20:44:21.632Z,1338756261.632 [calibrateSparton2DWithPitch:MassOff:Calibration:Manual_Loop:B.Pitch] Running Loop=1
2012-06-03T20:44:21.637Z,1338756261.637 [calibrateSparton2DWithPitch:MassOff:Calibration:Manual_Loop:A.Point] Running Loop=1
2012-06-03T20:44:41.638Z,1338756281.638 [calibrateSparton2DWithPitch:MassOff:Calibration:Manual_Loop:C.Wait](INFO): Done Waiting.
2012-06-03T20:44:41.638Z,1338756281.638 [calibrateSparton2DWithPitch:MassOff:Calibration:Manual_Loop:C.Wait] Stopped
2012-06-03T20:44:41.638Z,1338756281.638 [calibrateSparton2DWithPitch:MassOff:Calibration:Manual_Loop:C.Wait](DEBUG): Uninitialize Wait Component.
2012-06-03T20:44:41.639Z,1338756281.639 [calibrateSparton2DWithPitch:MassOff:Calibration:Manual_Loop:D.Point] Running Loop=1
2012-06-03T20:44:41.639Z,1338756281.639 [calibrateSparton2DWithPitch:MassOff:Calibration:Manual_Loop:D.Point](DEBUG): Initialize.
2012-06-03T20:46:52.468Z,1338756412.468 [calibrateSparton2DWithPitch:MassOff:Calibration:Manual_Loop:D.Point] Stopped
2012-06-03T20:46:52.470Z,1338756412.470 [calibrateSparton2DWithPitch:MassOff:Calibration:Manual_Loop](INFO): Completed calibrateSparton2DWithPitch:MassOff:Calibration:Manual_Loop
2012-06-03T20:46:52.470Z,1338756412.470 [calibrateSparton2DWithPitch:MassOff:Calibration:Manual_Loop] Stopped
2012-06-03T20:46:52.470Z,1338756412.470 [calibrateSparton2DWithPitch:MassOff:Calibration:Manual_Loop](INFO): Aggregate::uninitialize calibrateSparton2DWithPitch:MassOff:Calibration:Manual_Loop
2012-06-03T20:46:52.470Z,1338756412.470 [calibrateSparton2DWithPitch:MassOff:Calibration:Manual_Loop:A.Point] Stopped
2012-06-03T20:46:52.470Z,1338756412.470 [calibrateSparton2DWithPitch:MassOff:Calibration:Manual_Loop:B.Pitch] Stopped
2012-06-03T20:46:52.470Z,1338756412.470 [calibrateSparton2DWithPitch:MassOff:Calibration:B.Wait] Running Loop=1
2012-06-03T20:46:52.470Z,1338756412.470 [calibrateSparton2DWithPitch:MassOff:Calibration:B.Wait](DEBUG): Initialize Wait Component.
2012-06-03T20:46:52.853Z,1338756412.853 [calibrateSparton2DWithPitch:MassOff:Calibration:D.CalibrateSparton] Preempted
2012-06-03T20:46:52.853Z,1338756412.853 [calibrateSparton2DWithPitch:MassOff:Calibration:D.CalibrateSparton](INFO): Preempted CalibrateSpartonComponent.
2012-06-03T20:46:52.887Z,1338756412.887 [calibrateSparton2DWithPitch:MassOff:Calibration:B.Wait](INFO): Done Waiting.
2012-06-03T20:46:52.891Z,1338756412.891 [calibrateSparton2DWithPitch:MassOff:Calibration:B.Wait] Stopped
2012-06-03T20:46:52.891Z,1338756412.891 [calibrateSparton2DWithPitch:MassOff:Calibration:B.Wait](DEBUG): Uninitialize Wait Component.
2012-06-03T20:46:52.891Z,1338756412.891 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop] Running Loop=1
2012-06-03T20:46:52.892Z,1338756412.892 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop](INFO): Aggregate::initialize calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop
2012-06-03T20:46:52.892Z,1338756412.892 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:A.Point] Running Loop=1
2012-06-03T20:46:52.892Z,1338756412.892 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:A.Point](DEBUG): Initialize.
2012-06-03T20:46:52.892Z,1338756412.892 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:PitchDown.Pitch] Running Loop=1
2012-06-03T20:46:52.892Z,1338756412.892 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:PitchDown.Pitch](DEBUG): Initialize.
2012-06-03T20:46:52.892Z,1338756412.892 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:PitchUp.Pitch] Running Loop=1
2012-06-03T20:46:52.892Z,1338756412.892 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:PitchUp.Pitch](DEBUG): Initialize.
2012-06-03T20:46:52.892Z,1338756412.892 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:D.Wait] Running Loop=1
2012-06-03T20:46:52.893Z,1338756412.893 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:D.Wait](DEBUG): Initialize Wait Component.
2012-06-03T20:46:53.268Z,1338756413.268 [calibrateSparton2DWithPitch:MassOff:Calibration:D.CalibrateSparton] Running Loop=1
2012-06-03T20:46:53.273Z,1338756413.273 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:PitchUp.Pitch] Running Loop=1
2012-06-03T20:46:53.278Z,1338756413.278 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:PitchDown.Pitch] Running Loop=1
2012-06-03T20:46:53.283Z,1338756413.283 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:A.Point] Running Loop=1
2012-06-03T20:47:13.671Z,1338756433.671 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:D.Wait](INFO): Done Waiting.
2012-06-03T20:47:13.671Z,1338756433.671 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:D.Wait] Stopped
2012-06-03T20:47:13.671Z,1338756433.671 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:D.Wait](DEBUG): Uninitialize Wait Component.
2012-06-03T20:47:13.671Z,1338756433.671 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:E.Point] Running Loop=1
2012-06-03T20:47:13.671Z,1338756433.671 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:E.Point](DEBUG): Initialize.
2012-06-03T20:49:21.760Z,1338756561.760 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:E.Point] Stopped
2012-06-03T20:49:21.761Z,1338756561.761 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop](INFO): Completed calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop
2012-06-03T20:49:21.761Z,1338756561.761 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop] Stopped
2012-06-03T20:49:21.761Z,1338756561.761 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop](INFO): Aggregate::uninitialize calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop
2012-06-03T20:49:21.761Z,1338756561.761 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:A.Point] Stopped
2012-06-03T20:49:21.761Z,1338756561.761 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:PitchDown.Pitch] Stopped
2012-06-03T20:49:21.761Z,1338756561.761 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:PitchUp.Pitch] Stopped
2012-06-03T20:49:21.762Z,1338756561.761 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop](INFO): Running loop #2
2012-06-03T20:49:21.762Z,1338756561.762 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop] Running Loop=2
2012-06-03T20:49:21.762Z,1338756561.762 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop](INFO): Aggregate::initialize calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop
2012-06-03T20:49:21.766Z,1338756561.766 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:A.Point] Running Loop=1
2012-06-03T20:49:21.766Z,1338756561.766 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:A.Point](DEBUG): Initialize.
2012-06-03T20:49:21.766Z,1338756561.766 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:PitchDown.Pitch] Running Loop=1
2012-06-03T20:49:21.767Z,1338756561.767 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:PitchDown.Pitch](DEBUG): Initialize.
2012-06-03T20:49:21.767Z,1338756561.767 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:PitchUp.Pitch] Running Loop=1
2012-06-03T20:49:21.767Z,1338756561.767 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:PitchUp.Pitch](DEBUG): Initialize.
2012-06-03T20:49:21.767Z,1338756561.767 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:D.Wait] Running Loop=1
2012-06-03T20:49:21.767Z,1338756561.767 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:D.Wait](DEBUG): Initialize Wait Component.
2012-06-03T20:49:42.163Z,1338756582.163 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:D.Wait](INFO): Done Waiting.
2012-06-03T20:49:42.163Z,1338756582.163 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:D.Wait] Stopped
2012-06-03T20:49:42.163Z,1338756582.163 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:D.Wait](DEBUG): Uninitialize Wait Component.
2012-06-03T20:49:42.164Z,1338756582.164 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:E.Point] Running Loop=1
2012-06-03T20:49:42.164Z,1338756582.164 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:E.Point](DEBUG): Initialize.
2012-06-03T20:51:50.436Z,1338756710.436 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:E.Point] Stopped
2012-06-03T20:51:50.437Z,1338756710.437 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop](INFO): Completed calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop
2012-06-03T20:51:50.437Z,1338756710.437 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop] Stopped
2012-06-03T20:51:50.437Z,1338756710.437 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop](INFO): Aggregate::uninitialize calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop
2012-06-03T20:51:50.438Z,1338756710.438 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:A.Point] Stopped
2012-06-03T20:51:50.438Z,1338756710.438 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:PitchDown.Pitch] Stopped
2012-06-03T20:51:50.438Z,1338756710.438 [calibrateSparton2DWithPitch:MassOff:Calibration:Auto_Loop:PitchUp.Pitch] Stopped
2012-06-03T20:51:50.438Z,1338756710.438 [calibrateSparton2DWithPitch:MassOff:Calibration](INFO): Completed calibrateSparton2DWithPitch:MassOff:Calibration
2012-06-03T20:51:50.438Z,1338756710.438 [calibrateSparton2DWithPitch:MassOff:Calibration] Stopped
2012-06-03T20:51:50.438Z,1338756710.438 [calibrateSparton2DWithPitch:MassOff:Calibration](INFO): Aggregate::uninitialize calibrateSparton2DWithPitch:MassOff:Calibration
2012-06-03T20:51:50.438Z,1338756710.438 [calibrateSparton2DWithPitch:MassOff:Calibration:D.CalibrateSparton] Stopped
2012-06-03T20:51:50.438Z,1338756710.438 [calibrateSparton2DWithPitch:MassOff:Calibration:D.CalibrateSparton](INFO): Uninitialize CalibrateSpartonComponent.
2012-06-03T20:51:50.485Z,1338756710.485 [AHRS_sp3003D](IMPORTANT): Magnetic calibration quality (0[best] to 10000) is 24
2012-06-03T20:51:50.485Z,1338756710.485 [calibrateSparton2DWithPitch:MassOff:PostCircles] Running Loop=1
2012-06-03T20:51:50.486Z,1338756710.486 [calibrateSparton2DWithPitch:MassOff:PostCircles](INFO): Aggregate::initialize calibrateSparton2DWithPitch:MassOff:PostCircles
2012-06-03T20:51:50.486Z,1338756710.486 [calibrateSparton2DWithPitch:MassOff:PostCircles:A.Point] Running Loop=1
2012-06-03T20:51:50.486Z,1338756710.486 [calibrateSparton2DWithPitch:MassOff:PostCircles:A.Point](DEBUG): Initialize.
2012-06-03T20:51:50.486Z,1338756710.486 [calibrateSparton2DWithPitch:MassOff:PostCircles:PitchDown.Pitch] Running Loop=1
2012-06-03T20:51:50.486Z,1338756710.486 [calibrateSparton2DWithPitch:MassOff:PostCircles:PitchDown.Pitch](DEBUG): Initialize.
2012-06-03T20:51:50.486Z,1338756710.486 [calibrateSparton2DWithPitch:MassOff:PostCircles:PitchUp.Pitch] Running Loop=1
2012-06-03T20:51:50.486Z,1338756710.487 [calibrateSparton2DWithPitch:MassOff:PostCircles:PitchUp.Pitch](DEBUG): Initialize.
2012-06-03T20:51:50.487Z,1338756710.487 [calibrateSparton2DWithPitch:MassOff:PostCircles:D.Wait] Running Loop=1
2012-06-03T20:51:50.487Z,1338756710.487 [calibrateSparton2DWithPitch:MassOff:PostCircles:D.Wait](DEBUG): Initialize Wait Component.
2012-06-03T20:51:50.848Z,1338756710.848 [calibrateSparton2DWithPitch:MassOff:PostCircles:PitchUp.Pitch] Running Loop=1
2012-06-03T20:51:50.856Z,1338756710.856 [calibrateSparton2DWithPitch:MassOff:PostCircles:PitchDown.Pitch] Running Loop=1
2012-06-03T20:51:50.861Z,1338756710.861 [calibrateSparton2DWithPitch:MassOff:PostCircles:A.Point] Running Loop=1
2012-06-03T20:52:10.916Z,1338756730.916 [calibrateSparton2DWithPitch:MassOff:PostCircles:D.Wait](INFO): Done Waiting.
2012-06-03T20:52:10.916Z,1338756730.916 [calibrateSparton2DWithPitch:MassOff:PostCircles:D.Wait] Stopped
2012-06-03T20:52:10.916Z,1338756730.916 [calibrateSparton2DWithPitch:MassOff:PostCircles:D.Wait](DEBUG): Uninitialize Wait Component.
2012-06-03T20:52:10.916Z,1338756730.916 [calibrateSparton2DWithPitch:MassOff:PostCircles:E.Point] Running Loop=1
2012-06-03T20:52:10.916Z,1338756730.917 [calibrateSparton2DWithPitch:MassOff:PostCircles:E.Point](DEBUG): Initialize.
2012-06-03T20:54:17.182Z,1338756857.182 [calibrateSparton2DWithPitch:MassOff:PostCircles:E.Point] Stopped
2012-06-03T20:54:17.183Z,1338756857.183 [calibrateSparton2DWithPitch:MassOff:PostCircles](INFO): Completed calibrateSparton2DWithPitch:MassOff:PostCircles
2012-06-03T20:54:17.184Z,1338756857.184 [calibrateSparton2DWithPitch:MassOff:PostCircles] Stopped
2012-06-03T20:54:17.184Z,1338756857.184 [calibrateSparton2DWithPitch:MassOff:PostCircles](INFO): Aggregate::uninitialize calibrateSparton2DWithPitch:MassOff:PostCircles
2012-06-03T20:54:17.184Z,1338756857.184 [calibrateSparton2DWithPitch:MassOff:PostCircles:A.Point] Stopped
2012-06-03T20:54:17.184Z,1338756857.184 [calibrateSparton2DWithPitch:MassOff:PostCircles:PitchDown.Pitch] Stopped
2012-06-03T20:54:17.184Z,1338756857.184 [calibrateSparton2DWithPitch:MassOff:PostCircles:PitchUp.Pitch] Stopped
2012-06-03T20:54:17.184Z,1338756857.184 [calibrateSparton2DWithPitch:MassOff:PostCircles](INFO): Running loop #2
2012-06-03T20:54:17.184Z,1338756857.184 [calibrateSparton2DWithPitch:MassOff:PostCircles] Running Loop=2
2012-06-03T20:54:17.184Z,1338756857.184 [calibrateSparton2DWithPitch:MassOff:PostCircles](INFO): Aggregate::initialize calibrateSparton2DWithPitch:MassOff:PostCircles
2012-06-03T20:54:17.184Z,1338756857.184 [calibrateSparton2DWithPitch:MassOff:PostCircles:A.Point] Running Loop=1
2012-06-03T20:54:17.184Z,1338756857.184 [calibrateSparton2DWithPitch:MassOff:PostCircles:A.Point](DEBUG): Initialize.
2012-06-03T20:54:17.185Z,1338756857.185 [calibrateSparton2DWithPitch:MassOff:PostCircles:PitchDown.Pitch] Running Loop=1
2012-06-03T20:54:17.185Z,1338756857.185 [calibrateSparton2DWithPitch:MassOff:PostCircles:PitchDown.Pitch](DEBUG): Initialize.
2012-06-03T20:54:17.185Z,1338756857.185 [calibrateSparton2DWithPitch:MassOff:PostCircles:PitchUp.Pitch] Running Loop=1
2012-06-03T20:54:17.185Z,1338756857.185 [calibrateSparton2DWithPitch:MassOff:PostCircles:PitchUp.Pitch](DEBUG): Initialize.
2012-06-03T20:54:17.185Z,1338756857.185 [calibrateSparton2DWithPitch:MassOff:PostCircles:D.Wait] Running Loop=1
2012-06-03T20:54:17.185Z,1338756857.185 [calibrateSparton2DWithPitch:MassOff:PostCircles:D.Wait](DEBUG): Initialize Wait Component.
2012-06-03T20:54:37.946Z,1338756877.946 [calibrateSparton2DWithPitch:MassOff:PostCircles:D.Wait](INFO): Done Waiting.
2012-06-03T20:54:37.947Z,1338756877.947 [calibrateSparton2DWithPitch:MassOff:PostCircles:D.Wait] Stopped
2012-06-03T20:54:37.947Z,1338756877.947 [calibrateSparton2DWithPitch:MassOff:PostCircles:D.Wait](DEBUG): Uninitialize Wait Component.
2012-06-03T20:54:37.947Z,1338756877.947 [calibrateSparton2DWithPitch:MassOff:PostCircles:E.Point] Running Loop=1
2012-06-03T20:54:37.947Z,1338756877.947 [calibrateSparton2DWithPitch:MassOff:PostCircles:E.Point](DEBUG): Initialize.
2012-06-03T20:56:48.142Z,1338757008.142 [calibrateSparton2DWithPitch:MassOff:PostCircles:E.Point] Stopped
2012-06-03T20:56:48.143Z,1338757008.143 [calibrateSparton2DWithPitch:MassOff:PostCircles](INFO): Completed calibrateSparton2DWithPitch:MassOff:PostCircles
2012-06-03T20:56:48.143Z,1338757008.143 [calibrateSparton2DWithPitch:MassOff:PostCircles] Stopped
2012-06-03T20:56:48.143Z,1338757008.143 [calibrateSparton2DWithPitch:MassOff:PostCircles](INFO): Aggregate::uninitialize calibrateSparton2DWithPitch:MassOff:PostCircles
2012-06-03T20:56:48.143Z,1338757008.143 [calibrateSparton2DWithPitch:MassOff:PostCircles:A.Point] Stopped
2012-06-03T20:56:48.143Z,1338757008.143 [calibrateSparton2DWithPitch:MassOff:PostCircles:PitchDown.Pitch] Stopped
2012-06-03T20:56:48.143Z,1338757008.143 [calibrateSparton2DWithPitch:MassOff:PostCircles:PitchUp.Pitch] Stopped
2012-06-03T20:56:48.144Z,1338757008.144 [calibrateSparton2DWithPitch:MassOff](INFO): Completed calibrateSparton2DWithPitch:MassOff
2012-06-03T20:56:48.144Z,1338757008.144 [calibrateSparton2DWithPitch:MassOff] Stopped
2012-06-03T20:56:48.144Z,1338757008.144 [calibrateSparton2DWithPitch:MassOff](INFO): Aggregate::uninitialize calibrateSparton2DWithPitch:MassOff
2012-06-03T20:56:48.144Z,1338757008.144 [calibrateSparton2DWithPitch:MassOff:A.Pitch] Stopped
2012-06-03T20:56:48.144Z,1338757008.144 [calibrateSparton2DWithPitch:PostCirclesMass] Running Loop=1
2012-06-03T20:56:48.144Z,1338757008.144 [calibrateSparton2DWithPitch:PostCirclesMass](INFO): Aggregate::initialize calibrateSparton2DWithPitch:PostCirclesMass
2012-06-03T20:56:48.145Z,1338757008.145 [calibrateSparton2DWithPitch:PostCirclesMass:A.Point] Running Loop=1
2012-06-03T20:56:48.145Z,1338757008.145 [calibrateSparton2DWithPitch:PostCirclesMass:A.Point](DEBUG): Initialize.
2012-06-03T20:56:48.145Z,1338757008.145 [calibrateSparton2DWithPitch:PostCirclesMass:PitchDown.Pitch] Running Loop=1
2012-06-03T20:56:48.145Z,1338757008.145 [calibrateSparton2DWithPitch:PostCirclesMass:PitchDown.Pitch](DEBUG): Initialize.
2012-06-03T20:56:48.145Z,1338757008.145 [calibrateSparton2DWithPitch:PostCirclesMass:PitchUp.Pitch] Running Loop=1
2012-06-03T20:56:48.145Z,1338757008.145 [calibrateSparton2DWithPitch:PostCirclesMass:PitchUp.Pitch](DEBUG): Initialize.
2012-06-03T20:56:48.145Z,1338757008.145 [calibrateSparton2DWithPitch:PostCirclesMass:D.Wait] Running Loop=1
2012-06-03T20:56:48.145Z,1338757008.145 [calibrateSparton2DWithPitch:PostCirclesMass:D.Wait](DEBUG): Initialize Wait Component.
2012-06-03T20:56:48.498Z,1338757008.498 [calibrateSparton2DWithPitch:PostCirclesMass:PitchUp.Pitch] Running Loop=1
2012-06-03T20:56:48.503Z,1338757008.503 [calibrateSparton2DWithPitch:PostCirclesMass:PitchDown.Pitch] Running Loop=1
2012-06-03T20:56:48.508Z,1338757008.508 [calibrateSparton2DWithPitch:PostCirclesMass:A.Point] Running Loop=1
2012-06-03T20:57:08.806Z,1338757028.806 [calibrateSparton2DWithPitch:PostCirclesMass:D.Wait](INFO): Done Waiting.
2012-06-03T20:57:08.806Z,1338757028.806 [calibrateSparton2DWithPitch:PostCirclesMass:D.Wait] Stopped
2012-06-03T20:57:08.806Z,1338757028.806 [calibrateSparton2DWithPitch:PostCirclesMass:D.Wait](DEBUG): Uninitialize Wait Component.
2012-06-03T20:57:08.806Z,1338757028.806 [calibrateSparton2DWithPitch:PostCirclesMass:E.Point] Running Loop=1
2012-06-03T20:57:08.807Z,1338757028.806 [calibrateSparton2DWithPitch:PostCirclesMass:E.Point](DEBUG): Initialize.
2012-06-03T20:59:18.221Z,1338757158.221 [calibrateSparton2DWithPitch:PostCirclesMass:E.Point] Stopped
2012-06-03T20:59:18.222Z,1338757158.222 [calibrateSparton2DWithPitch:PostCirclesMass](INFO): Completed calibrateSparton2DWithPitch:PostCirclesMass
2012-06-03T20:59:18.222Z,1338757158.222 [calibrateSparton2DWithPitch:PostCirclesMass] Stopped
2012-06-03T20:59:18.222Z,1338757158.223 [calibrateSparton2DWithPitch:PostCirclesMass](INFO): Aggregate::uninitialize calibrateSparton2DWithPitch:PostCirclesMass
2012-06-03T20:59:18.222Z,1338757158.223 [calibrateSparton2DWithPitch:PostCirclesMass:A.Point] Stopped
2012-06-03T20:59:18.223Z,1338757158.223 [calibrateSparton2DWithPitch:PostCirclesMass:PitchDown.Pitch] Stopped
2012-06-03T20:59:18.223Z,1338757158.223 [calibrateSparton2DWithPitch:PostCirclesMass:PitchUp.Pitch] Stopped
2012-06-03T20:59:18.223Z,1338757158.223 [calibrateSparton2DWithPitch:PostCirclesMass](INFO): Running loop #2
2012-06-03T20:59:18.223Z,1338757158.223 [calibrateSparton2DWithPitch:PostCirclesMass] Running Loop=2
2012-06-03T20:59:18.223Z,1338757158.223 [calibrateSparton2DWithPitch:PostCirclesMass](INFO): Aggregate::initialize calibrateSparton2DWithPitch:PostCirclesMass
2012-06-03T20:59:18.223Z,1338757158.223 [calibrateSparton2DWithPitch:PostCirclesMass:A.Point] Running Loop=1
2012-06-03T20:59:18.223Z,1338757158.223 [calibrateSparton2DWithPitch:PostCirclesMass:A.Point](DEBUG): Initialize.
2012-06-03T20:59:18.223Z,1338757158.223 [calibrateSparton2DWithPitch:PostCirclesMass:PitchDown.Pitch] Running Loop=1
2012-06-03T20:59:18.223Z,1338757158.224 [calibrateSparton2DWithPitch:PostCirclesMass:PitchDown.Pitch](DEBUG): Initialize.
2012-06-03T20:59:18.224Z,1338757158.224 [calibrateSparton2DWithPitch:PostCirclesMass:PitchUp.Pitch] Running Loop=1
2012-06-03T20:59:18.224Z,1338757158.224 [calibrateSparton2DWithPitch:PostCirclesMass:PitchUp.Pitch](DEBUG): Initialize.
2012-06-03T20:59:18.224Z,1338757158.224 [calibrateSparton2DWithPitch:PostCirclesMass:D.Wait] Running Loop=1
2012-06-03T20:59:18.224Z,1338757158.224 [calibrateSparton2DWithPitch:PostCirclesMass:D.Wait](DEBUG): Initialize Wait Component.
2012-06-03T20:59:38.625Z,1338757178.625 [calibrateSparton2DWithPitch:PostCirclesMass:D.Wait](INFO): Done Waiting.
2012-06-03T20:59:38.626Z,1338757178.626 [calibrateSparton2DWithPitch:PostCirclesMass:D.Wait] Stopped
2012-06-03T20:59:38.626Z,1338757178.626 [calibrateSparton2DWithPitch:PostCirclesMass:D.Wait](DEBUG): Uninitialize Wait Component.
2012-06-03T20:59:38.626Z,1338757178.626 [calibrateSparton2DWithPitch:PostCirclesMass:E.Point] Running Loop=1
2012-06-03T20:59:38.626Z,1338757178.626 [calibrateSparton2DWithPitch:PostCirclesMass:E.Point](DEBUG): Initialize.
2012-06-03T21:01:47.417Z,1338757307.417 [calibrateSparton2DWithPitch:PostCirclesMass:E.Point] Stopped
2012-06-03T21:01:47.419Z,1338757307.419 [calibrateSparton2DWithPitch:PostCirclesMass](INFO): Completed calibrateSparton2DWithPitch:PostCirclesMass
2012-06-03T21:01:47.419Z,1338757307.419 [calibrateSparton2DWithPitch:PostCirclesMass] Stopped
2012-06-03T21:01:47.419Z,1338757307.419 [calibrateSparton2DWithPitch:PostCirclesMass](INFO): Aggregate::uninitialize calibrateSparton2DWithPitch:PostCirclesMass
2012-06-03T21:01:47.419Z,1338757307.419 [calibrateSparton2DWithPitch:PostCirclesMass:A.Point] Stopped
2012-06-03T21:01:47.419Z,1338757307.419 [calibrateSparton2DWithPitch:PostCirclesMass:PitchDown.Pitch] Stopped
2012-06-03T21:01:47.419Z,1338757307.419 [calibrateSparton2DWithPitch:PostCirclesMass:PitchUp.Pitch] Stopped
2012-06-03T21:01:47.422Z,1338757307.422 [calibrateSparton2DWithPitch](INFO): Completed calibrateSparton2DWithPitch
2012-06-03T21:01:47.422Z,1338757307.422 [calibrateSparton2DWithPitch] Stopped
2012-06-03T21:01:47.423Z,1338757307.423 [calibrateSparton2DWithPitch](INFO): Aggregate::uninitialize calibrateSparton2DWithPitch
2012-06-03T21:01:47.423Z,1338757307.423 [calibrateSparton2DWithPitch:A.AltitudeEnvelope] Stopped
2012-06-03T21:01:47.423Z,1338757307.423 [calibrateSparton2DWithPitch:A.AltitudeEnvelope](DEBUG): Uninitialize AltitudeEnvelopeComponent.
2012-06-03T21:01:47.423Z,1338757307.423 [calibrateSparton2DWithPitch:B.DepthEnvelope] Stopped
2012-06-03T21:01:47.423Z,1338757307.423 [calibrateSparton2DWithPitch:B.DepthEnvelope](DEBUG): Uninitialize.
2012-06-03T21:01:47.423Z,1338757307.423 [calibrateSparton2DWithPitch:C.WaterDepthEnvelope] Stopped
2012-06-03T21:01:47.423Z,1338757307.423 [calibrateSparton2DWithPitch:C.WaterDepthEnvelope](DEBUG): Uninitialize WaterDepthEnvelopeComponent.
2012-06-03T21:01:47.423Z,1338757307.423 [calibrateSparton2DWithPitch:D.OffshoreEnvelope] Stopped
2012-06-03T21:01:47.423Z,1338757307.423 [calibrateSparton2DWithPitch:D.OffshoreEnvelope](DEBUG): Uninitialize OffshoreEnvelopeComponent.
2012-06-03T21:01:47.423Z,1338757307.423 [calibrateSparton2DWithPitch:E] Stopped
2012-06-03T21:01:47.423Z,1338757307.423 [calibrateSparton2DWithPitch:F.Buoyancy] Stopped
2012-06-03T21:01:47.423Z,1338757307.423 [calibrateSparton2DWithPitch:F.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2012-06-03T21:01:47.423Z,1338757307.423 [calibrateSparton2DWithPitch:G.SetSpeed] Stopped
2012-06-03T21:01:47.423Z,1338757307.423 [calibrateSparton2DWithPitch:G.SetSpeed](DEBUG): Uninitialize.
2012-06-03T21:01:47.424Z,1338757307.424 [calibrateSparton2DWithPitch:H.Pitch] Stopped
2012-06-03T21:01:47.837Z,1338757307.837 [MissionManager](IMPORTANT): Started mission Default
2012-06-03T21:01:47.837Z,1338757307.837 [Default] Running Loop=1
2012-06-03T21:01:47.837Z,1338757307.837 [Default](INFO): Aggregate::initialize Default
2012-06-03T21:01:47.837Z,1338757307.837 [Default:D.SetSpeed] Running Loop=1
2012-06-03T21:01:47.837Z,1338757307.837 [Default:D.SetSpeed](DEBUG): Initialize.
2012-06-03T21:01:47.837Z,1338757307.837 [Default:E.GoToSurface] Running Loop=1
2012-06-03T21:01:47.837Z,1338757307.837 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-03T21:01:47.838Z,1338757307.838 [Default:Iridium] Running Loop=1
2012-06-03T21:01:47.838Z,1338757307.838 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-06-03T21:01:47.838Z,1338757307.838 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-06-03T21:01:47.838Z,1338757307.838 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-06-03T21:01:47.838Z,1338757307.838 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-06-03T21:01:47.838Z,1338757307.838 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-03T21:01:47.840Z,1338757307.840 [Default:CallIridium] Running Loop=1
2012-06-03T21:01:47.840Z,1338757307.840 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-06-03T21:01:47.840Z,1338757307.840 [Default:CallIridium:A] Running Loop=1
2012-06-03T21:01:47.840Z,1338757307.841 [Default:CallIridium:A] Stopped
2012-06-03T21:01:47.841Z,1338757307.841 [Default:CallIridium:B] Running Loop=1
2012-06-03T21:01:47.841Z,1338757307.841 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-06-03T21:01:48.219Z,1338757308.219 [Default:GPS] Running Loop=1
2012-06-03T21:01:48.219Z,1338757308.219 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-06-03T21:01:48.219Z,1338757308.219 [Default:GPS:A.SetSpeed] Running Loop=1
2012-06-03T21:01:48.219Z,1338757308.219 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-06-03T21:01:48.219Z,1338757308.219 [Default:GPS:B.GoToSurface] Running Loop=1
2012-06-03T21:01:48.219Z,1338757308.219 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-03T21:06:25.293Z,1338757585.293 [Radio_Freewave](INFO): Powering up
2012-06-03T21:06:25.303Z,1338757585.303 [Default:Iridium:B.GoToSurface] Stopped
2012-06-03T21:06:25.304Z,1338757585.304 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-03T21:06:25.304Z,1338757585.304 [Default:Iridium:Read_Iridium] Running Loop=1
2012-06-03T21:06:25.305Z,1338757585.305 [Default:GPS:B.GoToSurface] Stopped
2012-06-03T21:06:25.305Z,1338757585.305 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-03T21:06:25.305Z,1338757585.305 [Default:GPS:Read_GPS] Running Loop=1
2012-06-03T21:06:26.096Z,1338757586.096 [NAL9601](INFO): Powering up
2012-06-03T21:07:31.722Z,1338757651.722 [NAL9601](INFO): NAL9601 initialized
2012-06-03T21:07:32.820Z,1338757652.820 [NAL9601](IMPORTANT): GPS fix at: 1338758111.00
2012-06-03T21:07:32.834Z,1338757652.834 [Navigation](IMPORTANT): Navigation Error 2.1 %DT

2012-06-03T21:07:32.836Z,1338757652.836 [Default:GPS:Read_GPS] Stopped
2012-06-03T21:07:32.836Z,1338757652.836 [Default:GPS:D] Running Loop=1
2012-06-03T21:07:33.230Z,1338757653.230 [Default:GPS:D] Stopped
2012-06-03T21:07:33.231Z,1338757653.231 [Default:GPS](INFO): Completed Default:GPS
2012-06-03T21:07:33.231Z,1338757653.231 [Default:GPS] Stopped
2012-06-03T21:07:33.231Z,1338757653.231 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-06-03T21:07:33.231Z,1338757653.231 [Default:GPS:A.SetSpeed] Stopped
2012-06-03T21:07:33.231Z,1338757653.231 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-06-03T21:07:51.967Z,1338757671.967 [NAL9601](INFO): SBD MO Status=1, MOMSN=8841, MT Status=0, MTMSN=0
2012-06-03T21:07:52.178Z,1338757672.178 [NAL9601](INFO): Sent 279 bytes from file Logs/20120603T200613/shore0003.lzma
2012-06-03T21:07:52.179Z,1338757672.179 [NAL9601](INFO): Packets left to send: 0
2012-06-03T21:07:52.180Z,1338757672.180 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000634
2012-06-03T21:08:02.453Z,1338757682.453 [NAL9601](INFO): SBD MO Status=1, MOMSN=8842, MT Status=0, MTMSN=0
2012-06-03T21:08:02.666Z,1338757682.666 [NAL9601](INFO): Sent 332 bytes from file Logs/20120603T200613/shore0004.lzma
2012-06-03T21:08:02.667Z,1338757682.667 [NAL9601](INFO): Packets left to send: 4
2012-06-03T21:08:02.774Z,1338757682.774 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000635
2012-06-03T21:08:15.772Z,1338757695.772 [NAL9601](INFO): SBD MO Status=1, MOMSN=8843, MT Status=0, MTMSN=0
2012-06-03T21:08:15.938Z,1338757695.938 [NAL9601](INFO): Sent 332 bytes from file Logs/20120603T200613/shore0004.lzma
2012-06-03T21:08:15.939Z,1338757695.939 [NAL9601](INFO): Packets left to send: 3
2012-06-03T21:08:15.940Z,1338757695.940 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000636
2012-06-03T21:08:24.677Z,1338757704.677 [NAL9601](INFO): SBD MO Status=1, MOMSN=8844, MT Status=0, MTMSN=0
2012-06-03T21:08:24.826Z,1338757704.826 [NAL9601](INFO): Sent 332 bytes from file Logs/20120603T200613/shore0004.lzma
2012-06-03T21:08:24.827Z,1338757704.827 [NAL9601](INFO): Packets left to send: 2
2012-06-03T21:08:24.828Z,1338757704.828 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000637
2012-06-03T21:08:35.256Z,1338757715.256 [NAL9601](INFO): SBD MO Status=1, MOMSN=8845, MT Status=0, MTMSN=0
2012-06-03T21:08:35.410Z,1338757715.410 [NAL9601](INFO): Sent 332 bytes from file Logs/20120603T200613/shore0004.lzma
2012-06-03T21:08:35.411Z,1338757715.411 [NAL9601](INFO): Packets left to send: 1
2012-06-03T21:08:35.412Z,1338757715.412 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000638
2012-06-03T21:08:41.368Z,1338757721.368 [NAL9601](INFO): SBD MO Status=1, MOMSN=8846, MT Status=0, MTMSN=0
2012-06-03T21:08:41.498Z,1338757721.498 [NAL9601](INFO): Sent 15 bytes from file Logs/20120603T200613/shore0004.lzma
2012-06-03T21:08:41.499Z,1338757721.499 [NAL9601](INFO): Packets left to send: 0
2012-06-03T21:08:41.500Z,1338757721.500 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000639
2012-06-03T21:08:48.553Z,1338757728.553 [NAL9601](INFO): SBD MO Status=0, MOMSN=8847, MT Status=0, MTMSN=0
2012-06-03T21:08:48.705Z,1338757728.705 [Default:Iridium:Read_Iridium] Stopped
2012-06-03T21:08:48.705Z,1338757728.705 [Default:Iridium](INFO): Completed Default:Iridium
2012-06-03T21:08:48.706Z,1338757728.705 [Default:Iridium] Stopped
2012-06-03T21:08:48.706Z,1338757728.706 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-06-03T21:08:48.706Z,1338757728.706 [Default:Iridium:A.SetSpeed] Stopped
2012-06-03T21:08:48.733Z,1338757728.733 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-06-03T21:08:48.733Z,1338757728.733 [Default:F.Wait] Running Loop=1
2012-06-03T21:08:48.733Z,1338757728.733 [Default:F.Wait](DEBUG): Initialize Wait Component.
2012-06-03T21:08:48.961Z,1338757728.961 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2012-06-03T21:08:48.961Z,1338757728.961 [Default:CallIridium:B] Stopped
2012-06-03T21:08:48.962Z,1338757728.962 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-06-03T21:08:48.963Z,1338757728.963 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-06-03T21:08:48.963Z,1338757728.963 [Default:CallIridium] Stopped
2012-06-03T21:08:48.963Z,1338757728.963 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-06-03T21:08:59.077Z,1338757739.077 [NAL9601](INFO): Powering down
2012-06-03T21:12:59.149Z,1338757979.149 [Radio_Freewave](INFO): Powering down
2012-06-03T21:13:04.113Z,1338757984.113 [Radio_Freewave](INFO): Powering up
2012-06-03T21:13:49.134Z,1338758029.135 [Default:CallIridium] Running Loop=1
2012-06-03T21:13:49.135Z,1338758029.135 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-06-03T21:13:49.135Z,1338758029.135 [Default:CallIridium:A] Running Loop=1
2012-06-03T21:13:49.135Z,1338758029.135 [Default:CallIridium:A] Stopped
2012-06-03T21:13:49.135Z,1338758029.135 [Default:CallIridium:B] Running Loop=1
2012-06-03T21:13:49.135Z,1338758029.135 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-06-03T21:13:54.067Z,1338758034.066 [Default:Iridium] Running Loop=1
2012-06-03T21:13:54.067Z,1338758034.067 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-06-03T21:13:54.067Z,1338758034.067 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-06-03T21:13:54.067Z,1338758034.067 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-06-03T21:13:54.067Z,1338758034.067 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-06-03T21:13:54.067Z,1338758034.067 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-03T21:13:54.068Z,1338758034.068 [Default:Iridium:B.GoToSurface] Stopped
2012-06-03T21:13:54.068Z,1338758034.068 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-03T21:13:54.068Z,1338758034.068 [Default:Iridium:Read_Iridium] Running Loop=1
2012-06-03T21:13:54.069Z,1338758034.069 [Default:GPS] Running Loop=1
2012-06-03T21:13:54.069Z,1338758034.069 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-06-03T21:13:54.069Z,1338758034.069 [Default:GPS:A.SetSpeed] Running Loop=1
2012-06-03T21:13:54.069Z,1338758034.069 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-06-03T21:13:54.069Z,1338758034.069 [Default:GPS:B.GoToSurface] Running Loop=1
2012-06-03T21:13:54.069Z,1338758034.069 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-03T21:13:54.071Z,1338758034.071 [Default:GPS:B.GoToSurface] Stopped
2012-06-03T21:13:54.071Z,1338758034.071 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-03T21:13:54.071Z,1338758034.071 [Default:GPS:Read_GPS] Running Loop=1
2012-06-03T21:13:54.756Z,1338758034.756 [NAL9601](INFO): Powering up
2012-06-03T21:15:00.485Z,1338758100.485 [NAL9601](INFO): NAL9601 initialized
2012-06-03T21:15:17.543Z,1338758117.543 [NAL9601](INFO): SBD MO Status=1, MOMSN=8848, MT Status=0, MTMSN=0
2012-06-03T21:15:17.742Z,1338758117.742 [NAL9601](INFO): Sent 119 bytes from file Logs/20120603T200613/shore0005.lzma
2012-06-03T21:15:17.743Z,1338758117.743 [NAL9601](INFO): Packets left to send: 0
2012-06-03T21:15:17.744Z,1338758117.744 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000640
2012-06-03T21:15:23.100Z,1338758123.100 [NAL9601](INFO): SBD MO Status=0, MOMSN=8849, MT Status=0, MTMSN=0
2012-06-03T21:15:23.249Z,1338758123.249 [Default:Iridium:Read_Iridium] Stopped
2012-06-03T21:15:23.249Z,1338758123.249 [Default:Iridium](INFO): Completed Default:Iridium
2012-06-03T21:15:23.250Z,1338758123.250 [Default:Iridium] Stopped
2012-06-03T21:15:23.250Z,1338758123.250 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-06-03T21:15:23.250Z,1338758123.250 [Default:Iridium:A.SetSpeed] Stopped
2012-06-03T21:15:23.250Z,1338758123.250 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-06-03T21:15:23.517Z,1338758123.517 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2012-06-03T21:15:23.517Z,1338758123.517 [Default:CallIridium:B] Stopped
2012-06-03T21:15:23.517Z,1338758123.517 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-06-03T21:15:23.517Z,1338758123.517 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-06-03T21:15:23.517Z,1338758123.517 [Default:CallIridium] Stopped
2012-06-03T21:15:23.518Z,1338758123.518 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-06-03T21:15:56.301Z,1338758156.302 [NAL9601](IMPORTANT): GPS fix at: 1338758622.00
2012-06-03T21:15:56.317Z,1338758156.317 [Default:GPS:Read_GPS] Stopped
2012-06-03T21:15:56.317Z,1338758156.317 [Default:GPS:D] Running Loop=1
2012-06-03T21:15:56.714Z,1338758156.714 [Default:GPS:D] Stopped
2012-06-03T21:15:56.715Z,1338758156.715 [Default:GPS](INFO): Completed Default:GPS
2012-06-03T21:15:56.715Z,1338758156.715 [Default:GPS] Stopped
2012-06-03T21:15:56.715Z,1338758156.715 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-06-03T21:15:56.715Z,1338758156.715 [Default:GPS:A.SetSpeed] Stopped
2012-06-03T21:15:56.715Z,1338758156.715 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-06-03T21:16:12.291Z,1338758172.291 [NAL9601](INFO): Powering down
2012-06-03T21:18:47.278Z,1338758327.279 [Batt_Ocean_Server](INFO): Bus busy reported for battery bank#5 STATUS: 65535
2012-06-03T21:20:27.308Z,1338758427.308 [Default:CallIridium] Running Loop=1
2012-06-03T21:20:27.308Z,1338758427.308 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-06-03T21:20:27.308Z,1338758427.308 [Default:CallIridium:A] Running Loop=1
2012-06-03T21:20:27.308Z,1338758427.308 [Default:CallIridium:A] Stopped
2012-06-03T21:20:27.309Z,1338758427.309 [Default:CallIridium:B] Running Loop=1
2012-06-03T21:20:27.309Z,1338758427.309 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-06-03T21:20:32.242Z,1338758432.242 [Default:Iridium] Running Loop=1
2012-06-03T21:20:32.242Z,1338758432.242 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-06-03T21:20:32.243Z,1338758432.243 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-06-03T21:20:32.243Z,1338758432.243 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-06-03T21:20:32.243Z,1338758432.243 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-06-03T21:20:32.243Z,1338758432.243 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-03T21:20:32.244Z,1338758432.244 [Default:Iridium:B.GoToSurface] Stopped
2012-06-03T21:20:32.244Z,1338758432.244 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-03T21:20:32.244Z,1338758432.244 [Default:Iridium:Read_Iridium] Running Loop=1
2012-06-03T21:20:32.244Z,1338758432.244 [Default:GPS] Running Loop=1
2012-06-03T21:20:32.244Z,1338758432.244 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-06-03T21:20:32.245Z,1338758432.245 [Default:GPS:A.SetSpeed] Running Loop=1
2012-06-03T21:20:32.245Z,1338758432.245 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-06-03T21:20:32.245Z,1338758432.245 [Default:GPS:B.GoToSurface] Running Loop=1
2012-06-03T21:20:32.245Z,1338758432.245 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-03T21:20:32.246Z,1338758432.246 [Default:GPS:B.GoToSurface] Stopped
2012-06-03T21:20:32.246Z,1338758432.246 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-03T21:20:32.246Z,1338758432.246 [Default:GPS:Read_GPS] Running Loop=1
2012-06-03T21:20:32.917Z,1338758432.917 [NAL9601](INFO): Powering up
2012-06-03T21:21:38.542Z,1338758498.542 [NAL9601](INFO): NAL9601 initialized
2012-06-03T21:22:18.005Z,1338758538.005 [NAL9601](INFO): SBD MO Status=1, MOMSN=8850, MT Status=0, MTMSN=0
2012-06-03T21:22:18.170Z,1338758538.170 [NAL9601](INFO): Sent 181 bytes from file Logs/20120603T200613/shore0006.lzma
2012-06-03T21:22:18.171Z,1338758538.171 [NAL9601](INFO): Packets left to send: 0
2012-06-03T21:22:18.905Z,1338758538.905 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000641
2012-06-03T21:22:29.009Z,1338758549.009 [NAL9601](INFO): SBD MO Status=0, MOMSN=8851, MT Status=0, MTMSN=0
2012-06-03T21:22:29.171Z,1338758549.171 [Default:Iridium:Read_Iridium] Stopped
2012-06-03T21:22:29.172Z,1338758549.172 [Default:Iridium](INFO): Completed Default:Iridium
2012-06-03T21:22:29.172Z,1338758549.172 [Default:Iridium] Stopped
2012-06-03T21:22:29.172Z,1338758549.172 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-06-03T21:22:29.172Z,1338758549.172 [Default:Iridium:A.SetSpeed] Stopped
2012-06-03T21:22:29.172Z,1338758549.172 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-06-03T21:22:29.422Z,1338758549.422 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2012-06-03T21:22:29.422Z,1338758549.422 [Default:CallIridium:B] Stopped
2012-06-03T21:22:29.423Z,1338758549.423 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-06-03T21:22:29.423Z,1338758549.423 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-06-03T21:22:29.423Z,1338758549.423 [Default:CallIridium] Stopped
2012-06-03T21:22:29.423Z,1338758549.423 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-06-03T21:22:50.225Z,1338758570.225 [NAL9601](IMPORTANT): GPS fix at: 1338759030.00
2012-06-03T21:22:50.267Z,1338758570.267 [Default:GPS:Read_GPS] Stopped
2012-06-03T21:22:50.267Z,1338758570.267 [Default:GPS:D] Running Loop=1
2012-06-03T21:22:50.618Z,1338758570.618 [Default:GPS:D] Stopped
2012-06-03T21:22:50.619Z,1338758570.619 [Default:GPS](INFO): Completed Default:GPS
2012-06-03T21:22:50.619Z,1338758570.619 [Default:GPS] Stopped
2012-06-03T21:22:50.619Z,1338758570.619 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-06-03T21:22:50.619Z,1338758570.619 [Default:GPS:A.SetSpeed] Stopped
2012-06-03T21:22:50.619Z,1338758570.619 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-06-03T21:23:06.154Z,1338758586.154 [NAL9601](INFO): Powering down
2012-06-03T21:27:31.128Z,1338758851.128 [Default:CallIridium] Running Loop=1
2012-06-03T21:27:31.128Z,1338758851.128 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-06-03T21:27:31.128Z,1338758851.128 [Default:CallIridium:A] Running Loop=1
2012-06-03T21:27:31.129Z,1338758851.129 [Default:CallIridium:A] Stopped
2012-06-03T21:27:31.129Z,1338758851.129 [Default:CallIridium:B] Running Loop=1
2012-06-03T21:27:31.129Z,1338758851.129 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-06-03T21:27:36.306Z,1338758856.306 [Default:Iridium] Running Loop=1
2012-06-03T21:27:36.306Z,1338758856.306 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-06-03T21:27:36.306Z,1338758856.306 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-06-03T21:27:36.306Z,1338758856.306 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-06-03T21:27:36.307Z,1338758856.307 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-06-03T21:27:36.307Z,1338758856.307 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-03T21:27:36.308Z,1338758856.308 [Default:Iridium:B.GoToSurface] Stopped
2012-06-03T21:27:36.308Z,1338758856.308 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-03T21:27:36.308Z,1338758856.308 [Default:Iridium:Read_Iridium] Running Loop=1
2012-06-03T21:27:36.308Z,1338758856.308 [Default:GPS] Running Loop=1
2012-06-03T21:27:36.308Z,1338758856.308 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-06-03T21:27:36.308Z,1338758856.309 [Default:GPS:A.SetSpeed] Running Loop=1
2012-06-03T21:27:36.308Z,1338758856.309 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-06-03T21:27:36.309Z,1338758856.309 [Default:GPS:B.GoToSurface] Running Loop=1
2012-06-03T21:27:36.309Z,1338758856.309 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-03T21:27:36.310Z,1338758856.310 [Default:GPS:B.GoToSurface] Stopped
2012-06-03T21:27:36.310Z,1338758856.310 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-03T21:27:36.310Z,1338758856.310 [Default:GPS:Read_GPS] Running Loop=1
2012-06-03T21:27:36.900Z,1338758856.900 [NAL9601](INFO): Powering up
2012-06-03T21:27:37.676Z,1338758857.676 [Radio_Freewave](INFO): Powering down
2012-06-03T21:27:38.481Z,1338758858.481 [Radio_Freewave](INFO): Powering up
2012-06-03T21:28:42.526Z,1338758922.526 [NAL9601](INFO): NAL9601 initialized
2012-06-03T21:29:16.789Z,1338758956.789 [NAL9601](INFO): SBD MO Status=1, MOMSN=8852, MT Status=0, MTMSN=0
2012-06-03T21:29:16.963Z,1338758956.963 [NAL9601](INFO): Sent 179 bytes from file Logs/20120603T200613/shore0007.lzma
2012-06-03T21:29:16.963Z,1338758956.963 [NAL9601](INFO): Packets left to send: 0
2012-06-03T21:29:16.964Z,1338758956.964 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000642
2012-06-03T21:29:19.987Z,1338758959.987 [NAL9601](INFO): SBD MO Status=0, MOMSN=8853, MT Status=0, MTMSN=0
2012-06-03T21:29:20.170Z,1338758960.170 [Default:Iridium:Read_Iridium] Stopped
2012-06-03T21:29:20.170Z,1338758960.170 [Default:Iridium](INFO): Completed Default:Iridium
2012-06-03T21:29:20.170Z,1338758960.170 [Default:Iridium] Stopped
2012-06-03T21:29:20.170Z,1338758960.170 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-06-03T21:29:20.170Z,1338758960.170 [Default:Iridium:A.SetSpeed] Stopped
2012-06-03T21:29:20.170Z,1338758960.170 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-06-03T21:29:20.382Z,1338758960.382 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2012-06-03T21:29:20.383Z,1338758960.383 [Default:CallIridium:B] Stopped
2012-06-03T21:29:20.383Z,1338758960.383 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-06-03T21:29:20.383Z,1338758960.383 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-06-03T21:29:20.383Z,1338758960.383 [Default:CallIridium] Stopped
2012-06-03T21:29:20.383Z,1338758960.383 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-06-03T21:29:28.774Z,1338758968.774 [Radio_Freewave](INFO): Powering down
2012-06-03T21:29:34.823Z,1338758974.823 [Radio_Freewave](INFO): Powering up
2012-06-03T21:31:58.022Z,1338759118.022 [NAL9601](IMPORTANT): GPS fix at: 1338759579.00
2012-06-03T21:31:58.037Z,1338759118.037 [Default:GPS:Read_GPS] Stopped
2012-06-03T21:31:58.037Z,1338759118.037 [Default:GPS:D] Running Loop=1
2012-06-03T21:31:58.472Z,1338759118.472 [Default:GPS:D] Stopped
2012-06-03T21:31:58.473Z,1338759118.473 [Default:GPS](INFO): Completed Default:GPS
2012-06-03T21:31:58.473Z,1338759118.473 [Default:GPS] Stopped
2012-06-03T21:31:58.473Z,1338759118.473 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-06-03T21:31:58.473Z,1338759118.473 [Default:GPS:A.SetSpeed] Stopped
2012-06-03T21:31:58.473Z,1338759118.473 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-06-03T21:32:14.002Z,1338759134.002 [NAL9601](INFO): Powering down
2012-06-03T21:34:23.993Z,1338759263.993 [Default:CallIridium] Running Loop=1
2012-06-03T21:34:23.993Z,1338759263.993 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-06-03T21:34:23.994Z,1338759263.994 [Default:CallIridium:A] Running Loop=1
2012-06-03T21:34:23.994Z,1338759263.994 [Default:CallIridium:A] Stopped
2012-06-03T21:34:23.994Z,1338759263.994 [Default:CallIridium:B] Running Loop=1
2012-06-03T21:34:23.994Z,1338759263.994 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-06-03T21:34:28.991Z,1338759268.991 [Default:Iridium] Running Loop=1
2012-06-03T21:34:28.991Z,1338759268.991 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-06-03T21:34:28.991Z,1338759268.991 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-06-03T21:34:28.991Z,1338759268.991 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-06-03T21:34:28.991Z,1338759268.991 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-06-03T21:34:28.991Z,1338759268.991 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-03T21:34:28.992Z,1338759268.992 [Default:Iridium:B.GoToSurface] Stopped
2012-06-03T21:34:28.992Z,1338759268.992 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-03T21:34:28.992Z,1338759268.992 [Default:Iridium:Read_Iridium] Running Loop=1
2012-06-03T21:34:28.993Z,1338759268.993 [Default:GPS] Running Loop=1
2012-06-03T21:34:28.993Z,1338759268.993 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-06-03T21:34:28.993Z,1338759268.993 [Default:GPS:A.SetSpeed] Running Loop=1
2012-06-03T21:34:28.993Z,1338759268.993 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-06-03T21:34:28.993Z,1338759268.993 [Default:GPS:B.GoToSurface] Running Loop=1
2012-06-03T21:34:28.993Z,1338759268.993 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-06-03T21:34:28.994Z,1338759268.994 [Default:GPS:B.GoToSurface] Stopped
2012-06-03T21:34:28.994Z,1338759268.994 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-06-03T21:34:28.995Z,1338759268.995 [Default:GPS:Read_GPS] Running Loop=1
2012-06-03T21:34:29.618Z,1338759269.618 [NAL9601](INFO): Powering up
2012-06-03T21:35:35.343Z,1338759335.343 [NAL9601](INFO): NAL9601 initialized
2012-06-03T21:35:36.421Z,1338759336.421 [NAL9601](IMPORTANT): GPS fix at: 1338759797.00
2012-06-03T21:35:36.437Z,1338759336.437 [Default:GPS:Read_GPS] Stopped
2012-06-03T21:35:36.437Z,1338759336.437 [Default:GPS:D] Running Loop=1
2012-06-03T21:35:36.836Z,1338759336.836 [Default:GPS:D] Stopped
2012-06-03T21:35:36.837Z,1338759336.837 [Default:GPS](INFO): Completed Default:GPS
2012-06-03T21:35:36.837Z,1338759336.837 [Default:GPS] Stopped
2012-06-03T21:35:36.837Z,1338759336.837 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-06-03T21:35:36.837Z,1338759336.837 [Default:GPS:A.SetSpeed] Stopped
2012-06-03T21:35:36.837Z,1338759336.837 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-06-03T21:35:50.861Z,1338759350.861 [NAL9601](IMPORTANT): SBD MO Status=1, MOMSN=8854, MT Status=1, MTMSN=2694
2012-06-03T21:35:51.006Z,1338759351.006 [NAL9601](INFO): Sent 209 bytes from file Logs/20120603T200613/shore0008.lzma
2012-06-03T21:35:51.007Z,1338759351.007 [NAL9601](INFO): Packets left to send: 0
2012-06-03T21:35:51.008Z,1338759351.008 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000643
2012-06-03T21:35:51.419Z,1338759351.419 [NAL9601](INFO): Received command:restart logs
2012-06-03T21:35:51.442Z,1338759351.442 [CommandLine](IMPORTANT): got command restart logs