2012-05-24T18:28:28.692Z,1337884108.692 [NAL9601](INFO): SBD MO Status=2, MOMSN=6812, MT Status=2, MTMSN=0
2012-05-24T18:28:28.692Z,1337884108.692 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-05-24T18:28:46.601Z,1337884126.601 [NAL9601](INFO): SBD MO Status=2, MOMSN=6812, MT Status=2, MTMSN=0
2012-05-24T18:28:46.601Z,1337884126.601 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-05-24T18:29:30.294Z,1337884170.294 [NAL9601](INFO): SBD MO Status=2, MOMSN=6812, MT Status=2, MTMSN=0
2012-05-24T18:29:30.294Z,1337884170.294 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-05-24T18:30:02.798Z,1337884202.797 [NAL9601](INFO): SBD MO Status=2, MOMSN=6812, MT Status=0, MTMSN=0
2012-05-24T18:30:02.798Z,1337884202.798 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-05-24T18:30:30.318Z,1337884230.318 [NAL9601](INFO): SBD MO Status=2, MOMSN=6812, MT Status=2, MTMSN=0
2012-05-24T18:30:30.318Z,1337884230.318 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-05-24T18:30:52.823Z,1337884252.823 [CommandLine](IMPORTANT): got command configSet VerticalControl.buoyancyNeutral 750.000000 cubic_centimeter
2012-05-24T18:30:52.869Z,1337884252.869 [CommandLine](IMPORTANT): got command load ./Missions/Maintenance/ballast_and_trim.xml
2012-05-24T18:30:52.869Z,1337884252.869 [MissionManager](INFO): Loading Mission: ./Missions/Maintenance/ballast_and_trim.xml
2012-05-24T18:30:52.947Z,1337884252.947 [MissionManager](INFO): DefineArg ballast_and_trim.BallastDepth = 25.000000 m
2012-05-24T18:30:52.950Z,1337884252.950 [MissionManager](INFO): DefineArg ballast_and_trim.HoldDuration = 25.000000 min
2012-05-24T18:30:52.952Z,1337884252.952 [MissionManager](INFO): DefineArg ballast_and_trim.Speed = 1.000000 m/s
2012-05-24T18:30:52.954Z,1337884252.954 [MissionManager](INFO): DefineArg ballast_and_trim.DepthDeadband = 4.000000 m
2012-05-24T18:30:52.956Z,1337884252.956 [MissionManager](INFO): DefineArg ballast_and_trim.BuoyPeriod = 0.400000 s
2012-05-24T18:30:52.963Z,1337884252.963 [MissionManager](INFO): DefineArg ballast_and_trim.BuoyancyDefault = 0.000945 n/a
2012-05-24T18:30:52.981Z,1337884252.981 [ballast_and_trim:D.SetSpeed](DEBUG): Construct.
2012-05-24T18:30:52.993Z,1337884252.993 [ballast_and_trim:ballast:A.SetSpeed](DEBUG): Construct.
2012-05-24T18:30:52.997Z,1337884252.997 [ballast_and_trim:ballast:C.Pitch](DEBUG): Construct.
2012-05-24T18:30:53.009Z,1337884253.009 [ballast_and_trim:ballast:D.Wait](DEBUG): Construct Wait.
2012-05-24T18:30:53.014Z,1337884253.013 [ballast_and_trim:ballast:ReportPositions:B.Wait](DEBUG): Construct Wait.
2012-05-24T18:30:53.016Z,1337884253.016 [ballast_and_trim:ballast:Float_Up:A.SetSpeed](DEBUG): Construct.
2012-05-24T18:30:53.021Z,1337884253.021 [ballast_and_trim:ballast:Float_Up:B.Buoyancy](DEBUG): Construct Buoyancy.
2012-05-24T18:30:53.024Z,1337884253.024 [ballast_and_trim:ballast:Float_Up:C.Wait](DEBUG): Construct Wait.
2012-05-24T18:30:53.029Z,1337884253.029 [MissionManager](DEBUG): 
<?xml version="1.0" encoding="UTF-8"?>
<Mission xmlns="Tethys"
       xmlns:Control="Tethys/Control"
       xmlns:Guidance="Tethys/Guidance" 
       xmlns:Trigger="Tethys/Trigger" 
       xmlns:Units="Tethys/Units"
       xmlns:Universal="Tethys/Universal"
       xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance"
       xsi:schemaLocation="Tethys http://aosn.mbari.org/tethys/Xml/Tethys.xsd
                           Tethys/Control http://aosn.mbari.org/tethys/Xml/Control.xsd
                           Tethys/Guidance http://aosn.mbari.org/tethys/Xml/Guidance.xsd
                           Tethys/Trigger http://aosn.mbari.org/tethys/Xml/Trigger.xsd
                           Tethys/Units http://aosn.mbari.org/tethys/Xml/Units.xsd
                           Tethys/Universal http://aosn.mbari.org/tethys/Xml/Universal.xsd"
       Id="ballast_and_trim">

<!-- The following arguments are input arguments -->

    <DefineArg Name="BallastDepth"><Units:meter/><Value>25.0</Value></DefineArg>

    <DefineArg Name="HoldDuration"><Units:minute/><Value>25</Value></DefineArg>

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

    <DefineArg Name="DepthDeadband"><Units:meter/><Value>4.0</Value></DefineArg>

    <DefineArg Name="BuoyPeriod"><Units:second/><Value>0.4</Value></DefineArg>

    <DefineArg Name="BuoyancyDefault"><Control:VerticalControl.buoyancyDefault/></DefineArg>

<!-- The timeout -->

    <Timeout Duration="P90M"/>

<!-- And now the mission -->

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

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

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

<!--    <Guidance:AltitudeEnvelope RunIn="Parallel">
        <Setting><Guidance:AltitudeEnvelope.minAltitude/><Units:meter/><Value>7</Value></Setting>
    </Guidance:AltitudeEnvelope>

    <Guidance:DepthEnvelope RunIn="Parallel">
        <Setting><Guidance:DepthEnvelope.maxDepth/><Units:meter/><Value>52</Value></Setting>
    </Guidance:DepthEnvelope>

    <Guidance:OffshoreEnvelope RunIn="Parallel">
        <Setting><Guidance:OffshoreEnvelope.minOffshore/><Units:kilometer/><Value>2.0</Value></Setting>
        <Setting><Guidance:OffshoreEnvelope.maxOffshore/><Units:kilometer/><Value>30.0</Value></Setting>
    </Guidance:OffshoreEnvelope>
-->
    <!-- <Insert Filename="Insert/Surface.xml" Id="SurfaceComms" /> -->

    <Guidance:SetSpeed>
        <While><Universal:platform_propeller_rotation_rate/>
            <Eq><Units:radian_per_second/><Value>0</Value></Eq>
        </While>
        <Setting><Guidance:SetSpeed.period/><Arg Name="BuoyPeriod"/></Setting>
    </Guidance:SetSpeed>

    <Aggregate Id="ballast">

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

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

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

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

        <Aggregate Id="ReportPositions" Repeat="5" >

            <Syslog Severity="Important">
            Buoyancy:<Universal:platform_buoyancy_position/><Units:cubic_centimeter/>
            Mass:<Universal:platform_mass_position/><Units:centimeter/>
            Pitch:<Universal:platform_pitch_angle/><Units:degree/>
            </Syslog>

            <Guidance:Wait RunIn="Sequence">
                <Timeout Duration="P1M"></Timeout>
            </Guidance:Wait>

        </Aggregate>

        <Aggregate Id="Float_Up">

            <Until><Universal:depth/>
                <Lt><Units:meter/><Value>1</Value></Lt>
            </Until>

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

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

            <Guidance:Wait RunIn="Sequence">
                <Timeout Duration="P15M"></Timeout>
            </Guidance:Wait>

        </Aggregate>

    </Aggregate>

</Mission>


2012-05-24T18:30:53.030Z,1337884253.030 [CommandLine](IMPORTANT): Loaded ./Missions/Maintenance/ballast_and_trim.xml
2012-05-24T18:30:53.148Z,1337884253.148 [CommandLine](IMPORTANT): got command set ballast_and_trim.BallastDepth 5.000000 meter
2012-05-24T18:30:53.362Z,1337884253.362 [CommandLine](IMPORTANT): got command set ballast_and_trim.HoldDuration 10.000000 minute
2012-05-24T18:30:56.335Z,1337884256.335 [CommandLine](IMPORTANT): got command set ballast_and_trim.DepthDeadband 1.000000 meter
2012-05-24T18:31:04.854Z,1337884264.854 [NAL9601](INFO): SBD MO Status=2, MOMSN=6812, MT Status=2, MTMSN=0
2012-05-24T18:31:04.854Z,1337884264.854 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-05-24T18:31:24.405Z,1337884284.405 [NAL9601](INFO): SBD MO Status=2, MOMSN=6812, MT Status=2, MTMSN=0
2012-05-24T18:31:24.405Z,1337884284.405 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-05-24T18:31:48.046Z,1337884308.046 [NAL9601](INFO): SBD MO Status=2, MOMSN=6812, MT Status=2, MTMSN=0
2012-05-24T18:31:48.046Z,1337884308.046 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-05-24T18:32:02.234Z,1337884322.234 [NAL9601](IMPORTANT): GPS fix at: 1337886321.00
2012-05-24T18:32:02.249Z,1337884322.249 [Default:GPS:Read_GPS] Stopped
2012-05-24T18:32:02.250Z,1337884322.250 [Default:GPS:D] Running Loop=1
2012-05-24T18:32:02.646Z,1337884322.646 [Default:GPS:D] Stopped
2012-05-24T18:32:02.647Z,1337884322.647 [Default:GPS](INFO): Completed Default:GPS
2012-05-24T18:32:02.647Z,1337884322.647 [Default:GPS] Stopped
2012-05-24T18:32:02.647Z,1337884322.647 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-05-24T18:32:02.647Z,1337884322.647 [Default:GPS:A.SetSpeed] Stopped
2012-05-24T18:32:02.647Z,1337884322.647 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-05-24T18:32:30.197Z,1337884350.197 [NAL9601](INFO): SBD MO Status=2, MOMSN=6812, MT Status=2, MTMSN=0
2012-05-24T18:32:30.197Z,1337884350.197 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-05-24T18:32:57.732Z,1337884377.732 [NAL9601](INFO): SBD MO Status=2, MOMSN=6812, MT Status=2, MTMSN=0
2012-05-24T18:32:57.733Z,1337884377.733 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-05-24T18:33:53.195Z,1337884433.195 [NAL9601](INFO): SBD MO Status=2, MOMSN=6812, MT Status=2, MTMSN=0
2012-05-24T18:33:53.195Z,1337884433.195 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-05-24T18:34:23.457Z,1337884463.457 [NAL9601](INFO): SBD MO Status=1, MOMSN=6812, MT Status=0, MTMSN=0
2012-05-24T18:34:23.643Z,1337884463.643 [NAL9601](INFO): Sent 332 bytes from file Logs/20120524T173012/shore0000.lzma
2012-05-24T18:34:23.643Z,1337884463.643 [NAL9601](INFO): Packets left to send: 1
2012-05-24T18:34:23.644Z,1337884463.644 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000014
2012-05-24T18:34:40.340Z,1337884480.340 [NAL9601](INFO): SBD MO Status=2, MOMSN=6813, MT Status=2, MTMSN=0
2012-05-24T18:34:40.340Z,1337884480.340 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-05-24T18:34:51.067Z,1337884491.067 [NAL9601](INFO): SBD MO Status=1, MOMSN=6813, MT Status=0, MTMSN=0
2012-05-24T18:34:51.199Z,1337884491.199 [NAL9601](INFO): Sent 104 bytes from file Logs/20120524T173012/shore0000.lzma
2012-05-24T18:34:51.199Z,1337884491.199 [NAL9601](INFO): Packets left to send: 0
2012-05-24T18:34:51.200Z,1337884491.200 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000015
2012-05-24T18:34:59.134Z,1337884499.134 [NAL9601](INFO): SBD MO Status=2, MOMSN=6814, MT Status=2, MTMSN=0
2012-05-24T18:34:59.134Z,1337884499.134 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-05-24T18:35:22.209Z,1337884522.209 [NAL9601](INFO): SBD MO Status=2, MOMSN=6814, MT Status=2, MTMSN=0
2012-05-24T18:35:22.209Z,1337884522.210 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-05-24T18:36:20.154Z,1337884580.154 [NAL9601](INFO): SBD MO Status=2, MOMSN=6814, MT Status=2, MTMSN=0
2012-05-24T18:36:20.154Z,1337884580.154 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-05-24T18:36:41.333Z,1337884601.332 [NAL9601](INFO): SBD MO Status=2, MOMSN=6814, MT Status=2, MTMSN=0
2012-05-24T18:36:41.333Z,1337884601.333 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-05-24T18:37:01.621Z,1337884621.621 [NAL9601](INFO): SBD MO Status=2, MOMSN=6814, MT Status=2, MTMSN=0
2012-05-24T18:37:01.622Z,1337884621.622 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-05-24T18:37:25.588Z,1337884645.588 [NAL9601](INFO): SBD MO Status=2, MOMSN=6814, MT Status=2, MTMSN=0
2012-05-24T18:37:25.589Z,1337884645.589 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-05-24T18:37:56.488Z,1337884676.488 [NAL9601](INFO): SBD MO Status=1, MOMSN=6814, MT Status=0, MTMSN=0
2012-05-24T18:37:56.611Z,1337884676.611 [NAL9601](INFO): Sent 332 bytes from file Logs/20120524T175653/shore0000.lzma
2012-05-24T18:37:56.611Z,1337884676.611 [NAL9601](INFO): Packets left to send: 3
2012-05-24T18:37:56.612Z,1337884676.612 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000016
2012-05-24T18:38:06.940Z,1337884686.940 [NAL9601](INFO): SBD MO Status=1, MOMSN=6815, MT Status=0, MTMSN=0
2012-05-24T18:38:07.095Z,1337884687.095 [NAL9601](INFO): Sent 332 bytes from file Logs/20120524T175653/shore0000.lzma
2012-05-24T18:38:07.095Z,1337884687.095 [NAL9601](INFO): Packets left to send: 2
2012-05-24T18:38:07.096Z,1337884687.096 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000017
2012-05-24T18:38:18.633Z,1337884698.634 [NAL9601](INFO): SBD MO Status=1, MOMSN=6816, MT Status=0, MTMSN=0
2012-05-24T18:38:18.775Z,1337884698.775 [NAL9601](INFO): Sent 332 bytes from file Logs/20120524T175653/shore0000.lzma
2012-05-24T18:38:18.775Z,1337884698.775 [NAL9601](INFO): Packets left to send: 1
2012-05-24T18:38:18.776Z,1337884698.776 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000018
2012-05-24T18:38:27.935Z,1337884707.935 [NAL9601](INFO): SBD MO Status=1, MOMSN=6817, MT Status=0, MTMSN=0
2012-05-24T18:38:28.063Z,1337884708.063 [NAL9601](INFO): Sent 178 bytes from file Logs/20120524T175653/shore0000.lzma
2012-05-24T18:38:28.063Z,1337884708.063 [NAL9601](INFO): Packets left to send: 0
2012-05-24T18:38:28.065Z,1337884708.065 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000019
2012-05-24T18:38:30.615Z,1337884710.615 [CommandLine](IMPORTANT): got command run
2012-05-24T18:38:30.615Z,1337884710.615 [CommandLine](IMPORTANT): Running 
2012-05-24T18:38:31.265Z,1337884711.265 [Default] Stopped
2012-05-24T18:38:31.265Z,1337884711.265 [Default](INFO): Aggregate::uninitialize Default
2012-05-24T18:38:31.265Z,1337884711.265 [Default:Iridium] Stopped
2012-05-24T18:38:31.265Z,1337884711.265 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-05-24T18:38:31.266Z,1337884711.266 [Default:Iridium:A.SetSpeed] Stopped
2012-05-24T18:38:31.266Z,1337884711.266 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-05-24T18:38:31.266Z,1337884711.266 [Default:Iridium:Read_Iridium] Stopped
2012-05-24T18:38:31.266Z,1337884711.266 [Default:CallIridium] Stopped
2012-05-24T18:38:31.266Z,1337884711.266 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-05-24T18:38:31.266Z,1337884711.266 [Default:CallIridium:B] Stopped
2012-05-24T18:38:31.266Z,1337884711.266 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-05-24T18:38:31.266Z,1337884711.266 [Default:D.SetSpeed] Stopped
2012-05-24T18:38:31.266Z,1337884711.266 [Default:D.SetSpeed](DEBUG): Uninitialize.
2012-05-24T18:38:31.266Z,1337884711.266 [Default:E.GoToSurface] Stopped
2012-05-24T18:38:31.266Z,1337884711.266 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-05-24T18:38:31.267Z,1337884711.267 [MissionManager](IMPORTANT): Started mission ballast_and_trim
2012-05-24T18:38:31.267Z,1337884711.267 [ballast_and_trim] Running Loop=1
2012-05-24T18:38:31.267Z,1337884711.267 [ballast_and_trim](INFO): Aggregate::initialize ballast_and_trim
2012-05-24T18:38:31.267Z,1337884711.267 [ballast_and_trim:A] Running Loop=1
2012-05-24T18:38:31.267Z,1337884711.267 [ballast_and_trim:B] Running Loop=1
2012-05-24T18:38:31.267Z,1337884711.267 [ballast_and_trim:C] Running Loop=1
2012-05-24T18:38:31.267Z,1337884711.267 [ballast_and_trim:D.SetSpeed] Running Loop=1
2012-05-24T18:38:31.267Z,1337884711.267 [ballast_and_trim:D.SetSpeed](DEBUG): Initialize.
2012-05-24T18:38:31.267Z,1337884711.267 [ballast_and_trim:ballast] Running Loop=1
2012-05-24T18:38:31.268Z,1337884711.267 [ballast_and_trim:ballast](INFO): Aggregate::initialize ballast_and_trim:ballast
2012-05-24T18:38:31.268Z,1337884711.268 [ballast_and_trim:ballast:A.SetSpeed] Running Loop=1
2012-05-24T18:38:31.268Z,1337884711.268 [ballast_and_trim:ballast:A.SetSpeed](DEBUG): Initialize.
2012-05-24T18:38:31.268Z,1337884711.268 [ballast_and_trim:ballast:B] Running Loop=1
2012-05-24T18:38:31.268Z,1337884711.268 [ballast_and_trim:ballast:C.Pitch] Running Loop=1
2012-05-24T18:38:31.268Z,1337884711.268 [ballast_and_trim:ballast:C.Pitch](DEBUG): Initialize.
2012-05-24T18:38:31.268Z,1337884711.268 [ballast_and_trim:ballast:D.Wait] Running Loop=1
2012-05-24T18:38:31.268Z,1337884711.268 [ballast_and_trim:ballast:D.Wait](DEBUG): Initialize Wait Component.
2012-05-24T18:38:31.269Z,1337884711.269 [ballast_and_trim:ballast:C.Pitch] Running Loop=1
2012-05-24T18:38:31.270Z,1337884711.270 [ballast_and_trim:ballast:B] Running Loop=1
2012-05-24T18:38:31.271Z,1337884711.271 [ballast_and_trim:ballast:A.SetSpeed] Running Loop=1
2012-05-24T18:38:31.272Z,1337884711.272 [ballast_and_trim:D.SetSpeed] Running Loop=1
2012-05-24T18:38:31.273Z,1337884711.273 [ballast_and_trim:C] Running Loop=1
2012-05-24T18:38:31.274Z,1337884711.274 [ballast_and_trim:B] Running Loop=1
2012-05-24T18:38:31.275Z,1337884711.275 [ballast_and_trim:A] Running Loop=1
2012-05-24T18:38:35.714Z,1337884715.714 [NAL9601](INFO): SBD MO Status=1, MOMSN=6818, MT Status=0, MTMSN=0
2012-05-24T18:38:35.851Z,1337884715.851 [NAL9601](INFO): Sent 111 bytes from file Logs/20120524T175653/shore0001.lzma
2012-05-24T18:38:35.851Z,1337884715.851 [NAL9601](INFO): Packets left to send: 0
2012-05-24T18:38:35.852Z,1337884715.852 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000020
2012-05-24T18:38:43.901Z,1337884723.901 [NAL9601](INFO): SBD MO Status=0, MOMSN=6819, MT Status=0, MTMSN=0
2012-05-24T18:38:49.385Z,1337884729.385 [NAL9601](INFO): Powering down
2012-05-24T18:38:56.749Z,1337884736.749 [Radio_Freewave](INFO): Powering down
2012-05-24T18:48:31.576Z,1337885311.576 [ballast_and_trim:ballast:D.Wait](INFO): Done Waiting.
2012-05-24T18:48:31.576Z,1337885311.576 [ballast_and_trim:ballast:D.Wait] Stopped
2012-05-24T18:48:31.576Z,1337885311.576 [ballast_and_trim:ballast:D.Wait](DEBUG): Uninitialize Wait Component.
2012-05-24T18:48:31.576Z,1337885311.576 [ballast_and_trim:ballast:ReportPositions] Running Loop=1
2012-05-24T18:48:31.577Z,1337885311.577 [ballast_and_trim:ballast:ReportPositions](INFO): Aggregate::initialize ballast_and_trim:ballast:ReportPositions
2012-05-24T18:48:31.577Z,1337885311.577 [ballast_and_trim:ballast:ReportPositions:A] Running Loop=1
2012-05-24T18:48:31.938Z,1337885311.938 [ballast_and_trim:ballast:ReportPositions:A](IMPORTANT): Buoyancy: 949.759502 cc Mass: -0.469689 cm Pitch: -0.069043 arcdeg 
2012-05-24T18:48:31.938Z,1337885311.938 [ballast_and_trim:ballast:ReportPositions:A] Stopped
2012-05-24T18:48:31.939Z,1337885311.939 [ballast_and_trim:ballast:ReportPositions:B.Wait] Running Loop=1
2012-05-24T18:48:31.939Z,1337885311.939 [ballast_and_trim:ballast:ReportPositions:B.Wait](DEBUG): Initialize Wait Component.
2012-05-24T18:49:32.221Z,1337885372.221 [ballast_and_trim:ballast:ReportPositions:B.Wait](INFO): Timed out from 2012-05-24T18:48:31.9Z
2012-05-24T18:49:32.222Z,1337885372.222 [ballast_and_trim:ballast:ReportPositions:A_Timeout] Running Loop=1
2012-05-24T18:49:32.222Z,1337885372.222 [ballast_and_trim:ballast:ReportPositions:A_Timeout](INFO): Aggregate::initialize ballast_and_trim:ballast:ReportPositions:A_Timeout
2012-05-24T18:49:32.222Z,1337885372.222 [ballast_and_trim:ballast:ReportPositions:A_Timeout](INFO): Completed ballast_and_trim:ballast:ReportPositions:A_Timeout
2012-05-24T18:49:32.222Z,1337885372.222 [ballast_and_trim:ballast:ReportPositions:B.Wait] Stopped
2012-05-24T18:49:32.222Z,1337885372.222 [ballast_and_trim:ballast:ReportPositions:B.Wait](DEBUG): Uninitialize Wait Component.
2012-05-24T18:49:32.223Z,1337885372.223 [ballast_and_trim:ballast:ReportPositions](INFO): Completed ballast_and_trim:ballast:ReportPositions
2012-05-24T18:49:32.223Z,1337885372.223 [ballast_and_trim:ballast:ReportPositions] Stopped
2012-05-24T18:49:32.223Z,1337885372.223 [ballast_and_trim:ballast:ReportPositions](INFO): Aggregate::uninitialize ballast_and_trim:ballast:ReportPositions
2012-05-24T18:49:32.223Z,1337885372.223 [ballast_and_trim:ballast:ReportPositions](INFO): Running loop #2
2012-05-24T18:49:32.223Z,1337885372.223 [ballast_and_trim:ballast:ReportPositions] Running Loop=2
2012-05-24T18:49:32.223Z,1337885372.223 [ballast_and_trim:ballast:ReportPositions](INFO): Aggregate::initialize ballast_and_trim:ballast:ReportPositions
2012-05-24T18:49:32.223Z,1337885372.223 [ballast_and_trim:ballast:ReportPositions:A] Running Loop=1
2012-05-24T18:49:32.587Z,1337885372.587 [ballast_and_trim:ballast:ReportPositions:A](IMPORTANT): Buoyancy: 950.028189 cc Mass: -0.473582 cm Pitch: 0.062793 arcdeg 
2012-05-24T18:49:32.587Z,1337885372.587 [ballast_and_trim:ballast:ReportPositions:A] Stopped
2012-05-24T18:49:32.587Z,1337885372.587 [ballast_and_trim:ballast:ReportPositions:B.Wait] Running Loop=1
2012-05-24T18:49:32.587Z,1337885372.587 [ballast_and_trim:ballast:ReportPositions:B.Wait](DEBUG): Initialize Wait Component.
2012-05-24T18:50:33.021Z,1337885433.021 [ballast_and_trim:ballast:ReportPositions:B.Wait](INFO): Timed out from 2012-05-24T18:49:32.6Z
2012-05-24T18:50:33.021Z,1337885433.021 [ballast_and_trim:ballast:ReportPositions:A_Timeout] Running Loop=1
2012-05-24T18:50:33.022Z,1337885433.022 [ballast_and_trim:ballast:ReportPositions:A_Timeout](INFO): Aggregate::initialize ballast_and_trim:ballast:ReportPositions:A_Timeout
2012-05-24T18:50:33.022Z,1337885433.022 [ballast_and_trim:ballast:ReportPositions:A_Timeout](INFO): Completed ballast_and_trim:ballast:ReportPositions:A_Timeout
2012-05-24T18:50:33.022Z,1337885433.022 [ballast_and_trim:ballast:ReportPositions:B.Wait] Stopped
2012-05-24T18:50:33.022Z,1337885433.022 [ballast_and_trim:ballast:ReportPositions:B.Wait](DEBUG): Uninitialize Wait Component.
2012-05-24T18:50:33.022Z,1337885433.022 [ballast_and_trim:ballast:ReportPositions](INFO): Completed ballast_and_trim:ballast:ReportPositions
2012-05-24T18:50:33.022Z,1337885433.022 [ballast_and_trim:ballast:ReportPositions] Stopped
2012-05-24T18:50:33.022Z,1337885433.022 [ballast_and_trim:ballast:ReportPositions](INFO): Aggregate::uninitialize ballast_and_trim:ballast:ReportPositions
2012-05-24T18:50:33.023Z,1337885433.023 [ballast_and_trim:ballast:ReportPositions](INFO): Running loop #3
2012-05-24T18:50:33.023Z,1337885433.023 [ballast_and_trim:ballast:ReportPositions] Running Loop=3
2012-05-24T18:50:33.023Z,1337885433.023 [ballast_and_trim:ballast:ReportPositions](INFO): Aggregate::initialize ballast_and_trim:ballast:ReportPositions
2012-05-24T18:50:33.023Z,1337885433.023 [ballast_and_trim:ballast:ReportPositions:A] Running Loop=1
2012-05-24T18:50:33.382Z,1337885433.382 [ballast_and_trim:ballast:ReportPositions:A](IMPORTANT): Buoyancy: 950.028189 cc Mass: -0.473582 cm Pitch: 0.018848 arcdeg 
2012-05-24T18:50:33.382Z,1337885433.382 [ballast_and_trim:ballast:ReportPositions:A] Stopped
2012-05-24T18:50:33.382Z,1337885433.382 [ballast_and_trim:ballast:ReportPositions:B.Wait] Running Loop=1
2012-05-24T18:50:33.383Z,1337885433.383 [ballast_and_trim:ballast:ReportPositions:B.Wait](DEBUG): Initialize Wait Component.
2012-05-24T18:51:33.403Z,1337885493.403 [ballast_and_trim:ballast:ReportPositions:B.Wait](INFO): Timed out from 2012-05-24T18:50:33.4Z
2012-05-24T18:51:33.403Z,1337885493.403 [ballast_and_trim:ballast:ReportPositions:A_Timeout] Running Loop=1
2012-05-24T18:51:33.403Z,1337885493.403 [ballast_and_trim:ballast:ReportPositions:A_Timeout](INFO): Aggregate::initialize ballast_and_trim:ballast:ReportPositions:A_Timeout
2012-05-24T18:51:33.403Z,1337885493.403 [ballast_and_trim:ballast:ReportPositions:A_Timeout](INFO): Completed ballast_and_trim:ballast:ReportPositions:A_Timeout
2012-05-24T18:51:33.403Z,1337885493.403 [ballast_and_trim:ballast:ReportPositions:B.Wait] Stopped
2012-05-24T18:51:33.403Z,1337885493.403 [ballast_and_trim:ballast:ReportPositions:B.Wait](DEBUG): Uninitialize Wait Component.
2012-05-24T18:51:33.403Z,1337885493.403 [ballast_and_trim:ballast:ReportPositions](INFO): Completed ballast_and_trim:ballast:ReportPositions
2012-05-24T18:51:33.403Z,1337885493.404 [ballast_and_trim:ballast:ReportPositions] Stopped
2012-05-24T18:51:33.404Z,1337885493.404 [ballast_and_trim:ballast:ReportPositions](INFO): Aggregate::uninitialize ballast_and_trim:ballast:ReportPositions
2012-05-24T18:51:33.404Z,1337885493.404 [ballast_and_trim:ballast:ReportPositions](INFO): Running loop #4
2012-05-24T18:51:33.404Z,1337885493.404 [ballast_and_trim:ballast:ReportPositions] Running Loop=4
2012-05-24T18:51:33.404Z,1337885493.404 [ballast_and_trim:ballast:ReportPositions](INFO): Aggregate::initialize ballast_and_trim:ballast:ReportPositions
2012-05-24T18:51:33.404Z,1337885493.404 [ballast_and_trim:ballast:ReportPositions:A] Running Loop=1
2012-05-24T18:51:33.782Z,1337885493.782 [ballast_and_trim:ballast:ReportPositions:A](IMPORTANT): Buoyancy: 950.028189 cc Mass: -0.473582 cm Pitch: 0.062793 arcdeg 
2012-05-24T18:51:33.782Z,1337885493.782 [ballast_and_trim:ballast:ReportPositions:A] Stopped
2012-05-24T18:51:33.782Z,1337885493.782 [ballast_and_trim:ballast:ReportPositions:B.Wait] Running Loop=1
2012-05-24T18:51:33.782Z,1337885493.782 [ballast_and_trim:ballast:ReportPositions:B.Wait](DEBUG): Initialize Wait Component.
2012-05-24T18:52:33.799Z,1337885553.799 [ballast_and_trim:ballast:ReportPositions:B.Wait](INFO): Timed out from 2012-05-24T18:51:33.8Z
2012-05-24T18:52:33.799Z,1337885553.799 [ballast_and_trim:ballast:ReportPositions:A_Timeout] Running Loop=1
2012-05-24T18:52:33.799Z,1337885553.799 [ballast_and_trim:ballast:ReportPositions:A_Timeout](INFO): Aggregate::initialize ballast_and_trim:ballast:ReportPositions:A_Timeout
2012-05-24T18:52:33.799Z,1337885553.799 [ballast_and_trim:ballast:ReportPositions:A_Timeout](INFO): Completed ballast_and_trim:ballast:ReportPositions:A_Timeout
2012-05-24T18:52:33.799Z,1337885553.799 [ballast_and_trim:ballast:ReportPositions:B.Wait] Stopped
2012-05-24T18:52:33.799Z,1337885553.799 [ballast_and_trim:ballast:ReportPositions:B.Wait](DEBUG): Uninitialize Wait Component.
2012-05-24T18:52:33.800Z,1337885553.800 [ballast_and_trim:ballast:ReportPositions](INFO): Completed ballast_and_trim:ballast:ReportPositions
2012-05-24T18:52:33.800Z,1337885553.800 [ballast_and_trim:ballast:ReportPositions] Stopped
2012-05-24T18:52:33.800Z,1337885553.800 [ballast_and_trim:ballast:ReportPositions](INFO): Aggregate::uninitialize ballast_and_trim:ballast:ReportPositions
2012-05-24T18:52:33.800Z,1337885553.800 [ballast_and_trim:ballast:ReportPositions](INFO): Running loop #5
2012-05-24T18:52:33.800Z,1337885553.800 [ballast_and_trim:ballast:ReportPositions] Running Loop=5
2012-05-24T18:52:33.800Z,1337885553.800 [ballast_and_trim:ballast:ReportPositions](INFO): Aggregate::initialize ballast_and_trim:ballast:ReportPositions
2012-05-24T18:52:33.800Z,1337885553.800 [ballast_and_trim:ballast:ReportPositions:A] Running Loop=1
2012-05-24T18:52:34.181Z,1337885554.181 [ballast_and_trim:ballast:ReportPositions:A](IMPORTANT): Buoyancy: 950.028189 cc Mass: -0.473582 cm Pitch: -0.069043 arcdeg 
2012-05-24T18:52:34.182Z,1337885554.181 [ballast_and_trim:ballast:ReportPositions:A] Stopped
2012-05-24T18:52:34.182Z,1337885554.182 [ballast_and_trim:ballast:ReportPositions:B.Wait] Running Loop=1
2012-05-24T18:52:34.182Z,1337885554.182 [ballast_and_trim:ballast:ReportPositions:B.Wait](DEBUG): Initialize Wait Component.
2012-05-24T18:53:34.202Z,1337885614.202 [ballast_and_trim:ballast:ReportPositions:B.Wait](INFO): Timed out from 2012-05-24T18:52:34.2Z
2012-05-24T18:53:34.202Z,1337885614.202 [ballast_and_trim:ballast:ReportPositions:A_Timeout] Running Loop=1
2012-05-24T18:53:34.202Z,1337885614.202 [ballast_and_trim:ballast:ReportPositions:A_Timeout](INFO): Aggregate::initialize ballast_and_trim:ballast:ReportPositions:A_Timeout
2012-05-24T18:53:34.202Z,1337885614.202 [ballast_and_trim:ballast:ReportPositions:A_Timeout](INFO): Completed ballast_and_trim:ballast:ReportPositions:A_Timeout
2012-05-24T18:53:34.203Z,1337885614.203 [ballast_and_trim:ballast:ReportPositions:B.Wait] Stopped
2012-05-24T18:53:34.203Z,1337885614.203 [ballast_and_trim:ballast:ReportPositions:B.Wait](DEBUG): Uninitialize Wait Component.
2012-05-24T18:53:34.203Z,1337885614.203 [ballast_and_trim:ballast:ReportPositions](INFO): Completed ballast_and_trim:ballast:ReportPositions
2012-05-24T18:53:34.203Z,1337885614.203 [ballast_and_trim:ballast:ReportPositions] Stopped
2012-05-24T18:53:34.203Z,1337885614.203 [ballast_and_trim:ballast:ReportPositions](INFO): Aggregate::uninitialize ballast_and_trim:ballast:ReportPositions
2012-05-24T18:53:34.203Z,1337885614.203 [ballast_and_trim:ballast:Float_Up] Running Loop=1
2012-05-24T18:53:34.203Z,1337885614.203 [ballast_and_trim:ballast:Float_Up](INFO): Aggregate::initialize ballast_and_trim:ballast:Float_Up
2012-05-24T18:53:34.203Z,1337885614.203 [ballast_and_trim:ballast:Float_Up:A.SetSpeed] Running Loop=1
2012-05-24T18:53:34.203Z,1337885614.203 [ballast_and_trim:ballast:Float_Up:A.SetSpeed](DEBUG): Initialize.
2012-05-24T18:53:34.204Z,1337885614.204 [ballast_and_trim:ballast:Float_Up:B.Buoyancy] Running Loop=1
2012-05-24T18:53:34.204Z,1337885614.204 [ballast_and_trim:ballast:Float_Up:B.Buoyancy](DEBUG): Initialize Buoyancy Component.
2012-05-24T18:53:34.204Z,1337885614.204 [ballast_and_trim:ballast:Float_Up:C.Wait] Running Loop=1
2012-05-24T18:53:34.204Z,1337885614.204 [ballast_and_trim:ballast:Float_Up:C.Wait](DEBUG): Initialize Wait Component.
2012-05-24T18:53:34.603Z,1337885614.603 [ballast_and_trim:ballast:Float_Up:B.Buoyancy] Running Loop=1
2012-05-24T18:53:34.604Z,1337885614.604 [ballast_and_trim:ballast:Float_Up:A.SetSpeed] Running Loop=1
2012-05-24T18:57:09.192Z,1337885829.192 [VerticalControl](CRITICAL): Can not stop descending, stopping mission after 840.333008 seconds.
2012-05-24T18:57:09.625Z,1337885829.625 [ballast_and_trim] Stopped
2012-05-24T18:57:09.626Z,1337885829.625 [ballast_and_trim](INFO): Aggregate::uninitialize ballast_and_trim
2012-05-24T18:57:09.626Z,1337885829.626 [ballast_and_trim:A] Stopped
2012-05-24T18:57:09.626Z,1337885829.626 [ballast_and_trim:B] Stopped
2012-05-24T18:57:09.626Z,1337885829.626 [ballast_and_trim:C] Stopped
2012-05-24T18:57:09.626Z,1337885829.626 [ballast_and_trim:D.SetSpeed] Stopped
2012-05-24T18:57:09.626Z,1337885829.626 [ballast_and_trim:D.SetSpeed](DEBUG): Uninitialize.
2012-05-24T18:57:09.626Z,1337885829.626 [ballast_and_trim:ballast] Stopped
2012-05-24T18:57:09.626Z,1337885829.626 [ballast_and_trim:ballast](INFO): Aggregate::uninitialize ballast_and_trim:ballast
2012-05-24T18:57:09.626Z,1337885829.626 [ballast_and_trim:ballast:A.SetSpeed] Stopped
2012-05-24T18:57:09.626Z,1337885829.626 [ballast_and_trim:ballast:A.SetSpeed](DEBUG): Uninitialize.
2012-05-24T18:57:09.626Z,1337885829.626 [ballast_and_trim:ballast:B] Stopped
2012-05-24T18:57:09.626Z,1337885829.626 [ballast_and_trim:ballast:C.Pitch] Stopped
2012-05-24T18:57:09.626Z,1337885829.626 [ballast_and_trim:ballast:Float_Up] Stopped
2012-05-24T18:57:09.627Z,1337885829.626 [ballast_and_trim:ballast:Float_Up](INFO): Aggregate::uninitialize ballast_and_trim:ballast:Float_Up
2012-05-24T18:57:09.627Z,1337885829.626 [ballast_and_trim:ballast:Float_Up:A.SetSpeed] Stopped
2012-05-24T18:57:09.627Z,1337885829.627 [ballast_and_trim:ballast:Float_Up:A.SetSpeed](DEBUG): Uninitialize.
2012-05-24T18:57:09.627Z,1337885829.627 [ballast_and_trim:ballast:Float_Up:B.Buoyancy] Stopped
2012-05-24T18:57:09.627Z,1337885829.627 [ballast_and_trim:ballast:Float_Up:B.Buoyancy](DEBUG): Uninitialize Buoyancy Component.
2012-05-24T18:57:09.627Z,1337885829.627 [ballast_and_trim:ballast:Float_Up:C.Wait] Stopped
2012-05-24T18:57:09.627Z,1337885829.627 [ballast_and_trim:ballast:Float_Up:C.Wait](DEBUG): Uninitialize Wait Component.
2012-05-24T18:57:09.984Z,1337885829.984 [MissionManager](IMPORTANT): Started mission Default
2012-05-24T18:57:09.984Z,1337885829.984 [Default] Running Loop=1
2012-05-24T18:57:09.984Z,1337885829.984 [Default](INFO): Aggregate::initialize Default
2012-05-24T18:57:09.984Z,1337885829.984 [Default:D.SetSpeed] Running Loop=1
2012-05-24T18:57:09.984Z,1337885829.984 [Default:D.SetSpeed](DEBUG): Initialize.
2012-05-24T18:57:09.985Z,1337885829.985 [Default:E.GoToSurface] Running Loop=1
2012-05-24T18:57:09.985Z,1337885829.985 [Default:E.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-05-24T18:57:09.985Z,1337885829.985 [Default:Iridium] Running Loop=1
2012-05-24T18:57:09.985Z,1337885829.985 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-05-24T18:57:09.985Z,1337885829.985 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-05-24T18:57:09.985Z,1337885829.985 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-05-24T18:57:09.985Z,1337885829.986 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-05-24T18:57:09.986Z,1337885829.986 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-05-24T18:57:09.987Z,1337885829.987 [Default:CallIridium] Running Loop=1
2012-05-24T18:57:09.987Z,1337885829.987 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-05-24T18:57:09.987Z,1337885829.987 [Default:CallIridium:A] Running Loop=1
2012-05-24T18:57:09.988Z,1337885829.988 [Default:CallIridium:A] Stopped
2012-05-24T18:57:09.988Z,1337885829.988 [Default:CallIridium:B] Running Loop=1
2012-05-24T18:57:09.988Z,1337885829.988 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-05-24T18:57:10.391Z,1337885830.391 [Default:GPS] Running Loop=1
2012-05-24T18:57:10.391Z,1337885830.391 [Default:GPS](INFO): Aggregate::initialize Default:GPS
2012-05-24T18:57:10.391Z,1337885830.391 [Default:GPS:A.SetSpeed] Running Loop=1
2012-05-24T18:57:10.391Z,1337885830.391 [Default:GPS:A.SetSpeed](DEBUG): Initialize.
2012-05-24T18:57:10.391Z,1337885830.391 [Default:GPS:B.GoToSurface] Running Loop=1
2012-05-24T18:57:10.391Z,1337885830.391 [Default:GPS:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-05-24T19:02:09.213Z,1337886129.213 [VerticalControl](CRITICAL): Can not stop descending, dropping drop weight after 300.020599 seconds.
2012-05-24T19:03:39.065Z,1337886219.065 [Radio_Freewave](INFO): Powering up
2012-05-24T19:03:39.075Z,1337886219.075 [Default:Iridium:B.GoToSurface] Stopped
2012-05-24T19:03:39.075Z,1337886219.075 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-05-24T19:03:39.075Z,1337886219.075 [Default:Iridium:Read_Iridium] Running Loop=1
2012-05-24T19:03:39.076Z,1337886219.076 [Default:GPS:B.GoToSurface] Stopped
2012-05-24T19:03:39.076Z,1337886219.076 [Default:GPS:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-05-24T19:03:39.076Z,1337886219.076 [Default:GPS:Read_GPS] Running Loop=1
2012-05-24T19:03:39.852Z,1337886219.852 [NAL9601](INFO): Powering up
2012-05-24T19:04:45.491Z,1337886285.491 [NAL9601](INFO): NAL9601 initialized
2012-05-24T19:05:14.292Z,1337886314.292 [NAL9601](INFO): SBD MO Status=2, MOMSN=6820, MT Status=2, MTMSN=0
2012-05-24T19:05:14.292Z,1337886314.293 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-05-24T19:05:51.416Z,1337886351.416 [NAL9601](INFO): SBD MO Status=2, MOMSN=6820, MT Status=2, MTMSN=0
2012-05-24T19:05:51.417Z,1337886351.417 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-05-24T19:08:10.837Z,1337886490.838 [NAL9601](INFO): SBD MO Status=2, MOMSN=6820, MT Status=2, MTMSN=0
2012-05-24T19:08:10.838Z,1337886490.838 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-05-24T19:09:38.998Z,1337886578.998 [NAL9601](INFO): SBD MO Status=2, MOMSN=6820, MT Status=2, MTMSN=0
2012-05-24T19:09:38.998Z,1337886578.998 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-05-24T19:10:29.956Z,1337886629.956 [NAL9601](INFO): SBD MO Status=2, MOMSN=6820, MT Status=2, MTMSN=0
2012-05-24T19:10:29.957Z,1337886629.957 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-05-24T19:10:53.141Z,1337886653.141 [NAL9601](INFO): SBD MO Status=2, MOMSN=6820, MT Status=2, MTMSN=0
2012-05-24T19:10:53.141Z,1337886653.141 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-05-24T19:11:27.111Z,1337886687.111 [NAL9601](INFO): SBD MO Status=2, MOMSN=6820, MT Status=2, MTMSN=0
2012-05-24T19:11:27.111Z,1337886687.111 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-05-24T19:11:40.243Z,1337886700.243 [NAL9601](INFO): SBD MO Status=1, MOMSN=6820, MT Status=0, MTMSN=0
2012-05-24T19:11:40.435Z,1337886700.435 [NAL9601](INFO): Sent 332 bytes from file Logs/20120524T182805/shore0000.lzma
2012-05-24T19:11:40.435Z,1337886700.435 [NAL9601](INFO): Packets left to send: 3
2012-05-24T19:11:40.436Z,1337886700.436 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000021
2012-05-24T19:11:52.761Z,1337886712.761 [NAL9601](INFO): SBD MO Status=1, MOMSN=6821, MT Status=0, MTMSN=0
2012-05-24T19:11:52.915Z,1337886712.915 [NAL9601](INFO): Sent 332 bytes from file Logs/20120524T182805/shore0000.lzma
2012-05-24T19:11:52.915Z,1337886712.915 [NAL9601](INFO): Packets left to send: 2
2012-05-24T19:11:52.916Z,1337886712.916 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000022
2012-05-24T19:12:06.471Z,1337886726.471 [NAL9601](INFO): SBD MO Status=1, MOMSN=6822, MT Status=0, MTMSN=0
2012-05-24T19:12:06.599Z,1337886726.599 [NAL9601](INFO): Sent 332 bytes from file Logs/20120524T182805/shore0000.lzma
2012-05-24T19:12:06.599Z,1337886726.599 [NAL9601](INFO): Packets left to send: 1
2012-05-24T19:12:06.600Z,1337886726.600 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000023
2012-05-24T19:12:22.541Z,1337886742.541 [NAL9601](INFO): SBD MO Status=1, MOMSN=6823, MT Status=0, MTMSN=0
2012-05-24T19:12:22.671Z,1337886742.671 [NAL9601](INFO): Sent 233 bytes from file Logs/20120524T182805/shore0000.lzma
2012-05-24T19:12:22.671Z,1337886742.671 [NAL9601](INFO): Packets left to send: 0
2012-05-24T19:12:22.672Z,1337886742.672 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000024
2012-05-24T19:12:30.945Z,1337886750.945 [NAL9601](INFO): SBD MO Status=0, MOMSN=6824, MT Status=0, MTMSN=0
2012-05-24T19:12:31.169Z,1337886751.169 [Default:Iridium:Read_Iridium] Stopped
2012-05-24T19:12:31.169Z,1337886751.169 [Default:Iridium](INFO): Completed Default:Iridium
2012-05-24T19:12:31.169Z,1337886751.169 [Default:Iridium] Stopped
2012-05-24T19:12:31.169Z,1337886751.169 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-05-24T19:12:31.169Z,1337886751.169 [Default:Iridium:A.SetSpeed] Stopped
2012-05-24T19:12:31.169Z,1337886751.169 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-05-24T19:12:31.169Z,1337886751.170 [Default:F.Wait] Running Loop=1
2012-05-24T19:12:31.170Z,1337886751.170 [Default:F.Wait](DEBUG): Initialize Wait Component.
2012-05-24T19:12:31.357Z,1337886751.357 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2012-05-24T19:12:31.357Z,1337886751.357 [Default:CallIridium:B] Stopped
2012-05-24T19:12:31.357Z,1337886751.357 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-05-24T19:12:31.358Z,1337886751.358 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-05-24T19:12:31.358Z,1337886751.358 [Default:CallIridium] Stopped
2012-05-24T19:12:31.358Z,1337886751.358 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-05-24T19:14:47.765Z,1337886887.765 [NAL9601](FAULT): GPS failed to acquire within timeout.
2012-05-24T19:14:47.765Z,1337886887.765 [NAL9601] Data Fault, FailCount= 1
2012-05-24T19:14:47.765Z,1337886887.765 [NAL9601](ERROR): Data Fault
2012-05-24T19:14:47.780Z,1337886887.780 [Default:CallIridium] Running Loop=1
2012-05-24T19:14:47.780Z,1337886887.780 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-05-24T19:14:47.781Z,1337886887.781 [Default:CallIridium:A] Running Loop=1
2012-05-24T19:14:47.781Z,1337886887.781 [Default:CallIridium:A] Stopped
2012-05-24T19:14:47.781Z,1337886887.781 [Default:CallIridium:B] Running Loop=1
2012-05-24T19:14:47.781Z,1337886887.781 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-05-24T19:14:47.790Z,1337886887.790 [CBIT](ERROR): Data Fault in component: NAL9601
2012-05-24T19:14:48.139Z,1337886888.139 [NAL9601](INFO): Powering down
2012-05-24T19:14:48.154Z,1337886888.154 [Default:Iridium] Running Loop=1
2012-05-24T19:14:48.154Z,1337886888.154 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-05-24T19:14:48.154Z,1337886888.154 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-05-24T19:14:48.154Z,1337886888.154 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-05-24T19:14:48.154Z,1337886888.154 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-05-24T19:14:48.154Z,1337886888.154 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-05-24T19:14:48.155Z,1337886888.155 [Default:Iridium:B.GoToSurface] Stopped
2012-05-24T19:14:48.155Z,1337886888.155 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-05-24T19:14:48.155Z,1337886888.155 [Default:Iridium:Read_Iridium] Running Loop=1
2012-05-24T19:14:48.164Z,1337886888.164 [CBIT](INFO): Clearing failed state for component NAL9601
2012-05-24T19:14:48.164Z,1337886888.164 [NAL9601] No Fault, FailCount= 1
2012-05-24T19:14:53.340Z,1337886893.340 [NAL9601](INFO): Powering up NAL9601
2012-05-24T19:15:59.088Z,1337886959.088 [NAL9601](INFO): NAL9601 initialized
2012-05-24T19:21:44.174Z,1337887304.174 [NAL9601](INFO): SBD MO Status=2, MOMSN=6825, MT Status=2, MTMSN=0
2012-05-24T19:21:44.174Z,1337887304.174 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-05-24T19:22:29.697Z,1337887349.697 [NAL9601](INFO): SBD MO Status=2, MOMSN=6825, MT Status=2, MTMSN=0
2012-05-24T19:22:29.697Z,1337887349.697 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-05-24T19:22:52.433Z,1337887372.433 [NAL9601](INFO): SBD MO Status=1, MOMSN=6825, MT Status=0, MTMSN=0
2012-05-24T19:22:52.623Z,1337887372.623 [NAL9601](INFO): Sent 162 bytes from file Logs/20120524T182805/shore0001.lzma
2012-05-24T19:22:52.623Z,1337887372.623 [NAL9601](INFO): Packets left to send: 0
2012-05-24T19:22:52.624Z,1337887372.624 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000025
2012-05-24T19:23:04.365Z,1337887384.365 [NAL9601](INFO): SBD MO Status=2, MOMSN=6826, MT Status=2, MTMSN=0
2012-05-24T19:23:04.366Z,1337887384.366 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-05-24T19:23:21.361Z,1337887401.361 [NAL9601](INFO): SBD MO Status=2, MOMSN=6826, MT Status=2, MTMSN=0
2012-05-24T19:23:21.362Z,1337887401.362 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-05-24T19:23:34.328Z,1337887414.328 [NAL9601](INFO): SBD MO Status=2, MOMSN=6826, MT Status=2, MTMSN=0
2012-05-24T19:23:34.328Z,1337887414.328 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-05-24T19:23:55.317Z,1337887435.318 [NAL9601](INFO): SBD MO Status=0, MOMSN=6826, MT Status=0, MTMSN=0
2012-05-24T19:23:55.546Z,1337887435.546 [Default:Iridium:Read_Iridium] Stopped
2012-05-24T19:23:55.547Z,1337887435.547 [Default:Iridium](INFO): Completed Default:Iridium
2012-05-24T19:23:55.547Z,1337887435.547 [Default:Iridium] Stopped
2012-05-24T19:23:55.547Z,1337887435.547 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-05-24T19:23:55.547Z,1337887435.547 [Default:Iridium:A.SetSpeed] Stopped
2012-05-24T19:23:55.547Z,1337887435.547 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-05-24T19:23:55.734Z,1337887435.734 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2012-05-24T19:23:55.734Z,1337887435.734 [Default:CallIridium:B] Stopped
2012-05-24T19:23:55.735Z,1337887435.735 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-05-24T19:23:55.735Z,1337887435.735 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-05-24T19:23:55.735Z,1337887435.735 [Default:CallIridium] Stopped
2012-05-24T19:23:55.735Z,1337887435.735 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-05-24T19:26:00.150Z,1337887560.150 [NAL9601](FAULT): GPS failed to acquire within timeout.
2012-05-24T19:26:00.150Z,1337887560.150 [NAL9601] Data Fault, FailCount= 2
2012-05-24T19:26:00.150Z,1337887560.151 [NAL9601](ERROR): Data Fault
2012-05-24T19:26:00.165Z,1337887560.165 [Default:CallIridium] Running Loop=1
2012-05-24T19:26:00.166Z,1337887560.165 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-05-24T19:26:00.166Z,1337887560.166 [Default:CallIridium:A] Running Loop=1
2012-05-24T19:26:00.166Z,1337887560.166 [Default:CallIridium:A] Stopped
2012-05-24T19:26:00.166Z,1337887560.166 [Default:CallIridium:B] Running Loop=1
2012-05-24T19:26:00.166Z,1337887560.166 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-05-24T19:26:00.176Z,1337887560.175 [CBIT](ERROR): Data Fault in component: NAL9601
2012-05-24T19:26:00.176Z,1337887560.176 [CBIT](CRITICAL): Data Fault in component: NAL9601
2012-05-24T19:26:00.516Z,1337887560.516 [NAL9601](INFO): Powering down
2012-05-24T19:26:00.531Z,1337887560.531 [Default:Iridium] Running Loop=1
2012-05-24T19:26:00.531Z,1337887560.531 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-05-24T19:26:00.531Z,1337887560.531 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-05-24T19:26:00.531Z,1337887560.531 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-05-24T19:26:00.531Z,1337887560.532 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-05-24T19:26:00.532Z,1337887560.532 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-05-24T19:26:00.533Z,1337887560.533 [Default:Iridium:B.GoToSurface] Stopped
2012-05-24T19:26:00.533Z,1337887560.533 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-05-24T19:26:00.533Z,1337887560.533 [Default:Iridium:Read_Iridium] Running Loop=1
2012-05-24T19:28:00.538Z,1337887680.538 [CBIT](INFO): Clearing failed count for component NAL9601
2012-05-24T19:28:00.539Z,1337887680.539 [NAL9601] No Fault, FailCount= 2
2012-05-24T19:28:00.917Z,1337887680.917 [NAL9601](INFO): Powering up NAL9601
2012-05-24T19:29:06.659Z,1337887746.659 [NAL9601](INFO): NAL9601 initialized
2012-05-24T19:30:59.370Z,1337887859.370 [NAL9601](INFO): SBD MO Status=1, MOMSN=6827, MT Status=0, MTMSN=0
2012-05-24T19:30:59.571Z,1337887859.571 [NAL9601](INFO): Sent 218 bytes from file Logs/20120524T182805/shore0002.lzma
2012-05-24T19:30:59.571Z,1337887859.571 [NAL9601](INFO): Packets left to send: 0
2012-05-24T19:30:59.572Z,1337887859.572 [NAL9601](INFO): Stored copy of sent data in Logs/latest/sent0000026
2012-05-24T19:31:16.169Z,1337887876.169 [NAL9601](INFO): SBD MO Status=2, MOMSN=6828, MT Status=2, MTMSN=0
2012-05-24T19:31:16.170Z,1337887876.170 [NAL9601](ERROR): Failed to initiate SBD session. Error code: 2
2012-05-24T19:31:20.805Z,1337887880.805 [NAL9601](ERROR): Inadequate Iridium signal strength reported: 0
2012-05-24T19:31:24.594Z,1337887884.594 [NAL9601](ERROR): Inadequate Iridium signal strength reported: 0
2012-05-24T19:31:50.382Z,1337887910.382 [NAL9601](INFO): SBD MO Status=0, MOMSN=6828, MT Status=0, MTMSN=0
2012-05-24T19:31:50.618Z,1337887910.618 [Default:Iridium:Read_Iridium] Stopped
2012-05-24T19:31:50.627Z,1337887910.627 [Default:Iridium](INFO): Completed Default:Iridium
2012-05-24T19:31:50.627Z,1337887910.627 [Default:Iridium] Stopped
2012-05-24T19:31:50.627Z,1337887910.627 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-05-24T19:31:50.627Z,1337887910.627 [Default:Iridium:A.SetSpeed] Stopped
2012-05-24T19:31:50.627Z,1337887910.627 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-05-24T19:31:50.887Z,1337887910.887 [Default:CallIridium:B](INFO): Completed Default:CallIridium:B
2012-05-24T19:31:50.887Z,1337887910.887 [Default:CallIridium:B] Stopped
2012-05-24T19:31:50.887Z,1337887910.887 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-05-24T19:31:50.887Z,1337887910.887 [Default:CallIridium](INFO): Completed Default:CallIridium
2012-05-24T19:31:50.887Z,1337887910.887 [Default:CallIridium] Stopped
2012-05-24T19:31:50.887Z,1337887910.887 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-05-24T19:36:50.759Z,1337888210.759 [Default:CallIridium] Running Loop=1
2012-05-24T19:36:50.760Z,1337888210.760 [Default:CallIridium](INFO): Aggregate::initialize Default:CallIridium
2012-05-24T19:36:50.760Z,1337888210.760 [Default:CallIridium:A] Running Loop=1
2012-05-24T19:36:50.760Z,1337888210.760 [Default:CallIridium:A] Stopped
2012-05-24T19:36:50.760Z,1337888210.760 [Default:CallIridium:B] Running Loop=1
2012-05-24T19:36:50.760Z,1337888210.760 [Default:CallIridium:B](INFO): Aggregate::initialize Default:CallIridium:B
2012-05-24T19:36:51.207Z,1337888211.207 [Default:Iridium] Running Loop=1
2012-05-24T19:36:51.207Z,1337888211.207 [Default:Iridium](INFO): Aggregate::initialize Default:Iridium
2012-05-24T19:36:51.207Z,1337888211.207 [Default:Iridium:A.SetSpeed] Running Loop=1
2012-05-24T19:36:51.207Z,1337888211.207 [Default:Iridium:A.SetSpeed](DEBUG): Initialize.
2012-05-24T19:36:51.208Z,1337888211.208 [Default:Iridium:B.GoToSurface] Running Loop=1
2012-05-24T19:36:51.208Z,1337888211.208 [Default:Iridium:B.GoToSurface](DEBUG): Initialize GoToSurfaceComponent.
2012-05-24T19:36:51.209Z,1337888211.209 [Default:Iridium:B.GoToSurface] Stopped
2012-05-24T19:36:51.209Z,1337888211.209 [Default:Iridium:B.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-05-24T19:36:51.209Z,1337888211.209 [Default:Iridium:Read_Iridium] Running Loop=1
2012-05-24T19:39:09.688Z,1337888349.688 [NAL9601](FAULT): GPS failed to acquire within timeout.
2012-05-24T19:39:09.688Z,1337888349.688 [NAL9601] Data Fault, FailCount= 1
2012-05-24T19:39:09.688Z,1337888349.688 [NAL9601](ERROR): Data Fault
2012-05-24T19:39:09.711Z,1337888349.711 [CBIT](ERROR): Data Fault in component: NAL9601
2012-05-24T19:39:10.067Z,1337888350.067 [NAL9601](INFO): Powering down
2012-05-24T19:39:10.091Z,1337888350.091 [CBIT](INFO): Clearing failed state for component NAL9601
2012-05-24T19:39:10.091Z,1337888350.091 [NAL9601] No Fault, FailCount= 1
2012-05-24T19:39:15.273Z,1337888355.273 [NAL9601](INFO): Powering up NAL9601
2012-05-24T19:40:20.911Z,1337888420.911 [NAL9601](INFO): NAL9601 initialized
2012-05-24T19:41:29.172Z,1337888489.172 [CommandLine](IMPORTANT): got command quit
2012-05-24T19:41:29.239Z,1337888489.239 [Supervisor](DEBUG): Uninitializing supervisor and starting cleanup.  Bye!
2012-05-24T19:41:29.239Z,1337888489.239 [ComponentRegistry](INFO): Shutting down NavChartDb ThreadHandler
2012-05-24T19:41:29.572Z,1337888489.572 [ComponentRegistry](INFO): Shutting down CTD_NeilBrown ThreadHandler
2012-05-24T19:41:29.579Z,1337888489.579 [ComponentRegistry](INFO): Shutting down AsyncPiEstimator ThreadHandler
2012-05-24T19:41:29.685Z,1337888489.685 [AsyncPiEstimator](DEBUG): Uninitialize AsyncPiEstimator.
2012-05-24T19:41:29.685Z,1337888489.685 [ComponentRegistry](INFO): Shutting down CommandLine ThreadHandler
2012-05-24T19:41:29.685Z,1337888489.685 [ComponentRegistry](INFO): Shutting down controlThread ThreadHandler
2012-05-24T19:41:29.754Z,1337888489.754 [controlThread](DEBUG): Uninitializing ControlThread
2012-05-24T19:41:29.755Z,1337888489.755 [AHRS_sp3003D](INFO): Powering down
2012-05-24T19:41:29.844Z,1337888489.843 [DVL_micro](INFO): Powering down
2012-05-24T19:41:29.844Z,1337888489.844 [NAL9601](INFO): Powering down
2012-05-24T19:41:29.845Z,1337888489.845 [WetLabsBB2FL](INFO): Powering down
2012-05-24T19:41:29.845Z,1337888489.845 [DepthRateCalculator](DEBUG): Initializing DepthRateCalculator.
2012-05-24T19:41:29.846Z,1337888489.846 [NavChart](DEBUG): Uninitialize NavChart Derivation.
2012-05-24T19:41:29.847Z,1337888489.847 [Default] Stopped
2012-05-24T19:41:29.847Z,1337888489.847 [Default](INFO): Aggregate::uninitialize Default
2012-05-24T19:41:29.847Z,1337888489.847 [Default:GPS] Stopped
2012-05-24T19:41:29.847Z,1337888489.847 [Default:GPS](INFO): Aggregate::uninitialize Default:GPS
2012-05-24T19:41:29.847Z,1337888489.847 [Default:GPS:A.SetSpeed] Stopped
2012-05-24T19:41:29.848Z,1337888489.848 [Default:GPS:A.SetSpeed](DEBUG): Uninitialize.
2012-05-24T19:41:29.848Z,1337888489.848 [Default:GPS:Read_GPS] Stopped
2012-05-24T19:41:29.848Z,1337888489.848 [Default:Iridium] Stopped
2012-05-24T19:41:29.848Z,1337888489.848 [Default:Iridium](INFO): Aggregate::uninitialize Default:Iridium
2012-05-24T19:41:29.848Z,1337888489.848 [Default:Iridium:A.SetSpeed] Stopped
2012-05-24T19:41:29.848Z,1337888489.848 [Default:Iridium:A.SetSpeed](DEBUG): Uninitialize.
2012-05-24T19:41:29.848Z,1337888489.848 [Default:Iridium:Read_Iridium] Stopped
2012-05-24T19:41:29.848Z,1337888489.848 [Default:CallIridium] Stopped
2012-05-24T19:41:29.848Z,1337888489.848 [Default:CallIridium](INFO): Aggregate::uninitialize Default:CallIridium
2012-05-24T19:41:29.848Z,1337888489.848 [Default:CallIridium:B] Stopped
2012-05-24T19:41:29.848Z,1337888489.848 [Default:CallIridium:B](INFO): Aggregate::uninitialize Default:CallIridium:B
2012-05-24T19:41:29.848Z,1337888489.848 [Default:D.SetSpeed] Stopped
2012-05-24T19:41:29.848Z,1337888489.848 [Default:D.SetSpeed](DEBUG): Uninitialize.
2012-05-24T19:41:29.849Z,1337888489.849 [Default:E.GoToSurface] Stopped
2012-05-24T19:41:29.849Z,1337888489.849 [Default:E.GoToSurface](DEBUG): Uninitialize GoToSurfaceComponent.
2012-05-24T19:41:29.849Z,1337888489.849 [Default:F.Wait] Stopped
2012-05-24T19:41:29.849Z,1337888489.849 [Default:F.Wait](DEBUG): Uninitialize Wait Component.
2012-05-24T19:41:29.853Z,1337888489.853 [VerticalControl](DEBUG): Uninitialize VerticalControlComponent.
2012-05-24T19:41:29.854Z,1337888489.853 [HorizontalControl](DEBUG): Uninitialize HorizontalControlComponent.
2012-05-24T19:41:29.854Z,1337888489.854 [SpeedControl](DEBUG): Uninitialize SpeedControlComponent.
2012-05-24T19:41:29.854Z,1337888489.854 [LoopControl](DEBUG): Uninitialize LoopControlComponent.
2012-05-24T19:41:29.854Z,1337888489.855 [BuoyancyServo](DEBUG): Uninitialize Buoyancy Servo.
2012-05-24T19:41:29.855Z,1337888489.855 [BuoyancyServo](INFO): Powering down
2012-05-24T19:41:29.855Z,1337888489.855 [ElevatorServo](DEBUG): Uninitialize Elevator Servo.
2012-05-24T19:41:29.855Z,1337888489.855 [ElevatorServo](INFO): Powering down
2012-05-24T19:41:29.856Z,1337888489.856 [MassServo](DEBUG): Uninitialize Mass Servo.
2012-05-24T19:41:29.856Z,1337888489.856 [MassServo](INFO): Powering down
2012-05-24T19:41:29.856Z,1337888489.856 [RudderServo](DEBUG): Uninitialize Rudder Servo.
2012-05-24T19:41:29.856Z,1337888489.856 [RudderServo](INFO): Powering down
2012-05-24T19:41:29.857Z,1337888489.856 [ThrusterServo](DEBUG): Uninitialize Thruster Servo.
2012-05-24T19:41:29.857Z,1337888489.857 [ThrusterServo](INFO): Powering down
2012-05-24T19:41:29.857Z,1337888489.857 [SBIT](DEBUG): Uninitialize SBIT Component.
2012-05-24T19:41:29.857Z,1337888489.857 [IBIT](DEBUG): Uninitialize IBIT Component.
2012-05-24T19:41:29.858Z,1337888489.858 [CBIT](DEBUG): Uninitialize CBIT Component.